2026-06-13 00:01:09.333072 | Job console starting 2026-06-13 00:01:09.472901 | Updating git repos 2026-06-13 00:01:09.506748 | Cloning repos into workspace 2026-06-13 00:01:09.952820 | Restoring repo states 2026-06-13 00:01:09.970377 | Merging changes 2026-06-13 00:01:09.970393 | Checking out repos 2026-06-13 00:01:10.041600 | Preparing playbooks 2026-06-13 00:01:17.412953 | Running Ansible setup 2026-06-13 00:01:21.258926 | PRE-RUN START: [trusted : softwarefactory-project.io/config/playbooks/base/pre.yaml@master] 2026-06-13 00:01:21.903509 | 2026-06-13 00:01:21.903622 | PLAY [localhost] 2026-06-13 00:01:21.910632 | 2026-06-13 00:01:21.910702 | TASK [Gathering Facts] 2026-06-13 00:01:22.918357 | localhost | ok 2026-06-13 00:01:22.924466 | 2026-06-13 00:01:22.924539 | TASK [Setup log path fact] 2026-06-13 00:01:22.941297 | localhost | ok 2026-06-13 00:01:22.952535 | 2026-06-13 00:01:22.952676 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-13 00:01:22.990145 | localhost | ok 2026-06-13 00:01:22.996021 | 2026-06-13 00:01:22.996104 | TASK [emit-job-header : Print job information] 2026-06-13 00:01:23.043033 | # Job Information 2026-06-13 00:01:23.043805 | Ansible Version: 2.16.18 2026-06-13 00:01:23.043854 | Job: k1s-tox 2026-06-13 00:01:23.043885 | Pipeline: periodic 2026-06-13 00:01:23.043912 | Executor: 38.102.83.47 2026-06-13 00:01:23.043939 | Triggered by: https://softwarefactory-project.io/r/plugins/gitiles/software-factory/k1s/+/None^!/ 2026-06-13 00:01:23.043973 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/0e3/local/0e3409c15336484ab357da1bd3fae5bb/ 2026-06-13 00:01:23.044003 | Event ID: 76aca1c232134746b5ef51ded6db101b 2026-06-13 00:01:23.055790 | 2026-06-13 00:01:23.056020 | LOOP [emit-job-header : Print node information] 2026-06-13 00:01:23.156792 | localhost | ok: 2026-06-13 00:01:23.157001 | localhost | # Node Information 2026-06-13 00:01:23.157044 | localhost | Inventory Hostname: testrunner 2026-06-13 00:01:23.157077 | localhost | Hostname: np0000072419 2026-06-13 00:01:23.157107 | localhost | Username: zuul-worker 2026-06-13 00:01:23.157140 | localhost | Distro: CentOS 9 2026-06-13 00:01:23.157168 | localhost | Provider: vexxhost-nodepool-sf 2026-06-13 00:01:23.157196 | localhost | Region: RegionOne 2026-06-13 00:01:23.157223 | localhost | Label: cloud-centos-9-stream 2026-06-13 00:01:23.157250 | localhost | Product Name: OpenStack Nova 2026-06-13 00:01:23.157277 | localhost | Interface IP: 38.102.83.50 2026-06-13 00:01:23.163414 | 2026-06-13 00:01:23.163501 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-13 00:01:23.586716 | localhost -> localhost | changed 2026-06-13 00:01:23.599939 | 2026-06-13 00:01:23.600127 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-13 00:01:24.550638 | localhost -> localhost | changed 2026-06-13 00:01:24.556280 | 2026-06-13 00:01:24.556333 | PLAY [all] 2026-06-13 00:01:24.561497 | 2026-06-13 00:01:24.561557 | TASK [include_role : start-zuul-console] 2026-06-13 00:01:24.579604 | testrunner | ok 2026-06-13 00:01:24.589197 | 2026-06-13 00:01:24.589256 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-13 00:01:24.965594 | testrunner | ok 2026-06-13 00:01:24.976370 | 2026-06-13 00:01:24.976460 | TASK [include_role : validate-host] 2026-06-13 00:01:25.004378 | testrunner | ok 2026-06-13 00:01:25.020916 | 2026-06-13 00:01:25.021016 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-13 00:01:25.058235 | testrunner | ok 2026-06-13 00:01:25.061876 | 2026-06-13 00:01:25.061935 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-13 00:01:25.348567 | testrunner -> localhost | ok 2026-06-13 00:01:25.360379 | 2026-06-13 00:01:25.360551 | TASK [validate-host : Collect information about the host] 2026-06-13 00:01:26.031406 | testrunner | ok 2026-06-13 00:01:26.038230 | 2026-06-13 00:01:26.038292 | TASK [validate-host : Sanitize hostname] 2026-06-13 00:01:26.085751 | testrunner | ok 2026-06-13 00:01:26.089644 | 2026-06-13 00:01:26.089707 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-13 00:01:26.620520 | testrunner -> localhost | changed 2026-06-13 00:01:26.630684 | 2026-06-13 00:01:26.630822 | TASK [validate-host : Collect information about zuul worker] 2026-06-13 00:01:27.083583 | testrunner | ok 2026-06-13 00:01:27.090294 | 2026-06-13 00:01:27.090381 | TASK [validate-host : Write out all zuul information for each host] 2026-06-13 00:01:27.611257 | testrunner -> localhost | changed 2026-06-13 00:01:27.621084 | 2026-06-13 00:01:27.621215 | TASK [include_role : prepare-workspace] 2026-06-13 00:01:27.639282 | testrunner | ok 2026-06-13 00:01:27.656774 | 2026-06-13 00:01:27.656876 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-13 00:01:27.918547 | testrunner | ok 2026-06-13 00:01:27.922457 | 2026-06-13 00:01:27.922514 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-13 00:01:28.786119 | testrunner | Output suppressed because no_log was given 2026-06-13 00:01:28.811643 | 2026-06-13 00:01:28.811842 | TASK [include_role : add-build-sshkey] 2026-06-13 00:01:28.848746 | testrunner | ok 2026-06-13 00:01:28.874889 | 2026-06-13 00:01:28.875005 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-13 00:01:29.112166 | testrunner -> localhost | ok 2026-06-13 00:01:29.121271 | 2026-06-13 00:01:29.121415 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-13 00:01:29.156356 | testrunner | ok 2026-06-13 00:01:29.176319 | testrunner | included: /var/lib/zuul/builds/0e3409c15336484ab357da1bd3fae5bb/trusted/project_2/softwarefactory-project.io/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-13 00:01:29.182191 | 2026-06-13 00:01:29.182293 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-13 00:01:30.510462 | testrunner -> localhost | Generating public/private rsa key pair. 2026-06-13 00:01:30.510622 | testrunner -> localhost | Your identification has been saved in /var/lib/zuul/builds/0e3409c15336484ab357da1bd3fae5bb/work/0e3409c15336484ab357da1bd3fae5bb_id_rsa 2026-06-13 00:01:30.510651 | testrunner -> localhost | Your public key has been saved in /var/lib/zuul/builds/0e3409c15336484ab357da1bd3fae5bb/work/0e3409c15336484ab357da1bd3fae5bb_id_rsa.pub 2026-06-13 00:01:30.510672 | testrunner -> localhost | The key fingerprint is: 2026-06-13 00:01:30.510691 | testrunner -> localhost | SHA256:XIzYzISGou0W1+fkqFP4pX5QVUcrn5aduxaH6PPr/lU zuul-build-sshkey 2026-06-13 00:01:30.510709 | testrunner -> localhost | The key's randomart image is: 2026-06-13 00:01:30.510726 | testrunner -> localhost | +---[RSA 3072]----+ 2026-06-13 00:01:30.510743 | testrunner -> localhost | | . .. ...o | 2026-06-13 00:01:30.510760 | testrunner -> localhost | | . . o* o. . . | 2026-06-13 00:01:30.510776 | testrunner -> localhost | | o . o. =.o . . | 2026-06-13 00:01:30.510792 | testrunner -> localhost | |. o . ..+. o +.| 2026-06-13 00:01:30.510808 | testrunner -> localhost | | . o . BS .=oE| 2026-06-13 00:01:30.510824 | testrunner -> localhost | | o . + + ....+| 2026-06-13 00:01:30.510840 | testrunner -> localhost | | . + + . .+| 2026-06-13 00:01:30.510858 | testrunner -> localhost | | o o . o .o| 2026-06-13 00:01:30.510875 | testrunner -> localhost | | o.. =*+.| 2026-06-13 00:01:30.510891 | testrunner -> localhost | +----[SHA256]-----+ 2026-06-13 00:01:30.510930 | testrunner -> localhost | ok: Runtime: 0:00:00.491462 2026-06-13 00:01:30.515553 | 2026-06-13 00:01:30.515616 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-13 00:01:30.541606 | testrunner | ok 2026-06-13 00:01:30.548706 | testrunner | included: /var/lib/zuul/builds/0e3409c15336484ab357da1bd3fae5bb/trusted/project_2/softwarefactory-project.io/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-13 00:01:30.555361 | 2026-06-13 00:01:30.555425 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-13 00:01:30.589158 | testrunner | skipping: Conditional result was False 2026-06-13 00:01:30.594372 | 2026-06-13 00:01:30.594486 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-13 00:01:31.101575 | testrunner | changed 2026-06-13 00:01:31.106418 | 2026-06-13 00:01:31.106523 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-13 00:01:31.351049 | testrunner | ok 2026-06-13 00:01:31.358620 | 2026-06-13 00:01:31.358787 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-13 00:01:32.091734 | testrunner | changed 2026-06-13 00:01:32.100284 | 2026-06-13 00:01:32.100525 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-13 00:01:33.793106 | testrunner | changed 2026-06-13 00:01:33.800211 | 2026-06-13 00:01:33.800346 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-13 00:01:33.827663 | testrunner | skipping: Conditional result was False 2026-06-13 00:01:33.834062 | 2026-06-13 00:01:33.834269 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-13 00:01:34.277665 | testrunner -> localhost | changed 2026-06-13 00:01:34.307144 | 2026-06-13 00:01:34.307485 | TASK [add-build-sshkey : Add back temp key] 2026-06-13 00:01:34.635553 | testrunner -> localhost | Identity added: /var/lib/zuul/builds/0e3409c15336484ab357da1bd3fae5bb/work/0e3409c15336484ab357da1bd3fae5bb_id_rsa (zuul-build-sshkey) 2026-06-13 00:01:34.635808 | testrunner -> localhost | ok: Runtime: 0:00:00.012990 2026-06-13 00:01:34.642569 | 2026-06-13 00:01:34.642669 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-13 00:01:34.998507 | testrunner | ok 2026-06-13 00:01:35.004774 | 2026-06-13 00:01:35.004895 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-13 00:01:35.031362 | testrunner | skipping: Conditional result was False 2026-06-13 00:01:35.038367 | 2026-06-13 00:01:35.038472 | TASK [include_role : prepare-workspace-openshift] 2026-06-13 00:01:35.062529 | testrunner | skipping: Conditional result was False 2026-06-13 00:01:35.070847 | 2026-06-13 00:01:35.071012 | TASK [include_role : remove-zuul-sshkey] 2026-06-13 00:01:35.096933 | testrunner | skipping: Conditional result was False 2026-06-13 00:01:35.103068 | 2026-06-13 00:01:35.103161 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-13 00:01:35.381536 | testrunner | ok: "logs" 2026-06-13 00:01:35.382051 | testrunner | ok: All items complete 2026-06-13 00:01:35.382120 | 2026-06-13 00:01:35.579445 | testrunner | ok: "artifacts" 2026-06-13 00:01:35.799678 | testrunner | ok: "docs" 2026-06-13 00:01:35.818404 | 2026-06-13 00:01:35.818703 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-13 00:01:36.066663 | testrunner | changed: "logs" 2026-06-13 00:01:36.277561 | testrunner | changed: "artifacts" 2026-06-13 00:01:36.492603 | testrunner | changed: "docs" 2026-06-13 00:01:36.511720 | 2026-06-13 00:01:36.511911 | TASK [include_role : rhel-registration] 2026-06-13 00:01:36.528078 | testrunner | skipping: Conditional result was False 2026-06-13 00:01:36.530476 | 2026-06-13 00:01:36.530580 | PLAY RECAP 2026-06-13 00:01:36.530673 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-13 00:01:36.530736 | testrunner | ok: 23 changed: 10 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 0 2026-06-13 00:01:36.530773 | 2026-06-13 00:01:36.678048 | PRE-RUN END RESULT_NORMAL: [trusted : softwarefactory-project.io/config/playbooks/base/pre.yaml@master] 2026-06-13 00:01:36.679796 | RUN START: [untrusted : softwarefactory-project.io/software-factory/k1s/playbooks/k1s-tox.yaml@master] 2026-06-13 00:01:37.301846 | 2026-06-13 00:01:37.301973 | PLAY [all] 2026-06-13 00:01:37.312546 | 2026-06-13 00:01:37.312625 | LOOP [ensure-podman : Find distribution installation] 2026-06-13 00:01:37.355404 | testrunner | ok: "/var/lib/zuul/builds/0e3409c15336484ab357da1bd3fae5bb/untrusted/project_3/softwarefactory-project.io/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml" 2026-06-13 00:01:37.366534 | testrunner | included: /var/lib/zuul/builds/0e3409c15336484ab357da1bd3fae5bb/untrusted/project_3/softwarefactory-project.io/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml 2026-06-13 00:01:37.370857 | 2026-06-13 00:01:37.370929 | TASK [ensure-podman : Install podman (RedHat)] 2026-06-13 00:02:25.304263 | testrunner | changed 2026-06-13 00:02:25.310892 | 2026-06-13 00:02:25.311008 | TASK [ensure-podman : Fetch podman version] 2026-06-12 20:02:26.076596 | testrunner | Client: Podman Engine 2026-06-12 20:02:26.076688 | testrunner | Version: 5.8.2 2026-06-12 20:02:26.076701 | testrunner | API Version: 5.8.2 2026-06-12 20:02:26.076706 | testrunner | Go Version: go1.26.1 (Red Hat 1.26.1-1.el9) 2026-06-12 20:02:26.076710 | testrunner | Built: Thu Apr 16 04:05:13 2026 2026-06-12 20:02:26.076715 | testrunner | OS/Arch: linux/amd64 2026-06-13 00:02:26.339381 | testrunner | ok: Runtime: 0:00:00.407859 2026-06-13 00:02:26.344384 | 2026-06-13 00:02:26.344521 | TASK [ensure-podman : Print podman version installed] 2026-06-13 00:02:26.372890 | Podman version: Client: Podman Engine 2026-06-13 00:02:26.373131 | Version: 5.8.2 2026-06-13 00:02:26.373186 | API Version: 5.8.2 2026-06-13 00:02:26.373220 | Go Version: go1.26.1 (Red Hat 1.26.1-1.el9) 2026-06-13 00:02:26.373248 | Built: Thu Apr 16 04:05:13 2026 2026-06-13 00:02:26.373274 | OS/Arch: linux/amd64 2026-06-13 00:02:26.377700 | 2026-06-13 00:02:26.377814 | TASK [ensure-podman : Validate podman engine] 2026-06-13 00:02:26.903629 | testrunner | skipping: Conditional result was False 2026-06-13 00:02:26.909092 | 2026-06-13 00:02:26.909237 | TASK [ensure-podman : Set up docker compatability socket] 2026-06-13 00:02:26.924323 | testrunner | skipping: Conditional result was False 2026-06-13 00:02:26.936213 | 2026-06-13 00:02:26.936373 | TASK [install tox] 2026-06-12 20:02:28.557067 | testrunner | Collecting tox 2026-06-12 20:02:28.623942 | testrunner | Downloading tox-4.30.3-py3-none-any.whl (175 kB) 2026-06-12 20:02:28.703078 | testrunner | Collecting pyproject-api>=1.9.1 2026-06-12 20:02:28.714498 | testrunner | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2026-06-12 20:02:28.781132 | testrunner | Collecting typing-extensions>=4.14.1 2026-06-12 20:02:28.791900 | testrunner | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-06-12 20:02:28.986355 | testrunner | Collecting virtualenv>=20.31.2 2026-06-12 20:02:28.996848 | testrunner | Downloading virtualenv-21.4.3-py3-none-any.whl (7.6 MB) 2026-06-12 20:02:29.132309 | testrunner | Collecting pluggy>=1.6 2026-06-12 20:02:29.143222 | testrunner | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-06-12 20:02:29.286838 | testrunner | Collecting chardet>=5.2 2026-06-12 20:02:29.297816 | testrunner | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2026-06-12 20:02:29.361992 | testrunner | Collecting cachetools>=6.1 2026-06-12 20:02:29.373508 | testrunner | Downloading cachetools-6.2.6-py3-none-any.whl (11 kB) 2026-06-12 20:02:29.471502 | testrunner | Collecting colorama>=0.4.6 2026-06-12 20:02:29.482558 | testrunner | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-06-12 20:02:29.601060 | testrunner | Collecting platformdirs>=4.3.8 2026-06-12 20:02:29.613244 | testrunner | Downloading platformdirs-4.4.0-py3-none-any.whl (18 kB) 2026-06-12 20:02:29.682135 | testrunner | Collecting packaging>=25 2026-06-12 20:02:29.698756 | testrunner | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-06-12 20:02:29.800917 | testrunner | Collecting tomli>=2.2.1 2026-06-12 20:02:29.811467 | testrunner | Downloading tomli-2.4.1-py3-none-any.whl (14 kB) 2026-06-12 20:02:29.936258 | testrunner | Collecting filelock>=3.18 2026-06-12 20:02:29.948420 | testrunner | Downloading filelock-3.19.1-py3-none-any.whl (15 kB) 2026-06-12 20:02:30.028514 | testrunner | Collecting distlib<1,>=0.3.7 2026-06-12 20:02:30.039301 | testrunner | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-06-12 20:02:30.090059 | testrunner | Collecting python-discovery>=1.4.2 2026-06-12 20:02:30.100967 | testrunner | Downloading python_discovery-1.4.2-py3-none-any.whl (33 kB) 2026-06-12 20:02:30.267476 | testrunner | Installing collected packages: platformdirs, filelock, typing-extensions, tomli, python-discovery, packaging, distlib, virtualenv, pyproject-api, pluggy, colorama, chardet, cachetools, tox 2026-06-12 20:02:30.593817 | testrunner | WARNING: The script virtualenv is installed in '/usr/local/bin' which is not on PATH. 2026-06-12 20:02:30.593859 | testrunner | Consider adding this directory to PATH or, if you prefer to suppress this warning, use --no-warn-script-location. 2026-06-12 20:02:30.811780 | testrunner | WARNING: The script chardetect is installed in '/usr/local/bin' which is not on PATH. 2026-06-12 20:02:30.812052 | testrunner | Consider adding this directory to PATH or, if you prefer to suppress this warning, use --no-warn-script-location. 2026-06-12 20:02:30.941656 | testrunner | WARNING: The script tox is installed in '/usr/local/bin' which is not on PATH. 2026-06-12 20:02:30.941708 | testrunner | Consider adding this directory to PATH or, if you prefer to suppress this warning, use --no-warn-script-location. 2026-06-12 20:02:30.954239 | testrunner | ERROR: pip's dependency resolver does not currently take into account all the packages that are installed. This behaviour is the source of the following dependency conflicts. 2026-06-12 20:02:30.954294 | testrunner | requests 2.25.1 requires chardet<5,>=3.0.2, but you have chardet 5.2.0 which is incompatible. 2026-06-12 20:02:30.954312 | testrunner | Successfully installed cachetools-6.2.6 chardet-5.2.0 colorama-0.4.6 distlib-0.4.3 filelock-3.19.1 packaging-26.2 platformdirs-4.4.0 pluggy-1.6.0 pyproject-api-1.9.1 python-discovery-1.4.2 tomli-2.4.1 tox-4.30.3 typing-extensions-4.15.0 virtualenv-21.4.3 2026-06-12 20:02:30.954323 | testrunner | WARNING: Running pip as the 'root' user can result in broken permissions and conflicting behaviour with the system package manager. It is recommended to use a virtual environment instead: https://pip.pypa.io/warnings/venv 2026-06-13 00:02:31.477806 | testrunner | ok: Runtime: 0:00:04.000428 2026-06-13 00:02:31.482929 | 2026-06-13 00:02:31.483036 | TASK [Install kubectl] 2026-06-13 00:02:49.796123 | testrunner | changed: OK (49258496 bytes) 2026-06-13 00:02:49.801779 | 2026-06-13 00:02:49.801911 | TASK [run tox pep8] 2026-06-12 20:02:50.906085 | testrunner | pep8: install_deps> python -I -m pip install ansible flake8 mypy openshift==0.13.2 pytest -r requirements.txt 2026-06-12 20:03:27.571534 | testrunner | .pkg: install_requires> python -I -m pip install 'setuptools>=40.8.0' 2026-06-12 20:03:28.254233 | testrunner | .pkg: _optional_hooks> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__ 2026-06-12 20:03:28.574214 | testrunner | .pkg: get_requires_for_build_sdist> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__ 2026-06-12 20:03:28.778528 | testrunner | .pkg: get_requires_for_build_wheel> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__ 2026-06-12 20:03:28.962132 | testrunner | .pkg: prepare_metadata_for_build_wheel> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__ 2026-06-12 20:03:29.170910 | testrunner | .pkg: build_sdist> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__ 2026-06-12 20:03:29.427467 | testrunner | pep8: install_package_deps> python -I -m pip install cherrypy routes 2026-06-12 20:03:30.326003 | testrunner | pep8: install_package> python -I -m pip install --force-reinstall --no-deps /home/zuul-worker/src/softwarefactory-project.io/software-factory/k1s/.tox/.tmp/package/1/k1s-1.0.1.tar.gz 2026-06-12 20:03:32.599825 | testrunner | pep8: commands[0]> mypy --ignore-missing-imports k1s/api.py 2026-06-12 20:03:33.936322 | testrunner | Success: no issues found in 1 source file 2026-06-12 20:03:33.941640 | testrunner | pep8: commands[1]> flake8 k1s 2026-06-12 20:03:34.244910 | testrunner | pep8: OK (43.94=setup[42.30]+cmd[1.34,0.30] seconds) 2026-06-12 20:03:34.244966 | testrunner | congratulations :) (43.97 seconds) 2026-06-13 00:03:34.427297 | testrunner | ok: Runtime: 0:00:44.203831 2026-06-13 00:03:34.431522 | 2026-06-13 00:03:34.431585 | TASK [run tox py39] 2026-06-12 20:03:35.221371 | testrunner | py39: install_deps> python -I -m pip install ansible flake8 mypy openshift==0.13.2 pytest -r requirements.txt 2026-06-12 20:04:06.716123 | testrunner | .pkg: _optional_hooks> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__ 2026-06-12 20:04:06.907090 | testrunner | .pkg: get_requires_for_build_sdist> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__ 2026-06-12 20:04:07.086693 | testrunner | .pkg: get_requires_for_build_wheel> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__ 2026-06-12 20:04:07.267566 | testrunner | .pkg: prepare_metadata_for_build_wheel> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__ 2026-06-12 20:04:07.454661 | testrunner | .pkg: build_sdist> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__ 2026-06-12 20:04:07.670339 | testrunner | py39: install_package_deps> python -I -m pip install cherrypy routes 2026-06-12 20:04:08.318690 | testrunner | py39: install_package> python -I -m pip install --force-reinstall --no-deps /home/zuul-worker/src/softwarefactory-project.io/software-factory/k1s/.tox/.tmp/package/2/k1s-1.0.1.tar.gz 2026-06-12 20:04:10.241569 | testrunner | py39: commands[0]> pytest -v tests.py 2026-06-12 20:04:10.420755 | testrunner | ============================= test session starts ============================== 2026-06-12 20:04:10.420837 | testrunner | platform linux -- Python 3.9.25, pytest-8.4.2, pluggy-1.6.0 -- /home/zuul-worker/src/softwarefactory-project.io/software-factory/k1s/.tox/py39/bin/python 2026-06-12 20:04:10.420849 | testrunner | cachedir: .tox/py39/.pytest_cache 2026-06-12 20:04:10.420859 | testrunner | rootdir: /home/zuul-worker/src/softwarefactory-project.io/software-factory/k1s 2026-06-12 20:04:10.823435 | testrunner | collecting ... collected 5 items 2026-06-12 20:04:10.823510 | testrunner | 2026-06-12 20:05:03.411789 | testrunner | tests.py::K1sTestCase::test_ansible PASSED [ 20%] 2026-06-12 20:05:03.741081 | testrunner | tests.py::K1sTestCase::test_bad_token PASSED [ 40%] 2026-06-12 20:05:06.691955 | testrunner | tests.py::K1sTestCase::test_create PASSED [ 60%] 2026-06-12 20:05:11.589508 | testrunner | tests.py::K1sTestCase::test_kubectl PASSED [ 80%] 2026-06-12 20:05:15.652813 | testrunner | tests.py::K1sTestCase::test_python_client PASSED [100%] 2026-06-12 20:05:15.652870 | testrunner | 2026-06-12 20:05:15.652881 | testrunner | =============================== warnings summary =============================== 2026-06-12 20:05:15.652890 | testrunner | tests.py::K1sTestCase::test_bad_token 2026-06-12 20:05:15.652900 | testrunner | tests.py::K1sTestCase::test_create 2026-06-12 20:05:15.652908 | testrunner | tests.py::K1sTestCase::test_create 2026-06-12 20:05:15.652916 | testrunner | tests.py::K1sTestCase::test_create 2026-06-12 20:05:15.652924 | testrunner | tests.py::K1sTestCase::test_create 2026-06-12 20:05:15.652932 | testrunner | tests.py::K1sTestCase::test_python_client 2026-06-12 20:05:15.652941 | testrunner | /home/zuul-worker/src/softwarefactory-project.io/software-factory/k1s/.tox/py39/lib/python3.9/site-packages/urllib3/connectionpool.py:1097: InsecureRequestWarning: Unverified HTTPS request is being made to host 'localhost'. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#tls-warnings 2026-06-12 20:05:15.652950 | testrunner | warnings.warn( 2026-06-12 20:05:15.652958 | testrunner | 2026-06-12 20:05:15.652966 | testrunner | -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 2026-06-12 20:05:15.652974 | testrunner | =================== 5 passed, 6 warnings in 65.11s (0:01:05) =================== 2026-06-12 20:05:15.751139 | testrunner | py39: OK (100.83=setup[35.32]+cmd[65.51] seconds) 2026-06-12 20:05:15.751196 | testrunner | congratulations :) (100.86 seconds) 2026-06-13 00:05:16.120876 | testrunner | ok: Runtime: 0:01:41.121135 2026-06-13 00:05:16.122692 | 2026-06-13 00:05:16.122749 | PLAY RECAP 2026-06-13 00:05:16.122798 | testrunner | ok: 8 changed: 6 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-13 00:05:16.122825 | 2026-06-13 00:05:16.291398 | RUN END RESULT_NORMAL: [untrusted : softwarefactory-project.io/software-factory/k1s/playbooks/k1s-tox.yaml@master] 2026-06-13 00:05:16.292991 | POST-RUN START: [trusted : softwarefactory-project.io/config/playbooks/base/post.yaml@master] 2026-06-13 00:05:17.010401 | 2026-06-13 00:05:17.010556 | PLAY [all] 2026-06-13 00:05:17.022519 | 2026-06-13 00:05:17.022653 | TASK [include_role : fetch-output] 2026-06-13 00:05:17.062986 | testrunner | ok 2026-06-13 00:05:17.077783 | 2026-06-13 00:05:17.077902 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-13 00:05:17.122258 | testrunner | skipping: Conditional result was False 2026-06-13 00:05:17.126633 | 2026-06-13 00:05:17.126738 | TASK [fetch-output : Set log path for single node] 2026-06-13 00:05:17.155534 | testrunner | ok 2026-06-13 00:05:17.159139 | 2026-06-13 00:05:17.159211 | LOOP [fetch-output : Ensure local output dirs] 2026-06-13 00:05:17.601230 | testrunner -> localhost | changed: "/var/lib/zuul/builds/0e3409c15336484ab357da1bd3fae5bb/work/logs" 2026-06-13 00:05:17.874784 | testrunner -> localhost | changed: "/var/lib/zuul/builds/0e3409c15336484ab357da1bd3fae5bb/work/artifacts" 2026-06-13 00:05:18.134837 | testrunner -> localhost | changed: "/var/lib/zuul/builds/0e3409c15336484ab357da1bd3fae5bb/work/docs" 2026-06-13 00:05:18.150265 | 2026-06-13 00:05:18.150400 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-13 00:05:18.882255 | testrunner | ok 2026-06-13 00:05:18.882738 | testrunner | changed: All items complete 2026-06-13 00:05:18.882825 | 2026-06-13 00:05:19.368093 | testrunner | changed: .d..t...... ./ 2026-06-13 00:05:19.832050 | testrunner | changed: .d..t...... ./ 2026-06-13 00:05:19.848791 | 2026-06-13 00:05:19.848886 | TASK [include_role : fetch-output-openshift] 2026-06-13 00:05:19.864296 | testrunner | skipping: Conditional result was False 2026-06-13 00:05:19.869538 | 2026-06-13 00:05:19.869616 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-13 00:05:19.897781 | testrunner | skipping: Conditional result was False 2026-06-13 00:05:19.905050 | testrunner | skipping: Conditional result was False 2026-06-13 00:05:19.925798 | 2026-06-13 00:05:19.925940 | TASK [include_role : rhel-registration] 2026-06-13 00:05:19.941544 | testrunner | skipping: Conditional result was False 2026-06-13 00:05:19.946637 | 2026-06-13 00:05:19.946780 | PLAY [localhost] 2026-06-13 00:05:19.960715 | 2026-06-13 00:05:19.960850 | TASK [add-fileserver : Create SSH private key tempfile] 2026-06-13 00:05:20.412385 | localhost | changed 2026-06-13 00:05:20.418011 | 2026-06-13 00:05:20.418142 | TASK [add-fileserver : Create SSH private key from secret] 2026-06-13 00:05:21.273845 | localhost | changed 2026-06-13 00:05:21.279025 | 2026-06-13 00:05:21.279125 | TASK [add-fileserver : Add fileserver ssh key] 2026-06-13 00:05:21.761115 | localhost | Identity added: /var/lib/zuul/builds/0e3409c15336484ab357da1bd3fae5bb/work/tmp/ansible.ixn149l0 (/var/lib/zuul/builds/0e3409c15336484ab357da1bd3fae5bb/work/tmp/ansible.ixn149l0) 2026-06-13 00:05:21.761350 | localhost | ok: Runtime: 0:00:00.014623 2026-06-13 00:05:21.803817 | 2026-06-13 00:05:21.803979 | TASK [add-fileserver : Remove SSH private key from disk] 2026-06-13 00:05:22.086999 | localhost | ok: Runtime: 0:00:00.005791 2026-06-13 00:05:22.092320 | 2026-06-13 00:05:22.092412 | TASK [add-fileserver : Add fileserver to inventory] 2026-06-13 00:05:22.149337 | localhost | changed 2026-06-13 00:05:22.158111 | 2026-06-13 00:05:22.159571 | TASK [add-fileserver : Add fileserver server to known hosts] 2026-06-13 00:05:22.613134 | localhost | changed 2026-06-13 00:05:22.622094 | 2026-06-13 00:05:22.622186 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-13 00:05:22.965039 | localhost | changed 2026-06-13 00:05:22.985621 | 2026-06-13 00:05:22.985767 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-13 00:05:23.014463 | localhost | ok 2026-06-13 00:05:23.020309 | 2026-06-13 00:05:23.020370 | TASK [include_role : report-logjuicer] 2026-06-13 00:05:23.054485 | localhost | skipping: Conditional result was False 2026-06-13 00:05:23.056757 | 2026-06-13 00:05:23.056811 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com] 2026-06-13 00:05:23.060519 | 2026-06-13 00:05:23.060585 | TASK [Set zuul-log-path fact] 2026-06-13 00:05:23.075719 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-13 00:05:23.086492 | 2026-06-13 00:05:23.086576 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-13 00:05:23.113016 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-13 00:05:23.116979 | 2026-06-13 00:05:23.117080 | TASK [upload-logs : Create log directories] 2026-06-13 00:05:24.048582 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed 2026-06-13 00:05:24.050402 | 2026-06-13 00:05:24.050495 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-13 00:05:24.340890 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.008131 2026-06-13 00:05:24.345073 | 2026-06-13 00:05:24.345191 | TASK [upload-logs : Upload logs to log server] 2026-06-13 00:05:25.507669 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed: 2026-06-13 00:05:25.507865 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | .d..tp..... ./ 2026-06-13 00:05:25.507900 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com |