2025-12-08 14:27:53.486603 | Job console starting...
2025-12-08 14:27:53.698534 | Updating repositories
2025-12-08 14:27:54.449722 | Preparing job workspace
2025-12-08 14:28:01.412457 | Running Ansible setup...
2025-12-08 14:28:04.150725 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-08 14:28:04.622739 | 
2025-12-08 14:28:04.622834 | PLAY [localhost]
2025-12-08 14:28:04.630575 | 
2025-12-08 14:28:04.630659 | TASK [Gathering Facts]
2025-12-08 14:28:05.384669 | localhost | ok
2025-12-08 14:28:05.396736 | 
2025-12-08 14:28:05.396838 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-08 14:28:05.683971 | localhost -> localhost | changed
2025-12-08 14:28:05.688560 | 
2025-12-08 14:28:05.688636 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-08 14:28:06.311082 | localhost -> localhost | changed
2025-12-08 14:28:06.318565 | 
2025-12-08 14:28:06.318669 | TASK [Setup log path fact]
2025-12-08 14:28:06.335516 | localhost | ok
2025-12-08 14:28:06.345342 | 
2025-12-08 14:28:06.345410 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-08 14:28:06.362866 | localhost | ok
2025-12-08 14:28:06.369977 | 
2025-12-08 14:28:06.370035 | TASK [emit-job-header : Print job information]
2025-12-08 14:28:06.396555 | # Job Information
2025-12-08 14:28:06.396673 | Ansible Version: 2.15.12
2025-12-08 14:28:06.396701 | Job: cifmw-molecule-cifmw_cephadm
2025-12-08 14:28:06.396721 | Pipeline: github-check
2025-12-08 14:28:06.396739 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-12-08 14:28:06.396756 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3169
2025-12-08 14:28:06.396774 | Log URL (when completed): https://logserver.rdoproject.org/2ef/rdoproject.org/2ef2cb91a9174df0a982e58f2662d09e/
2025-12-08 14:28:06.396792 | Event ID: f455c5b0-d441-11f0-9462-d710a874670d
2025-12-08 14:28:06.400151 | 
2025-12-08 14:28:06.400218 | LOOP [emit-job-header : Print node information]
2025-12-08 14:28:06.477581 | localhost | ok:
2025-12-08 14:28:06.477740 | localhost | # Node Information
2025-12-08 14:28:06.477769 | localhost | Inventory Hostname: controller
2025-12-08 14:28:06.477793 | localhost | Hostname: np0005550389
2025-12-08 14:28:06.477811 | localhost | Username: zuul
2025-12-08 14:28:06.477830 | localhost | Distro: CentOS 9
2025-12-08 14:28:06.477846 | localhost | Provider: ibm-bm4-nodepool
2025-12-08 14:28:06.477863 | localhost | Region: regionOne
2025-12-08 14:28:06.477880 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-12-08 14:28:06.477900 | localhost | Product Name: OpenStack Compute
2025-12-08 14:28:06.477935 | localhost | Interface IP: 192.168.26.52
2025-12-08 14:28:06.501467 | 
2025-12-08 14:28:06.501517 | PLAY [all]
2025-12-08 14:28:06.506934 | 
2025-12-08 14:28:06.506995 | TASK [Gather network facts]
2025-12-08 14:28:06.830234 | controller | ok
2025-12-08 14:28:06.843392 | 
2025-12-08 14:28:06.843452 | TASK [include_role : start-zuul-console]
2025-12-08 14:28:06.860189 | controller | ok
2025-12-08 14:28:06.871264 | 
2025-12-08 14:28:06.871326 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-08 14:28:07.152499 | controller | ok
2025-12-08 14:28:07.160578 | 
2025-12-08 14:28:07.160650 | TASK [include_role : add-build-sshkey]
2025-12-08 14:28:07.178017 | controller | ok
2025-12-08 14:28:07.189717 | 
2025-12-08 14:28:07.189776 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-08 14:28:07.364781 | controller -> localhost | ok
2025-12-08 14:28:07.370038 | 
2025-12-08 14:28:07.370102 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-08 14:28:07.387790 | controller | ok
2025-12-08 14:28:07.399982 | controller | included: /var/lib/zuul/builds/2ef2cb91a9174df0a982e58f2662d09e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-08 14:28:07.405032 | 
2025-12-08 14:28:07.405091 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-08 14:28:08.126948 | controller -> localhost | Generating public/private rsa key pair.
2025-12-08 14:28:08.127124 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/2ef2cb91a9174df0a982e58f2662d09e/work/2ef2cb91a9174df0a982e58f2662d09e_id_rsa.
2025-12-08 14:28:08.127158 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/2ef2cb91a9174df0a982e58f2662d09e/work/2ef2cb91a9174df0a982e58f2662d09e_id_rsa.pub.
2025-12-08 14:28:08.127182 | controller -> localhost | The key fingerprint is:
2025-12-08 14:28:08.127203 | controller -> localhost | SHA256:vVP/z947TO/SxLm+NVuOFq3txxb1Ku8m6rkZKclJcuk zuul-build-sshkey
2025-12-08 14:28:08.127223 | controller -> localhost | The key's randomart image is:
2025-12-08 14:28:08.127241 | controller -> localhost | +---[RSA 3072]----+
2025-12-08 14:28:08.127260 | controller -> localhost | |                 |
2025-12-08 14:28:08.127280 | controller -> localhost | |                 |
2025-12-08 14:28:08.127298 | controller -> localhost | |                 |
2025-12-08 14:28:08.127316 | controller -> localhost | |         o      .|
2025-12-08 14:28:08.127333 | controller -> localhost | |      . S . .  o+|
2025-12-08 14:28:08.127350 | controller -> localhost | |       * o + ..+=|
2025-12-08 14:28:08.127367 | controller -> localhost | |        E =   +X*|
2025-12-08 14:28:08.127390 | controller -> localhost | |         . =o *O&|
2025-12-08 14:28:08.127409 | controller -> localhost | |         .*o B=@/|
2025-12-08 14:28:08.127427 | controller -> localhost | +----[SHA256]-----+
2025-12-08 14:28:08.127474 | controller -> localhost | ok: Runtime: 0:00:00.413080
2025-12-08 14:28:08.133198 | 
2025-12-08 14:28:08.133266 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-08 14:28:08.151085 | controller | ok
2025-12-08 14:28:08.160295 | controller | included: /var/lib/zuul/builds/2ef2cb91a9174df0a982e58f2662d09e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-08 14:28:08.167799 | 
2025-12-08 14:28:08.167862 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-08 14:28:08.181427 | controller | skipping: Conditional result was False
2025-12-08 14:28:08.186456 | 
2025-12-08 14:28:08.186521 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-08 14:28:08.518771 | controller | changed
2025-12-08 14:28:08.523032 | 
2025-12-08 14:28:08.523091 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-08 14:28:08.692835 | controller | ok
2025-12-08 14:28:08.697101 | 
2025-12-08 14:28:08.697160 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-08 14:28:09.161502 | controller | changed
2025-12-08 14:28:09.166178 | 
2025-12-08 14:28:09.166239 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-08 14:28:09.630561 | controller | changed
2025-12-08 14:28:09.634959 | 
2025-12-08 14:28:09.635027 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-08 14:28:09.648478 | controller | skipping: Conditional result was False
2025-12-08 14:28:09.654409 | 
2025-12-08 14:28:09.654476 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-08 14:28:09.931269 | controller -> localhost | changed
2025-12-08 14:28:09.939659 | 
2025-12-08 14:28:09.939720 | TASK [add-build-sshkey : Add back temp key]
2025-12-08 14:28:10.145873 | controller -> localhost | Identity added: /var/lib/zuul/builds/2ef2cb91a9174df0a982e58f2662d09e/work/2ef2cb91a9174df0a982e58f2662d09e_id_rsa (zuul-build-sshkey)
2025-12-08 14:28:10.146083 | controller -> localhost | ok: Runtime: 0:00:00.006322
2025-12-08 14:28:10.151279 | 
2025-12-08 14:28:10.151341 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-08 14:28:10.412938 | controller | ok
2025-12-08 14:28:10.416939 | 
2025-12-08 14:28:10.416999 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-08 14:28:10.440723 | controller | skipping: Conditional result was False
2025-12-08 14:28:10.449134 | 
2025-12-08 14:28:10.449191 | TASK [include_role : validate-host]
2025-12-08 14:28:10.465995 | controller | ok
2025-12-08 14:28:10.484424 | 
2025-12-08 14:28:10.484490 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-08 14:28:10.512354 | controller | ok
2025-12-08 14:28:10.516625 | 
2025-12-08 14:28:10.516687 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-08 14:28:10.702003 | controller -> localhost | ok
2025-12-08 14:28:10.706982 | 
2025-12-08 14:28:10.707039 | TASK [validate-host : Collect information about the host]
2025-12-08 14:28:11.253085 | controller | ok
2025-12-08 14:28:11.260212 | 
2025-12-08 14:28:11.260269 | TASK [validate-host : Sanitize hostname]
2025-12-08 14:28:11.317273 | controller | ok
2025-12-08 14:28:11.322534 | 
2025-12-08 14:28:11.322597 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-08 14:28:11.702477 | controller -> localhost | changed
2025-12-08 14:28:11.706724 | 
2025-12-08 14:28:11.706779 | TASK [validate-host : Collect information about zuul worker]
2025-12-08 14:28:11.987608 | controller | ok
2025-12-08 14:28:11.991942 | 
2025-12-08 14:28:11.992005 | TASK [validate-host : Write out all zuul information for each host]
2025-12-08 14:28:12.320575 | controller -> localhost | changed
2025-12-08 14:28:12.328566 | 
2025-12-08 14:28:12.328631 | TASK [include_role : prepare-workspace-openshift]
2025-12-08 14:28:12.341624 | controller | skipping: Conditional result was False
2025-12-08 14:28:12.345841 | 
2025-12-08 14:28:12.345902 | TASK [include_role : remove-zuul-sshkey]
2025-12-08 14:28:12.358299 | controller | skipping: Conditional result was False
2025-12-08 14:28:12.362895 | 
2025-12-08 14:28:12.362970 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-08 14:28:12.537683 | controller | ok: "logs"
2025-12-08 14:28:12.537883 | controller | ok: All items complete
2025-12-08 14:28:12.537934 | 
2025-12-08 14:28:12.711390 | controller | ok: "artifacts"
2025-12-08 14:28:12.873282 | controller | ok: "docs"
2025-12-08 14:28:12.879565 | 
2025-12-08 14:28:12.879642 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-08 14:28:13.061364 | controller | changed: "logs"
2025-12-08 14:28:13.219386 | controller | changed: "artifacts"
2025-12-08 14:28:13.381510 | controller | changed: "docs"
2025-12-08 14:28:13.408286 | 
2025-12-08 14:28:13.408347 | PLAY RECAP
2025-12-08 14:28:13.408391 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-08 14:28:13.408418 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-08 14:28:13.408436 | 
2025-12-08 14:28:13.476409 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-08 14:28:13.477152 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-08 14:28:13.989680 | 
2025-12-08 14:28:13.989782 | PLAY [all]
2025-12-08 14:28:14.008609 | 
2025-12-08 14:28:14.008685 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-08 14:28:14.057277 | controller | ok
2025-12-08 14:28:14.062000 | 
2025-12-08 14:28:14.062079 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-08 14:28:14.364402 | controller | changed
2025-12-08 14:28:14.369238 | 
2025-12-08 14:28:14.369305 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-08 14:28:15.048510 | controller | changed
2025-12-08 14:28:15.056823 | 
2025-12-08 14:28:15.056886 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-08 14:28:15.441773 | controller | changed:
2025-12-08 14:28:15.442016 | controller | {
2025-12-08 14:28:15.442045 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-08 14:28:15.442074 | controller | }
2025-12-08 14:28:15.636250 | controller | changed:
2025-12-08 14:28:15.636304 | controller | {
2025-12-08 14:28:15.636332 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-08 14:28:15.636352 | controller | }
2025-12-08 14:28:15.824550 | controller | changed:
2025-12-08 14:28:15.824642 | controller | {
2025-12-08 14:28:15.824668 | 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-08 14:28:15.824687 | controller | }
2025-12-08 14:28:16.020795 | controller | changed:
2025-12-08 14:28:16.020933 | controller | {
2025-12-08 14:28:16.020970 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-08 14:28:16.020992 | controller | }
2025-12-08 14:28:16.215840 | controller | changed:
2025-12-08 14:28:16.215970 | controller | {
2025-12-08 14:28:16.216001 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-08 14:28:16.216024 | controller | }
2025-12-08 14:28:16.405398 | controller | changed:
2025-12-08 14:28:16.405513 | controller | {
2025-12-08 14:28:16.405542 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-08 14:28:16.405563 | controller | }
2025-12-08 14:28:16.591844 | controller | changed:
2025-12-08 14:28:16.591900 | controller | {
2025-12-08 14:28:16.591942 | 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-08 14:28:16.591964 | controller | }
2025-12-08 14:28:16.773224 | controller | changed:
2025-12-08 14:28:16.773271 | controller | {
2025-12-08 14:28:16.773297 | 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-08 14:28:16.773317 | controller | }
2025-12-08 14:28:16.951789 | controller | changed:
2025-12-08 14:28:16.951842 | controller | {
2025-12-08 14:28:16.951866 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-08 14:28:16.951885 | controller | }
2025-12-08 14:28:17.132357 | controller | changed:
2025-12-08 14:28:17.132403 | controller | {
2025-12-08 14:28:17.132433 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-08 14:28:17.132455 | controller | }
2025-12-08 14:28:17.321362 | controller | changed:
2025-12-08 14:28:17.321411 | controller | {
2025-12-08 14:28:17.321434 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-08 14:28:17.321451 | controller | }
2025-12-08 14:28:17.502609 | controller | changed:
2025-12-08 14:28:17.502655 | controller | {
2025-12-08 14:28:17.502680 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-08 14:28:17.502699 | controller | }
2025-12-08 14:28:17.690898 | controller | changed:
2025-12-08 14:28:17.690973 | controller | {
2025-12-08 14:28:17.690997 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-08 14:28:17.691015 | controller | }
2025-12-08 14:28:17.878265 | controller | changed:
2025-12-08 14:28:17.878348 | controller | {
2025-12-08 14:28:17.878379 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-08 14:28:17.878400 | controller | }
2025-12-08 14:28:18.066553 | controller | changed:
2025-12-08 14:28:18.066611 | controller | {
2025-12-08 14:28:18.066637 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-08 14:28:18.066656 | controller | }
2025-12-08 14:28:18.251829 | controller | changed:
2025-12-08 14:28:18.251877 | controller | {
2025-12-08 14:28:18.251899 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-08 14:28:18.251936 | controller | }
2025-12-08 14:28:18.433770 | controller | changed:
2025-12-08 14:28:18.433819 | controller | {
2025-12-08 14:28:18.433842 | 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-08 14:28:18.433861 | controller | }
2025-12-08 14:28:18.617102 | controller | changed:
2025-12-08 14:28:18.617148 | controller | {
2025-12-08 14:28:18.617172 | 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-08 14:28:18.617198 | controller | }
2025-12-08 14:28:18.799005 | controller | changed:
2025-12-08 14:28:18.799056 | controller | {
2025-12-08 14:28:18.799079 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-08 14:28:18.799097 | controller | }
2025-12-08 14:28:18.984113 | controller | changed:
2025-12-08 14:28:18.984218 | controller | {
2025-12-08 14:28:18.984243 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-08 14:28:18.984262 | controller | }
2025-12-08 14:28:19.167571 | controller | changed:
2025-12-08 14:28:19.167766 | controller | {
2025-12-08 14:28:19.167796 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-08 14:28:19.167817 | controller | }
2025-12-08 14:28:19.348543 | controller | changed:
2025-12-08 14:28:19.348597 | controller | {
2025-12-08 14:28:19.348622 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-08 14:28:19.348640 | controller | }
2025-12-08 14:28:19.530494 | controller | changed:
2025-12-08 14:28:19.530539 | controller | {
2025-12-08 14:28:19.530563 | 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-08 14:28:19.530582 | controller | }
2025-12-08 14:28:19.713426 | controller | changed:
2025-12-08 14:28:19.713473 | controller | {
2025-12-08 14:28:19.713496 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-08 14:28:19.713514 | controller | }
2025-12-08 14:28:19.896825 | controller | changed:
2025-12-08 14:28:19.896871 | controller | {
2025-12-08 14:28:19.896894 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-08 14:28:19.896932 | controller | }
2025-12-08 14:28:20.079380 | controller | changed:
2025-12-08 14:28:20.079428 | controller | {
2025-12-08 14:28:20.079451 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-08 14:28:20.079469 | controller | }
2025-12-08 14:28:20.101145 | 
2025-12-08 14:28:20.101254 | TASK [Set timezone to UTC]
2025-12-08 14:28:20.454122 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-08 14:28:20.458328 | 
2025-12-08 14:28:20.458387 | TASK [Create nodepool directory]
2025-12-08 14:28:20.639265 | controller | changed
2025-12-08 14:28:20.644241 | 
2025-12-08 14:28:20.644308 | TASK [Create nodepool sub_nodes file]
2025-12-08 14:28:21.088143 | controller | changed
2025-12-08 14:28:21.092709 | 
2025-12-08 14:28:21.092771 | TASK [Create nodepool sub_nodes_private file]
2025-12-08 14:28:21.535768 | controller | changed
2025-12-08 14:28:21.540204 | 
2025-12-08 14:28:21.540262 | LOOP [Populate nodepool sub_nodes file]
2025-12-08 14:28:21.569411 | 
2025-12-08 14:28:21.569526 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-08 14:28:21.597572 | 
2025-12-08 14:28:21.597698 | TASK [Create nodepool primary file]
2025-12-08 14:28:21.621003 | controller | skipping: Conditional result was False
2025-12-08 14:28:21.626148 | 
2025-12-08 14:28:21.626214 | TASK [Create nodepool node_private for this node]
2025-12-08 14:28:22.110026 | controller | changed
2025-12-08 14:28:22.115324 | 
2025-12-08 14:28:22.115384 | LOOP [Copy ssh keys to nodepool directory]
2025-12-08 14:28:22.402823 | controller | ok: Item: id_rsa Runtime: 0:00:00.003714
2025-12-08 14:28:22.403021 | 
2025-12-08 14:28:22.547791 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004524
2025-12-08 14:28:22.559761 | 
2025-12-08 14:28:22.559854 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-08 14:28:23.036810 | controller | changed
2025-12-08 14:28:23.041804 | 
2025-12-08 14:28:23.041868 | TASK [Validate sudoers config after edits]
2025-12-08 14:28:23.246473 | controller | /etc/sudoers: parsed OK
2025-12-08 14:28:23.246541 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-08 14:28:23.246551 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-08 14:28:23.246559 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-08 14:28:23.570257 | controller | ok: Runtime: 0:00:00.005585
2025-12-08 14:28:23.577181 | 
2025-12-08 14:28:23.577246 | TASK [Show the environment passed in to job shell scripts]
2025-12-08 14:28:23.764379 | controller | SHELL=/bin/bash
2025-12-08 14:28:23.764421 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-08 14:28:23.764430 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-08 14:28:23.764479 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/69/3169/47b313238437a5780df0b75f08c1f733e706d0e9
2025-12-08 14:28:23.764487 | controller | PWD=/home/zuul
2025-12-08 14:28:23.764493 | controller | ZUUL_PIPELINE=github-check
2025-12-08 14:28:23.764498 | controller | LOGNAME=zuul
2025-12-08 14:28:23.764503 | controller | XDG_SESSION_TYPE=tty
2025-12-08 14:28:23.764508 | controller | _=/usr/bin/env
2025-12-08 14:28:23.764513 | controller | MOTD_SHOWN=pam
2025-12-08 14:28:23.764518 | controller | HOME=/home/zuul
2025-12-08 14:28:23.764522 | controller | LANG=en_US.UTF-8
2025-12-08 14:28:23.764527 | controller | SSH_CONNECTION=192.168.26.12 35852 192.168.26.52 22
2025-12-08 14:28:23.764533 | 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-08 14:28:23.764542 | controller | ZUUL_CHANGE_IDS=3169,47b313238437a5780df0b75f08c1f733e706d0e9
2025-12-08 14:28:23.764547 | controller | WORKSPACE=/home/zuul/workspace
2025-12-08 14:28:23.764552 | controller | XDG_SESSION_CLASS=user
2025-12-08 14:28:23.764557 | controller | SELINUX_ROLE_REQUESTED=
2025-12-08 14:28:23.764562 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-08 14:28:23.764567 | controller | USER=zuul
2025-12-08 14:28:23.764572 | controller | ZUUL_VOTING=True
2025-12-08 14:28:23.764577 | controller | BUILD_TIMEOUT=1800000
2025-12-08 14:28:23.764582 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-08 14:28:23.764587 | controller | SHLVL=1
2025-12-08 14:28:23.764592 | controller | ZUUL_PATCHSET=47b313238437a5780df0b75f08c1f733e706d0e9
2025-12-08 14:28:23.764597 | controller | XDG_SESSION_ID=1
2025-12-08 14:28:23.764602 | controller | ZUUL_BRANCH=main
2025-12-08 14:28:23.764607 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-08 14:28:23.764612 | controller | SSH_CLIENT=192.168.26.12 35852 22
2025-12-08 14:28:23.764617 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-08 14:28:23.764622 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-08 14:28:23.764627 | controller | which_declare=declare -f
2025-12-08 14:28:23.764632 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-08 14:28:23.764638 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-08 14:28:23.764643 | controller | ZUUL_CHANGE=3169
2025-12-08 14:28:23.764647 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-08 14:28:23.764652 | controller | ZUUL_UUID=2ef2cb91a9174df0a982e58f2662d09e
2025-12-08 14:28:23.764657 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-08 14:28:23.764673 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-08 14:28:23.764679 | controller | }
2025-12-08 14:28:24.103655 | controller | ok: Runtime: 0:00:00.005003
2025-12-08 14:28:24.108955 | 
2025-12-08 14:28:24.109015 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-08 14:28:24.122731 | controller | skipping: Conditional result was False
2025-12-08 14:28:24.127739 | 
2025-12-08 14:28:24.127798 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-08 14:28:24.650807 | controller | skipping: Conditional result was False
2025-12-08 14:28:24.656471 | 
2025-12-08 14:28:24.656538 | TASK [Ensure legacy workspace directory]
2025-12-08 14:28:24.836687 | controller | changed
2025-12-08 14:28:24.854324 | 
2025-12-08 14:28:24.854397 | PLAY RECAP
2025-12-08 14:28:24.854444 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-08 14:28:24.854466 | 
2025-12-08 14:28:24.930408 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-08 14:28:24.931159 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-08 14:28:25.414935 | 
2025-12-08 14:28:25.415032 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-08 14:28:25.433638 | 
2025-12-08 14:28:25.433707 | TASK [Create zuul-output directory]
2025-12-08 14:28:25.726144 | controller | changed
2025-12-08 14:28:25.730498 | 
2025-12-08 14:28:25.730565 | TASK [Slurp Zuul inventory test]
2025-12-08 14:28:25.987730 | controller -> localhost | ok
2025-12-08 14:28:25.993905 | 
2025-12-08 14:28:25.993990 | TASK [Save zuul inventory]
2025-12-08 14:28:26.671937 | controller | changed
2025-12-08 14:28:26.676648 | 
2025-12-08 14:28:26.676712 | TASK [Save zuul vars without the change_message]
2025-12-08 14:28:27.192601 | controller | changed
2025-12-08 14:28:27.208125 | 
2025-12-08 14:28:27.208172 | PLAY RECAP
2025-12-08 14:28:27.208210 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-08 14:28:27.208231 | 
2025-12-08 14:28:27.268688 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-08 14:28:27.269410 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-08 14:28:27.776243 | 
2025-12-08 14:28:27.776341 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-08 14:28:27.794985 | 
2025-12-08 14:28:27.795055 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-08 14:28:27.813289 | controller | ok
2025-12-08 14:28:27.828570 | 
2025-12-08 14:28:27.828660 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-08 14:28:27.852627 | controller | skipping: Conditional result was False
2025-12-08 14:28:27.857932 | 
2025-12-08 14:28:27.858001 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-08 14:28:28.149095 | controller | ok
2025-12-08 14:28:28.154084 | 
2025-12-08 14:28:28.154150 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-08 14:28:28.700796 | controller | ok
2025-12-08 14:28:28.710043 | 
2025-12-08 14:28:28.710101 | TASK [Prepare workspace]
2025-12-08 14:28:28.727661 | controller | ok
2025-12-08 14:28:28.742543 | 
2025-12-08 14:28:28.742614 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-08 14:28:29.003370 | controller | ok
2025-12-08 14:28:29.008494 | 
2025-12-08 14:28:29.008557 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-08 14:28:31.309745 | controller | Output suppressed because no_log was given
2025-12-08 14:28:31.318804 | 
2025-12-08 14:28:31.318880 | LOOP [Create zuul-output directory]
2025-12-08 14:28:31.491138 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-08 14:28:31.635627 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-08 14:28:31.645902 | 
2025-12-08 14:28:31.646009 | TASK [Install required packages]
2025-12-08 14:29:29.765821 | controller | changed
2025-12-08 14:29:29.770699 | 
2025-12-08 14:29:29.770759 | TASK [Install venv]
2025-12-08 14:30:28.817377 | controller | changed
2025-12-08 14:30:28.838819 | 
2025-12-08 14:30:28.838878 | PLAY RECAP
2025-12-08 14:30:28.838936 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-08 14:30:28.838964 | 
2025-12-08 14:30:28.907939 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-08 14:30:28.908745 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-08 14:30:29.381379 | 
2025-12-08 14:30:29.381476 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-12-08 14:30:29.400227 | 
2025-12-08 14:30:29.400306 | TASK [Gather required facts]
2025-12-08 14:30:29.834942 | controller | ok
2025-12-08 14:30:29.839743 | 
2025-12-08 14:30:29.839813 | TASK [Load environment var if instructed to]
2025-12-08 14:30:29.863632 | controller | skipping: Conditional result was False
2025-12-08 14:30:29.869013 | 
2025-12-08 14:30:29.869079 | TASK [Ensure group_vars dir exists]
2025-12-08 14:30:30.160433 | controller | ok
2025-12-08 14:30:30.166368 | 
2025-12-08 14:30:30.166430 | TASK [Print related variables]
2025-12-08 14:30:30.194704 | controller | ok:
2025-12-08 14:30:30.194819 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-12-08 14:30:30.194850 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_cephadm
2025-12-08 14:30:30.199875 | 
2025-12-08 14:30:30.199954 | TASK [Run molecule]
2025-12-08 14:30:31.084577 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-12-08 14:30:31.155426 | controller | INFO     Performing prerun with role_name_check=0...
2025-12-08 14:30:47.687970 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 14:30:47.688405 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 14:30:47.688830 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 14:30:47.689259 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 14:30:47.689709 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+007ebb8f was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 1.0.0+007ebb8f (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 14:30:47.690120 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 14:30:47.690544 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 14:30:47.690949 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 14:30:47.691366 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 14:30:47.691755 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 14:30:47.692148 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 14:30:47.692601 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 14:30:47.693003 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 14:30:47.693429 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 14:30:47.693836 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 14:30:47.702479 | controller | INFO     Running default > prepare
2025-12-08 14:30:48.300937 | controller |
2025-12-08 14:30:48.301054 | controller | PLAY [Prepare] *****************************************************************
2025-12-08 14:30:48.301156 | controller |
2025-12-08 14:30:48.301325 | controller | TASK [Gathering Facts] *********************************************************
2025-12-08 14:30:48.301447 | controller | Monday 08 December 2025  14:30:48 +0000 (0:00:00.011)       0:00:00.011 *******
2025-12-08 14:30:49.095186 | controller | ok: [instance]
2025-12-08 14:30:49.095268 | controller |
2025-12-08 14:30:49.095367 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-12-08 14:30:49.095475 | controller | Monday 08 December 2025  14:30:49 +0000 (0:00:00.794)       0:00:00.806 *******
2025-12-08 14:30:49.112802 | controller | skipping: [instance]
2025-12-08 14:30:49.112896 | controller |
2025-12-08 14:30:49.113008 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-12-08 14:30:49.113106 | controller | Monday 08 December 2025  14:30:49 +0000 (0:00:00.017)       0:00:00.823 *******
2025-12-08 14:30:49.149848 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-12-08 14:30:49.149931 | controller |
2025-12-08 14:30:49.150028 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-12-08 14:30:49.150124 | controller | Monday 08 December 2025  14:30:49 +0000 (0:00:00.037)       0:00:00.860 *******
2025-12-08 14:30:49.427577 | controller | ok: [instance]
2025-12-08 14:30:49.427654 | controller |
2025-12-08 14:30:49.427753 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-12-08 14:30:49.427849 | controller | Monday 08 December 2025  14:30:49 +0000 (0:00:00.277)       0:00:01.138 *******
2025-12-08 14:30:49.709518 | controller | ok: [instance]
2025-12-08 14:30:49.709596 | controller |
2025-12-08 14:30:49.709691 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-12-08 14:30:49.709780 | controller | Monday 08 December 2025  14:30:49 +0000 (0:00:00.281)       0:00:01.420 *******
2025-12-08 14:30:50.348858 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-12-08 14:30:50.348939 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-12-08 14:30:50.349044 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-12-08 14:30:50.349140 | controller |
2025-12-08 14:30:50.349268 | controller | TASK [Deploy repo-setup] *******************************************************
2025-12-08 14:30:50.349368 | controller | Monday 08 December 2025  14:30:50 +0000 (0:00:00.639)       0:00:02.059 *******
2025-12-08 14:30:50.406665 | controller |
2025-12-08 14:30:50.406801 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-12-08 14:30:50.406929 | controller | Monday 08 December 2025  14:30:50 +0000 (0:00:00.058)       0:00:02.117 *******
2025-12-08 14:30:50.954377 | controller | changed: [instance] => (item=tmp)
2025-12-08 14:30:50.954431 | controller | changed: [instance] => (item=artifacts/repositories)
2025-12-08 14:30:50.954526 | controller | changed: [instance] => (item=venv/repo_setup)
2025-12-08 14:30:50.954614 | controller |
2025-12-08 14:30:50.954707 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-12-08 14:30:50.954794 | controller | Monday 08 December 2025  14:30:50 +0000 (0:00:00.547)       0:00:02.665 *******
2025-12-08 14:30:51.692787 | controller | ok: [instance]
2025-12-08 14:30:51.692851 | controller |
2025-12-08 14:30:51.692951 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-12-08 14:30:51.693056 | controller | Monday 08 December 2025  14:30:51 +0000 (0:00:00.738)       0:00:03.403 *******
2025-12-08 14:30:52.771621 | controller | changed: [instance]
2025-12-08 14:30:52.771681 | controller |
2025-12-08 14:30:52.771782 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-12-08 14:30:52.771887 | controller | Monday 08 December 2025  14:30:52 +0000 (0:00:01.078)       0:00:04.482 *******
2025-12-08 14:31:01.065520 | controller | changed: [instance]
2025-12-08 14:31:01.740239 | controller |
2025-12-08 14:31:01.740267 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-12-08 14:31:01.740275 | controller | Monday 08 December 2025  14:31:01 +0000 (0:00:08.293)       0:00:12.776 *******
2025-12-08 14:31:01.740284 | controller | changed: [instance]
2025-12-08 14:31:01.761182 | controller |
2025-12-08 14:31:01.761201 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-12-08 14:31:01.761209 | controller | Monday 08 December 2025  14:31:01 +0000 (0:00:00.674)       0:00:13.450 *******
2025-12-08 14:31:01.761217 | controller | skipping: [instance]
2025-12-08 14:31:02.527149 | controller |
2025-12-08 14:31:02.527191 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-12-08 14:31:02.527200 | controller | Monday 08 December 2025  14:31:01 +0000 (0:00:00.021)       0:00:13.472 *******
2025-12-08 14:31:02.527209 | controller | changed: [instance]
2025-12-08 14:31:02.561485 | controller |
2025-12-08 14:31:02.561501 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-12-08 14:31:02.561508 | controller | Monday 08 December 2025  14:31:02 +0000 (0:00:00.765)       0:00:14.237 *******
2025-12-08 14:31:02.561516 | controller | skipping: [instance]
2025-12-08 14:31:02.593267 | controller |
2025-12-08 14:31:02.593306 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-12-08 14:31:02.593317 | controller | Monday 08 December 2025  14:31:02 +0000 (0:00:00.034)       0:00:14.272 *******
2025-12-08 14:31:02.593331 | controller | skipping: [instance]
2025-12-08 14:31:02.593478 | controller |
2025-12-08 14:31:02.593501 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-12-08 14:31:02.593514 | controller | Monday 08 December 2025  14:31:02 +0000 (0:00:00.031)       0:00:14.304 *******
2025-12-08 14:31:02.625423 | controller | skipping: [instance]
2025-12-08 14:31:03.028219 | controller |
2025-12-08 14:31:03.028243 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-12-08 14:31:03.028251 | controller | Monday 08 December 2025  14:31:02 +0000 (0:00:00.032)       0:00:14.336 *******
2025-12-08 14:31:03.028260 | controller | changed: [instance]
2025-12-08 14:31:03.449930 | controller |
2025-12-08 14:31:03.449962 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-12-08 14:31:03.449970 | controller | Monday 08 December 2025  14:31:03 +0000 (0:00:00.402)       0:00:14.738 *******
2025-12-08 14:31:03.449981 | controller | changed: [instance]
2025-12-08 14:31:03.475270 | controller |
2025-12-08 14:31:03.475305 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-12-08 14:31:03.475317 | controller | Monday 08 December 2025  14:31:03 +0000 (0:00:00.421)       0:00:15.160 *******
2025-12-08 14:31:03.475330 | controller | skipping: [instance]
2025-12-08 14:31:03.475362 | controller |
2025-12-08 14:31:03.475373 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-12-08 14:31:03.475460 | controller | Monday 08 December 2025  14:31:03 +0000 (0:00:00.025)       0:00:15.186 *******
2025-12-08 14:31:03.501227 | controller | skipping: [instance]
2025-12-08 14:31:03.526540 | controller |
2025-12-08 14:31:03.526561 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-12-08 14:31:03.526569 | controller | Monday 08 December 2025  14:31:03 +0000 (0:00:00.025)       0:00:15.212 *******
2025-12-08 14:31:03.526578 | controller | skipping: [instance]
2025-12-08 14:31:03.560137 | controller |
2025-12-08 14:31:03.560154 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-12-08 14:31:03.560176 | controller | Monday 08 December 2025  14:31:03 +0000 (0:00:00.025)       0:00:15.237 *******
2025-12-08 14:31:03.560188 | controller | ok: [instance]
2025-12-08 14:31:03.583647 | controller |
2025-12-08 14:31:03.583663 | controller | TASK [repo_setup : Create download directory] **********************************
2025-12-08 14:31:03.583669 | controller | Monday 08 December 2025  14:31:03 +0000 (0:00:00.033)       0:00:15.271 *******
2025-12-08 14:31:03.583677 | controller | skipping: [instance]
2025-12-08 14:31:03.608215 | controller |
2025-12-08 14:31:03.608232 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-12-08 14:31:03.608239 | controller | Monday 08 December 2025  14:31:03 +0000 (0:00:00.023)       0:00:15.294 *******
2025-12-08 14:31:03.608253 | controller | skipping: [instance]
2025-12-08 14:31:03.631619 | controller |
2025-12-08 14:31:03.631636 | controller | TASK [Download the RPM] ********************************************************
2025-12-08 14:31:03.631642 | controller | Monday 08 December 2025  14:31:03 +0000 (0:00:00.024)       0:00:15.319 *******
2025-12-08 14:31:03.631650 | controller | skipping: [instance]
2025-12-08 14:31:03.655226 | controller |
2025-12-08 14:31:03.655260 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-12-08 14:31:03.655272 | controller | Monday 08 December 2025  14:31:03 +0000 (0:00:00.023)       0:00:15.342 *******
2025-12-08 14:31:03.655286 | controller | skipping: [instance]
2025-12-08 14:31:03.655530 | controller |
2025-12-08 14:31:03.680199 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-12-08 14:31:03.680216 | controller | Monday 08 December 2025  14:31:03 +0000 (0:00:00.023)       0:00:15.366 *******
2025-12-08 14:31:03.680226 | controller | skipping: [instance]
2025-12-08 14:31:03.706142 | controller |
2025-12-08 14:31:03.706160 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-12-08 14:31:03.706201 | controller | Monday 08 December 2025  14:31:03 +0000 (0:00:00.024)       0:00:15.391 *******
2025-12-08 14:31:03.706212 | controller | skipping: [instance]
2025-12-08 14:31:03.728995 | controller |
2025-12-08 14:31:03.729010 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-12-08 14:31:03.729018 | controller | Monday 08 December 2025  14:31:03 +0000 (0:00:00.025)       0:00:15.416 *******
2025-12-08 14:31:03.729025 | controller | skipping: [instance]
2025-12-08 14:31:03.919216 | controller |
2025-12-08 14:31:03.919238 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-12-08 14:31:03.919245 | controller | Monday 08 December 2025  14:31:03 +0000 (0:00:00.023)       0:00:15.440 *******
2025-12-08 14:31:03.919254 | controller | ok: [instance]
2025-12-08 14:31:03.919427 | controller |
2025-12-08 14:31:03.919863 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-12-08 14:31:04.114875 | controller | Monday 08 December 2025  14:31:03 +0000 (0:00:00.190)       0:00:15.630 *******
2025-12-08 14:31:04.114896 | controller | changed: [instance]
2025-12-08 14:31:04.324197 | controller |
2025-12-08 14:31:04.324220 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-12-08 14:31:04.324227 | controller | Monday 08 December 2025  14:31:04 +0000 (0:00:00.195)       0:00:15.825 *******
2025-12-08 14:31:04.324236 | controller | changed: [instance]
2025-12-08 14:31:04.344403 | controller |
2025-12-08 14:31:04.344426 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-12-08 14:31:04.344435 | controller | Monday 08 December 2025  14:31:04 +0000 (0:00:00.209)       0:00:16.034 *******
2025-12-08 14:31:04.344457 | controller | skipping: [instance]
2025-12-08 14:31:04.363537 | controller |
2025-12-08 14:31:04.363553 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-12-08 14:31:04.363559 | controller | Monday 08 December 2025  14:31:04 +0000 (0:00:00.020)       0:00:16.055 *******
2025-12-08 14:31:04.363567 | controller | skipping: [instance]
2025-12-08 14:31:04.382490 | controller |
2025-12-08 14:31:04.382523 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-12-08 14:31:04.382537 | controller | Monday 08 December 2025  14:31:04 +0000 (0:00:00.019)       0:00:16.074 *******
2025-12-08 14:31:04.382553 | controller | skipping: [instance]
2025-12-08 14:31:04.401268 | controller |
2025-12-08 14:31:04.401285 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-12-08 14:31:04.401293 | controller | Monday 08 December 2025  14:31:04 +0000 (0:00:00.018)       0:00:16.093 *******
2025-12-08 14:31:04.401301 | controller | skipping: [instance]
2025-12-08 14:31:04.421427 | controller |
2025-12-08 14:31:04.421460 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-12-08 14:31:04.421470 | controller | Monday 08 December 2025  14:31:04 +0000 (0:00:00.018)       0:00:16.111 *******
2025-12-08 14:31:04.421480 | controller | skipping: [instance]
2025-12-08 14:31:04.421635 | controller |
2025-12-08 14:31:04.421799 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-12-08 14:31:04.421960 | controller | Monday 08 December 2025  14:31:04 +0000 (0:00:00.020)       0:00:16.132 *******
2025-12-08 14:31:04.441065 | controller | skipping: [instance]
2025-12-08 14:31:04.441273 | controller |
2025-12-08 14:31:04.441407 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-12-08 14:31:04.441550 | controller | Monday 08 December 2025  14:31:04 +0000 (0:00:00.019)       0:00:16.151 *******
2025-12-08 14:31:04.455912 | controller | skipping: [instance]
2025-12-08 14:31:04.456214 | controller |
2025-12-08 14:31:04.456357 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-12-08 14:31:04.456496 | controller | Monday 08 December 2025  14:31:04 +0000 (0:00:00.014)       0:00:16.166 *******
2025-12-08 14:31:04.481976 | controller | skipping: [instance]
2025-12-08 14:31:04.482276 | controller |
2025-12-08 14:31:04.482410 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-12-08 14:31:04.482549 | controller | Monday 08 December 2025  14:31:04 +0000 (0:00:00.026)       0:00:16.192 *******
2025-12-08 14:31:04.498016 | controller | skipping: [instance]
2025-12-08 14:31:04.498324 | controller |
2025-12-08 14:31:04.498613 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-12-08 14:31:04.498870 | controller | Monday 08 December 2025  14:31:04 +0000 (0:00:00.016)       0:00:16.209 *******
2025-12-08 14:31:04.515890 | controller | skipping: [instance]
2025-12-08 14:31:04.516159 | controller |
2025-12-08 14:31:04.516394 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-12-08 14:31:04.516530 | controller | Monday 08 December 2025  14:31:04 +0000 (0:00:00.017)       0:00:16.226 *******
2025-12-08 14:31:04.541412 | controller | skipping: [instance]
2025-12-08 14:31:04.541699 | controller |
2025-12-08 14:31:04.541949 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-12-08 14:31:04.542248 | controller | Monday 08 December 2025  14:31:04 +0000 (0:00:00.025)       0:00:16.252 *******
2025-12-08 14:31:04.569487 | controller | skipping: [instance]
2025-12-08 14:31:04.569754 | controller |
2025-12-08 14:31:04.570005 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-12-08 14:31:04.570287 | controller | Monday 08 December 2025  14:31:04 +0000 (0:00:00.028)       0:00:16.280 *******
2025-12-08 14:31:04.596921 | controller | skipping: [instance]
2025-12-08 14:31:04.624014 | controller |
2025-12-08 14:31:04.624031 | controller | TASK [test_deps : Install additional packages] *********************************
2025-12-08 14:31:04.624038 | controller | Monday 08 December 2025  14:31:04 +0000 (0:00:00.027)       0:00:16.307 *******
2025-12-08 14:31:04.624046 | controller | skipping: [instance]
2025-12-08 14:31:45.758743 | controller |
2025-12-08 14:31:45.758774 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-12-08 14:31:45.758782 | controller | Monday 08 December 2025  14:31:04 +0000 (0:00:00.027)       0:00:16.335 *******
2025-12-08 14:31:45.758791 | controller | ok: [instance]
2025-12-08 14:31:46.873741 | controller |
2025-12-08 14:31:46.873768 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-12-08 14:31:46.873776 | controller | Monday 08 December 2025  14:31:45 +0000 (0:00:41.134)       0:00:57.469 *******
2025-12-08 14:31:46.873786 | controller | ok: [instance]
2025-12-08 14:31:47.965875 | controller |
2025-12-08 14:31:47.965901 | controller | TASK [test_deps : Install extra packages] **************************************
2025-12-08 14:31:47.965909 | controller | Monday 08 December 2025  14:31:46 +0000 (0:00:01.115)       0:00:58.584 *******
2025-12-08 14:31:47.965918 | controller | ok: [instance]
2025-12-08 14:31:48.262448 | controller |
2025-12-08 14:31:48.262477 | controller | TASK [Ensure ceph-admin group exists] ******************************************
2025-12-08 14:31:48.262485 | controller | Monday 08 December 2025  14:31:47 +0000 (0:00:01.092)       0:00:59.676 *******
2025-12-08 14:31:48.262495 | controller | changed: [instance]
2025-12-08 14:31:48.731228 | controller |
2025-12-08 14:31:48.731258 | controller | TASK [Ensure ceph-admin user exists] *******************************************
2025-12-08 14:31:48.731265 | controller | Monday 08 December 2025  14:31:48 +0000 (0:00:00.296)       0:00:59.973 *******
2025-12-08 14:31:48.731275 | controller | changed: [instance]
2025-12-08 14:31:48.731856 | controller |
2025-12-08 14:31:48.731869 | controller | PLAY RECAP *********************************************************************
2025-12-08 14:31:48.731875 | controller | instance                   : ok=22   changed=11   unreachable=0    failed=0    skipped=29   rescued=0    ignored=0
2025-12-08 14:31:48.731881 | controller |
2025-12-08 14:31:48.731886 | controller | Monday 08 December 2025  14:31:48 +0000 (0:00:00.467)       0:01:00.440 *******
2025-12-08 14:31:48.731896 | controller | ===============================================================================
2025-12-08 14:31:48.731901 | controller | test_deps : Install selinux python libs -------------------------------- 41.13s
2025-12-08 14:31:48.731906 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.29s
2025-12-08 14:31:48.731911 | controller | test_deps : Install python yaml libs ------------------------------------ 1.12s
2025-12-08 14:31:48.731916 | controller | test_deps : Install extra packages -------------------------------------- 1.09s
2025-12-08 14:31:48.731920 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.08s
2025-12-08 14:31:48.731925 | controller | Gathering Facts --------------------------------------------------------- 0.79s
2025-12-08 14:31:48.731930 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.77s
2025-12-08 14:31:48.731935 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.74s
2025-12-08 14:31:48.731940 | controller | repo_setup : Install repo-setup package --------------------------------- 0.67s
2025-12-08 14:31:48.731945 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.64s
2025-12-08 14:31:48.731950 | controller | repo_setup : Ensure directories are present ----------------------------- 0.55s
2025-12-08 14:31:48.731955 | controller | Ensure ceph-admin user exists ------------------------------------------- 0.47s
2025-12-08 14:31:48.731960 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.42s
2025-12-08 14:31:48.731964 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.40s
2025-12-08 14:31:48.731972 | controller | Ensure ceph-admin group exists ------------------------------------------ 0.30s
2025-12-08 14:31:48.790068 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.28s
2025-12-08 14:31:48.790101 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s
2025-12-08 14:31:48.790108 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.21s
2025-12-08 14:31:48.790115 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.20s
2025-12-08 14:31:48.790120 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.19s
2025-12-08 14:31:48.790129 | controller | INFO     Running default > converge
2025-12-08 14:31:49.195200 | controller |
2025-12-08 14:31:49.195396 | controller | PLAY [Converge] ****************************************************************
2025-12-08 14:31:49.195525 | controller |
2025-12-08 14:31:49.195650 | controller | TASK [Gathering Facts] *********************************************************
2025-12-08 14:31:49.195774 | controller | Monday 08 December 2025  14:31:49 +0000 (0:00:00.013)       0:00:00.013 *******
2025-12-08 14:31:49.931384 | controller | ok: [instance]
2025-12-08 14:31:49.951930 | controller |
2025-12-08 14:31:49.951951 | controller | TASK [cifmw_cephadm : Gather distribution facts if they do not exist] **********
2025-12-08 14:31:49.951959 | controller | Monday 08 December 2025  14:31:49 +0000 (0:00:00.736)       0:00:00.749 *******
2025-12-08 14:31:49.951968 | controller | skipping: [instance]
2025-12-08 14:31:49.972885 | controller |
2025-12-08 14:31:49.972900 | controller | TASK [cifmw_cephadm : Install cephadm package] *********************************
2025-12-08 14:31:49.972907 | controller | Monday 08 December 2025  14:31:49 +0000 (0:00:00.020)       0:00:00.770 *******
2025-12-08 14:31:49.972915 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_cephadm/tasks/install_cephadm.yml for instance
2025-12-08 14:31:49.997940 | controller |
2025-12-08 14:31:49.997957 | controller | TASK [cifmw_cephadm : Enabled ceph Tools Repository] ***************************
2025-12-08 14:31:49.997963 | controller | Monday 08 December 2025  14:31:49 +0000 (0:00:00.020)       0:00:00.791 *******
2025-12-08 14:31:49.997977 | controller | skipping: [instance]
2025-12-08 14:31:54.976230 | controller |
2025-12-08 14:31:54.976260 | controller | TASK [cifmw_cephadm : Install cephadm package] *********************************
2025-12-08 14:31:54.976268 | controller | Monday 08 December 2025  14:31:49 +0000 (0:00:00.025)       0:00:00.816 *******
2025-12-08 14:31:54.976277 | controller | changed: [instance]
2025-12-08 14:31:55.259955 | controller |
2025-12-08 14:31:55.259984 | controller | TASK [cifmw_cephadm : Stat cephadm file] ***************************************
2025-12-08 14:31:55.259997 | controller | Monday 08 December 2025  14:31:54 +0000 (0:00:04.977)       0:00:05.794 *******
2025-12-08 14:31:55.260007 | controller | ok: [instance]
2025-12-08 14:31:55.275962 | controller |
2025-12-08 14:31:55.275981 | controller | TASK [cifmw_cephadm : Fail if cephadm is not available] ************************
2025-12-08 14:31:55.275988 | controller | Monday 08 December 2025  14:31:55 +0000 (0:00:00.283)       0:00:06.078 *******
2025-12-08 14:31:55.275997 | controller | skipping: [instance]
2025-12-08 14:31:55.306219 | controller |
2025-12-08 14:31:55.306239 | controller | TASK [cifmw_cephadm : Set list of found FSIDs] *********************************
2025-12-08 14:31:55.306247 | controller | Monday 08 December 2025  14:31:55 +0000 (0:00:00.016)       0:00:06.094 *******
2025-12-08 14:31:55.306257 | controller | skipping: [instance]
2025-12-08 14:31:55.306505 | controller |
2025-12-08 14:31:55.306518 | controller | TASK [cifmw_cephadm : Fail if >1 FSID was discovered] **************************
2025-12-08 14:31:55.337312 | controller | Monday 08 December 2025  14:31:55 +0000 (0:00:00.030)       0:00:06.125 *******
2025-12-08 14:31:55.337333 | controller | skipping: [instance]
2025-12-08 14:31:55.371019 | controller |
2025-12-08 14:31:55.371037 | controller | TASK [cifmw_cephadm : Set FSID to the discovered value] ************************
2025-12-08 14:31:55.371044 | controller | Monday 08 December 2025  14:31:55 +0000 (0:00:00.030)       0:00:06.156 *******
2025-12-08 14:31:55.371052 | controller | ok: [instance]
2025-12-08 14:31:55.399650 | controller |
2025-12-08 14:31:55.399668 | controller | TASK [cifmw_cephadm : Set random cifmw_cephadm_fsid if no running ceph containers were found] ***
2025-12-08 14:31:55.399675 | controller | Monday 08 December 2025  14:31:55 +0000 (0:00:00.033)       0:00:06.189 *******
2025-12-08 14:31:55.399683 | controller | skipping: [instance]
2025-12-08 14:31:55.431605 | controller |
2025-12-08 14:31:55.431622 | controller | TASK [cifmw_cephadm : Set first monitor IP if it was not passed] ***************
2025-12-08 14:31:55.431629 | controller | Monday 08 December 2025  14:31:55 +0000 (0:00:00.028)       0:00:06.218 *******
2025-12-08 14:31:55.431637 | controller | ok: [instance]
2025-12-08 14:31:55.702298 | controller |
2025-12-08 14:31:55.702327 | controller | TASK [cifmw_cephadm : Ensure cifmw_cephadm_config_home (e.g. /etc/ceph) exists] ***
2025-12-08 14:31:55.702336 | controller | Monday 08 December 2025  14:31:55 +0000 (0:00:00.031)       0:00:06.250 *******
2025-12-08 14:31:55.702346 | controller | changed: [instance]
2025-12-08 14:31:55.896984 | controller |
2025-12-08 14:31:55.897013 | controller | TASK [cifmw_cephadm : Ensure specs directory exists] ***************************
2025-12-08 14:31:55.897021 | controller | Monday 08 December 2025  14:31:55 +0000 (0:00:00.270)       0:00:06.520 *******
2025-12-08 14:31:55.897031 | controller | changed: [instance]
2025-12-08 14:31:56.067711 | controller |
2025-12-08 14:31:56.067739 | controller | TASK [cifmw_cephadm : Stat spec file on ansible host] **************************
2025-12-08 14:31:56.067747 | controller | Monday 08 December 2025  14:31:55 +0000 (0:00:00.194)       0:00:06.715 *******
2025-12-08 14:31:56.067756 | controller | ok: [instance -> localhost]
2025-12-08 14:31:56.084549 | controller |
2025-12-08 14:31:56.084567 | controller | TASK [cifmw_cephadm : Push cifmw_cephadm_spec to bootstrap node if spec file exsits] ***
2025-12-08 14:31:56.084574 | controller | Monday 08 December 2025  14:31:56 +0000 (0:00:00.170)       0:00:06.886 *******
2025-12-08 14:31:56.084583 | controller | skipping: [instance]
2025-12-08 14:31:56.294838 | controller |
2025-12-08 14:31:56.294865 | controller | TASK [cifmw_cephadm : Stat cifmw_cephadm_bootstrap_conf file] ******************
2025-12-08 14:31:56.294874 | controller | Monday 08 December 2025  14:31:56 +0000 (0:00:00.017)       0:00:06.903 *******
2025-12-08 14:31:56.294883 | controller | ok: [instance -> localhost]
2025-12-08 14:31:56.728889 | controller |
2025-12-08 14:31:56.728918 | controller | TASK [cifmw_cephadm : Push it to cifmw_cephadm_assimilate_conf on bootstrap node] ***
2025-12-08 14:31:56.728932 | controller | Monday 08 December 2025  14:31:56 +0000 (0:00:00.209)       0:00:07.113 *******
2025-12-08 14:31:56.728943 | controller | changed: [instance]
2025-12-08 14:31:56.755133 | controller |
2025-12-08 14:31:56.755152 | controller | TASK [cifmw_cephadm : Add spec to necessary file list when using spec to bootstrap] ***
2025-12-08 14:31:56.755160 | controller | Monday 08 December 2025  14:31:56 +0000 (0:00:00.434)       0:00:07.547 *******
2025-12-08 14:31:56.755189 | controller | skipping: [instance]
2025-12-08 14:31:57.149217 | controller |
2025-12-08 14:31:57.149246 | controller | TASK [cifmw_cephadm : Stat necessary files to bootstrap with cephadm] **********
2025-12-08 14:31:57.149254 | controller | Monday 08 December 2025  14:31:56 +0000 (0:00:00.026)       0:00:07.573 *******
2025-12-08 14:31:57.149264 | controller | ok: [instance] => (item=/home/ceph-admin/.ssh/id_rsa)
2025-12-08 14:31:57.186268 | controller | ok: [instance] => (item=/home/ceph-admin/.ssh/id_rsa.pub)
2025-12-08 14:31:57.186303 | controller |
2025-12-08 14:31:57.186311 | controller | TASK [cifmw_cephadm : Fail if necessary files are missing] *********************
2025-12-08 14:31:57.186318 | controller | Monday 08 December 2025  14:31:57 +0000 (0:00:00.393)       0:00:07.967 *******
2025-12-08 14:31:57.186331 | controller | skipping: [instance] => (item={'changed': False, 'stat': {'exists': True, 'path': '/home/ceph-admin/.ssh/id_rsa', 'mode': '0600', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1002, 'gid': 1002, 'size': 2622, 'inode': 163586643, 'dev': 64513, 'nlink': 1, 'atime': 1765204308.6866121, 'mtime': 1765204308.6816123, 'ctime': 1765204308.6816123, 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': False, 'xgrp': False, 'woth': False, 'roth': False, 'xoth': False, 'isuid': False, 'isgid': False, 'blocks': 8, 'block_size': 4096, 'device_type': 0, 'readable': True, 'writeable': True, 'executable': False, 'pw_name': 'ceph-admin', 'gr_name': 'ceph-admin', 'checksum': '07b9fc64db00c925903767d1150f6de78ee2147c', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '3672309874', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/ceph-admin/.ssh/id_rsa', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': '/home/ceph-admin/.ssh/id_rsa', 'ansible_loop_var': 'item'})
2025-12-08 14:31:57.186374 | controller | skipping: [instance] => (item={'changed': False, 'stat': {'exists': True, 'path': '/home/ceph-admin/.ssh/id_rsa.pub', 'mode': '0600', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1002, 'gid': 1002, 'size': 587, 'inode': 163586644, 'dev': 64513, 'nlink': 1, 'atime': 1765204308.6866121, 'mtime': 1765204308.6816123, 'ctime': 1765204308.6816123, 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': False, 'xgrp': False, 'woth': False, 'roth': False, 'xoth': False, 'isuid': False, 'isgid': False, 'blocks': 8, 'block_size': 4096, 'device_type': 0, 'readable': True, 'writeable': True, 'executable': False, 'pw_name': 'ceph-admin', 'gr_name': 'ceph-admin', 'checksum': 'e085badd8f732aebb89ddc38e652996ebbffb706', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '1745412662', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/ceph-admin/.ssh/id_rsa.pub', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': '/home/ceph-admin/.ssh/id_rsa.pub', 'ansible_loop_var': 'item'})
2025-12-08 14:31:57.186382 | controller | skipping: [instance]
2025-12-08 14:31:57.186390 | controller |
2025-12-08 14:31:57.186413 | controller | TASK [cifmw_cephadm : Stat pre ceph conf file in case we should bootrap with it] ***
2025-12-08 14:31:57.186538 | controller | Monday 08 December 2025  14:31:57 +0000 (0:00:00.037)       0:00:08.004 *******
2025-12-08 14:31:57.393930 | controller | ok: [instance]
2025-12-08 14:31:57.418013 | controller |
2025-12-08 14:31:57.418032 | controller | TASK [cifmw_cephadm : If cephadm bootstrap was not run report the reason] ******
2025-12-08 14:31:57.418044 | controller | Monday 08 December 2025  14:31:57 +0000 (0:00:00.207)       0:00:08.212 *******
2025-12-08 14:31:57.418053 | controller | skipping: [instance]
2025-12-08 14:31:57.684090 | controller |
2025-12-08 14:31:57.684116 | controller | TASK [Mock ceph_mon_dump command] **********************************************
2025-12-08 14:31:57.684123 | controller | Monday 08 December 2025  14:31:57 +0000 (0:00:00.024)       0:00:08.236 *******
2025-12-08 14:31:57.684132 | controller | changed: [instance -> localhost]
2025-12-08 14:31:57.701240 | controller |
2025-12-08 14:31:57.701274 | controller | TASK [Mock ceph_keys_module_output] ********************************************
2025-12-08 14:31:57.701282 | controller | Monday 08 December 2025  14:31:57 +0000 (0:00:00.265)       0:00:08.502 *******
2025-12-08 14:31:57.701291 | controller | ok: [instance]
2025-12-08 14:31:57.701371 | controller |
2025-12-08 14:31:57.701391 | controller | TASK [cifmw_cephadm : Get ceph_cli] ********************************************
2025-12-08 14:31:57.701403 | controller | Monday 08 December 2025  14:31:57 +0000 (0:00:00.017)       0:00:08.519 *******
2025-12-08 14:31:57.721032 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_cephadm/tasks/ceph_cli.yml for instance
2025-12-08 14:31:57.756862 | controller |
2025-12-08 14:31:57.756880 | controller | TASK [cifmw_cephadm : Set ceph CLI] ********************************************
2025-12-08 14:31:57.756887 | controller | Monday 08 December 2025  14:31:57 +0000 (0:00:00.019)       0:00:08.539 *******
2025-12-08 14:31:57.756895 | controller | ok: [instance]
2025-12-08 14:31:57.783146 | controller |
2025-12-08 14:31:57.783187 | controller | TASK [cifmw_cephadm : Wait for the expected number of monitors to be running] ***
2025-12-08 14:31:57.783200 | controller | Monday 08 December 2025  14:31:57 +0000 (0:00:00.035)       0:00:08.575 *******
2025-12-08 14:31:57.783212 | controller | skipping: [instance]
2025-12-08 14:31:57.816552 | controller |
2025-12-08 14:31:57.816574 | controller | TASK [cifmw_cephadm : Extract mons_json] ***************************************
2025-12-08 14:31:57.816583 | controller | Monday 08 December 2025  14:31:57 +0000 (0:00:00.026)       0:00:08.601 *******
2025-12-08 14:31:57.816593 | controller | ok: [instance]
2025-12-08 14:31:57.816767 | controller |
2025-12-08 14:31:57.816936 | controller | TASK [cifmw_cephadm : Build mons_list] *****************************************
2025-12-08 14:31:57.817101 | controller | Monday 08 December 2025  14:31:57 +0000 (0:00:00.033)       0:00:08.635 *******
2025-12-08 14:31:57.859278 | controller | ok: [instance] => (item=[{'type': 'v2', 'addr': '172.16.11.241:3300', 'nonce': 0}, {'type': 'v1', 'addr': '172.16.11.241:6789', 'nonce': 0}])
2025-12-08 14:31:57.887003 | controller | ok: [instance] => (item=[{'type': 'v2', 'addr': '172.16.11.176:3300', 'nonce': 0}, {'type': 'v1', 'addr': '172.16.11.176:6789', 'nonce': 0}])
2025-12-08 14:31:57.887025 | controller | ok: [instance] => (item=[{'type': 'v2', 'addr': '172.16.11.82:3300', 'nonce': 0}, {'type': 'v1', 'addr': '172.16.11.82:6789', 'nonce': 0}])
2025-12-08 14:31:57.887032 | controller |
2025-12-08 14:31:57.887039 | controller | TASK [cifmw_cephadm : Set cifmw_ceph_client_external_cluster_mon_ips from mons_list] ***
2025-12-08 14:31:57.887045 | controller | Monday 08 December 2025  14:31:57 +0000 (0:00:00.042)       0:00:08.677 *******
2025-12-08 14:31:57.887054 | controller | ok: [instance]
2025-12-08 14:31:57.919211 | controller |
2025-12-08 14:31:57.919230 | controller | TASK [cifmw_cephadm : Ensure cifmw_ceph_client_vars path exists] ***************
2025-12-08 14:31:57.919237 | controller | Monday 08 December 2025  14:31:57 +0000 (0:00:00.027)       0:00:08.705 *******
2025-12-08 14:31:57.919245 | controller | skipping: [instance]
2025-12-08 14:31:57.919417 | controller |
2025-12-08 14:31:57.919429 | controller | TASK [cifmw_cephadm : Save cifmw_ceph_client_vars file] ************************
2025-12-08 14:31:57.919438 | controller | Monday 08 December 2025  14:31:57 +0000 (0:00:00.032)       0:00:08.737 *******
2025-12-08 14:31:57.957561 | controller | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: ansible.errors.AnsibleUndefinedVariable: 'cifmw_ceph_s3_glance_user' is undefined. 'cifmw_ceph_s3_glance_user' is undefined
2025-12-08 14:31:57.958143 | controller | fatal: [instance -> localhost]: FAILED! => changed=false
2025-12-08 14:31:57.960212 | controller |   msg: 'AnsibleUndefinedVariable: ''cifmw_ceph_s3_glance_user'' is undefined. ''cifmw_ceph_s3_glance_user'' is undefined'
2025-12-08 14:31:57.960237 | controller |
2025-12-08 14:31:57.962253 | controller | PLAY RECAP *********************************************************************
2025-12-08 14:31:57.962278 | controller | instance                   : ok=20   changed=5    unreachable=0    failed=1    skipped=12   rescued=0    ignored=0
2025-12-08 14:31:57.962451 | controller |
2025-12-08 14:31:57.962468 | controller | Monday 08 December 2025  14:31:57 +0000 (0:00:00.041)       0:00:08.779 *******
2025-12-08 14:31:57.962476 | controller | ===============================================================================
2025-12-08 14:31:57.962482 | controller | cifmw_cephadm : Install cephadm package --------------------------------- 4.98s
2025-12-08 14:31:57.962487 | controller | Gathering Facts --------------------------------------------------------- 0.74s
2025-12-08 14:31:57.962492 | controller | cifmw_cephadm : Push it to cifmw_cephadm_assimilate_conf on bootstrap node --- 0.43s
2025-12-08 14:31:57.962498 | controller | cifmw_cephadm : Stat necessary files to bootstrap with cephadm ---------- 0.39s
2025-12-08 14:31:57.962508 | controller | cifmw_cephadm : Stat cephadm file --------------------------------------- 0.28s
2025-12-08 14:31:57.962514 | controller | cifmw_cephadm : Ensure cifmw_cephadm_config_home (e.g. /etc/ceph) exists --- 0.27s
2025-12-08 14:31:57.962520 | controller | Mock ceph_mon_dump command ---------------------------------------------- 0.27s
2025-12-08 14:31:57.962525 | controller | cifmw_cephadm : Stat cifmw_cephadm_bootstrap_conf file ------------------ 0.21s
2025-12-08 14:31:57.962530 | controller | cifmw_cephadm : Stat pre ceph conf file in case we should bootrap with it --- 0.21s
2025-12-08 14:31:57.962536 | controller | cifmw_cephadm : Ensure specs directory exists --------------------------- 0.19s
2025-12-08 14:31:57.962541 | controller | cifmw_cephadm : Stat spec file on ansible host -------------------------- 0.17s
2025-12-08 14:31:57.962546 | controller | cifmw_cephadm : Build mons_list ----------------------------------------- 0.04s
2025-12-08 14:31:57.962552 | controller | cifmw_cephadm : Save cifmw_ceph_client_vars file ------------------------ 0.04s
2025-12-08 14:31:57.962557 | controller | cifmw_cephadm : Fail if necessary files are missing --------------------- 0.04s
2025-12-08 14:31:57.962563 | controller | cifmw_cephadm : Set ceph CLI -------------------------------------------- 0.04s
2025-12-08 14:31:57.962568 | controller | cifmw_cephadm : Set FSID to the discovered value ------------------------ 0.03s
2025-12-08 14:31:57.962573 | controller | cifmw_cephadm : Extract mons_json --------------------------------------- 0.03s
2025-12-08 14:31:57.962579 | controller | cifmw_cephadm : Ensure cifmw_ceph_client_vars path exists --------------- 0.03s
2025-12-08 14:31:57.962587 | controller | cifmw_cephadm : Set first monitor IP if it was not passed --------------- 0.03s
2025-12-08 14:31:58.012534 | controller | cifmw_cephadm : Fail if >1 FSID was discovered -------------------------- 0.03s
2025-12-08 14:31:58.012561 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/cifmw_cephadm/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_cephadm/molecule/default/converge.yml']
2025-12-08 14:31:58.013205 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-12-08 14:31:58.021441 | controller | INFO     Running default > cleanup
2025-12-08 14:31:58.021848 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-12-08 14:31:58.029057 | controller | INFO     Running default > destroy
2025-12-08 14:31:58.029447 | controller | WARNING  Skipping, instances are delegated.
2025-12-08 14:31:58.029801 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-12-08 14:31:58.031339 | controller | INFO     Writing /tmp/report.html report.
2025-12-08 14:31:58.324767 | controller | ERROR
2025-12-08 14:31:58.325081 | controller | {
2025-12-08 14:31:58.325115 | controller |   "delta": "0:01:27.588835",
2025-12-08 14:31:58.325138 | controller |   "end": "2025-12-08 14:31:58.072086",
2025-12-08 14:31:58.325156 | controller |   "msg": "non-zero return code",
2025-12-08 14:31:58.325173 | controller |   "rc": 1,
2025-12-08 14:31:58.325189 | controller |   "start": "2025-12-08 14:30:30.483251"
2025-12-08 14:31:58.325205 | controller | }
failure
2025-12-08 14:31:58.329575 | 
2025-12-08 14:31:58.329635 | PLAY RECAP
2025-12-08 14:31:58.329678 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-12-08 14:31:58.329700 | 
2025-12-08 14:31:58.388873 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-08 14:31:58.389679 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-08 14:31:58.890640 | 
2025-12-08 14:31:58.890755 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-08 14:31:58.910419 | 
2025-12-08 14:31:58.910487 | TASK [Filter out host if needed]
2025-12-08 14:31:58.918126 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-08 14:31:58.922145 | 
2025-12-08 14:31:58.922207 | TASK [Ensure file is present]
2025-12-08 14:31:59.202907 | controller | ok
2025-12-08 14:31:59.209111 | 
2025-12-08 14:31:59.209191 | TASK [Manage molecule report file]
2025-12-08 14:31:59.732056 | controller | changed
2025-12-08 14:31:59.737345 | 
2025-12-08 14:31:59.737403 | TASK [Check if we get ci-framework-data basedir]
2025-12-08 14:31:59.907187 | controller | ok
2025-12-08 14:31:59.912183 | 
2025-12-08 14:31:59.912246 | TASK [Create ci-framework-data log directory for zuul]
2025-12-08 14:32:00.193495 | controller | changed
2025-12-08 14:32:00.198158 | 
2025-12-08 14:32:00.198217 | TASK [Copy ci-framework interesting files]
2025-12-08 14:32:00.376318 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-12-08 14:32:00.719786 | controller | changed
2025-12-08 14:32:00.724253 | 
2025-12-08 14:32:00.724310 | TASK [Get SELinux listing]
2025-12-08 14:32:01.247981 | controller | changed
2025-12-08 14:32:01.253414 | 
2025-12-08 14:32:01.253476 | TASK [Generate log index]
2025-12-08 14:32:01.808793 | controller | changed
2025-12-08 14:32:01.813501 | 
2025-12-08 14:32:01.813566 | TASK [Get some env related data]
2025-12-08 14:32:02.316989 | controller | /home/zuul/.local/bin/ansible
2025-12-08 14:32:02.870254 | controller | changed
2025-12-08 14:32:02.875078 | 
2025-12-08 14:32:02.875140 | TASK [Generate list of logs to collect in home directory]
2025-12-08 14:32:03.135197 | controller | ok: All paths examined
2025-12-08 14:32:03.139743 | 
2025-12-08 14:32:03.139802 | LOOP [Copy logs from home directory]
2025-12-08 14:32:03.412094 | controller | changed:
2025-12-08 14:32:03.412250 | controller | {
2025-12-08 14:32:03.412276 | controller |   "atime": 1765204213.0865922,
2025-12-08 14:32:03.412295 | controller |   "ctime": 1765204228.4038072,
2025-12-08 14:32:03.412313 | controller |   "dev": 64513,
2025-12-08 14:32:03.412329 | controller |   "gid": 1000,
2025-12-08 14:32:03.412346 | controller |   "gr_name": "zuul",
2025-12-08 14:32:03.412362 | controller |   "inode": 4329455,
2025-12-08 14:32:03.412376 | controller |   "isblk": false,
2025-12-08 14:32:03.412391 | controller |   "ischr": false,
2025-12-08 14:32:03.412405 | controller |   "isdir": false,
2025-12-08 14:32:03.412419 | controller |   "isfifo": false,
2025-12-08 14:32:03.412434 | controller |   "isgid": false,
2025-12-08 14:32:03.412447 | controller |   "islnk": false,
2025-12-08 14:32:03.412462 | controller |   "isreg": true,
2025-12-08 14:32:03.412477 | controller |   "issock": false,
2025-12-08 14:32:03.412491 | controller |   "isuid": false,
2025-12-08 14:32:03.412504 | controller |   "mode": "0644",
2025-12-08 14:32:03.412518 | controller |   "mtime": 1765204228.4038072,
2025-12-08 14:32:03.412532 | controller |   "nlink": 1,
2025-12-08 14:32:03.412546 | controller |   "path": "/home/zuul/ansible.log",
2025-12-08 14:32:03.412565 | controller |   "pw_name": "zuul",
2025-12-08 14:32:03.412580 | controller |   "rgrp": true,
2025-12-08 14:32:03.412594 | controller |   "roth": true,
2025-12-08 14:32:03.412607 | controller |   "rusr": true,
2025-12-08 14:32:03.412630 | controller |   "size": 6749,
2025-12-08 14:32:03.412646 | controller |   "uid": 1000,
2025-12-08 14:32:03.412660 | controller |   "wgrp": false,
2025-12-08 14:32:03.412674 | controller |   "woth": false,
2025-12-08 14:32:03.412690 | controller |   "wusr": true,
2025-12-08 14:32:03.412704 | controller |   "xgrp": false,
2025-12-08 14:32:03.412717 | controller |   "xoth": false,
2025-12-08 14:32:03.412730 | controller |   "xusr": false
2025-12-08 14:32:03.412743 | controller | }
2025-12-08 14:32:03.446434 | 
2025-12-08 14:32:03.446522 | TASK [Copy crio stats log file]
2025-12-08 14:32:03.460986 | controller | skipping: Conditional result was False
2025-12-08 14:32:03.466668 | 
2025-12-08 14:32:03.466761 | TASK [Get SELinux related data]
2025-12-08 14:32:03.654108 | controller | <no matches>
2025-12-08 14:32:03.990020 | controller | ERROR
2025-12-08 14:32:03.990135 | controller | {
2025-12-08 14:32:03.990162 | controller |   "delta": "0:00:00.008180",
2025-12-08 14:32:03.990182 | controller |   "end": "2025-12-08 14:32:03.655576",
2025-12-08 14:32:03.990197 | controller |   "msg": "non-zero return code",
2025-12-08 14:32:03.990212 | controller |   "rc": 1,
2025-12-08 14:32:03.990227 | controller |   "start": "2025-12-08 14:32:03.647396"
2025-12-08 14:32:03.990240 | controller | }
2025-12-08 14:32:03.990259 | controller | ERROR: Ignoring Errors
2025-12-08 14:32:03.994880 | 
2025-12-08 14:32:03.994959 | TASK [Create system configuration directory]
2025-12-08 14:32:04.166350 | controller | changed
2025-12-08 14:32:04.170735 | 
2025-12-08 14:32:04.170794 | TASK [Get some of the system configurations]
2025-12-08 14:32:04.697145 | controller | changed
2025-12-08 14:32:04.702351 | 
2025-12-08 14:32:04.702415 | TASK [Copy generated documentation if available]
2025-12-08 14:32:04.727270 | controller | skipping: Conditional result was False
2025-12-08 14:32:04.732409 | 
2025-12-08 14:32:04.732467 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-08 14:32:04.755376 | controller | skipping: Conditional result was False
2025-12-08 14:32:04.760788 | 
2025-12-08 14:32:04.760845 | TASK [Compress logs bigger than 2MB]
2025-12-08 14:32:05.286846 | controller | changed
2025-12-08 14:32:05.291874 | 
2025-12-08 14:32:05.291968 | TASK [Copy files from workspace on node]
2025-12-08 14:32:05.326638 | controller | ok
2025-12-08 14:32:05.348054 | 
2025-12-08 14:32:05.348118 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-08 14:32:05.371119 | controller | skipping: Conditional result was False
2025-12-08 14:32:05.376513 | 
2025-12-08 14:32:05.376575 | TASK [fetch-output : Set log path for single node]
2025-12-08 14:32:05.405510 | controller | ok
2025-12-08 14:32:05.410024 | 
2025-12-08 14:32:05.410084 | LOOP [fetch-output : Ensure local output dirs]
2025-12-08 14:32:05.621488 | controller -> localhost | ok: "/var/lib/zuul/builds/2ef2cb91a9174df0a982e58f2662d09e/work/logs"
2025-12-08 14:32:05.621724 | controller -> localhost | changed: All items complete
2025-12-08 14:32:05.621752 | 
2025-12-08 14:32:05.789146 | controller -> localhost | changed: "/var/lib/zuul/builds/2ef2cb91a9174df0a982e58f2662d09e/work/artifacts"
2025-12-08 14:32:05.958798 | controller -> localhost | changed: "/var/lib/zuul/builds/2ef2cb91a9174df0a982e58f2662d09e/work/docs"
2025-12-08 14:32:05.970750 | 
2025-12-08 14:32:05.970866 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-08 14:32:06.462123 | controller | changed:
2025-12-08 14:32:06.462336 | controller | .d..t...... ./
2025-12-08 14:32:06.462365 | controller | >f+++++++++ README.html
2025-12-08 14:32:06.462384 | controller | >f+++++++++ ansible-execution.log
2025-12-08 14:32:06.462403 | controller | >f+++++++++ ansible.log
2025-12-08 14:32:06.462420 | controller | >f+++++++++ dmesg.log
2025-12-08 14:32:06.462438 | controller | >f+++++++++ installed-pkgs.log
2025-12-08 14:32:06.462457 | controller | >f+++++++++ python.log
2025-12-08 14:32:06.462474 | controller | >f+++++++++ registries.conf
2025-12-08 14:32:06.462490 | controller | >f+++++++++ report.html
2025-12-08 14:32:06.462507 | controller | >f+++++++++ selinux-denials.log
2025-12-08 14:32:06.462522 | controller | >f+++++++++ selinux-listing.log
2025-12-08 14:32:06.462537 | controller | cd+++++++++ ci-framework-data/
2025-12-08 14:32:06.462552 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-08 14:32:06.462565 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-08 14:32:06.462580 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-08 14:32:06.462594 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-08 14:32:06.462608 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-08 14:32:06.462635 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-12-08 14:32:06.462655 | controller | cd+++++++++ registries.conf.d/
2025-12-08 14:32:06.462673 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-08 14:32:06.462688 | controller | cd+++++++++ system-config/
2025-12-08 14:32:06.462702 | controller | cd+++++++++ system-config/libvirt/
2025-12-08 14:32:06.462717 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-08 14:32:06.462731 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-08 14:32:06.798079 | controller | changed: .d..t...... ./
2025-12-08 14:32:07.131768 | controller | changed: .d..t...... ./
2025-12-08 14:32:07.153776 | 
2025-12-08 14:32:07.153892 | TASK [Return artifact to Zuul]
2025-12-08 14:32:07.182262 | controller | ok
2025-12-08 14:32:07.199822 | 
2025-12-08 14:32:07.199881 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-08 14:32:07.200005 | 
2025-12-08 14:32:07.200039 | PLAY RECAP
2025-12-08 14:32:07.200078 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-12-08 14:32:07.200099 | 
2025-12-08 14:32:07.289647 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-08 14:32:07.290371 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-08 14:32:07.777816 | 
2025-12-08 14:32:07.777924 | PLAY [all]
2025-12-08 14:32:07.794749 | 
2025-12-08 14:32:07.794817 | TASK [include_role : fetch-output]
2025-12-08 14:32:07.824020 | controller | ok
2025-12-08 14:32:07.839864 | 
2025-12-08 14:32:07.839957 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-08 14:32:07.874056 | controller | skipping: Conditional result was False
2025-12-08 14:32:07.879324 | 
2025-12-08 14:32:07.879400 | TASK [fetch-output : Set log path for single node]
2025-12-08 14:32:07.907151 | controller | ok
2025-12-08 14:32:07.912027 | 
2025-12-08 14:32:07.912100 | LOOP [fetch-output : Ensure local output dirs]
2025-12-08 14:32:08.210804 | controller -> localhost | ok: "/var/lib/zuul/builds/2ef2cb91a9174df0a982e58f2662d09e/work/logs"
2025-12-08 14:32:08.384139 | controller -> localhost | ok: "/var/lib/zuul/builds/2ef2cb91a9174df0a982e58f2662d09e/work/artifacts"
2025-12-08 14:32:08.550825 | controller -> localhost | ok: "/var/lib/zuul/builds/2ef2cb91a9174df0a982e58f2662d09e/work/docs"
2025-12-08 14:32:08.559477 | 
2025-12-08 14:32:08.559573 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-08 14:32:09.045466 | controller | ok
2025-12-08 14:32:09.045712 | controller | ok: All items complete
2025-12-08 14:32:09.045743 | 
2025-12-08 14:32:09.414871 | controller | ok
2025-12-08 14:32:09.784049 | controller | ok
2025-12-08 14:32:09.800945 | 
2025-12-08 14:32:09.801141 | TASK [include_role : fetch-output-openshift]
2025-12-08 14:32:09.827789 | controller | skipping: Conditional result was False
2025-12-08 14:32:09.834028 | 
2025-12-08 14:32:09.834100 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-08 14:32:10.183248 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.009207
2025-12-08 14:32:10.371867 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008996
2025-12-08 14:32:10.403145 | 
2025-12-08 14:32:10.403284 | PLAY [all]
2025-12-08 14:32:10.417299 | 
2025-12-08 14:32:10.417373 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-08 14:32:10.855491 | controller | changed
2025-12-08 14:32:10.876643 | 
2025-12-08 14:32:10.876825 | PLAY RECAP
2025-12-08 14:32:10.876878 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-08 14:32:10.876902 | 
2025-12-08 14:32:10.956748 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-08 14:32:10.957796 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-08 14:32:11.489346 | 
2025-12-08 14:32:11.489452 | PLAY [localhost]
2025-12-08 14:32:11.505356 | 
2025-12-08 14:32:11.505433 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-08 14:32:11.807436 | localhost | changed
2025-12-08 14:32:11.812002 | 
2025-12-08 14:32:11.812084 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-08 14:32:11.840251 | localhost | ok
2025-12-08 14:32:11.847562 | 
2025-12-08 14:32:11.847636 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-08 14:32:12.127386 | localhost | changed
2025-12-08 14:32:12.132701 | 
2025-12-08 14:32:12.132769 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-08 14:32:12.635338 | localhost | changed
2025-12-08 14:32:12.639792 | 
2025-12-08 14:32:12.639854 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-08 14:32:12.950816 | localhost | Identity added: /var/lib/zuul/builds/2ef2cb91a9174df0a982e58f2662d09e/work/tmp/ansible.0g5737ce (/var/lib/zuul/builds/2ef2cb91a9174df0a982e58f2662d09e/work/tmp/ansible.0g5737ce)
2025-12-08 14:32:12.951028 | localhost | ok: Runtime: 0:00:00.007224
2025-12-08 14:32:12.955039 | 
2025-12-08 14:32:12.955107 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-08 14:32:13.154872 | localhost | ok: Runtime: 0:00:00.004401
2025-12-08 14:32:13.159310 | 
2025-12-08 14:32:13.159378 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-08 14:32:13.207445 | localhost | changed
2025-12-08 14:32:13.212448 | 
2025-12-08 14:32:13.212512 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-08 14:32:13.502538 | localhost | changed
2025-12-08 14:32:13.521567 | 
2025-12-08 14:32:13.521649 | PLAY [localhost]
2025-12-08 14:32:13.532736 | 
2025-12-08 14:32:13.532804 | TASK [Generate bulk log download script]
2025-12-08 14:32:13.551174 | localhost | ok
2025-12-08 14:32:13.561799 | 
2025-12-08 14:32:13.561864 | TASK [local-log-download : Check API endpoint is defined]
2025-12-08 14:32:13.589155 | localhost | ok: All assertions passed
2025-12-08 14:32:13.593450 | 
2025-12-08 14:32:13.593509 | TASK [local-log-download : Create download script]
2025-12-08 14:32:13.920591 | localhost -> localhost | changed
2025-12-08 14:32:13.929044 | 
2025-12-08 14:32:13.929105 | TASK [Register quick-download link]
2025-12-08 14:32:13.945817 | localhost | ok
2025-12-08 14:32:13.979323 | 
2025-12-08 14:32:13.979381 | PLAY [logserver.rdoproject.org]
2025-12-08 14:32:13.987869 | 
2025-12-08 14:32:13.987940 | TASK [Set zuul-log-path fact]
2025-12-08 14:32:14.003680 | logserver.rdoproject.org | ok
2025-12-08 14:32:14.012012 | 
2025-12-08 14:32:14.012078 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-08 14:32:14.028523 | logserver.rdoproject.org | ok
2025-12-08 14:32:14.033293 | 
2025-12-08 14:32:14.033352 | TASK [upload-logs : Create log directories]
2025-12-08 14:32:15.526300 | logserver.rdoproject.org | changed
2025-12-08 14:32:15.529379 | 
2025-12-08 14:32:15.529437 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-08 14:32:15.735058 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004355
2025-12-08 14:32:15.739237 | 
2025-12-08 14:32:15.739296 | TASK [upload-logs : Upload logs to log server]
2025-12-08 14:32:17.629937 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-08 14:32:17.632786 | 
2025-12-08 14:32:17.632861 | LOOP [upload-logs : Compress console log and json output]
2025-12-08 14:32:17.667709 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-08 14:32:17.674949 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-08 14:32:17.679454 | 
2025-12-08 14:32:17.679530 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-08 14:32:17.711124 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-08 14:32:17.711328 | 
2025-12-08 14:32:17.714768 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-08 14:32:17.726044 | 
2025-12-08 14:32:17.726121 | LOOP [upload-logs : Upload console log and json output]