2025-10-07 15:32:10.689854 | Job console starting 2025-10-07 15:32:10.740175 | Updating git repos 2025-10-07 15:32:10.803366 | Cloning repos into workspace 2025-10-07 15:32:10.868463 | Restoring repo states 2025-10-07 15:32:10.878533 | Merging changes 2025-10-07 15:32:11.367970 | Checking out repos 2025-10-07 15:32:11.401148 | Preparing playbooks 2025-10-07 15:32:12.041962 | Running Ansible setup 2025-10-07 15:32:16.969505 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-07 15:32:17.865603 | 2025-10-07 15:32:17.865764 | PLAY [Base pre] 2025-10-07 15:32:17.881267 | 2025-10-07 15:32:17.881402 | TASK [Setup log path fact] 2025-10-07 15:32:17.910160 | debian-bookworm | ok 2025-10-07 15:32:17.925895 | 2025-10-07 15:32:17.926152 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-07 15:32:17.969033 | debian-bookworm | ok 2025-10-07 15:32:17.987176 | 2025-10-07 15:32:17.987338 | TASK [emit-job-header : Print job information] 2025-10-07 15:32:18.067151 | # Job Information 2025-10-07 15:32:18.067357 | Ansible Version: 2.16.14 2025-10-07 15:32:18.067394 | Job: tox 2025-10-07 15:32:18.067428 | Pipeline: check 2025-10-07 15:32:18.067452 | Executor: 521e9411259a 2025-10-07 15:32:18.067474 | Triggered by: https://github.com/osism/openstack-image-manager/pull/944 2025-10-07 15:32:18.067496 | Event ID: c521ba40-a392-11f0-9fd5-f611ab3857bd 2025-10-07 15:32:18.072594 | 2025-10-07 15:32:18.072714 | LOOP [emit-job-header : Print node information] 2025-10-07 15:32:18.200175 | debian-bookworm | ok: 2025-10-07 15:32:18.200453 | debian-bookworm | # Node Information 2025-10-07 15:32:18.200493 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-07 15:32:18.200520 | debian-bookworm | Hostname: debian 2025-10-07 15:32:18.200543 | debian-bookworm | Username: zuul 2025-10-07 15:32:18.200564 | debian-bookworm | Distro: Debian 12.12 2025-10-07 15:32:18.200594 | debian-bookworm | Provider: regiocloud-a 2025-10-07 15:32:18.200620 | debian-bookworm | Region: 2025-10-07 15:32:18.200641 | debian-bookworm | Label: debian-bookworm 2025-10-07 15:32:18.200661 | debian-bookworm | Product Name: OpenStack Nova 2025-10-07 15:32:18.200681 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fedf:8fbb 2025-10-07 15:32:18.218608 | 2025-10-07 15:32:18.218736 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-07 15:32:18.768648 | debian-bookworm -> localhost | changed 2025-10-07 15:32:18.776117 | 2025-10-07 15:32:18.776255 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-07 15:32:19.849618 | debian-bookworm -> localhost | changed 2025-10-07 15:32:19.863752 | 2025-10-07 15:32:19.863852 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-07 15:32:20.232149 | debian-bookworm -> localhost | ok 2025-10-07 15:32:20.237876 | 2025-10-07 15:32:20.237975 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-07 15:32:20.266025 | debian-bookworm | ok 2025-10-07 15:32:20.282735 | debian-bookworm | included: /var/lib/zuul/builds/b23259b346b3460998542658182806fd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-07 15:32:20.288429 | 2025-10-07 15:32:20.288519 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-07 15:32:22.751702 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-07 15:32:22.751866 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/b23259b346b3460998542658182806fd/work/b23259b346b3460998542658182806fd_id_rsa 2025-10-07 15:32:22.751898 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/b23259b346b3460998542658182806fd/work/b23259b346b3460998542658182806fd_id_rsa.pub 2025-10-07 15:32:22.751920 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-07 15:32:22.751940 | debian-bookworm -> localhost | SHA256:Y4qvIiRz9NByXEkepp839X5dhRlDfV2F1cCDUxO7Ulc zuul-build-sshkey 2025-10-07 15:32:22.751958 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-07 15:32:22.751984 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-07 15:32:22.752001 | debian-bookworm -> localhost | | .+. =O*E| 2025-10-07 15:32:22.752019 | debian-bookworm -> localhost | | +o. o +B*| 2025-10-07 15:32:22.752035 | debian-bookworm -> localhost | | o... . .=.+| 2025-10-07 15:32:22.752051 | debian-bookworm -> localhost | | + +. . . . . o.| 2025-10-07 15:32:22.752067 | debian-bookworm -> localhost | | . = o S o . .| 2025-10-07 15:32:22.752085 | debian-bookworm -> localhost | |o.. .. + o . . ..| 2025-10-07 15:32:22.752101 | debian-bookworm -> localhost | |oo . . . . .| 2025-10-07 15:32:22.752117 | debian-bookworm -> localhost | |. . . . | 2025-10-07 15:32:22.752133 | debian-bookworm -> localhost | | . .... | 2025-10-07 15:32:22.752150 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-07 15:32:22.752193 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.980008 2025-10-07 15:32:22.757138 | 2025-10-07 15:32:22.757231 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-07 15:32:22.773287 | debian-bookworm | ok 2025-10-07 15:32:22.788521 | debian-bookworm | included: /var/lib/zuul/builds/b23259b346b3460998542658182806fd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-07 15:32:22.795539 | 2025-10-07 15:32:22.795611 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-07 15:32:22.807896 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:32:22.812353 | 2025-10-07 15:32:22.812423 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-07 15:32:23.411168 | debian-bookworm | changed 2025-10-07 15:32:23.418114 | 2025-10-07 15:32:23.418240 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-07 15:32:23.676364 | debian-bookworm | ok 2025-10-07 15:32:23.681933 | 2025-10-07 15:32:23.682025 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-07 15:32:24.329351 | debian-bookworm | changed 2025-10-07 15:32:24.335690 | 2025-10-07 15:32:24.335784 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-07 15:32:24.941842 | debian-bookworm | changed 2025-10-07 15:32:24.946992 | 2025-10-07 15:32:24.947081 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-07 15:32:24.960455 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:32:24.967179 | 2025-10-07 15:32:24.967316 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-07 15:32:25.356361 | debian-bookworm -> localhost | changed 2025-10-07 15:32:25.374092 | 2025-10-07 15:32:25.374195 | TASK [add-build-sshkey : Add back temp key] 2025-10-07 15:32:25.743488 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/b23259b346b3460998542658182806fd/work/b23259b346b3460998542658182806fd_id_rsa (zuul-build-sshkey) 2025-10-07 15:32:25.743704 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.009449 2025-10-07 15:32:25.751124 | 2025-10-07 15:32:25.751237 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-07 15:32:26.164076 | debian-bookworm | ok 2025-10-07 15:32:26.170201 | 2025-10-07 15:32:26.170315 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-07 15:32:26.232946 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:32:26.244985 | 2025-10-07 15:32:26.245102 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-07 15:32:26.648950 | debian-bookworm | ok 2025-10-07 15:32:26.658945 | 2025-10-07 15:32:26.659038 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-07 15:32:26.686091 | debian-bookworm | ok 2025-10-07 15:32:26.690858 | 2025-10-07 15:32:26.690938 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-07 15:32:26.925547 | debian-bookworm -> localhost | ok 2025-10-07 15:32:26.931492 | 2025-10-07 15:32:26.931574 | TASK [validate-host : Collect information about the host] 2025-10-07 15:32:28.064395 | debian-bookworm | ok 2025-10-07 15:32:28.074427 | 2025-10-07 15:32:28.074533 | TASK [validate-host : Sanitize hostname] 2025-10-07 15:32:28.163222 | debian-bookworm | ok 2025-10-07 15:32:28.192155 | 2025-10-07 15:32:28.192265 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-07 15:32:28.760197 | debian-bookworm -> localhost | changed 2025-10-07 15:32:28.766866 | 2025-10-07 15:32:28.766958 | TASK [validate-host : Collect information about zuul worker] 2025-10-07 15:32:29.147163 | debian-bookworm | ok 2025-10-07 15:32:29.151601 | 2025-10-07 15:32:29.151688 | TASK [validate-host : Write out all zuul information for each host] 2025-10-07 15:32:29.583160 | debian-bookworm -> localhost | changed 2025-10-07 15:32:29.607713 | 2025-10-07 15:32:29.607926 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-07 15:32:29.889702 | debian-bookworm | ok 2025-10-07 15:32:29.901376 | 2025-10-07 15:32:29.901546 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-07 15:32:30.739232 | debian-bookworm | changed: 2025-10-07 15:32:30.739400 | debian-bookworm | cd+++++++++ src/ 2025-10-07 15:32:30.739435 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-07 15:32:30.739460 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-07 15:32:30.739482 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-10-07 15:32:30.739503 | debian-bookworm | =6.1 2025-10-07 15:32:52.267134 | debian-bookworm | Downloading cachetools-6.2.0-py3-none-any.whl (11 kB) 2025-10-07 15:32:52.311017 | debian-bookworm | Collecting chardet>=5.2 2025-10-07 15:32:52.318912 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-10-07 15:32:52.336130 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 13.3 MB/s eta 0:00:00 2025-10-07 15:32:52.372378 | debian-bookworm | Collecting colorama>=0.4.6 2025-10-07 15:32:52.380828 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-10-07 15:32:52.446585 | debian-bookworm | Collecting filelock>=3.18 2025-10-07 15:32:52.455367 | debian-bookworm | Downloading filelock-3.19.1-py3-none-any.whl (15 kB) 2025-10-07 15:32:52.524390 | debian-bookworm | Collecting packaging>=25 2025-10-07 15:32:52.532160 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-07 15:32:52.540690 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.5 MB/s eta 0:00:00 2025-10-07 15:32:52.588557 | debian-bookworm | Collecting platformdirs>=4.3.8 2025-10-07 15:32:52.598039 | debian-bookworm | Downloading platformdirs-4.4.0-py3-none-any.whl (18 kB) 2025-10-07 15:32:52.634605 | debian-bookworm | Collecting pluggy>=1.6 2025-10-07 15:32:52.644105 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-10-07 15:32:52.688086 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-10-07 15:32:52.695957 | debian-bookworm | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2025-10-07 15:32:52.819618 | debian-bookworm | Collecting virtualenv>=20.31.2 2025-10-07 15:32:52.830111 | debian-bookworm | Downloading virtualenv-20.34.0-py3-none-any.whl (6.0 MB) 2025-10-07 15:32:53.092883 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 23.1 MB/s eta 0:00:00 2025-10-07 15:32:53.193391 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-10-07 15:32:53.204034 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-10-07 15:32:53.278672 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 6.8 MB/s eta 0:00:00 2025-10-07 15:32:53.433425 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-10-07 15:32:54.241266 | 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.3 virtualenv-20.34.0 2025-10-07 15:32:54.804319 | debian-bookworm | ok: Runtime: 0:00:02.817988 2025-10-07 15:32:54.817351 | 2025-10-07 15:32:54.817520 | TASK [ensure-tox : Export installed tox_executable path] 2025-10-07 15:32:54.849078 | debian-bookworm | ok 2025-10-07 15:32:54.854149 | 2025-10-07 15:32:54.854305 | TASK [ensure-tox : Output tox version] 2025-10-07 15:32:55.383782 | 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-07 15:32:55.401818 | debian-bookworm | 4.30.3 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-07 15:32:55.883562 | debian-bookworm | ok: Runtime: 0:00:00.317827 2025-10-07 15:32:55.889557 | 2025-10-07 15:32:55.889669 | TASK [ensure-tox : Make global symlink] 2025-10-07 15:32:55.933821 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:32:55.941288 | 2025-10-07 15:32:55.941391 | PLAY RECAP 2025-10-07 15:32:55.941448 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-10-07 15:32:55.941473 | 2025-10-07 15:32:56.080878 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-10-07 15:32:56.081983 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-07 15:32:56.827473 | 2025-10-07 15:32:56.827637 | PLAY [all] 2025-10-07 15:32:56.873942 | 2025-10-07 15:32:56.874113 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-10-07 15:32:57.417323 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-07 15:32:57.479573 | debian-bookworm | ok: Runtime: 0:00:00.031831 2025-10-07 15:32:57.485345 | 2025-10-07 15:32:57.485473 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-10-07 15:32:58.016452 | debian-bookworm | ok: Runtime: 0:00:00.006700 2025-10-07 15:32:58.031577 | 2025-10-07 15:32:58.031747 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-10-07 15:32:58.338041 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-07 15:32:58.341065 | debian-bookworm | sudo: a password is required 2025-10-07 15:32:58.567229 | debian-bookworm | ok: Runtime: 0:00:00.023116 2025-10-07 15:32:58.587058 | 2025-10-07 15:32:58.587219 | TASK [tox : Check to see if the constraints file exists] 2025-10-07 15:32:58.622023 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:32:58.629624 | 2025-10-07 15:32:58.629741 | TASK [tox : Fail if constraints file is missing] 2025-10-07 15:32:58.664712 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:32:58.670779 | 2025-10-07 15:32:58.670924 | TASK [tox : Record file location] 2025-10-07 15:32:58.704529 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:32:58.710279 | 2025-10-07 15:32:58.710393 | TASK [tox : Install tox siblings] 2025-10-07 15:32:58.739773 | debian-bookworm | ok 2025-10-07 15:32:58.748115 | debian-bookworm | included: /var/lib/zuul/builds/b23259b346b3460998542658182806fd/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-10-07 15:32:58.753822 | 2025-10-07 15:32:58.754014 | TASK [tox : Run tox without tests] 2025-10-07 15:32:59.835947 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-10-07 15:33:12.349041 | debian-bookworm | test: OK (13.04 seconds) 2025-10-07 15:33:12.349223 | debian-bookworm | congratulations :) (13.07 seconds) 2025-10-07 15:33:12.790508 | debian-bookworm | ok: Runtime: 0:00:13.351585 2025-10-07 15:33:12.806974 | 2025-10-07 15:33:12.807140 | TASK [tox : Create a tempfile to save tox showconfig] 2025-10-07 15:33:13.203216 | debian-bookworm | changed 2025-10-07 15:33:13.211304 | 2025-10-07 15:33:13.211439 | TASK [tox : Get tox envlist config] 2025-10-07 15:33:14.242379 | debian-bookworm | ok: Runtime: 0:00:00.295903 2025-10-07 15:33:14.255833 | 2025-10-07 15:33:14.256027 | TASK [tox : Install any sibling python packages] 2025-10-07 15:33:14.666191 | debian-bookworm | ok: No setup.cfg, no action needed 2025-10-07 15:33:14.674970 | 2025-10-07 15:33:14.675108 | TASK [tox : Remove tempfile] 2025-10-07 15:33:15.105148 | debian-bookworm | changed 2025-10-07 15:33:15.113692 | 2025-10-07 15:33:15.113832 | TASK [tox : Emit tox command] 2025-10-07 15:33:15.161414 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-10-07 15:33:15.169094 | 2025-10-07 15:33:15.169242 | TASK [tox : Run tox] 2025-10-07 15:33:15.714877 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-10-07 15:33:16.283561 | debian-bookworm | ............... 2025-10-07 15:33:16.283596 | debian-bookworm | ---------------------------------------------------------------------- 2025-10-07 15:33:16.283608 | debian-bookworm | Ran 15 tests in 0.170s 2025-10-07 15:33:16.283614 | debian-bookworm | 2025-10-07 15:33:16.283621 | debian-bookworm | OK 2025-10-07 15:33:16.340322 | debian-bookworm | test: OK (0.66=setup[0.04]+cmd[0.62] seconds) 2025-10-07 15:33:16.340431 | debian-bookworm | congratulations :) (0.69 seconds) 2025-10-07 15:33:16.733753 | debian-bookworm | ok: Runtime: 0:00:00.922693 2025-10-07 15:33:16.746129 | 2025-10-07 15:33:16.746353 | TASK [tox : Look for output] 2025-10-07 15:33:17.117053 | debian-bookworm | ok 2025-10-07 15:33:17.134415 | 2025-10-07 15:33:17.134546 | TASK [tox : Return file comments to Zuul] 2025-10-07 15:33:17.178867 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:33:17.192274 | 2025-10-07 15:33:17.192391 | PLAY RECAP 2025-10-07 15:33:17.192476 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-10-07 15:33:17.192519 | 2025-10-07 15:33:17.314761 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-07 15:33:17.317471 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-07 15:33:18.048819 | 2025-10-07 15:33:18.048963 | PLAY [all] 2025-10-07 15:33:18.063878 | 2025-10-07 15:33:18.064004 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-10-07 15:33:18.109719 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:33:18.120572 | 2025-10-07 15:33:18.120756 | TASK [fetch-tox-output : Set tox log path for single node] 2025-10-07 15:33:18.156580 | debian-bookworm | ok 2025-10-07 15:33:18.164408 | 2025-10-07 15:33:18.164545 | TASK [fetch-tox-output : Ensure local tox dir] 2025-10-07 15:33:18.634706 | debian-bookworm -> localhost | changed 2025-10-07 15:33:18.650058 | 2025-10-07 15:33:18.650223 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-10-07 15:33:18.705248 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:33:18.717728 | 2025-10-07 15:33:18.717902 | TASK [fetch-tox-output : Set envlist fact] 2025-10-07 15:33:18.753392 | debian-bookworm | ok 2025-10-07 15:33:18.761773 | 2025-10-07 15:33:18.761905 | TASK [fetch-tox-output : Get tox version] 2025-10-07 15:33:19.427479 | 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-07 15:33:19.445617 | debian-bookworm | 4.30.3 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-07 15:33:19.792942 | debian-bookworm | ok: Runtime: 0:00:00.289090 2025-10-07 15:33:19.804159 | 2025-10-07 15:33:19.804333 | TASK [fetch-tox-output : Find all default environments] 2025-10-07 15:33:20.335608 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:33:20.347868 | 2025-10-07 15:33:20.348049 | TASK [fetch-tox-output : Set envlist fact] 2025-10-07 15:33:20.393408 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:33:20.405007 | 2025-10-07 15:33:20.405225 | TASK [fetch-tox-output : Find all default environments] 2025-10-07 15:33:20.937483 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:33:20.949804 | 2025-10-07 15:33:20.949973 | TASK [fetch-tox-output : Set envlist fact] 2025-10-07 15:33:20.984935 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:33:20.997266 | 2025-10-07 15:33:20.997423 | LOOP [fetch-tox-output : Copy tox logs] 2025-10-07 15:33:21.044612 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:33:21.058869 | 2025-10-07 15:33:21.059022 | LOOP [fetch-tox-output : Collect tox logs] 2025-10-07 15:33:21.845459 | debian-bookworm | changed: 2025-10-07 15:33:21.845714 | debian-bookworm | created directory /var/lib/zuul/builds/b23259b346b3460998542658182806fd/work/logs/tox/test 2025-10-07 15:33:21.845757 | debian-bookworm | cd+++++++++ ./ 2025-10-07 15:33:21.845785 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-10-07 15:33:21.845819 | 2025-10-07 15:33:21.862649 | 2025-10-07 15:33:21.862741 | PLAY RECAP 2025-10-07 15:33:21.862793 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-10-07 15:33:21.862816 | 2025-10-07 15:33:21.987163 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-07 15:33:21.988242 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-07 15:33:22.757773 | 2025-10-07 15:33:22.757922 | PLAY [all] 2025-10-07 15:33:22.774513 | 2025-10-07 15:33:22.774641 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-07 15:33:23.001791 | debian-bookworm | changed: non-zero return code 2025-10-07 15:33:23.011447 | 2025-10-07 15:33:23.011604 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-07 15:33:23.035886 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:33:23.046184 | 2025-10-07 15:33:23.046364 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-07 15:33:23.079848 | 2025-10-07 15:33:23.080081 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-07 15:33:23.118940 | 2025-10-07 15:33:23.119219 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-07 15:33:23.134680 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:33:23.143586 | 2025-10-07 15:33:23.143709 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-07 15:33:23.176494 | 2025-10-07 15:33:23.176758 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-07 15:33:23.200986 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:33:23.214548 | 2025-10-07 15:33:23.214731 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-07 15:33:23.239627 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:33:23.252510 | 2025-10-07 15:33:23.252671 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-07 15:33:23.268178 | debian-bookworm | skipping: Conditional result was False 2025-10-07 15:33:23.274805 | 2025-10-07 15:33:23.274900 | PLAY RECAP 2025-10-07 15:33:23.274952 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-07 15:33:23.274976 | 2025-10-07 15:33:23.395035 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-07 15:33:23.397383 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-07 15:33:24.181739 | 2025-10-07 15:33:24.181888 | PLAY [Base post] 2025-10-07 15:33:24.195768 | 2025-10-07 15:33:24.195901 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-07 15:33:24.726408 | debian-bookworm | changed 2025-10-07 15:33:24.736459 | 2025-10-07 15:33:24.736584 | PLAY RECAP 2025-10-07 15:33:24.736662 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-07 15:33:24.736740 | 2025-10-07 15:33:24.849733 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-07 15:33:24.852479 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-07 15:33:25.630969 | 2025-10-07 15:33:25.631132 | PLAY [Base post-logs] 2025-10-07 15:33:25.642110 | 2025-10-07 15:33:25.642254 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-07 15:33:26.099096 | localhost | changed 2025-10-07 15:33:26.115646 | 2025-10-07 15:33:26.115826 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-07 15:33:26.155258 | localhost | ok 2025-10-07 15:33:26.161631 | 2025-10-07 15:33:26.161774 | TASK [Set zuul-log-path fact] 2025-10-07 15:33:26.178295 | localhost | ok 2025-10-07 15:33:26.188313 | 2025-10-07 15:33:26.188431 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-07 15:33:26.213716 | localhost | ok 2025-10-07 15:33:26.218107 | 2025-10-07 15:33:26.218248 | TASK [upload-logs : Create log directories] 2025-10-07 15:33:26.698297 | localhost | changed 2025-10-07 15:33:26.703274 | 2025-10-07 15:33:26.703427 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-07 15:33:27.186157 | localhost -> localhost | ok: Runtime: 0:00:00.006668 2025-10-07 15:33:27.194576 | 2025-10-07 15:33:27.194757 | TASK [upload-logs : Upload logs to log server] 2025-10-07 15:33:27.739978 | localhost | Output suppressed because no_log was given 2025-10-07 15:33:27.743909 | 2025-10-07 15:33:27.744117 | LOOP [upload-logs : Compress console log and json output] 2025-10-07 15:33:27.801499 | localhost | skipping: Conditional result was False 2025-10-07 15:33:27.806558 | localhost | skipping: Conditional result was False 2025-10-07 15:33:27.818502 | 2025-10-07 15:33:27.818726 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-07 15:33:27.863873 | localhost | skipping: Conditional result was False 2025-10-07 15:33:27.864389 | 2025-10-07 15:33:27.868164 | localhost | skipping: Conditional result was False 2025-10-07 15:33:27.881153 | 2025-10-07 15:33:27.881409 | LOOP [upload-logs : Upload console log and json output]