2026-06-11 16:05:40.472412 | Job console starting
2026-06-11 16:05:40.596747 | Updating git repos
2026-06-11 16:05:40.648661 | Cloning repos into workspace
2026-06-11 16:05:42.926268 | Restoring repo states
2026-06-11 16:05:42.947656 | Merging changes
2026-06-11 16:05:44.212884 | Checking out repos
2026-06-11 16:05:44.913818 | Preparing playbooks
2026-06-11 16:05:49.813645 | Running Ansible setup
2026-06-11 16:05:53.709294 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-06-11 16:05:54.348344 | 
2026-06-11 16:05:54.348489 | PLAY [localhost]
2026-06-11 16:05:54.356196 | 
2026-06-11 16:05:54.356268 | TASK [Gathering Facts]
2026-06-11 16:05:55.367938 | localhost | ok
2026-06-11 16:05:55.386941 | 
2026-06-11 16:05:55.387187 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-11 16:05:55.877733 | localhost -> localhost | changed
2026-06-11 16:05:55.888174 | 
2026-06-11 16:05:55.888321 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-11 16:05:57.043213 | localhost -> localhost | changed
2026-06-11 16:05:57.056174 | 
2026-06-11 16:05:57.056314 | TASK [Setup log path fact]
2026-06-11 16:05:57.082068 | localhost | ok
2026-06-11 16:05:57.101485 | 
2026-06-11 16:05:57.101627 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-11 16:05:57.158836 | localhost | ok
2026-06-11 16:05:57.165947 | 
2026-06-11 16:05:57.166035 | TASK [emit-job-header : Print job information]
2026-06-11 16:05:57.215442 | # Job Information
2026-06-11 16:05:57.215671 | Ansible Version: 2.16.18
2026-06-11 16:05:57.215705 | Job: cifmw-molecule-cifmw_cephadm
2026-06-11 16:05:57.215731 | Pipeline: github-check
2026-06-11 16:05:57.215755 | Executor: 38.102.83.51
2026-06-11 16:05:57.215777 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3773
2026-06-11 16:05:57.215803 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/46b/rdoproject.org/46b67896172642c9a635b926e305e70d/
2026-06-11 16:05:57.215829 | Event ID: 195418e0-65af-11f1-94f4-855b379b46d5
2026-06-11 16:05:57.219997 | 
2026-06-11 16:05:57.220084 | LOOP [emit-job-header : Print node information]
2026-06-11 16:05:57.332613 | localhost | ok:
2026-06-11 16:05:57.333020 | localhost | # Node Information
2026-06-11 16:05:57.333086 | localhost | Inventory Hostname: controller
2026-06-11 16:05:57.333137 | localhost | Hostname: np0000070932
2026-06-11 16:05:57.333178 | localhost | Username: zuul
2026-06-11 16:05:57.333221 | localhost | Distro: CentOS 9
2026-06-11 16:05:57.333258 | localhost | Provider: vexxhost-nodepool-tripleo
2026-06-11 16:05:57.333294 | localhost | Region: RegionOne
2026-06-11 16:05:57.333330 | localhost | Label: cloud-centos-9-stream-tripleo
2026-06-11 16:05:57.333405 | localhost | Product Name: OpenStack Nova
2026-06-11 16:05:57.333444 | localhost | Interface IP: 38.102.83.146
2026-06-11 16:05:57.350604 | 
2026-06-11 16:05:57.350808 | PLAY [all]
2026-06-11 16:05:57.362144 | 
2026-06-11 16:05:57.362279 | TASK [Gather network facts]
2026-06-11 16:05:57.824204 | controller | ok
2026-06-11 16:05:57.836846 | 
2026-06-11 16:05:57.836978 | TASK [include_role : start-zuul-console]
2026-06-11 16:05:57.872489 | controller | ok
2026-06-11 16:05:57.882907 | 
2026-06-11 16:05:57.882975 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-06-11 16:05:58.323017 | controller | ok
2026-06-11 16:05:58.332546 | 
2026-06-11 16:05:58.332632 | TASK [include_role : add-build-sshkey]
2026-06-11 16:05:58.370101 | controller | ok
2026-06-11 16:05:58.393153 | 
2026-06-11 16:05:58.393292 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-11 16:05:58.714329 | controller -> localhost | ok
2026-06-11 16:05:58.723213 | 
2026-06-11 16:05:58.723342 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-11 16:05:58.758653 | controller | ok
2026-06-11 16:05:58.784602 | controller | included: /var/lib/zuul/builds/46b67896172642c9a635b926e305e70d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-06-11 16:05:58.792542 | 
2026-06-11 16:05:58.792621 | TASK [add-build-sshkey : Create Temp SSH key]
2026-06-11 16:06:00.386483 | controller -> localhost | Generating public/private rsa key pair.
2026-06-11 16:06:00.386785 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/46b67896172642c9a635b926e305e70d/work/46b67896172642c9a635b926e305e70d_id_rsa
2026-06-11 16:06:00.386848 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/46b67896172642c9a635b926e305e70d/work/46b67896172642c9a635b926e305e70d_id_rsa.pub
2026-06-11 16:06:00.386894 | controller -> localhost | The key fingerprint is:
2026-06-11 16:06:00.386937 | controller -> localhost | SHA256:ZxdRqFyTWwKuKhmo2HfeNyW/Uw620KCLm/ZFNWOlVzI zuul-build-sshkey
2026-06-11 16:06:00.386979 | controller -> localhost | The key's randomart image is:
2026-06-11 16:06:00.387020 | controller -> localhost | +---[RSA 3072]----+
2026-06-11 16:06:00.387060 | controller -> localhost | |          ...+E .|
2026-06-11 16:06:00.387101 | controller -> localhost | |         .  *+.+ |
2026-06-11 16:06:00.387141 | controller -> localhost | |         ..oB=.  |
2026-06-11 16:06:00.387180 | controller -> localhost | |   .     .+o.=   |
2026-06-11 16:06:00.387219 | controller -> localhost | |  . .   S.+o.    |
2026-06-11 16:06:00.387257 | controller -> localhost | |.o   o ..+o.= .  |
2026-06-11 16:06:00.387296 | controller -> localhost | |o . + o. ..* =   |
2026-06-11 16:06:00.387334 | controller -> localhost | |   . +oo..o + .  |
2026-06-11 16:06:00.387431 | controller -> localhost | |     .+oo. ..o   |
2026-06-11 16:06:00.387473 | controller -> localhost | +----[SHA256]-----+
2026-06-11 16:06:00.387571 | controller -> localhost | ok: Runtime: 0:00:00.170563
2026-06-11 16:06:00.395599 | 
2026-06-11 16:06:00.395734 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-06-11 16:06:00.419292 | controller | ok
2026-06-11 16:06:00.436324 | controller | included: /var/lib/zuul/builds/46b67896172642c9a635b926e305e70d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-06-11 16:06:00.449824 | 
2026-06-11 16:06:00.449995 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-06-11 16:06:00.466422 | controller | skipping: Conditional result was False
2026-06-11 16:06:00.472696 | 
2026-06-11 16:06:00.472807 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-06-11 16:06:01.315767 | controller | changed
2026-06-11 16:06:01.324005 | 
2026-06-11 16:06:01.324144 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-06-11 16:06:01.594969 | controller | ok
2026-06-11 16:06:01.602899 | 
2026-06-11 16:06:01.603036 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-06-11 16:06:02.431261 | controller | changed
2026-06-11 16:06:02.436103 | 
2026-06-11 16:06:02.436190 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-06-11 16:06:03.212917 | controller | changed
2026-06-11 16:06:03.216695 | 
2026-06-11 16:06:03.216754 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-06-11 16:06:03.251285 | controller | skipping: Conditional result was False
2026-06-11 16:06:03.256082 | 
2026-06-11 16:06:03.256152 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-11 16:06:03.727234 | controller -> localhost | changed
2026-06-11 16:06:03.742375 | 
2026-06-11 16:06:03.742508 | TASK [add-build-sshkey : Add back temp key]
2026-06-11 16:06:04.098457 | controller -> localhost | Identity added: /var/lib/zuul/builds/46b67896172642c9a635b926e305e70d/work/46b67896172642c9a635b926e305e70d_id_rsa (zuul-build-sshkey)
2026-06-11 16:06:04.098799 | controller -> localhost | ok: Runtime: 0:00:00.020845
2026-06-11 16:06:04.106260 | 
2026-06-11 16:06:04.106400 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-06-11 16:06:04.494095 | controller | ok
2026-06-11 16:06:04.498697 | 
2026-06-11 16:06:04.498780 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-06-11 16:06:04.534635 | controller | skipping: Conditional result was False
2026-06-11 16:06:04.544169 | 
2026-06-11 16:06:04.544290 | TASK [include_role : validate-host]
2026-06-11 16:06:04.581886 | controller | ok
2026-06-11 16:06:04.605384 | 
2026-06-11 16:06:04.605476 | TASK [validate-host : Define zuul_info_dir fact]
2026-06-11 16:06:04.644862 | controller | ok
2026-06-11 16:06:04.649094 | 
2026-06-11 16:06:04.649171 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-06-11 16:06:04.947633 | controller -> localhost | ok
2026-06-11 16:06:04.957454 | 
2026-06-11 16:06:04.957593 | TASK [validate-host : Collect information about the host]
2026-06-11 16:06:05.672602 | controller | ok
2026-06-11 16:06:05.689186 | 
2026-06-11 16:06:05.689331 | TASK [validate-host : Sanitize hostname]
2026-06-11 16:06:05.743254 | controller | ok
2026-06-11 16:06:05.751333 | 
2026-06-11 16:06:05.751491 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-06-11 16:06:06.317343 | controller -> localhost | changed
2026-06-11 16:06:06.324394 | 
2026-06-11 16:06:06.324462 | TASK [validate-host : Collect information about zuul worker]
2026-06-11 16:06:06.781855 | controller | ok
2026-06-11 16:06:06.791241 | 
2026-06-11 16:06:06.791558 | TASK [validate-host : Write out all zuul information for each host]
2026-06-11 16:06:07.356856 | controller -> localhost | changed
2026-06-11 16:06:07.367139 | 
2026-06-11 16:06:07.367238 | TASK [include_role : prepare-workspace-openshift]
2026-06-11 16:06:07.393587 | controller | skipping: Conditional result was False
2026-06-11 16:06:07.404017 | 
2026-06-11 16:06:07.404161 | TASK [include_role : remove-zuul-sshkey]
2026-06-11 16:06:07.455255 | controller | skipping: Conditional result was False
2026-06-11 16:06:07.462042 | 
2026-06-11 16:06:07.462141 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-11 16:06:07.736055 | controller | ok: "logs"
2026-06-11 16:06:07.736555 | controller | ok: All items complete
2026-06-11 16:06:07.736619 | 
2026-06-11 16:06:07.962820 | controller | ok: "artifacts"
2026-06-11 16:06:08.192215 | controller | ok: "docs"
2026-06-11 16:06:08.209032 | 
2026-06-11 16:06:08.209262 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-11 16:06:08.482994 | controller | changed: "logs"
2026-06-11 16:06:08.699669 | controller | changed: "artifacts"
2026-06-11 16:06:08.939953 | controller | changed: "docs"
2026-06-11 16:06:08.955120 | 
2026-06-11 16:06:08.955317 | PLAY RECAP
2026-06-11 16:06:08.955442 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-06-11 16:06:08.955502 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-11 16:06:08.955541 | 
2026-06-11 16:06:09.118700 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-06-11 16:06:09.121195 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-06-11 16:06:09.790602 | 
2026-06-11 16:06:09.790729 | PLAY [all]
2026-06-11 16:06:09.801001 | 
2026-06-11 16:06:09.801077 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-06-11 16:06:09.883222 | controller | ok
2026-06-11 16:06:09.890713 | 
2026-06-11 16:06:09.890870 | TASK [mirror-info-fork : Create /etc/ci]
2026-06-11 16:06:10.341269 | controller | changed
2026-06-11 16:06:10.350538 | 
2026-06-11 16:06:10.350725 | TASK [mirror-info-fork : Install ci_mirror script]
2026-06-11 16:06:11.605203 | controller | changed
2026-06-11 16:06:11.621835 | 
2026-06-11 16:06:11.621969 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-06-11 16:06:12.091999 | controller | changed:
2026-06-11 16:06:12.092402 | controller | {
2026-06-11 16:06:12.092470 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-06-11 16:06:12.092611 | controller | }
2026-06-11 16:06:12.348756 | controller | changed:
2026-06-11 16:06:12.348936 | controller | {
2026-06-11 16:06:12.348993 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-06-11 16:06:12.349037 | controller | }
2026-06-11 16:06:12.608808 | controller | changed:
2026-06-11 16:06:12.609118 | controller | {
2026-06-11 16:06:12.609181 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-06-11 16:06:12.609223 | controller | }
2026-06-11 16:06:12.877903 | controller | changed:
2026-06-11 16:06:12.878111 | controller | {
2026-06-11 16:06:12.878166 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-06-11 16:06:12.878205 | controller | }
2026-06-11 16:06:13.168181 | controller | changed:
2026-06-11 16:06:13.168407 | controller | {
2026-06-11 16:06:13.168483 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-06-11 16:06:13.168541 | controller | }
2026-06-11 16:06:13.397099 | controller | changed:
2026-06-11 16:06:13.397266 | controller | {
2026-06-11 16:06:13.397317 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-06-11 16:06:13.397391 | controller | }
2026-06-11 16:06:13.687173 | controller | changed:
2026-06-11 16:06:13.687378 | controller | {
2026-06-11 16:06:13.687438 | 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"
2026-06-11 16:06:13.687632 | controller | }
2026-06-11 16:06:13.977345 | controller | changed:
2026-06-11 16:06:13.977560 | controller | {
2026-06-11 16:06:13.977611 | 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"
2026-06-11 16:06:13.977793 | controller | }
2026-06-11 16:06:14.255621 | controller | changed:
2026-06-11 16:06:14.255795 | controller | {
2026-06-11 16:06:14.255845 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-06-11 16:06:14.255885 | controller | }
2026-06-11 16:06:14.524085 | controller | changed:
2026-06-11 16:06:14.524282 | controller | {
2026-06-11 16:06:14.524334 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-06-11 16:06:14.524416 | controller | }
2026-06-11 16:06:14.793638 | controller | changed:
2026-06-11 16:06:14.793834 | controller | {
2026-06-11 16:06:14.793885 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-06-11 16:06:14.793926 | controller | }
2026-06-11 16:06:15.069790 | controller | changed:
2026-06-11 16:06:15.069981 | controller | {
2026-06-11 16:06:15.070032 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-06-11 16:06:15.070074 | controller | }
2026-06-11 16:06:15.320581 | controller | changed:
2026-06-11 16:06:15.320752 | controller | {
2026-06-11 16:06:15.320813 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-06-11 16:06:15.320863 | controller | }
2026-06-11 16:06:15.601419 | controller | changed:
2026-06-11 16:06:15.601568 | controller | {
2026-06-11 16:06:15.601599 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-06-11 16:06:15.601624 | controller | }
2026-06-11 16:06:15.860670 | controller | changed:
2026-06-11 16:06:15.860868 | controller | {
2026-06-11 16:06:15.860918 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-06-11 16:06:15.860960 | controller | }
2026-06-11 16:06:16.126453 | controller | changed:
2026-06-11 16:06:16.126618 | controller | {
2026-06-11 16:06:16.126649 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-06-11 16:06:16.126673 | controller | }
2026-06-11 16:06:16.424230 | controller | changed:
2026-06-11 16:06:16.424455 | controller | {
2026-06-11 16:06:16.424509 | 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"
2026-06-11 16:06:16.424550 | controller | }
2026-06-11 16:06:16.690696 | controller | changed:
2026-06-11 16:06:16.690884 | controller | {
2026-06-11 16:06:16.690934 | 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"
2026-06-11 16:06:16.690995 | controller | }
2026-06-11 16:06:16.997709 | controller | changed:
2026-06-11 16:06:16.997894 | controller | {
2026-06-11 16:06:16.997943 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-06-11 16:06:16.997982 | controller | }
2026-06-11 16:06:17.264312 | controller | changed:
2026-06-11 16:06:17.264462 | controller | {
2026-06-11 16:06:17.264485 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-06-11 16:06:17.264503 | controller | }
2026-06-11 16:06:17.530795 | controller | changed:
2026-06-11 16:06:17.530982 | controller | {
2026-06-11 16:06:17.531031 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-06-11 16:06:17.531069 | controller | }
2026-06-11 16:06:17.789423 | controller | changed:
2026-06-11 16:06:17.789557 | controller | {
2026-06-11 16:06:17.789606 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-06-11 16:06:17.789646 | controller | }
2026-06-11 16:06:18.084212 | controller | changed:
2026-06-11 16:06:18.084389 | controller | {
2026-06-11 16:06:18.084425 | 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"
2026-06-11 16:06:18.084451 | controller | }
2026-06-11 16:06:18.371340 | controller | changed:
2026-06-11 16:06:18.371506 | controller | {
2026-06-11 16:06:18.371537 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-06-11 16:06:18.371562 | controller | }
2026-06-11 16:06:18.648394 | controller | changed:
2026-06-11 16:06:18.648576 | controller | {
2026-06-11 16:06:18.648626 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-06-11 16:06:18.648666 | controller | }
2026-06-11 16:06:18.957122 | controller | changed:
2026-06-11 16:06:18.957373 | controller | {
2026-06-11 16:06:18.957435 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-06-11 16:06:18.957477 | controller | }
2026-06-11 16:06:18.994859 | 
2026-06-11 16:06:18.995002 | TASK [Set timezone to UTC]
2026-06-11 16:06:19.620597 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-06-11 16:06:19.628113 | 
2026-06-11 16:06:19.628242 | TASK [Create nodepool directory]
2026-06-11 16:06:19.917135 | controller | changed
2026-06-11 16:06:19.928030 | 
2026-06-11 16:06:19.928164 | TASK [Create nodepool sub_nodes file]
2026-06-11 16:06:20.627540 | controller | changed
2026-06-11 16:06:20.636049 | 
2026-06-11 16:06:20.636187 | TASK [Create nodepool sub_nodes_private file]
2026-06-11 16:06:21.371287 | controller | changed
2026-06-11 16:06:21.379633 | 
2026-06-11 16:06:21.379768 | LOOP [Populate nodepool sub_nodes file]
2026-06-11 16:06:21.437051 | 
2026-06-11 16:06:21.437300 | LOOP [Populate nodepool sub_nodes_private file]
2026-06-11 16:06:21.493944 | 
2026-06-11 16:06:21.494242 | TASK [Create nodepool primary file]
2026-06-11 16:06:21.537742 | controller | skipping: Conditional result was False
2026-06-11 16:06:21.547622 | 
2026-06-11 16:06:21.547756 | TASK [Create nodepool node_private for this node]
2026-06-11 16:06:22.346855 | controller | changed
2026-06-11 16:06:22.366958 | 
2026-06-11 16:06:22.367206 | LOOP [Copy ssh keys to nodepool directory]
2026-06-11 16:06:22.860136 | controller | ok: Item: id_rsa Runtime: 0:00:00.005938
2026-06-11 16:06:22.860533 | 
2026-06-11 16:06:23.107994 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.006853
2026-06-11 16:06:23.125324 | 
2026-06-11 16:06:23.125599 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-06-11 16:06:23.986331 | controller | changed
2026-06-11 16:06:23.994604 | 
2026-06-11 16:06:23.994733 | TASK [Validate sudoers config after edits]
2026-06-11 16:06:24.351003 | controller | /etc/sudoers: parsed OK
2026-06-11 16:06:24.351123 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-06-11 16:06:24.351136 | controller | /etc/sudoers.d/zuul: parsed OK
2026-06-11 16:06:24.351143 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-06-11 16:06:24.548143 | controller | ok: Runtime: 0:00:00.013135
2026-06-11 16:06:24.558709 | 
2026-06-11 16:06:24.558857 | TASK [Show the environment passed in to job shell scripts]
2026-06-11 16:06:24.893306 | controller | SHELL=/bin/bash
2026-06-11 16:06:24.893553 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-06-11 16:06:24.893570 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-06-11 16:06:24.893585 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/73/3773/af28e3154d9fffee44d2c46cf5df3df2c533ab9a
2026-06-11 16:06:24.893595 | controller | PWD=/home/zuul
2026-06-11 16:06:24.893604 | controller | ZUUL_PIPELINE=github-check
2026-06-11 16:06:24.893618 | controller | LOGNAME=zuul
2026-06-11 16:06:24.893626 | controller | XDG_SESSION_TYPE=tty
2026-06-11 16:06:24.893634 | controller | _=/usr/bin/env
2026-06-11 16:06:24.893647 | controller | MOTD_SHOWN=pam
2026-06-11 16:06:24.893654 | controller | HOME=/home/zuul
2026-06-11 16:06:24.893662 | controller | LANG=en_US.UTF-8
2026-06-11 16:06:24.893672 | controller | SSH_CONNECTION=38.102.83.51 60270 38.102.83.146 22
2026-06-11 16:06:24.893683 | 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
2026-06-11 16:06:24.893692 | controller | ZUUL_CHANGE_IDS=3773,af28e3154d9fffee44d2c46cf5df3df2c533ab9a
2026-06-11 16:06:24.893701 | controller | WORKSPACE=/home/zuul/workspace
2026-06-11 16:06:24.893709 | controller | XDG_SESSION_CLASS=user
2026-06-11 16:06:24.893717 | controller | SELINUX_ROLE_REQUESTED=
2026-06-11 16:06:24.893725 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-06-11 16:06:24.893733 | controller | USER=zuul
2026-06-11 16:06:24.893740 | controller | ZUUL_VOTING=True
2026-06-11 16:06:24.893748 | controller | BUILD_TIMEOUT=1800000
2026-06-11 16:06:24.893756 | controller | SELINUX_USE_CURRENT_RANGE=
2026-06-11 16:06:24.893764 | controller | SHLVL=1
2026-06-11 16:06:24.893772 | controller | ZUUL_PATCHSET=af28e3154d9fffee44d2c46cf5df3df2c533ab9a
2026-06-11 16:06:24.893812 | controller | XDG_SESSION_ID=1
2026-06-11 16:06:24.893820 | controller | ZUUL_BRANCH=main
2026-06-11 16:06:24.893828 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-06-11 16:06:24.893836 | controller | SSH_CLIENT=38.102.83.51 60270 22
2026-06-11 16:06:24.893848 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-06-11 16:06:24.893857 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-06-11 16:06:24.893865 | controller | which_declare=declare -f
2026-06-11 16:06:24.893873 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-06-11 16:06:24.893881 | controller | SELINUX_LEVEL_REQUESTED=
2026-06-11 16:06:24.893888 | controller | ZUUL_CHANGE=3773
2026-06-11 16:06:24.893896 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-06-11 16:06:24.893904 | controller | ZUUL_UUID=46b67896172642c9a635b926e305e70d
2026-06-11 16:06:24.893913 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-06-11 16:06:24.893921 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-06-11 16:06:24.893930 | controller | }
2026-06-11 16:06:25.108050 | controller | ok: Runtime: 0:00:00.007121
2026-06-11 16:06:25.116725 | 
2026-06-11 16:06:25.116852 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-06-11 16:06:25.144415 | controller | skipping: Conditional result was False
2026-06-11 16:06:25.155049 | 
2026-06-11 16:06:25.155188 | TASK [Symlink /home/zuul-worker/workspace]
2026-06-11 16:06:25.695213 | controller | skipping: Conditional result was False
2026-06-11 16:06:25.705748 | 
2026-06-11 16:06:25.705873 | TASK [Ensure legacy workspace directory]
2026-06-11 16:06:25.984562 | controller | changed
2026-06-11 16:06:25.987568 | 
2026-06-11 16:06:25.987666 | PLAY RECAP
2026-06-11 16:06:25.987754 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-06-11 16:06:25.987913 | 
2026-06-11 16:06:26.147542 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-06-11 16:06:26.149977 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-06-11 16:06:26.836743 | 
2026-06-11 16:06:26.836875 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-06-11 16:06:26.848791 | 
2026-06-11 16:06:26.848865 | TASK [Create zuul-output directory]
2026-06-11 16:06:27.306854 | controller | changed
2026-06-11 16:06:27.315615 | 
2026-06-11 16:06:27.315763 | TASK [Slurp Zuul inventory test]
2026-06-11 16:06:27.790484 | controller -> localhost | ok
2026-06-11 16:06:27.802987 | 
2026-06-11 16:06:27.803128 | TASK [Save zuul inventory]
2026-06-11 16:06:28.990034 | controller | changed
2026-06-11 16:06:29.000945 | 
2026-06-11 16:06:29.001151 | TASK [Save zuul vars without the change_message]
2026-06-11 16:06:29.845499 | controller | changed
2026-06-11 16:06:29.848328 | 
2026-06-11 16:06:29.848459 | PLAY RECAP
2026-06-11 16:06:29.848544 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-11 16:06:29.848587 | 
2026-06-11 16:06:29.988902 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-06-11 16:06:29.990586 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-06-11 16:06:30.708420 | 
2026-06-11 16:06:30.708592 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-06-11 16:06:30.721478 | 
2026-06-11 16:06:30.721620 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-06-11 16:06:30.750726 | controller | ok
2026-06-11 16:06:30.766569 | 
2026-06-11 16:06:30.766777 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-06-11 16:06:30.812871 | controller | skipping: Conditional result was False
2026-06-11 16:06:30.819827 | 
2026-06-11 16:06:30.819932 | TASK [mirror-info-fork : Create /etc/ci]
2026-06-11 16:06:31.301119 | controller | ok
2026-06-11 16:06:31.310390 | 
2026-06-11 16:06:31.310553 | TASK [mirror-info-fork : Install ci_mirror script]
2026-06-11 16:06:32.245065 | controller | ok
2026-06-11 16:06:32.252932 | 
2026-06-11 16:06:32.253000 | TASK [Prepare workspace]
2026-06-11 16:06:32.273158 | controller | ok
2026-06-11 16:06:32.296789 | 
2026-06-11 16:06:32.297013 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-11 16:06:32.727992 | controller | ok
2026-06-11 16:06:32.738645 | 
2026-06-11 16:06:32.738774 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-11 16:06:34.958475 | controller | Output suppressed because no_log was given
2026-06-11 16:06:34.968101 | 
2026-06-11 16:06:34.968191 | LOOP [Create zuul-output directory]
2026-06-11 16:06:35.252046 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-06-11 16:06:35.445174 | controller | ok: "/home/zuul/zuul-output/logs"
2026-06-11 16:06:35.454741 | 
2026-06-11 16:06:35.454948 | TASK [Install required packages]
2026-06-11 16:07:20.922268 | controller | changed
2026-06-11 16:07:20.930175 | 
2026-06-11 16:07:20.930281 | TASK [Install venv]
2026-06-11 16:08:40.758308 | controller | changed
2026-06-11 16:08:40.779155 | 
2026-06-11 16:08:40.779275 | PLAY RECAP
2026-06-11 16:08:40.779392 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-11 16:08:40.779446 | 
2026-06-11 16:08:41.023524 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-06-11 16:08:41.026172 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-06-11 16:08:41.682082 | 
2026-06-11 16:08:41.682214 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-06-11 16:08:41.693576 | 
2026-06-11 16:08:41.693645 | TASK [Gather required facts]
2026-06-11 16:08:42.323306 | controller | ok
2026-06-11 16:08:42.329004 | 
2026-06-11 16:08:42.329108 | TASK [Load environment var if instructed to]
2026-06-11 16:08:42.378492 | controller | skipping: Conditional result was False
2026-06-11 16:08:42.388219 | 
2026-06-11 16:08:42.388480 | TASK [Ensure group_vars dir exists]
2026-06-11 16:08:44.157135 | controller | ok
2026-06-11 16:08:44.166304 | 
2026-06-11 16:08:44.166608 | TASK [Print related variables]
2026-06-11 16:08:44.213300 | controller | ok:
2026-06-11 16:08:44.213653 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-06-11 16:08:44.213712 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_cephadm
2026-06-11 16:08:44.221785 | 
2026-06-11 16:08:44.221918 | TASK [Run molecule]
2026-06-11 16:08:45.447035 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-06-11 16:08:45.536166 | controller | INFO     Performing prerun with role_name_check=0...
2026-06-11 16:09:06.260130 | 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).
2026-06-11 16:09:06.260852 | 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).
2026-06-11 16:09:06.261926 | 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).
2026-06-11 16:09:06.263012 | 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).
2026-06-11 16:09:06.264141 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+704e4dde was found installed in /home/zuul/.cache/ansible-compat/94fd59/collections/ansible_collections, only the first one will be used, 1.0.0+704e4dde (/home/zuul/.ansible/collections/ansible_collections).
2026-06-11 16:09:06.265263 | 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).
2026-06-11 16:09:06.266225 | 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).
2026-06-11 16:09:06.267273 | 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).
2026-06-11 16:09:06.268319 | 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).
2026-06-11 16:09:06.269340 | 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).
2026-06-11 16:09:06.270357 | 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).
2026-06-11 16:09:06.271421 | 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).
2026-06-11 16:09:06.272436 | 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).
2026-06-11 16:09:06.273424 | 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).
2026-06-11 16:09:06.274432 | 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).
2026-06-11 16:09:06.296637 | controller | INFO     Running default > prepare
2026-06-11 16:09:07.086627 | controller |
2026-06-11 16:09:07.086714 | controller | PLAY [Prepare] *****************************************************************
2026-06-11 16:09:07.086729 | controller |
2026-06-11 16:09:07.086916 | controller | TASK [Gathering Facts] *********************************************************
2026-06-11 16:09:07.087042 | controller | Thursday 11 June 2026  16:09:07 +0000 (0:00:00.013)       0:00:00.013 *********
2026-06-11 16:09:08.106393 | controller | ok: [instance]
2026-06-11 16:09:08.106621 | controller |
2026-06-11 16:09:08.106823 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2026-06-11 16:09:08.107104 | controller | Thursday 11 June 2026  16:09:08 +0000 (0:00:01.020)       0:00:01.034 *********
2026-06-11 16:09:08.133332 | controller | skipping: [instance]
2026-06-11 16:09:08.133545 | controller |
2026-06-11 16:09:08.133834 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2026-06-11 16:09:08.134196 | controller | Thursday 11 June 2026  16:09:08 +0000 (0:00:00.026)       0:00:01.061 *********
2026-06-11 16:09:08.182495 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2026-06-11 16:09:08.182571 | controller |
2026-06-11 16:09:08.182649 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2026-06-11 16:09:08.182804 | controller | Thursday 11 June 2026  16:09:08 +0000 (0:00:00.048)       0:00:01.110 *********
2026-06-11 16:09:08.548587 | controller | ok: [instance]
2026-06-11 16:09:08.548716 | controller |
2026-06-11 16:09:08.548724 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2026-06-11 16:09:08.548730 | controller | Thursday 11 June 2026  16:09:08 +0000 (0:00:00.366)       0:00:01.476 *********
2026-06-11 16:09:08.917369 | controller | ok: [instance]
2026-06-11 16:09:08.917473 | controller |
2026-06-11 16:09:08.917493 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2026-06-11 16:09:08.917667 | controller | Thursday 11 June 2026  16:09:08 +0000 (0:00:00.367)       0:00:01.844 *********
2026-06-11 16:09:09.805899 | controller | ok: [instance] => (item=ubi-9-appstream)
2026-06-11 16:09:09.806017 | controller | ok: [instance] => (item=ubi-9-baseos)
2026-06-11 16:09:09.806220 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2026-06-11 16:09:09.806402 | controller |
2026-06-11 16:09:09.806608 | controller | TASK [Deploy repo-setup] *******************************************************
2026-06-11 16:09:09.806836 | controller | Thursday 11 June 2026  16:09:09 +0000 (0:00:00.889)       0:00:02.733 *********
2026-06-11 16:09:09.879421 | controller |
2026-06-11 16:09:09.879476 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2026-06-11 16:09:09.879646 | controller | Thursday 11 June 2026  16:09:09 +0000 (0:00:00.073)       0:00:02.807 *********
2026-06-11 16:09:10.613102 | controller | changed: [instance] => (item=tmp)
2026-06-11 16:09:10.613306 | controller | changed: [instance] => (item=artifacts/repositories)
2026-06-11 16:09:10.613552 | controller | changed: [instance] => (item=venv/repo_setup)
2026-06-11 16:09:10.613812 | controller |
2026-06-11 16:09:10.614063 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2026-06-11 16:09:10.614306 | controller | Thursday 11 June 2026  16:09:10 +0000 (0:00:00.733)       0:00:03.541 *********
2026-06-11 16:09:11.605044 | controller | ok: [instance]
2026-06-11 16:09:11.605408 | controller |
2026-06-11 16:09:11.605438 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2026-06-11 16:09:11.605445 | controller | Thursday 11 June 2026  16:09:11 +0000 (0:00:00.991)       0:00:04.532 *********
2026-06-11 16:09:12.804975 | controller | changed: [instance]
2026-06-11 16:09:12.805025 | controller |
2026-06-11 16:09:12.805036 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2026-06-11 16:09:12.805054 | controller | Thursday 11 June 2026  16:09:12 +0000 (0:00:01.198)       0:00:05.731 *********
2026-06-11 16:09:20.802116 | controller | changed: [instance]
2026-06-11 16:09:20.802147 | controller |
2026-06-11 16:09:20.802163 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2026-06-11 16:09:20.802168 | controller | Thursday 11 June 2026  16:09:20 +0000 (0:00:07.997)       0:00:13.728 *********
2026-06-11 16:09:21.688636 | controller | changed: [instance]
2026-06-11 16:09:21.688662 | controller |
2026-06-11 16:09:21.688667 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2026-06-11 16:09:21.688671 | controller | Thursday 11 June 2026  16:09:21 +0000 (0:00:00.888)       0:00:14.616 *********
2026-06-11 16:09:21.712426 | controller | skipping: [instance]
2026-06-11 16:09:21.712451 | controller |
2026-06-11 16:09:21.712456 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2026-06-11 16:09:21.712460 | controller | Thursday 11 June 2026  16:09:21 +0000 (0:00:00.023)       0:00:14.640 *********
2026-06-11 16:09:22.385439 | controller | changed: [instance]
2026-06-11 16:09:22.385468 | controller |
2026-06-11 16:09:22.385473 | controller | TASK [repo_setup : Get component repo] *****************************************
2026-06-11 16:09:22.385477 | controller | Thursday 11 June 2026  16:09:22 +0000 (0:00:00.672)       0:00:15.313 *********
2026-06-11 16:09:22.445062 | controller | skipping: [instance]
2026-06-11 16:09:22.445112 | controller |
2026-06-11 16:09:22.445122 | controller | TASK [repo_setup : Rename component repo] **************************************
2026-06-11 16:09:22.445263 | controller | Thursday 11 June 2026  16:09:22 +0000 (0:00:00.059)       0:00:15.372 *********
2026-06-11 16:09:22.492344 | controller | skipping: [instance]
2026-06-11 16:09:22.492377 | controller |
2026-06-11 16:09:22.492382 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2026-06-11 16:09:22.492386 | controller | Thursday 11 June 2026  16:09:22 +0000 (0:00:00.047)       0:00:15.420 *********
2026-06-11 16:09:22.564698 | controller | skipping: [instance]
2026-06-11 16:09:22.564729 | controller |
2026-06-11 16:09:22.564736 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2026-06-11 16:09:22.564742 | controller | Thursday 11 June 2026  16:09:22 +0000 (0:00:00.071)       0:00:15.491 *********
2026-06-11 16:09:22.974572 | controller | changed: [instance]
2026-06-11 16:09:22.974616 | controller |
2026-06-11 16:09:22.974625 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2026-06-11 16:09:22.974632 | controller | Thursday 11 June 2026  16:09:22 +0000 (0:00:00.410)       0:00:15.902 *********
2026-06-11 16:09:23.605564 | controller | changed: [instance]
2026-06-11 16:09:23.605597 | controller |
2026-06-11 16:09:23.605604 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2026-06-11 16:09:23.605609 | controller | Thursday 11 June 2026  16:09:23 +0000 (0:00:00.630)       0:00:16.532 *********
2026-06-11 16:09:23.643862 | controller | skipping: [instance]
2026-06-11 16:09:23.643903 | controller |
2026-06-11 16:09:23.643912 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2026-06-11 16:09:23.643920 | controller | Thursday 11 June 2026  16:09:23 +0000 (0:00:00.037)       0:00:16.570 *********
2026-06-11 16:09:23.680454 | controller | skipping: [instance]
2026-06-11 16:09:23.680486 | controller |
2026-06-11 16:09:23.680492 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2026-06-11 16:09:23.680498 | controller | Thursday 11 June 2026  16:09:23 +0000 (0:00:00.037)       0:00:16.607 *********
2026-06-11 16:09:23.716845 | controller | skipping: [instance]
2026-06-11 16:09:23.716909 | controller |
2026-06-11 16:09:23.716918 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2026-06-11 16:09:23.716925 | controller | Thursday 11 June 2026  16:09:23 +0000 (0:00:00.035)       0:00:16.643 *********
2026-06-11 16:09:23.783178 | controller | ok: [instance]
2026-06-11 16:09:23.783566 | controller |
2026-06-11 16:09:23.783739 | controller | TASK [repo_setup : Create download directory] **********************************
2026-06-11 16:09:23.783931 | controller | Thursday 11 June 2026  16:09:23 +0000 (0:00:00.067)       0:00:16.711 *********
2026-06-11 16:09:23.813718 | controller | skipping: [instance]
2026-06-11 16:09:23.814076 | controller |
2026-06-11 16:09:23.814368 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2026-06-11 16:09:23.814642 | controller | Thursday 11 June 2026  16:09:23 +0000 (0:00:00.030)       0:00:16.741 *********
2026-06-11 16:09:23.846627 | controller | skipping: [instance]
2026-06-11 16:09:23.847073 | controller |
2026-06-11 16:09:23.847374 | controller | TASK [Download the RPM] ********************************************************
2026-06-11 16:09:23.847651 | controller | Thursday 11 June 2026  16:09:23 +0000 (0:00:00.033)       0:00:16.774 *********
2026-06-11 16:09:23.885935 | controller | skipping: [instance]
2026-06-11 16:09:23.886404 | controller |
2026-06-11 16:09:23.886681 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2026-06-11 16:09:23.887134 | controller | Thursday 11 June 2026  16:09:23 +0000 (0:00:00.039)       0:00:16.814 *********
2026-06-11 16:09:23.933571 | controller | skipping: [instance]
2026-06-11 16:09:23.933943 | controller |
2026-06-11 16:09:23.934258 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2026-06-11 16:09:23.934537 | controller | Thursday 11 June 2026  16:09:23 +0000 (0:00:00.047)       0:00:16.861 *********
2026-06-11 16:09:23.966720 | controller | skipping: [instance]
2026-06-11 16:09:23.967055 | controller |
2026-06-11 16:09:23.967368 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2026-06-11 16:09:23.967655 | controller | Thursday 11 June 2026  16:09:23 +0000 (0:00:00.033)       0:00:16.895 *********
2026-06-11 16:09:24.007362 | controller | skipping: [instance]
2026-06-11 16:09:24.007509 | controller |
2026-06-11 16:09:24.007635 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2026-06-11 16:09:24.007757 | controller | Thursday 11 June 2026  16:09:24 +0000 (0:00:00.040)       0:00:16.935 *********
2026-06-11 16:09:24.050066 | controller | skipping: [instance]
2026-06-11 16:09:24.050206 | controller |
2026-06-11 16:09:24.050333 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2026-06-11 16:09:24.050490 | controller | Thursday 11 June 2026  16:09:24 +0000 (0:00:00.042)       0:00:16.978 *********
2026-06-11 16:09:24.340752 | controller | ok: [instance]
2026-06-11 16:09:24.340805 | controller |
2026-06-11 16:09:24.340812 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2026-06-11 16:09:24.340817 | controller | Thursday 11 June 2026  16:09:24 +0000 (0:00:00.289)       0:00:17.268 *********
2026-06-11 16:09:24.592412 | controller | changed: [instance]
2026-06-11 16:09:24.592474 | controller |
2026-06-11 16:09:24.592486 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2026-06-11 16:09:24.592497 | controller | Thursday 11 June 2026  16:09:24 +0000 (0:00:00.251)       0:00:17.519 *********
2026-06-11 16:09:24.884822 | controller | changed: [instance]
2026-06-11 16:09:24.884858 | controller |
2026-06-11 16:09:24.884865 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2026-06-11 16:09:24.884871 | controller | Thursday 11 June 2026  16:09:24 +0000 (0:00:00.292)       0:00:17.812 *********
2026-06-11 16:09:24.914729 | controller | skipping: [instance]
2026-06-11 16:09:24.914762 | controller |
2026-06-11 16:09:24.914769 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2026-06-11 16:09:24.914802 | controller | Thursday 11 June 2026  16:09:24 +0000 (0:00:00.030)       0:00:17.842 *********
2026-06-11 16:09:24.943525 | controller | skipping: [instance]
2026-06-11 16:09:24.943561 | controller |
2026-06-11 16:09:24.943571 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2026-06-11 16:09:24.943580 | controller | Thursday 11 June 2026  16:09:24 +0000 (0:00:00.028)       0:00:17.871 *********
2026-06-11 16:09:24.976139 | controller | skipping: [instance]
2026-06-11 16:09:24.976211 | controller |
2026-06-11 16:09:24.976218 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2026-06-11 16:09:24.976225 | controller | Thursday 11 June 2026  16:09:24 +0000 (0:00:00.031)       0:00:17.903 *********
2026-06-11 16:09:25.009150 | controller | skipping: [instance]
2026-06-11 16:09:25.009221 | controller |
2026-06-11 16:09:25.009229 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2026-06-11 16:09:25.009236 | controller | Thursday 11 June 2026  16:09:25 +0000 (0:00:00.032)       0:00:17.935 *********
2026-06-11 16:09:25.048493 | controller | skipping: [instance]
2026-06-11 16:09:25.048655 | controller |
2026-06-11 16:09:25.049074 | controller | TASK [repo_setup : Lower the priority of component repos to allow installation from gating repo] ***
2026-06-11 16:09:25.049381 | controller | Thursday 11 June 2026  16:09:25 +0000 (0:00:00.039)       0:00:17.975 *********
2026-06-11 16:09:25.075642 | controller | skipping: [instance]
2026-06-11 16:09:25.075939 | controller |
2026-06-11 16:09:25.076330 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2026-06-11 16:09:25.076749 | controller | Thursday 11 June 2026  16:09:25 +0000 (0:00:00.027)       0:00:18.003 *********
2026-06-11 16:09:25.094085 | controller | skipping: [instance]
2026-06-11 16:09:25.094198 | controller |
2026-06-11 16:09:25.094360 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2026-06-11 16:09:25.094527 | controller | Thursday 11 June 2026  16:09:25 +0000 (0:00:00.018)       0:00:18.022 *********
2026-06-11 16:09:25.131449 | controller | skipping: [instance]
2026-06-11 16:09:25.131632 | controller |
2026-06-11 16:09:25.131936 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2026-06-11 16:09:25.132258 | controller | Thursday 11 June 2026  16:09:25 +0000 (0:00:00.036)       0:00:18.059 *********
2026-06-11 16:09:25.150514 | controller | skipping: [instance]
2026-06-11 16:09:25.150810 | controller |
2026-06-11 16:09:25.151089 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2026-06-11 16:09:25.151361 | controller | Thursday 11 June 2026  16:09:25 +0000 (0:00:00.019)       0:00:18.078 *********
2026-06-11 16:09:25.178410 | controller | skipping: [instance]
2026-06-11 16:09:25.178613 | controller |
2026-06-11 16:09:25.178901 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2026-06-11 16:09:25.179153 | controller | Thursday 11 June 2026  16:09:25 +0000 (0:00:00.027)       0:00:18.106 *********
2026-06-11 16:09:25.221623 | controller | skipping: [instance]
2026-06-11 16:09:25.221815 | controller |
2026-06-11 16:09:25.222031 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2026-06-11 16:09:25.222235 | controller | Thursday 11 June 2026  16:09:25 +0000 (0:00:00.043)       0:00:18.149 *********
2026-06-11 16:09:25.251738 | controller | skipping: [instance]
2026-06-11 16:09:25.251900 | controller |
2026-06-11 16:09:25.252106 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2026-06-11 16:09:25.252395 | controller | Thursday 11 June 2026  16:09:25 +0000 (0:00:00.030)       0:00:18.179 *********
2026-06-11 16:09:25.287617 | controller | skipping: [instance]
2026-06-11 16:09:25.287773 | controller |
2026-06-11 16:09:25.287974 | controller | TASK [test_deps : Install additional packages] *********************************
2026-06-11 16:09:25.288137 | controller | Thursday 11 June 2026  16:09:25 +0000 (0:00:00.035)       0:00:18.215 *********
2026-06-11 16:09:25.341087 | controller | skipping: [instance]
2026-06-11 16:09:25.341239 | controller |
2026-06-11 16:09:25.341384 | controller | TASK [test_deps : Install selinux python libs] *********************************
2026-06-11 16:09:25.341561 | controller | Thursday 11 June 2026  16:09:25 +0000 (0:00:00.053)       0:00:18.269 *********
2026-06-11 16:10:09.877090 | controller | ok: [instance]
2026-06-11 16:10:09.877141 | controller |
2026-06-11 16:10:09.877147 | controller | TASK [test_deps : Install python yaml libs] ************************************
2026-06-11 16:10:09.877156 | controller | Thursday 11 June 2026  16:10:09 +0000 (0:00:44.535)       0:01:02.804 *********
2026-06-11 16:10:11.341031 | controller | ok: [instance]
2026-06-11 16:10:11.341820 | controller |
2026-06-11 16:10:11.341882 | controller | TASK [test_deps : Install extra packages] **************************************
2026-06-11 16:10:11.341895 | controller | Thursday 11 June 2026  16:10:11 +0000 (0:00:01.463)       0:01:04.268 *********
2026-06-11 16:10:12.846079 | controller | ok: [instance]
2026-06-11 16:10:12.846148 | controller |
2026-06-11 16:10:12.846444 | controller | TASK [Ensure ceph-admin group exists] ******************************************
2026-06-11 16:10:12.846494 | controller | Thursday 11 June 2026  16:10:12 +0000 (0:00:01.504)       0:01:05.772 *********
2026-06-11 16:10:13.355258 | controller | changed: [instance]
2026-06-11 16:10:13.355298 | controller |
2026-06-11 16:10:13.355309 | controller | TASK [Ensure ceph-admin user exists] *******************************************
2026-06-11 16:10:13.355318 | controller | Thursday 11 June 2026  16:10:13 +0000 (0:00:00.509)       0:01:06.281 *********
2026-06-11 16:10:13.980967 | controller | changed: [instance]
2026-06-11 16:10:13.981012 | controller |
2026-06-11 16:10:13.981021 | controller | PLAY RECAP *********************************************************************
2026-06-11 16:10:13.984892 | controller | instance                   : ok=22   changed=11   unreachable=0    failed=0    skipped=29   rescued=0    ignored=0
2026-06-11 16:10:13.984927 | controller |
2026-06-11 16:10:13.984932 | controller | Thursday 11 June 2026  16:10:13 +0000 (0:00:00.626)       0:01:06.908 *********
2026-06-11 16:10:13.984937 | controller | ===============================================================================
2026-06-11 16:10:13.984940 | controller | test_deps : Install selinux python libs -------------------------------- 44.54s
2026-06-11 16:10:13.984944 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.00s
2026-06-11 16:10:13.984947 | controller | test_deps : Install extra packages -------------------------------------- 1.50s
2026-06-11 16:10:13.984951 | controller | test_deps : Install python yaml libs ------------------------------------ 1.46s
2026-06-11 16:10:13.984954 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.20s
2026-06-11 16:10:13.984958 | controller | Gathering Facts --------------------------------------------------------- 1.02s
2026-06-11 16:10:13.984963 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.99s
2026-06-11 16:10:13.984966 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.89s
2026-06-11 16:10:13.984970 | controller | repo_setup : Install repo-setup package --------------------------------- 0.89s
2026-06-11 16:10:13.984973 | controller | repo_setup : Ensure directories are present ----------------------------- 0.73s
2026-06-11 16:10:13.984976 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.67s
2026-06-11 16:10:13.984980 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.63s
2026-06-11 16:10:13.985043 | controller | Ensure ceph-admin user exists ------------------------------------------- 0.63s
2026-06-11 16:10:13.985387 | controller | Ensure ceph-admin group exists ------------------------------------------ 0.51s
2026-06-11 16:10:13.985712 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.41s
2026-06-11 16:10:13.986095 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.37s
2026-06-11 16:10:13.986451 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.37s
2026-06-11 16:10:13.986731 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.29s
2026-06-11 16:10:13.987060 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.29s
2026-06-11 16:10:13.987364 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.25s
2026-06-11 16:10:14.066989 | controller | INFO     Running default > converge
2026-06-11 16:10:14.598490 | controller |
2026-06-11 16:10:14.599007 | controller | PLAY [Converge] ****************************************************************
2026-06-11 16:10:14.599358 | controller |
2026-06-11 16:10:14.599736 | controller | TASK [Gathering Facts] *********************************************************
2026-06-11 16:10:14.600155 | controller | Thursday 11 June 2026  16:10:14 +0000 (0:00:00.017)       0:00:00.017 *********
2026-06-11 16:10:15.535978 | controller | ok: [instance]
2026-06-11 16:10:15.536014 | controller |
2026-06-11 16:10:15.536019 | controller | TASK [cifmw_cephadm : Gather distribution facts if they do not exist] **********
2026-06-11 16:10:15.536160 | controller | Thursday 11 June 2026  16:10:15 +0000 (0:00:00.936)       0:00:00.953 *********
2026-06-11 16:10:15.573755 | controller | skipping: [instance]
2026-06-11 16:10:15.573820 | controller |
2026-06-11 16:10:15.573828 | controller | TASK [cifmw_cephadm : Set list of found FSIDs] *********************************
2026-06-11 16:10:15.573834 | controller | Thursday 11 June 2026  16:10:15 +0000 (0:00:00.038)       0:00:00.991 *********
2026-06-11 16:10:15.620018 | controller | skipping: [instance]
2026-06-11 16:10:15.620056 | controller |
2026-06-11 16:10:15.620063 | controller | TASK [cifmw_cephadm : Fail if >1 FSID was discovered] **************************
2026-06-11 16:10:15.620184 | controller | Thursday 11 June 2026  16:10:15 +0000 (0:00:00.046)       0:00:01.038 *********
2026-06-11 16:10:15.661699 | controller | skipping: [instance]
2026-06-11 16:10:15.662057 | controller |
2026-06-11 16:10:15.662343 | controller | TASK [cifmw_cephadm : Set FSID to the discovered value] ************************
2026-06-11 16:10:15.662637 | controller | Thursday 11 June 2026  16:10:15 +0000 (0:00:00.042)       0:00:01.080 *********
2026-06-11 16:10:15.700131 | controller | ok: [instance]
2026-06-11 16:10:15.700421 | controller |
2026-06-11 16:10:15.700691 | controller | TASK [cifmw_cephadm : Set random cifmw_cephadm_fsid if no running ceph containers were found] ***
2026-06-11 16:10:15.701006 | controller | Thursday 11 June 2026  16:10:15 +0000 (0:00:00.038)       0:00:01.119 *********
2026-06-11 16:10:15.763027 | controller | skipping: [instance]
2026-06-11 16:10:15.763312 | controller |
2026-06-11 16:10:15.763581 | controller | TASK [cifmw_cephadm : Set first monitor IP if it was not passed] ***************
2026-06-11 16:10:15.763911 | controller | Thursday 11 June 2026  16:10:15 +0000 (0:00:00.062)       0:00:01.182 *********
2026-06-11 16:10:15.807871 | controller | ok: [instance]
2026-06-11 16:10:15.808166 | controller |
2026-06-11 16:10:15.808436 | controller | TASK [cifmw_cephadm : Ensure cifmw_cephadm_config_home (e.g. /etc/ceph) exists] ***
2026-06-11 16:10:15.808706 | controller | Thursday 11 June 2026  16:10:15 +0000 (0:00:00.044)       0:00:01.226 *********
2026-06-11 16:10:16.204701 | controller | changed: [instance]
2026-06-11 16:10:16.204734 | controller |
2026-06-11 16:10:16.204740 | controller | TASK [cifmw_cephadm : Ensure specs directory exists] ***************************
2026-06-11 16:10:16.204746 | controller | Thursday 11 June 2026  16:10:16 +0000 (0:00:00.396)       0:00:01.623 *********
2026-06-11 16:10:16.502066 | controller | changed: [instance]
2026-06-11 16:10:16.502127 | controller |
2026-06-11 16:10:16.502141 | controller | TASK [cifmw_cephadm : Stat spec file on ansible host] **************************
2026-06-11 16:10:16.502154 | controller | Thursday 11 June 2026  16:10:16 +0000 (0:00:00.296)       0:00:01.920 *********
2026-06-11 16:10:16.848941 | controller | ok: [instance -> localhost]
2026-06-11 16:10:16.848986 | controller |
2026-06-11 16:10:16.848997 | controller | TASK [cifmw_cephadm : Push cifmw_cephadm_spec to bootstrap node if spec file exsits] ***
2026-06-11 16:10:16.849007 | controller | Thursday 11 June 2026  16:10:16 +0000 (0:00:00.347)       0:00:02.267 *********
2026-06-11 16:10:16.876985 | controller | skipping: [instance]
2026-06-11 16:10:16.877018 | controller |
2026-06-11 16:10:16.877383 | controller | TASK [cifmw_cephadm : Stat cifmw_cephadm_bootstrap_conf file] ******************
2026-06-11 16:10:16.877408 | controller | Thursday 11 June 2026  16:10:16 +0000 (0:00:00.028)       0:00:02.295 *********
2026-06-11 16:10:17.222981 | controller | ok: [instance -> localhost]
2026-06-11 16:10:17.223040 | controller |
2026-06-11 16:10:17.223047 | controller | TASK [cifmw_cephadm : Push it to cifmw_cephadm_assimilate_conf on bootstrap node] ***
2026-06-11 16:10:17.223052 | controller | Thursday 11 June 2026  16:10:17 +0000 (0:00:00.346)       0:00:02.641 *********
2026-06-11 16:10:17.847991 | controller | changed: [instance]
2026-06-11 16:10:17.848036 | controller |
2026-06-11 16:10:17.848043 | controller | TASK [cifmw_cephadm : Add spec to necessary file list when using spec to bootstrap] ***
2026-06-11 16:10:17.848260 | controller | Thursday 11 June 2026  16:10:17 +0000 (0:00:00.624)       0:00:03.266 *********
2026-06-11 16:10:17.895640 | controller | skipping: [instance]
2026-06-11 16:10:17.895715 | controller |
2026-06-11 16:10:17.895725 | controller | TASK [cifmw_cephadm : Stat necessary files to bootstrap with cephadm] **********
2026-06-11 16:10:17.895735 | controller | Thursday 11 June 2026  16:10:17 +0000 (0:00:00.047)       0:00:03.313 *********
2026-06-11 16:10:18.465928 | controller | ok: [instance] => (item=/home/ceph-admin/.ssh/id_rsa)
2026-06-11 16:10:18.465982 | controller | ok: [instance] => (item=/home/ceph-admin/.ssh/id_rsa.pub)
2026-06-11 16:10:18.465995 | controller |
2026-06-11 16:10:18.466020 | controller | TASK [cifmw_cephadm : Fail if necessary files are missing] *********************
2026-06-11 16:10:18.466049 | controller | Thursday 11 June 2026  16:10:18 +0000 (0:00:00.569)       0:00:03.883 *********
2026-06-11 16:10:18.549154 | 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': 2635, 'inode': 121645215, 'dev': 64513, 'nlink': 1, 'atime': 1781194213.924629, 'mtime': 1781194213.9156287, 'ctime': 1781194213.9166286, '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': 'e7a6d1b3165bfc81ce6d084b7e85e5dcef73d667', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '2952001770', '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'})
2026-06-11 16:10:18.549241 | 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': 597, 'inode': 121645216, 'dev': 64513, 'nlink': 1, 'atime': 1781194213.924629, 'mtime': 1781194213.9156287, 'ctime': 1781194213.9166286, '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': '6cda6d82e4ecaea3651b466e4f87ff9ef5bb4091', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '3247945396', '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'})
2026-06-11 16:10:18.549247 | controller | skipping: [instance]
2026-06-11 16:10:18.549253 | controller |
2026-06-11 16:10:18.549257 | controller | TASK [cifmw_cephadm : Stat pre ceph conf file in case we should bootrap with it] ***
2026-06-11 16:10:18.549271 | controller | Thursday 11 June 2026  16:10:18 +0000 (0:00:00.083)       0:00:03.966 *********
2026-06-11 16:10:18.859933 | controller | ok: [instance]
2026-06-11 16:10:18.859979 | controller |
2026-06-11 16:10:18.860281 | controller | TASK [cifmw_cephadm : If cephadm bootstrap was not run report the reason] ******
2026-06-11 16:10:18.860321 | controller | Thursday 11 June 2026  16:10:18 +0000 (0:00:00.312)       0:00:04.278 *********
2026-06-11 16:10:18.893556 | controller | skipping: [instance]
2026-06-11 16:10:18.893597 | controller |
2026-06-11 16:10:18.893607 | controller | TASK [Mock ceph_mon_dump command] **********************************************
2026-06-11 16:10:18.893616 | controller | Thursday 11 June 2026  16:10:18 +0000 (0:00:00.033)       0:00:04.311 *********
2026-06-11 16:10:19.231952 | controller | changed: [instance -> localhost]
2026-06-11 16:10:19.232024 | controller |
2026-06-11 16:10:19.232039 | controller | TASK [Mock ceph_keys_module_output] ********************************************
2026-06-11 16:10:19.232074 | controller | Thursday 11 June 2026  16:10:19 +0000 (0:00:00.337)       0:00:04.649 *********
2026-06-11 16:10:19.252559 | controller | ok: [instance]
2026-06-11 16:10:19.252598 | controller |
2026-06-11 16:10:19.252609 | controller | TASK [cifmw_cephadm : Get ceph_cli] ********************************************
2026-06-11 16:10:19.252618 | controller | Thursday 11 June 2026  16:10:19 +0000 (0:00:00.021)       0:00:04.671 *********
2026-06-11 16:10:19.278997 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_cephadm/tasks/ceph_cli.yml for instance
2026-06-11 16:10:19.279034 | controller |
2026-06-11 16:10:19.279040 | controller | TASK [cifmw_cephadm : Set ceph CLI] ********************************************
2026-06-11 16:10:19.279117 | controller | Thursday 11 June 2026  16:10:19 +0000 (0:00:00.026)       0:00:04.697 *********
2026-06-11 16:10:19.343066 | controller | ok: [instance]
2026-06-11 16:10:19.343354 | controller |
2026-06-11 16:10:19.343620 | controller | TASK [cifmw_cephadm : Wait for the expected number of monitors to be running] ***
2026-06-11 16:10:19.343954 | controller | Thursday 11 June 2026  16:10:19 +0000 (0:00:00.064)       0:00:04.761 *********
2026-06-11 16:10:19.373967 | controller | skipping: [instance]
2026-06-11 16:10:19.374260 | controller |
2026-06-11 16:10:19.374569 | controller | TASK [cifmw_cephadm : Extract mons_json] ***************************************
2026-06-11 16:10:19.374865 | controller | Thursday 11 June 2026  16:10:19 +0000 (0:00:00.031)       0:00:04.793 *********
2026-06-11 16:10:19.435476 | controller | ok: [instance]
2026-06-11 16:10:19.435772 | controller |
2026-06-11 16:10:19.436106 | controller | TASK [cifmw_cephadm : Build mons_list] *****************************************
2026-06-11 16:10:19.436380 | controller | Thursday 11 June 2026  16:10:19 +0000 (0:00:00.061)       0:00:04.854 *********
2026-06-11 16:10:19.504516 | controller | ok: [instance] => (item=[{'type': 'v2', 'addr': '172.16.11.241:3300', 'nonce': 0}, {'type': 'v1', 'addr': '172.16.11.241:6789', 'nonce': 0}])
2026-06-11 16:10:19.504736 | controller | ok: [instance] => (item=[{'type': 'v2', 'addr': '172.16.11.176:3300', 'nonce': 0}, {'type': 'v1', 'addr': '172.16.11.176:6789', 'nonce': 0}])
2026-06-11 16:10:19.504949 | controller | ok: [instance] => (item=[{'type': 'v2', 'addr': '172.16.11.82:3300', 'nonce': 0}, {'type': 'v1', 'addr': '172.16.11.82:6789', 'nonce': 0}])
2026-06-11 16:10:19.505118 | controller |
2026-06-11 16:10:19.505300 | controller | TASK [cifmw_cephadm : Set cifmw_ceph_client_external_cluster_mon_ips from mons_list] ***
2026-06-11 16:10:19.505728 | controller | Thursday 11 June 2026  16:10:19 +0000 (0:00:00.069)       0:00:04.923 *********
2026-06-11 16:10:19.555612 | controller | ok: [instance]
2026-06-11 16:10:19.555911 | controller |
2026-06-11 16:10:19.556134 | controller | TASK [cifmw_cephadm : Ensure cifmw_ceph_client_vars path exists] ***************
2026-06-11 16:10:19.556328 | controller | Thursday 11 June 2026  16:10:19 +0000 (0:00:00.050)       0:00:04.974 *********
2026-06-11 16:10:19.597569 | controller | skipping: [instance]
2026-06-11 16:10:19.597824 | controller |
2026-06-11 16:10:19.598040 | controller | TASK [cifmw_cephadm : Save cifmw_ceph_client_vars file] ************************
2026-06-11 16:10:19.598353 | controller | Thursday 11 June 2026  16:10:19 +0000 (0:00:00.041)       0:00:05.016 *********
2026-06-11 16:10:20.058493 | controller | changed: [instance -> localhost]
2026-06-11 16:10:20.058531 | controller |
2026-06-11 16:10:20.058538 | controller | TASK [Run verify tasks] ********************************************************
2026-06-11 16:10:20.058544 | controller | Thursday 11 June 2026  16:10:20 +0000 (0:00:00.460)       0:00:05.476 *********
2026-06-11 16:10:20.111037 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_cephadm/molecule/default/tasks/verify.yml for instance
2026-06-11 16:10:20.111081 | controller |
2026-06-11 16:10:20.111087 | controller | TASK [Fail if the FSID is not defined or not valid] ****************************
2026-06-11 16:10:20.111212 | controller | Thursday 11 June 2026  16:10:20 +0000 (0:00:00.052)       0:00:05.529 *********
2026-06-11 16:10:20.176550 | controller | skipping: [instance]
2026-06-11 16:10:20.176579 | controller |
2026-06-11 16:10:20.176584 | controller | TASK [Stat pre ceph conf file] *************************************************
2026-06-11 16:10:20.176589 | controller | Thursday 11 June 2026  16:10:20 +0000 (0:00:00.066)       0:00:05.595 *********
2026-06-11 16:10:20.461279 | controller | ok: [instance]
2026-06-11 16:10:20.461308 | controller |
2026-06-11 16:10:20.461313 | controller | TASK [Fail if pre ceph conf file is missing] ***********************************
2026-06-11 16:10:20.461317 | controller | Thursday 11 June 2026  16:10:20 +0000 (0:00:00.284)       0:00:05.879 *********
2026-06-11 16:10:20.493324 | controller | skipping: [instance]
2026-06-11 16:10:20.493362 | controller |
2026-06-11 16:10:20.493367 | controller | TASK [Get ceph_cli] ************************************************************
2026-06-11 16:10:20.493373 | controller | Thursday 11 June 2026  16:10:20 +0000 (0:00:00.031)       0:00:05.911 *********
2026-06-11 16:10:20.534000 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_cephadm/tasks/ceph_cli.yml for instance
2026-06-11 16:10:20.534035 | controller |
2026-06-11 16:10:20.534583 | controller | TASK [Set ceph CLI] ************************************************************
2026-06-11 16:10:20.534613 | controller | Thursday 11 June 2026  16:10:20 +0000 (0:00:00.040)       0:00:05.952 *********
2026-06-11 16:10:20.575078 | controller | ok: [instance]
2026-06-11 16:10:20.575423 | controller |
2026-06-11 16:10:20.575866 | controller | TASK [Assert that ceph_cli contains expected parameters] ***********************
2026-06-11 16:10:20.576312 | controller | Thursday 11 June 2026  16:10:20 +0000 (0:00:00.041)       0:00:05.994 *********
2026-06-11 16:10:20.653880 | controller | ok: [instance] => changed=false
2026-06-11 16:10:20.654024 | controller |   msg: All assertions passed
2026-06-11 16:10:20.654172 | controller |
2026-06-11 16:10:20.654331 | controller | TASK [Confirm we can inlcude_vars the generated cifmw_ceph_client input file] ***
2026-06-11 16:10:20.654487 | controller | Thursday 11 June 2026  16:10:20 +0000 (0:00:00.078)       0:00:06.072 *********
2026-06-11 16:10:20.685300 | controller | ok: [instance -> localhost]
2026-06-11 16:10:20.685631 | controller |
2026-06-11 16:10:20.686003 | controller | TASK [Assert that the generated client vars are correct] ***********************
2026-06-11 16:10:20.686386 | controller | Thursday 11 June 2026  16:10:20 +0000 (0:00:00.031)       0:00:06.104 *********
2026-06-11 16:10:20.757759 | controller | ok: [instance] => changed=false
2026-06-11 16:10:20.757988 | controller |   msg: All assertions passed
2026-06-11 16:10:20.758195 | controller |
2026-06-11 16:10:20.758405 | controller | PLAY RECAP *********************************************************************
2026-06-11 16:10:20.758564 | controller | instance                   : ok=25   changed=5    unreachable=0    failed=0    skipped=12   rescued=0    ignored=0
2026-06-11 16:10:20.758707 | controller |
2026-06-11 16:10:20.758890 | controller | Thursday 11 June 2026  16:10:20 +0000 (0:00:00.072)       0:00:06.176 *********
2026-06-11 16:10:20.759032 | controller | ===============================================================================
2026-06-11 16:10:20.759189 | controller | Gathering Facts --------------------------------------------------------- 0.94s
2026-06-11 16:10:20.759339 | controller | cifmw_cephadm : Push it to cifmw_cephadm_assimilate_conf on bootstrap node --- 0.62s
2026-06-11 16:10:20.759484 | controller | cifmw_cephadm : Stat necessary files to bootstrap with cephadm ---------- 0.57s
2026-06-11 16:10:20.759631 | controller | cifmw_cephadm : Save cifmw_ceph_client_vars file ------------------------ 0.46s
2026-06-11 16:10:20.759814 | controller | cifmw_cephadm : Ensure cifmw_cephadm_config_home (e.g. /etc/ceph) exists --- 0.40s
2026-06-11 16:10:20.760060 | controller | cifmw_cephadm : Stat spec file on ansible host -------------------------- 0.35s
2026-06-11 16:10:20.760203 | controller | cifmw_cephadm : Stat cifmw_cephadm_bootstrap_conf file ------------------ 0.35s
2026-06-11 16:10:20.760353 | controller | Mock ceph_mon_dump command ---------------------------------------------- 0.34s
2026-06-11 16:10:20.760502 | controller | cifmw_cephadm : Stat pre ceph conf file in case we should bootrap with it --- 0.31s
2026-06-11 16:10:20.760701 | controller | cifmw_cephadm : Ensure specs directory exists --------------------------- 0.30s
2026-06-11 16:10:20.760941 | controller | Stat pre ceph conf file ------------------------------------------------- 0.28s
2026-06-11 16:10:20.761123 | controller | cifmw_cephadm : Fail if necessary files are missing --------------------- 0.08s
2026-06-11 16:10:20.761283 | controller | Assert that ceph_cli contains expected parameters ----------------------- 0.08s
2026-06-11 16:10:20.761458 | controller | Assert that the generated client vars are correct ----------------------- 0.07s
2026-06-11 16:10:20.761612 | controller | cifmw_cephadm : Build mons_list ----------------------------------------- 0.07s
2026-06-11 16:10:20.761813 | controller | Fail if the FSID is not defined or not valid ---------------------------- 0.07s
2026-06-11 16:10:20.761977 | controller | cifmw_cephadm : Set ceph CLI -------------------------------------------- 0.06s
2026-06-11 16:10:20.762124 | controller | cifmw_cephadm : Set random cifmw_cephadm_fsid if no running ceph containers were found --- 0.06s
2026-06-11 16:10:20.762266 | controller | cifmw_cephadm : Extract mons_json --------------------------------------- 0.06s
2026-06-11 16:10:20.762418 | controller | Run verify tasks -------------------------------------------------------- 0.05s
2026-06-11 16:10:20.840380 | controller | INFO     Running default > cleanup
2026-06-11 16:10:20.841062 | controller | WARNING  Skipping, cleanup playbook not configured.
2026-06-11 16:10:20.841896 | controller | INFO     Writing /tmp/report.html report.
2026-06-11 16:10:21.421471 | controller | ok: Runtime: 0:01:36.257987
2026-06-11 16:10:21.429877 | 
2026-06-11 16:10:21.429998 | PLAY RECAP
2026-06-11 16:10:21.430081 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-11 16:10:21.430123 | 
2026-06-11 16:10:21.625415 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-06-11 16:10:21.627550 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-06-11 16:10:22.319055 | 
2026-06-11 16:10:22.319182 | PLAY [Ensure ci-framework-data base directories exist on all nodes]
2026-06-11 16:10:22.330466 | 
2026-06-11 16:10:22.330579 | TASK [Create ci-framework-data/logs directory if missing]
2026-06-11 16:10:22.856065 | controller | ok
2026-06-11 16:10:22.900548 | 
2026-06-11 16:10:22.900683 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-06-11 16:10:22.907406 | 
2026-06-11 16:10:22.907520 | TASK [Filter out host if needed]
2026-06-11 16:10:22.918296 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-06-11 16:10:22.922523 | 
2026-06-11 16:10:22.922594 | TASK [Ensure file is present]
2026-06-11 16:10:23.389907 | controller | ok
2026-06-11 16:10:23.398196 | 
2026-06-11 16:10:23.398294 | TASK [Manage molecule report file]
2026-06-11 16:10:23.933012 | controller | ok: Runtime: 0:00:00.013951
2026-06-11 16:10:23.942052 | 
2026-06-11 16:10:23.942190 | TASK [Check if we get ci-framework-data basedir]
2026-06-11 16:10:24.217421 | controller | ok
2026-06-11 16:10:24.223211 | 
2026-06-11 16:10:24.223316 | TASK [Create ci-framework-data log directory for zuul]
2026-06-11 16:10:24.478985 | controller | changed
2026-06-11 16:10:24.490793 | 
2026-06-11 16:10:24.490954 | TASK [Copy ci-framework interesting files]
2026-06-11 16:10:24.799075 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-06-11 16:10:25.038989 | controller | ok: Runtime: 0:00:00.027175
2026-06-11 16:10:25.045730 | 
2026-06-11 16:10:25.045833 | TASK [Get SELinux listing]
2026-06-11 16:10:25.583868 | controller | ok: Runtime: 0:00:00.011771
2026-06-11 16:10:25.592721 | 
2026-06-11 16:10:25.592884 | TASK [Generate log index]
2026-06-11 16:10:26.522092 | controller | changed
2026-06-11 16:10:26.531051 | 
2026-06-11 16:10:26.531178 | TASK [Get some env related data]
2026-06-11 16:10:27.308289 | controller | /home/zuul/.local/bin/ansible
2026-06-11 16:10:28.077379 | controller | ok: Runtime: 0:00:01.183050
2026-06-11 16:10:28.127697 | 
2026-06-11 16:10:28.127834 | TASK [Generate list of logs to collect in home directory]
2026-06-11 16:10:28.527578 | controller | ok: All paths examined
2026-06-11 16:10:28.536891 | 
2026-06-11 16:10:28.537020 | LOOP [Copy logs from home directory]
2026-06-11 16:10:29.092881 | controller | changed:
2026-06-11 16:10:29.093156 | controller | {
2026-06-11 16:10:29.093222 | controller |   "atime": 1781194098.4012785,
2026-06-11 16:10:29.093273 | controller |   "ctime": 1781194120.3779254,
2026-06-11 16:10:29.093318 | controller |   "dev": 64513,
2026-06-11 16:10:29.093396 | controller |   "gid": 1000,
2026-06-11 16:10:29.093443 | controller |   "gr_name": "zuul",
2026-06-11 16:10:29.093495 | controller |   "inode": 2711,
2026-06-11 16:10:29.093540 | controller |   "isblk": false,
2026-06-11 16:10:29.093583 | controller |   "ischr": false,
2026-06-11 16:10:29.093626 | controller |   "isdir": false,
2026-06-11 16:10:29.093668 | controller |   "isfifo": false,
2026-06-11 16:10:29.093709 | controller |   "isgid": false,
2026-06-11 16:10:29.093752 | controller |   "islnk": false,
2026-06-11 16:10:29.093794 | controller |   "isreg": true,
2026-06-11 16:10:29.093836 | controller |   "issock": false,
2026-06-11 16:10:29.093885 | controller |   "isuid": false,
2026-06-11 16:10:29.093929 | controller |   "mode": "0644",
2026-06-11 16:10:29.093973 | controller |   "mtime": 1781194120.3779254,
2026-06-11 16:10:29.094020 | controller |   "nlink": 1,
2026-06-11 16:10:29.094064 | controller |   "path": "/home/zuul/ansible.log",
2026-06-11 16:10:29.094108 | controller |   "pw_name": "zuul",
2026-06-11 16:10:29.094152 | controller |   "rgrp": true,
2026-06-11 16:10:29.094196 | controller |   "roth": true,
2026-06-11 16:10:29.094244 | controller |   "rusr": true,
2026-06-11 16:10:29.094288 | controller |   "size": 6749,
2026-06-11 16:10:29.094330 | controller |   "uid": 1000,
2026-06-11 16:10:29.094415 | controller |   "wgrp": false,
2026-06-11 16:10:29.094461 | controller |   "woth": false,
2026-06-11 16:10:29.094504 | controller |   "wusr": true,
2026-06-11 16:10:29.094542 | controller |   "xgrp": false,
2026-06-11 16:10:29.094601 | controller |   "xoth": false,
2026-06-11 16:10:29.094641 | controller |   "xusr": false
2026-06-11 16:10:29.094678 | controller | }
2026-06-11 16:10:29.106885 | 
2026-06-11 16:10:29.107085 | TASK [Copy crio stats log file]
2026-06-11 16:10:29.135208 | controller | skipping: Conditional result was False
2026-06-11 16:10:29.145730 | 
2026-06-11 16:10:29.145885 | TASK [Get SELinux related data]
2026-06-11 16:10:29.465565 | controller | <no matches>
2026-06-11 16:10:29.696022 | controller | ERROR
2026-06-11 16:10:29.696256 | controller | {
2026-06-11 16:10:29.696314 | controller |   "delta": "0:00:00.023400",
2026-06-11 16:10:29.696419 | controller |   "end": "2026-06-11 16:10:29.467005",
2026-06-11 16:10:29.696475 | controller |   "msg": "non-zero return code",
2026-06-11 16:10:29.696513 | controller |   "rc": 1,
2026-06-11 16:10:29.696548 | controller |   "start": "2026-06-11 16:10:29.443605"
2026-06-11 16:10:29.696583 | controller | }
2026-06-11 16:10:29.696627 | controller | ERROR: Ignoring Errors
2026-06-11 16:10:29.704190 | 
2026-06-11 16:10:29.704310 | TASK [Create system configuration directory]
2026-06-11 16:10:29.948672 | controller | changed
2026-06-11 16:10:29.953887 | 
2026-06-11 16:10:29.953959 | TASK [Get some of the system configurations]
2026-06-11 16:10:30.491529 | controller | ok: Runtime: 0:00:00.037386
2026-06-11 16:10:30.499922 | 
2026-06-11 16:10:30.500053 | TASK [Copy generated documentation if available]
2026-06-11 16:10:30.536805 | controller | skipping: Conditional result was False
2026-06-11 16:10:30.544057 | 
2026-06-11 16:10:30.544224 | TASK [Copy generated AsciiDoc documentation if available]
2026-06-11 16:10:30.580154 | controller | skipping: Conditional result was False
2026-06-11 16:10:30.589092 | 
2026-06-11 16:10:30.589218 | TASK [Compress logs bigger than 2MB]
2026-06-11 16:10:31.135639 | controller | ok: Runtime: 0:00:00.007580
2026-06-11 16:10:31.143296 | 
2026-06-11 16:10:31.143446 | TASK [Copy files from workspace on node]
2026-06-11 16:10:31.169035 | controller | ok
2026-06-11 16:10:31.207437 | 
2026-06-11 16:10:31.207545 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-11 16:10:31.242241 | controller | skipping: Conditional result was False
2026-06-11 16:10:31.248024 | 
2026-06-11 16:10:31.248108 | TASK [fetch-output : Set log path for single node]
2026-06-11 16:10:31.291587 | controller | ok
2026-06-11 16:10:31.299753 | 
2026-06-11 16:10:31.299889 | LOOP [fetch-output : Ensure local output dirs]
2026-06-11 16:10:31.610250 | controller -> localhost | changed: "/var/lib/zuul/builds/46b67896172642c9a635b926e305e70d/work/logs"
2026-06-11 16:10:31.610567 | controller -> localhost | changed: All items complete
2026-06-11 16:10:31.610598 | 
2026-06-11 16:10:31.843511 | controller -> localhost | changed: "/var/lib/zuul/builds/46b67896172642c9a635b926e305e70d/work/artifacts"
2026-06-11 16:10:32.082028 | controller -> localhost | changed: "/var/lib/zuul/builds/46b67896172642c9a635b926e305e70d/work/docs"
2026-06-11 16:10:32.093256 | 
2026-06-11 16:10:32.093451 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-11 16:10:32.917169 | controller | changed:
2026-06-11 16:10:32.917775 | controller | .d..t...... ./
2026-06-11 16:10:32.917846 | controller | >f+++++++++ README.html
2026-06-11 16:10:32.917893 | controller | >f+++++++++ ansible-execution.log
2026-06-11 16:10:32.917933 | controller | >f+++++++++ ansible.log
2026-06-11 16:10:32.917979 | controller | >f+++++++++ dmesg.log
2026-06-11 16:10:32.918019 | controller | >f+++++++++ installed-pkgs.log
2026-06-11 16:10:32.918059 | controller | >f+++++++++ python.log
2026-06-11 16:10:32.918097 | controller | >f+++++++++ registries.conf
2026-06-11 16:10:32.918137 | controller | >f+++++++++ report.html
2026-06-11 16:10:32.918172 | controller | >f+++++++++ selinux-denials.log
2026-06-11 16:10:32.918219 | controller | >f+++++++++ selinux-listing.log
2026-06-11 16:10:32.918258 | controller | cd+++++++++ ci-framework-data/
2026-06-11 16:10:32.918298 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-06-11 16:10:32.918339 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-06-11 16:10:32.918421 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-06-11 16:10:32.918464 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-06-11 16:10:32.918507 | controller | cd+++++++++ ci-framework-data/logs/
2026-06-11 16:10:32.918563 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-06-11 16:10:32.918625 | controller | cd+++++++++ registries.conf.d/
2026-06-11 16:10:32.918690 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-06-11 16:10:32.918744 | controller | cd+++++++++ system-config/
2026-06-11 16:10:32.918786 | controller | cd+++++++++ system-config/libvirt/
2026-06-11 16:10:32.918827 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-06-11 16:10:32.918870 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-06-11 16:10:33.407879 | controller | changed: .d..t...... ./
2026-06-11 16:10:33.956789 | controller | changed: .d..t...... ./
2026-06-11 16:10:33.982681 | 
2026-06-11 16:10:33.982822 | TASK [Return artifact to Zuul]
2026-06-11 16:10:34.031073 | controller | ok
2026-06-11 16:10:34.033178 | 
2026-06-11 16:10:34.033226 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-06-11 16:10:34.033331 | 
2026-06-11 16:10:34.033382 | PLAY RECAP
2026-06-11 16:10:34.033425 | controller | ok: 19 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-06-11 16:10:34.033444 | 
2026-06-11 16:10:34.311721 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-06-11 16:10:34.314009 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-06-11 16:10:35.017124 | 
2026-06-11 16:10:35.017312 | PLAY [all]
2026-06-11 16:10:35.028825 | 
2026-06-11 16:10:35.028927 | TASK [include_role : fetch-output]
2026-06-11 16:10:35.067671 | controller | ok
2026-06-11 16:10:35.083494 | 
2026-06-11 16:10:35.083574 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-11 16:10:35.149073 | controller | skipping: Conditional result was False
2026-06-11 16:10:35.153810 | 
2026-06-11 16:10:35.153879 | TASK [fetch-output : Set log path for single node]
2026-06-11 16:10:35.195696 | controller | ok
2026-06-11 16:10:35.200302 | 
2026-06-11 16:10:35.200410 | LOOP [fetch-output : Ensure local output dirs]
2026-06-11 16:10:35.704870 | controller -> localhost | ok: "/var/lib/zuul/builds/46b67896172642c9a635b926e305e70d/work/logs"
2026-06-11 16:10:35.942644 | controller -> localhost | ok: "/var/lib/zuul/builds/46b67896172642c9a635b926e305e70d/work/artifacts"
2026-06-11 16:10:36.207137 | controller -> localhost | ok: "/var/lib/zuul/builds/46b67896172642c9a635b926e305e70d/work/docs"
2026-06-11 16:10:36.219706 | 
2026-06-11 16:10:36.219815 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-11 16:10:36.949338 | controller | ok
2026-06-11 16:10:36.949724 | controller | ok: All items complete
2026-06-11 16:10:36.949788 | 
2026-06-11 16:10:37.467114 | controller | ok
2026-06-11 16:10:38.026488 | controller | ok
2026-06-11 16:10:38.050845 | 
2026-06-11 16:10:38.050993 | TASK [include_role : fetch-output-openshift]
2026-06-11 16:10:38.078767 | controller | skipping: Conditional result was False
2026-06-11 16:10:38.089651 | 
2026-06-11 16:10:38.089795 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-11 16:10:38.647581 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013453
2026-06-11 16:10:38.896589 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.015604
2026-06-11 16:10:38.914075 | 
2026-06-11 16:10:38.914268 | PLAY [all]
2026-06-11 16:10:38.929655 | 
2026-06-11 16:10:38.929943 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-06-11 16:10:39.463855 | controller | changed
2026-06-11 16:10:39.473280 | 
2026-06-11 16:10:39.473411 | PLAY RECAP
2026-06-11 16:10:39.473502 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-06-11 16:10:39.473546 | 
2026-06-11 16:10:39.618146 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-06-11 16:10:39.620785 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-06-11 16:10:40.300929 | 
2026-06-11 16:10:40.301065 | PLAY [localhost]
2026-06-11 16:10:40.311852 | 
2026-06-11 16:10:40.311940 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-11 16:10:40.778917 | localhost | changed
2026-06-11 16:10:40.788743 | 
2026-06-11 16:10:40.788890 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-11 16:10:40.826192 | localhost | ok
2026-06-11 16:10:40.832004 | 
2026-06-11 16:10:40.832068 | TASK [add-fileserver : Create SSH private key tempfile]
2026-06-11 16:10:41.243575 | localhost | changed
2026-06-11 16:10:41.248663 | 
2026-06-11 16:10:41.248738 | TASK [add-fileserver : Create SSH private key from secret]
2026-06-11 16:10:42.045363 | localhost | changed
2026-06-11 16:10:42.049666 | 
2026-06-11 16:10:42.049731 | TASK [add-fileserver : Add fileserver ssh key]
2026-06-11 16:10:42.629308 | localhost | Identity added: /var/lib/zuul/builds/46b67896172642c9a635b926e305e70d/work/tmp/ansible.wqigiof8 (/var/lib/zuul/builds/46b67896172642c9a635b926e305e70d/work/tmp/ansible.wqigiof8)
2026-06-11 16:10:42.629713 | localhost | ok: Runtime: 0:00:00.055763
2026-06-11 16:10:42.637227 | 
2026-06-11 16:10:42.637740 | TASK [add-fileserver : Remove SSH private key from disk]
2026-06-11 16:10:43.001522 | localhost | ok: Runtime: 0:00:00.010700
2026-06-11 16:10:43.009690 | 
2026-06-11 16:10:43.009834 | TASK [add-fileserver : Add fileserver to inventory]
2026-06-11 16:10:43.095104 | localhost | changed
2026-06-11 16:10:43.105929 | 
2026-06-11 16:10:43.106195 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-06-11 16:10:43.615912 | localhost | changed
2026-06-11 16:10:43.621456 | 
2026-06-11 16:10:43.621510 | PLAY [localhost]
2026-06-11 16:10:43.625608 | 
2026-06-11 16:10:43.625670 | TASK [Generate bulk log download script]
2026-06-11 16:10:43.644291 | localhost | ok
2026-06-11 16:10:43.656005 | 
2026-06-11 16:10:43.656093 | TASK [local-log-download : Check API endpoint is defined]
2026-06-11 16:10:43.705238 | localhost | ok: All assertions passed
2026-06-11 16:10:43.710390 | 
2026-06-11 16:10:43.710484 | TASK [local-log-download : Create download script]
2026-06-11 16:10:44.253829 | localhost -> localhost | changed
2026-06-11 16:10:44.316264 | 
2026-06-11 16:10:44.316559 | TASK [Register quick-download link]
2026-06-11 16:10:44.346701 | localhost | ok
2026-06-11 16:10:44.349427 | 
2026-06-11 16:10:44.349499 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com]
2026-06-11 16:10:44.355678 | 
2026-06-11 16:10:44.355752 | TASK [Set zuul-log-path fact]
2026-06-11 16:10:44.373383 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-11 16:10:44.383320 | 
2026-06-11 16:10:44.383432 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-11 16:10:44.430967 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-11 16:10:44.434055 | 
2026-06-11 16:10:44.434135 | TASK [upload-logs : Create log directories]
2026-06-11 16:10:45.428680 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed
2026-06-11 16:10:45.432627 | 
2026-06-11 16:10:45.432777 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-11 16:10:45.787144 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.025736
2026-06-11 16:10:45.793102 | 
2026-06-11 16:10:45.793245 | TASK [upload-logs : Upload logs to log server]
2026-06-11 16:10:48.048680 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given
2026-06-11 16:10:48.052200 | 
2026-06-11 16:10:48.052333 | LOOP [upload-logs : Compress console log and json output]