2025-10-19 19:26:02.012436 | Job console starting 2025-10-19 19:26:02.030023 | Updating git repos 2025-10-19 19:26:02.069989 | Cloning repos into workspace 2025-10-19 19:26:02.107756 | Restoring repo states 2025-10-19 19:26:02.116616 | Merging changes 2025-10-19 19:26:02.643079 | Checking out repos 2025-10-19 19:26:02.722533 | Preparing playbooks 2025-10-19 19:26:03.629636 | Running Ansible setup 2025-10-19 19:26:08.709399 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-19 19:26:09.924908 | 2025-10-19 19:26:09.925074 | PLAY [Base pre] 2025-10-19 19:26:09.941224 | 2025-10-19 19:26:09.941368 | TASK [Setup log path fact] 2025-10-19 19:26:09.960092 | debian-bookworm | ok 2025-10-19 19:26:09.975241 | 2025-10-19 19:26:09.975386 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-19 19:26:10.015937 | debian-bookworm | ok 2025-10-19 19:26:10.030222 | 2025-10-19 19:26:10.030355 | TASK [emit-job-header : Print job information] 2025-10-19 19:26:10.108262 | # Job Information 2025-10-19 19:26:10.108456 | Ansible Version: 2.16.14 2025-10-19 19:26:10.108490 | Job: tox 2025-10-19 19:26:10.108522 | Pipeline: check 2025-10-19 19:26:10.108544 | Executor: 521e9411259a 2025-10-19 19:26:10.108566 | Triggered by: https://github.com/osism/openstack-image-manager/pull/943 2025-10-19 19:26:10.108588 | Event ID: 6de9cbd0-ad21-11f0-821c-a955c0d7e2b9 2025-10-19 19:26:10.113562 | 2025-10-19 19:26:10.113675 | LOOP [emit-job-header : Print node information] 2025-10-19 19:26:10.310417 | debian-bookworm | ok: 2025-10-19 19:26:10.310598 | debian-bookworm | # Node Information 2025-10-19 19:26:10.310633 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-19 19:26:10.310658 | debian-bookworm | Hostname: debian 2025-10-19 19:26:10.310680 | debian-bookworm | Username: zuul 2025-10-19 19:26:10.310701 | debian-bookworm | Distro: Debian 12.12 2025-10-19 19:26:10.310725 | debian-bookworm | Provider: regiocloud-a 2025-10-19 19:26:10.310746 | debian-bookworm | Region: 2025-10-19 19:26:10.310767 | debian-bookworm | Label: debian-bookworm 2025-10-19 19:26:10.310787 | debian-bookworm | Product Name: OpenStack Nova 2025-10-19 19:26:10.310806 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fec9:493a 2025-10-19 19:26:10.327241 | 2025-10-19 19:26:10.327371 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-19 19:26:10.964506 | debian-bookworm -> localhost | changed 2025-10-19 19:26:10.971294 | 2025-10-19 19:26:10.971392 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-19 19:26:12.231426 | debian-bookworm -> localhost | changed 2025-10-19 19:26:12.241025 | 2025-10-19 19:26:12.241129 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-19 19:26:12.719490 | debian-bookworm -> localhost | ok 2025-10-19 19:26:12.725830 | 2025-10-19 19:26:12.725957 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-19 19:26:12.763302 | debian-bookworm | ok 2025-10-19 19:26:12.795015 | debian-bookworm | included: /var/lib/zuul/builds/673c5b2599ba4ed594e2254142993f8a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-19 19:26:12.805720 | 2025-10-19 19:26:12.805820 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-19 19:26:14.592761 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-19 19:26:14.592946 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/673c5b2599ba4ed594e2254142993f8a/work/673c5b2599ba4ed594e2254142993f8a_id_rsa 2025-10-19 19:26:14.592978 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/673c5b2599ba4ed594e2254142993f8a/work/673c5b2599ba4ed594e2254142993f8a_id_rsa.pub 2025-10-19 19:26:14.593000 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-19 19:26:14.593021 | debian-bookworm -> localhost | SHA256:EzYn1iZnmJqceHlt2osgvhzfCYqD5jdudRUVOKCX8PE zuul-build-sshkey 2025-10-19 19:26:14.593040 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-19 19:26:14.593065 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-19 19:26:14.593083 | debian-bookworm -> localhost | | . o. oo. | 2025-10-19 19:26:14.593101 | debian-bookworm -> localhost | | + +B | 2025-10-19 19:26:14.593118 | debian-bookworm -> localhost | | . +XEO | 2025-10-19 19:26:14.593135 | debian-bookworm -> localhost | | o.B % | 2025-10-19 19:26:14.593151 | debian-bookworm -> localhost | | . B S o | 2025-10-19 19:26:14.593171 | debian-bookworm -> localhost | | o o = | 2025-10-19 19:26:14.593187 | debian-bookworm -> localhost | | . + + . . | 2025-10-19 19:26:14.593203 | debian-bookworm -> localhost | |...B = + o . | 2025-10-19 19:26:14.593220 | debian-bookworm -> localhost | |o.=o*.. + . | 2025-10-19 19:26:14.593237 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-19 19:26:14.593276 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.981147 2025-10-19 19:26:14.599067 | 2025-10-19 19:26:14.599151 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-19 19:26:14.614917 | debian-bookworm | ok 2025-10-19 19:26:14.622002 | debian-bookworm | included: /var/lib/zuul/builds/673c5b2599ba4ed594e2254142993f8a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-19 19:26:14.637095 | 2025-10-19 19:26:14.637165 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-19 19:26:14.653194 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:26:14.657702 | 2025-10-19 19:26:14.657771 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-19 19:26:15.276983 | debian-bookworm | changed 2025-10-19 19:26:15.284354 | 2025-10-19 19:26:15.284465 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-19 19:26:15.529268 | debian-bookworm | ok 2025-10-19 19:26:15.538944 | 2025-10-19 19:26:15.539087 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-19 19:26:16.180117 | debian-bookworm | changed 2025-10-19 19:26:16.193394 | 2025-10-19 19:26:16.193487 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-19 19:26:16.854270 | debian-bookworm | changed 2025-10-19 19:26:16.859427 | 2025-10-19 19:26:16.859516 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-19 19:26:16.879093 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:26:16.884806 | 2025-10-19 19:26:16.884917 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-19 19:26:17.272364 | debian-bookworm -> localhost | changed 2025-10-19 19:26:17.282477 | 2025-10-19 19:26:17.282582 | TASK [add-build-sshkey : Add back temp key] 2025-10-19 19:26:17.627207 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/673c5b2599ba4ed594e2254142993f8a/work/673c5b2599ba4ed594e2254142993f8a_id_rsa (zuul-build-sshkey) 2025-10-19 19:26:17.627442 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.030970 2025-10-19 19:26:17.634808 | 2025-10-19 19:26:17.634934 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-19 19:26:18.129059 | debian-bookworm | ok 2025-10-19 19:26:18.133900 | 2025-10-19 19:26:18.133988 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-19 19:26:18.156986 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:26:18.177016 | 2025-10-19 19:26:18.177123 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-19 19:26:18.588332 | debian-bookworm | ok 2025-10-19 19:26:18.598283 | 2025-10-19 19:26:18.598371 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-19 19:26:18.637221 | debian-bookworm | ok 2025-10-19 19:26:18.642725 | 2025-10-19 19:26:18.642807 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-19 19:26:18.911857 | debian-bookworm -> localhost | ok 2025-10-19 19:26:18.917785 | 2025-10-19 19:26:18.917872 | TASK [validate-host : Collect information about the host] 2025-10-19 19:26:19.994308 | debian-bookworm | ok 2025-10-19 19:26:20.005692 | 2025-10-19 19:26:20.005792 | TASK [validate-host : Sanitize hostname] 2025-10-19 19:26:20.085650 | debian-bookworm | ok 2025-10-19 19:26:20.113893 | 2025-10-19 19:26:20.113992 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-19 19:26:20.705426 | debian-bookworm -> localhost | changed 2025-10-19 19:26:20.711571 | 2025-10-19 19:26:20.711669 | TASK [validate-host : Collect information about zuul worker] 2025-10-19 19:26:21.149143 | debian-bookworm | ok 2025-10-19 19:26:21.153752 | 2025-10-19 19:26:21.153894 | TASK [validate-host : Write out all zuul information for each host] 2025-10-19 19:26:21.758058 | debian-bookworm -> localhost | changed 2025-10-19 19:26:21.770022 | 2025-10-19 19:26:21.770144 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-19 19:26:22.026930 | debian-bookworm | ok 2025-10-19 19:26:22.033441 | 2025-10-19 19:26:22.033601 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-19 19:26:22.933689 | debian-bookworm | changed: 2025-10-19 19:26:22.933841 | debian-bookworm | cd+++++++++ src/ 2025-10-19 19:26:22.933869 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-19 19:26:22.933921 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-19 19:26:22.933939 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-10-19 19:26:22.933956 | debian-bookworm | =6.2 2025-10-19 19:26:44.567204 | debian-bookworm | Downloading cachetools-6.2.1-py3-none-any.whl (11 kB) 2025-10-19 19:26:44.618650 | debian-bookworm | Collecting chardet>=5.2 2025-10-19 19:26:44.634542 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-10-19 19:26:44.647900 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 21.6 MB/s eta 0:00:00 2025-10-19 19:26:44.696530 | debian-bookworm | Collecting colorama>=0.4.6 2025-10-19 19:26:44.711193 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-10-19 19:26:44.780638 | debian-bookworm | Collecting filelock>=3.20 2025-10-19 19:26:44.798777 | debian-bookworm | Downloading filelock-3.20.0-py3-none-any.whl (16 kB) 2025-10-19 19:26:44.864094 | debian-bookworm | Collecting packaging>=25 2025-10-19 19:26:44.880524 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-19 19:26:44.892502 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 8.6 MB/s eta 0:00:00 2025-10-19 19:26:44.957229 | debian-bookworm | Collecting platformdirs>=4.5 2025-10-19 19:26:44.972910 | debian-bookworm | Downloading platformdirs-4.5.0-py3-none-any.whl (18 kB) 2025-10-19 19:26:45.039940 | debian-bookworm | Collecting pluggy>=1.6 2025-10-19 19:26:45.057040 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-10-19 19:26:45.116834 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-10-19 19:26:45.163678 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-10-19 19:26:45.314572 | debian-bookworm | Collecting virtualenv>=20.34 2025-10-19 19:26:45.328907 | debian-bookworm | Downloading virtualenv-20.35.3-py3-none-any.whl (6.0 MB) 2025-10-19 19:26:45.580448 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 24.3 MB/s eta 0:00:00 2025-10-19 19:26:45.685262 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-10-19 19:26:45.700497 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-10-19 19:26:45.727410 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 21.4 MB/s eta 0:00:00 2025-10-19 19:26:45.879715 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-10-19 19:26:46.714964 | 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 19:26:46.971161 | debian-bookworm | ok: Runtime: 0:00:03.104813 2025-10-19 19:26:46.980782 | 2025-10-19 19:26:46.980969 | TASK [ensure-tox : Export installed tox_executable path] 2025-10-19 19:26:47.031950 | debian-bookworm | ok 2025-10-19 19:26:47.037393 | 2025-10-19 19:26:47.037507 | TASK [ensure-tox : Output tox version] 2025-10-19 19:26:47.578473 | 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 19:26:47.597086 | debian-bookworm | 4.31.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-19 19:26:48.076779 | debian-bookworm | ok: Runtime: 0:00:00.305420 2025-10-19 19:26:48.082674 | 2025-10-19 19:26:48.082791 | TASK [ensure-tox : Make global symlink] 2025-10-19 19:26:48.097711 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:26:48.105479 | 2025-10-19 19:26:48.105574 | PLAY RECAP 2025-10-19 19:26:48.105629 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-10-19 19:26:48.105664 | 2025-10-19 19:26:48.296864 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-10-19 19:26:48.297978 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-19 19:26:49.131736 | 2025-10-19 19:26:49.131925 | PLAY [all] 2025-10-19 19:26:49.153562 | 2025-10-19 19:26:49.153717 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-10-19 19:26:49.754429 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-19 19:26:50.211407 | debian-bookworm | ok: Runtime: 0:00:00.024551 2025-10-19 19:26:50.221685 | 2025-10-19 19:26:50.221847 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-10-19 19:26:50.752656 | debian-bookworm | ok: Runtime: 0:00:00.005885 2025-10-19 19:26:50.759044 | 2025-10-19 19:26:50.759163 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-10-19 19:26:51.073877 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-19 19:26:51.077645 | debian-bookworm | sudo: a password is required 2025-10-19 19:26:51.301006 | debian-bookworm | ok: Runtime: 0:00:00.024890 2025-10-19 19:26:51.312327 | 2025-10-19 19:26:51.312430 | TASK [tox : Check to see if the constraints file exists] 2025-10-19 19:26:51.347280 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:26:51.357518 | 2025-10-19 19:26:51.357669 | TASK [tox : Fail if constraints file is missing] 2025-10-19 19:26:51.382467 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:26:51.397820 | 2025-10-19 19:26:51.398000 | TASK [tox : Record file location] 2025-10-19 19:26:51.433056 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:26:51.444488 | 2025-10-19 19:26:51.444647 | TASK [tox : Install tox siblings] 2025-10-19 19:26:51.479574 | debian-bookworm | ok 2025-10-19 19:26:51.490310 | debian-bookworm | included: /var/lib/zuul/builds/673c5b2599ba4ed594e2254142993f8a/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-10-19 19:26:51.496045 | 2025-10-19 19:26:51.496145 | TASK [tox : Run tox without tests] 2025-10-19 19:26:52.581376 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-10-19 19:27:06.469437 | debian-bookworm | test: OK (14.44 seconds) 2025-10-19 19:27:06.469537 | debian-bookworm | congratulations :) (14.47 seconds) 2025-10-19 19:27:06.579328 | debian-bookworm | ok: Runtime: 0:00:14.746320 2025-10-19 19:27:06.588150 | 2025-10-19 19:27:06.588285 | TASK [tox : Create a tempfile to save tox showconfig] 2025-10-19 19:27:06.989151 | debian-bookworm | changed 2025-10-19 19:27:06.999103 | 2025-10-19 19:27:06.999261 | TASK [tox : Get tox envlist config] 2025-10-19 19:27:08.031443 | debian-bookworm | ok: Runtime: 0:00:00.342602 2025-10-19 19:27:08.045970 | 2025-10-19 19:27:08.046197 | TASK [tox : Install any sibling python packages] 2025-10-19 19:27:08.493445 | debian-bookworm | ok: No setup.cfg, no action needed 2025-10-19 19:27:08.502911 | 2025-10-19 19:27:08.503060 | TASK [tox : Remove tempfile] 2025-10-19 19:27:08.923179 | debian-bookworm | changed 2025-10-19 19:27:08.931332 | 2025-10-19 19:27:08.931464 | TASK [tox : Emit tox command] 2025-10-19 19:27:08.978186 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-10-19 19:27:08.985763 | 2025-10-19 19:27:08.985953 | TASK [tox : Run tox] 2025-10-19 19:27:09.607066 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-10-19 19:27:10.227914 | debian-bookworm | ............... 2025-10-19 19:27:10.227959 | debian-bookworm | ---------------------------------------------------------------------- 2025-10-19 19:27:10.227975 | debian-bookworm | Ran 15 tests in 0.175s 2025-10-19 19:27:10.227980 | debian-bookworm | 2025-10-19 19:27:10.227988 | debian-bookworm | OK 2025-10-19 19:27:10.316548 | debian-bookworm | test: OK (0.75=setup[0.04]+cmd[0.71] seconds) 2025-10-19 19:27:10.316609 | debian-bookworm | congratulations :) (0.78 seconds) 2025-10-19 19:27:10.550162 | debian-bookworm | ok: Runtime: 0:00:01.058682 2025-10-19 19:27:10.563312 | 2025-10-19 19:27:10.563465 | TASK [tox : Look for output] 2025-10-19 19:27:10.965744 | debian-bookworm | ok 2025-10-19 19:27:10.984449 | 2025-10-19 19:27:10.984578 | TASK [tox : Return file comments to Zuul] 2025-10-19 19:27:11.018505 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:27:11.024768 | 2025-10-19 19:27:11.024852 | PLAY RECAP 2025-10-19 19:27:11.024934 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-10-19 19:27:11.024960 | 2025-10-19 19:27:11.150119 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-19 19:27:11.153252 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-19 19:27:11.876643 | 2025-10-19 19:27:11.876794 | PLAY [all] 2025-10-19 19:27:11.891686 | 2025-10-19 19:27:11.891820 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-10-19 19:27:11.947330 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:27:11.960215 | 2025-10-19 19:27:11.960412 | TASK [fetch-tox-output : Set tox log path for single node] 2025-10-19 19:27:11.999641 | debian-bookworm | ok 2025-10-19 19:27:12.004763 | 2025-10-19 19:27:12.004868 | TASK [fetch-tox-output : Ensure local tox dir] 2025-10-19 19:27:12.454726 | debian-bookworm -> localhost | changed 2025-10-19 19:27:12.463169 | 2025-10-19 19:27:12.463295 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-10-19 19:27:12.518717 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:27:12.531432 | 2025-10-19 19:27:12.531603 | TASK [fetch-tox-output : Set envlist fact] 2025-10-19 19:27:12.578024 | debian-bookworm | ok 2025-10-19 19:27:12.586524 | 2025-10-19 19:27:12.586643 | TASK [fetch-tox-output : Get tox version] 2025-10-19 19:27:13.238012 | 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 19:27:13.260038 | debian-bookworm | 4.31.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-19 19:27:13.616704 | debian-bookworm | ok: Runtime: 0:00:00.296070 2025-10-19 19:27:13.629473 | 2025-10-19 19:27:13.629636 | TASK [fetch-tox-output : Find all default environments] 2025-10-19 19:27:14.161693 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:27:14.175342 | 2025-10-19 19:27:14.175527 | TASK [fetch-tox-output : Set envlist fact] 2025-10-19 19:27:14.210794 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:27:14.222735 | 2025-10-19 19:27:14.222961 | TASK [fetch-tox-output : Find all default environments] 2025-10-19 19:27:14.755852 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:27:14.768205 | 2025-10-19 19:27:14.768381 | TASK [fetch-tox-output : Set envlist fact] 2025-10-19 19:27:14.803310 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:27:14.816113 | 2025-10-19 19:27:14.816273 | LOOP [fetch-tox-output : Copy tox logs] 2025-10-19 19:27:14.864519 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:27:14.886075 | 2025-10-19 19:27:14.886237 | LOOP [fetch-tox-output : Collect tox logs] 2025-10-19 19:27:15.691604 | debian-bookworm | changed: 2025-10-19 19:27:15.691938 | debian-bookworm | created directory /var/lib/zuul/builds/673c5b2599ba4ed594e2254142993f8a/work/logs/tox/test 2025-10-19 19:27:15.692007 | debian-bookworm | cd+++++++++ ./ 2025-10-19 19:27:15.692052 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-10-19 19:27:15.692101 | 2025-10-19 19:27:15.710152 | 2025-10-19 19:27:15.710277 | PLAY RECAP 2025-10-19 19:27:15.710357 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-10-19 19:27:15.710396 | 2025-10-19 19:27:15.829629 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-19 19:27:15.832266 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-19 19:27:16.555451 | 2025-10-19 19:27:16.555617 | PLAY [all] 2025-10-19 19:27:16.571393 | 2025-10-19 19:27:16.571544 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-19 19:27:16.790154 | debian-bookworm | changed: non-zero return code 2025-10-19 19:27:16.798773 | 2025-10-19 19:27:16.799010 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-19 19:27:16.814455 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:27:16.822294 | 2025-10-19 19:27:16.822428 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-19 19:27:16.861033 | 2025-10-19 19:27:16.861306 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-19 19:27:16.909476 | 2025-10-19 19:27:16.909724 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-19 19:27:16.934289 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:27:16.949858 | 2025-10-19 19:27:16.950133 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-19 19:27:16.987474 | 2025-10-19 19:27:16.987776 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-19 19:27:17.012502 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:27:17.025863 | 2025-10-19 19:27:17.026054 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-19 19:27:17.050760 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:27:17.063261 | 2025-10-19 19:27:17.063415 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-19 19:27:17.087712 | debian-bookworm | skipping: Conditional result was False 2025-10-19 19:27:17.099037 | 2025-10-19 19:27:17.099145 | PLAY RECAP 2025-10-19 19:27:17.099224 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-19 19:27:17.099267 | 2025-10-19 19:27:17.214704 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-19 19:27:17.217418 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-19 19:27:17.950539 | 2025-10-19 19:27:17.950703 | PLAY [Base post] 2025-10-19 19:27:17.964274 | 2025-10-19 19:27:17.964401 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-19 19:27:18.528572 | debian-bookworm | changed 2025-10-19 19:27:18.537779 | 2025-10-19 19:27:18.537929 | PLAY RECAP 2025-10-19 19:27:18.538008 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-19 19:27:18.538086 | 2025-10-19 19:27:18.659187 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-19 19:27:18.661749 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-19 19:27:19.460932 | 2025-10-19 19:27:19.461100 | PLAY [Base post-logs] 2025-10-19 19:27:19.471672 | 2025-10-19 19:27:19.471812 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-19 19:27:19.906387 | localhost | changed 2025-10-19 19:27:19.916589 | 2025-10-19 19:27:19.916749 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-19 19:27:19.952378 | localhost | ok 2025-10-19 19:27:19.955496 | 2025-10-19 19:27:19.955597 | TASK [Set zuul-log-path fact] 2025-10-19 19:27:19.971164 | localhost | ok 2025-10-19 19:27:19.979027 | 2025-10-19 19:27:19.979138 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-19 19:27:20.003983 | localhost | ok 2025-10-19 19:27:20.006953 | 2025-10-19 19:27:20.007055 | TASK [upload-logs : Create log directories] 2025-10-19 19:27:20.490865 | localhost | changed 2025-10-19 19:27:20.493726 | 2025-10-19 19:27:20.493829 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-19 19:27:20.988374 | localhost -> localhost | ok: Runtime: 0:00:00.008667 2025-10-19 19:27:20.994331 | 2025-10-19 19:27:20.994478 | TASK [upload-logs : Upload logs to log server] 2025-10-19 19:27:21.583638 | localhost | Output suppressed because no_log was given 2025-10-19 19:27:21.585484 | 2025-10-19 19:27:21.585586 | LOOP [upload-logs : Compress console log and json output] 2025-10-19 19:27:21.631632 | localhost | skipping: Conditional result was False 2025-10-19 19:27:21.638264 | localhost | skipping: Conditional result was False 2025-10-19 19:27:21.641617 | 2025-10-19 19:27:21.641728 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-19 19:27:21.697625 | localhost | skipping: Conditional result was False 2025-10-19 19:27:21.698025 | 2025-10-19 19:27:21.704669 | localhost | skipping: Conditional result was False 2025-10-19 19:27:21.713910 | 2025-10-19 19:27:21.714132 | LOOP [upload-logs : Upload console log and json output]