2025-12-03 11:16:14.530820 | Job console starting...
2025-12-03 11:16:14.776807 | Updating repositories
2025-12-03 11:16:15.574304 | Preparing job workspace
2025-12-03 11:16:34.132515 | Running Ansible setup...
2025-12-03 11:16:41.171089 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-03 11:16:42.692376 | 
2025-12-03 11:16:42.692486 | PLAY [localhost]
2025-12-03 11:16:42.713144 | 
2025-12-03 11:16:42.713243 | TASK [Gathering Facts]
2025-12-03 11:16:45.375160 | localhost | ok
2025-12-03 11:16:45.403723 | 
2025-12-03 11:16:45.403818 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-03 11:16:46.384810 | localhost -> localhost | changed
2025-12-03 11:16:46.391814 | 
2025-12-03 11:16:46.391904 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-03 11:16:49.078406 | localhost -> localhost | changed
2025-12-03 11:16:49.087087 | 
2025-12-03 11:16:49.087175 | TASK [Setup log path fact]
2025-12-03 11:16:49.183452 | localhost | ok
2025-12-03 11:16:49.200237 | 
2025-12-03 11:16:49.200324 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-03 11:16:49.228106 | localhost | ok
2025-12-03 11:16:49.238963 | 
2025-12-03 11:16:49.239043 | TASK [emit-job-header : Print job information]
2025-12-03 11:16:49.286579 | # Job Information
2025-12-03 11:16:49.286714 | Ansible Version: 2.15.12
2025-12-03 11:16:49.286746 | Job: cifmw-molecule-hci_prepare
2025-12-03 11:16:49.286795 | Pipeline: github-check
2025-12-03 11:16:49.286820 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-12-03 11:16:49.286840 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3451
2025-12-03 11:16:49.286862 | Log URL (when completed): https://logserver.rdoproject.org/bc9/rdoproject.org/bc931c3292fb49d6804bf93c4b66616d/
2025-12-03 11:16:49.286882 | Event ID: 4ab514e0-d039-11f0-81e0-fb882bdc02c6
2025-12-03 11:16:49.290543 | 
2025-12-03 11:16:49.290609 | LOOP [emit-job-header : Print node information]
2025-12-03 11:16:49.874737 | localhost | ok:
2025-12-03 11:16:49.874963 | localhost | # Node Information
2025-12-03 11:16:49.875010 | localhost | Inventory Hostname: controller
2025-12-03 11:16:49.875042 | localhost | Hostname: np0005543732
2025-12-03 11:16:49.875069 | localhost | Username: zuul
2025-12-03 11:16:49.875097 | localhost | Distro: CentOS 9
2025-12-03 11:16:49.875122 | localhost | Provider: ibm-bm4-nodepool
2025-12-03 11:16:49.875145 | localhost | Region: regionOne
2025-12-03 11:16:49.875168 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-12-03 11:16:49.875190 | localhost | Product Name: OpenStack Compute
2025-12-03 11:16:49.875212 | localhost | Interface IP: 192.168.26.98
2025-12-03 11:16:49.960462 | 
2025-12-03 11:16:49.960551 | PLAY [all]
2025-12-03 11:16:49.982822 | 
2025-12-03 11:16:49.982898 | TASK [Gather network facts]
2025-12-03 11:16:50.438557 | controller | ok
2025-12-03 11:16:50.480375 | 
2025-12-03 11:16:50.480456 | TASK [include_role : start-zuul-console]
2025-12-03 11:16:50.541204 | controller | ok
2025-12-03 11:16:50.614781 | 
2025-12-03 11:16:50.614860 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-03 11:16:51.468651 | controller | ok
2025-12-03 11:16:51.479390 | 
2025-12-03 11:16:51.479460 | TASK [include_role : add-build-sshkey]
2025-12-03 11:16:51.558544 | controller | ok
2025-12-03 11:16:51.614854 | 
2025-12-03 11:16:51.615465 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-03 11:16:52.457311 | controller -> localhost | ok
2025-12-03 11:16:52.465265 | 
2025-12-03 11:16:52.465351 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-03 11:16:52.535693 | controller | ok
2025-12-03 11:16:52.571786 | controller | included: /var/lib/zuul/builds/bc931c3292fb49d6804bf93c4b66616d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-03 11:16:52.600646 | 
2025-12-03 11:16:52.600720 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-03 11:16:54.389558 | controller -> localhost | Generating public/private rsa key pair.
2025-12-03 11:16:54.389754 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/bc931c3292fb49d6804bf93c4b66616d/work/bc931c3292fb49d6804bf93c4b66616d_id_rsa.
2025-12-03 11:16:54.389792 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/bc931c3292fb49d6804bf93c4b66616d/work/bc931c3292fb49d6804bf93c4b66616d_id_rsa.pub.
2025-12-03 11:16:54.389818 | controller -> localhost | The key fingerprint is:
2025-12-03 11:16:54.389842 | controller -> localhost | SHA256:H15l8yM8RoeRdBWEAKkcKzyV7LlN7eKSRGrM4LzjWbk zuul-build-sshkey
2025-12-03 11:16:54.389863 | controller -> localhost | The key's randomart image is:
2025-12-03 11:16:54.389882 | controller -> localhost | +---[RSA 3072]----+
2025-12-03 11:16:54.389901 | controller -> localhost | |      . ..o.oo+++|
2025-12-03 11:16:54.389943 | controller -> localhost | |       = .   o+  |
2025-12-03 11:16:54.389966 | controller -> localhost | |    . + = .  o+. |
2025-12-03 11:16:54.389987 | controller -> localhost | |   . + B . .oo.o |
2025-12-03 11:16:54.390006 | controller -> localhost | |  o + = S o .= ..|
2025-12-03 11:16:54.390024 | controller -> localhost | |   o =.o = +. o .|
2025-12-03 11:16:54.390043 | controller -> localhost | |    oo. o +      |
2025-12-03 11:16:54.390063 | controller -> localhost | |   oo .o .       |
2025-12-03 11:16:54.390081 | controller -> localhost | |  .o.E  .        |
2025-12-03 11:16:54.390099 | controller -> localhost | +----[SHA256]-----+
2025-12-03 11:16:54.390144 | controller -> localhost | ok: Runtime: 0:00:00.221814
2025-12-03 11:16:54.397395 | 
2025-12-03 11:16:54.397469 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-03 11:16:54.489180 | controller | ok
2025-12-03 11:16:54.516937 | controller | included: /var/lib/zuul/builds/bc931c3292fb49d6804bf93c4b66616d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-03 11:16:54.582141 | 
2025-12-03 11:16:54.582243 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-03 11:16:54.652581 | controller | skipping: Conditional result was False
2025-12-03 11:16:54.659673 | 
2025-12-03 11:16:54.659790 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-03 11:16:55.374432 | controller | changed
2025-12-03 11:16:55.380652 | 
2025-12-03 11:16:55.380734 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-03 11:16:55.661961 | controller | ok
2025-12-03 11:16:55.667942 | 
2025-12-03 11:16:55.668022 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-03 11:16:56.480588 | controller | changed
2025-12-03 11:16:56.486189 | 
2025-12-03 11:16:56.486291 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-03 11:16:57.596206 | controller | changed
2025-12-03 11:16:57.622802 | 
2025-12-03 11:16:57.622891 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-03 11:16:57.703933 | controller | skipping: Conditional result was False
2025-12-03 11:16:57.709991 | 
2025-12-03 11:16:57.710264 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-03 11:16:58.846784 | controller -> localhost | changed
2025-12-03 11:16:58.856779 | 
2025-12-03 11:16:58.856858 | TASK [add-build-sshkey : Add back temp key]
2025-12-03 11:16:59.758646 | controller -> localhost | Identity added: /var/lib/zuul/builds/bc931c3292fb49d6804bf93c4b66616d/work/bc931c3292fb49d6804bf93c4b66616d_id_rsa (zuul-build-sshkey)
2025-12-03 11:16:59.758854 | controller -> localhost | ok: Runtime: 0:00:00.058623
2025-12-03 11:16:59.765561 | 
2025-12-03 11:16:59.765629 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-03 11:17:00.301482 | controller | ok
2025-12-03 11:17:00.305988 | 
2025-12-03 11:17:00.306061 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-03 11:17:00.403764 | controller | skipping: Conditional result was False
2025-12-03 11:17:00.413399 | 
2025-12-03 11:17:00.413479 | TASK [include_role : validate-host]
2025-12-03 11:17:00.502106 | controller | ok
2025-12-03 11:17:00.575546 | 
2025-12-03 11:17:00.575679 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-03 11:17:00.657330 | controller | ok
2025-12-03 11:17:00.665083 | 
2025-12-03 11:17:00.665169 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-03 11:17:01.509237 | controller -> localhost | ok
2025-12-03 11:17:01.518763 | 
2025-12-03 11:17:01.518846 | TASK [validate-host : Collect information about the host]
2025-12-03 11:17:02.190938 | controller | ok
2025-12-03 11:17:02.226401 | 
2025-12-03 11:17:02.226489 | TASK [validate-host : Sanitize hostname]
2025-12-03 11:17:02.366804 | controller | ok
2025-12-03 11:17:02.371752 | 
2025-12-03 11:17:02.371817 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-03 11:17:03.760004 | controller -> localhost | changed
2025-12-03 11:17:03.765781 | 
2025-12-03 11:17:03.765868 | TASK [validate-host : Collect information about zuul worker]
2025-12-03 11:17:04.424370 | controller | ok
2025-12-03 11:17:04.429376 | 
2025-12-03 11:17:04.429466 | TASK [validate-host : Write out all zuul information for each host]
2025-12-03 11:17:05.977924 | controller -> localhost | changed
2025-12-03 11:17:05.994298 | 
2025-12-03 11:17:05.994371 | TASK [include_role : prepare-workspace-openshift]
2025-12-03 11:17:06.034129 | controller | skipping: Conditional result was False
2025-12-03 11:17:06.039050 | 
2025-12-03 11:17:06.039131 | TASK [include_role : remove-zuul-sshkey]
2025-12-03 11:17:06.068546 | controller | skipping: Conditional result was False
2025-12-03 11:17:06.074974 | 
2025-12-03 11:17:06.075058 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-03 11:17:06.289322 | controller | ok: "logs"
2025-12-03 11:17:06.289539 | controller | ok: All items complete
2025-12-03 11:17:06.289572 | 
2025-12-03 11:17:06.473297 | controller | ok: "artifacts"
2025-12-03 11:17:06.676604 | controller | ok: "docs"
2025-12-03 11:17:06.693208 | 
2025-12-03 11:17:06.693320 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-03 11:17:06.955562 | controller | changed: "logs"
2025-12-03 11:17:07.146763 | controller | changed: "artifacts"
2025-12-03 11:17:07.342894 | controller | changed: "docs"
2025-12-03 11:17:07.403327 | 
2025-12-03 11:17:07.403410 | PLAY RECAP
2025-12-03 11:17:07.403458 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-03 11:17:07.403488 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-03 11:17:07.403509 | 
2025-12-03 11:17:07.739777 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-03 11:17:07.740598 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-03 11:17:09.963979 | 
2025-12-03 11:17:09.964097 | PLAY [all]
2025-12-03 11:17:10.050035 | 
2025-12-03 11:17:10.050149 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-03 11:17:10.216506 | controller | ok
2025-12-03 11:17:10.224238 | 
2025-12-03 11:17:10.224318 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-03 11:17:10.945562 | controller | changed
2025-12-03 11:17:10.951736 | 
2025-12-03 11:17:10.951819 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-03 11:17:12.458438 | controller | changed
2025-12-03 11:17:12.471332 | 
2025-12-03 11:17:12.471416 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-03 11:17:13.438054 | controller | changed:
2025-12-03 11:17:13.438216 | controller | {
2025-12-03 11:17:13.438262 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-03 11:17:13.438293 | controller | }
2025-12-03 11:17:13.684679 | controller | changed:
2025-12-03 11:17:13.684766 | controller | {
2025-12-03 11:17:13.684799 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-03 11:17:13.684824 | controller | }
2025-12-03 11:17:13.908299 | controller | changed:
2025-12-03 11:17:13.908388 | controller | {
2025-12-03 11:17:13.908416 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-12-03 11:17:13.908439 | controller | }
2025-12-03 11:17:14.157642 | controller | changed:
2025-12-03 11:17:14.157731 | controller | {
2025-12-03 11:17:14.157759 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-03 11:17:14.157782 | controller | }
2025-12-03 11:17:14.386287 | controller | changed:
2025-12-03 11:17:14.386373 | controller | {
2025-12-03 11:17:14.386402 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-03 11:17:14.386425 | controller | }
2025-12-03 11:17:14.611029 | controller | changed:
2025-12-03 11:17:14.611120 | controller | {
2025-12-03 11:17:14.611149 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-03 11:17:14.611172 | controller | }
2025-12-03 11:17:14.832651 | controller | changed:
2025-12-03 11:17:14.832729 | controller | {
2025-12-03 11:17:14.832757 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-12-03 11:17:14.832780 | controller | }
2025-12-03 11:17:15.050535 | controller | changed:
2025-12-03 11:17:15.050616 | controller | {
2025-12-03 11:17:15.050645 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-12-03 11:17:15.050669 | controller | }
2025-12-03 11:17:15.300407 | controller | changed:
2025-12-03 11:17:15.300485 | controller | {
2025-12-03 11:17:15.300513 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-03 11:17:15.300535 | controller | }
2025-12-03 11:17:15.480209 | controller | changed:
2025-12-03 11:17:15.480297 | controller | {
2025-12-03 11:17:15.480325 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-03 11:17:15.480346 | controller | }
2025-12-03 11:17:15.726784 | controller | changed:
2025-12-03 11:17:15.726872 | controller | {
2025-12-03 11:17:15.726901 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-03 11:17:15.726944 | controller | }
2025-12-03 11:17:15.947489 | controller | changed:
2025-12-03 11:17:15.947577 | controller | {
2025-12-03 11:17:15.947606 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-03 11:17:15.947635 | controller | }
2025-12-03 11:17:16.170996 | controller | changed:
2025-12-03 11:17:16.171076 | controller | {
2025-12-03 11:17:16.171104 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-03 11:17:16.171127 | controller | }
2025-12-03 11:17:16.375310 | controller | changed:
2025-12-03 11:17:16.375387 | controller | {
2025-12-03 11:17:16.375416 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-03 11:17:16.375437 | controller | }
2025-12-03 11:17:16.604585 | controller | changed:
2025-12-03 11:17:16.604674 | controller | {
2025-12-03 11:17:16.604703 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-03 11:17:16.604725 | controller | }
2025-12-03 11:17:16.819124 | controller | changed:
2025-12-03 11:17:16.819200 | controller | {
2025-12-03 11:17:16.819241 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-03 11:17:16.819265 | controller | }
2025-12-03 11:17:17.046700 | controller | changed:
2025-12-03 11:17:17.046817 | controller | {
2025-12-03 11:17:17.046845 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-12-03 11:17:17.046869 | controller | }
2025-12-03 11:17:17.268340 | controller | changed:
2025-12-03 11:17:17.268491 | controller | {
2025-12-03 11:17:17.268520 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-12-03 11:17:17.268550 | controller | }
2025-12-03 11:17:17.488000 | controller | changed:
2025-12-03 11:17:17.488085 | controller | {
2025-12-03 11:17:17.488113 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-03 11:17:17.488134 | controller | }
2025-12-03 11:17:17.709178 | controller | changed:
2025-12-03 11:17:17.709282 | controller | {
2025-12-03 11:17:17.709312 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-03 11:17:17.709335 | controller | }
2025-12-03 11:17:17.953893 | controller | changed:
2025-12-03 11:17:17.954003 | controller | {
2025-12-03 11:17:17.954032 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-03 11:17:17.954054 | controller | }
2025-12-03 11:17:18.176147 | controller | changed:
2025-12-03 11:17:18.176241 | controller | {
2025-12-03 11:17:18.176271 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-03 11:17:18.176293 | controller | }
2025-12-03 11:17:18.423153 | controller | changed:
2025-12-03 11:17:18.423246 | controller | {
2025-12-03 11:17:18.423277 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-12-03 11:17:18.423300 | controller | }
2025-12-03 11:17:18.658501 | controller | changed:
2025-12-03 11:17:18.658605 | controller | {
2025-12-03 11:17:18.658659 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-03 11:17:18.658684 | controller | }
2025-12-03 11:17:18.881591 | controller | changed:
2025-12-03 11:17:18.881669 | controller | {
2025-12-03 11:17:18.881695 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-03 11:17:18.881715 | controller | }
2025-12-03 11:17:19.104981 | controller | changed:
2025-12-03 11:17:19.105059 | controller | {
2025-12-03 11:17:19.105087 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-03 11:17:19.105109 | controller | }
2025-12-03 11:17:19.137017 | 
2025-12-03 11:17:19.137126 | TASK [Set timezone to UTC]
2025-12-03 11:17:19.815937 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-03 11:17:19.826270 | 
2025-12-03 11:17:19.826338 | TASK [Create nodepool directory]
2025-12-03 11:17:20.118643 | controller | changed
2025-12-03 11:17:20.126145 | 
2025-12-03 11:17:20.126211 | TASK [Create nodepool sub_nodes file]
2025-12-03 11:17:20.863246 | controller | changed
2025-12-03 11:17:20.880166 | 
2025-12-03 11:17:20.880320 | TASK [Create nodepool sub_nodes_private file]
2025-12-03 11:17:21.553891 | controller | changed
2025-12-03 11:17:21.559635 | 
2025-12-03 11:17:21.559715 | LOOP [Populate nodepool sub_nodes file]
2025-12-03 11:17:21.651023 | 
2025-12-03 11:17:21.651239 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-03 11:17:21.682415 | 
2025-12-03 11:17:21.682603 | TASK [Create nodepool primary file]
2025-12-03 11:17:21.717669 | controller | skipping: Conditional result was False
2025-12-03 11:17:21.723045 | 
2025-12-03 11:17:21.723123 | TASK [Create nodepool node_private for this node]
2025-12-03 11:17:22.409106 | controller | changed
2025-12-03 11:17:22.414773 | 
2025-12-03 11:17:22.414836 | LOOP [Copy ssh keys to nodepool directory]
2025-12-03 11:17:23.051146 | controller | ok: Item: id_rsa Runtime: 0:00:00.005119
2025-12-03 11:17:23.051332 | 
2025-12-03 11:17:23.238168 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004752
2025-12-03 11:17:23.249062 | 
2025-12-03 11:17:23.249163 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-03 11:17:23.946613 | controller | changed
2025-12-03 11:17:23.954403 | 
2025-12-03 11:17:23.954482 | TASK [Validate sudoers config after edits]
2025-12-03 11:17:24.260494 | controller | /etc/sudoers: parsed OK
2025-12-03 11:17:24.260561 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-03 11:17:24.260572 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-03 11:17:24.260578 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-03 11:17:24.525021 | controller | ok: Runtime: 0:00:00.004667
2025-12-03 11:17:24.531065 | 
2025-12-03 11:17:24.531161 | TASK [Show the environment passed in to job shell scripts]
2025-12-03 11:17:24.842836 | controller | SHELL=/bin/bash
2025-12-03 11:17:24.842885 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-03 11:17:24.842895 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-03 11:17:24.842903 | controller | ZUUL_CHANGES=rdo-jobs:master:refs/changes/88/58488/1^openstack-k8s-operators/ci-framework:main:refs/changes/51/3451/2261ed433b240c3ce81443dcd27657d36bfed699
2025-12-03 11:17:24.842910 | controller | PWD=/home/zuul
2025-12-03 11:17:24.842916 | controller | ZUUL_PIPELINE=github-check
2025-12-03 11:17:24.842969 | controller | LOGNAME=zuul
2025-12-03 11:17:24.842982 | controller | XDG_SESSION_TYPE=tty
2025-12-03 11:17:24.842988 | controller | _=/usr/bin/env
2025-12-03 11:17:24.842993 | controller | MOTD_SHOWN=pam
2025-12-03 11:17:24.842998 | controller | HOME=/home/zuul
2025-12-03 11:17:24.843004 | controller | LANG=en_US.UTF-8
2025-12-03 11:17:24.843009 | controller | SSH_CONNECTION=192.168.26.12 33858 192.168.26.98 22
2025-12-03 11:17:24.843015 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-12-03 11:17:24.843023 | controller | ZUUL_CHANGE_IDS=58488,1 3451,2261ed433b240c3ce81443dcd27657d36bfed699
2025-12-03 11:17:24.843028 | controller | WORKSPACE=/home/zuul/workspace
2025-12-03 11:17:24.843034 | controller | XDG_SESSION_CLASS=user
2025-12-03 11:17:24.843039 | controller | SELINUX_ROLE_REQUESTED=
2025-12-03 11:17:24.843044 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-03 11:17:24.843050 | controller | USER=zuul
2025-12-03 11:17:24.843055 | controller | ZUUL_VOTING=True
2025-12-03 11:17:24.843061 | controller | BUILD_TIMEOUT=1800000
2025-12-03 11:17:24.843066 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-03 11:17:24.843071 | controller | SHLVL=1
2025-12-03 11:17:24.843077 | controller | ZUUL_PATCHSET=2261ed433b240c3ce81443dcd27657d36bfed699
2025-12-03 11:17:24.843082 | controller | XDG_SESSION_ID=1
2025-12-03 11:17:24.843087 | controller | ZUUL_BRANCH=main
2025-12-03 11:17:24.843093 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-03 11:17:24.843098 | controller | SSH_CLIENT=192.168.26.12 33858 22
2025-12-03 11:17:24.843104 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-03 11:17:24.843109 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-03 11:17:24.843115 | controller | which_declare=declare -f
2025-12-03 11:17:24.843121 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-03 11:17:24.843126 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-03 11:17:24.843132 | controller | ZUUL_CHANGE=3451
2025-12-03 11:17:24.843138 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-03 11:17:24.843144 | controller | ZUUL_UUID=bc931c3292fb49d6804bf93c4b66616d
2025-12-03 11:17:24.843149 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-03 11:17:24.843154 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-03 11:17:24.843160 | controller | }
2025-12-03 11:17:25.098741 | controller | ok: Runtime: 0:00:00.005312
2025-12-03 11:17:25.104153 | 
2025-12-03 11:17:25.104215 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-03 11:17:25.161717 | controller | skipping: Conditional result was False
2025-12-03 11:17:25.167653 | 
2025-12-03 11:17:25.167723 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-03 11:17:25.728084 | controller | skipping: Conditional result was False
2025-12-03 11:17:25.733860 | 
2025-12-03 11:17:25.733986 | TASK [Ensure legacy workspace directory]
2025-12-03 11:17:25.994473 | controller | changed
2025-12-03 11:17:26.128986 | 
2025-12-03 11:17:26.129069 | PLAY RECAP
2025-12-03 11:17:26.129114 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-03 11:17:26.129137 | 
2025-12-03 11:17:26.216507 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-03 11:17:26.217263 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-03 11:17:27.820071 | 
2025-12-03 11:17:27.820161 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-03 11:17:27.863835 | 
2025-12-03 11:17:27.863945 | TASK [Create zuul-output directory]
2025-12-03 11:17:28.355024 | controller | changed
2025-12-03 11:17:28.366448 | 
2025-12-03 11:17:28.366584 | TASK [Slurp Zuul inventory test]
2025-12-03 11:17:29.252432 | controller -> localhost | ok
2025-12-03 11:17:29.259859 | 
2025-12-03 11:17:29.259971 | TASK [Save zuul inventory]
2025-12-03 11:17:30.755902 | controller | changed
2025-12-03 11:17:30.768163 | 
2025-12-03 11:17:30.768266 | TASK [Save zuul vars without the change_message]
2025-12-03 11:17:31.714775 | controller | changed
2025-12-03 11:17:31.774332 | 
2025-12-03 11:17:31.774408 | PLAY RECAP
2025-12-03 11:17:31.774451 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-03 11:17:31.774475 | 
2025-12-03 11:17:32.052243 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-03 11:17:32.053152 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-03 11:17:33.641132 | 
2025-12-03 11:17:33.641239 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-03 11:17:33.683684 | 
2025-12-03 11:17:33.683774 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-03 11:17:33.737054 | controller | ok
2025-12-03 11:17:33.794481 | 
2025-12-03 11:17:33.794582 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-03 11:17:33.884395 | controller | skipping: Conditional result was False
2025-12-03 11:17:33.890364 | 
2025-12-03 11:17:33.890437 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-03 11:17:34.518201 | controller | ok
2025-12-03 11:17:34.528500 | 
2025-12-03 11:17:34.528588 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-03 11:17:35.707555 | controller | ok
2025-12-03 11:17:35.729541 | 
2025-12-03 11:17:35.729624 | TASK [Prepare workspace]
2025-12-03 11:17:35.799147 | controller | ok
2025-12-03 11:17:35.863285 | 
2025-12-03 11:17:35.863404 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-03 11:17:36.418545 | controller | ok
2025-12-03 11:17:36.438380 | 
2025-12-03 11:17:36.438467 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-03 11:17:40.990869 | controller | Output suppressed because no_log was given
2025-12-03 11:17:41.000088 | 
2025-12-03 11:17:41.000163 | LOOP [Create zuul-output directory]
2025-12-03 11:17:41.249149 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-03 11:17:41.431133 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-03 11:17:41.441246 | 
2025-12-03 11:17:41.441343 | TASK [Install required packages]
2025-12-03 11:18:31.509475 | controller | changed
2025-12-03 11:18:31.515074 | 
2025-12-03 11:18:31.515149 | TASK [Install venv]
2025-12-03 11:19:33.793641 | controller | changed
2025-12-03 11:19:33.820501 | 
2025-12-03 11:19:33.820588 | PLAY RECAP
2025-12-03 11:19:33.820635 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-03 11:19:33.820658 | 
2025-12-03 11:19:33.908103 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-03 11:19:33.908973 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-03 11:19:34.451873 | 
2025-12-03 11:19:34.451987 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-12-03 11:19:34.472981 | 
2025-12-03 11:19:34.473066 | TASK [Gather required facts]
2025-12-03 11:19:34.958038 | controller | ok
2025-12-03 11:19:34.963438 | 
2025-12-03 11:19:34.963534 | TASK [Load environment var if instructed to]
2025-12-03 11:19:34.987051 | controller | skipping: Conditional result was False
2025-12-03 11:19:34.992336 | 
2025-12-03 11:19:34.992409 | TASK [Ensure group_vars dir exists]
2025-12-03 11:19:35.304807 | controller | ok
2025-12-03 11:19:35.310958 | 
2025-12-03 11:19:35.311044 | TASK [Print related variables]
2025-12-03 11:19:35.339463 | controller | ok:
2025-12-03 11:19:35.339580 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-12-03 11:19:35.339610 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/hci_prepare
2025-12-03 11:19:35.344652 | 
2025-12-03 11:19:35.344719 | TASK [Run molecule]
2025-12-03 11:19:36.300075 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-12-03 11:19:36.370508 | controller | INFO     Performing prerun with role_name_check=0...
2025-12-03 11:19:52.911900 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:19:52.912238 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:19:52.912265 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:19:52.912677 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:19:52.913155 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+2261ed43 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 1.0.0+2261ed43 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:19:52.913615 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:19:52.914054 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:19:52.914500 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:19:52.914925 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:19:52.915391 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:19:52.915817 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:19:52.916330 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:19:52.916743 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:19:52.917179 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:19:52.917646 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:19:52.927178 | controller | INFO     Running default > prepare
2025-12-03 11:19:53.578675 | controller |
2025-12-03 11:19:53.578727 | controller | PLAY [Prepare] *****************************************************************
2025-12-03 11:19:53.578829 | controller |
2025-12-03 11:19:53.578928 | controller | TASK [Gathering Facts] *********************************************************
2025-12-03 11:19:53.579053 | controller | Wednesday 03 December 2025  11:19:53 +0000 (0:00:00.012)       0:00:00.012 ****
2025-12-03 11:19:54.423412 | controller | ok: [instance]
2025-12-03 11:19:54.423472 | controller |
2025-12-03 11:19:54.423597 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-12-03 11:19:54.423698 | controller | Wednesday 03 December 2025  11:19:54 +0000 (0:00:00.845)       0:00:00.857 ****
2025-12-03 11:19:54.440693 | controller | skipping: [instance]
2025-12-03 11:19:54.440802 | controller |
2025-12-03 11:19:54.440922 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-12-03 11:19:54.441074 | controller | Wednesday 03 December 2025  11:19:54 +0000 (0:00:00.017)       0:00:00.875 ****
2025-12-03 11:19:54.480651 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-12-03 11:19:54.480769 | controller |
2025-12-03 11:19:54.480893 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-12-03 11:19:54.481047 | controller | Wednesday 03 December 2025  11:19:54 +0000 (0:00:00.039)       0:00:00.915 ****
2025-12-03 11:19:54.761660 | controller | ok: [instance]
2025-12-03 11:19:54.761722 | controller |
2025-12-03 11:19:54.761832 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-12-03 11:19:54.761926 | controller | Wednesday 03 December 2025  11:19:54 +0000 (0:00:00.280)       0:00:01.196 ****
2025-12-03 11:19:55.064253 | controller | ok: [instance]
2025-12-03 11:19:55.064326 | controller |
2025-12-03 11:19:55.064431 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-12-03 11:19:55.064535 | controller | Wednesday 03 December 2025  11:19:55 +0000 (0:00:00.302)       0:00:01.498 ****
2025-12-03 11:19:55.755927 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-12-03 11:19:55.756010 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-12-03 11:19:55.756104 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-12-03 11:19:55.756196 | controller |
2025-12-03 11:19:55.756314 | controller | TASK [Deploy repo-setup] *******************************************************
2025-12-03 11:19:55.756409 | controller | Wednesday 03 December 2025  11:19:55 +0000 (0:00:00.691)       0:00:02.190 ****
2025-12-03 11:19:55.814043 | controller |
2025-12-03 11:19:55.814181 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-12-03 11:19:55.814310 | controller | Wednesday 03 December 2025  11:19:55 +0000 (0:00:00.058)       0:00:02.248 ****
2025-12-03 11:19:56.379417 | controller | changed: [instance] => (item=tmp)
2025-12-03 11:19:56.379490 | controller | changed: [instance] => (item=artifacts/repositories)
2025-12-03 11:19:56.379590 | controller | changed: [instance] => (item=venv/repo_setup)
2025-12-03 11:19:56.379684 | controller |
2025-12-03 11:19:56.379794 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-12-03 11:19:56.379894 | controller | Wednesday 03 December 2025  11:19:56 +0000 (0:00:00.564)       0:00:02.813 ****
2025-12-03 11:19:57.206848 | controller | ok: [instance]
2025-12-03 11:19:57.207231 | controller |
2025-12-03 11:19:58.274022 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-12-03 11:19:58.274056 | controller | Wednesday 03 December 2025  11:19:57 +0000 (0:00:00.827)       0:00:03.641 ****
2025-12-03 11:19:58.274071 | controller | changed: [instance]
2025-12-03 11:20:06.246530 | controller |
2025-12-03 11:20:06.246569 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-12-03 11:20:06.246578 | controller | Wednesday 03 December 2025  11:19:58 +0000 (0:00:01.066)       0:00:04.708 ****
2025-12-03 11:20:06.246589 | controller | changed: [instance]
2025-12-03 11:20:06.961615 | controller |
2025-12-03 11:20:06.961654 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-12-03 11:20:06.961664 | controller | Wednesday 03 December 2025  11:20:06 +0000 (0:00:07.972)       0:00:12.680 ****
2025-12-03 11:20:06.961674 | controller | changed: [instance]
2025-12-03 11:20:06.989572 | controller |
2025-12-03 11:20:06.989593 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-12-03 11:20:06.989601 | controller | Wednesday 03 December 2025  11:20:06 +0000 (0:00:00.714)       0:00:13.395 ****
2025-12-03 11:20:06.989611 | controller | skipping: [instance]
2025-12-03 11:20:07.813325 | controller |
2025-12-03 11:20:07.813355 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-12-03 11:20:07.813364 | controller | Wednesday 03 December 2025  11:20:06 +0000 (0:00:00.028)       0:00:13.423 ****
2025-12-03 11:20:07.813374 | controller | changed: [instance]
2025-12-03 11:20:07.849623 | controller |
2025-12-03 11:20:07.849646 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-12-03 11:20:07.849656 | controller | Wednesday 03 December 2025  11:20:07 +0000 (0:00:00.823)       0:00:14.247 ****
2025-12-03 11:20:07.849667 | controller | skipping: [instance]
2025-12-03 11:20:07.884684 | controller |
2025-12-03 11:20:07.884703 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-12-03 11:20:07.884711 | controller | Wednesday 03 December 2025  11:20:07 +0000 (0:00:00.036)       0:00:14.284 ****
2025-12-03 11:20:07.884719 | controller | skipping: [instance]
2025-12-03 11:20:07.919791 | controller |
2025-12-03 11:20:07.919814 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-12-03 11:20:07.919824 | controller | Wednesday 03 December 2025  11:20:07 +0000 (0:00:00.035)       0:00:14.319 ****
2025-12-03 11:20:07.919834 | controller | skipping: [instance]
2025-12-03 11:20:08.457293 | controller |
2025-12-03 11:20:08.457325 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-12-03 11:20:08.457333 | controller | Wednesday 03 December 2025  11:20:07 +0000 (0:00:00.035)       0:00:14.354 ****
2025-12-03 11:20:08.457343 | controller | changed: [instance]
2025-12-03 11:20:08.879160 | controller |
2025-12-03 11:20:08.879205 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-12-03 11:20:08.879214 | controller | Wednesday 03 December 2025  11:20:08 +0000 (0:00:00.537)       0:00:14.891 ****
2025-12-03 11:20:08.879225 | controller | changed: [instance]
2025-12-03 11:20:08.907050 | controller |
2025-12-03 11:20:08.907072 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-12-03 11:20:08.907079 | controller | Wednesday 03 December 2025  11:20:08 +0000 (0:00:00.421)       0:00:15.313 ****
2025-12-03 11:20:08.907089 | controller | skipping: [instance]
2025-12-03 11:20:08.934494 | controller |
2025-12-03 11:20:08.934527 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-12-03 11:20:08.934535 | controller | Wednesday 03 December 2025  11:20:08 +0000 (0:00:00.028)       0:00:15.341 ****
2025-12-03 11:20:08.934548 | controller | skipping: [instance]
2025-12-03 11:20:08.962698 | controller |
2025-12-03 11:20:08.962717 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-12-03 11:20:08.962725 | controller | Wednesday 03 December 2025  11:20:08 +0000 (0:00:00.027)       0:00:15.369 ****
2025-12-03 11:20:08.962734 | controller | skipping: [instance]
2025-12-03 11:20:08.999435 | controller |
2025-12-03 11:20:08.999454 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-12-03 11:20:08.999461 | controller | Wednesday 03 December 2025  11:20:08 +0000 (0:00:00.028)       0:00:15.397 ****
2025-12-03 11:20:08.999470 | controller | ok: [instance]
2025-12-03 11:20:09.025797 | controller |
2025-12-03 11:20:09.025815 | controller | TASK [repo_setup : Create download directory] **********************************
2025-12-03 11:20:09.025823 | controller | Wednesday 03 December 2025  11:20:08 +0000 (0:00:00.036)       0:00:15.434 ****
2025-12-03 11:20:09.025832 | controller | skipping: [instance]
2025-12-03 11:20:09.051803 | controller |
2025-12-03 11:20:09.051822 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-12-03 11:20:09.051829 | controller | Wednesday 03 December 2025  11:20:09 +0000 (0:00:00.026)       0:00:15.460 ****
2025-12-03 11:20:09.051843 | controller | skipping: [instance]
2025-12-03 11:20:09.078017 | controller |
2025-12-03 11:20:09.078035 | controller | TASK [Download the RPM] ********************************************************
2025-12-03 11:20:09.078043 | controller | Wednesday 03 December 2025  11:20:09 +0000 (0:00:00.026)       0:00:15.486 ****
2025-12-03 11:20:09.078052 | controller | skipping: [instance]
2025-12-03 11:20:09.104480 | controller |
2025-12-03 11:20:09.104499 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-12-03 11:20:09.104506 | controller | Wednesday 03 December 2025  11:20:09 +0000 (0:00:00.026)       0:00:15.512 ****
2025-12-03 11:20:09.104515 | controller | skipping: [instance]
2025-12-03 11:20:09.157043 | controller |
2025-12-03 11:20:09.157076 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-12-03 11:20:09.157085 | controller | Wednesday 03 December 2025  11:20:09 +0000 (0:00:00.026)       0:00:15.539 ****
2025-12-03 11:20:09.157095 | controller | skipping: [instance]
2025-12-03 11:20:09.157121 | controller |
2025-12-03 11:20:09.157355 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-12-03 11:20:09.183789 | controller | Wednesday 03 December 2025  11:20:09 +0000 (0:00:00.052)       0:00:15.591 ****
2025-12-03 11:20:09.183809 | controller | skipping: [instance]
2025-12-03 11:20:09.207140 | controller |
2025-12-03 11:20:09.207159 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-12-03 11:20:09.207168 | controller | Wednesday 03 December 2025  11:20:09 +0000 (0:00:00.026)       0:00:15.618 ****
2025-12-03 11:20:09.207193 | controller | skipping: [instance]
2025-12-03 11:20:09.379457 | controller |
2025-12-03 11:20:09.379489 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-12-03 11:20:09.379497 | controller | Wednesday 03 December 2025  11:20:09 +0000 (0:00:00.023)       0:00:15.641 ****
2025-12-03 11:20:09.379507 | controller | ok: [instance]
2025-12-03 11:20:09.588762 | controller |
2025-12-03 11:20:09.588793 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-12-03 11:20:09.588801 | controller | Wednesday 03 December 2025  11:20:09 +0000 (0:00:00.172)       0:00:15.813 ****
2025-12-03 11:20:09.588812 | controller | changed: [instance]
2025-12-03 11:20:09.816051 | controller |
2025-12-03 11:20:09.816086 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-12-03 11:20:09.816094 | controller | Wednesday 03 December 2025  11:20:09 +0000 (0:00:00.209)       0:00:16.023 ****
2025-12-03 11:20:09.816104 | controller | changed: [instance]
2025-12-03 11:20:09.816288 | controller |
2025-12-03 11:20:09.816300 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-12-03 11:20:09.816309 | controller | Wednesday 03 December 2025  11:20:09 +0000 (0:00:00.227)       0:00:16.250 ****
2025-12-03 11:20:09.837367 | controller | skipping: [instance]
2025-12-03 11:20:09.858952 | controller |
2025-12-03 11:20:09.858971 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-12-03 11:20:09.858979 | controller | Wednesday 03 December 2025  11:20:09 +0000 (0:00:00.021)       0:00:16.271 ****
2025-12-03 11:20:09.858988 | controller | skipping: [instance]
2025-12-03 11:20:09.880645 | controller |
2025-12-03 11:20:09.880669 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-12-03 11:20:09.880677 | controller | Wednesday 03 December 2025  11:20:09 +0000 (0:00:00.021)       0:00:16.293 ****
2025-12-03 11:20:09.880688 | controller | skipping: [instance]
2025-12-03 11:20:09.901831 | controller |
2025-12-03 11:20:09.901847 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-12-03 11:20:09.901854 | controller | Wednesday 03 December 2025  11:20:09 +0000 (0:00:00.021)       0:00:16.315 ****
2025-12-03 11:20:09.901863 | controller | skipping: [instance]
2025-12-03 11:20:09.923401 | controller |
2025-12-03 11:20:09.923422 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-12-03 11:20:09.923431 | controller | Wednesday 03 December 2025  11:20:09 +0000 (0:00:00.021)       0:00:16.336 ****
2025-12-03 11:20:09.923442 | controller | skipping: [instance]
2025-12-03 11:20:09.944654 | controller |
2025-12-03 11:20:09.944673 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-12-03 11:20:09.944691 | controller | Wednesday 03 December 2025  11:20:09 +0000 (0:00:00.021)       0:00:16.357 ****
2025-12-03 11:20:09.944702 | controller | skipping: [instance]
2025-12-03 11:20:09.960809 | controller |
2025-12-03 11:20:09.960832 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-12-03 11:20:09.960842 | controller | Wednesday 03 December 2025  11:20:09 +0000 (0:00:00.021)       0:00:16.379 ****
2025-12-03 11:20:09.960851 | controller | skipping: [instance]
2025-12-03 11:20:09.988553 | controller |
2025-12-03 11:20:09.988575 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-12-03 11:20:09.988585 | controller | Wednesday 03 December 2025  11:20:09 +0000 (0:00:00.016)       0:00:16.395 ****
2025-12-03 11:20:09.988595 | controller | skipping: [instance]
2025-12-03 11:20:09.988766 | controller |
2025-12-03 11:20:09.988930 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-12-03 11:20:09.989107 | controller | Wednesday 03 December 2025  11:20:09 +0000 (0:00:00.028)       0:00:16.423 ****
2025-12-03 11:20:10.004615 | controller | skipping: [instance]
2025-12-03 11:20:10.004798 | controller |
2025-12-03 11:20:10.004993 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-12-03 11:20:10.005147 | controller | Wednesday 03 December 2025  11:20:10 +0000 (0:00:00.016)       0:00:16.439 ****
2025-12-03 11:20:10.024533 | controller | skipping: [instance]
2025-12-03 11:20:10.024714 | controller |
2025-12-03 11:20:10.024886 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-12-03 11:20:10.025060 | controller | Wednesday 03 December 2025  11:20:10 +0000 (0:00:00.019)       0:00:16.459 ****
2025-12-03 11:20:10.054850 | controller | skipping: [instance]
2025-12-03 11:20:10.055056 | controller |
2025-12-03 11:20:10.055224 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-12-03 11:20:10.055366 | controller | Wednesday 03 December 2025  11:20:10 +0000 (0:00:00.030)       0:00:16.489 ****
2025-12-03 11:20:10.083572 | controller | skipping: [instance]
2025-12-03 11:20:10.083721 | controller |
2025-12-03 11:20:10.083864 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-12-03 11:20:10.084028 | controller | Wednesday 03 December 2025  11:20:10 +0000 (0:00:00.028)       0:00:16.518 ****
2025-12-03 11:20:10.112350 | controller | skipping: [instance]
2025-12-03 11:20:10.112533 | controller |
2025-12-03 11:20:10.112707 | controller | TASK [test_deps : Install additional packages] *********************************
2025-12-03 11:20:10.112870 | controller | Wednesday 03 December 2025  11:20:10 +0000 (0:00:00.028)       0:00:16.547 ****
2025-12-03 11:20:10.140460 | controller | skipping: [instance]
2025-12-03 11:20:10.140643 | controller |
2025-12-03 11:20:10.140814 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-12-03 11:20:10.141003 | controller | Wednesday 03 December 2025  11:20:10 +0000 (0:00:00.028)       0:00:16.575 ****
2025-12-03 11:20:56.050629 | controller | ok: [instance]
2025-12-03 11:20:57.276587 | controller |
2025-12-03 11:20:57.276629 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-12-03 11:20:57.276639 | controller | Wednesday 03 December 2025  11:20:56 +0000 (0:00:45.907)       0:01:02.482 ****
2025-12-03 11:20:57.276650 | controller | ok: [instance]
2025-12-03 11:20:57.332037 | controller |
2025-12-03 11:20:57.332076 | controller | TASK [test_deps : Install extra packages] **************************************
2025-12-03 11:20:57.332086 | controller | Wednesday 03 December 2025  11:20:57 +0000 (0:00:01.227)       0:01:03.710 ****
2025-12-03 11:20:57.332097 | controller | skipping: [instance]
2025-12-03 11:20:57.333765 | controller |
2025-12-03 11:20:57.333784 | controller | PLAY RECAP *********************************************************************
2025-12-03 11:20:57.333792 | controller | instance                   : ok=19   changed=9    unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-12-03 11:20:57.333799 | controller |
2025-12-03 11:20:57.333804 | controller | Wednesday 03 December 2025  11:20:57 +0000 (0:00:00.055)       0:01:03.765 ****
2025-12-03 11:20:57.333810 | controller | ===============================================================================
2025-12-03 11:20:57.333822 | controller | test_deps : Install selinux python libs -------------------------------- 45.91s
2025-12-03 11:20:57.403290 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.97s
2025-12-03 11:20:57.403323 | controller | test_deps : Install python yaml libs ------------------------------------ 1.23s
2025-12-03 11:20:57.403336 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.07s
2025-12-03 11:20:57.403342 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-12-03 11:20:57.403348 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.83s
2025-12-03 11:20:57.403353 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.82s
2025-12-03 11:20:57.403359 | controller | repo_setup : Install repo-setup package --------------------------------- 0.71s
2025-12-03 11:20:57.403364 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.69s
2025-12-03 11:20:57.403369 | controller | repo_setup : Ensure directories are present ----------------------------- 0.56s
2025-12-03 11:20:57.403374 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.54s
2025-12-03 11:20:57.403380 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.42s
2025-12-03 11:20:57.403385 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.30s
2025-12-03 11:20:57.403390 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s
2025-12-03 11:20:57.403395 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.23s
2025-12-03 11:20:57.403401 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.21s
2025-12-03 11:20:57.403407 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.17s
2025-12-03 11:20:57.403412 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-12-03 11:20:57.403417 | controller | test_deps : Install extra packages -------------------------------------- 0.06s
2025-12-03 11:20:57.403423 | controller | repo_setup : Get rhos-release tool version ------------------------------ 0.05s
2025-12-03 11:20:57.403433 | controller | INFO     Running default > converge
2025-12-03 11:20:57.856309 | controller |
2025-12-03 11:20:58.665663 | controller | PLAY [Converge] ****************************************************************
2025-12-03 11:20:58.665699 | controller |
2025-12-03 11:20:58.665707 | controller | TASK [Gathering Facts] *********************************************************
2025-12-03 11:20:58.665713 | controller | Wednesday 03 December 2025  11:20:57 +0000 (0:00:00.013)       0:00:00.013 ****
2025-12-03 11:20:58.665724 | controller | ok: [instance]
2025-12-03 11:20:58.683681 | controller |
2025-12-03 11:20:58.683711 | controller | TASK [Add compute-0 to inventory] **********************************************
2025-12-03 11:20:58.683719 | controller | Wednesday 03 December 2025  11:20:58 +0000 (0:00:00.809)       0:00:00.823 ****
2025-12-03 11:20:58.683730 | controller | changed: [instance]
2025-12-03 11:20:59.188249 | controller |
2025-12-03 11:20:59.188292 | controller | TASK [Ensure minimal dirs exists] **********************************************
2025-12-03 11:20:59.188301 | controller | Wednesday 03 December 2025  11:20:58 +0000 (0:00:00.018)       0:00:00.842 ****
2025-12-03 11:20:59.188312 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-12-03 11:20:59.761561 | controller | changed: [instance] => (item=/etc/ci/env)
2025-12-03 11:20:59.761605 | controller |
2025-12-03 11:20:59.761615 | controller | TASK [Expected network info] ***************************************************
2025-12-03 11:20:59.761623 | controller | Wednesday 03 December 2025  11:20:59 +0000 (0:00:00.503)       0:00:01.346 ****
2025-12-03 11:20:59.761635 | controller | changed: [instance]
2025-12-03 11:20:59.793860 | controller |
2025-12-03 11:20:59.793881 | controller | TASK [hci_prepare : Set common facts] ******************************************
2025-12-03 11:20:59.793889 | controller | Wednesday 03 December 2025  11:20:59 +0000 (0:00:00.573)       0:00:01.919 ****
2025-12-03 11:20:59.793899 | controller | ok: [instance]
2025-12-03 11:20:59.832036 | controller |
2025-12-03 11:20:59.832059 | controller | TASK [hci_prepare : Load parameters] *******************************************
2025-12-03 11:20:59.832075 | controller | Wednesday 03 December 2025  11:20:59 +0000 (0:00:00.033)       0:00:01.952 ****
2025-12-03 11:20:59.832085 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/hci_prepare/tasks/load_parameters.yml for instance
2025-12-03 11:20:59.832111 | controller |
2025-12-03 11:20:59.832305 | controller | TASK [hci_prepare : Load parameters] *******************************************
2025-12-03 11:20:59.862501 | controller | Wednesday 03 December 2025  11:20:59 +0000 (0:00:00.038)       0:00:01.990 ****
2025-12-03 11:20:59.862524 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-12-03 11:20:59.898821 | controller | ok: [instance] => (item=/etc/ci/env)
2025-12-03 11:20:59.898850 | controller |
2025-12-03 11:20:59.898859 | controller | TASK [hci_prepare : Extract first compute from inventory] **********************
2025-12-03 11:20:59.898866 | controller | Wednesday 03 December 2025  11:20:59 +0000 (0:00:00.030)       0:00:02.020 ****
2025-12-03 11:20:59.898876 | controller | ok: [instance]
2025-12-03 11:20:59.939056 | controller |
2025-12-03 11:20:59.939085 | controller | TASK [hci_prepare : Ensure we have needed bits for compute when needed] ********
2025-12-03 11:20:59.939095 | controller | Wednesday 03 December 2025  11:20:59 +0000 (0:00:00.036)       0:00:02.056 ****
2025-12-03 11:20:59.939107 | controller | ok: [instance] => changed=false
2025-12-03 11:20:59.978785 | controller |   msg: All assertions passed
2025-12-03 11:20:59.978807 | controller |
2025-12-03 11:20:59.978816 | controller | TASK [hci_prepare : Set mtu value from crc_ci_bootstrap_networks_out] **********
2025-12-03 11:20:59.978822 | controller | Wednesday 03 December 2025  11:20:59 +0000 (0:00:00.040)       0:00:02.097 ****
2025-12-03 11:20:59.978831 | controller | ok: [instance]
2025-12-03 11:21:00.020407 | controller |
2025-12-03 11:21:00.020434 | controller | TASK [hci_prepare : Set vlan value from crc_ci_bootstrap_networks_out] *********
2025-12-03 11:21:00.020442 | controller | Wednesday 03 December 2025  11:20:59 +0000 (0:00:00.039)       0:00:02.137 ****
2025-12-03 11:21:00.020452 | controller | ok: [instance]
2025-12-03 11:21:00.212872 | controller |
2025-12-03 11:21:00.212912 | controller | TASK [hci_prepare : Ensure the kustomizations dirs exists] *********************
2025-12-03 11:21:00.212922 | controller | Wednesday 03 December 2025  11:21:00 +0000 (0:00:00.041)       0:00:02.178 ****
2025-12-03 11:21:00.212948 | controller | changed: [instance]
2025-12-03 11:21:00.551369 | controller |
2025-12-03 11:21:00.551414 | controller | TASK [hci_prepare : Prepare EDPM network for HCI deployment] *******************
2025-12-03 11:21:00.551424 | controller | Wednesday 03 December 2025  11:21:00 +0000 (0:00:00.192)       0:00:02.370 ****
2025-12-03 11:21:00.551435 | controller | changed: [instance]
2025-12-03 11:21:00.894884 | controller |
2025-12-03 11:21:00.894927 | controller | TASK [hci_prepare : Enable services needed to deploy Ceph] *********************
2025-12-03 11:21:00.894998 | controller | Wednesday 03 December 2025  11:21:00 +0000 (0:00:00.338)       0:00:02.709 ****
2025-12-03 11:21:00.895015 | controller | changed: [instance]
2025-12-03 11:21:00.919088 | controller |
2025-12-03 11:21:00.919132 | controller | TASK [hci_prepare : Disable discover_hosts when deploying hci on phase1] *******
2025-12-03 11:21:00.919144 | controller | Wednesday 03 December 2025  11:21:00 +0000 (0:00:00.343)       0:00:03.052 ****
2025-12-03 11:21:00.919158 | controller | ok: [instance]
2025-12-03 11:21:00.919194 | controller |
2025-12-03 11:21:00.919202 | controller | TASK [Assert mtu and vlan values were set] *************************************
2025-12-03 11:21:00.919212 | controller | Wednesday 03 December 2025  11:21:00 +0000 (0:00:00.024)       0:00:03.077 ****
2025-12-03 11:21:00.955047 | controller | ok: [instance] => changed=false
2025-12-03 11:21:00.955386 | controller |   msg: All assertions passed
2025-12-03 11:21:00.955429 | controller |
2025-12-03 11:21:01.357579 | controller | TASK [Check expected kustomizations] *******************************************
2025-12-03 11:21:01.357615 | controller | Wednesday 03 December 2025  11:21:00 +0000 (0:00:00.035)       0:00:03.113 ****
2025-12-03 11:21:01.357628 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml)
2025-12-03 11:21:01.389063 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml)
2025-12-03 11:21:01.389105 | controller |
2025-12-03 11:21:01.389120 | controller | TASK [Check if expected files where created] ***********************************
2025-12-03 11:21:01.389129 | controller | Wednesday 03 December 2025  11:21:01 +0000 (0:00:00.402)       0:00:03.515 ****
2025-12-03 11:21:01.389144 | controller | ok: [instance] => (item={'changed': False, 'stat': {'exists': True, 'path': '/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 294, 'inode': 8657019, 'dev': 64513, 'nlink': 1, 'atime': 1764760860.5121467, 'mtime': 1764760860.3741453, 'ctime': 1764760860.5161467, 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False, 'blocks': 8, 'block_size': 4096, 'device_type': 0, 'readable': True, 'writeable': True, 'executable': False, 'pw_name': 'zuul', 'gr_name': 'zuul', 'checksum': 'eb1b0d7f73292ae259724656eabf9af0d6b574b2', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '2373297293', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': '/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml', 'ansible_loop_var': 'item'}) => changed=false
2025-12-03 11:21:01.389184 | controller |   ansible_loop_var: item
2025-12-03 11:21:01.389193 | controller |   item:
2025-12-03 11:21:01.389201 | controller |     ansible_loop_var: item
2025-12-03 11:21:01.389208 | controller |     changed: false
2025-12-03 11:21:01.389215 | controller |     failed: false
2025-12-03 11:21:01.389224 | controller |     invocation:
2025-12-03 11:21:01.390476 | controller |       module_args:
2025-12-03 11:21:01.390513 | controller |         checksum_algorithm: sha1
2025-12-03 11:21:01.390523 | controller |         follow: false
2025-12-03 11:21:01.390529 | controller |         get_attributes: true
2025-12-03 11:21:01.390535 | controller |         get_checksum: true
2025-12-03 11:21:01.390540 | controller |         get_md5: false
2025-12-03 11:21:01.390545 | controller |         get_mime: true
2025-12-03 11:21:01.390551 | controller |         path: /home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml
2025-12-03 11:21:01.390557 | controller |     item: /home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml
2025-12-03 11:21:01.390563 | controller |     stat:
2025-12-03 11:21:01.390569 | controller |       atime: 1764760860.5121467
2025-12-03 11:21:01.390574 | controller |       attr_flags: ''
2025-12-03 11:21:01.390580 | controller |       attributes: []
2025-12-03 11:21:01.390588 | controller |       block_size: 4096
2025-12-03 11:21:01.390648 | controller |       blocks: 8
2025-12-03 11:21:01.390761 | controller |       charset: us-ascii
2025-12-03 11:21:01.390877 | controller |       checksum: eb1b0d7f73292ae259724656eabf9af0d6b574b2
2025-12-03 11:21:01.391003 | controller |       ctime: 1764760860.5161467
2025-12-03 11:21:01.391136 | controller |       dev: 64513
2025-12-03 11:21:01.391255 | controller |       device_type: 0
2025-12-03 11:21:01.391373 | controller |       executable: false
2025-12-03 11:21:01.391495 | controller |       exists: true
2025-12-03 11:21:01.391598 | controller |       gid: 1000
2025-12-03 11:21:01.391702 | controller |       gr_name: zuul
2025-12-03 11:21:01.391809 | controller |       inode: 8657019
2025-12-03 11:21:01.391916 | controller |       isblk: false
2025-12-03 11:21:01.392041 | controller |       ischr: false
2025-12-03 11:21:01.392155 | controller |       isdir: false
2025-12-03 11:21:01.392274 | controller |       isfifo: false
2025-12-03 11:21:01.392608 | controller |       isgid: false
2025-12-03 11:21:01.392735 | controller |       islnk: false
2025-12-03 11:21:01.392846 | controller |       isreg: true
2025-12-03 11:21:01.392964 | controller |       issock: false
2025-12-03 11:21:01.393092 | controller |       isuid: false
2025-12-03 11:21:01.393203 | controller |       mimetype: text/plain
2025-12-03 11:21:01.393321 | controller |       mode: '0644'
2025-12-03 11:21:01.393435 | controller |       mtime: 1764760860.3741453
2025-12-03 11:21:01.393544 | controller |       nlink: 1
2025-12-03 11:21:01.393659 | controller |       path: /home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml
2025-12-03 11:21:01.393794 | controller |       pw_name: zuul
2025-12-03 11:21:01.393987 | controller |       readable: true
2025-12-03 11:21:01.394132 | controller |       rgrp: true
2025-12-03 11:21:01.394279 | controller |       roth: true
2025-12-03 11:21:01.394414 | controller |       rusr: true
2025-12-03 11:21:01.394545 | controller |       size: 294
2025-12-03 11:21:01.394674 | controller |       uid: 1000
2025-12-03 11:21:01.394803 | controller |       version: '2373297293'
2025-12-03 11:21:01.394951 | controller |       wgrp: false
2025-12-03 11:21:01.395105 | controller |       woth: false
2025-12-03 11:21:01.395253 | controller |       writeable: true
2025-12-03 11:21:01.395394 | controller |       wusr: true
2025-12-03 11:21:01.395526 | controller |       xgrp: false
2025-12-03 11:21:01.395656 | controller |       xoth: false
2025-12-03 11:21:01.395785 | controller |       xusr: false
2025-12-03 11:21:01.395914 | controller |   msg: All assertions passed
2025-12-03 11:21:01.396110 | controller | ok: [instance] => (item={'changed': False, 'stat': {'exists': True, 'path': '/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 446, 'inode': 20999066, 'dev': 64513, 'nlink': 1, 'atime': 1764760860.8581505, 'mtime': 1764760860.7221491, 'ctime': 1764760860.8621507, 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False, 'blocks': 8, 'block_size': 4096, 'device_type': 0, 'readable': True, 'writeable': True, 'executable': False, 'pw_name': 'zuul', 'gr_name': 'zuul', 'checksum': '3bb4e07b60ede01bd9d3d77cd53d3ab90828582d', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '1319847166', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': '/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml', 'ansible_loop_var': 'item'}) => changed=false
2025-12-03 11:21:01.396276 | controller |   ansible_loop_var: item
2025-12-03 11:21:01.396408 | controller |   item:
2025-12-03 11:21:01.396537 | controller |     ansible_loop_var: item
2025-12-03 11:21:01.396663 | controller |     changed: false
2025-12-03 11:21:01.396794 | controller |     failed: false
2025-12-03 11:21:01.396923 | controller |     invocation:
2025-12-03 11:21:01.397091 | controller |       module_args:
2025-12-03 11:21:01.397246 | controller |         checksum_algorithm: sha1
2025-12-03 11:21:01.397385 | controller |         follow: false
2025-12-03 11:21:01.397525 | controller |         get_attributes: true
2025-12-03 11:21:01.397656 | controller |         get_checksum: true
2025-12-03 11:21:01.397784 | controller |         get_md5: false
2025-12-03 11:21:01.397914 | controller |         get_mime: true
2025-12-03 11:21:01.398074 | controller |         path: /home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml
2025-12-03 11:21:01.398216 | controller |     item: /home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml
2025-12-03 11:21:01.398362 | controller |     stat:
2025-12-03 11:21:01.398502 | controller |       atime: 1764760860.8581505
2025-12-03 11:21:01.398635 | controller |       attr_flags: ''
2025-12-03 11:21:01.398763 | controller |       attributes: []
2025-12-03 11:21:01.398891 | controller |       block_size: 4096
2025-12-03 11:21:01.399046 | controller |       blocks: 8
2025-12-03 11:21:01.399182 | controller |       charset: us-ascii
2025-12-03 11:21:01.399330 | controller |       checksum: 3bb4e07b60ede01bd9d3d77cd53d3ab90828582d
2025-12-03 11:21:01.399466 | controller |       ctime: 1764760860.8621507
2025-12-03 11:21:01.399599 | controller |       dev: 64513
2025-12-03 11:21:01.399728 | controller |       device_type: 0
2025-12-03 11:21:01.399854 | controller |       executable: false
2025-12-03 11:21:01.400005 | controller |       exists: true
2025-12-03 11:21:01.400141 | controller |       gid: 1000
2025-12-03 11:21:01.400283 | controller |       gr_name: zuul
2025-12-03 11:21:01.400424 | controller |       inode: 20999066
2025-12-03 11:21:01.400553 | controller |       isblk: false
2025-12-03 11:21:01.400681 | controller |       ischr: false
2025-12-03 11:21:01.400805 | controller |       isdir: false
2025-12-03 11:21:01.400930 | controller |       isfifo: false
2025-12-03 11:21:01.401098 | controller |       isgid: false
2025-12-03 11:21:01.401229 | controller |       islnk: false
2025-12-03 11:21:01.401377 | controller |       isreg: true
2025-12-03 11:21:01.401505 | controller |       issock: false
2025-12-03 11:21:01.401630 | controller |       isuid: false
2025-12-03 11:21:01.401758 | controller |       mimetype: text/plain
2025-12-03 11:21:01.401884 | controller |       mode: '0644'
2025-12-03 11:21:01.402034 | controller |       mtime: 1764760860.7221491
2025-12-03 11:21:01.402169 | controller |       nlink: 1
2025-12-03 11:21:01.402322 | controller |       path: /home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml
2025-12-03 11:21:01.402453 | controller |       pw_name: zuul
2025-12-03 11:21:01.402579 | controller |       readable: true
2025-12-03 11:21:01.402706 | controller |       rgrp: true
2025-12-03 11:21:01.402830 | controller |       roth: true
2025-12-03 11:21:01.402978 | controller |       rusr: true
2025-12-03 11:21:01.403113 | controller |       size: 446
2025-12-03 11:21:01.403251 | controller |       uid: 1000
2025-12-03 11:21:01.403391 | controller |       version: '1319847166'
2025-12-03 11:21:01.403518 | controller |       wgrp: false
2025-12-03 11:21:01.403643 | controller |       woth: false
2025-12-03 11:21:01.403768 | controller |       writeable: true
2025-12-03 11:21:01.403892 | controller |       wusr: true
2025-12-03 11:21:01.404043 | controller |       xgrp: false
2025-12-03 11:21:01.404175 | controller |       xoth: false
2025-12-03 11:21:01.404333 | controller |       xusr: false
2025-12-03 11:21:01.404464 | controller |   msg: All assertions passed
2025-12-03 11:21:01.404587 | controller |
2025-12-03 11:21:01.404719 | controller | TASK [hci_prepare : Set common facts] ******************************************
2025-12-03 11:21:01.404853 | controller | Wednesday 03 December 2025  11:21:01 +0000 (0:00:00.031)       0:00:03.547 ****
2025-12-03 11:21:01.419358 | controller | ok: [instance]
2025-12-03 11:21:01.419531 | controller |
2025-12-03 11:21:01.419697 | controller | TASK [hci_prepare : Ensure directories] ****************************************
2025-12-03 11:21:01.419860 | controller | Wednesday 03 December 2025  11:21:01 +0000 (0:00:00.031)       0:00:03.578 ****
2025-12-03 11:21:01.802501 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts)
2025-12-03 11:21:01.819999 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane)
2025-12-03 11:21:01.820021 | controller |
2025-12-03 11:21:01.820029 | controller | TASK [hci_prepare : Create ceph config secret] *********************************
2025-12-03 11:21:01.820035 | controller | Wednesday 03 December 2025  11:21:01 +0000 (0:00:00.382)       0:00:03.960 ****
2025-12-03 11:21:01.820045 | controller | skipping: [instance]
2025-12-03 11:21:01.820100 | controller |
2025-12-03 11:21:01.820116 | controller | TASK [hci_prepare : Set Ceph FSID fact] ****************************************
2025-12-03 11:21:01.820127 | controller | Wednesday 03 December 2025  11:21:01 +0000 (0:00:00.018)       0:00:03.978 ****
2025-12-03 11:21:01.837666 | controller | skipping: [instance]
2025-12-03 11:21:01.854464 | controller |
2025-12-03 11:21:01.854484 | controller | TASK [hci_prepare : Generate nova config map] **********************************
2025-12-03 11:21:01.854492 | controller | Wednesday 03 December 2025  11:21:01 +0000 (0:00:00.017)       0:00:03.996 ****
2025-12-03 11:21:01.854501 | controller | skipping: [instance]
2025-12-03 11:21:01.871724 | controller |
2025-12-03 11:21:01.871743 | controller | TASK [hci_prepare : Create nova config map] ************************************
2025-12-03 11:21:01.871751 | controller | Wednesday 03 December 2025  11:21:01 +0000 (0:00:00.016)       0:00:04.012 ****
2025-12-03 11:21:01.871760 | controller | skipping: [instance]
2025-12-03 11:21:01.890075 | controller |
2025-12-03 11:21:01.890096 | controller | TASK [hci_prepare : Generate Ceph-Nova Dataplane Service] **********************
2025-12-03 11:21:01.890104 | controller | Wednesday 03 December 2025  11:21:01 +0000 (0:00:00.017)       0:00:04.030 ****
2025-12-03 11:21:01.890113 | controller | skipping: [instance]
2025-12-03 11:21:01.910016 | controller |
2025-12-03 11:21:01.910040 | controller | TASK [hci_prepare : Create Ceph-Nova Dataplane Service] ************************
2025-12-03 11:21:01.910049 | controller | Wednesday 03 December 2025  11:21:01 +0000 (0:00:00.018)       0:00:04.048 ****
2025-12-03 11:21:01.910069 | controller | skipping: [instance]
2025-12-03 11:21:01.928042 | controller |
2025-12-03 11:21:01.928070 | controller | TASK [hci_prepare : Delete OpenStackDataPlaneDeployment] ***********************
2025-12-03 11:21:01.928080 | controller | Wednesday 03 December 2025  11:21:01 +0000 (0:00:00.019)       0:00:04.068 ****
2025-12-03 11:21:01.928092 | controller | skipping: [instance]
2025-12-03 11:21:02.282072 | controller |
2025-12-03 11:21:02.282125 | controller | TASK [hci_prepare : Create configuration to finish HCI deployment] *************
2025-12-03 11:21:02.282134 | controller | Wednesday 03 December 2025  11:21:01 +0000 (0:00:00.017)       0:00:04.086 ****
2025-12-03 11:21:02.282146 | controller | changed: [instance]
2025-12-03 11:21:02.300855 | controller |
2025-12-03 11:21:02.300888 | controller | TASK [hci_prepare : Enabled nova discover_hosts after deployment] **************
2025-12-03 11:21:02.300898 | controller | Wednesday 03 December 2025  11:21:02 +0000 (0:00:00.353)       0:00:04.439 ****
2025-12-03 11:21:02.300910 | controller | ok: [instance]
2025-12-03 11:21:02.322674 | controller |
2025-12-03 11:21:02.322708 | controller | TASK [hci_prepare : Save HCI info] *********************************************
2025-12-03 11:21:02.322718 | controller | Wednesday 03 December 2025  11:21:02 +0000 (0:00:00.019)       0:00:04.459 ****
2025-12-03 11:21:02.322731 | controller | skipping: [instance]
2025-12-03 11:21:02.524014 | controller |
2025-12-03 11:21:02.524048 | controller | TASK [Check expected kustomizations - phase 2] *********************************
2025-12-03 11:21:02.524056 | controller | Wednesday 03 December 2025  11:21:02 +0000 (0:00:00.021)       0:00:04.480 ****
2025-12-03 11:21:02.524067 | controller | ok: [instance]
2025-12-03 11:21:02.524161 | controller |
2025-12-03 11:21:02.524172 | controller | TASK [Check if expected files where created - phase 2] *************************
2025-12-03 11:21:02.524180 | controller | Wednesday 03 December 2025  11:21:02 +0000 (0:00:00.201)       0:00:04.682 ****
2025-12-03 11:21:02.555082 | controller | ok: [instance] => changed=false
2025-12-03 11:21:02.555141 | controller |   msg: All assertions passed
2025-12-03 11:21:02.555151 | controller |
2025-12-03 11:21:02.555157 | controller | PLAY RECAP *********************************************************************
2025-12-03 11:21:02.555164 | controller | instance                   : ok=24   changed=7    unreachable=0    failed=0    skipped=8    rescued=0    ignored=0
2025-12-03 11:21:02.555170 | controller |
2025-12-03 11:21:02.555176 | controller | Wednesday 03 December 2025  11:21:02 +0000 (0:00:00.030)       0:00:04.712 ****
2025-12-03 11:21:02.555182 | controller | ===============================================================================
2025-12-03 11:21:02.555187 | controller | Gathering Facts --------------------------------------------------------- 0.81s
2025-12-03 11:21:02.555195 | controller | Expected network info --------------------------------------------------- 0.57s
2025-12-03 11:21:02.555218 | controller | Ensure minimal dirs exists ---------------------------------------------- 0.50s
2025-12-03 11:21:02.556753 | controller | Check expected kustomizations ------------------------------------------- 0.40s
2025-12-03 11:21:02.615375 | controller | hci_prepare : Ensure directories ---------------------------------------- 0.38s
2025-12-03 11:21:02.615415 | controller | hci_prepare : Create configuration to finish HCI deployment ------------- 0.35s
2025-12-03 11:21:02.615423 | controller | hci_prepare : Enable services needed to deploy Ceph --------------------- 0.34s
2025-12-03 11:21:02.615429 | controller | hci_prepare : Prepare EDPM network for HCI deployment ------------------- 0.34s
2025-12-03 11:21:02.615440 | controller | Check expected kustomizations - phase 2 --------------------------------- 0.20s
2025-12-03 11:21:02.615446 | controller | hci_prepare : Ensure the kustomizations dirs exists --------------------- 0.19s
2025-12-03 11:21:02.615451 | controller | hci_prepare : Set vlan value from crc_ci_bootstrap_networks_out --------- 0.04s
2025-12-03 11:21:02.615463 | controller | hci_prepare : Ensure we have needed bits for compute when needed -------- 0.04s
2025-12-03 11:21:02.615469 | controller | hci_prepare : Set mtu value from crc_ci_bootstrap_networks_out ---------- 0.04s
2025-12-03 11:21:02.615474 | controller | hci_prepare : Load parameters ------------------------------------------- 0.04s
2025-12-03 11:21:02.615480 | controller | hci_prepare : Extract first compute from inventory ---------------------- 0.04s
2025-12-03 11:21:02.615490 | controller | Assert mtu and vlan values were set ------------------------------------- 0.04s
2025-12-03 11:21:02.615495 | controller | hci_prepare : Set common facts ------------------------------------------ 0.03s
2025-12-03 11:21:02.615501 | controller | Check if expected files where created ----------------------------------- 0.03s
2025-12-03 11:21:02.615506 | controller | hci_prepare : Set common facts ------------------------------------------ 0.03s
2025-12-03 11:21:02.615511 | controller | Check if expected files where created - phase 2 ------------------------- 0.03s
2025-12-03 11:21:02.615522 | controller | INFO     Running default > cleanup
2025-12-03 11:21:02.616009 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-12-03 11:21:02.616656 | controller | INFO     Writing /tmp/report.html report.
2025-12-03 11:21:02.969696 | controller | changed
2025-12-03 11:21:02.991083 | 
2025-12-03 11:21:02.991157 | PLAY RECAP
2025-12-03 11:21:02.991198 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-03 11:21:02.991222 | 
2025-12-03 11:21:03.064477 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-03 11:21:03.065288 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-03 11:21:03.643069 | 
2025-12-03 11:21:03.643184 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-03 11:21:03.668050 | 
2025-12-03 11:21:03.668311 | TASK [Filter out host if needed]
2025-12-03 11:21:03.678062 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-03 11:21:03.683053 | 
2025-12-03 11:21:03.683136 | TASK [Ensure file is present]
2025-12-03 11:21:04.008718 | controller | ok
2025-12-03 11:21:04.015942 | 
2025-12-03 11:21:04.016037 | TASK [Manage molecule report file]
2025-12-03 11:21:04.540812 | controller | changed
2025-12-03 11:21:04.546804 | 
2025-12-03 11:21:04.546888 | TASK [Check if we get ci-framework-data basedir]
2025-12-03 11:21:04.750412 | controller | ok
2025-12-03 11:21:04.755843 | 
2025-12-03 11:21:04.755923 | TASK [Create ci-framework-data log directory for zuul]
2025-12-03 11:21:05.081584 | controller | changed
2025-12-03 11:21:05.086937 | 
2025-12-03 11:21:05.087025 | TASK [Copy ci-framework interesting files]
2025-12-03 11:21:05.297379 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-12-03 11:21:05.613708 | controller | changed
2025-12-03 11:21:05.619000 | 
2025-12-03 11:21:05.619067 | TASK [Get SELinux listing]
2025-12-03 11:21:06.149093 | controller | changed
2025-12-03 11:21:06.155234 | 
2025-12-03 11:21:06.155346 | TASK [Generate log index]
2025-12-03 11:21:06.802776 | controller | changed
2025-12-03 11:21:06.807987 | 
2025-12-03 11:21:06.808056 | TASK [Get some env related data]
2025-12-03 11:21:07.354441 | controller | /home/zuul/.local/bin/ansible
2025-12-03 11:21:08.335817 | controller | changed
2025-12-03 11:21:08.341048 | 
2025-12-03 11:21:08.341110 | TASK [Generate list of logs to collect in home directory]
2025-12-03 11:21:08.647099 | controller | ok: All paths examined
2025-12-03 11:21:08.652356 | 
2025-12-03 11:21:08.652423 | LOOP [Copy logs from home directory]
2025-12-03 11:21:08.994603 | controller | changed:
2025-12-03 11:21:08.994708 | controller | {
2025-12-03 11:21:08.994736 | controller |   "atime": 1764760757.6453595,
2025-12-03 11:21:08.994762 | controller |   "ctime": 1764760773.3434465,
2025-12-03 11:21:08.994780 | controller |   "dev": 64513,
2025-12-03 11:21:08.994797 | controller |   "gid": 1000,
2025-12-03 11:21:08.994813 | controller |   "gr_name": "zuul",
2025-12-03 11:21:08.994829 | controller |   "inode": 4344457,
2025-12-03 11:21:08.994844 | controller |   "isblk": false,
2025-12-03 11:21:08.994858 | controller |   "ischr": false,
2025-12-03 11:21:08.994873 | controller |   "isdir": false,
2025-12-03 11:21:08.994888 | controller |   "isfifo": false,
2025-12-03 11:21:08.994902 | controller |   "isgid": false,
2025-12-03 11:21:08.994942 | controller |   "islnk": false,
2025-12-03 11:21:08.994960 | controller |   "isreg": true,
2025-12-03 11:21:08.994979 | controller |   "issock": false,
2025-12-03 11:21:08.994995 | controller |   "isuid": false,
2025-12-03 11:21:08.995010 | controller |   "mode": "0644",
2025-12-03 11:21:08.995025 | controller |   "mtime": 1764760773.3434465,
2025-12-03 11:21:08.995039 | controller |   "nlink": 1,
2025-12-03 11:21:08.995053 | controller |   "path": "/home/zuul/ansible.log",
2025-12-03 11:21:08.995069 | controller |   "pw_name": "zuul",
2025-12-03 11:21:08.995083 | controller |   "rgrp": true,
2025-12-03 11:21:08.995097 | controller |   "roth": true,
2025-12-03 11:21:08.995110 | controller |   "rusr": true,
2025-12-03 11:21:08.995124 | controller |   "size": 6749,
2025-12-03 11:21:08.995138 | controller |   "uid": 1000,
2025-12-03 11:21:08.995151 | controller |   "wgrp": false,
2025-12-03 11:21:08.995164 | controller |   "woth": false,
2025-12-03 11:21:08.995179 | controller |   "wusr": true,
2025-12-03 11:21:08.995194 | controller |   "xgrp": false,
2025-12-03 11:21:08.995207 | controller |   "xoth": false,
2025-12-03 11:21:08.995220 | controller |   "xusr": false
2025-12-03 11:21:08.995233 | controller | }
2025-12-03 11:21:09.009256 | 
2025-12-03 11:21:09.009385 | TASK [Copy crio stats log file]
2025-12-03 11:21:09.023329 | controller | skipping: Conditional result was False
2025-12-03 11:21:09.029162 | 
2025-12-03 11:21:09.029237 | TASK [Get SELinux related data]
2025-12-03 11:21:09.248291 | controller | <no matches>
2025-12-03 11:21:09.560198 | controller | ERROR
2025-12-03 11:21:09.560424 | controller | {
2025-12-03 11:21:09.560478 | controller |   "delta": "0:00:00.010831",
2025-12-03 11:21:09.560506 | controller |   "end": "2025-12-03 11:21:09.248994",
2025-12-03 11:21:09.560526 | controller |   "msg": "non-zero return code",
2025-12-03 11:21:09.560552 | controller |   "rc": 1,
2025-12-03 11:21:09.560583 | controller |   "start": "2025-12-03 11:21:09.238163"
2025-12-03 11:21:09.560601 | controller | }
2025-12-03 11:21:09.560627 | controller | ERROR: Ignoring Errors
2025-12-03 11:21:09.570700 | 
2025-12-03 11:21:09.570974 | TASK [Create system configuration directory]
2025-12-03 11:21:09.784283 | controller | changed
2025-12-03 11:21:09.789054 | 
2025-12-03 11:21:09.789119 | TASK [Get some of the system configurations]
2025-12-03 11:21:10.317654 | controller | changed
2025-12-03 11:21:10.323071 | 
2025-12-03 11:21:10.323138 | TASK [Copy generated documentation if available]
2025-12-03 11:21:10.336772 | controller | skipping: Conditional result was False
2025-12-03 11:21:10.342547 | 
2025-12-03 11:21:10.342621 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-03 11:21:10.359517 | controller | skipping: Conditional result was False
2025-12-03 11:21:10.366854 | 
2025-12-03 11:21:10.366937 | TASK [Compress logs bigger than 2MB]
2025-12-03 11:21:10.902805 | controller | changed
2025-12-03 11:21:10.908505 | 
2025-12-03 11:21:10.908576 | TASK [Copy files from workspace on node]
2025-12-03 11:21:10.927218 | controller | ok
2025-12-03 11:21:10.950217 | 
2025-12-03 11:21:10.950314 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-03 11:21:10.974122 | controller | skipping: Conditional result was False
2025-12-03 11:21:10.981862 | 
2025-12-03 11:21:10.981975 | TASK [fetch-output : Set log path for single node]
2025-12-03 11:21:11.011389 | controller | ok
2025-12-03 11:21:11.016144 | 
2025-12-03 11:21:11.016207 | LOOP [fetch-output : Ensure local output dirs]
2025-12-03 11:21:11.216624 | controller -> localhost | ok: "/var/lib/zuul/builds/bc931c3292fb49d6804bf93c4b66616d/work/logs"
2025-12-03 11:21:11.216839 | controller -> localhost | changed: All items complete
2025-12-03 11:21:11.216869 | 
2025-12-03 11:21:11.423348 | controller -> localhost | changed: "/var/lib/zuul/builds/bc931c3292fb49d6804bf93c4b66616d/work/artifacts"
2025-12-03 11:21:11.649077 | controller -> localhost | changed: "/var/lib/zuul/builds/bc931c3292fb49d6804bf93c4b66616d/work/docs"
2025-12-03 11:21:11.658006 | 
2025-12-03 11:21:11.658088 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-03 11:21:12.244344 | controller | changed:
2025-12-03 11:21:12.244512 | controller | .d..t...... ./
2025-12-03 11:21:12.244542 | controller | >f+++++++++ README.html
2025-12-03 11:21:12.244564 | controller | >f+++++++++ ansible-execution.log
2025-12-03 11:21:12.244584 | controller | >f+++++++++ ansible.log
2025-12-03 11:21:12.244604 | controller | >f+++++++++ dmesg.log
2025-12-03 11:21:12.244623 | controller | >f+++++++++ installed-pkgs.log
2025-12-03 11:21:12.244644 | controller | >f+++++++++ python.log
2025-12-03 11:21:12.244663 | controller | >f+++++++++ registries.conf
2025-12-03 11:21:12.244681 | controller | >f+++++++++ report.html
2025-12-03 11:21:12.244701 | controller | >f+++++++++ selinux-denials.log
2025-12-03 11:21:12.244719 | controller | >f+++++++++ selinux-listing.log
2025-12-03 11:21:12.244737 | controller | cd+++++++++ ci-framework-data/
2025-12-03 11:21:12.244754 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-03 11:21:12.244771 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-03 11:21:12.244788 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-12-03 11:21:12.244804 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/kustomizations/
2025-12-03 11:21:12.244821 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/kustomizations/dataplane/
2025-12-03 11:21:12.244837 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/kustomizations/dataplane/87-hci-post-kustomization.yaml
2025-12-03 11:21:12.244854 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml
2025-12-03 11:21:12.244870 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml
2025-12-03 11:21:12.244887 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-03 11:21:12.244903 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/network_info.yml
2025-12-03 11:21:12.244943 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-03 11:21:12.244963 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-12-03 11:21:12.244980 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-12-03 11:21:12.244997 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-03 11:21:12.245014 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-12-03 11:21:12.245030 | controller | cd+++++++++ registries.conf.d/
2025-12-03 11:21:12.245046 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-03 11:21:12.245063 | controller | cd+++++++++ system-config/
2025-12-03 11:21:12.245079 | controller | cd+++++++++ system-config/libvirt/
2025-12-03 11:21:12.245095 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-03 11:21:12.245112 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-03 11:21:12.685468 | controller | changed: .d..t...... ./
2025-12-03 11:21:13.075580 | controller | changed: .d..t...... ./
2025-12-03 11:21:13.095415 | 
2025-12-03 11:21:13.095500 | TASK [Return artifact to Zuul]
2025-12-03 11:21:13.130611 | controller | ok
2025-12-03 11:21:13.153197 | 
2025-12-03 11:21:13.153260 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-03 11:21:13.153373 | 
2025-12-03 11:21:13.153401 | PLAY RECAP
2025-12-03 11:21:13.153438 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-12-03 11:21:13.153458 | 
2025-12-03 11:21:13.236952 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-03 11:21:13.237707 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-03 11:21:13.894405 | 
2025-12-03 11:21:13.894514 | PLAY [all]
2025-12-03 11:21:13.914025 | 
2025-12-03 11:21:13.914108 | TASK [include_role : fetch-output]
2025-12-03 11:21:13.985888 | controller | ok
2025-12-03 11:21:14.003807 | 
2025-12-03 11:21:14.003938 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-03 11:21:14.048620 | controller | skipping: Conditional result was False
2025-12-03 11:21:14.054261 | 
2025-12-03 11:21:14.054350 | TASK [fetch-output : Set log path for single node]
2025-12-03 11:21:14.085091 | controller | ok
2025-12-03 11:21:14.090571 | 
2025-12-03 11:21:14.090654 | LOOP [fetch-output : Ensure local output dirs]
2025-12-03 11:21:14.534838 | controller -> localhost | ok: "/var/lib/zuul/builds/bc931c3292fb49d6804bf93c4b66616d/work/logs"
2025-12-03 11:21:14.753351 | controller -> localhost | ok: "/var/lib/zuul/builds/bc931c3292fb49d6804bf93c4b66616d/work/artifacts"
2025-12-03 11:21:14.960706 | controller -> localhost | ok: "/var/lib/zuul/builds/bc931c3292fb49d6804bf93c4b66616d/work/docs"
2025-12-03 11:21:14.970071 | 
2025-12-03 11:21:14.970168 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-03 11:21:15.513597 | controller | ok
2025-12-03 11:21:15.513830 | controller | ok: All items complete
2025-12-03 11:21:15.513866 | 
2025-12-03 11:21:15.893634 | controller | ok
2025-12-03 11:21:16.268808 | controller | ok
2025-12-03 11:21:16.280044 | 
2025-12-03 11:21:16.280124 | TASK [include_role : fetch-output-openshift]
2025-12-03 11:21:16.293162 | controller | skipping: Conditional result was False
2025-12-03 11:21:16.305052 | 
2025-12-03 11:21:16.305134 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-03 11:21:16.685164 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006900
2025-12-03 11:21:16.883229 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006882
2025-12-03 11:21:16.912720 | 
2025-12-03 11:21:16.912820 | PLAY [all]
2025-12-03 11:21:16.933602 | 
2025-12-03 11:21:16.933681 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-03 11:21:17.399174 | controller | changed
2025-12-03 11:21:17.422490 | 
2025-12-03 11:21:17.422557 | PLAY RECAP
2025-12-03 11:21:17.422602 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-03 11:21:17.422626 | 
2025-12-03 11:21:17.505798 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-03 11:21:17.506548 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-03 11:21:18.128734 | 
2025-12-03 11:21:18.128840 | PLAY [localhost]
2025-12-03 11:21:18.155390 | 
2025-12-03 11:21:18.155481 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-03 11:21:18.536227 | localhost | changed
2025-12-03 11:21:18.540851 | 
2025-12-03 11:21:18.540952 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-03 11:21:18.568886 | localhost | ok
2025-12-03 11:21:18.577037 | 
2025-12-03 11:21:18.577131 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-03 11:21:18.911492 | localhost | changed
2025-12-03 11:21:18.917192 | 
2025-12-03 11:21:18.917287 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-03 11:21:19.567603 | localhost | changed
2025-12-03 11:21:19.573092 | 
2025-12-03 11:21:19.573179 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-03 11:21:19.957312 | localhost | Identity added: /var/lib/zuul/builds/bc931c3292fb49d6804bf93c4b66616d/work/tmp/ansible.353wgpjr (/var/lib/zuul/builds/bc931c3292fb49d6804bf93c4b66616d/work/tmp/ansible.353wgpjr)
2025-12-03 11:21:19.957520 | localhost | ok: Runtime: 0:00:00.006949
2025-12-03 11:21:19.961826 | 
2025-12-03 11:21:19.961896 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-03 11:21:20.208734 | localhost | ok: Runtime: 0:00:00.004646
2025-12-03 11:21:20.214178 | 
2025-12-03 11:21:20.214383 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-03 11:21:20.263648 | localhost | changed
2025-12-03 11:21:20.268357 | 
2025-12-03 11:21:20.268446 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-03 11:21:20.610535 | localhost | changed
2025-12-03 11:21:20.631426 | 
2025-12-03 11:21:20.631502 | PLAY [localhost]
2025-12-03 11:21:20.644560 | 
2025-12-03 11:21:20.644624 | TASK [Generate bulk log download script]
2025-12-03 11:21:20.662095 | localhost | ok
2025-12-03 11:21:20.674224 | 
2025-12-03 11:21:20.674305 | TASK [local-log-download : Check API endpoint is defined]
2025-12-03 11:21:20.701574 | localhost | ok: All assertions passed
2025-12-03 11:21:20.706238 | 
2025-12-03 11:21:20.706325 | TASK [local-log-download : Create download script]
2025-12-03 11:21:21.145885 | localhost -> localhost | changed
2025-12-03 11:21:21.155088 | 
2025-12-03 11:21:21.155172 | TASK [Register quick-download link]
2025-12-03 11:21:21.172862 | localhost | ok
2025-12-03 11:21:21.211302 | 
2025-12-03 11:21:21.211375 | PLAY [logserver.rdoproject.org]
2025-12-03 11:21:21.221627 | 
2025-12-03 11:21:21.221704 | TASK [Set zuul-log-path fact]
2025-12-03 11:21:21.237290 | logserver.rdoproject.org | ok
2025-12-03 11:21:21.247734 | 
2025-12-03 11:21:21.247819 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-03 11:21:21.284606 | logserver.rdoproject.org | ok
2025-12-03 11:21:21.290124 | 
2025-12-03 11:21:21.290215 | TASK [upload-logs : Create log directories]
2025-12-03 11:21:22.855374 | logserver.rdoproject.org | changed
2025-12-03 11:21:22.860365 | 
2025-12-03 11:21:22.860448 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-03 11:21:23.106581 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.006879
2025-12-03 11:21:23.111432 | 
2025-12-03 11:21:23.111518 | TASK [upload-logs : Upload logs to log server]
2025-12-03 11:21:24.549375 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-03 11:21:24.552686 | 
2025-12-03 11:21:24.552762 | LOOP [upload-logs : Compress console log and json output]
2025-12-03 11:21:24.618559 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 11:21:24.619397 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 11:21:24.626298 | 
2025-12-03 11:21:24.626416 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-03 11:21:24.665489 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 11:21:24.665695 | 
2025-12-03 11:21:24.670217 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 11:21:24.674615 | 
2025-12-03 11:21:24.674731 | LOOP [upload-logs : Upload console log and json output]