2025-10-01 10:43:14.230423 | Job console starting... 2025-10-01 10:43:14.247920 | Updating repositories 2025-10-01 10:43:14.292319 | Preparing job workspace 2025-10-01 10:43:20.973874 | Running Ansible setup... 2025-10-01 10:43:28.212586 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-01 10:43:29.082700 | 2025-10-01 10:43:29.082834 | PLAY [localhost] 2025-10-01 10:43:29.094256 | 2025-10-01 10:43:29.094347 | TASK [Gathering Facts] 2025-10-01 10:43:30.677502 | localhost | ok 2025-10-01 10:43:30.702718 | 2025-10-01 10:43:30.702872 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-01 10:43:31.115345 | localhost -> localhost | changed 2025-10-01 10:43:31.121456 | 2025-10-01 10:43:31.121538 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-01 10:43:32.812255 | localhost -> localhost | changed 2025-10-01 10:43:32.823858 | 2025-10-01 10:43:32.824067 | TASK [Setup log path fact] 2025-10-01 10:43:32.845524 | localhost | ok 2025-10-01 10:43:32.860981 | 2025-10-01 10:43:32.861101 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-01 10:43:32.890981 | localhost | ok 2025-10-01 10:43:32.902603 | 2025-10-01 10:43:32.902708 | TASK [emit-job-header : Print job information] 2025-10-01 10:43:32.942919 | # Job Information 2025-10-01 10:43:32.943068 | Ansible Version: 2.15.12 2025-10-01 10:43:32.943096 | Job: cifmw-molecule-operator_deploy 2025-10-01 10:43:32.943119 | Pipeline: github-check 2025-10-01 10:43:32.943139 | Executor: ze03.softwarefactory-project.io 2025-10-01 10:43:32.943157 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3237 2025-10-01 10:43:32.943176 | Log URL (when completed): https://logserver.rdoproject.org/65c/rdoproject.org/65c5612102dd4a5b81cea8f4bb00ba5b/ 2025-10-01 10:43:32.943195 | Event ID: 2c962f60-9eb3-11f0-844e-f06c90bcb25f 2025-10-01 10:43:32.947754 | 2025-10-01 10:43:32.947827 | LOOP [emit-job-header : Print node information] 2025-10-01 10:43:33.076367 | localhost | ok: 2025-10-01 10:43:33.076561 | localhost | # Node Information 2025-10-01 10:43:33.076589 | localhost | Inventory Hostname: controller 2025-10-01 10:43:33.076613 | localhost | Hostname: np0005464347 2025-10-01 10:43:33.076633 | localhost | Username: zuul 2025-10-01 10:43:33.076653 | localhost | Distro: CentOS 9 2025-10-01 10:43:33.076671 | localhost | Provider: vexxhost-nodepool-tripleo 2025-10-01 10:43:33.076688 | localhost | Region: RegionOne 2025-10-01 10:43:33.076704 | localhost | Label: centos-9-stream-crc-2-48-0-xl 2025-10-01 10:43:33.076720 | localhost | Product Name: OpenStack Nova 2025-10-01 10:43:33.076756 | localhost | Interface IP: 38.129.56.204 2025-10-01 10:43:33.104085 | 2025-10-01 10:43:33.104197 | PLAY [all] 2025-10-01 10:43:33.114535 | 2025-10-01 10:43:33.114619 | TASK [Gather network facts] 2025-10-01 10:43:33.783252 | controller | ok 2025-10-01 10:43:33.852416 | 2025-10-01 10:43:33.852531 | TASK [include_role : start-zuul-console] 2025-10-01 10:43:33.896754 | controller | ok 2025-10-01 10:43:33.919534 | 2025-10-01 10:43:33.919644 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-01 10:43:34.657658 | controller | ok 2025-10-01 10:43:34.674782 | 2025-10-01 10:43:34.674892 | TASK [include_role : add-build-sshkey] 2025-10-01 10:43:34.728659 | controller | ok 2025-10-01 10:43:34.760307 | 2025-10-01 10:43:34.760421 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-01 10:43:35.157943 | controller -> localhost | ok 2025-10-01 10:43:35.164039 | 2025-10-01 10:43:35.164123 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-01 10:43:35.203994 | controller | ok 2025-10-01 10:43:35.218637 | controller | included: /var/lib/zuul/builds/65c5612102dd4a5b81cea8f4bb00ba5b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-01 10:43:35.226297 | 2025-10-01 10:43:35.226397 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-01 10:43:36.138714 | controller -> localhost | Generating public/private rsa key pair. 2025-10-01 10:43:36.138957 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/65c5612102dd4a5b81cea8f4bb00ba5b/work/65c5612102dd4a5b81cea8f4bb00ba5b_id_rsa. 2025-10-01 10:43:36.138989 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/65c5612102dd4a5b81cea8f4bb00ba5b/work/65c5612102dd4a5b81cea8f4bb00ba5b_id_rsa.pub. 2025-10-01 10:43:36.139011 | controller -> localhost | The key fingerprint is: 2025-10-01 10:43:36.139030 | controller -> localhost | SHA256:zQMZOj0U1PR/2sXL1tgXUcnoAoeWY6JrhgJ8+FuUhNI zuul-build-sshkey 2025-10-01 10:43:36.139049 | controller -> localhost | The key's randomart image is: 2025-10-01 10:43:36.139068 | controller -> localhost | +---[RSA 3072]----+ 2025-10-01 10:43:36.139086 | controller -> localhost | | . . .=++ o o| 2025-10-01 10:43:36.139103 | controller -> localhost | | . E . = X.o . o.| 2025-10-01 10:43:36.139121 | controller -> localhost | |. o . = O + o . | 2025-10-01 10:43:36.139139 | controller -> localhost | |.o . + . = . o ..| 2025-10-01 10:43:36.139156 | controller -> localhost | |. o o . S + . ..+| 2025-10-01 10:43:36.139173 | controller -> localhost | | . o = . .*=| 2025-10-01 10:43:36.139190 | controller -> localhost | | . = o+=| 2025-10-01 10:43:36.139210 | controller -> localhost | | . . .| 2025-10-01 10:43:36.139228 | controller -> localhost | | | 2025-10-01 10:43:36.139245 | controller -> localhost | +----[SHA256]-----+ 2025-10-01 10:43:36.139291 | controller -> localhost | ok: Runtime: 0:00:00.441256 2025-10-01 10:43:36.146753 | 2025-10-01 10:43:36.146832 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-01 10:43:36.222869 | controller | ok 2025-10-01 10:43:36.237671 | controller | included: /var/lib/zuul/builds/65c5612102dd4a5b81cea8f4bb00ba5b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-01 10:43:36.259790 | 2025-10-01 10:43:36.259896 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-01 10:43:36.324811 | controller | skipping: Conditional result was False 2025-10-01 10:43:36.332098 | 2025-10-01 10:43:36.332221 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-01 10:43:37.204514 | controller | changed 2025-10-01 10:43:37.216967 | 2025-10-01 10:43:37.217305 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-01 10:43:37.592931 | controller | ok 2025-10-01 10:43:37.598349 | 2025-10-01 10:43:37.598436 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-01 10:43:39.510499 | controller | changed 2025-10-01 10:43:39.517682 | 2025-10-01 10:43:39.517793 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-01 10:43:41.441936 | controller | changed 2025-10-01 10:43:41.449587 | 2025-10-01 10:43:41.449690 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-01 10:43:41.473998 | controller | skipping: Conditional result was False 2025-10-01 10:43:41.486942 | 2025-10-01 10:43:41.487062 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-01 10:43:42.043740 | controller -> localhost | changed 2025-10-01 10:43:42.071572 | 2025-10-01 10:43:42.071707 | TASK [add-build-sshkey : Add back temp key] 2025-10-01 10:43:42.508392 | controller -> localhost | Identity added: /var/lib/zuul/builds/65c5612102dd4a5b81cea8f4bb00ba5b/work/65c5612102dd4a5b81cea8f4bb00ba5b_id_rsa (zuul-build-sshkey) 2025-10-01 10:43:42.508536 | controller -> localhost | ok: Runtime: 0:00:00.027881 2025-10-01 10:43:42.514445 | 2025-10-01 10:43:42.514534 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-01 10:43:43.058243 | controller | ok 2025-10-01 10:43:43.065272 | 2025-10-01 10:43:43.065360 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-01 10:43:43.090795 | controller | skipping: Conditional result was False 2025-10-01 10:43:43.101154 | 2025-10-01 10:43:43.101243 | TASK [include_role : validate-host] 2025-10-01 10:43:43.130519 | controller | ok 2025-10-01 10:43:43.155385 | 2025-10-01 10:43:43.155560 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-01 10:43:43.191133 | controller | ok 2025-10-01 10:43:43.197765 | 2025-10-01 10:43:43.197845 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-01 10:43:43.457210 | controller -> localhost | ok 2025-10-01 10:43:43.464044 | 2025-10-01 10:43:43.464188 | TASK [validate-host : Collect information about the host] 2025-10-01 10:43:44.352988 | controller | ok 2025-10-01 10:43:44.362629 | 2025-10-01 10:43:44.362709 | TASK [validate-host : Sanitize hostname] 2025-10-01 10:43:44.432041 | controller | ok 2025-10-01 10:43:44.437864 | 2025-10-01 10:43:44.437935 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-01 10:43:44.991210 | controller -> localhost | changed 2025-10-01 10:43:44.997201 | 2025-10-01 10:43:44.997262 | TASK [validate-host : Collect information about zuul worker] 2025-10-01 10:43:45.520149 | controller | ok 2025-10-01 10:43:45.528462 | 2025-10-01 10:43:45.528555 | TASK [validate-host : Write out all zuul information for each host] 2025-10-01 10:43:46.000932 | controller -> localhost | changed 2025-10-01 10:43:46.012410 | 2025-10-01 10:43:46.012480 | TASK [include_role : prepare-workspace-openshift] 2025-10-01 10:43:46.027390 | controller | skipping: Conditional result was False 2025-10-01 10:43:46.034093 | 2025-10-01 10:43:46.034158 | TASK [include_role : remove-zuul-sshkey] 2025-10-01 10:43:46.058212 | controller | skipping: Conditional result was False 2025-10-01 10:43:46.064042 | 2025-10-01 10:43:46.064106 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-01 10:43:46.502697 | controller | ok: "logs" 2025-10-01 10:43:46.502997 | controller | ok: All items complete 2025-10-01 10:43:46.503035 | 2025-10-01 10:43:46.855032 | controller | ok: "artifacts" 2025-10-01 10:43:47.154026 | controller | ok: "docs" 2025-10-01 10:43:47.170798 | 2025-10-01 10:43:47.170951 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-01 10:43:47.572644 | controller | changed: "logs" 2025-10-01 10:43:47.926936 | controller | changed: "artifacts" 2025-10-01 10:43:48.297261 | controller | changed: "docs" 2025-10-01 10:43:48.341492 | 2025-10-01 10:43:48.341592 | PLAY RECAP 2025-10-01 10:43:48.341650 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-01 10:43:48.341713 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-01 10:43:48.341765 | 2025-10-01 10:43:48.446249 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-01 10:43:48.447037 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-01 10:43:49.146911 | 2025-10-01 10:43:49.147041 | PLAY [all] 2025-10-01 10:43:49.171533 | 2025-10-01 10:43:49.171670 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-01 10:43:49.232512 | controller | ok 2025-10-01 10:43:49.237965 | 2025-10-01 10:43:49.238128 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-01 10:43:49.870385 | controller | changed 2025-10-01 10:43:49.881332 | 2025-10-01 10:43:49.881641 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-01 10:43:52.077005 | controller | changed 2025-10-01 10:43:52.088520 | 2025-10-01 10:43:52.088664 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-01 10:43:52.733024 | controller | changed: 2025-10-01 10:43:52.733403 | controller | { 2025-10-01 10:43:52.733436 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-01 10:43:52.733608 | controller | } 2025-10-01 10:43:53.135873 | controller | changed: 2025-10-01 10:43:53.135972 | controller | { 2025-10-01 10:43:53.136001 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-01 10:43:53.136022 | controller | } 2025-10-01 10:43:53.568278 | controller | changed: 2025-10-01 10:43:53.568379 | controller | { 2025-10-01 10:43:53.568402 | 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:43:53.568420 | controller | } 2025-10-01 10:43:53.992970 | controller | changed: 2025-10-01 10:43:53.993068 | controller | { 2025-10-01 10:43:53.993092 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-01 10:43:53.993110 | controller | } 2025-10-01 10:43:54.435722 | controller | changed: 2025-10-01 10:43:54.435837 | controller | { 2025-10-01 10:43:54.435860 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-01 10:43:54.435879 | controller | } 2025-10-01 10:43:54.916271 | controller | changed: 2025-10-01 10:43:54.916369 | controller | { 2025-10-01 10:43:54.916394 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-01 10:43:54.916413 | controller | } 2025-10-01 10:43:55.426444 | controller | changed: 2025-10-01 10:43:55.426686 | controller | { 2025-10-01 10:43:55.426716 | 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:43:55.426753 | controller | } 2025-10-01 10:43:55.776879 | controller | changed: 2025-10-01 10:43:55.778525 | controller | { 2025-10-01 10:43:55.778585 | 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:43:55.778615 | controller | } 2025-10-01 10:43:56.206136 | controller | changed: 2025-10-01 10:43:56.206307 | controller | { 2025-10-01 10:43:56.206359 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-01 10:43:56.206399 | controller | } 2025-10-01 10:43:56.619808 | controller | changed: 2025-10-01 10:43:56.619913 | controller | { 2025-10-01 10:43:56.619937 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-01 10:43:56.619957 | controller | } 2025-10-01 10:43:57.058001 | controller | changed: 2025-10-01 10:43:57.058113 | controller | { 2025-10-01 10:43:57.058144 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-01 10:43:57.058164 | controller | } 2025-10-01 10:43:57.450636 | controller | changed: 2025-10-01 10:43:57.450787 | controller | { 2025-10-01 10:43:57.450827 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-01 10:43:57.450857 | controller | } 2025-10-01 10:43:57.870665 | controller | changed: 2025-10-01 10:43:57.870829 | controller | { 2025-10-01 10:43:57.870869 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-01 10:43:57.870900 | controller | } 2025-10-01 10:43:58.255474 | controller | changed: 2025-10-01 10:43:58.255578 | controller | { 2025-10-01 10:43:58.255602 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-01 10:43:58.255621 | controller | } 2025-10-01 10:43:58.679689 | controller | changed: 2025-10-01 10:43:58.679868 | controller | { 2025-10-01 10:43:58.679927 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-01 10:43:58.679976 | controller | } 2025-10-01 10:43:59.094407 | controller | changed: 2025-10-01 10:43:59.094515 | controller | { 2025-10-01 10:43:59.094538 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-01 10:43:59.094557 | controller | } 2025-10-01 10:43:59.541435 | controller | changed: 2025-10-01 10:43:59.541575 | controller | { 2025-10-01 10:43:59.541618 | 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:43:59.541655 | controller | } 2025-10-01 10:44:00.010643 | controller | changed: 2025-10-01 10:44:00.010768 | controller | { 2025-10-01 10:44:00.010793 | 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:00.010822 | controller | } 2025-10-01 10:44:00.491070 | controller | changed: 2025-10-01 10:44:00.492099 | controller | { 2025-10-01 10:44:00.492183 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-01 10:44:00.492224 | controller | } 2025-10-01 10:44:00.938127 | controller | changed: 2025-10-01 10:44:00.938306 | controller | { 2025-10-01 10:44:00.938367 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-01 10:44:00.938410 | controller | } 2025-10-01 10:44:01.437670 | controller | changed: 2025-10-01 10:44:01.437811 | controller | { 2025-10-01 10:44:01.437839 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-01 10:44:01.437858 | controller | } 2025-10-01 10:44:01.894503 | controller | changed: 2025-10-01 10:44:01.894632 | controller | { 2025-10-01 10:44:01.894673 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-01 10:44:01.894708 | controller | } 2025-10-01 10:44:02.350422 | controller | changed: 2025-10-01 10:44:02.350639 | controller | { 2025-10-01 10:44:02.350707 | 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:02.350867 | controller | } 2025-10-01 10:44:02.807870 | controller | changed: 2025-10-01 10:44:02.808121 | controller | { 2025-10-01 10:44:02.808181 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-01 10:44:02.808223 | controller | } 2025-10-01 10:44:03.257260 | controller | changed: 2025-10-01 10:44:03.257386 | controller | { 2025-10-01 10:44:03.257417 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-01 10:44:03.257441 | controller | } 2025-10-01 10:44:03.734837 | controller | changed: 2025-10-01 10:44:03.735055 | controller | { 2025-10-01 10:44:03.735099 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-01 10:44:03.735129 | controller | } 2025-10-01 10:44:03.771783 | 2025-10-01 10:44:03.771923 | TASK [Set timezone to UTC] 2025-10-01 10:44:04.705804 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-01 10:44:04.719720 | 2025-10-01 10:44:04.719961 | TASK [Create nodepool directory] 2025-10-01 10:44:05.173344 | controller | changed 2025-10-01 10:44:05.195857 | 2025-10-01 10:44:05.195977 | TASK [Create nodepool sub_nodes file] 2025-10-01 10:44:07.009876 | controller | changed 2025-10-01 10:44:07.019547 | 2025-10-01 10:44:07.019693 | TASK [Create nodepool sub_nodes_private file] 2025-10-01 10:44:08.762500 | controller | changed 2025-10-01 10:44:08.768190 | 2025-10-01 10:44:08.768262 | LOOP [Populate nodepool sub_nodes file] 2025-10-01 10:44:08.800047 | 2025-10-01 10:44:08.800237 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-01 10:44:08.830064 | 2025-10-01 10:44:08.830219 | TASK [Create nodepool primary file] 2025-10-01 10:44:08.853866 | controller | skipping: Conditional result was False 2025-10-01 10:44:08.859494 | 2025-10-01 10:44:08.859572 | TASK [Create nodepool node_private for this node] 2025-10-01 10:44:10.774827 | controller | changed 2025-10-01 10:44:10.786562 | 2025-10-01 10:44:10.786670 | LOOP [Copy ssh keys to nodepool directory] 2025-10-01 10:44:11.391121 | controller | ok: Item: id_rsa Runtime: 0:00:00.011270 2025-10-01 10:44:11.391319 | 2025-10-01 10:44:11.763390 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.009041 2025-10-01 10:44:11.774285 | 2025-10-01 10:44:11.774410 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-01 10:44:13.575997 | controller | changed 2025-10-01 10:44:13.581315 | 2025-10-01 10:44:13.581419 | TASK [Validate sudoers config after edits] 2025-10-01 10:44:14.134551 | controller | /etc/sudoers: parsed OK 2025-10-01 10:44:14.134745 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-01 10:44:14.134766 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-01 10:44:14.134781 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-01 10:44:14.176074 | controller | ok: Runtime: 0:00:00.011251 2025-10-01 10:44:14.190011 | 2025-10-01 10:44:14.190156 | TASK [Show the environment passed in to job shell scripts] 2025-10-01 10:44:14.607759 | controller | SHELL=/bin/bash 2025-10-01 10:44:14.607853 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-01 10:44:14.607866 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-01 10:44:14.607895 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/37/3237/9f82bbc44cf7fa37a2cf6305277fe598af8c6e39 2025-10-01 10:44:14.607905 | controller | PWD=/home/zuul 2025-10-01 10:44:14.607914 | controller | ZUUL_PIPELINE=github-check 2025-10-01 10:44:14.607923 | controller | LOGNAME=zuul 2025-10-01 10:44:14.607932 | controller | XDG_SESSION_TYPE=tty 2025-10-01 10:44:14.607941 | controller | _=/usr/bin/env 2025-10-01 10:44:14.607950 | controller | MOTD_SHOWN=pam 2025-10-01 10:44:14.607983 | controller | HOME=/home/zuul 2025-10-01 10:44:14.607993 | controller | LANG=en_US.UTF-8 2025-10-01 10:44:14.608002 | controller | SSH_CONNECTION=38.102.83.114 34838 38.129.56.204 22 2025-10-01 10:44:14.608011 | 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:14.608026 | controller | ZUUL_CHANGE_IDS=3237,9f82bbc44cf7fa37a2cf6305277fe598af8c6e39 2025-10-01 10:44:14.608035 | controller | WORKSPACE=/home/zuul/workspace 2025-10-01 10:44:14.608044 | controller | XDG_SESSION_CLASS=user 2025-10-01 10:44:14.608056 | controller | SELINUX_ROLE_REQUESTED= 2025-10-01 10:44:14.608130 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-01 10:44:14.608145 | controller | USER=zuul 2025-10-01 10:44:14.608154 | controller | ZUUL_VOTING=True 2025-10-01 10:44:14.608163 | controller | BUILD_TIMEOUT=1800000 2025-10-01 10:44:14.608172 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-01 10:44:14.608180 | controller | SHLVL=1 2025-10-01 10:44:14.608190 | controller | ZUUL_PATCHSET=9f82bbc44cf7fa37a2cf6305277fe598af8c6e39 2025-10-01 10:44:14.608199 | controller | XDG_SESSION_ID=1 2025-10-01 10:44:14.608208 | controller | ZUUL_BRANCH=main 2025-10-01 10:44:14.608217 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-01 10:44:14.608226 | controller | SSH_CLIENT=38.102.83.114 34838 22 2025-10-01 10:44:14.608236 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-01 10:44:14.608245 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-01 10:44:14.608256 | controller | which_declare=declare -f 2025-10-01 10:44:14.608267 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-01 10:44:14.608276 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-01 10:44:14.608285 | controller | ZUUL_CHANGE=3237 2025-10-01 10:44:14.608293 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-01 10:44:14.608302 | controller | ZUUL_UUID=65c5612102dd4a5b81cea8f4bb00ba5b 2025-10-01 10:44:14.608312 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-01 10:44:14.608321 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-01 10:44:14.608330 | controller | } 2025-10-01 10:44:14.736368 | controller | ok: Runtime: 0:00:00.012973 2025-10-01 10:44:14.741909 | 2025-10-01 10:44:14.741973 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-01 10:44:14.765841 | controller | skipping: Conditional result was False 2025-10-01 10:44:14.771607 | 2025-10-01 10:44:14.771678 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-01 10:44:15.408030 | controller | skipping: Conditional result was False 2025-10-01 10:44:15.413845 | 2025-10-01 10:44:15.413918 | TASK [Ensure legacy workspace directory] 2025-10-01 10:44:15.816860 | controller | changed 2025-10-01 10:44:15.836571 | 2025-10-01 10:44:15.836662 | PLAY RECAP 2025-10-01 10:44:15.836704 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-01 10:44:15.836769 | 2025-10-01 10:44:15.954317 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-01 10:44:15.955085 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-01 10:44:16.743302 | 2025-10-01 10:44:16.743460 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-01 10:44:16.777445 | 2025-10-01 10:44:16.777626 | TASK [Create zuul-output directory] 2025-10-01 10:44:17.376553 | controller | changed 2025-10-01 10:44:17.384794 | 2025-10-01 10:44:17.384901 | TASK [Slurp Zuul inventory test] 2025-10-01 10:44:17.961220 | controller -> localhost | ok 2025-10-01 10:44:17.968250 | 2025-10-01 10:44:17.968322 | TASK [Save zuul inventory] 2025-10-01 10:44:20.030070 | controller | changed 2025-10-01 10:44:20.038166 | 2025-10-01 10:44:20.038246 | TASK [Save zuul vars without the change_message] 2025-10-01 10:44:21.897244 | controller | changed 2025-10-01 10:44:21.951270 | 2025-10-01 10:44:21.951436 | PLAY RECAP 2025-10-01 10:44:21.951505 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-01 10:44:21.951549 | 2025-10-01 10:44:22.191495 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-01 10:44:22.192336 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-01 10:44:26.107978 | 2025-10-01 10:44:26.108103 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-10-01 10:44:26.131389 | 2025-10-01 10:44:26.131530 | TASK [Read group_vars] 2025-10-01 10:44:26.161255 | controller | ok 2025-10-01 10:44:26.180967 | 2025-10-01 10:44:26.181104 | TASK [cifmw_helpers : Check directory is available] 2025-10-01 10:44:26.743068 | controller | ok 2025-10-01 10:44:26.751231 | 2025-10-01 10:44:26.751300 | TASK [cifmw_helpers : List dir in ansible user home] 2025-10-01 10:44:27.320707 | controller | var one 2025-10-01 10:44:27.324185 | 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:27.327501 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory 2025-10-01 10:44:27.330696 | controller | ls: cannot access '/root/src/github.com/openstack-k8s-operators/ci-framework/group_vars': Permission denied 2025-10-01 10:44:27.333709 | 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:27.789342 | controller | ERROR 2025-10-01 10:44:27.789569 | controller | { 2025-10-01 10:44:27.789615 | controller | "delta": "0:00:00.021389", 2025-10-01 10:44:27.789636 | controller | "end": "2025-10-01 10:44:27.334395", 2025-10-01 10:44:27.789657 | controller | "msg": "non-zero return code", 2025-10-01 10:44:27.789712 | controller | "rc": 2, 2025-10-01 10:44:27.789762 | controller | "start": "2025-10-01 10:44:27.313006" 2025-10-01 10:44:27.789783 | controller | } 2025-10-01 10:44:27.789808 | controller | ERROR: Ignoring Errors 2025-10-01 10:44:27.796822 | 2025-10-01 10:44:27.796889 | TASK [cifmw_helpers : List available files] 2025-10-01 10:44:28.327538 | controller | skipping: Conditional result was False 2025-10-01 10:44:28.346119 | 2025-10-01 10:44:28.346267 | LOOP [cifmw_helpers : Read vars] 2025-10-01 10:44:28.392034 | 2025-10-01 10:44:28.392248 | LOOP [cifmw_helpers : Call task to parse all files as fact] 2025-10-01 10:44:28.431217 | 2025-10-01 10:44:28.431375 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-10-01 10:44:28.458645 | controller | ok 2025-10-01 10:44:28.508207 | 2025-10-01 10:44:28.508322 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-01 10:44:28.532490 | controller | skipping: Conditional result was False 2025-10-01 10:44:28.538761 | 2025-10-01 10:44:28.538828 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-01 10:44:29.028360 | controller | ok 2025-10-01 10:44:29.035997 | 2025-10-01 10:44:29.036074 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-01 10:44:30.436651 | controller | ok 2025-10-01 10:44:30.462691 | 2025-10-01 10:44:30.462866 | TASK [Prepare workspace] 2025-10-01 10:44:30.505935 | controller | ok 2025-10-01 10:44:30.565944 | 2025-10-01 10:44:30.566095 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-01 10:44:31.044522 | controller | ok 2025-10-01 10:44:31.051350 | 2025-10-01 10:44:31.051441 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-01 10:44:35.641144 | controller | Output suppressed because no_log was given 2025-10-01 10:44:35.665280 | 2025-10-01 10:44:35.665461 | LOOP [Create zuul-output directory] 2025-10-01 10:44:36.088706 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-10-01 10:44:36.089041 | 2025-10-01 10:44:36.474398 | controller | ok: "/home/zuul/zuul-output/logs" 2025-10-01 10:44:36.506172 | 2025-10-01 10:44:36.506336 | TASK [Install required packages] 2025-10-01 10:44:52.461782 | controller | ok: Nothing to do 2025-10-01 10:44:52.475568 | 2025-10-01 10:44:52.475691 | TASK [Install venv] 2025-10-01 10:45:55.463572 | controller | changed 2025-10-01 10:45:55.491159 | 2025-10-01 10:45:55.491255 | PLAY RECAP 2025-10-01 10:45:55.491296 | controller | ok: 9 changed: 4 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1 2025-10-01 10:45:55.491318 | 2025-10-01 10:45:55.599679 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-01 10:45:55.600609 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-01 10:45:56.162151 | 2025-10-01 10:45:56.162267 | PLAY [Run ci/playbooks/molecule-test.yml] 2025-10-01 10:45:56.195442 | 2025-10-01 10:45:56.195580 | TASK [Gather required facts] 2025-10-01 10:45:56.930550 | controller | ok 2025-10-01 10:45:56.941517 | 2025-10-01 10:45:56.941676 | TASK [Load environment var if instructed to] 2025-10-01 10:45:56.967814 | controller | skipping: Conditional result was False 2025-10-01 10:45:56.978062 | 2025-10-01 10:45:56.978221 | TASK [Run molecule] 2025-10-01 10:45:58.323219 | controller | INFO Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml 2025-10-01 10:45:58.400141 | controller | INFO Performing prerun with role_name_check=0... 2025-10-01 10:46:16.242416 | controller | WARNING Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:46:16.243452 | controller | WARNING Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:46:16.244446 | controller | WARNING Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:46:16.245452 | controller | WARNING Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:46:16.246495 | controller | WARNING Another version of 'cifmw.general' 1.0.0+9f82bbc4 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 1.0.0+9f82bbc4 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:46:16.247441 | controller | WARNING Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:46:16.248444 | controller | WARNING Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:46:16.249536 | controller | WARNING Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:46:16.250490 | controller | WARNING Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:46:16.251425 | controller | WARNING Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:46:16.252366 | controller | WARNING Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:46:16.253341 | controller | WARNING Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:46:16.254297 | controller | WARNING Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:46:16.255257 | controller | WARNING Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:46:16.256222 | controller | WARNING Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:46:16.277948 | controller | INFO Running default > prepare 2025-10-01 10:46:17.075154 | controller | 2025-10-01 10:46:17.075385 | controller | PLAY [Prepare] ***************************************************************** 2025-10-01 10:46:17.075646 | controller | 2025-10-01 10:46:17.075857 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-01 10:46:17.076097 | controller | Wednesday 01 October 2025 10:46:17 +0000 (0:00:00.020) 0:00:00.020 ***** 2025-10-01 10:46:18.272772 | controller | ok: [instance] 2025-10-01 10:46:18.272943 | controller | 2025-10-01 10:46:18.273176 | controller | TASK [test_deps : Gather facts if they don't exist] **************************** 2025-10-01 10:46:18.273418 | controller | Wednesday 01 October 2025 10:46:18 +0000 (0:00:01.198) 0:00:01.219 ***** 2025-10-01 10:46:18.311275 | controller | skipping: [instance] 2025-10-01 10:46:18.311452 | controller | 2025-10-01 10:46:18.311698 | controller | TASK [test_deps : Gather variables for each operating system] ****************** 2025-10-01 10:46:18.311916 | controller | Wednesday 01 October 2025 10:46:18 +0000 (0:00:00.038) 0:00:01.257 ***** 2025-10-01 10:46:18.394569 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml) 2025-10-01 10:46:18.394758 | controller | 2025-10-01 10:46:18.395007 | controller | TASK [test_deps : Detect mirrors file] ***************************************** 2025-10-01 10:46:18.395246 | controller | Wednesday 01 October 2025 10:46:18 +0000 (0:00:00.083) 0:00:01.341 ***** 2025-10-01 10:46:18.830174 | controller | ok: [instance] 2025-10-01 10:46:18.830250 | controller | 2025-10-01 10:46:18.830270 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] *********** 2025-10-01 10:46:18.830412 | controller | Wednesday 01 October 2025 10:46:18 +0000 (0:00:00.435) 0:00:01.776 ***** 2025-10-01 10:46:19.337254 | controller | ok: [instance] 2025-10-01 10:46:19.337338 | controller | 2025-10-01 10:46:19.337398 | controller | TASK [test_deps : Disable ubi 9 repos] ***************************************** 2025-10-01 10:46:19.337615 | controller | Wednesday 01 October 2025 10:46:19 +0000 (0:00:00.506) 0:00:02.282 ***** 2025-10-01 10:46:20.291219 | controller | ok: [instance] => (item=ubi-9-appstream) 2025-10-01 10:46:20.291311 | controller | ok: [instance] => (item=ubi-9-baseos) 2025-10-01 10:46:20.291447 | controller | ok: [instance] => (item=ubi-9-codeready-builder) 2025-10-01 10:46:20.291580 | controller | 2025-10-01 10:46:20.291710 | controller | TASK [Deploy repo-setup] ******************************************************* 2025-10-01 10:46:20.291870 | controller | Wednesday 01 October 2025 10:46:20 +0000 (0:00:00.954) 0:00:03.237 ***** 2025-10-01 10:46:20.359854 | controller | 2025-10-01 10:46:21.189591 | controller | TASK [repo_setup : Ensure directories are present] ***************************** 2025-10-01 10:46:21.189645 | controller | Wednesday 01 October 2025 10:46:20 +0000 (0:00:00.069) 0:00:03.306 ***** 2025-10-01 10:46:21.189672 | controller | changed: [instance] => (item=tmp) 2025-10-01 10:46:21.190035 | controller | changed: [instance] => (item=artifacts/repositories) 2025-10-01 10:46:21.190087 | controller | changed: [instance] => (item=venv/repo_setup) 2025-10-01 10:46:21.190106 | controller | 2025-10-01 10:46:21.190133 | controller | TASK [repo_setup : Make sure git-core package is installed] ******************** 2025-10-01 10:46:21.190393 | controller | Wednesday 01 October 2025 10:46:21 +0000 (0:00:00.828) 0:00:04.134 ***** 2025-10-01 10:46:22.194626 | controller | ok: [instance] 2025-10-01 10:46:22.194674 | controller | 2025-10-01 10:46:22.194688 | controller | TASK [repo_setup : Get repo-setup repository] ********************************** 2025-10-01 10:46:22.194710 | controller | Wednesday 01 October 2025 10:46:22 +0000 (0:00:01.005) 0:00:05.140 ***** 2025-10-01 10:46:23.437709 | controller | changed: [instance] 2025-10-01 10:46:23.437815 | controller | 2025-10-01 10:46:23.437847 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************ 2025-10-01 10:46:23.437865 | controller | Wednesday 01 October 2025 10:46:23 +0000 (0:00:01.242) 0:00:06.383 ***** 2025-10-01 10:46:31.659689 | controller | changed: [instance] 2025-10-01 10:46:32.698064 | controller | 2025-10-01 10:46:32.698138 | controller | TASK [repo_setup : Install repo-setup package] ********************************* 2025-10-01 10:46:32.698155 | controller | Wednesday 01 October 2025 10:46:31 +0000 (0:00:08.221) 0:00:14.604 ***** 2025-10-01 10:46:32.698179 | controller | changed: [instance] 2025-10-01 10:46:32.747670 | controller | 2025-10-01 10:46:32.747726 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] *** 2025-10-01 10:46:32.747742 | controller | Wednesday 01 October 2025 10:46:32 +0000 (0:00:01.038) 0:00:15.643 ***** 2025-10-01 10:46:32.747764 | controller | skipping: [instance] 2025-10-01 10:46:32.748170 | controller | 2025-10-01 10:46:32.748231 | controller | TASK [repo_setup : Run repo-setup] ********************************************* 2025-10-01 10:46:33.582598 | controller | Wednesday 01 October 2025 10:46:32 +0000 (0:00:00.049) 0:00:15.693 ***** 2025-10-01 10:46:33.582657 | controller | changed: [instance] 2025-10-01 10:46:33.583094 | controller | 2025-10-01 10:46:33.583137 | controller | TASK [repo_setup : Get component repo] ***************************************** 2025-10-01 10:46:33.641648 | controller | Wednesday 01 October 2025 10:46:33 +0000 (0:00:00.834) 0:00:16.528 ***** 2025-10-01 10:46:33.641720 | controller | skipping: [instance] 2025-10-01 10:46:33.642198 | controller | 2025-10-01 10:46:33.642245 | controller | TASK [repo_setup : Rename component repo] ************************************** 2025-10-01 10:46:33.722499 | controller | Wednesday 01 October 2025 10:46:33 +0000 (0:00:00.059) 0:00:16.587 ***** 2025-10-01 10:46:33.722567 | controller | skipping: [instance] 2025-10-01 10:46:33.722620 | controller | 2025-10-01 10:46:33.722861 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] ******* 2025-10-01 10:46:33.723735 | controller | Wednesday 01 October 2025 10:46:33 +0000 (0:00:00.081) 0:00:16.668 ***** 2025-10-01 10:46:33.801707 | controller | skipping: [instance] 2025-10-01 10:46:33.801929 | controller | 2025-10-01 10:46:33.802190 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************ 2025-10-01 10:46:33.802407 | controller | Wednesday 01 October 2025 10:46:33 +0000 (0:00:00.079) 0:00:16.747 ***** 2025-10-01 10:46:34.428232 | controller | changed: [instance] 2025-10-01 10:46:34.428539 | controller | 2025-10-01 10:46:34.428799 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] ******************* 2025-10-01 10:46:34.429139 | controller | Wednesday 01 October 2025 10:46:34 +0000 (0:00:00.625) 0:00:17.373 ***** 2025-10-01 10:46:35.100740 | controller | changed: [instance] 2025-10-01 10:46:35.101052 | controller | 2025-10-01 10:46:35.101474 | controller | TASK [repo_setup : Dump current-podified hash] ********************************* 2025-10-01 10:46:35.101822 | controller | Wednesday 01 October 2025 10:46:35 +0000 (0:00:00.673) 0:00:18.047 ***** 2025-10-01 10:46:35.158664 | controller | skipping: [instance] 2025-10-01 10:46:35.158987 | controller | 2025-10-01 10:46:35.159351 | controller | TASK [repo_setup : Slurp current podified hash] ******************************** 2025-10-01 10:46:35.159765 | controller | Wednesday 01 October 2025 10:46:35 +0000 (0:00:00.057) 0:00:18.104 ***** 2025-10-01 10:46:35.222857 | controller | skipping: [instance] 2025-10-01 10:46:35.222962 | controller | 2025-10-01 10:46:35.223174 | controller | TASK [repo_setup : Update the value of full_hash] ****************************** 2025-10-01 10:46:35.223335 | controller | Wednesday 01 October 2025 10:46:35 +0000 (0:00:00.063) 0:00:18.168 ***** 2025-10-01 10:46:35.262259 | controller | skipping: [instance] 2025-10-01 10:46:35.262333 | controller | 2025-10-01 10:46:35.262496 | controller | TASK [repo_setup : Export hashes facts for further use] ************************ 2025-10-01 10:46:35.262622 | controller | Wednesday 01 October 2025 10:46:35 +0000 (0:00:00.039) 0:00:18.208 ***** 2025-10-01 10:46:35.323724 | controller | ok: [instance] 2025-10-01 10:46:35.323933 | controller | 2025-10-01 10:46:35.324200 | controller | TASK [repo_setup : Create download directory] ********************************** 2025-10-01 10:46:35.324466 | controller | Wednesday 01 October 2025 10:46:35 +0000 (0:00:00.061) 0:00:18.270 ***** 2025-10-01 10:46:35.370106 | controller | skipping: [instance] 2025-10-01 10:46:35.370402 | controller | 2025-10-01 10:46:35.370777 | controller | TASK [repo_setup : Print the URL to request] *********************************** 2025-10-01 10:46:35.371122 | controller | Wednesday 01 October 2025 10:46:35 +0000 (0:00:00.045) 0:00:18.315 ***** 2025-10-01 10:46:35.435680 | controller | skipping: [instance] 2025-10-01 10:46:35.435833 | controller | 2025-10-01 10:46:35.435972 | controller | TASK [Download the RPM] ******************************************************** 2025-10-01 10:46:35.436178 | controller | Wednesday 01 October 2025 10:46:35 +0000 (0:00:00.066) 0:00:18.382 ***** 2025-10-01 10:46:35.477589 | controller | skipping: [instance] 2025-10-01 10:46:35.477740 | controller | 2025-10-01 10:46:35.477898 | controller | TASK [repo_setup : Install RHOS Release tool] ********************************** 2025-10-01 10:46:35.478026 | controller | Wednesday 01 October 2025 10:46:35 +0000 (0:00:00.042) 0:00:18.424 ***** 2025-10-01 10:46:35.524508 | controller | skipping: [instance] 2025-10-01 10:46:35.524603 | controller | 2025-10-01 10:46:35.524779 | controller | TASK [repo_setup : Get rhos-release tool version] ****************************** 2025-10-01 10:46:35.524922 | controller | Wednesday 01 October 2025 10:46:35 +0000 (0:00:00.046) 0:00:18.471 ***** 2025-10-01 10:46:35.608173 | controller | skipping: [instance] 2025-10-01 10:46:35.608547 | controller | 2025-10-01 10:46:35.608912 | controller | TASK [repo_setup : Print rhos-release tool version] **************************** 2025-10-01 10:46:35.609314 | controller | Wednesday 01 October 2025 10:46:35 +0000 (0:00:00.082) 0:00:18.554 ***** 2025-10-01 10:46:35.659784 | controller | skipping: [instance] 2025-10-01 10:46:35.660027 | controller | 2025-10-01 10:46:35.660317 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] *** 2025-10-01 10:46:35.660708 | controller | Wednesday 01 October 2025 10:46:35 +0000 (0:00:00.051) 0:00:18.605 ***** 2025-10-01 10:46:35.694846 | controller | skipping: [instance] 2025-10-01 10:46:35.695143 | controller | 2025-10-01 10:46:35.695471 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] *************************** 2025-10-01 10:46:35.695776 | controller | Wednesday 01 October 2025 10:46:35 +0000 (0:00:00.035) 0:00:18.641 ***** 2025-10-01 10:46:35.980644 | controller | ok: [instance] 2025-10-01 10:46:36.320950 | controller | 2025-10-01 10:46:36.321012 | controller | TASK [repo_setup : Use RDO proxy mirrors] ************************************** 2025-10-01 10:46:36.321029 | controller | Wednesday 01 October 2025 10:46:35 +0000 (0:00:00.284) 0:00:18.925 ***** 2025-10-01 10:46:36.321083 | controller | changed: [instance] 2025-10-01 10:46:36.321144 | controller | 2025-10-01 10:46:36.321281 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] *** 2025-10-01 10:46:36.321325 | controller | Wednesday 01 October 2025 10:46:36 +0000 (0:00:00.341) 0:00:19.267 ***** 2025-10-01 10:46:36.676790 | controller | changed: [instance] 2025-10-01 10:46:36.724574 | controller | 2025-10-01 10:46:36.724662 | controller | TASK [repo_setup : Check for gating.repo file on content provider] ************* 2025-10-01 10:46:36.724684 | controller | Wednesday 01 October 2025 10:46:36 +0000 (0:00:00.354) 0:00:19.622 ***** 2025-10-01 10:46:36.724719 | controller | skipping: [instance] 2025-10-01 10:46:36.771698 | controller | 2025-10-01 10:46:36.771783 | controller | TASK [repo_setup : Populate gating repo from content provider ip] ************** 2025-10-01 10:46:36.771799 | controller | Wednesday 01 October 2025 10:46:36 +0000 (0:00:00.047) 0:00:19.669 ***** 2025-10-01 10:46:36.771826 | controller | skipping: [instance] 2025-10-01 10:46:36.771853 | controller | 2025-10-01 10:46:36.771866 | controller | TASK [repo_setup : Check for DLRN repo at the destination] ********************* 2025-10-01 10:46:36.771883 | controller | Wednesday 01 October 2025 10:46:36 +0000 (0:00:00.047) 0:00:19.717 ***** 2025-10-01 10:46:36.817568 | controller | skipping: [instance] 2025-10-01 10:46:36.817812 | controller | 2025-10-01 10:46:36.818046 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] *** 2025-10-01 10:46:36.818400 | controller | Wednesday 01 October 2025 10:46:36 +0000 (0:00:00.046) 0:00:19.763 ***** 2025-10-01 10:46:36.866053 | controller | skipping: [instance] 2025-10-01 10:46:36.866243 | controller | 2025-10-01 10:46:36.866506 | controller | TASK [repo_setup : Check for DLRN component repo] ****************************** 2025-10-01 10:46:36.866720 | controller | Wednesday 01 October 2025 10:46:36 +0000 (0:00:00.048) 0:00:19.812 ***** 2025-10-01 10:46:36.913121 | controller | skipping: [instance] 2025-10-01 10:46:36.913284 | controller | 2025-10-01 10:46:36.913567 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] *** 2025-10-01 10:46:36.913769 | controller | Wednesday 01 October 2025 10:46:36 +0000 (0:00:00.047) 0:00:19.859 ***** 2025-10-01 10:46:36.959878 | controller | skipping: [instance] 2025-10-01 10:46:36.960035 | controller | 2025-10-01 10:46:36.960273 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ******** 2025-10-01 10:46:36.960496 | controller | Wednesday 01 October 2025 10:46:36 +0000 (0:00:00.046) 0:00:19.906 ***** 2025-10-01 10:46:36.994602 | controller | skipping: [instance] 2025-10-01 10:46:36.994760 | controller | 2025-10-01 10:46:36.994973 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ****** 2025-10-01 10:46:36.995180 | controller | Wednesday 01 October 2025 10:46:36 +0000 (0:00:00.034) 0:00:19.941 ***** 2025-10-01 10:46:37.054992 | controller | skipping: [instance] 2025-10-01 10:46:37.055170 | controller | 2025-10-01 10:46:37.055395 | controller | TASK [repo_setup : Cleanup existing metadata] ********************************** 2025-10-01 10:46:37.055666 | controller | Wednesday 01 October 2025 10:46:37 +0000 (0:00:00.060) 0:00:20.001 ***** 2025-10-01 10:46:37.089374 | controller | skipping: [instance] 2025-10-01 10:46:37.089573 | controller | 2025-10-01 10:46:37.089792 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] ********* 2025-10-01 10:46:37.090033 | controller | Wednesday 01 October 2025 10:46:37 +0000 (0:00:00.034) 0:00:20.035 ***** 2025-10-01 10:46:37.135244 | controller | skipping: [instance] 2025-10-01 10:46:37.135453 | controller | 2025-10-01 10:46:37.135690 | controller | TASK [test_deps : Look for redhat-release rpm] ********************************* 2025-10-01 10:46:37.135908 | controller | Wednesday 01 October 2025 10:46:37 +0000 (0:00:00.045) 0:00:20.081 ***** 2025-10-01 10:46:37.190896 | controller | skipping: [instance] 2025-10-01 10:46:37.191075 | controller | 2025-10-01 10:46:37.191319 | controller | TASK [test_deps : Remove redhat-release] *************************************** 2025-10-01 10:46:37.191605 | controller | Wednesday 01 October 2025 10:46:37 +0000 (0:00:00.056) 0:00:20.137 ***** 2025-10-01 10:46:37.249087 | controller | skipping: [instance] 2025-10-01 10:46:37.249195 | controller | 2025-10-01 10:46:37.249492 | controller | TASK [test_deps : Install centos-stream-release] ******************************* 2025-10-01 10:46:37.249709 | controller | Wednesday 01 October 2025 10:46:37 +0000 (0:00:00.058) 0:00:20.195 ***** 2025-10-01 10:46:37.309731 | controller | skipping: [instance] 2025-10-01 10:46:37.309902 | controller | 2025-10-01 10:46:37.310118 | controller | TASK [test_deps : Install additional packages] ********************************* 2025-10-01 10:46:37.310328 | controller | Wednesday 01 October 2025 10:46:37 +0000 (0:00:00.060) 0:00:20.256 ***** 2025-10-01 10:46:37.373136 | controller | skipping: [instance] 2025-10-01 10:46:37.373398 | controller | 2025-10-01 10:46:37.373703 | controller | TASK [test_deps : Install selinux python libs] ********************************* 2025-10-01 10:46:37.373980 | controller | Wednesday 01 October 2025 10:46:37 +0000 (0:00:00.062) 0:00:20.319 ***** 2025-10-01 10:47:16.565729 | controller | ok: [instance] 2025-10-01 10:47:16.565850 | controller | 2025-10-01 10:47:16.566301 | controller | TASK [test_deps : Install python yaml libs] ************************************ 2025-10-01 10:47:18.094681 | controller | Wednesday 01 October 2025 10:47:16 +0000 (0:00:39.191) 0:00:59.511 ***** 2025-10-01 10:47:18.094753 | controller | ok: [instance] 2025-10-01 10:47:18.095323 | controller | 2025-10-01 10:47:18.095396 | controller | TASK [test_deps : Install extra packages] ************************************** 2025-10-01 10:47:18.148652 | controller | Wednesday 01 October 2025 10:47:18 +0000 (0:00:01.528) 0:01:01.040 ***** 2025-10-01 10:47:18.148760 | controller | skipping: [instance] 2025-10-01 10:47:18.148851 | controller | 2025-10-01 10:47:18.148872 | controller | TASK [ci_setup : Gather variables for each operating system] ******************* 2025-10-01 10:47:18.149120 | controller | Wednesday 01 October 2025 10:47:18 +0000 (0:00:00.054) 0:01:01.094 ***** 2025-10-01 10:47:18.215671 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml) 2025-10-01 10:47:18.215714 | controller | 2025-10-01 10:47:18.215989 | controller | TASK [ci_setup : List packages to install] ************************************* 2025-10-01 10:47:18.216223 | controller | Wednesday 01 October 2025 10:47:18 +0000 (0:00:00.067) 0:01:01.161 ***** 2025-10-01 10:47:18.257274 | controller | ok: [instance] => 2025-10-01 10:47:18.257551 | controller | cifmw_ci_setup_packages: 2025-10-01 10:47:18.257809 | controller | - bash-completion 2025-10-01 10:47:18.258051 | controller | - ca-certificates 2025-10-01 10:47:18.258283 | controller | - git-core 2025-10-01 10:47:18.258559 | controller | - make 2025-10-01 10:47:18.258820 | controller | - tar 2025-10-01 10:47:18.259051 | controller | - tmux 2025-10-01 10:47:18.259285 | controller | - python3-pip 2025-10-01 10:47:18.259532 | controller | 2025-10-01 10:47:18.259774 | controller | TASK [ci_setup : Install needed packages] ************************************** 2025-10-01 10:47:18.260043 | controller | Wednesday 01 October 2025 10:47:18 +0000 (0:00:00.041) 0:01:01.203 ***** 2025-10-01 10:47:23.890653 | controller | changed: [instance] 2025-10-01 10:47:23.890754 | controller | 2025-10-01 10:47:23.890778 | controller | TASK [ci_setup : Gather version of openshift client] *************************** 2025-10-01 10:47:25.268391 | controller | Wednesday 01 October 2025 10:47:23 +0000 (0:00:05.633) 0:01:06.836 ***** 2025-10-01 10:47:25.268504 | controller | ok: [instance] 2025-10-01 10:47:25.584320 | controller | 2025-10-01 10:47:25.584370 | controller | TASK [ci_setup : Ensure openshift client install path is present] ************** 2025-10-01 10:47:25.584386 | controller | Wednesday 01 October 2025 10:47:25 +0000 (0:00:01.376) 0:01:08.213 ***** 2025-10-01 10:47:25.584408 | controller | changed: [instance] 2025-10-01 10:47:25.584544 | controller | 2025-10-01 10:47:25.584662 | controller | TASK [ci_setup : Install openshift client] ************************************* 2025-10-01 10:47:25.584681 | controller | Wednesday 01 October 2025 10:47:25 +0000 (0:00:00.317) 0:01:08.531 ***** 2025-10-01 10:47:30.886513 | controller | changed: [instance] 2025-10-01 10:47:30.931686 | controller | 2025-10-01 10:47:30.931750 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] ********************** 2025-10-01 10:47:30.931767 | controller | Wednesday 01 October 2025 10:47:30 +0000 (0:00:05.301) 0:01:13.832 ***** 2025-10-01 10:47:30.931789 | controller | ok: [instance] 2025-10-01 10:47:30.931858 | controller | 2025-10-01 10:47:30.932259 | controller | TASK [ci_setup : Create completion file] *************************************** 2025-10-01 10:47:31.314605 | controller | Wednesday 01 October 2025 10:47:30 +0000 (0:00:00.045) 0:01:13.878 ***** 2025-10-01 10:47:31.314678 | controller | changed: [instance] 2025-10-01 10:47:31.315232 | controller | 2025-10-01 10:47:31.315309 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************ 2025-10-01 10:47:31.685121 | controller | Wednesday 01 October 2025 10:47:31 +0000 (0:00:00.382) 0:01:14.260 ***** 2025-10-01 10:47:31.685166 | controller | changed: [instance] 2025-10-01 10:47:31.720621 | controller | 2025-10-01 10:47:31.720675 | controller | TASK [ci_setup : Check rhsm status] ******************************************** 2025-10-01 10:47:31.720684 | controller | Wednesday 01 October 2025 10:47:31 +0000 (0:00:00.370) 0:01:14.631 ***** 2025-10-01 10:47:31.720696 | controller | skipping: [instance] 2025-10-01 10:47:31.720727 | controller | 2025-10-01 10:47:31.720736 | controller | TASK [ci_setup : Gather the repos to be enabled] ******************************* 2025-10-01 10:47:31.721055 | controller | Wednesday 01 October 2025 10:47:31 +0000 (0:00:00.034) 0:01:14.666 ***** 2025-10-01 10:47:31.756788 | controller | skipping: [instance] 2025-10-01 10:47:31.792415 | controller | 2025-10-01 10:47:31.792517 | controller | TASK [ci_setup : Enabling the required repositories.] ************************** 2025-10-01 10:47:31.792538 | controller | Wednesday 01 October 2025 10:47:31 +0000 (0:00:00.035) 0:01:14.701 ***** 2025-10-01 10:47:31.792563 | controller | skipping: [instance] 2025-10-01 10:47:31.792988 | controller | 2025-10-01 10:47:31.793372 | controller | TASK [ci_setup : Get current /etc/redhat-release] ****************************** 2025-10-01 10:47:31.793797 | controller | Wednesday 01 October 2025 10:47:31 +0000 (0:00:00.036) 0:01:14.738 ***** 2025-10-01 10:47:31.830222 | controller | skipping: [instance] 2025-10-01 10:47:31.830677 | controller | 2025-10-01 10:47:31.831076 | controller | TASK [ci_setup : Print current /etc/redhat-release] **************************** 2025-10-01 10:47:31.831512 | controller | Wednesday 01 October 2025 10:47:31 +0000 (0:00:00.037) 0:01:14.776 ***** 2025-10-01 10:47:31.859015 | controller | skipping: [instance] 2025-10-01 10:47:31.859332 | controller | 2025-10-01 10:47:31.859728 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] ********* 2025-10-01 10:47:31.860067 | controller | Wednesday 01 October 2025 10:47:31 +0000 (0:00:00.029) 0:01:14.805 ***** 2025-10-01 10:47:31.891248 | controller | skipping: [instance] 2025-10-01 10:47:31.891592 | controller | 2025-10-01 10:47:31.891900 | controller | TASK [ci_setup : Manage directories] ******************************************* 2025-10-01 10:47:31.892195 | controller | Wednesday 01 October 2025 10:47:31 +0000 (0:00:00.032) 0:01:14.837 ***** 2025-10-01 10:47:33.246680 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr) 2025-10-01 10:47:33.246777 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs) 2025-10-01 10:47:33.246796 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp) 2025-10-01 10:47:33.246814 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes) 2025-10-01 10:47:33.247392 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters) 2025-10-01 10:50:38.823766 | controller | 2025-10-01 10:50:38.823827 | controller | TASK [Ensure CRC is started] *************************************************** 2025-10-01 10:50:38.823839 | controller | Wednesday 01 October 2025 10:47:33 +0000 (0:00:01.354) 0:01:16.192 ***** 2025-10-01 10:50:38.823852 | controller | changed: [instance] 2025-10-01 10:50:40.095805 | controller | 2025-10-01 10:50:40.095858 | controller | TASK [Fetch install_yamls repository] ****************************************** 2025-10-01 10:50:40.095900 | controller | Wednesday 01 October 2025 10:50:38 +0000 (0:03:05.572) 0:04:21.765 ***** 2025-10-01 10:50:40.095922 | controller | changed: [instance] 2025-10-01 10:50:40.096146 | controller | 2025-10-01 10:50:40.096370 | controller | PLAY RECAP ********************************************************************* 2025-10-01 10:50:40.096396 | controller | instance : ok=31 changed=17 unreachable=0 failed=0 skipped=36 rescued=0 ignored=0 2025-10-01 10:50:40.096715 | controller | 2025-10-01 10:50:40.097568 | controller | Wednesday 01 October 2025 10:50:40 +0000 (0:00:01.277) 0:04:23.042 ***** 2025-10-01 10:50:40.097769 | controller | =============================================================================== 2025-10-01 10:50:40.097792 | controller | Ensure CRC is started ------------------------------------------------- 185.57s 2025-10-01 10:50:40.097806 | controller | test_deps : Install selinux python libs -------------------------------- 39.19s 2025-10-01 10:50:40.097824 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.22s 2025-10-01 10:50:40.098040 | controller | ci_setup : Install needed packages -------------------------------------- 5.63s 2025-10-01 10:50:40.098769 | controller | ci_setup : Install openshift client ------------------------------------- 5.30s 2025-10-01 10:50:40.098804 | controller | test_deps : Install python yaml libs ------------------------------------ 1.53s 2025-10-01 10:50:40.099170 | controller | ci_setup : Gather version of openshift client --------------------------- 1.38s 2025-10-01 10:50:40.099194 | controller | ci_setup : Manage directories ------------------------------------------- 1.35s 2025-10-01 10:50:40.099213 | controller | Fetch install_yamls repository ------------------------------------------ 1.28s 2025-10-01 10:50:40.099489 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.24s 2025-10-01 10:50:40.099539 | controller | Gathering Facts --------------------------------------------------------- 1.20s 2025-10-01 10:50:40.099712 | controller | repo_setup : Install repo-setup package --------------------------------- 1.04s 2025-10-01 10:50:40.099820 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.01s 2025-10-01 10:50:40.100077 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.95s 2025-10-01 10:50:40.100332 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.83s 2025-10-01 10:50:40.100602 | controller | repo_setup : Ensure directories are present ----------------------------- 0.83s 2025-10-01 10:50:40.100870 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.67s 2025-10-01 10:50:40.101127 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.63s 2025-10-01 10:50:40.101372 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.51s 2025-10-01 10:50:40.101650 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.44s 2025-10-01 10:50:40.202738 | controller | INFO Running default > converge 2025-10-01 10:50:40.743331 | controller | 2025-10-01 10:50:40.743564 | controller | PLAY [Converge] **************************************************************** 2025-10-01 10:50:40.744959 | controller | 2025-10-01 10:50:41.928356 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-01 10:50:41.928398 | controller | Wednesday 01 October 2025 10:50:40 +0000 (0:00:00.036) 0:00:00.036 ***** 2025-10-01 10:50:41.928415 | controller | ok: [instance] 2025-10-01 10:52:28.179844 | controller | 2025-10-01 10:52:28.179971 | controller | TASK [operator_deploy : Deploy selected operators] ***************************** 2025-10-01 10:52:28.179989 | controller | Wednesday 01 October 2025 10:50:41 +0000 (0:00:01.183) 0:00:01.220 ***** 2025-10-01 10:52:28.180015 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_000_deploy_selected.log 2025-10-01 10:52:28.260205 | controller | changed: [instance] => (item={'name': 'keystone'}) 2025-10-01 10:52:28.260309 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_001_deploy_selected.log 2025-10-01 10:52:28.260326 | controller | changed: [instance] => (item={'name': 'rabbitmq', 'params': {'RABBITMQ_IMG': 'quay.io/openstack-k8s-operators/rabbitmq-cluster-operator-index:latest'}}) 2025-10-01 10:52:28.260340 | controller | 2025-10-01 10:52:28.260353 | controller | PLAY RECAP ********************************************************************* 2025-10-01 10:52:28.260366 | controller | instance : ok=2 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2025-10-01 10:52:28.260380 | controller | 2025-10-01 10:52:28.260393 | controller | Wednesday 01 October 2025 10:52:28 +0000 (0:01:46.250) 0:01:47.470 ***** 2025-10-01 10:52:28.260408 | controller | =============================================================================== 2025-10-01 10:52:28.260421 | controller | operator_deploy : Deploy selected operators --------------------------- 106.25s 2025-10-01 10:52:28.260464 | controller | Gathering Facts --------------------------------------------------------- 1.18s 2025-10-01 10:52:28.260493 | controller | INFO Running default > cleanup 2025-10-01 10:52:28.261448 | controller | WARNING Skipping, cleanup playbook not configured. 2025-10-01 10:52:28.262711 | controller | INFO Writing /tmp/report.html report. 2025-10-01 10:52:28.533401 | controller | changed 2025-10-01 10:52:28.580372 | 2025-10-01 10:52:28.580492 | PLAY RECAP 2025-10-01 10:52:28.580566 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-01 10:52:28.580609 | 2025-10-01 10:52:28.690632 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-01 10:52:28.692606 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-01 10:52:29.338116 | 2025-10-01 10:52:29.338287 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-01 10:52:29.359145 | 2025-10-01 10:52:29.359266 | TASK [Filter out host if needed] 2025-10-01 10:52:29.368140 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-01 10:52:29.372661 | 2025-10-01 10:52:29.372737 | TASK [Ensure file is present] 2025-10-01 10:52:30.025152 | controller | ok 2025-10-01 10:52:30.041217 | 2025-10-01 10:52:30.041384 | TASK [Manage molecule report file] 2025-10-01 10:52:31.090094 | controller | changed 2025-10-01 10:52:31.095989 | 2025-10-01 10:52:31.096088 | TASK [Check if we get ci-framework-data basedir] 2025-10-01 10:52:31.498694 | controller | ok 2025-10-01 10:52:31.510349 | 2025-10-01 10:52:31.510478 | TASK [Create ci-framework-data log directory for zuul] 2025-10-01 10:52:32.080956 | controller | changed 2025-10-01 10:52:32.093117 | 2025-10-01 10:52:32.093251 | TASK [Copy ci-framework interesting files] 2025-10-01 10:52:32.482907 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-10-01 10:52:32.642592 | controller | changed 2025-10-01 10:52:32.654276 | 2025-10-01 10:52:32.654442 | TASK [Get SELinux listing] 2025-10-01 10:52:33.206136 | controller | changed 2025-10-01 10:52:33.218592 | 2025-10-01 10:52:33.218801 | TASK [Generate log index] 2025-10-01 10:52:34.885515 | controller | changed 2025-10-01 10:52:34.897380 | 2025-10-01 10:52:34.897550 | TASK [Get some env related data] 2025-10-01 10:52:35.910880 | controller | /home/zuul/.local/bin/ansible 2025-10-01 10:52:36.951399 | controller | changed 2025-10-01 10:52:36.963322 | 2025-10-01 10:52:36.963591 | TASK [Generate list of logs to collect in home directory] 2025-10-01 10:52:37.562571 | controller | ok: All paths examined 2025-10-01 10:52:37.576578 | 2025-10-01 10:52:37.576713 | LOOP [Copy logs from home directory] 2025-10-01 10:52:38.673884 | controller | changed: 2025-10-01 10:52:38.674259 | controller | { 2025-10-01 10:52:38.674322 | controller | "atime": 1743544925.4788878, 2025-10-01 10:52:38.674368 | controller | "ctime": 1743545329.1409318, 2025-10-01 10:52:38.674410 | controller | "dev": 64513, 2025-10-01 10:52:38.674448 | controller | "gid": 1000, 2025-10-01 10:52:38.674486 | controller | "gr_name": "zuul", 2025-10-01 10:52:38.674526 | controller | "inode": 4518807, 2025-10-01 10:52:38.674566 | controller | "isblk": false, 2025-10-01 10:52:38.674605 | controller | "ischr": false, 2025-10-01 10:52:38.674644 | controller | "isdir": false, 2025-10-01 10:52:38.674682 | controller | "isfifo": false, 2025-10-01 10:52:38.674782 | controller | "isgid": false, 2025-10-01 10:52:38.674855 | controller | "islnk": false, 2025-10-01 10:52:38.674934 | controller | "isreg": true, 2025-10-01 10:52:38.674983 | controller | "issock": false, 2025-10-01 10:52:38.675022 | controller | "isuid": false, 2025-10-01 10:52:38.675059 | controller | "mode": "0644", 2025-10-01 10:52:38.675096 | controller | "mtime": 1743545329.1409318, 2025-10-01 10:52:38.675134 | controller | "nlink": 1, 2025-10-01 10:52:38.675172 | controller | "path": "/home/zuul/crc-setup.log", 2025-10-01 10:52:38.675229 | controller | "pw_name": "zuul", 2025-10-01 10:52:38.675271 | controller | "rgrp": true, 2025-10-01 10:52:38.675336 | controller | "roth": true, 2025-10-01 10:52:38.675383 | controller | "rusr": true, 2025-10-01 10:52:38.675423 | controller | "size": 4108, 2025-10-01 10:52:38.675461 | controller | "uid": 1000, 2025-10-01 10:52:38.675497 | controller | "wgrp": false, 2025-10-01 10:52:38.675534 | controller | "woth": false, 2025-10-01 10:52:38.675577 | controller | "wusr": true, 2025-10-01 10:52:38.675616 | controller | "xgrp": false, 2025-10-01 10:52:38.675654 | controller | "xoth": false, 2025-10-01 10:52:38.675690 | controller | "xusr": false 2025-10-01 10:52:38.675773 | controller | } 2025-10-01 10:52:39.512701 | controller | changed: 2025-10-01 10:52:39.513060 | controller | { 2025-10-01 10:52:39.513110 | controller | "atime": 1743545331.0429637, 2025-10-01 10:52:39.513137 | controller | "ctime": 1743545842.0111232, 2025-10-01 10:52:39.513160 | controller | "dev": 64513, 2025-10-01 10:52:39.513183 | controller | "gid": 1000, 2025-10-01 10:52:39.513206 | controller | "gr_name": "zuul", 2025-10-01 10:52:39.513228 | controller | "inode": 4194437, 2025-10-01 10:52:39.513251 | controller | "isblk": false, 2025-10-01 10:52:39.513273 | controller | "ischr": false, 2025-10-01 10:52:39.513295 | controller | "isdir": false, 2025-10-01 10:52:39.513317 | controller | "isfifo": false, 2025-10-01 10:52:39.513338 | controller | "isgid": false, 2025-10-01 10:52:39.513359 | controller | "islnk": false, 2025-10-01 10:52:39.513381 | controller | "isreg": true, 2025-10-01 10:52:39.513402 | controller | "issock": false, 2025-10-01 10:52:39.513424 | controller | "isuid": false, 2025-10-01 10:52:39.513445 | controller | "mode": "0644", 2025-10-01 10:52:39.513467 | controller | "mtime": 1743545842.0111232, 2025-10-01 10:52:39.513490 | controller | "nlink": 1, 2025-10-01 10:52:39.513512 | controller | "path": "/home/zuul/crc-start.log", 2025-10-01 10:52:39.513535 | controller | "pw_name": "zuul", 2025-10-01 10:52:39.513558 | controller | "rgrp": true, 2025-10-01 10:52:39.513587 | controller | "roth": true, 2025-10-01 10:52:39.513611 | controller | "rusr": true, 2025-10-01 10:52:39.513633 | controller | "size": 4023, 2025-10-01 10:52:39.513655 | controller | "uid": 1000, 2025-10-01 10:52:39.513677 | controller | "wgrp": false, 2025-10-01 10:52:39.513698 | controller | "woth": false, 2025-10-01 10:52:39.513719 | controller | "wusr": true, 2025-10-01 10:52:39.513772 | controller | "xgrp": false, 2025-10-01 10:52:39.513797 | controller | "xoth": false, 2025-10-01 10:52:39.513820 | controller | "xusr": false 2025-10-01 10:52:39.513867 | controller | } 2025-10-01 10:52:40.279510 | controller | changed: 2025-10-01 10:52:40.279810 | controller | { 2025-10-01 10:52:40.279873 | controller | "atime": 1759315537.8244042, 2025-10-01 10:52:40.279913 | controller | "ctime": 1759315554.751773, 2025-10-01 10:52:40.279949 | controller | "dev": 64513, 2025-10-01 10:52:40.279984 | controller | "gid": 1000, 2025-10-01 10:52:40.280018 | controller | "gr_name": "zuul", 2025-10-01 10:52:40.280051 | controller | "inode": 4233588, 2025-10-01 10:52:40.280085 | controller | "isblk": false, 2025-10-01 10:52:40.280119 | controller | "ischr": false, 2025-10-01 10:52:40.280152 | controller | "isdir": false, 2025-10-01 10:52:40.280184 | controller | "isfifo": false, 2025-10-01 10:52:40.280217 | controller | "isgid": false, 2025-10-01 10:52:40.280251 | controller | "islnk": false, 2025-10-01 10:52:40.280285 | controller | "isreg": true, 2025-10-01 10:52:40.280320 | controller | "issock": false, 2025-10-01 10:52:40.280354 | controller | "isuid": false, 2025-10-01 10:52:40.280389 | controller | "mode": "0644", 2025-10-01 10:52:40.280423 | controller | "mtime": 1759315554.751773, 2025-10-01 10:52:40.280457 | controller | "nlink": 1, 2025-10-01 10:52:40.280491 | controller | "path": "/home/zuul/ansible.log", 2025-10-01 10:52:40.280527 | controller | "pw_name": "zuul", 2025-10-01 10:52:40.280564 | controller | "rgrp": true, 2025-10-01 10:52:40.280600 | controller | "roth": true, 2025-10-01 10:52:40.280640 | controller | "rusr": true, 2025-10-01 10:52:40.280677 | controller | "size": 6701, 2025-10-01 10:52:40.280714 | controller | "uid": 1000, 2025-10-01 10:52:40.280802 | controller | "wgrp": false, 2025-10-01 10:52:40.280842 | controller | "woth": false, 2025-10-01 10:52:40.280878 | controller | "wusr": true, 2025-10-01 10:52:40.280913 | controller | "xgrp": false, 2025-10-01 10:52:40.280947 | controller | "xoth": false, 2025-10-01 10:52:40.280981 | controller | "xusr": false 2025-10-01 10:52:40.281016 | controller | } 2025-10-01 10:52:40.300053 | 2025-10-01 10:52:40.300238 | TASK [Copy crio stats log file] 2025-10-01 10:52:40.337931 | controller | skipping: Conditional result was False 2025-10-01 10:52:40.348388 | 2025-10-01 10:52:40.348488 | TASK [Get SELinux related data] 2025-10-01 10:52:40.767237 | controller | 2025-10-01 10:52:40.895798 | controller | ERROR 2025-10-01 10:52:40.896199 | controller | { 2025-10-01 10:52:40.896281 | controller | "delta": "0:00:00.021165", 2025-10-01 10:52:40.896594 | controller | "end": "2025-10-01 10:52:40.769494", 2025-10-01 10:52:40.896656 | controller | "msg": "non-zero return code", 2025-10-01 10:52:40.896694 | controller | "rc": 1, 2025-10-01 10:52:40.896782 | controller | "start": "2025-10-01 10:52:40.748329" 2025-10-01 10:52:40.896825 | controller | } 2025-10-01 10:52:40.896876 | controller | ERROR: Ignoring Errors 2025-10-01 10:52:40.912294 | 2025-10-01 10:52:40.912523 | TASK [Create system configuration directory] 2025-10-01 10:52:41.293673 | controller | changed 2025-10-01 10:52:41.306490 | 2025-10-01 10:52:41.306669 | TASK [Get some of the system configurations] 2025-10-01 10:52:41.856056 | controller | changed 2025-10-01 10:52:41.861474 | 2025-10-01 10:52:41.861534 | TASK [Copy generated documentation if available] 2025-10-01 10:52:41.885108 | controller | skipping: Conditional result was False 2025-10-01 10:52:41.891251 | 2025-10-01 10:52:41.891322 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-01 10:52:41.914654 | controller | skipping: Conditional result was False 2025-10-01 10:52:41.920918 | 2025-10-01 10:52:41.920990 | TASK [Compress logs bigger than 2MB] 2025-10-01 10:52:42.450330 | controller | changed 2025-10-01 10:52:42.463113 | 2025-10-01 10:52:42.463268 | TASK [Copy files from workspace on node] 2025-10-01 10:52:42.493201 | controller | ok 2025-10-01 10:52:42.536636 | 2025-10-01 10:52:42.536802 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-01 10:52:42.561885 | controller | skipping: Conditional result was False 2025-10-01 10:52:42.571473 | 2025-10-01 10:52:42.571606 | TASK [fetch-output : Set log path for single node] 2025-10-01 10:52:42.606214 | controller | ok 2025-10-01 10:52:42.613297 | 2025-10-01 10:52:42.613387 | LOOP [fetch-output : Ensure local output dirs] 2025-10-01 10:52:42.858213 | controller -> localhost | ok: "/var/lib/zuul/builds/65c5612102dd4a5b81cea8f4bb00ba5b/work/logs" 2025-10-01 10:52:42.858479 | controller -> localhost | changed: All items complete 2025-10-01 10:52:42.858517 | 2025-10-01 10:52:43.091351 | controller -> localhost | changed: "/var/lib/zuul/builds/65c5612102dd4a5b81cea8f4bb00ba5b/work/artifacts" 2025-10-01 10:52:43.354582 | controller -> localhost | changed: "/var/lib/zuul/builds/65c5612102dd4a5b81cea8f4bb00ba5b/work/docs" 2025-10-01 10:52:43.370256 | 2025-10-01 10:52:43.370361 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-01 10:52:44.466354 | controller | changed: 2025-10-01 10:52:44.479786 | controller | .d..t...... ./ 2025-10-01 10:52:44.479898 | controller | >f+++++++++ README.html 2025-10-01 10:52:44.479950 | controller | >f+++++++++ ansible-execution.log 2025-10-01 10:52:44.479994 | controller | >f+++++++++ ansible.log 2025-10-01 10:52:44.480034 | controller | >f+++++++++ crc-setup.log 2025-10-01 10:52:44.480072 | controller | >f+++++++++ crc-start.log 2025-10-01 10:52:44.480108 | controller | >f+++++++++ dmesg.log 2025-10-01 10:52:44.480153 | controller | >f+++++++++ installed-pkgs.log 2025-10-01 10:52:44.480191 | controller | >f+++++++++ python.log 2025-10-01 10:52:44.480228 | controller | >f+++++++++ registries.conf 2025-10-01 10:52:44.480266 | controller | >f+++++++++ report.html 2025-10-01 10:52:44.480302 | controller | >f+++++++++ selinux-denials.log 2025-10-01 10:52:44.480338 | controller | >f+++++++++ selinux-listing.log 2025-10-01 10:52:44.480374 | controller | cd+++++++++ ci-framework-data/ 2025-10-01 10:52:44.480410 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-01 10:52:44.480448 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_deploy_selected.sh 2025-10-01 10:52:44.480486 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_001_deploy_selected.sh 2025-10-01 10:52:44.480522 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-01 10:52:44.480558 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/ 2025-10-01 10:52:44.480593 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/ 2025-10-01 10:52:44.480629 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/ 2025-10-01 10:52:44.480665 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-01 10:52:44.480701 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-10-01 10:52:44.480776 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/ 2025-10-01 10:52:44.480817 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5 2025-10-01 10:52:44.480857 | controller | cd+++++++++ ci-framework-data/logs/ 2025-10-01 10:52:44.480895 | controller | >f+++++++++ ci-framework-data/logs/ci_script_000_deploy_selected.log 2025-10-01 10:52:44.480931 | controller | >f+++++++++ ci-framework-data/logs/ci_script_001_deploy_selected.log 2025-10-01 10:52:44.480968 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log 2025-10-01 10:52:44.481005 | controller | cd+++++++++ registries.conf.d/ 2025-10-01 10:52:44.481052 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-10-01 10:52:44.481089 | controller | cd+++++++++ system-config/ 2025-10-01 10:52:44.481125 | controller | cd+++++++++ system-config/libvirt/ 2025-10-01 10:52:44.481161 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-10-01 10:52:44.481197 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-10-01 10:52:44.481232 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf 2025-10-01 10:52:44.481268 | controller | >f+++++++++ system-config/libvirt/network.conf 2025-10-01 10:52:44.481303 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf 2025-10-01 10:52:44.481337 | controller | >f+++++++++ system-config/libvirt/qemu.conf 2025-10-01 10:52:44.481372 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf 2025-10-01 10:52:44.481407 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf 2025-10-01 10:52:44.481441 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf 2025-10-01 10:52:44.481476 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf 2025-10-01 10:52:44.481511 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf 2025-10-01 10:52:44.481545 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf 2025-10-01 10:52:44.481581 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf 2025-10-01 10:52:44.481617 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf 2025-10-01 10:52:44.481652 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf 2025-10-01 10:52:44.481688 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf 2025-10-01 10:52:45.129753 | controller | changed: .d..t...... ./ 2025-10-01 10:52:45.801583 | controller | changed: .d..t...... ./ 2025-10-01 10:52:45.827630 | 2025-10-01 10:52:45.827782 | TASK [Return artifact to Zuul] 2025-10-01 10:52:45.878694 | controller | ok 2025-10-01 10:52:45.897787 | 2025-10-01 10:52:45.897849 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-01 10:52:45.897944 | 2025-10-01 10:52:45.897970 | PLAY RECAP 2025-10-01 10:52:45.898010 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1 2025-10-01 10:52:45.898038 | 2025-10-01 10:52:46.012581 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-01 10:52:46.013563 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-01 10:52:46.586578 | 2025-10-01 10:52:46.586772 | PLAY [all] 2025-10-01 10:52:46.606299 | 2025-10-01 10:52:46.606379 | TASK [include_role : fetch-output] 2025-10-01 10:52:46.645392 | controller | ok 2025-10-01 10:52:46.666428 | 2025-10-01 10:52:46.666600 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-01 10:52:46.743200 | controller | skipping: Conditional result was False 2025-10-01 10:52:46.757686 | 2025-10-01 10:52:46.757905 | TASK [fetch-output : Set log path for single node] 2025-10-01 10:52:46.816837 | controller | ok 2025-10-01 10:52:46.828019 | 2025-10-01 10:52:46.828288 | LOOP [fetch-output : Ensure local output dirs] 2025-10-01 10:52:47.297659 | controller -> localhost | ok: "/var/lib/zuul/builds/65c5612102dd4a5b81cea8f4bb00ba5b/work/logs" 2025-10-01 10:52:47.583519 | controller -> localhost | ok: "/var/lib/zuul/builds/65c5612102dd4a5b81cea8f4bb00ba5b/work/artifacts" 2025-10-01 10:52:47.832019 | controller -> localhost | ok: "/var/lib/zuul/builds/65c5612102dd4a5b81cea8f4bb00ba5b/work/docs" 2025-10-01 10:52:47.841705 | 2025-10-01 10:52:47.841802 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-01 10:52:48.737656 | controller | ok 2025-10-01 10:52:48.738059 | controller | ok: All items complete 2025-10-01 10:52:48.738126 | 2025-10-01 10:52:49.461364 | controller | ok 2025-10-01 10:52:50.100478 | controller | ok 2025-10-01 10:52:50.126523 | 2025-10-01 10:52:50.126701 | TASK [include_role : fetch-output-openshift] 2025-10-01 10:52:50.143767 | controller | skipping: Conditional result was False 2025-10-01 10:52:50.152643 | 2025-10-01 10:52:50.152765 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-01 10:52:50.605978 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.012355 2025-10-01 10:52:50.889100 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009235 2025-10-01 10:52:50.930009 | 2025-10-01 10:52:50.930104 | PLAY [all] 2025-10-01 10:52:50.945341 | 2025-10-01 10:52:50.945430 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-01 10:52:51.979661 | controller | changed 2025-10-01 10:52:52.026984 | 2025-10-01 10:52:52.027061 | PLAY RECAP 2025-10-01 10:52:52.027117 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-01 10:52:52.027145 | 2025-10-01 10:52:52.134792 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-01 10:52:52.135585 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-01 10:52:52.784662 | 2025-10-01 10:52:52.784854 | PLAY [localhost] 2025-10-01 10:52:52.804364 | 2025-10-01 10:52:52.804487 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-01 10:52:53.249242 | localhost | changed 2025-10-01 10:52:53.263981 | 2025-10-01 10:52:53.264193 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-01 10:52:53.298905 | localhost | ok 2025-10-01 10:52:53.314458 | 2025-10-01 10:52:53.314602 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-01 10:52:53.668072 | localhost | changed 2025-10-01 10:52:53.679033 | 2025-10-01 10:52:53.679194 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-01 10:52:54.385471 | localhost | changed 2025-10-01 10:52:54.396415 | 2025-10-01 10:52:54.396555 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-01 10:52:54.884358 | localhost | Identity added: /var/lib/zuul/builds/65c5612102dd4a5b81cea8f4bb00ba5b/work/tmp/ansible.bjd0yo24 (/var/lib/zuul/builds/65c5612102dd4a5b81cea8f4bb00ba5b/work/tmp/ansible.bjd0yo24) 2025-10-01 10:52:54.884685 | localhost | ok: Runtime: 0:00:00.013602 2025-10-01 10:52:54.896176 | 2025-10-01 10:52:54.896323 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-01 10:52:55.241176 | localhost | ok: Runtime: 0:00:00.010327 2025-10-01 10:52:55.253656 | 2025-10-01 10:52:55.253847 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-01 10:52:55.343639 | localhost | changed 2025-10-01 10:52:55.353692 | 2025-10-01 10:52:55.353871 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-01 10:52:55.792089 | localhost | changed 2025-10-01 10:52:55.814127 | 2025-10-01 10:52:55.814193 | PLAY [localhost] 2025-10-01 10:52:55.827251 | 2025-10-01 10:52:55.827317 | TASK [Generate bulk log download script] 2025-10-01 10:52:55.845296 | localhost | ok 2025-10-01 10:52:55.857573 | 2025-10-01 10:52:55.857636 | TASK [local-log-download : Check API endpoint is defined] 2025-10-01 10:52:55.897898 | localhost | ok: All assertions passed 2025-10-01 10:52:55.905146 | 2025-10-01 10:52:55.905314 | TASK [local-log-download : Create download script] 2025-10-01 10:52:56.347335 | localhost -> localhost | changed 2025-10-01 10:52:56.356102 | 2025-10-01 10:52:56.356166 | TASK [Register quick-download link] 2025-10-01 10:52:56.385430 | localhost | ok 2025-10-01 10:52:56.423779 | 2025-10-01 10:52:56.423885 | PLAY [logserver.rdoproject.org] 2025-10-01 10:52:56.433100 | 2025-10-01 10:52:56.433160 | TASK [Set zuul-log-path fact] 2025-10-01 10:52:56.449637 | logserver.rdoproject.org | ok 2025-10-01 10:52:56.458951 | 2025-10-01 10:52:56.459015 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-01 10:52:56.495681 | logserver.rdoproject.org | ok 2025-10-01 10:52:56.503301 | 2025-10-01 10:52:56.503394 | TASK [upload-logs : Create log directories] 2025-10-01 10:52:57.160461 | logserver.rdoproject.org | changed 2025-10-01 10:52:57.168454 | 2025-10-01 10:52:57.168618 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-01 10:52:57.493850 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005198 2025-10-01 10:52:57.498769 | 2025-10-01 10:52:57.498839 | TASK [upload-logs : Upload logs to log server] 2025-10-01 10:52:58.172291 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-01 10:52:58.176977 | 2025-10-01 10:52:58.177065 | LOOP [upload-logs : Compress console log and json output] 2025-10-01 10:52:58.234243 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 10:52:58.247068 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 10:52:58.263090 | 2025-10-01 10:52:58.263263 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-01 10:52:58.309801 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 10:52:58.310085 | 2025-10-01 10:52:58.313658 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 10:52:58.330211 | 2025-10-01 10:52:58.330415 | LOOP [upload-logs : Upload console log and json output]