2026-05-30 17:43:41.524584 | Job console starting 2026-05-30 17:43:41.564406 | Updating git repos 2026-05-30 17:43:41.635855 | Cloning repos into workspace 2026-05-30 17:43:41.675547 | Restoring repo states 2026-05-30 17:43:41.715306 | Merging changes 2026-05-30 17:43:42.332535 | Checking out repos 2026-05-30 17:43:42.420681 | Preparing playbooks 2026-05-30 17:43:43.954784 | Running Ansible setup 2026-05-30 17:43:49.318677 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-30 17:43:50.295061 | 2026-05-30 17:43:50.295230 | PLAY [Base pre] 2026-05-30 17:43:50.336082 | 2026-05-30 17:43:50.336251 | TASK [Setup log path fact] 2026-05-30 17:43:50.369368 | debian-bookworm | ok 2026-05-30 17:43:50.414295 | 2026-05-30 17:43:50.414513 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-30 17:43:50.474031 | debian-bookworm | ok 2026-05-30 17:43:50.492721 | 2026-05-30 17:43:50.492871 | TASK [emit-job-header : Print job information] 2026-05-30 17:43:50.588949 | # Job Information 2026-05-30 17:43:50.589160 | Ansible Version: 2.16.14 2026-05-30 17:43:50.589197 | Job: tox 2026-05-30 17:43:50.589229 | Pipeline: check 2026-05-30 17:43:50.589252 | Executor: 521e9411259a 2026-05-30 17:43:50.589273 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1223 2026-05-30 17:43:50.589295 | Event ID: 1357f7e0-5c4f-11f1-919f-103d351c2be7 2026-05-30 17:43:50.594678 | 2026-05-30 17:43:50.594816 | LOOP [emit-job-header : Print node information] 2026-05-30 17:43:50.773161 | debian-bookworm | ok: 2026-05-30 17:43:50.773386 | debian-bookworm | # Node Information 2026-05-30 17:43:50.773424 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-30 17:43:50.773449 | debian-bookworm | Hostname: debian 2026-05-30 17:43:50.773471 | debian-bookworm | Username: zuul 2026-05-30 17:43:50.773491 | debian-bookworm | Distro: Debian 12.14 2026-05-30 17:43:50.773516 | debian-bookworm | Provider: regiocloud-a 2026-05-30 17:43:50.773537 | debian-bookworm | Region: 2026-05-30 17:43:50.773558 | debian-bookworm | Label: debian-bookworm 2026-05-30 17:43:50.773578 | debian-bookworm | Product Name: OpenStack Nova 2026-05-30 17:43:50.773598 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fed4:a09e 2026-05-30 17:43:50.802213 | 2026-05-30 17:43:50.802390 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-30 17:43:51.760561 | debian-bookworm -> localhost | changed 2026-05-30 17:43:51.768134 | 2026-05-30 17:43:51.768270 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-30 17:43:53.568630 | debian-bookworm -> localhost | changed 2026-05-30 17:43:53.587718 | 2026-05-30 17:43:53.587869 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-30 17:43:54.101203 | debian-bookworm -> localhost | ok 2026-05-30 17:43:54.108668 | 2026-05-30 17:43:54.108807 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-30 17:43:54.150039 | debian-bookworm | ok 2026-05-30 17:43:54.166650 | debian-bookworm | included: /var/lib/zuul/builds/45d592738bac40c1abce08f83787aebc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-30 17:43:54.174929 | 2026-05-30 17:43:54.175041 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-30 17:43:56.050625 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-30 17:43:56.050927 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/45d592738bac40c1abce08f83787aebc/work/45d592738bac40c1abce08f83787aebc_id_rsa 2026-05-30 17:43:56.050970 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/45d592738bac40c1abce08f83787aebc/work/45d592738bac40c1abce08f83787aebc_id_rsa.pub 2026-05-30 17:43:56.050998 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-30 17:43:56.051025 | debian-bookworm -> localhost | SHA256:l3h7HKjs9Xad3iksKO+nnfz6XTPQ8394qKY559AE0uI zuul-build-sshkey 2026-05-30 17:43:56.051047 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-30 17:43:56.051081 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-30 17:43:56.051103 | debian-bookworm -> localhost | | | 2026-05-30 17:43:56.051126 | debian-bookworm -> localhost | | . | 2026-05-30 17:43:56.051146 | debian-bookworm -> localhost | | o o | 2026-05-30 17:43:56.051166 | debian-bookworm -> localhost | | . + + . | 2026-05-30 17:43:56.051186 | debian-bookworm -> localhost | | E = o. o | 2026-05-30 17:43:56.051214 | debian-bookworm -> localhost | | . + = .. o| 2026-05-30 17:43:56.051235 | debian-bookworm -> localhost | | o = = *+| 2026-05-30 17:43:56.051255 | debian-bookworm -> localhost | | o o+B=o=.@| 2026-05-30 17:43:56.051276 | debian-bookworm -> localhost | | =+=%O=o=+| 2026-05-30 17:43:56.051297 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-30 17:43:56.051374 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.019275 2026-05-30 17:43:56.058041 | 2026-05-30 17:43:56.058164 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-30 17:43:56.088051 | debian-bookworm | ok 2026-05-30 17:43:56.097880 | debian-bookworm | included: /var/lib/zuul/builds/45d592738bac40c1abce08f83787aebc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-30 17:43:56.108456 | 2026-05-30 17:43:56.108594 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-30 17:43:56.132995 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:43:56.139273 | 2026-05-30 17:43:56.139415 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-30 17:43:56.899179 | debian-bookworm | changed 2026-05-30 17:43:56.904970 | 2026-05-30 17:43:56.905084 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-30 17:43:57.201618 | debian-bookworm | ok 2026-05-30 17:43:57.207432 | 2026-05-30 17:43:57.207560 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-30 17:43:57.911883 | debian-bookworm | changed 2026-05-30 17:43:57.924876 | 2026-05-30 17:43:57.925058 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-30 17:43:58.686602 | debian-bookworm | changed 2026-05-30 17:43:58.696309 | 2026-05-30 17:43:58.696513 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-30 17:43:58.721783 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:43:58.744801 | 2026-05-30 17:43:58.744976 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-30 17:43:59.397556 | debian-bookworm -> localhost | changed 2026-05-30 17:43:59.417988 | 2026-05-30 17:43:59.418153 | TASK [add-build-sshkey : Add back temp key] 2026-05-30 17:43:59.888986 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/45d592738bac40c1abce08f83787aebc/work/45d592738bac40c1abce08f83787aebc_id_rsa (zuul-build-sshkey) 2026-05-30 17:43:59.889260 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011205 2026-05-30 17:43:59.899472 | 2026-05-30 17:43:59.899614 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-30 17:44:00.327350 | debian-bookworm | ok 2026-05-30 17:44:00.333725 | 2026-05-30 17:44:00.333869 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-30 17:44:00.368204 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:44:00.380004 | 2026-05-30 17:44:00.380140 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-30 17:44:00.886797 | debian-bookworm | ok 2026-05-30 17:44:00.898413 | 2026-05-30 17:44:00.898540 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-30 17:44:00.927905 | debian-bookworm | ok 2026-05-30 17:44:00.940400 | 2026-05-30 17:44:00.940538 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-30 17:44:01.286221 | debian-bookworm -> localhost | ok 2026-05-30 17:44:01.299472 | 2026-05-30 17:44:01.299637 | TASK [validate-host : Collect information about the host] 2026-05-30 17:44:02.707781 | debian-bookworm | ok 2026-05-30 17:44:02.721577 | 2026-05-30 17:44:02.721706 | TASK [validate-host : Sanitize hostname] 2026-05-30 17:44:02.847196 | debian-bookworm | ok 2026-05-30 17:44:02.923241 | 2026-05-30 17:44:02.923406 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-30 17:44:03.616924 | debian-bookworm -> localhost | changed 2026-05-30 17:44:03.625775 | 2026-05-30 17:44:03.625929 | TASK [validate-host : Collect information about zuul worker] 2026-05-30 17:44:04.087941 | debian-bookworm | ok 2026-05-30 17:44:04.093103 | 2026-05-30 17:44:04.093230 | TASK [validate-host : Write out all zuul information for each host] 2026-05-30 17:44:04.753452 | debian-bookworm -> localhost | changed 2026-05-30 17:44:04.763361 | 2026-05-30 17:44:04.763490 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-30 17:44:05.043244 | debian-bookworm | ok 2026-05-30 17:44:05.058628 | 2026-05-30 17:44:05.058771 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-30 17:44:06.060564 | debian-bookworm | changed: 2026-05-30 17:44:06.061107 | debian-bookworm | cd+++++++++ src/ 2026-05-30 17:44:06.061175 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-30 17:44:06.061218 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-30 17:44:06.061254 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-30 17:44:06.061288 | debian-bookworm | =7.0.3 2026-05-30 17:44:30.868042 | debian-bookworm | Downloading cachetools-7.1.4-py3-none-any.whl (16 kB) 2026-05-30 17:44:30.909438 | debian-bookworm | Collecting colorama>=0.4.6 2026-05-30 17:44:30.921096 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-05-30 17:44:30.982739 | debian-bookworm | Collecting filelock>=3.25 2026-05-30 17:44:30.995000 | debian-bookworm | Downloading filelock-3.29.0-py3-none-any.whl (39 kB) 2026-05-30 17:44:31.046107 | debian-bookworm | Collecting packaging>=26 2026-05-30 17:44:31.057384 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-05-30 17:44:31.069497 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 9.9 MB/s eta 0:00:00 2026-05-30 17:44:31.119022 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-05-30 17:44:31.130708 | debian-bookworm | Downloading platformdirs-4.10.0-py3-none-any.whl (22 kB) 2026-05-30 17:44:31.172182 | debian-bookworm | Collecting pluggy>=1.6 2026-05-30 17:44:31.183587 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-05-30 17:44:31.227159 | debian-bookworm | Collecting pyproject-api>=1.10 2026-05-30 17:44:31.239507 | debian-bookworm | Downloading pyproject_api-1.10.1-py3-none-any.whl (12 kB) 2026-05-30 17:44:31.308258 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-05-30 17:44:31.319764 | debian-bookworm | Downloading python_discovery-1.4.0-py3-none-any.whl (33 kB) 2026-05-30 17:44:31.355649 | debian-bookworm | Collecting tomli-w>=1.2 2026-05-30 17:44:31.368080 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-05-30 17:44:31.521894 | debian-bookworm | Collecting virtualenv>=21.1 2026-05-30 17:44:31.536058 | debian-bookworm | Downloading virtualenv-21.4.1-py3-none-any.whl (7.6 MB) 2026-05-30 17:44:31.882286 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 22.3 MB/s eta 0:00:00 2026-05-30 17:44:31.999708 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-05-30 17:44:32.011537 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-05-30 17:44:32.034571 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 23.9 MB/s eta 0:00:00 2026-05-30 17:44:32.156611 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-05-30 17:44:32.937142 | 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.10.0 pluggy-1.6.0 pyproject-api-1.10.1 python-discovery-1.4.0 tomli-w-1.2.0 tox-4.55.0 virtualenv-21.4.1 2026-05-30 17:44:33.294048 | debian-bookworm | ok: Runtime: 0:00:03.035013 2026-05-30 17:44:33.300623 | 2026-05-30 17:44:33.300736 | TASK [ensure-tox : Export installed tox_executable path] 2026-05-30 17:44:33.332473 | debian-bookworm | ok 2026-05-30 17:44:33.338168 | 2026-05-30 17:44:33.338327 | TASK [ensure-tox : Output tox version] 2026-05-30 17:44:33.921233 | 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-30 17:44:33.938855 | debian-bookworm | 4.55.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-30 17:44:34.375529 | debian-bookworm | ok: Runtime: 0:00:00.319668 2026-05-30 17:44:34.381533 | 2026-05-30 17:44:34.381641 | TASK [ensure-tox : Make global symlink] 2026-05-30 17:44:34.432537 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:44:34.442519 | 2026-05-30 17:44:34.442624 | PLAY RECAP 2026-05-30 17:44:34.442682 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-05-30 17:44:34.442708 | 2026-05-30 17:44:34.633043 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-05-30 17:44:34.634170 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-30 17:44:35.410798 | 2026-05-30 17:44:35.411006 | PLAY [all] 2026-05-30 17:44:35.435156 | 2026-05-30 17:44:35.435334 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-05-30 17:44:35.956423 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-30 17:44:36.005353 | debian-bookworm | ok: Runtime: 0:00:00.032081 2026-05-30 17:44:36.011481 | 2026-05-30 17:44:36.011653 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-05-30 17:44:36.541891 | debian-bookworm | ok: Runtime: 0:00:00.007694 2026-05-30 17:44:36.548160 | 2026-05-30 17:44:36.548294 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-05-30 17:44:36.834129 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-30 17:44:36.836577 | debian-bookworm | sudo: a password is required 2026-05-30 17:44:37.079001 | debian-bookworm | ok: Runtime: 0:00:00.021911 2026-05-30 17:44:37.089702 | 2026-05-30 17:44:37.089832 | TASK [tox : Check to see if the constraints file exists] 2026-05-30 17:44:37.114513 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:44:37.120921 | 2026-05-30 17:44:37.121042 | TASK [tox : Fail if constraints file is missing] 2026-05-30 17:44:37.145228 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:44:37.151798 | 2026-05-30 17:44:37.151927 | TASK [tox : Record file location] 2026-05-30 17:44:37.176095 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:44:37.182174 | 2026-05-30 17:44:37.182300 | TASK [tox : Install tox siblings] 2026-05-30 17:44:37.222023 | debian-bookworm | ok 2026-05-30 17:44:37.236711 | debian-bookworm | included: /var/lib/zuul/builds/45d592738bac40c1abce08f83787aebc/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-05-30 17:44:37.244696 | 2026-05-30 17:44:37.244838 | TASK [tox : Run tox without tests] 2026-05-30 17:44:38.699740 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-05-30 17:44:51.175205 | debian-bookworm | test: OK (13.33 seconds) 2026-05-30 17:44:51.175255 | debian-bookworm | congratulations :) (13.36 seconds) 2026-05-30 17:44:51.300219 | debian-bookworm | ok: Runtime: 0:00:13.658666 2026-05-30 17:44:51.313607 | 2026-05-30 17:44:51.313830 | TASK [tox : Create a tempfile to save tox showconfig] 2026-05-30 17:44:51.709046 | debian-bookworm | changed 2026-05-30 17:44:51.714482 | 2026-05-30 17:44:51.714596 | TASK [tox : Detect tox version] 2026-05-30 17:44:52.243370 | debian-bookworm | 4.55.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-30 17:44:52.746196 | debian-bookworm | ok: Runtime: 0:00:00.299697 2026-05-30 17:44:52.758504 | 2026-05-30 17:44:52.758677 | TASK [tox : Get tox envlist config] 2026-05-30 17:44:53.793297 | debian-bookworm | ok: Runtime: 0:00:00.269545 2026-05-30 17:44:53.806129 | 2026-05-30 17:44:53.806307 | TASK [tox : Install any sibling python packages] 2026-05-30 17:44:54.255645 | debian-bookworm | ok: No setup.cfg, no action needed 2026-05-30 17:44:54.263816 | 2026-05-30 17:44:54.263938 | TASK [tox : Remove tempfile] 2026-05-30 17:44:54.725965 | debian-bookworm | changed 2026-05-30 17:44:54.731811 | 2026-05-30 17:44:54.731921 | TASK [tox : Emit tox command] 2026-05-30 17:44:54.781928 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-05-30 17:44:54.787151 | 2026-05-30 17:44:54.787267 | TASK [tox : Run tox] 2026-05-30 17:44:55.350854 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-05-30 17:44:56.067392 | debian-bookworm | ............... 2026-05-30 17:44:56.067453 | debian-bookworm | ---------------------------------------------------------------------- 2026-05-30 17:44:56.067466 | debian-bookworm | Ran 15 tests in 0.173s 2026-05-30 17:44:56.067471 | debian-bookworm | 2026-05-30 17:44:56.067479 | debian-bookworm | OK 2026-05-30 17:44:56.179379 | debian-bookworm | test: OK (0.87=setup[0.04]+cmd[0.82] seconds) 2026-05-30 17:44:56.179448 | debian-bookworm | congratulations :) (0.90 seconds) 2026-05-30 17:44:56.323846 | debian-bookworm | ok: Runtime: 0:00:01.177456 2026-05-30 17:44:56.334758 | 2026-05-30 17:44:56.334969 | TASK [tox : Look for output] 2026-05-30 17:44:56.731970 | debian-bookworm | ok 2026-05-30 17:44:56.746299 | 2026-05-30 17:44:56.746471 | TASK [tox : Return file comments to Zuul] 2026-05-30 17:44:56.781460 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:44:56.788088 | 2026-05-30 17:44:56.788179 | PLAY RECAP 2026-05-30 17:44:56.788237 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-05-30 17:44:56.788263 | 2026-05-30 17:44:56.923017 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-30 17:44:56.924360 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-30 17:44:57.693342 | 2026-05-30 17:44:57.693565 | PLAY [all] 2026-05-30 17:44:57.709778 | 2026-05-30 17:44:57.709938 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-05-30 17:44:57.755322 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:44:57.761343 | 2026-05-30 17:44:57.761516 | TASK [fetch-tox-output : Set tox log path for single node] 2026-05-30 17:44:57.815017 | debian-bookworm | ok 2026-05-30 17:44:57.822562 | 2026-05-30 17:44:57.822714 | TASK [fetch-tox-output : Ensure local tox dir] 2026-05-30 17:44:58.304277 | debian-bookworm -> localhost | changed 2026-05-30 17:44:58.319322 | 2026-05-30 17:44:58.319514 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-30 17:44:58.374928 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:44:58.388053 | 2026-05-30 17:44:58.388237 | TASK [fetch-tox-output : Set envlist fact] 2026-05-30 17:44:58.428082 | debian-bookworm | ok 2026-05-30 17:44:58.433979 | 2026-05-30 17:44:58.434092 | TASK [fetch-tox-output : Get tox version] 2026-05-30 17:44:59.128360 | 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-30 17:44:59.146056 | debian-bookworm | 4.55.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-30 17:44:59.463562 | debian-bookworm | ok: Runtime: 0:00:00.321418 2026-05-30 17:44:59.477339 | 2026-05-30 17:44:59.477592 | TASK [fetch-tox-output : Find all default environments] 2026-05-30 17:45:00.013564 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:45:00.027959 | 2026-05-30 17:45:00.028189 | TASK [fetch-tox-output : Set envlist fact] 2026-05-30 17:45:00.065255 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:45:00.073033 | 2026-05-30 17:45:00.073186 | TASK [fetch-tox-output : Find all default environments] 2026-05-30 17:45:00.602415 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:45:00.608967 | 2026-05-30 17:45:00.609104 | TASK [fetch-tox-output : Set envlist fact] 2026-05-30 17:45:00.644638 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:45:00.657572 | 2026-05-30 17:45:00.657741 | LOOP [fetch-tox-output : Copy tox logs] 2026-05-30 17:45:00.707969 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:45:00.728358 | 2026-05-30 17:45:00.728570 | LOOP [fetch-tox-output : Collect tox logs] 2026-05-30 17:45:01.544029 | debian-bookworm | changed: 2026-05-30 17:45:01.544242 | debian-bookworm | created directory /var/lib/zuul/builds/45d592738bac40c1abce08f83787aebc/work/logs/tox/test 2026-05-30 17:45:01.544278 | debian-bookworm | cd+++++++++ ./ 2026-05-30 17:45:01.544303 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-05-30 17:45:01.544333 | 2026-05-30 17:45:01.563486 | 2026-05-30 17:45:01.563634 | PLAY RECAP 2026-05-30 17:45:01.563714 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-05-30 17:45:01.563747 | 2026-05-30 17:45:01.706987 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-30 17:45:01.708058 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-30 17:45:02.472693 | 2026-05-30 17:45:02.472895 | PLAY [all] 2026-05-30 17:45:02.489588 | 2026-05-30 17:45:02.489773 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-30 17:45:02.739862 | debian-bookworm | changed: non-zero return code 2026-05-30 17:45:02.748692 | 2026-05-30 17:45:02.748874 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-30 17:45:02.774374 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:45:02.785123 | 2026-05-30 17:45:02.785300 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-30 17:45:02.819364 | 2026-05-30 17:45:02.819642 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-30 17:45:02.867655 | 2026-05-30 17:45:02.868006 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-30 17:45:02.893747 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:45:02.900530 | 2026-05-30 17:45:02.900684 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-30 17:45:02.934689 | 2026-05-30 17:45:02.935000 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-30 17:45:02.960756 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:45:02.974887 | 2026-05-30 17:45:02.975106 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-30 17:45:03.000901 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:45:03.012859 | 2026-05-30 17:45:03.013067 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-30 17:45:03.038625 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:45:03.053469 | 2026-05-30 17:45:03.053611 | PLAY RECAP 2026-05-30 17:45:03.053694 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-30 17:45:03.053737 | 2026-05-30 17:45:03.198660 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-30 17:45:03.199810 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-30 17:45:03.931710 | 2026-05-30 17:45:03.931901 | PLAY [Base post-fetch] 2026-05-30 17:45:03.947532 | 2026-05-30 17:45:03.947692 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-30 17:45:04.003367 | debian-bookworm | skipping: Conditional result was False 2026-05-30 17:45:04.015963 | 2026-05-30 17:45:04.016242 | TASK [fetch-output : Set log path for single node] 2026-05-30 17:45:04.049891 | debian-bookworm | ok 2026-05-30 17:45:04.057494 | 2026-05-30 17:45:04.057646 | LOOP [fetch-output : Ensure local output dirs] 2026-05-30 17:45:04.570442 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/45d592738bac40c1abce08f83787aebc/work/logs" 2026-05-30 17:45:04.843013 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/45d592738bac40c1abce08f83787aebc/work/artifacts" 2026-05-30 17:45:05.098632 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/45d592738bac40c1abce08f83787aebc/work/docs" 2026-05-30 17:45:05.114495 | 2026-05-30 17:45:05.114634 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-30 17:45:05.918878 | debian-bookworm | changed: 2026-05-30 17:45:05.919184 | debian-bookworm | .d..t...... ./ 2026-05-30 17:45:05.919236 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-30 17:45:05.919281 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-30 17:45:05.919320 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-30 17:45:05.919385 | debian-bookworm | changed: All items complete 2026-05-30 17:45:05.919450 | 2026-05-30 17:45:06.502555 | debian-bookworm | changed: .d..t...... ./ 2026-05-30 17:45:07.053205 | debian-bookworm | changed: .d..t...... ./ 2026-05-30 17:45:07.066693 | 2026-05-30 17:45:07.066827 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-30 17:45:07.563326 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010194 2026-05-30 17:45:07.838179 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.012477 2026-05-30 17:45:07.855161 | 2026-05-30 17:45:07.855366 | PLAY RECAP 2026-05-30 17:45:07.855443 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-30 17:45:07.855471 | 2026-05-30 17:45:07.984632 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-30 17:45:07.985788 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-30 17:45:08.736354 | 2026-05-30 17:45:08.736542 | PLAY [Base post] 2026-05-30 17:45:08.750872 | 2026-05-30 17:45:08.751024 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-30 17:45:09.367917 | debian-bookworm | changed 2026-05-30 17:45:09.377755 | 2026-05-30 17:45:09.377901 | PLAY RECAP 2026-05-30 17:45:09.377979 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-30 17:45:09.378059 | 2026-05-30 17:45:09.503498 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-30 17:45:09.505351 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-30 17:45:10.331988 | 2026-05-30 17:45:10.332174 | PLAY [Base post-logs] 2026-05-30 17:45:10.343750 | 2026-05-30 17:45:10.343905 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-30 17:45:10.796159 | localhost | changed 2026-05-30 17:45:10.808683 | 2026-05-30 17:45:10.808842 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-30 17:45:10.838234 | localhost | ok 2026-05-30 17:45:10.844220 | 2026-05-30 17:45:10.844388 | TASK [Set zuul-log-path fact] 2026-05-30 17:45:10.862038 | localhost | ok 2026-05-30 17:45:10.872637 | 2026-05-30 17:45:10.872751 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-30 17:45:10.908715 | localhost | ok 2026-05-30 17:45:10.913521 | 2026-05-30 17:45:10.913653 | TASK [upload-logs : Create log directories] 2026-05-30 17:45:11.415638 | localhost | changed 2026-05-30 17:45:11.420579 | 2026-05-30 17:45:11.420744 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-30 17:45:11.944486 | localhost -> localhost | ok: Runtime: 0:00:00.007671 2026-05-30 17:45:11.948909 | 2026-05-30 17:45:11.949029 | TASK [upload-logs : Upload logs to log server] 2026-05-30 17:45:12.533778 | localhost | Output suppressed because no_log was given 2026-05-30 17:45:12.536008 | 2026-05-30 17:45:12.536128 | LOOP [upload-logs : Compress console log and json output] 2026-05-30 17:45:12.588901 | localhost | skipping: Conditional result was False 2026-05-30 17:45:12.597470 | localhost | skipping: Conditional result was False 2026-05-30 17:45:12.607188 | 2026-05-30 17:45:12.607596 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-30 17:45:12.654320 | localhost | skipping: Conditional result was False 2026-05-30 17:45:12.654909 | 2026-05-30 17:45:12.658385 | localhost | skipping: Conditional result was False 2026-05-30 17:45:12.672541 | 2026-05-30 17:45:12.672773 | LOOP [upload-logs : Upload console log and json output]