2025-10-07 07:28:19.352150 | Job console starting...
2025-10-07 07:28:19.681310 | Updating repositories
2025-10-07 07:28:20.661159 | Preparing job workspace
2025-10-07 07:28:36.975759 | Running Ansible setup...
2025-10-07 07:28:46.140823 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-07 07:28:47.653359 | 
2025-10-07 07:28:47.653458 | PLAY [localhost]
2025-10-07 07:28:47.693626 | 
2025-10-07 07:28:47.693711 | TASK [Gathering Facts]
2025-10-07 07:28:50.473317 | localhost | ok
2025-10-07 07:28:50.525894 | 
2025-10-07 07:28:50.526053 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-07 07:28:51.763379 | localhost -> localhost | changed
2025-10-07 07:28:51.769781 | 
2025-10-07 07:28:51.769863 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-07 07:28:55.193298 | localhost -> localhost | changed
2025-10-07 07:28:55.205981 | 
2025-10-07 07:28:55.208962 | TASK [Setup log path fact]
2025-10-07 07:28:55.269425 | localhost | ok
2025-10-07 07:28:55.337292 | 
2025-10-07 07:28:55.337387 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-07 07:28:55.405431 | localhost | ok
2025-10-07 07:28:55.420309 | 
2025-10-07 07:28:55.420396 | TASK [emit-job-header : Print job information]
2025-10-07 07:28:55.539003 | # Job Information
2025-10-07 07:28:55.539179 | Ansible Version: 2.15.12
2025-10-07 07:28:55.539211 | Job: cifmw-molecule-deploy_bmh
2025-10-07 07:28:55.539236 | Pipeline: github-check
2025-10-07 07:28:55.539257 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-07 07:28:55.539277 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3366
2025-10-07 07:28:55.539297 | Log URL (when completed): https://logserver.rdoproject.org/65c/rdoproject.org/65cecfbf0c7c4346b762b7e8523fa3d7/
2025-10-07 07:28:55.539317 | Event ID: 0b3e6df0-a34f-11f0-8d70-8afb6d89ca3c
2025-10-07 07:28:55.543167 | 
2025-10-07 07:28:55.543245 | LOOP [emit-job-header : Print node information]
2025-10-07 07:28:55.948431 | localhost | ok:
2025-10-07 07:28:55.948647 | localhost | # Node Information
2025-10-07 07:28:55.948680 | localhost | Inventory Hostname: controller
2025-10-07 07:28:55.948705 | localhost | Hostname: np0005473032
2025-10-07 07:28:55.948727 | localhost | Username: zuul
2025-10-07 07:28:55.948749 | localhost | Distro: CentOS 9
2025-10-07 07:28:55.948768 | localhost | Provider: ibm-bm4-nodepool
2025-10-07 07:28:55.948787 | localhost | Region: regionOne
2025-10-07 07:28:55.948804 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-07 07:28:55.948823 | localhost | Product Name: OpenStack Compute
2025-10-07 07:28:55.948841 | localhost | Interface IP: 192.168.26.163
2025-10-07 07:28:56.004630 | 
2025-10-07 07:28:56.004715 | PLAY [all]
2025-10-07 07:28:56.023030 | 
2025-10-07 07:28:56.023101 | TASK [Gather network facts]
2025-10-07 07:28:56.533319 | controller | ok
2025-10-07 07:28:56.588766 | 
2025-10-07 07:28:56.588868 | TASK [include_role : start-zuul-console]
2025-10-07 07:28:56.650669 | controller | ok
2025-10-07 07:28:56.689966 | 
2025-10-07 07:28:56.690051 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-07 07:28:57.580018 | controller | ok
2025-10-07 07:28:57.607732 | 
2025-10-07 07:28:57.607809 | TASK [include_role : add-build-sshkey]
2025-10-07 07:28:57.685217 | controller | ok
2025-10-07 07:28:57.741747 | 
2025-10-07 07:28:57.741839 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-07 07:28:58.797431 | controller -> localhost | ok
2025-10-07 07:28:58.802991 | 
2025-10-07 07:28:58.803058 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-07 07:28:58.885780 | controller | ok
2025-10-07 07:28:58.944510 | controller | included: /var/lib/zuul/builds/65cecfbf0c7c4346b762b7e8523fa3d7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-07 07:28:58.977877 | 
2025-10-07 07:28:58.977991 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-07 07:29:00.967260 | controller -> localhost | Generating public/private rsa key pair.
2025-10-07 07:29:00.967458 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/65cecfbf0c7c4346b762b7e8523fa3d7/work/65cecfbf0c7c4346b762b7e8523fa3d7_id_rsa.
2025-10-07 07:29:00.967493 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/65cecfbf0c7c4346b762b7e8523fa3d7/work/65cecfbf0c7c4346b762b7e8523fa3d7_id_rsa.pub.
2025-10-07 07:29:00.967520 | controller -> localhost | The key fingerprint is:
2025-10-07 07:29:00.967542 | controller -> localhost | SHA256:Xm7HE051MbTDoZFFCtVR4bUR6hP+TbecjO68q3zLhiA zuul-build-sshkey
2025-10-07 07:29:00.967564 | controller -> localhost | The key's randomart image is:
2025-10-07 07:29:00.967586 | controller -> localhost | +---[RSA 3072]----+
2025-10-07 07:29:00.967607 | controller -> localhost | |           ..o*%B|
2025-10-07 07:29:00.967627 | controller -> localhost | |            ..B.O|
2025-10-07 07:29:00.967647 | controller -> localhost | |             =.*.|
2025-10-07 07:29:00.967666 | controller -> localhost | |            o....|
2025-10-07 07:29:00.967685 | controller -> localhost | |        S . o+  o|
2025-10-07 07:29:00.967704 | controller -> localhost | |       E + + .*.=|
2025-10-07 07:29:00.967723 | controller -> localhost | |        o + *. *.|
2025-10-07 07:29:00.967741 | controller -> localhost | |         ..o++   |
2025-10-07 07:29:00.967760 | controller -> localhost | |           o=O+  |
2025-10-07 07:29:00.967778 | controller -> localhost | +----[SHA256]-----+
2025-10-07 07:29:00.967827 | controller -> localhost | ok: Runtime: 0:00:00.173159
2025-10-07 07:29:00.973733 | 
2025-10-07 07:29:00.973801 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-07 07:29:01.051714 | controller | ok
2025-10-07 07:29:01.081635 | controller | included: /var/lib/zuul/builds/65cecfbf0c7c4346b762b7e8523fa3d7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-07 07:29:01.123118 | 
2025-10-07 07:29:01.123211 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-07 07:29:01.200858 | controller | skipping: Conditional result was False
2025-10-07 07:29:01.207509 | 
2025-10-07 07:29:01.207601 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-07 07:29:02.025529 | controller | changed
2025-10-07 07:29:02.036079 | 
2025-10-07 07:29:02.036165 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-07 07:29:02.417620 | controller | ok
2025-10-07 07:29:02.422518 | 
2025-10-07 07:29:02.422585 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-07 07:29:03.159202 | controller | changed
2025-10-07 07:29:03.183296 | 
2025-10-07 07:29:03.183395 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-07 07:29:04.108846 | controller | changed
2025-10-07 07:29:04.114733 | 
2025-10-07 07:29:04.117276 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-07 07:29:04.170611 | controller | skipping: Conditional result was False
2025-10-07 07:29:04.176482 | 
2025-10-07 07:29:04.176547 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-07 07:29:05.433395 | controller -> localhost | changed
2025-10-07 07:29:05.452681 | 
2025-10-07 07:29:05.452751 | TASK [add-build-sshkey : Add back temp key]
2025-10-07 07:29:06.383235 | controller -> localhost | Identity added: /var/lib/zuul/builds/65cecfbf0c7c4346b762b7e8523fa3d7/work/65cecfbf0c7c4346b762b7e8523fa3d7_id_rsa (zuul-build-sshkey)
2025-10-07 07:29:06.383416 | controller -> localhost | ok: Runtime: 0:00:00.025727
2025-10-07 07:29:06.388940 | 
2025-10-07 07:29:06.389004 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-07 07:29:06.992486 | controller | ok
2025-10-07 07:29:06.998246 | 
2025-10-07 07:29:06.998341 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-07 07:29:07.078450 | controller | skipping: Conditional result was False
2025-10-07 07:29:07.095504 | 
2025-10-07 07:29:07.095591 | TASK [include_role : validate-host]
2025-10-07 07:29:07.134083 | controller | ok
2025-10-07 07:29:07.177860 | 
2025-10-07 07:29:07.177974 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-07 07:29:07.250337 | controller | ok
2025-10-07 07:29:07.262964 | 
2025-10-07 07:29:07.263047 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-07 07:29:07.954618 | controller -> localhost | ok
2025-10-07 07:29:07.967938 | 
2025-10-07 07:29:07.968009 | TASK [validate-host : Collect information about the host]
2025-10-07 07:29:08.616188 | controller | ok
2025-10-07 07:29:08.646179 | 
2025-10-07 07:29:08.646262 | TASK [validate-host : Sanitize hostname]
2025-10-07 07:29:08.750608 | controller | ok
2025-10-07 07:29:08.755639 | 
2025-10-07 07:29:08.755707 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-07 07:29:10.176577 | controller -> localhost | changed
2025-10-07 07:29:10.186386 | 
2025-10-07 07:29:10.186452 | TASK [validate-host : Collect information about zuul worker]
2025-10-07 07:29:11.073937 | controller | ok
2025-10-07 07:29:11.078654 | 
2025-10-07 07:29:11.078718 | TASK [validate-host : Write out all zuul information for each host]
2025-10-07 07:29:12.710000 | controller -> localhost | changed
2025-10-07 07:29:12.724964 | 
2025-10-07 07:29:12.725052 | TASK [include_role : prepare-workspace-openshift]
2025-10-07 07:29:12.778154 | controller | skipping: Conditional result was False
2025-10-07 07:29:12.795881 | 
2025-10-07 07:29:12.796008 | TASK [include_role : remove-zuul-sshkey]
2025-10-07 07:29:12.846168 | controller | skipping: Conditional result was False
2025-10-07 07:29:12.851602 | 
2025-10-07 07:29:12.851670 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-07 07:29:13.150066 | controller | ok: "logs"
2025-10-07 07:29:13.150276 | controller | ok: All items complete
2025-10-07 07:29:13.150306 | 
2025-10-07 07:29:13.329552 | controller | ok: "artifacts"
2025-10-07 07:29:13.518970 | controller | ok: "docs"
2025-10-07 07:29:13.528952 | 
2025-10-07 07:29:13.529061 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-07 07:29:13.857534 | controller | changed: "logs"
2025-10-07 07:29:14.054819 | controller | changed: "artifacts"
2025-10-07 07:29:14.260427 | controller | changed: "docs"
2025-10-07 07:29:14.305101 | 
2025-10-07 07:29:14.305211 | PLAY RECAP
2025-10-07 07:29:14.305258 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-07 07:29:14.305855 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-07 07:29:14.305893 | 
2025-10-07 07:29:14.496325 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-07 07:29:14.497072 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-07 07:29:17.054475 | 
2025-10-07 07:29:17.081597 | PLAY [all]
2025-10-07 07:29:17.210640 | 
2025-10-07 07:29:17.210764 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-07 07:29:17.425031 | controller | ok
2025-10-07 07:29:17.434408 | 
2025-10-07 07:29:17.434530 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-07 07:29:18.362302 | controller | changed
2025-10-07 07:29:18.370837 | 
2025-10-07 07:29:18.371012 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-07 07:29:20.189747 | controller | changed
2025-10-07 07:29:20.223467 | 
2025-10-07 07:29:20.223591 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-07 07:29:21.216811 | controller | changed:
2025-10-07 07:29:21.217017 | controller | {
2025-10-07 07:29:21.217054 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-07 07:29:21.217098 | controller | }
2025-10-07 07:29:21.447018 | controller | changed:
2025-10-07 07:29:21.447102 | controller | {
2025-10-07 07:29:21.447132 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-07 07:29:21.447155 | controller | }
2025-10-07 07:29:21.689412 | controller | changed:
2025-10-07 07:29:21.689504 | controller | {
2025-10-07 07:29:21.689533 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-07 07:29:21.689555 | controller | }
2025-10-07 07:29:21.921892 | controller | changed:
2025-10-07 07:29:21.921988 | controller | {
2025-10-07 07:29:21.922017 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-07 07:29:21.922040 | controller | }
2025-10-07 07:29:22.155133 | controller | changed:
2025-10-07 07:29:22.155234 | controller | {
2025-10-07 07:29:22.155262 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-07 07:29:22.155284 | controller | }
2025-10-07 07:29:22.423400 | controller | changed:
2025-10-07 07:29:22.423483 | controller | {
2025-10-07 07:29:22.423512 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-07 07:29:22.423534 | controller | }
2025-10-07 07:29:22.655589 | controller | changed:
2025-10-07 07:29:22.655673 | controller | {
2025-10-07 07:29:22.655702 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-07 07:29:22.655725 | controller | }
2025-10-07 07:29:22.972813 | controller | changed:
2025-10-07 07:29:22.972903 | controller | {
2025-10-07 07:29:22.972959 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-07 07:29:22.972986 | controller | }
2025-10-07 07:29:23.154536 | controller | changed:
2025-10-07 07:29:23.154620 | controller | {
2025-10-07 07:29:23.154650 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-07 07:29:23.154673 | controller | }
2025-10-07 07:29:23.423086 | controller | changed:
2025-10-07 07:29:23.423176 | controller | {
2025-10-07 07:29:23.423223 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-07 07:29:23.423246 | controller | }
2025-10-07 07:29:23.701944 | controller | changed:
2025-10-07 07:29:23.702044 | controller | {
2025-10-07 07:29:23.702072 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-07 07:29:23.702093 | controller | }
2025-10-07 07:29:23.913281 | controller | changed:
2025-10-07 07:29:23.913372 | controller | {
2025-10-07 07:29:23.913401 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-07 07:29:23.913424 | controller | }
2025-10-07 07:29:24.173388 | controller | changed:
2025-10-07 07:29:24.173479 | controller | {
2025-10-07 07:29:24.173508 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-07 07:29:24.173530 | controller | }
2025-10-07 07:29:24.435473 | controller | changed:
2025-10-07 07:29:24.435573 | controller | {
2025-10-07 07:29:24.435601 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-07 07:29:24.435623 | controller | }
2025-10-07 07:29:24.660941 | controller | changed:
2025-10-07 07:29:24.661035 | controller | {
2025-10-07 07:29:24.661061 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-07 07:29:24.661081 | controller | }
2025-10-07 07:29:24.868119 | controller | changed:
2025-10-07 07:29:24.868283 | controller | {
2025-10-07 07:29:24.868321 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-07 07:29:24.868345 | controller | }
2025-10-07 07:29:25.150292 | controller | changed:
2025-10-07 07:29:25.150370 | controller | {
2025-10-07 07:29:25.150398 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-07 07:29:25.150420 | controller | }
2025-10-07 07:29:25.382449 | controller | changed:
2025-10-07 07:29:25.382530 | controller | {
2025-10-07 07:29:25.382558 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-07 07:29:25.382589 | controller | }
2025-10-07 07:29:25.617460 | controller | changed:
2025-10-07 07:29:25.617554 | controller | {
2025-10-07 07:29:25.617582 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-07 07:29:25.617605 | controller | }
2025-10-07 07:29:25.870769 | controller | changed:
2025-10-07 07:29:25.870854 | controller | {
2025-10-07 07:29:25.870882 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-07 07:29:25.870905 | controller | }
2025-10-07 07:29:26.132255 | controller | changed:
2025-10-07 07:29:26.132345 | controller | {
2025-10-07 07:29:26.132373 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-07 07:29:26.132394 | controller | }
2025-10-07 07:29:26.357163 | controller | changed:
2025-10-07 07:29:26.357260 | controller | {
2025-10-07 07:29:26.357287 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-07 07:29:26.357309 | controller | }
2025-10-07 07:29:26.599149 | controller | changed:
2025-10-07 07:29:26.599556 | controller | {
2025-10-07 07:29:26.599595 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-07 07:29:26.599620 | controller | }
2025-10-07 07:29:26.844696 | controller | changed:
2025-10-07 07:29:26.844780 | controller | {
2025-10-07 07:29:26.844807 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-07 07:29:26.844828 | controller | }
2025-10-07 07:29:27.084187 | controller | changed:
2025-10-07 07:29:27.084292 | controller | {
2025-10-07 07:29:27.084320 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-07 07:29:27.084341 | controller | }
2025-10-07 07:29:27.329560 | controller | changed:
2025-10-07 07:29:27.329755 | controller | {
2025-10-07 07:29:27.329789 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-07 07:29:27.329812 | controller | }
2025-10-07 07:29:27.373234 | 
2025-10-07 07:29:27.373376 | TASK [Set timezone to UTC]
2025-10-07 07:29:28.009078 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-07 07:29:28.024471 | 
2025-10-07 07:29:28.024641 | TASK [Create nodepool directory]
2025-10-07 07:29:28.298321 | controller | changed
2025-10-07 07:29:28.342156 | 
2025-10-07 07:29:28.342281 | TASK [Create nodepool sub_nodes file]
2025-10-07 07:29:29.219477 | controller | changed
2025-10-07 07:29:29.229454 | 
2025-10-07 07:29:29.229542 | TASK [Create nodepool sub_nodes_private file]
2025-10-07 07:29:30.002412 | controller | changed
2025-10-07 07:29:30.007622 | 
2025-10-07 07:29:30.007687 | LOOP [Populate nodepool sub_nodes file]
2025-10-07 07:29:30.160452 | 
2025-10-07 07:29:30.160606 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-07 07:29:30.219675 | 
2025-10-07 07:29:30.219829 | TASK [Create nodepool primary file]
2025-10-07 07:29:30.243417 | controller | skipping: Conditional result was False
2025-10-07 07:29:30.248891 | 
2025-10-07 07:29:30.248985 | TASK [Create nodepool node_private for this node]
2025-10-07 07:29:31.026478 | controller | changed
2025-10-07 07:29:31.041440 | 
2025-10-07 07:29:31.042177 | LOOP [Copy ssh keys to nodepool directory]
2025-10-07 07:29:32.010416 | controller | ok: Item: id_rsa Runtime: 0:00:00.005605
2025-10-07 07:29:32.010589 | 
2025-10-07 07:29:32.193120 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005639
2025-10-07 07:29:32.200636 | 
2025-10-07 07:29:32.200745 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-07 07:29:32.912441 | controller | changed
2025-10-07 07:29:32.917590 | 
2025-10-07 07:29:32.917657 | TASK [Validate sudoers config after edits]
2025-10-07 07:29:33.420413 | controller | /etc/sudoers: parsed OK
2025-10-07 07:29:33.420512 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-07 07:29:33.420527 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-07 07:29:33.420534 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-07 07:29:33.543383 | controller | ok: Runtime: 0:00:00.006323
2025-10-07 07:29:33.548857 | 
2025-10-07 07:29:33.548952 | TASK [Show the environment passed in to job shell scripts]
2025-10-07 07:29:33.951636 | controller | SHELL=/bin/bash
2025-10-07 07:29:33.951711 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-07 07:29:33.951722 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-07 07:29:33.951776 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/66/3366/b916ef9cc83cd6f61cb4c8a27bbc55fc27d7eaae
2025-10-07 07:29:33.951786 | controller | PWD=/home/zuul
2025-10-07 07:29:33.951792 | controller | ZUUL_PIPELINE=github-check
2025-10-07 07:29:33.951798 | controller | LOGNAME=zuul
2025-10-07 07:29:33.951804 | controller | XDG_SESSION_TYPE=tty
2025-10-07 07:29:33.951810 | controller | _=/usr/bin/env
2025-10-07 07:29:33.951816 | controller | MOTD_SHOWN=pam
2025-10-07 07:29:33.951821 | controller | HOME=/home/zuul
2025-10-07 07:29:33.951827 | controller | LANG=en_US.UTF-8
2025-10-07 07:29:33.951832 | controller | SSH_CONNECTION=192.168.26.12 39820 192.168.26.163 22
2025-10-07 07:29:33.951838 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-07 07:29:33.951847 | controller | ZUUL_CHANGE_IDS=3366,b916ef9cc83cd6f61cb4c8a27bbc55fc27d7eaae
2025-10-07 07:29:33.951853 | controller | WORKSPACE=/home/zuul/workspace
2025-10-07 07:29:33.951859 | controller | XDG_SESSION_CLASS=user
2025-10-07 07:29:33.951865 | controller | SELINUX_ROLE_REQUESTED=
2025-10-07 07:29:33.951871 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-07 07:29:33.951876 | controller | USER=zuul
2025-10-07 07:29:33.951882 | controller | ZUUL_VOTING=True
2025-10-07 07:29:33.951887 | controller | BUILD_TIMEOUT=1800000
2025-10-07 07:29:33.951893 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-07 07:29:33.951898 | controller | SHLVL=1
2025-10-07 07:29:33.951904 | controller | ZUUL_PATCHSET=b916ef9cc83cd6f61cb4c8a27bbc55fc27d7eaae
2025-10-07 07:29:33.951909 | controller | XDG_SESSION_ID=1
2025-10-07 07:29:33.951915 | controller | ZUUL_BRANCH=main
2025-10-07 07:29:33.951920 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-07 07:29:33.951926 | controller | SSH_CLIENT=192.168.26.12 39820 22
2025-10-07 07:29:33.951931 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-07 07:29:33.951937 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-07 07:29:33.951943 | controller | which_declare=declare -f
2025-10-07 07:29:33.951949 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-07 07:29:33.951955 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-07 07:29:33.951961 | controller | ZUUL_CHANGE=3366
2025-10-07 07:29:33.951979 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-07 07:29:33.951986 | controller | ZUUL_UUID=65cecfbf0c7c4346b762b7e8523fa3d7
2025-10-07 07:29:33.951992 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-07 07:29:33.951998 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-07 07:29:33.952003 | controller | }
2025-10-07 07:29:34.128395 | controller | ok: Runtime: 0:00:00.006996
2025-10-07 07:29:34.134360 | 
2025-10-07 07:29:34.134459 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-07 07:29:34.209463 | controller | skipping: Conditional result was False
2025-10-07 07:29:34.215396 | 
2025-10-07 07:29:34.215465 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-07 07:29:34.984644 | controller | skipping: Conditional result was False
2025-10-07 07:29:34.990992 | 
2025-10-07 07:29:34.991064 | TASK [Ensure legacy workspace directory]
2025-10-07 07:29:35.273998 | controller | changed
2025-10-07 07:29:35.363830 | 
2025-10-07 07:29:35.363950 | PLAY RECAP
2025-10-07 07:29:35.364007 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-07 07:29:35.364033 | 
2025-10-07 07:29:35.793016 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-07 07:29:35.793774 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-07 07:29:37.449164 | 
2025-10-07 07:29:37.449282 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-07 07:29:37.522041 | 
2025-10-07 07:29:37.522223 | TASK [Create zuul-output directory]
2025-10-07 07:29:38.192468 | controller | changed
2025-10-07 07:29:38.197391 | 
2025-10-07 07:29:38.197475 | TASK [Slurp Zuul inventory test]
2025-10-07 07:29:39.208890 | controller -> localhost | ok
2025-10-07 07:29:39.215231 | 
2025-10-07 07:29:39.215302 | TASK [Save zuul inventory]
2025-10-07 07:29:40.775184 | controller | changed
2025-10-07 07:29:40.780269 | 
2025-10-07 07:29:40.780337 | TASK [Save zuul vars without the change_message]
2025-10-07 07:29:41.723760 | controller | changed
2025-10-07 07:29:41.801375 | 
2025-10-07 07:29:41.801458 | PLAY RECAP
2025-10-07 07:29:41.801507 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-07 07:29:41.801531 | 
2025-10-07 07:29:42.197989 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-07 07:29:42.198747 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-07 07:29:43.982073 | 
2025-10-07 07:29:43.982185 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-07 07:29:44.020317 | 
2025-10-07 07:29:44.020425 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-07 07:29:44.071257 | controller | ok
2025-10-07 07:29:44.102876 | 
2025-10-07 07:29:44.103007 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-07 07:29:44.178874 | controller | skipping: Conditional result was False
2025-10-07 07:29:44.185107 | 
2025-10-07 07:29:44.185179 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-07 07:29:44.796404 | controller | ok
2025-10-07 07:29:44.804602 | 
2025-10-07 07:29:44.804685 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-07 07:29:45.774617 | controller | ok
2025-10-07 07:29:45.796720 | 
2025-10-07 07:29:45.796804 | TASK [Prepare workspace]
2025-10-07 07:29:45.838892 | controller | ok
2025-10-07 07:29:45.859800 | 
2025-10-07 07:29:45.859931 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-07 07:29:46.268737 | controller | ok
2025-10-07 07:29:46.287755 | 
2025-10-07 07:29:46.287837 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-07 07:29:49.358588 | controller | Output suppressed because no_log was given
2025-10-07 07:29:49.369200 | 
2025-10-07 07:29:49.369274 | LOOP [Create zuul-output directory]
2025-10-07 07:29:49.585348 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-07 07:29:49.772033 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-07 07:29:49.790668 | 
2025-10-07 07:29:49.790769 | TASK [Install required packages]
2025-10-07 07:30:53.641154 | controller | changed
2025-10-07 07:30:53.646614 | 
2025-10-07 07:30:53.646703 | TASK [Install venv]
2025-10-07 07:32:02.476251 | controller | changed
2025-10-07 07:32:02.515282 | 
2025-10-07 07:32:02.515370 | PLAY RECAP
2025-10-07 07:32:02.515418 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-07 07:32:02.515443 | 
2025-10-07 07:32:02.605072 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-07 07:32:02.605904 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-07 07:32:03.128862 | 
2025-10-07 07:32:03.128995 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-07 07:32:03.149363 | 
2025-10-07 07:32:03.149465 | TASK [Gather required facts]
2025-10-07 07:32:03.635809 | controller | ok
2025-10-07 07:32:03.641559 | 
2025-10-07 07:32:03.641660 | TASK [Load environment var if instructed to]
2025-10-07 07:32:03.666073 | controller | skipping: Conditional result was False
2025-10-07 07:32:03.671423 | 
2025-10-07 07:32:03.671495 | TASK [Check if group_vars dir exists]
2025-10-07 07:32:03.973606 | controller | ok
2025-10-07 07:32:03.979733 | 
2025-10-07 07:32:03.979816 | TASK [Create group_vars dir if does not exist]
2025-10-07 07:32:03.993963 | controller | skipping: Conditional result was False
2025-10-07 07:32:03.999459 | 
2025-10-07 07:32:03.999542 | TASK [Run molecule]
2025-10-07 07:32:04.956508 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-07 07:32:05.025977 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-07 07:32:22.253021 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 07:32:22.253690 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 07:32:22.253894 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 07:32:22.254366 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 07:32:22.254857 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+f5954785 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 1.0.0+f5954785 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 07:32:22.255346 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 07:32:22.255806 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 07:32:22.256274 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 07:32:22.256753 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 07:32:22.257209 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 07:32:22.257664 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 07:32:22.258123 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 07:32:22.258583 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 07:32:22.259026 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 07:32:22.259494 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 07:32:22.271066 | controller | INFO     Running default > prepare
2025-10-07 07:32:23.003115 | controller |
2025-10-07 07:32:23.003188 | controller | PLAY [Prepare] *****************************************************************
2025-10-07 07:32:23.003214 | controller |
2025-10-07 07:32:23.003343 | controller | TASK [Gathering Facts] *********************************************************
2025-10-07 07:32:23.003452 | controller | Tuesday 07 October 2025  07:32:22 +0000 (0:00:00.012)       0:00:00.012 *******
2025-10-07 07:32:23.929315 | controller | ok: [instance]
2025-10-07 07:32:23.929368 | controller |
2025-10-07 07:32:23.929404 | controller | TASK [Create temp file] ********************************************************
2025-10-07 07:32:23.929544 | controller | Tuesday 07 October 2025  07:32:23 +0000 (0:00:00.927)       0:00:00.939 *******
2025-10-07 07:32:24.565183 | controller | changed: [instance]
2025-10-07 07:32:24.565269 | controller |
2025-10-07 07:32:24.565369 | controller | PLAY RECAP *********************************************************************
2025-10-07 07:32:24.565382 | controller | instance                   : ok=2    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-07 07:32:24.565464 | controller |
2025-10-07 07:32:24.565599 | controller | Tuesday 07 October 2025  07:32:24 +0000 (0:00:00.636)       0:00:01.575 *******
2025-10-07 07:32:24.565696 | controller | ===============================================================================
2025-10-07 07:32:24.565786 | controller | Gathering Facts --------------------------------------------------------- 0.93s
2025-10-07 07:32:24.565883 | controller | Create temp file -------------------------------------------------------- 0.64s
2025-10-07 07:32:24.641227 | controller | INFO     Running default > converge
2025-10-07 07:32:25.100653 | controller |
2025-10-07 07:32:25.100727 | controller | PLAY [Converge] ****************************************************************
2025-10-07 07:32:25.968208 | controller |
2025-10-07 07:32:25.968261 | controller | TASK [Gathering Facts] *********************************************************
2025-10-07 07:32:25.968271 | controller | Tuesday 07 October 2025  07:32:25 +0000 (0:00:00.037)       0:00:00.037 *******
2025-10-07 07:32:25.968286 | controller | ok: [instance]
2025-10-07 07:32:25.968311 | controller |
2025-10-07 07:32:25.997078 | controller | TASK [Include deploy_bmh role] *************************************************
2025-10-07 07:32:25.997115 | controller | Tuesday 07 October 2025  07:32:25 +0000 (0:00:00.867)       0:00:00.905 *******
2025-10-07 07:32:25.997138 | controller |
2025-10-07 07:32:25.997162 | controller | TASK [deploy_bmh : Load baremetal hosts information from file] *****************
2025-10-07 07:32:25.997300 | controller | Tuesday 07 October 2025  07:32:25 +0000 (0:00:00.029)       0:00:00.935 *******
2025-10-07 07:32:26.028029 | controller | ok: [instance]
2025-10-07 07:32:26.028076 | controller |
2025-10-07 07:32:26.028237 | controller | TASK [deploy_bmh : Set baremetal hosts facts] **********************************
2025-10-07 07:32:26.028292 | controller | Tuesday 07 October 2025  07:32:26 +0000 (0:00:00.030)       0:00:00.966 *******
2025-10-07 07:32:26.068653 | controller | ok: [instance]
2025-10-07 07:32:26.068707 | controller |
2025-10-07 07:32:26.068775 | controller | TASK [deploy_bmh : Create destination directory] *******************************
2025-10-07 07:32:26.068883 | controller | Tuesday 07 October 2025  07:32:26 +0000 (0:00:00.040)       0:00:01.007 *******
2025-10-07 07:32:26.404626 | controller | changed: [instance]
2025-10-07 07:32:26.404689 | controller |
2025-10-07 07:32:26.404816 | controller | TASK [deploy_bmh : Build and create Secret CRs] ********************************
2025-10-07 07:32:26.404929 | controller | Tuesday 07 October 2025  07:32:26 +0000 (0:00:00.335)       0:00:01.342 *******
2025-10-07 07:32:26.466891 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/deploy_bmh/tasks/create_templated_resource.yml for instance => (item=compute-0)
2025-10-07 07:32:26.466995 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/deploy_bmh/tasks/create_templated_resource.yml for instance => (item=compute-1)
2025-10-07 07:32:26.467119 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/deploy_bmh/tasks/create_templated_resource.yml for instance => (item=controller-0)
2025-10-07 07:32:26.467246 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/deploy_bmh/tasks/create_templated_resource.yml for instance => (item=crc-0)
2025-10-07 07:32:26.467339 | controller |
2025-10-07 07:32:26.467435 | controller | TASK [deploy_bmh : Create the templates CR files] ******************************
2025-10-07 07:32:26.467569 | controller | Tuesday 07 October 2025  07:32:26 +0000 (0:00:00.062)       0:00:01.405 *******
2025-10-07 07:32:27.102263 | controller | changed: [instance]
2025-10-07 07:32:27.102317 | controller |
2025-10-07 07:32:27.102628 | controller | TASK [deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists] *********
2025-10-07 07:32:27.102678 | controller | Tuesday 07 October 2025  07:32:27 +0000 (0:00:00.635)       0:00:02.040 *******
2025-10-07 07:32:27.135007 | controller | skipping: [instance]
2025-10-07 07:32:27.167148 | controller |
2025-10-07 07:32:27.167170 | controller | TASK [deploy_bmh : Apply the generated CRs] ************************************
2025-10-07 07:32:27.167178 | controller | Tuesday 07 October 2025  07:32:27 +0000 (0:00:00.032)       0:00:02.073 *******
2025-10-07 07:32:27.167188 | controller | skipping: [instance]
2025-10-07 07:32:27.575245 | controller |
2025-10-07 07:32:27.575281 | controller | TASK [deploy_bmh : Create the templates CR files] ******************************
2025-10-07 07:32:27.575290 | controller | Tuesday 07 October 2025  07:32:27 +0000 (0:00:00.032)       0:00:02.105 *******
2025-10-07 07:32:27.575301 | controller | changed: [instance]
2025-10-07 07:32:27.609089 | controller |
2025-10-07 07:32:27.609116 | controller | TASK [deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists] *********
2025-10-07 07:32:27.609124 | controller | Tuesday 07 October 2025  07:32:27 +0000 (0:00:00.407)       0:00:02.513 *******
2025-10-07 07:32:27.609133 | controller | skipping: [instance]
2025-10-07 07:32:27.642623 | controller |
2025-10-07 07:32:27.642684 | controller | TASK [deploy_bmh : Apply the generated CRs] ************************************
2025-10-07 07:32:27.642693 | controller | Tuesday 07 October 2025  07:32:27 +0000 (0:00:00.034)       0:00:02.547 *******
2025-10-07 07:32:27.642706 | controller | skipping: [instance]
2025-10-07 07:32:27.642725 | controller |
2025-10-07 07:32:27.642733 | controller | TASK [deploy_bmh : Create the templates CR files] ******************************
2025-10-07 07:32:27.642774 | controller | Tuesday 07 October 2025  07:32:27 +0000 (0:00:00.033)       0:00:02.581 *******
2025-10-07 07:32:28.049725 | controller | changed: [instance]
2025-10-07 07:32:28.082057 | controller |
2025-10-07 07:32:28.082079 | controller | TASK [deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists] *********
2025-10-07 07:32:28.082087 | controller | Tuesday 07 October 2025  07:32:28 +0000 (0:00:00.406)       0:00:02.987 *******
2025-10-07 07:32:28.082096 | controller | skipping: [instance]
2025-10-07 07:32:28.114709 | controller |
2025-10-07 07:32:28.114731 | controller | TASK [deploy_bmh : Apply the generated CRs] ************************************
2025-10-07 07:32:28.114740 | controller | Tuesday 07 October 2025  07:32:28 +0000 (0:00:00.032)       0:00:03.020 *******
2025-10-07 07:32:28.114751 | controller | skipping: [instance]
2025-10-07 07:32:28.521989 | controller |
2025-10-07 07:32:28.522028 | controller | TASK [deploy_bmh : Create the templates CR files] ******************************
2025-10-07 07:32:28.522036 | controller | Tuesday 07 October 2025  07:32:28 +0000 (0:00:00.032)       0:00:03.053 *******
2025-10-07 07:32:28.522047 | controller | changed: [instance]
2025-10-07 07:32:28.557591 | controller |
2025-10-07 07:32:28.557641 | controller | TASK [deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists] *********
2025-10-07 07:32:28.557649 | controller | Tuesday 07 October 2025  07:32:28 +0000 (0:00:00.406)       0:00:03.460 *******
2025-10-07 07:32:28.557669 | controller | skipping: [instance]
2025-10-07 07:32:28.598624 | controller |
2025-10-07 07:32:28.598684 | controller | TASK [deploy_bmh : Apply the generated CRs] ************************************
2025-10-07 07:32:28.598692 | controller | Tuesday 07 October 2025  07:32:28 +0000 (0:00:00.034)       0:00:03.494 *******
2025-10-07 07:32:28.598706 | controller | skipping: [instance]
2025-10-07 07:32:28.599354 | controller |
2025-10-07 07:32:28.599409 | controller | TASK [deploy_bmh : Build and create BMH Netconfig Secrets] *********************
2025-10-07 07:32:28.599427 | controller | Tuesday 07 October 2025  07:32:28 +0000 (0:00:00.041)       0:00:03.536 *******
2025-10-07 07:32:28.659910 | controller | skipping: [instance] => (item=compute-0)
2025-10-07 07:32:28.738670 | controller | skipping: [instance] => (item=compute-1)
2025-10-07 07:32:28.738737 | controller | skipping: [instance] => (item=controller-0)
2025-10-07 07:32:28.738754 | controller | skipping: [instance] => (item=crc-0)
2025-10-07 07:32:28.738771 | controller | skipping: [instance]
2025-10-07 07:32:28.738779 | controller |
2025-10-07 07:32:28.738787 | controller | TASK [deploy_bmh : Build and create BMH CRs] ***********************************
2025-10-07 07:32:28.738794 | controller | Tuesday 07 October 2025  07:32:28 +0000 (0:00:00.060)       0:00:03.597 *******
2025-10-07 07:32:28.738809 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/deploy_bmh/tasks/create_templated_resource.yml for instance => (item=compute-0)
2025-10-07 07:32:29.168375 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/deploy_bmh/tasks/create_templated_resource.yml for instance => (item=compute-1)
2025-10-07 07:32:29.168417 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/deploy_bmh/tasks/create_templated_resource.yml for instance => (item=controller-0)
2025-10-07 07:32:29.168425 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/deploy_bmh/tasks/create_templated_resource.yml for instance => (item=crc-0)
2025-10-07 07:32:29.168432 | controller |
2025-10-07 07:32:29.168438 | controller | TASK [deploy_bmh : Create the templates CR files] ******************************
2025-10-07 07:32:29.168445 | controller | Tuesday 07 October 2025  07:32:28 +0000 (0:00:00.078)       0:00:03.676 *******
2025-10-07 07:32:29.168455 | controller | changed: [instance]
2025-10-07 07:32:29.208201 | controller |
2025-10-07 07:32:29.208227 | controller | TASK [deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists] *********
2025-10-07 07:32:29.208235 | controller | Tuesday 07 October 2025  07:32:29 +0000 (0:00:00.429)       0:00:04.106 *******
2025-10-07 07:32:29.208245 | controller | skipping: [instance]
2025-10-07 07:32:29.247424 | controller |
2025-10-07 07:32:29.247451 | controller | TASK [deploy_bmh : Apply the generated CRs] ************************************
2025-10-07 07:32:29.247458 | controller | Tuesday 07 October 2025  07:32:29 +0000 (0:00:00.040)       0:00:04.146 *******
2025-10-07 07:32:29.247468 | controller | skipping: [instance]
2025-10-07 07:32:29.635692 | controller |
2025-10-07 07:32:29.635732 | controller | TASK [deploy_bmh : Create the templates CR files] ******************************
2025-10-07 07:32:29.635740 | controller | Tuesday 07 October 2025  07:32:29 +0000 (0:00:00.039)       0:00:04.185 *******
2025-10-07 07:32:29.635752 | controller | changed: [instance]
2025-10-07 07:32:29.667752 | controller |
2025-10-07 07:32:29.667772 | controller | TASK [deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists] *********
2025-10-07 07:32:29.667779 | controller | Tuesday 07 October 2025  07:32:29 +0000 (0:00:00.388)       0:00:04.573 *******
2025-10-07 07:32:29.667789 | controller | skipping: [instance]
2025-10-07 07:32:29.701390 | controller |
2025-10-07 07:32:29.701420 | controller | TASK [deploy_bmh : Apply the generated CRs] ************************************
2025-10-07 07:32:29.701429 | controller | Tuesday 07 October 2025  07:32:29 +0000 (0:00:00.032)       0:00:04.606 *******
2025-10-07 07:32:29.701440 | controller | skipping: [instance]
2025-10-07 07:32:30.116332 | controller |
2025-10-07 07:32:30.116369 | controller | TASK [deploy_bmh : Create the templates CR files] ******************************
2025-10-07 07:32:30.116378 | controller | Tuesday 07 October 2025  07:32:29 +0000 (0:00:00.033)       0:00:04.639 *******
2025-10-07 07:32:30.116388 | controller | changed: [instance]
2025-10-07 07:32:30.148151 | controller |
2025-10-07 07:32:30.148173 | controller | TASK [deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists] *********
2025-10-07 07:32:30.148181 | controller | Tuesday 07 October 2025  07:32:30 +0000 (0:00:00.414)       0:00:05.054 *******
2025-10-07 07:32:30.148190 | controller | skipping: [instance]
2025-10-07 07:32:30.180204 | controller |
2025-10-07 07:32:30.180224 | controller | TASK [deploy_bmh : Apply the generated CRs] ************************************
2025-10-07 07:32:30.180232 | controller | Tuesday 07 October 2025  07:32:30 +0000 (0:00:00.032)       0:00:05.086 *******
2025-10-07 07:32:30.180241 | controller | skipping: [instance]
2025-10-07 07:32:30.577051 | controller |
2025-10-07 07:32:30.577094 | controller | TASK [deploy_bmh : Create the templates CR files] ******************************
2025-10-07 07:32:30.577104 | controller | Tuesday 07 October 2025  07:32:30 +0000 (0:00:00.032)       0:00:05.118 *******
2025-10-07 07:32:30.577114 | controller | changed: [instance]
2025-10-07 07:32:30.609524 | controller |
2025-10-07 07:32:30.609566 | controller | TASK [deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists] *********
2025-10-07 07:32:30.609576 | controller | Tuesday 07 October 2025  07:32:30 +0000 (0:00:00.396)       0:00:05.515 *******
2025-10-07 07:32:30.609589 | controller | skipping: [instance]
2025-10-07 07:32:30.661165 | controller |
2025-10-07 07:32:30.661200 | controller | TASK [deploy_bmh : Apply the generated CRs] ************************************
2025-10-07 07:32:30.661215 | controller | Tuesday 07 October 2025  07:32:30 +0000 (0:00:00.032)       0:00:05.547 *******
2025-10-07 07:32:30.661226 | controller | skipping: [instance]
2025-10-07 07:32:30.728761 | controller |
2025-10-07 07:32:30.728798 | controller | PLAY RECAP *********************************************************************
2025-10-07 07:32:30.728807 | controller | instance                   : ok=20   changed=9    unreachable=0    failed=0    skipped=17   rescued=0    ignored=0
2025-10-07 07:32:30.728813 | controller |
2025-10-07 07:32:30.728818 | controller | Tuesday 07 October 2025  07:32:30 +0000 (0:00:00.049)       0:00:05.597 *******
2025-10-07 07:32:30.728824 | controller | ===============================================================================
2025-10-07 07:32:30.728829 | controller | Gathering Facts --------------------------------------------------------- 0.87s
2025-10-07 07:32:30.728835 | controller | deploy_bmh : Create the templates CR files ------------------------------ 0.64s
2025-10-07 07:32:30.728840 | controller | deploy_bmh : Create the templates CR files ------------------------------ 0.43s
2025-10-07 07:32:30.728846 | controller | deploy_bmh : Create the templates CR files ------------------------------ 0.41s
2025-10-07 07:32:30.728851 | controller | deploy_bmh : Create the templates CR files ------------------------------ 0.41s
2025-10-07 07:32:30.728857 | controller | deploy_bmh : Create the templates CR files ------------------------------ 0.41s
2025-10-07 07:32:30.728862 | controller | deploy_bmh : Create the templates CR files ------------------------------ 0.41s
2025-10-07 07:32:30.728867 | controller | deploy_bmh : Create the templates CR files ------------------------------ 0.40s
2025-10-07 07:32:30.728872 | controller | deploy_bmh : Create the templates CR files ------------------------------ 0.39s
2025-10-07 07:32:30.728877 | controller | deploy_bmh : Create destination directory ------------------------------- 0.34s
2025-10-07 07:32:30.728883 | controller | deploy_bmh : Build and create BMH CRs ----------------------------------- 0.08s
2025-10-07 07:32:30.728888 | controller | deploy_bmh : Build and create Secret CRs -------------------------------- 0.06s
2025-10-07 07:32:30.728893 | controller | deploy_bmh : Build and create BMH Netconfig Secrets --------------------- 0.06s
2025-10-07 07:32:30.728898 | controller | deploy_bmh : Apply the generated CRs ------------------------------------ 0.05s
2025-10-07 07:32:30.728903 | controller | deploy_bmh : Apply the generated CRs ------------------------------------ 0.04s
2025-10-07 07:32:30.728909 | controller | deploy_bmh : Set baremetal hosts facts ---------------------------------- 0.04s
2025-10-07 07:32:30.728914 | controller | deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists --------- 0.04s
2025-10-07 07:32:30.728919 | controller | deploy_bmh : Apply the generated CRs ------------------------------------ 0.04s
2025-10-07 07:32:30.728925 | controller | deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists --------- 0.03s
2025-10-07 07:32:30.728930 | controller | deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists --------- 0.03s
2025-10-07 07:32:30.728958 | controller | INFO     Running default > cleanup
2025-10-07 07:32:31.186850 | controller |
2025-10-07 07:32:31.187156 | controller | PLAY [Cleanup] *****************************************************************
2025-10-07 07:32:31.187335 | controller |
2025-10-07 07:32:31.187522 | controller | TASK [Gathering Facts] *********************************************************
2025-10-07 07:32:31.187693 | controller | Tuesday 07 October 2025  07:32:31 +0000 (0:00:00.037)       0:00:00.037 *******
2025-10-07 07:32:32.027616 | controller | ok: [instance]
2025-10-07 07:32:32.027889 | controller |
2025-10-07 07:32:32.027959 | controller | TASK [Include deploy_bmh role] *************************************************
2025-10-07 07:32:32.054026 | controller | Tuesday 07 October 2025  07:32:32 +0000 (0:00:00.840)       0:00:00.878 *******
2025-10-07 07:32:32.054074 | controller |
2025-10-07 07:32:32.371556 | controller | TASK [deploy_bmh : Get the BM manifests] ***************************************
2025-10-07 07:32:32.371607 | controller | Tuesday 07 October 2025  07:32:32 +0000 (0:00:00.026)       0:00:00.904 *******
2025-10-07 07:32:32.371621 | controller | ok: [instance]
2025-10-07 07:32:32.428610 | controller |
2025-10-07 07:32:32.428646 | controller | TASK [deploy_bmh : Removing resources if deployed] *****************************
2025-10-07 07:32:32.428655 | controller | Tuesday 07 October 2025  07:32:32 +0000 (0:00:00.316)       0:00:01.221 *******
2025-10-07 07:32:32.428667 | controller | skipping: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-secret-compute-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 174, 'inode': 159407686, 'dev': 64513, 'nlink': 1, 'atime': 1759822347.0596826, 'mtime': 1759822346.7786834, 'ctime': 1759822347.0626824, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-10-07 07:32:32.429900 | controller | skipping: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-secret-compute-1.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 174, 'inode': 4508895, 'dev': 64513, 'nlink': 1, 'atime': 1759822347.535681, 'mtime': 1759822347.3746815, 'ctime': 1759822347.538681, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-10-07 07:32:32.429954 | controller | skipping: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-secret-controller-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 177, 'inode': 16863114, 'dev': 64513, 'nlink': 1, 'atime': 1759822348.0116796, 'mtime': 1759822347.8656802, 'ctime': 1759822348.0146797, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-10-07 07:32:33.988598 | controller | skipping: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-secret-crc-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 170, 'inode': 29368790, 'dev': 64513, 'nlink': 1, 'atime': 1759822348.4826784, 'mtime': 1759822348.3326788, 'ctime': 1759822348.4866784, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-10-07 07:32:33.988636 | controller | skipping: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-compute-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 416, 'inode': 41956484, 'dev': 64513, 'nlink': 1, 'atime': 1759822349.1246765, 'mtime': 1759822348.965677, 'ctime': 1759822349.1286764, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-10-07 07:32:33.988645 | controller | skipping: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-compute-1.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 416, 'inode': 54560517, 'dev': 64513, 'nlink': 1, 'atime': 1759822349.595675, 'mtime': 1759822349.4546754, 'ctime': 1759822349.598675, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-10-07 07:32:33.988655 | controller | skipping: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-controller-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 425, 'inode': 67148895, 'dev': 64513, 'nlink': 1, 'atime': 1759822350.0786736, 'mtime': 1759822349.9326742, 'ctime': 1759822350.0826735, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-10-07 07:32:33.988660 | controller | skipping: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-crc-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 404, 'inode': 79711340, 'dev': 64513, 'nlink': 1, 'atime': 1759822350.5376723, 'mtime': 1759822350.3976727, 'ctime': 1759822350.5406723, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-10-07 07:32:33.988666 | controller | skipping: [instance]
2025-10-07 07:32:33.988672 | controller |
2025-10-07 07:32:33.988678 | controller | TASK [deploy_bmh : Delete CR files] ********************************************
2025-10-07 07:32:33.988684 | controller | Tuesday 07 October 2025  07:32:32 +0000 (0:00:00.057)       0:00:01.279 *******
2025-10-07 07:32:33.988700 | controller | changed: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-secret-compute-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 174, 'inode': 159407686, 'dev': 64513, 'nlink': 1, 'atime': 1759822347.0596826, 'mtime': 1759822346.7786834, 'ctime': 1759822347.0626824, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-10-07 07:32:33.988707 | controller | changed: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-secret-compute-1.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 174, 'inode': 4508895, 'dev': 64513, 'nlink': 1, 'atime': 1759822347.535681, 'mtime': 1759822347.3746815, 'ctime': 1759822347.538681, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-10-07 07:32:33.988713 | controller | changed: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-secret-controller-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 177, 'inode': 16863114, 'dev': 64513, 'nlink': 1, 'atime': 1759822348.0116796, 'mtime': 1759822347.8656802, 'ctime': 1759822348.0146797, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-10-07 07:32:33.988719 | controller | changed: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-secret-crc-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 170, 'inode': 29368790, 'dev': 64513, 'nlink': 1, 'atime': 1759822348.4826784, 'mtime': 1759822348.3326788, 'ctime': 1759822348.4866784, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-10-07 07:32:33.988728 | controller | changed: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-compute-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 416, 'inode': 41956484, 'dev': 64513, 'nlink': 1, 'atime': 1759822349.1246765, 'mtime': 1759822348.965677, 'ctime': 1759822349.1286764, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-10-07 07:32:33.988736 | controller | changed: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-compute-1.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 416, 'inode': 54560517, 'dev': 64513, 'nlink': 1, 'atime': 1759822349.595675, 'mtime': 1759822349.4546754, 'ctime': 1759822349.598675, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-10-07 07:32:33.988744 | controller | changed: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-controller-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 425, 'inode': 67148895, 'dev': 64513, 'nlink': 1, 'atime': 1759822350.0786736, 'mtime': 1759822349.9326742, 'ctime': 1759822350.0826735, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-10-07 07:32:34.052348 | controller | changed: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-crc-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 404, 'inode': 79711340, 'dev': 64513, 'nlink': 1, 'atime': 1759822350.5376723, 'mtime': 1759822350.3976727, 'ctime': 1759822350.5406723, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-10-07 07:32:34.052379 | controller |
2025-10-07 07:32:34.052387 | controller | PLAY RECAP *********************************************************************
2025-10-07 07:32:34.052394 | controller | instance                   : ok=3    changed=1    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2025-10-07 07:32:34.052399 | controller |
2025-10-07 07:32:34.052405 | controller | Tuesday 07 October 2025  07:32:33 +0000 (0:00:01.558)       0:00:02.837 *******
2025-10-07 07:32:34.052410 | controller | ===============================================================================
2025-10-07 07:32:34.052415 | controller | deploy_bmh : Delete CR files -------------------------------------------- 1.56s
2025-10-07 07:32:34.052421 | controller | Gathering Facts --------------------------------------------------------- 0.84s
2025-10-07 07:32:34.052426 | controller | deploy_bmh : Get the BM manifests --------------------------------------- 0.32s
2025-10-07 07:32:34.052432 | controller | deploy_bmh : Removing resources if deployed ----------------------------- 0.06s
2025-10-07 07:32:34.052445 | controller | Include deploy_bmh role ------------------------------------------------- 0.03s
2025-10-07 07:32:34.052455 | controller | INFO     Writing /tmp/report.html report.
2025-10-07 07:32:34.564338 | controller | changed
2025-10-07 07:32:34.585668 | 
2025-10-07 07:32:34.585756 | PLAY RECAP
2025-10-07 07:32:34.585806 | controller | ok: 3 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-07 07:32:34.585839 | 
2025-10-07 07:32:34.661841 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-07 07:32:34.662709 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-07 07:32:35.191223 | 
2025-10-07 07:32:35.191326 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-07 07:32:35.211808 | 
2025-10-07 07:32:35.211901 | TASK [Filter out host if needed]
2025-10-07 07:32:35.220751 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-07 07:32:35.225359 | 
2025-10-07 07:32:35.225439 | TASK [Ensure file is present]
2025-10-07 07:32:35.538540 | controller | ok
2025-10-07 07:32:35.545830 | 
2025-10-07 07:32:35.545950 | TASK [Manage molecule report file]
2025-10-07 07:32:36.074433 | controller | changed
2025-10-07 07:32:36.080216 | 
2025-10-07 07:32:36.080288 | TASK [Check if we get ci-framework-data basedir]
2025-10-07 07:32:36.293474 | controller | ok
2025-10-07 07:32:36.298722 | 
2025-10-07 07:32:36.298812 | TASK [Create ci-framework-data log directory for zuul]
2025-10-07 07:32:36.623414 | controller | changed
2025-10-07 07:32:36.629466 | 
2025-10-07 07:32:36.629548 | TASK [Copy ci-framework interesting files]
2025-10-07 07:32:36.842093 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-07 07:32:37.158752 | controller | changed
2025-10-07 07:32:37.164611 | 
2025-10-07 07:32:37.164697 | TASK [Get SELinux listing]
2025-10-07 07:32:37.695630 | controller | changed
2025-10-07 07:32:37.701846 | 
2025-10-07 07:32:37.701955 | TASK [Generate log index]
2025-10-07 07:32:38.379904 | controller | changed
2025-10-07 07:32:38.385155 | 
2025-10-07 07:32:38.385237 | TASK [Get some env related data]
2025-10-07 07:32:38.944380 | controller | /home/zuul/.local/bin/ansible
2025-10-07 07:32:39.914667 | controller | changed
2025-10-07 07:32:39.919995 | 
2025-10-07 07:32:39.920059 | TASK [Generate list of logs to collect in home directory]
2025-10-07 07:32:40.215638 | controller | ok: All paths examined
2025-10-07 07:32:40.220889 | 
2025-10-07 07:32:40.220981 | LOOP [Copy logs from home directory]
2025-10-07 07:32:40.532110 | controller | changed:
2025-10-07 07:32:40.532221 | controller | {
2025-10-07 07:32:40.532250 | controller |   "atime": 1759822302.3611495,
2025-10-07 07:32:40.532271 | controller |   "ctime": 1759822322.019429,
2025-10-07 07:32:40.532289 | controller |   "dev": 64513,
2025-10-07 07:32:40.532307 | controller |   "gid": 1000,
2025-10-07 07:32:40.532324 | controller |   "gr_name": "zuul",
2025-10-07 07:32:40.532342 | controller |   "inode": 4450477,
2025-10-07 07:32:40.532358 | controller |   "isblk": false,
2025-10-07 07:32:40.532374 | controller |   "ischr": false,
2025-10-07 07:32:40.532395 | controller |   "isdir": false,
2025-10-07 07:32:40.532413 | controller |   "isfifo": false,
2025-10-07 07:32:40.532430 | controller |   "isgid": false,
2025-10-07 07:32:40.532446 | controller |   "islnk": false,
2025-10-07 07:32:40.532461 | controller |   "isreg": true,
2025-10-07 07:32:40.532475 | controller |   "issock": false,
2025-10-07 07:32:40.532489 | controller |   "isuid": false,
2025-10-07 07:32:40.532503 | controller |   "mode": "0644",
2025-10-07 07:32:40.532517 | controller |   "mtime": 1759822322.019429,
2025-10-07 07:32:40.532530 | controller |   "nlink": 1,
2025-10-07 07:32:40.532544 | controller |   "path": "/home/zuul/ansible.log",
2025-10-07 07:32:40.532561 | controller |   "pw_name": "zuul",
2025-10-07 07:32:40.532576 | controller |   "rgrp": true,
2025-10-07 07:32:40.532605 | controller |   "roth": true,
2025-10-07 07:32:40.532623 | controller |   "rusr": true,
2025-10-07 07:32:40.532639 | controller |   "size": 6749,
2025-10-07 07:32:40.532653 | controller |   "uid": 1000,
2025-10-07 07:32:40.532668 | controller |   "wgrp": false,
2025-10-07 07:32:40.532682 | controller |   "woth": false,
2025-10-07 07:32:40.532700 | controller |   "wusr": true,
2025-10-07 07:32:40.532716 | controller |   "xgrp": false,
2025-10-07 07:32:40.532730 | controller |   "xoth": false,
2025-10-07 07:32:40.532745 | controller |   "xusr": false
2025-10-07 07:32:40.532759 | controller | }
2025-10-07 07:32:40.545615 | 
2025-10-07 07:32:40.545719 | TASK [Copy crio stats log file]
2025-10-07 07:32:40.560154 | controller | skipping: Conditional result was False
2025-10-07 07:32:40.565416 | 
2025-10-07 07:32:40.565484 | TASK [Get SELinux related data]
2025-10-07 07:32:40.785293 | controller | <no matches>
2025-10-07 07:32:41.092015 | controller | ERROR
2025-10-07 07:32:41.092236 | controller | {
2025-10-07 07:32:41.092277 | controller |   "delta": "0:00:00.009089",
2025-10-07 07:32:41.092300 | controller |   "end": "2025-10-07 07:32:40.786021",
2025-10-07 07:32:41.092320 | controller |   "msg": "non-zero return code",
2025-10-07 07:32:41.092337 | controller |   "rc": 1,
2025-10-07 07:32:41.092354 | controller |   "start": "2025-10-07 07:32:40.776932"
2025-10-07 07:32:41.092369 | controller | }
2025-10-07 07:32:41.092395 | controller | ERROR: Ignoring Errors
2025-10-07 07:32:41.099029 | 
2025-10-07 07:32:41.099107 | TASK [Create system configuration directory]
2025-10-07 07:32:41.282164 | controller | changed
2025-10-07 07:32:41.287180 | 
2025-10-07 07:32:41.287264 | TASK [Get some of the system configurations]
2025-10-07 07:32:41.816967 | controller | changed
2025-10-07 07:32:41.822293 | 
2025-10-07 07:32:41.822371 | TASK [Copy generated documentation if available]
2025-10-07 07:32:41.837150 | controller | skipping: Conditional result was False
2025-10-07 07:32:41.842331 | 
2025-10-07 07:32:41.842395 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-07 07:32:41.856502 | controller | skipping: Conditional result was False
2025-10-07 07:32:41.862263 | 
2025-10-07 07:32:41.862328 | TASK [Compress logs bigger than 2MB]
2025-10-07 07:32:42.389788 | controller | changed
2025-10-07 07:32:42.396634 | 
2025-10-07 07:32:42.396725 | TASK [Copy files from workspace on node]
2025-10-07 07:32:42.416255 | controller | ok
2025-10-07 07:32:42.440608 | 
2025-10-07 07:32:42.440701 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-07 07:32:42.454695 | controller | skipping: Conditional result was False
2025-10-07 07:32:42.460920 | 
2025-10-07 07:32:42.461021 | TASK [fetch-output : Set log path for single node]
2025-10-07 07:32:42.490004 | controller | ok
2025-10-07 07:32:42.497124 | 
2025-10-07 07:32:42.497212 | LOOP [fetch-output : Ensure local output dirs]
2025-10-07 07:32:42.699321 | controller -> localhost | ok: "/var/lib/zuul/builds/65cecfbf0c7c4346b762b7e8523fa3d7/work/logs"
2025-10-07 07:32:42.699544 | controller -> localhost | changed: All items complete
2025-10-07 07:32:42.699574 | 
2025-10-07 07:32:42.887277 | controller -> localhost | changed: "/var/lib/zuul/builds/65cecfbf0c7c4346b762b7e8523fa3d7/work/artifacts"
2025-10-07 07:32:43.074649 | controller -> localhost | changed: "/var/lib/zuul/builds/65cecfbf0c7c4346b762b7e8523fa3d7/work/docs"
2025-10-07 07:32:43.085979 | 
2025-10-07 07:32:43.086080 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-07 07:32:43.643939 | controller | changed:
2025-10-07 07:32:43.644112 | controller | .d..t...... ./
2025-10-07 07:32:43.644143 | controller | >f+++++++++ README.html
2025-10-07 07:32:43.644167 | controller | >f+++++++++ ansible-execution.log
2025-10-07 07:32:43.644189 | controller | >f+++++++++ ansible.log
2025-10-07 07:32:43.644209 | controller | >f+++++++++ dmesg.log
2025-10-07 07:32:43.644228 | controller | >f+++++++++ installed-pkgs.log
2025-10-07 07:32:43.644249 | controller | >f+++++++++ python.log
2025-10-07 07:32:43.644270 | controller | >f+++++++++ registries.conf
2025-10-07 07:32:43.644288 | controller | >f+++++++++ report.html
2025-10-07 07:32:43.644308 | controller | >f+++++++++ selinux-denials.log
2025-10-07 07:32:43.644327 | controller | >f+++++++++ selinux-listing.log
2025-10-07 07:32:43.644345 | controller | cd+++++++++ ci-framework-data/
2025-10-07 07:32:43.644363 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-07 07:32:43.644381 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-07 07:32:43.644398 | controller | cd+++++++++ ci-framework-data/artifacts/deploy_bmh/
2025-10-07 07:32:43.644416 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-07 07:32:43.644433 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-07 07:32:43.644451 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-07 07:32:43.644468 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-07 07:32:43.644485 | controller | cd+++++++++ registries.conf.d/
2025-10-07 07:32:43.644501 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-07 07:32:43.644518 | controller | cd+++++++++ system-config/
2025-10-07 07:32:43.644535 | controller | cd+++++++++ system-config/libvirt/
2025-10-07 07:32:43.644552 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-07 07:32:43.644568 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-07 07:32:44.041860 | controller | changed: .d..t...... ./
2025-10-07 07:32:44.431382 | controller | changed: .d..t...... ./
2025-10-07 07:32:44.450288 | 
2025-10-07 07:32:44.450386 | TASK [Return artifact to Zuul]
2025-10-07 07:32:44.478902 | controller | ok
2025-10-07 07:32:44.497617 | 
2025-10-07 07:32:44.497683 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-07 07:32:44.497783 | 
2025-10-07 07:32:44.497809 | PLAY RECAP
2025-10-07 07:32:44.497846 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-07 07:32:44.497866 | 
2025-10-07 07:32:44.583201 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-07 07:32:44.583979 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-07 07:32:45.147867 | 
2025-10-07 07:32:45.148002 | PLAY [all]
2025-10-07 07:32:45.167295 | 
2025-10-07 07:32:45.167372 | TASK [include_role : fetch-output]
2025-10-07 07:32:45.196114 | controller | ok
2025-10-07 07:32:45.212958 | 
2025-10-07 07:32:45.213049 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-07 07:32:45.257310 | controller | skipping: Conditional result was False
2025-10-07 07:32:45.262818 | 
2025-10-07 07:32:45.262929 | TASK [fetch-output : Set log path for single node]
2025-10-07 07:32:45.291419 | controller | ok
2025-10-07 07:32:45.296641 | 
2025-10-07 07:32:45.296722 | LOOP [fetch-output : Ensure local output dirs]
2025-10-07 07:32:45.650403 | controller -> localhost | ok: "/var/lib/zuul/builds/65cecfbf0c7c4346b762b7e8523fa3d7/work/logs"
2025-10-07 07:32:45.843128 | controller -> localhost | ok: "/var/lib/zuul/builds/65cecfbf0c7c4346b762b7e8523fa3d7/work/artifacts"
2025-10-07 07:32:46.034761 | controller -> localhost | ok: "/var/lib/zuul/builds/65cecfbf0c7c4346b762b7e8523fa3d7/work/docs"
2025-10-07 07:32:46.050380 | 
2025-10-07 07:32:46.050466 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-07 07:32:46.621645 | controller | ok
2025-10-07 07:32:46.621956 | controller | ok: All items complete
2025-10-07 07:32:46.622000 | 
2025-10-07 07:32:47.009300 | controller | ok
2025-10-07 07:32:47.398013 | controller | ok
2025-10-07 07:32:47.418417 | 
2025-10-07 07:32:47.418530 | TASK [include_role : fetch-output-openshift]
2025-10-07 07:32:47.432042 | controller | skipping: Conditional result was False
2025-10-07 07:32:47.438853 | 
2025-10-07 07:32:47.438955 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-07 07:32:47.801411 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007534
2025-10-07 07:32:47.997254 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006637
2025-10-07 07:32:48.028482 | 
2025-10-07 07:32:48.028578 | PLAY [all]
2025-10-07 07:32:48.042534 | 
2025-10-07 07:32:48.042621 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-07 07:32:48.467586 | controller | changed
2025-10-07 07:32:48.489815 | 
2025-10-07 07:32:48.489897 | PLAY RECAP
2025-10-07 07:32:48.489964 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-07 07:32:48.489991 | 
2025-10-07 07:32:48.565114 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-07 07:32:48.565860 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-07 07:32:49.145893 | 
2025-10-07 07:32:49.146014 | PLAY [localhost]
2025-10-07 07:32:49.163980 | 
2025-10-07 07:32:49.164060 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-07 07:32:49.485303 | localhost | changed
2025-10-07 07:32:49.489871 | 
2025-10-07 07:32:49.489980 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-07 07:32:49.517480 | localhost | ok
2025-10-07 07:32:49.525766 | 
2025-10-07 07:32:49.525839 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-07 07:32:49.826663 | localhost | changed
2025-10-07 07:32:49.832748 | 
2025-10-07 07:32:49.832850 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-07 07:32:50.399349 | localhost | changed
2025-10-07 07:32:50.404310 | 
2025-10-07 07:32:50.404401 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-07 07:32:50.756089 | localhost | Identity added: /var/lib/zuul/builds/65cecfbf0c7c4346b762b7e8523fa3d7/work/tmp/ansible.821q17jt (/var/lib/zuul/builds/65cecfbf0c7c4346b762b7e8523fa3d7/work/tmp/ansible.821q17jt)
2025-10-07 07:32:50.756260 | localhost | ok: Runtime: 0:00:00.007679
2025-10-07 07:32:50.760509 | 
2025-10-07 07:32:50.760582 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-07 07:32:50.991629 | localhost | ok: Runtime: 0:00:00.004503
2025-10-07 07:32:50.996495 | 
2025-10-07 07:32:50.996570 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-07 07:32:51.049309 | localhost | changed
2025-10-07 07:32:51.053783 | 
2025-10-07 07:32:51.053864 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-07 07:32:51.379503 | localhost | changed
2025-10-07 07:32:51.406421 | 
2025-10-07 07:32:51.406520 | PLAY [localhost]
2025-10-07 07:32:51.427559 | 
2025-10-07 07:32:51.427661 | TASK [Generate bulk log download script]
2025-10-07 07:32:51.446726 | localhost | ok
2025-10-07 07:32:51.458989 | 
2025-10-07 07:32:51.459071 | TASK [local-log-download : Check API endpoint is defined]
2025-10-07 07:32:51.486714 | localhost | ok: All assertions passed
2025-10-07 07:32:51.491770 | 
2025-10-07 07:32:51.491877 | TASK [local-log-download : Create download script]
2025-10-07 07:32:51.860761 | localhost -> localhost | changed
2025-10-07 07:32:51.869978 | 
2025-10-07 07:32:51.870055 | TASK [Register quick-download link]
2025-10-07 07:32:51.887727 | localhost | ok
2025-10-07 07:32:51.924774 | 
2025-10-07 07:32:51.924871 | PLAY [logserver.rdoproject.org]
2025-10-07 07:32:51.935029 | 
2025-10-07 07:32:51.935091 | TASK [Set zuul-log-path fact]
2025-10-07 07:32:51.951127 | logserver.rdoproject.org | ok
2025-10-07 07:32:51.964135 | 
2025-10-07 07:32:51.964241 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-07 07:32:51.990863 | logserver.rdoproject.org | ok
2025-10-07 07:32:51.997070 | 
2025-10-07 07:32:51.997248 | TASK [upload-logs : Create log directories]
2025-10-07 07:32:53.756255 | logserver.rdoproject.org | changed
2025-10-07 07:32:53.760193 | 
2025-10-07 07:32:53.760283 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-07 07:32:54.009810 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.016893
2025-10-07 07:32:54.015016 | 
2025-10-07 07:32:54.015177 | TASK [upload-logs : Upload logs to log server]
2025-10-07 07:32:55.808060 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-07 07:32:55.810996 | 
2025-10-07 07:32:55.811065 | LOOP [upload-logs : Compress console log and json output]
2025-10-07 07:32:55.859734 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-07 07:32:55.863497 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-07 07:32:55.870017 | 
2025-10-07 07:32:55.870129 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-07 07:32:55.905871 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-07 07:32:55.906110 | 
2025-10-07 07:32:55.909381 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-07 07:32:55.917091 | 
2025-10-07 07:32:55.917199 | LOOP [upload-logs : Upload console log and json output]