2025-10-22 20:59:29.945324 | Job console starting 2025-10-22 20:59:29.960040 | Updating git repos 2025-10-22 20:59:29.996705 | Cloning repos into workspace 2025-10-22 20:59:30.045048 | Restoring repo states 2025-10-22 20:59:30.058242 | Merging changes 2025-10-22 20:59:30.569911 | Checking out repos 2025-10-22 20:59:30.646524 | Preparing playbooks 2025-10-22 20:59:31.480369 | Running Ansible setup 2025-10-22 20:59:37.046372 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-22 20:59:38.252545 | 2025-10-22 20:59:38.252724 | PLAY [Base pre] 2025-10-22 20:59:38.285866 | 2025-10-22 20:59:38.286054 | TASK [Setup log path fact] 2025-10-22 20:59:38.348283 | debian-bookworm | ok 2025-10-22 20:59:38.366621 | 2025-10-22 20:59:38.366781 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-22 20:59:38.406633 | debian-bookworm | ok 2025-10-22 20:59:38.416904 | 2025-10-22 20:59:38.417080 | TASK [emit-job-header : Print job information] 2025-10-22 20:59:38.456594 | # Job Information 2025-10-22 20:59:38.456783 | Ansible Version: 2.16.14 2025-10-22 20:59:38.456819 | Job: tox 2025-10-22 20:59:38.456852 | Pipeline: check 2025-10-22 20:59:38.456875 | Executor: 521e9411259a 2025-10-22 20:59:38.456895 | Triggered by: https://github.com/osism/openstack-image-manager/pull/980 2025-10-22 20:59:38.456916 | Event ID: fb7e1590-af89-11f0-8bf9-e238ef451312 2025-10-22 20:59:38.461900 | 2025-10-22 20:59:38.462049 | LOOP [emit-job-header : Print node information] 2025-10-22 20:59:38.683466 | debian-bookworm | ok: 2025-10-22 20:59:38.683673 | debian-bookworm | # Node Information 2025-10-22 20:59:38.683707 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-22 20:59:38.683732 | debian-bookworm | Hostname: debian 2025-10-22 20:59:38.683753 | debian-bookworm | Username: zuul 2025-10-22 20:59:38.683773 | debian-bookworm | Distro: Debian 12.12 2025-10-22 20:59:38.683796 | debian-bookworm | Provider: regiocloud-a 2025-10-22 20:59:38.683817 | debian-bookworm | Region: 2025-10-22 20:59:38.683838 | debian-bookworm | Label: debian-bookworm 2025-10-22 20:59:38.683857 | debian-bookworm | Product Name: OpenStack Nova 2025-10-22 20:59:38.683877 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe03:cf8e 2025-10-22 20:59:38.712105 | 2025-10-22 20:59:38.712262 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-22 20:59:39.231220 | debian-bookworm -> localhost | changed 2025-10-22 20:59:39.238059 | 2025-10-22 20:59:39.238163 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-22 20:59:40.517972 | debian-bookworm -> localhost | changed 2025-10-22 20:59:40.526345 | 2025-10-22 20:59:40.526423 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-22 20:59:41.075008 | debian-bookworm -> localhost | ok 2025-10-22 20:59:41.080387 | 2025-10-22 20:59:41.080480 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-22 20:59:41.106730 | debian-bookworm | ok 2025-10-22 20:59:41.132540 | debian-bookworm | included: /var/lib/zuul/builds/15cd474297304e139f2226ae4ad8edf8/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-22 20:59:41.137691 | 2025-10-22 20:59:41.137765 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-22 20:59:42.758099 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-22 20:59:42.758307 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/15cd474297304e139f2226ae4ad8edf8/work/15cd474297304e139f2226ae4ad8edf8_id_rsa 2025-10-22 20:59:42.758389 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/15cd474297304e139f2226ae4ad8edf8/work/15cd474297304e139f2226ae4ad8edf8_id_rsa.pub 2025-10-22 20:59:42.758439 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-22 20:59:42.758473 | debian-bookworm -> localhost | SHA256:0T4NaP8g8RrZsr22CY1WD3oOHK8AGVDDjG4x6Fc5ltk zuul-build-sshkey 2025-10-22 20:59:42.758498 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-22 20:59:42.758532 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-22 20:59:42.758557 | debian-bookworm -> localhost | | ..=o = | 2025-10-22 20:59:42.758582 | debian-bookworm -> localhost | |. +.oO E o | 2025-10-22 20:59:42.758604 | debian-bookworm -> localhost | |.. o+ . = o | 2025-10-22 20:59:42.758626 | debian-bookworm -> localhost | | .o. o . O o | 2025-10-22 20:59:42.758648 | debian-bookworm -> localhost | | .. o S X . | 2025-10-22 20:59:42.758679 | debian-bookworm -> localhost | | . . # * | 2025-10-22 20:59:42.758702 | debian-bookworm -> localhost | | . X = o | 2025-10-22 20:59:42.758724 | debian-bookworm -> localhost | | o *.o | 2025-10-22 20:59:42.758748 | debian-bookworm -> localhost | | ..=. | 2025-10-22 20:59:42.758771 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-22 20:59:42.758825 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.829827 2025-10-22 20:59:42.765584 | 2025-10-22 20:59:42.765672 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-22 20:59:42.793363 | debian-bookworm | ok 2025-10-22 20:59:42.802815 | debian-bookworm | included: /var/lib/zuul/builds/15cd474297304e139f2226ae4ad8edf8/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-22 20:59:42.825253 | 2025-10-22 20:59:42.825335 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-22 20:59:42.848048 | debian-bookworm | skipping: Conditional result was False 2025-10-22 20:59:42.854003 | 2025-10-22 20:59:42.854087 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-22 20:59:43.439402 | debian-bookworm | changed 2025-10-22 20:59:43.447414 | 2025-10-22 20:59:43.447527 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-22 20:59:43.701063 | debian-bookworm | ok 2025-10-22 20:59:43.709672 | 2025-10-22 20:59:43.709794 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-22 20:59:44.398291 | debian-bookworm | changed 2025-10-22 20:59:44.406400 | 2025-10-22 20:59:44.406508 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-22 20:59:45.033691 | debian-bookworm | changed 2025-10-22 20:59:45.038881 | 2025-10-22 20:59:45.038978 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-22 20:59:45.061643 | debian-bookworm | skipping: Conditional result was False 2025-10-22 20:59:45.067398 | 2025-10-22 20:59:45.067489 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-22 20:59:45.537164 | debian-bookworm -> localhost | changed 2025-10-22 20:59:45.545669 | 2025-10-22 20:59:45.545750 | TASK [add-build-sshkey : Add back temp key] 2025-10-22 20:59:45.849328 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/15cd474297304e139f2226ae4ad8edf8/work/15cd474297304e139f2226ae4ad8edf8_id_rsa (zuul-build-sshkey) 2025-10-22 20:59:45.849536 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.009043 2025-10-22 20:59:45.856847 | 2025-10-22 20:59:45.856961 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-22 20:59:46.296799 | debian-bookworm | ok 2025-10-22 20:59:46.303053 | 2025-10-22 20:59:46.303143 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-22 20:59:46.326090 | debian-bookworm | skipping: Conditional result was False 2025-10-22 20:59:46.335826 | 2025-10-22 20:59:46.335914 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-22 20:59:46.788321 | debian-bookworm | ok 2025-10-22 20:59:46.799233 | 2025-10-22 20:59:46.799337 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-22 20:59:46.837425 | debian-bookworm | ok 2025-10-22 20:59:46.842220 | 2025-10-22 20:59:46.842309 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-22 20:59:47.153670 | debian-bookworm -> localhost | ok 2025-10-22 20:59:47.166502 | 2025-10-22 20:59:47.166633 | TASK [validate-host : Collect information about the host] 2025-10-22 20:59:48.242099 | debian-bookworm | ok 2025-10-22 20:59:48.261573 | 2025-10-22 20:59:48.261675 | TASK [validate-host : Sanitize hostname] 2025-10-22 20:59:48.356271 | debian-bookworm | ok 2025-10-22 20:59:48.386026 | 2025-10-22 20:59:48.386130 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-22 20:59:48.946776 | debian-bookworm -> localhost | changed 2025-10-22 20:59:48.952124 | 2025-10-22 20:59:48.952214 | TASK [validate-host : Collect information about zuul worker] 2025-10-22 20:59:49.444298 | debian-bookworm | ok 2025-10-22 20:59:49.448751 | 2025-10-22 20:59:49.448843 | TASK [validate-host : Write out all zuul information for each host] 2025-10-22 20:59:49.990176 | debian-bookworm -> localhost | changed 2025-10-22 20:59:50.001638 | 2025-10-22 20:59:50.001727 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-22 20:59:50.260909 | debian-bookworm | ok 2025-10-22 20:59:50.266069 | 2025-10-22 20:59:50.266153 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-22 20:59:51.139250 | debian-bookworm | changed: 2025-10-22 20:59:51.139415 | debian-bookworm | cd+++++++++ src/ 2025-10-22 20:59:51.139448 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-22 20:59:51.139472 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-22 20:59:51.139494 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-10-22 20:59:51.139515 | debian-bookworm | =6.2 2025-10-22 21:00:13.841579 | debian-bookworm | Downloading cachetools-6.2.1-py3-none-any.whl (11 kB) 2025-10-22 21:00:13.875344 | debian-bookworm | Collecting chardet>=5.2 2025-10-22 21:00:13.889556 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-10-22 21:00:13.902634 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 19.7 MB/s eta 0:00:00 2025-10-22 21:00:13.940172 | debian-bookworm | Collecting colorama>=0.4.6 2025-10-22 21:00:13.954436 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-10-22 21:00:14.020359 | debian-bookworm | Collecting filelock>=3.20 2025-10-22 21:00:14.036339 | debian-bookworm | Downloading filelock-3.20.0-py3-none-any.whl (16 kB) 2025-10-22 21:00:14.085749 | debian-bookworm | Collecting packaging>=25 2025-10-22 21:00:14.099452 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-22 21:00:14.107160 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 12.0 MB/s eta 0:00:00 2025-10-22 21:00:14.155538 | debian-bookworm | Collecting platformdirs>=4.5 2025-10-22 21:00:14.169637 | debian-bookworm | Downloading platformdirs-4.5.0-py3-none-any.whl (18 kB) 2025-10-22 21:00:14.209573 | debian-bookworm | Collecting pluggy>=1.6 2025-10-22 21:00:14.223591 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-10-22 21:00:14.363284 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-10-22 21:00:14.379283 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-10-22 21:00:14.517561 | debian-bookworm | Collecting virtualenv>=20.34 2025-10-22 21:00:14.532221 | debian-bookworm | Downloading virtualenv-20.35.3-py3-none-any.whl (6.0 MB) 2025-10-22 21:00:14.851973 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 19.5 MB/s eta 0:00:00 2025-10-22 21:00:14.952686 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-10-22 21:00:14.966596 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-10-22 21:00:14.995458 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 20.1 MB/s eta 0:00:00 2025-10-22 21:00:15.154674 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-10-22 21:00:15.991962 | debian-bookworm | Successfully installed cachetools-6.2.1 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.20.0 packaging-25.0 platformdirs-4.5.0 pluggy-1.6.0 pyproject-api-1.10.0 tox-4.31.0 virtualenv-20.35.3 2025-10-22 21:00:16.180917 | debian-bookworm | ok: Runtime: 0:00:03.214530 2025-10-22 21:00:16.186519 | 2025-10-22 21:00:16.186629 | TASK [ensure-tox : Export installed tox_executable path] 2025-10-22 21:00:16.216848 | debian-bookworm | ok 2025-10-22 21:00:16.222412 | 2025-10-22 21:00:16.222516 | TASK [ensure-tox : Output tox version] 2025-10-22 21:00:16.767105 | 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-10-22 21:00:16.786301 | debian-bookworm | 4.31.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-22 21:00:17.252749 | debian-bookworm | ok: Runtime: 0:00:00.323394 2025-10-22 21:00:17.258547 | 2025-10-22 21:00:17.258652 | TASK [ensure-tox : Make global symlink] 2025-10-22 21:00:17.282478 | debian-bookworm | skipping: Conditional result was False 2025-10-22 21:00:17.289190 | 2025-10-22 21:00:17.289275 | PLAY RECAP 2025-10-22 21:00:17.289328 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-10-22 21:00:17.289353 | 2025-10-22 21:00:17.474816 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-10-22 21:00:17.476733 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-22 21:00:18.352898 | 2025-10-22 21:00:18.353080 | PLAY [all] 2025-10-22 21:00:18.369227 | 2025-10-22 21:00:18.369371 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-10-22 21:00:18.924404 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-22 21:00:19.398536 | debian-bookworm | ok: Runtime: 0:00:00.119510 2025-10-22 21:00:19.413525 | 2025-10-22 21:00:19.413667 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-10-22 21:00:19.947868 | debian-bookworm | ok: Runtime: 0:00:00.007770 2025-10-22 21:00:19.971152 | 2025-10-22 21:00:19.971296 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-10-22 21:00:20.292507 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-22 21:00:20.294225 | debian-bookworm | sudo: a password is required 2025-10-22 21:00:20.502243 | debian-bookworm | ok: Runtime: 0:00:00.027026 2025-10-22 21:00:20.520590 | 2025-10-22 21:00:20.520786 | TASK [tox : Check to see if the constraints file exists] 2025-10-22 21:00:20.555397 | debian-bookworm | skipping: Conditional result was False 2025-10-22 21:00:20.561363 | 2025-10-22 21:00:20.561472 | TASK [tox : Fail if constraints file is missing] 2025-10-22 21:00:20.584792 | debian-bookworm | skipping: Conditional result was False 2025-10-22 21:00:20.590760 | 2025-10-22 21:00:20.590912 | TASK [tox : Record file location] 2025-10-22 21:00:20.624673 | debian-bookworm | skipping: Conditional result was False 2025-10-22 21:00:20.639599 | 2025-10-22 21:00:20.639862 | TASK [tox : Install tox siblings] 2025-10-22 21:00:20.681497 | debian-bookworm | ok 2025-10-22 21:00:20.694386 | debian-bookworm | included: /var/lib/zuul/builds/15cd474297304e139f2226ae4ad8edf8/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-10-22 21:00:20.702381 | 2025-10-22 21:00:20.702584 | TASK [tox : Run tox without tests] 2025-10-22 21:00:21.825445 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-10-22 21:00:35.336343 | debian-bookworm | test: OK (14.09 seconds) 2025-10-22 21:00:35.336442 | debian-bookworm | congratulations :) (14.12 seconds) 2025-10-22 21:00:35.738730 | debian-bookworm | ok: Runtime: 0:00:14.403665 2025-10-22 21:00:35.752391 | 2025-10-22 21:00:35.752562 | TASK [tox : Create a tempfile to save tox showconfig] 2025-10-22 21:00:36.165010 | debian-bookworm | changed 2025-10-22 21:00:36.175262 | 2025-10-22 21:00:36.175430 | TASK [tox : Get tox envlist config] 2025-10-22 21:00:37.211751 | debian-bookworm | ok: Runtime: 0:00:00.392198 2025-10-22 21:00:37.223530 | 2025-10-22 21:00:37.223683 | TASK [tox : Install any sibling python packages] 2025-10-22 21:00:37.697058 | debian-bookworm | ok: No setup.cfg, no action needed 2025-10-22 21:00:37.705402 | 2025-10-22 21:00:37.705551 | TASK [tox : Remove tempfile] 2025-10-22 21:00:38.146610 | debian-bookworm | changed 2025-10-22 21:00:38.155913 | 2025-10-22 21:00:38.156097 | TASK [tox : Emit tox command] 2025-10-22 21:00:38.211219 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-10-22 21:00:38.218849 | 2025-10-22 21:00:38.219004 | TASK [tox : Run tox] 2025-10-22 21:00:38.879861 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-10-22 21:00:39.530451 | debian-bookworm | ............... 2025-10-22 21:00:39.530509 | debian-bookworm | ---------------------------------------------------------------------- 2025-10-22 21:00:39.530526 | debian-bookworm | Ran 15 tests in 0.166s 2025-10-22 21:00:39.530532 | debian-bookworm | 2025-10-22 21:00:39.530539 | debian-bookworm | OK 2025-10-22 21:00:39.619116 | debian-bookworm | test: OK (0.79=setup[0.06]+cmd[0.74] seconds) 2025-10-22 21:00:39.619184 | debian-bookworm | congratulations :) (0.82 seconds) 2025-10-22 21:00:39.783916 | debian-bookworm | ok: Runtime: 0:00:01.118455 2025-10-22 21:00:39.797306 | 2025-10-22 21:00:39.797460 | TASK [tox : Look for output] 2025-10-22 21:00:40.189117 | debian-bookworm | ok 2025-10-22 21:00:40.206536 | 2025-10-22 21:00:40.206657 | TASK [tox : Return file comments to Zuul] 2025-10-22 21:00:40.241171 | debian-bookworm | skipping: Conditional result was False 2025-10-22 21:00:40.252726 | 2025-10-22 21:00:40.252843 | PLAY RECAP 2025-10-22 21:00:40.252920 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-10-22 21:00:40.253017 | 2025-10-22 21:00:40.377183 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-22 21:00:40.378729 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-22 21:00:41.123313 | 2025-10-22 21:00:41.123471 | PLAY [all] 2025-10-22 21:00:41.138372 | 2025-10-22 21:00:41.138499 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-10-22 21:00:41.193581 | debian-bookworm | skipping: Conditional result was False 2025-10-22 21:00:41.204246 | 2025-10-22 21:00:41.204441 | TASK [fetch-tox-output : Set tox log path for single node] 2025-10-22 21:00:41.261466 | debian-bookworm | ok 2025-10-22 21:00:41.269353 | 2025-10-22 21:00:41.269489 | TASK [fetch-tox-output : Ensure local tox dir] 2025-10-22 21:00:41.738378 | debian-bookworm -> localhost | changed 2025-10-22 21:00:41.746405 | 2025-10-22 21:00:41.746524 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-10-22 21:00:41.800728 | debian-bookworm | skipping: Conditional result was False 2025-10-22 21:00:41.813516 | 2025-10-22 21:00:41.813728 | TASK [fetch-tox-output : Set envlist fact] 2025-10-22 21:00:41.846334 | debian-bookworm | ok 2025-10-22 21:00:41.853551 | 2025-10-22 21:00:41.853667 | TASK [fetch-tox-output : Get tox version] 2025-10-22 21:00:42.531518 | 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-10-22 21:00:42.549976 | debian-bookworm | 4.31.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-22 21:00:42.884700 | debian-bookworm | ok: Runtime: 0:00:00.301033 2025-10-22 21:00:42.896468 | 2025-10-22 21:00:42.896630 | TASK [fetch-tox-output : Find all default environments] 2025-10-22 21:00:43.428663 | debian-bookworm | skipping: Conditional result was False 2025-10-22 21:00:43.434986 | 2025-10-22 21:00:43.435123 | TASK [fetch-tox-output : Set envlist fact] 2025-10-22 21:00:43.469193 | debian-bookworm | skipping: Conditional result was False 2025-10-22 21:00:43.477996 | 2025-10-22 21:00:43.478136 | TASK [fetch-tox-output : Find all default environments] 2025-10-22 21:00:44.008559 | debian-bookworm | skipping: Conditional result was False 2025-10-22 21:00:44.022110 | 2025-10-22 21:00:44.022291 | TASK [fetch-tox-output : Set envlist fact] 2025-10-22 21:00:44.057726 | debian-bookworm | skipping: Conditional result was False 2025-10-22 21:00:44.070611 | 2025-10-22 21:00:44.070774 | LOOP [fetch-tox-output : Copy tox logs] 2025-10-22 21:00:44.122173 | debian-bookworm | skipping: Conditional result was False 2025-10-22 21:00:44.138459 | 2025-10-22 21:00:44.138611 | LOOP [fetch-tox-output : Collect tox logs] 2025-10-22 21:00:44.927034 | debian-bookworm | changed: 2025-10-22 21:00:44.927262 | debian-bookworm | created directory /var/lib/zuul/builds/15cd474297304e139f2226ae4ad8edf8/work/logs/tox/test 2025-10-22 21:00:44.927305 | debian-bookworm | cd+++++++++ ./ 2025-10-22 21:00:44.927337 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-10-22 21:00:44.927375 | 2025-10-22 21:00:44.940044 | 2025-10-22 21:00:44.940141 | PLAY RECAP 2025-10-22 21:00:44.940192 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-10-22 21:00:44.940217 | 2025-10-22 21:00:45.060719 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-22 21:00:45.061818 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-22 21:00:45.770057 | 2025-10-22 21:00:45.770214 | PLAY [all] 2025-10-22 21:00:45.785552 | 2025-10-22 21:00:45.785695 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-22 21:00:46.034620 | debian-bookworm | changed: non-zero return code 2025-10-22 21:00:46.043361 | 2025-10-22 21:00:46.043516 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-22 21:00:46.068028 | debian-bookworm | skipping: Conditional result was False 2025-10-22 21:00:46.076974 | 2025-10-22 21:00:46.077119 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-22 21:00:46.109608 | 2025-10-22 21:00:46.109890 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-22 21:00:46.140135 | 2025-10-22 21:00:46.140312 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-22 21:00:46.173756 | debian-bookworm | skipping: Conditional result was False 2025-10-22 21:00:46.186140 | 2025-10-22 21:00:46.186303 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-22 21:00:46.222411 | 2025-10-22 21:00:46.222700 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-22 21:00:46.237381 | debian-bookworm | skipping: Conditional result was False 2025-10-22 21:00:46.244764 | 2025-10-22 21:00:46.244883 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-22 21:00:46.258039 | debian-bookworm | skipping: Conditional result was False 2025-10-22 21:00:46.265080 | 2025-10-22 21:00:46.265197 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-22 21:00:46.278634 | debian-bookworm | skipping: Conditional result was False 2025-10-22 21:00:46.285978 | 2025-10-22 21:00:46.286063 | PLAY RECAP 2025-10-22 21:00:46.286119 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-22 21:00:46.286146 | 2025-10-22 21:00:46.407895 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-22 21:00:46.409035 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-22 21:00:47.108077 | 2025-10-22 21:00:47.108230 | PLAY [Base post] 2025-10-22 21:00:47.121470 | 2025-10-22 21:00:47.121598 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-22 21:00:47.729022 | debian-bookworm | changed 2025-10-22 21:00:47.735784 | 2025-10-22 21:00:47.735898 | PLAY RECAP 2025-10-22 21:00:47.735976 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-22 21:00:47.736039 | 2025-10-22 21:00:47.859153 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-22 21:00:47.861734 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-22 21:00:48.694086 | 2025-10-22 21:00:48.694240 | PLAY [Base post-logs] 2025-10-22 21:00:48.705281 | 2025-10-22 21:00:48.705415 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-22 21:00:49.150040 | localhost | changed 2025-10-22 21:00:49.163002 | 2025-10-22 21:00:49.163165 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-22 21:00:49.212030 | localhost | ok 2025-10-22 21:00:49.217572 | 2025-10-22 21:00:49.217722 | TASK [Set zuul-log-path fact] 2025-10-22 21:00:49.245035 | localhost | ok 2025-10-22 21:00:49.257787 | 2025-10-22 21:00:49.257923 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-22 21:00:49.288904 | localhost | ok 2025-10-22 21:00:49.295769 | 2025-10-22 21:00:49.295958 | TASK [upload-logs : Create log directories] 2025-10-22 21:00:49.804300 | localhost | changed 2025-10-22 21:00:49.809508 | 2025-10-22 21:00:49.809646 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-22 21:00:50.284192 | localhost -> localhost | ok: Runtime: 0:00:00.007263 2025-10-22 21:00:50.292560 | 2025-10-22 21:00:50.292676 | TASK [upload-logs : Upload logs to log server] 2025-10-22 21:00:50.857281 | localhost | Output suppressed because no_log was given 2025-10-22 21:00:50.860968 | 2025-10-22 21:00:50.861155 | LOOP [upload-logs : Compress console log and json output] 2025-10-22 21:00:50.923799 | localhost | skipping: Conditional result was False 2025-10-22 21:00:50.929422 | localhost | skipping: Conditional result was False 2025-10-22 21:00:50.940624 | 2025-10-22 21:00:50.940767 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-22 21:00:50.989791 | localhost | skipping: Conditional result was False 2025-10-22 21:00:50.990405 | 2025-10-22 21:00:50.993981 | localhost | skipping: Conditional result was False 2025-10-22 21:00:51.000573 | 2025-10-22 21:00:51.000753 | LOOP [upload-logs : Upload console log and json output]