2025-09-07 00:18:38.943916 | Job console starting 2025-09-07 00:18:38.956081 | Updating git repos 2025-09-07 00:18:38.998345 | Cloning repos into workspace 2025-09-07 00:18:39.087124 | Restoring repo states 2025-09-07 00:18:39.102401 | Merging changes 2025-09-07 00:18:39.870458 | Checking out repos 2025-09-07 00:18:39.932081 | Preparing playbooks 2025-09-07 00:18:41.129280 | Running Ansible setup 2025-09-07 00:18:47.698541 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-07 00:18:49.514038 | 2025-09-07 00:18:49.514158 | PLAY [Base pre] 2025-09-07 00:18:49.562786 | 2025-09-07 00:18:49.562914 | TASK [Setup log path fact] 2025-09-07 00:18:49.596766 | debian-bookworm | ok 2025-09-07 00:18:49.658757 | 2025-09-07 00:18:49.660600 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-07 00:18:49.767285 | debian-bookworm | ok 2025-09-07 00:18:49.778819 | 2025-09-07 00:18:49.778984 | TASK [emit-job-header : Print job information] 2025-09-07 00:18:49.843772 | # Job Information 2025-09-07 00:18:49.843918 | Ansible Version: 2.16.14 2025-09-07 00:18:49.843947 | Job: tox 2025-09-07 00:18:49.843974 | Pipeline: check 2025-09-07 00:18:49.843993 | Executor: 521e9411259a 2025-09-07 00:18:49.844010 | Triggered by: https://github.com/osism/openstack-image-manager/pull/918 2025-09-07 00:18:49.844028 | Event ID: 0e2277a0-8b80-11f0-9840-63a2e3ded26b 2025-09-07 00:18:49.851665 | 2025-09-07 00:18:49.851790 | LOOP [emit-job-header : Print node information] 2025-09-07 00:18:49.986195 | debian-bookworm | ok: 2025-09-07 00:18:49.986332 | debian-bookworm | # Node Information 2025-09-07 00:18:49.986359 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-07 00:18:49.986379 | debian-bookworm | Hostname: debian 2025-09-07 00:18:49.986396 | debian-bookworm | Username: zuul 2025-09-07 00:18:49.986413 | debian-bookworm | Distro: Debian 12.11 2025-09-07 00:18:49.986433 | debian-bookworm | Provider: regiocloud-a 2025-09-07 00:18:49.986451 | debian-bookworm | Region: 2025-09-07 00:18:49.986467 | debian-bookworm | Label: debian-bookworm 2025-09-07 00:18:49.986483 | debian-bookworm | Product Name: OpenStack Nova 2025-09-07 00:18:49.986499 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fef2:f3b8 2025-09-07 00:18:50.000461 | 2025-09-07 00:18:50.000555 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-07 00:18:50.926464 | debian-bookworm -> localhost | changed 2025-09-07 00:18:50.932350 | 2025-09-07 00:18:50.932438 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-07 00:18:53.922375 | debian-bookworm -> localhost | changed 2025-09-07 00:18:53.931053 | 2025-09-07 00:18:53.931132 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-07 00:18:54.617355 | debian-bookworm -> localhost | ok 2025-09-07 00:18:54.622166 | 2025-09-07 00:18:54.622245 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-07 00:18:54.658546 | debian-bookworm | ok 2025-09-07 00:18:54.681190 | debian-bookworm | included: /var/lib/zuul/builds/75b557a555af4f85aadaa80f0f22da71/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-07 00:18:54.696173 | 2025-09-07 00:18:54.696264 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-07 00:18:56.631843 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-07 00:18:56.632005 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/75b557a555af4f85aadaa80f0f22da71/work/75b557a555af4f85aadaa80f0f22da71_id_rsa 2025-09-07 00:18:56.632037 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/75b557a555af4f85aadaa80f0f22da71/work/75b557a555af4f85aadaa80f0f22da71_id_rsa.pub 2025-09-07 00:18:56.632059 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-07 00:18:56.632082 | debian-bookworm -> localhost | SHA256:HmwDj3gpTKhv6pvur/aem02RZwfH6hSQSrU6BEmxDxQ zuul-build-sshkey 2025-09-07 00:18:56.632101 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-07 00:18:56.632126 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-07 00:18:56.632145 | debian-bookworm -> localhost | |.E+ .o. | 2025-09-07 00:18:56.632162 | debian-bookworm -> localhost | |..oo .o . | 2025-09-07 00:18:56.632178 | debian-bookworm -> localhost | | oo.o..o o | 2025-09-07 00:18:56.632194 | debian-bookworm -> localhost | | .++...*= | 2025-09-07 00:18:56.632210 | debian-bookworm -> localhost | |. ++o+=S. | 2025-09-07 00:18:56.632230 | debian-bookworm -> localhost | | . .o*o.o | 2025-09-07 00:18:56.632248 | debian-bookworm -> localhost | | o . .. | 2025-09-07 00:18:56.632265 | debian-bookworm -> localhost | | +. = | 2025-09-07 00:18:56.632282 | debian-bookworm -> localhost | |*B*B.. | 2025-09-07 00:18:56.632299 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-07 00:18:56.632339 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.090187 2025-09-07 00:18:56.637474 | 2025-09-07 00:18:56.637543 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-07 00:18:56.663921 | debian-bookworm | ok 2025-09-07 00:18:56.683404 | debian-bookworm | included: /var/lib/zuul/builds/75b557a555af4f85aadaa80f0f22da71/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-07 00:18:56.692110 | 2025-09-07 00:18:56.692194 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-07 00:18:56.719183 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:18:56.736288 | 2025-09-07 00:18:56.736381 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-07 00:18:57.545233 | debian-bookworm | changed 2025-09-07 00:18:57.549525 | 2025-09-07 00:18:57.549599 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-07 00:18:57.852700 | debian-bookworm | ok 2025-09-07 00:18:57.857480 | 2025-09-07 00:18:57.857555 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-07 00:18:58.680740 | debian-bookworm | changed 2025-09-07 00:18:58.696399 | 2025-09-07 00:18:58.697106 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-07 00:18:59.476839 | debian-bookworm | changed 2025-09-07 00:18:59.491360 | 2025-09-07 00:18:59.491538 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-07 00:18:59.513223 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:18:59.537569 | 2025-09-07 00:18:59.537679 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-07 00:19:00.001035 | debian-bookworm -> localhost | changed 2025-09-07 00:19:00.012372 | 2025-09-07 00:19:00.012472 | TASK [add-build-sshkey : Add back temp key] 2025-09-07 00:19:00.644812 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/75b557a555af4f85aadaa80f0f22da71/work/75b557a555af4f85aadaa80f0f22da71_id_rsa (zuul-build-sshkey) 2025-09-07 00:19:00.646792 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.010758 2025-09-07 00:19:00.667616 | 2025-09-07 00:19:00.667743 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-07 00:19:01.174037 | debian-bookworm | ok 2025-09-07 00:19:01.187420 | 2025-09-07 00:19:01.187525 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-07 00:19:01.214662 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:19:01.240950 | 2025-09-07 00:19:01.241425 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-07 00:19:01.748448 | debian-bookworm | ok 2025-09-07 00:19:01.758549 | 2025-09-07 00:19:01.758640 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-07 00:19:01.807168 | debian-bookworm | ok 2025-09-07 00:19:01.811963 | 2025-09-07 00:19:01.812047 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-07 00:19:02.168201 | debian-bookworm -> localhost | ok 2025-09-07 00:19:02.174645 | 2025-09-07 00:19:02.174759 | TASK [validate-host : Collect information about the host] 2025-09-07 00:19:03.642185 | debian-bookworm | ok 2025-09-07 00:19:03.651770 | 2025-09-07 00:19:03.651860 | TASK [validate-host : Sanitize hostname] 2025-09-07 00:19:03.734179 | debian-bookworm | ok 2025-09-07 00:19:03.779636 | 2025-09-07 00:19:03.779781 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-07 00:19:04.329001 | debian-bookworm -> localhost | changed 2025-09-07 00:19:04.334389 | 2025-09-07 00:19:04.334478 | TASK [validate-host : Collect information about zuul worker] 2025-09-07 00:19:04.718959 | debian-bookworm | ok 2025-09-07 00:19:04.726055 | 2025-09-07 00:19:04.726169 | TASK [validate-host : Write out all zuul information for each host] 2025-09-07 00:19:05.214291 | debian-bookworm -> localhost | changed 2025-09-07 00:19:05.223343 | 2025-09-07 00:19:05.223434 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-07 00:19:05.488866 | debian-bookworm | ok 2025-09-07 00:19:05.494422 | 2025-09-07 00:19:05.502383 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-07 00:19:06.488504 | debian-bookworm | changed: 2025-09-07 00:19:06.488692 | debian-bookworm | cd+++++++++ src/ 2025-09-07 00:19:06.488755 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-07 00:19:06.488780 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-07 00:19:06.488801 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-09-07 00:19:06.488821 | debian-bookworm | =6.1 2025-09-07 00:19:31.970916 | debian-bookworm | Downloading cachetools-6.2.0-py3-none-any.whl (11 kB) 2025-09-07 00:19:32.003635 | debian-bookworm | Collecting chardet>=5.2 2025-09-07 00:19:32.011728 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-09-07 00:19:32.084662 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 2.9 MB/s eta 0:00:00 2025-09-07 00:19:32.137663 | debian-bookworm | Collecting colorama>=0.4.6 2025-09-07 00:19:32.147805 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-09-07 00:19:32.214508 | debian-bookworm | Collecting filelock>=3.18 2025-09-07 00:19:32.225345 | debian-bookworm | Downloading filelock-3.19.1-py3-none-any.whl (15 kB) 2025-09-07 00:19:32.323147 | debian-bookworm | Collecting packaging>=25 2025-09-07 00:19:32.331444 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-09-07 00:19:32.340072 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 10.2 MB/s eta 0:00:00 2025-09-07 00:19:32.400461 | debian-bookworm | Collecting platformdirs>=4.3.8 2025-09-07 00:19:32.408627 | debian-bookworm | Downloading platformdirs-4.4.0-py3-none-any.whl (18 kB) 2025-09-07 00:19:32.466661 | debian-bookworm | Collecting pluggy>=1.6 2025-09-07 00:19:32.475529 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-09-07 00:19:32.531398 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-09-07 00:19:32.541168 | debian-bookworm | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2025-09-07 00:19:32.669531 | debian-bookworm | Collecting virtualenv>=20.31.2 2025-09-07 00:19:32.677282 | debian-bookworm | Downloading virtualenv-20.34.0-py3-none-any.whl (6.0 MB) 2025-09-07 00:19:32.971254 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 20.7 MB/s eta 0:00:00 2025-09-07 00:19:33.077925 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-09-07 00:19:33.087874 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-09-07 00:19:33.115674 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 21.6 MB/s eta 0:00:00 2025-09-07 00:19:33.281639 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-09-07 00:19:34.196798 | 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-07 00:19:34.426603 | debian-bookworm | ok: Runtime: 0:00:03.153021 2025-09-07 00:19:34.439114 | 2025-09-07 00:19:34.439261 | TASK [ensure-tox : Export installed tox_executable path] 2025-09-07 00:19:34.495014 | debian-bookworm | ok 2025-09-07 00:19:34.501811 | 2025-09-07 00:19:34.501931 | TASK [ensure-tox : Output tox version] 2025-09-07 00:19:35.038801 | 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-07 00:19:35.057470 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-07 00:19:35.530775 | debian-bookworm | ok: Runtime: 0:00:00.305374 2025-09-07 00:19:35.542099 | 2025-09-07 00:19:35.542240 | TASK [ensure-tox : Make global symlink] 2025-09-07 00:19:35.576028 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:19:35.587828 | 2025-09-07 00:19:35.587975 | PLAY RECAP 2025-09-07 00:19:35.588056 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-09-07 00:19:35.588094 | 2025-09-07 00:19:35.744174 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-09-07 00:19:35.745311 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-07 00:19:36.528843 | 2025-09-07 00:19:36.529006 | PLAY [all] 2025-09-07 00:19:36.562793 | 2025-09-07 00:19:36.563053 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-09-07 00:19:37.024554 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-07 00:19:37.098038 | debian-bookworm | ok: Runtime: 0:00:00.030052 2025-09-07 00:19:37.107044 | 2025-09-07 00:19:37.107222 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-09-07 00:19:37.640841 | debian-bookworm | ok: Runtime: 0:00:00.006672 2025-09-07 00:19:37.653588 | 2025-09-07 00:19:37.653798 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-09-07 00:19:37.944117 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-07 00:19:37.945527 | debian-bookworm | sudo: a password is required 2025-09-07 00:19:38.190093 | debian-bookworm | ok: Runtime: 0:00:00.022367 2025-09-07 00:19:38.224402 | 2025-09-07 00:19:38.224549 | TASK [tox : Check to see if the constraints file exists] 2025-09-07 00:19:38.253357 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:19:38.260443 | 2025-09-07 00:19:38.260596 | TASK [tox : Fail if constraints file is missing] 2025-09-07 00:19:38.286396 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:19:38.292974 | 2025-09-07 00:19:38.293097 | TASK [tox : Record file location] 2025-09-07 00:19:38.316929 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:19:38.323724 | 2025-09-07 00:19:38.323852 | TASK [tox : Install tox siblings] 2025-09-07 00:19:38.345951 | debian-bookworm | ok 2025-09-07 00:19:38.357074 | debian-bookworm | included: /var/lib/zuul/builds/75b557a555af4f85aadaa80f0f22da71/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-09-07 00:19:38.362905 | 2025-09-07 00:19:38.363030 | TASK [tox : Run tox without tests] 2025-09-07 00:19:39.555837 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-09-07 00:19:52.530121 | debian-bookworm | test: OK (13.62 seconds) 2025-09-07 00:19:52.530177 | debian-bookworm | congratulations :) (13.65 seconds) 2025-09-07 00:19:52.907027 | debian-bookworm | ok: Runtime: 0:00:13.945482 2025-09-07 00:19:52.913741 | 2025-09-07 00:19:52.913864 | TASK [tox : Create a tempfile to save tox showconfig] 2025-09-07 00:19:53.360653 | debian-bookworm | changed 2025-09-07 00:19:53.366128 | 2025-09-07 00:19:53.366247 | TASK [tox : Get tox envlist config] 2025-09-07 00:19:54.397492 | debian-bookworm | ok: Runtime: 0:00:00.330786 2025-09-07 00:19:54.404919 | 2025-09-07 00:19:54.405053 | TASK [tox : Install any sibling python packages] 2025-09-07 00:19:54.856072 | debian-bookworm | ok: No setup.cfg, no action needed 2025-09-07 00:19:54.861616 | 2025-09-07 00:19:54.861748 | TASK [tox : Remove tempfile] 2025-09-07 00:19:55.292613 | debian-bookworm | changed 2025-09-07 00:19:55.300317 | 2025-09-07 00:19:55.300456 | TASK [tox : Emit tox command] 2025-09-07 00:19:55.352785 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-09-07 00:19:55.359333 | 2025-09-07 00:19:55.359454 | TASK [tox : Run tox] 2025-09-07 00:19:56.003000 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-09-07 00:19:56.670274 | debian-bookworm | ............... 2025-09-07 00:19:56.670355 | debian-bookworm | ---------------------------------------------------------------------- 2025-09-07 00:19:56.670386 | debian-bookworm | Ran 15 tests in 0.171s 2025-09-07 00:19:56.670400 | debian-bookworm | 2025-09-07 00:19:56.670418 | debian-bookworm | OK 2025-09-07 00:19:56.733570 | debian-bookworm | test: OK (0.79=setup[0.06]+cmd[0.73] seconds) 2025-09-07 00:19:56.733664 | debian-bookworm | congratulations :) (0.82 seconds) 2025-09-07 00:19:56.933230 | debian-bookworm | ok: Runtime: 0:00:01.086474 2025-09-07 00:19:56.940894 | 2025-09-07 00:19:56.941030 | TASK [tox : Look for output] 2025-09-07 00:19:57.307755 | debian-bookworm | ok 2025-09-07 00:19:57.325586 | 2025-09-07 00:19:57.325747 | TASK [tox : Return file comments to Zuul] 2025-09-07 00:19:57.360056 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:19:57.366676 | 2025-09-07 00:19:57.366927 | PLAY RECAP 2025-09-07 00:19:57.366983 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-09-07 00:19:57.367006 | 2025-09-07 00:19:57.512915 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-07 00:19:57.514015 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-07 00:19:58.403798 | 2025-09-07 00:19:58.403948 | PLAY [all] 2025-09-07 00:19:58.418928 | 2025-09-07 00:19:58.419054 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-09-07 00:19:58.474353 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:19:58.481654 | 2025-09-07 00:19:58.481806 | TASK [fetch-tox-output : Set tox log path for single node] 2025-09-07 00:19:58.539934 | debian-bookworm | ok 2025-09-07 00:19:58.549124 | 2025-09-07 00:19:58.549329 | TASK [fetch-tox-output : Ensure local tox dir] 2025-09-07 00:19:59.038132 | debian-bookworm -> localhost | changed 2025-09-07 00:19:59.052377 | 2025-09-07 00:19:59.052537 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-09-07 00:19:59.128293 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:19:59.141887 | 2025-09-07 00:19:59.142102 | TASK [fetch-tox-output : Set envlist fact] 2025-09-07 00:19:59.182132 | debian-bookworm | ok 2025-09-07 00:19:59.188032 | 2025-09-07 00:19:59.188153 | TASK [fetch-tox-output : Get tox version] 2025-09-07 00:19:59.821032 | 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-07 00:19:59.838287 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-07 00:20:00.218959 | debian-bookworm | ok: Runtime: 0:00:00.265714 2025-09-07 00:20:00.231330 | 2025-09-07 00:20:00.231505 | TASK [fetch-tox-output : Find all default environments] 2025-09-07 00:20:00.765937 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:20:00.778619 | 2025-09-07 00:20:00.778807 | TASK [fetch-tox-output : Set envlist fact] 2025-09-07 00:20:00.813893 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:20:00.826367 | 2025-09-07 00:20:00.826521 | TASK [fetch-tox-output : Find all default environments] 2025-09-07 00:20:01.362956 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:20:01.376888 | 2025-09-07 00:20:01.377051 | TASK [fetch-tox-output : Set envlist fact] 2025-09-07 00:20:01.423163 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:20:01.437046 | 2025-09-07 00:20:01.437211 | LOOP [fetch-tox-output : Copy tox logs] 2025-09-07 00:20:01.490437 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:20:01.505790 | 2025-09-07 00:20:01.505961 | LOOP [fetch-tox-output : Collect tox logs] 2025-09-07 00:20:02.291516 | debian-bookworm | changed: 2025-09-07 00:20:02.291820 | debian-bookworm | created directory /var/lib/zuul/builds/75b557a555af4f85aadaa80f0f22da71/work/logs/tox/test 2025-09-07 00:20:02.291882 | debian-bookworm | cd+++++++++ ./ 2025-09-07 00:20:02.291924 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-09-07 00:20:02.291972 | 2025-09-07 00:20:02.312604 | 2025-09-07 00:20:02.312754 | PLAY RECAP 2025-09-07 00:20:02.312846 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-09-07 00:20:02.312890 | 2025-09-07 00:20:02.441332 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-07 00:20:02.442394 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-07 00:20:03.209069 | 2025-09-07 00:20:03.209236 | PLAY [all] 2025-09-07 00:20:03.231498 | 2025-09-07 00:20:03.231646 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-09-07 00:20:03.459672 | debian-bookworm | changed: non-zero return code 2025-09-07 00:20:03.468356 | 2025-09-07 00:20:03.468527 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-09-07 00:20:03.493816 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:20:03.502966 | 2025-09-07 00:20:03.503112 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-09-07 00:20:03.535931 | 2025-09-07 00:20:03.536152 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-09-07 00:20:03.566981 | 2025-09-07 00:20:03.567148 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-09-07 00:20:03.590743 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:20:03.604603 | 2025-09-07 00:20:03.604785 | LOOP [fetch-subunit-output : Generate subunit file] 2025-09-07 00:20:03.641396 | 2025-09-07 00:20:03.641664 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-09-07 00:20:03.665909 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:20:03.678893 | 2025-09-07 00:20:03.679051 | TASK [fetch-subunit-output : Remove the temporary file] 2025-09-07 00:20:03.703307 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:20:03.712894 | 2025-09-07 00:20:03.713021 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-09-07 00:20:03.736886 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:20:03.751110 | 2025-09-07 00:20:03.751218 | PLAY RECAP 2025-09-07 00:20:03.751295 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-07 00:20:03.751338 | 2025-09-07 00:20:03.897772 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-07 00:20:03.900432 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-07 00:20:04.667473 | 2025-09-07 00:20:04.667630 | PLAY [Base post] 2025-09-07 00:20:04.681151 | 2025-09-07 00:20:04.681289 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-07 00:20:05.257365 | debian-bookworm | changed 2025-09-07 00:20:05.266496 | 2025-09-07 00:20:05.266629 | PLAY RECAP 2025-09-07 00:20:05.266721 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-07 00:20:05.266795 | 2025-09-07 00:20:05.390154 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-07 00:20:05.392182 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-07 00:20:06.254058 | 2025-09-07 00:20:06.254239 | PLAY [Base post-logs] 2025-09-07 00:20:06.265560 | 2025-09-07 00:20:06.265731 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-07 00:20:06.735721 | localhost | changed 2025-09-07 00:20:06.753757 | 2025-09-07 00:20:06.753937 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-07 00:20:06.791811 | localhost | ok 2025-09-07 00:20:06.798095 | 2025-09-07 00:20:06.798258 | TASK [Set zuul-log-path fact] 2025-09-07 00:20:06.815980 | localhost | ok 2025-09-07 00:20:06.827958 | 2025-09-07 00:20:06.828093 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-07 00:20:06.854596 | localhost | ok 2025-09-07 00:20:06.861468 | 2025-09-07 00:20:06.861624 | TASK [upload-logs : Create log directories] 2025-09-07 00:20:07.372505 | localhost | changed 2025-09-07 00:20:07.376305 | 2025-09-07 00:20:07.376440 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-07 00:20:07.889162 | localhost -> localhost | ok: Runtime: 0:00:00.006221 2025-09-07 00:20:07.898446 | 2025-09-07 00:20:07.898648 | TASK [upload-logs : Upload logs to log server] 2025-09-07 00:20:08.513269 | localhost | Output suppressed because no_log was given 2025-09-07 00:20:08.515177 | 2025-09-07 00:20:08.515289 | LOOP [upload-logs : Compress console log and json output] 2025-09-07 00:20:08.568294 | localhost | skipping: Conditional result was False 2025-09-07 00:20:08.574023 | localhost | skipping: Conditional result was False 2025-09-07 00:20:08.586674 | 2025-09-07 00:20:08.586998 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-07 00:20:08.638784 | localhost | skipping: Conditional result was False 2025-09-07 00:20:08.639428 | 2025-09-07 00:20:08.642397 | localhost | skipping: Conditional result was False 2025-09-07 00:20:08.648996 | 2025-09-07 00:20:08.649197 | LOOP [upload-logs : Upload console log and json output]