2025-09-22 03:09:35.295137 | Job console starting 2025-09-22 03:09:35.302295 | Updating git repos 2025-09-22 03:09:35.354506 | Cloning repos into workspace 2025-09-22 03:09:35.429607 | Restoring repo states 2025-09-22 03:09:35.443838 | Merging changes 2025-09-22 03:09:35.443856 | Checking out repos 2025-09-22 03:09:35.580320 | Preparing playbooks 2025-09-22 03:09:36.597791 | Running Ansible setup 2025-09-22 03:09:41.591423 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-22 03:09:42.680936 | 2025-09-22 03:09:42.681063 | PLAY [Base pre] 2025-09-22 03:09:42.717112 | 2025-09-22 03:09:42.717239 | TASK [Setup log path fact] 2025-09-22 03:09:42.735715 | debian-bookworm | ok 2025-09-22 03:09:42.756095 | 2025-09-22 03:09:42.756220 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-22 03:09:42.784827 | debian-bookworm | ok 2025-09-22 03:09:42.794191 | 2025-09-22 03:09:42.794291 | TASK [emit-job-header : Print job information] 2025-09-22 03:09:42.854045 | # Job Information 2025-09-22 03:09:42.854225 | Ansible Version: 2.16.14 2025-09-22 03:09:42.854261 | Job: tox 2025-09-22 03:09:42.854294 | Pipeline: periodic-daily 2025-09-22 03:09:42.854317 | Executor: 521e9411259a 2025-09-22 03:09:42.854338 | Triggered by: https://github.com/osism/openstack-image-manager 2025-09-22 03:09:42.854362 | Event ID: c32e9af1a9ca42389e28ffd7f313d7d1 2025-09-22 03:09:42.863630 | 2025-09-22 03:09:42.863754 | LOOP [emit-job-header : Print node information] 2025-09-22 03:09:43.052930 | debian-bookworm | ok: 2025-09-22 03:09:43.053129 | debian-bookworm | # Node Information 2025-09-22 03:09:43.053165 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-22 03:09:43.053190 | debian-bookworm | Hostname: debian 2025-09-22 03:09:43.053212 | debian-bookworm | Username: zuul 2025-09-22 03:09:43.053232 | debian-bookworm | Distro: Debian 12.12 2025-09-22 03:09:43.053255 | debian-bookworm | Provider: regiocloud-a 2025-09-22 03:09:43.053277 | debian-bookworm | Region: 2025-09-22 03:09:43.053298 | debian-bookworm | Label: debian-bookworm 2025-09-22 03:09:43.053317 | debian-bookworm | Product Name: OpenStack Nova 2025-09-22 03:09:43.053338 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe65:165c 2025-09-22 03:09:43.066870 | 2025-09-22 03:09:43.067006 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-22 03:09:43.626331 | debian-bookworm -> localhost | changed 2025-09-22 03:09:43.638823 | 2025-09-22 03:09:43.638995 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-22 03:09:45.166136 | debian-bookworm -> localhost | changed 2025-09-22 03:09:45.178018 | 2025-09-22 03:09:45.178115 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-22 03:09:45.630149 | debian-bookworm -> localhost | ok 2025-09-22 03:09:45.636037 | 2025-09-22 03:09:45.636131 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-22 03:09:45.694407 | debian-bookworm | ok 2025-09-22 03:09:45.737590 | debian-bookworm | included: /var/lib/zuul/builds/aeca9d9ea62f4d1989baac4f75d2d812/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-22 03:09:45.755694 | 2025-09-22 03:09:45.755792 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-22 03:09:46.983036 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-22 03:09:46.983227 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/aeca9d9ea62f4d1989baac4f75d2d812/work/aeca9d9ea62f4d1989baac4f75d2d812_id_rsa 2025-09-22 03:09:46.983265 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/aeca9d9ea62f4d1989baac4f75d2d812/work/aeca9d9ea62f4d1989baac4f75d2d812_id_rsa.pub 2025-09-22 03:09:46.983292 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-22 03:09:46.983315 | debian-bookworm -> localhost | SHA256:1gnNJHXjrcCMVUZWHjM5udZfWKjSjRSGkkGPJnuImqU zuul-build-sshkey 2025-09-22 03:09:46.983337 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-22 03:09:46.983368 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-22 03:09:46.983391 | debian-bookworm -> localhost | | .++++Xo=+ | 2025-09-22 03:09:46.983413 | debian-bookworm -> localhost | | o%.*.+=+.| 2025-09-22 03:09:46.983435 | debian-bookworm -> localhost | | . =.Bo.+o* | 2025-09-22 03:09:46.983456 | debian-bookworm -> localhost | | . = o.o+.= o| 2025-09-22 03:09:46.983506 | debian-bookworm -> localhost | | o o S o... o| 2025-09-22 03:09:46.983530 | debian-bookworm -> localhost | | = o .| 2025-09-22 03:09:46.983552 | debian-bookworm -> localhost | | E | 2025-09-22 03:09:46.983573 | debian-bookworm -> localhost | | | 2025-09-22 03:09:46.983595 | debian-bookworm -> localhost | | | 2025-09-22 03:09:46.983615 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-22 03:09:46.983668 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.450677 2025-09-22 03:09:46.989982 | 2025-09-22 03:09:46.990074 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-22 03:09:47.027650 | debian-bookworm | ok 2025-09-22 03:09:47.040373 | debian-bookworm | included: /var/lib/zuul/builds/aeca9d9ea62f4d1989baac4f75d2d812/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-22 03:09:47.058703 | 2025-09-22 03:09:47.058782 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-22 03:09:47.115154 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:09:47.121152 | 2025-09-22 03:09:47.121249 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-22 03:09:47.869344 | debian-bookworm | changed 2025-09-22 03:09:47.873705 | 2025-09-22 03:09:47.873774 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-22 03:09:48.167967 | debian-bookworm | ok 2025-09-22 03:09:48.174954 | 2025-09-22 03:09:48.175117 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-22 03:09:48.848816 | debian-bookworm | changed 2025-09-22 03:09:48.861270 | 2025-09-22 03:09:48.861876 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-22 03:09:49.483869 | debian-bookworm | changed 2025-09-22 03:09:49.490999 | 2025-09-22 03:09:49.491096 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-22 03:09:49.514220 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:09:49.524196 | 2025-09-22 03:09:49.524322 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-22 03:09:49.943351 | debian-bookworm -> localhost | changed 2025-09-22 03:09:49.953610 | 2025-09-22 03:09:49.953707 | TASK [add-build-sshkey : Add back temp key] 2025-09-22 03:09:50.261880 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/aeca9d9ea62f4d1989baac4f75d2d812/work/aeca9d9ea62f4d1989baac4f75d2d812_id_rsa (zuul-build-sshkey) 2025-09-22 03:09:50.262090 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.019020 2025-09-22 03:09:50.269486 | 2025-09-22 03:09:50.269607 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-22 03:09:50.666556 | debian-bookworm | ok 2025-09-22 03:09:50.671405 | 2025-09-22 03:09:50.671499 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-22 03:09:50.694545 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:09:50.704948 | 2025-09-22 03:09:50.705049 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-22 03:09:51.111551 | debian-bookworm | ok 2025-09-22 03:09:51.124329 | 2025-09-22 03:09:51.124848 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-22 03:09:51.154114 | debian-bookworm | ok 2025-09-22 03:09:51.159902 | 2025-09-22 03:09:51.159999 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-22 03:09:51.476101 | debian-bookworm -> localhost | ok 2025-09-22 03:09:51.482625 | 2025-09-22 03:09:51.482712 | TASK [validate-host : Collect information about the host] 2025-09-22 03:09:52.571236 | debian-bookworm | ok 2025-09-22 03:09:52.582444 | 2025-09-22 03:09:52.582584 | TASK [validate-host : Sanitize hostname] 2025-09-22 03:09:52.693981 | debian-bookworm | ok 2025-09-22 03:09:52.736685 | 2025-09-22 03:09:52.736820 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-22 03:09:53.392124 | debian-bookworm -> localhost | changed 2025-09-22 03:09:53.399254 | 2025-09-22 03:09:53.399377 | TASK [validate-host : Collect information about zuul worker] 2025-09-22 03:09:53.892432 | debian-bookworm | ok 2025-09-22 03:09:53.898339 | 2025-09-22 03:09:53.898501 | TASK [validate-host : Write out all zuul information for each host] 2025-09-22 03:09:55.082387 | debian-bookworm -> localhost | changed 2025-09-22 03:09:55.095614 | 2025-09-22 03:09:55.095744 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-22 03:09:55.457306 | debian-bookworm | ok 2025-09-22 03:09:55.468991 | 2025-09-22 03:09:55.470776 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-22 03:09:56.648497 | debian-bookworm | changed: 2025-09-22 03:09:56.648782 | debian-bookworm | cd+++++++++ src/ 2025-09-22 03:09:56.648839 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-22 03:09:56.648883 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-22 03:09:56.648926 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-09-22 03:09:56.648965 | debian-bookworm | =6.1 2025-09-22 03:10:19.101560 | debian-bookworm | Downloading cachetools-6.2.0-py3-none-any.whl (11 kB) 2025-09-22 03:10:19.142816 | debian-bookworm | Collecting chardet>=5.2 2025-09-22 03:10:19.151037 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-09-22 03:10:19.169263 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 13.2 MB/s eta 0:00:00 2025-09-22 03:10:19.204020 | debian-bookworm | Collecting colorama>=0.4.6 2025-09-22 03:10:19.212355 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-09-22 03:10:19.262791 | debian-bookworm | Collecting filelock>=3.18 2025-09-22 03:10:19.270224 | debian-bookworm | Downloading filelock-3.19.1-py3-none-any.whl (15 kB) 2025-09-22 03:10:19.314840 | debian-bookworm | Collecting packaging>=25 2025-09-22 03:10:19.322657 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-09-22 03:10:19.330645 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 11.2 MB/s eta 0:00:00 2025-09-22 03:10:19.372514 | debian-bookworm | Collecting platformdirs>=4.3.8 2025-09-22 03:10:19.380668 | debian-bookworm | Downloading platformdirs-4.4.0-py3-none-any.whl (18 kB) 2025-09-22 03:10:19.418445 | debian-bookworm | Collecting pluggy>=1.6 2025-09-22 03:10:19.425811 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-09-22 03:10:19.472687 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-09-22 03:10:19.481145 | debian-bookworm | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2025-09-22 03:10:19.608874 | debian-bookworm | Collecting virtualenv>=20.31.2 2025-09-22 03:10:19.617029 | debian-bookworm | Downloading virtualenv-20.34.0-py3-none-any.whl (6.0 MB) 2025-09-22 03:10:19.878850 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 23.3 MB/s eta 0:00:00 2025-09-22 03:10:19.980222 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-09-22 03:10:19.987789 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-09-22 03:10:20.015844 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 20.5 MB/s eta 0:00:00 2025-09-22 03:10:20.166688 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-09-22 03:10:21.106903 | 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-22 03:10:22.921787 | debian-bookworm | ok: Runtime: 0:00:02.905702 2025-09-22 03:10:22.927294 | 2025-09-22 03:10:22.927384 | TASK [ensure-tox : Export installed tox_executable path] 2025-09-22 03:10:22.956790 | debian-bookworm | ok 2025-09-22 03:10:22.971407 | 2025-09-22 03:10:22.971519 | TASK [ensure-tox : Output tox version] 2025-09-22 03:10:22.146982 | 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-22 03:10:22.165414 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-22 03:10:24.016613 | debian-bookworm | ok: Runtime: 0:00:00.300536 2025-09-22 03:10:24.021264 | 2025-09-22 03:10:24.021332 | TASK [ensure-tox : Make global symlink] 2025-09-22 03:10:24.043970 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:10:24.048993 | 2025-09-22 03:10:24.049047 | PLAY RECAP 2025-09-22 03:10:24.049089 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-09-22 03:10:24.049111 | 2025-09-22 03:10:24.228227 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-09-22 03:10:24.229044 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-22 03:10:24.946701 | 2025-09-22 03:10:24.946808 | PLAY [all] 2025-09-22 03:10:24.960126 | 2025-09-22 03:10:24.960224 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-09-22 03:10:24.064539 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-22 03:10:25.490539 | debian-bookworm | ok: Runtime: 0:00:00.025703 2025-09-22 03:10:25.495503 | 2025-09-22 03:10:25.495609 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-09-22 03:10:26.016527 | debian-bookworm | ok: Runtime: 0:00:00.006751 2025-09-22 03:10:26.022217 | 2025-09-22 03:10:26.022316 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-09-22 03:10:24.950362 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-22 03:10:24.954008 | debian-bookworm | sudo: a password is required 2025-09-22 03:10:26.549120 | debian-bookworm | ok: Runtime: 0:00:00.023916 2025-09-22 03:10:26.558534 | 2025-09-22 03:10:26.558625 | TASK [tox : Check to see if the constraints file exists] 2025-09-22 03:10:26.578789 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:10:26.593829 | 2025-09-22 03:10:26.593930 | TASK [tox : Fail if constraints file is missing] 2025-09-22 03:10:26.647031 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:10:26.653104 | 2025-09-22 03:10:26.653210 | TASK [tox : Record file location] 2025-09-22 03:10:26.697135 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:10:26.703291 | 2025-09-22 03:10:26.703396 | TASK [tox : Install tox siblings] 2025-09-22 03:10:26.737301 | debian-bookworm | ok 2025-09-22 03:10:26.745600 | debian-bookworm | included: /var/lib/zuul/builds/aeca9d9ea62f4d1989baac4f75d2d812/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-09-22 03:10:26.751213 | 2025-09-22 03:10:26.751302 | TASK [tox : Run tox without tests] 2025-09-22 03:10:26.553099 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-09-22 03:10:40.307552 | debian-bookworm | test: OK (12.93 seconds) 2025-09-22 03:10:40.307677 | debian-bookworm | congratulations :) (12.96 seconds) 2025-09-22 03:10:40.846558 | debian-bookworm | ok: Runtime: 0:00:14.618327 2025-09-22 03:10:40.853431 | 2025-09-22 03:10:40.853527 | TASK [tox : Create a tempfile to save tox showconfig] 2025-09-22 03:10:41.256867 | debian-bookworm | changed 2025-09-22 03:10:41.261912 | 2025-09-22 03:10:41.262000 | TASK [tox : Get tox envlist config] 2025-09-22 03:10:42.289193 | debian-bookworm | ok: Runtime: 0:00:00.344695 2025-09-22 03:10:42.310137 | 2025-09-22 03:10:42.310988 | TASK [tox : Install any sibling python packages] 2025-09-22 03:10:42.774042 | debian-bookworm | ok: No setup.cfg, no action needed 2025-09-22 03:10:42.782588 | 2025-09-22 03:10:42.782706 | TASK [tox : Remove tempfile] 2025-09-22 03:10:43.237270 | debian-bookworm | changed 2025-09-22 03:10:43.250713 | 2025-09-22 03:10:43.255376 | TASK [tox : Emit tox command] 2025-09-22 03:10:43.297313 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-09-22 03:10:43.304968 | 2025-09-22 03:10:43.305103 | TASK [tox : Run tox] 2025-09-22 03:10:44.001138 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-09-22 03:10:44.634700 | debian-bookworm | ............... 2025-09-22 03:10:44.634753 | debian-bookworm | ---------------------------------------------------------------------- 2025-09-22 03:10:44.634769 | debian-bookworm | Ran 15 tests in 0.180s 2025-09-22 03:10:44.634774 | debian-bookworm | 2025-09-22 03:10:44.634782 | debian-bookworm | OK 2025-09-22 03:10:44.728242 | debian-bookworm | test: OK (0.77=setup[0.05]+cmd[0.72] seconds) 2025-09-22 03:10:44.728303 | debian-bookworm | congratulations :) (0.81 seconds) 2025-09-22 03:10:44.898404 | debian-bookworm | ok: Runtime: 0:00:01.124518 2025-09-22 03:10:44.906134 | 2025-09-22 03:10:44.906252 | TASK [tox : Look for output] 2025-09-22 03:10:45.567848 | debian-bookworm | ok 2025-09-22 03:10:45.624477 | 2025-09-22 03:10:45.624629 | TASK [tox : Return file comments to Zuul] 2025-09-22 03:10:45.734459 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:10:45.745222 | 2025-09-22 03:10:45.745328 | PLAY RECAP 2025-09-22 03:10:45.745417 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-09-22 03:10:45.745449 | 2025-09-22 03:10:46.215438 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-22 03:10:46.216538 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-22 03:10:47.955245 | 2025-09-22 03:10:47.955381 | PLAY [all] 2025-09-22 03:10:47.994773 | 2025-09-22 03:10:47.994914 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-09-22 03:10:48.075956 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:10:48.081357 | 2025-09-22 03:10:48.081493 | TASK [fetch-tox-output : Set tox log path for single node] 2025-09-22 03:10:48.120580 | debian-bookworm | ok 2025-09-22 03:10:48.125444 | 2025-09-22 03:10:48.125538 | TASK [fetch-tox-output : Ensure local tox dir] 2025-09-22 03:10:48.620636 | debian-bookworm -> localhost | changed 2025-09-22 03:10:48.633550 | 2025-09-22 03:10:48.633690 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-09-22 03:10:48.718610 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:10:48.729369 | 2025-09-22 03:10:48.729574 | TASK [fetch-tox-output : Set envlist fact] 2025-09-22 03:10:48.771361 | debian-bookworm | ok 2025-09-22 03:10:48.776925 | 2025-09-22 03:10:48.777058 | TASK [fetch-tox-output : Get tox version] 2025-09-22 03:10:49.475228 | 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-22 03:10:49.493428 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-22 03:10:49.808180 | debian-bookworm | ok: Runtime: 0:00:00.303786 2025-09-22 03:10:49.813085 | 2025-09-22 03:10:49.813168 | TASK [fetch-tox-output : Find all default environments] 2025-09-22 03:10:50.358805 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:10:50.364516 | 2025-09-22 03:10:50.364621 | TASK [fetch-tox-output : Set envlist fact] 2025-09-22 03:10:50.397551 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:10:50.403059 | 2025-09-22 03:10:50.403153 | TASK [fetch-tox-output : Find all default environments] 2025-09-22 03:10:50.947819 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:10:50.960535 | 2025-09-22 03:10:50.960681 | TASK [fetch-tox-output : Set envlist fact] 2025-09-22 03:10:50.994917 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:10:51.008074 | 2025-09-22 03:10:51.008220 | LOOP [fetch-tox-output : Copy tox logs] 2025-09-22 03:10:51.050132 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:10:51.065363 | 2025-09-22 03:10:51.065530 | LOOP [fetch-tox-output : Collect tox logs] 2025-09-22 03:10:51.851473 | debian-bookworm | changed: 2025-09-22 03:10:51.851650 | debian-bookworm | created directory /var/lib/zuul/builds/aeca9d9ea62f4d1989baac4f75d2d812/work/logs/tox/test 2025-09-22 03:10:51.851686 | debian-bookworm | cd+++++++++ ./ 2025-09-22 03:10:51.851711 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-09-22 03:10:51.851737 | 2025-09-22 03:10:51.865498 | 2025-09-22 03:10:51.865610 | PLAY RECAP 2025-09-22 03:10:51.865664 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-09-22 03:10:51.865689 | 2025-09-22 03:10:51.957633 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-22 03:10:51.958707 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-22 03:10:52.756963 | 2025-09-22 03:10:52.757132 | PLAY [all] 2025-09-22 03:10:52.773418 | 2025-09-22 03:10:52.773576 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-09-22 03:10:53.048020 | debian-bookworm | changed: non-zero return code 2025-09-22 03:10:53.053913 | 2025-09-22 03:10:53.054056 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-09-22 03:10:53.105821 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:10:53.112185 | 2025-09-22 03:10:53.112312 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-09-22 03:10:53.147081 | 2025-09-22 03:10:53.147273 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-09-22 03:10:53.178818 | 2025-09-22 03:10:53.179029 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-09-22 03:10:53.223242 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:10:53.233580 | 2025-09-22 03:10:53.233734 | LOOP [fetch-subunit-output : Generate subunit file] 2025-09-22 03:10:53.287665 | 2025-09-22 03:10:53.287882 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-09-22 03:10:53.332161 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:10:53.338621 | 2025-09-22 03:10:53.338753 | TASK [fetch-subunit-output : Remove the temporary file] 2025-09-22 03:10:53.362556 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:10:53.368687 | 2025-09-22 03:10:53.368822 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-09-22 03:10:53.392719 | debian-bookworm | skipping: Conditional result was False 2025-09-22 03:10:53.400854 | 2025-09-22 03:10:53.400949 | PLAY RECAP 2025-09-22 03:10:53.401003 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-22 03:10:53.401029 | 2025-09-22 03:10:53.548601 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-22 03:10:53.549738 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-22 03:10:54.308355 | 2025-09-22 03:10:54.308568 | PLAY [Base post] 2025-09-22 03:10:54.323370 | 2025-09-22 03:10:54.323540 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-22 03:10:54.980490 | debian-bookworm | changed 2025-09-22 03:10:54.992507 | 2025-09-22 03:10:54.992633 | PLAY RECAP 2025-09-22 03:10:54.992693 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-22 03:10:54.992755 | 2025-09-22 03:10:55.145032 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-22 03:10:55.146111 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-22 03:10:56.356203 | 2025-09-22 03:10:56.356411 | PLAY [Base post-logs] 2025-09-22 03:10:56.391985 | 2025-09-22 03:10:56.392151 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-22 03:10:57.167936 | localhost | changed 2025-09-22 03:10:57.175531 | 2025-09-22 03:10:57.175642 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-22 03:10:57.212012 | localhost | ok 2025-09-22 03:10:57.214732 | 2025-09-22 03:10:57.214819 | TASK [Set zuul-log-path fact] 2025-09-22 03:10:57.240811 | localhost | ok 2025-09-22 03:10:57.246778 | 2025-09-22 03:10:57.246880 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-22 03:10:57.280508 | localhost | ok 2025-09-22 03:10:57.282708 | 2025-09-22 03:10:57.282779 | TASK [upload-logs : Create log directories] 2025-09-22 03:10:57.853027 | localhost | changed 2025-09-22 03:10:57.855182 | 2025-09-22 03:10:57.855256 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-22 03:10:58.616606 | localhost -> localhost | ok: Runtime: 0:00:00.009460 2025-09-22 03:10:58.619606 | 2025-09-22 03:10:58.619687 | TASK [upload-logs : Upload logs to log server] 2025-09-22 03:10:59.343846 | localhost | Output suppressed because no_log was given 2025-09-22 03:10:59.347088 | 2025-09-22 03:10:59.347169 | LOOP [upload-logs : Compress console log and json output] 2025-09-22 03:10:59.390104 | localhost | skipping: Conditional result was False 2025-09-22 03:10:59.396150 | localhost | skipping: Conditional result was False 2025-09-22 03:10:59.402562 | 2025-09-22 03:10:59.402645 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-22 03:10:59.458876 | localhost | skipping: Conditional result was False 2025-09-22 03:10:59.459098 | 2025-09-22 03:10:59.463762 | localhost | skipping: Conditional result was False 2025-09-22 03:10:59.467510 | 2025-09-22 03:10:59.467587 | LOOP [upload-logs : Upload console log and json output]