2025-10-20 15:32:04.164981 | Job console starting 2025-10-20 15:32:04.184935 | Updating git repos 2025-10-20 15:32:04.242651 | Cloning repos into workspace 2025-10-20 15:32:04.324441 | Restoring repo states 2025-10-20 15:32:04.353849 | Merging changes 2025-10-20 15:32:04.873920 | Checking out repos 2025-10-20 15:32:04.905848 | Preparing playbooks 2025-10-20 15:32:05.725082 | Running Ansible setup 2025-10-20 15:32:11.086885 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-20 15:32:12.394911 | 2025-10-20 15:32:12.395087 | PLAY [Base pre] 2025-10-20 15:32:12.412173 | 2025-10-20 15:32:12.412332 | TASK [Setup log path fact] 2025-10-20 15:32:12.452286 | debian-bookworm | ok 2025-10-20 15:32:12.485240 | 2025-10-20 15:32:12.485410 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-20 15:32:12.567280 | debian-bookworm | ok 2025-10-20 15:32:12.602922 | 2025-10-20 15:32:12.603073 | TASK [emit-job-header : Print job information] 2025-10-20 15:32:12.663412 | # Job Information 2025-10-20 15:32:12.663597 | Ansible Version: 2.16.14 2025-10-20 15:32:12.663633 | Job: tox 2025-10-20 15:32:12.663665 | Pipeline: check 2025-10-20 15:32:12.663687 | Executor: 521e9411259a 2025-10-20 15:32:12.663709 | Triggered by: https://github.com/osism/openstack-image-manager/pull/976 2025-10-20 15:32:12.663732 | Event ID: e92408d0-adc9-11f0-88da-8e7b5c5a2574 2025-10-20 15:32:12.668797 | 2025-10-20 15:32:12.668924 | LOOP [emit-job-header : Print node information] 2025-10-20 15:32:12.788307 | debian-bookworm | ok: 2025-10-20 15:32:12.788520 | debian-bookworm | # Node Information 2025-10-20 15:32:12.788557 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-20 15:32:12.788581 | debian-bookworm | Hostname: debian 2025-10-20 15:32:12.788602 | debian-bookworm | Username: zuul 2025-10-20 15:32:12.788623 | debian-bookworm | Distro: Debian 12.12 2025-10-20 15:32:12.788648 | debian-bookworm | Provider: regiocloud-a 2025-10-20 15:32:12.788669 | debian-bookworm | Region: 2025-10-20 15:32:12.788689 | debian-bookworm | Label: debian-bookworm 2025-10-20 15:32:12.788709 | debian-bookworm | Product Name: OpenStack Nova 2025-10-20 15:32:12.788727 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe07:dc6a 2025-10-20 15:32:12.835772 | 2025-10-20 15:32:12.835953 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-20 15:32:13.880018 | debian-bookworm -> localhost | changed 2025-10-20 15:32:13.887848 | 2025-10-20 15:32:13.888017 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-20 15:32:15.319561 | debian-bookworm -> localhost | changed 2025-10-20 15:32:15.328423 | 2025-10-20 15:32:15.328563 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-20 15:32:15.613688 | debian-bookworm -> localhost | ok 2025-10-20 15:32:15.618540 | 2025-10-20 15:32:15.618621 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-20 15:32:15.655284 | debian-bookworm | ok 2025-10-20 15:32:15.682698 | debian-bookworm | included: /var/lib/zuul/builds/6dd8c5021611443396a7be093c388e3c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-20 15:32:15.688207 | 2025-10-20 15:32:15.688288 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-20 15:32:17.256088 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-20 15:32:17.256253 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/6dd8c5021611443396a7be093c388e3c/work/6dd8c5021611443396a7be093c388e3c_id_rsa 2025-10-20 15:32:17.256283 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/6dd8c5021611443396a7be093c388e3c/work/6dd8c5021611443396a7be093c388e3c_id_rsa.pub 2025-10-20 15:32:17.256305 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-20 15:32:17.256327 | debian-bookworm -> localhost | SHA256:faaUAmDPDTrfEStEAWKLL6BDtGoNHnh2Fc6nTnUjms8 zuul-build-sshkey 2025-10-20 15:32:17.256346 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-20 15:32:17.256372 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-20 15:32:17.256390 | debian-bookworm -> localhost | | .o +oBo. | 2025-10-20 15:32:17.256407 | debian-bookworm -> localhost | |oo.+ X o o | 2025-10-20 15:32:17.256423 | debian-bookworm -> localhost | |+=+ + B B o | 2025-10-20 15:32:17.256439 | debian-bookworm -> localhost | |*++. o X = o | 2025-10-20 15:32:17.256455 | debian-bookworm -> localhost | |=o.. * S + o | 2025-10-20 15:32:17.256475 | debian-bookworm -> localhost | |.o o o o + | 2025-10-20 15:32:17.256492 | debian-bookworm -> localhost | | . E . | 2025-10-20 15:32:17.256508 | debian-bookworm -> localhost | | | 2025-10-20 15:32:17.256524 | debian-bookworm -> localhost | | | 2025-10-20 15:32:17.256540 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-20 15:32:17.256584 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.990179 2025-10-20 15:32:17.261700 | 2025-10-20 15:32:17.261774 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-20 15:32:17.288029 | debian-bookworm | ok 2025-10-20 15:32:17.303373 | debian-bookworm | included: /var/lib/zuul/builds/6dd8c5021611443396a7be093c388e3c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-20 15:32:17.322488 | 2025-10-20 15:32:17.322566 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-20 15:32:17.353213 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:32:17.357977 | 2025-10-20 15:32:17.358049 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-20 15:32:18.050448 | debian-bookworm | changed 2025-10-20 15:32:18.054879 | 2025-10-20 15:32:18.054963 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-20 15:32:18.346261 | debian-bookworm | ok 2025-10-20 15:32:18.350660 | 2025-10-20 15:32:18.350732 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-20 15:32:18.975675 | debian-bookworm | changed 2025-10-20 15:32:18.982265 | 2025-10-20 15:32:18.982355 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-20 15:32:19.624214 | debian-bookworm | changed 2025-10-20 15:32:19.633517 | 2025-10-20 15:32:19.633639 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-20 15:32:19.661109 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:32:19.670062 | 2025-10-20 15:32:19.670166 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-20 15:32:20.100065 | debian-bookworm -> localhost | changed 2025-10-20 15:32:20.108593 | 2025-10-20 15:32:20.108718 | TASK [add-build-sshkey : Add back temp key] 2025-10-20 15:32:20.458680 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/6dd8c5021611443396a7be093c388e3c/work/6dd8c5021611443396a7be093c388e3c_id_rsa (zuul-build-sshkey) 2025-10-20 15:32:20.458881 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.008689 2025-10-20 15:32:20.468210 | 2025-10-20 15:32:20.468286 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-20 15:32:20.803783 | debian-bookworm | ok 2025-10-20 15:32:20.812604 | 2025-10-20 15:32:20.812739 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-20 15:32:20.847000 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:32:20.871506 | 2025-10-20 15:32:20.871644 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-20 15:32:21.263975 | debian-bookworm | ok 2025-10-20 15:32:21.279551 | 2025-10-20 15:32:21.279646 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-20 15:32:21.307576 | debian-bookworm | ok 2025-10-20 15:32:21.312424 | 2025-10-20 15:32:21.312509 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-20 15:32:21.637098 | debian-bookworm -> localhost | ok 2025-10-20 15:32:21.642669 | 2025-10-20 15:32:21.642748 | TASK [validate-host : Collect information about the host] 2025-10-20 15:32:22.717364 | debian-bookworm | ok 2025-10-20 15:32:22.727360 | 2025-10-20 15:32:22.727447 | TASK [validate-host : Sanitize hostname] 2025-10-20 15:32:22.816037 | debian-bookworm | ok 2025-10-20 15:32:22.880143 | 2025-10-20 15:32:22.880256 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-20 15:32:23.512055 | debian-bookworm -> localhost | changed 2025-10-20 15:32:23.519654 | 2025-10-20 15:32:23.519776 | TASK [validate-host : Collect information about zuul worker] 2025-10-20 15:32:23.925986 | debian-bookworm | ok 2025-10-20 15:32:23.932263 | 2025-10-20 15:32:23.932361 | TASK [validate-host : Write out all zuul information for each host] 2025-10-20 15:32:24.579225 | debian-bookworm -> localhost | changed 2025-10-20 15:32:24.588384 | 2025-10-20 15:32:24.588481 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-20 15:32:24.871646 | debian-bookworm | ok 2025-10-20 15:32:24.876916 | 2025-10-20 15:32:24.877002 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-20 15:32:25.708995 | debian-bookworm | changed: 2025-10-20 15:32:25.709128 | debian-bookworm | cd+++++++++ src/ 2025-10-20 15:32:25.709156 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-20 15:32:25.709176 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-20 15:32:25.709194 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-10-20 15:32:25.709211 | debian-bookworm | =6.2 2025-10-20 15:32:46.956647 | debian-bookworm | Downloading cachetools-6.2.1-py3-none-any.whl (11 kB) 2025-10-20 15:32:47.009752 | debian-bookworm | Collecting chardet>=5.2 2025-10-20 15:32:47.025522 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-10-20 15:32:47.049270 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 10.7 MB/s eta 0:00:00 2025-10-20 15:32:47.096041 | debian-bookworm | Collecting colorama>=0.4.6 2025-10-20 15:32:47.109779 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-10-20 15:32:47.171918 | debian-bookworm | Collecting filelock>=3.20 2025-10-20 15:32:47.185689 | debian-bookworm | Downloading filelock-3.20.0-py3-none-any.whl (16 kB) 2025-10-20 15:32:47.241479 | debian-bookworm | Collecting packaging>=25 2025-10-20 15:32:47.255747 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-20 15:32:47.264574 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 10.0 MB/s eta 0:00:00 2025-10-20 15:32:47.322000 | debian-bookworm | Collecting platformdirs>=4.5 2025-10-20 15:32:47.336583 | debian-bookworm | Downloading platformdirs-4.5.0-py3-none-any.whl (18 kB) 2025-10-20 15:32:47.385592 | debian-bookworm | Collecting pluggy>=1.6 2025-10-20 15:32:47.399786 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-10-20 15:32:47.439002 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-10-20 15:32:47.453919 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-10-20 15:32:47.579428 | debian-bookworm | Collecting virtualenv>=20.34 2025-10-20 15:32:47.594409 | debian-bookworm | Downloading virtualenv-20.35.3-py3-none-any.whl (6.0 MB) 2025-10-20 15:32:47.939421 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 17.7 MB/s eta 0:00:00 2025-10-20 15:32:48.052541 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-10-20 15:32:48.066467 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-10-20 15:32:48.090283 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 22.6 MB/s eta 0:00:00 2025-10-20 15:32:48.248223 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-10-20 15:32:49.060773 | 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-20 15:32:49.372678 | debian-bookworm | ok: Runtime: 0:00:03.048152 2025-10-20 15:32:49.383148 | 2025-10-20 15:32:49.383310 | TASK [ensure-tox : Export installed tox_executable path] 2025-10-20 15:32:49.424721 | debian-bookworm | ok 2025-10-20 15:32:49.429680 | 2025-10-20 15:32:49.429789 | TASK [ensure-tox : Output tox version] 2025-10-20 15:32:49.949805 | 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-20 15:32:49.968110 | debian-bookworm | 4.31.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-20 15:32:50.459970 | debian-bookworm | ok: Runtime: 0:00:00.294999 2025-10-20 15:32:50.477431 | 2025-10-20 15:32:50.478265 | TASK [ensure-tox : Make global symlink] 2025-10-20 15:32:50.512878 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:32:50.519613 | 2025-10-20 15:32:50.519700 | PLAY RECAP 2025-10-20 15:32:50.519753 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-10-20 15:32:50.519778 | 2025-10-20 15:32:50.686012 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-10-20 15:32:50.687218 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-20 15:32:51.462220 | 2025-10-20 15:32:51.462383 | PLAY [all] 2025-10-20 15:32:51.479710 | 2025-10-20 15:32:51.480266 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-10-20 15:32:51.931941 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-20 15:32:52.008221 | debian-bookworm | ok: Runtime: 0:00:00.028015 2025-10-20 15:32:52.014193 | 2025-10-20 15:32:52.014333 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-10-20 15:32:52.567130 | debian-bookworm | ok: Runtime: 0:00:00.007296 2025-10-20 15:32:52.573238 | 2025-10-20 15:32:52.573364 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-10-20 15:32:52.842066 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-20 15:32:52.843729 | debian-bookworm | sudo: a password is required 2025-10-20 15:32:53.101161 | debian-bookworm | ok: Runtime: 0:00:00.017959 2025-10-20 15:32:53.111698 | 2025-10-20 15:32:53.111807 | TASK [tox : Check to see if the constraints file exists] 2025-10-20 15:32:53.146288 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:32:53.154275 | 2025-10-20 15:32:53.154399 | TASK [tox : Fail if constraints file is missing] 2025-10-20 15:32:53.178690 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:32:53.191457 | 2025-10-20 15:32:53.191607 | TASK [tox : Record file location] 2025-10-20 15:32:53.226185 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:32:53.240395 | 2025-10-20 15:32:53.240578 | TASK [tox : Install tox siblings] 2025-10-20 15:32:53.272245 | debian-bookworm | ok 2025-10-20 15:32:53.280627 | debian-bookworm | included: /var/lib/zuul/builds/6dd8c5021611443396a7be093c388e3c/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-10-20 15:32:53.286445 | 2025-10-20 15:32:53.286555 | TASK [tox : Run tox without tests] 2025-10-20 15:32:54.352051 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-10-20 15:33:07.930015 | debian-bookworm | test: OK (14.11 seconds) 2025-10-20 15:33:07.930105 | debian-bookworm | congratulations :) (14.14 seconds) 2025-10-20 15:33:08.333638 | debian-bookworm | ok: Runtime: 0:00:14.413908 2025-10-20 15:33:08.348493 | 2025-10-20 15:33:08.348704 | TASK [tox : Create a tempfile to save tox showconfig] 2025-10-20 15:33:08.763061 | debian-bookworm | changed 2025-10-20 15:33:08.770238 | 2025-10-20 15:33:08.770356 | TASK [tox : Get tox envlist config] 2025-10-20 15:33:09.801076 | debian-bookworm | ok: Runtime: 0:00:00.333261 2025-10-20 15:33:09.813727 | 2025-10-20 15:33:09.813888 | TASK [tox : Install any sibling python packages] 2025-10-20 15:33:10.274040 | debian-bookworm | ok: No setup.cfg, no action needed 2025-10-20 15:33:10.281451 | 2025-10-20 15:33:10.281578 | TASK [tox : Remove tempfile] 2025-10-20 15:33:10.714691 | debian-bookworm | changed 2025-10-20 15:33:10.724344 | 2025-10-20 15:33:10.724482 | TASK [tox : Emit tox command] 2025-10-20 15:33:10.782735 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-10-20 15:33:10.792421 | 2025-10-20 15:33:10.792591 | TASK [tox : Run tox] 2025-10-20 15:33:11.422760 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-10-20 15:33:12.052692 | debian-bookworm | ............... 2025-10-20 15:33:12.052742 | debian-bookworm | ---------------------------------------------------------------------- 2025-10-20 15:33:12.052757 | debian-bookworm | Ran 15 tests in 0.171s 2025-10-20 15:33:12.052762 | debian-bookworm | 2025-10-20 15:33:12.052770 | debian-bookworm | OK 2025-10-20 15:33:12.146664 | debian-bookworm | test: OK (0.76=setup[0.04]+cmd[0.72] seconds) 2025-10-20 15:33:12.146725 | debian-bookworm | congratulations :) (0.79 seconds) 2025-10-20 15:33:12.360388 | debian-bookworm | ok: Runtime: 0:00:01.065183 2025-10-20 15:33:12.371452 | 2025-10-20 15:33:12.371598 | TASK [tox : Look for output] 2025-10-20 15:33:12.761564 | debian-bookworm | ok 2025-10-20 15:33:12.779424 | 2025-10-20 15:33:12.779549 | TASK [tox : Return file comments to Zuul] 2025-10-20 15:33:12.814674 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:33:12.825993 | 2025-10-20 15:33:12.826099 | PLAY RECAP 2025-10-20 15:33:12.826178 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-10-20 15:33:12.826216 | 2025-10-20 15:33:12.948390 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-20 15:33:12.951084 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-20 15:33:13.695868 | 2025-10-20 15:33:13.696065 | PLAY [all] 2025-10-20 15:33:13.711083 | 2025-10-20 15:33:13.711207 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-10-20 15:33:13.777246 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:33:13.790731 | 2025-10-20 15:33:13.791003 | TASK [fetch-tox-output : Set tox log path for single node] 2025-10-20 15:33:13.839783 | debian-bookworm | ok 2025-10-20 15:33:13.848135 | 2025-10-20 15:33:13.848270 | TASK [fetch-tox-output : Ensure local tox dir] 2025-10-20 15:33:14.343164 | debian-bookworm -> localhost | changed 2025-10-20 15:33:14.351244 | 2025-10-20 15:33:14.351363 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-10-20 15:33:14.406261 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:33:14.418681 | 2025-10-20 15:33:14.418893 | TASK [fetch-tox-output : Set envlist fact] 2025-10-20 15:33:14.465952 | debian-bookworm | ok 2025-10-20 15:33:14.475569 | 2025-10-20 15:33:14.475715 | TASK [fetch-tox-output : Get tox version] 2025-10-20 15:33:15.136575 | 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-20 15:33:15.154807 | debian-bookworm | 4.31.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-20 15:33:15.509187 | debian-bookworm | ok: Runtime: 0:00:00.278122 2025-10-20 15:33:15.523031 | 2025-10-20 15:33:15.523200 | TASK [fetch-tox-output : Find all default environments] 2025-10-20 15:33:16.057858 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:33:16.071432 | 2025-10-20 15:33:16.071602 | TASK [fetch-tox-output : Set envlist fact] 2025-10-20 15:33:16.109131 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:33:16.118871 | 2025-10-20 15:33:16.119027 | TASK [fetch-tox-output : Find all default environments] 2025-10-20 15:33:16.649578 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:33:16.663157 | 2025-10-20 15:33:16.663454 | TASK [fetch-tox-output : Set envlist fact] 2025-10-20 15:33:16.699150 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:33:16.705995 | 2025-10-20 15:33:16.706097 | LOOP [fetch-tox-output : Copy tox logs] 2025-10-20 15:33:16.758327 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:33:16.774882 | 2025-10-20 15:33:16.775099 | LOOP [fetch-tox-output : Collect tox logs] 2025-10-20 15:33:17.589213 | debian-bookworm | changed: 2025-10-20 15:33:17.589560 | debian-bookworm | created directory /var/lib/zuul/builds/6dd8c5021611443396a7be093c388e3c/work/logs/tox/test 2025-10-20 15:33:17.589637 | debian-bookworm | cd+++++++++ ./ 2025-10-20 15:33:17.589690 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-10-20 15:33:17.589748 | 2025-10-20 15:33:17.610559 | 2025-10-20 15:33:17.610685 | PLAY RECAP 2025-10-20 15:33:17.610768 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-10-20 15:33:17.610806 | 2025-10-20 15:33:17.735828 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-20 15:33:17.738115 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-20 15:33:18.469794 | 2025-10-20 15:33:18.469969 | PLAY [all] 2025-10-20 15:33:18.486776 | 2025-10-20 15:33:18.486950 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-20 15:33:18.734904 | debian-bookworm | changed: non-zero return code 2025-10-20 15:33:18.744051 | 2025-10-20 15:33:18.744218 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-20 15:33:18.769010 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:33:18.782514 | 2025-10-20 15:33:18.782681 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-20 15:33:18.817865 | 2025-10-20 15:33:18.818111 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-20 15:33:18.854525 | 2025-10-20 15:33:18.854772 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-20 15:33:18.879261 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:33:18.891242 | 2025-10-20 15:33:18.891393 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-20 15:33:18.927377 | 2025-10-20 15:33:18.927641 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-20 15:33:18.952010 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:33:18.963616 | 2025-10-20 15:33:18.963776 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-20 15:33:18.989467 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:33:19.003301 | 2025-10-20 15:33:19.003454 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-20 15:33:19.027763 | debian-bookworm | skipping: Conditional result was False 2025-10-20 15:33:19.042636 | 2025-10-20 15:33:19.042749 | PLAY RECAP 2025-10-20 15:33:19.042859 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-20 15:33:19.042905 | 2025-10-20 15:33:19.169603 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-20 15:33:19.172252 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-20 15:33:19.919542 | 2025-10-20 15:33:19.919704 | PLAY [Base post] 2025-10-20 15:33:19.933231 | 2025-10-20 15:33:19.933361 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-20 15:33:20.509305 | debian-bookworm | changed 2025-10-20 15:33:20.518851 | 2025-10-20 15:33:20.519001 | PLAY RECAP 2025-10-20 15:33:20.519080 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-20 15:33:20.519155 | 2025-10-20 15:33:20.648334 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-20 15:33:20.651078 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-20 15:33:21.437447 | 2025-10-20 15:33:21.437610 | PLAY [Base post-logs] 2025-10-20 15:33:21.448296 | 2025-10-20 15:33:21.448426 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-20 15:33:21.889047 | localhost | changed 2025-10-20 15:33:21.899282 | 2025-10-20 15:33:21.899426 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-20 15:33:21.934545 | localhost | ok 2025-10-20 15:33:21.937617 | 2025-10-20 15:33:21.937711 | TASK [Set zuul-log-path fact] 2025-10-20 15:33:21.952139 | localhost | ok 2025-10-20 15:33:21.960114 | 2025-10-20 15:33:21.960220 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-20 15:33:21.987411 | localhost | ok 2025-10-20 15:33:21.994313 | 2025-10-20 15:33:21.994483 | TASK [upload-logs : Create log directories] 2025-10-20 15:33:22.498423 | localhost | changed 2025-10-20 15:33:22.501417 | 2025-10-20 15:33:22.501518 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-20 15:33:22.978361 | localhost -> localhost | ok: Runtime: 0:00:00.007216 2025-10-20 15:33:22.982577 | 2025-10-20 15:33:22.982691 | TASK [upload-logs : Upload logs to log server] 2025-10-20 15:33:23.524464 | localhost | Output suppressed because no_log was given 2025-10-20 15:33:23.526506 | 2025-10-20 15:33:23.526618 | LOOP [upload-logs : Compress console log and json output] 2025-10-20 15:33:23.574635 | localhost | skipping: Conditional result was False 2025-10-20 15:33:23.582345 | localhost | skipping: Conditional result was False 2025-10-20 15:33:23.590623 | 2025-10-20 15:33:23.590868 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-20 15:33:23.638568 | localhost | skipping: Conditional result was False 2025-10-20 15:33:23.638956 | 2025-10-20 15:33:23.645857 | localhost | skipping: Conditional result was False 2025-10-20 15:33:23.653440 | 2025-10-20 15:33:23.653681 | LOOP [upload-logs : Upload console log and json output]