2025-12-09 10:14:28.203150 | Job console starting...
2025-12-09 10:14:28.416930 | Updating repositories
2025-12-09 10:14:29.314855 | Preparing job workspace
2025-12-09 10:14:36.746624 | Running Ansible setup...
2025-12-09 10:14:39.651241 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-09 10:14:40.168766 | 
2025-12-09 10:14:40.168875 | PLAY [localhost]
2025-12-09 10:14:40.177358 | 
2025-12-09 10:14:40.177431 | TASK [Gathering Facts]
2025-12-09 10:14:41.023176 | localhost | ok
2025-12-09 10:14:41.036586 | 
2025-12-09 10:14:41.036696 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-09 10:14:41.375151 | localhost -> localhost | changed
2025-12-09 10:14:41.381031 | 
2025-12-09 10:14:41.381132 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-09 10:14:42.147342 | localhost -> localhost | changed
2025-12-09 10:14:42.155772 | 
2025-12-09 10:14:42.155844 | TASK [Setup log path fact]
2025-12-09 10:14:42.173540 | localhost | ok
2025-12-09 10:14:42.184492 | 
2025-12-09 10:14:42.184560 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-09 10:14:42.212173 | localhost | ok
2025-12-09 10:14:42.219379 | 
2025-12-09 10:14:42.219446 | TASK [emit-job-header : Print job information]
2025-12-09 10:14:42.247247 | # Job Information
2025-12-09 10:14:42.247373 | Ansible Version: 2.15.12
2025-12-09 10:14:42.247402 | Job: cifmw-molecule-cifmw_cephadm
2025-12-09 10:14:42.247423 | Pipeline: github-check
2025-12-09 10:14:42.247442 | Executor: ibm-bm3-ze.softwarefactory-project.io
2025-12-09 10:14:42.247460 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3169
2025-12-09 10:14:42.247490 | Log URL (when completed): https://logserver.rdoproject.org/4b5/rdoproject.org/4b56be4badf14eafa082909e6c86026e/
2025-12-09 10:14:42.247511 | Event ID: b3b40a20-d4e7-11f0-80e2-3e4e94888256
2025-12-09 10:14:42.250949 | 
2025-12-09 10:14:42.251013 | LOOP [emit-job-header : Print node information]
2025-12-09 10:14:42.341718 | localhost | ok:
2025-12-09 10:14:42.341976 | localhost | # Node Information
2025-12-09 10:14:42.342014 | localhost | Inventory Hostname: controller
2025-12-09 10:14:42.342043 | localhost | Hostname: np0005551674
2025-12-09 10:14:42.342067 | localhost | Username: zuul
2025-12-09 10:14:42.342089 | localhost | Distro: CentOS 9
2025-12-09 10:14:42.342107 | localhost | Provider: ibm-bm3-nodepool
2025-12-09 10:14:42.342125 | localhost | Region: regionOne
2025-12-09 10:14:42.342143 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-12-09 10:14:42.342159 | localhost | Product Name: OpenStack Compute
2025-12-09 10:14:42.342176 | localhost | Interface IP: 192.168.25.29
2025-12-09 10:14:42.363970 | 
2025-12-09 10:14:42.364055 | PLAY [all]
2025-12-09 10:14:42.370121 | 
2025-12-09 10:14:42.370183 | TASK [Gather network facts]
2025-12-09 10:14:42.714676 | controller | ok
2025-12-09 10:14:42.728689 | 
2025-12-09 10:14:42.728760 | TASK [include_role : start-zuul-console]
2025-12-09 10:14:42.746917 | controller | ok
2025-12-09 10:14:42.759175 | 
2025-12-09 10:14:42.759243 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-09 10:14:43.061155 | controller | ok
2025-12-09 10:14:43.069565 | 
2025-12-09 10:14:43.069645 | TASK [include_role : add-build-sshkey]
2025-12-09 10:14:43.088243 | controller | ok
2025-12-09 10:14:43.101368 | 
2025-12-09 10:14:43.101438 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-09 10:14:43.297439 | controller -> localhost | ok
2025-12-09 10:14:43.303068 | 
2025-12-09 10:14:43.303137 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-09 10:14:43.321603 | controller | ok
2025-12-09 10:14:43.335043 | controller | included: /var/lib/zuul/builds/4b56be4badf14eafa082909e6c86026e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-09 10:14:43.340730 | 
2025-12-09 10:14:43.340794 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-09 10:14:44.052498 | controller -> localhost | Generating public/private rsa key pair.
2025-12-09 10:14:44.052801 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4b56be4badf14eafa082909e6c86026e/work/4b56be4badf14eafa082909e6c86026e_id_rsa.
2025-12-09 10:14:44.052848 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4b56be4badf14eafa082909e6c86026e/work/4b56be4badf14eafa082909e6c86026e_id_rsa.pub.
2025-12-09 10:14:44.052874 | controller -> localhost | The key fingerprint is:
2025-12-09 10:14:44.052896 | controller -> localhost | SHA256:wA3dus7W5npaM5TE6AKOA8n5s3YePWU0WrzroZYehKA zuul-build-sshkey
2025-12-09 10:14:44.052918 | controller -> localhost | The key's randomart image is:
2025-12-09 10:14:44.052937 | controller -> localhost | +---[RSA 3072]----+
2025-12-09 10:14:44.052955 | controller -> localhost | |      .. .       |
2025-12-09 10:14:44.052974 | controller -> localhost | |...  . ooo.      |
2025-12-09 10:14:44.052992 | controller -> localhost | |oo .. o o*o      |
2025-12-09 10:14:44.053010 | controller -> localhost | | .oo...o=.o.     |
2025-12-09 10:14:44.053029 | controller -> localhost | | Eoo...oS=o      |
2025-12-09 10:14:44.053049 | controller -> localhost | |   .o o.+..      |
2025-12-09 10:14:44.053067 | controller -> localhost | |   o o *.++      |
2025-12-09 10:14:44.053088 | controller -> localhost | |  . o .oO.=o     |
2025-12-09 10:14:44.053107 | controller -> localhost | |     .o+oB.      |
2025-12-09 10:14:44.053125 | controller -> localhost | +----[SHA256]-----+
2025-12-09 10:14:44.053191 | controller -> localhost | ok: Runtime: 0:00:00.376143
2025-12-09 10:14:44.059774 | 
2025-12-09 10:14:44.059853 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-09 10:14:44.082569 | controller | ok
2025-12-09 10:14:44.092584 | controller | included: /var/lib/zuul/builds/4b56be4badf14eafa082909e6c86026e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-09 10:14:44.100769 | 
2025-12-09 10:14:44.100861 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-09 10:14:44.114569 | controller | skipping: Conditional result was False
2025-12-09 10:14:44.120289 | 
2025-12-09 10:14:44.120361 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-09 10:14:44.483785 | controller | changed
2025-12-09 10:14:44.488569 | 
2025-12-09 10:14:44.488635 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-09 10:14:44.679199 | controller | ok
2025-12-09 10:14:44.684359 | 
2025-12-09 10:14:44.684439 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-09 10:14:45.209828 | controller | changed
2025-12-09 10:14:45.215010 | 
2025-12-09 10:14:45.215081 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-09 10:14:45.743490 | controller | changed
2025-12-09 10:14:45.748904 | 
2025-12-09 10:14:45.749001 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-09 10:14:45.763913 | controller | skipping: Conditional result was False
2025-12-09 10:14:45.771214 | 
2025-12-09 10:14:45.771298 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-09 10:14:46.100676 | controller -> localhost | changed
2025-12-09 10:14:46.110616 | 
2025-12-09 10:14:46.110703 | TASK [add-build-sshkey : Add back temp key]
2025-12-09 10:14:46.337414 | controller -> localhost | Identity added: /var/lib/zuul/builds/4b56be4badf14eafa082909e6c86026e/work/4b56be4badf14eafa082909e6c86026e_id_rsa (zuul-build-sshkey)
2025-12-09 10:14:46.337696 | controller -> localhost | ok: Runtime: 0:00:00.006989
2025-12-09 10:14:46.343279 | 
2025-12-09 10:14:46.343345 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-09 10:14:46.632314 | controller | ok
2025-12-09 10:14:46.637605 | 
2025-12-09 10:14:46.637706 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-09 10:14:46.662602 | controller | skipping: Conditional result was False
2025-12-09 10:14:46.672016 | 
2025-12-09 10:14:46.672088 | TASK [include_role : validate-host]
2025-12-09 10:14:46.689869 | controller | ok
2025-12-09 10:14:46.710467 | 
2025-12-09 10:14:46.710574 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-09 10:14:46.739049 | controller | ok
2025-12-09 10:14:46.743815 | 
2025-12-09 10:14:46.743878 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-09 10:14:46.949829 | controller -> localhost | ok
2025-12-09 10:14:46.955714 | 
2025-12-09 10:14:46.955807 | TASK [validate-host : Collect information about the host]
2025-12-09 10:14:47.557270 | controller | ok
2025-12-09 10:14:47.565228 | 
2025-12-09 10:14:47.565299 | TASK [validate-host : Sanitize hostname]
2025-12-09 10:14:47.633622 | controller | ok
2025-12-09 10:14:47.640281 | 
2025-12-09 10:14:47.640364 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-09 10:14:48.049733 | controller -> localhost | changed
2025-12-09 10:14:48.054952 | 
2025-12-09 10:14:48.055018 | TASK [validate-host : Collect information about zuul worker]
2025-12-09 10:14:48.363831 | controller | ok
2025-12-09 10:14:48.368502 | 
2025-12-09 10:14:48.368602 | TASK [validate-host : Write out all zuul information for each host]
2025-12-09 10:14:48.727044 | controller -> localhost | changed
2025-12-09 10:14:48.737230 | 
2025-12-09 10:14:48.737311 | TASK [include_role : prepare-workspace-openshift]
2025-12-09 10:14:48.750300 | controller | skipping: Conditional result was False
2025-12-09 10:14:48.755503 | 
2025-12-09 10:14:48.755579 | TASK [include_role : remove-zuul-sshkey]
2025-12-09 10:14:48.768702 | controller | skipping: Conditional result was False
2025-12-09 10:14:48.773948 | 
2025-12-09 10:14:48.774027 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-09 10:14:48.953913 | controller | ok: "logs"
2025-12-09 10:14:48.954133 | controller | ok: All items complete
2025-12-09 10:14:48.954162 | 
2025-12-09 10:14:49.111248 | controller | ok: "artifacts"
2025-12-09 10:14:49.270795 | controller | ok: "docs"
2025-12-09 10:14:49.284251 | 
2025-12-09 10:14:49.284327 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-09 10:14:49.516816 | controller | changed: "logs"
2025-12-09 10:14:49.716649 | controller | changed: "artifacts"
2025-12-09 10:14:49.878833 | controller | changed: "docs"
2025-12-09 10:14:49.905513 | 
2025-12-09 10:14:49.905579 | PLAY RECAP
2025-12-09 10:14:49.905623 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-09 10:14:49.905667 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-09 10:14:49.905694 | 
2025-12-09 10:14:50.013606 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-09 10:14:50.014377 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-09 10:14:50.564620 | 
2025-12-09 10:14:50.564762 | PLAY [all]
2025-12-09 10:14:50.583866 | 
2025-12-09 10:14:50.583943 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-09 10:14:50.633156 | controller | ok
2025-12-09 10:14:50.638082 | 
2025-12-09 10:14:50.638164 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-09 10:14:50.983368 | controller | changed
2025-12-09 10:14:50.989994 | 
2025-12-09 10:14:50.990072 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-09 10:14:51.783705 | controller | changed
2025-12-09 10:14:51.793434 | 
2025-12-09 10:14:51.793548 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-09 10:14:52.146249 | controller | changed:
2025-12-09 10:14:52.152042 | controller | {
2025-12-09 10:14:52.152085 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-09 10:14:52.152121 | controller | }
2025-12-09 10:14:52.362848 | controller | changed:
2025-12-09 10:14:52.362937 | controller | {
2025-12-09 10:14:52.362966 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-09 10:14:52.362987 | controller | }
2025-12-09 10:14:52.569072 | controller | changed:
2025-12-09 10:14:52.569361 | controller | {
2025-12-09 10:14:52.569403 | 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-09 10:14:52.569428 | controller | }
2025-12-09 10:14:52.771598 | controller | changed:
2025-12-09 10:14:52.771719 | controller | {
2025-12-09 10:14:52.771749 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-09 10:14:52.771770 | controller | }
2025-12-09 10:14:52.974180 | controller | changed:
2025-12-09 10:14:52.974289 | controller | {
2025-12-09 10:14:52.974316 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-09 10:14:52.974337 | controller | }
2025-12-09 10:14:53.177789 | controller | changed:
2025-12-09 10:14:53.178001 | controller | {
2025-12-09 10:14:53.178038 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-09 10:14:53.178061 | controller | }
2025-12-09 10:14:53.384152 | controller | changed:
2025-12-09 10:14:53.384269 | controller | {
2025-12-09 10:14:53.384299 | 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-09 10:14:53.384323 | controller | }
2025-12-09 10:14:53.592030 | controller | changed:
2025-12-09 10:14:53.592145 | controller | {
2025-12-09 10:14:53.592174 | 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-09 10:14:53.592198 | controller | }
2025-12-09 10:14:53.804854 | controller | changed:
2025-12-09 10:14:53.804970 | controller | {
2025-12-09 10:14:53.804999 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-09 10:14:53.805021 | controller | }
2025-12-09 10:14:54.005273 | controller | changed:
2025-12-09 10:14:54.005379 | controller | {
2025-12-09 10:14:54.005406 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-09 10:14:54.005427 | controller | }
2025-12-09 10:14:54.205549 | controller | changed:
2025-12-09 10:14:54.205678 | controller | {
2025-12-09 10:14:54.205710 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-09 10:14:54.205732 | controller | }
2025-12-09 10:14:54.425129 | controller | changed:
2025-12-09 10:14:54.425226 | controller | {
2025-12-09 10:14:54.425264 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-09 10:14:54.425285 | controller | }
2025-12-09 10:14:54.604925 | controller | changed:
2025-12-09 10:14:54.605037 | controller | {
2025-12-09 10:14:54.605066 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-09 10:14:54.605087 | controller | }
2025-12-09 10:14:54.807361 | controller | changed:
2025-12-09 10:14:54.807471 | controller | {
2025-12-09 10:14:54.807518 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-09 10:14:54.807541 | controller | }
2025-12-09 10:14:55.011427 | controller | changed:
2025-12-09 10:14:55.011546 | controller | {
2025-12-09 10:14:55.011574 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-09 10:14:55.011595 | controller | }
2025-12-09 10:14:55.214446 | controller | changed:
2025-12-09 10:14:55.214631 | controller | {
2025-12-09 10:14:55.214678 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-09 10:14:55.214702 | controller | }
2025-12-09 10:14:55.429706 | controller | changed:
2025-12-09 10:14:55.429937 | controller | {
2025-12-09 10:14:55.429976 | 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-09 10:14:55.430001 | controller | }
2025-12-09 10:14:55.633360 | controller | changed:
2025-12-09 10:14:55.633541 | controller | {
2025-12-09 10:14:55.633576 | 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-09 10:14:55.633611 | controller | }
2025-12-09 10:14:55.841506 | controller | changed:
2025-12-09 10:14:55.841607 | controller | {
2025-12-09 10:14:55.841633 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-09 10:14:55.841668 | controller | }
2025-12-09 10:14:56.047616 | controller | changed:
2025-12-09 10:14:56.047741 | controller | {
2025-12-09 10:14:56.047773 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-09 10:14:56.047797 | controller | }
2025-12-09 10:14:56.252937 | controller | changed:
2025-12-09 10:14:56.253048 | controller | {
2025-12-09 10:14:56.253077 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-09 10:14:56.253097 | controller | }
2025-12-09 10:14:56.454072 | controller | changed:
2025-12-09 10:14:56.454284 | controller | {
2025-12-09 10:14:56.454358 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-09 10:14:56.454386 | controller | }
2025-12-09 10:14:56.668095 | controller | changed:
2025-12-09 10:14:56.668212 | controller | {
2025-12-09 10:14:56.668242 | 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-09 10:14:56.668265 | controller | }
2025-12-09 10:14:56.888081 | controller | changed:
2025-12-09 10:14:56.888195 | controller | {
2025-12-09 10:14:56.888221 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-09 10:14:56.888240 | controller | }
2025-12-09 10:14:57.099434 | controller | changed:
2025-12-09 10:14:57.099547 | controller | {
2025-12-09 10:14:57.099574 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-09 10:14:57.099594 | controller | }
2025-12-09 10:14:57.299225 | controller | changed:
2025-12-09 10:14:57.299445 | controller | {
2025-12-09 10:14:57.299500 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-09 10:14:57.299526 | controller | }
2025-12-09 10:14:57.325775 | 
2025-12-09 10:14:57.325892 | TASK [Set timezone to UTC]
2025-12-09 10:14:57.699280 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-09 10:14:57.704273 | 
2025-12-09 10:14:57.704351 | TASK [Create nodepool directory]
2025-12-09 10:14:57.897616 | controller | changed
2025-12-09 10:14:57.903399 | 
2025-12-09 10:14:57.903505 | TASK [Create nodepool sub_nodes file]
2025-12-09 10:14:58.410533 | controller | changed
2025-12-09 10:14:58.415593 | 
2025-12-09 10:14:58.415679 | TASK [Create nodepool sub_nodes_private file]
2025-12-09 10:14:58.912334 | controller | changed
2025-12-09 10:14:58.917315 | 
2025-12-09 10:14:58.917378 | LOOP [Populate nodepool sub_nodes file]
2025-12-09 10:14:58.947079 | 
2025-12-09 10:14:58.947209 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-09 10:14:58.977072 | 
2025-12-09 10:14:58.977232 | TASK [Create nodepool primary file]
2025-12-09 10:14:59.002366 | controller | skipping: Conditional result was False
2025-12-09 10:14:59.007867 | 
2025-12-09 10:14:59.007944 | TASK [Create nodepool node_private for this node]
2025-12-09 10:14:59.523378 | controller | changed
2025-12-09 10:14:59.528739 | 
2025-12-09 10:14:59.528863 | LOOP [Copy ssh keys to nodepool directory]
2025-12-09 10:14:59.856990 | controller | ok: Item: id_rsa Runtime: 0:00:00.004431
2025-12-09 10:14:59.857219 | 
2025-12-09 10:15:00.017465 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004196
2025-12-09 10:15:00.024873 | 
2025-12-09 10:15:00.024949 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-09 10:15:00.563579 | controller | changed
2025-12-09 10:15:00.568557 | 
2025-12-09 10:15:00.568627 | TASK [Validate sudoers config after edits]
2025-12-09 10:15:00.784207 | controller | /etc/sudoers: parsed OK
2025-12-09 10:15:00.784305 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-09 10:15:00.784316 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-09 10:15:00.784322 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-09 10:15:01.095367 | controller | ok: Runtime: 0:00:00.005207
2025-12-09 10:15:01.101168 | 
2025-12-09 10:15:01.101232 | TASK [Show the environment passed in to job shell scripts]
2025-12-09 10:15:01.301136 | controller | SHELL=/bin/bash
2025-12-09 10:15:01.301179 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-09 10:15:01.301189 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-09 10:15:01.301196 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/69/3169/e5d00275ab43859058fff25a4aba9527ddca1d29
2025-12-09 10:15:01.301202 | controller | PWD=/home/zuul
2025-12-09 10:15:01.301208 | controller | ZUUL_PIPELINE=github-check
2025-12-09 10:15:01.301213 | controller | LOGNAME=zuul
2025-12-09 10:15:01.301219 | controller | XDG_SESSION_TYPE=tty
2025-12-09 10:15:01.301266 | controller | _=/usr/bin/env
2025-12-09 10:15:01.301275 | controller | MOTD_SHOWN=pam
2025-12-09 10:15:01.301281 | controller | HOME=/home/zuul
2025-12-09 10:15:01.301286 | controller | LANG=en_US.UTF-8
2025-12-09 10:15:01.301292 | controller | SSH_CONNECTION=192.168.25.12 33770 192.168.25.29 22
2025-12-09 10:15:01.301297 | 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-09 10:15:01.301306 | controller | ZUUL_CHANGE_IDS=3169,e5d00275ab43859058fff25a4aba9527ddca1d29
2025-12-09 10:15:01.301312 | controller | WORKSPACE=/home/zuul/workspace
2025-12-09 10:15:01.301317 | controller | XDG_SESSION_CLASS=user
2025-12-09 10:15:01.301323 | controller | SELINUX_ROLE_REQUESTED=
2025-12-09 10:15:01.301328 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-09 10:15:01.301334 | controller | USER=zuul
2025-12-09 10:15:01.301339 | controller | ZUUL_VOTING=True
2025-12-09 10:15:01.301344 | controller | BUILD_TIMEOUT=1800000
2025-12-09 10:15:01.301350 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-09 10:15:01.301355 | controller | SHLVL=1
2025-12-09 10:15:01.301360 | controller | ZUUL_PATCHSET=e5d00275ab43859058fff25a4aba9527ddca1d29
2025-12-09 10:15:01.301366 | controller | XDG_SESSION_ID=1
2025-12-09 10:15:01.301371 | controller | ZUUL_BRANCH=main
2025-12-09 10:15:01.301377 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-09 10:15:01.301382 | controller | SSH_CLIENT=192.168.25.12 33770 22
2025-12-09 10:15:01.301389 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-09 10:15:01.301394 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-09 10:15:01.301401 | controller | which_declare=declare -f
2025-12-09 10:15:01.301407 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-09 10:15:01.301413 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-09 10:15:01.301418 | controller | ZUUL_CHANGE=3169
2025-12-09 10:15:01.301424 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-09 10:15:01.301429 | controller | ZUUL_UUID=4b56be4badf14eafa082909e6c86026e
2025-12-09 10:15:01.301435 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-09 10:15:01.301441 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-09 10:15:01.301446 | controller | }
2025-12-09 10:15:01.624310 | controller | ok: Runtime: 0:00:00.005721
2025-12-09 10:15:01.629415 | 
2025-12-09 10:15:01.629487 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-09 10:15:01.643271 | controller | skipping: Conditional result was False
2025-12-09 10:15:01.648906 | 
2025-12-09 10:15:01.648972 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-09 10:15:02.171094 | controller | skipping: Conditional result was False
2025-12-09 10:15:02.177230 | 
2025-12-09 10:15:02.177302 | TASK [Ensure legacy workspace directory]
2025-12-09 10:15:02.369837 | controller | changed
2025-12-09 10:15:02.387734 | 
2025-12-09 10:15:02.387795 | PLAY RECAP
2025-12-09 10:15:02.387839 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-09 10:15:02.387862 | 
2025-12-09 10:15:02.479595 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-09 10:15:02.480552 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-09 10:15:03.015423 | 
2025-12-09 10:15:03.015580 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-09 10:15:03.037707 | 
2025-12-09 10:15:03.037847 | TASK [Create zuul-output directory]
2025-12-09 10:15:03.404788 | controller | changed
2025-12-09 10:15:03.409964 | 
2025-12-09 10:15:03.410078 | TASK [Slurp Zuul inventory test]
2025-12-09 10:15:03.687385 | controller -> localhost | ok
2025-12-09 10:15:03.694454 | 
2025-12-09 10:15:03.694546 | TASK [Save zuul inventory]
2025-12-09 10:15:04.444308 | controller | changed
2025-12-09 10:15:04.449180 | 
2025-12-09 10:15:04.449243 | TASK [Save zuul vars without the change_message]
2025-12-09 10:15:05.007140 | controller | changed
2025-12-09 10:15:05.024355 | 
2025-12-09 10:15:05.024411 | PLAY RECAP
2025-12-09 10:15:05.024455 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-09 10:15:05.024494 | 
2025-12-09 10:15:05.101113 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-09 10:15:05.101944 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-09 10:15:05.699176 | 
2025-12-09 10:15:05.699292 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-09 10:15:05.719902 | 
2025-12-09 10:15:05.719997 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-09 10:15:05.738632 | controller | ok
2025-12-09 10:15:05.756060 | 
2025-12-09 10:15:05.756153 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-09 10:15:05.780525 | controller | skipping: Conditional result was False
2025-12-09 10:15:05.786751 | 
2025-12-09 10:15:05.786839 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-09 10:15:06.130220 | controller | ok
2025-12-09 10:15:06.135803 | 
2025-12-09 10:15:06.135879 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-09 10:15:06.756265 | controller | ok
2025-12-09 10:15:06.767230 | 
2025-12-09 10:15:06.767330 | TASK [Prepare workspace]
2025-12-09 10:15:06.786634 | controller | ok
2025-12-09 10:15:06.806520 | 
2025-12-09 10:15:06.806816 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-09 10:15:07.103269 | controller | ok
2025-12-09 10:15:07.110722 | 
2025-12-09 10:15:07.110822 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-09 10:15:09.544764 | controller | Output suppressed because no_log was given
2025-12-09 10:15:09.554211 | 
2025-12-09 10:15:09.554309 | LOOP [Create zuul-output directory]
2025-12-09 10:15:09.749322 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-09 10:15:09.909971 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-09 10:15:09.924119 | 
2025-12-09 10:15:09.924273 | TASK [Install required packages]
2025-12-09 10:16:03.622148 | controller | changed
2025-12-09 10:16:03.627207 | 
2025-12-09 10:16:03.627272 | TASK [Install venv]
2025-12-09 10:17:05.168466 | controller | changed
2025-12-09 10:17:05.193133 | 
2025-12-09 10:17:05.193201 | PLAY RECAP
2025-12-09 10:17:05.193245 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-09 10:17:05.193269 | 
2025-12-09 10:17:05.285281 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-09 10:17:05.286168 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-09 10:17:05.809855 | 
2025-12-09 10:17:05.809972 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-12-09 10:17:05.830488 | 
2025-12-09 10:17:05.830604 | TASK [Gather required facts]
2025-12-09 10:17:06.337973 | controller | ok
2025-12-09 10:17:06.343891 | 
2025-12-09 10:17:06.343979 | TASK [Load environment var if instructed to]
2025-12-09 10:17:06.371394 | controller | skipping: Conditional result was False
2025-12-09 10:17:06.376938 | 
2025-12-09 10:17:06.377005 | TASK [Ensure group_vars dir exists]
2025-12-09 10:17:06.690701 | controller | ok
2025-12-09 10:17:06.697486 | 
2025-12-09 10:17:06.697576 | TASK [Print related variables]
2025-12-09 10:17:06.728997 | controller | ok:
2025-12-09 10:17:06.729134 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-12-09 10:17:06.729163 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_cephadm
2025-12-09 10:17:06.734153 | 
2025-12-09 10:17:06.734215 | TASK [Run molecule]
2025-12-09 10:17:07.717022 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-12-09 10:17:07.790180 | controller | INFO     Performing prerun with role_name_check=0...
2025-12-09 10:17:24.213275 | 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-09 10:17:24.213722 | 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-09 10:17:24.214188 | 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-09 10:17:24.214642 | 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-09 10:17:24.215115 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+ee21a1cf was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 1.0.0+ee21a1cf (/home/zuul/.ansible/collections/ansible_collections).
2025-12-09 10:17:24.215576 | 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-09 10:17:24.216004 | 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-09 10:17:24.216472 | 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-09 10:17:24.216905 | 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-09 10:17:24.217350 | 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-09 10:17:24.217803 | 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-09 10:17:24.218271 | 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-09 10:17:24.218715 | 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-09 10:17:24.219151 | 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-09 10:17:24.219602 | 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-09 10:17:24.228710 | controller | INFO     Running default > prepare
2025-12-09 10:17:24.891131 | controller |
2025-12-09 10:17:24.891242 | controller | PLAY [Prepare] *****************************************************************
2025-12-09 10:17:24.891254 | controller |
2025-12-09 10:17:24.891265 | controller | TASK [Gathering Facts] *********************************************************
2025-12-09 10:17:25.743154 | controller | Tuesday 09 December 2025  10:17:24 +0000 (0:00:00.012)       0:00:00.012 ******
2025-12-09 10:17:25.743191 | controller | ok: [instance]
2025-12-09 10:17:25.743242 | controller |
2025-12-09 10:17:25.743372 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-12-09 10:17:25.743526 | controller | Tuesday 09 December 2025  10:17:25 +0000 (0:00:00.852)       0:00:00.865 ******
2025-12-09 10:17:25.760904 | controller | skipping: [instance]
2025-12-09 10:17:25.761013 | controller |
2025-12-09 10:17:25.761166 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-12-09 10:17:25.761286 | controller | Tuesday 09 December 2025  10:17:25 +0000 (0:00:00.018)       0:00:00.883 ******
2025-12-09 10:17:25.801732 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-12-09 10:17:25.801843 | controller |
2025-12-09 10:17:25.801969 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-12-09 10:17:25.802125 | controller | Tuesday 09 December 2025  10:17:25 +0000 (0:00:00.040)       0:00:00.924 ******
2025-12-09 10:17:26.096486 | controller | ok: [instance]
2025-12-09 10:17:26.096556 | controller |
2025-12-09 10:17:26.096659 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-12-09 10:17:26.096761 | controller | Tuesday 09 December 2025  10:17:26 +0000 (0:00:00.294)       0:00:01.218 ******
2025-12-09 10:17:26.403572 | controller | ok: [instance]
2025-12-09 10:17:26.403641 | controller |
2025-12-09 10:17:26.403746 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-12-09 10:17:26.403850 | controller | Tuesday 09 December 2025  10:17:26 +0000 (0:00:00.307)       0:00:01.526 ******
2025-12-09 10:17:27.089956 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-12-09 10:17:27.090032 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-12-09 10:17:27.090163 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-12-09 10:17:27.090253 | controller |
2025-12-09 10:17:27.090355 | controller | TASK [Deploy repo-setup] *******************************************************
2025-12-09 10:17:27.090467 | controller | Tuesday 09 December 2025  10:17:27 +0000 (0:00:00.686)       0:00:02.212 ******
2025-12-09 10:17:27.149206 | controller |
2025-12-09 10:17:27.149296 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-12-09 10:17:27.149406 | controller | Tuesday 09 December 2025  10:17:27 +0000 (0:00:00.059)       0:00:02.271 ******
2025-12-09 10:17:27.726937 | controller | changed: [instance] => (item=tmp)
2025-12-09 10:17:27.726981 | controller | changed: [instance] => (item=artifacts/repositories)
2025-12-09 10:17:27.727057 | controller | changed: [instance] => (item=venv/repo_setup)
2025-12-09 10:17:27.727169 | controller |
2025-12-09 10:17:27.727273 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-12-09 10:17:27.727374 | controller | Tuesday 09 December 2025  10:17:27 +0000 (0:00:00.577)       0:00:02.849 ******
2025-12-09 10:17:28.522421 | controller | ok: [instance]
2025-12-09 10:17:28.522767 | controller |
2025-12-09 10:17:29.570265 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-12-09 10:17:29.570294 | controller | Tuesday 09 December 2025  10:17:28 +0000 (0:00:00.795)       0:00:03.644 ******
2025-12-09 10:17:29.570308 | controller | changed: [instance]
2025-12-09 10:17:38.399132 | controller |
2025-12-09 10:17:38.399168 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-12-09 10:17:38.399177 | controller | Tuesday 09 December 2025  10:17:29 +0000 (0:00:01.047)       0:00:04.692 ******
2025-12-09 10:17:38.399187 | controller | changed: [instance]
2025-12-09 10:17:39.090321 | controller |
2025-12-09 10:17:39.090364 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-12-09 10:17:39.090372 | controller | Tuesday 09 December 2025  10:17:38 +0000 (0:00:08.828)       0:00:13.521 ******
2025-12-09 10:17:39.090383 | controller | changed: [instance]
2025-12-09 10:17:39.111452 | controller |
2025-12-09 10:17:39.111493 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-12-09 10:17:39.111504 | controller | Tuesday 09 December 2025  10:17:39 +0000 (0:00:00.691)       0:00:14.212 ******
2025-12-09 10:17:39.111515 | controller | skipping: [instance]
2025-12-09 10:17:39.912946 | controller |
2025-12-09 10:17:39.912975 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-12-09 10:17:39.912984 | controller | Tuesday 09 December 2025  10:17:39 +0000 (0:00:00.021)       0:00:14.233 ******
2025-12-09 10:17:39.912993 | controller | changed: [instance]
2025-12-09 10:17:39.949011 | controller |
2025-12-09 10:17:39.949035 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-12-09 10:17:39.949061 | controller | Tuesday 09 December 2025  10:17:39 +0000 (0:00:00.801)       0:00:15.035 ******
2025-12-09 10:17:39.949074 | controller | skipping: [instance]
2025-12-09 10:17:39.985152 | controller |
2025-12-09 10:17:39.985187 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-12-09 10:17:39.985195 | controller | Tuesday 09 December 2025  10:17:39 +0000 (0:00:00.036)       0:00:15.071 ******
2025-12-09 10:17:39.985206 | controller | skipping: [instance]
2025-12-09 10:17:39.985238 | controller |
2025-12-09 10:17:39.985247 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-12-09 10:17:39.985594 | controller | Tuesday 09 December 2025  10:17:39 +0000 (0:00:00.036)       0:00:15.107 ******
2025-12-09 10:17:40.019833 | controller | skipping: [instance]
2025-12-09 10:17:40.447115 | controller |
2025-12-09 10:17:40.447144 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-12-09 10:17:40.447152 | controller | Tuesday 09 December 2025  10:17:40 +0000 (0:00:00.034)       0:00:15.142 ******
2025-12-09 10:17:40.447162 | controller | changed: [instance]
2025-12-09 10:17:40.935902 | controller |
2025-12-09 10:17:40.935936 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-12-09 10:17:40.935945 | controller | Tuesday 09 December 2025  10:17:40 +0000 (0:00:00.427)       0:00:15.569 ******
2025-12-09 10:17:40.935956 | controller | changed: [instance]
2025-12-09 10:17:40.964914 | controller |
2025-12-09 10:17:40.964951 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-12-09 10:17:40.964959 | controller | Tuesday 09 December 2025  10:17:40 +0000 (0:00:00.488)       0:00:16.058 ******
2025-12-09 10:17:40.964970 | controller | skipping: [instance]
2025-12-09 10:17:40.995177 | controller |
2025-12-09 10:17:40.995206 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-12-09 10:17:40.995214 | controller | Tuesday 09 December 2025  10:17:40 +0000 (0:00:00.028)       0:00:16.087 ******
2025-12-09 10:17:40.995228 | controller | skipping: [instance]
2025-12-09 10:17:40.995252 | controller |
2025-12-09 10:17:40.995427 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-12-09 10:17:41.024095 | controller | Tuesday 09 December 2025  10:17:40 +0000 (0:00:00.030)       0:00:16.117 ******
2025-12-09 10:17:41.024119 | controller | skipping: [instance]
2025-12-09 10:17:41.024366 | controller |
2025-12-09 10:17:41.061744 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-12-09 10:17:41.061767 | controller | Tuesday 09 December 2025  10:17:41 +0000 (0:00:00.029)       0:00:16.146 ******
2025-12-09 10:17:41.061778 | controller | ok: [instance]
2025-12-09 10:17:41.088569 | controller |
2025-12-09 10:17:41.088594 | controller | TASK [repo_setup : Create download directory] **********************************
2025-12-09 10:17:41.088604 | controller | Tuesday 09 December 2025  10:17:41 +0000 (0:00:00.037)       0:00:16.184 ******
2025-12-09 10:17:41.088615 | controller | skipping: [instance]
2025-12-09 10:17:41.115669 | controller |
2025-12-09 10:17:41.115692 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-12-09 10:17:41.115699 | controller | Tuesday 09 December 2025  10:17:41 +0000 (0:00:00.026)       0:00:16.210 ******
2025-12-09 10:17:41.115715 | controller | skipping: [instance]
2025-12-09 10:17:41.141113 | controller |
2025-12-09 10:17:41.141133 | controller | TASK [Download the RPM] ********************************************************
2025-12-09 10:17:41.141141 | controller | Tuesday 09 December 2025  10:17:41 +0000 (0:00:00.027)       0:00:16.238 ******
2025-12-09 10:17:41.141150 | controller | skipping: [instance]
2025-12-09 10:17:41.141173 | controller |
2025-12-09 10:17:41.141408 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-12-09 10:17:41.167258 | controller | Tuesday 09 December 2025  10:17:41 +0000 (0:00:00.025)       0:00:16.263 ******
2025-12-09 10:17:41.167286 | controller | skipping: [instance]
2025-12-09 10:17:41.193030 | controller |
2025-12-09 10:17:41.193066 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-12-09 10:17:41.193078 | controller | Tuesday 09 December 2025  10:17:41 +0000 (0:00:00.025)       0:00:16.289 ******
2025-12-09 10:17:41.193088 | controller | skipping: [instance]
2025-12-09 10:17:41.221121 | controller |
2025-12-09 10:17:41.221145 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-12-09 10:17:41.221155 | controller | Tuesday 09 December 2025  10:17:41 +0000 (0:00:00.025)       0:00:16.315 ******
2025-12-09 10:17:41.221166 | controller | skipping: [instance]
2025-12-09 10:17:41.221238 | controller |
2025-12-09 10:17:41.221258 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-12-09 10:17:41.221270 | controller | Tuesday 09 December 2025  10:17:41 +0000 (0:00:00.028)       0:00:16.343 ******
2025-12-09 10:17:41.246093 | controller | skipping: [instance]
2025-12-09 10:17:41.246138 | controller |
2025-12-09 10:17:41.246148 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-12-09 10:17:41.246272 | controller | Tuesday 09 December 2025  10:17:41 +0000 (0:00:00.025)       0:00:16.368 ******
2025-12-09 10:17:41.437712 | controller | ok: [instance]
2025-12-09 10:17:41.647545 | controller |
2025-12-09 10:17:41.647574 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-12-09 10:17:41.647582 | controller | Tuesday 09 December 2025  10:17:41 +0000 (0:00:00.191)       0:00:16.559 ******
2025-12-09 10:17:41.647592 | controller | changed: [instance]
2025-12-09 10:17:41.866990 | controller |
2025-12-09 10:17:41.867018 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-12-09 10:17:41.867026 | controller | Tuesday 09 December 2025  10:17:41 +0000 (0:00:00.209)       0:00:16.769 ******
2025-12-09 10:17:41.867036 | controller | changed: [instance]
2025-12-09 10:17:41.886694 | controller |
2025-12-09 10:17:41.886724 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-12-09 10:17:41.886733 | controller | Tuesday 09 December 2025  10:17:41 +0000 (0:00:00.219)       0:00:16.989 ******
2025-12-09 10:17:41.886745 | controller | skipping: [instance]
2025-12-09 10:17:41.906596 | controller |
2025-12-09 10:17:41.906622 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-12-09 10:17:41.906638 | controller | Tuesday 09 December 2025  10:17:41 +0000 (0:00:00.019)       0:00:17.009 ******
2025-12-09 10:17:41.906647 | controller | skipping: [instance]
2025-12-09 10:17:41.927122 | controller |
2025-12-09 10:17:41.927153 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-12-09 10:17:41.927161 | controller | Tuesday 09 December 2025  10:17:41 +0000 (0:00:00.019)       0:00:17.029 ******
2025-12-09 10:17:41.927172 | controller | skipping: [instance]
2025-12-09 10:17:41.927323 | controller |
2025-12-09 10:17:41.927345 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-12-09 10:17:41.947997 | controller | Tuesday 09 December 2025  10:17:41 +0000 (0:00:00.020)       0:00:17.049 ******
2025-12-09 10:17:41.948026 | controller | skipping: [instance]
2025-12-09 10:17:41.968758 | controller |
2025-12-09 10:17:41.968779 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-12-09 10:17:41.968788 | controller | Tuesday 09 December 2025  10:17:41 +0000 (0:00:00.020)       0:00:17.070 ******
2025-12-09 10:17:41.968799 | controller | skipping: [instance]
2025-12-09 10:17:41.989126 | controller |
2025-12-09 10:17:41.989150 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-12-09 10:17:41.989167 | controller | Tuesday 09 December 2025  10:17:41 +0000 (0:00:00.020)       0:00:17.091 ******
2025-12-09 10:17:41.989178 | controller | skipping: [instance]
2025-12-09 10:17:41.989201 | controller |
2025-12-09 10:17:41.989352 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-12-09 10:17:42.006557 | controller | Tuesday 09 December 2025  10:17:41 +0000 (0:00:00.020)       0:00:17.111 ******
2025-12-09 10:17:42.006578 | controller | skipping: [instance]
2025-12-09 10:17:42.033853 | controller |
2025-12-09 10:17:42.033874 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-12-09 10:17:42.033882 | controller | Tuesday 09 December 2025  10:17:42 +0000 (0:00:00.017)       0:00:17.129 ******
2025-12-09 10:17:42.033893 | controller | skipping: [instance]
2025-12-09 10:17:42.049593 | controller |
2025-12-09 10:17:42.049609 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-12-09 10:17:42.049616 | controller | Tuesday 09 December 2025  10:17:42 +0000 (0:00:00.027)       0:00:17.156 ******
2025-12-09 10:17:42.049624 | controller | skipping: [instance]
2025-12-09 10:17:42.068642 | controller |
2025-12-09 10:17:42.068658 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-12-09 10:17:42.068665 | controller | Tuesday 09 December 2025  10:17:42 +0000 (0:00:00.015)       0:00:17.172 ******
2025-12-09 10:17:42.068673 | controller | skipping: [instance]
2025-12-09 10:17:42.096532 | controller |
2025-12-09 10:17:42.096554 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-12-09 10:17:42.096563 | controller | Tuesday 09 December 2025  10:17:42 +0000 (0:00:00.019)       0:00:17.191 ******
2025-12-09 10:17:42.096574 | controller | skipping: [instance]
2025-12-09 10:17:42.124269 | controller |
2025-12-09 10:17:42.124293 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-12-09 10:17:42.124302 | controller | Tuesday 09 December 2025  10:17:42 +0000 (0:00:00.027)       0:00:17.218 ******
2025-12-09 10:17:42.124313 | controller | skipping: [instance]
2025-12-09 10:17:42.124483 | controller |
2025-12-09 10:17:42.124673 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-12-09 10:17:42.124808 | controller | Tuesday 09 December 2025  10:17:42 +0000 (0:00:00.028)       0:00:17.247 ******
2025-12-09 10:17:42.152467 | controller | skipping: [instance]
2025-12-09 10:17:42.152664 | controller |
2025-12-09 10:17:42.152813 | controller | TASK [test_deps : Install additional packages] *********************************
2025-12-09 10:17:42.152949 | controller | Tuesday 09 December 2025  10:17:42 +0000 (0:00:00.028)       0:00:17.275 ******
2025-12-09 10:17:42.180868 | controller | skipping: [instance]
2025-12-09 10:17:42.181068 | controller |
2025-12-09 10:17:42.181259 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-12-09 10:17:42.181427 | controller | Tuesday 09 December 2025  10:17:42 +0000 (0:00:00.028)       0:00:17.303 ******
2025-12-09 10:18:24.972911 | controller | ok: [instance]
2025-12-09 10:18:26.157070 | controller |
2025-12-09 10:18:26.157183 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-12-09 10:18:26.157192 | controller | Tuesday 09 December 2025  10:18:24 +0000 (0:00:42.791)       0:01:00.095 ******
2025-12-09 10:18:26.157203 | controller | ok: [instance]
2025-12-09 10:18:27.361130 | controller |
2025-12-09 10:18:27.361160 | controller | TASK [test_deps : Install extra packages] **************************************
2025-12-09 10:18:27.361168 | controller | Tuesday 09 December 2025  10:18:26 +0000 (0:00:01.184)       0:01:01.279 ******
2025-12-09 10:18:27.361178 | controller | ok: [instance]
2025-12-09 10:18:27.361327 | controller |
2025-12-09 10:18:27.361346 | controller | TASK [Ensure ceph-admin group exists] ******************************************
2025-12-09 10:18:27.676934 | controller | Tuesday 09 December 2025  10:18:27 +0000 (0:00:01.204)       0:01:02.483 ******
2025-12-09 10:18:27.676969 | controller | changed: [instance]
2025-12-09 10:18:28.294143 | controller |
2025-12-09 10:18:28.294177 | controller | TASK [Ensure ceph-admin user exists] *******************************************
2025-12-09 10:18:28.294187 | controller | Tuesday 09 December 2025  10:18:27 +0000 (0:00:00.315)       0:01:02.799 ******
2025-12-09 10:18:28.294200 | controller | changed: [instance]
2025-12-09 10:18:28.294236 | controller |
2025-12-09 10:18:28.294245 | controller | PLAY RECAP *********************************************************************
2025-12-09 10:18:28.294252 | controller | instance                   : ok=22   changed=11   unreachable=0    failed=0    skipped=29   rescued=0    ignored=0
2025-12-09 10:18:28.294259 | controller |
2025-12-09 10:18:28.294266 | controller | Tuesday 09 December 2025  10:18:28 +0000 (0:00:00.615)       0:01:03.414 ******
2025-12-09 10:18:28.294277 | controller | ===============================================================================
2025-12-09 10:18:28.294284 | controller | test_deps : Install selinux python libs -------------------------------- 42.79s
2025-12-09 10:18:28.294291 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.83s
2025-12-09 10:18:28.294298 | controller | test_deps : Install extra packages -------------------------------------- 1.20s
2025-12-09 10:18:28.294305 | controller | test_deps : Install python yaml libs ------------------------------------ 1.18s
2025-12-09 10:18:28.294311 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.05s
2025-12-09 10:18:28.294318 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-12-09 10:18:28.294324 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.80s
2025-12-09 10:18:28.294331 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.80s
2025-12-09 10:18:28.294337 | controller | repo_setup : Install repo-setup package --------------------------------- 0.69s
2025-12-09 10:18:28.294344 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.69s
2025-12-09 10:18:28.294350 | controller | Ensure ceph-admin user exists ------------------------------------------- 0.62s
2025-12-09 10:18:28.294357 | controller | repo_setup : Ensure directories are present ----------------------------- 0.58s
2025-12-09 10:18:28.294364 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.49s
2025-12-09 10:18:28.294370 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.43s
2025-12-09 10:18:28.294377 | controller | Ensure ceph-admin group exists ------------------------------------------ 0.32s
2025-12-09 10:18:28.294384 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.31s
2025-12-09 10:18:28.294390 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.29s
2025-12-09 10:18:28.294400 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.22s
2025-12-09 10:18:28.351225 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.21s
2025-12-09 10:18:28.351251 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.19s
2025-12-09 10:18:28.351264 | controller | INFO     Running default > converge
2025-12-09 10:18:28.799064 | controller |
2025-12-09 10:18:29.574573 | controller | PLAY [Converge] ****************************************************************
2025-12-09 10:18:29.574608 | controller |
2025-12-09 10:18:29.574617 | controller | TASK [Gathering Facts] *********************************************************
2025-12-09 10:18:29.574625 | controller | Tuesday 09 December 2025  10:18:28 +0000 (0:00:00.015)       0:00:00.015 ******
2025-12-09 10:18:29.574636 | controller | ok: [instance]
2025-12-09 10:18:29.595477 | controller |
2025-12-09 10:18:29.595505 | controller | TASK [cifmw_cephadm : Gather distribution facts if they do not exist] **********
2025-12-09 10:18:29.595514 | controller | Tuesday 09 December 2025  10:18:29 +0000 (0:00:00.775)       0:00:00.791 ******
2025-12-09 10:18:29.595526 | controller | skipping: [instance]
2025-12-09 10:18:29.616580 | controller |
2025-12-09 10:18:29.616607 | controller | TASK [cifmw_cephadm : Install cephadm package] *********************************
2025-12-09 10:18:29.616616 | controller | Tuesday 09 December 2025  10:18:29 +0000 (0:00:00.021)       0:00:00.812 ******
2025-12-09 10:18:29.616628 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_cephadm/tasks/install_cephadm.yml for instance
2025-12-09 10:18:29.641494 | controller |
2025-12-09 10:18:29.641521 | controller | TASK [cifmw_cephadm : Enabled ceph Tools Repository] ***************************
2025-12-09 10:18:29.641531 | controller | Tuesday 09 December 2025  10:18:29 +0000 (0:00:00.021)       0:00:00.833 ******
2025-12-09 10:18:29.641550 | controller | skipping: [instance]
2025-12-09 10:18:34.610174 | controller |
2025-12-09 10:18:34.610219 | controller | TASK [cifmw_cephadm : Install cephadm package] *********************************
2025-12-09 10:18:34.610228 | controller | Tuesday 09 December 2025  10:18:29 +0000 (0:00:00.024)       0:00:00.858 ******
2025-12-09 10:18:34.610243 | controller | changed: [instance]
2025-12-09 10:18:34.909606 | controller |
2025-12-09 10:18:34.909636 | controller | TASK [cifmw_cephadm : Stat cephadm file] ***************************************
2025-12-09 10:18:34.909645 | controller | Tuesday 09 December 2025  10:18:34 +0000 (0:00:04.968)       0:00:05.826 ******
2025-12-09 10:18:34.909655 | controller | ok: [instance]
2025-12-09 10:18:34.926129 | controller |
2025-12-09 10:18:34.926162 | controller | TASK [cifmw_cephadm : Fail if cephadm is not available] ************************
2025-12-09 10:18:34.926170 | controller | Tuesday 09 December 2025  10:18:34 +0000 (0:00:00.299)       0:00:06.126 ******
2025-12-09 10:18:34.926181 | controller | skipping: [instance]
2025-12-09 10:18:34.926215 | controller |
2025-12-09 10:18:34.926391 | controller | TASK [cifmw_cephadm : Set list of found FSIDs] *********************************
2025-12-09 10:18:34.957628 | controller | Tuesday 09 December 2025  10:18:34 +0000 (0:00:00.016)       0:00:06.143 ******
2025-12-09 10:18:34.957655 | controller | skipping: [instance]
2025-12-09 10:18:34.988979 | controller |
2025-12-09 10:18:34.988998 | controller | TASK [cifmw_cephadm : Fail if >1 FSID was discovered] **************************
2025-12-09 10:18:34.989006 | controller | Tuesday 09 December 2025  10:18:34 +0000 (0:00:00.031)       0:00:06.174 ******
2025-12-09 10:18:34.989015 | controller | skipping: [instance]
2025-12-09 10:18:35.025482 | controller |
2025-12-09 10:18:35.025501 | controller | TASK [cifmw_cephadm : Set FSID to the discovered value] ************************
2025-12-09 10:18:35.025508 | controller | Tuesday 09 December 2025  10:18:34 +0000 (0:00:00.031)       0:00:06.205 ******
2025-12-09 10:18:35.025517 | controller | ok: [instance]
2025-12-09 10:18:35.056264 | controller |
2025-12-09 10:18:35.056282 | controller | TASK [cifmw_cephadm : Set random cifmw_cephadm_fsid if no running ceph containers were found] ***
2025-12-09 10:18:35.056289 | controller | Tuesday 09 December 2025  10:18:35 +0000 (0:00:00.036)       0:00:06.242 ******
2025-12-09 10:18:35.056298 | controller | skipping: [instance]
2025-12-09 10:18:35.090134 | controller |
2025-12-09 10:18:35.090166 | controller | TASK [cifmw_cephadm : Set first monitor IP if it was not passed] ***************
2025-12-09 10:18:35.090175 | controller | Tuesday 09 December 2025  10:18:35 +0000 (0:00:00.030)       0:00:06.273 ******
2025-12-09 10:18:35.090185 | controller | ok: [instance]
2025-12-09 10:18:35.090218 | controller |
2025-12-09 10:18:35.090228 | controller | TASK [cifmw_cephadm : Ensure cifmw_cephadm_config_home (e.g. /etc/ceph) exists] ***
2025-12-09 10:18:35.090301 | controller | Tuesday 09 December 2025  10:18:35 +0000 (0:00:00.033)       0:00:06.307 ******
2025-12-09 10:18:35.391156 | controller | changed: [instance]
2025-12-09 10:18:35.391349 | controller |
2025-12-09 10:18:35.391369 | controller | TASK [cifmw_cephadm : Ensure specs directory exists] ***************************
2025-12-09 10:18:35.587524 | controller | Tuesday 09 December 2025  10:18:35 +0000 (0:00:00.300)       0:00:06.608 ******
2025-12-09 10:18:35.587556 | controller | changed: [instance]
2025-12-09 10:18:35.767616 | controller |
2025-12-09 10:18:35.767645 | controller | TASK [cifmw_cephadm : Stat spec file on ansible host] **************************
2025-12-09 10:18:35.767653 | controller | Tuesday 09 December 2025  10:18:35 +0000 (0:00:00.196)       0:00:06.804 ******
2025-12-09 10:18:35.767663 | controller | ok: [instance -> localhost]
2025-12-09 10:18:35.783944 | controller |
2025-12-09 10:18:35.783962 | controller | TASK [cifmw_cephadm : Push cifmw_cephadm_spec to bootstrap node if spec file exsits] ***
2025-12-09 10:18:35.783969 | controller | Tuesday 09 December 2025  10:18:35 +0000 (0:00:00.179)       0:00:06.984 ******
2025-12-09 10:18:35.783978 | controller | skipping: [instance]
2025-12-09 10:18:36.009042 | controller |
2025-12-09 10:18:36.009097 | controller | TASK [cifmw_cephadm : Stat cifmw_cephadm_bootstrap_conf file] ******************
2025-12-09 10:18:36.009107 | controller | Tuesday 09 December 2025  10:18:35 +0000 (0:00:00.016)       0:00:07.001 ******
2025-12-09 10:18:36.009119 | controller | ok: [instance -> localhost]
2025-12-09 10:18:36.461943 | controller |
2025-12-09 10:18:36.461973 | controller | TASK [cifmw_cephadm : Push it to cifmw_cephadm_assimilate_conf on bootstrap node] ***
2025-12-09 10:18:36.461987 | controller | Tuesday 09 December 2025  10:18:36 +0000 (0:00:00.224)       0:00:07.225 ******
2025-12-09 10:18:36.461998 | controller | changed: [instance]
2025-12-09 10:18:36.488684 | controller |
2025-12-09 10:18:36.488706 | controller | TASK [cifmw_cephadm : Add spec to necessary file list when using spec to bootstrap] ***
2025-12-09 10:18:36.488716 | controller | Tuesday 09 December 2025  10:18:36 +0000 (0:00:00.452)       0:00:07.678 ******
2025-12-09 10:18:36.488727 | controller | skipping: [instance]
2025-12-09 10:18:36.878298 | controller |
2025-12-09 10:18:36.878328 | controller | TASK [cifmw_cephadm : Stat necessary files to bootstrap with cephadm] **********
2025-12-09 10:18:36.878336 | controller | Tuesday 09 December 2025  10:18:36 +0000 (0:00:00.026)       0:00:07.705 ******
2025-12-09 10:18:36.878346 | controller | ok: [instance] => (item=/home/ceph-admin/.ssh/id_rsa)
2025-12-09 10:18:36.915139 | controller | ok: [instance] => (item=/home/ceph-admin/.ssh/id_rsa.pub)
2025-12-09 10:18:36.915168 | controller |
2025-12-09 10:18:36.915177 | controller | TASK [cifmw_cephadm : Fail if necessary files are missing] *********************
2025-12-09 10:18:36.915185 | controller | Tuesday 09 December 2025  10:18:36 +0000 (0:00:00.389)       0:00:08.094 ******
2025-12-09 10:18:36.915203 | 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': 88087937, 'dev': 64513, 'nlink': 1, 'atime': 1765275508.2452252, 'mtime': 1765275508.2392251, 'ctime': 1765275508.240225, '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': '9101b27eb415a30936d5e8532949cdf0840c10f4', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '2063593810', '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-09 10:18:36.915751 | 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': 88087938, 'dev': 64513, 'nlink': 1, 'atime': 1765275508.2452252, 'mtime': 1765275508.2392251, 'ctime': 1765275508.240225, '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': 'e15587e4c34b5c5d8f89718d7280ff170ac012d1', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '1015477316', '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-09 10:18:37.116113 | controller | skipping: [instance]
2025-12-09 10:18:37.116143 | controller |
2025-12-09 10:18:37.116152 | controller | TASK [cifmw_cephadm : Stat pre ceph conf file in case we should bootrap with it] ***
2025-12-09 10:18:37.116159 | controller | Tuesday 09 December 2025  10:18:36 +0000 (0:00:00.037)       0:00:08.132 ******
2025-12-09 10:18:37.116169 | controller | ok: [instance]
2025-12-09 10:18:37.142999 | controller |
2025-12-09 10:18:37.143019 | controller | TASK [cifmw_cephadm : If cephadm bootstrap was not run report the reason] ******
2025-12-09 10:18:37.143033 | controller | Tuesday 09 December 2025  10:18:37 +0000 (0:00:00.200)       0:00:08.332 ******
2025-12-09 10:18:37.143071 | controller | skipping: [instance]
2025-12-09 10:18:37.394452 | controller |
2025-12-09 10:18:37.394475 | controller | TASK [Mock ceph_mon_dump command] **********************************************
2025-12-09 10:18:37.394482 | controller | Tuesday 09 December 2025  10:18:37 +0000 (0:00:00.027)       0:00:08.360 ******
2025-12-09 10:18:37.394491 | controller | changed: [instance -> localhost]
2025-12-09 10:18:37.411550 | controller |
2025-12-09 10:18:37.411571 | controller | TASK [Mock ceph_keys_module_output] ********************************************
2025-12-09 10:18:37.411580 | controller | Tuesday 09 December 2025  10:18:37 +0000 (0:00:00.251)       0:00:08.611 ******
2025-12-09 10:18:37.411591 | controller | ok: [instance]
2025-12-09 10:18:37.433006 | controller |
2025-12-09 10:18:37.433024 | controller | TASK [cifmw_cephadm : Get ceph_cli] ********************************************
2025-12-09 10:18:37.433031 | controller | Tuesday 09 December 2025  10:18:37 +0000 (0:00:00.016)       0:00:08.628 ******
2025-12-09 10:18:37.433040 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_cephadm/tasks/ceph_cli.yml for instance
2025-12-09 10:18:37.470613 | controller |
2025-12-09 10:18:37.470633 | controller | TASK [cifmw_cephadm : Set ceph CLI] ********************************************
2025-12-09 10:18:37.470640 | controller | Tuesday 09 December 2025  10:18:37 +0000 (0:00:00.021)       0:00:08.650 ******
2025-12-09 10:18:37.470649 | controller | ok: [instance]
2025-12-09 10:18:37.500119 | controller |
2025-12-09 10:18:37.500141 | controller | TASK [cifmw_cephadm : Wait for the expected number of monitors to be running] ***
2025-12-09 10:18:37.500149 | controller | Tuesday 09 December 2025  10:18:37 +0000 (0:00:00.037)       0:00:08.687 ******
2025-12-09 10:18:37.500159 | controller | skipping: [instance]
2025-12-09 10:18:37.534217 | controller |
2025-12-09 10:18:37.534248 | controller | TASK [cifmw_cephadm : Extract mons_json] ***************************************
2025-12-09 10:18:37.534257 | controller | Tuesday 09 December 2025  10:18:37 +0000 (0:00:00.029)       0:00:08.717 ******
2025-12-09 10:18:37.534266 | controller | ok: [instance]
2025-12-09 10:18:37.579144 | controller |
2025-12-09 10:18:37.579181 | controller | TASK [cifmw_cephadm : Build mons_list] *****************************************
2025-12-09 10:18:37.579189 | controller | Tuesday 09 December 2025  10:18:37 +0000 (0:00:00.034)       0:00:08.751 ******
2025-12-09 10:18:37.579201 | 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-09 10:18:37.579251 | 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-09 10:18:37.579643 | 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-09 10:18:37.606994 | controller |
2025-12-09 10:18:37.607019 | controller | TASK [cifmw_cephadm : Set cifmw_ceph_client_external_cluster_mon_ips from mons_list] ***
2025-12-09 10:18:37.607029 | controller | Tuesday 09 December 2025  10:18:37 +0000 (0:00:00.045)       0:00:08.796 ******
2025-12-09 10:18:37.607040 | controller | ok: [instance]
2025-12-09 10:18:37.607247 | controller |
2025-12-09 10:18:37.607408 | controller | TASK [cifmw_cephadm : Ensure cifmw_ceph_client_vars path exists] ***************
2025-12-09 10:18:37.607553 | controller | Tuesday 09 December 2025  10:18:37 +0000 (0:00:00.027)       0:00:08.824 ******
2025-12-09 10:18:37.636884 | controller | skipping: [instance]
2025-12-09 10:18:37.637100 | controller |
2025-12-09 10:18:37.637306 | controller | TASK [cifmw_cephadm : Save cifmw_ceph_client_vars file] ************************
2025-12-09 10:18:37.637450 | controller | Tuesday 09 December 2025  10:18:37 +0000 (0:00:00.029)       0:00:08.854 ******
2025-12-09 10:18:37.675274 | 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-09 10:18:37.675805 | controller | fatal: [instance -> localhost]: FAILED! => changed=false
2025-12-09 10:18:37.680205 | controller |   msg: 'AnsibleUndefinedVariable: ''cifmw_ceph_s3_glance_user'' is undefined. ''cifmw_ceph_s3_glance_user'' is undefined'
2025-12-09 10:18:37.680232 | controller |
2025-12-09 10:18:37.728566 | controller | PLAY RECAP *********************************************************************
2025-12-09 10:18:37.728589 | controller | instance                   : ok=20   changed=5    unreachable=0    failed=1    skipped=12   rescued=0    ignored=0
2025-12-09 10:18:37.728597 | controller |
2025-12-09 10:18:37.728603 | controller | Tuesday 09 December 2025  10:18:37 +0000 (0:00:00.041)       0:00:08.895 ******
2025-12-09 10:18:37.728609 | controller | ===============================================================================
2025-12-09 10:18:37.728614 | controller | cifmw_cephadm : Install cephadm package --------------------------------- 4.97s
2025-12-09 10:18:37.728619 | controller | Gathering Facts --------------------------------------------------------- 0.78s
2025-12-09 10:18:37.728625 | controller | cifmw_cephadm : Push it to cifmw_cephadm_assimilate_conf on bootstrap node --- 0.45s
2025-12-09 10:18:37.728630 | controller | cifmw_cephadm : Stat necessary files to bootstrap with cephadm ---------- 0.39s
2025-12-09 10:18:37.728636 | controller | cifmw_cephadm : Ensure cifmw_cephadm_config_home (e.g. /etc/ceph) exists --- 0.30s
2025-12-09 10:18:37.728641 | controller | cifmw_cephadm : Stat cephadm file --------------------------------------- 0.30s
2025-12-09 10:18:37.728647 | controller | Mock ceph_mon_dump command ---------------------------------------------- 0.25s
2025-12-09 10:18:37.728652 | controller | cifmw_cephadm : Stat cifmw_cephadm_bootstrap_conf file ------------------ 0.22s
2025-12-09 10:18:37.728657 | controller | cifmw_cephadm : Stat pre ceph conf file in case we should bootrap with it --- 0.20s
2025-12-09 10:18:37.728662 | controller | cifmw_cephadm : Ensure specs directory exists --------------------------- 0.20s
2025-12-09 10:18:37.728668 | controller | cifmw_cephadm : Stat spec file on ansible host -------------------------- 0.18s
2025-12-09 10:18:37.728673 | controller | cifmw_cephadm : Build mons_list ----------------------------------------- 0.05s
2025-12-09 10:18:37.728678 | controller | cifmw_cephadm : Save cifmw_ceph_client_vars file ------------------------ 0.04s
2025-12-09 10:18:37.728684 | controller | cifmw_cephadm : Set ceph CLI -------------------------------------------- 0.04s
2025-12-09 10:18:37.728689 | controller | cifmw_cephadm : Fail if necessary files are missing --------------------- 0.04s
2025-12-09 10:18:37.728694 | controller | cifmw_cephadm : Set FSID to the discovered value ------------------------ 0.04s
2025-12-09 10:18:37.728699 | controller | cifmw_cephadm : Extract mons_json --------------------------------------- 0.03s
2025-12-09 10:18:37.728704 | controller | cifmw_cephadm : Set first monitor IP if it was not passed --------------- 0.03s
2025-12-09 10:18:37.728710 | controller | cifmw_cephadm : Set list of found FSIDs --------------------------------- 0.03s
2025-12-09 10:18:37.728715 | controller | cifmw_cephadm : Fail if >1 FSID was discovered -------------------------- 0.03s
2025-12-09 10:18:37.728726 | 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-09 10:18:37.729172 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-12-09 10:18:37.737561 | controller | INFO     Running default > cleanup
2025-12-09 10:18:37.738003 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-12-09 10:18:37.745552 | controller | INFO     Running default > destroy
2025-12-09 10:18:37.745900 | controller | WARNING  Skipping, instances are delegated.
2025-12-09 10:18:37.746302 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-12-09 10:18:37.747788 | controller | INFO     Writing /tmp/report.html report.
2025-12-09 10:18:37.862578 | controller | ERROR
2025-12-09 10:18:37.862817 | controller | {
2025-12-09 10:18:37.862851 | controller |   "delta": "0:01:30.717661",
2025-12-09 10:18:37.862873 | controller |   "end": "2025-12-09 10:18:37.791272",
2025-12-09 10:18:37.862892 | controller |   "msg": "non-zero return code",
2025-12-09 10:18:37.862908 | controller |   "rc": 1,
2025-12-09 10:18:37.862924 | controller |   "start": "2025-12-09 10:17:07.073611"
2025-12-09 10:18:37.862939 | controller | }
failure
2025-12-09 10:18:37.867318 | 
2025-12-09 10:18:37.867371 | PLAY RECAP
2025-12-09 10:18:37.867416 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-12-09 10:18:37.867440 | 
2025-12-09 10:18:37.950065 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-09 10:18:37.952154 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-09 10:18:38.514136 | 
2025-12-09 10:18:38.514285 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-09 10:18:38.535532 | 
2025-12-09 10:18:38.535605 | TASK [Filter out host if needed]
2025-12-09 10:18:38.544549 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-09 10:18:38.548957 | 
2025-12-09 10:18:38.549022 | TASK [Ensure file is present]
2025-12-09 10:18:38.873145 | controller | ok
2025-12-09 10:18:38.880414 | 
2025-12-09 10:18:38.880482 | TASK [Manage molecule report file]
2025-12-09 10:18:39.407841 | controller | changed
2025-12-09 10:18:39.413395 | 
2025-12-09 10:18:39.413458 | TASK [Check if we get ci-framework-data basedir]
2025-12-09 10:18:39.605982 | controller | ok
2025-12-09 10:18:39.611307 | 
2025-12-09 10:18:39.611373 | TASK [Create ci-framework-data log directory for zuul]
2025-12-09 10:18:39.926637 | controller | changed
2025-12-09 10:18:39.931866 | 
2025-12-09 10:18:39.931929 | TASK [Copy ci-framework interesting files]
2025-12-09 10:18:40.127072 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-12-09 10:18:40.458873 | controller | changed
2025-12-09 10:18:40.464619 | 
2025-12-09 10:18:40.464710 | TASK [Get SELinux listing]
2025-12-09 10:18:40.991827 | controller | changed
2025-12-09 10:18:40.998673 | 
2025-12-09 10:18:40.998757 | TASK [Generate log index]
2025-12-09 10:18:41.631627 | controller | changed
2025-12-09 10:18:41.636605 | 
2025-12-09 10:18:41.636685 | TASK [Get some env related data]
2025-12-09 10:18:42.185880 | controller | /home/zuul/.local/bin/ansible
2025-12-09 10:18:43.165322 | controller | changed
2025-12-09 10:18:43.170640 | 
2025-12-09 10:18:43.170719 | TASK [Generate list of logs to collect in home directory]
2025-12-09 10:18:43.463957 | controller | ok: All paths examined
2025-12-09 10:18:43.469256 | 
2025-12-09 10:18:43.469321 | LOOP [Copy logs from home directory]
2025-12-09 10:18:43.801020 | controller | changed:
2025-12-09 10:18:43.801194 | controller | {
2025-12-09 10:18:43.801221 | controller |   "atime": 1765275409.133035,
2025-12-09 10:18:43.801241 | controller |   "ctime": 1765275424.752113,
2025-12-09 10:18:43.801258 | controller |   "dev": 64513,
2025-12-09 10:18:43.801274 | controller |   "gid": 1000,
2025-12-09 10:18:43.801290 | controller |   "gr_name": "zuul",
2025-12-09 10:18:43.801305 | controller |   "inode": 4649094,
2025-12-09 10:18:43.801320 | controller |   "isblk": false,
2025-12-09 10:18:43.801335 | controller |   "ischr": false,
2025-12-09 10:18:43.801350 | controller |   "isdir": false,
2025-12-09 10:18:43.801364 | controller |   "isfifo": false,
2025-12-09 10:18:43.801379 | controller |   "isgid": false,
2025-12-09 10:18:43.801393 | controller |   "islnk": false,
2025-12-09 10:18:43.801407 | controller |   "isreg": true,
2025-12-09 10:18:43.801421 | controller |   "issock": false,
2025-12-09 10:18:43.801434 | controller |   "isuid": false,
2025-12-09 10:18:43.801448 | controller |   "mode": "0644",
2025-12-09 10:18:43.801463 | controller |   "mtime": 1765275424.752113,
2025-12-09 10:18:43.801479 | controller |   "nlink": 1,
2025-12-09 10:18:43.801495 | controller |   "path": "/home/zuul/ansible.log",
2025-12-09 10:18:43.801510 | controller |   "pw_name": "zuul",
2025-12-09 10:18:43.801540 | controller |   "rgrp": true,
2025-12-09 10:18:43.801556 | controller |   "roth": true,
2025-12-09 10:18:43.801570 | controller |   "rusr": true,
2025-12-09 10:18:43.801584 | controller |   "size": 6749,
2025-12-09 10:18:43.801597 | controller |   "uid": 1000,
2025-12-09 10:18:43.801611 | controller |   "wgrp": false,
2025-12-09 10:18:43.801624 | controller |   "woth": false,
2025-12-09 10:18:43.801640 | controller |   "wusr": true,
2025-12-09 10:18:43.801676 | controller |   "xgrp": false,
2025-12-09 10:18:43.801694 | controller |   "xoth": false,
2025-12-09 10:18:43.801708 | controller |   "xusr": false
2025-12-09 10:18:43.801722 | controller | }
2025-12-09 10:18:43.815717 | 
2025-12-09 10:18:43.815896 | TASK [Copy crio stats log file]
2025-12-09 10:18:43.841113 | controller | skipping: Conditional result was False
2025-12-09 10:18:43.846830 | 
2025-12-09 10:18:43.846902 | TASK [Get SELinux related data]
2025-12-09 10:18:44.055848 | controller | <no matches>
2025-12-09 10:18:44.373419 | controller | ERROR
2025-12-09 10:18:44.373580 | controller | {
2025-12-09 10:18:44.373612 | controller |   "delta": "0:00:00.008383",
2025-12-09 10:18:44.373634 | controller |   "end": "2025-12-09 10:18:44.056919",
2025-12-09 10:18:44.373672 | controller |   "msg": "non-zero return code",
2025-12-09 10:18:44.373695 | controller |   "rc": 1,
2025-12-09 10:18:44.373712 | controller |   "start": "2025-12-09 10:18:44.048536"
2025-12-09 10:18:44.373726 | controller | }
2025-12-09 10:18:44.373749 | controller | ERROR: Ignoring Errors
2025-12-09 10:18:44.379126 | 
2025-12-09 10:18:44.379189 | TASK [Create system configuration directory]
2025-12-09 10:18:44.571334 | controller | changed
2025-12-09 10:18:44.576345 | 
2025-12-09 10:18:44.576419 | TASK [Get some of the system configurations]
2025-12-09 10:18:45.102841 | controller | changed
2025-12-09 10:18:45.108556 | 
2025-12-09 10:18:45.108623 | TASK [Copy generated documentation if available]
2025-12-09 10:18:45.133335 | controller | skipping: Conditional result was False
2025-12-09 10:18:45.139002 | 
2025-12-09 10:18:45.139070 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-09 10:18:45.163615 | controller | skipping: Conditional result was False
2025-12-09 10:18:45.170586 | 
2025-12-09 10:18:45.170674 | TASK [Compress logs bigger than 2MB]
2025-12-09 10:18:45.697434 | controller | changed
2025-12-09 10:18:45.703605 | 
2025-12-09 10:18:45.703707 | TASK [Copy files from workspace on node]
2025-12-09 10:18:45.723756 | controller | ok
2025-12-09 10:18:45.746290 | 
2025-12-09 10:18:45.746370 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-09 10:18:45.770138 | controller | skipping: Conditional result was False
2025-12-09 10:18:45.775478 | 
2025-12-09 10:18:45.775555 | TASK [fetch-output : Set log path for single node]
2025-12-09 10:18:45.804691 | controller | ok
2025-12-09 10:18:45.809311 | 
2025-12-09 10:18:45.809372 | LOOP [fetch-output : Ensure local output dirs]
2025-12-09 10:18:46.006496 | controller -> localhost | ok: "/var/lib/zuul/builds/4b56be4badf14eafa082909e6c86026e/work/logs"
2025-12-09 10:18:46.006904 | controller -> localhost | changed: All items complete
2025-12-09 10:18:46.006938 | 
2025-12-09 10:18:46.188838 | controller -> localhost | changed: "/var/lib/zuul/builds/4b56be4badf14eafa082909e6c86026e/work/artifacts"
2025-12-09 10:18:46.370539 | controller -> localhost | changed: "/var/lib/zuul/builds/4b56be4badf14eafa082909e6c86026e/work/docs"
2025-12-09 10:18:46.388115 | 
2025-12-09 10:18:46.388246 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-09 10:18:46.927291 | controller | changed:
2025-12-09 10:18:46.927540 | controller | .d..t...... ./
2025-12-09 10:18:46.927573 | controller | >f+++++++++ README.html
2025-12-09 10:18:46.927598 | controller | >f+++++++++ ansible-execution.log
2025-12-09 10:18:46.927623 | controller | >f+++++++++ ansible.log
2025-12-09 10:18:46.927643 | controller | >f+++++++++ dmesg.log
2025-12-09 10:18:46.927685 | controller | >f+++++++++ installed-pkgs.log
2025-12-09 10:18:46.927712 | controller | >f+++++++++ python.log
2025-12-09 10:18:46.927735 | controller | >f+++++++++ registries.conf
2025-12-09 10:18:46.927754 | controller | >f+++++++++ report.html
2025-12-09 10:18:46.927774 | controller | >f+++++++++ selinux-denials.log
2025-12-09 10:18:46.927792 | controller | >f+++++++++ selinux-listing.log
2025-12-09 10:18:46.927810 | controller | cd+++++++++ ci-framework-data/
2025-12-09 10:18:46.927829 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-09 10:18:46.927847 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-09 10:18:46.927865 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-09 10:18:46.927882 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-09 10:18:46.927898 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-09 10:18:46.927916 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-12-09 10:18:46.927939 | controller | cd+++++++++ registries.conf.d/
2025-12-09 10:18:46.927957 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-09 10:18:46.927974 | controller | cd+++++++++ system-config/
2025-12-09 10:18:46.927991 | controller | cd+++++++++ system-config/libvirt/
2025-12-09 10:18:46.928008 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-09 10:18:46.928025 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-09 10:18:47.303722 | controller | changed: .d..t...... ./
2025-12-09 10:18:47.680533 | controller | changed: .d..t...... ./
2025-12-09 10:18:47.702534 | 
2025-12-09 10:18:47.702738 | TASK [Return artifact to Zuul]
2025-12-09 10:18:47.733567 | controller | ok
2025-12-09 10:18:47.754985 | 
2025-12-09 10:18:47.755117 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-09 10:18:47.755238 | 
2025-12-09 10:18:47.755263 | PLAY RECAP
2025-12-09 10:18:47.755301 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-12-09 10:18:47.755321 | 
2025-12-09 10:18:47.854163 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-09 10:18:47.856258 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-09 10:18:48.401011 | 
2025-12-09 10:18:48.401151 | PLAY [all]
2025-12-09 10:18:48.420534 | 
2025-12-09 10:18:48.420625 | TASK [include_role : fetch-output]
2025-12-09 10:18:48.450711 | controller | ok
2025-12-09 10:18:48.467905 | 
2025-12-09 10:18:48.467998 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-09 10:18:48.513457 | controller | skipping: Conditional result was False
2025-12-09 10:18:48.519210 | 
2025-12-09 10:18:48.519279 | TASK [fetch-output : Set log path for single node]
2025-12-09 10:18:48.558857 | controller | ok
2025-12-09 10:18:48.563771 | 
2025-12-09 10:18:48.563841 | LOOP [fetch-output : Ensure local output dirs]
2025-12-09 10:18:48.922691 | controller -> localhost | ok: "/var/lib/zuul/builds/4b56be4badf14eafa082909e6c86026e/work/logs"
2025-12-09 10:18:49.111222 | controller -> localhost | ok: "/var/lib/zuul/builds/4b56be4badf14eafa082909e6c86026e/work/artifacts"
2025-12-09 10:18:49.300255 | controller -> localhost | ok: "/var/lib/zuul/builds/4b56be4badf14eafa082909e6c86026e/work/docs"
2025-12-09 10:18:49.316907 | 
2025-12-09 10:18:49.317048 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-09 10:18:49.853038 | controller | ok
2025-12-09 10:18:49.853292 | controller | ok: All items complete
2025-12-09 10:18:49.853321 | 
2025-12-09 10:18:50.219130 | controller | ok
2025-12-09 10:18:50.576844 | controller | ok
2025-12-09 10:18:50.589388 | 
2025-12-09 10:18:50.589461 | TASK [include_role : fetch-output-openshift]
2025-12-09 10:18:50.604926 | controller | skipping: Conditional result was False
2025-12-09 10:18:50.611277 | 
2025-12-09 10:18:50.611347 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-09 10:18:50.969323 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007585
2025-12-09 10:18:51.163119 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007091
2025-12-09 10:18:51.197938 | 
2025-12-09 10:18:51.198010 | PLAY [all]
2025-12-09 10:18:51.211463 | 
2025-12-09 10:18:51.211550 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-09 10:18:51.617849 | controller | changed
2025-12-09 10:18:51.639069 | 
2025-12-09 10:18:51.639149 | PLAY RECAP
2025-12-09 10:18:51.639200 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-09 10:18:51.639223 | 
2025-12-09 10:18:51.726055 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-09 10:18:51.726923 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-09 10:18:52.296926 | 
2025-12-09 10:18:52.297054 | PLAY [localhost]
2025-12-09 10:18:52.314535 | 
2025-12-09 10:18:52.314614 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-09 10:18:52.647857 | localhost | changed
2025-12-09 10:18:52.652919 | 
2025-12-09 10:18:52.653036 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-09 10:18:52.682849 | localhost | ok
2025-12-09 10:18:52.691027 | 
2025-12-09 10:18:52.691113 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-09 10:18:53.004405 | localhost | changed
2025-12-09 10:18:53.010405 | 
2025-12-09 10:18:53.010480 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-09 10:18:53.587436 | localhost | changed
2025-12-09 10:18:53.592571 | 
2025-12-09 10:18:53.592639 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-09 10:18:53.947204 | localhost | Identity added: /var/lib/zuul/builds/4b56be4badf14eafa082909e6c86026e/work/tmp/ansible.5pkzfhgp (/var/lib/zuul/builds/4b56be4badf14eafa082909e6c86026e/work/tmp/ansible.5pkzfhgp)
2025-12-09 10:18:53.947416 | localhost | ok: Runtime: 0:00:00.006833
2025-12-09 10:18:53.951876 | 
2025-12-09 10:18:53.951944 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-09 10:18:54.174289 | localhost | ok: Runtime: 0:00:00.004729
2025-12-09 10:18:54.179225 | 
2025-12-09 10:18:54.179291 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-09 10:18:54.229680 | localhost | changed
2025-12-09 10:18:54.233858 | 
2025-12-09 10:18:54.233924 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-09 10:18:54.567057 | localhost | changed
2025-12-09 10:18:54.586792 | 
2025-12-09 10:18:54.586848 | PLAY [localhost]
2025-12-09 10:18:54.598400 | 
2025-12-09 10:18:54.598465 | TASK [Generate bulk log download script]
2025-12-09 10:18:54.617435 | localhost | ok
2025-12-09 10:18:54.628777 | 
2025-12-09 10:18:54.628842 | TASK [local-log-download : Check API endpoint is defined]
2025-12-09 10:18:54.656096 | localhost | ok: All assertions passed
2025-12-09 10:18:54.660374 | 
2025-12-09 10:18:54.660436 | TASK [local-log-download : Create download script]
2025-12-09 10:18:55.028194 | localhost -> localhost | changed
2025-12-09 10:18:55.037413 | 
2025-12-09 10:18:55.037488 | TASK [Register quick-download link]
2025-12-09 10:18:55.054620 | localhost | ok
2025-12-09 10:18:55.096935 | 
2025-12-09 10:18:55.097040 | PLAY [logserver.rdoproject.org]
2025-12-09 10:18:55.106564 | 
2025-12-09 10:18:55.106626 | TASK [Set zuul-log-path fact]
2025-12-09 10:18:55.123169 | logserver.rdoproject.org | ok
2025-12-09 10:18:55.131892 | 
2025-12-09 10:18:55.131957 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-09 10:18:55.157810 | logserver.rdoproject.org | ok
2025-12-09 10:18:55.162996 | 
2025-12-09 10:18:55.163060 | TASK [upload-logs : Create log directories]
2025-12-09 10:18:57.033568 | logserver.rdoproject.org | changed
2025-12-09 10:18:57.036886 | 
2025-12-09 10:18:57.036952 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-09 10:18:57.275317 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.006487
2025-12-09 10:18:57.281303 | 
2025-12-09 10:18:57.281383 | TASK [upload-logs : Upload logs to log server]
2025-12-09 10:18:58.682547 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-09 10:18:58.687544 | 
2025-12-09 10:18:58.687617 | LOOP [upload-logs : Compress console log and json output]
2025-12-09 10:18:58.733155 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-09 10:18:58.741777 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-09 10:18:58.747941 | 
2025-12-09 10:18:58.748016 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-09 10:18:58.790925 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-09 10:18:58.791158 | 
2025-12-09 10:18:58.794255 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-09 10:18:58.809043 | 
2025-12-09 10:18:58.809118 | LOOP [upload-logs : Upload console log and json output]