2026-02-18 15:34:55.644490 | Job console starting...
2026-02-18 15:34:55.860320 | Updating repositories
2026-02-18 15:34:56.583496 | Preparing job workspace
2026-02-18 15:35:03.359186 | Running Ansible setup...
2026-02-18 15:35:06.086934 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-02-18 15:35:06.571124 | 
2026-02-18 15:35:06.571224 | PLAY [localhost]
2026-02-18 15:35:06.579107 | 
2026-02-18 15:35:06.579175 | TASK [Gathering Facts]
2026-02-18 15:35:07.333281 | localhost | ok
2026-02-18 15:35:07.345246 | 
2026-02-18 15:35:07.345349 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-18 15:35:07.673682 | localhost -> localhost | changed
2026-02-18 15:35:07.678848 | 
2026-02-18 15:35:07.678916 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-18 15:35:08.330550 | localhost -> localhost | changed
2026-02-18 15:35:08.339001 | 
2026-02-18 15:35:08.339086 | TASK [Setup log path fact]
2026-02-18 15:35:08.355828 | localhost | ok
2026-02-18 15:35:08.366174 | 
2026-02-18 15:35:08.366245 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-18 15:35:08.383332 | localhost | ok
2026-02-18 15:35:08.390398 | 
2026-02-18 15:35:08.390462 | TASK [emit-job-header : Print job information]
2026-02-18 15:35:08.417002 | # Job Information
2026-02-18 15:35:08.417116 | Ansible Version: 2.15.12
2026-02-18 15:35:08.417143 | Job: cifmw-molecule-adoption_osp_deploy
2026-02-18 15:35:08.417165 | Pipeline: github-check
2026-02-18 15:35:08.417184 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-02-18 15:35:08.417201 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3307
2026-02-18 15:35:08.417227 | Log URL (when completed): https://logserver.rdoproject.org/cfe/rdoproject.org/cfe108d459234e1da3ff2c4f73e7019f/
2026-02-18 15:35:08.417245 | Event ID: 3ed039b0-0cdf-11f1-8dec-a917006d3801
2026-02-18 15:35:08.420686 | 
2026-02-18 15:35:08.420756 | LOOP [emit-job-header : Print node information]
2026-02-18 15:35:08.502755 | localhost | ok:
2026-02-18 15:35:08.502906 | localhost | # Node Information
2026-02-18 15:35:08.502934 | localhost | Inventory Hostname: controller
2026-02-18 15:35:08.502962 | localhost | Hostname: np0005623371
2026-02-18 15:35:08.502984 | localhost | Username: zuul
2026-02-18 15:35:08.503003 | localhost | Distro: CentOS 9
2026-02-18 15:35:08.503021 | localhost | Provider: ibm-bm4-nodepool
2026-02-18 15:35:08.503038 | localhost | Region: regionOne
2026-02-18 15:35:08.503054 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2026-02-18 15:35:08.503069 | localhost | Product Name: OpenStack Compute
2026-02-18 15:35:08.503085 | localhost | Interface IP: 192.168.26.40
2026-02-18 15:35:08.521108 | 
2026-02-18 15:35:08.521180 | PLAY [all]
2026-02-18 15:35:08.527019 | 
2026-02-18 15:35:08.527079 | TASK [Gather network facts]
2026-02-18 15:35:08.859045 | controller | ok
2026-02-18 15:35:08.871483 | 
2026-02-18 15:35:08.871539 | TASK [include_role : start-zuul-console]
2026-02-18 15:35:08.888640 | controller | ok
2026-02-18 15:35:08.899408 | 
2026-02-18 15:35:08.899471 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-02-18 15:35:09.170056 | controller | ok
2026-02-18 15:35:09.178261 | 
2026-02-18 15:35:09.178352 | TASK [include_role : add-build-sshkey]
2026-02-18 15:35:09.195658 | controller | ok
2026-02-18 15:35:09.207796 | 
2026-02-18 15:35:09.207864 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-18 15:35:09.393164 | controller -> localhost | ok
2026-02-18 15:35:09.398372 | 
2026-02-18 15:35:09.398449 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-18 15:35:09.417063 | controller | ok
2026-02-18 15:35:09.429760 | controller | included: /var/lib/zuul/builds/cfe108d459234e1da3ff2c4f73e7019f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-18 15:35:09.434874 | 
2026-02-18 15:35:09.434933 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-18 15:35:09.854408 | controller -> localhost | Generating public/private rsa key pair.
2026-02-18 15:35:09.854616 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/cfe108d459234e1da3ff2c4f73e7019f/work/cfe108d459234e1da3ff2c4f73e7019f_id_rsa.
2026-02-18 15:35:09.854651 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/cfe108d459234e1da3ff2c4f73e7019f/work/cfe108d459234e1da3ff2c4f73e7019f_id_rsa.pub.
2026-02-18 15:35:09.854674 | controller -> localhost | The key fingerprint is:
2026-02-18 15:35:09.854694 | controller -> localhost | SHA256:aHBxUjvTHaZ/qilcl8vTOiwIaCQz9U9YIvcO7kEvrGI zuul-build-sshkey
2026-02-18 15:35:09.854715 | controller -> localhost | The key's randomart image is:
2026-02-18 15:35:09.854751 | controller -> localhost | +---[RSA 3072]----+
2026-02-18 15:35:09.854769 | controller -> localhost | |      o.o   o    |
2026-02-18 15:35:09.854788 | controller -> localhost | |    o o+.o + .   |
2026-02-18 15:35:09.854806 | controller -> localhost | |   ..+.=+ o .    |
2026-02-18 15:35:09.854825 | controller -> localhost | |  + .o=.oo .     |
2026-02-18 15:35:09.854842 | controller -> localhost | |   = =o*S   ...  |
2026-02-18 15:35:09.854858 | controller -> localhost | |    o.* + . oo   |
2026-02-18 15:35:09.854874 | controller -> localhost | |   . o = o +.o   |
2026-02-18 15:35:09.854892 | controller -> localhost | |  E . . + .o* .  |
2026-02-18 15:35:09.854910 | controller -> localhost | | . .     .o..+   |
2026-02-18 15:35:09.854927 | controller -> localhost | +----[SHA256]-----+
2026-02-18 15:35:09.854973 | controller -> localhost | ok: Runtime: 0:00:00.103653
2026-02-18 15:35:09.860771 | 
2026-02-18 15:35:09.860835 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-18 15:35:09.878702 | controller | ok
2026-02-18 15:35:09.887537 | controller | included: /var/lib/zuul/builds/cfe108d459234e1da3ff2c4f73e7019f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-18 15:35:09.894913 | 
2026-02-18 15:35:09.894975 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-18 15:35:09.908407 | controller | skipping: Conditional result was False
2026-02-18 15:35:09.913487 | 
2026-02-18 15:35:09.913551 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-18 15:35:10.254807 | controller | changed
2026-02-18 15:35:10.260771 | 
2026-02-18 15:35:10.260834 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-18 15:35:10.440735 | controller | ok
2026-02-18 15:35:10.445439 | 
2026-02-18 15:35:10.445502 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-18 15:35:10.969816 | controller | changed
2026-02-18 15:35:10.974396 | 
2026-02-18 15:35:10.974466 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-18 15:35:11.488396 | controller | changed
2026-02-18 15:35:11.492994 | 
2026-02-18 15:35:11.493066 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-18 15:35:11.506629 | controller | skipping: Conditional result was False
2026-02-18 15:35:11.511866 | 
2026-02-18 15:35:11.511931 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-18 15:35:11.798763 | controller -> localhost | changed
2026-02-18 15:35:11.807599 | 
2026-02-18 15:35:11.807665 | TASK [add-build-sshkey : Add back temp key]
2026-02-18 15:35:12.013461 | controller -> localhost | Identity added: /var/lib/zuul/builds/cfe108d459234e1da3ff2c4f73e7019f/work/cfe108d459234e1da3ff2c4f73e7019f_id_rsa (zuul-build-sshkey)
2026-02-18 15:35:12.013715 | controller -> localhost | ok: Runtime: 0:00:00.006444
2026-02-18 15:35:12.019258 | 
2026-02-18 15:35:12.019340 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-18 15:35:12.295512 | controller | ok
2026-02-18 15:35:12.301654 | 
2026-02-18 15:35:12.301735 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-18 15:35:12.326135 | controller | skipping: Conditional result was False
2026-02-18 15:35:12.336540 | 
2026-02-18 15:35:12.336605 | TASK [include_role : validate-host]
2026-02-18 15:35:12.355019 | controller | ok
2026-02-18 15:35:12.379196 | 
2026-02-18 15:35:12.379274 | TASK [validate-host : Define zuul_info_dir fact]
2026-02-18 15:35:12.407707 | controller | ok
2026-02-18 15:35:12.412862 | 
2026-02-18 15:35:12.412924 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-02-18 15:35:12.608938 | controller -> localhost | ok
2026-02-18 15:35:12.614672 | 
2026-02-18 15:35:12.614747 | TASK [validate-host : Collect information about the host]
2026-02-18 15:35:13.191744 | controller | ok
2026-02-18 15:35:13.199944 | 
2026-02-18 15:35:13.200015 | TASK [validate-host : Sanitize hostname]
2026-02-18 15:35:13.268608 | controller | ok
2026-02-18 15:35:13.273282 | 
2026-02-18 15:35:13.273378 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-02-18 15:35:13.664561 | controller -> localhost | changed
2026-02-18 15:35:13.690319 | 
2026-02-18 15:35:13.690397 | TASK [validate-host : Collect information about zuul worker]
2026-02-18 15:35:13.988469 | controller | ok
2026-02-18 15:35:13.992765 | 
2026-02-18 15:35:13.992824 | TASK [validate-host : Write out all zuul information for each host]
2026-02-18 15:35:14.330529 | controller -> localhost | changed
2026-02-18 15:35:14.339088 | 
2026-02-18 15:35:14.339155 | TASK [include_role : prepare-workspace-openshift]
2026-02-18 15:35:14.352634 | controller | skipping: Conditional result was False
2026-02-18 15:35:14.357148 | 
2026-02-18 15:35:14.357207 | TASK [include_role : remove-zuul-sshkey]
2026-02-18 15:35:14.370405 | controller | skipping: Conditional result was False
2026-02-18 15:35:14.375216 | 
2026-02-18 15:35:14.375277 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-18 15:35:14.549327 | controller | ok: "logs"
2026-02-18 15:35:14.549577 | controller | ok: All items complete
2026-02-18 15:35:14.549613 | 
2026-02-18 15:35:14.699865 | controller | ok: "artifacts"
2026-02-18 15:35:14.857362 | controller | ok: "docs"
2026-02-18 15:35:14.869869 | 
2026-02-18 15:35:14.869962 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-18 15:35:15.065675 | controller | changed: "logs"
2026-02-18 15:35:15.225553 | controller | changed: "artifacts"
2026-02-18 15:35:15.386221 | controller | changed: "docs"
2026-02-18 15:35:15.409421 | 
2026-02-18 15:35:15.409493 | PLAY RECAP
2026-02-18 15:35:15.409536 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-02-18 15:35:15.409564 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-18 15:35:15.409584 | 
2026-02-18 15:35:15.483765 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-02-18 15:35:15.484541 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-02-18 15:35:16.005536 | 
2026-02-18 15:35:16.005634 | PLAY [all]
2026-02-18 15:35:16.023471 | 
2026-02-18 15:35:16.023542 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-02-18 15:35:16.071714 | controller | ok
2026-02-18 15:35:16.076499 | 
2026-02-18 15:35:16.076573 | TASK [mirror-info-fork : Create /etc/ci]
2026-02-18 15:35:16.389172 | controller | changed
2026-02-18 15:35:16.394173 | 
2026-02-18 15:35:16.394246 | TASK [mirror-info-fork : Install ci_mirror script]
2026-02-18 15:35:17.134161 | controller | changed
2026-02-18 15:35:17.142581 | 
2026-02-18 15:35:17.142657 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-02-18 15:35:17.472062 | controller | changed:
2026-02-18 15:35:17.472377 | controller | {
2026-02-18 15:35:17.472418 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-02-18 15:35:17.472450 | controller | }
2026-02-18 15:35:17.657121 | controller | changed:
2026-02-18 15:35:17.657171 | controller | {
2026-02-18 15:35:17.657196 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-02-18 15:35:17.657215 | controller | }
2026-02-18 15:35:17.850324 | controller | changed:
2026-02-18 15:35:17.850404 | controller | {
2026-02-18 15:35:17.850429 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-02-18 15:35:17.850449 | controller | }
2026-02-18 15:35:18.037979 | controller | changed:
2026-02-18 15:35:18.038064 | controller | {
2026-02-18 15:35:18.038091 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-02-18 15:35:18.038112 | controller | }
2026-02-18 15:35:18.244109 | controller | changed:
2026-02-18 15:35:18.244164 | controller | {
2026-02-18 15:35:18.244189 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-02-18 15:35:18.244210 | controller | }
2026-02-18 15:35:18.433637 | controller | changed:
2026-02-18 15:35:18.433694 | controller | {
2026-02-18 15:35:18.433731 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-02-18 15:35:18.433752 | controller | }
2026-02-18 15:35:18.622371 | controller | changed:
2026-02-18 15:35:18.622420 | controller | {
2026-02-18 15:35:18.622444 | 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-02-18 15:35:18.622463 | controller | }
2026-02-18 15:35:18.813897 | controller | changed:
2026-02-18 15:35:18.813948 | controller | {
2026-02-18 15:35:18.813974 | 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-02-18 15:35:18.813994 | controller | }
2026-02-18 15:35:19.003953 | controller | changed:
2026-02-18 15:35:19.004016 | controller | {
2026-02-18 15:35:19.004042 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-02-18 15:35:19.004062 | controller | }
2026-02-18 15:35:19.197967 | controller | changed:
2026-02-18 15:35:19.198016 | controller | {
2026-02-18 15:35:19.198043 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-02-18 15:35:19.198072 | controller | }
2026-02-18 15:35:19.386320 | controller | changed:
2026-02-18 15:35:19.386414 | controller | {
2026-02-18 15:35:19.386444 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-02-18 15:35:19.386465 | controller | }
2026-02-18 15:35:19.587258 | controller | changed:
2026-02-18 15:35:19.587340 | controller | {
2026-02-18 15:35:19.587369 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-02-18 15:35:19.587389 | controller | }
2026-02-18 15:35:19.767642 | controller | changed:
2026-02-18 15:35:19.767691 | controller | {
2026-02-18 15:35:19.767723 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-02-18 15:35:19.767744 | controller | }
2026-02-18 15:35:19.957542 | controller | changed:
2026-02-18 15:35:19.957638 | controller | {
2026-02-18 15:35:19.957664 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-02-18 15:35:19.957704 | controller | }
2026-02-18 15:35:20.144829 | controller | changed:
2026-02-18 15:35:20.144879 | controller | {
2026-02-18 15:35:20.144903 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-02-18 15:35:20.144922 | controller | }
2026-02-18 15:35:20.335553 | controller | changed:
2026-02-18 15:35:20.335603 | controller | {
2026-02-18 15:35:20.335627 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-02-18 15:35:20.335645 | controller | }
2026-02-18 15:35:20.522258 | controller | changed:
2026-02-18 15:35:20.522322 | controller | {
2026-02-18 15:35:20.522348 | 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-02-18 15:35:20.522368 | controller | }
2026-02-18 15:35:20.712240 | controller | changed:
2026-02-18 15:35:20.712290 | controller | {
2026-02-18 15:35:20.712333 | 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-02-18 15:35:20.712363 | controller | }
2026-02-18 15:35:20.898223 | controller | changed:
2026-02-18 15:35:20.898276 | controller | {
2026-02-18 15:35:20.898321 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-02-18 15:35:20.898345 | controller | }
2026-02-18 15:35:21.083276 | controller | changed:
2026-02-18 15:35:21.083336 | controller | {
2026-02-18 15:35:21.083361 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-02-18 15:35:21.083380 | controller | }
2026-02-18 15:35:21.271403 | controller | changed:
2026-02-18 15:35:21.271456 | controller | {
2026-02-18 15:35:21.271481 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-02-18 15:35:21.271502 | controller | }
2026-02-18 15:35:21.462148 | controller | changed:
2026-02-18 15:35:21.462200 | controller | {
2026-02-18 15:35:21.462226 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-02-18 15:35:21.462245 | controller | }
2026-02-18 15:35:21.647280 | controller | changed:
2026-02-18 15:35:21.647387 | controller | {
2026-02-18 15:35:21.647415 | 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-02-18 15:35:21.647437 | controller | }
2026-02-18 15:35:21.841432 | controller | changed:
2026-02-18 15:35:21.841487 | controller | {
2026-02-18 15:35:21.841511 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-02-18 15:35:21.841530 | controller | }
2026-02-18 15:35:22.029849 | controller | changed:
2026-02-18 15:35:22.029908 | controller | {
2026-02-18 15:35:22.029933 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-02-18 15:35:22.029952 | controller | }
2026-02-18 15:35:22.235789 | controller | changed:
2026-02-18 15:35:22.235846 | controller | {
2026-02-18 15:35:22.235872 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-02-18 15:35:22.235893 | controller | }
2026-02-18 15:35:22.260177 | 
2026-02-18 15:35:22.260288 | TASK [Set timezone to UTC]
2026-02-18 15:35:22.623988 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-02-18 15:35:22.628265 | 
2026-02-18 15:35:22.628352 | TASK [Create nodepool directory]
2026-02-18 15:35:22.819452 | controller | changed
2026-02-18 15:35:22.824813 | 
2026-02-18 15:35:22.824880 | TASK [Create nodepool sub_nodes file]
2026-02-18 15:35:23.298912 | controller | changed
2026-02-18 15:35:23.303734 | 
2026-02-18 15:35:23.303814 | TASK [Create nodepool sub_nodes_private file]
2026-02-18 15:35:23.768709 | controller | changed
2026-02-18 15:35:23.773676 | 
2026-02-18 15:35:23.773742 | LOOP [Populate nodepool sub_nodes file]
2026-02-18 15:35:23.802541 | 
2026-02-18 15:35:23.802658 | LOOP [Populate nodepool sub_nodes_private file]
2026-02-18 15:35:23.831120 | 
2026-02-18 15:35:23.831231 | TASK [Create nodepool primary file]
2026-02-18 15:35:23.854516 | controller | skipping: Conditional result was False
2026-02-18 15:35:23.859464 | 
2026-02-18 15:35:23.859531 | TASK [Create nodepool node_private for this node]
2026-02-18 15:35:24.364700 | controller | changed
2026-02-18 15:35:24.369879 | 
2026-02-18 15:35:24.369941 | LOOP [Copy ssh keys to nodepool directory]
2026-02-18 15:35:24.665331 | controller | ok: Item: id_rsa Runtime: 0:00:00.004330
2026-02-18 15:35:24.665501 | 
2026-02-18 15:35:24.812883 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004507
2026-02-18 15:35:24.827039 | 
2026-02-18 15:35:24.827128 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-02-18 15:35:25.300017 | controller | changed
2026-02-18 15:35:25.304755 | 
2026-02-18 15:35:25.304827 | TASK [Validate sudoers config after edits]
2026-02-18 15:35:25.500551 | controller | /etc/sudoers: parsed OK
2026-02-18 15:35:25.500616 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-02-18 15:35:25.500626 | controller | /etc/sudoers.d/zuul: parsed OK
2026-02-18 15:35:25.500632 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-02-18 15:35:25.829882 | controller | ok: Runtime: 0:00:00.004850
2026-02-18 15:35:25.834639 | 
2026-02-18 15:35:25.834697 | TASK [Show the environment passed in to job shell scripts]
2026-02-18 15:35:26.013937 | controller | SHELL=/bin/bash
2026-02-18 15:35:26.013970 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-02-18 15:35:26.013979 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-02-18 15:35:26.013985 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/07/3307/e2af3e8bcea69451253f5fc203cd77f20970cdc9
2026-02-18 15:35:26.013991 | controller | PWD=/home/zuul
2026-02-18 15:35:26.013996 | controller | ZUUL_PIPELINE=github-check
2026-02-18 15:35:26.014001 | controller | LOGNAME=zuul
2026-02-18 15:35:26.014005 | controller | XDG_SESSION_TYPE=tty
2026-02-18 15:35:26.014010 | controller | _=/usr/bin/env
2026-02-18 15:35:26.014041 | controller | MOTD_SHOWN=pam
2026-02-18 15:35:26.014049 | controller | HOME=/home/zuul
2026-02-18 15:35:26.014054 | controller | LANG=en_US.UTF-8
2026-02-18 15:35:26.014059 | controller | SSH_CONNECTION=192.168.26.12 60446 192.168.26.40 22
2026-02-18 15:35:26.014064 | 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-02-18 15:35:26.014071 | controller | ZUUL_CHANGE_IDS=3307,e2af3e8bcea69451253f5fc203cd77f20970cdc9
2026-02-18 15:35:26.014076 | controller | WORKSPACE=/home/zuul/workspace
2026-02-18 15:35:26.014081 | controller | XDG_SESSION_CLASS=user
2026-02-18 15:35:26.014086 | controller | SELINUX_ROLE_REQUESTED=
2026-02-18 15:35:26.014091 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-02-18 15:35:26.014096 | controller | USER=zuul
2026-02-18 15:35:26.014100 | controller | ZUUL_VOTING=True
2026-02-18 15:35:26.014105 | controller | BUILD_TIMEOUT=1800000
2026-02-18 15:35:26.014110 | controller | SELINUX_USE_CURRENT_RANGE=
2026-02-18 15:35:26.014115 | controller | SHLVL=1
2026-02-18 15:35:26.014120 | controller | ZUUL_PATCHSET=e2af3e8bcea69451253f5fc203cd77f20970cdc9
2026-02-18 15:35:26.014124 | controller | XDG_SESSION_ID=1
2026-02-18 15:35:26.014129 | controller | ZUUL_BRANCH=main
2026-02-18 15:35:26.014134 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-02-18 15:35:26.014139 | controller | SSH_CLIENT=192.168.26.12 60446 22
2026-02-18 15:35:26.014145 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-02-18 15:35:26.014150 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-02-18 15:35:26.014155 | controller | which_declare=declare -f
2026-02-18 15:35:26.014160 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-02-18 15:35:26.014165 | controller | SELINUX_LEVEL_REQUESTED=
2026-02-18 15:35:26.014170 | controller | ZUUL_CHANGE=3307
2026-02-18 15:35:26.014174 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-02-18 15:35:26.014179 | controller | ZUUL_UUID=cfe108d459234e1da3ff2c4f73e7019f
2026-02-18 15:35:26.014184 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-02-18 15:35:26.014189 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-02-18 15:35:26.014194 | controller | }
2026-02-18 15:35:26.358806 | controller | ok: Runtime: 0:00:00.005508
2026-02-18 15:35:26.363271 | 
2026-02-18 15:35:26.363343 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-02-18 15:35:26.376741 | controller | skipping: Conditional result was False
2026-02-18 15:35:26.381727 | 
2026-02-18 15:35:26.381792 | TASK [Symlink /home/zuul-worker/workspace]
2026-02-18 15:35:26.903858 | controller | skipping: Conditional result was False
2026-02-18 15:35:26.908779 | 
2026-02-18 15:35:26.908839 | TASK [Ensure legacy workspace directory]
2026-02-18 15:35:27.078400 | controller | changed
2026-02-18 15:35:27.094234 | 
2026-02-18 15:35:27.094307 | PLAY RECAP
2026-02-18 15:35:27.094364 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-02-18 15:35:27.094393 | 
2026-02-18 15:35:27.164940 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-02-18 15:35:27.165883 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-02-18 15:35:27.625231 | 
2026-02-18 15:35:27.625341 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-02-18 15:35:27.643791 | 
2026-02-18 15:35:27.643861 | TASK [Create zuul-output directory]
2026-02-18 15:35:27.926386 | controller | changed
2026-02-18 15:35:27.931090 | 
2026-02-18 15:35:27.931164 | TASK [Slurp Zuul inventory test]
2026-02-18 15:35:28.177926 | controller -> localhost | ok
2026-02-18 15:35:28.183772 | 
2026-02-18 15:35:28.183834 | TASK [Save zuul inventory]
2026-02-18 15:35:28.839124 | controller | changed
2026-02-18 15:35:28.843501 | 
2026-02-18 15:35:28.843563 | TASK [Save zuul vars without the change_message]
2026-02-18 15:35:29.369986 | controller | changed
2026-02-18 15:35:29.386876 | 
2026-02-18 15:35:29.386942 | PLAY RECAP
2026-02-18 15:35:29.386988 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-18 15:35:29.387009 | 
2026-02-18 15:35:29.449383 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-02-18 15:35:29.450077 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-02-18 15:35:29.956022 | 
2026-02-18 15:35:29.956124 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-02-18 15:35:29.976147 | 
2026-02-18 15:35:29.976214 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-02-18 15:35:29.994234 | controller | ok
2026-02-18 15:35:30.010004 | 
2026-02-18 15:35:30.010079 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-02-18 15:35:30.033813 | controller | skipping: Conditional result was False
2026-02-18 15:35:30.039202 | 
2026-02-18 15:35:30.039267 | TASK [mirror-info-fork : Create /etc/ci]
2026-02-18 15:35:30.330531 | controller | ok
2026-02-18 15:35:30.335820 | 
2026-02-18 15:35:30.335884 | TASK [mirror-info-fork : Install ci_mirror script]
2026-02-18 15:35:30.893007 | controller | ok
2026-02-18 15:35:30.902158 | 
2026-02-18 15:35:30.902217 | TASK [Prepare workspace]
2026-02-18 15:35:30.920158 | controller | ok
2026-02-18 15:35:30.934823 | 
2026-02-18 15:35:30.934886 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-18 15:35:31.236734 | controller | ok
2026-02-18 15:35:31.241798 | 
2026-02-18 15:35:31.241858 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-18 15:35:33.498749 | controller | Output suppressed because no_log was given
2026-02-18 15:35:33.507026 | 
2026-02-18 15:35:33.507088 | LOOP [Create zuul-output directory]
2026-02-18 15:35:33.680325 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-02-18 15:35:33.828836 | controller | ok: "/home/zuul/zuul-output/logs"
2026-02-18 15:35:33.839564 | 
2026-02-18 15:35:33.839626 | TASK [Install required packages]
2026-02-18 15:36:07.484963 | controller | changed
2026-02-18 15:36:07.489701 | 
2026-02-18 15:36:07.489771 | TASK [Install venv]
2026-02-18 15:37:06.911361 | controller | changed
2026-02-18 15:37:06.933341 | 
2026-02-18 15:37:06.933397 | PLAY RECAP
2026-02-18 15:37:06.933442 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-18 15:37:06.933465 | 
2026-02-18 15:37:06.999438 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-02-18 15:37:07.000223 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-02-18 15:37:07.476035 | 
2026-02-18 15:37:07.476125 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-02-18 15:37:07.494545 | 
2026-02-18 15:37:07.494612 | TASK [Gather required facts]
2026-02-18 15:37:07.948623 | controller | ok
2026-02-18 15:37:07.953684 | 
2026-02-18 15:37:07.953768 | TASK [Load environment var if instructed to]
2026-02-18 15:37:07.977683 | controller | skipping: Conditional result was False
2026-02-18 15:37:07.982833 | 
2026-02-18 15:37:07.982899 | TASK [Ensure group_vars dir exists]
2026-02-18 15:37:08.264473 | controller | ok
2026-02-18 15:37:08.269867 | 
2026-02-18 15:37:08.269938 | TASK [Print related variables]
2026-02-18 15:37:08.298143 | controller | ok:
2026-02-18 15:37:08.298256 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-02-18 15:37:08.298284 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/adoption_osp_deploy
2026-02-18 15:37:08.302866 | 
2026-02-18 15:37:08.302925 | TASK [Run molecule]
2026-02-18 15:37:09.154416 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-02-18 15:37:09.218771 | controller | WARNING  Driver podman does not provide a schema.
2026-02-18 15:37:09.221038 | controller | INFO     Performing prerun with role_name_check=0...
2026-02-18 15:37:26.625681 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-18 15:37:26.626136 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-18 15:37:26.626524 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-18 15:37:26.626933 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-18 15:37:26.627402 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+35ea6ef2 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 1.0.0+35ea6ef2 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-18 15:37:26.627828 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-18 15:37:26.628223 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-18 15:37:26.628590 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-18 15:37:26.628985 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-18 15:37:26.629381 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-18 15:37:26.629773 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-18 15:37:26.630152 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-18 15:37:26.630572 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-18 15:37:26.630990 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-18 15:37:26.631398 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-18 15:37:26.640387 | controller | INFO     Running default > destroy
2026-02-18 15:37:26.850934 | controller | INFO     Sanity checks: 'podman'
2026-02-18 15:37:27.687823 | controller |
2026-02-18 15:37:27.687886 | controller | PLAY [Destroy] *****************************************************************
2026-02-18 15:37:27.687928 | controller |
2026-02-18 15:37:27.688036 | controller | TASK [Get passwd entries for USER env] *****************************************
2026-02-18 15:37:27.688130 | controller | Wednesday 18 February 2026  15:37:27 +0000 (0:00:00.014)       0:00:00.014 ****
2026-02-18 15:37:27.985655 | controller | ok: [localhost]
2026-02-18 15:37:27.985753 | controller |
2026-02-18 15:37:27.985858 | controller | TASK [Get shell async_dir] *****************************************************
2026-02-18 15:37:27.985959 | controller | Wednesday 18 February 2026  15:37:27 +0000 (0:00:00.298)       0:00:00.312 ****
2026-02-18 15:37:28.028813 | controller | ok: [localhost]
2026-02-18 15:37:28.028900 | controller |
2026-02-18 15:37:28.028996 | controller | TASK [Set async_dir for HOME env] **********************************************
2026-02-18 15:37:28.029092 | controller | Wednesday 18 February 2026  15:37:28 +0000 (0:00:00.043)       0:00:00.356 ****
2026-02-18 15:37:28.063884 | controller | skipping: [localhost]
2026-02-18 15:37:28.063987 | controller |
2026-02-18 15:37:28.064103 | controller | TASK [Destroy molecule instance(s)] ********************************************
2026-02-18 15:37:28.064243 | controller | Wednesday 18 February 2026  15:37:28 +0000 (0:00:00.034)       0:00:00.391 ****
2026-02-18 15:37:28.442393 | controller | changed: [localhost] => (item={'command': '/sbin/init', 'dockerfile': 'containerfile', 'image': 'registry.access.redhat.com/ubi9/ubi-init', 'name': 'instance', 'pre_build_image': True})
2026-02-18 15:37:28.442453 | controller |
2026-02-18 15:37:28.442553 | controller | TASK [Wait for instance(s) deletion to complete] *******************************
2026-02-18 15:37:28.442651 | controller | Wednesday 18 February 2026  15:37:28 +0000 (0:00:00.377)       0:00:00.768 ****
2026-02-18 15:37:33.888141 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) deletion to complete (300 retries left).
2026-02-18 15:37:33.888219 | controller | changed: [localhost] => (item={'failed': 0, 'started': 1, 'finished': 0, 'ansible_job_id': 'j374759563997.30032', 'results_file': '/home/zuul/.ansible_async/j374759563997.30032', 'changed': True, 'item': {'command': '/sbin/init', 'dockerfile': 'containerfile', 'image': 'registry.access.redhat.com/ubi9/ubi-init', 'name': 'instance', 'pre_build_image': True}, 'ansible_loop_var': 'item'})
2026-02-18 15:37:33.888317 | controller |
2026-02-18 15:37:33.888405 | controller | TASK [Delete podman network dedicated to this scenario] ************************
2026-02-18 15:37:33.888490 | controller | Wednesday 18 February 2026  15:37:33 +0000 (0:00:05.446)       0:00:06.215 ****
2026-02-18 15:37:33.936221 | controller | skipping: [localhost] => (item=instance: None specified)
2026-02-18 15:37:33.936291 | controller | skipping: [localhost]
2026-02-18 15:37:33.936387 | controller |
2026-02-18 15:37:33.936471 | controller | PLAY RECAP *********************************************************************
2026-02-18 15:37:33.936559 | controller | localhost                  : ok=4    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2026-02-18 15:37:33.936625 | controller |
2026-02-18 15:37:33.936735 | controller | Wednesday 18 February 2026  15:37:33 +0000 (0:00:00.048)       0:00:06.263 ****
2026-02-18 15:37:33.936809 | controller | ===============================================================================
2026-02-18 15:37:33.936891 | controller | Wait for instance(s) deletion to complete ------------------------------- 5.45s
2026-02-18 15:37:33.936975 | controller | Destroy molecule instance(s) -------------------------------------------- 0.38s
2026-02-18 15:37:33.937057 | controller | Get passwd entries for USER env ----------------------------------------- 0.30s
2026-02-18 15:37:33.937138 | controller | Delete podman network dedicated to this scenario ------------------------ 0.05s
2026-02-18 15:37:33.937247 | controller | Get shell async_dir ----------------------------------------------------- 0.04s
2026-02-18 15:37:33.937342 | controller | Set async_dir for HOME env ---------------------------------------------- 0.03s
2026-02-18 15:37:33.993322 | controller | INFO     Running default > create
2026-02-18 15:37:34.388216 | controller |
2026-02-18 15:37:34.388463 | controller | PLAY [Create] ******************************************************************
2026-02-18 15:37:34.388641 | controller |
2026-02-18 15:37:34.388774 | controller | TASK [Get podman executable path] **********************************************
2026-02-18 15:37:34.388851 | controller | Wednesday 18 February 2026  15:37:34 +0000 (0:00:00.013)       0:00:00.013 ****
2026-02-18 15:37:34.679522 | controller | ok: [localhost]
2026-02-18 15:37:34.679609 | controller |
2026-02-18 15:37:34.679708 | controller | TASK [Register podman executable path] *****************************************
2026-02-18 15:37:34.679814 | controller | Wednesday 18 February 2026  15:37:34 +0000 (0:00:00.291)       0:00:00.305 ****
2026-02-18 15:37:34.719221 | controller | ok: [localhost]
2026-02-18 15:37:34.719283 | controller |
2026-02-18 15:37:34.719389 | controller | TASK [Get passwd entries for USER env] *****************************************
2026-02-18 15:37:34.719478 | controller | Wednesday 18 February 2026  15:37:34 +0000 (0:00:00.039)       0:00:00.345 ****
2026-02-18 15:37:35.008384 | controller | ok: [localhost]
2026-02-18 15:37:35.008472 | controller |
2026-02-18 15:37:35.008574 | controller | TASK [Get shell async_dir] *****************************************************
2026-02-18 15:37:35.008683 | controller | Wednesday 18 February 2026  15:37:35 +0000 (0:00:00.289)       0:00:00.634 ****
2026-02-18 15:37:35.050494 | controller | ok: [localhost]
2026-02-18 15:37:35.050542 | controller |
2026-02-18 15:37:35.050679 | controller | TASK [Set async_dir for HOME env] **********************************************
2026-02-18 15:37:35.050755 | controller | Wednesday 18 February 2026  15:37:35 +0000 (0:00:00.042)       0:00:00.676 ****
2026-02-18 15:37:35.083611 | controller | skipping: [localhost]
2026-02-18 15:37:35.083723 | controller |
2026-02-18 15:37:35.083745 | controller | TASK [Log into a container registry] *******************************************
2026-02-18 15:37:35.083789 | controller | Wednesday 18 February 2026  15:37:35 +0000 (0:00:00.033)       0:00:00.709 ****
2026-02-18 15:37:35.125909 | controller | skipping: [localhost] => (item="instance registry username: None specified")
2026-02-18 15:37:35.125992 | controller | skipping: [localhost]
2026-02-18 15:37:35.126077 | controller |
2026-02-18 15:37:35.126165 | controller | TASK [Check presence of custom Dockerfiles] ************************************
2026-02-18 15:37:35.126275 | controller | Wednesday 18 February 2026  15:37:35 +0000 (0:00:00.042)       0:00:00.751 ****
2026-02-18 15:37:35.424317 | controller | ok: [localhost] => (item=Dockerfile: containerfile)
2026-02-18 15:37:35.424400 | controller |
2026-02-18 15:37:35.424502 | controller | TASK [Create Dockerfiles from image names] *************************************
2026-02-18 15:37:35.424601 | controller | Wednesday 18 February 2026  15:37:35 +0000 (0:00:00.298)       0:00:01.049 ****
2026-02-18 15:37:35.465106 | controller | skipping: [localhost] => (item="Dockerfile: containerfile; Image: registry.access.redhat.com/ubi9/ubi-init")
2026-02-18 15:37:35.465191 | controller | skipping: [localhost]
2026-02-18 15:37:35.465313 | controller |
2026-02-18 15:37:35.465407 | controller | TASK [Discover local Podman images] ********************************************
2026-02-18 15:37:35.465501 | controller | Wednesday 18 February 2026  15:37:35 +0000 (0:00:00.041)       0:00:01.091 ****
2026-02-18 15:37:35.800990 | controller | ok: [localhost] => (item=instance)
2026-02-18 15:37:35.801074 | controller |
2026-02-18 15:37:35.801169 | controller | TASK [Build an Ansible compatible image] ***************************************
2026-02-18 15:37:35.801280 | controller | Wednesday 18 February 2026  15:37:35 +0000 (0:00:00.335)       0:00:01.426 ****
2026-02-18 15:37:35.845640 | controller | skipping: [localhost] => (item=registry.access.redhat.com/ubi9/ubi-init)
2026-02-18 15:37:35.845737 | controller | skipping: [localhost]
2026-02-18 15:37:35.845842 | controller |
2026-02-18 15:37:35.845941 | controller | TASK [Determine the CMD directives] ********************************************
2026-02-18 15:37:35.846033 | controller | Wednesday 18 February 2026  15:37:35 +0000 (0:00:00.044)       0:00:01.471 ****
2026-02-18 15:37:35.892262 | controller | ok: [localhost] => (item="instance command: /sbin/init")
2026-02-18 15:37:35.892355 | controller |
2026-02-18 15:37:35.892452 | controller | TASK [Remove possible pre-existing containers] *********************************
2026-02-18 15:37:35.892542 | controller | Wednesday 18 February 2026  15:37:35 +0000 (0:00:00.046)       0:00:01.518 ****
2026-02-18 15:37:36.137822 | controller | changed: [localhost]
2026-02-18 15:37:36.138168 | controller |
2026-02-18 15:37:36.180299 | controller | TASK [Discover local podman networks] ******************************************
2026-02-18 15:37:36.180338 | controller | Wednesday 18 February 2026  15:37:36 +0000 (0:00:00.245)       0:00:01.763 ****
2026-02-18 15:37:36.180353 | controller | skipping: [localhost] => (item=instance: None specified)
2026-02-18 15:37:36.180379 | controller | skipping: [localhost]
2026-02-18 15:37:36.180389 | controller |
2026-02-18 15:37:36.180485 | controller | TASK [Create podman network dedicated to this scenario] ************************
2026-02-18 15:37:36.180506 | controller | Wednesday 18 February 2026  15:37:36 +0000 (0:00:00.042)       0:00:01.806 ****
2026-02-18 15:37:36.216583 | controller | skipping: [localhost]
2026-02-18 15:37:36.533108 | controller |
2026-02-18 15:37:36.533131 | controller | TASK [Create molecule instance(s)] *********************************************
2026-02-18 15:37:36.533138 | controller | Wednesday 18 February 2026  15:37:36 +0000 (0:00:00.036)       0:00:01.842 ****
2026-02-18 15:37:36.533147 | controller | changed: [localhost] => (item=instance)
2026-02-18 15:37:41.984296 | controller |
2026-02-18 15:37:41.984326 | controller | TASK [Wait for instance(s) creation to complete] *******************************
2026-02-18 15:37:41.984334 | controller | Wednesday 18 February 2026  15:37:36 +0000 (0:00:00.316)       0:00:02.158 ****
2026-02-18 15:37:41.984344 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) creation to complete (300 retries left).
2026-02-18 15:37:41.984367 | controller | changed: [localhost] => (item=instance)
2026-02-18 15:37:41.984373 | controller |
2026-02-18 15:37:41.984378 | controller | PLAY RECAP *********************************************************************
2026-02-18 15:37:41.984383 | controller | localhost                  : ok=10   changed=3    unreachable=0    failed=0    skipped=6    rescued=0    ignored=0
2026-02-18 15:37:41.984388 | controller |
2026-02-18 15:37:41.984393 | controller | Wednesday 18 February 2026  15:37:41 +0000 (0:00:05.450)       0:00:07.609 ****
2026-02-18 15:37:41.984398 | controller | ===============================================================================
2026-02-18 15:37:41.984403 | controller | Wait for instance(s) creation to complete ------------------------------- 5.45s
2026-02-18 15:37:41.984410 | controller | Discover local Podman images -------------------------------------------- 0.34s
2026-02-18 15:37:41.984430 | controller | Create molecule instance(s) --------------------------------------------- 0.32s
2026-02-18 15:37:41.985329 | controller | Check presence of custom Dockerfiles ------------------------------------ 0.30s
2026-02-18 15:37:41.985378 | controller | Get podman executable path ---------------------------------------------- 0.29s
2026-02-18 15:37:41.985386 | controller | Get passwd entries for USER env ----------------------------------------- 0.29s
2026-02-18 15:37:41.985391 | controller | Remove possible pre-existing containers --------------------------------- 0.25s
2026-02-18 15:37:41.985396 | controller | Determine the CMD directives -------------------------------------------- 0.05s
2026-02-18 15:37:41.985401 | controller | Build an Ansible compatible image --------------------------------------- 0.04s
2026-02-18 15:37:41.985406 | controller | Discover local podman networks ------------------------------------------ 0.04s
2026-02-18 15:37:41.985412 | controller | Get shell async_dir ----------------------------------------------------- 0.04s
2026-02-18 15:37:41.985417 | controller | Log into a container registry ------------------------------------------- 0.04s
2026-02-18 15:37:41.985422 | controller | Create Dockerfiles from image names ------------------------------------- 0.04s
2026-02-18 15:37:41.985429 | controller | Register podman executable path ----------------------------------------- 0.04s
2026-02-18 15:37:41.985594 | controller | Create podman network dedicated to this scenario ------------------------ 0.04s
2026-02-18 15:37:42.037331 | controller | Set async_dir for HOME env ---------------------------------------------- 0.03s
2026-02-18 15:37:42.037358 | controller | INFO     Running default > prepare
2026-02-18 15:37:42.037829 | controller | WARNING  Skipping, prepare playbook not configured.
2026-02-18 15:37:42.046044 | controller | INFO     Running default > converge
2026-02-18 15:37:42.442608 | controller |
2026-02-18 15:37:42.442664 | controller | PLAY [Converge] ****************************************************************
2026-02-18 15:37:42.442674 | controller |
2026-02-18 15:37:42.471997 | controller | TASK [Gather stack nodes and facts] ********************************************
2026-02-18 15:37:42.472019 | controller | Wednesday 18 February 2026  15:37:42 +0000 (0:00:00.017)       0:00:00.017 ****
2026-02-18 15:37:42.472033 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/adoption_osp_deploy/tasks/gather_stack_nodes.yml for instance => (item={'stackname': 'overcloud', 'network_data_file': 'network_data.yaml.j2', 'vips_data_file': 'vips_data_overcloud.yaml', 'stack_nodes': ['osp-controllers']})
2026-02-18 15:37:42.504789 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/adoption_osp_deploy/tasks/gather_stack_nodes.yml for instance => (item={'stackname': 'cell1', 'network_data_file': 'network_data.yaml.j2', 'vips_data_file': 'vips_data_cell1.yaml', 'stack_nodes': ['cell1-osp-computes', 'cell1-osp-controllers']})
2026-02-18 15:37:42.504814 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/adoption_osp_deploy/tasks/gather_stack_nodes.yml for instance => (item={'stackname': 'cell2', 'network_data_file': 'network_data.yaml.j2', 'vips_data_file': 'vips_data_cell2.yaml', 'stack_nodes': ['cell2-osp-computes', 'cell2-osp-controllers']})
2026-02-18 15:37:42.504821 | controller |
2026-02-18 15:37:42.504827 | controller | TASK [Get main facts for the overcloud stack] **********************************
2026-02-18 15:37:42.504833 | controller | Wednesday 18 February 2026  15:37:42 +0000 (0:00:00.029)       0:00:00.047 ****
2026-02-18 15:37:42.504842 | controller | ok: [instance]
2026-02-18 15:37:42.587961 | controller |
2026-02-18 15:37:42.587977 | controller | TASK [Gather other facts for stack overcloud"] *********************************
2026-02-18 15:37:42.587984 | controller | Wednesday 18 February 2026  15:37:42 +0000 (0:00:00.033)       0:00:00.080 ****
2026-02-18 15:37:42.587993 | controller | ok: [instance]
2026-02-18 15:37:42.645310 | controller |
2026-02-18 15:37:42.645346 | controller | TASK [Gather nodes for stack overcloud] ****************************************
2026-02-18 15:37:42.645355 | controller | Wednesday 18 February 2026  15:37:42 +0000 (0:00:00.083)       0:00:00.163 ****
2026-02-18 15:37:42.645367 | controller | skipping: [instance] => (item=cell1-osp-computes)
2026-02-18 15:37:42.645394 | controller | skipping: [instance] => (item=cell1-osp-controllers)
2026-02-18 15:37:42.645402 | controller | skipping: [instance] => (item=cell2-osp-computes)
2026-02-18 15:37:42.645408 | controller | skipping: [instance] => (item=cell2-osp-controllers)
2026-02-18 15:37:42.645415 | controller | skipping: [instance] => (item=controllers)
2026-02-18 15:37:42.645421 | controller | skipping: [instance] => (item=ocps)
2026-02-18 15:37:42.645428 | controller | ok: [instance] => (item=osp-controllers)
2026-02-18 15:37:42.645436 | controller | skipping: [instance] => (item=osp-underclouds)
2026-02-18 15:37:42.645460 | controller |
2026-02-18 15:37:42.645637 | controller | TASK [Get main facts for the overcloud stack] **********************************
2026-02-18 15:37:42.674247 | controller | Wednesday 18 February 2026  15:37:42 +0000 (0:00:00.056)       0:00:00.220 ****
2026-02-18 15:37:42.674267 | controller | ok: [instance]
2026-02-18 15:37:42.674369 | controller |
2026-02-18 15:37:42.674379 | controller | TASK [Gather other facts for stack cell1"] *************************************
2026-02-18 15:37:42.674386 | controller | Wednesday 18 February 2026  15:37:42 +0000 (0:00:00.029)       0:00:00.249 ****
2026-02-18 15:37:42.739308 | controller | ok: [instance]
2026-02-18 15:37:42.739389 | controller |
2026-02-18 15:37:42.739408 | controller | TASK [Gather nodes for stack cell1] ********************************************
2026-02-18 15:37:42.739418 | controller | Wednesday 18 February 2026  15:37:42 +0000 (0:00:00.064)       0:00:00.314 ****
2026-02-18 15:37:42.802289 | controller | ok: [instance] => (item=cell1-osp-computes)
2026-02-18 15:37:42.802335 | controller | ok: [instance] => (item=cell1-osp-controllers)
2026-02-18 15:37:42.802348 | controller | skipping: [instance] => (item=cell2-osp-computes)
2026-02-18 15:37:42.802356 | controller | skipping: [instance] => (item=cell2-osp-controllers)
2026-02-18 15:37:42.802372 | controller | skipping: [instance] => (item=controllers)
2026-02-18 15:37:42.802380 | controller | skipping: [instance] => (item=ocps)
2026-02-18 15:37:42.802388 | controller | skipping: [instance] => (item=osp-controllers)
2026-02-18 15:37:42.802395 | controller | skipping: [instance] => (item=osp-underclouds)
2026-02-18 15:37:42.802402 | controller |
2026-02-18 15:37:42.802413 | controller | TASK [Get main facts for the overcloud stack] **********************************
2026-02-18 15:37:42.832315 | controller | Wednesday 18 February 2026  15:37:42 +0000 (0:00:00.062)       0:00:00.377 ****
2026-02-18 15:37:42.832340 | controller | ok: [instance]
2026-02-18 15:37:42.899294 | controller |
2026-02-18 15:37:42.899315 | controller | TASK [Gather other facts for stack cell2"] *************************************
2026-02-18 15:37:42.899324 | controller | Wednesday 18 February 2026  15:37:42 +0000 (0:00:00.030)       0:00:00.407 ****
2026-02-18 15:37:42.899334 | controller | ok: [instance]
2026-02-18 15:37:42.899360 | controller |
2026-02-18 15:37:42.899531 | controller | TASK [Gather nodes for stack cell2] ********************************************
2026-02-18 15:37:42.962286 | controller | Wednesday 18 February 2026  15:37:42 +0000 (0:00:00.067)       0:00:00.475 ****
2026-02-18 15:37:42.962333 | controller | skipping: [instance] => (item=cell1-osp-computes)
2026-02-18 15:37:42.962483 | controller | skipping: [instance] => (item=cell1-osp-controllers)
2026-02-18 15:37:42.962502 | controller | ok: [instance] => (item=cell2-osp-computes)
2026-02-18 15:37:42.962510 | controller | ok: [instance] => (item=cell2-osp-controllers)
2026-02-18 15:37:42.962516 | controller | skipping: [instance] => (item=controllers)
2026-02-18 15:37:42.962521 | controller | skipping: [instance] => (item=ocps)
2026-02-18 15:37:42.962526 | controller | skipping: [instance] => (item=osp-controllers)
2026-02-18 15:37:42.962531 | controller | skipping: [instance] => (item=osp-underclouds)
2026-02-18 15:37:42.962539 | controller |
2026-02-18 15:37:42.991085 | controller | TASK [Store result for verification as persistent fact] ************************
2026-02-18 15:37:42.991102 | controller | Wednesday 18 February 2026  15:37:42 +0000 (0:00:00.062)       0:00:00.537 ****
2026-02-18 15:37:42.991111 | controller | ok: [instance]
2026-02-18 15:37:43.041461 | controller |
2026-02-18 15:37:43.041480 | controller | PLAY RECAP *********************************************************************
2026-02-18 15:37:43.041487 | controller | instance                   : ok=13   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-02-18 15:37:43.041493 | controller |
2026-02-18 15:37:43.041498 | controller | Wednesday 18 February 2026  15:37:42 +0000 (0:00:00.028)       0:00:00.565 ****
2026-02-18 15:37:43.041504 | controller | ===============================================================================
2026-02-18 15:37:43.041509 | controller | Gather other facts for stack overcloud" --------------------------------- 0.08s
2026-02-18 15:37:43.041520 | controller | Gather other facts for stack cell2" ------------------------------------- 0.07s
2026-02-18 15:37:43.041525 | controller | Gather other facts for stack cell1" ------------------------------------- 0.06s
2026-02-18 15:37:43.041530 | controller | Gather nodes for stack cell1 -------------------------------------------- 0.06s
2026-02-18 15:37:43.041535 | controller | Gather nodes for stack cell2 -------------------------------------------- 0.06s
2026-02-18 15:37:43.041540 | controller | Gather nodes for stack overcloud ---------------------------------------- 0.06s
2026-02-18 15:37:43.041545 | controller | Get main facts for the overcloud stack ---------------------------------- 0.03s
2026-02-18 15:37:43.041549 | controller | Get main facts for the overcloud stack ---------------------------------- 0.03s
2026-02-18 15:37:43.041554 | controller | Gather stack nodes and facts -------------------------------------------- 0.03s
2026-02-18 15:37:43.041559 | controller | Get main facts for the overcloud stack ---------------------------------- 0.03s
2026-02-18 15:37:43.041564 | controller | Store result for verification as persistent fact ------------------------ 0.03s
2026-02-18 15:37:43.041572 | controller | INFO     Running default > verify
2026-02-18 15:37:43.041882 | controller | INFO     Running Ansible Verifier
2026-02-18 15:37:43.430015 | controller |
2026-02-18 15:37:43.430375 | controller | PLAY [Verify] ******************************************************************
2026-02-18 15:37:43.430583 | controller |
2026-02-18 15:37:43.430715 | controller | TASK [Set _tripleo_nodes_stack from persistent fact] ***************************
2026-02-18 15:37:43.430845 | controller | Wednesday 18 February 2026  15:37:43 +0000 (0:00:00.018)       0:00:00.018 ****
2026-02-18 15:37:43.452092 | controller | skipping: [instance]
2026-02-18 15:37:43.522294 | controller |
2026-02-18 15:37:43.522328 | controller | TASK [Assert gathered nodes for stacks] ****************************************
2026-02-18 15:37:43.522337 | controller | Wednesday 18 February 2026  15:37:43 +0000 (0:00:00.022)       0:00:00.040 ****
2026-02-18 15:37:43.522348 | controller | ok: [instance] => (item={'stackname': 'overcloud', 'network_data_file': 'network_data.yaml.j2', 'vips_data_file': 'vips_data_overcloud.yaml', 'stack_nodes': ['osp-controllers']}) => changed=false
2026-02-18 15:37:43.522428 | controller |   _stack:
2026-02-18 15:37:43.522437 | controller |     network_data_file: network_data.yaml.j2
2026-02-18 15:37:43.522443 | controller |     stack_nodes:
2026-02-18 15:37:43.522449 | controller |     - osp-controllers
2026-02-18 15:37:43.522454 | controller |     stackname: overcloud
2026-02-18 15:37:43.522460 | controller |     vips_data_file: vips_data_overcloud.yaml
2026-02-18 15:37:43.522465 | controller |   ansible_loop_var: _stack
2026-02-18 15:37:43.522471 | controller |   msg: Successfully verified gathered nodes for stack overcloud
2026-02-18 15:37:43.522477 | controller | ok: [instance] => (item={'stackname': 'cell1', 'network_data_file': 'network_data.yaml.j2', 'vips_data_file': 'vips_data_cell1.yaml', 'stack_nodes': ['cell1-osp-computes', 'cell1-osp-controllers']}) => changed=false
2026-02-18 15:37:43.522488 | controller |   _stack:
2026-02-18 15:37:43.522494 | controller |     network_data_file: network_data.yaml.j2
2026-02-18 15:37:43.522500 | controller |     stack_nodes:
2026-02-18 15:37:43.522505 | controller |     - cell1-osp-computes
2026-02-18 15:37:43.522511 | controller |     - cell1-osp-controllers
2026-02-18 15:37:43.522516 | controller |     stackname: cell1
2026-02-18 15:37:43.522521 | controller |     vips_data_file: vips_data_cell1.yaml
2026-02-18 15:37:43.522527 | controller |   ansible_loop_var: _stack
2026-02-18 15:37:43.522532 | controller |   msg: Successfully verified gathered nodes for stack cell1
2026-02-18 15:37:43.522538 | controller | ok: [instance] => (item={'stackname': 'cell2', 'network_data_file': 'network_data.yaml.j2', 'vips_data_file': 'vips_data_cell2.yaml', 'stack_nodes': ['cell2-osp-computes', 'cell2-osp-controllers']}) => changed=false
2026-02-18 15:37:43.522543 | controller |   _stack:
2026-02-18 15:37:43.522549 | controller |     network_data_file: network_data.yaml.j2
2026-02-18 15:37:43.522554 | controller |     stack_nodes:
2026-02-18 15:37:43.522560 | controller |     - cell2-osp-computes
2026-02-18 15:37:43.522565 | controller |     - cell2-osp-controllers
2026-02-18 15:37:43.522570 | controller |     stackname: cell2
2026-02-18 15:37:43.522577 | controller |     vips_data_file: vips_data_cell2.yaml
2026-02-18 15:37:43.522582 | controller |   ansible_loop_var: _stack
2026-02-18 15:37:43.522588 | controller |   msg: Successfully verified gathered nodes for stack cell2
2026-02-18 15:37:43.522593 | controller |
2026-02-18 15:37:43.522598 | controller | PLAY RECAP *********************************************************************
2026-02-18 15:37:43.522604 | controller | instance                   : ok=1    changed=0    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-02-18 15:37:43.522609 | controller |
2026-02-18 15:37:43.522615 | controller | Wednesday 18 February 2026  15:37:43 +0000 (0:00:00.067)       0:00:00.108 ****
2026-02-18 15:37:43.522620 | controller | ===============================================================================
2026-02-18 15:37:43.522626 | controller | Assert gathered nodes for stacks ---------------------------------------- 0.07s
2026-02-18 15:37:43.522634 | controller | Set _tripleo_nodes_stack from persistent fact --------------------------- 0.02s
2026-02-18 15:37:43.558523 | controller | INFO     Verifier completed successfully.
2026-02-18 15:37:43.566072 | controller | INFO     Running default > destroy
2026-02-18 15:37:43.943486 | controller |
2026-02-18 15:37:43.943645 | controller | PLAY [Destroy] *****************************************************************
2026-02-18 15:37:43.943743 | controller |
2026-02-18 15:37:43.943859 | controller | TASK [Get passwd entries for USER env] *****************************************
2026-02-18 15:37:43.943946 | controller | Wednesday 18 February 2026  15:37:43 +0000 (0:00:00.013)       0:00:00.013 ****
2026-02-18 15:37:44.215274 | controller | ok: [localhost]
2026-02-18 15:37:44.215324 | controller |
2026-02-18 15:37:44.215334 | controller | TASK [Get shell async_dir] *****************************************************
2026-02-18 15:37:44.215472 | controller | Wednesday 18 February 2026  15:37:44 +0000 (0:00:00.272)       0:00:00.285 ****
2026-02-18 15:37:44.255961 | controller | ok: [localhost]
2026-02-18 15:37:44.289352 | controller |
2026-02-18 15:37:44.289388 | controller | TASK [Set async_dir for HOME env] **********************************************
2026-02-18 15:37:44.289402 | controller | Wednesday 18 February 2026  15:37:44 +0000 (0:00:00.040)       0:00:00.326 ****
2026-02-18 15:37:44.289418 | controller | skipping: [localhost]
2026-02-18 15:37:44.654950 | controller |
2026-02-18 15:37:44.654972 | controller | TASK [Destroy molecule instance(s)] ********************************************
2026-02-18 15:37:44.654979 | controller | Wednesday 18 February 2026  15:37:44 +0000 (0:00:00.033)       0:00:00.359 ****
2026-02-18 15:37:44.654988 | controller | changed: [localhost] => (item={'command': '/sbin/init', 'dockerfile': 'containerfile', 'image': 'registry.access.redhat.com/ubi9/ubi-init', 'name': 'instance', 'pre_build_image': True})
2026-02-18 15:37:44.655016 | controller |
2026-02-18 15:37:44.655262 | controller | TASK [Wait for instance(s) deletion to complete] *******************************
2026-02-18 15:37:50.084285 | controller | Wednesday 18 February 2026  15:37:44 +0000 (0:00:00.365)       0:00:00.725 ****
2026-02-18 15:37:50.084377 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) deletion to complete (300 retries left).
2026-02-18 15:37:50.084691 | controller | changed: [localhost] => (item={'failed': 0, 'started': 1, 'finished': 0, 'ansible_job_id': 'j170630299670.30510', 'results_file': '/home/zuul/.ansible_async/j170630299670.30510', 'changed': True, 'item': {'command': '/sbin/init', 'dockerfile': 'containerfile', 'image': 'registry.access.redhat.com/ubi9/ubi-init', 'name': 'instance', 'pre_build_image': True}, 'ansible_loop_var': 'item'})
2026-02-18 15:37:50.132961 | controller |
2026-02-18 15:37:50.132979 | controller | TASK [Delete podman network dedicated to this scenario] ************************
2026-02-18 15:37:50.132987 | controller | Wednesday 18 February 2026  15:37:50 +0000 (0:00:05.429)       0:00:06.154 ****
2026-02-18 15:37:50.132995 | controller | skipping: [localhost] => (item=instance: None specified)
2026-02-18 15:37:50.174273 | controller | skipping: [localhost]
2026-02-18 15:37:50.174293 | controller |
2026-02-18 15:37:50.174301 | controller | PLAY RECAP *********************************************************************
2026-02-18 15:37:50.174307 | controller | localhost                  : ok=4    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2026-02-18 15:37:50.174313 | controller |
2026-02-18 15:37:50.174319 | controller | Wednesday 18 February 2026  15:37:50 +0000 (0:00:00.048)       0:00:06.202 ****
2026-02-18 15:37:50.174324 | controller | ===============================================================================
2026-02-18 15:37:50.174330 | controller | Wait for instance(s) deletion to complete ------------------------------- 5.43s
2026-02-18 15:37:50.174335 | controller | Destroy molecule instance(s) -------------------------------------------- 0.37s
2026-02-18 15:37:50.174340 | controller | Get passwd entries for USER env ----------------------------------------- 0.27s
2026-02-18 15:37:50.174346 | controller | Delete podman network dedicated to this scenario ------------------------ 0.05s
2026-02-18 15:37:50.174351 | controller | Get shell async_dir ----------------------------------------------------- 0.04s
2026-02-18 15:37:50.174357 | controller | Set async_dir for HOME env ---------------------------------------------- 0.03s
2026-02-18 15:37:50.174366 | controller | INFO     Pruning extra files from scenario ephemeral directory
2026-02-18 15:37:50.176157 | controller | INFO     Writing /tmp/report.html report.
2026-02-18 15:37:50.396451 | controller | changed
2026-02-18 15:37:50.414896 | 
2026-02-18 15:37:50.414955 | PLAY RECAP
2026-02-18 15:37:50.414996 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-18 15:37:50.415018 | 
2026-02-18 15:37:50.473934 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-02-18 15:37:50.474747 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-02-18 15:37:50.980246 | 
2026-02-18 15:37:50.980387 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-02-18 15:37:51.000601 | 
2026-02-18 15:37:51.000678 | TASK [Filter out host if needed]
2026-02-18 15:37:51.009286 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-02-18 15:37:51.013921 | 
2026-02-18 15:37:51.013990 | TASK [Ensure file is present]
2026-02-18 15:37:51.305326 | controller | ok
2026-02-18 15:37:51.311564 | 
2026-02-18 15:37:51.311624 | TASK [Manage molecule report file]
2026-02-18 15:37:51.835480 | controller | changed
2026-02-18 15:37:51.840673 | 
2026-02-18 15:37:51.840749 | TASK [Check if we get ci-framework-data basedir]
2026-02-18 15:37:52.011150 | controller | ok
2026-02-18 15:37:52.016290 | 
2026-02-18 15:37:52.016373 | TASK [Create ci-framework-data log directory for zuul]
2026-02-18 15:37:52.318172 | controller | changed
2026-02-18 15:37:52.323333 | 
2026-02-18 15:37:52.323401 | TASK [Copy ci-framework interesting files]
2026-02-18 15:37:52.504517 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-02-18 15:37:52.849917 | controller | changed
2026-02-18 15:37:52.855125 | 
2026-02-18 15:37:52.855192 | TASK [Get SELinux listing]
2026-02-18 15:37:53.380193 | controller | changed
2026-02-18 15:37:53.385901 | 
2026-02-18 15:37:53.385969 | TASK [Generate log index]
2026-02-18 15:37:53.960688 | controller | changed
2026-02-18 15:37:53.965038 | 
2026-02-18 15:37:53.965098 | TASK [Get some env related data]
2026-02-18 15:37:54.473349 | controller | /home/zuul/.local/bin/ansible
2026-02-18 15:37:54.990377 | controller | changed
2026-02-18 15:37:54.994959 | 
2026-02-18 15:37:54.995020 | TASK [Generate list of logs to collect in home directory]
2026-02-18 15:37:55.328801 | controller | ok: All paths examined
2026-02-18 15:37:55.333584 | 
2026-02-18 15:37:55.333647 | LOOP [Copy logs from home directory]
2026-02-18 15:37:55.636757 | controller | changed:
2026-02-18 15:37:55.636870 | controller | {
2026-02-18 15:37:55.636895 | controller |   "atime": 1771429010.6829512,
2026-02-18 15:37:55.636913 | controller |   "ctime": 1771429026.5043473,
2026-02-18 15:37:55.636928 | controller |   "dev": 64513,
2026-02-18 15:37:55.636943 | controller |   "gid": 1000,
2026-02-18 15:37:55.636957 | controller |   "gr_name": "zuul",
2026-02-18 15:37:55.636971 | controller |   "inode": 448272,
2026-02-18 15:37:55.636985 | controller |   "isblk": false,
2026-02-18 15:37:55.636998 | controller |   "ischr": false,
2026-02-18 15:37:55.637011 | controller |   "isdir": false,
2026-02-18 15:37:55.637024 | controller |   "isfifo": false,
2026-02-18 15:37:55.637038 | controller |   "isgid": false,
2026-02-18 15:37:55.637051 | controller |   "islnk": false,
2026-02-18 15:37:55.637064 | controller |   "isreg": true,
2026-02-18 15:37:55.637078 | controller |   "issock": false,
2026-02-18 15:37:55.637091 | controller |   "isuid": false,
2026-02-18 15:37:55.637104 | controller |   "mode": "0644",
2026-02-18 15:37:55.637117 | controller |   "mtime": 1771429026.5043473,
2026-02-18 15:37:55.637130 | controller |   "nlink": 1,
2026-02-18 15:37:55.637143 | controller |   "path": "/home/zuul/ansible.log",
2026-02-18 15:37:55.637156 | controller |   "pw_name": "zuul",
2026-02-18 15:37:55.637170 | controller |   "rgrp": true,
2026-02-18 15:37:55.637184 | controller |   "roth": true,
2026-02-18 15:37:55.637197 | controller |   "rusr": true,
2026-02-18 15:37:55.637210 | controller |   "size": 6749,
2026-02-18 15:37:55.637223 | controller |   "uid": 1000,
2026-02-18 15:37:55.637235 | controller |   "wgrp": false,
2026-02-18 15:37:55.637247 | controller |   "woth": false,
2026-02-18 15:37:55.637262 | controller |   "wusr": true,
2026-02-18 15:37:55.637276 | controller |   "xgrp": false,
2026-02-18 15:37:55.637289 | controller |   "xoth": false,
2026-02-18 15:37:55.637325 | controller |   "xusr": false
2026-02-18 15:37:55.637342 | controller | }
2026-02-18 15:37:55.650617 | 
2026-02-18 15:37:55.650709 | TASK [Copy crio stats log file]
2026-02-18 15:37:55.664418 | controller | skipping: Conditional result was False
2026-02-18 15:37:55.669575 | 
2026-02-18 15:37:55.669633 | TASK [Get SELinux related data]
2026-02-18 15:37:55.880826 | controller | <no matches>
2026-02-18 15:37:56.194608 | controller | ERROR
2026-02-18 15:37:56.194812 | controller | {
2026-02-18 15:37:56.194843 | controller |   "delta": "0:00:00.012035",
2026-02-18 15:37:56.194864 | controller |   "end": "2026-02-18 15:37:55.882055",
2026-02-18 15:37:56.194881 | controller |   "msg": "non-zero return code",
2026-02-18 15:37:56.194898 | controller |   "rc": 1,
2026-02-18 15:37:56.194913 | controller |   "start": "2026-02-18 15:37:55.870020"
2026-02-18 15:37:56.194928 | controller | }
2026-02-18 15:37:56.194948 | controller | ERROR: Ignoring Errors
2026-02-18 15:37:56.199964 | 
2026-02-18 15:37:56.200025 | TASK [Create system configuration directory]
2026-02-18 15:37:56.390660 | controller | changed
2026-02-18 15:37:56.395195 | 
2026-02-18 15:37:56.395262 | TASK [Get some of the system configurations]
2026-02-18 15:37:56.921019 | controller | changed
2026-02-18 15:37:56.926461 | 
2026-02-18 15:37:56.926528 | TASK [Copy generated documentation if available]
2026-02-18 15:37:56.940003 | controller | skipping: Conditional result was False
2026-02-18 15:37:56.945384 | 
2026-02-18 15:37:56.945448 | TASK [Copy generated AsciiDoc documentation if available]
2026-02-18 15:37:56.958054 | controller | skipping: Conditional result was False
2026-02-18 15:37:56.963811 | 
2026-02-18 15:37:56.963881 | TASK [Compress logs bigger than 2MB]
2026-02-18 15:37:57.489571 | controller | changed
2026-02-18 15:37:57.494468 | 
2026-02-18 15:37:57.494526 | TASK [Copy files from workspace on node]
2026-02-18 15:37:57.512761 | controller | ok
2026-02-18 15:37:57.533402 | 
2026-02-18 15:37:57.533466 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-18 15:37:57.547123 | controller | skipping: Conditional result was False
2026-02-18 15:37:57.552952 | 
2026-02-18 15:37:57.553018 | TASK [fetch-output : Set log path for single node]
2026-02-18 15:37:57.580987 | controller | ok
2026-02-18 15:37:57.585758 | 
2026-02-18 15:37:57.585820 | LOOP [fetch-output : Ensure local output dirs]
2026-02-18 15:37:57.770175 | controller -> localhost | ok: "/var/lib/zuul/builds/cfe108d459234e1da3ff2c4f73e7019f/work/logs"
2026-02-18 15:37:57.770421 | controller -> localhost | changed: All items complete
2026-02-18 15:37:57.770453 | 
2026-02-18 15:37:57.936066 | controller -> localhost | changed: "/var/lib/zuul/builds/cfe108d459234e1da3ff2c4f73e7019f/work/artifacts"
2026-02-18 15:37:58.101077 | controller -> localhost | changed: "/var/lib/zuul/builds/cfe108d459234e1da3ff2c4f73e7019f/work/docs"
2026-02-18 15:37:58.112336 | 
2026-02-18 15:37:58.112417 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-18 15:37:58.609596 | controller | changed:
2026-02-18 15:37:58.609810 | controller | .d..t...... ./
2026-02-18 15:37:58.609845 | controller | >f+++++++++ README.html
2026-02-18 15:37:58.609868 | controller | >f+++++++++ ansible-execution.log
2026-02-18 15:37:58.609892 | controller | >f+++++++++ ansible.log
2026-02-18 15:37:58.609912 | controller | >f+++++++++ dmesg.log
2026-02-18 15:37:58.609930 | controller | >f+++++++++ installed-pkgs.log
2026-02-18 15:37:58.609951 | controller | >f+++++++++ python.log
2026-02-18 15:37:58.609973 | controller | >f+++++++++ registries.conf
2026-02-18 15:37:58.609991 | controller | >f+++++++++ report.html
2026-02-18 15:37:58.610011 | controller | >f+++++++++ selinux-denials.log
2026-02-18 15:37:58.610029 | controller | >f+++++++++ selinux-listing.log
2026-02-18 15:37:58.610049 | controller | cd+++++++++ ci-framework-data/
2026-02-18 15:37:58.610068 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-02-18 15:37:58.610087 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-02-18 15:37:58.610105 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-02-18 15:37:58.610122 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-02-18 15:37:58.610140 | controller | cd+++++++++ ci-framework-data/logs/
2026-02-18 15:37:58.610158 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-02-18 15:37:58.610175 | controller | cd+++++++++ registries.conf.d/
2026-02-18 15:37:58.610192 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-02-18 15:37:58.610209 | controller | cd+++++++++ system-config/
2026-02-18 15:37:58.610225 | controller | cd+++++++++ system-config/libvirt/
2026-02-18 15:37:58.610243 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-02-18 15:37:58.610261 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-02-18 15:37:58.955754 | controller | changed: .d..t...... ./
2026-02-18 15:37:59.302108 | controller | changed: .d..t...... ./
2026-02-18 15:37:59.321438 | 
2026-02-18 15:37:59.321503 | TASK [Return artifact to Zuul]
2026-02-18 15:37:59.349619 | controller | ok
2026-02-18 15:37:59.368320 | 
2026-02-18 15:37:59.368379 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-02-18 15:37:59.368479 | 
2026-02-18 15:37:59.368506 | PLAY RECAP
2026-02-18 15:37:59.368544 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-02-18 15:37:59.368566 | 
2026-02-18 15:37:59.440328 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-02-18 15:37:59.440989 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-02-18 15:37:59.909717 | 
2026-02-18 15:37:59.909813 | PLAY [all]
2026-02-18 15:37:59.926438 | 
2026-02-18 15:37:59.926508 | TASK [include_role : fetch-output]
2026-02-18 15:37:59.954871 | controller | ok
2026-02-18 15:37:59.970201 | 
2026-02-18 15:37:59.970400 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-18 15:38:00.004351 | controller | skipping: Conditional result was False
2026-02-18 15:38:00.009790 | 
2026-02-18 15:38:00.009864 | TASK [fetch-output : Set log path for single node]
2026-02-18 15:38:00.037941 | controller | ok
2026-02-18 15:38:00.042826 | 
2026-02-18 15:38:00.042894 | LOOP [fetch-output : Ensure local output dirs]
2026-02-18 15:38:00.344645 | controller -> localhost | ok: "/var/lib/zuul/builds/cfe108d459234e1da3ff2c4f73e7019f/work/logs"
2026-02-18 15:38:00.512023 | controller -> localhost | ok: "/var/lib/zuul/builds/cfe108d459234e1da3ff2c4f73e7019f/work/artifacts"
2026-02-18 15:38:00.676863 | controller -> localhost | ok: "/var/lib/zuul/builds/cfe108d459234e1da3ff2c4f73e7019f/work/docs"
2026-02-18 15:38:00.690998 | 
2026-02-18 15:38:00.691105 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-18 15:38:01.178267 | controller | ok
2026-02-18 15:38:01.178473 | controller | ok: All items complete
2026-02-18 15:38:01.178505 | 
2026-02-18 15:38:01.524743 | controller | ok
2026-02-18 15:38:01.877601 | controller | ok
2026-02-18 15:38:01.891244 | 
2026-02-18 15:38:01.891354 | TASK [include_role : fetch-output-openshift]
2026-02-18 15:38:01.905400 | controller | skipping: Conditional result was False
2026-02-18 15:38:01.910661 | 
2026-02-18 15:38:01.910740 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-18 15:38:02.232269 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.005904
2026-02-18 15:38:02.406891 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005472
2026-02-18 15:38:02.432948 | 
2026-02-18 15:38:02.433028 | PLAY [all]
2026-02-18 15:38:02.445409 | 
2026-02-18 15:38:02.445476 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-18 15:38:02.809252 | controller | changed
2026-02-18 15:38:02.829578 | 
2026-02-18 15:38:02.829625 | PLAY RECAP
2026-02-18 15:38:02.829663 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-02-18 15:38:02.829684 | 
2026-02-18 15:38:02.895163 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-02-18 15:38:02.895909 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-02-18 15:38:03.426086 | 
2026-02-18 15:38:03.426192 | PLAY [localhost]
2026-02-18 15:38:03.441998 | 
2026-02-18 15:38:03.442073 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-18 15:38:03.722482 | localhost | changed
2026-02-18 15:38:03.726866 | 
2026-02-18 15:38:03.726956 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-18 15:38:03.743828 | localhost | ok
2026-02-18 15:38:03.751402 | 
2026-02-18 15:38:03.751476 | TASK [add-fileserver : Create SSH private key tempfile]
2026-02-18 15:38:04.041355 | localhost | changed
2026-02-18 15:38:04.047036 | 
2026-02-18 15:38:04.047114 | TASK [add-fileserver : Create SSH private key from secret]
2026-02-18 15:38:04.570283 | localhost | changed
2026-02-18 15:38:04.575242 | 
2026-02-18 15:38:04.575349 | TASK [add-fileserver : Add fileserver ssh key]
2026-02-18 15:38:04.896263 | localhost | Identity added: /var/lib/zuul/builds/cfe108d459234e1da3ff2c4f73e7019f/work/tmp/ansible._onmdnut (/var/lib/zuul/builds/cfe108d459234e1da3ff2c4f73e7019f/work/tmp/ansible._onmdnut)
2026-02-18 15:38:04.896443 | localhost | ok: Runtime: 0:00:00.006489
2026-02-18 15:38:04.900499 | 
2026-02-18 15:38:04.900571 | TASK [add-fileserver : Remove SSH private key from disk]
2026-02-18 15:38:05.110059 | localhost | ok: Runtime: 0:00:00.003869
2026-02-18 15:38:05.114536 | 
2026-02-18 15:38:05.114611 | TASK [add-fileserver : Add fileserver to inventory]
2026-02-18 15:38:05.161980 | localhost | changed
2026-02-18 15:38:05.166728 | 
2026-02-18 15:38:05.166801 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-02-18 15:38:05.488508 | localhost | changed
2026-02-18 15:38:05.507871 | 
2026-02-18 15:38:05.507947 | PLAY [localhost]
2026-02-18 15:38:05.519091 | 
2026-02-18 15:38:05.519161 | TASK [Generate bulk log download script]
2026-02-18 15:38:05.538565 | localhost | ok
2026-02-18 15:38:05.549973 | 
2026-02-18 15:38:05.550049 | TASK [local-log-download : Check API endpoint is defined]
2026-02-18 15:38:05.577126 | localhost | ok: All assertions passed
2026-02-18 15:38:05.581547 | 
2026-02-18 15:38:05.581622 | TASK [local-log-download : Create download script]
2026-02-18 15:38:05.918361 | localhost -> localhost | changed
2026-02-18 15:38:05.927099 | 
2026-02-18 15:38:05.927186 | TASK [Register quick-download link]
2026-02-18 15:38:05.943911 | localhost | ok
2026-02-18 15:38:05.979258 | 
2026-02-18 15:38:05.979347 | PLAY [logserver.rdoproject.org]
2026-02-18 15:38:05.987874 | 
2026-02-18 15:38:05.987932 | TASK [Set zuul-log-path fact]
2026-02-18 15:38:06.003544 | logserver.rdoproject.org | ok
2026-02-18 15:38:06.012268 | 
2026-02-18 15:38:06.012370 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-18 15:38:06.038557 | logserver.rdoproject.org | ok
2026-02-18 15:38:06.044006 | 
2026-02-18 15:38:06.044081 | TASK [upload-logs : Create log directories]
2026-02-18 15:38:07.775614 | logserver.rdoproject.org | changed
2026-02-18 15:38:07.778896 | 
2026-02-18 15:38:07.778974 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-18 15:38:08.026326 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005156
2026-02-18 15:38:08.030648 | 
2026-02-18 15:38:08.030727 | TASK [upload-logs : Upload logs to log server]
2026-02-18 15:38:09.456348 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-02-18 15:38:09.459235 | 
2026-02-18 15:38:09.459316 | LOOP [upload-logs : Compress console log and json output]
2026-02-18 15:38:09.498512 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-18 15:38:09.506087 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-18 15:38:09.518233 | 
2026-02-18 15:38:09.518348 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-18 15:38:09.550246 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-18 15:38:09.550485 | 
2026-02-18 15:38:09.553683 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-18 15:38:09.565108 | 
2026-02-18 15:38:09.565205 | LOOP [upload-logs : Upload console log and json output]