2026-05-17 03:01:52.526871 | Job console starting 2026-05-17 03:01:52.550047 | Updating git repos 2026-05-17 03:01:52.650534 | Cloning repos into workspace 2026-05-17 03:01:52.806792 | Restoring repo states 2026-05-17 03:01:52.835357 | Merging changes 2026-05-17 03:01:52.835380 | Checking out repos 2026-05-17 03:01:52.987794 | Preparing playbooks 2026-05-17 03:01:55.007634 | Running Ansible setup 2026-05-17 03:02:01.881415 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-17 03:02:03.128932 | 2026-05-17 03:02:03.129061 | PLAY [Base pre] 2026-05-17 03:02:03.168667 | 2026-05-17 03:02:03.168793 | TASK [Setup log path fact] 2026-05-17 03:02:03.207172 | debian-bookworm | ok 2026-05-17 03:02:03.241663 | 2026-05-17 03:02:03.241804 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-17 03:02:03.273503 | debian-bookworm | ok 2026-05-17 03:02:03.301805 | 2026-05-17 03:02:03.301918 | TASK [emit-job-header : Print job information] 2026-05-17 03:02:03.370523 | # Job Information 2026-05-17 03:02:03.370681 | Ansible Version: 2.16.14 2026-05-17 03:02:03.370714 | Job: tox 2026-05-17 03:02:03.370748 | Pipeline: periodic-daily 2026-05-17 03:02:03.370771 | Executor: 521e9411259a 2026-05-17 03:02:03.370792 | Triggered by: https://github.com/osism/openstack-image-manager 2026-05-17 03:02:03.370815 | Event ID: 669bf48cbc254780bd2d05676811ea16 2026-05-17 03:02:03.375738 | 2026-05-17 03:02:03.375829 | LOOP [emit-job-header : Print node information] 2026-05-17 03:02:03.489569 | debian-bookworm | ok: 2026-05-17 03:02:03.489734 | debian-bookworm | # Node Information 2026-05-17 03:02:03.489770 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-17 03:02:03.489797 | debian-bookworm | Hostname: debian 2026-05-17 03:02:03.489819 | debian-bookworm | Username: zuul 2026-05-17 03:02:03.489841 | debian-bookworm | Distro: Debian 12.14 2026-05-17 03:02:03.489866 | debian-bookworm | Provider: regiocloud-a 2026-05-17 03:02:03.489888 | debian-bookworm | Region: 2026-05-17 03:02:03.489910 | debian-bookworm | Label: debian-bookworm 2026-05-17 03:02:03.489929 | debian-bookworm | Product Name: OpenStack Nova 2026-05-17 03:02:03.489948 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe0f:e33 2026-05-17 03:02:03.510798 | 2026-05-17 03:02:03.511487 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-17 03:02:04.306683 | debian-bookworm -> localhost | changed 2026-05-17 03:02:04.314461 | 2026-05-17 03:02:04.314595 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-17 03:02:06.435377 | debian-bookworm -> localhost | changed 2026-05-17 03:02:06.447838 | 2026-05-17 03:02:06.448011 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-17 03:02:07.225354 | debian-bookworm -> localhost | ok 2026-05-17 03:02:07.230336 | 2026-05-17 03:02:07.230423 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-17 03:02:07.269476 | debian-bookworm | ok 2026-05-17 03:02:07.298215 | debian-bookworm | included: /var/lib/zuul/builds/f47e15ccf5bd4c49b35ff10b6541b374/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-17 03:02:07.328211 | 2026-05-17 03:02:07.328321 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-17 03:02:08.956284 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-17 03:02:08.956461 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/f47e15ccf5bd4c49b35ff10b6541b374/work/f47e15ccf5bd4c49b35ff10b6541b374_id_rsa 2026-05-17 03:02:08.956492 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/f47e15ccf5bd4c49b35ff10b6541b374/work/f47e15ccf5bd4c49b35ff10b6541b374_id_rsa.pub 2026-05-17 03:02:08.956514 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-17 03:02:08.956534 | debian-bookworm -> localhost | SHA256:NcyhSZMRwNL1OiCNBp+pmisQ0dI+NI35HpDi1xDhM5g zuul-build-sshkey 2026-05-17 03:02:08.956553 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-17 03:02:08.956579 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-17 03:02:08.956598 | debian-bookworm -> localhost | | o.Boo.o*+. | 2026-05-17 03:02:08.956616 | debian-bookworm -> localhost | |+ &+o=o..B . | 2026-05-17 03:02:08.956632 | debian-bookworm -> localhost | |.E BX.o o * | 2026-05-17 03:02:08.956648 | debian-bookworm -> localhost | |..o+=o . o . | 2026-05-17 03:02:08.956664 | debian-bookworm -> localhost | | .oo . S | 2026-05-17 03:02:08.956683 | debian-bookworm -> localhost | |.o . . | 2026-05-17 03:02:08.956700 | debian-bookworm -> localhost | |+ | 2026-05-17 03:02:08.956716 | debian-bookworm -> localhost | |.. | 2026-05-17 03:02:08.956732 | debian-bookworm -> localhost | |o | 2026-05-17 03:02:08.956749 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-17 03:02:08.956793 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.982809 2026-05-17 03:02:08.961891 | 2026-05-17 03:02:08.961967 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-17 03:02:09.002410 | debian-bookworm | ok 2026-05-17 03:02:09.010445 | debian-bookworm | included: /var/lib/zuul/builds/f47e15ccf5bd4c49b35ff10b6541b374/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-17 03:02:09.035590 | 2026-05-17 03:02:09.035679 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-17 03:02:09.106468 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:02:09.112670 | 2026-05-17 03:02:09.112759 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-17 03:02:10.211207 | debian-bookworm | changed 2026-05-17 03:02:10.215635 | 2026-05-17 03:02:10.215720 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-17 03:02:10.511667 | debian-bookworm | ok 2026-05-17 03:02:10.516957 | 2026-05-17 03:02:10.517041 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-17 03:02:11.223690 | debian-bookworm | changed 2026-05-17 03:02:11.229499 | 2026-05-17 03:02:11.233342 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-17 03:02:11.900863 | debian-bookworm | changed 2026-05-17 03:02:11.909897 | 2026-05-17 03:02:11.910006 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-17 03:02:11.945350 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:02:11.951949 | 2026-05-17 03:02:11.952071 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-17 03:02:12.507483 | debian-bookworm -> localhost | changed 2026-05-17 03:02:12.520421 | 2026-05-17 03:02:12.520512 | TASK [add-build-sshkey : Add back temp key] 2026-05-17 03:02:12.861012 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/f47e15ccf5bd4c49b35ff10b6541b374/work/f47e15ccf5bd4c49b35ff10b6541b374_id_rsa (zuul-build-sshkey) 2026-05-17 03:02:12.861211 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.026252 2026-05-17 03:02:12.902304 | 2026-05-17 03:02:12.902413 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-17 03:02:13.587992 | debian-bookworm | ok 2026-05-17 03:02:13.597731 | 2026-05-17 03:02:13.597843 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-17 03:02:13.636298 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:02:13.653154 | 2026-05-17 03:02:13.653360 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-17 03:02:14.149610 | debian-bookworm | ok 2026-05-17 03:02:14.170531 | 2026-05-17 03:02:14.170643 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-17 03:02:14.237711 | debian-bookworm | ok 2026-05-17 03:02:14.247112 | 2026-05-17 03:02:14.247226 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-17 03:02:14.999337 | debian-bookworm -> localhost | ok 2026-05-17 03:02:15.007174 | 2026-05-17 03:02:15.007279 | TASK [validate-host : Collect information about the host] 2026-05-17 03:02:16.544624 | debian-bookworm | ok 2026-05-17 03:02:16.562591 | 2026-05-17 03:02:16.562692 | TASK [validate-host : Sanitize hostname] 2026-05-17 03:02:16.697989 | debian-bookworm | ok 2026-05-17 03:02:16.797442 | 2026-05-17 03:02:16.797577 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-17 03:02:18.068676 | debian-bookworm -> localhost | changed 2026-05-17 03:02:18.073563 | 2026-05-17 03:02:18.073648 | TASK [validate-host : Collect information about zuul worker] 2026-05-17 03:02:18.559098 | debian-bookworm | ok 2026-05-17 03:02:18.563113 | 2026-05-17 03:02:18.563194 | TASK [validate-host : Write out all zuul information for each host] 2026-05-17 03:02:19.370397 | debian-bookworm -> localhost | changed 2026-05-17 03:02:19.379454 | 2026-05-17 03:02:19.379541 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-17 03:02:19.622560 | debian-bookworm | ok 2026-05-17 03:02:19.634870 | 2026-05-17 03:02:19.634986 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-17 03:02:21.370543 | debian-bookworm | changed: 2026-05-17 03:02:21.370716 | debian-bookworm | cd+++++++++ src/ 2026-05-17 03:02:21.370751 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-17 03:02:21.370775 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-17 03:02:21.370797 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-17 03:02:21.370816 | debian-bookworm | =7.0.3 2026-05-17 03:02:47.576393 | debian-bookworm | Downloading cachetools-7.1.2-py3-none-any.whl (16 kB) 2026-05-17 03:02:47.624646 | debian-bookworm | Collecting colorama>=0.4.6 2026-05-17 03:02:47.635345 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-05-17 03:02:47.693855 | debian-bookworm | Collecting filelock>=3.25 2026-05-17 03:02:47.704427 | debian-bookworm | Downloading filelock-3.29.0-py3-none-any.whl (39 kB) 2026-05-17 03:02:47.756927 | debian-bookworm | Collecting packaging>=26 2026-05-17 03:02:47.767354 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-05-17 03:02:47.783215 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 6.9 MB/s eta 0:00:00 2026-05-17 03:02:47.830636 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-05-17 03:02:47.843059 | debian-bookworm | Downloading platformdirs-4.9.6-py3-none-any.whl (21 kB) 2026-05-17 03:02:47.921036 | debian-bookworm | Collecting pluggy>=1.6 2026-05-17 03:02:47.932698 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-05-17 03:02:47.983107 | debian-bookworm | Collecting pyproject-api>=1.10 2026-05-17 03:02:47.994472 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-05-17 03:02:48.057287 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-05-17 03:02:48.070528 | debian-bookworm | Downloading python_discovery-1.3.1-py3-none-any.whl (33 kB) 2026-05-17 03:02:48.121490 | debian-bookworm | Collecting tomli-w>=1.2 2026-05-17 03:02:48.133691 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-05-17 03:02:48.344785 | debian-bookworm | Collecting virtualenv>=21.1 2026-05-17 03:02:48.356214 | debian-bookworm | Downloading virtualenv-21.3.3-py3-none-any.whl (7.6 MB) 2026-05-17 03:02:48.696316 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 22.7 MB/s eta 0:00:00 2026-05-17 03:02:48.814294 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-05-17 03:02:48.825858 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-05-17 03:02:48.856243 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 17.1 MB/s eta 0:00:00 2026-05-17 03:02:48.967240 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-05-17 03:02:49.758358 | debian-bookworm | Successfully installed cachetools-7.1.2 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-17 03:02:49.999247 | debian-bookworm | ok: Runtime: 0:00:03.095574 2026-05-17 03:02:50.010654 | 2026-05-17 03:02:50.010788 | TASK [ensure-tox : Export installed tox_executable path] 2026-05-17 03:02:50.066006 | debian-bookworm | ok 2026-05-17 03:02:50.071156 | 2026-05-17 03:02:50.071274 | TASK [ensure-tox : Output tox version] 2026-05-17 03:02:50.725068 | 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-17 03:02:50.742408 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-17 03:02:51.174274 | debian-bookworm | ok: Runtime: 0:00:00.299490 2026-05-17 03:02:51.187738 | 2026-05-17 03:02:51.187858 | TASK [ensure-tox : Make global symlink] 2026-05-17 03:02:51.213200 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:02:51.222494 | 2026-05-17 03:02:51.222614 | PLAY RECAP 2026-05-17 03:02:51.222680 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-05-17 03:02:51.222707 | 2026-05-17 03:02:51.359434 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-05-17 03:02:51.360599 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-17 03:02:52.249431 | 2026-05-17 03:02:52.249582 | PLAY [all] 2026-05-17 03:02:52.284011 | 2026-05-17 03:02:52.284138 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-05-17 03:02:52.721581 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-17 03:02:52.813586 | debian-bookworm | ok: Runtime: 0:00:00.031397 2026-05-17 03:02:52.819082 | 2026-05-17 03:02:52.819189 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-05-17 03:02:53.384213 | debian-bookworm | ok: Runtime: 0:00:00.008952 2026-05-17 03:02:53.392695 | 2026-05-17 03:02:53.392804 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-05-17 03:02:53.768296 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-17 03:02:53.769830 | debian-bookworm | sudo: a password is required 2026-05-17 03:02:53.970441 | debian-bookworm | ok: Runtime: 0:00:00.018151 2026-05-17 03:02:53.980415 | 2026-05-17 03:02:53.980508 | TASK [tox : Check to see if the constraints file exists] 2026-05-17 03:02:54.012779 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:02:54.018527 | 2026-05-17 03:02:54.018654 | TASK [tox : Fail if constraints file is missing] 2026-05-17 03:02:54.061823 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:02:54.067790 | 2026-05-17 03:02:54.067889 | TASK [tox : Record file location] 2026-05-17 03:02:54.101204 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:02:54.106728 | 2026-05-17 03:02:54.106851 | TASK [tox : Install tox siblings] 2026-05-17 03:02:54.135594 | debian-bookworm | ok 2026-05-17 03:02:54.148583 | debian-bookworm | included: /var/lib/zuul/builds/f47e15ccf5bd4c49b35ff10b6541b374/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-05-17 03:02:54.160351 | 2026-05-17 03:02:54.160452 | TASK [tox : Run tox without tests] 2026-05-17 03:02:55.499957 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-05-17 03:03:09.469345 | debian-bookworm | test: OK (14.72 seconds) 2026-05-17 03:03:09.469421 | debian-bookworm | congratulations :) (14.75 seconds) 2026-05-17 03:03:09.710793 | debian-bookworm | ok: Runtime: 0:00:15.053922 2026-05-17 03:03:09.716187 | 2026-05-17 03:03:09.716282 | TASK [tox : Create a tempfile to save tox showconfig] 2026-05-17 03:03:10.131842 | debian-bookworm | changed 2026-05-17 03:03:10.136742 | 2026-05-17 03:03:10.136837 | TASK [tox : Detect tox version] 2026-05-17 03:03:10.845530 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-17 03:03:11.214931 | debian-bookworm | ok: Runtime: 0:00:00.303628 2026-05-17 03:03:11.219850 | 2026-05-17 03:03:11.219934 | TASK [tox : Get tox envlist config] 2026-05-17 03:03:12.279218 | debian-bookworm | ok: Runtime: 0:00:00.367368 2026-05-17 03:03:12.284622 | 2026-05-17 03:03:12.284727 | TASK [tox : Install any sibling python packages] 2026-05-17 03:03:12.934722 | debian-bookworm | ok: No setup.cfg, no action needed 2026-05-17 03:03:12.939700 | 2026-05-17 03:03:12.939778 | TASK [tox : Remove tempfile] 2026-05-17 03:03:13.779693 | debian-bookworm | changed 2026-05-17 03:03:13.786052 | 2026-05-17 03:03:13.786148 | TASK [tox : Emit tox command] 2026-05-17 03:03:13.823963 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-05-17 03:03:13.842454 | 2026-05-17 03:03:13.842570 | TASK [tox : Run tox] 2026-05-17 03:03:14.443716 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-05-17 03:03:15.159451 | debian-bookworm | ............... 2026-05-17 03:03:15.159500 | debian-bookworm | ---------------------------------------------------------------------- 2026-05-17 03:03:15.159512 | debian-bookworm | Ran 15 tests in 0.170s 2026-05-17 03:03:15.159518 | debian-bookworm | 2026-05-17 03:03:15.159525 | debian-bookworm | OK 2026-05-17 03:03:15.253352 | debian-bookworm | test: OK (0.85=setup[0.05]+cmd[0.81] seconds) 2026-05-17 03:03:15.253428 | debian-bookworm | congratulations :) (0.88 seconds) 2026-05-17 03:03:15.387089 | debian-bookworm | ok: Runtime: 0:00:01.186536 2026-05-17 03:03:15.392473 | 2026-05-17 03:03:15.392568 | TASK [tox : Look for output] 2026-05-17 03:03:15.933829 | debian-bookworm | ok 2026-05-17 03:03:15.979819 | 2026-05-17 03:03:15.982617 | TASK [tox : Return file comments to Zuul] 2026-05-17 03:03:16.101806 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:03:16.108615 | 2026-05-17 03:03:16.108723 | PLAY RECAP 2026-05-17 03:03:16.108772 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-05-17 03:03:16.108794 | 2026-05-17 03:03:16.451300 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-17 03:03:16.453929 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-17 03:03:18.840599 | 2026-05-17 03:03:18.840754 | PLAY [all] 2026-05-17 03:03:18.893094 | 2026-05-17 03:03:18.893230 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-05-17 03:03:19.018136 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:03:19.023829 | 2026-05-17 03:03:19.023952 | TASK [fetch-tox-output : Set tox log path for single node] 2026-05-17 03:03:19.118014 | debian-bookworm | ok 2026-05-17 03:03:19.135072 | 2026-05-17 03:03:19.135193 | TASK [fetch-tox-output : Ensure local tox dir] 2026-05-17 03:03:20.459407 | debian-bookworm -> localhost | changed 2026-05-17 03:03:20.468495 | 2026-05-17 03:03:20.468590 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-17 03:03:20.589365 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:03:20.597005 | 2026-05-17 03:03:20.597104 | TASK [fetch-tox-output : Set envlist fact] 2026-05-17 03:03:20.731930 | debian-bookworm | ok 2026-05-17 03:03:20.736997 | 2026-05-17 03:03:20.737086 | TASK [fetch-tox-output : Get tox version] 2026-05-17 03:03:21.914922 | 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-17 03:03:21.932648 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-17 03:03:22.301538 | debian-bookworm | ok: Runtime: 0:00:00.333305 2026-05-17 03:03:22.307299 | 2026-05-17 03:03:22.307402 | TASK [fetch-tox-output : Find all default environments] 2026-05-17 03:03:22.851360 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:03:22.857182 | 2026-05-17 03:03:22.857291 | TASK [fetch-tox-output : Set envlist fact] 2026-05-17 03:03:22.910878 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:03:22.916332 | 2026-05-17 03:03:22.916434 | TASK [fetch-tox-output : Find all default environments] 2026-05-17 03:03:23.479256 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:03:23.486319 | 2026-05-17 03:03:23.486422 | TASK [fetch-tox-output : Set envlist fact] 2026-05-17 03:03:23.529626 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:03:23.535698 | 2026-05-17 03:03:23.535825 | LOOP [fetch-tox-output : Copy tox logs] 2026-05-17 03:03:23.608795 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:03:23.619028 | 2026-05-17 03:03:23.619134 | LOOP [fetch-tox-output : Collect tox logs] 2026-05-17 03:03:24.388205 | debian-bookworm | changed: 2026-05-17 03:03:24.388399 | debian-bookworm | created directory /var/lib/zuul/builds/f47e15ccf5bd4c49b35ff10b6541b374/work/logs/tox/test 2026-05-17 03:03:24.388433 | debian-bookworm | cd+++++++++ ./ 2026-05-17 03:03:24.388472 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-05-17 03:03:24.388503 | 2026-05-17 03:03:24.398045 | 2026-05-17 03:03:24.398140 | PLAY RECAP 2026-05-17 03:03:24.398188 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-05-17 03:03:24.398213 | 2026-05-17 03:03:24.493245 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-17 03:03:24.495877 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-17 03:03:25.092603 | 2026-05-17 03:03:25.092748 | PLAY [all] 2026-05-17 03:03:25.119378 | 2026-05-17 03:03:25.119567 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-17 03:03:25.409003 | debian-bookworm | changed: non-zero return code 2026-05-17 03:03:25.417782 | 2026-05-17 03:03:25.417901 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-17 03:03:25.461146 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:03:25.465896 | 2026-05-17 03:03:25.465993 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-17 03:03:25.504503 | 2026-05-17 03:03:25.504652 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-17 03:03:25.563643 | 2026-05-17 03:03:25.563809 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-17 03:03:25.599253 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:03:25.605063 | 2026-05-17 03:03:25.605158 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-17 03:03:25.673469 | 2026-05-17 03:03:25.673631 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-17 03:03:25.707905 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:03:25.712779 | 2026-05-17 03:03:25.712884 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-17 03:03:25.737002 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:03:25.741836 | 2026-05-17 03:03:25.741921 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-17 03:03:25.764601 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:03:25.770655 | 2026-05-17 03:03:25.770738 | PLAY RECAP 2026-05-17 03:03:25.770793 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-17 03:03:25.770817 | 2026-05-17 03:03:25.997074 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-17 03:03:25.998787 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-17 03:03:27.393681 | 2026-05-17 03:03:27.393859 | PLAY [Base post-fetch] 2026-05-17 03:03:27.426049 | 2026-05-17 03:03:27.426184 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-17 03:03:27.490420 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:03:27.496300 | 2026-05-17 03:03:27.496424 | TASK [fetch-output : Set log path for single node] 2026-05-17 03:03:27.545031 | debian-bookworm | ok 2026-05-17 03:03:27.551306 | 2026-05-17 03:03:27.551407 | LOOP [fetch-output : Ensure local output dirs] 2026-05-17 03:03:28.217908 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/f47e15ccf5bd4c49b35ff10b6541b374/work/logs" 2026-05-17 03:03:28.553858 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f47e15ccf5bd4c49b35ff10b6541b374/work/artifacts" 2026-05-17 03:03:28.864464 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f47e15ccf5bd4c49b35ff10b6541b374/work/docs" 2026-05-17 03:03:28.880255 | 2026-05-17 03:03:28.880359 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-17 03:03:29.660695 | debian-bookworm | changed: 2026-05-17 03:03:29.662931 | debian-bookworm | .d..t...... ./ 2026-05-17 03:03:29.663022 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-17 03:03:29.663058 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-17 03:03:29.663099 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-17 03:03:29.663154 | debian-bookworm | changed: All items complete 2026-05-17 03:03:29.663182 | 2026-05-17 03:03:30.335760 | debian-bookworm | changed: .d..t...... ./ 2026-05-17 03:03:30.943995 | debian-bookworm | changed: .d..t...... ./ 2026-05-17 03:03:30.963871 | 2026-05-17 03:03:30.963988 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-17 03:03:30.987518 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:03:30.991395 | debian-bookworm | skipping: Conditional result was False 2026-05-17 03:03:31.008767 | 2026-05-17 03:03:31.008858 | PLAY RECAP 2026-05-17 03:03:31.008908 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-17 03:03:31.008932 | 2026-05-17 03:03:31.193545 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-17 03:03:31.195085 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-17 03:03:32.373175 | 2026-05-17 03:03:32.373330 | PLAY [Base post] 2026-05-17 03:03:32.408864 | 2026-05-17 03:03:32.409011 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-17 03:03:33.096128 | debian-bookworm | changed 2026-05-17 03:03:33.120961 | 2026-05-17 03:03:33.121124 | PLAY RECAP 2026-05-17 03:03:33.121198 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-17 03:03:33.121264 | 2026-05-17 03:03:34.003337 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-17 03:03:34.004916 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-17 03:03:35.435514 | 2026-05-17 03:03:35.435635 | PLAY [Base post-logs] 2026-05-17 03:03:35.444051 | 2026-05-17 03:03:35.444154 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-17 03:03:36.291709 | localhost | changed 2026-05-17 03:03:36.300953 | 2026-05-17 03:03:36.301086 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-17 03:03:36.356076 | localhost | ok 2026-05-17 03:03:36.358829 | 2026-05-17 03:03:36.359194 | TASK [Set zuul-log-path fact] 2026-05-17 03:03:36.391558 | localhost | ok 2026-05-17 03:03:36.399256 | 2026-05-17 03:03:36.399356 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-17 03:03:36.447051 | localhost | ok 2026-05-17 03:03:36.449838 | 2026-05-17 03:03:36.449919 | TASK [upload-logs : Create log directories] 2026-05-17 03:03:37.023994 | localhost | changed 2026-05-17 03:03:37.026507 | 2026-05-17 03:03:37.026597 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-17 03:03:37.553571 | localhost -> localhost | ok: Runtime: 0:00:00.003409 2026-05-17 03:03:37.558436 | 2026-05-17 03:03:37.558529 | TASK [upload-logs : Upload logs to log server] 2026-05-17 03:03:38.041724 | localhost | Output suppressed because no_log was given 2026-05-17 03:03:38.043569 | 2026-05-17 03:03:38.043659 | LOOP [upload-logs : Compress console log and json output] 2026-05-17 03:03:38.091855 | localhost | skipping: Conditional result was False 2026-05-17 03:03:38.098331 | localhost | skipping: Conditional result was False 2026-05-17 03:03:38.112694 | 2026-05-17 03:03:38.113094 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-17 03:03:38.155826 | localhost | skipping: Conditional result was False 2026-05-17 03:03:38.156071 | 2026-05-17 03:03:38.160820 | localhost | skipping: Conditional result was False 2026-05-17 03:03:38.175624 | 2026-05-17 03:03:38.175924 | LOOP [upload-logs : Upload console log and json output]