2025-09-03 03:19:58.126267 | Job console starting 2025-09-03 03:19:58.136444 | Updating git repos 2025-09-03 03:19:58.171190 | Cloning repos into workspace 2025-09-03 03:19:58.222848 | Restoring repo states 2025-09-03 03:19:58.230977 | Merging changes 2025-09-03 03:19:58.230994 | Checking out repos 2025-09-03 03:19:58.307133 | Preparing playbooks 2025-09-03 03:19:58.878650 | Running Ansible setup 2025-09-03 03:20:03.088311 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-03 03:20:03.827804 | 2025-09-03 03:20:03.827925 | PLAY [Base pre] 2025-09-03 03:20:03.848559 | 2025-09-03 03:20:03.848697 | TASK [Setup log path fact] 2025-09-03 03:20:03.866418 | debian-bookworm | ok 2025-09-03 03:20:03.883515 | 2025-09-03 03:20:03.883678 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-03 03:20:03.920723 | debian-bookworm | ok 2025-09-03 03:20:03.938335 | 2025-09-03 03:20:03.938450 | TASK [emit-job-header : Print job information] 2025-09-03 03:20:03.971348 | # Job Information 2025-09-03 03:20:03.971576 | Ansible Version: 2.16.14 2025-09-03 03:20:03.971643 | Job: tox 2025-09-03 03:20:03.971684 | Pipeline: periodic-daily 2025-09-03 03:20:03.971711 | Executor: 521e9411259a 2025-09-03 03:20:03.971733 | Triggered by: https://github.com/osism/openstack-image-manager 2025-09-03 03:20:03.971756 | Event ID: a50f94b150224ce180fd7d5429b6ec77 2025-09-03 03:20:03.977179 | 2025-09-03 03:20:03.977284 | LOOP [emit-job-header : Print node information] 2025-09-03 03:20:04.102954 | debian-bookworm | ok: 2025-09-03 03:20:04.103775 | debian-bookworm | # Node Information 2025-09-03 03:20:04.104711 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-03 03:20:04.105363 | debian-bookworm | Hostname: debian 2025-09-03 03:20:04.105517 | debian-bookworm | Username: zuul 2025-09-03 03:20:04.105575 | debian-bookworm | Distro: Debian 12.11 2025-09-03 03:20:04.105785 | debian-bookworm | Provider: regiocloud-a 2025-09-03 03:20:04.105817 | debian-bookworm | Region: 2025-09-03 03:20:04.105843 | debian-bookworm | Label: debian-bookworm 2025-09-03 03:20:04.105866 | debian-bookworm | Product Name: OpenStack Nova 2025-09-03 03:20:04.105888 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe45:cde 2025-09-03 03:20:04.125452 | 2025-09-03 03:20:04.129318 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-03 03:20:04.653701 | debian-bookworm -> localhost | changed 2025-09-03 03:20:04.661783 | 2025-09-03 03:20:04.661908 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-03 03:20:05.696368 | debian-bookworm -> localhost | changed 2025-09-03 03:20:05.709422 | 2025-09-03 03:20:05.709575 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-03 03:20:06.027016 | debian-bookworm -> localhost | ok 2025-09-03 03:20:06.035392 | 2025-09-03 03:20:06.035523 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-03 03:20:06.067076 | debian-bookworm | ok 2025-09-03 03:20:06.085907 | debian-bookworm | included: /var/lib/zuul/builds/16dff533a7e44b579541e4f11ff91614/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-03 03:20:06.092455 | 2025-09-03 03:20:06.092566 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-03 03:20:08.441324 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-03 03:20:08.441564 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/16dff533a7e44b579541e4f11ff91614/work/16dff533a7e44b579541e4f11ff91614_id_rsa 2025-09-03 03:20:08.441728 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/16dff533a7e44b579541e4f11ff91614/work/16dff533a7e44b579541e4f11ff91614_id_rsa.pub 2025-09-03 03:20:08.441766 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-03 03:20:08.441791 | debian-bookworm -> localhost | SHA256:MM9nPvYS+QXhq6qqcDPZPqBplSGS4ftsBl9cyUPj9l8 zuul-build-sshkey 2025-09-03 03:20:08.441816 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-03 03:20:08.441851 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-03 03:20:08.441874 | debian-bookworm -> localhost | | | 2025-09-03 03:20:08.441938 | debian-bookworm -> localhost | |. o . | 2025-09-03 03:20:08.441965 | debian-bookworm -> localhost | |.o * o . . | 2025-09-03 03:20:08.441986 | debian-bookworm -> localhost | |oo . % o | 2025-09-03 03:20:08.442007 | debian-bookworm -> localhost | |. o + o S o. o | 2025-09-03 03:20:08.442032 | debian-bookworm -> localhost | | o.= o =o .E. | 2025-09-03 03:20:08.442054 | debian-bookworm -> localhost | |.o@.o =+.. | 2025-09-03 03:20:08.442075 | debian-bookworm -> localhost | |o+ X. .o+. | 2025-09-03 03:20:08.442096 | debian-bookworm -> localhost | |. +.oo.... .. | 2025-09-03 03:20:08.442117 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-03 03:20:08.442176 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.770092 2025-09-03 03:20:08.450307 | 2025-09-03 03:20:08.450440 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-03 03:20:08.508480 | debian-bookworm | ok 2025-09-03 03:20:08.531760 | debian-bookworm | included: /var/lib/zuul/builds/16dff533a7e44b579541e4f11ff91614/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-03 03:20:08.544986 | 2025-09-03 03:20:08.545115 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-03 03:20:08.571554 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:20:08.583059 | 2025-09-03 03:20:08.583249 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-03 03:20:09.354667 | debian-bookworm | changed 2025-09-03 03:20:09.371860 | 2025-09-03 03:20:09.371997 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-03 03:20:09.712700 | debian-bookworm | ok 2025-09-03 03:20:09.724810 | 2025-09-03 03:20:09.724954 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-03 03:20:10.402602 | debian-bookworm | changed 2025-09-03 03:20:10.411325 | 2025-09-03 03:20:10.411458 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-03 03:20:11.048097 | debian-bookworm | changed 2025-09-03 03:20:11.067732 | 2025-09-03 03:20:11.067923 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-03 03:20:11.092188 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:20:11.102301 | 2025-09-03 03:20:11.102425 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-03 03:20:11.637790 | debian-bookworm -> localhost | changed 2025-09-03 03:20:11.650402 | 2025-09-03 03:20:11.650600 | TASK [add-build-sshkey : Add back temp key] 2025-09-03 03:20:11.993793 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/16dff533a7e44b579541e4f11ff91614/work/16dff533a7e44b579541e4f11ff91614_id_rsa (zuul-build-sshkey) 2025-09-03 03:20:11.994042 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012133 2025-09-03 03:20:12.002600 | 2025-09-03 03:20:12.002759 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-03 03:20:12.441937 | debian-bookworm | ok 2025-09-03 03:20:12.448958 | 2025-09-03 03:20:12.449072 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-03 03:20:12.472881 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:20:12.485817 | 2025-09-03 03:20:12.485936 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-03 03:20:12.926808 | debian-bookworm | ok 2025-09-03 03:20:12.939534 | 2025-09-03 03:20:12.939690 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-03 03:20:12.979442 | debian-bookworm | ok 2025-09-03 03:20:12.985432 | 2025-09-03 03:20:12.985546 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-03 03:20:13.313813 | debian-bookworm -> localhost | ok 2025-09-03 03:20:13.321586 | 2025-09-03 03:20:13.321718 | TASK [validate-host : Collect information about the host] 2025-09-03 03:20:14.484474 | debian-bookworm | ok 2025-09-03 03:20:14.502169 | 2025-09-03 03:20:14.502308 | TASK [validate-host : Sanitize hostname] 2025-09-03 03:20:14.607043 | debian-bookworm | ok 2025-09-03 03:20:14.650597 | 2025-09-03 03:20:14.650758 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-03 03:20:15.223322 | debian-bookworm -> localhost | changed 2025-09-03 03:20:15.236658 | 2025-09-03 03:20:15.236851 | TASK [validate-host : Collect information about zuul worker] 2025-09-03 03:20:15.662243 | debian-bookworm | ok 2025-09-03 03:20:15.671389 | 2025-09-03 03:20:15.671564 | TASK [validate-host : Write out all zuul information for each host] 2025-09-03 03:20:16.327548 | debian-bookworm -> localhost | changed 2025-09-03 03:20:16.340255 | 2025-09-03 03:20:16.340388 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-03 03:20:16.629938 | debian-bookworm | ok 2025-09-03 03:20:16.635879 | 2025-09-03 03:20:16.635995 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-03 03:20:17.584451 | debian-bookworm | changed: 2025-09-03 03:20:17.584703 | debian-bookworm | cd+++++++++ src/ 2025-09-03 03:20:17.584742 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-03 03:20:17.584766 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-03 03:20:17.584788 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-09-03 03:20:17.584808 | debian-bookworm | =6.1 2025-09-03 03:20:38.843066 | debian-bookworm | Downloading cachetools-6.2.0-py3-none-any.whl (11 kB) 2025-09-03 03:20:38.869948 | debian-bookworm | Collecting chardet>=5.2 2025-09-03 03:20:38.877218 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-09-03 03:20:38.897426 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 10.7 MB/s eta 0:00:00 2025-09-03 03:20:38.927831 | debian-bookworm | Collecting colorama>=0.4.6 2025-09-03 03:20:38.935159 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-09-03 03:20:38.993047 | debian-bookworm | Collecting filelock>=3.18 2025-09-03 03:20:39.000006 | debian-bookworm | Downloading filelock-3.19.1-py3-none-any.whl (15 kB) 2025-09-03 03:20:39.042260 | debian-bookworm | Collecting packaging>=25 2025-09-03 03:20:39.049335 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-09-03 03:20:39.059160 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 10.6 MB/s eta 0:00:00 2025-09-03 03:20:39.118142 | debian-bookworm | Collecting platformdirs>=4.3.8 2025-09-03 03:20:39.125922 | debian-bookworm | Downloading platformdirs-4.4.0-py3-none-any.whl (18 kB) 2025-09-03 03:20:39.169403 | debian-bookworm | Collecting pluggy>=1.6 2025-09-03 03:20:39.176836 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-09-03 03:20:39.210838 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-09-03 03:20:39.219116 | debian-bookworm | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2025-09-03 03:20:39.336234 | debian-bookworm | Collecting virtualenv>=20.31.2 2025-09-03 03:20:39.343739 | debian-bookworm | Downloading virtualenv-20.34.0-py3-none-any.whl (6.0 MB) 2025-09-03 03:20:39.624516 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 21.5 MB/s eta 0:00:00 2025-09-03 03:20:39.728560 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-09-03 03:20:39.737494 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-09-03 03:20:39.760890 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 23.9 MB/s eta 0:00:00 2025-09-03 03:20:39.896813 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-09-03 03:20:40.695976 | 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.29.0 virtualenv-20.34.0 2025-09-03 03:20:40.923065 | debian-bookworm | ok: Runtime: 0:00:02.657289 2025-09-03 03:20:40.934193 | 2025-09-03 03:20:40.934370 | TASK [ensure-tox : Export installed tox_executable path] 2025-09-03 03:20:40.976295 | debian-bookworm | ok 2025-09-03 03:20:40.981213 | 2025-09-03 03:20:40.981372 | TASK [ensure-tox : Output tox version] 2025-09-03 03:20:41.507620 | debian-bookworm | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-09-03 03:20:41.525746 | debian-bookworm | 4.29.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-03 03:20:42.010641 | debian-bookworm | ok: Runtime: 0:00:00.298216 2025-09-03 03:20:42.016645 | 2025-09-03 03:20:42.016775 | TASK [ensure-tox : Make global symlink] 2025-09-03 03:20:42.040891 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:20:42.047381 | 2025-09-03 03:20:42.047463 | PLAY RECAP 2025-09-03 03:20:42.047516 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-09-03 03:20:42.047540 | 2025-09-03 03:20:42.182490 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-09-03 03:20:42.183769 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-03 03:20:43.098919 | 2025-09-03 03:20:43.099087 | PLAY [all] 2025-09-03 03:20:43.115533 | 2025-09-03 03:20:43.115731 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-09-03 03:20:43.576556 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-03 03:20:43.647059 | debian-bookworm | ok: Runtime: 0:00:00.029745 2025-09-03 03:20:43.652887 | 2025-09-03 03:20:43.653023 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-09-03 03:20:44.213334 | debian-bookworm | ok: Runtime: 0:00:00.007227 2025-09-03 03:20:44.223613 | 2025-09-03 03:20:44.223848 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-09-03 03:20:44.526767 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-03 03:20:44.531118 | debian-bookworm | sudo: a password is required 2025-09-03 03:20:44.759047 | debian-bookworm | ok: Runtime: 0:00:00.024346 2025-09-03 03:20:44.781628 | 2025-09-03 03:20:44.781818 | TASK [tox : Check to see if the constraints file exists] 2025-09-03 03:20:44.807952 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:20:44.814495 | 2025-09-03 03:20:44.814611 | TASK [tox : Fail if constraints file is missing] 2025-09-03 03:20:44.858934 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:20:44.872249 | 2025-09-03 03:20:44.872433 | TASK [tox : Record file location] 2025-09-03 03:20:44.899453 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:20:44.913403 | 2025-09-03 03:20:44.913610 | TASK [tox : Install tox siblings] 2025-09-03 03:20:44.938069 | debian-bookworm | ok 2025-09-03 03:20:44.947077 | debian-bookworm | included: /var/lib/zuul/builds/16dff533a7e44b579541e4f11ff91614/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-09-03 03:20:44.953879 | 2025-09-03 03:20:44.954005 | TASK [tox : Run tox without tests] 2025-09-03 03:20:46.111330 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-09-03 03:20:58.895069 | debian-bookworm | test: OK (13.42 seconds) 2025-09-03 03:20:58.895155 | debian-bookworm | congratulations :) (13.45 seconds) 2025-09-03 03:20:58.992632 | debian-bookworm | ok: Runtime: 0:00:13.721328 2025-09-03 03:20:58.998351 | 2025-09-03 03:20:58.998459 | TASK [tox : Create a tempfile to save tox showconfig] 2025-09-03 03:20:59.411889 | debian-bookworm | changed 2025-09-03 03:20:59.417758 | 2025-09-03 03:20:59.417879 | TASK [tox : Get tox envlist config] 2025-09-03 03:21:00.447381 | debian-bookworm | ok: Runtime: 0:00:00.332830 2025-09-03 03:21:00.453644 | 2025-09-03 03:21:00.453780 | TASK [tox : Install any sibling python packages] 2025-09-03 03:21:00.941489 | debian-bookworm | ok: No setup.cfg, no action needed 2025-09-03 03:21:00.947407 | 2025-09-03 03:21:00.947521 | TASK [tox : Remove tempfile] 2025-09-03 03:21:01.414156 | debian-bookworm | changed 2025-09-03 03:21:01.422416 | 2025-09-03 03:21:01.422548 | TASK [tox : Emit tox command] 2025-09-03 03:21:01.462245 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-09-03 03:21:01.467546 | 2025-09-03 03:21:01.467689 | TASK [tox : Run tox] 2025-09-03 03:21:02.104727 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-09-03 03:21:02.755442 | debian-bookworm | ............... 2025-09-03 03:21:02.755493 | debian-bookworm | ---------------------------------------------------------------------- 2025-09-03 03:21:02.756098 | debian-bookworm | Ran 15 tests in 0.176s 2025-09-03 03:21:02.756115 | debian-bookworm | 2025-09-03 03:21:02.756123 | debian-bookworm | OK 2025-09-03 03:21:02.852513 | debian-bookworm | test: OK (0.79=setup[0.05]+cmd[0.74] seconds) 2025-09-03 03:21:02.852614 | debian-bookworm | congratulations :) (0.82 seconds) 2025-09-03 03:21:03.039376 | debian-bookworm | ok: Runtime: 0:00:01.111100 2025-09-03 03:21:03.050987 | 2025-09-03 03:21:03.051135 | TASK [tox : Look for output] 2025-09-03 03:21:03.447011 | debian-bookworm | ok 2025-09-03 03:21:03.461526 | 2025-09-03 03:21:03.461672 | TASK [tox : Return file comments to Zuul] 2025-09-03 03:21:03.495947 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:21:03.502650 | 2025-09-03 03:21:03.502776 | PLAY RECAP 2025-09-03 03:21:03.502885 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-09-03 03:21:03.502915 | 2025-09-03 03:21:03.639370 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-03 03:21:03.642285 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-03 03:21:04.390950 | 2025-09-03 03:21:04.391116 | PLAY [all] 2025-09-03 03:21:04.406233 | 2025-09-03 03:21:04.406379 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-09-03 03:21:04.451069 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:21:04.456913 | 2025-09-03 03:21:04.457053 | TASK [fetch-tox-output : Set tox log path for single node] 2025-09-03 03:21:04.486371 | debian-bookworm | ok 2025-09-03 03:21:04.491829 | 2025-09-03 03:21:04.491956 | TASK [fetch-tox-output : Ensure local tox dir] 2025-09-03 03:21:04.973955 | debian-bookworm -> localhost | changed 2025-09-03 03:21:04.990973 | 2025-09-03 03:21:04.991147 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-09-03 03:21:05.045600 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:21:05.051396 | 2025-09-03 03:21:05.051515 | TASK [fetch-tox-output : Set envlist fact] 2025-09-03 03:21:05.089932 | debian-bookworm | ok 2025-09-03 03:21:05.102619 | 2025-09-03 03:21:05.102783 | TASK [fetch-tox-output : Get tox version] 2025-09-03 03:21:05.749542 | debian-bookworm | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-09-03 03:21:05.767082 | debian-bookworm | 4.29.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-03 03:21:06.137067 | debian-bookworm | ok: Runtime: 0:00:00.238902 2025-09-03 03:21:06.145354 | 2025-09-03 03:21:06.145471 | TASK [fetch-tox-output : Find all default environments] 2025-09-03 03:21:06.686340 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:21:06.698258 | 2025-09-03 03:21:06.698435 | TASK [fetch-tox-output : Set envlist fact] 2025-09-03 03:21:06.734111 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:21:06.740939 | 2025-09-03 03:21:06.741154 | TASK [fetch-tox-output : Find all default environments] 2025-09-03 03:21:07.269355 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:21:07.275785 | 2025-09-03 03:21:07.275915 | TASK [fetch-tox-output : Set envlist fact] 2025-09-03 03:21:07.299904 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:21:07.306449 | 2025-09-03 03:21:07.306575 | LOOP [fetch-tox-output : Copy tox logs] 2025-09-03 03:21:07.350335 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:21:07.368395 | 2025-09-03 03:21:07.368547 | LOOP [fetch-tox-output : Collect tox logs] 2025-09-03 03:21:08.184944 | debian-bookworm | changed: 2025-09-03 03:21:08.185217 | debian-bookworm | created directory /var/lib/zuul/builds/16dff533a7e44b579541e4f11ff91614/work/logs/tox/test 2025-09-03 03:21:08.185276 | debian-bookworm | cd+++++++++ ./ 2025-09-03 03:21:08.185318 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-09-03 03:21:08.185366 | 2025-09-03 03:21:08.198754 | 2025-09-03 03:21:08.198881 | PLAY RECAP 2025-09-03 03:21:08.198937 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-09-03 03:21:08.198962 | 2025-09-03 03:21:08.329117 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-03 03:21:08.331820 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-03 03:21:09.102224 | 2025-09-03 03:21:09.102534 | PLAY [all] 2025-09-03 03:21:09.133298 | 2025-09-03 03:21:09.133548 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-09-03 03:21:09.366312 | debian-bookworm | changed: non-zero return code 2025-09-03 03:21:09.375234 | 2025-09-03 03:21:09.375388 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-09-03 03:21:09.401226 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:21:09.411299 | 2025-09-03 03:21:09.411425 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-09-03 03:21:09.443840 | 2025-09-03 03:21:09.444303 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-09-03 03:21:09.477177 | 2025-09-03 03:21:09.477454 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-09-03 03:21:09.501488 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:21:09.508734 | 2025-09-03 03:21:09.508860 | LOOP [fetch-subunit-output : Generate subunit file] 2025-09-03 03:21:09.539896 | 2025-09-03 03:21:09.540109 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-09-03 03:21:09.554344 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:21:09.562922 | 2025-09-03 03:21:09.563074 | TASK [fetch-subunit-output : Remove the temporary file] 2025-09-03 03:21:09.587541 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:21:09.593748 | 2025-09-03 03:21:09.593864 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-09-03 03:21:09.618912 | debian-bookworm | skipping: Conditional result was False 2025-09-03 03:21:09.628495 | 2025-09-03 03:21:09.628590 | PLAY RECAP 2025-09-03 03:21:09.628643 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-03 03:21:09.628668 | 2025-09-03 03:21:09.763109 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-03 03:21:09.764238 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-03 03:21:10.494772 | 2025-09-03 03:21:10.494934 | PLAY [Base post] 2025-09-03 03:21:10.508408 | 2025-09-03 03:21:10.508522 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-03 03:21:11.035525 | debian-bookworm | changed 2025-09-03 03:21:11.045223 | 2025-09-03 03:21:11.045329 | PLAY RECAP 2025-09-03 03:21:11.045400 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-03 03:21:11.045475 | 2025-09-03 03:21:11.142492 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-03 03:21:11.145144 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-03 03:21:11.930227 | 2025-09-03 03:21:11.930359 | PLAY [Base post-logs] 2025-09-03 03:21:11.939654 | 2025-09-03 03:21:11.939789 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-03 03:21:12.376094 | localhost | changed 2025-09-03 03:21:12.400938 | 2025-09-03 03:21:12.401207 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-03 03:21:12.428194 | localhost | ok 2025-09-03 03:21:12.431278 | 2025-09-03 03:21:12.431428 | TASK [Set zuul-log-path fact] 2025-09-03 03:21:12.445917 | localhost | ok 2025-09-03 03:21:12.453570 | 2025-09-03 03:21:12.453674 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-03 03:21:12.477903 | localhost | ok 2025-09-03 03:21:12.480839 | 2025-09-03 03:21:12.480946 | TASK [upload-logs : Create log directories] 2025-09-03 03:21:12.973356 | localhost | changed 2025-09-03 03:21:12.976236 | 2025-09-03 03:21:12.976344 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-03 03:21:13.466225 | localhost -> localhost | ok: Runtime: 0:00:00.007414 2025-09-03 03:21:13.470294 | 2025-09-03 03:21:13.470403 | TASK [upload-logs : Upload logs to log server] 2025-09-03 03:21:14.044803 | localhost | Output suppressed because no_log was given 2025-09-03 03:21:14.046572 | 2025-09-03 03:21:14.046662 | LOOP [upload-logs : Compress console log and json output] 2025-09-03 03:21:14.086978 | localhost | skipping: Conditional result was False 2025-09-03 03:21:14.094114 | localhost | skipping: Conditional result was False 2025-09-03 03:21:14.106002 | 2025-09-03 03:21:14.106231 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-03 03:21:14.159043 | localhost | skipping: Conditional result was False 2025-09-03 03:21:14.159574 | 2025-09-03 03:21:14.163458 | localhost | skipping: Conditional result was False 2025-09-03 03:21:14.178280 | 2025-09-03 03:21:14.178879 | LOOP [upload-logs : Upload console log and json output]