2026-06-10 09:55:29.009676 | Job console starting
2026-06-10 09:55:29.130902 | Updating git repos
2026-06-10 09:55:29.191350 | Cloning repos into workspace
2026-06-10 09:55:31.513495 | Restoring repo states
2026-06-10 09:55:31.532017 | Merging changes
2026-06-10 09:55:33.559966 | Checking out repos
2026-06-10 09:55:34.207488 | Preparing playbooks
2026-06-10 09:55:41.332365 | Running Ansible setup
2026-06-10 09:55:46.464188 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-06-10 09:55:47.049484 | 
2026-06-10 09:55:47.049649 | PLAY [localhost]
2026-06-10 09:55:47.058433 | 
2026-06-10 09:55:47.058536 | TASK [Gathering Facts]
2026-06-10 09:55:48.035396 | localhost | ok
2026-06-10 09:55:48.044582 | 
2026-06-10 09:55:48.044685 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-10 09:55:48.510333 | localhost -> localhost | changed
2026-06-10 09:55:48.523170 | 
2026-06-10 09:55:48.523382 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-10 09:55:49.613822 | localhost -> localhost | changed
2026-06-10 09:55:49.630956 | 
2026-06-10 09:55:49.631139 | TASK [Setup log path fact]
2026-06-10 09:55:49.653636 | localhost | ok
2026-06-10 09:55:49.672810 | 
2026-06-10 09:55:49.673050 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-10 09:55:49.714958 | localhost | ok
2026-06-10 09:55:49.726023 | 
2026-06-10 09:55:49.726157 | TASK [emit-job-header : Print job information]
2026-06-10 09:55:49.767899 | # Job Information
2026-06-10 09:55:49.768174 | Ansible Version: 2.16.18
2026-06-10 09:55:49.768229 | Job: cifmw-molecule-ci_local_storage
2026-06-10 09:55:49.768272 | Pipeline: github-check
2026-06-10 09:55:49.768310 | Executor: 38.129.56.84
2026-06-10 09:55:49.768346 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3868
2026-06-10 09:55:49.768386 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/e1f/rdoproject.org/e1f04a35fcb14648a1cfe92e4697c701/
2026-06-10 09:55:49.768425 | Event ID: 3d1700b0-64b2-11f1-9d7e-089b464e492d
2026-06-10 09:55:49.774105 | 
2026-06-10 09:55:49.774238 | LOOP [emit-job-header : Print node information]
2026-06-10 09:55:49.887513 | localhost | ok:
2026-06-10 09:55:49.887802 | localhost | # Node Information
2026-06-10 09:55:49.887885 | localhost | Inventory Hostname: controller
2026-06-10 09:55:49.887941 | localhost | Hostname: np0000069576
2026-06-10 09:55:49.887982 | localhost | Username: zuul
2026-06-10 09:55:49.888023 | localhost | Distro: CentOS 9
2026-06-10 09:55:49.888061 | localhost | Provider: vexxhost-nodepool-tripleo
2026-06-10 09:55:49.888097 | localhost | Region: RegionOne
2026-06-10 09:55:49.888133 | localhost | Label: centos-9-stream-crc-2-56-0-xl
2026-06-10 09:55:49.888168 | localhost | Product Name: OpenStack Nova
2026-06-10 09:55:49.888204 | localhost | Interface IP: 38.102.83.74
2026-06-10 09:55:49.899357 | 
2026-06-10 09:55:49.899460 | PLAY [all]
2026-06-10 09:55:49.910388 | 
2026-06-10 09:55:49.910510 | TASK [Gather network facts]
2026-06-10 09:55:50.463379 | controller | ok
2026-06-10 09:55:50.479471 | 
2026-06-10 09:55:50.479704 | TASK [include_role : start-zuul-console]
2026-06-10 09:55:50.512745 | controller | ok
2026-06-10 09:55:50.536238 | 
2026-06-10 09:55:50.536374 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-06-10 09:55:51.073439 | controller | ok
2026-06-10 09:55:51.081495 | 
2026-06-10 09:55:51.081606 | TASK [include_role : add-build-sshkey]
2026-06-10 09:55:51.112780 | controller | ok
2026-06-10 09:55:51.127206 | 
2026-06-10 09:55:51.127347 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-10 09:55:51.438232 | controller -> localhost | ok
2026-06-10 09:55:51.448663 | 
2026-06-10 09:55:51.448803 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-10 09:55:51.482806 | controller | ok
2026-06-10 09:55:51.513973 | controller | included: /var/lib/zuul/builds/e1f04a35fcb14648a1cfe92e4697c701/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-06-10 09:55:51.523540 | 
2026-06-10 09:55:51.523673 | TASK [add-build-sshkey : Create Temp SSH key]
2026-06-10 09:55:52.466589 | controller -> localhost | Generating public/private rsa key pair.
2026-06-10 09:55:52.467587 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e1f04a35fcb14648a1cfe92e4697c701/work/e1f04a35fcb14648a1cfe92e4697c701_id_rsa
2026-06-10 09:55:52.467756 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e1f04a35fcb14648a1cfe92e4697c701/work/e1f04a35fcb14648a1cfe92e4697c701_id_rsa.pub
2026-06-10 09:55:52.467978 | controller -> localhost | The key fingerprint is:
2026-06-10 09:55:52.468134 | controller -> localhost | SHA256:qsDp/yS9zprI4a5d6cAQoPojyR/U2n2C26c0sgRmLX8 zuul-build-sshkey
2026-06-10 09:55:52.468788 | controller -> localhost | The key's randomart image is:
2026-06-10 09:55:52.468969 | controller -> localhost | +---[RSA 3072]----+
2026-06-10 09:55:52.469043 | controller -> localhost | |.                |
2026-06-10 09:55:52.469089 | controller -> localhost | |o                |
2026-06-10 09:55:52.469129 | controller -> localhost | |o                |
2026-06-10 09:55:52.469168 | controller -> localhost | |..  o            |
2026-06-10 09:55:52.469207 | controller -> localhost | |o  * o  S        |
2026-06-10 09:55:52.469246 | controller -> localhost | |.*+.*oo.         |
2026-06-10 09:55:52.469284 | controller -> localhost | |o.Xo+*+E .       |
2026-06-10 09:55:52.469322 | controller -> localhost | | *.Oo*B.+.       |
2026-06-10 09:55:52.469360 | controller -> localhost | |.oBo**=oo        |
2026-06-10 09:55:52.469397 | controller -> localhost | +----[SHA256]-----+
2026-06-10 09:55:52.469489 | controller -> localhost | ok: Runtime: 0:00:00.438317
2026-06-10 09:55:52.478176 | 
2026-06-10 09:55:52.478307 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-06-10 09:55:52.512882 | controller | ok
2026-06-10 09:55:52.532206 | controller | included: /var/lib/zuul/builds/e1f04a35fcb14648a1cfe92e4697c701/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-06-10 09:55:52.547707 | 
2026-06-10 09:55:52.547830 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-06-10 09:55:52.583235 | controller | skipping: Conditional result was False
2026-06-10 09:55:52.593395 | 
2026-06-10 09:55:52.593540 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-06-10 09:55:53.354565 | controller | changed
2026-06-10 09:55:53.359416 | 
2026-06-10 09:55:53.359500 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-06-10 09:55:53.736625 | controller | ok
2026-06-10 09:55:53.741394 | 
2026-06-10 09:55:53.741496 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-06-10 09:55:55.479009 | controller | changed
2026-06-10 09:55:55.489209 | 
2026-06-10 09:55:55.489363 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-06-10 09:55:57.291158 | controller | changed
2026-06-10 09:55:57.299442 | 
2026-06-10 09:55:57.299582 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-06-10 09:55:57.326380 | controller | skipping: Conditional result was False
2026-06-10 09:55:57.338531 | 
2026-06-10 09:55:57.338726 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-10 09:55:57.802272 | controller -> localhost | changed
2026-06-10 09:55:57.817457 | 
2026-06-10 09:55:57.817593 | TASK [add-build-sshkey : Add back temp key]
2026-06-10 09:55:58.155225 | controller -> localhost | Identity added: /var/lib/zuul/builds/e1f04a35fcb14648a1cfe92e4697c701/work/e1f04a35fcb14648a1cfe92e4697c701_id_rsa (zuul-build-sshkey)
2026-06-10 09:55:58.155603 | controller -> localhost | ok: Runtime: 0:00:00.018143
2026-06-10 09:55:58.164085 | 
2026-06-10 09:55:58.164229 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-06-10 09:55:58.721484 | controller | ok
2026-06-10 09:55:58.728438 | 
2026-06-10 09:55:58.728579 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-06-10 09:55:58.765983 | controller | skipping: Conditional result was False
2026-06-10 09:55:58.783238 | 
2026-06-10 09:55:58.783404 | TASK [include_role : validate-host]
2026-06-10 09:55:58.817685 | controller | ok
2026-06-10 09:55:58.852759 | 
2026-06-10 09:55:58.852967 | TASK [validate-host : Define zuul_info_dir fact]
2026-06-10 09:55:58.894246 | controller | ok
2026-06-10 09:55:58.899228 | 
2026-06-10 09:55:58.899316 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-06-10 09:55:59.188646 | controller -> localhost | ok
2026-06-10 09:55:59.193948 | 
2026-06-10 09:55:59.194038 | TASK [validate-host : Collect information about the host]
2026-06-10 09:56:00.068143 | controller | ok
2026-06-10 09:56:00.084765 | 
2026-06-10 09:56:00.084934 | TASK [validate-host : Sanitize hostname]
2026-06-10 09:56:00.158645 | controller | ok
2026-06-10 09:56:00.171472 | 
2026-06-10 09:56:00.171711 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-06-10 09:56:00.736547 | controller -> localhost | changed
2026-06-10 09:56:00.741101 | 
2026-06-10 09:56:00.741166 | TASK [validate-host : Collect information about zuul worker]
2026-06-10 09:56:01.362501 | controller | ok
2026-06-10 09:56:01.366777 | 
2026-06-10 09:56:01.366865 | TASK [validate-host : Write out all zuul information for each host]
2026-06-10 09:56:01.863724 | controller -> localhost | changed
2026-06-10 09:56:01.887750 | 
2026-06-10 09:56:01.887941 | TASK [include_role : prepare-workspace-openshift]
2026-06-10 09:56:01.915611 | controller | skipping: Conditional result was False
2026-06-10 09:56:01.923591 | 
2026-06-10 09:56:01.923724 | TASK [include_role : remove-zuul-sshkey]
2026-06-10 09:56:01.965015 | controller | skipping: Conditional result was False
2026-06-10 09:56:01.975750 | 
2026-06-10 09:56:01.975890 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-10 09:56:02.394275 | controller | ok: "logs"
2026-06-10 09:56:02.394691 | controller | ok: All items complete
2026-06-10 09:56:02.394759 | 
2026-06-10 09:56:02.701479 | controller | ok: "artifacts"
2026-06-10 09:56:03.055907 | controller | ok: "docs"
2026-06-10 09:56:03.069148 | 
2026-06-10 09:56:03.069379 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-10 09:56:03.449121 | controller | changed: "logs"
2026-06-10 09:56:03.807263 | controller | changed: "artifacts"
2026-06-10 09:56:04.152796 | controller | changed: "docs"
2026-06-10 09:56:04.170287 | 
2026-06-10 09:56:04.170385 | PLAY RECAP
2026-06-10 09:56:04.170465 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-06-10 09:56:04.170519 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-10 09:56:04.170556 | 
2026-06-10 09:56:04.316481 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-06-10 09:56:04.318710 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-06-10 09:56:04.975553 | 
2026-06-10 09:56:04.975692 | PLAY [all]
2026-06-10 09:56:04.986351 | 
2026-06-10 09:56:04.986436 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-06-10 09:56:05.064771 | controller | ok
2026-06-10 09:56:05.068719 | 
2026-06-10 09:56:05.068805 | TASK [mirror-info-fork : Create /etc/ci]
2026-06-10 09:56:05.634897 | controller | changed
2026-06-10 09:56:05.639910 | 
2026-06-10 09:56:05.639984 | TASK [mirror-info-fork : Install ci_mirror script]
2026-06-10 09:56:07.458106 | controller | changed
2026-06-10 09:56:07.476197 | 
2026-06-10 09:56:07.476362 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-06-10 09:56:08.117412 | controller | changed:
2026-06-10 09:56:08.117612 | controller | {
2026-06-10 09:56:08.117637 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-06-10 09:56:08.117688 | controller | }
2026-06-10 09:56:08.549449 | controller | changed:
2026-06-10 09:56:08.549620 | controller | {
2026-06-10 09:56:08.549674 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-06-10 09:56:08.549716 | controller | }
2026-06-10 09:56:08.937807 | controller | changed:
2026-06-10 09:56:08.938034 | controller | {
2026-06-10 09:56:08.938097 | 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-10 09:56:08.938147 | controller | }
2026-06-10 09:56:09.427605 | controller | changed:
2026-06-10 09:56:09.427811 | controller | {
2026-06-10 09:56:09.429815 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-06-10 09:56:09.429921 | controller | }
2026-06-10 09:56:09.810138 | controller | changed:
2026-06-10 09:56:09.810304 | controller | {
2026-06-10 09:56:09.810368 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-06-10 09:56:09.810410 | controller | }
2026-06-10 09:56:10.235444 | controller | changed:
2026-06-10 09:56:10.235607 | controller | {
2026-06-10 09:56:10.235663 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-06-10 09:56:10.235708 | controller | }
2026-06-10 09:56:10.654760 | controller | changed:
2026-06-10 09:56:10.654962 | controller | {
2026-06-10 09:56:10.655016 | 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-10 09:56:10.655057 | controller | }
2026-06-10 09:56:11.051579 | controller | changed:
2026-06-10 09:56:11.051747 | controller | {
2026-06-10 09:56:11.051801 | 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-10 09:56:11.051896 | controller | }
2026-06-10 09:56:11.485650 | controller | changed:
2026-06-10 09:56:11.485802 | controller | {
2026-06-10 09:56:11.485843 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-06-10 09:56:11.485900 | controller | }
2026-06-10 09:56:11.916441 | controller | changed:
2026-06-10 09:56:11.916587 | controller | {
2026-06-10 09:56:11.916636 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-06-10 09:56:11.916675 | controller | }
2026-06-10 09:56:12.317725 | controller | changed:
2026-06-10 09:56:12.317902 | controller | {
2026-06-10 09:56:12.317980 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-06-10 09:56:12.318035 | controller | }
2026-06-10 09:56:12.732553 | controller | changed:
2026-06-10 09:56:12.732673 | controller | {
2026-06-10 09:56:12.732696 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-06-10 09:56:12.732715 | controller | }
2026-06-10 09:56:13.167375 | controller | changed:
2026-06-10 09:56:13.167665 | controller | {
2026-06-10 09:56:13.167725 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-06-10 09:56:13.167768 | controller | }
2026-06-10 09:56:13.648037 | controller | changed:
2026-06-10 09:56:13.648178 | controller | {
2026-06-10 09:56:13.648209 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-06-10 09:56:13.648236 | controller | }
2026-06-10 09:56:14.020093 | controller | changed:
2026-06-10 09:56:14.020313 | controller | {
2026-06-10 09:56:14.020370 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-06-10 09:56:14.020412 | controller | }
2026-06-10 09:56:14.422885 | controller | changed:
2026-06-10 09:56:14.423045 | controller | {
2026-06-10 09:56:14.423094 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-06-10 09:56:14.423133 | controller | }
2026-06-10 09:56:14.840811 | controller | changed:
2026-06-10 09:56:14.841005 | controller | {
2026-06-10 09:56:14.841056 | 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-10 09:56:14.841099 | controller | }
2026-06-10 09:56:15.224062 | controller | changed:
2026-06-10 09:56:15.224230 | controller | {
2026-06-10 09:56:15.224284 | 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-10 09:56:15.224423 | controller | }
2026-06-10 09:56:15.593246 | controller | changed:
2026-06-10 09:56:15.593362 | controller | {
2026-06-10 09:56:15.593384 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-06-10 09:56:15.593402 | controller | }
2026-06-10 09:56:16.009210 | controller | changed:
2026-06-10 09:56:16.009568 | controller | {
2026-06-10 09:56:16.009622 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-06-10 09:56:16.009664 | controller | }
2026-06-10 09:56:16.411400 | controller | changed:
2026-06-10 09:56:16.411703 | controller | {
2026-06-10 09:56:16.411973 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-06-10 09:56:16.412057 | controller | }
2026-06-10 09:56:16.852210 | controller | changed:
2026-06-10 09:56:16.852365 | controller | {
2026-06-10 09:56:16.852438 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-06-10 09:56:16.852480 | controller | }
2026-06-10 09:56:17.232913 | controller | changed:
2026-06-10 09:56:17.233096 | controller | {
2026-06-10 09:56:17.233148 | 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-10 09:56:17.233202 | controller | }
2026-06-10 09:56:17.644197 | controller | changed:
2026-06-10 09:56:17.644375 | controller | {
2026-06-10 09:56:17.644425 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-06-10 09:56:17.644464 | controller | }
2026-06-10 09:56:18.053913 | controller | changed:
2026-06-10 09:56:18.054076 | controller | {
2026-06-10 09:56:18.054128 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-06-10 09:56:18.054167 | controller | }
2026-06-10 09:56:18.468540 | controller | changed:
2026-06-10 09:56:18.468698 | controller | {
2026-06-10 09:56:18.468747 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-06-10 09:56:18.468787 | controller | }
2026-06-10 09:56:18.510548 | 
2026-06-10 09:56:18.510763 | TASK [Set timezone to UTC]
2026-06-10 09:56:19.220738 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-06-10 09:56:19.229023 | 
2026-06-10 09:56:19.229187 | TASK [Create nodepool directory]
2026-06-10 09:56:19.624589 | controller | changed
2026-06-10 09:56:19.629739 | 
2026-06-10 09:56:19.629811 | TASK [Create nodepool sub_nodes file]
2026-06-10 09:56:21.123129 | controller | changed
2026-06-10 09:56:21.130798 | 
2026-06-10 09:56:21.130963 | TASK [Create nodepool sub_nodes_private file]
2026-06-10 09:56:22.609064 | controller | changed
2026-06-10 09:56:22.612730 | 
2026-06-10 09:56:22.612792 | LOOP [Populate nodepool sub_nodes file]
2026-06-10 09:56:22.672179 | 
2026-06-10 09:56:22.672373 | LOOP [Populate nodepool sub_nodes_private file]
2026-06-10 09:56:22.711356 | 
2026-06-10 09:56:22.711539 | TASK [Create nodepool primary file]
2026-06-10 09:56:22.735877 | controller | skipping: Conditional result was False
2026-06-10 09:56:22.741528 | 
2026-06-10 09:56:22.741599 | TASK [Create nodepool node_private for this node]
2026-06-10 09:56:24.324006 | controller | changed
2026-06-10 09:56:24.333366 | 
2026-06-10 09:56:24.333497 | LOOP [Copy ssh keys to nodepool directory]
2026-06-10 09:56:24.949710 | controller | ok: Item: id_rsa Runtime: 0:00:00.007907
2026-06-10 09:56:24.950008 | 
2026-06-10 09:56:25.215296 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005805
2026-06-10 09:56:25.222387 | 
2026-06-10 09:56:25.222568 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-06-10 09:56:26.704885 | controller | changed
2026-06-10 09:56:26.712528 | 
2026-06-10 09:56:26.712660 | TASK [Validate sudoers config after edits]
2026-06-10 09:56:27.164502 | controller | /etc/sudoers: parsed OK
2026-06-10 09:56:27.164564 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-06-10 09:56:27.164576 | controller | /etc/sudoers.d/zuul: parsed OK
2026-06-10 09:56:27.164584 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-06-10 09:56:27.254962 | controller | ok: Runtime: 0:00:00.008418
2026-06-10 09:56:27.264220 | 
2026-06-10 09:56:27.264354 | TASK [Show the environment passed in to job shell scripts]
2026-06-10 09:56:27.678193 | controller | SHELL=/bin/bash
2026-06-10 09:56:27.678291 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-06-10 09:56:27.678369 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-06-10 09:56:27.678385 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/89/3989/40bee6119c5e40bf54a372b80a12a0ebdff9661f^openstack-k8s-operators/ci-framework:main:refs/changes/68/3868/19e61090ad44008046333639aa9a672fbf50b86c
2026-06-10 09:56:27.678397 | controller | PWD=/home/zuul
2026-06-10 09:56:27.678405 | controller | ZUUL_PIPELINE=github-check
2026-06-10 09:56:27.678413 | controller | LOGNAME=zuul
2026-06-10 09:56:27.678422 | controller | XDG_SESSION_TYPE=tty
2026-06-10 09:56:27.678429 | controller | _=/usr/bin/env
2026-06-10 09:56:27.678437 | controller | MOTD_SHOWN=pam
2026-06-10 09:56:27.678444 | controller | HOME=/home/zuul
2026-06-10 09:56:27.678453 | controller | LANG=en_US.UTF-8
2026-06-10 09:56:27.678461 | controller | SSH_CONNECTION=38.129.56.84 36622 38.102.83.74 22
2026-06-10 09:56:27.678470 | 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-10 09:56:27.678479 | controller | ZUUL_CHANGE_IDS=3989,40bee6119c5e40bf54a372b80a12a0ebdff9661f 3868,19e61090ad44008046333639aa9a672fbf50b86c
2026-06-10 09:56:27.678487 | controller | WORKSPACE=/home/zuul/workspace
2026-06-10 09:56:27.678495 | controller | XDG_SESSION_CLASS=user
2026-06-10 09:56:27.678503 | controller | SELINUX_ROLE_REQUESTED=
2026-06-10 09:56:27.678511 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-06-10 09:56:27.678519 | controller | USER=zuul
2026-06-10 09:56:27.678527 | controller | ZUUL_VOTING=True
2026-06-10 09:56:27.678534 | controller | BUILD_TIMEOUT=1800000
2026-06-10 09:56:27.678542 | controller | SELINUX_USE_CURRENT_RANGE=
2026-06-10 09:56:27.678550 | controller | SHLVL=1
2026-06-10 09:56:27.678558 | controller | ZUUL_PATCHSET=19e61090ad44008046333639aa9a672fbf50b86c
2026-06-10 09:56:27.678565 | controller | XDG_SESSION_ID=1
2026-06-10 09:56:27.678573 | controller | ZUUL_BRANCH=main
2026-06-10 09:56:27.678585 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-06-10 09:56:27.678593 | controller | SSH_CLIENT=38.129.56.84 36622 22
2026-06-10 09:56:27.678601 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-06-10 09:56:27.678609 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-06-10 09:56:27.678617 | controller | which_declare=declare -f
2026-06-10 09:56:27.678625 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-06-10 09:56:27.678633 | controller | SELINUX_LEVEL_REQUESTED=
2026-06-10 09:56:27.678640 | controller | ZUUL_CHANGE=3868
2026-06-10 09:56:27.678648 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-06-10 09:56:27.678656 | controller | ZUUL_UUID=e1f04a35fcb14648a1cfe92e4697c701
2026-06-10 09:56:27.678664 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-06-10 09:56:27.678672 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-06-10 09:56:27.678680 | controller | }
2026-06-10 09:56:27.814216 | controller | ok: Runtime: 0:00:00.011818
2026-06-10 09:56:27.823224 | 
2026-06-10 09:56:27.823462 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-06-10 09:56:27.850347 | controller | skipping: Conditional result was False
2026-06-10 09:56:27.860816 | 
2026-06-10 09:56:27.860998 | TASK [Symlink /home/zuul-worker/workspace]
2026-06-10 09:56:28.403200 | controller | skipping: Conditional result was False
2026-06-10 09:56:28.409666 | 
2026-06-10 09:56:28.409762 | TASK [Ensure legacy workspace directory]
2026-06-10 09:56:28.811476 | controller | changed
2026-06-10 09:56:28.814278 | 
2026-06-10 09:56:28.814382 | PLAY RECAP
2026-06-10 09:56:28.814481 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-06-10 09:56:28.814528 | 
2026-06-10 09:56:28.966033 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-06-10 09:56:28.967617 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-06-10 09:56:29.629151 | 
2026-06-10 09:56:29.629334 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-06-10 09:56:29.641179 | 
2026-06-10 09:56:29.641284 | TASK [Create zuul-output directory]
2026-06-10 09:56:30.253635 | controller | changed
2026-06-10 09:56:30.262322 | 
2026-06-10 09:56:30.262547 | TASK [Slurp Zuul inventory test]
2026-06-10 09:56:30.820641 | controller -> localhost | ok
2026-06-10 09:56:30.827209 | 
2026-06-10 09:56:30.827275 | TASK [Save zuul inventory]
2026-06-10 09:56:32.690399 | controller | changed
2026-06-10 09:56:32.701240 | 
2026-06-10 09:56:32.701466 | TASK [Save zuul vars without the change_message]
2026-06-10 09:56:34.396241 | controller | changed
2026-06-10 09:56:34.399362 | 
2026-06-10 09:56:34.399457 | PLAY RECAP
2026-06-10 09:56:34.399595 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-10 09:56:34.399649 | 
2026-06-10 09:56:34.585847 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-06-10 09:56:34.587368 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-06-10 09:56:35.312048 | 
2026-06-10 09:56:35.312218 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-06-10 09:56:35.328559 | 
2026-06-10 09:56:35.328747 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-06-10 09:56:35.350297 | controller | ok
2026-06-10 09:56:35.366058 | 
2026-06-10 09:56:35.366218 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-06-10 09:56:35.410818 | controller | skipping: Conditional result was False
2026-06-10 09:56:35.415721 | 
2026-06-10 09:56:35.415793 | TASK [mirror-info-fork : Create /etc/ci]
2026-06-10 09:56:35.958957 | controller | ok
2026-06-10 09:56:35.968366 | 
2026-06-10 09:56:35.968506 | TASK [mirror-info-fork : Install ci_mirror script]
2026-06-10 09:56:37.410933 | controller | ok
2026-06-10 09:56:37.420572 | 
2026-06-10 09:56:37.420723 | TASK [Prepare workspace]
2026-06-10 09:56:37.445278 | controller | ok
2026-06-10 09:56:37.470691 | 
2026-06-10 09:56:37.470907 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-10 09:56:38.045227 | controller | ok
2026-06-10 09:56:38.056403 | 
2026-06-10 09:56:38.056570 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-10 09:56:40.522465 | controller | Output suppressed because no_log was given
2026-06-10 09:56:40.528923 | 
2026-06-10 09:56:40.528985 | LOOP [Create zuul-output directory]
2026-06-10 09:56:40.887812 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-06-10 09:56:41.222627 | controller | ok: "/home/zuul/zuul-output/logs"
2026-06-10 09:56:41.239764 | 
2026-06-10 09:56:41.239928 | TASK [Install required packages]
2026-06-10 09:57:00.164801 | controller | ok: Nothing to do
2026-06-10 09:57:00.168907 | 
2026-06-10 09:57:00.168985 | TASK [Install venv]
2026-06-10 09:58:31.691043 | controller | changed
2026-06-10 09:58:31.709955 | 
2026-06-10 09:58:31.710095 | PLAY RECAP
2026-06-10 09:58:31.710157 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-10 09:58:31.710187 | 
2026-06-10 09:58:31.871535 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-06-10 09:58:31.873331 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-06-10 09:58:32.529212 | 
2026-06-10 09:58:32.529331 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-06-10 09:58:32.540289 | 
2026-06-10 09:58:32.540371 | TASK [Gather required facts]
2026-06-10 09:58:33.339263 | controller | ok
2026-06-10 09:58:33.349535 | 
2026-06-10 09:58:33.349703 | TASK [Load environment var if instructed to]
2026-06-10 09:58:33.386817 | controller | skipping: Conditional result was False
2026-06-10 09:58:33.396525 | 
2026-06-10 09:58:33.396672 | TASK [Ensure group_vars dir exists]
2026-06-10 09:58:33.953716 | controller | ok
2026-06-10 09:58:33.959172 | 
2026-06-10 09:58:33.959272 | TASK [Print related variables]
2026-06-10 09:58:34.002648 | controller | ok:
2026-06-10 09:58:34.002863 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-06-10 09:58:34.002938 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_local_storage
2026-06-10 09:58:34.007624 | 
2026-06-10 09:58:34.007699 | TASK [Run molecule]
2026-06-10 09:58:35.355373 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-06-10 09:58:35.429692 | controller | INFO     Performing prerun with role_name_check=0...
2026-06-10 09:58:57.792370 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-10 09:58:57.792673 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-10 09:58:57.793095 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-10 09:58:57.793571 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-10 09:58:57.794014 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+62a58af1 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 1.0.0+62a58af1 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-10 09:58:57.794511 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-10 09:58:57.794915 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-10 09:58:57.795395 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-10 09:58:57.795906 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-10 09:58:57.796334 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-10 09:58:57.796771 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-10 09:58:57.797231 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-10 09:58:57.797650 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-10 09:58:57.798065 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-10 09:58:57.798517 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-06-10 09:58:57.808227 | controller | INFO     Running default > prepare
2026-06-10 09:58:58.543248 | controller |
2026-06-10 09:58:58.543291 | controller | PLAY [Prepare] *****************************************************************
2026-06-10 09:58:58.543298 | controller |
2026-06-10 09:58:58.543419 | controller | TASK [Gathering Facts] *********************************************************
2026-06-10 09:58:58.543556 | controller | Wednesday 10 June 2026  09:58:58 +0000 (0:00:00.014)       0:00:00.014 ********
2026-06-10 09:58:59.487175 | controller | ok: [instance]
2026-06-10 09:58:59.487341 | controller |
2026-06-10 09:58:59.487565 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2026-06-10 09:58:59.487778 | controller | Wednesday 10 June 2026  09:58:59 +0000 (0:00:00.944)       0:00:00.958 ********
2026-06-10 09:58:59.518540 | controller | skipping: [instance]
2026-06-10 09:58:59.518716 | controller |
2026-06-10 09:58:59.518922 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2026-06-10 09:58:59.519141 | controller | Wednesday 10 June 2026  09:58:59 +0000 (0:00:00.031)       0:00:00.990 ********
2026-06-10 09:58:59.595443 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2026-06-10 09:58:59.595673 | controller |
2026-06-10 09:58:59.596012 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2026-06-10 09:58:59.596342 | controller | Wednesday 10 June 2026  09:58:59 +0000 (0:00:00.076)       0:00:01.067 ********
2026-06-10 09:58:59.918243 | controller | ok: [instance]
2026-06-10 09:58:59.918394 | controller |
2026-06-10 09:58:59.918641 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2026-06-10 09:58:59.918849 | controller | Wednesday 10 June 2026  09:58:59 +0000 (0:00:00.323)       0:00:01.390 ********
2026-06-10 09:59:00.285102 | controller | ok: [instance]
2026-06-10 09:59:00.285205 | controller |
2026-06-10 09:59:00.285219 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2026-06-10 09:59:00.285368 | controller | Wednesday 10 June 2026  09:59:00 +0000 (0:00:00.366)       0:00:01.757 ********
2026-06-10 09:59:01.040295 | controller | ok: [instance] => (item=ubi-9-appstream)
2026-06-10 09:59:01.040386 | controller | ok: [instance] => (item=ubi-9-baseos)
2026-06-10 09:59:01.040567 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2026-06-10 09:59:01.040757 | controller |
2026-06-10 09:59:01.040954 | controller | TASK [Deploy repo-setup] *******************************************************
2026-06-10 09:59:01.041132 | controller | Wednesday 10 June 2026  09:59:01 +0000 (0:00:00.754)       0:00:02.512 ********
2026-06-10 09:59:01.106479 | controller |
2026-06-10 09:59:01.106697 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2026-06-10 09:59:01.107065 | controller | Wednesday 10 June 2026  09:59:01 +0000 (0:00:00.066)       0:00:02.578 ********
2026-06-10 09:59:01.731702 | controller | changed: [instance] => (item=tmp)
2026-06-10 09:59:01.731804 | controller | changed: [instance] => (item=artifacts/repositories)
2026-06-10 09:59:01.731950 | controller | changed: [instance] => (item=venv/repo_setup)
2026-06-10 09:59:01.732090 | controller |
2026-06-10 09:59:01.732271 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2026-06-10 09:59:01.732407 | controller | Wednesday 10 June 2026  09:59:01 +0000 (0:00:00.624)       0:00:03.203 ********
2026-06-10 09:59:02.750394 | controller | ok: [instance]
2026-06-10 09:59:02.751305 | controller |
2026-06-10 09:59:02.751386 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2026-06-10 09:59:02.751400 | controller | Wednesday 10 June 2026  09:59:02 +0000 (0:00:01.018)       0:00:04.222 ********
2026-06-10 09:59:03.920352 | controller | changed: [instance]
2026-06-10 09:59:03.920395 | controller |
2026-06-10 09:59:03.920406 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2026-06-10 09:59:03.920415 | controller | Wednesday 10 June 2026  09:59:03 +0000 (0:00:01.169)       0:00:05.391 ********
2026-06-10 09:59:11.538344 | controller | changed: [instance]
2026-06-10 09:59:11.538393 | controller |
2026-06-10 09:59:11.538451 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2026-06-10 09:59:11.538707 | controller | Wednesday 10 June 2026  09:59:11 +0000 (0:00:07.618)       0:00:13.010 ********
2026-06-10 09:59:12.378010 | controller | changed: [instance]
2026-06-10 09:59:12.378036 | controller |
2026-06-10 09:59:12.378041 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2026-06-10 09:59:12.378046 | controller | Wednesday 10 June 2026  09:59:12 +0000 (0:00:00.840)       0:00:13.850 ********
2026-06-10 09:59:12.410394 | controller | skipping: [instance]
2026-06-10 09:59:12.410446 | controller |
2026-06-10 09:59:12.410458 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2026-06-10 09:59:12.410469 | controller | Wednesday 10 June 2026  09:59:12 +0000 (0:00:00.031)       0:00:13.882 ********
2026-06-10 09:59:13.197345 | controller | changed: [instance]
2026-06-10 09:59:13.197414 | controller |
2026-06-10 09:59:13.197425 | controller | TASK [repo_setup : Get component repo] *****************************************
2026-06-10 09:59:13.197435 | controller | Wednesday 10 June 2026  09:59:13 +0000 (0:00:00.785)       0:00:14.668 ********
2026-06-10 09:59:13.265452 | controller | skipping: [instance]
2026-06-10 09:59:13.265527 | controller |
2026-06-10 09:59:13.265577 | controller | TASK [repo_setup : Rename component repo] **************************************
2026-06-10 09:59:13.265771 | controller | Wednesday 10 June 2026  09:59:13 +0000 (0:00:00.069)       0:00:14.737 ********
2026-06-10 09:59:13.339372 | controller | skipping: [instance]
2026-06-10 09:59:13.339420 | controller |
2026-06-10 09:59:13.340019 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2026-06-10 09:59:13.340067 | controller | Wednesday 10 June 2026  09:59:13 +0000 (0:00:00.073)       0:00:14.810 ********
2026-06-10 09:59:13.404348 | controller | skipping: [instance]
2026-06-10 09:59:13.404756 | controller |
2026-06-10 09:59:13.405058 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2026-06-10 09:59:13.405336 | controller | Wednesday 10 June 2026  09:59:13 +0000 (0:00:00.066)       0:00:14.876 ********
2026-06-10 09:59:14.087940 | controller | changed: [instance]
2026-06-10 09:59:14.088517 | controller |
2026-06-10 09:59:14.088527 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2026-06-10 09:59:14.088533 | controller | Wednesday 10 June 2026  09:59:14 +0000 (0:00:00.682)       0:00:15.559 ********
2026-06-10 09:59:14.731281 | controller | changed: [instance]
2026-06-10 09:59:14.731385 | controller |
2026-06-10 09:59:14.731396 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2026-06-10 09:59:14.731405 | controller | Wednesday 10 June 2026  09:59:14 +0000 (0:00:00.643)       0:00:16.202 ********
2026-06-10 09:59:14.785168 | controller | skipping: [instance]
2026-06-10 09:59:14.785215 | controller |
2026-06-10 09:59:14.785227 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2026-06-10 09:59:14.785236 | controller | Wednesday 10 June 2026  09:59:14 +0000 (0:00:00.053)       0:00:16.256 ********
2026-06-10 09:59:14.851307 | controller | skipping: [instance]
2026-06-10 09:59:14.851403 | controller |
2026-06-10 09:59:14.851416 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2026-06-10 09:59:14.851425 | controller | Wednesday 10 June 2026  09:59:14 +0000 (0:00:00.066)       0:00:16.322 ********
2026-06-10 09:59:14.903605 | controller | skipping: [instance]
2026-06-10 09:59:14.903841 | controller |
2026-06-10 09:59:14.904088 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2026-06-10 09:59:14.904360 | controller | Wednesday 10 June 2026  09:59:14 +0000 (0:00:00.053)       0:00:16.375 ********
2026-06-10 09:59:14.986846 | controller | ok: [instance]
2026-06-10 09:59:14.987090 | controller |
2026-06-10 09:59:14.987424 | controller | TASK [repo_setup : Create download directory] **********************************
2026-06-10 09:59:14.987713 | controller | Wednesday 10 June 2026  09:59:14 +0000 (0:00:00.082)       0:00:16.458 ********
2026-06-10 09:59:15.044073 | controller | skipping: [instance]
2026-06-10 09:59:15.044392 | controller |
2026-06-10 09:59:15.044677 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2026-06-10 09:59:15.044946 | controller | Wednesday 10 June 2026  09:59:15 +0000 (0:00:00.057)       0:00:16.516 ********
2026-06-10 09:59:15.101884 | controller | skipping: [instance]
2026-06-10 09:59:15.102243 | controller |
2026-06-10 09:59:15.102549 | controller | TASK [Download the RPM] ********************************************************
2026-06-10 09:59:15.102839 | controller | Wednesday 10 June 2026  09:59:15 +0000 (0:00:00.057)       0:00:16.573 ********
2026-06-10 09:59:15.159621 | controller | skipping: [instance]
2026-06-10 09:59:15.159978 | controller |
2026-06-10 09:59:15.160356 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2026-06-10 09:59:15.160660 | controller | Wednesday 10 June 2026  09:59:15 +0000 (0:00:00.057)       0:00:16.631 ********
2026-06-10 09:59:15.221829 | controller | skipping: [instance]
2026-06-10 09:59:15.222255 | controller |
2026-06-10 09:59:15.222566 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2026-06-10 09:59:15.222876 | controller | Wednesday 10 June 2026  09:59:15 +0000 (0:00:00.062)       0:00:16.693 ********
2026-06-10 09:59:15.274330 | controller | skipping: [instance]
2026-06-10 09:59:15.274509 | controller |
2026-06-10 09:59:15.274769 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2026-06-10 09:59:15.275091 | controller | Wednesday 10 June 2026  09:59:15 +0000 (0:00:00.052)       0:00:16.746 ********
2026-06-10 09:59:15.329913 | controller | skipping: [instance]
2026-06-10 09:59:15.330224 | controller |
2026-06-10 09:59:15.330488 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2026-06-10 09:59:15.330734 | controller | Wednesday 10 June 2026  09:59:15 +0000 (0:00:00.055)       0:00:16.801 ********
2026-06-10 09:59:15.389832 | controller | skipping: [instance]
2026-06-10 09:59:15.390075 | controller |
2026-06-10 09:59:15.390364 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2026-06-10 09:59:15.390643 | controller | Wednesday 10 June 2026  09:59:15 +0000 (0:00:00.059)       0:00:16.861 ********
2026-06-10 09:59:15.665323 | controller | ok: [instance]
2026-06-10 09:59:15.665468 | controller |
2026-06-10 09:59:15.665478 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2026-06-10 09:59:15.665486 | controller | Wednesday 10 June 2026  09:59:15 +0000 (0:00:00.274)       0:00:17.136 ********
2026-06-10 09:59:15.972917 | controller | changed: [instance]
2026-06-10 09:59:15.972959 | controller |
2026-06-10 09:59:15.972970 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2026-06-10 09:59:15.972980 | controller | Wednesday 10 June 2026  09:59:15 +0000 (0:00:00.307)       0:00:17.444 ********
2026-06-10 09:59:16.334421 | controller | changed: [instance]
2026-06-10 09:59:16.334527 | controller |
2026-06-10 09:59:16.334537 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2026-06-10 09:59:16.334543 | controller | Wednesday 10 June 2026  09:59:16 +0000 (0:00:00.361)       0:00:17.805 ********
2026-06-10 09:59:16.381410 | controller | skipping: [instance]
2026-06-10 09:59:16.382353 | controller |
2026-06-10 09:59:16.382466 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2026-06-10 09:59:16.382484 | controller | Wednesday 10 June 2026  09:59:16 +0000 (0:00:00.046)       0:00:17.852 ********
2026-06-10 09:59:16.424345 | controller | skipping: [instance]
2026-06-10 09:59:16.424387 | controller |
2026-06-10 09:59:16.424399 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2026-06-10 09:59:16.424670 | controller | Wednesday 10 June 2026  09:59:16 +0000 (0:00:00.042)       0:00:17.895 ********
2026-06-10 09:59:16.467325 | controller | skipping: [instance]
2026-06-10 09:59:16.467577 | controller |
2026-06-10 09:59:16.467756 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2026-06-10 09:59:16.467956 | controller | Wednesday 10 June 2026  09:59:16 +0000 (0:00:00.043)       0:00:17.939 ********
2026-06-10 09:59:16.512899 | controller | skipping: [instance]
2026-06-10 09:59:16.512981 | controller |
2026-06-10 09:59:16.513199 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2026-06-10 09:59:16.513378 | controller | Wednesday 10 June 2026  09:59:16 +0000 (0:00:00.045)       0:00:17.984 ********
2026-06-10 09:59:16.564938 | controller | skipping: [instance]
2026-06-10 09:59:16.565238 | controller |
2026-06-10 09:59:16.565511 | controller | TASK [repo_setup : Lower the priority of component repos to allow installation from gating repo] ***
2026-06-10 09:59:16.565762 | controller | Wednesday 10 June 2026  09:59:16 +0000 (0:00:00.051)       0:00:18.036 ********
2026-06-10 09:59:16.614932 | controller | skipping: [instance]
2026-06-10 09:59:16.615376 | controller |
2026-06-10 09:59:16.615762 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2026-06-10 09:59:16.616145 | controller | Wednesday 10 June 2026  09:59:16 +0000 (0:00:00.049)       0:00:18.086 ********
2026-06-10 09:59:16.652095 | controller | skipping: [instance]
2026-06-10 09:59:16.652379 | controller |
2026-06-10 09:59:16.652641 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2026-06-10 09:59:16.652888 | controller | Wednesday 10 June 2026  09:59:16 +0000 (0:00:00.037)       0:00:18.123 ********
2026-06-10 09:59:16.713003 | controller | skipping: [instance]
2026-06-10 09:59:16.713406 | controller |
2026-06-10 09:59:16.713660 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2026-06-10 09:59:16.713906 | controller | Wednesday 10 June 2026  09:59:16 +0000 (0:00:00.060)       0:00:18.184 ********
2026-06-10 09:59:16.746873 | controller | skipping: [instance]
2026-06-10 09:59:16.747092 | controller |
2026-06-10 09:59:16.747365 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2026-06-10 09:59:16.747608 | controller | Wednesday 10 June 2026  09:59:16 +0000 (0:00:00.034)       0:00:18.218 ********
2026-06-10 09:59:16.787975 | controller | skipping: [instance]
2026-06-10 09:59:16.788314 | controller |
2026-06-10 09:59:16.788640 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2026-06-10 09:59:16.788959 | controller | Wednesday 10 June 2026  09:59:16 +0000 (0:00:00.041)       0:00:18.260 ********
2026-06-10 09:59:16.852816 | controller | skipping: [instance]
2026-06-10 09:59:16.853146 | controller |
2026-06-10 09:59:16.853469 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2026-06-10 09:59:16.853822 | controller | Wednesday 10 June 2026  09:59:16 +0000 (0:00:00.064)       0:00:18.324 ********
2026-06-10 09:59:16.913864 | controller | skipping: [instance]
2026-06-10 09:59:16.914166 | controller |
2026-06-10 09:59:16.914498 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2026-06-10 09:59:16.914924 | controller | Wednesday 10 June 2026  09:59:16 +0000 (0:00:00.061)       0:00:18.385 ********
2026-06-10 09:59:16.964691 | controller | skipping: [instance]
2026-06-10 09:59:16.964950 | controller |
2026-06-10 09:59:16.965290 | controller | TASK [test_deps : Install additional packages] *********************************
2026-06-10 09:59:16.965625 | controller | Wednesday 10 June 2026  09:59:16 +0000 (0:00:00.050)       0:00:18.436 ********
2026-06-10 09:59:17.022436 | controller | skipping: [instance]
2026-06-10 09:59:17.022572 | controller |
2026-06-10 09:59:17.022580 | controller | TASK [test_deps : Install selinux python libs] *********************************
2026-06-10 09:59:17.022726 | controller | Wednesday 10 June 2026  09:59:17 +0000 (0:00:00.058)       0:00:18.495 ********
2026-06-10 10:00:02.606794 | controller | ok: [instance]
2026-06-10 10:00:02.606823 | controller |
2026-06-10 10:00:02.606828 | controller | TASK [test_deps : Install python yaml libs] ************************************
2026-06-10 10:00:02.606832 | controller | Wednesday 10 June 2026  10:00:02 +0000 (0:00:45.584)       0:01:04.079 ********
2026-06-10 10:00:04.064946 | controller | ok: [instance]
2026-06-10 10:00:04.064985 | controller |
2026-06-10 10:00:04.064996 | controller | TASK [test_deps : Install extra packages] **************************************
2026-06-10 10:00:04.065005 | controller | Wednesday 10 June 2026  10:00:04 +0000 (0:00:01.457)       0:01:05.536 ********
2026-06-10 10:00:04.125356 | controller | skipping: [instance]
2026-06-10 10:00:04.125403 | controller |
2026-06-10 10:00:04.125414 | controller | TASK [Start CRC] ***************************************************************
2026-06-10 10:00:04.125443 | controller | Wednesday 10 June 2026  10:00:04 +0000 (0:00:00.060)       0:01:05.597 ********
2026-06-10 10:00:04.170071 | controller |
2026-06-10 10:00:04.170152 | controller | TASK [cifmw_helpers : Ensure CRC is started] ***********************************
2026-06-10 10:00:04.170167 | controller | Wednesday 10 June 2026  10:00:04 +0000 (0:00:00.044)       0:01:05.641 ********
2026-06-10 10:11:28.385020 | controller | changed: [instance]
2026-06-10 10:11:28.385588 | controller |
2026-06-10 10:11:28.385607 | controller | TASK [cifmw_helpers : Wait for cluster to stabilize after certificate renewal] ***
2026-06-10 10:11:28.385623 | controller | Wednesday 10 June 2026  10:11:28 +0000 (0:11:24.215)       0:12:29.857 ********
2026-06-10 10:11:29.831829 | controller | ok: [instance]
2026-06-10 10:11:29.832039 | controller |
2026-06-10 10:11:29.832244 | controller | TASK [Inject crc hostname/IP in hosts] *****************************************
2026-06-10 10:11:29.832393 | controller | Wednesday 10 June 2026  10:11:29 +0000 (0:00:01.446)       0:12:31.303 ********
2026-06-10 10:11:30.273951 | controller | changed: [instance]
2026-06-10 10:11:30.273999 | controller |
2026-06-10 10:11:30.274006 | controller | PLAY RECAP *********************************************************************
2026-06-10 10:11:30.274012 | controller | instance                   : ok=22   changed=11   unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2026-06-10 10:11:30.274017 | controller |
2026-06-10 10:11:30.274022 | controller | Wednesday 10 June 2026  10:11:30 +0000 (0:00:00.438)       0:12:31.742 ********
2026-06-10 10:11:30.274027 | controller | ===============================================================================
2026-06-10 10:11:30.274032 | controller | cifmw_helpers : Ensure CRC is started --------------------------------- 684.22s
2026-06-10 10:11:30.274036 | controller | test_deps : Install selinux python libs -------------------------------- 45.58s
2026-06-10 10:11:30.274042 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.62s
2026-06-10 10:11:30.274047 | controller | test_deps : Install python yaml libs ------------------------------------ 1.46s
2026-06-10 10:11:30.274052 | controller | cifmw_helpers : Wait for cluster to stabilize after certificate renewal --- 1.45s
2026-06-10 10:11:30.274057 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.17s
2026-06-10 10:11:30.274062 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.02s
2026-06-10 10:11:30.274067 | controller | Gathering Facts --------------------------------------------------------- 0.94s
2026-06-10 10:11:30.274071 | controller | repo_setup : Install repo-setup package --------------------------------- 0.84s
2026-06-10 10:11:30.274076 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.79s
2026-06-10 10:11:30.274081 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.75s
2026-06-10 10:11:30.274086 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.68s
2026-06-10 10:11:30.274090 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.64s
2026-06-10 10:11:30.274095 | controller | repo_setup : Ensure directories are present ----------------------------- 0.62s
2026-06-10 10:11:30.274100 | controller | Inject crc hostname/IP in hosts ----------------------------------------- 0.44s
2026-06-10 10:11:30.274104 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.37s
2026-06-10 10:11:30.274109 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.36s
2026-06-10 10:11:30.274139 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.32s
2026-06-10 10:11:30.274144 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.31s
2026-06-10 10:11:30.274154 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.27s
2026-06-10 10:11:30.369977 | controller | INFO     Running default > converge
2026-06-10 10:11:31.006637 | controller |
2026-06-10 10:11:31.006709 | controller | PLAY [Converge] ****************************************************************
2026-06-10 10:11:31.006975 | controller |
2026-06-10 10:11:31.007152 | controller | TASK [Gathering Facts] *********************************************************
2026-06-10 10:11:31.007402 | controller | Wednesday 10 June 2026  10:11:31 +0000 (0:00:00.017)       0:00:00.017 ********
2026-06-10 10:11:31.987265 | controller | ok: [instance]
2026-06-10 10:11:31.987378 | controller |
2026-06-10 10:11:31.987574 | controller | TASK [Check if new ssh keypair exists] *****************************************
2026-06-10 10:11:31.987797 | controller | Wednesday 10 June 2026  10:11:31 +0000 (0:00:00.981)       0:00:00.999 ********
2026-06-10 10:11:32.011030 | controller |
2026-06-10 10:11:32.011227 | controller | TASK [recognize_ssh_keypair : Check if id_ed25519 key exists] ******************
2026-06-10 10:11:32.011308 | controller | Wednesday 10 June 2026  10:11:32 +0000 (0:00:00.024)       0:00:01.023 ********
2026-06-10 10:11:32.391027 | controller | ok: [instance]
2026-06-10 10:11:32.391073 | controller |
2026-06-10 10:11:32.391084 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair exists] ******************
2026-06-10 10:11:32.391094 | controller | Wednesday 10 June 2026  10:11:32 +0000 (0:00:00.378)       0:00:01.402 ********
2026-06-10 10:11:32.423086 | controller | ok: [instance]
2026-06-10 10:11:32.423155 | controller |
2026-06-10 10:11:32.423168 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair does not exists] *********
2026-06-10 10:11:32.423178 | controller | Wednesday 10 June 2026  10:11:32 +0000 (0:00:00.032)       0:00:01.434 ********
2026-06-10 10:11:32.448802 | controller | skipping: [instance]
2026-06-10 10:11:32.448847 | controller |
2026-06-10 10:11:32.448857 | controller | TASK [Add the crc host dynamically] ********************************************
2026-06-10 10:11:32.448866 | controller | Wednesday 10 June 2026  10:11:32 +0000 (0:00:00.026)       0:00:01.460 ********
2026-06-10 10:11:32.476228 | controller | changed: [instance]
2026-06-10 10:11:32.476272 | controller |
2026-06-10 10:11:32.476283 | controller | TASK [Run ci_local_storage role] ***********************************************
2026-06-10 10:11:32.476297 | controller | Wednesday 10 June 2026  10:11:32 +0000 (0:00:00.027)       0:00:01.488 ********
2026-06-10 10:11:32.513497 | controller |
2026-06-10 10:11:32.513537 | controller | TASK [ci_local_storage : Create role needed directories] ***********************
2026-06-10 10:11:32.513547 | controller | Wednesday 10 June 2026  10:11:32 +0000 (0:00:00.037)       0:00:01.525 ********
2026-06-10 10:11:32.853182 | controller | changed: [instance]
2026-06-10 10:11:32.853225 | controller |
2026-06-10 10:11:32.853231 | controller | TASK [ci_local_storage : Create the cifmw_cls_namespace namespace"] ************
2026-06-10 10:11:32.853364 | controller | Wednesday 10 June 2026  10:11:32 +0000 (0:00:00.339)       0:00:01.865 ********
2026-06-10 10:11:34.419464 | controller | changed: [instance]
2026-06-10 10:11:34.419496 | controller |
2026-06-10 10:11:34.419501 | controller | TASK [ci_local_storage : Save storage manifests as artifacts] ******************
2026-06-10 10:11:34.419505 | controller | Wednesday 10 June 2026  10:11:34 +0000 (0:00:01.566)       0:00:03.431 ********
2026-06-10 10:11:35.029534 | controller | changed: [instance]
2026-06-10 10:11:35.029588 | controller |
2026-06-10 10:11:35.029593 | controller | TASK [ci_local_storage : Get k8s nodes] ****************************************
2026-06-10 10:11:35.029597 | controller | Wednesday 10 June 2026  10:11:35 +0000 (0:00:00.609)       0:00:04.041 ********
2026-06-10 10:11:35.886928 | controller | ok: [instance]
2026-06-10 10:11:35.886953 | controller |
2026-06-10 10:11:35.886958 | controller | TASK [ci_local_storage : Fetch hostnames for all hosts] ************************
2026-06-10 10:11:35.886964 | controller | Wednesday 10 June 2026  10:11:35 +0000 (0:00:00.857)       0:00:04.899 ********
2026-06-10 10:11:38.259275 | controller | changed: [instance] => (item=instance)
2026-06-10 10:11:38.259345 | controller | changed: [instance -> crc] => (item=crc)
2026-06-10 10:11:38.259537 | controller |
2026-06-10 10:11:38.260032 | controller | TASK [ci_local_storage : Set the hosts k8s ansible hosts] **********************
2026-06-10 10:11:38.260039 | controller | Wednesday 10 June 2026  10:11:38 +0000 (0:00:02.372)       0:00:07.271 ********
2026-06-10 10:11:38.323613 | controller | ok: [instance]
2026-06-10 10:11:38.323659 | controller |
2026-06-10 10:11:38.323674 | controller | TASK [ci_local_storage : Apply the storage class manifests] ********************
2026-06-10 10:11:38.323688 | controller | Wednesday 10 June 2026  10:11:38 +0000 (0:00:00.063)       0:00:07.334 ********
2026-06-10 10:11:38.999952 | controller | changed: [instance]
2026-06-10 10:11:38.999982 | controller |
2026-06-10 10:11:38.999987 | controller | TASK [ci_local_storage : Create directories on worker node] ********************
2026-06-10 10:11:38.999991 | controller | Wednesday 10 June 2026  10:11:38 +0000 (0:00:00.677)       0:00:08.012 ********
2026-06-10 10:11:39.029079 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_local_storage/tasks/worker_node_dirs.yml for instance => (item=crc)
2026-06-10 10:11:39.029154 | controller |
2026-06-10 10:11:39.029167 | controller | TASK [ci_local_storage : Perform action in the PV directory] *******************
2026-06-10 10:11:39.029187 | controller | Wednesday 10 June 2026  10:11:39 +0000 (0:00:00.029)       0:00:08.041 ********
2026-06-10 10:11:51.268311 | controller | changed: [instance -> crc] => (item=1)
2026-06-10 10:11:51.268348 | controller | changed: [instance -> crc] => (item=2)
2026-06-10 10:11:51.268354 | controller | changed: [instance -> crc] => (item=3)
2026-06-10 10:11:51.268360 | controller | changed: [instance -> crc] => (item=4)
2026-06-10 10:11:51.268445 | controller | changed: [instance -> crc] => (item=5)
2026-06-10 10:11:51.268536 | controller | changed: [instance -> crc] => (item=6)
2026-06-10 10:11:51.268608 | controller | changed: [instance -> crc] => (item=7)
2026-06-10 10:11:51.268687 | controller | changed: [instance -> crc] => (item=8)
2026-06-10 10:11:51.268765 | controller | changed: [instance -> crc] => (item=9)
2026-06-10 10:11:51.268848 | controller | changed: [instance -> crc] => (item=10)
2026-06-10 10:11:51.268927 | controller |
2026-06-10 10:11:51.269014 | controller | TASK [ci_local_storage : Manage PV directories on nodes unreachable via SSH] ***
2026-06-10 10:11:51.269133 | controller | Wednesday 10 June 2026  10:11:51 +0000 (0:00:12.238)       0:00:20.280 ********
2026-06-10 10:11:51.297257 | controller | skipping: [instance]
2026-06-10 10:11:51.297460 | controller |
2026-06-10 10:11:51.297765 | controller | TASK [ci_local_storage : Generate pv related storage manifest file] ************
2026-06-10 10:11:51.298035 | controller | Wednesday 10 June 2026  10:11:51 +0000 (0:00:00.029)       0:00:20.309 ********
2026-06-10 10:11:51.697597 | controller | changed: [instance]
2026-06-10 10:11:51.697733 | controller |
2026-06-10 10:11:51.697936 | controller | TASK [ci_local_storage : Apply pv related storage manifest file] ***************
2026-06-10 10:11:51.698154 | controller | Wednesday 10 June 2026  10:11:51 +0000 (0:00:00.399)       0:00:20.709 ********
2026-06-10 10:11:52.629767 | controller | changed: [instance]
2026-06-10 10:11:52.629849 | controller |
2026-06-10 10:11:52.629943 | controller | TASK [Not create directories on crc] *******************************************
2026-06-10 10:11:52.630132 | controller | Wednesday 10 June 2026  10:11:52 +0000 (0:00:00.932)       0:00:21.642 ********
2026-06-10 10:12:04.289837 | controller | ok: [instance -> crc] => (item=1)
2026-06-10 10:12:04.289876 | controller | ok: [instance -> crc] => (item=2)
2026-06-10 10:12:04.289892 | controller | ok: [instance -> crc] => (item=3)
2026-06-10 10:12:04.290233 | controller | ok: [instance -> crc] => (item=4)
2026-06-10 10:12:04.290246 | controller | ok: [instance -> crc] => (item=5)
2026-06-10 10:12:04.290251 | controller | ok: [instance -> crc] => (item=6)
2026-06-10 10:12:04.290274 | controller | ok: [instance -> crc] => (item=7)
2026-06-10 10:12:04.290847 | controller | ok: [instance -> crc] => (item=8)
2026-06-10 10:12:04.290871 | controller | ok: [instance -> crc] => (item=9)
2026-06-10 10:12:04.290876 | controller | ok: [instance -> crc] => (item=10)
2026-06-10 10:12:04.290881 | controller |
2026-06-10 10:12:04.290885 | controller | TASK [Fail if we have a change] ************************************************
2026-06-10 10:12:04.290889 | controller | Wednesday 10 June 2026  10:12:04 +0000 (0:00:11.660)       0:00:33.302 ********
2026-06-10 10:12:04.328261 | controller | skipping: [instance]
2026-06-10 10:12:04.328292 | controller |
2026-06-10 10:12:04.328297 | controller | TASK [Get all pvs] *************************************************************
2026-06-10 10:12:04.328302 | controller | Wednesday 10 June 2026  10:12:04 +0000 (0:00:00.037)       0:00:33.340 ********
2026-06-10 10:12:05.059344 | controller | ok: [instance]
2026-06-10 10:12:05.059395 | controller |
2026-06-10 10:12:05.059424 | controller | TASK [Assert that we created the wanted number of pvs] *************************
2026-06-10 10:12:05.059464 | controller | Wednesday 10 June 2026  10:12:05 +0000 (0:00:00.731)       0:00:34.071 ********
2026-06-10 10:12:05.117264 | controller | ok: [instance] => changed=false
2026-06-10 10:12:05.117329 | controller |   msg: All assertions passed
2026-06-10 10:12:05.117334 | controller |
2026-06-10 10:12:05.117338 | controller | TASK [Gather names from pvs] ***************************************************
2026-06-10 10:12:05.117348 | controller | Wednesday 10 June 2026  10:12:05 +0000 (0:00:00.057)       0:00:34.128 ********
2026-06-10 10:12:05.162244 | controller | ok: [instance]
2026-06-10 10:12:05.162320 | controller |
2026-06-10 10:12:05.162400 | controller | TASK [Gather computed PV names] ************************************************
2026-06-10 10:12:05.162522 | controller | Wednesday 10 June 2026  10:12:05 +0000 (0:00:00.045)       0:00:34.174 ********
2026-06-10 10:12:05.263889 | controller | ok: [instance] => (item=1)
2026-06-10 10:12:05.264083 | controller | ok: [instance] => (item=2)
2026-06-10 10:12:05.264462 | controller | ok: [instance] => (item=3)
2026-06-10 10:12:05.264512 | controller | ok: [instance] => (item=4)
2026-06-10 10:12:05.264909 | controller | ok: [instance] => (item=5)
2026-06-10 10:12:05.264914 | controller | ok: [instance] => (item=6)
2026-06-10 10:12:05.266136 | controller | ok: [instance] => (item=7)
2026-06-10 10:12:05.266147 | controller | ok: [instance] => (item=8)
2026-06-10 10:12:05.266153 | controller | ok: [instance] => (item=9)
2026-06-10 10:12:05.266158 | controller | ok: [instance] => (item=10)
2026-06-10 10:12:05.266163 | controller |
2026-06-10 10:12:05.266168 | controller | TASK [Assert that the pvs names are correct] ***********************************
2026-06-10 10:12:05.266432 | controller | Wednesday 10 June 2026  10:12:05 +0000 (0:00:00.101)       0:00:34.276 ********
2026-06-10 10:12:05.372079 | controller | ok: [instance] => (item=['molecule-storage01-crc', 'molecule-storage01-crc']) => changed=false
2026-06-10 10:12:05.372156 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.372171 | controller |   item:
2026-06-10 10:12:05.372180 | controller |   - molecule-storage01-crc
2026-06-10 10:12:05.372194 | controller |   - molecule-storage01-crc
2026-06-10 10:12:05.372898 | controller |   msg: All assertions passed
2026-06-10 10:12:05.372910 | controller | ok: [instance] => (item=['molecule-storage02-crc', 'molecule-storage02-crc']) => changed=false
2026-06-10 10:12:05.372919 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.372927 | controller |   item:
2026-06-10 10:12:05.373183 | controller |   - molecule-storage02-crc
2026-06-10 10:12:05.373706 | controller |   - molecule-storage02-crc
2026-06-10 10:12:05.373737 | controller |   msg: All assertions passed
2026-06-10 10:12:05.373746 | controller | ok: [instance] => (item=['molecule-storage03-crc', 'molecule-storage03-crc']) => changed=false
2026-06-10 10:12:05.374008 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.374313 | controller |   item:
2026-06-10 10:12:05.374331 | controller |   - molecule-storage03-crc
2026-06-10 10:12:05.374582 | controller |   - molecule-storage03-crc
2026-06-10 10:12:05.374799 | controller |   msg: All assertions passed
2026-06-10 10:12:05.375070 | controller | ok: [instance] => (item=['molecule-storage04-crc', 'molecule-storage04-crc']) => changed=false
2026-06-10 10:12:05.375084 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.375568 | controller |   item:
2026-06-10 10:12:05.375585 | controller |   - molecule-storage04-crc
2026-06-10 10:12:05.375721 | controller |   - molecule-storage04-crc
2026-06-10 10:12:05.376006 | controller |   msg: All assertions passed
2026-06-10 10:12:05.376263 | controller | ok: [instance] => (item=['molecule-storage05-crc', 'molecule-storage05-crc']) => changed=false
2026-06-10 10:12:05.376514 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.376749 | controller |   item:
2026-06-10 10:12:05.376961 | controller |   - molecule-storage05-crc
2026-06-10 10:12:05.377220 | controller |   - molecule-storage05-crc
2026-06-10 10:12:05.377457 | controller |   msg: All assertions passed
2026-06-10 10:12:05.377694 | controller | ok: [instance] => (item=['molecule-storage06-crc', 'molecule-storage06-crc']) => changed=false
2026-06-10 10:12:05.377924 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.378181 | controller |   item:
2026-06-10 10:12:05.378452 | controller |   - molecule-storage06-crc
2026-06-10 10:12:05.378760 | controller |   - molecule-storage06-crc
2026-06-10 10:12:05.378990 | controller |   msg: All assertions passed
2026-06-10 10:12:05.379254 | controller | ok: [instance] => (item=['molecule-storage07-crc', 'molecule-storage07-crc']) => changed=false
2026-06-10 10:12:05.379526 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.379738 | controller |   item:
2026-06-10 10:12:05.379963 | controller |   - molecule-storage07-crc
2026-06-10 10:12:05.380248 | controller |   - molecule-storage07-crc
2026-06-10 10:12:05.380519 | controller |   msg: All assertions passed
2026-06-10 10:12:05.380773 | controller | ok: [instance] => (item=['molecule-storage08-crc', 'molecule-storage08-crc']) => changed=false
2026-06-10 10:12:05.381007 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.381271 | controller |   item:
2026-06-10 10:12:05.381519 | controller |   - molecule-storage08-crc
2026-06-10 10:12:05.381759 | controller |   - molecule-storage08-crc
2026-06-10 10:12:05.382056 | controller |   msg: All assertions passed
2026-06-10 10:12:05.382372 | controller | ok: [instance] => (item=['molecule-storage09-crc', 'molecule-storage09-crc']) => changed=false
2026-06-10 10:12:05.382637 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.382856 | controller |   item:
2026-06-10 10:12:05.383135 | controller |   - molecule-storage09-crc
2026-06-10 10:12:05.383465 | controller |   - molecule-storage09-crc
2026-06-10 10:12:05.383676 | controller |   msg: All assertions passed
2026-06-10 10:12:05.383940 | controller | ok: [instance] => (item=['molecule-storage10-crc', 'molecule-storage10-crc']) => changed=false
2026-06-10 10:12:05.384255 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.384482 | controller |   item:
2026-06-10 10:12:05.384773 | controller |   - molecule-storage10-crc
2026-06-10 10:12:05.385025 | controller |   - molecule-storage10-crc
2026-06-10 10:12:05.385306 | controller |   msg: All assertions passed
2026-06-10 10:12:05.385560 | controller |
2026-06-10 10:12:05.385801 | controller | TASK [Gather mountpoints from pvs] *********************************************
2026-06-10 10:12:05.386059 | controller | Wednesday 10 June 2026  10:12:05 +0000 (0:00:00.107)       0:00:34.383 ********
2026-06-10 10:12:05.412706 | controller | ok: [instance]
2026-06-10 10:12:05.412969 | controller |
2026-06-10 10:12:05.413262 | controller | TASK [Gather computed mountpoints] *********************************************
2026-06-10 10:12:05.413557 | controller | Wednesday 10 June 2026  10:12:05 +0000 (0:00:00.041)       0:00:34.425 ********
2026-06-10 10:12:05.532238 | controller | ok: [instance] => (item=1)
2026-06-10 10:12:05.532296 | controller | ok: [instance] => (item=2)
2026-06-10 10:12:05.532705 | controller | ok: [instance] => (item=3)
2026-06-10 10:12:05.532988 | controller | ok: [instance] => (item=4)
2026-06-10 10:12:05.533307 | controller | ok: [instance] => (item=5)
2026-06-10 10:12:05.533565 | controller | ok: [instance] => (item=6)
2026-06-10 10:12:05.533875 | controller | ok: [instance] => (item=7)
2026-06-10 10:12:05.534150 | controller | ok: [instance] => (item=8)
2026-06-10 10:12:05.534403 | controller | ok: [instance] => (item=9)
2026-06-10 10:12:05.534734 | controller | ok: [instance] => (item=10)
2026-06-10 10:12:05.534954 | controller |
2026-06-10 10:12:05.535250 | controller | TASK [Assert that the mountpoints in the pv are correct] ***********************
2026-06-10 10:12:05.535494 | controller | Wednesday 10 June 2026  10:12:05 +0000 (0:00:00.119)       0:00:34.544 ********
2026-06-10 10:12:05.640288 | controller | ok: [instance] => (item=['/mnt/openstack/pv01', '/mnt/openstack/pv01']) => changed=false
2026-06-10 10:12:05.640345 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.640568 | controller |   item:
2026-06-10 10:12:05.640755 | controller |   - /mnt/openstack/pv01
2026-06-10 10:12:05.640956 | controller |   - /mnt/openstack/pv01
2026-06-10 10:12:05.641167 | controller |   msg: All assertions passed
2026-06-10 10:12:05.641403 | controller | ok: [instance] => (item=['/mnt/openstack/pv02', '/mnt/openstack/pv02']) => changed=false
2026-06-10 10:12:05.641587 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.641823 | controller |   item:
2026-06-10 10:12:05.641985 | controller |   - /mnt/openstack/pv02
2026-06-10 10:12:05.642198 | controller |   - /mnt/openstack/pv02
2026-06-10 10:12:05.642391 | controller |   msg: All assertions passed
2026-06-10 10:12:05.642616 | controller | ok: [instance] => (item=['/mnt/openstack/pv03', '/mnt/openstack/pv03']) => changed=false
2026-06-10 10:12:05.642804 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.642997 | controller |   item:
2026-06-10 10:12:05.643208 | controller |   - /mnt/openstack/pv03
2026-06-10 10:12:05.643395 | controller |   - /mnt/openstack/pv03
2026-06-10 10:12:05.643603 | controller |   msg: All assertions passed
2026-06-10 10:12:05.643783 | controller | ok: [instance] => (item=['/mnt/openstack/pv04', '/mnt/openstack/pv04']) => changed=false
2026-06-10 10:12:05.643977 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.644184 | controller |   item:
2026-06-10 10:12:05.644378 | controller |   - /mnt/openstack/pv04
2026-06-10 10:12:05.644567 | controller |   - /mnt/openstack/pv04
2026-06-10 10:12:05.644817 | controller |   msg: All assertions passed
2026-06-10 10:12:05.644994 | controller | ok: [instance] => (item=['/mnt/openstack/pv05', '/mnt/openstack/pv05']) => changed=false
2026-06-10 10:12:05.645183 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.645383 | controller |   item:
2026-06-10 10:12:05.645594 | controller |   - /mnt/openstack/pv05
2026-06-10 10:12:05.645783 | controller |   - /mnt/openstack/pv05
2026-06-10 10:12:05.645999 | controller |   msg: All assertions passed
2026-06-10 10:12:05.646246 | controller | ok: [instance] => (item=['/mnt/openstack/pv06', '/mnt/openstack/pv06']) => changed=false
2026-06-10 10:12:05.646419 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.646632 | controller |   item:
2026-06-10 10:12:05.646798 | controller |   - /mnt/openstack/pv06
2026-06-10 10:12:05.647021 | controller |   - /mnt/openstack/pv06
2026-06-10 10:12:05.647209 | controller |   msg: All assertions passed
2026-06-10 10:12:05.647386 | controller | ok: [instance] => (item=['/mnt/openstack/pv07', '/mnt/openstack/pv07']) => changed=false
2026-06-10 10:12:05.647594 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.647772 | controller |   item:
2026-06-10 10:12:05.647948 | controller |   - /mnt/openstack/pv07
2026-06-10 10:12:05.648194 | controller |   - /mnt/openstack/pv07
2026-06-10 10:12:05.648392 | controller |   msg: All assertions passed
2026-06-10 10:12:05.648564 | controller | ok: [instance] => (item=['/mnt/openstack/pv08', '/mnt/openstack/pv08']) => changed=false
2026-06-10 10:12:05.648745 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.648974 | controller |   item:
2026-06-10 10:12:05.649181 | controller |   - /mnt/openstack/pv08
2026-06-10 10:12:05.649408 | controller |   - /mnt/openstack/pv08
2026-06-10 10:12:05.649556 | controller |   msg: All assertions passed
2026-06-10 10:12:05.649736 | controller | ok: [instance] => (item=['/mnt/openstack/pv09', '/mnt/openstack/pv09']) => changed=false
2026-06-10 10:12:05.649917 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.650098 | controller |   item:
2026-06-10 10:12:05.650306 | controller |   - /mnt/openstack/pv09
2026-06-10 10:12:05.650521 | controller |   - /mnt/openstack/pv09
2026-06-10 10:12:05.650788 | controller |   msg: All assertions passed
2026-06-10 10:12:05.650950 | controller | ok: [instance] => (item=['/mnt/openstack/pv10', '/mnt/openstack/pv10']) => changed=false
2026-06-10 10:12:05.651193 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.651375 | controller |   item:
2026-06-10 10:12:05.651551 | controller |   - /mnt/openstack/pv10
2026-06-10 10:12:05.651735 | controller |   - /mnt/openstack/pv10
2026-06-10 10:12:05.651913 | controller |   msg: All assertions passed
2026-06-10 10:12:05.652084 | controller |
2026-06-10 10:12:05.652319 | controller | TASK [Assert that the storage class is correct] ********************************
2026-06-10 10:12:05.652495 | controller | Wednesday 10 June 2026  10:12:05 +0000 (0:00:00.107)       0:00:34.651 ********
2026-06-10 10:12:05.721933 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2026-06-10 10:12:05.722114 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.722396 | controller |   item: molecule-storage
2026-06-10 10:12:05.722618 | controller |   msg: All assertions passed
2026-06-10 10:12:05.722877 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2026-06-10 10:12:05.723105 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.723325 | controller |   item: molecule-storage
2026-06-10 10:12:05.723486 | controller |   msg: All assertions passed
2026-06-10 10:12:05.723701 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2026-06-10 10:12:05.723945 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.724170 | controller |   item: molecule-storage
2026-06-10 10:12:05.724367 | controller |   msg: All assertions passed
2026-06-10 10:12:05.724589 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2026-06-10 10:12:05.724814 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.725001 | controller |   item: molecule-storage
2026-06-10 10:12:05.725246 | controller |   msg: All assertions passed
2026-06-10 10:12:05.725460 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2026-06-10 10:12:05.725677 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.725913 | controller |   item: molecule-storage
2026-06-10 10:12:05.726145 | controller |   msg: All assertions passed
2026-06-10 10:12:05.726358 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2026-06-10 10:12:05.726603 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.726815 | controller |   item: molecule-storage
2026-06-10 10:12:05.727028 | controller |   msg: All assertions passed
2026-06-10 10:12:05.727281 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2026-06-10 10:12:05.727502 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.727689 | controller |   item: molecule-storage
2026-06-10 10:12:05.727875 | controller |   msg: All assertions passed
2026-06-10 10:12:05.728113 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2026-06-10 10:12:05.728375 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.728576 | controller |   item: molecule-storage
2026-06-10 10:12:05.728790 | controller |   msg: All assertions passed
2026-06-10 10:12:05.728982 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2026-06-10 10:12:05.729227 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.729446 | controller |   item: molecule-storage
2026-06-10 10:12:05.729648 | controller |   msg: All assertions passed
2026-06-10 10:12:05.729836 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2026-06-10 10:12:05.730025 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.730286 | controller |   item: molecule-storage
2026-06-10 10:12:05.730517 | controller |   msg: All assertions passed
2026-06-10 10:12:05.730772 | controller |
2026-06-10 10:12:05.730978 | controller | TASK [Assert that the storage capacity is correct] *****************************
2026-06-10 10:12:05.731182 | controller | Wednesday 10 June 2026  10:12:05 +0000 (0:00:00.081)       0:00:34.733 ********
2026-06-10 10:12:05.796060 | controller | ok: [instance] => (item=100Mi) => changed=false
2026-06-10 10:12:05.796261 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.796457 | controller |   item: 100Mi
2026-06-10 10:12:05.796655 | controller |   msg: All assertions passed
2026-06-10 10:12:05.796857 | controller | ok: [instance] => (item=100Mi) => changed=false
2026-06-10 10:12:05.797075 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.797326 | controller |   item: 100Mi
2026-06-10 10:12:05.797501 | controller |   msg: All assertions passed
2026-06-10 10:12:05.797701 | controller | ok: [instance] => (item=100Mi) => changed=false
2026-06-10 10:12:05.797901 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.798098 | controller |   item: 100Mi
2026-06-10 10:12:05.798336 | controller |   msg: All assertions passed
2026-06-10 10:12:05.798538 | controller | ok: [instance] => (item=100Mi) => changed=false
2026-06-10 10:12:05.798769 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.798979 | controller |   item: 100Mi
2026-06-10 10:12:05.799180 | controller |   msg: All assertions passed
2026-06-10 10:12:05.799416 | controller | ok: [instance] => (item=100Mi) => changed=false
2026-06-10 10:12:05.799598 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.799782 | controller |   item: 100Mi
2026-06-10 10:12:05.799985 | controller |   msg: All assertions passed
2026-06-10 10:12:05.800176 | controller | ok: [instance] => (item=100Mi) => changed=false
2026-06-10 10:12:05.800422 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.800607 | controller |   item: 100Mi
2026-06-10 10:12:05.800760 | controller |   msg: All assertions passed
2026-06-10 10:12:05.800962 | controller | ok: [instance] => (item=100Mi) => changed=false
2026-06-10 10:12:05.801163 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.801369 | controller |   item: 100Mi
2026-06-10 10:12:05.801538 | controller |   msg: All assertions passed
2026-06-10 10:12:05.801756 | controller | ok: [instance] => (item=100Mi) => changed=false
2026-06-10 10:12:05.801926 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.802100 | controller |   item: 100Mi
2026-06-10 10:12:05.802334 | controller |   msg: All assertions passed
2026-06-10 10:12:05.802620 | controller | ok: [instance] => (item=100Mi) => changed=false
2026-06-10 10:12:05.802975 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.802997 | controller |   item: 100Mi
2026-06-10 10:12:05.803447 | controller |   msg: All assertions passed
2026-06-10 10:12:05.803478 | controller | ok: [instance] => (item=100Mi) => changed=false
2026-06-10 10:12:05.803585 | controller |   ansible_loop_var: item
2026-06-10 10:12:05.803791 | controller |   item: 100Mi
2026-06-10 10:12:05.803892 | controller |   msg: All assertions passed
2026-06-10 10:12:05.804519 | controller |
2026-06-10 10:12:05.804556 | controller | TASK [Delete the created pvs] **************************************************
2026-06-10 10:12:05.804566 | controller | Wednesday 10 June 2026  10:12:05 +0000 (0:00:00.074)       0:00:34.807 ********
2026-06-10 10:12:05.844156 | controller |
2026-06-10 10:12:05.844208 | controller | TASK [ci_local_storage : Get all pvs] ******************************************
2026-06-10 10:12:05.844223 | controller | Wednesday 10 June 2026  10:12:05 +0000 (0:00:00.047)       0:00:34.855 ********
2026-06-10 10:12:06.591591 | controller | ok: [instance]
2026-06-10 10:12:06.591664 | controller |
2026-06-10 10:12:06.591695 | controller | TASK [ci_local_storage : Export PVs as facts] **********************************
2026-06-10 10:12:06.591705 | controller | Wednesday 10 June 2026  10:12:06 +0000 (0:00:00.747)       0:00:35.602 ********
2026-06-10 10:12:06.663418 | controller | ok: [instance]
2026-06-10 10:12:06.663474 | controller |
2026-06-10 10:12:06.663483 | controller | TASK [ci_local_storage : Delete all pvs] ***************************************
2026-06-10 10:12:06.663491 | controller | Wednesday 10 June 2026  10:12:06 +0000 (0:00:00.072)       0:00:35.675 ********
2026-06-10 10:12:13.214318 | controller | changed: [instance] => (item=molecule-storage01-crc)
2026-06-10 10:12:13.214611 | controller | changed: [instance] => (item=molecule-storage02-crc)
2026-06-10 10:12:13.214622 | controller | changed: [instance] => (item=molecule-storage03-crc)
2026-06-10 10:12:13.214649 | controller | changed: [instance] => (item=molecule-storage04-crc)
2026-06-10 10:12:13.214657 | controller | changed: [instance] => (item=molecule-storage05-crc)
2026-06-10 10:12:13.214664 | controller | changed: [instance] => (item=molecule-storage06-crc)
2026-06-10 10:12:13.214672 | controller | changed: [instance] => (item=molecule-storage07-crc)
2026-06-10 10:12:13.214689 | controller | changed: [instance] => (item=molecule-storage08-crc)
2026-06-10 10:12:13.214697 | controller | changed: [instance] => (item=molecule-storage09-crc)
2026-06-10 10:12:13.214705 | controller | changed: [instance] => (item=molecule-storage10-crc)
2026-06-10 10:12:13.214742 | controller |
2026-06-10 10:12:13.214859 | controller | TASK [ci_local_storage : Get k8s nodes] ****************************************
2026-06-10 10:12:13.214948 | controller | Wednesday 10 June 2026  10:12:13 +0000 (0:00:06.550)       0:00:42.225 ********
2026-06-10 10:12:13.875502 | controller | ok: [instance]
2026-06-10 10:12:13.875578 | controller |
2026-06-10 10:12:13.877051 | controller | TASK [ci_local_storage : Fetch hostnames for all hosts] ************************
2026-06-10 10:12:13.877086 | controller | Wednesday 10 June 2026  10:12:13 +0000 (0:00:00.662)       0:00:42.887 ********
2026-06-10 10:12:15.192677 | controller | changed: [instance] => (item=instance)
2026-06-10 10:12:15.192769 | controller | changed: [instance -> crc] => (item=crc)
2026-06-10 10:12:15.192783 | controller |
2026-06-10 10:12:15.192911 | controller | TASK [ci_local_storage : Set the hosts k8s ansible hosts] **********************
2026-06-10 10:12:15.192991 | controller | Wednesday 10 June 2026  10:12:15 +0000 (0:00:01.317)       0:00:44.205 ********
2026-06-10 10:12:15.263334 | controller | ok: [instance]
2026-06-10 10:12:15.263401 | controller |
2026-06-10 10:12:15.263465 | controller | TASK [ci_local_storage : Delete directories on worker node] ********************
2026-06-10 10:12:15.263572 | controller | Wednesday 10 June 2026  10:12:15 +0000 (0:00:00.070)       0:00:44.275 ********
2026-06-10 10:12:15.297785 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_local_storage/tasks/worker_node_dirs.yml for instance => (item=crc)
2026-06-10 10:12:15.297831 | controller |
2026-06-10 10:12:15.297933 | controller | TASK [ci_local_storage : Perform action in the PV directory] *******************
2026-06-10 10:12:15.298007 | controller | Wednesday 10 June 2026  10:12:15 +0000 (0:00:00.034)       0:00:44.310 ********
2026-06-10 10:12:27.248172 | controller | changed: [instance -> crc] => (item=1)
2026-06-10 10:12:27.248251 | controller | changed: [instance -> crc] => (item=2)
2026-06-10 10:12:27.248325 | controller | changed: [instance -> crc] => (item=3)
2026-06-10 10:12:27.248440 | controller | changed: [instance -> crc] => (item=4)
2026-06-10 10:12:27.248535 | controller | changed: [instance -> crc] => (item=5)
2026-06-10 10:12:27.248624 | controller | changed: [instance -> crc] => (item=6)
2026-06-10 10:12:27.248722 | controller | changed: [instance -> crc] => (item=7)
2026-06-10 10:12:27.248805 | controller | changed: [instance -> crc] => (item=8)
2026-06-10 10:12:27.248888 | controller | changed: [instance -> crc] => (item=9)
2026-06-10 10:12:27.249001 | controller | changed: [instance -> crc] => (item=10)
2026-06-10 10:12:27.249061 | controller |
2026-06-10 10:12:27.249172 | controller | TASK [ci_local_storage : Delete PV directories on nodes unreachable via SSH] ***
2026-06-10 10:12:27.249287 | controller | Wednesday 10 June 2026  10:12:27 +0000 (0:00:11.950)       0:00:56.260 ********
2026-06-10 10:12:27.289886 | controller | skipping: [instance]
2026-06-10 10:12:27.289979 | controller |
2026-06-10 10:12:27.290167 | controller | TASK [ci_local_storage : Remove the cifmw_cls_namespace namespace] *************
2026-06-10 10:12:27.290361 | controller | Wednesday 10 June 2026  10:12:27 +0000 (0:00:00.041)       0:00:56.302 ********
2026-06-10 10:12:38.044226 | controller | changed: [instance]
2026-06-10 10:12:38.044283 | controller |
2026-06-10 10:12:38.044307 | controller | TASK [Get all namespaces] ******************************************************
2026-06-10 10:12:38.044513 | controller | Wednesday 10 June 2026  10:12:38 +0000 (0:00:10.753)       0:01:07.055 ********
2026-06-10 10:12:38.897996 | controller | ok: [instance]
2026-06-10 10:12:38.898025 | controller |
2026-06-10 10:12:38.898030 | controller | TASK [Assert that the cifmw_cls_namespace ns is deleted] ***********************
2026-06-10 10:12:38.898034 | controller | Wednesday 10 June 2026  10:12:38 +0000 (0:00:00.854)       0:01:07.910 ********
2026-06-10 10:12:38.968322 | controller | ok: [instance] => changed=false
2026-06-10 10:12:38.968367 | controller |   msg: All assertions passed
2026-06-10 10:12:38.968375 | controller |
2026-06-10 10:12:38.968382 | controller | TASK [Get k8s node names for fallback test] ************************************
2026-06-10 10:12:38.968388 | controller | Wednesday 10 June 2026  10:12:38 +0000 (0:00:00.069)       0:01:07.979 ********
2026-06-10 10:12:39.603013 | controller | ok: [instance]
2026-06-10 10:12:39.603040 | controller |
2026-06-10 10:12:39.603045 | controller | TASK [Simulate no SSH-reachable hosts matching k8s nodes] **********************
2026-06-10 10:12:39.603049 | controller | Wednesday 10 June 2026  10:12:39 +0000 (0:00:00.635)       0:01:08.614 ********
2026-06-10 10:12:39.635497 | controller | ok: [instance]
2026-06-10 10:12:39.635574 | controller |
2026-06-10 10:12:39.635594 | controller | TASK [Run uncovered_node_dirs.yml (create)] ************************************
2026-06-10 10:12:39.635712 | controller | Wednesday 10 June 2026  10:12:39 +0000 (0:00:00.032)       0:01:08.647 ********
2026-06-10 10:12:39.674238 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_local_storage/tasks/uncovered_node_dirs.yml for instance
2026-06-10 10:12:39.674564 | controller |
2026-06-10 10:12:39.674942 | controller | TASK [Identify k8s nodes not reachable via SSH (SNO BM)] ***********************
2026-06-10 10:12:39.674984 | controller | Wednesday 10 June 2026  10:12:39 +0000 (0:00:00.038)       0:01:08.686 ********
2026-06-10 10:12:39.732230 | controller | ok: [instance]
2026-06-10 10:12:39.733165 | controller |
2026-06-10 10:12:39.733220 | controller | TASK [Manage PV directories via oc debug for unreachable nodes] ****************
2026-06-10 10:12:39.733264 | controller | Wednesday 10 June 2026  10:12:39 +0000 (0:00:00.057)       0:01:08.744 ********
2026-06-10 10:12:58.734163 | controller | changed: [instance] => (item=crc)
2026-06-10 10:12:58.734209 | controller |
2026-06-10 10:12:58.734242 | controller | TASK [Assert uncovered nodes were identified] **********************************
2026-06-10 10:12:58.734318 | controller | Wednesday 10 June 2026  10:12:58 +0000 (0:00:19.001)       0:01:27.746 ********
2026-06-10 10:12:58.775183 | controller | ok: [instance] => changed=false
2026-06-10 10:12:58.775425 | controller |   msg: All assertions passed
2026-06-10 10:12:58.775586 | controller |
2026-06-10 10:12:58.775791 | controller | TASK [Verify directories created on node] **************************************
2026-06-10 10:12:58.775997 | controller | Wednesday 10 June 2026  10:12:58 +0000 (0:00:00.040)       0:01:27.786 ********
2026-06-10 10:13:02.523999 | controller | ok: [instance -> crc] => (item=1)
2026-06-10 10:13:02.524055 | controller | ok: [instance -> crc] => (item=2)
2026-06-10 10:13:02.524166 | controller | ok: [instance -> crc] => (item=3)
2026-06-10 10:13:02.524252 | controller |
2026-06-10 10:13:02.524340 | controller | TASK [Assert all fallback directories exist] ***********************************
2026-06-10 10:13:02.524444 | controller | Wednesday 10 June 2026  10:13:02 +0000 (0:00:03.748)       0:01:31.535 ********
2026-06-10 10:13:02.594634 | controller | ok: [instance] => (item=/mnt/openstack-fallback/pv01) => changed=false
2026-06-10 10:13:02.594770 | controller |   ansible_loop_var: item
2026-06-10 10:13:02.594985 | controller |   item:
2026-06-10 10:13:02.595228 | controller |     ansible_loop_var: item
2026-06-10 10:13:02.595467 | controller |     changed: false
2026-06-10 10:13:02.595666 | controller |     failed: false
2026-06-10 10:13:02.595851 | controller |     invocation:
2026-06-10 10:13:02.596080 | controller |       module_args:
2026-06-10 10:13:02.596328 | controller |         checksum_algorithm: sha1
2026-06-10 10:13:02.596517 | controller |         follow: false
2026-06-10 10:13:02.596702 | controller |         get_attributes: true
2026-06-10 10:13:02.596908 | controller |         get_checksum: true
2026-06-10 10:13:02.597129 | controller |         get_md5: false
2026-06-10 10:13:02.597329 | controller |         get_mime: true
2026-06-10 10:13:02.597540 | controller |         path: /mnt/openstack-fallback/pv01
2026-06-10 10:13:02.597743 | controller |     item: 1
2026-06-10 10:13:02.597929 | controller |     stat:
2026-06-10 10:13:02.598150 | controller |       atime: 1781086378.3740044
2026-06-10 10:13:02.598367 | controller |       attr_flags: ''
2026-06-10 10:13:02.598563 | controller |       attributes: []
2026-06-10 10:13:02.598758 | controller |       block_size: 4096
2026-06-10 10:13:02.598975 | controller |       blocks: 0
2026-06-10 10:13:02.599215 | controller |       charset: binary
2026-06-10 10:13:02.599437 | controller |       ctime: 1781086378.3760045
2026-06-10 10:13:02.599635 | controller |       dev: 64516
2026-06-10 10:13:02.599821 | controller |       device_type: 0
2026-06-10 10:13:02.600003 | controller |       executable: true
2026-06-10 10:13:02.600250 | controller |       exists: true
2026-06-10 10:13:02.600426 | controller |       gid: 0
2026-06-10 10:13:02.600599 | controller |       gr_name: root
2026-06-10 10:13:02.600785 | controller |       inode: 48636464
2026-06-10 10:13:02.600970 | controller |       isblk: false
2026-06-10 10:13:02.601206 | controller |       ischr: false
2026-06-10 10:13:02.601385 | controller |       isdir: true
2026-06-10 10:13:02.601588 | controller |       isfifo: false
2026-06-10 10:13:02.601815 | controller |       isgid: false
2026-06-10 10:13:02.602023 | controller |       islnk: false
2026-06-10 10:13:02.602269 | controller |       isreg: false
2026-06-10 10:13:02.602468 | controller |       issock: false
2026-06-10 10:13:02.602663 | controller |       isuid: false
2026-06-10 10:13:02.602858 | controller |       mimetype: inode/directory
2026-06-10 10:13:02.603144 | controller |       mode: '0775'
2026-06-10 10:13:02.603402 | controller |       mtime: 1781086378.3740044
2026-06-10 10:13:02.603580 | controller |       nlink: 2
2026-06-10 10:13:02.603784 | controller |       path: /mnt/openstack-fallback/pv01
2026-06-10 10:13:02.603965 | controller |       pw_name: root
2026-06-10 10:13:02.604220 | controller |       readable: true
2026-06-10 10:13:02.604413 | controller |       rgrp: true
2026-06-10 10:13:02.604619 | controller |       roth: true
2026-06-10 10:13:02.604812 | controller |       rusr: true
2026-06-10 10:13:02.605003 | controller |       size: 6
2026-06-10 10:13:02.605230 | controller |       uid: 0
2026-06-10 10:13:02.605413 | controller |       version: '618078937'
2026-06-10 10:13:02.605610 | controller |       wgrp: true
2026-06-10 10:13:02.605795 | controller |       woth: false
2026-06-10 10:13:02.605984 | controller |       writeable: true
2026-06-10 10:13:02.606423 | controller |       wusr: true
2026-06-10 10:13:02.606686 | controller |       xgrp: true
2026-06-10 10:13:02.606880 | controller |       xoth: true
2026-06-10 10:13:02.607079 | controller |       xusr: true
2026-06-10 10:13:02.607322 | controller |   msg: All assertions passed
2026-06-10 10:13:02.607814 | controller | ok: [instance] => (item=/mnt/openstack-fallback/pv02) => changed=false
2026-06-10 10:13:02.607827 | controller |   ansible_loop_var: item
2026-06-10 10:13:02.608518 | controller |   item:
2026-06-10 10:13:02.608541 | controller |     ansible_loop_var: item
2026-06-10 10:13:02.608546 | controller |     changed: false
2026-06-10 10:13:02.608566 | controller |     failed: false
2026-06-10 10:13:02.609430 | controller |     invocation:
2026-06-10 10:13:02.609452 | controller |       module_args:
2026-06-10 10:13:02.609458 | controller |         checksum_algorithm: sha1
2026-06-10 10:13:02.609463 | controller |         follow: false
2026-06-10 10:13:02.609596 | controller |         get_attributes: true
2026-06-10 10:13:02.609775 | controller |         get_checksum: true
2026-06-10 10:13:02.609953 | controller |         get_md5: false
2026-06-10 10:13:02.610151 | controller |         get_mime: true
2026-06-10 10:13:02.610443 | controller |         path: /mnt/openstack-fallback/pv02
2026-06-10 10:13:02.610641 | controller |     item: 2
2026-06-10 10:13:02.610824 | controller |     stat:
2026-06-10 10:13:02.611022 | controller |       atime: 1781086378.3780046
2026-06-10 10:13:02.611252 | controller |       attr_flags: ''
2026-06-10 10:13:02.611468 | controller |       attributes: []
2026-06-10 10:13:02.611649 | controller |       block_size: 4096
2026-06-10 10:13:02.611854 | controller |       blocks: 0
2026-06-10 10:13:02.612022 | controller |       charset: binary
2026-06-10 10:13:02.612241 | controller |       ctime: 1781086378.3800046
2026-06-10 10:13:02.612427 | controller |       dev: 64516
2026-06-10 10:13:02.612606 | controller |       device_type: 0
2026-06-10 10:13:02.612797 | controller |       executable: true
2026-06-10 10:13:02.612977 | controller |       exists: true
2026-06-10 10:13:02.613189 | controller |       gid: 0
2026-06-10 10:13:02.613382 | controller |       gr_name: root
2026-06-10 10:13:02.613579 | controller |       inode: 50384169
2026-06-10 10:13:02.613764 | controller |       isblk: false
2026-06-10 10:13:02.613951 | controller |       ischr: false
2026-06-10 10:13:02.614149 | controller |       isdir: true
2026-06-10 10:13:02.614367 | controller |       isfifo: false
2026-06-10 10:13:02.614547 | controller |       isgid: false
2026-06-10 10:13:02.614730 | controller |       islnk: false
2026-06-10 10:13:02.614900 | controller |       isreg: false
2026-06-10 10:13:02.615135 | controller |       issock: false
2026-06-10 10:13:02.615357 | controller |       isuid: false
2026-06-10 10:13:02.615544 | controller |       mimetype: inode/directory
2026-06-10 10:13:02.615729 | controller |       mode: '0775'
2026-06-10 10:13:02.615925 | controller |       mtime: 1781086378.3780046
2026-06-10 10:13:02.616139 | controller |       nlink: 2
2026-06-10 10:13:02.616338 | controller |       path: /mnt/openstack-fallback/pv02
2026-06-10 10:13:02.616515 | controller |       pw_name: root
2026-06-10 10:13:02.616723 | controller |       readable: true
2026-06-10 10:13:02.616910 | controller |       rgrp: true
2026-06-10 10:13:02.617083 | controller |       roth: true
2026-06-10 10:13:02.617302 | controller |       rusr: true
2026-06-10 10:13:02.617471 | controller |       size: 6
2026-06-10 10:13:02.617653 | controller |       uid: 0
2026-06-10 10:13:02.617840 | controller |       version: '1779221141'
2026-06-10 10:13:02.618025 | controller |       wgrp: true
2026-06-10 10:13:02.618256 | controller |       woth: false
2026-06-10 10:13:02.618430 | controller |       writeable: true
2026-06-10 10:13:02.618611 | controller |       wusr: true
2026-06-10 10:13:02.618805 | controller |       xgrp: true
2026-06-10 10:13:02.619005 | controller |       xoth: true
2026-06-10 10:13:02.619251 | controller |       xusr: true
2026-06-10 10:13:02.619456 | controller |   msg: All assertions passed
2026-06-10 10:13:02.619638 | controller | ok: [instance] => (item=/mnt/openstack-fallback/pv03) => changed=false
2026-06-10 10:13:02.619816 | controller |   ansible_loop_var: item
2026-06-10 10:13:02.620000 | controller |   item:
2026-06-10 10:13:02.620227 | controller |     ansible_loop_var: item
2026-06-10 10:13:02.620413 | controller |     changed: false
2026-06-10 10:13:02.620598 | controller |     failed: false
2026-06-10 10:13:02.620776 | controller |     invocation:
2026-06-10 10:13:02.620961 | controller |       module_args:
2026-06-10 10:13:02.621184 | controller |         checksum_algorithm: sha1
2026-06-10 10:13:02.621365 | controller |         follow: false
2026-06-10 10:13:02.621574 | controller |         get_attributes: true
2026-06-10 10:13:02.621754 | controller |         get_checksum: true
2026-06-10 10:13:02.621931 | controller |         get_md5: false
2026-06-10 10:13:02.622191 | controller |         get_mime: true
2026-06-10 10:13:02.622465 | controller |         path: /mnt/openstack-fallback/pv03
2026-06-10 10:13:02.622725 | controller |     item: 3
2026-06-10 10:13:02.622981 | controller |     stat:
2026-06-10 10:13:02.623343 | controller |       atime: 1781086378.3830047
2026-06-10 10:13:02.623652 | controller |       attr_flags: ''
2026-06-10 10:13:02.623935 | controller |       attributes: []
2026-06-10 10:13:02.624262 | controller |       block_size: 4096
2026-06-10 10:13:02.624556 | controller |       blocks: 0
2026-06-10 10:13:02.624833 | controller |       charset: binary
2026-06-10 10:13:02.625108 | controller |       ctime: 1781086378.3850048
2026-06-10 10:13:02.625431 | controller |       dev: 64516
2026-06-10 10:13:02.625738 | controller |       device_type: 0
2026-06-10 10:13:02.626023 | controller |       executable: true
2026-06-10 10:13:02.626339 | controller |       exists: true
2026-06-10 10:13:02.626637 | controller |       gid: 0
2026-06-10 10:13:02.626918 | controller |       gr_name: root
2026-06-10 10:13:02.627276 | controller |       inode: 52432565
2026-06-10 10:13:02.627564 | controller |       isblk: false
2026-06-10 10:13:02.627851 | controller |       ischr: false
2026-06-10 10:13:02.628108 | controller |       isdir: true
2026-06-10 10:13:02.628463 | controller |       isfifo: false
2026-06-10 10:13:02.628761 | controller |       isgid: false
2026-06-10 10:13:02.629096 | controller |       islnk: false
2026-06-10 10:13:02.629433 | controller |       isreg: false
2026-06-10 10:13:02.629795 | controller |       issock: false
2026-06-10 10:13:02.630088 | controller |       isuid: false
2026-06-10 10:13:02.630404 | controller |       mimetype: inode/directory
2026-06-10 10:13:02.630691 | controller |       mode: '0775'
2026-06-10 10:13:02.630970 | controller |       mtime: 1781086378.3830047
2026-06-10 10:13:02.631308 | controller |       nlink: 2
2026-06-10 10:13:02.631596 | controller |       path: /mnt/openstack-fallback/pv03
2026-06-10 10:13:02.631878 | controller |       pw_name: root
2026-06-10 10:13:02.632173 | controller |       readable: true
2026-06-10 10:13:02.632431 | controller |       rgrp: true
2026-06-10 10:13:02.632563 | controller |       roth: true
2026-06-10 10:13:02.632686 | controller |       rusr: true
2026-06-10 10:13:02.632798 | controller |       size: 6
2026-06-10 10:13:02.632924 | controller |       uid: 0
2026-06-10 10:13:02.633046 | controller |       version: '1784473906'
2026-06-10 10:13:02.633178 | controller |       wgrp: true
2026-06-10 10:13:02.633310 | controller |       woth: false
2026-06-10 10:13:02.633414 | controller |       writeable: true
2026-06-10 10:13:02.633538 | controller |       wusr: true
2026-06-10 10:13:02.633658 | controller |       xgrp: true
2026-06-10 10:13:02.633763 | controller |       xoth: true
2026-06-10 10:13:02.633881 | controller |       xusr: true
2026-06-10 10:13:02.634000 | controller |   msg: All assertions passed
2026-06-10 10:13:02.634110 | controller |
2026-06-10 10:13:02.634260 | controller | TASK [Run uncovered_node_dirs.yml (cleanup)] ***********************************
2026-06-10 10:13:02.634386 | controller | Wednesday 10 June 2026  10:13:02 +0000 (0:00:00.070)       0:01:31.606 ********
2026-06-10 10:13:02.645616 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_local_storage/tasks/uncovered_node_dirs.yml for instance
2026-06-10 10:13:02.645715 | controller |
2026-06-10 10:13:02.645879 | controller | TASK [Identify k8s nodes not reachable via SSH (SNO BM)] ***********************
2026-06-10 10:13:02.646016 | controller | Wednesday 10 June 2026  10:13:02 +0000 (0:00:00.051)       0:01:31.658 ********
2026-06-10 10:13:02.709390 | controller | ok: [instance]
2026-06-10 10:13:02.709516 | controller |
2026-06-10 10:13:02.709813 | controller | TASK [Manage PV directories via oc debug for unreachable nodes] ****************
2026-06-10 10:13:02.710029 | controller | Wednesday 10 June 2026  10:13:02 +0000 (0:00:00.062)       0:01:31.721 ********
2026-06-10 10:13:04.734023 | controller | changed: [instance] => (item=crc)
2026-06-10 10:13:04.736199 | controller |
2026-06-10 10:13:04.736230 | controller | TASK [Verify fallback directories removed] *************************************
2026-06-10 10:13:04.736241 | controller | Wednesday 10 June 2026  10:13:04 +0000 (0:00:02.024)       0:01:33.745 ********
2026-06-10 10:13:05.906732 | controller | ok: [instance -> crc]
2026-06-10 10:13:05.906803 | controller |
2026-06-10 10:13:05.906933 | controller | TASK [Assert fallback directory tree is gone] **********************************
2026-06-10 10:13:05.907091 | controller | Wednesday 10 June 2026  10:13:05 +0000 (0:00:01.173)       0:01:34.918 ********
2026-06-10 10:13:05.956435 | controller | ok: [instance] => changed=false
2026-06-10 10:13:05.956515 | controller |   msg: All assertions passed
2026-06-10 10:13:05.956648 | controller |
2026-06-10 10:13:05.956776 | controller | PLAY RECAP *********************************************************************
2026-06-10 10:13:05.956910 | controller | instance                   : ok=50   changed=15   unreachable=0    failed=0    skipped=4    rescued=0    ignored=0
2026-06-10 10:13:05.957036 | controller |
2026-06-10 10:13:05.957185 | controller | Wednesday 10 June 2026  10:13:05 +0000 (0:00:00.049)       0:01:34.968 ********
2026-06-10 10:13:05.957322 | controller | ===============================================================================
2026-06-10 10:13:05.957442 | controller | Manage PV directories via oc debug for unreachable nodes --------------- 19.00s
2026-06-10 10:13:05.957595 | controller | ci_local_storage : Perform action in the PV directory ------------------ 12.24s
2026-06-10 10:13:05.957738 | controller | ci_local_storage : Perform action in the PV directory ------------------ 11.95s
2026-06-10 10:13:05.957875 | controller | Not create directories on crc ------------------------------------------ 11.66s
2026-06-10 10:13:05.958045 | controller | ci_local_storage : Remove the cifmw_cls_namespace namespace ------------ 10.75s
2026-06-10 10:13:05.958205 | controller | ci_local_storage : Delete all pvs --------------------------------------- 6.55s
2026-06-10 10:13:05.958313 | controller | Verify directories created on node -------------------------------------- 3.75s
2026-06-10 10:13:05.958454 | controller | ci_local_storage : Fetch hostnames for all hosts ------------------------ 2.37s
2026-06-10 10:13:05.958565 | controller | Manage PV directories via oc debug for unreachable nodes ---------------- 2.02s
2026-06-10 10:13:05.958692 | controller | ci_local_storage : Create the cifmw_cls_namespace namespace" ------------ 1.57s
2026-06-10 10:13:05.958845 | controller | ci_local_storage : Fetch hostnames for all hosts ------------------------ 1.32s
2026-06-10 10:13:05.958937 | controller | Verify fallback directories removed ------------------------------------- 1.17s
2026-06-10 10:13:05.959056 | controller | Gathering Facts --------------------------------------------------------- 0.98s
2026-06-10 10:13:05.959180 | controller | ci_local_storage : Apply pv related storage manifest file --------------- 0.93s
2026-06-10 10:13:05.959295 | controller | ci_local_storage : Get k8s nodes ---------------------------------------- 0.86s
2026-06-10 10:13:05.959402 | controller | Get all namespaces ------------------------------------------------------ 0.85s
2026-06-10 10:13:05.959520 | controller | ci_local_storage : Get all pvs ------------------------------------------ 0.75s
2026-06-10 10:13:05.959638 | controller | Get all pvs ------------------------------------------------------------- 0.73s
2026-06-10 10:13:05.959744 | controller | ci_local_storage : Apply the storage class manifests -------------------- 0.68s
2026-06-10 10:13:05.959844 | controller | ci_local_storage : Get k8s nodes ---------------------------------------- 0.66s
2026-06-10 10:13:06.055791 | controller | INFO     Running default > cleanup
2026-06-10 10:13:06.056128 | controller | WARNING  Skipping, cleanup playbook not configured.
2026-06-10 10:13:06.056653 | controller | INFO     Writing /tmp/report.html report.
2026-06-10 10:13:06.239194 | controller | ok: Runtime: 0:14:31.571098
2026-06-10 10:13:06.266655 | 
2026-06-10 10:13:06.266801 | PLAY RECAP
2026-06-10 10:13:06.266886 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-10 10:13:06.266921 | 
2026-06-10 10:13:06.406986 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-06-10 10:13:06.409903 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-06-10 10:13:07.098234 | 
2026-06-10 10:13:07.098374 | PLAY [Ensure ci-framework-data base directories exist on all nodes]
2026-06-10 10:13:07.109998 | 
2026-06-10 10:13:07.110115 | TASK [Create ci-framework-data/logs directory if missing]
2026-06-10 10:13:07.637540 | controller | ok
2026-06-10 10:13:07.666983 | 
2026-06-10 10:13:07.667103 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-06-10 10:13:07.673572 | 
2026-06-10 10:13:07.673688 | TASK [Filter out host if needed]
2026-06-10 10:13:07.685715 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-06-10 10:13:07.690015 | 
2026-06-10 10:13:07.690089 | TASK [Ensure file is present]
2026-06-10 10:13:08.206043 | controller | ok
2026-06-10 10:13:08.218054 | 
2026-06-10 10:13:08.218292 | TASK [Manage molecule report file]
2026-06-10 10:13:09.262310 | controller | ok: Runtime: 0:00:00.008095
2026-06-10 10:13:09.270335 | 
2026-06-10 10:13:09.270470 | TASK [Check if we get ci-framework-data basedir]
2026-06-10 10:13:09.639565 | controller | ok
2026-06-10 10:13:09.648314 | 
2026-06-10 10:13:09.648451 | TASK [Create ci-framework-data log directory for zuul]
2026-06-10 10:13:09.997895 | controller | changed
2026-06-10 10:13:10.006657 | 
2026-06-10 10:13:10.006802 | TASK [Copy ci-framework interesting files]
2026-06-10 10:13:10.419004 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-06-10 10:13:10.556762 | controller | ok: Runtime: 0:00:00.018863
2026-06-10 10:13:10.565179 | 
2026-06-10 10:13:10.565377 | TASK [Get SELinux listing]
2026-06-10 10:13:11.117688 | controller | ok: Runtime: 0:00:00.014619
2026-06-10 10:13:11.127248 | 
2026-06-10 10:13:11.127481 | TASK [Generate log index]
2026-06-10 10:13:12.624525 | controller | changed
2026-06-10 10:13:12.640593 | 
2026-06-10 10:13:12.640914 | TASK [Get some env related data]
2026-06-10 10:13:13.572171 | controller | /home/zuul/.local/bin/ansible
2026-06-10 10:13:14.734970 | controller | ok: Runtime: 0:00:01.336911
2026-06-10 10:13:14.740190 | 
2026-06-10 10:13:14.740282 | TASK [Generate list of logs to collect in home directory]
2026-06-10 10:13:15.241377 | controller | ok: All paths examined
2026-06-10 10:13:15.251658 | 
2026-06-10 10:13:15.251928 | LOOP [Copy logs from home directory]
2026-06-10 10:13:16.126339 | controller | changed:
2026-06-10 10:13:16.126762 | controller | {
2026-06-10 10:13:16.126821 | controller |   "atime": 1777967689.47849,
2026-06-10 10:13:16.126900 | controller |   "ctime": 1777968154.1714108,
2026-06-10 10:13:16.126940 | controller |   "dev": 64513,
2026-06-10 10:13:16.126977 | controller |   "gid": 1000,
2026-06-10 10:13:16.127011 | controller |   "gr_name": "zuul",
2026-06-10 10:13:16.127072 | controller |   "inode": 236288,
2026-06-10 10:13:16.127106 | controller |   "isblk": false,
2026-06-10 10:13:16.127138 | controller |   "ischr": false,
2026-06-10 10:13:16.127170 | controller |   "isdir": false,
2026-06-10 10:13:16.127201 | controller |   "isfifo": false,
2026-06-10 10:13:16.127233 | controller |   "isgid": false,
2026-06-10 10:13:16.127264 | controller |   "islnk": false,
2026-06-10 10:13:16.127295 | controller |   "isreg": true,
2026-06-10 10:13:16.127327 | controller |   "issock": false,
2026-06-10 10:13:16.127367 | controller |   "isuid": false,
2026-06-10 10:13:16.127400 | controller |   "mode": "0644",
2026-06-10 10:13:16.127433 | controller |   "mtime": 1777968154.1714108,
2026-06-10 10:13:16.127468 | controller |   "nlink": 1,
2026-06-10 10:13:16.127501 | controller |   "path": "/home/zuul/crc-setup.log",
2026-06-10 10:13:16.127535 | controller |   "pw_name": "zuul",
2026-06-10 10:13:16.127624 | controller |   "rgrp": true,
2026-06-10 10:13:16.127672 | controller |   "roth": true,
2026-06-10 10:13:16.127710 | controller |   "rusr": true,
2026-06-10 10:13:16.127744 | controller |   "size": 4108,
2026-06-10 10:13:16.127777 | controller |   "uid": 1000,
2026-06-10 10:13:16.127809 | controller |   "wgrp": false,
2026-06-10 10:13:16.127841 | controller |   "woth": false,
2026-06-10 10:13:16.127913 | controller |   "wusr": true,
2026-06-10 10:13:16.127948 | controller |   "xgrp": false,
2026-06-10 10:13:16.127981 | controller |   "xoth": false,
2026-06-10 10:13:16.128014 | controller |   "xusr": false
2026-06-10 10:13:16.128047 | controller | }
2026-06-10 10:13:16.968465 | controller | changed:
2026-06-10 10:13:16.968646 | controller | {
2026-06-10 10:13:16.968695 | controller |   "atime": 1777968156.210465,
2026-06-10 10:13:16.968734 | controller |   "ctime": 1777968956.4694307,
2026-06-10 10:13:16.968768 | controller |   "dev": 64513,
2026-06-10 10:13:16.968801 | controller |   "gid": 1000,
2026-06-10 10:13:16.968834 | controller |   "gr_name": "zuul",
2026-06-10 10:13:16.968907 | controller |   "inode": 2688,
2026-06-10 10:13:16.968943 | controller |   "isblk": false,
2026-06-10 10:13:16.968976 | controller |   "ischr": false,
2026-06-10 10:13:16.969024 | controller |   "isdir": false,
2026-06-10 10:13:16.969056 | controller |   "isfifo": false,
2026-06-10 10:13:16.969088 | controller |   "isgid": false,
2026-06-10 10:13:16.969119 | controller |   "islnk": false,
2026-06-10 10:13:16.969150 | controller |   "isreg": true,
2026-06-10 10:13:16.969181 | controller |   "issock": false,
2026-06-10 10:13:16.969212 | controller |   "isuid": false,
2026-06-10 10:13:16.969244 | controller |   "mode": "0644",
2026-06-10 10:13:16.969276 | controller |   "mtime": 1777968956.4694307,
2026-06-10 10:13:16.969309 | controller |   "nlink": 1,
2026-06-10 10:13:16.969341 | controller |   "path": "/home/zuul/crc-start.log",
2026-06-10 10:13:16.969373 | controller |   "pw_name": "zuul",
2026-06-10 10:13:16.969405 | controller |   "rgrp": true,
2026-06-10 10:13:16.969437 | controller |   "roth": true,
2026-06-10 10:13:16.969468 | controller |   "rusr": true,
2026-06-10 10:13:16.969500 | controller |   "size": 5003,
2026-06-10 10:13:16.969532 | controller |   "uid": 1000,
2026-06-10 10:13:16.969563 | controller |   "wgrp": false,
2026-06-10 10:13:16.969595 | controller |   "woth": false,
2026-06-10 10:13:16.969626 | controller |   "wusr": true,
2026-06-10 10:13:16.969657 | controller |   "xgrp": false,
2026-06-10 10:13:16.969689 | controller |   "xoth": false,
2026-06-10 10:13:16.969720 | controller |   "xusr": false
2026-06-10 10:13:16.969751 | controller | }
2026-06-10 10:13:17.804051 | controller | changed:
2026-06-10 10:13:17.804243 | controller | {
2026-06-10 10:13:17.804292 | controller |   "atime": 1781085471.147635,
2026-06-10 10:13:17.804330 | controller |   "ctime": 1781085511.247937,
2026-06-10 10:13:17.804432 | controller |   "dev": 64513,
2026-06-10 10:13:17.804477 | controller |   "gid": 1000,
2026-06-10 10:13:17.804510 | controller |   "gr_name": "zuul",
2026-06-10 10:13:17.804543 | controller |   "inode": 3016645,
2026-06-10 10:13:17.804576 | controller |   "isblk": false,
2026-06-10 10:13:17.804608 | controller |   "ischr": false,
2026-06-10 10:13:17.804639 | controller |   "isdir": false,
2026-06-10 10:13:17.804670 | controller |   "isfifo": false,
2026-06-10 10:13:17.804702 | controller |   "isgid": false,
2026-06-10 10:13:17.804734 | controller |   "islnk": false,
2026-06-10 10:13:17.804766 | controller |   "isreg": true,
2026-06-10 10:13:17.804799 | controller |   "issock": false,
2026-06-10 10:13:17.804833 | controller |   "isuid": false,
2026-06-10 10:13:17.804903 | controller |   "mode": "0644",
2026-06-10 10:13:17.804940 | controller |   "mtime": 1781085511.247937,
2026-06-10 10:13:17.804998 | controller |   "nlink": 1,
2026-06-10 10:13:17.805034 | controller |   "path": "/home/zuul/ansible.log",
2026-06-10 10:13:17.805067 | controller |   "pw_name": "zuul",
2026-06-10 10:13:17.805100 | controller |   "rgrp": true,
2026-06-10 10:13:17.805132 | controller |   "roth": true,
2026-06-10 10:13:17.805164 | controller |   "rusr": true,
2026-06-10 10:13:17.805196 | controller |   "size": 6701,
2026-06-10 10:13:17.805227 | controller |   "uid": 1000,
2026-06-10 10:13:17.805259 | controller |   "wgrp": false,
2026-06-10 10:13:17.805290 | controller |   "woth": false,
2026-06-10 10:13:17.805321 | controller |   "wusr": true,
2026-06-10 10:13:17.805352 | controller |   "xgrp": false,
2026-06-10 10:13:17.805383 | controller |   "xoth": false,
2026-06-10 10:13:17.805416 | controller |   "xusr": false
2026-06-10 10:13:17.805504 | controller | }
2026-06-10 10:13:17.824533 | 
2026-06-10 10:13:17.824820 | TASK [Copy crio stats log file]
2026-06-10 10:13:17.863963 | controller | skipping: Conditional result was False
2026-06-10 10:13:17.872488 | 
2026-06-10 10:13:17.872636 | TASK [Get SELinux related data]
2026-06-10 10:13:18.244938 | controller | <no matches>
2026-06-10 10:13:18.413295 | controller | ERROR
2026-06-10 10:13:18.413625 | controller | {
2026-06-10 10:13:18.413683 | controller |   "delta": "0:00:00.014322",
2026-06-10 10:13:18.413722 | controller |   "end": "2026-06-10 10:13:18.246045",
2026-06-10 10:13:18.413756 | controller |   "msg": "non-zero return code",
2026-06-10 10:13:18.413805 | controller |   "rc": 1,
2026-06-10 10:13:18.413839 | controller |   "start": "2026-06-10 10:13:18.231723"
2026-06-10 10:13:18.413917 | controller | }
2026-06-10 10:13:18.413965 | controller | ERROR: Ignoring Errors
2026-06-10 10:13:18.422420 | 
2026-06-10 10:13:18.422658 | TASK [Create system configuration directory]
2026-06-10 10:13:18.818758 | controller | changed
2026-06-10 10:13:18.822723 | 
2026-06-10 10:13:18.822818 | TASK [Get some of the system configurations]
2026-06-10 10:13:19.358177 | controller | ok: Runtime: 0:00:00.023307
2026-06-10 10:13:19.365787 | 
2026-06-10 10:13:19.365966 | TASK [Copy generated documentation if available]
2026-06-10 10:13:19.392221 | controller | skipping: Conditional result was False
2026-06-10 10:13:19.401712 | 
2026-06-10 10:13:19.401890 | TASK [Copy generated AsciiDoc documentation if available]
2026-06-10 10:13:19.439598 | controller | skipping: Conditional result was False
2026-06-10 10:13:19.455022 | 
2026-06-10 10:13:19.455273 | TASK [Compress logs bigger than 2MB]
2026-06-10 10:13:19.996763 | controller | ok: Runtime: 0:00:00.008284
2026-06-10 10:13:20.005460 | 
2026-06-10 10:13:20.005610 | TASK [Copy files from workspace on node]
2026-06-10 10:13:20.038712 | controller | ok
2026-06-10 10:13:20.066169 | 
2026-06-10 10:13:20.066311 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-10 10:13:20.101736 | controller | skipping: Conditional result was False
2026-06-10 10:13:20.108887 | 
2026-06-10 10:13:20.108985 | TASK [fetch-output : Set log path for single node]
2026-06-10 10:13:20.151197 | controller | ok
2026-06-10 10:13:20.159607 | 
2026-06-10 10:13:20.159699 | LOOP [fetch-output : Ensure local output dirs]
2026-06-10 10:13:20.457846 | controller -> localhost | changed: "/var/lib/zuul/builds/e1f04a35fcb14648a1cfe92e4697c701/work/logs"
2026-06-10 10:13:20.458161 | controller -> localhost | changed: All items complete
2026-06-10 10:13:20.458190 | 
2026-06-10 10:13:20.706350 | controller -> localhost | changed: "/var/lib/zuul/builds/e1f04a35fcb14648a1cfe92e4697c701/work/artifacts"
2026-06-10 10:13:20.937202 | controller -> localhost | changed: "/var/lib/zuul/builds/e1f04a35fcb14648a1cfe92e4697c701/work/docs"
2026-06-10 10:13:20.961060 | 
2026-06-10 10:13:20.961307 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-10 10:13:21.836694 | controller | changed:
2026-06-10 10:13:21.837096 | controller | .d..t...... ./
2026-06-10 10:13:21.837154 | controller | >f+++++++++ README.html
2026-06-10 10:13:21.837199 | controller | >f+++++++++ ansible-execution.log
2026-06-10 10:13:21.837238 | controller | >f+++++++++ ansible.log
2026-06-10 10:13:21.837285 | controller | >f+++++++++ crc-setup.log
2026-06-10 10:13:21.837345 | controller | >f+++++++++ crc-start.log
2026-06-10 10:13:21.837406 | controller | >f+++++++++ dmesg.log
2026-06-10 10:13:21.837448 | controller | >f+++++++++ installed-pkgs.log
2026-06-10 10:13:21.837486 | controller | >f+++++++++ python.log
2026-06-10 10:13:21.837556 | controller | >f+++++++++ registries.conf
2026-06-10 10:13:21.837597 | controller | >f+++++++++ report.html
2026-06-10 10:13:21.837632 | controller | >f+++++++++ selinux-denials.log
2026-06-10 10:13:21.837668 | controller | >f+++++++++ selinux-listing.log
2026-06-10 10:13:21.837703 | controller | cd+++++++++ ci-framework-data/
2026-06-10 10:13:21.837737 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-06-10 10:13:21.837772 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-06-10 10:13:21.837811 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2026-06-10 10:13:21.837880 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/storage/
2026-06-10 10:13:21.837934 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/storage/storage-class.yaml
2026-06-10 10:13:21.837976 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/storage/storage.yaml
2026-06-10 10:13:21.838014 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-06-10 10:13:21.838076 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-06-10 10:13:21.838115 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2026-06-10 10:13:21.838152 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2026-06-10 10:13:21.838189 | controller | cd+++++++++ ci-framework-data/logs/
2026-06-10 10:13:21.838226 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-06-10 10:13:21.838265 | controller | cd+++++++++ registries.conf.d/
2026-06-10 10:13:21.838301 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-06-10 10:13:21.838338 | controller | cd+++++++++ system-config/
2026-06-10 10:13:21.838391 | controller | cd+++++++++ system-config/libvirt/
2026-06-10 10:13:21.838452 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-06-10 10:13:21.838513 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-06-10 10:13:21.838561 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2026-06-10 10:13:21.838599 | controller | >f+++++++++ system-config/libvirt/network.conf
2026-06-10 10:13:21.838635 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2026-06-10 10:13:21.838673 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2026-06-10 10:13:21.838710 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2026-06-10 10:13:21.838746 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2026-06-10 10:13:21.838782 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2026-06-10 10:13:21.838818 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2026-06-10 10:13:21.838890 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2026-06-10 10:13:21.838941 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2026-06-10 10:13:21.838982 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2026-06-10 10:13:21.839017 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2026-06-10 10:13:21.839054 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2026-06-10 10:13:21.839090 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2026-06-10 10:13:22.445522 | controller | changed: .d..t...... ./
2026-06-10 10:13:23.091557 | controller | changed: .d..t...... ./
2026-06-10 10:13:23.118215 | 
2026-06-10 10:13:23.118367 | TASK [Return artifact to Zuul]
2026-06-10 10:13:23.166943 | controller | ok
2026-06-10 10:13:23.169210 | 
2026-06-10 10:13:23.169269 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-06-10 10:13:23.169383 | 
2026-06-10 10:13:23.169409 | PLAY RECAP
2026-06-10 10:13:23.169447 | controller | ok: 19 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-06-10 10:13:23.169465 | 
2026-06-10 10:13:23.341079 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-06-10 10:13:23.343542 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-06-10 10:13:23.965096 | 
2026-06-10 10:13:23.965425 | PLAY [all]
2026-06-10 10:13:23.975895 | 
2026-06-10 10:13:23.976052 | TASK [include_role : fetch-output]
2026-06-10 10:13:24.024949 | controller | ok
2026-06-10 10:13:24.042604 | 
2026-06-10 10:13:24.042794 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-10 10:13:24.109017 | controller | skipping: Conditional result was False
2026-06-10 10:13:24.116407 | 
2026-06-10 10:13:24.116552 | TASK [fetch-output : Set log path for single node]
2026-06-10 10:13:24.163426 | controller | ok
2026-06-10 10:13:24.173898 | 
2026-06-10 10:13:24.174105 | LOOP [fetch-output : Ensure local output dirs]
2026-06-10 10:13:24.649686 | controller -> localhost | ok: "/var/lib/zuul/builds/e1f04a35fcb14648a1cfe92e4697c701/work/logs"
2026-06-10 10:13:24.926498 | controller -> localhost | ok: "/var/lib/zuul/builds/e1f04a35fcb14648a1cfe92e4697c701/work/artifacts"
2026-06-10 10:13:25.222167 | controller -> localhost | ok: "/var/lib/zuul/builds/e1f04a35fcb14648a1cfe92e4697c701/work/docs"
2026-06-10 10:13:25.232907 | 
2026-06-10 10:13:25.232985 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-10 10:13:26.123604 | controller | ok
2026-06-10 10:13:26.124029 | controller | ok: All items complete
2026-06-10 10:13:26.124092 | 
2026-06-10 10:13:26.817155 | controller | ok
2026-06-10 10:13:27.513193 | controller | ok
2026-06-10 10:13:27.533617 | 
2026-06-10 10:13:27.533814 | TASK [include_role : fetch-output-openshift]
2026-06-10 10:13:27.560798 | controller | skipping: Conditional result was False
2026-06-10 10:13:27.568153 | 
2026-06-10 10:13:27.568269 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-10 10:13:28.073067 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.009226
2026-06-10 10:13:28.384825 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.014548
2026-06-10 10:13:28.407363 | 
2026-06-10 10:13:28.407540 | PLAY [all]
2026-06-10 10:13:28.418671 | 
2026-06-10 10:13:28.418953 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-06-10 10:13:29.005314 | controller | changed
2026-06-10 10:13:29.014486 | 
2026-06-10 10:13:29.014639 | PLAY RECAP
2026-06-10 10:13:29.014734 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-06-10 10:13:29.014780 | 
2026-06-10 10:13:29.232956 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-06-10 10:13:29.235372 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-06-10 10:13:29.883279 | 
2026-06-10 10:13:29.883563 | PLAY [localhost]
2026-06-10 10:13:29.894361 | 
2026-06-10 10:13:29.894457 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-10 10:13:30.349218 | localhost | changed
2026-06-10 10:13:30.361093 | 
2026-06-10 10:13:30.361284 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-10 10:13:30.402731 | localhost | ok
2026-06-10 10:13:30.412495 | 
2026-06-10 10:13:30.412720 | TASK [add-fileserver : Create SSH private key tempfile]
2026-06-10 10:13:30.902844 | localhost | changed
2026-06-10 10:13:30.909684 | 
2026-06-10 10:13:30.909780 | TASK [add-fileserver : Create SSH private key from secret]
2026-06-10 10:13:31.772939 | localhost | changed
2026-06-10 10:13:31.782224 | 
2026-06-10 10:13:31.782379 | TASK [add-fileserver : Add fileserver ssh key]
2026-06-10 10:13:32.318143 | localhost | Identity added: /var/lib/zuul/builds/e1f04a35fcb14648a1cfe92e4697c701/work/tmp/ansible.erltiyj5 (/var/lib/zuul/builds/e1f04a35fcb14648a1cfe92e4697c701/work/tmp/ansible.erltiyj5)
2026-06-10 10:13:32.318476 | localhost | ok: Runtime: 0:00:00.021256
2026-06-10 10:13:32.325539 | 
2026-06-10 10:13:32.325674 | TASK [add-fileserver : Remove SSH private key from disk]
2026-06-10 10:13:32.698096 | localhost | ok: Runtime: 0:00:00.010696
2026-06-10 10:13:32.706125 | 
2026-06-10 10:13:32.706259 | TASK [add-fileserver : Add fileserver to inventory]
2026-06-10 10:13:32.798838 | localhost | changed
2026-06-10 10:13:32.802204 | 
2026-06-10 10:13:32.802272 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-06-10 10:13:33.328457 | localhost | changed
2026-06-10 10:13:33.336364 | 
2026-06-10 10:13:33.336464 | PLAY [localhost]
2026-06-10 10:13:33.342636 | 
2026-06-10 10:13:33.342738 | TASK [Generate bulk log download script]
2026-06-10 10:13:33.374486 | localhost | ok
2026-06-10 10:13:33.389936 | 
2026-06-10 10:13:33.390032 | TASK [local-log-download : Check API endpoint is defined]
2026-06-10 10:13:33.443704 | localhost | ok: All assertions passed
2026-06-10 10:13:33.459077 | 
2026-06-10 10:13:33.459354 | TASK [local-log-download : Create download script]
2026-06-10 10:13:33.934049 | localhost -> localhost | changed
2026-06-10 10:13:33.990704 | 
2026-06-10 10:13:33.990900 | TASK [Register quick-download link]
2026-06-10 10:13:34.021405 | localhost | ok
2026-06-10 10:13:34.024188 | 
2026-06-10 10:13:34.024258 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com]
2026-06-10 10:13:34.030590 | 
2026-06-10 10:13:34.030668 | TASK [Set zuul-log-path fact]
2026-06-10 10:13:34.048938 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-10 10:13:34.057486 | 
2026-06-10 10:13:34.057570 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-10 10:13:34.095508 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-10 10:13:34.100707 | 
2026-06-10 10:13:34.100889 | TASK [upload-logs : Create log directories]
2026-06-10 10:13:35.135716 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed
2026-06-10 10:13:35.138248 | 
2026-06-10 10:13:35.138335 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-10 10:13:35.542720 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.008409
2026-06-10 10:13:35.545962 | 
2026-06-10 10:13:35.546027 | TASK [upload-logs : Upload logs to log server]
2026-06-10 10:13:38.758234 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given
2026-06-10 10:13:38.761949 | 
2026-06-10 10:13:38.762097 | LOOP [upload-logs : Compress console log and json output]