2025-09-06 03:09:53.351515 | Job console starting 2025-09-06 03:09:53.369161 | Updating git repos 2025-09-06 03:09:53.400084 | Cloning repos into workspace 2025-09-06 03:09:53.468926 | Restoring repo states 2025-09-06 03:09:53.492192 | Merging changes 2025-09-06 03:09:53.492213 | Checking out repos 2025-09-06 03:09:53.531723 | Preparing playbooks 2025-09-06 03:09:54.686219 | Running Ansible setup 2025-09-06 03:09:59.440061 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-06 03:10:00.862041 | 2025-09-06 03:10:00.862217 | PLAY [Base pre] 2025-09-06 03:10:00.887233 | 2025-09-06 03:10:00.889527 | TASK [Setup log path fact] 2025-09-06 03:10:00.909881 | debian-bookworm | ok 2025-09-06 03:10:00.931016 | 2025-09-06 03:10:00.931182 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-06 03:10:01.005087 | debian-bookworm | ok 2025-09-06 03:10:01.034868 | 2025-09-06 03:10:01.035299 | TASK [emit-job-header : Print job information] 2025-09-06 03:10:01.141051 | # Job Information 2025-09-06 03:10:01.141608 | Ansible Version: 2.16.14 2025-09-06 03:10:01.141702 | Job: tox 2025-09-06 03:10:01.141740 | Pipeline: periodic-daily 2025-09-06 03:10:01.141765 | Executor: 521e9411259a 2025-09-06 03:10:01.141786 | Triggered by: https://github.com/osism/openstack-image-manager 2025-09-06 03:10:01.141810 | Event ID: 4dd710199c544233b2f8b3b1b7862685 2025-09-06 03:10:01.148212 | 2025-09-06 03:10:01.148624 | LOOP [emit-job-header : Print node information] 2025-09-06 03:10:01.393073 | debian-bookworm | ok: 2025-09-06 03:10:01.393297 | debian-bookworm | # Node Information 2025-09-06 03:10:01.393333 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-06 03:10:01.393375 | debian-bookworm | Hostname: debian 2025-09-06 03:10:01.393417 | debian-bookworm | Username: zuul 2025-09-06 03:10:01.393440 | debian-bookworm | Distro: Debian 12.11 2025-09-06 03:10:01.393464 | debian-bookworm | Provider: regiocloud-a 2025-09-06 03:10:01.393486 | debian-bookworm | Region: 2025-09-06 03:10:01.393507 | debian-bookworm | Label: debian-bookworm 2025-09-06 03:10:01.393527 | debian-bookworm | Product Name: OpenStack Nova 2025-09-06 03:10:01.393547 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe36:e3f8 2025-09-06 03:10:01.412807 | 2025-09-06 03:10:01.412954 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-06 03:10:02.593414 | debian-bookworm -> localhost | changed 2025-09-06 03:10:02.601057 | 2025-09-06 03:10:02.601195 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-06 03:10:04.051315 | debian-bookworm -> localhost | changed 2025-09-06 03:10:04.070863 | 2025-09-06 03:10:04.083605 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-06 03:10:04.651383 | debian-bookworm -> localhost | ok 2025-09-06 03:10:04.658229 | 2025-09-06 03:10:04.658358 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-06 03:10:04.703545 | debian-bookworm | ok 2025-09-06 03:10:04.770181 | debian-bookworm | included: /var/lib/zuul/builds/88a63a747e8b44d09df8b88c7b436c3a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-06 03:10:04.778268 | 2025-09-06 03:10:04.778364 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-06 03:10:07.854999 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-06 03:10:07.855189 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/88a63a747e8b44d09df8b88c7b436c3a/work/88a63a747e8b44d09df8b88c7b436c3a_id_rsa 2025-09-06 03:10:07.855228 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/88a63a747e8b44d09df8b88c7b436c3a/work/88a63a747e8b44d09df8b88c7b436c3a_id_rsa.pub 2025-09-06 03:10:07.855256 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-06 03:10:07.855282 | debian-bookworm -> localhost | SHA256:epleOSeawVa3kkIeneaqRVFQXfk69zslr9ye8IbNBVE zuul-build-sshkey 2025-09-06 03:10:07.855305 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-06 03:10:07.855337 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-06 03:10:07.855360 | debian-bookworm -> localhost | | .oo. ..oE| 2025-09-06 03:10:07.855381 | debian-bookworm -> localhost | | . . o | 2025-09-06 03:10:07.855413 | debian-bookworm -> localhost | | . o | 2025-09-06 03:10:07.855433 | debian-bookworm -> localhost | | o . . .| 2025-09-06 03:10:07.855453 | debian-bookworm -> localhost | | S = . o | 2025-09-06 03:10:07.855481 | debian-bookworm -> localhost | | * B + .+ +| 2025-09-06 03:10:07.855502 | debian-bookworm -> localhost | | . @ O o.+=+| 2025-09-06 03:10:07.855522 | debian-bookworm -> localhost | | = B = oo*+| 2025-09-06 03:10:07.855543 | debian-bookworm -> localhost | | ..= +*=| 2025-09-06 03:10:07.855562 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-06 03:10:07.855611 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.260758 2025-09-06 03:10:07.861647 | 2025-09-06 03:10:07.861732 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-06 03:10:07.889195 | debian-bookworm | ok 2025-09-06 03:10:07.898027 | debian-bookworm | included: /var/lib/zuul/builds/88a63a747e8b44d09df8b88c7b436c3a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-06 03:10:07.924220 | 2025-09-06 03:10:07.924331 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-06 03:10:07.957741 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:10:07.963876 | 2025-09-06 03:10:07.963973 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-06 03:10:08.607368 | debian-bookworm | changed 2025-09-06 03:10:08.612626 | 2025-09-06 03:10:08.612718 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-06 03:10:08.902278 | debian-bookworm | ok 2025-09-06 03:10:08.911751 | 2025-09-06 03:10:08.911856 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-06 03:10:09.696603 | debian-bookworm | changed 2025-09-06 03:10:09.703227 | 2025-09-06 03:10:09.703329 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-06 03:10:10.410040 | debian-bookworm | changed 2025-09-06 03:10:10.415666 | 2025-09-06 03:10:10.416187 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-06 03:10:10.439457 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:10:10.446261 | 2025-09-06 03:10:10.446364 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-06 03:10:11.194599 | debian-bookworm -> localhost | changed 2025-09-06 03:10:11.214789 | 2025-09-06 03:10:11.215003 | TASK [add-build-sshkey : Add back temp key] 2025-09-06 03:10:11.761929 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/88a63a747e8b44d09df8b88c7b436c3a/work/88a63a747e8b44d09df8b88c7b436c3a_id_rsa (zuul-build-sshkey) 2025-09-06 03:10:11.762134 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.008761 2025-09-06 03:10:11.769433 | 2025-09-06 03:10:11.769522 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-06 03:10:12.155738 | debian-bookworm | ok 2025-09-06 03:10:12.160491 | 2025-09-06 03:10:12.160574 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-06 03:10:12.197250 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:10:12.206893 | 2025-09-06 03:10:12.206979 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-06 03:10:12.754873 | debian-bookworm | ok 2025-09-06 03:10:12.777090 | 2025-09-06 03:10:12.777726 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-06 03:10:12.827177 | debian-bookworm | ok 2025-09-06 03:10:12.839698 | 2025-09-06 03:10:12.839803 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-06 03:10:13.560928 | debian-bookworm -> localhost | ok 2025-09-06 03:10:13.575010 | 2025-09-06 03:10:13.575114 | TASK [validate-host : Collect information about the host] 2025-09-06 03:10:14.976961 | debian-bookworm | ok 2025-09-06 03:10:14.998675 | 2025-09-06 03:10:14.998957 | TASK [validate-host : Sanitize hostname] 2025-09-06 03:10:15.173137 | debian-bookworm | ok 2025-09-06 03:10:15.236620 | 2025-09-06 03:10:15.236728 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-06 03:10:15.891858 | debian-bookworm -> localhost | changed 2025-09-06 03:10:15.897154 | 2025-09-06 03:10:15.897360 | TASK [validate-host : Collect information about zuul worker] 2025-09-06 03:10:16.288507 | debian-bookworm | ok 2025-09-06 03:10:16.292584 | 2025-09-06 03:10:16.292677 | TASK [validate-host : Write out all zuul information for each host] 2025-09-06 03:10:17.189606 | debian-bookworm -> localhost | changed 2025-09-06 03:10:17.197110 | 2025-09-06 03:10:17.197191 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-06 03:10:17.507066 | debian-bookworm | ok 2025-09-06 03:10:17.511320 | 2025-09-06 03:10:17.511421 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-06 03:10:18.985866 | debian-bookworm | changed: 2025-09-06 03:10:18.986008 | debian-bookworm | cd+++++++++ src/ 2025-09-06 03:10:18.986036 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-06 03:10:18.986056 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-06 03:10:18.986074 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-09-06 03:10:18.986090 | debian-bookworm | =6.1 2025-09-06 03:10:42.573526 | debian-bookworm | Downloading cachetools-6.2.0-py3-none-any.whl (11 kB) 2025-09-06 03:10:42.604600 | debian-bookworm | Collecting chardet>=5.2 2025-09-06 03:10:42.612766 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-09-06 03:10:42.642983 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 7.6 MB/s eta 0:00:00 2025-09-06 03:10:42.694101 | debian-bookworm | Collecting colorama>=0.4.6 2025-09-06 03:10:42.704330 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-09-06 03:10:42.771804 | debian-bookworm | Collecting filelock>=3.18 2025-09-06 03:10:42.780761 | debian-bookworm | Downloading filelock-3.19.1-py3-none-any.whl (15 kB) 2025-09-06 03:10:42.830993 | debian-bookworm | Collecting packaging>=25 2025-09-06 03:10:42.838738 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-09-06 03:10:42.851398 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 5.7 MB/s eta 0:00:00 2025-09-06 03:10:42.893809 | debian-bookworm | Collecting platformdirs>=4.3.8 2025-09-06 03:10:42.901861 | debian-bookworm | Downloading platformdirs-4.4.0-py3-none-any.whl (18 kB) 2025-09-06 03:10:42.938952 | debian-bookworm | Collecting pluggy>=1.6 2025-09-06 03:10:42.946472 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-09-06 03:10:42.981651 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-09-06 03:10:42.989375 | debian-bookworm | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2025-09-06 03:10:43.119329 | debian-bookworm | Collecting virtualenv>=20.31.2 2025-09-06 03:10:43.127516 | debian-bookworm | Downloading virtualenv-20.34.0-py3-none-any.whl (6.0 MB) 2025-09-06 03:10:43.422487 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 20.6 MB/s eta 0:00:00 2025-09-06 03:10:43.528159 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-09-06 03:10:43.535800 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-09-06 03:10:43.563994 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 20.6 MB/s eta 0:00:00 2025-09-06 03:10:43.721624 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-09-06 03:10:44.728028 | 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.30.2 virtualenv-20.34.0 2025-09-06 03:10:44.959169 | debian-bookworm | ok: Runtime: 0:00:03.129613 2025-09-06 03:10:44.965563 | 2025-09-06 03:10:44.965689 | TASK [ensure-tox : Export installed tox_executable path] 2025-09-06 03:10:45.018562 | debian-bookworm | ok 2025-09-06 03:10:45.029671 | 2025-09-06 03:10:45.029816 | TASK [ensure-tox : Output tox version] 2025-09-06 03:10:45.614063 | 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-09-06 03:10:45.632870 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-06 03:10:46.075207 | debian-bookworm | ok: Runtime: 0:00:00.321433 2025-09-06 03:10:46.081279 | 2025-09-06 03:10:46.081434 | TASK [ensure-tox : Make global symlink] 2025-09-06 03:10:46.115866 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:10:46.130307 | 2025-09-06 03:10:46.130765 | PLAY RECAP 2025-09-06 03:10:46.130892 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-09-06 03:10:46.130935 | 2025-09-06 03:10:46.374736 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-09-06 03:10:46.376272 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-06 03:10:47.430887 | 2025-09-06 03:10:47.431182 | PLAY [all] 2025-09-06 03:10:47.448634 | 2025-09-06 03:10:47.448795 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-09-06 03:10:47.903315 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-06 03:10:47.977788 | debian-bookworm | ok: Runtime: 0:00:00.029801 2025-09-06 03:10:47.983677 | 2025-09-06 03:10:47.983815 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-09-06 03:10:48.515732 | debian-bookworm | ok: Runtime: 0:00:00.004851 2025-09-06 03:10:48.521960 | 2025-09-06 03:10:48.522084 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-09-06 03:10:48.843411 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-06 03:10:48.847129 | debian-bookworm | sudo: a password is required 2025-09-06 03:10:49.059276 | debian-bookworm | ok: Runtime: 0:00:00.024418 2025-09-06 03:10:49.068876 | 2025-09-06 03:10:49.068973 | TASK [tox : Check to see if the constraints file exists] 2025-09-06 03:10:49.128247 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:10:49.136027 | 2025-09-06 03:10:49.136175 | TASK [tox : Fail if constraints file is missing] 2025-09-06 03:10:49.160421 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:10:49.166685 | 2025-09-06 03:10:49.166796 | TASK [tox : Record file location] 2025-09-06 03:10:49.199954 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:10:49.205621 | 2025-09-06 03:10:49.205737 | TASK [tox : Install tox siblings] 2025-09-06 03:10:49.239213 | debian-bookworm | ok 2025-09-06 03:10:49.255560 | debian-bookworm | included: /var/lib/zuul/builds/88a63a747e8b44d09df8b88c7b436c3a/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-09-06 03:10:49.262453 | 2025-09-06 03:10:49.262561 | TASK [tox : Run tox without tests] 2025-09-06 03:10:50.471991 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-09-06 03:11:03.469528 | debian-bookworm | test: OK (13.57 seconds) 2025-09-06 03:11:03.469582 | debian-bookworm | congratulations :) (13.60 seconds) 2025-09-06 03:11:03.827980 | debian-bookworm | ok: Runtime: 0:00:13.893196 2025-09-06 03:11:03.834103 | 2025-09-06 03:11:03.834218 | TASK [tox : Create a tempfile to save tox showconfig] 2025-09-06 03:11:04.364177 | debian-bookworm | changed 2025-09-06 03:11:04.369526 | 2025-09-06 03:11:04.369632 | TASK [tox : Get tox envlist config] 2025-09-06 03:11:05.417920 | debian-bookworm | ok: Runtime: 0:00:00.360911 2025-09-06 03:11:05.423468 | 2025-09-06 03:11:05.423561 | TASK [tox : Install any sibling python packages] 2025-09-06 03:11:05.854941 | debian-bookworm | ok: No setup.cfg, no action needed 2025-09-06 03:11:05.860128 | 2025-09-06 03:11:05.860213 | TASK [tox : Remove tempfile] 2025-09-06 03:11:06.251564 | debian-bookworm | changed 2025-09-06 03:11:06.257568 | 2025-09-06 03:11:06.257673 | TASK [tox : Emit tox command] 2025-09-06 03:11:06.286377 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-09-06 03:11:06.292334 | 2025-09-06 03:11:06.292439 | TASK [tox : Run tox] 2025-09-06 03:11:06.945287 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-09-06 03:11:07.612851 | debian-bookworm | ............... 2025-09-06 03:11:07.612908 | debian-bookworm | ---------------------------------------------------------------------- 2025-09-06 03:11:07.612925 | debian-bookworm | Ran 15 tests in 0.178s 2025-09-06 03:11:07.612930 | debian-bookworm | 2025-09-06 03:11:07.612937 | debian-bookworm | OK 2025-09-06 03:11:07.705511 | debian-bookworm | test: OK (0.80=setup[0.04]+cmd[0.76] seconds) 2025-09-06 03:11:07.705637 | debian-bookworm | congratulations :) (0.83 seconds) 2025-09-06 03:11:07.875165 | debian-bookworm | ok: Runtime: 0:00:01.135085 2025-09-06 03:11:07.887901 | 2025-09-06 03:11:07.888039 | TASK [tox : Look for output] 2025-09-06 03:11:08.262616 | debian-bookworm | ok 2025-09-06 03:11:08.280033 | 2025-09-06 03:11:08.280254 | TASK [tox : Return file comments to Zuul] 2025-09-06 03:11:08.322551 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:11:08.328921 | 2025-09-06 03:11:08.328988 | PLAY RECAP 2025-09-06 03:11:08.329038 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-09-06 03:11:08.329061 | 2025-09-06 03:11:08.447740 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-06 03:11:08.450783 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-06 03:11:09.183467 | 2025-09-06 03:11:09.183601 | PLAY [all] 2025-09-06 03:11:09.197836 | 2025-09-06 03:11:09.197964 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-09-06 03:11:09.242172 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:11:09.253531 | 2025-09-06 03:11:09.253666 | TASK [fetch-tox-output : Set tox log path for single node] 2025-09-06 03:11:09.296670 | debian-bookworm | ok 2025-09-06 03:11:09.302415 | 2025-09-06 03:11:09.302513 | TASK [fetch-tox-output : Ensure local tox dir] 2025-09-06 03:11:09.710094 | debian-bookworm -> localhost | changed 2025-09-06 03:11:09.718209 | 2025-09-06 03:11:09.718336 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-09-06 03:11:09.762255 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:11:09.776842 | 2025-09-06 03:11:09.777020 | TASK [fetch-tox-output : Set envlist fact] 2025-09-06 03:11:09.835889 | debian-bookworm | ok 2025-09-06 03:11:09.841431 | 2025-09-06 03:11:09.841516 | TASK [fetch-tox-output : Get tox version] 2025-09-06 03:11:10.506637 | 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-09-06 03:11:10.525656 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-06 03:11:10.870110 | debian-bookworm | ok: Runtime: 0:00:00.296254 2025-09-06 03:11:10.875457 | 2025-09-06 03:11:10.875546 | TASK [fetch-tox-output : Find all default environments] 2025-09-06 03:11:11.408384 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:11:11.418724 | 2025-09-06 03:11:11.419221 | TASK [fetch-tox-output : Set envlist fact] 2025-09-06 03:11:11.478318 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:11:11.485335 | 2025-09-06 03:11:11.485458 | TASK [fetch-tox-output : Find all default environments] 2025-09-06 03:11:12.021750 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:11:12.032174 | 2025-09-06 03:11:12.032339 | TASK [fetch-tox-output : Set envlist fact] 2025-09-06 03:11:12.102466 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:11:12.109452 | 2025-09-06 03:11:12.109574 | LOOP [fetch-tox-output : Copy tox logs] 2025-09-06 03:11:12.173576 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:11:12.184900 | 2025-09-06 03:11:12.185035 | LOOP [fetch-tox-output : Collect tox logs] 2025-09-06 03:11:13.658910 | debian-bookworm | changed: 2025-09-06 03:11:13.659099 | debian-bookworm | created directory /var/lib/zuul/builds/88a63a747e8b44d09df8b88c7b436c3a/work/logs/tox/test 2025-09-06 03:11:13.659172 | debian-bookworm | cd+++++++++ ./ 2025-09-06 03:11:13.659200 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-09-06 03:11:13.659230 | 2025-09-06 03:11:13.675316 | 2025-09-06 03:11:13.675400 | PLAY RECAP 2025-09-06 03:11:13.675452 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-09-06 03:11:13.675477 | 2025-09-06 03:11:13.849723 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-06 03:11:13.850647 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-06 03:11:14.615459 | 2025-09-06 03:11:14.615611 | PLAY [all] 2025-09-06 03:11:14.630985 | 2025-09-06 03:11:14.631123 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-09-06 03:11:14.897075 | debian-bookworm | changed: non-zero return code 2025-09-06 03:11:14.902721 | 2025-09-06 03:11:14.902875 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-09-06 03:11:14.957793 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:11:14.964434 | 2025-09-06 03:11:14.964579 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-09-06 03:11:14.995135 | 2025-09-06 03:11:14.995366 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-09-06 03:11:15.026605 | 2025-09-06 03:11:15.026807 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-09-06 03:11:15.050385 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:11:15.056381 | 2025-09-06 03:11:15.056505 | LOOP [fetch-subunit-output : Generate subunit file] 2025-09-06 03:11:15.085736 | 2025-09-06 03:11:15.085930 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-09-06 03:11:15.109611 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:11:15.115477 | 2025-09-06 03:11:15.115595 | TASK [fetch-subunit-output : Remove the temporary file] 2025-09-06 03:11:15.149835 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:11:15.159495 | 2025-09-06 03:11:15.159644 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-09-06 03:11:15.188860 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:11:15.197543 | 2025-09-06 03:11:15.197646 | PLAY RECAP 2025-09-06 03:11:15.197701 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-06 03:11:15.197727 | 2025-09-06 03:11:15.471907 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-06 03:11:15.472995 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-06 03:11:16.853649 | 2025-09-06 03:11:16.853825 | PLAY [Base post] 2025-09-06 03:11:16.894253 | 2025-09-06 03:11:16.894433 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-06 03:11:17.448510 | debian-bookworm | changed 2025-09-06 03:11:17.458097 | 2025-09-06 03:11:17.458203 | PLAY RECAP 2025-09-06 03:11:17.458261 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-06 03:11:17.458351 | 2025-09-06 03:11:17.554698 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-06 03:11:17.555663 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-06 03:11:18.294034 | 2025-09-06 03:11:18.294160 | PLAY [Base post-logs] 2025-09-06 03:11:18.303600 | 2025-09-06 03:11:18.303710 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-06 03:11:18.693432 | localhost | changed 2025-09-06 03:11:18.703457 | 2025-09-06 03:11:18.703595 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-06 03:11:18.728612 | localhost | ok 2025-09-06 03:11:18.731568 | 2025-09-06 03:11:18.731668 | TASK [Set zuul-log-path fact] 2025-09-06 03:11:18.746395 | localhost | ok 2025-09-06 03:11:18.754446 | 2025-09-06 03:11:18.754539 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-06 03:11:18.778747 | localhost | ok 2025-09-06 03:11:18.781536 | 2025-09-06 03:11:18.781618 | TASK [upload-logs : Create log directories] 2025-09-06 03:11:19.242653 | localhost | changed 2025-09-06 03:11:19.245346 | 2025-09-06 03:11:19.245438 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-06 03:11:20.066658 | localhost -> localhost | ok: Runtime: 0:00:00.024558 2025-09-06 03:11:20.070076 | 2025-09-06 03:11:20.070162 | TASK [upload-logs : Upload logs to log server] 2025-09-06 03:11:20.664709 | localhost | Output suppressed because no_log was given 2025-09-06 03:11:20.666313 | 2025-09-06 03:11:20.666401 | LOOP [upload-logs : Compress console log and json output] 2025-09-06 03:11:20.725809 | localhost | skipping: Conditional result was False 2025-09-06 03:11:20.731089 | localhost | skipping: Conditional result was False 2025-09-06 03:11:20.736721 | 2025-09-06 03:11:20.736815 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-06 03:11:20.817605 | localhost | skipping: Conditional result was False 2025-09-06 03:11:20.817850 | 2025-09-06 03:11:20.832794 | localhost | skipping: Conditional result was False 2025-09-06 03:11:20.836474 | 2025-09-06 03:11:20.836569 | LOOP [upload-logs : Upload console log and json output]