2025-09-29 15:24:36.775874 | Job console starting... 2025-09-29 15:24:36.784815 | Updating repositories 2025-09-29 15:24:37.296006 | Preparing job workspace 2025-09-29 15:24:55.663215 | Running Ansible setup... 2025-09-29 15:25:01.801046 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-09-29 15:25:02.464547 | 2025-09-29 15:25:02.464737 | PLAY [localhost] 2025-09-29 15:25:02.481981 | 2025-09-29 15:25:02.482180 | TASK [Gathering Facts] 2025-09-29 15:25:03.544950 | localhost | ok 2025-09-29 15:25:03.574212 | 2025-09-29 15:25:03.574523 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-29 15:25:04.152692 | localhost -> localhost | changed 2025-09-29 15:25:04.169988 | 2025-09-29 15:25:04.170180 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-29 15:25:05.065460 | localhost -> localhost | changed 2025-09-29 15:25:05.092805 | 2025-09-29 15:25:05.092998 | TASK [Setup log path fact] 2025-09-29 15:25:05.115917 | localhost | ok 2025-09-29 15:25:05.135062 | 2025-09-29 15:25:05.135209 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-29 15:25:05.186435 | localhost | ok 2025-09-29 15:25:05.194677 | 2025-09-29 15:25:05.194791 | TASK [emit-job-header : Print job information] 2025-09-29 15:25:05.233003 | # Job Information 2025-09-29 15:25:05.233183 | Ansible Version: 2.15.12 2025-09-29 15:25:05.233235 | Job: telemetry-openstack-meta-content-provider-master 2025-09-29 15:25:05.233256 | Pipeline: github-check 2025-09-29 15:25:05.233274 | Executor: ze01.softwarefactory-project.io 2025-09-29 15:25:05.233292 | Triggered by: https://github.com/openstack-k8s-operators/telemetry-operator/pull/720 2025-09-29 15:25:05.233310 | Log URL (when completed): https://logserver.rdoproject.org/d91/rdoproject.org/d914f3e0509141f2822ec736e6faa2a1/ 2025-09-29 15:25:05.233328 | Event ID: e47706d0-9d47-11f0-8ca5-8f7be987d0ac 2025-09-29 15:25:05.237809 | 2025-09-29 15:25:05.237896 | LOOP [emit-job-header : Print node information] 2025-09-29 15:25:05.349918 | localhost | ok: 2025-09-29 15:25:05.350149 | localhost | # Node Information 2025-09-29 15:25:05.350184 | localhost | Inventory Hostname: controller 2025-09-29 15:25:05.350207 | localhost | Hostname: np0005459978 2025-09-29 15:25:05.350227 | localhost | Username: zuul-worker 2025-09-29 15:25:05.350247 | localhost | Distro: CentOS 10 2025-09-29 15:25:05.350265 | localhost | Provider: vexxhost-nodepool-tripleo 2025-09-29 15:25:05.350282 | localhost | Region: RegionOne 2025-09-29 15:25:05.350299 | localhost | Label: cloud-centos-10-stream-tripleo-vexxhost 2025-09-29 15:25:05.350316 | localhost | Product Name: OpenStack Nova 2025-09-29 15:25:05.350334 | localhost | Interface IP: 38.102.83.53 2025-09-29 15:25:05.377206 | 2025-09-29 15:25:05.377340 | PLAY [all] 2025-09-29 15:25:05.387785 | 2025-09-29 15:25:05.387921 | TASK [Gather network facts] 2025-09-29 15:25:06.945831 | controller | ok 2025-09-29 15:25:06.982520 | 2025-09-29 15:25:06.982653 | TASK [include_role : start-zuul-console] 2025-09-29 15:25:07.005457 | controller | ok 2025-09-29 15:25:07.025005 | 2025-09-29 15:25:07.025155 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-29 15:25:07.521111 | controller | ok 2025-09-29 15:25:07.542205 | 2025-09-29 15:25:07.542394 | TASK [include_role : add-build-sshkey] 2025-09-29 15:25:07.570820 | controller | ok 2025-09-29 15:25:07.596534 | 2025-09-29 15:25:07.596684 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-29 15:25:07.888998 | controller -> localhost | ok 2025-09-29 15:25:07.895569 | 2025-09-29 15:25:07.895670 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-29 15:25:07.932752 | controller | ok 2025-09-29 15:25:07.947473 | controller | included: /var/lib/zuul/builds/d914f3e0509141f2822ec736e6faa2a1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-29 15:25:07.953920 | 2025-09-29 15:25:07.954000 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-29 15:25:08.488737 | controller -> localhost | Generating public/private rsa key pair. 2025-09-29 15:25:08.488903 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/d914f3e0509141f2822ec736e6faa2a1/work/d914f3e0509141f2822ec736e6faa2a1_id_rsa. 2025-09-29 15:25:08.488933 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/d914f3e0509141f2822ec736e6faa2a1/work/d914f3e0509141f2822ec736e6faa2a1_id_rsa.pub. 2025-09-29 15:25:08.488955 | controller -> localhost | The key fingerprint is: 2025-09-29 15:25:08.488975 | controller -> localhost | SHA256:aBFNcD2eJhKX6PbKZgxD8j3zFeg/SikyL8bXs5/CRfU zuul-build-sshkey 2025-09-29 15:25:08.488995 | controller -> localhost | The key's randomart image is: 2025-09-29 15:25:08.489033 | controller -> localhost | +---[RSA 3072]----+ 2025-09-29 15:25:08.489058 | controller -> localhost | | o=oo | 2025-09-29 15:25:08.489078 | controller -> localhost | | oo+ o . | 2025-09-29 15:25:08.489122 | controller -> localhost | | ..o o + . | 2025-09-29 15:25:08.489141 | controller -> localhost | | . . +oo * E | 2025-09-29 15:25:08.489158 | controller -> localhost | | + oo+S+ . | 2025-09-29 15:25:08.489176 | controller -> localhost | | +.+ o.o | 2025-09-29 15:25:08.489193 | controller -> localhost | | .o=.Bo+ | 2025-09-29 15:25:08.489211 | controller -> localhost | | ++Bo* o. | 2025-09-29 15:25:08.489228 | controller -> localhost | | . =. o*o. | 2025-09-29 15:25:08.489245 | controller -> localhost | +----[SHA256]-----+ 2025-09-29 15:25:08.489285 | controller -> localhost | ok: Runtime: 0:00:00.103076 2025-09-29 15:25:08.495408 | 2025-09-29 15:25:08.495470 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-29 15:25:08.514193 | controller | ok 2025-09-29 15:25:08.526915 | controller | included: /var/lib/zuul/builds/d914f3e0509141f2822ec736e6faa2a1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-29 15:25:08.536262 | 2025-09-29 15:25:08.536369 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-29 15:25:08.550546 | controller | skipping: Conditional result was False 2025-09-29 15:25:08.557352 | 2025-09-29 15:25:08.557456 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-29 15:25:09.071062 | controller | changed 2025-09-29 15:25:09.077929 | 2025-09-29 15:25:09.078088 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-29 15:25:09.374361 | controller | ok 2025-09-29 15:25:09.387407 | 2025-09-29 15:25:09.387597 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-29 15:25:10.472108 | controller | changed 2025-09-29 15:25:10.482831 | 2025-09-29 15:25:10.482938 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-29 15:25:11.406433 | controller | changed 2025-09-29 15:25:11.418063 | 2025-09-29 15:25:11.418197 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-29 15:25:11.434992 | controller | skipping: Conditional result was False 2025-09-29 15:25:11.447778 | 2025-09-29 15:25:11.447922 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-29 15:25:11.845190 | controller -> localhost | changed 2025-09-29 15:25:11.868416 | 2025-09-29 15:25:11.868607 | TASK [add-build-sshkey : Add back temp key] 2025-09-29 15:25:12.216121 | controller -> localhost | Identity added: /var/lib/zuul/builds/d914f3e0509141f2822ec736e6faa2a1/work/d914f3e0509141f2822ec736e6faa2a1_id_rsa (zuul-build-sshkey) 2025-09-29 15:25:12.216328 | controller -> localhost | ok: Runtime: 0:00:00.008057 2025-09-29 15:25:12.248721 | 2025-09-29 15:25:12.248837 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-29 15:25:12.640764 | controller | ok 2025-09-29 15:25:12.653626 | 2025-09-29 15:25:12.653800 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-29 15:25:12.699757 | controller | skipping: Conditional result was False 2025-09-29 15:25:12.714266 | 2025-09-29 15:25:12.714428 | TASK [include_role : validate-host] 2025-09-29 15:25:12.746587 | controller | ok 2025-09-29 15:25:12.774046 | 2025-09-29 15:25:12.774184 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-29 15:25:12.807162 | controller | ok 2025-09-29 15:25:12.816412 | 2025-09-29 15:25:12.816542 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-29 15:25:13.066529 | controller -> localhost | ok 2025-09-29 15:25:13.072930 | 2025-09-29 15:25:13.072992 | TASK [validate-host : Collect information about the host] 2025-09-29 15:25:14.977047 | controller | ok 2025-09-29 15:25:14.992591 | 2025-09-29 15:25:14.992738 | TASK [validate-host : Sanitize hostname] 2025-09-29 15:25:15.063633 | controller | ok 2025-09-29 15:25:15.068898 | 2025-09-29 15:25:15.068965 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-29 15:25:15.499476 | controller -> localhost | changed 2025-09-29 15:25:15.506786 | 2025-09-29 15:25:15.507001 | TASK [validate-host : Collect information about zuul worker] 2025-09-29 15:25:15.925654 | controller | ok 2025-09-29 15:25:15.940231 | 2025-09-29 15:25:15.940448 | TASK [validate-host : Write out all zuul information for each host] 2025-09-29 15:25:16.399651 | controller -> localhost | changed 2025-09-29 15:25:16.410203 | 2025-09-29 15:25:16.410273 | TASK [include_role : prepare-workspace-openshift] 2025-09-29 15:25:16.424460 | controller | skipping: Conditional result was False 2025-09-29 15:25:16.430303 | 2025-09-29 15:25:16.430365 | TASK [include_role : remove-zuul-sshkey] 2025-09-29 15:25:16.454230 | controller | skipping: Conditional result was False 2025-09-29 15:25:16.460147 | 2025-09-29 15:25:16.460214 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-09-29 15:25:16.737239 | controller | ok: "logs" 2025-09-29 15:25:16.738243 | controller | ok: All items complete 2025-09-29 15:25:16.738323 | 2025-09-29 15:25:17.003284 | controller | ok: "artifacts" 2025-09-29 15:25:17.264436 | controller | ok: "docs" 2025-09-29 15:25:17.274606 | 2025-09-29 15:25:17.274706 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-09-29 15:25:17.556851 | controller | changed: "logs" 2025-09-29 15:25:17.817568 | controller | changed: "artifacts" 2025-09-29 15:25:18.082163 | controller | changed: "docs" 2025-09-29 15:25:18.119435 | 2025-09-29 15:25:18.119543 | PLAY RECAP 2025-09-29 15:25:18.119587 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-09-29 15:25:18.119613 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-29 15:25:18.119633 | 2025-09-29 15:25:18.243228 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-09-29 15:25:18.245070 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-09-29 15:25:18.953940 | 2025-09-29 15:25:18.954070 | PLAY [all] 2025-09-29 15:25:18.975188 | 2025-09-29 15:25:18.975296 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-09-29 15:25:19.045064 | controller | ok 2025-09-29 15:25:19.050636 | 2025-09-29 15:25:19.050732 | TASK [mirror-info-fork : Create /etc/ci] 2025-09-29 15:25:19.529532 | controller | changed 2025-09-29 15:25:19.536208 | 2025-09-29 15:25:19.536295 | TASK [mirror-info-fork : Install ci_mirror script] 2025-09-29 15:25:20.875770 | controller | changed 2025-09-29 15:25:20.899094 | 2025-09-29 15:25:20.899232 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-09-29 15:25:21.385967 | controller | changed: 2025-09-29 15:25:21.386418 | controller | { 2025-09-29 15:25:21.386449 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-09-29 15:25:21.386483 | controller | } 2025-09-29 15:25:21.699270 | controller | changed: 2025-09-29 15:25:21.699369 | controller | { 2025-09-29 15:25:21.699395 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-09-29 15:25:21.699415 | controller | } 2025-09-29 15:25:22.034584 | controller | changed: 2025-09-29 15:25:22.034691 | controller | { 2025-09-29 15:25:22.034715 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com" 2025-09-29 15:25:22.034739 | controller | } 2025-09-29 15:25:22.323621 | controller | changed: 2025-09-29 15:25:22.323726 | controller | { 2025-09-29 15:25:22.323753 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-09-29 15:25:22.323772 | controller | } 2025-09-29 15:25:22.646188 | controller | changed: 2025-09-29 15:25:22.646293 | controller | { 2025-09-29 15:25:22.646315 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-09-29 15:25:22.646332 | controller | } 2025-09-29 15:25:23.028751 | controller | changed: 2025-09-29 15:25:23.028881 | controller | { 2025-09-29 15:25:23.028914 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-09-29 15:25:23.028940 | controller | } 2025-09-29 15:25:23.286964 | controller | changed: 2025-09-29 15:25:23.287221 | controller | { 2025-09-29 15:25:23.287279 | 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-09-29 15:25:23.287324 | controller | } 2025-09-29 15:25:23.596463 | controller | changed: 2025-09-29 15:25:23.596546 | controller | { 2025-09-29 15:25:23.596572 | 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-09-29 15:25:23.596592 | controller | } 2025-09-29 15:25:23.912252 | controller | changed: 2025-09-29 15:25:23.912337 | controller | { 2025-09-29 15:25:23.912362 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-09-29 15:25:23.912382 | controller | } 2025-09-29 15:25:24.223953 | controller | changed: 2025-09-29 15:25:24.224076 | controller | { 2025-09-29 15:25:24.224101 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-09-29 15:25:24.224119 | controller | } 2025-09-29 15:25:24.537346 | controller | changed: 2025-09-29 15:25:24.537543 | controller | { 2025-09-29 15:25:24.537593 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-09-29 15:25:24.537632 | controller | } 2025-09-29 15:25:24.827964 | controller | changed: 2025-09-29 15:25:24.828199 | controller | { 2025-09-29 15:25:24.828242 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-09-29 15:25:24.828269 | controller | } 2025-09-29 15:25:25.131146 | controller | changed: 2025-09-29 15:25:25.131252 | controller | { 2025-09-29 15:25:25.131277 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-09-29 15:25:25.131296 | controller | } 2025-09-29 15:25:25.406304 | controller | changed: 2025-09-29 15:25:25.406405 | controller | { 2025-09-29 15:25:25.406437 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-09-29 15:25:25.406462 | controller | } 2025-09-29 15:25:25.686183 | controller | changed: 2025-09-29 15:25:25.686284 | controller | { 2025-09-29 15:25:25.686308 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-09-29 15:25:25.686329 | controller | } 2025-09-29 15:25:26.021542 | controller | changed: 2025-09-29 15:25:26.021649 | controller | { 2025-09-29 15:25:26.021679 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-09-29 15:25:26.021859 | controller | } 2025-09-29 15:25:26.360880 | controller | changed: 2025-09-29 15:25:26.362955 | controller | { 2025-09-29 15:25:26.363091 | 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-09-29 15:25:26.363147 | controller | } 2025-09-29 15:25:26.678338 | controller | changed: 2025-09-29 15:25:26.678650 | controller | { 2025-09-29 15:25:26.678694 | 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-09-29 15:25:26.678733 | controller | } 2025-09-29 15:25:27.003666 | controller | changed: 2025-09-29 15:25:27.003819 | controller | { 2025-09-29 15:25:27.003868 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-09-29 15:25:27.003905 | controller | } 2025-09-29 15:25:27.319906 | controller | changed: 2025-09-29 15:25:27.320056 | controller | { 2025-09-29 15:25:27.320109 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-09-29 15:25:27.320148 | controller | } 2025-09-29 15:25:27.588881 | controller | changed: 2025-09-29 15:25:27.588972 | controller | { 2025-09-29 15:25:27.588996 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-09-29 15:25:27.589029 | controller | } 2025-09-29 15:25:27.905359 | controller | changed: 2025-09-29 15:25:27.905509 | controller | { 2025-09-29 15:25:27.905549 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-09-29 15:25:27.905575 | controller | } 2025-09-29 15:25:28.182855 | controller | changed: 2025-09-29 15:25:28.183219 | controller | { 2025-09-29 15:25:28.183292 | 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-09-29 15:25:28.183339 | controller | } 2025-09-29 15:25:28.528732 | controller | changed: 2025-09-29 15:25:28.528882 | controller | { 2025-09-29 15:25:28.528932 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-09-29 15:25:28.528971 | controller | } 2025-09-29 15:25:28.854113 | controller | changed: 2025-09-29 15:25:28.854226 | controller | { 2025-09-29 15:25:28.854254 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-09-29 15:25:28.854277 | controller | } 2025-09-29 15:25:29.170203 | controller | changed: 2025-09-29 15:25:29.170462 | controller | { 2025-09-29 15:25:29.170521 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-09-29 15:25:29.170561 | controller | } 2025-09-29 15:25:29.212524 | 2025-09-29 15:25:29.212648 | TASK [Set timezone to UTC] 2025-09-29 15:25:29.942508 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-09-29 15:25:29.954728 | 2025-09-29 15:25:29.954884 | TASK [Create nodepool directory] 2025-09-29 15:25:30.381741 | controller | changed 2025-09-29 15:25:30.395765 | 2025-09-29 15:25:30.395934 | TASK [Create nodepool sub_nodes file] 2025-09-29 15:25:31.270859 | controller | changed 2025-09-29 15:25:31.283316 | 2025-09-29 15:25:31.283508 | TASK [Create nodepool sub_nodes_private file] 2025-09-29 15:25:32.180147 | controller | changed 2025-09-29 15:25:32.186166 | 2025-09-29 15:25:32.186272 | LOOP [Populate nodepool sub_nodes file] 2025-09-29 15:25:32.218694 | 2025-09-29 15:25:32.218930 | LOOP [Populate nodepool sub_nodes_private file] 2025-09-29 15:25:32.250486 | 2025-09-29 15:25:32.250665 | TASK [Create nodepool primary file] 2025-09-29 15:25:32.275448 | controller | skipping: Conditional result was False 2025-09-29 15:25:32.281922 | 2025-09-29 15:25:32.282029 | TASK [Create nodepool node_private for this node] 2025-09-29 15:25:33.094129 | controller | changed 2025-09-29 15:25:33.110582 | 2025-09-29 15:25:33.110757 | LOOP [Copy ssh keys to nodepool directory] 2025-09-29 15:25:33.627587 | controller | ok: Item: id_rsa Runtime: 0:00:00.011732 2025-09-29 15:25:33.627772 | 2025-09-29 15:25:33.919961 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.007655 2025-09-29 15:25:33.938180 | 2025-09-29 15:25:33.938796 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-09-29 15:25:34.867112 | controller | changed 2025-09-29 15:25:34.880690 | 2025-09-29 15:25:34.880843 | TASK [Validate sudoers config after edits] 2025-09-29 15:25:35.253923 | controller | /etc/sudoers: parsed OK 2025-09-29 15:25:35.254137 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-09-29 15:25:35.254159 | controller | /etc/sudoers.d/zuul: parsed OK 2025-09-29 15:25:35.254201 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-09-29 15:25:35.430850 | controller | ok: Runtime: 0:00:00.011120 2025-09-29 15:25:35.446147 | 2025-09-29 15:25:35.446310 | TASK [Show the environment passed in to job shell scripts] 2025-09-29 15:25:35.849646 | controller | SHELL=/bin/bash 2025-09-29 15:25:35.849748 | controller | ZUUL_PROJECT=openstack-k8s-operators/telemetry-operator 2025-09-29 15:25:35.849825 | controller | ZUUL_SHORT_PROJECT_NAME=telemetry-operator 2025-09-29 15:25:35.849843 | controller | ZUUL_CHANGES=openstack/cloudkitty:master:refs/changes/68/950868/9^openstack/cloudkitty:master:refs/changes/09/961209/3^openstack/cloudkitty:master:refs/changes/09/961609/2^openstack-k8s-operators/install_yamls:main:refs/changes/88/1088/b94b2b0bc589e16ec8b0788b2abcb24aca116f6e^openstack-k8s-operators/tcib:main:refs/changes/28/328/c796791a0fee6fb5a778f83ec5863f045a9daf68^openstack-k8s-operators/telemetry-operator:main:refs/changes/20/720/4dea54bebd7c25d3d2f63a8fc01aca129af8333f 2025-09-29 15:25:35.849859 | controller | GPG_TTY=not a tty 2025-09-29 15:25:35.849873 | controller | PWD=/home/zuul-worker 2025-09-29 15:25:35.849885 | controller | ZUUL_PIPELINE=github-check 2025-09-29 15:25:35.849898 | controller | LOGNAME=zuul-worker 2025-09-29 15:25:35.849911 | controller | XDG_SESSION_TYPE=tty 2025-09-29 15:25:35.849923 | controller | _=/usr/bin/env 2025-09-29 15:25:35.849936 | controller | MOTD_SHOWN=pam 2025-09-29 15:25:35.849949 | controller | HOME=/home/zuul-worker 2025-09-29 15:25:35.849962 | controller | LANG=en_US.UTF-8 2025-09-29 15:25:35.849974 | controller | SSH_CONNECTION=38.102.83.114 48802 38.102.83.53 22 2025-09-29 15:25:35.849991 | 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-09-29 15:25:35.850004 | controller | ZUUL_CHANGE_IDS=950868,9 961209,3 961609,2 1088,b94b2b0bc589e16ec8b0788b2abcb24aca116f6e 328,c796791a0fee6fb5a778f83ec5863f045a9daf68 720,4dea54bebd7c25d3d2f63a8fc01aca129af8333f 2025-09-29 15:25:35.850016 | controller | WORKSPACE=/home/zuul/workspace 2025-09-29 15:25:35.850029 | controller | XDG_SESSION_CLASS=user 2025-09-29 15:25:35.850043 | controller | SELINUX_ROLE_REQUESTED= 2025-09-29 15:25:35.850059 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-09-29 15:25:35.850076 | controller | USER=zuul-worker 2025-09-29 15:25:35.850092 | controller | ZUUL_VOTING=True 2025-09-29 15:25:35.850108 | controller | BUILD_TIMEOUT=5000000 2025-09-29 15:25:35.850123 | controller | SELINUX_USE_CURRENT_RANGE= 2025-09-29 15:25:35.850140 | controller | SHLVL=1 2025-09-29 15:25:35.850156 | controller | ZUUL_PATCHSET=4dea54bebd7c25d3d2f63a8fc01aca129af8333f 2025-09-29 15:25:35.850172 | controller | XDG_SESSION_ID=1 2025-09-29 15:25:35.850188 | controller | ZUUL_BRANCH=main 2025-09-29 15:25:35.850203 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-09-29 15:25:35.850219 | controller | SSH_CLIENT=38.102.83.114 48802 22 2025-09-29 15:25:35.850236 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-09-29 15:25:35.850252 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-09-29 15:25:35.850269 | controller | PATH=/home/zuul-worker/.local/bin:/home/zuul-worker/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-09-29 15:25:35.850285 | controller | SELINUX_LEVEL_REQUESTED= 2025-09-29 15:25:35.850300 | controller | ZUUL_CHANGE=720 2025-09-29 15:25:35.850357 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-09-29 15:25:35.850374 | controller | ZUUL_UUID=d914f3e0509141f2822ec736e6faa2a1 2025-09-29 15:25:36.002763 | controller | ok: Runtime: 0:00:00.008788 2025-09-29 15:25:36.018819 | 2025-09-29 15:25:36.019086 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-09-29 15:25:36.354892 | controller | changed 2025-09-29 15:25:36.360231 | 2025-09-29 15:25:36.360309 | TASK [Symlink /home/zuul-worker/workspace] 2025-09-29 15:25:36.892881 | controller | ok: Runtime: 0:00:00.010161 2025-09-29 15:25:36.908747 | 2025-09-29 15:25:36.908987 | TASK [Ensure legacy workspace directory] 2025-09-29 15:25:36.937005 | controller | skipping: Conditional result was False 2025-09-29 15:25:36.981287 | 2025-09-29 15:25:36.981740 | PLAY RECAP 2025-09-29 15:25:36.981830 | controller | ok: 15 changed: 12 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-09-29 15:25:36.981866 | 2025-09-29 15:25:37.108236 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-09-29 15:25:37.109559 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/read_global_vars.yml@main] 2025-09-29 15:25:37.731265 | 2025-09-29 15:25:37.731399 | PLAY [Load global variables] 2025-09-29 15:25:37.755597 | 2025-09-29 15:25:37.755700 | TASK [Read group_vars all file] 2025-09-29 15:25:37.775042 | controller | ok 2025-09-29 15:25:37.790553 | 2025-09-29 15:25:37.790630 | TASK [cifmw_helpers : Check if file is available] 2025-09-29 15:25:38.251302 | controller | ok 2025-09-29 15:25:38.259537 | 2025-09-29 15:25:38.259661 | TASK [cifmw_helpers : Read vars] 2025-09-29 15:25:38.296567 | 2025-09-29 15:25:38.296830 | LOOP [cifmw_helpers : Set vars as fact] 2025-09-29 15:25:38.414841 | 2025-09-29 15:25:38.415053 | PLAY RECAP 2025-09-29 15:25:38.415119 | controller | ok: 1 changed: 0 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-09-29 15:25:38.415151 | 2025-09-29 15:25:38.519244 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/read_global_vars.yml@main] 2025-09-29 15:25:38.521110 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-prepare.yml@main] 2025-09-29 15:25:39.191427 | 2025-09-29 15:25:39.191561 | PLAY [Run ci/playbooks/e2e-prepare.yml] 2025-09-29 15:25:39.214316 | 2025-09-29 15:25:39.214465 | TASK [Clone repos in the job workspace] 2025-09-29 15:25:39.243889 | controller | ok 2025-09-29 15:25:39.261473 | 2025-09-29 15:25:39.261613 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-09-29 15:25:39.737395 | controller | ok 2025-09-29 15:25:39.751675 | 2025-09-29 15:25:39.751880 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-09-29 15:25:57.282547 | controller | Output suppressed because no_log was given 2025-09-29 15:25:57.295697 | 2025-09-29 15:25:57.295786 | TASK [Create zuul-output directory] 2025-09-29 15:25:57.760860 | controller | ok 2025-09-29 15:25:57.768306 | 2025-09-29 15:25:57.768498 | TASK [Install required packages] 2025-09-29 15:26:04.002579 | controller | ERROR 2025-09-29 15:26:04.002826 | controller | { 2025-09-29 15:26:04.002881 | controller | "msg": "Failed to download metadata for repo 'baseos': Cannot download repomd.xml: Cannot download repodata/repomd.xml: All mirrors were tried", 2025-09-29 15:26:04.002917 | controller | "rc": 1, 2025-09-29 15:26:04.002949 | controller | "results": [] 2025-09-29 15:26:04.002991 | controller | } failure 2025-09-29 15:26:04.005350 | 2025-09-29 15:26:04.005424 | PLAY RECAP 2025-09-29 15:26:04.005524 | controller | ok: 3 changed: 1 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-29 15:26:04.005561 | 2025-09-29 15:26:04.147977 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-prepare.yml@main] 2025-09-29 15:26:04.149496 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-09-29 15:26:04.823996 | 2025-09-29 15:26:04.824130 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-09-29 15:26:04.849940 | 2025-09-29 15:26:04.850124 | TASK [Filter out host if needed] 2025-09-29 15:26:04.859383 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-09-29 15:26:04.864118 | 2025-09-29 15:26:04.864183 | TASK [Ensure file is present] 2025-09-29 15:26:05.323682 | controller | ok 2025-09-29 15:26:05.338269 | 2025-09-29 15:26:05.338434 | TASK [Manage molecule report file] 2025-09-29 15:26:05.894578 | controller | skipping: Conditional result was False 2025-09-29 15:26:05.908159 | 2025-09-29 15:26:05.908350 | TASK [Check if we get ci-framework-data basedir] 2025-09-29 15:26:06.194443 | controller | ok 2025-09-29 15:26:06.213656 | 2025-09-29 15:26:06.213900 | TASK [Create ci-framework-data log directory for zuul] 2025-09-29 15:26:06.232122 | controller | skipping: Conditional result was False 2025-09-29 15:26:06.244308 | 2025-09-29 15:26:06.244432 | TASK [Copy ci-framework interesting files] 2025-09-29 15:26:06.784925 | controller | skipping: Conditional result was False 2025-09-29 15:26:06.791808 | 2025-09-29 15:26:06.791914 | TASK [Get SELinux listing] 2025-09-29 15:26:07.331206 | controller | skipping: Conditional result was False 2025-09-29 15:26:07.346296 | 2025-09-29 15:26:07.346505 | TASK [Generate log index] 2025-09-29 15:26:07.375905 | controller | skipping: Conditional result was False 2025-09-29 15:26:07.389169 | 2025-09-29 15:26:07.389385 | TASK [Get some env related data] 2025-09-29 15:26:09.115183 | controller | dmesg: read kernel buffer failed: Operation not permitted 2025-09-29 15:26:09.448146 | controller | ERROR 2025-09-29 15:26:09.448634 | controller | { 2025-09-29 15:26:09.448701 | controller | "delta": "0:00:01.290408", 2025-09-29 15:26:09.448742 | controller | "end": "2025-09-29 15:26:09.116299", 2025-09-29 15:26:09.448779 | controller | "msg": "non-zero return code", 2025-09-29 15:26:09.448820 | controller | "rc": 1, 2025-09-29 15:26:09.448856 | controller | "start": "2025-09-29 15:26:07.825891" 2025-09-29 15:26:09.448891 | controller | } 2025-09-29 15:26:09.448938 | controller | ERROR: Ignoring Errors 2025-09-29 15:26:09.460360 | 2025-09-29 15:26:09.460561 | TASK [Generate list of logs to collect in home directory] 2025-09-29 15:26:09.899961 | controller | ok: All paths examined 2025-09-29 15:26:09.912183 | 2025-09-29 15:26:09.912367 | LOOP [Copy logs from home directory] 2025-09-29 15:26:09.956301 | 2025-09-29 15:26:09.956600 | TASK [Copy crio stats log file] 2025-09-29 15:26:09.984110 | controller | skipping: Conditional result was False 2025-09-29 15:26:09.997348 | 2025-09-29 15:26:09.997482 | TASK [Get SELinux related data] 2025-09-29 15:26:10.399208 | controller | 2025-09-29 15:26:10.578457 | controller | ERROR 2025-09-29 15:26:10.578605 | controller | { 2025-09-29 15:26:10.578643 | controller | "delta": "0:00:00.011263", 2025-09-29 15:26:10.578670 | controller | "end": "2025-09-29 15:26:10.400615", 2025-09-29 15:26:10.578695 | controller | "msg": "non-zero return code", 2025-09-29 15:26:10.578720 | controller | "rc": 1, 2025-09-29 15:26:10.578763 | controller | "start": "2025-09-29 15:26:10.389352" 2025-09-29 15:26:10.578791 | controller | } 2025-09-29 15:26:10.578829 | controller | ERROR: Ignoring Errors 2025-09-29 15:26:10.586051 | 2025-09-29 15:26:10.586162 | TASK [Create system configuration directory] 2025-09-29 15:26:11.021562 | controller | changed 2025-09-29 15:26:11.032851 | 2025-09-29 15:26:11.032987 | TASK [Get some of the system configurations] 2025-09-29 15:26:11.352303 | controller | cp: cannot stat '/etc/libvirt/*.conf': No such file or directory 2025-09-29 15:26:11.357229 | controller | cp: cannot stat '/etc/containers/registries.conf': No such file or directory 2025-09-29 15:26:11.358661 | controller | cp: cannot stat '/etc/containers/registries.conf.d': No such file or directory 2025-09-29 15:26:11.359777 | controller | chown: cannot access '/home/zuul-worker/zuul-output/logs/registries.conf.d': No such file or directory 2025-09-29 15:26:11.583602 | controller | ERROR 2025-09-29 15:26:11.583897 | controller | { 2025-09-29 15:26:11.583955 | controller | "delta": "0:00:00.015026", 2025-09-29 15:26:11.583995 | controller | "end": "2025-09-29 15:26:11.360105", 2025-09-29 15:26:11.584078 | controller | "msg": "non-zero return code", 2025-09-29 15:26:11.584117 | controller | "rc": 1, 2025-09-29 15:26:11.584153 | controller | "start": "2025-09-29 15:26:11.345079" 2025-09-29 15:26:11.584188 | controller | } 2025-09-29 15:26:11.584234 | controller | ERROR: Ignoring Errors 2025-09-29 15:26:11.596134 | 2025-09-29 15:26:11.596280 | TASK [Copy generated documentation if available] 2025-09-29 15:26:11.623326 | controller | skipping: Conditional result was False 2025-09-29 15:26:11.637203 | 2025-09-29 15:26:11.637384 | TASK [Copy generated AsciiDoc documentation if available] 2025-09-29 15:26:11.664715 | controller | skipping: Conditional result was False 2025-09-29 15:26:11.673167 | 2025-09-29 15:26:11.673271 | TASK [Compress logs bigger than 2MB] 2025-09-29 15:26:12.215213 | controller | changed 2025-09-29 15:26:12.227629 | 2025-09-29 15:26:12.227759 | TASK [Copy files from workspace on node] 2025-09-29 15:26:12.255191 | controller | ok 2025-09-29 15:26:12.299987 | 2025-09-29 15:26:12.300145 | TASK [fetch-output : Set log path for multiple nodes] 2025-09-29 15:26:12.326397 | controller | skipping: Conditional result was False 2025-09-29 15:26:12.336207 | 2025-09-29 15:26:12.336336 | TASK [fetch-output : Set log path for single node] 2025-09-29 15:26:12.379542 | controller | ok 2025-09-29 15:26:12.387340 | 2025-09-29 15:26:12.387435 | LOOP [fetch-output : Ensure local output dirs] 2025-09-29 15:26:12.655995 | controller -> localhost | ok: "/var/lib/zuul/builds/d914f3e0509141f2822ec736e6faa2a1/work/logs" 2025-09-29 15:26:12.656276 | 2025-09-29 15:26:12.886367 | controller -> localhost | changed: "/var/lib/zuul/builds/d914f3e0509141f2822ec736e6faa2a1/work/artifacts" 2025-09-29 15:26:13.116830 | controller -> localhost | changed: "/var/lib/zuul/builds/d914f3e0509141f2822ec736e6faa2a1/work/docs" 2025-09-29 15:26:13.133398 | 2025-09-29 15:26:13.133525 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-09-29 15:26:13.822569 | controller | changed: 2025-09-29 15:26:13.822804 | controller | .d..t...... ./ 2025-09-29 15:26:13.822834 | controller | >f+++++++++ dmesg.log 2025-09-29 15:26:13.822854 | controller | >f+++++++++ installed-pkgs.log 2025-09-29 15:26:13.822873 | controller | >f+++++++++ python.log 2025-09-29 15:26:13.822890 | controller | >f+++++++++ selinux-denials.log 2025-09-29 15:26:13.822906 | controller | cd+++++++++ system-config/ 2025-09-29 15:26:13.822922 | controller | cd+++++++++ system-config/libvirt/ 2025-09-29 15:26:14.293569 | controller | changed: .d..t...... ./ 2025-09-29 15:26:14.816552 | controller | changed: .d..t...... ./ 2025-09-29 15:26:14.846534 | 2025-09-29 15:26:14.846723 | TASK [Return artifact to Zuul] 2025-09-29 15:26:14.874046 | controller | skipping: Conditional result was False 2025-09-29 15:26:14.905746 | 2025-09-29 15:26:14.905863 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-09-29 15:26:14.905993 | 2025-09-29 15:26:14.906066 | PLAY RECAP 2025-09-29 15:26:14.906123 | controller | ok: 11 changed: 7 unreachable: 0 failed: 0 skipped: 11 rescued: 0 ignored: 3 2025-09-29 15:26:14.906150 | 2025-09-29 15:26:15.026426 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-09-29 15:26:15.029344 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-09-29 15:26:15.658143 | 2025-09-29 15:26:15.658256 | PLAY [all] 2025-09-29 15:26:15.678766 | 2025-09-29 15:26:15.678894 | TASK [include_role : fetch-output] 2025-09-29 15:26:15.719164 | controller | ok 2025-09-29 15:26:15.737427 | 2025-09-29 15:26:15.737555 | TASK [fetch-output : Set log path for multiple nodes] 2025-09-29 15:26:15.813350 | controller | skipping: Conditional result was False 2025-09-29 15:26:15.826465 | 2025-09-29 15:26:15.826634 | TASK [fetch-output : Set log path for single node] 2025-09-29 15:26:15.895233 | controller | ok 2025-09-29 15:26:15.906656 | 2025-09-29 15:26:15.906835 | LOOP [fetch-output : Ensure local output dirs] 2025-09-29 15:26:16.424186 | controller -> localhost | ok: "/var/lib/zuul/builds/d914f3e0509141f2822ec736e6faa2a1/work/logs" 2025-09-29 15:26:16.687077 | controller -> localhost | ok: "/var/lib/zuul/builds/d914f3e0509141f2822ec736e6faa2a1/work/artifacts" 2025-09-29 15:26:16.928966 | controller -> localhost | ok: "/var/lib/zuul/builds/d914f3e0509141f2822ec736e6faa2a1/work/docs" 2025-09-29 15:26:16.946225 | 2025-09-29 15:26:16.946350 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-09-29 15:26:17.647863 | controller | ok 2025-09-29 15:26:17.648130 | controller | ok: All items complete 2025-09-29 15:26:17.648166 | 2025-09-29 15:26:18.219099 | controller | ok 2025-09-29 15:26:18.735675 | controller | ok 2025-09-29 15:26:18.781375 | 2025-09-29 15:26:18.781613 | TASK [include_role : fetch-output-openshift] 2025-09-29 15:26:18.808860 | controller | skipping: Conditional result was False 2025-09-29 15:26:18.818391 | 2025-09-29 15:26:18.818528 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-09-29 15:26:19.275836 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.010966 2025-09-29 15:26:19.527622 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.010478 2025-09-29 15:26:19.563667 | 2025-09-29 15:26:19.563764 | PLAY [all] 2025-09-29 15:26:19.578683 | 2025-09-29 15:26:19.578754 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-29 15:26:20.093700 | controller | changed 2025-09-29 15:26:20.141512 | 2025-09-29 15:26:20.141648 | PLAY RECAP 2025-09-29 15:26:20.141709 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-09-29 15:26:20.141739 | 2025-09-29 15:26:20.254913 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-09-29 15:26:20.257893 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-09-29 15:26:20.959843 | 2025-09-29 15:26:20.959953 | PLAY [localhost] 2025-09-29 15:26:20.977451 | 2025-09-29 15:26:20.977540 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-29 15:26:21.336292 | localhost | changed 2025-09-29 15:26:21.341420 | 2025-09-29 15:26:21.341499 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-29 15:26:21.369603 | localhost | ok 2025-09-29 15:26:21.378174 | 2025-09-29 15:26:21.378286 | TASK [add-fileserver : Create SSH private key tempfile] 2025-09-29 15:26:21.797702 | localhost | changed 2025-09-29 15:26:21.809956 | 2025-09-29 15:26:21.810174 | TASK [add-fileserver : Create SSH private key from secret] 2025-09-29 15:26:22.548443 | localhost | changed 2025-09-29 15:26:22.561295 | 2025-09-29 15:26:22.561491 | TASK [add-fileserver : Add fileserver ssh key] 2025-09-29 15:26:23.004832 | localhost | Identity added: /var/lib/zuul/builds/d914f3e0509141f2822ec736e6faa2a1/work/tmp/ansible.oqz1hwzq (/var/lib/zuul/builds/d914f3e0509141f2822ec736e6faa2a1/work/tmp/ansible.oqz1hwzq) 2025-09-29 15:26:23.005059 | localhost | ok: Runtime: 0:00:00.008751 2025-09-29 15:26:23.010807 | 2025-09-29 15:26:23.010902 | TASK [add-fileserver : Remove SSH private key from disk] 2025-09-29 15:26:23.298746 | localhost | ok: Runtime: 0:00:00.024184 2025-09-29 15:26:23.306327 | 2025-09-29 15:26:23.306424 | TASK [add-fileserver : Add fileserver to inventory] 2025-09-29 15:26:23.370146 | localhost | changed 2025-09-29 15:26:23.377165 | 2025-09-29 15:26:23.377265 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-09-29 15:26:23.817457 | localhost | changed 2025-09-29 15:26:23.839227 | 2025-09-29 15:26:23.839321 | PLAY [localhost] 2025-09-29 15:26:23.850849 | 2025-09-29 15:26:23.850917 | TASK [Generate bulk log download script] 2025-09-29 15:26:23.870305 | localhost | ok 2025-09-29 15:26:23.910282 | 2025-09-29 15:26:23.910390 | TASK [local-log-download : Check API endpoint is defined] 2025-09-29 15:26:23.960617 | localhost | ok: All assertions passed 2025-09-29 15:26:23.967248 | 2025-09-29 15:26:23.967335 | TASK [local-log-download : Create download script] 2025-09-29 15:26:24.468931 | localhost -> localhost | changed 2025-09-29 15:26:24.478471 | 2025-09-29 15:26:24.478691 | TASK [Register quick-download link] 2025-09-29 15:26:24.497406 | localhost | ok 2025-09-29 15:26:24.520905 | 2025-09-29 15:26:24.521011 | PLAY [logserver.rdoproject.org] 2025-09-29 15:26:24.532053 | 2025-09-29 15:26:24.532169 | TASK [Set zuul-log-path fact] 2025-09-29 15:26:24.550334 | logserver.rdoproject.org | ok 2025-09-29 15:26:24.562856 | 2025-09-29 15:26:24.562991 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-29 15:26:24.594393 | logserver.rdoproject.org | ok 2025-09-29 15:26:24.600002 | 2025-09-29 15:26:24.600130 | TASK [upload-logs : Create log directories] 2025-09-29 15:26:25.440627 | logserver.rdoproject.org | changed 2025-09-29 15:26:25.452354 | 2025-09-29 15:26:25.452558 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-29 15:26:25.788899 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.009525 2025-09-29 15:26:25.797686 | 2025-09-29 15:26:25.797800 | TASK [upload-logs : Upload logs to log server] 2025-09-29 15:26:26.535137 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-09-29 15:26:26.539420 | 2025-09-29 15:26:26.539508 | LOOP [upload-logs : Compress console log and json output] 2025-09-29 15:26:26.606128 | logserver.rdoproject.org | skipping: Conditional result was False 2025-09-29 15:26:26.615582 | logserver.rdoproject.org | skipping: Conditional result was False 2025-09-29 15:26:26.621643 | 2025-09-29 15:26:26.621744 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-29 15:26:26.660252 | logserver.rdoproject.org | skipping: Conditional result was False 2025-09-29 15:26:26.660536 | 2025-09-29 15:26:26.663750 | logserver.rdoproject.org | skipping: Conditional result was False 2025-09-29 15:26:26.670709 | 2025-09-29 15:26:26.670797 | LOOP [upload-logs : Upload console log and json output]