2025-10-13 06:55:02.581790 | Job console starting... 2025-10-13 06:55:03.057825 | Updating repositories 2025-10-13 06:55:04.009490 | Preparing job workspace 2025-10-13 06:55:24.522587 | Running Ansible setup... 2025-10-13 06:55:36.549922 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-13 06:55:39.593312 | 2025-10-13 06:55:39.593435 | PLAY [localhost] 2025-10-13 06:55:39.639947 | 2025-10-13 06:55:39.640064 | TASK [Gathering Facts] 2025-10-13 06:55:43.537439 | localhost | ok 2025-10-13 06:55:43.550356 | 2025-10-13 06:55:43.550440 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-13 06:55:45.971428 | localhost -> localhost | changed 2025-10-13 06:55:45.977128 | 2025-10-13 06:55:45.977212 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-13 06:55:50.860261 | localhost -> localhost | changed 2025-10-13 06:55:50.868731 | 2025-10-13 06:55:50.868801 | TASK [Setup log path fact] 2025-10-13 06:55:50.928115 | localhost | ok 2025-10-13 06:55:50.961838 | 2025-10-13 06:55:50.961946 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-13 06:55:51.058689 | localhost | ok 2025-10-13 06:55:51.075343 | 2025-10-13 06:55:51.075418 | TASK [emit-job-header : Print job information] 2025-10-13 06:55:51.176906 | # Job Information 2025-10-13 06:55:51.177169 | Ansible Version: 2.15.12 2025-10-13 06:55:51.177201 | Job: cifmw-molecule-edpm_build_images 2025-10-13 06:55:51.177226 | Pipeline: github-check 2025-10-13 06:55:51.177247 | Executor: ibm-bm4-ze.softwarefactory-project.io 2025-10-13 06:55:51.177268 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3237 2025-10-13 06:55:51.177288 | Log URL (when completed): https://logserver.rdoproject.org/4b1/rdoproject.org/4b10ea63c51940789cc5dc32079f94d6/ 2025-10-13 06:55:51.177308 | Event ID: 0a621880-a801-11f0-8fd5-3020d4b2b2c2 2025-10-13 06:55:51.188646 | 2025-10-13 06:55:51.188714 | LOOP [emit-job-header : Print node information] 2025-10-13 06:55:51.793656 | localhost | ok: 2025-10-13 06:55:51.793807 | localhost | # Node Information 2025-10-13 06:55:51.793839 | localhost | Inventory Hostname: controller 2025-10-13 06:55:51.793867 | localhost | Hostname: np0005482650 2025-10-13 06:55:51.793891 | localhost | Username: zuul 2025-10-13 06:55:51.793960 | localhost | Distro: CentOS 9 2025-10-13 06:55:51.793992 | localhost | Provider: ibm-bm4-nodepool 2025-10-13 06:55:51.794015 | localhost | Region: regionOne 2025-10-13 06:55:51.794046 | localhost | Label: cloud-centos-9-stream-tripleo-ibm 2025-10-13 06:55:51.794075 | localhost | Product Name: OpenStack Compute 2025-10-13 06:55:51.794096 | localhost | Interface IP: 192.168.26.203 2025-10-13 06:55:51.926987 | 2025-10-13 06:55:51.927080 | PLAY [all] 2025-10-13 06:55:51.976215 | 2025-10-13 06:55:51.976311 | TASK [Gather network facts] 2025-10-13 06:55:52.444558 | controller | ok 2025-10-13 06:55:52.542742 | 2025-10-13 06:55:52.542844 | TASK [include_role : start-zuul-console] 2025-10-13 06:55:52.625976 | controller | ok 2025-10-13 06:55:52.692402 | 2025-10-13 06:55:52.692501 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-13 06:55:53.603924 | controller | ok 2025-10-13 06:55:53.612605 | 2025-10-13 06:55:53.612682 | TASK [include_role : add-build-sshkey] 2025-10-13 06:55:53.745261 | controller | ok 2025-10-13 06:55:53.778188 | 2025-10-13 06:55:53.778272 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-13 06:55:55.061679 | controller -> localhost | ok 2025-10-13 06:55:55.067735 | 2025-10-13 06:55:55.067806 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-13 06:55:55.232385 | controller | ok 2025-10-13 06:55:55.287852 | controller | included: /var/lib/zuul/builds/4b10ea63c51940789cc5dc32079f94d6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-13 06:55:55.337079 | 2025-10-13 06:55:55.337188 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-13 06:55:58.226143 | controller -> localhost | Generating public/private rsa key pair. 2025-10-13 06:55:58.226290 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4b10ea63c51940789cc5dc32079f94d6/work/4b10ea63c51940789cc5dc32079f94d6_id_rsa. 2025-10-13 06:55:58.226325 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4b10ea63c51940789cc5dc32079f94d6/work/4b10ea63c51940789cc5dc32079f94d6_id_rsa.pub. 2025-10-13 06:55:58.226350 | controller -> localhost | The key fingerprint is: 2025-10-13 06:55:58.226371 | controller -> localhost | SHA256:RUFBy8sefg5WDNhWe3RLlTvsjny9dUD4K3keZDQ0lzw zuul-build-sshkey 2025-10-13 06:55:58.226391 | controller -> localhost | The key's randomart image is: 2025-10-13 06:55:58.226409 | controller -> localhost | +---[RSA 3072]----+ 2025-10-13 06:55:58.226427 | controller -> localhost | | o*o. =.B| 2025-10-13 06:55:58.226445 | controller -> localhost | | = o =.E.| 2025-10-13 06:55:58.226462 | controller -> localhost | | . B o *.o| 2025-10-13 06:55:58.226481 | controller -> localhost | | + + = = | 2025-10-13 06:55:58.226865 | controller -> localhost | | S + o * .| 2025-10-13 06:55:58.226894 | controller -> localhost | | o o + + | 2025-10-13 06:55:58.226935 | controller -> localhost | | = = * +| 2025-10-13 06:55:58.226959 | controller -> localhost | | . + * ++| 2025-10-13 06:55:58.226978 | controller -> localhost | | . o..| 2025-10-13 06:55:58.226995 | controller -> localhost | +----[SHA256]-----+ 2025-10-13 06:55:58.227033 | controller -> localhost | ok: Runtime: 0:00:00.655556 2025-10-13 06:55:58.232690 | 2025-10-13 06:55:58.232755 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-13 06:55:58.308433 | controller | ok 2025-10-13 06:55:58.337744 | controller | included: /var/lib/zuul/builds/4b10ea63c51940789cc5dc32079f94d6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-13 06:55:58.385025 | 2025-10-13 06:55:58.385118 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-13 06:55:58.475838 | controller | skipping: Conditional result was False 2025-10-13 06:55:58.481225 | 2025-10-13 06:55:58.481297 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-13 06:55:59.363689 | controller | changed 2025-10-13 06:55:59.376149 | 2025-10-13 06:55:59.376215 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-13 06:55:59.677549 | controller | ok 2025-10-13 06:55:59.683002 | 2025-10-13 06:55:59.683099 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-13 06:56:00.601926 | controller | changed 2025-10-13 06:56:00.607375 | 2025-10-13 06:56:00.607446 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-13 06:56:01.478359 | controller | changed 2025-10-13 06:56:01.483573 | 2025-10-13 06:56:01.483638 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-13 06:56:01.594461 | controller | skipping: Conditional result was False 2025-10-13 06:56:01.600268 | 2025-10-13 06:56:01.600343 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-13 06:56:03.265416 | controller -> localhost | changed 2025-10-13 06:56:03.281427 | 2025-10-13 06:56:03.281510 | TASK [add-build-sshkey : Add back temp key] 2025-10-13 06:56:04.477218 | controller -> localhost | Identity added: /var/lib/zuul/builds/4b10ea63c51940789cc5dc32079f94d6/work/4b10ea63c51940789cc5dc32079f94d6_id_rsa (zuul-build-sshkey) 2025-10-13 06:56:04.477400 | controller -> localhost | ok: Runtime: 0:00:00.020419 2025-10-13 06:56:04.484900 | 2025-10-13 06:56:04.484990 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-13 06:56:05.113594 | controller | ok 2025-10-13 06:56:05.123243 | 2025-10-13 06:56:05.123326 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-13 06:56:05.250292 | controller | skipping: Conditional result was False 2025-10-13 06:56:05.263537 | 2025-10-13 06:56:05.263620 | TASK [include_role : validate-host] 2025-10-13 06:56:05.342577 | controller | ok 2025-10-13 06:56:05.509037 | 2025-10-13 06:56:05.509158 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-13 06:56:05.626132 | controller | ok 2025-10-13 06:56:05.632012 | 2025-10-13 06:56:05.632099 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-13 06:56:06.800250 | controller -> localhost | ok 2025-10-13 06:56:06.805976 | 2025-10-13 06:56:06.806041 | TASK [validate-host : Collect information about the host] 2025-10-13 06:56:07.492086 | controller | ok 2025-10-13 06:56:07.524540 | 2025-10-13 06:56:07.524641 | TASK [validate-host : Sanitize hostname] 2025-10-13 06:56:07.711500 | controller | ok 2025-10-13 06:56:07.720408 | 2025-10-13 06:56:07.720488 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-13 06:56:11.002944 | controller -> localhost | changed 2025-10-13 06:56:11.008825 | 2025-10-13 06:56:11.008900 | TASK [validate-host : Collect information about zuul worker] 2025-10-13 06:56:11.831000 | controller | ok 2025-10-13 06:56:11.835855 | 2025-10-13 06:56:11.836475 | TASK [validate-host : Write out all zuul information for each host] 2025-10-13 06:56:14.312353 | controller -> localhost | changed 2025-10-13 06:56:14.322003 | 2025-10-13 06:56:14.324936 | TASK [include_role : prepare-workspace-openshift] 2025-10-13 06:56:14.410138 | controller | skipping: Conditional result was False 2025-10-13 06:56:14.415621 | 2025-10-13 06:56:14.415687 | TASK [include_role : remove-zuul-sshkey] 2025-10-13 06:56:14.501231 | controller | skipping: Conditional result was False 2025-10-13 06:56:14.515983 | 2025-10-13 06:56:14.516092 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-13 06:56:14.897514 | controller | ok: "logs" 2025-10-13 06:56:14.897757 | controller | ok: All items complete 2025-10-13 06:56:14.897788 | 2025-10-13 06:56:15.092244 | controller | ok: "artifacts" 2025-10-13 06:56:15.290185 | controller | ok: "docs" 2025-10-13 06:56:15.315850 | 2025-10-13 06:56:15.315967 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-13 06:56:15.719048 | controller | changed: "logs" 2025-10-13 06:56:15.906830 | controller | changed: "artifacts" 2025-10-13 06:56:16.086797 | controller | changed: "docs" 2025-10-13 06:56:16.159463 | 2025-10-13 06:56:16.159554 | PLAY RECAP 2025-10-13 06:56:16.159600 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-13 06:56:16.159630 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-13 06:56:16.159651 | 2025-10-13 06:56:16.604545 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-13 06:56:16.605511 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-13 06:56:19.421221 | 2025-10-13 06:56:19.421339 | PLAY [all] 2025-10-13 06:56:19.519646 | 2025-10-13 06:56:19.519768 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-13 06:56:19.687258 | controller | ok 2025-10-13 06:56:19.692890 | 2025-10-13 06:56:19.693016 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-13 06:56:20.606222 | controller | changed 2025-10-13 06:56:20.614507 | 2025-10-13 06:56:20.614588 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-13 06:56:22.857105 | controller | changed 2025-10-13 06:56:22.890305 | 2025-10-13 06:56:22.890403 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-13 06:56:23.712589 | controller | changed: 2025-10-13 06:56:23.712801 | controller | { 2025-10-13 06:56:23.712836 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-13 06:56:23.712868 | controller | } 2025-10-13 06:56:23.970956 | controller | changed: 2025-10-13 06:56:23.971038 | controller | { 2025-10-13 06:56:23.971068 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-13 06:56:23.971104 | controller | } 2025-10-13 06:56:24.197799 | controller | changed: 2025-10-13 06:56:24.197888 | controller | { 2025-10-13 06:56:24.198479 | 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-13 06:56:24.198519 | controller | } 2025-10-13 06:56:24.432252 | controller | changed: 2025-10-13 06:56:24.432332 | controller | { 2025-10-13 06:56:24.432361 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-13 06:56:24.432384 | controller | } 2025-10-13 06:56:24.679325 | controller | changed: 2025-10-13 06:56:24.679409 | controller | { 2025-10-13 06:56:24.679437 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-13 06:56:24.679461 | controller | } 2025-10-13 06:56:24.908876 | controller | changed: 2025-10-13 06:56:24.912033 | controller | { 2025-10-13 06:56:24.912067 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-13 06:56:24.912100 | controller | } 2025-10-13 06:56:25.128230 | controller | changed: 2025-10-13 06:56:25.128313 | controller | { 2025-10-13 06:56:25.128341 | 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-13 06:56:25.128365 | controller | } 2025-10-13 06:56:25.429322 | controller | changed: 2025-10-13 06:56:25.429406 | controller | { 2025-10-13 06:56:25.429435 | 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-13 06:56:25.429458 | controller | } 2025-10-13 06:56:25.565029 | controller | changed: 2025-10-13 06:56:25.565128 | controller | { 2025-10-13 06:56:25.565159 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-13 06:56:25.565181 | controller | } 2025-10-13 06:56:25.785682 | controller | changed: 2025-10-13 06:56:25.785760 | controller | { 2025-10-13 06:56:25.785788 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-13 06:56:25.785809 | controller | } 2025-10-13 06:56:26.007412 | controller | changed: 2025-10-13 06:56:26.007569 | controller | { 2025-10-13 06:56:26.007602 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-13 06:56:26.007647 | controller | } 2025-10-13 06:56:26.232109 | controller | changed: 2025-10-13 06:56:26.232315 | controller | { 2025-10-13 06:56:26.232358 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-13 06:56:26.232384 | controller | } 2025-10-13 06:56:26.463285 | controller | changed: 2025-10-13 06:56:26.463371 | controller | { 2025-10-13 06:56:26.463400 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-13 06:56:26.463422 | controller | } 2025-10-13 06:56:26.700752 | controller | changed: 2025-10-13 06:56:26.700836 | controller | { 2025-10-13 06:56:26.700864 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-13 06:56:26.700886 | controller | } 2025-10-13 06:56:26.932030 | controller | changed: 2025-10-13 06:56:26.932133 | controller | { 2025-10-13 06:56:26.932164 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-13 06:56:26.932186 | controller | } 2025-10-13 06:56:27.149115 | controller | changed: 2025-10-13 06:56:27.149195 | controller | { 2025-10-13 06:56:27.149222 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-13 06:56:27.149244 | controller | } 2025-10-13 06:56:27.363497 | controller | changed: 2025-10-13 06:56:27.363602 | controller | { 2025-10-13 06:56:27.363638 | 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-13 06:56:27.363667 | controller | } 2025-10-13 06:56:27.574356 | controller | changed: 2025-10-13 06:56:27.574441 | controller | { 2025-10-13 06:56:27.574469 | 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-13 06:56:27.574501 | controller | } 2025-10-13 06:56:27.801377 | controller | changed: 2025-10-13 06:56:27.801454 | controller | { 2025-10-13 06:56:27.801597 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-13 06:56:27.801628 | controller | } 2025-10-13 06:56:28.009214 | controller | changed: 2025-10-13 06:56:28.009295 | controller | { 2025-10-13 06:56:28.009322 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-13 06:56:28.009345 | controller | } 2025-10-13 06:56:28.231873 | controller | changed: 2025-10-13 06:56:28.231981 | controller | { 2025-10-13 06:56:28.232009 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-13 06:56:28.232031 | controller | } 2025-10-13 06:56:28.430008 | controller | changed: 2025-10-13 06:56:28.430108 | controller | { 2025-10-13 06:56:28.430136 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-13 06:56:28.430156 | controller | } 2025-10-13 06:56:28.644954 | controller | changed: 2025-10-13 06:56:28.645045 | controller | { 2025-10-13 06:56:28.645073 | 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-13 06:56:28.645106 | controller | } 2025-10-13 06:56:28.888379 | controller | changed: 2025-10-13 06:56:28.888474 | controller | { 2025-10-13 06:56:28.888502 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-13 06:56:28.888525 | controller | } 2025-10-13 06:56:29.111339 | controller | changed: 2025-10-13 06:56:29.111426 | controller | { 2025-10-13 06:56:29.111453 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-13 06:56:29.111474 | controller | } 2025-10-13 06:56:29.330879 | controller | changed: 2025-10-13 06:56:29.331023 | controller | { 2025-10-13 06:56:29.331053 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-13 06:56:29.331076 | controller | } 2025-10-13 06:56:29.389515 | 2025-10-13 06:56:29.389667 | TASK [Set timezone to UTC] 2025-10-13 06:56:30.223411 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-13 06:56:30.235831 | 2025-10-13 06:56:30.241486 | TASK [Create nodepool directory] 2025-10-13 06:56:30.578236 | controller | changed 2025-10-13 06:56:30.583720 | 2025-10-13 06:56:30.583791 | TASK [Create nodepool sub_nodes file] 2025-10-13 06:56:31.404083 | controller | changed 2025-10-13 06:56:31.420933 | 2025-10-13 06:56:31.421070 | TASK [Create nodepool sub_nodes_private file] 2025-10-13 06:56:32.132480 | controller | changed 2025-10-13 06:56:32.143880 | 2025-10-13 06:56:32.143998 | LOOP [Populate nodepool sub_nodes file] 2025-10-13 06:56:32.253153 | 2025-10-13 06:56:32.253319 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-13 06:56:32.368693 | 2025-10-13 06:56:32.368964 | TASK [Create nodepool primary file] 2025-10-13 06:56:32.495691 | controller | skipping: Conditional result was False 2025-10-13 06:56:32.501429 | 2025-10-13 06:56:32.501523 | TASK [Create nodepool node_private for this node] 2025-10-13 06:56:33.236882 | controller | changed 2025-10-13 06:56:33.243753 | 2025-10-13 06:56:33.243816 | LOOP [Copy ssh keys to nodepool directory] 2025-10-13 06:56:34.275318 | controller | ok: Item: id_rsa Runtime: 0:00:00.005517 2025-10-13 06:56:34.275484 | 2025-10-13 06:56:34.462402 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004388 2025-10-13 06:56:34.476205 | 2025-10-13 06:56:34.476319 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-13 06:56:35.193975 | controller | changed 2025-10-13 06:56:35.201440 | 2025-10-13 06:56:35.201528 | TASK [Validate sudoers config after edits] 2025-10-13 06:56:35.546651 | controller | /etc/sudoers: parsed OK 2025-10-13 06:56:35.546733 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-13 06:56:35.546744 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-13 06:56:35.546750 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-13 06:56:35.805011 | controller | ok: Runtime: 0:00:00.005074 2025-10-13 06:56:35.811370 | 2025-10-13 06:56:35.811445 | TASK [Show the environment passed in to job shell scripts] 2025-10-13 06:56:36.206661 | controller | SHELL=/bin/bash 2025-10-13 06:56:36.206698 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-13 06:56:36.206707 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-13 06:56:36.206713 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/37/3237/bfffe1921af150ba61d6d18c8f8601410cec1c04 2025-10-13 06:56:36.206718 | controller | PWD=/home/zuul 2025-10-13 06:56:36.206724 | controller | ZUUL_PIPELINE=github-check 2025-10-13 06:56:36.206729 | controller | LOGNAME=zuul 2025-10-13 06:56:36.206734 | controller | XDG_SESSION_TYPE=tty 2025-10-13 06:56:36.206740 | controller | _=/usr/bin/env 2025-10-13 06:56:36.206745 | controller | MOTD_SHOWN=pam 2025-10-13 06:56:36.206779 | controller | HOME=/home/zuul 2025-10-13 06:56:36.206788 | controller | LANG=en_US.UTF-8 2025-10-13 06:56:36.206794 | controller | SSH_CONNECTION=192.168.26.12 35050 192.168.26.203 22 2025-10-13 06:56:36.206799 | 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-13 06:56:36.206807 | controller | ZUUL_CHANGE_IDS=3237,bfffe1921af150ba61d6d18c8f8601410cec1c04 2025-10-13 06:56:36.206812 | controller | WORKSPACE=/home/zuul/workspace 2025-10-13 06:56:36.206818 | controller | XDG_SESSION_CLASS=user 2025-10-13 06:56:36.206824 | controller | SELINUX_ROLE_REQUESTED= 2025-10-13 06:56:36.206829 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-13 06:56:36.206834 | controller | USER=zuul 2025-10-13 06:56:36.206839 | controller | ZUUL_VOTING=True 2025-10-13 06:56:36.206844 | controller | BUILD_TIMEOUT=1800000 2025-10-13 06:56:36.206849 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-13 06:56:36.206855 | controller | SHLVL=1 2025-10-13 06:56:36.206860 | controller | ZUUL_PATCHSET=bfffe1921af150ba61d6d18c8f8601410cec1c04 2025-10-13 06:56:36.206865 | controller | XDG_SESSION_ID=1 2025-10-13 06:56:36.206871 | controller | ZUUL_BRANCH=main 2025-10-13 06:56:36.206946 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-13 06:56:36.206960 | controller | SSH_CLIENT=192.168.26.12 35050 22 2025-10-13 06:56:36.206965 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-13 06:56:36.206971 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-13 06:56:36.206976 | controller | which_declare=declare -f 2025-10-13 06:56:36.206981 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-13 06:56:36.206987 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-13 06:56:36.206992 | controller | ZUUL_CHANGE=3237 2025-10-13 06:56:36.206997 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-13 06:56:36.207002 | controller | ZUUL_UUID=4b10ea63c51940789cc5dc32079f94d6 2025-10-13 06:56:36.207008 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-13 06:56:36.207015 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-13 06:56:36.207020 | controller | } 2025-10-13 06:56:36.390521 | controller | ok: Runtime: 0:00:00.005219 2025-10-13 06:56:36.396613 | 2025-10-13 06:56:36.396697 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-13 06:56:36.432124 | controller | skipping: Conditional result was False 2025-10-13 06:56:36.439032 | 2025-10-13 06:56:36.439123 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-13 06:56:37.066144 | controller | skipping: Conditional result was False 2025-10-13 06:56:37.072475 | 2025-10-13 06:56:37.072548 | TASK [Ensure legacy workspace directory] 2025-10-13 06:56:37.334390 | controller | changed 2025-10-13 06:56:37.392410 | 2025-10-13 06:56:37.392499 | PLAY RECAP 2025-10-13 06:56:37.392547 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-13 06:56:37.392571 | 2025-10-13 06:56:37.557318 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-13 06:56:37.558121 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-13 06:56:38.350719 | 2025-10-13 06:56:38.350819 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-13 06:56:38.397697 | 2025-10-13 06:56:38.397777 | TASK [Create zuul-output directory] 2025-10-13 06:56:38.929043 | controller | changed 2025-10-13 06:56:38.946133 | 2025-10-13 06:56:38.946286 | TASK [Slurp Zuul inventory test] 2025-10-13 06:56:39.834205 | controller -> localhost | ok 2025-10-13 06:56:39.857342 | 2025-10-13 06:56:39.857423 | TASK [Save zuul inventory] 2025-10-13 06:56:41.346168 | controller | changed 2025-10-13 06:56:41.360684 | 2025-10-13 06:56:41.360834 | TASK [Save zuul vars without the change_message] 2025-10-13 06:56:42.112809 | controller | changed 2025-10-13 06:56:42.167660 | 2025-10-13 06:56:42.167743 | PLAY RECAP 2025-10-13 06:56:42.167788 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-13 06:56:42.167813 | 2025-10-13 06:56:42.301993 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-13 06:56:42.302708 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-13 06:56:43.307976 | 2025-10-13 06:56:43.308080 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-10-13 06:56:43.337627 | 2025-10-13 06:56:43.337708 | TASK [Read group_vars] 2025-10-13 06:56:43.366528 | controller | ok 2025-10-13 06:56:43.469296 | ANSIBLE PARSE ERROR 2025-10-13 06:56:43.469310 | Using /var/lib/zuul/builds/4b10ea63c51940789cc5dc32079f94d6/ansible/pre_playbook_3/ansible.cfg as config file 2025-10-13 06:56:43.469315 | 2025-10-13 06:56:43.469318 | PLAY [Run ci/playbooks/molecule-prepare.yml] *********************************** 2025-10-13 06:56:43.469321 | 2025-10-13 06:56:43.469324 | TASK [Read group_vars name=cifmw_helpers, tasks_from=var_dir.yml] ************** 2025-10-13 06:56:43.469326 | ERROR! We were unable to read either as JSON nor YAML, these are the errors we got from each: 2025-10-13 06:56:43.469329 | JSON: Expecting value: line 1 column 1 (char 0) 2025-10-13 06:56:43.469332 | 2025-10-13 06:56:43.469335 | Syntax Error while loading YAML. 2025-10-13 06:56:43.469338 | found unacceptable key (unhashable type: 'AnsibleMapping') 2025-10-13 06:56:43.469340 | 2025-10-13 06:56:43.469343 | The error appears to be in '/var/lib/zuul/builds/4b10ea63c51940789cc5dc32079f94d6/untrusted/project_0/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_helpers/tasks/var_dir.yml': line 7, column 12, but may 2025-10-13 06:56:43.469346 | be elsewhere in the file depending on the exact syntax problem. 2025-10-13 06:56:43.469348 | 2025-10-13 06:56:43.469351 | The offending line appears to be: 2025-10-13 06:56:43.469354 | 2025-10-13 06:56:43.469356 | ansible.builtin.stat: 2025-10-13 06:56:43.469359 | path: {{ provided_dir }} 2025-10-13 06:56:43.469361 | ^ here 2025-10-13 06:56:43.469364 | We could be wrong, but this one looks like it might be an issue with 2025-10-13 06:56:43.469366 | missing quotes. Always quote template expression brackets when they 2025-10-13 06:56:43.469369 | start a value. For instance: 2025-10-13 06:56:43.469372 | 2025-10-13 06:56:43.469374 | with_items: 2025-10-13 06:56:43.469377 | - {{ foo }} 2025-10-13 06:56:43.469379 | 2025-10-13 06:56:43.469382 | Should be written as: 2025-10-13 06:56:43.469384 | 2025-10-13 06:56:43.469386 | with_items: 2025-10-13 06:56:43.469389 | - "{{ foo }}" 2025-10-13 06:56:43.469562 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-13 06:56:43.470443 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-13 06:56:44.483302 | 2025-10-13 06:56:44.483409 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-13 06:56:44.512247 | 2025-10-13 06:56:44.512341 | TASK [Filter out host if needed] 2025-10-13 06:56:44.526609 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-13 06:56:44.531072 | 2025-10-13 06:56:44.531159 | TASK [Ensure file is present] 2025-10-13 06:56:45.019738 | controller | ok 2025-10-13 06:56:45.026009 | 2025-10-13 06:56:45.026080 | TASK [Manage molecule report file] 2025-10-13 06:56:45.614702 | controller | skipping: Conditional result was False 2025-10-13 06:56:45.620244 | 2025-10-13 06:56:45.620311 | TASK [Check if we get ci-framework-data basedir] 2025-10-13 06:56:45.843901 | controller | ok 2025-10-13 06:56:45.852782 | 2025-10-13 06:56:45.852851 | TASK [Create ci-framework-data log directory for zuul] 2025-10-13 06:56:46.345732 | controller | changed 2025-10-13 06:56:46.354048 | 2025-10-13 06:56:46.354119 | TASK [Copy ci-framework interesting files] 2025-10-13 06:56:46.774001 | controller | cp: cannot stat '/home/zuul/ci-framework-data/logs': No such file or directory 2025-10-13 06:56:46.777852 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-10-13 06:56:46.898745 | controller | changed 2025-10-13 06:56:46.904108 | 2025-10-13 06:56:46.904187 | TASK [Get SELinux listing] 2025-10-13 06:56:47.464194 | controller | changed 2025-10-13 06:56:47.469788 | 2025-10-13 06:56:47.469857 | TASK [Generate log index] 2025-10-13 06:56:48.097181 | controller | changed 2025-10-13 06:56:48.104117 | 2025-10-13 06:56:48.104203 | TASK [Get some env related data] 2025-10-13 06:56:49.628725 | controller | changed 2025-10-13 06:56:49.633833 | 2025-10-13 06:56:49.633896 | TASK [Generate list of logs to collect in home directory] 2025-10-13 06:56:49.936447 | controller | ok: All paths examined 2025-10-13 06:56:49.941409 | 2025-10-13 06:56:49.941477 | LOOP [Copy logs from home directory] 2025-10-13 06:56:49.960682 | 2025-10-13 06:56:49.960816 | TASK [Copy crio stats log file] 2025-10-13 06:56:49.974398 | controller | skipping: Conditional result was False 2025-10-13 06:56:49.981123 | 2025-10-13 06:56:49.981216 | TASK [Get SELinux related data] 2025-10-13 06:56:50.225969 | controller | 2025-10-13 06:56:50.531059 | controller | ERROR 2025-10-13 06:56:50.531239 | controller | { 2025-10-13 06:56:50.531275 | controller | "delta": "0:00:00.009430", 2025-10-13 06:56:50.531297 | controller | "end": "2025-10-13 06:56:50.227610", 2025-10-13 06:56:50.531316 | controller | "msg": "non-zero return code", 2025-10-13 06:56:50.531334 | controller | "rc": 1, 2025-10-13 06:56:50.531352 | controller | "start": "2025-10-13 06:56:50.218180" 2025-10-13 06:56:50.531370 | controller | } 2025-10-13 06:56:50.531396 | controller | ERROR: Ignoring Errors 2025-10-13 06:56:50.536538 | 2025-10-13 06:56:50.536604 | TASK [Create system configuration directory] 2025-10-13 06:56:50.732896 | controller | changed 2025-10-13 06:56:50.740965 | 2025-10-13 06:56:50.741076 | TASK [Get some of the system configurations] 2025-10-13 06:56:50.958381 | controller | cp: cannot stat '/etc/libvirt/*.conf': No such file or directory 2025-10-13 06:56:50.963139 | controller | cp: cannot stat '/etc/containers/registries.conf': No such file or directory 2025-10-13 06:56:50.964399 | controller | cp: cannot stat '/etc/containers/registries.conf.d': No such file or directory 2025-10-13 06:56:50.965603 | controller | chown: cannot access '/home/zuul/zuul-output/logs/registries.conf.d': No such file or directory 2025-10-13 06:56:51.278735 | controller | ERROR 2025-10-13 06:56:51.278846 | controller | { 2025-10-13 06:56:51.278877 | controller | "delta": "0:00:00.011934", 2025-10-13 06:56:51.278898 | controller | "end": "2025-10-13 06:56:50.965896", 2025-10-13 06:56:51.278961 | controller | "msg": "non-zero return code", 2025-10-13 06:56:51.278985 | controller | "rc": 1, 2025-10-13 06:56:51.279005 | controller | "start": "2025-10-13 06:56:50.953962" 2025-10-13 06:56:51.279024 | controller | } 2025-10-13 06:56:51.279047 | controller | ERROR: Ignoring Errors 2025-10-13 06:56:51.284129 | 2025-10-13 06:56:51.284208 | TASK [Copy generated documentation if available] 2025-10-13 06:56:51.329147 | controller | skipping: Conditional result was False 2025-10-13 06:56:51.335653 | 2025-10-13 06:56:51.335736 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-13 06:56:51.359715 | controller | skipping: Conditional result was False 2025-10-13 06:56:51.365422 | 2025-10-13 06:56:51.365504 | TASK [Compress logs bigger than 2MB] 2025-10-13 06:56:51.938825 | controller | changed 2025-10-13 06:56:51.944510 | 2025-10-13 06:56:51.944592 | TASK [Copy files from workspace on node] 2025-10-13 06:56:51.973343 | controller | ok 2025-10-13 06:56:52.002504 | 2025-10-13 06:56:52.002620 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-13 06:56:52.025864 | controller | skipping: Conditional result was False 2025-10-13 06:56:52.031163 | 2025-10-13 06:56:52.031232 | TASK [fetch-output : Set log path for single node] 2025-10-13 06:56:52.058315 | controller | ok 2025-10-13 06:56:52.063083 | 2025-10-13 06:56:52.063156 | LOOP [fetch-output : Ensure local output dirs] 2025-10-13 06:56:52.310192 | controller -> localhost | ok: "/var/lib/zuul/builds/4b10ea63c51940789cc5dc32079f94d6/work/logs" 2025-10-13 06:56:52.310447 | 2025-10-13 06:56:52.499889 | controller -> localhost | changed: "/var/lib/zuul/builds/4b10ea63c51940789cc5dc32079f94d6/work/artifacts" 2025-10-13 06:56:52.696399 | controller -> localhost | changed: "/var/lib/zuul/builds/4b10ea63c51940789cc5dc32079f94d6/work/docs" 2025-10-13 06:56:52.709594 | 2025-10-13 06:56:52.709704 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-13 06:56:53.303660 | controller | changed: 2025-10-13 06:56:53.303868 | controller | .d..t...... ./ 2025-10-13 06:56:53.303899 | controller | >f+++++++++ README.html 2025-10-13 06:56:53.303958 | controller | >f+++++++++ dmesg.log 2025-10-13 06:56:53.303983 | controller | >f+++++++++ installed-pkgs.log 2025-10-13 06:56:53.304004 | controller | >f+++++++++ python.log 2025-10-13 06:56:53.304023 | controller | >f+++++++++ selinux-denials.log 2025-10-13 06:56:53.304041 | controller | >f+++++++++ selinux-listing.log 2025-10-13 06:56:53.304058 | controller | cd+++++++++ ci-framework-data/ 2025-10-13 06:56:53.304074 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-13 06:56:53.304092 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-13 06:56:53.304110 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-13 06:56:53.304127 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-10-13 06:56:53.304160 | controller | cd+++++++++ system-config/ 2025-10-13 06:56:53.304178 | controller | cd+++++++++ system-config/libvirt/ 2025-10-13 06:56:53.702629 | controller | changed: .d..t...... ./ 2025-10-13 06:56:54.136361 | controller | changed: .d..t...... ./ 2025-10-13 06:56:54.153768 | 2025-10-13 06:56:54.153871 | TASK [Return artifact to Zuul] 2025-10-13 06:56:54.173180 | controller | skipping: Conditional result was False 2025-10-13 06:56:54.194052 | 2025-10-13 06:56:54.194118 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-13 06:56:54.194248 | 2025-10-13 06:56:54.194278 | PLAY RECAP 2025-10-13 06:56:54.194316 | controller | ok: 15 changed: 11 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 2 2025-10-13 06:56:54.194339 | 2025-10-13 06:56:54.269971 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-13 06:56:54.270701 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-13 06:56:54.831870 | 2025-10-13 06:56:54.832041 | PLAY [all] 2025-10-13 06:56:54.850772 | 2025-10-13 06:56:54.850846 | TASK [include_role : fetch-output] 2025-10-13 06:56:54.879844 | controller | ok 2025-10-13 06:56:54.896081 | 2025-10-13 06:56:54.896173 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-13 06:56:54.939612 | controller | skipping: Conditional result was False 2025-10-13 06:56:54.945185 | 2025-10-13 06:56:54.945260 | TASK [fetch-output : Set log path for single node] 2025-10-13 06:56:55.026009 | controller | ok 2025-10-13 06:56:55.031063 | 2025-10-13 06:56:55.031154 | LOOP [fetch-output : Ensure local output dirs] 2025-10-13 06:56:55.428271 | controller -> localhost | ok: "/var/lib/zuul/builds/4b10ea63c51940789cc5dc32079f94d6/work/logs" 2025-10-13 06:56:55.633245 | controller -> localhost | ok: "/var/lib/zuul/builds/4b10ea63c51940789cc5dc32079f94d6/work/artifacts" 2025-10-13 06:56:55.825986 | controller -> localhost | ok: "/var/lib/zuul/builds/4b10ea63c51940789cc5dc32079f94d6/work/docs" 2025-10-13 06:56:55.837254 | 2025-10-13 06:56:55.837364 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-13 06:56:56.342900 | controller | ok 2025-10-13 06:56:56.343109 | controller | ok: All items complete 2025-10-13 06:56:56.343153 | 2025-10-13 06:56:56.714430 | controller | ok 2025-10-13 06:56:57.075357 | controller | ok 2025-10-13 06:56:57.092340 | 2025-10-13 06:56:57.092435 | TASK [include_role : fetch-output-openshift] 2025-10-13 06:56:57.106677 | controller | skipping: Conditional result was False 2025-10-13 06:56:57.112551 | 2025-10-13 06:56:57.112623 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-13 06:56:57.447476 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006132 2025-10-13 06:56:57.646045 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.011402 2025-10-13 06:56:57.677332 | 2025-10-13 06:56:57.677464 | PLAY [all] 2025-10-13 06:56:57.691870 | 2025-10-13 06:56:57.691990 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-13 06:56:58.054570 | controller | changed 2025-10-13 06:56:58.075230 | 2025-10-13 06:56:58.075282 | PLAY RECAP 2025-10-13 06:56:58.075324 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-13 06:56:58.075347 | 2025-10-13 06:56:58.139949 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-13 06:56:58.140719 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-13 06:56:58.678281 | 2025-10-13 06:56:58.678421 | PLAY [localhost] 2025-10-13 06:56:58.695706 | 2025-10-13 06:56:58.695783 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-13 06:56:58.986457 | localhost | changed 2025-10-13 06:56:58.990855 | 2025-10-13 06:56:58.990954 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-13 06:56:59.008305 | localhost | ok 2025-10-13 06:56:59.016132 | 2025-10-13 06:56:59.016211 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-13 06:56:59.306999 | localhost | changed 2025-10-13 06:56:59.312567 | 2025-10-13 06:56:59.312645 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-13 06:56:59.866199 | localhost | changed 2025-10-13 06:56:59.871021 | 2025-10-13 06:56:59.871089 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-13 06:57:00.223195 | localhost | Identity added: /var/lib/zuul/builds/4b10ea63c51940789cc5dc32079f94d6/work/tmp/ansible.7_x5492y (/var/lib/zuul/builds/4b10ea63c51940789cc5dc32079f94d6/work/tmp/ansible.7_x5492y) 2025-10-13 06:57:00.223373 | localhost | ok: Runtime: 0:00:00.006308 2025-10-13 06:57:00.227561 | 2025-10-13 06:57:00.227634 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-13 06:57:00.438772 | localhost | ok: Runtime: 0:00:00.004145 2025-10-13 06:57:00.443494 | 2025-10-13 06:57:00.443576 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-13 06:57:00.490964 | localhost | changed 2025-10-13 06:57:00.495738 | 2025-10-13 06:57:00.495805 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-13 06:57:00.799009 | localhost | changed 2025-10-13 06:57:00.817893 | 2025-10-13 06:57:00.817974 | PLAY [localhost] 2025-10-13 06:57:00.829017 | 2025-10-13 06:57:00.829076 | TASK [Generate bulk log download script] 2025-10-13 06:57:00.846923 | localhost | ok 2025-10-13 06:57:00.857924 | 2025-10-13 06:57:00.857992 | TASK [local-log-download : Check API endpoint is defined] 2025-10-13 06:57:00.885195 | localhost | ok: All assertions passed 2025-10-13 06:57:00.889493 | 2025-10-13 06:57:00.889558 | TASK [local-log-download : Create download script] 2025-10-13 06:57:01.236127 | localhost -> localhost | changed 2025-10-13 06:57:01.244454 | 2025-10-13 06:57:01.244519 | TASK [Register quick-download link] 2025-10-13 06:57:01.260674 | localhost | ok 2025-10-13 06:57:01.292765 | 2025-10-13 06:57:01.292836 | PLAY [logserver.rdoproject.org] 2025-10-13 06:57:01.301789 | 2025-10-13 06:57:01.301848 | TASK [Set zuul-log-path fact] 2025-10-13 06:57:01.317102 | logserver.rdoproject.org | ok 2025-10-13 06:57:01.325648 | 2025-10-13 06:57:01.325715 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-13 06:57:01.340746 | logserver.rdoproject.org | ok 2025-10-13 06:57:01.345744 | 2025-10-13 06:57:01.345807 | TASK [upload-logs : Create log directories] 2025-10-13 06:57:03.056757 | logserver.rdoproject.org | changed 2025-10-13 06:57:03.060027 | 2025-10-13 06:57:03.060093 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-13 06:57:03.277607 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005423 2025-10-13 06:57:03.282260 | 2025-10-13 06:57:03.282326 | TASK [upload-logs : Upload logs to log server] 2025-10-13 06:57:04.708017 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-13 06:57:04.710715 | 2025-10-13 06:57:04.710780 | LOOP [upload-logs : Compress console log and json output] 2025-10-13 06:57:04.746627 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 06:57:04.754790 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 06:57:04.767395 | 2025-10-13 06:57:04.767469 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-13 06:57:04.802467 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 06:57:04.802685 | 2025-10-13 06:57:04.805844 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 06:57:04.814517 | 2025-10-13 06:57:04.814586 | LOOP [upload-logs : Upload console log and json output]