2025-10-03 14:29:17.826052 | Job console starting... 2025-10-03 14:29:17.834884 | Updating repositories 2025-10-03 14:29:17.867360 | Preparing job workspace 2025-10-03 14:29:25.388524 | Running Ansible setup... 2025-10-03 14:29:32.533356 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-03 14:29:33.252349 | 2025-10-03 14:29:33.252474 | PLAY [localhost] 2025-10-03 14:29:33.261232 | 2025-10-03 14:29:33.261301 | TASK [Gathering Facts] 2025-10-03 14:29:34.388099 | localhost | ok 2025-10-03 14:29:34.408974 | 2025-10-03 14:29:34.409175 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-03 14:29:34.920242 | localhost -> localhost | changed 2025-10-03 14:29:34.926925 | 2025-10-03 14:29:34.926997 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-03 14:29:36.119598 | localhost -> localhost | changed 2025-10-03 14:29:36.137168 | 2025-10-03 14:29:36.137398 | TASK [Setup log path fact] 2025-10-03 14:29:36.178600 | localhost | ok 2025-10-03 14:29:36.222256 | 2025-10-03 14:29:36.222970 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 14:29:36.279880 | localhost | ok 2025-10-03 14:29:36.296660 | 2025-10-03 14:29:36.296858 | TASK [emit-job-header : Print job information] 2025-10-03 14:29:36.337615 | # Job Information 2025-10-03 14:29:36.337845 | Ansible Version: 2.15.12 2025-10-03 14:29:36.337872 | Job: cifmw-molecule-ci_multus 2025-10-03 14:29:36.337891 | Pipeline: github-check 2025-10-03 14:29:36.337909 | Executor: ze02.softwarefactory-project.io 2025-10-03 14:29:36.337927 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3366 2025-10-03 14:29:36.337946 | Log URL (when completed): https://logserver.rdoproject.org/5a7/rdoproject.org/5a740235803e4157b5655e2be72ca944/ 2025-10-03 14:29:36.337964 | Event ID: 2534be40-a065-11f0-8cb8-613a1bcbea70 2025-10-03 14:29:36.342642 | 2025-10-03 14:29:36.342745 | LOOP [emit-job-header : Print node information] 2025-10-03 14:29:36.461851 | localhost | ok: 2025-10-03 14:29:36.462058 | localhost | # Node Information 2025-10-03 14:29:36.462085 | localhost | Inventory Hostname: controller 2025-10-03 14:29:36.462107 | localhost | Hostname: np0005469654 2025-10-03 14:29:36.462126 | localhost | Username: zuul 2025-10-03 14:29:36.462146 | localhost | Distro: CentOS 9 2025-10-03 14:29:36.462164 | localhost | Provider: vexxhost-nodepool-tripleo 2025-10-03 14:29:36.462183 | localhost | Region: RegionOne 2025-10-03 14:29:36.462202 | localhost | Label: centos-9-stream-crc-2-48-0-xxl 2025-10-03 14:29:36.462219 | localhost | Product Name: OpenStack Nova 2025-10-03 14:29:36.462235 | localhost | Interface IP: 38.102.83.130 2025-10-03 14:29:36.490769 | 2025-10-03 14:29:36.490916 | PLAY [all] 2025-10-03 14:29:36.498754 | 2025-10-03 14:29:36.499574 | TASK [Gather network facts] 2025-10-03 14:29:37.080414 | controller | ok 2025-10-03 14:29:37.122127 | 2025-10-03 14:29:37.122298 | TASK [include_role : start-zuul-console] 2025-10-03 14:29:37.162116 | controller | ok 2025-10-03 14:29:37.188825 | 2025-10-03 14:29:37.188983 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-03 14:29:37.728841 | controller | ok 2025-10-03 14:29:37.738128 | 2025-10-03 14:29:37.738209 | TASK [include_role : add-build-sshkey] 2025-10-03 14:29:37.789625 | controller | ok 2025-10-03 14:29:37.822462 | 2025-10-03 14:29:37.822590 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-03 14:29:38.163153 | controller -> localhost | ok 2025-10-03 14:29:38.169575 | 2025-10-03 14:29:38.169642 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-03 14:29:38.218395 | controller | ok 2025-10-03 14:29:38.245380 | controller | included: /var/lib/zuul/builds/5a740235803e4157b5655e2be72ca944/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-03 14:29:38.261133 | 2025-10-03 14:29:38.261216 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-03 14:29:39.195204 | controller -> localhost | Generating public/private rsa key pair. 2025-10-03 14:29:39.195460 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5a740235803e4157b5655e2be72ca944/work/5a740235803e4157b5655e2be72ca944_id_rsa. 2025-10-03 14:29:39.195492 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5a740235803e4157b5655e2be72ca944/work/5a740235803e4157b5655e2be72ca944_id_rsa.pub. 2025-10-03 14:29:39.195514 | controller -> localhost | The key fingerprint is: 2025-10-03 14:29:39.195533 | controller -> localhost | SHA256:Df79DCfx1kL95uH+SRPopjns2lqaR5LrozDFvaQnmT8 zuul-build-sshkey 2025-10-03 14:29:39.195551 | controller -> localhost | The key's randomart image is: 2025-10-03 14:29:39.195569 | controller -> localhost | +---[RSA 3072]----+ 2025-10-03 14:29:39.195586 | controller -> localhost | | | 2025-10-03 14:29:39.195604 | controller -> localhost | | | 2025-10-03 14:29:39.195620 | controller -> localhost | | . | 2025-10-03 14:29:39.195641 | controller -> localhost | | ...o .. | 2025-10-03 14:29:39.195689 | controller -> localhost | | oSoo .....| 2025-10-03 14:29:39.195720 | controller -> localhost | | . =+.o.+ .o| 2025-10-03 14:29:39.195740 | controller -> localhost | | o = o*.+o=++| 2025-10-03 14:29:39.195757 | controller -> localhost | | o +E=++Bo++| 2025-10-03 14:29:39.195774 | controller -> localhost | | .oBB=. +++| 2025-10-03 14:29:39.195790 | controller -> localhost | +----[SHA256]-----+ 2025-10-03 14:29:39.195842 | controller -> localhost | ok: Runtime: 0:00:00.421806 2025-10-03 14:29:39.203164 | 2025-10-03 14:29:39.203241 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-03 14:29:39.235823 | controller | ok 2025-10-03 14:29:39.262131 | controller | included: /var/lib/zuul/builds/5a740235803e4157b5655e2be72ca944/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-03 14:29:39.270975 | 2025-10-03 14:29:39.271049 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-03 14:29:39.306429 | controller | skipping: Conditional result was False 2025-10-03 14:29:39.313911 | 2025-10-03 14:29:39.314014 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-03 14:29:40.239743 | controller | changed 2025-10-03 14:29:40.257952 | 2025-10-03 14:29:40.258154 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-03 14:29:40.658615 | controller | ok 2025-10-03 14:29:40.672243 | 2025-10-03 14:29:40.672395 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-03 14:29:42.213030 | controller | changed 2025-10-03 14:29:42.227624 | 2025-10-03 14:29:42.227820 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-03 14:29:43.797004 | controller | changed 2025-10-03 14:29:43.804885 | 2025-10-03 14:29:43.804970 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-03 14:29:43.831906 | controller | skipping: Conditional result was False 2025-10-03 14:29:43.842339 | 2025-10-03 14:29:43.842415 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-03 14:29:44.317115 | controller -> localhost | changed 2025-10-03 14:29:44.336448 | 2025-10-03 14:29:44.336532 | TASK [add-build-sshkey : Add back temp key] 2025-10-03 14:29:44.660563 | controller -> localhost | Identity added: /var/lib/zuul/builds/5a740235803e4157b5655e2be72ca944/work/5a740235803e4157b5655e2be72ca944_id_rsa (zuul-build-sshkey) 2025-10-03 14:29:44.660854 | controller -> localhost | ok: Runtime: 0:00:00.013175 2025-10-03 14:29:44.667918 | 2025-10-03 14:29:44.668014 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-03 14:29:45.169170 | controller | ok 2025-10-03 14:29:45.195555 | 2025-10-03 14:29:45.195756 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-03 14:29:45.461679 | controller | skipping: Conditional result was False 2025-10-03 14:29:45.471232 | 2025-10-03 14:29:45.471296 | TASK [include_role : validate-host] 2025-10-03 14:29:45.493309 | controller | ok 2025-10-03 14:29:45.520420 | 2025-10-03 14:29:45.520566 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-03 14:29:45.551369 | controller | ok 2025-10-03 14:29:45.556740 | 2025-10-03 14:29:45.556808 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-03 14:29:45.811620 | controller -> localhost | ok 2025-10-03 14:29:45.825381 | 2025-10-03 14:29:45.825656 | TASK [validate-host : Collect information about the host] 2025-10-03 14:29:46.755651 | controller | ok 2025-10-03 14:29:46.773608 | 2025-10-03 14:29:46.773855 | TASK [validate-host : Sanitize hostname] 2025-10-03 14:29:46.853925 | controller | ok 2025-10-03 14:29:46.870501 | 2025-10-03 14:29:46.870715 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-03 14:29:47.486676 | controller -> localhost | changed 2025-10-03 14:29:47.493970 | 2025-10-03 14:29:47.494105 | TASK [validate-host : Collect information about zuul worker] 2025-10-03 14:29:48.007869 | controller | ok 2025-10-03 14:29:48.013088 | 2025-10-03 14:29:48.013162 | TASK [validate-host : Write out all zuul information for each host] 2025-10-03 14:29:48.465941 | controller -> localhost | changed 2025-10-03 14:29:48.477207 | 2025-10-03 14:29:48.477330 | TASK [include_role : prepare-workspace-openshift] 2025-10-03 14:29:48.502529 | controller | skipping: Conditional result was False 2025-10-03 14:29:48.509752 | 2025-10-03 14:29:48.509853 | TASK [include_role : remove-zuul-sshkey] 2025-10-03 14:29:48.535585 | controller | skipping: Conditional result was False 2025-10-03 14:29:48.542356 | 2025-10-03 14:29:48.542424 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-03 14:29:48.902660 | controller | ok: "logs" 2025-10-03 14:29:48.902959 | controller | ok: All items complete 2025-10-03 14:29:48.902989 | 2025-10-03 14:29:49.244578 | controller | ok: "artifacts" 2025-10-03 14:29:49.600635 | controller | ok: "docs" 2025-10-03 14:29:49.610143 | 2025-10-03 14:29:49.610317 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-03 14:29:49.963218 | controller | changed: "logs" 2025-10-03 14:29:50.272785 | controller | changed: "artifacts" 2025-10-03 14:29:50.584122 | controller | changed: "docs" 2025-10-03 14:29:50.611968 | 2025-10-03 14:29:50.612112 | PLAY RECAP 2025-10-03 14:29:50.612164 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-03 14:29:50.612190 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 14:29:50.612209 | 2025-10-03 14:29:50.792549 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-03 14:29:50.793483 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-03 14:29:51.555020 | 2025-10-03 14:29:51.555268 | PLAY [all] 2025-10-03 14:29:51.578052 | 2025-10-03 14:29:51.578210 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-03 14:29:51.646928 | controller | ok 2025-10-03 14:29:51.653387 | 2025-10-03 14:29:51.653465 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-03 14:29:52.384131 | controller | changed 2025-10-03 14:29:52.390580 | 2025-10-03 14:29:52.390660 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-03 14:29:54.238544 | controller | changed 2025-10-03 14:29:54.255739 | 2025-10-03 14:29:54.255826 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-03 14:29:54.990898 | controller | changed: 2025-10-03 14:29:54.991119 | controller | { 2025-10-03 14:29:54.991145 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-03 14:29:54.991179 | controller | } 2025-10-03 14:29:55.393231 | controller | changed: 2025-10-03 14:29:55.393379 | controller | { 2025-10-03 14:29:55.393407 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-03 14:29:55.393428 | controller | } 2025-10-03 14:29:55.788467 | controller | changed: 2025-10-03 14:29:55.788600 | controller | { 2025-10-03 14:29:55.788624 | 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-03 14:29:55.788643 | controller | } 2025-10-03 14:29:56.183017 | controller | changed: 2025-10-03 14:29:56.183336 | controller | { 2025-10-03 14:29:56.183378 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-03 14:29:56.183406 | controller | } 2025-10-03 14:29:56.591153 | controller | changed: 2025-10-03 14:29:56.591312 | controller | { 2025-10-03 14:29:56.591348 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-03 14:29:56.591378 | controller | } 2025-10-03 14:29:57.002164 | controller | changed: 2025-10-03 14:29:57.002594 | controller | { 2025-10-03 14:29:57.002686 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-03 14:29:57.003043 | controller | } 2025-10-03 14:29:57.375613 | controller | changed: 2025-10-03 14:29:57.375821 | controller | { 2025-10-03 14:29:57.375864 | 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-03 14:29:57.375885 | controller | } 2025-10-03 14:29:57.843347 | controller | changed: 2025-10-03 14:29:57.843450 | controller | { 2025-10-03 14:29:57.843475 | 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-03 14:29:57.843495 | controller | } 2025-10-03 14:29:58.136598 | controller | changed: 2025-10-03 14:29:58.136758 | controller | { 2025-10-03 14:29:58.136835 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-03 14:29:58.136858 | controller | } 2025-10-03 14:29:58.550728 | controller | changed: 2025-10-03 14:29:58.550871 | controller | { 2025-10-03 14:29:58.550895 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-03 14:29:58.550913 | controller | } 2025-10-03 14:29:58.927372 | controller | changed: 2025-10-03 14:29:58.927511 | controller | { 2025-10-03 14:29:58.927534 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-03 14:29:58.927552 | controller | } 2025-10-03 14:29:59.369115 | controller | changed: 2025-10-03 14:29:59.369298 | controller | { 2025-10-03 14:29:59.369332 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-03 14:29:59.369360 | controller | } 2025-10-03 14:29:59.775723 | controller | changed: 2025-10-03 14:29:59.775856 | controller | { 2025-10-03 14:29:59.775879 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-03 14:29:59.775898 | controller | } 2025-10-03 14:30:00.171384 | controller | changed: 2025-10-03 14:30:00.171501 | controller | { 2025-10-03 14:30:00.171524 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-03 14:30:00.171543 | controller | } 2025-10-03 14:30:00.542392 | controller | changed: 2025-10-03 14:30:00.542531 | controller | { 2025-10-03 14:30:00.542555 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-03 14:30:00.542573 | controller | } 2025-10-03 14:30:00.979305 | controller | changed: 2025-10-03 14:30:00.979451 | controller | { 2025-10-03 14:30:00.979474 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-03 14:30:00.979492 | controller | } 2025-10-03 14:30:01.380998 | controller | changed: 2025-10-03 14:30:01.381134 | controller | { 2025-10-03 14:30:01.381157 | 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-03 14:30:01.381176 | controller | } 2025-10-03 14:30:01.786071 | controller | changed: 2025-10-03 14:30:01.787188 | controller | { 2025-10-03 14:30:01.787239 | 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-03 14:30:01.787267 | controller | } 2025-10-03 14:30:02.158021 | controller | changed: 2025-10-03 14:30:02.158182 | controller | { 2025-10-03 14:30:02.158215 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-03 14:30:02.158242 | controller | } 2025-10-03 14:30:02.552405 | controller | changed: 2025-10-03 14:30:02.552544 | controller | { 2025-10-03 14:30:02.552567 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-03 14:30:02.552586 | controller | } 2025-10-03 14:30:02.947445 | controller | changed: 2025-10-03 14:30:02.947751 | controller | { 2025-10-03 14:30:02.947791 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-03 14:30:02.947812 | controller | } 2025-10-03 14:30:03.294176 | controller | changed: 2025-10-03 14:30:03.294329 | controller | { 2025-10-03 14:30:03.294353 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-03 14:30:03.294371 | controller | } 2025-10-03 14:30:03.704999 | controller | changed: 2025-10-03 14:30:03.705136 | controller | { 2025-10-03 14:30:03.705160 | 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-03 14:30:03.705179 | controller | } 2025-10-03 14:30:04.107371 | controller | changed: 2025-10-03 14:30:04.107598 | controller | { 2025-10-03 14:30:04.107625 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-03 14:30:04.107718 | controller | } 2025-10-03 14:30:04.488185 | controller | changed: 2025-10-03 14:30:04.488325 | controller | { 2025-10-03 14:30:04.488352 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-03 14:30:04.488370 | controller | } 2025-10-03 14:30:04.874917 | controller | changed: 2025-10-03 14:30:04.875152 | controller | { 2025-10-03 14:30:04.875227 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-03 14:30:04.875290 | controller | } 2025-10-03 14:30:04.905595 | 2025-10-03 14:30:04.905773 | TASK [Set timezone to UTC] 2025-10-03 14:30:05.691952 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-03 14:30:05.702491 | 2025-10-03 14:30:05.702631 | TASK [Create nodepool directory] 2025-10-03 14:30:06.092040 | controller | changed 2025-10-03 14:30:06.103573 | 2025-10-03 14:30:06.103746 | TASK [Create nodepool sub_nodes file] 2025-10-03 14:30:07.524942 | controller | changed 2025-10-03 14:30:07.535869 | 2025-10-03 14:30:07.536011 | TASK [Create nodepool sub_nodes_private file] 2025-10-03 14:30:08.952833 | controller | changed 2025-10-03 14:30:08.961690 | 2025-10-03 14:30:08.961782 | LOOP [Populate nodepool sub_nodes file] 2025-10-03 14:30:09.031920 | 2025-10-03 14:30:09.032304 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-03 14:30:09.122011 | 2025-10-03 14:30:09.124100 | TASK [Create nodepool primary file] 2025-10-03 14:30:09.185850 | controller | skipping: Conditional result was False 2025-10-03 14:30:09.194948 | 2025-10-03 14:30:09.195073 | TASK [Create nodepool node_private for this node] 2025-10-03 14:30:10.626842 | controller | changed 2025-10-03 14:30:10.637487 | 2025-10-03 14:30:10.637576 | LOOP [Copy ssh keys to nodepool directory] 2025-10-03 14:30:11.159196 | controller | ok: Item: id_rsa Runtime: 0:00:00.008880 2025-10-03 14:30:11.159405 | 2025-10-03 14:30:11.496811 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.009218 2025-10-03 14:30:11.516018 | 2025-10-03 14:30:11.516155 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-03 14:30:12.898224 | controller | changed 2025-10-03 14:30:12.908091 | 2025-10-03 14:30:12.908223 | TASK [Validate sudoers config after edits] 2025-10-03 14:30:13.327362 | controller | /etc/sudoers: parsed OK 2025-10-03 14:30:13.327501 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-03 14:30:13.327515 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-03 14:30:13.327523 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-03 14:30:13.461732 | controller | ok: Runtime: 0:00:00.009537 2025-10-03 14:30:13.469909 | 2025-10-03 14:30:13.470068 | TASK [Show the environment passed in to job shell scripts] 2025-10-03 14:30:13.902658 | controller | SHELL=/bin/bash 2025-10-03 14:30:13.902750 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-03 14:30:13.902771 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-03 14:30:13.902789 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/66/3366/b916ef9cc83cd6f61cb4c8a27bbc55fc27d7eaae 2025-10-03 14:30:13.902805 | controller | PWD=/home/zuul 2025-10-03 14:30:13.902823 | controller | ZUUL_PIPELINE=github-check 2025-10-03 14:30:13.903009 | controller | LOGNAME=zuul 2025-10-03 14:30:13.903036 | controller | XDG_SESSION_TYPE=tty 2025-10-03 14:30:13.903054 | controller | _=/usr/bin/env 2025-10-03 14:30:13.903067 | controller | MOTD_SHOWN=pam 2025-10-03 14:30:13.903080 | controller | HOME=/home/zuul 2025-10-03 14:30:13.903093 | controller | LANG=en_US.UTF-8 2025-10-03 14:30:13.903106 | controller | SSH_CONNECTION=38.102.83.114 52428 38.102.83.130 22 2025-10-03 14:30:13.903120 | 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-03 14:30:13.903141 | controller | ZUUL_CHANGE_IDS=3366,b916ef9cc83cd6f61cb4c8a27bbc55fc27d7eaae 2025-10-03 14:30:13.903154 | controller | WORKSPACE=/home/zuul/workspace 2025-10-03 14:30:13.903168 | controller | XDG_SESSION_CLASS=user 2025-10-03 14:30:13.903181 | controller | SELINUX_ROLE_REQUESTED= 2025-10-03 14:30:13.903194 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-03 14:30:13.903207 | controller | USER=zuul 2025-10-03 14:30:13.903220 | controller | ZUUL_VOTING=True 2025-10-03 14:30:13.903234 | controller | BUILD_TIMEOUT=1800000 2025-10-03 14:30:13.903247 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-03 14:30:13.903260 | controller | SHLVL=1 2025-10-03 14:30:13.903273 | controller | ZUUL_PATCHSET=b916ef9cc83cd6f61cb4c8a27bbc55fc27d7eaae 2025-10-03 14:30:13.903286 | controller | XDG_SESSION_ID=1 2025-10-03 14:30:13.903299 | controller | ZUUL_BRANCH=main 2025-10-03 14:30:13.903312 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-03 14:30:13.903326 | controller | SSH_CLIENT=38.102.83.114 52428 22 2025-10-03 14:30:13.903340 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-03 14:30:13.903353 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-03 14:30:13.903366 | controller | which_declare=declare -f 2025-10-03 14:30:13.903381 | 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-03 14:30:13.903397 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-03 14:30:13.903410 | controller | ZUUL_CHANGE=3366 2025-10-03 14:30:13.903423 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-03 14:30:13.903436 | controller | ZUUL_UUID=5a740235803e4157b5655e2be72ca944 2025-10-03 14:30:13.903486 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-03 14:30:13.903500 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-03 14:30:13.903513 | controller | } 2025-10-03 14:30:14.047930 | controller | ok: Runtime: 0:00:00.010147 2025-10-03 14:30:14.054331 | 2025-10-03 14:30:14.054397 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-03 14:30:14.099796 | controller | skipping: Conditional result was False 2025-10-03 14:30:14.106952 | 2025-10-03 14:30:14.107054 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-03 14:30:14.662376 | controller | skipping: Conditional result was False 2025-10-03 14:30:14.668783 | 2025-10-03 14:30:14.668879 | TASK [Ensure legacy workspace directory] 2025-10-03 14:30:14.996120 | controller | changed 2025-10-03 14:30:15.032540 | 2025-10-03 14:30:15.032723 | PLAY RECAP 2025-10-03 14:30:15.032779 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-03 14:30:15.032801 | 2025-10-03 14:30:15.255436 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-03 14:30:15.256278 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master] 2025-10-03 14:30:15.999795 | 2025-10-03 14:30:15.999927 | PLAY [all,!compute] 2025-10-03 14:30:16.035192 | 2025-10-03 14:30:16.035393 | TASK [Enable container-tools rhel-modules for c8/crc node] 2025-10-03 14:30:16.588259 | controller | skipping: Conditional result was False 2025-10-03 14:30:16.594648 | 2025-10-03 14:30:16.594758 | TASK [Install podman] 2025-10-03 14:30:16.670273 | controller | skipping: Conditional result was False 2025-10-03 14:30:16.676971 | 2025-10-03 14:30:16.677051 | TASK [Set var name for quay login zuul secret] 2025-10-03 14:30:16.771099 | controller | skipping: Conditional result was False 2025-10-03 14:30:16.778853 | 2025-10-03 14:30:16.779009 | TASK [Print the username] 2025-10-03 14:30:17.315235 | controller | skipping: Conditional result was False 2025-10-03 14:30:17.324540 | 2025-10-03 14:30:17.324782 | TASK [Fail when user or password is not set] 2025-10-03 14:30:17.374289 | controller | skipping: Conditional result was False 2025-10-03 14:30:17.380640 | 2025-10-03 14:30:17.380773 | TASK [Set vars for quay login] 2025-10-03 14:30:17.433949 | 2025-10-03 14:30:17.434149 | LOOP [Perform container registry login(s) with podman] 2025-10-03 14:30:17.490577 | 2025-10-03 14:30:17.490809 | LOOP [Perform container registry login(s) with buildah] 2025-10-03 14:30:17.546512 | 2025-10-03 14:30:17.546760 | TASK [Set Insecure registry for content provider] 2025-10-03 14:30:17.591025 | controller | skipping: Conditional result was False 2025-10-03 14:30:17.613285 | 2025-10-03 14:30:17.613401 | PLAY RECAP 2025-10-03 14:30:17.613445 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 9 rescued: 0 ignored: 0 2025-10-03 14:30:17.613465 | 2025-10-03 14:30:17.759893 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master] 2025-10-03 14:30:17.760791 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master] 2025-10-03 14:30:18.360807 | 2025-10-03 14:30:18.360985 | PLAY [Prefer ipv4 over ipv6] 2025-10-03 14:30:18.383536 | 2025-10-03 14:30:18.383714 | TASK [Configure /etc/gai.conf to prever ipv4 over ipv6] 2025-10-03 14:30:18.471399 | controller | skipping: Conditional result was False 2025-10-03 14:30:18.495800 | 2025-10-03 14:30:18.495954 | PLAY [controller] 2025-10-03 14:30:18.515901 | 2025-10-03 14:30:18.516080 | TASK [Fail when openshift_pull_sec not provided] 2025-10-03 14:30:18.545536 | controller | skipping: Conditional result was False 2025-10-03 14:30:18.558223 | 2025-10-03 14:30:18.558429 | TASK [Clone repos in the job workspace] 2025-10-03 14:30:18.601558 | controller | ok 2025-10-03 14:30:18.635714 | 2025-10-03 14:30:18.635923 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-03 14:30:19.124531 | controller | ok 2025-10-03 14:30:19.145914 | 2025-10-03 14:30:19.146077 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-03 14:30:22.710528 | controller | Output suppressed because no_log was given 2025-10-03 14:30:22.720794 | 2025-10-03 14:30:22.720876 | TASK [Create pull-secret.txt file] 2025-10-03 14:30:24.467818 | controller | Output suppressed because no_log was given 2025-10-03 14:30:24.473489 | 2025-10-03 14:30:24.473564 | TASK [Apply CRC features and print image build date] 2025-10-03 14:30:24.507269 | controller | ok 2025-10-03 14:30:24.517948 | controller | included: /var/lib/zuul/builds/5a740235803e4157b5655e2be72ca944/trusted/project_0/review.rdoproject.org/config/playbooks/crc/features.yaml 2025-10-03 14:30:24.533517 | 2025-10-03 14:30:24.533651 | TASK [Set additional parameters for crc - telemetry] 2025-10-03 14:30:25.116993 | controller | skipping: Conditional result was False 2025-10-03 14:30:25.127198 | 2025-10-03 14:30:25.127327 | TASK [Set additional parameters for crc - monitoring] 2025-10-03 14:30:25.658259 | controller | skipping: Conditional result was False 2025-10-03 14:30:25.668165 | 2025-10-03 14:30:25.668293 | TASK [Set minimum required memory to run the CRC when monitoring enabled] 2025-10-03 14:30:26.208850 | controller | skipping: Conditional result was False 2025-10-03 14:30:26.224982 | 2025-10-03 14:30:26.225206 | TASK [Set additional parameters for crc - enable microshift preset] 2025-10-03 14:30:26.785486 | controller | skipping: Conditional result was False 2025-10-03 14:30:26.798056 | 2025-10-03 14:30:26.798155 | TASK [Set additional parameters for crc - enable okd preset] 2025-10-03 14:30:27.350759 | controller | skipping: Conditional result was False 2025-10-03 14:30:27.363096 | 2025-10-03 14:30:27.363223 | TASK [Delete the cluster if there's a non-default bundle URL] 2025-10-03 14:30:27.919573 | controller | skipping: Conditional result was False 2025-10-03 14:30:27.933305 | 2025-10-03 14:30:27.933476 | TASK [Execute crc start command] 2025-10-03 14:34:25.910959 | controller | ok: Runtime: 0:03:57.226945 2025-10-03 14:34:25.924185 | 2025-10-03 14:34:25.924322 | TASK [Verify that OpenShift is up and running] 2025-10-03 14:34:25.954393 | controller | ok 2025-10-03 14:34:25.965988 | controller | included: /var/lib/zuul/builds/5a740235803e4157b5655e2be72ca944/trusted/project_0/review.rdoproject.org/config/playbooks/crc/verify_openshift_start.yaml 2025-10-03 14:34:25.974794 | 2025-10-03 14:34:25.974880 | TASK [Create script to login and verify services] 2025-10-03 14:34:27.532798 | controller | changed 2025-10-03 14:34:27.545233 | 2025-10-03 14:34:27.545397 | TASK [Ensure that user is logged] 2025-10-03 14:34:27.928914 | controller | Checking login to the cluster 1... 2025-10-03 14:34:29.070805 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:34:29.123948 | controller | 2025-10-03 14:34:29.124049 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-03 14:34:29.130033 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:34:34.131700 | controller | Checking login to the cluster 2... 2025-10-03 14:34:34.248483 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:34:34.274768 | controller | 2025-10-03 14:34:34.274907 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-03 14:34:34.279507 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:34:39.284360 | controller | Checking login to the cluster 3... 2025-10-03 14:34:39.447410 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:34:39.472093 | controller | 2025-10-03 14:34:39.472178 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-03 14:34:39.478984 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:34:44.483152 | controller | Checking login to the cluster 4... 2025-10-03 14:34:44.613535 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:34:44.636271 | controller | 2025-10-03 14:34:44.636382 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-03 14:34:44.641604 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:34:49.643566 | controller | Checking login to the cluster 5... 2025-10-03 14:34:49.777024 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:34:49.819907 | controller | 2025-10-03 14:34:49.819986 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-03 14:34:49.825489 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:34:54.828647 | controller | Checking login to the cluster 6... 2025-10-03 14:34:54.952891 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:34:54.980350 | controller | 2025-10-03 14:34:54.980419 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-03 14:34:54.985297 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:34:59.991724 | controller | Checking login to the cluster 7... 2025-10-03 14:35:00.186057 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:35:00.234980 | controller | 2025-10-03 14:35:00.235057 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-03 14:35:00.241518 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:35:05.244561 | controller | Checking login to the cluster 8... 2025-10-03 14:35:05.374477 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:35:05.394458 | controller | 2025-10-03 14:35:05.394529 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-03 14:35:05.399047 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:35:10.401785 | controller | Checking login to the cluster 9... 2025-10-03 14:35:10.556685 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:35:10.860743 | controller | 2025-10-03 14:35:10.860807 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-03 14:35:10.865346 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:35:15.868043 | controller | Checking login to the cluster 10... 2025-10-03 14:35:16.665931 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:35:19.379041 | controller | 2025-10-03 14:35:19.379115 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-03 14:35:19.383802 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:35:24.387199 | controller | Checking login to the cluster 11... 2025-10-03 14:35:25.555126 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:35:26.281077 | controller | 2025-10-03 14:35:26.281166 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-03 14:35:26.288904 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:35:31.295808 | controller | Checking login to the cluster 12... 2025-10-03 14:35:31.474763 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:35:31.742186 | controller | 2025-10-03 14:35:31.742257 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-03 14:35:31.748128 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:35:36.750848 | controller | Checking login to the cluster 13... 2025-10-03 14:35:37.443171 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:35:41.714526 | controller | 2025-10-03 14:35:41.714675 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-03 14:35:41.721826 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:35:46.725435 | controller | Checking login to the cluster 14... 2025-10-03 14:35:47.143090 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:35:47.477240 | controller | 2025-10-03 14:35:47.477329 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-03 14:35:47.483394 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:35:52.491385 | controller | Checking login to the cluster 15... 2025-10-03 14:35:52.692300 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:35:53.070724 | controller | 2025-10-03 14:35:53.070822 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-03 14:35:53.077212 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:35:58.080387 | controller | Checking login to the cluster 16... 2025-10-03 14:35:59.611534 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:36:00.994328 | controller | 2025-10-03 14:36:00.994466 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-03 14:36:00.999784 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:36:06.002256 | controller | Checking login to the cluster 17... 2025-10-03 14:36:06.250474 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:36:06.304535 | controller | 2025-10-03 14:36:06.304633 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-03 14:36:06.309379 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:36:11.317237 | controller | Checking login to the cluster 18... 2025-10-03 14:36:11.806343 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:36:13.958366 | controller | 2025-10-03 14:36:13.958549 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-03 14:36:13.969249 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:36:18.971351 | controller | Checking login to the cluster 19... 2025-10-03 14:36:19.126882 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:36:19.494249 | controller | 2025-10-03 14:36:19.494334 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-03 14:36:19.499454 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:36:24.502272 | controller | Checking login to the cluster 20... 2025-10-03 14:36:24.695566 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:36:24.999704 | controller | 2025-10-03 14:36:24.999787 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-03 14:36:25.005083 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:36:30.007583 | controller | Checking login to the cluster 21... 2025-10-03 14:36:30.169404 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:36:30.322107 | controller | 2025-10-03 14:36:30.322187 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-03 14:36:30.327010 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:36:35.329404 | controller | Checking login to the cluster 22... 2025-10-03 14:36:35.498387 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:36:35.691229 | controller | 2025-10-03 14:36:35.691316 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-03 14:36:35.698536 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:36:40.703311 | controller | Checking login to the cluster 23... 2025-10-03 14:36:40.857314 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:36:40.907979 | controller | 2025-10-03 14:36:40.908094 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-03 14:36:40.912964 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:36:45.916468 | controller | Checking login to the cluster 24... 2025-10-03 14:36:46.092863 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:36:46.137583 | controller | 2025-10-03 14:36:46.137676 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-03 14:36:46.145282 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:36:51.148616 | controller | Checking login to the cluster 25... 2025-10-03 14:36:51.315493 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:36:54.381496 | controller | 2025-10-03 14:36:54.381556 | controller | Unable to connect to the server: EOF 2025-10-03 14:36:54.386598 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:36:59.389696 | controller | Checking login to the cluster 26... 2025-10-03 14:36:59.516009 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:37:02.615334 | controller | 2025-10-03 14:37:02.615420 | controller | Unable to connect to the server: EOF 2025-10-03 14:37:02.620329 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:37:07.622720 | controller | Checking login to the cluster 27... 2025-10-03 14:37:07.758508 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:37:07.789054 | controller | 2025-10-03 14:37:07.789130 | controller | Unable to connect to the server: EOF 2025-10-03 14:37:07.793508 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:37:12.796715 | controller | Checking login to the cluster 28... 2025-10-03 14:37:12.938666 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:37:12.966802 | controller | 2025-10-03 14:37:12.966896 | controller | Unable to connect to the server: EOF 2025-10-03 14:37:12.972506 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:37:17.975543 | controller | Checking login to the cluster 29... 2025-10-03 14:37:18.117126 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:37:18.134166 | controller | 2025-10-03 14:37:18.134260 | controller | Unable to connect to the server: EOF 2025-10-03 14:37:18.138885 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:37:23.141522 | controller | Checking login to the cluster 30... 2025-10-03 14:37:23.255546 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:37:23.281887 | controller | 2025-10-03 14:37:23.281969 | controller | Unable to connect to the server: EOF 2025-10-03 14:37:23.288431 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:37:28.291510 | controller | Checking login to the cluster 31... 2025-10-03 14:37:28.437035 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:37:28.482071 | controller | 2025-10-03 14:37:28.482163 | controller | Unable to connect to the server: EOF 2025-10-03 14:37:28.487370 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:37:33.489775 | controller | Checking login to the cluster 32... 2025-10-03 14:37:33.622759 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:37:33.654191 | controller | 2025-10-03 14:37:33.654267 | controller | Unable to connect to the server: EOF 2025-10-03 14:37:33.659960 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:37:38.662498 | controller | Checking login to the cluster 33... 2025-10-03 14:37:38.788531 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:37:38.813494 | controller | 2025-10-03 14:37:38.813577 | controller | Unable to connect to the server: EOF 2025-10-03 14:37:38.819052 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:37:43.821969 | controller | Checking login to the cluster 34... 2025-10-03 14:37:43.938542 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:37:43.965593 | controller | 2025-10-03 14:37:43.965686 | controller | Unable to connect to the server: EOF 2025-10-03 14:37:43.970974 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:37:48.974558 | controller | Checking login to the cluster 35... 2025-10-03 14:37:49.085932 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:37:49.116571 | controller | 2025-10-03 14:37:49.116658 | controller | Unable to connect to the server: EOF 2025-10-03 14:37:49.121197 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:37:54.123664 | controller | Checking login to the cluster 36... 2025-10-03 14:37:54.249260 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:37:54.275787 | controller | 2025-10-03 14:37:54.275872 | controller | Unable to connect to the server: EOF 2025-10-03 14:37:54.281740 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:37:59.285197 | controller | Checking login to the cluster 37... 2025-10-03 14:37:59.417107 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:37:59.447652 | controller | 2025-10-03 14:37:59.447740 | controller | Unable to connect to the server: EOF 2025-10-03 14:37:59.454924 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:38:04.458108 | controller | Checking login to the cluster 38... 2025-10-03 14:38:04.618011 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:38:04.643872 | controller | 2025-10-03 14:38:04.643975 | controller | Unable to connect to the server: EOF 2025-10-03 14:38:04.650484 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:38:09.652286 | controller | Checking login to the cluster 39... 2025-10-03 14:38:09.765023 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:38:09.785276 | controller | 2025-10-03 14:38:09.785362 | controller | Unable to connect to the server: EOF 2025-10-03 14:38:09.792112 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:38:14.794181 | controller | Checking login to the cluster 40... 2025-10-03 14:38:14.903802 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:38:14.920885 | controller | 2025-10-03 14:38:14.920977 | controller | Unable to connect to the server: EOF 2025-10-03 14:38:14.927212 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:38:19.929675 | controller | Checking login to the cluster 41... 2025-10-03 14:38:20.047594 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:38:20.070125 | controller | 2025-10-03 14:38:20.070213 | controller | Unable to connect to the server: EOF 2025-10-03 14:38:20.075988 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:38:25.078604 | controller | Checking login to the cluster 42... 2025-10-03 14:38:25.207550 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:38:25.227906 | controller | 2025-10-03 14:38:25.227980 | controller | Unable to connect to the server: EOF 2025-10-03 14:38:25.234022 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:38:30.236196 | controller | Checking login to the cluster 43... 2025-10-03 14:38:30.368410 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:38:30.429918 | controller | 2025-10-03 14:38:30.430005 | controller | Unable to connect to the server: EOF 2025-10-03 14:38:30.436335 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-03 14:38:35.438568 | controller | Checking login to the cluster 44... 2025-10-03 14:38:35.548542 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-03 14:38:56.184876 | controller | 2025-10-03 14:38:56.184987 | controller | Login successful. 2025-10-03 14:38:56.246214 | controller | 2025-10-03 14:38:56.246304 | controller | You have access to 65 projects, the list has been suppressed. You can list all projects with 'oc projects' 2025-10-03 14:38:56.280429 | controller | 2025-10-03 14:38:56.280672 | controller | Using project "default". 2025-10-03 14:38:56.455925 | controller | changed 2025-10-03 14:38:56.470255 | 2025-10-03 14:38:56.470407 | TASK [Delete all openshift-marketplace pods] 2025-10-03 14:38:58.851169 | controller | pod "certified-operators-f8szs" deleted 2025-10-03 14:38:58.904141 | controller | pod "certified-operators-j55n7" deleted 2025-10-03 14:38:58.961954 | controller | pod "community-operators-5zsqg" deleted 2025-10-03 14:38:58.973460 | controller | pod "community-operators-pjqp8" deleted 2025-10-03 14:38:59.023039 | controller | pod "marketplace-operator-79b997595-d2lpl" deleted 2025-10-03 14:38:59.075483 | controller | pod "redhat-marketplace-2z8jn" deleted 2025-10-03 14:38:59.140317 | controller | pod "redhat-marketplace-ps6xk" deleted 2025-10-03 14:38:59.253303 | controller | pod "redhat-operators-tqxl9" deleted 2025-10-03 14:38:59.704424 | controller | pod "redhat-operators-w2l9s" deleted 2025-10-03 14:39:13.542229 | controller | ok 2025-10-03 14:39:13.554482 | 2025-10-03 14:39:13.554610 | TASK [Copy script ensure_services_up.sh] 2025-10-03 14:39:13.601931 | controller | skipping: Conditional result was False 2025-10-03 14:39:13.613807 | 2025-10-03 14:39:13.613989 | TASK [Ensure all pods are up and running] 2025-10-03 14:39:14.162014 | controller | skipping: Conditional result was False 2025-10-03 14:39:14.175760 | 2025-10-03 14:39:14.175902 | TASK [Check if there is a image build date file] 2025-10-03 14:39:14.845993 | controller | ok 2025-10-03 14:39:14.860279 | 2025-10-03 14:39:14.860497 | TASK [Print image date if available] 2025-10-03 14:39:15.261464 | controller | Tue Apr 1 06:17:46 PM EDT 2025 2025-10-03 14:39:15.408197 | controller | changed 2025-10-03 14:39:15.419880 | 2025-10-03 14:39:15.420011 | TASK [Fix machineconfigpool] 2025-10-03 14:39:15.487014 | controller | skipping: Conditional result was False 2025-10-03 14:39:15.494976 | 2025-10-03 14:39:15.495058 | TASK [Show available nodes] 2025-10-03 14:39:17.661076 | controller | NAME STATUS ROLES AGE VERSION 2025-10-03 14:39:17.663511 | controller | crc Ready control-plane,master,worker 222d v1.31.5 2025-10-03 14:39:18.037265 | controller | ok: Runtime: 0:00:01.766315 2025-10-03 14:39:18.049539 | 2025-10-03 14:39:18.049706 | TASK [Configure insecure registry inside crc vm] 2025-10-03 14:39:18.086785 | controller | skipping: Conditional result was False 2025-10-03 14:39:18.101174 | 2025-10-03 14:39:18.101330 | TASK [Catch CRC IP] 2025-10-03 14:39:18.720655 | controller | 192.168.130.11 2025-10-03 14:39:19.153179 | controller | changed 2025-10-03 14:39:19.160875 | 2025-10-03 14:39:19.161017 | TASK [Check if id_ed25519 key exists] 2025-10-03 14:39:20.267170 | controller | ok 2025-10-03 14:39:20.279600 | 2025-10-03 14:39:20.279767 | TASK [Set fact if new keypair exists] 2025-10-03 14:39:20.366587 | controller | ok 2025-10-03 14:39:20.373356 | 2025-10-03 14:39:20.373442 | TASK [Run crc] 2025-10-03 14:39:22.715094 | controller | 2025-10-03 14:39:39.333863 | controller | PLAY [OpenShift secret copy and CRC securitization] **************************** 2025-10-03 14:39:39.333919 | controller | 2025-10-03 14:39:39.333927 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-03 14:39:39.333941 | controller | [WARNING]: Platform linux on host 192.168.130.11 is using the discovered Python 2025-10-03 14:39:39.383375 | controller | interpreter at /usr/bin/python3.9, but future installation of another Python 2025-10-03 14:39:39.383465 | controller | interpreter could change the meaning of that path. See 2025-10-03 14:39:39.383481 | controller | https://docs.ansible.com/ansible- 2025-10-03 14:39:39.383490 | controller | core/2.14/reference_appendices/interpreter_discovery.html for more information. 2025-10-03 14:39:39.383510 | controller | ok: [192.168.130.11] 2025-10-03 14:39:41.319687 | controller | 2025-10-03 14:39:41.319742 | controller | TASK [Copy pull-secret] ******************************************************** 2025-10-03 14:39:41.319757 | controller | changed: [192.168.130.11] 2025-10-03 14:39:42.872596 | controller | 2025-10-03 14:39:42.872659 | controller | TASK [Add crio config] ********************************************************* 2025-10-03 14:39:42.872677 | controller | changed: [192.168.130.11] 2025-10-03 14:39:48.810158 | controller | 2025-10-03 14:39:48.810258 | controller | TASK [Restart crio to pickup new configuration] ******************************** 2025-10-03 14:39:48.810305 | controller | changed: [192.168.130.11] 2025-10-03 14:39:48.884294 | controller | 2025-10-03 14:39:48.884377 | controller | PLAY RECAP ********************************************************************* 2025-10-03 14:39:48.884388 | controller | 192.168.130.11 : ok=4 changed=3 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2025-10-03 14:39:48.884395 | controller | 2025-10-03 14:39:48.964093 | controller | changed 2025-10-03 14:39:48.983495 | 2025-10-03 14:39:48.983716 | TASK [Set slower etcd profile] 2025-10-03 14:39:49.052687 | controller | ok 2025-10-03 14:39:49.085601 | 2025-10-03 14:39:49.085766 | TASK [prepare-crc-cloud : Set slower profile] 2025-10-03 14:39:49.697997 | controller | etcd.operator.openshift.io/cluster patched 2025-10-03 14:39:50.124622 | controller | ok 2025-10-03 14:39:50.139402 | 2025-10-03 14:39:50.139576 | TASK [prepare-crc-cloud : Wait 30 seconds to apply] 2025-10-03 14:40:20.190073 | controller | ok 2025-10-03 14:40:20.201559 | 2025-10-03 14:40:20.201769 | TASK [prepare-crc-cloud : Get pod phase status] 2025-10-03 14:44:22.201573 | controller | ok 2025-10-03 14:44:22.226101 | 2025-10-03 14:44:22.226287 | TASK [Set limit for disk usage - IOPS and Read/Write] 2025-10-03 14:44:22.300913 | controller | ok 2025-10-03 14:44:22.309872 | controller | included: /var/lib/zuul/builds/5a740235803e4157b5655e2be72ca944/trusted/project_0/review.rdoproject.org/config/playbooks/crc/limit-disk-usage.yaml 2025-10-03 14:44:22.322733 | 2025-10-03 14:44:22.322843 | TASK [Set facts for disk limitation] 2025-10-03 14:44:22.354922 | controller | ok 2025-10-03 14:44:22.363301 | 2025-10-03 14:44:22.363385 | TASK [Print limits for the disk] 2025-10-03 14:44:22.404908 | controller | ok: Set limit to crc VM to the vda disk: iops 18000 read/write 262144000 2025-10-03 14:44:22.411402 | 2025-10-03 14:44:22.411482 | TASK [Set limit to crc VM to the vda disk] 2025-10-03 14:44:22.869287 | controller | 2025-10-03 14:44:22.978231 | controller | changed 2025-10-03 14:44:22.994848 | 2025-10-03 14:44:22.994987 | TASK [Pause machineconfigpool] 2025-10-03 14:44:23.046840 | controller | ok 2025-10-03 14:44:23.087483 | 2025-10-03 14:44:23.087584 | LOOP [prepare-crc-cloud : Pause the machineconfig configuration if mcp is not needed] 2025-10-03 14:45:23.753338 | controller | ERROR: Item: master 2025-10-03 14:45:23.754345 | controller | { 2025-10-03 14:45:23.754435 | controller | "ansible_loop_var": "item", 2025-10-03 14:45:23.754494 | controller | "delta": "0:01:00.150164", 2025-10-03 14:45:23.754547 | controller | "end": "2025-10-03 14:45:23.701070", 2025-10-03 14:45:23.754600 | controller | "failed_when_result": true, 2025-10-03 14:45:23.754650 | controller | "item": "master", 2025-10-03 14:45:23.754828 | controller | "msg": "non-zero return code", 2025-10-03 14:45:23.754888 | controller | "rc": 1, 2025-10-03 14:45:23.754938 | controller | "start": "2025-10-03 14:44:23.550906" 2025-10-03 14:45:23.754989 | controller | } 2025-10-03 14:45:32.679831 | controller | ok failure 2025-10-03 14:45:32.697625 | 2025-10-03 14:45:32.697911 | PLAY RECAP 2025-10-03 14:45:32.698000 | controller | ok: 23 changed: 10 unreachable: 0 failed: 1 skipped: 12 rescued: 0 ignored: 0 2025-10-03 14:45:32.698046 | 2025-10-03 14:45:32.817305 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master] 2025-10-03 14:45:32.818765 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master] 2025-10-03 14:45:33.444889 | 2025-10-03 14:45:33.445046 | PLAY [Report DLRN results after job run] 2025-10-03 14:45:33.473861 | 2025-10-03 14:45:33.474131 | TASK [Report job status to DLRN] 2025-10-03 14:45:33.497789 | controller | ok 2025-10-03 14:45:33.522135 | 2025-10-03 14:45:33.522268 | TASK [dlrn_report : Warning if DLRN is not running] 2025-10-03 14:45:33.588439 | controller | skipping: Conditional result was False 2025-10-03 14:45:33.596544 | 2025-10-03 14:45:33.596640 | TASK [dlrn_report : Install dlrnapi-client shyaml package] 2025-10-03 14:45:33.652559 | controller | skipping: Conditional result was False 2025-10-03 14:45:33.665379 | 2025-10-03 14:45:33.665528 | TASK [dlrn_report : Install kinit related package] 2025-10-03 14:45:33.713143 | controller | skipping: Conditional result was False 2025-10-03 14:45:33.725936 | 2025-10-03 14:45:33.726100 | TASK [dlrn_report : Install dlrn kerberos related packages] 2025-10-03 14:45:33.773990 | controller | skipping: Conditional result was False 2025-10-03 14:45:33.786445 | 2025-10-03 14:45:33.786592 | TASK [Set zuul-log-path fact] 2025-10-03 14:45:33.837511 | controller | skipping: Conditional result was False 2025-10-03 14:45:33.849820 | 2025-10-03 14:45:33.849971 | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used] 2025-10-03 14:45:33.897653 | controller | skipping: Conditional result was False 2025-10-03 14:45:33.910339 | 2025-10-03 14:45:33.910490 | TASK [Get hash related data from repo_setup role] 2025-10-03 14:45:33.957092 | controller | skipping: Conditional result was False 2025-10-03 14:45:33.966146 | 2025-10-03 14:45:33.966247 | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication] 2025-10-03 14:45:34.509106 | controller | skipping: Conditional result was False 2025-10-03 14:45:34.522256 | 2025-10-03 14:45:34.522408 | TASK [dlrn_report : Set empty value for dlrnapi password] 2025-10-03 14:45:34.611591 | controller | skipping: Conditional result was False 2025-10-03 14:45:34.624791 | 2025-10-03 14:45:34.624971 | TASK [dlrn_report : Report results to dlrn for the tested hash] 2025-10-03 14:45:35.177962 | controller | skipping: Conditional result was False 2025-10-03 14:45:35.216465 | 2025-10-03 14:45:35.216611 | PLAY RECAP 2025-10-03 14:45:35.216721 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 0 2025-10-03 14:45:35.216774 | 2025-10-03 14:45:35.365168 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master] 2025-10-03 14:45:35.366005 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-03 14:45:35.992027 | 2025-10-03 14:45:35.992143 | PLAY [all] 2025-10-03 14:45:36.012200 | 2025-10-03 14:45:36.012330 | TASK [include_role : fetch-output] 2025-10-03 14:45:36.052461 | controller | ok 2025-10-03 14:45:36.070915 | 2025-10-03 14:45:36.071053 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-03 14:45:36.136651 | controller | skipping: Conditional result was False 2025-10-03 14:45:36.142901 | 2025-10-03 14:45:36.142974 | TASK [fetch-output : Set log path for single node] 2025-10-03 14:45:36.189474 | controller | ok 2025-10-03 14:45:36.203556 | 2025-10-03 14:45:36.203850 | LOOP [fetch-output : Ensure local output dirs] 2025-10-03 14:45:36.637166 | controller -> localhost | ok: "/var/lib/zuul/builds/5a740235803e4157b5655e2be72ca944/work/logs" 2025-10-03 14:45:36.900698 | controller -> localhost | changed: "/var/lib/zuul/builds/5a740235803e4157b5655e2be72ca944/work/artifacts" 2025-10-03 14:45:37.132216 | controller -> localhost | changed: "/var/lib/zuul/builds/5a740235803e4157b5655e2be72ca944/work/docs" 2025-10-03 14:45:37.146410 | 2025-10-03 14:45:37.146493 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-03 14:45:37.991418 | controller | changed: .d..t...... ./ 2025-10-03 14:45:37.991622 | controller | changed: All items complete 2025-10-03 14:45:37.991649 | 2025-10-03 14:45:38.604794 | controller | changed: .d..t...... ./ 2025-10-03 14:45:39.183982 | controller | changed: .d..t...... ./ 2025-10-03 14:45:39.209171 | 2025-10-03 14:45:39.209329 | TASK [include_role : fetch-output-openshift] 2025-10-03 14:45:39.234330 | controller | skipping: Conditional result was False 2025-10-03 14:45:39.243012 | 2025-10-03 14:45:39.243145 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-03 14:45:39.664064 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007992 2025-10-03 14:45:39.920337 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008531 2025-10-03 14:45:39.954356 | 2025-10-03 14:45:39.954555 | PLAY [all] 2025-10-03 14:45:39.970989 | 2025-10-03 14:45:39.971083 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-03 14:45:40.552309 | controller | changed 2025-10-03 14:45:40.576039 | 2025-10-03 14:45:40.576142 | PLAY RECAP 2025-10-03 14:45:40.576184 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-03 14:45:40.576204 | 2025-10-03 14:45:40.667819 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-03 14:45:40.669515 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-03 14:45:41.314034 | 2025-10-03 14:45:41.314145 | PLAY [localhost] 2025-10-03 14:45:41.332027 | 2025-10-03 14:45:41.332117 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-03 14:45:41.774034 | localhost | changed 2025-10-03 14:45:41.784558 | 2025-10-03 14:45:41.784806 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-03 14:45:41.821454 | localhost | ok 2025-10-03 14:45:41.840255 | 2025-10-03 14:45:41.840470 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-03 14:45:42.262208 | localhost | changed 2025-10-03 14:45:42.268701 | 2025-10-03 14:45:42.268799 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-03 14:45:43.025327 | localhost | changed 2025-10-03 14:45:43.032708 | 2025-10-03 14:45:43.032808 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-03 14:45:43.497079 | localhost | Identity added: /var/lib/zuul/builds/5a740235803e4157b5655e2be72ca944/work/tmp/ansible.fru0obgv (/var/lib/zuul/builds/5a740235803e4157b5655e2be72ca944/work/tmp/ansible.fru0obgv) 2025-10-03 14:45:43.497476 | localhost | ok: Runtime: 0:00:00.008332 2025-10-03 14:45:43.507918 | 2025-10-03 14:45:43.508062 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-03 14:45:43.809195 | localhost | ok: Runtime: 0:00:00.008617 2025-10-03 14:45:43.820428 | 2025-10-03 14:45:43.820638 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-03 14:45:43.908311 | localhost | changed 2025-10-03 14:45:43.918519 | 2025-10-03 14:45:43.918721 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-03 14:45:44.312619 | localhost | changed 2025-10-03 14:45:44.361558 | 2025-10-03 14:45:44.361712 | PLAY [localhost] 2025-10-03 14:45:44.380016 | 2025-10-03 14:45:44.380158 | TASK [Generate bulk log download script] 2025-10-03 14:45:44.398594 | localhost | ok 2025-10-03 14:45:44.411095 | 2025-10-03 14:45:44.411214 | TASK [local-log-download : Check API endpoint is defined] 2025-10-03 14:45:44.441366 | localhost | ok: All assertions passed 2025-10-03 14:45:44.448494 | 2025-10-03 14:45:44.448651 | TASK [local-log-download : Create download script] 2025-10-03 14:45:44.890791 | localhost -> localhost | changed 2025-10-03 14:45:44.903169 | 2025-10-03 14:45:44.903280 | TASK [Register quick-download link] 2025-10-03 14:45:44.936981 | localhost | ok 2025-10-03 14:45:44.982763 | 2025-10-03 14:45:44.982865 | PLAY [logserver.rdoproject.org] 2025-10-03 14:45:44.992695 | 2025-10-03 14:45:44.992760 | TASK [Set zuul-log-path fact] 2025-10-03 14:45:45.010215 | logserver.rdoproject.org | ok 2025-10-03 14:45:45.019084 | 2025-10-03 14:45:45.019146 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 14:45:45.056213 | logserver.rdoproject.org | ok 2025-10-03 14:45:45.061653 | 2025-10-03 14:45:45.061735 | TASK [upload-logs : Create log directories] 2025-10-03 14:45:45.888903 | logserver.rdoproject.org | changed 2025-10-03 14:45:45.895829 | 2025-10-03 14:45:45.895966 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-03 14:45:46.264065 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.019283 2025-10-03 14:45:46.269965 | 2025-10-03 14:45:46.270047 | TASK [upload-logs : Upload logs to log server] 2025-10-03 14:45:46.950125 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-03 14:45:46.967520 | 2025-10-03 14:45:46.967781 | LOOP [upload-logs : Compress console log and json output] 2025-10-03 14:45:47.054794 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 14:45:47.070065 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 14:45:47.087988 | 2025-10-03 14:45:47.088174 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-03 14:45:47.140464 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 14:45:47.140712 | 2025-10-03 14:45:47.145769 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 14:45:47.161628 | 2025-10-03 14:45:47.162137 | LOOP [upload-logs : Upload console log and json output]