2025-10-03 12:49:22.418581 | Job console starting...
2025-10-03 12:49:22.660647 | Updating repositories
2025-10-03 12:49:23.503727 | Preparing job workspace
2025-10-03 12:49:38.892477 | Running Ansible setup...
2025-10-03 12:49:45.208091 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-03 12:49:46.250971 | 
2025-10-03 12:49:46.251118 | PLAY [localhost]
2025-10-03 12:49:46.277326 | 
2025-10-03 12:49:46.277423 | TASK [Gathering Facts]
2025-10-03 12:49:49.188998 | localhost | ok
2025-10-03 12:49:49.218777 | 
2025-10-03 12:49:49.218933 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-03 12:49:50.486824 | localhost -> localhost | changed
2025-10-03 12:49:50.494164 | 
2025-10-03 12:49:50.494239 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-03 12:49:53.893851 | localhost -> localhost | changed
2025-10-03 12:49:53.902847 | 
2025-10-03 12:49:53.902952 | TASK [Setup log path fact]
2025-10-03 12:49:53.966522 | localhost | ok
2025-10-03 12:49:53.998951 | 
2025-10-03 12:49:53.999034 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-03 12:49:54.123226 | localhost | ok
2025-10-03 12:49:54.142421 | 
2025-10-03 12:49:54.142498 | TASK [emit-job-header : Print job information]
2025-10-03 12:49:54.304553 | # Job Information
2025-10-03 12:49:54.304832 | Ansible Version: 2.15.12
2025-10-03 12:49:54.305043 | Job: cifmw-molecule-ci_setup
2025-10-03 12:49:54.305101 | Pipeline: github-check
2025-10-03 12:49:54.305130 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-03 12:49:54.305153 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3366
2025-10-03 12:49:54.305175 | Log URL (when completed): https://logserver.rdoproject.org/cc9/rdoproject.org/cc9db8a592ba4e0fac5e79abddf8fa1c/
2025-10-03 12:49:54.305197 | Event ID: 31f0a9e0-a057-11f0-8d04-891189cb7229
2025-10-03 12:49:54.317205 | 
2025-10-03 12:49:54.317288 | LOOP [emit-job-header : Print node information]
2025-10-03 12:49:54.707984 | localhost | ok:
2025-10-03 12:49:54.708171 | localhost | # Node Information
2025-10-03 12:49:54.708201 | localhost | Inventory Hostname: controller
2025-10-03 12:49:54.708224 | localhost | Hostname: np0005468984
2025-10-03 12:49:54.708245 | localhost | Username: zuul
2025-10-03 12:49:54.708265 | localhost | Distro: CentOS 9
2025-10-03 12:49:54.708282 | localhost | Provider: ibm-bm4-nodepool
2025-10-03 12:49:54.708300 | localhost | Region: regionOne
2025-10-03 12:49:54.708316 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-03 12:49:54.708332 | localhost | Product Name: OpenStack Compute
2025-10-03 12:49:54.708349 | localhost | Interface IP: 192.168.26.54
2025-10-03 12:49:54.782323 | 
2025-10-03 12:49:54.782422 | PLAY [all]
2025-10-03 12:49:54.796024 | 
2025-10-03 12:49:54.796091 | TASK [Gather network facts]
2025-10-03 12:49:55.274482 | controller | ok
2025-10-03 12:49:55.335625 | 
2025-10-03 12:49:55.335745 | TASK [include_role : start-zuul-console]
2025-10-03 12:49:55.380349 | controller | ok
2025-10-03 12:49:55.406280 | 
2025-10-03 12:49:55.406353 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-03 12:49:56.048599 | controller | ok
2025-10-03 12:49:56.067200 | 
2025-10-03 12:49:56.067269 | TASK [include_role : add-build-sshkey]
2025-10-03 12:49:56.133203 | controller | ok
2025-10-03 12:49:56.186147 | 
2025-10-03 12:49:56.186241 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-03 12:49:57.355809 | controller -> localhost | ok
2025-10-03 12:49:57.361745 | 
2025-10-03 12:49:57.361808 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-03 12:49:57.451497 | controller | ok
2025-10-03 12:49:57.518599 | controller | included: /var/lib/zuul/builds/cc9db8a592ba4e0fac5e79abddf8fa1c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-03 12:49:57.547090 | 
2025-10-03 12:49:57.547185 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-03 12:49:59.964325 | controller -> localhost | Generating public/private rsa key pair.
2025-10-03 12:49:59.964516 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/cc9db8a592ba4e0fac5e79abddf8fa1c/work/cc9db8a592ba4e0fac5e79abddf8fa1c_id_rsa.
2025-10-03 12:49:59.964553 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/cc9db8a592ba4e0fac5e79abddf8fa1c/work/cc9db8a592ba4e0fac5e79abddf8fa1c_id_rsa.pub.
2025-10-03 12:49:59.964579 | controller -> localhost | The key fingerprint is:
2025-10-03 12:49:59.964601 | controller -> localhost | SHA256:qt3C2Fnnz7lfu0clWLMXw3VyWjyxKyss0NjMEQterCM zuul-build-sshkey
2025-10-03 12:49:59.964622 | controller -> localhost | The key's randomart image is:
2025-10-03 12:49:59.964642 | controller -> localhost | +---[RSA 3072]----+
2025-10-03 12:49:59.964662 | controller -> localhost | |       ..o    ooB|
2025-10-03 12:49:59.964681 | controller -> localhost | |      . o.o   oX+|
2025-10-03 12:49:59.964699 | controller -> localhost | |       ..o   o.++|
2025-10-03 12:49:59.964719 | controller -> localhost | |     E o* . . o +|
2025-10-03 12:49:59.964737 | controller -> localhost | |      .oS=   . +.|
2025-10-03 12:49:59.964755 | controller -> localhost | |       .o o   o .|
2025-10-03 12:49:59.964773 | controller -> localhost | |     +.o + o . ..|
2025-10-03 12:49:59.964791 | controller -> localhost | |    .o=.  o... .o|
2025-10-03 12:49:59.964809 | controller -> localhost | |    . ...  .=o.oo|
2025-10-03 12:49:59.964827 | controller -> localhost | +----[SHA256]-----+
2025-10-03 12:49:59.964879 | controller -> localhost | ok: Runtime: 0:00:00.641987
2025-10-03 12:49:59.970658 | 
2025-10-03 12:49:59.970729 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-03 12:50:00.039424 | controller | ok
2025-10-03 12:50:00.082624 | controller | included: /var/lib/zuul/builds/cc9db8a592ba4e0fac5e79abddf8fa1c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-03 12:50:00.112042 | 
2025-10-03 12:50:00.112130 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-03 12:50:00.257272 | controller | skipping: Conditional result was False
2025-10-03 12:50:00.263973 | 
2025-10-03 12:50:00.264129 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-03 12:50:01.502313 | controller | changed
2025-10-03 12:50:01.507469 | 
2025-10-03 12:50:01.507537 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-03 12:50:01.857817 | controller | ok
2025-10-03 12:50:02.090020 | 
2025-10-03 12:50:02.090133 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-03 12:50:02.945298 | controller | changed
2025-10-03 12:50:02.975267 | 
2025-10-03 12:50:02.975345 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-03 12:50:03.893464 | controller | changed
2025-10-03 12:50:03.902596 | 
2025-10-03 12:50:03.902689 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-03 12:50:04.002257 | controller | skipping: Conditional result was False
2025-10-03 12:50:04.008845 | 
2025-10-03 12:50:04.008936 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-03 12:50:05.604971 | controller -> localhost | changed
2025-10-03 12:50:05.625861 | 
2025-10-03 12:50:05.625961 | TASK [add-build-sshkey : Add back temp key]
2025-10-03 12:50:06.879085 | controller -> localhost | Identity added: /var/lib/zuul/builds/cc9db8a592ba4e0fac5e79abddf8fa1c/work/cc9db8a592ba4e0fac5e79abddf8fa1c_id_rsa (zuul-build-sshkey)
2025-10-03 12:50:06.879371 | controller -> localhost | ok: Runtime: 0:00:00.030526
2025-10-03 12:50:06.885423 | 
2025-10-03 12:50:06.885499 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-03 12:50:07.628172 | controller | ok
2025-10-03 12:50:07.633627 | 
2025-10-03 12:50:07.633694 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-03 12:50:07.773522 | controller | skipping: Conditional result was False
2025-10-03 12:50:07.784214 | 
2025-10-03 12:50:07.784280 | TASK [include_role : validate-host]
2025-10-03 12:50:07.865232 | controller | ok
2025-10-03 12:50:07.968006 | 
2025-10-03 12:50:07.968114 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-03 12:50:08.036025 | controller | ok
2025-10-03 12:50:08.044568 | 
2025-10-03 12:50:08.044635 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-03 12:50:09.301643 | controller -> localhost | ok
2025-10-03 12:50:09.307660 | 
2025-10-03 12:50:09.307735 | TASK [validate-host : Collect information about the host]
2025-10-03 12:50:10.120996 | controller | ok
2025-10-03 12:50:10.148070 | 
2025-10-03 12:50:10.148168 | TASK [validate-host : Sanitize hostname]
2025-10-03 12:50:10.413132 | controller | ok
2025-10-03 12:50:10.418024 | 
2025-10-03 12:50:10.418092 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-03 12:50:12.417317 | controller -> localhost | changed
2025-10-03 12:50:12.423575 | 
2025-10-03 12:50:12.423651 | TASK [validate-host : Collect information about zuul worker]
2025-10-03 12:50:13.580986 | controller | ok
2025-10-03 12:50:13.600616 | 
2025-10-03 12:50:13.600702 | TASK [validate-host : Write out all zuul information for each host]
2025-10-03 12:50:15.338561 | controller -> localhost | changed
2025-10-03 12:50:15.353111 | 
2025-10-03 12:50:15.353180 | TASK [include_role : prepare-workspace-openshift]
2025-10-03 12:50:15.398460 | controller | skipping: Conditional result was False
2025-10-03 12:50:15.404243 | 
2025-10-03 12:50:15.404306 | TASK [include_role : remove-zuul-sshkey]
2025-10-03 12:50:15.469124 | controller | skipping: Conditional result was False
2025-10-03 12:50:15.475665 | 
2025-10-03 12:50:15.475802 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-03 12:50:15.817441 | controller | ok: "logs"
2025-10-03 12:50:15.817651 | controller | ok: All items complete
2025-10-03 12:50:15.817681 | 
2025-10-03 12:50:15.997770 | controller | ok: "artifacts"
2025-10-03 12:50:16.206616 | controller | ok: "docs"
2025-10-03 12:50:16.216723 | 
2025-10-03 12:50:16.216790 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-03 12:50:16.578167 | controller | changed: "logs"
2025-10-03 12:50:16.796145 | controller | changed: "artifacts"
2025-10-03 12:50:17.035386 | controller | changed: "docs"
2025-10-03 12:50:17.104887 | 
2025-10-03 12:50:17.105985 | PLAY RECAP
2025-10-03 12:50:17.106058 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-03 12:50:17.106093 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-03 12:50:17.106115 | 
2025-10-03 12:50:17.702096 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-03 12:50:17.702865 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-03 12:50:20.455031 | 
2025-10-03 12:50:20.455144 | PLAY [all]
2025-10-03 12:50:20.532844 | 
2025-10-03 12:50:20.532979 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-03 12:50:20.754499 | controller | ok
2025-10-03 12:50:20.759763 | 
2025-10-03 12:50:20.759838 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-03 12:50:21.806641 | controller | changed
2025-10-03 12:50:21.816461 | 
2025-10-03 12:50:21.816535 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-03 12:50:23.897656 | controller | changed
2025-10-03 12:50:23.927074 | 
2025-10-03 12:50:23.927178 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-03 12:50:24.986698 | controller | changed:
2025-10-03 12:50:24.986927 | controller | {
2025-10-03 12:50:24.986964 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-03 12:50:24.986996 | controller | }
2025-10-03 12:50:25.216477 | controller | changed:
2025-10-03 12:50:25.216567 | controller | {
2025-10-03 12:50:25.216596 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-03 12:50:25.216620 | controller | }
2025-10-03 12:50:25.473014 | controller | changed:
2025-10-03 12:50:25.473092 | controller | {
2025-10-03 12:50:25.473119 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-03 12:50:25.473141 | controller | }
2025-10-03 12:50:25.739293 | controller | changed:
2025-10-03 12:50:25.739374 | controller | {
2025-10-03 12:50:25.739402 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-03 12:50:25.739424 | controller | }
2025-10-03 12:50:25.995656 | controller | changed:
2025-10-03 12:50:25.995739 | controller | {
2025-10-03 12:50:25.995767 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-03 12:50:25.995789 | controller | }
2025-10-03 12:50:26.249351 | controller | changed:
2025-10-03 12:50:26.249431 | controller | {
2025-10-03 12:50:26.249462 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-03 12:50:26.249486 | controller | }
2025-10-03 12:50:26.478444 | controller | changed:
2025-10-03 12:50:26.478540 | controller | {
2025-10-03 12:50:26.478569 | 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"
2025-10-03 12:50:26.478592 | controller | }
2025-10-03 12:50:26.731338 | controller | changed:
2025-10-03 12:50:26.731436 | controller | {
2025-10-03 12:50:26.731465 | 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"
2025-10-03 12:50:26.731489 | controller | }
2025-10-03 12:50:26.952855 | controller | changed:
2025-10-03 12:50:26.952967 | controller | {
2025-10-03 12:50:26.952996 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-03 12:50:26.953017 | controller | }
2025-10-03 12:50:27.199404 | controller | changed:
2025-10-03 12:50:27.199490 | controller | {
2025-10-03 12:50:27.199516 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-03 12:50:27.199538 | controller | }
2025-10-03 12:50:27.493284 | controller | changed:
2025-10-03 12:50:27.493379 | controller | {
2025-10-03 12:50:27.493408 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-03 12:50:27.493430 | controller | }
2025-10-03 12:50:27.748163 | controller | changed:
2025-10-03 12:50:27.748279 | controller | {
2025-10-03 12:50:27.748307 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-03 12:50:27.748329 | controller | }
2025-10-03 12:50:27.984733 | controller | changed:
2025-10-03 12:50:27.985058 | controller | {
2025-10-03 12:50:27.985103 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-03 12:50:27.985129 | controller | }
2025-10-03 12:50:28.207293 | controller | changed:
2025-10-03 12:50:28.207382 | controller | {
2025-10-03 12:50:28.207408 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-03 12:50:28.207428 | controller | }
2025-10-03 12:50:28.454630 | controller | changed:
2025-10-03 12:50:28.454780 | controller | {
2025-10-03 12:50:28.454813 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-03 12:50:28.454835 | controller | }
2025-10-03 12:50:28.706649 | controller | changed:
2025-10-03 12:50:28.706743 | controller | {
2025-10-03 12:50:28.706770 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-03 12:50:28.706792 | controller | }
2025-10-03 12:50:28.951385 | controller | changed:
2025-10-03 12:50:28.951473 | controller | {
2025-10-03 12:50:28.951500 | 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"
2025-10-03 12:50:28.951523 | controller | }
2025-10-03 12:50:29.188011 | controller | changed:
2025-10-03 12:50:29.188172 | controller | {
2025-10-03 12:50:29.188208 | 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"
2025-10-03 12:50:29.188239 | controller | }
2025-10-03 12:50:29.419799 | controller | changed:
2025-10-03 12:50:29.419896 | controller | {
2025-10-03 12:50:29.419944 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-03 12:50:29.419969 | controller | }
2025-10-03 12:50:29.670052 | controller | changed:
2025-10-03 12:50:29.670131 | controller | {
2025-10-03 12:50:29.670160 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-03 12:50:29.670181 | controller | }
2025-10-03 12:50:29.900347 | controller | changed:
2025-10-03 12:50:29.900528 | controller | {
2025-10-03 12:50:29.900566 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-03 12:50:29.900589 | controller | }
2025-10-03 12:50:30.114484 | controller | changed:
2025-10-03 12:50:30.114566 | controller | {
2025-10-03 12:50:30.114594 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-03 12:50:30.114615 | controller | }
2025-10-03 12:50:30.357753 | controller | changed:
2025-10-03 12:50:30.357846 | controller | {
2025-10-03 12:50:30.357883 | 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"
2025-10-03 12:50:30.357931 | controller | }
2025-10-03 12:50:30.597707 | controller | changed:
2025-10-03 12:50:30.597804 | controller | {
2025-10-03 12:50:30.597830 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-03 12:50:30.597852 | controller | }
2025-10-03 12:50:30.832716 | controller | changed:
2025-10-03 12:50:30.832800 | controller | {
2025-10-03 12:50:30.832827 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-03 12:50:30.832849 | controller | }
2025-10-03 12:50:31.094807 | controller | changed:
2025-10-03 12:50:31.094892 | controller | {
2025-10-03 12:50:31.094943 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-03 12:50:31.094972 | controller | }
2025-10-03 12:50:31.159541 | 
2025-10-03 12:50:31.159691 | TASK [Set timezone to UTC]
2025-10-03 12:50:32.549114 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-03 12:50:32.561405 | 
2025-10-03 12:50:32.561489 | TASK [Create nodepool directory]
2025-10-03 12:50:32.874893 | controller | changed
2025-10-03 12:50:32.885718 | 
2025-10-03 12:50:32.885792 | TASK [Create nodepool sub_nodes file]
2025-10-03 12:50:33.699397 | controller | changed
2025-10-03 12:50:33.706621 | 
2025-10-03 12:50:33.706888 | TASK [Create nodepool sub_nodes_private file]
2025-10-03 12:50:34.501102 | controller | changed
2025-10-03 12:50:34.516093 | 
2025-10-03 12:50:34.516555 | LOOP [Populate nodepool sub_nodes file]
2025-10-03 12:50:34.674611 | 
2025-10-03 12:50:34.674774 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-03 12:50:34.779116 | 
2025-10-03 12:50:34.779284 | TASK [Create nodepool primary file]
2025-10-03 12:50:34.886702 | controller | skipping: Conditional result was False
2025-10-03 12:50:34.893628 | 
2025-10-03 12:50:34.893791 | TASK [Create nodepool node_private for this node]
2025-10-03 12:50:35.713104 | controller | changed
2025-10-03 12:50:35.735294 | 
2025-10-03 12:50:35.735387 | LOOP [Copy ssh keys to nodepool directory]
2025-10-03 12:50:36.959763 | controller | ok: Item: id_rsa Runtime: 0:00:00.005858
2025-10-03 12:50:36.959983 | 
2025-10-03 12:50:37.202629 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005333
2025-10-03 12:50:37.211502 | 
2025-10-03 12:50:37.217966 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-03 12:50:38.043453 | controller | changed
2025-10-03 12:50:38.054125 | 
2025-10-03 12:50:38.054194 | TASK [Validate sudoers config after edits]
2025-10-03 12:50:38.651230 | controller | /etc/sudoers: parsed OK
2025-10-03 12:50:38.651278 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-03 12:50:38.651288 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-03 12:50:38.651294 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-03 12:50:38.689399 | controller | ok: Runtime: 0:00:00.005188
2025-10-03 12:50:38.694983 | 
2025-10-03 12:50:38.695048 | TASK [Show the environment passed in to job shell scripts]
2025-10-03 12:50:39.170819 | controller | SHELL=/bin/bash
2025-10-03 12:50:39.170872 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-03 12:50:39.170882 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-03 12:50:39.170888 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/66/3366/43edaa0ed5edd68d687cc142834eabbaec1542ac
2025-10-03 12:50:39.170894 | controller | PWD=/home/zuul
2025-10-03 12:50:39.170900 | controller | ZUUL_PIPELINE=github-check
2025-10-03 12:50:39.170906 | controller | LOGNAME=zuul
2025-10-03 12:50:39.170920 | controller | XDG_SESSION_TYPE=tty
2025-10-03 12:50:39.170930 | controller | _=/usr/bin/env
2025-10-03 12:50:39.170940 | controller | MOTD_SHOWN=pam
2025-10-03 12:50:39.170950 | controller | HOME=/home/zuul
2025-10-03 12:50:39.171001 | controller | LANG=en_US.UTF-8
2025-10-03 12:50:39.171010 | controller | SSH_CONNECTION=192.168.26.12 60786 192.168.26.54 22
2025-10-03 12:50:39.171016 | 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
2025-10-03 12:50:39.171025 | controller | ZUUL_CHANGE_IDS=3366,43edaa0ed5edd68d687cc142834eabbaec1542ac
2025-10-03 12:50:39.171031 | controller | WORKSPACE=/home/zuul/workspace
2025-10-03 12:50:39.171036 | controller | XDG_SESSION_CLASS=user
2025-10-03 12:50:39.171042 | controller | SELINUX_ROLE_REQUESTED=
2025-10-03 12:50:39.171047 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-03 12:50:39.171052 | controller | USER=zuul
2025-10-03 12:50:39.171058 | controller | ZUUL_VOTING=True
2025-10-03 12:50:39.171063 | controller | BUILD_TIMEOUT=1800000
2025-10-03 12:50:39.171069 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-03 12:50:39.171074 | controller | SHLVL=1
2025-10-03 12:50:39.171080 | controller | ZUUL_PATCHSET=43edaa0ed5edd68d687cc142834eabbaec1542ac
2025-10-03 12:50:39.171087 | controller | XDG_SESSION_ID=1
2025-10-03 12:50:39.171092 | controller | ZUUL_BRANCH=main
2025-10-03 12:50:39.171097 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-03 12:50:39.171102 | controller | SSH_CLIENT=192.168.26.12 60786 22
2025-10-03 12:50:39.171108 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-03 12:50:39.171113 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-03 12:50:39.171118 | controller | which_declare=declare -f
2025-10-03 12:50:39.171124 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-03 12:50:39.171130 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-03 12:50:39.171135 | controller | ZUUL_CHANGE=3366
2025-10-03 12:50:39.171140 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-03 12:50:39.171145 | controller | ZUUL_UUID=cc9db8a592ba4e0fac5e79abddf8fa1c
2025-10-03 12:50:39.171151 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-03 12:50:39.171156 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-03 12:50:39.171162 | controller | }
2025-10-03 12:50:39.329637 | controller | ok: Runtime: 0:00:00.005827
2025-10-03 12:50:39.334966 | 
2025-10-03 12:50:39.335035 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-03 12:50:39.407191 | controller | skipping: Conditional result was False
2025-10-03 12:50:39.536199 | 
2025-10-03 12:50:39.536300 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-03 12:50:40.158301 | controller | skipping: Conditional result was False
2025-10-03 12:50:40.163849 | 
2025-10-03 12:50:40.163942 | TASK [Ensure legacy workspace directory]
2025-10-03 12:50:40.412502 | controller | changed
2025-10-03 12:50:40.544560 | 
2025-10-03 12:50:40.544650 | PLAY RECAP
2025-10-03 12:50:40.544705 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-03 12:50:40.544730 | 
2025-10-03 12:50:41.175837 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-03 12:50:41.176642 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-03 12:50:43.765823 | 
2025-10-03 12:50:43.766259 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-03 12:50:43.876899 | 
2025-10-03 12:50:43.877047 | TASK [Create zuul-output directory]
2025-10-03 12:50:44.800789 | controller | changed
2025-10-03 12:50:44.813742 | 
2025-10-03 12:50:44.813848 | TASK [Slurp Zuul inventory test]
2025-10-03 12:50:46.489657 | controller -> localhost | ok
2025-10-03 12:50:46.498431 | 
2025-10-03 12:50:46.498506 | TASK [Save zuul inventory]
2025-10-03 12:50:48.407737 | controller | changed
2025-10-03 12:50:48.417861 | 
2025-10-03 12:50:48.417998 | TASK [Save zuul vars without the change_message]
2025-10-03 12:50:49.687902 | controller | changed
2025-10-03 12:50:49.781434 | 
2025-10-03 12:50:49.781553 | PLAY RECAP
2025-10-03 12:50:49.781605 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-03 12:50:49.781630 | 
2025-10-03 12:50:50.190482 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-03 12:50:50.191284 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-03 12:50:52.102400 | 
2025-10-03 12:50:52.102522 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-03 12:50:52.194383 | 
2025-10-03 12:50:52.194510 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-03 12:50:52.261526 | controller | ok
2025-10-03 12:50:52.338181 | 
2025-10-03 12:50:52.338323 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-03 12:50:52.458204 | controller | skipping: Conditional result was False
2025-10-03 12:50:52.464270 | 
2025-10-03 12:50:52.464347 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-03 12:50:53.309833 | controller | ok
2025-10-03 12:50:53.327275 | 
2025-10-03 12:50:53.327428 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-03 12:50:54.600825 | controller | ok
2025-10-03 12:50:54.634844 | 
2025-10-03 12:50:54.634965 | TASK [Prepare workspace]
2025-10-03 12:50:54.697972 | controller | ok
2025-10-03 12:50:54.788131 | 
2025-10-03 12:50:54.788235 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-03 12:50:55.496040 | controller | ok
2025-10-03 12:50:55.502209 | 
2025-10-03 12:50:55.502276 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-03 12:51:02.617268 | controller | Output suppressed because no_log was given
2025-10-03 12:51:02.645265 | 
2025-10-03 12:51:02.645359 | LOOP [Create zuul-output directory]
2025-10-03 12:51:02.930092 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-03 12:51:03.114267 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-03 12:51:03.122467 | 
2025-10-03 12:51:03.122566 | TASK [Install required packages]
2025-10-03 12:51:53.131272 | controller | changed
2025-10-03 12:51:53.138807 | 
2025-10-03 12:51:53.139000 | TASK [Install venv]
2025-10-03 12:52:54.863247 | controller | changed
2025-10-03 12:52:54.901731 | 
2025-10-03 12:52:54.901873 | PLAY RECAP
2025-10-03 12:52:54.901947 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-03 12:52:54.901975 | 
2025-10-03 12:52:55.072379 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-03 12:52:55.073241 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-03 12:52:55.720982 | 
2025-10-03 12:52:55.721082 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-03 12:52:55.747485 | 
2025-10-03 12:52:55.747713 | TASK [Gather required facts]
2025-10-03 12:52:56.494752 | controller | ok
2025-10-03 12:52:56.500294 | 
2025-10-03 12:52:56.500375 | TASK [Load environment var if instructed to]
2025-10-03 12:52:56.559595 | controller | skipping: Conditional result was False
2025-10-03 12:52:56.565262 | 
2025-10-03 12:52:56.565335 | TASK [Check if group_vars dir exists]
2025-10-03 12:52:56.924160 | controller | ok
2025-10-03 12:52:56.932440 | 
2025-10-03 12:52:56.932513 | TASK [Create group_vars dir if does not exist]
2025-10-03 12:52:56.971990 | controller | ERROR
2025-10-03 12:52:56.972168 | controller | {
2025-10-03 12:52:56.972201 | controller |   "msg": "The conditional check 'not group_vars_dir.stat.exits' failed. The error was: error while evaluating conditional (not group_vars_dir.stat.exits): 'dict object' has no attribute 'exits'. 'dict object' has no attribute 'exits'\n\nThe error appears to be in '/var/lib/zuul/builds/cc9db8a592ba4e0fac5e79abddf8fa1c/untrusted/project_0/github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml': line 25, column 7, but may\nbe elsewhere in the file depending on the exact syntax problem.\n\nThe offending line appears to be:\n\n\n    - name: Create group_vars dir if does not exist\n      ^ here\n"
2025-10-03 12:52:56.972227 | controller | }
failure
2025-10-03 12:52:56.974006 | 
2025-10-03 12:52:56.974060 | PLAY RECAP
2025-10-03 12:52:56.974105 | controller | ok: 2 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-10-03 12:52:56.974128 | 
2025-10-03 12:52:57.084467 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-03 12:52:57.085337 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-03 12:52:57.926123 | 
2025-10-03 12:52:57.926240 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-03 12:52:57.978381 | 
2025-10-03 12:52:57.978502 | TASK [Filter out host if needed]
2025-10-03 12:52:58.005433 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-03 12:52:58.017297 | 
2025-10-03 12:52:58.017374 | TASK [Ensure file is present]
2025-10-03 12:52:58.682177 | controller | ok
2025-10-03 12:52:58.695741 | 
2025-10-03 12:52:58.695831 | TASK [Manage molecule report file]
2025-10-03 12:52:59.270671 | controller | skipping: Conditional result was False
2025-10-03 12:52:59.276217 | 
2025-10-03 12:52:59.276290 | TASK [Check if we get ci-framework-data basedir]
2025-10-03 12:52:59.504369 | controller | ok
2025-10-03 12:52:59.513152 | 
2025-10-03 12:52:59.513230 | TASK [Create ci-framework-data log directory for zuul]
2025-10-03 12:52:59.913238 | controller | changed
2025-10-03 12:52:59.918997 | 
2025-10-03 12:52:59.919089 | TASK [Copy ci-framework interesting files]
2025-10-03 12:53:00.344947 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-03 12:53:00.486727 | controller | changed
2025-10-03 12:53:00.493304 | 
2025-10-03 12:53:00.493377 | TASK [Get SELinux listing]
2025-10-03 12:53:01.046356 | controller | changed
2025-10-03 12:53:01.052441 | 
2025-10-03 12:53:01.052510 | TASK [Generate log index]
2025-10-03 12:53:01.912634 | controller | changed
2025-10-03 12:53:01.917653 | 
2025-10-03 12:53:01.917716 | TASK [Get some env related data]
2025-10-03 12:53:02.452387 | controller | /home/zuul/.local/bin/ansible
2025-10-03 12:53:03.456638 | controller | changed
2025-10-03 12:53:03.464516 | 
2025-10-03 12:53:03.464610 | TASK [Generate list of logs to collect in home directory]
2025-10-03 12:53:03.953654 | controller | ok: All paths examined
2025-10-03 12:53:03.969825 | 
2025-10-03 12:53:03.970003 | LOOP [Copy logs from home directory]
2025-10-03 12:53:04.395119 | controller | changed:
2025-10-03 12:53:04.395265 | controller | {
2025-10-03 12:53:04.395293 | controller |   "atime": 1759495958.2220232,
2025-10-03 12:53:04.395314 | controller |   "ctime": 1759495974.4332166,
2025-10-03 12:53:04.395333 | controller |   "dev": 64513,
2025-10-03 12:53:04.395350 | controller |   "gid": 1000,
2025-10-03 12:53:04.395366 | controller |   "gr_name": "zuul",
2025-10-03 12:53:04.395383 | controller |   "inode": 4448753,
2025-10-03 12:53:04.395398 | controller |   "isblk": false,
2025-10-03 12:53:04.395414 | controller |   "ischr": false,
2025-10-03 12:53:04.395429 | controller |   "isdir": false,
2025-10-03 12:53:04.395445 | controller |   "isfifo": false,
2025-10-03 12:53:04.395460 | controller |   "isgid": false,
2025-10-03 12:53:04.395475 | controller |   "islnk": false,
2025-10-03 12:53:04.395490 | controller |   "isreg": true,
2025-10-03 12:53:04.395505 | controller |   "issock": false,
2025-10-03 12:53:04.395519 | controller |   "isuid": false,
2025-10-03 12:53:04.395534 | controller |   "mode": "0644",
2025-10-03 12:53:04.395548 | controller |   "mtime": 1759495974.4332166,
2025-10-03 12:53:04.395562 | controller |   "nlink": 1,
2025-10-03 12:53:04.395577 | controller |   "path": "/home/zuul/ansible.log",
2025-10-03 12:53:04.395594 | controller |   "pw_name": "zuul",
2025-10-03 12:53:04.395609 | controller |   "rgrp": true,
2025-10-03 12:53:04.395624 | controller |   "roth": true,
2025-10-03 12:53:04.395639 | controller |   "rusr": true,
2025-10-03 12:53:04.395653 | controller |   "size": 6749,
2025-10-03 12:53:04.395668 | controller |   "uid": 1000,
2025-10-03 12:53:04.395682 | controller |   "wgrp": false,
2025-10-03 12:53:04.395696 | controller |   "woth": false,
2025-10-03 12:53:04.395714 | controller |   "wusr": true,
2025-10-03 12:53:04.410631 | controller |   "xgrp": false,
2025-10-03 12:53:04.410700 | controller |   "xoth": false,
2025-10-03 12:53:04.410729 | controller |   "xusr": false
2025-10-03 12:53:04.410751 | controller | }
2025-10-03 12:53:04.429203 | 
2025-10-03 12:53:04.429410 | TASK [Copy crio stats log file]
2025-10-03 12:53:04.484635 | controller | skipping: Conditional result was False
2025-10-03 12:53:04.491756 | 
2025-10-03 12:53:04.491829 | TASK [Get SELinux related data]
2025-10-03 12:53:04.794829 | controller | <no matches>
2025-10-03 12:53:05.066812 | controller | ERROR
2025-10-03 12:53:05.067078 | controller | {
2025-10-03 12:53:05.067113 | controller |   "delta": "0:00:00.012986",
2025-10-03 12:53:05.067136 | controller |   "end": "2025-10-03 12:53:04.796380",
2025-10-03 12:53:05.067155 | controller |   "msg": "non-zero return code",
2025-10-03 12:53:05.067173 | controller |   "rc": 1,
2025-10-03 12:53:05.067190 | controller |   "start": "2025-10-03 12:53:04.783394"
2025-10-03 12:53:05.067207 | controller | }
2025-10-03 12:53:05.067232 | controller | ERROR: Ignoring Errors
2025-10-03 12:53:05.073125 | 
2025-10-03 12:53:05.073198 | TASK [Create system configuration directory]
2025-10-03 12:53:05.297823 | controller | changed
2025-10-03 12:53:05.305544 | 
2025-10-03 12:53:05.305643 | TASK [Get some of the system configurations]
2025-10-03 12:53:05.881531 | controller | changed
2025-10-03 12:53:05.887504 | 
2025-10-03 12:53:05.887575 | TASK [Copy generated documentation if available]
2025-10-03 12:53:05.949437 | controller | skipping: Conditional result was False
2025-10-03 12:53:05.955666 | 
2025-10-03 12:53:05.955748 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-03 12:53:06.023097 | controller | skipping: Conditional result was False
2025-10-03 12:53:06.029603 | 
2025-10-03 12:53:06.029750 | TASK [Compress logs bigger than 2MB]
2025-10-03 12:53:06.607838 | controller | changed
2025-10-03 12:53:06.614681 | 
2025-10-03 12:53:06.614925 | TASK [Copy files from workspace on node]
2025-10-03 12:53:06.682138 | controller | ok
2025-10-03 12:53:06.756646 | 
2025-10-03 12:53:06.756770 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-03 12:53:06.783198 | controller | skipping: Conditional result was False
2025-10-03 12:53:06.789664 | 
2025-10-03 12:53:06.789747 | TASK [fetch-output : Set log path for single node]
2025-10-03 12:53:06.893061 | controller | ok
2025-10-03 12:53:06.898137 | 
2025-10-03 12:53:06.898216 | LOOP [fetch-output : Ensure local output dirs]
2025-10-03 12:53:07.409299 | controller -> localhost | ok: "/var/lib/zuul/builds/cc9db8a592ba4e0fac5e79abddf8fa1c/work/logs"
2025-10-03 12:53:07.409839 | controller -> localhost | changed: All items complete
2025-10-03 12:53:07.409874 | 
2025-10-03 12:53:07.954333 | controller -> localhost | changed: "/var/lib/zuul/builds/cc9db8a592ba4e0fac5e79abddf8fa1c/work/artifacts"
2025-10-03 12:53:08.306353 | controller -> localhost | changed: "/var/lib/zuul/builds/cc9db8a592ba4e0fac5e79abddf8fa1c/work/docs"
2025-10-03 12:53:08.317512 | 
2025-10-03 12:53:08.325055 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-03 12:53:09.954010 | controller | changed:
2025-10-03 12:53:09.954221 | controller | .d..t...... ./
2025-10-03 12:53:09.954262 | controller | >f+++++++++ README.html
2025-10-03 12:53:09.954286 | controller | >f+++++++++ ansible.log
2025-10-03 12:53:09.954307 | controller | >f+++++++++ dmesg.log
2025-10-03 12:53:09.954327 | controller | >f+++++++++ installed-pkgs.log
2025-10-03 12:53:09.954345 | controller | >f+++++++++ python.log
2025-10-03 12:53:09.954366 | controller | >f+++++++++ registries.conf
2025-10-03 12:53:09.954389 | controller | >f+++++++++ selinux-denials.log
2025-10-03 12:53:09.954406 | controller | >f+++++++++ selinux-listing.log
2025-10-03 12:53:09.954426 | controller | cd+++++++++ ci-framework-data/
2025-10-03 12:53:09.954444 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-03 12:53:09.954462 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-03 12:53:09.954479 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-03 12:53:09.954496 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-03 12:53:09.954512 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-03 12:53:09.954530 | controller | cd+++++++++ registries.conf.d/
2025-10-03 12:53:09.954547 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-03 12:53:09.954564 | controller | cd+++++++++ system-config/
2025-10-03 12:53:09.954581 | controller | cd+++++++++ system-config/libvirt/
2025-10-03 12:53:09.954597 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-03 12:53:09.954615 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-03 12:53:10.867851 | controller | changed: .d..t...... ./
2025-10-03 12:53:11.847825 | controller | changed: .d..t...... ./
2025-10-03 12:53:11.893081 | 
2025-10-03 12:53:11.893195 | TASK [Return artifact to Zuul]
2025-10-03 12:53:11.966427 | controller | skipping: Conditional result was False
2025-10-03 12:53:12.022485 | 
2025-10-03 12:53:12.022581 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-03 12:53:12.022695 | 
2025-10-03 12:53:12.022724 | PLAY RECAP
2025-10-03 12:53:12.022764 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1
2025-10-03 12:53:12.022786 | 
2025-10-03 12:53:12.312307 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-03 12:53:12.313403 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-03 12:53:13.900254 | 
2025-10-03 12:53:13.900394 | PLAY [all]
2025-10-03 12:53:13.961279 | 
2025-10-03 12:53:13.961419 | TASK [include_role : fetch-output]
2025-10-03 12:53:14.061051 | controller | ok
2025-10-03 12:53:14.112675 | 
2025-10-03 12:53:14.112816 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-03 12:53:14.304348 | controller | skipping: Conditional result was False
2025-10-03 12:53:14.310770 | 
2025-10-03 12:53:14.310849 | TASK [fetch-output : Set log path for single node]
2025-10-03 12:53:14.427606 | controller | ok
2025-10-03 12:53:14.433168 | 
2025-10-03 12:53:14.433275 | LOOP [fetch-output : Ensure local output dirs]
2025-10-03 12:53:15.702606 | controller -> localhost | ok: "/var/lib/zuul/builds/cc9db8a592ba4e0fac5e79abddf8fa1c/work/logs"
2025-10-03 12:53:16.379803 | controller -> localhost | ok: "/var/lib/zuul/builds/cc9db8a592ba4e0fac5e79abddf8fa1c/work/artifacts"
2025-10-03 12:53:17.133851 | controller -> localhost | ok: "/var/lib/zuul/builds/cc9db8a592ba4e0fac5e79abddf8fa1c/work/docs"
2025-10-03 12:53:17.163784 | 
2025-10-03 12:53:17.163894 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-03 12:53:18.539052 | controller | ok
2025-10-03 12:53:18.539272 | controller | ok: All items complete
2025-10-03 12:53:18.539302 | 
2025-10-03 12:53:19.606028 | controller | ok
2025-10-03 12:53:20.683513 | controller | ok
2025-10-03 12:53:20.703833 | 
2025-10-03 12:53:20.703985 | TASK [include_role : fetch-output-openshift]
2025-10-03 12:53:20.731341 | controller | skipping: Conditional result was False
2025-10-03 12:53:20.739243 | 
2025-10-03 12:53:20.739336 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-03 12:53:22.096424 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.026115
2025-10-03 12:53:22.767162 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.072265
2025-10-03 12:53:22.878151 | 
2025-10-03 12:53:22.878248 | PLAY [all]
2025-10-03 12:53:22.918244 | 
2025-10-03 12:53:22.918334 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-03 12:53:23.907975 | controller | changed
2025-10-03 12:53:24.121977 | 
2025-10-03 12:53:24.122073 | PLAY RECAP
2025-10-03 12:53:24.122121 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-03 12:53:24.122145 | 
2025-10-03 12:53:24.646985 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-03 12:53:24.647740 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-03 12:53:27.217850 | 
2025-10-03 12:53:27.218021 | PLAY [localhost]
2025-10-03 12:53:27.350676 | 
2025-10-03 12:53:27.350828 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-03 12:53:28.881363 | localhost | changed
2025-10-03 12:53:28.889660 | 
2025-10-03 12:53:28.889748 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-03 12:53:28.967595 | localhost | ok
2025-10-03 12:53:28.992843 | 
2025-10-03 12:53:28.992972 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-03 12:53:30.473154 | localhost | changed
2025-10-03 12:53:30.481549 | 
2025-10-03 12:53:30.481647 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-03 12:53:33.363342 | localhost | changed
2025-10-03 12:53:33.368803 | 
2025-10-03 12:53:33.368876 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-03 12:53:35.327893 | localhost | Identity added: /var/lib/zuul/builds/cc9db8a592ba4e0fac5e79abddf8fa1c/work/tmp/ansible.kw25v3y4 (/var/lib/zuul/builds/cc9db8a592ba4e0fac5e79abddf8fa1c/work/tmp/ansible.kw25v3y4)
2025-10-03 12:53:35.328110 | localhost | ok: Runtime: 0:00:00.032204
2025-10-03 12:53:35.339857 | 
2025-10-03 12:53:35.339968 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-03 12:53:36.642230 | localhost | ok: Runtime: 0:00:00.041169
2025-10-03 12:53:36.647652 | 
2025-10-03 12:53:36.647722 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-03 12:53:37.228452 | localhost | changed
2025-10-03 12:53:37.546525 | 
2025-10-03 12:53:37.546710 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-03 12:53:38.963287 | localhost | changed
2025-10-03 12:53:39.056272 | 
2025-10-03 12:53:39.056392 | PLAY [localhost]
2025-10-03 12:53:39.132297 | 
2025-10-03 12:53:39.132486 | TASK [Generate bulk log download script]
2025-10-03 12:53:39.220567 | localhost | ok
2025-10-03 12:53:39.255479 | 
2025-10-03 12:53:39.255626 | TASK [local-log-download : Check API endpoint is defined]
2025-10-03 12:53:39.392968 | localhost | ok: All assertions passed
2025-10-03 12:53:39.398106 | 
2025-10-03 12:53:39.398173 | TASK [local-log-download : Create download script]
2025-10-03 12:53:41.389856 | localhost -> localhost | changed
2025-10-03 12:53:41.399756 | 
2025-10-03 12:53:41.399824 | TASK [Register quick-download link]
2025-10-03 12:53:41.509997 | localhost | ok
2025-10-03 12:53:41.559071 | 
2025-10-03 12:53:41.559145 | PLAY [logserver.rdoproject.org]
2025-10-03 12:53:41.593872 | 
2025-10-03 12:53:41.593991 | TASK [Set zuul-log-path fact]
2025-10-03 12:53:41.661903 | logserver.rdoproject.org | ok
2025-10-03 12:53:41.704024 | 
2025-10-03 12:53:41.704145 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-03 12:53:41.812444 | logserver.rdoproject.org | ok
2025-10-03 12:53:41.818930 | 
2025-10-03 12:53:41.819013 | TASK [upload-logs : Create log directories]
2025-10-03 12:53:44.022219 | logserver.rdoproject.org | changed
2025-10-03 12:53:44.026013 | 
2025-10-03 12:53:44.026083 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-03 12:53:45.046355 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.032713
2025-10-03 12:53:45.051955 | 
2025-10-03 12:53:45.052031 | TASK [upload-logs : Upload logs to log server]
2025-10-03 12:53:48.259116 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-03 12:53:48.262304 | 
2025-10-03 12:53:48.262372 | LOOP [upload-logs : Compress console log and json output]
2025-10-03 12:53:48.607645 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 12:53:48.641003 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 12:53:48.656717 | 
2025-10-03 12:53:48.656849 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-03 12:53:48.800414 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 12:53:48.800758 | 
2025-10-03 12:53:48.814291 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 12:53:48.825076 | 
2025-10-03 12:53:48.825282 | LOOP [upload-logs : Upload console log and json output]