2026-06-12 00:00:56.985628 | Job console starting 2026-06-12 00:00:57.102463 | Updating git repos 2026-06-12 00:00:57.123535 | Cloning repos into workspace 2026-06-12 00:00:57.537216 | Restoring repo states 2026-06-12 00:00:57.556631 | Merging changes 2026-06-12 00:00:57.556650 | Checking out repos 2026-06-12 00:00:57.611681 | Preparing playbooks 2026-06-12 00:01:01.525801 | Running Ansible setup 2026-06-12 00:01:05.149397 | PRE-RUN START: [trusted : softwarefactory-project.io/config/playbooks/base/pre.yaml@master] 2026-06-12 00:01:05.803012 | 2026-06-12 00:01:05.803149 | PLAY [localhost] 2026-06-12 00:01:05.821141 | 2026-06-12 00:01:05.821335 | TASK [Gathering Facts] 2026-06-12 00:01:06.713229 | localhost | ok 2026-06-12 00:01:06.723549 | 2026-06-12 00:01:06.723670 | TASK [Setup log path fact] 2026-06-12 00:01:06.741961 | localhost | ok 2026-06-12 00:01:06.757593 | 2026-06-12 00:01:06.757723 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-12 00:01:06.788027 | localhost | ok 2026-06-12 00:01:06.799706 | 2026-06-12 00:01:06.799826 | TASK [emit-job-header : Print job information] 2026-06-12 00:01:06.840375 | # Job Information 2026-06-12 00:01:06.840581 | Ansible Version: 2.16.18 2026-06-12 00:01:06.840627 | Job: k1s-tox 2026-06-12 00:01:06.840657 | Pipeline: periodic 2026-06-12 00:01:06.840684 | Executor: 38.102.83.32 2026-06-12 00:01:06.840710 | Triggered by: https://softwarefactory-project.io/r/plugins/gitiles/software-factory/k1s/+/None^!/ 2026-06-12 00:01:06.840739 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/a7a/local/a7ad667eb5474ddca4adf445afae5e64/ 2026-06-12 00:01:06.840766 | Event ID: 90385a5d96c54c918b5d3b451c0ebea0 2026-06-12 00:01:06.844074 | 2026-06-12 00:01:06.844149 | LOOP [emit-job-header : Print node information] 2026-06-12 00:01:06.949082 | localhost | ok: 2026-06-12 00:01:06.949264 | localhost | # Node Information 2026-06-12 00:01:06.949302 | localhost | Inventory Hostname: testrunner 2026-06-12 00:01:06.949331 | localhost | Hostname: np0000071337 2026-06-12 00:01:06.949358 | localhost | Username: zuul-worker 2026-06-12 00:01:06.949389 | localhost | Distro: CentOS 9 2026-06-12 00:01:06.949417 | localhost | Provider: vexxhost-nodepool-sf 2026-06-12 00:01:06.949442 | localhost | Region: RegionOne 2026-06-12 00:01:06.949468 | localhost | Label: cloud-centos-9-stream 2026-06-12 00:01:06.949492 | localhost | Product Name: OpenStack Nova 2026-06-12 00:01:06.949517 | localhost | Interface IP: 38.102.83.103 2026-06-12 00:01:06.956398 | 2026-06-12 00:01:06.956474 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-12 00:01:07.377256 | localhost -> localhost | changed 2026-06-12 00:01:07.381783 | 2026-06-12 00:01:07.381863 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-12 00:01:08.298154 | localhost -> localhost | changed 2026-06-12 00:01:08.305995 | 2026-06-12 00:01:08.306089 | PLAY [all] 2026-06-12 00:01:08.313737 | 2026-06-12 00:01:08.313830 | TASK [include_role : start-zuul-console] 2026-06-12 00:01:08.333100 | testrunner | ok 2026-06-12 00:01:08.347443 | 2026-06-12 00:01:08.347582 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-12 00:01:08.754787 | testrunner | ok 2026-06-12 00:01:08.765674 | 2026-06-12 00:01:08.765758 | TASK [include_role : validate-host] 2026-06-12 00:01:08.785397 | testrunner | ok 2026-06-12 00:01:08.809037 | 2026-06-12 00:01:08.809171 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-12 00:01:08.839255 | testrunner | ok 2026-06-12 00:01:08.844605 | 2026-06-12 00:01:08.844684 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-12 00:01:09.102142 | testrunner -> localhost | ok 2026-06-12 00:01:09.108921 | 2026-06-12 00:01:09.108999 | TASK [validate-host : Collect information about the host] 2026-06-12 00:01:09.816072 | testrunner | ok 2026-06-12 00:01:09.825190 | 2026-06-12 00:01:09.825295 | TASK [validate-host : Sanitize hostname] 2026-06-12 00:01:09.872582 | testrunner | ok 2026-06-12 00:01:09.876108 | 2026-06-12 00:01:09.876173 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-12 00:01:10.477636 | testrunner -> localhost | changed 2026-06-12 00:01:10.482125 | 2026-06-12 00:01:10.482218 | TASK [validate-host : Collect information about zuul worker] 2026-06-12 00:01:10.940180 | testrunner | ok 2026-06-12 00:01:10.944437 | 2026-06-12 00:01:10.944646 | TASK [validate-host : Write out all zuul information for each host] 2026-06-12 00:01:11.375885 | testrunner -> localhost | changed 2026-06-12 00:01:11.383224 | 2026-06-12 00:01:11.383424 | TASK [include_role : prepare-workspace] 2026-06-12 00:01:11.403168 | testrunner | ok 2026-06-12 00:01:11.428753 | 2026-06-12 00:01:11.428863 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-12 00:01:11.662781 | testrunner | ok 2026-06-12 00:01:11.668436 | 2026-06-12 00:01:11.668584 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-12 00:01:12.399691 | testrunner | Output suppressed because no_log was given 2026-06-12 00:01:12.406668 | 2026-06-12 00:01:12.406793 | TASK [include_role : add-build-sshkey] 2026-06-12 00:01:12.423816 | testrunner | ok 2026-06-12 00:01:12.439627 | 2026-06-12 00:01:12.439697 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-12 00:01:12.707235 | testrunner -> localhost | ok 2026-06-12 00:01:12.711552 | 2026-06-12 00:01:12.711631 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-12 00:01:12.739172 | testrunner | ok 2026-06-12 00:01:12.751159 | testrunner | included: /var/lib/zuul/builds/a7ad667eb5474ddca4adf445afae5e64/trusted/project_2/softwarefactory-project.io/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-12 00:01:12.755589 | 2026-06-12 00:01:12.755650 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-12 00:01:13.479256 | testrunner -> localhost | Generating public/private rsa key pair. 2026-06-12 00:01:13.479640 | testrunner -> localhost | Your identification has been saved in /var/lib/zuul/builds/a7ad667eb5474ddca4adf445afae5e64/work/a7ad667eb5474ddca4adf445afae5e64_id_rsa 2026-06-12 00:01:13.479713 | testrunner -> localhost | Your public key has been saved in /var/lib/zuul/builds/a7ad667eb5474ddca4adf445afae5e64/work/a7ad667eb5474ddca4adf445afae5e64_id_rsa.pub 2026-06-12 00:01:13.479764 | testrunner -> localhost | The key fingerprint is: 2026-06-12 00:01:13.479810 | testrunner -> localhost | SHA256:imBLEgDslsydbmyspBVWGIWFd8AdVV6o6UosdxXLBqo zuul-build-sshkey 2026-06-12 00:01:13.479855 | testrunner -> localhost | The key's randomart image is: 2026-06-12 00:01:13.479901 | testrunner -> localhost | +---[RSA 3072]----+ 2026-06-12 00:01:13.479945 | testrunner -> localhost | |= .O+o.o..... | 2026-06-12 00:01:13.479990 | testrunner -> localhost | |..+ + o ..o. | 2026-06-12 00:01:13.480033 | testrunner -> localhost | |= oo.. . =.o | 2026-06-12 00:01:13.480075 | testrunner -> localhost | | Boo . o = | 2026-06-12 00:01:13.480134 | testrunner -> localhost | |o.B. o .So | 2026-06-12 00:01:13.480199 | testrunner -> localhost | | =.OE.+.o | 2026-06-12 00:01:13.480257 | testrunner -> localhost | |o.= .+.o | 2026-06-12 00:01:13.480302 | testrunner -> localhost | |.. . | 2026-06-12 00:01:13.480343 | testrunner -> localhost | | | 2026-06-12 00:01:13.480387 | testrunner -> localhost | +----[SHA256]-----+ 2026-06-12 00:01:13.480492 | testrunner -> localhost | ok: Runtime: 0:00:00.247805 2026-06-12 00:01:13.490871 | 2026-06-12 00:01:13.491010 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-12 00:01:13.524092 | testrunner | ok 2026-06-12 00:01:13.541927 | testrunner | included: /var/lib/zuul/builds/a7ad667eb5474ddca4adf445afae5e64/trusted/project_2/softwarefactory-project.io/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-12 00:01:13.552625 | 2026-06-12 00:01:13.552742 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-12 00:01:13.577141 | testrunner | skipping: Conditional result was False 2026-06-12 00:01:13.581344 | 2026-06-12 00:01:13.581417 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-12 00:01:14.066175 | testrunner | changed 2026-06-12 00:01:14.071322 | 2026-06-12 00:01:14.071430 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-12 00:01:14.353714 | testrunner | ok 2026-06-12 00:01:14.361627 | 2026-06-12 00:01:14.361809 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-12 00:01:15.236026 | testrunner | changed 2026-06-12 00:01:15.239406 | 2026-06-12 00:01:15.239475 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-12 00:01:15.998745 | testrunner | changed 2026-06-12 00:01:16.002347 | 2026-06-12 00:01:16.002420 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-12 00:01:16.037378 | testrunner | skipping: Conditional result was False 2026-06-12 00:01:16.042394 | 2026-06-12 00:01:16.042473 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-12 00:01:16.517056 | testrunner -> localhost | changed 2026-06-12 00:01:16.524342 | 2026-06-12 00:01:16.524441 | TASK [add-build-sshkey : Add back temp key] 2026-06-12 00:01:16.820358 | testrunner -> localhost | Identity added: /var/lib/zuul/builds/a7ad667eb5474ddca4adf445afae5e64/work/a7ad667eb5474ddca4adf445afae5e64_id_rsa (zuul-build-sshkey) 2026-06-12 00:01:16.820591 | testrunner -> localhost | ok: Runtime: 0:00:00.013043 2026-06-12 00:01:16.825489 | 2026-06-12 00:01:16.825585 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-12 00:01:17.263027 | testrunner | ok 2026-06-12 00:01:17.270195 | 2026-06-12 00:01:17.270342 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-12 00:01:17.311881 | testrunner | skipping: Conditional result was False 2026-06-12 00:01:17.329768 | 2026-06-12 00:01:17.329987 | TASK [include_role : prepare-workspace-openshift] 2026-06-12 00:01:17.366972 | testrunner | skipping: Conditional result was False 2026-06-12 00:01:17.383969 | 2026-06-12 00:01:17.384260 | TASK [include_role : remove-zuul-sshkey] 2026-06-12 00:01:17.402141 | testrunner | skipping: Conditional result was False 2026-06-12 00:01:17.411003 | 2026-06-12 00:01:17.411154 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-12 00:01:17.700381 | testrunner | ok: "logs" 2026-06-12 00:01:17.700880 | testrunner | ok: All items complete 2026-06-12 00:01:17.701224 | 2026-06-12 00:01:17.923164 | testrunner | ok: "artifacts" 2026-06-12 00:01:18.172823 | testrunner | ok: "docs" 2026-06-12 00:01:18.181689 | 2026-06-12 00:01:18.181829 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-12 00:01:18.462757 | testrunner | changed: "logs" 2026-06-12 00:01:18.712325 | testrunner | changed: "artifacts" 2026-06-12 00:01:18.937087 | testrunner | changed: "docs" 2026-06-12 00:01:18.946250 | 2026-06-12 00:01:18.946405 | TASK [include_role : rhel-registration] 2026-06-12 00:01:18.961206 | testrunner | skipping: Conditional result was False 2026-06-12 00:01:18.962893 | 2026-06-12 00:01:18.962947 | PLAY RECAP 2026-06-12 00:01:18.962999 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-12 00:01:18.963028 | testrunner | ok: 23 changed: 10 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 0 2026-06-12 00:01:18.963047 | 2026-06-12 00:01:19.103821 | PRE-RUN END RESULT_NORMAL: [trusted : softwarefactory-project.io/config/playbooks/base/pre.yaml@master] 2026-06-12 00:01:19.105109 | RUN START: [untrusted : softwarefactory-project.io/software-factory/k1s/playbooks/k1s-tox.yaml@master] 2026-06-12 00:01:19.740007 | 2026-06-12 00:01:19.740141 | PLAY [all] 2026-06-12 00:01:19.752089 | 2026-06-12 00:01:19.752241 | LOOP [ensure-podman : Find distribution installation] 2026-06-12 00:01:19.780323 | testrunner | ok: "/var/lib/zuul/builds/a7ad667eb5474ddca4adf445afae5e64/untrusted/project_3/softwarefactory-project.io/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml" 2026-06-12 00:01:19.792614 | testrunner | included: /var/lib/zuul/builds/a7ad667eb5474ddca4adf445afae5e64/untrusted/project_3/softwarefactory-project.io/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml 2026-06-12 00:01:19.797129 | 2026-06-12 00:01:19.797223 | TASK [ensure-podman : Install podman (RedHat)] 2026-06-12 00:02:21.283733 | testrunner | changed 2026-06-12 00:02:21.292317 | 2026-06-12 00:02:21.292434 | TASK [ensure-podman : Fetch podman version] 2026-06-11 20:02:21.823278 | testrunner | Client: Podman Engine 2026-06-11 20:02:21.823332 | testrunner | Version: 5.8.2 2026-06-11 20:02:21.823346 | testrunner | API Version: 5.8.2 2026-06-11 20:02:21.823360 | testrunner | Go Version: go1.26.1 (Red Hat 1.26.1-1.el9) 2026-06-11 20:02:21.823369 | testrunner | Built: Thu Apr 16 04:05:13 2026 2026-06-11 20:02:21.823375 | testrunner | OS/Arch: linux/amd64 2026-06-12 00:02:22.329401 | testrunner | ok: Runtime: 0:00:00.215307 2026-06-12 00:02:22.338173 | 2026-06-12 00:02:22.338325 | TASK [ensure-podman : Print podman version installed] 2026-06-12 00:02:22.384241 | Podman version: Client: Podman Engine 2026-06-12 00:02:22.384400 | Version: 5.8.2 2026-06-12 00:02:22.384433 | API Version: 5.8.2 2026-06-12 00:02:22.384461 | Go Version: go1.26.1 (Red Hat 1.26.1-1.el9) 2026-06-12 00:02:22.384483 | Built: Thu Apr 16 04:05:13 2026 2026-06-12 00:02:22.384500 | OS/Arch: linux/amd64 2026-06-12 00:02:22.388649 | 2026-06-12 00:02:22.388722 | TASK [ensure-podman : Validate podman engine] 2026-06-12 00:02:22.909759 | testrunner | skipping: Conditional result was False 2026-06-12 00:02:22.915133 | 2026-06-12 00:02:22.915247 | TASK [ensure-podman : Set up docker compatability socket] 2026-06-12 00:02:22.932135 | testrunner | skipping: Conditional result was False 2026-06-12 00:02:22.942974 | 2026-06-12 00:02:22.943116 | TASK [install tox] 2026-06-11 20:02:24.065159 | testrunner | Collecting tox 2026-06-11 20:02:24.123257 | testrunner | Downloading tox-4.30.3-py3-none-any.whl (175 kB) 2026-06-11 20:02:24.211063 | testrunner | Collecting cachetools>=6.1 2026-06-11 20:02:24.221885 | testrunner | Downloading cachetools-6.2.6-py3-none-any.whl (11 kB) 2026-06-11 20:02:24.310110 | testrunner | Collecting colorama>=0.4.6 2026-06-11 20:02:24.320981 | testrunner | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-06-11 20:02:24.466528 | testrunner | Collecting chardet>=5.2 2026-06-11 20:02:24.479216 | testrunner | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2026-06-11 20:02:24.685943 | testrunner | Collecting virtualenv>=20.31.2 2026-06-11 20:02:24.696905 | testrunner | Downloading virtualenv-21.4.3-py3-none-any.whl (7.6 MB) 2026-06-11 20:02:24.822100 | testrunner | Collecting pyproject-api>=1.9.1 2026-06-11 20:02:24.835406 | testrunner | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2026-06-11 20:02:24.881594 | testrunner | Collecting pluggy>=1.6 2026-06-11 20:02:24.892397 | testrunner | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-06-11 20:02:25.001651 | testrunner | Collecting tomli>=2.2.1 2026-06-11 20:02:25.012300 | testrunner | Downloading tomli-2.4.1-py3-none-any.whl (14 kB) 2026-06-11 20:02:25.075336 | testrunner | Collecting typing-extensions>=4.14.1 2026-06-11 20:02:25.086615 | testrunner | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-06-11 20:02:25.157467 | testrunner | Collecting packaging>=25 2026-06-11 20:02:25.168822 | testrunner | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-06-11 20:02:25.238242 | testrunner | Collecting platformdirs>=4.3.8 2026-06-11 20:02:25.248956 | testrunner | Downloading platformdirs-4.4.0-py3-none-any.whl (18 kB) 2026-06-11 20:02:25.330460 | testrunner | Collecting filelock>=3.18 2026-06-11 20:02:25.341443 | testrunner | Downloading filelock-3.19.1-py3-none-any.whl (15 kB) 2026-06-11 20:02:25.423463 | testrunner | Collecting distlib<1,>=0.3.7 2026-06-11 20:02:25.434647 | testrunner | Downloading distlib-0.4.2-py2.py3-none-any.whl (470 kB) 2026-06-11 20:02:25.477529 | testrunner | Collecting python-discovery>=1.4.2 2026-06-11 20:02:25.499033 | testrunner | Downloading python_discovery-1.4.2-py3-none-any.whl (33 kB) 2026-06-11 20:02:25.688919 | testrunner | Installing collected packages: platformdirs, filelock, typing-extensions, tomli, python-discovery, packaging, distlib, virtualenv, pyproject-api, pluggy, colorama, chardet, cachetools, tox 2026-06-11 20:02:26.013727 | testrunner | WARNING: The script virtualenv is installed in '/usr/local/bin' which is not on PATH. 2026-06-11 20:02:26.013809 | testrunner | Consider adding this directory to PATH or, if you prefer to suppress this warning, use --no-warn-script-location. 2026-06-11 20:02:26.240495 | testrunner | WARNING: The script chardetect is installed in '/usr/local/bin' which is not on PATH. 2026-06-11 20:02:26.240544 | testrunner | Consider adding this directory to PATH or, if you prefer to suppress this warning, use --no-warn-script-location. 2026-06-11 20:02:26.424129 | testrunner | WARNING: The script tox is installed in '/usr/local/bin' which is not on PATH. 2026-06-11 20:02:26.424182 | testrunner | Consider adding this directory to PATH or, if you prefer to suppress this warning, use --no-warn-script-location. 2026-06-11 20:02:26.448411 | 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-11 20:02:26.448457 | testrunner | requests 2.25.1 requires chardet<5,>=3.0.2, but you have chardet 5.2.0 which is incompatible. 2026-06-11 20:02:26.448470 | testrunner | Successfully installed cachetools-6.2.6 chardet-5.2.0 colorama-0.4.6 distlib-0.4.2 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-11 20:02:26.448476 | 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-12 00:02:26.972239 | testrunner | ok: Runtime: 0:00:03.521169 2026-06-12 00:02:26.976111 | 2026-06-12 00:02:26.976190 | TASK [Install kubectl] 2026-06-12 00:02:29.866555 | testrunner | changed: OK (49258496 bytes) 2026-06-12 00:02:29.870715 | 2026-06-12 00:02:29.870812 | TASK [run tox pep8] 2026-06-11 20:02:30.859165 | testrunner | pep8: install_deps> python -I -m pip install ansible flake8 mypy openshift==0.13.2 pytest -r requirements.txt 2026-06-11 20:03:05.428104 | testrunner | .pkg: install_requires> python -I -m pip install 'setuptools>=40.8.0' 2026-06-11 20:03:06.117017 | testrunner | .pkg: _optional_hooks> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__ 2026-06-11 20:03:06.406725 | 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-11 20:03:06.626202 | 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-11 20:03:06.827544 | 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-11 20:03:07.030438 | testrunner | .pkg: build_sdist> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__ 2026-06-11 20:03:07.233255 | testrunner | pep8: install_package_deps> python -I -m pip install cherrypy routes 2026-06-11 20:03:08.080738 | 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-11 20:03:10.090177 | testrunner | pep8: commands[0]> mypy --ignore-missing-imports k1s/api.py 2026-06-11 20:03:11.494163 | testrunner | Success: no issues found in 1 source file 2026-06-11 20:03:11.499020 | testrunner | pep8: commands[1]> flake8 k1s 2026-06-11 20:03:11.922741 | testrunner | pep8: OK (41.60=setup[39.77]+cmd[1.41,0.42] seconds) 2026-06-11 20:03:11.922892 | testrunner | congratulations :) (41.63 seconds) 2026-06-12 00:03:12.457984 | testrunner | ok: Runtime: 0:00:41.861881 2026-06-12 00:03:12.462346 | 2026-06-12 00:03:12.462412 | TASK [run tox py39] 2026-06-11 20:03:13.157237 | testrunner | py39: install_deps> python -I -m pip install ansible flake8 mypy openshift==0.13.2 pytest -r requirements.txt 2026-06-11 20:03:54.438767 | testrunner | .pkg: _optional_hooks> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__ 2026-06-11 20:03:54.600720 | 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-11 20:03:54.795707 | 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-11 20:03:55.004522 | 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-11 20:03:55.203872 | testrunner | .pkg: build_sdist> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__ 2026-06-11 20:03:55.440047 | testrunner | py39: install_package_deps> python -I -m pip install cherrypy routes 2026-06-11 20:03:56.077658 | 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-11 20:03:58.010533 | testrunner | py39: commands[0]> pytest -v tests.py 2026-06-11 20:03:58.206777 | testrunner | ============================= test session starts ============================== 2026-06-11 20:03:58.206876 | 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-11 20:03:58.206885 | testrunner | cachedir: .tox/py39/.pytest_cache 2026-06-11 20:03:58.206892 | testrunner | rootdir: /home/zuul-worker/src/softwarefactory-project.io/software-factory/k1s 2026-06-11 20:03:58.628842 | testrunner | collecting ... collected 5 items 2026-06-11 20:03:58.628881 | testrunner | 2026-06-11 20:04:50.135472 | testrunner | tests.py::K1sTestCase::test_ansible PASSED [ 20%] 2026-06-11 20:04:50.388929 | testrunner | tests.py::K1sTestCase::test_bad_token PASSED [ 40%] 2026-06-11 20:04:53.359148 | testrunner | tests.py::K1sTestCase::test_create PASSED [ 60%] 2026-06-11 20:04:58.078383 | testrunner | tests.py::K1sTestCase::test_kubectl PASSED [ 80%] 2026-06-11 20:05:02.028435 | testrunner | tests.py::K1sTestCase::test_python_client PASSED [100%] 2026-06-11 20:05:02.028507 | testrunner | 2026-06-11 20:05:02.028512 | testrunner | =============================== warnings summary =============================== 2026-06-11 20:05:02.028516 | testrunner | tests.py::K1sTestCase::test_bad_token 2026-06-11 20:05:02.028522 | testrunner | tests.py::K1sTestCase::test_create 2026-06-11 20:05:02.028525 | testrunner | tests.py::K1sTestCase::test_create 2026-06-11 20:05:02.028529 | testrunner | tests.py::K1sTestCase::test_create 2026-06-11 20:05:02.028532 | testrunner | tests.py::K1sTestCase::test_create 2026-06-11 20:05:02.028536 | testrunner | tests.py::K1sTestCase::test_python_client 2026-06-11 20:05:02.028540 | 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-11 20:05:02.028545 | testrunner | warnings.warn( 2026-06-11 20:05:02.028549 | testrunner | 2026-06-11 20:05:02.028552 | testrunner | -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 2026-06-11 20:05:02.028556 | testrunner | =================== 5 passed, 6 warnings in 63.66s (0:01:03) =================== 2026-06-11 20:05:02.170848 | testrunner | py39: OK (109.24=setup[45.08]+cmd[64.16] seconds) 2026-06-11 20:05:02.170883 | testrunner | congratulations :) (109.27 seconds) 2026-06-12 00:05:02.249080 | testrunner | ok: Runtime: 0:01:49.507963 2026-06-12 00:05:02.252505 | 2026-06-12 00:05:02.252588 | PLAY RECAP 2026-06-12 00:05:02.252630 | testrunner | ok: 8 changed: 6 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-12 00:05:02.252650 | 2026-06-12 00:05:02.497956 | RUN END RESULT_NORMAL: [untrusted : softwarefactory-project.io/software-factory/k1s/playbooks/k1s-tox.yaml@master] 2026-06-12 00:05:02.499179 | POST-RUN START: [trusted : softwarefactory-project.io/config/playbooks/base/post.yaml@master] 2026-06-12 00:05:03.336139 | 2026-06-12 00:05:03.336346 | PLAY [all] 2026-06-12 00:05:03.348422 | 2026-06-12 00:05:03.348600 | TASK [include_role : fetch-output] 2026-06-12 00:05:03.390868 | testrunner | ok 2026-06-12 00:05:03.410964 | 2026-06-12 00:05:03.411120 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-12 00:05:03.467449 | testrunner | skipping: Conditional result was False 2026-06-12 00:05:03.472950 | 2026-06-12 00:05:03.473302 | TASK [fetch-output : Set log path for single node] 2026-06-12 00:05:03.518143 | testrunner | ok 2026-06-12 00:05:03.522243 | 2026-06-12 00:05:03.522349 | LOOP [fetch-output : Ensure local output dirs] 2026-06-12 00:05:03.998048 | testrunner -> localhost | changed: "/var/lib/zuul/builds/a7ad667eb5474ddca4adf445afae5e64/work/logs" 2026-06-12 00:05:04.299188 | testrunner -> localhost | changed: "/var/lib/zuul/builds/a7ad667eb5474ddca4adf445afae5e64/work/artifacts" 2026-06-12 00:05:04.583926 | testrunner -> localhost | changed: "/var/lib/zuul/builds/a7ad667eb5474ddca4adf445afae5e64/work/docs" 2026-06-12 00:05:04.596372 | 2026-06-12 00:05:04.596480 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-12 00:05:05.320022 | testrunner | ok 2026-06-12 00:05:05.320215 | testrunner | changed: All items complete 2026-06-12 00:05:05.320242 | 2026-06-12 00:05:05.833552 | testrunner | changed: .d..t...... ./ 2026-06-12 00:05:06.322747 | testrunner | changed: .d..t...... ./ 2026-06-12 00:05:06.334120 | 2026-06-12 00:05:06.334250 | TASK [include_role : fetch-output-openshift] 2026-06-12 00:05:06.348394 | testrunner | skipping: Conditional result was False 2026-06-12 00:05:06.354913 | 2026-06-12 00:05:06.355003 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-12 00:05:06.381639 | testrunner | skipping: Conditional result was False 2026-06-12 00:05:06.389587 | testrunner | skipping: Conditional result was False 2026-06-12 00:05:06.410921 | 2026-06-12 00:05:06.411070 | TASK [include_role : rhel-registration] 2026-06-12 00:05:06.436104 | testrunner | skipping: Conditional result was False 2026-06-12 00:05:06.439443 | 2026-06-12 00:05:06.439548 | PLAY [localhost] 2026-06-12 00:05:06.447995 | 2026-06-12 00:05:06.448157 | TASK [add-fileserver : Create SSH private key tempfile] 2026-06-12 00:05:06.903668 | localhost | changed 2026-06-12 00:05:06.908357 | 2026-06-12 00:05:06.908476 | TASK [add-fileserver : Create SSH private key from secret] 2026-06-12 00:05:07.783354 | localhost | changed 2026-06-12 00:05:07.788575 | 2026-06-12 00:05:07.788681 | TASK [add-fileserver : Add fileserver ssh key] 2026-06-12 00:05:08.273725 | localhost | Identity added: /var/lib/zuul/builds/a7ad667eb5474ddca4adf445afae5e64/work/tmp/ansible.e6p2_ah7 (/var/lib/zuul/builds/a7ad667eb5474ddca4adf445afae5e64/work/tmp/ansible.e6p2_ah7) 2026-06-12 00:05:08.273978 | localhost | ok: Runtime: 0:00:00.012263 2026-06-12 00:05:08.325854 | 2026-06-12 00:05:08.326001 | TASK [add-fileserver : Remove SSH private key from disk] 2026-06-12 00:05:08.612407 | localhost | ok: Runtime: 0:00:00.005408 2026-06-12 00:05:08.616959 | 2026-06-12 00:05:08.617058 | TASK [add-fileserver : Add fileserver to inventory] 2026-06-12 00:05:08.677824 | localhost | changed 2026-06-12 00:05:08.681912 | 2026-06-12 00:05:08.682035 | TASK [add-fileserver : Add fileserver server to known hosts] 2026-06-12 00:05:09.179936 | localhost | changed 2026-06-12 00:05:09.193230 | 2026-06-12 00:05:09.193354 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-12 00:05:09.580647 | localhost | changed 2026-06-12 00:05:09.593375 | 2026-06-12 00:05:09.593509 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-12 00:05:09.622612 | localhost | ok 2026-06-12 00:05:09.630918 | 2026-06-12 00:05:09.631052 | TASK [include_role : report-logjuicer] 2026-06-12 00:05:09.670073 | localhost | skipping: Conditional result was False 2026-06-12 00:05:09.673293 | 2026-06-12 00:05:09.673385 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com] 2026-06-12 00:05:09.678835 | 2026-06-12 00:05:09.678933 | TASK [Set zuul-log-path fact] 2026-06-12 00:05:09.716148 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-12 00:05:09.724304 | 2026-06-12 00:05:09.724381 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-12 00:05:09.749923 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-12 00:05:09.753785 | 2026-06-12 00:05:09.754549 | TASK [upload-logs : Create log directories] 2026-06-12 00:05:10.584157 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed 2026-06-12 00:05:10.587129 | 2026-06-12 00:05:10.587266 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-12 00:05:10.913766 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.005748 2026-06-12 00:05:10.917036 | 2026-06-12 00:05:10.917166 | TASK [upload-logs : Upload logs to log server] 2026-06-12 00:05:12.101723 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed: 2026-06-12 00:05:12.101897 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | .d..tp..... ./ 2026-06-12 00:05:12.101925 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com |