2026-05-22 03:17:57.604001 | Job console starting 2026-05-22 03:17:57.618748 | Updating git repos 2026-05-22 03:17:57.663764 | Cloning repos into workspace 2026-05-22 03:17:57.734123 | Restoring repo states 2026-05-22 03:17:57.742399 | Merging changes 2026-05-22 03:17:57.742421 | Checking out repos 2026-05-22 03:17:57.794627 | Preparing playbooks 2026-05-22 03:17:58.877585 | Running Ansible setup 2026-05-22 03:18:03.851814 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-22 03:18:05.023441 | 2026-05-22 03:18:05.023621 | PLAY [Base pre] 2026-05-22 03:18:05.041441 | 2026-05-22 03:18:05.041606 | TASK [Setup log path fact] 2026-05-22 03:18:05.072055 | debian-bookworm | ok 2026-05-22 03:18:05.089070 | 2026-05-22 03:18:05.089869 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-22 03:18:05.125559 | debian-bookworm | ok 2026-05-22 03:18:05.138528 | 2026-05-22 03:18:05.138668 | TASK [emit-job-header : Print job information] 2026-05-22 03:18:05.178579 | # Job Information 2026-05-22 03:18:05.178945 | Ansible Version: 2.16.14 2026-05-22 03:18:05.178986 | Job: tox 2026-05-22 03:18:05.179021 | Pipeline: periodic-daily 2026-05-22 03:18:05.179044 | Executor: 521e9411259a 2026-05-22 03:18:05.179065 | Triggered by: https://github.com/osism/openstack-image-manager 2026-05-22 03:18:05.179088 | Event ID: 8bcbdc039e47463e8d988fb30d0b7f99 2026-05-22 03:18:05.184192 | 2026-05-22 03:18:05.184340 | LOOP [emit-job-header : Print node information] 2026-05-22 03:18:05.505557 | debian-bookworm | ok: 2026-05-22 03:18:05.505780 | debian-bookworm | # Node Information 2026-05-22 03:18:05.505817 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-22 03:18:05.505843 | debian-bookworm | Hostname: debian 2026-05-22 03:18:05.505865 | debian-bookworm | Username: zuul 2026-05-22 03:18:05.505886 | debian-bookworm | Distro: Debian 12.14 2026-05-22 03:18:05.505911 | debian-bookworm | Provider: regiocloud-a 2026-05-22 03:18:05.505932 | debian-bookworm | Region: 2026-05-22 03:18:05.505953 | debian-bookworm | Label: debian-bookworm 2026-05-22 03:18:05.505972 | debian-bookworm | Product Name: OpenStack Nova 2026-05-22 03:18:05.505992 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fefd:f9b2 2026-05-22 03:18:05.525969 | 2026-05-22 03:18:05.526120 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-22 03:18:06.943603 | debian-bookworm -> localhost | changed 2026-05-22 03:18:06.951217 | 2026-05-22 03:18:06.951402 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-22 03:18:08.297278 | debian-bookworm -> localhost | changed 2026-05-22 03:18:08.312019 | 2026-05-22 03:18:08.312128 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-22 03:18:08.743469 | debian-bookworm -> localhost | ok 2026-05-22 03:18:08.749488 | 2026-05-22 03:18:08.749598 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-22 03:18:08.784695 | debian-bookworm | ok 2026-05-22 03:18:08.823614 | debian-bookworm | included: /var/lib/zuul/builds/e7a1df6a85ce45bf9cc12cd8f9e34f2f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-22 03:18:08.838647 | 2026-05-22 03:18:08.838756 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-22 03:18:13.305495 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-22 03:18:13.305695 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/e7a1df6a85ce45bf9cc12cd8f9e34f2f/work/e7a1df6a85ce45bf9cc12cd8f9e34f2f_id_rsa 2026-05-22 03:18:13.305732 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/e7a1df6a85ce45bf9cc12cd8f9e34f2f/work/e7a1df6a85ce45bf9cc12cd8f9e34f2f_id_rsa.pub 2026-05-22 03:18:13.305758 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-22 03:18:13.305783 | debian-bookworm -> localhost | SHA256:hjtAKaUkD4D8V8kfGxGBYa0v6GJj44aNHHfj5ptljiA zuul-build-sshkey 2026-05-22 03:18:13.305805 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-22 03:18:13.305834 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-22 03:18:13.305857 | debian-bookworm -> localhost | |B . . .o=++ | 2026-05-22 03:18:13.305878 | debian-bookworm -> localhost | |.* o ..= + | 2026-05-22 03:18:13.305898 | debian-bookworm -> localhost | | = o . o + | 2026-05-22 03:18:13.305918 | debian-bookworm -> localhost | | + . o o | 2026-05-22 03:18:13.305940 | debian-bookworm -> localhost | | o o S | 2026-05-22 03:18:13.305963 | debian-bookworm -> localhost | | . . = + . | 2026-05-22 03:18:13.305985 | debian-bookworm -> localhost | |.E*.+ +o. | 2026-05-22 03:18:13.306006 | debian-bookworm -> localhost | | +.O.+*. | 2026-05-22 03:18:13.306028 | debian-bookworm -> localhost | | =.*=.. | 2026-05-22 03:18:13.306050 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-22 03:18:13.306156 | debian-bookworm -> localhost | ok: Runtime: 0:00:03.504031 2026-05-22 03:18:13.320584 | 2026-05-22 03:18:13.320691 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-22 03:18:13.360220 | debian-bookworm | ok 2026-05-22 03:18:13.370485 | debian-bookworm | included: /var/lib/zuul/builds/e7a1df6a85ce45bf9cc12cd8f9e34f2f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-22 03:18:13.380705 | 2026-05-22 03:18:13.380804 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-22 03:18:13.414220 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:18:13.420011 | 2026-05-22 03:18:13.420111 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-22 03:18:14.121511 | debian-bookworm | changed 2026-05-22 03:18:14.132183 | 2026-05-22 03:18:14.132551 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-22 03:18:14.416988 | debian-bookworm | ok 2026-05-22 03:18:14.443507 | 2026-05-22 03:18:14.443801 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-22 03:18:15.269891 | debian-bookworm | changed 2026-05-22 03:18:15.277618 | 2026-05-22 03:18:15.277715 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-22 03:18:15.983452 | debian-bookworm | changed 2026-05-22 03:18:15.989536 | 2026-05-22 03:18:15.989625 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-22 03:18:16.032088 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:18:16.042052 | 2026-05-22 03:18:16.042158 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-22 03:18:16.949174 | debian-bookworm -> localhost | changed 2026-05-22 03:18:16.966102 | 2026-05-22 03:18:16.966211 | TASK [add-build-sshkey : Add back temp key] 2026-05-22 03:18:17.480659 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/e7a1df6a85ce45bf9cc12cd8f9e34f2f/work/e7a1df6a85ce45bf9cc12cd8f9e34f2f_id_rsa (zuul-build-sshkey) 2026-05-22 03:18:17.480863 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012969 2026-05-22 03:18:17.488288 | 2026-05-22 03:18:17.488384 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-22 03:18:18.019802 | debian-bookworm | ok 2026-05-22 03:18:18.028512 | 2026-05-22 03:18:18.028609 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-22 03:18:18.119658 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:18:18.151149 | 2026-05-22 03:18:18.151288 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-22 03:18:18.667746 | debian-bookworm | ok 2026-05-22 03:18:18.688926 | 2026-05-22 03:18:18.689037 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-22 03:18:18.727032 | debian-bookworm | ok 2026-05-22 03:18:18.755478 | 2026-05-22 03:18:18.755582 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-22 03:18:19.333605 | debian-bookworm -> localhost | ok 2026-05-22 03:18:19.339726 | 2026-05-22 03:18:19.339892 | TASK [validate-host : Collect information about the host] 2026-05-22 03:18:20.612031 | debian-bookworm | ok 2026-05-22 03:18:20.653162 | 2026-05-22 03:18:20.653711 | TASK [validate-host : Sanitize hostname] 2026-05-22 03:18:20.860121 | debian-bookworm | ok 2026-05-22 03:18:21.006509 | 2026-05-22 03:18:21.006865 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-22 03:18:22.399520 | debian-bookworm -> localhost | changed 2026-05-22 03:18:22.406579 | 2026-05-22 03:18:22.406661 | TASK [validate-host : Collect information about zuul worker] 2026-05-22 03:18:22.920475 | debian-bookworm | ok 2026-05-22 03:18:22.924364 | 2026-05-22 03:18:22.924457 | TASK [validate-host : Write out all zuul information for each host] 2026-05-22 03:18:23.610288 | debian-bookworm -> localhost | changed 2026-05-22 03:18:23.640215 | 2026-05-22 03:18:23.640364 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-22 03:18:23.915026 | debian-bookworm | ok 2026-05-22 03:18:23.920744 | 2026-05-22 03:18:23.922396 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-22 03:18:24.824196 | debian-bookworm | changed: 2026-05-22 03:18:24.824388 | debian-bookworm | cd+++++++++ src/ 2026-05-22 03:18:24.824423 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-22 03:18:24.824447 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-22 03:18:24.824468 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-22 03:18:24.824488 | debian-bookworm | =7.0.3 2026-05-22 03:18:50.818873 | debian-bookworm | Downloading cachetools-7.1.4-py3-none-any.whl (16 kB) 2026-05-22 03:18:50.863666 | debian-bookworm | Collecting colorama>=0.4.6 2026-05-22 03:18:50.876200 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-05-22 03:18:50.956967 | debian-bookworm | Collecting filelock>=3.25 2026-05-22 03:18:50.969759 | debian-bookworm | Downloading filelock-3.29.0-py3-none-any.whl (39 kB) 2026-05-22 03:18:51.037510 | debian-bookworm | Collecting packaging>=26 2026-05-22 03:18:51.050098 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-05-22 03:18:51.061265 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 13.1 MB/s eta 0:00:00 2026-05-22 03:18:51.116997 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-05-22 03:18:51.129241 | debian-bookworm | Downloading platformdirs-4.9.6-py3-none-any.whl (21 kB) 2026-05-22 03:18:51.170332 | debian-bookworm | Collecting pluggy>=1.6 2026-05-22 03:18:51.182709 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-05-22 03:18:51.242103 | debian-bookworm | Collecting pyproject-api>=1.10 2026-05-22 03:18:51.254977 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-05-22 03:18:51.295447 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-05-22 03:18:51.308265 | debian-bookworm | Downloading python_discovery-1.3.1-py3-none-any.whl (33 kB) 2026-05-22 03:18:51.354760 | debian-bookworm | Collecting tomli-w>=1.2 2026-05-22 03:18:51.367893 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-05-22 03:18:51.535419 | debian-bookworm | Collecting virtualenv>=21.1 2026-05-22 03:18:51.549136 | debian-bookworm | Downloading virtualenv-21.3.3-py3-none-any.whl (7.6 MB) 2026-05-22 03:18:51.896734 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 22.1 MB/s eta 0:00:00 2026-05-22 03:18:52.003175 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-05-22 03:18:52.018326 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-05-22 03:18:52.042758 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 24.6 MB/s eta 0:00:00 2026-05-22 03:18:52.165325 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-05-22 03:18:53.074298 | debian-bookworm | Successfully installed cachetools-7.1.4 colorama-0.4.6 distlib-0.4.0 filelock-3.29.0 packaging-26.2 platformdirs-4.9.6 pluggy-1.6.0 pyproject-api-1.10.0 python-discovery-1.3.1 tomli-w-1.2.0 tox-4.54.0 virtualenv-21.3.3 2026-05-22 03:18:53.517646 | debian-bookworm | ok: Runtime: 0:00:03.341029 2026-05-22 03:18:53.523410 | 2026-05-22 03:18:53.523504 | TASK [ensure-tox : Export installed tox_executable path] 2026-05-22 03:18:53.573508 | debian-bookworm | ok 2026-05-22 03:18:53.584216 | 2026-05-22 03:18:53.584321 | TASK [ensure-tox : Output tox version] 2026-05-22 03:18:54.263307 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-05-22 03:18:54.282488 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-22 03:18:54.656436 | debian-bookworm | ok: Runtime: 0:00:00.371016 2026-05-22 03:18:54.669356 | 2026-05-22 03:18:54.669687 | TASK [ensure-tox : Make global symlink] 2026-05-22 03:18:54.715326 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:18:54.725730 | 2026-05-22 03:18:54.726042 | PLAY RECAP 2026-05-22 03:18:54.726192 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-05-22 03:18:54.726245 | 2026-05-22 03:18:55.107929 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-05-22 03:18:55.109238 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-22 03:18:56.812258 | 2026-05-22 03:18:56.812388 | PLAY [all] 2026-05-22 03:18:56.867183 | 2026-05-22 03:18:56.867325 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-05-22 03:18:57.483068 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-22 03:18:57.910848 | debian-bookworm | ok: Runtime: 0:00:00.027653 2026-05-22 03:18:57.915599 | 2026-05-22 03:18:57.915691 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-05-22 03:18:58.475031 | debian-bookworm | ok: Runtime: 0:00:00.005879 2026-05-22 03:18:58.480053 | 2026-05-22 03:18:58.480147 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-05-22 03:18:58.838327 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-22 03:18:58.839936 | debian-bookworm | sudo: a password is required 2026-05-22 03:18:59.029551 | debian-bookworm | ok: Runtime: 0:00:00.018689 2026-05-22 03:18:59.037549 | 2026-05-22 03:18:59.037635 | TASK [tox : Check to see if the constraints file exists] 2026-05-22 03:18:59.080978 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:18:59.088656 | 2026-05-22 03:18:59.088756 | TASK [tox : Fail if constraints file is missing] 2026-05-22 03:18:59.142166 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:18:59.147852 | 2026-05-22 03:18:59.148034 | TASK [tox : Record file location] 2026-05-22 03:18:59.191184 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:18:59.195808 | 2026-05-22 03:18:59.195899 | TASK [tox : Install tox siblings] 2026-05-22 03:18:59.224991 | debian-bookworm | ok 2026-05-22 03:18:59.238852 | debian-bookworm | included: /var/lib/zuul/builds/e7a1df6a85ce45bf9cc12cd8f9e34f2f/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-05-22 03:18:59.255921 | 2026-05-22 03:18:59.256022 | TASK [tox : Run tox without tests] 2026-05-22 03:19:00.623285 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-05-22 03:19:14.350272 | debian-bookworm | test: OK (14.44 seconds) 2026-05-22 03:19:14.350355 | debian-bookworm | congratulations :) (14.47 seconds) 2026-05-22 03:19:14.823615 | debian-bookworm | ok: Runtime: 0:00:14.798640 2026-05-22 03:19:14.829106 | 2026-05-22 03:19:14.829207 | TASK [tox : Create a tempfile to save tox showconfig] 2026-05-22 03:19:15.595858 | debian-bookworm | changed 2026-05-22 03:19:15.601092 | 2026-05-22 03:19:15.601210 | TASK [tox : Detect tox version] 2026-05-22 03:19:16.362884 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-22 03:19:16.699398 | debian-bookworm | ok: Runtime: 0:00:00.335626 2026-05-22 03:19:16.708594 | 2026-05-22 03:19:16.708764 | TASK [tox : Get tox envlist config] 2026-05-22 03:19:17.777824 | debian-bookworm | ok: Runtime: 0:00:00.375738 2026-05-22 03:19:17.782603 | 2026-05-22 03:19:17.782783 | TASK [tox : Install any sibling python packages] 2026-05-22 03:19:18.415294 | debian-bookworm | ok: No setup.cfg, no action needed 2026-05-22 03:19:18.424974 | 2026-05-22 03:19:18.425184 | TASK [tox : Remove tempfile] 2026-05-22 03:19:18.883123 | debian-bookworm | changed 2026-05-22 03:19:18.888371 | 2026-05-22 03:19:18.888459 | TASK [tox : Emit tox command] 2026-05-22 03:19:18.926996 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-05-22 03:19:18.933503 | 2026-05-22 03:19:18.933611 | TASK [tox : Run tox] 2026-05-22 03:19:19.538221 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-05-22 03:19:20.316451 | debian-bookworm | ............... 2026-05-22 03:19:20.316553 | debian-bookworm | ---------------------------------------------------------------------- 2026-05-22 03:19:20.317110 | debian-bookworm | Ran 15 tests in 0.178s 2026-05-22 03:19:20.317145 | debian-bookworm | 2026-05-22 03:19:20.317167 | debian-bookworm | OK 2026-05-22 03:19:20.426266 | debian-bookworm | test: OK (0.93=setup[0.05]+cmd[0.88] seconds) 2026-05-22 03:19:20.426334 | debian-bookworm | congratulations :) (0.96 seconds) 2026-05-22 03:19:20.964461 | debian-bookworm | ok: Runtime: 0:00:01.270224 2026-05-22 03:19:20.969110 | 2026-05-22 03:19:20.969218 | TASK [tox : Look for output] 2026-05-22 03:19:21.398690 | debian-bookworm | ok 2026-05-22 03:19:21.417558 | 2026-05-22 03:19:21.417677 | TASK [tox : Return file comments to Zuul] 2026-05-22 03:19:21.443532 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:19:21.450253 | 2026-05-22 03:19:21.450318 | PLAY RECAP 2026-05-22 03:19:21.450368 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-05-22 03:19:21.450389 | 2026-05-22 03:19:21.532016 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-22 03:19:21.534963 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-22 03:19:22.108973 | 2026-05-22 03:19:22.109084 | PLAY [all] 2026-05-22 03:19:22.140245 | 2026-05-22 03:19:22.140357 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-05-22 03:19:22.224092 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:19:22.228660 | 2026-05-22 03:19:22.228762 | TASK [fetch-tox-output : Set tox log path for single node] 2026-05-22 03:19:22.255870 | debian-bookworm | ok 2026-05-22 03:19:22.260528 | 2026-05-22 03:19:22.260611 | TASK [fetch-tox-output : Ensure local tox dir] 2026-05-22 03:19:22.791770 | debian-bookworm -> localhost | changed 2026-05-22 03:19:22.803084 | 2026-05-22 03:19:22.803286 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-22 03:19:22.857338 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:19:22.864948 | 2026-05-22 03:19:22.865088 | TASK [fetch-tox-output : Set envlist fact] 2026-05-22 03:19:22.905349 | debian-bookworm | ok 2026-05-22 03:19:22.912404 | 2026-05-22 03:19:22.912501 | TASK [fetch-tox-output : Get tox version] 2026-05-22 03:19:23.616921 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-05-22 03:19:23.635031 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-22 03:19:23.948743 | debian-bookworm | ok: Runtime: 0:00:00.333808 2026-05-22 03:19:23.954029 | 2026-05-22 03:19:23.954118 | TASK [fetch-tox-output : Find all default environments] 2026-05-22 03:19:24.482455 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:19:24.488098 | 2026-05-22 03:19:24.488227 | TASK [fetch-tox-output : Set envlist fact] 2026-05-22 03:19:24.535804 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:19:24.541795 | 2026-05-22 03:19:24.541894 | TASK [fetch-tox-output : Find all default environments] 2026-05-22 03:19:25.115939 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:19:25.122112 | 2026-05-22 03:19:25.122264 | TASK [fetch-tox-output : Set envlist fact] 2026-05-22 03:19:25.173959 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:19:25.194664 | 2026-05-22 03:19:25.194895 | LOOP [fetch-tox-output : Copy tox logs] 2026-05-22 03:19:25.248371 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:19:25.258193 | 2026-05-22 03:19:25.258330 | LOOP [fetch-tox-output : Collect tox logs] 2026-05-22 03:19:26.322915 | debian-bookworm | changed: 2026-05-22 03:19:26.323115 | debian-bookworm | created directory /var/lib/zuul/builds/e7a1df6a85ce45bf9cc12cd8f9e34f2f/work/logs/tox/test 2026-05-22 03:19:26.323151 | debian-bookworm | cd+++++++++ ./ 2026-05-22 03:19:26.323191 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-05-22 03:19:26.323219 | 2026-05-22 03:19:26.338948 | 2026-05-22 03:19:26.339071 | PLAY RECAP 2026-05-22 03:19:26.339125 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-05-22 03:19:26.339169 | 2026-05-22 03:19:26.527993 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-22 03:19:26.529099 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-22 03:19:27.629451 | 2026-05-22 03:19:27.629623 | PLAY [all] 2026-05-22 03:19:27.666704 | 2026-05-22 03:19:27.666905 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-22 03:19:27.942813 | debian-bookworm | changed: non-zero return code 2026-05-22 03:19:27.953248 | 2026-05-22 03:19:27.953390 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-22 03:19:27.999305 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:19:28.005445 | 2026-05-22 03:19:28.005575 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-22 03:19:28.035898 | 2026-05-22 03:19:28.036088 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-22 03:19:28.088336 | 2026-05-22 03:19:28.088705 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-22 03:19:28.122779 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:19:28.131501 | 2026-05-22 03:19:28.131668 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-22 03:19:28.170124 | 2026-05-22 03:19:28.170436 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-22 03:19:28.210242 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:19:28.216322 | 2026-05-22 03:19:28.216451 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-22 03:19:28.250393 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:19:28.256542 | 2026-05-22 03:19:28.256655 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-22 03:19:28.290323 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:19:28.297066 | 2026-05-22 03:19:28.297174 | PLAY RECAP 2026-05-22 03:19:28.297230 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-22 03:19:28.297255 | 2026-05-22 03:19:28.657976 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-22 03:19:28.659115 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-22 03:19:29.667345 | 2026-05-22 03:19:29.667528 | PLAY [Base post-fetch] 2026-05-22 03:19:29.700772 | 2026-05-22 03:19:29.700944 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-22 03:19:29.767653 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:19:29.776731 | 2026-05-22 03:19:29.776910 | TASK [fetch-output : Set log path for single node] 2026-05-22 03:19:29.816723 | debian-bookworm | ok 2026-05-22 03:19:29.822767 | 2026-05-22 03:19:29.822949 | LOOP [fetch-output : Ensure local output dirs] 2026-05-22 03:19:30.537602 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/e7a1df6a85ce45bf9cc12cd8f9e34f2f/work/logs" 2026-05-22 03:19:30.869642 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/e7a1df6a85ce45bf9cc12cd8f9e34f2f/work/artifacts" 2026-05-22 03:19:31.151942 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/e7a1df6a85ce45bf9cc12cd8f9e34f2f/work/docs" 2026-05-22 03:19:31.163053 | 2026-05-22 03:19:31.163190 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-22 03:19:31.916742 | debian-bookworm | changed: 2026-05-22 03:19:31.916932 | debian-bookworm | .d..t...... ./ 2026-05-22 03:19:31.916967 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-22 03:19:31.917003 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-22 03:19:31.917033 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-22 03:19:31.917072 | debian-bookworm | changed: All items complete 2026-05-22 03:19:31.917098 | 2026-05-22 03:19:32.465187 | debian-bookworm | changed: .d..t...... ./ 2026-05-22 03:19:32.994518 | debian-bookworm | changed: .d..t...... ./ 2026-05-22 03:19:33.010240 | 2026-05-22 03:19:33.010352 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-22 03:19:33.032898 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:19:33.036960 | debian-bookworm | skipping: Conditional result was False 2026-05-22 03:19:33.050538 | 2026-05-22 03:19:33.050607 | PLAY RECAP 2026-05-22 03:19:33.050657 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-22 03:19:33.050681 | 2026-05-22 03:19:33.143602 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-22 03:19:33.144592 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-22 03:19:33.971537 | 2026-05-22 03:19:33.971679 | PLAY [Base post] 2026-05-22 03:19:34.002237 | 2026-05-22 03:19:34.002362 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-22 03:19:34.630644 | debian-bookworm | changed 2026-05-22 03:19:34.637253 | 2026-05-22 03:19:34.637349 | PLAY RECAP 2026-05-22 03:19:34.637408 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-22 03:19:34.637469 | 2026-05-22 03:19:34.728352 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-22 03:19:34.730135 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-22 03:19:35.605623 | 2026-05-22 03:19:35.605796 | PLAY [Base post-logs] 2026-05-22 03:19:35.617135 | 2026-05-22 03:19:35.617330 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-22 03:19:36.150610 | localhost | changed 2026-05-22 03:19:36.170424 | 2026-05-22 03:19:36.170621 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-22 03:19:36.227548 | localhost | ok 2026-05-22 03:19:36.230764 | 2026-05-22 03:19:36.230893 | TASK [Set zuul-log-path fact] 2026-05-22 03:19:36.246404 | localhost | ok 2026-05-22 03:19:36.256304 | 2026-05-22 03:19:36.256437 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-22 03:19:36.304500 | localhost | ok 2026-05-22 03:19:36.308736 | 2026-05-22 03:19:36.308910 | TASK [upload-logs : Create log directories] 2026-05-22 03:19:36.992666 | localhost | changed 2026-05-22 03:19:36.996811 | 2026-05-22 03:19:36.997068 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-22 03:19:37.676842 | localhost -> localhost | ok: Runtime: 0:00:00.023392 2026-05-22 03:19:37.685090 | 2026-05-22 03:19:37.685311 | TASK [upload-logs : Upload logs to log server] 2026-05-22 03:19:38.502085 | localhost | Output suppressed because no_log was given 2026-05-22 03:19:38.504152 | 2026-05-22 03:19:38.504262 | LOOP [upload-logs : Compress console log and json output] 2026-05-22 03:19:38.554427 | localhost | skipping: Conditional result was False 2026-05-22 03:19:38.561012 | localhost | skipping: Conditional result was False 2026-05-22 03:19:38.575716 | 2026-05-22 03:19:38.576084 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-22 03:19:38.631117 | localhost | skipping: Conditional result was False 2026-05-22 03:19:38.631464 | 2026-05-22 03:19:38.638824 | localhost | skipping: Conditional result was False 2026-05-22 03:19:38.646623 | 2026-05-22 03:19:38.646773 | LOOP [upload-logs : Upload console log and json output]