2025-12-20 00:17:47.072735 | Job console starting 2025-12-20 00:17:47.104630 | Updating git repos 2025-12-20 00:17:47.153496 | Cloning repos into workspace 2025-12-20 00:17:47.230004 | Restoring repo states 2025-12-20 00:17:47.251301 | Merging changes 2025-12-20 00:17:47.879612 | Checking out repos 2025-12-20 00:17:47.922621 | Preparing playbooks 2025-12-20 00:17:48.813022 | Running Ansible setup 2025-12-20 00:17:54.539524 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-20 00:17:55.592856 | 2025-12-20 00:17:55.593084 | PLAY [Base pre] 2025-12-20 00:17:55.655666 | 2025-12-20 00:17:55.657711 | TASK [Setup log path fact] 2025-12-20 00:17:55.716829 | debian-bookworm | ok 2025-12-20 00:17:55.777750 | 2025-12-20 00:17:55.777938 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-20 00:17:55.848692 | debian-bookworm | ok 2025-12-20 00:17:55.868590 | 2025-12-20 00:17:55.868729 | TASK [emit-job-header : Print job information] 2025-12-20 00:17:55.995967 | # Job Information 2025-12-20 00:17:55.996186 | Ansible Version: 2.16.14 2025-12-20 00:17:55.996224 | Job: tox 2025-12-20 00:17:55.996257 | Pipeline: check 2025-12-20 00:17:55.996280 | Executor: 521e9411259a 2025-12-20 00:17:55.996301 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1046 2025-12-20 00:17:55.996348 | Event ID: 49b70190-dd39-11f0-858a-2612dbc6cd71 2025-12-20 00:17:56.001573 | 2025-12-20 00:17:56.001704 | LOOP [emit-job-header : Print node information] 2025-12-20 00:17:56.435188 | debian-bookworm | ok: 2025-12-20 00:17:56.435489 | debian-bookworm | # Node Information 2025-12-20 00:17:56.435569 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-12-20 00:17:56.435597 | debian-bookworm | Hostname: debian 2025-12-20 00:17:56.435619 | debian-bookworm | Username: zuul 2025-12-20 00:17:56.435640 | debian-bookworm | Distro: Debian 12.12 2025-12-20 00:17:56.435664 | debian-bookworm | Provider: regiocloud-a 2025-12-20 00:17:56.435684 | debian-bookworm | Region: 2025-12-20 00:17:56.435705 | debian-bookworm | Label: debian-bookworm 2025-12-20 00:17:56.435725 | debian-bookworm | Product Name: OpenStack Nova 2025-12-20 00:17:56.435744 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe4d:ba1 2025-12-20 00:17:56.459580 | 2025-12-20 00:17:56.459740 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-20 00:17:57.793986 | debian-bookworm -> localhost | changed 2025-12-20 00:17:57.804934 | 2025-12-20 00:17:57.805084 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-20 00:18:00.148910 | debian-bookworm -> localhost | changed 2025-12-20 00:18:00.168491 | 2025-12-20 00:18:00.168807 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-20 00:18:00.884690 | debian-bookworm -> localhost | ok 2025-12-20 00:18:00.891329 | 2025-12-20 00:18:00.891482 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-20 00:18:00.940796 | debian-bookworm | ok 2025-12-20 00:18:00.975076 | debian-bookworm | included: /var/lib/zuul/builds/353c06e7ee2f44348c9d40341a2046a9/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-20 00:18:00.981604 | 2025-12-20 00:18:00.981735 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-20 00:18:05.765799 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-12-20 00:18:05.766196 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/353c06e7ee2f44348c9d40341a2046a9/work/353c06e7ee2f44348c9d40341a2046a9_id_rsa 2025-12-20 00:18:05.766846 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/353c06e7ee2f44348c9d40341a2046a9/work/353c06e7ee2f44348c9d40341a2046a9_id_rsa.pub 2025-12-20 00:18:05.766903 | debian-bookworm -> localhost | The key fingerprint is: 2025-12-20 00:18:05.766934 | debian-bookworm -> localhost | SHA256:sCF9465i/Im+At6/s67W6bdxO4vpeinyZ8OmzbL6wvA zuul-build-sshkey 2025-12-20 00:18:05.766958 | debian-bookworm -> localhost | The key's randomart image is: 2025-12-20 00:18:05.766993 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-12-20 00:18:05.767015 | debian-bookworm -> localhost | | | 2025-12-20 00:18:05.767038 | debian-bookworm -> localhost | | . | 2025-12-20 00:18:05.767059 | debian-bookworm -> localhost | | . + o | 2025-12-20 00:18:05.767078 | debian-bookworm -> localhost | | . * . | 2025-12-20 00:18:05.767098 | debian-bookworm -> localhost | | . S | 2025-12-20 00:18:05.767127 | debian-bookworm -> localhost | |.. . | 2025-12-20 00:18:05.767147 | debian-bookworm -> localhost | |..=.. o.o. | 2025-12-20 00:18:05.767166 | debian-bookworm -> localhost | | ..E+Bo%=.. | 2025-12-20 00:18:05.767221 | debian-bookworm -> localhost | | .*#/^Booo | 2025-12-20 00:18:05.767248 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-12-20 00:18:05.767333 | debian-bookworm -> localhost | ok: Runtime: 0:00:03.476849 2025-12-20 00:18:05.777206 | 2025-12-20 00:18:05.777362 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-20 00:18:05.838102 | debian-bookworm | ok 2025-12-20 00:18:05.866389 | debian-bookworm | included: /var/lib/zuul/builds/353c06e7ee2f44348c9d40341a2046a9/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-20 00:18:05.887555 | 2025-12-20 00:18:05.887941 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-20 00:18:05.918470 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:18:05.929615 | 2025-12-20 00:18:05.930149 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-20 00:18:06.902291 | debian-bookworm | changed 2025-12-20 00:18:06.907963 | 2025-12-20 00:18:06.908079 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-20 00:18:07.258310 | debian-bookworm | ok 2025-12-20 00:18:07.268220 | 2025-12-20 00:18:07.268388 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-20 00:18:08.085886 | debian-bookworm | changed 2025-12-20 00:18:08.106069 | 2025-12-20 00:18:08.106230 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-20 00:18:08.931838 | debian-bookworm | changed 2025-12-20 00:18:08.940208 | 2025-12-20 00:18:08.940391 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-20 00:18:08.969076 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:18:08.975875 | 2025-12-20 00:18:08.976003 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-20 00:18:09.701289 | debian-bookworm -> localhost | changed 2025-12-20 00:18:09.719670 | 2025-12-20 00:18:09.719820 | TASK [add-build-sshkey : Add back temp key] 2025-12-20 00:18:10.621127 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/353c06e7ee2f44348c9d40341a2046a9/work/353c06e7ee2f44348c9d40341a2046a9_id_rsa (zuul-build-sshkey) 2025-12-20 00:18:10.621412 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.048638 2025-12-20 00:18:10.630205 | 2025-12-20 00:18:10.630385 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-20 00:18:11.551375 | debian-bookworm | ok 2025-12-20 00:18:11.564792 | 2025-12-20 00:18:11.564943 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-20 00:18:11.660960 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:18:11.676794 | 2025-12-20 00:18:11.676954 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-20 00:18:12.664587 | debian-bookworm | ok 2025-12-20 00:18:12.683289 | 2025-12-20 00:18:12.683492 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-20 00:18:12.726719 | debian-bookworm | ok 2025-12-20 00:18:12.732676 | 2025-12-20 00:18:12.732853 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-20 00:18:13.139852 | debian-bookworm -> localhost | ok 2025-12-20 00:18:13.150664 | 2025-12-20 00:18:13.150801 | TASK [validate-host : Collect information about the host] 2025-12-20 00:18:15.176515 | debian-bookworm | ok 2025-12-20 00:18:15.211794 | 2025-12-20 00:18:15.211954 | TASK [validate-host : Sanitize hostname] 2025-12-20 00:18:15.545659 | debian-bookworm | ok 2025-12-20 00:18:15.690141 | 2025-12-20 00:18:15.690294 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-20 00:18:17.880483 | debian-bookworm -> localhost | changed 2025-12-20 00:18:17.887453 | 2025-12-20 00:18:17.887580 | TASK [validate-host : Collect information about zuul worker] 2025-12-20 00:18:18.575651 | debian-bookworm | ok 2025-12-20 00:18:18.593266 | 2025-12-20 00:18:18.593444 | TASK [validate-host : Write out all zuul information for each host] 2025-12-20 00:18:20.330700 | debian-bookworm -> localhost | changed 2025-12-20 00:18:20.360507 | 2025-12-20 00:18:20.360654 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-20 00:18:20.706355 | debian-bookworm | ok 2025-12-20 00:18:20.727524 | 2025-12-20 00:18:20.727898 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-20 00:18:22.977819 | debian-bookworm | changed: 2025-12-20 00:18:22.978131 | debian-bookworm | cd+++++++++ src/ 2025-12-20 00:18:22.978172 | debian-bookworm | cd+++++++++ src/github.com/ 2025-12-20 00:18:22.978198 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-12-20 00:18:22.978220 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-12-20 00:18:22.978241 | debian-bookworm | =6.2 2025-12-20 00:18:49.889582 | debian-bookworm | Downloading cachetools-6.2.4-py3-none-any.whl (11 kB) 2025-12-20 00:18:49.920812 | debian-bookworm | Collecting chardet>=5.2 2025-12-20 00:18:49.942535 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-12-20 00:18:49.952670 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 26.7 MB/s eta 0:00:00 2025-12-20 00:18:49.991169 | debian-bookworm | Collecting colorama>=0.4.6 2025-12-20 00:18:50.002946 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-12-20 00:18:50.059504 | debian-bookworm | Collecting filelock>=3.20 2025-12-20 00:18:50.071124 | debian-bookworm | Downloading filelock-3.20.1-py3-none-any.whl (16 kB) 2025-12-20 00:18:50.116707 | debian-bookworm | Collecting packaging>=25 2025-12-20 00:18:50.128240 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-12-20 00:18:50.220912 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 1.2 MB/s eta 0:00:00 2025-12-20 00:18:50.284037 | debian-bookworm | Collecting platformdirs>=4.5 2025-12-20 00:18:50.296213 | debian-bookworm | Downloading platformdirs-4.5.1-py3-none-any.whl (18 kB) 2025-12-20 00:18:50.333613 | debian-bookworm | Collecting pluggy>=1.6 2025-12-20 00:18:50.348580 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-12-20 00:18:50.512658 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-12-20 00:18:50.526958 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-12-20 00:18:50.678331 | debian-bookworm | Collecting virtualenv>=20.34 2025-12-20 00:18:50.692038 | debian-bookworm | Downloading virtualenv-20.35.4-py3-none-any.whl (6.0 MB) 2025-12-20 00:18:50.968804 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 22.1 MB/s eta 0:00:00 2025-12-20 00:18:51.079286 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-12-20 00:18:51.091108 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-12-20 00:18:51.115456 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 21.3 MB/s eta 0:00:00 2025-12-20 00:18:51.248297 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-12-20 00:18:51.999525 | debian-bookworm | Successfully installed cachetools-6.2.4 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.20.1 packaging-25.0 platformdirs-4.5.1 pluggy-1.6.0 pyproject-api-1.10.0 tox-4.32.0 virtualenv-20.35.4 2025-12-20 00:18:52.263888 | debian-bookworm | ok: Runtime: 0:00:03.123336 2025-12-20 00:18:52.280127 | 2025-12-20 00:18:52.280377 | TASK [ensure-tox : Export installed tox_executable path] 2025-12-20 00:18:52.323365 | debian-bookworm | ok 2025-12-20 00:18:52.329136 | 2025-12-20 00:18:52.329520 | TASK [ensure-tox : Output tox version] 2025-12-20 00:18:52.886216 | 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-12-20 00:18:52.905199 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-12-20 00:18:53.366268 | debian-bookworm | ok: Runtime: 0:00:00.308564 2025-12-20 00:18:53.372978 | 2025-12-20 00:18:53.373092 | TASK [ensure-tox : Make global symlink] 2025-12-20 00:18:53.421230 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:18:53.430191 | 2025-12-20 00:18:53.430336 | PLAY RECAP 2025-12-20 00:18:53.430403 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-12-20 00:18:53.430430 | 2025-12-20 00:18:53.656163 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-12-20 00:18:53.657336 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-12-20 00:18:54.563691 | 2025-12-20 00:18:54.563887 | PLAY [all] 2025-12-20 00:18:54.581168 | 2025-12-20 00:18:54.581358 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-12-20 00:18:55.224518 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-12-20 00:18:55.614040 | debian-bookworm | ok: Runtime: 0:00:00.030075 2025-12-20 00:18:55.620066 | 2025-12-20 00:18:55.620206 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-12-20 00:18:56.174310 | debian-bookworm | ok: Runtime: 0:00:00.006966 2025-12-20 00:18:56.185583 | 2025-12-20 00:18:56.185738 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-12-20 00:18:56.546658 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-12-20 00:18:56.548249 | debian-bookworm | sudo: a password is required 2025-12-20 00:18:56.731870 | debian-bookworm | ok: Runtime: 0:00:00.022648 2025-12-20 00:18:56.762155 | 2025-12-20 00:18:56.762720 | TASK [tox : Check to see if the constraints file exists] 2025-12-20 00:18:56.859304 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:18:56.897934 | 2025-12-20 00:18:56.898097 | TASK [tox : Fail if constraints file is missing] 2025-12-20 00:18:56.956716 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:18:56.978996 | 2025-12-20 00:18:56.979660 | TASK [tox : Record file location] 2025-12-20 00:18:57.029825 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:18:57.041950 | 2025-12-20 00:18:57.042109 | TASK [tox : Install tox siblings] 2025-12-20 00:18:57.098469 | debian-bookworm | ok 2025-12-20 00:18:57.119134 | debian-bookworm | included: /var/lib/zuul/builds/353c06e7ee2f44348c9d40341a2046a9/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-12-20 00:18:57.130949 | 2025-12-20 00:18:57.131100 | TASK [tox : Run tox without tests] 2025-12-20 00:18:58.435046 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-12-20 00:19:12.095711 | debian-bookworm | test: OK (14.25 seconds) 2025-12-20 00:19:12.095763 | debian-bookworm | congratulations :) (14.28 seconds) 2025-12-20 00:19:12.231955 | debian-bookworm | ok: Runtime: 0:00:14.572009 2025-12-20 00:19:12.246472 | 2025-12-20 00:19:12.246636 | TASK [tox : Create a tempfile to save tox showconfig] 2025-12-20 00:19:12.633902 | debian-bookworm | changed 2025-12-20 00:19:12.640108 | 2025-12-20 00:19:12.640249 | TASK [tox : Get tox envlist config] 2025-12-20 00:19:13.671066 | debian-bookworm | ok: Runtime: 0:00:00.307162 2025-12-20 00:19:13.677079 | 2025-12-20 00:19:13.677202 | TASK [tox : Install any sibling python packages] 2025-12-20 00:19:14.170589 | debian-bookworm | ok: No setup.cfg, no action needed 2025-12-20 00:19:14.178182 | 2025-12-20 00:19:14.178363 | TASK [tox : Remove tempfile] 2025-12-20 00:19:14.600189 | debian-bookworm | changed 2025-12-20 00:19:14.606533 | 2025-12-20 00:19:14.606671 | TASK [tox : Emit tox command] 2025-12-20 00:19:14.657841 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-12-20 00:19:14.663323 | 2025-12-20 00:19:14.663466 | TASK [tox : Run tox] 2025-12-20 00:19:15.346063 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-12-20 00:19:16.071422 | debian-bookworm | ............... 2025-12-20 00:19:16.071508 | debian-bookworm | ---------------------------------------------------------------------- 2025-12-20 00:19:16.071526 | debian-bookworm | Ran 15 tests in 0.195s 2025-12-20 00:19:16.071531 | debian-bookworm | 2025-12-20 00:19:16.071539 | debian-bookworm | OK 2025-12-20 00:19:16.156802 | debian-bookworm | test: OK (0.85=setup[0.05]+cmd[0.81] seconds) 2025-12-20 00:19:16.156927 | debian-bookworm | congratulations :) (0.88 seconds) 2025-12-20 00:19:16.261091 | debian-bookworm | ok: Runtime: 0:00:01.183941 2025-12-20 00:19:16.269084 | 2025-12-20 00:19:16.269221 | TASK [tox : Look for output] 2025-12-20 00:19:16.724225 | debian-bookworm | ok 2025-12-20 00:19:16.746144 | 2025-12-20 00:19:16.746384 | TASK [tox : Return file comments to Zuul] 2025-12-20 00:19:16.785210 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:19:16.793827 | 2025-12-20 00:19:16.793932 | PLAY RECAP 2025-12-20 00:19:16.793999 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-12-20 00:19:16.794030 | 2025-12-20 00:19:16.934251 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-12-20 00:19:16.937544 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-12-20 00:19:17.761549 | 2025-12-20 00:19:17.761732 | PLAY [all] 2025-12-20 00:19:17.778244 | 2025-12-20 00:19:17.778435 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-12-20 00:19:17.846290 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:19:17.853986 | 2025-12-20 00:19:17.854189 | TASK [fetch-tox-output : Set tox log path for single node] 2025-12-20 00:19:17.915853 | debian-bookworm | ok 2025-12-20 00:19:17.922213 | 2025-12-20 00:19:17.922409 | TASK [fetch-tox-output : Ensure local tox dir] 2025-12-20 00:19:18.482853 | debian-bookworm -> localhost | changed 2025-12-20 00:19:18.496196 | 2025-12-20 00:19:18.496412 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-12-20 00:19:18.577400 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:19:18.586390 | 2025-12-20 00:19:18.586576 | TASK [fetch-tox-output : Set envlist fact] 2025-12-20 00:19:18.639365 | debian-bookworm | ok 2025-12-20 00:19:18.648524 | 2025-12-20 00:19:18.648739 | TASK [fetch-tox-output : Get tox version] 2025-12-20 00:19:19.371816 | 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-12-20 00:19:19.391003 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-12-20 00:19:19.684946 | debian-bookworm | ok: Runtime: 0:00:00.305942 2025-12-20 00:19:19.696489 | 2025-12-20 00:19:19.696677 | TASK [fetch-tox-output : Find all default environments] 2025-12-20 00:19:20.246674 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:19:20.261582 | 2025-12-20 00:19:20.261796 | TASK [fetch-tox-output : Set envlist fact] 2025-12-20 00:19:20.299044 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:19:20.313032 | 2025-12-20 00:19:20.313247 | TASK [fetch-tox-output : Find all default environments] 2025-12-20 00:19:20.849208 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:19:20.869300 | 2025-12-20 00:19:20.869483 | TASK [fetch-tox-output : Set envlist fact] 2025-12-20 00:19:20.895830 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:19:20.903599 | 2025-12-20 00:19:20.904181 | LOOP [fetch-tox-output : Copy tox logs] 2025-12-20 00:19:20.952816 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:19:20.973692 | 2025-12-20 00:19:20.973886 | LOOP [fetch-tox-output : Collect tox logs] 2025-12-20 00:19:21.877435 | debian-bookworm | changed: 2025-12-20 00:19:21.877739 | debian-bookworm | created directory /var/lib/zuul/builds/353c06e7ee2f44348c9d40341a2046a9/work/logs/tox/test 2025-12-20 00:19:21.877807 | debian-bookworm | cd+++++++++ ./ 2025-12-20 00:19:21.877859 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-12-20 00:19:21.877915 | 2025-12-20 00:19:21.897802 | 2025-12-20 00:19:21.897967 | PLAY RECAP 2025-12-20 00:19:21.898057 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-12-20 00:19:21.898100 | 2025-12-20 00:19:22.069268 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-12-20 00:19:22.071933 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-20 00:19:22.878784 | 2025-12-20 00:19:22.878998 | PLAY [all] 2025-12-20 00:19:22.900446 | 2025-12-20 00:19:22.900617 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-12-20 00:19:23.100181 | debian-bookworm | changed: non-zero return code 2025-12-20 00:19:23.110373 | 2025-12-20 00:19:23.110616 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-12-20 00:19:23.137423 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:19:23.143830 | 2025-12-20 00:19:23.143976 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-12-20 00:19:23.186067 | 2025-12-20 00:19:23.186327 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-12-20 00:19:23.228864 | 2025-12-20 00:19:23.229736 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-12-20 00:19:23.253745 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:19:23.261042 | 2025-12-20 00:19:23.261240 | LOOP [fetch-subunit-output : Generate subunit file] 2025-12-20 00:19:23.292750 | 2025-12-20 00:19:23.292994 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-12-20 00:19:23.318468 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:19:23.334761 | 2025-12-20 00:19:23.335022 | TASK [fetch-subunit-output : Remove the temporary file] 2025-12-20 00:19:23.363029 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:19:23.378465 | 2025-12-20 00:19:23.378736 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-12-20 00:19:23.405600 | debian-bookworm | skipping: Conditional result was False 2025-12-20 00:19:23.421645 | 2025-12-20 00:19:23.421776 | PLAY RECAP 2025-12-20 00:19:23.421853 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-12-20 00:19:23.421894 | 2025-12-20 00:19:23.578414 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-20 00:19:23.581967 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-20 00:19:24.480505 | 2025-12-20 00:19:24.480691 | PLAY [Base post] 2025-12-20 00:19:24.499930 | 2025-12-20 00:19:24.500097 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-12-20 00:19:25.166966 | debian-bookworm | changed 2025-12-20 00:19:25.174456 | 2025-12-20 00:19:25.174593 | PLAY RECAP 2025-12-20 00:19:25.174664 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-20 00:19:25.174745 | 2025-12-20 00:19:25.305260 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-20 00:19:25.308062 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-12-20 00:19:26.166065 | 2025-12-20 00:19:26.166237 | PLAY [Base post-logs] 2025-12-20 00:19:26.177249 | 2025-12-20 00:19:26.177448 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-20 00:19:26.673879 | localhost | changed 2025-12-20 00:19:26.689592 | 2025-12-20 00:19:26.689811 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-20 00:19:26.730035 | localhost | ok 2025-12-20 00:19:26.738219 | 2025-12-20 00:19:26.738425 | TASK [Set zuul-log-path fact] 2025-12-20 00:19:26.766523 | localhost | ok 2025-12-20 00:19:26.776752 | 2025-12-20 00:19:26.776980 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-20 00:19:26.805039 | localhost | ok 2025-12-20 00:19:26.812369 | 2025-12-20 00:19:26.812542 | TASK [upload-logs : Create log directories] 2025-12-20 00:19:27.361733 | localhost | changed 2025-12-20 00:19:27.364714 | 2025-12-20 00:19:27.364819 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-12-20 00:19:27.909255 | localhost -> localhost | ok: Runtime: 0:00:00.007553 2025-12-20 00:19:27.916519 | 2025-12-20 00:19:27.916680 | TASK [upload-logs : Upload logs to log server] 2025-12-20 00:19:28.509999 | localhost | Output suppressed because no_log was given 2025-12-20 00:19:28.513996 | 2025-12-20 00:19:28.514193 | LOOP [upload-logs : Compress console log and json output] 2025-12-20 00:19:28.580809 | localhost | skipping: Conditional result was False 2025-12-20 00:19:28.587743 | localhost | skipping: Conditional result was False 2025-12-20 00:19:28.594568 | 2025-12-20 00:19:28.594685 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-20 00:19:28.664628 | localhost | skipping: Conditional result was False 2025-12-20 00:19:28.665270 | 2025-12-20 00:19:28.669542 | localhost | skipping: Conditional result was False 2025-12-20 00:19:28.684721 | 2025-12-20 00:19:28.684970 | LOOP [upload-logs : Upload console log and json output]