2025-12-04 16:44:42.576406 | Job console starting... 2025-12-04 16:44:42.787464 | Updating repositories 2025-12-04 16:44:42.802370 | Preparing job workspace 2025-12-04 16:44:49.349590 | Running Ansible setup... 2025-12-04 16:44:52.106136 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-12-04 16:44:52.569627 | 2025-12-04 16:44:52.569717 | PLAY [localhost] 2025-12-04 16:44:52.577433 | 2025-12-04 16:44:52.577500 | TASK [Gathering Facts] 2025-12-04 16:44:53.320865 | localhost | ok 2025-12-04 16:44:53.332731 | 2025-12-04 16:44:53.332807 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-04 16:44:53.619487 | localhost -> localhost | changed 2025-12-04 16:44:53.624327 | 2025-12-04 16:44:53.624394 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-04 16:44:54.244784 | localhost -> localhost | changed 2025-12-04 16:44:54.252321 | 2025-12-04 16:44:54.252399 | TASK [Setup log path fact] 2025-12-04 16:44:54.269313 | localhost | ok 2025-12-04 16:44:54.279217 | 2025-12-04 16:44:54.279283 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-04 16:44:54.305740 | localhost | ok 2025-12-04 16:44:54.312458 | 2025-12-04 16:44:54.312522 | TASK [emit-job-header : Print job information] 2025-12-04 16:44:54.339308 | # Job Information 2025-12-04 16:44:54.339416 | Ansible Version: 2.15.12 2025-12-04 16:44:54.339444 | Job: build-push-container-cifmw-client 2025-12-04 16:44:54.339465 | Pipeline: github-check 2025-12-04 16:44:54.339482 | Executor: ibm-bm4-ze.softwarefactory-project.io 2025-12-04 16:44:54.339499 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3531 2025-12-04 16:44:54.339517 | Log URL (when completed): https://logserver.rdoproject.org/26c/rdoproject.org/26c71ef211154eaba31ba79be0290321/ 2025-12-04 16:44:54.339534 | Event ID: 67b0f0d0-d130-11f0-99ed-d692e1c83ffc 2025-12-04 16:44:54.342928 | 2025-12-04 16:44:54.342998 | LOOP [emit-job-header : Print node information] 2025-12-04 16:44:54.420187 | localhost | ok: 2025-12-04 16:44:54.420371 | localhost | # Node Information 2025-12-04 16:44:54.420398 | localhost | Inventory Hostname: controller 2025-12-04 16:44:54.420419 | localhost | Hostname: np0005545862 2025-12-04 16:44:54.420437 | localhost | Username: zuul 2025-12-04 16:44:54.420454 | localhost | Distro: CentOS 9 2025-12-04 16:44:54.420470 | localhost | Provider: ibm-bm4-nodepool 2025-12-04 16:44:54.420485 | localhost | Region: regionOne 2025-12-04 16:44:54.420499 | localhost | Label: cloud-centos-9-stream-tripleo 2025-12-04 16:44:54.420514 | localhost | Product Name: OpenStack Compute 2025-12-04 16:44:54.420528 | localhost | Interface IP: 192.168.26.231 2025-12-04 16:44:54.444143 | 2025-12-04 16:44:54.444212 | PLAY [all] 2025-12-04 16:44:54.449789 | 2025-12-04 16:44:54.449846 | TASK [Gather network facts] 2025-12-04 16:44:54.782444 | controller | ok 2025-12-04 16:44:54.794741 | 2025-12-04 16:44:54.794796 | TASK [include_role : start-zuul-console] 2025-12-04 16:44:54.812557 | controller | ok 2025-12-04 16:44:54.822457 | 2025-12-04 16:44:54.822519 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-04 16:44:55.103334 | controller | ok 2025-12-04 16:44:55.110790 | 2025-12-04 16:44:55.110845 | TASK [include_role : add-build-sshkey] 2025-12-04 16:44:55.127982 | controller | ok 2025-12-04 16:44:55.139325 | 2025-12-04 16:44:55.139390 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-04 16:44:55.314536 | controller -> localhost | ok 2025-12-04 16:44:55.319282 | 2025-12-04 16:44:55.319347 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-04 16:44:55.337893 | controller | ok 2025-12-04 16:44:55.348568 | controller | included: /var/lib/zuul/builds/26c71ef211154eaba31ba79be0290321/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-04 16:44:55.353393 | 2025-12-04 16:44:55.353451 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-04 16:44:55.862188 | controller -> localhost | Generating public/private rsa key pair. 2025-12-04 16:44:55.862350 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/26c71ef211154eaba31ba79be0290321/work/26c71ef211154eaba31ba79be0290321_id_rsa. 2025-12-04 16:44:55.862381 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/26c71ef211154eaba31ba79be0290321/work/26c71ef211154eaba31ba79be0290321_id_rsa.pub. 2025-12-04 16:44:55.862403 | controller -> localhost | The key fingerprint is: 2025-12-04 16:44:55.862423 | controller -> localhost | SHA256:uhov8oxyrGW/UDRdFiMCjuBu24epcChDsNDnziiUndA zuul-build-sshkey 2025-12-04 16:44:55.862445 | controller -> localhost | The key's randomart image is: 2025-12-04 16:44:55.862463 | controller -> localhost | +---[RSA 3072]----+ 2025-12-04 16:44:55.862482 | controller -> localhost | |. ... . =. | 2025-12-04 16:44:55.862500 | controller -> localhost | |o+. o + . | 2025-12-04 16:44:55.862519 | controller -> localhost | |+ooE+ . | 2025-12-04 16:44:55.862536 | controller -> localhost | |+.++.. | 2025-12-04 16:44:55.862553 | controller -> localhost | |.* oo S | 2025-12-04 16:44:55.862570 | controller -> localhost | |+.o=o . | 2025-12-04 16:44:55.862587 | controller -> localhost | |*o*++.. | 2025-12-04 16:44:55.862604 | controller -> localhost | |+*==oo . | 2025-12-04 16:44:55.862620 | controller -> localhost | |.=.o*+o | 2025-12-04 16:44:55.862668 | controller -> localhost | +----[SHA256]-----+ 2025-12-04 16:44:55.862717 | controller -> localhost | ok: Runtime: 0:00:00.209541 2025-12-04 16:44:55.867722 | 2025-12-04 16:44:55.867781 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-04 16:44:55.885274 | controller | ok 2025-12-04 16:44:55.893637 | controller | included: /var/lib/zuul/builds/26c71ef211154eaba31ba79be0290321/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-04 16:44:55.900774 | 2025-12-04 16:44:55.900830 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-04 16:44:55.914230 | controller | skipping: Conditional result was False 2025-12-04 16:44:55.918901 | 2025-12-04 16:44:55.918983 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-04 16:44:56.261677 | controller | changed 2025-12-04 16:44:56.265953 | 2025-12-04 16:44:56.266013 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-04 16:44:56.456342 | controller | ok 2025-12-04 16:44:56.460803 | 2025-12-04 16:44:56.460861 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-04 16:44:57.004689 | controller | changed 2025-12-04 16:44:57.009035 | 2025-12-04 16:44:57.009092 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-04 16:44:57.574328 | controller | changed 2025-12-04 16:44:57.578725 | 2025-12-04 16:44:57.578783 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-04 16:44:57.592558 | controller | skipping: Conditional result was False 2025-12-04 16:44:57.598410 | 2025-12-04 16:44:57.598474 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-04 16:44:57.874783 | controller -> localhost | changed 2025-12-04 16:44:57.883704 | 2025-12-04 16:44:57.883765 | TASK [add-build-sshkey : Add back temp key] 2025-12-04 16:44:58.089508 | controller -> localhost | Identity added: /var/lib/zuul/builds/26c71ef211154eaba31ba79be0290321/work/26c71ef211154eaba31ba79be0290321_id_rsa (zuul-build-sshkey) 2025-12-04 16:44:58.089686 | controller -> localhost | ok: Runtime: 0:00:00.006829 2025-12-04 16:44:58.095295 | 2025-12-04 16:44:58.095359 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-04 16:44:58.356838 | controller | ok 2025-12-04 16:44:58.360871 | 2025-12-04 16:44:58.360956 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-04 16:44:58.384438 | controller | skipping: Conditional result was False 2025-12-04 16:44:58.392716 | 2025-12-04 16:44:58.392773 | TASK [include_role : validate-host] 2025-12-04 16:44:58.410114 | controller | ok 2025-12-04 16:44:58.427671 | 2025-12-04 16:44:58.427736 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-04 16:44:58.455386 | controller | ok 2025-12-04 16:44:58.459688 | 2025-12-04 16:44:58.459745 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-04 16:44:58.645295 | controller -> localhost | ok 2025-12-04 16:44:58.650718 | 2025-12-04 16:44:58.650781 | TASK [validate-host : Collect information about the host] 2025-12-04 16:44:59.250133 | controller | ok 2025-12-04 16:44:59.257599 | 2025-12-04 16:44:59.257658 | TASK [validate-host : Sanitize hostname] 2025-12-04 16:44:59.325724 | controller | ok 2025-12-04 16:44:59.330872 | 2025-12-04 16:44:59.330951 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-04 16:44:59.707441 | controller -> localhost | changed 2025-12-04 16:44:59.712021 | 2025-12-04 16:44:59.712082 | TASK [validate-host : Collect information about zuul worker] 2025-12-04 16:45:00.003294 | controller | ok 2025-12-04 16:45:00.007254 | 2025-12-04 16:45:00.007313 | TASK [validate-host : Write out all zuul information for each host] 2025-12-04 16:45:00.336553 | controller -> localhost | changed 2025-12-04 16:45:00.344827 | 2025-12-04 16:45:00.344885 | TASK [include_role : prepare-workspace-openshift] 2025-12-04 16:45:00.358002 | controller | skipping: Conditional result was False 2025-12-04 16:45:00.362745 | 2025-12-04 16:45:00.362805 | TASK [include_role : remove-zuul-sshkey] 2025-12-04 16:45:00.375902 | controller | skipping: Conditional result was False 2025-12-04 16:45:00.380630 | 2025-12-04 16:45:00.380688 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-12-04 16:45:00.559375 | controller | ok: "logs" 2025-12-04 16:45:00.559590 | controller | ok: All items complete 2025-12-04 16:45:00.559617 | 2025-12-04 16:45:00.713995 | controller | ok: "artifacts" 2025-12-04 16:45:00.865960 | controller | ok: "docs" 2025-12-04 16:45:00.878331 | 2025-12-04 16:45:00.878417 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-12-04 16:45:01.062222 | controller | changed: "logs" 2025-12-04 16:45:01.218889 | controller | changed: "artifacts" 2025-12-04 16:45:01.382852 | controller | changed: "docs" 2025-12-04 16:45:01.405964 | 2025-12-04 16:45:01.406013 | PLAY RECAP 2025-12-04 16:45:01.406053 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-12-04 16:45:01.406077 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-04 16:45:01.406095 | 2025-12-04 16:45:01.473021 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-12-04 16:45:01.473851 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-12-04 16:45:01.968989 | 2025-12-04 16:45:01.969087 | PLAY [all] 2025-12-04 16:45:01.987447 | 2025-12-04 16:45:01.987531 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-12-04 16:45:02.036193 | controller | ok 2025-12-04 16:45:02.040825 | 2025-12-04 16:45:02.040921 | TASK [mirror-info-fork : Create /etc/ci] 2025-12-04 16:45:02.373770 | controller | changed 2025-12-04 16:45:02.378616 | 2025-12-04 16:45:02.378683 | TASK [mirror-info-fork : Install ci_mirror script] 2025-12-04 16:45:03.126853 | controller | changed 2025-12-04 16:45:03.136263 | 2025-12-04 16:45:03.136351 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-12-04 16:45:03.462260 | controller | changed: 2025-12-04 16:45:03.462441 | controller | { 2025-12-04 16:45:03.462469 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-12-04 16:45:03.462496 | controller | } 2025-12-04 16:45:03.652578 | controller | changed: 2025-12-04 16:45:03.652632 | controller | { 2025-12-04 16:45:03.652659 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-12-04 16:45:03.652680 | controller | } 2025-12-04 16:45:03.846601 | controller | changed: 2025-12-04 16:45:03.846647 | controller | { 2025-12-04 16:45:03.846671 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com" 2025-12-04 16:45:03.846688 | controller | } 2025-12-04 16:45:04.036994 | controller | changed: 2025-12-04 16:45:04.037040 | controller | { 2025-12-04 16:45:04.037064 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-12-04 16:45:04.037082 | controller | } 2025-12-04 16:45:04.230420 | controller | changed: 2025-12-04 16:45:04.230471 | controller | { 2025-12-04 16:45:04.230496 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-12-04 16:45:04.230515 | controller | } 2025-12-04 16:45:04.420791 | controller | changed: 2025-12-04 16:45:04.420839 | controller | { 2025-12-04 16:45:04.420864 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-12-04 16:45:04.420884 | controller | } 2025-12-04 16:45:04.612137 | controller | changed: 2025-12-04 16:45:04.612184 | controller | { 2025-12-04 16:45:04.612208 | 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-12-04 16:45:04.612227 | controller | } 2025-12-04 16:45:04.807298 | controller | changed: 2025-12-04 16:45:04.807344 | controller | { 2025-12-04 16:45:04.807368 | 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-12-04 16:45:04.807387 | controller | } 2025-12-04 16:45:05.001898 | controller | changed: 2025-12-04 16:45:05.001974 | controller | { 2025-12-04 16:45:05.001999 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-12-04 16:45:05.002018 | controller | } 2025-12-04 16:45:05.210401 | controller | changed: 2025-12-04 16:45:05.210451 | controller | { 2025-12-04 16:45:05.210477 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-12-04 16:45:05.210496 | controller | } 2025-12-04 16:45:05.408657 | controller | changed: 2025-12-04 16:45:05.408703 | controller | { 2025-12-04 16:45:05.408728 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-12-04 16:45:05.408746 | controller | } 2025-12-04 16:45:05.626289 | controller | changed: 2025-12-04 16:45:05.626371 | controller | { 2025-12-04 16:45:05.626397 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-12-04 16:45:05.626416 | controller | } 2025-12-04 16:45:05.805395 | controller | changed: 2025-12-04 16:45:05.805446 | controller | { 2025-12-04 16:45:05.805470 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-12-04 16:45:05.805488 | controller | } 2025-12-04 16:45:05.999947 | controller | changed: 2025-12-04 16:45:06.000044 | controller | { 2025-12-04 16:45:06.000070 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-12-04 16:45:06.000089 | controller | } 2025-12-04 16:45:06.193541 | controller | changed: 2025-12-04 16:45:06.193630 | controller | { 2025-12-04 16:45:06.193653 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-12-04 16:45:06.193670 | controller | } 2025-12-04 16:45:06.386973 | controller | changed: 2025-12-04 16:45:06.387025 | controller | { 2025-12-04 16:45:06.387049 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-12-04 16:45:06.387065 | controller | } 2025-12-04 16:45:06.581577 | controller | changed: 2025-12-04 16:45:06.581648 | controller | { 2025-12-04 16:45:06.581673 | 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-12-04 16:45:06.581692 | controller | } 2025-12-04 16:45:06.776138 | controller | changed: 2025-12-04 16:45:06.776226 | controller | { 2025-12-04 16:45:06.776253 | 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-12-04 16:45:06.776281 | controller | } 2025-12-04 16:45:06.969749 | controller | changed: 2025-12-04 16:45:06.969801 | controller | { 2025-12-04 16:45:06.969827 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-12-04 16:45:06.969845 | controller | } 2025-12-04 16:45:07.160070 | controller | changed: 2025-12-04 16:45:07.160119 | controller | { 2025-12-04 16:45:07.160144 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-12-04 16:45:07.160164 | controller | } 2025-12-04 16:45:07.353850 | controller | changed: 2025-12-04 16:45:07.353952 | controller | { 2025-12-04 16:45:07.353983 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-12-04 16:45:07.354003 | controller | } 2025-12-04 16:45:07.540491 | controller | changed: 2025-12-04 16:45:07.540568 | controller | { 2025-12-04 16:45:07.540592 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-12-04 16:45:07.540610 | controller | } 2025-12-04 16:45:07.737100 | controller | changed: 2025-12-04 16:45:07.737151 | controller | { 2025-12-04 16:45:07.737176 | 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-12-04 16:45:07.737196 | controller | } 2025-12-04 16:45:07.928451 | controller | changed: 2025-12-04 16:45:07.928500 | controller | { 2025-12-04 16:45:07.928525 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-12-04 16:45:07.928544 | controller | } 2025-12-04 16:45:08.118404 | controller | changed: 2025-12-04 16:45:08.118450 | controller | { 2025-12-04 16:45:08.118472 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-12-04 16:45:08.118490 | controller | } 2025-12-04 16:45:08.306349 | controller | changed: 2025-12-04 16:45:08.306425 | controller | { 2025-12-04 16:45:08.306448 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-12-04 16:45:08.306465 | controller | } 2025-12-04 16:45:08.322687 | 2025-12-04 16:45:08.322781 | TASK [Set timezone to UTC] 2025-12-04 16:45:08.675952 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-12-04 16:45:08.680141 | 2025-12-04 16:45:08.680202 | TASK [Create nodepool directory] 2025-12-04 16:45:08.870861 | controller | changed 2025-12-04 16:45:08.875959 | 2025-12-04 16:45:08.876023 | TASK [Create nodepool sub_nodes file] 2025-12-04 16:45:09.329556 | controller | changed 2025-12-04 16:45:09.334047 | 2025-12-04 16:45:09.334111 | TASK [Create nodepool sub_nodes_private file] 2025-12-04 16:45:09.798975 | controller | changed 2025-12-04 16:45:09.804158 | 2025-12-04 16:45:09.804219 | LOOP [Populate nodepool sub_nodes file] 2025-12-04 16:45:09.832124 | 2025-12-04 16:45:09.832238 | LOOP [Populate nodepool sub_nodes_private file] 2025-12-04 16:45:09.860231 | 2025-12-04 16:45:09.860353 | TASK [Create nodepool primary file] 2025-12-04 16:45:09.883033 | controller | skipping: Conditional result was False 2025-12-04 16:45:09.888519 | 2025-12-04 16:45:09.888594 | TASK [Create nodepool node_private for this node] 2025-12-04 16:45:10.383116 | controller | changed 2025-12-04 16:45:10.387930 | 2025-12-04 16:45:10.387987 | LOOP [Copy ssh keys to nodepool directory] 2025-12-04 16:45:10.678597 | controller | ok: Item: id_rsa Runtime: 0:00:00.004627 2025-12-04 16:45:10.678779 | 2025-12-04 16:45:10.832968 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004227 2025-12-04 16:45:10.847281 | 2025-12-04 16:45:10.847386 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-12-04 16:45:11.373469 | controller | changed 2025-12-04 16:45:11.377756 | 2025-12-04 16:45:11.377812 | TASK [Validate sudoers config after edits] 2025-12-04 16:45:11.582301 | controller | /etc/sudoers: parsed OK 2025-12-04 16:45:11.582352 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-12-04 16:45:11.582361 | controller | /etc/sudoers.d/zuul: parsed OK 2025-12-04 16:45:11.582367 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-12-04 16:45:11.902189 | controller | ok: Runtime: 0:00:00.004932 2025-12-04 16:45:11.906761 | 2025-12-04 16:45:11.906815 | TASK [Show the environment passed in to job shell scripts] 2025-12-04 16:45:12.088922 | controller | SHELL=/bin/bash 2025-12-04 16:45:12.088964 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-12-04 16:45:12.088983 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-12-04 16:45:12.088997 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/31/3531/d228bdad382de12daec88e05f28d28773e79d8a0 2025-12-04 16:45:12.089008 | controller | PWD=/home/zuul 2025-12-04 16:45:12.089018 | controller | ZUUL_PIPELINE=github-check 2025-12-04 16:45:12.089068 | controller | LOGNAME=zuul 2025-12-04 16:45:12.089077 | controller | XDG_SESSION_TYPE=tty 2025-12-04 16:45:12.089083 | controller | _=/usr/bin/env 2025-12-04 16:45:12.089088 | controller | MOTD_SHOWN=pam 2025-12-04 16:45:12.089094 | controller | HOME=/home/zuul 2025-12-04 16:45:12.089099 | controller | LANG=en_US.UTF-8 2025-12-04 16:45:12.089104 | controller | SSH_CONNECTION=192.168.26.12 39562 192.168.26.231 22 2025-12-04 16:45:12.089109 | 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-12-04 16:45:12.089118 | controller | ZUUL_CHANGE_IDS=3531,d228bdad382de12daec88e05f28d28773e79d8a0 2025-12-04 16:45:12.089123 | controller | WORKSPACE=/home/zuul/workspace 2025-12-04 16:45:12.089128 | controller | XDG_SESSION_CLASS=user 2025-12-04 16:45:12.089133 | controller | SELINUX_ROLE_REQUESTED= 2025-12-04 16:45:12.089138 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-12-04 16:45:12.089143 | controller | USER=zuul 2025-12-04 16:45:12.089148 | controller | ZUUL_VOTING=True 2025-12-04 16:45:12.089153 | controller | BUILD_TIMEOUT=5400000 2025-12-04 16:45:12.089158 | controller | SELINUX_USE_CURRENT_RANGE= 2025-12-04 16:45:12.089163 | controller | SHLVL=1 2025-12-04 16:45:12.089168 | controller | ZUUL_PATCHSET=d228bdad382de12daec88e05f28d28773e79d8a0 2025-12-04 16:45:12.089173 | controller | XDG_SESSION_ID=1 2025-12-04 16:45:12.089178 | controller | ZUUL_BRANCH=main 2025-12-04 16:45:12.089183 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-12-04 16:45:12.089188 | controller | SSH_CLIENT=192.168.26.12 39562 22 2025-12-04 16:45:12.089194 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-12-04 16:45:12.089199 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-12-04 16:45:12.089204 | controller | which_declare=declare -f 2025-12-04 16:45:12.089210 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-12-04 16:45:12.089215 | controller | SELINUX_LEVEL_REQUESTED= 2025-12-04 16:45:12.089220 | controller | ZUUL_CHANGE=3531 2025-12-04 16:45:12.089225 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-12-04 16:45:12.089230 | controller | ZUUL_UUID=26c71ef211154eaba31ba79be0290321 2025-12-04 16:45:12.089235 | controller | BASH_FUNC_which%%=() { ( alias; 2025-12-04 16:45:12.089240 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-12-04 16:45:12.089245 | controller | } 2025-12-04 16:45:12.431193 | controller | ok: Runtime: 0:00:00.005394 2025-12-04 16:45:12.435822 | 2025-12-04 16:45:12.435879 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-12-04 16:45:12.449887 | controller | skipping: Conditional result was False 2025-12-04 16:45:12.454531 | 2025-12-04 16:45:12.454591 | TASK [Symlink /home/zuul-worker/workspace] 2025-12-04 16:45:12.977613 | controller | skipping: Conditional result was False 2025-12-04 16:45:12.982739 | 2025-12-04 16:45:12.982797 | TASK [Ensure legacy workspace directory] 2025-12-04 16:45:13.153936 | controller | changed 2025-12-04 16:45:13.170111 | 2025-12-04 16:45:13.170160 | PLAY RECAP 2025-12-04 16:45:13.170202 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-12-04 16:45:13.170223 | 2025-12-04 16:45:13.243720 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-12-04 16:45:13.244799 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master] 2025-12-04 16:45:13.712045 | 2025-12-04 16:45:13.712141 | PLAY [all,!compute] 2025-12-04 16:45:13.731302 | 2025-12-04 16:45:13.731385 | TASK [Enable container-tools rhel-modules for c8/crc node] 2025-12-04 16:45:14.258219 | controller | skipping: Conditional result was False 2025-12-04 16:45:14.263699 | 2025-12-04 16:45:14.263774 | TASK [Install podman] 2025-12-04 16:46:03.553932 | controller | changed 2025-12-04 16:46:03.559155 | 2025-12-04 16:46:03.559217 | TASK [Set var name for quay login zuul secret] 2025-12-04 16:46:03.603362 | controller | skipping: Conditional result was False 2025-12-04 16:46:03.608478 | 2025-12-04 16:46:03.608543 | TASK [Print the username] 2025-12-04 16:46:03.912099 | controller | openstack-k8s-operators+cirobot 2025-12-04 16:46:04.133239 | controller | ok: Runtime: 0:00:00.004188 2025-12-04 16:46:04.137873 | 2025-12-04 16:46:04.137944 | TASK [Fail when user or password is not set] 2025-12-04 16:46:04.182002 | controller | skipping: Conditional result was False 2025-12-04 16:46:04.186951 | 2025-12-04 16:46:04.187015 | TASK [Set vars for quay login] 2025-12-04 16:46:04.235148 | controller | Output suppressed because no_log was given 2025-12-04 16:46:04.239070 | 2025-12-04 16:46:04.239126 | LOOP [Perform container registry login(s) with podman] 2025-12-04 16:46:05.321900 | controller | Output suppressed because no_log was given 2025-12-04 16:46:05.331231 | 2025-12-04 16:46:05.331292 | LOOP [Perform container registry login(s) with buildah] 2025-12-04 16:46:06.172584 | controller | Output suppressed because no_log was given 2025-12-04 16:46:06.176846 | 2025-12-04 16:46:06.176920 | TASK [Set Insecure registry for content provider] 2025-12-04 16:46:06.220726 | controller | skipping: Conditional result was False 2025-12-04 16:46:06.238764 | 2025-12-04 16:46:06.238815 | PLAY RECAP 2025-12-04 16:46:06.238874 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-12-04 16:46:06.238899 | 2025-12-04 16:46:06.306564 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master] 2025-12-04 16:46:06.307494 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-12-04 16:46:06.819010 | 2025-12-04 16:46:06.819108 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-12-04 16:46:06.837323 | 2025-12-04 16:46:06.837389 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-12-04 16:46:06.855206 | controller | ok 2025-12-04 16:46:06.871659 | 2025-12-04 16:46:06.871744 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-12-04 16:46:06.895363 | controller | skipping: Conditional result was False 2025-12-04 16:46:06.900660 | 2025-12-04 16:46:06.900734 | TASK [mirror-info-fork : Create /etc/ci] 2025-12-04 16:46:07.192097 | controller | ok 2025-12-04 16:46:07.197377 | 2025-12-04 16:46:07.197446 | TASK [mirror-info-fork : Install ci_mirror script] 2025-12-04 16:46:07.804106 | controller | ok 2025-12-04 16:46:07.813955 | 2025-12-04 16:46:07.814024 | TASK [Prepare workspace] 2025-12-04 16:46:07.831555 | controller | ok 2025-12-04 16:46:07.846932 | 2025-12-04 16:46:07.847003 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-12-04 16:46:08.148323 | controller | ok 2025-12-04 16:46:08.154427 | 2025-12-04 16:46:08.154487 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-12-04 16:46:10.095959 | controller | Output suppressed because no_log was given 2025-12-04 16:46:10.103993 | 2025-12-04 16:46:10.104053 | LOOP [Create zuul-output directory] 2025-12-04 16:46:10.286816 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-12-04 16:46:10.442901 | controller | ok: "/home/zuul/zuul-output/logs" 2025-12-04 16:46:10.448597 | 2025-12-04 16:46:10.448670 | TASK [Install required packages] 2025-12-04 16:46:11.333271 | controller | ok: Nothing to do 2025-12-04 16:46:11.337597 | 2025-12-04 16:46:11.337653 | TASK [Install venv] 2025-12-04 16:47:28.782932 | controller | changed 2025-12-04 16:47:28.805261 | 2025-12-04 16:47:28.805319 | PLAY RECAP 2025-12-04 16:47:28.805359 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-12-04 16:47:28.805380 | 2025-12-04 16:47:28.868883 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-12-04 16:47:28.869752 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-12-04 16:47:29.319676 | 2025-12-04 16:47:29.319779 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-12-04 16:47:29.338157 | 2025-12-04 16:47:29.338225 | TASK [Create zuul-output directory] 2025-12-04 16:47:29.619510 | controller | changed 2025-12-04 16:47:29.624539 | 2025-12-04 16:47:29.624638 | TASK [Slurp Zuul inventory test] 2025-12-04 16:47:29.860693 | controller -> localhost | ok 2025-12-04 16:47:29.866379 | 2025-12-04 16:47:29.866442 | TASK [Save zuul inventory] 2025-12-04 16:47:30.524088 | controller | changed 2025-12-04 16:47:30.528717 | 2025-12-04 16:47:30.528779 | TASK [Save zuul vars without the change_message] 2025-12-04 16:47:31.044162 | controller | changed 2025-12-04 16:47:31.060367 | 2025-12-04 16:47:31.060415 | PLAY RECAP 2025-12-04 16:47:31.060457 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-04 16:47:31.060478 | 2025-12-04 16:47:31.124246 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-12-04 16:47:31.125212 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/build_push_container_runner.yml@main] 2025-12-04 16:47:31.594805 | 2025-12-04 16:47:31.594900 | PLAY [Run build_push_container role] 2025-12-04 16:47:31.613876 | 2025-12-04 16:47:31.613975 | TASK [Run build_push_container role] 2025-12-04 16:47:32.270048 | controller | 2025-12-04 16:47:33.026813 | controller | PLAY [Run build_push_container role] ******************************************* 2025-12-04 16:47:33.026848 | controller | 2025-12-04 16:47:33.026858 | controller | TASK [Gathering Facts ] ******************************************************** 2025-12-04 16:47:33.026865 | controller | Thursday 04 December 2025 16:47:32 +0000 (0:00:00.041) 0:00:00.041 ***** 2025-12-04 16:47:33.026871 | controller | Thursday 04 December 2025 16:47:32 +0000 (0:00:00.040) 0:00:00.040 ***** 2025-12-04 16:47:33.026883 | controller | ok: [localhost] 2025-12-04 16:47:33.059455 | controller | 2025-12-04 16:47:33.059484 | controller | TASK [Run build_push_container role name=build_push_container] ***************** 2025-12-04 16:47:33.059492 | controller | Thursday 04 December 2025 16:47:33 +0000 (0:00:00.757) 0:00:00.798 ***** 2025-12-04 16:47:33.059510 | controller | Thursday 04 December 2025 16:47:33 +0000 (0:00:00.757) 0:00:00.797 ***** 2025-12-04 16:47:33.059521 | controller | 2025-12-04 16:47:33.430909 | controller | TASK [build_push_container : Ensure directories are present path={{ item }}, state=directory, mode=0755, recurse=True] *** 2025-12-04 16:47:33.430941 | controller | Thursday 04 December 2025 16:47:33 +0000 (0:00:00.032) 0:00:00.831 ***** 2025-12-04 16:47:33.430949 | controller | Thursday 04 December 2025 16:47:33 +0000 (0:00:00.032) 0:00:00.830 ***** 2025-12-04 16:47:33.430960 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/scripts) 2025-12-04 16:47:34.153638 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/logs) 2025-12-04 16:47:34.153671 | controller | 2025-12-04 16:47:34.153678 | controller | TASK [podman : Ensure podman is installed name={{ cifmw_podman_packages }}, state=present] *** 2025-12-04 16:47:34.153684 | controller | Thursday 04 December 2025 16:47:33 +0000 (0:00:00.371) 0:00:01.202 ***** 2025-12-04 16:47:34.153689 | controller | Thursday 04 December 2025 16:47:33 +0000 (0:00:00.371) 0:00:01.201 ***** 2025-12-04 16:47:34.153699 | controller | ok: [localhost] 2025-12-04 16:47:34.495247 | controller | 2025-12-04 16:47:34.495293 | controller | TASK [podman : Enable loginctl linger for ansible_user_id _raw_params=loginctl enable-linger {{ cifmw_podman_user_linger }}] *** 2025-12-04 16:47:34.495302 | controller | Thursday 04 December 2025 16:47:34 +0000 (0:00:00.722) 0:00:01.925 ***** 2025-12-04 16:47:34.495308 | controller | Thursday 04 December 2025 16:47:34 +0000 (0:00:00.722) 0:00:01.924 ***** 2025-12-04 16:47:34.495318 | controller | changed: [localhost] 2025-12-04 16:47:34.517065 | controller | 2025-12-04 16:47:34.517090 | 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-12-04 16:47:34.517101 | controller | Thursday 04 December 2025 16:47:34 +0000 (0:00:00.341) 0:00:02.267 ***** 2025-12-04 16:47:34.517107 | controller | Thursday 04 December 2025 16:47:34 +0000 (0:00:00.341) 0:00:02.265 ***** 2025-12-04 16:47:34.517117 | controller | skipping: [localhost] 2025-12-04 16:47:34.534394 | controller | 2025-12-04 16:47:34.534416 | 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-12-04 16:47:34.534424 | controller | Thursday 04 December 2025 16:47:34 +0000 (0:00:00.022) 0:00:02.289 ***** 2025-12-04 16:47:34.534430 | controller | Thursday 04 December 2025 16:47:34 +0000 (0:00:00.022) 0:00:02.288 ***** 2025-12-04 16:47:34.534439 | controller | skipping: [localhost] 2025-12-04 16:47:34.557611 | controller | 2025-12-04 16:47:34.557637 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed _raw_params=podman system migrate] *** 2025-12-04 16:47:34.557648 | controller | Thursday 04 December 2025 16:47:34 +0000 (0:00:00.017) 0:00:02.306 ***** 2025-12-04 16:47:34.557655 | controller | Thursday 04 December 2025 16:47:34 +0000 (0:00:00.017) 0:00:02.305 ***** 2025-12-04 16:47:34.557669 | controller | skipping: [localhost] 2025-12-04 16:47:42.013349 | controller | 2025-12-04 16:47:42.013386 | 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-12-04 16:47:42.013406 | controller | Thursday 04 December 2025 16:47:34 +0000 (0:00:00.023) 0:00:02.329 ***** 2025-12-04 16:47:42.013412 | controller | Thursday 04 December 2025 16:47:34 +0000 (0:00:00.023) 0:00:02.328 ***** 2025-12-04 16:47:42.013424 | 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-12-04 16:47:42.200560 | controller | changed: [localhost] 2025-12-04 16:47:42.200590 | controller | 2025-12-04 16:47:42.200598 | controller | TASK [build_push_container : Ensure manifest doesn't exist _raw_params=podman manifest rm {{ _container_manifest_name }}] *** 2025-12-04 16:47:42.200604 | controller | Thursday 04 December 2025 16:47:42 +0000 (0:00:07.455) 0:00:09.785 ***** 2025-12-04 16:47:42.200610 | controller | Thursday 04 December 2025 16:47:42 +0000 (0:00:07.455) 0:00:09.784 ***** 2025-12-04 16:47:42.200624 | controller | ok: [localhost] 2025-12-04 16:47:42.396983 | controller | 2025-12-04 16:47:42.397006 | controller | TASK [build_push_container : Create manifest _raw_params=podman manifest create {{ _container_manifest_name }}] *** 2025-12-04 16:47:42.397014 | controller | Thursday 04 December 2025 16:47:42 +0000 (0:00:00.187) 0:00:09.972 ***** 2025-12-04 16:47:42.397020 | controller | Thursday 04 December 2025 16:47:42 +0000 (0:00:00.187) 0:00:09.971 ***** 2025-12-04 16:47:42.397029 | controller | changed: [localhost] 2025-12-04 17:00:08.209190 | controller | 2025-12-04 17:00:08.209227 | controller | TASK [build_push_container : Build container output_dir={{ (cifmw_build_push_container_artifacts, 'scripts') | ansible.builtin.path_join }}, script={{ _podman_build_cmd }}] *** 2025-12-04 17:00:08.209239 | controller | Thursday 04 December 2025 16:47:42 +0000 (0:00:00.196) 0:00:10.169 ***** 2025-12-04 17:00:08.209245 | controller | Thursday 04 December 2025 16:47:42 +0000 (0:00:00.196) 0:00:10.167 ***** 2025-12-04 17:00:08.209254 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_001_build.log 2025-12-04 17:00:42.184335 | controller | changed: [localhost] 2025-12-04 17:00:42.184407 | controller | 2025-12-04 17:00:42.184417 | 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-12-04 17:00:42.184427 | controller | Thursday 04 December 2025 17:00:08 +0000 (0:12:25.812) 0:12:35.981 ***** 2025-12-04 17:00:42.184433 | controller | Thursday 04 December 2025 17:00:08 +0000 (0:12:25.812) 0:12:35.979 ***** 2025-12-04 17:00:42.184446 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_002_push.log 2025-12-04 17:00:42.233871 | controller | changed: [localhost] => (item=d228bdad382de12daec88e05f28d28773e79d8a0) 2025-12-04 17:00:42.233895 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_003_push.log 2025-12-04 17:00:42.233904 | controller | changed: [localhost] => (item=3531-latest) 2025-12-04 17:00:42.233911 | controller | 2025-12-04 17:00:42.233917 | 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-12-04 17:00:42.233923 | controller | Thursday 04 December 2025 17:00:42 +0000 (0:00:33.975) 0:13:09.956 ***** 2025-12-04 17:00:42.233930 | controller | Thursday 04 December 2025 17:00:42 +0000 (0:00:33.975) 0:13:09.955 ***** 2025-12-04 17:00:42.233941 | controller | skipping: [localhost] => (item=d228bdad382de12daec88e05f28d28773e79d8a0) 2025-12-04 17:00:42.279931 | controller | skipping: [localhost] => (item=3531-latest) 2025-12-04 17:00:42.279977 | controller | skipping: [localhost] 2025-12-04 17:00:42.279992 | controller | 2025-12-04 17:00:42.280003 | controller | PLAY RECAP ********************************************************************* 2025-12-04 17:00:42.280013 | controller | localhost : ok=9 changed=6 unreachable=0 failed=0 skipped=4 rescued=0 ignored=0 2025-12-04 17:00:42.280120 | controller | 2025-12-04 17:00:42.280138 | controller | Thursday 04 December 2025 17:00:42 +0000 (0:00:00.049) 0:13:10.005 ***** 2025-12-04 17:00:42.280161 | controller | =============================================================================== 2025-12-04 17:00:42.280171 | controller | build_push_container : Build container -------------------------------- 745.81s 2025-12-04 17:00:42.280181 | controller | build_push_container : Push manifest ----------------------------------- 33.98s 2025-12-04 17:00:42.280190 | controller | build_push_container : Install qemu-user-static for multi arch builds --- 7.46s 2025-12-04 17:00:42.280200 | controller | Gathering Facts --------------------------------------------------------- 0.76s 2025-12-04 17:00:42.280210 | controller | podman : Ensure podman is installed ------------------------------------- 0.72s 2025-12-04 17:00:42.280219 | controller | build_push_container : Ensure directories are present ------------------- 0.37s 2025-12-04 17:00:42.280229 | controller | podman : Enable loginctl linger for ansible_user_id --------------------- 0.34s 2025-12-04 17:00:42.280239 | controller | build_push_container : Create manifest ---------------------------------- 0.20s 2025-12-04 17:00:42.280251 | controller | build_push_container : Ensure manifest doesn't exist -------------------- 0.19s 2025-12-04 17:00:42.280261 | controller | build_push_container : Push container ----------------------------------- 0.05s 2025-12-04 17:00:42.280271 | controller | Run build_push_container role ------------------------------------------- 0.03s 2025-12-04 17:00:42.280280 | controller | podman : Run podman system migrate if subuid/subgid files were changed --- 0.02s 2025-12-04 17:00:42.280290 | controller | podman : Ensure subordinate UID entry exists for zuul ------------------- 0.02s 2025-12-04 17:00:42.280299 | controller | podman : Ensure subordinate GID entry exists for zuul ------------------- 0.02s 2025-12-04 17:00:42.280309 | controller | Thursday 04 December 2025 17:00:42 +0000 (0:00:00.049) 0:13:10.005 ***** 2025-12-04 17:00:42.280318 | controller | =============================================================================== 2025-12-04 17:00:42.280328 | controller | build_push_container -------------------------------------------------- 788.05s 2025-12-04 17:00:42.280337 | controller | podman ------------------------------------------------------------------ 1.13s 2025-12-04 17:00:42.280347 | controller | gather_facts ------------------------------------------------------------ 0.76s 2025-12-04 17:00:42.280356 | controller | ansible.builtin.include_role -------------------------------------------- 0.03s 2025-12-04 17:00:42.280366 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ 2025-12-04 17:00:42.280375 | controller | total ----------------------------------------------------------------- 789.96s 2025-12-04 17:00:42.451307 | controller | changed 2025-12-04 17:00:42.469343 | 2025-12-04 17:00:42.469398 | PLAY RECAP 2025-12-04 17:00:42.469439 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-04 17:00:42.469462 | 2025-12-04 17:00:42.527030 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/build_push_container_runner.yml@main] 2025-12-04 17:00:42.528003 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-12-04 17:00:43.038680 | 2025-12-04 17:00:43.038761 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-12-04 17:00:43.056957 | 2025-12-04 17:00:43.057029 | TASK [Filter out host if needed] 2025-12-04 17:00:43.065270 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-12-04 17:00:43.069637 | 2025-12-04 17:00:43.069704 | TASK [Ensure file is present] 2025-12-04 17:00:43.363167 | controller | ok 2025-12-04 17:00:43.368370 | 2025-12-04 17:00:43.368436 | TASK [Manage molecule report file] 2025-12-04 17:00:43.890436 | controller | skipping: Conditional result was False 2025-12-04 17:00:43.895046 | 2025-12-04 17:00:43.895105 | TASK [Check if we get ci-framework-data basedir] 2025-12-04 17:00:44.066230 | controller | ok 2025-12-04 17:00:44.071745 | 2025-12-04 17:00:44.071811 | TASK [Create ci-framework-data log directory for zuul] 2025-12-04 17:00:44.344780 | controller | changed 2025-12-04 17:00:44.349515 | 2025-12-04 17:00:44.349578 | TASK [Copy ci-framework interesting files] 2025-12-04 17:00:44.626681 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-12-04 17:00:44.871413 | controller | changed 2025-12-04 17:00:44.875969 | 2025-12-04 17:00:44.876033 | TASK [Get SELinux listing] 2025-12-04 17:00:45.400361 | controller | changed 2025-12-04 17:00:45.405699 | 2025-12-04 17:00:45.405760 | TASK [Generate log index] 2025-12-04 17:00:45.992039 | controller | changed 2025-12-04 17:00:45.996701 | 2025-12-04 17:00:45.996763 | TASK [Get some env related data] 2025-12-04 17:00:46.512053 | controller | /home/zuul/.local/bin/ansible 2025-12-04 17:00:47.543424 | controller | changed 2025-12-04 17:00:47.548231 | 2025-12-04 17:00:47.548285 | TASK [Generate list of logs to collect in home directory] 2025-12-04 17:00:47.829620 | controller | ok: All paths examined 2025-12-04 17:00:47.834472 | 2025-12-04 17:00:47.834535 | LOOP [Copy logs from home directory] 2025-12-04 17:00:48.105727 | controller | changed: 2025-12-04 17:00:48.105874 | controller | { 2025-12-04 17:00:48.105903 | controller | "atime": 1764866832.997839, 2025-12-04 17:00:48.105952 | controller | "ctime": 1764867642.2334135, 2025-12-04 17:00:48.105975 | controller | "dev": 64513, 2025-12-04 17:00:48.105994 | controller | "gid": 1000, 2025-12-04 17:00:48.106011 | controller | "gr_name": "zuul", 2025-12-04 17:00:48.106028 | controller | "inode": 4330640, 2025-12-04 17:00:48.106044 | controller | "isblk": false, 2025-12-04 17:00:48.106060 | controller | "ischr": false, 2025-12-04 17:00:48.106076 | controller | "isdir": false, 2025-12-04 17:00:48.106091 | controller | "isfifo": false, 2025-12-04 17:00:48.106105 | controller | "isgid": false, 2025-12-04 17:00:48.106119 | controller | "islnk": false, 2025-12-04 17:00:48.106133 | controller | "isreg": true, 2025-12-04 17:00:48.106147 | controller | "issock": false, 2025-12-04 17:00:48.106172 | controller | "isuid": false, 2025-12-04 17:00:48.106187 | controller | "mode": "0644", 2025-12-04 17:00:48.106201 | controller | "mtime": 1764867642.2334135, 2025-12-04 17:00:48.106216 | controller | "nlink": 1, 2025-12-04 17:00:48.106230 | controller | "path": "/home/zuul/ansible.log", 2025-12-04 17:00:48.106245 | controller | "pw_name": "zuul", 2025-12-04 17:00:48.106259 | controller | "rgrp": true, 2025-12-04 17:00:48.106273 | controller | "roth": true, 2025-12-04 17:00:48.106288 | controller | "rusr": true, 2025-12-04 17:00:48.106302 | controller | "size": 18992, 2025-12-04 17:00:48.106315 | controller | "uid": 1000, 2025-12-04 17:00:48.106329 | controller | "wgrp": false, 2025-12-04 17:00:48.106344 | controller | "woth": false, 2025-12-04 17:00:48.106361 | controller | "wusr": true, 2025-12-04 17:00:48.106376 | controller | "xgrp": false, 2025-12-04 17:00:48.106391 | controller | "xoth": false, 2025-12-04 17:00:48.106404 | controller | "xusr": false 2025-12-04 17:00:48.106419 | controller | } 2025-12-04 17:00:48.136845 | 2025-12-04 17:00:48.136957 | TASK [Copy crio stats log file] 2025-12-04 17:00:48.150998 | controller | skipping: Conditional result was False 2025-12-04 17:00:48.155835 | 2025-12-04 17:00:48.155898 | TASK [Get SELinux related data] 2025-12-04 17:00:48.352852 | controller | 2025-12-04 17:00:48.680295 | controller | ERROR 2025-12-04 17:00:48.680435 | controller | { 2025-12-04 17:00:48.680464 | controller | "delta": "0:00:00.008733", 2025-12-04 17:00:48.680482 | controller | "end": "2025-12-04 17:00:48.353816", 2025-12-04 17:00:48.680507 | controller | "msg": "non-zero return code", 2025-12-04 17:00:48.680523 | controller | "rc": 1, 2025-12-04 17:00:48.680539 | controller | "start": "2025-12-04 17:00:48.345083" 2025-12-04 17:00:48.680554 | controller | } 2025-12-04 17:00:48.680573 | controller | ERROR: Ignoring Errors 2025-12-04 17:00:48.684823 | 2025-12-04 17:00:48.684882 | TASK [Create system configuration directory] 2025-12-04 17:00:48.865176 | controller | changed 2025-12-04 17:00:48.869694 | 2025-12-04 17:00:48.869756 | TASK [Get some of the system configurations] 2025-12-04 17:00:49.392249 | controller | changed 2025-12-04 17:00:49.396812 | 2025-12-04 17:00:49.396867 | TASK [Copy generated documentation if available] 2025-12-04 17:00:49.410521 | controller | skipping: Conditional result was False 2025-12-04 17:00:49.416677 | 2025-12-04 17:00:49.416794 | TASK [Copy generated AsciiDoc documentation if available] 2025-12-04 17:00:49.430505 | controller | skipping: Conditional result was False 2025-12-04 17:00:49.435800 | 2025-12-04 17:00:49.435862 | TASK [Compress logs bigger than 2MB] 2025-12-04 17:00:49.962083 | controller | changed 2025-12-04 17:00:49.966786 | 2025-12-04 17:00:49.966847 | TASK [Copy files from workspace on node] 2025-12-04 17:00:49.984641 | controller | ok 2025-12-04 17:00:50.004949 | 2025-12-04 17:00:50.005008 | TASK [fetch-output : Set log path for multiple nodes] 2025-12-04 17:00:50.018578 | controller | skipping: Conditional result was False 2025-12-04 17:00:50.023579 | 2025-12-04 17:00:50.023644 | TASK [fetch-output : Set log path for single node] 2025-12-04 17:00:50.051581 | controller | ok 2025-12-04 17:00:50.056041 | 2025-12-04 17:00:50.056099 | LOOP [fetch-output : Ensure local output dirs] 2025-12-04 17:00:50.235975 | controller -> localhost | ok: "/var/lib/zuul/builds/26c71ef211154eaba31ba79be0290321/work/logs" 2025-12-04 17:00:50.236199 | controller -> localhost | changed: All items complete 2025-12-04 17:00:50.236227 | 2025-12-04 17:00:50.404118 | controller -> localhost | changed: "/var/lib/zuul/builds/26c71ef211154eaba31ba79be0290321/work/artifacts" 2025-12-04 17:00:50.567309 | controller -> localhost | changed: "/var/lib/zuul/builds/26c71ef211154eaba31ba79be0290321/work/docs" 2025-12-04 17:00:50.583956 | 2025-12-04 17:00:50.584039 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-12-04 17:00:51.092405 | controller | changed: 2025-12-04 17:00:51.092594 | controller | .d..t...... ./ 2025-12-04 17:00:51.092626 | controller | >f+++++++++ README.html 2025-12-04 17:00:51.092647 | controller | >f+++++++++ ansible.log 2025-12-04 17:00:51.092669 | controller | >f+++++++++ dmesg.log 2025-12-04 17:00:51.092688 | controller | >f+++++++++ installed-pkgs.log 2025-12-04 17:00:51.092705 | controller | >f+++++++++ python.log 2025-12-04 17:00:51.092726 | controller | >f+++++++++ registries.conf 2025-12-04 17:00:51.092747 | controller | >f+++++++++ selinux-denials.log 2025-12-04 17:00:51.092764 | controller | >f+++++++++ selinux-listing.log 2025-12-04 17:00:51.092784 | controller | cd+++++++++ ci-framework-data/ 2025-12-04 17:00:51.092802 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-12-04 17:00:51.092820 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-12-04 17:00:51.092838 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/ 2025-12-04 17:00:51.092855 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/logs/ 2025-12-04 17:00:51.092872 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_000_install_qemu_user_static_for.log 2025-12-04 17:00:51.092889 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_001_build.log 2025-12-04 17:00:51.092931 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_002_push.log 2025-12-04 17:00:51.092959 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_003_push.log 2025-12-04 17:00:51.092986 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ 2025-12-04 17:00:51.093005 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_000_install_qemu_user_static_for.sh 2025-12-04 17:00:51.093022 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_001_build.sh 2025-12-04 17:00:51.093038 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_002_push.sh 2025-12-04 17:00:51.093055 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_003_push.sh 2025-12-04 17:00:51.093072 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-12-04 17:00:51.093089 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-12-04 17:00:51.093104 | controller | cd+++++++++ ci-framework-data/logs/ 2025-12-04 17:00:51.093120 | controller | cd+++++++++ registries.conf.d/ 2025-12-04 17:00:51.093136 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-12-04 17:00:51.093151 | controller | cd+++++++++ system-config/ 2025-12-04 17:00:51.093177 | controller | cd+++++++++ system-config/libvirt/ 2025-12-04 17:00:51.093194 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-12-04 17:00:51.093210 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-12-04 17:00:51.433844 | controller | changed: .d..t...... ./ 2025-12-04 17:00:51.761815 | controller | changed: .d..t...... ./ 2025-12-04 17:00:51.777806 | 2025-12-04 17:00:51.777889 | TASK [Return artifact to Zuul] 2025-12-04 17:00:51.792120 | controller | skipping: Conditional result was False 2025-12-04 17:00:51.810298 | 2025-12-04 17:00:51.810354 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-12-04 17:00:51.810451 | 2025-12-04 17:00:51.810478 | PLAY RECAP 2025-12-04 17:00:51.810514 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1 2025-12-04 17:00:51.810534 | 2025-12-04 17:00:51.880874 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-12-04 17:00:51.881783 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-12-04 17:00:52.352023 | 2025-12-04 17:00:52.352166 | PLAY [all] 2025-12-04 17:00:52.370089 | 2025-12-04 17:00:52.370175 | TASK [include_role : fetch-output] 2025-12-04 17:00:52.399056 | controller | ok 2025-12-04 17:00:52.414563 | 2025-12-04 17:00:52.414651 | TASK [fetch-output : Set log path for multiple nodes] 2025-12-04 17:00:52.458538 | controller | skipping: Conditional result was False 2025-12-04 17:00:52.463961 | 2025-12-04 17:00:52.464037 | TASK [fetch-output : Set log path for single node] 2025-12-04 17:00:52.492326 | controller | ok 2025-12-04 17:00:52.497743 | 2025-12-04 17:00:52.497817 | LOOP [fetch-output : Ensure local output dirs] 2025-12-04 17:00:52.796282 | controller -> localhost | ok: "/var/lib/zuul/builds/26c71ef211154eaba31ba79be0290321/work/logs" 2025-12-04 17:00:52.974131 | controller -> localhost | ok: "/var/lib/zuul/builds/26c71ef211154eaba31ba79be0290321/work/artifacts" 2025-12-04 17:00:53.157238 | controller -> localhost | ok: "/var/lib/zuul/builds/26c71ef211154eaba31ba79be0290321/work/docs" 2025-12-04 17:00:53.166141 | 2025-12-04 17:00:53.166244 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-12-04 17:00:53.745138 | controller | ok 2025-12-04 17:00:53.745388 | controller | ok: All items complete 2025-12-04 17:00:53.745419 | 2025-12-04 17:00:54.108405 | controller | ok 2025-12-04 17:00:54.471848 | controller | ok 2025-12-04 17:00:54.488281 | 2025-12-04 17:00:54.488390 | TASK [include_role : fetch-output-openshift] 2025-12-04 17:00:54.512296 | controller | skipping: Conditional result was False 2025-12-04 17:00:54.517863 | 2025-12-04 17:00:54.517955 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-12-04 17:00:54.896610 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006507 2025-12-04 17:00:55.089148 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006248 2025-12-04 17:00:55.115642 | 2025-12-04 17:00:55.115718 | PLAY [all] 2025-12-04 17:00:55.129245 | 2025-12-04 17:00:55.129315 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-12-04 17:00:55.502763 | controller | changed 2025-12-04 17:00:55.523093 | 2025-12-04 17:00:55.523143 | PLAY RECAP 2025-12-04 17:00:55.523198 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-12-04 17:00:55.523221 | 2025-12-04 17:00:55.593086 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-12-04 17:00:55.594057 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-12-04 17:00:56.109274 | 2025-12-04 17:00:56.109367 | PLAY [localhost] 2025-12-04 17:00:56.125752 | 2025-12-04 17:00:56.125829 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-04 17:00:56.417589 | localhost | changed 2025-12-04 17:00:56.421965 | 2025-12-04 17:00:56.422041 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-04 17:00:56.439620 | localhost | ok 2025-12-04 17:00:56.447159 | 2025-12-04 17:00:56.447239 | TASK [add-fileserver : Create SSH private key tempfile] 2025-12-04 17:00:56.727790 | localhost | changed 2025-12-04 17:00:56.732352 | 2025-12-04 17:00:56.732422 | TASK [add-fileserver : Create SSH private key from secret] 2025-12-04 17:00:57.257057 | localhost | changed 2025-12-04 17:00:57.261934 | 2025-12-04 17:00:57.262002 | TASK [add-fileserver : Add fileserver ssh key] 2025-12-04 17:00:57.582818 | localhost | Identity added: /var/lib/zuul/builds/26c71ef211154eaba31ba79be0290321/work/tmp/ansible.i3q37nil (/var/lib/zuul/builds/26c71ef211154eaba31ba79be0290321/work/tmp/ansible.i3q37nil) 2025-12-04 17:00:57.582994 | localhost | ok: Runtime: 0:00:00.006791 2025-12-04 17:00:57.586859 | 2025-12-04 17:00:57.586940 | TASK [add-fileserver : Remove SSH private key from disk] 2025-12-04 17:00:57.796477 | localhost | ok: Runtime: 0:00:00.004598 2025-12-04 17:00:57.801069 | 2025-12-04 17:00:57.801143 | TASK [add-fileserver : Add fileserver to inventory] 2025-12-04 17:00:57.849514 | localhost | changed 2025-12-04 17:00:57.853279 | 2025-12-04 17:00:57.853342 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-12-04 17:00:58.133795 | localhost | changed 2025-12-04 17:00:58.151582 | 2025-12-04 17:00:58.151636 | PLAY [localhost] 2025-12-04 17:00:58.161743 | 2025-12-04 17:00:58.161805 | TASK [Generate bulk log download script] 2025-12-04 17:00:58.179710 | localhost | ok 2025-12-04 17:00:58.189889 | 2025-12-04 17:00:58.189968 | TASK [local-log-download : Check API endpoint is defined] 2025-12-04 17:00:58.216708 | localhost | ok: All assertions passed 2025-12-04 17:00:58.220702 | 2025-12-04 17:00:58.220761 | TASK [local-log-download : Create download script] 2025-12-04 17:00:58.537496 | localhost -> localhost | changed 2025-12-04 17:00:58.545534 | 2025-12-04 17:00:58.545597 | TASK [Register quick-download link] 2025-12-04 17:00:58.562559 | localhost | ok 2025-12-04 17:00:58.597257 | 2025-12-04 17:00:58.597314 | PLAY [logserver.rdoproject.org] 2025-12-04 17:00:58.606482 | 2025-12-04 17:00:58.606543 | TASK [Set zuul-log-path fact] 2025-12-04 17:00:58.622447 | logserver.rdoproject.org | ok 2025-12-04 17:00:58.631152 | 2025-12-04 17:00:58.631226 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-04 17:00:58.656988 | logserver.rdoproject.org | ok 2025-12-04 17:00:58.662110 | 2025-12-04 17:00:58.662184 | TASK [upload-logs : Create log directories] 2025-12-04 17:01:00.327695 | logserver.rdoproject.org | changed 2025-12-04 17:01:00.330645 | 2025-12-04 17:01:00.330705 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-12-04 17:01:00.536408 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004127 2025-12-04 17:01:00.540727 | 2025-12-04 17:01:00.540797 | TASK [upload-logs : Upload logs to log server] 2025-12-04 17:01:01.930830 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-12-04 17:01:01.933562 | 2025-12-04 17:01:01.933627 | LOOP [upload-logs : Compress console log and json output] 2025-12-04 17:01:01.968653 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-04 17:01:01.976057 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-04 17:01:01.980671 | 2025-12-04 17:01:01.980752 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-04 17:01:02.012084 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-04 17:01:02.012279 | 2025-12-04 17:01:02.015711 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-04 17:01:02.027298 | 2025-12-04 17:01:02.027377 | LOOP [upload-logs : Upload console log and json output]