2026-02-26 12:31:43.253485 | Job console starting...
2026-02-26 12:31:43.456818 | Updating repositories
2026-02-26 12:31:44.198843 | Preparing job workspace
2026-02-26 12:31:51.057662 | Running Ansible setup...
2026-02-26 12:31:54.535158 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-02-26 12:31:55.006620 | 
2026-02-26 12:31:55.006714 | PLAY [localhost]
2026-02-26 12:31:55.014657 | 
2026-02-26 12:31:55.014727 | TASK [Gathering Facts]
2026-02-26 12:31:55.768805 | localhost | ok
2026-02-26 12:31:55.780931 | 
2026-02-26 12:31:55.781016 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-26 12:31:56.068096 | localhost -> localhost | changed
2026-02-26 12:31:56.072824 | 
2026-02-26 12:31:56.072891 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-26 12:31:56.694763 | localhost -> localhost | changed
2026-02-26 12:31:56.702342 | 
2026-02-26 12:31:56.702407 | TASK [Setup log path fact]
2026-02-26 12:31:56.719479 | localhost | ok
2026-02-26 12:31:56.729395 | 
2026-02-26 12:31:56.729457 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-26 12:31:56.756593 | localhost | ok
2026-02-26 12:31:56.763243 | 
2026-02-26 12:31:56.763337 | TASK [emit-job-header : Print job information]
2026-02-26 12:31:56.789913 | # Job Information
2026-02-26 12:31:56.790020 | Ansible Version: 2.15.12
2026-02-26 12:31:56.790048 | Job: cifmw-molecule-sushy_emulator
2026-02-26 12:31:56.790069 | Pipeline: github-check
2026-02-26 12:31:56.790089 | Executor: ibm-bm3-ze.softwarefactory-project.io
2026-02-26 12:31:56.790107 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3678
2026-02-26 12:31:56.790125 | Log URL (when completed): https://logserver.rdoproject.org/421/rdoproject.org/42146453665d413694d4fae1e2a32b2e/
2026-02-26 12:31:56.790143 | Event ID: d42d3b40-130e-11f1-8b84-91bad812e2cf
2026-02-26 12:31:56.793767 | 
2026-02-26 12:31:56.793832 | LOOP [emit-job-header : Print node information]
2026-02-26 12:31:56.871810 | localhost | ok:
2026-02-26 12:31:56.871993 | localhost | # Node Information
2026-02-26 12:31:56.872022 | localhost | Inventory Hostname: controller
2026-02-26 12:31:56.872046 | localhost | Hostname: np0005631456
2026-02-26 12:31:56.872065 | localhost | Username: zuul
2026-02-26 12:31:56.872083 | localhost | Distro: CentOS 9
2026-02-26 12:31:56.872099 | localhost | Provider: ibm-bm3-nodepool
2026-02-26 12:31:56.872114 | localhost | Region: regionOne
2026-02-26 12:31:56.872128 | localhost | Label: centos-9-stream-crc-2-48-0-xl-ibm
2026-02-26 12:31:56.872143 | localhost | Product Name: OpenStack Compute
2026-02-26 12:31:56.872157 | localhost | Interface IP: 192.168.25.249
2026-02-26 12:31:56.895218 | 
2026-02-26 12:31:56.895309 | PLAY [all]
2026-02-26 12:31:56.900874 | 
2026-02-26 12:31:56.900933 | TASK [Gather network facts]
2026-02-26 12:31:57.346949 | controller | ok
2026-02-26 12:31:57.360669 | 
2026-02-26 12:31:57.360729 | TASK [include_role : start-zuul-console]
2026-02-26 12:31:57.378968 | controller | ok
2026-02-26 12:31:57.388926 | 
2026-02-26 12:31:57.388985 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-02-26 12:31:57.762355 | controller | ok
2026-02-26 12:31:57.770204 | 
2026-02-26 12:31:57.770305 | TASK [include_role : add-build-sshkey]
2026-02-26 12:31:57.788154 | controller | ok
2026-02-26 12:31:57.800188 | 
2026-02-26 12:31:57.800252 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-26 12:31:57.975504 | controller -> localhost | ok
2026-02-26 12:31:57.980411 | 
2026-02-26 12:31:57.980481 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-26 12:31:57.998758 | controller | ok
2026-02-26 12:31:58.009761 | controller | included: /var/lib/zuul/builds/42146453665d413694d4fae1e2a32b2e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-26 12:31:58.014775 | 
2026-02-26 12:31:58.014833 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-26 12:31:58.462845 | controller -> localhost | Generating public/private rsa key pair.
2026-02-26 12:31:58.463019 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/42146453665d413694d4fae1e2a32b2e/work/42146453665d413694d4fae1e2a32b2e_id_rsa.
2026-02-26 12:31:58.463051 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/42146453665d413694d4fae1e2a32b2e/work/42146453665d413694d4fae1e2a32b2e_id_rsa.pub.
2026-02-26 12:31:58.463073 | controller -> localhost | The key fingerprint is:
2026-02-26 12:31:58.463092 | controller -> localhost | SHA256:Sx2TGdKTjjnNZO+TQM7R6pObRbTChisTk2dyeKM8zBQ zuul-build-sshkey
2026-02-26 12:31:58.463110 | controller -> localhost | The key's randomart image is:
2026-02-26 12:31:58.463127 | controller -> localhost | +---[RSA 3072]----+
2026-02-26 12:31:58.463143 | controller -> localhost | |        ...o     |
2026-02-26 12:31:58.463159 | controller -> localhost | |      E  .O+o    |
2026-02-26 12:31:58.463175 | controller -> localhost | |       + #=* .   |
2026-02-26 12:31:58.463191 | controller -> localhost | |      B @.%o+    |
2026-02-26 12:31:58.463208 | controller -> localhost | |     = XS*.* .   |
2026-02-26 12:31:58.463223 | controller -> localhost | |      O...+ =    |
2026-02-26 12:31:58.463239 | controller -> localhost | |       +.  = .   |
2026-02-26 12:31:58.463255 | controller -> localhost | |          o      |
2026-02-26 12:31:58.463302 | controller -> localhost | |                 |
2026-02-26 12:31:58.463324 | controller -> localhost | +----[SHA256]-----+
2026-02-26 12:31:58.463369 | controller -> localhost | ok: Runtime: 0:00:00.155064
2026-02-26 12:31:58.468289 | 
2026-02-26 12:31:58.468351 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-26 12:31:58.486080 | controller | ok
2026-02-26 12:31:58.494718 | controller | included: /var/lib/zuul/builds/42146453665d413694d4fae1e2a32b2e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-26 12:31:58.501709 | 
2026-02-26 12:31:58.501767 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-26 12:31:58.515333 | controller | skipping: Conditional result was False
2026-02-26 12:31:58.520559 | 
2026-02-26 12:31:58.520626 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-26 12:31:58.994883 | controller | changed
2026-02-26 12:31:58.999635 | 
2026-02-26 12:31:58.999697 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-26 12:31:59.271080 | controller | ok
2026-02-26 12:31:59.275705 | 
2026-02-26 12:31:59.275766 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-26 12:32:00.482971 | controller | changed
2026-02-26 12:32:00.487800 | 
2026-02-26 12:32:00.487864 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-26 12:32:01.714944 | controller | changed
2026-02-26 12:32:01.719682 | 
2026-02-26 12:32:01.719745 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-26 12:32:01.733505 | controller | skipping: Conditional result was False
2026-02-26 12:32:01.738757 | 
2026-02-26 12:32:01.738819 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-26 12:32:02.015322 | controller -> localhost | changed
2026-02-26 12:32:02.024397 | 
2026-02-26 12:32:02.024471 | TASK [add-build-sshkey : Add back temp key]
2026-02-26 12:32:02.240660 | controller -> localhost | Identity added: /var/lib/zuul/builds/42146453665d413694d4fae1e2a32b2e/work/42146453665d413694d4fae1e2a32b2e_id_rsa (zuul-build-sshkey)
2026-02-26 12:32:02.240848 | controller -> localhost | ok: Runtime: 0:00:00.006476
2026-02-26 12:32:02.246362 | 
2026-02-26 12:32:02.246425 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-26 12:32:02.587997 | controller | ok
2026-02-26 12:32:02.592146 | 
2026-02-26 12:32:02.592211 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-26 12:32:02.615878 | controller | skipping: Conditional result was False
2026-02-26 12:32:02.624503 | 
2026-02-26 12:32:02.624565 | TASK [include_role : validate-host]
2026-02-26 12:32:02.642082 | controller | ok
2026-02-26 12:32:02.658724 | 
2026-02-26 12:32:02.658783 | TASK [validate-host : Define zuul_info_dir fact]
2026-02-26 12:32:02.676540 | controller | ok
2026-02-26 12:32:02.680541 | 
2026-02-26 12:32:02.680599 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-02-26 12:32:02.855499 | controller -> localhost | ok
2026-02-26 12:32:02.860905 | 
2026-02-26 12:32:02.860976 | TASK [validate-host : Collect information about the host]
2026-02-26 12:32:03.552878 | controller | ok
2026-02-26 12:32:03.560092 | 
2026-02-26 12:32:03.560149 | TASK [validate-host : Sanitize hostname]
2026-02-26 12:32:03.597708 | controller | ok
2026-02-26 12:32:03.602383 | 
2026-02-26 12:32:03.602450 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-02-26 12:32:03.951430 | controller -> localhost | changed
2026-02-26 12:32:03.956557 | 
2026-02-26 12:32:03.956621 | TASK [validate-host : Collect information about zuul worker]
2026-02-26 12:32:04.340684 | controller | ok
2026-02-26 12:32:04.344967 | 
2026-02-26 12:32:04.345036 | TASK [validate-host : Write out all zuul information for each host]
2026-02-26 12:32:04.682549 | controller -> localhost | changed
2026-02-26 12:32:04.690562 | 
2026-02-26 12:32:04.690625 | TASK [include_role : prepare-workspace-openshift]
2026-02-26 12:32:04.703545 | controller | skipping: Conditional result was False
2026-02-26 12:32:04.708188 | 
2026-02-26 12:32:04.708244 | TASK [include_role : remove-zuul-sshkey]
2026-02-26 12:32:04.721236 | controller | skipping: Conditional result was False
2026-02-26 12:32:04.725831 | 
2026-02-26 12:32:04.725891 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-26 12:32:05.006618 | controller | ok: "logs"
2026-02-26 12:32:05.006869 | controller | ok: All items complete
2026-02-26 12:32:05.006900 | 
2026-02-26 12:32:05.268032 | controller | ok: "artifacts"
2026-02-26 12:32:05.503437 | controller | ok: "docs"
2026-02-26 12:32:05.518308 | 
2026-02-26 12:32:05.518367 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-26 12:32:05.780377 | controller | changed: "logs"
2026-02-26 12:32:06.024489 | controller | changed: "artifacts"
2026-02-26 12:32:06.265816 | controller | changed: "docs"
2026-02-26 12:32:06.290090 | 
2026-02-26 12:32:06.290137 | PLAY RECAP
2026-02-26 12:32:06.290179 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-02-26 12:32:06.290214 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-26 12:32:06.290232 | 
2026-02-26 12:32:06.360531 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-02-26 12:32:06.361208 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-02-26 12:32:06.857864 | 
2026-02-26 12:32:06.857974 | PLAY [all]
2026-02-26 12:32:06.876152 | 
2026-02-26 12:32:06.876228 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-02-26 12:32:06.925038 | controller | ok
2026-02-26 12:32:06.929472 | 
2026-02-26 12:32:06.929548 | TASK [mirror-info-fork : Create /etc/ci]
2026-02-26 12:32:07.324670 | controller | changed
2026-02-26 12:32:07.329921 | 
2026-02-26 12:32:07.329992 | TASK [mirror-info-fork : Install ci_mirror script]
2026-02-26 12:32:08.616147 | controller | changed
2026-02-26 12:32:08.625848 | 
2026-02-26 12:32:08.625917 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-02-26 12:32:09.053620 | controller | changed:
2026-02-26 12:32:09.053782 | controller | {
2026-02-26 12:32:09.053810 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-02-26 12:32:09.053848 | controller | }
2026-02-26 12:32:09.335533 | controller | changed:
2026-02-26 12:32:09.335626 | controller | {
2026-02-26 12:32:09.335652 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-02-26 12:32:09.335671 | controller | }
2026-02-26 12:32:09.630716 | controller | changed:
2026-02-26 12:32:09.630799 | controller | {
2026-02-26 12:32:09.630824 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-02-26 12:32:09.630844 | controller | }
2026-02-26 12:32:09.921708 | controller | changed:
2026-02-26 12:32:09.921796 | controller | {
2026-02-26 12:32:09.921824 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-02-26 12:32:09.921844 | controller | }
2026-02-26 12:32:10.208446 | controller | changed:
2026-02-26 12:32:10.208543 | controller | {
2026-02-26 12:32:10.208569 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-02-26 12:32:10.208590 | controller | }
2026-02-26 12:32:10.492987 | controller | changed:
2026-02-26 12:32:10.493076 | controller | {
2026-02-26 12:32:10.493101 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-02-26 12:32:10.493120 | controller | }
2026-02-26 12:32:10.786505 | controller | changed:
2026-02-26 12:32:10.786565 | controller | {
2026-02-26 12:32:10.786589 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2026-02-26 12:32:10.786608 | controller | }
2026-02-26 12:32:11.077799 | controller | changed:
2026-02-26 12:32:11.077961 | controller | {
2026-02-26 12:32:11.077998 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2026-02-26 12:32:11.078021 | controller | }
2026-02-26 12:32:11.363764 | controller | changed:
2026-02-26 12:32:11.363820 | controller | {
2026-02-26 12:32:11.363845 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-02-26 12:32:11.363864 | controller | }
2026-02-26 12:32:11.654802 | controller | changed:
2026-02-26 12:32:11.654872 | controller | {
2026-02-26 12:32:11.654896 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-02-26 12:32:11.654915 | controller | }
2026-02-26 12:32:11.912259 | controller | changed:
2026-02-26 12:32:11.912345 | controller | {
2026-02-26 12:32:11.912371 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-02-26 12:32:11.912389 | controller | }
2026-02-26 12:32:12.188176 | controller | changed:
2026-02-26 12:32:12.188226 | controller | {
2026-02-26 12:32:12.188251 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-02-26 12:32:12.188299 | controller | }
2026-02-26 12:32:12.461036 | controller | changed:
2026-02-26 12:32:12.461087 | controller | {
2026-02-26 12:32:12.461113 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-02-26 12:32:12.461133 | controller | }
2026-02-26 12:32:12.759449 | controller | changed:
2026-02-26 12:32:12.759507 | controller | {
2026-02-26 12:32:12.759533 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-02-26 12:32:12.759553 | controller | }
2026-02-26 12:32:13.044614 | controller | changed:
2026-02-26 12:32:13.044665 | controller | {
2026-02-26 12:32:13.044690 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-02-26 12:32:13.044709 | controller | }
2026-02-26 12:32:13.334031 | controller | changed:
2026-02-26 12:32:13.334085 | controller | {
2026-02-26 12:32:13.334110 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-02-26 12:32:13.334129 | controller | }
2026-02-26 12:32:13.615081 | controller | changed:
2026-02-26 12:32:13.615128 | controller | {
2026-02-26 12:32:13.615152 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2026-02-26 12:32:13.615172 | controller | }
2026-02-26 12:32:13.890619 | controller | changed:
2026-02-26 12:32:13.890669 | controller | {
2026-02-26 12:32:13.890695 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2026-02-26 12:32:13.890723 | controller | }
2026-02-26 12:32:14.179877 | controller | changed:
2026-02-26 12:32:14.179926 | controller | {
2026-02-26 12:32:14.179950 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-02-26 12:32:14.179969 | controller | }
2026-02-26 12:32:14.465757 | controller | changed:
2026-02-26 12:32:14.465804 | controller | {
2026-02-26 12:32:14.465828 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-02-26 12:32:14.465848 | controller | }
2026-02-26 12:32:14.754240 | controller | changed:
2026-02-26 12:32:14.754319 | controller | {
2026-02-26 12:32:14.754346 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-02-26 12:32:14.754364 | controller | }
2026-02-26 12:32:15.041666 | controller | changed:
2026-02-26 12:32:15.041729 | controller | {
2026-02-26 12:32:15.041756 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-02-26 12:32:15.041776 | controller | }
2026-02-26 12:32:15.339402 | controller | changed:
2026-02-26 12:32:15.339455 | controller | {
2026-02-26 12:32:15.339480 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2026-02-26 12:32:15.339501 | controller | }
2026-02-26 12:32:15.646768 | controller | changed:
2026-02-26 12:32:15.646815 | controller | {
2026-02-26 12:32:15.646838 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-02-26 12:32:15.646858 | controller | }
2026-02-26 12:32:15.952556 | controller | changed:
2026-02-26 12:32:15.952625 | controller | {
2026-02-26 12:32:15.952649 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-02-26 12:32:15.952666 | controller | }
2026-02-26 12:32:16.257477 | controller | changed:
2026-02-26 12:32:16.257559 | controller | {
2026-02-26 12:32:16.257584 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-02-26 12:32:16.257604 | controller | }
2026-02-26 12:32:16.275131 | 
2026-02-26 12:32:16.275195 | TASK [Set timezone to UTC]
2026-02-26 12:32:16.771938 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-02-26 12:32:16.776152 | 
2026-02-26 12:32:16.776211 | TASK [Create nodepool directory]
2026-02-26 12:32:17.070247 | controller | changed
2026-02-26 12:32:17.075439 | 
2026-02-26 12:32:17.075510 | TASK [Create nodepool sub_nodes file]
2026-02-26 12:32:18.114657 | controller | changed
2026-02-26 12:32:18.119402 | 
2026-02-26 12:32:18.119468 | TASK [Create nodepool sub_nodes_private file]
2026-02-26 12:32:19.139210 | controller | changed
2026-02-26 12:32:19.144056 | 
2026-02-26 12:32:19.144119 | LOOP [Populate nodepool sub_nodes file]
2026-02-26 12:32:19.172463 | 
2026-02-26 12:32:19.172577 | LOOP [Populate nodepool sub_nodes_private file]
2026-02-26 12:32:19.200920 | 
2026-02-26 12:32:19.201041 | TASK [Create nodepool primary file]
2026-02-26 12:32:19.224352 | controller | skipping: Conditional result was False
2026-02-26 12:32:19.229554 | 
2026-02-26 12:32:19.229625 | TASK [Create nodepool node_private for this node]
2026-02-26 12:32:20.244371 | controller | changed
2026-02-26 12:32:20.249610 | 
2026-02-26 12:32:20.249675 | LOOP [Copy ssh keys to nodepool directory]
2026-02-26 12:32:20.634804 | controller | ok: Item: id_rsa Runtime: 0:00:00.004700
2026-02-26 12:32:20.634954 | 
2026-02-26 12:32:20.870504 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005094
2026-02-26 12:32:20.884715 | 
2026-02-26 12:32:20.884786 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-02-26 12:32:21.888434 | controller | changed
2026-02-26 12:32:21.892927 | 
2026-02-26 12:32:21.892985 | TASK [Validate sudoers config after edits]
2026-02-26 12:32:22.158284 | controller | /etc/sudoers: parsed OK
2026-02-26 12:32:22.158352 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-02-26 12:32:22.158363 | controller | /etc/sudoers.d/zuul: parsed OK
2026-02-26 12:32:22.158374 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-02-26 12:32:22.416582 | controller | ok: Runtime: 0:00:00.005695
2026-02-26 12:32:22.421162 | 
2026-02-26 12:32:22.421216 | TASK [Show the environment passed in to job shell scripts]
2026-02-26 12:32:22.671725 | controller | SHELL=/bin/bash
2026-02-26 12:32:22.671759 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-02-26 12:32:22.671768 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-02-26 12:32:22.671775 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/78/3678/79bd51efd6147c0557dcf4fe58dd324d6dccaf35
2026-02-26 12:32:22.671780 | controller | PWD=/home/zuul
2026-02-26 12:32:22.671785 | controller | ZUUL_PIPELINE=github-check
2026-02-26 12:32:22.671790 | controller | LOGNAME=zuul
2026-02-26 12:32:22.671794 | controller | XDG_SESSION_TYPE=tty
2026-02-26 12:32:22.671799 | controller | _=/usr/bin/env
2026-02-26 12:32:22.671804 | controller | MOTD_SHOWN=pam
2026-02-26 12:32:22.671830 | controller | HOME=/home/zuul
2026-02-26 12:32:22.671836 | controller | LANG=en_US.UTF-8
2026-02-26 12:32:22.671841 | controller | SSH_CONNECTION=192.168.25.12 45416 192.168.25.249 22
2026-02-26 12:32:22.671845 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2026-02-26 12:32:22.671852 | controller | ZUUL_CHANGE_IDS=3678,79bd51efd6147c0557dcf4fe58dd324d6dccaf35
2026-02-26 12:32:22.671857 | controller | WORKSPACE=/home/zuul/workspace
2026-02-26 12:32:22.671862 | controller | XDG_SESSION_CLASS=user
2026-02-26 12:32:22.671867 | controller | SELINUX_ROLE_REQUESTED=
2026-02-26 12:32:22.671872 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-02-26 12:32:22.671876 | controller | USER=zuul
2026-02-26 12:32:22.671881 | controller | ZUUL_VOTING=True
2026-02-26 12:32:22.671886 | controller | BUILD_TIMEOUT=1800000
2026-02-26 12:32:22.671891 | controller | SELINUX_USE_CURRENT_RANGE=
2026-02-26 12:32:22.671896 | controller | SHLVL=1
2026-02-26 12:32:22.671901 | controller | ZUUL_PATCHSET=79bd51efd6147c0557dcf4fe58dd324d6dccaf35
2026-02-26 12:32:22.671905 | controller | XDG_SESSION_ID=1
2026-02-26 12:32:22.671910 | controller | ZUUL_BRANCH=main
2026-02-26 12:32:22.671915 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-02-26 12:32:22.671920 | controller | SSH_CLIENT=192.168.25.12 45416 22
2026-02-26 12:32:22.671925 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-02-26 12:32:22.671930 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-02-26 12:32:22.671935 | controller | which_declare=declare -f
2026-02-26 12:32:22.671940 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-02-26 12:32:22.671945 | controller | SELINUX_LEVEL_REQUESTED=
2026-02-26 12:32:22.671949 | controller | ZUUL_CHANGE=3678
2026-02-26 12:32:22.671954 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-02-26 12:32:22.671959 | controller | ZUUL_UUID=42146453665d413694d4fae1e2a32b2e
2026-02-26 12:32:22.671964 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-02-26 12:32:22.671968 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-02-26 12:32:22.671973 | controller | }
2026-02-26 12:32:22.946169 | controller | ok: Runtime: 0:00:00.005272
2026-02-26 12:32:22.951234 | 
2026-02-26 12:32:22.951325 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-02-26 12:32:22.965302 | controller | skipping: Conditional result was False
2026-02-26 12:32:22.971006 | 
2026-02-26 12:32:22.971078 | TASK [Symlink /home/zuul-worker/workspace]
2026-02-26 12:32:23.516589 | controller | skipping: Conditional result was False
2026-02-26 12:32:23.521801 | 
2026-02-26 12:32:23.521867 | TASK [Ensure legacy workspace directory]
2026-02-26 12:32:23.772600 | controller | changed
2026-02-26 12:32:23.788936 | 
2026-02-26 12:32:23.788986 | PLAY RECAP
2026-02-26 12:32:23.789028 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-02-26 12:32:23.789051 | 
2026-02-26 12:32:23.858439 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-02-26 12:32:23.859135 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-02-26 12:32:24.312545 | 
2026-02-26 12:32:24.312634 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-02-26 12:32:24.330797 | 
2026-02-26 12:32:24.330864 | TASK [Create zuul-output directory]
2026-02-26 12:32:24.684035 | controller | changed
2026-02-26 12:32:24.688506 | 
2026-02-26 12:32:24.688574 | TASK [Slurp Zuul inventory test]
2026-02-26 12:32:24.934726 | controller -> localhost | ok
2026-02-26 12:32:24.940394 | 
2026-02-26 12:32:24.940482 | TASK [Save zuul inventory]
2026-02-26 12:32:26.052849 | controller | changed
2026-02-26 12:32:26.057181 | 
2026-02-26 12:32:26.057241 | TASK [Save zuul vars without the change_message]
2026-02-26 12:32:27.078731 | controller | changed
2026-02-26 12:32:27.096065 | 
2026-02-26 12:32:27.096116 | PLAY RECAP
2026-02-26 12:32:27.096154 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-26 12:32:27.096175 | 
2026-02-26 12:32:27.156573 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-02-26 12:32:27.157260 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-02-26 12:32:27.653384 | 
2026-02-26 12:32:27.653497 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-02-26 12:32:27.671709 | 
2026-02-26 12:32:27.671776 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-02-26 12:32:27.689503 | controller | ok
2026-02-26 12:32:27.704993 | 
2026-02-26 12:32:27.705077 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-02-26 12:32:27.728910 | controller | skipping: Conditional result was False
2026-02-26 12:32:27.734464 | 
2026-02-26 12:32:27.734535 | TASK [mirror-info-fork : Create /etc/ci]
2026-02-26 12:32:28.117140 | controller | ok
2026-02-26 12:32:28.122226 | 
2026-02-26 12:32:28.122332 | TASK [mirror-info-fork : Install ci_mirror script]
2026-02-26 12:32:29.117239 | controller | ok
2026-02-26 12:32:29.126307 | 
2026-02-26 12:32:29.126374 | TASK [Prepare workspace]
2026-02-26 12:32:29.144489 | controller | ok
2026-02-26 12:32:29.160790 | 
2026-02-26 12:32:29.160858 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-26 12:32:29.507296 | controller | ok
2026-02-26 12:32:29.512963 | 
2026-02-26 12:32:29.513041 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-26 12:32:31.945655 | controller | Output suppressed because no_log was given
2026-02-26 12:32:31.954386 | 
2026-02-26 12:32:31.954451 | LOOP [Create zuul-output directory]
2026-02-26 12:32:32.221387 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-02-26 12:32:32.437653 | controller | ok: "/home/zuul/zuul-output/logs"
2026-02-26 12:32:32.452411 | 
2026-02-26 12:32:32.452507 | TASK [Install required packages]
2026-02-26 12:32:50.684121 | controller | ok: Nothing to do
2026-02-26 12:32:50.688642 | 
2026-02-26 12:32:50.688698 | TASK [Install venv]
2026-02-26 12:33:49.381530 | controller | changed
2026-02-26 12:33:49.403849 | 
2026-02-26 12:33:49.403905 | PLAY RECAP
2026-02-26 12:33:49.403947 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-26 12:33:49.403968 | 
2026-02-26 12:33:49.467910 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-02-26 12:33:49.468684 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-02-26 12:33:49.936721 | 
2026-02-26 12:33:49.936813 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-02-26 12:33:49.955315 | 
2026-02-26 12:33:49.955381 | TASK [Gather required facts]
2026-02-26 12:33:50.472190 | controller | ok
2026-02-26 12:33:50.476938 | 
2026-02-26 12:33:50.477006 | TASK [Load environment var if instructed to]
2026-02-26 12:33:50.500880 | controller | skipping: Conditional result was False
2026-02-26 12:33:50.505924 | 
2026-02-26 12:33:50.505986 | TASK [Ensure group_vars dir exists]
2026-02-26 12:33:50.859207 | controller | ok
2026-02-26 12:33:50.864071 | 
2026-02-26 12:33:50.864131 | TASK [Print related variables]
2026-02-26 12:33:50.893019 | controller | ok:
2026-02-26 12:33:50.893131 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-02-26 12:33:50.893160 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/sushy_emulator
2026-02-26 12:33:50.897537 | 
2026-02-26 12:33:50.897595 | TASK [Run molecule]
2026-02-26 12:33:51.894589 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-02-26 12:33:51.961372 | controller | INFO     Performing prerun with role_name_check=0...
2026-02-26 12:34:09.088329 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/8baae2/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 12:34:09.088738 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/8baae2/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 12:34:09.089149 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/8baae2/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 12:34:09.089557 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/8baae2/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 12:34:09.089984 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+79bd51ef was found installed in /home/zuul/.cache/ansible-compat/8baae2/collections/ansible_collections, only the first one will be used, 1.0.0+79bd51ef (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 12:34:09.090413 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/8baae2/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 12:34:09.090856 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/8baae2/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 12:34:09.091302 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/8baae2/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 12:34:09.091731 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/8baae2/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 12:34:09.092161 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/8baae2/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 12:34:09.092588 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/8baae2/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 12:34:09.093031 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/8baae2/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 12:34:09.093443 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/8baae2/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 12:34:09.093907 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/8baae2/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 12:34:09.094328 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/8baae2/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 12:34:09.104227 | controller | INFO     Running default > prepare
2026-02-26 12:34:09.755337 | controller |
2026-02-26 12:34:09.755396 | controller | PLAY [Prepare] *****************************************************************
2026-02-26 12:34:09.755519 | controller |
2026-02-26 12:34:09.755591 | controller | TASK [Gathering Facts] *********************************************************
2026-02-26 12:34:09.755633 | controller | Thursday 26 February 2026  12:34:09 +0000 (0:00:00.012)       0:00:00.012 *****
2026-02-26 12:34:10.662652 | controller | ok: [instance]
2026-02-26 12:34:10.662704 | controller |
2026-02-26 12:34:10.662834 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2026-02-26 12:34:10.662936 | controller | Thursday 26 February 2026  12:34:10 +0000 (0:00:00.908)       0:00:00.921 *****
2026-02-26 12:34:10.686503 | controller | skipping: [instance]
2026-02-26 12:34:10.686687 | controller |
2026-02-26 12:34:10.686703 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2026-02-26 12:34:10.686808 | controller | Thursday 26 February 2026  12:34:10 +0000 (0:00:00.024)       0:00:00.945 *****
2026-02-26 12:34:10.733906 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2026-02-26 12:34:10.733997 | controller |
2026-02-26 12:34:10.734137 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2026-02-26 12:34:10.734269 | controller | Thursday 26 February 2026  12:34:10 +0000 (0:00:00.047)       0:00:00.992 *****
2026-02-26 12:34:11.041227 | controller | ok: [instance]
2026-02-26 12:34:11.041310 | controller |
2026-02-26 12:34:11.041428 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2026-02-26 12:34:11.041556 | controller | Thursday 26 February 2026  12:34:11 +0000 (0:00:00.307)       0:00:01.299 *****
2026-02-26 12:34:11.381159 | controller | ok: [instance]
2026-02-26 12:34:11.381204 | controller |
2026-02-26 12:34:11.381338 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2026-02-26 12:34:11.381512 | controller | Thursday 26 February 2026  12:34:11 +0000 (0:00:00.339)       0:00:01.639 *****
2026-02-26 12:34:12.139023 | controller | ok: [instance] => (item=ubi-9-appstream)
2026-02-26 12:34:12.139068 | controller | ok: [instance] => (item=ubi-9-baseos)
2026-02-26 12:34:12.139188 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2026-02-26 12:34:12.139273 | controller |
2026-02-26 12:34:12.139369 | controller | TASK [Deploy repo-setup] *******************************************************
2026-02-26 12:34:12.139463 | controller | Thursday 26 February 2026  12:34:12 +0000 (0:00:00.758)       0:00:02.397 *****
2026-02-26 12:34:12.200800 | controller |
2026-02-26 12:34:12.200916 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2026-02-26 12:34:12.201036 | controller | Thursday 26 February 2026  12:34:12 +0000 (0:00:00.062)       0:00:02.459 *****
2026-02-26 12:34:12.772478 | controller | changed: [instance] => (item=tmp)
2026-02-26 12:34:12.772529 | controller | changed: [instance] => (item=artifacts/repositories)
2026-02-26 12:34:12.772883 | controller | changed: [instance] => (item=venv/repo_setup)
2026-02-26 12:34:12.772972 | controller |
2026-02-26 12:34:12.773072 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2026-02-26 12:34:12.773172 | controller | Thursday 26 February 2026  12:34:12 +0000 (0:00:00.570)       0:00:03.030 *****
2026-02-26 12:34:13.662846 | controller | ok: [instance]
2026-02-26 12:34:13.662915 | controller |
2026-02-26 12:34:13.663170 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2026-02-26 12:34:14.806549 | controller | Thursday 26 February 2026  12:34:13 +0000 (0:00:00.890)       0:00:03.921 *****
2026-02-26 12:34:14.806605 | controller | changed: [instance]
2026-02-26 12:34:22.301622 | controller |
2026-02-26 12:34:22.301700 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2026-02-26 12:34:22.301714 | controller | Thursday 26 February 2026  12:34:14 +0000 (0:00:01.143)       0:00:05.064 *****
2026-02-26 12:34:22.301726 | controller | changed: [instance]
2026-02-26 12:34:23.021735 | controller |
2026-02-26 12:34:23.021794 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2026-02-26 12:34:23.021804 | controller | Thursday 26 February 2026  12:34:22 +0000 (0:00:07.495)       0:00:12.559 *****
2026-02-26 12:34:23.021817 | controller | changed: [instance]
2026-02-26 12:34:23.021855 | controller |
2026-02-26 12:34:23.021864 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2026-02-26 12:34:23.050640 | controller | Thursday 26 February 2026  12:34:23 +0000 (0:00:00.720)       0:00:13.279 *****
2026-02-26 12:34:23.050734 | controller | skipping: [instance]
2026-02-26 12:34:23.867718 | controller |
2026-02-26 12:34:23.867773 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2026-02-26 12:34:23.867784 | controller | Thursday 26 February 2026  12:34:23 +0000 (0:00:00.029)       0:00:13.309 *****
2026-02-26 12:34:23.867798 | controller | changed: [instance]
2026-02-26 12:34:23.913586 | controller |
2026-02-26 12:34:23.913633 | controller | TASK [repo_setup : Get component repo] *****************************************
2026-02-26 12:34:23.913642 | controller | Thursday 26 February 2026  12:34:23 +0000 (0:00:00.816)       0:00:14.125 *****
2026-02-26 12:34:23.913656 | controller | skipping: [instance]
2026-02-26 12:34:23.953180 | controller |
2026-02-26 12:34:23.953202 | controller | TASK [repo_setup : Rename component repo] **************************************
2026-02-26 12:34:23.953212 | controller | Thursday 26 February 2026  12:34:23 +0000 (0:00:00.046)       0:00:14.172 *****
2026-02-26 12:34:23.953222 | controller | skipping: [instance]
2026-02-26 12:34:23.996685 | controller |
2026-02-26 12:34:23.996731 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2026-02-26 12:34:23.996741 | controller | Thursday 26 February 2026  12:34:23 +0000 (0:00:00.039)       0:00:14.211 *****
2026-02-26 12:34:23.996759 | controller | skipping: [instance]
2026-02-26 12:34:23.996837 | controller |
2026-02-26 12:34:23.996873 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2026-02-26 12:34:24.545608 | controller | Thursday 26 February 2026  12:34:23 +0000 (0:00:00.043)       0:00:14.255 *****
2026-02-26 12:34:24.545658 | controller | changed: [instance]
2026-02-26 12:34:24.992239 | controller |
2026-02-26 12:34:24.992267 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2026-02-26 12:34:24.992274 | controller | Thursday 26 February 2026  12:34:24 +0000 (0:00:00.548)       0:00:14.804 *****
2026-02-26 12:34:24.992285 | controller | changed: [instance]
2026-02-26 12:34:25.025318 | controller |
2026-02-26 12:34:25.025341 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2026-02-26 12:34:25.025348 | controller | Thursday 26 February 2026  12:34:24 +0000 (0:00:00.446)       0:00:15.251 *****
2026-02-26 12:34:25.025357 | controller | skipping: [instance]
2026-02-26 12:34:25.056451 | controller |
2026-02-26 12:34:25.056470 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2026-02-26 12:34:25.056477 | controller | Thursday 26 February 2026  12:34:25 +0000 (0:00:00.032)       0:00:15.284 *****
2026-02-26 12:34:25.056491 | controller | skipping: [instance]
2026-02-26 12:34:25.093394 | controller |
2026-02-26 12:34:25.093414 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2026-02-26 12:34:25.093423 | controller | Thursday 26 February 2026  12:34:25 +0000 (0:00:00.031)       0:00:15.315 *****
2026-02-26 12:34:25.093433 | controller | skipping: [instance]
2026-02-26 12:34:25.134419 | controller |
2026-02-26 12:34:25.134448 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2026-02-26 12:34:25.134461 | controller | Thursday 26 February 2026  12:34:25 +0000 (0:00:00.037)       0:00:15.352 *****
2026-02-26 12:34:25.134476 | controller | ok: [instance]
2026-02-26 12:34:25.166769 | controller |
2026-02-26 12:34:25.166829 | controller | TASK [repo_setup : Create download directory] **********************************
2026-02-26 12:34:25.166840 | controller | Thursday 26 February 2026  12:34:25 +0000 (0:00:00.040)       0:00:15.392 *****
2026-02-26 12:34:25.166855 | controller | skipping: [instance]
2026-02-26 12:34:25.196359 | controller |
2026-02-26 12:34:25.196396 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2026-02-26 12:34:25.196409 | controller | Thursday 26 February 2026  12:34:25 +0000 (0:00:00.032)       0:00:15.425 *****
2026-02-26 12:34:25.196443 | controller | skipping: [instance]
2026-02-26 12:34:25.226793 | controller |
2026-02-26 12:34:25.226810 | controller | TASK [Download the RPM] ********************************************************
2026-02-26 12:34:25.226817 | controller | Thursday 26 February 2026  12:34:25 +0000 (0:00:00.029)       0:00:15.454 *****
2026-02-26 12:34:25.226825 | controller | skipping: [instance]
2026-02-26 12:34:25.226951 | controller |
2026-02-26 12:34:25.227079 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2026-02-26 12:34:25.227201 | controller | Thursday 26 February 2026  12:34:25 +0000 (0:00:00.030)       0:00:15.485 *****
2026-02-26 12:34:25.255856 | controller | skipping: [instance]
2026-02-26 12:34:25.256094 | controller |
2026-02-26 12:34:25.256319 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2026-02-26 12:34:25.256577 | controller | Thursday 26 February 2026  12:34:25 +0000 (0:00:00.029)       0:00:15.514 *****
2026-02-26 12:34:25.289033 | controller | skipping: [instance]
2026-02-26 12:34:25.289196 | controller |
2026-02-26 12:34:25.289352 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2026-02-26 12:34:25.289499 | controller | Thursday 26 February 2026  12:34:25 +0000 (0:00:00.033)       0:00:15.548 *****
2026-02-26 12:34:25.319895 | controller | skipping: [instance]
2026-02-26 12:34:25.320057 | controller |
2026-02-26 12:34:25.320214 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2026-02-26 12:34:25.320361 | controller | Thursday 26 February 2026  12:34:25 +0000 (0:00:00.030)       0:00:15.579 *****
2026-02-26 12:34:25.348348 | controller | skipping: [instance]
2026-02-26 12:34:25.348674 | controller |
2026-02-26 12:34:25.348922 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2026-02-26 12:34:25.349089 | controller | Thursday 26 February 2026  12:34:25 +0000 (0:00:00.028)       0:00:15.607 *****
2026-02-26 12:34:25.541497 | controller | ok: [instance]
2026-02-26 12:34:25.756767 | controller |
2026-02-26 12:34:25.756824 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2026-02-26 12:34:25.756833 | controller | Thursday 26 February 2026  12:34:25 +0000 (0:00:00.192)       0:00:15.800 *****
2026-02-26 12:34:25.756847 | controller | changed: [instance]
2026-02-26 12:34:25.990196 | controller |
2026-02-26 12:34:25.990232 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2026-02-26 12:34:25.990241 | controller | Thursday 26 February 2026  12:34:25 +0000 (0:00:00.215)       0:00:16.015 *****
2026-02-26 12:34:25.990252 | controller | changed: [instance]
2026-02-26 12:34:26.013264 | controller |
2026-02-26 12:34:26.013282 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2026-02-26 12:34:26.013289 | controller | Thursday 26 February 2026  12:34:25 +0000 (0:00:00.233)       0:00:16.248 *****
2026-02-26 12:34:26.013298 | controller | skipping: [instance]
2026-02-26 12:34:26.034702 | controller |
2026-02-26 12:34:26.034762 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2026-02-26 12:34:26.034772 | controller | Thursday 26 February 2026  12:34:26 +0000 (0:00:00.023)       0:00:16.271 *****
2026-02-26 12:34:26.034787 | controller | skipping: [instance]
2026-02-26 12:34:26.062571 | controller |
2026-02-26 12:34:26.062600 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2026-02-26 12:34:26.062610 | controller | Thursday 26 February 2026  12:34:26 +0000 (0:00:00.021)       0:00:16.293 *****
2026-02-26 12:34:26.062622 | controller | skipping: [instance]
2026-02-26 12:34:26.086714 | controller |
2026-02-26 12:34:26.086761 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2026-02-26 12:34:26.086771 | controller | Thursday 26 February 2026  12:34:26 +0000 (0:00:00.028)       0:00:16.321 *****
2026-02-26 12:34:26.086787 | controller | skipping: [instance]
2026-02-26 12:34:26.114271 | controller |
2026-02-26 12:34:26.114302 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2026-02-26 12:34:26.114322 | controller | Thursday 26 February 2026  12:34:26 +0000 (0:00:00.024)       0:00:16.345 *****
2026-02-26 12:34:26.114334 | controller | skipping: [instance]
2026-02-26 12:34:26.142695 | controller |
2026-02-26 12:34:26.142736 | controller | TASK [repo_setup : Lower the priority of component repos to allow installation from gating repo] ***
2026-02-26 12:34:26.142756 | controller | Thursday 26 February 2026  12:34:26 +0000 (0:00:00.027)       0:00:16.372 *****
2026-02-26 12:34:26.142768 | controller | skipping: [instance]
2026-02-26 12:34:26.142801 | controller |
2026-02-26 12:34:26.142811 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2026-02-26 12:34:26.142897 | controller | Thursday 26 February 2026  12:34:26 +0000 (0:00:00.028)       0:00:16.401 *****
2026-02-26 12:34:26.160967 | controller | skipping: [instance]
2026-02-26 12:34:26.161085 | controller |
2026-02-26 12:34:26.161245 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2026-02-26 12:34:26.161347 | controller | Thursday 26 February 2026  12:34:26 +0000 (0:00:00.018)       0:00:16.420 *****
2026-02-26 12:34:26.196445 | controller | skipping: [instance]
2026-02-26 12:34:26.196627 | controller |
2026-02-26 12:34:26.196747 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2026-02-26 12:34:26.196861 | controller | Thursday 26 February 2026  12:34:26 +0000 (0:00:00.035)       0:00:16.455 *****
2026-02-26 12:34:26.220325 | controller | skipping: [instance]
2026-02-26 12:34:26.220448 | controller |
2026-02-26 12:34:26.220641 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2026-02-26 12:34:26.220744 | controller | Thursday 26 February 2026  12:34:26 +0000 (0:00:00.024)       0:00:16.479 *****
2026-02-26 12:34:26.242176 | controller | skipping: [instance]
2026-02-26 12:34:26.242286 | controller |
2026-02-26 12:34:26.242299 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2026-02-26 12:34:26.242312 | controller | Thursday 26 February 2026  12:34:26 +0000 (0:00:00.021)       0:00:16.500 *****
2026-02-26 12:34:26.276637 | controller | skipping: [instance]
2026-02-26 12:34:26.276804 | controller |
2026-02-26 12:34:26.276915 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2026-02-26 12:34:26.277078 | controller | Thursday 26 February 2026  12:34:26 +0000 (0:00:00.034)       0:00:16.535 *****
2026-02-26 12:34:26.307471 | controller | skipping: [instance]
2026-02-26 12:34:26.307713 | controller |
2026-02-26 12:34:26.307902 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2026-02-26 12:34:26.307973 | controller | Thursday 26 February 2026  12:34:26 +0000 (0:00:00.030)       0:00:16.566 *****
2026-02-26 12:34:26.342986 | controller | skipping: [instance]
2026-02-26 12:34:26.343130 | controller |
2026-02-26 12:34:26.343241 | controller | TASK [test_deps : Install additional packages] *********************************
2026-02-26 12:34:26.343399 | controller | Thursday 26 February 2026  12:34:26 +0000 (0:00:00.035)       0:00:16.601 *****
2026-02-26 12:34:26.375468 | controller | skipping: [instance]
2026-02-26 12:34:26.375604 | controller |
2026-02-26 12:34:26.375808 | controller | TASK [test_deps : Install selinux python libs] *********************************
2026-02-26 12:34:26.375923 | controller | Thursday 26 February 2026  12:34:26 +0000 (0:00:00.032)       0:00:16.634 *****
2026-02-26 12:35:12.954617 | controller | ok: [instance]
2026-02-26 12:35:14.128176 | controller |
2026-02-26 12:35:14.128242 | controller | TASK [test_deps : Install python yaml libs] ************************************
2026-02-26 12:35:14.128251 | controller | Thursday 26 February 2026  12:35:12 +0000 (0:00:46.575)       0:01:03.210 *****
2026-02-26 12:35:14.128268 | controller | ok: [instance]
2026-02-26 12:35:14.163474 | controller |
2026-02-26 12:35:14.163515 | controller | TASK [test_deps : Install extra packages] **************************************
2026-02-26 12:35:14.163523 | controller | Thursday 26 February 2026  12:35:14 +0000 (0:00:01.175)       0:01:04.386 *****
2026-02-26 12:35:14.163553 | controller | skipping: [instance]
2026-02-26 12:41:06.276450 | controller |
2026-02-26 12:41:06.276488 | controller | TASK [Ensure CRC is started] ***************************************************
2026-02-26 12:41:06.276495 | controller | Thursday 26 February 2026  12:35:14 +0000 (0:00:00.035)       0:01:04.422 *****
2026-02-26 12:41:06.276505 | controller | fatal: [instance]: FAILED! => changed=true
2026-02-26 12:41:06.277744 | controller |   cmd:
2026-02-26 12:41:06.277780 | controller |   - crc
2026-02-26 12:41:06.277794 | controller |   - start
2026-02-26 12:41:06.277828 | controller |   delta: '0:05:51.934898'
2026-02-26 12:41:06.277835 | controller |   end: '2026-02-26 12:41:06.244676'
2026-02-26 12:41:06.277841 | controller |   msg: non-zero return code
2026-02-26 12:41:06.277854 | controller |   rc: 1
2026-02-26 12:41:06.277859 | controller |   start: '2026-02-26 12:35:14.309778'
2026-02-26 12:41:06.277869 | controller |   stderr: |-
2026-02-26 12:41:06.277875 | controller |     level=warning msg="A new version (2.58.0) has been published on https://developers.redhat.com/content-gateway/file/pub/openshift-v4/clients/crc/2.58.0/crc-linux-amd64.tar.xz"
2026-02-26 12:41:06.277880 | controller |     level=info msg="Using bundle path /home/zuul/.crc/cache/crc_libvirt_4.18.1_amd64.crcbundle"
2026-02-26 12:41:06.277886 | controller |     level=info msg="Checking if running as non-root"
2026-02-26 12:41:06.277891 | controller |     level=info msg="Checking if running inside WSL2"
2026-02-26 12:41:06.277896 | controller |     level=info msg="Checking if crc-admin-helper executable is cached"
2026-02-26 12:41:06.277901 | controller |     level=info msg="Checking if running on a supported CPU architecture"
2026-02-26 12:41:06.277906 | controller |     level=info msg="Checking if crc executable symlink exists"
2026-02-26 12:41:06.277911 | controller |     level=info msg="Checking minimum RAM requirements"
2026-02-26 12:41:06.277916 | controller |     level=info msg="Check if Podman binary exists in: /home/zuul/.crc/bin/oc"
2026-02-26 12:41:06.277922 | controller |     level=info msg="Checking if Virtualization is enabled"
2026-02-26 12:41:06.277947 | controller |     level=info msg="Checking if KVM is enabled"
2026-02-26 12:41:06.277970 | controller |     level=info msg="Checking if libvirt is installed"
2026-02-26 12:41:06.278189 | controller |     level=info msg="Checking if user is part of libvirt group"
2026-02-26 12:41:06.278216 | controller |     level=info msg="Checking if active user/process is currently part of the libvirt group"
2026-02-26 12:41:06.278224 | controller |     level=info msg="Checking if libvirt daemon is running"
2026-02-26 12:41:06.278440 | controller |     level=info msg="Checking if a supported libvirt version is installed"
2026-02-26 12:41:06.278604 | controller |     level=info msg="Checking if crc-driver-libvirt is installed"
2026-02-26 12:41:06.278640 | controller |     level=info msg="Checking crc daemon systemd socket units"
2026-02-26 12:41:06.278665 | controller |     level=info msg="Checking if systemd-networkd is running"
2026-02-26 12:41:06.278673 | controller |     level=info msg="Checking if NetworkManager is installed"
2026-02-26 12:41:06.279060 | controller |     level=info msg="Checking if NetworkManager service is running"
2026-02-26 12:41:06.279142 | controller |     level=info msg="Checking if /etc/NetworkManager/conf.d/crc-nm-dnsmasq.conf exists"
2026-02-26 12:41:06.279153 | controller |     level=info msg="Checking if /etc/NetworkManager/dnsmasq.d/crc.conf exists"
2026-02-26 12:41:06.279159 | controller |     level=info msg="Checking if libvirt 'crc' network is available"
2026-02-26 12:41:06.279167 | controller |     level=info msg="Checking if libvirt 'crc' network is active"
2026-02-26 12:41:06.280592 | controller |     level=info msg="Loading bundle: crc_libvirt_4.18.1_amd64..."
2026-02-26 12:41:06.280669 | controller |     level=info msg="Starting CRC VM for openshift 4.18.1..."
2026-02-26 12:41:06.280677 | controller |     level=info msg="CRC instance is running with IP 192.168.130.11"
2026-02-26 12:41:06.280683 | controller |     level=info msg="CRC VM is running"
2026-02-26 12:41:06.280688 | controller |     level=info msg="Updating authorized keys..."
2026-02-26 12:41:06.280693 | controller |     level=info msg="Configuring shared directories"
2026-02-26 12:41:06.280698 | controller |     level=info msg="Check internal and public DNS query..."
2026-02-26 12:41:06.280703 | controller |     level=info msg="Check DNS query from host..."
2026-02-26 12:41:06.280708 | controller |     level=info msg="Verifying validity of the kubelet certificates..."
2026-02-26 12:41:06.280714 | controller |     level=info msg="Starting kubelet service"
2026-02-26 12:41:06.280719 | controller |     level=info msg="Kubelet serving certificate has expired, waiting for automatic renewal... [will take up to 5 minutes]"
2026-02-26 12:41:06.280724 | controller |     Failed to renew TLS certificates: please check if a newer CRC release is available: Temporary error: certificate /var/lib/kubelet/pki/kubelet-server-current.pem still expired (x59)
2026-02-26 12:41:06.280729 | controller |   stderr_lines: <omitted>
2026-02-26 12:41:06.280734 | controller |   stdout: ''
2026-02-26 12:41:06.280740 | controller |   stdout_lines: <omitted>
2026-02-26 12:41:06.280745 | controller |
2026-02-26 12:41:06.280750 | controller | PLAY RECAP *********************************************************************
2026-02-26 12:41:06.280762 | controller | instance                   : ok=19   changed=9    unreachable=0    failed=1    skipped=30   rescued=0    ignored=0
2026-02-26 12:41:06.280784 | controller |
2026-02-26 12:41:06.280893 | controller | Thursday 26 February 2026  12:41:06 +0000 (0:05:52.114)       0:06:56.536 *****
2026-02-26 12:41:06.281002 | controller | ===============================================================================
2026-02-26 12:41:06.281109 | controller | Ensure CRC is started ------------------------------------------------- 352.11s
2026-02-26 12:41:06.281200 | controller | test_deps : Install selinux python libs -------------------------------- 46.58s
2026-02-26 12:41:06.281305 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.50s
2026-02-26 12:41:06.281399 | controller | test_deps : Install python yaml libs ------------------------------------ 1.18s
2026-02-26 12:41:06.281508 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.14s
2026-02-26 12:41:06.281625 | controller | Gathering Facts --------------------------------------------------------- 0.91s
2026-02-26 12:41:06.281746 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.89s
2026-02-26 12:41:06.281849 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.82s
2026-02-26 12:41:06.281948 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.76s
2026-02-26 12:41:06.282052 | controller | repo_setup : Install repo-setup package --------------------------------- 0.72s
2026-02-26 12:41:06.282151 | controller | repo_setup : Ensure directories are present ----------------------------- 0.57s
2026-02-26 12:41:06.282247 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.55s
2026-02-26 12:41:06.282345 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.45s
2026-02-26 12:41:06.282453 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.34s
2026-02-26 12:41:06.282567 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.31s
2026-02-26 12:41:06.282682 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.23s
2026-02-26 12:41:06.282780 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.22s
2026-02-26 12:41:06.282881 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.19s
2026-02-26 12:41:06.282978 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2026-02-26 12:41:06.283073 | controller | test_deps : Gather variables for each operating system ------------------ 0.05s
2026-02-26 12:41:06.334931 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/sushy_emulator/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/sushy_emulator/molecule/default/prepare.yml']
2026-02-26 12:41:06.335308 | controller | WARNING  An error occurred during the test sequence action: 'prepare'. Cleaning up.
2026-02-26 12:41:06.344322 | controller | INFO     Running default > cleanup
2026-02-26 12:41:06.345091 | controller | WARNING  Skipping, cleanup playbook not configured.
2026-02-26 12:41:06.352225 | controller | INFO     Running default > destroy
2026-02-26 12:41:06.352526 | controller | WARNING  Skipping, instances are delegated.
2026-02-26 12:41:06.352837 | controller | INFO     Pruning extra files from scenario ephemeral directory
2026-02-26 12:41:06.354231 | controller | INFO     Writing /tmp/report.html report.
2026-02-26 12:41:06.443797 | controller | ERROR
2026-02-26 12:41:06.443925 | controller | {
2026-02-26 12:41:06.443955 | controller |   "delta": "0:07:15.158312",
2026-02-26 12:41:06.443977 | controller |   "end": "2026-02-26 12:41:06.410861",
2026-02-26 12:41:06.443995 | controller |   "msg": "non-zero return code",
2026-02-26 12:41:06.444011 | controller |   "rc": 1,
2026-02-26 12:41:06.444026 | controller |   "start": "2026-02-26 12:33:51.252549"
2026-02-26 12:41:06.444040 | controller | }
failure
2026-02-26 12:41:06.447824 | 
2026-02-26 12:41:06.447890 | PLAY RECAP
2026-02-26 12:41:06.447928 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-02-26 12:41:06.447948 | 
2026-02-26 12:41:06.505330 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-02-26 12:41:06.505995 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-02-26 12:41:06.986372 | 
2026-02-26 12:41:06.986464 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-02-26 12:41:07.004733 | 
2026-02-26 12:41:07.004802 | TASK [Filter out host if needed]
2026-02-26 12:41:07.012533 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-02-26 12:41:07.016557 | 
2026-02-26 12:41:07.016617 | TASK [Ensure file is present]
2026-02-26 12:41:07.390881 | controller | ok
2026-02-26 12:41:07.395873 | 
2026-02-26 12:41:07.395934 | TASK [Manage molecule report file]
2026-02-26 12:41:07.919843 | controller | changed
2026-02-26 12:41:07.924634 | 
2026-02-26 12:41:07.924691 | TASK [Check if we get ci-framework-data basedir]
2026-02-26 12:41:08.167098 | controller | ok
2026-02-26 12:41:08.171645 | 
2026-02-26 12:41:08.171701 | TASK [Create ci-framework-data log directory for zuul]
2026-02-26 12:41:08.535877 | controller | changed
2026-02-26 12:41:08.540778 | 
2026-02-26 12:41:08.540842 | TASK [Copy ci-framework interesting files]
2026-02-26 12:41:08.783783 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-02-26 12:41:09.066921 | controller | changed
2026-02-26 12:41:09.071556 | 
2026-02-26 12:41:09.071614 | TASK [Get SELinux listing]
2026-02-26 12:41:09.595745 | controller | changed
2026-02-26 12:41:09.600874 | 
2026-02-26 12:41:09.600936 | TASK [Generate log index]
2026-02-26 12:41:11.040377 | controller | changed
2026-02-26 12:41:11.045079 | 
2026-02-26 12:41:11.045146 | TASK [Get some env related data]
2026-02-26 12:41:11.847011 | controller | /home/zuul/.local/bin/ansible
2026-02-26 12:41:12.571051 | controller | changed
2026-02-26 12:41:12.575956 | 
2026-02-26 12:41:12.576017 | TASK [Generate list of logs to collect in home directory]
2026-02-26 12:41:13.019043 | controller | ok: All paths examined
2026-02-26 12:41:13.024074 | 
2026-02-26 12:41:13.024135 | LOOP [Copy logs from home directory]
2026-02-26 12:41:13.870541 | controller | changed:
2026-02-26 12:41:13.870747 | controller | {
2026-02-26 12:41:13.870776 | controller |   "atime": 1763989167.9348345,
2026-02-26 12:41:13.870797 | controller |   "ctime": 1763990251.6129293,
2026-02-26 12:41:13.870815 | controller |   "dev": 64513,
2026-02-26 12:41:13.870832 | controller |   "gid": 1000,
2026-02-26 12:41:13.870848 | controller |   "gr_name": "zuul",
2026-02-26 12:41:13.870864 | controller |   "inode": 5131423,
2026-02-26 12:41:13.870879 | controller |   "isblk": false,
2026-02-26 12:41:13.870894 | controller |   "ischr": false,
2026-02-26 12:41:13.870908 | controller |   "isdir": false,
2026-02-26 12:41:13.870922 | controller |   "isfifo": false,
2026-02-26 12:41:13.870937 | controller |   "isgid": false,
2026-02-26 12:41:13.870951 | controller |   "islnk": false,
2026-02-26 12:41:13.870965 | controller |   "isreg": true,
2026-02-26 12:41:13.870979 | controller |   "issock": false,
2026-02-26 12:41:13.870992 | controller |   "isuid": false,
2026-02-26 12:41:13.871006 | controller |   "mode": "0644",
2026-02-26 12:41:13.871019 | controller |   "mtime": 1763990251.6129293,
2026-02-26 12:41:13.871034 | controller |   "nlink": 1,
2026-02-26 12:41:13.871049 | controller |   "path": "/home/zuul/crc-setup.log",
2026-02-26 12:41:13.871065 | controller |   "pw_name": "zuul",
2026-02-26 12:41:13.871080 | controller |   "rgrp": true,
2026-02-26 12:41:13.871094 | controller |   "roth": true,
2026-02-26 12:41:13.871108 | controller |   "rusr": true,
2026-02-26 12:41:13.871123 | controller |   "size": 4108,
2026-02-26 12:41:13.871136 | controller |   "uid": 1000,
2026-02-26 12:41:13.871152 | controller |   "wgrp": false,
2026-02-26 12:41:13.871166 | controller |   "woth": false,
2026-02-26 12:41:13.871182 | controller |   "wusr": true,
2026-02-26 12:41:13.871198 | controller |   "xgrp": false,
2026-02-26 12:41:13.871212 | controller |   "xoth": false,
2026-02-26 12:41:13.871225 | controller |   "xusr": false
2026-02-26 12:41:13.871239 | controller | }
2026-02-26 12:41:14.647001 | controller | changed:
2026-02-26 12:41:14.647104 | controller | {
2026-02-26 12:41:14.647128 | controller |   "atime": 1763990253.7119775,
2026-02-26 12:41:14.647146 | controller |   "ctime": 1763991032.5164254,
2026-02-26 12:41:14.647162 | controller |   "dev": 64513,
2026-02-26 12:41:14.647177 | controller |   "gid": 1000,
2026-02-26 12:41:14.647191 | controller |   "gr_name": "zuul",
2026-02-26 12:41:14.647205 | controller |   "inode": 5131425,
2026-02-26 12:41:14.647218 | controller |   "isblk": false,
2026-02-26 12:41:14.647232 | controller |   "ischr": false,
2026-02-26 12:41:14.647245 | controller |   "isdir": false,
2026-02-26 12:41:14.647258 | controller |   "isfifo": false,
2026-02-26 12:41:14.647302 | controller |   "isgid": false,
2026-02-26 12:41:14.647320 | controller |   "islnk": false,
2026-02-26 12:41:14.647334 | controller |   "isreg": true,
2026-02-26 12:41:14.647348 | controller |   "issock": false,
2026-02-26 12:41:14.647361 | controller |   "isuid": false,
2026-02-26 12:41:14.647374 | controller |   "mode": "0644",
2026-02-26 12:41:14.647387 | controller |   "mtime": 1763991032.5164254,
2026-02-26 12:41:14.647401 | controller |   "nlink": 1,
2026-02-26 12:41:14.647414 | controller |   "path": "/home/zuul/crc-start.log",
2026-02-26 12:41:14.647427 | controller |   "pw_name": "zuul",
2026-02-26 12:41:14.647441 | controller |   "rgrp": true,
2026-02-26 12:41:14.647453 | controller |   "roth": true,
2026-02-26 12:41:14.647466 | controller |   "rusr": true,
2026-02-26 12:41:14.647479 | controller |   "size": 4428,
2026-02-26 12:41:14.647492 | controller |   "uid": 1000,
2026-02-26 12:41:14.647505 | controller |   "wgrp": false,
2026-02-26 12:41:14.647518 | controller |   "woth": false,
2026-02-26 12:41:14.647530 | controller |   "wusr": true,
2026-02-26 12:41:14.647542 | controller |   "xgrp": false,
2026-02-26 12:41:14.647563 | controller |   "xoth": false,
2026-02-26 12:41:14.647581 | controller |   "xusr": false
2026-02-26 12:41:14.647595 | controller | }
2026-02-26 12:41:15.378325 | controller | changed:
2026-02-26 12:41:15.378439 | controller | {
2026-02-26 12:41:15.378464 | controller |   "atime": 1772109211.642039,
2026-02-26 12:41:15.378482 | controller |   "ctime": 1772109227.9309878,
2026-02-26 12:41:15.378497 | controller |   "dev": 64513,
2026-02-26 12:41:15.378512 | controller |   "gid": 1000,
2026-02-26 12:41:15.378527 | controller |   "gr_name": "zuul",
2026-02-26 12:41:15.378542 | controller |   "inode": 5131449,
2026-02-26 12:41:15.378557 | controller |   "isblk": false,
2026-02-26 12:41:15.378571 | controller |   "ischr": false,
2026-02-26 12:41:15.378584 | controller |   "isdir": false,
2026-02-26 12:41:15.378597 | controller |   "isfifo": false,
2026-02-26 12:41:15.378611 | controller |   "isgid": false,
2026-02-26 12:41:15.378624 | controller |   "islnk": false,
2026-02-26 12:41:15.378636 | controller |   "isreg": true,
2026-02-26 12:41:15.378649 | controller |   "issock": false,
2026-02-26 12:41:15.378662 | controller |   "isuid": false,
2026-02-26 12:41:15.378676 | controller |   "mode": "0644",
2026-02-26 12:41:15.378688 | controller |   "mtime": 1772109227.9309878,
2026-02-26 12:41:15.378701 | controller |   "nlink": 1,
2026-02-26 12:41:15.378714 | controller |   "path": "/home/zuul/ansible.log",
2026-02-26 12:41:15.378728 | controller |   "pw_name": "zuul",
2026-02-26 12:41:15.378741 | controller |   "rgrp": true,
2026-02-26 12:41:15.378755 | controller |   "roth": true,
2026-02-26 12:41:15.378770 | controller |   "rusr": true,
2026-02-26 12:41:15.378784 | controller |   "size": 6749,
2026-02-26 12:41:15.378797 | controller |   "uid": 1000,
2026-02-26 12:41:15.378810 | controller |   "wgrp": false,
2026-02-26 12:41:15.378822 | controller |   "woth": false,
2026-02-26 12:41:15.378835 | controller |   "wusr": true,
2026-02-26 12:41:15.378847 | controller |   "xgrp": false,
2026-02-26 12:41:15.378860 | controller |   "xoth": false,
2026-02-26 12:41:15.378873 | controller |   "xusr": false
2026-02-26 12:41:15.378887 | controller | }
2026-02-26 12:41:15.388965 | 
2026-02-26 12:41:15.389173 | TASK [Copy crio stats log file]
2026-02-26 12:41:15.406152 | controller | skipping: Conditional result was False
2026-02-26 12:41:15.412849 | 
2026-02-26 12:41:15.412917 | TASK [Get SELinux related data]
2026-02-26 12:41:15.709829 | controller | <no matches>
2026-02-26 12:41:15.941970 | controller | ERROR
2026-02-26 12:41:15.942430 | controller | {
2026-02-26 12:41:15.942472 | controller |   "delta": "0:00:00.012109",
2026-02-26 12:41:15.942496 | controller |   "end": "2026-02-26 12:41:15.711436",
2026-02-26 12:41:15.942515 | controller |   "msg": "non-zero return code",
2026-02-26 12:41:15.942531 | controller |   "rc": 1,
2026-02-26 12:41:15.942546 | controller |   "start": "2026-02-26 12:41:15.699327"
2026-02-26 12:41:15.942561 | controller | }
2026-02-26 12:41:15.942584 | controller | ERROR: Ignoring Errors
2026-02-26 12:41:15.949156 | 
2026-02-26 12:41:15.949228 | TASK [Create system configuration directory]
2026-02-26 12:41:16.236052 | controller | changed
2026-02-26 12:41:16.241109 | 
2026-02-26 12:41:16.241180 | TASK [Get some of the system configurations]
2026-02-26 12:41:16.772064 | controller | changed
2026-02-26 12:41:16.778857 | 
2026-02-26 12:41:16.778943 | TASK [Copy generated documentation if available]
2026-02-26 12:41:16.806378 | controller | skipping: Conditional result was False
2026-02-26 12:41:16.812948 | 
2026-02-26 12:41:16.813096 | TASK [Copy generated AsciiDoc documentation if available]
2026-02-26 12:41:16.838030 | controller | skipping: Conditional result was False
2026-02-26 12:41:16.845705 | 
2026-02-26 12:41:16.845807 | TASK [Compress logs bigger than 2MB]
2026-02-26 12:41:17.377029 | controller | changed
2026-02-26 12:41:17.382888 | 
2026-02-26 12:41:17.382952 | TASK [Copy files from workspace on node]
2026-02-26 12:41:17.404562 | controller | ok
2026-02-26 12:41:17.427591 | 
2026-02-26 12:41:17.427656 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-26 12:41:17.451720 | controller | skipping: Conditional result was False
2026-02-26 12:41:17.457236 | 
2026-02-26 12:41:17.457324 | TASK [fetch-output : Set log path for single node]
2026-02-26 12:41:17.487652 | controller | ok
2026-02-26 12:41:17.492341 | 
2026-02-26 12:41:17.492404 | LOOP [fetch-output : Ensure local output dirs]
2026-02-26 12:41:17.680534 | controller -> localhost | ok: "/var/lib/zuul/builds/42146453665d413694d4fae1e2a32b2e/work/logs"
2026-02-26 12:41:17.680861 | controller -> localhost | changed: All items complete
2026-02-26 12:41:17.680907 | 
2026-02-26 12:41:17.862388 | controller -> localhost | changed: "/var/lib/zuul/builds/42146453665d413694d4fae1e2a32b2e/work/artifacts"
2026-02-26 12:41:18.078731 | controller -> localhost | changed: "/var/lib/zuul/builds/42146453665d413694d4fae1e2a32b2e/work/docs"
2026-02-26 12:41:18.097069 | 
2026-02-26 12:41:18.097237 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-26 12:41:18.758222 | controller | changed:
2026-02-26 12:41:18.758506 | controller | .d..t...... ./
2026-02-26 12:41:18.758538 | controller | >f+++++++++ README.html
2026-02-26 12:41:18.758561 | controller | >f+++++++++ ansible-execution.log
2026-02-26 12:41:18.758582 | controller | >f+++++++++ ansible.log
2026-02-26 12:41:18.758600 | controller | >f+++++++++ crc-setup.log
2026-02-26 12:41:18.758618 | controller | >f+++++++++ crc-start.log
2026-02-26 12:41:18.758636 | controller | >f+++++++++ dmesg.log
2026-02-26 12:41:18.758660 | controller | >f+++++++++ installed-pkgs.log
2026-02-26 12:41:18.758678 | controller | >f+++++++++ python.log
2026-02-26 12:41:18.758696 | controller | >f+++++++++ registries.conf
2026-02-26 12:41:18.758713 | controller | >f+++++++++ report.html
2026-02-26 12:41:18.758729 | controller | >f+++++++++ selinux-denials.log
2026-02-26 12:41:18.758745 | controller | >f+++++++++ selinux-listing.log
2026-02-26 12:41:18.758761 | controller | cd+++++++++ ci-framework-data/
2026-02-26 12:41:18.758778 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-02-26 12:41:18.758794 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-02-26 12:41:18.758811 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-02-26 12:41:18.758827 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-02-26 12:41:18.758843 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2026-02-26 12:41:18.758859 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2026-02-26 12:41:18.758875 | controller | cd+++++++++ ci-framework-data/logs/
2026-02-26 12:41:18.758891 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-02-26 12:41:18.758909 | controller | cd+++++++++ registries.conf.d/
2026-02-26 12:41:18.758926 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-02-26 12:41:18.758942 | controller | cd+++++++++ system-config/
2026-02-26 12:41:18.758958 | controller | cd+++++++++ system-config/libvirt/
2026-02-26 12:41:18.758975 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-02-26 12:41:18.758991 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-02-26 12:41:18.759007 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2026-02-26 12:41:18.759024 | controller | >f+++++++++ system-config/libvirt/network.conf
2026-02-26 12:41:18.759041 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2026-02-26 12:41:18.759058 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2026-02-26 12:41:18.759074 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2026-02-26 12:41:18.759090 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2026-02-26 12:41:18.759106 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2026-02-26 12:41:18.759122 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2026-02-26 12:41:18.759138 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2026-02-26 12:41:18.759159 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2026-02-26 12:41:18.759176 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2026-02-26 12:41:18.759191 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2026-02-26 12:41:18.759207 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2026-02-26 12:41:18.759222 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2026-02-26 12:41:19.241864 | controller | changed: .d..t...... ./
2026-02-26 12:41:19.715068 | controller | changed: .d..t...... ./
2026-02-26 12:41:19.736030 | 
2026-02-26 12:41:19.736150 | TASK [Return artifact to Zuul]
2026-02-26 12:41:19.765767 | controller | ok
2026-02-26 12:41:19.784699 | 
2026-02-26 12:41:19.784755 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-02-26 12:41:19.784856 | 
2026-02-26 12:41:19.784884 | PLAY RECAP
2026-02-26 12:41:19.784924 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-02-26 12:41:19.784947 | 
2026-02-26 12:41:19.865006 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-02-26 12:41:19.865697 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-02-26 12:41:20.343749 | 
2026-02-26 12:41:20.343843 | PLAY [all]
2026-02-26 12:41:20.361621 | 
2026-02-26 12:41:20.361694 | TASK [include_role : fetch-output]
2026-02-26 12:41:20.390845 | controller | ok
2026-02-26 12:41:20.407080 | 
2026-02-26 12:41:20.407159 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-26 12:41:20.441588 | controller | skipping: Conditional result was False
2026-02-26 12:41:20.446717 | 
2026-02-26 12:41:20.446787 | TASK [fetch-output : Set log path for single node]
2026-02-26 12:41:20.474609 | controller | ok
2026-02-26 12:41:20.479155 | 
2026-02-26 12:41:20.479219 | LOOP [fetch-output : Ensure local output dirs]
2026-02-26 12:41:20.780722 | controller -> localhost | ok: "/var/lib/zuul/builds/42146453665d413694d4fae1e2a32b2e/work/logs"
2026-02-26 12:41:20.956143 | controller -> localhost | ok: "/var/lib/zuul/builds/42146453665d413694d4fae1e2a32b2e/work/artifacts"
2026-02-26 12:41:21.129895 | controller -> localhost | ok: "/var/lib/zuul/builds/42146453665d413694d4fae1e2a32b2e/work/docs"
2026-02-26 12:41:21.146753 | 
2026-02-26 12:41:21.146857 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-26 12:41:21.738173 | controller | ok
2026-02-26 12:41:21.738483 | controller | ok: All items complete
2026-02-26 12:41:21.738518 | 
2026-02-26 12:41:22.177933 | controller | ok
2026-02-26 12:41:22.622482 | controller | ok
2026-02-26 12:41:22.638842 | 
2026-02-26 12:41:22.638950 | TASK [include_role : fetch-output-openshift]
2026-02-26 12:41:22.652299 | controller | skipping: Conditional result was False
2026-02-26 12:41:22.657646 | 
2026-02-26 12:41:22.657708 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-26 12:41:22.980326 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006069
2026-02-26 12:41:23.161891 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005952
2026-02-26 12:41:23.191978 | 
2026-02-26 12:41:23.192093 | PLAY [all]
2026-02-26 12:41:23.205662 | 
2026-02-26 12:41:23.205730 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-26 12:41:23.671187 | controller | changed
2026-02-26 12:41:23.692174 | 
2026-02-26 12:41:23.692226 | PLAY RECAP
2026-02-26 12:41:23.692303 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-02-26 12:41:23.692337 | 
2026-02-26 12:41:23.755761 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-02-26 12:41:23.756616 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-02-26 12:41:24.268913 | 
2026-02-26 12:41:24.269021 | PLAY [localhost]
2026-02-26 12:41:24.285390 | 
2026-02-26 12:41:24.285469 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-26 12:41:24.576453 | localhost | changed
2026-02-26 12:41:24.580794 | 
2026-02-26 12:41:24.580874 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-26 12:41:24.598596 | localhost | ok
2026-02-26 12:41:24.606222 | 
2026-02-26 12:41:24.606318 | TASK [add-fileserver : Create SSH private key tempfile]
2026-02-26 12:41:24.886641 | localhost | changed
2026-02-26 12:41:24.892233 | 
2026-02-26 12:41:24.892340 | TASK [add-fileserver : Create SSH private key from secret]
2026-02-26 12:41:25.406687 | localhost | changed
2026-02-26 12:41:25.411435 | 
2026-02-26 12:41:25.411501 | TASK [add-fileserver : Add fileserver ssh key]
2026-02-26 12:41:25.722190 | localhost | Identity added: /var/lib/zuul/builds/42146453665d413694d4fae1e2a32b2e/work/tmp/ansible.jqc9xtft (/var/lib/zuul/builds/42146453665d413694d4fae1e2a32b2e/work/tmp/ansible.jqc9xtft)
2026-02-26 12:41:25.722416 | localhost | ok: Runtime: 0:00:00.005440
2026-02-26 12:41:25.726328 | 
2026-02-26 12:41:25.726392 | TASK [add-fileserver : Remove SSH private key from disk]
2026-02-26 12:41:25.937412 | localhost | ok: Runtime: 0:00:00.004636
2026-02-26 12:41:25.942152 | 
2026-02-26 12:41:25.942240 | TASK [add-fileserver : Add fileserver to inventory]
2026-02-26 12:41:25.990053 | localhost | changed
2026-02-26 12:41:25.994961 | 
2026-02-26 12:41:25.995029 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-02-26 12:41:26.295638 | localhost | changed
2026-02-26 12:41:26.314340 | 
2026-02-26 12:41:26.314396 | PLAY [localhost]
2026-02-26 12:41:26.325207 | 
2026-02-26 12:41:26.325292 | TASK [Generate bulk log download script]
2026-02-26 12:41:26.342775 | localhost | ok
2026-02-26 12:41:26.353468 | 
2026-02-26 12:41:26.353533 | TASK [local-log-download : Check API endpoint is defined]
2026-02-26 12:41:26.380248 | localhost | ok: All assertions passed
2026-02-26 12:41:26.384629 | 
2026-02-26 12:41:26.384693 | TASK [local-log-download : Create download script]
2026-02-26 12:41:26.721670 | localhost -> localhost | changed
2026-02-26 12:41:26.729931 | 
2026-02-26 12:41:26.729996 | TASK [Register quick-download link]
2026-02-26 12:41:26.747136 | localhost | ok
2026-02-26 12:41:26.780318 | 
2026-02-26 12:41:26.780387 | PLAY [logserver.rdoproject.org]
2026-02-26 12:41:26.790184 | 
2026-02-26 12:41:26.790245 | TASK [Set zuul-log-path fact]
2026-02-26 12:41:26.805885 | logserver.rdoproject.org | ok
2026-02-26 12:41:26.814471 | 
2026-02-26 12:41:26.814536 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-26 12:41:26.830788 | logserver.rdoproject.org | ok
2026-02-26 12:41:26.836064 | 
2026-02-26 12:41:26.836130 | TASK [upload-logs : Create log directories]
2026-02-26 12:41:28.691056 | logserver.rdoproject.org | changed
2026-02-26 12:41:28.693987 | 
2026-02-26 12:41:28.694045 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-26 12:41:28.900510 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.003779
2026-02-26 12:41:28.905112 | 
2026-02-26 12:41:28.905181 | TASK [upload-logs : Upload logs to log server]
2026-02-26 12:41:30.382704 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-02-26 12:41:30.385450 | 
2026-02-26 12:41:30.385516 | LOOP [upload-logs : Compress console log and json output]
2026-02-26 12:41:30.420444 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-26 12:41:30.428430 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-26 12:41:30.441966 | 
2026-02-26 12:41:30.442055 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-26 12:41:30.472377 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-26 12:41:30.472567 | 
2026-02-26 12:41:30.475758 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-26 12:41:30.488497 | 
2026-02-26 12:41:30.488576 | LOOP [upload-logs : Upload console log and json output]