2025-10-15 07:04:04.077439 | Job console starting 2025-10-15 07:04:04.130684 | Updating git repos 2025-10-15 07:04:04.214518 | Cloning repos into workspace 2025-10-15 07:04:04.354531 | Restoring repo states 2025-10-15 07:04:04.365991 | Merging changes 2025-10-15 07:04:04.975779 | Checking out repos 2025-10-15 07:04:05.039816 | Preparing playbooks 2025-10-15 07:04:05.825223 | Running Ansible setup 2025-10-15 07:04:11.035752 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-15 07:04:12.081488 | 2025-10-15 07:04:12.081646 | PLAY [Base pre] 2025-10-15 07:04:12.128258 | 2025-10-15 07:04:12.128422 | TASK [Setup log path fact] 2025-10-15 07:04:12.166159 | debian-bookworm | ok 2025-10-15 07:04:12.185270 | 2025-10-15 07:04:12.185423 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-15 07:04:12.246518 | debian-bookworm | ok 2025-10-15 07:04:12.265164 | 2025-10-15 07:04:12.265288 | TASK [emit-job-header : Print job information] 2025-10-15 07:04:12.350978 | # Job Information 2025-10-15 07:04:12.351200 | Ansible Version: 2.16.14 2025-10-15 07:04:12.351237 | Job: tox 2025-10-15 07:04:12.351270 | Pipeline: check 2025-10-15 07:04:12.351293 | Executor: 521e9411259a 2025-10-15 07:04:12.351313 | Triggered by: https://github.com/osism/openstack-image-manager/pull/960 2025-10-15 07:04:12.351335 | Event ID: 1d123880-a995-11f0-81e2-6154fbf9a0f7 2025-10-15 07:04:12.356360 | 2025-10-15 07:04:12.356472 | LOOP [emit-job-header : Print node information] 2025-10-15 07:04:12.538665 | debian-bookworm | ok: 2025-10-15 07:04:12.538825 | debian-bookworm | # Node Information 2025-10-15 07:04:12.538868 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-15 07:04:12.538889 | debian-bookworm | Hostname: debian 2025-10-15 07:04:12.538907 | debian-bookworm | Username: zuul 2025-10-15 07:04:12.538923 | debian-bookworm | Distro: Debian 12.12 2025-10-15 07:04:12.538942 | debian-bookworm | Provider: regiocloud-a 2025-10-15 07:04:12.538959 | debian-bookworm | Region: 2025-10-15 07:04:12.538975 | debian-bookworm | Label: debian-bookworm 2025-10-15 07:04:12.538991 | debian-bookworm | Product Name: OpenStack Nova 2025-10-15 07:04:12.539007 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe94:abae 2025-10-15 07:04:12.549318 | 2025-10-15 07:04:12.549411 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-15 07:04:13.106195 | debian-bookworm -> localhost | changed 2025-10-15 07:04:13.111796 | 2025-10-15 07:04:13.111878 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-15 07:04:14.288515 | debian-bookworm -> localhost | changed 2025-10-15 07:04:14.298754 | 2025-10-15 07:04:14.298873 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-15 07:04:14.597407 | debian-bookworm -> localhost | ok 2025-10-15 07:04:14.602147 | 2025-10-15 07:04:14.602233 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-15 07:04:14.628581 | debian-bookworm | ok 2025-10-15 07:04:14.641651 | debian-bookworm | included: /var/lib/zuul/builds/d3d948eb18944044aca87768f587e805/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-15 07:04:14.652159 | 2025-10-15 07:04:14.652244 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-15 07:04:16.299338 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-15 07:04:16.299501 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/d3d948eb18944044aca87768f587e805/work/d3d948eb18944044aca87768f587e805_id_rsa 2025-10-15 07:04:16.299531 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/d3d948eb18944044aca87768f587e805/work/d3d948eb18944044aca87768f587e805_id_rsa.pub 2025-10-15 07:04:16.299552 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-15 07:04:16.299572 | debian-bookworm -> localhost | SHA256:MkSNpNHzPv6v3A2H9/nk1igQpDYhtCUP/lM8BR94T6A zuul-build-sshkey 2025-10-15 07:04:16.299590 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-15 07:04:16.299615 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-15 07:04:16.299633 | debian-bookworm -> localhost | | .+*o. .o+. | 2025-10-15 07:04:16.299650 | debian-bookworm -> localhost | | ==*o.o+... | 2025-10-15 07:04:16.299666 | debian-bookworm -> localhost | | . ++.+E..o | 2025-10-15 07:04:16.299682 | debian-bookworm -> localhost | | . .=... . | 2025-10-15 07:04:16.299698 | debian-bookworm -> localhost | | ooS. . | 2025-10-15 07:04:16.299715 | debian-bookworm -> localhost | | oo.. . | 2025-10-15 07:04:16.299731 | debian-bookworm -> localhost | | . . .o o.o| 2025-10-15 07:04:16.299747 | debian-bookworm -> localhost | | .. ..=.++| 2025-10-15 07:04:16.299763 | debian-bookworm -> localhost | | .+oo..o=| 2025-10-15 07:04:16.299779 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-15 07:04:16.299820 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.080181 2025-10-15 07:04:16.305532 | 2025-10-15 07:04:16.305611 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-15 07:04:16.342368 | debian-bookworm | ok 2025-10-15 07:04:16.349607 | debian-bookworm | included: /var/lib/zuul/builds/d3d948eb18944044aca87768f587e805/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-15 07:04:16.363914 | 2025-10-15 07:04:16.363986 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-15 07:04:16.376184 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:04:16.380681 | 2025-10-15 07:04:16.380750 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-15 07:04:16.961966 | debian-bookworm | changed 2025-10-15 07:04:16.966167 | 2025-10-15 07:04:16.966242 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-15 07:04:17.245468 | debian-bookworm | ok 2025-10-15 07:04:17.257885 | 2025-10-15 07:04:17.258038 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-15 07:04:17.920609 | debian-bookworm | changed 2025-10-15 07:04:17.927179 | 2025-10-15 07:04:17.927267 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-15 07:04:18.590142 | debian-bookworm | changed 2025-10-15 07:04:18.595312 | 2025-10-15 07:04:18.595395 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-15 07:04:18.618130 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:04:18.623666 | 2025-10-15 07:04:18.623751 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-15 07:04:19.005412 | debian-bookworm -> localhost | changed 2025-10-15 07:04:19.022102 | 2025-10-15 07:04:19.022208 | TASK [add-build-sshkey : Add back temp key] 2025-10-15 07:04:19.636210 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/d3d948eb18944044aca87768f587e805/work/d3d948eb18944044aca87768f587e805_id_rsa (zuul-build-sshkey) 2025-10-15 07:04:19.636418 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012978 2025-10-15 07:04:19.644785 | 2025-10-15 07:04:19.644880 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-15 07:04:20.203295 | debian-bookworm | ok 2025-10-15 07:04:20.208183 | 2025-10-15 07:04:20.208277 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-15 07:04:20.241236 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:04:20.251036 | 2025-10-15 07:04:20.251151 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-15 07:04:20.646664 | debian-bookworm | ok 2025-10-15 07:04:20.666091 | 2025-10-15 07:04:20.666290 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-15 07:04:20.708362 | debian-bookworm | ok 2025-10-15 07:04:20.723954 | 2025-10-15 07:04:20.724085 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-15 07:04:20.978814 | debian-bookworm -> localhost | ok 2025-10-15 07:04:20.986820 | 2025-10-15 07:04:20.986938 | TASK [validate-host : Collect information about the host] 2025-10-15 07:04:22.143232 | debian-bookworm | ok 2025-10-15 07:04:22.155590 | 2025-10-15 07:04:22.155687 | TASK [validate-host : Sanitize hostname] 2025-10-15 07:04:22.264529 | debian-bookworm | ok 2025-10-15 07:04:22.295456 | 2025-10-15 07:04:22.295554 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-15 07:04:23.054674 | debian-bookworm -> localhost | changed 2025-10-15 07:04:23.064130 | 2025-10-15 07:04:23.064314 | TASK [validate-host : Collect information about zuul worker] 2025-10-15 07:04:23.535601 | debian-bookworm | ok 2025-10-15 07:04:23.541558 | 2025-10-15 07:04:23.541672 | TASK [validate-host : Write out all zuul information for each host] 2025-10-15 07:04:24.472178 | debian-bookworm -> localhost | changed 2025-10-15 07:04:24.495914 | 2025-10-15 07:04:24.496017 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-15 07:04:24.832669 | debian-bookworm | ok 2025-10-15 07:04:24.841708 | 2025-10-15 07:04:24.841803 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-15 07:04:26.005506 | debian-bookworm | changed: 2025-10-15 07:04:26.005944 | debian-bookworm | cd+++++++++ src/ 2025-10-15 07:04:26.006231 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-15 07:04:26.009873 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-15 07:04:26.024960 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-10-15 07:04:26.025018 | debian-bookworm | =6.2 2025-10-15 07:04:50.072176 | debian-bookworm | Downloading cachetools-6.2.1-py3-none-any.whl (11 kB) 2025-10-15 07:04:50.109155 | debian-bookworm | Collecting chardet>=5.2 2025-10-15 07:04:50.126139 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-10-15 07:04:50.151042 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 10.0 MB/s eta 0:00:00 2025-10-15 07:04:50.200929 | debian-bookworm | Collecting colorama>=0.4.6 2025-10-15 07:04:50.220254 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-10-15 07:04:50.292885 | debian-bookworm | Collecting filelock>=3.20 2025-10-15 07:04:50.307601 | debian-bookworm | Downloading filelock-3.20.0-py3-none-any.whl (16 kB) 2025-10-15 07:04:50.364825 | debian-bookworm | Collecting packaging>=25 2025-10-15 07:04:50.379350 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-15 07:04:50.389222 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 8.7 MB/s eta 0:00:00 2025-10-15 07:04:50.438688 | debian-bookworm | Collecting platformdirs>=4.5 2025-10-15 07:04:50.453295 | debian-bookworm | Downloading platformdirs-4.5.0-py3-none-any.whl (18 kB) 2025-10-15 07:04:50.495820 | debian-bookworm | Collecting pluggy>=1.6 2025-10-15 07:04:50.510924 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-10-15 07:04:50.552395 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-10-15 07:04:50.568722 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-10-15 07:04:50.698051 | debian-bookworm | Collecting virtualenv>=20.34 2025-10-15 07:04:50.712332 | debian-bookworm | Downloading virtualenv-20.35.3-py3-none-any.whl (6.0 MB) 2025-10-15 07:04:51.042138 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 18.5 MB/s eta 0:00:00 2025-10-15 07:04:51.156271 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-10-15 07:04:51.170806 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-10-15 07:04:51.199105 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 20.5 MB/s eta 0:00:00 2025-10-15 07:04:51.375100 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-10-15 07:04:52.326670 | 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-15 07:04:52.919491 | debian-bookworm | ok: Runtime: 0:00:03.283999 2025-10-15 07:04:52.925837 | 2025-10-15 07:04:52.925960 | TASK [ensure-tox : Export installed tox_executable path] 2025-10-15 07:04:52.957164 | debian-bookworm | ok 2025-10-15 07:04:52.963618 | 2025-10-15 07:04:52.963751 | TASK [ensure-tox : Output tox version] 2025-10-15 07:04:53.555683 | 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-15 07:04:53.575565 | debian-bookworm | 4.31.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-15 07:04:54.037393 | debian-bookworm | ok: Runtime: 0:00:00.345907 2025-10-15 07:04:54.043317 | 2025-10-15 07:04:54.043436 | TASK [ensure-tox : Make global symlink] 2025-10-15 07:04:54.067552 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:04:54.075858 | 2025-10-15 07:04:54.075982 | PLAY RECAP 2025-10-15 07:04:54.076088 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-10-15 07:04:54.076119 | 2025-10-15 07:04:54.222382 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-10-15 07:04:54.223685 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-15 07:04:54.993195 | 2025-10-15 07:04:54.993395 | PLAY [all] 2025-10-15 07:04:55.010952 | 2025-10-15 07:04:55.011156 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-10-15 07:04:55.521628 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-15 07:04:55.585414 | debian-bookworm | ok: Runtime: 0:00:00.027790 2025-10-15 07:04:55.597719 | 2025-10-15 07:04:55.597971 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-10-15 07:04:56.134131 | debian-bookworm | ok: Runtime: 0:00:00.006746 2025-10-15 07:04:56.150704 | 2025-10-15 07:04:56.150900 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-10-15 07:04:56.467355 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-15 07:04:56.469104 | debian-bookworm | sudo: a password is required 2025-10-15 07:04:56.694679 | debian-bookworm | ok: Runtime: 0:00:00.020522 2025-10-15 07:04:56.705493 | 2025-10-15 07:04:56.705601 | TASK [tox : Check to see if the constraints file exists] 2025-10-15 07:04:56.739907 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:04:56.746074 | 2025-10-15 07:04:56.746199 | TASK [tox : Fail if constraints file is missing] 2025-10-15 07:04:56.774791 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:04:56.781354 | 2025-10-15 07:04:56.781465 | TASK [tox : Record file location] 2025-10-15 07:04:56.805181 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:04:56.811813 | 2025-10-15 07:04:56.811919 | TASK [tox : Install tox siblings] 2025-10-15 07:04:56.834073 | debian-bookworm | ok 2025-10-15 07:04:56.843498 | debian-bookworm | included: /var/lib/zuul/builds/d3d948eb18944044aca87768f587e805/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-10-15 07:04:56.849254 | 2025-10-15 07:04:56.849357 | TASK [tox : Run tox without tests] 2025-10-15 07:04:57.933740 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-10-15 07:05:12.737921 | debian-bookworm | test: OK (15.36 seconds) 2025-10-15 07:05:12.737997 | debian-bookworm | congratulations :) (15.39 seconds) 2025-10-15 07:05:12.887519 | debian-bookworm | ok: Runtime: 0:00:15.689692 2025-10-15 07:05:12.893607 | 2025-10-15 07:05:12.893723 | TASK [tox : Create a tempfile to save tox showconfig] 2025-10-15 07:05:13.318278 | debian-bookworm | changed 2025-10-15 07:05:13.323664 | 2025-10-15 07:05:13.323779 | TASK [tox : Get tox envlist config] 2025-10-15 07:05:14.353111 | debian-bookworm | ok: Runtime: 0:00:00.344359 2025-10-15 07:05:14.359449 | 2025-10-15 07:05:14.359561 | TASK [tox : Install any sibling python packages] 2025-10-15 07:05:14.816356 | debian-bookworm | ok: No setup.cfg, no action needed 2025-10-15 07:05:14.824063 | 2025-10-15 07:05:14.824188 | TASK [tox : Remove tempfile] 2025-10-15 07:05:15.266254 | debian-bookworm | changed 2025-10-15 07:05:15.276823 | 2025-10-15 07:05:15.276968 | TASK [tox : Emit tox command] 2025-10-15 07:05:15.322513 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-10-15 07:05:15.330317 | 2025-10-15 07:05:15.330444 | TASK [tox : Run tox] 2025-10-15 07:05:15.966644 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-10-15 07:05:16.610106 | debian-bookworm | ............... 2025-10-15 07:05:16.610159 | debian-bookworm | ---------------------------------------------------------------------- 2025-10-15 07:05:16.610176 | debian-bookworm | Ran 15 tests in 0.177s 2025-10-15 07:05:16.610181 | debian-bookworm | 2025-10-15 07:05:16.610189 | debian-bookworm | OK 2025-10-15 07:05:16.696240 | debian-bookworm | test: OK (0.77=setup[0.04]+cmd[0.73] seconds) 2025-10-15 07:05:16.696318 | debian-bookworm | congratulations :) (0.80 seconds) 2025-10-15 07:05:16.898988 | debian-bookworm | ok: Runtime: 0:00:01.091654 2025-10-15 07:05:16.912349 | 2025-10-15 07:05:16.912554 | TASK [tox : Look for output] 2025-10-15 07:05:17.318006 | debian-bookworm | ok 2025-10-15 07:05:17.331885 | 2025-10-15 07:05:17.332018 | TASK [tox : Return file comments to Zuul] 2025-10-15 07:05:17.356870 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:05:17.365883 | 2025-10-15 07:05:17.365982 | PLAY RECAP 2025-10-15 07:05:17.366091 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-10-15 07:05:17.366119 | 2025-10-15 07:05:17.495741 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-15 07:05:17.496840 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-15 07:05:18.255995 | 2025-10-15 07:05:18.256162 | PLAY [all] 2025-10-15 07:05:18.271299 | 2025-10-15 07:05:18.271443 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-10-15 07:05:18.348563 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:05:18.355029 | 2025-10-15 07:05:18.355201 | TASK [fetch-tox-output : Set tox log path for single node] 2025-10-15 07:05:18.394946 | debian-bookworm | ok 2025-10-15 07:05:18.400140 | 2025-10-15 07:05:18.400253 | TASK [fetch-tox-output : Ensure local tox dir] 2025-10-15 07:05:18.868422 | debian-bookworm -> localhost | changed 2025-10-15 07:05:18.876373 | 2025-10-15 07:05:18.876484 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-10-15 07:05:18.920532 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:05:18.928942 | 2025-10-15 07:05:18.929128 | TASK [fetch-tox-output : Set envlist fact] 2025-10-15 07:05:18.974677 | debian-bookworm | ok 2025-10-15 07:05:18.994302 | 2025-10-15 07:05:18.994427 | TASK [fetch-tox-output : Get tox version] 2025-10-15 07:05:19.695100 | 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-15 07:05:19.723754 | debian-bookworm | 4.31.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-15 07:05:20.024881 | debian-bookworm | ok: Runtime: 0:00:00.335012 2025-10-15 07:05:20.033606 | 2025-10-15 07:05:20.033753 | TASK [fetch-tox-output : Find all default environments] 2025-10-15 07:05:20.588472 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:05:20.594857 | 2025-10-15 07:05:20.594996 | TASK [fetch-tox-output : Set envlist fact] 2025-10-15 07:05:20.629162 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:05:20.636630 | 2025-10-15 07:05:20.636749 | TASK [fetch-tox-output : Find all default environments] 2025-10-15 07:05:21.167027 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:05:21.180367 | 2025-10-15 07:05:21.180540 | TASK [fetch-tox-output : Set envlist fact] 2025-10-15 07:05:21.215918 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:05:21.229074 | 2025-10-15 07:05:21.229232 | LOOP [fetch-tox-output : Copy tox logs] 2025-10-15 07:05:21.281138 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:05:21.304649 | 2025-10-15 07:05:21.304825 | LOOP [fetch-tox-output : Collect tox logs] 2025-10-15 07:05:22.080956 | debian-bookworm | changed: 2025-10-15 07:05:22.081181 | debian-bookworm | created directory /var/lib/zuul/builds/d3d948eb18944044aca87768f587e805/work/logs/tox/test 2025-10-15 07:05:22.081219 | debian-bookworm | cd+++++++++ ./ 2025-10-15 07:05:22.081245 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-10-15 07:05:22.081274 | 2025-10-15 07:05:22.091443 | 2025-10-15 07:05:22.091537 | PLAY RECAP 2025-10-15 07:05:22.091589 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-10-15 07:05:22.091615 | 2025-10-15 07:05:22.228762 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-15 07:05:22.229845 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-15 07:05:22.974330 | 2025-10-15 07:05:22.974481 | PLAY [all] 2025-10-15 07:05:22.989680 | 2025-10-15 07:05:22.989807 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-15 07:05:23.232962 | debian-bookworm | changed: non-zero return code 2025-10-15 07:05:23.241682 | 2025-10-15 07:05:23.241876 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-15 07:05:23.267827 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:05:23.278557 | 2025-10-15 07:05:23.278721 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-15 07:05:23.317987 | 2025-10-15 07:05:23.318307 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-15 07:05:23.370007 | 2025-10-15 07:05:23.370302 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-15 07:05:23.396683 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:05:23.408923 | 2025-10-15 07:05:23.409217 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-15 07:05:23.439162 | 2025-10-15 07:05:23.439345 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-15 07:05:23.463723 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:05:23.472675 | 2025-10-15 07:05:23.472818 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-15 07:05:23.497075 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:05:23.510520 | 2025-10-15 07:05:23.510689 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-15 07:05:23.535918 | debian-bookworm | skipping: Conditional result was False 2025-10-15 07:05:23.550204 | 2025-10-15 07:05:23.550316 | PLAY RECAP 2025-10-15 07:05:23.550396 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-15 07:05:23.550437 | 2025-10-15 07:05:23.683704 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-15 07:05:23.686388 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-15 07:05:24.418871 | 2025-10-15 07:05:24.419023 | PLAY [Base post] 2025-10-15 07:05:24.432648 | 2025-10-15 07:05:24.432777 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-15 07:05:25.013007 | debian-bookworm | changed 2025-10-15 07:05:25.019783 | 2025-10-15 07:05:25.019894 | PLAY RECAP 2025-10-15 07:05:25.019959 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-15 07:05:25.020020 | 2025-10-15 07:05:25.137188 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-15 07:05:25.138399 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-15 07:05:25.928975 | 2025-10-15 07:05:25.929181 | PLAY [Base post-logs] 2025-10-15 07:05:25.939728 | 2025-10-15 07:05:25.939858 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-15 07:05:26.371910 | localhost | changed 2025-10-15 07:05:26.387035 | 2025-10-15 07:05:26.387281 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-15 07:05:26.416322 | localhost | ok 2025-10-15 07:05:26.422146 | 2025-10-15 07:05:26.422292 | TASK [Set zuul-log-path fact] 2025-10-15 07:05:26.439436 | localhost | ok 2025-10-15 07:05:26.451822 | 2025-10-15 07:05:26.451948 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-15 07:05:26.478991 | localhost | ok 2025-10-15 07:05:26.484121 | 2025-10-15 07:05:26.484261 | TASK [upload-logs : Create log directories] 2025-10-15 07:05:26.971893 | localhost | changed 2025-10-15 07:05:26.977501 | 2025-10-15 07:05:26.977656 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-15 07:05:27.498345 | localhost -> localhost | ok: Runtime: 0:00:00.006818 2025-10-15 07:05:27.507884 | 2025-10-15 07:05:27.508133 | TASK [upload-logs : Upload logs to log server] 2025-10-15 07:05:28.053374 | localhost | Output suppressed because no_log was given 2025-10-15 07:05:28.060833 | 2025-10-15 07:05:28.060974 | LOOP [upload-logs : Compress console log and json output] 2025-10-15 07:05:28.122752 | localhost | skipping: Conditional result was False 2025-10-15 07:05:28.127252 | localhost | skipping: Conditional result was False 2025-10-15 07:05:28.132702 | 2025-10-15 07:05:28.132870 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-15 07:05:28.180176 | localhost | skipping: Conditional result was False 2025-10-15 07:05:28.180571 | 2025-10-15 07:05:28.184089 | localhost | skipping: Conditional result was False 2025-10-15 07:05:28.194986 | 2025-10-15 07:05:28.195207 | LOOP [upload-logs : Upload console log and json output]