2025-10-08 15:03:12.257490 | Job console starting... 2025-10-08 15:03:12.271876 | Updating repositories 2025-10-08 15:03:12.327866 | Preparing job workspace 2025-10-08 15:03:17.686629 | Running Ansible setup... 2025-10-08 15:03:22.906028 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-08 15:03:23.468483 | 2025-10-08 15:03:23.468605 | PLAY [localhost] 2025-10-08 15:03:23.477611 | 2025-10-08 15:03:23.477727 | TASK [Gathering Facts] 2025-10-08 15:03:24.478020 | localhost | ok 2025-10-08 15:03:24.499190 | 2025-10-08 15:03:24.499338 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-08 15:03:24.950641 | localhost -> localhost | changed 2025-10-08 15:03:24.963476 | 2025-10-08 15:03:24.963633 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-08 15:03:25.889009 | localhost -> localhost | changed 2025-10-08 15:03:25.901838 | 2025-10-08 15:03:25.902002 | TASK [Setup log path fact] 2025-10-08 15:03:25.927473 | localhost | ok 2025-10-08 15:03:25.946051 | 2025-10-08 15:03:25.946156 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-08 15:03:25.976821 | localhost | ok 2025-10-08 15:03:25.987967 | 2025-10-08 15:03:25.988054 | TASK [emit-job-header : Print job information] 2025-10-08 15:03:26.029114 | # Job Information 2025-10-08 15:03:26.029357 | Ansible Version: 2.15.12 2025-10-08 15:03:26.029395 | Job: cifmw-molecule-operator_deploy 2025-10-08 15:03:26.029422 | Pipeline: github-check 2025-10-08 15:03:26.029446 | Executor: ze02.softwarefactory-project.io 2025-10-08 15:03:26.029469 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3368 2025-10-08 15:03:26.029493 | Log URL (when completed): https://logserver.rdoproject.org/349/rdoproject.org/34988eb0dcd340b2a05df673cc1c0588/ 2025-10-08 15:03:26.029518 | Event ID: dc080060-a456-11f0-897c-380a1fc1fefa 2025-10-08 15:03:26.036556 | 2025-10-08 15:03:26.036642 | LOOP [emit-job-header : Print node information] 2025-10-08 15:03:26.143304 | localhost | ok: 2025-10-08 15:03:26.143656 | localhost | # Node Information 2025-10-08 15:03:26.143779 | localhost | Inventory Hostname: controller 2025-10-08 15:03:26.143834 | localhost | Hostname: np0005476975 2025-10-08 15:03:26.143879 | localhost | Username: zuul 2025-10-08 15:03:26.143927 | localhost | Distro: CentOS 9 2025-10-08 15:03:26.143968 | localhost | Provider: vexxhost-nodepool-tripleo 2025-10-08 15:03:26.144008 | localhost | Region: RegionOne 2025-10-08 15:03:26.144047 | localhost | Label: centos-9-stream-crc-2-48-0-xl 2025-10-08 15:03:26.144086 | localhost | Product Name: OpenStack Nova 2025-10-08 15:03:26.144124 | localhost | Interface IP: 38.129.56.66 2025-10-08 15:03:26.179693 | 2025-10-08 15:03:26.179771 | PLAY [all] 2025-10-08 15:03:26.189119 | 2025-10-08 15:03:26.189200 | TASK [Gather network facts] 2025-10-08 15:03:26.808253 | controller | ok 2025-10-08 15:03:26.843836 | 2025-10-08 15:03:26.843918 | TASK [include_role : start-zuul-console] 2025-10-08 15:03:26.867156 | controller | ok 2025-10-08 15:03:26.884895 | 2025-10-08 15:03:26.884984 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-08 15:03:27.455053 | controller | ok 2025-10-08 15:03:27.467405 | 2025-10-08 15:03:27.467487 | TASK [include_role : add-build-sshkey] 2025-10-08 15:03:27.499653 | controller | ok 2025-10-08 15:03:27.520209 | 2025-10-08 15:03:27.520293 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-08 15:03:27.820225 | controller -> localhost | ok 2025-10-08 15:03:27.829285 | 2025-10-08 15:03:27.829374 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-08 15:03:27.853008 | controller | ok 2025-10-08 15:03:27.873259 | controller | included: /var/lib/zuul/builds/34988eb0dcd340b2a05df673cc1c0588/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-08 15:03:27.881998 | 2025-10-08 15:03:27.882080 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-08 15:03:28.395643 | controller -> localhost | Generating public/private rsa key pair. 2025-10-08 15:03:28.395875 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/34988eb0dcd340b2a05df673cc1c0588/work/34988eb0dcd340b2a05df673cc1c0588_id_rsa. 2025-10-08 15:03:28.395907 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/34988eb0dcd340b2a05df673cc1c0588/work/34988eb0dcd340b2a05df673cc1c0588_id_rsa.pub. 2025-10-08 15:03:28.395930 | controller -> localhost | The key fingerprint is: 2025-10-08 15:03:28.395950 | controller -> localhost | SHA256:EbjUjF/kIjhFRHKyoG/Mwo+aM8Vei0UzNtBCIVyFCCE zuul-build-sshkey 2025-10-08 15:03:28.395971 | controller -> localhost | The key's randomart image is: 2025-10-08 15:03:28.395990 | controller -> localhost | +---[RSA 3072]----+ 2025-10-08 15:03:28.396008 | controller -> localhost | |Eo*+==*=... | 2025-10-08 15:03:28.396027 | controller -> localhost | |.=oo.B+ oo. | 2025-10-08 15:03:28.396045 | controller -> localhost | |. o+..oo.. | 2025-10-08 15:03:28.396064 | controller -> localhost | |.+ *....o | 2025-10-08 15:03:28.396083 | controller -> localhost | |.o=o + S | 2025-10-08 15:03:28.396100 | controller -> localhost | | o= o | 2025-10-08 15:03:28.396118 | controller -> localhost | | + = . | 2025-10-08 15:03:28.396136 | controller -> localhost | |+.o . | 2025-10-08 15:03:28.396154 | controller -> localhost | |oo | 2025-10-08 15:03:28.396189 | controller -> localhost | +----[SHA256]-----+ 2025-10-08 15:03:28.396232 | controller -> localhost | ok: Runtime: 0:00:00.128931 2025-10-08 15:03:28.401755 | 2025-10-08 15:03:28.401833 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-08 15:03:28.419513 | controller | ok 2025-10-08 15:03:28.430166 | controller | included: /var/lib/zuul/builds/34988eb0dcd340b2a05df673cc1c0588/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-08 15:03:28.438145 | 2025-10-08 15:03:28.438212 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-08 15:03:28.461703 | controller | skipping: Conditional result was False 2025-10-08 15:03:28.467237 | 2025-10-08 15:03:28.467301 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-08 15:03:29.133159 | controller | changed 2025-10-08 15:03:29.138176 | 2025-10-08 15:03:29.138244 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-08 15:03:29.536177 | controller | ok 2025-10-08 15:03:29.544418 | 2025-10-08 15:03:29.544545 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-08 15:03:31.422159 | controller | changed 2025-10-08 15:03:31.438644 | 2025-10-08 15:03:31.438877 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-08 15:03:33.280071 | controller | changed 2025-10-08 15:03:33.285131 | 2025-10-08 15:03:33.285189 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-08 15:03:33.300599 | controller | skipping: Conditional result was False 2025-10-08 15:03:33.306414 | 2025-10-08 15:03:33.306496 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-08 15:03:33.687114 | controller -> localhost | changed 2025-10-08 15:03:33.708870 | 2025-10-08 15:03:33.709003 | TASK [add-build-sshkey : Add back temp key] 2025-10-08 15:03:34.029333 | controller -> localhost | Identity added: /var/lib/zuul/builds/34988eb0dcd340b2a05df673cc1c0588/work/34988eb0dcd340b2a05df673cc1c0588_id_rsa (zuul-build-sshkey) 2025-10-08 15:03:34.029708 | controller -> localhost | ok: Runtime: 0:00:00.019148 2025-10-08 15:03:34.044509 | 2025-10-08 15:03:34.044645 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-08 15:03:34.536453 | controller | ok 2025-10-08 15:03:34.550250 | 2025-10-08 15:03:34.551169 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-08 15:03:34.591606 | controller | skipping: Conditional result was False 2025-10-08 15:03:34.651489 | 2025-10-08 15:03:34.651610 | TASK [include_role : validate-host] 2025-10-08 15:03:34.683040 | controller | ok 2025-10-08 15:03:34.707717 | 2025-10-08 15:03:34.707844 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-08 15:03:34.748848 | controller | ok 2025-10-08 15:03:34.754100 | 2025-10-08 15:03:34.754185 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-08 15:03:35.042625 | controller -> localhost | ok 2025-10-08 15:03:35.048658 | 2025-10-08 15:03:35.048739 | TASK [validate-host : Collect information about the host] 2025-10-08 15:03:35.995901 | controller | ok 2025-10-08 15:03:36.004001 | 2025-10-08 15:03:36.004059 | TASK [validate-host : Sanitize hostname] 2025-10-08 15:03:36.089801 | controller | ok 2025-10-08 15:03:36.100846 | 2025-10-08 15:03:36.100986 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-08 15:03:36.617587 | controller -> localhost | changed 2025-10-08 15:03:36.623402 | 2025-10-08 15:03:36.623463 | TASK [validate-host : Collect information about zuul worker] 2025-10-08 15:03:37.188508 | controller | ok 2025-10-08 15:03:37.200008 | 2025-10-08 15:03:37.200176 | TASK [validate-host : Write out all zuul information for each host] 2025-10-08 15:03:37.798846 | controller -> localhost | changed 2025-10-08 15:03:37.809717 | 2025-10-08 15:03:37.809793 | TASK [include_role : prepare-workspace-openshift] 2025-10-08 15:03:37.835293 | controller | skipping: Conditional result was False 2025-10-08 15:03:37.841225 | 2025-10-08 15:03:37.841287 | TASK [include_role : remove-zuul-sshkey] 2025-10-08 15:03:37.876412 | controller | skipping: Conditional result was False 2025-10-08 15:03:37.882319 | 2025-10-08 15:03:37.882383 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-08 15:03:38.232623 | controller | ok: "logs" 2025-10-08 15:03:38.232968 | controller | ok: All items complete 2025-10-08 15:03:38.233001 | 2025-10-08 15:03:38.599488 | controller | ok: "artifacts" 2025-10-08 15:03:38.973785 | controller | ok: "docs" 2025-10-08 15:03:38.989090 | 2025-10-08 15:03:38.989453 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-08 15:03:39.374622 | controller | changed: "logs" 2025-10-08 15:03:39.740458 | controller | changed: "artifacts" 2025-10-08 15:03:40.122557 | controller | changed: "docs" 2025-10-08 15:03:40.165873 | 2025-10-08 15:03:40.165946 | PLAY RECAP 2025-10-08 15:03:40.166007 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-08 15:03:40.166042 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-08 15:03:40.166066 | 2025-10-08 15:03:40.278621 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-08 15:03:40.281653 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-08 15:03:40.913801 | 2025-10-08 15:03:40.913918 | PLAY [all] 2025-10-08 15:03:40.933724 | 2025-10-08 15:03:40.933807 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-08 15:03:41.013388 | controller | ok 2025-10-08 15:03:41.018410 | 2025-10-08 15:03:41.018484 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-08 15:03:41.581497 | controller | changed 2025-10-08 15:03:41.589506 | 2025-10-08 15:03:41.589601 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-08 15:03:43.614437 | controller | changed 2025-10-08 15:03:43.629087 | 2025-10-08 15:03:43.629176 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-08 15:03:44.303377 | controller | changed: 2025-10-08 15:03:44.303687 | controller | { 2025-10-08 15:03:44.303754 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-08 15:03:44.303808 | controller | } 2025-10-08 15:03:44.720585 | controller | changed: 2025-10-08 15:03:44.720769 | controller | { 2025-10-08 15:03:44.720826 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-08 15:03:44.720869 | controller | } 2025-10-08 15:03:45.136817 | controller | changed: 2025-10-08 15:03:45.136966 | controller | { 2025-10-08 15:03:45.137017 | 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-08 15:03:45.137057 | controller | } 2025-10-08 15:03:45.551422 | controller | changed: 2025-10-08 15:03:45.551613 | controller | { 2025-10-08 15:03:45.551656 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-08 15:03:45.551713 | controller | } 2025-10-08 15:03:45.927096 | controller | changed: 2025-10-08 15:03:45.927192 | controller | { 2025-10-08 15:03:45.927214 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-08 15:03:45.927232 | controller | } 2025-10-08 15:03:46.372171 | controller | changed: 2025-10-08 15:03:46.373079 | controller | { 2025-10-08 15:03:46.373115 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-08 15:03:46.373136 | controller | } 2025-10-08 15:03:46.821705 | controller | changed: 2025-10-08 15:03:46.821818 | controller | { 2025-10-08 15:03:46.821851 | 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-08 15:03:46.821878 | controller | } 2025-10-08 15:03:47.272517 | controller | changed: 2025-10-08 15:03:47.276849 | controller | { 2025-10-08 15:03:47.276964 | 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-08 15:03:47.277017 | controller | } 2025-10-08 15:03:47.745156 | controller | changed: 2025-10-08 15:03:47.747378 | controller | { 2025-10-08 15:03:47.747425 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-08 15:03:47.747452 | controller | } 2025-10-08 15:03:48.160095 | controller | changed: 2025-10-08 15:03:48.160242 | controller | { 2025-10-08 15:03:48.160291 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-08 15:03:48.160331 | controller | } 2025-10-08 15:03:48.573575 | controller | changed: 2025-10-08 15:03:48.573746 | controller | { 2025-10-08 15:03:48.573794 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-08 15:03:48.573830 | controller | } 2025-10-08 15:03:49.048748 | controller | changed: 2025-10-08 15:03:49.048943 | controller | { 2025-10-08 15:03:49.049011 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-08 15:03:49.049064 | controller | } 2025-10-08 15:03:49.516318 | controller | changed: 2025-10-08 15:03:49.516491 | controller | { 2025-10-08 15:03:49.516540 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-08 15:03:49.516580 | controller | } 2025-10-08 15:03:49.956994 | controller | changed: 2025-10-08 15:03:49.957146 | controller | { 2025-10-08 15:03:49.957197 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-08 15:03:49.957237 | controller | } 2025-10-08 15:03:50.441595 | controller | changed: 2025-10-08 15:03:50.441807 | controller | { 2025-10-08 15:03:50.441860 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-08 15:03:50.441901 | controller | } 2025-10-08 15:03:50.905396 | controller | changed: 2025-10-08 15:03:50.905508 | controller | { 2025-10-08 15:03:50.905531 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-08 15:03:50.905551 | controller | } 2025-10-08 15:03:51.349448 | controller | changed: 2025-10-08 15:03:51.349787 | controller | { 2025-10-08 15:03:51.349863 | 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-08 15:03:51.349907 | controller | } 2025-10-08 15:03:51.754578 | controller | changed: 2025-10-08 15:03:51.754690 | controller | { 2025-10-08 15:03:51.754719 | 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-08 15:03:51.754747 | controller | } 2025-10-08 15:03:52.161874 | controller | changed: 2025-10-08 15:03:52.162162 | controller | { 2025-10-08 15:03:52.162377 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-08 15:03:52.162431 | controller | } 2025-10-08 15:03:52.590252 | controller | changed: 2025-10-08 15:03:52.590351 | controller | { 2025-10-08 15:03:52.590375 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-08 15:03:52.590394 | controller | } 2025-10-08 15:03:53.040076 | controller | changed: 2025-10-08 15:03:53.040227 | controller | { 2025-10-08 15:03:53.040265 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-08 15:03:53.040294 | controller | } 2025-10-08 15:03:53.446151 | controller | changed: 2025-10-08 15:03:53.446243 | controller | { 2025-10-08 15:03:53.446267 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-08 15:03:53.446287 | controller | } 2025-10-08 15:03:53.883581 | controller | changed: 2025-10-08 15:03:53.884307 | controller | { 2025-10-08 15:03:53.884363 | 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-08 15:03:53.884395 | controller | } 2025-10-08 15:03:54.301460 | controller | changed: 2025-10-08 15:03:54.301557 | controller | { 2025-10-08 15:03:54.301580 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-08 15:03:54.301598 | controller | } 2025-10-08 15:03:54.746543 | controller | changed: 2025-10-08 15:03:54.747595 | controller | { 2025-10-08 15:03:54.747637 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-08 15:03:54.747660 | controller | } 2025-10-08 15:03:55.198432 | controller | changed: 2025-10-08 15:03:55.202710 | controller | { 2025-10-08 15:03:55.202765 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-08 15:03:55.202787 | controller | } 2025-10-08 15:03:55.246809 | 2025-10-08 15:03:55.247030 | TASK [Set timezone to UTC] 2025-10-08 15:03:56.007433 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-08 15:03:56.012249 | 2025-10-08 15:03:56.012310 | TASK [Create nodepool directory] 2025-10-08 15:03:56.458399 | controller | changed 2025-10-08 15:03:56.463917 | 2025-10-08 15:03:56.463977 | TASK [Create nodepool sub_nodes file] 2025-10-08 15:03:58.085874 | controller | changed 2025-10-08 15:03:58.091205 | 2025-10-08 15:03:58.091266 | TASK [Create nodepool sub_nodes_private file] 2025-10-08 15:03:59.753914 | controller | changed 2025-10-08 15:03:59.765043 | 2025-10-08 15:03:59.765139 | LOOP [Populate nodepool sub_nodes file] 2025-10-08 15:03:59.801844 | 2025-10-08 15:03:59.802022 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-08 15:03:59.836168 | 2025-10-08 15:03:59.836328 | TASK [Create nodepool primary file] 2025-10-08 15:03:59.881344 | controller | skipping: Conditional result was False 2025-10-08 15:03:59.889240 | 2025-10-08 15:03:59.889336 | TASK [Create nodepool node_private for this node] 2025-10-08 15:04:01.708286 | controller | changed 2025-10-08 15:04:01.721024 | 2025-10-08 15:04:01.721173 | LOOP [Copy ssh keys to nodepool directory] 2025-10-08 15:04:02.321979 | controller | ok: Item: id_rsa Runtime: 0:00:00.010309 2025-10-08 15:04:02.322394 | 2025-10-08 15:04:02.673645 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.006412 2025-10-08 15:04:02.695205 | 2025-10-08 15:04:02.695389 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-08 15:04:04.281366 | controller | changed 2025-10-08 15:04:04.286753 | 2025-10-08 15:04:04.286816 | TASK [Validate sudoers config after edits] 2025-10-08 15:04:04.752648 | controller | /etc/sudoers: parsed OK 2025-10-08 15:04:04.752952 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-08 15:04:04.752972 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-08 15:04:04.752986 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-08 15:04:04.818249 | controller | ok: Runtime: 0:00:00.010831 2025-10-08 15:04:04.826478 | 2025-10-08 15:04:04.826557 | TASK [Show the environment passed in to job shell scripts] 2025-10-08 15:04:05.228230 | controller | SHELL=/bin/bash 2025-10-08 15:04:05.228306 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-08 15:04:05.228325 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-08 15:04:05.228339 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/68/3368/aa3d6015903436a1e30822664d9bf9e0f671d4a4 2025-10-08 15:04:05.228353 | controller | PWD=/home/zuul 2025-10-08 15:04:05.228366 | controller | ZUUL_PIPELINE=github-check 2025-10-08 15:04:05.228379 | controller | LOGNAME=zuul 2025-10-08 15:04:05.228392 | controller | XDG_SESSION_TYPE=tty 2025-10-08 15:04:05.228405 | controller | _=/usr/bin/env 2025-10-08 15:04:05.228418 | controller | MOTD_SHOWN=pam 2025-10-08 15:04:05.228431 | controller | HOME=/home/zuul 2025-10-08 15:04:05.228444 | controller | LANG=en_US.UTF-8 2025-10-08 15:04:05.228457 | controller | SSH_CONNECTION=38.102.83.114 45258 38.129.56.66 22 2025-10-08 15:04:05.228470 | 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-08 15:04:05.228488 | controller | ZUUL_CHANGE_IDS=3368,aa3d6015903436a1e30822664d9bf9e0f671d4a4 2025-10-08 15:04:05.228541 | controller | WORKSPACE=/home/zuul/workspace 2025-10-08 15:04:05.228555 | controller | XDG_SESSION_CLASS=user 2025-10-08 15:04:05.228569 | controller | SELINUX_ROLE_REQUESTED= 2025-10-08 15:04:05.228581 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-08 15:04:05.228594 | controller | USER=zuul 2025-10-08 15:04:05.228607 | controller | ZUUL_VOTING=True 2025-10-08 15:04:05.228620 | controller | BUILD_TIMEOUT=1800000 2025-10-08 15:04:05.228633 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-08 15:04:05.228646 | controller | SHLVL=1 2025-10-08 15:04:05.228659 | controller | ZUUL_PATCHSET=aa3d6015903436a1e30822664d9bf9e0f671d4a4 2025-10-08 15:04:05.228672 | controller | XDG_SESSION_ID=1 2025-10-08 15:04:05.228685 | controller | ZUUL_BRANCH=main 2025-10-08 15:04:05.228698 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-08 15:04:05.228711 | controller | SSH_CLIENT=38.102.83.114 45258 22 2025-10-08 15:04:05.228724 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-08 15:04:05.228737 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-08 15:04:05.228750 | controller | which_declare=declare -f 2025-10-08 15:04:05.228763 | 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-08 15:04:05.228776 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-08 15:04:05.228789 | controller | ZUUL_CHANGE=3368 2025-10-08 15:04:05.228802 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-08 15:04:05.228815 | controller | ZUUL_UUID=34988eb0dcd340b2a05df673cc1c0588 2025-10-08 15:04:05.228832 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-08 15:04:05.228845 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-08 15:04:05.228858 | controller | } 2025-10-08 15:04:05.371536 | controller | ok: Runtime: 0:00:00.010703 2025-10-08 15:04:05.385861 | 2025-10-08 15:04:05.385995 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-08 15:04:05.415549 | controller | skipping: Conditional result was False 2025-10-08 15:04:05.427449 | 2025-10-08 15:04:05.427541 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-08 15:04:06.015482 | controller | skipping: Conditional result was False 2025-10-08 15:04:06.021973 | 2025-10-08 15:04:06.022039 | TASK [Ensure legacy workspace directory] 2025-10-08 15:04:06.434776 | controller | changed 2025-10-08 15:04:06.454879 | 2025-10-08 15:04:06.454935 | PLAY RECAP 2025-10-08 15:04:06.454975 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-08 15:04:06.454995 | 2025-10-08 15:04:06.558798 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-08 15:04:06.559575 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-08 15:04:07.116162 | 2025-10-08 15:04:07.116267 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-08 15:04:07.148458 | 2025-10-08 15:04:07.148561 | TASK [Create zuul-output directory] 2025-10-08 15:04:07.768868 | controller | changed 2025-10-08 15:04:07.781800 | 2025-10-08 15:04:07.781968 | TASK [Slurp Zuul inventory test] 2025-10-08 15:04:08.145925 | controller -> localhost | ok 2025-10-08 15:04:08.155071 | 2025-10-08 15:04:08.155199 | TASK [Save zuul inventory] 2025-10-08 15:04:09.951226 | controller | changed 2025-10-08 15:04:09.956720 | 2025-10-08 15:04:09.956788 | TASK [Save zuul vars without the change_message] 2025-10-08 15:04:11.703061 | controller | changed 2025-10-08 15:04:11.746547 | 2025-10-08 15:04:11.746638 | PLAY RECAP 2025-10-08 15:04:11.746723 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-08 15:04:11.746753 | 2025-10-08 15:04:11.870514 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-08 15:04:11.871295 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-08 15:04:12.445629 | 2025-10-08 15:04:12.445750 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-10-08 15:04:12.466559 | 2025-10-08 15:04:12.466640 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-10-08 15:04:12.495761 | controller | ok 2025-10-08 15:04:12.513074 | 2025-10-08 15:04:12.513171 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-08 15:04:12.560530 | controller | skipping: Conditional result was False 2025-10-08 15:04:12.573205 | 2025-10-08 15:04:12.573409 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-08 15:04:13.133209 | controller | ok 2025-10-08 15:04:13.143161 | 2025-10-08 15:04:13.143284 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-08 15:04:14.734115 | controller | ok 2025-10-08 15:04:14.748725 | 2025-10-08 15:04:14.748815 | TASK [Prepare workspace] 2025-10-08 15:04:14.770465 | controller | ok 2025-10-08 15:04:14.795626 | 2025-10-08 15:04:14.795740 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-08 15:04:15.342708 | controller | ok 2025-10-08 15:04:15.348909 | 2025-10-08 15:04:15.348972 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-08 15:04:18.835490 | controller | Output suppressed because no_log was given 2025-10-08 15:04:18.848871 | 2025-10-08 15:04:18.849037 | LOOP [Create zuul-output directory] 2025-10-08 15:04:19.230386 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-10-08 15:04:19.624882 | controller | ok: "/home/zuul/zuul-output/logs" 2025-10-08 15:04:19.641358 | 2025-10-08 15:04:19.641468 | TASK [Install required packages] 2025-10-08 15:04:34.606785 | controller | ok: Nothing to do 2025-10-08 15:04:34.612910 | 2025-10-08 15:04:34.612982 | TASK [Install venv] 2025-10-08 15:05:38.936340 | controller | changed 2025-10-08 15:05:38.973527 | 2025-10-08 15:05:38.973643 | PLAY RECAP 2025-10-08 15:05:38.973742 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-08 15:05:38.973772 | 2025-10-08 15:05:39.103728 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-08 15:05:39.106619 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-08 15:05:39.638392 | 2025-10-08 15:05:39.638495 | PLAY [Run ci/playbooks/molecule-test.yml] 2025-10-08 15:05:39.659259 | 2025-10-08 15:05:39.659338 | TASK [Gather required facts] 2025-10-08 15:05:40.373744 | controller | ok 2025-10-08 15:05:40.380741 | 2025-10-08 15:05:40.380847 | TASK [Load environment var if instructed to] 2025-10-08 15:05:40.415631 | controller | skipping: Conditional result was False 2025-10-08 15:05:40.421939 | 2025-10-08 15:05:40.422018 | TASK [Run molecule] 2025-10-08 15:05:41.709925 | controller | INFO Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml 2025-10-08 15:05:41.785697 | controller | INFO Performing prerun with role_name_check=0... 2025-10-08 15:06:04.543346 | 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-08 15:06:04.544450 | 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-08 15:06:04.545579 | 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-08 15:06:04.546644 | 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-08 15:06:04.547774 | controller | WARNING Another version of 'cifmw.general' 1.0.0+9ece53e1 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 1.0.0+9ece53e1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 15:06:04.548940 | 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-08 15:06:04.550022 | 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-08 15:06:04.551073 | 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-08 15:06:04.552137 | 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-08 15:06:04.553224 | 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-08 15:06:04.554307 | 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-08 15:06:04.555384 | 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-08 15:06:04.556419 | 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-08 15:06:04.557503 | 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-08 15:06:04.558545 | 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-08 15:06:04.581936 | controller | INFO Running default > prepare 2025-10-08 15:06:05.424850 | controller | 2025-10-08 15:06:05.425097 | controller | PLAY [Prepare] ***************************************************************** 2025-10-08 15:06:05.425346 | controller | 2025-10-08 15:06:05.425615 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-08 15:06:05.425852 | controller | Wednesday 08 October 2025 15:06:05 +0000 (0:00:00.021) 0:00:00.021 ***** 2025-10-08 15:06:07.570007 | controller | ok: [instance] 2025-10-08 15:06:07.570215 | controller | 2025-10-08 15:06:07.570504 | controller | TASK [test_deps : Gather facts if they don't exist] **************************** 2025-10-08 15:06:07.570780 | controller | Wednesday 08 October 2025 15:06:07 +0000 (0:00:02.145) 0:00:02.167 ***** 2025-10-08 15:06:07.598397 | controller | skipping: [instance] 2025-10-08 15:06:07.598443 | controller | 2025-10-08 15:06:07.598598 | controller | TASK [test_deps : Gather variables for each operating system] ****************** 2025-10-08 15:06:07.598738 | controller | Wednesday 08 October 2025 15:06:07 +0000 (0:00:00.028) 0:00:02.196 ***** 2025-10-08 15:06:07.666180 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml) 2025-10-08 15:06:07.666285 | controller | 2025-10-08 15:06:07.666483 | controller | TASK [test_deps : Detect mirrors file] ***************************************** 2025-10-08 15:06:07.666683 | controller | Wednesday 08 October 2025 15:06:07 +0000 (0:00:00.067) 0:00:02.264 ***** 2025-10-08 15:06:08.088351 | controller | ok: [instance] 2025-10-08 15:06:08.088407 | controller | 2025-10-08 15:06:08.088474 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] *********** 2025-10-08 15:06:08.088587 | controller | Wednesday 08 October 2025 15:06:08 +0000 (0:00:00.422) 0:00:02.686 ***** 2025-10-08 15:06:08.530030 | controller | ok: [instance] 2025-10-08 15:06:08.530239 | controller | 2025-10-08 15:06:08.530487 | controller | TASK [test_deps : Disable ubi 9 repos] ***************************************** 2025-10-08 15:06:08.530747 | controller | Wednesday 08 October 2025 15:06:08 +0000 (0:00:00.441) 0:00:03.127 ***** 2025-10-08 15:06:09.551916 | controller | ok: [instance] => (item=ubi-9-appstream) 2025-10-08 15:06:09.552246 | controller | ok: [instance] => (item=ubi-9-baseos) 2025-10-08 15:06:09.553279 | controller | ok: [instance] => (item=ubi-9-codeready-builder) 2025-10-08 15:06:09.650416 | controller | 2025-10-08 15:06:09.650455 | controller | TASK [Deploy repo-setup] ******************************************************* 2025-10-08 15:06:09.650465 | controller | Wednesday 08 October 2025 15:06:09 +0000 (0:00:01.021) 0:00:04.149 ***** 2025-10-08 15:06:09.650477 | controller | 2025-10-08 15:06:10.442174 | controller | TASK [repo_setup : Ensure directories are present] ***************************** 2025-10-08 15:06:10.442216 | controller | Wednesday 08 October 2025 15:06:09 +0000 (0:00:00.099) 0:00:04.248 ***** 2025-10-08 15:06:10.442230 | controller | changed: [instance] => (item=tmp) 2025-10-08 15:06:10.443386 | controller | changed: [instance] => (item=artifacts/repositories) 2025-10-08 15:06:11.501987 | controller | changed: [instance] => (item=venv/repo_setup) 2025-10-08 15:06:11.502026 | controller | 2025-10-08 15:06:11.502037 | controller | TASK [repo_setup : Make sure git-core package is installed] ******************** 2025-10-08 15:06:11.502085 | controller | Wednesday 08 October 2025 15:06:10 +0000 (0:00:00.791) 0:00:05.039 ***** 2025-10-08 15:06:11.502115 | controller | ok: [instance] 2025-10-08 15:06:12.728008 | controller | 2025-10-08 15:06:12.728097 | controller | TASK [repo_setup : Get repo-setup repository] ********************************** 2025-10-08 15:06:12.728131 | controller | Wednesday 08 October 2025 15:06:11 +0000 (0:00:01.059) 0:00:06.099 ***** 2025-10-08 15:06:12.728154 | controller | changed: [instance] 2025-10-08 15:06:20.904212 | controller | 2025-10-08 15:06:20.904253 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************ 2025-10-08 15:06:20.904262 | controller | Wednesday 08 October 2025 15:06:12 +0000 (0:00:01.225) 0:00:07.324 ***** 2025-10-08 15:06:20.904272 | controller | changed: [instance] 2025-10-08 15:06:20.904821 | controller | 2025-10-08 15:06:21.823961 | controller | TASK [repo_setup : Install repo-setup package] ********************************* 2025-10-08 15:06:21.824016 | controller | Wednesday 08 October 2025 15:06:20 +0000 (0:00:08.177) 0:00:15.501 ***** 2025-10-08 15:06:21.824096 | controller | changed: [instance] 2025-10-08 15:06:21.863305 | controller | 2025-10-08 15:06:21.863368 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] *** 2025-10-08 15:06:21.863384 | controller | Wednesday 08 October 2025 15:06:21 +0000 (0:00:00.919) 0:00:16.421 ***** 2025-10-08 15:06:21.863407 | controller | skipping: [instance] 2025-10-08 15:06:22.728193 | controller | 2025-10-08 15:06:22.728253 | controller | TASK [repo_setup : Run repo-setup] ********************************************* 2025-10-08 15:06:22.728270 | controller | Wednesday 08 October 2025 15:06:21 +0000 (0:00:00.040) 0:00:16.461 ***** 2025-10-08 15:06:22.728293 | controller | changed: [instance] 2025-10-08 15:06:22.728350 | controller | 2025-10-08 15:06:22.728745 | controller | TASK [repo_setup : Get component repo] ***************************************** 2025-10-08 15:06:22.788229 | controller | Wednesday 08 October 2025 15:06:22 +0000 (0:00:00.864) 0:00:17.326 ***** 2025-10-08 15:06:22.788278 | controller | skipping: [instance] 2025-10-08 15:06:22.788635 | controller | 2025-10-08 15:06:22.788676 | controller | TASK [repo_setup : Rename component repo] ************************************** 2025-10-08 15:06:22.847225 | controller | Wednesday 08 October 2025 15:06:22 +0000 (0:00:00.059) 0:00:17.385 ***** 2025-10-08 15:06:22.847293 | controller | skipping: [instance] 2025-10-08 15:06:22.847355 | controller | 2025-10-08 15:06:22.848180 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] ******* 2025-10-08 15:06:22.848490 | controller | Wednesday 08 October 2025 15:06:22 +0000 (0:00:00.058) 0:00:17.444 ***** 2025-10-08 15:06:22.905547 | controller | skipping: [instance] 2025-10-08 15:06:22.905870 | controller | 2025-10-08 15:06:22.906229 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************ 2025-10-08 15:06:22.906582 | controller | Wednesday 08 October 2025 15:06:22 +0000 (0:00:00.058) 0:00:17.503 ***** 2025-10-08 15:06:23.510303 | controller | changed: [instance] 2025-10-08 15:06:23.510358 | controller | 2025-10-08 15:06:23.510374 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] ******************* 2025-10-08 15:06:23.510392 | controller | Wednesday 08 October 2025 15:06:23 +0000 (0:00:00.604) 0:00:18.107 ***** 2025-10-08 15:06:24.188184 | controller | changed: [instance] 2025-10-08 15:06:24.272686 | controller | 2025-10-08 15:06:24.272721 | controller | TASK [repo_setup : Dump current-podified hash] ********************************* 2025-10-08 15:06:24.272729 | controller | Wednesday 08 October 2025 15:06:24 +0000 (0:00:00.677) 0:00:18.784 ***** 2025-10-08 15:06:24.272741 | controller | skipping: [instance] 2025-10-08 15:06:24.318398 | controller | 2025-10-08 15:06:24.318498 | controller | TASK [repo_setup : Slurp current podified hash] ******************************** 2025-10-08 15:06:24.318516 | controller | Wednesday 08 October 2025 15:06:24 +0000 (0:00:00.085) 0:00:18.870 ***** 2025-10-08 15:06:24.318559 | controller | skipping: [instance] 2025-10-08 15:06:24.318619 | controller | 2025-10-08 15:06:24.318641 | controller | TASK [repo_setup : Update the value of full_hash] ****************************** 2025-10-08 15:06:24.318905 | controller | Wednesday 08 October 2025 15:06:24 +0000 (0:00:00.045) 0:00:18.916 ***** 2025-10-08 15:06:24.380293 | controller | skipping: [instance] 2025-10-08 15:06:24.380354 | controller | 2025-10-08 15:06:24.380370 | controller | TASK [repo_setup : Export hashes facts for further use] ************************ 2025-10-08 15:06:24.380388 | controller | Wednesday 08 October 2025 15:06:24 +0000 (0:00:00.061) 0:00:18.978 ***** 2025-10-08 15:06:24.453474 | controller | ok: [instance] 2025-10-08 15:06:24.453888 | controller | 2025-10-08 15:06:24.454037 | controller | TASK [repo_setup : Create download directory] ********************************** 2025-10-08 15:06:24.454380 | controller | Wednesday 08 October 2025 15:06:24 +0000 (0:00:00.073) 0:00:19.051 ***** 2025-10-08 15:06:24.513410 | controller | skipping: [instance] 2025-10-08 15:06:24.513645 | controller | 2025-10-08 15:06:24.513916 | controller | TASK [repo_setup : Print the URL to request] *********************************** 2025-10-08 15:06:24.514203 | controller | Wednesday 08 October 2025 15:06:24 +0000 (0:00:00.059) 0:00:19.111 ***** 2025-10-08 15:06:24.556843 | controller | skipping: [instance] 2025-10-08 15:06:24.557034 | controller | 2025-10-08 15:06:24.557338 | controller | TASK [Download the RPM] ******************************************************** 2025-10-08 15:06:24.557604 | controller | Wednesday 08 October 2025 15:06:24 +0000 (0:00:00.043) 0:00:19.154 ***** 2025-10-08 15:06:24.602392 | controller | skipping: [instance] 2025-10-08 15:06:24.602585 | controller | 2025-10-08 15:06:24.602848 | controller | TASK [repo_setup : Install RHOS Release tool] ********************************** 2025-10-08 15:06:24.603180 | controller | Wednesday 08 October 2025 15:06:24 +0000 (0:00:00.045) 0:00:19.199 ***** 2025-10-08 15:06:24.656603 | controller | skipping: [instance] 2025-10-08 15:06:24.656805 | controller | 2025-10-08 15:06:24.657078 | controller | TASK [repo_setup : Get rhos-release tool version] ****************************** 2025-10-08 15:06:24.657352 | controller | Wednesday 08 October 2025 15:06:24 +0000 (0:00:00.054) 0:00:19.254 ***** 2025-10-08 15:06:24.714933 | controller | skipping: [instance] 2025-10-08 15:06:24.715248 | controller | 2025-10-08 15:06:24.715581 | controller | TASK [repo_setup : Print rhos-release tool version] **************************** 2025-10-08 15:06:24.715922 | controller | Wednesday 08 October 2025 15:06:24 +0000 (0:00:00.057) 0:00:19.312 ***** 2025-10-08 15:06:24.763265 | controller | skipping: [instance] 2025-10-08 15:06:24.763518 | controller | 2025-10-08 15:06:24.763793 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] *** 2025-10-08 15:06:24.764076 | controller | Wednesday 08 October 2025 15:06:24 +0000 (0:00:00.048) 0:00:19.361 ***** 2025-10-08 15:06:24.819812 | controller | skipping: [instance] 2025-10-08 15:06:24.820082 | controller | 2025-10-08 15:06:24.820401 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] *************************** 2025-10-08 15:06:24.820733 | controller | Wednesday 08 October 2025 15:06:24 +0000 (0:00:00.056) 0:00:19.417 ***** 2025-10-08 15:06:25.120307 | controller | ok: [instance] 2025-10-08 15:06:26.482695 | controller | 2025-10-08 15:06:26.482759 | controller | TASK [repo_setup : Use RDO proxy mirrors] ************************************** 2025-10-08 15:06:26.482785 | controller | Wednesday 08 October 2025 15:06:25 +0000 (0:00:00.299) 0:00:19.716 ***** 2025-10-08 15:06:26.482816 | controller | changed: [instance] 2025-10-08 15:06:26.482953 | controller | 2025-10-08 15:06:26.483607 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] *** 2025-10-08 15:06:26.841586 | controller | Wednesday 08 October 2025 15:06:26 +0000 (0:00:01.363) 0:00:21.080 ***** 2025-10-08 15:06:26.841628 | controller | changed: [instance] 2025-10-08 15:06:26.869830 | controller | 2025-10-08 15:06:26.869888 | controller | TASK [repo_setup : Check for gating.repo file on content provider] ************* 2025-10-08 15:06:26.869907 | controller | Wednesday 08 October 2025 15:06:26 +0000 (0:00:00.359) 0:00:21.439 ***** 2025-10-08 15:06:26.869930 | controller | skipping: [instance] 2025-10-08 15:06:26.899274 | controller | 2025-10-08 15:06:26.899337 | controller | TASK [repo_setup : Populate gating repo from content provider ip] ************** 2025-10-08 15:06:26.899354 | controller | Wednesday 08 October 2025 15:06:26 +0000 (0:00:00.028) 0:00:21.467 ***** 2025-10-08 15:06:26.899378 | controller | skipping: [instance] 2025-10-08 15:06:26.928289 | controller | 2025-10-08 15:06:26.928348 | controller | TASK [repo_setup : Check for DLRN repo at the destination] ********************* 2025-10-08 15:06:26.928363 | controller | Wednesday 08 October 2025 15:06:26 +0000 (0:00:00.029) 0:00:21.497 ***** 2025-10-08 15:06:26.928408 | controller | skipping: [instance] 2025-10-08 15:06:26.928451 | controller | 2025-10-08 15:06:26.928512 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] *** 2025-10-08 15:06:26.928798 | controller | Wednesday 08 October 2025 15:06:26 +0000 (0:00:00.029) 0:00:21.526 ***** 2025-10-08 15:06:26.962838 | controller | skipping: [instance] 2025-10-08 15:06:26.963228 | controller | 2025-10-08 15:06:26.963577 | controller | TASK [repo_setup : Check for DLRN component repo] ****************************** 2025-10-08 15:06:26.963891 | controller | Wednesday 08 October 2025 15:06:26 +0000 (0:00:00.034) 0:00:21.561 ***** 2025-10-08 15:06:26.995676 | controller | skipping: [instance] 2025-10-08 15:06:26.996101 | controller | 2025-10-08 15:06:26.996482 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] *** 2025-10-08 15:06:26.996809 | controller | Wednesday 08 October 2025 15:06:26 +0000 (0:00:00.032) 0:00:21.593 ***** 2025-10-08 15:06:27.030470 | controller | skipping: [instance] 2025-10-08 15:06:27.030884 | controller | 2025-10-08 15:06:27.031282 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ******** 2025-10-08 15:06:27.031625 | controller | Wednesday 08 October 2025 15:06:27 +0000 (0:00:00.034) 0:00:21.628 ***** 2025-10-08 15:06:27.059703 | controller | skipping: [instance] 2025-10-08 15:06:27.060094 | controller | 2025-10-08 15:06:27.060453 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ****** 2025-10-08 15:06:27.060783 | controller | Wednesday 08 October 2025 15:06:27 +0000 (0:00:00.029) 0:00:21.657 ***** 2025-10-08 15:06:27.107387 | controller | skipping: [instance] 2025-10-08 15:06:27.107748 | controller | 2025-10-08 15:06:27.108130 | controller | TASK [repo_setup : Cleanup existing metadata] ********************************** 2025-10-08 15:06:27.108448 | controller | Wednesday 08 October 2025 15:06:27 +0000 (0:00:00.048) 0:00:21.705 ***** 2025-10-08 15:06:27.137522 | controller | skipping: [instance] 2025-10-08 15:06:27.137881 | controller | 2025-10-08 15:06:27.138250 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] ********* 2025-10-08 15:06:27.138584 | controller | Wednesday 08 October 2025 15:06:27 +0000 (0:00:00.030) 0:00:21.735 ***** 2025-10-08 15:06:27.167510 | controller | skipping: [instance] 2025-10-08 15:06:27.167661 | controller | 2025-10-08 15:06:27.167810 | controller | TASK [test_deps : Look for redhat-release rpm] ********************************* 2025-10-08 15:06:27.167952 | controller | Wednesday 08 October 2025 15:06:27 +0000 (0:00:00.030) 0:00:21.766 ***** 2025-10-08 15:06:27.220798 | controller | skipping: [instance] 2025-10-08 15:06:27.221202 | controller | 2025-10-08 15:06:27.221546 | controller | TASK [test_deps : Remove redhat-release] *************************************** 2025-10-08 15:06:27.221875 | controller | Wednesday 08 October 2025 15:06:27 +0000 (0:00:00.052) 0:00:21.818 ***** 2025-10-08 15:06:27.264512 | controller | skipping: [instance] 2025-10-08 15:06:27.264828 | controller | 2025-10-08 15:06:27.265215 | controller | TASK [test_deps : Install centos-stream-release] ******************************* 2025-10-08 15:06:27.265535 | controller | Wednesday 08 October 2025 15:06:27 +0000 (0:00:00.044) 0:00:21.862 ***** 2025-10-08 15:06:27.331032 | controller | skipping: [instance] 2025-10-08 15:06:27.331973 | controller | 2025-10-08 15:06:27.332350 | controller | TASK [test_deps : Install additional packages] ********************************* 2025-10-08 15:06:27.332628 | controller | Wednesday 08 October 2025 15:06:27 +0000 (0:00:00.065) 0:00:21.928 ***** 2025-10-08 15:06:27.391835 | controller | skipping: [instance] 2025-10-08 15:06:27.392043 | controller | 2025-10-08 15:06:27.392338 | controller | TASK [test_deps : Install selinux python libs] ********************************* 2025-10-08 15:06:27.392609 | controller | Wednesday 08 October 2025 15:06:27 +0000 (0:00:00.060) 0:00:21.989 ***** 2025-10-08 15:07:08.553252 | controller | ok: [instance] 2025-10-08 15:07:08.553416 | controller | 2025-10-08 15:07:08.553865 | controller | TASK [test_deps : Install python yaml libs] ************************************ 2025-10-08 15:07:10.119290 | controller | Wednesday 08 October 2025 15:07:08 +0000 (0:00:41.161) 0:01:03.150 ***** 2025-10-08 15:07:10.119357 | controller | ok: [instance] 2025-10-08 15:07:10.119724 | controller | 2025-10-08 15:07:10.119798 | controller | TASK [test_deps : Install extra packages] ************************************** 2025-10-08 15:07:10.199301 | controller | Wednesday 08 October 2025 15:07:10 +0000 (0:00:01.565) 0:01:04.716 ***** 2025-10-08 15:07:10.199383 | controller | skipping: [instance] 2025-10-08 15:07:10.284354 | controller | 2025-10-08 15:07:10.284449 | controller | TASK [ci_setup : Gather variables for each operating system] ******************* 2025-10-08 15:07:10.284467 | controller | Wednesday 08 October 2025 15:07:10 +0000 (0:00:00.079) 0:01:04.795 ***** 2025-10-08 15:07:10.284496 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml) 2025-10-08 15:07:10.309906 | controller | 2025-10-08 15:07:10.310079 | controller | TASK [ci_setup : List packages to install] ************************************* 2025-10-08 15:07:10.310101 | controller | Wednesday 08 October 2025 15:07:10 +0000 (0:00:00.085) 0:01:04.881 ***** 2025-10-08 15:07:10.310168 | controller | ok: [instance] => 2025-10-08 15:07:10.310489 | controller | cifmw_ci_setup_packages: 2025-10-08 15:07:10.310554 | controller | - bash-completion 2025-10-08 15:07:10.310675 | controller | - ca-certificates 2025-10-08 15:07:10.310931 | controller | - git-core 2025-10-08 15:07:10.311224 | controller | - make 2025-10-08 15:07:10.311484 | controller | - tar 2025-10-08 15:07:10.311803 | controller | - tmux 2025-10-08 15:07:10.312125 | controller | - python3-pip 2025-10-08 15:07:10.312407 | controller | 2025-10-08 15:07:10.312695 | controller | TASK [ci_setup : Install needed packages] ************************************** 2025-10-08 15:07:10.313233 | controller | Wednesday 08 October 2025 15:07:10 +0000 (0:00:00.026) 0:01:04.908 ***** 2025-10-08 15:07:16.198414 | controller | changed: [instance] 2025-10-08 15:07:17.500788 | controller | 2025-10-08 15:07:17.500840 | controller | TASK [ci_setup : Gather version of openshift client] *************************** 2025-10-08 15:07:17.500857 | controller | Wednesday 08 October 2025 15:07:16 +0000 (0:00:05.886) 0:01:10.795 ***** 2025-10-08 15:07:17.500879 | controller | ok: [instance] 2025-10-08 15:07:17.500984 | controller | 2025-10-08 15:07:17.501527 | controller | TASK [ci_setup : Ensure openshift client install path is present] ************** 2025-10-08 15:07:17.501615 | controller | Wednesday 08 October 2025 15:07:17 +0000 (0:00:01.303) 0:01:12.098 ***** 2025-10-08 15:07:17.844655 | controller | changed: [instance] 2025-10-08 15:07:17.845240 | controller | 2025-10-08 15:07:17.845421 | controller | TASK [ci_setup : Install openshift client] ************************************* 2025-10-08 15:07:17.845462 | controller | Wednesday 08 October 2025 15:07:17 +0000 (0:00:00.344) 0:01:12.442 ***** 2025-10-08 15:07:22.978227 | controller | changed: [instance] 2025-10-08 15:07:23.053220 | controller | 2025-10-08 15:07:23.053274 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] ********************** 2025-10-08 15:07:23.053291 | controller | Wednesday 08 October 2025 15:07:22 +0000 (0:00:05.132) 0:01:17.575 ***** 2025-10-08 15:07:23.053312 | controller | ok: [instance] 2025-10-08 15:07:23.053367 | controller | 2025-10-08 15:07:23.053387 | controller | TASK [ci_setup : Create completion file] *************************************** 2025-10-08 15:07:23.053792 | controller | Wednesday 08 October 2025 15:07:23 +0000 (0:00:00.075) 0:01:17.650 ***** 2025-10-08 15:07:23.496807 | controller | changed: [instance] 2025-10-08 15:07:23.932275 | controller | 2025-10-08 15:07:23.932329 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************ 2025-10-08 15:07:23.932346 | controller | Wednesday 08 October 2025 15:07:23 +0000 (0:00:00.443) 0:01:18.094 ***** 2025-10-08 15:07:23.932368 | controller | changed: [instance] 2025-10-08 15:07:23.971212 | controller | 2025-10-08 15:07:23.971257 | controller | TASK [ci_setup : Check rhsm status] ******************************************** 2025-10-08 15:07:23.971269 | controller | Wednesday 08 October 2025 15:07:23 +0000 (0:00:00.435) 0:01:18.529 ***** 2025-10-08 15:07:23.971285 | controller | skipping: [instance] 2025-10-08 15:07:24.006162 | controller | 2025-10-08 15:07:24.006203 | controller | TASK [ci_setup : Gather the repos to be enabled] ******************************* 2025-10-08 15:07:24.006213 | controller | Wednesday 08 October 2025 15:07:23 +0000 (0:00:00.039) 0:01:18.568 ***** 2025-10-08 15:07:24.006227 | controller | skipping: [instance] 2025-10-08 15:07:24.006832 | controller | 2025-10-08 15:07:24.037530 | controller | TASK [ci_setup : Enabling the required repositories.] ************************** 2025-10-08 15:07:24.037549 | controller | Wednesday 08 October 2025 15:07:24 +0000 (0:00:00.035) 0:01:18.603 ***** 2025-10-08 15:07:24.037560 | controller | skipping: [instance] 2025-10-08 15:07:24.069579 | controller | 2025-10-08 15:07:24.069596 | controller | TASK [ci_setup : Get current /etc/redhat-release] ****************************** 2025-10-08 15:07:24.069603 | controller | Wednesday 08 October 2025 15:07:24 +0000 (0:00:00.032) 0:01:18.636 ***** 2025-10-08 15:07:24.069612 | controller | skipping: [instance] 2025-10-08 15:07:24.069741 | controller | 2025-10-08 15:07:24.069875 | controller | TASK [ci_setup : Print current /etc/redhat-release] **************************** 2025-10-08 15:07:24.070027 | controller | Wednesday 08 October 2025 15:07:24 +0000 (0:00:00.032) 0:01:18.668 ***** 2025-10-08 15:07:24.097597 | controller | skipping: [instance] 2025-10-08 15:07:24.097890 | controller | 2025-10-08 15:07:24.098233 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] ********* 2025-10-08 15:07:24.098624 | controller | Wednesday 08 October 2025 15:07:24 +0000 (0:00:00.027) 0:01:18.696 ***** 2025-10-08 15:07:24.123382 | controller | skipping: [instance] 2025-10-08 15:07:24.123677 | controller | 2025-10-08 15:07:24.124001 | controller | TASK [ci_setup : Manage directories] ******************************************* 2025-10-08 15:07:24.124353 | controller | Wednesday 08 October 2025 15:07:24 +0000 (0:00:00.025) 0:01:18.721 ***** 2025-10-08 15:07:25.585391 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr) 2025-10-08 15:07:25.585542 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs) 2025-10-08 15:07:25.585578 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp) 2025-10-08 15:07:25.586662 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes) 2025-10-08 15:10:25.976640 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters) 2025-10-08 15:10:25.976719 | controller | 2025-10-08 15:10:25.976736 | controller | TASK [Ensure CRC is started] *************************************************** 2025-10-08 15:10:25.976750 | controller | Wednesday 08 October 2025 15:07:25 +0000 (0:00:01.460) 0:01:20.182 ***** 2025-10-08 15:10:25.976771 | controller | changed: [instance] 2025-10-08 15:10:27.384352 | controller | 2025-10-08 15:10:27.384425 | controller | TASK [Fetch install_yamls repository] ****************************************** 2025-10-08 15:10:27.384460 | controller | Wednesday 08 October 2025 15:10:25 +0000 (0:03:00.390) 0:04:20.573 ***** 2025-10-08 15:10:27.384484 | controller | changed: [instance] 2025-10-08 15:10:27.387018 | controller | 2025-10-08 15:10:27.387077 | controller | PLAY RECAP ********************************************************************* 2025-10-08 15:10:27.387091 | controller | instance : ok=31 changed=17 unreachable=0 failed=0 skipped=36 rescued=0 ignored=0 2025-10-08 15:10:27.387100 | controller | 2025-10-08 15:10:27.387109 | controller | Wednesday 08 October 2025 15:10:27 +0000 (0:00:01.408) 0:04:21.981 ***** 2025-10-08 15:10:27.387123 | controller | =============================================================================== 2025-10-08 15:10:27.455284 | controller | Ensure CRC is started ------------------------------------------------- 180.39s 2025-10-08 15:10:27.455314 | controller | test_deps : Install selinux python libs -------------------------------- 41.16s 2025-10-08 15:10:27.455322 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.18s 2025-10-08 15:10:27.455328 | controller | ci_setup : Install needed packages -------------------------------------- 5.89s 2025-10-08 15:10:27.455333 | controller | ci_setup : Install openshift client ------------------------------------- 5.13s 2025-10-08 15:10:27.455339 | controller | Gathering Facts --------------------------------------------------------- 2.15s 2025-10-08 15:10:27.455345 | controller | test_deps : Install python yaml libs ------------------------------------ 1.57s 2025-10-08 15:10:27.455351 | controller | ci_setup : Manage directories ------------------------------------------- 1.46s 2025-10-08 15:10:27.455357 | controller | Fetch install_yamls repository ------------------------------------------ 1.41s 2025-10-08 15:10:27.455362 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 1.36s 2025-10-08 15:10:27.455368 | controller | ci_setup : Gather version of openshift client --------------------------- 1.30s 2025-10-08 15:10:27.455374 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.23s 2025-10-08 15:10:27.455380 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.06s 2025-10-08 15:10:27.455385 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 1.02s 2025-10-08 15:10:27.455391 | controller | repo_setup : Install repo-setup package --------------------------------- 0.92s 2025-10-08 15:10:27.455397 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.86s 2025-10-08 15:10:27.455403 | controller | repo_setup : Ensure directories are present ----------------------------- 0.79s 2025-10-08 15:10:27.455409 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.68s 2025-10-08 15:10:27.455414 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.60s 2025-10-08 15:10:27.455426 | controller | ci_setup : Create completion file --------------------------------------- 0.44s 2025-10-08 15:10:27.455437 | controller | INFO Running default > converge 2025-10-08 15:10:27.957804 | controller | 2025-10-08 15:10:27.958293 | controller | PLAY [Converge] **************************************************************** 2025-10-08 15:10:27.958625 | controller | 2025-10-08 15:10:27.958945 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-08 15:10:27.959294 | controller | Wednesday 08 October 2025 15:10:27 +0000 (0:00:00.036) 0:00:00.036 ***** 2025-10-08 15:10:29.159432 | controller | ok: [instance] 2025-10-08 15:10:29.160024 | controller | 2025-10-08 15:12:42.085188 | controller | TASK [operator_deploy : Deploy selected operators] ***************************** 2025-10-08 15:12:42.085277 | controller | Wednesday 08 October 2025 15:10:29 +0000 (0:00:01.201) 0:00:01.238 ***** 2025-10-08 15:12:42.085304 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_000_deploy_selected.log 2025-10-08 15:12:42.085359 | controller | changed: [instance] => (item={'name': 'keystone'}) 2025-10-08 15:12:42.085376 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_001_deploy_selected.log 2025-10-08 15:12:42.085400 | controller | changed: [instance] => (item={'name': 'rabbitmq', 'params': {'RABBITMQ_IMG': 'quay.io/openstack-k8s-operators/rabbitmq-cluster-operator-index:latest'}}) 2025-10-08 15:12:42.085418 | controller | 2025-10-08 15:12:42.085456 | controller | PLAY RECAP ********************************************************************* 2025-10-08 15:12:42.085472 | controller | instance : ok=2 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2025-10-08 15:12:42.085499 | controller | 2025-10-08 15:12:42.085557 | controller | Wednesday 08 October 2025 15:12:42 +0000 (0:02:12.926) 0:02:14.165 ***** 2025-10-08 15:12:42.085628 | controller | =============================================================================== 2025-10-08 15:12:42.085744 | controller | operator_deploy : Deploy selected operators --------------------------- 132.93s 2025-10-08 15:12:42.085861 | controller | Gathering Facts --------------------------------------------------------- 1.20s 2025-10-08 15:12:42.155631 | controller | INFO Running default > cleanup 2025-10-08 15:12:42.156016 | controller | WARNING Skipping, cleanup playbook not configured. 2025-10-08 15:12:42.156623 | controller | INFO Writing /tmp/report.html report. 2025-10-08 15:12:42.478193 | controller | changed 2025-10-08 15:12:42.529004 | 2025-10-08 15:12:42.529157 | PLAY RECAP 2025-10-08 15:12:42.529214 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-08 15:12:42.529242 | 2025-10-08 15:12:42.653892 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-08 15:12:42.655785 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-08 15:12:43.355336 | 2025-10-08 15:12:43.355523 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-08 15:12:43.380616 | 2025-10-08 15:12:43.380853 | TASK [Filter out host if needed] 2025-10-08 15:12:43.391352 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-08 15:12:43.396570 | 2025-10-08 15:12:43.396680 | TASK [Ensure file is present] 2025-10-08 15:12:44.066479 | controller | ok 2025-10-08 15:12:44.080230 | 2025-10-08 15:12:44.080372 | TASK [Manage molecule report file] 2025-10-08 15:12:44.652747 | controller | changed 2025-10-08 15:12:44.665170 | 2025-10-08 15:12:44.665314 | TASK [Check if we get ci-framework-data basedir] 2025-10-08 15:12:45.240878 | controller | ok 2025-10-08 15:12:45.253157 | 2025-10-08 15:12:45.253538 | TASK [Create ci-framework-data log directory for zuul] 2025-10-08 15:12:45.779734 | controller | changed 2025-10-08 15:12:45.787909 | 2025-10-08 15:12:45.788005 | TASK [Copy ci-framework interesting files] 2025-10-08 15:12:46.157101 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-10-08 15:12:46.329889 | controller | changed 2025-10-08 15:12:46.341503 | 2025-10-08 15:12:46.341643 | TASK [Get SELinux listing] 2025-10-08 15:12:46.904635 | controller | changed 2025-10-08 15:12:46.919092 | 2025-10-08 15:12:46.919276 | TASK [Generate log index] 2025-10-08 15:12:49.130495 | controller | changed 2025-10-08 15:12:49.143340 | 2025-10-08 15:12:49.143474 | TASK [Get some env related data] 2025-10-08 15:12:50.154156 | controller | /home/zuul/.local/bin/ansible 2025-10-08 15:12:51.698652 | controller | changed 2025-10-08 15:12:51.712018 | 2025-10-08 15:12:51.712170 | TASK [Generate list of logs to collect in home directory] 2025-10-08 15:12:52.324258 | controller | ok: All paths examined 2025-10-08 15:12:52.338385 | 2025-10-08 15:12:52.338660 | LOOP [Copy logs from home directory] 2025-10-08 15:12:53.470526 | controller | changed: 2025-10-08 15:12:53.470938 | controller | { 2025-10-08 15:12:53.470993 | controller | "atime": 1743544925.4788878, 2025-10-08 15:12:53.471032 | controller | "ctime": 1743545329.1409318, 2025-10-08 15:12:53.471067 | controller | "dev": 64513, 2025-10-08 15:12:53.471100 | controller | "gid": 1000, 2025-10-08 15:12:53.471133 | controller | "gr_name": "zuul", 2025-10-08 15:12:53.471168 | controller | "inode": 4518807, 2025-10-08 15:12:53.471201 | controller | "isblk": false, 2025-10-08 15:12:53.471235 | controller | "ischr": false, 2025-10-08 15:12:53.471267 | controller | "isdir": false, 2025-10-08 15:12:53.471299 | controller | "isfifo": false, 2025-10-08 15:12:53.471332 | controller | "isgid": false, 2025-10-08 15:12:53.471364 | controller | "islnk": false, 2025-10-08 15:12:53.471396 | controller | "isreg": true, 2025-10-08 15:12:53.471427 | controller | "issock": false, 2025-10-08 15:12:53.471472 | controller | "isuid": false, 2025-10-08 15:12:53.471504 | controller | "mode": "0644", 2025-10-08 15:12:53.471536 | controller | "mtime": 1743545329.1409318, 2025-10-08 15:12:53.471567 | controller | "nlink": 1, 2025-10-08 15:12:53.471599 | controller | "path": "/home/zuul/crc-setup.log", 2025-10-08 15:12:53.471639 | controller | "pw_name": "zuul", 2025-10-08 15:12:53.472023 | controller | "rgrp": true, 2025-10-08 15:12:53.472078 | controller | "roth": true, 2025-10-08 15:12:53.472124 | controller | "rusr": true, 2025-10-08 15:12:53.472166 | controller | "size": 4108, 2025-10-08 15:12:53.472199 | controller | "uid": 1000, 2025-10-08 15:12:53.472231 | controller | "wgrp": false, 2025-10-08 15:12:53.472262 | controller | "woth": false, 2025-10-08 15:12:53.472299 | controller | "wusr": true, 2025-10-08 15:12:53.472333 | controller | "xgrp": false, 2025-10-08 15:12:53.472366 | controller | "xoth": false, 2025-10-08 15:12:53.472403 | controller | "xusr": false 2025-10-08 15:12:53.472434 | controller | } 2025-10-08 15:12:54.429573 | controller | changed: 2025-10-08 15:12:54.429749 | controller | { 2025-10-08 15:12:54.429781 | controller | "atime": 1743545331.0429637, 2025-10-08 15:12:54.429805 | controller | "ctime": 1743545842.0111232, 2025-10-08 15:12:54.429827 | controller | "dev": 64513, 2025-10-08 15:12:54.429849 | controller | "gid": 1000, 2025-10-08 15:12:54.429869 | controller | "gr_name": "zuul", 2025-10-08 15:12:54.429889 | controller | "inode": 4194437, 2025-10-08 15:12:54.429909 | controller | "isblk": false, 2025-10-08 15:12:54.429929 | controller | "ischr": false, 2025-10-08 15:12:54.429948 | controller | "isdir": false, 2025-10-08 15:12:54.429968 | controller | "isfifo": false, 2025-10-08 15:12:54.429987 | controller | "isgid": false, 2025-10-08 15:12:54.430006 | controller | "islnk": false, 2025-10-08 15:12:54.430025 | controller | "isreg": true, 2025-10-08 15:12:54.430045 | controller | "issock": false, 2025-10-08 15:12:54.430065 | controller | "isuid": false, 2025-10-08 15:12:54.430085 | controller | "mode": "0644", 2025-10-08 15:12:54.430105 | controller | "mtime": 1743545842.0111232, 2025-10-08 15:12:54.430125 | controller | "nlink": 1, 2025-10-08 15:12:54.430145 | controller | "path": "/home/zuul/crc-start.log", 2025-10-08 15:12:54.430166 | controller | "pw_name": "zuul", 2025-10-08 15:12:54.430186 | controller | "rgrp": true, 2025-10-08 15:12:54.430206 | controller | "roth": true, 2025-10-08 15:12:54.430226 | controller | "rusr": true, 2025-10-08 15:12:54.430245 | controller | "size": 4023, 2025-10-08 15:12:54.430264 | controller | "uid": 1000, 2025-10-08 15:12:54.430284 | controller | "wgrp": false, 2025-10-08 15:12:54.430303 | controller | "woth": false, 2025-10-08 15:12:54.430323 | controller | "wusr": true, 2025-10-08 15:12:54.430342 | controller | "xgrp": false, 2025-10-08 15:12:54.430361 | controller | "xoth": false, 2025-10-08 15:12:54.430381 | controller | "xusr": false 2025-10-08 15:12:54.430401 | controller | } 2025-10-08 15:12:55.392748 | controller | changed: 2025-10-08 15:12:55.392919 | controller | { 2025-10-08 15:12:55.392969 | controller | "atime": 1759935920.5597873, 2025-10-08 15:12:55.393008 | controller | "ctime": 1759935938.2003946, 2025-10-08 15:12:55.393061 | controller | "dev": 64513, 2025-10-08 15:12:55.393104 | controller | "gid": 1000, 2025-10-08 15:12:55.393147 | controller | "gr_name": "zuul", 2025-10-08 15:12:55.393190 | controller | "inode": 4518819, 2025-10-08 15:12:55.393231 | controller | "isblk": false, 2025-10-08 15:12:55.393272 | controller | "ischr": false, 2025-10-08 15:12:55.393313 | controller | "isdir": false, 2025-10-08 15:12:55.393353 | controller | "isfifo": false, 2025-10-08 15:12:55.393392 | controller | "isgid": false, 2025-10-08 15:12:55.393433 | controller | "islnk": false, 2025-10-08 15:12:55.393474 | controller | "isreg": true, 2025-10-08 15:12:55.393515 | controller | "issock": false, 2025-10-08 15:12:55.393556 | controller | "isuid": false, 2025-10-08 15:12:55.393596 | controller | "mode": "0644", 2025-10-08 15:12:55.393637 | controller | "mtime": 1759935938.2003946, 2025-10-08 15:12:55.393710 | controller | "nlink": 1, 2025-10-08 15:12:55.393758 | controller | "path": "/home/zuul/ansible.log", 2025-10-08 15:12:55.393800 | controller | "pw_name": "zuul", 2025-10-08 15:12:55.393842 | controller | "rgrp": true, 2025-10-08 15:12:55.393884 | controller | "roth": true, 2025-10-08 15:12:55.393983 | controller | "rusr": true, 2025-10-08 15:12:55.394037 | controller | "size": 6701, 2025-10-08 15:12:55.394080 | controller | "uid": 1000, 2025-10-08 15:12:55.394123 | controller | "wgrp": false, 2025-10-08 15:12:55.394164 | controller | "woth": false, 2025-10-08 15:12:55.394205 | controller | "wusr": true, 2025-10-08 15:12:55.394245 | controller | "xgrp": false, 2025-10-08 15:12:55.394284 | controller | "xoth": false, 2025-10-08 15:12:55.394323 | controller | "xusr": false 2025-10-08 15:12:55.394363 | controller | } 2025-10-08 15:12:55.412962 | 2025-10-08 15:12:55.413221 | TASK [Copy crio stats log file] 2025-10-08 15:12:55.443001 | controller | skipping: Conditional result was False 2025-10-08 15:12:55.456082 | 2025-10-08 15:12:55.456233 | TASK [Get SELinux related data] 2025-10-08 15:12:55.958548 | controller | 2025-10-08 15:12:56.007951 | controller | ERROR 2025-10-08 15:12:56.008300 | controller | { 2025-10-08 15:12:56.008360 | controller | "delta": "0:00:00.028899", 2025-10-08 15:12:56.008402 | controller | "end": "2025-10-08 15:12:55.959676", 2025-10-08 15:12:56.008440 | controller | "msg": "non-zero return code", 2025-10-08 15:12:56.008476 | controller | "rc": 1, 2025-10-08 15:12:56.008511 | controller | "start": "2025-10-08 15:12:55.930777" 2025-10-08 15:12:56.008545 | controller | } 2025-10-08 15:12:56.008591 | controller | ERROR: Ignoring Errors 2025-10-08 15:12:56.021692 | 2025-10-08 15:12:56.021961 | TASK [Create system configuration directory] 2025-10-08 15:12:56.461193 | controller | changed 2025-10-08 15:12:56.473056 | 2025-10-08 15:12:56.473208 | TASK [Get some of the system configurations] 2025-10-08 15:12:57.024964 | controller | changed 2025-10-08 15:12:57.031115 | 2025-10-08 15:12:57.031189 | TASK [Copy generated documentation if available] 2025-10-08 15:12:57.067545 | controller | skipping: Conditional result was False 2025-10-08 15:12:57.080797 | 2025-10-08 15:12:57.080936 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-08 15:12:57.111109 | controller | skipping: Conditional result was False 2025-10-08 15:12:57.124860 | 2025-10-08 15:12:57.124993 | TASK [Compress logs bigger than 2MB] 2025-10-08 15:12:57.675479 | controller | changed 2025-10-08 15:12:57.682734 | 2025-10-08 15:12:57.682853 | TASK [Copy files from workspace on node] 2025-10-08 15:12:57.705376 | controller | ok 2025-10-08 15:12:57.735623 | 2025-10-08 15:12:57.735807 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-08 15:12:57.771997 | controller | skipping: Conditional result was False 2025-10-08 15:12:57.780395 | 2025-10-08 15:12:57.780492 | TASK [fetch-output : Set log path for single node] 2025-10-08 15:12:57.827643 | controller | ok 2025-10-08 15:12:57.839300 | 2025-10-08 15:12:57.839439 | LOOP [fetch-output : Ensure local output dirs] 2025-10-08 15:12:58.151983 | controller -> localhost | ok: "/var/lib/zuul/builds/34988eb0dcd340b2a05df673cc1c0588/work/logs" 2025-10-08 15:12:58.152649 | controller -> localhost | changed: All items complete 2025-10-08 15:12:58.152781 | 2025-10-08 15:12:58.438509 | controller -> localhost | changed: "/var/lib/zuul/builds/34988eb0dcd340b2a05df673cc1c0588/work/artifacts" 2025-10-08 15:12:58.672187 | controller -> localhost | changed: "/var/lib/zuul/builds/34988eb0dcd340b2a05df673cc1c0588/work/docs" 2025-10-08 15:12:58.692444 | 2025-10-08 15:12:58.692603 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-08 15:12:59.820384 | controller | changed: 2025-10-08 15:12:59.820588 | controller | .d..t...... ./ 2025-10-08 15:12:59.820614 | controller | >f+++++++++ README.html 2025-10-08 15:12:59.820633 | controller | >f+++++++++ ansible-execution.log 2025-10-08 15:12:59.820653 | controller | >f+++++++++ ansible.log 2025-10-08 15:12:59.820690 | controller | >f+++++++++ crc-setup.log 2025-10-08 15:12:59.820709 | controller | >f+++++++++ crc-start.log 2025-10-08 15:12:59.820725 | controller | >f+++++++++ dmesg.log 2025-10-08 15:12:59.820745 | controller | >f+++++++++ installed-pkgs.log 2025-10-08 15:12:59.820768 | controller | >f+++++++++ python.log 2025-10-08 15:12:59.820786 | controller | >f+++++++++ registries.conf 2025-10-08 15:12:59.820802 | controller | >f+++++++++ report.html 2025-10-08 15:12:59.820818 | controller | >f+++++++++ selinux-denials.log 2025-10-08 15:12:59.820833 | controller | >f+++++++++ selinux-listing.log 2025-10-08 15:12:59.820849 | controller | cd+++++++++ ci-framework-data/ 2025-10-08 15:12:59.820864 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-08 15:12:59.820880 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_deploy_selected.sh 2025-10-08 15:12:59.820896 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_001_deploy_selected.sh 2025-10-08 15:12:59.820912 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-08 15:12:59.820927 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/ 2025-10-08 15:12:59.820943 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/ 2025-10-08 15:12:59.821030 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/ 2025-10-08 15:12:59.821051 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-08 15:12:59.821068 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-10-08 15:12:59.821084 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/ 2025-10-08 15:12:59.821100 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5 2025-10-08 15:12:59.821116 | controller | cd+++++++++ ci-framework-data/logs/ 2025-10-08 15:12:59.821132 | controller | >f+++++++++ ci-framework-data/logs/ci_script_000_deploy_selected.log 2025-10-08 15:12:59.821147 | controller | >f+++++++++ ci-framework-data/logs/ci_script_001_deploy_selected.log 2025-10-08 15:12:59.821163 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log 2025-10-08 15:12:59.821179 | controller | cd+++++++++ registries.conf.d/ 2025-10-08 15:12:59.821194 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-10-08 15:12:59.821210 | controller | cd+++++++++ system-config/ 2025-10-08 15:12:59.821226 | controller | cd+++++++++ system-config/libvirt/ 2025-10-08 15:12:59.821241 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-10-08 15:12:59.821324 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-10-08 15:12:59.821343 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf 2025-10-08 15:12:59.821359 | controller | >f+++++++++ system-config/libvirt/network.conf 2025-10-08 15:12:59.821375 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf 2025-10-08 15:12:59.821390 | controller | >f+++++++++ system-config/libvirt/qemu.conf 2025-10-08 15:12:59.821405 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf 2025-10-08 15:12:59.821421 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf 2025-10-08 15:12:59.821436 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf 2025-10-08 15:12:59.821452 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf 2025-10-08 15:12:59.821468 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf 2025-10-08 15:12:59.821483 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf 2025-10-08 15:12:59.821499 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf 2025-10-08 15:12:59.821514 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf 2025-10-08 15:12:59.821530 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf 2025-10-08 15:12:59.821545 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf 2025-10-08 15:13:00.538884 | controller | changed: .d..t...... ./ 2025-10-08 15:13:01.139216 | controller | changed: .d..t...... ./ 2025-10-08 15:13:01.162007 | 2025-10-08 15:13:01.162148 | TASK [Return artifact to Zuul] 2025-10-08 15:13:01.203704 | controller | ok 2025-10-08 15:13:01.224726 | 2025-10-08 15:13:01.224842 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-08 15:13:01.224950 | 2025-10-08 15:13:01.224976 | PLAY RECAP 2025-10-08 15:13:01.225013 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1 2025-10-08 15:13:01.225031 | 2025-10-08 15:13:01.349040 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-08 15:13:01.349936 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-08 15:13:02.001618 | 2025-10-08 15:13:02.001789 | PLAY [all] 2025-10-08 15:13:02.021605 | 2025-10-08 15:13:02.021697 | TASK [include_role : fetch-output] 2025-10-08 15:13:02.052975 | controller | ok 2025-10-08 15:13:02.072754 | 2025-10-08 15:13:02.072852 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-08 15:13:02.120649 | controller | skipping: Conditional result was False 2025-10-08 15:13:02.129177 | 2025-10-08 15:13:02.129273 | TASK [fetch-output : Set log path for single node] 2025-10-08 15:13:02.175771 | controller | ok 2025-10-08 15:13:02.185284 | 2025-10-08 15:13:02.185377 | LOOP [fetch-output : Ensure local output dirs] 2025-10-08 15:13:02.672929 | controller -> localhost | ok: "/var/lib/zuul/builds/34988eb0dcd340b2a05df673cc1c0588/work/logs" 2025-10-08 15:13:02.966276 | controller -> localhost | ok: "/var/lib/zuul/builds/34988eb0dcd340b2a05df673cc1c0588/work/artifacts" 2025-10-08 15:13:03.213426 | controller -> localhost | ok: "/var/lib/zuul/builds/34988eb0dcd340b2a05df673cc1c0588/work/docs" 2025-10-08 15:13:03.224272 | 2025-10-08 15:13:03.224363 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-08 15:13:04.184933 | controller | ok 2025-10-08 15:13:04.185346 | controller | ok: All items complete 2025-10-08 15:13:04.185406 | 2025-10-08 15:13:04.970705 | controller | ok 2025-10-08 15:13:05.650448 | controller | ok 2025-10-08 15:13:05.683928 | 2025-10-08 15:13:05.684182 | TASK [include_role : fetch-output-openshift] 2025-10-08 15:13:05.723063 | controller | skipping: Conditional result was False 2025-10-08 15:13:05.732406 | 2025-10-08 15:13:05.732504 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-08 15:13:06.200059 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.012943 2025-10-08 15:13:06.494327 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.010072 2025-10-08 15:13:06.524679 | 2025-10-08 15:13:06.524803 | PLAY [all] 2025-10-08 15:13:06.540805 | 2025-10-08 15:13:06.540916 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-08 15:13:07.166043 | controller | changed 2025-10-08 15:13:07.190852 | 2025-10-08 15:13:07.190910 | PLAY RECAP 2025-10-08 15:13:07.190952 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-08 15:13:07.190972 | 2025-10-08 15:13:07.303156 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-08 15:13:07.304825 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-08 15:13:07.969038 | 2025-10-08 15:13:07.969164 | PLAY [localhost] 2025-10-08 15:13:07.986680 | 2025-10-08 15:13:07.986762 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-08 15:13:08.506253 | localhost | changed 2025-10-08 15:13:08.510915 | 2025-10-08 15:13:08.510989 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-08 15:13:08.539380 | localhost | ok 2025-10-08 15:13:08.548046 | 2025-10-08 15:13:08.548115 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-08 15:13:08.880788 | localhost | changed 2025-10-08 15:13:08.885792 | 2025-10-08 15:13:08.885861 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-08 15:13:09.576524 | localhost | changed 2025-10-08 15:13:09.581915 | 2025-10-08 15:13:09.581980 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-08 15:13:10.027601 | localhost | Identity added: /var/lib/zuul/builds/34988eb0dcd340b2a05df673cc1c0588/work/tmp/ansible._4hyr9uq (/var/lib/zuul/builds/34988eb0dcd340b2a05df673cc1c0588/work/tmp/ansible._4hyr9uq) 2025-10-08 15:13:10.028040 | localhost | ok: Runtime: 0:00:00.013590 2025-10-08 15:13:10.038881 | 2025-10-08 15:13:10.039022 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-08 15:13:10.326833 | localhost | ok: Runtime: 0:00:00.005979 2025-10-08 15:13:10.332561 | 2025-10-08 15:13:10.332631 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-08 15:13:10.381460 | localhost | changed 2025-10-08 15:13:10.385727 | 2025-10-08 15:13:10.385791 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-08 15:13:10.778873 | localhost | changed 2025-10-08 15:13:10.799380 | 2025-10-08 15:13:10.799435 | PLAY [localhost] 2025-10-08 15:13:10.811757 | 2025-10-08 15:13:10.811820 | TASK [Generate bulk log download script] 2025-10-08 15:13:10.831924 | localhost | ok 2025-10-08 15:13:10.844126 | 2025-10-08 15:13:10.844255 | TASK [local-log-download : Check API endpoint is defined] 2025-10-08 15:13:10.892389 | localhost | ok: All assertions passed 2025-10-08 15:13:10.896958 | 2025-10-08 15:13:10.897024 | TASK [local-log-download : Create download script] 2025-10-08 15:13:11.428573 | localhost -> localhost | changed 2025-10-08 15:13:11.437943 | 2025-10-08 15:13:11.438008 | TASK [Register quick-download link] 2025-10-08 15:13:11.467313 | localhost | ok 2025-10-08 15:13:11.509850 | 2025-10-08 15:13:11.509977 | PLAY [logserver.rdoproject.org] 2025-10-08 15:13:11.519586 | 2025-10-08 15:13:11.519651 | TASK [Set zuul-log-path fact] 2025-10-08 15:13:11.537940 | logserver.rdoproject.org | ok 2025-10-08 15:13:11.547607 | 2025-10-08 15:13:11.547709 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-08 15:13:11.585431 | logserver.rdoproject.org | ok 2025-10-08 15:13:11.590805 | 2025-10-08 15:13:11.590868 | TASK [upload-logs : Create log directories] 2025-10-08 15:13:12.297402 | logserver.rdoproject.org | changed 2025-10-08 15:13:12.304599 | 2025-10-08 15:13:12.304766 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-08 15:13:12.598229 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.006577 2025-10-08 15:13:12.604454 | 2025-10-08 15:13:12.604573 | TASK [upload-logs : Upload logs to log server] 2025-10-08 15:13:13.351127 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-08 15:13:13.354275 | 2025-10-08 15:13:13.354338 | LOOP [upload-logs : Compress console log and json output] 2025-10-08 15:13:13.396508 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-08 15:13:13.404744 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-08 15:13:13.415307 | 2025-10-08 15:13:13.415481 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-08 15:13:13.459765 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-08 15:13:13.460174 | 2025-10-08 15:13:13.466069 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-08 15:13:13.476224 | 2025-10-08 15:13:13.476371 | LOOP [upload-logs : Upload console log and json output]