2026-06-12 10:17:42.955557 | Job console starting
2026-06-12 10:17:43.077645 | Updating git repos
2026-06-12 10:17:43.133219 | Cloning repos into workspace
2026-06-12 10:17:45.399620 | Restoring repo states
2026-06-12 10:17:45.416875 | Merging changes
2026-06-12 10:17:46.601706 | Checking out repos
2026-06-12 10:17:47.267183 | Preparing playbooks
2026-06-12 10:17:51.971855 | Running Ansible setup
2026-06-12 10:17:55.915125 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-06-12 10:17:56.521813 | 
2026-06-12 10:17:56.521925 | PLAY [localhost]
2026-06-12 10:17:56.529033 | 
2026-06-12 10:17:56.529098 | TASK [Gathering Facts]
2026-06-12 10:17:57.474569 | localhost | ok
2026-06-12 10:17:57.487709 | 
2026-06-12 10:17:57.487913 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-12 10:17:58.001238 | localhost -> localhost | changed
2026-06-12 10:17:58.010699 | 
2026-06-12 10:17:58.010842 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-12 10:17:59.026796 | localhost -> localhost | changed
2026-06-12 10:17:59.038702 | 
2026-06-12 10:17:59.038832 | TASK [Setup log path fact]
2026-06-12 10:17:59.059929 | localhost | ok
2026-06-12 10:17:59.078774 | 
2026-06-12 10:17:59.078908 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-12 10:17:59.100487 | localhost | ok
2026-06-12 10:17:59.111284 | 
2026-06-12 10:17:59.111416 | TASK [emit-job-header : Print job information]
2026-06-12 10:17:59.152293 | # Job Information
2026-06-12 10:17:59.152503 | Ansible Version: 2.16.18
2026-06-12 10:17:59.152596 | Job: cifmw-molecule-cifmw_cephadm
2026-06-12 10:17:59.152639 | Pipeline: github-check
2026-06-12 10:17:59.152677 | Executor: 38.102.83.32
2026-06-12 10:17:59.152712 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3773
2026-06-12 10:17:59.152752 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/e35/rdoproject.org/e358dd5bfd39485aa52403dbc3669c02/
2026-06-12 10:17:59.152795 | Event ID: 70d0afd0-6644-11f1-8f83-ea505e2c95b1
2026-06-12 10:17:59.158686 | 
2026-06-12 10:17:59.158817 | LOOP [emit-job-header : Print node information]
2026-06-12 10:17:59.244349 | localhost | ok:
2026-06-12 10:17:59.244601 | localhost | # Node Information
2026-06-12 10:17:59.244658 | localhost | Inventory Hostname: controller
2026-06-12 10:17:59.244702 | localhost | Hostname: np0000071742
2026-06-12 10:17:59.244741 | localhost | Username: zuul
2026-06-12 10:17:59.244782 | localhost | Distro: CentOS 9
2026-06-12 10:17:59.244822 | localhost | Provider: vexxhost-nodepool-tripleo
2026-06-12 10:17:59.244857 | localhost | Region: RegionOne
2026-06-12 10:17:59.244890 | localhost | Label: cloud-centos-9-stream-tripleo
2026-06-12 10:17:59.244924 | localhost | Product Name: OpenStack Nova
2026-06-12 10:17:59.244956 | localhost | Interface IP: 38.102.83.64
2026-06-12 10:17:59.254213 | 
2026-06-12 10:17:59.254328 | PLAY [all]
2026-06-12 10:17:59.264941 | 
2026-06-12 10:17:59.265067 | TASK [Gather network facts]
2026-06-12 10:17:59.707093 | controller | ok
2026-06-12 10:17:59.719221 | 
2026-06-12 10:17:59.719369 | TASK [include_role : start-zuul-console]
2026-06-12 10:17:59.742832 | controller | ok
2026-06-12 10:17:59.763075 | 
2026-06-12 10:17:59.763220 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-06-12 10:18:00.172956 | controller | ok
2026-06-12 10:18:00.185644 | 
2026-06-12 10:18:00.185774 | TASK [include_role : add-build-sshkey]
2026-06-12 10:18:00.218032 | controller | ok
2026-06-12 10:18:00.241647 | 
2026-06-12 10:18:00.241839 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-12 10:18:00.531777 | controller -> localhost | ok
2026-06-12 10:18:00.540563 | 
2026-06-12 10:18:00.540695 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-12 10:18:00.562324 | controller | ok
2026-06-12 10:18:00.588126 | controller | included: /var/lib/zuul/builds/e358dd5bfd39485aa52403dbc3669c02/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-06-12 10:18:00.596950 | 
2026-06-12 10:18:00.597045 | TASK [add-build-sshkey : Create Temp SSH key]
2026-06-12 10:18:01.176549 | controller -> localhost | Generating public/private rsa key pair.
2026-06-12 10:18:01.176873 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e358dd5bfd39485aa52403dbc3669c02/work/e358dd5bfd39485aa52403dbc3669c02_id_rsa
2026-06-12 10:18:01.176956 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e358dd5bfd39485aa52403dbc3669c02/work/e358dd5bfd39485aa52403dbc3669c02_id_rsa.pub
2026-06-12 10:18:01.177003 | controller -> localhost | The key fingerprint is:
2026-06-12 10:18:01.177045 | controller -> localhost | SHA256:uzXQYaiJIZKpCTk8IhKIlt8twU5AdaqtMM/3xhZybTA zuul-build-sshkey
2026-06-12 10:18:01.177084 | controller -> localhost | The key's randomart image is:
2026-06-12 10:18:01.177123 | controller -> localhost | +---[RSA 3072]----+
2026-06-12 10:18:01.177161 | controller -> localhost | |+ oo+. .         |
2026-06-12 10:18:01.177200 | controller -> localhost | |+B   +o  .       |
2026-06-12 10:18:01.177238 | controller -> localhost | |%oo =.o . o      |
2026-06-12 10:18:01.177276 | controller -> localhost | |*+.oo* E o .     |
2026-06-12 10:18:01.177314 | controller -> localhost | |oo ...+ S .      |
2026-06-12 10:18:01.177350 | controller -> localhost | |  = .. o =       |
2026-06-12 10:18:01.177387 | controller -> localhost | |   + .+ + o      |
2026-06-12 10:18:01.177424 | controller -> localhost | |    . .+ o .     |
2026-06-12 10:18:01.177460 | controller -> localhost | |      o..        |
2026-06-12 10:18:01.177497 | controller -> localhost | +----[SHA256]-----+
2026-06-12 10:18:01.177624 | controller -> localhost | ok: Runtime: 0:00:00.067001
2026-06-12 10:18:01.187122 | 
2026-06-12 10:18:01.187256 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-06-12 10:18:01.219584 | controller | ok
2026-06-12 10:18:01.237261 | controller | included: /var/lib/zuul/builds/e358dd5bfd39485aa52403dbc3669c02/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-06-12 10:18:01.251829 | 
2026-06-12 10:18:01.251958 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-06-12 10:18:01.276762 | controller | skipping: Conditional result was False
2026-06-12 10:18:01.282018 | 
2026-06-12 10:18:01.282180 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-06-12 10:18:01.700464 | controller | changed
2026-06-12 10:18:01.708072 | 
2026-06-12 10:18:01.708211 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-06-12 10:18:01.945244 | controller | ok
2026-06-12 10:18:01.952599 | 
2026-06-12 10:18:01.952745 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-06-12 10:18:02.735868 | controller | changed
2026-06-12 10:18:02.741869 | 
2026-06-12 10:18:02.741945 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-06-12 10:18:03.577386 | controller | changed
2026-06-12 10:18:03.585642 | 
2026-06-12 10:18:03.585791 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-06-12 10:18:03.615804 | controller | skipping: Conditional result was False
2026-06-12 10:18:03.628156 | 
2026-06-12 10:18:03.628409 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-12 10:18:04.128991 | controller -> localhost | changed
2026-06-12 10:18:04.143106 | 
2026-06-12 10:18:04.143236 | TASK [add-build-sshkey : Add back temp key]
2026-06-12 10:18:04.459476 | controller -> localhost | Identity added: /var/lib/zuul/builds/e358dd5bfd39485aa52403dbc3669c02/work/e358dd5bfd39485aa52403dbc3669c02_id_rsa (zuul-build-sshkey)
2026-06-12 10:18:04.459807 | controller -> localhost | ok: Runtime: 0:00:00.016158
2026-06-12 10:18:04.466936 | 
2026-06-12 10:18:04.467059 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-06-12 10:18:04.804336 | controller | ok
2026-06-12 10:18:04.811421 | 
2026-06-12 10:18:04.811593 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-06-12 10:18:04.848813 | controller | skipping: Conditional result was False
2026-06-12 10:18:04.862389 | 
2026-06-12 10:18:04.862564 | TASK [include_role : validate-host]
2026-06-12 10:18:04.899593 | controller | ok
2026-06-12 10:18:04.929262 | 
2026-06-12 10:18:04.929378 | TASK [validate-host : Define zuul_info_dir fact]
2026-06-12 10:18:04.969837 | controller | ok
2026-06-12 10:18:04.976222 | 
2026-06-12 10:18:04.976350 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-06-12 10:18:05.286248 | controller -> localhost | ok
2026-06-12 10:18:05.295648 | 
2026-06-12 10:18:05.295779 | TASK [validate-host : Collect information about the host]
2026-06-12 10:18:06.013476 | controller | ok
2026-06-12 10:18:06.032077 | 
2026-06-12 10:18:06.032221 | TASK [validate-host : Sanitize hostname]
2026-06-12 10:18:06.106705 | controller | ok
2026-06-12 10:18:06.114424 | 
2026-06-12 10:18:06.114585 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-06-12 10:18:06.632827 | controller -> localhost | changed
2026-06-12 10:18:06.643969 | 
2026-06-12 10:18:06.644221 | TASK [validate-host : Collect information about zuul worker]
2026-06-12 10:18:07.112699 | controller | ok
2026-06-12 10:18:07.119941 | 
2026-06-12 10:18:07.120077 | TASK [validate-host : Write out all zuul information for each host]
2026-06-12 10:18:07.652414 | controller -> localhost | changed
2026-06-12 10:18:07.659598 | 
2026-06-12 10:18:07.659662 | TASK [include_role : prepare-workspace-openshift]
2026-06-12 10:18:07.684335 | controller | skipping: Conditional result was False
2026-06-12 10:18:07.692313 | 
2026-06-12 10:18:07.692457 | TASK [include_role : remove-zuul-sshkey]
2026-06-12 10:18:07.719877 | controller | skipping: Conditional result was False
2026-06-12 10:18:07.727908 | 
2026-06-12 10:18:07.728052 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-12 10:18:08.008716 | controller | ok: "logs"
2026-06-12 10:18:08.009226 | controller | ok: All items complete
2026-06-12 10:18:08.009292 | 
2026-06-12 10:18:08.248836 | controller | ok: "artifacts"
2026-06-12 10:18:08.477478 | controller | ok: "docs"
2026-06-12 10:18:08.487715 | 
2026-06-12 10:18:08.487864 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-12 10:18:08.736730 | controller | changed: "logs"
2026-06-12 10:18:08.942976 | controller | changed: "artifacts"
2026-06-12 10:18:09.143002 | controller | changed: "docs"
2026-06-12 10:18:09.155944 | 
2026-06-12 10:18:09.156154 | PLAY RECAP
2026-06-12 10:18:09.156257 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-06-12 10:18:09.156315 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-12 10:18:09.156356 | 
2026-06-12 10:18:09.314587 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-06-12 10:18:09.317228 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-06-12 10:18:09.987081 | 
2026-06-12 10:18:09.987289 | PLAY [all]
2026-06-12 10:18:09.997831 | 
2026-06-12 10:18:09.997925 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-06-12 10:18:10.077605 | controller | ok
2026-06-12 10:18:10.082678 | 
2026-06-12 10:18:10.082798 | TASK [mirror-info-fork : Create /etc/ci]
2026-06-12 10:18:10.531488 | controller | changed
2026-06-12 10:18:10.540601 | 
2026-06-12 10:18:10.540753 | TASK [mirror-info-fork : Install ci_mirror script]
2026-06-12 10:18:11.538484 | controller | changed
2026-06-12 10:18:11.555974 | 
2026-06-12 10:18:11.556366 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-06-12 10:18:12.028102 | controller | changed:
2026-06-12 10:18:12.028267 | controller | {
2026-06-12 10:18:12.028294 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-06-12 10:18:12.028322 | controller | }
2026-06-12 10:18:12.288961 | controller | changed:
2026-06-12 10:18:12.289155 | controller | {
2026-06-12 10:18:12.289228 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-06-12 10:18:12.289272 | controller | }
2026-06-12 10:18:12.573054 | controller | changed:
2026-06-12 10:18:12.573180 | controller | {
2026-06-12 10:18:12.573211 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-06-12 10:18:12.573237 | controller | }
2026-06-12 10:18:12.812252 | controller | changed:
2026-06-12 10:18:12.812429 | controller | {
2026-06-12 10:18:12.812485 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-06-12 10:18:12.812580 | controller | }
2026-06-12 10:18:13.059130 | controller | changed:
2026-06-12 10:18:13.059290 | controller | {
2026-06-12 10:18:13.059342 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-06-12 10:18:13.059402 | controller | }
2026-06-12 10:18:13.319433 | controller | changed:
2026-06-12 10:18:13.319696 | controller | {
2026-06-12 10:18:13.319753 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-06-12 10:18:13.319794 | controller | }
2026-06-12 10:18:13.552788 | controller | changed:
2026-06-12 10:18:13.553008 | controller | {
2026-06-12 10:18:13.553062 | 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-06-12 10:18:13.553150 | controller | }
2026-06-12 10:18:13.798132 | controller | changed:
2026-06-12 10:18:13.798355 | controller | {
2026-06-12 10:18:13.798409 | 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-06-12 10:18:13.798475 | controller | }
2026-06-12 10:18:14.029927 | controller | changed:
2026-06-12 10:18:14.030112 | controller | {
2026-06-12 10:18:14.030164 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-06-12 10:18:14.030202 | controller | }
2026-06-12 10:18:14.263962 | controller | changed:
2026-06-12 10:18:14.264295 | controller | {
2026-06-12 10:18:14.264357 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-06-12 10:18:14.264398 | controller | }
2026-06-12 10:18:14.534721 | controller | changed:
2026-06-12 10:18:14.534949 | controller | {
2026-06-12 10:18:14.535002 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-06-12 10:18:14.535041 | controller | }
2026-06-12 10:18:14.818571 | controller | changed:
2026-06-12 10:18:14.818729 | controller | {
2026-06-12 10:18:14.818779 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-06-12 10:18:14.818818 | controller | }
2026-06-12 10:18:15.069339 | controller | changed:
2026-06-12 10:18:15.069611 | controller | {
2026-06-12 10:18:15.069679 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-06-12 10:18:15.069722 | controller | }
2026-06-12 10:18:15.357426 | controller | changed:
2026-06-12 10:18:15.357562 | controller | {
2026-06-12 10:18:15.357599 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-06-12 10:18:15.357625 | controller | }
2026-06-12 10:18:15.560978 | controller | changed:
2026-06-12 10:18:15.561138 | controller | {
2026-06-12 10:18:15.561191 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-06-12 10:18:15.561233 | controller | }
2026-06-12 10:18:15.832581 | controller | changed:
2026-06-12 10:18:15.832856 | controller | {
2026-06-12 10:18:15.832918 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-06-12 10:18:15.832958 | controller | }
2026-06-12 10:18:16.113833 | controller | changed:
2026-06-12 10:18:16.113994 | controller | {
2026-06-12 10:18:16.114046 | 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-06-12 10:18:16.114088 | controller | }
2026-06-12 10:18:16.359080 | controller | changed:
2026-06-12 10:18:16.359221 | controller | {
2026-06-12 10:18:16.359262 | 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-06-12 10:18:16.359317 | controller | }
2026-06-12 10:18:16.630935 | controller | changed:
2026-06-12 10:18:16.631107 | controller | {
2026-06-12 10:18:16.631170 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-06-12 10:18:16.631222 | controller | }
2026-06-12 10:18:16.917243 | controller | changed:
2026-06-12 10:18:16.917418 | controller | {
2026-06-12 10:18:16.917483 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-06-12 10:18:16.917596 | controller | }
2026-06-12 10:18:17.187185 | controller | changed:
2026-06-12 10:18:17.187332 | controller | {
2026-06-12 10:18:17.187379 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-06-12 10:18:17.187419 | controller | }
2026-06-12 10:18:17.441089 | controller | changed:
2026-06-12 10:18:17.441238 | controller | {
2026-06-12 10:18:17.441288 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-06-12 10:18:17.441374 | controller | }
2026-06-12 10:18:17.732755 | controller | changed:
2026-06-12 10:18:17.733016 | controller | {
2026-06-12 10:18:17.733067 | 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-06-12 10:18:17.733107 | controller | }
2026-06-12 10:18:17.989136 | controller | changed:
2026-06-12 10:18:17.989445 | controller | {
2026-06-12 10:18:17.989506 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-06-12 10:18:17.989594 | controller | }
2026-06-12 10:18:18.241130 | controller | changed:
2026-06-12 10:18:18.241385 | controller | {
2026-06-12 10:18:18.241437 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-06-12 10:18:18.241476 | controller | }
2026-06-12 10:18:18.474803 | controller | changed:
2026-06-12 10:18:18.474954 | controller | {
2026-06-12 10:18:18.475003 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-06-12 10:18:18.475041 | controller | }
2026-06-12 10:18:18.511212 | 
2026-06-12 10:18:18.511447 | TASK [Set timezone to UTC]
2026-06-12 10:18:19.103436 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-06-12 10:18:19.110739 | 
2026-06-12 10:18:19.110869 | TASK [Create nodepool directory]
2026-06-12 10:18:19.357892 | controller | changed
2026-06-12 10:18:19.367866 | 
2026-06-12 10:18:19.367995 | TASK [Create nodepool sub_nodes file]
2026-06-12 10:18:20.000036 | controller | changed
2026-06-12 10:18:20.008805 | 
2026-06-12 10:18:20.008972 | TASK [Create nodepool sub_nodes_private file]
2026-06-12 10:18:20.742788 | controller | changed
2026-06-12 10:18:20.750668 | 
2026-06-12 10:18:20.750797 | LOOP [Populate nodepool sub_nodes file]
2026-06-12 10:18:20.795315 | 
2026-06-12 10:18:20.795627 | LOOP [Populate nodepool sub_nodes_private file]
2026-06-12 10:18:20.839565 | 
2026-06-12 10:18:20.839853 | TASK [Create nodepool primary file]
2026-06-12 10:18:20.875217 | controller | skipping: Conditional result was False
2026-06-12 10:18:20.883811 | 
2026-06-12 10:18:20.883952 | TASK [Create nodepool node_private for this node]
2026-06-12 10:18:21.696881 | controller | changed
2026-06-12 10:18:21.709765 | 
2026-06-12 10:18:21.709977 | LOOP [Copy ssh keys to nodepool directory]
2026-06-12 10:18:22.180200 | controller | ok: Item: id_rsa Runtime: 0:00:00.007007
2026-06-12 10:18:22.180550 | 
2026-06-12 10:18:22.420024 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005141
2026-06-12 10:18:22.435333 | 
2026-06-12 10:18:22.435558 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-06-12 10:18:23.182701 | controller | changed
2026-06-12 10:18:23.191970 | 
2026-06-12 10:18:23.192190 | TASK [Validate sudoers config after edits]
2026-06-12 10:18:23.556803 | controller | /etc/sudoers: parsed OK
2026-06-12 10:18:23.556917 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-06-12 10:18:23.556934 | controller | /etc/sudoers.d/zuul: parsed OK
2026-06-12 10:18:23.556987 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-06-12 10:18:23.733643 | controller | ok: Runtime: 0:00:00.009912
2026-06-12 10:18:23.748054 | 
2026-06-12 10:18:23.748227 | TASK [Show the environment passed in to job shell scripts]
2026-06-12 10:18:24.042936 | controller | SHELL=/bin/bash
2026-06-12 10:18:24.043087 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-06-12 10:18:24.043106 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-06-12 10:18:24.043121 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/73/3773/6121110e30eb9d443148efa71c9a0f3ef7a20d14
2026-06-12 10:18:24.043133 | controller | PWD=/home/zuul
2026-06-12 10:18:24.043143 | controller | ZUUL_PIPELINE=github-check
2026-06-12 10:18:24.043157 | controller | LOGNAME=zuul
2026-06-12 10:18:24.043184 | controller | XDG_SESSION_TYPE=tty
2026-06-12 10:18:24.043215 | controller | _=/usr/bin/env
2026-06-12 10:18:24.043244 | controller | MOTD_SHOWN=pam
2026-06-12 10:18:24.043257 | controller | HOME=/home/zuul
2026-06-12 10:18:24.043270 | controller | LANG=en_US.UTF-8
2026-06-12 10:18:24.043285 | controller | SSH_CONNECTION=38.102.83.32 46766 38.102.83.64 22
2026-06-12 10:18:24.043302 | 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-06-12 10:18:24.043317 | controller | ZUUL_CHANGE_IDS=3773,6121110e30eb9d443148efa71c9a0f3ef7a20d14
2026-06-12 10:18:24.043331 | controller | WORKSPACE=/home/zuul/workspace
2026-06-12 10:18:24.043345 | controller | XDG_SESSION_CLASS=user
2026-06-12 10:18:24.043358 | controller | SELINUX_ROLE_REQUESTED=
2026-06-12 10:18:24.043372 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-06-12 10:18:24.043386 | controller | USER=zuul
2026-06-12 10:18:24.043399 | controller | ZUUL_VOTING=True
2026-06-12 10:18:24.043412 | controller | BUILD_TIMEOUT=1800000
2026-06-12 10:18:24.043424 | controller | SELINUX_USE_CURRENT_RANGE=
2026-06-12 10:18:24.043435 | controller | SHLVL=1
2026-06-12 10:18:24.043444 | controller | ZUUL_PATCHSET=6121110e30eb9d443148efa71c9a0f3ef7a20d14
2026-06-12 10:18:24.043453 | controller | XDG_SESSION_ID=1
2026-06-12 10:18:24.043461 | controller | ZUUL_BRANCH=main
2026-06-12 10:18:24.043469 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-06-12 10:18:24.043478 | controller | SSH_CLIENT=38.102.83.32 46766 22
2026-06-12 10:18:24.043492 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-06-12 10:18:24.043501 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-06-12 10:18:24.043510 | controller | which_declare=declare -f
2026-06-12 10:18:24.043518 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-06-12 10:18:24.043527 | controller | SELINUX_LEVEL_REQUESTED=
2026-06-12 10:18:24.043535 | controller | ZUUL_CHANGE=3773
2026-06-12 10:18:24.043544 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-06-12 10:18:24.043553 | controller | ZUUL_UUID=e358dd5bfd39485aa52403dbc3669c02
2026-06-12 10:18:24.043561 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-06-12 10:18:24.043570 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-06-12 10:18:24.043580 | controller | }
2026-06-12 10:18:24.296419 | controller | ok: Runtime: 0:00:00.008875
2026-06-12 10:18:24.305204 | 
2026-06-12 10:18:24.305343 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-06-12 10:18:24.332575 | controller | skipping: Conditional result was False
2026-06-12 10:18:24.342316 | 
2026-06-12 10:18:24.342487 | TASK [Symlink /home/zuul-worker/workspace]
2026-06-12 10:18:24.881648 | controller | skipping: Conditional result was False
2026-06-12 10:18:24.891422 | 
2026-06-12 10:18:24.891591 | TASK [Ensure legacy workspace directory]
2026-06-12 10:18:25.159824 | controller | changed
2026-06-12 10:18:25.162335 | 
2026-06-12 10:18:25.162598 | PLAY RECAP
2026-06-12 10:18:25.162894 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-06-12 10:18:25.163030 | 
2026-06-12 10:18:25.306894 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-06-12 10:18:25.309337 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-06-12 10:18:25.900173 | 
2026-06-12 10:18:25.900275 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-06-12 10:18:25.910924 | 
2026-06-12 10:18:25.911008 | TASK [Create zuul-output directory]
2026-06-12 10:18:26.297259 | controller | changed
2026-06-12 10:18:26.305683 | 
2026-06-12 10:18:26.305858 | TASK [Slurp Zuul inventory test]
2026-06-12 10:18:26.730174 | controller -> localhost | ok
2026-06-12 10:18:26.740575 | 
2026-06-12 10:18:26.740731 | TASK [Save zuul inventory]
2026-06-12 10:18:27.714036 | controller | changed
2026-06-12 10:18:27.721781 | 
2026-06-12 10:18:27.721995 | TASK [Save zuul vars without the change_message]
2026-06-12 10:18:28.576180 | controller | changed
2026-06-12 10:18:28.578592 | 
2026-06-12 10:18:28.578688 | PLAY RECAP
2026-06-12 10:18:28.578764 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-12 10:18:28.578807 | 
2026-06-12 10:18:28.697106 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-06-12 10:18:28.699467 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-06-12 10:18:29.433017 | 
2026-06-12 10:18:29.433167 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-06-12 10:18:29.444854 | 
2026-06-12 10:18:29.444929 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-06-12 10:18:29.465596 | controller | ok
2026-06-12 10:18:29.479531 | 
2026-06-12 10:18:29.479610 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-06-12 10:18:29.524078 | controller | skipping: Conditional result was False
2026-06-12 10:18:29.529282 | 
2026-06-12 10:18:29.529352 | TASK [mirror-info-fork : Create /etc/ci]
2026-06-12 10:18:29.989830 | controller | ok
2026-06-12 10:18:29.998796 | 
2026-06-12 10:18:29.998937 | TASK [mirror-info-fork : Install ci_mirror script]
2026-06-12 10:18:30.826803 | controller | ok
2026-06-12 10:18:30.837000 | 
2026-06-12 10:18:30.837091 | TASK [Prepare workspace]
2026-06-12 10:18:30.860941 | controller | ok
2026-06-12 10:18:30.884930 | 
2026-06-12 10:18:30.885052 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-12 10:18:31.285152 | controller | ok
2026-06-12 10:18:31.295616 | 
2026-06-12 10:18:31.295810 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-12 10:18:33.340303 | controller | Output suppressed because no_log was given
2026-06-12 10:18:33.355254 | 
2026-06-12 10:18:33.355442 | LOOP [Create zuul-output directory]
2026-06-12 10:18:33.631576 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-06-12 10:18:33.840088 | controller | ok: "/home/zuul/zuul-output/logs"
2026-06-12 10:18:33.851127 | 
2026-06-12 10:18:33.851330 | TASK [Install required packages]
2026-06-12 10:19:19.357083 | controller | changed
2026-06-12 10:19:19.365179 | 
2026-06-12 10:19:19.365307 | TASK [Install venv]
2026-06-12 10:20:23.571062 | controller | changed
2026-06-12 10:20:23.589019 | 
2026-06-12 10:20:23.589123 | PLAY RECAP
2026-06-12 10:20:23.589209 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-12 10:20:23.589254 | 
2026-06-12 10:20:23.717216 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-06-12 10:20:23.719776 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-06-12 10:20:24.297688 | 
2026-06-12 10:20:24.297799 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-06-12 10:20:24.308320 | 
2026-06-12 10:20:24.308404 | TASK [Gather required facts]
2026-06-12 10:20:24.911049 | controller | ok
2026-06-12 10:20:24.919863 | 
2026-06-12 10:20:24.920023 | TASK [Load environment var if instructed to]
2026-06-12 10:20:24.946715 | controller | skipping: Conditional result was False
2026-06-12 10:20:24.954938 | 
2026-06-12 10:20:24.955069 | TASK [Ensure group_vars dir exists]
2026-06-12 10:20:25.422642 | controller | ok
2026-06-12 10:20:25.432418 | 
2026-06-12 10:20:25.432536 | TASK [Print related variables]
2026-06-12 10:20:25.491907 | controller | ok:
2026-06-12 10:20:25.492097 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-06-12 10:20:25.492169 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_cephadm
2026-06-12 10:20:25.497233 | 
2026-06-12 10:20:25.497322 | TASK [Run molecule]
2026-06-12 10:20:26.707544 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-06-12 10:20:26.781379 | controller | INFO     Performing prerun with role_name_check=0...
2026-06-12 10:20:51.097902 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-12 10:20:51.098444 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-12 10:20:51.098983 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-12 10:20:51.099464 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-12 10:20:51.099947 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+804b117a was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 1.0.0+804b117a (/home/zuul/.ansible/collections/ansible_collections).
2026-06-12 10:20:51.100424 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-12 10:20:51.100902 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-12 10:20:51.101338 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-12 10:20:51.101781 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-12 10:20:51.102209 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-12 10:20:51.102671 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-12 10:20:51.103117 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-12 10:20:51.103551 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-12 10:20:51.104015 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-12 10:20:51.104588 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-12 10:20:51.116474 | controller | INFO     Running default > prepare
2026-06-12 10:20:51.855120 | controller |
2026-06-12 10:20:51.855247 | controller | PLAY [Prepare] *****************************************************************
2026-06-12 10:20:51.855387 | controller |
2026-06-12 10:20:51.855519 | controller | TASK [Gathering Facts] *********************************************************
2026-06-12 10:20:51.855654 | controller | Friday 12 June 2026  10:20:51 +0000 (0:00:00.013)       0:00:00.013 ***********
2026-06-12 10:20:52.777275 | controller | ok: [instance]
2026-06-12 10:20:52.777378 | controller |
2026-06-12 10:20:52.777390 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2026-06-12 10:20:52.777436 | controller | Friday 12 June 2026  10:20:52 +0000 (0:00:00.922)       0:00:00.936 ***********
2026-06-12 10:20:52.793353 | controller | skipping: [instance]
2026-06-12 10:20:52.793410 | controller |
2026-06-12 10:20:52.793507 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2026-06-12 10:20:52.793601 | controller | Friday 12 June 2026  10:20:52 +0000 (0:00:00.016)       0:00:00.953 ***********
2026-06-12 10:20:52.834485 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2026-06-12 10:20:52.834551 | controller |
2026-06-12 10:20:52.834565 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2026-06-12 10:20:52.834862 | controller | Friday 12 June 2026  10:20:52 +0000 (0:00:00.040)       0:00:00.994 ***********
2026-06-12 10:20:53.158883 | controller | ok: [instance]
2026-06-12 10:20:53.158985 | controller |
2026-06-12 10:20:53.158998 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2026-06-12 10:20:53.159068 | controller | Friday 12 June 2026  10:20:53 +0000 (0:00:00.324)       0:00:01.318 ***********
2026-06-12 10:20:53.543249 | controller | ok: [instance]
2026-06-12 10:20:53.543361 | controller |
2026-06-12 10:20:53.543382 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2026-06-12 10:20:53.543519 | controller | Friday 12 June 2026  10:20:53 +0000 (0:00:00.383)       0:00:01.702 ***********
2026-06-12 10:20:54.361404 | controller | ok: [instance] => (item=ubi-9-appstream)
2026-06-12 10:20:54.361431 | controller | ok: [instance] => (item=ubi-9-baseos)
2026-06-12 10:20:54.361436 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2026-06-12 10:20:54.361440 | controller |
2026-06-12 10:20:54.361444 | controller | TASK [Deploy repo-setup] *******************************************************
2026-06-12 10:20:54.361448 | controller | Friday 12 June 2026  10:20:54 +0000 (0:00:00.817)       0:00:02.520 ***********
2026-06-12 10:20:54.425007 | controller |
2026-06-12 10:20:54.425064 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2026-06-12 10:20:54.425076 | controller | Friday 12 June 2026  10:20:54 +0000 (0:00:00.063)       0:00:02.584 ***********
2026-06-12 10:20:55.116276 | controller | changed: [instance] => (item=tmp)
2026-06-12 10:20:55.116310 | controller | changed: [instance] => (item=artifacts/repositories)
2026-06-12 10:20:55.116317 | controller | changed: [instance] => (item=venv/repo_setup)
2026-06-12 10:20:55.116324 | controller |
2026-06-12 10:20:55.116330 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2026-06-12 10:20:55.116336 | controller | Friday 12 June 2026  10:20:55 +0000 (0:00:00.690)       0:00:03.274 ***********
2026-06-12 10:20:56.028797 | controller | ok: [instance]
2026-06-12 10:20:56.028827 | controller |
2026-06-12 10:20:56.029250 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2026-06-12 10:20:56.029287 | controller | Friday 12 June 2026  10:20:56 +0000 (0:00:00.912)       0:00:04.187 ***********
2026-06-12 10:20:57.099842 | controller | changed: [instance]
2026-06-12 10:20:57.099874 | controller |
2026-06-12 10:20:57.099880 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2026-06-12 10:20:57.099937 | controller | Friday 12 June 2026  10:20:57 +0000 (0:00:01.071)       0:00:05.259 ***********
2026-06-12 10:21:04.616939 | controller | changed: [instance]
2026-06-12 10:21:04.617568 | controller |
2026-06-12 10:21:04.617619 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2026-06-12 10:21:04.617631 | controller | Friday 12 June 2026  10:21:04 +0000 (0:00:07.516)       0:00:12.775 ***********
2026-06-12 10:21:05.409952 | controller | changed: [instance]
2026-06-12 10:21:05.409998 | controller |
2026-06-12 10:21:05.410005 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2026-06-12 10:21:05.410091 | controller | Friday 12 June 2026  10:21:05 +0000 (0:00:00.792)       0:00:13.568 ***********
2026-06-12 10:21:05.447967 | controller | skipping: [instance]
2026-06-12 10:21:05.448003 | controller |
2026-06-12 10:21:05.448319 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2026-06-12 10:21:05.448349 | controller | Friday 12 June 2026  10:21:05 +0000 (0:00:00.038)       0:00:13.606 ***********
2026-06-12 10:21:06.142937 | controller | changed: [instance]
2026-06-12 10:21:06.142982 | controller |
2026-06-12 10:21:06.142988 | controller | TASK [repo_setup : Get component repo] *****************************************
2026-06-12 10:21:06.142994 | controller | Friday 12 June 2026  10:21:06 +0000 (0:00:00.693)       0:00:14.300 ***********
2026-06-12 10:21:06.208968 | controller | skipping: [instance]
2026-06-12 10:21:06.209026 | controller |
2026-06-12 10:21:06.209041 | controller | TASK [repo_setup : Rename component repo] **************************************
2026-06-12 10:21:06.209056 | controller | Friday 12 June 2026  10:21:06 +0000 (0:00:00.066)       0:00:14.366 ***********
2026-06-12 10:21:06.250335 | controller | skipping: [instance]
2026-06-12 10:21:06.250365 | controller |
2026-06-12 10:21:06.250371 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2026-06-12 10:21:06.250376 | controller | Friday 12 June 2026  10:21:06 +0000 (0:00:00.042)       0:00:14.409 ***********
2026-06-12 10:21:06.318335 | controller | skipping: [instance]
2026-06-12 10:21:06.318368 | controller |
2026-06-12 10:21:06.318375 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2026-06-12 10:21:06.318380 | controller | Friday 12 June 2026  10:21:06 +0000 (0:00:00.067)       0:00:14.477 ***********
2026-06-12 10:21:06.681917 | controller | changed: [instance]
2026-06-12 10:21:06.681970 | controller |
2026-06-12 10:21:06.681981 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2026-06-12 10:21:06.682101 | controller | Friday 12 June 2026  10:21:06 +0000 (0:00:00.363)       0:00:14.840 ***********
2026-06-12 10:21:07.327816 | controller | changed: [instance]
2026-06-12 10:21:07.328060 | controller |
2026-06-12 10:21:07.328088 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2026-06-12 10:21:07.328093 | controller | Friday 12 June 2026  10:21:07 +0000 (0:00:00.647)       0:00:15.487 ***********
2026-06-12 10:21:07.364512 | controller | skipping: [instance]
2026-06-12 10:21:07.364542 | controller |
2026-06-12 10:21:07.364548 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2026-06-12 10:21:07.364553 | controller | Friday 12 June 2026  10:21:07 +0000 (0:00:00.036)       0:00:15.523 ***********
2026-06-12 10:21:07.402372 | controller | skipping: [instance]
2026-06-12 10:21:07.402402 | controller |
2026-06-12 10:21:07.402407 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2026-06-12 10:21:07.402412 | controller | Friday 12 June 2026  10:21:07 +0000 (0:00:00.038)       0:00:15.561 ***********
2026-06-12 10:21:07.448468 | controller | skipping: [instance]
2026-06-12 10:21:07.448512 | controller |
2026-06-12 10:21:07.448518 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2026-06-12 10:21:07.448522 | controller | Friday 12 June 2026  10:21:07 +0000 (0:00:00.045)       0:00:15.607 ***********
2026-06-12 10:21:07.490488 | controller | ok: [instance]
2026-06-12 10:21:07.490522 | controller |
2026-06-12 10:21:07.490530 | controller | TASK [repo_setup : Create download directory] **********************************
2026-06-12 10:21:07.490538 | controller | Friday 12 June 2026  10:21:07 +0000 (0:00:00.042)       0:00:15.649 ***********
2026-06-12 10:21:07.518071 | controller | skipping: [instance]
2026-06-12 10:21:07.518273 | controller |
2026-06-12 10:21:07.518481 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2026-06-12 10:21:07.518662 | controller | Friday 12 June 2026  10:21:07 +0000 (0:00:00.028)       0:00:15.677 ***********
2026-06-12 10:21:07.546300 | controller | skipping: [instance]
2026-06-12 10:21:07.546511 | controller |
2026-06-12 10:21:07.546708 | controller | TASK [Download the RPM] ********************************************************
2026-06-12 10:21:07.546987 | controller | Friday 12 June 2026  10:21:07 +0000 (0:00:00.028)       0:00:15.705 ***********
2026-06-12 10:21:07.575292 | controller | skipping: [instance]
2026-06-12 10:21:07.575516 | controller |
2026-06-12 10:21:07.575745 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2026-06-12 10:21:07.575946 | controller | Friday 12 June 2026  10:21:07 +0000 (0:00:00.029)       0:00:15.734 ***********
2026-06-12 10:21:07.603313 | controller | skipping: [instance]
2026-06-12 10:21:07.603554 | controller |
2026-06-12 10:21:07.603688 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2026-06-12 10:21:07.603847 | controller | Friday 12 June 2026  10:21:07 +0000 (0:00:00.027)       0:00:15.762 ***********
2026-06-12 10:21:07.649998 | controller | skipping: [instance]
2026-06-12 10:21:07.650186 | controller |
2026-06-12 10:21:07.650321 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2026-06-12 10:21:07.650450 | controller | Friday 12 June 2026  10:21:07 +0000 (0:00:00.046)       0:00:15.809 ***********
2026-06-12 10:21:07.679552 | controller | skipping: [instance]
2026-06-12 10:21:07.679791 | controller |
2026-06-12 10:21:07.680060 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2026-06-12 10:21:07.680291 | controller | Friday 12 June 2026  10:21:07 +0000 (0:00:00.029)       0:00:15.839 ***********
2026-06-12 10:21:07.704947 | controller | skipping: [instance]
2026-06-12 10:21:07.705180 | controller |
2026-06-12 10:21:07.705399 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2026-06-12 10:21:07.705655 | controller | Friday 12 June 2026  10:21:07 +0000 (0:00:00.025)       0:00:15.864 ***********
2026-06-12 10:21:07.955372 | controller | ok: [instance]
2026-06-12 10:21:07.955403 | controller |
2026-06-12 10:21:07.955411 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2026-06-12 10:21:07.955417 | controller | Friday 12 June 2026  10:21:07 +0000 (0:00:00.250)       0:00:16.114 ***********
2026-06-12 10:21:08.225560 | controller | changed: [instance]
2026-06-12 10:21:08.225587 | controller |
2026-06-12 10:21:08.225592 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2026-06-12 10:21:08.225597 | controller | Friday 12 June 2026  10:21:08 +0000 (0:00:00.270)       0:00:16.384 ***********
2026-06-12 10:21:08.543982 | controller | changed: [instance]
2026-06-12 10:21:08.544030 | controller |
2026-06-12 10:21:08.544044 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2026-06-12 10:21:08.544056 | controller | Friday 12 June 2026  10:21:08 +0000 (0:00:00.318)       0:00:16.703 ***********
2026-06-12 10:21:08.582752 | controller | skipping: [instance]
2026-06-12 10:21:08.582788 | controller |
2026-06-12 10:21:08.582795 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2026-06-12 10:21:08.582801 | controller | Friday 12 June 2026  10:21:08 +0000 (0:00:00.038)       0:00:16.741 ***********
2026-06-12 10:21:08.623856 | controller | skipping: [instance]
2026-06-12 10:21:08.623895 | controller |
2026-06-12 10:21:08.623900 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2026-06-12 10:21:08.623904 | controller | Friday 12 June 2026  10:21:08 +0000 (0:00:00.040)       0:00:16.782 ***********
2026-06-12 10:21:08.653326 | controller | skipping: [instance]
2026-06-12 10:21:08.653359 | controller |
2026-06-12 10:21:08.653366 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2026-06-12 10:21:08.653371 | controller | Friday 12 June 2026  10:21:08 +0000 (0:00:00.030)       0:00:16.812 ***********
2026-06-12 10:21:08.686917 | controller | skipping: [instance]
2026-06-12 10:21:08.686960 | controller |
2026-06-12 10:21:08.686967 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2026-06-12 10:21:08.687002 | controller | Friday 12 June 2026  10:21:08 +0000 (0:00:00.034)       0:00:16.846 ***********
2026-06-12 10:21:08.712608 | controller | skipping: [instance]
2026-06-12 10:21:08.712668 | controller |
2026-06-12 10:21:08.712852 | controller | TASK [repo_setup : Lower the priority of component repos to allow installation from gating repo] ***
2026-06-12 10:21:08.712973 | controller | Friday 12 June 2026  10:21:08 +0000 (0:00:00.025)       0:00:16.872 ***********
2026-06-12 10:21:08.734632 | controller | skipping: [instance]
2026-06-12 10:21:08.734695 | controller |
2026-06-12 10:21:08.734839 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2026-06-12 10:21:08.734955 | controller | Friday 12 June 2026  10:21:08 +0000 (0:00:00.022)       0:00:16.894 ***********
2026-06-12 10:21:08.753330 | controller | skipping: [instance]
2026-06-12 10:21:08.753474 | controller |
2026-06-12 10:21:08.753629 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2026-06-12 10:21:08.753793 | controller | Friday 12 June 2026  10:21:08 +0000 (0:00:00.018)       0:00:16.912 ***********
2026-06-12 10:21:08.784612 | controller | skipping: [instance]
2026-06-12 10:21:08.784725 | controller |
2026-06-12 10:21:08.784875 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2026-06-12 10:21:08.785021 | controller | Friday 12 June 2026  10:21:08 +0000 (0:00:00.031)       0:00:16.944 ***********
2026-06-12 10:21:08.804043 | controller | skipping: [instance]
2026-06-12 10:21:08.804209 | controller |
2026-06-12 10:21:08.804376 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2026-06-12 10:21:08.804539 | controller | Friday 12 June 2026  10:21:08 +0000 (0:00:00.019)       0:00:16.963 ***********
2026-06-12 10:21:08.826746 | controller | skipping: [instance]
2026-06-12 10:21:08.826896 | controller |
2026-06-12 10:21:08.827094 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2026-06-12 10:21:08.827252 | controller | Friday 12 June 2026  10:21:08 +0000 (0:00:00.022)       0:00:16.986 ***********
2026-06-12 10:21:08.857671 | controller | skipping: [instance]
2026-06-12 10:21:08.857805 | controller |
2026-06-12 10:21:08.857966 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2026-06-12 10:21:08.858115 | controller | Friday 12 June 2026  10:21:08 +0000 (0:00:00.031)       0:00:17.017 ***********
2026-06-12 10:21:08.896984 | controller | skipping: [instance]
2026-06-12 10:21:08.897122 | controller |
2026-06-12 10:21:08.897276 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2026-06-12 10:21:08.897434 | controller | Friday 12 June 2026  10:21:08 +0000 (0:00:00.039)       0:00:17.056 ***********
2026-06-12 10:21:08.941801 | controller | skipping: [instance]
2026-06-12 10:21:08.941999 | controller |
2026-06-12 10:21:08.942208 | controller | TASK [test_deps : Install additional packages] *********************************
2026-06-12 10:21:08.942415 | controller | Friday 12 June 2026  10:21:08 +0000 (0:00:00.044)       0:00:17.101 ***********
2026-06-12 10:21:08.990240 | controller | skipping: [instance]
2026-06-12 10:21:08.990521 | controller |
2026-06-12 10:21:08.990803 | controller | TASK [test_deps : Install selinux python libs] *********************************
2026-06-12 10:21:08.991143 | controller | Friday 12 June 2026  10:21:08 +0000 (0:00:00.048)       0:00:17.149 ***********
2026-06-12 10:21:51.237939 | controller | ok: [instance]
2026-06-12 10:21:51.238634 | controller |
2026-06-12 10:21:51.238670 | controller | TASK [test_deps : Install python yaml libs] ************************************
2026-06-12 10:21:51.238682 | controller | Friday 12 June 2026  10:21:51 +0000 (0:00:42.247)       0:00:59.396 ***********
2026-06-12 10:21:52.638814 | controller | ok: [instance]
2026-06-12 10:21:52.638840 | controller |
2026-06-12 10:21:52.638946 | controller | TASK [test_deps : Install extra packages] **************************************
2026-06-12 10:21:52.638976 | controller | Friday 12 June 2026  10:21:52 +0000 (0:00:01.401)       0:01:00.797 ***********
2026-06-12 10:21:54.026936 | controller | ok: [instance]
2026-06-12 10:21:54.026982 | controller |
2026-06-12 10:21:54.027310 | controller | TASK [Ensure ceph-admin group exists] ******************************************
2026-06-12 10:21:54.027349 | controller | Friday 12 June 2026  10:21:54 +0000 (0:00:01.387)       0:01:02.185 ***********
2026-06-12 10:21:54.375837 | controller | changed: [instance]
2026-06-12 10:21:54.375876 | controller |
2026-06-12 10:21:54.376079 | controller | TASK [Ensure ceph-admin user exists] *******************************************
2026-06-12 10:21:54.376114 | controller | Friday 12 June 2026  10:21:54 +0000 (0:00:00.349)       0:01:02.535 ***********
2026-06-12 10:21:54.913881 | controller | changed: [instance]
2026-06-12 10:21:54.913917 | controller |
2026-06-12 10:21:54.913922 | controller | PLAY RECAP *********************************************************************
2026-06-12 10:21:54.914903 | controller | instance                   : ok=22   changed=11   unreachable=0    failed=0    skipped=29   rescued=0    ignored=0
2026-06-12 10:21:54.914934 | controller |
2026-06-12 10:21:54.914941 | controller | Friday 12 June 2026  10:21:54 +0000 (0:00:00.537)       0:01:03.072 ***********
2026-06-12 10:21:54.916802 | controller | ===============================================================================
2026-06-12 10:21:54.916821 | controller | test_deps : Install selinux python libs -------------------------------- 42.25s
2026-06-12 10:21:54.916825 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.52s
2026-06-12 10:21:54.916829 | controller | test_deps : Install python yaml libs ------------------------------------ 1.40s
2026-06-12 10:21:54.916832 | controller | test_deps : Install extra packages -------------------------------------- 1.39s
2026-06-12 10:21:54.916836 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.07s
2026-06-12 10:21:54.916839 | controller | Gathering Facts --------------------------------------------------------- 0.92s
2026-06-12 10:21:54.916844 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.91s
2026-06-12 10:21:54.916847 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.82s
2026-06-12 10:21:54.919488 | controller | repo_setup : Install repo-setup package --------------------------------- 0.79s
2026-06-12 10:21:54.919516 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.69s
2026-06-12 10:21:54.919526 | controller | repo_setup : Ensure directories are present ----------------------------- 0.69s
2026-06-12 10:21:54.919534 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.65s
2026-06-12 10:21:54.919542 | controller | Ensure ceph-admin user exists ------------------------------------------- 0.54s
2026-06-12 10:21:54.919550 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.38s
2026-06-12 10:21:54.919558 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.36s
2026-06-12 10:21:54.919566 | controller | Ensure ceph-admin group exists ------------------------------------------ 0.35s
2026-06-12 10:21:54.919574 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.32s
2026-06-12 10:21:54.919582 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.32s
2026-06-12 10:21:54.919591 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.27s
2026-06-12 10:21:54.919599 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.25s
2026-06-12 10:21:54.992124 | controller | INFO     Running default > converge
2026-06-12 10:21:55.453572 | controller |
2026-06-12 10:21:55.453850 | controller | PLAY [Converge] ****************************************************************
2026-06-12 10:21:55.454035 | controller |
2026-06-12 10:21:55.454217 | controller | TASK [Gathering Facts] *********************************************************
2026-06-12 10:21:55.454426 | controller | Friday 12 June 2026  10:21:55 +0000 (0:00:00.015)       0:00:00.015 ***********
2026-06-12 10:21:56.353833 | controller | ok: [instance]
2026-06-12 10:21:56.353879 | controller |
2026-06-12 10:21:56.353890 | controller | TASK [cifmw_cephadm : Gather distribution facts if they do not exist] **********
2026-06-12 10:21:56.354028 | controller | Friday 12 June 2026  10:21:56 +0000 (0:00:00.899)       0:00:00.915 ***********
2026-06-12 10:21:56.379886 | controller | skipping: [instance]
2026-06-12 10:21:56.380373 | controller |
2026-06-12 10:21:56.380407 | controller | TASK [cifmw_cephadm : Set list of found FSIDs] *********************************
2026-06-12 10:21:56.380417 | controller | Friday 12 June 2026  10:21:56 +0000 (0:00:00.026)       0:00:00.942 ***********
2026-06-12 10:21:56.411482 | controller | skipping: [instance]
2026-06-12 10:21:56.411508 | controller |
2026-06-12 10:21:56.411517 | controller | TASK [cifmw_cephadm : Fail if >1 FSID was discovered] **************************
2026-06-12 10:21:56.411536 | controller | Friday 12 June 2026  10:21:56 +0000 (0:00:00.031)       0:00:00.973 ***********
2026-06-12 10:21:56.446972 | controller | skipping: [instance]
2026-06-12 10:21:56.447047 | controller |
2026-06-12 10:21:56.447060 | controller | TASK [cifmw_cephadm : Set FSID to the discovered value] ************************
2026-06-12 10:21:56.447071 | controller | Friday 12 June 2026  10:21:56 +0000 (0:00:00.035)       0:00:01.008 ***********
2026-06-12 10:21:56.480595 | controller | ok: [instance]
2026-06-12 10:21:56.480672 | controller |
2026-06-12 10:21:56.480869 | controller | TASK [cifmw_cephadm : Set random cifmw_cephadm_fsid if no running ceph containers were found] ***
2026-06-12 10:21:56.481029 | controller | Friday 12 June 2026  10:21:56 +0000 (0:00:00.034)       0:00:01.043 ***********
2026-06-12 10:21:56.519050 | controller | skipping: [instance]
2026-06-12 10:21:56.519240 | controller |
2026-06-12 10:21:56.519454 | controller | TASK [cifmw_cephadm : Set first monitor IP if it was not passed] ***************
2026-06-12 10:21:56.519680 | controller | Friday 12 June 2026  10:21:56 +0000 (0:00:00.038)       0:00:01.081 ***********
2026-06-12 10:21:56.563346 | controller | ok: [instance]
2026-06-12 10:21:56.563543 | controller |
2026-06-12 10:21:56.563841 | controller | TASK [cifmw_cephadm : Ensure cifmw_cephadm_config_home (e.g. /etc/ceph) exists] ***
2026-06-12 10:21:56.564128 | controller | Friday 12 June 2026  10:21:56 +0000 (0:00:00.044)       0:00:01.125 ***********
2026-06-12 10:21:56.922112 | controller | changed: [instance]
2026-06-12 10:21:56.922297 | controller |
2026-06-12 10:21:56.922417 | controller | TASK [cifmw_cephadm : Ensure specs directory exists] ***************************
2026-06-12 10:21:56.922533 | controller | Friday 12 June 2026  10:21:56 +0000 (0:00:00.358)       0:00:01.483 ***********
2026-06-12 10:21:57.157252 | controller | changed: [instance]
2026-06-12 10:21:57.157302 | controller |
2026-06-12 10:21:57.157307 | controller | TASK [cifmw_cephadm : Stat spec file on ansible host] **************************
2026-06-12 10:21:57.157313 | controller | Friday 12 June 2026  10:21:57 +0000 (0:00:00.235)       0:00:01.719 ***********
2026-06-12 10:21:57.528884 | controller | ok: [instance -> localhost]
2026-06-12 10:21:57.528924 | controller |
2026-06-12 10:21:57.529186 | controller | TASK [cifmw_cephadm : Push cifmw_cephadm_spec to bootstrap node if spec file exsits] ***
2026-06-12 10:21:57.529236 | controller | Friday 12 June 2026  10:21:57 +0000 (0:00:00.370)       0:00:02.089 ***********
2026-06-12 10:21:57.552620 | controller | skipping: [instance]
2026-06-12 10:21:57.552646 | controller |
2026-06-12 10:21:57.552654 | controller | TASK [cifmw_cephadm : Stat cifmw_cephadm_bootstrap_conf file] ******************
2026-06-12 10:21:57.552659 | controller | Friday 12 June 2026  10:21:57 +0000 (0:00:00.024)       0:00:02.114 ***********
2026-06-12 10:21:57.862635 | controller | ok: [instance -> localhost]
2026-06-12 10:21:57.862667 | controller |
2026-06-12 10:21:57.862673 | controller | TASK [cifmw_cephadm : Push it to cifmw_cephadm_assimilate_conf on bootstrap node] ***
2026-06-12 10:21:57.862679 | controller | Friday 12 June 2026  10:21:57 +0000 (0:00:00.309)       0:00:02.424 ***********
2026-06-12 10:21:58.427951 | controller | changed: [instance]
2026-06-12 10:21:58.427992 | controller |
2026-06-12 10:21:58.428000 | controller | TASK [cifmw_cephadm : Add spec to necessary file list when using spec to bootstrap] ***
2026-06-12 10:21:58.428189 | controller | Friday 12 June 2026  10:21:58 +0000 (0:00:00.565)       0:00:02.989 ***********
2026-06-12 10:21:58.454919 | controller | skipping: [instance]
2026-06-12 10:21:58.454956 | controller |
2026-06-12 10:21:58.455310 | controller | TASK [cifmw_cephadm : Stat necessary files to bootstrap with cephadm] **********
2026-06-12 10:21:58.455345 | controller | Friday 12 June 2026  10:21:58 +0000 (0:00:00.027)       0:00:03.016 ***********
2026-06-12 10:21:58.947936 | controller | ok: [instance] => (item=/home/ceph-admin/.ssh/id_rsa)
2026-06-12 10:21:58.947968 | controller | ok: [instance] => (item=/home/ceph-admin/.ssh/id_rsa.pub)
2026-06-12 10:21:58.947973 | controller |
2026-06-12 10:21:58.947977 | controller | TASK [cifmw_cephadm : Fail if necessary files are missing] *********************
2026-06-12 10:21:58.948047 | controller | Friday 12 June 2026  10:21:58 +0000 (0:00:00.492)       0:00:03.509 ***********
2026-06-12 10:21:59.020495 | controller | skipping: [instance] => (item={'changed': False, 'stat': {'exists': True, 'path': '/home/ceph-admin/.ssh/id_rsa', 'mode': '0600', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1002, 'gid': 1002, 'size': 2643, 'inode': 88087276, 'dev': 64513, 'nlink': 1, 'atime': 1781259714.8597486, 'mtime': 1781259714.8537483, 'ctime': 1781259714.8547485, 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': False, 'xgrp': False, 'woth': False, 'roth': False, 'xoth': False, 'isuid': False, 'isgid': False, 'blocks': 8, 'block_size': 4096, 'device_type': 0, 'readable': True, 'writeable': True, 'executable': False, 'pw_name': 'ceph-admin', 'gr_name': 'ceph-admin', 'checksum': '8e3f8c165106c29a134239fac0dc1301f050b322', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '178828459', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/ceph-admin/.ssh/id_rsa', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': '/home/ceph-admin/.ssh/id_rsa', 'ansible_loop_var': 'item'})
2026-06-12 10:21:59.020537 | controller | skipping: [instance] => (item={'changed': False, 'stat': {'exists': True, 'path': '/home/ceph-admin/.ssh/id_rsa.pub', 'mode': '0600', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1002, 'gid': 1002, 'size': 597, 'inode': 88087277, 'dev': 64513, 'nlink': 1, 'atime': 1781259714.8597486, 'mtime': 1781259714.8537483, 'ctime': 1781259714.8547485, 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': False, 'xgrp': False, 'woth': False, 'roth': False, 'xoth': False, 'isuid': False, 'isgid': False, 'blocks': 8, 'block_size': 4096, 'device_type': 0, 'readable': True, 'writeable': True, 'executable': False, 'pw_name': 'ceph-admin', 'gr_name': 'ceph-admin', 'checksum': '5f170cba60e924f0c2d36e449f7bf7bd2b5b60f9', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '3575783349', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/ceph-admin/.ssh/id_rsa.pub', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': '/home/ceph-admin/.ssh/id_rsa.pub', 'ansible_loop_var': 'item'})
2026-06-12 10:21:59.020543 | controller | skipping: [instance]
2026-06-12 10:21:59.020548 | controller |
2026-06-12 10:21:59.020552 | controller | TASK [cifmw_cephadm : Stat pre ceph conf file in case we should bootrap with it] ***
2026-06-12 10:21:59.020556 | controller | Friday 12 June 2026  10:21:59 +0000 (0:00:00.071)       0:00:03.581 ***********
2026-06-12 10:21:59.246908 | controller | ok: [instance]
2026-06-12 10:21:59.246934 | controller |
2026-06-12 10:21:59.246939 | controller | TASK [cifmw_cephadm : If cephadm bootstrap was not run report the reason] ******
2026-06-12 10:21:59.246943 | controller | Friday 12 June 2026  10:21:59 +0000 (0:00:00.226)       0:00:03.808 ***********
2026-06-12 10:21:59.283077 | controller | skipping: [instance]
2026-06-12 10:21:59.283102 | controller |
2026-06-12 10:21:59.283108 | controller | TASK [Mock ceph_mon_dump command] **********************************************
2026-06-12 10:21:59.283113 | controller | Friday 12 June 2026  10:21:59 +0000 (0:00:00.036)       0:00:03.844 ***********
2026-06-12 10:21:59.574894 | controller | changed: [instance -> localhost]
2026-06-12 10:21:59.574935 | controller |
2026-06-12 10:21:59.574945 | controller | TASK [Mock ceph_keys_module_output] ********************************************
2026-06-12 10:21:59.574954 | controller | Friday 12 June 2026  10:21:59 +0000 (0:00:00.291)       0:00:04.136 ***********
2026-06-12 10:21:59.594812 | controller | ok: [instance]
2026-06-12 10:21:59.594834 | controller |
2026-06-12 10:21:59.594973 | controller | TASK [cifmw_cephadm : Get ceph_cli] ********************************************
2026-06-12 10:21:59.594984 | controller | Friday 12 June 2026  10:21:59 +0000 (0:00:00.020)       0:00:04.157 ***********
2026-06-12 10:21:59.616921 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_cephadm/tasks/ceph_cli.yml for instance
2026-06-12 10:21:59.616933 | controller |
2026-06-12 10:21:59.616938 | controller | TASK [cifmw_cephadm : Set ceph CLI] ********************************************
2026-06-12 10:21:59.616942 | controller | Friday 12 June 2026  10:21:59 +0000 (0:00:00.022)       0:00:04.179 ***********
2026-06-12 10:21:59.660744 | controller | ok: [instance]
2026-06-12 10:21:59.660757 | controller |
2026-06-12 10:21:59.660762 | controller | TASK [cifmw_cephadm : Wait for the expected number of monitors to be running] ***
2026-06-12 10:21:59.660774 | controller | Friday 12 June 2026  10:21:59 +0000 (0:00:00.043)       0:00:04.222 ***********
2026-06-12 10:21:59.692483 | controller | skipping: [instance]
2026-06-12 10:21:59.692495 | controller |
2026-06-12 10:21:59.692499 | controller | TASK [cifmw_cephadm : Extract mons_json] ***************************************
2026-06-12 10:21:59.692502 | controller | Friday 12 June 2026  10:21:59 +0000 (0:00:00.032)       0:00:04.254 ***********
2026-06-12 10:21:59.730029 | controller | ok: [instance]
2026-06-12 10:21:59.730042 | controller |
2026-06-12 10:21:59.730046 | controller | TASK [cifmw_cephadm : Build mons_list] *****************************************
2026-06-12 10:21:59.730049 | controller | Friday 12 June 2026  10:21:59 +0000 (0:00:00.037)       0:00:04.292 ***********
2026-06-12 10:21:59.779906 | controller | ok: [instance] => (item=[{'type': 'v2', 'addr': '172.16.11.241:3300', 'nonce': 0}, {'type': 'v1', 'addr': '172.16.11.241:6789', 'nonce': 0}])
2026-06-12 10:21:59.779948 | controller | ok: [instance] => (item=[{'type': 'v2', 'addr': '172.16.11.176:3300', 'nonce': 0}, {'type': 'v1', 'addr': '172.16.11.176:6789', 'nonce': 0}])
2026-06-12 10:21:59.779963 | controller | ok: [instance] => (item=[{'type': 'v2', 'addr': '172.16.11.82:3300', 'nonce': 0}, {'type': 'v1', 'addr': '172.16.11.82:6789', 'nonce': 0}])
2026-06-12 10:21:59.780093 | controller |
2026-06-12 10:21:59.780849 | controller | TASK [cifmw_cephadm : Set cifmw_ceph_client_external_cluster_mon_ips from mons_list] ***
2026-06-12 10:21:59.781177 | controller | Friday 12 June 2026  10:21:59 +0000 (0:00:00.049)       0:00:04.341 ***********
2026-06-12 10:21:59.809533 | controller | ok: [instance]
2026-06-12 10:21:59.809914 | controller |
2026-06-12 10:21:59.810229 | controller | TASK [cifmw_cephadm : Ensure cifmw_ceph_client_vars path exists] ***************
2026-06-12 10:21:59.810528 | controller | Friday 12 June 2026  10:21:59 +0000 (0:00:00.030)       0:00:04.372 ***********
2026-06-12 10:21:59.870203 | controller | skipping: [instance]
2026-06-12 10:21:59.870573 | controller |
2026-06-12 10:21:59.870962 | controller | TASK [cifmw_cephadm : Save cifmw_ceph_client_vars file] ************************
2026-06-12 10:21:59.871301 | controller | Friday 12 June 2026  10:21:59 +0000 (0:00:00.059)       0:00:04.431 ***********
2026-06-12 10:22:00.333906 | controller | changed: [instance -> localhost]
2026-06-12 10:22:00.333981 | controller |
2026-06-12 10:22:00.333992 | controller | TASK [Run verify tasks] ********************************************************
2026-06-12 10:22:00.334001 | controller | Friday 12 June 2026  10:22:00 +0000 (0:00:00.463)       0:00:04.895 ***********
2026-06-12 10:22:00.385980 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_cephadm/molecule/default/tasks/verify.yml for instance
2026-06-12 10:22:00.386030 | controller |
2026-06-12 10:22:00.386040 | controller | TASK [Fail if the FSID is not defined or not valid] ****************************
2026-06-12 10:22:00.386049 | controller | Friday 12 June 2026  10:22:00 +0000 (0:00:00.052)       0:00:04.947 ***********
2026-06-12 10:22:00.408862 | controller | skipping: [instance]
2026-06-12 10:22:00.408896 | controller |
2026-06-12 10:22:00.408902 | controller | TASK [Stat pre ceph conf file] *************************************************
2026-06-12 10:22:00.409121 | controller | Friday 12 June 2026  10:22:00 +0000 (0:00:00.022)       0:00:04.970 ***********
2026-06-12 10:22:00.665891 | controller | ok: [instance]
2026-06-12 10:22:00.665951 | controller |
2026-06-12 10:22:00.666005 | controller | TASK [Fail if pre ceph conf file is missing] ***********************************
2026-06-12 10:22:00.666184 | controller | Friday 12 June 2026  10:22:00 +0000 (0:00:00.257)       0:00:05.227 ***********
2026-06-12 10:22:00.705967 | controller | skipping: [instance]
2026-06-12 10:22:00.706014 | controller |
2026-06-12 10:22:00.706026 | controller | TASK [Get ceph_cli] ************************************************************
2026-06-12 10:22:00.706124 | controller | Friday 12 June 2026  10:22:00 +0000 (0:00:00.040)       0:00:05.267 ***********
2026-06-12 10:22:00.743938 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_cephadm/tasks/ceph_cli.yml for instance
2026-06-12 10:22:00.744400 | controller |
2026-06-12 10:22:00.744426 | controller | TASK [Set ceph CLI] ************************************************************
2026-06-12 10:22:00.744436 | controller | Friday 12 June 2026  10:22:00 +0000 (0:00:00.038)       0:00:05.306 ***********
2026-06-12 10:22:00.785970 | controller | ok: [instance]
2026-06-12 10:22:00.786019 | controller |
2026-06-12 10:22:00.786029 | controller | TASK [Assert that ceph_cli contains expected parameters] ***********************
2026-06-12 10:22:00.786044 | controller | Friday 12 June 2026  10:22:00 +0000 (0:00:00.041)       0:00:05.347 ***********
2026-06-12 10:22:00.831545 | controller | ok: [instance] => changed=false
2026-06-12 10:22:00.831852 | controller |   msg: All assertions passed
2026-06-12 10:22:00.832130 | controller |
2026-06-12 10:22:00.832414 | controller | TASK [Confirm we can inlcude_vars the generated cifmw_ceph_client input file] ***
2026-06-12 10:22:00.832691 | controller | Friday 12 June 2026  10:22:00 +0000 (0:00:00.045)       0:00:05.393 ***********
2026-06-12 10:22:00.859467 | controller | ok: [instance -> localhost]
2026-06-12 10:22:00.859624 | controller |
2026-06-12 10:22:00.859849 | controller | TASK [Assert that the generated client vars are correct] ***********************
2026-06-12 10:22:00.860037 | controller | Friday 12 June 2026  10:22:00 +0000 (0:00:00.028)       0:00:05.421 ***********
2026-06-12 10:22:00.914535 | controller | ok: [instance] => changed=false
2026-06-12 10:22:00.914665 | controller |   msg: All assertions passed
2026-06-12 10:22:00.914848 | controller |
2026-06-12 10:22:00.915006 | controller | PLAY RECAP *********************************************************************
2026-06-12 10:22:00.915163 | controller | instance                   : ok=25   changed=5    unreachable=0    failed=0    skipped=12   rescued=0    ignored=0
2026-06-12 10:22:00.915406 | controller |
2026-06-12 10:22:00.915465 | controller | Friday 12 June 2026  10:22:00 +0000 (0:00:00.054)       0:00:05.476 ***********
2026-06-12 10:22:00.915627 | controller | ===============================================================================
2026-06-12 10:22:00.915808 | controller | Gathering Facts --------------------------------------------------------- 0.90s
2026-06-12 10:22:00.915988 | controller | cifmw_cephadm : Push it to cifmw_cephadm_assimilate_conf on bootstrap node --- 0.57s
2026-06-12 10:22:00.916145 | controller | cifmw_cephadm : Stat necessary files to bootstrap with cephadm ---------- 0.49s
2026-06-12 10:22:00.916297 | controller | cifmw_cephadm : Save cifmw_ceph_client_vars file ------------------------ 0.46s
2026-06-12 10:22:00.916449 | controller | cifmw_cephadm : Stat spec file on ansible host -------------------------- 0.37s
2026-06-12 10:22:00.916602 | controller | cifmw_cephadm : Ensure cifmw_cephadm_config_home (e.g. /etc/ceph) exists --- 0.36s
2026-06-12 10:22:00.916771 | controller | cifmw_cephadm : Stat cifmw_cephadm_bootstrap_conf file ------------------ 0.31s
2026-06-12 10:22:00.916938 | controller | Mock ceph_mon_dump command ---------------------------------------------- 0.29s
2026-06-12 10:22:00.917091 | controller | Stat pre ceph conf file ------------------------------------------------- 0.26s
2026-06-12 10:22:00.917251 | controller | cifmw_cephadm : Ensure specs directory exists --------------------------- 0.24s
2026-06-12 10:22:00.917404 | controller | cifmw_cephadm : Stat pre ceph conf file in case we should bootrap with it --- 0.23s
2026-06-12 10:22:00.917594 | controller | cifmw_cephadm : Fail if necessary files are missing --------------------- 0.07s
2026-06-12 10:22:00.917764 | controller | cifmw_cephadm : Ensure cifmw_ceph_client_vars path exists --------------- 0.06s
2026-06-12 10:22:00.917934 | controller | Assert that the generated client vars are correct ----------------------- 0.05s
2026-06-12 10:22:00.918087 | controller | Run verify tasks -------------------------------------------------------- 0.05s
2026-06-12 10:22:00.918242 | controller | cifmw_cephadm : Build mons_list ----------------------------------------- 0.05s
2026-06-12 10:22:00.918407 | controller | Assert that ceph_cli contains expected parameters ----------------------- 0.05s
2026-06-12 10:22:00.918541 | controller | cifmw_cephadm : Set first monitor IP if it was not passed --------------- 0.04s
2026-06-12 10:22:00.918690 | controller | cifmw_cephadm : Set ceph CLI -------------------------------------------- 0.04s
2026-06-12 10:22:00.918868 | controller | Set ceph CLI ------------------------------------------------------------ 0.04s
2026-06-12 10:22:01.006317 | controller | INFO     Running default > cleanup
2026-06-12 10:22:01.006821 | controller | WARNING  Skipping, cleanup playbook not configured.
2026-06-12 10:22:01.007491 | controller | INFO     Writing /tmp/report.html report.
2026-06-12 10:22:01.180678 | controller | ok: Runtime: 0:01:35.147644
2026-06-12 10:22:01.189976 | 
2026-06-12 10:22:01.190077 | PLAY RECAP
2026-06-12 10:22:01.190168 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-12 10:22:01.190226 | 
2026-06-12 10:22:01.385302 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-06-12 10:22:01.387073 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-06-12 10:22:01.998287 | 
2026-06-12 10:22:01.998384 | PLAY [Ensure ci-framework-data base directories exist on all nodes]
2026-06-12 10:22:02.008250 | 
2026-06-12 10:22:02.008316 | TASK [Create ci-framework-data/logs directory if missing]
2026-06-12 10:22:02.458433 | controller | ok
2026-06-12 10:22:02.500756 | 
2026-06-12 10:22:02.500854 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-06-12 10:22:02.507103 | 
2026-06-12 10:22:02.507216 | TASK [Filter out host if needed]
2026-06-12 10:22:02.517782 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-06-12 10:22:02.522027 | 
2026-06-12 10:22:02.522098 | TASK [Ensure file is present]
2026-06-12 10:22:02.997841 | controller | ok
2026-06-12 10:22:03.010022 | 
2026-06-12 10:22:03.010114 | TASK [Manage molecule report file]
2026-06-12 10:22:03.545159 | controller | ok: Runtime: 0:00:00.004451
2026-06-12 10:22:03.553660 | 
2026-06-12 10:22:03.553796 | TASK [Check if we get ci-framework-data basedir]
2026-06-12 10:22:03.807004 | controller | ok
2026-06-12 10:22:03.819358 | 
2026-06-12 10:22:03.819621 | TASK [Create ci-framework-data log directory for zuul]
2026-06-12 10:22:04.057002 | controller | changed
2026-06-12 10:22:04.073024 | 
2026-06-12 10:22:04.073164 | TASK [Copy ci-framework interesting files]
2026-06-12 10:22:04.330916 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-06-12 10:22:04.602790 | controller | ok: Runtime: 0:00:00.014858
2026-06-12 10:22:04.606288 | 
2026-06-12 10:22:04.606353 | TASK [Get SELinux listing]
2026-06-12 10:22:05.151400 | controller | ok: Runtime: 0:00:00.012268
2026-06-12 10:22:05.157922 | 
2026-06-12 10:22:05.158030 | TASK [Generate log index]
2026-06-12 10:22:06.123006 | controller | changed
2026-06-12 10:22:06.132210 | 
2026-06-12 10:22:06.132334 | TASK [Get some env related data]
2026-06-12 10:22:06.784069 | controller | /home/zuul/.local/bin/ansible
2026-06-12 10:22:07.679697 | controller | ok: Runtime: 0:00:01.035801
2026-06-12 10:22:07.730721 | 
2026-06-12 10:22:07.730854 | TASK [Generate list of logs to collect in home directory]
2026-06-12 10:22:08.104864 | controller | ok: All paths examined
2026-06-12 10:22:08.110221 | 
2026-06-12 10:22:08.110308 | LOOP [Copy logs from home directory]
2026-06-12 10:22:08.571313 | controller | changed:
2026-06-12 10:22:08.571787 | controller | {
2026-06-12 10:22:08.571850 | controller |   "atime": 1781259607.208016,
2026-06-12 10:22:08.571893 | controller |   "ctime": 1781259623.2607086,
2026-06-12 10:22:08.571932 | controller |   "dev": 64513,
2026-06-12 10:22:08.571971 | controller |   "gid": 1000,
2026-06-12 10:22:08.572009 | controller |   "gr_name": "zuul",
2026-06-12 10:22:08.572047 | controller |   "inode": 309368,
2026-06-12 10:22:08.572085 | controller |   "isblk": false,
2026-06-12 10:22:08.572123 | controller |   "ischr": false,
2026-06-12 10:22:08.572159 | controller |   "isdir": false,
2026-06-12 10:22:08.572198 | controller |   "isfifo": false,
2026-06-12 10:22:08.572235 | controller |   "isgid": false,
2026-06-12 10:22:08.572271 | controller |   "islnk": false,
2026-06-12 10:22:08.572307 | controller |   "isreg": true,
2026-06-12 10:22:08.572343 | controller |   "issock": false,
2026-06-12 10:22:08.572387 | controller |   "isuid": false,
2026-06-12 10:22:08.572425 | controller |   "mode": "0644",
2026-06-12 10:22:08.572462 | controller |   "mtime": 1781259623.2607086,
2026-06-12 10:22:08.572499 | controller |   "nlink": 1,
2026-06-12 10:22:08.572579 | controller |   "path": "/home/zuul/ansible.log",
2026-06-12 10:22:08.572619 | controller |   "pw_name": "zuul",
2026-06-12 10:22:08.572656 | controller |   "rgrp": true,
2026-06-12 10:22:08.572692 | controller |   "roth": true,
2026-06-12 10:22:08.572732 | controller |   "rusr": true,
2026-06-12 10:22:08.572770 | controller |   "size": 6749,
2026-06-12 10:22:08.572806 | controller |   "uid": 1000,
2026-06-12 10:22:08.572854 | controller |   "wgrp": false,
2026-06-12 10:22:08.572890 | controller |   "woth": false,
2026-06-12 10:22:08.572952 | controller |   "wusr": true,
2026-06-12 10:22:08.572988 | controller |   "xgrp": false,
2026-06-12 10:22:08.573023 | controller |   "xoth": false,
2026-06-12 10:22:08.573058 | controller |   "xusr": false
2026-06-12 10:22:08.573095 | controller | }
2026-06-12 10:22:08.587600 | 
2026-06-12 10:22:08.587886 | TASK [Copy crio stats log file]
2026-06-12 10:22:08.614353 | controller | skipping: Conditional result was False
2026-06-12 10:22:08.623346 | 
2026-06-12 10:22:08.623468 | TASK [Get SELinux related data]
2026-06-12 10:22:08.882005 | controller | <no matches>
2026-06-12 10:22:09.170870 | controller | ERROR
2026-06-12 10:22:09.171205 | controller | {
2026-06-12 10:22:09.171268 | controller |   "delta": "0:00:00.010301",
2026-06-12 10:22:09.171313 | controller |   "end": "2026-06-12 10:22:08.882885",
2026-06-12 10:22:09.171351 | controller |   "msg": "non-zero return code",
2026-06-12 10:22:09.171390 | controller |   "rc": 1,
2026-06-12 10:22:09.171428 | controller |   "start": "2026-06-12 10:22:08.872584"
2026-06-12 10:22:09.171466 | controller | }
2026-06-12 10:22:09.171551 | controller | ERROR: Ignoring Errors
2026-06-12 10:22:09.181484 | 
2026-06-12 10:22:09.181650 | TASK [Create system configuration directory]
2026-06-12 10:22:09.449251 | controller | changed
2026-06-12 10:22:09.456443 | 
2026-06-12 10:22:09.456582 | TASK [Get some of the system configurations]
2026-06-12 10:22:10.000939 | controller | ok: Runtime: 0:00:00.014602
2026-06-12 10:22:10.007284 | 
2026-06-12 10:22:10.007428 | TASK [Copy generated documentation if available]
2026-06-12 10:22:10.043560 | controller | skipping: Conditional result was False
2026-06-12 10:22:10.050380 | 
2026-06-12 10:22:10.050487 | TASK [Copy generated AsciiDoc documentation if available]
2026-06-12 10:22:10.087297 | controller | skipping: Conditional result was False
2026-06-12 10:22:10.097565 | 
2026-06-12 10:22:10.097695 | TASK [Compress logs bigger than 2MB]
2026-06-12 10:22:10.643253 | controller | ok: Runtime: 0:00:00.007814
2026-06-12 10:22:10.650774 | 
2026-06-12 10:22:10.650923 | TASK [Copy files from workspace on node]
2026-06-12 10:22:10.674404 | controller | ok
2026-06-12 10:22:10.710363 | 
2026-06-12 10:22:10.710446 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-12 10:22:10.725353 | controller | skipping: Conditional result was False
2026-06-12 10:22:10.730988 | 
2026-06-12 10:22:10.731067 | TASK [fetch-output : Set log path for single node]
2026-06-12 10:22:10.759215 | controller | ok
2026-06-12 10:22:10.764159 | 
2026-06-12 10:22:10.764370 | LOOP [fetch-output : Ensure local output dirs]
2026-06-12 10:22:11.063679 | controller -> localhost | changed: "/var/lib/zuul/builds/e358dd5bfd39485aa52403dbc3669c02/work/logs"
2026-06-12 10:22:11.064225 | controller -> localhost | changed: All items complete
2026-06-12 10:22:11.064292 | 
2026-06-12 10:22:11.318595 | controller -> localhost | changed: "/var/lib/zuul/builds/e358dd5bfd39485aa52403dbc3669c02/work/artifacts"
2026-06-12 10:22:11.536186 | controller -> localhost | changed: "/var/lib/zuul/builds/e358dd5bfd39485aa52403dbc3669c02/work/docs"
2026-06-12 10:22:11.575137 | 
2026-06-12 10:22:11.575366 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-12 10:22:12.507944 | controller | changed:
2026-06-12 10:22:12.508192 | controller | .d..t...... ./
2026-06-12 10:22:12.508235 | controller | >f+++++++++ README.html
2026-06-12 10:22:12.508264 | controller | >f+++++++++ ansible-execution.log
2026-06-12 10:22:12.508288 | controller | >f+++++++++ ansible.log
2026-06-12 10:22:12.508315 | controller | >f+++++++++ dmesg.log
2026-06-12 10:22:12.508339 | controller | >f+++++++++ installed-pkgs.log
2026-06-12 10:22:12.508362 | controller | >f+++++++++ python.log
2026-06-12 10:22:12.508384 | controller | >f+++++++++ registries.conf
2026-06-12 10:22:12.508407 | controller | >f+++++++++ report.html
2026-06-12 10:22:12.508429 | controller | >f+++++++++ selinux-denials.log
2026-06-12 10:22:12.508459 | controller | >f+++++++++ selinux-listing.log
2026-06-12 10:22:12.508481 | controller | cd+++++++++ ci-framework-data/
2026-06-12 10:22:12.508503 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-06-12 10:22:12.508563 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-06-12 10:22:12.508590 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-06-12 10:22:12.508613 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-06-12 10:22:12.508635 | controller | cd+++++++++ ci-framework-data/logs/
2026-06-12 10:22:12.508657 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-06-12 10:22:12.508680 | controller | cd+++++++++ registries.conf.d/
2026-06-12 10:22:12.508703 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-06-12 10:22:12.508726 | controller | cd+++++++++ system-config/
2026-06-12 10:22:12.508749 | controller | cd+++++++++ system-config/libvirt/
2026-06-12 10:22:12.508771 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-06-12 10:22:12.508793 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-06-12 10:22:13.036243 | controller | changed: .d..t...... ./
2026-06-12 10:22:13.604794 | controller | changed: .d..t...... ./
2026-06-12 10:22:13.631253 | 
2026-06-12 10:22:13.631414 | TASK [Return artifact to Zuul]
2026-06-12 10:22:13.675649 | controller | ok
2026-06-12 10:22:13.680124 | 
2026-06-12 10:22:13.680241 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-06-12 10:22:13.680444 | 
2026-06-12 10:22:13.680502 | PLAY RECAP
2026-06-12 10:22:13.680623 | controller | ok: 19 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-06-12 10:22:13.680667 | 
2026-06-12 10:22:13.880850 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-06-12 10:22:13.883126 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-06-12 10:22:14.590036 | 
2026-06-12 10:22:14.590312 | PLAY [all]
2026-06-12 10:22:14.600794 | 
2026-06-12 10:22:14.600880 | TASK [include_role : fetch-output]
2026-06-12 10:22:14.641413 | controller | ok
2026-06-12 10:22:14.657813 | 
2026-06-12 10:22:14.657914 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-12 10:22:14.733238 | controller | skipping: Conditional result was False
2026-06-12 10:22:14.741983 | 
2026-06-12 10:22:14.742128 | TASK [fetch-output : Set log path for single node]
2026-06-12 10:22:14.788422 | controller | ok
2026-06-12 10:22:14.797434 | 
2026-06-12 10:22:14.797660 | LOOP [fetch-output : Ensure local output dirs]
2026-06-12 10:22:15.291804 | controller -> localhost | ok: "/var/lib/zuul/builds/e358dd5bfd39485aa52403dbc3669c02/work/logs"
2026-06-12 10:22:15.543602 | controller -> localhost | ok: "/var/lib/zuul/builds/e358dd5bfd39485aa52403dbc3669c02/work/artifacts"
2026-06-12 10:22:15.792395 | controller -> localhost | ok: "/var/lib/zuul/builds/e358dd5bfd39485aa52403dbc3669c02/work/docs"
2026-06-12 10:22:15.804329 | 
2026-06-12 10:22:15.804441 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-12 10:22:16.534894 | controller | ok
2026-06-12 10:22:16.535132 | controller | ok: All items complete
2026-06-12 10:22:16.535172 | 
2026-06-12 10:22:17.076456 | controller | ok
2026-06-12 10:22:17.603666 | controller | ok
2026-06-12 10:22:17.631935 | 
2026-06-12 10:22:17.632147 | TASK [include_role : fetch-output-openshift]
2026-06-12 10:22:17.659863 | controller | skipping: Conditional result was False
2026-06-12 10:22:17.669484 | 
2026-06-12 10:22:17.669666 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-12 10:22:18.143000 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013605
2026-06-12 10:22:18.389361 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.011293
2026-06-12 10:22:18.408363 | 
2026-06-12 10:22:18.408622 | PLAY [all]
2026-06-12 10:22:18.418698 | 
2026-06-12 10:22:18.418870 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-06-12 10:22:18.931720 | controller | changed
2026-06-12 10:22:18.940990 | 
2026-06-12 10:22:18.941107 | PLAY RECAP
2026-06-12 10:22:18.941190 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-06-12 10:22:18.941233 | 
2026-06-12 10:22:19.139989 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-06-12 10:22:19.142334 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-06-12 10:22:19.858325 | 
2026-06-12 10:22:19.858446 | PLAY [localhost]
2026-06-12 10:22:19.868228 | 
2026-06-12 10:22:19.868301 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-12 10:22:20.350876 | localhost | changed
2026-06-12 10:22:20.361393 | 
2026-06-12 10:22:20.361574 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-12 10:22:20.401405 | localhost | ok
2026-06-12 10:22:20.409919 | 
2026-06-12 10:22:20.410005 | TASK [add-fileserver : Create SSH private key tempfile]
2026-06-12 10:22:20.850208 | localhost | changed
2026-06-12 10:22:20.862056 | 
2026-06-12 10:22:20.862298 | TASK [add-fileserver : Create SSH private key from secret]
2026-06-12 10:22:21.726505 | localhost | changed
2026-06-12 10:22:21.731794 | 
2026-06-12 10:22:21.731859 | TASK [add-fileserver : Add fileserver ssh key]
2026-06-12 10:22:22.221723 | localhost | Identity added: /var/lib/zuul/builds/e358dd5bfd39485aa52403dbc3669c02/work/tmp/ansible.ryvp4za8 (/var/lib/zuul/builds/e358dd5bfd39485aa52403dbc3669c02/work/tmp/ansible.ryvp4za8)
2026-06-12 10:22:22.222016 | localhost | ok: Runtime: 0:00:00.018380
2026-06-12 10:22:22.229237 | 
2026-06-12 10:22:22.229377 | TASK [add-fileserver : Remove SSH private key from disk]
2026-06-12 10:22:22.577088 | localhost | ok: Runtime: 0:00:00.010580
2026-06-12 10:22:22.581550 | 
2026-06-12 10:22:22.581699 | TASK [add-fileserver : Add fileserver to inventory]
2026-06-12 10:22:22.640056 | localhost | changed
2026-06-12 10:22:22.649632 | 
2026-06-12 10:22:22.649775 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-06-12 10:22:23.221273 | localhost | changed
2026-06-12 10:22:23.232912 | 
2026-06-12 10:22:23.233022 | PLAY [localhost]
2026-06-12 10:22:23.241471 | 
2026-06-12 10:22:23.241625 | TASK [Generate bulk log download script]
2026-06-12 10:22:23.267958 | localhost | ok
2026-06-12 10:22:23.287988 | 
2026-06-12 10:22:23.288120 | TASK [local-log-download : Check API endpoint is defined]
2026-06-12 10:22:23.338054 | localhost | ok: All assertions passed
2026-06-12 10:22:23.343152 | 
2026-06-12 10:22:23.343234 | TASK [local-log-download : Create download script]
2026-06-12 10:22:23.905966 | localhost -> localhost | changed
2026-06-12 10:22:23.980753 | 
2026-06-12 10:22:23.980957 | TASK [Register quick-download link]
2026-06-12 10:22:24.010849 | localhost | ok
2026-06-12 10:22:24.013338 | 
2026-06-12 10:22:24.013390 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com]
2026-06-12 10:22:24.018117 | 
2026-06-12 10:22:24.018172 | TASK [Set zuul-log-path fact]
2026-06-12 10:22:24.044668 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-12 10:22:24.051401 | 
2026-06-12 10:22:24.051476 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-12 10:22:24.090920 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-12 10:22:24.096568 | 
2026-06-12 10:22:24.096707 | TASK [upload-logs : Create log directories]
2026-06-12 10:22:25.121593 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed
2026-06-12 10:22:25.124853 | 
2026-06-12 10:22:25.124980 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-12 10:22:25.497159 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.011831
2026-06-12 10:22:25.503206 | 
2026-06-12 10:22:25.503335 | TASK [upload-logs : Upload logs to log server]
2026-06-12 10:22:27.730887 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given
2026-06-12 10:22:27.732446 | 
2026-06-12 10:22:27.732507 | LOOP [upload-logs : Compress console log and json output]