2025-10-03 12:44:09.644571 | Job console starting... 2025-10-03 12:44:09.892165 | Updating repositories 2025-10-03 12:44:10.650018 | Preparing job workspace 2025-10-03 12:44:20.822023 | Running Ansible setup... 2025-10-03 12:44:25.759897 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-03 12:44:27.397502 | 2025-10-03 12:44:27.397604 | PLAY [localhost] 2025-10-03 12:44:27.423191 | 2025-10-03 12:44:27.423257 | TASK [Gathering Facts] 2025-10-03 12:44:29.150670 | localhost | ok 2025-10-03 12:44:29.182752 | 2025-10-03 12:44:29.182850 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-03 12:44:29.932972 | localhost -> localhost | changed 2025-10-03 12:44:29.938138 | 2025-10-03 12:44:29.938209 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-03 12:44:31.812804 | localhost -> localhost | changed 2025-10-03 12:44:31.821088 | 2025-10-03 12:44:31.821153 | TASK [Setup log path fact] 2025-10-03 12:44:31.889564 | localhost | ok 2025-10-03 12:44:31.907654 | 2025-10-03 12:44:31.907723 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 12:44:32.006090 | localhost | ok 2025-10-03 12:44:32.030882 | 2025-10-03 12:44:32.030982 | TASK [emit-job-header : Print job information] 2025-10-03 12:44:32.111764 | # Job Information 2025-10-03 12:44:32.111931 | Ansible Version: 2.15.12 2025-10-03 12:44:32.111969 | Job: cifmw-molecule-ci_gen_kustomize_values 2025-10-03 12:44:32.111994 | Pipeline: github-check 2025-10-03 12:44:32.112014 | Executor: ibm-bm4-ze.softwarefactory-project.io 2025-10-03 12:44:32.112033 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3366 2025-10-03 12:44:32.112053 | Log URL (when completed): https://logserver.rdoproject.org/44e/rdoproject.org/44e01b237b474070b805b72fdd20144a/ 2025-10-03 12:44:32.112072 | Event ID: 8a294f00-a056-11f0-8eee-1e8238c96249 2025-10-03 12:44:32.117301 | 2025-10-03 12:44:32.117366 | LOOP [emit-job-header : Print node information] 2025-10-03 12:44:32.291043 | localhost | ok: 2025-10-03 12:44:32.291210 | localhost | # Node Information 2025-10-03 12:44:32.291239 | localhost | Inventory Hostname: controller 2025-10-03 12:44:32.291263 | localhost | Hostname: np0005468883 2025-10-03 12:44:32.291283 | localhost | Username: zuul 2025-10-03 12:44:32.291302 | localhost | Distro: CentOS 9 2025-10-03 12:44:32.291320 | localhost | Provider: ibm-bm4-nodepool 2025-10-03 12:44:32.291338 | localhost | Region: regionOne 2025-10-03 12:44:32.291357 | localhost | Label: cloud-centos-9-stream-tripleo-ibm 2025-10-03 12:44:32.291374 | localhost | Product Name: OpenStack Compute 2025-10-03 12:44:32.291392 | localhost | Interface IP: 192.168.26.223 2025-10-03 12:44:32.361427 | 2025-10-03 12:44:32.361514 | PLAY [all] 2025-10-03 12:44:32.381695 | 2025-10-03 12:44:32.381763 | TASK [Gather network facts] 2025-10-03 12:44:32.807005 | controller | ok 2025-10-03 12:44:32.845412 | 2025-10-03 12:44:32.845502 | TASK [include_role : start-zuul-console] 2025-10-03 12:44:32.880065 | controller | ok 2025-10-03 12:44:32.905647 | 2025-10-03 12:44:32.905719 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-03 12:44:33.665631 | controller | ok 2025-10-03 12:44:33.693957 | 2025-10-03 12:44:33.694961 | TASK [include_role : add-build-sshkey] 2025-10-03 12:44:33.746816 | controller | ok 2025-10-03 12:44:33.764875 | 2025-10-03 12:44:33.764977 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-03 12:44:34.333069 | controller -> localhost | ok 2025-10-03 12:44:34.338582 | 2025-10-03 12:44:34.338662 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-03 12:44:34.439625 | controller | ok 2025-10-03 12:44:34.484721 | controller | included: /var/lib/zuul/builds/44e01b237b474070b805b72fdd20144a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-03 12:44:34.510122 | 2025-10-03 12:44:34.510206 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-03 12:44:36.243132 | controller -> localhost | Generating public/private rsa key pair. 2025-10-03 12:44:36.243323 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/44e01b237b474070b805b72fdd20144a/work/44e01b237b474070b805b72fdd20144a_id_rsa. 2025-10-03 12:44:36.243355 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/44e01b237b474070b805b72fdd20144a/work/44e01b237b474070b805b72fdd20144a_id_rsa.pub. 2025-10-03 12:44:36.243379 | controller -> localhost | The key fingerprint is: 2025-10-03 12:44:36.243400 | controller -> localhost | SHA256:u4hHEvTcZTSxpoi57xBfDjxEgIuwrAjIck0AMhf+Wsg zuul-build-sshkey 2025-10-03 12:44:36.243419 | controller -> localhost | The key's randomart image is: 2025-10-03 12:44:36.243438 | controller -> localhost | +---[RSA 3072]----+ 2025-10-03 12:44:36.243455 | controller -> localhost | |+.++... .+. | 2025-10-03 12:44:36.243473 | controller -> localhost | |o+. o. oo | 2025-10-03 12:44:36.243491 | controller -> localhost | |=o.= o.. oo | 2025-10-03 12:44:36.243509 | controller -> localhost | |*+ooo=o..o | 2025-10-03 12:44:36.243527 | controller -> localhost | |=.E *.= S | 2025-10-03 12:44:36.243544 | controller -> localhost | |o o.+.= . | 2025-10-03 12:44:36.243561 | controller -> localhost | | . oo. o | 2025-10-03 12:44:36.243578 | controller -> localhost | | +.. . | 2025-10-03 12:44:36.243595 | controller -> localhost | | .o+ . | 2025-10-03 12:44:36.243611 | controller -> localhost | +----[SHA256]-----+ 2025-10-03 12:44:36.243650 | controller -> localhost | ok: Runtime: 0:00:00.578294 2025-10-03 12:44:36.249511 | 2025-10-03 12:44:36.249579 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-03 12:44:36.319970 | controller | ok 2025-10-03 12:44:36.349179 | controller | included: /var/lib/zuul/builds/44e01b237b474070b805b72fdd20144a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-03 12:44:36.406075 | 2025-10-03 12:44:36.406148 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-03 12:44:36.480590 | controller | skipping: Conditional result was False 2025-10-03 12:44:36.487200 | 2025-10-03 12:44:36.487283 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-03 12:44:37.524824 | controller | changed 2025-10-03 12:44:37.530687 | 2025-10-03 12:44:37.530753 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-03 12:44:37.813062 | controller | ok 2025-10-03 12:44:37.819076 | 2025-10-03 12:44:37.819145 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-03 12:44:38.804458 | controller | changed 2025-10-03 12:44:38.809414 | 2025-10-03 12:44:38.814608 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-03 12:44:39.641461 | controller | changed 2025-10-03 12:44:39.652302 | 2025-10-03 12:44:39.652381 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-03 12:44:39.726199 | controller | skipping: Conditional result was False 2025-10-03 12:44:39.732348 | 2025-10-03 12:44:39.732416 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-03 12:44:41.096411 | controller -> localhost | changed 2025-10-03 12:44:41.205124 | 2025-10-03 12:44:41.205203 | TASK [add-build-sshkey : Add back temp key] 2025-10-03 12:44:42.258067 | controller -> localhost | Identity added: /var/lib/zuul/builds/44e01b237b474070b805b72fdd20144a/work/44e01b237b474070b805b72fdd20144a_id_rsa (zuul-build-sshkey) 2025-10-03 12:44:42.258275 | controller -> localhost | ok: Runtime: 0:00:00.034820 2025-10-03 12:44:42.266242 | 2025-10-03 12:44:42.266310 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-03 12:44:43.032778 | controller | ok 2025-10-03 12:44:43.037343 | 2025-10-03 12:44:43.037401 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-03 12:44:43.151533 | controller | skipping: Conditional result was False 2025-10-03 12:44:43.163103 | 2025-10-03 12:44:43.163174 | TASK [include_role : validate-host] 2025-10-03 12:44:43.247001 | controller | ok 2025-10-03 12:44:43.318158 | 2025-10-03 12:44:43.318253 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-03 12:44:43.410390 | controller | ok 2025-10-03 12:44:43.415068 | 2025-10-03 12:44:43.415132 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-03 12:44:44.354253 | controller -> localhost | ok 2025-10-03 12:44:44.360643 | 2025-10-03 12:44:44.360717 | TASK [validate-host : Collect information about the host] 2025-10-03 12:44:45.084101 | controller | ok 2025-10-03 12:44:45.111357 | 2025-10-03 12:44:45.111464 | TASK [validate-host : Sanitize hostname] 2025-10-03 12:44:45.310014 | controller | ok 2025-10-03 12:44:45.314387 | 2025-10-03 12:44:45.314444 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-03 12:44:46.954669 | controller -> localhost | changed 2025-10-03 12:44:46.959545 | 2025-10-03 12:44:46.959607 | TASK [validate-host : Collect information about zuul worker] 2025-10-03 12:44:47.772085 | controller | ok 2025-10-03 12:44:47.776900 | 2025-10-03 12:44:47.776991 | TASK [validate-host : Write out all zuul information for each host] 2025-10-03 12:44:49.472757 | controller -> localhost | changed 2025-10-03 12:44:49.481589 | 2025-10-03 12:44:49.481654 | TASK [include_role : prepare-workspace-openshift] 2025-10-03 12:44:49.551564 | controller | skipping: Conditional result was False 2025-10-03 12:44:49.557008 | 2025-10-03 12:44:49.557075 | TASK [include_role : remove-zuul-sshkey] 2025-10-03 12:44:49.610860 | controller | skipping: Conditional result was False 2025-10-03 12:44:49.617132 | 2025-10-03 12:44:49.617202 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-03 12:44:49.942436 | controller | ok: "logs" 2025-10-03 12:44:49.942661 | controller | ok: All items complete 2025-10-03 12:44:49.942689 | 2025-10-03 12:44:50.158655 | controller | ok: "artifacts" 2025-10-03 12:44:50.396678 | controller | ok: "docs" 2025-10-03 12:44:50.412112 | 2025-10-03 12:44:50.412189 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-03 12:44:50.828310 | controller | changed: "logs" 2025-10-03 12:44:51.023382 | controller | changed: "artifacts" 2025-10-03 12:44:51.228520 | controller | changed: "docs" 2025-10-03 12:44:51.314972 | 2025-10-03 12:44:51.315051 | PLAY RECAP 2025-10-03 12:44:51.315726 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-03 12:44:51.315762 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 12:44:51.315784 | 2025-10-03 12:44:51.907860 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-03 12:44:51.908596 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-03 12:44:54.112937 | 2025-10-03 12:44:54.116024 | PLAY [all] 2025-10-03 12:44:54.227887 | 2025-10-03 12:44:54.228054 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-03 12:44:54.563362 | controller | ok 2025-10-03 12:44:54.568536 | 2025-10-03 12:44:54.568630 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-03 12:44:55.652679 | controller | changed 2025-10-03 12:44:55.660706 | 2025-10-03 12:44:55.660787 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-03 12:44:57.697428 | controller | changed 2025-10-03 12:44:57.740286 | 2025-10-03 12:44:57.740402 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-03 12:44:58.762371 | controller | changed: 2025-10-03 12:44:58.762774 | controller | { 2025-10-03 12:44:58.762814 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-03 12:44:58.762871 | controller | } 2025-10-03 12:44:59.019214 | controller | changed: 2025-10-03 12:44:59.019309 | controller | { 2025-10-03 12:44:59.019340 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-03 12:44:59.019363 | controller | } 2025-10-03 12:44:59.281727 | controller | changed: 2025-10-03 12:44:59.281806 | controller | { 2025-10-03 12:44:59.281833 | 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-03 12:44:59.281856 | controller | } 2025-10-03 12:44:59.507032 | controller | changed: 2025-10-03 12:44:59.507109 | controller | { 2025-10-03 12:44:59.507138 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-03 12:44:59.507161 | controller | } 2025-10-03 12:44:59.737893 | controller | changed: 2025-10-03 12:44:59.738022 | controller | { 2025-10-03 12:44:59.738051 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-03 12:44:59.738074 | controller | } 2025-10-03 12:44:59.967990 | controller | changed: 2025-10-03 12:44:59.968070 | controller | { 2025-10-03 12:44:59.968099 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-03 12:44:59.968121 | controller | } 2025-10-03 12:45:00.222494 | controller | changed: 2025-10-03 12:45:00.228000 | controller | { 2025-10-03 12:45:00.228042 | 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-03 12:45:00.228069 | controller | } 2025-10-03 12:45:00.578487 | controller | changed: 2025-10-03 12:45:00.578561 | controller | { 2025-10-03 12:45:00.578591 | 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-03 12:45:00.578617 | controller | } 2025-10-03 12:45:00.756159 | controller | changed: 2025-10-03 12:45:00.756244 | controller | { 2025-10-03 12:45:00.756274 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-03 12:45:00.756297 | controller | } 2025-10-03 12:45:00.983450 | controller | changed: 2025-10-03 12:45:00.983522 | controller | { 2025-10-03 12:45:00.983548 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-03 12:45:00.983570 | controller | } 2025-10-03 12:45:01.182222 | controller | changed: 2025-10-03 12:45:01.182294 | controller | { 2025-10-03 12:45:01.182322 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-03 12:45:01.182345 | controller | } 2025-10-03 12:45:01.408005 | controller | changed: 2025-10-03 12:45:01.408090 | controller | { 2025-10-03 12:45:01.408118 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-03 12:45:01.408139 | controller | } 2025-10-03 12:45:01.652161 | controller | changed: 2025-10-03 12:45:01.652242 | controller | { 2025-10-03 12:45:01.652270 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-03 12:45:01.652293 | controller | } 2025-10-03 12:45:01.877366 | controller | changed: 2025-10-03 12:45:01.877441 | controller | { 2025-10-03 12:45:01.877469 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-03 12:45:01.877490 | controller | } 2025-10-03 12:45:02.112776 | controller | changed: 2025-10-03 12:45:02.112848 | controller | { 2025-10-03 12:45:02.112875 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-03 12:45:02.112896 | controller | } 2025-10-03 12:45:02.346982 | controller | changed: 2025-10-03 12:45:02.347052 | controller | { 2025-10-03 12:45:02.347078 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-03 12:45:02.347099 | controller | } 2025-10-03 12:45:02.576939 | controller | changed: 2025-10-03 12:45:02.577014 | controller | { 2025-10-03 12:45:02.577042 | 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-03 12:45:02.577065 | controller | } 2025-10-03 12:45:02.800077 | controller | changed: 2025-10-03 12:45:02.800153 | controller | { 2025-10-03 12:45:02.800182 | 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-03 12:45:02.800212 | controller | } 2025-10-03 12:45:03.016693 | controller | changed: 2025-10-03 12:45:03.016767 | controller | { 2025-10-03 12:45:03.016795 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-03 12:45:03.016816 | controller | } 2025-10-03 12:45:03.271483 | controller | changed: 2025-10-03 12:45:03.271561 | controller | { 2025-10-03 12:45:03.271589 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-03 12:45:03.271610 | controller | } 2025-10-03 12:45:03.497295 | controller | changed: 2025-10-03 12:45:03.497441 | controller | { 2025-10-03 12:45:03.497477 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-03 12:45:03.497502 | controller | } 2025-10-03 12:45:03.725464 | controller | changed: 2025-10-03 12:45:03.725537 | controller | { 2025-10-03 12:45:03.725564 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-03 12:45:03.725585 | controller | } 2025-10-03 12:45:03.949370 | controller | changed: 2025-10-03 12:45:03.949446 | controller | { 2025-10-03 12:45:03.949477 | 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-03 12:45:03.949499 | controller | } 2025-10-03 12:45:04.176261 | controller | changed: 2025-10-03 12:45:04.176342 | controller | { 2025-10-03 12:45:04.176369 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-03 12:45:04.176390 | controller | } 2025-10-03 12:45:04.395826 | controller | changed: 2025-10-03 12:45:04.395948 | controller | { 2025-10-03 12:45:04.395983 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-03 12:45:04.396007 | controller | } 2025-10-03 12:45:04.608077 | controller | changed: 2025-10-03 12:45:04.608150 | controller | { 2025-10-03 12:45:04.608177 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-03 12:45:04.608199 | controller | } 2025-10-03 12:45:04.640217 | 2025-10-03 12:45:04.640288 | TASK [Set timezone to UTC] 2025-10-03 12:45:05.515988 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-03 12:45:05.522306 | 2025-10-03 12:45:05.522387 | TASK [Create nodepool directory] 2025-10-03 12:45:05.869825 | controller | changed 2025-10-03 12:45:05.878632 | 2025-10-03 12:45:05.878718 | TASK [Create nodepool sub_nodes file] 2025-10-03 12:45:06.721687 | controller | changed 2025-10-03 12:45:06.730764 | 2025-10-03 12:45:06.730900 | TASK [Create nodepool sub_nodes_private file] 2025-10-03 12:45:07.508345 | controller | changed 2025-10-03 12:45:07.513397 | 2025-10-03 12:45:07.520974 | LOOP [Populate nodepool sub_nodes file] 2025-10-03 12:45:07.619253 | 2025-10-03 12:45:07.619399 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-03 12:45:07.720285 | 2025-10-03 12:45:07.720508 | TASK [Create nodepool primary file] 2025-10-03 12:45:07.830972 | controller | skipping: Conditional result was False 2025-10-03 12:45:07.836820 | 2025-10-03 12:45:07.836895 | TASK [Create nodepool node_private for this node] 2025-10-03 12:45:08.663561 | controller | changed 2025-10-03 12:45:08.669130 | 2025-10-03 12:45:08.669198 | LOOP [Copy ssh keys to nodepool directory] 2025-10-03 12:45:09.787872 | controller | ok: Item: id_rsa Runtime: 0:00:00.005397 2025-10-03 12:45:09.788161 | 2025-10-03 12:45:09.951184 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004401 2025-10-03 12:45:09.959588 | 2025-10-03 12:45:09.959694 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-03 12:45:10.754885 | controller | changed 2025-10-03 12:45:10.762580 | 2025-10-03 12:45:10.762649 | TASK [Validate sudoers config after edits] 2025-10-03 12:45:11.312972 | controller | /etc/sudoers: parsed OK 2025-10-03 12:45:11.313035 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-03 12:45:11.313046 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-03 12:45:11.313054 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-03 12:45:11.416073 | controller | ok: Runtime: 0:00:00.004586 2025-10-03 12:45:11.421204 | 2025-10-03 12:45:11.421269 | TASK [Show the environment passed in to job shell scripts] 2025-10-03 12:45:11.929987 | controller | SHELL=/bin/bash 2025-10-03 12:45:11.930036 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-03 12:45:11.930046 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-03 12:45:11.930052 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/66/3366/a1071ae3d6aee1b042b6027c89930a1199768d59 2025-10-03 12:45:11.930058 | controller | PWD=/home/zuul 2025-10-03 12:45:11.930063 | controller | ZUUL_PIPELINE=github-check 2025-10-03 12:45:11.930069 | controller | LOGNAME=zuul 2025-10-03 12:45:11.930093 | controller | XDG_SESSION_TYPE=tty 2025-10-03 12:45:11.930100 | controller | _=/usr/bin/env 2025-10-03 12:45:11.930105 | controller | MOTD_SHOWN=pam 2025-10-03 12:45:11.930110 | controller | HOME=/home/zuul 2025-10-03 12:45:11.930115 | controller | LANG=en_US.UTF-8 2025-10-03 12:45:11.930121 | controller | SSH_CONNECTION=192.168.26.12 44196 192.168.26.223 22 2025-10-03 12:45:11.930126 | 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-03 12:45:11.930134 | controller | ZUUL_CHANGE_IDS=3366,a1071ae3d6aee1b042b6027c89930a1199768d59 2025-10-03 12:45:11.930139 | controller | WORKSPACE=/home/zuul/workspace 2025-10-03 12:45:11.930144 | controller | XDG_SESSION_CLASS=user 2025-10-03 12:45:11.930149 | controller | SELINUX_ROLE_REQUESTED= 2025-10-03 12:45:11.930155 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-03 12:45:11.930160 | controller | USER=zuul 2025-10-03 12:45:11.930165 | controller | ZUUL_VOTING=True 2025-10-03 12:45:11.930170 | controller | BUILD_TIMEOUT=1800000 2025-10-03 12:45:11.930175 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-03 12:45:11.930180 | controller | SHLVL=1 2025-10-03 12:45:11.930185 | controller | ZUUL_PATCHSET=a1071ae3d6aee1b042b6027c89930a1199768d59 2025-10-03 12:45:11.930191 | controller | XDG_SESSION_ID=1 2025-10-03 12:45:11.930196 | controller | ZUUL_BRANCH=main 2025-10-03 12:45:11.930201 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-03 12:45:11.930206 | controller | SSH_CLIENT=192.168.26.12 44196 22 2025-10-03 12:45:11.930211 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-03 12:45:11.930216 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-03 12:45:11.930221 | controller | which_declare=declare -f 2025-10-03 12:45:11.930227 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-03 12:45:11.930232 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-03 12:45:11.930237 | controller | ZUUL_CHANGE=3366 2025-10-03 12:45:11.930242 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-03 12:45:11.930247 | controller | ZUUL_UUID=44e01b237b474070b805b72fdd20144a 2025-10-03 12:45:11.930254 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-03 12:45:11.930259 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-03 12:45:11.930265 | controller | } 2025-10-03 12:45:12.032274 | controller | ok: Runtime: 0:00:00.005148 2025-10-03 12:45:12.037779 | 2025-10-03 12:45:12.037841 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-03 12:45:12.135941 | controller | skipping: Conditional result was False 2025-10-03 12:45:12.142623 | 2025-10-03 12:45:12.142702 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-03 12:45:12.873674 | controller | skipping: Conditional result was False 2025-10-03 12:45:12.879747 | 2025-10-03 12:45:12.880006 | TASK [Ensure legacy workspace directory] 2025-10-03 12:45:13.185636 | controller | changed 2025-10-03 12:45:13.286145 | 2025-10-03 12:45:13.286229 | PLAY RECAP 2025-10-03 12:45:13.286277 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-03 12:45:13.286301 | 2025-10-03 12:45:13.809507 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-03 12:45:13.810304 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-03 12:45:16.836367 | 2025-10-03 12:45:16.836476 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-03 12:45:16.957736 | 2025-10-03 12:45:16.957851 | TASK [Create zuul-output directory] 2025-10-03 12:45:17.927943 | controller | changed 2025-10-03 12:45:17.934728 | 2025-10-03 12:45:17.934809 | TASK [Slurp Zuul inventory test] 2025-10-03 12:45:19.469559 | controller -> localhost | ok 2025-10-03 12:45:19.476116 | 2025-10-03 12:45:19.476201 | TASK [Save zuul inventory] 2025-10-03 12:45:21.313473 | controller | changed 2025-10-03 12:45:21.324286 | 2025-10-03 12:45:21.324353 | TASK [Save zuul vars without the change_message] 2025-10-03 12:45:22.478086 | controller | changed 2025-10-03 12:45:22.594897 | 2025-10-03 12:45:22.595018 | PLAY RECAP 2025-10-03 12:45:22.595065 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 12:45:22.595089 | 2025-10-03 12:45:23.054239 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-03 12:45:23.054995 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-03 12:45:26.183531 | 2025-10-03 12:45:26.183650 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-10-03 12:45:26.361157 | 2025-10-03 12:45:26.361270 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-10-03 12:45:26.455965 | controller | ok 2025-10-03 12:45:26.529202 | 2025-10-03 12:45:26.529325 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-03 12:45:26.672231 | controller | skipping: Conditional result was False 2025-10-03 12:45:26.684119 | 2025-10-03 12:45:26.684200 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-03 12:45:27.733232 | controller | ok 2025-10-03 12:45:27.753131 | 2025-10-03 12:45:27.753206 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-03 12:45:29.027827 | controller | ok 2025-10-03 12:45:29.067224 | 2025-10-03 12:45:29.067313 | TASK [Prepare workspace] 2025-10-03 12:45:29.146030 | controller | ok 2025-10-03 12:45:29.272718 | 2025-10-03 12:45:29.274359 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-03 12:45:30.219733 | controller | ok 2025-10-03 12:45:30.228158 | 2025-10-03 12:45:30.228226 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-03 12:45:39.060344 | controller | Output suppressed because no_log was given 2025-10-03 12:45:39.093879 | 2025-10-03 12:45:39.094012 | LOOP [Create zuul-output directory] 2025-10-03 12:45:39.413786 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-10-03 12:45:39.564579 | controller | ok: "/home/zuul/zuul-output/logs" 2025-10-03 12:45:39.579388 | 2025-10-03 12:45:39.579516 | TASK [Install required packages] 2025-10-03 12:46:29.968582 | controller | changed 2025-10-03 12:46:29.973739 | 2025-10-03 12:46:29.973814 | TASK [Install venv] 2025-10-03 12:47:33.650538 | controller | changed 2025-10-03 12:47:33.678364 | 2025-10-03 12:47:33.678451 | PLAY RECAP 2025-10-03 12:47:33.678497 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-03 12:47:33.678522 | 2025-10-03 12:47:33.775577 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-03 12:47:33.776466 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-03 12:47:34.315603 | 2025-10-03 12:47:34.315719 | PLAY [Run ci/playbooks/molecule-test.yml] 2025-10-03 12:47:34.335844 | 2025-10-03 12:47:34.335937 | TASK [Gather required facts] 2025-10-03 12:47:34.837850 | controller | ok 2025-10-03 12:47:34.843711 | 2025-10-03 12:47:34.843786 | TASK [Load environment var if instructed to] 2025-10-03 12:47:34.867430 | controller | skipping: Conditional result was False 2025-10-03 12:47:34.874234 | 2025-10-03 12:47:34.874308 | TASK [Check if group_vars dir exists] 2025-10-03 12:47:35.219575 | controller | ok 2025-10-03 12:47:35.226546 | 2025-10-03 12:47:35.226618 | TASK [Create group_vars dir if does not exist] 2025-10-03 12:47:35.256344 | controller | ERROR 2025-10-03 12:47:35.256529 | controller | { 2025-10-03 12:47:35.256562 | controller | "msg": "The conditional check 'not group_vars_dir.stat.exits' failed. The error was: error while evaluating conditional (not group_vars_dir.stat.exits): 'dict object' has no attribute 'exits'. 'dict object' has no attribute 'exits'\n\nThe error appears to be in '/var/lib/zuul/builds/44e01b237b474070b805b72fdd20144a/untrusted/project_0/github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml': line 25, column 7, but may\nbe elsewhere in the file depending on the exact syntax problem.\n\nThe offending line appears to be:\n\n\n - name: Create group_vars dir if does not exist\n ^ here\n" 2025-10-03 12:47:35.256588 | controller | } failure 2025-10-03 12:47:35.257638 | 2025-10-03 12:47:35.257706 | PLAY RECAP 2025-10-03 12:47:35.257753 | controller | ok: 2 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0 2025-10-03 12:47:35.257776 | 2025-10-03 12:47:35.352775 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-03 12:47:35.353558 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-03 12:47:35.934439 | 2025-10-03 12:47:35.934543 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-03 12:47:35.954845 | 2025-10-03 12:47:35.954939 | TASK [Filter out host if needed] 2025-10-03 12:47:35.963413 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-03 12:47:35.968006 | 2025-10-03 12:47:35.968076 | TASK [Ensure file is present] 2025-10-03 12:47:36.270809 | controller | ok 2025-10-03 12:47:36.278202 | 2025-10-03 12:47:36.278277 | TASK [Manage molecule report file] 2025-10-03 12:47:36.806053 | controller | skipping: Conditional result was False 2025-10-03 12:47:36.816130 | 2025-10-03 12:47:36.816246 | TASK [Check if we get ci-framework-data basedir] 2025-10-03 12:47:37.021496 | controller | ok 2025-10-03 12:47:37.026734 | 2025-10-03 12:47:37.026808 | TASK [Create ci-framework-data log directory for zuul] 2025-10-03 12:47:37.476769 | controller | changed 2025-10-03 12:47:37.481948 | 2025-10-03 12:47:37.482016 | TASK [Copy ci-framework interesting files] 2025-10-03 12:47:37.814807 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-10-03 12:47:38.008777 | controller | changed 2025-10-03 12:47:38.014181 | 2025-10-03 12:47:38.014260 | TASK [Get SELinux listing] 2025-10-03 12:47:38.540295 | controller | changed 2025-10-03 12:47:38.546879 | 2025-10-03 12:47:38.546982 | TASK [Generate log index] 2025-10-03 12:47:39.184819 | controller | changed 2025-10-03 12:47:39.190278 | 2025-10-03 12:47:39.190354 | TASK [Get some env related data] 2025-10-03 12:47:39.730519 | controller | /home/zuul/.local/bin/ansible 2025-10-03 12:47:40.732606 | controller | changed 2025-10-03 12:47:40.738281 | 2025-10-03 12:47:40.738371 | TASK [Generate list of logs to collect in home directory] 2025-10-03 12:47:41.040006 | controller | ok: All paths examined 2025-10-03 12:47:41.045256 | 2025-10-03 12:47:41.045322 | LOOP [Copy logs from home directory] 2025-10-03 12:47:41.333454 | controller | changed: 2025-10-03 12:47:41.334020 | controller | { 2025-10-03 12:47:41.334075 | controller | "atime": 1759495636.1240404, 2025-10-03 12:47:41.334101 | controller | "ctime": 1759495653.208187, 2025-10-03 12:47:41.334122 | controller | "dev": 64513, 2025-10-03 12:47:41.334142 | controller | "gid": 1000, 2025-10-03 12:47:41.334161 | controller | "gr_name": "zuul", 2025-10-03 12:47:41.334179 | controller | "inode": 4450430, 2025-10-03 12:47:41.334197 | controller | "isblk": false, 2025-10-03 12:47:41.334214 | controller | "ischr": false, 2025-10-03 12:47:41.334230 | controller | "isdir": false, 2025-10-03 12:47:41.334246 | controller | "isfifo": false, 2025-10-03 12:47:41.334262 | controller | "isgid": false, 2025-10-03 12:47:41.334278 | controller | "islnk": false, 2025-10-03 12:47:41.334293 | controller | "isreg": true, 2025-10-03 12:47:41.334308 | controller | "issock": false, 2025-10-03 12:47:41.334323 | controller | "isuid": false, 2025-10-03 12:47:41.334337 | controller | "mode": "0644", 2025-10-03 12:47:41.334352 | controller | "mtime": 1759495653.208187, 2025-10-03 12:47:41.334367 | controller | "nlink": 1, 2025-10-03 12:47:41.334383 | controller | "path": "/home/zuul/ansible.log", 2025-10-03 12:47:41.334404 | controller | "pw_name": "zuul", 2025-10-03 12:47:41.334421 | controller | "rgrp": true, 2025-10-03 12:47:41.334437 | controller | "roth": true, 2025-10-03 12:47:41.334452 | controller | "rusr": true, 2025-10-03 12:47:41.334467 | controller | "size": 6749, 2025-10-03 12:47:41.334482 | controller | "uid": 1000, 2025-10-03 12:47:41.334497 | controller | "wgrp": false, 2025-10-03 12:47:41.334511 | controller | "woth": false, 2025-10-03 12:47:41.334528 | controller | "wusr": true, 2025-10-03 12:47:41.334543 | controller | "xgrp": false, 2025-10-03 12:47:41.334558 | controller | "xoth": false, 2025-10-03 12:47:41.334572 | controller | "xusr": false 2025-10-03 12:47:41.334586 | controller | } 2025-10-03 12:47:41.373140 | 2025-10-03 12:47:41.373238 | TASK [Copy crio stats log file] 2025-10-03 12:47:41.387187 | controller | skipping: Conditional result was False 2025-10-03 12:47:41.394425 | 2025-10-03 12:47:41.394495 | TASK [Get SELinux related data] 2025-10-03 12:47:41.600815 | controller | 2025-10-03 12:47:41.922230 | controller | ERROR 2025-10-03 12:47:41.922347 | controller | { 2025-10-03 12:47:41.922379 | controller | "delta": "0:00:00.010402", 2025-10-03 12:47:41.922399 | controller | "end": "2025-10-03 12:47:41.601814", 2025-10-03 12:47:41.922418 | controller | "msg": "non-zero return code", 2025-10-03 12:47:41.922434 | controller | "rc": 1, 2025-10-03 12:47:41.922449 | controller | "start": "2025-10-03 12:47:41.591412" 2025-10-03 12:47:41.922465 | controller | } 2025-10-03 12:47:41.922485 | controller | ERROR: Ignoring Errors 2025-10-03 12:47:41.935223 | 2025-10-03 12:47:41.935312 | TASK [Create system configuration directory] 2025-10-03 12:47:42.126665 | controller | changed 2025-10-03 12:47:42.132050 | 2025-10-03 12:47:42.132116 | TASK [Get some of the system configurations] 2025-10-03 12:47:42.661928 | controller | changed 2025-10-03 12:47:42.668786 | 2025-10-03 12:47:42.668863 | TASK [Copy generated documentation if available] 2025-10-03 12:47:42.692538 | controller | skipping: Conditional result was False 2025-10-03 12:47:42.698579 | 2025-10-03 12:47:42.698650 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-03 12:47:42.712000 | controller | skipping: Conditional result was False 2025-10-03 12:47:42.718442 | 2025-10-03 12:47:42.718506 | TASK [Compress logs bigger than 2MB] 2025-10-03 12:47:43.246601 | controller | changed 2025-10-03 12:47:43.252383 | 2025-10-03 12:47:43.252450 | TASK [Copy files from workspace on node] 2025-10-03 12:47:43.270577 | controller | ok 2025-10-03 12:47:43.294703 | 2025-10-03 12:47:43.294795 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-03 12:47:43.318964 | controller | skipping: Conditional result was False 2025-10-03 12:47:43.324796 | 2025-10-03 12:47:43.324866 | TASK [fetch-output : Set log path for single node] 2025-10-03 12:47:43.352878 | controller | ok 2025-10-03 12:47:43.357967 | 2025-10-03 12:47:43.358034 | LOOP [fetch-output : Ensure local output dirs] 2025-10-03 12:47:43.618043 | controller -> localhost | ok: "/var/lib/zuul/builds/44e01b237b474070b805b72fdd20144a/work/logs" 2025-10-03 12:47:43.618306 | controller -> localhost | changed: All items complete 2025-10-03 12:47:43.618336 | 2025-10-03 12:47:43.855701 | controller -> localhost | changed: "/var/lib/zuul/builds/44e01b237b474070b805b72fdd20144a/work/artifacts" 2025-10-03 12:47:44.156264 | controller -> localhost | changed: "/var/lib/zuul/builds/44e01b237b474070b805b72fdd20144a/work/docs" 2025-10-03 12:47:44.171136 | 2025-10-03 12:47:44.171237 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-03 12:47:44.725208 | controller | changed: 2025-10-03 12:47:44.725388 | controller | .d..t...... ./ 2025-10-03 12:47:44.725424 | controller | >f+++++++++ README.html 2025-10-03 12:47:44.725446 | controller | >f+++++++++ ansible.log 2025-10-03 12:47:44.725466 | controller | >f+++++++++ dmesg.log 2025-10-03 12:47:44.725486 | controller | >f+++++++++ installed-pkgs.log 2025-10-03 12:47:44.725505 | controller | >f+++++++++ python.log 2025-10-03 12:47:44.725525 | controller | >f+++++++++ registries.conf 2025-10-03 12:47:44.725544 | controller | >f+++++++++ selinux-denials.log 2025-10-03 12:47:44.725561 | controller | >f+++++++++ selinux-listing.log 2025-10-03 12:47:44.725580 | controller | cd+++++++++ ci-framework-data/ 2025-10-03 12:47:44.725597 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-03 12:47:44.725615 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-03 12:47:44.725633 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-03 12:47:44.725649 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-10-03 12:47:44.725666 | controller | cd+++++++++ ci-framework-data/logs/ 2025-10-03 12:47:44.725682 | controller | cd+++++++++ registries.conf.d/ 2025-10-03 12:47:44.725699 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-10-03 12:47:44.725714 | controller | cd+++++++++ system-config/ 2025-10-03 12:47:44.725730 | controller | cd+++++++++ system-config/libvirt/ 2025-10-03 12:47:44.725746 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-10-03 12:47:44.725778 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-10-03 12:47:45.103860 | controller | changed: .d..t...... ./ 2025-10-03 12:47:45.542785 | controller | changed: .d..t...... ./ 2025-10-03 12:47:45.559026 | 2025-10-03 12:47:45.559135 | TASK [Return artifact to Zuul] 2025-10-03 12:47:45.577705 | controller | skipping: Conditional result was False 2025-10-03 12:47:45.604248 | 2025-10-03 12:47:45.604336 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-03 12:47:45.604442 | 2025-10-03 12:47:45.604470 | PLAY RECAP 2025-10-03 12:47:45.604510 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1 2025-10-03 12:47:45.604532 | 2025-10-03 12:47:45.686229 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-03 12:47:45.686992 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-03 12:47:46.341844 | 2025-10-03 12:47:46.341977 | PLAY [all] 2025-10-03 12:47:46.365428 | 2025-10-03 12:47:46.365550 | TASK [include_role : fetch-output] 2025-10-03 12:47:46.405145 | controller | ok 2025-10-03 12:47:46.432755 | 2025-10-03 12:47:46.432895 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-03 12:47:46.477344 | controller | skipping: Conditional result was False 2025-10-03 12:47:46.483156 | 2025-10-03 12:47:46.483236 | TASK [fetch-output : Set log path for single node] 2025-10-03 12:47:46.521429 | controller | ok 2025-10-03 12:47:46.526419 | 2025-10-03 12:47:46.526488 | LOOP [fetch-output : Ensure local output dirs] 2025-10-03 12:47:46.882164 | controller -> localhost | ok: "/var/lib/zuul/builds/44e01b237b474070b805b72fdd20144a/work/logs" 2025-10-03 12:47:47.235997 | controller -> localhost | ok: "/var/lib/zuul/builds/44e01b237b474070b805b72fdd20144a/work/artifacts" 2025-10-03 12:47:47.443796 | controller -> localhost | ok: "/var/lib/zuul/builds/44e01b237b474070b805b72fdd20144a/work/docs" 2025-10-03 12:47:47.459503 | 2025-10-03 12:47:47.459607 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-03 12:47:48.058040 | controller | ok 2025-10-03 12:47:48.059986 | controller | ok: All items complete 2025-10-03 12:47:48.060033 | 2025-10-03 12:47:48.465497 | controller | ok 2025-10-03 12:47:48.860629 | controller | ok 2025-10-03 12:47:48.891289 | 2025-10-03 12:47:48.891392 | TASK [include_role : fetch-output-openshift] 2025-10-03 12:47:48.906108 | controller | skipping: Conditional result was False 2025-10-03 12:47:48.912400 | 2025-10-03 12:47:48.912483 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-03 12:47:49.282436 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006652 2025-10-03 12:47:49.489941 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006746 2025-10-03 12:47:49.520864 | 2025-10-03 12:47:49.521006 | PLAY [all] 2025-10-03 12:47:49.536236 | 2025-10-03 12:47:49.536311 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-03 12:47:49.957548 | controller | changed 2025-10-03 12:47:49.982675 | 2025-10-03 12:47:49.982747 | PLAY RECAP 2025-10-03 12:47:49.982805 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-03 12:47:49.982830 | 2025-10-03 12:47:50.058197 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-03 12:47:50.058954 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-03 12:47:50.669949 | 2025-10-03 12:47:50.670084 | PLAY [localhost] 2025-10-03 12:47:50.692229 | 2025-10-03 12:47:50.692312 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-03 12:47:51.042922 | localhost | changed 2025-10-03 12:47:51.047618 | 2025-10-03 12:47:51.047699 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-03 12:47:51.096904 | localhost | ok 2025-10-03 12:47:51.106196 | 2025-10-03 12:47:51.106278 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-03 12:47:51.484495 | localhost | changed 2025-10-03 12:47:51.493234 | 2025-10-03 12:47:51.493311 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-03 12:47:52.164019 | localhost | changed 2025-10-03 12:47:52.169473 | 2025-10-03 12:47:52.169612 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-03 12:47:52.555380 | localhost | Identity added: /var/lib/zuul/builds/44e01b237b474070b805b72fdd20144a/work/tmp/ansible.rq6ex66z (/var/lib/zuul/builds/44e01b237b474070b805b72fdd20144a/work/tmp/ansible.rq6ex66z) 2025-10-03 12:47:52.555534 | localhost | ok: Runtime: 0:00:00.012260 2025-10-03 12:47:52.560066 | 2025-10-03 12:47:52.560134 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-03 12:47:52.806347 | localhost | ok: Runtime: 0:00:00.005002 2025-10-03 12:47:52.811151 | 2025-10-03 12:47:52.811220 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-03 12:47:52.860274 | localhost | changed 2025-10-03 12:47:52.864788 | 2025-10-03 12:47:52.864877 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-03 12:47:53.248511 | localhost | changed 2025-10-03 12:47:53.267682 | 2025-10-03 12:47:53.267742 | PLAY [localhost] 2025-10-03 12:47:53.285266 | 2025-10-03 12:47:53.285336 | TASK [Generate bulk log download script] 2025-10-03 12:47:53.302360 | localhost | ok 2025-10-03 12:47:53.315011 | 2025-10-03 12:47:53.315106 | TASK [local-log-download : Check API endpoint is defined] 2025-10-03 12:47:53.352500 | localhost | ok: All assertions passed 2025-10-03 12:47:53.357060 | 2025-10-03 12:47:53.357128 | TASK [local-log-download : Create download script] 2025-10-03 12:47:53.848195 | localhost -> localhost | changed 2025-10-03 12:47:53.857545 | 2025-10-03 12:47:53.857622 | TASK [Register quick-download link] 2025-10-03 12:47:53.887856 | localhost | ok 2025-10-03 12:47:53.943085 | 2025-10-03 12:47:53.943163 | PLAY [logserver.rdoproject.org] 2025-10-03 12:47:53.953219 | 2025-10-03 12:47:53.953287 | TASK [Set zuul-log-path fact] 2025-10-03 12:47:53.969840 | logserver.rdoproject.org | ok 2025-10-03 12:47:53.979886 | 2025-10-03 12:47:53.979978 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 12:47:54.016563 | logserver.rdoproject.org | ok 2025-10-03 12:47:54.022277 | 2025-10-03 12:47:54.022345 | TASK [upload-logs : Create log directories] 2025-10-03 12:47:55.779850 | logserver.rdoproject.org | changed 2025-10-03 12:47:55.783412 | 2025-10-03 12:47:55.783478 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-03 12:47:56.249958 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.011416 2025-10-03 12:47:56.256672 | 2025-10-03 12:47:56.256782 | TASK [upload-logs : Upload logs to log server] 2025-10-03 12:47:57.851172 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-03 12:47:57.854552 | 2025-10-03 12:47:57.854643 | LOOP [upload-logs : Compress console log and json output] 2025-10-03 12:47:57.912301 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 12:47:57.922109 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 12:47:57.927071 | 2025-10-03 12:47:57.927172 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-03 12:47:57.970513 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 12:47:57.970806 | 2025-10-03 12:47:57.973479 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 12:47:57.986386 | 2025-10-03 12:47:57.986819 | LOOP [upload-logs : Upload console log and json output]