2026-03-16 16:28:45.876567 | Job console starting... 2026-03-16 16:28:46.110929 | Updating repositories 2026-03-16 16:28:46.893761 | Preparing job workspace 2026-03-16 16:28:56.665519 | Running Ansible setup... 2026-03-16 16:29:02.131642 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-03-16 16:29:03.668848 | 2026-03-16 16:29:03.668961 | PLAY [localhost] 2026-03-16 16:29:03.693068 | 2026-03-16 16:29:03.693163 | TASK [Gathering Facts] 2026-03-16 16:29:05.107160 | localhost | ok 2026-03-16 16:29:05.127094 | 2026-03-16 16:29:05.127249 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-16 16:29:05.738442 | localhost -> localhost | changed 2026-03-16 16:29:05.743881 | 2026-03-16 16:29:05.743975 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-16 16:29:07.835932 | localhost -> localhost | changed 2026-03-16 16:29:07.844875 | 2026-03-16 16:29:07.844964 | TASK [Setup log path fact] 2026-03-16 16:29:07.906801 | localhost | ok 2026-03-16 16:29:07.937242 | 2026-03-16 16:29:07.937350 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-16 16:29:08.020336 | localhost | ok 2026-03-16 16:29:08.028038 | 2026-03-16 16:29:08.028142 | TASK [emit-job-header : Print job information] 2026-03-16 16:29:08.128560 | # Job Information 2026-03-16 16:29:08.128718 | Ansible Version: 2.15.12 2026-03-16 16:29:08.128747 | Job: cifmw-molecule-cifmw_helpers 2026-03-16 16:29:08.128768 | Pipeline: github-check 2026-03-16 16:29:08.128788 | Executor: ibm-bm4-ze.softwarefactory-project.io 2026-03-16 16:29:08.128806 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3770 2026-03-16 16:29:08.128825 | Log URL (when completed): https://logserver.rdoproject.org/6fd/rdoproject.org/6fdfba3260eb4aadac17e1fb112b6c75/ 2026-03-16 16:29:08.128844 | Event ID: 072a8580-2155-11f1-9026-7307b7bf6b98 2026-03-16 16:29:08.132451 | 2026-03-16 16:29:08.132517 | LOOP [emit-job-header : Print node information] 2026-03-16 16:29:08.397856 | localhost | ok: 2026-03-16 16:29:08.398059 | localhost | # Node Information 2026-03-16 16:29:08.398101 | localhost | Inventory Hostname: controller 2026-03-16 16:29:08.398130 | localhost | Hostname: np0005648719 2026-03-16 16:29:08.398153 | localhost | Username: zuul 2026-03-16 16:29:08.398175 | localhost | Distro: CentOS 9 2026-03-16 16:29:08.398194 | localhost | Provider: ibm-bm4-nodepool 2026-03-16 16:29:08.398213 | localhost | Region: regionOne 2026-03-16 16:29:08.398232 | localhost | Label: cloud-centos-9-stream-tripleo-ibm 2026-03-16 16:29:08.398250 | localhost | Product Name: OpenStack Compute 2026-03-16 16:29:08.398268 | localhost | Interface IP: 192.168.26.198 2026-03-16 16:29:08.463700 | 2026-03-16 16:29:08.463787 | PLAY [all] 2026-03-16 16:29:08.477329 | 2026-03-16 16:29:08.477400 | TASK [Gather network facts] 2026-03-16 16:29:08.872682 | controller | ok 2026-03-16 16:29:08.902483 | 2026-03-16 16:29:08.902566 | TASK [include_role : start-zuul-console] 2026-03-16 16:29:08.948693 | controller | ok 2026-03-16 16:29:08.969950 | 2026-03-16 16:29:08.970027 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-16 16:29:09.534980 | controller | ok 2026-03-16 16:29:09.544415 | 2026-03-16 16:29:09.544487 | TASK [include_role : add-build-sshkey] 2026-03-16 16:29:09.596416 | controller | ok 2026-03-16 16:29:09.618481 | 2026-03-16 16:29:09.618557 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-16 16:29:10.287000 | controller -> localhost | ok 2026-03-16 16:29:10.299587 | 2026-03-16 16:29:10.299665 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-16 16:29:10.396428 | controller | ok 2026-03-16 16:29:10.428608 | controller | included: /var/lib/zuul/builds/6fdfba3260eb4aadac17e1fb112b6c75/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-16 16:29:10.451915 | 2026-03-16 16:29:10.452183 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-16 16:29:12.093608 | controller -> localhost | Generating public/private rsa key pair. 2026-03-16 16:29:12.093839 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/6fdfba3260eb4aadac17e1fb112b6c75/work/6fdfba3260eb4aadac17e1fb112b6c75_id_rsa. 2026-03-16 16:29:12.093876 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/6fdfba3260eb4aadac17e1fb112b6c75/work/6fdfba3260eb4aadac17e1fb112b6c75_id_rsa.pub. 2026-03-16 16:29:12.093903 | controller -> localhost | The key fingerprint is: 2026-03-16 16:29:12.093924 | controller -> localhost | SHA256:oXtEJA3tPcddpTgxzjChXa7J1dQHwmhoiurKYZQIbo4 zuul-build-sshkey 2026-03-16 16:29:12.093945 | controller -> localhost | The key's randomart image is: 2026-03-16 16:29:12.093965 | controller -> localhost | +---[RSA 3072]----+ 2026-03-16 16:29:12.093983 | controller -> localhost | | o+..++= ooo| 2026-03-16 16:29:12.094004 | controller -> localhost | | o=ooB.B .+| 2026-03-16 16:29:12.094022 | controller -> localhost | |. . ++o..B.o..| 2026-03-16 16:29:12.094041 | controller -> localhost | |+ . . .o.oo+o.. | 2026-03-16 16:29:12.094059 | controller -> localhost | |.= . . S +o | 2026-03-16 16:29:12.094076 | controller -> localhost | |= . o | 2026-03-16 16:29:12.094106 | controller -> localhost | |E= . . | 2026-03-16 16:29:12.094128 | controller -> localhost | |o o . | 2026-03-16 16:29:12.094147 | controller -> localhost | |.o | 2026-03-16 16:29:12.094164 | controller -> localhost | +----[SHA256]-----+ 2026-03-16 16:29:12.094208 | controller -> localhost | ok: Runtime: 0:00:00.341610 2026-03-16 16:29:12.100713 | 2026-03-16 16:29:12.100787 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-16 16:29:12.203883 | controller | ok 2026-03-16 16:29:12.236858 | controller | included: /var/lib/zuul/builds/6fdfba3260eb4aadac17e1fb112b6c75/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-16 16:29:12.267590 | 2026-03-16 16:29:12.267683 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-16 16:29:12.358995 | controller | skipping: Conditional result was False 2026-03-16 16:29:12.365507 | 2026-03-16 16:29:12.365607 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-16 16:29:12.989758 | controller | changed 2026-03-16 16:29:13.003197 | 2026-03-16 16:29:13.003264 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-16 16:29:13.242668 | controller | ok 2026-03-16 16:29:13.251890 | 2026-03-16 16:29:13.251973 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-16 16:29:14.075666 | controller | changed 2026-03-16 16:29:14.089159 | 2026-03-16 16:29:14.089245 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-16 16:29:14.852124 | controller | changed 2026-03-16 16:29:14.857170 | 2026-03-16 16:29:14.857245 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-16 16:29:14.917161 | controller | skipping: Conditional result was False 2026-03-16 16:29:14.927771 | 2026-03-16 16:29:14.927846 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-16 16:29:16.216784 | controller -> localhost | changed 2026-03-16 16:29:16.244273 | 2026-03-16 16:29:16.244382 | TASK [add-build-sshkey : Add back temp key] 2026-03-16 16:29:17.205371 | controller -> localhost | Identity added: /var/lib/zuul/builds/6fdfba3260eb4aadac17e1fb112b6c75/work/6fdfba3260eb4aadac17e1fb112b6c75_id_rsa (zuul-build-sshkey) 2026-03-16 16:29:17.205508 | controller -> localhost | ok: Runtime: 0:00:00.020137 2026-03-16 16:29:17.210786 | 2026-03-16 16:29:17.210852 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-16 16:29:17.827980 | controller | ok 2026-03-16 16:29:17.835552 | 2026-03-16 16:29:17.835633 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-16 16:29:17.951436 | controller | skipping: Conditional result was False 2026-03-16 16:29:17.964900 | 2026-03-16 16:29:17.964996 | TASK [include_role : validate-host] 2026-03-16 16:29:18.010136 | controller | ok 2026-03-16 16:29:18.082094 | 2026-03-16 16:29:18.082181 | TASK [validate-host : Define zuul_info_dir fact] 2026-03-16 16:29:18.178489 | controller | ok 2026-03-16 16:29:18.183488 | 2026-03-16 16:29:18.183577 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-03-16 16:29:19.238920 | controller -> localhost | ok 2026-03-16 16:29:19.244803 | 2026-03-16 16:29:19.244882 | TASK [validate-host : Collect information about the host] 2026-03-16 16:29:20.019882 | controller | ok 2026-03-16 16:29:20.048190 | 2026-03-16 16:29:20.048288 | TASK [validate-host : Sanitize hostname] 2026-03-16 16:29:20.146126 | controller | ok 2026-03-16 16:29:20.175354 | 2026-03-16 16:29:20.175472 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-03-16 16:29:21.433708 | controller -> localhost | changed 2026-03-16 16:29:21.439455 | 2026-03-16 16:29:21.439536 | TASK [validate-host : Collect information about zuul worker] 2026-03-16 16:29:22.226401 | controller | ok 2026-03-16 16:29:22.236435 | 2026-03-16 16:29:22.236518 | TASK [validate-host : Write out all zuul information for each host] 2026-03-16 16:29:23.981508 | controller -> localhost | changed 2026-03-16 16:29:23.991246 | 2026-03-16 16:29:23.991349 | TASK [include_role : prepare-workspace-openshift] 2026-03-16 16:29:24.107451 | controller | skipping: Conditional result was False 2026-03-16 16:29:24.112729 | 2026-03-16 16:29:24.112798 | TASK [include_role : remove-zuul-sshkey] 2026-03-16 16:29:24.187846 | controller | skipping: Conditional result was False 2026-03-16 16:29:24.194794 | 2026-03-16 16:29:24.194923 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-16 16:29:24.487570 | controller | ok: "logs" 2026-03-16 16:29:24.487797 | controller | ok: All items complete 2026-03-16 16:29:24.487829 | 2026-03-16 16:29:24.703556 | controller | ok: "artifacts" 2026-03-16 16:29:24.894557 | controller | ok: "docs" 2026-03-16 16:29:24.900786 | 2026-03-16 16:29:24.900856 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-16 16:29:25.224324 | controller | changed: "logs" 2026-03-16 16:29:25.419568 | controller | changed: "artifacts" 2026-03-16 16:29:25.603851 | controller | changed: "docs" 2026-03-16 16:29:25.667469 | 2026-03-16 16:29:25.667581 | PLAY RECAP 2026-03-16 16:29:25.667631 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2026-03-16 16:29:25.667662 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-16 16:29:25.667683 | 2026-03-16 16:29:26.109379 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-03-16 16:29:26.110154 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2026-03-16 16:29:27.978379 | 2026-03-16 16:29:27.978504 | PLAY [all] 2026-03-16 16:29:28.079573 | 2026-03-16 16:29:28.079693 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2026-03-16 16:29:28.248766 | controller | ok 2026-03-16 16:29:28.256056 | 2026-03-16 16:29:28.256168 | TASK [mirror-info-fork : Create /etc/ci] 2026-03-16 16:29:28.765356 | controller | changed 2026-03-16 16:29:28.775195 | 2026-03-16 16:29:28.775287 | TASK [mirror-info-fork : Install ci_mirror script] 2026-03-16 16:29:30.758943 | controller | changed 2026-03-16 16:29:30.797203 | 2026-03-16 16:29:30.797314 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2026-03-16 16:29:31.381283 | controller | changed: 2026-03-16 16:29:31.381475 | controller | { 2026-03-16 16:29:31.381510 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2026-03-16 16:29:31.381542 | controller | } 2026-03-16 16:29:31.604208 | controller | changed: 2026-03-16 16:29:31.604387 | controller | { 2026-03-16 16:29:31.604433 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2026-03-16 16:29:31.604466 | controller | } 2026-03-16 16:29:31.817206 | controller | changed: 2026-03-16 16:29:31.817322 | controller | { 2026-03-16 16:29:31.817358 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com" 2026-03-16 16:29:31.817383 | controller | } 2026-03-16 16:29:32.029952 | controller | changed: 2026-03-16 16:29:32.030035 | controller | { 2026-03-16 16:29:32.030064 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2026-03-16 16:29:32.030097 | controller | } 2026-03-16 16:29:32.267172 | controller | changed: 2026-03-16 16:29:32.267257 | controller | { 2026-03-16 16:29:32.267284 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2026-03-16 16:29:32.267328 | controller | } 2026-03-16 16:29:32.485215 | controller | changed: 2026-03-16 16:29:32.485320 | controller | { 2026-03-16 16:29:32.485355 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2026-03-16 16:29:32.485378 | controller | } 2026-03-16 16:29:32.713877 | controller | changed: 2026-03-16 16:29:32.713960 | controller | { 2026-03-16 16:29:32.713989 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen" 2026-03-16 16:29:32.714013 | controller | } 2026-03-16 16:29:32.941065 | controller | changed: 2026-03-16 16:29:32.941156 | controller | { 2026-03-16 16:29:32.941185 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora" 2026-03-16 16:29:32.941208 | controller | } 2026-03-16 16:29:33.181103 | controller | changed: 2026-03-16 16:29:33.181190 | controller | { 2026-03-16 16:29:33.181218 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2026-03-16 16:29:33.181239 | controller | } 2026-03-16 16:29:33.403431 | controller | changed: 2026-03-16 16:29:33.403512 | controller | { 2026-03-16 16:29:33.403538 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2026-03-16 16:29:33.403557 | controller | } 2026-03-16 16:29:33.615569 | controller | changed: 2026-03-16 16:29:33.615652 | controller | { 2026-03-16 16:29:33.615680 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2026-03-16 16:29:33.615701 | controller | } 2026-03-16 16:29:33.836719 | controller | changed: 2026-03-16 16:29:33.836809 | controller | { 2026-03-16 16:29:33.836838 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2026-03-16 16:29:33.836861 | controller | } 2026-03-16 16:29:34.056757 | controller | changed: 2026-03-16 16:29:34.056849 | controller | { 2026-03-16 16:29:34.056877 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com" 2026-03-16 16:29:34.056899 | controller | } 2026-03-16 16:29:34.288228 | controller | changed: 2026-03-16 16:29:34.288324 | controller | { 2026-03-16 16:29:34.288355 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2026-03-16 16:29:34.288379 | controller | } 2026-03-16 16:29:34.502367 | controller | changed: 2026-03-16 16:29:34.502451 | controller | { 2026-03-16 16:29:34.502480 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2026-03-16 16:29:34.502503 | controller | } 2026-03-16 16:29:34.735599 | controller | changed: 2026-03-16 16:29:34.735682 | controller | { 2026-03-16 16:29:34.735710 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2026-03-16 16:29:34.735732 | controller | } 2026-03-16 16:29:34.962402 | controller | changed: 2026-03-16 16:29:34.962484 | controller | { 2026-03-16 16:29:34.962512 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com" 2026-03-16 16:29:34.962536 | controller | } 2026-03-16 16:29:35.192710 | controller | changed: 2026-03-16 16:29:35.192789 | controller | { 2026-03-16 16:29:35.192817 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb" 2026-03-16 16:29:35.192847 | controller | } 2026-03-16 16:29:35.416195 | controller | changed: 2026-03-16 16:29:35.416280 | controller | { 2026-03-16 16:29:35.416343 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2026-03-16 16:29:35.416369 | controller | } 2026-03-16 16:29:35.650358 | controller | changed: 2026-03-16 16:29:35.650440 | controller | { 2026-03-16 16:29:35.650468 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2026-03-16 16:29:35.650490 | controller | } 2026-03-16 16:29:35.897048 | controller | changed: 2026-03-16 16:29:35.897138 | controller | { 2026-03-16 16:29:35.897168 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2026-03-16 16:29:35.897191 | controller | } 2026-03-16 16:29:36.134775 | controller | changed: 2026-03-16 16:29:36.134865 | controller | { 2026-03-16 16:29:36.134892 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2026-03-16 16:29:36.134915 | controller | } 2026-03-16 16:29:36.357208 | controller | changed: 2026-03-16 16:29:36.358152 | controller | { 2026-03-16 16:29:36.358203 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com" 2026-03-16 16:29:36.358230 | controller | } 2026-03-16 16:29:36.598152 | controller | changed: 2026-03-16 16:29:36.598231 | controller | { 2026-03-16 16:29:36.598261 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2026-03-16 16:29:36.598283 | controller | } 2026-03-16 16:29:36.850801 | controller | changed: 2026-03-16 16:29:36.850886 | controller | { 2026-03-16 16:29:36.850916 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2026-03-16 16:29:36.850938 | controller | } 2026-03-16 16:29:37.085052 | controller | changed: 2026-03-16 16:29:37.085151 | controller | { 2026-03-16 16:29:37.085179 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2026-03-16 16:29:37.085202 | controller | } 2026-03-16 16:29:37.135933 | 2026-03-16 16:29:37.136031 | TASK [Set timezone to UTC] 2026-03-16 16:29:37.670009 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2026-03-16 16:29:37.688233 | 2026-03-16 16:29:37.688335 | TASK [Create nodepool directory] 2026-03-16 16:29:38.032168 | controller | changed 2026-03-16 16:29:38.044473 | 2026-03-16 16:29:38.044560 | TASK [Create nodepool sub_nodes file] 2026-03-16 16:29:38.761073 | controller | changed 2026-03-16 16:29:38.766241 | 2026-03-16 16:29:38.766347 | TASK [Create nodepool sub_nodes_private file] 2026-03-16 16:29:39.567474 | controller | changed 2026-03-16 16:29:39.577889 | 2026-03-16 16:29:39.577955 | LOOP [Populate nodepool sub_nodes file] 2026-03-16 16:29:39.677548 | 2026-03-16 16:29:39.677688 | LOOP [Populate nodepool sub_nodes_private file] 2026-03-16 16:29:39.841010 | 2026-03-16 16:29:39.841175 | TASK [Create nodepool primary file] 2026-03-16 16:29:39.926990 | controller | skipping: Conditional result was False 2026-03-16 16:29:39.934598 | 2026-03-16 16:29:39.934675 | TASK [Create nodepool node_private for this node] 2026-03-16 16:29:40.823105 | controller | changed 2026-03-16 16:29:40.846255 | 2026-03-16 16:29:40.846356 | LOOP [Copy ssh keys to nodepool directory] 2026-03-16 16:29:41.826264 | controller | ok: Item: id_rsa Runtime: 0:00:00.008409 2026-03-16 16:29:41.826469 | 2026-03-16 16:29:42.032434 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.007039 2026-03-16 16:29:42.048532 | 2026-03-16 16:29:42.048642 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2026-03-16 16:29:42.878699 | controller | changed 2026-03-16 16:29:42.884038 | 2026-03-16 16:29:42.884110 | TASK [Validate sudoers config after edits] 2026-03-16 16:29:43.362243 | controller | /etc/sudoers: parsed OK 2026-03-16 16:29:43.362351 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2026-03-16 16:29:43.362362 | controller | /etc/sudoers.d/zuul: parsed OK 2026-03-16 16:29:43.362369 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2026-03-16 16:29:43.524860 | controller | ok: Runtime: 0:00:00.004998 2026-03-16 16:29:43.535028 | 2026-03-16 16:29:43.535127 | TASK [Show the environment passed in to job shell scripts] 2026-03-16 16:29:43.904438 | controller | SHELL=/bin/bash 2026-03-16 16:29:43.904480 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2026-03-16 16:29:43.904489 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2026-03-16 16:29:43.904497 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/70/3770/235f23c012043ef9e6fbd3c71c40da700f09e62a 2026-03-16 16:29:43.904503 | controller | PWD=/home/zuul 2026-03-16 16:29:43.904508 | controller | ZUUL_PIPELINE=github-check 2026-03-16 16:29:43.904543 | controller | LOGNAME=zuul 2026-03-16 16:29:43.904550 | controller | XDG_SESSION_TYPE=tty 2026-03-16 16:29:43.904556 | controller | _=/usr/bin/env 2026-03-16 16:29:43.904561 | controller | MOTD_SHOWN=pam 2026-03-16 16:29:43.904566 | controller | HOME=/home/zuul 2026-03-16 16:29:43.904572 | controller | LANG=en_US.UTF-8 2026-03-16 16:29:43.904577 | controller | SSH_CONNECTION=192.168.26.12 35150 192.168.26.198 22 2026-03-16 16:29:43.904582 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET 2026-03-16 16:29:43.904590 | controller | ZUUL_CHANGE_IDS=3770,235f23c012043ef9e6fbd3c71c40da700f09e62a 2026-03-16 16:29:43.904596 | controller | WORKSPACE=/home/zuul/workspace 2026-03-16 16:29:43.904601 | controller | XDG_SESSION_CLASS=user 2026-03-16 16:29:43.904606 | controller | SELINUX_ROLE_REQUESTED= 2026-03-16 16:29:43.904612 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2026-03-16 16:29:43.904617 | controller | USER=zuul 2026-03-16 16:29:43.904622 | controller | ZUUL_VOTING=True 2026-03-16 16:29:43.904627 | controller | BUILD_TIMEOUT=1800000 2026-03-16 16:29:43.904633 | controller | SELINUX_USE_CURRENT_RANGE= 2026-03-16 16:29:43.904638 | controller | SHLVL=1 2026-03-16 16:29:43.904643 | controller | ZUUL_PATCHSET=235f23c012043ef9e6fbd3c71c40da700f09e62a 2026-03-16 16:29:43.904649 | controller | XDG_SESSION_ID=1 2026-03-16 16:29:43.904654 | controller | ZUUL_BRANCH=main 2026-03-16 16:29:43.904659 | controller | XDG_RUNTIME_DIR=/run/user/1000 2026-03-16 16:29:43.904664 | controller | SSH_CLIENT=192.168.26.12 35150 22 2026-03-16 16:29:43.904671 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2026-03-16 16:29:43.904676 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2026-03-16 16:29:43.904682 | controller | which_declare=declare -f 2026-03-16 16:29:43.904687 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2026-03-16 16:29:43.904693 | controller | SELINUX_LEVEL_REQUESTED= 2026-03-16 16:29:43.904715 | controller | ZUUL_CHANGE=3770 2026-03-16 16:29:43.904724 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2026-03-16 16:29:43.904729 | controller | ZUUL_UUID=6fdfba3260eb4aadac17e1fb112b6c75 2026-03-16 16:29:43.904734 | controller | BASH_FUNC_which%%=() { ( alias; 2026-03-16 16:29:43.904740 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2026-03-16 16:29:43.904745 | controller | } 2026-03-16 16:29:44.121577 | controller | ok: Runtime: 0:00:00.005129 2026-03-16 16:29:44.131804 | 2026-03-16 16:29:44.131868 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2026-03-16 16:29:44.202213 | controller | skipping: Conditional result was False 2026-03-16 16:29:44.208123 | 2026-03-16 16:29:44.208204 | TASK [Symlink /home/zuul-worker/workspace] 2026-03-16 16:29:44.811133 | controller | skipping: Conditional result was False 2026-03-16 16:29:44.817925 | 2026-03-16 16:29:44.818152 | TASK [Ensure legacy workspace directory] 2026-03-16 16:29:45.126781 | controller | changed 2026-03-16 16:29:45.223312 | 2026-03-16 16:29:45.223407 | PLAY RECAP 2026-03-16 16:29:45.223457 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2026-03-16 16:29:45.223481 | 2026-03-16 16:29:45.752170 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2026-03-16 16:29:45.752925 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2026-03-16 16:29:47.717613 | 2026-03-16 16:29:47.717728 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2026-03-16 16:29:47.805281 | 2026-03-16 16:29:47.813195 | TASK [Create zuul-output directory] 2026-03-16 16:29:48.590159 | controller | changed 2026-03-16 16:29:48.602438 | 2026-03-16 16:29:48.602516 | TASK [Slurp Zuul inventory test] 2026-03-16 16:29:49.809599 | controller -> localhost | ok 2026-03-16 16:29:49.819670 | 2026-03-16 16:29:49.819741 | TASK [Save zuul inventory] 2026-03-16 16:29:51.319862 | controller | changed 2026-03-16 16:29:51.325936 | 2026-03-16 16:29:51.326016 | TASK [Save zuul vars without the change_message] 2026-03-16 16:29:52.240802 | controller | changed 2026-03-16 16:29:52.321366 | 2026-03-16 16:29:52.321447 | PLAY RECAP 2026-03-16 16:29:52.321493 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-16 16:29:52.321518 | 2026-03-16 16:29:52.660133 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2026-03-16 16:29:52.660881 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2026-03-16 16:29:54.624191 | 2026-03-16 16:29:54.624292 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2026-03-16 16:29:54.689025 | 2026-03-16 16:29:54.689148 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2026-03-16 16:29:54.733688 | controller | ok 2026-03-16 16:29:54.789579 | 2026-03-16 16:29:54.789690 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2026-03-16 16:29:54.848648 | controller | skipping: Conditional result was False 2026-03-16 16:29:54.854518 | 2026-03-16 16:29:54.854591 | TASK [mirror-info-fork : Create /etc/ci] 2026-03-16 16:29:55.450565 | controller | ok 2026-03-16 16:29:55.461077 | 2026-03-16 16:29:55.461186 | TASK [mirror-info-fork : Install ci_mirror script] 2026-03-16 16:29:56.553496 | controller | ok 2026-03-16 16:29:56.588538 | 2026-03-16 16:29:56.588910 | TASK [Prepare workspace] 2026-03-16 16:29:56.619472 | controller | ok 2026-03-16 16:29:56.688624 | 2026-03-16 16:29:56.688724 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-16 16:29:57.261145 | controller | ok 2026-03-16 16:29:57.273325 | 2026-03-16 16:29:57.273414 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-16 16:30:04.472515 | controller | Output suppressed because no_log was given 2026-03-16 16:30:04.499390 | 2026-03-16 16:30:04.499781 | LOOP [Create zuul-output directory] 2026-03-16 16:30:04.725122 | controller | changed: "/home/zuul/ci-framework-data/logs" 2026-03-16 16:30:04.896184 | controller | ok: "/home/zuul/zuul-output/logs" 2026-03-16 16:30:04.906827 | 2026-03-16 16:30:04.906941 | TASK [Install required packages] 2026-03-16 16:30:47.414491 | controller | changed 2026-03-16 16:30:47.420069 | 2026-03-16 16:30:47.420182 | TASK [Install venv] 2026-03-16 16:31:01.747533 | controller | ERROR 2026-03-16 16:31:01.747742 | controller | { 2026-03-16 16:31:01.747779 | controller | "msg": "gmake: *** [Makefile:56: setup_tests] Error 1", 2026-03-16 16:31:01.747802 | controller | "rc": 2 2026-03-16 16:31:01.747822 | controller | } failure 2026-03-16 16:31:01.767554 | 2026-03-16 16:31:01.767618 | PLAY RECAP 2026-03-16 16:31:01.767669 | controller | ok: 6 changed: 3 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0 2026-03-16 16:31:01.767693 | 2026-03-16 16:31:01.924061 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2026-03-16 16:31:01.924985 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2026-03-16 16:31:02.556287 | 2026-03-16 16:31:02.556406 | PLAY [Run ci/playbooks/collect-logs.yml] 2026-03-16 16:31:02.578111 | 2026-03-16 16:31:02.578190 | TASK [Filter out host if needed] 2026-03-16 16:31:02.587422 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2026-03-16 16:31:02.592391 | 2026-03-16 16:31:02.592459 | TASK [Ensure file is present] 2026-03-16 16:31:03.007424 | controller | ok 2026-03-16 16:31:03.016219 | 2026-03-16 16:31:03.016350 | TASK [Manage molecule report file] 2026-03-16 16:31:03.546396 | controller | skipping: Conditional result was False 2026-03-16 16:31:03.552106 | 2026-03-16 16:31:03.552194 | TASK [Check if we get ci-framework-data basedir] 2026-03-16 16:31:03.747939 | controller | ok 2026-03-16 16:31:03.754416 | 2026-03-16 16:31:03.754494 | TASK [Create ci-framework-data log directory for zuul] 2026-03-16 16:31:04.068267 | controller | changed 2026-03-16 16:31:04.076271 | 2026-03-16 16:31:04.076390 | TASK [Copy ci-framework interesting files] 2026-03-16 16:31:04.396308 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2026-03-16 16:31:04.604611 | controller | changed 2026-03-16 16:31:04.609900 | 2026-03-16 16:31:04.609976 | TASK [Get SELinux listing] 2026-03-16 16:31:05.136578 | controller | changed 2026-03-16 16:31:05.142755 | 2026-03-16 16:31:05.142846 | TASK [Generate log index] 2026-03-16 16:31:05.821443 | controller | changed 2026-03-16 16:31:05.830194 | 2026-03-16 16:31:05.830284 | TASK [Get some env related data] 2026-03-16 16:31:06.855929 | controller | changed 2026-03-16 16:31:06.862452 | 2026-03-16 16:31:06.862538 | TASK [Generate list of logs to collect in home directory] 2026-03-16 16:31:07.185059 | controller | ok: All paths examined 2026-03-16 16:31:07.190762 | 2026-03-16 16:31:07.190847 | LOOP [Copy logs from home directory] 2026-03-16 16:31:07.209973 | 2026-03-16 16:31:07.210752 | TASK [Copy crio stats log file] 2026-03-16 16:31:07.224717 | controller | skipping: Conditional result was False 2026-03-16 16:31:07.231335 | 2026-03-16 16:31:07.231424 | TASK [Get SELinux related data] 2026-03-16 16:31:07.467258 | controller | 2026-03-16 16:31:07.783279 | controller | ERROR 2026-03-16 16:31:07.783444 | controller | { 2026-03-16 16:31:07.783479 | controller | "delta": "0:00:00.008591", 2026-03-16 16:31:07.783501 | controller | "end": "2026-03-16 16:31:07.468110", 2026-03-16 16:31:07.783520 | controller | "msg": "non-zero return code", 2026-03-16 16:31:07.783536 | controller | "rc": 1, 2026-03-16 16:31:07.783553 | controller | "start": "2026-03-16 16:31:07.459519" 2026-03-16 16:31:07.783569 | controller | } 2026-03-16 16:31:07.783592 | controller | ERROR: Ignoring Errors 2026-03-16 16:31:07.789166 | 2026-03-16 16:31:07.789234 | TASK [Create system configuration directory] 2026-03-16 16:31:07.977770 | controller | changed 2026-03-16 16:31:07.984839 | 2026-03-16 16:31:07.984927 | TASK [Get some of the system configurations] 2026-03-16 16:31:08.534612 | controller | changed 2026-03-16 16:31:08.540164 | 2026-03-16 16:31:08.540232 | TASK [Copy generated documentation if available] 2026-03-16 16:31:08.564102 | controller | skipping: Conditional result was False 2026-03-16 16:31:08.569653 | 2026-03-16 16:31:08.569718 | TASK [Copy generated AsciiDoc documentation if available] 2026-03-16 16:31:08.613415 | controller | skipping: Conditional result was False 2026-03-16 16:31:08.619109 | 2026-03-16 16:31:08.619183 | TASK [Compress logs bigger than 2MB] 2026-03-16 16:31:09.148756 | controller | changed 2026-03-16 16:31:09.173356 | 2026-03-16 16:31:09.173465 | TASK [Copy files from workspace on node] 2026-03-16 16:31:09.198184 | controller | ok 2026-03-16 16:31:09.225502 | 2026-03-16 16:31:09.225604 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-16 16:31:09.249795 | controller | skipping: Conditional result was False 2026-03-16 16:31:09.256220 | 2026-03-16 16:31:09.256357 | TASK [fetch-output : Set log path for single node] 2026-03-16 16:31:09.286053 | controller | ok 2026-03-16 16:31:09.291640 | 2026-03-16 16:31:09.291729 | LOOP [fetch-output : Ensure local output dirs] 2026-03-16 16:31:09.692810 | controller -> localhost | ok: "/var/lib/zuul/builds/6fdfba3260eb4aadac17e1fb112b6c75/work/logs" 2026-03-16 16:31:09.693049 | 2026-03-16 16:31:09.960624 | controller -> localhost | changed: "/var/lib/zuul/builds/6fdfba3260eb4aadac17e1fb112b6c75/work/artifacts" 2026-03-16 16:31:10.226007 | controller -> localhost | changed: "/var/lib/zuul/builds/6fdfba3260eb4aadac17e1fb112b6c75/work/docs" 2026-03-16 16:31:10.244877 | 2026-03-16 16:31:10.244999 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-16 16:31:10.962274 | controller | changed: 2026-03-16 16:31:10.962497 | controller | .d..t...... ./ 2026-03-16 16:31:10.962530 | controller | >f+++++++++ README.html 2026-03-16 16:31:10.962555 | controller | >f+++++++++ dmesg.log 2026-03-16 16:31:10.962576 | controller | >f+++++++++ installed-pkgs.log 2026-03-16 16:31:10.962596 | controller | >f+++++++++ python.log 2026-03-16 16:31:10.962614 | controller | >f+++++++++ registries.conf 2026-03-16 16:31:10.962634 | controller | >f+++++++++ selinux-denials.log 2026-03-16 16:31:10.962652 | controller | >f+++++++++ selinux-listing.log 2026-03-16 16:31:10.962669 | controller | cd+++++++++ ci-framework-data/ 2026-03-16 16:31:10.962687 | controller | cd+++++++++ ci-framework-data/artifacts/ 2026-03-16 16:31:10.962705 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2026-03-16 16:31:10.962725 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2026-03-16 16:31:10.962743 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2026-03-16 16:31:10.962760 | controller | cd+++++++++ ci-framework-data/logs/ 2026-03-16 16:31:10.962778 | controller | cd+++++++++ registries.conf.d/ 2026-03-16 16:31:10.962795 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2026-03-16 16:31:10.962812 | controller | cd+++++++++ system-config/ 2026-03-16 16:31:10.962829 | controller | cd+++++++++ system-config/libvirt/ 2026-03-16 16:31:10.962845 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2026-03-16 16:31:10.962863 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2026-03-16 16:31:11.482821 | controller | changed: .d..t...... ./ 2026-03-16 16:31:11.933971 | controller | changed: .d..t...... ./ 2026-03-16 16:31:11.952682 | 2026-03-16 16:31:11.952767 | TASK [Return artifact to Zuul] 2026-03-16 16:31:12.012990 | controller | skipping: Conditional result was False 2026-03-16 16:31:12.066229 | 2026-03-16 16:31:12.066315 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2026-03-16 16:31:12.066422 | 2026-03-16 16:31:12.066449 | PLAY RECAP 2026-03-16 16:31:12.066487 | controller | ok: 15 changed: 11 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 1 2026-03-16 16:31:12.066508 | 2026-03-16 16:31:12.232071 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2026-03-16 16:31:12.232831 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-03-16 16:31:12.997127 | 2026-03-16 16:31:12.997230 | PLAY [all] 2026-03-16 16:31:13.061462 | 2026-03-16 16:31:13.061570 | TASK [include_role : fetch-output] 2026-03-16 16:31:13.110654 | controller | ok 2026-03-16 16:31:13.144653 | 2026-03-16 16:31:13.144761 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-16 16:31:13.241993 | controller | skipping: Conditional result was False 2026-03-16 16:31:13.248136 | 2026-03-16 16:31:13.248229 | TASK [fetch-output : Set log path for single node] 2026-03-16 16:31:13.331417 | controller | ok 2026-03-16 16:31:13.336728 | 2026-03-16 16:31:13.336814 | LOOP [fetch-output : Ensure local output dirs] 2026-03-16 16:31:14.230220 | controller -> localhost | ok: "/var/lib/zuul/builds/6fdfba3260eb4aadac17e1fb112b6c75/work/logs" 2026-03-16 16:31:14.471899 | controller -> localhost | ok: "/var/lib/zuul/builds/6fdfba3260eb4aadac17e1fb112b6c75/work/artifacts" 2026-03-16 16:31:14.888953 | controller -> localhost | ok: "/var/lib/zuul/builds/6fdfba3260eb4aadac17e1fb112b6c75/work/docs" 2026-03-16 16:31:14.900554 | 2026-03-16 16:31:14.900660 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-16 16:31:15.948942 | controller | ok 2026-03-16 16:31:15.949147 | controller | ok: All items complete 2026-03-16 16:31:15.949181 | 2026-03-16 16:31:16.572957 | controller | ok 2026-03-16 16:31:17.032548 | controller | ok 2026-03-16 16:31:17.048979 | 2026-03-16 16:31:17.049089 | TASK [include_role : fetch-output-openshift] 2026-03-16 16:31:17.068707 | controller | skipping: Conditional result was False 2026-03-16 16:31:17.074812 | 2026-03-16 16:31:17.074906 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-16 16:31:18.220602 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.050078 2026-03-16 16:31:18.762537 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007775 2026-03-16 16:31:18.817671 | 2026-03-16 16:31:18.817771 | PLAY [all] 2026-03-16 16:31:18.845289 | 2026-03-16 16:31:18.845398 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-16 16:31:19.296152 | controller | changed 2026-03-16 16:31:19.382962 | 2026-03-16 16:31:19.383171 | PLAY RECAP 2026-03-16 16:31:19.383223 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-03-16 16:31:19.383248 | 2026-03-16 16:31:19.732907 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-03-16 16:31:19.733674 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2026-03-16 16:31:20.922583 | 2026-03-16 16:31:20.922690 | PLAY [localhost] 2026-03-16 16:31:20.942144 | 2026-03-16 16:31:20.942237 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-16 16:31:21.639139 | localhost | changed 2026-03-16 16:31:21.643830 | 2026-03-16 16:31:21.643938 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-16 16:31:21.709088 | localhost | ok 2026-03-16 16:31:21.734865 | 2026-03-16 16:31:21.734966 | TASK [add-fileserver : Create SSH private key tempfile] 2026-03-16 16:31:22.391372 | localhost | changed 2026-03-16 16:31:22.396598 | 2026-03-16 16:31:22.396748 | TASK [add-fileserver : Create SSH private key from secret] 2026-03-16 16:31:24.099358 | localhost | changed 2026-03-16 16:31:24.105101 | 2026-03-16 16:31:24.105193 | TASK [add-fileserver : Add fileserver ssh key] 2026-03-16 16:31:25.130227 | localhost | Identity added: /var/lib/zuul/builds/6fdfba3260eb4aadac17e1fb112b6c75/work/tmp/ansible.ron7wpf8 (/var/lib/zuul/builds/6fdfba3260eb4aadac17e1fb112b6c75/work/tmp/ansible.ron7wpf8) 2026-03-16 16:31:25.130432 | localhost | ok: Runtime: 0:00:00.018544 2026-03-16 16:31:25.135402 | 2026-03-16 16:31:25.135484 | TASK [add-fileserver : Remove SSH private key from disk] 2026-03-16 16:31:25.992844 | localhost | ok: Runtime: 0:00:00.042086 2026-03-16 16:31:25.997582 | 2026-03-16 16:31:25.997666 | TASK [add-fileserver : Add fileserver to inventory] 2026-03-16 16:31:26.151484 | localhost | changed 2026-03-16 16:31:26.156017 | 2026-03-16 16:31:26.156108 | TASK [add-fileserver : Add fileserver server to known hosts] 2026-03-16 16:31:27.365807 | localhost | changed 2026-03-16 16:31:27.439874 | 2026-03-16 16:31:27.439961 | PLAY [localhost] 2026-03-16 16:31:27.472040 | 2026-03-16 16:31:27.472139 | TASK [Generate bulk log download script] 2026-03-16 16:31:27.609898 | localhost | ok 2026-03-16 16:31:27.658157 | 2026-03-16 16:31:27.658261 | TASK [local-log-download : Check API endpoint is defined] 2026-03-16 16:31:27.738531 | localhost | ok: All assertions passed 2026-03-16 16:31:27.743458 | 2026-03-16 16:31:27.743542 | TASK [local-log-download : Create download script] 2026-03-16 16:31:28.893105 | localhost -> localhost | changed 2026-03-16 16:31:28.916924 | 2026-03-16 16:31:28.917001 | TASK [Register quick-download link] 2026-03-16 16:31:29.032769 | localhost | ok 2026-03-16 16:31:29.076684 | 2026-03-16 16:31:29.076769 | PLAY [logserver.rdoproject.org] 2026-03-16 16:31:29.128606 | 2026-03-16 16:31:29.128687 | TASK [Set zuul-log-path fact] 2026-03-16 16:31:29.191111 | logserver.rdoproject.org | ok 2026-03-16 16:31:29.211674 | 2026-03-16 16:31:29.211765 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-16 16:31:29.297440 | logserver.rdoproject.org | ok 2026-03-16 16:31:29.307770 | 2026-03-16 16:31:29.307849 | TASK [upload-logs : Create log directories] 2026-03-16 16:31:31.211268 | logserver.rdoproject.org | changed 2026-03-16 16:31:31.214557 | 2026-03-16 16:31:31.214819 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-16 16:31:31.871030 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.024243 2026-03-16 16:31:31.876130 | 2026-03-16 16:31:31.876215 | TASK [upload-logs : Upload logs to log server] 2026-03-16 16:31:34.225475 | logserver.rdoproject.org | Output suppressed because no_log was given 2026-03-16 16:31:34.228672 | 2026-03-16 16:31:34.228742 | LOOP [upload-logs : Compress console log and json output] 2026-03-16 16:31:34.412056 | logserver.rdoproject.org | skipping: Conditional result was False 2026-03-16 16:31:34.427289 | logserver.rdoproject.org | skipping: Conditional result was False 2026-03-16 16:31:34.441050 | 2026-03-16 16:31:34.441150 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-16 16:31:34.540620 | logserver.rdoproject.org | skipping: Conditional result was False 2026-03-16 16:31:34.540904 | 2026-03-16 16:31:34.541504 | logserver.rdoproject.org | skipping: Conditional result was False 2026-03-16 16:31:34.549567 | 2026-03-16 16:31:34.549653 | LOOP [upload-logs : Upload console log and json output]