2025-10-03 13:05:06.349417 | Job console starting... 2025-10-03 13:05:06.373347 | Updating repositories 2025-10-03 13:05:06.435011 | Preparing job workspace 2025-10-03 13:05:14.661336 | Running Ansible setup... 2025-10-03 13:05:20.876819 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-03 13:05:21.531545 | 2025-10-03 13:05:21.531685 | PLAY [localhost] 2025-10-03 13:05:21.541343 | 2025-10-03 13:05:21.541451 | TASK [Gathering Facts] 2025-10-03 13:05:22.572298 | localhost | ok 2025-10-03 13:05:22.602354 | 2025-10-03 13:05:22.602585 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-03 13:05:23.052142 | localhost -> localhost | changed 2025-10-03 13:05:23.063874 | 2025-10-03 13:05:23.064074 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-03 13:05:24.054736 | localhost -> localhost | changed 2025-10-03 13:05:24.063607 | 2025-10-03 13:05:24.063720 | TASK [Setup log path fact] 2025-10-03 13:05:24.081943 | localhost | ok 2025-10-03 13:05:24.094053 | 2025-10-03 13:05:24.094138 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 13:05:24.121524 | localhost | ok 2025-10-03 13:05:24.129134 | 2025-10-03 13:05:24.129197 | TASK [emit-job-header : Print job information] 2025-10-03 13:05:24.156919 | # Job Information 2025-10-03 13:05:24.157062 | Ansible Version: 2.15.12 2025-10-03 13:05:24.157091 | Job: periodic-edpm-build-push-images-centos-10-master 2025-10-03 13:05:24.157112 | Pipeline: check 2025-10-03 13:05:24.157130 | Executor: ze01.softwarefactory-project.io 2025-10-03 13:05:24.157148 | Triggered by: https://review.rdoproject.org/r/c/testproject/+/58379 2025-10-03 13:05:24.157165 | Log URL (when completed): https://logserver.rdoproject.org/6fd/rdoproject.org/6fdc2dc9d16141e5bfaf929cc097681a/ 2025-10-03 13:05:24.157182 | Event ID: 05e3eb76045f40ac8d996d9acfc81090 2025-10-03 13:05:24.160745 | 2025-10-03 13:05:24.160808 | LOOP [emit-job-header : Print node information] 2025-10-03 13:05:24.265731 | localhost | ok: 2025-10-03 13:05:24.265996 | localhost | # Node Information 2025-10-03 13:05:24.266115 | localhost | Inventory Hostname: controller 2025-10-03 13:05:24.266166 | localhost | Hostname: np0005469228 2025-10-03 13:05:24.266208 | localhost | Username: zuul-worker 2025-10-03 13:05:24.266251 | localhost | Distro: CentOS 10 2025-10-03 13:05:24.266291 | localhost | Provider: vexxhost-nodepool-sf 2025-10-03 13:05:24.266329 | localhost | Region: RegionOne 2025-10-03 13:05:24.266364 | localhost | Label: cloud-centos-10-stream 2025-10-03 13:05:24.266399 | localhost | Product Name: OpenStack Nova 2025-10-03 13:05:24.266460 | localhost | Interface IP: 38.102.83.212 2025-10-03 13:05:24.313002 | 2025-10-03 13:05:24.313183 | PLAY [all] 2025-10-03 13:05:24.323517 | 2025-10-03 13:05:24.323671 | TASK [Gather network facts] 2025-10-03 13:05:24.860452 | controller | ok 2025-10-03 13:05:24.882817 | 2025-10-03 13:05:24.882952 | TASK [include_role : start-zuul-console] 2025-10-03 13:05:24.905364 | controller | ok 2025-10-03 13:05:24.917661 | 2025-10-03 13:05:24.917782 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-03 13:05:25.383250 | controller | ok 2025-10-03 13:05:25.412144 | 2025-10-03 13:05:25.412327 | TASK [include_role : add-build-sshkey] 2025-10-03 13:05:25.438132 | controller | ok 2025-10-03 13:05:25.461554 | 2025-10-03 13:05:25.461700 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-03 13:05:25.745892 | controller -> localhost | ok 2025-10-03 13:05:25.754582 | 2025-10-03 13:05:25.754714 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-03 13:05:25.778688 | controller | ok 2025-10-03 13:05:25.800427 | controller | included: /var/lib/zuul/builds/6fdc2dc9d16141e5bfaf929cc097681a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-03 13:05:25.810361 | 2025-10-03 13:05:25.810518 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-03 13:05:26.674732 | controller -> localhost | Generating public/private rsa key pair. 2025-10-03 13:05:26.674929 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/6fdc2dc9d16141e5bfaf929cc097681a/work/6fdc2dc9d16141e5bfaf929cc097681a_id_rsa. 2025-10-03 13:05:26.674959 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/6fdc2dc9d16141e5bfaf929cc097681a/work/6fdc2dc9d16141e5bfaf929cc097681a_id_rsa.pub. 2025-10-03 13:05:26.674980 | controller -> localhost | The key fingerprint is: 2025-10-03 13:05:26.674999 | controller -> localhost | SHA256:ZnngLdoDj7NLOQg+cpD3MuHiqQKpamI82sSBaq31r2w zuul-build-sshkey 2025-10-03 13:05:26.675035 | controller -> localhost | The key's randomart image is: 2025-10-03 13:05:26.675055 | controller -> localhost | +---[RSA 3072]----+ 2025-10-03 13:05:26.675072 | controller -> localhost | | | 2025-10-03 13:05:26.675090 | controller -> localhost | | | 2025-10-03 13:05:26.675107 | controller -> localhost | | . | 2025-10-03 13:05:26.675125 | controller -> localhost | | o . + | 2025-10-03 13:05:26.675143 | controller -> localhost | |+.= . S o | 2025-10-03 13:05:26.675160 | controller -> localhost | |+*.= . X o | 2025-10-03 13:05:26.675177 | controller -> localhost | |B.Xoo B + | 2025-10-03 13:05:26.675197 | controller -> localhost | |*@++oE + . | 2025-10-03 13:05:26.675215 | controller -> localhost | |%+o .+=o | 2025-10-03 13:05:26.675232 | controller -> localhost | +----[SHA256]-----+ 2025-10-03 13:05:26.675274 | controller -> localhost | ok: Runtime: 0:00:00.358121 2025-10-03 13:05:26.680734 | 2025-10-03 13:05:26.680798 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-03 13:05:26.709425 | controller | ok 2025-10-03 13:05:26.719509 | controller | included: /var/lib/zuul/builds/6fdc2dc9d16141e5bfaf929cc097681a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-03 13:05:26.727750 | 2025-10-03 13:05:26.727864 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-03 13:05:26.752353 | controller | skipping: Conditional result was False 2025-10-03 13:05:26.758913 | 2025-10-03 13:05:26.759048 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-03 13:05:27.311673 | controller | changed 2025-10-03 13:05:27.322184 | 2025-10-03 13:05:27.322310 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-03 13:05:27.666813 | controller | ok 2025-10-03 13:05:27.680735 | 2025-10-03 13:05:27.680937 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-03 13:05:28.726227 | controller | changed 2025-10-03 13:05:28.737391 | 2025-10-03 13:05:28.737561 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-03 13:05:29.768184 | controller | changed 2025-10-03 13:05:29.776944 | 2025-10-03 13:05:29.777119 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-03 13:05:29.803485 | controller | skipping: Conditional result was False 2025-10-03 13:05:29.814719 | 2025-10-03 13:05:29.814877 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-03 13:05:30.266067 | controller -> localhost | changed 2025-10-03 13:05:30.281678 | 2025-10-03 13:05:30.281783 | TASK [add-build-sshkey : Add back temp key] 2025-10-03 13:05:30.613471 | controller -> localhost | Identity added: /var/lib/zuul/builds/6fdc2dc9d16141e5bfaf929cc097681a/work/6fdc2dc9d16141e5bfaf929cc097681a_id_rsa (zuul-build-sshkey) 2025-10-03 13:05:30.613917 | controller -> localhost | ok: Runtime: 0:00:00.014668 2025-10-03 13:05:30.624190 | 2025-10-03 13:05:30.624472 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-03 13:05:31.081126 | controller | ok 2025-10-03 13:05:31.085931 | 2025-10-03 13:05:31.085996 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-03 13:05:31.158435 | controller | skipping: Conditional result was False 2025-10-03 13:05:31.174280 | 2025-10-03 13:05:31.174431 | TASK [include_role : validate-host] 2025-10-03 13:05:31.207269 | controller | ok 2025-10-03 13:05:31.257613 | 2025-10-03 13:05:31.257869 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-03 13:05:31.286355 | controller | ok 2025-10-03 13:05:31.291171 | 2025-10-03 13:05:31.291231 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-03 13:05:31.539201 | controller -> localhost | ok 2025-10-03 13:05:31.547973 | 2025-10-03 13:05:31.548146 | TASK [validate-host : Collect information about the host] 2025-10-03 13:05:32.351611 | controller | ok 2025-10-03 13:05:32.360761 | 2025-10-03 13:05:32.360822 | TASK [validate-host : Sanitize hostname] 2025-10-03 13:05:32.439198 | controller | ok 2025-10-03 13:05:32.451385 | 2025-10-03 13:05:32.451602 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-03 13:05:32.929337 | controller -> localhost | changed 2025-10-03 13:05:32.934856 | 2025-10-03 13:05:32.935044 | TASK [validate-host : Collect information about zuul worker] 2025-10-03 13:05:33.411202 | controller | ok 2025-10-03 13:05:33.423511 | 2025-10-03 13:05:33.423728 | TASK [validate-host : Write out all zuul information for each host] 2025-10-03 13:05:33.945531 | controller -> localhost | changed 2025-10-03 13:05:33.968143 | 2025-10-03 13:05:33.968278 | TASK [include_role : prepare-workspace-openshift] 2025-10-03 13:05:33.992330 | controller | skipping: Conditional result was False 2025-10-03 13:05:33.998226 | 2025-10-03 13:05:33.998490 | TASK [include_role : remove-zuul-sshkey] 2025-10-03 13:05:34.022684 | controller | skipping: Conditional result was False 2025-10-03 13:05:34.034153 | 2025-10-03 13:05:34.034336 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-03 13:05:34.395642 | controller | ok: "logs" 2025-10-03 13:05:34.396146 | controller | ok: All items complete 2025-10-03 13:05:34.396211 | 2025-10-03 13:05:34.661071 | controller | ok: "artifacts" 2025-10-03 13:05:34.895760 | controller | ok: "docs" 2025-10-03 13:05:34.908964 | 2025-10-03 13:05:34.909137 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-03 13:05:35.204422 | controller | changed: "logs" 2025-10-03 13:05:35.514881 | controller | changed: "artifacts" 2025-10-03 13:05:35.797433 | controller | changed: "docs" 2025-10-03 13:05:35.838478 | 2025-10-03 13:05:35.838591 | PLAY RECAP 2025-10-03 13:05:35.838649 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-03 13:05:35.838688 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 13:05:35.838716 | 2025-10-03 13:05:35.972505 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-03 13:05:35.974060 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-03 13:05:36.641731 | 2025-10-03 13:05:36.641870 | PLAY [all] 2025-10-03 13:05:36.662893 | 2025-10-03 13:05:36.663075 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-03 13:05:36.743727 | controller | ok 2025-10-03 13:05:36.749416 | 2025-10-03 13:05:36.749539 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-03 13:05:37.240664 | controller | changed 2025-10-03 13:05:37.247977 | 2025-10-03 13:05:37.248064 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-03 13:05:38.537001 | controller | changed 2025-10-03 13:05:38.552972 | 2025-10-03 13:05:38.553110 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-03 13:05:39.090289 | controller | changed: 2025-10-03 13:05:39.090601 | controller | { 2025-10-03 13:05:39.090663 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-03 13:05:39.090722 | controller | } 2025-10-03 13:05:39.396800 | controller | changed: 2025-10-03 13:05:39.397156 | controller | { 2025-10-03 13:05:39.397191 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-03 13:05:39.397213 | controller | } 2025-10-03 13:05:39.715205 | controller | changed: 2025-10-03 13:05:39.715332 | controller | { 2025-10-03 13:05:39.715368 | 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-03 13:05:39.715396 | controller | } 2025-10-03 13:05:40.009772 | controller | changed: 2025-10-03 13:05:40.009876 | controller | { 2025-10-03 13:05:40.009899 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-03 13:05:40.009918 | controller | } 2025-10-03 13:05:40.324610 | controller | changed: 2025-10-03 13:05:40.324924 | controller | { 2025-10-03 13:05:40.324959 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-03 13:05:40.324978 | controller | } 2025-10-03 13:05:40.626959 | controller | changed: 2025-10-03 13:05:40.627114 | controller | { 2025-10-03 13:05:40.627152 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-03 13:05:40.627179 | controller | } 2025-10-03 13:05:40.964037 | controller | changed: 2025-10-03 13:05:40.964303 | controller | { 2025-10-03 13:05:40.964365 | 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-03 13:05:40.964408 | controller | } 2025-10-03 13:05:41.250811 | controller | changed: 2025-10-03 13:05:41.251199 | controller | { 2025-10-03 13:05:41.251271 | 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-03 13:05:41.251315 | controller | } 2025-10-03 13:05:41.592769 | controller | changed: 2025-10-03 13:05:41.592951 | controller | { 2025-10-03 13:05:41.593196 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-03 13:05:41.593255 | controller | } 2025-10-03 13:05:41.850790 | controller | changed: 2025-10-03 13:05:41.851206 | controller | { 2025-10-03 13:05:41.851287 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-03 13:05:41.851331 | controller | } 2025-10-03 13:05:42.174088 | controller | changed: 2025-10-03 13:05:42.174460 | controller | { 2025-10-03 13:05:42.174550 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-03 13:05:42.174601 | controller | } 2025-10-03 13:05:42.477930 | controller | changed: 2025-10-03 13:05:42.478060 | controller | { 2025-10-03 13:05:42.478088 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-03 13:05:42.478107 | controller | } 2025-10-03 13:05:42.786756 | controller | changed: 2025-10-03 13:05:42.788060 | controller | { 2025-10-03 13:05:42.788115 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-03 13:05:42.788145 | controller | } 2025-10-03 13:05:43.138229 | controller | changed: 2025-10-03 13:05:43.138405 | controller | { 2025-10-03 13:05:43.138457 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-03 13:05:43.138498 | controller | } 2025-10-03 13:05:43.477659 | controller | changed: 2025-10-03 13:05:43.477934 | controller | { 2025-10-03 13:05:43.477997 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-03 13:05:43.478072 | controller | } 2025-10-03 13:05:43.835605 | controller | changed: 2025-10-03 13:05:43.835768 | controller | { 2025-10-03 13:05:43.835797 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-03 13:05:43.835816 | controller | } 2025-10-03 13:05:44.137168 | controller | changed: 2025-10-03 13:05:44.137324 | controller | { 2025-10-03 13:05:44.137375 | 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-03 13:05:44.137417 | controller | } 2025-10-03 13:05:44.450926 | controller | changed: 2025-10-03 13:05:44.451279 | controller | { 2025-10-03 13:05:44.451341 | 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-03 13:05:44.451383 | controller | } 2025-10-03 13:05:44.784244 | controller | changed: 2025-10-03 13:05:44.784364 | controller | { 2025-10-03 13:05:44.784388 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-03 13:05:44.784412 | controller | } 2025-10-03 13:05:45.112267 | controller | changed: 2025-10-03 13:05:45.112536 | controller | { 2025-10-03 13:05:45.112607 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-03 13:05:45.112642 | controller | } 2025-10-03 13:05:45.447675 | controller | changed: 2025-10-03 13:05:45.448185 | controller | { 2025-10-03 13:05:45.448245 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-03 13:05:45.448276 | controller | } 2025-10-03 13:05:45.713073 | controller | changed: 2025-10-03 13:05:45.713293 | controller | { 2025-10-03 13:05:45.713585 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-03 13:05:45.713625 | controller | } 2025-10-03 13:05:46.031669 | controller | changed: 2025-10-03 13:05:46.031802 | controller | { 2025-10-03 13:05:46.031837 | 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-03 13:05:46.031865 | controller | } 2025-10-03 13:05:46.377097 | controller | changed: 2025-10-03 13:05:46.377227 | controller | { 2025-10-03 13:05:46.377259 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-03 13:05:46.377286 | controller | } 2025-10-03 13:05:46.704341 | controller | changed: 2025-10-03 13:05:46.704437 | controller | { 2025-10-03 13:05:46.704461 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-03 13:05:46.704479 | controller | } 2025-10-03 13:05:47.060310 | controller | changed: 2025-10-03 13:05:47.060488 | controller | { 2025-10-03 13:05:47.060546 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-03 13:05:47.060589 | controller | } 2025-10-03 13:05:47.111304 | 2025-10-03 13:05:47.111457 | TASK [Set timezone to UTC] 2025-10-03 13:05:47.866094 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-03 13:05:47.886229 | 2025-10-03 13:05:47.886378 | TASK [Create nodepool directory] 2025-10-03 13:05:48.218975 | controller | changed 2025-10-03 13:05:48.225219 | 2025-10-03 13:05:48.225304 | TASK [Create nodepool sub_nodes file] 2025-10-03 13:05:49.122644 | controller | changed 2025-10-03 13:05:49.128261 | 2025-10-03 13:05:49.128325 | TASK [Create nodepool sub_nodes_private file] 2025-10-03 13:05:50.090949 | controller | changed 2025-10-03 13:05:50.096487 | 2025-10-03 13:05:50.096545 | LOOP [Populate nodepool sub_nodes file] 2025-10-03 13:05:50.146856 | 2025-10-03 13:05:50.147052 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-03 13:05:50.187258 | 2025-10-03 13:05:50.187420 | TASK [Create nodepool primary file] 2025-10-03 13:05:50.221200 | controller | skipping: Conditional result was False 2025-10-03 13:05:50.228471 | 2025-10-03 13:05:50.228591 | TASK [Create nodepool node_private for this node] 2025-10-03 13:05:51.159613 | controller | changed 2025-10-03 13:05:51.165742 | 2025-10-03 13:05:51.165803 | LOOP [Copy ssh keys to nodepool directory] 2025-10-03 13:05:51.653726 | controller | ok: Item: id_rsa Runtime: 0:00:00.011345 2025-10-03 13:05:51.654104 | 2025-10-03 13:05:51.915602 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.006513 2025-10-03 13:05:51.932140 | 2025-10-03 13:05:51.932281 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-03 13:05:52.900127 | controller | changed 2025-10-03 13:05:52.911849 | 2025-10-03 13:05:52.912040 | TASK [Validate sudoers config after edits] 2025-10-03 13:05:53.284100 | controller | /etc/sudoers: parsed OK 2025-10-03 13:05:53.284178 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-03 13:05:53.284194 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-03 13:05:53.284206 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-03 13:05:53.462212 | controller | ok: Runtime: 0:00:00.010236 2025-10-03 13:05:53.473603 | 2025-10-03 13:05:53.473755 | TASK [Show the environment passed in to job shell scripts] 2025-10-03 13:05:53.896326 | controller | SHELL=/bin/bash 2025-10-03 13:05:53.896416 | controller | ZUUL_PROJECT=testproject 2025-10-03 13:05:53.896429 | controller | ZUUL_SHORT_PROJECT_NAME=testproject 2025-10-03 13:05:53.896438 | controller | ZUUL_CHANGES=rdo-jobs:master:refs/changes/73/58373/1^openstack-k8s-operators/edpm-image-builder:main:refs/changes/89/89/84dfaeffc64ebc0e8b94b5ed9fb3b7733682f44b^testproject:master:refs/changes/79/58379/2 2025-10-03 13:05:53.896452 | controller | GPG_TTY=not a tty 2025-10-03 13:05:53.896460 | controller | PWD=/home/zuul-worker 2025-10-03 13:05:53.896468 | controller | ZUUL_PIPELINE=check 2025-10-03 13:05:53.896476 | controller | LOGNAME=zuul-worker 2025-10-03 13:05:53.896484 | controller | XDG_SESSION_TYPE=tty 2025-10-03 13:05:53.896492 | controller | _=/usr/bin/env 2025-10-03 13:05:53.896499 | controller | MOTD_SHOWN=pam 2025-10-03 13:05:53.896507 | controller | HOME=/home/zuul-worker 2025-10-03 13:05:53.896515 | controller | LANG=en_US.UTF-8 2025-10-03 13:05:53.896574 | controller | SSH_CONNECTION=38.102.83.114 58226 38.102.83.212 22 2025-10-03 13:05:53.896589 | 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-03 13:05:53.896597 | controller | ZUUL_CHANGE_IDS=58373,1 89,84dfaeffc64ebc0e8b94b5ed9fb3b7733682f44b 58379,2 2025-10-03 13:05:53.896606 | controller | WORKSPACE=/home/zuul/workspace 2025-10-03 13:05:53.896614 | controller | XDG_SESSION_CLASS=user 2025-10-03 13:05:53.896621 | controller | SELINUX_ROLE_REQUESTED= 2025-10-03 13:05:53.896629 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-03 13:05:53.896636 | controller | USER=zuul-worker 2025-10-03 13:05:53.896644 | controller | ZUUL_VOTING=True 2025-10-03 13:05:53.896652 | controller | BUILD_TIMEOUT=5400000 2025-10-03 13:05:53.896659 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-03 13:05:53.896667 | controller | SHLVL=1 2025-10-03 13:05:53.896675 | controller | ZUUL_PATCHSET=2 2025-10-03 13:05:53.896683 | controller | XDG_SESSION_ID=1 2025-10-03 13:05:53.896691 | controller | ZUUL_BRANCH=master 2025-10-03 13:05:53.896699 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-03 13:05:53.896707 | controller | SSH_CLIENT=38.102.83.114 58226 22 2025-10-03 13:05:53.896715 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-03 13:05:53.896723 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-03 13:05:53.896731 | controller | PATH=/home/zuul-worker/.local/bin:/home/zuul-worker/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-03 13:05:53.896739 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-03 13:05:53.896747 | controller | ZUUL_CHANGE=58379 2025-10-03 13:05:53.896755 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-03 13:05:53.896763 | controller | ZUUL_UUID=6fdc2dc9d16141e5bfaf929cc097681a 2025-10-03 13:05:54.044677 | controller | ok: Runtime: 0:00:00.011383 2025-10-03 13:05:54.052580 | 2025-10-03 13:05:54.052662 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-03 13:05:54.423011 | controller | changed 2025-10-03 13:05:54.430727 | 2025-10-03 13:05:54.430832 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-03 13:05:54.963894 | controller | ok: Runtime: 0:00:00.011089 2025-10-03 13:05:54.970310 | 2025-10-03 13:05:54.970404 | TASK [Ensure legacy workspace directory] 2025-10-03 13:05:54.984606 | controller | skipping: Conditional result was False 2025-10-03 13:05:55.007840 | 2025-10-03 13:05:55.007980 | PLAY RECAP 2025-10-03 13:05:55.008060 | controller | ok: 15 changed: 12 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-10-03 13:05:55.008098 | 2025-10-03 13:05:55.129213 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-03 13:05:55.130579 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master] 2025-10-03 13:05:55.717666 | 2025-10-03 13:05:55.717810 | PLAY [all,!compute] 2025-10-03 13:05:55.748507 | 2025-10-03 13:05:55.748660 | TASK [Enable container-tools rhel-modules for c8/crc node] 2025-10-03 13:05:56.279641 | controller | skipping: Conditional result was False 2025-10-03 13:05:56.291848 | 2025-10-03 13:05:56.292058 | TASK [Install podman] 2025-10-03 13:06:57.745716 | controller | changed 2025-10-03 13:06:57.757179 | 2025-10-03 13:06:57.757331 | TASK [Set var name for quay login zuul secret] 2025-10-03 13:06:57.847702 | controller | ok 2025-10-03 13:06:57.856412 | 2025-10-03 13:06:57.856582 | TASK [Print the username] 2025-10-03 13:06:58.357991 | controller | podified-master-centos9+cirobot 2025-10-03 13:06:58.407303 | controller | ok: Runtime: 0:00:00.006068 2025-10-03 13:06:58.416414 | 2025-10-03 13:06:58.416690 | TASK [Fail when user or password is not set] 2025-10-03 13:06:58.474428 | controller | skipping: Conditional result was False 2025-10-03 13:06:58.484258 | 2025-10-03 13:06:58.484409 | TASK [Set vars for quay login] 2025-10-03 13:06:58.537364 | controller | Output suppressed because no_log was given 2025-10-03 13:06:58.544280 | 2025-10-03 13:06:58.544391 | LOOP [Perform container registry login(s) with podman] 2025-10-03 13:06:59.347237 | controller | Output suppressed because no_log was given 2025-10-03 13:06:59.360547 | 2025-10-03 13:06:59.360739 | LOOP [Perform container registry login(s) with buildah] 2025-10-03 13:06:59.444497 | controller | Output suppressed because no_log was given 2025-10-03 13:06:59.461169 | 2025-10-03 13:06:59.461366 | TASK [Set Insecure registry for content provider] 2025-10-03 13:06:59.529421 | controller | skipping: Conditional result was False 2025-10-03 13:06:59.549820 | 2025-10-03 13:06:59.549927 | PLAY RECAP 2025-10-03 13:06:59.549971 | controller | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-10-03 13:06:59.549992 | 2025-10-03 13:06:59.657590 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master] 2025-10-03 13:06:59.658795 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-03 13:07:00.255794 | 2025-10-03 13:07:00.255907 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-10-03 13:07:00.284610 | 2025-10-03 13:07:00.284737 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-10-03 13:07:00.305269 | controller | ok 2025-10-03 13:07:00.322850 | 2025-10-03 13:07:00.322928 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-03 13:07:00.366880 | controller | skipping: Conditional result was False 2025-10-03 13:07:00.372478 | 2025-10-03 13:07:00.372604 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-03 13:07:00.861919 | controller | ok 2025-10-03 13:07:00.875446 | 2025-10-03 13:07:00.875580 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-03 13:07:01.796251 | controller | ok 2025-10-03 13:07:01.823580 | 2025-10-03 13:07:01.823750 | TASK [Prepare workspace] 2025-10-03 13:07:01.846096 | controller | ok 2025-10-03 13:07:01.870817 | 2025-10-03 13:07:01.870977 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-03 13:07:02.322673 | controller | ok 2025-10-03 13:07:02.331247 | 2025-10-03 13:07:02.331381 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-03 13:07:09.961520 | controller | Output suppressed because no_log was given 2025-10-03 13:07:09.975327 | 2025-10-03 13:07:09.975477 | LOOP [Create zuul-output directory] 2025-10-03 13:07:10.265482 | controller | changed: "/home/zuul-worker/ci-framework-data/logs" 2025-10-03 13:07:10.538787 | controller | ok: "/home/zuul-worker/zuul-output/logs" 2025-10-03 13:07:10.555805 | 2025-10-03 13:07:10.555950 | TASK [Install required packages] 2025-10-03 13:07:11.889975 | controller | ok: Nothing to do 2025-10-03 13:07:11.897560 | 2025-10-03 13:07:11.897635 | TASK [Install venv] 2025-10-03 13:08:14.645641 | controller | changed 2025-10-03 13:08:14.703107 | 2025-10-03 13:08:14.703276 | PLAY RECAP 2025-10-03 13:08:14.703362 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-03 13:08:14.703409 | 2025-10-03 13:08:14.860395 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-03 13:08:14.861602 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-03 13:08:15.420288 | 2025-10-03 13:08:15.420393 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-03 13:08:15.441005 | 2025-10-03 13:08:15.441109 | TASK [Create zuul-output directory] 2025-10-03 13:08:15.864775 | controller | changed 2025-10-03 13:08:15.876915 | 2025-10-03 13:08:15.877162 | TASK [Slurp Zuul inventory test] 2025-10-03 13:08:16.225544 | controller -> localhost | ok 2025-10-03 13:08:16.233371 | 2025-10-03 13:08:16.233463 | TASK [Save zuul inventory] 2025-10-03 13:08:17.550644 | controller | changed 2025-10-03 13:08:17.570676 | 2025-10-03 13:08:17.570828 | TASK [Save zuul vars without the change_message] 2025-10-03 13:08:18.636465 | controller | changed 2025-10-03 13:08:18.692861 | 2025-10-03 13:08:18.692990 | PLAY RECAP 2025-10-03 13:08:18.693077 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 13:08:18.693112 | 2025-10-03 13:08:18.854741 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-03 13:08:18.855813 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/edpm_build_images/run.yml@main] 2025-10-03 13:08:19.501809 | 2025-10-03 13:08:19.501932 | PLAY [Run ci/playbooks/edpm_build_images/run.yml] 2025-10-03 13:08:19.523764 | 2025-10-03 13:08:19.523900 | TASK [Filter out host if needed] 2025-10-03 13:08:19.535399 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-03 13:08:19.540753 | 2025-10-03 13:08:19.540863 | TASK [Run EDPM build image] 2025-10-03 13:08:20.678968 | controller | 2025-10-03 13:08:22.677727 | controller | PLAY [Bootstrap playbook] ****************************************************** 2025-10-03 13:08:22.677824 | controller | 2025-10-03 13:08:22.677840 | controller | TASK [Gathering Facts ] ******************************************************** 2025-10-03 13:08:22.677854 | controller | Friday 03 October 2025 13:08:20 +0000 (0:00:00.029) 0:00:00.029 ******** 2025-10-03 13:08:22.677931 | controller | ok: [localhost] 2025-10-03 13:08:22.726943 | controller | 2025-10-03 13:08:22.727029 | controller | TASK [cifmw_setup : Set custom cifmw PATH reusable fact cifmw_path={{ ansible_user_dir }}/.crc/bin:{{ ansible_user_dir }}/.crc/bin/oc:{{ ansible_user_dir }}/bin:{{ ansible_env.PATH }}, cacheable=True] *** 2025-10-03 13:08:22.727059 | controller | Friday 03 October 2025 13:08:22 +0000 (0:00:01.999) 0:00:02.028 ******** 2025-10-03 13:08:22.727089 | controller | ok: [localhost] 2025-10-03 13:08:22.790971 | controller | 2025-10-03 13:08:22.791082 | controller | TASK [cifmw_setup : Get customized parameters ci_framework_params={{ 2025-10-03 13:08:22.791103 | controller | hostvars[inventory_hostname] | 2025-10-03 13:08:22.791121 | controller | dict2items | 2025-10-03 13:08:22.791136 | controller | selectattr("key", "match", 2025-10-03 13:08:22.791152 | controller | "^(cifmw|pre|post)_(?!install_yamls|openshift_token|openshift_login|openshift_kubeconfig).*") | 2025-10-03 13:08:22.791169 | controller | list | items2dict 2025-10-03 13:08:22.791185 | controller | }}] *** 2025-10-03 13:08:22.791200 | controller | Friday 03 October 2025 13:08:22 +0000 (0:00:00.049) 0:00:02.078 ******** 2025-10-03 13:08:22.791235 | controller | ok: [localhost] 2025-10-03 13:08:23.124581 | controller | 2025-10-03 13:08:23.124657 | controller | TASK [install_ca : Ensure target directory exists path={{ cifmw_install_ca_trust_dir }}, state=directory, mode=0755] *** 2025-10-03 13:08:23.124673 | controller | Friday 03 October 2025 13:08:22 +0000 (0:00:00.063) 0:00:02.142 ******** 2025-10-03 13:08:23.124700 | controller | ok: [localhost] 2025-10-03 13:08:23.158731 | controller | 2025-10-03 13:08:23.158802 | controller | TASK [install_ca : Install internal CA from url url={{ cifmw_install_ca_url }}, dest={{ cifmw_install_ca_trust_dir }}, validate_certs={{ cifmw_install_ca_url_validate_certs | default(omit) }}, mode=0644] *** 2025-10-03 13:08:23.158821 | controller | Friday 03 October 2025 13:08:23 +0000 (0:00:00.332) 0:00:02.474 ******** 2025-10-03 13:08:23.158846 | controller | skipping: [localhost] 2025-10-03 13:08:23.197036 | controller | 2025-10-03 13:08:23.197098 | controller | TASK [install_ca : Install custom CA bundle from inline dest={{ cifmw_install_ca_trust_dir }}/cifmw_inline_ca_bundle.crt, content={{ cifmw_install_ca_bundle_inline }}, mode=0644] *** 2025-10-03 13:08:23.197112 | controller | Friday 03 October 2025 13:08:23 +0000 (0:00:00.035) 0:00:02.510 ******** 2025-10-03 13:08:23.197138 | controller | skipping: [localhost] 2025-10-03 13:08:23.238977 | controller | 2025-10-03 13:08:23.239073 | controller | TASK [install_ca : Install custom CA bundle from file dest={{ cifmw_install_ca_trust_dir }}/{{ cifmw_install_ca_bundle_src | basename }}, src={{ cifmw_install_ca_bundle_src }}, mode=0644] *** 2025-10-03 13:08:23.239087 | controller | Friday 03 October 2025 13:08:23 +0000 (0:00:00.038) 0:00:02.548 ******** 2025-10-03 13:08:23.239116 | controller | skipping: [localhost] 2025-10-03 13:08:27.728414 | controller | 2025-10-03 13:08:27.728477 | controller | TASK [install_ca : Update ca bundle _raw_params=update-ca-trust] *************** 2025-10-03 13:08:27.728488 | controller | Friday 03 October 2025 13:08:23 +0000 (0:00:00.041) 0:00:02.590 ******** 2025-10-03 13:08:27.728506 | controller | changed: [localhost] 2025-10-03 13:08:28.429175 | controller | 2025-10-03 13:08:28.429218 | controller | TASK [repo_setup : Ensure directories are present path={{ cifmw_repo_setup_basedir }}/{{ item }}, state=directory, mode=0755] *** 2025-10-03 13:08:28.429226 | controller | Friday 03 October 2025 13:08:27 +0000 (0:00:04.489) 0:00:07.080 ******** 2025-10-03 13:08:28.429238 | controller | changed: [localhost] => (item=tmp) 2025-10-03 13:08:29.233913 | controller | changed: [localhost] => (item=artifacts/repositories) 2025-10-03 13:08:29.233950 | controller | changed: [localhost] => (item=venv/repo_setup) 2025-10-03 13:08:29.233957 | controller | 2025-10-03 13:08:29.233963 | controller | TASK [repo_setup : Make sure git-core package is installed name=git-core, state=present] *** 2025-10-03 13:08:29.233970 | controller | Friday 03 October 2025 13:08:28 +0000 (0:00:00.700) 0:00:07.780 ******** 2025-10-03 13:08:29.233990 | controller | ok: [localhost] 2025-10-03 13:08:29.721590 | controller | 2025-10-03 13:08:29.721625 | controller | TASK [repo_setup : Get repo-setup repository accept_hostkey=True, dest={{ cifmw_repo_setup_basedir }}/tmp/repo-setup, repo={{ cifmw_repo_setup_src }}] *** 2025-10-03 13:08:29.721636 | controller | Friday 03 October 2025 13:08:29 +0000 (0:00:00.804) 0:00:08.584 ******** 2025-10-03 13:08:29.721649 | controller | changed: [localhost] 2025-10-03 13:08:35.699450 | controller | 2025-10-03 13:08:35.699499 | controller | TASK [repo_setup : Initialize python venv and install requirements virtualenv={{ cifmw_repo_setup_venv }}, requirements={{ cifmw_repo_setup_basedir }}/tmp/repo-setup/requirements.txt, virtualenv_command=python3 -m venv --system-site-packages --upgrade-deps] *** 2025-10-03 13:08:35.699511 | controller | Friday 03 October 2025 13:08:29 +0000 (0:00:00.487) 0:00:09.071 ******** 2025-10-03 13:08:35.699527 | controller | changed: [localhost] 2025-10-03 13:08:36.452346 | controller | 2025-10-03 13:08:36.452402 | controller | TASK [repo_setup : Install repo-setup package chdir={{ cifmw_repo_setup_basedir }}/tmp/repo-setup, creates={{ cifmw_repo_setup_venv }}/bin/repo-setup, _raw_params={{ cifmw_repo_setup_venv }}/bin/python setup.py install] *** 2025-10-03 13:08:36.452425 | controller | Friday 03 October 2025 13:08:35 +0000 (0:00:05.979) 0:00:15.050 ******** 2025-10-03 13:08:36.452450 | controller | changed: [localhost] 2025-10-03 13:08:36.485136 | controller | 2025-10-03 13:08:36.485185 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider cifmw_repo_setup_dlrn_hash_tag={{ content_provider_dlrn_md5_hash }}] *** 2025-10-03 13:08:36.485202 | controller | Friday 03 October 2025 13:08:36 +0000 (0:00:00.752) 0:00:15.803 ******** 2025-10-03 13:08:36.485228 | controller | skipping: [localhost] 2025-10-03 13:08:37.251733 | controller | 2025-10-03 13:08:37.251808 | controller | TASK [repo_setup : Run repo-setup _raw_params={{ cifmw_repo_setup_venv }}/bin/repo-setup {{ cifmw_repo_setup_promotion }} {{ cifmw_repo_setup_additional_repos }} -d {{ cifmw_repo_setup_os_release }}{{ cifmw_repo_setup_dist_major_version }} -b {{ cifmw_repo_setup_branch }} --rdo-mirror {{ cifmw_repo_setup_rdo_mirror }} {% if cifmw_repo_setup_dlrn_hash_tag | length > 0 %} --dlrn-hash-tag {{ cifmw_repo_setup_dlrn_hash_tag }} {% endif %} -o {{ cifmw_repo_setup_output }}] *** 2025-10-03 13:08:37.251840 | controller | Friday 03 October 2025 13:08:36 +0000 (0:00:00.032) 0:00:15.836 ******** 2025-10-03 13:08:37.251904 | controller | changed: [localhost] 2025-10-03 13:08:37.306705 | controller | 2025-10-03 13:08:37.306832 | controller | TASK [repo_setup : Get component repo url={{ cifmw_repo_setup_dlrn_uri }}/{{ cifmw_repo_setup_os_release }}{{ cifmw_repo_setup_dist_major_version }}-{{ cifmw_repo_setup_branch }}/component/{{ cifmw_repo_setup_component_name }}/{{ cifmw_repo_setup_component_promotion_tag }}/delorean.repo, dest={{ cifmw_repo_setup_output }}/{{ cifmw_repo_setup_component_name }}_{{ cifmw_repo_setup_component_promotion_tag }}_delorean.repo, mode=0644] *** 2025-10-03 13:08:37.306857 | controller | Friday 03 October 2025 13:08:37 +0000 (0:00:00.766) 0:00:16.602 ******** 2025-10-03 13:08:37.306955 | controller | skipping: [localhost] 2025-10-03 13:08:37.362639 | controller | 2025-10-03 13:08:37.362757 | controller | TASK [repo_setup : Rename component repo path={{ cifmw_repo_setup_output }}/{{ cifmw_repo_setup_component_name }}_{{ cifmw_repo_setup_component_promotion_tag }}_delorean.repo, regexp=delorean-component-{{ cifmw_repo_setup_component_name }}, replace={{ cifmw_repo_setup_component_name }}-{{ cifmw_repo_setup_component_promotion_tag }}] *** 2025-10-03 13:08:37.362777 | controller | Friday 03 October 2025 13:08:37 +0000 (0:00:00.054) 0:00:16.657 ******** 2025-10-03 13:08:37.362809 | controller | skipping: [localhost] 2025-10-03 13:08:37.418885 | controller | 2025-10-03 13:08:37.418927 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo path={{ cifmw_repo_setup_output }}/delorean.repo, section=delorean-component-{{ cifmw_repo_setup_component_name }}, option=enabled, value=0, mode=0644] *** 2025-10-03 13:08:37.418939 | controller | Friday 03 October 2025 13:08:37 +0000 (0:00:00.055) 0:00:16.713 ******** 2025-10-03 13:08:37.418959 | controller | skipping: [localhost] 2025-10-03 13:08:37.940189 | controller | 2025-10-03 13:08:37.940281 | controller | TASK [repo_setup : Run repo-setup-get-hash _raw_params={{ cifmw_repo_setup_venv }}/bin/repo-setup-get-hash --dlrn-url {{ cifmw_repo_setup_dlrn_uri[:-1] }} --os-version {{ cifmw_repo_setup_os_release }}{{ cifmw_repo_setup_dist_major_version }} --release {{ cifmw_repo_setup_branch }} {% if cifmw_repo_setup_component_name | length > 0 -%} --component {{ cifmw_repo_setup_component_name }} --tag {{ cifmw_repo_setup_component_promotion_tag }} {% else -%} --tag {{cifmw_repo_setup_promotion }} {% endif -%} {% if (cifmw_repo_setup_dlrn_hash_tag | length > 0) and (cifmw_repo_setup_component_name | length <= 0) -%} --dlrn-hash-tag {{ cifmw_repo_setup_dlrn_hash_tag }} {% endif -%} --json] *** 2025-10-03 13:08:37.940299 | controller | Friday 03 October 2025 13:08:37 +0000 (0:00:00.056) 0:00:16.769 ******** 2025-10-03 13:08:37.940328 | controller | changed: [localhost] 2025-10-03 13:08:38.630573 | controller | 2025-10-03 13:08:38.630631 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file content={{ _repo_setup_json['full_hash'] }} 2025-10-03 13:08:38.630647 | controller | , dest={{ cifmw_repo_setup_basedir }}/artifacts/repositories/delorean.repo.md5, mode=0644] *** 2025-10-03 13:08:38.630660 | controller | Friday 03 October 2025 13:08:37 +0000 (0:00:00.521) 0:00:17.291 ******** 2025-10-03 13:08:38.630684 | controller | changed: [localhost] 2025-10-03 13:08:38.666537 | controller | 2025-10-03 13:08:38.666610 | controller | TASK [repo_setup : Dump current-podified hash url={{ cifmw_repo_setup_dlrn_uri }}/{{ cifmw_repo_setup_os_release }}{{ cifmw_repo_setup_dist_major_version }}-{{ cifmw_repo_setup_branch }}/current-podified/delorean.repo.md5, dest={{ cifmw_repo_setup_basedir }}/artifacts/repositories/delorean.repo.md5, mode=0644] *** 2025-10-03 13:08:38.666617 | controller | Friday 03 October 2025 13:08:38 +0000 (0:00:00.690) 0:00:17.981 ******** 2025-10-03 13:08:38.666637 | controller | skipping: [localhost] 2025-10-03 13:08:38.699380 | controller | 2025-10-03 13:08:38.699414 | controller | TASK [repo_setup : Slurp current podified hash src={{ cifmw_repo_setup_basedir }}/artifacts/repositories/delorean.repo.md5] *** 2025-10-03 13:08:38.699427 | controller | Friday 03 October 2025 13:08:38 +0000 (0:00:00.036) 0:00:18.017 ******** 2025-10-03 13:08:38.699449 | controller | skipping: [localhost] 2025-10-03 13:08:38.731991 | controller | 2025-10-03 13:08:38.732080 | controller | TASK [repo_setup : Update the value of full_hash _repo_setup_json={{ _repo_setup_json | combine({'full_hash': _hash}, recursive=true) }}] *** 2025-10-03 13:08:38.732096 | controller | Friday 03 October 2025 13:08:38 +0000 (0:00:00.032) 0:00:18.050 ******** 2025-10-03 13:08:38.732126 | controller | skipping: [localhost] 2025-10-03 13:08:38.777981 | controller | 2025-10-03 13:08:38.778081 | controller | TASK [repo_setup : Export hashes facts for further use cifmw_repo_setup_full_hash={{ _repo_setup_json['full_hash'] }}, cifmw_repo_setup_commit_hash={{ _repo_setup_json['commit_hash'] }}, cifmw_repo_setup_distro_hash={{ _repo_setup_json['distro_hash'] }}, cifmw_repo_setup_extended_hash={{ _repo_setup_json['extended_hash'] }}, cifmw_repo_setup_dlrn_api_url={{ _repo_setup_json['dlrn_api_url'] }}, cifmw_repo_setup_dlrn_url={{ _repo_setup_json['dlrn_url'] }}, cifmw_repo_setup_release={{ _repo_setup_json['release'] }}, cacheable=True] *** 2025-10-03 13:08:38.778099 | controller | Friday 03 October 2025 13:08:38 +0000 (0:00:00.032) 0:00:18.083 ******** 2025-10-03 13:08:38.778130 | controller | ok: [localhost] 2025-10-03 13:08:38.803912 | controller | 2025-10-03 13:08:38.804024 | controller | TASK [repo_setup : Create download directory path={{ cifmw_repo_setup_rhos_release_path }}, state=directory, mode=0755] *** 2025-10-03 13:08:38.804041 | controller | Friday 03 October 2025 13:08:38 +0000 (0:00:00.046) 0:00:18.129 ******** 2025-10-03 13:08:38.804070 | controller | skipping: [localhost] 2025-10-03 13:08:38.830940 | controller | 2025-10-03 13:08:38.831026 | controller | TASK [repo_setup : Print the URL to request msg={{ cifmw_repo_setup_rhos_release_rpm }}] *** 2025-10-03 13:08:38.831042 | controller | Friday 03 October 2025 13:08:38 +0000 (0:00:00.025) 0:00:18.155 ******** 2025-10-03 13:08:38.831070 | controller | skipping: [localhost] 2025-10-03 13:08:38.857543 | controller | 2025-10-03 13:08:38.857662 | controller | TASK [Download the RPM name=krb_request] *************************************** 2025-10-03 13:08:38.857678 | controller | Friday 03 October 2025 13:08:38 +0000 (0:00:00.027) 0:00:18.182 ******** 2025-10-03 13:08:38.857708 | controller | skipping: [localhost] 2025-10-03 13:08:38.883679 | controller | 2025-10-03 13:08:38.883775 | controller | TASK [repo_setup : Install RHOS Release tool name={{ 2025-10-03 13:08:38.883791 | controller | cifmw_repo_setup_rhos_release_rpm 2025-10-03 13:08:38.883805 | controller | if cifmw_repo_setup_rhos_release_rpm is not url 2025-10-03 13:08:38.883817 | controller | else cifmw_krb_request_out.path 2025-10-03 13:08:38.883830 | controller | }}, state=present, disable_gpg_check={{ cifmw_repo_setup_rhos_release_gpg_check | bool }}] *** 2025-10-03 13:08:38.883843 | controller | Friday 03 October 2025 13:08:38 +0000 (0:00:00.026) 0:00:18.209 ******** 2025-10-03 13:08:38.883915 | controller | skipping: [localhost] 2025-10-03 13:08:38.911406 | controller | 2025-10-03 13:08:38.911497 | controller | TASK [repo_setup : Get rhos-release tool version _raw_params=rhos-release --version] *** 2025-10-03 13:08:38.911513 | controller | Friday 03 October 2025 13:08:38 +0000 (0:00:00.026) 0:00:18.235 ******** 2025-10-03 13:08:38.911541 | controller | skipping: [localhost] 2025-10-03 13:08:38.943400 | controller | 2025-10-03 13:08:38.943497 | controller | TASK [repo_setup : Print rhos-release tool version msg={{ rr_version.stdout }}] *** 2025-10-03 13:08:38.943513 | controller | Friday 03 October 2025 13:08:38 +0000 (0:00:00.027) 0:00:18.262 ******** 2025-10-03 13:08:38.943543 | controller | skipping: [localhost] 2025-10-03 13:08:38.971895 | controller | 2025-10-03 13:08:38.971992 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }} _raw_params=rhos-release {{ cifmw_repo_setup_rhos_release_args }} \ 2025-10-03 13:08:38.972007 | controller | -t {{ cifmw_repo_setup_output }}] *** 2025-10-03 13:08:38.972021 | controller | Friday 03 October 2025 13:08:38 +0000 (0:00:00.031) 0:00:18.294 ******** 2025-10-03 13:08:38.972050 | controller | skipping: [localhost] 2025-10-03 13:08:39.223410 | controller | 2025-10-03 13:08:39.223501 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh path=/etc/ci/mirror_info.sh] *** 2025-10-03 13:08:39.223517 | controller | Friday 03 October 2025 13:08:38 +0000 (0:00:00.028) 0:00:18.323 ******** 2025-10-03 13:08:39.223573 | controller | ok: [localhost] 2025-10-03 13:08:39.506794 | controller | 2025-10-03 13:08:39.506914 | controller | TASK [repo_setup : Use RDO proxy mirrors chdir={{ cifmw_repo_setup_output }}, _raw_params=set -o pipefail 2025-10-03 13:08:39.506935 | controller | source /etc/ci/mirror_info.sh 2025-10-03 13:08:39.506951 | controller | sed -i -e "s|https://trunk.rdoproject.org|$NODEPOOL_RDO_PROXY|g" *.repo 2025-10-03 13:08:39.506965 | controller | ] *** 2025-10-03 13:08:39.506978 | controller | Friday 03 October 2025 13:08:39 +0000 (0:00:00.251) 0:00:18.574 ******** 2025-10-03 13:08:39.507005 | controller | changed: [localhost] 2025-10-03 13:08:39.555597 | controller | 2025-10-03 13:08:39.555665 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) chdir={{ cifmw_repo_setup_output }}, _raw_params=set -o pipefail 2025-10-03 13:08:39.555675 | controller | source /etc/ci/mirror_info.sh 2025-10-03 13:08:39.555684 | controller | sed -i -e "s|http://mirror.stream.centos.org|$NODEPOOL_CENTOS_MIRROR|g" *.repo 2025-10-03 13:08:39.555693 | controller | ] *** 2025-10-03 13:08:39.555702 | controller | Friday 03 October 2025 13:08:39 +0000 (0:00:00.283) 0:00:18.858 ******** 2025-10-03 13:08:39.555722 | controller | skipping: [localhost] 2025-10-03 13:08:39.583466 | controller | 2025-10-03 13:08:39.583532 | controller | TASK [repo_setup : Check for gating.repo file on content provider url=http://{{ content_provider_registry_ip }}:8766/gating.repo] *** 2025-10-03 13:08:39.583543 | controller | Friday 03 October 2025 13:08:39 +0000 (0:00:00.049) 0:00:18.907 ******** 2025-10-03 13:08:39.583565 | controller | skipping: [localhost] 2025-10-03 13:08:39.615274 | controller | 2025-10-03 13:08:39.615345 | controller | TASK [repo_setup : Populate gating repo from content provider ip content=[gating-repo] 2025-10-03 13:08:39.615361 | controller | baseurl=http://{{ content_provider_registry_ip }}:8766/ 2025-10-03 13:08:39.615374 | controller | enabled=1 2025-10-03 13:08:39.615386 | controller | gpgcheck=0 2025-10-03 13:08:39.615399 | controller | priority=1 2025-10-03 13:08:39.615412 | controller | , dest={{ cifmw_repo_setup_output }}/gating.repo, mode=0644] *** 2025-10-03 13:08:39.615440 | controller | Friday 03 October 2025 13:08:39 +0000 (0:00:00.027) 0:00:18.935 ******** 2025-10-03 13:08:39.615466 | controller | skipping: [localhost] 2025-10-03 13:08:39.651705 | controller | 2025-10-03 13:08:39.651780 | controller | TASK [repo_setup : Check for DLRN repo at the destination path={{ cifmw_repo_setup_output }}/delorean.repo] *** 2025-10-03 13:08:39.651791 | controller | Friday 03 October 2025 13:08:39 +0000 (0:00:00.031) 0:00:18.966 ******** 2025-10-03 13:08:39.651812 | controller | skipping: [localhost] 2025-10-03 13:08:39.692341 | controller | 2025-10-03 13:08:39.692420 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo path={{ cifmw_repo_setup_output }}/delorean.repo, regexp=priority=1, replace=priority=20] *** 2025-10-03 13:08:39.692434 | controller | Friday 03 October 2025 13:08:39 +0000 (0:00:00.036) 0:00:19.003 ******** 2025-10-03 13:08:39.692470 | controller | skipping: [localhost] 2025-10-03 13:08:39.738538 | controller | 2025-10-03 13:08:39.738617 | controller | TASK [repo_setup : Check for DLRN component repo path={{ cifmw_repo_setup_output }}/{{ _comp_repo }}] *** 2025-10-03 13:08:39.738630 | controller | Friday 03 October 2025 13:08:39 +0000 (0:00:00.040) 0:00:19.043 ******** 2025-10-03 13:08:39.738658 | controller | skipping: [localhost] 2025-10-03 13:08:39.774930 | controller | 2025-10-03 13:08:39.774970 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo path={{ cifmw_repo_setup_output }}//{{ _comp_repo }}, regexp=priority=1, replace=priority=2] *** 2025-10-03 13:08:39.774984 | controller | Friday 03 October 2025 13:08:39 +0000 (0:00:00.045) 0:00:19.089 ******** 2025-10-03 13:08:39.775008 | controller | skipping: [localhost] 2025-10-03 13:08:39.812561 | controller | 2025-10-03 13:08:39.812641 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory paths=/etc/yum.repos.d/, patterns=*.repo, recurse=False] *** 2025-10-03 13:08:39.812656 | controller | Friday 03 October 2025 13:08:39 +0000 (0:00:00.036) 0:00:19.125 ******** 2025-10-03 13:08:39.812683 | controller | skipping: [localhost] 2025-10-03 13:08:39.855528 | controller | 2025-10-03 13:08:39.855617 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory path={{ item }}, state=absent] *** 2025-10-03 13:08:39.855631 | controller | Friday 03 October 2025 13:08:39 +0000 (0:00:00.037) 0:00:19.163 ******** 2025-10-03 13:08:39.855658 | controller | skipping: [localhost] 2025-10-03 13:08:39.894539 | controller | 2025-10-03 13:08:39.894612 | controller | TASK [repo_setup : Cleanup existing metadata _raw_params=dnf clean metadata] *** 2025-10-03 13:08:39.894626 | controller | Friday 03 October 2025 13:08:39 +0000 (0:00:00.042) 0:00:19.206 ******** 2025-10-03 13:08:39.894653 | controller | skipping: [localhost] 2025-10-03 13:08:39.939999 | controller | 2025-10-03 13:08:39.940098 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory mode=0755, remote_src=True, src={{ cifmw_repo_setup_output }}/, dest=/etc/yum.repos.d] *** 2025-10-03 13:08:39.940114 | controller | Friday 03 October 2025 13:08:39 +0000 (0:00:00.038) 0:00:19.245 ******** 2025-10-03 13:08:39.940143 | controller | skipping: [localhost] 2025-10-03 13:08:40.003003 | controller | 2025-10-03 13:08:40.003102 | controller | TASK [ci_setup : Gather variables for each operating system _raw_params={{ item }}] *** 2025-10-03 13:08:40.003117 | controller | Friday 03 October 2025 13:08:39 +0000 (0:00:00.045) 0:00:19.290 ******** 2025-10-03 13:08:40.003148 | controller | ok: [localhost] => (item=/home/zuul-worker/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml) 2025-10-03 13:08:40.032966 | controller | 2025-10-03 13:08:40.033060 | controller | TASK [ci_setup : List packages to install var=cifmw_ci_setup_packages] ********* 2025-10-03 13:08:40.033077 | controller | Friday 03 October 2025 13:08:40 +0000 (0:00:00.063) 0:00:19.354 ******** 2025-10-03 13:08:40.033107 | controller | ok: [localhost] => 2025-10-03 13:08:57.143939 | controller | cifmw_ci_setup_packages: 2025-10-03 13:08:57.144019 | controller | - bash-completion 2025-10-03 13:08:57.144033 | controller | - ca-certificates 2025-10-03 13:08:57.144046 | controller | - git-core 2025-10-03 13:08:57.144058 | controller | - make 2025-10-03 13:08:57.144071 | controller | - tar 2025-10-03 13:08:57.144083 | controller | - tmux 2025-10-03 13:08:57.144115 | controller | - python3-pip 2025-10-03 13:08:57.144128 | controller | 2025-10-03 13:08:57.144141 | controller | TASK [ci_setup : Install needed packages name={{ cifmw_ci_setup_packages }}, state=latest] *** 2025-10-03 13:08:57.144154 | controller | Friday 03 October 2025 13:08:40 +0000 (0:00:00.030) 0:00:19.384 ******** 2025-10-03 13:08:57.144181 | controller | changed: [localhost] 2025-10-03 13:08:57.402834 | controller | 2025-10-03 13:08:57.402924 | controller | TASK [ci_setup : Gather version of openshift client _raw_params=oc version --client -o yaml] *** 2025-10-03 13:08:57.402942 | controller | Friday 03 October 2025 13:08:57 +0000 (0:00:17.110) 0:00:36.494 ******** 2025-10-03 13:08:57.402968 | controller | ok: [localhost] 2025-10-03 13:08:57.666779 | controller | 2025-10-03 13:08:57.666845 | controller | TASK [ci_setup : Ensure openshift client install path is present path={{ cifmw_ci_setup_oc_install_path }}, state=directory, mode=0755] *** 2025-10-03 13:08:57.666900 | controller | Friday 03 October 2025 13:08:57 +0000 (0:00:00.258) 0:00:36.753 ******** 2025-10-03 13:08:57.666936 | controller | changed: [localhost] 2025-10-03 13:09:03.027795 | controller | 2025-10-03 13:09:03.027973 | controller | TASK [ci_setup : Install openshift client src={{ cifmw_ci_setup_openshift_client_download_uri }}/{{ cifmw_ci_setup_openshift_client_version }}/openshift-client-linux.tar.gz, dest={{ cifmw_ci_setup_oc_install_path }}, remote_src=True, mode=0755, creates={{ cifmw_ci_setup_oc_install_path }}/oc] *** 2025-10-03 13:09:03.027989 | controller | Friday 03 October 2025 13:08:57 +0000 (0:00:00.263) 0:00:37.017 ******** 2025-10-03 13:09:03.028017 | controller | changed: [localhost] 2025-10-03 13:09:03.068578 | controller | 2025-10-03 13:09:03.068687 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed cifmw_path={{ cifmw_ci_setup_oc_install_path }}:{{ ansible_env.PATH }}, cacheable=True] *** 2025-10-03 13:09:03.068697 | controller | Friday 03 October 2025 13:09:03 +0000 (0:00:05.358) 0:00:42.376 ******** 2025-10-03 13:09:03.068723 | controller | skipping: [localhost] 2025-10-03 13:09:03.524740 | controller | 2025-10-03 13:09:03.524825 | controller | TASK [ci_setup : Create completion file] *************************************** 2025-10-03 13:09:03.524840 | controller | Friday 03 October 2025 13:09:03 +0000 (0:00:00.043) 0:00:42.419 ******** 2025-10-03 13:09:03.524905 | controller | changed: [localhost] 2025-10-03 13:09:03.908272 | controller | 2025-10-03 13:09:03.908345 | controller | TASK [ci_setup : Source completion from within .bashrc create=True, mode=0644, path={{ ansible_user_dir }}/.bashrc, block=if [ -f ~/.oc_completion ]; then 2025-10-03 13:09:03.908355 | controller | source ~/.oc_completion 2025-10-03 13:09:03.908362 | controller | fi] *** 2025-10-03 13:09:03.908372 | controller | Friday 03 October 2025 13:09:03 +0000 (0:00:00.455) 0:00:42.875 ******** 2025-10-03 13:09:03.908394 | controller | changed: [localhost] 2025-10-03 13:09:03.933967 | controller | 2025-10-03 13:09:03.934034 | controller | TASK [ci_setup : Check rhsm status _raw_params=subscription-manager status] **** 2025-10-03 13:09:03.934043 | controller | Friday 03 October 2025 13:09:03 +0000 (0:00:00.384) 0:00:43.259 ******** 2025-10-03 13:09:03.934063 | controller | skipping: [localhost] 2025-10-03 13:09:03.965145 | controller | 2025-10-03 13:09:03.965214 | controller | TASK [ci_setup : Gather the repos to be enabled _repos={{ 2025-10-03 13:09:03.965223 | controller | cifmw_ci_setup_rhel_rhsm_default_repos + 2025-10-03 13:09:03.965231 | controller | (cifmw_ci_setup_rhel_rhsm_extra_repos | default([])) 2025-10-03 13:09:03.965238 | controller | }}] *** 2025-10-03 13:09:03.965246 | controller | Friday 03 October 2025 13:09:03 +0000 (0:00:00.025) 0:00:43.285 ******** 2025-10-03 13:09:03.965266 | controller | skipping: [localhost] 2025-10-03 13:09:03.991710 | controller | 2025-10-03 13:09:03.991786 | controller | TASK [ci_setup : Enabling the required repositories. name={{ item }}, state={{ rhsm_repo_state | default('enabled') }}] *** 2025-10-03 13:09:03.991795 | controller | Friday 03 October 2025 13:09:03 +0000 (0:00:00.031) 0:00:43.316 ******** 2025-10-03 13:09:03.991817 | controller | skipping: [localhost] 2025-10-03 13:09:04.022350 | controller | 2025-10-03 13:09:04.022426 | controller | TASK [ci_setup : Get current /etc/redhat-release _raw_params=cat /etc/redhat-release] *** 2025-10-03 13:09:04.022436 | controller | Friday 03 October 2025 13:09:03 +0000 (0:00:00.026) 0:00:43.343 ******** 2025-10-03 13:09:04.022458 | controller | skipping: [localhost] 2025-10-03 13:09:04.046058 | controller | 2025-10-03 13:09:04.046131 | controller | TASK [ci_setup : Print current /etc/redhat-release msg={{ _current_rh_release.stdout }}] *** 2025-10-03 13:09:04.046140 | controller | Friday 03 October 2025 13:09:04 +0000 (0:00:00.030) 0:00:43.373 ******** 2025-10-03 13:09:04.046163 | controller | skipping: [localhost] 2025-10-03 13:09:04.084428 | controller | 2025-10-03 13:09:04.084491 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum name={{ item.name }}, baseurl={{ item.baseurl }}, description={{ item.description | default(item.name) }}, gpgcheck={{ item.gpgcheck | default(false) }}, enabled=True, state={{ yum_repo_state | default('present') }}] *** 2025-10-03 13:09:04.084502 | controller | Friday 03 October 2025 13:09:04 +0000 (0:00:00.023) 0:00:43.397 ******** 2025-10-03 13:09:04.084519 | controller | skipping: [localhost] 2025-10-03 13:09:05.439990 | controller | 2025-10-03 13:09:05.440117 | controller | TASK [ci_setup : Manage directories path={{ item }}, state={{ directory_state }}, mode=0755, owner={{ ansible_user_id }}, group={{ ansible_user_id }}] *** 2025-10-03 13:09:05.440134 | controller | Friday 03 October 2025 13:09:04 +0000 (0:00:00.038) 0:00:43.436 ******** 2025-10-03 13:09:05.440172 | controller | changed: [localhost] => (item=/home/zuul-worker/ci-framework-data/artifacts/manifests/openstack/cr) 2025-10-03 13:09:05.984008 | controller | ok: [localhost] => (item=/home/zuul-worker/ci-framework-data/logs) 2025-10-03 13:09:05.984095 | controller | ok: [localhost] => (item=/home/zuul-worker/ci-framework-data/tmp) 2025-10-03 13:09:05.984106 | controller | changed: [localhost] => (item=/home/zuul-worker/ci-framework-data/volumes) 2025-10-03 13:09:05.984114 | controller | ok: [localhost] => (item=/home/zuul-worker/ci-framework-data/artifacts/parameters) 2025-10-03 13:09:05.984123 | controller | 2025-10-03 13:09:05.984133 | controller | TASK [cifmw_setup : Create artifacts with custom params mode=0644, dest={{ cifmw_basedir|default(ansible_user_dir ~ '/ci-framework-data') }}/artifacts/parameters/custom-params.yml, content={{ ci_framework_params | to_nice_yaml }}] *** 2025-10-03 13:09:05.984144 | controller | Friday 03 October 2025 13:09:05 +0000 (0:00:01.355) 0:00:44.791 ******** 2025-10-03 13:09:05.984171 | controller | changed: [localhost] 2025-10-03 13:09:07.864550 | controller | 2025-10-03 13:09:07.864603 | controller | PLAY [Run ci/playbooks/edpm_build_images/edpm_image_builder.yml] *************** 2025-10-03 13:09:07.864618 | controller | 2025-10-03 13:09:07.864631 | controller | TASK [Gathering Facts ] ******************************************************** 2025-10-03 13:09:07.864644 | controller | Friday 03 October 2025 13:09:05 +0000 (0:00:00.544) 0:00:45.335 ******** 2025-10-03 13:09:07.864667 | controller | ok: [controller] 2025-10-03 13:09:08.220398 | controller | 2025-10-03 13:09:08.220476 | controller | TASK [Read hash from delorean.repo.md5 file path={{ cifmw_basedir }}/artifacts/repositories/delorean.repo.md5] *** 2025-10-03 13:09:08.220487 | controller | Friday 03 October 2025 13:09:07 +0000 (0:00:01.879) 0:00:47.215 ******** 2025-10-03 13:09:08.220510 | controller | ok: [controller] 2025-10-03 13:09:08.263223 | controller | 2025-10-03 13:09:08.263295 | controller | TASK [Set fact for cifmw_edpm_build_images_tag var cifmw_edpm_build_images_tag={{ file_content['content'] | b64decode | string | trim }}] *** 2025-10-03 13:09:08.263306 | controller | Friday 03 October 2025 13:09:08 +0000 (0:00:00.355) 0:00:47.571 ******** 2025-10-03 13:09:08.263329 | controller | ok: [controller] 2025-10-03 13:09:08.295835 | controller | 2025-10-03 13:09:08.295950 | controller | TASK [edpm_build_images : Add edpm-image-builder to the list of packages to be installed on host cifmw_edpm_build_images_host_packages={{ cifmw_edpm_build_images_host_packages + ['edpm-image-builder'] }}] *** 2025-10-03 13:09:08.295961 | controller | Friday 03 October 2025 13:09:08 +0000 (0:00:00.042) 0:00:47.614 ******** 2025-10-03 13:09:08.295983 | controller | ok: [controller] 2025-10-03 13:10:18.098656 | controller | 2025-10-03 13:10:18.098724 | controller | TASK [edpm_build_images : Install required packages name={{ cifmw_edpm_build_images_host_packages }}, state=latest] *** 2025-10-03 13:10:18.098733 | controller | Friday 03 October 2025 13:09:08 +0000 (0:00:00.032) 0:00:47.646 ******** 2025-10-03 13:10:18.098749 | controller | changed: [controller] 2025-10-03 13:10:18.322662 | controller | 2025-10-03 13:10:18.322718 | controller | TASK [edpm_build_images : Ensure logs/edpm_images directory path={{ cifmw_edpm_build_images_basedir }}/logs/edpm_images, state=directory, mode=0755] *** 2025-10-03 13:10:18.322742 | controller | Friday 03 October 2025 13:10:18 +0000 (0:01:09.802) 0:01:57.449 ******** 2025-10-03 13:10:18.322759 | controller | changed: [controller] 2025-10-03 13:10:18.965207 | controller | 2025-10-03 13:10:18.965296 | controller | TASK [discover_latest_image : Get latest image url={{ cifmw_discover_latest_image_base_url }}, image_prefix={{ cifmw_discover_latest_image_qcow_prefix }}, images_file={{ cifmw_discover_latest_image_images_file }}] *** 2025-10-03 13:10:18.965315 | controller | Friday 03 October 2025 13:10:18 +0000 (0:00:00.224) 0:01:57.673 ******** 2025-10-03 13:10:18.965361 | controller | changed: [controller] 2025-10-03 13:10:19.029925 | controller | 2025-10-03 13:10:19.030008 | controller | TASK [discover_latest_image : Export facts accordingly cifmw_discovered_image_name={{ discovered_image['data']['image_name'] }}, cifmw_discovered_image_url={{ discovered_image['data']['image_url'] }}, cifmw_discovered_hash={{ discovered_image['data']['hash'] }}, cifmw_discovered_hash_algorithm={{ discovered_image['data']['hash_algorithm'] }}, cacheable=True] *** 2025-10-03 13:10:19.030025 | controller | Friday 03 October 2025 13:10:18 +0000 (0:00:00.642) 0:01:58.315 ******** 2025-10-03 13:10:19.030053 | controller | ok: [controller] 2025-10-03 13:10:32.138419 | controller | 2025-10-03 13:10:32.138514 | controller | TASK [edpm_build_images : Download TripleO source image url={{ cifmw_discovered_image_url }}, dest={{ cifmw_edpm_build_images_basedir }}, timeout=20, mode=0644] *** 2025-10-03 13:10:32.138530 | controller | Friday 03 October 2025 13:10:19 +0000 (0:00:00.065) 0:01:58.381 ******** 2025-10-03 13:10:32.138558 | controller | changed: [controller] 2025-10-03 13:10:32.159950 | controller | 2025-10-03 13:10:32.160037 | controller | TASK [edpm_build_images : Check if cert exits path={{ cifmw_edpm_build_images_cert_path }}] *** 2025-10-03 13:10:32.160053 | controller | Friday 03 October 2025 13:10:32 +0000 (0:00:13.108) 0:02:11.489 ******** 2025-10-03 13:10:32.160082 | controller | skipping: [controller] 2025-10-03 13:10:32.184317 | controller | 2025-10-03 13:10:32.184404 | controller | TASK [edpm_build_images : Read the certificates path={{ cifmw_edpm_build_images_cert_path }}, ou_filter={{ cifmw_edpm_build_images_cert_filter_ou | default(omit) }}, cn_filter={{ cifmw_edpm_build_images_cert_filter_cn | default(omit) }}] *** 2025-10-03 13:10:32.184421 | controller | Friday 03 October 2025 13:10:32 +0000 (0:00:00.021) 0:02:11.511 ******** 2025-10-03 13:10:32.184449 | controller | skipping: [controller] 2025-10-03 13:10:32.212939 | controller | 2025-10-03 13:10:32.212992 | controller | TASK [edpm_build_images : Dump the certificates dest={{ _cifmw_edpm_build_images_cert_source }}, content={{ _cifmw_edpm_build_images_certs.certs | join(' 2025-10-03 13:10:32.213007 | controller | ') }}, mode=0644] *** 2025-10-03 13:10:32.213021 | controller | Friday 03 October 2025 13:10:32 +0000 (0:00:00.024) 0:02:11.535 ******** 2025-10-03 13:10:32.213049 | controller | skipping: [controller] 2025-10-03 13:10:32.246249 | controller | 2025-10-03 13:10:32.246298 | controller | TASK [edpm_build_images : Install libguestfs packages name=['libguestfs', 'libguestfs-tools', 'libguestfs-tools-c'], state=present] *** 2025-10-03 13:10:32.246318 | controller | Friday 03 October 2025 13:10:32 +0000 (0:00:00.027) 0:02:11.563 ******** 2025-10-03 13:10:32.246341 | controller | skipping: [controller] 2025-10-03 13:10:32.273574 | controller | 2025-10-03 13:10:32.273626 | controller | TASK [edpm_build_images : Add cert if it exists _raw_params=virt-customize -a {{ cifmw_edpm_build_images_basedir }}/{{ cifmw_discovered_image_name }} --upload {{ _cifmw_edpm_build_images_cert_source }}:{{ cifmw_edpm_build_images_cert_dest }} --run-command 'update-ca-trust' 2025-10-03 13:10:32.273642 | controller | ] *** 2025-10-03 13:10:32.273655 | controller | Friday 03 October 2025 13:10:32 +0000 (0:00:00.033) 0:02:11.597 ******** 2025-10-03 13:10:32.273678 | controller | skipping: [controller] 2025-10-03 13:10:32.742570 | controller | 2025-10-03 13:10:32.742605 | controller | TASK [edpm_build_images : Build EDPM hardened uefi image chdir={{ cifmw_edpm_image_builder_repo_path }}, _raw_params=diskimage-builder {% if ansible_distribution | lower == 'centos' %} images/edpm-hardened-uefi-{{ ansible_distribution | lower }}-{{ ansible_distribution_major_version }}-stream.yaml {% else %} images/edpm-hardened-uefi-rhel-{{ ansible_distribution_major_version }}.yaml {% endif %} > {{ cifmw_edpm_build_images_basedir }}/logs/edpm_images/edpm_hardened_uefi_image_build.log 2> {{ cifmw_edpm_build_images_basedir }}/logs/edpm_images/edpm_hardened_uefi_image_build_err.log] *** 2025-10-03 13:10:32.742624 | controller | Friday 03 October 2025 13:10:32 +0000 (0:00:00.027) 0:02:11.624 ******** 2025-10-03 13:10:32.742640 | controller | task path: /home/zuul-worker/src/github.com/openstack-k8s-operators/ci-framework/roles/edpm_build_images/tasks/run.yml:2 2025-10-03 13:10:32.832904 | controller | fatal: [controller]: FAILED! => 2025-10-03 13:10:32.833032 | controller | changed: true 2025-10-03 13:10:32.833041 | controller | cmd: diskimage-builder images/edpm-hardened-uefi-centos-10-stream.yaml > /home/zuul-worker/ci-framework-data/logs/edpm_images/edpm_hardened_uefi_image_build.log 2025-10-03 13:10:32.833048 | controller | 2> /home/zuul-worker/ci-framework-data/logs/edpm_images/edpm_hardened_uefi_image_build_err.log 2025-10-03 13:10:32.833054 | controller | delta: '0:00:00.170921' 2025-10-03 13:10:32.833059 | controller | end: '2025-10-03 13:10:32.712297' 2025-10-03 13:10:32.833065 | controller | msg: non-zero return code 2025-10-03 13:10:32.833071 | controller | rc: 1 2025-10-03 13:10:32.833077 | controller | start: '2025-10-03 13:10:32.541376' 2025-10-03 13:10:32.833082 | controller | stderr: '' 2025-10-03 13:10:32.833088 | controller | stderr_lines: [] 2025-10-03 13:10:32.833093 | controller | stdout: '' 2025-10-03 13:10:32.833099 | controller | stdout_lines: [] 2025-10-03 13:10:32.833105 | controller | 2025-10-03 13:10:32.833111 | controller | NO MORE HOSTS LEFT ************************************************************* 2025-10-03 13:10:32.833117 | controller | 2025-10-03 13:10:32.833122 | controller | PLAY RECAP ********************************************************************* 2025-10-03 13:10:32.833128 | controller | controller : ok=9 changed=4 unreachable=0 failed=1 skipped=5 rescued=0 ignored=0 2025-10-03 13:10:32.833134 | controller | localhost : ok=26 changed=16 unreachable=0 failed=0 skipped=35 rescued=0 ignored=0 2025-10-03 13:10:32.833140 | controller | 2025-10-03 13:10:32.833146 | controller | Friday 03 October 2025 13:10:32 +0000 (0:00:00.469) 0:02:12.093 ******** 2025-10-03 13:10:32.833153 | controller | =============================================================================== 2025-10-03 13:10:32.833161 | controller | edpm_build_images : Install required packages -------------------------- 69.80s 2025-10-03 13:10:32.833168 | controller | ci_setup : Install needed packages ------------------------------------- 17.11s 2025-10-03 13:10:32.833175 | controller | edpm_build_images : Download TripleO source image ---------------------- 13.11s 2025-10-03 13:10:32.833183 | controller | repo_setup : Initialize python venv and install requirements ------------ 5.98s 2025-10-03 13:10:32.833193 | controller | ci_setup : Install openshift client ------------------------------------- 5.36s 2025-10-03 13:10:32.833201 | controller | install_ca : Update ca bundle ------------------------------------------- 4.49s 2025-10-03 13:10:32.833207 | controller | Gathering Facts --------------------------------------------------------- 2.00s 2025-10-03 13:10:32.833213 | controller | Gathering Facts --------------------------------------------------------- 1.88s 2025-10-03 13:10:32.833218 | controller | ci_setup : Manage directories ------------------------------------------- 1.36s 2025-10-03 13:10:32.833224 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.80s 2025-10-03 13:10:32.833229 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.77s 2025-10-03 13:10:32.833235 | controller | repo_setup : Install repo-setup package --------------------------------- 0.75s 2025-10-03 13:10:32.833240 | controller | repo_setup : Ensure directories are present ----------------------------- 0.70s 2025-10-03 13:10:32.833246 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.69s 2025-10-03 13:10:32.833251 | controller | discover_latest_image : Get latest image -------------------------------- 0.64s 2025-10-03 13:10:32.833256 | controller | cifmw_setup : Create artifacts with custom params ----------------------- 0.54s 2025-10-03 13:10:32.833270 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.52s 2025-10-03 13:10:32.833275 | controller | repo_setup : Get repo-setup repository ---------------------------------- 0.49s 2025-10-03 13:10:32.833281 | controller | edpm_build_images : Build EDPM hardened uefi image ---------------------- 0.47s 2025-10-03 13:10:32.833286 | controller | ci_setup : Create completion file --------------------------------------- 0.46s 2025-10-03 13:10:33.215935 | controller | ERROR 2025-10-03 13:10:33.216104 | controller | { 2025-10-03 13:10:33.216150 | controller | "delta": "0:02:12.845086", 2025-10-03 13:10:33.216180 | controller | "end": "2025-10-03 13:10:32.833654", 2025-10-03 13:10:33.216207 | controller | "msg": "non-zero return code", 2025-10-03 13:10:33.216231 | controller | "rc": 2, 2025-10-03 13:10:33.216256 | controller | "start": "2025-10-03 13:08:19.988568" 2025-10-03 13:10:33.216282 | controller | } failure 2025-10-03 13:10:33.223099 | 2025-10-03 13:10:33.223228 | PLAY RECAP 2025-10-03 13:10:33.223280 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-10-03 13:10:33.223303 | 2025-10-03 13:10:33.351294 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/edpm_build_images/run.yml@main] 2025-10-03 13:10:33.352760 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-03 13:10:33.991786 | 2025-10-03 13:10:33.991951 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-03 13:10:34.014450 | 2025-10-03 13:10:34.014610 | TASK [Filter out host if needed] 2025-10-03 13:10:34.026696 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-03 13:10:34.032093 | 2025-10-03 13:10:34.032235 | TASK [Ensure file is present] 2025-10-03 13:10:34.492308 | controller | ok 2025-10-03 13:10:34.498702 | 2025-10-03 13:10:34.498800 | TASK [Manage molecule report file] 2025-10-03 13:10:35.034394 | controller | skipping: Conditional result was False 2025-10-03 13:10:35.040910 | 2025-10-03 13:10:35.041060 | TASK [Check if we get ci-framework-data basedir] 2025-10-03 13:10:35.393043 | controller | ok 2025-10-03 13:10:35.402577 | 2025-10-03 13:10:35.402744 | TASK [Create ci-framework-data log directory for zuul] 2025-10-03 13:10:35.837320 | controller | changed 2025-10-03 13:10:35.842817 | 2025-10-03 13:10:35.842882 | TASK [Copy ci-framework interesting files] 2025-10-03 13:10:36.276855 | controller | cp: cannot stat '/home/zuul-worker/ci-framework-data/tests': No such file or directory 2025-10-03 13:10:36.376049 | controller | changed 2025-10-03 13:10:36.387107 | 2025-10-03 13:10:36.387242 | TASK [Get SELinux listing] 2025-10-03 13:10:36.933193 | controller | changed 2025-10-03 13:10:36.946324 | 2025-10-03 13:10:36.946491 | TASK [Generate log index] 2025-10-03 13:10:37.881358 | controller | changed 2025-10-03 13:10:37.887210 | 2025-10-03 13:10:37.887306 | TASK [Get some env related data] 2025-10-03 13:10:38.782442 | controller | /home/zuul-worker/.local/bin/ansible 2025-10-03 13:10:39.466326 | controller | dmesg: read kernel buffer failed: Operation not permitted 2025-10-03 13:10:39.923202 | controller | ERROR 2025-10-03 13:10:39.923362 | controller | { 2025-10-03 13:10:39.923394 | controller | "delta": "0:00:01.304123", 2025-10-03 13:10:39.923417 | controller | "end": "2025-10-03 13:10:39.467314", 2025-10-03 13:10:39.923435 | controller | "msg": "non-zero return code", 2025-10-03 13:10:39.923452 | controller | "rc": 1, 2025-10-03 13:10:39.923468 | controller | "start": "2025-10-03 13:10:38.163191" 2025-10-03 13:10:39.923484 | controller | } 2025-10-03 13:10:39.923510 | controller | ERROR: Ignoring Errors 2025-10-03 13:10:39.930361 | 2025-10-03 13:10:39.930456 | TASK [Generate list of logs to collect in home directory] 2025-10-03 13:10:40.435969 | controller | ok: All paths examined 2025-10-03 13:10:40.442828 | 2025-10-03 13:10:40.442965 | LOOP [Copy logs from home directory] 2025-10-03 13:10:40.888099 | controller | changed: 2025-10-03 13:10:40.888390 | controller | { 2025-10-03 13:10:40.888430 | controller | "atime": 1759496878.667382, 2025-10-03 13:10:40.888462 | controller | "ctime": 1759497032.741427, 2025-10-03 13:10:40.888488 | controller | "dev": 64514, 2025-10-03 13:10:40.888511 | controller | "gid": 1000, 2025-10-03 13:10:40.888728 | controller | "gr_name": "zuul-worker", 2025-10-03 13:10:40.888762 | controller | "inode": 5151866, 2025-10-03 13:10:40.888789 | controller | "isblk": false, 2025-10-03 13:10:40.888816 | controller | "ischr": false, 2025-10-03 13:10:40.888840 | controller | "isdir": false, 2025-10-03 13:10:40.888863 | controller | "isfifo": false, 2025-10-03 13:10:40.888889 | controller | "isgid": false, 2025-10-03 13:10:40.888915 | controller | "islnk": false, 2025-10-03 13:10:40.888942 | controller | "isreg": true, 2025-10-03 13:10:40.889003 | controller | "issock": false, 2025-10-03 13:10:40.894056 | controller | "isuid": false, 2025-10-03 13:10:40.894122 | controller | "mode": "0644", 2025-10-03 13:10:40.894161 | controller | "mtime": 1759497032.741427, 2025-10-03 13:10:40.894193 | controller | "nlink": 1, 2025-10-03 13:10:40.894230 | controller | "path": "/home/zuul-worker/ansible.log", 2025-10-03 13:10:40.894263 | controller | "pw_name": "zuul-worker", 2025-10-03 13:10:40.894294 | controller | "rgrp": true, 2025-10-03 13:10:40.894326 | controller | "roth": true, 2025-10-03 13:10:40.894357 | controller | "rusr": true, 2025-10-03 13:10:40.894387 | controller | "size": 47870, 2025-10-03 13:10:40.894519 | controller | "uid": 1000, 2025-10-03 13:10:40.894558 | controller | "wgrp": false, 2025-10-03 13:10:40.894589 | controller | "woth": false, 2025-10-03 13:10:40.894619 | controller | "wusr": true, 2025-10-03 13:10:40.894648 | controller | "xgrp": false, 2025-10-03 13:10:40.894676 | controller | "xoth": false, 2025-10-03 13:10:40.894705 | controller | "xusr": false 2025-10-03 13:10:40.894733 | controller | } 2025-10-03 13:10:40.902976 | 2025-10-03 13:10:40.903163 | TASK [Copy crio stats log file] 2025-10-03 13:10:40.927882 | controller | skipping: Conditional result was False 2025-10-03 13:10:40.934254 | 2025-10-03 13:10:40.934343 | TASK [Get SELinux related data] 2025-10-03 13:10:41.241952 | controller | 2025-10-03 13:10:41.466166 | controller | ERROR 2025-10-03 13:10:41.466430 | controller | { 2025-10-03 13:10:41.466460 | controller | "delta": "0:00:00.008403", 2025-10-03 13:10:41.466480 | controller | "end": "2025-10-03 13:10:41.242981", 2025-10-03 13:10:41.466498 | controller | "msg": "non-zero return code", 2025-10-03 13:10:41.466514 | controller | "rc": 1, 2025-10-03 13:10:41.466531 | controller | "start": "2025-10-03 13:10:41.234578" 2025-10-03 13:10:41.466547 | controller | } 2025-10-03 13:10:41.466571 | controller | ERROR: Ignoring Errors 2025-10-03 13:10:41.472875 | 2025-10-03 13:10:41.472965 | TASK [Create system configuration directory] 2025-10-03 13:10:41.777432 | controller | changed 2025-10-03 13:10:41.789506 | 2025-10-03 13:10:41.789686 | TASK [Get some of the system configurations] 2025-10-03 13:10:42.335534 | controller | changed 2025-10-03 13:10:42.342375 | 2025-10-03 13:10:42.342485 | TASK [Copy generated documentation if available] 2025-10-03 13:10:42.366870 | controller | skipping: Conditional result was False 2025-10-03 13:10:42.374073 | 2025-10-03 13:10:42.374239 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-03 13:10:42.399331 | controller | skipping: Conditional result was False 2025-10-03 13:10:42.406537 | 2025-10-03 13:10:42.406658 | TASK [Compress logs bigger than 2MB] 2025-10-03 13:10:42.949711 | controller | changed 2025-10-03 13:10:42.961528 | 2025-10-03 13:10:42.961753 | TASK [Copy files from workspace on node] 2025-10-03 13:10:42.991410 | controller | ok 2025-10-03 13:10:43.035099 | 2025-10-03 13:10:43.035288 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-03 13:10:43.071366 | controller | skipping: Conditional result was False 2025-10-03 13:10:43.086573 | 2025-10-03 13:10:43.086788 | TASK [fetch-output : Set log path for single node] 2025-10-03 13:10:43.135894 | controller | ok 2025-10-03 13:10:43.147421 | 2025-10-03 13:10:43.147619 | LOOP [fetch-output : Ensure local output dirs] 2025-10-03 13:10:43.481371 | controller -> localhost | ok: "/var/lib/zuul/builds/6fdc2dc9d16141e5bfaf929cc097681a/work/logs" 2025-10-03 13:10:43.481715 | controller -> localhost | changed: All items complete 2025-10-03 13:10:43.481754 | 2025-10-03 13:10:43.744055 | controller -> localhost | changed: "/var/lib/zuul/builds/6fdc2dc9d16141e5bfaf929cc097681a/work/artifacts" 2025-10-03 13:10:43.990575 | controller -> localhost | changed: "/var/lib/zuul/builds/6fdc2dc9d16141e5bfaf929cc097681a/work/docs" 2025-10-03 13:10:44.003237 | 2025-10-03 13:10:44.003363 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-03 13:10:44.850895 | controller | changed: 2025-10-03 13:10:44.851531 | controller | .d..t...... ./ 2025-10-03 13:10:44.851636 | controller | >f+++++++++ README.html 2025-10-03 13:10:44.851705 | controller | >f+++++++++ ansible.log 2025-10-03 13:10:44.851773 | controller | >f+++++++++ dmesg.log 2025-10-03 13:10:44.851834 | controller | >f+++++++++ installed-pkgs.log 2025-10-03 13:10:44.851895 | controller | >f+++++++++ python.log 2025-10-03 13:10:44.851955 | controller | >f+++++++++ registries.conf 2025-10-03 13:10:44.852051 | controller | >f+++++++++ selinux-denials.log 2025-10-03 13:10:44.852131 | controller | >f+++++++++ selinux-listing.log 2025-10-03 13:10:44.852195 | controller | cd+++++++++ ci-framework-data/ 2025-10-03 13:10:44.852258 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-03 13:10:44.852321 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-03 13:10:44.852389 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/ 2025-10-03 13:10:44.852458 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/ 2025-10-03 13:10:44.852524 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/ 2025-10-03 13:10:44.852582 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-03 13:10:44.852642 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/custom-params.yml 2025-10-03 13:10:44.852708 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-10-03 13:10:44.852775 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/ 2025-10-03 13:10:44.852839 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5 2025-10-03 13:10:44.852904 | controller | cd+++++++++ ci-framework-data/logs/ 2025-10-03 13:10:44.852968 | controller | cd+++++++++ ci-framework-data/logs/edpm_images/ 2025-10-03 13:10:44.853077 | controller | >f+++++++++ ci-framework-data/logs/edpm_images/edpm_hardened_uefi_image_build.log 2025-10-03 13:10:44.853150 | controller | >f+++++++++ ci-framework-data/logs/edpm_images/edpm_hardened_uefi_image_build_err.log 2025-10-03 13:10:44.853214 | controller | cd+++++++++ registries.conf.d/ 2025-10-03 13:10:44.853275 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-10-03 13:10:44.853336 | controller | cd+++++++++ system-config/ 2025-10-03 13:10:44.853395 | controller | cd+++++++++ system-config/libvirt/ 2025-10-03 13:10:44.853452 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-10-03 13:10:44.853514 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-10-03 13:10:44.853574 | controller | >f+++++++++ system-config/libvirt/network.conf 2025-10-03 13:10:44.853632 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf 2025-10-03 13:10:44.853728 | controller | >f+++++++++ system-config/libvirt/qemu.conf 2025-10-03 13:10:44.853790 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf 2025-10-03 13:10:44.853850 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf 2025-10-03 13:10:44.853913 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf 2025-10-03 13:10:44.853974 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf 2025-10-03 13:10:44.854099 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf 2025-10-03 13:10:45.384423 | controller | changed: .d..t...... ./ 2025-10-03 13:10:45.887853 | controller | changed: .d..t...... ./ 2025-10-03 13:10:45.907782 | 2025-10-03 13:10:45.907928 | TASK [Return artifact to Zuul] 2025-10-03 13:10:45.943737 | controller | skipping: Conditional result was False 2025-10-03 13:10:45.976756 | 2025-10-03 13:10:45.976893 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-03 13:10:45.977054 | 2025-10-03 13:10:45.977099 | PLAY RECAP 2025-10-03 13:10:45.977154 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 2 2025-10-03 13:10:45.977184 | 2025-10-03 13:10:46.162225 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-03 13:10:46.164647 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master] 2025-10-03 13:10:46.798311 | 2025-10-03 13:10:46.798434 | PLAY [Report DLRN results after job run] 2025-10-03 13:10:46.841967 | 2025-10-03 13:10:46.842172 | TASK [Report job status to DLRN] 2025-10-03 13:10:46.863172 | controller | ok 2025-10-03 13:10:46.887120 | 2025-10-03 13:10:46.887296 | TASK [dlrn_report : Warning if DLRN is not running] 2025-10-03 13:10:46.939806 | controller | ok: DLRN will not be executed if it is a testproject, make sure you set cifmw_dlrn_report_force to true if you want to report to DLRN 2025-10-03 13:10:46.947851 | 2025-10-03 13:10:46.947991 | TASK [dlrn_report : Install dlrnapi-client shyaml package] 2025-10-03 13:10:47.005353 | controller | skipping: Conditional result was False 2025-10-03 13:10:47.018739 | 2025-10-03 13:10:47.018995 | TASK [dlrn_report : Install kinit related package] 2025-10-03 13:10:47.066782 | controller | skipping: Conditional result was False 2025-10-03 13:10:47.080055 | 2025-10-03 13:10:47.080268 | TASK [dlrn_report : Install dlrn kerberos related packages] 2025-10-03 13:10:47.128134 | controller | skipping: Conditional result was False 2025-10-03 13:10:47.149930 | 2025-10-03 13:10:47.150131 | TASK [Set zuul-log-path fact] 2025-10-03 13:10:47.196376 | controller | skipping: Conditional result was False 2025-10-03 13:10:47.204890 | 2025-10-03 13:10:47.205069 | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used] 2025-10-03 13:10:47.260026 | controller | skipping: Conditional result was False 2025-10-03 13:10:47.266177 | 2025-10-03 13:10:47.266279 | TASK [Get hash related data from repo_setup role] 2025-10-03 13:10:47.311118 | controller | skipping: Conditional result was False 2025-10-03 13:10:47.321101 | 2025-10-03 13:10:47.321248 | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication] 2025-10-03 13:10:47.870412 | controller | skipping: Conditional result was False 2025-10-03 13:10:47.883316 | 2025-10-03 13:10:47.883499 | TASK [dlrn_report : Set empty value for dlrnapi password] 2025-10-03 13:10:47.931684 | controller | skipping: Conditional result was False 2025-10-03 13:10:47.944493 | 2025-10-03 13:10:47.944664 | TASK [dlrn_report : Report results to dlrn for the tested hash] 2025-10-03 13:10:48.496438 | controller | skipping: Conditional result was False 2025-10-03 13:10:48.550428 | 2025-10-03 13:10:48.550567 | PLAY RECAP 2025-10-03 13:10:48.550625 | controller | ok: 1 changed: 0 unreachable: 0 failed: 0 skipped: 9 rescued: 0 ignored: 0 2025-10-03 13:10:48.550655 | 2025-10-03 13:10:48.672342 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master] 2025-10-03 13:10:48.674583 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-03 13:10:49.331581 | 2025-10-03 13:10:49.331739 | PLAY [all] 2025-10-03 13:10:49.352386 | 2025-10-03 13:10:49.352542 | TASK [include_role : fetch-output] 2025-10-03 13:10:49.383101 | controller | ok 2025-10-03 13:10:49.400058 | 2025-10-03 13:10:49.400241 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-03 13:10:49.445111 | controller | skipping: Conditional result was False 2025-10-03 13:10:49.453833 | 2025-10-03 13:10:49.453991 | TASK [fetch-output : Set log path for single node] 2025-10-03 13:10:49.496856 | controller | ok 2025-10-03 13:10:49.505392 | 2025-10-03 13:10:49.505528 | LOOP [fetch-output : Ensure local output dirs] 2025-10-03 13:10:50.011839 | controller -> localhost | ok: "/var/lib/zuul/builds/6fdc2dc9d16141e5bfaf929cc097681a/work/logs" 2025-10-03 13:10:50.370214 | controller -> localhost | ok: "/var/lib/zuul/builds/6fdc2dc9d16141e5bfaf929cc097681a/work/artifacts" 2025-10-03 13:10:50.676198 | controller -> localhost | ok: "/var/lib/zuul/builds/6fdc2dc9d16141e5bfaf929cc097681a/work/docs" 2025-10-03 13:10:50.695303 | 2025-10-03 13:10:50.695445 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-03 13:10:51.412061 | controller | ok 2025-10-03 13:10:51.412393 | controller | ok: All items complete 2025-10-03 13:10:51.412446 | 2025-10-03 13:10:52.011381 | controller | ok 2025-10-03 13:10:52.570310 | controller | ok 2025-10-03 13:10:52.595320 | 2025-10-03 13:10:52.595504 | TASK [include_role : fetch-output-openshift] 2025-10-03 13:10:52.621627 | controller | skipping: Conditional result was False 2025-10-03 13:10:52.632685 | 2025-10-03 13:10:52.632853 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-03 13:10:53.102552 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013815 2025-10-03 13:10:53.348320 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.011517 2025-10-03 13:10:53.391476 | 2025-10-03 13:10:53.391607 | PLAY [all] 2025-10-03 13:10:53.409294 | 2025-10-03 13:10:53.409447 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-03 13:10:53.926203 | controller | changed 2025-10-03 13:10:53.972565 | 2025-10-03 13:10:53.972700 | PLAY RECAP 2025-10-03 13:10:53.972756 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-03 13:10:53.972784 | 2025-10-03 13:10:54.080971 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-03 13:10:54.083569 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-03 13:10:54.777848 | 2025-10-03 13:10:54.777982 | PLAY [localhost] 2025-10-03 13:10:54.796566 | 2025-10-03 13:10:54.796682 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-03 13:10:55.201332 | localhost | changed 2025-10-03 13:10:55.206347 | 2025-10-03 13:10:55.206425 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-03 13:10:55.238238 | localhost | ok 2025-10-03 13:10:55.246837 | 2025-10-03 13:10:55.246915 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-03 13:10:55.661981 | localhost | changed 2025-10-03 13:10:55.667052 | 2025-10-03 13:10:55.667121 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-03 13:10:56.437666 | localhost | changed 2025-10-03 13:10:56.451611 | 2025-10-03 13:10:56.451839 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-03 13:10:56.980171 | localhost | Identity added: /var/lib/zuul/builds/6fdc2dc9d16141e5bfaf929cc097681a/work/tmp/ansible.xpsm_0qn (/var/lib/zuul/builds/6fdc2dc9d16141e5bfaf929cc097681a/work/tmp/ansible.xpsm_0qn) 2025-10-03 13:10:56.980527 | localhost | ok: Runtime: 0:00:00.013944 2025-10-03 13:10:56.987472 | 2025-10-03 13:10:56.987631 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-03 13:10:57.327929 | localhost | ok: Runtime: 0:00:00.007466 2025-10-03 13:10:57.333801 | 2025-10-03 13:10:57.333952 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-03 13:10:57.396488 | localhost | changed 2025-10-03 13:10:57.401333 | 2025-10-03 13:10:57.401415 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-03 13:10:57.923839 | localhost | changed 2025-10-03 13:10:57.954235 | 2025-10-03 13:10:57.954369 | PLAY [localhost] 2025-10-03 13:10:57.966802 | 2025-10-03 13:10:57.966876 | TASK [Generate bulk log download script] 2025-10-03 13:10:57.997974 | localhost | ok 2025-10-03 13:10:58.012870 | 2025-10-03 13:10:58.013055 | TASK [local-log-download : Check API endpoint is defined] 2025-10-03 13:10:58.065149 | localhost | ok: All assertions passed 2025-10-03 13:10:58.070823 | 2025-10-03 13:10:58.070912 | TASK [local-log-download : Create download script] 2025-10-03 13:10:58.592692 | localhost -> localhost | changed 2025-10-03 13:10:58.681343 | 2025-10-03 13:10:58.681530 | TASK [Register quick-download link] 2025-10-03 13:10:58.714157 | localhost | ok 2025-10-03 13:10:58.733738 | 2025-10-03 13:10:58.733891 | PLAY [logserver.rdoproject.org] 2025-10-03 13:10:58.747146 | 2025-10-03 13:10:58.747324 | TASK [Set zuul-log-path fact] 2025-10-03 13:10:58.767462 | logserver.rdoproject.org | ok 2025-10-03 13:10:58.779192 | 2025-10-03 13:10:58.779362 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 13:10:58.809969 | logserver.rdoproject.org | ok 2025-10-03 13:10:58.816224 | 2025-10-03 13:10:58.816289 | TASK [upload-logs : Create log directories] 2025-10-03 13:10:59.533913 | logserver.rdoproject.org | changed 2025-10-03 13:10:59.542574 | 2025-10-03 13:10:59.542753 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-03 13:10:59.872153 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.009630 2025-10-03 13:10:59.883892 | 2025-10-03 13:10:59.884087 | TASK [upload-logs : Upload logs to log server] 2025-10-03 13:11:00.699687 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-03 13:11:00.703508 | 2025-10-03 13:11:00.703586 | LOOP [upload-logs : Compress console log and json output] 2025-10-03 13:11:00.767389 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 13:11:00.781891 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 13:11:00.793791 | 2025-10-03 13:11:00.793899 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-03 13:11:00.840751 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 13:11:00.841050 | 2025-10-03 13:11:00.845854 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 13:11:00.852905 | 2025-10-03 13:11:00.853065 | LOOP [upload-logs : Upload console log and json output]