2025-12-08 12:00:41.190165 | Job console starting...
2025-12-08 12:00:41.403207 | Updating repositories
2025-12-08 12:00:42.175348 | Preparing job workspace
2025-12-08 12:00:49.336381 | Running Ansible setup...
2025-12-08 12:00:52.105992 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-08 12:00:52.584508 | 
2025-12-08 12:00:52.584613 | PLAY [localhost]
2025-12-08 12:00:52.592824 | 
2025-12-08 12:00:52.592895 | TASK [Gathering Facts]
2025-12-08 12:00:53.397210 | localhost | ok
2025-12-08 12:00:53.410330 | 
2025-12-08 12:00:53.410436 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-08 12:00:53.728653 | localhost -> localhost | changed
2025-12-08 12:00:53.734206 | 
2025-12-08 12:00:53.734279 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-08 12:00:54.396788 | localhost -> localhost | changed
2025-12-08 12:00:54.405333 | 
2025-12-08 12:00:54.405401 | TASK [Setup log path fact]
2025-12-08 12:00:54.422851 | localhost | ok
2025-12-08 12:00:54.434285 | 
2025-12-08 12:00:54.434356 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-08 12:00:54.461513 | localhost | ok
2025-12-08 12:00:54.469184 | 
2025-12-08 12:00:54.469252 | TASK [emit-job-header : Print job information]
2025-12-08 12:00:54.496556 | # Job Information
2025-12-08 12:00:54.496679 | Ansible Version: 2.15.12
2025-12-08 12:00:54.496709 | Job: cifmw-molecule-cifmw_cephadm
2025-12-08 12:00:54.496733 | Pipeline: github-check
2025-12-08 12:00:54.496753 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-12-08 12:00:54.496771 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3169
2025-12-08 12:00:54.496790 | Log URL (when completed): https://logserver.rdoproject.org/cea/rdoproject.org/cea78b4f84fb4537b7c73f0a1e00e586/
2025-12-08 12:00:54.496822 | Event ID: 60f8cba0-d42d-11f0-93db-79bf0252f942
2025-12-08 12:00:54.500372 | 
2025-12-08 12:00:54.500436 | LOOP [emit-job-header : Print node information]
2025-12-08 12:00:54.595821 | localhost | ok:
2025-12-08 12:00:54.596228 | localhost | # Node Information
2025-12-08 12:00:54.596272 | localhost | Inventory Hostname: controller
2025-12-08 12:00:54.596311 | localhost | Hostname: np0005550294
2025-12-08 12:00:54.596341 | localhost | Username: zuul
2025-12-08 12:00:54.596369 | localhost | Distro: CentOS 9
2025-12-08 12:00:54.596394 | localhost | Provider: ibm-bm4-nodepool
2025-12-08 12:00:54.596417 | localhost | Region: regionOne
2025-12-08 12:00:54.596441 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-12-08 12:00:54.596465 | localhost | Product Name: OpenStack Compute
2025-12-08 12:00:54.596488 | localhost | Interface IP: 192.168.26.26
2025-12-08 12:00:54.617320 | 
2025-12-08 12:00:54.617437 | PLAY [all]
2025-12-08 12:00:54.623662 | 
2025-12-08 12:00:54.623725 | TASK [Gather network facts]
2025-12-08 12:00:54.987862 | controller | ok
2025-12-08 12:00:55.001710 | 
2025-12-08 12:00:55.001776 | TASK [include_role : start-zuul-console]
2025-12-08 12:00:55.020600 | controller | ok
2025-12-08 12:00:55.033255 | 
2025-12-08 12:00:55.033322 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-08 12:00:55.356147 | controller | ok
2025-12-08 12:00:55.365026 | 
2025-12-08 12:00:55.365098 | TASK [include_role : add-build-sshkey]
2025-12-08 12:00:55.394622 | controller | ok
2025-12-08 12:00:55.407879 | 
2025-12-08 12:00:55.407984 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-08 12:00:55.604349 | controller -> localhost | ok
2025-12-08 12:00:55.609961 | 
2025-12-08 12:00:55.610038 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-08 12:00:55.628473 | controller | ok
2025-12-08 12:00:55.641975 | controller | included: /var/lib/zuul/builds/cea78b4f84fb4537b7c73f0a1e00e586/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-08 12:00:55.647196 | 
2025-12-08 12:00:55.647265 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-08 12:00:56.096250 | controller -> localhost | Generating public/private rsa key pair.
2025-12-08 12:00:56.096478 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/cea78b4f84fb4537b7c73f0a1e00e586/work/cea78b4f84fb4537b7c73f0a1e00e586_id_rsa.
2025-12-08 12:00:56.096515 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/cea78b4f84fb4537b7c73f0a1e00e586/work/cea78b4f84fb4537b7c73f0a1e00e586_id_rsa.pub.
2025-12-08 12:00:56.096541 | controller -> localhost | The key fingerprint is:
2025-12-08 12:00:56.096563 | controller -> localhost | SHA256:A+80v1W+FdLpLkWl7DN5CU6o+DKHQBdAsQXktmCFpdo zuul-build-sshkey
2025-12-08 12:00:56.096586 | controller -> localhost | The key's randomart image is:
2025-12-08 12:00:56.096606 | controller -> localhost | +---[RSA 3072]----+
2025-12-08 12:00:56.096624 | controller -> localhost | |    =O+.         |
2025-12-08 12:00:56.096642 | controller -> localhost | |   .+ o.        .|
2025-12-08 12:00:56.096659 | controller -> localhost | |   + +. .   .. ..|
2025-12-08 12:00:56.096676 | controller -> localhost | |  + o..+   . o+..|
2025-12-08 12:00:56.096693 | controller -> localhost | | . E... S . oo+=.|
2025-12-08 12:00:56.096739 | controller -> localhost | |     . + =   +Boo|
2025-12-08 12:00:56.096760 | controller -> localhost | |      . + . . o=.|
2025-12-08 12:00:56.096778 | controller -> localhost | |       + o o ..o |
2025-12-08 12:00:56.096806 | controller -> localhost | |        + .   o. |
2025-12-08 12:00:56.096824 | controller -> localhost | +----[SHA256]-----+
2025-12-08 12:00:56.096872 | controller -> localhost | ok: Runtime: 0:00:00.124951
2025-12-08 12:00:56.103542 | 
2025-12-08 12:00:56.103608 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-08 12:00:56.132324 | controller | ok
2025-12-08 12:00:56.142475 | controller | included: /var/lib/zuul/builds/cea78b4f84fb4537b7c73f0a1e00e586/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-08 12:00:56.150502 | 
2025-12-08 12:00:56.150565 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-08 12:00:56.165329 | controller | skipping: Conditional result was False
2025-12-08 12:00:56.171167 | 
2025-12-08 12:00:56.171236 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-08 12:00:56.565038 | controller | changed
2025-12-08 12:00:56.571409 | 
2025-12-08 12:00:56.571473 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-08 12:00:56.772407 | controller | ok
2025-12-08 12:00:56.777396 | 
2025-12-08 12:00:56.777482 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-08 12:00:57.303053 | controller | changed
2025-12-08 12:00:57.307781 | 
2025-12-08 12:00:57.307852 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-08 12:00:57.841425 | controller | changed
2025-12-08 12:00:57.846340 | 
2025-12-08 12:00:57.846408 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-08 12:00:57.859214 | controller | skipping: Conditional result was False
2025-12-08 12:00:57.864335 | 
2025-12-08 12:00:57.864401 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-08 12:00:58.151530 | controller -> localhost | changed
2025-12-08 12:00:58.160653 | 
2025-12-08 12:00:58.160743 | TASK [add-build-sshkey : Add back temp key]
2025-12-08 12:00:58.377581 | controller -> localhost | Identity added: /var/lib/zuul/builds/cea78b4f84fb4537b7c73f0a1e00e586/work/cea78b4f84fb4537b7c73f0a1e00e586_id_rsa (zuul-build-sshkey)
2025-12-08 12:00:58.377758 | controller -> localhost | ok: Runtime: 0:00:00.006460
2025-12-08 12:00:58.383135 | 
2025-12-08 12:00:58.383195 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-08 12:00:58.655087 | controller | ok
2025-12-08 12:00:58.659466 | 
2025-12-08 12:00:58.659525 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-08 12:00:58.683229 | controller | skipping: Conditional result was False
2025-12-08 12:00:58.691727 | 
2025-12-08 12:00:58.691787 | TASK [include_role : validate-host]
2025-12-08 12:00:58.709455 | controller | ok
2025-12-08 12:00:58.728810 | 
2025-12-08 12:00:58.728876 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-08 12:00:58.756182 | controller | ok
2025-12-08 12:00:58.760300 | 
2025-12-08 12:00:58.760361 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-08 12:00:58.945745 | controller -> localhost | ok
2025-12-08 12:00:58.951641 | 
2025-12-08 12:00:58.951716 | TASK [validate-host : Collect information about the host]
2025-12-08 12:00:59.548608 | controller | ok
2025-12-08 12:00:59.557039 | 
2025-12-08 12:00:59.557122 | TASK [validate-host : Sanitize hostname]
2025-12-08 12:00:59.626099 | controller | ok
2025-12-08 12:00:59.630866 | 
2025-12-08 12:00:59.630944 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-08 12:01:00.021474 | controller -> localhost | changed
2025-12-08 12:01:00.047158 | 
2025-12-08 12:01:00.047256 | TASK [validate-host : Collect information about zuul worker]
2025-12-08 12:01:00.361362 | controller | ok
2025-12-08 12:01:00.366564 | 
2025-12-08 12:01:00.366652 | TASK [validate-host : Write out all zuul information for each host]
2025-12-08 12:01:00.725408 | controller -> localhost | changed
2025-12-08 12:01:00.735476 | 
2025-12-08 12:01:00.735549 | TASK [include_role : prepare-workspace-openshift]
2025-12-08 12:01:00.750153 | controller | skipping: Conditional result was False
2025-12-08 12:01:00.755967 | 
2025-12-08 12:01:00.756033 | TASK [include_role : remove-zuul-sshkey]
2025-12-08 12:01:00.769724 | controller | skipping: Conditional result was False
2025-12-08 12:01:00.775480 | 
2025-12-08 12:01:00.775552 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-08 12:01:00.961737 | controller | ok: "logs"
2025-12-08 12:01:00.962376 | controller | ok: All items complete
2025-12-08 12:01:00.962437 | 
2025-12-08 12:01:01.120419 | controller | ok: "artifacts"
2025-12-08 12:01:01.278489 | controller | ok: "docs"
2025-12-08 12:01:01.291448 | 
2025-12-08 12:01:01.291526 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-08 12:01:01.487638 | controller | changed: "logs"
2025-12-08 12:01:01.644306 | controller | changed: "artifacts"
2025-12-08 12:01:01.802204 | controller | changed: "docs"
2025-12-08 12:01:01.833518 | 
2025-12-08 12:01:01.833590 | PLAY RECAP
2025-12-08 12:01:01.833640 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-08 12:01:01.833670 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-08 12:01:01.833690 | 
2025-12-08 12:01:01.910601 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-08 12:01:01.911379 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-08 12:01:02.417334 | 
2025-12-08 12:01:02.417482 | PLAY [all]
2025-12-08 12:01:02.435236 | 
2025-12-08 12:01:02.435303 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-08 12:01:02.483271 | controller | ok
2025-12-08 12:01:02.488039 | 
2025-12-08 12:01:02.488112 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-08 12:01:02.789442 | controller | changed
2025-12-08 12:01:02.795065 | 
2025-12-08 12:01:02.795146 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-08 12:01:03.521725 | controller | changed
2025-12-08 12:01:03.531032 | 
2025-12-08 12:01:03.531107 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-08 12:01:03.859454 | controller | changed:
2025-12-08 12:01:03.859633 | controller | {
2025-12-08 12:01:03.859667 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-08 12:01:03.859819 | controller | }
2025-12-08 12:01:04.047693 | controller | changed:
2025-12-08 12:01:04.047746 | controller | {
2025-12-08 12:01:04.047775 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-08 12:01:04.047806 | controller | }
2025-12-08 12:01:04.240299 | controller | changed:
2025-12-08 12:01:04.240351 | controller | {
2025-12-08 12:01:04.240377 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-12-08 12:01:04.240397 | controller | }
2025-12-08 12:01:04.429042 | controller | changed:
2025-12-08 12:01:04.429098 | controller | {
2025-12-08 12:01:04.429125 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-08 12:01:04.429145 | controller | }
2025-12-08 12:01:04.621297 | controller | changed:
2025-12-08 12:01:04.621348 | controller | {
2025-12-08 12:01:04.621374 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-08 12:01:04.621394 | controller | }
2025-12-08 12:01:04.811928 | controller | changed:
2025-12-08 12:01:04.811982 | controller | {
2025-12-08 12:01:04.812009 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-08 12:01:04.812030 | controller | }
2025-12-08 12:01:05.001205 | controller | changed:
2025-12-08 12:01:05.001255 | controller | {
2025-12-08 12:01:05.001280 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-12-08 12:01:05.001301 | controller | }
2025-12-08 12:01:05.189247 | controller | changed:
2025-12-08 12:01:05.189297 | controller | {
2025-12-08 12:01:05.189324 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-12-08 12:01:05.189345 | controller | }
2025-12-08 12:01:05.376456 | controller | changed:
2025-12-08 12:01:05.376514 | controller | {
2025-12-08 12:01:05.376541 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-08 12:01:05.376561 | controller | }
2025-12-08 12:01:05.564131 | controller | changed:
2025-12-08 12:01:05.564346 | controller | {
2025-12-08 12:01:05.564378 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-08 12:01:05.564401 | controller | }
2025-12-08 12:01:05.755313 | controller | changed:
2025-12-08 12:01:05.755367 | controller | {
2025-12-08 12:01:05.755394 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-08 12:01:05.755415 | controller | }
2025-12-08 12:01:05.960371 | controller | changed:
2025-12-08 12:01:05.960522 | controller | {
2025-12-08 12:01:05.960557 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-08 12:01:05.960581 | controller | }
2025-12-08 12:01:06.136133 | controller | changed:
2025-12-08 12:01:06.136197 | controller | {
2025-12-08 12:01:06.136224 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-08 12:01:06.136246 | controller | }
2025-12-08 12:01:06.326965 | controller | changed:
2025-12-08 12:01:06.327018 | controller | {
2025-12-08 12:01:06.327043 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-08 12:01:06.327062 | controller | }
2025-12-08 12:01:06.515428 | controller | changed:
2025-12-08 12:01:06.515479 | controller | {
2025-12-08 12:01:06.515505 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-08 12:01:06.515524 | controller | }
2025-12-08 12:01:06.702186 | controller | changed:
2025-12-08 12:01:06.702239 | controller | {
2025-12-08 12:01:06.702265 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-08 12:01:06.702284 | controller | }
2025-12-08 12:01:06.897538 | controller | changed:
2025-12-08 12:01:06.897598 | controller | {
2025-12-08 12:01:06.897623 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-12-08 12:01:06.897644 | controller | }
2025-12-08 12:01:07.091570 | controller | changed:
2025-12-08 12:01:07.091623 | controller | {
2025-12-08 12:01:07.091648 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-12-08 12:01:07.091674 | controller | }
2025-12-08 12:01:07.286238 | controller | changed:
2025-12-08 12:01:07.286330 | controller | {
2025-12-08 12:01:07.286357 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-08 12:01:07.286376 | controller | }
2025-12-08 12:01:07.476770 | controller | changed:
2025-12-08 12:01:07.477110 | controller | {
2025-12-08 12:01:07.477143 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-08 12:01:07.477172 | controller | }
2025-12-08 12:01:07.672905 | controller | changed:
2025-12-08 12:01:07.673012 | controller | {
2025-12-08 12:01:07.673116 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-08 12:01:07.673254 | controller | }
2025-12-08 12:01:07.866313 | controller | changed:
2025-12-08 12:01:07.866460 | controller | {
2025-12-08 12:01:07.866491 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-08 12:01:07.866512 | controller | }
2025-12-08 12:01:08.063152 | controller | changed:
2025-12-08 12:01:08.063223 | controller | {
2025-12-08 12:01:08.063251 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-12-08 12:01:08.063273 | controller | }
2025-12-08 12:01:08.257946 | controller | changed:
2025-12-08 12:01:08.258022 | controller | {
2025-12-08 12:01:08.258049 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-08 12:01:08.258070 | controller | }
2025-12-08 12:01:08.448737 | controller | changed:
2025-12-08 12:01:08.448810 | controller | {
2025-12-08 12:01:08.448837 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-08 12:01:08.448859 | controller | }
2025-12-08 12:01:08.648677 | controller | changed:
2025-12-08 12:01:08.648754 | controller | {
2025-12-08 12:01:08.648783 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-08 12:01:08.648816 | controller | }
2025-12-08 12:01:08.670504 | 
2025-12-08 12:01:08.670577 | TASK [Set timezone to UTC]
2025-12-08 12:01:09.042961 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-08 12:01:09.047562 | 
2025-12-08 12:01:09.047624 | TASK [Create nodepool directory]
2025-12-08 12:01:09.249154 | controller | changed
2025-12-08 12:01:09.254369 | 
2025-12-08 12:01:09.254433 | TASK [Create nodepool sub_nodes file]
2025-12-08 12:01:09.737734 | controller | changed
2025-12-08 12:01:09.742392 | 
2025-12-08 12:01:09.742455 | TASK [Create nodepool sub_nodes_private file]
2025-12-08 12:01:10.235486 | controller | changed
2025-12-08 12:01:10.240301 | 
2025-12-08 12:01:10.240360 | LOOP [Populate nodepool sub_nodes file]
2025-12-08 12:01:10.269204 | 
2025-12-08 12:01:10.269317 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-08 12:01:10.296937 | 
2025-12-08 12:01:10.297060 | TASK [Create nodepool primary file]
2025-12-08 12:01:10.319591 | controller | skipping: Conditional result was False
2025-12-08 12:01:10.324335 | 
2025-12-08 12:01:10.324404 | TASK [Create nodepool node_private for this node]
2025-12-08 12:01:10.847134 | controller | changed
2025-12-08 12:01:10.852331 | 
2025-12-08 12:01:10.852391 | LOOP [Copy ssh keys to nodepool directory]
2025-12-08 12:01:11.165816 | controller | ok: Item: id_rsa Runtime: 0:00:00.005278
2025-12-08 12:01:11.166007 | 
2025-12-08 12:01:11.322486 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004817
2025-12-08 12:01:11.335103 | 
2025-12-08 12:01:11.335183 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-08 12:01:11.861145 | controller | changed
2025-12-08 12:01:11.866110 | 
2025-12-08 12:01:11.866178 | TASK [Validate sudoers config after edits]
2025-12-08 12:01:12.073577 | controller | /etc/sudoers: parsed OK
2025-12-08 12:01:12.073622 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-08 12:01:12.073631 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-08 12:01:12.073637 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-08 12:01:12.391483 | controller | ok: Runtime: 0:00:00.005409
2025-12-08 12:01:12.396896 | 
2025-12-08 12:01:12.396976 | TASK [Show the environment passed in to job shell scripts]
2025-12-08 12:01:12.585879 | controller | SHELL=/bin/bash
2025-12-08 12:01:12.585915 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-08 12:01:12.585925 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-08 12:01:12.585932 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/69/3169/0842d842974276f3c7200b524f6073685714cae2
2025-12-08 12:01:12.585938 | controller | PWD=/home/zuul
2025-12-08 12:01:12.585943 | controller | ZUUL_PIPELINE=github-check
2025-12-08 12:01:12.585949 | controller | LOGNAME=zuul
2025-12-08 12:01:12.585954 | controller | XDG_SESSION_TYPE=tty
2025-12-08 12:01:12.585959 | controller | _=/usr/bin/env
2025-12-08 12:01:12.585964 | controller | MOTD_SHOWN=pam
2025-12-08 12:01:12.585970 | controller | HOME=/home/zuul
2025-12-08 12:01:12.586002 | controller | LANG=en_US.UTF-8
2025-12-08 12:01:12.586010 | controller | SSH_CONNECTION=192.168.26.12 54966 192.168.26.26 22
2025-12-08 12:01:12.586015 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-12-08 12:01:12.586023 | controller | ZUUL_CHANGE_IDS=3169,0842d842974276f3c7200b524f6073685714cae2
2025-12-08 12:01:12.586029 | controller | WORKSPACE=/home/zuul/workspace
2025-12-08 12:01:12.586034 | controller | XDG_SESSION_CLASS=user
2025-12-08 12:01:12.586040 | controller | SELINUX_ROLE_REQUESTED=
2025-12-08 12:01:12.586045 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-08 12:01:12.586050 | controller | USER=zuul
2025-12-08 12:01:12.586056 | controller | ZUUL_VOTING=True
2025-12-08 12:01:12.586061 | controller | BUILD_TIMEOUT=1800000
2025-12-08 12:01:12.586067 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-08 12:01:12.586072 | controller | SHLVL=1
2025-12-08 12:01:12.586077 | controller | ZUUL_PATCHSET=0842d842974276f3c7200b524f6073685714cae2
2025-12-08 12:01:12.586083 | controller | XDG_SESSION_ID=1
2025-12-08 12:01:12.586088 | controller | ZUUL_BRANCH=main
2025-12-08 12:01:12.586093 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-08 12:01:12.586099 | controller | SSH_CLIENT=192.168.26.12 54966 22
2025-12-08 12:01:12.586106 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-08 12:01:12.586111 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-08 12:01:12.586117 | controller | which_declare=declare -f
2025-12-08 12:01:12.586122 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-08 12:01:12.586127 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-08 12:01:12.586133 | controller | ZUUL_CHANGE=3169
2025-12-08 12:01:12.586138 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-08 12:01:12.586143 | controller | ZUUL_UUID=cea78b4f84fb4537b7c73f0a1e00e586
2025-12-08 12:01:12.586149 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-08 12:01:12.586154 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-08 12:01:12.586160 | controller | }
2025-12-08 12:01:12.922818 | controller | ok: Runtime: 0:00:00.006399
2025-12-08 12:01:12.928500 | 
2025-12-08 12:01:12.928561 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-08 12:01:12.942117 | controller | skipping: Conditional result was False
2025-12-08 12:01:12.947140 | 
2025-12-08 12:01:12.947202 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-08 12:01:13.471433 | controller | skipping: Conditional result was False
2025-12-08 12:01:13.477195 | 
2025-12-08 12:01:13.477274 | TASK [Ensure legacy workspace directory]
2025-12-08 12:01:13.657057 | controller | changed
2025-12-08 12:01:13.674627 | 
2025-12-08 12:01:13.674677 | PLAY RECAP
2025-12-08 12:01:13.674723 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-08 12:01:13.674746 | 
2025-12-08 12:01:13.743688 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-08 12:01:13.744479 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-08 12:01:14.234130 | 
2025-12-08 12:01:14.234257 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-08 12:01:14.253821 | 
2025-12-08 12:01:14.253893 | TASK [Create zuul-output directory]
2025-12-08 12:01:14.557153 | controller | changed
2025-12-08 12:01:14.562105 | 
2025-12-08 12:01:14.562198 | TASK [Slurp Zuul inventory test]
2025-12-08 12:01:14.829056 | controller -> localhost | ok
2025-12-08 12:01:14.835177 | 
2025-12-08 12:01:14.835243 | TASK [Save zuul inventory]
2025-12-08 12:01:15.543175 | controller | changed
2025-12-08 12:01:15.547971 | 
2025-12-08 12:01:15.548036 | TASK [Save zuul vars without the change_message]
2025-12-08 12:01:16.114897 | controller | changed
2025-12-08 12:01:16.132012 | 
2025-12-08 12:01:16.132071 | PLAY RECAP
2025-12-08 12:01:16.132112 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-08 12:01:16.132135 | 
2025-12-08 12:01:16.194107 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-08 12:01:16.194956 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-08 12:01:16.735181 | 
2025-12-08 12:01:16.735286 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-08 12:01:16.755161 | 
2025-12-08 12:01:16.755234 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-08 12:01:16.773406 | controller | ok
2025-12-08 12:01:16.790114 | 
2025-12-08 12:01:16.790199 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-08 12:01:16.813225 | controller | skipping: Conditional result was False
2025-12-08 12:01:16.818811 | 
2025-12-08 12:01:16.818892 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-08 12:01:17.130600 | controller | ok
2025-12-08 12:01:17.136083 | 
2025-12-08 12:01:17.136153 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-08 12:01:17.712857 | controller | ok
2025-12-08 12:01:17.722847 | 
2025-12-08 12:01:17.722927 | TASK [Prepare workspace]
2025-12-08 12:01:17.740620 | controller | ok
2025-12-08 12:01:17.757617 | 
2025-12-08 12:01:17.757681 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-08 12:01:18.038627 | controller | ok
2025-12-08 12:01:18.044418 | 
2025-12-08 12:01:18.044489 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-08 12:01:20.451745 | controller | Output suppressed because no_log was given
2025-12-08 12:01:20.462609 | 
2025-12-08 12:01:20.462724 | LOOP [Create zuul-output directory]
2025-12-08 12:01:20.647241 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-08 12:01:20.800480 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-08 12:01:20.806794 | 
2025-12-08 12:01:20.806924 | TASK [Install required packages]
2025-12-08 12:02:14.310714 | controller | changed
2025-12-08 12:02:14.315935 | 
2025-12-08 12:02:14.315998 | TASK [Install venv]
2025-12-08 12:03:12.879064 | controller | changed
2025-12-08 12:03:12.903423 | 
2025-12-08 12:03:12.903482 | PLAY RECAP
2025-12-08 12:03:12.903528 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-08 12:03:12.903553 | 
2025-12-08 12:03:12.974372 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-08 12:03:12.975239 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-08 12:03:13.467807 | 
2025-12-08 12:03:13.467972 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-12-08 12:03:13.489882 | 
2025-12-08 12:03:13.489970 | TASK [Gather required facts]
2025-12-08 12:03:13.945585 | controller | ok
2025-12-08 12:03:13.950665 | 
2025-12-08 12:03:13.950740 | TASK [Load environment var if instructed to]
2025-12-08 12:03:13.974427 | controller | skipping: Conditional result was False
2025-12-08 12:03:13.979381 | 
2025-12-08 12:03:13.979445 | TASK [Ensure group_vars dir exists]
2025-12-08 12:03:14.271278 | controller | ok
2025-12-08 12:03:14.277171 | 
2025-12-08 12:03:14.277234 | TASK [Print related variables]
2025-12-08 12:03:14.305529 | controller | ok:
2025-12-08 12:03:14.305634 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-12-08 12:03:14.305661 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_cephadm
2025-12-08 12:03:14.310086 | 
2025-12-08 12:03:14.310150 | TASK [Run molecule]
2025-12-08 12:03:15.201393 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-12-08 12:03:15.264586 | controller | INFO     Performing prerun with role_name_check=0...
2025-12-08 12:03:31.646450 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 12:03:31.646902 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 12:03:31.647478 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 12:03:31.647880 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 12:03:31.648401 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+b3c2a495 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 1.0.0+b3c2a495 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 12:03:31.648840 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 12:03:31.649274 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 12:03:31.649710 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 12:03:31.650144 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 12:03:31.650589 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 12:03:31.651024 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 12:03:31.651465 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 12:03:31.651896 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 12:03:31.652359 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 12:03:31.652800 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-08 12:03:31.662218 | controller | INFO     Running default > prepare
2025-12-08 12:03:32.289966 | controller |
2025-12-08 12:03:32.290058 | controller | PLAY [Prepare] *****************************************************************
2025-12-08 12:03:32.290142 | controller |
2025-12-08 12:03:32.290259 | controller | TASK [Gathering Facts] *********************************************************
2025-12-08 12:03:32.290351 | controller | Monday 08 December 2025  12:03:32 +0000 (0:00:00.011)       0:00:00.011 *******
2025-12-08 12:03:33.085720 | controller | ok: [instance]
2025-12-08 12:03:33.085795 | controller |
2025-12-08 12:03:33.085904 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-12-08 12:03:33.086022 | controller | Monday 08 December 2025  12:03:33 +0000 (0:00:00.796)       0:00:00.807 *******
2025-12-08 12:03:33.103053 | controller | skipping: [instance]
2025-12-08 12:03:33.103141 | controller |
2025-12-08 12:03:33.103268 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-12-08 12:03:33.103367 | controller | Monday 08 December 2025  12:03:33 +0000 (0:00:00.017)       0:00:00.825 *******
2025-12-08 12:03:33.142519 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-12-08 12:03:33.142606 | controller |
2025-12-08 12:03:33.142704 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-12-08 12:03:33.142800 | controller | Monday 08 December 2025  12:03:33 +0000 (0:00:00.039)       0:00:00.865 *******
2025-12-08 12:03:33.429209 | controller | ok: [instance]
2025-12-08 12:03:33.429259 | controller |
2025-12-08 12:03:33.429365 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-12-08 12:03:33.429462 | controller | Monday 08 December 2025  12:03:33 +0000 (0:00:00.286)       0:00:01.151 *******
2025-12-08 12:03:33.713969 | controller | ok: [instance]
2025-12-08 12:03:33.714042 | controller |
2025-12-08 12:03:33.714141 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-12-08 12:03:33.714274 | controller | Monday 08 December 2025  12:03:33 +0000 (0:00:00.284)       0:00:01.436 *******
2025-12-08 12:03:34.357786 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-12-08 12:03:34.357870 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-12-08 12:03:34.357980 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-12-08 12:03:34.358072 | controller |
2025-12-08 12:03:34.358192 | controller | TASK [Deploy repo-setup] *******************************************************
2025-12-08 12:03:34.358277 | controller | Monday 08 December 2025  12:03:34 +0000 (0:00:00.643)       0:00:02.080 *******
2025-12-08 12:03:34.413811 | controller |
2025-12-08 12:03:34.413978 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-12-08 12:03:34.414232 | controller | Monday 08 December 2025  12:03:34 +0000 (0:00:00.056)       0:00:02.136 *******
2025-12-08 12:03:34.972778 | controller | changed: [instance] => (item=tmp)
2025-12-08 12:03:34.972865 | controller | changed: [instance] => (item=artifacts/repositories)
2025-12-08 12:03:34.972985 | controller | changed: [instance] => (item=venv/repo_setup)
2025-12-08 12:03:34.973087 | controller |
2025-12-08 12:03:34.973206 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-12-08 12:03:34.973313 | controller | Monday 08 December 2025  12:03:34 +0000 (0:00:00.558)       0:00:02.694 *******
2025-12-08 12:03:35.708603 | controller | ok: [instance]
2025-12-08 12:03:35.708676 | controller |
2025-12-08 12:03:35.708787 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-12-08 12:03:35.708896 | controller | Monday 08 December 2025  12:03:35 +0000 (0:00:00.735)       0:00:03.430 *******
2025-12-08 12:03:36.713018 | controller | changed: [instance]
2025-12-08 12:03:36.713242 | controller |
2025-12-08 12:03:36.713457 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-12-08 12:03:45.129261 | controller | Monday 08 December 2025  12:03:36 +0000 (0:00:01.004)       0:00:04.435 *******
2025-12-08 12:03:45.129296 | controller | changed: [instance]
2025-12-08 12:03:45.129322 | controller |
2025-12-08 12:03:45.129337 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-12-08 12:03:45.129432 | controller | Monday 08 December 2025  12:03:45 +0000 (0:00:08.416)       0:00:12.851 *******
2025-12-08 12:03:45.785199 | controller | changed: [instance]
2025-12-08 12:03:45.805408 | controller |
2025-12-08 12:03:45.805423 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-12-08 12:03:45.805431 | controller | Monday 08 December 2025  12:03:45 +0000 (0:00:00.655)       0:00:13.507 *******
2025-12-08 12:03:45.805439 | controller | skipping: [instance]
2025-12-08 12:03:46.578991 | controller |
2025-12-08 12:03:46.579021 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-12-08 12:03:46.579029 | controller | Monday 08 December 2025  12:03:45 +0000 (0:00:00.020)       0:00:13.527 *******
2025-12-08 12:03:46.579039 | controller | changed: [instance]
2025-12-08 12:03:46.613252 | controller |
2025-12-08 12:03:46.613287 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-12-08 12:03:46.613296 | controller | Monday 08 December 2025  12:03:46 +0000 (0:00:00.773)       0:00:14.300 *******
2025-12-08 12:03:46.613306 | controller | skipping: [instance]
2025-12-08 12:03:46.613333 | controller |
2025-12-08 12:03:46.613625 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-12-08 12:03:46.647251 | controller | Monday 08 December 2025  12:03:46 +0000 (0:00:00.034)       0:00:14.335 *******
2025-12-08 12:03:46.647291 | controller | skipping: [instance]
2025-12-08 12:03:46.647378 | controller |
2025-12-08 12:03:46.647396 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-12-08 12:03:46.647407 | controller | Monday 08 December 2025  12:03:46 +0000 (0:00:00.033)       0:00:14.369 *******
2025-12-08 12:03:46.682293 | controller | skipping: [instance]
2025-12-08 12:03:47.094304 | controller |
2025-12-08 12:03:47.094329 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-12-08 12:03:47.094337 | controller | Monday 08 December 2025  12:03:46 +0000 (0:00:00.035)       0:00:14.404 *******
2025-12-08 12:03:47.094347 | controller | changed: [instance]
2025-12-08 12:03:47.538231 | controller |
2025-12-08 12:03:47.538261 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-12-08 12:03:47.538269 | controller | Monday 08 December 2025  12:03:47 +0000 (0:00:00.412)       0:00:14.816 *******
2025-12-08 12:03:47.538280 | controller | changed: [instance]
2025-12-08 12:03:47.565453 | controller |
2025-12-08 12:03:47.565482 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-12-08 12:03:47.565490 | controller | Monday 08 December 2025  12:03:47 +0000 (0:00:00.443)       0:00:15.260 *******
2025-12-08 12:03:47.565500 | controller | skipping: [instance]
2025-12-08 12:03:47.592624 | controller |
2025-12-08 12:03:47.592640 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-12-08 12:03:47.592647 | controller | Monday 08 December 2025  12:03:47 +0000 (0:00:00.027)       0:00:15.287 *******
2025-12-08 12:03:47.592659 | controller | skipping: [instance]
2025-12-08 12:03:47.619227 | controller |
2025-12-08 12:03:47.619250 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-12-08 12:03:47.619260 | controller | Monday 08 December 2025  12:03:47 +0000 (0:00:00.027)       0:00:15.314 *******
2025-12-08 12:03:47.619272 | controller | skipping: [instance]
2025-12-08 12:03:47.619525 | controller |
2025-12-08 12:03:47.619538 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-12-08 12:03:47.655207 | controller | Monday 08 December 2025  12:03:47 +0000 (0:00:00.026)       0:00:15.341 *******
2025-12-08 12:03:47.655234 | controller | ok: [instance]
2025-12-08 12:03:47.680975 | controller |
2025-12-08 12:03:47.680997 | controller | TASK [repo_setup : Create download directory] **********************************
2025-12-08 12:03:47.681006 | controller | Monday 08 December 2025  12:03:47 +0000 (0:00:00.035)       0:00:15.377 *******
2025-12-08 12:03:47.681017 | controller | skipping: [instance]
2025-12-08 12:03:47.681255 | controller |
2025-12-08 12:03:47.681459 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-12-08 12:03:47.681621 | controller | Monday 08 December 2025  12:03:47 +0000 (0:00:00.026)       0:00:15.403 *******
2025-12-08 12:03:47.706461 | controller | skipping: [instance]
2025-12-08 12:03:47.706633 | controller |
2025-12-08 12:03:47.706774 | controller | TASK [Download the RPM] ********************************************************
2025-12-08 12:03:47.706915 | controller | Monday 08 December 2025  12:03:47 +0000 (0:00:00.025)       0:00:15.428 *******
2025-12-08 12:03:47.733189 | controller | skipping: [instance]
2025-12-08 12:03:47.733483 | controller |
2025-12-08 12:03:47.733721 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-12-08 12:03:47.733849 | controller | Monday 08 December 2025  12:03:47 +0000 (0:00:00.026)       0:00:15.455 *******
2025-12-08 12:03:47.759124 | controller | skipping: [instance]
2025-12-08 12:03:47.759445 | controller |
2025-12-08 12:03:47.759716 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-12-08 12:03:47.759860 | controller | Monday 08 December 2025  12:03:47 +0000 (0:00:00.025)       0:00:15.481 *******
2025-12-08 12:03:47.785226 | controller | skipping: [instance]
2025-12-08 12:03:47.785518 | controller |
2025-12-08 12:03:47.785783 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-12-08 12:03:47.785912 | controller | Monday 08 December 2025  12:03:47 +0000 (0:00:00.026)       0:00:15.507 *******
2025-12-08 12:03:47.813306 | controller | skipping: [instance]
2025-12-08 12:03:47.813588 | controller |
2025-12-08 12:03:47.813803 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-12-08 12:03:47.813943 | controller | Monday 08 December 2025  12:03:47 +0000 (0:00:00.028)       0:00:15.535 *******
2025-12-08 12:03:47.838434 | controller | skipping: [instance]
2025-12-08 12:03:47.838711 | controller |
2025-12-08 12:03:47.838924 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-12-08 12:03:47.839071 | controller | Monday 08 December 2025  12:03:47 +0000 (0:00:00.025)       0:00:15.560 *******
2025-12-08 12:03:48.031533 | controller | ok: [instance]
2025-12-08 12:03:48.222794 | controller |
2025-12-08 12:03:48.222822 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-12-08 12:03:48.222830 | controller | Monday 08 December 2025  12:03:48 +0000 (0:00:00.192)       0:00:15.753 *******
2025-12-08 12:03:48.222839 | controller | changed: [instance]
2025-12-08 12:03:48.431651 | controller |
2025-12-08 12:03:48.431673 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-12-08 12:03:48.431680 | controller | Monday 08 December 2025  12:03:48 +0000 (0:00:00.191)       0:00:15.944 *******
2025-12-08 12:03:48.431689 | controller | changed: [instance]
2025-12-08 12:03:48.451240 | controller |
2025-12-08 12:03:48.451270 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-12-08 12:03:48.451278 | controller | Monday 08 December 2025  12:03:48 +0000 (0:00:00.208)       0:00:16.153 *******
2025-12-08 12:03:48.451288 | controller | skipping: [instance]
2025-12-08 12:03:48.471818 | controller |
2025-12-08 12:03:48.471846 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-12-08 12:03:48.471857 | controller | Monday 08 December 2025  12:03:48 +0000 (0:00:00.019)       0:00:16.173 *******
2025-12-08 12:03:48.471869 | controller | skipping: [instance]
2025-12-08 12:03:48.492912 | controller |
2025-12-08 12:03:48.492937 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-12-08 12:03:48.492945 | controller | Monday 08 December 2025  12:03:48 +0000 (0:00:00.020)       0:00:16.193 *******
2025-12-08 12:03:48.492956 | controller | skipping: [instance]
2025-12-08 12:03:48.514256 | controller |
2025-12-08 12:03:48.514290 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-12-08 12:03:48.514298 | controller | Monday 08 December 2025  12:03:48 +0000 (0:00:00.021)       0:00:16.214 *******
2025-12-08 12:03:48.514310 | controller | skipping: [instance]
2025-12-08 12:03:48.534595 | controller |
2025-12-08 12:03:48.534628 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-12-08 12:03:48.534636 | controller | Monday 08 December 2025  12:03:48 +0000 (0:00:00.021)       0:00:16.236 *******
2025-12-08 12:03:48.534647 | controller | skipping: [instance]
2025-12-08 12:03:48.534756 | controller |
2025-12-08 12:03:48.534928 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-12-08 12:03:48.535107 | controller | Monday 08 December 2025  12:03:48 +0000 (0:00:00.020)       0:00:16.257 *******
2025-12-08 12:03:48.555220 | controller | skipping: [instance]
2025-12-08 12:03:48.555388 | controller |
2025-12-08 12:03:48.555555 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-12-08 12:03:48.555725 | controller | Monday 08 December 2025  12:03:48 +0000 (0:00:00.020)       0:00:16.277 *******
2025-12-08 12:03:48.571113 | controller | skipping: [instance]
2025-12-08 12:03:48.571302 | controller |
2025-12-08 12:03:48.571420 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-12-08 12:03:48.571532 | controller | Monday 08 December 2025  12:03:48 +0000 (0:00:00.015)       0:00:16.293 *******
2025-12-08 12:03:48.597671 | controller | skipping: [instance]
2025-12-08 12:03:48.597832 | controller |
2025-12-08 12:03:48.597993 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-12-08 12:03:48.598187 | controller | Monday 08 December 2025  12:03:48 +0000 (0:00:00.026)       0:00:16.320 *******
2025-12-08 12:03:48.612791 | controller | skipping: [instance]
2025-12-08 12:03:48.612952 | controller |
2025-12-08 12:03:48.613136 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-12-08 12:03:48.613338 | controller | Monday 08 December 2025  12:03:48 +0000 (0:00:00.015)       0:00:16.335 *******
2025-12-08 12:03:48.632690 | controller | skipping: [instance]
2025-12-08 12:03:48.632851 | controller |
2025-12-08 12:03:48.633014 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-12-08 12:03:48.633221 | controller | Monday 08 December 2025  12:03:48 +0000 (0:00:00.019)       0:00:16.355 *******
2025-12-08 12:03:48.659489 | controller | skipping: [instance]
2025-12-08 12:03:48.659624 | controller |
2025-12-08 12:03:48.659760 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-12-08 12:03:48.659903 | controller | Monday 08 December 2025  12:03:48 +0000 (0:00:00.026)       0:00:16.382 *******
2025-12-08 12:03:48.687234 | controller | skipping: [instance]
2025-12-08 12:03:48.687453 | controller |
2025-12-08 12:03:48.687600 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-12-08 12:03:48.687710 | controller | Monday 08 December 2025  12:03:48 +0000 (0:00:00.027)       0:00:16.409 *******
2025-12-08 12:03:48.714873 | controller | skipping: [instance]
2025-12-08 12:03:48.715125 | controller |
2025-12-08 12:03:48.715385 | controller | TASK [test_deps : Install additional packages] *********************************
2025-12-08 12:03:48.715568 | controller | Monday 08 December 2025  12:03:48 +0000 (0:00:00.027)       0:00:16.437 *******
2025-12-08 12:03:48.741783 | controller | skipping: [instance]
2025-12-08 12:03:48.742011 | controller |
2025-12-08 12:03:48.742283 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-12-08 12:03:48.742519 | controller | Monday 08 December 2025  12:03:48 +0000 (0:00:00.026)       0:00:16.464 *******
2025-12-08 12:04:34.734923 | controller | ok: [instance]
2025-12-08 12:04:35.834943 | controller |
2025-12-08 12:04:35.834986 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-12-08 12:04:35.834994 | controller | Monday 08 December 2025  12:04:34 +0000 (0:00:45.992)       0:01:02.456 *******
2025-12-08 12:04:35.835006 | controller | ok: [instance]
2025-12-08 12:04:36.904388 | controller |
2025-12-08 12:04:36.904421 | controller | TASK [test_deps : Install extra packages] **************************************
2025-12-08 12:04:36.904429 | controller | Monday 08 December 2025  12:04:35 +0000 (0:00:01.100)       0:01:03.557 *******
2025-12-08 12:04:36.904440 | controller | ok: [instance]
2025-12-08 12:04:37.213333 | controller |
2025-12-08 12:04:37.213364 | controller | TASK [Ensure ceph-admin group exists] ******************************************
2025-12-08 12:04:37.213372 | controller | Monday 08 December 2025  12:04:36 +0000 (0:00:01.069)       0:01:04.626 *******
2025-12-08 12:04:37.213382 | controller | changed: [instance]
2025-12-08 12:04:37.853286 | controller |
2025-12-08 12:04:37.853326 | controller | TASK [Ensure ceph-admin user exists] *******************************************
2025-12-08 12:04:37.853337 | controller | Monday 08 December 2025  12:04:37 +0000 (0:00:00.308)       0:01:04.935 *******
2025-12-08 12:04:37.853351 | controller | changed: [instance]
2025-12-08 12:04:37.853401 | controller |
2025-12-08 12:04:37.855637 | controller | PLAY RECAP *********************************************************************
2025-12-08 12:04:37.855682 | controller | instance                   : ok=22   changed=11   unreachable=0    failed=0    skipped=29   rescued=0    ignored=0
2025-12-08 12:04:37.907762 | controller |
2025-12-08 12:04:37.907795 | controller | Monday 08 December 2025  12:04:37 +0000 (0:00:00.640)       0:01:05.575 *******
2025-12-08 12:04:37.907807 | controller | ===============================================================================
2025-12-08 12:04:37.907813 | controller | test_deps : Install selinux python libs -------------------------------- 45.99s
2025-12-08 12:04:37.907818 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.42s
2025-12-08 12:04:37.907823 | controller | test_deps : Install python yaml libs ------------------------------------ 1.10s
2025-12-08 12:04:37.907828 | controller | test_deps : Install extra packages -------------------------------------- 1.07s
2025-12-08 12:04:37.907833 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.00s
2025-12-08 12:04:37.907838 | controller | Gathering Facts --------------------------------------------------------- 0.80s
2025-12-08 12:04:37.907843 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.77s
2025-12-08 12:04:37.907847 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.74s
2025-12-08 12:04:37.907852 | controller | repo_setup : Install repo-setup package --------------------------------- 0.66s
2025-12-08 12:04:37.907857 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.64s
2025-12-08 12:04:37.907862 | controller | Ensure ceph-admin user exists ------------------------------------------- 0.64s
2025-12-08 12:04:37.907867 | controller | repo_setup : Ensure directories are present ----------------------------- 0.56s
2025-12-08 12:04:37.907872 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.44s
2025-12-08 12:04:37.907877 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.41s
2025-12-08 12:04:37.907881 | controller | Ensure ceph-admin group exists ------------------------------------------ 0.31s
2025-12-08 12:04:37.907886 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.29s
2025-12-08 12:04:37.907891 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.28s
2025-12-08 12:04:37.907896 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.21s
2025-12-08 12:04:37.907901 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.19s
2025-12-08 12:04:37.907906 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.19s
2025-12-08 12:04:37.907916 | controller | INFO     Running default > converge
2025-12-08 12:04:38.317793 | controller |
2025-12-08 12:04:39.087045 | controller | PLAY [Converge] ****************************************************************
2025-12-08 12:04:39.087079 | controller |
2025-12-08 12:04:39.087087 | controller | TASK [Gathering Facts] *********************************************************
2025-12-08 12:04:39.087093 | controller | Monday 08 December 2025  12:04:38 +0000 (0:00:00.013)       0:00:00.013 *******
2025-12-08 12:04:39.087103 | controller | ok: [instance]
2025-12-08 12:04:39.108883 | controller |
2025-12-08 12:04:39.108911 | controller | TASK [cifmw_cephadm : Gather distribution facts if they do not exist] **********
2025-12-08 12:04:39.108919 | controller | Monday 08 December 2025  12:04:39 +0000 (0:00:00.769)       0:00:00.783 *******
2025-12-08 12:04:39.108929 | controller | skipping: [instance]
2025-12-08 12:04:39.130599 | controller |
2025-12-08 12:04:39.130617 | controller | TASK [cifmw_cephadm : Install cephadm package] *********************************
2025-12-08 12:04:39.130624 | controller | Monday 08 December 2025  12:04:39 +0000 (0:00:00.022)       0:00:00.805 *******
2025-12-08 12:04:39.130632 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_cephadm/tasks/install_cephadm.yml for instance
2025-12-08 12:04:39.155739 | controller |
2025-12-08 12:04:39.155768 | controller | TASK [cifmw_cephadm : Enabled ceph Tools Repository] ***************************
2025-12-08 12:04:39.155775 | controller | Monday 08 December 2025  12:04:39 +0000 (0:00:00.021)       0:00:00.826 *******
2025-12-08 12:04:39.155791 | controller | skipping: [instance]
2025-12-08 12:04:45.013847 | controller |
2025-12-08 12:04:45.013879 | controller | TASK [cifmw_cephadm : Install cephadm package] *********************************
2025-12-08 12:04:45.013888 | controller | Monday 08 December 2025  12:04:39 +0000 (0:00:00.025)       0:00:00.852 *******
2025-12-08 12:04:45.013905 | controller | changed: [instance]
2025-12-08 12:04:45.336099 | controller |
2025-12-08 12:04:45.336130 | controller | TASK [cifmw_cephadm : Stat cephadm file] ***************************************
2025-12-08 12:04:45.336138 | controller | Monday 08 December 2025  12:04:45 +0000 (0:00:05.857)       0:00:06.709 *******
2025-12-08 12:04:45.336147 | controller | ok: [instance]
2025-12-08 12:04:45.357684 | controller |
2025-12-08 12:04:45.357705 | controller | TASK [cifmw_cephadm : Fail if cephadm is not available] ************************
2025-12-08 12:04:45.357713 | controller | Monday 08 December 2025  12:04:45 +0000 (0:00:00.322)       0:00:07.031 *******
2025-12-08 12:04:45.357722 | controller | skipping: [instance]
2025-12-08 12:04:45.394756 | controller |
2025-12-08 12:04:45.394776 | controller | TASK [cifmw_cephadm : Set list of found FSIDs] *********************************
2025-12-08 12:04:45.394784 | controller | Monday 08 December 2025  12:04:45 +0000 (0:00:00.022)       0:00:07.053 *******
2025-12-08 12:04:45.394794 | controller | skipping: [instance]
2025-12-08 12:04:45.432276 | controller |
2025-12-08 12:04:45.432327 | controller | TASK [cifmw_cephadm : Fail if >1 FSID was discovered] **************************
2025-12-08 12:04:45.432339 | controller | Monday 08 December 2025  12:04:45 +0000 (0:00:00.037)       0:00:07.091 *******
2025-12-08 12:04:45.432353 | controller | skipping: [instance]
2025-12-08 12:04:45.432386 | controller |
2025-12-08 12:04:45.432602 | controller | TASK [cifmw_cephadm : Set FSID to the discovered value] ************************
2025-12-08 12:04:45.472754 | controller | Monday 08 December 2025  12:04:45 +0000 (0:00:00.037)       0:00:07.128 *******
2025-12-08 12:04:45.472775 | controller | ok: [instance]
2025-12-08 12:04:45.507509 | controller |
2025-12-08 12:04:45.507525 | controller | TASK [cifmw_cephadm : Set random cifmw_cephadm_fsid if no running ceph containers were found] ***
2025-12-08 12:04:45.507532 | controller | Monday 08 December 2025  12:04:45 +0000 (0:00:00.040)       0:00:07.169 *******
2025-12-08 12:04:45.507541 | controller | skipping: [instance]
2025-12-08 12:04:45.548607 | controller |
2025-12-08 12:04:45.548625 | controller | TASK [cifmw_cephadm : Set first monitor IP if it was not passed] ***************
2025-12-08 12:04:45.548632 | controller | Monday 08 December 2025  12:04:45 +0000 (0:00:00.034)       0:00:07.203 *******
2025-12-08 12:04:45.548641 | controller | ok: [instance]
2025-12-08 12:04:45.867582 | controller |
2025-12-08 12:04:45.867611 | controller | TASK [cifmw_cephadm : Ensure cifmw_cephadm_config_home (e.g. /etc/ceph) exists] ***
2025-12-08 12:04:45.867619 | controller | Monday 08 December 2025  12:04:45 +0000 (0:00:00.040)       0:00:07.244 *******
2025-12-08 12:04:45.867629 | controller | changed: [instance]
2025-12-08 12:04:46.096610 | controller |
2025-12-08 12:04:46.096630 | controller | TASK [cifmw_cephadm : Ensure specs directory exists] ***************************
2025-12-08 12:04:46.096638 | controller | Monday 08 December 2025  12:04:45 +0000 (0:00:00.318)       0:00:07.563 *******
2025-12-08 12:04:46.096647 | controller | changed: [instance]
2025-12-08 12:04:46.311646 | controller |
2025-12-08 12:04:46.311667 | controller | TASK [cifmw_cephadm : Stat spec file on ansible host] **************************
2025-12-08 12:04:46.311675 | controller | Monday 08 December 2025  12:04:46 +0000 (0:00:00.229)       0:00:07.792 *******
2025-12-08 12:04:46.311683 | controller | ok: [instance -> localhost]
2025-12-08 12:04:46.332677 | controller |
2025-12-08 12:04:46.332695 | controller | TASK [cifmw_cephadm : Push cifmw_cephadm_spec to bootstrap node if spec file exsits] ***
2025-12-08 12:04:46.332703 | controller | Monday 08 December 2025  12:04:46 +0000 (0:00:00.215)       0:00:08.007 *******
2025-12-08 12:04:46.332711 | controller | skipping: [instance]
2025-12-08 12:04:46.560731 | controller |
2025-12-08 12:04:46.560756 | controller | TASK [cifmw_cephadm : Stat cifmw_cephadm_bootstrap_conf file] ******************
2025-12-08 12:04:46.560764 | controller | Monday 08 December 2025  12:04:46 +0000 (0:00:00.021)       0:00:08.029 *******
2025-12-08 12:04:46.560774 | controller | ok: [instance -> localhost]
2025-12-08 12:04:47.064080 | controller |
2025-12-08 12:04:47.064105 | controller | TASK [cifmw_cephadm : Push it to cifmw_cephadm_assimilate_conf on bootstrap node] ***
2025-12-08 12:04:47.064121 | controller | Monday 08 December 2025  12:04:46 +0000 (0:00:00.227)       0:00:08.256 *******
2025-12-08 12:04:47.064131 | controller | changed: [instance]
2025-12-08 12:04:47.099362 | controller |
2025-12-08 12:04:47.099380 | controller | TASK [cifmw_cephadm : Add spec to necessary file list when using spec to bootstrap] ***
2025-12-08 12:04:47.099388 | controller | Monday 08 December 2025  12:04:47 +0000 (0:00:00.503)       0:00:08.760 *******
2025-12-08 12:04:47.099397 | controller | skipping: [instance]
2025-12-08 12:04:47.538926 | controller |
2025-12-08 12:04:47.538955 | controller | TASK [cifmw_cephadm : Stat necessary files to bootstrap with cephadm] **********
2025-12-08 12:04:47.538963 | controller | Monday 08 December 2025  12:04:47 +0000 (0:00:00.035)       0:00:08.795 *******
2025-12-08 12:04:47.538973 | controller | ok: [instance] => (item=/home/ceph-admin/.ssh/id_rsa)
2025-12-08 12:04:47.587379 | controller | ok: [instance] => (item=/home/ceph-admin/.ssh/id_rsa.pub)
2025-12-08 12:04:47.587445 | controller |
2025-12-08 12:04:47.587454 | controller | TASK [cifmw_cephadm : Fail if necessary files are missing] *********************
2025-12-08 12:04:47.587462 | controller | Monday 08 December 2025  12:04:47 +0000 (0:00:00.439)       0:00:09.234 *******
2025-12-08 12:04:47.587487 | 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': 163586346, 'dev': 64513, 'nlink': 1, 'atime': 1765195477.8062172, 'mtime': 1765195477.8002172, 'ctime': 1765195477.801217, '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': '00bd64a164dd66c3504fad82f506957dc2cc34d4', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '683119147', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/ceph-admin/.ssh/id_rsa', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': '/home/ceph-admin/.ssh/id_rsa', 'ansible_loop_var': 'item'})
2025-12-08 12:04:47.587529 | 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': 163586347, 'dev': 64513, 'nlink': 1, 'atime': 1765195477.8062172, 'mtime': 1765195477.8002172, 'ctime': 1765195477.801217, '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': 'ed4364cd8e932c7608eec03618eccecc8de5a353', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '2008344552', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/ceph-admin/.ssh/id_rsa.pub', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': '/home/ceph-admin/.ssh/id_rsa.pub', 'ansible_loop_var': 'item'})
2025-12-08 12:04:47.587931 | controller | skipping: [instance]
2025-12-08 12:04:47.587966 | controller |
2025-12-08 12:04:47.824609 | controller | TASK [cifmw_cephadm : Stat pre ceph conf file in case we should bootrap with it] ***
2025-12-08 12:04:47.824642 | controller | Monday 08 December 2025  12:04:47 +0000 (0:00:00.048)       0:00:09.283 *******
2025-12-08 12:04:47.824656 | controller | ok: [instance]
2025-12-08 12:04:47.857779 | controller |
2025-12-08 12:04:47.857798 | controller | TASK [cifmw_cephadm : If cephadm bootstrap was not run report the reason] ******
2025-12-08 12:04:47.857812 | controller | Monday 08 December 2025  12:04:47 +0000 (0:00:00.236)       0:00:09.520 *******
2025-12-08 12:04:47.857820 | controller | skipping: [instance]
2025-12-08 12:04:48.166974 | controller |
2025-12-08 12:04:48.167002 | controller | TASK [Mock ceph_mon_dump command] **********************************************
2025-12-08 12:04:48.167010 | controller | Monday 08 December 2025  12:04:47 +0000 (0:00:00.033)       0:00:09.554 *******
2025-12-08 12:04:48.167020 | controller | changed: [instance -> localhost]
2025-12-08 12:04:48.187373 | controller |
2025-12-08 12:04:48.187426 | controller | TASK [Mock ceph_keys_module_output] ********************************************
2025-12-08 12:04:48.187441 | controller | Monday 08 December 2025  12:04:48 +0000 (0:00:00.308)       0:00:09.863 *******
2025-12-08 12:04:48.187460 | controller | ok: [instance]
2025-12-08 12:04:48.212342 | controller |
2025-12-08 12:04:48.212380 | controller | TASK [cifmw_cephadm : Get ceph_cli] ********************************************
2025-12-08 12:04:48.212394 | controller | Monday 08 December 2025  12:04:48 +0000 (0:00:00.020)       0:00:09.883 *******
2025-12-08 12:04:48.212411 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_cephadm/tasks/ceph_cli.yml for instance
2025-12-08 12:04:48.212455 | controller |
2025-12-08 12:04:48.212470 | controller | TASK [cifmw_cephadm : Set ceph CLI] ********************************************
2025-12-08 12:04:48.212572 | controller | Monday 08 December 2025  12:04:48 +0000 (0:00:00.024)       0:00:09.908 *******
2025-12-08 12:04:48.258919 | controller | ok: [instance]
2025-12-08 12:04:48.292762 | controller |
2025-12-08 12:04:48.292779 | controller | TASK [cifmw_cephadm : Wait for the expected number of monitors to be running] ***
2025-12-08 12:04:48.292787 | controller | Monday 08 December 2025  12:04:48 +0000 (0:00:00.046)       0:00:09.955 *******
2025-12-08 12:04:48.292795 | controller | skipping: [instance]
2025-12-08 12:04:48.331762 | controller |
2025-12-08 12:04:48.331779 | controller | TASK [cifmw_cephadm : Extract mons_json] ***************************************
2025-12-08 12:04:48.331786 | controller | Monday 08 December 2025  12:04:48 +0000 (0:00:00.033)       0:00:09.989 *******
2025-12-08 12:04:48.331794 | controller | ok: [instance]
2025-12-08 12:04:48.384232 | controller |
2025-12-08 12:04:48.384252 | controller | TASK [cifmw_cephadm : Build mons_list] *****************************************
2025-12-08 12:04:48.384259 | controller | Monday 08 December 2025  12:04:48 +0000 (0:00:00.039)       0:00:10.028 *******
2025-12-08 12:04:48.384269 | controller | ok: [instance] => (item=[{'type': 'v2', 'addr': '172.16.11.241:3300', 'nonce': 0}, {'type': 'v1', 'addr': '172.16.11.241:6789', 'nonce': 0}])
2025-12-08 12:04:48.384780 | controller | ok: [instance] => (item=[{'type': 'v2', 'addr': '172.16.11.176:3300', 'nonce': 0}, {'type': 'v1', 'addr': '172.16.11.176:6789', 'nonce': 0}])
2025-12-08 12:04:48.384795 | controller | ok: [instance] => (item=[{'type': 'v2', 'addr': '172.16.11.82:3300', 'nonce': 0}, {'type': 'v1', 'addr': '172.16.11.82:6789', 'nonce': 0}])
2025-12-08 12:04:48.423878 | controller |
2025-12-08 12:04:48.423896 | controller | TASK [cifmw_cephadm : Set cifmw_ceph_client_external_cluster_mon_ips from mons_list] ***
2025-12-08 12:04:48.423903 | controller | Monday 08 December 2025  12:04:48 +0000 (0:00:00.052)       0:00:10.080 *******
2025-12-08 12:04:48.423912 | controller | ok: [instance]
2025-12-08 12:04:48.461282 | controller |
2025-12-08 12:04:48.461329 | controller | TASK [cifmw_cephadm : Ensure cifmw_ceph_client_vars path exists] ***************
2025-12-08 12:04:48.461338 | controller | Monday 08 December 2025  12:04:48 +0000 (0:00:00.039)       0:00:10.119 *******
2025-12-08 12:04:48.461349 | controller | skipping: [instance]
2025-12-08 12:04:48.461372 | controller |
2025-12-08 12:04:48.461379 | controller | TASK [cifmw_cephadm : Save cifmw_ceph_client_vars file] ************************
2025-12-08 12:04:48.461463 | controller | Monday 08 December 2025  12:04:48 +0000 (0:00:00.037)       0:00:10.157 *******
2025-12-08 12:04:48.508669 | controller | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: ansible.errors.AnsibleUndefinedVariable: 'cifmw_ceph_s3_glance_user' is undefined. 'cifmw_ceph_s3_glance_user' is undefined
2025-12-08 12:04:48.509413 | controller | fatal: [instance -> localhost]: FAILED! => changed=false
2025-12-08 12:04:48.511626 | controller |   msg: 'AnsibleUndefinedVariable: ''cifmw_ceph_s3_glance_user'' is undefined. ''cifmw_ceph_s3_glance_user'' is undefined'
2025-12-08 12:04:48.511653 | controller |
2025-12-08 12:04:48.511749 | controller | PLAY RECAP *********************************************************************
2025-12-08 12:04:48.514057 | controller | instance                   : ok=20   changed=5    unreachable=0    failed=1    skipped=12   rescued=0    ignored=0
2025-12-08 12:04:48.583250 | controller |
2025-12-08 12:04:48.583282 | controller | Monday 08 December 2025  12:04:48 +0000 (0:00:00.050)       0:00:10.208 *******
2025-12-08 12:04:48.583291 | controller | ===============================================================================
2025-12-08 12:04:48.583297 | controller | cifmw_cephadm : Install cephadm package --------------------------------- 5.86s
2025-12-08 12:04:48.583303 | controller | Gathering Facts --------------------------------------------------------- 0.77s
2025-12-08 12:04:48.583308 | controller | cifmw_cephadm : Push it to cifmw_cephadm_assimilate_conf on bootstrap node --- 0.50s
2025-12-08 12:04:48.583314 | controller | cifmw_cephadm : Stat necessary files to bootstrap with cephadm ---------- 0.44s
2025-12-08 12:04:48.583330 | controller | cifmw_cephadm : Stat cephadm file --------------------------------------- 0.32s
2025-12-08 12:04:48.583336 | controller | cifmw_cephadm : Ensure cifmw_cephadm_config_home (e.g. /etc/ceph) exists --- 0.32s
2025-12-08 12:04:48.583341 | controller | Mock ceph_mon_dump command ---------------------------------------------- 0.31s
2025-12-08 12:04:48.583347 | controller | cifmw_cephadm : Stat pre ceph conf file in case we should bootrap with it --- 0.24s
2025-12-08 12:04:48.583352 | controller | cifmw_cephadm : Ensure specs directory exists --------------------------- 0.23s
2025-12-08 12:04:48.583357 | controller | cifmw_cephadm : Stat cifmw_cephadm_bootstrap_conf file ------------------ 0.23s
2025-12-08 12:04:48.583363 | controller | cifmw_cephadm : Stat spec file on ansible host -------------------------- 0.22s
2025-12-08 12:04:48.583368 | controller | cifmw_cephadm : Build mons_list ----------------------------------------- 0.05s
2025-12-08 12:04:48.583374 | controller | cifmw_cephadm : Save cifmw_ceph_client_vars file ------------------------ 0.05s
2025-12-08 12:04:48.583379 | controller | cifmw_cephadm : Fail if necessary files are missing --------------------- 0.05s
2025-12-08 12:04:48.583384 | controller | cifmw_cephadm : Set ceph CLI -------------------------------------------- 0.05s
2025-12-08 12:04:48.583389 | controller | cifmw_cephadm : Set first monitor IP if it was not passed --------------- 0.04s
2025-12-08 12:04:48.583395 | controller | cifmw_cephadm : Set FSID to the discovered value ------------------------ 0.04s
2025-12-08 12:04:48.583400 | controller | cifmw_cephadm : Set cifmw_ceph_client_external_cluster_mon_ips from mons_list --- 0.04s
2025-12-08 12:04:48.583405 | controller | cifmw_cephadm : Extract mons_json --------------------------------------- 0.04s
2025-12-08 12:04:48.583411 | controller | cifmw_cephadm : Fail if >1 FSID was discovered -------------------------- 0.04s
2025-12-08 12:04:48.583425 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/cifmw_cephadm/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_cephadm/molecule/default/converge.yml']
2025-12-08 12:04:48.583868 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-12-08 12:04:48.592828 | controller | INFO     Running default > cleanup
2025-12-08 12:04:48.593301 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-12-08 12:04:48.601183 | controller | INFO     Running default > destroy
2025-12-08 12:04:48.601549 | controller | WARNING  Skipping, instances are delegated.
2025-12-08 12:04:48.601894 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-12-08 12:04:48.603458 | controller | INFO     Writing /tmp/report.html report.
2025-12-08 12:04:48.943975 | controller | ERROR
2025-12-08 12:04:48.944126 | controller | {
2025-12-08 12:04:48.944159 | controller |   "delta": "0:01:34.046174",
2025-12-08 12:04:48.944181 | controller |   "end": "2025-12-08 12:04:48.652156",
2025-12-08 12:04:48.944199 | controller |   "msg": "non-zero return code",
2025-12-08 12:04:48.944216 | controller |   "rc": 1,
2025-12-08 12:04:48.944232 | controller |   "start": "2025-12-08 12:03:14.605982"
2025-12-08 12:04:48.944247 | controller | }
failure
2025-12-08 12:04:48.947714 | 
2025-12-08 12:04:48.947761 | PLAY RECAP
2025-12-08 12:04:48.947799 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-12-08 12:04:48.947820 | 
2025-12-08 12:04:49.006102 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-08 12:04:49.006865 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-08 12:04:49.490163 | 
2025-12-08 12:04:49.490273 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-08 12:04:49.509559 | 
2025-12-08 12:04:49.509633 | TASK [Filter out host if needed]
2025-12-08 12:04:49.518212 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-08 12:04:49.522592 | 
2025-12-08 12:04:49.522658 | TASK [Ensure file is present]
2025-12-08 12:04:49.816284 | controller | ok
2025-12-08 12:04:49.823302 | 
2025-12-08 12:04:49.823371 | TASK [Manage molecule report file]
2025-12-08 12:04:50.348672 | controller | changed
2025-12-08 12:04:50.354003 | 
2025-12-08 12:04:50.354083 | TASK [Check if we get ci-framework-data basedir]
2025-12-08 12:04:50.534234 | controller | ok
2025-12-08 12:04:50.539198 | 
2025-12-08 12:04:50.539258 | TASK [Create ci-framework-data log directory for zuul]
2025-12-08 12:04:50.841086 | controller | changed
2025-12-08 12:04:50.846210 | 
2025-12-08 12:04:50.846275 | TASK [Copy ci-framework interesting files]
2025-12-08 12:04:51.033235 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-12-08 12:04:51.371702 | controller | changed
2025-12-08 12:04:51.377100 | 
2025-12-08 12:04:51.377167 | TASK [Get SELinux listing]
2025-12-08 12:04:51.904572 | controller | changed
2025-12-08 12:04:51.910334 | 
2025-12-08 12:04:51.910405 | TASK [Generate log index]
2025-12-08 12:04:52.506720 | controller | changed
2025-12-08 12:04:52.511504 | 
2025-12-08 12:04:52.511564 | TASK [Get some env related data]
2025-12-08 12:04:53.024720 | controller | /home/zuul/.local/bin/ansible
2025-12-08 12:04:54.038540 | controller | changed
2025-12-08 12:04:54.043663 | 
2025-12-08 12:04:54.043738 | TASK [Generate list of logs to collect in home directory]
2025-12-08 12:04:54.336178 | controller | ok: All paths examined
2025-12-08 12:04:54.341099 | 
2025-12-08 12:04:54.341158 | LOOP [Copy logs from home directory]
2025-12-08 12:04:54.646891 | controller | changed:
2025-12-08 12:04:54.647011 | controller | {
2025-12-08 12:04:54.647039 | controller |   "atime": 1765195376.7586956,
2025-12-08 12:04:54.647060 | controller |   "ctime": 1765195392.4309645,
2025-12-08 12:04:54.647077 | controller |   "dev": 64513,
2025-12-08 12:04:54.647092 | controller |   "gid": 1000,
2025-12-08 12:04:54.647107 | controller |   "gr_name": "zuul",
2025-12-08 12:04:54.647122 | controller |   "inode": 4329476,
2025-12-08 12:04:54.647136 | controller |   "isblk": false,
2025-12-08 12:04:54.647151 | controller |   "ischr": false,
2025-12-08 12:04:54.647165 | controller |   "isdir": false,
2025-12-08 12:04:54.647179 | controller |   "isfifo": false,
2025-12-08 12:04:54.647193 | controller |   "isgid": false,
2025-12-08 12:04:54.647207 | controller |   "islnk": false,
2025-12-08 12:04:54.647220 | controller |   "isreg": true,
2025-12-08 12:04:54.647233 | controller |   "issock": false,
2025-12-08 12:04:54.647246 | controller |   "isuid": false,
2025-12-08 12:04:54.647259 | controller |   "mode": "0644",
2025-12-08 12:04:54.647272 | controller |   "mtime": 1765195392.4309645,
2025-12-08 12:04:54.647285 | controller |   "nlink": 1,
2025-12-08 12:04:54.647299 | controller |   "path": "/home/zuul/ansible.log",
2025-12-08 12:04:54.647316 | controller |   "pw_name": "zuul",
2025-12-08 12:04:54.647332 | controller |   "rgrp": true,
2025-12-08 12:04:54.647348 | controller |   "roth": true,
2025-12-08 12:04:54.647363 | controller |   "rusr": true,
2025-12-08 12:04:54.647377 | controller |   "size": 6749,
2025-12-08 12:04:54.647391 | controller |   "uid": 1000,
2025-12-08 12:04:54.647405 | controller |   "wgrp": false,
2025-12-08 12:04:54.647419 | controller |   "woth": false,
2025-12-08 12:04:54.647436 | controller |   "wusr": true,
2025-12-08 12:04:54.647452 | controller |   "xgrp": false,
2025-12-08 12:04:54.647467 | controller |   "xoth": false,
2025-12-08 12:04:54.647482 | controller |   "xusr": false
2025-12-08 12:04:54.647496 | controller | }
2025-12-08 12:04:54.660774 | 
2025-12-08 12:04:54.660845 | TASK [Copy crio stats log file]
2025-12-08 12:04:54.674065 | controller | skipping: Conditional result was False
2025-12-08 12:04:54.679170 | 
2025-12-08 12:04:54.679237 | TASK [Get SELinux related data]
2025-12-08 12:04:54.878650 | controller | <no matches>
2025-12-08 12:04:55.205079 | controller | ERROR
2025-12-08 12:04:55.205268 | controller | {
2025-12-08 12:04:55.205307 | controller |   "delta": "0:00:00.008761",
2025-12-08 12:04:55.205334 | controller |   "end": "2025-12-08 12:04:54.879639",
2025-12-08 12:04:55.205357 | controller |   "msg": "non-zero return code",
2025-12-08 12:04:55.205376 | controller |   "rc": 1,
2025-12-08 12:04:55.205395 | controller |   "start": "2025-12-08 12:04:54.870878"
2025-12-08 12:04:55.205413 | controller | }
2025-12-08 12:04:55.205438 | controller | ERROR: Ignoring Errors
2025-12-08 12:04:55.210557 | 
2025-12-08 12:04:55.210618 | TASK [Create system configuration directory]
2025-12-08 12:04:55.390591 | controller | changed
2025-12-08 12:04:55.395368 | 
2025-12-08 12:04:55.395430 | TASK [Get some of the system configurations]
2025-12-08 12:04:55.931157 | controller | changed
2025-12-08 12:04:55.936457 | 
2025-12-08 12:04:55.936520 | TASK [Copy generated documentation if available]
2025-12-08 12:04:55.950312 | controller | skipping: Conditional result was False
2025-12-08 12:04:55.955632 | 
2025-12-08 12:04:55.955699 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-08 12:04:55.969122 | controller | skipping: Conditional result was False
2025-12-08 12:04:55.974974 | 
2025-12-08 12:04:55.975039 | TASK [Compress logs bigger than 2MB]
2025-12-08 12:04:56.501088 | controller | changed
2025-12-08 12:04:56.506166 | 
2025-12-08 12:04:56.506228 | TASK [Copy files from workspace on node]
2025-12-08 12:04:56.524502 | controller | ok
2025-12-08 12:04:56.546046 | 
2025-12-08 12:04:56.546119 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-08 12:04:56.559680 | controller | skipping: Conditional result was False
2025-12-08 12:04:56.565665 | 
2025-12-08 12:04:56.565737 | TASK [fetch-output : Set log path for single node]
2025-12-08 12:04:56.594032 | controller | ok
2025-12-08 12:04:56.600105 | 
2025-12-08 12:04:56.600170 | LOOP [fetch-output : Ensure local output dirs]
2025-12-08 12:04:56.784854 | controller -> localhost | ok: "/var/lib/zuul/builds/cea78b4f84fb4537b7c73f0a1e00e586/work/logs"
2025-12-08 12:04:56.785107 | controller -> localhost | changed: All items complete
2025-12-08 12:04:56.785141 | 
2025-12-08 12:04:56.960637 | controller -> localhost | changed: "/var/lib/zuul/builds/cea78b4f84fb4537b7c73f0a1e00e586/work/artifacts"
2025-12-08 12:04:57.138128 | controller -> localhost | changed: "/var/lib/zuul/builds/cea78b4f84fb4537b7c73f0a1e00e586/work/docs"
2025-12-08 12:04:57.146592 | 
2025-12-08 12:04:57.146676 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-08 12:04:57.670627 | controller | changed:
2025-12-08 12:04:57.670815 | controller | .d..t...... ./
2025-12-08 12:04:57.670859 | controller | >f+++++++++ README.html
2025-12-08 12:04:57.670884 | controller | >f+++++++++ ansible-execution.log
2025-12-08 12:04:57.670926 | controller | >f+++++++++ ansible.log
2025-12-08 12:04:57.670955 | controller | >f+++++++++ dmesg.log
2025-12-08 12:04:57.670977 | controller | >f+++++++++ installed-pkgs.log
2025-12-08 12:04:57.671000 | controller | >f+++++++++ python.log
2025-12-08 12:04:57.671021 | controller | >f+++++++++ registries.conf
2025-12-08 12:04:57.671039 | controller | >f+++++++++ report.html
2025-12-08 12:04:57.671060 | controller | >f+++++++++ selinux-denials.log
2025-12-08 12:04:57.671078 | controller | >f+++++++++ selinux-listing.log
2025-12-08 12:04:57.671096 | controller | cd+++++++++ ci-framework-data/
2025-12-08 12:04:57.671113 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-08 12:04:57.671131 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-08 12:04:57.671149 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-08 12:04:57.671166 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-08 12:04:57.671183 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-08 12:04:57.671200 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-12-08 12:04:57.671221 | controller | cd+++++++++ registries.conf.d/
2025-12-08 12:04:57.671240 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-08 12:04:57.671257 | controller | cd+++++++++ system-config/
2025-12-08 12:04:57.671275 | controller | cd+++++++++ system-config/libvirt/
2025-12-08 12:04:57.671292 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-08 12:04:57.671309 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-08 12:04:58.036424 | controller | changed: .d..t...... ./
2025-12-08 12:04:58.395762 | controller | changed: .d..t...... ./
2025-12-08 12:04:58.416304 | 
2025-12-08 12:04:58.416371 | TASK [Return artifact to Zuul]
2025-12-08 12:04:58.444516 | controller | ok
2025-12-08 12:04:58.463452 | 
2025-12-08 12:04:58.463506 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-08 12:04:58.463596 | 
2025-12-08 12:04:58.463624 | PLAY RECAP
2025-12-08 12:04:58.463661 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-12-08 12:04:58.463683 | 
2025-12-08 12:04:58.532532 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-08 12:04:58.533273 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-08 12:04:59.046637 | 
2025-12-08 12:04:59.046742 | PLAY [all]
2025-12-08 12:04:59.065020 | 
2025-12-08 12:04:59.065097 | TASK [include_role : fetch-output]
2025-12-08 12:04:59.093774 | controller | ok
2025-12-08 12:04:59.110133 | 
2025-12-08 12:04:59.110214 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-08 12:04:59.154179 | controller | skipping: Conditional result was False
2025-12-08 12:04:59.159480 | 
2025-12-08 12:04:59.159550 | TASK [fetch-output : Set log path for single node]
2025-12-08 12:04:59.187622 | controller | ok
2025-12-08 12:04:59.193037 | 
2025-12-08 12:04:59.193111 | LOOP [fetch-output : Ensure local output dirs]
2025-12-08 12:04:59.513954 | controller -> localhost | ok: "/var/lib/zuul/builds/cea78b4f84fb4537b7c73f0a1e00e586/work/logs"
2025-12-08 12:04:59.701044 | controller -> localhost | ok: "/var/lib/zuul/builds/cea78b4f84fb4537b7c73f0a1e00e586/work/artifacts"
2025-12-08 12:04:59.881029 | controller -> localhost | ok: "/var/lib/zuul/builds/cea78b4f84fb4537b7c73f0a1e00e586/work/docs"
2025-12-08 12:04:59.893530 | 
2025-12-08 12:04:59.893673 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-08 12:05:00.423598 | controller | ok
2025-12-08 12:05:00.423840 | controller | ok: All items complete
2025-12-08 12:05:00.423877 | 
2025-12-08 12:05:00.785127 | controller | ok
2025-12-08 12:05:01.154944 | controller | ok
2025-12-08 12:05:01.168321 | 
2025-12-08 12:05:01.168434 | TASK [include_role : fetch-output-openshift]
2025-12-08 12:05:01.182690 | controller | skipping: Conditional result was False
2025-12-08 12:05:01.188500 | 
2025-12-08 12:05:01.188570 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-08 12:05:01.553745 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.009212
2025-12-08 12:05:01.752598 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007605
2025-12-08 12:05:01.785564 | 
2025-12-08 12:05:01.785666 | PLAY [all]
2025-12-08 12:05:01.799296 | 
2025-12-08 12:05:01.799370 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-08 12:05:02.217753 | controller | changed
2025-12-08 12:05:02.248822 | 
2025-12-08 12:05:02.248932 | PLAY RECAP
2025-12-08 12:05:02.248989 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-08 12:05:02.249013 | 
2025-12-08 12:05:02.328401 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-08 12:05:02.329213 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-08 12:05:02.898845 | 
2025-12-08 12:05:02.899102 | PLAY [localhost]
2025-12-08 12:05:02.916246 | 
2025-12-08 12:05:02.916330 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-08 12:05:03.232004 | localhost | changed
2025-12-08 12:05:03.236608 | 
2025-12-08 12:05:03.236691 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-08 12:05:03.267474 | localhost | ok
2025-12-08 12:05:03.275776 | 
2025-12-08 12:05:03.276082 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-08 12:05:03.578718 | localhost | changed
2025-12-08 12:05:03.585025 | 
2025-12-08 12:05:03.585141 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-08 12:05:04.153116 | localhost | changed
2025-12-08 12:05:04.159121 | 
2025-12-08 12:05:04.159255 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-08 12:05:04.527514 | localhost | Identity added: /var/lib/zuul/builds/cea78b4f84fb4537b7c73f0a1e00e586/work/tmp/ansible.nld2_zyt (/var/lib/zuul/builds/cea78b4f84fb4537b7c73f0a1e00e586/work/tmp/ansible.nld2_zyt)
2025-12-08 12:05:04.527712 | localhost | ok: Runtime: 0:00:00.008082
2025-12-08 12:05:04.532033 | 
2025-12-08 12:05:04.532107 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-08 12:05:04.763285 | localhost | ok: Runtime: 0:00:00.005934
2025-12-08 12:05:04.767803 | 
2025-12-08 12:05:04.767881 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-08 12:05:04.818764 | localhost | changed
2025-12-08 12:05:04.822593 | 
2025-12-08 12:05:04.822657 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-08 12:05:05.169840 | localhost | changed
2025-12-08 12:05:05.191455 | 
2025-12-08 12:05:05.191554 | PLAY [localhost]
2025-12-08 12:05:05.203041 | 
2025-12-08 12:05:05.203108 | TASK [Generate bulk log download script]
2025-12-08 12:05:05.234837 | localhost | ok
2025-12-08 12:05:05.246596 | 
2025-12-08 12:05:05.246668 | TASK [local-log-download : Check API endpoint is defined]
2025-12-08 12:05:05.286517 | localhost | ok: All assertions passed
2025-12-08 12:05:05.291523 | 
2025-12-08 12:05:05.291593 | TASK [local-log-download : Create download script]
2025-12-08 12:05:05.677298 | localhost -> localhost | changed
2025-12-08 12:05:05.686695 | 
2025-12-08 12:05:05.686820 | TASK [Register quick-download link]
2025-12-08 12:05:05.717084 | localhost | ok
2025-12-08 12:05:05.768592 | 
2025-12-08 12:05:05.768683 | PLAY [logserver.rdoproject.org]
2025-12-08 12:05:05.777551 | 
2025-12-08 12:05:05.777613 | TASK [Set zuul-log-path fact]
2025-12-08 12:05:05.796230 | logserver.rdoproject.org | ok
2025-12-08 12:05:05.805320 | 
2025-12-08 12:05:05.805392 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-08 12:05:05.833455 | logserver.rdoproject.org | ok
2025-12-08 12:05:05.838503 | 
2025-12-08 12:05:05.838572 | TASK [upload-logs : Create log directories]
2025-12-08 12:05:07.843076 | logserver.rdoproject.org | changed
2025-12-08 12:05:07.846550 | 
2025-12-08 12:05:07.846647 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-08 12:05:08.104821 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.007095
2025-12-08 12:05:08.111427 | 
2025-12-08 12:05:08.111519 | TASK [upload-logs : Upload logs to log server]
2025-12-08 12:05:09.853179 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-08 12:05:09.856518 | 
2025-12-08 12:05:09.856614 | LOOP [upload-logs : Compress console log and json output]
2025-12-08 12:05:09.924050 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-08 12:05:09.932676 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-08 12:05:09.937565 | 
2025-12-08 12:05:09.937682 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-08 12:05:09.978625 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-08 12:05:09.978935 | 
2025-12-08 12:05:09.981351 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-08 12:05:09.987468 | 
2025-12-08 12:05:09.987592 | LOOP [upload-logs : Upload console log and json output]