2026-05-17 00:40:36.452109 | Job console starting 2026-05-17 00:40:36.467257 | Updating git repos 2026-05-17 00:40:36.535411 | Cloning repos into workspace 2026-05-17 00:40:36.601418 | Restoring repo states 2026-05-17 00:40:36.621889 | Merging changes 2026-05-17 00:40:37.254344 | Checking out repos 2026-05-17 00:40:37.342392 | Preparing playbooks 2026-05-17 00:40:38.750186 | Running Ansible setup 2026-05-17 00:40:43.658674 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-17 00:40:44.526392 | 2026-05-17 00:40:44.526531 | PLAY [Base pre] 2026-05-17 00:40:44.545257 | 2026-05-17 00:40:44.545389 | TASK [Setup log path fact] 2026-05-17 00:40:44.575077 | debian-bookworm | ok 2026-05-17 00:40:44.595254 | 2026-05-17 00:40:44.595398 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-17 00:40:44.644033 | debian-bookworm | ok 2026-05-17 00:40:44.658665 | 2026-05-17 00:40:44.658784 | TASK [emit-job-header : Print job information] 2026-05-17 00:40:44.717334 | # Job Information 2026-05-17 00:40:44.717500 | Ansible Version: 2.16.14 2026-05-17 00:40:44.717536 | Job: tox 2026-05-17 00:40:44.717569 | Pipeline: check 2026-05-17 00:40:44.717592 | Executor: 521e9411259a 2026-05-17 00:40:44.717612 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1210 2026-05-17 00:40:44.717634 | Event ID: ff2c9530-5188-11f1-8fbc-5aeb4003ebb2 2026-05-17 00:40:44.722334 | 2026-05-17 00:40:44.722427 | LOOP [emit-job-header : Print node information] 2026-05-17 00:40:44.889213 | debian-bookworm | ok: 2026-05-17 00:40:44.889379 | debian-bookworm | # Node Information 2026-05-17 00:40:44.889412 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-17 00:40:44.889437 | debian-bookworm | Hostname: debian 2026-05-17 00:40:44.889459 | debian-bookworm | Username: zuul 2026-05-17 00:40:44.889479 | debian-bookworm | Distro: Debian 12.14 2026-05-17 00:40:44.889502 | debian-bookworm | Provider: regiocloud-a 2026-05-17 00:40:44.889524 | debian-bookworm | Region: 2026-05-17 00:40:44.889544 | debian-bookworm | Label: debian-bookworm 2026-05-17 00:40:44.889563 | debian-bookworm | Product Name: OpenStack Nova 2026-05-17 00:40:44.889582 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feae:9e36 2026-05-17 00:40:44.909067 | 2026-05-17 00:40:44.909184 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-17 00:40:45.592721 | debian-bookworm -> localhost | changed 2026-05-17 00:40:45.599581 | 2026-05-17 00:40:45.599686 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-17 00:40:46.736644 | debian-bookworm -> localhost | changed 2026-05-17 00:40:46.745280 | 2026-05-17 00:40:46.745435 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-17 00:40:47.121687 | debian-bookworm -> localhost | ok 2026-05-17 00:40:47.126592 | 2026-05-17 00:40:47.126677 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-17 00:40:47.164587 | debian-bookworm | ok 2026-05-17 00:40:47.186515 | debian-bookworm | included: /var/lib/zuul/builds/214edf9b3fd04abf842aa21ef0fb91ae/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-17 00:40:47.194705 | 2026-05-17 00:40:47.194781 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-17 00:40:48.872558 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-17 00:40:48.872735 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/214edf9b3fd04abf842aa21ef0fb91ae/work/214edf9b3fd04abf842aa21ef0fb91ae_id_rsa 2026-05-17 00:40:48.872766 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/214edf9b3fd04abf842aa21ef0fb91ae/work/214edf9b3fd04abf842aa21ef0fb91ae_id_rsa.pub 2026-05-17 00:40:48.872787 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-17 00:40:48.872807 | debian-bookworm -> localhost | SHA256:5K1xrsa4wk00xvCCNmAxpr3uBDQHYdR45/yXttDNNhM zuul-build-sshkey 2026-05-17 00:40:48.872825 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-17 00:40:48.872849 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-17 00:40:48.872867 | debian-bookworm -> localhost | |.O= | 2026-05-17 00:40:48.872885 | debian-bookworm -> localhost | |=+oo.. | 2026-05-17 00:40:48.872902 | debian-bookworm -> localhost | |o+oo++ . | 2026-05-17 00:40:48.872944 | debian-bookworm -> localhost | |. *..o*o . E | 2026-05-17 00:40:48.872963 | debian-bookworm -> localhost | |.... +..S * . | 2026-05-17 00:40:48.872987 | debian-bookworm -> localhost | | o .o X * | 2026-05-17 00:40:48.873004 | debian-bookworm -> localhost | | o. o o= + o | 2026-05-17 00:40:48.873020 | debian-bookworm -> localhost | | o o o oo | 2026-05-17 00:40:48.873036 | debian-bookworm -> localhost | | . ..o. | 2026-05-17 00:40:48.873053 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-17 00:40:48.873108 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.826856 2026-05-17 00:40:48.878404 | 2026-05-17 00:40:48.878478 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-17 00:40:48.918578 | debian-bookworm | ok 2026-05-17 00:40:48.929201 | debian-bookworm | included: /var/lib/zuul/builds/214edf9b3fd04abf842aa21ef0fb91ae/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-17 00:40:48.936539 | 2026-05-17 00:40:48.936622 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-17 00:40:48.949560 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:40:48.956807 | 2026-05-17 00:40:48.956890 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-17 00:40:49.591591 | debian-bookworm | changed 2026-05-17 00:40:49.595963 | 2026-05-17 00:40:49.596035 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-17 00:40:49.880613 | debian-bookworm | ok 2026-05-17 00:40:49.887699 | 2026-05-17 00:40:49.887811 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-17 00:40:50.612369 | debian-bookworm | changed 2026-05-17 00:40:50.622222 | 2026-05-17 00:40:50.622339 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-17 00:40:51.292535 | debian-bookworm | changed 2026-05-17 00:40:51.304520 | 2026-05-17 00:40:51.304641 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-17 00:40:51.328825 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:40:51.337887 | 2026-05-17 00:40:51.338131 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-17 00:40:52.095617 | debian-bookworm -> localhost | changed 2026-05-17 00:40:52.110752 | 2026-05-17 00:40:52.110885 | TASK [add-build-sshkey : Add back temp key] 2026-05-17 00:40:52.824108 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/214edf9b3fd04abf842aa21ef0fb91ae/work/214edf9b3fd04abf842aa21ef0fb91ae_id_rsa (zuul-build-sshkey) 2026-05-17 00:40:52.824324 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.029773 2026-05-17 00:40:52.832556 | 2026-05-17 00:40:52.832657 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-17 00:40:53.351641 | debian-bookworm | ok 2026-05-17 00:40:53.361234 | 2026-05-17 00:40:53.361339 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-17 00:40:53.409415 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:40:53.426216 | 2026-05-17 00:40:53.426335 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-17 00:40:54.022929 | debian-bookworm | ok 2026-05-17 00:40:54.045357 | 2026-05-17 00:40:54.045471 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-17 00:40:54.134649 | debian-bookworm | ok 2026-05-17 00:40:54.145834 | 2026-05-17 00:40:54.145968 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-17 00:40:54.722438 | debian-bookworm -> localhost | ok 2026-05-17 00:40:54.730967 | 2026-05-17 00:40:54.731106 | TASK [validate-host : Collect information about the host] 2026-05-17 00:40:55.913845 | debian-bookworm | ok 2026-05-17 00:40:55.926864 | 2026-05-17 00:40:55.926996 | TASK [validate-host : Sanitize hostname] 2026-05-17 00:40:56.016035 | debian-bookworm | ok 2026-05-17 00:40:56.050176 | 2026-05-17 00:40:56.050281 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-17 00:40:56.963358 | debian-bookworm -> localhost | changed 2026-05-17 00:40:56.968906 | 2026-05-17 00:40:56.969017 | TASK [validate-host : Collect information about zuul worker] 2026-05-17 00:40:57.321143 | debian-bookworm | ok 2026-05-17 00:40:57.325735 | 2026-05-17 00:40:57.325835 | TASK [validate-host : Write out all zuul information for each host] 2026-05-17 00:40:58.262975 | debian-bookworm -> localhost | changed 2026-05-17 00:40:58.273082 | 2026-05-17 00:40:58.273183 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-17 00:40:58.508562 | debian-bookworm | ok 2026-05-17 00:40:58.523695 | 2026-05-17 00:40:58.523800 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-17 00:40:59.472184 | debian-bookworm | changed: 2026-05-17 00:40:59.472334 | debian-bookworm | cd+++++++++ src/ 2026-05-17 00:40:59.472362 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-17 00:40:59.472382 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-17 00:40:59.472400 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-17 00:40:59.472417 | debian-bookworm | =7.0.3 2026-05-17 00:41:25.338584 | debian-bookworm | Downloading cachetools-7.1.2-py3-none-any.whl (16 kB) 2026-05-17 00:41:25.382146 | debian-bookworm | Collecting colorama>=0.4.6 2026-05-17 00:41:25.394030 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-05-17 00:41:25.455984 | debian-bookworm | Collecting filelock>=3.25 2026-05-17 00:41:25.467899 | debian-bookworm | Downloading filelock-3.29.0-py3-none-any.whl (39 kB) 2026-05-17 00:41:25.520725 | debian-bookworm | Collecting packaging>=26 2026-05-17 00:41:25.532154 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-05-17 00:41:25.543568 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 11.2 MB/s eta 0:00:00 2026-05-17 00:41:25.594142 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-05-17 00:41:25.606211 | debian-bookworm | Downloading platformdirs-4.9.6-py3-none-any.whl (21 kB) 2026-05-17 00:41:25.648155 | debian-bookworm | Collecting pluggy>=1.6 2026-05-17 00:41:25.659921 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-05-17 00:41:25.822638 | debian-bookworm | Collecting pyproject-api>=1.10 2026-05-17 00:41:25.835923 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-05-17 00:41:25.874709 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-05-17 00:41:25.886124 | debian-bookworm | Downloading python_discovery-1.3.1-py3-none-any.whl (33 kB) 2026-05-17 00:41:25.922330 | debian-bookworm | Collecting tomli-w>=1.2 2026-05-17 00:41:25.933785 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-05-17 00:41:26.166919 | debian-bookworm | Collecting virtualenv>=21.1 2026-05-17 00:41:26.182848 | debian-bookworm | Downloading virtualenv-21.3.3-py3-none-any.whl (7.6 MB) 2026-05-17 00:41:26.538972 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 21.7 MB/s eta 0:00:00 2026-05-17 00:41:26.654394 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-05-17 00:41:26.665967 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-05-17 00:41:26.688215 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 24.3 MB/s eta 0:00:00 2026-05-17 00:41:26.803785 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-05-17 00:41:27.589750 | 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 00:41:28.051886 | debian-bookworm | ok: Runtime: 0:00:03.515597 2026-05-17 00:41:28.062268 | 2026-05-17 00:41:28.062430 | TASK [ensure-tox : Export installed tox_executable path] 2026-05-17 00:41:28.120659 | debian-bookworm | ok 2026-05-17 00:41:28.130020 | 2026-05-17 00:41:28.130202 | TASK [ensure-tox : Output tox version] 2026-05-17 00:41:28.700704 | 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 00:41:28.718604 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-17 00:41:29.165754 | debian-bookworm | ok: Runtime: 0:00:00.347486 2026-05-17 00:41:29.171764 | 2026-05-17 00:41:29.171881 | TASK [ensure-tox : Make global symlink] 2026-05-17 00:41:29.196114 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:41:29.203250 | 2026-05-17 00:41:29.203351 | PLAY RECAP 2026-05-17 00:41:29.203409 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-05-17 00:41:29.203434 | 2026-05-17 00:41:29.357354 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-05-17 00:41:29.359074 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-17 00:41:30.228835 | 2026-05-17 00:41:30.229051 | PLAY [all] 2026-05-17 00:41:30.267425 | 2026-05-17 00:41:30.267601 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-05-17 00:41:30.856091 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-17 00:41:31.332855 | debian-bookworm | ok: Runtime: 0:00:00.026143 2026-05-17 00:41:31.340072 | 2026-05-17 00:41:31.340227 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-05-17 00:41:31.881472 | debian-bookworm | ok: Runtime: 0:00:00.006334 2026-05-17 00:41:31.888690 | 2026-05-17 00:41:31.888833 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-05-17 00:41:32.334694 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-17 00:41:32.335577 | debian-bookworm | sudo: a password is required 2026-05-17 00:41:32.453019 | debian-bookworm | ok: Runtime: 0:00:00.019229 2026-05-17 00:41:32.463571 | 2026-05-17 00:41:32.463695 | TASK [tox : Check to see if the constraints file exists] 2026-05-17 00:41:32.501025 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:41:32.507585 | 2026-05-17 00:41:32.507727 | TASK [tox : Fail if constraints file is missing] 2026-05-17 00:41:32.542327 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:41:32.549437 | 2026-05-17 00:41:32.549578 | TASK [tox : Record file location] 2026-05-17 00:41:32.575160 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:41:32.582301 | 2026-05-17 00:41:32.582456 | TASK [tox : Install tox siblings] 2026-05-17 00:41:32.616470 | debian-bookworm | ok 2026-05-17 00:41:32.635047 | debian-bookworm | included: /var/lib/zuul/builds/214edf9b3fd04abf842aa21ef0fb91ae/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-05-17 00:41:32.651388 | 2026-05-17 00:41:32.651529 | TASK [tox : Run tox without tests] 2026-05-17 00:41:34.052324 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-05-17 00:41:46.277794 | debian-bookworm | test: OK (13.02 seconds) 2026-05-17 00:41:46.277866 | debian-bookworm | congratulations :) (13.05 seconds) 2026-05-17 00:41:46.721584 | debian-bookworm | ok: Runtime: 0:00:13.364556 2026-05-17 00:41:46.728255 | 2026-05-17 00:41:46.728380 | TASK [tox : Create a tempfile to save tox showconfig] 2026-05-17 00:41:47.180786 | debian-bookworm | changed 2026-05-17 00:41:47.186232 | 2026-05-17 00:41:47.186338 | TASK [tox : Detect tox version] 2026-05-17 00:41:47.715215 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-17 00:41:48.215940 | debian-bookworm | ok: Runtime: 0:00:00.284048 2026-05-17 00:41:48.221941 | 2026-05-17 00:41:48.222087 | TASK [tox : Get tox envlist config] 2026-05-17 00:41:49.255376 | debian-bookworm | ok: Runtime: 0:00:00.329591 2026-05-17 00:41:49.269185 | 2026-05-17 00:41:49.269373 | TASK [tox : Install any sibling python packages] 2026-05-17 00:41:49.756256 | debian-bookworm | ok: No setup.cfg, no action needed 2026-05-17 00:41:49.762051 | 2026-05-17 00:41:49.762162 | TASK [tox : Remove tempfile] 2026-05-17 00:41:50.237528 | debian-bookworm | changed 2026-05-17 00:41:50.243706 | 2026-05-17 00:41:50.243826 | TASK [tox : Emit tox command] 2026-05-17 00:41:50.283555 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-05-17 00:41:50.289887 | 2026-05-17 00:41:50.290058 | TASK [tox : Run tox] 2026-05-17 00:41:50.862943 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-05-17 00:41:51.544084 | debian-bookworm | ............... 2026-05-17 00:41:51.544178 | debian-bookworm | ---------------------------------------------------------------------- 2026-05-17 00:41:51.544206 | debian-bookworm | Ran 15 tests in 0.171s 2026-05-17 00:41:51.544221 | debian-bookworm | 2026-05-17 00:41:51.544238 | debian-bookworm | OK 2026-05-17 00:41:51.612335 | debian-bookworm | test: OK (0.79=setup[0.04]+cmd[0.75] seconds) 2026-05-17 00:41:51.612453 | debian-bookworm | congratulations :) (0.82 seconds) 2026-05-17 00:41:51.823076 | debian-bookworm | ok: Runtime: 0:00:01.079939 2026-05-17 00:41:51.831454 | 2026-05-17 00:41:51.831591 | TASK [tox : Look for output] 2026-05-17 00:41:52.264551 | debian-bookworm | ok 2026-05-17 00:41:52.295026 | 2026-05-17 00:41:52.295183 | TASK [tox : Return file comments to Zuul] 2026-05-17 00:41:52.343303 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:41:52.352058 | 2026-05-17 00:41:52.352187 | PLAY RECAP 2026-05-17 00:41:52.352247 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-05-17 00:41:52.352273 | 2026-05-17 00:41:52.581301 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-17 00:41:52.583383 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-17 00:41:53.427954 | 2026-05-17 00:41:53.428153 | PLAY [all] 2026-05-17 00:41:53.458160 | 2026-05-17 00:41:53.458348 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-05-17 00:41:53.503910 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:41:53.510635 | 2026-05-17 00:41:53.510825 | TASK [fetch-tox-output : Set tox log path for single node] 2026-05-17 00:41:53.552151 | debian-bookworm | ok 2026-05-17 00:41:53.557318 | 2026-05-17 00:41:53.557438 | TASK [fetch-tox-output : Ensure local tox dir] 2026-05-17 00:41:54.068385 | debian-bookworm -> localhost | changed 2026-05-17 00:41:54.088822 | 2026-05-17 00:41:54.089038 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-17 00:41:54.134527 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:41:54.140831 | 2026-05-17 00:41:54.140981 | TASK [fetch-tox-output : Set envlist fact] 2026-05-17 00:41:54.198903 | debian-bookworm | ok 2026-05-17 00:41:54.205643 | 2026-05-17 00:41:54.205756 | TASK [fetch-tox-output : Get tox version] 2026-05-17 00:41:54.931841 | 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 00:41:54.950246 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-17 00:41:55.235579 | debian-bookworm | ok: Runtime: 0:00:00.345051 2026-05-17 00:41:55.244148 | 2026-05-17 00:41:55.244288 | TASK [fetch-tox-output : Find all default environments] 2026-05-17 00:41:55.802033 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:41:55.816867 | 2026-05-17 00:41:55.817657 | TASK [fetch-tox-output : Set envlist fact] 2026-05-17 00:41:55.860699 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:41:55.872026 | 2026-05-17 00:41:55.872161 | TASK [fetch-tox-output : Find all default environments] 2026-05-17 00:41:56.416257 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:41:56.431497 | 2026-05-17 00:41:56.431693 | TASK [fetch-tox-output : Set envlist fact] 2026-05-17 00:41:56.467593 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:41:56.478303 | 2026-05-17 00:41:56.478474 | LOOP [fetch-tox-output : Copy tox logs] 2026-05-17 00:41:56.539225 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:41:56.560450 | 2026-05-17 00:41:56.560639 | LOOP [fetch-tox-output : Collect tox logs] 2026-05-17 00:41:57.393354 | debian-bookworm | changed: 2026-05-17 00:41:57.393563 | debian-bookworm | created directory /var/lib/zuul/builds/214edf9b3fd04abf842aa21ef0fb91ae/work/logs/tox/test 2026-05-17 00:41:57.393597 | debian-bookworm | cd+++++++++ ./ 2026-05-17 00:41:57.393622 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-05-17 00:41:57.393651 | 2026-05-17 00:41:57.405050 | 2026-05-17 00:41:57.405173 | PLAY RECAP 2026-05-17 00:41:57.405229 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-05-17 00:41:57.405253 | 2026-05-17 00:41:57.561524 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-17 00:41:57.562610 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-17 00:41:58.392852 | 2026-05-17 00:41:58.393056 | PLAY [all] 2026-05-17 00:41:58.411521 | 2026-05-17 00:41:58.411702 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-17 00:41:58.665589 | debian-bookworm | changed: non-zero return code 2026-05-17 00:41:58.671831 | 2026-05-17 00:41:58.672032 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-17 00:41:58.696767 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:41:58.702768 | 2026-05-17 00:41:58.702937 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-17 00:41:58.749669 | 2026-05-17 00:41:58.749889 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-17 00:41:58.793605 | 2026-05-17 00:41:58.793847 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-17 00:41:58.818332 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:41:58.830070 | 2026-05-17 00:41:58.830247 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-17 00:41:58.867786 | 2026-05-17 00:41:58.868161 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-17 00:41:58.893595 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:41:58.907234 | 2026-05-17 00:41:58.907429 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-17 00:41:58.923667 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:41:58.935860 | 2026-05-17 00:41:58.936091 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-17 00:41:58.961563 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:41:58.978372 | 2026-05-17 00:41:58.978534 | PLAY RECAP 2026-05-17 00:41:58.978711 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-17 00:41:58.978761 | 2026-05-17 00:41:59.129106 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-17 00:41:59.131892 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-17 00:41:59.973095 | 2026-05-17 00:41:59.973287 | PLAY [Base post-fetch] 2026-05-17 00:41:59.992849 | 2026-05-17 00:41:59.993069 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-17 00:42:00.070017 | debian-bookworm | skipping: Conditional result was False 2026-05-17 00:42:00.076158 | 2026-05-17 00:42:00.076333 | TASK [fetch-output : Set log path for single node] 2026-05-17 00:42:00.115714 | debian-bookworm | ok 2026-05-17 00:42:00.121154 | 2026-05-17 00:42:00.121282 | LOOP [fetch-output : Ensure local output dirs] 2026-05-17 00:42:00.617779 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/214edf9b3fd04abf842aa21ef0fb91ae/work/logs" 2026-05-17 00:42:00.927788 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/214edf9b3fd04abf842aa21ef0fb91ae/work/artifacts" 2026-05-17 00:42:01.241943 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/214edf9b3fd04abf842aa21ef0fb91ae/work/docs" 2026-05-17 00:42:01.255281 | 2026-05-17 00:42:01.255432 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-17 00:42:02.061131 | debian-bookworm | changed: 2026-05-17 00:42:02.061330 | debian-bookworm | .d..t...... ./ 2026-05-17 00:42:02.061364 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-17 00:42:02.061401 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-17 00:42:02.061428 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-17 00:42:02.061480 | debian-bookworm | changed: All items complete 2026-05-17 00:42:02.061505 | 2026-05-17 00:42:02.565094 | debian-bookworm | changed: .d..t...... ./ 2026-05-17 00:42:03.109020 | debian-bookworm | changed: .d..t...... ./ 2026-05-17 00:42:03.133320 | 2026-05-17 00:42:03.133441 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-17 00:42:03.621752 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010882 2026-05-17 00:42:04.016393 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010273 2026-05-17 00:42:04.029155 | 2026-05-17 00:42:04.029328 | PLAY RECAP 2026-05-17 00:42:04.029381 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-17 00:42:04.029406 | 2026-05-17 00:42:04.192738 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-17 00:42:04.194177 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-17 00:42:04.924636 | 2026-05-17 00:42:04.924807 | PLAY [Base post] 2026-05-17 00:42:04.938872 | 2026-05-17 00:42:04.939025 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-17 00:42:05.474549 | debian-bookworm | changed 2026-05-17 00:42:05.480911 | 2026-05-17 00:42:05.481024 | PLAY RECAP 2026-05-17 00:42:05.481086 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-17 00:42:05.481146 | 2026-05-17 00:42:05.573081 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-17 00:42:05.574214 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-17 00:42:06.353006 | 2026-05-17 00:42:06.353216 | PLAY [Base post-logs] 2026-05-17 00:42:06.365067 | 2026-05-17 00:42:06.365240 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-17 00:42:06.903429 | localhost | changed 2026-05-17 00:42:06.913834 | 2026-05-17 00:42:06.914025 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-17 00:42:06.949846 | localhost | ok 2026-05-17 00:42:06.953386 | 2026-05-17 00:42:06.953493 | TASK [Set zuul-log-path fact] 2026-05-17 00:42:06.968605 | localhost | ok 2026-05-17 00:42:06.976963 | 2026-05-17 00:42:06.977125 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-17 00:42:07.003132 | localhost | ok 2026-05-17 00:42:07.009087 | 2026-05-17 00:42:07.009227 | TASK [upload-logs : Create log directories] 2026-05-17 00:42:07.556446 | localhost | changed 2026-05-17 00:42:07.563229 | 2026-05-17 00:42:07.563376 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-17 00:42:08.101192 | localhost -> localhost | ok: Runtime: 0:00:00.004789 2026-05-17 00:42:08.105764 | 2026-05-17 00:42:08.105898 | TASK [upload-logs : Upload logs to log server] 2026-05-17 00:42:08.692451 | localhost | Output suppressed because no_log was given 2026-05-17 00:42:08.694358 | 2026-05-17 00:42:08.694471 | LOOP [upload-logs : Compress console log and json output] 2026-05-17 00:42:08.757133 | localhost | skipping: Conditional result was False 2026-05-17 00:42:08.764104 | localhost | skipping: Conditional result was False 2026-05-17 00:42:08.776567 | 2026-05-17 00:42:08.776809 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-17 00:42:08.834409 | localhost | skipping: Conditional result was False 2026-05-17 00:42:08.834720 | 2026-05-17 00:42:08.839700 | localhost | skipping: Conditional result was False 2026-05-17 00:42:08.852200 | 2026-05-17 00:42:08.852437 | LOOP [upload-logs : Upload console log and json output]