2026-01-25 07:45:58.455917 | Job console starting...
2026-01-25 07:45:58.681273 | Updating repositories
2026-01-25 07:45:59.455882 | Preparing job workspace
2026-01-25 07:46:07.810246 | Running Ansible setup...
2026-01-25 07:46:11.502243 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-25 07:46:12.069178 | 
2026-01-25 07:46:12.069289 | PLAY [localhost]
2026-01-25 07:46:12.080812 | 
2026-01-25 07:46:12.080888 | TASK [Gathering Facts]
2026-01-25 07:46:13.264863 | localhost | ok
2026-01-25 07:46:13.292893 | 
2026-01-25 07:46:13.292999 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-25 07:46:13.725930 | localhost -> localhost | changed
2026-01-25 07:46:13.731567 | 
2026-01-25 07:46:13.731654 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-25 07:46:14.881389 | localhost -> localhost | changed
2026-01-25 07:46:14.890205 | 
2026-01-25 07:46:14.890285 | TASK [Setup log path fact]
2026-01-25 07:46:14.923071 | localhost | ok
2026-01-25 07:46:14.942692 | 
2026-01-25 07:46:14.942786 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-25 07:46:14.970290 | localhost | ok
2026-01-25 07:46:14.981683 | 
2026-01-25 07:46:14.981763 | TASK [emit-job-header : Print job information]
2026-01-25 07:46:15.028673 | # Job Information
2026-01-25 07:46:15.028790 | Ansible Version: 2.15.12
2026-01-25 07:46:15.028821 | Job: cifmw-molecule-openshift_obs
2026-01-25 07:46:15.028845 | Pipeline: github-check
2026-01-25 07:46:15.028865 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-01-25 07:46:15.028886 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3619
2026-01-25 07:46:15.028906 | Log URL (when completed): https://logserver.rdoproject.org/0ed/rdoproject.org/0edc3efc9e7a4aacbf90394c4ff0beef/
2026-01-25 07:46:15.030366 | Event ID: 782b3520-f9c0-11f0-9fc9-b2aac073cae4
2026-01-25 07:46:15.034536 | 
2026-01-25 07:46:15.034605 | LOOP [emit-job-header : Print node information]
2026-01-25 07:46:15.146512 | localhost | ok:
2026-01-25 07:46:15.146670 | localhost | # Node Information
2026-01-25 07:46:15.146702 | localhost | Inventory Hostname: controller
2026-01-25 07:46:15.146730 | localhost | Hostname: np0005594915
2026-01-25 07:46:15.146752 | localhost | Username: zuul
2026-01-25 07:46:15.146774 | localhost | Distro: CentOS 9
2026-01-25 07:46:15.146794 | localhost | Provider: ibm-bm4-nodepool
2026-01-25 07:46:15.146812 | localhost | Region: regionOne
2026-01-25 07:46:15.146830 | localhost | Label: centos-9-stream-crc-2-48-0-xxl-ibm
2026-01-25 07:46:15.146847 | localhost | Product Name: OpenStack Compute
2026-01-25 07:46:15.146865 | localhost | Interface IP: 192.168.26.197
2026-01-25 07:46:15.187384 | 
2026-01-25 07:46:15.187487 | PLAY [all]
2026-01-25 07:46:15.194730 | 
2026-01-25 07:46:15.194803 | TASK [Gather network facts]
2026-01-25 07:46:15.662240 | controller | ok
2026-01-25 07:46:15.679666 | 
2026-01-25 07:46:15.679766 | TASK [include_role : start-zuul-console]
2026-01-25 07:46:15.697646 | controller | ok
2026-01-25 07:46:15.709167 | 
2026-01-25 07:46:15.709257 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-25 07:46:16.094756 | controller | ok
2026-01-25 07:46:16.105223 | 
2026-01-25 07:46:16.105345 | TASK [include_role : add-build-sshkey]
2026-01-25 07:46:16.133401 | controller | ok
2026-01-25 07:46:16.147208 | 
2026-01-25 07:46:16.147279 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-25 07:46:16.395688 | controller -> localhost | ok
2026-01-25 07:46:16.401728 | 
2026-01-25 07:46:16.401808 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-25 07:46:16.420964 | controller | ok
2026-01-25 07:46:16.434069 | controller | included: /var/lib/zuul/builds/0edc3efc9e7a4aacbf90394c4ff0beef/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-25 07:46:16.439774 | 
2026-01-25 07:46:16.439841 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-25 07:46:16.944923 | controller -> localhost | Generating public/private rsa key pair.
2026-01-25 07:46:16.945100 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0edc3efc9e7a4aacbf90394c4ff0beef/work/0edc3efc9e7a4aacbf90394c4ff0beef_id_rsa.
2026-01-25 07:46:16.945139 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0edc3efc9e7a4aacbf90394c4ff0beef/work/0edc3efc9e7a4aacbf90394c4ff0beef_id_rsa.pub.
2026-01-25 07:46:16.945165 | controller -> localhost | The key fingerprint is:
2026-01-25 07:46:16.945200 | controller -> localhost | SHA256:FqNFNeK0VXEzHqgVOA5EqFmVdDqKt18bjEiohkUxgHk zuul-build-sshkey
2026-01-25 07:46:16.945224 | controller -> localhost | The key's randomart image is:
2026-01-25 07:46:16.945244 | controller -> localhost | +---[RSA 3072]----+
2026-01-25 07:46:16.945262 | controller -> localhost | |ooo    *Oo=o++=  |
2026-01-25 07:46:16.945281 | controller -> localhost | |o Eo  o+o*o.oo + |
2026-01-25 07:46:16.945319 | controller -> localhost | | ..  +  Oo +  .  |
2026-01-25 07:46:16.945342 | controller -> localhost | | .  oo + +o      |
2026-01-25 07:46:16.945361 | controller -> localhost | |  . o = S        |
2026-01-25 07:46:16.945379 | controller -> localhost | | o . o + o       |
2026-01-25 07:46:16.945395 | controller -> localhost | |. o   o . +      |
2026-01-25 07:46:16.945416 | controller -> localhost | | .     . . o     |
2026-01-25 07:46:16.945435 | controller -> localhost | |        . .      |
2026-01-25 07:46:16.945453 | controller -> localhost | +----[SHA256]-----+
2026-01-25 07:46:16.945498 | controller -> localhost | ok: Runtime: 0:00:00.146929
2026-01-25 07:46:16.951676 | 
2026-01-25 07:46:16.951746 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-25 07:46:16.970830 | controller | ok
2026-01-25 07:46:16.982471 | controller | included: /var/lib/zuul/builds/0edc3efc9e7a4aacbf90394c4ff0beef/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-25 07:46:16.991245 | 
2026-01-25 07:46:16.991341 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-25 07:46:17.005309 | controller | skipping: Conditional result was False
2026-01-25 07:46:17.010873 | 
2026-01-25 07:46:17.010938 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-25 07:46:17.517513 | controller | changed
2026-01-25 07:46:17.526185 | 
2026-01-25 07:46:17.526411 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-25 07:46:17.807922 | controller | ok
2026-01-25 07:46:17.812825 | 
2026-01-25 07:46:17.812898 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-25 07:46:18.921425 | controller | changed
2026-01-25 07:46:18.926355 | 
2026-01-25 07:46:18.926440 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-25 07:46:20.006345 | controller | changed
2026-01-25 07:46:20.011339 | 
2026-01-25 07:46:20.011412 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-25 07:46:20.047731 | controller | skipping: Conditional result was False
2026-01-25 07:46:20.053827 | 
2026-01-25 07:46:20.053899 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-25 07:46:20.567080 | controller -> localhost | changed
2026-01-25 07:46:20.578670 | 
2026-01-25 07:46:20.578756 | TASK [add-build-sshkey : Add back temp key]
2026-01-25 07:46:20.861764 | controller -> localhost | Identity added: /var/lib/zuul/builds/0edc3efc9e7a4aacbf90394c4ff0beef/work/0edc3efc9e7a4aacbf90394c4ff0beef_id_rsa (zuul-build-sshkey)
2026-01-25 07:46:20.861930 | controller -> localhost | ok: Runtime: 0:00:00.008791
2026-01-25 07:46:20.867682 | 
2026-01-25 07:46:20.867750 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-25 07:46:21.223955 | controller | ok
2026-01-25 07:46:21.231688 | 
2026-01-25 07:46:21.231775 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-25 07:46:21.255182 | controller | skipping: Conditional result was False
2026-01-25 07:46:21.265769 | 
2026-01-25 07:46:21.265845 | TASK [include_role : validate-host]
2026-01-25 07:46:21.289496 | controller | ok
2026-01-25 07:46:21.319266 | 
2026-01-25 07:46:21.319379 | TASK [validate-host : Define zuul_info_dir fact]
2026-01-25 07:46:21.349562 | controller | ok
2026-01-25 07:46:21.392801 | 
2026-01-25 07:46:21.392987 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-01-25 07:46:21.620753 | controller -> localhost | ok
2026-01-25 07:46:21.626714 | 
2026-01-25 07:46:21.626796 | TASK [validate-host : Collect information about the host]
2026-01-25 07:46:22.295997 | controller | ok
2026-01-25 07:46:22.304701 | 
2026-01-25 07:46:22.304858 | TASK [validate-host : Sanitize hostname]
2026-01-25 07:46:22.354444 | controller | ok
2026-01-25 07:46:22.361100 | 
2026-01-25 07:46:22.361202 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-01-25 07:46:23.020560 | controller -> localhost | changed
2026-01-25 07:46:23.026561 | 
2026-01-25 07:46:23.026655 | TASK [validate-host : Collect information about zuul worker]
2026-01-25 07:46:23.413503 | controller | ok
2026-01-25 07:46:23.418363 | 
2026-01-25 07:46:23.418450 | TASK [validate-host : Write out all zuul information for each host]
2026-01-25 07:46:23.871542 | controller -> localhost | changed
2026-01-25 07:46:23.893277 | 
2026-01-25 07:46:23.893377 | TASK [include_role : prepare-workspace-openshift]
2026-01-25 07:46:23.923975 | controller | skipping: Conditional result was False
2026-01-25 07:46:23.929939 | 
2026-01-25 07:46:23.930026 | TASK [include_role : remove-zuul-sshkey]
2026-01-25 07:46:23.955112 | controller | skipping: Conditional result was False
2026-01-25 07:46:23.960502 | 
2026-01-25 07:46:23.960616 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-25 07:46:24.255248 | controller | ok: "logs"
2026-01-25 07:46:24.255520 | controller | ok: All items complete
2026-01-25 07:46:24.255553 | 
2026-01-25 07:46:24.503583 | controller | ok: "artifacts"
2026-01-25 07:46:24.750539 | controller | ok: "docs"
2026-01-25 07:46:24.761137 | 
2026-01-25 07:46:24.761904 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-25 07:46:25.059142 | controller | changed: "logs"
2026-01-25 07:46:25.288762 | controller | changed: "artifacts"
2026-01-25 07:46:25.520149 | controller | changed: "docs"
2026-01-25 07:46:25.550376 | 
2026-01-25 07:46:25.550465 | PLAY RECAP
2026-01-25 07:46:25.550509 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-25 07:46:25.550539 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-25 07:46:25.550559 | 
2026-01-25 07:46:25.637424 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-25 07:46:25.638105 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-25 07:46:26.268106 | 
2026-01-25 07:46:26.268284 | PLAY [all]
2026-01-25 07:46:26.288021 | 
2026-01-25 07:46:26.288106 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-01-25 07:46:26.360233 | controller | ok
2026-01-25 07:46:26.365390 | 
2026-01-25 07:46:26.365505 | TASK [mirror-info-fork : Create /etc/ci]
2026-01-25 07:46:26.769662 | controller | changed
2026-01-25 07:46:26.775625 | 
2026-01-25 07:46:26.775715 | TASK [mirror-info-fork : Install ci_mirror script]
2026-01-25 07:46:28.071913 | controller | changed
2026-01-25 07:46:28.082314 | 
2026-01-25 07:46:28.082402 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-01-25 07:46:28.530602 | controller | changed:
2026-01-25 07:46:28.530795 | controller | {
2026-01-25 07:46:28.530832 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-01-25 07:46:28.530864 | controller | }
2026-01-25 07:46:28.815204 | controller | changed:
2026-01-25 07:46:28.815279 | controller | {
2026-01-25 07:46:28.815330 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-01-25 07:46:28.815356 | controller | }
2026-01-25 07:46:29.103117 | controller | changed:
2026-01-25 07:46:29.103223 | controller | {
2026-01-25 07:46:29.103253 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-01-25 07:46:29.103274 | controller | }
2026-01-25 07:46:29.385223 | controller | changed:
2026-01-25 07:46:29.385331 | controller | {
2026-01-25 07:46:29.385367 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-01-25 07:46:29.385390 | controller | }
2026-01-25 07:46:29.667713 | controller | changed:
2026-01-25 07:46:29.667795 | controller | {
2026-01-25 07:46:29.667824 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-01-25 07:46:29.667846 | controller | }
2026-01-25 07:46:29.946133 | controller | changed:
2026-01-25 07:46:29.946237 | controller | {
2026-01-25 07:46:29.946270 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-01-25 07:46:29.946292 | controller | }
2026-01-25 07:46:30.228119 | controller | changed:
2026-01-25 07:46:30.228245 | controller | {
2026-01-25 07:46:30.228276 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2026-01-25 07:46:30.228314 | controller | }
2026-01-25 07:46:30.508487 | controller | changed:
2026-01-25 07:46:30.508570 | controller | {
2026-01-25 07:46:30.508599 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2026-01-25 07:46:30.508621 | controller | }
2026-01-25 07:46:30.787902 | controller | changed:
2026-01-25 07:46:30.788002 | controller | {
2026-01-25 07:46:30.788032 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-01-25 07:46:30.788055 | controller | }
2026-01-25 07:46:31.104168 | controller | changed:
2026-01-25 07:46:31.104246 | controller | {
2026-01-25 07:46:31.104273 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-01-25 07:46:31.104310 | controller | }
2026-01-25 07:46:31.366527 | controller | changed:
2026-01-25 07:46:31.366613 | controller | {
2026-01-25 07:46:31.366640 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-01-25 07:46:31.366661 | controller | }
2026-01-25 07:46:31.671152 | controller | changed:
2026-01-25 07:46:31.671249 | controller | {
2026-01-25 07:46:31.671280 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-01-25 07:46:31.671322 | controller | }
2026-01-25 07:46:31.960379 | controller | changed:
2026-01-25 07:46:31.960461 | controller | {
2026-01-25 07:46:31.960489 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2026-01-25 07:46:31.960511 | controller | }
2026-01-25 07:46:32.234027 | controller | changed:
2026-01-25 07:46:32.234164 | controller | {
2026-01-25 07:46:32.234210 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-01-25 07:46:32.234235 | controller | }
2026-01-25 07:46:32.511179 | controller | changed:
2026-01-25 07:46:32.511277 | controller | {
2026-01-25 07:46:32.511332 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-01-25 07:46:32.511357 | controller | }
2026-01-25 07:46:32.790331 | controller | changed:
2026-01-25 07:46:32.790407 | controller | {
2026-01-25 07:46:32.790435 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-01-25 07:46:32.790456 | controller | }
2026-01-25 07:46:33.072411 | controller | changed:
2026-01-25 07:46:33.072489 | controller | {
2026-01-25 07:46:33.072518 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2026-01-25 07:46:33.072543 | controller | }
2026-01-25 07:46:33.358816 | controller | changed:
2026-01-25 07:46:33.358903 | controller | {
2026-01-25 07:46:33.358933 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2026-01-25 07:46:33.358974 | controller | }
2026-01-25 07:46:33.637566 | controller | changed:
2026-01-25 07:46:33.637643 | controller | {
2026-01-25 07:46:33.637671 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-01-25 07:46:33.637693 | controller | }
2026-01-25 07:46:33.931241 | controller | changed:
2026-01-25 07:46:33.931371 | controller | {
2026-01-25 07:46:33.931404 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-01-25 07:46:33.931426 | controller | }
2026-01-25 07:46:34.214672 | controller | changed:
2026-01-25 07:46:34.214757 | controller | {
2026-01-25 07:46:34.214785 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-01-25 07:46:34.214807 | controller | }
2026-01-25 07:46:34.496463 | controller | changed:
2026-01-25 07:46:34.496546 | controller | {
2026-01-25 07:46:34.496573 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-01-25 07:46:34.496594 | controller | }
2026-01-25 07:46:34.779159 | controller | changed:
2026-01-25 07:46:34.779261 | controller | {
2026-01-25 07:46:34.779288 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2026-01-25 07:46:34.779370 | controller | }
2026-01-25 07:46:35.066542 | controller | changed:
2026-01-25 07:46:35.066618 | controller | {
2026-01-25 07:46:35.066646 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-01-25 07:46:35.066666 | controller | }
2026-01-25 07:46:35.351839 | controller | changed:
2026-01-25 07:46:35.351925 | controller | {
2026-01-25 07:46:35.351952 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-01-25 07:46:35.351974 | controller | }
2026-01-25 07:46:35.650101 | controller | changed:
2026-01-25 07:46:35.650207 | controller | {
2026-01-25 07:46:35.650242 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-01-25 07:46:35.650263 | controller | }
2026-01-25 07:46:35.672445 | 
2026-01-25 07:46:35.672728 | TASK [Set timezone to UTC]
2026-01-25 07:46:36.178828 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-01-25 07:46:36.184278 | 
2026-01-25 07:46:36.184383 | TASK [Create nodepool directory]
2026-01-25 07:46:36.453198 | controller | changed
2026-01-25 07:46:36.459459 | 
2026-01-25 07:46:36.459548 | TASK [Create nodepool sub_nodes file]
2026-01-25 07:46:37.362528 | controller | changed
2026-01-25 07:46:37.373289 | 
2026-01-25 07:46:37.373478 | TASK [Create nodepool sub_nodes_private file]
2026-01-25 07:46:38.281460 | controller | changed
2026-01-25 07:46:38.286758 | 
2026-01-25 07:46:38.286850 | LOOP [Populate nodepool sub_nodes file]
2026-01-25 07:46:38.316533 | 
2026-01-25 07:46:38.316685 | LOOP [Populate nodepool sub_nodes_private file]
2026-01-25 07:46:38.346959 | 
2026-01-25 07:46:38.347097 | TASK [Create nodepool primary file]
2026-01-25 07:46:38.370793 | controller | skipping: Conditional result was False
2026-01-25 07:46:38.376506 | 
2026-01-25 07:46:38.376597 | TASK [Create nodepool node_private for this node]
2026-01-25 07:46:39.342778 | controller | changed
2026-01-25 07:46:39.349447 | 
2026-01-25 07:46:39.349530 | LOOP [Copy ssh keys to nodepool directory]
2026-01-25 07:46:39.744034 | controller | ok: Item: id_rsa Runtime: 0:00:00.003701
2026-01-25 07:46:39.744235 | 
2026-01-25 07:46:39.969493 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004632
2026-01-25 07:46:39.983854 | 
2026-01-25 07:46:39.983954 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-01-25 07:46:40.960260 | controller | changed
2026-01-25 07:46:40.965341 | 
2026-01-25 07:46:40.965425 | TASK [Validate sudoers config after edits]
2026-01-25 07:46:41.251123 | controller | /etc/sudoers: parsed OK
2026-01-25 07:46:41.251241 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-01-25 07:46:41.251257 | controller | /etc/sudoers.d/zuul: parsed OK
2026-01-25 07:46:41.251264 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-01-25 07:46:41.492739 | controller | ok: Runtime: 0:00:00.005136
2026-01-25 07:46:41.498212 | 
2026-01-25 07:46:41.498279 | TASK [Show the environment passed in to job shell scripts]
2026-01-25 07:46:41.791450 | controller | SHELL=/bin/bash
2026-01-25 07:46:41.791497 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-01-25 07:46:41.791508 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-01-25 07:46:41.791514 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/19/3619/357a1d5bdf38af6a9dfd790ff15d1c9b02f9f293
2026-01-25 07:46:41.791520 | controller | PWD=/home/zuul
2026-01-25 07:46:41.791526 | controller | ZUUL_PIPELINE=github-check
2026-01-25 07:46:41.791532 | controller | LOGNAME=zuul
2026-01-25 07:46:41.791538 | controller | XDG_SESSION_TYPE=tty
2026-01-25 07:46:41.791543 | controller | _=/usr/bin/env
2026-01-25 07:46:41.791549 | controller | MOTD_SHOWN=pam
2026-01-25 07:46:41.791607 | controller | HOME=/home/zuul
2026-01-25 07:46:41.791616 | controller | LANG=en_US.UTF-8
2026-01-25 07:46:41.791621 | controller | SSH_CONNECTION=192.168.26.12 58026 192.168.26.197 22
2026-01-25 07:46:41.791627 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2026-01-25 07:46:41.791637 | controller | ZUUL_CHANGE_IDS=3619,357a1d5bdf38af6a9dfd790ff15d1c9b02f9f293
2026-01-25 07:46:41.791643 | controller | WORKSPACE=/home/zuul/workspace
2026-01-25 07:46:41.791649 | controller | XDG_SESSION_CLASS=user
2026-01-25 07:46:41.791655 | controller | SELINUX_ROLE_REQUESTED=
2026-01-25 07:46:41.791660 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-01-25 07:46:41.791665 | controller | USER=zuul
2026-01-25 07:46:41.791671 | controller | ZUUL_VOTING=True
2026-01-25 07:46:41.791677 | controller | BUILD_TIMEOUT=3600000
2026-01-25 07:46:41.791682 | controller | SELINUX_USE_CURRENT_RANGE=
2026-01-25 07:46:41.791687 | controller | SHLVL=1
2026-01-25 07:46:41.791693 | controller | ZUUL_PATCHSET=357a1d5bdf38af6a9dfd790ff15d1c9b02f9f293
2026-01-25 07:46:41.791699 | controller | XDG_SESSION_ID=1
2026-01-25 07:46:41.791704 | controller | ZUUL_BRANCH=main
2026-01-25 07:46:41.791710 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-01-25 07:46:41.791716 | controller | SSH_CLIENT=192.168.26.12 58026 22
2026-01-25 07:46:41.791721 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-01-25 07:46:41.791727 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-01-25 07:46:41.791734 | controller | which_declare=declare -f
2026-01-25 07:46:41.791740 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-01-25 07:46:41.791746 | controller | SELINUX_LEVEL_REQUESTED=
2026-01-25 07:46:41.791751 | controller | ZUUL_CHANGE=3619
2026-01-25 07:46:41.791757 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-01-25 07:46:41.791762 | controller | ZUUL_UUID=0edc3efc9e7a4aacbf90394c4ff0beef
2026-01-25 07:46:41.791768 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-01-25 07:46:41.791774 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-01-25 07:46:41.791780 | controller | }
2026-01-25 07:46:42.036504 | controller | ok: Runtime: 0:00:00.007046
2026-01-25 07:46:42.042663 | 
2026-01-25 07:46:42.042739 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-01-25 07:46:42.057411 | controller | skipping: Conditional result was False
2026-01-25 07:46:42.064345 | 
2026-01-25 07:46:42.064430 | TASK [Symlink /home/zuul-worker/workspace]
2026-01-25 07:46:42.622622 | controller | skipping: Conditional result was False
2026-01-25 07:46:42.628957 | 
2026-01-25 07:46:42.629050 | TASK [Ensure legacy workspace directory]
2026-01-25 07:46:42.891895 | controller | changed
2026-01-25 07:46:42.910766 | 
2026-01-25 07:46:42.910840 | PLAY RECAP
2026-01-25 07:46:42.910886 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-25 07:46:42.910909 | 
2026-01-25 07:46:42.987677 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-25 07:46:42.988450 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-25 07:46:43.601810 | 
2026-01-25 07:46:43.601969 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-01-25 07:46:43.624152 | 
2026-01-25 07:46:43.624239 | TASK [Create zuul-output directory]
2026-01-25 07:46:44.029663 | controller | changed
2026-01-25 07:46:44.035058 | 
2026-01-25 07:46:44.035171 | TASK [Slurp Zuul inventory test]
2026-01-25 07:46:44.323315 | controller -> localhost | ok
2026-01-25 07:46:44.331095 | 
2026-01-25 07:46:44.331186 | TASK [Save zuul inventory]
2026-01-25 07:46:45.557458 | controller | changed
2026-01-25 07:46:45.565743 | 
2026-01-25 07:46:45.565833 | TASK [Save zuul vars without the change_message]
2026-01-25 07:46:46.588560 | controller | changed
2026-01-25 07:46:46.609654 | 
2026-01-25 07:46:46.609721 | PLAY RECAP
2026-01-25 07:46:46.609764 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-25 07:46:46.609787 | 
2026-01-25 07:46:46.682779 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-25 07:46:46.683563 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-01-25 07:46:47.285985 | 
2026-01-25 07:46:47.286125 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-01-25 07:46:47.317466 | 
2026-01-25 07:46:47.317566 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-01-25 07:46:47.346470 | controller | ok
2026-01-25 07:46:47.368665 | 
2026-01-25 07:46:47.368774 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-01-25 07:46:47.392419 | controller | skipping: Conditional result was False
2026-01-25 07:46:47.398628 | 
2026-01-25 07:46:47.398707 | TASK [mirror-info-fork : Create /etc/ci]
2026-01-25 07:46:47.802686 | controller | ok
2026-01-25 07:46:47.810425 | 
2026-01-25 07:46:47.810561 | TASK [mirror-info-fork : Install ci_mirror script]
2026-01-25 07:46:48.740683 | controller | ok
2026-01-25 07:46:48.750617 | 
2026-01-25 07:46:48.750709 | TASK [Prepare workspace]
2026-01-25 07:46:48.769272 | controller | ok
2026-01-25 07:46:48.814376 | 
2026-01-25 07:46:48.814472 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-25 07:46:49.229610 | controller | ok
2026-01-25 07:46:49.235592 | 
2026-01-25 07:46:49.235671 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-25 07:46:51.812937 | controller | Output suppressed because no_log was given
2026-01-25 07:46:51.822488 | 
2026-01-25 07:46:51.822574 | LOOP [Create zuul-output directory]
2026-01-25 07:46:52.080757 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-01-25 07:46:52.305662 | controller | ok: "/home/zuul/zuul-output/logs"
2026-01-25 07:46:52.322399 | 
2026-01-25 07:46:52.322513 | TASK [Install required packages]
2026-01-25 07:47:10.229863 | controller | ok: Nothing to do
2026-01-25 07:47:10.235677 | 
2026-01-25 07:47:10.235753 | TASK [Install venv]
2026-01-25 07:48:09.936747 | controller | changed
2026-01-25 07:48:09.962018 | 
2026-01-25 07:48:09.962092 | PLAY RECAP
2026-01-25 07:48:09.962137 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-01-25 07:48:09.962160 | 
2026-01-25 07:48:10.057179 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-01-25 07:48:10.058037 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-01-25 07:48:10.622387 | 
2026-01-25 07:48:10.622485 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-01-25 07:48:10.642581 | 
2026-01-25 07:48:10.642658 | TASK [Gather required facts]
2026-01-25 07:48:11.227066 | controller | ok
2026-01-25 07:48:11.234904 | 
2026-01-25 07:48:11.235002 | TASK [Load environment var if instructed to]
2026-01-25 07:48:11.259468 | controller | skipping: Conditional result was False
2026-01-25 07:48:11.265175 | 
2026-01-25 07:48:11.265262 | TASK [Ensure group_vars dir exists]
2026-01-25 07:48:11.678439 | controller | ok
2026-01-25 07:48:11.683877 | 
2026-01-25 07:48:11.683955 | TASK [Print related variables]
2026-01-25 07:48:11.734761 | controller | ok:
2026-01-25 07:48:11.734881 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-01-25 07:48:11.734914 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/openshift_obs
2026-01-25 07:48:11.740053 | 
2026-01-25 07:48:11.740119 | TASK [Run molecule]
2026-01-25 07:48:12.774512 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-01-25 07:48:12.845238 | controller | INFO     Performing prerun with role_name_check=0...
2026-01-25 07:48:29.392686 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/9c43a6/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:29.393099 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/9c43a6/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:29.393576 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/9c43a6/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:29.394001 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/9c43a6/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:29.394492 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+357a1d5b was found installed in /home/zuul/.cache/ansible-compat/9c43a6/collections/ansible_collections, only the first one will be used, 1.0.0+357a1d5b (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:29.394920 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/9c43a6/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:29.395367 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/9c43a6/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:29.395795 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/9c43a6/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:29.396217 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/9c43a6/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:29.396648 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/9c43a6/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:29.397038 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/9c43a6/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:29.397496 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/9c43a6/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:29.397916 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/9c43a6/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:29.398384 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/9c43a6/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:29.398813 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/9c43a6/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:29.408120 | controller | INFO     Running default > prepare
2026-01-25 07:48:30.065490 | controller |
2026-01-25 07:48:30.065611 | controller | PLAY [Prepare] *****************************************************************
2026-01-25 07:48:30.065718 | controller |
2026-01-25 07:48:30.065831 | controller | TASK [Gathering Facts] *********************************************************
2026-01-25 07:48:30.065932 | controller | Sunday 25 January 2026  07:48:30 +0000 (0:00:00.013)       0:00:00.013 ********
2026-01-25 07:48:30.914640 | controller | ok: [instance]
2026-01-25 07:48:30.914728 | controller |
2026-01-25 07:48:30.914793 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2026-01-25 07:48:30.914888 | controller | Sunday 25 January 2026  07:48:30 +0000 (0:00:00.849)       0:00:00.862 ********
2026-01-25 07:48:30.931978 | controller | skipping: [instance]
2026-01-25 07:48:30.932088 | controller |
2026-01-25 07:48:30.932220 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2026-01-25 07:48:30.932368 | controller | Sunday 25 January 2026  07:48:30 +0000 (0:00:00.017)       0:00:00.880 ********
2026-01-25 07:48:30.973222 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2026-01-25 07:48:30.973315 | controller |
2026-01-25 07:48:30.973429 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2026-01-25 07:48:30.973541 | controller | Sunday 25 January 2026  07:48:30 +0000 (0:00:00.041)       0:00:00.921 ********
2026-01-25 07:48:31.250523 | controller | ok: [instance]
2026-01-25 07:48:31.250567 | controller |
2026-01-25 07:48:31.250701 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2026-01-25 07:48:31.250757 | controller | Sunday 25 January 2026  07:48:31 +0000 (0:00:00.277)       0:00:01.198 ********
2026-01-25 07:48:31.553768 | controller | ok: [instance]
2026-01-25 07:48:31.553849 | controller |
2026-01-25 07:48:31.553965 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2026-01-25 07:48:31.554071 | controller | Sunday 25 January 2026  07:48:31 +0000 (0:00:00.303)       0:00:01.502 ********
2026-01-25 07:48:32.244014 | controller | ok: [instance] => (item=ubi-9-appstream)
2026-01-25 07:48:32.244055 | controller | ok: [instance] => (item=ubi-9-baseos)
2026-01-25 07:48:32.244141 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2026-01-25 07:48:32.244230 | controller |
2026-01-25 07:48:32.244368 | controller | TASK [Deploy repo-setup] *******************************************************
2026-01-25 07:48:32.244459 | controller | Sunday 25 January 2026  07:48:32 +0000 (0:00:00.690)       0:00:02.192 ********
2026-01-25 07:48:32.303081 | controller |
2026-01-25 07:48:32.303165 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2026-01-25 07:48:32.303280 | controller | Sunday 25 January 2026  07:48:32 +0000 (0:00:00.059)       0:00:02.251 ********
2026-01-25 07:48:32.825535 | controller | changed: [instance] => (item=tmp)
2026-01-25 07:48:32.825634 | controller | changed: [instance] => (item=artifacts/repositories)
2026-01-25 07:48:32.825737 | controller | changed: [instance] => (item=venv/repo_setup)
2026-01-25 07:48:32.825830 | controller |
2026-01-25 07:48:32.825935 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2026-01-25 07:48:32.826022 | controller | Sunday 25 January 2026  07:48:32 +0000 (0:00:00.522)       0:00:02.773 ********
2026-01-25 07:48:33.661149 | controller | ok: [instance]
2026-01-25 07:48:33.661224 | controller |
2026-01-25 07:48:33.661548 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2026-01-25 07:48:34.721997 | controller | Sunday 25 January 2026  07:48:33 +0000 (0:00:00.835)       0:00:03.609 ********
2026-01-25 07:48:34.722033 | controller | changed: [instance]
2026-01-25 07:48:42.604885 | controller |
2026-01-25 07:48:42.604926 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2026-01-25 07:48:42.604934 | controller | Sunday 25 January 2026  07:48:34 +0000 (0:00:01.060)       0:00:04.670 ********
2026-01-25 07:48:42.604945 | controller | changed: [instance]
2026-01-25 07:48:43.298265 | controller |
2026-01-25 07:48:43.298303 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2026-01-25 07:48:43.298312 | controller | Sunday 25 January 2026  07:48:42 +0000 (0:00:07.882)       0:00:12.552 ********
2026-01-25 07:48:43.298335 | controller | changed: [instance]
2026-01-25 07:48:43.298416 | controller |
2026-01-25 07:48:43.298446 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2026-01-25 07:48:43.298458 | controller | Sunday 25 January 2026  07:48:43 +0000 (0:00:00.693)       0:00:13.246 ********
2026-01-25 07:48:43.319605 | controller | skipping: [instance]
2026-01-25 07:48:44.114842 | controller |
2026-01-25 07:48:44.114872 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2026-01-25 07:48:44.114880 | controller | Sunday 25 January 2026  07:48:43 +0000 (0:00:00.021)       0:00:13.268 ********
2026-01-25 07:48:44.114890 | controller | changed: [instance]
2026-01-25 07:48:44.151122 | controller |
2026-01-25 07:48:44.151146 | controller | TASK [repo_setup : Get component repo] *****************************************
2026-01-25 07:48:44.151154 | controller | Sunday 25 January 2026  07:48:44 +0000 (0:00:00.794)       0:00:14.062 ********
2026-01-25 07:48:44.151164 | controller | skipping: [instance]
2026-01-25 07:48:44.186793 | controller |
2026-01-25 07:48:44.186814 | controller | TASK [repo_setup : Rename component repo] **************************************
2026-01-25 07:48:44.186822 | controller | Sunday 25 January 2026  07:48:44 +0000 (0:00:00.036)       0:00:14.099 ********
2026-01-25 07:48:44.186831 | controller | skipping: [instance]
2026-01-25 07:48:44.223027 | controller |
2026-01-25 07:48:44.223045 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2026-01-25 07:48:44.223052 | controller | Sunday 25 January 2026  07:48:44 +0000 (0:00:00.035)       0:00:14.135 ********
2026-01-25 07:48:44.223061 | controller | skipping: [instance]
2026-01-25 07:48:44.764826 | controller |
2026-01-25 07:48:44.764856 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2026-01-25 07:48:44.764864 | controller | Sunday 25 January 2026  07:48:44 +0000 (0:00:00.035)       0:00:14.171 ********
2026-01-25 07:48:44.764874 | controller | changed: [instance]
2026-01-25 07:48:45.203307 | controller |
2026-01-25 07:48:45.203359 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2026-01-25 07:48:45.203369 | controller | Sunday 25 January 2026  07:48:44 +0000 (0:00:00.541)       0:00:14.712 ********
2026-01-25 07:48:45.203381 | controller | changed: [instance]
2026-01-25 07:48:45.203412 | controller |
2026-01-25 07:48:45.203653 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2026-01-25 07:48:45.232215 | controller | Sunday 25 January 2026  07:48:45 +0000 (0:00:00.438)       0:00:15.151 ********
2026-01-25 07:48:45.232249 | controller | skipping: [instance]
2026-01-25 07:48:45.260927 | controller |
2026-01-25 07:48:45.260950 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2026-01-25 07:48:45.260957 | controller | Sunday 25 January 2026  07:48:45 +0000 (0:00:00.028)       0:00:15.180 ********
2026-01-25 07:48:45.260968 | controller | skipping: [instance]
2026-01-25 07:48:45.288866 | controller |
2026-01-25 07:48:45.288888 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2026-01-25 07:48:45.288895 | controller | Sunday 25 January 2026  07:48:45 +0000 (0:00:00.028)       0:00:15.209 ********
2026-01-25 07:48:45.288905 | controller | skipping: [instance]
2026-01-25 07:48:45.327674 | controller |
2026-01-25 07:48:45.327696 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2026-01-25 07:48:45.327705 | controller | Sunday 25 January 2026  07:48:45 +0000 (0:00:00.028)       0:00:15.237 ********
2026-01-25 07:48:45.327716 | controller | ok: [instance]
2026-01-25 07:48:45.355939 | controller |
2026-01-25 07:48:45.355957 | controller | TASK [repo_setup : Create download directory] **********************************
2026-01-25 07:48:45.355965 | controller | Sunday 25 January 2026  07:48:45 +0000 (0:00:00.038)       0:00:15.276 ********
2026-01-25 07:48:45.355974 | controller | skipping: [instance]
2026-01-25 07:48:45.382434 | controller |
2026-01-25 07:48:45.382456 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2026-01-25 07:48:45.382466 | controller | Sunday 25 January 2026  07:48:45 +0000 (0:00:00.027)       0:00:15.304 ********
2026-01-25 07:48:45.382487 | controller | skipping: [instance]
2026-01-25 07:48:45.382654 | controller |
2026-01-25 07:48:45.382820 | controller | TASK [Download the RPM] ********************************************************
2026-01-25 07:48:45.382960 | controller | Sunday 25 January 2026  07:48:45 +0000 (0:00:00.027)       0:00:15.331 ********
2026-01-25 07:48:45.409015 | controller | skipping: [instance]
2026-01-25 07:48:45.409161 | controller |
2026-01-25 07:48:45.409344 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2026-01-25 07:48:45.409488 | controller | Sunday 25 January 2026  07:48:45 +0000 (0:00:00.026)       0:00:15.357 ********
2026-01-25 07:48:45.435538 | controller | skipping: [instance]
2026-01-25 07:48:45.435686 | controller |
2026-01-25 07:48:45.435826 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2026-01-25 07:48:45.435960 | controller | Sunday 25 January 2026  07:48:45 +0000 (0:00:00.026)       0:00:15.384 ********
2026-01-25 07:48:45.461548 | controller | skipping: [instance]
2026-01-25 07:48:45.461693 | controller |
2026-01-25 07:48:45.461830 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2026-01-25 07:48:45.461962 | controller | Sunday 25 January 2026  07:48:45 +0000 (0:00:00.025)       0:00:15.410 ********
2026-01-25 07:48:45.489295 | controller | skipping: [instance]
2026-01-25 07:48:45.489603 | controller |
2026-01-25 07:48:45.489755 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2026-01-25 07:48:45.489899 | controller | Sunday 25 January 2026  07:48:45 +0000 (0:00:00.027)       0:00:15.438 ********
2026-01-25 07:48:45.515876 | controller | skipping: [instance]
2026-01-25 07:48:45.516018 | controller |
2026-01-25 07:48:45.516155 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2026-01-25 07:48:45.516316 | controller | Sunday 25 January 2026  07:48:45 +0000 (0:00:00.026)       0:00:15.464 ********
2026-01-25 07:48:45.701072 | controller | ok: [instance]
2026-01-25 07:48:45.912033 | controller |
2026-01-25 07:48:45.912065 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2026-01-25 07:48:45.912073 | controller | Sunday 25 January 2026  07:48:45 +0000 (0:00:00.184)       0:00:15.649 ********
2026-01-25 07:48:45.912083 | controller | changed: [instance]
2026-01-25 07:48:46.133873 | controller |
2026-01-25 07:48:46.133904 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2026-01-25 07:48:46.133913 | controller | Sunday 25 January 2026  07:48:45 +0000 (0:00:00.211)       0:00:15.860 ********
2026-01-25 07:48:46.133923 | controller | changed: [instance]
2026-01-25 07:48:46.154827 | controller |
2026-01-25 07:48:46.154845 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2026-01-25 07:48:46.154852 | controller | Sunday 25 January 2026  07:48:46 +0000 (0:00:00.221)       0:00:16.082 ********
2026-01-25 07:48:46.154860 | controller | skipping: [instance]
2026-01-25 07:48:46.176277 | controller |
2026-01-25 07:48:46.176308 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2026-01-25 07:48:46.176316 | controller | Sunday 25 January 2026  07:48:46 +0000 (0:00:00.021)       0:00:16.103 ********
2026-01-25 07:48:46.176395 | controller | skipping: [instance]
2026-01-25 07:48:46.198671 | controller |
2026-01-25 07:48:46.198695 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2026-01-25 07:48:46.198704 | controller | Sunday 25 January 2026  07:48:46 +0000 (0:00:00.021)       0:00:16.124 ********
2026-01-25 07:48:46.198722 | controller | skipping: [instance]
2026-01-25 07:48:46.221039 | controller |
2026-01-25 07:48:46.221059 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2026-01-25 07:48:46.221068 | controller | Sunday 25 January 2026  07:48:46 +0000 (0:00:00.022)       0:00:16.147 ********
2026-01-25 07:48:46.221081 | controller | skipping: [instance]
2026-01-25 07:48:46.243068 | controller |
2026-01-25 07:48:46.243087 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2026-01-25 07:48:46.243094 | controller | Sunday 25 January 2026  07:48:46 +0000 (0:00:00.022)       0:00:16.169 ********
2026-01-25 07:48:46.243103 | controller | skipping: [instance]
2026-01-25 07:48:46.264911 | controller |
2026-01-25 07:48:46.264944 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2026-01-25 07:48:46.264963 | controller | Sunday 25 January 2026  07:48:46 +0000 (0:00:00.021)       0:00:16.191 ********
2026-01-25 07:48:46.264973 | controller | skipping: [instance]
2026-01-25 07:48:46.280751 | controller |
2026-01-25 07:48:46.280789 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2026-01-25 07:48:46.280800 | controller | Sunday 25 January 2026  07:48:46 +0000 (0:00:00.021)       0:00:16.213 ********
2026-01-25 07:48:46.280814 | controller | skipping: [instance]
2026-01-25 07:48:46.281027 | controller |
2026-01-25 07:48:46.281251 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2026-01-25 07:48:46.281460 | controller | Sunday 25 January 2026  07:48:46 +0000 (0:00:00.016)       0:00:16.229 ********
2026-01-25 07:48:46.309811 | controller | skipping: [instance]
2026-01-25 07:48:46.309996 | controller |
2026-01-25 07:48:46.310167 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2026-01-25 07:48:46.310378 | controller | Sunday 25 January 2026  07:48:46 +0000 (0:00:00.029)       0:00:16.258 ********
2026-01-25 07:48:46.325844 | controller | skipping: [instance]
2026-01-25 07:48:46.326030 | controller |
2026-01-25 07:48:46.326243 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2026-01-25 07:48:46.326450 | controller | Sunday 25 January 2026  07:48:46 +0000 (0:00:00.016)       0:00:16.274 ********
2026-01-25 07:48:46.346074 | controller | skipping: [instance]
2026-01-25 07:48:46.346283 | controller |
2026-01-25 07:48:46.346475 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2026-01-25 07:48:46.346657 | controller | Sunday 25 January 2026  07:48:46 +0000 (0:00:00.020)       0:00:16.294 ********
2026-01-25 07:48:46.374422 | controller | skipping: [instance]
2026-01-25 07:48:46.374634 | controller |
2026-01-25 07:48:46.374808 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2026-01-25 07:48:46.374941 | controller | Sunday 25 January 2026  07:48:46 +0000 (0:00:00.028)       0:00:16.323 ********
2026-01-25 07:48:46.403542 | controller | skipping: [instance]
2026-01-25 07:48:46.403724 | controller |
2026-01-25 07:48:46.403881 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2026-01-25 07:48:46.404236 | controller | Sunday 25 January 2026  07:48:46 +0000 (0:00:00.029)       0:00:16.352 ********
2026-01-25 07:48:46.436246 | controller | skipping: [instance]
2026-01-25 07:48:46.436394 | controller |
2026-01-25 07:48:46.436546 | controller | TASK [test_deps : Install additional packages] *********************************
2026-01-25 07:48:46.436684 | controller | Sunday 25 January 2026  07:48:46 +0000 (0:00:00.032)       0:00:16.384 ********
2026-01-25 07:48:46.467460 | controller | skipping: [instance]
2026-01-25 07:48:46.467611 | controller |
2026-01-25 07:48:46.467759 | controller | TASK [test_deps : Install selinux python libs] *********************************
2026-01-25 07:48:46.467891 | controller | Sunday 25 January 2026  07:48:46 +0000 (0:00:00.031)       0:00:16.415 ********
2026-01-25 07:49:28.568668 | controller | ok: [instance]
2026-01-25 07:49:29.832294 | controller |
2026-01-25 07:49:29.832324 | controller | TASK [test_deps : Install python yaml libs] ************************************
2026-01-25 07:49:29.832333 | controller | Sunday 25 January 2026  07:49:28 +0000 (0:00:42.100)       0:00:58.516 ********
2026-01-25 07:49:29.832359 | controller | ok: [instance]
2026-01-25 07:49:29.832384 | controller |
2026-01-25 07:49:29.832392 | controller | TASK [test_deps : Install extra packages] **************************************
2026-01-25 07:49:29.832464 | controller | Sunday 25 January 2026  07:49:29 +0000 (0:00:01.263)       0:00:59.780 ********
2026-01-25 07:49:29.863017 | controller | skipping: [instance]
2026-01-25 07:52:12.813815 | controller |
2026-01-25 07:52:12.813852 | controller | TASK [Ensure CRC is started] ***************************************************
2026-01-25 07:52:12.813861 | controller | Sunday 25 January 2026  07:49:29 +0000 (0:00:00.030)       0:00:59.811 ********
2026-01-25 07:52:12.813871 | controller | changed: [instance]
2026-01-25 07:52:12.814372 | controller |
2026-01-25 07:52:12.814532 | controller | PLAY RECAP *********************************************************************
2026-01-25 07:52:12.814545 | controller | instance                   : ok=20   changed=10   unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2026-01-25 07:52:12.814551 | controller |
2026-01-25 07:52:12.814563 | controller | Sunday 25 January 2026  07:52:12 +0000 (0:02:42.950)       0:03:42.762 ********
2026-01-25 07:52:12.814569 | controller | ===============================================================================
2026-01-25 07:52:12.814579 | controller | Ensure CRC is started ------------------------------------------------- 162.95s
2026-01-25 07:52:12.814588 | controller | test_deps : Install selinux python libs -------------------------------- 42.10s
2026-01-25 07:52:12.814720 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.88s
2026-01-25 07:52:12.815164 | controller | test_deps : Install python yaml libs ------------------------------------ 1.26s
2026-01-25 07:52:12.815194 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.06s
2026-01-25 07:52:12.815212 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2026-01-25 07:52:12.815238 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.84s
2026-01-25 07:52:12.815245 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.79s
2026-01-25 07:52:12.815250 | controller | repo_setup : Install repo-setup package --------------------------------- 0.69s
2026-01-25 07:52:12.815257 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.69s
2026-01-25 07:52:12.815606 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.54s
2026-01-25 07:52:12.815751 | controller | repo_setup : Ensure directories are present ----------------------------- 0.52s
2026-01-25 07:52:12.815775 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.44s
2026-01-25 07:52:12.815782 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.30s
2026-01-25 07:52:12.815790 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s
2026-01-25 07:52:12.815924 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.22s
2026-01-25 07:52:12.815938 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.21s
2026-01-25 07:52:12.816103 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.18s
2026-01-25 07:52:12.816117 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2026-01-25 07:52:12.872097 | controller | test_deps : Gather variables for each operating system ------------------ 0.04s
2026-01-25 07:52:12.872148 | controller | INFO     Running default > converge
2026-01-25 07:52:13.287376 | controller |
2026-01-25 07:52:13.287480 | controller | PLAY [Converge] ****************************************************************
2026-01-25 07:52:13.287590 | controller |
2026-01-25 07:52:13.287786 | controller | TASK [Gathering Facts] *********************************************************
2026-01-25 07:52:13.287815 | controller | Sunday 25 January 2026  07:52:13 +0000 (0:00:00.013)       0:00:00.013 ********
2026-01-25 07:52:14.150319 | controller | ok: [instance]
2026-01-25 07:52:14.152424 | controller |
2026-01-25 07:52:14.455201 | controller | TASK [Add crc hostname with it's IP to /etc/hosts] *****************************
2026-01-25 07:52:14.455239 | controller | Sunday 25 January 2026  07:52:14 +0000 (0:00:00.863)       0:00:00.876 ********
2026-01-25 07:52:14.455253 | controller | changed: [instance]
2026-01-25 07:52:14.479592 | controller |
2026-01-25 07:52:14.479620 | controller | TASK [Check if new ssh keypair exists] *****************************************
2026-01-25 07:52:14.479628 | controller | Sunday 25 January 2026  07:52:14 +0000 (0:00:00.304)       0:00:01.181 ********
2026-01-25 07:52:14.479640 | controller |
2026-01-25 07:52:14.479706 | controller | TASK [recognize_ssh_keypair : Check if id_ed25519 key exists] ******************
2026-01-25 07:52:14.479826 | controller | Sunday 25 January 2026  07:52:14 +0000 (0:00:00.024)       0:00:01.206 ********
2026-01-25 07:52:14.762566 | controller | ok: [instance]
2026-01-25 07:52:14.762612 | controller |
2026-01-25 07:52:14.762761 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair exists] ******************
2026-01-25 07:52:14.762849 | controller | Sunday 25 January 2026  07:52:14 +0000 (0:00:00.282)       0:00:01.489 ********
2026-01-25 07:52:14.783339 | controller | ok: [instance]
2026-01-25 07:52:14.783434 | controller |
2026-01-25 07:52:14.783538 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair does not exists] *********
2026-01-25 07:52:14.783641 | controller | Sunday 25 January 2026  07:52:14 +0000 (0:00:00.021)       0:00:01.510 ********
2026-01-25 07:52:14.806275 | controller | skipping: [instance]
2026-01-25 07:52:14.806371 | controller |
2026-01-25 07:52:14.806467 | controller | TASK [Add the crc host dynamically] ********************************************
2026-01-25 07:52:14.806573 | controller | Sunday 25 January 2026  07:52:14 +0000 (0:00:00.022)       0:00:01.532 ********
2026-01-25 07:52:14.825069 | controller | changed: [instance]
2026-01-25 07:52:14.825163 | controller |
2026-01-25 07:52:14.825290 | controller | TASK [Deploy Cluster observability Operator] ***********************************
2026-01-25 07:52:14.825387 | controller | Sunday 25 January 2026  07:52:14 +0000 (0:00:00.019)       0:00:01.551 ********
2026-01-25 07:52:14.856027 | controller |
2026-01-25 07:52:14.856143 | controller | TASK [openshift_obs : Install cluster observability operator.] *****************
2026-01-25 07:52:14.856260 | controller | Sunday 25 January 2026  07:52:14 +0000 (0:00:00.030)       0:00:01.582 ********
2026-01-25 07:52:15.645632 | controller | changed: [instance]
2026-01-25 07:52:15.645682 | controller |
2026-01-25 07:52:15.645838 | controller | TASK [openshift_obs : Wait for observability operator deployment] **************
2026-01-25 07:52:15.645898 | controller | Sunday 25 January 2026  07:52:15 +0000 (0:00:00.789)       0:00:02.372 ********
2026-01-25 07:57:33.247033 | controller | ok: [instance]
2026-01-25 07:57:33.247088 | controller |
2026-01-25 07:57:33.247099 | controller | TASK [openshift_obs : Wait for observability-operator pod] *********************
2026-01-25 07:57:33.247300 | controller | Sunday 25 January 2026  07:57:33 +0000 (0:05:17.601)       0:05:19.973 ********
2026-01-25 07:59:00.002342 | controller | ok: [instance]
2026-01-25 07:59:00.002468 | controller |
2026-01-25 07:59:00.002483 | controller | TASK [Gather information about observability operator.] ************************
2026-01-25 07:59:00.821685 | controller | Sunday 25 January 2026  07:58:59 +0000 (0:01:26.755)       0:06:46.728 ********
2026-01-25 07:59:00.821728 | controller | ok: [instance]
2026-01-25 07:59:00.847857 | controller |
2026-01-25 07:59:00.847882 | controller | TASK [For debugging purpose, print the information] ****************************
2026-01-25 07:59:00.847891 | controller | Sunday 25 January 2026  07:59:00 +0000 (0:00:00.819)       0:06:47.547 ********
2026-01-25 07:59:00.847902 | controller | ok: [instance] =>
2026-01-25 07:59:00.886897 | controller |   _obs:
2026-01-25 07:59:00.886921 | controller |     api_found: true
2026-01-25 07:59:00.886930 | controller |     changed: false
2026-01-25 07:59:00.886936 | controller |     failed: false
2026-01-25 07:59:00.886942 | controller |     resources:
2026-01-25 07:59:00.886947 | controller |     - apiVersion: operators.coreos.com/v1
2026-01-25 07:59:00.886953 | controller |       kind: Operator
2026-01-25 07:59:00.886959 | controller |       metadata:
2026-01-25 07:59:00.886964 | controller |         creationTimestamp: '2026-01-25T07:54:29Z'
2026-01-25 07:59:00.886969 | controller |         generation: 1
2026-01-25 07:59:00.886975 | controller |         managedFields:
2026-01-25 07:59:00.886980 | controller |         - apiVersion: operators.coreos.com/v1
2026-01-25 07:59:00.886985 | controller |           fieldsType: FieldsV1
2026-01-25 07:59:00.886990 | controller |           fieldsV1:
2026-01-25 07:59:00.886996 | controller |             f:spec: {}
2026-01-25 07:59:00.887001 | controller |           manager: olm
2026-01-25 07:59:00.887006 | controller |           operation: Update
2026-01-25 07:59:00.887011 | controller |           time: '2026-01-25T07:54:29Z'
2026-01-25 07:59:00.887017 | controller |         - apiVersion: operators.coreos.com/v1
2026-01-25 07:59:00.887033 | controller |           fieldsType: FieldsV1
2026-01-25 07:59:00.887039 | controller |           fieldsV1:
2026-01-25 07:59:00.887044 | controller |             f:status:
2026-01-25 07:59:00.887049 | controller |               .: {}
2026-01-25 07:59:00.887055 | controller |               f:components:
2026-01-25 07:59:00.887060 | controller |                 .: {}
2026-01-25 07:59:00.887065 | controller |                 f:labelSelector: {}
2026-01-25 07:59:00.887071 | controller |                 f:refs: {}
2026-01-25 07:59:00.887076 | controller |           manager: olm
2026-01-25 07:59:00.887081 | controller |           operation: Update
2026-01-25 07:59:00.887086 | controller |           subresource: status
2026-01-25 07:59:00.887092 | controller |           time: '2026-01-25T07:58:33Z'
2026-01-25 07:59:00.887098 | controller |         name: observability-operator.openshift-operators
2026-01-25 07:59:00.887108 | controller |         resourceVersion: '34948'
2026-01-25 07:59:00.887113 | controller |         uid: 57c0f0e1-8560-48e5-861a-133b431d7711
2026-01-25 07:59:00.887119 | controller |       spec: {}
2026-01-25 07:59:00.887124 | controller |       status:
2026-01-25 07:59:00.887129 | controller |         components:
2026-01-25 07:59:00.887134 | controller |           labelSelector:
2026-01-25 07:59:00.887141 | controller |             matchExpressions:
2026-01-25 07:59:00.887147 | controller |             - key: operators.coreos.com/observability-operator.openshift-operators
2026-01-25 07:59:00.887152 | controller |               operator: Exists
2026-01-25 07:59:00.887158 | controller |           refs:
2026-01-25 07:59:00.887163 | controller |           - apiVersion: operators.coreos.com/v1alpha1
2026-01-25 07:59:00.887168 | controller |             conditions:
2026-01-25 07:59:00.887173 | controller |             - lastTransitionTime: '2026-01-25T07:54:38Z'
2026-01-25 07:59:00.887178 | controller |               message: all available catalogsources are healthy
2026-01-25 07:59:00.887197 | controller |               reason: AllCatalogSourcesHealthy
2026-01-25 07:59:00.887206 | controller |               status: 'False'
2026-01-25 07:59:00.887212 | controller |               type: CatalogSourcesUnhealthy
2026-01-25 07:59:00.887217 | controller |             kind: Subscription
2026-01-25 07:59:00.887222 | controller |             name: observability-operator
2026-01-25 07:59:00.887227 | controller |             namespace: openshift-operators
2026-01-25 07:59:00.887245 | controller |
2026-01-25 07:59:00.887251 | controller | TASK [Ensure the operator was deployed] ****************************************
2026-01-25 07:59:00.887257 | controller | Sunday 25 January 2026  07:59:00 +0000 (0:00:00.021)       0:06:47.569 ********
2026-01-25 07:59:00.887266 | controller | ok: [instance] => changed=false
2026-01-25 07:59:00.887289 | controller |   msg: All assertions passed
2026-01-25 07:59:00.887296 | controller |
2026-01-25 07:59:00.887303 | controller | PLAY RECAP *********************************************************************
2026-01-25 07:59:00.887431 | controller | instance                   : ok=11   changed=3    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-01-25 07:59:00.887520 | controller |
2026-01-25 07:59:00.887628 | controller | Sunday 25 January 2026  07:59:00 +0000 (0:00:00.043)       0:06:47.613 ********
2026-01-25 07:59:00.888013 | controller | ===============================================================================
2026-01-25 07:59:00.888134 | controller | openshift_obs : Wait for observability operator deployment ------------ 317.60s
2026-01-25 07:59:00.888286 | controller | openshift_obs : Wait for observability-operator pod -------------------- 86.76s
2026-01-25 07:59:00.888411 | controller | Gathering Facts --------------------------------------------------------- 0.86s
2026-01-25 07:59:00.888512 | controller | Gather information about observability operator. ------------------------ 0.82s
2026-01-25 07:59:00.888632 | controller | openshift_obs : Install cluster observability operator. ----------------- 0.79s
2026-01-25 07:59:00.888729 | controller | Add crc hostname with it's IP to /etc/hosts ----------------------------- 0.30s
2026-01-25 07:59:00.888835 | controller | recognize_ssh_keypair : Check if id_ed25519 key exists ------------------ 0.28s
2026-01-25 07:59:00.888938 | controller | Ensure the operator was deployed ---------------------------------------- 0.04s
2026-01-25 07:59:00.889046 | controller | Deploy Cluster observability Operator ----------------------------------- 0.03s
2026-01-25 07:59:00.889150 | controller | Check if new ssh keypair exists ----------------------------------------- 0.02s
2026-01-25 07:59:00.889292 | controller | recognize_ssh_keypair : Set fact if new keypair does not exists --------- 0.02s
2026-01-25 07:59:00.889409 | controller | For debugging purpose, print the information ---------------------------- 0.02s
2026-01-25 07:59:00.889519 | controller | recognize_ssh_keypair : Set fact if new keypair exists ------------------ 0.02s
2026-01-25 07:59:00.889629 | controller | Add the crc host dynamically -------------------------------------------- 0.02s
2026-01-25 07:59:00.941490 | controller | INFO     Running default > cleanup
2026-01-25 07:59:00.941880 | controller | WARNING  Skipping, cleanup playbook not configured.
2026-01-25 07:59:00.942496 | controller | INFO     Writing /tmp/report.html report.
2026-01-25 07:59:01.011162 | controller | changed
2026-01-25 07:59:01.030039 | 
2026-01-25 07:59:01.030089 | PLAY RECAP
2026-01-25 07:59:01.030127 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-01-25 07:59:01.030148 | 
2026-01-25 07:59:01.094821 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-01-25 07:59:01.095668 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-25 07:59:01.629643 | 
2026-01-25 07:59:01.629756 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-01-25 07:59:01.650053 | 
2026-01-25 07:59:01.650140 | TASK [Filter out host if needed]
2026-01-25 07:59:01.658918 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-01-25 07:59:01.663468 | 
2026-01-25 07:59:01.663542 | TASK [Ensure file is present]
2026-01-25 07:59:02.057973 | controller | ok
2026-01-25 07:59:02.063439 | 
2026-01-25 07:59:02.063512 | TASK [Manage molecule report file]
2026-01-25 07:59:02.588272 | controller | changed
2026-01-25 07:59:02.593481 | 
2026-01-25 07:59:02.593547 | TASK [Check if we get ci-framework-data basedir]
2026-01-25 07:59:02.845359 | controller | ok
2026-01-25 07:59:02.850505 | 
2026-01-25 07:59:02.850568 | TASK [Create ci-framework-data log directory for zuul]
2026-01-25 07:59:03.213512 | controller | changed
2026-01-25 07:59:03.219239 | 
2026-01-25 07:59:03.219329 | TASK [Copy ci-framework interesting files]
2026-01-25 07:59:03.485365 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-01-25 07:59:03.745427 | controller | changed
2026-01-25 07:59:03.752032 | 
2026-01-25 07:59:03.752180 | TASK [Get SELinux listing]
2026-01-25 07:59:04.280343 | controller | changed
2026-01-25 07:59:04.286610 | 
2026-01-25 07:59:04.286683 | TASK [Generate log index]
2026-01-25 07:59:05.642059 | controller | changed
2026-01-25 07:59:05.647282 | 
2026-01-25 07:59:05.647384 | TASK [Get some env related data]
2026-01-25 07:59:06.592517 | controller | /home/zuul/.local/bin/ansible
2026-01-25 07:59:07.707321 | controller | changed
2026-01-25 07:59:07.733168 | 
2026-01-25 07:59:07.733281 | TASK [Generate list of logs to collect in home directory]
2026-01-25 07:59:08.136051 | controller | ok: All paths examined
2026-01-25 07:59:08.141520 | 
2026-01-25 07:59:08.141583 | LOOP [Copy logs from home directory]
2026-01-25 07:59:08.912766 | controller | changed:
2026-01-25 07:59:08.912967 | controller | {
2026-01-25 07:59:08.912996 | controller |   "atime": 1743544925.4788878,
2026-01-25 07:59:08.913016 | controller |   "ctime": 1743545329.1409318,
2026-01-25 07:59:08.913033 | controller |   "dev": 64513,
2026-01-25 07:59:08.913048 | controller |   "gid": 1000,
2026-01-25 07:59:08.913063 | controller |   "gr_name": "zuul",
2026-01-25 07:59:08.913080 | controller |   "inode": 4518807,
2026-01-25 07:59:08.913096 | controller |   "isblk": false,
2026-01-25 07:59:08.913110 | controller |   "ischr": false,
2026-01-25 07:59:08.913125 | controller |   "isdir": false,
2026-01-25 07:59:08.913138 | controller |   "isfifo": false,
2026-01-25 07:59:08.913153 | controller |   "isgid": false,
2026-01-25 07:59:08.913166 | controller |   "islnk": false,
2026-01-25 07:59:08.913180 | controller |   "isreg": true,
2026-01-25 07:59:08.913193 | controller |   "issock": false,
2026-01-25 07:59:08.913207 | controller |   "isuid": false,
2026-01-25 07:59:08.913220 | controller |   "mode": "0644",
2026-01-25 07:59:08.913245 | controller |   "mtime": 1743545329.1409318,
2026-01-25 07:59:08.913261 | controller |   "nlink": 1,
2026-01-25 07:59:08.913275 | controller |   "path": "/home/zuul/crc-setup.log",
2026-01-25 07:59:08.913310 | controller |   "pw_name": "zuul",
2026-01-25 07:59:08.913335 | controller |   "rgrp": true,
2026-01-25 07:59:08.913351 | controller |   "roth": true,
2026-01-25 07:59:08.913367 | controller |   "rusr": true,
2026-01-25 07:59:08.913381 | controller |   "size": 4108,
2026-01-25 07:59:08.913395 | controller |   "uid": 1000,
2026-01-25 07:59:08.913409 | controller |   "wgrp": false,
2026-01-25 07:59:08.913422 | controller |   "woth": false,
2026-01-25 07:59:08.913445 | controller |   "wusr": true,
2026-01-25 07:59:08.913459 | controller |   "xgrp": false,
2026-01-25 07:59:08.913473 | controller |   "xoth": false,
2026-01-25 07:59:08.913486 | controller |   "xusr": false
2026-01-25 07:59:08.913499 | controller | }
2026-01-25 07:59:09.625988 | controller | changed:
2026-01-25 07:59:09.626158 | controller | {
2026-01-25 07:59:09.626193 | controller |   "atime": 1743545331.0429637,
2026-01-25 07:59:09.626215 | controller |   "ctime": 1743545842.0111232,
2026-01-25 07:59:09.626242 | controller |   "dev": 64513,
2026-01-25 07:59:09.626260 | controller |   "gid": 1000,
2026-01-25 07:59:09.626275 | controller |   "gr_name": "zuul",
2026-01-25 07:59:09.626288 | controller |   "inode": 4194437,
2026-01-25 07:59:09.626325 | controller |   "isblk": false,
2026-01-25 07:59:09.626344 | controller |   "ischr": false,
2026-01-25 07:59:09.626359 | controller |   "isdir": false,
2026-01-25 07:59:09.626373 | controller |   "isfifo": false,
2026-01-25 07:59:09.626387 | controller |   "isgid": false,
2026-01-25 07:59:09.626409 | controller |   "islnk": false,
2026-01-25 07:59:09.626424 | controller |   "isreg": true,
2026-01-25 07:59:09.626438 | controller |   "issock": false,
2026-01-25 07:59:09.626457 | controller |   "isuid": false,
2026-01-25 07:59:09.626478 | controller |   "mode": "0644",
2026-01-25 07:59:09.626492 | controller |   "mtime": 1743545842.0111232,
2026-01-25 07:59:09.626506 | controller |   "nlink": 1,
2026-01-25 07:59:09.626519 | controller |   "path": "/home/zuul/crc-start.log",
2026-01-25 07:59:09.626533 | controller |   "pw_name": "zuul",
2026-01-25 07:59:09.626546 | controller |   "rgrp": true,
2026-01-25 07:59:09.626559 | controller |   "roth": true,
2026-01-25 07:59:09.626573 | controller |   "rusr": true,
2026-01-25 07:59:09.626587 | controller |   "size": 4023,
2026-01-25 07:59:09.626602 | controller |   "uid": 1000,
2026-01-25 07:59:09.626617 | controller |   "wgrp": false,
2026-01-25 07:59:09.626630 | controller |   "woth": false,
2026-01-25 07:59:09.626643 | controller |   "wusr": true,
2026-01-25 07:59:09.626665 | controller |   "xgrp": false,
2026-01-25 07:59:09.626679 | controller |   "xoth": false,
2026-01-25 07:59:09.626693 | controller |   "xusr": false
2026-01-25 07:59:09.626706 | controller | }
2026-01-25 07:59:10.327474 | controller | changed:
2026-01-25 07:59:10.327567 | controller | {
2026-01-25 07:59:10.327592 | controller |   "atime": 1769327273.9516625,
2026-01-25 07:59:10.327609 | controller |   "ctime": 1769327289.5148537,
2026-01-25 07:59:10.327626 | controller |   "dev": 64513,
2026-01-25 07:59:10.327642 | controller |   "gid": 1000,
2026-01-25 07:59:10.327657 | controller |   "gr_name": "zuul",
2026-01-25 07:59:10.327671 | controller |   "inode": 4340277,
2026-01-25 07:59:10.327684 | controller |   "isblk": false,
2026-01-25 07:59:10.327698 | controller |   "ischr": false,
2026-01-25 07:59:10.327712 | controller |   "isdir": false,
2026-01-25 07:59:10.327725 | controller |   "isfifo": false,
2026-01-25 07:59:10.327738 | controller |   "isgid": false,
2026-01-25 07:59:10.327750 | controller |   "islnk": false,
2026-01-25 07:59:10.327764 | controller |   "isreg": true,
2026-01-25 07:59:10.327778 | controller |   "issock": false,
2026-01-25 07:59:10.327791 | controller |   "isuid": false,
2026-01-25 07:59:10.327805 | controller |   "mode": "0644",
2026-01-25 07:59:10.327819 | controller |   "mtime": 1769327289.5148537,
2026-01-25 07:59:10.327833 | controller |   "nlink": 1,
2026-01-25 07:59:10.327847 | controller |   "path": "/home/zuul/ansible.log",
2026-01-25 07:59:10.327861 | controller |   "pw_name": "zuul",
2026-01-25 07:59:10.327876 | controller |   "rgrp": true,
2026-01-25 07:59:10.327891 | controller |   "roth": true,
2026-01-25 07:59:10.327907 | controller |   "rusr": true,
2026-01-25 07:59:10.327922 | controller |   "size": 6749,
2026-01-25 07:59:10.327936 | controller |   "uid": 1000,
2026-01-25 07:59:10.327950 | controller |   "wgrp": false,
2026-01-25 07:59:10.327964 | controller |   "woth": false,
2026-01-25 07:59:10.327977 | controller |   "wusr": true,
2026-01-25 07:59:10.327991 | controller |   "xgrp": false,
2026-01-25 07:59:10.328006 | controller |   "xoth": false,
2026-01-25 07:59:10.328021 | controller |   "xusr": false
2026-01-25 07:59:10.328035 | controller | }
2026-01-25 07:59:10.341118 | 
2026-01-25 07:59:10.341226 | TASK [Copy crio stats log file]
2026-01-25 07:59:10.355205 | controller | skipping: Conditional result was False
2026-01-25 07:59:10.360761 | 
2026-01-25 07:59:10.360830 | TASK [Get SELinux related data]
2026-01-25 07:59:10.695483 | controller | <no matches>
2026-01-25 07:59:10.889932 | controller | ERROR
2026-01-25 07:59:10.890136 | controller | {
2026-01-25 07:59:10.890174 | controller |   "delta": "0:00:00.021854",
2026-01-25 07:59:10.890200 | controller |   "end": "2026-01-25 07:59:10.697349",
2026-01-25 07:59:10.890222 | controller |   "msg": "non-zero return code",
2026-01-25 07:59:10.890353 | controller |   "rc": 1,
2026-01-25 07:59:10.890384 | controller |   "start": "2026-01-25 07:59:10.675495"
2026-01-25 07:59:10.890407 | controller | }
2026-01-25 07:59:10.890435 | controller | ERROR: Ignoring Errors
2026-01-25 07:59:10.895701 | 
2026-01-25 07:59:10.895765 | TASK [Create system configuration directory]
2026-01-25 07:59:11.167117 | controller | changed
2026-01-25 07:59:11.172069 | 
2026-01-25 07:59:11.172135 | TASK [Get some of the system configurations]
2026-01-25 07:59:11.698528 | controller | changed
2026-01-25 07:59:11.703615 | 
2026-01-25 07:59:11.703676 | TASK [Copy generated documentation if available]
2026-01-25 07:59:11.717119 | controller | skipping: Conditional result was False
2026-01-25 07:59:11.722835 | 
2026-01-25 07:59:11.722897 | TASK [Copy generated AsciiDoc documentation if available]
2026-01-25 07:59:11.736571 | controller | skipping: Conditional result was False
2026-01-25 07:59:11.742086 | 
2026-01-25 07:59:11.742149 | TASK [Compress logs bigger than 2MB]
2026-01-25 07:59:12.269590 | controller | changed
2026-01-25 07:59:12.274557 | 
2026-01-25 07:59:12.274620 | TASK [Copy files from workspace on node]
2026-01-25 07:59:12.292116 | controller | ok
2026-01-25 07:59:12.313510 | 
2026-01-25 07:59:12.313581 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-25 07:59:12.326276 | controller | skipping: Conditional result was False
2026-01-25 07:59:12.331605 | 
2026-01-25 07:59:12.331677 | TASK [fetch-output : Set log path for single node]
2026-01-25 07:59:12.359096 | controller | ok
2026-01-25 07:59:12.364065 | 
2026-01-25 07:59:12.364138 | LOOP [fetch-output : Ensure local output dirs]
2026-01-25 07:59:12.563834 | controller -> localhost | ok: "/var/lib/zuul/builds/0edc3efc9e7a4aacbf90394c4ff0beef/work/logs"
2026-01-25 07:59:12.564059 | controller -> localhost | changed: All items complete
2026-01-25 07:59:12.564094 | 
2026-01-25 07:59:12.742857 | controller -> localhost | changed: "/var/lib/zuul/builds/0edc3efc9e7a4aacbf90394c4ff0beef/work/artifacts"
2026-01-25 07:59:12.926283 | controller -> localhost | changed: "/var/lib/zuul/builds/0edc3efc9e7a4aacbf90394c4ff0beef/work/docs"
2026-01-25 07:59:12.941289 | 
2026-01-25 07:59:12.941402 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-25 07:59:13.596602 | controller | changed:
2026-01-25 07:59:13.596789 | controller | .d..t...... ./
2026-01-25 07:59:13.596822 | controller | >f+++++++++ README.html
2026-01-25 07:59:13.596844 | controller | >f+++++++++ ansible-execution.log
2026-01-25 07:59:13.596866 | controller | >f+++++++++ ansible.log
2026-01-25 07:59:13.596885 | controller | >f+++++++++ crc-setup.log
2026-01-25 07:59:13.596903 | controller | >f+++++++++ crc-start.log
2026-01-25 07:59:13.596921 | controller | >f+++++++++ dmesg.log
2026-01-25 07:59:13.596942 | controller | >f+++++++++ installed-pkgs.log
2026-01-25 07:59:13.596960 | controller | >f+++++++++ python.log
2026-01-25 07:59:13.596977 | controller | >f+++++++++ registries.conf
2026-01-25 07:59:13.596993 | controller | >f+++++++++ report.html
2026-01-25 07:59:13.597018 | controller | >f+++++++++ selinux-denials.log
2026-01-25 07:59:13.597034 | controller | >f+++++++++ selinux-listing.log
2026-01-25 07:59:13.597050 | controller | cd+++++++++ ci-framework-data/
2026-01-25 07:59:13.597066 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-01-25 07:59:13.597082 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-01-25 07:59:13.597100 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-01-25 07:59:13.597116 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-01-25 07:59:13.597133 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2026-01-25 07:59:13.597149 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2026-01-25 07:59:13.597165 | controller | cd+++++++++ ci-framework-data/logs/
2026-01-25 07:59:13.597182 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-01-25 07:59:13.597198 | controller | cd+++++++++ registries.conf.d/
2026-01-25 07:59:13.597214 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-01-25 07:59:13.597230 | controller | cd+++++++++ system-config/
2026-01-25 07:59:13.597259 | controller | cd+++++++++ system-config/libvirt/
2026-01-25 07:59:13.597276 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-01-25 07:59:13.597314 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-01-25 07:59:13.597341 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2026-01-25 07:59:13.597360 | controller | >f+++++++++ system-config/libvirt/network.conf
2026-01-25 07:59:13.597377 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2026-01-25 07:59:13.597394 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2026-01-25 07:59:13.597410 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2026-01-25 07:59:13.597426 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2026-01-25 07:59:13.597441 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2026-01-25 07:59:13.597459 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2026-01-25 07:59:13.597475 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2026-01-25 07:59:13.597491 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2026-01-25 07:59:13.597506 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2026-01-25 07:59:13.597522 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2026-01-25 07:59:13.597538 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2026-01-25 07:59:13.597553 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2026-01-25 07:59:14.105396 | controller | changed: .d..t...... ./
2026-01-25 07:59:14.609813 | controller | changed: .d..t...... ./
2026-01-25 07:59:14.624888 | 
2026-01-25 07:59:14.624973 | TASK [Return artifact to Zuul]
2026-01-25 07:59:14.652982 | controller | ok
2026-01-25 07:59:14.671639 | 
2026-01-25 07:59:14.671725 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-01-25 07:59:14.671824 | 
2026-01-25 07:59:14.671852 | PLAY RECAP
2026-01-25 07:59:14.672059 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-01-25 07:59:14.672080 | 
2026-01-25 07:59:14.748584 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-25 07:59:14.749356 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-25 07:59:15.271722 | 
2026-01-25 07:59:15.271827 | PLAY [all]
2026-01-25 07:59:15.290753 | 
2026-01-25 07:59:15.290833 | TASK [include_role : fetch-output]
2026-01-25 07:59:15.319554 | controller | ok
2026-01-25 07:59:15.336195 | 
2026-01-25 07:59:15.336313 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-25 07:59:15.380287 | controller | skipping: Conditional result was False
2026-01-25 07:59:15.385689 | 
2026-01-25 07:59:15.385763 | TASK [fetch-output : Set log path for single node]
2026-01-25 07:59:15.414117 | controller | ok
2026-01-25 07:59:15.419467 | 
2026-01-25 07:59:15.419540 | LOOP [fetch-output : Ensure local output dirs]
2026-01-25 07:59:15.743206 | controller -> localhost | ok: "/var/lib/zuul/builds/0edc3efc9e7a4aacbf90394c4ff0beef/work/logs"
2026-01-25 07:59:15.923688 | controller -> localhost | ok: "/var/lib/zuul/builds/0edc3efc9e7a4aacbf90394c4ff0beef/work/artifacts"
2026-01-25 07:59:16.106376 | controller -> localhost | ok: "/var/lib/zuul/builds/0edc3efc9e7a4aacbf90394c4ff0beef/work/docs"
2026-01-25 07:59:16.119269 | 
2026-01-25 07:59:16.119392 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-25 07:59:16.751161 | controller | ok
2026-01-25 07:59:16.751471 | controller | ok: All items complete
2026-01-25 07:59:16.751513 | 
2026-01-25 07:59:17.231443 | controller | ok
2026-01-25 07:59:17.706634 | controller | ok
2026-01-25 07:59:17.726291 | 
2026-01-25 07:59:17.726408 | TASK [include_role : fetch-output-openshift]
2026-01-25 07:59:17.739775 | controller | skipping: Conditional result was False
2026-01-25 07:59:17.745385 | 
2026-01-25 07:59:17.745460 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-25 07:59:18.079649 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007238
2026-01-25 07:59:18.271155 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006084
2026-01-25 07:59:18.298320 | 
2026-01-25 07:59:18.298402 | PLAY [all]
2026-01-25 07:59:18.311967 | 
2026-01-25 07:59:18.312034 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-01-25 07:59:18.828353 | controller | changed
2026-01-25 07:59:18.849618 | 
2026-01-25 07:59:18.849684 | PLAY RECAP
2026-01-25 07:59:18.849726 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-01-25 07:59:18.849749 | 
2026-01-25 07:59:18.920369 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-25 07:59:18.921051 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-01-25 07:59:19.461064 | 
2026-01-25 07:59:19.461178 | PLAY [localhost]
2026-01-25 07:59:19.478250 | 
2026-01-25 07:59:19.478362 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-25 07:59:19.789217 | localhost | changed
2026-01-25 07:59:19.793699 | 
2026-01-25 07:59:19.793791 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-25 07:59:19.811281 | localhost | ok
2026-01-25 07:59:19.818971 | 
2026-01-25 07:59:19.819050 | TASK [add-fileserver : Create SSH private key tempfile]
2026-01-25 07:59:20.119627 | localhost | changed
2026-01-25 07:59:20.125149 | 
2026-01-25 07:59:20.125248 | TASK [add-fileserver : Create SSH private key from secret]
2026-01-25 07:59:20.669769 | localhost | changed
2026-01-25 07:59:20.674643 | 
2026-01-25 07:59:20.674720 | TASK [add-fileserver : Add fileserver ssh key]
2026-01-25 07:59:21.015657 | localhost | Identity added: /var/lib/zuul/builds/0edc3efc9e7a4aacbf90394c4ff0beef/work/tmp/ansible.95nvrsbu (/var/lib/zuul/builds/0edc3efc9e7a4aacbf90394c4ff0beef/work/tmp/ansible.95nvrsbu)
2026-01-25 07:59:21.015825 | localhost | ok: Runtime: 0:00:00.006283
2026-01-25 07:59:21.020000 | 
2026-01-25 07:59:21.020073 | TASK [add-fileserver : Remove SSH private key from disk]
2026-01-25 07:59:21.239844 | localhost | ok: Runtime: 0:00:00.004482
2026-01-25 07:59:21.244514 | 
2026-01-25 07:59:21.244607 | TASK [add-fileserver : Add fileserver to inventory]
2026-01-25 07:59:21.292386 | localhost | changed
2026-01-25 07:59:21.297121 | 
2026-01-25 07:59:21.297190 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-01-25 07:59:21.608258 | localhost | changed
2026-01-25 07:59:21.627096 | 
2026-01-25 07:59:21.627154 | PLAY [localhost]
2026-01-25 07:59:21.638025 | 
2026-01-25 07:59:21.638089 | TASK [Generate bulk log download script]
2026-01-25 07:59:21.655993 | localhost | ok
2026-01-25 07:59:21.667166 | 
2026-01-25 07:59:21.667250 | TASK [local-log-download : Check API endpoint is defined]
2026-01-25 07:59:21.694348 | localhost | ok: All assertions passed
2026-01-25 07:59:21.698655 | 
2026-01-25 07:59:21.698727 | TASK [local-log-download : Create download script]
2026-01-25 07:59:22.045930 | localhost -> localhost | changed
2026-01-25 07:59:22.054487 | 
2026-01-25 07:59:22.054566 | TASK [Register quick-download link]
2026-01-25 07:59:22.071336 | localhost | ok
2026-01-25 07:59:22.106336 | 
2026-01-25 07:59:22.106413 | PLAY [logserver.rdoproject.org]
2026-01-25 07:59:22.115431 | 
2026-01-25 07:59:22.115491 | TASK [Set zuul-log-path fact]
2026-01-25 07:59:22.130786 | logserver.rdoproject.org | ok
2026-01-25 07:59:22.139478 | 
2026-01-25 07:59:22.139549 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-25 07:59:22.164970 | logserver.rdoproject.org | ok
2026-01-25 07:59:22.170292 | 
2026-01-25 07:59:22.170381 | TASK [upload-logs : Create log directories]
2026-01-25 07:59:23.887919 | logserver.rdoproject.org | changed
2026-01-25 07:59:23.891282 | 
2026-01-25 07:59:23.896141 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-01-25 07:59:24.122527 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004922
2026-01-25 07:59:24.126893 | 
2026-01-25 07:59:24.126964 | TASK [upload-logs : Upload logs to log server]
2026-01-25 07:59:25.580649 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-01-25 07:59:25.583555 | 
2026-01-25 07:59:25.583631 | LOOP [upload-logs : Compress console log and json output]
2026-01-25 07:59:25.622004 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-25 07:59:25.630385 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-25 07:59:25.640655 | 
2026-01-25 07:59:25.640749 | LOOP [upload-logs : Upload compressed console log and json output]
2026-01-25 07:59:25.674721 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-25 07:59:25.675025 | 
2026-01-25 07:59:25.678419 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-25 07:59:25.688542 | 
2026-01-25 07:59:25.688639 | LOOP [upload-logs : Upload console log and json output]