2025-11-26 00:17:20.258828 | Job console starting 2025-11-26 00:17:20.273017 | Updating git repos 2025-11-26 00:17:20.318937 | Cloning repos into workspace 2025-11-26 00:17:20.394746 | Restoring repo states 2025-11-26 00:17:20.402755 | Merging changes 2025-11-26 00:17:20.924266 | Checking out repos 2025-11-26 00:17:21.002559 | Preparing playbooks 2025-11-26 00:17:22.056753 | Running Ansible setup 2025-11-26 00:17:26.861813 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-11-26 00:17:27.858971 | 2025-11-26 00:17:27.859178 | PLAY [Base pre] 2025-11-26 00:17:27.877349 | 2025-11-26 00:17:27.877502 | TASK [Setup log path fact] 2025-11-26 00:17:27.917178 | debian-bookworm | ok 2025-11-26 00:17:27.944251 | 2025-11-26 00:17:27.944426 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-26 00:17:28.001947 | debian-bookworm | ok 2025-11-26 00:17:28.022393 | 2025-11-26 00:17:28.022538 | TASK [emit-job-header : Print job information] 2025-11-26 00:17:28.092472 | # Job Information 2025-11-26 00:17:28.092671 | Ansible Version: 2.16.14 2025-11-26 00:17:28.092708 | Job: tox 2025-11-26 00:17:28.092741 | Pipeline: check 2025-11-26 00:17:28.092765 | Executor: 521e9411259a 2025-11-26 00:17:28.092786 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1005 2025-11-26 00:17:28.092808 | Event ID: 40678220-ca5d-11f0-9c89-a0cb0a628b99 2025-11-26 00:17:28.098268 | 2025-11-26 00:17:28.098396 | LOOP [emit-job-header : Print node information] 2025-11-26 00:17:28.316348 | debian-bookworm | ok: 2025-11-26 00:17:28.316612 | debian-bookworm | # Node Information 2025-11-26 00:17:28.316652 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-11-26 00:17:28.316679 | debian-bookworm | Hostname: debian 2025-11-26 00:17:28.316701 | debian-bookworm | Username: zuul 2025-11-26 00:17:28.316721 | debian-bookworm | Distro: Debian 12.12 2025-11-26 00:17:28.316745 | debian-bookworm | Provider: regiocloud-a 2025-11-26 00:17:28.316766 | debian-bookworm | Region: 2025-11-26 00:17:28.316786 | debian-bookworm | Label: debian-bookworm 2025-11-26 00:17:28.316805 | debian-bookworm | Product Name: OpenStack Nova 2025-11-26 00:17:28.316824 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe5a:3d3d 2025-11-26 00:17:28.353536 | 2025-11-26 00:17:28.353680 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-26 00:17:29.136164 | debian-bookworm -> localhost | changed 2025-11-26 00:17:29.143637 | 2025-11-26 00:17:29.143769 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-26 00:17:30.452218 | debian-bookworm -> localhost | changed 2025-11-26 00:17:30.463612 | 2025-11-26 00:17:30.463745 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-26 00:17:30.772468 | debian-bookworm -> localhost | ok 2025-11-26 00:17:30.788680 | 2025-11-26 00:17:30.788917 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-26 00:17:30.819952 | debian-bookworm | ok 2025-11-26 00:17:30.837827 | debian-bookworm | included: /var/lib/zuul/builds/909fc8dd865a4d799eb6e546ec22f913/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-26 00:17:30.855769 | 2025-11-26 00:17:30.855895 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-26 00:17:32.474068 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-11-26 00:17:32.474318 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/909fc8dd865a4d799eb6e546ec22f913/work/909fc8dd865a4d799eb6e546ec22f913_id_rsa 2025-11-26 00:17:32.474359 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/909fc8dd865a4d799eb6e546ec22f913/work/909fc8dd865a4d799eb6e546ec22f913_id_rsa.pub 2025-11-26 00:17:32.474386 | debian-bookworm -> localhost | The key fingerprint is: 2025-11-26 00:17:32.474412 | debian-bookworm -> localhost | SHA256:fZM00U5pgWXP3ZJWYIqiEV6PjjFE8icaC6RF6DoRvpQ zuul-build-sshkey 2025-11-26 00:17:32.474434 | debian-bookworm -> localhost | The key's randomart image is: 2025-11-26 00:17:32.474472 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-11-26 00:17:32.474493 | debian-bookworm -> localhost | | o+ ..+ . .+*+.| 2025-11-26 00:17:32.474515 | debian-bookworm -> localhost | |o+ = o o ..===o| 2025-11-26 00:17:32.474535 | debian-bookworm -> localhost | |+.o . B + o +++ =| 2025-11-26 00:17:32.474555 | debian-bookworm -> localhost | |.E . + X o . +.. | 2025-11-26 00:17:32.474575 | debian-bookworm -> localhost | |o.. o o S . + | 2025-11-26 00:17:32.474599 | debian-bookworm -> localhost | |o. . . | 2025-11-26 00:17:32.474620 | debian-bookworm -> localhost | | . | 2025-11-26 00:17:32.474640 | debian-bookworm -> localhost | | | 2025-11-26 00:17:32.474660 | debian-bookworm -> localhost | | | 2025-11-26 00:17:32.474680 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-11-26 00:17:32.474729 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.943646 2025-11-26 00:17:32.482060 | 2025-11-26 00:17:32.482230 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-26 00:17:32.521544 | debian-bookworm | ok 2025-11-26 00:17:32.537830 | debian-bookworm | included: /var/lib/zuul/builds/909fc8dd865a4d799eb6e546ec22f913/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-26 00:17:32.559206 | 2025-11-26 00:17:32.559331 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-26 00:17:32.601565 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:17:32.608946 | 2025-11-26 00:17:32.609070 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-26 00:17:33.305984 | debian-bookworm | changed 2025-11-26 00:17:33.312281 | 2025-11-26 00:17:33.312387 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-26 00:17:33.561476 | debian-bookworm | ok 2025-11-26 00:17:33.569709 | 2025-11-26 00:17:33.569843 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-26 00:17:34.295175 | debian-bookworm | changed 2025-11-26 00:17:34.305537 | 2025-11-26 00:17:34.305689 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-26 00:17:34.913873 | debian-bookworm | changed 2025-11-26 00:17:34.924464 | 2025-11-26 00:17:34.924609 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-26 00:17:34.950219 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:17:34.958737 | 2025-11-26 00:17:34.958905 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-26 00:17:35.481260 | debian-bookworm -> localhost | changed 2025-11-26 00:17:35.493781 | 2025-11-26 00:17:35.493934 | TASK [add-build-sshkey : Add back temp key] 2025-11-26 00:17:35.880712 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/909fc8dd865a4d799eb6e546ec22f913/work/909fc8dd865a4d799eb6e546ec22f913_id_rsa (zuul-build-sshkey) 2025-11-26 00:17:35.880961 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.018165 2025-11-26 00:17:35.894409 | 2025-11-26 00:17:35.894546 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-26 00:17:36.310893 | debian-bookworm | ok 2025-11-26 00:17:36.316002 | 2025-11-26 00:17:36.316156 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-26 00:17:36.358908 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:17:36.377823 | 2025-11-26 00:17:36.378003 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-26 00:17:36.897033 | debian-bookworm | ok 2025-11-26 00:17:36.923319 | 2025-11-26 00:17:36.923504 | TASK [validate-host : Define zuul_info_dir fact] 2025-11-26 00:17:36.954942 | debian-bookworm | ok 2025-11-26 00:17:36.962415 | 2025-11-26 00:17:36.962537 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-11-26 00:17:37.458461 | debian-bookworm -> localhost | ok 2025-11-26 00:17:37.465140 | 2025-11-26 00:17:37.465249 | TASK [validate-host : Collect information about the host] 2025-11-26 00:17:38.577614 | debian-bookworm | ok 2025-11-26 00:17:38.598952 | 2025-11-26 00:17:38.599096 | TASK [validate-host : Sanitize hostname] 2025-11-26 00:17:38.756138 | debian-bookworm | ok 2025-11-26 00:17:38.867253 | 2025-11-26 00:17:38.867416 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-11-26 00:17:39.512456 | debian-bookworm -> localhost | changed 2025-11-26 00:17:39.526376 | 2025-11-26 00:17:39.526551 | TASK [validate-host : Collect information about zuul worker] 2025-11-26 00:17:39.967232 | debian-bookworm | ok 2025-11-26 00:17:39.972820 | 2025-11-26 00:17:39.972961 | TASK [validate-host : Write out all zuul information for each host] 2025-11-26 00:17:40.804265 | debian-bookworm -> localhost | changed 2025-11-26 00:17:40.814767 | 2025-11-26 00:17:40.814938 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-11-26 00:17:41.089009 | debian-bookworm | ok 2025-11-26 00:17:41.096384 | 2025-11-26 00:17:41.096532 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-11-26 00:17:42.081094 | debian-bookworm | changed: 2025-11-26 00:17:42.081353 | debian-bookworm | cd+++++++++ src/ 2025-11-26 00:17:42.081391 | debian-bookworm | cd+++++++++ src/github.com/ 2025-11-26 00:17:42.081415 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-11-26 00:17:42.081437 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-11-26 00:17:42.081457 | debian-bookworm | =6.2 2025-11-26 00:18:04.243004 | debian-bookworm | Downloading cachetools-6.2.2-py3-none-any.whl (11 kB) 2025-11-26 00:18:04.298772 | debian-bookworm | Collecting chardet>=5.2 2025-11-26 00:18:04.310616 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-11-26 00:18:04.331276 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 11.8 MB/s eta 0:00:00 2025-11-26 00:18:04.385897 | debian-bookworm | Collecting colorama>=0.4.6 2025-11-26 00:18:04.397765 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-11-26 00:18:04.454996 | debian-bookworm | Collecting filelock>=3.20 2025-11-26 00:18:04.466156 | debian-bookworm | Downloading filelock-3.20.0-py3-none-any.whl (16 kB) 2025-11-26 00:18:04.527244 | debian-bookworm | Collecting packaging>=25 2025-11-26 00:18:04.538431 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-11-26 00:18:04.549011 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 10.8 MB/s eta 0:00:00 2025-11-26 00:18:04.600293 | debian-bookworm | Collecting platformdirs>=4.5 2025-11-26 00:18:04.611761 | debian-bookworm | Downloading platformdirs-4.5.0-py3-none-any.whl (18 kB) 2025-11-26 00:18:04.654188 | debian-bookworm | Collecting pluggy>=1.6 2025-11-26 00:18:04.665280 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-11-26 00:18:04.713671 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-11-26 00:18:04.725821 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-11-26 00:18:04.853585 | debian-bookworm | Collecting virtualenv>=20.34 2025-11-26 00:18:04.865646 | debian-bookworm | Downloading virtualenv-20.35.4-py3-none-any.whl (6.0 MB) 2025-11-26 00:18:05.155171 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 21.1 MB/s eta 0:00:00 2025-11-26 00:18:05.257195 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-11-26 00:18:05.268220 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-11-26 00:18:05.290555 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 23.9 MB/s eta 0:00:00 2025-11-26 00:18:05.434086 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-11-26 00:18:06.343935 | debian-bookworm | Successfully installed cachetools-6.2.2 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.32.0 virtualenv-20.35.4 2025-11-26 00:18:06.622968 | debian-bookworm | ok: Runtime: 0:00:03.077308 2025-11-26 00:18:06.636433 | 2025-11-26 00:18:06.636621 | TASK [ensure-tox : Export installed tox_executable path] 2025-11-26 00:18:06.695362 | debian-bookworm | ok 2025-11-26 00:18:06.702587 | 2025-11-26 00:18:06.702740 | TASK [ensure-tox : Output tox version] 2025-11-26 00:18:07.220577 | 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-11-26 00:18:07.239167 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-11-26 00:18:07.738678 | debian-bookworm | ok: Runtime: 0:00:00.293691 2025-11-26 00:18:07.744672 | 2025-11-26 00:18:07.744789 | TASK [ensure-tox : Make global symlink] 2025-11-26 00:18:07.768925 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:18:07.775793 | 2025-11-26 00:18:07.775886 | PLAY RECAP 2025-11-26 00:18:07.775951 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-11-26 00:18:07.775976 | 2025-11-26 00:18:07.922011 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-11-26 00:18:07.923294 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-11-26 00:18:08.791859 | 2025-11-26 00:18:08.792035 | PLAY [all] 2025-11-26 00:18:08.811587 | 2025-11-26 00:18:08.811750 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-11-26 00:18:09.277899 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-11-26 00:18:09.344830 | debian-bookworm | ok: Runtime: 0:00:00.028846 2025-11-26 00:18:09.353903 | 2025-11-26 00:18:09.354229 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-11-26 00:18:09.889582 | debian-bookworm | ok: Runtime: 0:00:00.007468 2025-11-26 00:18:09.904195 | 2025-11-26 00:18:09.904410 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-11-26 00:18:10.219044 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-11-26 00:18:10.221747 | debian-bookworm | sudo: a password is required 2025-11-26 00:18:10.448343 | debian-bookworm | ok: Runtime: 0:00:00.024435 2025-11-26 00:18:10.459634 | 2025-11-26 00:18:10.459806 | TASK [tox : Check to see if the constraints file exists] 2025-11-26 00:18:10.484968 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:18:10.492583 | 2025-11-26 00:18:10.492775 | TASK [tox : Fail if constraints file is missing] 2025-11-26 00:18:10.530660 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:18:10.537896 | 2025-11-26 00:18:10.538012 | TASK [tox : Record file location] 2025-11-26 00:18:10.574478 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:18:10.586345 | 2025-11-26 00:18:10.586504 | TASK [tox : Install tox siblings] 2025-11-26 00:18:10.625720 | debian-bookworm | ok 2025-11-26 00:18:10.635110 | debian-bookworm | included: /var/lib/zuul/builds/909fc8dd865a4d799eb6e546ec22f913/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-11-26 00:18:10.641594 | 2025-11-26 00:18:10.641725 | TASK [tox : Run tox without tests] 2025-11-26 00:18:11.747108 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-11-26 00:18:25.556043 | debian-bookworm | test: OK (14.38 seconds) 2025-11-26 00:18:25.556101 | debian-bookworm | congratulations :) (14.40 seconds) 2025-11-26 00:18:25.679433 | debian-bookworm | ok: Runtime: 0:00:14.692775 2025-11-26 00:18:25.688474 | 2025-11-26 00:18:25.688614 | TASK [tox : Create a tempfile to save tox showconfig] 2025-11-26 00:18:26.151003 | debian-bookworm | changed 2025-11-26 00:18:26.160936 | 2025-11-26 00:18:26.161088 | TASK [tox : Get tox envlist config] 2025-11-26 00:18:27.195012 | debian-bookworm | ok: Runtime: 0:00:00.326259 2025-11-26 00:18:27.210146 | 2025-11-26 00:18:27.210337 | TASK [tox : Install any sibling python packages] 2025-11-26 00:18:27.656441 | debian-bookworm | ok: No setup.cfg, no action needed 2025-11-26 00:18:27.662246 | 2025-11-26 00:18:27.662353 | TASK [tox : Remove tempfile] 2025-11-26 00:18:28.105377 | debian-bookworm | changed 2025-11-26 00:18:28.117496 | 2025-11-26 00:18:28.117702 | TASK [tox : Emit tox command] 2025-11-26 00:18:28.163505 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-11-26 00:18:28.174599 | 2025-11-26 00:18:28.174787 | TASK [tox : Run tox] 2025-11-26 00:18:28.794500 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-11-26 00:18:29.415310 | debian-bookworm | ............... 2025-11-26 00:18:29.415361 | debian-bookworm | ---------------------------------------------------------------------- 2025-11-26 00:18:29.415377 | debian-bookworm | Ran 15 tests in 0.169s 2025-11-26 00:18:29.415382 | debian-bookworm | 2025-11-26 00:18:29.415389 | debian-bookworm | OK 2025-11-26 00:18:29.503435 | debian-bookworm | test: OK (0.75=setup[0.04]+cmd[0.71] seconds) 2025-11-26 00:18:29.503496 | debian-bookworm | congratulations :) (0.78 seconds) 2025-11-26 00:18:29.748222 | debian-bookworm | ok: Runtime: 0:00:01.057778 2025-11-26 00:18:29.756963 | 2025-11-26 00:18:29.757095 | TASK [tox : Look for output] 2025-11-26 00:18:30.162278 | debian-bookworm | ok 2025-11-26 00:18:30.182574 | 2025-11-26 00:18:30.182779 | TASK [tox : Return file comments to Zuul] 2025-11-26 00:18:30.229133 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:18:30.245049 | 2025-11-26 00:18:30.245190 | PLAY RECAP 2025-11-26 00:18:30.245320 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-11-26 00:18:30.245371 | 2025-11-26 00:18:30.382190 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-11-26 00:18:30.383602 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-11-26 00:18:31.157377 | 2025-11-26 00:18:31.157547 | PLAY [all] 2025-11-26 00:18:31.173562 | 2025-11-26 00:18:31.173719 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-11-26 00:18:31.229582 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:18:31.235664 | 2025-11-26 00:18:31.235822 | TASK [fetch-tox-output : Set tox log path for single node] 2025-11-26 00:18:31.275822 | debian-bookworm | ok 2025-11-26 00:18:31.281686 | 2025-11-26 00:18:31.281806 | TASK [fetch-tox-output : Ensure local tox dir] 2025-11-26 00:18:31.774422 | debian-bookworm -> localhost | changed 2025-11-26 00:18:31.783600 | 2025-11-26 00:18:31.783747 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-11-26 00:18:31.839014 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:18:31.845412 | 2025-11-26 00:18:31.845543 | TASK [fetch-tox-output : Set envlist fact] 2025-11-26 00:18:31.892245 | debian-bookworm | ok 2025-11-26 00:18:31.901618 | 2025-11-26 00:18:31.901747 | TASK [fetch-tox-output : Get tox version] 2025-11-26 00:18:32.564696 | 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-11-26 00:18:32.582982 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-11-26 00:18:32.934382 | debian-bookworm | ok: Runtime: 0:00:00.280464 2025-11-26 00:18:32.946097 | 2025-11-26 00:18:32.946326 | TASK [fetch-tox-output : Find all default environments] 2025-11-26 00:18:33.479740 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:18:33.492878 | 2025-11-26 00:18:33.493164 | TASK [fetch-tox-output : Set envlist fact] 2025-11-26 00:18:33.518439 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:18:33.524511 | 2025-11-26 00:18:33.524639 | TASK [fetch-tox-output : Find all default environments] 2025-11-26 00:18:34.054494 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:18:34.068746 | 2025-11-26 00:18:34.068945 | TASK [fetch-tox-output : Set envlist fact] 2025-11-26 00:18:34.104225 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:18:34.110949 | 2025-11-26 00:18:34.111072 | LOOP [fetch-tox-output : Copy tox logs] 2025-11-26 00:18:34.160169 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:18:34.172956 | 2025-11-26 00:18:34.173097 | LOOP [fetch-tox-output : Collect tox logs] 2025-11-26 00:18:34.991221 | debian-bookworm | changed: 2025-11-26 00:18:34.991540 | debian-bookworm | created directory /var/lib/zuul/builds/909fc8dd865a4d799eb6e546ec22f913/work/logs/tox/test 2025-11-26 00:18:34.991594 | debian-bookworm | cd+++++++++ ./ 2025-11-26 00:18:34.991621 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-11-26 00:18:34.991655 | 2025-11-26 00:18:35.008638 | 2025-11-26 00:18:35.008755 | PLAY RECAP 2025-11-26 00:18:35.008813 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-11-26 00:18:35.008838 | 2025-11-26 00:18:35.159851 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-11-26 00:18:35.160982 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-26 00:18:35.940945 | 2025-11-26 00:18:35.941111 | PLAY [all] 2025-11-26 00:18:35.958766 | 2025-11-26 00:18:35.958942 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-11-26 00:18:36.210655 | debian-bookworm | changed: non-zero return code 2025-11-26 00:18:36.216345 | 2025-11-26 00:18:36.216472 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-11-26 00:18:36.250729 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:18:36.257480 | 2025-11-26 00:18:36.257607 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-11-26 00:18:36.304583 | 2025-11-26 00:18:36.304887 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-11-26 00:18:36.353223 | 2025-11-26 00:18:36.353490 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-11-26 00:18:36.388304 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:18:36.401858 | 2025-11-26 00:18:36.402040 | LOOP [fetch-subunit-output : Generate subunit file] 2025-11-26 00:18:36.444968 | 2025-11-26 00:18:36.445264 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-11-26 00:18:36.469199 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:18:36.479893 | 2025-11-26 00:18:36.480089 | TASK [fetch-subunit-output : Remove the temporary file] 2025-11-26 00:18:36.506795 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:18:36.522144 | 2025-11-26 00:18:36.522373 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-11-26 00:18:36.548077 | debian-bookworm | skipping: Conditional result was False 2025-11-26 00:18:36.560830 | 2025-11-26 00:18:36.560961 | PLAY RECAP 2025-11-26 00:18:36.561039 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-26 00:18:36.561082 | 2025-11-26 00:18:36.693237 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-26 00:18:36.694396 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-26 00:18:37.477714 | 2025-11-26 00:18:37.477883 | PLAY [Base post] 2025-11-26 00:18:37.492509 | 2025-11-26 00:18:37.492680 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-11-26 00:18:38.048925 | debian-bookworm | changed 2025-11-26 00:18:38.055389 | 2025-11-26 00:18:38.055500 | PLAY RECAP 2025-11-26 00:18:38.055564 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-26 00:18:38.055625 | 2025-11-26 00:18:38.184762 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-26 00:18:38.185903 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-11-26 00:18:38.998970 | 2025-11-26 00:18:38.999156 | PLAY [Base post-logs] 2025-11-26 00:18:39.011579 | 2025-11-26 00:18:39.011770 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-26 00:18:39.496266 | localhost | changed 2025-11-26 00:18:39.509138 | 2025-11-26 00:18:39.509350 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-26 00:18:39.545159 | localhost | ok 2025-11-26 00:18:39.548385 | 2025-11-26 00:18:39.548482 | TASK [Set zuul-log-path fact] 2025-11-26 00:18:39.564627 | localhost | ok 2025-11-26 00:18:39.573530 | 2025-11-26 00:18:39.573649 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-26 00:18:39.610106 | localhost | ok 2025-11-26 00:18:39.615799 | 2025-11-26 00:18:39.615968 | TASK [upload-logs : Create log directories] 2025-11-26 00:18:40.151666 | localhost | changed 2025-11-26 00:18:40.154528 | 2025-11-26 00:18:40.154642 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-11-26 00:18:40.675955 | localhost -> localhost | ok: Runtime: 0:00:00.014592 2025-11-26 00:18:40.681209 | 2025-11-26 00:18:40.681385 | TASK [upload-logs : Upload logs to log server] 2025-11-26 00:18:41.289629 | localhost | Output suppressed because no_log was given 2025-11-26 00:18:41.293376 | 2025-11-26 00:18:41.293599 | LOOP [upload-logs : Compress console log and json output] 2025-11-26 00:18:41.349188 | localhost | skipping: Conditional result was False 2025-11-26 00:18:41.354470 | localhost | skipping: Conditional result was False 2025-11-26 00:18:41.364611 | 2025-11-26 00:18:41.364807 | LOOP [upload-logs : Upload compressed console log and json output] 2025-11-26 00:18:41.417599 | localhost | skipping: Conditional result was False 2025-11-26 00:18:41.417894 | 2025-11-26 00:18:41.425168 | localhost | skipping: Conditional result was False 2025-11-26 00:18:41.436610 | 2025-11-26 00:18:41.436959 | LOOP [upload-logs : Upload console log and json output]