2025-11-26 07:43:59.064647 | Job console starting... 2025-11-26 07:43:59.273659 | Updating repositories 2025-11-26 07:44:00.135565 | Preparing job workspace 2025-11-26 07:44:05.968135 | Running Ansible setup... 2025-11-26 07:44:08.867253 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-11-26 07:44:09.372873 | 2025-11-26 07:44:09.373026 | PLAY [localhost] 2025-11-26 07:44:09.381342 | 2025-11-26 07:44:09.381414 | TASK [Gathering Facts] 2025-11-26 07:44:10.176601 | localhost | ok 2025-11-26 07:44:10.189783 | 2025-11-26 07:44:10.189873 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-26 07:44:10.507236 | localhost -> localhost | changed 2025-11-26 07:44:10.512495 | 2025-11-26 07:44:10.512571 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-26 07:44:11.195150 | localhost -> localhost | changed 2025-11-26 07:44:11.203509 | 2025-11-26 07:44:11.203594 | TASK [Setup log path fact] 2025-11-26 07:44:11.220689 | localhost | ok 2025-11-26 07:44:11.232015 | 2025-11-26 07:44:11.232090 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-26 07:44:11.259212 | localhost | ok 2025-11-26 07:44:11.266661 | 2025-11-26 07:44:11.266732 | TASK [emit-job-header : Print job information] 2025-11-26 07:44:11.294295 | # Job Information 2025-11-26 07:44:11.294435 | Ansible Version: 2.15.12 2025-11-26 07:44:11.294467 | Job: build-push-container-cifmw-client-post 2025-11-26 07:44:11.294489 | Pipeline: github-post 2025-11-26 07:44:11.294510 | Executor: ibm-bm4-ze.softwarefactory-project.io 2025-11-26 07:44:11.294529 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/commit/e99f135999d3359aa047e643dd0cbf0710d583e6 2025-11-26 07:44:11.294549 | Log URL (when completed): https://logserver.rdoproject.org/018/rdoproject.org/0189566355ad463e901ef6c273d14bbd/ 2025-11-26 07:44:11.294570 | Event ID: 99ac54bc-ca9b-11f0-84c7-6bf043dac31e 2025-11-26 07:44:11.298246 | 2025-11-26 07:44:11.298320 | LOOP [emit-job-header : Print node information] 2025-11-26 07:44:11.384218 | localhost | ok: 2025-11-26 07:44:11.384430 | localhost | # Node Information 2025-11-26 07:44:11.384461 | localhost | Inventory Hostname: controller 2025-11-26 07:44:11.384486 | localhost | Hostname: np0005536263 2025-11-26 07:44:11.384506 | localhost | Username: zuul 2025-11-26 07:44:11.384526 | localhost | Distro: CentOS 9 2025-11-26 07:44:11.384543 | localhost | Provider: ibm-bm4-nodepool 2025-11-26 07:44:11.384560 | localhost | Region: regionOne 2025-11-26 07:44:11.384576 | localhost | Label: cloud-centos-9-stream-tripleo 2025-11-26 07:44:11.384592 | localhost | Product Name: OpenStack Compute 2025-11-26 07:44:11.384606 | localhost | Interface IP: 192.168.26.220 2025-11-26 07:44:11.411759 | 2025-11-26 07:44:11.411857 | PLAY [all] 2025-11-26 07:44:11.418275 | 2025-11-26 07:44:11.418347 | TASK [Gather network facts] 2025-11-26 07:44:11.762123 | controller | ok 2025-11-26 07:44:11.776452 | 2025-11-26 07:44:11.776524 | TASK [include_role : start-zuul-console] 2025-11-26 07:44:11.794063 | controller | ok 2025-11-26 07:44:11.805093 | 2025-11-26 07:44:11.805155 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-26 07:44:12.096809 | controller | ok 2025-11-26 07:44:12.105281 | 2025-11-26 07:44:12.105343 | TASK [include_role : add-build-sshkey] 2025-11-26 07:44:12.122186 | controller | ok 2025-11-26 07:44:12.134533 | 2025-11-26 07:44:12.134597 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-26 07:44:12.319442 | controller -> localhost | ok 2025-11-26 07:44:12.325448 | 2025-11-26 07:44:12.325526 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-26 07:44:12.342742 | controller | ok 2025-11-26 07:44:12.354758 | controller | included: /var/lib/zuul/builds/0189566355ad463e901ef6c273d14bbd/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-26 07:44:12.360336 | 2025-11-26 07:44:12.360401 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-26 07:44:12.778384 | controller -> localhost | Generating public/private rsa key pair. 2025-11-26 07:44:12.778555 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0189566355ad463e901ef6c273d14bbd/work/0189566355ad463e901ef6c273d14bbd_id_rsa. 2025-11-26 07:44:12.778589 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0189566355ad463e901ef6c273d14bbd/work/0189566355ad463e901ef6c273d14bbd_id_rsa.pub. 2025-11-26 07:44:12.778614 | controller -> localhost | The key fingerprint is: 2025-11-26 07:44:12.778637 | controller -> localhost | SHA256:q0bihsn25DKiRW/eidEnj+BfUxcpBj+NP1tOijpZsCU zuul-build-sshkey 2025-11-26 07:44:12.778658 | controller -> localhost | The key's randomart image is: 2025-11-26 07:44:12.778678 | controller -> localhost | +---[RSA 3072]----+ 2025-11-26 07:44:12.778698 | controller -> localhost | | . | 2025-11-26 07:44:12.778718 | controller -> localhost | | o o . | 2025-11-26 07:44:12.778737 | controller -> localhost | | * + | 2025-11-26 07:44:12.778756 | controller -> localhost | | E..+ . | 2025-11-26 07:44:12.778775 | controller -> localhost | | . S=. + o | 2025-11-26 07:44:12.778793 | controller -> localhost | | . .... .o.o B | 2025-11-26 07:44:12.778811 | controller -> localhost | | ..+*oo =o. o . | 2025-11-26 07:44:12.778832 | controller -> localhost | |..B*o=.Ooo | 2025-11-26 07:44:12.778851 | controller -> localhost | |oo =*o* o. | 2025-11-26 07:44:12.778869 | controller -> localhost | +----[SHA256]-----+ 2025-11-26 07:44:12.778941 | controller -> localhost | ok: Runtime: 0:00:00.103331 2025-11-26 07:44:12.784493 | 2025-11-26 07:44:12.784558 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-26 07:44:12.801501 | controller | ok 2025-11-26 07:44:12.810508 | controller | included: /var/lib/zuul/builds/0189566355ad463e901ef6c273d14bbd/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-26 07:44:12.818090 | 2025-11-26 07:44:12.818153 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-26 07:44:12.830963 | controller | skipping: Conditional result was False 2025-11-26 07:44:12.835825 | 2025-11-26 07:44:12.835903 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-26 07:44:13.198151 | controller | changed 2025-11-26 07:44:13.202701 | 2025-11-26 07:44:13.202762 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-26 07:44:13.401879 | controller | ok 2025-11-26 07:44:13.406508 | 2025-11-26 07:44:13.406573 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-26 07:44:13.930037 | controller | changed 2025-11-26 07:44:13.934627 | 2025-11-26 07:44:13.934695 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-26 07:44:14.458954 | controller | changed 2025-11-26 07:44:14.464423 | 2025-11-26 07:44:14.464488 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-26 07:44:14.477655 | controller | skipping: Conditional result was False 2025-11-26 07:44:14.482654 | 2025-11-26 07:44:14.482720 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-26 07:44:14.779687 | controller -> localhost | changed 2025-11-26 07:44:14.789126 | 2025-11-26 07:44:14.789206 | TASK [add-build-sshkey : Add back temp key] 2025-11-26 07:44:15.004763 | controller -> localhost | Identity added: /var/lib/zuul/builds/0189566355ad463e901ef6c273d14bbd/work/0189566355ad463e901ef6c273d14bbd_id_rsa (zuul-build-sshkey) 2025-11-26 07:44:15.004977 | controller -> localhost | ok: Runtime: 0:00:00.006936 2025-11-26 07:44:15.010514 | 2025-11-26 07:44:15.010577 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-26 07:44:15.281420 | controller | ok 2025-11-26 07:44:15.285851 | 2025-11-26 07:44:15.285939 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-26 07:44:15.309280 | controller | skipping: Conditional result was False 2025-11-26 07:44:15.318452 | 2025-11-26 07:44:15.318516 | TASK [include_role : validate-host] 2025-11-26 07:44:15.335590 | controller | ok 2025-11-26 07:44:15.354521 | 2025-11-26 07:44:15.354585 | TASK [validate-host : Define zuul_info_dir fact] 2025-11-26 07:44:15.382020 | controller | ok 2025-11-26 07:44:15.386456 | 2025-11-26 07:44:15.386518 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-11-26 07:44:15.602223 | controller -> localhost | ok 2025-11-26 07:44:15.607825 | 2025-11-26 07:44:15.607928 | TASK [validate-host : Collect information about the host] 2025-11-26 07:44:16.193506 | controller | ok 2025-11-26 07:44:16.201168 | 2025-11-26 07:44:16.201233 | TASK [validate-host : Sanitize hostname] 2025-11-26 07:44:16.270137 | controller | ok 2025-11-26 07:44:16.292698 | 2025-11-26 07:44:16.292783 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-11-26 07:44:16.660159 | controller -> localhost | changed 2025-11-26 07:44:16.665407 | 2025-11-26 07:44:16.665472 | TASK [validate-host : Collect information about zuul worker] 2025-11-26 07:44:16.977689 | controller | ok 2025-11-26 07:44:16.982238 | 2025-11-26 07:44:16.982309 | TASK [validate-host : Write out all zuul information for each host] 2025-11-26 07:44:17.340359 | controller -> localhost | changed 2025-11-26 07:44:17.349341 | 2025-11-26 07:44:17.349407 | TASK [include_role : prepare-workspace-openshift] 2025-11-26 07:44:17.362652 | controller | skipping: Conditional result was False 2025-11-26 07:44:17.367560 | 2025-11-26 07:44:17.367622 | TASK [include_role : remove-zuul-sshkey] 2025-11-26 07:44:17.380633 | controller | skipping: Conditional result was False 2025-11-26 07:44:17.385505 | 2025-11-26 07:44:17.385572 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-11-26 07:44:17.568507 | controller | ok: "logs" 2025-11-26 07:44:17.568729 | controller | ok: All items complete 2025-11-26 07:44:17.568759 | 2025-11-26 07:44:17.727955 | controller | ok: "artifacts" 2025-11-26 07:44:17.884866 | controller | ok: "docs" 2025-11-26 07:44:17.890820 | 2025-11-26 07:44:17.890958 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-11-26 07:44:18.074494 | controller | changed: "logs" 2025-11-26 07:44:18.238459 | controller | changed: "artifacts" 2025-11-26 07:44:18.401464 | controller | changed: "docs" 2025-11-26 07:44:18.422732 | 2025-11-26 07:44:18.422830 | PLAY RECAP 2025-11-26 07:44:18.422891 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-11-26 07:44:18.422945 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-26 07:44:18.422968 | 2025-11-26 07:44:18.498749 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-11-26 07:44:18.499734 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-11-26 07:44:19.029079 | 2025-11-26 07:44:19.029182 | PLAY [all] 2025-11-26 07:44:19.048707 | 2025-11-26 07:44:19.048789 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-11-26 07:44:19.097236 | controller | ok 2025-11-26 07:44:19.102194 | 2025-11-26 07:44:19.102276 | TASK [mirror-info-fork : Create /etc/ci] 2025-11-26 07:44:19.414471 | controller | changed 2025-11-26 07:44:19.419896 | 2025-11-26 07:44:19.420007 | TASK [mirror-info-fork : Install ci_mirror script] 2025-11-26 07:44:20.166424 | controller | changed 2025-11-26 07:44:20.176091 | 2025-11-26 07:44:20.176177 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-11-26 07:44:20.522552 | controller | changed: 2025-11-26 07:44:20.522759 | controller | { 2025-11-26 07:44:20.522794 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-11-26 07:44:20.522823 | controller | } 2025-11-26 07:44:20.722718 | controller | changed: 2025-11-26 07:44:20.722811 | controller | { 2025-11-26 07:44:20.722841 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-11-26 07:44:20.722875 | controller | } 2025-11-26 07:44:20.921462 | controller | changed: 2025-11-26 07:44:20.921551 | controller | { 2025-11-26 07:44:20.921577 | 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-26 07:44:20.921598 | controller | } 2025-11-26 07:44:21.118014 | controller | changed: 2025-11-26 07:44:21.118097 | controller | { 2025-11-26 07:44:21.118124 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-11-26 07:44:21.118143 | controller | } 2025-11-26 07:44:21.312825 | controller | changed: 2025-11-26 07:44:21.312948 | controller | { 2025-11-26 07:44:21.312982 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-11-26 07:44:21.313004 | controller | } 2025-11-26 07:44:21.507356 | controller | changed: 2025-11-26 07:44:21.507456 | controller | { 2025-11-26 07:44:21.507485 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-11-26 07:44:21.507507 | controller | } 2025-11-26 07:44:21.701178 | controller | changed: 2025-11-26 07:44:21.701230 | controller | { 2025-11-26 07:44:21.701257 | 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-26 07:44:21.701279 | controller | } 2025-11-26 07:44:21.897421 | controller | changed: 2025-11-26 07:44:21.897513 | controller | { 2025-11-26 07:44:21.897540 | 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-26 07:44:21.897562 | controller | } 2025-11-26 07:44:22.091595 | controller | changed: 2025-11-26 07:44:22.091662 | controller | { 2025-11-26 07:44:22.091689 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-11-26 07:44:22.091709 | controller | } 2025-11-26 07:44:22.287531 | controller | changed: 2025-11-26 07:44:22.287595 | controller | { 2025-11-26 07:44:22.287621 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-11-26 07:44:22.287640 | controller | } 2025-11-26 07:44:22.481942 | controller | changed: 2025-11-26 07:44:22.482038 | controller | { 2025-11-26 07:44:22.482064 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-11-26 07:44:22.482084 | controller | } 2025-11-26 07:44:22.692271 | controller | changed: 2025-11-26 07:44:22.692354 | controller | { 2025-11-26 07:44:22.692380 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-11-26 07:44:22.692399 | controller | } 2025-11-26 07:44:22.871284 | controller | changed: 2025-11-26 07:44:22.871469 | controller | { 2025-11-26 07:44:22.871505 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-11-26 07:44:22.871529 | controller | } 2025-11-26 07:44:23.069940 | controller | changed: 2025-11-26 07:44:23.070035 | controller | { 2025-11-26 07:44:23.070061 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-11-26 07:44:23.070080 | controller | } 2025-11-26 07:44:23.270469 | controller | changed: 2025-11-26 07:44:23.270559 | controller | { 2025-11-26 07:44:23.270585 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-11-26 07:44:23.270606 | controller | } 2025-11-26 07:44:23.470050 | controller | changed: 2025-11-26 07:44:23.470125 | controller | { 2025-11-26 07:44:23.470158 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-11-26 07:44:23.470184 | controller | } 2025-11-26 07:44:23.670943 | controller | changed: 2025-11-26 07:44:23.671035 | controller | { 2025-11-26 07:44:23.671069 | 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-26 07:44:23.671090 | controller | } 2025-11-26 07:44:23.872877 | controller | changed: 2025-11-26 07:44:23.872992 | controller | { 2025-11-26 07:44:23.873020 | 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-26 07:44:23.873073 | controller | } 2025-11-26 07:44:24.072778 | controller | changed: 2025-11-26 07:44:24.072883 | controller | { 2025-11-26 07:44:24.072927 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-11-26 07:44:24.072954 | controller | } 2025-11-26 07:44:24.272894 | controller | changed: 2025-11-26 07:44:24.273000 | controller | { 2025-11-26 07:44:24.273028 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-11-26 07:44:24.273049 | controller | } 2025-11-26 07:44:24.473771 | controller | changed: 2025-11-26 07:44:24.473872 | controller | { 2025-11-26 07:44:24.473899 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-11-26 07:44:24.473948 | controller | } 2025-11-26 07:44:24.677902 | controller | changed: 2025-11-26 07:44:24.678019 | controller | { 2025-11-26 07:44:24.678046 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-11-26 07:44:24.678065 | controller | } 2025-11-26 07:44:24.880395 | controller | changed: 2025-11-26 07:44:24.880501 | controller | { 2025-11-26 07:44:24.880528 | 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-26 07:44:24.880548 | controller | } 2025-11-26 07:44:25.083698 | controller | changed: 2025-11-26 07:44:25.083795 | controller | { 2025-11-26 07:44:25.083820 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-11-26 07:44:25.083839 | controller | } 2025-11-26 07:44:25.289975 | controller | changed: 2025-11-26 07:44:25.290076 | controller | { 2025-11-26 07:44:25.290102 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-11-26 07:44:25.290121 | controller | } 2025-11-26 07:44:25.493779 | controller | changed: 2025-11-26 07:44:25.494018 | controller | { 2025-11-26 07:44:25.494048 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-11-26 07:44:25.494070 | controller | } 2025-11-26 07:44:25.514240 | 2025-11-26 07:44:25.514409 | TASK [Set timezone to UTC] 2025-11-26 07:44:25.888905 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-11-26 07:44:25.893699 | 2025-11-26 07:44:25.893766 | TASK [Create nodepool directory] 2025-11-26 07:44:26.096233 | controller | changed 2025-11-26 07:44:26.101675 | 2025-11-26 07:44:26.101747 | TASK [Create nodepool sub_nodes file] 2025-11-26 07:44:26.606600 | controller | changed 2025-11-26 07:44:26.611567 | 2025-11-26 07:44:26.611655 | TASK [Create nodepool sub_nodes_private file] 2025-11-26 07:44:27.106724 | controller | changed 2025-11-26 07:44:27.111553 | 2025-11-26 07:44:27.111616 | LOOP [Populate nodepool sub_nodes file] 2025-11-26 07:44:27.140868 | 2025-11-26 07:44:27.141014 | LOOP [Populate nodepool sub_nodes_private file] 2025-11-26 07:44:27.169938 | 2025-11-26 07:44:27.170057 | TASK [Create nodepool primary file] 2025-11-26 07:44:27.193612 | controller | skipping: Conditional result was False 2025-11-26 07:44:27.199143 | 2025-11-26 07:44:27.199208 | TASK [Create nodepool node_private for this node] 2025-11-26 07:44:27.723861 | controller | changed 2025-11-26 07:44:27.729286 | 2025-11-26 07:44:27.729350 | LOOP [Copy ssh keys to nodepool directory] 2025-11-26 07:44:28.045354 | controller | ok: Item: id_rsa Runtime: 0:00:00.004594 2025-11-26 07:44:28.045544 | 2025-11-26 07:44:28.205777 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004377 2025-11-26 07:44:28.215766 | 2025-11-26 07:44:28.215890 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-11-26 07:44:28.720342 | controller | changed 2025-11-26 07:44:28.725162 | 2025-11-26 07:44:28.725223 | TASK [Validate sudoers config after edits] 2025-11-26 07:44:28.935259 | controller | /etc/sudoers: parsed OK 2025-11-26 07:44:28.935346 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-11-26 07:44:28.935356 | controller | /etc/sudoers.d/zuul: parsed OK 2025-11-26 07:44:28.935362 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-11-26 07:44:29.250629 | controller | ok: Runtime: 0:00:00.004928 2025-11-26 07:44:29.256164 | 2025-11-26 07:44:29.256226 | TASK [Show the environment passed in to job shell scripts] 2025-11-26 07:44:29.452394 | controller | SHELL=/bin/bash 2025-11-26 07:44:29.452437 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-11-26 07:44:29.452447 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-11-26 07:44:29.452453 | controller | ZUUL_REFNAME=refs/heads/main 2025-11-26 07:44:29.452459 | controller | PWD=/home/zuul 2025-11-26 07:44:29.452464 | controller | ZUUL_PIPELINE=github-post 2025-11-26 07:44:29.452516 | controller | LOGNAME=zuul 2025-11-26 07:44:29.452526 | controller | XDG_SESSION_TYPE=tty 2025-11-26 07:44:29.452532 | controller | _=/usr/bin/env 2025-11-26 07:44:29.452537 | controller | MOTD_SHOWN=pam 2025-11-26 07:44:29.452566 | controller | HOME=/home/zuul 2025-11-26 07:44:29.452575 | controller | LANG=en_US.UTF-8 2025-11-26 07:44:29.452581 | controller | ZUUL_NEWREV=e99f135999d3359aa047e643dd0cbf0710d583e6 2025-11-26 07:44:29.452587 | controller | SSH_CONNECTION=192.168.26.12 54462 192.168.26.220 22 2025-11-26 07:44:29.452592 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_REFNAME ZUUL_OLDREV ZUUL_NEWREV 2025-11-26 07:44:29.452602 | controller | WORKSPACE=/home/zuul/workspace 2025-11-26 07:44:29.452607 | controller | XDG_SESSION_CLASS=user 2025-11-26 07:44:29.452613 | controller | SELINUX_ROLE_REQUESTED= 2025-11-26 07:44:29.452618 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-11-26 07:44:29.452623 | controller | USER=zuul 2025-11-26 07:44:29.452629 | controller | ZUUL_VOTING=True 2025-11-26 07:44:29.452634 | controller | BUILD_TIMEOUT=5400000 2025-11-26 07:44:29.452639 | controller | SELINUX_USE_CURRENT_RANGE= 2025-11-26 07:44:29.452645 | controller | SHLVL=1 2025-11-26 07:44:29.452651 | controller | XDG_SESSION_ID=1 2025-11-26 07:44:29.452657 | controller | ZUUL_OLDREV=8eeaca4315fb917587e494d361da3adedffa5ede 2025-11-26 07:44:29.452662 | controller | ZUUL_BRANCH=main 2025-11-26 07:44:29.452667 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-11-26 07:44:29.452673 | controller | SSH_CLIENT=192.168.26.12 54462 22 2025-11-26 07:44:29.452678 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-11-26 07:44:29.452684 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-11-26 07:44:29.452689 | controller | which_declare=declare -f 2025-11-26 07:44:29.452695 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-11-26 07:44:29.452701 | controller | SELINUX_LEVEL_REQUESTED= 2025-11-26 07:44:29.452707 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-11-26 07:44:29.452712 | controller | ZUUL_UUID=0189566355ad463e901ef6c273d14bbd 2025-11-26 07:44:29.452717 | controller | BASH_FUNC_which%%=() { ( alias; 2025-11-26 07:44:29.452724 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-11-26 07:44:29.452730 | controller | } 2025-11-26 07:44:29.781789 | controller | ok: Runtime: 0:00:00.005510 2025-11-26 07:44:29.787190 | 2025-11-26 07:44:29.787257 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-11-26 07:44:29.800688 | controller | skipping: Conditional result was False 2025-11-26 07:44:29.805933 | 2025-11-26 07:44:29.805997 | TASK [Symlink /home/zuul-worker/workspace] 2025-11-26 07:44:30.329773 | controller | skipping: Conditional result was False 2025-11-26 07:44:30.335219 | 2025-11-26 07:44:30.335287 | TASK [Ensure legacy workspace directory] 2025-11-26 07:44:30.519287 | controller | changed 2025-11-26 07:44:30.538017 | 2025-11-26 07:44:30.538110 | PLAY RECAP 2025-11-26 07:44:30.538167 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-11-26 07:44:30.538189 | 2025-11-26 07:44:30.667701 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-11-26 07:44:30.668745 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master] 2025-11-26 07:44:31.170044 | 2025-11-26 07:44:31.170149 | PLAY [all,!compute] 2025-11-26 07:44:31.189367 | 2025-11-26 07:44:31.189446 | TASK [Enable container-tools rhel-modules for c8/crc node] 2025-11-26 07:44:31.715076 | controller | skipping: Conditional result was False 2025-11-26 07:44:31.720856 | 2025-11-26 07:44:31.720956 | TASK [Install podman] 2025-11-26 07:45:31.596788 | controller | changed 2025-11-26 07:45:31.601937 | 2025-11-26 07:45:31.602006 | TASK [Set var name for quay login zuul secret] 2025-11-26 07:45:31.645259 | controller | skipping: Conditional result was False 2025-11-26 07:45:31.650290 | 2025-11-26 07:45:31.650359 | TASK [Print the username] 2025-11-26 07:45:31.958397 | controller | openstack-k8s-operators+cirobot 2025-11-26 07:45:32.174490 | controller | ok: Runtime: 0:00:00.004129 2025-11-26 07:45:32.179708 | 2025-11-26 07:45:32.179771 | TASK [Fail when user or password is not set] 2025-11-26 07:45:32.223380 | controller | skipping: Conditional result was False 2025-11-26 07:45:32.228700 | 2025-11-26 07:45:32.228782 | TASK [Set vars for quay login] 2025-11-26 07:45:32.276413 | controller | Output suppressed because no_log was given 2025-11-26 07:45:32.280842 | 2025-11-26 07:45:32.280903 | LOOP [Perform container registry login(s) with podman] 2025-11-26 07:45:33.389379 | controller | Output suppressed because no_log was given 2025-11-26 07:45:33.393861 | 2025-11-26 07:45:33.393939 | LOOP [Perform container registry login(s) with buildah] 2025-11-26 07:45:34.137404 | controller | Output suppressed because no_log was given 2025-11-26 07:45:34.142599 | 2025-11-26 07:45:34.142672 | TASK [Set Insecure registry for content provider] 2025-11-26 07:45:34.186552 | controller | skipping: Conditional result was False 2025-11-26 07:45:34.205976 | 2025-11-26 07:45:34.206052 | PLAY RECAP 2025-11-26 07:45:34.206098 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-11-26 07:45:34.206122 | 2025-11-26 07:45:34.271971 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master] 2025-11-26 07:45:34.272951 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-11-26 07:45:34.827060 | 2025-11-26 07:45:34.827175 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-11-26 07:45:34.848820 | 2025-11-26 07:45:34.848897 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-11-26 07:45:34.867002 | controller | ok 2025-11-26 07:45:34.883674 | 2025-11-26 07:45:34.883756 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-11-26 07:45:34.907274 | controller | skipping: Conditional result was False 2025-11-26 07:45:34.912934 | 2025-11-26 07:45:34.913008 | TASK [mirror-info-fork : Create /etc/ci] 2025-11-26 07:45:35.234724 | controller | ok 2025-11-26 07:45:35.240331 | 2025-11-26 07:45:35.240403 | TASK [mirror-info-fork : Install ci_mirror script] 2025-11-26 07:45:35.856403 | controller | ok 2025-11-26 07:45:35.867189 | 2025-11-26 07:45:35.867256 | TASK [Prepare workspace] 2025-11-26 07:45:35.884640 | controller | ok 2025-11-26 07:45:35.900652 | 2025-11-26 07:45:35.900721 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-11-26 07:45:36.191584 | controller | ok 2025-11-26 07:45:36.198175 | 2025-11-26 07:45:36.198240 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-11-26 07:45:38.043600 | controller | Output suppressed because no_log was given 2025-11-26 07:45:38.052812 | 2025-11-26 07:45:38.052877 | LOOP [Create zuul-output directory] 2025-11-26 07:45:38.239452 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-11-26 07:45:38.399853 | controller | ok: "/home/zuul/zuul-output/logs" 2025-11-26 07:45:38.416253 | 2025-11-26 07:45:38.416356 | TASK [Install required packages] 2025-11-26 07:45:39.258242 | controller | ok: Nothing to do 2025-11-26 07:45:39.263006 | 2025-11-26 07:45:39.263067 | TASK [Install venv] 2025-11-26 07:46:39.997607 | controller | changed 2025-11-26 07:46:40.022214 | 2025-11-26 07:46:40.022294 | PLAY RECAP 2025-11-26 07:46:40.022338 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-11-26 07:46:40.022362 | 2025-11-26 07:46:40.096977 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-11-26 07:46:40.097933 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-11-26 07:46:40.599488 | 2025-11-26 07:46:40.599584 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-11-26 07:46:40.623101 | 2025-11-26 07:46:40.623194 | TASK [Create zuul-output directory] 2025-11-26 07:46:40.925999 | controller | changed 2025-11-26 07:46:40.930743 | 2025-11-26 07:46:40.930815 | TASK [Slurp Zuul inventory test] 2025-11-26 07:46:41.186768 | controller -> localhost | ok 2025-11-26 07:46:41.192757 | 2025-11-26 07:46:41.192831 | TASK [Save zuul inventory] 2025-11-26 07:46:41.898904 | controller | changed 2025-11-26 07:46:41.903872 | 2025-11-26 07:46:41.903955 | TASK [Save zuul vars without the change_message] 2025-11-26 07:46:42.484701 | controller | changed 2025-11-26 07:46:42.502085 | 2025-11-26 07:46:42.502135 | PLAY RECAP 2025-11-26 07:46:42.502178 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-26 07:46:42.502201 | 2025-11-26 07:46:42.565187 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-11-26 07:46:42.566322 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/build_push_container_runner.yml@main] 2025-11-26 07:46:43.085046 | 2025-11-26 07:46:43.085148 | PLAY [Run build_push_container role] 2025-11-26 07:46:43.105483 | 2025-11-26 07:46:43.105554 | TASK [Run build_push_container role] 2025-11-26 07:46:43.822485 | controller | 2025-11-26 07:46:44.613594 | controller | PLAY [Run build_push_container role] ******************************************* 2025-11-26 07:46:44.613644 | controller | 2025-11-26 07:46:44.613654 | controller | TASK [Gathering Facts ] ******************************************************** 2025-11-26 07:46:44.613662 | controller | Wednesday 26 November 2025 07:46:43 +0000 (0:00:00.045) 0:00:00.045 **** 2025-11-26 07:46:44.613669 | controller | Wednesday 26 November 2025 07:46:43 +0000 (0:00:00.044) 0:00:00.044 **** 2025-11-26 07:46:44.613682 | controller | ok: [localhost] 2025-11-26 07:46:44.648780 | controller | 2025-11-26 07:46:44.648805 | controller | TASK [Run build_push_container role name=build_push_container] ***************** 2025-11-26 07:46:44.648815 | controller | Wednesday 26 November 2025 07:46:44 +0000 (0:00:00.791) 0:00:00.837 **** 2025-11-26 07:46:44.648822 | controller | Wednesday 26 November 2025 07:46:44 +0000 (0:00:00.791) 0:00:00.835 **** 2025-11-26 07:46:44.648834 | controller | 2025-11-26 07:46:45.051424 | controller | TASK [build_push_container : Ensure directories are present path={{ item }}, state=directory, mode=0755, recurse=True] *** 2025-11-26 07:46:45.051472 | controller | Wednesday 26 November 2025 07:46:44 +0000 (0:00:00.035) 0:00:00.872 **** 2025-11-26 07:46:45.051480 | controller | Wednesday 26 November 2025 07:46:44 +0000 (0:00:00.035) 0:00:00.871 **** 2025-11-26 07:46:45.051494 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/scripts) 2025-11-26 07:46:45.781851 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/logs) 2025-11-26 07:46:45.781911 | controller | 2025-11-26 07:46:45.781920 | controller | TASK [podman : Ensure podman is installed name={{ cifmw_podman_packages }}, state=present] *** 2025-11-26 07:46:45.781926 | controller | Wednesday 26 November 2025 07:46:45 +0000 (0:00:00.402) 0:00:01.274 **** 2025-11-26 07:46:45.781932 | controller | Wednesday 26 November 2025 07:46:45 +0000 (0:00:00.402) 0:00:01.273 **** 2025-11-26 07:46:45.781944 | controller | ok: [localhost] 2025-11-26 07:46:46.132144 | controller | 2025-11-26 07:46:46.132202 | controller | TASK [podman : Enable loginctl linger for ansible_user_id _raw_params=loginctl enable-linger {{ cifmw_podman_user_linger }}] *** 2025-11-26 07:46:46.132212 | controller | Wednesday 26 November 2025 07:46:45 +0000 (0:00:00.730) 0:00:02.005 **** 2025-11-26 07:46:46.132219 | controller | Wednesday 26 November 2025 07:46:45 +0000 (0:00:00.730) 0:00:02.003 **** 2025-11-26 07:46:46.132231 | controller | changed: [localhost] 2025-11-26 07:46:46.154518 | controller | 2025-11-26 07:46:46.154560 | 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-26 07:46:46.154576 | controller | Wednesday 26 November 2025 07:46:46 +0000 (0:00:00.350) 0:00:02.355 **** 2025-11-26 07:46:46.154584 | controller | Wednesday 26 November 2025 07:46:46 +0000 (0:00:00.350) 0:00:02.354 **** 2025-11-26 07:46:46.154595 | controller | skipping: [localhost] 2025-11-26 07:46:46.173064 | controller | 2025-11-26 07:46:46.173085 | 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-26 07:46:46.173092 | controller | Wednesday 26 November 2025 07:46:46 +0000 (0:00:00.022) 0:00:02.378 **** 2025-11-26 07:46:46.173097 | controller | Wednesday 26 November 2025 07:46:46 +0000 (0:00:00.022) 0:00:02.376 **** 2025-11-26 07:46:46.173107 | controller | skipping: [localhost] 2025-11-26 07:46:46.197688 | controller | 2025-11-26 07:46:46.197713 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed _raw_params=podman system migrate] *** 2025-11-26 07:46:46.197722 | controller | Wednesday 26 November 2025 07:46:46 +0000 (0:00:00.018) 0:00:02.396 **** 2025-11-26 07:46:46.197730 | controller | Wednesday 26 November 2025 07:46:46 +0000 (0:00:00.018) 0:00:02.395 **** 2025-11-26 07:46:46.197741 | controller | skipping: [localhost] 2025-11-26 07:46:53.213568 | controller | 2025-11-26 07:46:53.213633 | 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-26 07:46:53.213655 | controller | Wednesday 26 November 2025 07:46:46 +0000 (0:00:00.024) 0:00:02.421 **** 2025-11-26 07:46:53.213663 | controller | Wednesday 26 November 2025 07:46:46 +0000 (0:00:00.024) 0:00:02.419 **** 2025-11-26 07:46:53.213676 | 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-26 07:46:53.422336 | controller | changed: [localhost] 2025-11-26 07:46:53.422367 | controller | 2025-11-26 07:46:53.422376 | controller | TASK [build_push_container : Ensure manifest doesn't exist _raw_params=podman manifest rm {{ _container_manifest_name }}] *** 2025-11-26 07:46:53.422382 | controller | Wednesday 26 November 2025 07:46:53 +0000 (0:00:07.015) 0:00:09.437 **** 2025-11-26 07:46:53.422389 | controller | Wednesday 26 November 2025 07:46:53 +0000 (0:00:07.015) 0:00:09.435 **** 2025-11-26 07:46:53.422400 | controller | ok: [localhost] 2025-11-26 07:46:53.627964 | controller | 2025-11-26 07:46:53.628004 | controller | TASK [build_push_container : Create manifest _raw_params=podman manifest create {{ _container_manifest_name }}] *** 2025-11-26 07:46:53.628012 | controller | Wednesday 26 November 2025 07:46:53 +0000 (0:00:00.208) 0:00:09.645 **** 2025-11-26 07:46:53.628019 | controller | Wednesday 26 November 2025 07:46:53 +0000 (0:00:00.208) 0:00:09.644 **** 2025-11-26 07:46:53.628030 | controller | changed: [localhost] 2025-11-26 07:59:41.786772 | controller | 2025-11-26 07:59:41.786818 | 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-26 07:59:41.786831 | controller | Wednesday 26 November 2025 07:46:53 +0000 (0:00:00.205) 0:00:09.851 **** 2025-11-26 07:59:41.786838 | controller | Wednesday 26 November 2025 07:46:53 +0000 (0:00:00.205) 0:00:09.850 **** 2025-11-26 07:59:41.786848 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_001_build.log 2025-11-26 08:00:11.396445 | controller | changed: [localhost] 2025-11-26 08:00:11.396492 | controller | 2025-11-26 08:00:11.396502 | 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-26 08:00:11.396511 | controller | Wednesday 26 November 2025 07:59:41 +0000 (0:12:48.158) 0:12:58.010 **** 2025-11-26 08:00:11.396517 | controller | Wednesday 26 November 2025 07:59:41 +0000 (0:12:48.158) 0:12:58.008 **** 2025-11-26 08:00:11.396527 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_002_push.log 2025-11-26 08:00:11.445906 | controller | changed: [localhost] => (item=e99f135999d3359aa047e643dd0cbf0710d583e6) 2025-11-26 08:00:11.445937 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_003_push.log 2025-11-26 08:00:11.445944 | controller | changed: [localhost] => (item=latest) 2025-11-26 08:00:11.445950 | controller | 2025-11-26 08:00:11.445955 | 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-26 08:00:11.445960 | controller | Wednesday 26 November 2025 08:00:11 +0000 (0:00:29.609) 0:13:27.620 **** 2025-11-26 08:00:11.445965 | controller | Wednesday 26 November 2025 08:00:11 +0000 (0:00:29.609) 0:13:27.618 **** 2025-11-26 08:00:11.445974 | controller | skipping: [localhost] => (item=e99f135999d3359aa047e643dd0cbf0710d583e6) 2025-11-26 08:00:11.493727 | controller | skipping: [localhost] => (item=latest) 2025-11-26 08:00:11.493789 | controller | skipping: [localhost] 2025-11-26 08:00:11.493855 | controller | 2025-11-26 08:00:11.493866 | controller | PLAY RECAP ********************************************************************* 2025-11-26 08:00:11.493874 | controller | localhost : ok=9 changed=6 unreachable=0 failed=0 skipped=4 rescued=0 ignored=0 2025-11-26 08:00:11.493880 | controller | 2025-11-26 08:00:11.493886 | controller | Wednesday 26 November 2025 08:00:11 +0000 (0:00:00.049) 0:13:27.669 **** 2025-11-26 08:00:11.493902 | controller | =============================================================================== 2025-11-26 08:00:11.493908 | controller | build_push_container : Build container -------------------------------- 768.16s 2025-11-26 08:00:11.493914 | controller | build_push_container : Push manifest ----------------------------------- 29.61s 2025-11-26 08:00:11.493920 | controller | build_push_container : Install qemu-user-static for multi arch builds --- 7.02s 2025-11-26 08:00:11.493927 | controller | Gathering Facts --------------------------------------------------------- 0.79s 2025-11-26 08:00:11.493933 | controller | podman : Ensure podman is installed ------------------------------------- 0.73s 2025-11-26 08:00:11.493939 | controller | build_push_container : Ensure directories are present ------------------- 0.40s 2025-11-26 08:00:11.493945 | controller | podman : Enable loginctl linger for ansible_user_id --------------------- 0.35s 2025-11-26 08:00:11.493952 | controller | build_push_container : Ensure manifest doesn't exist -------------------- 0.21s 2025-11-26 08:00:11.493961 | controller | build_push_container : Create manifest ---------------------------------- 0.21s 2025-11-26 08:00:11.493968 | controller | build_push_container : Push container ----------------------------------- 0.05s 2025-11-26 08:00:11.493974 | controller | Run build_push_container role ------------------------------------------- 0.04s 2025-11-26 08:00:11.493980 | controller | podman : Run podman system migrate if subuid/subgid files were changed --- 0.02s 2025-11-26 08:00:11.493987 | controller | podman : Ensure subordinate UID entry exists for zuul ------------------- 0.02s 2025-11-26 08:00:11.493993 | controller | podman : Ensure subordinate GID entry exists for zuul ------------------- 0.02s 2025-11-26 08:00:11.493999 | controller | Wednesday 26 November 2025 08:00:11 +0000 (0:00:00.049) 0:13:27.668 **** 2025-11-26 08:00:11.494005 | controller | =============================================================================== 2025-11-26 08:00:11.494011 | controller | build_push_container -------------------------------------------------- 805.65s 2025-11-26 08:00:11.494017 | controller | podman ------------------------------------------------------------------ 1.15s 2025-11-26 08:00:11.494023 | controller | gather_facts ------------------------------------------------------------ 0.79s 2025-11-26 08:00:11.494030 | controller | ansible.builtin.include_role -------------------------------------------- 0.04s 2025-11-26 08:00:11.494036 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ 2025-11-26 08:00:11.494042 | controller | total ----------------------------------------------------------------- 807.62s 2025-11-26 08:00:11.976562 | controller | changed 2025-11-26 08:00:11.994457 | 2025-11-26 08:00:11.994524 | PLAY RECAP 2025-11-26 08:00:11.994566 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-26 08:00:11.994589 | 2025-11-26 08:00:12.063505 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/build_push_container_runner.yml@main] 2025-11-26 08:00:12.064564 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-11-26 08:00:12.572153 | 2025-11-26 08:00:12.572247 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-11-26 08:00:12.592123 | 2025-11-26 08:00:12.592193 | TASK [Filter out host if needed] 2025-11-26 08:00:12.600571 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-11-26 08:00:12.605136 | 2025-11-26 08:00:12.605204 | TASK [Ensure file is present] 2025-11-26 08:00:12.898567 | controller | ok 2025-11-26 08:00:12.903623 | 2025-11-26 08:00:12.903686 | TASK [Manage molecule report file] 2025-11-26 08:00:13.427836 | controller | skipping: Conditional result was False 2025-11-26 08:00:13.432978 | 2025-11-26 08:00:13.433047 | TASK [Check if we get ci-framework-data basedir] 2025-11-26 08:00:13.613615 | controller | ok 2025-11-26 08:00:13.618598 | 2025-11-26 08:00:13.618660 | TASK [Create ci-framework-data log directory for zuul] 2025-11-26 08:00:13.910535 | controller | changed 2025-11-26 08:00:13.915615 | 2025-11-26 08:00:13.915683 | TASK [Copy ci-framework interesting files] 2025-11-26 08:00:14.215935 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-11-26 08:00:14.438187 | controller | changed 2025-11-26 08:00:14.443180 | 2025-11-26 08:00:14.443246 | TASK [Get SELinux listing] 2025-11-26 08:00:14.968229 | controller | changed 2025-11-26 08:00:14.973934 | 2025-11-26 08:00:14.974001 | TASK [Generate log index] 2025-11-26 08:00:15.569422 | controller | changed 2025-11-26 08:00:15.574194 | 2025-11-26 08:00:15.574258 | TASK [Get some env related data] 2025-11-26 08:00:16.110240 | controller | /home/zuul/.local/bin/ansible 2025-11-26 08:00:17.098527 | controller | changed 2025-11-26 08:00:17.103455 | 2025-11-26 08:00:17.103515 | TASK [Generate list of logs to collect in home directory] 2025-11-26 08:00:17.375749 | controller | ok: All paths examined 2025-11-26 08:00:17.380670 | 2025-11-26 08:00:17.380795 | LOOP [Copy logs from home directory] 2025-11-26 08:00:17.662591 | controller | changed: 2025-11-26 08:00:17.662993 | controller | { 2025-11-26 08:00:17.663023 | controller | "atime": 1764143183.762796, 2025-11-26 08:00:17.663044 | controller | "ctime": 1764144011.445066, 2025-11-26 08:00:17.663062 | controller | "dev": 64513, 2025-11-26 08:00:17.663078 | controller | "gid": 1000, 2025-11-26 08:00:17.663093 | controller | "gr_name": "zuul", 2025-11-26 08:00:17.663109 | controller | "inode": 4331799, 2025-11-26 08:00:17.663124 | controller | "isblk": false, 2025-11-26 08:00:17.663140 | controller | "ischr": false, 2025-11-26 08:00:17.663154 | controller | "isdir": false, 2025-11-26 08:00:17.663169 | controller | "isfifo": false, 2025-11-26 08:00:17.663183 | controller | "isgid": false, 2025-11-26 08:00:17.663197 | controller | "islnk": false, 2025-11-26 08:00:17.663210 | controller | "isreg": true, 2025-11-26 08:00:17.663224 | controller | "issock": false, 2025-11-26 08:00:17.663237 | controller | "isuid": false, 2025-11-26 08:00:17.663250 | controller | "mode": "0644", 2025-11-26 08:00:17.663273 | controller | "mtime": 1764144011.445066, 2025-11-26 08:00:17.663288 | controller | "nlink": 1, 2025-11-26 08:00:17.663301 | controller | "path": "/home/zuul/ansible.log", 2025-11-26 08:00:17.663318 | controller | "pw_name": "zuul", 2025-11-26 08:00:17.663332 | controller | "rgrp": true, 2025-11-26 08:00:17.663345 | controller | "roth": true, 2025-11-26 08:00:17.663359 | controller | "rusr": true, 2025-11-26 08:00:17.663373 | controller | "size": 18982, 2025-11-26 08:00:17.663386 | controller | "uid": 1000, 2025-11-26 08:00:17.663400 | controller | "wgrp": false, 2025-11-26 08:00:17.663413 | controller | "woth": false, 2025-11-26 08:00:17.663429 | controller | "wusr": true, 2025-11-26 08:00:17.663443 | controller | "xgrp": false, 2025-11-26 08:00:17.663457 | controller | "xoth": false, 2025-11-26 08:00:17.663470 | controller | "xusr": false 2025-11-26 08:00:17.663484 | controller | } 2025-11-26 08:00:17.694480 | 2025-11-26 08:00:17.694599 | TASK [Copy crio stats log file] 2025-11-26 08:00:17.708257 | controller | skipping: Conditional result was False 2025-11-26 08:00:17.713699 | 2025-11-26 08:00:17.713777 | TASK [Get SELinux related data] 2025-11-26 08:00:17.917340 | controller | 2025-11-26 08:00:18.238739 | controller | ERROR 2025-11-26 08:00:18.238900 | controller | { 2025-11-26 08:00:18.238955 | controller | "delta": "0:00:00.009667", 2025-11-26 08:00:18.238979 | controller | "end": "2025-11-26 08:00:17.917884", 2025-11-26 08:00:18.238997 | controller | "msg": "non-zero return code", 2025-11-26 08:00:18.239013 | controller | "rc": 1, 2025-11-26 08:00:18.239037 | controller | "start": "2025-11-26 08:00:17.908217" 2025-11-26 08:00:18.239054 | controller | } 2025-11-26 08:00:18.239075 | controller | ERROR: Ignoring Errors 2025-11-26 08:00:18.243755 | 2025-11-26 08:00:18.243817 | TASK [Create system configuration directory] 2025-11-26 08:00:18.424051 | controller | changed 2025-11-26 08:00:18.428744 | 2025-11-26 08:00:18.428809 | TASK [Get some of the system configurations] 2025-11-26 08:00:18.954668 | controller | changed 2025-11-26 08:00:18.959566 | 2025-11-26 08:00:18.959631 | TASK [Copy generated documentation if available] 2025-11-26 08:00:18.972940 | controller | skipping: Conditional result was False 2025-11-26 08:00:18.978233 | 2025-11-26 08:00:18.978307 | TASK [Copy generated AsciiDoc documentation if available] 2025-11-26 08:00:18.991341 | controller | skipping: Conditional result was False 2025-11-26 08:00:18.996454 | 2025-11-26 08:00:18.996515 | TASK [Compress logs bigger than 2MB] 2025-11-26 08:00:19.522634 | controller | changed 2025-11-26 08:00:19.527814 | 2025-11-26 08:00:19.527884 | TASK [Copy files from workspace on node] 2025-11-26 08:00:19.545723 | controller | ok 2025-11-26 08:00:19.567215 | 2025-11-26 08:00:19.567304 | TASK [fetch-output : Set log path for multiple nodes] 2025-11-26 08:00:19.580717 | controller | skipping: Conditional result was False 2025-11-26 08:00:19.586143 | 2025-11-26 08:00:19.586213 | TASK [fetch-output : Set log path for single node] 2025-11-26 08:00:19.614161 | controller | ok 2025-11-26 08:00:19.618902 | 2025-11-26 08:00:19.618983 | LOOP [fetch-output : Ensure local output dirs] 2025-11-26 08:00:19.810121 | controller -> localhost | ok: "/var/lib/zuul/builds/0189566355ad463e901ef6c273d14bbd/work/logs" 2025-11-26 08:00:19.810355 | controller -> localhost | changed: All items complete 2025-11-26 08:00:19.810384 | 2025-11-26 08:00:19.985579 | controller -> localhost | changed: "/var/lib/zuul/builds/0189566355ad463e901ef6c273d14bbd/work/artifacts" 2025-11-26 08:00:20.159893 | controller -> localhost | changed: "/var/lib/zuul/builds/0189566355ad463e901ef6c273d14bbd/work/docs" 2025-11-26 08:00:20.176509 | 2025-11-26 08:00:20.176605 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-26 08:00:20.691943 | controller | changed: 2025-11-26 08:00:20.692112 | controller | .d..t...... ./ 2025-11-26 08:00:20.692142 | controller | >f+++++++++ README.html 2025-11-26 08:00:20.692163 | controller | >f+++++++++ ansible.log 2025-11-26 08:00:20.692182 | controller | >f+++++++++ dmesg.log 2025-11-26 08:00:20.692199 | controller | >f+++++++++ installed-pkgs.log 2025-11-26 08:00:20.692216 | controller | >f+++++++++ python.log 2025-11-26 08:00:20.692235 | controller | >f+++++++++ registries.conf 2025-11-26 08:00:20.692270 | controller | >f+++++++++ selinux-denials.log 2025-11-26 08:00:20.692288 | controller | >f+++++++++ selinux-listing.log 2025-11-26 08:00:20.692307 | controller | cd+++++++++ ci-framework-data/ 2025-11-26 08:00:20.692323 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-11-26 08:00:20.692339 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-11-26 08:00:20.692354 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/ 2025-11-26 08:00:20.692369 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/logs/ 2025-11-26 08:00:20.692385 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_000_install_qemu_user_static_for.log 2025-11-26 08:00:20.692402 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_001_build.log 2025-11-26 08:00:20.692418 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_002_push.log 2025-11-26 08:00:20.692433 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_003_push.log 2025-11-26 08:00:20.692448 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ 2025-11-26 08:00:20.692462 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_000_install_qemu_user_static_for.sh 2025-11-26 08:00:20.692483 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_001_build.sh 2025-11-26 08:00:20.692499 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_002_push.sh 2025-11-26 08:00:20.692514 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_003_push.sh 2025-11-26 08:00:20.692529 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-11-26 08:00:20.692544 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-11-26 08:00:20.692558 | controller | cd+++++++++ ci-framework-data/logs/ 2025-11-26 08:00:20.692572 | controller | cd+++++++++ registries.conf.d/ 2025-11-26 08:00:20.692587 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-11-26 08:00:20.692601 | controller | cd+++++++++ system-config/ 2025-11-26 08:00:20.692616 | controller | cd+++++++++ system-config/libvirt/ 2025-11-26 08:00:20.692630 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-11-26 08:00:20.692645 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-11-26 08:00:21.050142 | controller | changed: .d..t...... ./ 2025-11-26 08:00:21.410426 | controller | changed: .d..t...... ./ 2025-11-26 08:00:21.426122 | 2025-11-26 08:00:21.426197 | TASK [Return artifact to Zuul] 2025-11-26 08:00:21.439136 | controller | skipping: Conditional result was False 2025-11-26 08:00:21.457492 | 2025-11-26 08:00:21.457549 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-11-26 08:00:21.457639 | 2025-11-26 08:00:21.457665 | PLAY RECAP 2025-11-26 08:00:21.457702 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1 2025-11-26 08:00:21.457723 | 2025-11-26 08:00:21.528608 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-11-26 08:00:21.529606 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-11-26 08:00:22.027928 | 2025-11-26 08:00:22.028050 | PLAY [all] 2025-11-26 08:00:22.046585 | 2025-11-26 08:00:22.046663 | TASK [include_role : fetch-output] 2025-11-26 08:00:22.075854 | controller | ok 2025-11-26 08:00:22.091300 | 2025-11-26 08:00:22.091388 | TASK [fetch-output : Set log path for multiple nodes] 2025-11-26 08:00:22.135244 | controller | skipping: Conditional result was False 2025-11-26 08:00:22.140646 | 2025-11-26 08:00:22.140721 | TASK [fetch-output : Set log path for single node] 2025-11-26 08:00:22.168289 | controller | ok 2025-11-26 08:00:22.173199 | 2025-11-26 08:00:22.173284 | LOOP [fetch-output : Ensure local output dirs] 2025-11-26 08:00:22.480550 | controller -> localhost | ok: "/var/lib/zuul/builds/0189566355ad463e901ef6c273d14bbd/work/logs" 2025-11-26 08:00:22.657325 | controller -> localhost | ok: "/var/lib/zuul/builds/0189566355ad463e901ef6c273d14bbd/work/artifacts" 2025-11-26 08:00:22.835708 | controller -> localhost | ok: "/var/lib/zuul/builds/0189566355ad463e901ef6c273d14bbd/work/docs" 2025-11-26 08:00:22.851756 | 2025-11-26 08:00:22.851829 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-26 08:00:23.344158 | controller | ok 2025-11-26 08:00:23.344360 | controller | ok: All items complete 2025-11-26 08:00:23.344390 | 2025-11-26 08:00:23.700561 | controller | ok 2025-11-26 08:00:24.061375 | controller | ok 2025-11-26 08:00:24.078670 | 2025-11-26 08:00:24.078739 | TASK [include_role : fetch-output-openshift] 2025-11-26 08:00:24.092461 | controller | skipping: Conditional result was False 2025-11-26 08:00:24.097782 | 2025-11-26 08:00:24.097850 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-26 08:00:24.120015 | controller | skipping: Conditional result was False 2025-11-26 08:00:24.127269 | controller | skipping: Conditional result was False 2025-11-26 08:00:24.155340 | 2025-11-26 08:00:24.155395 | PLAY [all] 2025-11-26 08:00:24.168905 | 2025-11-26 08:00:24.168999 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-11-26 08:00:24.552313 | controller | changed 2025-11-26 08:00:24.573998 | 2025-11-26 08:00:24.574056 | PLAY RECAP 2025-11-26 08:00:24.574099 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2025-11-26 08:00:24.574120 | 2025-11-26 08:00:24.639324 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-11-26 08:00:24.640269 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-11-26 08:00:25.189962 | 2025-11-26 08:00:25.190059 | PLAY [localhost] 2025-11-26 08:00:25.207196 | 2025-11-26 08:00:25.207288 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-26 08:00:25.497727 | localhost | changed 2025-11-26 08:00:25.502138 | 2025-11-26 08:00:25.502229 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-26 08:00:25.519864 | localhost | ok 2025-11-26 08:00:25.527620 | 2025-11-26 08:00:25.527690 | TASK [add-fileserver : Create SSH private key tempfile] 2025-11-26 08:00:25.821133 | localhost | changed 2025-11-26 08:00:25.825638 | 2025-11-26 08:00:25.825706 | TASK [add-fileserver : Create SSH private key from secret] 2025-11-26 08:00:26.348464 | localhost | changed 2025-11-26 08:00:26.353263 | 2025-11-26 08:00:26.353329 | TASK [add-fileserver : Add fileserver ssh key] 2025-11-26 08:00:26.674266 | localhost | Identity added: /var/lib/zuul/builds/0189566355ad463e901ef6c273d14bbd/work/tmp/ansible.dsrg0a4t (/var/lib/zuul/builds/0189566355ad463e901ef6c273d14bbd/work/tmp/ansible.dsrg0a4t) 2025-11-26 08:00:26.674421 | localhost | ok: Runtime: 0:00:00.006046 2025-11-26 08:00:26.678622 | 2025-11-26 08:00:26.678693 | TASK [add-fileserver : Remove SSH private key from disk] 2025-11-26 08:00:26.887880 | localhost | ok: Runtime: 0:00:00.003989 2025-11-26 08:00:26.892475 | 2025-11-26 08:00:26.892540 | TASK [add-fileserver : Add fileserver to inventory] 2025-11-26 08:00:26.941081 | localhost | changed 2025-11-26 08:00:26.945115 | 2025-11-26 08:00:26.945180 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-11-26 08:00:27.255507 | localhost | changed 2025-11-26 08:00:27.274851 | 2025-11-26 08:00:27.274925 | PLAY [localhost] 2025-11-26 08:00:27.285946 | 2025-11-26 08:00:27.286009 | TASK [Generate bulk log download script] 2025-11-26 08:00:27.303812 | localhost | ok 2025-11-26 08:00:27.314776 | 2025-11-26 08:00:27.314840 | TASK [local-log-download : Check API endpoint is defined] 2025-11-26 08:00:27.341888 | localhost | ok: All assertions passed 2025-11-26 08:00:27.346129 | 2025-11-26 08:00:27.346191 | TASK [local-log-download : Create download script] 2025-11-26 08:00:27.683052 | localhost -> localhost | changed 2025-11-26 08:00:27.691532 | 2025-11-26 08:00:27.691595 | TASK [Register quick-download link] 2025-11-26 08:00:27.708403 | localhost | ok 2025-11-26 08:00:27.740156 | 2025-11-26 08:00:27.740233 | PLAY [logserver.rdoproject.org] 2025-11-26 08:00:27.749364 | 2025-11-26 08:00:27.749422 | TASK [Set zuul-log-path fact] 2025-11-26 08:00:27.764768 | logserver.rdoproject.org | ok 2025-11-26 08:00:27.773298 | 2025-11-26 08:00:27.773364 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-26 08:00:27.789754 | logserver.rdoproject.org | ok 2025-11-26 08:00:27.794815 | 2025-11-26 08:00:27.794882 | TASK [upload-logs : Create log directories] 2025-11-26 08:00:29.702950 | logserver.rdoproject.org | changed 2025-11-26 08:00:29.705923 | 2025-11-26 08:00:29.705988 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-11-26 08:00:29.922214 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.003927 2025-11-26 08:00:29.926684 | 2025-11-26 08:00:29.926747 | TASK [upload-logs : Upload logs to log server] 2025-11-26 08:00:31.405039 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-11-26 08:00:31.407779 | 2025-11-26 08:00:31.407840 | LOOP [upload-logs : Compress console log and json output] 2025-11-26 08:00:31.443553 | logserver.rdoproject.org | skipping: Conditional result was False 2025-11-26 08:00:31.451465 | logserver.rdoproject.org | skipping: Conditional result was False 2025-11-26 08:00:31.465276 | 2025-11-26 08:00:31.465360 | LOOP [upload-logs : Upload compressed console log and json output] 2025-11-26 08:00:31.499693 | logserver.rdoproject.org | skipping: Conditional result was False 2025-11-26 08:00:31.499896 | 2025-11-26 08:00:31.502851 | logserver.rdoproject.org | skipping: Conditional result was False 2025-11-26 08:00:31.513867 | 2025-11-26 08:00:31.513980 | LOOP [upload-logs : Upload console log and json output]