2026-02-27 18:25:58.419520 | Job console starting...
2026-02-27 18:25:58.696788 | Updating repositories
2026-02-27 18:25:59.462947 | Preparing job workspace
2026-02-27 18:26:07.104753 | Running Ansible setup...
2026-02-27 18:26:11.396903 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-02-27 18:26:11.980246 | 
2026-02-27 18:26:11.980392 | PLAY [localhost]
2026-02-27 18:26:11.989199 | 
2026-02-27 18:26:11.989270 | TASK [Gathering Facts]
2026-02-27 18:26:12.987788 | localhost | ok
2026-02-27 18:26:13.003548 | 
2026-02-27 18:26:13.003709 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-27 18:26:13.416445 | localhost -> localhost | changed
2026-02-27 18:26:13.422596 | 
2026-02-27 18:26:13.422687 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-27 18:26:14.281576 | localhost -> localhost | changed
2026-02-27 18:26:14.304413 | 
2026-02-27 18:26:14.304511 | TASK [Setup log path fact]
2026-02-27 18:26:14.321544 | localhost | ok
2026-02-27 18:26:14.332399 | 
2026-02-27 18:26:14.332481 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-27 18:26:14.349884 | localhost | ok
2026-02-27 18:26:14.357824 | 
2026-02-27 18:26:14.357902 | TASK [emit-job-header : Print job information]
2026-02-27 18:26:14.408150 | # Job Information
2026-02-27 18:26:14.408325 | Ansible Version: 2.15.12
2026-02-27 18:26:14.408362 | Job: cifmw-molecule-openshift_setup
2026-02-27 18:26:14.408387 | Pipeline: github-check
2026-02-27 18:26:14.408408 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-02-27 18:26:14.408428 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3720
2026-02-27 18:26:14.408461 | Log URL (when completed): https://logserver.rdoproject.org/b03/rdoproject.org/b0364962af8f4746b36a70c74f8344a6/
2026-02-27 18:26:14.408481 | Event ID: 991557d0-1409-11f1-8dae-dcdf29da46b2
2026-02-27 18:26:14.412149 | 
2026-02-27 18:26:14.412220 | LOOP [emit-job-header : Print node information]
2026-02-27 18:26:14.511104 | localhost | ok:
2026-02-27 18:26:14.511336 | localhost | # Node Information
2026-02-27 18:26:14.511376 | localhost | Inventory Hostname: controller
2026-02-27 18:26:14.511403 | localhost | Hostname: np0005633372
2026-02-27 18:26:14.511424 | localhost | Username: zuul
2026-02-27 18:26:14.511458 | localhost | Distro: CentOS 9
2026-02-27 18:26:14.511479 | localhost | Provider: ibm-bm4-nodepool
2026-02-27 18:26:14.511498 | localhost | Region: regionOne
2026-02-27 18:26:14.511516 | localhost | Label: centos-9-stream-crc-2-48-0-xl-ibm
2026-02-27 18:26:14.511533 | localhost | Product Name: OpenStack Compute
2026-02-27 18:26:14.511551 | localhost | Interface IP: 192.168.26.180
2026-02-27 18:26:14.536981 | 
2026-02-27 18:26:14.537105 | PLAY [all]
2026-02-27 18:26:14.543329 | 
2026-02-27 18:26:14.543393 | TASK [Gather network facts]
2026-02-27 18:26:14.967577 | controller | ok
2026-02-27 18:26:14.992549 | 
2026-02-27 18:26:14.992731 | TASK [include_role : start-zuul-console]
2026-02-27 18:26:15.035504 | controller | ok
2026-02-27 18:26:15.047426 | 
2026-02-27 18:26:15.047514 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-02-27 18:26:15.457966 | controller | ok
2026-02-27 18:26:15.474543 | 
2026-02-27 18:26:15.474698 | TASK [include_role : add-build-sshkey]
2026-02-27 18:26:15.506243 | controller | ok
2026-02-27 18:26:15.520581 | 
2026-02-27 18:26:15.520665 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-27 18:26:15.739515 | controller -> localhost | ok
2026-02-27 18:26:15.747220 | 
2026-02-27 18:26:15.747346 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-27 18:26:15.778182 | controller | ok
2026-02-27 18:26:15.791923 | controller | included: /var/lib/zuul/builds/b0364962af8f4746b36a70c74f8344a6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-27 18:26:15.797781 | 
2026-02-27 18:26:15.797851 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-27 18:26:16.442239 | controller -> localhost | Generating public/private rsa key pair.
2026-02-27 18:26:16.442541 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b0364962af8f4746b36a70c74f8344a6/work/b0364962af8f4746b36a70c74f8344a6_id_rsa.
2026-02-27 18:26:16.442585 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b0364962af8f4746b36a70c74f8344a6/work/b0364962af8f4746b36a70c74f8344a6_id_rsa.pub.
2026-02-27 18:26:16.442611 | controller -> localhost | The key fingerprint is:
2026-02-27 18:26:16.442633 | controller -> localhost | SHA256:bu6DOb0poPWDXmIlVbSjb9lcQ5dw1TS8As9dwpQlboI zuul-build-sshkey
2026-02-27 18:26:16.442655 | controller -> localhost | The key's randomart image is:
2026-02-27 18:26:16.442674 | controller -> localhost | +---[RSA 3072]----+
2026-02-27 18:26:16.442692 | controller -> localhost | |       .o   .o=*=|
2026-02-27 18:26:16.442710 | controller -> localhost | |       . . o ++++|
2026-02-27 18:26:16.442727 | controller -> localhost | |      . o E * *.o|
2026-02-27 18:26:16.442745 | controller -> localhost | |     . . . . B o |
2026-02-27 18:26:16.442763 | controller -> localhost | |    . o S   o .  |
2026-02-27 18:26:16.442781 | controller -> localhost | |    oo o + . .   |
2026-02-27 18:26:16.442797 | controller -> localhost | |   oo+.+* o      |
2026-02-27 18:26:16.442817 | controller -> localhost | |  ...oB+o.       |
2026-02-27 18:26:16.442834 | controller -> localhost | |   ..  ==o       |
2026-02-27 18:26:16.442851 | controller -> localhost | +----[SHA256]-----+
2026-02-27 18:26:16.442904 | controller -> localhost | ok: Runtime: 0:00:00.204240
2026-02-27 18:26:16.448866 | 
2026-02-27 18:26:16.448940 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-27 18:26:16.469498 | controller | ok
2026-02-27 18:26:16.479166 | controller | included: /var/lib/zuul/builds/b0364962af8f4746b36a70c74f8344a6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-27 18:26:16.487287 | 
2026-02-27 18:26:16.487371 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-27 18:26:16.511757 | controller | skipping: Conditional result was False
2026-02-27 18:26:16.518114 | 
2026-02-27 18:26:16.518184 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-27 18:26:17.021600 | controller | changed
2026-02-27 18:26:17.027663 | 
2026-02-27 18:26:17.027758 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-27 18:26:17.300423 | controller | ok
2026-02-27 18:26:17.307789 | 
2026-02-27 18:26:17.307858 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-27 18:26:18.533001 | controller | changed
2026-02-27 18:26:18.537963 | 
2026-02-27 18:26:18.538039 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-27 18:26:19.841849 | controller | changed
2026-02-27 18:26:19.846856 | 
2026-02-27 18:26:19.846921 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-27 18:26:19.860571 | controller | skipping: Conditional result was False
2026-02-27 18:26:19.877591 | 
2026-02-27 18:26:19.877703 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-27 18:26:20.225971 | controller -> localhost | changed
2026-02-27 18:26:20.235572 | 
2026-02-27 18:26:20.235636 | TASK [add-build-sshkey : Add back temp key]
2026-02-27 18:26:20.557561 | controller -> localhost | Identity added: /var/lib/zuul/builds/b0364962af8f4746b36a70c74f8344a6/work/b0364962af8f4746b36a70c74f8344a6_id_rsa (zuul-build-sshkey)
2026-02-27 18:26:20.557756 | controller -> localhost | ok: Runtime: 0:00:00.015137
2026-02-27 18:26:20.563161 | 
2026-02-27 18:26:20.563225 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-27 18:26:20.946571 | controller | ok
2026-02-27 18:26:20.959655 | 
2026-02-27 18:26:20.959750 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-27 18:26:20.983514 | controller | skipping: Conditional result was False
2026-02-27 18:26:20.993090 | 
2026-02-27 18:26:20.993167 | TASK [include_role : validate-host]
2026-02-27 18:26:21.012683 | controller | ok
2026-02-27 18:26:21.031701 | 
2026-02-27 18:26:21.031800 | TASK [validate-host : Define zuul_info_dir fact]
2026-02-27 18:26:21.062751 | controller | ok
2026-02-27 18:26:21.069069 | 
2026-02-27 18:26:21.069140 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-02-27 18:26:21.369862 | controller -> localhost | ok
2026-02-27 18:26:21.375031 | 
2026-02-27 18:26:21.375110 | TASK [validate-host : Collect information about the host]
2026-02-27 18:26:22.087654 | controller | ok
2026-02-27 18:26:22.096041 | 
2026-02-27 18:26:22.096131 | TASK [validate-host : Sanitize hostname]
2026-02-27 18:26:22.163388 | controller | ok
2026-02-27 18:26:22.168480 | 
2026-02-27 18:26:22.168560 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-02-27 18:26:22.613552 | controller -> localhost | changed
2026-02-27 18:26:22.620008 | 
2026-02-27 18:26:22.620088 | TASK [validate-host : Collect information about zuul worker]
2026-02-27 18:26:23.038381 | controller | ok
2026-02-27 18:26:23.043012 | 
2026-02-27 18:26:23.043088 | TASK [validate-host : Write out all zuul information for each host]
2026-02-27 18:26:23.446887 | controller -> localhost | changed
2026-02-27 18:26:23.457699 | 
2026-02-27 18:26:23.457818 | TASK [include_role : prepare-workspace-openshift]
2026-02-27 18:26:23.471585 | controller | skipping: Conditional result was False
2026-02-27 18:26:23.478197 | 
2026-02-27 18:26:23.478287 | TASK [include_role : remove-zuul-sshkey]
2026-02-27 18:26:23.493137 | controller | skipping: Conditional result was False
2026-02-27 18:26:23.498596 | 
2026-02-27 18:26:23.498680 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-27 18:26:23.775329 | controller | ok: "logs"
2026-02-27 18:26:23.775644 | controller | ok: All items complete
2026-02-27 18:26:23.775678 | 
2026-02-27 18:26:24.010866 | controller | ok: "artifacts"
2026-02-27 18:26:24.243464 | controller | ok: "docs"
2026-02-27 18:26:24.250897 | 
2026-02-27 18:26:24.251024 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-27 18:26:24.525965 | controller | changed: "logs"
2026-02-27 18:26:24.769188 | controller | changed: "artifacts"
2026-02-27 18:26:25.002480 | controller | changed: "docs"
2026-02-27 18:26:25.031389 | 
2026-02-27 18:26:25.031576 | PLAY RECAP
2026-02-27 18:26:25.031632 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-02-27 18:26:25.031662 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-27 18:26:25.031684 | 
2026-02-27 18:26:25.141288 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-02-27 18:26:25.142093 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-02-27 18:26:25.752953 | 
2026-02-27 18:26:25.753057 | PLAY [all]
2026-02-27 18:26:25.772567 | 
2026-02-27 18:26:25.772643 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-02-27 18:26:25.832528 | controller | ok
2026-02-27 18:26:25.837412 | 
2026-02-27 18:26:25.837490 | TASK [mirror-info-fork : Create /etc/ci]
2026-02-27 18:26:26.273678 | controller | changed
2026-02-27 18:26:26.279395 | 
2026-02-27 18:26:26.279703 | TASK [mirror-info-fork : Install ci_mirror script]
2026-02-27 18:26:27.604774 | controller | changed
2026-02-27 18:26:27.615210 | 
2026-02-27 18:26:27.615315 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-02-27 18:26:28.142056 | controller | changed:
2026-02-27 18:26:28.142260 | controller | {
2026-02-27 18:26:28.142317 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-02-27 18:26:28.142358 | controller | }
2026-02-27 18:26:28.436516 | controller | changed:
2026-02-27 18:26:28.436680 | controller | {
2026-02-27 18:26:28.436716 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-02-27 18:26:28.436741 | controller | }
2026-02-27 18:26:28.739669 | controller | changed:
2026-02-27 18:26:28.739784 | controller | {
2026-02-27 18:26:28.739818 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-02-27 18:26:28.739841 | controller | }
2026-02-27 18:26:29.051808 | controller | changed:
2026-02-27 18:26:29.051900 | controller | {
2026-02-27 18:26:29.051929 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-02-27 18:26:29.051953 | controller | }
2026-02-27 18:26:29.365464 | controller | changed:
2026-02-27 18:26:29.365916 | controller | {
2026-02-27 18:26:29.365959 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-02-27 18:26:29.365986 | controller | }
2026-02-27 18:26:29.683813 | controller | changed:
2026-02-27 18:26:29.683921 | controller | {
2026-02-27 18:26:29.683951 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-02-27 18:26:29.683972 | controller | }
2026-02-27 18:26:30.009663 | controller | changed:
2026-02-27 18:26:30.009772 | controller | {
2026-02-27 18:26:30.009801 | 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-02-27 18:26:30.009825 | controller | }
2026-02-27 18:26:30.342009 | controller | changed:
2026-02-27 18:26:30.342104 | controller | {
2026-02-27 18:26:30.342140 | 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-02-27 18:26:30.342168 | controller | }
2026-02-27 18:26:30.665246 | controller | changed:
2026-02-27 18:26:30.665389 | controller | {
2026-02-27 18:26:30.665422 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-02-27 18:26:30.665446 | controller | }
2026-02-27 18:26:30.950331 | controller | changed:
2026-02-27 18:26:30.950436 | controller | {
2026-02-27 18:26:30.950464 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-02-27 18:26:30.950486 | controller | }
2026-02-27 18:26:31.276257 | controller | changed:
2026-02-27 18:26:31.276375 | controller | {
2026-02-27 18:26:31.276405 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-02-27 18:26:31.276427 | controller | }
2026-02-27 18:26:31.569717 | controller | changed:
2026-02-27 18:26:31.569810 | controller | {
2026-02-27 18:26:31.569840 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-02-27 18:26:31.569863 | controller | }
2026-02-27 18:26:31.884541 | controller | changed:
2026-02-27 18:26:31.884825 | controller | {
2026-02-27 18:26:31.885038 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-02-27 18:26:31.885072 | controller | }
2026-02-27 18:26:32.192633 | controller | changed:
2026-02-27 18:26:32.192727 | controller | {
2026-02-27 18:26:32.192755 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-02-27 18:26:32.192775 | controller | }
2026-02-27 18:26:32.501090 | controller | changed:
2026-02-27 18:26:32.501336 | controller | {
2026-02-27 18:26:32.501377 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-02-27 18:26:32.501402 | controller | }
2026-02-27 18:26:32.837353 | controller | changed:
2026-02-27 18:26:32.837584 | controller | {
2026-02-27 18:26:32.837630 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-02-27 18:26:32.837655 | controller | }
2026-02-27 18:26:33.163155 | controller | changed:
2026-02-27 18:26:33.163240 | controller | {
2026-02-27 18:26:33.163268 | 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-02-27 18:26:33.163336 | controller | }
2026-02-27 18:26:33.458957 | controller | changed:
2026-02-27 18:26:33.459050 | controller | {
2026-02-27 18:26:33.459078 | 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-02-27 18:26:33.459110 | controller | }
2026-02-27 18:26:33.751399 | controller | changed:
2026-02-27 18:26:33.751489 | controller | {
2026-02-27 18:26:33.751515 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-02-27 18:26:33.751536 | controller | }
2026-02-27 18:26:34.047355 | controller | changed:
2026-02-27 18:26:34.047450 | controller | {
2026-02-27 18:26:34.047476 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-02-27 18:26:34.047496 | controller | }
2026-02-27 18:26:34.334182 | controller | changed:
2026-02-27 18:26:34.334278 | controller | {
2026-02-27 18:26:34.334329 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-02-27 18:26:34.334354 | controller | }
2026-02-27 18:26:34.640578 | controller | changed:
2026-02-27 18:26:34.640662 | controller | {
2026-02-27 18:26:34.640690 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-02-27 18:26:34.640709 | controller | }
2026-02-27 18:26:34.941641 | controller | changed:
2026-02-27 18:26:34.941737 | controller | {
2026-02-27 18:26:34.941764 | 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-02-27 18:26:34.941785 | controller | }
2026-02-27 18:26:35.233523 | controller | changed:
2026-02-27 18:26:35.233623 | controller | {
2026-02-27 18:26:35.233650 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-02-27 18:26:35.233671 | controller | }
2026-02-27 18:26:35.524948 | controller | changed:
2026-02-27 18:26:35.525026 | controller | {
2026-02-27 18:26:35.525054 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-02-27 18:26:35.525074 | controller | }
2026-02-27 18:26:35.825191 | controller | changed:
2026-02-27 18:26:35.825289 | controller | {
2026-02-27 18:26:35.825336 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-02-27 18:26:35.825358 | controller | }
2026-02-27 18:26:35.844848 | 
2026-02-27 18:26:35.844953 | TASK [Set timezone to UTC]
2026-02-27 18:26:36.350457 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-02-27 18:26:36.355364 | 
2026-02-27 18:26:36.355447 | TASK [Create nodepool directory]
2026-02-27 18:26:36.638753 | controller | changed
2026-02-27 18:26:36.643949 | 
2026-02-27 18:26:36.644013 | TASK [Create nodepool sub_nodes file]
2026-02-27 18:26:37.663701 | controller | changed
2026-02-27 18:26:37.668716 | 
2026-02-27 18:26:37.668802 | TASK [Create nodepool sub_nodes_private file]
2026-02-27 18:26:38.721543 | controller | changed
2026-02-27 18:26:38.726626 | 
2026-02-27 18:26:38.726752 | LOOP [Populate nodepool sub_nodes file]
2026-02-27 18:26:38.756100 | 
2026-02-27 18:26:38.756259 | LOOP [Populate nodepool sub_nodes_private file]
2026-02-27 18:26:38.786223 | 
2026-02-27 18:26:38.786489 | TASK [Create nodepool primary file]
2026-02-27 18:26:38.810406 | controller | skipping: Conditional result was False
2026-02-27 18:26:38.815968 | 
2026-02-27 18:26:38.816046 | TASK [Create nodepool node_private for this node]
2026-02-27 18:26:39.910925 | controller | changed
2026-02-27 18:26:39.916240 | 
2026-02-27 18:26:39.916319 | LOOP [Copy ssh keys to nodepool directory]
2026-02-27 18:26:40.331556 | controller | ok: Item: id_rsa Runtime: 0:00:00.006971
2026-02-27 18:26:40.331745 | 
2026-02-27 18:26:40.581777 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005883
2026-02-27 18:26:40.588522 | 
2026-02-27 18:26:40.588602 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-02-27 18:26:41.660008 | controller | changed
2026-02-27 18:26:41.665001 | 
2026-02-27 18:26:41.665086 | TASK [Validate sudoers config after edits]
2026-02-27 18:26:42.026892 | controller | /etc/sudoers: parsed OK
2026-02-27 18:26:42.026951 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-02-27 18:26:42.026960 | controller | /etc/sudoers.d/zuul: parsed OK
2026-02-27 18:26:42.026966 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-02-27 18:26:42.220650 | controller | ok: Runtime: 0:00:00.004883
2026-02-27 18:26:42.226222 | 
2026-02-27 18:26:42.226288 | TASK [Show the environment passed in to job shell scripts]
2026-02-27 18:26:42.504030 | controller | SHELL=/bin/bash
2026-02-27 18:26:42.504079 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-02-27 18:26:42.504088 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-02-27 18:26:42.504097 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/20/3720/252e5f5e4649e1bb126fc1e81f2bc8e3a007543a
2026-02-27 18:26:42.504159 | controller | PWD=/home/zuul
2026-02-27 18:26:42.504168 | controller | ZUUL_PIPELINE=github-check
2026-02-27 18:26:42.504174 | controller | LOGNAME=zuul
2026-02-27 18:26:42.504179 | controller | XDG_SESSION_TYPE=tty
2026-02-27 18:26:42.504185 | controller | _=/usr/bin/env
2026-02-27 18:26:42.504190 | controller | MOTD_SHOWN=pam
2026-02-27 18:26:42.504195 | controller | HOME=/home/zuul
2026-02-27 18:26:42.504201 | controller | LANG=en_US.UTF-8
2026-02-27 18:26:42.504206 | controller | SSH_CONNECTION=192.168.26.12 41624 192.168.26.180 22
2026-02-27 18:26:42.504212 | 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-02-27 18:26:42.504221 | controller | ZUUL_CHANGE_IDS=3720,252e5f5e4649e1bb126fc1e81f2bc8e3a007543a
2026-02-27 18:26:42.504227 | controller | WORKSPACE=/home/zuul/workspace
2026-02-27 18:26:42.504233 | controller | XDG_SESSION_CLASS=user
2026-02-27 18:26:42.504239 | controller | SELINUX_ROLE_REQUESTED=
2026-02-27 18:26:42.504244 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-02-27 18:26:42.504250 | controller | USER=zuul
2026-02-27 18:26:42.504255 | controller | ZUUL_VOTING=True
2026-02-27 18:26:42.504261 | controller | BUILD_TIMEOUT=1800000
2026-02-27 18:26:42.504266 | controller | SELINUX_USE_CURRENT_RANGE=
2026-02-27 18:26:42.504271 | controller | SHLVL=1
2026-02-27 18:26:42.504277 | controller | ZUUL_PATCHSET=252e5f5e4649e1bb126fc1e81f2bc8e3a007543a
2026-02-27 18:26:42.504282 | controller | XDG_SESSION_ID=1
2026-02-27 18:26:42.504288 | controller | ZUUL_BRANCH=main
2026-02-27 18:26:42.504293 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-02-27 18:26:42.504298 | controller | SSH_CLIENT=192.168.26.12 41624 22
2026-02-27 18:26:42.504304 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-02-27 18:26:42.504310 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-02-27 18:26:42.504315 | controller | which_declare=declare -f
2026-02-27 18:26:42.504321 | 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-02-27 18:26:42.504327 | controller | SELINUX_LEVEL_REQUESTED=
2026-02-27 18:26:42.504332 | controller | ZUUL_CHANGE=3720
2026-02-27 18:26:42.504338 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-02-27 18:26:42.504343 | controller | ZUUL_UUID=b0364962af8f4746b36a70c74f8344a6
2026-02-27 18:26:42.504348 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-02-27 18:26:42.504354 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-02-27 18:26:42.504360 | controller | }
2026-02-27 18:26:42.757851 | controller | ok: Runtime: 0:00:00.006639
2026-02-27 18:26:42.763528 | 
2026-02-27 18:26:42.763592 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-02-27 18:26:42.791700 | controller | skipping: Conditional result was False
2026-02-27 18:26:42.797484 | 
2026-02-27 18:26:42.797553 | TASK [Symlink /home/zuul-worker/workspace]
2026-02-27 18:26:43.384226 | controller | skipping: Conditional result was False
2026-02-27 18:26:43.390461 | 
2026-02-27 18:26:43.390557 | TASK [Ensure legacy workspace directory]
2026-02-27 18:26:43.642915 | controller | changed
2026-02-27 18:26:43.661346 | 
2026-02-27 18:26:43.661416 | PLAY RECAP
2026-02-27 18:26:43.661462 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-02-27 18:26:43.661485 | 
2026-02-27 18:26:43.736375 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-02-27 18:26:43.737086 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-02-27 18:26:44.340318 | 
2026-02-27 18:26:44.340422 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-02-27 18:26:44.362284 | 
2026-02-27 18:26:44.362383 | TASK [Create zuul-output directory]
2026-02-27 18:26:44.778188 | controller | changed
2026-02-27 18:26:44.783654 | 
2026-02-27 18:26:44.783752 | TASK [Slurp Zuul inventory test]
2026-02-27 18:26:45.071740 | controller -> localhost | ok
2026-02-27 18:26:45.078563 | 
2026-02-27 18:26:45.078662 | TASK [Save zuul inventory]
2026-02-27 18:26:46.228170 | controller | changed
2026-02-27 18:26:46.239759 | 
2026-02-27 18:26:46.239836 | TASK [Save zuul vars without the change_message]
2026-02-27 18:26:47.242772 | controller | changed
2026-02-27 18:26:47.261385 | 
2026-02-27 18:26:47.261460 | PLAY RECAP
2026-02-27 18:26:47.261504 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-27 18:26:47.261527 | 
2026-02-27 18:26:47.349090 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-02-27 18:26:47.349852 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-02-27 18:26:47.995900 | 
2026-02-27 18:26:47.996004 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-02-27 18:26:48.032467 | 
2026-02-27 18:26:48.032583 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-02-27 18:26:48.063398 | controller | ok
2026-02-27 18:26:48.080359 | 
2026-02-27 18:26:48.080440 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-02-27 18:26:48.124340 | controller | skipping: Conditional result was False
2026-02-27 18:26:48.130247 | 
2026-02-27 18:26:48.130361 | TASK [mirror-info-fork : Create /etc/ci]
2026-02-27 18:26:48.553567 | controller | ok
2026-02-27 18:26:48.561212 | 
2026-02-27 18:26:48.561333 | TASK [mirror-info-fork : Install ci_mirror script]
2026-02-27 18:26:49.509462 | controller | ok
2026-02-27 18:26:49.521057 | 
2026-02-27 18:26:49.521133 | TASK [Prepare workspace]
2026-02-27 18:26:49.539563 | controller | ok
2026-02-27 18:26:49.556029 | 
2026-02-27 18:26:49.556097 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-27 18:26:49.910066 | controller | ok
2026-02-27 18:26:49.917572 | 
2026-02-27 18:26:49.917640 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-27 18:26:52.573506 | controller | Output suppressed because no_log was given
2026-02-27 18:26:52.586149 | 
2026-02-27 18:26:52.586218 | LOOP [Create zuul-output directory]
2026-02-27 18:26:52.835373 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-02-27 18:26:53.050416 | controller | ok: "/home/zuul/zuul-output/logs"
2026-02-27 18:26:53.062618 | 
2026-02-27 18:26:53.062681 | TASK [Install required packages]
2026-02-27 18:27:18.328455 | controller | ok: Nothing to do
2026-02-27 18:27:18.333373 | 
2026-02-27 18:27:18.333434 | TASK [Install venv]
2026-02-27 18:28:17.189154 | controller | changed
2026-02-27 18:28:17.214155 | 
2026-02-27 18:28:17.214217 | PLAY RECAP
2026-02-27 18:28:17.214277 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-27 18:28:17.214323 | 
2026-02-27 18:28:17.283858 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-02-27 18:28:17.284686 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-02-27 18:28:17.790997 | 
2026-02-27 18:28:17.791098 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-02-27 18:28:17.811405 | 
2026-02-27 18:28:17.811482 | TASK [Gather required facts]
2026-02-27 18:28:18.349113 | controller | ok
2026-02-27 18:28:18.354406 | 
2026-02-27 18:28:18.354486 | TASK [Load environment var if instructed to]
2026-02-27 18:28:18.378600 | controller | skipping: Conditional result was False
2026-02-27 18:28:18.383826 | 
2026-02-27 18:28:18.383889 | TASK [Ensure group_vars dir exists]
2026-02-27 18:28:18.746830 | controller | ok
2026-02-27 18:28:18.752018 | 
2026-02-27 18:28:18.752086 | TASK [Print related variables]
2026-02-27 18:28:18.780763 | controller | ok:
2026-02-27 18:28:18.780872 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-02-27 18:28:18.780901 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/openshift_setup
2026-02-27 18:28:18.785641 | 
2026-02-27 18:28:18.785704 | TASK [Run molecule]
2026-02-27 18:28:19.767998 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-02-27 18:28:19.837333 | controller | INFO     Performing prerun with role_name_check=0...
2026-02-27 18:28:36.400373 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-27 18:28:36.400805 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-27 18:28:36.401260 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-27 18:28:36.401699 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-27 18:28:36.402154 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+1e6d5ffd was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 1.0.0+1e6d5ffd (/home/zuul/.ansible/collections/ansible_collections).
2026-02-27 18:28:36.402592 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-27 18:28:36.403000 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-27 18:28:36.403433 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-27 18:28:36.403835 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-27 18:28:36.404267 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-27 18:28:36.404718 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-27 18:28:36.405184 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-27 18:28:36.405627 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-27 18:28:36.406071 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-27 18:28:36.406512 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-27 18:28:36.415780 | controller | INFO     Running default > prepare
2026-02-27 18:28:37.057539 | controller |
2026-02-27 18:28:37.057591 | controller | PLAY [Prepare] *****************************************************************
2026-02-27 18:28:37.057693 | controller |
2026-02-27 18:28:37.057790 | controller | TASK [Gathering Facts] *********************************************************
2026-02-27 18:28:37.057881 | controller | Friday 27 February 2026  18:28:37 +0000 (0:00:00.013)       0:00:00.013 *******
2026-02-27 18:28:37.976149 | controller | ok: [instance]
2026-02-27 18:28:37.976195 | controller |
2026-02-27 18:28:37.976378 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2026-02-27 18:28:37.976450 | controller | Friday 27 February 2026  18:28:37 +0000 (0:00:00.919)       0:00:00.932 *******
2026-02-27 18:28:37.993660 | controller | skipping: [instance]
2026-02-27 18:28:37.993689 | controller |
2026-02-27 18:28:37.993897 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2026-02-27 18:28:37.993950 | controller | Friday 27 February 2026  18:28:37 +0000 (0:00:00.017)       0:00:00.950 *******
2026-02-27 18:28:38.033438 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2026-02-27 18:28:38.033514 | controller |
2026-02-27 18:28:38.033655 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2026-02-27 18:28:38.033710 | controller | Friday 27 February 2026  18:28:38 +0000 (0:00:00.039)       0:00:00.990 *******
2026-02-27 18:28:38.320505 | controller | ok: [instance]
2026-02-27 18:28:38.320541 | controller |
2026-02-27 18:28:38.320684 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2026-02-27 18:28:38.320775 | controller | Friday 27 February 2026  18:28:38 +0000 (0:00:00.286)       0:00:01.277 *******
2026-02-27 18:28:38.642192 | controller | ok: [instance]
2026-02-27 18:28:38.642246 | controller |
2026-02-27 18:28:38.642356 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2026-02-27 18:28:38.642466 | controller | Friday 27 February 2026  18:28:38 +0000 (0:00:00.321)       0:00:01.598 *******
2026-02-27 18:28:39.354201 | controller | ok: [instance] => (item=ubi-9-appstream)
2026-02-27 18:28:39.354247 | controller | ok: [instance] => (item=ubi-9-baseos)
2026-02-27 18:28:39.354359 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2026-02-27 18:28:39.354442 | controller |
2026-02-27 18:28:39.354526 | controller | TASK [Deploy repo-setup] *******************************************************
2026-02-27 18:28:39.354617 | controller | Friday 27 February 2026  18:28:39 +0000 (0:00:00.711)       0:00:02.310 *******
2026-02-27 18:28:39.414680 | controller |
2026-02-27 18:28:39.414730 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2026-02-27 18:28:39.414832 | controller | Friday 27 February 2026  18:28:39 +0000 (0:00:00.060)       0:00:02.371 *******
2026-02-27 18:28:39.960909 | controller | changed: [instance] => (item=tmp)
2026-02-27 18:28:39.960950 | controller | changed: [instance] => (item=artifacts/repositories)
2026-02-27 18:28:39.961038 | controller | changed: [instance] => (item=venv/repo_setup)
2026-02-27 18:28:39.961144 | controller |
2026-02-27 18:28:39.961233 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2026-02-27 18:28:39.961322 | controller | Friday 27 February 2026  18:28:39 +0000 (0:00:00.546)       0:00:02.917 *******
2026-02-27 18:28:40.837810 | controller | ok: [instance]
2026-02-27 18:28:41.882753 | controller |
2026-02-27 18:28:41.882793 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2026-02-27 18:28:41.882801 | controller | Friday 27 February 2026  18:28:40 +0000 (0:00:00.876)       0:00:03.794 *******
2026-02-27 18:28:41.882815 | controller | changed: [instance]
2026-02-27 18:28:49.603765 | controller |
2026-02-27 18:28:49.603809 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2026-02-27 18:28:49.603818 | controller | Friday 27 February 2026  18:28:41 +0000 (0:00:01.045)       0:00:04.839 *******
2026-02-27 18:28:49.603828 | controller | changed: [instance]
2026-02-27 18:28:50.332114 | controller |
2026-02-27 18:28:50.332153 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2026-02-27 18:28:50.332162 | controller | Friday 27 February 2026  18:28:49 +0000 (0:00:07.720)       0:00:12.560 *******
2026-02-27 18:28:50.332173 | controller | changed: [instance]
2026-02-27 18:28:50.353927 | controller |
2026-02-27 18:28:50.353960 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2026-02-27 18:28:50.353969 | controller | Friday 27 February 2026  18:28:50 +0000 (0:00:00.728)       0:00:13.288 *******
2026-02-27 18:28:50.353979 | controller | skipping: [instance]
2026-02-27 18:28:51.201089 | controller |
2026-02-27 18:28:51.201120 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2026-02-27 18:28:51.201128 | controller | Friday 27 February 2026  18:28:50 +0000 (0:00:00.022)       0:00:13.310 *******
2026-02-27 18:28:51.201138 | controller | changed: [instance]
2026-02-27 18:28:51.237546 | controller |
2026-02-27 18:28:51.237574 | controller | TASK [repo_setup : Get component repo] *****************************************
2026-02-27 18:28:51.237584 | controller | Friday 27 February 2026  18:28:51 +0000 (0:00:00.846)       0:00:14.157 *******
2026-02-27 18:28:51.237595 | controller | skipping: [instance]
2026-02-27 18:28:51.287551 | controller |
2026-02-27 18:28:51.287572 | controller | TASK [repo_setup : Rename component repo] **************************************
2026-02-27 18:28:51.287581 | controller | Friday 27 February 2026  18:28:51 +0000 (0:00:00.036)       0:00:14.193 *******
2026-02-27 18:28:51.287593 | controller | skipping: [instance]
2026-02-27 18:28:51.324078 | controller |
2026-02-27 18:28:51.324102 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2026-02-27 18:28:51.324110 | controller | Friday 27 February 2026  18:28:51 +0000 (0:00:00.050)       0:00:14.244 *******
2026-02-27 18:28:51.324120 | controller | skipping: [instance]
2026-02-27 18:28:51.324223 | controller |
2026-02-27 18:28:51.324234 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2026-02-27 18:28:51.324370 | controller | Friday 27 February 2026  18:28:51 +0000 (0:00:00.036)       0:00:14.280 *******
2026-02-27 18:28:51.853644 | controller | changed: [instance]
2026-02-27 18:28:51.853779 | controller |
2026-02-27 18:28:51.853900 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2026-02-27 18:28:51.854027 | controller | Friday 27 February 2026  18:28:51 +0000 (0:00:00.529)       0:00:14.810 *******
2026-02-27 18:28:52.271155 | controller | changed: [instance]
2026-02-27 18:28:52.271205 | controller |
2026-02-27 18:28:52.271460 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2026-02-27 18:28:52.300064 | controller | Friday 27 February 2026  18:28:52 +0000 (0:00:00.417)       0:00:15.227 *******
2026-02-27 18:28:52.300116 | controller | skipping: [instance]
2026-02-27 18:28:52.330800 | controller |
2026-02-27 18:28:52.330830 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2026-02-27 18:28:52.330838 | controller | Friday 27 February 2026  18:28:52 +0000 (0:00:00.028)       0:00:15.256 *******
2026-02-27 18:28:52.330852 | controller | skipping: [instance]
2026-02-27 18:28:52.359463 | controller |
2026-02-27 18:28:52.359482 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2026-02-27 18:28:52.359490 | controller | Friday 27 February 2026  18:28:52 +0000 (0:00:00.030)       0:00:15.287 *******
2026-02-27 18:28:52.359499 | controller | skipping: [instance]
2026-02-27 18:28:52.397806 | controller |
2026-02-27 18:28:52.397824 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2026-02-27 18:28:52.397831 | controller | Friday 27 February 2026  18:28:52 +0000 (0:00:00.028)       0:00:15.316 *******
2026-02-27 18:28:52.397840 | controller | ok: [instance]
2026-02-27 18:28:52.424769 | controller |
2026-02-27 18:28:52.424791 | controller | TASK [repo_setup : Create download directory] **********************************
2026-02-27 18:28:52.424800 | controller | Friday 27 February 2026  18:28:52 +0000 (0:00:00.038)       0:00:15.354 *******
2026-02-27 18:28:52.424810 | controller | skipping: [instance]
2026-02-27 18:28:52.451135 | controller |
2026-02-27 18:28:52.451174 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2026-02-27 18:28:52.451184 | controller | Friday 27 February 2026  18:28:52 +0000 (0:00:00.026)       0:00:15.381 *******
2026-02-27 18:28:52.451205 | controller | skipping: [instance]
2026-02-27 18:28:52.451235 | controller |
2026-02-27 18:28:52.451245 | controller | TASK [Download the RPM] ********************************************************
2026-02-27 18:28:52.451335 | controller | Friday 27 February 2026  18:28:52 +0000 (0:00:00.026)       0:00:15.407 *******
2026-02-27 18:28:52.476752 | controller | skipping: [instance]
2026-02-27 18:28:52.476927 | controller |
2026-02-27 18:28:52.477092 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2026-02-27 18:28:52.477228 | controller | Friday 27 February 2026  18:28:52 +0000 (0:00:00.025)       0:00:15.433 *******
2026-02-27 18:28:52.503106 | controller | skipping: [instance]
2026-02-27 18:28:52.503256 | controller |
2026-02-27 18:28:52.503409 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2026-02-27 18:28:52.503538 | controller | Friday 27 February 2026  18:28:52 +0000 (0:00:00.026)       0:00:15.460 *******
2026-02-27 18:28:52.528791 | controller | skipping: [instance]
2026-02-27 18:28:52.528934 | controller |
2026-02-27 18:28:52.529094 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2026-02-27 18:28:52.529232 | controller | Friday 27 February 2026  18:28:52 +0000 (0:00:00.025)       0:00:15.485 *******
2026-02-27 18:28:52.559144 | controller | skipping: [instance]
2026-02-27 18:28:52.559343 | controller |
2026-02-27 18:28:52.559516 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2026-02-27 18:28:52.559676 | controller | Friday 27 February 2026  18:28:52 +0000 (0:00:00.030)       0:00:15.516 *******
2026-02-27 18:28:52.584513 | controller | skipping: [instance]
2026-02-27 18:28:52.584687 | controller |
2026-02-27 18:28:52.584848 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2026-02-27 18:28:52.585003 | controller | Friday 27 February 2026  18:28:52 +0000 (0:00:00.025)       0:00:15.541 *******
2026-02-27 18:28:52.765812 | controller | ok: [instance]
2026-02-27 18:28:52.967680 | controller |
2026-02-27 18:28:52.967714 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2026-02-27 18:28:52.967724 | controller | Friday 27 February 2026  18:28:52 +0000 (0:00:00.180)       0:00:15.722 *******
2026-02-27 18:28:52.967736 | controller | changed: [instance]
2026-02-27 18:28:53.188452 | controller |
2026-02-27 18:28:53.188483 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2026-02-27 18:28:53.188491 | controller | Friday 27 February 2026  18:28:52 +0000 (0:00:00.201)       0:00:15.924 *******
2026-02-27 18:28:53.188501 | controller | changed: [instance]
2026-02-27 18:28:53.208773 | controller |
2026-02-27 18:28:53.208793 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2026-02-27 18:28:53.208802 | controller | Friday 27 February 2026  18:28:53 +0000 (0:00:00.220)       0:00:16.144 *******
2026-02-27 18:28:53.208812 | controller | skipping: [instance]
2026-02-27 18:28:53.229811 | controller |
2026-02-27 18:28:53.229830 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2026-02-27 18:28:53.229839 | controller | Friday 27 February 2026  18:28:53 +0000 (0:00:00.020)       0:00:16.165 *******
2026-02-27 18:28:53.229849 | controller | skipping: [instance]
2026-02-27 18:28:53.250835 | controller |
2026-02-27 18:28:53.250853 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2026-02-27 18:28:53.250861 | controller | Friday 27 February 2026  18:28:53 +0000 (0:00:00.021)       0:00:16.186 *******
2026-02-27 18:28:53.250869 | controller | skipping: [instance]
2026-02-27 18:28:53.271979 | controller |
2026-02-27 18:28:53.271997 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2026-02-27 18:28:53.272005 | controller | Friday 27 February 2026  18:28:53 +0000 (0:00:00.020)       0:00:16.207 *******
2026-02-27 18:28:53.272014 | controller | skipping: [instance]
2026-02-27 18:28:53.294171 | controller |
2026-02-27 18:28:53.294212 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2026-02-27 18:28:53.294222 | controller | Friday 27 February 2026  18:28:53 +0000 (0:00:00.021)       0:00:16.228 *******
2026-02-27 18:28:53.294235 | controller | skipping: [instance]
2026-02-27 18:28:53.294432 | controller |
2026-02-27 18:28:53.294470 | controller | TASK [repo_setup : Lower the priority of component repos to allow installation from gating repo] ***
2026-02-27 18:28:53.315726 | controller | Friday 27 February 2026  18:28:53 +0000 (0:00:00.022)       0:00:16.250 *******
2026-02-27 18:28:53.315747 | controller | skipping: [instance]
2026-02-27 18:28:53.331537 | controller |
2026-02-27 18:28:53.331559 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2026-02-27 18:28:53.331569 | controller | Friday 27 February 2026  18:28:53 +0000 (0:00:00.021)       0:00:16.272 *******
2026-02-27 18:28:53.331580 | controller | skipping: [instance]
2026-02-27 18:28:53.331714 | controller |
2026-02-27 18:28:53.331848 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2026-02-27 18:28:53.331975 | controller | Friday 27 February 2026  18:28:53 +0000 (0:00:00.016)       0:00:16.288 *******
2026-02-27 18:28:53.359507 | controller | skipping: [instance]
2026-02-27 18:28:53.359678 | controller |
2026-02-27 18:28:53.359812 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2026-02-27 18:28:53.359941 | controller | Friday 27 February 2026  18:28:53 +0000 (0:00:00.027)       0:00:16.316 *******
2026-02-27 18:28:53.376099 | controller | skipping: [instance]
2026-02-27 18:28:53.376278 | controller |
2026-02-27 18:28:53.376458 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2026-02-27 18:28:53.376624 | controller | Friday 27 February 2026  18:28:53 +0000 (0:00:00.016)       0:00:16.333 *******
2026-02-27 18:28:53.396420 | controller | skipping: [instance]
2026-02-27 18:28:53.396594 | controller |
2026-02-27 18:28:53.396756 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2026-02-27 18:28:53.396892 | controller | Friday 27 February 2026  18:28:53 +0000 (0:00:00.020)       0:00:16.353 *******
2026-02-27 18:28:53.427211 | controller | skipping: [instance]
2026-02-27 18:28:53.427410 | controller |
2026-02-27 18:28:53.427578 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2026-02-27 18:28:53.427735 | controller | Friday 27 February 2026  18:28:53 +0000 (0:00:00.030)       0:00:16.384 *******
2026-02-27 18:28:53.456460 | controller | skipping: [instance]
2026-02-27 18:28:53.456605 | controller |
2026-02-27 18:28:53.456741 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2026-02-27 18:28:53.456869 | controller | Friday 27 February 2026  18:28:53 +0000 (0:00:00.029)       0:00:16.413 *******
2026-02-27 18:28:53.485781 | controller | skipping: [instance]
2026-02-27 18:28:53.485935 | controller |
2026-02-27 18:28:53.486096 | controller | TASK [test_deps : Install additional packages] *********************************
2026-02-27 18:28:53.486232 | controller | Friday 27 February 2026  18:28:53 +0000 (0:00:00.029)       0:00:16.442 *******
2026-02-27 18:28:53.514248 | controller | skipping: [instance]
2026-02-27 18:28:53.514413 | controller |
2026-02-27 18:28:53.514549 | controller | TASK [test_deps : Install selinux python libs] *********************************
2026-02-27 18:28:53.514680 | controller | Friday 27 February 2026  18:28:53 +0000 (0:00:00.028)       0:00:16.471 *******
2026-02-27 18:29:36.611617 | controller | ok: [instance]
2026-02-27 18:29:37.850978 | controller |
2026-02-27 18:29:37.851010 | controller | TASK [test_deps : Install python yaml libs] ************************************
2026-02-27 18:29:37.851034 | controller | Friday 27 February 2026  18:29:36 +0000 (0:00:43.096)       0:00:59.567 *******
2026-02-27 18:29:37.851048 | controller | ok: [instance]
2026-02-27 18:29:37.881916 | controller |
2026-02-27 18:29:37.881934 | controller | TASK [test_deps : Install extra packages] **************************************
2026-02-27 18:29:37.881942 | controller | Friday 27 February 2026  18:29:37 +0000 (0:00:01.239)       0:01:00.807 *******
2026-02-27 18:29:37.881951 | controller | skipping: [instance]
2026-02-27 18:29:37.903991 | controller |
2026-02-27 18:29:37.904009 | controller | TASK [Start CRC] ***************************************************************
2026-02-27 18:29:37.904033 | controller | Friday 27 February 2026  18:29:37 +0000 (0:00:00.031)       0:01:00.838 *******
2026-02-27 18:29:37.904046 | controller |
2026-02-27 18:36:56.033249 | controller | TASK [cifmw_helpers : Ensure CRC is started] ***********************************
2026-02-27 18:36:56.033467 | controller | Friday 27 February 2026  18:29:37 +0000 (0:00:00.022)       0:01:00.860 *******
2026-02-27 18:36:56.033483 | controller | changed: [instance]
2026-02-27 18:36:56.068162 | controller |
2026-02-27 18:36:56.068203 | controller | TASK [cifmw_helpers : Login to the OpenShift when certificate is expired] ******
2026-02-27 18:36:56.068221 | controller | Friday 27 February 2026  18:36:56 +0000 (0:07:18.128)       0:08:18.989 *******
2026-02-27 18:36:56.068243 | controller | skipping: [instance]
2026-02-27 18:36:56.068264 | controller |
2026-02-27 18:36:56.068319 | controller | PLAY RECAP *********************************************************************
2026-02-27 18:36:56.069677 | controller | instance                   : ok=20   changed=10   unreachable=0    failed=0    skipped=31   rescued=0    ignored=0
2026-02-27 18:36:56.070109 | controller |
2026-02-27 18:36:56.070122 | controller | Friday 27 February 2026  18:36:56 +0000 (0:00:00.035)       0:08:19.024 *******
2026-02-27 18:36:56.070128 | controller | ===============================================================================
2026-02-27 18:36:56.070134 | controller | cifmw_helpers : Ensure CRC is started --------------------------------- 438.13s
2026-02-27 18:36:56.070139 | controller | test_deps : Install selinux python libs -------------------------------- 43.10s
2026-02-27 18:36:56.070145 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.72s
2026-02-27 18:36:56.070164 | controller | test_deps : Install python yaml libs ------------------------------------ 1.24s
2026-02-27 18:36:56.070169 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.05s
2026-02-27 18:36:56.070175 | controller | Gathering Facts --------------------------------------------------------- 0.92s
2026-02-27 18:36:56.070180 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.88s
2026-02-27 18:36:56.070186 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.85s
2026-02-27 18:36:56.070191 | controller | repo_setup : Install repo-setup package --------------------------------- 0.73s
2026-02-27 18:36:56.070196 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.71s
2026-02-27 18:36:56.070202 | controller | repo_setup : Ensure directories are present ----------------------------- 0.55s
2026-02-27 18:36:56.070210 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.53s
2026-02-27 18:36:56.070249 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.42s
2026-02-27 18:36:56.070257 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.32s
2026-02-27 18:36:56.070263 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.29s
2026-02-27 18:36:56.070270 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.22s
2026-02-27 18:36:56.070491 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.20s
2026-02-27 18:36:56.070513 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.18s
2026-02-27 18:36:56.129892 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2026-02-27 18:36:56.129928 | controller | repo_setup : Rename component repo -------------------------------------- 0.05s
2026-02-27 18:36:56.129943 | controller | INFO     Running default > converge
2026-02-27 18:36:56.641167 | controller |
2026-02-27 18:36:56.641316 | controller | PLAY [Converge] ****************************************************************
2026-02-27 18:36:56.641423 | controller |
2026-02-27 18:36:56.641536 | controller | TASK [Gathering Facts] *********************************************************
2026-02-27 18:36:56.641654 | controller | Friday 27 February 2026  18:36:56 +0000 (0:00:00.020)       0:00:00.020 *******
2026-02-27 18:36:57.499559 | controller | ok: [instance]
2026-02-27 18:36:57.780921 | controller |
2026-02-27 18:36:57.780967 | controller | TASK [openshift_setup : Ensure output directory exists] ************************
2026-02-27 18:36:57.780976 | controller | Friday 27 February 2026  18:36:57 +0000 (0:00:00.858)       0:00:00.879 *******
2026-02-27 18:36:57.780989 | controller | ok: [instance]
2026-02-27 18:36:57.822252 | controller |
2026-02-27 18:36:57.822284 | controller | TASK [openshift_setup : Fetch namespaces to create] ****************************
2026-02-27 18:36:57.822293 | controller | Friday 27 February 2026  18:36:57 +0000 (0:00:00.281)       0:00:01.160 *******
2026-02-27 18:36:57.822316 | controller | ok: [instance]
2026-02-27 18:36:59.384485 | controller |
2026-02-27 18:36:59.384523 | controller | TASK [openshift_setup : Create required namespaces] ****************************
2026-02-27 18:36:59.384531 | controller | Friday 27 February 2026  18:36:57 +0000 (0:00:00.041)       0:00:01.202 *******
2026-02-27 18:36:59.384547 | controller | changed: [instance] => (item=openstack)
2026-02-27 18:37:00.273531 | controller | changed: [instance] => (item=openstack-operators)
2026-02-27 18:37:00.273571 | controller |
2026-02-27 18:37:00.273580 | controller | TASK [openshift_setup : Get internal OpenShift registry route] *****************
2026-02-27 18:37:00.273586 | controller | Friday 27 February 2026  18:36:59 +0000 (0:00:01.561)       0:00:02.764 *******
2026-02-27 18:37:00.273597 | controller | ok: [instance]
2026-02-27 18:37:00.273616 | controller |
2026-02-27 18:37:00.273693 | controller | TASK [openshift_setup : Allow anonymous image-pulls in CRC registry for targeted namespaces] ***
2026-02-27 18:37:00.273790 | controller | Friday 27 February 2026  18:37:00 +0000 (0:00:00.889)       0:00:03.653 *******
2026-02-27 18:37:01.465518 | controller | changed: [instance] => (item=openstack)
2026-02-27 18:37:01.465573 | controller | changed: [instance] => (item=openstack-operators)
2026-02-27 18:37:01.465675 | controller |
2026-02-27 18:37:01.465783 | controller | TASK [openshift_setup : Wait for the image registry to be ready] ***************
2026-02-27 18:37:01.465888 | controller | Friday 27 February 2026  18:37:01 +0000 (0:00:01.191)       0:00:04.845 *******
2026-02-27 18:37:01.491528 | controller | skipping: [instance]
2026-02-27 18:37:01.491576 | controller |
2026-02-27 18:37:01.491696 | controller | TASK [openshift_setup : Login into OpenShift internal registry] ****************
2026-02-27 18:37:01.491780 | controller | Friday 27 February 2026  18:37:01 +0000 (0:00:00.026)       0:00:04.872 *******
2026-02-27 18:37:01.513382 | controller | skipping: [instance]
2026-02-27 18:37:01.513433 | controller |
2026-02-27 18:37:01.513538 | controller | TASK [Ensure we have custom CA installed on host] ******************************
2026-02-27 18:37:01.513629 | controller | Friday 27 February 2026  18:37:01 +0000 (0:00:00.021)       0:00:04.893 *******
2026-02-27 18:37:01.548957 | controller |
2026-02-27 18:37:01.549084 | controller | TASK [install_ca : Ensure target directory exists] *****************************
2026-02-27 18:37:01.549229 | controller | Friday 27 February 2026  18:37:01 +0000 (0:00:00.035)       0:00:04.929 *******
2026-02-27 18:37:01.761428 | controller | ok: [instance]
2026-02-27 18:37:01.761514 | controller |
2026-02-27 18:37:01.761643 | controller | TASK [install_ca : Install internal CA from url] *******************************
2026-02-27 18:37:01.761688 | controller | Friday 27 February 2026  18:37:01 +0000 (0:00:00.211)       0:00:05.141 *******
2026-02-27 18:37:01.788338 | controller | skipping: [instance]
2026-02-27 18:37:01.788414 | controller |
2026-02-27 18:37:01.788511 | controller | TASK [install_ca : Install custom CA bundle from inline] ***********************
2026-02-27 18:37:01.788607 | controller | Friday 27 February 2026  18:37:01 +0000 (0:00:00.027)       0:00:05.168 *******
2026-02-27 18:37:01.814779 | controller | skipping: [instance]
2026-02-27 18:37:01.814871 | controller |
2026-02-27 18:37:01.814989 | controller | TASK [install_ca : Install custom CA bundle from file] *************************
2026-02-27 18:37:01.815130 | controller | Friday 27 February 2026  18:37:01 +0000 (0:00:00.026)       0:00:05.195 *******
2026-02-27 18:37:01.840361 | controller | skipping: [instance]
2026-02-27 18:37:01.840445 | controller |
2026-02-27 18:37:01.840548 | controller | TASK [install_ca : Update ca bundle] *******************************************
2026-02-27 18:37:01.840661 | controller | Friday 27 February 2026  18:37:01 +0000 (0:00:00.025)       0:00:05.220 *******
2026-02-27 18:37:01.872962 | controller | skipping: [instance]
2026-02-27 18:37:01.873102 | controller |
2026-02-27 18:37:01.873194 | controller | TASK [openshift_setup : Update ca bundle] **************************************
2026-02-27 18:37:01.873302 | controller | Friday 27 February 2026  18:37:01 +0000 (0:00:00.032)       0:00:05.253 *******
2026-02-27 18:37:03.086204 | controller | changed: [instance]
2026-02-27 18:37:03.086436 | controller |
2026-02-27 18:37:03.086469 | controller | TASK [openshift_setup : Slurp CAs file] ****************************************
2026-02-27 18:37:03.086480 | controller | Friday 27 February 2026  18:37:03 +0000 (0:00:01.212)       0:00:06.466 *******
2026-02-27 18:37:03.369553 | controller | ok: [instance]
2026-02-27 18:37:03.369639 | controller |
2026-02-27 18:37:03.369652 | controller | TASK [openshift_setup : Create config map with registry CAs] *******************
2026-02-27 18:37:03.369673 | controller | Friday 27 February 2026  18:37:03 +0000 (0:00:00.283)       0:00:06.749 *******
2026-02-27 18:37:04.042735 | controller | changed: [instance]
2026-02-27 18:37:04.042793 | controller |
2026-02-27 18:37:04.042897 | controller | TASK [openshift_setup : Install Red Hat CA for pulling images from internal registry] ***
2026-02-27 18:37:04.042995 | controller | Friday 27 February 2026  18:37:04 +0000 (0:00:00.673)       0:00:07.423 *******
2026-02-27 18:37:04.663707 | controller | changed: [instance]
2026-02-27 18:37:04.663764 | controller |
2026-02-27 18:37:04.663871 | controller | TASK [openshift_setup : Add insecure registry] *********************************
2026-02-27 18:37:04.663966 | controller | Friday 27 February 2026  18:37:04 +0000 (0:00:00.620)       0:00:08.043 *******
2026-02-27 18:37:04.686179 | controller | skipping: [instance]
2026-02-27 18:37:04.686257 | controller |
2026-02-27 18:37:04.686359 | controller | TASK [openshift_setup : Create a ICSP with repository digest mirrors] **********
2026-02-27 18:37:04.686459 | controller | Friday 27 February 2026  18:37:04 +0000 (0:00:00.022)       0:00:08.066 *******
2026-02-27 18:37:05.366872 | controller | changed: [instance]
2026-02-27 18:37:05.366937 | controller |
2026-02-27 18:37:05.367057 | controller | TASK [openshift_setup : Gather network.operator info] **************************
2026-02-27 18:37:05.367161 | controller | Friday 27 February 2026  18:37:05 +0000 (0:00:00.678)       0:00:08.744 *******
2026-02-27 18:37:05.988880 | controller | ok: [instance]
2026-02-27 18:37:06.746050 | controller |
2026-02-27 18:37:06.746101 | controller | TASK [openshift_setup : Patch network operator] ********************************
2026-02-27 18:37:06.746110 | controller | Friday 27 February 2026  18:37:05 +0000 (0:00:00.621)       0:00:09.365 *******
2026-02-27 18:37:06.746124 | controller | changed: [instance]
2026-02-27 18:37:07.377638 | controller |
2026-02-27 18:37:07.377668 | controller | TASK [openshift_setup : Patch samples registry configuration] ******************
2026-02-27 18:37:07.377676 | controller | Friday 27 February 2026  18:37:06 +0000 (0:00:00.756)       0:00:10.122 *******
2026-02-27 18:37:07.377686 | controller | changed: [instance]
2026-02-27 18:37:07.395550 | controller |
2026-02-27 18:37:07.395581 | controller | TASK [openshift_setup : Delete the pods from openshift-marketplace namespace] ***
2026-02-27 18:37:07.395589 | controller | Friday 27 February 2026  18:37:07 +0000 (0:00:00.632)       0:00:10.754 *******
2026-02-27 18:37:07.395599 | controller | skipping: [instance]
2026-02-27 18:37:07.417929 | controller |
2026-02-27 18:37:07.417969 | controller | TASK [openshift_setup : Wait for openshift-marketplace pods to be running] *****
2026-02-27 18:37:07.417979 | controller | Friday 27 February 2026  18:37:07 +0000 (0:00:00.020)       0:00:10.775 *******
2026-02-27 18:37:07.417991 | controller | skipping: [instance]
2026-02-27 18:37:08.029363 | controller |
2026-02-27 18:37:08.029394 | controller | TASK [Check that config map is created] ****************************************
2026-02-27 18:37:08.029402 | controller | Friday 27 February 2026  18:37:07 +0000 (0:00:00.022)       0:00:10.797 *******
2026-02-27 18:37:08.029412 | controller | ok: [instance]
2026-02-27 18:37:08.029647 | controller |
2026-02-27 18:37:08.050169 | controller | TASK [Assert that configmap was created] ***************************************
2026-02-27 18:37:08.050222 | controller | Friday 27 February 2026  18:37:08 +0000 (0:00:00.611)       0:00:11.409 *******
2026-02-27 18:37:08.050236 | controller | ok: [instance] => changed=false
2026-02-27 18:37:08.050316 | controller |   msg: All assertions passed
2026-02-27 18:37:08.050334 | controller |
2026-02-27 18:37:08.050344 | controller | TASK [Check that Image cluster is patched] *************************************
2026-02-27 18:37:08.642104 | controller | Friday 27 February 2026  18:37:08 +0000 (0:00:00.020)       0:00:11.430 *******
2026-02-27 18:37:08.642139 | controller | ok: [instance]
2026-02-27 18:37:08.662633 | controller |
2026-02-27 18:37:08.662657 | controller | TASK [Assert that cluster was patched] *****************************************
2026-02-27 18:37:08.662667 | controller | Friday 27 February 2026  18:37:08 +0000 (0:00:00.591)       0:00:12.022 *******
2026-02-27 18:37:08.662678 | controller | ok: [instance] => changed=false
2026-02-27 18:37:09.265433 | controller |   msg: All assertions passed
2026-02-27 18:37:09.265461 | controller |
2026-02-27 18:37:09.265470 | controller | TASK [Check that registry mirror resource is created] **************************
2026-02-27 18:37:09.265476 | controller | Friday 27 February 2026  18:37:08 +0000 (0:00:00.020)       0:00:12.042 *******
2026-02-27 18:37:09.265491 | controller | ok: [instance]
2026-02-27 18:37:09.304102 | controller |
2026-02-27 18:37:09.304125 | controller | TASK [Assert that digest mirrors are correct] **********************************
2026-02-27 18:37:09.304133 | controller | Friday 27 February 2026  18:37:09 +0000 (0:00:00.602)       0:00:12.645 *******
2026-02-27 18:37:09.304143 | controller | ok: [instance] => changed=false
2026-02-27 18:37:09.305600 | controller |   msg: All assertions passed
2026-02-27 18:37:09.305616 | controller |
2026-02-27 18:37:09.305623 | controller | PLAY RECAP *********************************************************************
2026-02-27 18:37:09.305629 | controller | instance                   : ok=21   changed=8    unreachable=0    failed=0    skipped=9    rescued=0    ignored=0
2026-02-27 18:37:09.305635 | controller |
2026-02-27 18:37:09.305640 | controller | Friday 27 February 2026  18:37:09 +0000 (0:00:00.037)       0:00:12.683 *******
2026-02-27 18:37:09.305646 | controller | ===============================================================================
2026-02-27 18:37:09.305651 | controller | openshift_setup : Create required namespaces ---------------------------- 1.56s
2026-02-27 18:37:09.305656 | controller | openshift_setup : Update ca bundle -------------------------------------- 1.21s
2026-02-27 18:37:09.305662 | controller | openshift_setup : Allow anonymous image-pulls in CRC registry for targeted namespaces --- 1.19s
2026-02-27 18:37:09.305668 | controller | openshift_setup : Get internal OpenShift registry route ----------------- 0.89s
2026-02-27 18:37:09.305676 | controller | Gathering Facts --------------------------------------------------------- 0.86s
2026-02-27 18:37:09.361681 | controller | openshift_setup : Patch network operator -------------------------------- 0.76s
2026-02-27 18:37:09.361711 | controller | openshift_setup : Create a ICSP with repository digest mirrors ---------- 0.68s
2026-02-27 18:37:09.361726 | controller | openshift_setup : Create config map with registry CAs ------------------- 0.67s
2026-02-27 18:37:09.361732 | controller | openshift_setup : Patch samples registry configuration ------------------ 0.63s
2026-02-27 18:37:09.361738 | controller | openshift_setup : Gather network.operator info -------------------------- 0.62s
2026-02-27 18:37:09.361743 | controller | openshift_setup : Install Red Hat CA for pulling images from internal registry --- 0.62s
2026-02-27 18:37:09.361749 | controller | Check that config map is created ---------------------------------------- 0.61s
2026-02-27 18:37:09.361754 | controller | Check that registry mirror resource is created -------------------------- 0.60s
2026-02-27 18:37:09.361760 | controller | Check that Image cluster is patched ------------------------------------- 0.59s
2026-02-27 18:37:09.361765 | controller | openshift_setup : Slurp CAs file ---------------------------------------- 0.28s
2026-02-27 18:37:09.361770 | controller | openshift_setup : Ensure output directory exists ------------------------ 0.28s
2026-02-27 18:37:09.361776 | controller | install_ca : Ensure target directory exists ----------------------------- 0.21s
2026-02-27 18:37:09.361781 | controller | openshift_setup : Fetch namespaces to create ---------------------------- 0.04s
2026-02-27 18:37:09.361786 | controller | Assert that digest mirrors are correct ---------------------------------- 0.04s
2026-02-27 18:37:09.361792 | controller | Ensure we have custom CA installed on host ------------------------------ 0.04s
2026-02-27 18:37:09.361801 | controller | INFO     Running default > cleanup
2026-02-27 18:37:09.362307 | controller | WARNING  Skipping, cleanup playbook not configured.
2026-02-27 18:37:09.362892 | controller | INFO     Writing /tmp/report.html report.
2026-02-27 18:37:09.454988 | controller | changed
2026-02-27 18:37:09.474316 | 
2026-02-27 18:37:09.474370 | PLAY RECAP
2026-02-27 18:37:09.474410 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-27 18:37:09.474448 | 
2026-02-27 18:37:09.536047 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-02-27 18:37:09.536835 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-02-27 18:37:10.050665 | 
2026-02-27 18:37:10.050830 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-02-27 18:37:10.072523 | 
2026-02-27 18:37:10.072599 | TASK [Filter out host if needed]
2026-02-27 18:37:10.081093 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-02-27 18:37:10.085492 | 
2026-02-27 18:37:10.085559 | TASK [Ensure file is present]
2026-02-27 18:37:10.469589 | controller | ok
2026-02-27 18:37:10.475078 | 
2026-02-27 18:37:10.475145 | TASK [Manage molecule report file]
2026-02-27 18:37:11.000165 | controller | changed
2026-02-27 18:37:11.005288 | 
2026-02-27 18:37:11.005372 | TASK [Check if we get ci-framework-data basedir]
2026-02-27 18:37:11.246278 | controller | ok
2026-02-27 18:37:11.251356 | 
2026-02-27 18:37:11.251435 | TASK [Create ci-framework-data log directory for zuul]
2026-02-27 18:37:11.614216 | controller | changed
2026-02-27 18:37:11.620653 | 
2026-02-27 18:37:11.620724 | TASK [Copy ci-framework interesting files]
2026-02-27 18:37:11.871917 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-02-27 18:37:12.146359 | controller | changed
2026-02-27 18:37:12.151505 | 
2026-02-27 18:37:12.151574 | TASK [Get SELinux listing]
2026-02-27 18:37:12.676877 | controller | changed
2026-02-27 18:37:12.682172 | 
2026-02-27 18:37:12.682237 | TASK [Generate log index]
2026-02-27 18:37:13.973778 | controller | changed
2026-02-27 18:37:13.978623 | 
2026-02-27 18:37:13.978690 | TASK [Get some env related data]
2026-02-27 18:37:14.719459 | controller | /home/zuul/.local/bin/ansible
2026-02-27 18:37:15.505206 | controller | changed
2026-02-27 18:37:15.510349 | 
2026-02-27 18:37:15.510415 | TASK [Generate list of logs to collect in home directory]
2026-02-27 18:37:15.934415 | controller | ok: All paths examined
2026-02-27 18:37:15.939566 | 
2026-02-27 18:37:15.939626 | LOOP [Copy logs from home directory]
2026-02-27 18:37:16.685652 | controller | changed:
2026-02-27 18:37:16.685856 | controller | {
2026-02-27 18:37:16.685885 | controller |   "atime": 1772005405.932821,
2026-02-27 18:37:16.685906 | controller |   "ctime": 1772006396.9238591,
2026-02-27 18:37:16.685923 | controller |   "dev": 64513,
2026-02-27 18:37:16.685940 | controller |   "gid": 1000,
2026-02-27 18:37:16.685955 | controller |   "gr_name": "zuul",
2026-02-27 18:37:16.685971 | controller |   "inode": 2773,
2026-02-27 18:37:16.685986 | controller |   "isblk": false,
2026-02-27 18:37:16.686000 | controller |   "ischr": false,
2026-02-27 18:37:16.686015 | controller |   "isdir": false,
2026-02-27 18:37:16.686029 | controller |   "isfifo": false,
2026-02-27 18:37:16.686043 | controller |   "isgid": false,
2026-02-27 18:37:16.686057 | controller |   "islnk": false,
2026-02-27 18:37:16.686070 | controller |   "isreg": true,
2026-02-27 18:37:16.686084 | controller |   "issock": false,
2026-02-27 18:37:16.686098 | controller |   "isuid": false,
2026-02-27 18:37:16.686111 | controller |   "mode": "0644",
2026-02-27 18:37:16.686125 | controller |   "mtime": 1772006396.9238591,
2026-02-27 18:37:16.686140 | controller |   "nlink": 1,
2026-02-27 18:37:16.686154 | controller |   "path": "/home/zuul/crc-setup.log",
2026-02-27 18:37:16.686169 | controller |   "pw_name": "zuul",
2026-02-27 18:37:16.686183 | controller |   "rgrp": true,
2026-02-27 18:37:16.686197 | controller |   "roth": true,
2026-02-27 18:37:16.686211 | controller |   "rusr": true,
2026-02-27 18:37:16.686224 | controller |   "size": 4108,
2026-02-27 18:37:16.686238 | controller |   "uid": 1000,
2026-02-27 18:37:16.686251 | controller |   "wgrp": false,
2026-02-27 18:37:16.686265 | controller |   "woth": false,
2026-02-27 18:37:16.686281 | controller |   "wusr": true,
2026-02-27 18:37:16.686313 | controller |   "xgrp": false,
2026-02-27 18:37:16.686336 | controller |   "xoth": false,
2026-02-27 18:37:16.686351 | controller |   "xusr": false
2026-02-27 18:37:16.686366 | controller | }
2026-02-27 18:37:17.385091 | controller | changed:
2026-02-27 18:37:17.385176 | controller | {
2026-02-27 18:37:17.385202 | controller |   "atime": 1772006398.6759083,
2026-02-27 18:37:17.385229 | controller |   "ctime": 1772007098.5537844,
2026-02-27 18:37:17.385253 | controller |   "dev": 64513,
2026-02-27 18:37:17.385275 | controller |   "gid": 1000,
2026-02-27 18:37:17.385309 | controller |   "gr_name": "zuul",
2026-02-27 18:37:17.385401 | controller |   "inode": 359205,
2026-02-27 18:37:17.385446 | controller |   "isblk": false,
2026-02-27 18:37:17.385467 | controller |   "ischr": false,
2026-02-27 18:37:17.385484 | controller |   "isdir": false,
2026-02-27 18:37:17.385499 | controller |   "isfifo": false,
2026-02-27 18:37:17.385514 | controller |   "isgid": false,
2026-02-27 18:37:17.385529 | controller |   "islnk": false,
2026-02-27 18:37:17.385543 | controller |   "isreg": true,
2026-02-27 18:37:17.385558 | controller |   "issock": false,
2026-02-27 18:37:17.385572 | controller |   "isuid": false,
2026-02-27 18:37:17.385585 | controller |   "mode": "0644",
2026-02-27 18:37:17.385599 | controller |   "mtime": 1772007098.5537844,
2026-02-27 18:37:17.385619 | controller |   "nlink": 1,
2026-02-27 18:37:17.385634 | controller |   "path": "/home/zuul/crc-start.log",
2026-02-27 18:37:17.385648 | controller |   "pw_name": "zuul",
2026-02-27 18:37:17.385662 | controller |   "rgrp": true,
2026-02-27 18:37:17.385676 | controller |   "roth": true,
2026-02-27 18:37:17.385690 | controller |   "rusr": true,
2026-02-27 18:37:17.385704 | controller |   "size": 4654,
2026-02-27 18:37:17.385717 | controller |   "uid": 1000,
2026-02-27 18:37:17.385731 | controller |   "wgrp": false,
2026-02-27 18:37:17.385744 | controller |   "woth": false,
2026-02-27 18:37:17.385757 | controller |   "wusr": true,
2026-02-27 18:37:17.385770 | controller |   "xgrp": false,
2026-02-27 18:37:17.385783 | controller |   "xoth": false,
2026-02-27 18:37:17.385796 | controller |   "xusr": false
2026-02-27 18:37:17.385810 | controller | }
2026-02-27 18:37:18.086594 | controller | changed:
2026-02-27 18:37:18.086685 | controller | {
2026-02-27 18:37:18.086710 | controller |   "atime": 1772216880.0439208,
2026-02-27 18:37:18.086730 | controller |   "ctime": 1772216896.7501242,
2026-02-27 18:37:18.086746 | controller |   "dev": 64513,
2026-02-27 18:37:18.086762 | controller |   "gid": 1000,
2026-02-27 18:37:18.086776 | controller |   "gr_name": "zuul",
2026-02-27 18:37:18.086790 | controller |   "inode": 1189019,
2026-02-27 18:37:18.086804 | controller |   "isblk": false,
2026-02-27 18:37:18.086818 | controller |   "ischr": false,
2026-02-27 18:37:18.086831 | controller |   "isdir": false,
2026-02-27 18:37:18.086845 | controller |   "isfifo": false,
2026-02-27 18:37:18.086858 | controller |   "isgid": false,
2026-02-27 18:37:18.086871 | controller |   "islnk": false,
2026-02-27 18:37:18.086884 | controller |   "isreg": true,
2026-02-27 18:37:18.086898 | controller |   "issock": false,
2026-02-27 18:37:18.086911 | controller |   "isuid": false,
2026-02-27 18:37:18.086924 | controller |   "mode": "0644",
2026-02-27 18:37:18.086938 | controller |   "mtime": 1772216896.7501242,
2026-02-27 18:37:18.086952 | controller |   "nlink": 1,
2026-02-27 18:37:18.086965 | controller |   "path": "/home/zuul/ansible.log",
2026-02-27 18:37:18.086980 | controller |   "pw_name": "zuul",
2026-02-27 18:37:18.086994 | controller |   "rgrp": true,
2026-02-27 18:37:18.087008 | controller |   "roth": true,
2026-02-27 18:37:18.087025 | controller |   "rusr": true,
2026-02-27 18:37:18.087040 | controller |   "size": 6749,
2026-02-27 18:37:18.087055 | controller |   "uid": 1000,
2026-02-27 18:37:18.087069 | controller |   "wgrp": false,
2026-02-27 18:37:18.087082 | controller |   "woth": false,
2026-02-27 18:37:18.087096 | controller |   "wusr": true,
2026-02-27 18:37:18.087109 | controller |   "xgrp": false,
2026-02-27 18:37:18.087122 | controller |   "xoth": false,
2026-02-27 18:37:18.087135 | controller |   "xusr": false
2026-02-27 18:37:18.087149 | controller | }
2026-02-27 18:37:18.102272 | 
2026-02-27 18:37:18.102399 | TASK [Copy crio stats log file]
2026-02-27 18:37:18.116114 | controller | skipping: Conditional result was False
2026-02-27 18:37:18.121635 | 
2026-02-27 18:37:18.121701 | TASK [Get SELinux related data]
2026-02-27 18:37:18.456091 | controller | <no matches>
2026-02-27 18:37:18.655737 | controller | ERROR
2026-02-27 18:37:18.655936 | controller | {
2026-02-27 18:37:18.655970 | controller |   "delta": "0:00:00.021696",
2026-02-27 18:37:18.655993 | controller |   "end": "2026-02-27 18:37:18.463264",
2026-02-27 18:37:18.656011 | controller |   "msg": "non-zero return code",
2026-02-27 18:37:18.656029 | controller |   "rc": 1,
2026-02-27 18:37:18.656045 | controller |   "start": "2026-02-27 18:37:18.441568"
2026-02-27 18:37:18.656061 | controller | }
2026-02-27 18:37:18.656084 | controller | ERROR: Ignoring Errors
2026-02-27 18:37:18.661023 | 
2026-02-27 18:37:18.661086 | TASK [Create system configuration directory]
2026-02-27 18:37:18.972383 | controller | changed
2026-02-27 18:37:18.977261 | 
2026-02-27 18:37:18.977340 | TASK [Get some of the system configurations]
2026-02-27 18:37:19.502609 | controller | changed
2026-02-27 18:37:19.507791 | 
2026-02-27 18:37:19.507855 | TASK [Copy generated documentation if available]
2026-02-27 18:37:19.520914 | controller | skipping: Conditional result was False
2026-02-27 18:37:19.526274 | 
2026-02-27 18:37:19.526357 | TASK [Copy generated AsciiDoc documentation if available]
2026-02-27 18:37:19.538906 | controller | skipping: Conditional result was False
2026-02-27 18:37:19.543945 | 
2026-02-27 18:37:19.544007 | TASK [Compress logs bigger than 2MB]
2026-02-27 18:37:20.069646 | controller | changed
2026-02-27 18:37:20.074789 | 
2026-02-27 18:37:20.074852 | TASK [Copy files from workspace on node]
2026-02-27 18:37:20.092455 | controller | ok
2026-02-27 18:37:20.114365 | 
2026-02-27 18:37:20.114451 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-27 18:37:20.127130 | controller | skipping: Conditional result was False
2026-02-27 18:37:20.132930 | 
2026-02-27 18:37:20.132998 | TASK [fetch-output : Set log path for single node]
2026-02-27 18:37:20.161066 | controller | ok
2026-02-27 18:37:20.166133 | 
2026-02-27 18:37:20.166199 | LOOP [fetch-output : Ensure local output dirs]
2026-02-27 18:37:20.355077 | controller -> localhost | ok: "/var/lib/zuul/builds/b0364962af8f4746b36a70c74f8344a6/work/logs"
2026-02-27 18:37:20.355311 | controller -> localhost | changed: All items complete
2026-02-27 18:37:20.355349 | 
2026-02-27 18:37:20.528917 | controller -> localhost | changed: "/var/lib/zuul/builds/b0364962af8f4746b36a70c74f8344a6/work/artifacts"
2026-02-27 18:37:20.704679 | controller -> localhost | changed: "/var/lib/zuul/builds/b0364962af8f4746b36a70c74f8344a6/work/docs"
2026-02-27 18:37:20.714396 | 
2026-02-27 18:37:20.714489 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-27 18:37:21.369201 | controller | changed:
2026-02-27 18:37:21.369418 | controller | .d..t...... ./
2026-02-27 18:37:21.369463 | controller | >f+++++++++ README.html
2026-02-27 18:37:21.369487 | controller | >f+++++++++ ansible-execution.log
2026-02-27 18:37:21.369510 | controller | >f+++++++++ ansible.log
2026-02-27 18:37:21.369529 | controller | >f+++++++++ crc-setup.log
2026-02-27 18:37:21.369548 | controller | >f+++++++++ crc-start.log
2026-02-27 18:37:21.369567 | controller | >f+++++++++ dmesg.log
2026-02-27 18:37:21.369588 | controller | >f+++++++++ installed-pkgs.log
2026-02-27 18:37:21.369607 | controller | >f+++++++++ python.log
2026-02-27 18:37:21.369625 | controller | >f+++++++++ registries.conf
2026-02-27 18:37:21.369643 | controller | >f+++++++++ report.html
2026-02-27 18:37:21.369661 | controller | >f+++++++++ selinux-denials.log
2026-02-27 18:37:21.369678 | controller | >f+++++++++ selinux-listing.log
2026-02-27 18:37:21.369695 | controller | cd+++++++++ ci-framework-data/
2026-02-27 18:37:21.369711 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-02-27 18:37:21.369728 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-02-27 18:37:21.369745 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-02-27 18:37:21.369762 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-02-27 18:37:21.369779 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2026-02-27 18:37:21.369796 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2026-02-27 18:37:21.369812 | controller | cd+++++++++ ci-framework-data/logs/
2026-02-27 18:37:21.369830 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-02-27 18:37:21.369847 | controller | cd+++++++++ registries.conf.d/
2026-02-27 18:37:21.369864 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-02-27 18:37:21.369881 | controller | cd+++++++++ system-config/
2026-02-27 18:37:21.369898 | controller | cd+++++++++ system-config/libvirt/
2026-02-27 18:37:21.369915 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-02-27 18:37:21.369932 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-02-27 18:37:21.369957 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2026-02-27 18:37:21.369976 | controller | >f+++++++++ system-config/libvirt/network.conf
2026-02-27 18:37:21.369993 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2026-02-27 18:37:21.370010 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2026-02-27 18:37:21.370027 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2026-02-27 18:37:21.370044 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2026-02-27 18:37:21.370060 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2026-02-27 18:37:21.370077 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2026-02-27 18:37:21.370093 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2026-02-27 18:37:21.370110 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2026-02-27 18:37:21.370126 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2026-02-27 18:37:21.370143 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2026-02-27 18:37:21.370159 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2026-02-27 18:37:21.370175 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2026-02-27 18:37:21.826468 | controller | changed: .d..t...... ./
2026-02-27 18:37:22.264573 | controller | changed: .d..t...... ./
2026-02-27 18:37:22.276133 | 
2026-02-27 18:37:22.276199 | TASK [Return artifact to Zuul]
2026-02-27 18:37:22.304432 | controller | ok
2026-02-27 18:37:22.322654 | 
2026-02-27 18:37:22.322708 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-02-27 18:37:22.322799 | 
2026-02-27 18:37:22.322826 | PLAY RECAP
2026-02-27 18:37:22.322860 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-02-27 18:37:22.322880 | 
2026-02-27 18:37:22.397964 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-02-27 18:37:22.398672 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-02-27 18:37:22.899705 | 
2026-02-27 18:37:22.899803 | PLAY [all]
2026-02-27 18:37:22.917723 | 
2026-02-27 18:37:22.917796 | TASK [include_role : fetch-output]
2026-02-27 18:37:22.945722 | controller | ok
2026-02-27 18:37:22.962004 | 
2026-02-27 18:37:22.962095 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-27 18:37:23.005651 | controller | skipping: Conditional result was False
2026-02-27 18:37:23.011204 | 
2026-02-27 18:37:23.011280 | TASK [fetch-output : Set log path for single node]
2026-02-27 18:37:23.038821 | controller | ok
2026-02-27 18:37:23.044085 | 
2026-02-27 18:37:23.044158 | LOOP [fetch-output : Ensure local output dirs]
2026-02-27 18:37:23.356744 | controller -> localhost | ok: "/var/lib/zuul/builds/b0364962af8f4746b36a70c74f8344a6/work/logs"
2026-02-27 18:37:23.532893 | controller -> localhost | ok: "/var/lib/zuul/builds/b0364962af8f4746b36a70c74f8344a6/work/artifacts"
2026-02-27 18:37:23.707479 | controller -> localhost | ok: "/var/lib/zuul/builds/b0364962af8f4746b36a70c74f8344a6/work/docs"
2026-02-27 18:37:23.722621 | 
2026-02-27 18:37:23.722900 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-27 18:37:24.383148 | controller | ok
2026-02-27 18:37:24.383343 | controller | ok: All items complete
2026-02-27 18:37:24.383377 | 
2026-02-27 18:37:24.930812 | controller | ok
2026-02-27 18:37:25.445458 | controller | ok
2026-02-27 18:37:25.458167 | 
2026-02-27 18:37:25.458268 | TASK [include_role : fetch-output-openshift]
2026-02-27 18:37:25.471157 | controller | skipping: Conditional result was False
2026-02-27 18:37:25.477017 | 
2026-02-27 18:37:25.477085 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-27 18:37:25.815359 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006169
2026-02-27 18:37:26.003358 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006121
2026-02-27 18:37:26.031087 | 
2026-02-27 18:37:26.031178 | PLAY [all]
2026-02-27 18:37:26.045277 | 
2026-02-27 18:37:26.045364 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-27 18:37:26.531076 | controller | changed
2026-02-27 18:37:26.552171 | 
2026-02-27 18:37:26.552230 | PLAY RECAP
2026-02-27 18:37:26.552272 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-02-27 18:37:26.552314 | 
2026-02-27 18:37:26.620403 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-02-27 18:37:26.621117 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-02-27 18:37:27.164873 | 
2026-02-27 18:37:27.164979 | PLAY [localhost]
2026-02-27 18:37:27.182181 | 
2026-02-27 18:37:27.182254 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-27 18:37:27.483212 | localhost | changed
2026-02-27 18:37:27.487626 | 
2026-02-27 18:37:27.487702 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-27 18:37:27.504892 | localhost | ok
2026-02-27 18:37:27.512628 | 
2026-02-27 18:37:27.512695 | TASK [add-fileserver : Create SSH private key tempfile]
2026-02-27 18:37:27.803242 | localhost | changed
2026-02-27 18:37:27.808663 | 
2026-02-27 18:37:27.808735 | TASK [add-fileserver : Create SSH private key from secret]
2026-02-27 18:37:28.342357 | localhost | changed
2026-02-27 18:37:28.347340 | 
2026-02-27 18:37:28.347409 | TASK [add-fileserver : Add fileserver ssh key]
2026-02-27 18:37:28.669794 | localhost | Identity added: /var/lib/zuul/builds/b0364962af8f4746b36a70c74f8344a6/work/tmp/ansible.jprk1ft5 (/var/lib/zuul/builds/b0364962af8f4746b36a70c74f8344a6/work/tmp/ansible.jprk1ft5)
2026-02-27 18:37:28.669982 | localhost | ok: Runtime: 0:00:00.005456
2026-02-27 18:37:28.677375 | 
2026-02-27 18:37:28.677461 | TASK [add-fileserver : Remove SSH private key from disk]
2026-02-27 18:37:28.886359 | localhost | ok: Runtime: 0:00:00.003925
2026-02-27 18:37:28.891028 | 
2026-02-27 18:37:28.891109 | TASK [add-fileserver : Add fileserver to inventory]
2026-02-27 18:37:28.938186 | localhost | changed
2026-02-27 18:37:28.943103 | 
2026-02-27 18:37:28.943171 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-02-27 18:37:29.243287 | localhost | changed
2026-02-27 18:37:29.262680 | 
2026-02-27 18:37:29.262744 | PLAY [localhost]
2026-02-27 18:37:29.273968 | 
2026-02-27 18:37:29.274030 | TASK [Generate bulk log download script]
2026-02-27 18:37:29.291558 | localhost | ok
2026-02-27 18:37:29.302863 | 
2026-02-27 18:37:29.302928 | TASK [local-log-download : Check API endpoint is defined]
2026-02-27 18:37:29.329395 | localhost | ok: All assertions passed
2026-02-27 18:37:29.333846 | 
2026-02-27 18:37:29.333910 | TASK [local-log-download : Create download script]
2026-02-27 18:37:29.670467 | localhost -> localhost | changed
2026-02-27 18:37:29.678691 | 
2026-02-27 18:37:29.678757 | TASK [Register quick-download link]
2026-02-27 18:37:29.695124 | localhost | ok
2026-02-27 18:37:29.727054 | 
2026-02-27 18:37:29.727132 | PLAY [logserver.rdoproject.org]
2026-02-27 18:37:29.736651 | 
2026-02-27 18:37:29.736712 | TASK [Set zuul-log-path fact]
2026-02-27 18:37:29.752115 | logserver.rdoproject.org | ok
2026-02-27 18:37:29.760914 | 
2026-02-27 18:37:29.761002 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-27 18:37:29.776081 | logserver.rdoproject.org | ok
2026-02-27 18:37:29.781279 | 
2026-02-27 18:37:29.781371 | TASK [upload-logs : Create log directories]
2026-02-27 18:37:31.509791 | logserver.rdoproject.org | changed
2026-02-27 18:37:31.513220 | 
2026-02-27 18:37:31.513288 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-27 18:37:31.750731 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.006300
2026-02-27 18:37:31.756466 | 
2026-02-27 18:37:31.756552 | TASK [upload-logs : Upload logs to log server]
2026-02-27 18:37:33.363817 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-02-27 18:37:33.367137 | 
2026-02-27 18:37:33.367216 | LOOP [upload-logs : Compress console log and json output]
2026-02-27 18:37:33.410787 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-27 18:37:33.419462 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-27 18:37:33.427952 | 
2026-02-27 18:37:33.428111 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-27 18:37:33.468289 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-27 18:37:33.468650 | 
2026-02-27 18:37:33.471811 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-27 18:37:33.477424 | 
2026-02-27 18:37:33.477645 | LOOP [upload-logs : Upload console log and json output]