2026-01-20 16:38:27.014616 | Job console starting...
2026-01-20 16:38:27.235897 | Updating repositories
2026-01-20 16:38:28.274628 | Preparing job workspace
2026-01-20 16:38:52.078234 | Running Ansible setup...
2026-01-20 16:38:59.741111 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-20 16:39:00.423688 | 
2026-01-20 16:39:00.423785 | PLAY [localhost]
2026-01-20 16:39:00.436542 | 
2026-01-20 16:39:00.436615 | TASK [Gathering Facts]
2026-01-20 16:39:01.689672 | localhost | ok
2026-01-20 16:39:01.702981 | 
2026-01-20 16:39:01.703799 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-20 16:39:02.367189 | localhost -> localhost | changed
2026-01-20 16:39:02.376231 | 
2026-01-20 16:39:02.384250 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-20 16:39:03.264995 | localhost -> localhost | changed
2026-01-20 16:39:03.274514 | 
2026-01-20 16:39:03.274595 | TASK [Setup log path fact]
2026-01-20 16:39:03.326608 | localhost | ok
2026-01-20 16:39:03.338232 | 
2026-01-20 16:39:03.338332 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-20 16:39:03.375546 | localhost | ok
2026-01-20 16:39:03.384269 | 
2026-01-20 16:39:03.384428 | TASK [emit-job-header : Print job information]
2026-01-20 16:39:03.435659 | # Job Information
2026-01-20 16:39:03.435792 | Ansible Version: 2.15.12
2026-01-20 16:39:03.435821 | Job: cifmw-molecule-shiftstack
2026-01-20 16:39:03.435844 | Pipeline: github-check
2026-01-20 16:39:03.435863 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-01-20 16:39:03.435881 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3451
2026-01-20 16:39:03.435900 | Log URL (when completed): https://logserver.rdoproject.org/55e/rdoproject.org/55e6eb0768914fb284749682e637d3da/
2026-01-20 16:39:03.435919 | Event ID: 1110c990-f61e-11f0-91a3-9d3a1ada832b
2026-01-20 16:39:03.440052 | 
2026-01-20 16:39:03.440142 | LOOP [emit-job-header : Print node information]
2026-01-20 16:39:03.551254 | localhost | ok:
2026-01-20 16:39:03.551539 | localhost | # Node Information
2026-01-20 16:39:03.551578 | localhost | Inventory Hostname: controller
2026-01-20 16:39:03.551606 | localhost | Hostname: np0005589192
2026-01-20 16:39:03.551628 | localhost | Username: zuul
2026-01-20 16:39:03.551651 | localhost | Distro: CentOS 9
2026-01-20 16:39:03.551670 | localhost | Provider: ibm-bm4-nodepool
2026-01-20 16:39:03.551689 | localhost | Region: regionOne
2026-01-20 16:39:03.551707 | localhost | Label: centos-9-stream-crc-2-48-0-xl-ibm
2026-01-20 16:39:03.551724 | localhost | Product Name: OpenStack Compute
2026-01-20 16:39:03.551740 | localhost | Interface IP: 192.168.26.197
2026-01-20 16:39:03.581079 | 
2026-01-20 16:39:03.581174 | PLAY [all]
2026-01-20 16:39:03.599131 | 
2026-01-20 16:39:03.599199 | TASK [Gather network facts]
2026-01-20 16:39:04.088100 | controller | ok
2026-01-20 16:39:04.107646 | 
2026-01-20 16:39:04.107736 | TASK [include_role : start-zuul-console]
2026-01-20 16:39:04.127790 | controller | ok
2026-01-20 16:39:04.143567 | 
2026-01-20 16:39:04.143676 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-20 16:39:04.582170 | controller | ok
2026-01-20 16:39:04.590887 | 
2026-01-20 16:39:04.590956 | TASK [include_role : add-build-sshkey]
2026-01-20 16:39:04.645890 | controller | ok
2026-01-20 16:39:04.680392 | 
2026-01-20 16:39:04.680496 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-20 16:39:05.206258 | controller -> localhost | ok
2026-01-20 16:39:05.211862 | 
2026-01-20 16:39:05.211927 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-20 16:39:05.270884 | controller | ok
2026-01-20 16:39:05.326408 | controller | included: /var/lib/zuul/builds/55e6eb0768914fb284749682e637d3da/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-20 16:39:05.363578 | 
2026-01-20 16:39:05.363696 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-20 16:39:06.546440 | controller -> localhost | Generating public/private rsa key pair.
2026-01-20 16:39:06.546623 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/55e6eb0768914fb284749682e637d3da/work/55e6eb0768914fb284749682e637d3da_id_rsa.
2026-01-20 16:39:06.546657 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/55e6eb0768914fb284749682e637d3da/work/55e6eb0768914fb284749682e637d3da_id_rsa.pub.
2026-01-20 16:39:06.546684 | controller -> localhost | The key fingerprint is:
2026-01-20 16:39:06.546706 | controller -> localhost | SHA256:6jNFYPCzfSbh6b7lzSgRXiYzmQfKtQL/ErnFH5hrKZs zuul-build-sshkey
2026-01-20 16:39:06.546727 | controller -> localhost | The key's randomart image is:
2026-01-20 16:39:06.546746 | controller -> localhost | +---[RSA 3072]----+
2026-01-20 16:39:06.546765 | controller -> localhost | |    ..           |
2026-01-20 16:39:06.546798 | controller -> localhost | |    ..o o        |
2026-01-20 16:39:06.546822 | controller -> localhost | |     =o*.B       |
2026-01-20 16:39:06.546843 | controller -> localhost | |      B=#o=      |
2026-01-20 16:39:06.546862 | controller -> localhost | |      .OS%o.     |
2026-01-20 16:39:06.546894 | controller -> localhost | |      +oO+.      |
2026-01-20 16:39:06.546914 | controller -> localhost | |      .B...      |
2026-01-20 16:39:06.546945 | controller -> localhost | |     .E..o +     |
2026-01-20 16:39:06.546967 | controller -> localhost | |      .oooo o    |
2026-01-20 16:39:06.546985 | controller -> localhost | +----[SHA256]-----+
2026-01-20 16:39:06.547029 | controller -> localhost | ok: Runtime: 0:00:00.156403
2026-01-20 16:39:06.553657 | 
2026-01-20 16:39:06.553729 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-20 16:39:06.623165 | controller | ok
2026-01-20 16:39:06.653396 | controller | included: /var/lib/zuul/builds/55e6eb0768914fb284749682e637d3da/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-20 16:39:06.669858 | 
2026-01-20 16:39:06.669930 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-20 16:39:06.727536 | controller | skipping: Conditional result was False
2026-01-20 16:39:06.734637 | 
2026-01-20 16:39:06.734708 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-20 16:39:07.567601 | controller | changed
2026-01-20 16:39:07.574449 | 
2026-01-20 16:39:07.574518 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-20 16:39:07.841657 | controller | ok
2026-01-20 16:39:07.846827 | 
2026-01-20 16:39:07.846892 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-20 16:39:09.093808 | controller | changed
2026-01-20 16:39:09.104093 | 
2026-01-20 16:39:09.104167 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-20 16:39:10.362941 | controller | changed
2026-01-20 16:39:10.368511 | 
2026-01-20 16:39:10.368586 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-20 16:39:10.419256 | controller | skipping: Conditional result was False
2026-01-20 16:39:10.426635 | 
2026-01-20 16:39:10.426717 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-20 16:39:11.088621 | controller -> localhost | changed
2026-01-20 16:39:11.102712 | 
2026-01-20 16:39:11.102817 | TASK [add-build-sshkey : Add back temp key]
2026-01-20 16:39:11.839534 | controller -> localhost | Identity added: /var/lib/zuul/builds/55e6eb0768914fb284749682e637d3da/work/55e6eb0768914fb284749682e637d3da_id_rsa (zuul-build-sshkey)
2026-01-20 16:39:11.839736 | controller -> localhost | ok: Runtime: 0:00:00.007489
2026-01-20 16:39:11.845983 | 
2026-01-20 16:39:11.846053 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-20 16:39:12.320816 | controller | ok
2026-01-20 16:39:12.325185 | 
2026-01-20 16:39:12.325248 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-20 16:39:12.492810 | controller | skipping: Conditional result was False
2026-01-20 16:39:12.501677 | 
2026-01-20 16:39:12.501985 | TASK [include_role : validate-host]
2026-01-20 16:39:12.555888 | controller | ok
2026-01-20 16:39:12.616791 | 
2026-01-20 16:39:12.616916 | TASK [validate-host : Define zuul_info_dir fact]
2026-01-20 16:39:12.693395 | controller | ok
2026-01-20 16:39:12.698049 | 
2026-01-20 16:39:12.698129 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-01-20 16:39:13.340548 | controller -> localhost | ok
2026-01-20 16:39:13.346494 | 
2026-01-20 16:39:13.346570 | TASK [validate-host : Collect information about the host]
2026-01-20 16:39:14.128483 | controller | ok
2026-01-20 16:39:14.138697 | 
2026-01-20 16:39:14.138794 | TASK [validate-host : Sanitize hostname]
2026-01-20 16:39:14.243564 | controller | ok
2026-01-20 16:39:14.252796 | 
2026-01-20 16:39:14.252889 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-01-20 16:39:14.815422 | controller -> localhost | changed
2026-01-20 16:39:14.822893 | 
2026-01-20 16:39:14.822992 | TASK [validate-host : Collect information about zuul worker]
2026-01-20 16:39:15.417972 | controller | ok
2026-01-20 16:39:15.423276 | 
2026-01-20 16:39:15.423366 | TASK [validate-host : Write out all zuul information for each host]
2026-01-20 16:39:16.738239 | controller -> localhost | changed
2026-01-20 16:39:16.748727 | 
2026-01-20 16:39:16.748805 | TASK [include_role : prepare-workspace-openshift]
2026-01-20 16:39:16.805361 | controller | skipping: Conditional result was False
2026-01-20 16:39:16.810622 | 
2026-01-20 16:39:16.810700 | TASK [include_role : remove-zuul-sshkey]
2026-01-20 16:39:16.880005 | controller | skipping: Conditional result was False
2026-01-20 16:39:16.885549 | 
2026-01-20 16:39:16.885635 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-20 16:39:17.265370 | controller | ok: "logs"
2026-01-20 16:39:17.265607 | controller | ok: All items complete
2026-01-20 16:39:17.265637 | 
2026-01-20 16:39:17.519172 | controller | ok: "artifacts"
2026-01-20 16:39:17.785150 | controller | ok: "docs"
2026-01-20 16:39:17.795500 | 
2026-01-20 16:39:17.795595 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-20 16:39:18.085446 | controller | changed: "logs"
2026-01-20 16:39:18.338283 | controller | changed: "artifacts"
2026-01-20 16:39:18.612102 | controller | changed: "docs"
2026-01-20 16:39:18.688194 | 
2026-01-20 16:39:18.688283 | PLAY RECAP
2026-01-20 16:39:18.688356 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-20 16:39:18.688388 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-20 16:39:18.688409 | 
2026-01-20 16:39:19.091762 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-20 16:39:19.092503 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-20 16:39:20.668680 | 
2026-01-20 16:39:20.668802 | PLAY [all]
2026-01-20 16:39:20.718913 | 
2026-01-20 16:39:20.719041 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-01-20 16:39:20.850694 | controller | ok
2026-01-20 16:39:20.859790 | 
2026-01-20 16:39:20.859875 | TASK [mirror-info-fork : Create /etc/ci]
2026-01-20 16:39:21.371347 | controller | changed
2026-01-20 16:39:21.393158 | 
2026-01-20 16:39:21.393513 | TASK [mirror-info-fork : Install ci_mirror script]
2026-01-20 16:39:23.116356 | controller | changed
2026-01-20 16:39:23.148548 | 
2026-01-20 16:39:23.148655 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-01-20 16:39:23.830912 | controller | changed:
2026-01-20 16:39:23.831096 | controller | {
2026-01-20 16:39:23.831131 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-01-20 16:39:23.831164 | controller | }
2026-01-20 16:39:24.174412 | controller | changed:
2026-01-20 16:39:24.174584 | controller | {
2026-01-20 16:39:24.174629 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-01-20 16:39:24.174656 | controller | }
2026-01-20 16:39:24.501681 | controller | changed:
2026-01-20 16:39:24.501775 | controller | {
2026-01-20 16:39:24.501804 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-01-20 16:39:24.501827 | controller | }
2026-01-20 16:39:24.817750 | controller | changed:
2026-01-20 16:39:24.817837 | controller | {
2026-01-20 16:39:24.817866 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-01-20 16:39:24.817890 | controller | }
2026-01-20 16:39:25.127199 | controller | changed:
2026-01-20 16:39:25.127305 | controller | {
2026-01-20 16:39:25.127344 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-01-20 16:39:25.127370 | controller | }
2026-01-20 16:39:25.429631 | controller | changed:
2026-01-20 16:39:25.429713 | controller | {
2026-01-20 16:39:25.429741 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-01-20 16:39:25.429764 | controller | }
2026-01-20 16:39:25.758122 | controller | changed:
2026-01-20 16:39:25.758202 | controller | {
2026-01-20 16:39:25.758230 | 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-01-20 16:39:25.758254 | controller | }
2026-01-20 16:39:26.066682 | controller | changed:
2026-01-20 16:39:26.066777 | controller | {
2026-01-20 16:39:26.066807 | 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-01-20 16:39:26.066830 | controller | }
2026-01-20 16:39:26.365474 | controller | changed:
2026-01-20 16:39:26.365577 | controller | {
2026-01-20 16:39:26.365612 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-01-20 16:39:26.365639 | controller | }
2026-01-20 16:39:26.663548 | controller | changed:
2026-01-20 16:39:26.663639 | controller | {
2026-01-20 16:39:26.663665 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-01-20 16:39:26.663685 | controller | }
2026-01-20 16:39:26.968068 | controller | changed:
2026-01-20 16:39:26.968159 | controller | {
2026-01-20 16:39:26.968187 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-01-20 16:39:26.968208 | controller | }
2026-01-20 16:39:27.282733 | controller | changed:
2026-01-20 16:39:27.282827 | controller | {
2026-01-20 16:39:27.282855 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-01-20 16:39:27.282876 | controller | }
2026-01-20 16:39:27.591151 | controller | changed:
2026-01-20 16:39:27.591240 | controller | {
2026-01-20 16:39:27.591267 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2026-01-20 16:39:27.591289 | controller | }
2026-01-20 16:39:27.908043 | controller | changed:
2026-01-20 16:39:27.908160 | controller | {
2026-01-20 16:39:27.908188 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-01-20 16:39:27.908207 | controller | }
2026-01-20 16:39:28.217612 | controller | changed:
2026-01-20 16:39:28.217708 | controller | {
2026-01-20 16:39:28.217735 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-01-20 16:39:28.217755 | controller | }
2026-01-20 16:39:28.542521 | controller | changed:
2026-01-20 16:39:28.542608 | controller | {
2026-01-20 16:39:28.542637 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-01-20 16:39:28.542657 | controller | }
2026-01-20 16:39:28.873591 | controller | changed:
2026-01-20 16:39:28.873876 | controller | {
2026-01-20 16:39:28.873910 | 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-01-20 16:39:28.873934 | controller | }
2026-01-20 16:39:29.200874 | controller | changed:
2026-01-20 16:39:29.200981 | controller | {
2026-01-20 16:39:29.201008 | 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-01-20 16:39:29.201040 | controller | }
2026-01-20 16:39:29.501243 | controller | changed:
2026-01-20 16:39:29.501353 | controller | {
2026-01-20 16:39:29.501382 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-01-20 16:39:29.501402 | controller | }
2026-01-20 16:39:29.801870 | controller | changed:
2026-01-20 16:39:29.801967 | controller | {
2026-01-20 16:39:29.801995 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-01-20 16:39:29.802017 | controller | }
2026-01-20 16:39:30.110832 | controller | changed:
2026-01-20 16:39:30.110927 | controller | {
2026-01-20 16:39:30.110954 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-01-20 16:39:30.110974 | controller | }
2026-01-20 16:39:30.402146 | controller | changed:
2026-01-20 16:39:30.402249 | controller | {
2026-01-20 16:39:30.402289 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-01-20 16:39:30.402343 | controller | }
2026-01-20 16:39:30.715480 | controller | changed:
2026-01-20 16:39:30.715577 | controller | {
2026-01-20 16:39:30.715608 | 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-01-20 16:39:30.715630 | controller | }
2026-01-20 16:39:31.026785 | controller | changed:
2026-01-20 16:39:31.026956 | controller | {
2026-01-20 16:39:31.027000 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-01-20 16:39:31.027027 | controller | }
2026-01-20 16:39:31.329764 | controller | changed:
2026-01-20 16:39:31.329857 | controller | {
2026-01-20 16:39:31.329888 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-01-20 16:39:31.329910 | controller | }
2026-01-20 16:39:31.666422 | controller | changed:
2026-01-20 16:39:31.666511 | controller | {
2026-01-20 16:39:31.666539 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-01-20 16:39:31.666560 | controller | }
2026-01-20 16:39:31.688867 | 
2026-01-20 16:39:31.688978 | TASK [Set timezone to UTC]
2026-01-20 16:39:32.282511 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-01-20 16:39:32.303208 | 
2026-01-20 16:39:32.303334 | TASK [Create nodepool directory]
2026-01-20 16:39:32.628648 | controller | changed
2026-01-20 16:39:32.640251 | 
2026-01-20 16:39:32.640351 | TASK [Create nodepool sub_nodes file]
2026-01-20 16:39:33.651622 | controller | changed
2026-01-20 16:39:33.657460 | 
2026-01-20 16:39:33.657527 | TASK [Create nodepool sub_nodes_private file]
2026-01-20 16:39:34.650991 | controller | changed
2026-01-20 16:39:34.656379 | 
2026-01-20 16:39:34.656446 | LOOP [Populate nodepool sub_nodes file]
2026-01-20 16:39:34.685325 | 
2026-01-20 16:39:34.685461 | LOOP [Populate nodepool sub_nodes_private file]
2026-01-20 16:39:34.714594 | 
2026-01-20 16:39:34.714747 | TASK [Create nodepool primary file]
2026-01-20 16:39:34.738444 | controller | skipping: Conditional result was False
2026-01-20 16:39:34.745316 | 
2026-01-20 16:39:34.745396 | TASK [Create nodepool node_private for this node]
2026-01-20 16:39:35.848755 | controller | changed
2026-01-20 16:39:35.860429 | 
2026-01-20 16:39:35.860544 | LOOP [Copy ssh keys to nodepool directory]
2026-01-20 16:39:36.317322 | controller | ok: Item: id_rsa Runtime: 0:00:00.005969
2026-01-20 16:39:36.317505 | 
2026-01-20 16:39:36.585323 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004984
2026-01-20 16:39:36.601142 | 
2026-01-20 16:39:36.601233 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-01-20 16:39:37.692043 | controller | changed
2026-01-20 16:39:37.697212 | 
2026-01-20 16:39:37.697274 | TASK [Validate sudoers config after edits]
2026-01-20 16:39:37.990956 | controller | /etc/sudoers: parsed OK
2026-01-20 16:39:37.991016 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-01-20 16:39:37.991024 | controller | /etc/sudoers.d/zuul: parsed OK
2026-01-20 16:39:37.991030 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-01-20 16:39:38.226171 | controller | ok: Runtime: 0:00:00.005877
2026-01-20 16:39:38.232552 | 
2026-01-20 16:39:38.232628 | TASK [Show the environment passed in to job shell scripts]
2026-01-20 16:39:38.517577 | controller | SHELL=/bin/bash
2026-01-20 16:39:38.517639 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-01-20 16:39:38.517689 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-01-20 16:39:38.517699 | controller | ZUUL_CHANGES=rdo-jobs:master:refs/changes/88/58488/2^openstack-k8s-operators/ci-framework:main:refs/changes/51/3451/3b80a23c9439dd2e7554486894e6cc29bcabd667
2026-01-20 16:39:38.517706 | controller | PWD=/home/zuul
2026-01-20 16:39:38.517712 | controller | ZUUL_PIPELINE=github-check
2026-01-20 16:39:38.517717 | controller | LOGNAME=zuul
2026-01-20 16:39:38.517722 | controller | XDG_SESSION_TYPE=tty
2026-01-20 16:39:38.517728 | controller | _=/usr/bin/env
2026-01-20 16:39:38.517733 | controller | MOTD_SHOWN=pam
2026-01-20 16:39:38.517738 | controller | HOME=/home/zuul
2026-01-20 16:39:38.517743 | controller | LANG=en_US.UTF-8
2026-01-20 16:39:38.517748 | controller | SSH_CONNECTION=192.168.26.12 33978 192.168.26.197 22
2026-01-20 16:39:38.517754 | 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-01-20 16:39:38.517761 | controller | ZUUL_CHANGE_IDS=58488,2 3451,3b80a23c9439dd2e7554486894e6cc29bcabd667
2026-01-20 16:39:38.517767 | controller | WORKSPACE=/home/zuul/workspace
2026-01-20 16:39:38.517772 | controller | XDG_SESSION_CLASS=user
2026-01-20 16:39:38.517796 | controller | SELINUX_ROLE_REQUESTED=
2026-01-20 16:39:38.517801 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-01-20 16:39:38.517807 | controller | USER=zuul
2026-01-20 16:39:38.517812 | controller | ZUUL_VOTING=True
2026-01-20 16:39:38.517818 | controller | BUILD_TIMEOUT=1800000
2026-01-20 16:39:38.517823 | controller | SELINUX_USE_CURRENT_RANGE=
2026-01-20 16:39:38.517828 | controller | SHLVL=1
2026-01-20 16:39:38.517833 | controller | ZUUL_PATCHSET=3b80a23c9439dd2e7554486894e6cc29bcabd667
2026-01-20 16:39:38.517839 | controller | XDG_SESSION_ID=1
2026-01-20 16:39:38.517844 | controller | ZUUL_BRANCH=main
2026-01-20 16:39:38.517849 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-01-20 16:39:38.517854 | controller | SSH_CLIENT=192.168.26.12 33978 22
2026-01-20 16:39:38.517861 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-01-20 16:39:38.517867 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-01-20 16:39:38.517872 | controller | which_declare=declare -f
2026-01-20 16:39:38.517878 | 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-01-20 16:39:38.517884 | controller | SELINUX_LEVEL_REQUESTED=
2026-01-20 16:39:38.517889 | controller | ZUUL_CHANGE=3451
2026-01-20 16:39:38.517894 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-01-20 16:39:38.517899 | controller | ZUUL_UUID=55e6eb0768914fb284749682e637d3da
2026-01-20 16:39:38.517938 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-01-20 16:39:38.517945 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-01-20 16:39:38.517951 | controller | }
2026-01-20 16:39:38.762171 | controller | ok: Runtime: 0:00:00.006012
2026-01-20 16:39:38.767890 | 
2026-01-20 16:39:38.767956 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-01-20 16:39:38.782406 | controller | skipping: Conditional result was False
2026-01-20 16:39:38.810921 | 
2026-01-20 16:39:38.811019 | TASK [Symlink /home/zuul-worker/workspace]
2026-01-20 16:39:39.336415 | controller | skipping: Conditional result was False
2026-01-20 16:39:39.342995 | 
2026-01-20 16:39:39.343076 | TASK [Ensure legacy workspace directory]
2026-01-20 16:39:39.597164 | controller | changed
2026-01-20 16:39:39.623517 | 
2026-01-20 16:39:39.623604 | PLAY RECAP
2026-01-20 16:39:39.623654 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-20 16:39:39.623678 | 
2026-01-20 16:39:39.711308 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-20 16:39:39.712039 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-20 16:39:40.247354 | 
2026-01-20 16:39:40.247463 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-01-20 16:39:40.269219 | 
2026-01-20 16:39:40.269347 | TASK [Create zuul-output directory]
2026-01-20 16:39:40.674828 | controller | changed
2026-01-20 16:39:40.679996 | 
2026-01-20 16:39:40.680108 | TASK [Slurp Zuul inventory test]
2026-01-20 16:39:40.967789 | controller -> localhost | ok
2026-01-20 16:39:40.974939 | 
2026-01-20 16:39:40.975025 | TASK [Save zuul inventory]
2026-01-20 16:39:42.216391 | controller | changed
2026-01-20 16:39:42.222290 | 
2026-01-20 16:39:42.222395 | TASK [Save zuul vars without the change_message]
2026-01-20 16:39:43.311941 | controller | changed
2026-01-20 16:39:43.339665 | 
2026-01-20 16:39:43.339741 | PLAY RECAP
2026-01-20 16:39:43.339787 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-20 16:39:43.339810 | 
2026-01-20 16:39:43.427461 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-20 16:39:43.428323 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-01-20 16:39:44.042952 | 
2026-01-20 16:39:44.043059 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-01-20 16:39:44.064429 | 
2026-01-20 16:39:44.064506 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-01-20 16:39:44.092892 | controller | ok
2026-01-20 16:39:44.110603 | 
2026-01-20 16:39:44.110693 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-01-20 16:39:44.144013 | controller | skipping: Conditional result was False
2026-01-20 16:39:44.149793 | 
2026-01-20 16:39:44.149871 | TASK [mirror-info-fork : Create /etc/ci]
2026-01-20 16:39:44.564067 | controller | ok
2026-01-20 16:39:44.570029 | 
2026-01-20 16:39:44.570121 | TASK [mirror-info-fork : Install ci_mirror script]
2026-01-20 16:39:45.536977 | controller | ok
2026-01-20 16:39:45.546875 | 
2026-01-20 16:39:45.546946 | TASK [Prepare workspace]
2026-01-20 16:39:45.564838 | controller | ok
2026-01-20 16:39:45.581991 | 
2026-01-20 16:39:45.582067 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-20 16:39:45.956032 | controller | ok
2026-01-20 16:39:45.961840 | 
2026-01-20 16:39:45.961913 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-20 16:39:49.050977 | controller | Output suppressed because no_log was given
2026-01-20 16:39:49.061336 | 
2026-01-20 16:39:49.061446 | LOOP [Create zuul-output directory]
2026-01-20 16:39:49.314907 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-01-20 16:39:49.542860 | controller | ok: "/home/zuul/zuul-output/logs"
2026-01-20 16:39:49.549718 | 
2026-01-20 16:39:49.549824 | TASK [Install required packages]
2026-01-20 16:40:07.922047 | controller | ok: Nothing to do
2026-01-20 16:40:07.927500 | 
2026-01-20 16:40:07.927563 | TASK [Install venv]
2026-01-20 16:41:09.780653 | controller | changed
2026-01-20 16:41:09.806811 | 
2026-01-20 16:41:09.806886 | PLAY RECAP
2026-01-20 16:41:09.806930 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-01-20 16:41:09.806954 | 
2026-01-20 16:41:09.888028 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-01-20 16:41:09.888877 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-01-20 16:41:10.432495 | 
2026-01-20 16:41:10.432693 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-01-20 16:41:10.454406 | 
2026-01-20 16:41:10.454502 | TASK [Gather required facts]
2026-01-20 16:41:11.001469 | controller | ok
2026-01-20 16:41:11.006930 | 
2026-01-20 16:41:11.007009 | TASK [Load environment var if instructed to]
2026-01-20 16:41:11.031119 | controller | skipping: Conditional result was False
2026-01-20 16:41:11.036600 | 
2026-01-20 16:41:11.036672 | TASK [Ensure group_vars dir exists]
2026-01-20 16:41:11.420289 | controller | ok
2026-01-20 16:41:11.425599 | 
2026-01-20 16:41:11.425671 | TASK [Print related variables]
2026-01-20 16:41:11.454232 | controller | ok:
2026-01-20 16:41:11.454395 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-01-20 16:41:11.454428 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack
2026-01-20 16:41:11.459735 | 
2026-01-20 16:41:11.459800 | TASK [Run molecule]
2026-01-20 16:41:12.489856 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-01-20 16:41:12.560814 | controller | INFO     Performing prerun with role_name_check=0...
2026-01-20 16:41:29.039339 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-20 16:41:29.039738 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-20 16:41:29.040202 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-20 16:41:29.040657 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-20 16:41:29.041133 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+3b80a23c was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.0.0+3b80a23c (/home/zuul/.ansible/collections/ansible_collections).
2026-01-20 16:41:29.041586 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-20 16:41:29.042025 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-20 16:41:29.042474 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-20 16:41:29.042898 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-20 16:41:29.043358 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-20 16:41:29.043784 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-20 16:41:29.044248 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-20 16:41:29.044694 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-20 16:41:29.045150 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-20 16:41:29.045604 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-20 16:41:29.054674 | controller | INFO     Running default > prepare
2026-01-20 16:41:29.742779 | controller |
2026-01-20 16:41:29.742816 | controller | PLAY [Prepare] *****************************************************************
2026-01-20 16:41:29.742938 | controller |
2026-01-20 16:41:29.743052 | controller | TASK [Gathering Facts] *********************************************************
2026-01-20 16:41:29.743157 | controller | Tuesday 20 January 2026  16:41:29 +0000 (0:00:00.020)       0:00:00.020 *******
2026-01-20 16:41:30.588891 | controller | ok: [instance]
2026-01-20 16:41:30.588947 | controller |
2026-01-20 16:41:30.589067 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2026-01-20 16:41:30.589176 | controller | Tuesday 20 January 2026  16:41:30 +0000 (0:00:00.846)       0:00:00.867 *******
2026-01-20 16:41:30.606497 | controller | skipping: [instance]
2026-01-20 16:41:30.606541 | controller |
2026-01-20 16:41:30.606722 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2026-01-20 16:41:30.606813 | controller | Tuesday 20 January 2026  16:41:30 +0000 (0:00:00.017)       0:00:00.884 *******
2026-01-20 16:41:30.647625 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2026-01-20 16:41:30.647699 | controller |
2026-01-20 16:41:30.647850 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2026-01-20 16:41:30.648014 | controller | Tuesday 20 January 2026  16:41:30 +0000 (0:00:00.041)       0:00:00.926 *******
2026-01-20 16:41:30.928316 | controller | ok: [instance]
2026-01-20 16:41:30.928356 | controller |
2026-01-20 16:41:30.928478 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2026-01-20 16:41:30.928575 | controller | Tuesday 20 January 2026  16:41:30 +0000 (0:00:00.280)       0:00:01.206 *******
2026-01-20 16:41:31.238757 | controller | ok: [instance]
2026-01-20 16:41:31.238833 | controller |
2026-01-20 16:41:31.238938 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2026-01-20 16:41:31.239069 | controller | Tuesday 20 January 2026  16:41:31 +0000 (0:00:00.310)       0:00:01.517 *******
2026-01-20 16:41:31.966277 | controller | ok: [instance] => (item=ubi-9-appstream)
2026-01-20 16:41:31.966968 | controller | ok: [instance] => (item=ubi-9-baseos)
2026-01-20 16:41:32.026116 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2026-01-20 16:41:32.026158 | controller |
2026-01-20 16:41:32.026168 | controller | TASK [Deploy repo-setup] *******************************************************
2026-01-20 16:41:32.026175 | controller | Tuesday 20 January 2026  16:41:31 +0000 (0:00:00.727)       0:00:02.244 *******
2026-01-20 16:41:32.026188 | controller |
2026-01-20 16:41:32.564030 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2026-01-20 16:41:32.564077 | controller | Tuesday 20 January 2026  16:41:32 +0000 (0:00:00.059)       0:00:02.304 *******
2026-01-20 16:41:32.564091 | controller | changed: [instance] => (item=tmp)
2026-01-20 16:41:33.404084 | controller | changed: [instance] => (item=artifacts/repositories)
2026-01-20 16:41:33.404124 | controller | changed: [instance] => (item=venv/repo_setup)
2026-01-20 16:41:33.404133 | controller |
2026-01-20 16:41:33.404140 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2026-01-20 16:41:33.404148 | controller | Tuesday 20 January 2026  16:41:32 +0000 (0:00:00.537)       0:00:02.841 *******
2026-01-20 16:41:33.404169 | controller | ok: [instance]
2026-01-20 16:41:33.404212 | controller |
2026-01-20 16:41:33.404221 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2026-01-20 16:41:33.404235 | controller | Tuesday 20 January 2026  16:41:33 +0000 (0:00:00.840)       0:00:03.681 *******
2026-01-20 16:41:34.472568 | controller | changed: [instance]
2026-01-20 16:41:42.384780 | controller |
2026-01-20 16:41:42.384816 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2026-01-20 16:41:42.384825 | controller | Tuesday 20 January 2026  16:41:34 +0000 (0:00:01.068)       0:00:04.750 *******
2026-01-20 16:41:42.384835 | controller | changed: [instance]
2026-01-20 16:41:43.104875 | controller |
2026-01-20 16:41:43.104929 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2026-01-20 16:41:43.104941 | controller | Tuesday 20 January 2026  16:41:42 +0000 (0:00:07.912)       0:00:12.662 *******
2026-01-20 16:41:43.104951 | controller | changed: [instance]
2026-01-20 16:41:43.127883 | controller |
2026-01-20 16:41:43.127941 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2026-01-20 16:41:43.127954 | controller | Tuesday 20 January 2026  16:41:43 +0000 (0:00:00.720)       0:00:13.382 *******
2026-01-20 16:41:43.127966 | controller | skipping: [instance]
2026-01-20 16:41:44.496143 | controller |
2026-01-20 16:41:44.496172 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2026-01-20 16:41:44.496180 | controller | Tuesday 20 January 2026  16:41:43 +0000 (0:00:00.023)       0:00:13.405 *******
2026-01-20 16:41:44.496190 | controller | changed: [instance]
2026-01-20 16:41:44.532097 | controller |
2026-01-20 16:41:44.532127 | controller | TASK [repo_setup : Get component repo] *****************************************
2026-01-20 16:41:44.532136 | controller | Tuesday 20 January 2026  16:41:44 +0000 (0:00:01.368)       0:00:14.774 *******
2026-01-20 16:41:44.532148 | controller | skipping: [instance]
2026-01-20 16:41:44.532177 | controller |
2026-01-20 16:41:44.532191 | controller | TASK [repo_setup : Rename component repo] **************************************
2026-01-20 16:41:44.532278 | controller | Tuesday 20 January 2026  16:41:44 +0000 (0:00:00.036)       0:00:14.810 *******
2026-01-20 16:41:44.569877 | controller | skipping: [instance]
2026-01-20 16:41:44.605651 | controller |
2026-01-20 16:41:44.605671 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2026-01-20 16:41:44.605679 | controller | Tuesday 20 January 2026  16:41:44 +0000 (0:00:00.037)       0:00:14.847 *******
2026-01-20 16:41:44.605690 | controller | skipping: [instance]
2026-01-20 16:41:45.132985 | controller |
2026-01-20 16:41:45.133018 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2026-01-20 16:41:45.133026 | controller | Tuesday 20 January 2026  16:41:44 +0000 (0:00:00.036)       0:00:14.883 *******
2026-01-20 16:41:45.133037 | controller | changed: [instance]
2026-01-20 16:41:45.579549 | controller |
2026-01-20 16:41:45.579579 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2026-01-20 16:41:45.579587 | controller | Tuesday 20 January 2026  16:41:45 +0000 (0:00:00.527)       0:00:15.410 *******
2026-01-20 16:41:45.579597 | controller | changed: [instance]
2026-01-20 16:41:45.608852 | controller |
2026-01-20 16:41:45.608874 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2026-01-20 16:41:45.608882 | controller | Tuesday 20 January 2026  16:41:45 +0000 (0:00:00.446)       0:00:15.857 *******
2026-01-20 16:41:45.608891 | controller | skipping: [instance]
2026-01-20 16:41:45.636965 | controller |
2026-01-20 16:41:45.636984 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2026-01-20 16:41:45.636992 | controller | Tuesday 20 January 2026  16:41:45 +0000 (0:00:00.029)       0:00:15.886 *******
2026-01-20 16:41:45.637001 | controller | skipping: [instance]
2026-01-20 16:41:45.637260 | controller |
2026-01-20 16:41:45.665564 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2026-01-20 16:41:45.665586 | controller | Tuesday 20 January 2026  16:41:45 +0000 (0:00:00.028)       0:00:15.915 *******
2026-01-20 16:41:45.665603 | controller | skipping: [instance]
2026-01-20 16:41:45.703695 | controller |
2026-01-20 16:41:45.703715 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2026-01-20 16:41:45.703722 | controller | Tuesday 20 January 2026  16:41:45 +0000 (0:00:00.028)       0:00:15.943 *******
2026-01-20 16:41:45.703732 | controller | ok: [instance]
2026-01-20 16:41:45.729989 | controller |
2026-01-20 16:41:45.730051 | controller | TASK [repo_setup : Create download directory] **********************************
2026-01-20 16:41:45.730059 | controller | Tuesday 20 January 2026  16:41:45 +0000 (0:00:00.038)       0:00:15.981 *******
2026-01-20 16:41:45.730075 | controller | skipping: [instance]
2026-01-20 16:41:45.730117 | controller |
2026-01-20 16:41:45.730126 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2026-01-20 16:41:45.759015 | controller | Tuesday 20 January 2026  16:41:45 +0000 (0:00:00.026)       0:00:16.008 *******
2026-01-20 16:41:45.759062 | controller | skipping: [instance]
2026-01-20 16:41:45.759171 | controller |
2026-01-20 16:41:45.759182 | controller | TASK [Download the RPM] ********************************************************
2026-01-20 16:41:45.759192 | controller | Tuesday 20 January 2026  16:41:45 +0000 (0:00:00.029)       0:00:16.037 *******
2026-01-20 16:41:45.785887 | controller | skipping: [instance]
2026-01-20 16:41:45.786053 | controller |
2026-01-20 16:41:45.786178 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2026-01-20 16:41:45.786289 | controller | Tuesday 20 January 2026  16:41:45 +0000 (0:00:00.027)       0:00:16.064 *******
2026-01-20 16:41:45.814069 | controller | skipping: [instance]
2026-01-20 16:41:45.814175 | controller |
2026-01-20 16:41:45.814312 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2026-01-20 16:41:45.814464 | controller | Tuesday 20 January 2026  16:41:45 +0000 (0:00:00.028)       0:00:16.092 *******
2026-01-20 16:41:45.841081 | controller | skipping: [instance]
2026-01-20 16:41:45.841236 | controller |
2026-01-20 16:41:45.841354 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2026-01-20 16:41:45.841535 | controller | Tuesday 20 January 2026  16:41:45 +0000 (0:00:00.027)       0:00:16.119 *******
2026-01-20 16:41:45.869895 | controller | skipping: [instance]
2026-01-20 16:41:45.870081 | controller |
2026-01-20 16:41:45.870241 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2026-01-20 16:41:45.870380 | controller | Tuesday 20 January 2026  16:41:45 +0000 (0:00:00.028)       0:00:16.148 *******
2026-01-20 16:41:45.897540 | controller | skipping: [instance]
2026-01-20 16:41:45.897681 | controller |
2026-01-20 16:41:45.897830 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2026-01-20 16:41:45.897998 | controller | Tuesday 20 January 2026  16:41:45 +0000 (0:00:00.027)       0:00:16.175 *******
2026-01-20 16:41:46.089857 | controller | ok: [instance]
2026-01-20 16:41:46.304438 | controller |
2026-01-20 16:41:46.304468 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2026-01-20 16:41:46.304477 | controller | Tuesday 20 January 2026  16:41:46 +0000 (0:00:00.191)       0:00:16.367 *******
2026-01-20 16:41:46.304486 | controller | changed: [instance]
2026-01-20 16:41:46.534653 | controller |
2026-01-20 16:41:46.534681 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2026-01-20 16:41:46.534689 | controller | Tuesday 20 January 2026  16:41:46 +0000 (0:00:00.214)       0:00:16.582 *******
2026-01-20 16:41:46.534699 | controller | changed: [instance]
2026-01-20 16:41:46.556042 | controller |
2026-01-20 16:41:46.556105 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2026-01-20 16:41:46.556115 | controller | Tuesday 20 January 2026  16:41:46 +0000 (0:00:00.230)       0:00:16.812 *******
2026-01-20 16:41:46.556133 | controller | skipping: [instance]
2026-01-20 16:41:46.578453 | controller |
2026-01-20 16:41:46.578488 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2026-01-20 16:41:46.578498 | controller | Tuesday 20 January 2026  16:41:46 +0000 (0:00:00.021)       0:00:16.834 *******
2026-01-20 16:41:46.578510 | controller | skipping: [instance]
2026-01-20 16:41:46.599975 | controller |
2026-01-20 16:41:46.600019 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2026-01-20 16:41:46.600027 | controller | Tuesday 20 January 2026  16:41:46 +0000 (0:00:00.022)       0:00:16.856 *******
2026-01-20 16:41:46.600045 | controller | skipping: [instance]
2026-01-20 16:41:46.600080 | controller |
2026-01-20 16:41:46.600395 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2026-01-20 16:41:46.621936 | controller | Tuesday 20 January 2026  16:41:46 +0000 (0:00:00.021)       0:00:16.878 *******
2026-01-20 16:41:46.621966 | controller | skipping: [instance]
2026-01-20 16:41:46.643873 | controller |
2026-01-20 16:41:46.643896 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2026-01-20 16:41:46.643903 | controller | Tuesday 20 January 2026  16:41:46 +0000 (0:00:00.021)       0:00:16.900 *******
2026-01-20 16:41:46.643934 | controller | skipping: [instance]
2026-01-20 16:41:46.665672 | controller |
2026-01-20 16:41:46.665702 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2026-01-20 16:41:46.665718 | controller | Tuesday 20 January 2026  16:41:46 +0000 (0:00:00.021)       0:00:16.922 *******
2026-01-20 16:41:46.665731 | controller | skipping: [instance]
2026-01-20 16:41:46.665880 | controller |
2026-01-20 16:41:46.666054 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2026-01-20 16:41:46.666200 | controller | Tuesday 20 January 2026  16:41:46 +0000 (0:00:00.021)       0:00:16.944 *******
2026-01-20 16:41:46.684770 | controller | skipping: [instance]
2026-01-20 16:41:46.684946 | controller |
2026-01-20 16:41:46.685104 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2026-01-20 16:41:46.685243 | controller | Tuesday 20 January 2026  16:41:46 +0000 (0:00:00.019)       0:00:16.963 *******
2026-01-20 16:41:46.714184 | controller | skipping: [instance]
2026-01-20 16:41:46.714395 | controller |
2026-01-20 16:41:46.714573 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2026-01-20 16:41:46.714738 | controller | Tuesday 20 January 2026  16:41:46 +0000 (0:00:00.029)       0:00:16.992 *******
2026-01-20 16:41:46.731688 | controller | skipping: [instance]
2026-01-20 16:41:46.731869 | controller |
2026-01-20 16:41:46.732045 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2026-01-20 16:41:46.732200 | controller | Tuesday 20 January 2026  16:41:46 +0000 (0:00:00.017)       0:00:17.010 *******
2026-01-20 16:41:46.753165 | controller | skipping: [instance]
2026-01-20 16:41:46.753373 | controller |
2026-01-20 16:41:46.753563 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2026-01-20 16:41:46.753733 | controller | Tuesday 20 January 2026  16:41:46 +0000 (0:00:00.021)       0:00:17.031 *******
2026-01-20 16:41:46.782665 | controller | skipping: [instance]
2026-01-20 16:41:46.782862 | controller |
2026-01-20 16:41:46.783044 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2026-01-20 16:41:46.783189 | controller | Tuesday 20 January 2026  16:41:46 +0000 (0:00:00.029)       0:00:17.061 *******
2026-01-20 16:41:46.813579 | controller | skipping: [instance]
2026-01-20 16:41:46.813745 | controller |
2026-01-20 16:41:46.813891 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2026-01-20 16:41:46.814057 | controller | Tuesday 20 January 2026  16:41:46 +0000 (0:00:00.030)       0:00:17.091 *******
2026-01-20 16:41:46.844178 | controller | skipping: [instance]
2026-01-20 16:41:46.844387 | controller |
2026-01-20 16:41:46.844573 | controller | TASK [test_deps : Install additional packages] *********************************
2026-01-20 16:41:46.844777 | controller | Tuesday 20 January 2026  16:41:46 +0000 (0:00:00.030)       0:00:17.122 *******
2026-01-20 16:41:46.874659 | controller | skipping: [instance]
2026-01-20 16:41:46.874947 | controller |
2026-01-20 16:41:46.875181 | controller | TASK [test_deps : Install selinux python libs] *********************************
2026-01-20 16:41:46.875324 | controller | Tuesday 20 January 2026  16:41:46 +0000 (0:00:00.030)       0:00:17.153 *******
2026-01-20 16:42:49.194025 | controller | ok: [instance]
2026-01-20 16:42:49.194083 | controller |
2026-01-20 16:42:49.194309 | controller | TASK [test_deps : Install python yaml libs] ************************************
2026-01-20 16:42:50.456959 | controller | Tuesday 20 January 2026  16:42:49 +0000 (0:01:02.319)       0:01:19.472 *******
2026-01-20 16:42:50.456997 | controller | ok: [instance]
2026-01-20 16:42:50.489131 | controller |
2026-01-20 16:42:50.489172 | controller | TASK [test_deps : Install extra packages] **************************************
2026-01-20 16:42:50.489183 | controller | Tuesday 20 January 2026  16:42:50 +0000 (0:00:01.262)       0:01:20.734 *******
2026-01-20 16:42:50.489196 | controller | skipping: [instance]
2026-01-20 16:42:50.531958 | controller |
2026-01-20 16:42:50.531991 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2026-01-20 16:42:50.531999 | controller | Tuesday 20 January 2026  16:42:50 +0000 (0:00:00.032)       0:01:20.767 *******
2026-01-20 16:42:50.532010 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2026-01-20 16:42:50.551067 | controller |
2026-01-20 16:42:50.551106 | controller | TASK [ci_setup : List packages to install] *************************************
2026-01-20 16:42:50.551116 | controller | Tuesday 20 January 2026  16:42:50 +0000 (0:00:00.042)       0:01:20.810 *******
2026-01-20 16:42:50.551141 | controller | ok: [instance] =>
2026-01-20 16:42:50.551201 | controller |   cifmw_ci_setup_packages:
2026-01-20 16:42:50.551210 | controller |   - bash-completion
2026-01-20 16:42:50.551217 | controller |   - ca-certificates
2026-01-20 16:42:50.551225 | controller |   - git-core
2026-01-20 16:42:50.551251 | controller |   - make
2026-01-20 16:42:50.551830 | controller |   - tar
2026-01-20 16:42:58.238384 | controller |   - tmux
2026-01-20 16:42:58.238421 | controller |   - python3-pip
2026-01-20 16:42:58.238429 | controller |
2026-01-20 16:42:58.238443 | controller | TASK [ci_setup : Install needed packages] **************************************
2026-01-20 16:42:58.238455 | controller | Tuesday 20 January 2026  16:42:50 +0000 (0:00:00.019)       0:01:20.829 *******
2026-01-20 16:42:58.238467 | controller | changed: [instance]
2026-01-20 16:42:58.238599 | controller |
2026-01-20 16:42:58.238632 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2026-01-20 16:42:58.848858 | controller | Tuesday 20 January 2026  16:42:58 +0000 (0:00:07.687)       0:01:28.516 *******
2026-01-20 16:42:58.848917 | controller | ok: [instance]
2026-01-20 16:42:58.849179 | controller |
2026-01-20 16:42:58.849211 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2026-01-20 16:42:59.053421 | controller | Tuesday 20 January 2026  16:42:58 +0000 (0:00:00.610)       0:01:29.127 *******
2026-01-20 16:42:59.053463 | controller | changed: [instance]
2026-01-20 16:42:59.053666 | controller |
2026-01-20 16:42:59.053685 | controller | TASK [ci_setup : Install openshift client] *************************************
2026-01-20 16:43:05.040709 | controller | Tuesday 20 January 2026  16:42:59 +0000 (0:00:00.204)       0:01:29.331 *******
2026-01-20 16:43:05.040746 | controller | changed: [instance]
2026-01-20 16:43:05.074076 | controller |
2026-01-20 16:43:05.074117 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2026-01-20 16:43:05.074125 | controller | Tuesday 20 January 2026  16:43:05 +0000 (0:00:05.987)       0:01:35.318 *******
2026-01-20 16:43:05.074137 | controller | skipping: [instance]
2026-01-20 16:43:05.074165 | controller |
2026-01-20 16:43:05.074173 | controller | TASK [ci_setup : Create completion file] ***************************************
2026-01-20 16:43:05.074288 | controller | Tuesday 20 January 2026  16:43:05 +0000 (0:00:00.033)       0:01:35.352 *******
2026-01-20 16:43:05.345929 | controller | changed: [instance]
2026-01-20 16:43:05.627060 | controller |
2026-01-20 16:43:05.627100 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2026-01-20 16:43:05.627110 | controller | Tuesday 20 January 2026  16:43:05 +0000 (0:00:00.271)       0:01:35.623 *******
2026-01-20 16:43:05.627123 | controller | changed: [instance]
2026-01-20 16:43:05.644055 | controller |
2026-01-20 16:43:05.644096 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2026-01-20 16:43:05.644106 | controller | Tuesday 20 January 2026  16:43:05 +0000 (0:00:00.280)       0:01:35.904 *******
2026-01-20 16:43:05.644119 | controller | skipping: [instance]
2026-01-20 16:43:05.644330 | controller |
2026-01-20 16:43:05.644367 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2026-01-20 16:43:05.660562 | controller | Tuesday 20 January 2026  16:43:05 +0000 (0:00:00.017)       0:01:35.922 *******
2026-01-20 16:43:05.660616 | controller | skipping: [instance]
2026-01-20 16:43:05.677673 | controller |
2026-01-20 16:43:05.677699 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2026-01-20 16:43:05.677706 | controller | Tuesday 20 January 2026  16:43:05 +0000 (0:00:00.016)       0:01:35.938 *******
2026-01-20 16:43:05.677716 | controller | skipping: [instance]
2026-01-20 16:43:05.694663 | controller |
2026-01-20 16:43:05.694686 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2026-01-20 16:43:05.694694 | controller | Tuesday 20 January 2026  16:43:05 +0000 (0:00:00.017)       0:01:35.955 *******
2026-01-20 16:43:05.694703 | controller | skipping: [instance]
2026-01-20 16:43:05.711052 | controller |
2026-01-20 16:43:05.711118 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2026-01-20 16:43:05.711128 | controller | Tuesday 20 January 2026  16:43:05 +0000 (0:00:00.017)       0:01:35.972 *******
2026-01-20 16:43:05.711146 | controller | skipping: [instance]
2026-01-20 16:43:05.711285 | controller |
2026-01-20 16:43:05.711300 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2026-01-20 16:43:05.711437 | controller | Tuesday 20 January 2026  16:43:05 +0000 (0:00:00.016)       0:01:35.989 *******
2026-01-20 16:43:05.734082 | controller | skipping: [instance]
2026-01-20 16:43:05.734195 | controller |
2026-01-20 16:43:05.734374 | controller | TASK [ci_setup : Manage directories] *******************************************
2026-01-20 16:43:05.734491 | controller | Tuesday 20 January 2026  16:43:05 +0000 (0:00:00.022)       0:01:36.012 *******
2026-01-20 16:43:06.693729 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2026-01-20 16:46:04.311497 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2026-01-20 16:46:04.311559 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2026-01-20 16:46:04.311568 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2026-01-20 16:46:04.311575 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2026-01-20 16:46:04.311580 | controller |
2026-01-20 16:46:04.311586 | controller | TASK [Ensure CRC is started] ***************************************************
2026-01-20 16:46:04.311591 | controller | Tuesday 20 January 2026  16:43:06 +0000 (0:00:00.959)       0:01:36.971 *******
2026-01-20 16:46:04.311602 | controller | changed: [instance]
2026-01-20 16:46:05.148889 | controller |
2026-01-20 16:46:05.148939 | controller | TASK [Create the openstack namespace] ******************************************
2026-01-20 16:46:05.148961 | controller | Tuesday 20 January 2026  16:46:04 +0000 (0:02:57.617)       0:04:34.589 *******
2026-01-20 16:46:05.148974 | controller | changed: [instance]
2026-01-20 16:46:05.149202 | controller |
2026-01-20 16:46:05.149241 | controller | TASK [Create a dummy configmap] ************************************************
2026-01-20 16:46:05.774776 | controller | Tuesday 20 January 2026  16:46:05 +0000 (0:00:00.837)       0:04:35.427 *******
2026-01-20 16:46:05.774814 | controller | changed: [instance]
2026-01-20 16:46:06.420027 | controller |
2026-01-20 16:46:06.420073 | controller | TASK [Create a dummy openstack config secret] **********************************
2026-01-20 16:46:06.420082 | controller | Tuesday 20 January 2026  16:46:05 +0000 (0:00:00.625)       0:04:36.052 *******
2026-01-20 16:46:06.420092 | controller | changed: [instance]
2026-01-20 16:46:06.420179 | controller |
2026-01-20 16:46:06.420193 | controller | TASK [Create dummy openstack root ca secret] ***********************************
2026-01-20 16:46:07.051839 | controller | Tuesday 20 January 2026  16:46:06 +0000 (0:00:00.645)       0:04:36.698 *******
2026-01-20 16:46:07.051903 | controller | changed: [instance]
2026-01-20 16:46:07.051943 | controller |
2026-01-20 16:46:07.053119 | controller | PLAY RECAP *********************************************************************
2026-01-20 16:46:07.053158 | controller | instance                   : ok=33   changed=20   unreachable=0    failed=0    skipped=37   rescued=0    ignored=0
2026-01-20 16:46:07.053165 | controller |
2026-01-20 16:46:07.053171 | controller | Tuesday 20 January 2026  16:46:07 +0000 (0:00:00.631)       0:04:37.330 *******
2026-01-20 16:46:07.053177 | controller | ===============================================================================
2026-01-20 16:46:07.053182 | controller | Ensure CRC is started ------------------------------------------------- 177.62s
2026-01-20 16:46:07.053187 | controller | test_deps : Install selinux python libs -------------------------------- 62.32s
2026-01-20 16:46:07.053192 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.91s
2026-01-20 16:46:07.053197 | controller | ci_setup : Install needed packages -------------------------------------- 7.69s
2026-01-20 16:46:07.053203 | controller | ci_setup : Install openshift client ------------------------------------- 5.99s
2026-01-20 16:46:07.053208 | controller | repo_setup : Run repo-setup --------------------------------------------- 1.37s
2026-01-20 16:46:07.053215 | controller | test_deps : Install python yaml libs ------------------------------------ 1.26s
2026-01-20 16:46:07.053639 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.07s
2026-01-20 16:46:07.053763 | controller | ci_setup : Manage directories ------------------------------------------- 0.96s
2026-01-20 16:46:07.053775 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2026-01-20 16:46:07.053789 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.84s
2026-01-20 16:46:07.053794 | controller | Create the openstack namespace ------------------------------------------ 0.84s
2026-01-20 16:46:07.053802 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.73s
2026-01-20 16:46:07.053826 | controller | repo_setup : Install repo-setup package --------------------------------- 0.72s
2026-01-20 16:46:07.053955 | controller | Create a dummy openstack config secret ---------------------------------- 0.65s
2026-01-20 16:46:07.054544 | controller | Create dummy openstack root ca secret ----------------------------------- 0.63s
2026-01-20 16:46:07.119858 | controller | Create a dummy configmap ------------------------------------------------ 0.63s
2026-01-20 16:46:07.119942 | controller | ci_setup : Gather version of openshift client --------------------------- 0.61s
2026-01-20 16:46:07.119953 | controller | repo_setup : Ensure directories are present ----------------------------- 0.54s
2026-01-20 16:46:07.119959 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.53s
2026-01-20 16:46:07.119974 | controller | INFO     Running default > converge
2026-01-20 16:46:07.563622 | controller |
2026-01-20 16:46:07.563709 | controller | PLAY [Converge] ****************************************************************
2026-01-20 16:46:07.563726 | controller |
2026-01-20 16:46:07.564756 | controller | TASK [Gathering Facts] *********************************************************
2026-01-20 16:46:08.424171 | controller | Tuesday 20 January 2026  16:46:07 +0000 (0:00:00.036)       0:00:00.036 *******
2026-01-20 16:46:08.424218 | controller | ok: [instance]
2026-01-20 16:46:08.424255 | controller |
2026-01-20 16:46:08.424380 | controller | TASK [Include the shiftstack role] *********************************************
2026-01-20 16:46:08.424487 | controller | Tuesday 20 January 2026  16:46:08 +0000 (0:00:00.863)       0:00:00.900 *******
2026-01-20 16:46:08.448388 | controller |
2026-01-20 16:46:08.448502 | controller | TASK [shiftstack : Include pre test shiftstack tasks] **************************
2026-01-20 16:46:08.448629 | controller | Tuesday 20 January 2026  16:46:08 +0000 (0:00:00.024)       0:00:00.924 *******
2026-01-20 16:46:08.473074 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/pre_test_shiftstack.yml for instance
2026-01-20 16:46:08.473210 | controller |
2026-01-20 16:46:08.473324 | controller | TASK [shiftstack : Fail if the testconfig is not passed] ***********************
2026-01-20 16:46:08.473432 | controller | Tuesday 20 January 2026  16:46:08 +0000 (0:00:00.024)       0:00:00.949 *******
2026-01-20 16:46:08.498830 | controller | ok: [instance] => changed=false
2026-01-20 16:46:08.498984 | controller |   msg: All assertions passed
2026-01-20 16:46:08.499123 | controller |
2026-01-20 16:46:08.499250 | controller | TASK [shiftstack : Remove the shiftstackclient pod if exists] ******************
2026-01-20 16:46:08.499365 | controller | Tuesday 20 January 2026  16:46:08 +0000 (0:00:00.025)       0:00:00.975 *******
2026-01-20 16:46:09.276786 | controller | ok: [instance]
2026-01-20 16:46:09.276837 | controller |
2026-01-20 16:46:09.276966 | controller | TASK [shiftstack : Remove the shiftstack role data directory (if exists)] ******
2026-01-20 16:46:09.277101 | controller | Tuesday 20 January 2026  16:46:09 +0000 (0:00:00.777)       0:00:01.752 *******
2026-01-20 16:46:09.576779 | controller | ok: [instance]
2026-01-20 16:46:09.576843 | controller |
2026-01-20 16:46:09.577123 | controller | TASK [shiftstack : Create the directory '{{ item }}'] **************************
2026-01-20 16:46:09.577191 | controller | Tuesday 20 January 2026  16:46:09 +0000 (0:00:00.300)       0:00:02.053 *******
2026-01-20 16:46:10.316619 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack)
2026-01-20 16:46:10.316740 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs)
2026-01-20 16:46:10.316865 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack/manifests)
2026-01-20 16:46:10.316983 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack/artifacts)
2026-01-20 16:46:10.317106 | controller |
2026-01-20 16:46:10.317230 | controller | TASK [shiftstack : Deploy the pod 'shiftstackclient-shiftstack'] ***************
2026-01-20 16:46:10.317806 | controller | Tuesday 20 January 2026  16:46:10 +0000 (0:00:00.739)       0:00:02.792 *******
2026-01-20 16:46:10.344971 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/deploy_shiftstackclient_pod.yml for instance
2026-01-20 16:46:10.345043 | controller |
2026-01-20 16:46:10.345720 | controller | TASK [shiftstack : Switch context to admin on /home/zuul/.crc/machines/crc/kubeconfig] ***
2026-01-20 16:46:10.729841 | controller | Tuesday 20 January 2026  16:46:10 +0000 (0:00:00.028)       0:00:02.821 *******
2026-01-20 16:46:10.729924 | controller | changed: [instance]
2026-01-20 16:46:10.730058 | controller |
2026-01-20 16:46:10.730082 | controller | TASK [shiftstack : Encode kubeconfig file to Base64] ***************************
2026-01-20 16:46:10.730171 | controller | Tuesday 20 January 2026  16:46:10 +0000 (0:00:00.384)       0:00:03.206 *******
2026-01-20 16:46:10.762278 | controller | ok: [instance]
2026-01-20 16:46:10.762330 | controller |
2026-01-20 16:46:10.762341 | controller | TASK [shiftstack : Render the incluster kubeconfig secret manifest] ************
2026-01-20 16:46:10.762474 | controller | Tuesday 20 January 2026  16:46:10 +0000 (0:00:00.032)       0:00:03.238 *******
2026-01-20 16:46:11.350572 | controller | changed: [instance]
2026-01-20 16:46:11.350633 | controller |
2026-01-20 16:46:11.350743 | controller | TASK [shiftstack : Apply the manifest for the incluster kubeconfig secret] *****
2026-01-20 16:46:11.350855 | controller | Tuesday 20 January 2026  16:46:11 +0000 (0:00:00.588)       0:00:03.826 *******
2026-01-20 16:46:12.036698 | controller | changed: [instance]
2026-01-20 16:46:12.413799 | controller |
2026-01-20 16:46:12.413861 | controller | TASK [shiftstack : Render the pvc manifest] ************************************
2026-01-20 16:46:12.413870 | controller | Tuesday 20 January 2026  16:46:12 +0000 (0:00:00.685)       0:00:04.512 *******
2026-01-20 16:46:12.413886 | controller | changed: [instance]
2026-01-20 16:46:13.128775 | controller |
2026-01-20 16:46:13.128809 | controller | TASK [shiftstack : Apply the manifest for the PVC creation] ********************
2026-01-20 16:46:13.128817 | controller | Tuesday 20 January 2026  16:46:12 +0000 (0:00:00.374)       0:00:04.887 *******
2026-01-20 16:46:13.128832 | controller | changed: [instance]
2026-01-20 16:46:13.534439 | controller |
2026-01-20 16:46:13.534478 | controller | TASK [shiftstack : Render the pod manifest from a template] ********************
2026-01-20 16:46:13.534497 | controller | Tuesday 20 January 2026  16:46:13 +0000 (0:00:00.717)       0:00:05.604 *******
2026-01-20 16:46:13.534512 | controller | changed: [instance]
2026-01-20 16:52:15.875178 | controller |
2026-01-20 16:52:15.875223 | controller | TASK [shiftstack : Apply the manifest for the pod creation] ********************
2026-01-20 16:52:15.875231 | controller | Tuesday 20 January 2026  16:46:13 +0000 (0:00:00.405)       0:00:06.009 *******
2026-01-20 16:52:15.875241 | controller | fatal: [instance]: FAILED! => changed=true
2026-01-20 16:52:15.875271 | controller |   duration: 361
2026-01-20 16:52:15.875279 | controller |   method: create
2026-01-20 16:52:15.875685 | controller |   msg: '"Pod" "shiftstackclient-shiftstack": Timed out waiting on resource'
2026-01-20 16:52:15.875705 | controller |   result:
2026-01-20 16:52:15.875743 | controller |     apiVersion: v1
2026-01-20 16:52:15.875750 | controller |     kind: Pod
2026-01-20 16:52:15.875756 | controller |     metadata:
2026-01-20 16:52:15.875763 | controller |       annotations:
2026-01-20 16:52:15.876004 | controller |         k8s.ovn.org/pod-networks: '{"default":{"ip_addresses":["10.217.0.15/23"],"mac_address":"0a:58:0a:d9:00:0f","gateway_ips":["10.217.0.1"],"routes":[{"dest":"10.217.0.0/22","nextHop":"10.217.0.1"},{"dest":"10.217.4.0/23","nextHop":"10.217.0.1"},{"dest":"169.254.0.5/32","nextHop":"10.217.0.1"},{"dest":"100.64.0.0/16","nextHop":"10.217.0.1"}],"ip_address":"10.217.0.15/23","gateway_ip":"10.217.0.1","role":"primary"}}'
2026-01-20 16:52:15.876356 | controller |         k8s.v1.cni.cncf.io/network-status: |-
2026-01-20 16:52:15.876558 | controller |           [{
2026-01-20 16:52:15.876570 | controller |               "name": "ovn-kubernetes",
2026-01-20 16:52:15.876583 | controller |               "interface": "eth0",
2026-01-20 16:52:15.876588 | controller |               "ips": [
2026-01-20 16:52:15.876597 | controller |                   "10.217.0.15"
2026-01-20 16:52:15.876934 | controller |               ],
2026-01-20 16:52:15.876950 | controller |               "mac": "0a:58:0a:d9:00:0f",
2026-01-20 16:52:15.877297 | controller |               "default": true,
2026-01-20 16:52:15.877311 | controller |               "dns": {}
2026-01-20 16:52:15.877323 | controller |           }]
2026-01-20 16:52:15.877331 | controller |         openshift.io/scc: hostpath-provisioner
2026-01-20 16:52:15.877426 | controller |       creationTimestamp: '2026-01-20T16:46:14Z'
2026-01-20 16:52:15.877434 | controller |       managedFields:
2026-01-20 16:52:15.877440 | controller |       - apiVersion: v1
2026-01-20 16:52:15.877447 | controller |         fieldsType: FieldsV1
2026-01-20 16:52:15.877541 | controller |         fieldsV1:
2026-01-20 16:52:15.877632 | controller |           f:spec:
2026-01-20 16:52:15.877727 | controller |             f:containers:
2026-01-20 16:52:15.877829 | controller |               k:{"name":"shiftstackclient-shiftstack"}:
2026-01-20 16:52:15.877919 | controller |                 .: {}
2026-01-20 16:52:15.878039 | controller |                 f:args: {}
2026-01-20 16:52:15.878132 | controller |                 f:command: {}
2026-01-20 16:52:15.878217 | controller |                 f:image: {}
2026-01-20 16:52:15.878313 | controller |                 f:imagePullPolicy: {}
2026-01-20 16:52:15.878418 | controller |                 f:name: {}
2026-01-20 16:52:15.878535 | controller |                 f:resources: {}
2026-01-20 16:52:15.878621 | controller |                 f:securityContext:
2026-01-20 16:52:15.878708 | controller |                   .: {}
2026-01-20 16:52:15.878802 | controller |                   f:privileged: {}
2026-01-20 16:52:15.878923 | controller |                 f:terminationMessagePath: {}
2026-01-20 16:52:15.879016 | controller |                 f:terminationMessagePolicy: {}
2026-01-20 16:52:15.879101 | controller |                 f:volumeMounts:
2026-01-20 16:52:15.879191 | controller |                   .: {}
2026-01-20 16:52:15.879298 | controller |                   k:{"mountPath":"/home/cloud-admin/.original-config/cert/"}:
2026-01-20 16:52:15.879381 | controller |                     .: {}
2026-01-20 16:52:15.879480 | controller |                     f:mountPath: {}
2026-01-20 16:52:15.879572 | controller |                     f:name: {}
2026-01-20 16:52:15.879671 | controller |                     f:readOnly: {}
2026-01-20 16:52:15.879784 | controller |                   k:{"mountPath":"/home/cloud-admin/.original-config/openstack/clouds.yaml"}:
2026-01-20 16:52:15.879877 | controller |                     .: {}
2026-01-20 16:52:15.880009 | controller |                     f:mountPath: {}
2026-01-20 16:52:15.880106 | controller |                     f:name: {}
2026-01-20 16:52:15.880197 | controller |                     f:subPath: {}
2026-01-20 16:52:15.880312 | controller |                   k:{"mountPath":"/home/cloud-admin/.original-config/openstack/secure.yaml"}:
2026-01-20 16:52:15.880392 | controller |                     .: {}
2026-01-20 16:52:15.880497 | controller |                     f:mountPath: {}
2026-01-20 16:52:15.880589 | controller |                     f:name: {}
2026-01-20 16:52:15.880684 | controller |                     f:subPath: {}
2026-01-20 16:52:15.880781 | controller |                   k:{"mountPath":"/home/cloud-admin/artifacts/installation"}:
2026-01-20 16:52:15.880871 | controller |                     .: {}
2026-01-20 16:52:15.880994 | controller |                     f:mountPath: {}
2026-01-20 16:52:15.881073 | controller |                     f:name: {}
2026-01-20 16:52:15.881164 | controller |                   k:{"mountPath":"/home/cloud-admin/incluster-kubeconfig"}:
2026-01-20 16:52:15.881257 | controller |                     .: {}
2026-01-20 16:52:15.881353 | controller |                     f:mountPath: {}
2026-01-20 16:52:15.881446 | controller |                     f:name: {}
2026-01-20 16:52:15.881561 | controller |                     f:readOnly: {}
2026-01-20 16:52:15.881656 | controller |             f:dnsPolicy: {}
2026-01-20 16:52:15.881742 | controller |             f:enableServiceLinks: {}
2026-01-20 16:52:15.881832 | controller |             f:preemptionPolicy: {}
2026-01-20 16:52:15.881937 | controller |             f:priority: {}
2026-01-20 16:52:15.882034 | controller |             f:restartPolicy: {}
2026-01-20 16:52:15.882131 | controller |             f:schedulerName: {}
2026-01-20 16:52:15.882229 | controller |             f:securityContext: {}
2026-01-20 16:52:15.882319 | controller |             f:terminationGracePeriodSeconds: {}
2026-01-20 16:52:15.882408 | controller |             f:tolerations: {}
2026-01-20 16:52:15.882514 | controller |             f:volumes:
2026-01-20 16:52:15.882606 | controller |               .: {}
2026-01-20 16:52:15.882697 | controller |               k:{"name":"incluster-kubeconfig-volume"}:
2026-01-20 16:52:15.882787 | controller |                 .: {}
2026-01-20 16:52:15.882884 | controller |                 f:name: {}
2026-01-20 16:52:15.883009 | controller |                 f:secret:
2026-01-20 16:52:15.883106 | controller |                   .: {}
2026-01-20 16:52:15.883199 | controller |                   f:defaultMode: {}
2026-01-20 16:52:15.883304 | controller |                   f:secretName: {}
2026-01-20 16:52:15.883390 | controller |               k:{"name":"installation-volume"}:
2026-01-20 16:52:15.883488 | controller |                 .: {}
2026-01-20 16:52:15.883585 | controller |                 f:name: {}
2026-01-20 16:52:15.883679 | controller |                 f:persistentVolumeClaim:
2026-01-20 16:52:15.883770 | controller |                   .: {}
2026-01-20 16:52:15.883865 | controller |                   f:claimName: {}
2026-01-20 16:52:15.883988 | controller |               k:{"name":"openstack-cert"}:
2026-01-20 16:52:15.884086 | controller |                 .: {}
2026-01-20 16:52:15.884196 | controller |                 f:name: {}
2026-01-20 16:52:15.884280 | controller |                 f:secret:
2026-01-20 16:52:15.884367 | controller |                   .: {}
2026-01-20 16:52:15.884489 | controller |                   f:defaultMode: {}
2026-01-20 16:52:15.884589 | controller |                   f:secretName: {}
2026-01-20 16:52:15.884687 | controller |               k:{"name":"openstack-config"}:
2026-01-20 16:52:15.884779 | controller |                 .: {}
2026-01-20 16:52:15.884874 | controller |                 f:configMap:
2026-01-20 16:52:15.884986 | controller |                   .: {}
2026-01-20 16:52:15.885082 | controller |                   f:defaultMode: {}
2026-01-20 16:52:15.885177 | controller |                   f:name: {}
2026-01-20 16:52:15.885268 | controller |                 f:name: {}
2026-01-20 16:52:15.885372 | controller |               k:{"name":"openstack-config-secret"}:
2026-01-20 16:52:15.885468 | controller |                 .: {}
2026-01-20 16:52:15.885563 | controller |                 f:name: {}
2026-01-20 16:52:15.885657 | controller |                 f:secret:
2026-01-20 16:52:15.885754 | controller |                   .: {}
2026-01-20 16:52:15.885847 | controller |                   f:defaultMode: {}
2026-01-20 16:52:15.885962 | controller |                   f:secretName: {}
2026-01-20 16:52:15.886067 | controller |         manager: OpenAPI-Generator
2026-01-20 16:52:15.886162 | controller |         operation: Update
2026-01-20 16:52:15.886256 | controller |         time: '2026-01-20T16:46:14Z'
2026-01-20 16:52:15.886351 | controller |       - apiVersion: v1
2026-01-20 16:52:15.886446 | controller |         fieldsType: FieldsV1
2026-01-20 16:52:15.886566 | controller |         fieldsV1:
2026-01-20 16:52:15.886660 | controller |           f:metadata:
2026-01-20 16:52:15.886749 | controller |             f:annotations:
2026-01-20 16:52:15.886835 | controller |               f:k8s.ovn.org/pod-networks: {}
2026-01-20 16:52:15.886951 | controller |         manager: crc
2026-01-20 16:52:15.887051 | controller |         operation: Update
2026-01-20 16:52:15.887133 | controller |         subresource: status
2026-01-20 16:52:15.887225 | controller |         time: '2026-01-20T16:47:24Z'
2026-01-20 16:52:15.887319 | controller |       - apiVersion: v1
2026-01-20 16:52:15.887419 | controller |         fieldsType: FieldsV1
2026-01-20 16:52:15.887530 | controller |         fieldsV1:
2026-01-20 16:52:15.887624 | controller |           f:metadata:
2026-01-20 16:52:15.887727 | controller |             f:annotations:
2026-01-20 16:52:15.887827 | controller |               f:k8s.v1.cni.cncf.io/network-status: {}
2026-01-20 16:52:15.887923 | controller |         manager: multus-daemon
2026-01-20 16:52:15.888031 | controller |         operation: Update
2026-01-20 16:52:15.888126 | controller |         subresource: status
2026-01-20 16:52:15.888222 | controller |         time: '2026-01-20T16:51:34Z'
2026-01-20 16:52:15.888315 | controller |       - apiVersion: v1
2026-01-20 16:52:15.888411 | controller |         fieldsType: FieldsV1
2026-01-20 16:52:15.888526 | controller |         fieldsV1:
2026-01-20 16:52:15.888614 | controller |           f:status:
2026-01-20 16:52:15.888702 | controller |             f:conditions:
2026-01-20 16:52:15.888813 | controller |               k:{"type":"ContainersReady"}:
2026-01-20 16:52:15.888922 | controller |                 .: {}
2026-01-20 16:52:15.889018 | controller |                 f:lastProbeTime: {}
2026-01-20 16:52:15.889120 | controller |                 f:lastTransitionTime: {}
2026-01-20 16:52:15.889214 | controller |                 f:message: {}
2026-01-20 16:52:15.889307 | controller |                 f:reason: {}
2026-01-20 16:52:15.889398 | controller |                 f:status: {}
2026-01-20 16:52:15.889507 | controller |                 f:type: {}
2026-01-20 16:52:15.889600 | controller |               k:{"type":"Initialized"}:
2026-01-20 16:52:15.889711 | controller |                 .: {}
2026-01-20 16:52:15.889799 | controller |                 f:lastProbeTime: {}
2026-01-20 16:52:15.889885 | controller |                 f:lastTransitionTime: {}
2026-01-20 16:52:15.890286 | controller |                 f:status: {}
2026-01-20 16:52:15.890394 | controller |                 f:type: {}
2026-01-20 16:52:15.890506 | controller |               k:{"type":"PodReadyToStartContainers"}:
2026-01-20 16:52:15.890615 | controller |                 .: {}
2026-01-20 16:52:15.890717 | controller |                 f:lastProbeTime: {}
2026-01-20 16:52:15.890799 | controller |                 f:lastTransitionTime: {}
2026-01-20 16:52:15.890894 | controller |                 f:status: {}
2026-01-20 16:52:15.891025 | controller |                 f:type: {}
2026-01-20 16:52:15.891119 | controller |               k:{"type":"Ready"}:
2026-01-20 16:52:15.891209 | controller |                 .: {}
2026-01-20 16:52:15.891308 | controller |                 f:lastProbeTime: {}
2026-01-20 16:52:15.891399 | controller |                 f:lastTransitionTime: {}
2026-01-20 16:52:15.891509 | controller |                 f:message: {}
2026-01-20 16:52:15.891603 | controller |                 f:reason: {}
2026-01-20 16:52:15.891702 | controller |                 f:status: {}
2026-01-20 16:52:15.891798 | controller |                 f:type: {}
2026-01-20 16:52:15.891890 | controller |             f:containerStatuses: {}
2026-01-20 16:52:15.892016 | controller |             f:hostIP: {}
2026-01-20 16:52:15.892112 | controller |             f:hostIPs: {}
2026-01-20 16:52:15.892204 | controller |             f:podIP: {}
2026-01-20 16:52:15.892297 | controller |             f:podIPs:
2026-01-20 16:52:15.892396 | controller |               .: {}
2026-01-20 16:52:15.892505 | controller |               k:{"ip":"10.217.0.15"}:
2026-01-20 16:52:15.892595 | controller |                 .: {}
2026-01-20 16:52:15.892698 | controller |                 f:ip: {}
2026-01-20 16:52:15.892786 | controller |             f:startTime: {}
2026-01-20 16:52:15.892878 | controller |         manager: kubelet
2026-01-20 16:52:15.892994 | controller |         operation: Update
2026-01-20 16:52:15.893088 | controller |         subresource: status
2026-01-20 16:52:15.893181 | controller |         time: '2026-01-20T16:52:07Z'
2026-01-20 16:52:15.893282 | controller |       name: shiftstackclient-shiftstack
2026-01-20 16:52:15.893374 | controller |       namespace: openstack
2026-01-20 16:52:15.893489 | controller |       resourceVersion: '34501'
2026-01-20 16:52:15.893590 | controller |       uid: 167ef4c6-33b3-4327-8aa9-35a4e2802618
2026-01-20 16:52:15.893683 | controller |     spec:
2026-01-20 16:52:15.893778 | controller |       containers:
2026-01-20 16:52:15.893867 | controller |       - args:
2026-01-20 16:52:15.893979 | controller |         - infinity
2026-01-20 16:52:15.894082 | controller |         command:
2026-01-20 16:52:15.894173 | controller |         - /bin/sleep
2026-01-20 16:52:15.894281 | controller |         image: quay.io/shiftstack-qe/shiftstack-client:latest
2026-01-20 16:52:15.894366 | controller |         imagePullPolicy: Always
2026-01-20 16:52:15.894469 | controller |         name: shiftstackclient-shiftstack
2026-01-20 16:52:15.894580 | controller |         resources: {}
2026-01-20 16:52:15.894670 | controller |         securityContext:
2026-01-20 16:52:15.894763 | controller |           capabilities:
2026-01-20 16:52:15.894858 | controller |             drop:
2026-01-20 16:52:15.894969 | controller |             - KILL
2026-01-20 16:52:15.895060 | controller |             - MKNOD
2026-01-20 16:52:15.895146 | controller |             - SETGID
2026-01-20 16:52:15.895239 | controller |             - SETUID
2026-01-20 16:52:15.895344 | controller |           privileged: true
2026-01-20 16:52:15.895434 | controller |         terminationMessagePath: /dev/termination-log
2026-01-20 16:52:15.895558 | controller |         terminationMessagePolicy: File
2026-01-20 16:52:15.895644 | controller |         volumeMounts:
2026-01-20 16:52:15.895741 | controller |         - mountPath: /home/cloud-admin/.original-config/openstack/clouds.yaml
2026-01-20 16:52:15.895844 | controller |           name: openstack-config
2026-01-20 16:52:15.895961 | controller |           subPath: clouds.yaml
2026-01-20 16:52:15.896066 | controller |         - mountPath: /home/cloud-admin/.original-config/openstack/secure.yaml
2026-01-20 16:52:15.896164 | controller |           name: openstack-config-secret
2026-01-20 16:52:15.896272 | controller |           subPath: secure.yaml
2026-01-20 16:52:15.896370 | controller |         - mountPath: /home/cloud-admin/.original-config/cert/
2026-01-20 16:52:15.896471 | controller |           name: openstack-cert
2026-01-20 16:52:15.896577 | controller |           readOnly: true
2026-01-20 16:52:15.896674 | controller |         - mountPath: /home/cloud-admin/artifacts/installation
2026-01-20 16:52:15.896769 | controller |           name: installation-volume
2026-01-20 16:52:15.896865 | controller |         - mountPath: /home/cloud-admin/incluster-kubeconfig
2026-01-20 16:52:15.897004 | controller |           name: incluster-kubeconfig-volume
2026-01-20 16:52:15.897104 | controller |           readOnly: true
2026-01-20 16:52:15.897194 | controller |         - mountPath: /var/run/secrets/kubernetes.io/serviceaccount
2026-01-20 16:52:15.897296 | controller |           name: kube-api-access-65jbt
2026-01-20 16:52:15.897393 | controller |           readOnly: true
2026-01-20 16:52:15.897495 | controller |       dnsPolicy: ClusterFirst
2026-01-20 16:52:15.897591 | controller |       enableServiceLinks: true
2026-01-20 16:52:15.897687 | controller |       nodeName: crc
2026-01-20 16:52:15.897787 | controller |       preemptionPolicy: PreemptLowerPriority
2026-01-20 16:52:15.897880 | controller |       priority: 0
2026-01-20 16:52:15.898002 | controller |       restartPolicy: Always
2026-01-20 16:52:15.898094 | controller |       schedulerName: default-scheduler
2026-01-20 16:52:15.898188 | controller |       securityContext: {}
2026-01-20 16:52:15.898282 | controller |       serviceAccount: default
2026-01-20 16:52:15.898385 | controller |       serviceAccountName: default
2026-01-20 16:52:15.898484 | controller |       terminationGracePeriodSeconds: 0
2026-01-20 16:52:15.898574 | controller |       tolerations:
2026-01-20 16:52:15.898685 | controller |       - effect: NoExecute
2026-01-20 16:52:15.898775 | controller |         key: node.kubernetes.io/not-ready
2026-01-20 16:52:15.898869 | controller |         operator: Exists
2026-01-20 16:52:15.898990 | controller |         tolerationSeconds: 300
2026-01-20 16:52:15.899087 | controller |       - effect: NoExecute
2026-01-20 16:52:15.899175 | controller |         key: node.kubernetes.io/unreachable
2026-01-20 16:52:15.899263 | controller |         operator: Exists
2026-01-20 16:52:15.899364 | controller |         tolerationSeconds: 300
2026-01-20 16:52:15.899452 | controller |       volumes:
2026-01-20 16:52:15.899557 | controller |       - name: incluster-kubeconfig-volume
2026-01-20 16:52:15.899649 | controller |         secret:
2026-01-20 16:52:15.899742 | controller |           defaultMode: 420
2026-01-20 16:52:15.899842 | controller |           secretName: incluster-kubeconfig
2026-01-20 16:52:15.899962 | controller |       - configMap:
2026-01-20 16:52:15.900064 | controller |           defaultMode: 420
2026-01-20 16:52:15.900153 | controller |           name: openstack-config
2026-01-20 16:52:15.900252 | controller |         name: openstack-config
2026-01-20 16:52:15.900353 | controller |       - name: openstack-config-secret
2026-01-20 16:52:15.900444 | controller |         secret:
2026-01-20 16:52:15.900550 | controller |           defaultMode: 420
2026-01-20 16:52:15.900645 | controller |           secretName: openstack-config-secret
2026-01-20 16:52:15.900741 | controller |       - name: openstack-cert
2026-01-20 16:52:15.900834 | controller |         secret:
2026-01-20 16:52:15.900951 | controller |           defaultMode: 292
2026-01-20 16:52:15.901069 | controller |           secretName: rootca-public
2026-01-20 16:52:15.901171 | controller |       - name: installation-volume
2026-01-20 16:52:15.901275 | controller |         persistentVolumeClaim:
2026-01-20 16:52:15.901359 | controller |           claimName: shiftstackclient-shiftstack-pvc
2026-01-20 16:52:15.901472 | controller |       - name: kube-api-access-65jbt
2026-01-20 16:52:15.901652 | controller |         projected:
2026-01-20 16:52:15.901749 | controller |           defaultMode: 420
2026-01-20 16:52:15.901849 | controller |           sources:
2026-01-20 16:52:15.901959 | controller |           - serviceAccountToken:
2026-01-20 16:52:15.902052 | controller |               expirationSeconds: 3607
2026-01-20 16:52:15.902146 | controller |               path: token
2026-01-20 16:52:15.902241 | controller |           - configMap:
2026-01-20 16:52:15.902344 | controller |               items:
2026-01-20 16:52:15.902443 | controller |               - key: ca.crt
2026-01-20 16:52:15.902543 | controller |                 path: ca.crt
2026-01-20 16:52:15.902632 | controller |               name: kube-root-ca.crt
2026-01-20 16:52:15.902731 | controller |           - downwardAPI:
2026-01-20 16:52:15.902822 | controller |               items:
2026-01-20 16:52:15.902924 | controller |               - fieldRef:
2026-01-20 16:52:15.903031 | controller |                   apiVersion: v1
2026-01-20 16:52:15.903131 | controller |                   fieldPath: metadata.namespace
2026-01-20 16:52:15.903227 | controller |                 path: namespace
2026-01-20 16:52:15.903330 | controller |           - configMap:
2026-01-20 16:52:15.903426 | controller |               items:
2026-01-20 16:52:15.903539 | controller |               - key: service-ca.crt
2026-01-20 16:52:15.903638 | controller |                 path: service-ca.crt
2026-01-20 16:52:15.903744 | controller |               name: openshift-service-ca.crt
2026-01-20 16:52:15.903836 | controller |     status:
2026-01-20 16:52:15.903951 | controller |       conditions:
2026-01-20 16:52:15.904061 | controller |       - lastProbeTime: null
2026-01-20 16:52:15.904154 | controller |         lastTransitionTime: '2026-01-20T16:52:07Z'
2026-01-20 16:52:15.904251 | controller |         status: 'True'
2026-01-20 16:52:15.904340 | controller |         type: PodReadyToStartContainers
2026-01-20 16:52:15.904435 | controller |       - lastProbeTime: null
2026-01-20 16:52:15.904552 | controller |         lastTransitionTime: '2026-01-20T16:47:23Z'
2026-01-20 16:52:15.904646 | controller |         status: 'True'
2026-01-20 16:52:15.904731 | controller |         type: Initialized
2026-01-20 16:52:15.904819 | controller |       - lastProbeTime: null
2026-01-20 16:52:15.904936 | controller |         lastTransitionTime: '2026-01-20T16:47:23Z'
2026-01-20 16:52:15.905042 | controller |         message: 'containers with unready status: [shiftstackclient-shiftstack]'
2026-01-20 16:52:15.905137 | controller |         reason: ContainersNotReady
2026-01-20 16:52:15.905247 | controller |         status: 'False'
2026-01-20 16:52:15.905332 | controller |         type: Ready
2026-01-20 16:52:15.905432 | controller |       - lastProbeTime: null
2026-01-20 16:52:15.905540 | controller |         lastTransitionTime: '2026-01-20T16:47:23Z'
2026-01-20 16:52:15.905637 | controller |         message: 'containers with unready status: [shiftstackclient-shiftstack]'
2026-01-20 16:52:15.905734 | controller |         reason: ContainersNotReady
2026-01-20 16:52:15.905826 | controller |         status: 'False'
2026-01-20 16:52:15.905944 | controller |         type: ContainersReady
2026-01-20 16:52:15.906043 | controller |       - lastProbeTime: null
2026-01-20 16:52:15.906144 | controller |         lastTransitionTime: '2026-01-20T16:47:23Z'
2026-01-20 16:52:15.906236 | controller |         status: 'True'
2026-01-20 16:52:15.906333 | controller |         type: PodScheduled
2026-01-20 16:52:15.906437 | controller |       containerStatuses:
2026-01-20 16:52:15.906533 | controller |       - image: quay.io/shiftstack-qe/shiftstack-client:latest
2026-01-20 16:52:15.906620 | controller |         imageID: ''
2026-01-20 16:52:15.906731 | controller |         lastState: {}
2026-01-20 16:52:15.906827 | controller |         name: shiftstackclient-shiftstack
2026-01-20 16:52:15.906933 | controller |         ready: false
2026-01-20 16:52:15.907034 | controller |         restartCount: 0
2026-01-20 16:52:15.907129 | controller |         started: false
2026-01-20 16:52:15.907220 | controller |         state:
2026-01-20 16:52:15.907314 | controller |           waiting:
2026-01-20 16:52:15.907412 | controller |             message: 'rpc error: code = Canceled desc = copying config: context canceled'
2026-01-20 16:52:15.907526 | controller |             reason: ErrImagePull
2026-01-20 16:52:15.907617 | controller |         volumeMounts:
2026-01-20 16:52:15.907716 | controller |         - mountPath: /home/cloud-admin/.original-config/openstack/clouds.yaml
2026-01-20 16:52:15.907810 | controller |           name: openstack-config
2026-01-20 16:52:15.907920 | controller |         - mountPath: /home/cloud-admin/.original-config/openstack/secure.yaml
2026-01-20 16:52:15.908027 | controller |           name: openstack-config-secret
2026-01-20 16:52:15.908130 | controller |         - mountPath: /home/cloud-admin/.original-config/cert/
2026-01-20 16:52:15.908225 | controller |           name: openstack-cert
2026-01-20 16:52:15.908322 | controller |           readOnly: true
2026-01-20 16:52:15.908412 | controller |           recursiveReadOnly: Disabled
2026-01-20 16:52:15.908524 | controller |         - mountPath: /home/cloud-admin/artifacts/installation
2026-01-20 16:52:15.908625 | controller |           name: installation-volume
2026-01-20 16:52:15.908727 | controller |         - mountPath: /home/cloud-admin/incluster-kubeconfig
2026-01-20 16:52:15.908821 | controller |           name: incluster-kubeconfig-volume
2026-01-20 16:52:15.908925 | controller |           readOnly: true
2026-01-20 16:52:15.909041 | controller |           recursiveReadOnly: Disabled
2026-01-20 16:52:15.909141 | controller |         - mountPath: /var/run/secrets/kubernetes.io/serviceaccount
2026-01-20 16:52:15.909235 | controller |           name: kube-api-access-65jbt
2026-01-20 16:52:15.909336 | controller |           readOnly: true
2026-01-20 16:52:15.909425 | controller |           recursiveReadOnly: Disabled
2026-01-20 16:52:15.909532 | controller |       hostIP: 192.168.126.11
2026-01-20 16:52:15.909625 | controller |       hostIPs:
2026-01-20 16:52:15.909717 | controller |       - ip: 192.168.126.11
2026-01-20 16:52:15.909810 | controller |       phase: Pending
2026-01-20 16:52:15.909918 | controller |       podIP: 10.217.0.15
2026-01-20 16:52:15.910016 | controller |       podIPs:
2026-01-20 16:52:15.910110 | controller |       - ip: 10.217.0.15
2026-01-20 16:52:15.910212 | controller |       qosClass: BestEffort
2026-01-20 16:52:15.910322 | controller |       startTime: '2026-01-20T16:47:23Z'
2026-01-20 16:52:15.910486 | controller |
2026-01-20 16:52:15.910584 | controller | PLAY RECAP *********************************************************************
2026-01-20 16:52:15.910701 | controller | instance                   : ok=14   changed=7    unreachable=0    failed=1    skipped=0    rescued=0    ignored=0
2026-01-20 16:52:15.910797 | controller |
2026-01-20 16:52:15.910894 | controller | Tuesday 20 January 2026  16:52:15 +0000 (0:06:02.344)       0:06:08.354 *******
2026-01-20 16:52:15.911007 | controller | ===============================================================================
2026-01-20 16:52:15.911105 | controller | shiftstack : Apply the manifest for the pod creation ------------------ 362.34s
2026-01-20 16:52:15.911198 | controller | Gathering Facts --------------------------------------------------------- 0.86s
2026-01-20 16:52:15.911309 | controller | shiftstack : Remove the shiftstackclient pod if exists ------------------ 0.78s
2026-01-20 16:52:15.911400 | controller | shiftstack : Create the directory '{{ item }}' -------------------------- 0.74s
2026-01-20 16:52:15.911504 | controller | shiftstack : Apply the manifest for the PVC creation -------------------- 0.72s
2026-01-20 16:52:15.911602 | controller | shiftstack : Apply the manifest for the incluster kubeconfig secret ----- 0.69s
2026-01-20 16:52:15.911698 | controller | shiftstack : Render the incluster kubeconfig secret manifest ------------ 0.59s
2026-01-20 16:52:15.911792 | controller | shiftstack : Render the pod manifest from a template -------------------- 0.41s
2026-01-20 16:52:15.911896 | controller | shiftstack : Switch context to admin on /home/zuul/.crc/machines/crc/kubeconfig --- 0.38s
2026-01-20 16:52:15.912024 | controller | shiftstack : Render the pvc manifest ------------------------------------ 0.37s
2026-01-20 16:52:15.912119 | controller | shiftstack : Remove the shiftstack role data directory (if exists) ------ 0.30s
2026-01-20 16:52:15.912205 | controller | shiftstack : Encode kubeconfig file to Base64 --------------------------- 0.03s
2026-01-20 16:52:15.912309 | controller | shiftstack : Deploy the pod 'shiftstackclient-shiftstack' --------------- 0.03s
2026-01-20 16:52:15.912407 | controller | shiftstack : Fail if the testconfig is not passed ----------------------- 0.03s
2026-01-20 16:52:15.912508 | controller | shiftstack : Include pre test shiftstack tasks -------------------------- 0.02s
2026-01-20 16:52:15.912599 | controller | Include the shiftstack role --------------------------------------------- 0.02s
2026-01-20 16:52:15.926808 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/shiftstack/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/molecule/default/converge.yml']
2026-01-20 16:52:15.927231 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2026-01-20 16:52:15.936331 | controller | INFO     Running default > cleanup
2026-01-20 16:52:16.430844 | controller |
2026-01-20 16:52:16.430951 | controller | PLAY [Cleanup] *****************************************************************
2026-01-20 16:52:16.431059 | controller |
2026-01-20 16:52:16.431168 | controller | TASK [Gathering Facts] *********************************************************
2026-01-20 16:52:16.431268 | controller | Tuesday 20 January 2026  16:52:16 +0000 (0:00:00.050)       0:00:00.050 *******
2026-01-20 16:52:17.314483 | controller | ok: [instance]
2026-01-20 16:52:17.314530 | controller |
2026-01-20 16:52:17.314649 | controller | TASK [Include the shiftstack role and run the cleanup] *************************
2026-01-20 16:52:17.314755 | controller | Tuesday 20 January 2026  16:52:17 +0000 (0:00:00.883)       0:00:00.934 *******
2026-01-20 16:52:17.340422 | controller |
2026-01-20 16:52:17.340696 | controller | TASK [shiftstack : Destroy the Openshift cluster] ******************************
2026-01-20 16:52:17.359955 | controller | Tuesday 20 January 2026  16:52:17 +0000 (0:00:00.026)       0:00:00.960 *******
2026-01-20 16:52:17.359991 | controller | ok: [instance] =>
2026-01-20 16:52:17.360211 | controller |   msg: 'TODO: to be added once it''s supported in shiftstack-qa automation'
2026-01-20 16:52:17.360340 | controller |
2026-01-20 16:52:17.360357 | controller | TASK [shiftstack : Delete the secret 'incluster-kubeconfig'] *******************
2026-01-20 16:52:17.360877 | controller | Tuesday 20 January 2026  16:52:17 +0000 (0:00:00.019)       0:00:00.979 *******
2026-01-20 16:52:18.180539 | controller | changed: [instance]
2026-01-20 16:52:18.181984 | controller |
2026-01-20 16:52:18.837982 | controller | TASK [shiftstack : Delete the pod 'shiftstackclient-shiftstack'] ***************
2026-01-20 16:52:18.838028 | controller | Tuesday 20 January 2026  16:52:18 +0000 (0:00:00.820)       0:00:01.800 *******
2026-01-20 16:52:18.838042 | controller | changed: [instance]
2026-01-20 16:52:18.838132 | controller |
2026-01-20 16:52:18.838143 | controller | TASK [Delete the openstack namespace] ******************************************
2026-01-20 16:52:18.838243 | controller | Tuesday 20 January 2026  16:52:18 +0000 (0:00:00.657)       0:00:02.457 *******
2026-01-20 16:52:19.480753 | controller | changed: [instance]
2026-01-20 16:52:19.480827 | controller |
2026-01-20 16:52:19.480952 | controller | PLAY RECAP *********************************************************************
2026-01-20 16:52:19.481097 | controller | instance                   : ok=5    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-01-20 16:52:19.481199 | controller |
2026-01-20 16:52:19.481310 | controller | Tuesday 20 January 2026  16:52:19 +0000 (0:00:00.643)       0:00:03.100 *******
2026-01-20 16:52:19.481402 | controller | ===============================================================================
2026-01-20 16:52:19.481526 | controller | Gathering Facts --------------------------------------------------------- 0.88s
2026-01-20 16:52:19.481637 | controller | shiftstack : Delete the secret 'incluster-kubeconfig' ------------------- 0.82s
2026-01-20 16:52:19.481749 | controller | shiftstack : Delete the pod 'shiftstackclient-shiftstack' --------------- 0.66s
2026-01-20 16:52:19.481863 | controller | Delete the openstack namespace ------------------------------------------ 0.64s
2026-01-20 16:52:19.481986 | controller | Include the shiftstack role and run the cleanup ------------------------- 0.03s
2026-01-20 16:52:19.482092 | controller | shiftstack : Destroy the Openshift cluster ------------------------------ 0.02s
2026-01-20 16:52:19.536764 | controller | INFO     Running default > destroy
2026-01-20 16:52:19.537143 | controller | WARNING  Skipping, instances are delegated.
2026-01-20 16:52:19.537472 | controller | INFO     Pruning extra files from scenario ephemeral directory
2026-01-20 16:52:19.539015 | controller | INFO     Writing /tmp/report.html report.
2026-01-20 16:52:19.746361 | controller | ERROR
2026-01-20 16:52:19.746490 | controller | {
2026-01-20 16:52:19.746523 | controller |   "delta": "0:11:07.742412",
2026-01-20 16:52:19.746546 | controller |   "end": "2026-01-20 16:52:19.584666",
2026-01-20 16:52:19.746566 | controller |   "msg": "non-zero return code",
2026-01-20 16:52:19.746584 | controller |   "rc": 1,
2026-01-20 16:52:19.746601 | controller |   "start": "2026-01-20 16:41:11.842254"
2026-01-20 16:52:19.746617 | controller | }
failure
2026-01-20 16:52:19.792679 | 
2026-01-20 16:52:19.792743 | PLAY RECAP
2026-01-20 16:52:19.792786 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-01-20 16:52:19.792809 | 
2026-01-20 16:52:19.881856 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-01-20 16:52:19.882652 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-20 16:52:20.423876 | 
2026-01-20 16:52:20.423963 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-01-20 16:52:20.444648 | 
2026-01-20 16:52:20.444723 | TASK [Filter out host if needed]
2026-01-20 16:52:20.453476 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-01-20 16:52:20.458003 | 
2026-01-20 16:52:20.458070 | TASK [Ensure file is present]
2026-01-20 16:52:20.821914 | controller | ok
2026-01-20 16:52:20.827465 | 
2026-01-20 16:52:20.827532 | TASK [Manage molecule report file]
2026-01-20 16:52:21.352660 | controller | changed
2026-01-20 16:52:21.358043 | 
2026-01-20 16:52:21.358112 | TASK [Check if we get ci-framework-data basedir]
2026-01-20 16:52:21.619788 | controller | ok
2026-01-20 16:52:21.625952 | 
2026-01-20 16:52:21.626037 | TASK [Create ci-framework-data log directory for zuul]
2026-01-20 16:52:22.012249 | controller | changed
2026-01-20 16:52:22.018218 | 
2026-01-20 16:52:22.018321 | TASK [Copy ci-framework interesting files]
2026-01-20 16:52:22.545821 | controller | changed
2026-01-20 16:52:22.551266 | 
2026-01-20 16:52:22.551456 | TASK [Get SELinux listing]
2026-01-20 16:52:23.074595 | controller | changed
2026-01-20 16:52:23.080378 | 
2026-01-20 16:52:23.080450 | TASK [Generate log index]
2026-01-20 16:52:24.274435 | controller | changed
2026-01-20 16:52:24.279655 | 
2026-01-20 16:52:24.279727 | TASK [Get some env related data]
2026-01-20 16:52:25.096556 | controller | /home/zuul/.local/bin/ansible
2026-01-20 16:52:25.808462 | controller | changed
2026-01-20 16:52:25.837470 | 
2026-01-20 16:52:25.837584 | TASK [Generate list of logs to collect in home directory]
2026-01-20 16:52:26.200479 | controller | ok: All paths examined
2026-01-20 16:52:26.206050 | 
2026-01-20 16:52:26.206141 | LOOP [Copy logs from home directory]
2026-01-20 16:52:26.898143 | controller | changed:
2026-01-20 16:52:26.898389 | controller | {
2026-01-20 16:52:26.898421 | controller |   "atime": 1743544925.4788878,
2026-01-20 16:52:26.898444 | controller |   "ctime": 1743545329.1409318,
2026-01-20 16:52:26.898462 | controller |   "dev": 64513,
2026-01-20 16:52:26.898480 | controller |   "gid": 1000,
2026-01-20 16:52:26.898497 | controller |   "gr_name": "zuul",
2026-01-20 16:52:26.898515 | controller |   "inode": 4518807,
2026-01-20 16:52:26.898533 | controller |   "isblk": false,
2026-01-20 16:52:26.898550 | controller |   "ischr": false,
2026-01-20 16:52:26.898566 | controller |   "isdir": false,
2026-01-20 16:52:26.898582 | controller |   "isfifo": false,
2026-01-20 16:52:26.898598 | controller |   "isgid": false,
2026-01-20 16:52:26.898613 | controller |   "islnk": false,
2026-01-20 16:52:26.898628 | controller |   "isreg": true,
2026-01-20 16:52:26.898643 | controller |   "issock": false,
2026-01-20 16:52:26.898658 | controller |   "isuid": false,
2026-01-20 16:52:26.898673 | controller |   "mode": "0644",
2026-01-20 16:52:26.898687 | controller |   "mtime": 1743545329.1409318,
2026-01-20 16:52:26.898702 | controller |   "nlink": 1,
2026-01-20 16:52:26.898717 | controller |   "path": "/home/zuul/crc-setup.log",
2026-01-20 16:52:26.898733 | controller |   "pw_name": "zuul",
2026-01-20 16:52:26.898752 | controller |   "rgrp": true,
2026-01-20 16:52:26.898769 | controller |   "roth": true,
2026-01-20 16:52:26.898786 | controller |   "rusr": true,
2026-01-20 16:52:26.898802 | controller |   "size": 4108,
2026-01-20 16:52:26.898818 | controller |   "uid": 1000,
2026-01-20 16:52:26.898833 | controller |   "wgrp": false,
2026-01-20 16:52:26.898849 | controller |   "woth": false,
2026-01-20 16:52:26.898864 | controller |   "wusr": true,
2026-01-20 16:52:26.898882 | controller |   "xgrp": false,
2026-01-20 16:52:26.898898 | controller |   "xoth": false,
2026-01-20 16:52:26.898913 | controller |   "xusr": false
2026-01-20 16:52:26.898928 | controller | }
2026-01-20 16:52:27.492202 | controller | changed:
2026-01-20 16:52:27.492329 | controller | {
2026-01-20 16:52:27.492367 | controller |   "atime": 1743545331.0429637,
2026-01-20 16:52:27.492390 | controller |   "ctime": 1743545842.0111232,
2026-01-20 16:52:27.492410 | controller |   "dev": 64513,
2026-01-20 16:52:27.492429 | controller |   "gid": 1000,
2026-01-20 16:52:27.492446 | controller |   "gr_name": "zuul",
2026-01-20 16:52:27.492463 | controller |   "inode": 4194437,
2026-01-20 16:52:27.492479 | controller |   "isblk": false,
2026-01-20 16:52:27.492501 | controller |   "ischr": false,
2026-01-20 16:52:27.492519 | controller |   "isdir": false,
2026-01-20 16:52:27.492535 | controller |   "isfifo": false,
2026-01-20 16:52:27.492551 | controller |   "isgid": false,
2026-01-20 16:52:27.492567 | controller |   "islnk": false,
2026-01-20 16:52:27.492582 | controller |   "isreg": true,
2026-01-20 16:52:27.492597 | controller |   "issock": false,
2026-01-20 16:52:27.492612 | controller |   "isuid": false,
2026-01-20 16:52:27.492628 | controller |   "mode": "0644",
2026-01-20 16:52:27.492644 | controller |   "mtime": 1743545842.0111232,
2026-01-20 16:52:27.492661 | controller |   "nlink": 1,
2026-01-20 16:52:27.492676 | controller |   "path": "/home/zuul/crc-start.log",
2026-01-20 16:52:27.492692 | controller |   "pw_name": "zuul",
2026-01-20 16:52:27.492708 | controller |   "rgrp": true,
2026-01-20 16:52:27.492723 | controller |   "roth": true,
2026-01-20 16:52:27.492740 | controller |   "rusr": true,
2026-01-20 16:52:27.492757 | controller |   "size": 4023,
2026-01-20 16:52:27.492773 | controller |   "uid": 1000,
2026-01-20 16:52:27.492789 | controller |   "wgrp": false,
2026-01-20 16:52:27.492804 | controller |   "woth": false,
2026-01-20 16:52:27.492819 | controller |   "wusr": true,
2026-01-20 16:52:27.492833 | controller |   "xgrp": false,
2026-01-20 16:52:27.492848 | controller |   "xoth": false,
2026-01-20 16:52:27.492862 | controller |   "xusr": false
2026-01-20 16:52:27.492878 | controller | }
2026-01-20 16:52:28.082112 | controller | changed:
2026-01-20 16:52:28.082228 | controller | {
2026-01-20 16:52:28.082257 | controller |   "atime": 1768927252.273596,
2026-01-20 16:52:28.082276 | controller |   "ctime": 1768927269.3627248,
2026-01-20 16:52:28.082309 | controller |   "dev": 64513,
2026-01-20 16:52:28.082336 | controller |   "gid": 1000,
2026-01-20 16:52:28.082354 | controller |   "gr_name": "zuul",
2026-01-20 16:52:28.082371 | controller |   "inode": 4685811,
2026-01-20 16:52:28.082388 | controller |   "isblk": false,
2026-01-20 16:52:28.082404 | controller |   "ischr": false,
2026-01-20 16:52:28.082420 | controller |   "isdir": false,
2026-01-20 16:52:28.082435 | controller |   "isfifo": false,
2026-01-20 16:52:28.082450 | controller |   "isgid": false,
2026-01-20 16:52:28.082464 | controller |   "islnk": false,
2026-01-20 16:52:28.082479 | controller |   "isreg": true,
2026-01-20 16:52:28.082494 | controller |   "issock": false,
2026-01-20 16:52:28.082509 | controller |   "isuid": false,
2026-01-20 16:52:28.082523 | controller |   "mode": "0644",
2026-01-20 16:52:28.082536 | controller |   "mtime": 1768927269.3627248,
2026-01-20 16:52:28.082550 | controller |   "nlink": 1,
2026-01-20 16:52:28.082564 | controller |   "path": "/home/zuul/ansible.log",
2026-01-20 16:52:28.082578 | controller |   "pw_name": "zuul",
2026-01-20 16:52:28.082593 | controller |   "rgrp": true,
2026-01-20 16:52:28.082608 | controller |   "roth": true,
2026-01-20 16:52:28.082621 | controller |   "rusr": true,
2026-01-20 16:52:28.082638 | controller |   "size": 6749,
2026-01-20 16:52:28.082654 | controller |   "uid": 1000,
2026-01-20 16:52:28.082670 | controller |   "wgrp": false,
2026-01-20 16:52:28.082685 | controller |   "woth": false,
2026-01-20 16:52:28.082700 | controller |   "wusr": true,
2026-01-20 16:52:28.082715 | controller |   "xgrp": false,
2026-01-20 16:52:28.082729 | controller |   "xoth": false,
2026-01-20 16:52:28.082743 | controller |   "xusr": false
2026-01-20 16:52:28.082758 | controller | }
2026-01-20 16:52:28.098071 | 
2026-01-20 16:52:28.098199 | TASK [Copy crio stats log file]
2026-01-20 16:52:28.111889 | controller | skipping: Conditional result was False
2026-01-20 16:52:28.117871 | 
2026-01-20 16:52:28.117956 | TASK [Get SELinux related data]
2026-01-20 16:52:28.443221 | controller | <no matches>
2026-01-20 16:52:28.644605 | controller | ERROR
2026-01-20 16:52:28.644725 | controller | {
2026-01-20 16:52:28.644756 | controller |   "delta": "0:00:00.009819",
2026-01-20 16:52:28.644781 | controller |   "end": "2026-01-20 16:52:28.444299",
2026-01-20 16:52:28.644806 | controller |   "msg": "non-zero return code",
2026-01-20 16:52:28.644825 | controller |   "rc": 1,
2026-01-20 16:52:28.644864 | controller |   "start": "2026-01-20 16:52:28.434480"
2026-01-20 16:52:28.644886 | controller | }
2026-01-20 16:52:28.644911 | controller | ERROR: Ignoring Errors
2026-01-20 16:52:28.650099 | 
2026-01-20 16:52:28.650184 | TASK [Create system configuration directory]
2026-01-20 16:52:28.986265 | controller | changed
2026-01-20 16:52:28.991135 | 
2026-01-20 16:52:28.991218 | TASK [Get some of the system configurations]
2026-01-20 16:52:29.514605 | controller | changed
2026-01-20 16:52:29.519788 | 
2026-01-20 16:52:29.519860 | TASK [Copy generated documentation if available]
2026-01-20 16:52:29.533211 | controller | skipping: Conditional result was False
2026-01-20 16:52:29.539036 | 
2026-01-20 16:52:29.539115 | TASK [Copy generated AsciiDoc documentation if available]
2026-01-20 16:52:29.553643 | controller | skipping: Conditional result was False
2026-01-20 16:52:29.559564 | 
2026-01-20 16:52:29.559648 | TASK [Compress logs bigger than 2MB]
2026-01-20 16:52:30.087901 | controller | changed
2026-01-20 16:52:30.093181 | 
2026-01-20 16:52:30.093255 | TASK [Copy files from workspace on node]
2026-01-20 16:52:30.112194 | controller | ok
2026-01-20 16:52:30.134264 | 
2026-01-20 16:52:30.134355 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-20 16:52:30.147108 | controller | skipping: Conditional result was False
2026-01-20 16:52:30.152365 | 
2026-01-20 16:52:30.152433 | TASK [fetch-output : Set log path for single node]
2026-01-20 16:52:30.179593 | controller | ok
2026-01-20 16:52:30.184424 | 
2026-01-20 16:52:30.184491 | LOOP [fetch-output : Ensure local output dirs]
2026-01-20 16:52:30.375852 | controller -> localhost | ok: "/var/lib/zuul/builds/55e6eb0768914fb284749682e637d3da/work/logs"
2026-01-20 16:52:30.376063 | controller -> localhost | changed: All items complete
2026-01-20 16:52:30.376093 | 
2026-01-20 16:52:30.555473 | controller -> localhost | changed: "/var/lib/zuul/builds/55e6eb0768914fb284749682e637d3da/work/artifacts"
2026-01-20 16:52:30.738943 | controller -> localhost | changed: "/var/lib/zuul/builds/55e6eb0768914fb284749682e637d3da/work/docs"
2026-01-20 16:52:30.751968 | 
2026-01-20 16:52:30.752064 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-20 16:52:31.409205 | controller | changed:
2026-01-20 16:52:31.409390 | controller | .d..t...... ./
2026-01-20 16:52:31.409423 | controller | >f+++++++++ README.html
2026-01-20 16:52:31.409445 | controller | >f+++++++++ ansible-execution.log
2026-01-20 16:52:31.409465 | controller | >f+++++++++ ansible.log
2026-01-20 16:52:31.409486 | controller | >f+++++++++ crc-setup.log
2026-01-20 16:52:31.409712 | controller | >f+++++++++ crc-start.log
2026-01-20 16:52:31.409745 | controller | >f+++++++++ dmesg.log
2026-01-20 16:52:31.409836 | controller | >f+++++++++ installed-pkgs.log
2026-01-20 16:52:31.409894 | controller | >f+++++++++ python.log
2026-01-20 16:52:31.409919 | controller | >f+++++++++ registries.conf
2026-01-20 16:52:31.409961 | controller | >f+++++++++ report.html
2026-01-20 16:52:31.409983 | controller | >f+++++++++ selinux-denials.log
2026-01-20 16:52:31.410002 | controller | >f+++++++++ selinux-listing.log
2026-01-20 16:52:31.410044 | controller | cd+++++++++ ci-framework-data/
2026-01-20 16:52:31.410066 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-01-20 16:52:31.410087 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-01-20 16:52:31.410141 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2026-01-20 16:52:31.410164 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2026-01-20 16:52:31.410205 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2026-01-20 16:52:31.410225 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-01-20 16:52:31.410243 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-01-20 16:52:31.410286 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2026-01-20 16:52:31.410338 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2026-01-20 16:52:31.410361 | controller | cd+++++++++ ci-framework-data/logs/
2026-01-20 16:52:31.410401 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-01-20 16:52:31.410424 | controller | cd+++++++++ ci-framework-data/tests/
2026-01-20 16:52:31.410443 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/
2026-01-20 16:52:31.410481 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/ansible_command_logs/
2026-01-20 16:52:31.410502 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/artifacts/
2026-01-20 16:52:31.410520 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/manifests/
2026-01-20 16:52:31.410555 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/manifests/shiftstackclient-shiftstack_incluster_secret.yml
2026-01-20 16:52:31.410578 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/manifests/shiftstackclient-shiftstack_pod.yml
2026-01-20 16:52:31.410597 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/manifests/shiftstackclient-shiftstack_pvc.yml
2026-01-20 16:52:31.410614 | controller | cd+++++++++ registries.conf.d/
2026-01-20 16:52:31.410653 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-01-20 16:52:31.410672 | controller | cd+++++++++ system-config/
2026-01-20 16:52:31.410690 | controller | cd+++++++++ system-config/libvirt/
2026-01-20 16:52:31.410728 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-01-20 16:52:31.410747 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-01-20 16:52:31.410764 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2026-01-20 16:52:31.410799 | controller | >f+++++++++ system-config/libvirt/network.conf
2026-01-20 16:52:31.410820 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2026-01-20 16:52:31.410838 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2026-01-20 16:52:31.410872 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2026-01-20 16:52:31.410893 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2026-01-20 16:52:31.410911 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2026-01-20 16:52:31.410927 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2026-01-20 16:52:31.410964 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2026-01-20 16:52:31.410982 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2026-01-20 16:52:31.410999 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2026-01-20 16:52:31.411034 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2026-01-20 16:52:31.411056 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2026-01-20 16:52:31.411074 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2026-01-20 16:52:31.928346 | controller | changed: .d..t...... ./
2026-01-20 16:52:32.449592 | controller | changed: .d..t...... ./
2026-01-20 16:52:32.467525 | 
2026-01-20 16:52:32.467636 | TASK [Return artifact to Zuul]
2026-01-20 16:52:32.495798 | controller | ok
2026-01-20 16:52:32.514760 | 
2026-01-20 16:52:32.514816 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-01-20 16:52:32.514917 | 
2026-01-20 16:52:32.514945 | PLAY RECAP
2026-01-20 16:52:32.514981 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-01-20 16:52:32.515002 | 
2026-01-20 16:52:32.591476 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-20 16:52:32.592174 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-20 16:52:33.116830 | 
2026-01-20 16:52:33.116971 | PLAY [all]
2026-01-20 16:52:33.135694 | 
2026-01-20 16:52:33.135770 | TASK [include_role : fetch-output]
2026-01-20 16:52:33.175007 | controller | ok
2026-01-20 16:52:33.191191 | 
2026-01-20 16:52:33.191276 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-20 16:52:33.255634 | controller | skipping: Conditional result was False
2026-01-20 16:52:33.261596 | 
2026-01-20 16:52:33.261680 | TASK [fetch-output : Set log path for single node]
2026-01-20 16:52:33.303342 | controller | ok
2026-01-20 16:52:33.309471 | 
2026-01-20 16:52:33.309562 | LOOP [fetch-output : Ensure local output dirs]
2026-01-20 16:52:33.653599 | controller -> localhost | ok: "/var/lib/zuul/builds/55e6eb0768914fb284749682e637d3da/work/logs"
2026-01-20 16:52:33.842869 | controller -> localhost | ok: "/var/lib/zuul/builds/55e6eb0768914fb284749682e637d3da/work/artifacts"
2026-01-20 16:52:34.038805 | controller -> localhost | ok: "/var/lib/zuul/builds/55e6eb0768914fb284749682e637d3da/work/docs"
2026-01-20 16:52:34.055858 | 
2026-01-20 16:52:34.055934 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-20 16:52:34.666686 | controller | ok
2026-01-20 16:52:34.666852 | controller | ok: All items complete
2026-01-20 16:52:34.666882 | 
2026-01-20 16:52:35.114700 | controller | ok
2026-01-20 16:52:35.582646 | controller | ok
2026-01-20 16:52:35.595954 | 
2026-01-20 16:52:35.596065 | TASK [include_role : fetch-output-openshift]
2026-01-20 16:52:35.619502 | controller | skipping: Conditional result was False
2026-01-20 16:52:35.625198 | 
2026-01-20 16:52:35.625276 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-20 16:52:35.966565 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006480
2026-01-20 16:52:36.176458 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006567
2026-01-20 16:52:36.209389 | 
2026-01-20 16:52:36.209483 | PLAY [all]
2026-01-20 16:52:36.223854 | 
2026-01-20 16:52:36.223930 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-01-20 16:52:36.687681 | controller | changed
2026-01-20 16:52:36.709337 | 
2026-01-20 16:52:36.709397 | PLAY RECAP
2026-01-20 16:52:36.709449 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-01-20 16:52:36.709473 | 
2026-01-20 16:52:36.776366 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-20 16:52:36.777038 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-01-20 16:52:37.319124 | 
2026-01-20 16:52:37.319250 | PLAY [localhost]
2026-01-20 16:52:37.336265 | 
2026-01-20 16:52:37.336360 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-20 16:52:37.647179 | localhost | changed
2026-01-20 16:52:37.651797 | 
2026-01-20 16:52:37.651885 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-20 16:52:37.669394 | localhost | ok
2026-01-20 16:52:37.677085 | 
2026-01-20 16:52:37.677170 | TASK [add-fileserver : Create SSH private key tempfile]
2026-01-20 16:52:37.967446 | localhost | changed
2026-01-20 16:52:37.973619 | 
2026-01-20 16:52:37.973715 | TASK [add-fileserver : Create SSH private key from secret]
2026-01-20 16:52:38.537619 | localhost | changed
2026-01-20 16:52:38.542584 | 
2026-01-20 16:52:38.542660 | TASK [add-fileserver : Add fileserver ssh key]
2026-01-20 16:52:38.873692 | localhost | Identity added: /var/lib/zuul/builds/55e6eb0768914fb284749682e637d3da/work/tmp/ansible.hlccg1vc (/var/lib/zuul/builds/55e6eb0768914fb284749682e637d3da/work/tmp/ansible.hlccg1vc)
2026-01-20 16:52:38.873848 | localhost | ok: Runtime: 0:00:00.006284
2026-01-20 16:52:38.878075 | 
2026-01-20 16:52:38.878163 | TASK [add-fileserver : Remove SSH private key from disk]
2026-01-20 16:52:39.098140 | localhost | ok: Runtime: 0:00:00.004246
2026-01-20 16:52:39.102644 | 
2026-01-20 16:52:39.102712 | TASK [add-fileserver : Add fileserver to inventory]
2026-01-20 16:52:39.150728 | localhost | changed
2026-01-20 16:52:39.155557 | 
2026-01-20 16:52:39.155625 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-01-20 16:52:39.486980 | localhost | changed
2026-01-20 16:52:39.508311 | 
2026-01-20 16:52:39.508442 | PLAY [localhost]
2026-01-20 16:52:39.519639 | 
2026-01-20 16:52:39.519703 | TASK [Generate bulk log download script]
2026-01-20 16:52:39.537673 | localhost | ok
2026-01-20 16:52:39.550179 | 
2026-01-20 16:52:39.550248 | TASK [local-log-download : Check API endpoint is defined]
2026-01-20 16:52:39.577319 | localhost | ok: All assertions passed
2026-01-20 16:52:39.581690 | 
2026-01-20 16:52:39.581758 | TASK [local-log-download : Create download script]
2026-01-20 16:52:39.929226 | localhost -> localhost | changed
2026-01-20 16:52:39.938146 | 
2026-01-20 16:52:39.938217 | TASK [Register quick-download link]
2026-01-20 16:52:39.955490 | localhost | ok
2026-01-20 16:52:39.994000 | 
2026-01-20 16:52:39.994083 | PLAY [logserver.rdoproject.org]
2026-01-20 16:52:40.003447 | 
2026-01-20 16:52:40.003512 | TASK [Set zuul-log-path fact]
2026-01-20 16:52:40.019818 | logserver.rdoproject.org | ok
2026-01-20 16:52:40.029241 | 
2026-01-20 16:52:40.029324 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-20 16:52:40.055421 | logserver.rdoproject.org | ok
2026-01-20 16:52:40.060825 | 
2026-01-20 16:52:40.060890 | TASK [upload-logs : Create log directories]
2026-01-20 16:52:41.663042 | logserver.rdoproject.org | changed
2026-01-20 16:52:41.666085 | 
2026-01-20 16:52:41.666167 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-01-20 16:52:41.882140 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004881
2026-01-20 16:52:41.887461 | 
2026-01-20 16:52:41.887537 | TASK [upload-logs : Upload logs to log server]
2026-01-20 16:52:43.417002 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-01-20 16:52:43.420029 | 
2026-01-20 16:52:43.420100 | LOOP [upload-logs : Compress console log and json output]
2026-01-20 16:52:43.458624 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-20 16:52:43.467907 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-20 16:52:43.478552 | 
2026-01-20 16:52:43.478655 | LOOP [upload-logs : Upload compressed console log and json output]
2026-01-20 16:52:43.514058 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-20 16:52:43.514318 | 
2026-01-20 16:52:43.518947 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-20 16:52:43.526581 | 
2026-01-20 16:52:43.526670 | LOOP [upload-logs : Upload console log and json output]