2026-06-11 00:01:05.616819 | Job console starting
2026-06-11 00:01:05.731252 | Updating git repos
2026-06-11 00:01:05.769242 | Cloning repos into workspace
2026-06-11 00:01:06.183277 | Restoring repo states
2026-06-11 00:01:06.201732 | Merging changes
2026-06-11 00:01:06.201756 | Checking out repos
2026-06-11 00:01:06.281664 | Preparing playbooks
2026-06-11 00:01:12.503465 | Running Ansible setup
2026-06-11 00:01:16.390262 | PRE-RUN START: [trusted : softwarefactory-project.io/config/playbooks/base/pre.yaml@master]
2026-06-11 00:01:17.052743 | 
2026-06-11 00:01:17.052864 | PLAY [localhost]
2026-06-11 00:01:17.060204 | 
2026-06-11 00:01:17.060282 | TASK [Gathering Facts]
2026-06-11 00:01:18.020868 | localhost | ok
2026-06-11 00:01:18.034381 | 
2026-06-11 00:01:18.034610 | TASK [Setup log path fact]
2026-06-11 00:01:18.055995 | localhost | ok
2026-06-11 00:01:18.078753 | 
2026-06-11 00:01:18.078961 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-11 00:01:18.108764 | localhost | ok
2026-06-11 00:01:18.116587 | 
2026-06-11 00:01:18.116692 | TASK [emit-job-header : Print job information]
2026-06-11 00:01:18.149476 | # Job Information
2026-06-11 00:01:18.149866 | Ansible Version: 2.16.18
2026-06-11 00:01:18.149974 | Job: k1s-tox
2026-06-11 00:01:18.150047 | Pipeline: periodic
2026-06-11 00:01:18.150110 | Executor: 38.102.83.51
2026-06-11 00:01:18.150174 | Triggered by: https://softwarefactory-project.io/r/plugins/gitiles/software-factory/k1s/+/None^!/
2026-06-11 00:01:18.150246 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/d18/local/d181d8c58f234a6392a04209cbae7951/
2026-06-11 00:01:18.150312 | Event ID: bcac0573ff4140eda5263875b8b1e22c
2026-06-11 00:01:18.158307 | 
2026-06-11 00:01:18.158530 | LOOP [emit-job-header : Print node information]
2026-06-11 00:01:18.279502 | localhost | ok:
2026-06-11 00:01:18.279682 | localhost | # Node Information
2026-06-11 00:01:18.279707 | localhost | Inventory Hostname: testrunner
2026-06-11 00:01:18.279725 | localhost | Hostname: np0000070224
2026-06-11 00:01:18.279743 | localhost | Username: zuul-worker
2026-06-11 00:01:18.279762 | localhost | Distro: CentOS 9
2026-06-11 00:01:18.279778 | localhost | Provider: vexxhost-nodepool-sf
2026-06-11 00:01:18.279794 | localhost | Region: RegionOne
2026-06-11 00:01:18.279810 | localhost | Label: cloud-centos-9-stream
2026-06-11 00:01:18.279826 | localhost | Product Name: OpenStack Nova
2026-06-11 00:01:18.279868 | localhost | Interface IP: 38.102.83.58
2026-06-11 00:01:18.306070 | 
2026-06-11 00:01:18.306371 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-11 00:01:18.744316 | localhost -> localhost | changed
2026-06-11 00:01:18.748809 | 
2026-06-11 00:01:18.748871 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-11 00:01:19.637771 | localhost -> localhost | changed
2026-06-11 00:01:19.649848 | 
2026-06-11 00:01:19.649986 | PLAY [all]
2026-06-11 00:01:19.661378 | 
2026-06-11 00:01:19.661515 | TASK [include_role : start-zuul-console]
2026-06-11 00:01:19.685518 | testrunner | ok
2026-06-11 00:01:19.706189 | 
2026-06-11 00:01:19.706326 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-06-11 00:01:20.143782 | testrunner | ok
2026-06-11 00:01:20.151019 | 
2026-06-11 00:01:20.151110 | TASK [include_role : validate-host]
2026-06-11 00:01:20.179165 | testrunner | ok
2026-06-11 00:01:20.210493 | 
2026-06-11 00:01:20.210674 | TASK [validate-host : Define zuul_info_dir fact]
2026-06-11 00:01:20.264452 | testrunner | ok
2026-06-11 00:01:20.272291 | 
2026-06-11 00:01:20.272449 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-06-11 00:01:20.557275 | testrunner -> localhost | ok
2026-06-11 00:01:20.566929 | 
2026-06-11 00:01:20.567072 | TASK [validate-host : Collect information about the host]
2026-06-11 00:01:21.269968 | testrunner | ok
2026-06-11 00:01:21.288404 | 
2026-06-11 00:01:21.288540 | TASK [validate-host : Sanitize hostname]
2026-06-11 00:01:21.364993 | testrunner | ok
2026-06-11 00:01:21.372620 | 
2026-06-11 00:01:21.372766 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-06-11 00:01:21.917311 | testrunner -> localhost | changed
2026-06-11 00:01:21.927830 | 
2026-06-11 00:01:21.927970 | TASK [validate-host : Collect information about zuul worker]
2026-06-11 00:01:22.397511 | testrunner | ok
2026-06-11 00:01:22.404560 | 
2026-06-11 00:01:22.404683 | TASK [validate-host : Write out all zuul information for each host]
2026-06-11 00:01:23.007435 | testrunner -> localhost | changed
2026-06-11 00:01:23.016730 | 
2026-06-11 00:01:23.016819 | TASK [include_role : prepare-workspace]
2026-06-11 00:01:23.047279 | testrunner | ok
2026-06-11 00:01:23.081718 | 
2026-06-11 00:01:23.081809 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-11 00:01:23.386048 | testrunner | ok
2026-06-11 00:01:23.394789 | 
2026-06-11 00:01:23.394935 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-11 00:01:24.190212 | testrunner | Output suppressed because no_log was given
2026-06-11 00:01:24.202694 | 
2026-06-11 00:01:24.202826 | TASK [include_role : add-build-sshkey]
2026-06-11 00:01:24.236291 | testrunner | ok
2026-06-11 00:01:24.267719 | 
2026-06-11 00:01:24.267810 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-11 00:01:24.541447 | testrunner -> localhost | ok
2026-06-11 00:01:24.550302 | 
2026-06-11 00:01:24.550480 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-11 00:01:24.583842 | testrunner | ok
2026-06-11 00:01:24.609151 | testrunner | included: /var/lib/zuul/builds/d181d8c58f234a6392a04209cbae7951/trusted/project_2/softwarefactory-project.io/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-06-11 00:01:24.617542 | 
2026-06-11 00:01:24.617666 | TASK [add-build-sshkey : Create Temp SSH key]
2026-06-11 00:01:25.242925 | testrunner -> localhost | Generating public/private rsa key pair.
2026-06-11 00:01:25.243113 | testrunner -> localhost | Your identification has been saved in /var/lib/zuul/builds/d181d8c58f234a6392a04209cbae7951/work/d181d8c58f234a6392a04209cbae7951_id_rsa
2026-06-11 00:01:25.243143 | testrunner -> localhost | Your public key has been saved in /var/lib/zuul/builds/d181d8c58f234a6392a04209cbae7951/work/d181d8c58f234a6392a04209cbae7951_id_rsa.pub
2026-06-11 00:01:25.243164 | testrunner -> localhost | The key fingerprint is:
2026-06-11 00:01:25.243183 | testrunner -> localhost | SHA256:v7H+Wn8VfHDJ5damdOx4eJS3/vSrL5RLV52iFiMYNIE zuul-build-sshkey
2026-06-11 00:01:25.243204 | testrunner -> localhost | The key's randomart image is:
2026-06-11 00:01:25.243222 | testrunner -> localhost | +---[RSA 3072]----+
2026-06-11 00:01:25.243241 | testrunner -> localhost | |      o+.     . +|
2026-06-11 00:01:25.243260 | testrunner -> localhost | |     E ..     .=+|
2026-06-11 00:01:25.243278 | testrunner -> localhost | |        o     o+%|
2026-06-11 00:01:25.243297 | testrunner -> localhost | |       . . o o &*|
2026-06-11 00:01:25.243315 | testrunner -> localhost | |        S . + *.B|
2026-06-11 00:01:25.243332 | testrunner -> localhost | |         . o +.+.|
2026-06-11 00:01:25.292430 | testrunner -> localhost | |          + + o.o|
2026-06-11 00:01:25.292535 | testrunner -> localhost | |           = + .+|
2026-06-11 00:01:25.292560 | testrunner -> localhost | |         .=o..=++|
2026-06-11 00:01:25.292579 | testrunner -> localhost | +----[SHA256]-----+
2026-06-11 00:01:25.292657 | testrunner -> localhost | ok: Runtime: 0:00:00.144141
2026-06-11 00:01:25.301323 | 
2026-06-11 00:01:25.301691 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-06-11 00:01:25.329285 | testrunner | ok
2026-06-11 00:01:25.337042 | testrunner | included: /var/lib/zuul/builds/d181d8c58f234a6392a04209cbae7951/trusted/project_2/softwarefactory-project.io/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-06-11 00:01:25.344013 | 
2026-06-11 00:01:25.344080 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-06-11 00:01:25.368157 | testrunner | skipping: Conditional result was False
2026-06-11 00:01:25.372993 | 
2026-06-11 00:01:25.373069 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-06-11 00:01:25.850293 | testrunner | changed
2026-06-11 00:01:25.858455 | 
2026-06-11 00:01:25.858598 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-06-11 00:01:26.106810 | testrunner | ok
2026-06-11 00:01:26.114467 | 
2026-06-11 00:01:26.114612 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-06-11 00:01:26.861435 | testrunner | changed
2026-06-11 00:01:26.868886 | 
2026-06-11 00:01:26.869020 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-06-11 00:01:27.601513 | testrunner | changed
2026-06-11 00:01:27.605987 | 
2026-06-11 00:01:27.606073 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-06-11 00:01:27.640561 | testrunner | skipping: Conditional result was False
2026-06-11 00:01:27.644771 | 
2026-06-11 00:01:27.644838 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-11 00:01:28.133676 | testrunner -> localhost | changed
2026-06-11 00:01:28.143866 | 
2026-06-11 00:01:28.143974 | TASK [add-build-sshkey : Add back temp key]
2026-06-11 00:01:28.538147 | testrunner -> localhost | Identity added: /var/lib/zuul/builds/d181d8c58f234a6392a04209cbae7951/work/d181d8c58f234a6392a04209cbae7951_id_rsa (zuul-build-sshkey)
2026-06-11 00:01:28.538377 | testrunner -> localhost | ok: Runtime: 0:00:00.017999
2026-06-11 00:01:28.543412 | 
2026-06-11 00:01:28.543481 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-06-11 00:01:28.957466 | testrunner | ok
2026-06-11 00:01:28.960678 | 
2026-06-11 00:01:28.960741 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-06-11 00:01:29.011640 | testrunner | skipping: Conditional result was False
2026-06-11 00:01:29.021025 | 
2026-06-11 00:01:29.021130 | TASK [include_role : prepare-workspace-openshift]
2026-06-11 00:01:29.046306 | testrunner | skipping: Conditional result was False
2026-06-11 00:01:29.052292 | 
2026-06-11 00:01:29.052453 | TASK [include_role : remove-zuul-sshkey]
2026-06-11 00:01:29.076969 | testrunner | skipping: Conditional result was False
2026-06-11 00:01:29.082521 | 
2026-06-11 00:01:29.082620 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-11 00:01:29.360558 | testrunner | ok: "logs"
2026-06-11 00:01:29.361146 | testrunner | ok: All items complete
2026-06-11 00:01:29.361213 | 
2026-06-11 00:01:29.594619 | testrunner | ok: "artifacts"
2026-06-11 00:01:29.804186 | testrunner | ok: "docs"
2026-06-11 00:01:29.811502 | 
2026-06-11 00:01:29.811636 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-11 00:01:30.071420 | testrunner | changed: "logs"
2026-06-11 00:01:30.351204 | testrunner | changed: "artifacts"
2026-06-11 00:01:30.567393 | testrunner | changed: "docs"
2026-06-11 00:01:30.591317 | 
2026-06-11 00:01:30.591535 | TASK [include_role : rhel-registration]
2026-06-11 00:01:30.628668 | testrunner | skipping: Conditional result was False
2026-06-11 00:01:30.631043 | 
2026-06-11 00:01:30.631110 | PLAY RECAP
2026-06-11 00:01:30.631183 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-11 00:01:30.631222 | testrunner | ok: 23 changed: 10 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 0
2026-06-11 00:01:30.631247 | 
2026-06-11 00:01:30.818500 | PRE-RUN END RESULT_NORMAL: [trusted : softwarefactory-project.io/config/playbooks/base/pre.yaml@master]
2026-06-11 00:01:30.821292 | RUN START: [untrusted : softwarefactory-project.io/software-factory/k1s/playbooks/k1s-tox.yaml@master]
2026-06-11 00:01:31.466567 | 
2026-06-11 00:01:31.466908 | PLAY [all]
2026-06-11 00:01:31.478687 | 
2026-06-11 00:01:31.478782 | LOOP [ensure-podman : Find distribution installation]
2026-06-11 00:01:31.502316 | testrunner | ok: "/var/lib/zuul/builds/d181d8c58f234a6392a04209cbae7951/untrusted/project_3/softwarefactory-project.io/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml"
2026-06-11 00:01:31.508007 | testrunner | included: /var/lib/zuul/builds/d181d8c58f234a6392a04209cbae7951/untrusted/project_3/softwarefactory-project.io/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml
2026-06-11 00:01:31.512451 | 
2026-06-11 00:01:31.512520 | TASK [ensure-podman : Install podman (RedHat)]
2026-06-11 00:02:20.731605 | testrunner | changed
2026-06-11 00:02:20.744943 | 
2026-06-11 00:02:20.745142 | TASK [ensure-podman : Fetch podman version]
2026-06-10 20:02:21.452305 | testrunner | Client:       Podman Engine
2026-06-10 20:02:21.452387 | testrunner | Version:      5.8.2
2026-06-10 20:02:21.452396 | testrunner | API Version:  5.8.2
2026-06-10 20:02:21.452402 | testrunner | Go Version:   go1.26.1 (Red Hat 1.26.1-1.el9)
2026-06-10 20:02:21.452408 | testrunner | Built:        Thu Apr 16 04:05:13 2026
2026-06-10 20:02:21.452414 | testrunner | OS/Arch:      linux/amd64
2026-06-11 00:02:21.814943 | testrunner | ok: Runtime: 0:00:00.399352
2026-06-11 00:02:21.821554 | 
2026-06-11 00:02:21.821682 | TASK [ensure-podman : Print podman version installed]
2026-06-11 00:02:21.853177 | Podman version: Client:       Podman Engine
2026-06-11 00:02:21.853430 | Version:      5.8.2
2026-06-11 00:02:21.853493 | API Version:  5.8.2
2026-06-11 00:02:21.853527 | Go Version:   go1.26.1 (Red Hat 1.26.1-1.el9)
2026-06-11 00:02:21.853556 | Built:        Thu Apr 16 04:05:13 2026
2026-06-11 00:02:21.853582 | OS/Arch:      linux/amd64
2026-06-11 00:02:21.860853 | 
2026-06-11 00:02:21.861053 | TASK [ensure-podman : Validate podman engine]
2026-06-11 00:02:22.390087 | testrunner | skipping: Conditional result was False
2026-06-11 00:02:22.399592 | 
2026-06-11 00:02:22.399733 | TASK [ensure-podman : Set up docker compatability socket]
2026-06-11 00:02:22.425976 | testrunner | skipping: Conditional result was False
2026-06-11 00:02:22.446934 | 
2026-06-11 00:02:22.447096 | TASK [install tox]
2026-06-10 20:02:23.630853 | testrunner | Collecting tox
2026-06-10 20:02:23.681243 | testrunner |   Downloading tox-4.30.3-py3-none-any.whl (175 kB)
2026-06-10 20:02:23.809576 | testrunner | Collecting filelock>=3.18
2026-06-10 20:02:23.815873 | testrunner |   Downloading filelock-3.19.1-py3-none-any.whl (15 kB)
2026-06-10 20:02:23.940647 | testrunner | Collecting tomli>=2.2.1
2026-06-10 20:02:23.944696 | testrunner |   Downloading tomli-2.4.1-py3-none-any.whl (14 kB)
2026-06-10 20:02:23.990482 | testrunner | Collecting colorama>=0.4.6
2026-06-10 20:02:23.997392 | testrunner |   Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB)
2026-06-10 20:02:24.055970 | testrunner | Collecting typing-extensions>=4.14.1
2026-06-10 20:02:24.060175 | testrunner |   Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB)
2026-06-10 20:02:24.333322 | testrunner | Collecting virtualenv>=20.31.2
2026-06-10 20:02:24.347411 | testrunner |   Downloading virtualenv-21.4.2-py3-none-any.whl (7.6 MB)
2026-06-10 20:02:24.659436 | testrunner | Collecting chardet>=5.2
2026-06-10 20:02:24.665569 | testrunner |   Downloading chardet-5.2.0-py3-none-any.whl (199 kB)
2026-06-10 20:02:24.739342 | testrunner | Collecting platformdirs>=4.3.8
2026-06-10 20:02:24.743565 | testrunner |   Downloading platformdirs-4.4.0-py3-none-any.whl (18 kB)
2026-06-10 20:02:24.859690 | testrunner | Collecting packaging>=25
2026-06-10 20:02:24.863863 | testrunner |   Downloading packaging-26.2-py3-none-any.whl (100 kB)
2026-06-10 20:02:24.906056 | testrunner | Collecting pyproject-api>=1.9.1
2026-06-10 20:02:24.913907 | testrunner |   Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB)
2026-06-10 20:02:24.962236 | testrunner | Collecting pluggy>=1.6
2026-06-10 20:02:24.966480 | testrunner |   Downloading pluggy-1.6.0-py3-none-any.whl (20 kB)
2026-06-10 20:02:25.025712 | testrunner | Collecting cachetools>=6.1
2026-06-10 20:02:25.033140 | testrunner |   Downloading cachetools-6.2.6-py3-none-any.whl (11 kB)
2026-06-10 20:02:25.116783 | testrunner | Collecting distlib<1,>=0.3.7
2026-06-10 20:02:25.122208 | testrunner |   Downloading distlib-0.4.2-py2.py3-none-any.whl (470 kB)
2026-06-10 20:02:25.164201 | testrunner | Collecting python-discovery>=1.4
2026-06-10 20:02:25.168588 | testrunner |   Downloading python_discovery-1.4.0-py3-none-any.whl (33 kB)
2026-06-10 20:02:25.322881 | testrunner | Installing collected packages: platformdirs, filelock, typing-extensions, tomli, python-discovery, packaging, distlib, virtualenv, pyproject-api, pluggy, colorama, chardet, cachetools, tox
2026-06-10 20:02:25.691934 | testrunner |   WARNING: The script virtualenv is installed in '/usr/local/bin' which is not on PATH.
2026-06-10 20:02:25.691975 | testrunner |   Consider adding this directory to PATH or, if you prefer to suppress this warning, use --no-warn-script-location.
2026-06-10 20:02:25.920800 | testrunner |   WARNING: The script chardetect is installed in '/usr/local/bin' which is not on PATH.
2026-06-10 20:02:25.920842 | testrunner |   Consider adding this directory to PATH or, if you prefer to suppress this warning, use --no-warn-script-location.
2026-06-10 20:02:26.062518 | testrunner |   WARNING: The script tox is installed in '/usr/local/bin' which is not on PATH.
2026-06-10 20:02:26.062612 | testrunner |   Consider adding this directory to PATH or, if you prefer to suppress this warning, use --no-warn-script-location.
2026-06-10 20:02:26.079140 | 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-10 20:02:26.079283 | testrunner | requests 2.25.1 requires chardet<5,>=3.0.2, but you have chardet 5.2.0 which is incompatible.
2026-06-10 20:02:26.079306 | 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.0 tomli-2.4.1 tox-4.30.3 typing-extensions-4.15.0 virtualenv-21.4.2
2026-06-10 20:02:26.079315 | 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-11 00:02:26.521171 | testrunner | ok: Runtime: 0:00:03.750608
2026-06-11 00:02:26.524928 | 
2026-06-11 00:02:26.524996 | TASK [Install kubectl]
2026-06-11 00:02:42.609916 | testrunner | changed: OK (49258496 bytes)
2026-06-11 00:02:42.618112 | 
2026-06-11 00:02:42.618238 | TASK [run tox pep8]
2026-06-10 20:02:43.692938 | testrunner | pep8: install_deps> python -I -m pip install ansible flake8 mypy openshift==0.13.2 pytest -r requirements.txt
2026-06-10 20:03:33.511303 | testrunner | .pkg: install_requires> python -I -m pip install 'setuptools>=40.8.0'
2026-06-10 20:03:34.245478 | testrunner | .pkg: _optional_hooks> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
2026-06-10 20:03:34.566513 | 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-10 20:03:34.777674 | 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-10 20:03:34.959394 | 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-10 20:03:35.164057 | testrunner | .pkg: build_sdist> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
2026-06-10 20:03:35.380296 | testrunner | pep8: install_package_deps> python -I -m pip install cherrypy routes
2026-06-10 20:03:36.363812 | 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-10 20:03:38.547286 | testrunner | pep8: commands[0]> mypy --ignore-missing-imports k1s/api.py
2026-06-10 20:03:40.101285 | testrunner | Success: no issues found in 1 source file
2026-06-10 20:03:40.106717 | testrunner | pep8: commands[1]> flake8 k1s
2026-06-10 20:03:40.384377 | testrunner |   pep8: OK (57.26=setup[55.43]+cmd[1.56,0.27] seconds)
2026-06-10 20:03:40.384427 | testrunner |   congratulations :) (57.29 seconds)
2026-06-11 00:03:40.796210 | testrunner | ok: Runtime: 0:00:57.544346
2026-06-11 00:03:40.804957 | 
2026-06-11 00:03:40.805178 | TASK [run tox py39]
2026-06-10 20:03:41.509398 | testrunner | py39: install_deps> python -I -m pip install ansible flake8 mypy openshift==0.13.2 pytest -r requirements.txt
2026-06-10 20:04:20.559953 | testrunner | .pkg: _optional_hooks> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
2026-06-10 20:04:20.737778 | 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-10 20:04:20.916352 | 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-10 20:04:21.095634 | 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-10 20:04:21.293988 | testrunner | .pkg: build_sdist> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
2026-06-10 20:04:21.537062 | testrunner | py39: install_package_deps> python -I -m pip install cherrypy routes
2026-06-10 20:04:22.061524 | 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-10 20:04:24.075130 | testrunner | py39: commands[0]> pytest -v tests.py
2026-06-10 20:04:24.284400 | testrunner | ============================= test session starts ==============================
2026-06-10 20:04:24.284472 | 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-10 20:04:24.284480 | testrunner | cachedir: .tox/py39/.pytest_cache
2026-06-10 20:04:24.284487 | testrunner | rootdir: /home/zuul-worker/src/softwarefactory-project.io/software-factory/k1s
2026-06-10 20:04:24.715352 | testrunner | collecting ... collected 5 items
2026-06-10 20:04:24.715422 | testrunner |
2026-06-10 20:05:19.450345 | testrunner | tests.py::K1sTestCase::test_ansible PASSED                               [ 20%]
2026-06-10 20:05:19.725170 | testrunner | tests.py::K1sTestCase::test_bad_token PASSED                             [ 40%]
2026-06-10 20:05:22.741796 | testrunner | tests.py::K1sTestCase::test_create PASSED                                [ 60%]
2026-06-10 20:05:27.521861 | testrunner | tests.py::K1sTestCase::test_kubectl PASSED                               [ 80%]
2026-06-10 20:05:31.664858 | testrunner | tests.py::K1sTestCase::test_python_client PASSED                         [100%]
2026-06-10 20:05:31.664931 | testrunner |
2026-06-10 20:05:31.664942 | testrunner | =============================== warnings summary ===============================
2026-06-10 20:05:31.664951 | testrunner | tests.py::K1sTestCase::test_bad_token
2026-06-10 20:05:31.664961 | testrunner | tests.py::K1sTestCase::test_create
2026-06-10 20:05:31.664969 | testrunner | tests.py::K1sTestCase::test_create
2026-06-10 20:05:31.664977 | testrunner | tests.py::K1sTestCase::test_create
2026-06-10 20:05:31.664984 | testrunner | tests.py::K1sTestCase::test_create
2026-06-10 20:05:31.664992 | testrunner | tests.py::K1sTestCase::test_python_client
2026-06-10 20:05:31.665001 | 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-10 20:05:31.665010 | testrunner |     warnings.warn(
2026-06-10 20:05:31.665052 | testrunner |
2026-06-10 20:05:31.665066 | testrunner | -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html
2026-06-10 20:05:31.665078 | testrunner | =================== 5 passed, 6 warnings in 67.24s (0:01:07) ===================
2026-06-10 20:05:31.756780 | testrunner |   py39: OK (110.45=setup[42.77]+cmd[67.68] seconds)
2026-06-10 20:05:31.756828 | testrunner |   congratulations :) (110.49 seconds)
2026-06-11 00:05:31.998554 | testrunner | ok: Runtime: 0:01:50.728728
2026-06-11 00:05:32.002871 | 
2026-06-11 00:05:32.002989 | PLAY RECAP
2026-06-11 00:05:32.003080 | testrunner | ok: 8 changed: 6 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-06-11 00:05:32.003126 | 
2026-06-11 00:05:32.186958 | RUN END RESULT_NORMAL: [untrusted : softwarefactory-project.io/software-factory/k1s/playbooks/k1s-tox.yaml@master]
2026-06-11 00:05:32.189664 | POST-RUN START: [trusted : softwarefactory-project.io/config/playbooks/base/post.yaml@master]
2026-06-11 00:05:32.884165 | 
2026-06-11 00:05:32.884319 | PLAY [all]
2026-06-11 00:05:32.894709 | 
2026-06-11 00:05:32.894838 | TASK [include_role : fetch-output]
2026-06-11 00:05:32.935799 | testrunner | ok
2026-06-11 00:05:32.949107 | 
2026-06-11 00:05:32.949218 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-11 00:05:32.994785 | testrunner | skipping: Conditional result was False
2026-06-11 00:05:33.001791 | 
2026-06-11 00:05:33.002072 | TASK [fetch-output : Set log path for single node]
2026-06-11 00:05:33.045507 | testrunner | ok
2026-06-11 00:05:33.050658 | 
2026-06-11 00:05:33.050755 | LOOP [fetch-output : Ensure local output dirs]
2026-06-11 00:05:33.559666 | testrunner -> localhost | changed: "/var/lib/zuul/builds/d181d8c58f234a6392a04209cbae7951/work/logs"
2026-06-11 00:05:33.813087 | testrunner -> localhost | changed: "/var/lib/zuul/builds/d181d8c58f234a6392a04209cbae7951/work/artifacts"
2026-06-11 00:05:34.037440 | testrunner -> localhost | changed: "/var/lib/zuul/builds/d181d8c58f234a6392a04209cbae7951/work/docs"
2026-06-11 00:05:34.050434 | 
2026-06-11 00:05:34.050629 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-11 00:05:34.812649 | testrunner | ok
2026-06-11 00:05:34.812912 | testrunner | changed: All items complete
2026-06-11 00:05:34.812946 | 
2026-06-11 00:05:35.346704 | testrunner | changed: .d..t...... ./
2026-06-11 00:05:35.899732 | testrunner | changed: .d..t...... ./
2026-06-11 00:05:35.927041 | 
2026-06-11 00:05:35.927259 | TASK [include_role : fetch-output-openshift]
2026-06-11 00:05:35.954956 | testrunner | skipping: Conditional result was False
2026-06-11 00:05:35.965499 | 
2026-06-11 00:05:35.965665 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-11 00:05:36.012669 | testrunner | skipping: Conditional result was False
2026-06-11 00:05:36.021769 | testrunner | skipping: Conditional result was False
2026-06-11 00:05:36.043036 | 
2026-06-11 00:05:36.043262 | TASK [include_role : rhel-registration]
2026-06-11 00:05:36.069589 | testrunner | skipping: Conditional result was False
2026-06-11 00:05:36.073879 | 
2026-06-11 00:05:36.073947 | PLAY [localhost]
2026-06-11 00:05:36.082922 | 
2026-06-11 00:05:36.083021 | TASK [add-fileserver : Create SSH private key tempfile]
2026-06-11 00:05:36.470114 | localhost | changed
2026-06-11 00:05:36.478220 | 
2026-06-11 00:05:36.478386 | TASK [add-fileserver : Create SSH private key from secret]
2026-06-11 00:05:37.337538 | localhost | changed
2026-06-11 00:05:37.348648 | 
2026-06-11 00:05:37.348778 | TASK [add-fileserver : Add fileserver ssh key]
2026-06-11 00:05:37.848919 | localhost | Identity added: /var/lib/zuul/builds/d181d8c58f234a6392a04209cbae7951/work/tmp/ansible.cp7i4c48 (/var/lib/zuul/builds/d181d8c58f234a6392a04209cbae7951/work/tmp/ansible.cp7i4c48)
2026-06-11 00:05:37.849196 | localhost | ok: Runtime: 0:00:00.020011
2026-06-11 00:05:37.854241 | 
2026-06-11 00:05:37.854374 | TASK [add-fileserver : Remove SSH private key from disk]
2026-06-11 00:05:38.304486 | localhost | ok: Runtime: 0:00:00.010170
2026-06-11 00:05:38.308043 | 
2026-06-11 00:05:38.308105 | TASK [add-fileserver : Add fileserver to inventory]
2026-06-11 00:05:38.387372 | localhost | changed
2026-06-11 00:05:38.390890 | 
2026-06-11 00:05:38.390962 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-06-11 00:05:38.867519 | localhost | changed
2026-06-11 00:05:38.878226 | 
2026-06-11 00:05:38.878391 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-11 00:05:39.302991 | localhost | changed
2026-06-11 00:05:39.315106 | 
2026-06-11 00:05:39.315261 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-11 00:05:39.375503 | localhost | ok
2026-06-11 00:05:39.383945 | 
2026-06-11 00:05:39.384265 | TASK [include_role : report-logjuicer]
2026-06-11 00:05:39.430251 | localhost | skipping: Conditional result was False
2026-06-11 00:05:39.433891 | 
2026-06-11 00:05:39.433959 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com]
2026-06-11 00:05:39.437883 | 
2026-06-11 00:05:39.437952 | TASK [Set zuul-log-path fact]
2026-06-11 00:05:39.455191 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-11 00:05:39.464256 | 
2026-06-11 00:05:39.464365 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-11 00:05:39.492301 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-11 00:05:39.495550 | 
2026-06-11 00:05:39.495628 | TASK [upload-logs : Create log directories]
2026-06-11 00:05:40.304904 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed
2026-06-11 00:05:40.307208 | 
2026-06-11 00:05:40.307285 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-11 00:05:40.620963 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.010407
2026-06-11 00:05:40.625480 | 
2026-06-11 00:05:40.625626 | TASK [upload-logs : Upload logs to log server]
2026-06-11 00:05:41.770894 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed:
2026-06-11 00:05:41.771310 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | .d..tp..... ./
2026-06-11 00:05:41.771439 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | <f+++++++++ workspace-repos.json
2026-06-11 00:05:41.771512 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | <f+++++++++ zuul-manifest.json
2026-06-11 00:05:41.771574 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | cd+++++++++ zuul-info/
2026-06-11 00:05:41.771629 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | <f+++++++++ zuul-info/host-info.testrunner.yaml
2026-06-11 00:05:41.771680 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | <f+++++++++ zuul-info/inventory.yaml
2026-06-11 00:05:41.771726 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | <f+++++++++ zuul-info/zuul-info.testrunner.txt
2026-06-11 00:05:41.777509 | 
2026-06-11 00:05:41.777646 | LOOP [upload-logs : Compress console log and json output]
