2025-10-08 15:00:45.282790 | Job console starting... 2025-10-08 15:00:45.292248 | Updating repositories 2025-10-08 15:00:45.336581 | Preparing job workspace 2025-10-08 15:00:51.983016 | Running Ansible setup... 2025-10-08 15:00:57.284457 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-08 15:00:57.839068 | 2025-10-08 15:00:57.839179 | PLAY [localhost] 2025-10-08 15:00:57.847915 | 2025-10-08 15:00:57.847984 | TASK [Gathering Facts] 2025-10-08 15:00:58.800996 | localhost | ok 2025-10-08 15:00:58.822898 | 2025-10-08 15:00:58.823082 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-08 15:00:59.229077 | localhost -> localhost | changed 2025-10-08 15:00:59.237338 | 2025-10-08 15:00:59.237457 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-08 15:01:00.127950 | localhost -> localhost | changed 2025-10-08 15:01:00.136952 | 2025-10-08 15:01:00.137023 | TASK [Setup log path fact] 2025-10-08 15:01:00.155296 | localhost | ok 2025-10-08 15:01:00.174893 | 2025-10-08 15:01:00.174974 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-08 15:01:00.202409 | localhost | ok 2025-10-08 15:01:00.211051 | 2025-10-08 15:01:00.211116 | TASK [emit-job-header : Print job information] 2025-10-08 15:01:00.240965 | # Job Information 2025-10-08 15:01:00.241145 | Ansible Version: 2.15.12 2025-10-08 15:01:00.241180 | Job: cifmw-molecule-ci_nmstate 2025-10-08 15:01:00.241207 | Pipeline: github-check 2025-10-08 15:01:00.241232 | Executor: ze02.softwarefactory-project.io 2025-10-08 15:01:00.241257 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3368 2025-10-08 15:01:00.241283 | Log URL (when completed): https://logserver.rdoproject.org/52c/rdoproject.org/52c5c4b0566d45b989e1639655094bde/ 2025-10-08 15:01:00.241307 | Event ID: dc080060-a456-11f0-897c-380a1fc1fefa 2025-10-08 15:01:00.247831 | 2025-10-08 15:01:00.247920 | LOOP [emit-job-header : Print node information] 2025-10-08 15:01:00.353592 | localhost | ok: 2025-10-08 15:01:00.353889 | localhost | # Node Information 2025-10-08 15:01:00.353918 | localhost | Inventory Hostname: controller 2025-10-08 15:01:00.354023 | localhost | Hostname: np0005476881 2025-10-08 15:01:00.354162 | localhost | Username: zuul 2025-10-08 15:01:00.354185 | localhost | Distro: CentOS 9 2025-10-08 15:01:00.354203 | localhost | Provider: vexxhost-nodepool-tripleo 2025-10-08 15:01:00.354220 | localhost | Region: RegionOne 2025-10-08 15:01:00.354237 | localhost | Label: centos-9-stream-crc-2-48-0-xxl 2025-10-08 15:01:00.354253 | localhost | Product Name: OpenStack Nova 2025-10-08 15:01:00.354268 | localhost | Interface IP: 38.129.56.68 2025-10-08 15:01:00.387761 | 2025-10-08 15:01:00.387894 | PLAY [all] 2025-10-08 15:01:00.397141 | 2025-10-08 15:01:00.397230 | TASK [Gather network facts] 2025-10-08 15:01:01.001947 | controller | ok 2025-10-08 15:01:01.032169 | 2025-10-08 15:01:01.032267 | TASK [include_role : start-zuul-console] 2025-10-08 15:01:01.059470 | controller | ok 2025-10-08 15:01:01.082484 | 2025-10-08 15:01:01.082727 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-08 15:01:01.602319 | controller | ok 2025-10-08 15:01:01.621623 | 2025-10-08 15:01:01.621760 | TASK [include_role : add-build-sshkey] 2025-10-08 15:01:01.656445 | controller | ok 2025-10-08 15:01:01.678034 | 2025-10-08 15:01:01.678155 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-08 15:01:01.939623 | controller -> localhost | ok 2025-10-08 15:01:01.948174 | 2025-10-08 15:01:01.948267 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-08 15:01:01.971917 | controller | ok 2025-10-08 15:01:01.992352 | controller | included: /var/lib/zuul/builds/52c5c4b0566d45b989e1639655094bde/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-08 15:01:02.001855 | 2025-10-08 15:01:02.001942 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-08 15:01:02.680874 | controller -> localhost | Generating public/private rsa key pair. 2025-10-08 15:01:02.681146 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/52c5c4b0566d45b989e1639655094bde/work/52c5c4b0566d45b989e1639655094bde_id_rsa. 2025-10-08 15:01:02.681193 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/52c5c4b0566d45b989e1639655094bde/work/52c5c4b0566d45b989e1639655094bde_id_rsa.pub. 2025-10-08 15:01:02.681244 | controller -> localhost | The key fingerprint is: 2025-10-08 15:01:02.681280 | controller -> localhost | SHA256:fJ25WB8m+i8tu6Cmgl+k6uvh5ESgdkPwab19CTnl3sc zuul-build-sshkey 2025-10-08 15:01:02.681313 | controller -> localhost | The key's randomart image is: 2025-10-08 15:01:02.681344 | controller -> localhost | +---[RSA 3072]----+ 2025-10-08 15:01:02.681374 | controller -> localhost | | . . | 2025-10-08 15:01:02.681405 | controller -> localhost | | o o + | 2025-10-08 15:01:02.681435 | controller -> localhost | |. = . + . | 2025-10-08 15:01:02.681465 | controller -> localhost | |..o o.+ o..o | 2025-10-08 15:01:02.681493 | controller -> localhost | |...o ...S+..*Eo | 2025-10-08 15:01:02.681521 | controller -> localhost | |... .o .. +.= . | 2025-10-08 15:01:02.681549 | controller -> localhost | | +.. . + ... | 2025-10-08 15:01:02.681580 | controller -> localhost | | =.o.. .. oo . | 2025-10-08 15:01:02.681609 | controller -> localhost | | oBo...o. +*. | 2025-10-08 15:01:02.681637 | controller -> localhost | +----[SHA256]-----+ 2025-10-08 15:01:02.681723 | controller -> localhost | ok: Runtime: 0:00:00.206553 2025-10-08 15:01:02.692520 | 2025-10-08 15:01:02.692729 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-08 15:01:02.729712 | controller | ok 2025-10-08 15:01:02.745112 | controller | included: /var/lib/zuul/builds/52c5c4b0566d45b989e1639655094bde/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-08 15:01:02.755779 | 2025-10-08 15:01:02.755857 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-08 15:01:02.781297 | controller | skipping: Conditional result was False 2025-10-08 15:01:02.787701 | 2025-10-08 15:01:02.787819 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-08 15:01:03.469567 | controller | changed 2025-10-08 15:01:03.476921 | 2025-10-08 15:01:03.477012 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-08 15:01:03.807065 | controller | ok 2025-10-08 15:01:03.820235 | 2025-10-08 15:01:03.820380 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-08 15:01:05.493391 | controller | changed 2025-10-08 15:01:05.505053 | 2025-10-08 15:01:05.505201 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-08 15:01:07.309016 | controller | changed 2025-10-08 15:01:07.317134 | 2025-10-08 15:01:07.317241 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-08 15:01:07.343477 | controller | skipping: Conditional result was False 2025-10-08 15:01:07.354162 | 2025-10-08 15:01:07.354343 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-08 15:01:07.808494 | controller -> localhost | changed 2025-10-08 15:01:07.830893 | 2025-10-08 15:01:07.831022 | TASK [add-build-sshkey : Add back temp key] 2025-10-08 15:01:08.110187 | controller -> localhost | Identity added: /var/lib/zuul/builds/52c5c4b0566d45b989e1639655094bde/work/52c5c4b0566d45b989e1639655094bde_id_rsa (zuul-build-sshkey) 2025-10-08 15:01:08.110382 | controller -> localhost | ok: Runtime: 0:00:00.007677 2025-10-08 15:01:08.116330 | 2025-10-08 15:01:08.116391 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-08 15:01:08.616171 | controller | ok 2025-10-08 15:01:08.622761 | 2025-10-08 15:01:08.622847 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-08 15:01:08.677993 | controller | skipping: Conditional result was False 2025-10-08 15:01:08.691500 | 2025-10-08 15:01:08.691585 | TASK [include_role : validate-host] 2025-10-08 15:01:08.724957 | controller | ok 2025-10-08 15:01:08.780327 | 2025-10-08 15:01:08.780459 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-08 15:01:08.820249 | controller | ok 2025-10-08 15:01:08.824724 | 2025-10-08 15:01:08.824784 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-08 15:01:09.157073 | controller -> localhost | ok 2025-10-08 15:01:09.169827 | 2025-10-08 15:01:09.169995 | TASK [validate-host : Collect information about the host] 2025-10-08 15:01:10.071984 | controller | ok 2025-10-08 15:01:10.095239 | 2025-10-08 15:01:10.095431 | TASK [validate-host : Sanitize hostname] 2025-10-08 15:01:10.170084 | controller | ok 2025-10-08 15:01:10.177384 | 2025-10-08 15:01:10.177471 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-08 15:01:10.656563 | controller -> localhost | changed 2025-10-08 15:01:10.670645 | 2025-10-08 15:01:10.670820 | TASK [validate-host : Collect information about zuul worker] 2025-10-08 15:01:11.143340 | controller | ok 2025-10-08 15:01:11.155300 | 2025-10-08 15:01:11.155461 | TASK [validate-host : Write out all zuul information for each host] 2025-10-08 15:01:11.655577 | controller -> localhost | changed 2025-10-08 15:01:11.682054 | 2025-10-08 15:01:11.682196 | TASK [include_role : prepare-workspace-openshift] 2025-10-08 15:01:11.708314 | controller | skipping: Conditional result was False 2025-10-08 15:01:11.716020 | 2025-10-08 15:01:11.716120 | TASK [include_role : remove-zuul-sshkey] 2025-10-08 15:01:11.742019 | controller | skipping: Conditional result was False 2025-10-08 15:01:11.749721 | 2025-10-08 15:01:11.749808 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-08 15:01:12.124645 | controller | ok: "logs" 2025-10-08 15:01:12.125168 | controller | ok: All items complete 2025-10-08 15:01:12.125228 | 2025-10-08 15:01:12.497932 | controller | ok: "artifacts" 2025-10-08 15:01:12.839659 | controller | ok: "docs" 2025-10-08 15:01:12.858857 | 2025-10-08 15:01:12.859793 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-08 15:01:13.270113 | controller | changed: "logs" 2025-10-08 15:01:13.621944 | controller | changed: "artifacts" 2025-10-08 15:01:14.001358 | controller | changed: "docs" 2025-10-08 15:01:14.034283 | 2025-10-08 15:01:14.034391 | PLAY RECAP 2025-10-08 15:01:14.034432 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-08 15:01:14.034560 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-08 15:01:14.034591 | 2025-10-08 15:01:14.160890 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-08 15:01:14.162452 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-08 15:01:14.793753 | 2025-10-08 15:01:14.793860 | PLAY [all] 2025-10-08 15:01:14.814619 | 2025-10-08 15:01:14.814764 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-08 15:01:14.894860 | controller | ok 2025-10-08 15:01:14.899995 | 2025-10-08 15:01:14.900074 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-08 15:01:15.446247 | controller | changed 2025-10-08 15:01:15.459087 | 2025-10-08 15:01:15.459260 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-08 15:01:17.331618 | controller | changed 2025-10-08 15:01:17.349216 | 2025-10-08 15:01:17.349403 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-08 15:01:17.964904 | controller | changed: 2025-10-08 15:01:17.965090 | controller | { 2025-10-08 15:01:17.965121 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-08 15:01:17.965149 | controller | } 2025-10-08 15:01:18.375593 | controller | changed: 2025-10-08 15:01:18.375712 | controller | { 2025-10-08 15:01:18.375745 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-08 15:01:18.375766 | controller | } 2025-10-08 15:01:18.771001 | controller | changed: 2025-10-08 15:01:18.771116 | controller | { 2025-10-08 15:01:18.771169 | 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:01:18.771211 | controller | } 2025-10-08 15:01:19.187265 | controller | changed: 2025-10-08 15:01:19.187396 | controller | { 2025-10-08 15:01:19.187429 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-08 15:01:19.187456 | controller | } 2025-10-08 15:01:19.602935 | controller | changed: 2025-10-08 15:01:19.603631 | controller | { 2025-10-08 15:01:19.603724 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-08 15:01:19.603768 | controller | } 2025-10-08 15:01:20.019274 | controller | changed: 2025-10-08 15:01:20.019430 | controller | { 2025-10-08 15:01:20.019482 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-08 15:01:20.019524 | controller | } 2025-10-08 15:01:20.403264 | controller | changed: 2025-10-08 15:01:20.403417 | controller | { 2025-10-08 15:01:20.403470 | 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:01:20.403514 | controller | } 2025-10-08 15:01:20.827441 | controller | changed: 2025-10-08 15:01:20.827563 | controller | { 2025-10-08 15:01:20.827600 | 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:01:20.827630 | controller | } 2025-10-08 15:01:21.199499 | controller | changed: 2025-10-08 15:01:21.199692 | controller | { 2025-10-08 15:01:21.199772 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-08 15:01:21.199819 | controller | } 2025-10-08 15:01:21.585494 | controller | changed: 2025-10-08 15:01:21.585784 | controller | { 2025-10-08 15:01:21.585848 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-08 15:01:21.585894 | controller | } 2025-10-08 15:01:21.983937 | controller | changed: 2025-10-08 15:01:21.984544 | controller | { 2025-10-08 15:01:21.984587 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-08 15:01:21.984617 | controller | } 2025-10-08 15:01:22.403501 | controller | changed: 2025-10-08 15:01:22.403618 | controller | { 2025-10-08 15:01:22.403652 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-08 15:01:22.403703 | controller | } 2025-10-08 15:01:23.192136 | controller | changed: 2025-10-08 15:01:23.192407 | controller | { 2025-10-08 15:01:23.192470 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-08 15:01:23.192513 | controller | } 2025-10-08 15:01:23.578922 | controller | changed: 2025-10-08 15:01:23.579022 | controller | { 2025-10-08 15:01:23.579046 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-08 15:01:23.579065 | controller | } 2025-10-08 15:01:23.978934 | controller | changed: 2025-10-08 15:01:23.979035 | controller | { 2025-10-08 15:01:23.979059 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-08 15:01:23.979078 | controller | } 2025-10-08 15:01:24.429148 | controller | changed: 2025-10-08 15:01:24.429301 | controller | { 2025-10-08 15:01:24.429354 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-08 15:01:24.429395 | controller | } 2025-10-08 15:01:24.856132 | controller | changed: 2025-10-08 15:01:24.856253 | controller | { 2025-10-08 15:01:24.856290 | 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:01:24.856319 | controller | } 2025-10-08 15:01:25.208413 | controller | changed: 2025-10-08 15:01:25.208740 | controller | { 2025-10-08 15:01:25.208815 | 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:01:25.208863 | controller | } 2025-10-08 15:01:25.607162 | controller | changed: 2025-10-08 15:01:25.607329 | controller | { 2025-10-08 15:01:25.607382 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-08 15:01:25.607422 | controller | } 2025-10-08 15:01:25.996875 | controller | changed: 2025-10-08 15:01:25.997042 | controller | { 2025-10-08 15:01:25.997095 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-08 15:01:25.997137 | controller | } 2025-10-08 15:01:26.406658 | controller | changed: 2025-10-08 15:01:26.406833 | controller | { 2025-10-08 15:01:26.406876 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-08 15:01:26.406912 | controller | } 2025-10-08 15:01:26.791852 | controller | changed: 2025-10-08 15:01:26.792027 | controller | { 2025-10-08 15:01:26.792078 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-08 15:01:26.792117 | controller | } 2025-10-08 15:01:27.220126 | controller | changed: 2025-10-08 15:01:27.220291 | controller | { 2025-10-08 15:01:27.220341 | 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:01:27.220382 | controller | } 2025-10-08 15:01:27.650240 | controller | changed: 2025-10-08 15:01:27.651271 | controller | { 2025-10-08 15:01:27.651344 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-08 15:01:27.651389 | controller | } 2025-10-08 15:01:28.057440 | controller | changed: 2025-10-08 15:01:28.057755 | controller | { 2025-10-08 15:01:28.057822 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-08 15:01:28.057865 | controller | } 2025-10-08 15:01:28.514205 | controller | changed: 2025-10-08 15:01:28.514551 | controller | { 2025-10-08 15:01:28.514621 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-08 15:01:28.514711 | controller | } 2025-10-08 15:01:28.557008 | 2025-10-08 15:01:28.557164 | TASK [Set timezone to UTC] 2025-10-08 15:01:29.304417 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-08 15:01:29.338508 | 2025-10-08 15:01:29.338740 | TASK [Create nodepool directory] 2025-10-08 15:01:29.809543 | controller | changed 2025-10-08 15:01:29.822151 | 2025-10-08 15:01:29.822282 | TASK [Create nodepool sub_nodes file] 2025-10-08 15:01:31.255626 | controller | changed 2025-10-08 15:01:31.263249 | 2025-10-08 15:01:31.263338 | TASK [Create nodepool sub_nodes_private file] 2025-10-08 15:01:32.671265 | controller | changed 2025-10-08 15:01:32.678345 | 2025-10-08 15:01:32.678435 | LOOP [Populate nodepool sub_nodes file] 2025-10-08 15:01:32.723126 | 2025-10-08 15:01:32.723352 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-08 15:01:32.769006 | 2025-10-08 15:01:32.769215 | TASK [Create nodepool primary file] 2025-10-08 15:01:32.794368 | controller | skipping: Conditional result was False 2025-10-08 15:01:32.804627 | 2025-10-08 15:01:32.804783 | TASK [Create nodepool node_private for this node] 2025-10-08 15:01:34.234992 | controller | changed 2025-10-08 15:01:34.250323 | 2025-10-08 15:01:34.250439 | LOOP [Copy ssh keys to nodepool directory] 2025-10-08 15:01:34.726402 | controller | ok: Item: id_rsa Runtime: 0:00:00.010896 2025-10-08 15:01:34.726765 | 2025-10-08 15:01:35.070953 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.006109 2025-10-08 15:01:35.091139 | 2025-10-08 15:01:35.091284 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-08 15:01:36.659205 | controller | changed 2025-10-08 15:01:36.664814 | 2025-10-08 15:01:36.664877 | TASK [Validate sudoers config after edits] 2025-10-08 15:01:37.108874 | controller | /etc/sudoers: parsed OK 2025-10-08 15:01:37.108970 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-08 15:01:37.108988 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-08 15:01:37.109091 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-08 15:01:37.197896 | controller | ok: Runtime: 0:00:00.009552 2025-10-08 15:01:37.210616 | 2025-10-08 15:01:37.210812 | TASK [Show the environment passed in to job shell scripts] 2025-10-08 15:01:37.653523 | controller | SHELL=/bin/bash 2025-10-08 15:01:37.653683 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-08 15:01:37.653698 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-08 15:01:37.653706 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/68/3368/aa3d6015903436a1e30822664d9bf9e0f671d4a4 2025-10-08 15:01:37.653713 | controller | PWD=/home/zuul 2025-10-08 15:01:37.653719 | controller | ZUUL_PIPELINE=github-check 2025-10-08 15:01:37.653726 | controller | LOGNAME=zuul 2025-10-08 15:01:37.653732 | controller | XDG_SESSION_TYPE=tty 2025-10-08 15:01:37.653738 | controller | _=/usr/bin/env 2025-10-08 15:01:37.653744 | controller | MOTD_SHOWN=pam 2025-10-08 15:01:37.653750 | controller | HOME=/home/zuul 2025-10-08 15:01:37.653756 | controller | LANG=en_US.UTF-8 2025-10-08 15:01:37.653762 | controller | SSH_CONNECTION=38.102.83.114 55782 38.129.56.68 22 2025-10-08 15:01:37.653769 | 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:01:37.653779 | controller | ZUUL_CHANGE_IDS=3368,aa3d6015903436a1e30822664d9bf9e0f671d4a4 2025-10-08 15:01:37.653785 | controller | WORKSPACE=/home/zuul/workspace 2025-10-08 15:01:37.653791 | controller | XDG_SESSION_CLASS=user 2025-10-08 15:01:37.653799 | controller | SELINUX_ROLE_REQUESTED= 2025-10-08 15:01:37.653805 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-08 15:01:37.653813 | controller | USER=zuul 2025-10-08 15:01:37.653821 | controller | ZUUL_VOTING=True 2025-10-08 15:01:37.653829 | controller | BUILD_TIMEOUT=1800000 2025-10-08 15:01:37.653837 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-08 15:01:37.653845 | controller | SHLVL=1 2025-10-08 15:01:37.653853 | controller | ZUUL_PATCHSET=aa3d6015903436a1e30822664d9bf9e0f671d4a4 2025-10-08 15:01:37.653861 | controller | XDG_SESSION_ID=1 2025-10-08 15:01:37.653869 | controller | ZUUL_BRANCH=main 2025-10-08 15:01:37.653876 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-08 15:01:37.653884 | controller | SSH_CLIENT=38.102.83.114 55782 22 2025-10-08 15:01:37.653893 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-08 15:01:37.653900 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-08 15:01:37.653908 | controller | which_declare=declare -f 2025-10-08 15:01:37.653917 | 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:01:37.653925 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-08 15:01:37.653933 | controller | ZUUL_CHANGE=3368 2025-10-08 15:01:37.653940 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-08 15:01:37.653948 | controller | ZUUL_UUID=52c5c4b0566d45b989e1639655094bde 2025-10-08 15:01:37.653955 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-08 15:01:37.653966 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-08 15:01:37.653975 | controller | } 2025-10-08 15:01:37.762284 | controller | ok: Runtime: 0:00:00.008028 2025-10-08 15:01:37.811460 | 2025-10-08 15:01:37.811578 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-08 15:01:37.850005 | controller | skipping: Conditional result was False 2025-10-08 15:01:37.856157 | 2025-10-08 15:01:37.856222 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-08 15:01:38.392764 | controller | skipping: Conditional result was False 2025-10-08 15:01:38.408011 | 2025-10-08 15:01:38.408156 | TASK [Ensure legacy workspace directory] 2025-10-08 15:01:38.782023 | controller | changed 2025-10-08 15:01:38.832322 | 2025-10-08 15:01:38.832529 | PLAY RECAP 2025-10-08 15:01:38.832846 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-08 15:01:38.832900 | 2025-10-08 15:01:38.969197 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-08 15:01:38.971151 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master] 2025-10-08 15:01:39.519161 | 2025-10-08 15:01:39.519265 | PLAY [all,!compute] 2025-10-08 15:01:39.539641 | 2025-10-08 15:01:39.539753 | TASK [Enable container-tools rhel-modules for c8/crc node] 2025-10-08 15:01:40.079563 | controller | skipping: Conditional result was False 2025-10-08 15:01:40.087783 | 2025-10-08 15:01:40.087904 | TASK [Install podman] 2025-10-08 15:01:40.133790 | controller | skipping: Conditional result was False 2025-10-08 15:01:40.142521 | 2025-10-08 15:01:40.142646 | TASK [Set var name for quay login zuul secret] 2025-10-08 15:01:40.189567 | controller | skipping: Conditional result was False 2025-10-08 15:01:40.205117 | 2025-10-08 15:01:40.205795 | TASK [Print the username] 2025-10-08 15:01:40.770333 | controller | skipping: Conditional result was False 2025-10-08 15:01:40.780334 | 2025-10-08 15:01:40.780442 | TASK [Fail when user or password is not set] 2025-10-08 15:01:40.827929 | controller | skipping: Conditional result was False 2025-10-08 15:01:40.833487 | 2025-10-08 15:01:40.833554 | TASK [Set vars for quay login] 2025-10-08 15:01:40.875466 | 2025-10-08 15:01:40.875616 | LOOP [Perform container registry login(s) with podman] 2025-10-08 15:01:40.929954 | 2025-10-08 15:01:40.930159 | LOOP [Perform container registry login(s) with buildah] 2025-10-08 15:01:40.999226 | 2025-10-08 15:01:40.999831 | TASK [Set Insecure registry for content provider] 2025-10-08 15:01:41.046059 | controller | skipping: Conditional result was False 2025-10-08 15:01:41.079159 | 2025-10-08 15:01:41.079278 | PLAY RECAP 2025-10-08 15:01:41.079338 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 9 rescued: 0 ignored: 0 2025-10-08 15:01:41.079368 | 2025-10-08 15:01:41.196839 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master] 2025-10-08 15:01:41.197630 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master] 2025-10-08 15:01:41.810253 | 2025-10-08 15:01:41.810372 | PLAY [Prefer ipv4 over ipv6] 2025-10-08 15:01:41.830971 | 2025-10-08 15:01:41.831080 | TASK [Configure /etc/gai.conf to prever ipv4 over ipv6] 2025-10-08 15:01:41.896241 | controller | skipping: Conditional result was False 2025-10-08 15:01:41.917158 | 2025-10-08 15:01:41.917243 | PLAY [controller] 2025-10-08 15:01:41.931806 | 2025-10-08 15:01:41.931898 | TASK [Fail when openshift_pull_sec not provided] 2025-10-08 15:01:41.966350 | controller | skipping: Conditional result was False 2025-10-08 15:01:41.972857 | 2025-10-08 15:01:41.972923 | TASK [Clone repos in the job workspace] 2025-10-08 15:01:41.991691 | controller | ok 2025-10-08 15:01:42.007504 | 2025-10-08 15:01:42.007578 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-08 15:01:42.478610 | controller | ok 2025-10-08 15:01:42.485317 | 2025-10-08 15:01:42.485382 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-08 15:01:46.842946 | controller | Output suppressed because no_log was given 2025-10-08 15:01:46.856102 | 2025-10-08 15:01:46.856194 | TASK [Create pull-secret.txt file] 2025-10-08 15:01:48.525308 | controller | Output suppressed because no_log was given 2025-10-08 15:01:48.587633 | 2025-10-08 15:01:48.587808 | TASK [Apply CRC features and print image build date] 2025-10-08 15:01:48.607773 | controller | ok 2025-10-08 15:01:48.614648 | controller | included: /var/lib/zuul/builds/52c5c4b0566d45b989e1639655094bde/trusted/project_0/review.rdoproject.org/config/playbooks/crc/features.yaml 2025-10-08 15:01:48.625218 | 2025-10-08 15:01:48.625328 | TASK [Set additional parameters for crc - telemetry] 2025-10-08 15:01:49.166474 | controller | skipping: Conditional result was False 2025-10-08 15:01:49.175763 | 2025-10-08 15:01:49.175865 | TASK [Set additional parameters for crc - monitoring] 2025-10-08 15:01:49.718705 | controller | skipping: Conditional result was False 2025-10-08 15:01:49.724767 | 2025-10-08 15:01:49.724833 | TASK [Set minimum required memory to run the CRC when monitoring enabled] 2025-10-08 15:01:50.258253 | controller | skipping: Conditional result was False 2025-10-08 15:01:50.278490 | 2025-10-08 15:01:50.278828 | TASK [Set additional parameters for crc - enable microshift preset] 2025-10-08 15:01:50.849708 | controller | skipping: Conditional result was False 2025-10-08 15:01:50.855882 | 2025-10-08 15:01:50.855953 | TASK [Set additional parameters for crc - enable okd preset] 2025-10-08 15:01:51.386990 | controller | skipping: Conditional result was False 2025-10-08 15:01:51.392328 | 2025-10-08 15:01:51.392392 | TASK [Delete the cluster if there's a non-default bundle URL] 2025-10-08 15:01:51.950118 | controller | skipping: Conditional result was False 2025-10-08 15:01:51.958791 | 2025-10-08 15:01:51.958882 | TASK [Execute crc start command] 2025-10-08 15:05:26.807022 | controller | ok: Runtime: 0:03:33.890756 2025-10-08 15:05:26.821819 | 2025-10-08 15:05:26.821960 | TASK [Verify that OpenShift is up and running] 2025-10-08 15:05:26.851531 | controller | ok 2025-10-08 15:05:26.872244 | controller | included: /var/lib/zuul/builds/52c5c4b0566d45b989e1639655094bde/trusted/project_0/review.rdoproject.org/config/playbooks/crc/verify_openshift_start.yaml 2025-10-08 15:05:26.912435 | 2025-10-08 15:05:26.912551 | TASK [Create script to login and verify services] 2025-10-08 15:05:28.628333 | controller | changed 2025-10-08 15:05:28.636755 | 2025-10-08 15:05:28.636839 | TASK [Ensure that user is logged] 2025-10-08 15:05:29.062572 | controller | Checking login to the cluster 1... 2025-10-08 15:05:32.141877 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:05:32.280355 | controller | 2025-10-08 15:05:32.280429 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:05:32.285378 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:05:37.287983 | controller | Checking login to the cluster 2... 2025-10-08 15:05:37.466234 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:05:37.494870 | controller | 2025-10-08 15:05:37.494970 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:05:37.500199 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:05:42.502972 | controller | Checking login to the cluster 3... 2025-10-08 15:05:42.625473 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:05:42.645456 | controller | 2025-10-08 15:05:42.645541 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:05:42.651513 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:05:47.654826 | controller | Checking login to the cluster 4... 2025-10-08 15:05:47.788014 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:05:47.809108 | controller | 2025-10-08 15:05:47.809150 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:05:47.820861 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:05:52.826377 | controller | Checking login to the cluster 5... 2025-10-08 15:05:52.996124 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:05:53.027387 | controller | 2025-10-08 15:05:53.027467 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:05:53.035142 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:05:58.037743 | controller | Checking login to the cluster 6... 2025-10-08 15:05:58.159283 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:05:58.190540 | controller | 2025-10-08 15:05:58.190631 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:05:58.197757 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:06:03.202263 | controller | Checking login to the cluster 7... 2025-10-08 15:06:03.459148 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:06:03.583759 | controller | 2025-10-08 15:06:03.583843 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:06:03.590370 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:06:08.601890 | controller | Checking login to the cluster 8... 2025-10-08 15:06:08.798411 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:06:08.853524 | controller | 2025-10-08 15:06:08.853657 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:06:08.869995 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:06:13.873905 | controller | Checking login to the cluster 9... 2025-10-08 15:06:14.072838 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:06:14.106255 | controller | 2025-10-08 15:06:14.106328 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:06:14.110772 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:06:19.114419 | controller | Checking login to the cluster 10... 2025-10-08 15:06:19.269305 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:06:19.335856 | controller | 2025-10-08 15:06:19.335946 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:06:19.346802 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:06:24.361698 | controller | Checking login to the cluster 11... 2025-10-08 15:06:26.215787 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:06:28.394950 | controller | 2025-10-08 15:06:28.395035 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:06:28.405257 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:06:33.412108 | controller | Checking login to the cluster 12... 2025-10-08 15:06:33.598616 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:06:33.960023 | controller | 2025-10-08 15:06:33.960239 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:06:33.973644 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:06:38.977058 | controller | Checking login to the cluster 13... 2025-10-08 15:06:39.242904 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:06:39.807175 | controller | 2025-10-08 15:06:39.807288 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-08 15:06:39.814955 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:06:44.822361 | controller | Checking login to the cluster 14... 2025-10-08 15:06:45.427896 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:06:47.066340 | controller | 2025-10-08 15:06:47.066492 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-08 15:06:47.075152 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:06:52.078545 | controller | Checking login to the cluster 15... 2025-10-08 15:06:52.275660 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:06:52.531098 | controller | 2025-10-08 15:06:52.531212 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-08 15:06:52.542567 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:06:57.547670 | controller | Checking login to the cluster 16... 2025-10-08 15:06:57.715388 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:06:58.089306 | controller | 2025-10-08 15:06:58.089381 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-08 15:06:58.097426 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:07:03.105419 | controller | Checking login to the cluster 17... 2025-10-08 15:07:03.338681 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:07:03.674979 | controller | 2025-10-08 15:07:03.675109 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-08 15:07:03.686494 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:07:08.689965 | controller | Checking login to the cluster 18... 2025-10-08 15:07:08.928623 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:07:09.165404 | controller | 2025-10-08 15:07:09.165536 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-08 15:07:09.176190 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:07:14.189278 | controller | Checking login to the cluster 19... 2025-10-08 15:07:14.425465 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:07:14.722583 | controller | 2025-10-08 15:07:14.722732 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-08 15:07:14.735056 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:07:19.742403 | controller | Checking login to the cluster 20... 2025-10-08 15:07:20.615151 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:07:25.133222 | controller | 2025-10-08 15:07:25.133320 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:07:25.139983 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:07:30.144123 | controller | Checking login to the cluster 21... 2025-10-08 15:07:30.335635 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:07:30.423936 | controller | 2025-10-08 15:07:30.424024 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:07:30.429778 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:07:35.433036 | controller | Checking login to the cluster 22... 2025-10-08 15:07:35.726688 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:07:36.329226 | controller | 2025-10-08 15:07:36.329340 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:07:36.334771 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:07:41.338074 | controller | Checking login to the cluster 23... 2025-10-08 15:07:41.499828 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:07:41.558162 | controller | 2025-10-08 15:07:41.558265 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:07:41.563933 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:07:46.568157 | controller | Checking login to the cluster 24... 2025-10-08 15:07:46.943459 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:07:47.055009 | controller | 2025-10-08 15:07:47.055255 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:07:47.065635 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:07:52.070401 | controller | Checking login to the cluster 25... 2025-10-08 15:07:52.245322 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:07:52.282721 | controller | 2025-10-08 15:07:52.282825 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:07:52.293626 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:07:57.296576 | controller | Checking login to the cluster 26... 2025-10-08 15:07:57.466742 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:07:57.506870 | controller | 2025-10-08 15:07:57.506961 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:07:57.513191 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:08:02.516065 | controller | Checking login to the cluster 27... 2025-10-08 15:08:02.726899 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:08:02.753340 | controller | 2025-10-08 15:08:02.753451 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:08:02.759227 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:08:07.766150 | controller | Checking login to the cluster 28... 2025-10-08 15:08:07.928623 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:08:07.949091 | controller | 2025-10-08 15:08:07.949176 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:08:07.955932 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:08:12.960747 | controller | Checking login to the cluster 29... 2025-10-08 15:08:13.103384 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:08:13.127126 | controller | 2025-10-08 15:08:13.127288 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:08:13.134648 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:08:18.137580 | controller | Checking login to the cluster 30... 2025-10-08 15:08:18.322096 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:08:18.366581 | controller | 2025-10-08 15:08:18.366674 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-08 15:08:18.372163 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:08:23.376142 | controller | Checking login to the cluster 31... 2025-10-08 15:08:23.493289 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:08:23.524132 | controller | 2025-10-08 15:08:23.524321 | controller | Unable to connect to the server: EOF 2025-10-08 15:08:23.533730 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:08:28.536860 | controller | Checking login to the cluster 32... 2025-10-08 15:08:28.709348 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:08:28.733421 | controller | 2025-10-08 15:08:28.733477 | controller | Unable to connect to the server: EOF 2025-10-08 15:08:28.739426 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:08:33.744252 | controller | Checking login to the cluster 33... 2025-10-08 15:08:33.865636 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:08:33.885854 | controller | 2025-10-08 15:08:33.885951 | controller | Unable to connect to the server: EOF 2025-10-08 15:08:33.892642 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:08:38.896018 | controller | Checking login to the cluster 34... 2025-10-08 15:08:39.010562 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:08:39.038105 | controller | 2025-10-08 15:08:39.038207 | controller | Unable to connect to the server: EOF 2025-10-08 15:08:39.044932 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:08:44.048234 | controller | Checking login to the cluster 35... 2025-10-08 15:08:44.222894 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:08:44.249954 | controller | 2025-10-08 15:08:44.250081 | controller | Unable to connect to the server: EOF 2025-10-08 15:08:44.255719 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:08:49.260353 | controller | Checking login to the cluster 36... 2025-10-08 15:08:49.412692 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:08:49.439339 | controller | 2025-10-08 15:08:49.439455 | controller | Unable to connect to the server: EOF 2025-10-08 15:08:49.445458 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:08:54.447738 | controller | Checking login to the cluster 37... 2025-10-08 15:08:54.617612 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:08:54.651910 | controller | 2025-10-08 15:08:54.652059 | controller | Unable to connect to the server: EOF 2025-10-08 15:08:54.658206 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:08:59.660643 | controller | Checking login to the cluster 38... 2025-10-08 15:08:59.811873 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:08:59.833603 | controller | 2025-10-08 15:08:59.833700 | controller | Unable to connect to the server: EOF 2025-10-08 15:08:59.838243 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:09:04.841773 | controller | Checking login to the cluster 39... 2025-10-08 15:09:04.950378 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:09:04.972121 | controller | 2025-10-08 15:09:04.972219 | controller | Unable to connect to the server: EOF 2025-10-08 15:09:04.981927 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:09:09.984441 | controller | Checking login to the cluster 40... 2025-10-08 15:09:10.135144 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:09:10.148886 | controller | 2025-10-08 15:09:10.148967 | controller | Unable to connect to the server: EOF 2025-10-08 15:09:10.154980 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:09:15.157938 | controller | Checking login to the cluster 41... 2025-10-08 15:09:15.284288 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:09:15.305746 | controller | 2025-10-08 15:09:15.305829 | controller | Unable to connect to the server: EOF 2025-10-08 15:09:15.312139 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:09:20.314468 | controller | Checking login to the cluster 42... 2025-10-08 15:09:20.478980 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:09:20.496589 | controller | 2025-10-08 15:09:20.496672 | controller | Unable to connect to the server: EOF 2025-10-08 15:09:20.505563 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:09:25.508509 | controller | Checking login to the cluster 43... 2025-10-08 15:09:25.644230 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:09:25.660646 | controller | 2025-10-08 15:09:25.660721 | controller | Unable to connect to the server: EOF 2025-10-08 15:09:25.667892 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:09:30.669785 | controller | Checking login to the cluster 44... 2025-10-08 15:09:30.796331 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:09:30.816380 | controller | 2025-10-08 15:09:30.816459 | controller | Unable to connect to the server: EOF 2025-10-08 15:09:30.823916 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-08 15:09:35.826064 | controller | Checking login to the cluster 45... 2025-10-08 15:09:35.961369 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-08 15:09:47.954844 | controller | 2025-10-08 15:09:47.954962 | controller | Login successful. 2025-10-08 15:09:48.035274 | controller | 2025-10-08 15:09:48.035360 | controller | You have access to 65 projects, the list has been suppressed. You can list all projects with 'oc projects' 2025-10-08 15:09:48.064673 | controller | 2025-10-08 15:09:48.064740 | controller | Using project "default". 2025-10-08 15:09:48.568322 | controller | changed 2025-10-08 15:09:48.583629 | 2025-10-08 15:09:48.583814 | TASK [Delete all openshift-marketplace pods] 2025-10-08 15:09:53.952098 | controller | pod "certified-operators-5gzlr" deleted 2025-10-08 15:09:58.007501 | controller | pod "certified-operators-f8szs" deleted 2025-10-08 15:10:01.651115 | controller | pod "community-operators-5zsqg" deleted 2025-10-08 15:10:03.901924 | controller | pod "community-operators-q9v69" deleted 2025-10-08 15:10:06.417357 | controller | pod "marketplace-operator-79b997595-d2lpl" deleted 2025-10-08 15:10:10.441197 | controller | pod "redhat-marketplace-ps6xk" deleted 2025-10-08 15:10:11.354169 | controller | pod "redhat-marketplace-t4g5q" deleted 2025-10-08 15:10:13.536531 | controller | pod "redhat-operators-fmtsb" deleted 2025-10-08 15:10:13.871978 | controller | pod "redhat-operators-tqxl9" deleted 2025-10-08 15:10:16.174514 | controller | ok 2025-10-08 15:10:16.186742 | 2025-10-08 15:10:16.186882 | TASK [Copy script ensure_services_up.sh] 2025-10-08 15:10:16.254525 | controller | skipping: Conditional result was False 2025-10-08 15:10:16.267002 | 2025-10-08 15:10:16.267138 | TASK [Ensure all pods are up and running] 2025-10-08 15:10:16.817111 | controller | skipping: Conditional result was False 2025-10-08 15:10:16.829579 | 2025-10-08 15:10:16.829739 | TASK [Check if there is a image build date file] 2025-10-08 15:10:17.307458 | controller | ok 2025-10-08 15:10:17.319912 | 2025-10-08 15:10:17.320046 | TASK [Print image date if available] 2025-10-08 15:10:17.720400 | controller | Tue Apr 1 06:17:46 PM EDT 2025 2025-10-08 15:10:17.894095 | controller | changed 2025-10-08 15:10:17.899718 | 2025-10-08 15:10:17.899783 | TASK [Fix machineconfigpool] 2025-10-08 15:10:17.944254 | controller | skipping: Conditional result was False 2025-10-08 15:10:17.953990 | 2025-10-08 15:10:17.954127 | TASK [Show available nodes] 2025-10-08 15:10:19.743914 | controller | NAME STATUS ROLES AGE VERSION 2025-10-08 15:10:19.748154 | controller | crc Ready control-plane,master,worker 227d v1.31.5 2025-10-08 15:10:20.027773 | controller | ok: Runtime: 0:00:01.460853 2025-10-08 15:10:20.041179 | 2025-10-08 15:10:20.041399 | TASK [Configure insecure registry inside crc vm] 2025-10-08 15:10:20.080227 | controller | skipping: Conditional result was False 2025-10-08 15:10:20.093151 | 2025-10-08 15:10:20.093285 | TASK [Catch CRC IP] 2025-10-08 15:10:20.777019 | controller | 192.168.130.11 2025-10-08 15:10:21.157398 | controller | changed 2025-10-08 15:10:21.171458 | 2025-10-08 15:10:21.171604 | TASK [Check if id_ed25519 key exists] 2025-10-08 15:10:21.640315 | controller | ok 2025-10-08 15:10:21.654710 | 2025-10-08 15:10:21.654844 | TASK [Set fact if new keypair exists] 2025-10-08 15:10:21.705831 | controller | ok 2025-10-08 15:10:21.718360 | 2025-10-08 15:10:21.718557 | TASK [Run crc] 2025-10-08 15:10:24.461558 | controller | 2025-10-08 15:10:37.847297 | controller | PLAY [OpenShift secret copy and CRC securitization] **************************** 2025-10-08 15:10:37.847390 | controller | 2025-10-08 15:10:37.847399 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-08 15:10:37.847417 | controller | [WARNING]: Platform linux on host 192.168.130.11 is using the discovered Python 2025-10-08 15:10:37.893514 | controller | interpreter at /usr/bin/python3.9, but future installation of another Python 2025-10-08 15:10:37.893591 | controller | interpreter could change the meaning of that path. See 2025-10-08 15:10:37.893599 | controller | https://docs.ansible.com/ansible- 2025-10-08 15:10:37.893605 | controller | core/2.14/reference_appendices/interpreter_discovery.html for more information. 2025-10-08 15:10:37.893628 | controller | ok: [192.168.130.11] 2025-10-08 15:10:39.836501 | controller | 2025-10-08 15:10:39.836582 | controller | TASK [Copy pull-secret] ******************************************************** 2025-10-08 15:10:39.836618 | controller | changed: [192.168.130.11] 2025-10-08 15:10:41.490748 | controller | 2025-10-08 15:10:41.490842 | controller | TASK [Add crio config] ********************************************************* 2025-10-08 15:10:41.490871 | controller | changed: [192.168.130.11] 2025-10-08 15:10:47.819409 | controller | 2025-10-08 15:10:47.819494 | controller | TASK [Restart crio to pickup new configuration] ******************************** 2025-10-08 15:10:47.819527 | controller | changed: [192.168.130.11] 2025-10-08 15:10:47.896261 | controller | 2025-10-08 15:10:47.896330 | controller | PLAY RECAP ********************************************************************* 2025-10-08 15:10:47.896339 | controller | 192.168.130.11 : ok=4 changed=3 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2025-10-08 15:10:47.896345 | controller | 2025-10-08 15:10:48.309698 | controller | changed 2025-10-08 15:10:48.315711 | 2025-10-08 15:10:48.315778 | TASK [Set slower etcd profile] 2025-10-08 15:10:48.397284 | controller | ok 2025-10-08 15:10:48.427210 | 2025-10-08 15:10:48.427352 | TASK [prepare-crc-cloud : Set slower profile] 2025-10-08 15:10:49.054771 | controller | etcd.operator.openshift.io/cluster patched 2025-10-08 15:10:49.463484 | controller | ok 2025-10-08 15:10:49.470607 | 2025-10-08 15:10:49.470705 | TASK [prepare-crc-cloud : Wait 30 seconds to apply] 2025-10-08 15:11:19.529141 | controller | ok 2025-10-08 15:11:19.539741 | 2025-10-08 15:11:19.539940 | TASK [prepare-crc-cloud : Get pod phase status] 2025-10-08 15:16:55.232560 | controller | ok 2025-10-08 15:16:55.249061 | 2025-10-08 15:16:55.249145 | TASK [Set limit for disk usage - IOPS and Read/Write] 2025-10-08 15:16:55.316830 | controller | ok 2025-10-08 15:16:55.328015 | controller | included: /var/lib/zuul/builds/52c5c4b0566d45b989e1639655094bde/trusted/project_0/review.rdoproject.org/config/playbooks/crc/limit-disk-usage.yaml 2025-10-08 15:16:55.342389 | 2025-10-08 15:16:55.342489 | TASK [Set facts for disk limitation] 2025-10-08 15:16:55.386431 | controller | ok 2025-10-08 15:16:55.397019 | 2025-10-08 15:16:55.397097 | TASK [Print limits for the disk] 2025-10-08 15:16:55.447975 | controller | ok: Set limit to crc VM to the vda disk: iops 18000 read/write 262144000 2025-10-08 15:16:55.459537 | 2025-10-08 15:16:55.459764 | TASK [Set limit to crc VM to the vda disk] 2025-10-08 15:16:56.021161 | controller | 2025-10-08 15:16:56.065074 | controller | changed 2025-10-08 15:16:56.072219 | 2025-10-08 15:16:56.072279 | TASK [Pause machineconfigpool] 2025-10-08 15:16:56.145457 | controller | ok 2025-10-08 15:16:56.172006 | 2025-10-08 15:16:56.172240 | LOOP [prepare-crc-cloud : Pause the machineconfig configuration if mcp is not needed] 2025-10-08 15:16:57.299122 | controller | ok 2025-10-08 15:16:58.155191 | controller | ok 2025-10-08 15:16:58.174636 | 2025-10-08 15:16:58.174824 | TASK [prepare-crc-cloud : Print current machineconfigpool] 2025-10-08 15:16:58.800169 | controller | NAME CONFIG UPDATED UPDATING DEGRADED MACHINECOUNT READYMACHINECOUNT UPDATEDMACHINECOUNT DEGRADEDMACHINECOUNT AGE 2025-10-08 15:16:58.809982 | controller | master rendered-master-95020e6e79c40b4dd095817bccfc5060 True False False 1 1 1 0 227d 2025-10-08 15:16:58.810158 | controller | worker rendered-worker-594cc07c9feefb5e37f2530ac457d36c True False False 0 0 0 0 227d 2025-10-08 15:16:59.225951 | controller | ok 2025-10-08 15:16:59.292815 | 2025-10-08 15:16:59.292952 | PLAY [Set IOPS and Read/Write disk limitation excluding controller] 2025-10-08 15:16:59.293055 | 2025-10-08 15:16:59.293082 | PLAY RECAP 2025-10-08 15:16:59.293119 | controller | ok: 25 changed: 10 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0 2025-10-08 15:16:59.293138 | 2025-10-08 15:16:59.421641 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master] 2025-10-08 15:16:59.423332 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-08 15:17:00.031226 | 2025-10-08 15:17:00.031370 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-08 15:17:00.070709 | 2025-10-08 15:17:00.070876 | TASK [Create zuul-output directory] 2025-10-08 15:17:00.690870 | controller | changed 2025-10-08 15:17:00.704753 | 2025-10-08 15:17:00.705061 | TASK [Slurp Zuul inventory test] 2025-10-08 15:17:01.017599 | controller -> localhost | ok 2025-10-08 15:17:01.024322 | 2025-10-08 15:17:01.024397 | TASK [Save zuul inventory] 2025-10-08 15:17:02.899228 | controller | changed 2025-10-08 15:17:02.905255 | 2025-10-08 15:17:02.905473 | TASK [Save zuul vars without the change_message] 2025-10-08 15:17:04.270430 | controller | changed 2025-10-08 15:17:04.290926 | 2025-10-08 15:17:04.290981 | PLAY RECAP 2025-10-08 15:17:04.291024 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-08 15:17:04.291055 | 2025-10-08 15:17:04.391946 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-08 15:17:04.392892 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-08 15:17:05.114414 | 2025-10-08 15:17:05.114570 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-10-08 15:17:05.163720 | 2025-10-08 15:17:05.163883 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-10-08 15:17:05.215729 | controller | ok 2025-10-08 15:17:05.239826 | 2025-10-08 15:17:05.240087 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-08 15:17:05.275979 | controller | skipping: Conditional result was False 2025-10-08 15:17:05.284095 | 2025-10-08 15:17:05.284189 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-08 15:17:05.826738 | controller | ok 2025-10-08 15:17:05.834582 | 2025-10-08 15:17:05.834655 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-08 15:17:07.106507 | controller | ok 2025-10-08 15:17:07.125014 | 2025-10-08 15:17:07.125189 | TASK [Prepare workspace] 2025-10-08 15:17:07.147435 | controller | ok 2025-10-08 15:17:07.166358 | 2025-10-08 15:17:07.166497 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-08 15:17:07.679889 | controller | ok 2025-10-08 15:17:07.686466 | 2025-10-08 15:17:07.686531 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-08 15:17:09.145341 | controller | Output suppressed because no_log was given 2025-10-08 15:17:09.225777 | 2025-10-08 15:17:09.226257 | LOOP [Create zuul-output directory] 2025-10-08 15:17:09.643571 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-10-08 15:17:09.985208 | controller | ok: "/home/zuul/zuul-output/logs" 2025-10-08 15:17:09.999467 | 2025-10-08 15:17:09.999611 | TASK [Install required packages] 2025-10-08 15:17:30.799097 | controller | ok: Nothing to do 2025-10-08 15:17:30.808599 | 2025-10-08 15:17:30.808722 | TASK [Install venv] 2025-10-08 15:19:14.535755 | controller | changed 2025-10-08 15:19:14.594457 | 2025-10-08 15:19:14.594553 | PLAY RECAP 2025-10-08 15:19:14.594612 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-08 15:19:14.594639 | 2025-10-08 15:19:14.707836 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-08 15:19:14.709565 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-08 15:19:15.425703 | 2025-10-08 15:19:15.425872 | PLAY [Run ci/playbooks/molecule-test.yml] 2025-10-08 15:19:15.461359 | 2025-10-08 15:19:15.461592 | TASK [Gather required facts] 2025-10-08 15:19:16.538016 | controller | ok 2025-10-08 15:19:16.545634 | 2025-10-08 15:19:16.545997 | TASK [Load environment var if instructed to] 2025-10-08 15:19:16.596484 | controller | skipping: Conditional result was False 2025-10-08 15:19:16.604484 | 2025-10-08 15:19:16.604594 | TASK [Run molecule] 2025-10-08 15:19:17.974406 | controller | INFO Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml 2025-10-08 15:19:18.054751 | controller | INFO Performing prerun with role_name_check=0... 2025-10-08 15:19:48.799562 | controller | WARNING Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 15:19:48.800123 | controller | WARNING Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 15:19:48.800642 | controller | WARNING Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 15:19:48.801259 | controller | WARNING Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 15:19:48.801799 | controller | WARNING Another version of 'cifmw.general' 1.0.0+9ece53e1 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.0.0+9ece53e1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 15:19:48.802294 | controller | WARNING Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 15:19:48.802823 | controller | WARNING Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 15:19:48.803528 | controller | WARNING Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 15:19:48.804019 | controller | WARNING Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 15:19:48.804505 | controller | WARNING Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 15:19:48.805022 | controller | WARNING Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 15:19:48.805532 | controller | WARNING Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 15:19:48.806026 | controller | WARNING Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 15:19:48.806521 | controller | WARNING Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 15:19:48.806982 | controller | WARNING Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 15:19:48.817642 | controller | INFO Running default > prepare 2025-10-08 15:19:49.655271 | controller | 2025-10-08 15:19:49.655436 | controller | PLAY [Prepare] ***************************************************************** 2025-10-08 15:19:49.655551 | controller | 2025-10-08 15:19:49.655683 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-08 15:19:49.655811 | controller | Wednesday 08 October 2025 15:19:49 +0000 (0:00:00.020) 0:00:00.020 ***** 2025-10-08 15:19:51.336870 | controller | ok: [instance] 2025-10-08 15:19:51.363162 | controller | 2025-10-08 15:19:51.363215 | controller | TASK [test_deps : Gather facts if they don't exist] **************************** 2025-10-08 15:19:51.363227 | controller | Wednesday 08 October 2025 15:19:51 +0000 (0:00:01.681) 0:00:01.701 ***** 2025-10-08 15:19:51.363244 | controller | skipping: [instance] 2025-10-08 15:19:51.363324 | controller | 2025-10-08 15:19:51.363347 | controller | TASK [test_deps : Gather variables for each operating system] ****************** 2025-10-08 15:19:51.363489 | controller | Wednesday 08 October 2025 15:19:51 +0000 (0:00:00.026) 0:00:01.728 ***** 2025-10-08 15:19:51.423397 | 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:19:51.423461 | controller | 2025-10-08 15:19:51.423698 | controller | TASK [test_deps : Detect mirrors file] ***************************************** 2025-10-08 15:19:51.423838 | controller | Wednesday 08 October 2025 15:19:51 +0000 (0:00:00.060) 0:00:01.788 ***** 2025-10-08 15:19:51.990999 | controller | ok: [instance] 2025-10-08 15:19:51.991886 | controller | 2025-10-08 15:19:51.991951 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] *********** 2025-10-08 15:19:51.991971 | controller | Wednesday 08 October 2025 15:19:51 +0000 (0:00:00.567) 0:00:02.356 ***** 2025-10-08 15:19:52.579887 | controller | ok: [instance] 2025-10-08 15:19:52.579962 | controller | 2025-10-08 15:19:52.580137 | controller | TASK [test_deps : Disable ubi 9 repos] ***************************************** 2025-10-08 15:19:52.580287 | controller | Wednesday 08 October 2025 15:19:52 +0000 (0:00:00.588) 0:00:02.945 ***** 2025-10-08 15:19:53.620680 | controller | ok: [instance] => (item=ubi-9-appstream) 2025-10-08 15:19:53.620886 | controller | ok: [instance] => (item=ubi-9-baseos) 2025-10-08 15:19:53.621054 | controller | ok: [instance] => (item=ubi-9-codeready-builder) 2025-10-08 15:19:53.621534 | controller | 2025-10-08 15:19:53.689846 | controller | TASK [Deploy repo-setup] ******************************************************* 2025-10-08 15:19:53.689917 | controller | Wednesday 08 October 2025 15:19:53 +0000 (0:00:01.041) 0:00:03.986 ***** 2025-10-08 15:19:53.689938 | controller | 2025-10-08 15:19:54.682978 | controller | TASK [repo_setup : Ensure directories are present] ***************************** 2025-10-08 15:19:54.683012 | controller | Wednesday 08 October 2025 15:19:53 +0000 (0:00:00.068) 0:00:04.054 ***** 2025-10-08 15:19:54.683024 | controller | changed: [instance] => (item=tmp) 2025-10-08 15:19:55.749924 | controller | changed: [instance] => (item=artifacts/repositories) 2025-10-08 15:19:55.749961 | controller | changed: [instance] => (item=venv/repo_setup) 2025-10-08 15:19:55.749969 | controller | 2025-10-08 15:19:55.749976 | controller | TASK [repo_setup : Make sure git-core package is installed] ******************** 2025-10-08 15:19:55.749982 | controller | Wednesday 08 October 2025 15:19:54 +0000 (0:00:00.993) 0:00:05.048 ***** 2025-10-08 15:19:55.749993 | controller | ok: [instance] 2025-10-08 15:19:55.750000 | controller | 2025-10-08 15:19:55.750008 | controller | TASK [repo_setup : Get repo-setup repository] ********************************** 2025-10-08 15:19:55.750128 | controller | Wednesday 08 October 2025 15:19:55 +0000 (0:00:01.067) 0:00:06.115 ***** 2025-10-08 15:19:57.333607 | controller | changed: [instance] 2025-10-08 15:20:07.443000 | controller | 2025-10-08 15:20:07.443109 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************ 2025-10-08 15:20:07.443126 | controller | Wednesday 08 October 2025 15:19:57 +0000 (0:00:01.583) 0:00:07.698 ***** 2025-10-08 15:20:07.443144 | controller | changed: [instance] 2025-10-08 15:20:07.443186 | controller | 2025-10-08 15:20:07.443209 | controller | TASK [repo_setup : Install repo-setup package] ********************************* 2025-10-08 15:20:07.443218 | controller | Wednesday 08 October 2025 15:20:07 +0000 (0:00:10.109) 0:00:17.808 ***** 2025-10-08 15:20:08.378270 | controller | changed: [instance] 2025-10-08 15:20:08.378329 | controller | 2025-10-08 15:20:08.378574 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] *** 2025-10-08 15:20:08.407664 | controller | Wednesday 08 October 2025 15:20:08 +0000 (0:00:00.935) 0:00:18.743 ***** 2025-10-08 15:20:08.407718 | controller | skipping: [instance] 2025-10-08 15:20:09.184300 | controller | 2025-10-08 15:20:09.184343 | controller | TASK [repo_setup : Run repo-setup] ********************************************* 2025-10-08 15:20:09.184354 | controller | Wednesday 08 October 2025 15:20:08 +0000 (0:00:00.028) 0:00:18.772 ***** 2025-10-08 15:20:09.184368 | controller | changed: [instance] 2025-10-08 15:20:09.228257 | controller | 2025-10-08 15:20:09.228292 | controller | TASK [repo_setup : Get component repo] ***************************************** 2025-10-08 15:20:09.228300 | controller | Wednesday 08 October 2025 15:20:09 +0000 (0:00:00.776) 0:00:19.549 ***** 2025-10-08 15:20:09.228311 | controller | skipping: [instance] 2025-10-08 15:20:09.270981 | controller | 2025-10-08 15:20:09.271022 | controller | TASK [repo_setup : Rename component repo] ************************************** 2025-10-08 15:20:09.271030 | controller | Wednesday 08 October 2025 15:20:09 +0000 (0:00:00.043) 0:00:19.593 ***** 2025-10-08 15:20:09.271061 | controller | skipping: [instance] 2025-10-08 15:20:09.271943 | controller | 2025-10-08 15:20:09.272535 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] ******* 2025-10-08 15:20:09.272598 | controller | Wednesday 08 October 2025 15:20:09 +0000 (0:00:00.042) 0:00:19.636 ***** 2025-10-08 15:20:09.316906 | controller | skipping: [instance] 2025-10-08 15:20:09.880341 | controller | 2025-10-08 15:20:09.880382 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************ 2025-10-08 15:20:09.880390 | controller | Wednesday 08 October 2025 15:20:09 +0000 (0:00:00.045) 0:00:19.681 ***** 2025-10-08 15:20:09.880401 | controller | changed: [instance] 2025-10-08 15:20:09.880427 | controller | 2025-10-08 15:20:09.880437 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] ******************* 2025-10-08 15:20:09.880511 | controller | Wednesday 08 October 2025 15:20:09 +0000 (0:00:00.564) 0:00:20.245 ***** 2025-10-08 15:20:10.467443 | controller | changed: [instance] 2025-10-08 15:20:10.467515 | controller | 2025-10-08 15:20:10.467687 | controller | TASK [repo_setup : Dump current-podified hash] ********************************* 2025-10-08 15:20:10.467892 | controller | Wednesday 08 October 2025 15:20:10 +0000 (0:00:00.586) 0:00:20.832 ***** 2025-10-08 15:20:10.506492 | controller | skipping: [instance] 2025-10-08 15:20:10.506611 | controller | 2025-10-08 15:20:10.507007 | controller | TASK [repo_setup : Slurp current podified hash] ******************************** 2025-10-08 15:20:10.546832 | controller | Wednesday 08 October 2025 15:20:10 +0000 (0:00:00.039) 0:00:20.871 ***** 2025-10-08 15:20:10.546871 | controller | skipping: [instance] 2025-10-08 15:20:10.585745 | controller | 2025-10-08 15:20:10.585819 | controller | TASK [repo_setup : Update the value of full_hash] ****************************** 2025-10-08 15:20:10.585827 | controller | Wednesday 08 October 2025 15:20:10 +0000 (0:00:00.040) 0:00:20.912 ***** 2025-10-08 15:20:10.585854 | controller | skipping: [instance] 2025-10-08 15:20:10.641514 | controller | 2025-10-08 15:20:10.641571 | controller | TASK [repo_setup : Export hashes facts for further use] ************************ 2025-10-08 15:20:10.641581 | controller | Wednesday 08 October 2025 15:20:10 +0000 (0:00:00.038) 0:00:20.950 ***** 2025-10-08 15:20:10.641594 | controller | ok: [instance] 2025-10-08 15:20:10.641786 | controller | 2025-10-08 15:20:10.641977 | controller | TASK [repo_setup : Create download directory] ********************************** 2025-10-08 15:20:10.642155 | controller | Wednesday 08 October 2025 15:20:10 +0000 (0:00:00.056) 0:00:21.006 ***** 2025-10-08 15:20:10.681792 | controller | skipping: [instance] 2025-10-08 15:20:10.725793 | controller | 2025-10-08 15:20:10.725878 | controller | TASK [repo_setup : Print the URL to request] *********************************** 2025-10-08 15:20:10.725890 | controller | Wednesday 08 October 2025 15:20:10 +0000 (0:00:00.040) 0:00:21.047 ***** 2025-10-08 15:20:10.725922 | controller | skipping: [instance] 2025-10-08 15:20:10.762680 | controller | 2025-10-08 15:20:10.762722 | controller | TASK [Download the RPM] ******************************************************** 2025-10-08 15:20:10.762733 | controller | Wednesday 08 October 2025 15:20:10 +0000 (0:00:00.044) 0:00:21.091 ***** 2025-10-08 15:20:10.762747 | controller | skipping: [instance] 2025-10-08 15:20:10.796001 | controller | 2025-10-08 15:20:10.796038 | controller | TASK [repo_setup : Install RHOS Release tool] ********************************** 2025-10-08 15:20:10.796067 | controller | Wednesday 08 October 2025 15:20:10 +0000 (0:00:00.036) 0:00:21.127 ***** 2025-10-08 15:20:10.796082 | controller | skipping: [instance] 2025-10-08 15:20:10.831164 | controller | 2025-10-08 15:20:10.831237 | controller | TASK [repo_setup : Get rhos-release tool version] ****************************** 2025-10-08 15:20:10.831246 | controller | Wednesday 08 October 2025 15:20:10 +0000 (0:00:00.033) 0:00:21.161 ***** 2025-10-08 15:20:10.831265 | controller | skipping: [instance] 2025-10-08 15:20:10.865522 | controller | 2025-10-08 15:20:10.865563 | controller | TASK [repo_setup : Print rhos-release tool version] **************************** 2025-10-08 15:20:10.865571 | controller | Wednesday 08 October 2025 15:20:10 +0000 (0:00:00.034) 0:00:21.196 ***** 2025-10-08 15:20:10.865584 | controller | skipping: [instance] 2025-10-08 15:20:10.865739 | controller | 2025-10-08 15:20:10.865751 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] *** 2025-10-08 15:20:10.865849 | controller | Wednesday 08 October 2025 15:20:10 +0000 (0:00:00.034) 0:00:21.231 ***** 2025-10-08 15:20:10.900420 | controller | skipping: [instance] 2025-10-08 15:20:10.900455 | controller | 2025-10-08 15:20:10.900463 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] *************************** 2025-10-08 15:20:10.900471 | controller | Wednesday 08 October 2025 15:20:10 +0000 (0:00:00.034) 0:00:21.266 ***** 2025-10-08 15:20:11.137094 | controller | ok: [instance] 2025-10-08 15:20:11.137222 | controller | 2025-10-08 15:20:11.137365 | controller | TASK [repo_setup : Use RDO proxy mirrors] ************************************** 2025-10-08 15:20:11.137551 | controller | Wednesday 08 October 2025 15:20:11 +0000 (0:00:00.236) 0:00:21.502 ***** 2025-10-08 15:20:11.402218 | controller | changed: [instance] 2025-10-08 15:20:11.402328 | controller | 2025-10-08 15:20:11.402484 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] *** 2025-10-08 15:20:11.402631 | controller | Wednesday 08 October 2025 15:20:11 +0000 (0:00:00.265) 0:00:21.767 ***** 2025-10-08 15:20:11.681681 | controller | changed: [instance] 2025-10-08 15:20:11.681848 | controller | 2025-10-08 15:20:11.681863 | controller | TASK [repo_setup : Check for gating.repo file on content provider] ************* 2025-10-08 15:20:11.681987 | controller | Wednesday 08 October 2025 15:20:11 +0000 (0:00:00.279) 0:00:22.047 ***** 2025-10-08 15:20:11.707130 | controller | skipping: [instance] 2025-10-08 15:20:11.707173 | controller | 2025-10-08 15:20:11.707391 | controller | TASK [repo_setup : Populate gating repo from content provider ip] ************** 2025-10-08 15:20:11.735875 | controller | Wednesday 08 October 2025 15:20:11 +0000 (0:00:00.025) 0:00:22.073 ***** 2025-10-08 15:20:11.735921 | controller | skipping: [instance] 2025-10-08 15:20:11.736022 | controller | 2025-10-08 15:20:11.736032 | controller | TASK [repo_setup : Check for DLRN repo at the destination] ********************* 2025-10-08 15:20:11.736140 | controller | Wednesday 08 October 2025 15:20:11 +0000 (0:00:00.028) 0:00:22.101 ***** 2025-10-08 15:20:11.765156 | controller | skipping: [instance] 2025-10-08 15:20:11.765258 | controller | 2025-10-08 15:20:11.765447 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] *** 2025-10-08 15:20:11.765631 | controller | Wednesday 08 October 2025 15:20:11 +0000 (0:00:00.029) 0:00:22.130 ***** 2025-10-08 15:20:11.801285 | controller | skipping: [instance] 2025-10-08 15:20:11.801330 | controller | 2025-10-08 15:20:11.801455 | controller | TASK [repo_setup : Check for DLRN component repo] ****************************** 2025-10-08 15:20:11.801559 | controller | Wednesday 08 October 2025 15:20:11 +0000 (0:00:00.036) 0:00:22.166 ***** 2025-10-08 15:20:11.831196 | controller | skipping: [instance] 2025-10-08 15:20:11.831250 | controller | 2025-10-08 15:20:11.831374 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] *** 2025-10-08 15:20:11.831466 | controller | Wednesday 08 October 2025 15:20:11 +0000 (0:00:00.029) 0:00:22.196 ***** 2025-10-08 15:20:11.859638 | controller | skipping: [instance] 2025-10-08 15:20:11.859735 | controller | 2025-10-08 15:20:11.859927 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ******** 2025-10-08 15:20:11.860108 | controller | Wednesday 08 October 2025 15:20:11 +0000 (0:00:00.028) 0:00:22.224 ***** 2025-10-08 15:20:11.879316 | controller | skipping: [instance] 2025-10-08 15:20:11.879379 | controller | 2025-10-08 15:20:11.879494 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ****** 2025-10-08 15:20:11.879589 | controller | Wednesday 08 October 2025 15:20:11 +0000 (0:00:00.020) 0:00:22.244 ***** 2025-10-08 15:20:11.923144 | controller | skipping: [instance] 2025-10-08 15:20:11.923406 | controller | 2025-10-08 15:20:11.923658 | controller | TASK [repo_setup : Cleanup existing metadata] ********************************** 2025-10-08 15:20:11.923903 | controller | Wednesday 08 October 2025 15:20:11 +0000 (0:00:00.042) 0:00:22.287 ***** 2025-10-08 15:20:11.956066 | controller | skipping: [instance] 2025-10-08 15:20:11.956306 | controller | 2025-10-08 15:20:11.956328 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] ********* 2025-10-08 15:20:11.983304 | controller | Wednesday 08 October 2025 15:20:11 +0000 (0:00:00.032) 0:00:22.320 ***** 2025-10-08 15:20:11.983342 | controller | skipping: [instance] 2025-10-08 15:20:11.983385 | controller | 2025-10-08 15:20:11.983520 | controller | TASK [test_deps : Look for redhat-release rpm] ********************************* 2025-10-08 15:20:11.983644 | controller | Wednesday 08 October 2025 15:20:11 +0000 (0:00:00.028) 0:00:22.349 ***** 2025-10-08 15:20:12.017934 | controller | skipping: [instance] 2025-10-08 15:20:12.017995 | controller | 2025-10-08 15:20:12.018143 | controller | TASK [test_deps : Remove redhat-release] *************************************** 2025-10-08 15:20:12.018237 | controller | Wednesday 08 October 2025 15:20:12 +0000 (0:00:00.034) 0:00:22.383 ***** 2025-10-08 15:20:12.056260 | controller | skipping: [instance] 2025-10-08 15:20:12.056327 | controller | 2025-10-08 15:20:12.056356 | controller | TASK [test_deps : Install centos-stream-release] ******************************* 2025-10-08 15:20:12.056491 | controller | Wednesday 08 October 2025 15:20:12 +0000 (0:00:00.038) 0:00:22.421 ***** 2025-10-08 15:20:12.096444 | controller | skipping: [instance] 2025-10-08 15:20:12.096498 | controller | 2025-10-08 15:20:12.096639 | controller | TASK [test_deps : Install additional packages] ********************************* 2025-10-08 15:20:12.096736 | controller | Wednesday 08 October 2025 15:20:12 +0000 (0:00:00.040) 0:00:22.462 ***** 2025-10-08 15:20:12.132274 | controller | skipping: [instance] 2025-10-08 15:20:12.132343 | controller | 2025-10-08 15:20:12.132470 | controller | TASK [test_deps : Install selinux python libs] ********************************* 2025-10-08 15:20:12.132570 | controller | Wednesday 08 October 2025 15:20:12 +0000 (0:00:00.036) 0:00:22.498 ***** 2025-10-08 15:20:59.893537 | controller | ok: [instance] 2025-10-08 15:20:59.893703 | controller | 2025-10-08 15:20:59.893761 | controller | TASK [test_deps : Install python yaml libs] ************************************ 2025-10-08 15:20:59.893803 | controller | Wednesday 08 October 2025 15:20:59 +0000 (0:00:47.760) 0:01:10.258 ***** 2025-10-08 15:21:01.554497 | controller | ok: [instance] 2025-10-08 15:21:01.554596 | controller | 2025-10-08 15:21:01.554627 | controller | TASK [test_deps : Install extra packages] ************************************** 2025-10-08 15:21:01.554744 | controller | Wednesday 08 October 2025 15:21:01 +0000 (0:00:01.661) 0:01:11.920 ***** 2025-10-08 15:21:01.629210 | controller | skipping: [instance] 2025-10-08 15:21:01.629349 | controller | 2025-10-08 15:21:01.629418 | controller | PLAY RECAP ********************************************************************* 2025-10-08 15:21:01.629450 | controller | instance : ok=19 changed=9 unreachable=0 failed=0 skipped=30 rescued=0 ignored=0 2025-10-08 15:21:01.629497 | controller | 2025-10-08 15:21:01.629603 | controller | Wednesday 08 October 2025 15:21:01 +0000 (0:00:00.074) 0:01:11.994 ***** 2025-10-08 15:21:01.629712 | controller | =============================================================================== 2025-10-08 15:21:01.629878 | controller | test_deps : Install selinux python libs -------------------------------- 47.76s 2025-10-08 15:21:01.629966 | controller | repo_setup : Initialize python venv and install requirements ----------- 10.11s 2025-10-08 15:21:01.629992 | controller | Gathering Facts --------------------------------------------------------- 1.68s 2025-10-08 15:21:01.630090 | controller | test_deps : Install python yaml libs ------------------------------------ 1.66s 2025-10-08 15:21:01.630168 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.58s 2025-10-08 15:21:01.630271 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.07s 2025-10-08 15:21:01.630354 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 1.04s 2025-10-08 15:21:01.630447 | controller | repo_setup : Ensure directories are present ----------------------------- 0.99s 2025-10-08 15:21:01.630590 | controller | repo_setup : Install repo-setup package --------------------------------- 0.94s 2025-10-08 15:21:01.630834 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.78s 2025-10-08 15:21:01.630884 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.59s 2025-10-08 15:21:01.630898 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.59s 2025-10-08 15:21:01.630978 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.57s 2025-10-08 15:21:01.631095 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.56s 2025-10-08 15:21:01.631194 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.28s 2025-10-08 15:21:01.631286 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.27s 2025-10-08 15:21:01.631379 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.24s 2025-10-08 15:21:01.631487 | controller | test_deps : Install extra packages -------------------------------------- 0.07s 2025-10-08 15:21:01.631564 | controller | Deploy repo-setup ------------------------------------------------------- 0.07s 2025-10-08 15:21:01.631674 | controller | test_deps : Gather variables for each operating system ------------------ 0.06s 2025-10-08 15:21:01.717191 | controller | INFO Running default > converge 2025-10-08 15:21:02.255093 | controller | 2025-10-08 15:21:02.255340 | controller | PLAY [Add crc to the inventory] ************************************************ 2025-10-08 15:21:02.255581 | controller | 2025-10-08 15:21:02.255808 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-08 15:21:02.256060 | controller | Wednesday 08 October 2025 15:21:02 +0000 (0:00:00.016) 0:00:00.016 ***** 2025-10-08 15:21:03.383752 | controller | ok: [instance] 2025-10-08 15:21:03.383808 | controller | 2025-10-08 15:21:03.383925 | controller | TASK [Add crc hostname with it's IP to /etc/hosts] ***************************** 2025-10-08 15:21:03.384023 | controller | Wednesday 08 October 2025 15:21:03 +0000 (0:00:01.129) 0:00:01.146 ***** 2025-10-08 15:21:03.747947 | controller | changed: [instance] 2025-10-08 15:21:03.748063 | controller | 2025-10-08 15:21:03.748229 | controller | TASK [Check if new ssh keypair exists] ***************************************** 2025-10-08 15:21:03.748377 | controller | Wednesday 08 October 2025 15:21:03 +0000 (0:00:00.364) 0:00:01.510 ***** 2025-10-08 15:21:03.782465 | controller | 2025-10-08 15:21:03.782596 | controller | TASK [recognize_ssh_keypair : Check if id_ed25519 key exists] ****************** 2025-10-08 15:21:03.782767 | controller | Wednesday 08 October 2025 15:21:03 +0000 (0:00:00.034) 0:00:01.545 ***** 2025-10-08 15:21:04.143375 | controller | ok: [instance] 2025-10-08 15:21:04.143435 | controller | 2025-10-08 15:21:04.144014 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair exists] ****************** 2025-10-08 15:21:04.166941 | controller | Wednesday 08 October 2025 15:21:04 +0000 (0:00:00.360) 0:00:01.906 ***** 2025-10-08 15:21:04.166998 | controller | ok: [instance] 2025-10-08 15:21:04.167035 | controller | 2025-10-08 15:21:04.167156 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair does not exists] ********* 2025-10-08 15:21:04.167175 | controller | Wednesday 08 October 2025 15:21:04 +0000 (0:00:00.023) 0:00:01.929 ***** 2025-10-08 15:21:04.197317 | controller | skipping: [instance] 2025-10-08 15:21:04.197384 | controller | 2025-10-08 15:21:04.197472 | controller | TASK [Add the crc host dynamically] ******************************************** 2025-10-08 15:21:04.197581 | controller | Wednesday 08 October 2025 15:21:04 +0000 (0:00:00.030) 0:00:01.960 ***** 2025-10-08 15:21:04.260373 | controller | changed: [instance] 2025-10-08 15:21:04.260446 | controller | 2025-10-08 15:21:04.260579 | controller | TASK [Add a dummy ethernet interface to target the crc NMstate] **************** 2025-10-08 15:21:04.260595 | controller | Wednesday 08 October 2025 15:21:04 +0000 (0:00:00.062) 0:00:02.023 ***** 2025-10-08 15:21:04.826765 | controller | changed: [instance] 2025-10-08 15:21:04.826904 | controller | 2025-10-08 15:21:04.826942 | controller | PLAY [Get CRC facts] *********************************************************** 2025-10-08 15:21:04.826953 | controller | 2025-10-08 15:21:04.826962 | controller | TASK [Fetch crc network facts] ************************************************* 2025-10-08 15:21:04.826976 | controller | Wednesday 08 October 2025 15:21:04 +0000 (0:00:00.565) 0:00:02.588 ***** 2025-10-08 15:21:11.049815 | controller | ok: [crc] 2025-10-08 15:21:11.049888 | controller | 2025-10-08 15:21:11.050360 | controller | PLAY [Converge] **************************************************************** 2025-10-08 15:21:11.050991 | controller | 2025-10-08 15:21:11.051034 | controller | TASK [Set common molecule test facts] ****************************************** 2025-10-08 15:21:11.051107 | controller | Wednesday 08 October 2025 15:21:11 +0000 (0:00:06.222) 0:00:08.810 ***** 2025-10-08 15:21:11.114514 | controller | ok: [instance] 2025-10-08 15:21:11.115305 | controller | 2025-10-08 15:21:11.179260 | controller | TASK [Prepare the Networking Env Definition for ci_nmstate role] *************** 2025-10-08 15:21:11.179315 | controller | Wednesday 08 October 2025 15:21:11 +0000 (0:00:00.065) 0:00:08.876 ***** 2025-10-08 15:21:11.179336 | controller | 2025-10-08 15:21:11.179681 | controller | TASK [networking_mapper : Check for Networking Definition file existence] ****** 2025-10-08 15:21:11.179987 | controller | Wednesday 08 October 2025 15:21:11 +0000 (0:00:00.065) 0:00:08.941 ***** 2025-10-08 15:21:11.292893 | controller | skipping: [instance] 2025-10-08 15:21:11.293005 | controller | 2025-10-08 15:21:11.293215 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************ 2025-10-08 15:21:11.293323 | controller | Wednesday 08 October 2025 15:21:11 +0000 (0:00:00.113) 0:00:09.055 ***** 2025-10-08 15:21:11.384573 | controller | skipping: [instance] 2025-10-08 15:21:11.384952 | controller | 2025-10-08 15:21:11.385425 | controller | TASK [networking_mapper : Check for interfaces info file existence] ************ 2025-10-08 15:21:11.385878 | controller | Wednesday 08 October 2025 15:21:11 +0000 (0:00:00.091) 0:00:09.146 ***** 2025-10-08 15:21:11.426924 | controller | skipping: [instance] 2025-10-08 15:21:11.427090 | controller | 2025-10-08 15:21:11.427121 | controller | TASK [networking_mapper : Load the interfaces info from file] ****************** 2025-10-08 15:21:11.468660 | controller | Wednesday 08 October 2025 15:21:11 +0000 (0:00:00.042) 0:00:09.189 ***** 2025-10-08 15:21:11.468709 | controller | skipping: [instance] 2025-10-08 15:21:11.468811 | controller | 2025-10-08 15:21:11.468822 | controller | TASK [networking_mapper : Ensure local fact is empty] ************************** 2025-10-08 15:21:11.468831 | controller | Wednesday 08 October 2025 15:21:11 +0000 (0:00:00.041) 0:00:09.231 ***** 2025-10-08 15:21:11.489779 | controller | ok: [instance] 2025-10-08 15:21:11.627123 | controller | 2025-10-08 15:21:11.627202 | controller | TASK [networking_mapper : Set the input Networking Definition and patch it if needed] *** 2025-10-08 15:21:11.627217 | controller | Wednesday 08 October 2025 15:21:11 +0000 (0:00:00.021) 0:00:09.252 ***** 2025-10-08 15:21:11.627235 | controller | ok: [instance] => (item={'networks': {'default': {'network': '38.129.56.0/24', 'gateway': '38.129.56.1', 'dns': ['1.1.1.1', '1.0.0.1'], 'mtu': '1450'}, 'testing-untagged': {'network': '172.17.20.0/24', 'dns': ['192.168.130.11', '192.168.130.1'], 'mtu': '1500'}, 'testing-vlan': {'network': '172.17.21.0/24', 'vlan': 21}}, 'instances': {'crc': {'networks': {'testing-untagged': {'ip': '172.17.20.5'}, 'testing-vlan': {'ip': '172.17.21.5', 'trunk-parent': 'testing-untagged'}}}, 'instance': {'networks': {'default': {'ip': '38.129.56.68'}}}}}) 2025-10-08 15:21:11.627387 | controller | 2025-10-08 15:21:11.627441 | controller | TASK [networking_mapper : Gather facts if requested] *************************** 2025-10-08 15:21:11.627485 | controller | Wednesday 08 October 2025 15:21:11 +0000 (0:00:00.136) 0:00:09.388 ***** 2025-10-08 15:21:11.664753 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/networking_mapper/tasks/_gather_facts.yml for instance 2025-10-08 15:21:11.665193 | controller | 2025-10-08 15:21:11.665645 | controller | TASK [networking_mapper : Gather the facts] ************************************ 2025-10-08 15:21:11.665998 | controller | Wednesday 08 October 2025 15:21:11 +0000 (0:00:00.038) 0:00:09.427 ***** 2025-10-08 15:21:12.064308 | controller | skipping: [instance] => (item=crc) 2025-10-08 15:21:12.064432 | controller | skipping: [instance] => (item=instance) 2025-10-08 15:21:12.064535 | controller | skipping: [instance] 2025-10-08 15:21:12.064687 | controller | 2025-10-08 15:21:12.064889 | controller | TASK [networking_mapper : Save instances refreshed facts for troubleshooting purposes] *** 2025-10-08 15:21:12.065066 | controller | Wednesday 08 October 2025 15:21:12 +0000 (0:00:00.397) 0:00:09.825 ***** 2025-10-08 15:21:13.098271 | controller | changed: [instance] 2025-10-08 15:21:13.098335 | controller | 2025-10-08 15:21:13.098346 | controller | TASK [networking_mapper : Ensure CI infrastructure dir exists] ***************** 2025-10-08 15:21:13.098367 | controller | Wednesday 08 October 2025 15:21:13 +0000 (0:00:01.034) 0:00:10.860 ***** 2025-10-08 15:21:13.604406 | controller | changed: [instance] 2025-10-08 15:21:13.604462 | controller | 2025-10-08 15:21:13.604554 | controller | TASK [networking_mapper : Write the Networking Definition to file] ************* 2025-10-08 15:21:13.604660 | controller | Wednesday 08 October 2025 15:21:13 +0000 (0:00:00.506) 0:00:11.366 ***** 2025-10-08 15:21:14.167704 | controller | changed: [instance] 2025-10-08 15:21:14.167766 | controller | 2025-10-08 15:21:14.167852 | controller | TASK [networking_mapper : Call the networking mapper] ************************** 2025-10-08 15:21:14.167957 | controller | Wednesday 08 October 2025 15:21:14 +0000 (0:00:00.563) 0:00:11.930 ***** 2025-10-08 15:21:14.273700 | controller | ok: [instance] 2025-10-08 15:21:14.273755 | controller | 2025-10-08 15:21:14.273874 | controller | TASK [networking_mapper : Set networking mapper facts] ************************* 2025-10-08 15:21:14.273970 | controller | Wednesday 08 October 2025 15:21:14 +0000 (0:00:00.106) 0:00:12.036 ***** 2025-10-08 15:21:14.304342 | controller | ok: [instance] 2025-10-08 15:21:14.304435 | controller | 2025-10-08 15:21:14.304570 | controller | TASK [networking_mapper : Write the Networking Environment Definition to file] *** 2025-10-08 15:21:14.304696 | controller | Wednesday 08 October 2025 15:21:14 +0000 (0:00:00.030) 0:00:12.067 ***** 2025-10-08 15:21:14.860521 | controller | changed: [instance] 2025-10-08 15:21:14.860581 | controller | 2025-10-08 15:21:14.860675 | controller | TASK [Call the role under test] ************************************************ 2025-10-08 15:21:14.860779 | controller | Wednesday 08 October 2025 15:21:14 +0000 (0:00:00.555) 0:00:12.622 ***** 2025-10-08 15:21:14.951100 | controller | 2025-10-08 15:21:14.951229 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] *** 2025-10-08 15:21:14.951342 | controller | Wednesday 08 October 2025 15:21:14 +0000 (0:00:00.091) 0:00:12.714 ***** 2025-10-08 15:21:14.987734 | controller | skipping: [instance] 2025-10-08 15:21:14.988111 | controller | 2025-10-08 15:21:14.988234 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ****** 2025-10-08 15:21:14.988337 | controller | Wednesday 08 October 2025 15:21:14 +0000 (0:00:00.036) 0:00:12.750 ***** 2025-10-08 15:21:15.025148 | controller | skipping: [instance] 2025-10-08 15:21:15.025207 | controller | 2025-10-08 15:21:15.025313 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************ 2025-10-08 15:21:15.025412 | controller | Wednesday 08 October 2025 15:21:15 +0000 (0:00:00.037) 0:00:12.788 ***** 2025-10-08 15:21:15.065482 | controller | skipping: [instance] 2025-10-08 15:21:15.065535 | controller | 2025-10-08 15:21:15.065622 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ****** 2025-10-08 15:21:15.065734 | controller | Wednesday 08 October 2025 15:21:15 +0000 (0:00:00.040) 0:00:12.828 ***** 2025-10-08 15:21:15.103529 | controller | skipping: [instance] 2025-10-08 15:21:15.103592 | controller | 2025-10-08 15:21:15.103680 | controller | TASK [ci_nmstate : Initialize NMstates for each instance] ********************** 2025-10-08 15:21:15.103799 | controller | Wednesday 08 October 2025 15:21:15 +0000 (0:00:00.038) 0:00:12.866 ***** 2025-10-08 15:21:15.143201 | controller | ok: [instance] 2025-10-08 15:21:15.143309 | controller | 2025-10-08 15:21:15.143530 | controller | TASK [ci_nmstate : Build NMstates from the Networking Environment Definition] *** 2025-10-08 15:21:15.143583 | controller | Wednesday 08 October 2025 15:21:15 +0000 (0:00:00.039) 0:00:12.906 ***** 2025-10-08 15:21:15.214198 | controller | ok: [instance] => (item=crc) 2025-10-08 15:21:15.214611 | controller | ok: [instance] => (item=instance) 2025-10-08 15:21:16.349362 | controller | 2025-10-08 15:21:16.349415 | controller | TASK [ci_nmstate : Get k8s nodes] ********************************************** 2025-10-08 15:21:16.349427 | controller | Wednesday 08 October 2025 15:21:15 +0000 (0:00:00.070) 0:00:12.976 ***** 2025-10-08 15:21:16.349444 | controller | ok: [instance] 2025-10-08 15:21:16.349581 | controller | 2025-10-08 15:21:16.349602 | controller | TASK [ci_nmstate : Set the hosts that will provision nmstate using k8s] ******** 2025-10-08 15:21:16.349616 | controller | Wednesday 08 October 2025 15:21:16 +0000 (0:00:01.134) 0:00:14.111 ***** 2025-10-08 15:21:16.472715 | controller | ok: [instance] 2025-10-08 15:21:16.472909 | controller | 2025-10-08 15:21:16.473269 | controller | TASK [ci_nmstate : Provision k8s workers with nmstate] ************************* 2025-10-08 15:21:16.473598 | controller | Wednesday 08 October 2025 15:21:16 +0000 (0:00:00.123) 0:00:14.234 ***** 2025-10-08 15:21:16.570471 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_nmstate/tasks/nmstate_k8s_install.yml for instance 2025-10-08 15:21:16.570559 | controller | 2025-10-08 15:21:16.570706 | controller | TASK [ci_nmstate : Create the target dir to dump NMstate manifests] ************ 2025-10-08 15:21:16.570850 | controller | Wednesday 08 October 2025 15:21:16 +0000 (0:00:00.098) 0:00:14.332 ***** 2025-10-08 15:21:16.900787 | controller | changed: [instance] 2025-10-08 15:21:16.900864 | controller | 2025-10-08 15:21:16.900962 | controller | TASK [ci_nmstate : Create the nmstate namespace] ******************************* 2025-10-08 15:21:16.901146 | controller | Wednesday 08 October 2025 15:21:16 +0000 (0:00:00.330) 0:00:14.663 ***** 2025-10-08 15:21:18.110767 | controller | changed: [instance] 2025-10-08 15:21:18.110821 | controller | 2025-10-08 15:21:18.110932 | controller | TASK [ci_nmstate : Generate the OperatorGroup final CR] ************************ 2025-10-08 15:21:18.111035 | controller | Wednesday 08 October 2025 15:21:18 +0000 (0:00:01.209) 0:00:15.873 ***** 2025-10-08 15:21:18.203811 | controller | ok: [instance] => (item={'apiVersion': 'operators.coreos.com/v1', 'kind': 'OperatorGroup', 'metadata': {'annotations': {'olm.providedAPIs': 'NMState.v1.nmstate.io'}, 'name': 'openshift-nmstate', 'namespace': 'openshift-nmstate'}, 'spec': {'targetNamespaces': ['openshift-nmstate']}}) 2025-10-08 15:21:18.203871 | controller | 2025-10-08 15:21:18.203961 | controller | TASK [ci_nmstate : Generate the Subscription final CR] ************************* 2025-10-08 15:21:18.204074 | controller | Wednesday 08 October 2025 15:21:18 +0000 (0:00:00.093) 0:00:15.966 ***** 2025-10-08 15:21:18.295603 | controller | ok: [instance] => (item={'apiVersion': 'operators.coreos.com/v1alpha1', 'kind': 'Subscription', 'metadata': {'labels': {'operators.coreos.com/kubernetes-nmstate-operator.openshift-nmstate': ''}, 'name': 'kubernetes-nmstate-operator', 'namespace': 'openshift-nmstate'}, 'spec': {'channel': 'stable', 'installPlanApproval': 'Automatic', 'name': 'kubernetes-nmstate-operator', 'source': 'redhat-operators', 'sourceNamespace': 'openshift-marketplace'}}) 2025-10-08 15:21:18.295686 | controller | 2025-10-08 15:21:18.297752 | controller | TASK [ci_nmstate : Save k8s nmstate OLM manifests as artifacts] **************** 2025-10-08 15:21:19.199145 | controller | Wednesday 08 October 2025 15:21:18 +0000 (0:00:00.091) 0:00:16.058 ***** 2025-10-08 15:21:19.199195 | controller | changed: [instance] => (item=openshift-nmstate) 2025-10-08 15:21:20.825109 | controller | changed: [instance] => (item=kubernetes-nmstate-operator) 2025-10-08 15:21:20.825151 | controller | 2025-10-08 15:21:20.825160 | controller | TASK [ci_nmstate : Create the OLM subscription resources] ********************** 2025-10-08 15:21:20.825169 | controller | Wednesday 08 October 2025 15:21:19 +0000 (0:00:00.902) 0:00:16.961 ***** 2025-10-08 15:21:20.825195 | controller | changed: [instance] => (item=openshift-nmstate) 2025-10-08 15:21:52.985056 | controller | changed: [instance] => (item=kubernetes-nmstate-operator) 2025-10-08 15:21:52.985116 | controller | 2025-10-08 15:21:52.985126 | controller | TASK [ci_nmstate : Wait for nmstate operator to be installed] ****************** 2025-10-08 15:21:52.985134 | controller | Wednesday 08 October 2025 15:21:20 +0000 (0:00:01.626) 0:00:18.587 ***** 2025-10-08 15:21:52.985153 | controller | FAILED - RETRYING: [instance]: Wait for nmstate operator to be installed (12 retries left). 2025-10-08 15:21:52.985202 | controller | FAILED - RETRYING: [instance]: Wait for nmstate operator to be installed (11 retries left). 2025-10-08 15:21:52.985212 | controller | FAILED - RETRYING: [instance]: Wait for nmstate operator to be installed (10 retries left). 2025-10-08 15:21:52.985222 | controller | ok: [instance] 2025-10-08 15:21:52.985654 | controller | 2025-10-08 15:21:52.985696 | controller | TASK [ci_nmstate : Save nmstate operator config as an artifact] **************** 2025-10-08 15:21:52.985707 | controller | Wednesday 08 October 2025 15:21:52 +0000 (0:00:32.159) 0:00:50.747 ***** 2025-10-08 15:21:53.540483 | controller | changed: [instance] 2025-10-08 15:21:54.381995 | controller | 2025-10-08 15:21:54.382056 | controller | TASK [ci_nmstate : Trigger nmstate operator to install nmstate] **************** 2025-10-08 15:21:54.382070 | controller | Wednesday 08 October 2025 15:21:53 +0000 (0:00:00.555) 0:00:51.302 ***** 2025-10-08 15:21:54.382083 | controller | changed: [instance] 2025-10-08 15:22:25.770695 | controller | 2025-10-08 15:22:25.770737 | controller | TASK [ci_nmstate : Wait for nsmtate Deployments to be ready] ******************* 2025-10-08 15:22:25.770745 | controller | Wednesday 08 October 2025 15:21:54 +0000 (0:00:00.841) 0:00:52.144 ***** 2025-10-08 15:22:25.770756 | controller | ok: [instance] 2025-10-08 15:22:26.862253 | controller | 2025-10-08 15:22:26.862299 | controller | TASK [ci_nmstate : Wait for nsmtate DaemonSet to be ready] ********************* 2025-10-08 15:22:26.862310 | controller | Wednesday 08 October 2025 15:22:25 +0000 (0:00:31.389) 0:01:23.533 ***** 2025-10-08 15:22:26.862324 | controller | ok: [instance] 2025-10-08 15:22:26.914098 | controller | 2025-10-08 15:22:26.914148 | controller | TASK [ci_nmstate : Provision nncps] ******************************************** 2025-10-08 15:22:26.914165 | controller | Wednesday 08 October 2025 15:22:26 +0000 (0:00:01.091) 0:01:24.624 ***** 2025-10-08 15:22:26.914187 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_nmstate/tasks/nmstate_k8s_provision_node.yml for instance => (item=crc) 2025-10-08 15:22:26.914537 | controller | 2025-10-08 15:22:27.014818 | controller | TASK [ci_nmstate : Prepare the k8s nmstate nncp to apply] ********************** 2025-10-08 15:22:27.014881 | controller | Wednesday 08 October 2025 15:22:26 +0000 (0:00:00.052) 0:01:24.676 ***** 2025-10-08 15:22:27.014901 | controller | ok: [instance] 2025-10-08 15:22:27.014941 | controller | 2025-10-08 15:22:27.014959 | controller | TASK [ci_nmstate : Save k8s nmstate nncp manifests as artifacts] *************** 2025-10-08 15:22:27.015104 | controller | Wednesday 08 October 2025 15:22:27 +0000 (0:00:00.101) 0:01:24.777 ***** 2025-10-08 15:22:27.595986 | controller | changed: [instance] 2025-10-08 15:22:27.596076 | controller | 2025-10-08 15:22:27.596108 | controller | TASK [ci_nmstate : Provision nmstate nncp for the node worker] ***************** 2025-10-08 15:22:27.596286 | controller | Wednesday 08 October 2025 15:22:27 +0000 (0:00:00.580) 0:01:25.358 ***** 2025-10-08 15:22:28.532400 | controller | changed: [instance] 2025-10-08 15:22:28.532516 | controller | 2025-10-08 15:22:28.532578 | controller | TASK [ci_nmstate : Wait for nsmtate nncp to be ready] ************************** 2025-10-08 15:22:28.532722 | controller | Wednesday 08 October 2025 15:22:28 +0000 (0:00:00.936) 0:01:26.294 ***** 2025-10-08 15:27:56.734665 | controller | fatal: [instance]: FAILED! => changed=false 2025-10-08 15:27:56.734750 | controller | api_found: true 2025-10-08 15:27:56.734761 | controller | attempts: 30 2025-10-08 15:27:56.734768 | controller | resources: 2025-10-08 15:27:56.734774 | controller | - apiVersion: nmstate.io/v1 2025-10-08 15:27:56.734780 | controller | kind: NodeNetworkConfigurationPolicy 2025-10-08 15:27:56.734788 | controller | metadata: 2025-10-08 15:27:56.734795 | controller | annotations: 2025-10-08 15:27:56.734802 | controller | nmstate.io/webhook-mutating-timestamp: '1759936948243327134' 2025-10-08 15:27:56.734824 | controller | creationTimestamp: '2025-10-08T15:22:28Z' 2025-10-08 15:27:56.734840 | controller | generation: 1 2025-10-08 15:27:56.734848 | controller | managedFields: 2025-10-08 15:27:56.734856 | controller | - apiVersion: nmstate.io/v1 2025-10-08 15:27:56.734864 | controller | fieldsType: FieldsV1 2025-10-08 15:27:56.734872 | controller | fieldsV1: 2025-10-08 15:27:56.734879 | controller | f:spec: 2025-10-08 15:27:56.734887 | controller | .: {} 2025-10-08 15:27:56.734895 | controller | f:desiredState: 2025-10-08 15:27:56.734902 | controller | .: {} 2025-10-08 15:27:56.734914 | controller | f:dns-resolver: 2025-10-08 15:27:56.734940 | controller | .: {} 2025-10-08 15:27:56.735129 | controller | f:config: 2025-10-08 15:27:56.735163 | controller | .: {} 2025-10-08 15:27:56.735706 | controller | f:search: {} 2025-10-08 15:27:56.735806 | controller | f:server: {} 2025-10-08 15:27:56.735816 | controller | f:interfaces: {} 2025-10-08 15:27:56.735821 | controller | f:nodeSelector: 2025-10-08 15:27:56.735829 | controller | .: {} 2025-10-08 15:27:56.736063 | controller | f:kubernetes.io/hostname: {} 2025-10-08 15:27:56.736106 | controller | f:node-role.kubernetes.io/worker: {} 2025-10-08 15:27:56.736295 | controller | manager: OpenAPI-Generator 2025-10-08 15:27:56.736382 | controller | operation: Update 2025-10-08 15:27:56.736615 | controller | time: '2025-10-08T15:22:28Z' 2025-10-08 15:27:56.736647 | controller | - apiVersion: nmstate.io/v1 2025-10-08 15:27:56.736764 | controller | fieldsType: FieldsV1 2025-10-08 15:27:56.736898 | controller | fieldsV1: 2025-10-08 15:27:56.737017 | controller | f:status: 2025-10-08 15:27:56.737283 | controller | .: {} 2025-10-08 15:27:56.737331 | controller | f:conditions: {} 2025-10-08 15:27:56.737519 | controller | f:lastUnavailableNodeCountUpdate: {} 2025-10-08 15:27:56.737571 | controller | manager: manager 2025-10-08 15:27:56.737702 | controller | operation: Update 2025-10-08 15:27:56.737826 | controller | subresource: status 2025-10-08 15:27:56.737986 | controller | time: '2025-10-08T15:27:20Z' 2025-10-08 15:27:56.738304 | controller | name: crc 2025-10-08 15:27:56.738453 | controller | resourceVersion: '36109' 2025-10-08 15:27:56.738568 | controller | uid: e8bd0867-9def-4e15-8da0-e3896c9d1364 2025-10-08 15:27:56.738707 | controller | spec: 2025-10-08 15:27:56.738739 | controller | desiredState: 2025-10-08 15:27:56.738912 | controller | dns-resolver: 2025-10-08 15:27:56.739081 | controller | config: 2025-10-08 15:27:56.739107 | controller | search: 2025-10-08 15:27:56.739216 | controller | - testing-untagged.example.com 2025-10-08 15:27:56.739329 | controller | - testing-vlan.example.com 2025-10-08 15:27:56.739457 | controller | server: 2025-10-08 15:27:56.739598 | controller | - 192.168.130.11 2025-10-08 15:27:56.739823 | controller | - 192.168.130.1 2025-10-08 15:27:56.739869 | controller | interfaces: 2025-10-08 15:27:56.740018 | controller | - description: testing-untagged 2025-10-08 15:27:56.740382 | controller | ipv4: 2025-10-08 15:27:56.740424 | controller | address: 2025-10-08 15:27:56.740438 | controller | - ip: 172.17.20.5 2025-10-08 15:27:56.740560 | controller | prefix-length: 24 2025-10-08 15:27:56.740702 | controller | enabled: true 2025-10-08 15:27:56.740922 | controller | ipv6: 2025-10-08 15:27:56.740946 | controller | enabled: false 2025-10-08 15:27:56.741194 | controller | mtu: 1500 2025-10-08 15:27:56.741332 | controller | name: enp6s0 2025-10-08 15:27:56.741458 | controller | state: up 2025-10-08 15:27:56.741501 | controller | type: ethernet 2025-10-08 15:27:56.741722 | controller | - description: testing-vlan 2025-10-08 15:27:56.741762 | controller | ipv4: 2025-10-08 15:27:56.742001 | controller | address: 2025-10-08 15:27:56.742056 | controller | - ip: 172.17.21.5 2025-10-08 15:27:56.742265 | controller | prefix-length: 24 2025-10-08 15:27:56.742408 | controller | enabled: true 2025-10-08 15:27:56.742539 | controller | ipv6: 2025-10-08 15:27:56.742574 | controller | enabled: false 2025-10-08 15:27:56.742714 | controller | mtu: 1496 2025-10-08 15:27:56.742856 | controller | name: enp6s0.21 2025-10-08 15:27:56.742975 | controller | state: up 2025-10-08 15:27:56.743177 | controller | type: vlan 2025-10-08 15:27:56.743307 | controller | vlan: 2025-10-08 15:27:56.743434 | controller | base-iface: enp6s0 2025-10-08 15:27:56.743573 | controller | id: 21 2025-10-08 15:27:56.743815 | controller | nodeSelector: 2025-10-08 15:27:56.743953 | controller | kubernetes.io/hostname: crc 2025-10-08 15:27:56.744145 | controller | node-role.kubernetes.io/worker: '' 2025-10-08 15:27:56.744190 | controller | status: 2025-10-08 15:27:56.744309 | controller | conditions: 2025-10-08 15:27:56.744523 | controller | - lastHeartbeatTime: '2025-10-08T15:27:20Z' 2025-10-08 15:27:56.744661 | controller | lastTransitionTime: '2025-10-08T15:27:20Z' 2025-10-08 15:27:56.744789 | controller | reason: FailedToConfigure 2025-10-08 15:27:56.744942 | controller | status: 'False' 2025-10-08 15:27:56.745209 | controller | type: Available 2025-10-08 15:27:56.745937 | controller | - lastHeartbeatTime: '2025-10-08T15:27:20Z' 2025-10-08 15:27:56.745961 | controller | lastTransitionTime: '2025-10-08T15:27:20Z' 2025-10-08 15:27:56.746010 | controller | message: 1/1 nodes failed to configure 2025-10-08 15:27:56.746020 | controller | reason: FailedToConfigure 2025-10-08 15:27:56.746027 | controller | status: 'True' 2025-10-08 15:27:56.746035 | controller | type: Degraded 2025-10-08 15:27:56.746059 | controller | - lastHeartbeatTime: '2025-10-08T15:27:20Z' 2025-10-08 15:27:56.746078 | controller | lastTransitionTime: '2025-10-08T15:27:20Z' 2025-10-08 15:27:56.746148 | controller | reason: ConfigurationProgressing 2025-10-08 15:27:56.746303 | controller | status: 'False' 2025-10-08 15:27:56.746434 | controller | type: Progressing 2025-10-08 15:27:56.746559 | controller | lastUnavailableNodeCountUpdate: '2025-10-08T15:27:20Z' 2025-10-08 15:27:56.746855 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (30 retries left). 2025-10-08 15:27:56.746979 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (29 retries left). 2025-10-08 15:27:56.747099 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (28 retries left). 2025-10-08 15:27:56.747262 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (27 retries left). 2025-10-08 15:27:56.747404 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (26 retries left). 2025-10-08 15:27:56.747531 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (25 retries left). 2025-10-08 15:27:56.747677 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (24 retries left). 2025-10-08 15:27:56.747862 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (23 retries left). 2025-10-08 15:27:56.747997 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (22 retries left). 2025-10-08 15:27:56.748171 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (21 retries left). 2025-10-08 15:27:56.748315 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (20 retries left). 2025-10-08 15:27:56.748458 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (19 retries left). 2025-10-08 15:27:56.748608 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (18 retries left). 2025-10-08 15:27:56.748757 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (17 retries left). 2025-10-08 15:27:56.748907 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (16 retries left). 2025-10-08 15:27:56.749071 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (15 retries left). 2025-10-08 15:27:56.749239 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (14 retries left). 2025-10-08 15:27:56.749370 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (13 retries left). 2025-10-08 15:27:56.749515 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (12 retries left). 2025-10-08 15:27:56.749715 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (11 retries left). 2025-10-08 15:27:56.749813 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (10 retries left). 2025-10-08 15:27:56.749948 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (9 retries left). 2025-10-08 15:27:56.750105 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (8 retries left). 2025-10-08 15:27:56.750256 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (7 retries left). 2025-10-08 15:27:56.750387 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (6 retries left). 2025-10-08 15:27:56.750540 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (5 retries left). 2025-10-08 15:27:56.750670 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (4 retries left). 2025-10-08 15:27:56.750827 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (3 retries left). 2025-10-08 15:27:56.750970 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (2 retries left). 2025-10-08 15:27:56.751128 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (1 retries left). 2025-10-08 15:27:56.751270 | controller | 2025-10-08 15:27:56.751395 | controller | PLAY RECAP ********************************************************************* 2025-10-08 15:27:56.751552 | controller | crc : ok=1 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2025-10-08 15:27:56.751693 | controller | instance : ok=36 changed=15 unreachable=0 failed=1 skipped=10 rescued=0 ignored=0 2025-10-08 15:27:56.751858 | controller | 2025-10-08 15:27:56.752000 | controller | Wednesday 08 October 2025 15:27:56 +0000 (0:05:28.203) 0:06:54.498 ***** 2025-10-08 15:27:56.752139 | controller | =============================================================================== 2025-10-08 15:27:56.752294 | controller | ci_nmstate : Wait for nsmtate nncp to be ready ------------------------ 328.20s 2025-10-08 15:27:56.752429 | controller | ci_nmstate : Wait for nmstate operator to be installed ----------------- 32.16s 2025-10-08 15:27:56.752578 | controller | ci_nmstate : Wait for nsmtate Deployments to be ready ------------------ 31.39s 2025-10-08 15:27:56.752698 | controller | Fetch crc network facts ------------------------------------------------- 6.22s 2025-10-08 15:27:56.752829 | controller | ci_nmstate : Create the OLM subscription resources ---------------------- 1.63s 2025-10-08 15:27:56.752955 | controller | ci_nmstate : Create the nmstate namespace ------------------------------- 1.21s 2025-10-08 15:27:56.753099 | controller | ci_nmstate : Get k8s nodes ---------------------------------------------- 1.13s 2025-10-08 15:27:56.753243 | controller | Gathering Facts --------------------------------------------------------- 1.13s 2025-10-08 15:27:56.753380 | controller | ci_nmstate : Wait for nsmtate DaemonSet to be ready --------------------- 1.09s 2025-10-08 15:27:56.753524 | controller | networking_mapper : Save instances refreshed facts for troubleshooting purposes --- 1.04s 2025-10-08 15:27:56.753667 | controller | ci_nmstate : Provision nmstate nncp for the node worker ----------------- 0.94s 2025-10-08 15:27:56.753788 | controller | ci_nmstate : Save k8s nmstate OLM manifests as artifacts ---------------- 0.90s 2025-10-08 15:27:56.753917 | controller | ci_nmstate : Trigger nmstate operator to install nmstate ---------------- 0.84s 2025-10-08 15:27:56.754062 | controller | ci_nmstate : Save k8s nmstate nncp manifests as artifacts --------------- 0.58s 2025-10-08 15:27:56.754218 | controller | Add a dummy ethernet interface to target the crc NMstate ---------------- 0.57s 2025-10-08 15:27:56.754340 | controller | networking_mapper : Write the Networking Definition to file ------------- 0.56s 2025-10-08 15:27:56.754486 | controller | networking_mapper : Write the Networking Environment Definition to file --- 0.56s 2025-10-08 15:27:56.754634 | controller | ci_nmstate : Save nmstate operator config as an artifact ---------------- 0.56s 2025-10-08 15:27:56.754755 | controller | networking_mapper : Ensure CI infrastructure dir exists ----------------- 0.51s 2025-10-08 15:27:56.754892 | controller | networking_mapper : Gather the facts ------------------------------------ 0.40s 2025-10-08 15:27:56.823367 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/ci_nmstate/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_nmstate/molecule/default/converge.yml'] 2025-10-08 15:27:56.823873 | controller | WARNING An error occurred during the test sequence action: 'converge'. Cleaning up. 2025-10-08 15:27:56.837297 | controller | INFO Running default > cleanup 2025-10-08 15:27:56.837889 | controller | WARNING Skipping, cleanup playbook not configured. 2025-10-08 15:27:56.873885 | controller | INFO Running default > destroy 2025-10-08 15:27:56.874282 | controller | WARNING Skipping, instances are delegated. 2025-10-08 15:27:56.874662 | controller | INFO Pruning extra files from scenario ephemeral directory 2025-10-08 15:27:56.876298 | controller | INFO Writing /tmp/report.html report. 2025-10-08 15:27:57.245564 | controller | ERROR 2025-10-08 15:27:57.245977 | controller | { 2025-10-08 15:27:57.246048 | controller | "delta": "0:08:39.810918", 2025-10-08 15:27:57.246096 | controller | "end": "2025-10-08 15:27:56.956525", 2025-10-08 15:27:57.246138 | controller | "msg": "non-zero return code", 2025-10-08 15:27:57.246177 | controller | "rc": 1, 2025-10-08 15:27:57.246224 | controller | "start": "2025-10-08 15:19:17.145607" 2025-10-08 15:27:57.246285 | controller | } failure 2025-10-08 15:27:57.258373 | 2025-10-08 15:27:57.258484 | PLAY RECAP 2025-10-08 15:27:57.258569 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0 2025-10-08 15:27:57.258632 | 2025-10-08 15:27:57.386060 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-08 15:27:57.387809 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-08 15:27:58.050088 | 2025-10-08 15:27:58.050266 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-08 15:27:58.072793 | 2025-10-08 15:27:58.072919 | TASK [Filter out host if needed] 2025-10-08 15:27:58.082306 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-08 15:27:58.087357 | 2025-10-08 15:27:58.087422 | TASK [Ensure file is present] 2025-10-08 15:27:58.628706 | controller | ok 2025-10-08 15:27:58.642756 | 2025-10-08 15:27:58.642901 | TASK [Manage molecule report file] 2025-10-08 15:27:59.688978 | controller | changed 2025-10-08 15:27:59.702278 | 2025-10-08 15:27:59.702422 | TASK [Check if we get ci-framework-data basedir] 2025-10-08 15:28:00.051017 | controller | ok 2025-10-08 15:28:00.057050 | 2025-10-08 15:28:00.057114 | TASK [Create ci-framework-data log directory for zuul] 2025-10-08 15:28:00.583060 | controller | changed 2025-10-08 15:28:00.596530 | 2025-10-08 15:28:00.596702 | TASK [Copy ci-framework interesting files] 2025-10-08 15:28:01.062111 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-10-08 15:28:01.167059 | controller | changed 2025-10-08 15:28:01.172130 | 2025-10-08 15:28:01.172193 | TASK [Get SELinux listing] 2025-10-08 15:28:01.706128 | controller | changed 2025-10-08 15:28:01.712210 | 2025-10-08 15:28:01.712280 | TASK [Generate log index] 2025-10-08 15:28:03.338367 | controller | changed 2025-10-08 15:28:03.349869 | 2025-10-08 15:28:03.350002 | TASK [Get some env related data] 2025-10-08 15:28:04.205583 | controller | /home/zuul/.local/bin/ansible 2025-10-08 15:28:04.939031 | controller | changed 2025-10-08 15:28:04.944732 | 2025-10-08 15:28:04.944793 | TASK [Generate list of logs to collect in home directory] 2025-10-08 15:28:05.397649 | controller | ok: All paths examined 2025-10-08 15:28:05.403489 | 2025-10-08 15:28:05.403574 | LOOP [Copy logs from home directory] 2025-10-08 15:28:06.278291 | controller | changed: 2025-10-08 15:28:06.278534 | controller | { 2025-10-08 15:28:06.278561 | controller | "atime": 1743544925.4788878, 2025-10-08 15:28:06.278579 | controller | "ctime": 1743545329.1409318, 2025-10-08 15:28:06.278596 | controller | "dev": 64513, 2025-10-08 15:28:06.278611 | controller | "gid": 1000, 2025-10-08 15:28:06.278626 | controller | "gr_name": "zuul", 2025-10-08 15:28:06.278642 | controller | "inode": 4518807, 2025-10-08 15:28:06.278657 | controller | "isblk": false, 2025-10-08 15:28:06.278695 | controller | "ischr": false, 2025-10-08 15:28:06.278711 | controller | "isdir": false, 2025-10-08 15:28:06.278726 | controller | "isfifo": false, 2025-10-08 15:28:06.278740 | controller | "isgid": false, 2025-10-08 15:28:06.278755 | controller | "islnk": false, 2025-10-08 15:28:06.278769 | controller | "isreg": true, 2025-10-08 15:28:06.278783 | controller | "issock": false, 2025-10-08 15:28:06.278797 | controller | "isuid": false, 2025-10-08 15:28:06.278811 | controller | "mode": "0644", 2025-10-08 15:28:06.278825 | controller | "mtime": 1743545329.1409318, 2025-10-08 15:28:06.278839 | controller | "nlink": 1, 2025-10-08 15:28:06.278853 | controller | "path": "/home/zuul/crc-setup.log", 2025-10-08 15:28:06.278869 | controller | "pw_name": "zuul", 2025-10-08 15:28:06.278884 | controller | "rgrp": true, 2025-10-08 15:28:06.278899 | controller | "roth": true, 2025-10-08 15:28:06.278914 | controller | "rusr": true, 2025-10-08 15:28:06.278935 | controller | "size": 4108, 2025-10-08 15:28:06.278951 | controller | "uid": 1000, 2025-10-08 15:28:06.278966 | controller | "wgrp": false, 2025-10-08 15:28:06.278981 | controller | "woth": false, 2025-10-08 15:28:06.278998 | controller | "wusr": true, 2025-10-08 15:28:06.279014 | controller | "xgrp": false, 2025-10-08 15:28:06.279029 | controller | "xoth": false, 2025-10-08 15:28:06.279044 | controller | "xusr": false 2025-10-08 15:28:06.279059 | controller | } 2025-10-08 15:28:07.175768 | controller | changed: 2025-10-08 15:28:07.175969 | controller | { 2025-10-08 15:28:07.175996 | controller | "atime": 1759935929.0461504, 2025-10-08 15:28:07.176014 | controller | "ctime": 1759935926.3090794, 2025-10-08 15:28:07.176031 | controller | "dev": 64513, 2025-10-08 15:28:07.176046 | controller | "gid": 1000, 2025-10-08 15:28:07.176062 | controller | "gr_name": "zuul", 2025-10-08 15:28:07.176077 | controller | "inode": 4194437, 2025-10-08 15:28:07.176092 | controller | "isblk": false, 2025-10-08 15:28:07.176106 | controller | "ischr": false, 2025-10-08 15:28:07.176121 | controller | "isdir": false, 2025-10-08 15:28:07.176135 | controller | "isfifo": false, 2025-10-08 15:28:07.176150 | controller | "isgid": false, 2025-10-08 15:28:07.176164 | controller | "islnk": false, 2025-10-08 15:28:07.176179 | controller | "isreg": true, 2025-10-08 15:28:07.176194 | controller | "issock": false, 2025-10-08 15:28:07.176208 | controller | "isuid": false, 2025-10-08 15:28:07.176223 | controller | "mode": "0644", 2025-10-08 15:28:07.176237 | controller | "mtime": 1759935926.3090794, 2025-10-08 15:28:07.176252 | controller | "nlink": 1, 2025-10-08 15:28:07.176267 | controller | "path": "/home/zuul/crc-start.log", 2025-10-08 15:28:07.176281 | controller | "pw_name": "zuul", 2025-10-08 15:28:07.176297 | controller | "rgrp": true, 2025-10-08 15:28:07.176312 | controller | "roth": true, 2025-10-08 15:28:07.176326 | controller | "rusr": true, 2025-10-08 15:28:07.176341 | controller | "size": 3404, 2025-10-08 15:28:07.176356 | controller | "uid": 1000, 2025-10-08 15:28:07.176370 | controller | "wgrp": false, 2025-10-08 15:28:07.176384 | controller | "woth": false, 2025-10-08 15:28:07.176399 | controller | "wusr": true, 2025-10-08 15:28:07.176413 | controller | "xgrp": false, 2025-10-08 15:28:07.176427 | controller | "xoth": false, 2025-10-08 15:28:07.176442 | controller | "xusr": false 2025-10-08 15:28:07.176458 | controller | } 2025-10-08 15:28:07.933270 | controller | changed: 2025-10-08 15:28:07.933396 | controller | { 2025-10-08 15:28:07.933420 | controller | "atime": 1759936732.6786392, 2025-10-08 15:28:07.933590 | controller | "ctime": 1759936753.9421592, 2025-10-08 15:28:07.933619 | controller | "dev": 64513, 2025-10-08 15:28:07.933638 | controller | "gid": 1000, 2025-10-08 15:28:07.933654 | controller | "gr_name": "zuul", 2025-10-08 15:28:07.933692 | controller | "inode": 4685783, 2025-10-08 15:28:07.933710 | controller | "isblk": false, 2025-10-08 15:28:07.933726 | controller | "ischr": false, 2025-10-08 15:28:07.933742 | controller | "isdir": false, 2025-10-08 15:28:07.933758 | controller | "isfifo": false, 2025-10-08 15:28:07.933775 | controller | "isgid": false, 2025-10-08 15:28:07.933791 | controller | "islnk": false, 2025-10-08 15:28:07.933807 | controller | "isreg": true, 2025-10-08 15:28:07.933823 | controller | "issock": false, 2025-10-08 15:28:07.933839 | controller | "isuid": false, 2025-10-08 15:28:07.933855 | controller | "mode": "0644", 2025-10-08 15:28:07.933870 | controller | "mtime": 1759936753.9421592, 2025-10-08 15:28:07.933886 | controller | "nlink": 1, 2025-10-08 15:28:07.933902 | controller | "path": "/home/zuul/ansible.log", 2025-10-08 15:28:07.933919 | controller | "pw_name": "zuul", 2025-10-08 15:28:07.933936 | controller | "rgrp": true, 2025-10-08 15:28:07.933952 | controller | "roth": true, 2025-10-08 15:28:07.933972 | controller | "rusr": true, 2025-10-08 15:28:07.933989 | controller | "size": 6749, 2025-10-08 15:28:07.934013 | controller | "uid": 1000, 2025-10-08 15:28:07.934032 | controller | "wgrp": false, 2025-10-08 15:28:07.934053 | controller | "woth": false, 2025-10-08 15:28:07.934076 | controller | "wusr": true, 2025-10-08 15:28:07.934097 | controller | "xgrp": false, 2025-10-08 15:28:07.934118 | controller | "xoth": false, 2025-10-08 15:28:07.934134 | controller | "xusr": false 2025-10-08 15:28:07.934150 | controller | } 2025-10-08 15:28:07.950044 | 2025-10-08 15:28:07.950218 | TASK [Copy crio stats log file] 2025-10-08 15:28:07.976058 | controller | skipping: Conditional result was False 2025-10-08 15:28:07.982129 | 2025-10-08 15:28:07.982193 | TASK [Get SELinux related data] 2025-10-08 15:28:08.416615 | controller | 2025-10-08 15:28:08.526028 | controller | ERROR 2025-10-08 15:28:08.526151 | controller | { 2025-10-08 15:28:08.526200 | controller | "delta": "0:00:00.032064", 2025-10-08 15:28:08.526223 | controller | "end": "2025-10-08 15:28:08.419222", 2025-10-08 15:28:08.526240 | controller | "msg": "non-zero return code", 2025-10-08 15:28:08.526256 | controller | "rc": 1, 2025-10-08 15:28:08.526271 | controller | "start": "2025-10-08 15:28:08.387158" 2025-10-08 15:28:08.526287 | controller | } 2025-10-08 15:28:08.526310 | controller | ERROR: Ignoring Errors 2025-10-08 15:28:08.532046 | 2025-10-08 15:28:08.532108 | TASK [Create system configuration directory] 2025-10-08 15:28:08.871776 | controller | changed 2025-10-08 15:28:08.879701 | 2025-10-08 15:28:08.879795 | TASK [Get some of the system configurations] 2025-10-08 15:28:09.432607 | controller | changed 2025-10-08 15:28:09.445206 | 2025-10-08 15:28:09.445353 | TASK [Copy generated documentation if available] 2025-10-08 15:28:09.484207 | controller | skipping: Conditional result was False 2025-10-08 15:28:09.509111 | 2025-10-08 15:28:09.509240 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-08 15:28:09.547521 | controller | skipping: Conditional result was False 2025-10-08 15:28:09.555230 | 2025-10-08 15:28:09.555309 | TASK [Compress logs bigger than 2MB] 2025-10-08 15:28:10.094140 | controller | changed 2025-10-08 15:28:10.111199 | 2025-10-08 15:28:10.111331 | TASK [Copy files from workspace on node] 2025-10-08 15:28:10.138548 | controller | ok 2025-10-08 15:28:10.181870 | 2025-10-08 15:28:10.182001 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-08 15:28:10.207682 | controller | skipping: Conditional result was False 2025-10-08 15:28:10.214573 | 2025-10-08 15:28:10.214679 | TASK [fetch-output : Set log path for single node] 2025-10-08 15:28:10.266142 | controller | ok 2025-10-08 15:28:10.272756 | 2025-10-08 15:28:10.272821 | LOOP [fetch-output : Ensure local output dirs] 2025-10-08 15:28:10.553318 | controller -> localhost | ok: "/var/lib/zuul/builds/52c5c4b0566d45b989e1639655094bde/work/logs" 2025-10-08 15:28:10.553575 | controller -> localhost | changed: All items complete 2025-10-08 15:28:10.553602 | 2025-10-08 15:28:10.778440 | controller -> localhost | changed: "/var/lib/zuul/builds/52c5c4b0566d45b989e1639655094bde/work/artifacts" 2025-10-08 15:28:11.020740 | controller -> localhost | changed: "/var/lib/zuul/builds/52c5c4b0566d45b989e1639655094bde/work/docs" 2025-10-08 15:28:11.038062 | 2025-10-08 15:28:11.038233 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-08 15:28:11.953035 | controller | changed: 2025-10-08 15:28:11.953363 | controller | .d..t...... ./ 2025-10-08 15:28:11.953395 | controller | >f+++++++++ README.html 2025-10-08 15:28:11.953416 | controller | >f+++++++++ ansible-execution.log 2025-10-08 15:28:11.953436 | controller | >f+++++++++ ansible.log 2025-10-08 15:28:11.953454 | controller | >f+++++++++ crc-setup.log 2025-10-08 15:28:11.953472 | controller | >f+++++++++ crc-start.log 2025-10-08 15:28:11.953490 | controller | >f+++++++++ dmesg.log 2025-10-08 15:28:11.953510 | controller | >f+++++++++ installed-pkgs.log 2025-10-08 15:28:11.953529 | controller | >f+++++++++ python.log 2025-10-08 15:28:11.953561 | controller | >f+++++++++ registries.conf 2025-10-08 15:28:11.953585 | controller | >f+++++++++ report.html 2025-10-08 15:28:11.953603 | controller | >f+++++++++ selinux-denials.log 2025-10-08 15:28:11.953620 | controller | >f+++++++++ selinux-listing.log 2025-10-08 15:28:11.953637 | controller | cd+++++++++ ci-framework-data/ 2025-10-08 15:28:11.953653 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-08 15:28:11.953698 | controller | >f+++++++++ ci-framework-data/artifacts/networking-mapper-instances-facts.yml 2025-10-08 15:28:11.953718 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-08 15:28:11.953735 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/ 2025-10-08 15:28:11.953751 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/nmstate/ 2025-10-08 15:28:11.953768 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/crc-nncp.yaml 2025-10-08 15:28:11.953784 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/nmstate-nmstate-olm.yaml 2025-10-08 15:28:11.953802 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/nmstate-operatorgroup-olm.yaml 2025-10-08 15:28:11.953818 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/nmstate-subscription-olm.yaml 2025-10-08 15:28:11.953836 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-08 15:28:11.953854 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-10-08 15:28:11.953871 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/ 2025-10-08 15:28:11.953887 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5 2025-10-08 15:28:11.953903 | controller | cd+++++++++ ci-framework-data/logs/ 2025-10-08 15:28:11.953920 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log 2025-10-08 15:28:11.954125 | controller | cd+++++++++ registries.conf.d/ 2025-10-08 15:28:11.954152 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-10-08 15:28:11.954171 | controller | cd+++++++++ system-config/ 2025-10-08 15:28:11.954189 | controller | cd+++++++++ system-config/libvirt/ 2025-10-08 15:28:11.954206 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-10-08 15:28:11.954224 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-10-08 15:28:11.954240 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf 2025-10-08 15:28:11.954257 | controller | >f+++++++++ system-config/libvirt/network.conf 2025-10-08 15:28:11.954273 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf 2025-10-08 15:28:11.954290 | controller | >f+++++++++ system-config/libvirt/qemu.conf 2025-10-08 15:28:11.954307 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf 2025-10-08 15:28:11.954324 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf 2025-10-08 15:28:11.954340 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf 2025-10-08 15:28:11.954357 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf 2025-10-08 15:28:11.954373 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf 2025-10-08 15:28:11.954390 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf 2025-10-08 15:28:11.954406 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf 2025-10-08 15:28:11.954422 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf 2025-10-08 15:28:11.954437 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf 2025-10-08 15:28:11.954452 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf 2025-10-08 15:28:12.484460 | controller | changed: .d..t...... ./ 2025-10-08 15:28:13.071948 | controller | changed: .d..t...... ./ 2025-10-08 15:28:13.094483 | 2025-10-08 15:28:13.094618 | TASK [Return artifact to Zuul] 2025-10-08 15:28:13.134828 | controller | ok 2025-10-08 15:28:13.156625 | 2025-10-08 15:28:13.156782 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-08 15:28:13.156907 | 2025-10-08 15:28:13.156933 | PLAY RECAP 2025-10-08 15:28:13.156971 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1 2025-10-08 15:28:13.156990 | 2025-10-08 15:28:13.313690 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-08 15:28:13.318136 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master] 2025-10-08 15:28:13.958188 | 2025-10-08 15:28:13.958306 | PLAY [Report DLRN results after job run] 2025-10-08 15:28:13.978947 | 2025-10-08 15:28:13.979022 | TASK [Report job status to DLRN] 2025-10-08 15:28:13.998230 | controller | ok 2025-10-08 15:28:14.023203 | 2025-10-08 15:28:14.023289 | TASK [dlrn_report : Warning if DLRN is not running] 2025-10-08 15:28:14.088206 | controller | skipping: Conditional result was False 2025-10-08 15:28:14.094689 | 2025-10-08 15:28:14.094765 | TASK [dlrn_report : Install dlrnapi-client shyaml package] 2025-10-08 15:28:14.139998 | controller | skipping: Conditional result was False 2025-10-08 15:28:14.146589 | 2025-10-08 15:28:14.146660 | TASK [dlrn_report : Install kinit related package] 2025-10-08 15:28:14.191032 | controller | skipping: Conditional result was False 2025-10-08 15:28:14.197262 | 2025-10-08 15:28:14.197330 | TASK [dlrn_report : Install dlrn kerberos related packages] 2025-10-08 15:28:14.242200 | controller | skipping: Conditional result was False 2025-10-08 15:28:14.248715 | 2025-10-08 15:28:14.248786 | TASK [Set zuul-log-path fact] 2025-10-08 15:28:14.316956 | controller | skipping: Conditional result was False 2025-10-08 15:28:14.337099 | 2025-10-08 15:28:14.337182 | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used] 2025-10-08 15:28:14.392379 | controller | skipping: Conditional result was False 2025-10-08 15:28:14.398189 | 2025-10-08 15:28:14.398254 | TASK [Get hash related data from repo_setup role] 2025-10-08 15:28:14.442457 | controller | skipping: Conditional result was False 2025-10-08 15:28:14.449339 | 2025-10-08 15:28:14.449427 | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication] 2025-10-08 15:28:14.986255 | controller | skipping: Conditional result was False 2025-10-08 15:28:14.998986 | 2025-10-08 15:28:14.999127 | TASK [dlrn_report : Set empty value for dlrnapi password] 2025-10-08 15:28:15.056003 | controller | skipping: Conditional result was False 2025-10-08 15:28:15.061905 | 2025-10-08 15:28:15.061971 | TASK [dlrn_report : Report results to dlrn for the tested hash] 2025-10-08 15:28:15.590964 | controller | skipping: Conditional result was False 2025-10-08 15:28:15.614383 | 2025-10-08 15:28:15.614439 | PLAY RECAP 2025-10-08 15:28:15.614480 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 0 2025-10-08 15:28:15.614500 | 2025-10-08 15:28:15.705373 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master] 2025-10-08 15:28:15.706793 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-08 15:28:16.303859 | 2025-10-08 15:28:16.303992 | PLAY [all] 2025-10-08 15:28:16.323388 | 2025-10-08 15:28:16.323468 | TASK [include_role : fetch-output] 2025-10-08 15:28:16.362764 | controller | ok 2025-10-08 15:28:16.381261 | 2025-10-08 15:28:16.381347 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-08 15:28:16.426882 | controller | skipping: Conditional result was False 2025-10-08 15:28:16.435033 | 2025-10-08 15:28:16.435112 | TASK [fetch-output : Set log path for single node] 2025-10-08 15:28:16.484804 | controller | ok 2025-10-08 15:28:16.490069 | 2025-10-08 15:28:16.490136 | LOOP [fetch-output : Ensure local output dirs] 2025-10-08 15:28:16.893353 | controller -> localhost | ok: "/var/lib/zuul/builds/52c5c4b0566d45b989e1639655094bde/work/logs" 2025-10-08 15:28:17.138546 | controller -> localhost | ok: "/var/lib/zuul/builds/52c5c4b0566d45b989e1639655094bde/work/artifacts" 2025-10-08 15:28:17.381441 | controller -> localhost | ok: "/var/lib/zuul/builds/52c5c4b0566d45b989e1639655094bde/work/docs" 2025-10-08 15:28:17.398518 | 2025-10-08 15:28:17.398659 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-08 15:28:18.217168 | controller | ok 2025-10-08 15:28:18.217451 | controller | ok: All items complete 2025-10-08 15:28:18.217580 | 2025-10-08 15:28:18.801148 | controller | ok 2025-10-08 15:28:19.351420 | controller | ok 2025-10-08 15:28:19.369922 | 2025-10-08 15:28:19.370062 | TASK [include_role : fetch-output-openshift] 2025-10-08 15:28:19.394134 | controller | skipping: Conditional result was False 2025-10-08 15:28:19.404574 | 2025-10-08 15:28:19.404750 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-08 15:28:19.845686 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008538 2025-10-08 15:28:20.076924 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.023100 2025-10-08 15:28:20.110768 | 2025-10-08 15:28:20.110924 | PLAY [all] 2025-10-08 15:28:20.125762 | 2025-10-08 15:28:20.125831 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-08 15:28:20.713722 | controller | changed 2025-10-08 15:28:20.737021 | 2025-10-08 15:28:20.737076 | PLAY RECAP 2025-10-08 15:28:20.737117 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-08 15:28:20.737138 | 2025-10-08 15:28:20.916022 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-08 15:28:20.920335 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-08 15:28:21.599143 | 2025-10-08 15:28:21.599270 | PLAY [localhost] 2025-10-08 15:28:21.617148 | 2025-10-08 15:28:21.617226 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-08 15:28:22.017914 | localhost | changed 2025-10-08 15:28:22.022611 | 2025-10-08 15:28:22.022720 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-08 15:28:22.051395 | localhost | ok 2025-10-08 15:28:22.062328 | 2025-10-08 15:28:22.062402 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-08 15:28:22.397515 | localhost | changed 2025-10-08 15:28:22.403428 | 2025-10-08 15:28:22.403496 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-08 15:28:23.071284 | localhost | changed 2025-10-08 15:28:23.076602 | 2025-10-08 15:28:23.076693 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-08 15:28:23.507185 | localhost | Identity added: /var/lib/zuul/builds/52c5c4b0566d45b989e1639655094bde/work/tmp/ansible.qw_eoidq (/var/lib/zuul/builds/52c5c4b0566d45b989e1639655094bde/work/tmp/ansible.qw_eoidq) 2025-10-08 15:28:23.507418 | localhost | ok: Runtime: 0:00:00.009702 2025-10-08 15:28:23.513221 | 2025-10-08 15:28:23.514962 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-08 15:28:23.794026 | localhost | ok: Runtime: 0:00:00.005396 2025-10-08 15:28:23.800846 | 2025-10-08 15:28:23.800925 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-08 15:28:23.849595 | localhost | changed 2025-10-08 15:28:23.853961 | 2025-10-08 15:28:23.854028 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-08 15:28:24.251889 | localhost | changed 2025-10-08 15:28:24.273183 | 2025-10-08 15:28:24.273260 | PLAY [localhost] 2025-10-08 15:28:24.286024 | 2025-10-08 15:28:24.286088 | TASK [Generate bulk log download script] 2025-10-08 15:28:24.305795 | localhost | ok 2025-10-08 15:28:24.318591 | 2025-10-08 15:28:24.318748 | TASK [local-log-download : Check API endpoint is defined] 2025-10-08 15:28:24.347006 | localhost | ok: All assertions passed 2025-10-08 15:28:24.351754 | 2025-10-08 15:28:24.351816 | TASK [local-log-download : Create download script] 2025-10-08 15:28:24.760946 | localhost -> localhost | changed 2025-10-08 15:28:24.770442 | 2025-10-08 15:28:24.770513 | TASK [Register quick-download link] 2025-10-08 15:28:24.799233 | localhost | ok 2025-10-08 15:28:24.855977 | 2025-10-08 15:28:24.856163 | PLAY [logserver.rdoproject.org] 2025-10-08 15:28:24.872579 | 2025-10-08 15:28:24.872716 | TASK [Set zuul-log-path fact] 2025-10-08 15:28:24.890015 | logserver.rdoproject.org | ok 2025-10-08 15:28:24.900153 | 2025-10-08 15:28:24.900237 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-08 15:28:24.926707 | logserver.rdoproject.org | ok 2025-10-08 15:28:24.932122 | 2025-10-08 15:28:24.932200 | TASK [upload-logs : Create log directories] 2025-10-08 15:28:25.562493 | logserver.rdoproject.org | changed 2025-10-08 15:28:25.567567 | 2025-10-08 15:28:25.567632 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-08 15:28:25.839399 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.011732 2025-10-08 15:28:25.844446 | 2025-10-08 15:28:25.844510 | TASK [upload-logs : Upload logs to log server] 2025-10-08 15:28:26.560497 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-08 15:28:26.566812 | 2025-10-08 15:28:26.566890 | LOOP [upload-logs : Compress console log and json output] 2025-10-08 15:28:26.612825 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-08 15:28:26.620658 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-08 15:28:26.625631 | 2025-10-08 15:28:26.625818 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-08 15:28:26.669678 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-08 15:28:26.669978 | 2025-10-08 15:28:26.673419 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-08 15:28:26.685355 | 2025-10-08 15:28:26.685634 | LOOP [upload-logs : Upload console log and json output]