2025-11-25 09:07:22.242006 | Job console starting... 2025-11-25 09:07:22.258125 | Updating repositories 2025-11-25 09:07:22.283268 | Preparing job workspace 2025-11-25 09:07:27.958653 | Running Ansible setup... 2025-11-25 09:07:33.205074 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-11-25 09:07:33.807500 | 2025-11-25 09:07:33.807630 | PLAY [localhost] 2025-11-25 09:07:33.816818 | 2025-11-25 09:07:33.816922 | TASK [Gathering Facts] 2025-11-25 09:07:34.741344 | localhost | ok 2025-11-25 09:07:34.754018 | 2025-11-25 09:07:34.754099 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-25 09:07:35.143799 | localhost -> localhost | changed 2025-11-25 09:07:35.149607 | 2025-11-25 09:07:35.149678 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-25 09:07:36.091415 | localhost -> localhost | changed 2025-11-25 09:07:36.111099 | 2025-11-25 09:07:36.111434 | TASK [Setup log path fact] 2025-11-25 09:07:36.139497 | localhost | ok 2025-11-25 09:07:36.161980 | 2025-11-25 09:07:36.162079 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-25 09:07:36.202528 | localhost | ok 2025-11-25 09:07:36.213620 | 2025-11-25 09:07:36.213708 | TASK [emit-job-header : Print job information] 2025-11-25 09:07:36.265652 | # Job Information 2025-11-25 09:07:36.265856 | Ansible Version: 2.15.12 2025-11-25 09:07:36.265893 | Job: build-push-container-cifmw-client 2025-11-25 09:07:36.265921 | Pipeline: github-check 2025-11-25 09:07:36.265946 | Executor: ze03.softwarefactory-project.io 2025-11-25 09:07:36.265970 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3397 2025-11-25 09:07:36.265996 | Log URL (when completed): https://logserver.rdoproject.org/0d2/rdoproject.org/0d2079dcf64a47da8d436cbcceb38660/ 2025-11-25 09:07:36.266020 | Event ID: f8f0ee60-c9dd-11f0-8c1b-57ef65c2fc06 2025-11-25 09:07:36.271268 | 2025-11-25 09:07:36.271352 | LOOP [emit-job-header : Print node information] 2025-11-25 09:07:36.380625 | localhost | ok: 2025-11-25 09:07:36.380857 | localhost | # Node Information 2025-11-25 09:07:36.380901 | localhost | Inventory Hostname: controller 2025-11-25 09:07:36.380947 | localhost | Hostname: np0005534705 2025-11-25 09:07:36.380969 | localhost | Username: zuul 2025-11-25 09:07:36.380991 | localhost | Distro: CentOS 9 2025-11-25 09:07:36.381010 | localhost | Provider: vexxhost-nodepool-tripleo 2025-11-25 09:07:36.381028 | localhost | Region: RegionOne 2025-11-25 09:07:36.381045 | localhost | Label: cloud-centos-9-stream-tripleo 2025-11-25 09:07:36.381061 | localhost | Product Name: OpenStack Nova 2025-11-25 09:07:36.381078 | localhost | Interface IP: 38.102.83.190 2025-11-25 09:07:36.418579 | 2025-11-25 09:07:36.418779 | PLAY [all] 2025-11-25 09:07:36.429978 | 2025-11-25 09:07:36.430106 | TASK [Gather network facts] 2025-11-25 09:07:36.946885 | controller | ok 2025-11-25 09:07:36.967200 | 2025-11-25 09:07:36.967326 | TASK [include_role : start-zuul-console] 2025-11-25 09:07:36.988640 | controller | ok 2025-11-25 09:07:37.007570 | 2025-11-25 09:07:37.008029 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-25 09:07:37.410442 | controller | ok 2025-11-25 09:07:37.420574 | 2025-11-25 09:07:37.420666 | TASK [include_role : add-build-sshkey] 2025-11-25 09:07:37.451524 | controller | ok 2025-11-25 09:07:37.468309 | 2025-11-25 09:07:37.468433 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-25 09:07:37.696790 | controller -> localhost | ok 2025-11-25 09:07:37.703833 | 2025-11-25 09:07:37.704116 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-25 09:07:37.742550 | controller | ok 2025-11-25 09:07:37.755674 | controller | included: /var/lib/zuul/builds/0d2079dcf64a47da8d436cbcceb38660/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-25 09:07:37.764036 | 2025-11-25 09:07:37.764247 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-25 09:07:38.681298 | controller -> localhost | Generating public/private rsa key pair. 2025-11-25 09:07:38.681545 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0d2079dcf64a47da8d436cbcceb38660/work/0d2079dcf64a47da8d436cbcceb38660_id_rsa. 2025-11-25 09:07:38.681594 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0d2079dcf64a47da8d436cbcceb38660/work/0d2079dcf64a47da8d436cbcceb38660_id_rsa.pub. 2025-11-25 09:07:38.681628 | controller -> localhost | The key fingerprint is: 2025-11-25 09:07:38.681658 | controller -> localhost | SHA256:Q/DU0/sKVb8dCGnSMXLiHVP2zEQ1Mh4P8y0bOMSMK2g zuul-build-sshkey 2025-11-25 09:07:38.681695 | controller -> localhost | The key's randomart image is: 2025-11-25 09:07:38.681745 | controller -> localhost | +---[RSA 3072]----+ 2025-11-25 09:07:38.681805 | controller -> localhost | | . .+.@=O.+o| 2025-11-25 09:07:38.681837 | controller -> localhost | | +..O*X.# o| 2025-11-25 09:07:38.681865 | controller -> localhost | | .o.o++++B.| 2025-11-25 09:07:38.681895 | controller -> localhost | | E.. . oo =.| 2025-11-25 09:07:38.681931 | controller -> localhost | | . S. . .. +| 2025-11-25 09:07:38.681966 | controller -> localhost | | .. ...| 2025-11-25 09:07:38.682000 | controller -> localhost | | . . | 2025-11-25 09:07:38.682036 | controller -> localhost | | . | 2025-11-25 09:07:38.682064 | controller -> localhost | | | 2025-11-25 09:07:38.682090 | controller -> localhost | +----[SHA256]-----+ 2025-11-25 09:07:38.682152 | controller -> localhost | ok: Runtime: 0:00:00.538953 2025-11-25 09:07:38.698483 | 2025-11-25 09:07:38.698653 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-25 09:07:38.727734 | controller | ok 2025-11-25 09:07:38.738413 | controller | included: /var/lib/zuul/builds/0d2079dcf64a47da8d436cbcceb38660/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-25 09:07:38.746249 | 2025-11-25 09:07:38.746312 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-25 09:07:38.770311 | controller | skipping: Conditional result was False 2025-11-25 09:07:38.776120 | 2025-11-25 09:07:38.776186 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-25 09:07:39.236665 | controller | changed 2025-11-25 09:07:39.242813 | 2025-11-25 09:07:39.242912 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-25 09:07:39.486295 | controller | ok 2025-11-25 09:07:39.491454 | 2025-11-25 09:07:39.491521 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-25 09:07:40.405840 | controller | changed 2025-11-25 09:07:40.421356 | 2025-11-25 09:07:40.422344 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-25 09:07:41.441631 | controller | changed 2025-11-25 09:07:41.452970 | 2025-11-25 09:07:41.453110 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-25 09:07:41.480629 | controller | skipping: Conditional result was False 2025-11-25 09:07:41.493681 | 2025-11-25 09:07:41.493878 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-25 09:07:41.929759 | controller -> localhost | changed 2025-11-25 09:07:41.942190 | 2025-11-25 09:07:41.942360 | TASK [add-build-sshkey : Add back temp key] 2025-11-25 09:07:42.265478 | controller -> localhost | Identity added: /var/lib/zuul/builds/0d2079dcf64a47da8d436cbcceb38660/work/0d2079dcf64a47da8d436cbcceb38660_id_rsa (zuul-build-sshkey) 2025-11-25 09:07:42.265726 | controller -> localhost | ok: Runtime: 0:00:00.008490 2025-11-25 09:07:42.274167 | 2025-11-25 09:07:42.274236 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-25 09:07:42.691577 | controller | ok 2025-11-25 09:07:42.696577 | 2025-11-25 09:07:42.696643 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-25 09:07:42.723795 | controller | skipping: Conditional result was False 2025-11-25 09:07:42.759417 | 2025-11-25 09:07:42.759533 | TASK [include_role : validate-host] 2025-11-25 09:07:42.777830 | controller | ok 2025-11-25 09:07:42.796982 | 2025-11-25 09:07:42.797064 | TASK [validate-host : Define zuul_info_dir fact] 2025-11-25 09:07:42.825796 | controller | ok 2025-11-25 09:07:42.830956 | 2025-11-25 09:07:42.831043 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-11-25 09:07:43.102569 | controller -> localhost | ok 2025-11-25 09:07:43.139200 | 2025-11-25 09:07:43.139305 | TASK [validate-host : Collect information about the host] 2025-11-25 09:07:43.938077 | controller | ok 2025-11-25 09:07:43.958166 | 2025-11-25 09:07:43.958303 | TASK [validate-host : Sanitize hostname] 2025-11-25 09:07:44.039395 | controller | ok 2025-11-25 09:07:44.044636 | 2025-11-25 09:07:44.044868 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-11-25 09:07:44.488105 | controller -> localhost | changed 2025-11-25 09:07:44.502098 | 2025-11-25 09:07:44.502283 | TASK [validate-host : Collect information about zuul worker] 2025-11-25 09:07:44.952116 | controller | ok 2025-11-25 09:07:44.966051 | 2025-11-25 09:07:44.966295 | TASK [validate-host : Write out all zuul information for each host] 2025-11-25 09:07:45.440782 | controller -> localhost | changed 2025-11-25 09:07:45.451063 | 2025-11-25 09:07:45.451132 | TASK [include_role : prepare-workspace-openshift] 2025-11-25 09:07:45.465003 | controller | skipping: Conditional result was False 2025-11-25 09:07:45.470071 | 2025-11-25 09:07:45.470135 | TASK [include_role : remove-zuul-sshkey] 2025-11-25 09:07:45.483169 | controller | skipping: Conditional result was False 2025-11-25 09:07:45.488802 | 2025-11-25 09:07:45.488870 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-11-25 09:07:45.795606 | controller | ok: "logs" 2025-11-25 09:07:45.795896 | controller | ok: All items complete 2025-11-25 09:07:45.795927 | 2025-11-25 09:07:46.050976 | controller | ok: "artifacts" 2025-11-25 09:07:46.293282 | controller | ok: "docs" 2025-11-25 09:07:46.303663 | 2025-11-25 09:07:46.303850 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-11-25 09:07:46.637582 | controller | changed: "logs" 2025-11-25 09:07:46.889913 | controller | changed: "artifacts" 2025-11-25 09:07:47.113475 | controller | changed: "docs" 2025-11-25 09:07:47.145375 | 2025-11-25 09:07:47.145500 | PLAY RECAP 2025-11-25 09:07:47.145562 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-11-25 09:07:47.145599 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-25 09:07:47.145625 | 2025-11-25 09:07:47.287227 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-11-25 09:07:47.288617 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-11-25 09:07:47.942465 | 2025-11-25 09:07:47.942580 | PLAY [all] 2025-11-25 09:07:47.963436 | 2025-11-25 09:07:47.963530 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-11-25 09:07:48.033133 | controller | ok 2025-11-25 09:07:48.038623 | 2025-11-25 09:07:48.038715 | TASK [mirror-info-fork : Create /etc/ci] 2025-11-25 09:07:48.486770 | controller | changed 2025-11-25 09:07:48.492666 | 2025-11-25 09:07:48.492773 | TASK [mirror-info-fork : Install ci_mirror script] 2025-11-25 09:07:49.633407 | controller | changed 2025-11-25 09:07:49.644463 | 2025-11-25 09:07:49.644563 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-11-25 09:07:50.098387 | controller | changed: 2025-11-25 09:07:50.098571 | controller | { 2025-11-25 09:07:50.098603 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-11-25 09:07:50.098632 | controller | } 2025-11-25 09:07:50.376290 | controller | changed: 2025-11-25 09:07:50.376443 | controller | { 2025-11-25 09:07:50.376500 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-11-25 09:07:50.376543 | controller | } 2025-11-25 09:07:50.623547 | controller | changed: 2025-11-25 09:07:50.623690 | controller | { 2025-11-25 09:07:50.623728 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com" 2025-11-25 09:07:50.623774 | controller | } 2025-11-25 09:07:50.917009 | controller | changed: 2025-11-25 09:07:50.917131 | controller | { 2025-11-25 09:07:50.917172 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-11-25 09:07:50.917204 | controller | } 2025-11-25 09:07:51.207962 | controller | changed: 2025-11-25 09:07:51.208221 | controller | { 2025-11-25 09:07:51.208252 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-11-25 09:07:51.208272 | controller | } 2025-11-25 09:07:51.509128 | controller | changed: 2025-11-25 09:07:51.509227 | controller | { 2025-11-25 09:07:51.509251 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-11-25 09:07:51.509271 | controller | } 2025-11-25 09:07:51.798854 | controller | changed: 2025-11-25 09:07:51.799033 | controller | { 2025-11-25 09:07:51.799061 | 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-11-25 09:07:51.799082 | controller | } 2025-11-25 09:07:52.089191 | controller | changed: 2025-11-25 09:07:52.089354 | controller | { 2025-11-25 09:07:52.089408 | 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-11-25 09:07:52.089451 | controller | } 2025-11-25 09:07:52.420936 | controller | changed: 2025-11-25 09:07:52.421141 | controller | { 2025-11-25 09:07:52.421200 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-11-25 09:07:52.421246 | controller | } 2025-11-25 09:07:52.747327 | controller | changed: 2025-11-25 09:07:52.747487 | controller | { 2025-11-25 09:07:52.747537 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-11-25 09:07:52.747783 | controller | } 2025-11-25 09:07:52.982834 | controller | changed: 2025-11-25 09:07:52.983006 | controller | { 2025-11-25 09:07:52.983064 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-11-25 09:07:52.983107 | controller | } 2025-11-25 09:07:53.287379 | controller | changed: 2025-11-25 09:07:53.287581 | controller | { 2025-11-25 09:07:53.287639 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-11-25 09:07:53.287682 | controller | } 2025-11-25 09:07:53.544668 | controller | changed: 2025-11-25 09:07:53.544858 | controller | { 2025-11-25 09:07:53.544915 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-11-25 09:07:53.544958 | controller | } 2025-11-25 09:07:53.864154 | controller | changed: 2025-11-25 09:07:53.864436 | controller | { 2025-11-25 09:07:53.864493 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-11-25 09:07:53.864532 | controller | } 2025-11-25 09:07:54.142608 | controller | changed: 2025-11-25 09:07:54.142710 | controller | { 2025-11-25 09:07:54.142753 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-11-25 09:07:54.142774 | controller | } 2025-11-25 09:07:54.456816 | controller | changed: 2025-11-25 09:07:54.456974 | controller | { 2025-11-25 09:07:54.457025 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-11-25 09:07:54.457066 | controller | } 2025-11-25 09:07:54.786225 | controller | changed: 2025-11-25 09:07:54.786398 | controller | { 2025-11-25 09:07:54.786459 | 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-11-25 09:07:54.786675 | controller | } 2025-11-25 09:07:55.084025 | controller | changed: 2025-11-25 09:07:55.084145 | controller | { 2025-11-25 09:07:55.084179 | 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-11-25 09:07:55.084221 | controller | } 2025-11-25 09:07:55.405885 | controller | changed: 2025-11-25 09:07:55.405988 | controller | { 2025-11-25 09:07:55.406021 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-11-25 09:07:55.406049 | controller | } 2025-11-25 09:07:55.685219 | controller | changed: 2025-11-25 09:07:55.685403 | controller | { 2025-11-25 09:07:55.685454 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-11-25 09:07:55.685496 | controller | } 2025-11-25 09:07:55.999963 | controller | changed: 2025-11-25 09:07:56.000117 | controller | { 2025-11-25 09:07:56.000170 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-11-25 09:07:56.000211 | controller | } 2025-11-25 09:07:56.278210 | controller | changed: 2025-11-25 09:07:56.278347 | controller | { 2025-11-25 09:07:56.278397 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-11-25 09:07:56.278437 | controller | } 2025-11-25 09:07:56.536923 | controller | changed: 2025-11-25 09:07:56.537075 | controller | { 2025-11-25 09:07:56.537127 | 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-11-25 09:07:56.537170 | controller | } 2025-11-25 09:07:56.803915 | controller | changed: 2025-11-25 09:07:56.804092 | controller | { 2025-11-25 09:07:56.804170 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-11-25 09:07:56.804218 | controller | } 2025-11-25 09:07:57.115212 | controller | changed: 2025-11-25 09:07:57.115456 | controller | { 2025-11-25 09:07:57.115554 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-11-25 09:07:57.115618 | controller | } 2025-11-25 09:07:57.389909 | controller | changed: 2025-11-25 09:07:57.390174 | controller | { 2025-11-25 09:07:57.390241 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-11-25 09:07:57.390288 | controller | } 2025-11-25 09:07:57.441146 | 2025-11-25 09:07:57.441278 | TASK [Set timezone to UTC] 2025-11-25 09:07:58.139872 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-11-25 09:07:58.167812 | 2025-11-25 09:07:58.167959 | TASK [Create nodepool directory] 2025-11-25 09:07:58.450477 | controller | changed 2025-11-25 09:07:58.464761 | 2025-11-25 09:07:58.464911 | TASK [Create nodepool sub_nodes file] 2025-11-25 09:07:59.250974 | controller | changed 2025-11-25 09:07:59.265179 | 2025-11-25 09:07:59.265265 | TASK [Create nodepool sub_nodes_private file] 2025-11-25 09:07:59.970351 | controller | changed 2025-11-25 09:07:59.979468 | 2025-11-25 09:07:59.979597 | LOOP [Populate nodepool sub_nodes file] 2025-11-25 09:08:00.033345 | 2025-11-25 09:08:00.033520 | LOOP [Populate nodepool sub_nodes_private file] 2025-11-25 09:08:00.070545 | 2025-11-25 09:08:00.070947 | TASK [Create nodepool primary file] 2025-11-25 09:08:00.109996 | controller | skipping: Conditional result was False 2025-11-25 09:08:00.123694 | 2025-11-25 09:08:00.123895 | TASK [Create nodepool node_private for this node] 2025-11-25 09:08:00.868123 | controller | changed 2025-11-25 09:08:00.881480 | 2025-11-25 09:08:00.881637 | LOOP [Copy ssh keys to nodepool directory] 2025-11-25 09:08:01.302656 | controller | ok: Item: id_rsa Runtime: 0:00:00.007120 2025-11-25 09:08:01.303098 | 2025-11-25 09:08:01.514214 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.006898 2025-11-25 09:08:01.547721 | 2025-11-25 09:08:01.547969 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-11-25 09:08:02.376118 | controller | changed 2025-11-25 09:08:02.381758 | 2025-11-25 09:08:02.381824 | TASK [Validate sudoers config after edits] 2025-11-25 09:08:02.711575 | controller | /etc/sudoers: parsed OK 2025-11-25 09:08:02.711672 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-11-25 09:08:02.711687 | controller | /etc/sudoers.d/zuul: parsed OK 2025-11-25 09:08:02.711697 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-11-25 09:08:02.911868 | controller | ok: Runtime: 0:00:00.009173 2025-11-25 09:08:02.921636 | 2025-11-25 09:08:02.921814 | TASK [Show the environment passed in to job shell scripts] 2025-11-25 09:08:03.211056 | controller | SHELL=/bin/bash 2025-11-25 09:08:03.211119 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-11-25 09:08:03.211133 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-11-25 09:08:03.211142 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/97/3397/a34a0d1eb4ccd96195238ccc2d2eb38eb493dd1c 2025-11-25 09:08:03.211152 | controller | PWD=/home/zuul 2025-11-25 09:08:03.211161 | controller | ZUUL_PIPELINE=github-check 2025-11-25 09:08:03.211170 | controller | LOGNAME=zuul 2025-11-25 09:08:03.211178 | controller | XDG_SESSION_TYPE=tty 2025-11-25 09:08:03.211187 | controller | _=/usr/bin/env 2025-11-25 09:08:03.211196 | controller | MOTD_SHOWN=pam 2025-11-25 09:08:03.211205 | controller | HOME=/home/zuul 2025-11-25 09:08:03.211213 | controller | LANG=en_US.UTF-8 2025-11-25 09:08:03.211222 | controller | SSH_CONNECTION=38.102.83.114 48518 38.102.83.190 22 2025-11-25 09:08:03.211231 | 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-11-25 09:08:03.211244 | controller | ZUUL_CHANGE_IDS=3397,a34a0d1eb4ccd96195238ccc2d2eb38eb493dd1c 2025-11-25 09:08:03.211252 | controller | WORKSPACE=/home/zuul/workspace 2025-11-25 09:08:03.211262 | controller | XDG_SESSION_CLASS=user 2025-11-25 09:08:03.211270 | controller | SELINUX_ROLE_REQUESTED= 2025-11-25 09:08:03.211279 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-11-25 09:08:03.211288 | controller | USER=zuul 2025-11-25 09:08:03.211297 | controller | ZUUL_VOTING=True 2025-11-25 09:08:03.211324 | controller | BUILD_TIMEOUT=5400000 2025-11-25 09:08:03.211337 | controller | SELINUX_USE_CURRENT_RANGE= 2025-11-25 09:08:03.211346 | controller | SHLVL=1 2025-11-25 09:08:03.211355 | controller | ZUUL_PATCHSET=a34a0d1eb4ccd96195238ccc2d2eb38eb493dd1c 2025-11-25 09:08:03.211405 | controller | XDG_SESSION_ID=1 2025-11-25 09:08:03.211416 | controller | ZUUL_BRANCH=main 2025-11-25 09:08:03.211424 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-11-25 09:08:03.211433 | controller | SSH_CLIENT=38.102.83.114 48518 22 2025-11-25 09:08:03.211442 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-11-25 09:08:03.211451 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-11-25 09:08:03.211460 | controller | which_declare=declare -f 2025-11-25 09:08:03.211469 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-11-25 09:08:03.211477 | controller | SELINUX_LEVEL_REQUESTED= 2025-11-25 09:08:03.211486 | controller | ZUUL_CHANGE=3397 2025-11-25 09:08:03.211495 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-11-25 09:08:03.211504 | controller | ZUUL_UUID=0d2079dcf64a47da8d436cbcceb38660 2025-11-25 09:08:03.211513 | controller | BASH_FUNC_which%%=() { ( alias; 2025-11-25 09:08:03.211524 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-11-25 09:08:03.211533 | controller | } 2025-11-25 09:08:03.470546 | controller | ok: Runtime: 0:00:00.012115 2025-11-25 09:08:03.479007 | 2025-11-25 09:08:03.479102 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-11-25 09:08:03.502884 | controller | skipping: Conditional result was False 2025-11-25 09:08:03.508948 | 2025-11-25 09:08:03.509030 | TASK [Symlink /home/zuul-worker/workspace] 2025-11-25 09:08:04.037628 | controller | skipping: Conditional result was False 2025-11-25 09:08:04.043324 | 2025-11-25 09:08:04.043387 | TASK [Ensure legacy workspace directory] 2025-11-25 09:08:04.344417 | controller | changed 2025-11-25 09:08:04.369934 | 2025-11-25 09:08:04.370037 | PLAY RECAP 2025-11-25 09:08:04.370088 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-11-25 09:08:04.370116 | 2025-11-25 09:08:04.518297 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-11-25 09:08:04.520401 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master] 2025-11-25 09:08:05.146077 | 2025-11-25 09:08:05.146209 | PLAY [all,!compute] 2025-11-25 09:08:05.165349 | 2025-11-25 09:08:05.165438 | TASK [Enable container-tools rhel-modules for c8/crc node] 2025-11-25 09:08:05.697879 | controller | skipping: Conditional result was False 2025-11-25 09:08:05.711691 | 2025-11-25 09:08:05.711883 | TASK [Install podman] 2025-11-25 09:09:20.432064 | controller | changed 2025-11-25 09:09:20.444149 | 2025-11-25 09:09:20.444284 | TASK [Set var name for quay login zuul secret] 2025-11-25 09:09:20.522158 | controller | skipping: Conditional result was False 2025-11-25 09:09:20.534513 | 2025-11-25 09:09:20.534673 | TASK [Print the username] 2025-11-25 09:09:20.973230 | controller | openstack-k8s-operators+cirobot 2025-11-25 09:09:21.096473 | controller | ok: Runtime: 0:00:00.007419 2025-11-25 09:09:21.112210 | 2025-11-25 09:09:21.112365 | TASK [Fail when user or password is not set] 2025-11-25 09:09:21.191246 | controller | skipping: Conditional result was False 2025-11-25 09:09:21.197923 | 2025-11-25 09:09:21.198054 | TASK [Set vars for quay login] 2025-11-25 09:09:21.259148 | controller | Output suppressed because no_log was given 2025-11-25 09:09:21.264539 | 2025-11-25 09:09:21.264660 | LOOP [Perform container registry login(s) with podman] 2025-11-25 09:09:22.033273 | controller | Output suppressed because no_log was given 2025-11-25 09:09:22.040435 | 2025-11-25 09:09:22.040515 | LOOP [Perform container registry login(s) with buildah] 2025-11-25 09:09:22.512499 | controller | Output suppressed because no_log was given 2025-11-25 09:09:22.525516 | 2025-11-25 09:09:22.525694 | TASK [Set Insecure registry for content provider] 2025-11-25 09:09:22.572847 | controller | skipping: Conditional result was False 2025-11-25 09:09:22.612573 | 2025-11-25 09:09:22.612695 | PLAY RECAP 2025-11-25 09:09:22.612803 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-11-25 09:09:22.612845 | 2025-11-25 09:09:22.715004 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master] 2025-11-25 09:09:22.716034 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-11-25 09:09:23.391338 | 2025-11-25 09:09:23.391441 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-11-25 09:09:23.413103 | 2025-11-25 09:09:23.413220 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-11-25 09:09:23.432339 | controller | ok 2025-11-25 09:09:23.450015 | 2025-11-25 09:09:23.450137 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-11-25 09:09:23.494483 | controller | skipping: Conditional result was False 2025-11-25 09:09:23.500476 | 2025-11-25 09:09:23.500549 | TASK [mirror-info-fork : Create /etc/ci] 2025-11-25 09:09:23.946886 | controller | ok 2025-11-25 09:09:23.954184 | 2025-11-25 09:09:23.954300 | TASK [mirror-info-fork : Install ci_mirror script] 2025-11-25 09:09:24.759566 | controller | ok 2025-11-25 09:09:24.776078 | 2025-11-25 09:09:24.776219 | TASK [Prepare workspace] 2025-11-25 09:09:24.799374 | controller | ok 2025-11-25 09:09:24.821207 | 2025-11-25 09:09:24.821298 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-11-25 09:09:25.192193 | controller | ok 2025-11-25 09:09:25.205616 | 2025-11-25 09:09:25.205793 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-11-25 09:09:27.788347 | controller | Output suppressed because no_log was given 2025-11-25 09:09:27.802542 | 2025-11-25 09:09:27.802641 | LOOP [Create zuul-output directory] 2025-11-25 09:09:28.061023 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-11-25 09:09:28.250391 | controller | ok: "/home/zuul/zuul-output/logs" 2025-11-25 09:09:28.274067 | 2025-11-25 09:09:28.274512 | TASK [Install required packages] 2025-11-25 09:09:29.480698 | controller | ok: Nothing to do 2025-11-25 09:09:29.494969 | 2025-11-25 09:09:29.495221 | TASK [Install venv] 2025-11-25 09:12:16.563769 | controller | changed 2025-11-25 09:12:16.594310 | 2025-11-25 09:12:16.594452 | PLAY RECAP 2025-11-25 09:12:16.594504 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-11-25 09:12:16.594527 | 2025-11-25 09:12:16.717829 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-11-25 09:12:16.718980 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-11-25 09:12:17.259433 | 2025-11-25 09:12:17.259541 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-11-25 09:12:17.283679 | 2025-11-25 09:12:17.283797 | TASK [Create zuul-output directory] 2025-11-25 09:12:17.753964 | controller | changed 2025-11-25 09:12:17.774874 | 2025-11-25 09:12:17.775054 | TASK [Slurp Zuul inventory test] 2025-11-25 09:12:18.196943 | controller -> localhost | ok 2025-11-25 09:12:18.204610 | 2025-11-25 09:12:18.204694 | TASK [Save zuul inventory] 2025-11-25 09:12:19.212706 | controller | changed 2025-11-25 09:12:19.228002 | 2025-11-25 09:12:19.228196 | TASK [Save zuul vars without the change_message] 2025-11-25 09:12:20.041033 | controller | changed 2025-11-25 09:12:20.073206 | 2025-11-25 09:12:20.073346 | PLAY RECAP 2025-11-25 09:12:20.073414 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-25 09:12:20.073445 | 2025-11-25 09:12:20.203875 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-11-25 09:12:20.205328 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/build_push_container_runner.yml@main] 2025-11-25 09:12:20.824851 | 2025-11-25 09:12:20.824963 | PLAY [Run build_push_container role] 2025-11-25 09:12:20.847369 | 2025-11-25 09:12:20.847490 | TASK [Run build_push_container role] 2025-11-25 09:12:21.957504 | controller | 2025-11-25 09:12:23.100182 | controller | PLAY [Run build_push_container role] ******************************************* 2025-11-25 09:12:23.100254 | controller | 2025-11-25 09:12:23.100261 | controller | TASK [Gathering Facts ] ******************************************************** 2025-11-25 09:12:23.100267 | controller | Tuesday 25 November 2025 09:12:21 +0000 (0:00:00.065) 0:00:00.065 ****** 2025-11-25 09:12:23.100274 | controller | Tuesday 25 November 2025 09:12:21 +0000 (0:00:00.064) 0:00:00.064 ****** 2025-11-25 09:12:23.100291 | controller | ok: [localhost] 2025-11-25 09:12:23.157400 | controller | 2025-11-25 09:12:23.157471 | controller | TASK [Run build_push_container role name=build_push_container] ***************** 2025-11-25 09:12:23.157479 | controller | Tuesday 25 November 2025 09:12:23 +0000 (0:00:01.146) 0:00:01.211 ****** 2025-11-25 09:12:23.157485 | controller | Tuesday 25 November 2025 09:12:23 +0000 (0:00:01.146) 0:00:01.210 ****** 2025-11-25 09:12:23.157502 | controller | 2025-11-25 09:12:23.932416 | controller | TASK [build_push_container : Ensure directories are present path={{ item }}, state=directory, mode=0755, recurse=True] *** 2025-11-25 09:12:23.932453 | controller | Tuesday 25 November 2025 09:12:23 +0000 (0:00:00.057) 0:00:01.269 ****** 2025-11-25 09:12:23.932461 | controller | Tuesday 25 November 2025 09:12:23 +0000 (0:00:00.057) 0:00:01.268 ****** 2025-11-25 09:12:23.932473 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/scripts) 2025-11-25 09:12:24.815713 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/logs) 2025-11-25 09:12:24.815785 | controller | 2025-11-25 09:12:24.815795 | controller | TASK [podman : Ensure podman is installed name={{ cifmw_podman_packages }}, state=present] *** 2025-11-25 09:12:24.815803 | controller | Tuesday 25 November 2025 09:12:23 +0000 (0:00:00.775) 0:00:02.044 ****** 2025-11-25 09:12:24.815811 | controller | Tuesday 25 November 2025 09:12:23 +0000 (0:00:00.775) 0:00:02.043 ****** 2025-11-25 09:12:24.815832 | controller | ok: [localhost] 2025-11-25 09:12:25.702590 | controller | 2025-11-25 09:12:25.702697 | controller | TASK [podman : Enable loginctl linger for ansible_user_id _raw_params=loginctl enable-linger {{ cifmw_podman_user_linger }}] *** 2025-11-25 09:12:25.702720 | controller | Tuesday 25 November 2025 09:12:24 +0000 (0:00:00.883) 0:00:02.928 ****** 2025-11-25 09:12:25.702735 | controller | Tuesday 25 November 2025 09:12:24 +0000 (0:00:00.883) 0:00:02.926 ****** 2025-11-25 09:12:25.702760 | controller | changed: [localhost] 2025-11-25 09:12:25.743646 | controller | 2025-11-25 09:12:25.743683 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul path=/etc/subuid, line={{ target_user }}:{{ sub_id_start }}:{{ sub_id_count }}, state=present, create=True, mode=0644] *** 2025-11-25 09:12:25.743699 | controller | Tuesday 25 November 2025 09:12:25 +0000 (0:00:00.886) 0:00:03.814 ****** 2025-11-25 09:12:25.743708 | controller | Tuesday 25 November 2025 09:12:25 +0000 (0:00:00.886) 0:00:03.812 ****** 2025-11-25 09:12:25.743723 | controller | skipping: [localhost] 2025-11-25 09:12:25.769198 | controller | 2025-11-25 09:12:25.769244 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul path=/etc/subgid, line={{ target_user }}:{{ sub_id_start }}:{{ sub_id_count }}, state=present, create=True, mode=0644] *** 2025-11-25 09:12:25.769260 | controller | Tuesday 25 November 2025 09:12:25 +0000 (0:00:00.041) 0:00:03.855 ****** 2025-11-25 09:12:25.769275 | controller | Tuesday 25 November 2025 09:12:25 +0000 (0:00:00.041) 0:00:03.854 ****** 2025-11-25 09:12:25.769298 | controller | skipping: [localhost] 2025-11-25 09:12:25.803470 | controller | 2025-11-25 09:12:25.803525 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed _raw_params=podman system migrate] *** 2025-11-25 09:12:25.803545 | controller | Tuesday 25 November 2025 09:12:25 +0000 (0:00:00.026) 0:00:03.881 ****** 2025-11-25 09:12:25.803565 | controller | Tuesday 25 November 2025 09:12:25 +0000 (0:00:00.026) 0:00:03.880 ****** 2025-11-25 09:12:25.803595 | controller | skipping: [localhost] 2025-11-25 09:12:36.953813 | controller | 2025-11-25 09:12:36.953884 | controller | TASK [build_push_container : Install qemu-user-static for multi arch builds output_dir={{ (cifmw_build_push_container_artifacts, 'scripts') | ansible.builtin.path_join }}, script={{ _podman_qemu_user_static_cmd }}] *** 2025-11-25 09:12:36.953918 | controller | Tuesday 25 November 2025 09:12:25 +0000 (0:00:00.033) 0:00:03.915 ****** 2025-11-25 09:12:36.953930 | controller | Tuesday 25 November 2025 09:12:25 +0000 (0:00:00.033) 0:00:03.914 ****** 2025-11-25 09:12:36.953950 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_000_install_qemu_user_static_for.log 2025-11-25 09:12:37.199016 | controller | changed: [localhost] 2025-11-25 09:12:37.199060 | controller | 2025-11-25 09:12:37.199069 | controller | TASK [build_push_container : Ensure manifest doesn't exist _raw_params=podman manifest rm {{ _container_manifest_name }}] *** 2025-11-25 09:12:37.199077 | controller | Tuesday 25 November 2025 09:12:36 +0000 (0:00:11.150) 0:00:15.065 ****** 2025-11-25 09:12:37.199084 | controller | Tuesday 25 November 2025 09:12:36 +0000 (0:00:11.150) 0:00:15.064 ****** 2025-11-25 09:12:37.199099 | controller | ok: [localhost] 2025-11-25 09:12:37.450474 | controller | 2025-11-25 09:12:37.450553 | controller | TASK [build_push_container : Create manifest _raw_params=podman manifest create {{ _container_manifest_name }}] *** 2025-11-25 09:12:37.450565 | controller | Tuesday 25 November 2025 09:12:37 +0000 (0:00:00.245) 0:00:15.311 ****** 2025-11-25 09:12:37.450575 | controller | Tuesday 25 November 2025 09:12:37 +0000 (0:00:00.245) 0:00:15.310 ****** 2025-11-25 09:12:37.450598 | controller | changed: [localhost] 2025-11-25 09:28:11.250688 | controller | 2025-11-25 09:28:11.250769 | controller | TASK [build_push_container : Build container output_dir={{ (cifmw_build_push_container_artifacts, 'scripts') | ansible.builtin.path_join }}, script={{ _podman_build_cmd }}] *** 2025-11-25 09:28:11.250803 | controller | Tuesday 25 November 2025 09:12:37 +0000 (0:00:00.251) 0:00:15.563 ****** 2025-11-25 09:28:11.250812 | controller | Tuesday 25 November 2025 09:12:37 +0000 (0:00:00.251) 0:00:15.561 ****** 2025-11-25 09:28:11.250834 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_001_build.log 2025-11-25 09:28:50.668392 | controller | changed: [localhost] 2025-11-25 09:28:50.668450 | controller | 2025-11-25 09:28:50.668458 | controller | TASK [build_push_container : Push manifest output_dir={{ (cifmw_build_push_container_artifacts, 'scripts') | ansible.builtin.path_join }}, script={{ (_podman_push_manifest_cmd, item) | join(':') }}] *** 2025-11-25 09:28:50.668467 | controller | Tuesday 25 November 2025 09:28:11 +0000 (0:15:33.800) 0:15:49.363 ****** 2025-11-25 09:28:50.668474 | controller | Tuesday 25 November 2025 09:28:11 +0000 (0:15:33.800) 0:15:49.361 ****** 2025-11-25 09:28:50.668485 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_002_push.log 2025-11-25 09:28:50.726064 | controller | changed: [localhost] => (item=a34a0d1eb4ccd96195238ccc2d2eb38eb493dd1c) 2025-11-25 09:28:50.726103 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_003_push.log 2025-11-25 09:28:50.726111 | controller | changed: [localhost] => (item=3397-latest) 2025-11-25 09:28:50.726118 | controller | 2025-11-25 09:28:50.726124 | controller | TASK [build_push_container : Push container output_dir={{ (cifmw_build_push_container_artifacts, 'scripts') | ansible.builtin.path_join }}, script={{ (_podman_push_container_cmd, item) | join(':') }}] *** 2025-11-25 09:28:50.726131 | controller | Tuesday 25 November 2025 09:28:50 +0000 (0:00:39.417) 0:16:28.780 ****** 2025-11-25 09:28:50.726137 | controller | Tuesday 25 November 2025 09:28:50 +0000 (0:00:39.417) 0:16:28.779 ****** 2025-11-25 09:28:50.726152 | controller | skipping: [localhost] => (item=a34a0d1eb4ccd96195238ccc2d2eb38eb493dd1c) 2025-11-25 09:28:50.796985 | controller | skipping: [localhost] => (item=3397-latest) 2025-11-25 09:28:50.797101 | controller | skipping: [localhost] 2025-11-25 09:28:50.797113 | controller | 2025-11-25 09:28:50.797122 | controller | PLAY RECAP ********************************************************************* 2025-11-25 09:28:50.797131 | controller | localhost : ok=9 changed=6 unreachable=0 failed=0 skipped=4 rescued=0 ignored=0 2025-11-25 09:28:50.797138 | controller | 2025-11-25 09:28:50.797146 | controller | Tuesday 25 November 2025 09:28:50 +0000 (0:00:00.057) 0:16:28.838 ****** 2025-11-25 09:28:50.797169 | controller | =============================================================================== 2025-11-25 09:28:50.797177 | controller | build_push_container : Build container -------------------------------- 933.80s 2025-11-25 09:28:50.797185 | controller | build_push_container : Push manifest ----------------------------------- 39.42s 2025-11-25 09:28:50.797194 | controller | build_push_container : Install qemu-user-static for multi arch builds -- 11.15s 2025-11-25 09:28:50.797202 | controller | Gathering Facts --------------------------------------------------------- 1.15s 2025-11-25 09:28:50.797210 | controller | podman : Enable loginctl linger for ansible_user_id --------------------- 0.89s 2025-11-25 09:28:50.797218 | controller | podman : Ensure podman is installed ------------------------------------- 0.88s 2025-11-25 09:28:50.797226 | controller | build_push_container : Ensure directories are present ------------------- 0.78s 2025-11-25 09:28:50.797235 | controller | build_push_container : Create manifest ---------------------------------- 0.25s 2025-11-25 09:28:50.797246 | controller | build_push_container : Ensure manifest doesn't exist -------------------- 0.25s 2025-11-25 09:28:50.797254 | controller | build_push_container : Push container ----------------------------------- 0.06s 2025-11-25 09:28:50.797262 | controller | Run build_push_container role ------------------------------------------- 0.06s 2025-11-25 09:28:50.797270 | controller | podman : Ensure subordinate UID entry exists for zuul ------------------- 0.04s 2025-11-25 09:28:50.797278 | controller | podman : Run podman system migrate if subuid/subgid files were changed --- 0.03s 2025-11-25 09:28:50.797286 | controller | podman : Ensure subordinate GID entry exists for zuul ------------------- 0.03s 2025-11-25 09:28:50.797294 | controller | Tuesday 25 November 2025 09:28:50 +0000 (0:00:00.058) 0:16:28.837 ****** 2025-11-25 09:28:50.797322 | controller | =============================================================================== 2025-11-25 09:28:50.797335 | controller | build_push_container -------------------------------------------------- 985.70s 2025-11-25 09:28:50.797343 | controller | podman ------------------------------------------------------------------ 1.87s 2025-11-25 09:28:50.797351 | controller | gather_facts ------------------------------------------------------------ 1.15s 2025-11-25 09:28:50.797360 | controller | ansible.builtin.include_role -------------------------------------------- 0.06s 2025-11-25 09:28:50.797368 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ 2025-11-25 09:28:50.797375 | controller | total ----------------------------------------------------------------- 988.77s 2025-11-25 09:28:51.095556 | controller | changed 2025-11-25 09:28:51.115823 | 2025-11-25 09:28:51.115890 | PLAY RECAP 2025-11-25 09:28:51.115930 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-25 09:28:51.115950 | 2025-11-25 09:28:51.211670 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/build_push_container_runner.yml@main] 2025-11-25 09:28:51.213963 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-11-25 09:28:51.880884 | 2025-11-25 09:28:51.880998 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-11-25 09:28:51.903167 | 2025-11-25 09:28:51.903293 | TASK [Filter out host if needed] 2025-11-25 09:28:51.912418 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-11-25 09:28:51.917301 | 2025-11-25 09:28:51.917386 | TASK [Ensure file is present] 2025-11-25 09:28:52.580910 | controller | ok 2025-11-25 09:28:52.587678 | 2025-11-25 09:28:52.587813 | TASK [Manage molecule report file] 2025-11-25 09:28:53.118288 | controller | skipping: Conditional result was False 2025-11-25 09:28:53.131047 | 2025-11-25 09:28:53.131189 | TASK [Check if we get ci-framework-data basedir] 2025-11-25 09:28:53.382581 | controller | ok 2025-11-25 09:28:53.394472 | 2025-11-25 09:28:53.394603 | TASK [Create ci-framework-data log directory for zuul] 2025-11-25 09:28:53.837957 | controller | changed 2025-11-25 09:28:53.850719 | 2025-11-25 09:28:53.850884 | TASK [Copy ci-framework interesting files] 2025-11-25 09:28:54.281161 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-11-25 09:28:54.388696 | controller | changed 2025-11-25 09:28:54.402081 | 2025-11-25 09:28:54.402255 | TASK [Get SELinux listing] 2025-11-25 09:28:54.958501 | controller | changed 2025-11-25 09:28:54.964661 | 2025-11-25 09:28:54.964761 | TASK [Generate log index] 2025-11-25 09:28:55.946423 | controller | changed 2025-11-25 09:28:55.952052 | 2025-11-25 09:28:55.952140 | TASK [Get some env related data] 2025-11-25 09:28:57.069511 | controller | /home/zuul/.local/bin/ansible 2025-11-25 09:28:57.993511 | controller | changed 2025-11-25 09:28:58.002640 | 2025-11-25 09:28:58.002756 | TASK [Generate list of logs to collect in home directory] 2025-11-25 09:28:58.413122 | controller | ok: All paths examined 2025-11-25 09:28:58.453625 | 2025-11-25 09:28:58.454042 | LOOP [Copy logs from home directory] 2025-11-25 09:28:58.863570 | controller | changed: 2025-11-25 09:28:58.863835 | controller | { 2025-11-25 09:28:58.863891 | controller | "atime": 1764061910.6925762, 2025-11-25 09:28:58.863931 | controller | "ctime": 1764062930.7247944, 2025-11-25 09:28:58.863969 | controller | "dev": 64513, 2025-11-25 09:28:58.864005 | controller | "gid": 1000, 2025-11-25 09:28:58.864040 | controller | "gr_name": "zuul", 2025-11-25 09:28:58.864078 | controller | "inode": 4390154, 2025-11-25 09:28:58.864113 | controller | "isblk": false, 2025-11-25 09:28:58.864147 | controller | "ischr": false, 2025-11-25 09:28:58.864181 | controller | "isdir": false, 2025-11-25 09:28:58.864214 | controller | "isfifo": false, 2025-11-25 09:28:58.864248 | controller | "isgid": false, 2025-11-25 09:28:58.864282 | controller | "islnk": false, 2025-11-25 09:28:58.864315 | controller | "isreg": true, 2025-11-25 09:28:58.864347 | controller | "issock": false, 2025-11-25 09:28:58.864379 | controller | "isuid": false, 2025-11-25 09:28:58.864411 | controller | "mode": "0644", 2025-11-25 09:28:58.864444 | controller | "mtime": 1764062930.7247944, 2025-11-25 09:28:58.864477 | controller | "nlink": 1, 2025-11-25 09:28:58.864511 | controller | "path": "/home/zuul/ansible.log", 2025-11-25 09:28:58.864550 | controller | "pw_name": "zuul", 2025-11-25 09:28:58.864586 | controller | "rgrp": true, 2025-11-25 09:28:58.864620 | controller | "roth": true, 2025-11-25 09:28:58.864653 | controller | "rusr": true, 2025-11-25 09:28:58.864687 | controller | "size": 18992, 2025-11-25 09:28:58.864720 | controller | "uid": 1000, 2025-11-25 09:28:58.864792 | controller | "wgrp": false, 2025-11-25 09:28:58.864827 | controller | "woth": false, 2025-11-25 09:28:58.864866 | controller | "wusr": true, 2025-11-25 09:28:58.864902 | controller | "xgrp": false, 2025-11-25 09:28:58.864938 | controller | "xoth": false, 2025-11-25 09:28:58.864971 | controller | "xusr": false 2025-11-25 09:28:58.865004 | controller | } 2025-11-25 09:28:58.881361 | 2025-11-25 09:28:58.881562 | TASK [Copy crio stats log file] 2025-11-25 09:28:58.908814 | controller | skipping: Conditional result was False 2025-11-25 09:28:58.918188 | 2025-11-25 09:28:58.918292 | TASK [Get SELinux related data] 2025-11-25 09:28:59.224527 | controller | 2025-11-25 09:28:59.461951 | controller | ERROR 2025-11-25 09:28:59.462187 | controller | { 2025-11-25 09:28:59.462283 | controller | "delta": "0:00:00.015917", 2025-11-25 09:28:59.462330 | controller | "end": "2025-11-25 09:28:59.225974", 2025-11-25 09:28:59.462385 | controller | "msg": "non-zero return code", 2025-11-25 09:28:59.462422 | controller | "rc": 1, 2025-11-25 09:28:59.462455 | controller | "start": "2025-11-25 09:28:59.210057" 2025-11-25 09:28:59.462488 | controller | } 2025-11-25 09:28:59.462534 | controller | ERROR: Ignoring Errors 2025-11-25 09:28:59.474339 | 2025-11-25 09:28:59.474477 | TASK [Create system configuration directory] 2025-11-25 09:29:00.115002 | controller | changed 2025-11-25 09:29:00.127123 | 2025-11-25 09:29:00.127288 | TASK [Get some of the system configurations] 2025-11-25 09:29:01.173943 | controller | changed 2025-11-25 09:29:01.186977 | 2025-11-25 09:29:01.187120 | TASK [Copy generated documentation if available] 2025-11-25 09:29:01.215021 | controller | skipping: Conditional result was False 2025-11-25 09:29:01.228075 | 2025-11-25 09:29:01.228233 | TASK [Copy generated AsciiDoc documentation if available] 2025-11-25 09:29:01.255612 | controller | skipping: Conditional result was False 2025-11-25 09:29:01.268250 | 2025-11-25 09:29:01.268420 | TASK [Compress logs bigger than 2MB] 2025-11-25 09:29:01.831208 | controller | changed 2025-11-25 09:29:01.843880 | 2025-11-25 09:29:01.844035 | TASK [Copy files from workspace on node] 2025-11-25 09:29:01.887552 | controller | ok 2025-11-25 09:29:01.944078 | 2025-11-25 09:29:01.944401 | TASK [fetch-output : Set log path for multiple nodes] 2025-11-25 09:29:01.970539 | controller | skipping: Conditional result was False 2025-11-25 09:29:01.979224 | 2025-11-25 09:29:01.979336 | TASK [fetch-output : Set log path for single node] 2025-11-25 09:29:02.020883 | controller | ok 2025-11-25 09:29:02.028103 | 2025-11-25 09:29:02.028188 | LOOP [fetch-output : Ensure local output dirs] 2025-11-25 09:29:02.290301 | controller -> localhost | ok: "/var/lib/zuul/builds/0d2079dcf64a47da8d436cbcceb38660/work/logs" 2025-11-25 09:29:02.290555 | controller -> localhost | changed: All items complete 2025-11-25 09:29:02.290582 | 2025-11-25 09:29:02.501597 | controller -> localhost | changed: "/var/lib/zuul/builds/0d2079dcf64a47da8d436cbcceb38660/work/artifacts" 2025-11-25 09:29:02.751220 | controller -> localhost | changed: "/var/lib/zuul/builds/0d2079dcf64a47da8d436cbcceb38660/work/docs" 2025-11-25 09:29:02.771004 | 2025-11-25 09:29:02.771177 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-25 09:29:03.566795 | controller | changed: 2025-11-25 09:29:03.567012 | controller | .d..t...... ./ 2025-11-25 09:29:03.567042 | controller | >f+++++++++ README.html 2025-11-25 09:29:03.567061 | controller | >f+++++++++ ansible.log 2025-11-25 09:29:03.567082 | controller | >f+++++++++ dmesg.log 2025-11-25 09:29:03.567100 | controller | >f+++++++++ installed-pkgs.log 2025-11-25 09:29:03.567117 | controller | >f+++++++++ python.log 2025-11-25 09:29:03.567136 | controller | >f+++++++++ registries.conf 2025-11-25 09:29:03.567153 | controller | >f+++++++++ selinux-denials.log 2025-11-25 09:29:03.567170 | controller | >f+++++++++ selinux-listing.log 2025-11-25 09:29:03.567188 | controller | cd+++++++++ ci-framework-data/ 2025-11-25 09:29:03.567205 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-11-25 09:29:03.567222 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-11-25 09:29:03.567239 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/ 2025-11-25 09:29:03.567256 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/logs/ 2025-11-25 09:29:03.567272 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_000_install_qemu_user_static_for.log 2025-11-25 09:29:03.567289 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_001_build.log 2025-11-25 09:29:03.567305 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_002_push.log 2025-11-25 09:29:03.567321 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_003_push.log 2025-11-25 09:29:03.567346 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ 2025-11-25 09:29:03.567363 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_000_install_qemu_user_static_for.sh 2025-11-25 09:29:03.567379 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_001_build.sh 2025-11-25 09:29:03.567394 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_002_push.sh 2025-11-25 09:29:03.567410 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_003_push.sh 2025-11-25 09:29:03.567426 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-11-25 09:29:03.567442 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-11-25 09:29:03.567459 | controller | cd+++++++++ ci-framework-data/logs/ 2025-11-25 09:29:03.567475 | controller | cd+++++++++ registries.conf.d/ 2025-11-25 09:29:03.567491 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-11-25 09:29:03.567508 | controller | cd+++++++++ system-config/ 2025-11-25 09:29:03.567524 | controller | cd+++++++++ system-config/libvirt/ 2025-11-25 09:29:03.567540 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-11-25 09:29:03.567556 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-11-25 09:29:04.092442 | controller | changed: .d..t...... ./ 2025-11-25 09:29:04.583500 | controller | changed: .d..t...... ./ 2025-11-25 09:29:04.614614 | 2025-11-25 09:29:04.614829 | TASK [Return artifact to Zuul] 2025-11-25 09:29:04.639441 | controller | skipping: Conditional result was False 2025-11-25 09:29:04.660839 | 2025-11-25 09:29:04.660921 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-11-25 09:29:04.661027 | 2025-11-25 09:29:04.661053 | PLAY RECAP 2025-11-25 09:29:04.661091 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1 2025-11-25 09:29:04.661111 | 2025-11-25 09:29:04.788155 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-11-25 09:29:04.789165 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-11-25 09:29:05.378761 | 2025-11-25 09:29:05.378879 | PLAY [all] 2025-11-25 09:29:05.399071 | 2025-11-25 09:29:05.399193 | TASK [include_role : fetch-output] 2025-11-25 09:29:05.439687 | controller | ok 2025-11-25 09:29:05.457481 | 2025-11-25 09:29:05.457598 | TASK [fetch-output : Set log path for multiple nodes] 2025-11-25 09:29:05.522691 | controller | skipping: Conditional result was False 2025-11-25 09:29:05.536335 | 2025-11-25 09:29:05.536563 | TASK [fetch-output : Set log path for single node] 2025-11-25 09:29:05.582070 | controller | ok 2025-11-25 09:29:05.589436 | 2025-11-25 09:29:05.589534 | LOOP [fetch-output : Ensure local output dirs] 2025-11-25 09:29:05.954105 | controller -> localhost | ok: "/var/lib/zuul/builds/0d2079dcf64a47da8d436cbcceb38660/work/logs" 2025-11-25 09:29:06.162756 | controller -> localhost | ok: "/var/lib/zuul/builds/0d2079dcf64a47da8d436cbcceb38660/work/artifacts" 2025-11-25 09:29:06.430547 | controller -> localhost | ok: "/var/lib/zuul/builds/0d2079dcf64a47da8d436cbcceb38660/work/docs" 2025-11-25 09:29:06.458520 | 2025-11-25 09:29:06.458788 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-25 09:29:07.119644 | controller | ok 2025-11-25 09:29:07.120008 | controller | ok: All items complete 2025-11-25 09:29:07.120069 | 2025-11-25 09:29:07.635256 | controller | ok 2025-11-25 09:29:08.114000 | controller | ok 2025-11-25 09:29:08.130407 | 2025-11-25 09:29:08.130510 | TASK [include_role : fetch-output-openshift] 2025-11-25 09:29:08.154467 | controller | skipping: Conditional result was False 2025-11-25 09:29:08.160522 | 2025-11-25 09:29:08.160593 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-25 09:29:08.633701 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013535 2025-11-25 09:29:08.903368 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.012199 2025-11-25 09:29:08.946932 | 2025-11-25 09:29:08.947048 | PLAY [all] 2025-11-25 09:29:08.967397 | 2025-11-25 09:29:08.967484 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-11-25 09:29:09.586109 | controller | changed 2025-11-25 09:29:09.631819 | 2025-11-25 09:29:09.631924 | PLAY RECAP 2025-11-25 09:29:09.631978 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-11-25 09:29:09.632007 | 2025-11-25 09:29:09.731259 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-11-25 09:29:09.733391 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-11-25 09:29:10.353268 | 2025-11-25 09:29:10.353392 | PLAY [localhost] 2025-11-25 09:29:10.372081 | 2025-11-25 09:29:10.372180 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-25 09:29:10.766943 | localhost | changed 2025-11-25 09:29:10.771989 | 2025-11-25 09:29:10.772084 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-25 09:29:10.802988 | localhost | ok 2025-11-25 09:29:10.815408 | 2025-11-25 09:29:10.815520 | TASK [add-fileserver : Create SSH private key tempfile] 2025-11-25 09:29:11.181533 | localhost | changed 2025-11-25 09:29:11.186766 | 2025-11-25 09:29:11.186847 | TASK [add-fileserver : Create SSH private key from secret] 2025-11-25 09:29:11.960137 | localhost | changed 2025-11-25 09:29:11.977810 | 2025-11-25 09:29:11.978028 | TASK [add-fileserver : Add fileserver ssh key] 2025-11-25 09:29:12.393699 | localhost | Identity added: /var/lib/zuul/builds/0d2079dcf64a47da8d436cbcceb38660/work/tmp/ansible.olhhii90 (/var/lib/zuul/builds/0d2079dcf64a47da8d436cbcceb38660/work/tmp/ansible.olhhii90) 2025-11-25 09:29:12.394038 | localhost | ok: Runtime: 0:00:00.012568 2025-11-25 09:29:12.403642 | 2025-11-25 09:29:12.403820 | TASK [add-fileserver : Remove SSH private key from disk] 2025-11-25 09:29:12.673954 | localhost | ok: Runtime: 0:00:00.008179 2025-11-25 09:29:12.684971 | 2025-11-25 09:29:12.685127 | TASK [add-fileserver : Add fileserver to inventory] 2025-11-25 09:29:12.751019 | localhost | changed 2025-11-25 09:29:12.760445 | 2025-11-25 09:29:12.760602 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-11-25 09:29:13.138719 | localhost | changed 2025-11-25 09:29:13.167260 | 2025-11-25 09:29:13.167498 | PLAY [localhost] 2025-11-25 09:29:13.185796 | 2025-11-25 09:29:13.185929 | TASK [Generate bulk log download script] 2025-11-25 09:29:13.206172 | localhost | ok 2025-11-25 09:29:13.223669 | 2025-11-25 09:29:13.223811 | TASK [local-log-download : Check API endpoint is defined] 2025-11-25 09:29:13.254445 | localhost | ok: All assertions passed 2025-11-25 09:29:13.261440 | 2025-11-25 09:29:13.261532 | TASK [local-log-download : Create download script] 2025-11-25 09:29:13.742378 | localhost -> localhost | changed 2025-11-25 09:29:13.751658 | 2025-11-25 09:29:13.758787 | TASK [Register quick-download link] 2025-11-25 09:29:13.851043 | localhost | ok 2025-11-25 09:29:13.879660 | 2025-11-25 09:29:13.879820 | PLAY [logserver.rdoproject.org] 2025-11-25 09:29:13.891410 | 2025-11-25 09:29:13.891689 | TASK [Set zuul-log-path fact] 2025-11-25 09:29:13.929043 | logserver.rdoproject.org | ok 2025-11-25 09:29:13.947621 | 2025-11-25 09:29:13.947747 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-25 09:29:13.974038 | logserver.rdoproject.org | ok 2025-11-25 09:29:13.979583 | 2025-11-25 09:29:13.979651 | TASK [upload-logs : Create log directories] 2025-11-25 09:29:14.736345 | logserver.rdoproject.org | changed 2025-11-25 09:29:14.740788 | 2025-11-25 09:29:14.740855 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-11-25 09:29:15.135535 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005668 2025-11-25 09:29:15.142164 | 2025-11-25 09:29:15.142248 | TASK [upload-logs : Upload logs to log server] 2025-11-25 09:29:16.182530 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-11-25 09:29:16.185372 | 2025-11-25 09:29:16.185434 | LOOP [upload-logs : Compress console log and json output] 2025-11-25 09:29:16.277902 | logserver.rdoproject.org | skipping: Conditional result was False 2025-11-25 09:29:16.282850 | logserver.rdoproject.org | skipping: Conditional result was False 2025-11-25 09:29:16.295253 | 2025-11-25 09:29:16.295329 | LOOP [upload-logs : Upload compressed console log and json output] 2025-11-25 09:29:16.360644 | logserver.rdoproject.org | skipping: Conditional result was False 2025-11-25 09:29:16.360916 | 2025-11-25 09:29:16.361399 | logserver.rdoproject.org | skipping: Conditional result was False 2025-11-25 09:29:16.373407 | 2025-11-25 09:29:16.373485 | LOOP [upload-logs : Upload console log and json output]