2025-10-19 00:20:26.232054 | Job console starting 2025-10-19 00:20:26.241164 | Updating git repos 2025-10-19 00:20:26.263379 | Cloning repos into workspace 2025-10-19 00:20:26.345036 | Restoring repo states 2025-10-19 00:20:26.362570 | Merging changes 2025-10-19 00:20:26.988906 | Checking out repos 2025-10-19 00:20:27.020847 | Preparing playbooks 2025-10-19 00:20:27.466881 | Running Ansible setup 2025-10-19 00:20:32.223735 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-19 00:20:33.014236 | 2025-10-19 00:20:33.014364 | PLAY [Base pre] 2025-10-19 00:20:33.041227 | 2025-10-19 00:20:33.041349 | TASK [Setup log path fact] 2025-10-19 00:20:33.059563 | debian-bookworm | ok 2025-10-19 00:20:33.081556 | 2025-10-19 00:20:33.081682 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-19 00:20:33.111169 | debian-bookworm | ok 2025-10-19 00:20:33.128187 | 2025-10-19 00:20:33.128292 | TASK [emit-job-header : Print job information] 2025-10-19 00:20:33.189944 | # Job Information 2025-10-19 00:20:33.190097 | Ansible Version: 2.16.14 2025-10-19 00:20:33.190132 | Job: tox 2025-10-19 00:20:33.190165 | Pipeline: check 2025-10-19 00:20:33.190188 | Executor: 521e9411259a 2025-10-19 00:20:33.190208 | Triggered by: https://github.com/osism/openstack-image-manager/pull/941 2025-10-19 00:20:33.190229 | Event ID: 31e1ddd0-ac81-11f0-9d74-f1f0787f2d4e 2025-10-19 00:20:33.199376 | 2025-10-19 00:20:33.199485 | LOOP [emit-job-header : Print node information] 2025-10-19 00:20:33.405174 | debian-bookworm | ok: 2025-10-19 00:20:33.405378 | debian-bookworm | # Node Information 2025-10-19 00:20:33.405415 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-19 00:20:33.405440 | debian-bookworm | Hostname: debian 2025-10-19 00:20:33.405464 | debian-bookworm | Username: zuul 2025-10-19 00:20:33.405485 | debian-bookworm | Distro: Debian 12.12 2025-10-19 00:20:33.405510 | debian-bookworm | Provider: regiocloud-a 2025-10-19 00:20:33.405531 | debian-bookworm | Region: 2025-10-19 00:20:33.405552 | debian-bookworm | Label: debian-bookworm 2025-10-19 00:20:33.405572 | debian-bookworm | Product Name: OpenStack Nova 2025-10-19 00:20:33.405591 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feb3:d318 2025-10-19 00:20:33.439102 | 2025-10-19 00:20:33.439218 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-19 00:20:33.893576 | debian-bookworm -> localhost | changed 2025-10-19 00:20:33.900492 | 2025-10-19 00:20:33.900594 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-19 00:20:35.009493 | debian-bookworm -> localhost | changed 2025-10-19 00:20:35.029925 | 2025-10-19 00:20:35.030035 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-19 00:20:35.295202 | debian-bookworm -> localhost | ok 2025-10-19 00:20:35.300084 | 2025-10-19 00:20:35.300169 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-19 00:20:35.316692 | debian-bookworm | ok 2025-10-19 00:20:35.335841 | debian-bookworm | included: /var/lib/zuul/builds/ae03feb1beb149999c786241b091773c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-19 00:20:35.340524 | 2025-10-19 00:20:35.340593 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-19 00:20:36.935384 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-19 00:20:36.935653 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/ae03feb1beb149999c786241b091773c/work/ae03feb1beb149999c786241b091773c_id_rsa 2025-10-19 00:20:36.935731 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/ae03feb1beb149999c786241b091773c/work/ae03feb1beb149999c786241b091773c_id_rsa.pub 2025-10-19 00:20:36.935777 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-19 00:20:36.935818 | debian-bookworm -> localhost | SHA256:oUwD/V7mdgRXtl3z1pdE1riOQfA+sZLZobS8w9HPc9M zuul-build-sshkey 2025-10-19 00:20:36.935854 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-19 00:20:36.935901 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-19 00:20:36.935937 | debian-bookworm -> localhost | | .. .. .+*o| 2025-10-19 00:20:36.935973 | debian-bookworm -> localhost | | .. ..o.+oB| 2025-10-19 00:20:36.936007 | debian-bookworm -> localhost | | o.. .++ .o*| 2025-10-19 00:20:36.936040 | debian-bookworm -> localhost | | o o.+oOo+...| 2025-10-19 00:20:36.936073 | debian-bookworm -> localhost | | o.S+O.*+ | 2025-10-19 00:20:36.936115 | debian-bookworm -> localhost | | ..o+o+. .| 2025-10-19 00:20:36.936148 | debian-bookworm -> localhost | | .+. +.E| 2025-10-19 00:20:36.936181 | debian-bookworm -> localhost | | . o.| 2025-10-19 00:20:36.936216 | debian-bookworm -> localhost | | | 2025-10-19 00:20:36.936248 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-19 00:20:36.936330 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.076666 2025-10-19 00:20:36.946168 | 2025-10-19 00:20:36.946291 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-19 00:20:36.975724 | debian-bookworm | ok 2025-10-19 00:20:36.990662 | debian-bookworm | included: /var/lib/zuul/builds/ae03feb1beb149999c786241b091773c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-19 00:20:37.000364 | 2025-10-19 00:20:37.000566 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-19 00:20:37.024567 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:20:37.030231 | 2025-10-19 00:20:37.030329 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-19 00:20:37.649036 | debian-bookworm | changed 2025-10-19 00:20:37.655801 | 2025-10-19 00:20:37.655904 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-19 00:20:37.888961 | debian-bookworm | ok 2025-10-19 00:20:37.893628 | 2025-10-19 00:20:37.893725 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-19 00:20:38.604152 | debian-bookworm | changed 2025-10-19 00:20:38.610595 | 2025-10-19 00:20:38.610686 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-19 00:20:39.261848 | debian-bookworm | changed 2025-10-19 00:20:39.272732 | 2025-10-19 00:20:39.273064 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-19 00:20:39.299097 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:20:39.311951 | 2025-10-19 00:20:39.312190 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-19 00:20:39.668994 | debian-bookworm -> localhost | changed 2025-10-19 00:20:39.678059 | 2025-10-19 00:20:39.678149 | TASK [add-build-sshkey : Add back temp key] 2025-10-19 00:20:39.973481 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/ae03feb1beb149999c786241b091773c/work/ae03feb1beb149999c786241b091773c_id_rsa (zuul-build-sshkey) 2025-10-19 00:20:39.973689 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011948 2025-10-19 00:20:39.981079 | 2025-10-19 00:20:39.981169 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-19 00:20:40.349474 | debian-bookworm | ok 2025-10-19 00:20:40.354562 | 2025-10-19 00:20:40.354670 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-19 00:20:40.377558 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:20:40.387646 | 2025-10-19 00:20:40.387765 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-19 00:20:40.770271 | debian-bookworm | ok 2025-10-19 00:20:40.781098 | 2025-10-19 00:20:40.781269 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-19 00:20:40.810286 | debian-bookworm | ok 2025-10-19 00:20:40.815363 | 2025-10-19 00:20:40.815472 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-19 00:20:41.102423 | debian-bookworm -> localhost | ok 2025-10-19 00:20:41.108745 | 2025-10-19 00:20:41.108843 | TASK [validate-host : Collect information about the host] 2025-10-19 00:20:42.152880 | debian-bookworm | ok 2025-10-19 00:20:42.163106 | 2025-10-19 00:20:42.163257 | TASK [validate-host : Sanitize hostname] 2025-10-19 00:20:42.270009 | debian-bookworm | ok 2025-10-19 00:20:42.301889 | 2025-10-19 00:20:42.301986 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-19 00:20:42.812047 | debian-bookworm -> localhost | changed 2025-10-19 00:20:42.825057 | 2025-10-19 00:20:42.825209 | TASK [validate-host : Collect information about zuul worker] 2025-10-19 00:20:43.234742 | debian-bookworm | ok 2025-10-19 00:20:43.239221 | 2025-10-19 00:20:43.239327 | TASK [validate-host : Write out all zuul information for each host] 2025-10-19 00:20:43.720136 | debian-bookworm -> localhost | changed 2025-10-19 00:20:43.738222 | 2025-10-19 00:20:43.738337 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-19 00:20:44.012419 | debian-bookworm | ok 2025-10-19 00:20:44.019967 | 2025-10-19 00:20:44.020091 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-19 00:20:44.936431 | debian-bookworm | changed: 2025-10-19 00:20:44.936596 | debian-bookworm | cd+++++++++ src/ 2025-10-19 00:20:44.936630 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-19 00:20:44.936655 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-19 00:20:44.936676 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-10-19 00:20:44.936697 | debian-bookworm | =6.2 2025-10-19 00:21:06.908159 | debian-bookworm | Downloading cachetools-6.2.1-py3-none-any.whl (11 kB) 2025-10-19 00:21:06.942476 | debian-bookworm | Collecting chardet>=5.2 2025-10-19 00:21:06.955692 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-10-19 00:21:06.968053 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 21.2 MB/s eta 0:00:00 2025-10-19 00:21:07.006209 | debian-bookworm | Collecting colorama>=0.4.6 2025-10-19 00:21:07.019806 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-10-19 00:21:07.078987 | debian-bookworm | Collecting filelock>=3.20 2025-10-19 00:21:07.092496 | debian-bookworm | Downloading filelock-3.20.0-py3-none-any.whl (16 kB) 2025-10-19 00:21:07.152709 | debian-bookworm | Collecting packaging>=25 2025-10-19 00:21:07.165960 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-19 00:21:07.173451 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 12.1 MB/s eta 0:00:00 2025-10-19 00:21:07.231120 | debian-bookworm | Collecting platformdirs>=4.5 2025-10-19 00:21:07.244845 | debian-bookworm | Downloading platformdirs-4.5.0-py3-none-any.whl (18 kB) 2025-10-19 00:21:07.286063 | debian-bookworm | Collecting pluggy>=1.6 2025-10-19 00:21:07.299187 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-10-19 00:21:07.343156 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-10-19 00:21:07.359162 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-10-19 00:21:07.505135 | debian-bookworm | Collecting virtualenv>=20.34 2025-10-19 00:21:07.518860 | debian-bookworm | Downloading virtualenv-20.35.3-py3-none-any.whl (6.0 MB) 2025-10-19 00:21:07.826794 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 20.4 MB/s eta 0:00:00 2025-10-19 00:21:07.931107 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-10-19 00:21:07.944279 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-10-19 00:21:07.970170 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 19.6 MB/s eta 0:00:00 2025-10-19 00:21:08.099237 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-10-19 00:21:08.917291 | debian-bookworm | Successfully installed cachetools-6.2.1 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.20.0 packaging-25.0 platformdirs-4.5.0 pluggy-1.6.0 pyproject-api-1.10.0 tox-4.31.0 virtualenv-20.35.3 2025-10-19 00:21:09.272675 | debian-bookworm | ok: Runtime: 0:00:02.852569 2025-10-19 00:21:09.279341 | 2025-10-19 00:21:09.279464 | TASK [ensure-tox : Export installed tox_executable path] 2025-10-19 00:21:09.355222 | debian-bookworm | ok 2025-10-19 00:21:09.362612 | 2025-10-19 00:21:09.362750 | TASK [ensure-tox : Output tox version] 2025-10-19 00:21:09.935417 | 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-19 00:21:09.953864 | debian-bookworm | 4.31.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-19 00:21:10.397454 | debian-bookworm | ok: Runtime: 0:00:00.278179 2025-10-19 00:21:10.406585 | 2025-10-19 00:21:10.406751 | TASK [ensure-tox : Make global symlink] 2025-10-19 00:21:10.431617 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:21:10.445443 | 2025-10-19 00:21:10.445572 | PLAY RECAP 2025-10-19 00:21:10.446055 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-10-19 00:21:10.446114 | 2025-10-19 00:21:10.623179 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-10-19 00:21:10.624329 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-19 00:21:11.492342 | 2025-10-19 00:21:11.492503 | PLAY [all] 2025-10-19 00:21:11.508427 | 2025-10-19 00:21:11.508574 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-10-19 00:21:12.035072 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-19 00:21:12.045628 | debian-bookworm | ok: Runtime: 0:00:00.029074 2025-10-19 00:21:12.052855 | 2025-10-19 00:21:12.052999 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-10-19 00:21:12.583800 | debian-bookworm | ok: Runtime: 0:00:00.006409 2025-10-19 00:21:12.592556 | 2025-10-19 00:21:12.592690 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-10-19 00:21:12.972267 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-19 00:21:12.973874 | debian-bookworm | sudo: a password is required 2025-10-19 00:21:13.121882 | debian-bookworm | ok: Runtime: 0:00:00.017995 2025-10-19 00:21:13.141262 | 2025-10-19 00:21:13.141421 | TASK [tox : Check to see if the constraints file exists] 2025-10-19 00:21:13.189207 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:21:13.210315 | 2025-10-19 00:21:13.210440 | TASK [tox : Fail if constraints file is missing] 2025-10-19 00:21:13.247575 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:21:13.256137 | 2025-10-19 00:21:13.256461 | TASK [tox : Record file location] 2025-10-19 00:21:13.283064 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:21:13.308762 | 2025-10-19 00:21:13.310176 | TASK [tox : Install tox siblings] 2025-10-19 00:21:13.354995 | debian-bookworm | ok 2025-10-19 00:21:13.364675 | debian-bookworm | included: /var/lib/zuul/builds/ae03feb1beb149999c786241b091773c/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-10-19 00:21:13.371231 | 2025-10-19 00:21:13.371347 | TASK [tox : Run tox without tests] 2025-10-19 00:21:14.518344 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-10-19 00:21:28.256523 | debian-bookworm | test: OK (14.27 seconds) 2025-10-19 00:21:28.256636 | debian-bookworm | congratulations :) (14.30 seconds) 2025-10-19 00:21:28.426998 | debian-bookworm | ok: Runtime: 0:00:14.574532 2025-10-19 00:21:28.433853 | 2025-10-19 00:21:28.433981 | TASK [tox : Create a tempfile to save tox showconfig] 2025-10-19 00:21:28.830591 | debian-bookworm | changed 2025-10-19 00:21:28.837470 | 2025-10-19 00:21:28.837609 | TASK [tox : Get tox envlist config] 2025-10-19 00:21:29.870007 | debian-bookworm | ok: Runtime: 0:00:00.331646 2025-10-19 00:21:29.875743 | 2025-10-19 00:21:29.875858 | TASK [tox : Install any sibling python packages] 2025-10-19 00:21:30.351549 | debian-bookworm | ok: No setup.cfg, no action needed 2025-10-19 00:21:30.357445 | 2025-10-19 00:21:30.357551 | TASK [tox : Remove tempfile] 2025-10-19 00:21:30.798678 | debian-bookworm | changed 2025-10-19 00:21:30.807595 | 2025-10-19 00:21:30.807760 | TASK [tox : Emit tox command] 2025-10-19 00:21:30.854745 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-10-19 00:21:30.863554 | 2025-10-19 00:21:30.863745 | TASK [tox : Run tox] 2025-10-19 00:21:31.539289 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-10-19 00:21:32.156287 | debian-bookworm | ............... 2025-10-19 00:21:32.156331 | debian-bookworm | ---------------------------------------------------------------------- 2025-10-19 00:21:32.156346 | debian-bookworm | Ran 15 tests in 0.178s 2025-10-19 00:21:32.156351 | debian-bookworm | 2025-10-19 00:21:32.156358 | debian-bookworm | OK 2025-10-19 00:21:32.236388 | debian-bookworm | test: OK (0.74=setup[0.04]+cmd[0.70] seconds) 2025-10-19 00:21:32.236445 | debian-bookworm | congratulations :) (0.76 seconds) 2025-10-19 00:21:32.428334 | debian-bookworm | ok: Runtime: 0:00:01.038344 2025-10-19 00:21:32.440069 | 2025-10-19 00:21:32.440217 | TASK [tox : Look for output] 2025-10-19 00:21:32.829717 | debian-bookworm | ok 2025-10-19 00:21:32.847454 | 2025-10-19 00:21:32.847594 | TASK [tox : Return file comments to Zuul] 2025-10-19 00:21:32.883191 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:21:32.893840 | 2025-10-19 00:21:32.893976 | PLAY RECAP 2025-10-19 00:21:32.894084 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-10-19 00:21:32.894136 | 2025-10-19 00:21:33.020790 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-19 00:21:33.021919 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-19 00:21:33.731567 | 2025-10-19 00:21:33.731785 | PLAY [all] 2025-10-19 00:21:33.746777 | 2025-10-19 00:21:33.746926 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-10-19 00:21:33.802217 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:21:33.810876 | 2025-10-19 00:21:33.811027 | TASK [fetch-tox-output : Set tox log path for single node] 2025-10-19 00:21:33.844586 | debian-bookworm | ok 2025-10-19 00:21:33.851759 | 2025-10-19 00:21:33.851883 | TASK [fetch-tox-output : Ensure local tox dir] 2025-10-19 00:21:34.373059 | debian-bookworm -> localhost | changed 2025-10-19 00:21:34.381233 | 2025-10-19 00:21:34.381355 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-10-19 00:21:34.425521 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:21:34.438650 | 2025-10-19 00:21:34.438861 | TASK [fetch-tox-output : Set envlist fact] 2025-10-19 00:21:34.485229 | debian-bookworm | ok 2025-10-19 00:21:34.494037 | 2025-10-19 00:21:34.494165 | TASK [fetch-tox-output : Get tox version] 2025-10-19 00:21:35.225910 | 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-19 00:21:35.244132 | debian-bookworm | 4.31.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-19 00:21:35.527560 | debian-bookworm | ok: Runtime: 0:00:00.272744 2025-10-19 00:21:35.540687 | 2025-10-19 00:21:35.540869 | TASK [fetch-tox-output : Find all default environments] 2025-10-19 00:21:36.086097 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:21:36.098617 | 2025-10-19 00:21:36.098810 | TASK [fetch-tox-output : Set envlist fact] 2025-10-19 00:21:36.134652 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:21:36.147567 | 2025-10-19 00:21:36.147750 | TASK [fetch-tox-output : Find all default environments] 2025-10-19 00:21:36.679920 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:21:36.694239 | 2025-10-19 00:21:36.694425 | TASK [fetch-tox-output : Set envlist fact] 2025-10-19 00:21:36.730465 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:21:36.743470 | 2025-10-19 00:21:36.743635 | LOOP [fetch-tox-output : Copy tox logs] 2025-10-19 00:21:36.794114 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:21:36.811678 | 2025-10-19 00:21:36.811890 | LOOP [fetch-tox-output : Collect tox logs] 2025-10-19 00:21:37.605032 | debian-bookworm | changed: 2025-10-19 00:21:37.605321 | debian-bookworm | created directory /var/lib/zuul/builds/ae03feb1beb149999c786241b091773c/work/logs/tox/test 2025-10-19 00:21:37.605379 | debian-bookworm | cd+++++++++ ./ 2025-10-19 00:21:37.605422 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-10-19 00:21:37.605471 | 2025-10-19 00:21:37.625515 | 2025-10-19 00:21:37.625653 | PLAY RECAP 2025-10-19 00:21:37.625750 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-10-19 00:21:37.625793 | 2025-10-19 00:21:37.752233 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-19 00:21:37.754900 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-19 00:21:38.492427 | 2025-10-19 00:21:38.492575 | PLAY [all] 2025-10-19 00:21:38.508611 | 2025-10-19 00:21:38.508758 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-19 00:21:38.749337 | debian-bookworm | changed: non-zero return code 2025-10-19 00:21:38.757859 | 2025-10-19 00:21:38.758022 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-19 00:21:38.774268 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:21:38.781991 | 2025-10-19 00:21:38.782117 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-19 00:21:38.819595 | 2025-10-19 00:21:38.819905 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-19 00:21:38.857966 | 2025-10-19 00:21:38.858203 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-19 00:21:38.882335 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:21:38.893506 | 2025-10-19 00:21:38.893666 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-19 00:21:38.930512 | 2025-10-19 00:21:38.930825 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-19 00:21:38.956459 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:21:38.967650 | 2025-10-19 00:21:38.967858 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-19 00:21:38.992025 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:21:39.004386 | 2025-10-19 00:21:39.004534 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-19 00:21:39.028639 | debian-bookworm | skipping: Conditional result was False 2025-10-19 00:21:39.042130 | 2025-10-19 00:21:39.042249 | PLAY RECAP 2025-10-19 00:21:39.042326 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-19 00:21:39.042366 | 2025-10-19 00:21:39.162040 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-19 00:21:39.164400 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-19 00:21:39.883216 | 2025-10-19 00:21:39.883375 | PLAY [Base post] 2025-10-19 00:21:39.897184 | 2025-10-19 00:21:39.897313 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-19 00:21:40.471228 | debian-bookworm | changed 2025-10-19 00:21:40.481236 | 2025-10-19 00:21:40.481374 | PLAY RECAP 2025-10-19 00:21:40.481459 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-19 00:21:40.481542 | 2025-10-19 00:21:40.613215 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-19 00:21:40.616910 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-19 00:21:41.418381 | 2025-10-19 00:21:41.418552 | PLAY [Base post-logs] 2025-10-19 00:21:41.429322 | 2025-10-19 00:21:41.429453 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-19 00:21:41.884128 | localhost | changed 2025-10-19 00:21:41.898611 | 2025-10-19 00:21:41.898851 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-19 00:21:41.925302 | localhost | ok 2025-10-19 00:21:41.929973 | 2025-10-19 00:21:41.930115 | TASK [Set zuul-log-path fact] 2025-10-19 00:21:41.946603 | localhost | ok 2025-10-19 00:21:41.957820 | 2025-10-19 00:21:41.957948 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-19 00:21:41.983446 | localhost | ok 2025-10-19 00:21:41.987489 | 2025-10-19 00:21:41.987609 | TASK [upload-logs : Create log directories] 2025-10-19 00:21:42.486308 | localhost | changed 2025-10-19 00:21:42.491831 | 2025-10-19 00:21:42.492009 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-19 00:21:42.973007 | localhost -> localhost | ok: Runtime: 0:00:00.006334 2025-10-19 00:21:42.977053 | 2025-10-19 00:21:42.977170 | TASK [upload-logs : Upload logs to log server] 2025-10-19 00:21:43.532470 | localhost | Output suppressed because no_log was given 2025-10-19 00:21:43.534647 | 2025-10-19 00:21:43.534790 | LOOP [upload-logs : Compress console log and json output] 2025-10-19 00:21:43.597195 | localhost | skipping: Conditional result was False 2025-10-19 00:21:43.602227 | localhost | skipping: Conditional result was False 2025-10-19 00:21:43.616069 | 2025-10-19 00:21:43.616288 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-19 00:21:43.664275 | localhost | skipping: Conditional result was False 2025-10-19 00:21:43.666142 | 2025-10-19 00:21:43.668927 | localhost | skipping: Conditional result was False 2025-10-19 00:21:43.681034 | 2025-10-19 00:21:43.681258 | LOOP [upload-logs : Upload console log and json output]