2025-12-05 15:37:42.397250 | Job console starting...
2025-12-05 15:37:42.678310 | Updating repositories
2025-12-05 15:37:43.677159 | Preparing job workspace
2025-12-05 15:37:56.339980 | Running Ansible setup...
2025-12-05 15:38:01.495500 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 15:38:03.452611 | 
2025-12-05 15:38:03.452731 | PLAY [localhost]
2025-12-05 15:38:03.484861 | 
2025-12-05 15:38:03.484987 | TASK [Gathering Facts]
2025-12-05 15:38:05.185875 | localhost | ok
2025-12-05 15:38:05.210969 | 
2025-12-05 15:38:05.211067 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-05 15:38:05.760602 | localhost -> localhost | changed
2025-12-05 15:38:05.766399 | 
2025-12-05 15:38:05.766484 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-05 15:38:08.032796 | localhost -> localhost | changed
2025-12-05 15:38:08.041315 | 
2025-12-05 15:38:08.041385 | TASK [Setup log path fact]
2025-12-05 15:38:08.071886 | localhost | ok
2025-12-05 15:38:08.111293 | 
2025-12-05 15:38:08.111366 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 15:38:08.197922 | localhost | ok
2025-12-05 15:38:08.208850 | 
2025-12-05 15:38:08.208948 | TASK [emit-job-header : Print job information]
2025-12-05 15:38:08.302466 | # Job Information
2025-12-05 15:38:08.302598 | Ansible Version: 2.15.12
2025-12-05 15:38:08.302629 | Job: cifmw-molecule-edpm_kustomize
2025-12-05 15:38:08.302652 | Pipeline: github-check
2025-12-05 15:38:08.302674 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-12-05 15:38:08.302693 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3539
2025-12-05 15:38:08.302713 | Log URL (when completed): https://logserver.rdoproject.org/e00/rdoproject.org/e00d156684a04d6b9c123eb06f4011ec/
2025-12-05 15:38:08.302732 | Event ID: 757b9e30-d1ef-11f0-97eb-f505e6ea5a00
2025-12-05 15:38:08.306642 | 
2025-12-05 15:38:08.306710 | LOOP [emit-job-header : Print node information]
2025-12-05 15:38:08.555747 | localhost | ok:
2025-12-05 15:38:08.555978 | localhost | # Node Information
2025-12-05 15:38:08.556024 | localhost | Inventory Hostname: controller
2025-12-05 15:38:08.556051 | localhost | Hostname: np0005547524
2025-12-05 15:38:08.556073 | localhost | Username: zuul
2025-12-05 15:38:08.556094 | localhost | Distro: CentOS 9
2025-12-05 15:38:08.556112 | localhost | Provider: ibm-bm4-nodepool
2025-12-05 15:38:08.556130 | localhost | Region: regionOne
2025-12-05 15:38:08.556148 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-12-05 15:38:08.556166 | localhost | Product Name: OpenStack Compute
2025-12-05 15:38:08.556184 | localhost | Interface IP: 192.168.26.4
2025-12-05 15:38:08.612120 | 
2025-12-05 15:38:08.612205 | PLAY [all]
2025-12-05 15:38:08.627592 | 
2025-12-05 15:38:08.627659 | TASK [Gather network facts]
2025-12-05 15:38:09.101743 | controller | ok
2025-12-05 15:38:09.154716 | 
2025-12-05 15:38:09.154803 | TASK [include_role : start-zuul-console]
2025-12-05 15:38:09.225071 | controller | ok
2025-12-05 15:38:09.237259 | 
2025-12-05 15:38:09.237325 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-05 15:38:09.864050 | controller | ok
2025-12-05 15:38:09.899332 | 
2025-12-05 15:38:09.899427 | TASK [include_role : add-build-sshkey]
2025-12-05 15:38:10.003384 | controller | ok
2025-12-05 15:38:10.030536 | 
2025-12-05 15:38:10.030684 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-05 15:38:10.966582 | controller -> localhost | ok
2025-12-05 15:38:10.975696 | 
2025-12-05 15:38:10.975789 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-05 15:38:11.092678 | controller | ok
2025-12-05 15:38:11.114046 | controller | included: /var/lib/zuul/builds/e00d156684a04d6b9c123eb06f4011ec/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-05 15:38:11.123967 | 
2025-12-05 15:38:11.124052 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-05 15:38:12.864578 | controller -> localhost | Generating public/private rsa key pair.
2025-12-05 15:38:12.864822 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e00d156684a04d6b9c123eb06f4011ec/work/e00d156684a04d6b9c123eb06f4011ec_id_rsa.
2025-12-05 15:38:12.864858 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e00d156684a04d6b9c123eb06f4011ec/work/e00d156684a04d6b9c123eb06f4011ec_id_rsa.pub.
2025-12-05 15:38:12.864883 | controller -> localhost | The key fingerprint is:
2025-12-05 15:38:12.864927 | controller -> localhost | SHA256:Jq2Vimm8DGfUhe/0LkLs8lR2JE+Wpfdb+U+OyIoNra0 zuul-build-sshkey
2025-12-05 15:38:12.864959 | controller -> localhost | The key's randomart image is:
2025-12-05 15:38:12.864983 | controller -> localhost | +---[RSA 3072]----+
2025-12-05 15:38:12.865004 | controller -> localhost | |            .    |
2025-12-05 15:38:12.865034 | controller -> localhost | |       .   +     |
2025-12-05 15:38:12.865053 | controller -> localhost | |      . o * .    |
2025-12-05 15:38:12.865072 | controller -> localhost | |     . + B . .  .|
2025-12-05 15:38:12.865091 | controller -> localhost | |    ..o S o   ...|
2025-12-05 15:38:12.865108 | controller -> localhost | |   o oo@ +     o.|
2025-12-05 15:38:12.865126 | controller -> localhost | |  . Bo+ o o   . o|
2025-12-05 15:38:12.865146 | controller -> localhost | |   *.oo .B . . +.|
2025-12-05 15:38:12.865164 | controller -> localhost | |    oo..Eo=.o . o|
2025-12-05 15:38:12.865183 | controller -> localhost | +----[SHA256]-----+
2025-12-05 15:38:12.865225 | controller -> localhost | ok: Runtime: 0:00:00.584367
2025-12-05 15:38:12.870719 | 
2025-12-05 15:38:12.870782 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-05 15:38:12.900883 | controller | ok
2025-12-05 15:38:12.920088 | controller | included: /var/lib/zuul/builds/e00d156684a04d6b9c123eb06f4011ec/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-05 15:38:12.953994 | 
2025-12-05 15:38:12.954083 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-05 15:38:13.024643 | controller | skipping: Conditional result was False
2025-12-05 15:38:13.030141 | 
2025-12-05 15:38:13.030209 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-05 15:38:13.752706 | controller | changed
2025-12-05 15:38:13.757928 | 
2025-12-05 15:38:13.759303 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-05 15:38:14.008632 | controller | ok
2025-12-05 15:38:14.013606 | 
2025-12-05 15:38:14.013672 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-05 15:38:14.780133 | controller | changed
2025-12-05 15:38:14.794994 | 
2025-12-05 15:38:14.795076 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-05 15:38:15.641864 | controller | changed
2025-12-05 15:38:15.655567 | 
2025-12-05 15:38:15.655650 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-05 15:38:15.743858 | controller | skipping: Conditional result was False
2025-12-05 15:38:15.750572 | 
2025-12-05 15:38:15.750661 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-05 15:38:16.728793 | controller -> localhost | changed
2025-12-05 15:38:16.852478 | 
2025-12-05 15:38:16.852606 | TASK [add-build-sshkey : Add back temp key]
2025-12-05 15:38:17.482181 | controller -> localhost | Identity added: /var/lib/zuul/builds/e00d156684a04d6b9c123eb06f4011ec/work/e00d156684a04d6b9c123eb06f4011ec_id_rsa (zuul-build-sshkey)
2025-12-05 15:38:17.482355 | controller -> localhost | ok: Runtime: 0:00:00.012700
2025-12-05 15:38:17.488781 | 
2025-12-05 15:38:17.488848 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-05 15:38:17.976947 | controller | ok
2025-12-05 15:38:17.982405 | 
2025-12-05 15:38:17.982471 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-05 15:38:18.041418 | controller | skipping: Conditional result was False
2025-12-05 15:38:18.050794 | 
2025-12-05 15:38:18.050863 | TASK [include_role : validate-host]
2025-12-05 15:38:18.102706 | controller | ok
2025-12-05 15:38:18.155905 | 
2025-12-05 15:38:18.155994 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-05 15:38:18.244306 | controller | ok
2025-12-05 15:38:18.257588 | 
2025-12-05 15:38:18.257660 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-05 15:38:19.157969 | controller -> localhost | ok
2025-12-05 15:38:19.164051 | 
2025-12-05 15:38:19.164122 | TASK [validate-host : Collect information about the host]
2025-12-05 15:38:19.845004 | controller | ok
2025-12-05 15:38:19.857118 | 
2025-12-05 15:38:19.857185 | TASK [validate-host : Sanitize hostname]
2025-12-05 15:38:19.998385 | controller | ok
2025-12-05 15:38:20.003725 | 
2025-12-05 15:38:20.003795 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-05 15:38:21.335388 | controller -> localhost | changed
2025-12-05 15:38:21.341242 | 
2025-12-05 15:38:21.341311 | TASK [validate-host : Collect information about zuul worker]
2025-12-05 15:38:22.020852 | controller | ok
2025-12-05 15:38:22.026012 | 
2025-12-05 15:38:22.026088 | TASK [validate-host : Write out all zuul information for each host]
2025-12-05 15:38:23.312876 | controller -> localhost | changed
2025-12-05 15:38:23.322471 | 
2025-12-05 15:38:23.322556 | TASK [include_role : prepare-workspace-openshift]
2025-12-05 15:38:23.383707 | controller | skipping: Conditional result was False
2025-12-05 15:38:23.389021 | 
2025-12-05 15:38:23.389121 | TASK [include_role : remove-zuul-sshkey]
2025-12-05 15:38:23.453512 | controller | skipping: Conditional result was False
2025-12-05 15:38:23.460056 | 
2025-12-05 15:38:23.460159 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 15:38:23.748765 | controller | ok: "logs"
2025-12-05 15:38:23.749010 | controller | ok: All items complete
2025-12-05 15:38:23.749063 | 
2025-12-05 15:38:23.943455 | controller | ok: "artifacts"
2025-12-05 15:38:24.145602 | controller | ok: "docs"
2025-12-05 15:38:24.159843 | 
2025-12-05 15:38:24.159944 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 15:38:24.435550 | controller | changed: "logs"
2025-12-05 15:38:24.612883 | controller | changed: "artifacts"
2025-12-05 15:38:24.777809 | controller | changed: "docs"
2025-12-05 15:38:24.825583 | 
2025-12-05 15:38:24.825677 | PLAY RECAP
2025-12-05 15:38:24.825721 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 15:38:24.825752 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 15:38:24.825772 | 
2025-12-05 15:38:25.119477 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 15:38:25.120161 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-05 15:38:26.996538 | 
2025-12-05 15:38:26.996656 | PLAY [all]
2025-12-05 15:38:27.053656 | 
2025-12-05 15:38:27.053769 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-05 15:38:27.197257 | controller | ok
2025-12-05 15:38:27.202152 | 
2025-12-05 15:38:27.202232 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-05 15:38:27.808422 | controller | changed
2025-12-05 15:38:27.816021 | 
2025-12-05 15:38:27.816120 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-05 15:38:29.164559 | controller | changed
2025-12-05 15:38:29.193069 | 
2025-12-05 15:38:29.193166 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-05 15:38:29.841211 | controller | changed:
2025-12-05 15:38:29.841380 | controller | {
2025-12-05 15:38:29.841414 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-05 15:38:29.841444 | controller | }
2025-12-05 15:38:30.068175 | controller | changed:
2025-12-05 15:38:30.068274 | controller | {
2025-12-05 15:38:30.068314 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-05 15:38:30.068344 | controller | }
2025-12-05 15:38:30.293751 | controller | changed:
2025-12-05 15:38:30.293837 | controller | {
2025-12-05 15:38:30.293870 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-12-05 15:38:30.293895 | controller | }
2025-12-05 15:38:30.532183 | controller | changed:
2025-12-05 15:38:30.532264 | controller | {
2025-12-05 15:38:30.532294 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-05 15:38:30.532315 | controller | }
2025-12-05 15:38:30.745554 | controller | changed:
2025-12-05 15:38:30.745640 | controller | {
2025-12-05 15:38:30.745668 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-05 15:38:30.745689 | controller | }
2025-12-05 15:38:30.980762 | controller | changed:
2025-12-05 15:38:30.980851 | controller | {
2025-12-05 15:38:30.980880 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-05 15:38:30.980903 | controller | }
2025-12-05 15:38:31.200571 | controller | changed:
2025-12-05 15:38:31.200659 | controller | {
2025-12-05 15:38:31.200688 | 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-12-05 15:38:31.200711 | controller | }
2025-12-05 15:38:31.429902 | controller | changed:
2025-12-05 15:38:31.430024 | controller | {
2025-12-05 15:38:31.430070 | 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-12-05 15:38:31.430096 | controller | }
2025-12-05 15:38:31.661276 | controller | changed:
2025-12-05 15:38:31.661359 | controller | {
2025-12-05 15:38:31.661387 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-05 15:38:31.661409 | controller | }
2025-12-05 15:38:31.872881 | controller | changed:
2025-12-05 15:38:31.872991 | controller | {
2025-12-05 15:38:31.873020 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-05 15:38:31.873052 | controller | }
2025-12-05 15:38:32.082390 | controller | changed:
2025-12-05 15:38:32.082473 | controller | {
2025-12-05 15:38:32.082502 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-05 15:38:32.082525 | controller | }
2025-12-05 15:38:32.328889 | controller | changed:
2025-12-05 15:38:32.329098 | controller | {
2025-12-05 15:38:32.329136 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-05 15:38:32.329224 | controller | }
2025-12-05 15:38:32.561257 | controller | changed:
2025-12-05 15:38:32.561335 | controller | {
2025-12-05 15:38:32.561362 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-05 15:38:32.561384 | controller | }
2025-12-05 15:38:32.782367 | controller | changed:
2025-12-05 15:38:32.782450 | controller | {
2025-12-05 15:38:32.782479 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-05 15:38:32.782502 | controller | }
2025-12-05 15:38:33.029206 | controller | changed:
2025-12-05 15:38:33.029298 | controller | {
2025-12-05 15:38:33.029325 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-05 15:38:33.029346 | controller | }
2025-12-05 15:38:33.263891 | controller | changed:
2025-12-05 15:38:33.263998 | controller | {
2025-12-05 15:38:33.264026 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-05 15:38:33.264058 | controller | }
2025-12-05 15:38:33.484861 | controller | changed:
2025-12-05 15:38:33.484953 | controller | {
2025-12-05 15:38:33.484982 | 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-12-05 15:38:33.485005 | controller | }
2025-12-05 15:38:33.699903 | controller | changed:
2025-12-05 15:38:33.700025 | controller | {
2025-12-05 15:38:33.700064 | 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-12-05 15:38:33.700095 | controller | }
2025-12-05 15:38:33.908696 | controller | changed:
2025-12-05 15:38:33.908797 | controller | {
2025-12-05 15:38:33.908827 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-05 15:38:33.908849 | controller | }
2025-12-05 15:38:34.119862 | controller | changed:
2025-12-05 15:38:34.119978 | controller | {
2025-12-05 15:38:34.120007 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-05 15:38:34.120028 | controller | }
2025-12-05 15:38:34.323614 | controller | changed:
2025-12-05 15:38:34.323716 | controller | {
2025-12-05 15:38:34.323750 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-05 15:38:34.323778 | controller | }
2025-12-05 15:38:34.530237 | controller | changed:
2025-12-05 15:38:34.530323 | controller | {
2025-12-05 15:38:34.530352 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-05 15:38:34.530374 | controller | }
2025-12-05 15:38:34.747797 | controller | changed:
2025-12-05 15:38:34.747882 | controller | {
2025-12-05 15:38:34.747928 | 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-12-05 15:38:34.747957 | controller | }
2025-12-05 15:38:34.963599 | controller | changed:
2025-12-05 15:38:34.963678 | controller | {
2025-12-05 15:38:34.963706 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-05 15:38:34.963726 | controller | }
2025-12-05 15:38:35.179777 | controller | changed:
2025-12-05 15:38:35.179858 | controller | {
2025-12-05 15:38:35.179885 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-05 15:38:35.179933 | controller | }
2025-12-05 15:38:35.414178 | controller | changed:
2025-12-05 15:38:35.414256 | controller | {
2025-12-05 15:38:35.414284 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-05 15:38:35.414305 | controller | }
2025-12-05 15:38:35.445935 | 
2025-12-05 15:38:35.446042 | TASK [Set timezone to UTC]
2025-12-05 15:38:35.971795 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-05 15:38:35.977145 | 
2025-12-05 15:38:35.977217 | TASK [Create nodepool directory]
2025-12-05 15:38:36.255019 | controller | changed
2025-12-05 15:38:36.264753 | 
2025-12-05 15:38:36.264821 | TASK [Create nodepool sub_nodes file]
2025-12-05 15:38:36.931077 | controller | changed
2025-12-05 15:38:36.941282 | 
2025-12-05 15:38:36.941399 | TASK [Create nodepool sub_nodes_private file]
2025-12-05 15:38:37.610875 | controller | changed
2025-12-05 15:38:37.616758 | 
2025-12-05 15:38:37.616818 | LOOP [Populate nodepool sub_nodes file]
2025-12-05 15:38:37.693521 | 
2025-12-05 15:38:37.693658 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-05 15:38:37.775733 | 
2025-12-05 15:38:37.775872 | TASK [Create nodepool primary file]
2025-12-05 15:38:37.811512 | controller | skipping: Conditional result was False
2025-12-05 15:38:37.818757 | 
2025-12-05 15:38:37.818833 | TASK [Create nodepool node_private for this node]
2025-12-05 15:38:38.527677 | controller | changed
2025-12-05 15:38:38.535105 | 
2025-12-05 15:38:38.535179 | LOOP [Copy ssh keys to nodepool directory]
2025-12-05 15:38:38.906562 | controller | ok: Item: id_rsa Runtime: 0:00:00.004710
2025-12-05 15:38:38.906730 | 
2025-12-05 15:38:39.077110 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005463
2025-12-05 15:38:39.086726 | 
2025-12-05 15:38:39.086829 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-05 15:38:39.731645 | controller | changed
2025-12-05 15:38:39.736946 | 
2025-12-05 15:38:39.737018 | TASK [Validate sudoers config after edits]
2025-12-05 15:38:40.109927 | controller | /etc/sudoers: parsed OK
2025-12-05 15:38:40.109994 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-05 15:38:40.110005 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-05 15:38:40.110011 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-05 15:38:40.298278 | controller | ok: Runtime: 0:00:00.004558
2025-12-05 15:38:40.303988 | 
2025-12-05 15:38:40.304068 | TASK [Show the environment passed in to job shell scripts]
2025-12-05 15:38:40.558437 | controller | SHELL=/bin/bash
2025-12-05 15:38:40.558497 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-05 15:38:40.558507 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-05 15:38:40.558513 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/39/3539/d69732b2a1625f2c30dfa731f6f764b0a55cbde9
2025-12-05 15:38:40.558519 | controller | PWD=/home/zuul
2025-12-05 15:38:40.558525 | controller | ZUUL_PIPELINE=github-check
2025-12-05 15:38:40.558531 | controller | LOGNAME=zuul
2025-12-05 15:38:40.558536 | controller | XDG_SESSION_TYPE=tty
2025-12-05 15:38:40.558542 | controller | _=/usr/bin/env
2025-12-05 15:38:40.558589 | controller | MOTD_SHOWN=pam
2025-12-05 15:38:40.558597 | controller | HOME=/home/zuul
2025-12-05 15:38:40.558603 | controller | LANG=en_US.UTF-8
2025-12-05 15:38:40.558608 | controller | SSH_CONNECTION=192.168.26.12 42864 192.168.26.4 22
2025-12-05 15:38:40.558614 | 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-12-05 15:38:40.558623 | controller | ZUUL_CHANGE_IDS=3539,d69732b2a1625f2c30dfa731f6f764b0a55cbde9
2025-12-05 15:38:40.558628 | controller | WORKSPACE=/home/zuul/workspace
2025-12-05 15:38:40.558634 | controller | XDG_SESSION_CLASS=user
2025-12-05 15:38:40.558639 | controller | SELINUX_ROLE_REQUESTED=
2025-12-05 15:38:40.558645 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-05 15:38:40.558650 | controller | USER=zuul
2025-12-05 15:38:40.558655 | controller | ZUUL_VOTING=True
2025-12-05 15:38:40.558661 | controller | BUILD_TIMEOUT=1800000
2025-12-05 15:38:40.558666 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-05 15:38:40.558671 | controller | SHLVL=1
2025-12-05 15:38:40.558677 | controller | ZUUL_PATCHSET=d69732b2a1625f2c30dfa731f6f764b0a55cbde9
2025-12-05 15:38:40.558682 | controller | XDG_SESSION_ID=1
2025-12-05 15:38:40.558688 | controller | ZUUL_BRANCH=main
2025-12-05 15:38:40.558693 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-05 15:38:40.558698 | controller | SSH_CLIENT=192.168.26.12 42864 22
2025-12-05 15:38:40.558705 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-05 15:38:40.558711 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-05 15:38:40.558718 | controller | which_declare=declare -f
2025-12-05 15:38:40.558724 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-05 15:38:40.558730 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-05 15:38:40.558735 | controller | ZUUL_CHANGE=3539
2025-12-05 15:38:40.558741 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-05 15:38:40.558746 | controller | ZUUL_UUID=e00d156684a04d6b9c123eb06f4011ec
2025-12-05 15:38:40.558752 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-05 15:38:40.558757 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-05 15:38:40.558763 | controller | }
2025-12-05 15:38:40.871571 | controller | ok: Runtime: 0:00:00.005758
2025-12-05 15:38:40.877014 | 
2025-12-05 15:38:40.877090 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-05 15:38:40.900821 | controller | skipping: Conditional result was False
2025-12-05 15:38:40.906133 | 
2025-12-05 15:38:40.906199 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-05 15:38:41.458291 | controller | skipping: Conditional result was False
2025-12-05 15:38:41.463975 | 
2025-12-05 15:38:41.464042 | TASK [Ensure legacy workspace directory]
2025-12-05 15:38:41.715514 | controller | changed
2025-12-05 15:38:41.823355 | 
2025-12-05 15:38:41.823459 | PLAY RECAP
2025-12-05 15:38:41.823508 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 15:38:41.823532 | 
2025-12-05 15:38:41.916735 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-05 15:38:41.917516 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-05 15:38:43.483360 | 
2025-12-05 15:38:43.483462 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-05 15:38:43.513472 | 
2025-12-05 15:38:43.513548 | TASK [Create zuul-output directory]
2025-12-05 15:38:43.887651 | controller | changed
2025-12-05 15:38:43.892679 | 
2025-12-05 15:38:43.892752 | TASK [Slurp Zuul inventory test]
2025-12-05 15:38:44.727434 | controller -> localhost | ok
2025-12-05 15:38:44.740225 | 
2025-12-05 15:38:44.740300 | TASK [Save zuul inventory]
2025-12-05 15:38:45.788492 | controller | changed
2025-12-05 15:38:45.797544 | 
2025-12-05 15:38:45.797631 | TASK [Save zuul vars without the change_message]
2025-12-05 15:38:46.481731 | controller | changed
2025-12-05 15:38:46.533028 | 
2025-12-05 15:38:46.533129 | PLAY RECAP
2025-12-05 15:38:46.533178 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 15:38:46.533203 | 
2025-12-05 15:38:46.655406 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-05 15:38:46.656227 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-05 15:38:47.260933 | 
2025-12-05 15:38:47.261102 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-05 15:38:47.282390 | 
2025-12-05 15:38:47.282462 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-05 15:38:47.311200 | controller | ok
2025-12-05 15:38:47.328035 | 
2025-12-05 15:38:47.328125 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-05 15:38:47.351926 | controller | skipping: Conditional result was False
2025-12-05 15:38:47.362472 | 
2025-12-05 15:38:47.362542 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-05 15:38:47.745058 | controller | ok
2025-12-05 15:38:47.753055 | 
2025-12-05 15:38:47.753142 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-05 15:38:48.477027 | controller | ok
2025-12-05 15:38:48.502454 | 
2025-12-05 15:38:48.502551 | TASK [Prepare workspace]
2025-12-05 15:38:48.546229 | controller | ok
2025-12-05 15:38:48.579542 | 
2025-12-05 15:38:48.579628 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-05 15:38:49.001575 | controller | ok
2025-12-05 15:38:49.009397 | 
2025-12-05 15:38:49.009468 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-05 15:38:52.283876 | controller | Output suppressed because no_log was given
2025-12-05 15:38:52.297040 | 
2025-12-05 15:38:52.297144 | LOOP [Create zuul-output directory]
2025-12-05 15:38:52.494989 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-05 15:38:52.659781 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-05 15:38:52.666184 | 
2025-12-05 15:38:52.666250 | TASK [Install required packages]
2025-12-05 15:39:41.959632 | controller | changed
2025-12-05 15:39:41.971483 | 
2025-12-05 15:39:41.971574 | TASK [Install venv]
2025-12-05 15:40:14.574604 | controller | ERROR
2025-12-05 15:40:14.574788 | controller | {
2025-12-05 15:40:14.574822 | controller |   "msg": "gmake: *** [Makefile:60: setup_molecule] Error 1",
2025-12-05 15:40:14.574845 | controller |   "rc": 2
2025-12-05 15:40:14.574865 | controller | }
failure
2025-12-05 15:40:14.586884 | 
2025-12-05 15:40:14.586980 | PLAY RECAP
2025-12-05 15:40:14.587028 | controller | ok: 6 changed: 3 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-12-05 15:40:14.587053 | 
2025-12-05 15:40:14.743245 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-05 15:40:14.744652 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-05 15:40:15.546163 | 
2025-12-05 15:40:15.546281 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-05 15:40:15.581620 | 
2025-12-05 15:40:15.581734 | TASK [Filter out host if needed]
2025-12-05 15:40:15.597165 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-05 15:40:15.614400 | 
2025-12-05 15:40:15.614480 | TASK [Ensure file is present]
2025-12-05 15:40:16.058986 | controller | ok
2025-12-05 15:40:16.065633 | 
2025-12-05 15:40:16.065703 | TASK [Manage molecule report file]
2025-12-05 15:40:16.643022 | controller | skipping: Conditional result was False
2025-12-05 15:40:16.649304 | 
2025-12-05 15:40:16.649380 | TASK [Check if we get ci-framework-data basedir]
2025-12-05 15:40:16.911329 | controller | ok
2025-12-05 15:40:16.935472 | 
2025-12-05 15:40:16.935577 | TASK [Create ci-framework-data log directory for zuul]
2025-12-05 15:40:17.707403 | controller | changed
2025-12-05 15:40:17.720804 | 
2025-12-05 15:40:17.720893 | TASK [Copy ci-framework interesting files]
2025-12-05 15:40:18.529243 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-12-05 15:40:18.852757 | controller | changed
2025-12-05 15:40:18.858963 | 
2025-12-05 15:40:18.859043 | TASK [Get SELinux listing]
2025-12-05 15:40:19.449768 | controller | changed
2025-12-05 15:40:19.456018 | 
2025-12-05 15:40:19.456100 | TASK [Generate log index]
2025-12-05 15:40:20.363757 | controller | changed
2025-12-05 15:40:20.370460 | 
2025-12-05 15:40:20.370526 | TASK [Get some env related data]
2025-12-05 15:40:21.124480 | controller | /home/zuul/.local/bin/ansible
2025-12-05 15:40:21.993496 | controller | changed
2025-12-05 15:40:21.999527 | 
2025-12-05 15:40:21.999596 | TASK [Generate list of logs to collect in home directory]
2025-12-05 15:40:22.559515 | controller | ok: All paths examined
2025-12-05 15:40:22.736577 | 
2025-12-05 15:40:22.736678 | LOOP [Copy logs from home directory]
2025-12-05 15:40:22.796319 | 
2025-12-05 15:40:22.796479 | TASK [Copy crio stats log file]
2025-12-05 15:40:22.830278 | controller | skipping: Conditional result was False
2025-12-05 15:40:22.836962 | 
2025-12-05 15:40:22.837046 | TASK [Get SELinux related data]
2025-12-05 15:40:23.067540 | controller | <no matches>
2025-12-05 15:40:23.392736 | controller | ERROR
2025-12-05 15:40:23.392853 | controller | {
2025-12-05 15:40:23.392885 | controller |   "delta": "0:00:00.010200",
2025-12-05 15:40:23.392939 | controller |   "end": "2025-12-05 15:40:23.069804",
2025-12-05 15:40:23.392970 | controller |   "msg": "non-zero return code",
2025-12-05 15:40:23.392992 | controller |   "rc": 1,
2025-12-05 15:40:23.393013 | controller |   "start": "2025-12-05 15:40:23.059604"
2025-12-05 15:40:23.393031 | controller | }
2025-12-05 15:40:23.393056 | controller | ERROR: Ignoring Errors
2025-12-05 15:40:23.398889 | 
2025-12-05 15:40:23.398973 | TASK [Create system configuration directory]
2025-12-05 15:40:23.621644 | controller | changed
2025-12-05 15:40:23.630313 | 
2025-12-05 15:40:23.630406 | TASK [Get some of the system configurations]
2025-12-05 15:40:24.174520 | controller | changed
2025-12-05 15:40:24.179804 | 
2025-12-05 15:40:24.180033 | TASK [Copy generated documentation if available]
2025-12-05 15:40:24.223824 | controller | skipping: Conditional result was False
2025-12-05 15:40:24.229330 | 
2025-12-05 15:40:24.229397 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-05 15:40:24.263881 | controller | skipping: Conditional result was False
2025-12-05 15:40:24.270035 | 
2025-12-05 15:40:24.270110 | TASK [Compress logs bigger than 2MB]
2025-12-05 15:40:24.845543 | controller | changed
2025-12-05 15:40:24.852593 | 
2025-12-05 15:40:24.852684 | TASK [Copy files from workspace on node]
2025-12-05 15:40:24.929436 | controller | ok
2025-12-05 15:40:24.988475 | 
2025-12-05 15:40:24.988582 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 15:40:25.029404 | controller | skipping: Conditional result was False
2025-12-05 15:40:25.035067 | 
2025-12-05 15:40:25.035146 | TASK [fetch-output : Set log path for single node]
2025-12-05 15:40:25.095726 | controller | ok
2025-12-05 15:40:25.101540 | 
2025-12-05 15:40:25.101606 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 15:40:25.888889 | controller -> localhost | ok: "/var/lib/zuul/builds/e00d156684a04d6b9c123eb06f4011ec/work/logs"
2025-12-05 15:40:25.889151 | 
2025-12-05 15:40:26.605308 | controller -> localhost | changed: "/var/lib/zuul/builds/e00d156684a04d6b9c123eb06f4011ec/work/artifacts"
2025-12-05 15:40:27.215662 | controller -> localhost | changed: "/var/lib/zuul/builds/e00d156684a04d6b9c123eb06f4011ec/work/docs"
2025-12-05 15:40:27.223884 | 
2025-12-05 15:40:27.223976 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 15:40:28.624193 | controller | changed:
2025-12-05 15:40:28.624587 | controller | .d..t...... ./
2025-12-05 15:40:28.624619 | controller | >f+++++++++ README.html
2025-12-05 15:40:28.624643 | controller | >f+++++++++ dmesg.log
2025-12-05 15:40:28.624664 | controller | >f+++++++++ installed-pkgs.log
2025-12-05 15:40:28.624683 | controller | >f+++++++++ python.log
2025-12-05 15:40:28.624701 | controller | >f+++++++++ registries.conf
2025-12-05 15:40:28.624719 | controller | >f+++++++++ selinux-denials.log
2025-12-05 15:40:28.624735 | controller | >f+++++++++ selinux-listing.log
2025-12-05 15:40:28.624753 | controller | cd+++++++++ ci-framework-data/
2025-12-05 15:40:28.624770 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-05 15:40:28.624787 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-05 15:40:28.624805 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-05 15:40:28.624823 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-05 15:40:28.624841 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-05 15:40:28.624859 | controller | cd+++++++++ registries.conf.d/
2025-12-05 15:40:28.624877 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-05 15:40:28.624894 | controller | cd+++++++++ system-config/
2025-12-05 15:40:28.624933 | controller | cd+++++++++ system-config/libvirt/
2025-12-05 15:40:28.624956 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-05 15:40:28.624976 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-05 15:40:29.394797 | controller | changed: .d..t...... ./
2025-12-05 15:40:30.428728 | controller | changed: .d..t...... ./
2025-12-05 15:40:30.458004 | 
2025-12-05 15:40:30.458087 | TASK [Return artifact to Zuul]
2025-12-05 15:40:30.536183 | controller | skipping: Conditional result was False
2025-12-05 15:40:30.597056 | 
2025-12-05 15:40:30.597137 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-05 15:40:30.597256 | 
2025-12-05 15:40:30.597286 | PLAY RECAP
2025-12-05 15:40:30.597324 | controller | ok: 15 changed: 11 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 1
2025-12-05 15:40:30.597347 | 
2025-12-05 15:40:30.855899 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-05 15:40:30.856666 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 15:40:32.431074 | 
2025-12-05 15:40:32.431180 | PLAY [all]
2025-12-05 15:40:32.477977 | 
2025-12-05 15:40:32.478054 | TASK [include_role : fetch-output]
2025-12-05 15:40:32.517452 | controller | ok
2025-12-05 15:40:32.576540 | 
2025-12-05 15:40:32.576660 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 15:40:32.765595 | controller | skipping: Conditional result was False
2025-12-05 15:40:32.772989 | 
2025-12-05 15:40:32.773068 | TASK [fetch-output : Set log path for single node]
2025-12-05 15:40:32.903016 | controller | ok
2025-12-05 15:40:32.908103 | 
2025-12-05 15:40:32.908186 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 15:40:34.258720 | controller -> localhost | ok: "/var/lib/zuul/builds/e00d156684a04d6b9c123eb06f4011ec/work/logs"
2025-12-05 15:40:34.682610 | controller -> localhost | ok: "/var/lib/zuul/builds/e00d156684a04d6b9c123eb06f4011ec/work/artifacts"
2025-12-05 15:40:35.340431 | controller -> localhost | ok: "/var/lib/zuul/builds/e00d156684a04d6b9c123eb06f4011ec/work/docs"
2025-12-05 15:40:35.356622 | 
2025-12-05 15:40:35.356726 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 15:40:36.997854 | controller | ok
2025-12-05 15:40:36.998074 | controller | ok: All items complete
2025-12-05 15:40:36.998107 | 
2025-12-05 15:40:37.975236 | controller | ok
2025-12-05 15:40:39.000677 | controller | ok
2025-12-05 15:40:39.021365 | 
2025-12-05 15:40:39.021476 | TASK [include_role : fetch-output-openshift]
2025-12-05 15:40:39.107409 | controller | skipping: Conditional result was False
2025-12-05 15:40:39.113459 | 
2025-12-05 15:40:39.113535 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-05 15:40:40.909069 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.053694
2025-12-05 15:40:41.916409 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.038642
2025-12-05 15:40:42.024725 | 
2025-12-05 15:40:42.024834 | PLAY [all]
2025-12-05 15:40:42.085559 | 
2025-12-05 15:40:42.085644 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-05 15:40:43.283110 | controller | changed
2025-12-05 15:40:43.528041 | 
2025-12-05 15:40:43.528123 | PLAY RECAP
2025-12-05 15:40:43.528171 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 15:40:43.528195 | 
2025-12-05 15:40:43.928259 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 15:40:43.929011 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-05 15:40:47.367105 | 
2025-12-05 15:40:47.367221 | PLAY [localhost]
2025-12-05 15:40:47.451445 | 
2025-12-05 15:40:47.451534 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-05 15:40:48.833377 | localhost | changed
2025-12-05 15:40:48.838280 | 
2025-12-05 15:40:48.838400 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-05 15:40:48.910695 | localhost | ok
2025-12-05 15:40:48.925506 | 
2025-12-05 15:40:48.925584 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-05 15:40:50.076446 | localhost | changed
2025-12-05 15:40:50.081262 | 
2025-12-05 15:40:50.081334 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-05 15:40:52.992856 | localhost | changed
2025-12-05 15:40:53.006932 | 
2025-12-05 15:40:53.007023 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-05 15:40:54.063018 | localhost | Identity added: /var/lib/zuul/builds/e00d156684a04d6b9c123eb06f4011ec/work/tmp/ansible.yo3dg7dx (/var/lib/zuul/builds/e00d156684a04d6b9c123eb06f4011ec/work/tmp/ansible.yo3dg7dx)
2025-12-05 15:40:54.063174 | localhost | ok: Runtime: 0:00:00.060468
2025-12-05 15:40:54.068205 | 
2025-12-05 15:40:54.068289 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-05 15:40:55.047985 | localhost | ok: Runtime: 0:00:00.031496
2025-12-05 15:40:55.052610 | 
2025-12-05 15:40:55.052681 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-05 15:40:55.225890 | localhost | changed
2025-12-05 15:40:55.230482 | 
2025-12-05 15:40:55.230554 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-05 15:40:56.550701 | localhost | changed
2025-12-05 15:40:56.666664 | 
2025-12-05 15:40:56.666755 | PLAY [localhost]
2025-12-05 15:40:56.691933 | 
2025-12-05 15:40:56.692012 | TASK [Generate bulk log download script]
2025-12-05 15:40:56.768158 | localhost | ok
2025-12-05 15:40:56.825708 | 
2025-12-05 15:40:56.825806 | TASK [local-log-download : Check API endpoint is defined]
2025-12-05 15:40:56.943940 | localhost | ok: All assertions passed
2025-12-05 15:40:56.948447 | 
2025-12-05 15:40:56.948512 | TASK [local-log-download : Create download script]
2025-12-05 15:40:58.881235 | localhost -> localhost | changed
2025-12-05 15:40:58.890417 | 
2025-12-05 15:40:58.890492 | TASK [Register quick-download link]
2025-12-05 15:40:58.946598 | localhost | ok
2025-12-05 15:40:59.025500 | 
2025-12-05 15:40:59.025574 | PLAY [logserver.rdoproject.org]
2025-12-05 15:40:59.091198 | 
2025-12-05 15:40:59.091296 | TASK [Set zuul-log-path fact]
2025-12-05 15:40:59.157297 | logserver.rdoproject.org | ok
2025-12-05 15:40:59.174187 | 
2025-12-05 15:40:59.174291 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 15:40:59.235787 | logserver.rdoproject.org | ok
2025-12-05 15:40:59.241577 | 
2025-12-05 15:40:59.241655 | TASK [upload-logs : Create log directories]
2025-12-05 15:41:00.959023 | logserver.rdoproject.org | changed
2025-12-05 15:41:00.963039 | 
2025-12-05 15:41:00.963109 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-05 15:41:01.884195 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.023031
2025-12-05 15:41:01.889210 | 
2025-12-05 15:41:01.889303 | TASK [upload-logs : Upload logs to log server]
2025-12-05 15:41:03.726779 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-05 15:41:03.729903 | 
2025-12-05 15:41:03.729987 | LOOP [upload-logs : Compress console log and json output]
2025-12-05 15:41:03.875287 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:41:03.882477 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:41:03.890548 | 
2025-12-05 15:41:03.890655 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-05 15:41:04.038643 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:41:04.038956 | 
2025-12-05 15:41:04.042795 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:41:04.052996 | 
2025-12-05 15:41:04.053107 | LOOP [upload-logs : Upload console log and json output]