2025-11-25 14:37:51.078915 | Job console starting...
2025-11-25 14:37:51.313360 | Updating repositories
2025-11-25 14:37:52.038846 | Preparing job workspace
2025-11-25 14:37:59.233922 | Running Ansible setup...
2025-11-25 14:38:02.014795 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-25 14:38:02.504921 | 
2025-11-25 14:38:02.505006 | PLAY [localhost]
2025-11-25 14:38:02.513032 | 
2025-11-25 14:38:02.513097 | TASK [Gathering Facts]
2025-11-25 14:38:03.306486 | localhost | ok
2025-11-25 14:38:03.319462 | 
2025-11-25 14:38:03.319542 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-25 14:38:03.616065 | localhost -> localhost | changed
2025-11-25 14:38:03.621226 | 
2025-11-25 14:38:03.621314 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-25 14:38:04.263533 | localhost -> localhost | changed
2025-11-25 14:38:04.271220 | 
2025-11-25 14:38:04.271296 | TASK [Setup log path fact]
2025-11-25 14:38:04.288126 | localhost | ok
2025-11-25 14:38:04.298413 | 
2025-11-25 14:38:04.298479 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-25 14:38:04.314861 | localhost | ok
2025-11-25 14:38:04.321649 | 
2025-11-25 14:38:04.321713 | TASK [emit-job-header : Print job information]
2025-11-25 14:38:04.348417 | # Job Information
2025-11-25 14:38:04.348530 | Ansible Version: 2.15.12
2025-11-25 14:38:04.348560 | Job: cifmw-molecule-cifmw_ceph_client
2025-11-25 14:38:04.348582 | Pipeline: github-check
2025-11-25 14:38:04.348601 | Executor: ibm-bm3-ze.softwarefactory-project.io
2025-11-25 14:38:04.348620 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3169
2025-11-25 14:38:04.348639 | Log URL (when completed): https://logserver.rdoproject.org/79e/rdoproject.org/79ec692a76df44898c82236a52e0e2a5/
2025-11-25 14:38:04.348658 | Event ID: 2e3d8f50-ca0c-11f0-9a58-7d6af1ed6156
2025-11-25 14:38:04.352264 | 
2025-11-25 14:38:04.352330 | LOOP [emit-job-header : Print node information]
2025-11-25 14:38:04.434075 | localhost | ok:
2025-11-25 14:38:04.434248 | localhost | # Node Information
2025-11-25 14:38:04.434279 | localhost | Inventory Hostname: controller
2025-11-25 14:38:04.434303 | localhost | Hostname: np0005535390
2025-11-25 14:38:04.434324 | localhost | Username: zuul
2025-11-25 14:38:04.434344 | localhost | Distro: CentOS 9
2025-11-25 14:38:04.434363 | localhost | Provider: ibm-bm3-nodepool
2025-11-25 14:38:04.434402 | localhost | Region: regionOne
2025-11-25 14:38:04.434430 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-11-25 14:38:04.434453 | localhost | Product Name: OpenStack Compute
2025-11-25 14:38:04.434472 | localhost | Interface IP: 192.168.25.185
2025-11-25 14:38:04.453632 | 
2025-11-25 14:38:04.453705 | PLAY [all]
2025-11-25 14:38:04.459940 | 
2025-11-25 14:38:04.459998 | TASK [Gather network facts]
2025-11-25 14:38:04.802150 | controller | ok
2025-11-25 14:38:04.815583 | 
2025-11-25 14:38:04.815658 | TASK [include_role : start-zuul-console]
2025-11-25 14:38:04.833103 | controller | ok
2025-11-25 14:38:04.844919 | 
2025-11-25 14:38:04.844989 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-25 14:38:05.136022 | controller | ok
2025-11-25 14:38:05.144067 | 
2025-11-25 14:38:05.144131 | TASK [include_role : add-build-sshkey]
2025-11-25 14:38:05.161468 | controller | ok
2025-11-25 14:38:05.173455 | 
2025-11-25 14:38:05.173521 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-25 14:38:05.358797 | controller -> localhost | ok
2025-11-25 14:38:05.363878 | 
2025-11-25 14:38:05.363940 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-25 14:38:05.381061 | controller | ok
2025-11-25 14:38:05.393734 | controller | included: /var/lib/zuul/builds/79ec692a76df44898c82236a52e0e2a5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-25 14:38:05.399093 | 
2025-11-25 14:38:05.399154 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-25 14:38:05.858096 | controller -> localhost | Generating public/private rsa key pair.
2025-11-25 14:38:05.858249 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/79ec692a76df44898c82236a52e0e2a5/work/79ec692a76df44898c82236a52e0e2a5_id_rsa.
2025-11-25 14:38:05.858282 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/79ec692a76df44898c82236a52e0e2a5/work/79ec692a76df44898c82236a52e0e2a5_id_rsa.pub.
2025-11-25 14:38:05.858306 | controller -> localhost | The key fingerprint is:
2025-11-25 14:38:05.858328 | controller -> localhost | SHA256:UdnG/l5qETE9PqMWE1rLuZ24egK7RYXVpqaz3RpSxRw zuul-build-sshkey
2025-11-25 14:38:05.858347 | controller -> localhost | The key's randomart image is:
2025-11-25 14:38:05.858366 | controller -> localhost | +---[RSA 3072]----+
2025-11-25 14:38:05.858402 | controller -> localhost | |          .+ ..E |
2025-11-25 14:38:05.858430 | controller -> localhost | |         .. *o=+o|
2025-11-25 14:38:05.858450 | controller -> localhost | |        .  ++.B*.|
2025-11-25 14:38:05.858469 | controller -> localhost | |         . .oOo+ |
2025-11-25 14:38:05.858486 | controller -> localhost | |        S  .ooB.+|
2025-11-25 14:38:05.858504 | controller -> localhost | |         ..o.=oo.|
2025-11-25 14:38:05.858521 | controller -> localhost | |          oo=oo+ |
2025-11-25 14:38:05.858541 | controller -> localhost | |         ..o.+=. |
2025-11-25 14:38:05.858558 | controller -> localhost | |         ...+o.  |
2025-11-25 14:38:05.858574 | controller -> localhost | +----[SHA256]-----+
2025-11-25 14:38:05.858612 | controller -> localhost | ok: Runtime: 0:00:00.147929
2025-11-25 14:38:05.863703 | 
2025-11-25 14:38:05.863761 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-25 14:38:05.880654 | controller | ok
2025-11-25 14:38:05.888845 | controller | included: /var/lib/zuul/builds/79ec692a76df44898c82236a52e0e2a5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-25 14:38:05.895847 | 
2025-11-25 14:38:05.895907 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-25 14:38:05.909027 | controller | skipping: Conditional result was False
2025-11-25 14:38:05.913806 | 
2025-11-25 14:38:05.913875 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-25 14:38:06.254576 | controller | changed
2025-11-25 14:38:06.260713 | 
2025-11-25 14:38:06.260776 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-25 14:38:06.451284 | controller | ok
2025-11-25 14:38:06.456298 | 
2025-11-25 14:38:06.456414 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-25 14:38:06.981325 | controller | changed
2025-11-25 14:38:06.986363 | 
2025-11-25 14:38:06.986460 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-25 14:38:07.479579 | controller | changed
2025-11-25 14:38:07.484458 | 
2025-11-25 14:38:07.484536 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-25 14:38:07.497297 | controller | skipping: Conditional result was False
2025-11-25 14:38:07.502597 | 
2025-11-25 14:38:07.502672 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-25 14:38:07.788523 | controller -> localhost | changed
2025-11-25 14:38:07.797277 | 
2025-11-25 14:38:07.797342 | TASK [add-build-sshkey : Add back temp key]
2025-11-25 14:38:08.002259 | controller -> localhost | Identity added: /var/lib/zuul/builds/79ec692a76df44898c82236a52e0e2a5/work/79ec692a76df44898c82236a52e0e2a5_id_rsa (zuul-build-sshkey)
2025-11-25 14:38:08.002531 | controller -> localhost | ok: Runtime: 0:00:00.006534
2025-11-25 14:38:08.007696 | 
2025-11-25 14:38:08.007754 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-25 14:38:08.269426 | controller | ok
2025-11-25 14:38:08.273859 | 
2025-11-25 14:38:08.273923 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-25 14:38:08.297346 | controller | skipping: Conditional result was False
2025-11-25 14:38:08.306136 | 
2025-11-25 14:38:08.306198 | TASK [include_role : validate-host]
2025-11-25 14:38:08.323242 | controller | ok
2025-11-25 14:38:08.340963 | 
2025-11-25 14:38:08.341024 | TASK [validate-host : Define zuul_info_dir fact]
2025-11-25 14:38:08.368561 | controller | ok
2025-11-25 14:38:08.372860 | 
2025-11-25 14:38:08.372922 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-11-25 14:38:08.557929 | controller -> localhost | ok
2025-11-25 14:38:08.563379 | 
2025-11-25 14:38:08.563459 | TASK [validate-host : Collect information about the host]
2025-11-25 14:38:09.118926 | controller | ok
2025-11-25 14:38:09.126797 | 
2025-11-25 14:38:09.126872 | TASK [validate-host : Sanitize hostname]
2025-11-25 14:38:09.184161 | controller | ok
2025-11-25 14:38:09.188858 | 
2025-11-25 14:38:09.188920 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-11-25 14:38:09.578411 | controller -> localhost | changed
2025-11-25 14:38:09.602116 | 
2025-11-25 14:38:09.602200 | TASK [validate-host : Collect information about zuul worker]
2025-11-25 14:38:09.890884 | controller | ok
2025-11-25 14:38:09.895354 | 
2025-11-25 14:38:09.895432 | TASK [validate-host : Write out all zuul information for each host]
2025-11-25 14:38:10.242995 | controller -> localhost | changed
2025-11-25 14:38:10.251910 | 
2025-11-25 14:38:10.251973 | TASK [include_role : prepare-workspace-openshift]
2025-11-25 14:38:10.265066 | controller | skipping: Conditional result was False
2025-11-25 14:38:10.269620 | 
2025-11-25 14:38:10.269680 | TASK [include_role : remove-zuul-sshkey]
2025-11-25 14:38:10.282592 | controller | skipping: Conditional result was False
2025-11-25 14:38:10.287540 | 
2025-11-25 14:38:10.287605 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-25 14:38:10.468639 | controller | ok: "logs"
2025-11-25 14:38:10.468833 | controller | ok: All items complete
2025-11-25 14:38:10.468864 | 
2025-11-25 14:38:10.623124 | controller | ok: "artifacts"
2025-11-25 14:38:10.785225 | controller | ok: "docs"
2025-11-25 14:38:10.793109 | 
2025-11-25 14:38:10.793175 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-25 14:38:10.978970 | controller | changed: "logs"
2025-11-25 14:38:11.137764 | controller | changed: "artifacts"
2025-11-25 14:38:11.297231 | controller | changed: "docs"
2025-11-25 14:38:11.323560 | 
2025-11-25 14:38:11.323654 | PLAY RECAP
2025-11-25 14:38:11.323700 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-25 14:38:11.323729 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-25 14:38:11.323748 | 
2025-11-25 14:38:11.392311 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-25 14:38:11.393019 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-25 14:38:11.943661 | 
2025-11-25 14:38:11.943769 | PLAY [all]
2025-11-25 14:38:11.962607 | 
2025-11-25 14:38:11.962681 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-25 14:38:12.011002 | controller | ok
2025-11-25 14:38:12.015926 | 
2025-11-25 14:38:12.016032 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-25 14:38:12.328152 | controller | changed
2025-11-25 14:38:12.333535 | 
2025-11-25 14:38:12.333607 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-25 14:38:13.062222 | controller | changed
2025-11-25 14:38:13.071346 | 
2025-11-25 14:38:13.071443 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-11-25 14:38:13.410029 | controller | changed:
2025-11-25 14:38:13.410204 | controller | {
2025-11-25 14:38:13.410234 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-11-25 14:38:13.410264 | controller | }
2025-11-25 14:38:13.604277 | controller | changed:
2025-11-25 14:38:13.604342 | controller | {
2025-11-25 14:38:13.604370 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-11-25 14:38:13.604425 | controller | }
2025-11-25 14:38:13.795541 | controller | changed:
2025-11-25 14:38:13.795745 | controller | {
2025-11-25 14:38:13.795786 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-11-25 14:38:13.795820 | controller | }
2025-11-25 14:38:13.991742 | controller | changed:
2025-11-25 14:38:13.991800 | controller | {
2025-11-25 14:38:13.991839 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-11-25 14:38:13.991859 | controller | }
2025-11-25 14:38:14.178719 | controller | changed:
2025-11-25 14:38:14.178772 | controller | {
2025-11-25 14:38:14.178798 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-11-25 14:38:14.178829 | controller | }
2025-11-25 14:38:14.369132 | controller | changed:
2025-11-25 14:38:14.369209 | controller | {
2025-11-25 14:38:14.369237 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-11-25 14:38:14.369257 | controller | }
2025-11-25 14:38:14.576494 | controller | changed:
2025-11-25 14:38:14.576589 | controller | {
2025-11-25 14:38:14.576616 | 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-11-25 14:38:14.576637 | controller | }
2025-11-25 14:38:14.786699 | controller | changed:
2025-11-25 14:38:14.786780 | controller | {
2025-11-25 14:38:14.786807 | 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-11-25 14:38:14.786839 | controller | }
2025-11-25 14:38:14.983239 | controller | changed:
2025-11-25 14:38:14.983340 | controller | {
2025-11-25 14:38:14.983367 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-11-25 14:38:14.983426 | controller | }
2025-11-25 14:38:15.176311 | controller | changed:
2025-11-25 14:38:15.176423 | controller | {
2025-11-25 14:38:15.176452 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-11-25 14:38:15.176474 | controller | }
2025-11-25 14:38:15.370278 | controller | changed:
2025-11-25 14:38:15.370373 | controller | {
2025-11-25 14:38:15.370426 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-11-25 14:38:15.370448 | controller | }
2025-11-25 14:38:15.581363 | controller | changed:
2025-11-25 14:38:15.581475 | controller | {
2025-11-25 14:38:15.581503 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-11-25 14:38:15.581524 | controller | }
2025-11-25 14:38:15.754039 | controller | changed:
2025-11-25 14:38:15.754108 | controller | {
2025-11-25 14:38:15.754134 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-11-25 14:38:15.754153 | controller | }
2025-11-25 14:38:15.941264 | controller | changed:
2025-11-25 14:38:15.941325 | controller | {
2025-11-25 14:38:15.941350 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-11-25 14:38:15.941369 | controller | }
2025-11-25 14:38:16.132802 | controller | changed:
2025-11-25 14:38:16.132870 | controller | {
2025-11-25 14:38:16.132896 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-11-25 14:38:16.132915 | controller | }
2025-11-25 14:38:16.323274 | controller | changed:
2025-11-25 14:38:16.323366 | controller | {
2025-11-25 14:38:16.323427 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-11-25 14:38:16.323453 | controller | }
2025-11-25 14:38:16.512456 | controller | changed:
2025-11-25 14:38:16.512604 | controller | {
2025-11-25 14:38:16.512640 | 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-11-25 14:38:16.512664 | controller | }
2025-11-25 14:38:16.706616 | controller | changed:
2025-11-25 14:38:16.706683 | controller | {
2025-11-25 14:38:16.706709 | 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-11-25 14:38:16.706738 | controller | }
2025-11-25 14:38:16.901992 | controller | changed:
2025-11-25 14:38:16.902059 | controller | {
2025-11-25 14:38:16.902085 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-11-25 14:38:16.902104 | controller | }
2025-11-25 14:38:17.099992 | controller | changed:
2025-11-25 14:38:17.100126 | controller | {
2025-11-25 14:38:17.100158 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-11-25 14:38:17.100179 | controller | }
2025-11-25 14:38:17.298581 | controller | changed:
2025-11-25 14:38:17.298634 | controller | {
2025-11-25 14:38:17.298659 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-11-25 14:38:17.298678 | controller | }
2025-11-25 14:38:17.494378 | controller | changed:
2025-11-25 14:38:17.494566 | controller | {
2025-11-25 14:38:17.494592 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-11-25 14:38:17.494611 | controller | }
2025-11-25 14:38:17.692036 | controller | changed:
2025-11-25 14:38:17.692089 | controller | {
2025-11-25 14:38:17.692117 | 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-11-25 14:38:17.692139 | controller | }
2025-11-25 14:38:17.886313 | controller | changed:
2025-11-25 14:38:17.886376 | controller | {
2025-11-25 14:38:17.886435 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-11-25 14:38:17.886458 | controller | }
2025-11-25 14:38:18.080697 | controller | changed:
2025-11-25 14:38:18.080748 | controller | {
2025-11-25 14:38:18.080775 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-11-25 14:38:18.080795 | controller | }
2025-11-25 14:38:18.280678 | controller | changed:
2025-11-25 14:38:18.280730 | controller | {
2025-11-25 14:38:18.280755 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-11-25 14:38:18.280774 | controller | }
2025-11-25 14:38:18.299767 | 
2025-11-25 14:38:18.299842 | TASK [Set timezone to UTC]
2025-11-25 14:38:18.682875 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-11-25 14:38:18.687336 | 
2025-11-25 14:38:18.687417 | TASK [Create nodepool directory]
2025-11-25 14:38:18.879075 | controller | changed
2025-11-25 14:38:18.884637 | 
2025-11-25 14:38:18.884701 | TASK [Create nodepool sub_nodes file]
2025-11-25 14:38:19.357819 | controller | changed
2025-11-25 14:38:19.362520 | 
2025-11-25 14:38:19.362588 | TASK [Create nodepool sub_nodes_private file]
2025-11-25 14:38:19.835649 | controller | changed
2025-11-25 14:38:19.841124 | 
2025-11-25 14:38:19.841200 | LOOP [Populate nodepool sub_nodes file]
2025-11-25 14:38:19.869856 | 
2025-11-25 14:38:19.869980 | LOOP [Populate nodepool sub_nodes_private file]
2025-11-25 14:38:19.898354 | 
2025-11-25 14:38:19.898488 | TASK [Create nodepool primary file]
2025-11-25 14:38:19.921586 | controller | skipping: Conditional result was False
2025-11-25 14:38:19.926309 | 
2025-11-25 14:38:19.926403 | TASK [Create nodepool node_private for this node]
2025-11-25 14:38:20.429514 | controller | changed
2025-11-25 14:38:20.434676 | 
2025-11-25 14:38:20.434742 | LOOP [Copy ssh keys to nodepool directory]
2025-11-25 14:38:20.739250 | controller | ok: Item: id_rsa Runtime: 0:00:00.003862
2025-11-25 14:38:20.739441 | 
2025-11-25 14:38:20.891627 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.003996
2025-11-25 14:38:20.899052 | 
2025-11-25 14:38:20.899160 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-11-25 14:38:21.413922 | controller | changed
2025-11-25 14:38:21.418556 | 
2025-11-25 14:38:21.418618 | TASK [Validate sudoers config after edits]
2025-11-25 14:38:21.622281 | controller | /etc/sudoers: parsed OK
2025-11-25 14:38:21.622333 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-11-25 14:38:21.622342 | controller | /etc/sudoers.d/zuul: parsed OK
2025-11-25 14:38:21.622348 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-11-25 14:38:21.943427 | controller | ok: Runtime: 0:00:00.004714
2025-11-25 14:38:21.948906 | 
2025-11-25 14:38:21.948976 | TASK [Show the environment passed in to job shell scripts]
2025-11-25 14:38:22.143097 | controller | SHELL=/bin/bash
2025-11-25 14:38:22.143148 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-11-25 14:38:22.143158 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-11-25 14:38:22.143174 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/69/3169/ac733a5b0fe7d9d4bc94f6c04ef44d1791387733
2025-11-25 14:38:22.143181 | controller | PWD=/home/zuul
2025-11-25 14:38:22.143213 | controller | ZUUL_PIPELINE=github-check
2025-11-25 14:38:22.143221 | controller | LOGNAME=zuul
2025-11-25 14:38:22.143226 | controller | XDG_SESSION_TYPE=tty
2025-11-25 14:38:22.143232 | controller | _=/usr/bin/env
2025-11-25 14:38:22.143237 | controller | MOTD_SHOWN=pam
2025-11-25 14:38:22.143242 | controller | HOME=/home/zuul
2025-11-25 14:38:22.143247 | controller | LANG=en_US.UTF-8
2025-11-25 14:38:22.143253 | controller | SSH_CONNECTION=192.168.25.12 44120 192.168.25.185 22
2025-11-25 14:38:22.143258 | 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-11-25 14:38:22.143267 | controller | ZUUL_CHANGE_IDS=3169,ac733a5b0fe7d9d4bc94f6c04ef44d1791387733
2025-11-25 14:38:22.143272 | controller | WORKSPACE=/home/zuul/workspace
2025-11-25 14:38:22.143277 | controller | XDG_SESSION_CLASS=user
2025-11-25 14:38:22.143283 | controller | SELINUX_ROLE_REQUESTED=
2025-11-25 14:38:22.143288 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-11-25 14:38:22.143293 | controller | USER=zuul
2025-11-25 14:38:22.143298 | controller | ZUUL_VOTING=True
2025-11-25 14:38:22.143304 | controller | BUILD_TIMEOUT=1800000
2025-11-25 14:38:22.143309 | controller | SELINUX_USE_CURRENT_RANGE=
2025-11-25 14:38:22.143314 | controller | SHLVL=1
2025-11-25 14:38:22.143319 | controller | ZUUL_PATCHSET=ac733a5b0fe7d9d4bc94f6c04ef44d1791387733
2025-11-25 14:38:22.143325 | controller | XDG_SESSION_ID=1
2025-11-25 14:38:22.143330 | controller | ZUUL_BRANCH=main
2025-11-25 14:38:22.143335 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-11-25 14:38:22.143340 | controller | SSH_CLIENT=192.168.25.12 44120 22
2025-11-25 14:38:22.143346 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-11-25 14:38:22.143351 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-11-25 14:38:22.143356 | controller | which_declare=declare -f
2025-11-25 14:38:22.143362 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-11-25 14:38:22.143368 | controller | SELINUX_LEVEL_REQUESTED=
2025-11-25 14:38:22.143373 | controller | ZUUL_CHANGE=3169
2025-11-25 14:38:22.143378 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-11-25 14:38:22.143383 | controller | ZUUL_UUID=79ec692a76df44898c82236a52e0e2a5
2025-11-25 14:38:22.143388 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-11-25 14:38:22.143394 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-11-25 14:38:22.143399 | controller | }
2025-11-25 14:38:22.474645 | controller | ok: Runtime: 0:00:00.005331
2025-11-25 14:38:22.479504 | 
2025-11-25 14:38:22.479564 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-11-25 14:38:22.492852 | controller | skipping: Conditional result was False
2025-11-25 14:38:22.498142 | 
2025-11-25 14:38:22.498206 | TASK [Symlink /home/zuul-worker/workspace]
2025-11-25 14:38:23.022482 | controller | skipping: Conditional result was False
2025-11-25 14:38:23.028108 | 
2025-11-25 14:38:23.028174 | TASK [Ensure legacy workspace directory]
2025-11-25 14:38:23.208182 | controller | changed
2025-11-25 14:38:23.225470 | 
2025-11-25 14:38:23.225520 | PLAY RECAP
2025-11-25 14:38:23.225567 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-25 14:38:23.225591 | 
2025-11-25 14:38:23.302678 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-25 14:38:23.303374 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-25 14:38:23.783355 | 
2025-11-25 14:38:23.783477 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-11-25 14:38:23.802205 | 
2025-11-25 14:38:23.802277 | TASK [Create zuul-output directory]
2025-11-25 14:38:24.104581 | controller | changed
2025-11-25 14:38:24.109558 | 
2025-11-25 14:38:24.109641 | TASK [Slurp Zuul inventory test]
2025-11-25 14:38:24.375854 | controller -> localhost | ok
2025-11-25 14:38:24.381714 | 
2025-11-25 14:38:24.381779 | TASK [Save zuul inventory]
2025-11-25 14:38:25.069440 | controller | changed
2025-11-25 14:38:25.074128 | 
2025-11-25 14:38:25.074191 | TASK [Save zuul vars without the change_message]
2025-11-25 14:38:25.616682 | controller | changed
2025-11-25 14:38:25.633512 | 
2025-11-25 14:38:25.633559 | PLAY RECAP
2025-11-25 14:38:25.633600 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-25 14:38:25.633623 | 
2025-11-25 14:38:25.694608 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-25 14:38:25.695346 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-25 14:38:26.220638 | 
2025-11-25 14:38:26.220757 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-11-25 14:38:26.240587 | 
2025-11-25 14:38:26.240658 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-11-25 14:38:26.258886 | controller | ok
2025-11-25 14:38:26.275067 | 
2025-11-25 14:38:26.275150 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-25 14:38:26.298168 | controller | skipping: Conditional result was False
2025-11-25 14:38:26.303582 | 
2025-11-25 14:38:26.303653 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-25 14:38:26.604827 | controller | ok
2025-11-25 14:38:26.610034 | 
2025-11-25 14:38:26.610102 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-25 14:38:27.195084 | controller | ok
2025-11-25 14:38:27.204763 | 
2025-11-25 14:38:27.204835 | TASK [Prepare workspace]
2025-11-25 14:38:27.222705 | controller | ok
2025-11-25 14:38:27.238541 | 
2025-11-25 14:38:27.238603 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-25 14:38:27.519668 | controller | ok
2025-11-25 14:38:27.525139 | 
2025-11-25 14:38:27.525202 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-25 14:38:29.995965 | controller | Output suppressed because no_log was given
2025-11-25 14:38:30.004741 | 
2025-11-25 14:38:30.004805 | LOOP [Create zuul-output directory]
2025-11-25 14:38:30.183916 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-11-25 14:38:30.335779 | controller | ok: "/home/zuul/zuul-output/logs"
2025-11-25 14:38:30.348886 | 
2025-11-25 14:38:30.348987 | TASK [Install required packages]
2025-11-25 14:39:18.203075 | controller | changed
2025-11-25 14:39:18.207992 | 
2025-11-25 14:39:18.208056 | TASK [Install venv]
2025-11-25 14:40:18.047935 | controller | changed
2025-11-25 14:40:18.071318 | 
2025-11-25 14:40:18.071405 | PLAY RECAP
2025-11-25 14:40:18.071455 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-25 14:40:18.071479 | 
2025-11-25 14:40:18.139536 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-25 14:40:18.140256 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-25 14:40:18.655686 | 
2025-11-25 14:40:18.655779 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-11-25 14:40:18.675568 | 
2025-11-25 14:40:18.675638 | TASK [Gather required facts]
2025-11-25 14:40:19.130682 | controller | ok
2025-11-25 14:40:19.135907 | 
2025-11-25 14:40:19.135982 | TASK [Load environment var if instructed to]
2025-11-25 14:40:19.160222 | controller | skipping: Conditional result was False
2025-11-25 14:40:19.165406 | 
2025-11-25 14:40:19.165474 | TASK [Ensure group_vars dir exists]
2025-11-25 14:40:19.457152 | controller | ok
2025-11-25 14:40:19.462919 | 
2025-11-25 14:40:19.462982 | TASK [Print related variables]
2025-11-25 14:40:19.491041 | controller | ok:
2025-11-25 14:40:19.491148 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-11-25 14:40:19.491178 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_ceph_client
2025-11-25 14:40:19.495697 | 
2025-11-25 14:40:19.495756 | TASK [Run molecule]
2025-11-25 14:40:20.397278 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-11-25 14:40:20.461453 | controller | INFO     Performing prerun with role_name_check=0...
2025-11-25 14:40:36.988318 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/0ebe5d/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:40:36.988767 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/0ebe5d/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:40:36.989232 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/0ebe5d/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:40:36.989676 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/0ebe5d/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:40:36.990166 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+b5e9db4e was found installed in /home/zuul/.cache/ansible-compat/0ebe5d/collections/ansible_collections, only the first one will be used, 1.0.0+b5e9db4e (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:40:36.990614 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/0ebe5d/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:40:36.991076 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/0ebe5d/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:40:36.991518 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/0ebe5d/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:40:36.991944 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/0ebe5d/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:40:36.992378 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/0ebe5d/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:40:36.992813 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/0ebe5d/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:40:36.993275 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/0ebe5d/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:40:36.993713 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/0ebe5d/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:40:36.994184 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/0ebe5d/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:40:36.994619 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/0ebe5d/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:40:37.003672 | controller | INFO     Running default > prepare
2025-11-25 14:40:37.620569 | controller |
2025-11-25 14:40:37.620622 | controller | PLAY [Prepare] *****************************************************************
2025-11-25 14:40:37.620719 | controller |
2025-11-25 14:40:37.620825 | controller | TASK [Gathering Facts] *********************************************************
2025-11-25 14:40:37.620917 | controller | Tuesday 25 November 2025  14:40:37 +0000 (0:00:00.011)       0:00:00.011 ******
2025-11-25 14:40:38.439071 | controller | ok: [instance]
2025-11-25 14:40:38.439165 | controller |
2025-11-25 14:40:38.439178 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-11-25 14:40:38.439188 | controller | Tuesday 25 November 2025  14:40:38 +0000 (0:00:00.818)       0:00:00.830 ******
2025-11-25 14:40:38.461131 | controller | skipping: [instance]
2025-11-25 14:40:38.501917 | controller |
2025-11-25 14:40:38.501946 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-11-25 14:40:38.501954 | controller | Tuesday 25 November 2025  14:40:38 +0000 (0:00:00.018)       0:00:00.849 ******
2025-11-25 14:40:38.502005 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-11-25 14:40:38.781170 | controller |
2025-11-25 14:40:38.781199 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-11-25 14:40:38.781207 | controller | Tuesday 25 November 2025  14:40:38 +0000 (0:00:00.043)       0:00:00.893 ******
2025-11-25 14:40:38.781217 | controller | ok: [instance]
2025-11-25 14:40:39.075857 | controller |
2025-11-25 14:40:39.075884 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-11-25 14:40:39.075892 | controller | Tuesday 25 November 2025  14:40:38 +0000 (0:00:00.279)       0:00:01.172 ******
2025-11-25 14:40:39.075902 | controller | ok: [instance]
2025-11-25 14:40:39.736745 | controller |
2025-11-25 14:40:39.736780 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-11-25 14:40:39.736788 | controller | Tuesday 25 November 2025  14:40:39 +0000 (0:00:00.294)       0:00:01.467 ******
2025-11-25 14:40:39.736798 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-11-25 14:40:39.794795 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-11-25 14:40:39.794824 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-11-25 14:40:39.794833 | controller |
2025-11-25 14:40:39.794841 | controller | TASK [Deploy repo-setup] *******************************************************
2025-11-25 14:40:39.794848 | controller | Tuesday 25 November 2025  14:40:39 +0000 (0:00:00.660)       0:00:02.128 ******
2025-11-25 14:40:39.794858 | controller |
2025-11-25 14:40:40.318764 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-11-25 14:40:40.318793 | controller | Tuesday 25 November 2025  14:40:39 +0000 (0:00:00.058)       0:00:02.186 ******
2025-11-25 14:40:40.318805 | controller | changed: [instance] => (item=tmp)
2025-11-25 14:40:41.069982 | controller | changed: [instance] => (item=artifacts/repositories)
2025-11-25 14:40:41.070011 | controller | changed: [instance] => (item=venv/repo_setup)
2025-11-25 14:40:41.070019 | controller |
2025-11-25 14:40:41.070025 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-11-25 14:40:41.070031 | controller | Tuesday 25 November 2025  14:40:40 +0000 (0:00:00.523)       0:00:02.709 ******
2025-11-25 14:40:41.070040 | controller | ok: [instance]
2025-11-25 14:40:42.118601 | controller |
2025-11-25 14:40:42.118630 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-11-25 14:40:42.118638 | controller | Tuesday 25 November 2025  14:40:41 +0000 (0:00:00.751)       0:00:03.461 ******
2025-11-25 14:40:42.118649 | controller | changed: [instance]
2025-11-25 14:40:49.742092 | controller |
2025-11-25 14:40:49.742132 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-11-25 14:40:49.742146 | controller | Tuesday 25 November 2025  14:40:42 +0000 (0:00:01.048)       0:00:04.509 ******
2025-11-25 14:40:49.742158 | controller | changed: [instance]
2025-11-25 14:40:49.742186 | controller |
2025-11-25 14:40:49.742203 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-11-25 14:40:49.742273 | controller | Tuesday 25 November 2025  14:40:49 +0000 (0:00:07.623)       0:00:12.133 ******
2025-11-25 14:40:50.432708 | controller | changed: [instance]
2025-11-25 14:40:50.453704 | controller |
2025-11-25 14:40:50.453722 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-11-25 14:40:50.453730 | controller | Tuesday 25 November 2025  14:40:50 +0000 (0:00:00.690)       0:00:12.823 ******
2025-11-25 14:40:50.453739 | controller | skipping: [instance]
2025-11-25 14:40:51.240314 | controller |
2025-11-25 14:40:51.240341 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-11-25 14:40:51.240349 | controller | Tuesday 25 November 2025  14:40:50 +0000 (0:00:00.021)       0:00:12.845 ******
2025-11-25 14:40:51.240358 | controller | changed: [instance]
2025-11-25 14:40:51.276812 | controller |
2025-11-25 14:40:51.276833 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-11-25 14:40:51.276840 | controller | Tuesday 25 November 2025  14:40:51 +0000 (0:00:00.786)       0:00:13.631 ******
2025-11-25 14:40:51.276849 | controller | skipping: [instance]
2025-11-25 14:40:51.312860 | controller |
2025-11-25 14:40:51.312878 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-11-25 14:40:51.312885 | controller | Tuesday 25 November 2025  14:40:51 +0000 (0:00:00.036)       0:00:13.668 ******
2025-11-25 14:40:51.312894 | controller | skipping: [instance]
2025-11-25 14:40:51.347863 | controller |
2025-11-25 14:40:51.347880 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-11-25 14:40:51.347888 | controller | Tuesday 25 November 2025  14:40:51 +0000 (0:00:00.035)       0:00:13.704 ******
2025-11-25 14:40:51.347897 | controller | skipping: [instance]
2025-11-25 14:40:51.897302 | controller |
2025-11-25 14:40:51.897331 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-11-25 14:40:51.897339 | controller | Tuesday 25 November 2025  14:40:51 +0000 (0:00:00.035)       0:00:13.739 ******
2025-11-25 14:40:51.897349 | controller | changed: [instance]
2025-11-25 14:40:52.333940 | controller |
2025-11-25 14:40:52.333987 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-11-25 14:40:52.333998 | controller | Tuesday 25 November 2025  14:40:51 +0000 (0:00:00.549)       0:00:14.288 ******
2025-11-25 14:40:52.334008 | controller | changed: [instance]
2025-11-25 14:40:52.362442 | controller |
2025-11-25 14:40:52.362462 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-11-25 14:40:52.362471 | controller | Tuesday 25 November 2025  14:40:52 +0000 (0:00:00.436)       0:00:14.725 ******
2025-11-25 14:40:52.362481 | controller | skipping: [instance]
2025-11-25 14:40:52.390397 | controller |
2025-11-25 14:40:52.390414 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-11-25 14:40:52.390420 | controller | Tuesday 25 November 2025  14:40:52 +0000 (0:00:00.028)       0:00:14.753 ******
2025-11-25 14:40:52.390432 | controller | skipping: [instance]
2025-11-25 14:40:52.418935 | controller |
2025-11-25 14:40:52.418954 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-11-25 14:40:52.418962 | controller | Tuesday 25 November 2025  14:40:52 +0000 (0:00:00.028)       0:00:14.782 ******
2025-11-25 14:40:52.418995 | controller | skipping: [instance]
2025-11-25 14:40:52.482080 | controller |
2025-11-25 14:40:52.482107 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-11-25 14:40:52.482116 | controller | Tuesday 25 November 2025  14:40:52 +0000 (0:00:00.028)       0:00:14.810 ******
2025-11-25 14:40:52.482127 | controller | ok: [instance]
2025-11-25 14:40:52.508690 | controller |
2025-11-25 14:40:52.508706 | controller | TASK [repo_setup : Create download directory] **********************************
2025-11-25 14:40:52.508713 | controller | Tuesday 25 November 2025  14:40:52 +0000 (0:00:00.063)       0:00:14.873 ******
2025-11-25 14:40:52.508721 | controller | skipping: [instance]
2025-11-25 14:40:52.508877 | controller |
2025-11-25 14:40:52.509046 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-11-25 14:40:52.509194 | controller | Tuesday 25 November 2025  14:40:52 +0000 (0:00:00.026)       0:00:14.900 ******
2025-11-25 14:40:52.534900 | controller | skipping: [instance]
2025-11-25 14:40:52.535102 | controller |
2025-11-25 14:40:52.535280 | controller | TASK [Download the RPM] ********************************************************
2025-11-25 14:40:52.535442 | controller | Tuesday 25 November 2025  14:40:52 +0000 (0:00:00.026)       0:00:14.926 ******
2025-11-25 14:40:52.561185 | controller | skipping: [instance]
2025-11-25 14:40:52.561357 | controller |
2025-11-25 14:40:52.561517 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-11-25 14:40:52.561655 | controller | Tuesday 25 November 2025  14:40:52 +0000 (0:00:00.026)       0:00:14.953 ******
2025-11-25 14:40:52.587513 | controller | skipping: [instance]
2025-11-25 14:40:52.587700 | controller |
2025-11-25 14:40:52.587895 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-11-25 14:40:52.588100 | controller | Tuesday 25 November 2025  14:40:52 +0000 (0:00:00.026)       0:00:14.979 ******
2025-11-25 14:40:52.614263 | controller | skipping: [instance]
2025-11-25 14:40:52.614449 | controller |
2025-11-25 14:40:52.614616 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-11-25 14:40:52.614756 | controller | Tuesday 25 November 2025  14:40:52 +0000 (0:00:00.026)       0:00:15.006 ******
2025-11-25 14:40:52.642597 | controller | skipping: [instance]
2025-11-25 14:40:52.642796 | controller |
2025-11-25 14:40:52.643018 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-11-25 14:40:52.643201 | controller | Tuesday 25 November 2025  14:40:52 +0000 (0:00:00.028)       0:00:15.034 ******
2025-11-25 14:40:52.668198 | controller | skipping: [instance]
2025-11-25 14:40:52.668450 | controller |
2025-11-25 14:40:52.668688 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-11-25 14:40:52.668842 | controller | Tuesday 25 November 2025  14:40:52 +0000 (0:00:00.025)       0:00:15.060 ******
2025-11-25 14:40:52.855146 | controller | ok: [instance]
2025-11-25 14:40:53.067774 | controller |
2025-11-25 14:40:53.067813 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-11-25 14:40:53.067820 | controller | Tuesday 25 November 2025  14:40:52 +0000 (0:00:00.186)       0:00:15.246 ******
2025-11-25 14:40:53.067831 | controller | changed: [instance]
2025-11-25 14:40:53.295961 | controller |
2025-11-25 14:40:53.296002 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-11-25 14:40:53.296010 | controller | Tuesday 25 November 2025  14:40:53 +0000 (0:00:00.212)       0:00:15.459 ******
2025-11-25 14:40:53.296020 | controller | changed: [instance]
2025-11-25 14:40:53.316157 | controller |
2025-11-25 14:40:53.316184 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-11-25 14:40:53.316193 | controller | Tuesday 25 November 2025  14:40:53 +0000 (0:00:00.228)       0:00:15.687 ******
2025-11-25 14:40:53.316204 | controller | skipping: [instance]
2025-11-25 14:40:53.337649 | controller |
2025-11-25 14:40:53.337667 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-11-25 14:40:53.337674 | controller | Tuesday 25 November 2025  14:40:53 +0000 (0:00:00.020)       0:00:15.707 ******
2025-11-25 14:40:53.337683 | controller | skipping: [instance]
2025-11-25 14:40:53.358027 | controller |
2025-11-25 14:40:53.358049 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-11-25 14:40:53.358058 | controller | Tuesday 25 November 2025  14:40:53 +0000 (0:00:00.021)       0:00:15.729 ******
2025-11-25 14:40:53.358068 | controller | skipping: [instance]
2025-11-25 14:40:53.358534 | controller |
2025-11-25 14:40:53.379929 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-11-25 14:40:53.379951 | controller | Tuesday 25 November 2025  14:40:53 +0000 (0:00:00.020)       0:00:15.749 ******
2025-11-25 14:40:53.379963 | controller | skipping: [instance]
2025-11-25 14:40:53.400247 | controller |
2025-11-25 14:40:53.400269 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-11-25 14:40:53.400283 | controller | Tuesday 25 November 2025  14:40:53 +0000 (0:00:00.021)       0:00:15.771 ******
2025-11-25 14:40:53.400294 | controller | skipping: [instance]
2025-11-25 14:40:53.400473 | controller |
2025-11-25 14:40:53.400667 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-11-25 14:40:53.400879 | controller | Tuesday 25 November 2025  14:40:53 +0000 (0:00:00.020)       0:00:15.792 ******
2025-11-25 14:40:53.422257 | controller | skipping: [instance]
2025-11-25 14:40:53.422510 | controller |
2025-11-25 14:40:53.422753 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-11-25 14:40:53.423036 | controller | Tuesday 25 November 2025  14:40:53 +0000 (0:00:00.021)       0:00:15.813 ******
2025-11-25 14:40:53.438131 | controller | skipping: [instance]
2025-11-25 14:40:53.438384 | controller |
2025-11-25 14:40:53.438627 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-11-25 14:40:53.438887 | controller | Tuesday 25 November 2025  14:40:53 +0000 (0:00:00.016)       0:00:15.830 ******
2025-11-25 14:40:53.466052 | controller | skipping: [instance]
2025-11-25 14:40:53.466223 | controller |
2025-11-25 14:40:53.466375 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-11-25 14:40:53.466521 | controller | Tuesday 25 November 2025  14:40:53 +0000 (0:00:00.027)       0:00:15.857 ******
2025-11-25 14:40:53.482303 | controller | skipping: [instance]
2025-11-25 14:40:53.482455 | controller |
2025-11-25 14:40:53.482603 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-11-25 14:40:53.482749 | controller | Tuesday 25 November 2025  14:40:53 +0000 (0:00:00.016)       0:00:15.874 ******
2025-11-25 14:40:53.501700 | controller | skipping: [instance]
2025-11-25 14:40:53.501857 | controller |
2025-11-25 14:40:53.502025 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-11-25 14:40:53.502171 | controller | Tuesday 25 November 2025  14:40:53 +0000 (0:00:00.019)       0:00:15.893 ******
2025-11-25 14:40:53.530116 | controller | skipping: [instance]
2025-11-25 14:40:53.530295 | controller |
2025-11-25 14:40:53.530437 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-11-25 14:40:53.530574 | controller | Tuesday 25 November 2025  14:40:53 +0000 (0:00:00.028)       0:00:15.922 ******
2025-11-25 14:40:53.558522 | controller | skipping: [instance]
2025-11-25 14:40:53.558676 | controller |
2025-11-25 14:40:53.558835 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-11-25 14:40:53.558992 | controller | Tuesday 25 November 2025  14:40:53 +0000 (0:00:00.028)       0:00:15.950 ******
2025-11-25 14:40:53.587176 | controller | skipping: [instance]
2025-11-25 14:40:53.587361 | controller |
2025-11-25 14:40:53.587510 | controller | TASK [test_deps : Install additional packages] *********************************
2025-11-25 14:40:53.587651 | controller | Tuesday 25 November 2025  14:40:53 +0000 (0:00:00.028)       0:00:15.979 ******
2025-11-25 14:40:53.619043 | controller | skipping: [instance]
2025-11-25 14:40:53.619094 | controller |
2025-11-25 14:40:53.619109 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-11-25 14:40:53.619119 | controller | Tuesday 25 November 2025  14:40:53 +0000 (0:00:00.031)       0:00:16.010 ******
2025-11-25 14:41:31.074893 | controller | ok: [instance]
2025-11-25 14:41:32.283796 | controller |
2025-11-25 14:41:32.283836 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-11-25 14:41:32.283845 | controller | Tuesday 25 November 2025  14:41:31 +0000 (0:00:37.455)       0:00:53.466 ******
2025-11-25 14:41:32.283855 | controller | ok: [instance]
2025-11-25 14:41:32.322780 | controller |
2025-11-25 14:41:32.322798 | controller | TASK [test_deps : Install extra packages] **************************************
2025-11-25 14:41:32.322805 | controller | Tuesday 25 November 2025  14:41:32 +0000 (0:00:01.208)       0:00:54.675 ******
2025-11-25 14:41:32.322814 | controller | skipping: [instance]
2025-11-25 14:41:32.543054 | controller |
2025-11-25 14:41:32.543085 | controller | TASK [Ensure cifmw_ceph_client_config_home exists on all clients] **************
2025-11-25 14:41:32.543093 | controller | Tuesday 25 November 2025  14:41:32 +0000 (0:00:00.039)       0:00:54.714 ******
2025-11-25 14:41:32.543104 | controller | changed: [instance]
2025-11-25 14:41:32.545411 | controller |
2025-11-25 14:41:32.545441 | controller | PLAY RECAP *********************************************************************
2025-11-25 14:41:32.545455 | controller | instance                   : ok=20   changed=10   unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-11-25 14:41:32.602200 | controller |
2025-11-25 14:41:32.602238 | controller | Tuesday 25 November 2025  14:41:32 +0000 (0:00:00.219)       0:00:54.934 ******
2025-11-25 14:41:32.602248 | controller | ===============================================================================
2025-11-25 14:41:32.602254 | controller | test_deps : Install selinux python libs -------------------------------- 37.46s
2025-11-25 14:41:32.602259 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.62s
2025-11-25 14:41:32.602269 | controller | test_deps : Install python yaml libs ------------------------------------ 1.21s
2025-11-25 14:41:32.602274 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.05s
2025-11-25 14:41:32.602279 | controller | Gathering Facts --------------------------------------------------------- 0.82s
2025-11-25 14:41:32.602285 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.79s
2025-11-25 14:41:32.602290 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.75s
2025-11-25 14:41:32.602295 | controller | repo_setup : Install repo-setup package --------------------------------- 0.69s
2025-11-25 14:41:32.602301 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.66s
2025-11-25 14:41:32.602306 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.55s
2025-11-25 14:41:32.602311 | controller | repo_setup : Ensure directories are present ----------------------------- 0.52s
2025-11-25 14:41:32.602316 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.44s
2025-11-25 14:41:32.602322 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.29s
2025-11-25 14:41:32.602327 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s
2025-11-25 14:41:32.602332 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.23s
2025-11-25 14:41:32.602338 | controller | Ensure cifmw_ceph_client_config_home exists on all clients -------------- 0.22s
2025-11-25 14:41:32.602343 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.21s
2025-11-25 14:41:32.602349 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.19s
2025-11-25 14:41:32.602354 | controller | repo_setup : Export hashes facts for further use ------------------------ 0.06s
2025-11-25 14:41:32.602359 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-11-25 14:41:32.602369 | controller | INFO     Running default > converge
2025-11-25 14:41:33.005219 | controller |
2025-11-25 14:41:33.005390 | controller | PLAY [Converge] ****************************************************************
2025-11-25 14:41:33.005514 | controller |
2025-11-25 14:41:33.005640 | controller | TASK [Gathering Facts] *********************************************************
2025-11-25 14:41:33.005765 | controller | Tuesday 25 November 2025  14:41:33 +0000 (0:00:00.013)       0:00:00.013 ******
2025-11-25 14:41:33.760065 | controller | ok: [instance]
2025-11-25 14:41:33.779522 | controller |
2025-11-25 14:41:33.779551 | controller | TASK [cifmw_ceph_client : Check if the input variables exist] ******************
2025-11-25 14:41:33.779559 | controller | Tuesday 25 November 2025  14:41:33 +0000 (0:00:00.754)       0:00:00.768 ******
2025-11-25 14:41:33.779569 | controller | skipping: [instance]
2025-11-25 14:41:33.798057 | controller |
2025-11-25 14:41:33.798090 | controller | TASK [cifmw_ceph_client : Load variables produced by the cephadm provisioning process] ***
2025-11-25 14:41:33.798100 | controller | Tuesday 25 November 2025  14:41:33 +0000 (0:00:00.019)       0:00:00.787 ******
2025-11-25 14:41:33.798112 | controller | skipping: [instance]
2025-11-25 14:41:33.798142 | controller |
2025-11-25 14:41:33.798319 | controller | TASK [cifmw_ceph_client : Render keys provided for the Ceph cluster] ***********
2025-11-25 14:41:33.823916 | controller | Tuesday 25 November 2025  14:41:33 +0000 (0:00:00.018)       0:00:00.806 ******
2025-11-25 14:41:33.823946 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_ceph_client/tasks/create_keys.yml for instance
2025-11-25 14:41:34.698093 | controller |
2025-11-25 14:41:34.698124 | controller | TASK [cifmw_ceph_client : Create cephx key(s)] *********************************
2025-11-25 14:41:34.698138 | controller | Tuesday 25 November 2025  14:41:33 +0000 (0:00:00.025)       0:00:00.832 ******
2025-11-25 14:41:34.698148 | controller | changed: [instance] => (item={'caps': {'mgr': 'allow *', 'mon': 'profile rbd', 'osd': 'profile rbd pool=vms, profile rbd pool=volumes, profile rbd pool=metrics, profile rbd pool=backups, profile rbd pool=pool2, profile rbd pool=pool3, profile rbd pool=altrbd'}, 'key': 'AQC+vYNXgDAgAhAAc8UoYt+OTz5uhV7ItLdwUw==', 'mode': '0600', 'name': 'client.glance'})
2025-11-25 14:41:34.723666 | controller | changed: [instance] => (item={'caps': {'mgr': 'allow *', 'mon': 'profile rbd', 'osd': 'profile rbd pool=images'}, 'key': 'AQBRgQ9eAAAAABAAv84zEilJYZPNuJ0Iwn9Ndg==', 'mode': '0600', 'name': 'client.manila'})
2025-11-25 14:41:34.723698 | controller |
2025-11-25 14:41:34.723706 | controller | TASK [cifmw_ceph_client : Render ceph config for the Ceph Clients] *************
2025-11-25 14:41:34.723712 | controller | Tuesday 25 November 2025  14:41:34 +0000 (0:00:00.873)       0:00:01.705 ******
2025-11-25 14:41:34.723723 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_ceph_client/tasks/create_ceph_conf.yml for instance
2025-11-25 14:41:35.118985 | controller |
2025-11-25 14:41:35.119015 | controller | TASK [cifmw_ceph_client : Create ceph.conf] ************************************
2025-11-25 14:41:35.119029 | controller | Tuesday 25 November 2025  14:41:34 +0000 (0:00:00.025)       0:00:01.731 ******
2025-11-25 14:41:35.119038 | controller | changed: [instance]
2025-11-25 14:41:35.155332 | controller |
2025-11-25 14:41:35.155350 | controller | TASK [cifmw_ceph_client : Import glance Ceph RGW discovery playbook] ***********
2025-11-25 14:41:35.155357 | controller | Tuesday 25 November 2025  14:41:35 +0000 (0:00:00.395)       0:00:02.127 ******
2025-11-25 14:41:35.155366 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_ceph_client/tasks/glance-rgw-discovery.yaml for instance
2025-11-25 14:41:35.188807 | controller |
2025-11-25 14:41:35.188838 | controller | TASK [cifmw_ceph_client : List Ceph daemon instances on this host] *************
2025-11-25 14:41:35.188846 | controller | Tuesday 25 November 2025  14:41:35 +0000 (0:00:00.036)       0:00:02.163 ******
2025-11-25 14:41:35.188855 | controller | fatal: [instance -> {{ groups['cephs'][0] }}]: FAILED! =>
2025-11-25 14:41:35.191022 | controller |   censored: 'the output has been hidden due to the fact that ''no_log: true'' was specified for this result'
2025-11-25 14:41:35.191042 | controller |
2025-11-25 14:41:35.192078 | controller | PLAY RECAP *********************************************************************
2025-11-25 14:41:35.192130 | controller | instance                   : ok=6    changed=2    unreachable=0    failed=1    skipped=2    rescued=0    ignored=0
2025-11-25 14:41:35.192139 | controller |
2025-11-25 14:41:35.192145 | controller | Tuesday 25 November 2025  14:41:35 +0000 (0:00:00.036)       0:00:02.199 ******
2025-11-25 14:41:35.192151 | controller | ===============================================================================
2025-11-25 14:41:35.192156 | controller | cifmw_ceph_client : Create cephx key(s) --------------------------------- 0.87s
2025-11-25 14:41:35.192161 | controller | Gathering Facts --------------------------------------------------------- 0.75s
2025-11-25 14:41:35.192166 | controller | cifmw_ceph_client : Create ceph.conf ------------------------------------ 0.40s
2025-11-25 14:41:35.192171 | controller | cifmw_ceph_client : Import glance Ceph RGW discovery playbook ----------- 0.04s
2025-11-25 14:41:35.192177 | controller | cifmw_ceph_client : List Ceph daemon instances on this host ------------- 0.04s
2025-11-25 14:41:35.192197 | controller | cifmw_ceph_client : Render ceph config for the Ceph Clients ------------- 0.03s
2025-11-25 14:41:35.192468 | controller | cifmw_ceph_client : Render keys provided for the Ceph cluster ----------- 0.03s
2025-11-25 14:41:35.245841 | controller | cifmw_ceph_client : Check if the input variables exist ------------------ 0.02s
2025-11-25 14:41:35.245860 | controller | cifmw_ceph_client : Load variables produced by the cephadm provisioning process --- 0.02s
2025-11-25 14:41:35.245875 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/cifmw_ceph_client/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_ceph_client/molecule/default/converge.yml']
2025-11-25 14:41:35.246298 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-11-25 14:41:35.255038 | controller | INFO     Running default > cleanup
2025-11-25 14:41:35.255502 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-11-25 14:41:35.263756 | controller | INFO     Running default > destroy
2025-11-25 14:41:35.264142 | controller | WARNING  Skipping, instances are delegated.
2025-11-25 14:41:35.264499 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-11-25 14:41:35.266045 | controller | INFO     Writing /tmp/report.html report.
2025-11-25 14:41:35.609398 | controller | ERROR
2025-11-25 14:41:35.609500 | controller | {
2025-11-25 14:41:35.609530 | controller |   "delta": "0:01:15.519082",
2025-11-25 14:41:35.609550 | controller |   "end": "2025-11-25 14:41:35.309521",
2025-11-25 14:41:35.609565 | controller |   "msg": "non-zero return code",
2025-11-25 14:41:35.609580 | controller |   "rc": 1,
2025-11-25 14:41:35.609595 | controller |   "start": "2025-11-25 14:40:19.790439"
2025-11-25 14:41:35.609610 | controller | }
failure
2025-11-25 14:41:35.612324 | 
2025-11-25 14:41:35.612378 | PLAY RECAP
2025-11-25 14:41:35.612442 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-11-25 14:41:35.612470 | 
2025-11-25 14:41:35.665472 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-25 14:41:35.666233 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-25 14:41:36.172175 | 
2025-11-25 14:41:36.172277 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-11-25 14:41:36.192326 | 
2025-11-25 14:41:36.192426 | TASK [Filter out host if needed]
2025-11-25 14:41:36.200584 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-25 14:41:36.204631 | 
2025-11-25 14:41:36.204693 | TASK [Ensure file is present]
2025-11-25 14:41:36.496358 | controller | ok
2025-11-25 14:41:36.503066 | 
2025-11-25 14:41:36.503135 | TASK [Manage molecule report file]
2025-11-25 14:41:37.029315 | controller | changed
2025-11-25 14:41:37.034135 | 
2025-11-25 14:41:37.034194 | TASK [Check if we get ci-framework-data basedir]
2025-11-25 14:41:37.214933 | controller | ok
2025-11-25 14:41:37.219975 | 
2025-11-25 14:41:37.220038 | TASK [Create ci-framework-data log directory for zuul]
2025-11-25 14:41:37.531499 | controller | changed
2025-11-25 14:41:37.536635 | 
2025-11-25 14:41:37.536702 | TASK [Copy ci-framework interesting files]
2025-11-25 14:41:37.724577 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-11-25 14:41:38.062835 | controller | changed
2025-11-25 14:41:38.068066 | 
2025-11-25 14:41:38.068139 | TASK [Get SELinux listing]
2025-11-25 14:41:38.592816 | controller | changed
2025-11-25 14:41:38.598340 | 
2025-11-25 14:41:38.598423 | TASK [Generate log index]
2025-11-25 14:41:39.206946 | controller | changed
2025-11-25 14:41:39.211637 | 
2025-11-25 14:41:39.211703 | TASK [Get some env related data]
2025-11-25 14:41:39.735576 | controller | /home/zuul/.local/bin/ansible
2025-11-25 14:41:40.734938 | controller | changed
2025-11-25 14:41:40.739698 | 
2025-11-25 14:41:40.739760 | TASK [Generate list of logs to collect in home directory]
2025-11-25 14:41:41.021294 | controller | ok: All paths examined
2025-11-25 14:41:41.026248 | 
2025-11-25 14:41:41.026318 | LOOP [Copy logs from home directory]
2025-11-25 14:41:41.328459 | controller | changed:
2025-11-25 14:41:41.328569 | controller | {
2025-11-25 14:41:41.328596 | controller |   "atime": 1764081601.5737407,
2025-11-25 14:41:41.328615 | controller |   "ctime": 1764081617.6208498,
2025-11-25 14:41:41.328632 | controller |   "dev": 64513,
2025-11-25 14:41:41.328647 | controller |   "gid": 1000,
2025-11-25 14:41:41.328662 | controller |   "gr_name": "zuul",
2025-11-25 14:41:41.328677 | controller |   "inode": 4898638,
2025-11-25 14:41:41.328692 | controller |   "isblk": false,
2025-11-25 14:41:41.328705 | controller |   "ischr": false,
2025-11-25 14:41:41.328719 | controller |   "isdir": false,
2025-11-25 14:41:41.328732 | controller |   "isfifo": false,
2025-11-25 14:41:41.328746 | controller |   "isgid": false,
2025-11-25 14:41:41.328759 | controller |   "islnk": false,
2025-11-25 14:41:41.328772 | controller |   "isreg": true,
2025-11-25 14:41:41.328785 | controller |   "issock": false,
2025-11-25 14:41:41.328798 | controller |   "isuid": false,
2025-11-25 14:41:41.328811 | controller |   "mode": "0644",
2025-11-25 14:41:41.328824 | controller |   "mtime": 1764081617.6208498,
2025-11-25 14:41:41.328837 | controller |   "nlink": 1,
2025-11-25 14:41:41.328863 | controller |   "path": "/home/zuul/ansible.log",
2025-11-25 14:41:41.328881 | controller |   "pw_name": "zuul",
2025-11-25 14:41:41.328896 | controller |   "rgrp": true,
2025-11-25 14:41:41.328910 | controller |   "roth": true,
2025-11-25 14:41:41.328924 | controller |   "rusr": true,
2025-11-25 14:41:41.328939 | controller |   "size": 6749,
2025-11-25 14:41:41.328952 | controller |   "uid": 1000,
2025-11-25 14:41:41.328966 | controller |   "wgrp": false,
2025-11-25 14:41:41.328980 | controller |   "woth": false,
2025-11-25 14:41:41.328996 | controller |   "wusr": true,
2025-11-25 14:41:41.329010 | controller |   "xgrp": false,
2025-11-25 14:41:41.329024 | controller |   "xoth": false,
2025-11-25 14:41:41.329037 | controller |   "xusr": false
2025-11-25 14:41:41.329051 | controller | }
2025-11-25 14:41:41.341418 | 
2025-11-25 14:41:41.341478 | TASK [Copy crio stats log file]
2025-11-25 14:41:41.355603 | controller | skipping: Conditional result was False
2025-11-25 14:41:41.361169 | 
2025-11-25 14:41:41.361231 | TASK [Get SELinux related data]
2025-11-25 14:41:41.560285 | controller | <no matches>
2025-11-25 14:41:41.884873 | controller | ERROR
2025-11-25 14:41:41.885048 | controller | {
2025-11-25 14:41:41.885084 | controller |   "delta": "0:00:00.007975",
2025-11-25 14:41:41.885109 | controller |   "end": "2025-11-25 14:41:41.561217",
2025-11-25 14:41:41.885130 | controller |   "msg": "non-zero return code",
2025-11-25 14:41:41.885149 | controller |   "rc": 1,
2025-11-25 14:41:41.885175 | controller |   "start": "2025-11-25 14:41:41.553242"
2025-11-25 14:41:41.885194 | controller | }
2025-11-25 14:41:41.885219 | controller | ERROR: Ignoring Errors
2025-11-25 14:41:41.890093 | 
2025-11-25 14:41:41.890161 | TASK [Create system configuration directory]
2025-11-25 14:41:42.070749 | controller | changed
2025-11-25 14:41:42.075612 | 
2025-11-25 14:41:42.075678 | TASK [Get some of the system configurations]
2025-11-25 14:41:42.597950 | controller | changed
2025-11-25 14:41:42.602980 | 
2025-11-25 14:41:42.603041 | TASK [Copy generated documentation if available]
2025-11-25 14:41:42.616204 | controller | skipping: Conditional result was False
2025-11-25 14:41:42.621642 | 
2025-11-25 14:41:42.621710 | TASK [Copy generated AsciiDoc documentation if available]
2025-11-25 14:41:42.634848 | controller | skipping: Conditional result was False
2025-11-25 14:41:42.640779 | 
2025-11-25 14:41:42.640866 | TASK [Compress logs bigger than 2MB]
2025-11-25 14:41:43.165831 | controller | changed
2025-11-25 14:41:43.171420 | 
2025-11-25 14:41:43.171480 | TASK [Copy files from workspace on node]
2025-11-25 14:41:43.189555 | controller | ok
2025-11-25 14:41:43.210564 | 
2025-11-25 14:41:43.210636 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-25 14:41:43.224011 | controller | skipping: Conditional result was False
2025-11-25 14:41:43.229217 | 
2025-11-25 14:41:43.229282 | TASK [fetch-output : Set log path for single node]
2025-11-25 14:41:43.257420 | controller | ok
2025-11-25 14:41:43.263647 | 
2025-11-25 14:41:43.263705 | LOOP [fetch-output : Ensure local output dirs]
2025-11-25 14:41:43.449889 | controller -> localhost | ok: "/var/lib/zuul/builds/79ec692a76df44898c82236a52e0e2a5/work/logs"
2025-11-25 14:41:43.450121 | controller -> localhost | changed: All items complete
2025-11-25 14:41:43.450150 | 
2025-11-25 14:41:43.620762 | controller -> localhost | changed: "/var/lib/zuul/builds/79ec692a76df44898c82236a52e0e2a5/work/artifacts"
2025-11-25 14:41:43.791965 | controller -> localhost | changed: "/var/lib/zuul/builds/79ec692a76df44898c82236a52e0e2a5/work/docs"
2025-11-25 14:41:43.800427 | 
2025-11-25 14:41:43.800522 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-25 14:41:44.308279 | controller | changed:
2025-11-25 14:41:44.308478 | controller | .d..t...... ./
2025-11-25 14:41:44.308511 | controller | >f+++++++++ README.html
2025-11-25 14:41:44.308533 | controller | >f+++++++++ ansible-execution.log
2025-11-25 14:41:44.308554 | controller | >f+++++++++ ansible.log
2025-11-25 14:41:44.308573 | controller | >f+++++++++ dmesg.log
2025-11-25 14:41:44.308591 | controller | >f+++++++++ installed-pkgs.log
2025-11-25 14:41:44.308611 | controller | >f+++++++++ python.log
2025-11-25 14:41:44.308631 | controller | >f+++++++++ registries.conf
2025-11-25 14:41:44.308649 | controller | >f+++++++++ report.html
2025-11-25 14:41:44.308668 | controller | >f+++++++++ selinux-denials.log
2025-11-25 14:41:44.308684 | controller | >f+++++++++ selinux-listing.log
2025-11-25 14:41:44.308700 | controller | cd+++++++++ ci-framework-data/
2025-11-25 14:41:44.308716 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-11-25 14:41:44.308732 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-11-25 14:41:44.308747 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-11-25 14:41:44.308762 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-11-25 14:41:44.308777 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-11-25 14:41:44.308792 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-11-25 14:41:44.308806 | controller | cd+++++++++ ci-framework-data/logs/
2025-11-25 14:41:44.308821 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-11-25 14:41:44.308835 | controller | cd+++++++++ registries.conf.d/
2025-11-25 14:41:44.308861 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-11-25 14:41:44.308882 | controller | cd+++++++++ system-config/
2025-11-25 14:41:44.308897 | controller | cd+++++++++ system-config/libvirt/
2025-11-25 14:41:44.308912 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-11-25 14:41:44.308928 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-11-25 14:41:44.658134 | controller | changed: .d..t...... ./
2025-11-25 14:41:45.015458 | controller | changed: .d..t...... ./
2025-11-25 14:41:45.028437 | 
2025-11-25 14:41:45.028538 | TASK [Return artifact to Zuul]
2025-11-25 14:41:45.056701 | controller | ok
2025-11-25 14:41:45.074804 | 
2025-11-25 14:41:45.074869 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-11-25 14:41:45.074962 | 
2025-11-25 14:41:45.074989 | PLAY RECAP
2025-11-25 14:41:45.075024 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-11-25 14:41:45.075045 | 
2025-11-25 14:41:45.143621 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-25 14:41:45.144299 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-25 14:41:45.635441 | 
2025-11-25 14:41:45.635535 | PLAY [all]
2025-11-25 14:41:45.653481 | 
2025-11-25 14:41:45.653551 | TASK [include_role : fetch-output]
2025-11-25 14:41:45.681534 | controller | ok
2025-11-25 14:41:45.697771 | 
2025-11-25 14:41:45.697858 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-25 14:41:45.741811 | controller | skipping: Conditional result was False
2025-11-25 14:41:45.747064 | 
2025-11-25 14:41:45.747132 | TASK [fetch-output : Set log path for single node]
2025-11-25 14:41:45.775065 | controller | ok
2025-11-25 14:41:45.779778 | 
2025-11-25 14:41:45.779853 | LOOP [fetch-output : Ensure local output dirs]
2025-11-25 14:41:46.088663 | controller -> localhost | ok: "/var/lib/zuul/builds/79ec692a76df44898c82236a52e0e2a5/work/logs"
2025-11-25 14:41:46.267959 | controller -> localhost | ok: "/var/lib/zuul/builds/79ec692a76df44898c82236a52e0e2a5/work/artifacts"
2025-11-25 14:41:46.442849 | controller -> localhost | ok: "/var/lib/zuul/builds/79ec692a76df44898c82236a52e0e2a5/work/docs"
2025-11-25 14:41:46.458104 | 
2025-11-25 14:41:46.458198 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-25 14:41:46.958378 | controller | ok
2025-11-25 14:41:46.958563 | controller | ok: All items complete
2025-11-25 14:41:46.958600 | 
2025-11-25 14:41:47.319895 | controller | ok
2025-11-25 14:41:47.685631 | controller | ok
2025-11-25 14:41:47.701936 | 
2025-11-25 14:41:47.702034 | TASK [include_role : fetch-output-openshift]
2025-11-25 14:41:47.714999 | controller | skipping: Conditional result was False
2025-11-25 14:41:47.720359 | 
2025-11-25 14:41:47.720445 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-25 14:41:48.048712 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006056
2025-11-25 14:41:48.234465 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006022
2025-11-25 14:41:48.265764 | 
2025-11-25 14:41:48.265889 | PLAY [all]
2025-11-25 14:41:48.279331 | 
2025-11-25 14:41:48.279417 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-11-25 14:41:48.663684 | controller | changed
2025-11-25 14:41:48.684513 | 
2025-11-25 14:41:48.684563 | PLAY RECAP
2025-11-25 14:41:48.684608 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-25 14:41:48.684630 | 
2025-11-25 14:41:48.749308 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-25 14:41:48.750055 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-11-25 14:41:49.277574 | 
2025-11-25 14:41:49.277671 | PLAY [localhost]
2025-11-25 14:41:49.294422 | 
2025-11-25 14:41:49.294502 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-25 14:41:49.584857 | localhost | changed
2025-11-25 14:41:49.589313 | 
2025-11-25 14:41:49.589442 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-25 14:41:49.616610 | localhost | ok
2025-11-25 14:41:49.624171 | 
2025-11-25 14:41:49.624242 | TASK [add-fileserver : Create SSH private key tempfile]
2025-11-25 14:41:49.907311 | localhost | changed
2025-11-25 14:41:49.913850 | 
2025-11-25 14:41:49.913922 | TASK [add-fileserver : Create SSH private key from secret]
2025-11-25 14:41:50.436473 | localhost | changed
2025-11-25 14:41:50.441133 | 
2025-11-25 14:41:50.441200 | TASK [add-fileserver : Add fileserver ssh key]
2025-11-25 14:41:50.761868 | localhost | Identity added: /var/lib/zuul/builds/79ec692a76df44898c82236a52e0e2a5/work/tmp/ansible.3hn19jpu (/var/lib/zuul/builds/79ec692a76df44898c82236a52e0e2a5/work/tmp/ansible.3hn19jpu)
2025-11-25 14:41:50.762037 | localhost | ok: Runtime: 0:00:00.006254
2025-11-25 14:41:50.766214 | 
2025-11-25 14:41:50.766289 | TASK [add-fileserver : Remove SSH private key from disk]
2025-11-25 14:41:50.975824 | localhost | ok: Runtime: 0:00:00.003890
2025-11-25 14:41:50.980292 | 
2025-11-25 14:41:50.980359 | TASK [add-fileserver : Add fileserver to inventory]
2025-11-25 14:41:51.027896 | localhost | changed
2025-11-25 14:41:51.032740 | 
2025-11-25 14:41:51.032808 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-11-25 14:41:51.333480 | localhost | changed
2025-11-25 14:41:51.352690 | 
2025-11-25 14:41:51.352747 | PLAY [localhost]
2025-11-25 14:41:51.364309 | 
2025-11-25 14:41:51.364374 | TASK [Generate bulk log download script]
2025-11-25 14:41:51.382676 | localhost | ok
2025-11-25 14:41:51.393744 | 
2025-11-25 14:41:51.393814 | TASK [local-log-download : Check API endpoint is defined]
2025-11-25 14:41:51.420985 | localhost | ok: All assertions passed
2025-11-25 14:41:51.425292 | 
2025-11-25 14:41:51.425360 | TASK [local-log-download : Create download script]
2025-11-25 14:41:51.762641 | localhost -> localhost | changed
2025-11-25 14:41:51.770992 | 
2025-11-25 14:41:51.771078 | TASK [Register quick-download link]
2025-11-25 14:41:51.788144 | localhost | ok
2025-11-25 14:41:51.822487 | 
2025-11-25 14:41:51.822557 | PLAY [logserver.rdoproject.org]
2025-11-25 14:41:51.831696 | 
2025-11-25 14:41:51.831757 | TASK [Set zuul-log-path fact]
2025-11-25 14:41:51.847134 | logserver.rdoproject.org | ok
2025-11-25 14:41:51.856546 | 
2025-11-25 14:41:51.856650 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-25 14:41:51.882687 | logserver.rdoproject.org | ok
2025-11-25 14:41:51.887821 | 
2025-11-25 14:41:51.887898 | TASK [upload-logs : Create log directories]
2025-11-25 14:41:53.473172 | logserver.rdoproject.org | changed
2025-11-25 14:41:53.475930 | 
2025-11-25 14:41:53.475991 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-11-25 14:41:53.691702 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004443
2025-11-25 14:41:53.696072 | 
2025-11-25 14:41:53.696148 | TASK [upload-logs : Upload logs to log server]
2025-11-25 14:41:55.119935 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-11-25 14:41:55.122729 | 
2025-11-25 14:41:55.122796 | LOOP [upload-logs : Compress console log and json output]
2025-11-25 14:41:55.159602 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 14:41:55.167861 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 14:41:55.180278 | 
2025-11-25 14:41:55.180361 | LOOP [upload-logs : Upload compressed console log and json output]
2025-11-25 14:41:55.211989 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 14:41:55.212171 | 
2025-11-25 14:41:55.215250 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 14:41:55.227082 | 
2025-11-25 14:41:55.227169 | LOOP [upload-logs : Upload console log and json output]