2025-10-01 10:43:04.783910 | Job console starting... 2025-10-01 10:43:04.806092 | Updating repositories 2025-10-01 10:43:06.509347 | Preparing job workspace 2025-10-01 10:43:38.652972 | Running Ansible setup... 2025-10-01 10:43:46.274749 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-01 10:43:47.713455 | 2025-10-01 10:43:47.713631 | PLAY [localhost] 2025-10-01 10:43:47.729675 | 2025-10-01 10:43:47.729833 | TASK [Gathering Facts] 2025-10-01 10:43:49.912408 | localhost | ok 2025-10-01 10:43:49.930774 | 2025-10-01 10:43:49.930894 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-01 10:43:50.658725 | localhost -> localhost | changed 2025-10-01 10:43:50.664323 | 2025-10-01 10:43:50.664469 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-01 10:43:52.301728 | localhost -> localhost | changed 2025-10-01 10:43:52.310818 | 2025-10-01 10:43:52.310924 | TASK [Setup log path fact] 2025-10-01 10:43:52.346918 | localhost | ok 2025-10-01 10:43:52.384005 | 2025-10-01 10:43:52.384174 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-01 10:43:52.437856 | localhost | ok 2025-10-01 10:43:52.457448 | 2025-10-01 10:43:52.457577 | TASK [emit-job-header : Print job information] 2025-10-01 10:43:52.535401 | # Job Information 2025-10-01 10:43:52.535633 | Ansible Version: 2.15.12 2025-10-01 10:43:52.535660 | Job: openstack-k8s-operators-content-provider 2025-10-01 10:43:52.535683 | Pipeline: github-check 2025-10-01 10:43:52.535703 | Executor: ze01.softwarefactory-project.io 2025-10-01 10:43:52.535721 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3237 2025-10-01 10:43:52.535740 | Log URL (when completed): https://logserver.rdoproject.org/0fa/rdoproject.org/0fa41b4bf3d84143b87ab9d58e76ea01/ 2025-10-01 10:43:52.535758 | Event ID: 2c962f60-9eb3-11f0-844e-f06c90bcb25f 2025-10-01 10:43:52.539929 | 2025-10-01 10:43:52.540061 | LOOP [emit-job-header : Print node information] 2025-10-01 10:43:52.869872 | localhost | ok: 2025-10-01 10:43:52.870103 | localhost | # Node Information 2025-10-01 10:43:52.870134 | localhost | Inventory Hostname: controller 2025-10-01 10:43:52.870158 | localhost | Hostname: np0005464315 2025-10-01 10:43:52.870180 | localhost | Username: zuul 2025-10-01 10:43:52.870201 | localhost | Distro: CentOS 9 2025-10-01 10:43:52.870227 | localhost | Provider: vexxhost-nodepool-tripleo 2025-10-01 10:43:52.870255 | localhost | Region: RegionOne 2025-10-01 10:43:52.870279 | localhost | Label: cloud-centos-9-stream-tripleo-vexxhost 2025-10-01 10:43:52.870501 | localhost | Product Name: OpenStack Nova 2025-10-01 10:43:52.870527 | localhost | Interface IP: 38.102.83.30 2025-10-01 10:43:52.942792 | 2025-10-01 10:43:52.942902 | PLAY [all] 2025-10-01 10:43:52.958856 | 2025-10-01 10:43:52.958981 | TASK [Gather network facts] 2025-10-01 10:43:53.461147 | controller | ok 2025-10-01 10:43:53.513099 | 2025-10-01 10:43:53.513197 | TASK [include_role : start-zuul-console] 2025-10-01 10:43:53.574719 | controller | ok 2025-10-01 10:43:53.599398 | 2025-10-01 10:43:53.599519 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-01 10:43:54.149077 | controller | ok 2025-10-01 10:43:54.165903 | 2025-10-01 10:43:54.165991 | TASK [include_role : add-build-sshkey] 2025-10-01 10:43:54.269455 | controller | ok 2025-10-01 10:43:54.317987 | 2025-10-01 10:43:54.318141 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-01 10:43:55.144527 | controller -> localhost | ok 2025-10-01 10:43:55.150740 | 2025-10-01 10:43:55.150849 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-01 10:43:55.221771 | controller | ok 2025-10-01 10:43:55.249792 | controller | included: /var/lib/zuul/builds/0fa41b4bf3d84143b87ab9d58e76ea01/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-01 10:43:55.286525 | 2025-10-01 10:43:55.286629 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-01 10:43:56.709572 | controller -> localhost | Generating public/private rsa key pair. 2025-10-01 10:43:56.709760 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0fa41b4bf3d84143b87ab9d58e76ea01/work/0fa41b4bf3d84143b87ab9d58e76ea01_id_rsa. 2025-10-01 10:43:56.709792 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0fa41b4bf3d84143b87ab9d58e76ea01/work/0fa41b4bf3d84143b87ab9d58e76ea01_id_rsa.pub. 2025-10-01 10:43:56.709814 | controller -> localhost | The key fingerprint is: 2025-10-01 10:43:56.709834 | controller -> localhost | SHA256:5WARKy5NjcjscEAldgsly1StsFfE/DycAepp5VqLxLA zuul-build-sshkey 2025-10-01 10:43:56.709852 | controller -> localhost | The key's randomart image is: 2025-10-01 10:43:56.709871 | controller -> localhost | +---[RSA 3072]----+ 2025-10-01 10:43:56.709889 | controller -> localhost | | .O+==o.o. | 2025-10-01 10:43:56.709908 | controller -> localhost | | +.X +=o.o | 2025-10-01 10:43:56.709927 | controller -> localhost | | =oBo=+=o. | 2025-10-01 10:43:56.709946 | controller -> localhost | | .OoB o*+ | 2025-10-01 10:43:56.709965 | controller -> localhost | | E.O = S.. | 2025-10-01 10:43:56.709983 | controller -> localhost | | o = . | 2025-10-01 10:43:56.710001 | controller -> localhost | | o . | 2025-10-01 10:43:56.710040 | controller -> localhost | | | 2025-10-01 10:43:56.710062 | controller -> localhost | | | 2025-10-01 10:43:56.710080 | controller -> localhost | +----[SHA256]-----+ 2025-10-01 10:43:56.710121 | controller -> localhost | ok: Runtime: 0:00:00.262845 2025-10-01 10:43:56.728981 | 2025-10-01 10:43:56.730100 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-01 10:43:56.790430 | controller | ok 2025-10-01 10:43:56.815868 | controller | included: /var/lib/zuul/builds/0fa41b4bf3d84143b87ab9d58e76ea01/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-01 10:43:56.837748 | 2025-10-01 10:43:56.837860 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-01 10:43:56.897294 | controller | skipping: Conditional result was False 2025-10-01 10:43:56.903481 | 2025-10-01 10:43:56.903566 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-01 10:43:57.682241 | controller | changed 2025-10-01 10:43:57.694494 | 2025-10-01 10:43:57.694621 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-01 10:43:57.970044 | controller | ok 2025-10-01 10:43:57.981932 | 2025-10-01 10:43:57.982080 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-01 10:43:58.732552 | controller | changed 2025-10-01 10:43:58.738131 | 2025-10-01 10:43:58.738236 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-01 10:43:59.551167 | controller | changed 2025-10-01 10:43:59.559009 | 2025-10-01 10:43:59.559162 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-01 10:43:59.586199 | controller | skipping: Conditional result was False 2025-10-01 10:43:59.600116 | 2025-10-01 10:43:59.600246 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-01 10:44:00.087036 | controller -> localhost | changed 2025-10-01 10:44:00.098847 | 2025-10-01 10:44:00.098968 | TASK [add-build-sshkey : Add back temp key] 2025-10-01 10:44:00.673957 | controller -> localhost | Identity added: /var/lib/zuul/builds/0fa41b4bf3d84143b87ab9d58e76ea01/work/0fa41b4bf3d84143b87ab9d58e76ea01_id_rsa (zuul-build-sshkey) 2025-10-01 10:44:00.674256 | controller -> localhost | ok: Runtime: 0:00:00.018140 2025-10-01 10:44:00.679908 | 2025-10-01 10:44:00.679990 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-01 10:44:01.203430 | controller | ok 2025-10-01 10:44:01.210358 | 2025-10-01 10:44:01.210478 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-01 10:44:01.268236 | controller | skipping: Conditional result was False 2025-10-01 10:44:01.279367 | 2025-10-01 10:44:01.279483 | TASK [include_role : validate-host] 2025-10-01 10:44:01.326350 | controller | ok 2025-10-01 10:44:01.366441 | 2025-10-01 10:44:01.366548 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-01 10:44:01.427068 | controller | ok 2025-10-01 10:44:01.431900 | 2025-10-01 10:44:01.431962 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-01 10:44:01.726694 | controller -> localhost | ok 2025-10-01 10:44:01.734502 | 2025-10-01 10:44:01.734738 | TASK [validate-host : Collect information about the host] 2025-10-01 10:44:02.465623 | controller | ok 2025-10-01 10:44:02.479350 | 2025-10-01 10:44:02.479490 | TASK [validate-host : Sanitize hostname] 2025-10-01 10:44:02.558815 | controller | ok 2025-10-01 10:44:02.566469 | 2025-10-01 10:44:02.566576 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-01 10:44:03.159961 | controller -> localhost | changed 2025-10-01 10:44:03.166518 | 2025-10-01 10:44:03.166601 | TASK [validate-host : Collect information about zuul worker] 2025-10-01 10:44:03.629290 | controller | ok 2025-10-01 10:44:03.647717 | 2025-10-01 10:44:03.647846 | TASK [validate-host : Write out all zuul information for each host] 2025-10-01 10:44:04.580692 | controller -> localhost | changed 2025-10-01 10:44:04.591622 | 2025-10-01 10:44:04.591715 | TASK [include_role : prepare-workspace-openshift] 2025-10-01 10:44:04.654319 | controller | skipping: Conditional result was False 2025-10-01 10:44:04.664410 | 2025-10-01 10:44:04.664544 | TASK [include_role : remove-zuul-sshkey] 2025-10-01 10:44:04.722295 | controller | skipping: Conditional result was False 2025-10-01 10:44:04.728223 | 2025-10-01 10:44:04.728322 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-01 10:44:05.115089 | controller | ok: "logs" 2025-10-01 10:44:05.115316 | controller | ok: All items complete 2025-10-01 10:44:05.115343 | 2025-10-01 10:44:05.382877 | controller | ok: "artifacts" 2025-10-01 10:44:05.635624 | controller | ok: "docs" 2025-10-01 10:44:05.643807 | 2025-10-01 10:44:05.643924 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-01 10:44:05.990291 | controller | changed: "logs" 2025-10-01 10:44:06.258778 | controller | changed: "artifacts" 2025-10-01 10:44:06.531818 | controller | changed: "docs" 2025-10-01 10:44:06.587508 | 2025-10-01 10:44:06.587615 | PLAY RECAP 2025-10-01 10:44:06.587660 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-01 10:44:06.587687 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-01 10:44:06.587705 | 2025-10-01 10:44:06.834231 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-01 10:44:06.834925 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-01 10:44:08.093561 | 2025-10-01 10:44:08.093709 | PLAY [all] 2025-10-01 10:44:08.140424 | 2025-10-01 10:44:08.140563 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-01 10:44:08.317232 | controller | ok 2025-10-01 10:44:08.324219 | 2025-10-01 10:44:08.324361 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-01 10:44:08.868668 | controller | changed 2025-10-01 10:44:08.876863 | 2025-10-01 10:44:08.876987 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-01 10:44:10.648757 | controller | changed 2025-10-01 10:44:10.705581 | 2025-10-01 10:44:10.705705 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-01 10:44:11.583237 | controller | changed: 2025-10-01 10:44:11.583414 | controller | { 2025-10-01 10:44:11.583443 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-01 10:44:11.583474 | controller | } 2025-10-01 10:44:11.858593 | controller | changed: 2025-10-01 10:44:11.858699 | controller | { 2025-10-01 10:44:11.858727 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-01 10:44:11.858748 | controller | } 2025-10-01 10:44:12.186947 | controller | changed: 2025-10-01 10:44:12.191110 | controller | { 2025-10-01 10:44:12.191157 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com" 2025-10-01 10:44:12.191183 | controller | } 2025-10-01 10:44:12.476540 | controller | changed: 2025-10-01 10:44:12.476632 | controller | { 2025-10-01 10:44:12.476656 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-01 10:44:12.476675 | controller | } 2025-10-01 10:44:12.770295 | controller | changed: 2025-10-01 10:44:12.771055 | controller | { 2025-10-01 10:44:12.771100 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-01 10:44:12.771122 | controller | } 2025-10-01 10:44:13.062293 | controller | changed: 2025-10-01 10:44:13.062403 | controller | { 2025-10-01 10:44:13.062428 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-01 10:44:13.062447 | controller | } 2025-10-01 10:44:13.550949 | controller | changed: 2025-10-01 10:44:13.554303 | controller | { 2025-10-01 10:44:13.554344 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen" 2025-10-01 10:44:13.554368 | controller | } 2025-10-01 10:44:13.743504 | controller | changed: 2025-10-01 10:44:13.743611 | controller | { 2025-10-01 10:44:13.743636 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora" 2025-10-01 10:44:13.743656 | controller | } 2025-10-01 10:44:14.049954 | controller | changed: 2025-10-01 10:44:14.050058 | controller | { 2025-10-01 10:44:14.050086 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-01 10:44:14.050106 | controller | } 2025-10-01 10:44:14.325830 | controller | changed: 2025-10-01 10:44:14.325923 | controller | { 2025-10-01 10:44:14.325947 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-01 10:44:14.325966 | controller | } 2025-10-01 10:44:14.587672 | controller | changed: 2025-10-01 10:44:14.587819 | controller | { 2025-10-01 10:44:14.587847 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-01 10:44:14.587866 | controller | } 2025-10-01 10:44:14.832641 | controller | changed: 2025-10-01 10:44:14.832727 | controller | { 2025-10-01 10:44:14.832751 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-01 10:44:14.832770 | controller | } 2025-10-01 10:44:15.127319 | controller | changed: 2025-10-01 10:44:15.127420 | controller | { 2025-10-01 10:44:15.127443 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-01 10:44:15.127463 | controller | } 2025-10-01 10:44:15.391950 | controller | changed: 2025-10-01 10:44:15.392060 | controller | { 2025-10-01 10:44:15.392084 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-01 10:44:15.392102 | controller | } 2025-10-01 10:44:15.644557 | controller | changed: 2025-10-01 10:44:15.644651 | controller | { 2025-10-01 10:44:15.644674 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-01 10:44:15.644693 | controller | } 2025-10-01 10:44:15.930837 | controller | changed: 2025-10-01 10:44:15.930936 | controller | { 2025-10-01 10:44:15.930961 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-01 10:44:15.930980 | controller | } 2025-10-01 10:44:16.195561 | controller | changed: 2025-10-01 10:44:16.195681 | controller | { 2025-10-01 10:44:16.195718 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com" 2025-10-01 10:44:16.195745 | controller | } 2025-10-01 10:44:16.499804 | controller | changed: 2025-10-01 10:44:16.499908 | controller | { 2025-10-01 10:44:16.499936 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb" 2025-10-01 10:44:16.499966 | controller | } 2025-10-01 10:44:16.816893 | controller | changed: 2025-10-01 10:44:16.817777 | controller | { 2025-10-01 10:44:16.817825 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-01 10:44:16.817848 | controller | } 2025-10-01 10:44:17.104230 | controller | changed: 2025-10-01 10:44:17.104456 | controller | { 2025-10-01 10:44:17.104501 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-01 10:44:17.104530 | controller | } 2025-10-01 10:44:17.353488 | controller | changed: 2025-10-01 10:44:17.353632 | controller | { 2025-10-01 10:44:17.353660 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-01 10:44:17.353679 | controller | } 2025-10-01 10:44:17.620925 | controller | changed: 2025-10-01 10:44:17.621180 | controller | { 2025-10-01 10:44:17.621204 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-01 10:44:17.621224 | controller | } 2025-10-01 10:44:17.884178 | controller | changed: 2025-10-01 10:44:17.884308 | controller | { 2025-10-01 10:44:17.884369 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com" 2025-10-01 10:44:17.884402 | controller | } 2025-10-01 10:44:18.118490 | controller | changed: 2025-10-01 10:44:18.118593 | controller | { 2025-10-01 10:44:18.118619 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-01 10:44:18.118662 | controller | } 2025-10-01 10:44:18.381996 | controller | changed: 2025-10-01 10:44:18.382117 | controller | { 2025-10-01 10:44:18.382143 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-01 10:44:18.382188 | controller | } 2025-10-01 10:44:18.645512 | controller | changed: 2025-10-01 10:44:18.645615 | controller | { 2025-10-01 10:44:18.645639 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-01 10:44:18.645658 | controller | } 2025-10-01 10:44:18.669233 | 2025-10-01 10:44:18.669366 | TASK [Set timezone to UTC] 2025-10-01 10:44:19.276664 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-01 10:44:19.282662 | 2025-10-01 10:44:19.282744 | TASK [Create nodepool directory] 2025-10-01 10:44:19.570396 | controller | changed 2025-10-01 10:44:19.590881 | 2025-10-01 10:44:19.591010 | TASK [Create nodepool sub_nodes file] 2025-10-01 10:44:20.356433 | controller | changed 2025-10-01 10:44:20.374793 | 2025-10-01 10:44:20.375407 | TASK [Create nodepool sub_nodes_private file] 2025-10-01 10:44:21.215880 | controller | changed 2025-10-01 10:44:21.220982 | 2025-10-01 10:44:21.221064 | LOOP [Populate nodepool sub_nodes file] 2025-10-01 10:44:21.250302 | 2025-10-01 10:44:21.250477 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-01 10:44:21.290116 | 2025-10-01 10:44:21.290271 | TASK [Create nodepool primary file] 2025-10-01 10:44:21.315529 | controller | skipping: Conditional result was False 2025-10-01 10:44:21.322873 | 2025-10-01 10:44:21.322961 | TASK [Create nodepool node_private for this node] 2025-10-01 10:44:22.107787 | controller | changed 2025-10-01 10:44:22.118763 | 2025-10-01 10:44:22.118868 | LOOP [Copy ssh keys to nodepool directory] 2025-10-01 10:44:22.572088 | controller | ok: Item: id_rsa Runtime: 0:00:00.008546 2025-10-01 10:44:22.572342 | 2025-10-01 10:44:22.815645 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.006445 2025-10-01 10:44:22.827117 | 2025-10-01 10:44:22.827309 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-01 10:44:23.586295 | controller | changed 2025-10-01 10:44:23.598328 | 2025-10-01 10:44:23.598506 | TASK [Validate sudoers config after edits] 2025-10-01 10:44:23.914242 | controller | /etc/sudoers: parsed OK 2025-10-01 10:44:23.914355 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-01 10:44:23.914365 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-01 10:44:23.914372 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-01 10:44:24.140667 | controller | ok: Runtime: 0:00:00.007166 2025-10-01 10:44:24.147576 | 2025-10-01 10:44:24.147656 | TASK [Show the environment passed in to job shell scripts] 2025-10-01 10:44:24.440004 | controller | SHELL=/bin/bash 2025-10-01 10:44:24.440059 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-01 10:44:24.440071 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-01 10:44:24.440080 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/37/3237/9f82bbc44cf7fa37a2cf6305277fe598af8c6e39 2025-10-01 10:44:24.440089 | controller | PWD=/home/zuul 2025-10-01 10:44:24.440098 | controller | ZUUL_PIPELINE=github-check 2025-10-01 10:44:24.440105 | controller | LOGNAME=zuul 2025-10-01 10:44:24.440112 | controller | XDG_SESSION_TYPE=tty 2025-10-01 10:44:24.440118 | controller | _=/usr/bin/env 2025-10-01 10:44:24.440124 | controller | MOTD_SHOWN=pam 2025-10-01 10:44:24.440130 | controller | HOME=/home/zuul 2025-10-01 10:44:24.440135 | controller | LANG=en_US.UTF-8 2025-10-01 10:44:24.440141 | controller | SSH_CONNECTION=38.102.83.114 46090 38.102.83.30 22 2025-10-01 10:44:24.440148 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET 2025-10-01 10:44:24.440157 | controller | ZUUL_CHANGE_IDS=3237,9f82bbc44cf7fa37a2cf6305277fe598af8c6e39 2025-10-01 10:44:24.440163 | controller | WORKSPACE=/home/zuul/workspace 2025-10-01 10:44:24.440169 | controller | XDG_SESSION_CLASS=user 2025-10-01 10:44:24.440253 | controller | SELINUX_ROLE_REQUESTED= 2025-10-01 10:44:24.440266 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-01 10:44:24.440273 | controller | USER=zuul 2025-10-01 10:44:24.440281 | controller | ZUUL_VOTING=True 2025-10-01 10:44:24.440287 | controller | BUILD_TIMEOUT=1800000 2025-10-01 10:44:24.440294 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-01 10:44:24.440302 | controller | SHLVL=1 2025-10-01 10:44:24.440309 | controller | ZUUL_PATCHSET=9f82bbc44cf7fa37a2cf6305277fe598af8c6e39 2025-10-01 10:44:24.440316 | controller | XDG_SESSION_ID=1 2025-10-01 10:44:24.440323 | controller | ZUUL_BRANCH=main 2025-10-01 10:44:24.440330 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-01 10:44:24.440338 | controller | SSH_CLIENT=38.102.83.114 46090 22 2025-10-01 10:44:24.440345 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-01 10:44:24.440352 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-01 10:44:24.440360 | controller | which_declare=declare -f 2025-10-01 10:44:24.440367 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-01 10:44:24.440375 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-01 10:44:24.440383 | controller | ZUUL_CHANGE=3237 2025-10-01 10:44:24.440390 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-01 10:44:24.440397 | controller | ZUUL_UUID=0fa41b4bf3d84143b87ab9d58e76ea01 2025-10-01 10:44:24.440405 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-01 10:44:24.440412 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-01 10:44:24.440420 | controller | } 2025-10-01 10:44:24.682209 | controller | ok: Runtime: 0:00:00.007246 2025-10-01 10:44:24.688146 | 2025-10-01 10:44:24.688211 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-01 10:44:24.712843 | controller | skipping: Conditional result was False 2025-10-01 10:44:24.723571 | 2025-10-01 10:44:24.723668 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-01 10:44:25.305523 | controller | skipping: Conditional result was False 2025-10-01 10:44:25.311392 | 2025-10-01 10:44:25.311496 | TASK [Ensure legacy workspace directory] 2025-10-01 10:44:25.583868 | controller | changed 2025-10-01 10:44:25.623521 | 2025-10-01 10:44:25.623663 | PLAY RECAP 2025-10-01 10:44:25.623725 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-01 10:44:25.623754 | 2025-10-01 10:44:25.824912 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-01 10:44:25.825695 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-prepare.yml@main] 2025-10-01 10:44:26.519500 | 2025-10-01 10:44:26.519633 | PLAY [Get all repos on all hosts] 2025-10-01 10:44:26.543319 | 2025-10-01 10:44:26.543493 | TASK [Clone repos in the job workspace] 2025-10-01 10:44:26.579297 | controller | ok 2025-10-01 10:44:26.599035 | 2025-10-01 10:44:26.599160 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-01 10:44:26.984041 | controller | ok 2025-10-01 10:44:26.990872 | 2025-10-01 10:44:26.991003 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-01 10:44:51.043498 | controller | Output suppressed because no_log was given 2025-10-01 10:44:51.084613 | 2025-10-01 10:44:51.084701 | PLAY [Run ci/playbooks/e2e-prepare.yml] 2025-10-01 10:44:51.101399 | 2025-10-01 10:44:51.101530 | TASK [Read group_vars] 2025-10-01 10:44:51.120694 | controller | ok 2025-10-01 10:44:51.138521 | 2025-10-01 10:44:51.138638 | TASK [cifmw_helpers : Check directory is available] 2025-10-01 10:44:51.478625 | controller | ok 2025-10-01 10:44:51.491249 | 2025-10-01 10:44:51.491403 | TASK [cifmw_helpers : List dir in ansible user home] 2025-10-01 10:44:51.917014 | controller | var one 2025-10-01 10:44:51.919988 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars'$'\n': No such file or directory 2025-10-01 10:44:51.923170 | controller | all.yml 2025-10-01 10:44:51.926165 | controller | molecule 2025-10-01 10:44:51.926234 | controller | ls: cannot access '/root/src/github.com/openstack-k8s-operators/ci-framework/group_vars': Permission denied 2025-10-01 10:44:51.928253 | controller | ls: cannot access '/workspace/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory 2025-10-01 10:44:52.042716 | controller | ERROR 2025-10-01 10:44:52.043083 | controller | { 2025-10-01 10:44:52.043158 | controller | "delta": "0:00:00.017868", 2025-10-01 10:44:52.043213 | controller | "end": "2025-10-01 10:44:51.928863", 2025-10-01 10:44:52.043256 | controller | "msg": "non-zero return code", 2025-10-01 10:44:52.043295 | controller | "rc": 2, 2025-10-01 10:44:52.043334 | controller | "start": "2025-10-01 10:44:51.910995" 2025-10-01 10:44:52.043378 | controller | } 2025-10-01 10:44:52.043432 | controller | ERROR: Ignoring Errors 2025-10-01 10:44:52.056597 | 2025-10-01 10:44:52.056738 | TASK [cifmw_helpers : List available files] 2025-10-01 10:44:52.612725 | controller | skipping: Conditional result was False 2025-10-01 10:44:52.627442 | 2025-10-01 10:44:52.627598 | LOOP [cifmw_helpers : Read vars] 2025-10-01 10:44:52.667731 | 2025-10-01 10:44:52.668076 | LOOP [cifmw_helpers : Call task to parse all files as fact] 2025-10-01 10:44:52.719571 | 2025-10-01 10:44:52.719881 | TASK [Create zuul-output directory] 2025-10-01 10:44:53.101201 | controller | ok 2025-10-01 10:44:53.108811 | 2025-10-01 10:44:53.108889 | TASK [Install required packages] 2025-10-01 10:46:25.697599 | controller | changed 2025-10-01 10:46:25.703498 | 2025-10-01 10:46:25.703579 | TASK [Output pip related things] 2025-10-01 10:46:26.383560 | controller | pip 21.3.1 from /usr/lib/python3.9/site-packages/pip (python 3.9) 2025-10-01 10:46:26.737199 | controller | changed 2025-10-01 10:46:26.759194 | 2025-10-01 10:46:26.759359 | TASK [Install requirements] 2025-10-01 10:47:38.319786 | controller | changed 2025-10-01 10:47:38.333108 | 2025-10-01 10:47:38.333209 | TASK [Add cronjob to trigger job stats] 2025-10-01 10:47:38.380852 | controller | skipping: Conditional result was False 2025-10-01 10:47:38.389701 | 2025-10-01 10:47:38.389807 | LOOP [Construct project change list] 2025-10-01 10:47:38.451542 | controller | ok: 2025-10-01 10:47:38.451825 | controller | { 2025-10-01 10:47:38.451869 | controller | "branch": "main", 2025-10-01 10:47:38.451903 | controller | "change": "3237", 2025-10-01 10:47:38.451938 | controller | "change_message": "WIP: Move common variable to group_vars: cifmw_project_dir & cifmw_project_dir_absolute\n\nThere is no need to parse path, or call filters etc. to get the same value. \r\nThis specific PR takes care of ci-framework repo src path (`cifmw_project_dir` and `cifmw_project_dir_absolute`) [Link](https://issues.redhat.com/browse/OSPRH-19599) for tracking.\r\n\r\nDepends-On: https://github.com/openstack-k8s-operators/ci-framework/pull/3332\r\nDepends-On: https://github.com/openstack-k8s-operators/ci-framework/pull/3333", 2025-10-01 10:47:38.451970 | controller | "change_url": "https://github.com/openstack-k8s-operators/ci-framework/pull/3237", 2025-10-01 10:47:38.452001 | controller | "commit_id": "9f82bbc44cf7fa37a2cf6305277fe598af8c6e39", 2025-10-01 10:47:38.452074 | controller | "patchset": "9f82bbc44cf7fa37a2cf6305277fe598af8c6e39", 2025-10-01 10:47:38.452107 | controller | "project": { 2025-10-01 10:47:38.452138 | controller | "canonical_hostname": "github.com", 2025-10-01 10:47:38.452166 | controller | "canonical_name": "github.com/openstack-k8s-operators/ci-framework", 2025-10-01 10:47:38.452193 | controller | "name": "openstack-k8s-operators/ci-framework", 2025-10-01 10:47:38.452218 | controller | "short_name": "ci-framework", 2025-10-01 10:47:38.452246 | controller | "src_dir": "src/github.com/openstack-k8s-operators/ci-framework" 2025-10-01 10:47:38.452272 | controller | }, 2025-10-01 10:47:38.452296 | controller | "topic": null 2025-10-01 10:47:38.452321 | controller | } 2025-10-01 10:47:38.452370 | 2025-10-01 10:47:38.522681 | 2025-10-01 10:47:38.522839 | PLAY [Run ci/playbooks/build_runner_image.yml] 2025-10-01 10:47:38.538737 | 2025-10-01 10:47:38.538861 | TASK [Filter out host if needed] 2025-10-01 10:47:38.550382 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-01 10:47:38.555594 | 2025-10-01 10:47:38.555661 | TASK [Get git tag for image tagging] 2025-10-01 10:47:39.154895 | controller | skipping: Conditional result was False 2025-10-01 10:47:39.168957 | 2025-10-01 10:47:39.169166 | TASK [Set openstack-runner image] 2025-10-01 10:47:39.208839 | controller | skipping: Conditional result was False 2025-10-01 10:47:39.229397 | 2025-10-01 10:47:39.229601 | TASK [Build openstack-runner image] 2025-10-01 10:47:39.273348 | controller | skipping: Conditional result was False 2025-10-01 10:47:39.288115 | 2025-10-01 10:47:39.288277 | TASK [Push openstack-runner image] 2025-10-01 10:47:39.318949 | controller | skipping: Conditional result was False 2025-10-01 10:47:39.335739 | 2025-10-01 10:47:39.335925 | TASK [Make sure ci-framework directory exists] 2025-10-01 10:47:39.376568 | controller | skipping: Conditional result was False 2025-10-01 10:47:39.392870 | 2025-10-01 10:47:39.393094 | TASK [Create EDPM ansible var file] 2025-10-01 10:47:39.434230 | controller | skipping: Conditional result was False 2025-10-01 10:47:39.476972 | 2025-10-01 10:47:39.477157 | PLAY RECAP 2025-10-01 10:47:39.477221 | controller | ok: 9 changed: 5 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 1 2025-10-01 10:47:39.477250 | 2025-10-01 10:47:39.604466 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-prepare.yml@main] 2025-10-01 10:47:39.605253 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-01 10:47:40.251404 | 2025-10-01 10:47:40.251585 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-01 10:47:40.275097 | 2025-10-01 10:47:40.275247 | TASK [Create zuul-output directory] 2025-10-01 10:47:40.753308 | controller | changed 2025-10-01 10:47:40.769387 | 2025-10-01 10:47:40.769573 | TASK [Slurp Zuul inventory test] 2025-10-01 10:47:41.220572 | controller -> localhost | ok 2025-10-01 10:47:41.227848 | 2025-10-01 10:47:41.227914 | TASK [Save zuul inventory] 2025-10-01 10:47:42.241997 | controller | changed 2025-10-01 10:47:42.251196 | 2025-10-01 10:47:42.251348 | TASK [Save zuul vars without the change_message] 2025-10-01 10:47:43.171700 | controller | changed 2025-10-01 10:47:43.215155 | 2025-10-01 10:47:43.215308 | PLAY RECAP 2025-10-01 10:47:43.215397 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-01 10:47:43.215447 | 2025-10-01 10:47:43.379994 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-01 10:47:43.383409 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/pre.yml@main] 2025-10-01 10:47:43.999048 | 2025-10-01 10:47:43.999169 | PLAY [Get all repos on all hosts] 2025-10-01 10:47:44.029539 | 2025-10-01 10:47:44.029714 | TASK [Clone repos in the job workspace] 2025-10-01 10:47:44.050818 | controller | ok 2025-10-01 10:47:44.070380 | 2025-10-01 10:47:44.070522 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-01 10:47:44.465080 | controller | ok 2025-10-01 10:47:44.479577 | 2025-10-01 10:47:44.479753 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-01 10:47:46.275771 | controller | Output suppressed because no_log was given 2025-10-01 10:47:46.353634 | 2025-10-01 10:47:46.353893 | PLAY [Run ci/playbooks/content_provider/pre.yml] 2025-10-01 10:47:46.377506 | 2025-10-01 10:47:46.377687 | TASK [Filter out host if needed] 2025-10-01 10:47:46.390114 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-01 10:47:46.395815 | 2025-10-01 10:47:46.395930 | TASK [Install ansible-core] 2025-10-01 10:47:51.729273 | controller | changed 2025-10-01 10:47:51.742386 | 2025-10-01 10:47:51.742544 | TASK [Discover an IPv4 for provider job] 2025-10-01 10:47:51.817250 | controller | ok 2025-10-01 10:47:51.853760 | 2025-10-01 10:47:51.853861 | PLAY RECAP 2025-10-01 10:47:51.853916 | controller | ok: 4 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-01 10:47:51.853946 | 2025-10-01 10:47:51.983640 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/pre.yml@main] 2025-10-01 10:47:51.984469 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/run.yml@main] 2025-10-01 10:47:52.617545 | 2025-10-01 10:47:52.617671 | PLAY [Run ci/playbooks/content_provider/run.yml] 2025-10-01 10:47:52.638305 | 2025-10-01 10:47:52.638406 | TASK [Read group_vars] 2025-10-01 10:47:52.657521 | controller | ok 2025-10-01 10:47:52.677541 | 2025-10-01 10:47:52.677626 | TASK [cifmw_helpers : Check directory is available] 2025-10-01 10:47:53.092633 | controller | ok 2025-10-01 10:47:53.104388 | 2025-10-01 10:47:53.104542 | TASK [cifmw_helpers : List dir in ansible user home] 2025-10-01 10:47:53.537915 | controller | var one 2025-10-01 10:47:53.541552 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars'$'\n': No such file or directory 2025-10-01 10:47:53.544277 | controller | all.yml 2025-10-01 10:47:53.546510 | controller | molecule 2025-10-01 10:47:53.546537 | controller | ls: cannot access '/root/src/github.com/openstack-k8s-operators/ci-framework/group_vars': Permission denied 2025-10-01 10:47:53.549850 | controller | ls: cannot access '/workspace/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory 2025-10-01 10:47:53.652817 | controller | ERROR 2025-10-01 10:47:53.653184 | controller | { 2025-10-01 10:47:53.653259 | controller | "delta": "0:00:00.020713", 2025-10-01 10:47:53.653313 | controller | "end": "2025-10-01 10:47:53.550506", 2025-10-01 10:47:53.653356 | controller | "msg": "non-zero return code", 2025-10-01 10:47:53.653396 | controller | "rc": 2, 2025-10-01 10:47:53.653446 | controller | "start": "2025-10-01 10:47:53.529793" 2025-10-01 10:47:53.653492 | controller | } 2025-10-01 10:47:53.653570 | controller | ERROR: Ignoring Errors 2025-10-01 10:47:53.668283 | 2025-10-01 10:47:53.668490 | TASK [cifmw_helpers : List available files] 2025-10-01 10:47:54.222108 | controller | skipping: Conditional result was False 2025-10-01 10:47:54.236048 | 2025-10-01 10:47:54.236248 | LOOP [cifmw_helpers : Read vars] 2025-10-01 10:47:54.269602 | 2025-10-01 10:47:54.269778 | LOOP [cifmw_helpers : Call task to parse all files as fact] 2025-10-01 10:47:54.305630 | 2025-10-01 10:47:54.305821 | TASK [Filter out host if needed] 2025-10-01 10:47:54.317589 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-01 10:47:54.323962 | 2025-10-01 10:47:54.324102 | TASK [Deploy content provider] 2025-10-01 10:48:04.875350 | controller | ERROR 2025-10-01 10:48:04.875580 | controller | { 2025-10-01 10:48:04.875611 | controller | "msg": "The field 'environment' has an invalid value, which includes an undefined variable. The error was: 'cifmw_project_dir_absolute' is undefined. 'cifmw_project_dir_absolute' is undefined\n\nThe error appears to be in '/var/lib/zuul/builds/0fa41b4bf3d84143b87ab9d58e76ea01/untrusted/project_0/github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/run.yml': line 25, column 7, but may\nbe elsewhere in the file depending on the exact syntax problem.\n\nThe offending line appears to be:\n\n\n - name: Deploy content provider\n ^ here\n" 2025-10-01 10:48:04.875634 | controller | } failure 2025-10-01 10:48:04.876616 | 2025-10-01 10:48:04.876663 | PLAY RECAP 2025-10-01 10:48:04.876703 | controller | ok: 2 changed: 1 unreachable: 0 failed: 1 skipped: 3 rescued: 0 ignored: 1 2025-10-01 10:48:04.876724 | 2025-10-01 10:48:04.972249 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/run.yml@main] 2025-10-01 10:48:04.973890 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-01 10:48:05.593791 | 2025-10-01 10:48:05.593919 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-01 10:48:05.616097 | 2025-10-01 10:48:05.616231 | TASK [Filter out host if needed] 2025-10-01 10:48:05.625185 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-01 10:48:05.630528 | 2025-10-01 10:48:05.630598 | TASK [Ensure file is present] 2025-10-01 10:48:06.100851 | controller | ok 2025-10-01 10:48:06.114162 | 2025-10-01 10:48:06.114315 | TASK [Manage molecule report file] 2025-10-01 10:48:06.691614 | controller | skipping: Conditional result was False 2025-10-01 10:48:06.703919 | 2025-10-01 10:48:06.704112 | TASK [Check if we get ci-framework-data basedir] 2025-10-01 10:48:06.993559 | controller | ok 2025-10-01 10:48:07.000968 | 2025-10-01 10:48:07.001079 | TASK [Create ci-framework-data log directory for zuul] 2025-10-01 10:48:07.389783 | controller | changed 2025-10-01 10:48:07.403653 | 2025-10-01 10:48:07.403830 | TASK [Copy ci-framework interesting files] 2025-10-01 10:48:07.850627 | controller | cp: cannot stat '/home/zuul/ci-framework-data/logs': No such file or directory 2025-10-01 10:48:07.865051 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-10-01 10:48:07.951964 | controller | changed 2025-10-01 10:48:07.960493 | 2025-10-01 10:48:07.960610 | TASK [Get SELinux listing] 2025-10-01 10:48:08.502845 | controller | changed 2025-10-01 10:48:08.516806 | 2025-10-01 10:48:08.516997 | TASK [Generate log index] 2025-10-01 10:48:09.406437 | controller | changed 2025-10-01 10:48:09.412194 | 2025-10-01 10:48:09.412262 | TASK [Get some env related data] 2025-10-01 10:48:10.094934 | controller | /home/zuul/.local/bin/ansible 2025-10-01 10:48:10.950113 | controller | changed 2025-10-01 10:48:10.962585 | 2025-10-01 10:48:10.962733 | TASK [Generate list of logs to collect in home directory] 2025-10-01 10:48:11.401335 | controller | ok: All paths examined 2025-10-01 10:48:11.407211 | 2025-10-01 10:48:11.407346 | LOOP [Copy logs from home directory] 2025-10-01 10:48:11.826518 | controller | changed: 2025-10-01 10:48:11.826716 | controller | { 2025-10-01 10:48:11.826743 | controller | "atime": 1759315639.7215388, 2025-10-01 10:48:11.826763 | controller | "ctime": 1759315657.719059, 2025-10-01 10:48:11.826780 | controller | "dev": 64513, 2025-10-01 10:48:11.826797 | controller | "gid": 1000, 2025-10-01 10:48:11.826814 | controller | "gr_name": "zuul", 2025-10-01 10:48:11.826831 | controller | "inode": 4384053, 2025-10-01 10:48:11.826847 | controller | "isblk": false, 2025-10-01 10:48:11.826863 | controller | "ischr": false, 2025-10-01 10:48:11.826879 | controller | "isdir": false, 2025-10-01 10:48:11.826894 | controller | "isfifo": false, 2025-10-01 10:48:11.826910 | controller | "isgid": false, 2025-10-01 10:48:11.826926 | controller | "islnk": false, 2025-10-01 10:48:11.826941 | controller | "isreg": true, 2025-10-01 10:48:11.826957 | controller | "issock": false, 2025-10-01 10:48:11.826992 | controller | "isuid": false, 2025-10-01 10:48:11.827007 | controller | "mode": "0644", 2025-10-01 10:48:11.827047 | controller | "mtime": 1759315657.719059, 2025-10-01 10:48:11.827065 | controller | "nlink": 1, 2025-10-01 10:48:11.827083 | controller | "path": "/home/zuul/ansible.log", 2025-10-01 10:48:11.827099 | controller | "pw_name": "zuul", 2025-10-01 10:48:11.827116 | controller | "rgrp": true, 2025-10-01 10:48:11.827132 | controller | "roth": true, 2025-10-01 10:48:11.827148 | controller | "rusr": true, 2025-10-01 10:48:11.827163 | controller | "size": 6749, 2025-10-01 10:48:11.827179 | controller | "uid": 1000, 2025-10-01 10:48:11.827194 | controller | "wgrp": false, 2025-10-01 10:48:11.827212 | controller | "woth": false, 2025-10-01 10:48:11.827228 | controller | "wusr": true, 2025-10-01 10:48:11.827244 | controller | "xgrp": false, 2025-10-01 10:48:11.827259 | controller | "xoth": false, 2025-10-01 10:48:11.827274 | controller | "xusr": false 2025-10-01 10:48:11.827289 | controller | } 2025-10-01 10:48:11.842714 | 2025-10-01 10:48:11.842899 | TASK [Copy crio stats log file] 2025-10-01 10:48:11.879945 | controller | skipping: Conditional result was False 2025-10-01 10:48:11.892689 | 2025-10-01 10:48:11.892853 | TASK [Get SELinux related data] 2025-10-01 10:48:12.205074 | controller | 2025-10-01 10:48:12.446096 | controller | ERROR 2025-10-01 10:48:12.446327 | controller | { 2025-10-01 10:48:12.446400 | controller | "delta": "0:00:00.014992", 2025-10-01 10:48:12.446443 | controller | "end": "2025-10-01 10:48:12.206935", 2025-10-01 10:48:12.446479 | controller | "msg": "non-zero return code", 2025-10-01 10:48:12.446513 | controller | "rc": 1, 2025-10-01 10:48:12.446548 | controller | "start": "2025-10-01 10:48:12.191943" 2025-10-01 10:48:12.446581 | controller | } 2025-10-01 10:48:12.446630 | controller | ERROR: Ignoring Errors 2025-10-01 10:48:12.458608 | 2025-10-01 10:48:12.458781 | TASK [Create system configuration directory] 2025-10-01 10:48:12.743606 | controller | changed 2025-10-01 10:48:12.755624 | 2025-10-01 10:48:12.755763 | TASK [Get some of the system configurations] 2025-10-01 10:48:13.304609 | controller | changed 2025-10-01 10:48:13.316917 | 2025-10-01 10:48:13.317114 | TASK [Copy generated documentation if available] 2025-10-01 10:48:13.344414 | controller | skipping: Conditional result was False 2025-10-01 10:48:13.357532 | 2025-10-01 10:48:13.357689 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-01 10:48:13.384906 | controller | skipping: Conditional result was False 2025-10-01 10:48:13.398477 | 2025-10-01 10:48:13.398638 | TASK [Compress logs bigger than 2MB] 2025-10-01 10:48:13.949735 | controller | changed 2025-10-01 10:48:13.957788 | 2025-10-01 10:48:13.957876 | TASK [Copy files from workspace on node] 2025-10-01 10:48:13.980305 | controller | ok 2025-10-01 10:48:14.015545 | 2025-10-01 10:48:14.015678 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-01 10:48:14.040846 | controller | skipping: Conditional result was False 2025-10-01 10:48:14.049192 | 2025-10-01 10:48:14.049294 | TASK [fetch-output : Set log path for single node] 2025-10-01 10:48:14.101521 | controller | ok 2025-10-01 10:48:14.109744 | 2025-10-01 10:48:14.109895 | LOOP [fetch-output : Ensure local output dirs] 2025-10-01 10:48:14.412203 | controller -> localhost | ok: "/var/lib/zuul/builds/0fa41b4bf3d84143b87ab9d58e76ea01/work/logs" 2025-10-01 10:48:14.412998 | controller -> localhost | changed: All items complete 2025-10-01 10:48:14.413253 | 2025-10-01 10:48:14.696996 | controller -> localhost | changed: "/var/lib/zuul/builds/0fa41b4bf3d84143b87ab9d58e76ea01/work/artifacts" 2025-10-01 10:48:14.926542 | controller -> localhost | changed: "/var/lib/zuul/builds/0fa41b4bf3d84143b87ab9d58e76ea01/work/docs" 2025-10-01 10:48:14.943380 | 2025-10-01 10:48:14.943498 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-01 10:48:15.631600 | controller | changed: 2025-10-01 10:48:15.631950 | controller | .d..t...... ./ 2025-10-01 10:48:15.632052 | controller | >f+++++++++ README.html 2025-10-01 10:48:15.632115 | controller | >f+++++++++ ansible.log 2025-10-01 10:48:15.632180 | controller | >f+++++++++ dmesg.log 2025-10-01 10:48:15.632238 | controller | >f+++++++++ installed-pkgs.log 2025-10-01 10:48:15.632289 | controller | >f+++++++++ python.log 2025-10-01 10:48:15.632332 | controller | >f+++++++++ registries.conf 2025-10-01 10:48:15.632374 | controller | >f+++++++++ selinux-denials.log 2025-10-01 10:48:15.632420 | controller | >f+++++++++ selinux-listing.log 2025-10-01 10:48:15.632463 | controller | cd+++++++++ ci-framework-data/ 2025-10-01 10:48:15.632505 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-01 10:48:15.632547 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-01 10:48:15.632588 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-01 10:48:15.632819 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-10-01 10:48:15.632867 | controller | cd+++++++++ registries.conf.d/ 2025-10-01 10:48:15.632909 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-10-01 10:48:15.632951 | controller | cd+++++++++ system-config/ 2025-10-01 10:48:15.632994 | controller | cd+++++++++ system-config/libvirt/ 2025-10-01 10:48:15.633076 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-10-01 10:48:15.633127 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-10-01 10:48:16.151091 | controller | changed: .d..t...... ./ 2025-10-01 10:48:16.590630 | controller | changed: .d..t...... ./ 2025-10-01 10:48:16.606475 | 2025-10-01 10:48:16.606579 | TASK [Return artifact to Zuul] 2025-10-01 10:48:16.630427 | controller | skipping: Conditional result was False 2025-10-01 10:48:16.655825 | 2025-10-01 10:48:16.655912 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-01 10:48:16.656054 | 2025-10-01 10:48:16.656096 | PLAY RECAP 2025-10-01 10:48:16.656163 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1 2025-10-01 10:48:16.656193 | 2025-10-01 10:48:16.761509 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-01 10:48:16.763375 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-01 10:48:17.366454 | 2025-10-01 10:48:17.366611 | PLAY [all] 2025-10-01 10:48:17.385886 | 2025-10-01 10:48:17.385984 | TASK [include_role : fetch-output] 2025-10-01 10:48:17.435108 | controller | ok 2025-10-01 10:48:17.451985 | 2025-10-01 10:48:17.452116 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-01 10:48:17.517159 | controller | skipping: Conditional result was False 2025-10-01 10:48:17.522808 | 2025-10-01 10:48:17.522882 | TASK [fetch-output : Set log path for single node] 2025-10-01 10:48:17.568702 | controller | ok 2025-10-01 10:48:17.580752 | 2025-10-01 10:48:17.580901 | LOOP [fetch-output : Ensure local output dirs] 2025-10-01 10:48:17.991640 | controller -> localhost | ok: "/var/lib/zuul/builds/0fa41b4bf3d84143b87ab9d58e76ea01/work/logs" 2025-10-01 10:48:18.273701 | controller -> localhost | ok: "/var/lib/zuul/builds/0fa41b4bf3d84143b87ab9d58e76ea01/work/artifacts" 2025-10-01 10:48:18.551655 | controller -> localhost | ok: "/var/lib/zuul/builds/0fa41b4bf3d84143b87ab9d58e76ea01/work/docs" 2025-10-01 10:48:18.561827 | 2025-10-01 10:48:18.561931 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-01 10:48:19.183273 | controller | ok 2025-10-01 10:48:19.183495 | controller | ok: All items complete 2025-10-01 10:48:19.183524 | 2025-10-01 10:48:19.751618 | controller | ok 2025-10-01 10:48:20.297231 | controller | ok 2025-10-01 10:48:20.328075 | 2025-10-01 10:48:20.328312 | TASK [include_role : fetch-output-openshift] 2025-10-01 10:48:20.355639 | controller | skipping: Conditional result was False 2025-10-01 10:48:20.364331 | 2025-10-01 10:48:20.364453 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-01 10:48:20.828303 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.015068 2025-10-01 10:48:21.133420 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.013475 2025-10-01 10:48:21.191006 | 2025-10-01 10:48:21.191154 | PLAY [all] 2025-10-01 10:48:21.213757 | 2025-10-01 10:48:21.213889 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-01 10:48:21.771011 | controller | changed 2025-10-01 10:48:21.817657 | 2025-10-01 10:48:21.817758 | PLAY RECAP 2025-10-01 10:48:21.817812 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-01 10:48:21.817840 | 2025-10-01 10:48:21.930510 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-01 10:48:21.932245 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-01 10:48:22.521292 | 2025-10-01 10:48:22.521403 | PLAY [localhost] 2025-10-01 10:48:22.539259 | 2025-10-01 10:48:22.539357 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-01 10:48:22.922971 | localhost | changed 2025-10-01 10:48:22.927972 | 2025-10-01 10:48:22.928077 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-01 10:48:22.967852 | localhost | ok 2025-10-01 10:48:22.981834 | 2025-10-01 10:48:22.981975 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-01 10:48:23.410799 | localhost | changed 2025-10-01 10:48:23.417937 | 2025-10-01 10:48:23.418110 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-01 10:48:24.124228 | localhost | changed 2025-10-01 10:48:24.129987 | 2025-10-01 10:48:24.130096 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-01 10:48:24.586289 | localhost | Identity added: /var/lib/zuul/builds/0fa41b4bf3d84143b87ab9d58e76ea01/work/tmp/ansible.o2a08w22 (/var/lib/zuul/builds/0fa41b4bf3d84143b87ab9d58e76ea01/work/tmp/ansible.o2a08w22) 2025-10-01 10:48:24.586638 | localhost | ok: Runtime: 0:00:00.010046 2025-10-01 10:48:24.597471 | 2025-10-01 10:48:24.597657 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-01 10:48:24.869381 | localhost | ok: Runtime: 0:00:00.009345 2025-10-01 10:48:24.881095 | 2025-10-01 10:48:24.881273 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-01 10:48:24.948921 | localhost | changed 2025-10-01 10:48:24.959130 | 2025-10-01 10:48:24.959300 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-01 10:48:25.407051 | localhost | changed 2025-10-01 10:48:25.448283 | 2025-10-01 10:48:25.448465 | PLAY [localhost] 2025-10-01 10:48:25.466233 | 2025-10-01 10:48:25.466321 | TASK [Generate bulk log download script] 2025-10-01 10:48:25.488045 | localhost | ok 2025-10-01 10:48:25.507155 | 2025-10-01 10:48:25.507288 | TASK [local-log-download : Check API endpoint is defined] 2025-10-01 10:48:25.561952 | localhost | ok: All assertions passed 2025-10-01 10:48:25.571835 | 2025-10-01 10:48:25.571917 | TASK [local-log-download : Create download script] 2025-10-01 10:48:26.121589 | localhost -> localhost | changed 2025-10-01 10:48:26.130666 | 2025-10-01 10:48:26.130768 | TASK [Register quick-download link] 2025-10-01 10:48:26.160131 | localhost | ok 2025-10-01 10:48:26.177293 | 2025-10-01 10:48:26.177359 | PLAY [logserver.rdoproject.org] 2025-10-01 10:48:26.188719 | 2025-10-01 10:48:26.188779 | TASK [Set zuul-log-path fact] 2025-10-01 10:48:26.205963 | logserver.rdoproject.org | ok 2025-10-01 10:48:26.216927 | 2025-10-01 10:48:26.217032 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-01 10:48:26.254900 | logserver.rdoproject.org | ok 2025-10-01 10:48:26.260828 | 2025-10-01 10:48:26.260892 | TASK [upload-logs : Create log directories] 2025-10-01 10:48:26.997249 | logserver.rdoproject.org | changed 2025-10-01 10:48:27.002093 | 2025-10-01 10:48:27.002192 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-01 10:48:27.322845 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.009047 2025-10-01 10:48:27.333885 | 2025-10-01 10:48:27.334072 | TASK [upload-logs : Upload logs to log server] 2025-10-01 10:48:28.023065 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-01 10:48:28.025935 | 2025-10-01 10:48:28.025996 | LOOP [upload-logs : Compress console log and json output] 2025-10-01 10:48:28.092421 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 10:48:28.105739 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 10:48:28.119075 | 2025-10-01 10:48:28.119180 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-01 10:48:28.184913 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 10:48:28.185526 | 2025-10-01 10:48:28.189695 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 10:48:28.204362 | 2025-10-01 10:48:28.204517 | LOOP [upload-logs : Upload console log and json output]