2025-12-02 03:16:27.665224 | Job console starting 2025-12-02 03:16:27.681486 | Updating git repos 2025-12-02 03:16:27.725360 | Cloning repos into workspace 2025-12-02 03:16:27.830512 | Restoring repo states 2025-12-02 03:16:27.855633 | Merging changes 2025-12-02 03:16:27.855946 | Checking out repos 2025-12-02 03:16:27.927481 | Preparing playbooks 2025-12-02 03:16:29.091654 | Running Ansible setup 2025-12-02 03:16:34.774312 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-02 03:16:35.712141 | 2025-12-02 03:16:35.712272 | PLAY [Base pre] 2025-12-02 03:16:35.728044 | 2025-12-02 03:16:35.728159 | TASK [Setup log path fact] 2025-12-02 03:16:35.757181 | debian-bookworm | ok 2025-12-02 03:16:35.772823 | 2025-12-02 03:16:35.773007 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-02 03:16:35.811894 | debian-bookworm | ok 2025-12-02 03:16:35.823064 | 2025-12-02 03:16:35.823174 | TASK [emit-job-header : Print job information] 2025-12-02 03:16:35.899853 | # Job Information 2025-12-02 03:16:35.900025 | Ansible Version: 2.16.14 2025-12-02 03:16:35.900063 | Job: tox 2025-12-02 03:16:35.900108 | Pipeline: periodic-daily 2025-12-02 03:16:35.900132 | Executor: 521e9411259a 2025-12-02 03:16:35.900153 | Triggered by: https://github.com/osism/openstack-image-manager 2025-12-02 03:16:35.900175 | Event ID: 597f097d333c4292988f6d56f2c51d7e 2025-12-02 03:16:35.904997 | 2025-12-02 03:16:35.905098 | LOOP [emit-job-header : Print node information] 2025-12-02 03:16:36.044425 | debian-bookworm | ok: 2025-12-02 03:16:36.044600 | debian-bookworm | # Node Information 2025-12-02 03:16:36.044640 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-12-02 03:16:36.044671 | debian-bookworm | Hostname: debian 2025-12-02 03:16:36.044698 | debian-bookworm | Username: zuul 2025-12-02 03:16:36.044724 | debian-bookworm | Distro: Debian 12.12 2025-12-02 03:16:36.044753 | debian-bookworm | Provider: regiocloud-a 2025-12-02 03:16:36.044780 | debian-bookworm | Region: 2025-12-02 03:16:36.044806 | debian-bookworm | Label: debian-bookworm 2025-12-02 03:16:36.044831 | debian-bookworm | Product Name: OpenStack Nova 2025-12-02 03:16:36.044862 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fec7:3752 2025-12-02 03:16:36.063340 | 2025-12-02 03:16:36.063497 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-02 03:16:36.985959 | debian-bookworm -> localhost | changed 2025-12-02 03:16:36.993128 | 2025-12-02 03:16:36.993267 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-02 03:16:38.721488 | debian-bookworm -> localhost | changed 2025-12-02 03:16:38.742641 | 2025-12-02 03:16:38.742762 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-02 03:16:39.343573 | debian-bookworm -> localhost | ok 2025-12-02 03:16:39.358136 | 2025-12-02 03:16:39.358250 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-02 03:16:39.417221 | debian-bookworm | ok 2025-12-02 03:16:39.504920 | debian-bookworm | included: /var/lib/zuul/builds/6bbf7589c2c047ea99b073deb9c63122/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-02 03:16:39.553884 | 2025-12-02 03:16:39.554020 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-02 03:16:42.422760 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-12-02 03:16:42.422981 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/6bbf7589c2c047ea99b073deb9c63122/work/6bbf7589c2c047ea99b073deb9c63122_id_rsa 2025-12-02 03:16:42.423021 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/6bbf7589c2c047ea99b073deb9c63122/work/6bbf7589c2c047ea99b073deb9c63122_id_rsa.pub 2025-12-02 03:16:42.423049 | debian-bookworm -> localhost | The key fingerprint is: 2025-12-02 03:16:42.423074 | debian-bookworm -> localhost | SHA256:GUj33A945mY1eys+cs2WRC6yO6CVqUEZc8S7S0O7pRE zuul-build-sshkey 2025-12-02 03:16:42.423097 | debian-bookworm -> localhost | The key's randomart image is: 2025-12-02 03:16:42.423137 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-12-02 03:16:42.423164 | debian-bookworm -> localhost | | . +. | 2025-12-02 03:16:42.423186 | debian-bookworm -> localhost | | . = = o | 2025-12-02 03:16:42.423208 | debian-bookworm -> localhost | | . * = = o | 2025-12-02 03:16:42.423227 | debian-bookworm -> localhost | | o E + + + | 2025-12-02 03:16:42.423247 | debian-bookworm -> localhost | | . S * + = .| 2025-12-02 03:16:42.423272 | debian-bookworm -> localhost | | . @ = . +.| 2025-12-02 03:16:42.423293 | debian-bookworm -> localhost | | * O o.=..| 2025-12-02 03:16:42.423313 | debian-bookworm -> localhost | | o + +.o.= | 2025-12-02 03:16:42.423333 | debian-bookworm -> localhost | | .=.o | 2025-12-02 03:16:42.423354 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-12-02 03:16:42.423430 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.461014 2025-12-02 03:16:42.429834 | 2025-12-02 03:16:42.429927 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-02 03:16:42.462067 | debian-bookworm | ok 2025-12-02 03:16:42.501727 | debian-bookworm | included: /var/lib/zuul/builds/6bbf7589c2c047ea99b073deb9c63122/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-02 03:16:42.552988 | 2025-12-02 03:16:42.554765 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-02 03:16:42.617163 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:16:42.625379 | 2025-12-02 03:16:42.625561 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-02 03:16:43.474441 | debian-bookworm | changed 2025-12-02 03:16:43.480346 | 2025-12-02 03:16:43.480470 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-02 03:16:43.703151 | debian-bookworm | ok 2025-12-02 03:16:43.708914 | 2025-12-02 03:16:43.709011 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-02 03:16:44.327134 | debian-bookworm | changed 2025-12-02 03:16:44.336750 | 2025-12-02 03:16:44.336860 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-02 03:16:44.984402 | debian-bookworm | changed 2025-12-02 03:16:44.989652 | 2025-12-02 03:16:44.989752 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-02 03:16:45.025609 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:16:45.037933 | 2025-12-02 03:16:45.038040 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-02 03:16:45.973140 | debian-bookworm -> localhost | changed 2025-12-02 03:16:45.985509 | 2025-12-02 03:16:45.985648 | TASK [add-build-sshkey : Add back temp key] 2025-12-02 03:16:46.901390 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/6bbf7589c2c047ea99b073deb9c63122/work/6bbf7589c2c047ea99b073deb9c63122_id_rsa (zuul-build-sshkey) 2025-12-02 03:16:46.901664 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.028974 2025-12-02 03:16:46.910485 | 2025-12-02 03:16:46.910614 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-02 03:16:47.563974 | debian-bookworm | ok 2025-12-02 03:16:47.579659 | 2025-12-02 03:16:47.579816 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-02 03:16:47.603873 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:16:47.615230 | 2025-12-02 03:16:47.615390 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-02 03:16:48.324016 | debian-bookworm | ok 2025-12-02 03:16:48.342402 | 2025-12-02 03:16:48.342547 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-02 03:16:48.408234 | debian-bookworm | ok 2025-12-02 03:16:48.418034 | 2025-12-02 03:16:48.418162 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-02 03:16:49.293777 | debian-bookworm -> localhost | ok 2025-12-02 03:16:49.335570 | 2025-12-02 03:16:49.335707 | TASK [validate-host : Collect information about the host] 2025-12-02 03:16:51.253685 | debian-bookworm | ok 2025-12-02 03:16:51.333801 | 2025-12-02 03:16:51.333950 | TASK [validate-host : Sanitize hostname] 2025-12-02 03:16:51.613049 | debian-bookworm | ok 2025-12-02 03:16:51.794553 | 2025-12-02 03:16:51.794665 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-02 03:16:53.642364 | debian-bookworm -> localhost | changed 2025-12-02 03:16:53.647961 | 2025-12-02 03:16:53.648058 | TASK [validate-host : Collect information about zuul worker] 2025-12-02 03:16:54.411284 | debian-bookworm | ok 2025-12-02 03:16:54.422406 | 2025-12-02 03:16:54.422637 | TASK [validate-host : Write out all zuul information for each host] 2025-12-02 03:16:56.091419 | debian-bookworm -> localhost | changed 2025-12-02 03:16:56.103112 | 2025-12-02 03:16:56.103240 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-02 03:16:56.420056 | debian-bookworm | ok 2025-12-02 03:16:56.432149 | 2025-12-02 03:16:56.432290 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-02 03:16:58.137447 | debian-bookworm | changed: 2025-12-02 03:16:58.140516 | debian-bookworm | cd+++++++++ src/ 2025-12-02 03:16:58.140562 | debian-bookworm | cd+++++++++ src/github.com/ 2025-12-02 03:16:58.140587 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-12-02 03:16:58.140609 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-12-02 03:16:58.140629 | debian-bookworm | =6.2 2025-12-02 03:17:20.921623 | debian-bookworm | Downloading cachetools-6.2.2-py3-none-any.whl (11 kB) 2025-12-02 03:17:20.954125 | debian-bookworm | Collecting chardet>=5.2 2025-12-02 03:17:20.967791 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-12-02 03:17:21.024215 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 3.6 MB/s eta 0:00:00 2025-12-02 03:17:21.068394 | debian-bookworm | Collecting colorama>=0.4.6 2025-12-02 03:17:21.082680 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-12-02 03:17:21.148028 | debian-bookworm | Collecting filelock>=3.20 2025-12-02 03:17:21.161872 | debian-bookworm | Downloading filelock-3.20.0-py3-none-any.whl (16 kB) 2025-12-02 03:17:21.208822 | debian-bookworm | Collecting packaging>=25 2025-12-02 03:17:21.222318 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-12-02 03:17:21.327237 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 1.1 MB/s eta 0:00:00 2025-12-02 03:17:21.385334 | debian-bookworm | Collecting platformdirs>=4.5 2025-12-02 03:17:21.399322 | debian-bookworm | Downloading platformdirs-4.5.0-py3-none-any.whl (18 kB) 2025-12-02 03:17:21.443930 | debian-bookworm | Collecting pluggy>=1.6 2025-12-02 03:17:21.457746 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-12-02 03:17:21.498789 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-12-02 03:17:21.513286 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-12-02 03:17:21.640252 | debian-bookworm | Collecting virtualenv>=20.34 2025-12-02 03:17:21.653702 | debian-bookworm | Downloading virtualenv-20.35.4-py3-none-any.whl (6.0 MB) 2025-12-02 03:17:21.975984 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 18.9 MB/s eta 0:00:00 2025-12-02 03:17:22.075272 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-12-02 03:17:22.088891 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-12-02 03:17:22.122157 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 15.2 MB/s eta 0:00:00 2025-12-02 03:17:22.258212 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-12-02 03:17:23.038032 | debian-bookworm | Successfully installed cachetools-6.2.2 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.20.0 packaging-25.0 platformdirs-4.5.0 pluggy-1.6.0 pyproject-api-1.10.0 tox-4.32.0 virtualenv-20.35.4 2025-12-02 03:17:23.346503 | debian-bookworm | ok: Runtime: 0:00:03.005254 2025-12-02 03:17:23.352947 | 2025-12-02 03:17:23.353077 | TASK [ensure-tox : Export installed tox_executable path] 2025-12-02 03:17:23.386462 | debian-bookworm | ok 2025-12-02 03:17:23.391825 | 2025-12-02 03:17:23.391943 | TASK [ensure-tox : Output tox version] 2025-12-02 03:17:23.870027 | 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-12-02 03:17:23.887318 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-12-02 03:17:23.964616 | debian-bookworm | ok: Runtime: 0:00:00.261765 2025-12-02 03:17:23.970515 | 2025-12-02 03:17:23.970646 | TASK [ensure-tox : Make global symlink] 2025-12-02 03:17:23.994756 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:17:24.003698 | 2025-12-02 03:17:24.003814 | PLAY RECAP 2025-12-02 03:17:24.003867 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-12-02 03:17:24.003893 | 2025-12-02 03:17:24.149811 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-12-02 03:17:24.151094 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-12-02 03:17:24.945278 | 2025-12-02 03:17:24.945464 | PLAY [all] 2025-12-02 03:17:24.962058 | 2025-12-02 03:17:24.962200 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-12-02 03:17:25.433545 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-12-02 03:17:25.491543 | debian-bookworm | ok: Runtime: 0:00:00.022773 2025-12-02 03:17:25.502566 | 2025-12-02 03:17:25.502762 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-12-02 03:17:26.038218 | debian-bookworm | ok: Runtime: 0:00:00.006014 2025-12-02 03:17:26.053801 | 2025-12-02 03:17:26.054001 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-12-02 03:17:26.345118 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-12-02 03:17:26.346492 | debian-bookworm | sudo: a password is required 2025-12-02 03:17:26.598420 | debian-bookworm | ok: Runtime: 0:00:00.016288 2025-12-02 03:17:26.608965 | 2025-12-02 03:17:26.609101 | TASK [tox : Check to see if the constraints file exists] 2025-12-02 03:17:26.654050 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:17:26.660932 | 2025-12-02 03:17:26.661085 | TASK [tox : Fail if constraints file is missing] 2025-12-02 03:17:26.685706 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:17:26.693022 | 2025-12-02 03:17:26.693176 | TASK [tox : Record file location] 2025-12-02 03:17:26.730412 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:17:26.737115 | 2025-12-02 03:17:26.737257 | TASK [tox : Install tox siblings] 2025-12-02 03:17:26.768553 | debian-bookworm | ok 2025-12-02 03:17:26.778124 | debian-bookworm | included: /var/lib/zuul/builds/6bbf7589c2c047ea99b073deb9c63122/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-12-02 03:17:26.783989 | 2025-12-02 03:17:26.784098 | TASK [tox : Run tox without tests] 2025-12-02 03:17:27.763197 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-12-02 03:17:40.421658 | debian-bookworm | test: OK (13.13 seconds) 2025-12-02 03:17:40.423024 | debian-bookworm | congratulations :) (13.15 seconds) 2025-12-02 03:17:40.822710 | debian-bookworm | ok: Runtime: 0:00:13.407844 2025-12-02 03:17:40.828778 | 2025-12-02 03:17:40.828905 | TASK [tox : Create a tempfile to save tox showconfig] 2025-12-02 03:17:41.317132 | debian-bookworm | changed 2025-12-02 03:17:41.326600 | 2025-12-02 03:17:41.326742 | TASK [tox : Get tox envlist config] 2025-12-02 03:17:42.367875 | debian-bookworm | ok: Runtime: 0:00:00.285158 2025-12-02 03:17:42.375493 | 2025-12-02 03:17:42.375620 | TASK [tox : Install any sibling python packages] 2025-12-02 03:17:43.107452 | debian-bookworm | ok: No setup.cfg, no action needed 2025-12-02 03:17:43.116079 | 2025-12-02 03:17:43.116212 | TASK [tox : Remove tempfile] 2025-12-02 03:17:43.878434 | debian-bookworm | changed 2025-12-02 03:17:43.890257 | 2025-12-02 03:17:43.890423 | TASK [tox : Emit tox command] 2025-12-02 03:17:43.989373 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-12-02 03:17:44.008533 | 2025-12-02 03:17:44.008687 | TASK [tox : Run tox] 2025-12-02 03:17:44.831857 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-12-02 03:17:45.399337 | debian-bookworm | ............... 2025-12-02 03:17:45.399374 | debian-bookworm | ---------------------------------------------------------------------- 2025-12-02 03:17:45.399386 | debian-bookworm | Ran 15 tests in 0.163s 2025-12-02 03:17:45.399391 | debian-bookworm | 2025-12-02 03:17:45.399398 | debian-bookworm | OK 2025-12-02 03:17:45.454917 | debian-bookworm | test: OK (0.66=setup[0.04]+cmd[0.62] seconds) 2025-12-02 03:17:45.454961 | debian-bookworm | congratulations :) (0.68 seconds) 2025-12-02 03:17:45.711565 | debian-bookworm | ok: Runtime: 0:00:00.942064 2025-12-02 03:17:45.717567 | 2025-12-02 03:17:45.717684 | TASK [tox : Look for output] 2025-12-02 03:17:46.091543 | debian-bookworm | ok 2025-12-02 03:17:46.121702 | 2025-12-02 03:17:46.121857 | TASK [tox : Return file comments to Zuul] 2025-12-02 03:17:46.217268 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:17:46.224520 | 2025-12-02 03:17:46.224616 | PLAY RECAP 2025-12-02 03:17:46.224771 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-12-02 03:17:46.224807 | 2025-12-02 03:17:46.754979 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-12-02 03:17:46.756112 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-12-02 03:17:48.120581 | 2025-12-02 03:17:48.120753 | PLAY [all] 2025-12-02 03:17:48.164817 | 2025-12-02 03:17:48.164992 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-12-02 03:17:48.401542 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:17:48.408607 | 2025-12-02 03:17:48.408775 | TASK [fetch-tox-output : Set tox log path for single node] 2025-12-02 03:17:48.489437 | debian-bookworm | ok 2025-12-02 03:17:48.513751 | 2025-12-02 03:17:48.513904 | TASK [fetch-tox-output : Ensure local tox dir] 2025-12-02 03:17:49.522775 | debian-bookworm -> localhost | changed 2025-12-02 03:17:49.537619 | 2025-12-02 03:17:49.537764 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-12-02 03:17:49.603640 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:17:49.609901 | 2025-12-02 03:17:49.610040 | TASK [fetch-tox-output : Set envlist fact] 2025-12-02 03:17:49.663077 | debian-bookworm | ok 2025-12-02 03:17:49.671272 | 2025-12-02 03:17:49.671417 | TASK [fetch-tox-output : Get tox version] 2025-12-02 03:17:50.865132 | 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-12-02 03:17:50.882210 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-12-02 03:17:51.213311 | debian-bookworm | ok: Runtime: 0:00:00.256560 2025-12-02 03:17:51.219898 | 2025-12-02 03:17:51.220024 | TASK [fetch-tox-output : Find all default environments] 2025-12-02 03:17:51.841741 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:17:51.847403 | 2025-12-02 03:17:51.847507 | TASK [fetch-tox-output : Set envlist fact] 2025-12-02 03:17:51.891167 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:17:51.897956 | 2025-12-02 03:17:51.898059 | TASK [fetch-tox-output : Find all default environments] 2025-12-02 03:17:52.482997 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:17:52.488609 | 2025-12-02 03:17:52.488713 | TASK [fetch-tox-output : Set envlist fact] 2025-12-02 03:17:52.535251 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:17:52.557202 | 2025-12-02 03:17:52.557313 | LOOP [fetch-tox-output : Copy tox logs] 2025-12-02 03:17:52.641055 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:17:52.656207 | 2025-12-02 03:17:52.656324 | LOOP [fetch-tox-output : Collect tox logs] 2025-12-02 03:17:54.105651 | debian-bookworm | changed: 2025-12-02 03:17:54.105901 | debian-bookworm | created directory /var/lib/zuul/builds/6bbf7589c2c047ea99b073deb9c63122/work/logs/tox/test 2025-12-02 03:17:54.105939 | debian-bookworm | cd+++++++++ ./ 2025-12-02 03:17:54.105965 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-12-02 03:17:54.105992 | 2025-12-02 03:17:54.119871 | 2025-12-02 03:17:54.119948 | PLAY RECAP 2025-12-02 03:17:54.119990 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-12-02 03:17:54.120009 | 2025-12-02 03:17:54.541007 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-12-02 03:17:54.542017 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-02 03:17:56.099178 | 2025-12-02 03:17:56.099397 | PLAY [all] 2025-12-02 03:17:56.160319 | 2025-12-02 03:17:56.160488 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-12-02 03:17:56.441637 | debian-bookworm | changed: non-zero return code 2025-12-02 03:17:56.448796 | 2025-12-02 03:17:56.448916 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-12-02 03:17:56.484850 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:17:56.490608 | 2025-12-02 03:17:56.490727 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-12-02 03:17:56.529850 | 2025-12-02 03:17:56.530024 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-12-02 03:17:56.583862 | 2025-12-02 03:17:56.584037 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-12-02 03:17:56.617156 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:17:56.623111 | 2025-12-02 03:17:56.623218 | LOOP [fetch-subunit-output : Generate subunit file] 2025-12-02 03:17:56.641995 | 2025-12-02 03:17:56.642166 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-12-02 03:17:56.685527 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:17:56.691212 | 2025-12-02 03:17:56.691323 | TASK [fetch-subunit-output : Remove the temporary file] 2025-12-02 03:17:56.715796 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:17:56.721860 | 2025-12-02 03:17:56.721961 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-12-02 03:17:56.754922 | debian-bookworm | skipping: Conditional result was False 2025-12-02 03:17:56.763848 | 2025-12-02 03:17:56.763925 | PLAY RECAP 2025-12-02 03:17:56.763974 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-12-02 03:17:56.764008 | 2025-12-02 03:17:56.979468 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-02 03:17:56.980452 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-02 03:17:58.216606 | 2025-12-02 03:17:58.216736 | PLAY [Base post] 2025-12-02 03:17:58.228853 | 2025-12-02 03:17:58.228966 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-12-02 03:17:58.954228 | debian-bookworm | changed 2025-12-02 03:17:58.963441 | 2025-12-02 03:17:58.963542 | PLAY RECAP 2025-12-02 03:17:58.963603 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-02 03:17:58.963665 | 2025-12-02 03:17:59.245403 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-02 03:17:59.246382 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-12-02 03:18:00.999792 | 2025-12-02 03:18:00.999934 | PLAY [Base post-logs] 2025-12-02 03:18:01.029163 | 2025-12-02 03:18:01.029394 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-02 03:18:02.392945 | localhost | changed 2025-12-02 03:18:02.404828 | 2025-12-02 03:18:02.404947 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-02 03:18:02.471842 | localhost | ok 2025-12-02 03:18:02.474297 | 2025-12-02 03:18:02.474421 | TASK [Set zuul-log-path fact] 2025-12-02 03:18:02.508801 | localhost | ok 2025-12-02 03:18:02.515056 | 2025-12-02 03:18:02.515143 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-02 03:18:02.580116 | localhost | ok 2025-12-02 03:18:02.583128 | 2025-12-02 03:18:02.583209 | TASK [upload-logs : Create log directories] 2025-12-02 03:18:03.875205 | localhost | changed 2025-12-02 03:18:03.877993 | 2025-12-02 03:18:03.878069 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-12-02 03:18:04.806473 | localhost -> localhost | ok: Runtime: 0:00:00.011237 2025-12-02 03:18:04.809646 | 2025-12-02 03:18:04.809722 | TASK [upload-logs : Upload logs to log server] 2025-12-02 03:18:05.384486 | localhost | Output suppressed because no_log was given 2025-12-02 03:18:05.386090 | 2025-12-02 03:18:05.386173 | LOOP [upload-logs : Compress console log and json output] 2025-12-02 03:18:05.490935 | localhost | skipping: Conditional result was False 2025-12-02 03:18:05.507045 | localhost | skipping: Conditional result was False 2025-12-02 03:18:05.513968 | 2025-12-02 03:18:05.514072 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-02 03:18:05.561967 | localhost | skipping: Conditional result was False 2025-12-02 03:18:05.562190 | 2025-12-02 03:18:05.572489 | localhost | skipping: Conditional result was False 2025-12-02 03:18:05.582656 | 2025-12-02 03:18:05.582762 | LOOP [upload-logs : Upload console log and json output]