2026-06-08 16:43:06.229413 | Job console starting 2026-06-08 16:43:06.255407 | Updating git repos 2026-06-08 16:43:06.351247 | Cloning repos into workspace 2026-06-08 16:43:06.410305 | Restoring repo states 2026-06-08 16:43:06.433664 | Merging changes 2026-06-08 16:43:06.976183 | Checking out repos 2026-06-08 16:43:07.052667 | Preparing playbooks 2026-06-08 16:43:08.188131 | Running Ansible setup 2026-06-08 16:43:12.997542 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-06-08 16:43:13.917954 | 2026-06-08 16:43:13.918118 | PLAY [Base pre] 2026-06-08 16:43:13.949549 | 2026-06-08 16:43:13.949841 | TASK [Setup log path fact] 2026-06-08 16:43:13.980855 | debian-bookworm | ok 2026-06-08 16:43:14.013701 | 2026-06-08 16:43:14.013879 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-08 16:43:14.065491 | debian-bookworm | ok 2026-06-08 16:43:14.077064 | 2026-06-08 16:43:14.077976 | TASK [emit-job-header : Print job information] 2026-06-08 16:43:14.118538 | # Job Information 2026-06-08 16:43:14.118750 | Ansible Version: 2.16.14 2026-06-08 16:43:14.118786 | Job: tox 2026-06-08 16:43:14.118818 | Pipeline: check 2026-06-08 16:43:14.118864 | Executor: 521e9411259a 2026-06-08 16:43:14.118886 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1231 2026-06-08 16:43:14.118908 | Event ID: 1a211190-6359-11f1-847f-3f6c6e6a692b 2026-06-08 16:43:14.124691 | 2026-06-08 16:43:14.124812 | LOOP [emit-job-header : Print node information] 2026-06-08 16:43:14.237636 | debian-bookworm | ok: 2026-06-08 16:43:14.237865 | debian-bookworm | # Node Information 2026-06-08 16:43:14.237901 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-06-08 16:43:14.237926 | debian-bookworm | Hostname: debian 2026-06-08 16:43:14.237947 | debian-bookworm | Username: zuul 2026-06-08 16:43:14.237968 | debian-bookworm | Distro: Debian 12.14 2026-06-08 16:43:14.237991 | debian-bookworm | Provider: regiocloud-a 2026-06-08 16:43:14.238011 | debian-bookworm | Region: 2026-06-08 16:43:14.238032 | debian-bookworm | Label: debian-bookworm 2026-06-08 16:43:14.238052 | debian-bookworm | Product Name: OpenStack Nova 2026-06-08 16:43:14.238071 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe98:b14d 2026-06-08 16:43:14.255538 | 2026-06-08 16:43:14.255695 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-08 16:43:14.851734 | debian-bookworm -> localhost | changed 2026-06-08 16:43:14.866870 | 2026-06-08 16:43:14.869033 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-08 16:43:16.408469 | debian-bookworm -> localhost | changed 2026-06-08 16:43:16.418855 | 2026-06-08 16:43:16.418960 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-08 16:43:16.819964 | debian-bookworm -> localhost | ok 2026-06-08 16:43:16.825803 | 2026-06-08 16:43:16.825910 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-08 16:43:16.855586 | debian-bookworm | ok 2026-06-08 16:43:16.882074 | debian-bookworm | included: /var/lib/zuul/builds/038f967a4a3445fc88e5090b119fa879/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-08 16:43:16.896317 | 2026-06-08 16:43:16.896423 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-08 16:43:18.634244 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-06-08 16:43:18.634576 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/038f967a4a3445fc88e5090b119fa879/work/038f967a4a3445fc88e5090b119fa879_id_rsa 2026-06-08 16:43:18.634666 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/038f967a4a3445fc88e5090b119fa879/work/038f967a4a3445fc88e5090b119fa879_id_rsa.pub 2026-06-08 16:43:18.634717 | debian-bookworm -> localhost | The key fingerprint is: 2026-06-08 16:43:18.634765 | debian-bookworm -> localhost | SHA256:aitMf+CpCaYGNAZnYo3sr58e8H19smtqDiul1byhhI8 zuul-build-sshkey 2026-06-08 16:43:18.634809 | debian-bookworm -> localhost | The key's randomart image is: 2026-06-08 16:43:18.634909 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-06-08 16:43:18.634958 | debian-bookworm -> localhost | |. o | 2026-06-08 16:43:18.635001 | debian-bookworm -> localhost | |o+o. | 2026-06-08 16:43:18.635041 | debian-bookworm -> localhost | |++ | 2026-06-08 16:43:18.635080 | debian-bookworm -> localhost | | = | 2026-06-08 16:43:18.635119 | debian-bookworm -> localhost | |o.o . o S | 2026-06-08 16:43:18.635164 | debian-bookworm -> localhost | |. ooo+.=. | 2026-06-08 16:43:18.635204 | debian-bookworm -> localhost | |. +=B=++oo . | 2026-06-08 16:43:18.635241 | debian-bookworm -> localhost | | = E==B+..+ | 2026-06-08 16:43:18.635281 | debian-bookworm -> localhost | |o o+++++oo. | 2026-06-08 16:43:18.635320 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-06-08 16:43:18.635403 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.176612 2026-06-08 16:43:18.645621 | 2026-06-08 16:43:18.645752 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-08 16:43:18.668822 | debian-bookworm | ok 2026-06-08 16:43:18.679882 | debian-bookworm | included: /var/lib/zuul/builds/038f967a4a3445fc88e5090b119fa879/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-08 16:43:18.688479 | 2026-06-08 16:43:18.688559 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-08 16:43:18.708107 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:43:18.717047 | 2026-06-08 16:43:18.717168 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-08 16:43:19.358098 | debian-bookworm | changed 2026-06-08 16:43:19.365358 | 2026-06-08 16:43:19.365454 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-08 16:43:19.640409 | debian-bookworm | ok 2026-06-08 16:43:19.647011 | 2026-06-08 16:43:19.647120 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-08 16:43:20.353714 | debian-bookworm | changed 2026-06-08 16:43:20.363805 | 2026-06-08 16:43:20.364041 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-08 16:43:21.161359 | debian-bookworm | changed 2026-06-08 16:43:21.167759 | 2026-06-08 16:43:21.167878 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-08 16:43:21.205807 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:43:21.212254 | 2026-06-08 16:43:21.212396 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-08 16:43:21.735735 | debian-bookworm -> localhost | changed 2026-06-08 16:43:21.747018 | 2026-06-08 16:43:21.747156 | TASK [add-build-sshkey : Add back temp key] 2026-06-08 16:43:22.116216 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/038f967a4a3445fc88e5090b119fa879/work/038f967a4a3445fc88e5090b119fa879_id_rsa (zuul-build-sshkey) 2026-06-08 16:43:22.116787 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.018633 2026-06-08 16:43:22.132118 | 2026-06-08 16:43:22.132253 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-08 16:43:22.603206 | debian-bookworm | ok 2026-06-08 16:43:22.609481 | 2026-06-08 16:43:22.609654 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-08 16:43:22.634450 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:43:22.646589 | 2026-06-08 16:43:22.646781 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-08 16:43:23.124212 | debian-bookworm | ok 2026-06-08 16:43:23.135584 | 2026-06-08 16:43:23.135734 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-08 16:43:23.175888 | debian-bookworm | ok 2026-06-08 16:43:23.182704 | 2026-06-08 16:43:23.182825 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-08 16:43:23.489181 | debian-bookworm -> localhost | ok 2026-06-08 16:43:23.496207 | 2026-06-08 16:43:23.496338 | TASK [validate-host : Collect information about the host] 2026-06-08 16:43:24.683578 | debian-bookworm | ok 2026-06-08 16:43:24.698522 | 2026-06-08 16:43:24.698706 | TASK [validate-host : Sanitize hostname] 2026-06-08 16:43:24.827663 | debian-bookworm | ok 2026-06-08 16:43:24.877364 | 2026-06-08 16:43:24.877519 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-08 16:43:25.453152 | debian-bookworm -> localhost | changed 2026-06-08 16:43:25.459349 | 2026-06-08 16:43:25.459476 | TASK [validate-host : Collect information about zuul worker] 2026-06-08 16:43:25.871252 | debian-bookworm | ok 2026-06-08 16:43:25.877848 | 2026-06-08 16:43:25.877994 | TASK [validate-host : Write out all zuul information for each host] 2026-06-08 16:43:26.668017 | debian-bookworm -> localhost | changed 2026-06-08 16:43:26.678963 | 2026-06-08 16:43:26.679113 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-06-08 16:43:26.963792 | debian-bookworm | ok 2026-06-08 16:43:26.973205 | 2026-06-08 16:43:26.973472 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-06-08 16:43:27.890175 | debian-bookworm | changed: 2026-06-08 16:43:27.890454 | debian-bookworm | cd+++++++++ src/ 2026-06-08 16:43:27.890492 | debian-bookworm | cd+++++++++ src/github.com/ 2026-06-08 16:43:27.890517 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-06-08 16:43:27.890539 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-06-08 16:43:27.890560 | debian-bookworm | =7.0.3 2026-06-08 16:43:53.300354 | debian-bookworm | Downloading cachetools-7.1.4-py3-none-any.whl (16 kB) 2026-06-08 16:43:53.357692 | debian-bookworm | Collecting colorama>=0.4.6 2026-06-08 16:43:53.371346 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-06-08 16:43:53.439987 | debian-bookworm | Collecting filelock>=3.25 2026-06-08 16:43:53.451735 | debian-bookworm | Downloading filelock-3.29.1-py3-none-any.whl (40 kB) 2026-06-08 16:43:53.459944 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 40.8/40.8 kB 6.1 MB/s eta 0:00:00 2026-06-08 16:43:53.515705 | debian-bookworm | Collecting packaging>=26 2026-06-08 16:43:53.527334 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-06-08 16:43:53.539180 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 10.9 MB/s eta 0:00:00 2026-06-08 16:43:53.589339 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-06-08 16:43:53.601299 | debian-bookworm | Downloading platformdirs-4.10.0-py3-none-any.whl (22 kB) 2026-06-08 16:43:53.656720 | debian-bookworm | Collecting pluggy>=1.6 2026-06-08 16:43:53.668565 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-06-08 16:43:53.712517 | debian-bookworm | Collecting pyproject-api>=1.10 2026-06-08 16:43:53.725010 | debian-bookworm | Downloading pyproject_api-1.10.1-py3-none-any.whl (12 kB) 2026-06-08 16:43:53.761542 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-06-08 16:43:53.773128 | debian-bookworm | Downloading python_discovery-1.4.0-py3-none-any.whl (33 kB) 2026-06-08 16:43:53.806019 | debian-bookworm | Collecting tomli-w>=1.2 2026-06-08 16:43:53.817767 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-06-08 16:43:53.954370 | debian-bookworm | Collecting virtualenv>=21.1 2026-06-08 16:43:53.966057 | debian-bookworm | Downloading virtualenv-21.4.2-py3-none-any.whl (7.6 MB) 2026-06-08 16:43:54.308582 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 22.5 MB/s eta 0:00:00 2026-06-08 16:43:54.414034 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-06-08 16:43:54.425843 | debian-bookworm | Downloading distlib-0.4.2-py2.py3-none-any.whl (470 kB) 2026-06-08 16:43:54.448832 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.5/470.5 kB 23.3 MB/s eta 0:00:00 2026-06-08 16:43:54.555731 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-06-08 16:43:55.400197 | debian-bookworm | Successfully installed cachetools-7.1.4 colorama-0.4.6 distlib-0.4.2 filelock-3.29.1 packaging-26.2 platformdirs-4.10.0 pluggy-1.6.0 pyproject-api-1.10.1 python-discovery-1.4.0 tomli-w-1.2.0 tox-4.55.1 virtualenv-21.4.2 2026-06-08 16:43:55.648450 | debian-bookworm | ok: Runtime: 0:00:03.121331 2026-06-08 16:43:55.655459 | 2026-06-08 16:43:55.655602 | TASK [ensure-tox : Export installed tox_executable path] 2026-06-08 16:43:55.688207 | debian-bookworm | ok 2026-06-08 16:43:55.693780 | 2026-06-08 16:43:55.693912 | TASK [ensure-tox : Output tox version] 2026-06-08 16:43:56.256514 | 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-06-08 16:43:56.274299 | debian-bookworm | 4.55.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-06-08 16:43:56.725886 | debian-bookworm | ok: Runtime: 0:00:00.333069 2026-06-08 16:43:56.739139 | 2026-06-08 16:43:56.739314 | TASK [ensure-tox : Make global symlink] 2026-06-08 16:43:56.771455 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:43:56.781735 | 2026-06-08 16:43:56.781859 | PLAY RECAP 2026-06-08 16:43:56.781922 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-06-08 16:43:56.781952 | 2026-06-08 16:43:56.964508 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-06-08 16:43:56.965639 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-06-08 16:43:57.708571 | 2026-06-08 16:43:57.708757 | PLAY [all] 2026-06-08 16:43:57.738204 | 2026-06-08 16:43:57.738378 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-06-08 16:43:58.256718 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-06-08 16:43:58.320692 | debian-bookworm | ok: Runtime: 0:00:00.034149 2026-06-08 16:43:58.328416 | 2026-06-08 16:43:58.328636 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-06-08 16:43:58.856856 | debian-bookworm | ok: Runtime: 0:00:00.005645 2026-06-08 16:43:58.863089 | 2026-06-08 16:43:58.863222 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-06-08 16:43:59.171861 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-06-08 16:43:59.173362 | debian-bookworm | sudo: a password is required 2026-06-08 16:43:59.405622 | debian-bookworm | ok: Runtime: 0:00:00.019538 2026-06-08 16:43:59.415777 | 2026-06-08 16:43:59.415886 | TASK [tox : Check to see if the constraints file exists] 2026-06-08 16:43:59.461078 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:43:59.467914 | 2026-06-08 16:43:59.468048 | TASK [tox : Fail if constraints file is missing] 2026-06-08 16:43:59.492421 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:43:59.499092 | 2026-06-08 16:43:59.499216 | TASK [tox : Record file location] 2026-06-08 16:43:59.523523 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:43:59.529836 | 2026-06-08 16:43:59.529970 | TASK [tox : Install tox siblings] 2026-06-08 16:43:59.562886 | debian-bookworm | ok 2026-06-08 16:43:59.571845 | debian-bookworm | included: /var/lib/zuul/builds/038f967a4a3445fc88e5090b119fa879/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-06-08 16:43:59.577523 | 2026-06-08 16:43:59.577641 | TASK [tox : Run tox without tests] 2026-06-08 16:44:00.875042 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-06-08 16:44:14.319083 | debian-bookworm | test: OK (14.18 seconds) 2026-06-08 16:44:14.319177 | debian-bookworm | congratulations :) (14.21 seconds) 2026-06-08 16:44:14.616742 | debian-bookworm | ok: Runtime: 0:00:14.521793 2026-06-08 16:44:14.629308 | 2026-06-08 16:44:14.629542 | TASK [tox : Create a tempfile to save tox showconfig] 2026-06-08 16:44:15.065813 | debian-bookworm | changed 2026-06-08 16:44:15.074190 | 2026-06-08 16:44:15.074397 | TASK [tox : Detect tox version] 2026-06-08 16:44:15.697406 | debian-bookworm | 4.55.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-06-08 16:44:16.109872 | debian-bookworm | ok: Runtime: 0:00:00.340873 2026-06-08 16:44:16.120000 | 2026-06-08 16:44:16.120161 | TASK [tox : Get tox envlist config] 2026-06-08 16:44:17.154590 | debian-bookworm | ok: Runtime: 0:00:00.369390 2026-06-08 16:44:17.167375 | 2026-06-08 16:44:17.167545 | TASK [tox : Install any sibling python packages] 2026-06-08 16:44:17.646115 | debian-bookworm | ok: No setup.cfg, no action needed 2026-06-08 16:44:17.655237 | 2026-06-08 16:44:17.655429 | TASK [tox : Remove tempfile] 2026-06-08 16:44:18.136858 | debian-bookworm | changed 2026-06-08 16:44:18.142987 | 2026-06-08 16:44:18.143119 | TASK [tox : Emit tox command] 2026-06-08 16:44:18.198958 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-06-08 16:44:18.206578 | 2026-06-08 16:44:18.206723 | TASK [tox : Run tox] 2026-06-08 16:44:18.848289 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-06-08 16:44:19.592251 | debian-bookworm | ............... 2026-06-08 16:44:19.592313 | debian-bookworm | ---------------------------------------------------------------------- 2026-06-08 16:44:19.592327 | debian-bookworm | Ran 15 tests in 0.175s 2026-06-08 16:44:19.592333 | debian-bookworm | 2026-06-08 16:44:19.592341 | debian-bookworm | OK 2026-06-08 16:44:19.704559 | debian-bookworm | test: OK (0.90=setup[0.05]+cmd[0.85] seconds) 2026-06-08 16:44:19.704671 | debian-bookworm | congratulations :) (0.93 seconds) 2026-06-08 16:44:20.241079 | debian-bookworm | ok: Runtime: 0:00:01.244018 2026-06-08 16:44:20.254517 | 2026-06-08 16:44:20.254696 | TASK [tox : Look for output] 2026-06-08 16:44:20.726408 | debian-bookworm | ok 2026-06-08 16:44:20.744116 | 2026-06-08 16:44:20.744246 | TASK [tox : Return file comments to Zuul] 2026-06-08 16:44:20.789155 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:44:20.801895 | 2026-06-08 16:44:20.802011 | PLAY RECAP 2026-06-08 16:44:20.802091 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-06-08 16:44:20.802131 | 2026-06-08 16:44:20.947601 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-06-08 16:44:20.950034 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-06-08 16:44:21.743897 | 2026-06-08 16:44:21.744074 | PLAY [all] 2026-06-08 16:44:21.759704 | 2026-06-08 16:44:21.759861 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-06-08 16:44:21.825942 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:44:21.839957 | 2026-06-08 16:44:21.840204 | TASK [fetch-tox-output : Set tox log path for single node] 2026-06-08 16:44:21.889488 | debian-bookworm | ok 2026-06-08 16:44:21.898242 | 2026-06-08 16:44:21.898432 | TASK [fetch-tox-output : Ensure local tox dir] 2026-06-08 16:44:22.409938 | debian-bookworm -> localhost | changed 2026-06-08 16:44:22.418404 | 2026-06-08 16:44:22.418528 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-06-08 16:44:22.473570 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:44:22.486486 | 2026-06-08 16:44:22.486676 | TASK [fetch-tox-output : Set envlist fact] 2026-06-08 16:44:22.545079 | debian-bookworm | ok 2026-06-08 16:44:22.555166 | 2026-06-08 16:44:22.555340 | TASK [fetch-tox-output : Get tox version] 2026-06-08 16:44:23.310626 | 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-06-08 16:44:23.328312 | debian-bookworm | 4.55.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-06-08 16:44:23.587783 | debian-bookworm | ok: Runtime: 0:00:00.365377 2026-06-08 16:44:23.600483 | 2026-06-08 16:44:23.600663 | TASK [fetch-tox-output : Find all default environments] 2026-06-08 16:44:24.135171 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:44:24.142211 | 2026-06-08 16:44:24.142396 | TASK [fetch-tox-output : Set envlist fact] 2026-06-08 16:44:24.187499 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:44:24.199594 | 2026-06-08 16:44:24.199781 | TASK [fetch-tox-output : Find all default environments] 2026-06-08 16:44:24.735000 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:44:24.749007 | 2026-06-08 16:44:24.749202 | TASK [fetch-tox-output : Set envlist fact] 2026-06-08 16:44:24.795228 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:44:24.808939 | 2026-06-08 16:44:24.809114 | LOOP [fetch-tox-output : Copy tox logs] 2026-06-08 16:44:24.864524 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:44:24.880946 | 2026-06-08 16:44:24.881131 | LOOP [fetch-tox-output : Collect tox logs] 2026-06-08 16:44:25.686367 | debian-bookworm | changed: 2026-06-08 16:44:25.686620 | debian-bookworm | created directory /var/lib/zuul/builds/038f967a4a3445fc88e5090b119fa879/work/logs/tox/test 2026-06-08 16:44:25.686660 | debian-bookworm | cd+++++++++ ./ 2026-06-08 16:44:25.686686 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-06-08 16:44:25.686717 | 2026-06-08 16:44:25.709073 | 2026-06-08 16:44:25.709228 | PLAY RECAP 2026-06-08 16:44:25.709338 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-06-08 16:44:25.709381 | 2026-06-08 16:44:25.848637 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-06-08 16:44:25.849779 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-08 16:44:26.609036 | 2026-06-08 16:44:26.609211 | PLAY [all] 2026-06-08 16:44:26.625136 | 2026-06-08 16:44:26.625334 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-06-08 16:44:26.868703 | debian-bookworm | changed: non-zero return code 2026-06-08 16:44:26.874478 | 2026-06-08 16:44:26.874614 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-06-08 16:44:26.900051 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:44:26.908147 | 2026-06-08 16:44:26.908347 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-06-08 16:44:26.954578 | 2026-06-08 16:44:26.954887 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-06-08 16:44:26.988882 | 2026-06-08 16:44:26.989109 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-06-08 16:44:27.013321 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:44:27.026447 | 2026-06-08 16:44:27.026610 | LOOP [fetch-subunit-output : Generate subunit file] 2026-06-08 16:44:27.081103 | 2026-06-08 16:44:27.081382 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-06-08 16:44:27.107262 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:44:27.122457 | 2026-06-08 16:44:27.122655 | TASK [fetch-subunit-output : Remove the temporary file] 2026-06-08 16:44:27.148066 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:44:27.159930 | 2026-06-08 16:44:27.160114 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-06-08 16:44:27.195764 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:44:27.202420 | 2026-06-08 16:44:27.202513 | PLAY RECAP 2026-06-08 16:44:27.202569 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-06-08 16:44:27.202594 | 2026-06-08 16:44:27.335262 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-08 16:44:27.336661 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-06-08 16:44:28.111283 | 2026-06-08 16:44:28.111506 | PLAY [Base post-fetch] 2026-06-08 16:44:28.131503 | 2026-06-08 16:44:28.131663 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-08 16:44:28.177156 | debian-bookworm | skipping: Conditional result was False 2026-06-08 16:44:28.185415 | 2026-06-08 16:44:28.185628 | TASK [fetch-output : Set log path for single node] 2026-06-08 16:44:28.232574 | debian-bookworm | ok 2026-06-08 16:44:28.241135 | 2026-06-08 16:44:28.241377 | LOOP [fetch-output : Ensure local output dirs] 2026-06-08 16:44:28.775848 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/038f967a4a3445fc88e5090b119fa879/work/logs" 2026-06-08 16:44:29.109436 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/038f967a4a3445fc88e5090b119fa879/work/artifacts" 2026-06-08 16:44:29.390983 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/038f967a4a3445fc88e5090b119fa879/work/docs" 2026-06-08 16:44:29.405860 | 2026-06-08 16:44:29.405991 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-08 16:44:30.164219 | debian-bookworm | changed: 2026-06-08 16:44:30.164531 | debian-bookworm | .d..t...... ./ 2026-06-08 16:44:30.164580 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-06-08 16:44:30.164621 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-06-08 16:44:30.164658 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-06-08 16:44:30.164709 | debian-bookworm | changed: All items complete 2026-06-08 16:44:30.164743 | 2026-06-08 16:44:30.727641 | debian-bookworm | changed: .d..t...... ./ 2026-06-08 16:44:31.341758 | debian-bookworm | changed: .d..t...... ./ 2026-06-08 16:44:31.368154 | 2026-06-08 16:44:31.368375 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-08 16:44:31.952880 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.011405 2026-06-08 16:44:32.224459 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.007695 2026-06-08 16:44:32.243625 | 2026-06-08 16:44:32.243768 | PLAY RECAP 2026-06-08 16:44:32.243849 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-08 16:44:32.243888 | 2026-06-08 16:44:32.383185 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-06-08 16:44:32.386026 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-08 16:44:33.113981 | 2026-06-08 16:44:33.114153 | PLAY [Base post] 2026-06-08 16:44:33.128081 | 2026-06-08 16:44:33.128223 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-08 16:44:33.718165 | debian-bookworm | changed 2026-06-08 16:44:33.727618 | 2026-06-08 16:44:33.727783 | PLAY RECAP 2026-06-08 16:44:33.727885 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-08 16:44:33.727982 | 2026-06-08 16:44:33.851211 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-08 16:44:33.852366 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-08 16:44:34.637629 | 2026-06-08 16:44:34.637821 | PLAY [Base post-logs] 2026-06-08 16:44:34.649100 | 2026-06-08 16:44:34.649289 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-08 16:44:35.114428 | localhost | changed 2026-06-08 16:44:35.128333 | 2026-06-08 16:44:35.128498 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-08 16:44:35.156940 | localhost | ok 2026-06-08 16:44:35.162085 | 2026-06-08 16:44:35.162255 | TASK [Set zuul-log-path fact] 2026-06-08 16:44:35.179554 | localhost | ok 2026-06-08 16:44:35.190582 | 2026-06-08 16:44:35.190781 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-08 16:44:35.218291 | localhost | ok 2026-06-08 16:44:35.224171 | 2026-06-08 16:44:35.224337 | TASK [upload-logs : Create log directories] 2026-06-08 16:44:35.732098 | localhost | changed 2026-06-08 16:44:35.734970 | 2026-06-08 16:44:35.735075 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-08 16:44:36.265135 | localhost -> localhost | ok: Runtime: 0:00:00.009227 2026-06-08 16:44:36.274732 | 2026-06-08 16:44:36.274952 | TASK [upload-logs : Upload logs to log server] 2026-06-08 16:44:36.861958 | localhost | Output suppressed because no_log was given 2026-06-08 16:44:36.865549 | 2026-06-08 16:44:36.865735 | LOOP [upload-logs : Compress console log and json output] 2026-06-08 16:44:36.929609 | localhost | skipping: Conditional result was False 2026-06-08 16:44:36.938393 | localhost | skipping: Conditional result was False 2026-06-08 16:44:36.950672 | 2026-06-08 16:44:36.950935 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-08 16:44:37.000619 | localhost | skipping: Conditional result was False 2026-06-08 16:44:37.001411 | 2026-06-08 16:44:37.004664 | localhost | skipping: Conditional result was False 2026-06-08 16:44:37.011484 | 2026-06-08 16:44:37.011695 | LOOP [upload-logs : Upload console log and json output]