2025-09-21 00:18:45.368721 | Job console starting 2025-09-21 00:18:45.401442 | Updating git repos 2025-09-21 00:18:45.424579 | Cloning repos into workspace 2025-09-21 00:18:45.486072 | Restoring repo states 2025-09-21 00:18:45.492564 | Merging changes 2025-09-21 00:18:45.996506 | Checking out repos 2025-09-21 00:18:46.061419 | Preparing playbooks 2025-09-21 00:18:46.981981 | Running Ansible setup 2025-09-21 00:18:52.014227 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-21 00:18:53.107071 | 2025-09-21 00:18:53.107196 | PLAY [Base pre] 2025-09-21 00:18:53.129215 | 2025-09-21 00:18:53.129333 | TASK [Setup log path fact] 2025-09-21 00:18:53.151780 | debian-bookworm | ok 2025-09-21 00:18:53.176822 | 2025-09-21 00:18:53.176964 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-21 00:18:53.216525 | debian-bookworm | ok 2025-09-21 00:18:53.225878 | 2025-09-21 00:18:53.225981 | TASK [emit-job-header : Print job information] 2025-09-21 00:18:53.268446 | # Job Information 2025-09-21 00:18:53.268619 | Ansible Version: 2.16.14 2025-09-21 00:18:53.268654 | Job: tox 2025-09-21 00:18:53.268686 | Pipeline: check 2025-09-21 00:18:53.268709 | Executor: 521e9411259a 2025-09-21 00:18:53.268729 | Triggered by: https://github.com/osism/openstack-image-manager/pull/918 2025-09-21 00:18:53.268750 | Event ID: 651d6190-9680-11f0-9114-6f36c6501891 2025-09-21 00:18:53.273910 | 2025-09-21 00:18:53.274013 | LOOP [emit-job-header : Print node information] 2025-09-21 00:18:53.380517 | debian-bookworm | ok: 2025-09-21 00:18:53.380785 | debian-bookworm | # Node Information 2025-09-21 00:18:53.380824 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-21 00:18:53.380850 | debian-bookworm | Hostname: debian 2025-09-21 00:18:53.380872 | debian-bookworm | Username: zuul 2025-09-21 00:18:53.380892 | debian-bookworm | Distro: Debian 12.12 2025-09-21 00:18:53.380918 | debian-bookworm | Provider: regiocloud-a 2025-09-21 00:18:53.380941 | debian-bookworm | Region: 2025-09-21 00:18:53.380962 | debian-bookworm | Label: debian-bookworm 2025-09-21 00:18:53.380981 | debian-bookworm | Product Name: OpenStack Nova 2025-09-21 00:18:53.381000 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe1b:9787 2025-09-21 00:18:53.402096 | 2025-09-21 00:18:53.402209 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-21 00:18:53.904066 | debian-bookworm -> localhost | changed 2025-09-21 00:18:53.913107 | 2025-09-21 00:18:53.913396 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-21 00:18:55.410081 | debian-bookworm -> localhost | changed 2025-09-21 00:18:55.431481 | 2025-09-21 00:18:55.432651 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-21 00:18:55.991766 | debian-bookworm -> localhost | ok 2025-09-21 00:18:55.998261 | 2025-09-21 00:18:55.998374 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-21 00:18:56.047153 | debian-bookworm | ok 2025-09-21 00:18:56.080713 | debian-bookworm | included: /var/lib/zuul/builds/d06e6e9c96a74e7d869eaaa7955cb576/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-21 00:18:56.098618 | 2025-09-21 00:18:56.098711 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-21 00:19:00.204541 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-21 00:19:00.204738 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/d06e6e9c96a74e7d869eaaa7955cb576/work/d06e6e9c96a74e7d869eaaa7955cb576_id_rsa 2025-09-21 00:19:00.204772 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/d06e6e9c96a74e7d869eaaa7955cb576/work/d06e6e9c96a74e7d869eaaa7955cb576_id_rsa.pub 2025-09-21 00:19:00.204795 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-21 00:19:00.204817 | debian-bookworm -> localhost | SHA256:WUp/6j0tnrB5moV7fO9X/brKk2/rOIryFg0Nw0vdIaM zuul-build-sshkey 2025-09-21 00:19:00.204836 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-21 00:19:00.204862 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-21 00:19:00.204880 | debian-bookworm -> localhost | | . .o... | 2025-09-21 00:19:00.204898 | debian-bookworm -> localhost | | =..o. | 2025-09-21 00:19:00.204915 | debian-bookworm -> localhost | | .E=. | 2025-09-21 00:19:00.204931 | debian-bookworm -> localhost | | .o=. | 2025-09-21 00:19:00.204948 | debian-bookworm -> localhost | | So. . .| 2025-09-21 00:19:00.204965 | debian-bookworm -> localhost | | . .+ o| 2025-09-21 00:19:00.204982 | debian-bookworm -> localhost | | .+..o o| 2025-09-21 00:19:00.204999 | debian-bookworm -> localhost | | . .o XXo+ o| 2025-09-21 00:19:00.205016 | debian-bookworm -> localhost | | +o O*B@**+| 2025-09-21 00:19:00.205044 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-21 00:19:00.205090 | debian-bookworm -> localhost | ok: Runtime: 0:00:03.269225 2025-09-21 00:19:00.210071 | 2025-09-21 00:19:00.210150 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-21 00:19:00.246504 | debian-bookworm | ok 2025-09-21 00:19:00.254082 | debian-bookworm | included: /var/lib/zuul/builds/d06e6e9c96a74e7d869eaaa7955cb576/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-21 00:19:00.271710 | 2025-09-21 00:19:00.271794 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-21 00:19:00.314573 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:19:00.319341 | 2025-09-21 00:19:00.319436 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-21 00:19:00.945405 | debian-bookworm | changed 2025-09-21 00:19:00.950471 | 2025-09-21 00:19:00.950557 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-21 00:19:01.213755 | debian-bookworm | ok 2025-09-21 00:19:01.218251 | 2025-09-21 00:19:01.218326 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-21 00:19:01.915432 | debian-bookworm | changed 2025-09-21 00:19:01.923019 | 2025-09-21 00:19:01.923137 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-21 00:19:02.645699 | debian-bookworm | changed 2025-09-21 00:19:02.653368 | 2025-09-21 00:19:02.653460 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-21 00:19:02.688255 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:19:02.704665 | 2025-09-21 00:19:02.704757 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-21 00:19:03.481634 | debian-bookworm -> localhost | changed 2025-09-21 00:19:03.490264 | 2025-09-21 00:19:03.490350 | TASK [add-build-sshkey : Add back temp key] 2025-09-21 00:19:04.140475 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/d06e6e9c96a74e7d869eaaa7955cb576/work/d06e6e9c96a74e7d869eaaa7955cb576_id_rsa (zuul-build-sshkey) 2025-09-21 00:19:04.140656 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.009254 2025-09-21 00:19:04.146782 | 2025-09-21 00:19:04.146958 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-21 00:19:04.631477 | debian-bookworm | ok 2025-09-21 00:19:04.635570 | 2025-09-21 00:19:04.635649 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-21 00:19:04.673518 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:19:04.682473 | 2025-09-21 00:19:04.682577 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-21 00:19:05.200843 | debian-bookworm | ok 2025-09-21 00:19:05.215196 | 2025-09-21 00:19:05.215286 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-21 00:19:05.306565 | debian-bookworm | ok 2025-09-21 00:19:05.312709 | 2025-09-21 00:19:05.312798 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-21 00:19:06.025071 | debian-bookworm -> localhost | ok 2025-09-21 00:19:06.041130 | 2025-09-21 00:19:06.041214 | TASK [validate-host : Collect information about the host] 2025-09-21 00:19:07.151930 | debian-bookworm | ok 2025-09-21 00:19:07.196262 | 2025-09-21 00:19:07.197109 | TASK [validate-host : Sanitize hostname] 2025-09-21 00:19:07.416896 | debian-bookworm | ok 2025-09-21 00:19:07.509051 | 2025-09-21 00:19:07.509155 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-21 00:19:08.454938 | debian-bookworm -> localhost | changed 2025-09-21 00:19:08.459805 | 2025-09-21 00:19:08.459886 | TASK [validate-host : Collect information about zuul worker] 2025-09-21 00:19:08.855267 | debian-bookworm | ok 2025-09-21 00:19:08.858898 | 2025-09-21 00:19:08.858976 | TASK [validate-host : Write out all zuul information for each host] 2025-09-21 00:19:10.167109 | debian-bookworm -> localhost | changed 2025-09-21 00:19:10.174753 | 2025-09-21 00:19:10.174851 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-21 00:19:10.485055 | debian-bookworm | ok 2025-09-21 00:19:10.490980 | 2025-09-21 00:19:10.491078 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-21 00:19:11.433179 | debian-bookworm | changed: 2025-09-21 00:19:11.433337 | debian-bookworm | cd+++++++++ src/ 2025-09-21 00:19:11.433365 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-21 00:19:11.433385 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-21 00:19:11.433403 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-09-21 00:19:11.433420 | debian-bookworm | =6.1 2025-09-21 00:19:35.037977 | debian-bookworm | Downloading cachetools-6.2.0-py3-none-any.whl (11 kB) 2025-09-21 00:19:35.071902 | debian-bookworm | Collecting chardet>=5.2 2025-09-21 00:19:35.080191 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-09-21 00:19:35.096374 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 15.3 MB/s eta 0:00:00 2025-09-21 00:19:35.157178 | debian-bookworm | Collecting colorama>=0.4.6 2025-09-21 00:19:35.166805 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-09-21 00:19:35.232864 | debian-bookworm | Collecting filelock>=3.18 2025-09-21 00:19:35.240908 | debian-bookworm | Downloading filelock-3.19.1-py3-none-any.whl (15 kB) 2025-09-21 00:19:35.297368 | debian-bookworm | Collecting packaging>=25 2025-09-21 00:19:35.305379 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-09-21 00:19:35.316692 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.6 MB/s eta 0:00:00 2025-09-21 00:19:35.372365 | debian-bookworm | Collecting platformdirs>=4.3.8 2025-09-21 00:19:35.380742 | debian-bookworm | Downloading platformdirs-4.4.0-py3-none-any.whl (18 kB) 2025-09-21 00:19:35.419614 | debian-bookworm | Collecting pluggy>=1.6 2025-09-21 00:19:35.427944 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-09-21 00:19:35.464162 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-09-21 00:19:35.472724 | debian-bookworm | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2025-09-21 00:19:35.600003 | debian-bookworm | Collecting virtualenv>=20.31.2 2025-09-21 00:19:35.608358 | debian-bookworm | Downloading virtualenv-20.34.0-py3-none-any.whl (6.0 MB) 2025-09-21 00:19:35.852504 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 25.1 MB/s eta 0:00:00 2025-09-21 00:19:35.962531 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-09-21 00:19:35.970576 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-09-21 00:19:35.995652 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 22.8 MB/s eta 0:00:00 2025-09-21 00:19:36.159188 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-09-21 00:19:37.116237 | 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-21 00:19:37.401713 | debian-bookworm | ok: Runtime: 0:00:03.071273 2025-09-21 00:19:37.416662 | 2025-09-21 00:19:37.416896 | TASK [ensure-tox : Export installed tox_executable path] 2025-09-21 00:19:37.452124 | debian-bookworm | ok 2025-09-21 00:19:37.456745 | 2025-09-21 00:19:37.456840 | TASK [ensure-tox : Output tox version] 2025-09-21 00:19:37.979740 | 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-21 00:19:37.999770 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-21 00:19:38.483855 | debian-bookworm | ok: Runtime: 0:00:00.324741 2025-09-21 00:19:38.489732 | 2025-09-21 00:19:38.489823 | TASK [ensure-tox : Make global symlink] 2025-09-21 00:19:38.512964 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:19:38.519234 | 2025-09-21 00:19:38.519308 | PLAY RECAP 2025-09-21 00:19:38.519359 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-09-21 00:19:38.519384 | 2025-09-21 00:19:38.728462 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-09-21 00:19:38.729462 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-21 00:19:39.649545 | 2025-09-21 00:19:39.649672 | PLAY [all] 2025-09-21 00:19:39.674703 | 2025-09-21 00:19:39.674905 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-09-21 00:19:40.193764 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-21 00:19:40.242106 | debian-bookworm | ok: Runtime: 0:00:00.023030 2025-09-21 00:19:40.246447 | 2025-09-21 00:19:40.246531 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-09-21 00:19:40.840269 | debian-bookworm | ok: Runtime: 0:00:00.006576 2025-09-21 00:19:40.845211 | 2025-09-21 00:19:40.845290 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-09-21 00:19:41.143615 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-21 00:19:41.146729 | debian-bookworm | sudo: a password is required 2025-09-21 00:19:41.389355 | debian-bookworm | ok: Runtime: 0:00:00.026464 2025-09-21 00:19:41.399616 | 2025-09-21 00:19:41.399710 | TASK [tox : Check to see if the constraints file exists] 2025-09-21 00:19:41.433321 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:19:41.439355 | 2025-09-21 00:19:41.439456 | TASK [tox : Fail if constraints file is missing] 2025-09-21 00:19:41.475208 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:19:41.481912 | 2025-09-21 00:19:41.482312 | TASK [tox : Record file location] 2025-09-21 00:19:41.526970 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:19:41.532692 | 2025-09-21 00:19:41.532793 | TASK [tox : Install tox siblings] 2025-09-21 00:19:41.571716 | debian-bookworm | ok 2025-09-21 00:19:41.579660 | debian-bookworm | included: /var/lib/zuul/builds/d06e6e9c96a74e7d869eaaa7955cb576/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-09-21 00:19:41.586641 | 2025-09-21 00:19:41.586727 | TASK [tox : Run tox without tests] 2025-09-21 00:19:42.861304 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-09-21 00:19:56.561926 | debian-bookworm | test: OK (14.35 seconds) 2025-09-21 00:19:56.562054 | debian-bookworm | congratulations :) (14.39 seconds) 2025-09-21 00:19:56.681867 | debian-bookworm | ok: Runtime: 0:00:14.701491 2025-09-21 00:19:56.689673 | 2025-09-21 00:19:56.689909 | TASK [tox : Create a tempfile to save tox showconfig] 2025-09-21 00:19:57.133881 | debian-bookworm | changed 2025-09-21 00:19:57.142419 | 2025-09-21 00:19:57.142558 | TASK [tox : Get tox envlist config] 2025-09-21 00:19:58.177553 | debian-bookworm | ok: Runtime: 0:00:00.355502 2025-09-21 00:19:58.190987 | 2025-09-21 00:19:58.192047 | TASK [tox : Install any sibling python packages] 2025-09-21 00:19:58.669983 | debian-bookworm | ok: No setup.cfg, no action needed 2025-09-21 00:19:58.680216 | 2025-09-21 00:19:58.680366 | TASK [tox : Remove tempfile] 2025-09-21 00:19:59.127559 | debian-bookworm | changed 2025-09-21 00:19:59.133646 | 2025-09-21 00:19:59.133751 | TASK [tox : Emit tox command] 2025-09-21 00:19:59.176167 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-09-21 00:19:59.181320 | 2025-09-21 00:19:59.181432 | TASK [tox : Run tox] 2025-09-21 00:19:59.808546 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-09-21 00:20:00.476616 | debian-bookworm | ............... 2025-09-21 00:20:00.476690 | debian-bookworm | ---------------------------------------------------------------------- 2025-09-21 00:20:00.476710 | debian-bookworm | Ran 15 tests in 0.186s 2025-09-21 00:20:00.476715 | debian-bookworm | 2025-09-21 00:20:00.476724 | debian-bookworm | OK 2025-09-21 00:20:00.582297 | debian-bookworm | test: OK (0.82=setup[0.05]+cmd[0.77] seconds) 2025-09-21 00:20:00.582391 | debian-bookworm | congratulations :) (0.85 seconds) 2025-09-21 00:20:00.748104 | debian-bookworm | ok: Runtime: 0:00:01.142134 2025-09-21 00:20:00.760904 | 2025-09-21 00:20:00.761091 | TASK [tox : Look for output] 2025-09-21 00:20:01.174394 | debian-bookworm | ok 2025-09-21 00:20:01.190439 | 2025-09-21 00:20:01.190601 | TASK [tox : Return file comments to Zuul] 2025-09-21 00:20:01.239187 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:20:01.248302 | 2025-09-21 00:20:01.248430 | PLAY RECAP 2025-09-21 00:20:01.248497 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-09-21 00:20:01.248527 | 2025-09-21 00:20:01.392849 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-21 00:20:01.393946 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-21 00:20:02.154736 | 2025-09-21 00:20:02.154943 | PLAY [all] 2025-09-21 00:20:02.170770 | 2025-09-21 00:20:02.170949 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-09-21 00:20:02.216053 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:20:02.222941 | 2025-09-21 00:20:02.223142 | TASK [fetch-tox-output : Set tox log path for single node] 2025-09-21 00:20:02.264407 | debian-bookworm | ok 2025-09-21 00:20:02.274263 | 2025-09-21 00:20:02.274386 | TASK [fetch-tox-output : Ensure local tox dir] 2025-09-21 00:20:03.018461 | debian-bookworm -> localhost | changed 2025-09-21 00:20:03.032949 | 2025-09-21 00:20:03.033130 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-09-21 00:20:03.077889 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:20:03.092808 | 2025-09-21 00:20:03.092998 | TASK [fetch-tox-output : Set envlist fact] 2025-09-21 00:20:03.141963 | debian-bookworm | ok 2025-09-21 00:20:03.147839 | 2025-09-21 00:20:03.147958 | TASK [fetch-tox-output : Get tox version] 2025-09-21 00:20:03.865633 | 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-21 00:20:03.884274 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-21 00:20:04.179552 | debian-bookworm | ok: Runtime: 0:00:00.305365 2025-09-21 00:20:04.189046 | 2025-09-21 00:20:04.189143 | TASK [fetch-tox-output : Find all default environments] 2025-09-21 00:20:04.722618 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:20:04.728338 | 2025-09-21 00:20:04.728437 | TASK [fetch-tox-output : Set envlist fact] 2025-09-21 00:20:04.771775 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:20:04.777134 | 2025-09-21 00:20:04.777234 | TASK [fetch-tox-output : Find all default environments] 2025-09-21 00:20:05.317701 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:20:05.323424 | 2025-09-21 00:20:05.323525 | TASK [fetch-tox-output : Set envlist fact] 2025-09-21 00:20:05.366596 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:20:05.372760 | 2025-09-21 00:20:05.372906 | LOOP [fetch-tox-output : Copy tox logs] 2025-09-21 00:20:05.449328 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:20:05.462555 | 2025-09-21 00:20:05.462668 | LOOP [fetch-tox-output : Collect tox logs] 2025-09-21 00:20:06.446101 | debian-bookworm | changed: 2025-09-21 00:20:06.446305 | debian-bookworm | created directory /var/lib/zuul/builds/d06e6e9c96a74e7d869eaaa7955cb576/work/logs/tox/test 2025-09-21 00:20:06.446345 | debian-bookworm | cd+++++++++ ./ 2025-09-21 00:20:06.446370 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-09-21 00:20:06.446396 | 2025-09-21 00:20:06.454363 | 2025-09-21 00:20:06.454432 | PLAY RECAP 2025-09-21 00:20:06.454492 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-09-21 00:20:06.454518 | 2025-09-21 00:20:06.590470 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-21 00:20:06.591443 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-21 00:20:07.302213 | 2025-09-21 00:20:07.302354 | PLAY [all] 2025-09-21 00:20:07.318495 | 2025-09-21 00:20:07.318597 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-09-21 00:20:07.544417 | debian-bookworm | changed: non-zero return code 2025-09-21 00:20:07.554978 | 2025-09-21 00:20:07.555135 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-09-21 00:20:07.568468 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:20:07.575110 | 2025-09-21 00:20:07.575243 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-09-21 00:20:07.596454 | 2025-09-21 00:20:07.596718 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-09-21 00:20:07.627563 | 2025-09-21 00:20:07.627730 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-09-21 00:20:07.650560 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:20:07.656169 | 2025-09-21 00:20:07.656264 | LOOP [fetch-subunit-output : Generate subunit file] 2025-09-21 00:20:07.683929 | 2025-09-21 00:20:07.684102 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-09-21 00:20:07.696710 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:20:07.702391 | 2025-09-21 00:20:07.702483 | TASK [fetch-subunit-output : Remove the temporary file] 2025-09-21 00:20:07.735286 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:20:07.741909 | 2025-09-21 00:20:07.742111 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-09-21 00:20:07.765560 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:20:07.775973 | 2025-09-21 00:20:07.776202 | PLAY RECAP 2025-09-21 00:20:07.776259 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-21 00:20:07.776285 | 2025-09-21 00:20:07.885648 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-21 00:20:07.887644 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-21 00:20:08.596662 | 2025-09-21 00:20:08.596826 | PLAY [Base post] 2025-09-21 00:20:08.611206 | 2025-09-21 00:20:08.611312 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-21 00:20:09.150914 | debian-bookworm | changed 2025-09-21 00:20:09.160620 | 2025-09-21 00:20:09.160732 | PLAY RECAP 2025-09-21 00:20:09.160805 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-21 00:20:09.160884 | 2025-09-21 00:20:09.246779 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-21 00:20:09.249321 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-21 00:20:10.031399 | 2025-09-21 00:20:10.031525 | PLAY [Base post-logs] 2025-09-21 00:20:10.040759 | 2025-09-21 00:20:10.040868 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-21 00:20:10.443734 | localhost | changed 2025-09-21 00:20:10.458333 | 2025-09-21 00:20:10.458482 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-21 00:20:10.496346 | localhost | ok 2025-09-21 00:20:10.499677 | 2025-09-21 00:20:10.499776 | TASK [Set zuul-log-path fact] 2025-09-21 00:20:10.518001 | localhost | ok 2025-09-21 00:20:10.531308 | 2025-09-21 00:20:10.531434 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-21 00:20:10.568742 | localhost | ok 2025-09-21 00:20:10.574867 | 2025-09-21 00:20:10.575001 | TASK [upload-logs : Create log directories] 2025-09-21 00:20:10.999239 | localhost | changed 2025-09-21 00:20:11.001812 | 2025-09-21 00:20:11.001890 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-21 00:20:11.484515 | localhost -> localhost | ok: Runtime: 0:00:00.006687 2025-09-21 00:20:11.489662 | 2025-09-21 00:20:11.489799 | TASK [upload-logs : Upload logs to log server] 2025-09-21 00:20:12.019262 | localhost | Output suppressed because no_log was given 2025-09-21 00:20:12.021605 | 2025-09-21 00:20:12.021731 | LOOP [upload-logs : Compress console log and json output] 2025-09-21 00:20:12.067898 | localhost | skipping: Conditional result was False 2025-09-21 00:20:12.072892 | localhost | skipping: Conditional result was False 2025-09-21 00:20:12.086230 | 2025-09-21 00:20:12.086437 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-21 00:20:12.129199 | localhost | skipping: Conditional result was False 2025-09-21 00:20:12.129578 | 2025-09-21 00:20:12.133479 | localhost | skipping: Conditional result was False 2025-09-21 00:20:12.147285 | 2025-09-21 00:20:12.147502 | LOOP [upload-logs : Upload console log and json output]