2025-10-01 14:19:58.954069 | Job console starting... 2025-10-01 14:19:59.189994 | Updating repositories 2025-10-01 14:20:00.013537 | Preparing job workspace 2025-10-01 14:20:07.126681 | Running Ansible setup... 2025-10-01 14:20:09.947547 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-01 14:20:10.410156 | 2025-10-01 14:20:10.410284 | PLAY [localhost] 2025-10-01 14:20:10.417939 | 2025-10-01 14:20:10.418022 | TASK [Gathering Facts] 2025-10-01 14:20:11.193554 | localhost | ok 2025-10-01 14:20:11.205251 | 2025-10-01 14:20:11.205324 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-01 14:20:11.493149 | localhost -> localhost | changed 2025-10-01 14:20:11.497874 | 2025-10-01 14:20:11.497953 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-01 14:20:12.120192 | localhost -> localhost | changed 2025-10-01 14:20:12.127934 | 2025-10-01 14:20:12.128009 | TASK [Setup log path fact] 2025-10-01 14:20:12.145010 | localhost | ok 2025-10-01 14:20:12.155133 | 2025-10-01 14:20:12.155197 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-01 14:20:12.172462 | localhost | ok 2025-10-01 14:20:12.179063 | 2025-10-01 14:20:12.179126 | TASK [emit-job-header : Print job information] 2025-10-01 14:20:12.205828 | # Job Information 2025-10-01 14:20:12.205961 | Ansible Version: 2.15.12 2025-10-01 14:20:12.205991 | Job: build-push-container-cifmw-client 2025-10-01 14:20:12.206013 | Pipeline: github-check 2025-10-01 14:20:12.206032 | Executor: ibm-bm4-ze.softwarefactory-project.io 2025-10-01 14:20:12.206049 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3303 2025-10-01 14:20:12.206067 | Log URL (when completed): https://logserver.rdoproject.org/494/rdoproject.org/494325381e6f4e22a6b5a94626f5d769/ 2025-10-01 14:20:12.206084 | Event ID: 916f4f70-9ed1-11f0-9f52-3c94ac7cc282 2025-10-01 14:20:12.209398 | 2025-10-01 14:20:12.209460 | LOOP [emit-job-header : Print node information] 2025-10-01 14:20:12.287404 | localhost | ok: 2025-10-01 14:20:12.287592 | localhost | # Node Information 2025-10-01 14:20:12.287620 | localhost | Inventory Hostname: controller 2025-10-01 14:20:12.287641 | localhost | Hostname: np0005464766 2025-10-01 14:20:12.287659 | localhost | Username: zuul 2025-10-01 14:20:12.287677 | localhost | Distro: CentOS 9 2025-10-01 14:20:12.287694 | localhost | Provider: ibm-bm4-nodepool 2025-10-01 14:20:12.287709 | localhost | Region: regionOne 2025-10-01 14:20:12.287724 | localhost | Label: cloud-centos-9-stream-tripleo 2025-10-01 14:20:12.287738 | localhost | Product Name: OpenStack Compute 2025-10-01 14:20:12.287752 | localhost | Interface IP: 192.168.26.92 2025-10-01 14:20:12.311754 | 2025-10-01 14:20:12.311831 | PLAY [all] 2025-10-01 14:20:12.317542 | 2025-10-01 14:20:12.317599 | TASK [Gather network facts] 2025-10-01 14:20:12.681925 | controller | ok 2025-10-01 14:20:12.694212 | 2025-10-01 14:20:12.694270 | TASK [include_role : start-zuul-console] 2025-10-01 14:20:12.711998 | controller | ok 2025-10-01 14:20:12.721760 | 2025-10-01 14:20:12.721826 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-01 14:20:13.012244 | controller | ok 2025-10-01 14:20:13.019942 | 2025-10-01 14:20:13.020004 | TASK [include_role : add-build-sshkey] 2025-10-01 14:20:13.037140 | controller | ok 2025-10-01 14:20:13.048350 | 2025-10-01 14:20:13.048408 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-01 14:20:13.223410 | controller -> localhost | ok 2025-10-01 14:20:13.228229 | 2025-10-01 14:20:13.228286 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-01 14:20:13.246193 | controller | ok 2025-10-01 14:20:13.256940 | controller | included: /var/lib/zuul/builds/494325381e6f4e22a6b5a94626f5d769/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-01 14:20:13.261811 | 2025-10-01 14:20:13.261867 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-01 14:20:13.711957 | controller -> localhost | Generating public/private rsa key pair. 2025-10-01 14:20:13.712160 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/494325381e6f4e22a6b5a94626f5d769/work/494325381e6f4e22a6b5a94626f5d769_id_rsa. 2025-10-01 14:20:13.712197 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/494325381e6f4e22a6b5a94626f5d769/work/494325381e6f4e22a6b5a94626f5d769_id_rsa.pub. 2025-10-01 14:20:13.712221 | controller -> localhost | The key fingerprint is: 2025-10-01 14:20:13.712242 | controller -> localhost | SHA256:R3j4SS2RKiFUg87mEkeX353vHvistWqrUO7PLOnYo20 zuul-build-sshkey 2025-10-01 14:20:13.712261 | controller -> localhost | The key's randomart image is: 2025-10-01 14:20:13.712278 | controller -> localhost | +---[RSA 3072]----+ 2025-10-01 14:20:13.712296 | controller -> localhost | | ..oo. .. | 2025-10-01 14:20:13.712315 | controller -> localhost | | + +. o.o | 2025-10-01 14:20:13.712333 | controller -> localhost | | + o ooo=... | 2025-10-01 14:20:13.712352 | controller -> localhost | | . = . o=.oo | 2025-10-01 14:20:13.712369 | controller -> localhost | | = .S = . | 2025-10-01 14:20:13.712386 | controller -> localhost | | . . + .. | 2025-10-01 14:20:13.712403 | controller -> localhost | | . . ....o | 2025-10-01 14:20:13.712421 | controller -> localhost | | *Eo.+.o | 2025-10-01 14:20:13.712439 | controller -> localhost | | o+**B== | 2025-10-01 14:20:13.712455 | controller -> localhost | +----[SHA256]-----+ 2025-10-01 14:20:13.712500 | controller -> localhost | ok: Runtime: 0:00:00.151972 2025-10-01 14:20:13.717980 | 2025-10-01 14:20:13.718038 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-01 14:20:13.735357 | controller | ok 2025-10-01 14:20:13.743709 | controller | included: /var/lib/zuul/builds/494325381e6f4e22a6b5a94626f5d769/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-01 14:20:13.750622 | 2025-10-01 14:20:13.750678 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-01 14:20:13.763693 | controller | skipping: Conditional result was False 2025-10-01 14:20:13.768365 | 2025-10-01 14:20:13.768423 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-01 14:20:14.121935 | controller | changed 2025-10-01 14:20:14.126493 | 2025-10-01 14:20:14.126557 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-01 14:20:14.306930 | controller | ok 2025-10-01 14:20:14.311334 | 2025-10-01 14:20:14.311395 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-01 14:20:14.866253 | controller | changed 2025-10-01 14:20:14.870721 | 2025-10-01 14:20:14.870784 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-01 14:20:15.416426 | controller | changed 2025-10-01 14:20:15.420710 | 2025-10-01 14:20:15.420768 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-01 14:20:15.433976 | controller | skipping: Conditional result was False 2025-10-01 14:20:15.439264 | 2025-10-01 14:20:15.439328 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-01 14:20:15.716289 | controller -> localhost | changed 2025-10-01 14:20:15.725605 | 2025-10-01 14:20:15.725676 | TASK [add-build-sshkey : Add back temp key] 2025-10-01 14:20:15.931543 | controller -> localhost | Identity added: /var/lib/zuul/builds/494325381e6f4e22a6b5a94626f5d769/work/494325381e6f4e22a6b5a94626f5d769_id_rsa (zuul-build-sshkey) 2025-10-01 14:20:15.931692 | controller -> localhost | ok: Runtime: 0:00:00.006138 2025-10-01 14:20:15.936574 | 2025-10-01 14:20:15.936632 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-01 14:20:16.217127 | controller | ok 2025-10-01 14:20:16.221213 | 2025-10-01 14:20:16.221271 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-01 14:20:16.244837 | controller | skipping: Conditional result was False 2025-10-01 14:20:16.253602 | 2025-10-01 14:20:16.253664 | TASK [include_role : validate-host] 2025-10-01 14:20:16.271129 | controller | ok 2025-10-01 14:20:16.290072 | 2025-10-01 14:20:16.290141 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-01 14:20:16.317967 | controller | ok 2025-10-01 14:20:16.322284 | 2025-10-01 14:20:16.322347 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-01 14:20:16.507644 | controller -> localhost | ok 2025-10-01 14:20:16.512904 | 2025-10-01 14:20:16.512983 | TASK [validate-host : Collect information about the host] 2025-10-01 14:20:17.120945 | controller | ok 2025-10-01 14:20:17.128114 | 2025-10-01 14:20:17.128174 | TASK [validate-host : Sanitize hostname] 2025-10-01 14:20:17.186440 | controller | ok 2025-10-01 14:20:17.192058 | 2025-10-01 14:20:17.192117 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-01 14:20:17.570385 | controller -> localhost | changed 2025-10-01 14:20:17.575203 | 2025-10-01 14:20:17.575272 | TASK [validate-host : Collect information about zuul worker] 2025-10-01 14:20:17.886813 | controller | ok 2025-10-01 14:20:17.891302 | 2025-10-01 14:20:17.891393 | TASK [validate-host : Write out all zuul information for each host] 2025-10-01 14:20:18.228544 | controller -> localhost | changed 2025-10-01 14:20:18.237055 | 2025-10-01 14:20:18.237114 | TASK [include_role : prepare-workspace-openshift] 2025-10-01 14:20:18.249756 | controller | skipping: Conditional result was False 2025-10-01 14:20:18.254517 | 2025-10-01 14:20:18.254574 | TASK [include_role : remove-zuul-sshkey] 2025-10-01 14:20:18.267055 | controller | skipping: Conditional result was False 2025-10-01 14:20:18.271706 | 2025-10-01 14:20:18.271770 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-01 14:20:18.456662 | controller | ok: "logs" 2025-10-01 14:20:18.456904 | controller | ok: All items complete 2025-10-01 14:20:18.456963 | 2025-10-01 14:20:18.616074 | controller | ok: "artifacts" 2025-10-01 14:20:18.772188 | controller | ok: "docs" 2025-10-01 14:20:18.787276 | 2025-10-01 14:20:18.787338 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-01 14:20:18.972211 | controller | changed: "logs" 2025-10-01 14:20:19.133027 | controller | changed: "artifacts" 2025-10-01 14:20:19.293797 | controller | changed: "docs" 2025-10-01 14:20:19.314154 | 2025-10-01 14:20:19.314210 | PLAY RECAP 2025-10-01 14:20:19.314252 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-01 14:20:19.314278 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-01 14:20:19.314295 | 2025-10-01 14:20:19.385864 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-01 14:20:19.386880 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-01 14:20:19.872433 | 2025-10-01 14:20:19.872556 | PLAY [all] 2025-10-01 14:20:19.890013 | 2025-10-01 14:20:19.890081 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-01 14:20:19.927816 | controller | ok 2025-10-01 14:20:19.932556 | 2025-10-01 14:20:19.932628 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-01 14:20:20.254387 | controller | changed 2025-10-01 14:20:20.259598 | 2025-10-01 14:20:20.259671 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-01 14:20:21.026939 | controller | changed 2025-10-01 14:20:21.036067 | 2025-10-01 14:20:21.036136 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-01 14:20:21.361886 | controller | changed: 2025-10-01 14:20:21.362084 | controller | { 2025-10-01 14:20:21.362112 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-01 14:20:21.362138 | controller | } 2025-10-01 14:20:21.550244 | controller | changed: 2025-10-01 14:20:21.550293 | controller | { 2025-10-01 14:20:21.550320 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-01 14:20:21.550340 | controller | } 2025-10-01 14:20:21.740428 | controller | changed: 2025-10-01 14:20:21.740483 | controller | { 2025-10-01 14:20:21.740512 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com" 2025-10-01 14:20:21.740535 | controller | } 2025-10-01 14:20:21.939358 | controller | changed: 2025-10-01 14:20:21.939406 | controller | { 2025-10-01 14:20:21.939433 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-01 14:20:21.939452 | controller | } 2025-10-01 14:20:22.133761 | controller | changed: 2025-10-01 14:20:22.133860 | controller | { 2025-10-01 14:20:22.133888 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-01 14:20:22.133923 | controller | } 2025-10-01 14:20:22.330104 | controller | changed: 2025-10-01 14:20:22.330162 | controller | { 2025-10-01 14:20:22.330189 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-01 14:20:22.330210 | controller | } 2025-10-01 14:20:22.524333 | controller | changed: 2025-10-01 14:20:22.524384 | controller | { 2025-10-01 14:20:22.524410 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen" 2025-10-01 14:20:22.524430 | controller | } 2025-10-01 14:20:22.719524 | controller | changed: 2025-10-01 14:20:22.719569 | controller | { 2025-10-01 14:20:22.719593 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora" 2025-10-01 14:20:22.719611 | controller | } 2025-10-01 14:20:22.915062 | controller | changed: 2025-10-01 14:20:22.915114 | controller | { 2025-10-01 14:20:22.915138 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-01 14:20:22.915157 | controller | } 2025-10-01 14:20:23.117710 | controller | changed: 2025-10-01 14:20:23.117757 | controller | { 2025-10-01 14:20:23.117780 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-01 14:20:23.117799 | controller | } 2025-10-01 14:20:23.323639 | controller | changed: 2025-10-01 14:20:23.323744 | controller | { 2025-10-01 14:20:23.323775 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-01 14:20:23.323796 | controller | } 2025-10-01 14:20:23.527209 | controller | changed: 2025-10-01 14:20:23.527282 | controller | { 2025-10-01 14:20:23.527309 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-01 14:20:23.527329 | controller | } 2025-10-01 14:20:23.716693 | controller | changed: 2025-10-01 14:20:23.716747 | controller | { 2025-10-01 14:20:23.716773 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-01 14:20:23.716792 | controller | } 2025-10-01 14:20:23.908996 | controller | changed: 2025-10-01 14:20:23.909041 | controller | { 2025-10-01 14:20:23.909065 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-01 14:20:23.909083 | controller | } 2025-10-01 14:20:24.106173 | controller | changed: 2025-10-01 14:20:24.106217 | controller | { 2025-10-01 14:20:24.106241 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-01 14:20:24.106259 | controller | } 2025-10-01 14:20:24.315370 | controller | changed: 2025-10-01 14:20:24.315415 | controller | { 2025-10-01 14:20:24.315438 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-01 14:20:24.315455 | controller | } 2025-10-01 14:20:24.511149 | controller | changed: 2025-10-01 14:20:24.511194 | controller | { 2025-10-01 14:20:24.511218 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com" 2025-10-01 14:20:24.511237 | controller | } 2025-10-01 14:20:24.711166 | controller | changed: 2025-10-01 14:20:24.711214 | controller | { 2025-10-01 14:20:24.711241 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb" 2025-10-01 14:20:24.711268 | controller | } 2025-10-01 14:20:24.906305 | controller | changed: 2025-10-01 14:20:24.906355 | controller | { 2025-10-01 14:20:24.906380 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-01 14:20:24.906400 | controller | } 2025-10-01 14:20:25.113204 | controller | changed: 2025-10-01 14:20:25.113254 | controller | { 2025-10-01 14:20:25.113281 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-01 14:20:25.113302 | controller | } 2025-10-01 14:20:25.331095 | controller | changed: 2025-10-01 14:20:25.331145 | controller | { 2025-10-01 14:20:25.331170 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-01 14:20:25.331190 | controller | } 2025-10-01 14:20:25.526858 | controller | changed: 2025-10-01 14:20:25.526904 | controller | { 2025-10-01 14:20:25.526948 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-01 14:20:25.526969 | controller | } 2025-10-01 14:20:25.727378 | controller | changed: 2025-10-01 14:20:25.727431 | controller | { 2025-10-01 14:20:25.727457 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com" 2025-10-01 14:20:25.727477 | controller | } 2025-10-01 14:20:25.924797 | controller | changed: 2025-10-01 14:20:25.924852 | controller | { 2025-10-01 14:20:25.924875 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-01 14:20:25.924892 | controller | } 2025-10-01 14:20:26.128777 | controller | changed: 2025-10-01 14:20:26.128831 | controller | { 2025-10-01 14:20:26.128855 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-01 14:20:26.128872 | controller | } 2025-10-01 14:20:26.323303 | controller | changed: 2025-10-01 14:20:26.323348 | controller | { 2025-10-01 14:20:26.323371 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-01 14:20:26.323388 | controller | } 2025-10-01 14:20:26.342208 | 2025-10-01 14:20:26.342334 | TASK [Set timezone to UTC] 2025-10-01 14:20:26.725484 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-01 14:20:26.729678 | 2025-10-01 14:20:26.729740 | TASK [Create nodepool directory] 2025-10-01 14:20:26.931067 | controller | changed 2025-10-01 14:20:26.935857 | 2025-10-01 14:20:26.935937 | TASK [Create nodepool sub_nodes file] 2025-10-01 14:20:27.450677 | controller | changed 2025-10-01 14:20:27.455429 | 2025-10-01 14:20:27.455496 | TASK [Create nodepool sub_nodes_private file] 2025-10-01 14:20:27.971140 | controller | changed 2025-10-01 14:20:27.975489 | 2025-10-01 14:20:27.975548 | LOOP [Populate nodepool sub_nodes file] 2025-10-01 14:20:28.004589 | 2025-10-01 14:20:28.004706 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-01 14:20:28.032752 | 2025-10-01 14:20:28.032875 | TASK [Create nodepool primary file] 2025-10-01 14:20:28.056094 | controller | skipping: Conditional result was False 2025-10-01 14:20:28.061127 | 2025-10-01 14:20:28.061193 | TASK [Create nodepool node_private for this node] 2025-10-01 14:20:28.605763 | controller | changed 2025-10-01 14:20:28.611287 | 2025-10-01 14:20:28.611350 | LOOP [Copy ssh keys to nodepool directory] 2025-10-01 14:20:28.947028 | controller | ok: Item: id_rsa Runtime: 0:00:00.005728 2025-10-01 14:20:28.947386 | 2025-10-01 14:20:29.119732 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004508 2025-10-01 14:20:29.130007 | 2025-10-01 14:20:29.130126 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-01 14:20:29.687341 | controller | changed 2025-10-01 14:20:29.692280 | 2025-10-01 14:20:29.692343 | TASK [Validate sudoers config after edits] 2025-10-01 14:20:29.919542 | controller | /etc/sudoers: parsed OK 2025-10-01 14:20:29.919598 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-01 14:20:29.919607 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-01 14:20:29.919613 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-01 14:20:30.218225 | controller | ok: Runtime: 0:00:00.009286 2025-10-01 14:20:30.224255 | 2025-10-01 14:20:30.224323 | TASK [Show the environment passed in to job shell scripts] 2025-10-01 14:20:30.429506 | controller | SHELL=/bin/bash 2025-10-01 14:20:30.429558 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-01 14:20:30.429568 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-01 14:20:30.429575 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/03/3303/fa231e3f923dd2d1b6b7bceae37a9bc16d1acfdd 2025-10-01 14:20:30.429581 | controller | PWD=/home/zuul 2025-10-01 14:20:30.429586 | controller | ZUUL_PIPELINE=github-check 2025-10-01 14:20:30.429654 | controller | LOGNAME=zuul 2025-10-01 14:20:30.429661 | controller | XDG_SESSION_TYPE=tty 2025-10-01 14:20:30.429667 | controller | _=/usr/bin/env 2025-10-01 14:20:30.429671 | controller | MOTD_SHOWN=pam 2025-10-01 14:20:30.429676 | controller | HOME=/home/zuul 2025-10-01 14:20:30.429681 | controller | LANG=en_US.UTF-8 2025-10-01 14:20:30.429686 | controller | SSH_CONNECTION=192.168.26.12 49350 192.168.26.92 22 2025-10-01 14:20:30.429692 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET 2025-10-01 14:20:30.429701 | controller | ZUUL_CHANGE_IDS=3303,fa231e3f923dd2d1b6b7bceae37a9bc16d1acfdd 2025-10-01 14:20:30.429706 | controller | WORKSPACE=/home/zuul/workspace 2025-10-01 14:20:30.429712 | controller | XDG_SESSION_CLASS=user 2025-10-01 14:20:30.429717 | controller | SELINUX_ROLE_REQUESTED= 2025-10-01 14:20:30.429722 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-01 14:20:30.429727 | controller | USER=zuul 2025-10-01 14:20:30.429732 | controller | ZUUL_VOTING=True 2025-10-01 14:20:30.429737 | controller | BUILD_TIMEOUT=5400000 2025-10-01 14:20:30.429742 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-01 14:20:30.429746 | controller | SHLVL=1 2025-10-01 14:20:30.429751 | controller | ZUUL_PATCHSET=fa231e3f923dd2d1b6b7bceae37a9bc16d1acfdd 2025-10-01 14:20:30.429756 | controller | XDG_SESSION_ID=1 2025-10-01 14:20:30.429761 | controller | ZUUL_BRANCH=main 2025-10-01 14:20:30.429766 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-01 14:20:30.429770 | controller | SSH_CLIENT=192.168.26.12 49350 22 2025-10-01 14:20:30.429777 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-01 14:20:30.429782 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-01 14:20:30.429788 | controller | which_declare=declare -f 2025-10-01 14:20:30.429794 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-01 14:20:30.429799 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-01 14:20:30.429804 | controller | ZUUL_CHANGE=3303 2025-10-01 14:20:30.429809 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-01 14:20:30.429814 | controller | ZUUL_UUID=494325381e6f4e22a6b5a94626f5d769 2025-10-01 14:20:30.429820 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-01 14:20:30.429825 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-01 14:20:30.429830 | controller | } 2025-10-01 14:20:30.750979 | controller | ok: Runtime: 0:00:00.006867 2025-10-01 14:20:30.755991 | 2025-10-01 14:20:30.756054 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-01 14:20:30.769351 | controller | skipping: Conditional result was False 2025-10-01 14:20:30.774564 | 2025-10-01 14:20:30.774624 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-01 14:20:31.297963 | controller | skipping: Conditional result was False 2025-10-01 14:20:31.303939 | 2025-10-01 14:20:31.304010 | TASK [Ensure legacy workspace directory] 2025-10-01 14:20:31.495110 | controller | changed 2025-10-01 14:20:31.511859 | 2025-10-01 14:20:31.511932 | PLAY RECAP 2025-10-01 14:20:31.511982 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-01 14:20:31.512006 | 2025-10-01 14:20:31.593686 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-01 14:20:31.594646 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master] 2025-10-01 14:20:32.055438 | 2025-10-01 14:20:32.055533 | PLAY [all,!compute] 2025-10-01 14:20:32.073736 | 2025-10-01 14:20:32.073811 | TASK [Enable container-tools rhel-modules for c8/crc node] 2025-10-01 14:20:32.599033 | controller | skipping: Conditional result was False 2025-10-01 14:20:32.604463 | 2025-10-01 14:20:32.604533 | TASK [Install podman] 2025-10-01 14:21:31.701975 | controller | changed 2025-10-01 14:21:31.706504 | 2025-10-01 14:21:31.706561 | TASK [Set var name for quay login zuul secret] 2025-10-01 14:21:31.749540 | controller | skipping: Conditional result was False 2025-10-01 14:21:31.754201 | 2025-10-01 14:21:31.754259 | TASK [Print the username] 2025-10-01 14:21:32.039791 | controller | openstack-k8s-operators+cirobot 2025-10-01 14:21:32.277394 | controller | ok: Runtime: 0:00:00.003510 2025-10-01 14:21:32.282552 | 2025-10-01 14:21:32.282614 | TASK [Fail when user or password is not set] 2025-10-01 14:21:32.326038 | controller | skipping: Conditional result was False 2025-10-01 14:21:32.330863 | 2025-10-01 14:21:32.330943 | TASK [Set vars for quay login] 2025-10-01 14:21:32.379177 | controller | Output suppressed because no_log was given 2025-10-01 14:21:32.383077 | 2025-10-01 14:21:32.383133 | LOOP [Perform container registry login(s) with podman] 2025-10-01 14:21:33.412270 | controller | Output suppressed because no_log was given 2025-10-01 14:21:33.416386 | 2025-10-01 14:21:33.416449 | LOOP [Perform container registry login(s) with buildah] 2025-10-01 14:21:34.215055 | controller | Output suppressed because no_log was given 2025-10-01 14:21:34.219063 | 2025-10-01 14:21:34.219117 | TASK [Set Insecure registry for content provider] 2025-10-01 14:21:34.262705 | controller | skipping: Conditional result was False 2025-10-01 14:21:34.279584 | 2025-10-01 14:21:34.279640 | PLAY RECAP 2025-10-01 14:21:34.279695 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-10-01 14:21:34.279718 | 2025-10-01 14:21:34.341072 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master] 2025-10-01 14:21:34.342033 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-01 14:21:34.839441 | 2025-10-01 14:21:34.839537 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-10-01 14:21:34.857825 | 2025-10-01 14:21:34.857890 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-10-01 14:21:34.876016 | controller | ok 2025-10-01 14:21:34.891088 | 2025-10-01 14:21:34.891160 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-01 14:21:34.914734 | controller | skipping: Conditional result was False 2025-10-01 14:21:34.919969 | 2025-10-01 14:21:34.920034 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-01 14:21:35.211644 | controller | ok 2025-10-01 14:21:35.216479 | 2025-10-01 14:21:35.216544 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-01 14:21:35.805372 | controller | ok 2025-10-01 14:21:35.814944 | 2025-10-01 14:21:35.815002 | TASK [Prepare workspace] 2025-10-01 14:21:35.833353 | controller | ok 2025-10-01 14:21:35.848255 | 2025-10-01 14:21:35.848317 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-01 14:21:36.139498 | controller | ok 2025-10-01 14:21:36.145492 | 2025-10-01 14:21:36.145549 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-01 14:21:37.839723 | controller | Output suppressed because no_log was given 2025-10-01 14:21:37.848335 | 2025-10-01 14:21:37.848394 | LOOP [Create zuul-output directory] 2025-10-01 14:21:38.022349 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-10-01 14:21:38.169641 | controller | ok: "/home/zuul/zuul-output/logs" 2025-10-01 14:21:38.181456 | 2025-10-01 14:21:38.181532 | TASK [Install required packages] 2025-10-01 14:21:38.962773 | controller | ok: Nothing to do 2025-10-01 14:21:38.967120 | 2025-10-01 14:21:38.967174 | TASK [Install venv] 2025-10-01 14:22:37.225620 | controller | changed 2025-10-01 14:22:37.248466 | 2025-10-01 14:22:37.248575 | PLAY RECAP 2025-10-01 14:22:37.248621 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-01 14:22:37.248645 | 2025-10-01 14:22:37.318974 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-01 14:22:37.319796 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-01 14:22:37.782132 | 2025-10-01 14:22:37.782227 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-01 14:22:37.801090 | 2025-10-01 14:22:37.801157 | TASK [Create zuul-output directory] 2025-10-01 14:22:38.082715 | controller | changed 2025-10-01 14:22:38.087297 | 2025-10-01 14:22:38.087367 | TASK [Slurp Zuul inventory test] 2025-10-01 14:22:38.333705 | controller -> localhost | ok 2025-10-01 14:22:38.339820 | 2025-10-01 14:22:38.339886 | TASK [Save zuul inventory] 2025-10-01 14:22:38.995993 | controller | changed 2025-10-01 14:22:39.000476 | 2025-10-01 14:22:39.000552 | TASK [Save zuul vars without the change_message] 2025-10-01 14:22:39.515728 | controller | changed 2025-10-01 14:22:39.532231 | 2025-10-01 14:22:39.532281 | PLAY RECAP 2025-10-01 14:22:39.532322 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-01 14:22:39.532343 | 2025-10-01 14:22:39.593088 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-01 14:22:39.594080 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/build_push_container_runner.yml@main] 2025-10-01 14:22:40.061494 | 2025-10-01 14:22:40.061574 | PLAY [Run build_push_container role] 2025-10-01 14:22:40.080211 | 2025-10-01 14:22:40.080277 | TASK [Run build_push_container role] 2025-10-01 14:22:40.751682 | controller | 2025-10-01 14:22:41.505576 | controller | PLAY [Run build_push_container role] ******************************************* 2025-10-01 14:22:41.505639 | controller | 2025-10-01 14:22:41.505648 | controller | TASK [Gathering Facts ] ******************************************************** 2025-10-01 14:22:41.505655 | controller | Wednesday 01 October 2025 14:22:40 +0000 (0:00:00.041) 0:00:00.041 ***** 2025-10-01 14:22:41.505666 | controller | ok: [localhost] 2025-10-01 14:22:41.539185 | controller | 2025-10-01 14:22:41.539208 | controller | TASK [Run build_push_container role name=build_push_container] ***************** 2025-10-01 14:22:41.539217 | controller | Wednesday 01 October 2025 14:22:41 +0000 (0:00:00.754) 0:00:00.795 ***** 2025-10-01 14:22:41.539228 | controller | 2025-10-01 14:22:41.907000 | controller | TASK [build_push_container : Ensure directories are present path={{ item }}, state=directory, mode=0755, recurse=True] *** 2025-10-01 14:22:41.907039 | controller | Wednesday 01 October 2025 14:22:41 +0000 (0:00:00.033) 0:00:00.829 ***** 2025-10-01 14:22:41.907055 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/scripts) 2025-10-01 14:22:42.609040 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/logs) 2025-10-01 14:22:42.609063 | controller | 2025-10-01 14:22:42.609071 | controller | TASK [podman : Ensure podman is installed name={{ cifmw_podman_packages }}, state=present] *** 2025-10-01 14:22:42.609076 | controller | Wednesday 01 October 2025 14:22:41 +0000 (0:00:00.367) 0:00:01.197 ***** 2025-10-01 14:22:42.609086 | controller | ok: [localhost] 2025-10-01 14:22:42.873534 | controller | 2025-10-01 14:22:42.873566 | controller | TASK [podman : Enable loginctl linger for ansible_user_id _raw_params=loginctl enable-linger {{ cifmw_podman_user_linger }}] *** 2025-10-01 14:22:42.873574 | controller | Wednesday 01 October 2025 14:22:42 +0000 (0:00:00.701) 0:00:01.898 ***** 2025-10-01 14:22:42.873586 | controller | changed: [localhost] 2025-10-01 14:22:42.895486 | controller | 2025-10-01 14:22:42.895505 | 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-10-01 14:22:42.895513 | controller | Wednesday 01 October 2025 14:22:42 +0000 (0:00:00.264) 0:00:02.163 ***** 2025-10-01 14:22:42.895522 | controller | skipping: [localhost] 2025-10-01 14:22:42.915150 | controller | 2025-10-01 14:22:42.915168 | 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-10-01 14:22:42.915177 | controller | Wednesday 01 October 2025 14:22:42 +0000 (0:00:00.022) 0:00:02.185 ***** 2025-10-01 14:22:42.915188 | controller | skipping: [localhost] 2025-10-01 14:22:42.939442 | controller | 2025-10-01 14:22:42.939489 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed _raw_params=podman system migrate] *** 2025-10-01 14:22:42.939504 | controller | Wednesday 01 October 2025 14:22:42 +0000 (0:00:00.019) 0:00:02.205 ***** 2025-10-01 14:22:42.939517 | controller | skipping: [localhost] 2025-10-01 14:22:51.061001 | controller | 2025-10-01 14:22:51.061106 | 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-10-01 14:22:51.061120 | controller | Wednesday 01 October 2025 14:22:42 +0000 (0:00:00.024) 0:00:02.229 ***** 2025-10-01 14:22:51.061132 | 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-10-01 14:22:51.260565 | controller | changed: [localhost] 2025-10-01 14:22:51.260593 | controller | 2025-10-01 14:22:51.260602 | controller | TASK [build_push_container : Ensure manifest doesn't exist _raw_params=podman manifest rm {{ _container_manifest_name }}] *** 2025-10-01 14:22:51.260608 | controller | Wednesday 01 October 2025 14:22:51 +0000 (0:00:08.121) 0:00:10.350 ***** 2025-10-01 14:22:51.260627 | controller | ok: [localhost] 2025-10-01 14:22:51.472758 | controller | 2025-10-01 14:22:51.472797 | controller | TASK [build_push_container : Create manifest _raw_params=podman manifest create {{ _container_manifest_name }}] *** 2025-10-01 14:22:51.472813 | controller | Wednesday 01 October 2025 14:22:51 +0000 (0:00:00.199) 0:00:10.550 ***** 2025-10-01 14:22:51.472827 | controller | changed: [localhost] 2025-10-01 14:35:45.628500 | controller | 2025-10-01 14:35:45.628576 | controller | TASK [build_push_container : Build container output_dir={{ (cifmw_build_push_container_artifacts, 'scripts') | ansible.builtin.path_join }}, script={{ _podman_build_cmd }}] *** 2025-10-01 14:35:45.628590 | controller | Wednesday 01 October 2025 14:22:51 +0000 (0:00:00.212) 0:00:10.762 ***** 2025-10-01 14:35:45.628604 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_001_build.log 2025-10-01 14:36:39.683443 | controller | changed: [localhost] 2025-10-01 14:36:39.683487 | controller | 2025-10-01 14:36:39.683496 | 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-10-01 14:36:39.683506 | controller | Wednesday 01 October 2025 14:35:45 +0000 (0:12:54.155) 0:13:04.918 ***** 2025-10-01 14:36:39.683519 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_002_push.log 2025-10-01 14:36:39.734371 | controller | changed: [localhost] => (item=fa231e3f923dd2d1b6b7bceae37a9bc16d1acfdd) 2025-10-01 14:36:39.734414 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_003_push.log 2025-10-01 14:36:39.734422 | controller | changed: [localhost] => (item=3303-latest) 2025-10-01 14:36:39.734428 | controller | 2025-10-01 14:36:39.734434 | 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-10-01 14:36:39.734440 | controller | Wednesday 01 October 2025 14:36:39 +0000 (0:00:54.054) 0:13:58.973 ***** 2025-10-01 14:36:39.734450 | controller | skipping: [localhost] => (item=fa231e3f923dd2d1b6b7bceae37a9bc16d1acfdd) 2025-10-01 14:36:39.786782 | controller | skipping: [localhost] => (item=3303-latest) 2025-10-01 14:36:39.786819 | controller | skipping: [localhost] 2025-10-01 14:36:39.786830 | controller | 2025-10-01 14:36:39.786838 | controller | PLAY RECAP ********************************************************************* 2025-10-01 14:36:39.786845 | controller | localhost : ok=9 changed=6 unreachable=0 failed=0 skipped=4 rescued=0 ignored=0 2025-10-01 14:36:39.786853 | controller | 2025-10-01 14:36:39.786860 | controller | Wednesday 01 October 2025 14:36:39 +0000 (0:00:00.051) 0:13:59.024 ***** 2025-10-01 14:36:39.786868 | controller | =============================================================================== 2025-10-01 14:36:39.786877 | controller | build_push_container : Build container -------------------------------- 774.16s 2025-10-01 14:36:39.786884 | controller | build_push_container : Push manifest ----------------------------------- 54.05s 2025-10-01 14:36:39.786891 | controller | build_push_container : Install qemu-user-static for multi arch builds --- 8.12s 2025-10-01 14:36:39.786898 | controller | Gathering Facts --------------------------------------------------------- 0.75s 2025-10-01 14:36:39.786906 | controller | podman : Ensure podman is installed ------------------------------------- 0.70s 2025-10-01 14:36:39.786913 | controller | build_push_container : Ensure directories are present ------------------- 0.37s 2025-10-01 14:36:39.786921 | controller | podman : Enable loginctl linger for ansible_user_id --------------------- 0.26s 2025-10-01 14:36:39.786928 | controller | build_push_container : Create manifest ---------------------------------- 0.21s 2025-10-01 14:36:39.786935 | controller | build_push_container : Ensure manifest doesn't exist -------------------- 0.20s 2025-10-01 14:36:39.787049 | controller | build_push_container : Push container ----------------------------------- 0.05s 2025-10-01 14:36:39.787065 | controller | Run build_push_container role ------------------------------------------- 0.03s 2025-10-01 14:36:39.787073 | controller | podman : Run podman system migrate if subuid/subgid files were changed --- 0.02s 2025-10-01 14:36:39.787081 | controller | podman : Ensure subordinate UID entry exists for zuul ------------------- 0.02s 2025-10-01 14:36:39.787096 | controller | podman : Ensure subordinate GID entry exists for zuul ------------------- 0.02s 2025-10-01 14:36:39.980675 | controller | changed 2025-10-01 14:36:39.998138 | 2025-10-01 14:36:39.998193 | PLAY RECAP 2025-10-01 14:36:39.998235 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-01 14:36:39.998257 | 2025-10-01 14:36:40.055254 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/build_push_container_runner.yml@main] 2025-10-01 14:36:40.056605 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-01 14:36:40.603577 | 2025-10-01 14:36:40.603810 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-01 14:36:40.626568 | 2025-10-01 14:36:40.626803 | TASK [Filter out host if needed] 2025-10-01 14:36:40.636485 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-01 14:36:40.640984 | 2025-10-01 14:36:40.641052 | TASK [Ensure file is present] 2025-10-01 14:36:40.962448 | controller | ok 2025-10-01 14:36:40.967834 | 2025-10-01 14:36:40.967928 | TASK [Manage molecule report file] 2025-10-01 14:36:41.490049 | controller | skipping: Conditional result was False 2025-10-01 14:36:41.501240 | 2025-10-01 14:36:41.501341 | TASK [Check if we get ci-framework-data basedir] 2025-10-01 14:36:41.700365 | controller | ok 2025-10-01 14:36:41.705264 | 2025-10-01 14:36:41.705331 | TASK [Create ci-framework-data log directory for zuul] 2025-10-01 14:36:42.001455 | controller | changed 2025-10-01 14:36:42.006541 | 2025-10-01 14:36:42.006643 | TASK [Copy ci-framework interesting files] 2025-10-01 14:36:42.310816 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-10-01 14:36:42.535594 | controller | changed 2025-10-01 14:36:42.548666 | 2025-10-01 14:36:42.548810 | TASK [Get SELinux listing] 2025-10-01 14:36:43.071316 | controller | changed 2025-10-01 14:36:43.076958 | 2025-10-01 14:36:43.077026 | TASK [Generate log index] 2025-10-01 14:36:43.695897 | controller | changed 2025-10-01 14:36:43.700762 | 2025-10-01 14:36:43.700829 | TASK [Get some env related data] 2025-10-01 14:36:44.241122 | controller | /home/zuul/.local/bin/ansible 2025-10-01 14:36:45.229084 | controller | changed 2025-10-01 14:36:45.233486 | 2025-10-01 14:36:45.233545 | TASK [Generate list of logs to collect in home directory] 2025-10-01 14:36:45.536049 | controller | ok: All paths examined 2025-10-01 14:36:45.541107 | 2025-10-01 14:36:45.541169 | LOOP [Copy logs from home directory] 2025-10-01 14:36:45.855294 | controller | changed: 2025-10-01 14:36:45.855518 | controller | { 2025-10-01 14:36:45.855547 | controller | "atime": 1759328541.2211304, 2025-10-01 14:36:45.855568 | controller | "ctime": 1759329399.7338717, 2025-10-01 14:36:45.855587 | controller | "dev": 64513, 2025-10-01 14:36:45.855605 | controller | "gid": 1000, 2025-10-01 14:36:45.855620 | controller | "gr_name": "zuul", 2025-10-01 14:36:45.855637 | controller | "inode": 4339743, 2025-10-01 14:36:45.855653 | controller | "isblk": false, 2025-10-01 14:36:45.855668 | controller | "ischr": false, 2025-10-01 14:36:45.855682 | controller | "isdir": false, 2025-10-01 14:36:45.855697 | controller | "isfifo": false, 2025-10-01 14:36:45.855712 | controller | "isgid": false, 2025-10-01 14:36:45.855727 | controller | "islnk": false, 2025-10-01 14:36:45.855741 | controller | "isreg": true, 2025-10-01 14:36:45.855758 | controller | "issock": false, 2025-10-01 14:36:45.855772 | controller | "isuid": false, 2025-10-01 14:36:45.855787 | controller | "mode": "0644", 2025-10-01 14:36:45.855804 | controller | "mtime": 1759329399.7338717, 2025-10-01 14:36:45.855826 | controller | "nlink": 1, 2025-10-01 14:36:45.855843 | controller | "path": "/home/zuul/ansible.log", 2025-10-01 14:36:45.855859 | controller | "pw_name": "zuul", 2025-10-01 14:36:45.855890 | controller | "rgrp": true, 2025-10-01 14:36:45.855931 | controller | "roth": true, 2025-10-01 14:36:45.855960 | controller | "rusr": true, 2025-10-01 14:36:45.855979 | controller | "size": 16093, 2025-10-01 14:36:45.855996 | controller | "uid": 1000, 2025-10-01 14:36:45.856011 | controller | "wgrp": false, 2025-10-01 14:36:45.856025 | controller | "woth": false, 2025-10-01 14:36:45.856042 | controller | "wusr": true, 2025-10-01 14:36:45.856057 | controller | "xgrp": false, 2025-10-01 14:36:45.856073 | controller | "xoth": false, 2025-10-01 14:36:45.856088 | controller | "xusr": false 2025-10-01 14:36:45.856103 | controller | } 2025-10-01 14:36:45.882947 | 2025-10-01 14:36:45.883029 | TASK [Copy crio stats log file] 2025-10-01 14:36:45.896650 | controller | skipping: Conditional result was False 2025-10-01 14:36:45.902131 | 2025-10-01 14:36:45.902194 | TASK [Get SELinux related data] 2025-10-01 14:36:46.119866 | controller | 2025-10-01 14:36:46.429387 | controller | ERROR 2025-10-01 14:36:46.429544 | controller | { 2025-10-01 14:36:46.429574 | controller | "delta": "0:00:00.011980", 2025-10-01 14:36:46.429593 | controller | "end": "2025-10-01 14:36:46.120895", 2025-10-01 14:36:46.429609 | controller | "msg": "non-zero return code", 2025-10-01 14:36:46.429625 | controller | "rc": 1, 2025-10-01 14:36:46.429640 | controller | "start": "2025-10-01 14:36:46.108915" 2025-10-01 14:36:46.429655 | controller | } 2025-10-01 14:36:46.429675 | controller | ERROR: Ignoring Errors 2025-10-01 14:36:46.434162 | 2025-10-01 14:36:46.434220 | TASK [Create system configuration directory] 2025-10-01 14:36:46.615314 | controller | changed 2025-10-01 14:36:46.620198 | 2025-10-01 14:36:46.620262 | TASK [Get some of the system configurations] 2025-10-01 14:36:47.144353 | controller | changed 2025-10-01 14:36:47.149073 | 2025-10-01 14:36:47.149138 | TASK [Copy generated documentation if available] 2025-10-01 14:36:47.162076 | controller | skipping: Conditional result was False 2025-10-01 14:36:47.167166 | 2025-10-01 14:36:47.167227 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-01 14:36:47.179965 | controller | skipping: Conditional result was False 2025-10-01 14:36:47.184935 | 2025-10-01 14:36:47.184994 | TASK [Compress logs bigger than 2MB] 2025-10-01 14:36:47.708933 | controller | changed 2025-10-01 14:36:47.713890 | 2025-10-01 14:36:47.713967 | TASK [Copy files from workspace on node] 2025-10-01 14:36:47.731381 | controller | ok 2025-10-01 14:36:47.752364 | 2025-10-01 14:36:47.752427 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-01 14:36:47.765267 | controller | skipping: Conditional result was False 2025-10-01 14:36:47.770550 | 2025-10-01 14:36:47.770616 | TASK [fetch-output : Set log path for single node] 2025-10-01 14:36:47.797798 | controller | ok 2025-10-01 14:36:47.802612 | 2025-10-01 14:36:47.802674 | LOOP [fetch-output : Ensure local output dirs] 2025-10-01 14:36:47.992208 | controller -> localhost | ok: "/var/lib/zuul/builds/494325381e6f4e22a6b5a94626f5d769/work/logs" 2025-10-01 14:36:47.992475 | controller -> localhost | changed: All items complete 2025-10-01 14:36:47.992504 | 2025-10-01 14:36:48.161661 | controller -> localhost | changed: "/var/lib/zuul/builds/494325381e6f4e22a6b5a94626f5d769/work/artifacts" 2025-10-01 14:36:48.329790 | controller -> localhost | changed: "/var/lib/zuul/builds/494325381e6f4e22a6b5a94626f5d769/work/docs" 2025-10-01 14:36:48.339988 | 2025-10-01 14:36:48.340088 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-01 14:36:48.850496 | controller | changed: 2025-10-01 14:36:48.850678 | controller | .d..t...... ./ 2025-10-01 14:36:48.850709 | controller | >f+++++++++ README.html 2025-10-01 14:36:48.850730 | controller | >f+++++++++ ansible.log 2025-10-01 14:36:48.850760 | controller | >f+++++++++ dmesg.log 2025-10-01 14:36:48.850782 | controller | >f+++++++++ installed-pkgs.log 2025-10-01 14:36:48.850801 | controller | >f+++++++++ python.log 2025-10-01 14:36:48.850820 | controller | >f+++++++++ registries.conf 2025-10-01 14:36:48.850839 | controller | >f+++++++++ selinux-denials.log 2025-10-01 14:36:48.850857 | controller | >f+++++++++ selinux-listing.log 2025-10-01 14:36:48.850888 | controller | cd+++++++++ ci-framework-data/ 2025-10-01 14:36:48.850942 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-01 14:36:48.850972 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-01 14:36:48.850993 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/ 2025-10-01 14:36:48.851012 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/logs/ 2025-10-01 14:36:48.851029 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_000_install_qemu_user_static_for.log 2025-10-01 14:36:48.851047 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_001_build.log 2025-10-01 14:36:48.851063 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_002_push.log 2025-10-01 14:36:48.851078 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_003_push.log 2025-10-01 14:36:48.851093 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ 2025-10-01 14:36:48.851108 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_000_install_qemu_user_static_for.sh 2025-10-01 14:36:48.851123 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_001_build.sh 2025-10-01 14:36:48.851138 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_002_push.sh 2025-10-01 14:36:48.851154 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_003_push.sh 2025-10-01 14:36:48.851170 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-01 14:36:48.851187 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-10-01 14:36:48.851203 | controller | cd+++++++++ ci-framework-data/logs/ 2025-10-01 14:36:48.851218 | controller | cd+++++++++ registries.conf.d/ 2025-10-01 14:36:48.851234 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-10-01 14:36:48.851248 | controller | cd+++++++++ system-config/ 2025-10-01 14:36:48.851264 | controller | cd+++++++++ system-config/libvirt/ 2025-10-01 14:36:48.851278 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-10-01 14:36:48.851293 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-10-01 14:36:49.202928 | controller | changed: .d..t...... ./ 2025-10-01 14:36:49.555439 | controller | changed: .d..t...... ./ 2025-10-01 14:36:49.569202 | 2025-10-01 14:36:49.569281 | TASK [Return artifact to Zuul] 2025-10-01 14:36:49.593145 | controller | skipping: Conditional result was False 2025-10-01 14:36:49.610809 | 2025-10-01 14:36:49.610860 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-01 14:36:49.610978 | 2025-10-01 14:36:49.611009 | PLAY RECAP 2025-10-01 14:36:49.611044 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1 2025-10-01 14:36:49.611064 | 2025-10-01 14:36:49.684641 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-01 14:36:49.685588 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-01 14:36:50.156307 | 2025-10-01 14:36:50.156404 | PLAY [all] 2025-10-01 14:36:50.173492 | 2025-10-01 14:36:50.173558 | TASK [include_role : fetch-output] 2025-10-01 14:36:50.202102 | controller | ok 2025-10-01 14:36:50.216509 | 2025-10-01 14:36:50.216584 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-01 14:36:50.250233 | controller | skipping: Conditional result was False 2025-10-01 14:36:50.255340 | 2025-10-01 14:36:50.255406 | TASK [fetch-output : Set log path for single node] 2025-10-01 14:36:50.282892 | controller | ok 2025-10-01 14:36:50.287405 | 2025-10-01 14:36:50.287474 | LOOP [fetch-output : Ensure local output dirs] 2025-10-01 14:36:50.578853 | controller -> localhost | ok: "/var/lib/zuul/builds/494325381e6f4e22a6b5a94626f5d769/work/logs" 2025-10-01 14:36:50.750787 | controller -> localhost | ok: "/var/lib/zuul/builds/494325381e6f4e22a6b5a94626f5d769/work/artifacts" 2025-10-01 14:36:50.914679 | controller -> localhost | ok: "/var/lib/zuul/builds/494325381e6f4e22a6b5a94626f5d769/work/docs" 2025-10-01 14:36:50.924385 | 2025-10-01 14:36:50.924454 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-01 14:36:51.429544 | controller | ok 2025-10-01 14:36:51.429726 | controller | ok: All items complete 2025-10-01 14:36:51.429754 | 2025-10-01 14:36:51.789254 | controller | ok 2025-10-01 14:36:52.165278 | controller | ok 2025-10-01 14:36:52.185073 | 2025-10-01 14:36:52.185184 | TASK [include_role : fetch-output-openshift] 2025-10-01 14:36:52.198789 | controller | skipping: Conditional result was False 2025-10-01 14:36:52.204394 | 2025-10-01 14:36:52.204472 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-01 14:36:52.531524 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006068 2025-10-01 14:36:52.718598 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006058 2025-10-01 14:36:52.747960 | 2025-10-01 14:36:52.748034 | PLAY [all] 2025-10-01 14:36:52.761278 | 2025-10-01 14:36:52.761343 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-01 14:36:53.205890 | controller | changed 2025-10-01 14:36:53.226617 | 2025-10-01 14:36:53.226689 | PLAY RECAP 2025-10-01 14:36:53.226731 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-01 14:36:53.226753 | 2025-10-01 14:36:53.293056 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-01 14:36:53.293982 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-01 14:36:53.828827 | 2025-10-01 14:36:53.828958 | PLAY [localhost] 2025-10-01 14:36:53.845732 | 2025-10-01 14:36:53.845808 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-01 14:36:54.135849 | localhost | changed 2025-10-01 14:36:54.139877 | 2025-10-01 14:36:54.139964 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-01 14:36:54.167358 | localhost | ok 2025-10-01 14:36:54.174543 | 2025-10-01 14:36:54.174607 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-01 14:36:54.455939 | localhost | changed 2025-10-01 14:36:54.460159 | 2025-10-01 14:36:54.460225 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-01 14:36:54.952326 | localhost | changed 2025-10-01 14:36:54.956940 | 2025-10-01 14:36:54.957013 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-01 14:36:55.268435 | localhost | Identity added: /var/lib/zuul/builds/494325381e6f4e22a6b5a94626f5d769/work/tmp/ansible.pbdicx55 (/var/lib/zuul/builds/494325381e6f4e22a6b5a94626f5d769/work/tmp/ansible.pbdicx55) 2025-10-01 14:36:55.268616 | localhost | ok: Runtime: 0:00:00.006486 2025-10-01 14:36:55.272892 | 2025-10-01 14:36:55.272978 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-01 14:36:55.472237 | localhost | ok: Runtime: 0:00:00.003947 2025-10-01 14:36:55.476778 | 2025-10-01 14:36:55.476851 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-01 14:36:55.524479 | localhost | changed 2025-10-01 14:36:55.528155 | 2025-10-01 14:36:55.528217 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-01 14:36:55.817835 | localhost | changed 2025-10-01 14:36:55.836672 | 2025-10-01 14:36:55.836723 | PLAY [localhost] 2025-10-01 14:36:55.846949 | 2025-10-01 14:36:55.847006 | TASK [Generate bulk log download script] 2025-10-01 14:36:55.864601 | localhost | ok 2025-10-01 14:36:55.875949 | 2025-10-01 14:36:55.876013 | TASK [local-log-download : Check API endpoint is defined] 2025-10-01 14:36:55.902928 | localhost | ok: All assertions passed 2025-10-01 14:36:55.907236 | 2025-10-01 14:36:55.907300 | TASK [local-log-download : Create download script] 2025-10-01 14:36:56.224422 | localhost -> localhost | changed 2025-10-01 14:36:56.231815 | 2025-10-01 14:36:56.231885 | TASK [Register quick-download link] 2025-10-01 14:36:56.247648 | localhost | ok 2025-10-01 14:36:56.279832 | 2025-10-01 14:36:56.280020 | PLAY [logserver.rdoproject.org] 2025-10-01 14:36:56.289040 | 2025-10-01 14:36:56.289098 | TASK [Set zuul-log-path fact] 2025-10-01 14:36:56.304162 | logserver.rdoproject.org | ok 2025-10-01 14:36:56.312706 | 2025-10-01 14:36:56.312772 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-01 14:36:56.327964 | logserver.rdoproject.org | ok 2025-10-01 14:36:56.333071 | 2025-10-01 14:36:56.333138 | TASK [upload-logs : Create log directories] 2025-10-01 14:36:58.128928 | logserver.rdoproject.org | changed 2025-10-01 14:36:58.132371 | 2025-10-01 14:36:58.132445 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-01 14:36:58.353524 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005797 2025-10-01 14:36:58.359736 | 2025-10-01 14:36:58.359807 | TASK [upload-logs : Upload logs to log server] 2025-10-01 14:36:59.824895 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-01 14:36:59.827756 | 2025-10-01 14:36:59.827821 | LOOP [upload-logs : Compress console log and json output] 2025-10-01 14:36:59.869026 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 14:36:59.876202 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 14:36:59.887440 | 2025-10-01 14:36:59.887557 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-01 14:36:59.924775 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 14:36:59.925057 | 2025-10-01 14:36:59.927487 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 14:36:59.936753 | 2025-10-01 14:36:59.936884 | LOOP [upload-logs : Upload console log and json output]