2025-12-14 00:19:58.127294 | Job console starting 2025-12-14 00:19:58.140601 | Updating git repos 2025-12-14 00:19:58.177870 | Cloning repos into workspace 2025-12-14 00:19:58.297274 | Restoring repo states 2025-12-14 00:19:58.319221 | Merging changes 2025-12-14 00:19:59.109022 | Checking out repos 2025-12-14 00:19:59.216424 | Preparing playbooks 2025-12-14 00:20:00.815697 | Running Ansible setup 2025-12-14 00:20:10.783164 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-14 00:20:14.584872 | 2025-12-14 00:20:14.585051 | PLAY [Base pre] 2025-12-14 00:20:14.607740 | 2025-12-14 00:20:14.608135 | TASK [Setup log path fact] 2025-12-14 00:20:14.642703 | debian-bookworm | ok 2025-12-14 00:20:14.672039 | 2025-12-14 00:20:14.672270 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-14 00:20:14.727869 | debian-bookworm | ok 2025-12-14 00:20:14.739248 | 2025-12-14 00:20:14.739404 | TASK [emit-job-header : Print job information] 2025-12-14 00:20:14.802511 | # Job Information 2025-12-14 00:20:14.802716 | Ansible Version: 2.16.14 2025-12-14 00:20:14.802754 | Job: tox 2025-12-14 00:20:14.802788 | Pipeline: check 2025-12-14 00:20:14.802812 | Executor: 521e9411259a 2025-12-14 00:20:14.802866 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1035 2025-12-14 00:20:14.802891 | Event ID: 923e4d30-d882-11f0-87be-d98706fedeab 2025-12-14 00:20:14.808437 | 2025-12-14 00:20:14.808583 | LOOP [emit-job-header : Print node information] 2025-12-14 00:20:15.163367 | debian-bookworm | ok: 2025-12-14 00:20:15.163585 | debian-bookworm | # Node Information 2025-12-14 00:20:15.163622 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-12-14 00:20:15.163648 | debian-bookworm | Hostname: debian 2025-12-14 00:20:15.163669 | debian-bookworm | Username: zuul 2025-12-14 00:20:15.163690 | debian-bookworm | Distro: Debian 12.12 2025-12-14 00:20:15.163714 | debian-bookworm | Provider: regiocloud-a 2025-12-14 00:20:15.163735 | debian-bookworm | Region: 2025-12-14 00:20:15.163756 | debian-bookworm | Label: debian-bookworm 2025-12-14 00:20:15.163776 | debian-bookworm | Product Name: OpenStack Nova 2025-12-14 00:20:15.163795 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe9e:d437 2025-12-14 00:20:15.187981 | 2025-12-14 00:20:15.188144 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-14 00:20:17.112893 | debian-bookworm -> localhost | changed 2025-12-14 00:20:17.133664 | 2025-12-14 00:20:17.133824 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-14 00:20:19.700174 | debian-bookworm -> localhost | changed 2025-12-14 00:20:19.716603 | 2025-12-14 00:20:19.716739 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-14 00:20:20.823387 | debian-bookworm -> localhost | ok 2025-12-14 00:20:20.830408 | 2025-12-14 00:20:20.830641 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-14 00:20:20.882630 | debian-bookworm | ok 2025-12-14 00:20:20.911492 | debian-bookworm | included: /var/lib/zuul/builds/7f27fccc381d4dd290ea3d7abb22f22a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-14 00:20:20.919960 | 2025-12-14 00:20:20.920086 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-14 00:20:23.904336 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-12-14 00:20:23.904625 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/7f27fccc381d4dd290ea3d7abb22f22a/work/7f27fccc381d4dd290ea3d7abb22f22a_id_rsa 2025-12-14 00:20:23.904669 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/7f27fccc381d4dd290ea3d7abb22f22a/work/7f27fccc381d4dd290ea3d7abb22f22a_id_rsa.pub 2025-12-14 00:20:23.904696 | debian-bookworm -> localhost | The key fingerprint is: 2025-12-14 00:20:23.904722 | debian-bookworm -> localhost | SHA256:Cg4tvJfiZ65UJCTaA5m4QsPama9P71UiLJ0ty+LfyQc zuul-build-sshkey 2025-12-14 00:20:23.904746 | debian-bookworm -> localhost | The key's randomart image is: 2025-12-14 00:20:23.904781 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-12-14 00:20:23.904803 | debian-bookworm -> localhost | |=o. | 2025-12-14 00:20:23.904824 | debian-bookworm -> localhost | |=O | 2025-12-14 00:20:23.904845 | debian-bookworm -> localhost | |+o=o. | 2025-12-14 00:20:23.904865 | debian-bookworm -> localhost | |+.+= o o | 2025-12-14 00:20:23.904885 | debian-bookworm -> localhost | |. +.= * S . | 2025-12-14 00:20:23.904913 | debian-bookworm -> localhost | | *.= =Eo | 2025-12-14 00:20:23.904934 | debian-bookworm -> localhost | | +.* + .. | 2025-12-14 00:20:23.904955 | debian-bookworm -> localhost | | o.=oo + .. | 2025-12-14 00:20:23.904995 | debian-bookworm -> localhost | | o*+o+ +. | 2025-12-14 00:20:23.905017 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-12-14 00:20:23.905087 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.266818 2025-12-14 00:20:23.913264 | 2025-12-14 00:20:23.913401 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-14 00:20:23.963402 | debian-bookworm | ok 2025-12-14 00:20:23.994134 | debian-bookworm | included: /var/lib/zuul/builds/7f27fccc381d4dd290ea3d7abb22f22a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-14 00:20:24.034389 | 2025-12-14 00:20:24.034530 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-14 00:20:24.069076 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:20:24.082240 | 2025-12-14 00:20:24.082395 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-14 00:20:25.060855 | debian-bookworm | changed 2025-12-14 00:20:25.066752 | 2025-12-14 00:20:25.079410 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-14 00:20:25.378725 | debian-bookworm | ok 2025-12-14 00:20:25.384586 | 2025-12-14 00:20:25.384711 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-14 00:20:26.351247 | debian-bookworm | changed 2025-12-14 00:20:26.395414 | 2025-12-14 00:20:26.395596 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-14 00:20:27.359545 | debian-bookworm | changed 2025-12-14 00:20:27.403322 | 2025-12-14 00:20:27.405422 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-14 00:20:27.517572 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:20:27.526362 | 2025-12-14 00:20:27.526497 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-14 00:20:28.953324 | debian-bookworm -> localhost | changed 2025-12-14 00:20:28.975433 | 2025-12-14 00:20:28.975635 | TASK [add-build-sshkey : Add back temp key] 2025-12-14 00:20:30.498939 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/7f27fccc381d4dd290ea3d7abb22f22a/work/7f27fccc381d4dd290ea3d7abb22f22a_id_rsa (zuul-build-sshkey) 2025-12-14 00:20:30.499324 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.034110 2025-12-14 00:20:30.520693 | 2025-12-14 00:20:30.520868 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-14 00:20:31.855326 | debian-bookworm | ok 2025-12-14 00:20:31.868514 | 2025-12-14 00:20:31.868940 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-14 00:20:31.992084 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:20:32.006634 | 2025-12-14 00:20:32.006781 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-14 00:20:32.688121 | debian-bookworm | ok 2025-12-14 00:20:32.714250 | 2025-12-14 00:20:32.714402 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-14 00:20:32.785904 | debian-bookworm | ok 2025-12-14 00:20:32.802853 | 2025-12-14 00:20:32.802993 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-14 00:20:33.509495 | debian-bookworm -> localhost | ok 2025-12-14 00:20:33.516738 | 2025-12-14 00:20:33.516863 | TASK [validate-host : Collect information about the host] 2025-12-14 00:20:35.006035 | debian-bookworm | ok 2025-12-14 00:20:35.033443 | 2025-12-14 00:20:35.033595 | TASK [validate-host : Sanitize hostname] 2025-12-14 00:20:35.298417 | debian-bookworm | ok 2025-12-14 00:20:35.402745 | 2025-12-14 00:20:35.402932 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-14 00:20:36.619294 | debian-bookworm -> localhost | changed 2025-12-14 00:20:36.625982 | 2025-12-14 00:20:36.626122 | TASK [validate-host : Collect information about zuul worker] 2025-12-14 00:20:37.091978 | debian-bookworm | ok 2025-12-14 00:20:37.103907 | 2025-12-14 00:20:37.104058 | TASK [validate-host : Write out all zuul information for each host] 2025-12-14 00:20:38.028639 | debian-bookworm -> localhost | changed 2025-12-14 00:20:38.044664 | 2025-12-14 00:20:38.044804 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-14 00:20:38.364538 | debian-bookworm | ok 2025-12-14 00:20:38.371299 | 2025-12-14 00:20:38.371500 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-14 00:20:39.656597 | debian-bookworm | changed: 2025-12-14 00:20:39.656806 | debian-bookworm | cd+++++++++ src/ 2025-12-14 00:20:39.656842 | debian-bookworm | cd+++++++++ src/github.com/ 2025-12-14 00:20:39.656867 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-12-14 00:20:39.656889 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-12-14 00:20:39.656909 | debian-bookworm | =6.2 2025-12-14 00:21:04.935708 | debian-bookworm | Downloading cachetools-6.2.3-py3-none-any.whl (11 kB) 2025-12-14 00:21:04.968884 | debian-bookworm | Collecting chardet>=5.2 2025-12-14 00:21:04.983339 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-12-14 00:21:05.005984 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 11.7 MB/s eta 0:00:00 2025-12-14 00:21:05.056200 | debian-bookworm | Collecting colorama>=0.4.6 2025-12-14 00:21:05.068354 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-12-14 00:21:05.125962 | debian-bookworm | Collecting filelock>=3.20 2025-12-14 00:21:05.137597 | debian-bookworm | Downloading filelock-3.20.0-py3-none-any.whl (16 kB) 2025-12-14 00:21:05.187885 | debian-bookworm | Collecting packaging>=25 2025-12-14 00:21:05.201231 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-12-14 00:21:05.214075 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 7.9 MB/s eta 0:00:00 2025-12-14 00:21:05.273844 | debian-bookworm | Collecting platformdirs>=4.5 2025-12-14 00:21:05.285390 | debian-bookworm | Downloading platformdirs-4.5.1-py3-none-any.whl (18 kB) 2025-12-14 00:21:05.344349 | debian-bookworm | Collecting pluggy>=1.6 2025-12-14 00:21:05.355665 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-12-14 00:21:05.410908 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-12-14 00:21:05.423879 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-12-14 00:21:05.552608 | debian-bookworm | Collecting virtualenv>=20.34 2025-12-14 00:21:05.564805 | debian-bookworm | Downloading virtualenv-20.35.4-py3-none-any.whl (6.0 MB) 2025-12-14 00:21:05.890981 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 18.8 MB/s eta 0:00:00 2025-12-14 00:21:05.997134 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-12-14 00:21:06.008981 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-12-14 00:21:06.035605 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 20.0 MB/s eta 0:00:00 2025-12-14 00:21:06.186969 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-12-14 00:21:07.042640 | debian-bookworm | Successfully installed cachetools-6.2.3 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.20.0 packaging-25.0 platformdirs-4.5.1 pluggy-1.6.0 pyproject-api-1.10.0 tox-4.32.0 virtualenv-20.35.4 2025-12-14 00:21:07.478107 | debian-bookworm | ok: Runtime: 0:00:03.109477 2025-12-14 00:21:07.487596 | 2025-12-14 00:21:07.487732 | TASK [ensure-tox : Export installed tox_executable path] 2025-12-14 00:21:07.571181 | debian-bookworm | ok 2025-12-14 00:21:07.580915 | 2025-12-14 00:21:07.581059 | TASK [ensure-tox : Output tox version] 2025-12-14 00:21:08.364931 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-12-14 00:21:08.384957 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-12-14 00:21:08.631879 | debian-bookworm | ok: Runtime: 0:00:00.318120 2025-12-14 00:21:08.638912 | 2025-12-14 00:21:08.639034 | TASK [ensure-tox : Make global symlink] 2025-12-14 00:21:08.663406 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:21:08.675910 | 2025-12-14 00:21:08.676028 | PLAY RECAP 2025-12-14 00:21:08.676110 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-12-14 00:21:08.676140 | 2025-12-14 00:21:09.358019 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-12-14 00:21:09.360586 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-12-14 00:21:11.721270 | 2025-12-14 00:21:11.721453 | PLAY [all] 2025-12-14 00:21:11.786522 | 2025-12-14 00:21:11.786715 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-12-14 00:21:12.902937 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-12-14 00:21:13.412366 | debian-bookworm | ok: Runtime: 0:00:00.031993 2025-12-14 00:21:13.429424 | 2025-12-14 00:21:13.429686 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-12-14 00:21:14.074869 | debian-bookworm | ok: Runtime: 0:00:00.005888 2025-12-14 00:21:14.084957 | 2025-12-14 00:21:14.085297 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-12-14 00:21:14.496140 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-12-14 00:21:14.500780 | debian-bookworm | sudo: a password is required 2025-12-14 00:21:14.672355 | debian-bookworm | ok: Runtime: 0:00:00.028604 2025-12-14 00:21:14.684934 | 2025-12-14 00:21:14.685081 | TASK [tox : Check to see if the constraints file exists] 2025-12-14 00:21:14.753639 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:21:14.761216 | 2025-12-14 00:21:14.761363 | TASK [tox : Fail if constraints file is missing] 2025-12-14 00:21:14.892582 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:21:14.900809 | 2025-12-14 00:21:14.900957 | TASK [tox : Record file location] 2025-12-14 00:21:14.966463 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:21:14.973955 | 2025-12-14 00:21:14.974098 | TASK [tox : Install tox siblings] 2025-12-14 00:21:15.013724 | debian-bookworm | ok 2025-12-14 00:21:15.055009 | debian-bookworm | included: /var/lib/zuul/builds/7f27fccc381d4dd290ea3d7abb22f22a/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-12-14 00:21:15.089457 | 2025-12-14 00:21:15.089621 | TASK [tox : Run tox without tests] 2025-12-14 00:21:16.524864 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-12-14 00:21:30.937552 | debian-bookworm | test: OK (15.04 seconds) 2025-12-14 00:21:30.937621 | debian-bookworm | congratulations :) (15.07 seconds) 2025-12-14 00:21:31.239428 | debian-bookworm | ok: Runtime: 0:00:15.379650 2025-12-14 00:21:31.245897 | 2025-12-14 00:21:31.246037 | TASK [tox : Create a tempfile to save tox showconfig] 2025-12-14 00:21:32.593653 | debian-bookworm | changed 2025-12-14 00:21:32.611922 | 2025-12-14 00:21:32.612073 | TASK [tox : Get tox envlist config] 2025-12-14 00:21:33.749685 | debian-bookworm | ok: Runtime: 0:00:00.367626 2025-12-14 00:21:33.760160 | 2025-12-14 00:21:33.760326 | TASK [tox : Install any sibling python packages] 2025-12-14 00:21:34.837586 | debian-bookworm | ok: No setup.cfg, no action needed 2025-12-14 00:21:34.854793 | 2025-12-14 00:21:34.858519 | TASK [tox : Remove tempfile] 2025-12-14 00:21:36.350481 | debian-bookworm | changed 2025-12-14 00:21:36.362519 | 2025-12-14 00:21:36.362669 | TASK [tox : Emit tox command] 2025-12-14 00:21:36.576785 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-12-14 00:21:36.583338 | 2025-12-14 00:21:36.583486 | TASK [tox : Run tox] 2025-12-14 00:21:37.785375 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-12-14 00:21:38.470550 | debian-bookworm | ............... 2025-12-14 00:21:38.470689 | debian-bookworm | ---------------------------------------------------------------------- 2025-12-14 00:21:38.470724 | debian-bookworm | Ran 15 tests in 0.196s 2025-12-14 00:21:38.470740 | debian-bookworm | 2025-12-14 00:21:38.470758 | debian-bookworm | OK 2025-12-14 00:21:38.585180 | debian-bookworm | test: OK (0.84=setup[0.05]+cmd[0.79] seconds) 2025-12-14 00:21:38.585306 | debian-bookworm | congratulations :) (0.87 seconds) 2025-12-14 00:21:39.021097 | debian-bookworm | ok: Runtime: 0:00:01.181604 2025-12-14 00:21:39.027623 | 2025-12-14 00:21:39.027751 | TASK [tox : Look for output] 2025-12-14 00:21:39.805407 | debian-bookworm | ok 2025-12-14 00:21:39.897866 | 2025-12-14 00:21:39.898035 | TASK [tox : Return file comments to Zuul] 2025-12-14 00:21:40.115325 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:21:40.127851 | 2025-12-14 00:21:40.127985 | PLAY RECAP 2025-12-14 00:21:40.128047 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-12-14 00:21:40.128072 | 2025-12-14 00:21:40.567052 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-12-14 00:21:40.568182 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-12-14 00:21:42.745962 | 2025-12-14 00:21:42.746134 | PLAY [all] 2025-12-14 00:21:42.881174 | 2025-12-14 00:21:42.881970 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-12-14 00:21:43.000169 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:21:43.015369 | 2025-12-14 00:21:43.015846 | TASK [fetch-tox-output : Set tox log path for single node] 2025-12-14 00:21:43.169587 | debian-bookworm | ok 2025-12-14 00:21:43.196647 | 2025-12-14 00:21:43.196795 | TASK [fetch-tox-output : Ensure local tox dir] 2025-12-14 00:21:44.847728 | debian-bookworm -> localhost | changed 2025-12-14 00:21:44.861251 | 2025-12-14 00:21:44.861443 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-12-14 00:21:44.992592 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:21:44.999876 | 2025-12-14 00:21:45.000025 | TASK [fetch-tox-output : Set envlist fact] 2025-12-14 00:21:45.099534 | debian-bookworm | ok 2025-12-14 00:21:45.111932 | 2025-12-14 00:21:45.112065 | TASK [fetch-tox-output : Get tox version] 2025-12-14 00:21:46.155097 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-12-14 00:21:46.174142 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-12-14 00:21:46.681951 | debian-bookworm | ok: Runtime: 0:00:00.295979 2025-12-14 00:21:46.687959 | 2025-12-14 00:21:46.688100 | TASK [fetch-tox-output : Find all default environments] 2025-12-14 00:21:47.267019 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:21:47.273341 | 2025-12-14 00:21:47.273490 | TASK [fetch-tox-output : Set envlist fact] 2025-12-14 00:21:47.328662 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:21:47.340737 | 2025-12-14 00:21:47.340936 | TASK [fetch-tox-output : Find all default environments] 2025-12-14 00:21:47.875266 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:21:47.887803 | 2025-12-14 00:21:47.887965 | TASK [fetch-tox-output : Set envlist fact] 2025-12-14 00:21:47.952504 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:21:47.960403 | 2025-12-14 00:21:47.960541 | LOOP [fetch-tox-output : Copy tox logs] 2025-12-14 00:21:48.091249 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:21:48.110120 | 2025-12-14 00:21:48.110323 | LOOP [fetch-tox-output : Collect tox logs] 2025-12-14 00:21:49.817407 | debian-bookworm | changed: 2025-12-14 00:21:49.817606 | debian-bookworm | created directory /var/lib/zuul/builds/7f27fccc381d4dd290ea3d7abb22f22a/work/logs/tox/test 2025-12-14 00:21:49.817641 | debian-bookworm | cd+++++++++ ./ 2025-12-14 00:21:49.817666 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-12-14 00:21:49.817696 | 2025-12-14 00:21:49.826539 | 2025-12-14 00:21:49.826645 | PLAY RECAP 2025-12-14 00:21:49.826701 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-12-14 00:21:49.826725 | 2025-12-14 00:21:50.256151 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-12-14 00:21:50.257277 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-14 00:21:51.328946 | 2025-12-14 00:21:51.329121 | PLAY [all] 2025-12-14 00:21:51.361750 | 2025-12-14 00:21:51.361931 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-12-14 00:21:51.789840 | debian-bookworm | changed: non-zero return code 2025-12-14 00:21:51.801547 | 2025-12-14 00:21:51.801769 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-12-14 00:21:51.843510 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:21:51.853665 | 2025-12-14 00:21:51.853797 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-12-14 00:21:51.897034 | 2025-12-14 00:21:51.897306 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-12-14 00:21:51.960250 | 2025-12-14 00:21:51.960470 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-12-14 00:21:52.004847 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:21:52.011525 | 2025-12-14 00:21:52.011663 | LOOP [fetch-subunit-output : Generate subunit file] 2025-12-14 00:21:52.051716 | 2025-12-14 00:21:52.051935 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-12-14 00:21:52.075721 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:21:52.081839 | 2025-12-14 00:21:52.081982 | TASK [fetch-subunit-output : Remove the temporary file] 2025-12-14 00:21:52.106012 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:21:52.114155 | 2025-12-14 00:21:52.114325 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-12-14 00:21:52.138953 | debian-bookworm | skipping: Conditional result was False 2025-12-14 00:21:52.146311 | 2025-12-14 00:21:52.146419 | PLAY RECAP 2025-12-14 00:21:52.146474 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-12-14 00:21:52.146498 | 2025-12-14 00:21:52.391737 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-14 00:21:52.393744 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-14 00:21:53.218534 | 2025-12-14 00:21:53.218719 | PLAY [Base post] 2025-12-14 00:21:53.234520 | 2025-12-14 00:21:53.234691 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-12-14 00:21:53.901256 | debian-bookworm | changed 2025-12-14 00:21:53.917176 | 2025-12-14 00:21:53.917409 | PLAY RECAP 2025-12-14 00:21:53.917480 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-14 00:21:53.917545 | 2025-12-14 00:21:54.086879 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-14 00:21:54.088118 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-12-14 00:21:54.993336 | 2025-12-14 00:21:54.993516 | PLAY [Base post-logs] 2025-12-14 00:21:55.005504 | 2025-12-14 00:21:55.005668 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-14 00:21:55.674769 | localhost | changed 2025-12-14 00:21:55.691954 | 2025-12-14 00:21:55.692131 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-14 00:21:55.722084 | localhost | ok 2025-12-14 00:21:55.725756 | 2025-12-14 00:21:55.725867 | TASK [Set zuul-log-path fact] 2025-12-14 00:21:55.741098 | localhost | ok 2025-12-14 00:21:55.749697 | 2025-12-14 00:21:55.749874 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-14 00:21:55.787533 | localhost | ok 2025-12-14 00:21:55.792441 | 2025-12-14 00:21:55.792593 | TASK [upload-logs : Create log directories] 2025-12-14 00:21:56.331031 | localhost | changed 2025-12-14 00:21:56.334391 | 2025-12-14 00:21:56.334512 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-12-14 00:21:56.959654 | localhost -> localhost | ok: Runtime: 0:00:00.008254 2025-12-14 00:21:56.966682 | 2025-12-14 00:21:56.966820 | TASK [upload-logs : Upload logs to log server] 2025-12-14 00:21:57.720439 | localhost | Output suppressed because no_log was given 2025-12-14 00:21:57.722926 | 2025-12-14 00:21:57.723047 | LOOP [upload-logs : Compress console log and json output] 2025-12-14 00:21:57.790224 | localhost | skipping: Conditional result was False 2025-12-14 00:21:57.801040 | localhost | skipping: Conditional result was False 2025-12-14 00:21:57.820440 | 2025-12-14 00:21:57.820855 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-14 00:21:57.890092 | localhost | skipping: Conditional result was False 2025-12-14 00:21:57.890434 | 2025-12-14 00:21:57.897962 | localhost | skipping: Conditional result was False 2025-12-14 00:21:57.912341 | 2025-12-14 00:21:57.912545 | LOOP [upload-logs : Upload console log and json output]