2025-12-05 15:48:57.797511 | Job console starting...
2025-12-05 15:48:58.009104 | Updating repositories
2025-12-05 15:48:58.791066 | Preparing job workspace
2025-12-05 15:49:08.580792 | Running Ansible setup...
2025-12-05 15:49:13.899033 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 15:49:14.702582 | 
2025-12-05 15:49:14.702678 | PLAY [localhost]
2025-12-05 15:49:14.710969 | 
2025-12-05 15:49:14.711054 | TASK [Gathering Facts]
2025-12-05 15:49:15.896201 | localhost | ok
2025-12-05 15:49:15.912407 | 
2025-12-05 15:49:15.912488 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-05 15:49:16.317190 | localhost -> localhost | changed
2025-12-05 15:49:16.322977 | 
2025-12-05 15:49:16.323062 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-05 15:49:17.465618 | localhost -> localhost | changed
2025-12-05 15:49:17.473894 | 
2025-12-05 15:49:17.474010 | TASK [Setup log path fact]
2025-12-05 15:49:17.511113 | localhost | ok
2025-12-05 15:49:17.535853 | 
2025-12-05 15:49:17.535945 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 15:49:17.563966 | localhost | ok
2025-12-05 15:49:17.573568 | 
2025-12-05 15:49:17.573641 | TASK [emit-job-header : Print job information]
2025-12-05 15:49:17.610705 | # Job Information
2025-12-05 15:49:17.610830 | Ansible Version: 2.15.12
2025-12-05 15:49:17.610861 | Job: cifmw-molecule-sushy_emulator
2025-12-05 15:49:17.610885 | Pipeline: github-check
2025-12-05 15:49:17.610906 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-12-05 15:49:17.611013 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3539
2025-12-05 15:49:17.611042 | Log URL (when completed): https://logserver.rdoproject.org/913/rdoproject.org/91358828b4e34fffb887d2f11613b7d3/
2025-12-05 15:49:17.611064 | Event ID: 757b9e30-d1ef-11f0-97eb-f505e6ea5a00
2025-12-05 15:49:17.615691 | 
2025-12-05 15:49:17.615758 | LOOP [emit-job-header : Print node information]
2025-12-05 15:49:17.719598 | localhost | ok:
2025-12-05 15:49:17.719759 | localhost | # Node Information
2025-12-05 15:49:17.719793 | localhost | Inventory Hostname: controller
2025-12-05 15:49:17.719818 | localhost | Hostname: np0005547647
2025-12-05 15:49:17.719838 | localhost | Username: zuul
2025-12-05 15:49:17.719860 | localhost | Distro: CentOS 9
2025-12-05 15:49:17.719880 | localhost | Provider: ibm-bm4-nodepool
2025-12-05 15:49:17.719900 | localhost | Region: regionOne
2025-12-05 15:49:17.719948 | localhost | Label: centos-9-stream-crc-2-48-0-xl-ibm
2025-12-05 15:49:17.719973 | localhost | Product Name: OpenStack Compute
2025-12-05 15:49:17.719992 | localhost | Interface IP: 192.168.26.73
2025-12-05 15:49:17.740065 | 
2025-12-05 15:49:17.740159 | PLAY [all]
2025-12-05 15:49:17.746539 | 
2025-12-05 15:49:17.746615 | TASK [Gather network facts]
2025-12-05 15:49:18.162236 | controller | ok
2025-12-05 15:49:18.177398 | 
2025-12-05 15:49:18.177477 | TASK [include_role : start-zuul-console]
2025-12-05 15:49:18.194957 | controller | ok
2025-12-05 15:49:18.206032 | 
2025-12-05 15:49:18.206097 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-05 15:49:18.578243 | controller | ok
2025-12-05 15:49:18.591928 | 
2025-12-05 15:49:18.592015 | TASK [include_role : add-build-sshkey]
2025-12-05 15:49:18.630553 | controller | ok
2025-12-05 15:49:18.658064 | 
2025-12-05 15:49:18.658142 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-05 15:49:19.169784 | controller -> localhost | ok
2025-12-05 15:49:19.175504 | 
2025-12-05 15:49:19.175567 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-05 15:49:19.223214 | controller | ok
2025-12-05 15:49:19.247176 | controller | included: /var/lib/zuul/builds/91358828b4e34fffb887d2f11613b7d3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-05 15:49:19.259075 | 
2025-12-05 15:49:19.259138 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-05 15:49:19.957816 | controller -> localhost | Generating public/private rsa key pair.
2025-12-05 15:49:19.958023 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/91358828b4e34fffb887d2f11613b7d3/work/91358828b4e34fffb887d2f11613b7d3_id_rsa.
2025-12-05 15:49:19.958060 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/91358828b4e34fffb887d2f11613b7d3/work/91358828b4e34fffb887d2f11613b7d3_id_rsa.pub.
2025-12-05 15:49:19.958086 | controller -> localhost | The key fingerprint is:
2025-12-05 15:49:19.958109 | controller -> localhost | SHA256:aRRx0EkzxkFe5F6UmaosQwwEHJLb6PfwcsZLd27bHYA zuul-build-sshkey
2025-12-05 15:49:19.958130 | controller -> localhost | The key's randomart image is:
2025-12-05 15:49:19.958149 | controller -> localhost | +---[RSA 3072]----+
2025-12-05 15:49:19.958168 | controller -> localhost | |  .oo+. +BO+o .+ |
2025-12-05 15:49:19.958185 | controller -> localhost | |  ... .  =+= .+  |
2025-12-05 15:49:19.958222 | controller -> localhost | |   +   o. . ...  |
2025-12-05 15:49:19.958244 | controller -> localhost | |  o .  .o. o..   |
2025-12-05 15:49:19.958262 | controller -> localhost | | .     .S.E.o    |
2025-12-05 15:49:19.958279 | controller -> localhost | |  . o  .o o  .   |
2025-12-05 15:49:19.958295 | controller -> localhost | |   . =. .o.   .  |
2025-12-05 15:49:19.958311 | controller -> localhost | |    ..*. o.. . . |
2025-12-05 15:49:19.958329 | controller -> localhost | |     +.. .o.. .  |
2025-12-05 15:49:19.958346 | controller -> localhost | +----[SHA256]-----+
2025-12-05 15:49:19.958390 | controller -> localhost | ok: Runtime: 0:00:00.175130
2025-12-05 15:49:19.964117 | 
2025-12-05 15:49:19.964182 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-05 15:49:20.023720 | controller | ok
2025-12-05 15:49:20.047506 | controller | included: /var/lib/zuul/builds/91358828b4e34fffb887d2f11613b7d3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-05 15:49:20.067225 | 
2025-12-05 15:49:20.067309 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-05 15:49:20.085502 | controller | skipping: Conditional result was False
2025-12-05 15:49:20.091564 | 
2025-12-05 15:49:20.091631 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-05 15:49:20.652000 | controller | changed
2025-12-05 15:49:20.657148 | 
2025-12-05 15:49:20.657212 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-05 15:49:20.944571 | controller | ok
2025-12-05 15:49:20.949464 | 
2025-12-05 15:49:20.949528 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-05 15:49:22.001277 | controller | changed
2025-12-05 15:49:22.006284 | 
2025-12-05 15:49:22.006350 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-05 15:49:23.155119 | controller | changed
2025-12-05 15:49:23.162172 | 
2025-12-05 15:49:23.162238 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-05 15:49:23.207576 | controller | skipping: Conditional result was False
2025-12-05 15:49:23.213643 | 
2025-12-05 15:49:23.213707 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-05 15:49:23.683304 | controller -> localhost | changed
2025-12-05 15:49:23.698680 | 
2025-12-05 15:49:23.698751 | TASK [add-build-sshkey : Add back temp key]
2025-12-05 15:49:24.318850 | controller -> localhost | Identity added: /var/lib/zuul/builds/91358828b4e34fffb887d2f11613b7d3/work/91358828b4e34fffb887d2f11613b7d3_id_rsa (zuul-build-sshkey)
2025-12-05 15:49:24.319055 | controller -> localhost | ok: Runtime: 0:00:00.024556
2025-12-05 15:49:24.324782 | 
2025-12-05 15:49:24.324845 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-05 15:49:24.849183 | controller | ok
2025-12-05 15:49:24.853851 | 
2025-12-05 15:49:24.853943 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-05 15:49:24.889690 | controller | skipping: Conditional result was False
2025-12-05 15:49:24.898891 | 
2025-12-05 15:49:24.898982 | TASK [include_role : validate-host]
2025-12-05 15:49:24.916499 | controller | ok
2025-12-05 15:49:24.952772 | 
2025-12-05 15:49:24.952850 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-05 15:49:24.990747 | controller | ok
2025-12-05 15:49:24.995786 | 
2025-12-05 15:49:24.995847 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-05 15:49:25.256006 | controller -> localhost | ok
2025-12-05 15:49:25.261648 | 
2025-12-05 15:49:25.261720 | TASK [validate-host : Collect information about the host]
2025-12-05 15:49:25.937122 | controller | ok
2025-12-05 15:49:25.962595 | 
2025-12-05 15:49:25.962676 | TASK [validate-host : Sanitize hostname]
2025-12-05 15:49:26.010833 | controller | ok
2025-12-05 15:49:26.020774 | 
2025-12-05 15:49:26.020839 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-05 15:49:26.799235 | controller -> localhost | changed
2025-12-05 15:49:26.806119 | 
2025-12-05 15:49:26.806186 | TASK [validate-host : Collect information about zuul worker]
2025-12-05 15:49:27.495211 | controller | ok
2025-12-05 15:49:27.501124 | 
2025-12-05 15:49:27.501194 | TASK [validate-host : Write out all zuul information for each host]
2025-12-05 15:49:28.659544 | controller -> localhost | changed
2025-12-05 15:49:28.670002 | 
2025-12-05 15:49:28.670079 | TASK [include_role : prepare-workspace-openshift]
2025-12-05 15:49:28.736159 | controller | skipping: Conditional result was False
2025-12-05 15:49:28.745988 | 
2025-12-05 15:49:28.746067 | TASK [include_role : remove-zuul-sshkey]
2025-12-05 15:49:28.793292 | controller | skipping: Conditional result was False
2025-12-05 15:49:28.798789 | 
2025-12-05 15:49:28.798859 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 15:49:29.123799 | controller | ok: "logs"
2025-12-05 15:49:29.124038 | controller | ok: All items complete
2025-12-05 15:49:29.124074 | 
2025-12-05 15:49:29.370637 | controller | ok: "artifacts"
2025-12-05 15:49:29.593005 | controller | ok: "docs"
2025-12-05 15:49:29.608425 | 
2025-12-05 15:49:29.608525 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 15:49:29.887191 | controller | changed: "logs"
2025-12-05 15:49:30.117357 | controller | changed: "artifacts"
2025-12-05 15:49:30.361201 | controller | changed: "docs"
2025-12-05 15:49:30.407089 | 
2025-12-05 15:49:30.407181 | PLAY RECAP
2025-12-05 15:49:30.407228 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 15:49:30.407258 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 15:49:30.407279 | 
2025-12-05 15:49:30.571706 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 15:49:30.572489 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-05 15:49:31.340015 | 
2025-12-05 15:49:31.340126 | PLAY [all]
2025-12-05 15:49:31.360700 | 
2025-12-05 15:49:31.360777 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-05 15:49:31.409478 | controller | ok
2025-12-05 15:49:31.415189 | 
2025-12-05 15:49:31.415269 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-05 15:49:31.834002 | controller | changed
2025-12-05 15:49:31.844607 | 
2025-12-05 15:49:31.844698 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-05 15:49:33.258734 | controller | changed
2025-12-05 15:49:33.272876 | 
2025-12-05 15:49:33.279156 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-05 15:49:33.825825 | controller | changed:
2025-12-05 15:49:33.826294 | controller | {
2025-12-05 15:49:33.826337 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-05 15:49:33.826369 | controller | }
2025-12-05 15:49:34.109966 | controller | changed:
2025-12-05 15:49:34.110079 | controller | {
2025-12-05 15:49:34.110109 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-05 15:49:34.110132 | controller | }
2025-12-05 15:49:34.429466 | controller | changed:
2025-12-05 15:49:34.429549 | controller | {
2025-12-05 15:49:34.429577 | 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:49:34.429600 | controller | }
2025-12-05 15:49:34.727757 | controller | changed:
2025-12-05 15:49:34.727828 | controller | {
2025-12-05 15:49:34.727855 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-05 15:49:34.727876 | controller | }
2025-12-05 15:49:35.024856 | controller | changed:
2025-12-05 15:49:35.024957 | controller | {
2025-12-05 15:49:35.024987 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-05 15:49:35.025008 | controller | }
2025-12-05 15:49:35.328020 | controller | changed:
2025-12-05 15:49:35.328115 | controller | {
2025-12-05 15:49:35.328143 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-05 15:49:35.328165 | controller | }
2025-12-05 15:49:35.608234 | controller | changed:
2025-12-05 15:49:35.608365 | controller | {
2025-12-05 15:49:35.608397 | 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:49:35.608420 | controller | }
2025-12-05 15:49:35.884243 | controller | changed:
2025-12-05 15:49:35.884318 | controller | {
2025-12-05 15:49:35.884348 | 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:49:35.884371 | controller | }
2025-12-05 15:49:36.165052 | controller | changed:
2025-12-05 15:49:36.165216 | controller | {
2025-12-05 15:49:36.165253 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-05 15:49:36.165277 | controller | }
2025-12-05 15:49:36.467524 | controller | changed:
2025-12-05 15:49:36.467610 | controller | {
2025-12-05 15:49:36.467638 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-05 15:49:36.467660 | controller | }
2025-12-05 15:49:36.726676 | controller | changed:
2025-12-05 15:49:36.726770 | controller | {
2025-12-05 15:49:36.726798 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-05 15:49:36.726819 | controller | }
2025-12-05 15:49:37.003240 | controller | changed:
2025-12-05 15:49:37.003318 | controller | {
2025-12-05 15:49:37.003347 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-05 15:49:37.003369 | controller | }
2025-12-05 15:49:37.275247 | controller | changed:
2025-12-05 15:49:37.275323 | controller | {
2025-12-05 15:49:37.275349 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-05 15:49:37.275371 | controller | }
2025-12-05 15:49:37.552567 | controller | changed:
2025-12-05 15:49:37.552658 | controller | {
2025-12-05 15:49:37.552684 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-05 15:49:37.552704 | controller | }
2025-12-05 15:49:37.838776 | controller | changed:
2025-12-05 15:49:37.838855 | controller | {
2025-12-05 15:49:37.838884 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-05 15:49:37.838935 | controller | }
2025-12-05 15:49:38.168504 | controller | changed:
2025-12-05 15:49:38.168581 | controller | {
2025-12-05 15:49:38.168608 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-05 15:49:38.168630 | controller | }
2025-12-05 15:49:38.466299 | controller | changed:
2025-12-05 15:49:38.466397 | controller | {
2025-12-05 15:49:38.466425 | 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:49:38.466449 | controller | }
2025-12-05 15:49:38.792564 | controller | changed:
2025-12-05 15:49:38.792648 | controller | {
2025-12-05 15:49:38.792676 | 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:49:38.792705 | controller | }
2025-12-05 15:49:39.101352 | controller | changed:
2025-12-05 15:49:39.101444 | controller | {
2025-12-05 15:49:39.101472 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-05 15:49:39.101493 | controller | }
2025-12-05 15:49:39.437329 | controller | changed:
2025-12-05 15:49:39.437403 | controller | {
2025-12-05 15:49:39.437430 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-05 15:49:39.437453 | controller | }
2025-12-05 15:49:39.731166 | controller | changed:
2025-12-05 15:49:39.731323 | controller | {
2025-12-05 15:49:39.731358 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-05 15:49:39.731381 | controller | }
2025-12-05 15:49:40.007338 | controller | changed:
2025-12-05 15:49:40.007421 | controller | {
2025-12-05 15:49:40.007447 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-05 15:49:40.007468 | controller | }
2025-12-05 15:49:40.299863 | controller | changed:
2025-12-05 15:49:40.299982 | controller | {
2025-12-05 15:49:40.300012 | 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:49:40.300034 | controller | }
2025-12-05 15:49:40.582871 | controller | changed:
2025-12-05 15:49:40.582994 | controller | {
2025-12-05 15:49:40.583022 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-05 15:49:40.583053 | controller | }
2025-12-05 15:49:40.866289 | controller | changed:
2025-12-05 15:49:40.866700 | controller | {
2025-12-05 15:49:40.866746 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-05 15:49:40.866772 | controller | }
2025-12-05 15:49:41.160788 | controller | changed:
2025-12-05 15:49:41.160863 | controller | {
2025-12-05 15:49:41.160889 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-05 15:49:41.160941 | controller | }
2025-12-05 15:49:41.183093 | 
2025-12-05 15:49:41.183192 | TASK [Set timezone to UTC]
2025-12-05 15:49:41.705361 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-05 15:49:41.714767 | 
2025-12-05 15:49:41.714858 | TASK [Create nodepool directory]
2025-12-05 15:49:42.005308 | controller | changed
2025-12-05 15:49:42.012485 | 
2025-12-05 15:49:42.012549 | TASK [Create nodepool sub_nodes file]
2025-12-05 15:49:42.985484 | controller | changed
2025-12-05 15:49:42.990587 | 
2025-12-05 15:49:42.990662 | TASK [Create nodepool sub_nodes_private file]
2025-12-05 15:49:44.047190 | controller | changed
2025-12-05 15:49:44.056869 | 
2025-12-05 15:49:44.056968 | LOOP [Populate nodepool sub_nodes file]
2025-12-05 15:49:44.087168 | 
2025-12-05 15:49:44.087320 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-05 15:49:44.125881 | 
2025-12-05 15:49:44.126039 | TASK [Create nodepool primary file]
2025-12-05 15:49:44.181571 | controller | skipping: Conditional result was False
2025-12-05 15:49:44.187262 | 
2025-12-05 15:49:44.187337 | TASK [Create nodepool node_private for this node]
2025-12-05 15:49:45.270152 | controller | changed
2025-12-05 15:49:45.275733 | 
2025-12-05 15:49:45.275797 | LOOP [Copy ssh keys to nodepool directory]
2025-12-05 15:49:45.688250 | controller | ok: Item: id_rsa Runtime: 0:00:00.004717
2025-12-05 15:49:45.688476 | 
2025-12-05 15:49:45.920626 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004127
2025-12-05 15:49:45.927292 | 
2025-12-05 15:49:45.927391 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-05 15:49:46.979750 | controller | changed
2025-12-05 15:49:46.987159 | 
2025-12-05 15:49:46.987249 | TASK [Validate sudoers config after edits]
2025-12-05 15:49:47.278606 | controller | /etc/sudoers: parsed OK
2025-12-05 15:49:47.278712 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-05 15:49:47.278723 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-05 15:49:47.278729 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-05 15:49:47.520727 | controller | ok: Runtime: 0:00:00.005399
2025-12-05 15:49:47.526135 | 
2025-12-05 15:49:47.526200 | TASK [Show the environment passed in to job shell scripts]
2025-12-05 15:49:47.790064 | controller | SHELL=/bin/bash
2025-12-05 15:49:47.790099 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-05 15:49:47.790108 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-05 15:49:47.790116 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/39/3539/d69732b2a1625f2c30dfa731f6f764b0a55cbde9
2025-12-05 15:49:47.790121 | controller | PWD=/home/zuul
2025-12-05 15:49:47.790127 | controller | ZUUL_PIPELINE=github-check
2025-12-05 15:49:47.790132 | controller | LOGNAME=zuul
2025-12-05 15:49:47.790137 | controller | XDG_SESSION_TYPE=tty
2025-12-05 15:49:47.790142 | controller | _=/usr/bin/env
2025-12-05 15:49:47.790147 | controller | MOTD_SHOWN=pam
2025-12-05 15:49:47.790152 | controller | HOME=/home/zuul
2025-12-05 15:49:47.790157 | controller | LANG=en_US.UTF-8
2025-12-05 15:49:47.790163 | controller | SSH_CONNECTION=192.168.26.12 56220 192.168.26.73 22
2025-12-05 15:49:47.790197 | 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:49:47.790207 | controller | ZUUL_CHANGE_IDS=3539,d69732b2a1625f2c30dfa731f6f764b0a55cbde9
2025-12-05 15:49:47.790213 | controller | WORKSPACE=/home/zuul/workspace
2025-12-05 15:49:47.790218 | controller | XDG_SESSION_CLASS=user
2025-12-05 15:49:47.790224 | controller | SELINUX_ROLE_REQUESTED=
2025-12-05 15:49:47.790229 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-05 15:49:47.790234 | controller | USER=zuul
2025-12-05 15:49:47.790239 | controller | ZUUL_VOTING=True
2025-12-05 15:49:47.790244 | controller | BUILD_TIMEOUT=1800000
2025-12-05 15:49:47.790249 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-05 15:49:47.790254 | controller | SHLVL=1
2025-12-05 15:49:47.790259 | controller | ZUUL_PATCHSET=d69732b2a1625f2c30dfa731f6f764b0a55cbde9
2025-12-05 15:49:47.790264 | controller | XDG_SESSION_ID=1
2025-12-05 15:49:47.790270 | controller | ZUUL_BRANCH=main
2025-12-05 15:49:47.790275 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-05 15:49:47.790280 | controller | SSH_CLIENT=192.168.26.12 56220 22
2025-12-05 15:49:47.790285 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-05 15:49:47.790291 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-05 15:49:47.790296 | controller | which_declare=declare -f
2025-12-05 15:49:47.790301 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-05 15:49:47.790306 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-05 15:49:47.790312 | controller | ZUUL_CHANGE=3539
2025-12-05 15:49:47.790317 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-05 15:49:47.790322 | controller | ZUUL_UUID=91358828b4e34fffb887d2f11613b7d3
2025-12-05 15:49:47.790327 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-05 15:49:47.790332 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-05 15:49:47.790338 | controller | }
2025-12-05 15:49:48.052747 | controller | ok: Runtime: 0:00:00.005709
2025-12-05 15:49:48.058162 | 
2025-12-05 15:49:48.058225 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-05 15:49:48.091794 | controller | skipping: Conditional result was False
2025-12-05 15:49:48.096971 | 
2025-12-05 15:49:48.097038 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-05 15:49:48.682157 | controller | skipping: Conditional result was False
2025-12-05 15:49:48.687884 | 
2025-12-05 15:49:48.687990 | TASK [Ensure legacy workspace directory]
2025-12-05 15:49:48.939191 | controller | changed
2025-12-05 15:49:48.962199 | 
2025-12-05 15:49:48.962264 | PLAY RECAP
2025-12-05 15:49:48.962307 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 15:49:48.962330 | 
2025-12-05 15:49:49.032188 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-05 15:49:49.032868 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-05 15:49:49.603229 | 
2025-12-05 15:49:49.603344 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-05 15:49:49.653227 | 
2025-12-05 15:49:49.653310 | TASK [Create zuul-output directory]
2025-12-05 15:49:50.140377 | controller | changed
2025-12-05 15:49:50.153100 | 
2025-12-05 15:49:50.153202 | TASK [Slurp Zuul inventory test]
2025-12-05 15:49:50.736366 | controller -> localhost | ok
2025-12-05 15:49:50.742630 | 
2025-12-05 15:49:50.742708 | TASK [Save zuul inventory]
2025-12-05 15:49:52.099588 | controller | changed
2025-12-05 15:49:52.116671 | 
2025-12-05 15:49:52.116752 | TASK [Save zuul vars without the change_message]
2025-12-05 15:49:53.125950 | controller | changed
2025-12-05 15:49:53.184019 | 
2025-12-05 15:49:53.184101 | PLAY RECAP
2025-12-05 15:49:53.184144 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 15:49:53.184168 | 
2025-12-05 15:49:53.424243 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-05 15:49:53.424960 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-05 15:49:54.130451 | 
2025-12-05 15:49:54.130550 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-05 15:49:54.152883 | 
2025-12-05 15:49:54.152991 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-05 15:49:54.206658 | controller | ok
2025-12-05 15:49:54.245253 | 
2025-12-05 15:49:54.245369 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-05 15:49:54.289258 | controller | skipping: Conditional result was False
2025-12-05 15:49:54.295117 | 
2025-12-05 15:49:54.295190 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-05 15:49:54.702034 | controller | ok
2025-12-05 15:49:54.707588 | 
2025-12-05 15:49:54.707666 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-05 15:49:55.750288 | controller | ok
2025-12-05 15:49:55.760742 | 
2025-12-05 15:49:55.760858 | TASK [Prepare workspace]
2025-12-05 15:49:55.793043 | controller | ok
2025-12-05 15:49:55.809941 | 
2025-12-05 15:49:55.810035 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-05 15:49:56.155939 | controller | ok
2025-12-05 15:49:56.161964 | 
2025-12-05 15:49:56.162033 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-05 15:49:59.653885 | controller | Output suppressed because no_log was given
2025-12-05 15:49:59.671460 | 
2025-12-05 15:49:59.671529 | LOOP [Create zuul-output directory]
2025-12-05 15:49:59.948490 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-05 15:50:00.159858 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-05 15:50:00.174293 | 
2025-12-05 15:50:00.174364 | TASK [Install required packages]
2025-12-05 15:50:18.029161 | controller | ok: Nothing to do
2025-12-05 15:50:18.034359 | 
2025-12-05 15:50:18.034430 | TASK [Install venv]
2025-12-05 15:50:45.645979 | controller | ERROR
2025-12-05 15:50:45.646174 | controller | {
2025-12-05 15:50:45.646208 | controller |   "msg": "gmake: *** [Makefile:60: setup_molecule] Error 1",
2025-12-05 15:50:45.646232 | controller |   "rc": 2
2025-12-05 15:50:45.646253 | controller | }
failure
2025-12-05 15:50:45.649691 | 
2025-12-05 15:50:45.649739 | PLAY RECAP
2025-12-05 15:50:45.649782 | controller | ok: 6 changed: 2 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-12-05 15:50:45.649806 | 
2025-12-05 15:50:45.760407 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-05 15:50:45.761257 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-05 15:50:46.334619 | 
2025-12-05 15:50:46.334723 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-05 15:50:46.355479 | 
2025-12-05 15:50:46.355575 | TASK [Filter out host if needed]
2025-12-05 15:50:46.364553 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-05 15:50:46.369446 | 
2025-12-05 15:50:46.369518 | TASK [Ensure file is present]
2025-12-05 15:50:46.722320 | controller | ok
2025-12-05 15:50:46.728846 | 
2025-12-05 15:50:46.728944 | TASK [Manage molecule report file]
2025-12-05 15:50:46.763424 | controller | skipping: Conditional result was False
2025-12-05 15:50:46.768811 | 
2025-12-05 15:50:46.768880 | TASK [Check if we get ci-framework-data basedir]
2025-12-05 15:50:47.010310 | controller | ok
2025-12-05 15:50:47.016030 | 
2025-12-05 15:50:47.016095 | TASK [Create ci-framework-data log directory for zuul]
2025-12-05 15:50:47.368112 | controller | changed
2025-12-05 15:50:47.373367 | 
2025-12-05 15:50:47.373435 | TASK [Copy ci-framework interesting files]
2025-12-05 15:50:47.738164 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-12-05 15:50:47.896900 | controller | changed
2025-12-05 15:50:47.902026 | 
2025-12-05 15:50:47.902103 | TASK [Get SELinux listing]
2025-12-05 15:50:48.427920 | controller | changed
2025-12-05 15:50:48.433610 | 
2025-12-05 15:50:48.433683 | TASK [Generate log index]
2025-12-05 15:50:49.549805 | controller | changed
2025-12-05 15:50:49.555660 | 
2025-12-05 15:50:49.555744 | TASK [Get some env related data]
2025-12-05 15:50:50.194026 | controller | /home/zuul/.local/bin/ansible
2025-12-05 15:50:51.084375 | controller | changed
2025-12-05 15:50:51.089864 | 
2025-12-05 15:50:51.089970 | TASK [Generate list of logs to collect in home directory]
2025-12-05 15:50:51.463380 | controller | ok: All paths examined
2025-12-05 15:50:51.469124 | 
2025-12-05 15:50:51.469215 | LOOP [Copy logs from home directory]
2025-12-05 15:50:52.061614 | controller | changed:
2025-12-05 15:50:52.061750 | controller | {
2025-12-05 15:50:52.061779 | controller |   "atime": 1743544925.4788878,
2025-12-05 15:50:52.061800 | controller |   "ctime": 1743545329.1409318,
2025-12-05 15:50:52.061818 | controller |   "dev": 64513,
2025-12-05 15:50:52.061835 | controller |   "gid": 1000,
2025-12-05 15:50:52.061851 | controller |   "gr_name": "zuul",
2025-12-05 15:50:52.061868 | controller |   "inode": 4518807,
2025-12-05 15:50:52.061884 | controller |   "isblk": false,
2025-12-05 15:50:52.061900 | controller |   "ischr": false,
2025-12-05 15:50:52.061949 | controller |   "isdir": false,
2025-12-05 15:50:52.061969 | controller |   "isfifo": false,
2025-12-05 15:50:52.061987 | controller |   "isgid": false,
2025-12-05 15:50:52.062002 | controller |   "islnk": false,
2025-12-05 15:50:52.062018 | controller |   "isreg": true,
2025-12-05 15:50:52.062034 | controller |   "issock": false,
2025-12-05 15:50:52.062048 | controller |   "isuid": false,
2025-12-05 15:50:52.062070 | controller |   "mode": "0644",
2025-12-05 15:50:52.062089 | controller |   "mtime": 1743545329.1409318,
2025-12-05 15:50:52.062106 | controller |   "nlink": 1,
2025-12-05 15:50:52.062122 | controller |   "path": "/home/zuul/crc-setup.log",
2025-12-05 15:50:52.062139 | controller |   "pw_name": "zuul",
2025-12-05 15:50:52.062155 | controller |   "rgrp": true,
2025-12-05 15:50:52.062189 | controller |   "roth": true,
2025-12-05 15:50:52.062207 | controller |   "rusr": true,
2025-12-05 15:50:52.062224 | controller |   "size": 4108,
2025-12-05 15:50:52.062239 | controller |   "uid": 1000,
2025-12-05 15:50:52.062255 | controller |   "wgrp": false,
2025-12-05 15:50:52.062270 | controller |   "woth": false,
2025-12-05 15:50:52.062287 | controller |   "wusr": true,
2025-12-05 15:50:52.062304 | controller |   "xgrp": false,
2025-12-05 15:50:52.062320 | controller |   "xoth": false,
2025-12-05 15:50:52.062336 | controller |   "xusr": false
2025-12-05 15:50:52.062351 | controller | }
2025-12-05 15:50:52.626567 | controller | changed:
2025-12-05 15:50:52.626642 | controller | {
2025-12-05 15:50:52.626669 | controller |   "atime": 1743545331.0429637,
2025-12-05 15:50:52.626690 | controller |   "ctime": 1743545842.0111232,
2025-12-05 15:50:52.626708 | controller |   "dev": 64513,
2025-12-05 15:50:52.626725 | controller |   "gid": 1000,
2025-12-05 15:50:52.626742 | controller |   "gr_name": "zuul",
2025-12-05 15:50:52.626758 | controller |   "inode": 4194437,
2025-12-05 15:50:52.626775 | controller |   "isblk": false,
2025-12-05 15:50:52.626790 | controller |   "ischr": false,
2025-12-05 15:50:52.626805 | controller |   "isdir": false,
2025-12-05 15:50:52.626820 | controller |   "isfifo": false,
2025-12-05 15:50:52.626834 | controller |   "isgid": false,
2025-12-05 15:50:52.626848 | controller |   "islnk": false,
2025-12-05 15:50:52.626862 | controller |   "isreg": true,
2025-12-05 15:50:52.626877 | controller |   "issock": false,
2025-12-05 15:50:52.626891 | controller |   "isuid": false,
2025-12-05 15:50:52.627014 | controller |   "mode": "0644",
2025-12-05 15:50:52.627057 | controller |   "mtime": 1743545842.0111232,
2025-12-05 15:50:52.627081 | controller |   "nlink": 1,
2025-12-05 15:50:52.627100 | controller |   "path": "/home/zuul/crc-start.log",
2025-12-05 15:50:52.627118 | controller |   "pw_name": "zuul",
2025-12-05 15:50:52.627135 | controller |   "rgrp": true,
2025-12-05 15:50:52.627152 | controller |   "roth": true,
2025-12-05 15:50:52.627180 | controller |   "rusr": true,
2025-12-05 15:50:52.627196 | controller |   "size": 4023,
2025-12-05 15:50:52.627212 | controller |   "uid": 1000,
2025-12-05 15:50:52.627227 | controller |   "wgrp": false,
2025-12-05 15:50:52.627242 | controller |   "woth": false,
2025-12-05 15:50:52.627258 | controller |   "wusr": true,
2025-12-05 15:50:52.627272 | controller |   "xgrp": false,
2025-12-05 15:50:52.627286 | controller |   "xoth": false,
2025-12-05 15:50:52.627301 | controller |   "xusr": false
2025-12-05 15:50:52.627315 | controller | }
2025-12-05 15:50:52.637386 | 
2025-12-05 15:50:52.637449 | TASK [Copy crio stats log file]
2025-12-05 15:50:52.651128 | controller | skipping: Conditional result was False
2025-12-05 15:50:52.657132 | 
2025-12-05 15:50:52.657329 | TASK [Get SELinux related data]
2025-12-05 15:50:52.973624 | controller | <no matches>
2025-12-05 15:50:53.185294 | controller | ERROR
2025-12-05 15:50:53.185409 | controller | {
2025-12-05 15:50:53.185441 | controller |   "delta": "0:00:00.011794",
2025-12-05 15:50:53.185463 | controller |   "end": "2025-12-05 15:50:52.974485",
2025-12-05 15:50:53.185481 | controller |   "msg": "non-zero return code",
2025-12-05 15:50:53.185498 | controller |   "rc": 1,
2025-12-05 15:50:53.185513 | controller |   "start": "2025-12-05 15:50:52.962691"
2025-12-05 15:50:53.185528 | controller | }
2025-12-05 15:50:53.185549 | controller | ERROR: Ignoring Errors
2025-12-05 15:50:53.190679 | 
2025-12-05 15:50:53.190742 | TASK [Create system configuration directory]
2025-12-05 15:50:53.451667 | controller | changed
2025-12-05 15:50:53.456665 | 
2025-12-05 15:50:53.456740 | TASK [Get some of the system configurations]
2025-12-05 15:50:53.991799 | controller | changed
2025-12-05 15:50:53.997067 | 
2025-12-05 15:50:53.997130 | TASK [Copy generated documentation if available]
2025-12-05 15:50:54.010943 | controller | skipping: Conditional result was False
2025-12-05 15:50:54.016552 | 
2025-12-05 15:50:54.016614 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-05 15:50:54.030401 | controller | skipping: Conditional result was False
2025-12-05 15:50:54.035898 | 
2025-12-05 15:50:54.035975 | TASK [Compress logs bigger than 2MB]
2025-12-05 15:50:54.561005 | controller | changed
2025-12-05 15:50:54.566784 | 
2025-12-05 15:50:54.566866 | TASK [Copy files from workspace on node]
2025-12-05 15:50:54.585590 | controller | ok
2025-12-05 15:50:54.612451 | 
2025-12-05 15:50:54.612576 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 15:50:54.627397 | controller | skipping: Conditional result was False
2025-12-05 15:50:54.633692 | 
2025-12-05 15:50:54.633788 | TASK [fetch-output : Set log path for single node]
2025-12-05 15:50:54.669205 | controller | ok
2025-12-05 15:50:54.678656 | 
2025-12-05 15:50:54.678744 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 15:50:54.943645 | controller -> localhost | ok: "/var/lib/zuul/builds/91358828b4e34fffb887d2f11613b7d3/work/logs"
2025-12-05 15:50:54.943867 | controller -> localhost | changed: All items complete
2025-12-05 15:50:54.943898 | 
2025-12-05 15:50:55.171837 | controller -> localhost | changed: "/var/lib/zuul/builds/91358828b4e34fffb887d2f11613b7d3/work/artifacts"
2025-12-05 15:50:55.501409 | controller -> localhost | changed: "/var/lib/zuul/builds/91358828b4e34fffb887d2f11613b7d3/work/docs"
2025-12-05 15:50:55.514191 | 
2025-12-05 15:50:55.514296 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 15:50:56.384992 | controller | changed:
2025-12-05 15:50:56.385258 | controller | .d..t...... ./
2025-12-05 15:50:56.385297 | controller | >f+++++++++ README.html
2025-12-05 15:50:56.385323 | controller | >f+++++++++ crc-setup.log
2025-12-05 15:50:56.385345 | controller | >f+++++++++ crc-start.log
2025-12-05 15:50:56.385365 | controller | >f+++++++++ dmesg.log
2025-12-05 15:50:56.385384 | controller | >f+++++++++ installed-pkgs.log
2025-12-05 15:50:56.385402 | controller | >f+++++++++ python.log
2025-12-05 15:50:56.385419 | controller | >f+++++++++ registries.conf
2025-12-05 15:50:56.385436 | controller | >f+++++++++ selinux-denials.log
2025-12-05 15:50:56.385453 | controller | >f+++++++++ selinux-listing.log
2025-12-05 15:50:56.385468 | controller | cd+++++++++ ci-framework-data/
2025-12-05 15:50:56.385484 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-05 15:50:56.385500 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-05 15:50:56.385517 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-05 15:50:56.385533 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-05 15:50:56.385549 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-05 15:50:56.385565 | controller | cd+++++++++ registries.conf.d/
2025-12-05 15:50:56.385581 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-05 15:50:56.385598 | controller | cd+++++++++ system-config/
2025-12-05 15:50:56.385614 | controller | cd+++++++++ system-config/libvirt/
2025-12-05 15:50:56.385630 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-05 15:50:56.385645 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-05 15:50:56.385662 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-12-05 15:50:56.385680 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-12-05 15:50:56.385697 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-12-05 15:50:56.385714 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-12-05 15:50:56.385731 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-12-05 15:50:56.385754 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-12-05 15:50:56.385773 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-12-05 15:50:56.385790 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-12-05 15:50:56.385808 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-12-05 15:50:56.385824 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-12-05 15:50:56.385840 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-12-05 15:50:56.385856 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-12-05 15:50:56.385873 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-12-05 15:50:56.385889 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-12-05 15:50:56.859734 | controller | changed: .d..t...... ./
2025-12-05 15:50:57.304827 | controller | changed: .d..t...... ./
2025-12-05 15:50:57.326307 | 
2025-12-05 15:50:57.326393 | TASK [Return artifact to Zuul]
2025-12-05 15:50:57.350226 | controller | skipping: Conditional result was False
2025-12-05 15:50:57.379421 | 
2025-12-05 15:50:57.379497 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-05 15:50:57.379598 | 
2025-12-05 15:50:57.379628 | PLAY RECAP
2025-12-05 15:50:57.379667 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1
2025-12-05 15:50:57.379689 | 
2025-12-05 15:50:57.468360 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-05 15:50:57.469076 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 15:50:58.080805 | 
2025-12-05 15:50:58.080905 | PLAY [all]
2025-12-05 15:50:58.103925 | 
2025-12-05 15:50:58.104000 | TASK [include_role : fetch-output]
2025-12-05 15:50:58.142462 | controller | ok
2025-12-05 15:50:58.159690 | 
2025-12-05 15:50:58.159811 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 15:50:58.203894 | controller | skipping: Conditional result was False
2025-12-05 15:50:58.209428 | 
2025-12-05 15:50:58.209499 | TASK [fetch-output : Set log path for single node]
2025-12-05 15:50:58.237059 | controller | ok
2025-12-05 15:50:58.241973 | 
2025-12-05 15:50:58.242041 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 15:50:58.580032 | controller -> localhost | ok: "/var/lib/zuul/builds/91358828b4e34fffb887d2f11613b7d3/work/logs"
2025-12-05 15:50:58.812627 | controller -> localhost | ok: "/var/lib/zuul/builds/91358828b4e34fffb887d2f11613b7d3/work/artifacts"
2025-12-05 15:50:59.075259 | controller -> localhost | ok: "/var/lib/zuul/builds/91358828b4e34fffb887d2f11613b7d3/work/docs"
2025-12-05 15:50:59.089307 | 
2025-12-05 15:50:59.089409 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 15:50:59.840004 | controller | ok
2025-12-05 15:50:59.840213 | controller | ok: All items complete
2025-12-05 15:50:59.840245 | 
2025-12-05 15:51:00.391412 | controller | ok
2025-12-05 15:51:00.865875 | controller | ok
2025-12-05 15:51:00.886415 | 
2025-12-05 15:51:00.893538 | TASK [include_role : fetch-output-openshift]
2025-12-05 15:51:00.910600 | controller | skipping: Conditional result was False
2025-12-05 15:51:00.916557 | 
2025-12-05 15:51:00.916636 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-05 15:51:01.381310 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.032866
2025-12-05 15:51:01.620749 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006290
2025-12-05 15:51:01.660540 | 
2025-12-05 15:51:01.660622 | PLAY [all]
2025-12-05 15:51:01.682749 | 
2025-12-05 15:51:01.682825 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-05 15:51:02.161476 | controller | changed
2025-12-05 15:51:02.217057 | 
2025-12-05 15:51:02.217137 | PLAY RECAP
2025-12-05 15:51:02.217196 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 15:51:02.217228 | 
2025-12-05 15:51:02.335782 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 15:51:02.336503 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-05 15:51:03.082564 | 
2025-12-05 15:51:03.082695 | PLAY [localhost]
2025-12-05 15:51:03.114360 | 
2025-12-05 15:51:03.114441 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-05 15:51:03.508413 | localhost | changed
2025-12-05 15:51:03.513369 | 
2025-12-05 15:51:03.513458 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-05 15:51:03.541926 | localhost | ok
2025-12-05 15:51:03.552853 | 
2025-12-05 15:51:03.554962 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-05 15:51:03.949557 | localhost | changed
2025-12-05 15:51:03.954450 | 
2025-12-05 15:51:03.954523 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-05 15:51:04.682568 | localhost | changed
2025-12-05 15:51:04.688298 | 
2025-12-05 15:51:04.688371 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-05 15:51:05.130438 | localhost | Identity added: /var/lib/zuul/builds/91358828b4e34fffb887d2f11613b7d3/work/tmp/ansible.tpvedalt (/var/lib/zuul/builds/91358828b4e34fffb887d2f11613b7d3/work/tmp/ansible.tpvedalt)
2025-12-05 15:51:05.130614 | localhost | ok: Runtime: 0:00:00.009222
2025-12-05 15:51:05.136977 | 
2025-12-05 15:51:05.137048 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-05 15:51:05.614453 | localhost | ok: Runtime: 0:00:00.013712
2025-12-05 15:51:05.621593 | 
2025-12-05 15:51:05.621667 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-05 15:51:05.686061 | localhost | changed
2025-12-05 15:51:05.691387 | 
2025-12-05 15:51:05.691457 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-05 15:51:06.249398 | localhost | changed
2025-12-05 15:51:06.301219 | 
2025-12-05 15:51:06.301295 | PLAY [localhost]
2025-12-05 15:51:06.327625 | 
2025-12-05 15:51:06.327864 | TASK [Generate bulk log download script]
2025-12-05 15:51:06.369400 | localhost | ok
2025-12-05 15:51:06.389992 | 
2025-12-05 15:51:06.390079 | TASK [local-log-download : Check API endpoint is defined]
2025-12-05 15:51:06.450993 | localhost | ok: All assertions passed
2025-12-05 15:51:06.456055 | 
2025-12-05 15:51:06.456125 | TASK [local-log-download : Create download script]
2025-12-05 15:51:07.100751 | localhost -> localhost | changed
2025-12-05 15:51:07.148538 | 
2025-12-05 15:51:07.148638 | TASK [Register quick-download link]
2025-12-05 15:51:07.205815 | localhost | ok
2025-12-05 15:51:07.250362 | 
2025-12-05 15:51:07.250449 | PLAY [logserver.rdoproject.org]
2025-12-05 15:51:07.283521 | 
2025-12-05 15:51:07.283623 | TASK [Set zuul-log-path fact]
2025-12-05 15:51:07.316598 | logserver.rdoproject.org | ok
2025-12-05 15:51:07.335428 | 
2025-12-05 15:51:07.335513 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 15:51:07.385356 | logserver.rdoproject.org | ok
2025-12-05 15:51:07.390850 | 
2025-12-05 15:51:07.390958 | TASK [upload-logs : Create log directories]
2025-12-05 15:51:08.991501 | logserver.rdoproject.org | changed
2025-12-05 15:51:08.995222 | 
2025-12-05 15:51:08.995288 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-05 15:51:09.332597 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.011443
2025-12-05 15:51:09.337422 | 
2025-12-05 15:51:09.337487 | TASK [upload-logs : Upload logs to log server]
2025-12-05 15:51:10.967459 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-05 15:51:10.970989 | 
2025-12-05 15:51:10.971101 | LOOP [upload-logs : Compress console log and json output]
2025-12-05 15:51:11.048562 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:51:11.109681 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:51:11.115720 | 
2025-12-05 15:51:11.115831 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-05 15:51:11.182308 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:51:11.182538 | 
2025-12-05 15:51:11.183114 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:51:11.192095 | 
2025-12-05 15:51:11.192200 | LOOP [upload-logs : Upload console log and json output]