2025-10-07 15:55:06.071326 | Job console starting... 2025-10-07 15:55:06.108015 | Updating repositories 2025-10-07 15:55:06.158838 | Preparing job workspace 2025-10-07 15:55:13.500536 | Running Ansible setup... 2025-10-07 15:55:19.086882 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-07 15:55:19.712162 | 2025-10-07 15:55:19.712274 | PLAY [localhost] 2025-10-07 15:55:19.726197 | 2025-10-07 15:55:19.726290 | TASK [Gathering Facts] 2025-10-07 15:55:20.711193 | localhost | ok 2025-10-07 15:55:20.741527 | 2025-10-07 15:55:20.741940 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-07 15:55:21.154122 | localhost -> localhost | changed 2025-10-07 15:55:21.162495 | 2025-10-07 15:55:21.162585 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-07 15:55:22.035313 | localhost -> localhost | changed 2025-10-07 15:55:22.043325 | 2025-10-07 15:55:22.043399 | TASK [Setup log path fact] 2025-10-07 15:55:22.060328 | localhost | ok 2025-10-07 15:55:22.073800 | 2025-10-07 15:55:22.073924 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-07 15:55:22.103752 | localhost | ok 2025-10-07 15:55:22.114569 | 2025-10-07 15:55:22.114661 | TASK [emit-job-header : Print job information] 2025-10-07 15:55:22.145113 | # Job Information 2025-10-07 15:55:22.145293 | Ansible Version: 2.15.12 2025-10-07 15:55:22.145332 | Job: cifmw-molecule-ci_nmstate 2025-10-07 15:55:22.145359 | Pipeline: github-check 2025-10-07 15:55:22.145385 | Executor: ze04.softwarefactory-project.io 2025-10-07 15:55:22.145412 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3368 2025-10-07 15:55:22.145438 | Log URL (when completed): https://logserver.rdoproject.org/36c/rdoproject.org/36cb99831e744dc499f79029cfe2ad62/ 2025-10-07 15:55:22.145465 | Event ID: baecefb0-a395-11f0-9567-2a348cffbde7 2025-10-07 15:55:22.151275 | 2025-10-07 15:55:22.151361 | LOOP [emit-job-header : Print node information] 2025-10-07 15:55:22.272827 | localhost | ok: 2025-10-07 15:55:22.272968 | localhost | # Node Information 2025-10-07 15:55:22.272993 | localhost | Inventory Hostname: controller 2025-10-07 15:55:22.273017 | localhost | Hostname: np0005474584 2025-10-07 15:55:22.273036 | localhost | Username: zuul 2025-10-07 15:55:22.273056 | localhost | Distro: CentOS 9 2025-10-07 15:55:22.273074 | localhost | Provider: vexxhost-nodepool-tripleo 2025-10-07 15:55:22.273090 | localhost | Region: RegionOne 2025-10-07 15:55:22.273107 | localhost | Label: centos-9-stream-crc-2-48-0-xxl 2025-10-07 15:55:22.273124 | localhost | Product Name: OpenStack Nova 2025-10-07 15:55:22.273140 | localhost | Interface IP: 38.102.83.136 2025-10-07 15:55:22.303628 | 2025-10-07 15:55:22.303750 | PLAY [all] 2025-10-07 15:55:22.310537 | 2025-10-07 15:55:22.310603 | TASK [Gather network facts] 2025-10-07 15:55:22.975738 | controller | ok 2025-10-07 15:55:22.991993 | 2025-10-07 15:55:22.992066 | TASK [include_role : start-zuul-console] 2025-10-07 15:55:23.021339 | controller | ok 2025-10-07 15:55:23.033518 | 2025-10-07 15:55:23.033592 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-07 15:55:23.579673 | controller | ok 2025-10-07 15:55:23.597563 | 2025-10-07 15:55:23.597823 | TASK [include_role : add-build-sshkey] 2025-10-07 15:55:23.637739 | controller | ok 2025-10-07 15:55:23.662369 | 2025-10-07 15:55:23.662503 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-07 15:55:23.906147 | controller -> localhost | ok 2025-10-07 15:55:23.912164 | 2025-10-07 15:55:23.912232 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-07 15:55:23.953091 | controller | ok 2025-10-07 15:55:23.975938 | controller | included: /var/lib/zuul/builds/36cb99831e744dc499f79029cfe2ad62/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-07 15:55:23.984511 | 2025-10-07 15:55:23.984624 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-07 15:55:24.724435 | controller -> localhost | Generating public/private rsa key pair. 2025-10-07 15:55:24.724910 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/36cb99831e744dc499f79029cfe2ad62/work/36cb99831e744dc499f79029cfe2ad62_id_rsa. 2025-10-07 15:55:24.724994 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/36cb99831e744dc499f79029cfe2ad62/work/36cb99831e744dc499f79029cfe2ad62_id_rsa.pub. 2025-10-07 15:55:24.725067 | controller -> localhost | The key fingerprint is: 2025-10-07 15:55:24.725123 | controller -> localhost | SHA256:HsnCVSQdkVQX/sjh6Jlw3eCnaQuhwr0GAdvCKBb7vHk zuul-build-sshkey 2025-10-07 15:55:24.725165 | controller -> localhost | The key's randomart image is: 2025-10-07 15:55:24.725205 | controller -> localhost | +---[RSA 3072]----+ 2025-10-07 15:55:24.725244 | controller -> localhost | | .+*=. o. | 2025-10-07 15:55:24.725285 | controller -> localhost | | . . oo o | 2025-10-07 15:55:24.725323 | controller -> localhost | | o o +. + | 2025-10-07 15:55:24.725362 | controller -> localhost | | + ..+oo. * * | 2025-10-07 15:55:24.725401 | controller -> localhost | | . + o.S.. + * +| 2025-10-07 15:55:24.725438 | controller -> localhost | | o +.o = + + | 2025-10-07 15:55:24.725475 | controller -> localhost | | o +.o = + | 2025-10-07 15:55:24.725512 | controller -> localhost | | o E ... o . | 2025-10-07 15:55:24.725549 | controller -> localhost | | . .. . | 2025-10-07 15:55:24.725586 | controller -> localhost | +----[SHA256]-----+ 2025-10-07 15:55:24.725724 | controller -> localhost | ok: Runtime: 0:00:00.253241 2025-10-07 15:55:24.736254 | 2025-10-07 15:55:24.736365 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-07 15:55:24.783466 | controller | ok 2025-10-07 15:55:24.808418 | controller | included: /var/lib/zuul/builds/36cb99831e744dc499f79029cfe2ad62/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-07 15:55:24.826492 | 2025-10-07 15:55:24.826623 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-07 15:55:24.852857 | controller | skipping: Conditional result was False 2025-10-07 15:55:24.860631 | 2025-10-07 15:55:24.860877 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-07 15:55:25.631606 | controller | changed 2025-10-07 15:55:25.642919 | 2025-10-07 15:55:25.643043 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-07 15:55:26.038802 | controller | ok 2025-10-07 15:55:26.048909 | 2025-10-07 15:55:26.049007 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-07 15:55:31.217080 | controller | changed 2025-10-07 15:55:31.224260 | 2025-10-07 15:55:31.224339 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-07 15:55:33.202166 | controller | changed 2025-10-07 15:55:33.210889 | 2025-10-07 15:55:33.210979 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-07 15:55:33.238516 | controller | skipping: Conditional result was False 2025-10-07 15:55:33.286070 | 2025-10-07 15:55:33.286191 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-07 15:55:33.639205 | controller -> localhost | changed 2025-10-07 15:55:33.653814 | 2025-10-07 15:55:33.653916 | TASK [add-build-sshkey : Add back temp key] 2025-10-07 15:55:33.954042 | controller -> localhost | Identity added: /var/lib/zuul/builds/36cb99831e744dc499f79029cfe2ad62/work/36cb99831e744dc499f79029cfe2ad62_id_rsa (zuul-build-sshkey) 2025-10-07 15:55:33.954316 | controller -> localhost | ok: Runtime: 0:00:00.012098 2025-10-07 15:55:33.962572 | 2025-10-07 15:55:33.962663 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-07 15:55:34.415262 | controller | ok 2025-10-07 15:55:34.420167 | 2025-10-07 15:55:34.420231 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-07 15:55:34.444323 | controller | skipping: Conditional result was False 2025-10-07 15:55:34.457722 | 2025-10-07 15:55:34.457854 | TASK [include_role : validate-host] 2025-10-07 15:55:34.483331 | controller | ok 2025-10-07 15:55:34.503540 | 2025-10-07 15:55:34.503623 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-07 15:55:34.533240 | controller | ok 2025-10-07 15:55:34.537940 | 2025-10-07 15:55:34.538008 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-07 15:55:34.832034 | controller -> localhost | ok 2025-10-07 15:55:34.837872 | 2025-10-07 15:55:34.837935 | TASK [validate-host : Collect information about the host] 2025-10-07 15:55:35.741299 | controller | ok 2025-10-07 15:55:35.755816 | 2025-10-07 15:55:35.755945 | TASK [validate-host : Sanitize hostname] 2025-10-07 15:55:35.826493 | controller | ok 2025-10-07 15:55:35.831480 | 2025-10-07 15:55:35.831545 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-07 15:55:36.368644 | controller -> localhost | changed 2025-10-07 15:55:36.374899 | 2025-10-07 15:55:36.374972 | TASK [validate-host : Collect information about zuul worker] 2025-10-07 15:55:36.933628 | controller | ok 2025-10-07 15:55:36.942424 | 2025-10-07 15:55:36.942530 | TASK [validate-host : Write out all zuul information for each host] 2025-10-07 15:55:37.482430 | controller -> localhost | changed 2025-10-07 15:55:37.492964 | 2025-10-07 15:55:37.493036 | TASK [include_role : prepare-workspace-openshift] 2025-10-07 15:55:37.551199 | controller | skipping: Conditional result was False 2025-10-07 15:55:37.557139 | 2025-10-07 15:55:37.557214 | TASK [include_role : remove-zuul-sshkey] 2025-10-07 15:55:37.591260 | controller | skipping: Conditional result was False 2025-10-07 15:55:37.597376 | 2025-10-07 15:55:37.597451 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-07 15:55:38.001254 | controller | ok: "logs" 2025-10-07 15:55:38.001522 | controller | ok: All items complete 2025-10-07 15:55:38.001557 | 2025-10-07 15:55:38.362237 | controller | ok: "artifacts" 2025-10-07 15:55:38.748824 | controller | ok: "docs" 2025-10-07 15:55:38.757021 | 2025-10-07 15:55:38.757104 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-07 15:55:39.183142 | controller | changed: "logs" 2025-10-07 15:55:39.585279 | controller | changed: "artifacts" 2025-10-07 15:55:39.924363 | controller | changed: "docs" 2025-10-07 15:55:39.952633 | 2025-10-07 15:55:39.952759 | PLAY RECAP 2025-10-07 15:55:39.952806 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-07 15:55:39.952835 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-07 15:55:39.952856 | 2025-10-07 15:55:40.096009 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-07 15:55:40.096769 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-07 15:55:40.858722 | 2025-10-07 15:55:40.858854 | PLAY [all] 2025-10-07 15:55:40.884036 | 2025-10-07 15:55:40.884288 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-07 15:55:40.966309 | controller | ok 2025-10-07 15:55:40.971465 | 2025-10-07 15:55:40.971555 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-07 15:55:41.567537 | controller | changed 2025-10-07 15:55:41.581355 | 2025-10-07 15:55:41.581607 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-07 15:55:43.797994 | controller | changed 2025-10-07 15:55:43.817103 | 2025-10-07 15:55:43.817204 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-07 15:55:44.552484 | controller | changed: 2025-10-07 15:55:44.552856 | controller | { 2025-10-07 15:55:44.552920 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-07 15:55:44.552952 | controller | } 2025-10-07 15:55:44.997159 | controller | changed: 2025-10-07 15:55:44.997256 | controller | { 2025-10-07 15:55:44.997286 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-07 15:55:44.997307 | controller | } 2025-10-07 15:55:45.398347 | controller | changed: 2025-10-07 15:55:45.398492 | controller | { 2025-10-07 15:55:45.398521 | 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-07 15:55:45.398542 | controller | } 2025-10-07 15:55:45.814152 | controller | changed: 2025-10-07 15:55:45.814306 | controller | { 2025-10-07 15:55:45.814358 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-07 15:55:45.814399 | controller | } 2025-10-07 15:55:46.292609 | controller | changed: 2025-10-07 15:55:46.294427 | controller | { 2025-10-07 15:55:46.294557 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-07 15:55:46.294582 | controller | } 2025-10-07 15:55:46.654550 | controller | changed: 2025-10-07 15:55:46.654646 | controller | { 2025-10-07 15:55:46.654670 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-07 15:55:46.654707 | controller | } 2025-10-07 15:55:47.087860 | controller | changed: 2025-10-07 15:55:47.087967 | controller | { 2025-10-07 15:55:47.087991 | 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-07 15:55:47.088010 | controller | } 2025-10-07 15:55:47.430365 | controller | changed: 2025-10-07 15:55:47.430474 | controller | { 2025-10-07 15:55:47.430510 | 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-07 15:55:47.430539 | controller | } 2025-10-07 15:55:47.768836 | controller | changed: 2025-10-07 15:55:47.768941 | controller | { 2025-10-07 15:55:47.768968 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-07 15:55:47.768990 | controller | } 2025-10-07 15:55:48.156101 | controller | changed: 2025-10-07 15:55:48.156202 | controller | { 2025-10-07 15:55:48.156226 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-07 15:55:48.156244 | controller | } 2025-10-07 15:55:48.541088 | controller | changed: 2025-10-07 15:55:48.541178 | controller | { 2025-10-07 15:55:48.541202 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-07 15:55:48.541222 | controller | } 2025-10-07 15:55:49.000486 | controller | changed: 2025-10-07 15:55:49.000787 | controller | { 2025-10-07 15:55:49.000841 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-07 15:55:49.000883 | controller | } 2025-10-07 15:55:49.414002 | controller | changed: 2025-10-07 15:55:49.414095 | controller | { 2025-10-07 15:55:49.414120 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-07 15:55:49.414142 | controller | } 2025-10-07 15:55:49.809811 | controller | changed: 2025-10-07 15:55:49.809909 | controller | { 2025-10-07 15:55:49.809935 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-07 15:55:49.809955 | controller | } 2025-10-07 15:55:50.241694 | controller | changed: 2025-10-07 15:55:50.241791 | controller | { 2025-10-07 15:55:50.241814 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-07 15:55:50.241833 | controller | } 2025-10-07 15:55:50.651289 | controller | changed: 2025-10-07 15:55:50.651441 | controller | { 2025-10-07 15:55:50.651471 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-07 15:55:50.651491 | controller | } 2025-10-07 15:55:51.061970 | controller | changed: 2025-10-07 15:55:51.062086 | controller | { 2025-10-07 15:55:51.062119 | 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-07 15:55:51.062146 | controller | } 2025-10-07 15:55:51.421457 | controller | changed: 2025-10-07 15:55:51.421613 | controller | { 2025-10-07 15:55:51.421664 | 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-07 15:55:51.421745 | controller | } 2025-10-07 15:55:51.845135 | controller | changed: 2025-10-07 15:55:51.845229 | controller | { 2025-10-07 15:55:51.845253 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-07 15:55:51.845271 | controller | } 2025-10-07 15:55:52.266900 | controller | changed: 2025-10-07 15:55:52.267002 | controller | { 2025-10-07 15:55:52.267031 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-07 15:55:52.267051 | controller | } 2025-10-07 15:55:52.678180 | controller | changed: 2025-10-07 15:55:52.678430 | controller | { 2025-10-07 15:55:52.678465 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-07 15:55:52.678487 | controller | } 2025-10-07 15:55:53.122011 | controller | changed: 2025-10-07 15:55:53.122169 | controller | { 2025-10-07 15:55:53.122244 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-07 15:55:53.122286 | controller | } 2025-10-07 15:55:53.558710 | controller | changed: 2025-10-07 15:55:53.559026 | controller | { 2025-10-07 15:55:53.559092 | 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-07 15:55:53.559135 | controller | } 2025-10-07 15:55:53.932780 | controller | changed: 2025-10-07 15:55:53.932936 | controller | { 2025-10-07 15:55:53.932988 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-07 15:55:53.933029 | controller | } 2025-10-07 15:55:54.279230 | controller | changed: 2025-10-07 15:55:54.279325 | controller | { 2025-10-07 15:55:54.279351 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-07 15:55:54.279372 | controller | } 2025-10-07 15:55:54.665668 | controller | changed: 2025-10-07 15:55:54.665779 | controller | { 2025-10-07 15:55:54.665803 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-07 15:55:54.665822 | controller | } 2025-10-07 15:55:54.712632 | 2025-10-07 15:55:54.712790 | TASK [Set timezone to UTC] 2025-10-07 15:55:55.445577 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-07 15:55:55.450703 | 2025-10-07 15:55:55.450771 | TASK [Create nodepool directory] 2025-10-07 15:55:55.888838 | controller | changed 2025-10-07 15:55:55.897210 | 2025-10-07 15:55:55.897310 | TASK [Create nodepool sub_nodes file] 2025-10-07 15:55:57.462511 | controller | changed 2025-10-07 15:55:57.468124 | 2025-10-07 15:55:57.468189 | TASK [Create nodepool sub_nodes_private file] 2025-10-07 15:55:59.025743 | controller | changed 2025-10-07 15:55:59.031929 | 2025-10-07 15:55:59.031991 | LOOP [Populate nodepool sub_nodes file] 2025-10-07 15:55:59.093201 | 2025-10-07 15:55:59.094073 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-07 15:55:59.124162 | 2025-10-07 15:55:59.124315 | TASK [Create nodepool primary file] 2025-10-07 15:55:59.147759 | controller | skipping: Conditional result was False 2025-10-07 15:55:59.154182 | 2025-10-07 15:55:59.154256 | TASK [Create nodepool node_private for this node] 2025-10-07 15:56:00.822786 | controller | changed 2025-10-07 15:56:00.839805 | 2025-10-07 15:56:00.839968 | LOOP [Copy ssh keys to nodepool directory] 2025-10-07 15:56:01.430071 | controller | ok: Item: id_rsa Runtime: 0:00:00.011583 2025-10-07 15:56:01.430523 | 2025-10-07 15:56:01.811596 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.009131 2025-10-07 15:56:01.833990 | 2025-10-07 15:56:01.834208 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-07 15:56:03.373667 | controller | changed 2025-10-07 15:56:03.381589 | 2025-10-07 15:56:03.381716 | TASK [Validate sudoers config after edits] 2025-10-07 15:56:03.747797 | controller | /etc/sudoers: parsed OK 2025-10-07 15:56:03.748066 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-07 15:56:03.748081 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-07 15:56:03.748089 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-07 15:56:03.922416 | controller | ok: Runtime: 0:00:00.012117 2025-10-07 15:56:03.932155 | 2025-10-07 15:56:03.932265 | TASK [Show the environment passed in to job shell scripts] 2025-10-07 15:56:04.307113 | controller | SHELL=/bin/bash 2025-10-07 15:56:04.307210 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-07 15:56:04.307235 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-07 15:56:04.307257 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/68/3368/83ef6805b611cc3d0acd8d5bab85c9edc12f42a4 2025-10-07 15:56:04.307275 | controller | PWD=/home/zuul 2025-10-07 15:56:04.307292 | controller | ZUUL_PIPELINE=github-check 2025-10-07 15:56:04.307309 | controller | LOGNAME=zuul 2025-10-07 15:56:04.307326 | controller | XDG_SESSION_TYPE=tty 2025-10-07 15:56:04.307343 | controller | _=/usr/bin/env 2025-10-07 15:56:04.307359 | controller | MOTD_SHOWN=pam 2025-10-07 15:56:04.307377 | controller | HOME=/home/zuul 2025-10-07 15:56:04.307394 | controller | LANG=en_US.UTF-8 2025-10-07 15:56:04.307523 | controller | SSH_CONNECTION=38.102.83.114 34042 38.102.83.136 22 2025-10-07 15:56:04.307559 | 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-07 15:56:04.307581 | controller | ZUUL_CHANGE_IDS=3368,83ef6805b611cc3d0acd8d5bab85c9edc12f42a4 2025-10-07 15:56:04.307595 | controller | WORKSPACE=/home/zuul/workspace 2025-10-07 15:56:04.307608 | controller | XDG_SESSION_CLASS=user 2025-10-07 15:56:04.307622 | controller | SELINUX_ROLE_REQUESTED= 2025-10-07 15:56:04.307635 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-07 15:56:04.307649 | controller | USER=zuul 2025-10-07 15:56:04.307662 | controller | ZUUL_VOTING=True 2025-10-07 15:56:04.307675 | controller | BUILD_TIMEOUT=1800000 2025-10-07 15:56:04.307689 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-07 15:56:04.307702 | controller | SHLVL=1 2025-10-07 15:56:04.307715 | controller | ZUUL_PATCHSET=83ef6805b611cc3d0acd8d5bab85c9edc12f42a4 2025-10-07 15:56:04.307729 | controller | XDG_SESSION_ID=1 2025-10-07 15:56:04.307742 | controller | ZUUL_BRANCH=main 2025-10-07 15:56:04.307755 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-07 15:56:04.307769 | controller | SSH_CLIENT=38.102.83.114 34042 22 2025-10-07 15:56:04.307782 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-07 15:56:04.307796 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-07 15:56:04.307809 | controller | which_declare=declare -f 2025-10-07 15:56:04.307823 | 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-07 15:56:04.307836 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-07 15:56:04.307849 | controller | ZUUL_CHANGE=3368 2025-10-07 15:56:04.307862 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-07 15:56:04.307876 | controller | ZUUL_UUID=36cb99831e744dc499f79029cfe2ad62 2025-10-07 15:56:04.307889 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-07 15:56:04.307902 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-07 15:56:04.307916 | controller | } 2025-10-07 15:56:04.476101 | controller | ok: Runtime: 0:00:00.011428 2025-10-07 15:56:04.483537 | 2025-10-07 15:56:04.483623 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-07 15:56:04.508946 | controller | skipping: Conditional result was False 2025-10-07 15:56:04.515484 | 2025-10-07 15:56:04.515562 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-07 15:56:05.057317 | controller | skipping: Conditional result was False 2025-10-07 15:56:05.166673 | 2025-10-07 15:56:05.167168 | TASK [Ensure legacy workspace directory] 2025-10-07 15:56:05.566967 | controller | changed 2025-10-07 15:56:05.604737 | 2025-10-07 15:56:05.604858 | PLAY RECAP 2025-10-07 15:56:05.604917 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-07 15:56:05.604948 | 2025-10-07 15:56:05.767519 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-07 15:56:05.768328 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master] 2025-10-07 15:56:06.389564 | 2025-10-07 15:56:06.389674 | PLAY [all,!compute] 2025-10-07 15:56:06.410141 | 2025-10-07 15:56:06.410258 | TASK [Enable container-tools rhel-modules for c8/crc node] 2025-10-07 15:56:06.940179 | controller | skipping: Conditional result was False 2025-10-07 15:56:06.948845 | 2025-10-07 15:56:06.948962 | TASK [Install podman] 2025-10-07 15:56:07.008255 | controller | skipping: Conditional result was False 2025-10-07 15:56:07.014958 | 2025-10-07 15:56:07.015067 | TASK [Set var name for quay login zuul secret] 2025-10-07 15:56:07.060843 | controller | skipping: Conditional result was False 2025-10-07 15:56:07.067752 | 2025-10-07 15:56:07.067841 | TASK [Print the username] 2025-10-07 15:56:07.609337 | controller | skipping: Conditional result was False 2025-10-07 15:56:07.622490 | 2025-10-07 15:56:07.622650 | TASK [Fail when user or password is not set] 2025-10-07 15:56:07.690852 | controller | skipping: Conditional result was False 2025-10-07 15:56:07.703355 | 2025-10-07 15:56:07.703523 | TASK [Set vars for quay login] 2025-10-07 15:56:07.766312 | 2025-10-07 15:56:07.766629 | LOOP [Perform container registry login(s) with podman] 2025-10-07 15:56:07.843486 | 2025-10-07 15:56:07.843861 | LOOP [Perform container registry login(s) with buildah] 2025-10-07 15:56:07.913060 | 2025-10-07 15:56:07.913290 | TASK [Set Insecure registry for content provider] 2025-10-07 15:56:07.978792 | controller | skipping: Conditional result was False 2025-10-07 15:56:08.014119 | 2025-10-07 15:56:08.014205 | PLAY RECAP 2025-10-07 15:56:08.014247 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 9 rescued: 0 ignored: 0 2025-10-07 15:56:08.014268 | 2025-10-07 15:56:08.120897 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master] 2025-10-07 15:56:08.121651 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master] 2025-10-07 15:56:08.721043 | 2025-10-07 15:56:08.721195 | PLAY [Prefer ipv4 over ipv6] 2025-10-07 15:56:08.744645 | 2025-10-07 15:56:08.744763 | TASK [Configure /etc/gai.conf to prever ipv4 over ipv6] 2025-10-07 15:56:08.800845 | controller | skipping: Conditional result was False 2025-10-07 15:56:08.825314 | 2025-10-07 15:56:08.825401 | PLAY [controller] 2025-10-07 15:56:08.842899 | 2025-10-07 15:56:08.842993 | TASK [Fail when openshift_pull_sec not provided] 2025-10-07 15:56:08.857712 | controller | skipping: Conditional result was False 2025-10-07 15:56:08.866079 | 2025-10-07 15:56:08.866159 | TASK [Clone repos in the job workspace] 2025-10-07 15:56:08.896074 | controller | ok 2025-10-07 15:56:08.913455 | 2025-10-07 15:56:08.913541 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-07 15:56:09.401544 | controller | ok 2025-10-07 15:56:09.416913 | 2025-10-07 15:56:09.417062 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-07 15:56:15.926768 | controller | Output suppressed because no_log was given 2025-10-07 15:56:15.939136 | 2025-10-07 15:56:15.939294 | TASK [Create pull-secret.txt file] 2025-10-07 15:56:17.760443 | controller | Output suppressed because no_log was given 2025-10-07 15:56:17.765648 | 2025-10-07 15:56:17.765726 | TASK [Apply CRC features and print image build date] 2025-10-07 15:56:17.785451 | controller | ok 2025-10-07 15:56:17.792758 | controller | included: /var/lib/zuul/builds/36cb99831e744dc499f79029cfe2ad62/trusted/project_0/review.rdoproject.org/config/playbooks/crc/features.yaml 2025-10-07 15:56:17.799557 | 2025-10-07 15:56:17.799621 | TASK [Set additional parameters for crc - telemetry] 2025-10-07 15:56:18.331966 | controller | skipping: Conditional result was False 2025-10-07 15:56:18.337924 | 2025-10-07 15:56:18.337999 | TASK [Set additional parameters for crc - monitoring] 2025-10-07 15:56:18.871364 | controller | skipping: Conditional result was False 2025-10-07 15:56:18.879869 | 2025-10-07 15:56:18.879968 | TASK [Set minimum required memory to run the CRC when monitoring enabled] 2025-10-07 15:56:19.420133 | controller | skipping: Conditional result was False 2025-10-07 15:56:19.425992 | 2025-10-07 15:56:19.426058 | TASK [Set additional parameters for crc - enable microshift preset] 2025-10-07 15:56:19.958124 | controller | skipping: Conditional result was False 2025-10-07 15:56:19.965861 | 2025-10-07 15:56:19.965948 | TASK [Set additional parameters for crc - enable okd preset] 2025-10-07 15:56:20.517979 | controller | skipping: Conditional result was False 2025-10-07 15:56:20.526387 | 2025-10-07 15:56:20.526487 | TASK [Delete the cluster if there's a non-default bundle URL] 2025-10-07 15:56:21.072061 | controller | skipping: Conditional result was False 2025-10-07 15:56:21.077722 | 2025-10-07 15:56:21.077827 | TASK [Execute crc start command] 2025-10-07 15:59:38.399203 | controller | ok: Runtime: 0:03:16.478254 2025-10-07 15:59:38.406938 | 2025-10-07 15:59:38.407026 | TASK [Verify that OpenShift is up and running] 2025-10-07 15:59:38.427822 | controller | ok 2025-10-07 15:59:38.442394 | controller | included: /var/lib/zuul/builds/36cb99831e744dc499f79029cfe2ad62/trusted/project_0/review.rdoproject.org/config/playbooks/crc/verify_openshift_start.yaml 2025-10-07 15:59:38.458931 | 2025-10-07 15:59:38.459099 | TASK [Create script to login and verify services] 2025-10-07 15:59:40.059755 | controller | changed 2025-10-07 15:59:40.074823 | 2025-10-07 15:59:40.075109 | TASK [Ensure that user is logged] 2025-10-07 15:59:40.524664 | controller | Checking login to the cluster 1... 2025-10-07 15:59:41.585330 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 15:59:41.643838 | controller | 2025-10-07 15:59:41.643920 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 15:59:41.653062 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 15:59:46.656813 | controller | Checking login to the cluster 2... 2025-10-07 15:59:46.810206 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 15:59:46.832803 | controller | 2025-10-07 15:59:46.832868 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 15:59:46.839324 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 15:59:51.843423 | controller | Checking login to the cluster 3... 2025-10-07 15:59:51.978286 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 15:59:51.996199 | controller | 2025-10-07 15:59:51.996292 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 15:59:52.001859 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 15:59:57.004456 | controller | Checking login to the cluster 4... 2025-10-07 15:59:57.162805 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 15:59:57.181183 | controller | 2025-10-07 15:59:57.181243 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 15:59:57.185413 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:00:02.188359 | controller | Checking login to the cluster 5... 2025-10-07 16:00:02.335150 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:00:02.359780 | controller | 2025-10-07 16:00:02.359876 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:00:02.365420 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:00:07.367660 | controller | Checking login to the cluster 6... 2025-10-07 16:00:07.477189 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:00:07.494727 | controller | 2025-10-07 16:00:07.494804 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:00:07.501255 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:00:12.503887 | controller | Checking login to the cluster 7... 2025-10-07 16:00:12.658121 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:00:12.680901 | controller | 2025-10-07 16:00:12.681008 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:00:12.687812 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:00:17.691000 | controller | Checking login to the cluster 8... 2025-10-07 16:00:17.860548 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:00:17.881356 | controller | 2025-10-07 16:00:17.881492 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:00:17.891543 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:00:22.895039 | controller | Checking login to the cluster 9... 2025-10-07 16:00:23.067657 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:00:23.106197 | controller | 2025-10-07 16:00:23.106286 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:00:23.115246 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:00:28.119564 | controller | Checking login to the cluster 10... 2025-10-07 16:00:28.298342 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:00:28.319634 | controller | 2025-10-07 16:00:28.319729 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:00:28.328970 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:00:33.332276 | controller | Checking login to the cluster 11... 2025-10-07 16:00:33.498579 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:00:33.518722 | controller | 2025-10-07 16:00:33.518796 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:00:33.525698 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:00:38.528139 | controller | Checking login to the cluster 12... 2025-10-07 16:00:38.644222 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:00:38.667633 | controller | 2025-10-07 16:00:38.667719 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:00:38.672288 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:00:43.675643 | controller | Checking login to the cluster 13... 2025-10-07 16:00:43.806901 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:00:43.835702 | controller | 2025-10-07 16:00:43.835814 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:00:43.846667 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:00:48.850391 | controller | Checking login to the cluster 14... 2025-10-07 16:00:48.981741 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:00:48.999820 | controller | 2025-10-07 16:00:48.999906 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:00:49.005552 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:00:54.007598 | controller | Checking login to the cluster 15... 2025-10-07 16:00:54.113048 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:00:54.132273 | controller | 2025-10-07 16:00:54.132349 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:00:54.136809 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:00:59.139416 | controller | Checking login to the cluster 16... 2025-10-07 16:00:59.266290 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:00:59.289624 | controller | 2025-10-07 16:00:59.289717 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:00:59.296374 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:01:04.298998 | controller | Checking login to the cluster 17... 2025-10-07 16:01:04.427100 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:01:04.451660 | controller | 2025-10-07 16:01:04.451737 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:01:04.456152 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:01:09.458800 | controller | Checking login to the cluster 18... 2025-10-07 16:01:09.589458 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:01:09.652670 | controller | 2025-10-07 16:01:09.652758 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-07 16:01:09.658490 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:01:14.667509 | controller | Checking login to the cluster 19... 2025-10-07 16:01:14.830459 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:01:14.872118 | controller | 2025-10-07 16:01:14.872227 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-07 16:01:14.877428 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:01:19.884602 | controller | Checking login to the cluster 20... 2025-10-07 16:01:20.026346 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:01:20.111434 | controller | 2025-10-07 16:01:20.111580 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-07 16:01:20.123752 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:01:25.127630 | controller | Checking login to the cluster 21... 2025-10-07 16:01:25.351416 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:01:25.450803 | controller | 2025-10-07 16:01:25.450898 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-07 16:01:25.457590 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:01:30.465397 | controller | Checking login to the cluster 22... 2025-10-07 16:01:30.668099 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:01:30.798650 | controller | 2025-10-07 16:01:30.798717 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-07 16:01:30.805199 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:01:35.808733 | controller | Checking login to the cluster 23... 2025-10-07 16:01:35.991524 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:01:36.084647 | controller | 2025-10-07 16:01:36.084765 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-07 16:01:36.094700 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:01:41.099495 | controller | Checking login to the cluster 24... 2025-10-07 16:01:41.289003 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:01:41.467930 | controller | 2025-10-07 16:01:41.468066 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-07 16:01:41.479737 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:01:46.483145 | controller | Checking login to the cluster 25... 2025-10-07 16:01:46.665144 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:01:46.796036 | controller | 2025-10-07 16:01:46.796095 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503 2025-10-07 16:01:46.805512 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:01:51.809690 | controller | Checking login to the cluster 26... 2025-10-07 16:01:52.410328 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:01:53.953714 | controller | 2025-10-07 16:01:53.953806 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:01:53.959753 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:01:58.962785 | controller | Checking login to the cluster 27... 2025-10-07 16:01:59.134941 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:01:59.205934 | controller | 2025-10-07 16:01:59.206192 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:01:59.215462 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:02:04.219247 | controller | Checking login to the cluster 28... 2025-10-07 16:02:04.417331 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:02:04.503905 | controller | 2025-10-07 16:02:04.504028 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:02:04.514551 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:02:09.518453 | controller | Checking login to the cluster 29... 2025-10-07 16:02:09.705184 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:02:09.731182 | controller | 2025-10-07 16:02:09.731259 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:02:09.737935 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:02:14.742411 | controller | Checking login to the cluster 30... 2025-10-07 16:02:14.946613 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:02:14.985071 | controller | 2025-10-07 16:02:14.985237 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:02:14.996326 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:02:19.999863 | controller | Checking login to the cluster 31... 2025-10-07 16:02:20.123899 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:02:20.145828 | controller | 2025-10-07 16:02:20.145922 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:02:20.152258 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:02:25.154729 | controller | Checking login to the cluster 32... 2025-10-07 16:02:25.312040 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:02:25.335022 | controller | 2025-10-07 16:02:25.335086 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port? 2025-10-07 16:02:25.339553 | controller | Can not login to the CRC cluster. Sleeping... 2025-10-07 16:02:30.343140 | controller | Checking login to the cluster 33... 2025-10-07 16:02:30.474055 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported! 2025-10-07 16:02:30.913235 | controller | 2025-10-07 16:02:30.913345 | controller | Login successful. 2025-10-07 16:02:30.944518 | controller | 2025-10-07 16:02:30.944614 | controller | You have access to 65 projects, the list has been suppressed. You can list all projects with 'oc projects' 2025-10-07 16:02:30.968991 | controller | 2025-10-07 16:02:30.969197 | controller | Using project "default". 2025-10-07 16:02:31.352766 | controller | changed 2025-10-07 16:02:31.366908 | 2025-10-07 16:02:31.367119 | TASK [Delete all openshift-marketplace pods] 2025-10-07 16:02:32.779078 | controller | pod "certified-operators-7gsss" deleted 2025-10-07 16:02:32.798859 | controller | pod "certified-operators-f8szs" deleted 2025-10-07 16:02:32.813304 | controller | pod "community-operators-5zsqg" deleted 2025-10-07 16:02:32.836403 | controller | pod "community-operators-mqd4z" deleted 2025-10-07 16:02:32.863262 | controller | pod "marketplace-operator-79b997595-d2lpl" deleted 2025-10-07 16:02:32.889571 | controller | pod "redhat-marketplace-5lljl" deleted 2025-10-07 16:02:32.917363 | controller | pod "redhat-marketplace-ps6xk" deleted 2025-10-07 16:02:32.947300 | controller | pod "redhat-operators-mpv5n" deleted 2025-10-07 16:02:32.972039 | controller | pod "redhat-operators-tqxl9" deleted 2025-10-07 16:03:30.475634 | controller | ok 2025-10-07 16:03:30.481610 | 2025-10-07 16:03:30.483883 | TASK [Copy script ensure_services_up.sh] 2025-10-07 16:03:30.562083 | controller | skipping: Conditional result was False 2025-10-07 16:03:30.568629 | 2025-10-07 16:03:30.568725 | TASK [Ensure all pods are up and running] 2025-10-07 16:03:31.098003 | controller | skipping: Conditional result was False 2025-10-07 16:03:31.110027 | 2025-10-07 16:03:31.110172 | TASK [Check if there is a image build date file] 2025-10-07 16:03:31.803273 | controller | ok 2025-10-07 16:03:31.815613 | 2025-10-07 16:03:31.815787 | TASK [Print image date if available] 2025-10-07 16:03:32.186828 | controller | Tue Apr 1 06:17:46 PM EDT 2025 2025-10-07 16:03:32.358618 | controller | changed 2025-10-07 16:03:32.369656 | 2025-10-07 16:03:32.369791 | TASK [Fix machineconfigpool] 2025-10-07 16:03:32.453353 | controller | skipping: Conditional result was False 2025-10-07 16:03:32.463452 | 2025-10-07 16:03:32.463583 | TASK [Show available nodes] 2025-10-07 16:03:34.118596 | controller | NAME STATUS ROLES AGE VERSION 2025-10-07 16:03:34.121893 | controller | crc Ready control-plane,master,worker 226d v1.31.5 2025-10-07 16:03:34.511042 | controller | ok: Runtime: 0:00:01.007903 2025-10-07 16:03:34.516878 | 2025-10-07 16:03:34.516946 | TASK [Configure insecure registry inside crc vm] 2025-10-07 16:03:34.550775 | controller | skipping: Conditional result was False 2025-10-07 16:03:34.556911 | 2025-10-07 16:03:34.556983 | TASK [Catch CRC IP] 2025-10-07 16:03:35.150104 | controller | 192.168.130.11 2025-10-07 16:03:35.592815 | controller | changed 2025-10-07 16:03:35.646458 | 2025-10-07 16:03:35.646600 | TASK [Check if id_ed25519 key exists] 2025-10-07 16:03:36.187448 | controller | ok 2025-10-07 16:03:36.200994 | 2025-10-07 16:03:36.201229 | TASK [Set fact if new keypair exists] 2025-10-07 16:03:36.231847 | controller | ok 2025-10-07 16:03:36.237083 | 2025-10-07 16:03:36.237151 | TASK [Run crc] 2025-10-07 16:03:38.959512 | controller | 2025-10-07 16:03:50.169136 | controller | PLAY [OpenShift secret copy and CRC securitization] **************************** 2025-10-07 16:03:50.169205 | controller | 2025-10-07 16:03:50.169213 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-07 16:03:50.169234 | controller | [WARNING]: Platform linux on host 192.168.130.11 is using the discovered Python 2025-10-07 16:03:50.225510 | controller | interpreter at /usr/bin/python3.9, but future installation of another Python 2025-10-07 16:03:50.225575 | controller | interpreter could change the meaning of that path. See 2025-10-07 16:03:50.225582 | controller | https://docs.ansible.com/ansible- 2025-10-07 16:03:50.225590 | controller | core/2.14/reference_appendices/interpreter_discovery.html for more information. 2025-10-07 16:03:50.225607 | controller | ok: [192.168.130.11] 2025-10-07 16:03:52.336020 | controller | 2025-10-07 16:03:52.336167 | controller | TASK [Copy pull-secret] ******************************************************** 2025-10-07 16:03:52.336183 | controller | changed: [192.168.130.11] 2025-10-07 16:03:53.913289 | controller | 2025-10-07 16:03:53.913345 | controller | TASK [Add crio config] ********************************************************* 2025-10-07 16:03:53.913359 | controller | changed: [192.168.130.11] 2025-10-07 16:04:20.559833 | controller | 2025-10-07 16:04:20.559916 | controller | TASK [Restart crio to pickup new configuration] ******************************** 2025-10-07 16:04:20.559943 | controller | changed: [192.168.130.11] 2025-10-07 16:04:20.620246 | controller | 2025-10-07 16:04:20.620308 | controller | PLAY RECAP ********************************************************************* 2025-10-07 16:04:20.620316 | controller | 192.168.130.11 : ok=4 changed=3 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2025-10-07 16:04:20.620323 | controller | 2025-10-07 16:04:20.858711 | controller | changed 2025-10-07 16:04:20.875964 | 2025-10-07 16:04:20.876132 | TASK [Set slower etcd profile] 2025-10-07 16:04:20.939324 | controller | ok 2025-10-07 16:04:20.966091 | 2025-10-07 16:04:20.966212 | TASK [prepare-crc-cloud : Set slower profile] 2025-10-07 16:04:21.438983 | controller | etcd.operator.openshift.io/cluster patched 2025-10-07 16:04:21.504075 | controller | ok 2025-10-07 16:04:21.511484 | 2025-10-07 16:04:21.511551 | TASK [prepare-crc-cloud : Wait 30 seconds to apply] 2025-10-07 16:04:51.545108 | controller | ok 2025-10-07 16:04:51.552091 | 2025-10-07 16:04:51.552182 | TASK [prepare-crc-cloud : Get pod phase status] 2025-10-07 16:10:04.888608 | controller | ok 2025-10-07 16:10:04.914281 | 2025-10-07 16:10:04.914422 | TASK [Set limit for disk usage - IOPS and Read/Write] 2025-10-07 16:10:05.000155 | controller | ok 2025-10-07 16:10:05.015709 | controller | included: /var/lib/zuul/builds/36cb99831e744dc499f79029cfe2ad62/trusted/project_0/review.rdoproject.org/config/playbooks/crc/limit-disk-usage.yaml 2025-10-07 16:10:05.056621 | 2025-10-07 16:10:05.056781 | TASK [Set facts for disk limitation] 2025-10-07 16:10:05.078599 | controller | ok 2025-10-07 16:10:05.083931 | 2025-10-07 16:10:05.083996 | TASK [Print limits for the disk] 2025-10-07 16:10:05.113511 | controller | ok: Set limit to crc VM to the vda disk: iops 18000 read/write 262144000 2025-10-07 16:10:05.118756 | 2025-10-07 16:10:05.118818 | TASK [Set limit to crc VM to the vda disk] 2025-10-07 16:10:05.527435 | controller | 2025-10-07 16:10:05.647531 | controller | changed 2025-10-07 16:10:05.663219 | 2025-10-07 16:10:05.663373 | TASK [Pause machineconfigpool] 2025-10-07 16:10:05.754443 | controller | ok 2025-10-07 16:10:05.780245 | 2025-10-07 16:10:05.780332 | LOOP [prepare-crc-cloud : Pause the machineconfig configuration if mcp is not needed] 2025-10-07 16:10:06.399512 | controller | ok 2025-10-07 16:10:06.899281 | controller | ok 2025-10-07 16:10:06.916143 | 2025-10-07 16:10:06.916259 | TASK [prepare-crc-cloud : Print current machineconfigpool] 2025-10-07 16:10:07.408329 | controller | NAME CONFIG UPDATED UPDATING DEGRADED MACHINECOUNT READYMACHINECOUNT UPDATEDMACHINECOUNT DEGRADEDMACHINECOUNT AGE 2025-10-07 16:10:07.414013 | controller | master rendered-master-95020e6e79c40b4dd095817bccfc5060 True False False 1 1 1 0 226d 2025-10-07 16:10:07.414062 | controller | worker rendered-worker-594cc07c9feefb5e37f2530ac457d36c True False False 0 0 0 0 226d 2025-10-07 16:10:07.465771 | controller | ok 2025-10-07 16:10:07.517869 | 2025-10-07 16:10:07.517963 | PLAY [Set IOPS and Read/Write disk limitation excluding controller] 2025-10-07 16:10:07.518118 | 2025-10-07 16:10:07.518180 | PLAY RECAP 2025-10-07 16:10:07.518246 | controller | ok: 25 changed: 10 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0 2025-10-07 16:10:07.518277 | 2025-10-07 16:10:07.656071 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master] 2025-10-07 16:10:07.657645 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-07 16:10:08.272613 | 2025-10-07 16:10:08.272728 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-07 16:10:08.293401 | 2025-10-07 16:10:08.293496 | TASK [Create zuul-output directory] 2025-10-07 16:10:08.786593 | controller | changed 2025-10-07 16:10:08.791658 | 2025-10-07 16:10:08.791746 | TASK [Slurp Zuul inventory test] 2025-10-07 16:10:09.136719 | controller -> localhost | ok 2025-10-07 16:10:09.145304 | 2025-10-07 16:10:09.145386 | TASK [Save zuul inventory] 2025-10-07 16:10:10.706963 | controller | changed 2025-10-07 16:10:10.711887 | 2025-10-07 16:10:10.711950 | TASK [Save zuul vars without the change_message] 2025-10-07 16:10:12.245522 | controller | changed 2025-10-07 16:10:12.287135 | 2025-10-07 16:10:12.287256 | PLAY RECAP 2025-10-07 16:10:12.287312 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-07 16:10:12.287339 | 2025-10-07 16:10:12.426663 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-07 16:10:12.427782 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-07 16:10:13.147590 | 2025-10-07 16:10:13.147846 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-10-07 16:10:13.180272 | 2025-10-07 16:10:13.180513 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-10-07 16:10:13.212524 | controller | ok 2025-10-07 16:10:13.263526 | 2025-10-07 16:10:13.263755 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-07 16:10:13.292209 | controller | skipping: Conditional result was False 2025-10-07 16:10:13.301801 | 2025-10-07 16:10:13.301905 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-07 16:10:13.814987 | controller | ok 2025-10-07 16:10:13.823581 | 2025-10-07 16:10:13.824031 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-07 16:10:15.062930 | controller | ok 2025-10-07 16:10:15.073145 | 2025-10-07 16:10:15.073220 | TASK [Prepare workspace] 2025-10-07 16:10:15.091806 | controller | ok 2025-10-07 16:10:15.109197 | 2025-10-07 16:10:15.109268 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-07 16:10:15.587270 | controller | ok 2025-10-07 16:10:15.597008 | 2025-10-07 16:10:15.597154 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-07 16:10:16.890632 | controller | Output suppressed because no_log was given 2025-10-07 16:10:16.905286 | 2025-10-07 16:10:16.905647 | LOOP [Create zuul-output directory] 2025-10-07 16:10:17.324708 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-10-07 16:10:17.580154 | controller | ok: "/home/zuul/zuul-output/logs" 2025-10-07 16:10:17.590578 | 2025-10-07 16:10:17.590724 | TASK [Install required packages] 2025-10-07 16:10:33.274953 | controller | ok: Nothing to do 2025-10-07 16:10:33.290177 | 2025-10-07 16:10:33.290428 | TASK [Install venv] 2025-10-07 16:12:02.536975 | controller | changed 2025-10-07 16:12:02.567912 | 2025-10-07 16:12:02.568001 | PLAY RECAP 2025-10-07 16:12:02.568042 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-07 16:12:02.568063 | 2025-10-07 16:12:02.671558 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-07 16:12:02.673300 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-07 16:12:03.279384 | 2025-10-07 16:12:03.279488 | PLAY [Run ci/playbooks/molecule-test.yml] 2025-10-07 16:12:03.300534 | 2025-10-07 16:12:03.300625 | TASK [Gather required facts] 2025-10-07 16:12:04.006294 | controller | ok 2025-10-07 16:12:04.014334 | 2025-10-07 16:12:04.014433 | TASK [Load environment var if instructed to] 2025-10-07 16:12:04.040993 | controller | skipping: Conditional result was False 2025-10-07 16:12:04.050243 | 2025-10-07 16:12:04.050362 | TASK [Run molecule] 2025-10-07 16:12:05.381231 | controller | INFO Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml 2025-10-07 16:12:05.491908 | controller | INFO Performing prerun with role_name_check=0... 2025-10-07 16:12:23.704169 | 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-07 16:12:23.704634 | 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-07 16:12:23.705099 | 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-07 16:12:23.705761 | 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-07 16:12:23.706444 | controller | WARNING Another version of 'cifmw.general' 1.0.0+6451eac5 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.0.0+6451eac5 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 16:12:23.707088 | 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-07 16:12:23.707753 | 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-07 16:12:23.708406 | 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-07 16:12:23.709059 | 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-07 16:12:23.709722 | 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-07 16:12:23.710381 | 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-07 16:12:23.711020 | 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-07 16:12:23.711715 | 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-07 16:12:23.712344 | 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-07 16:12:23.713001 | 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-07 16:12:23.725334 | controller | INFO Running default > prepare 2025-10-07 16:12:24.566893 | controller | 2025-10-07 16:12:24.567004 | controller | PLAY [Prepare] ***************************************************************** 2025-10-07 16:12:24.567137 | controller | 2025-10-07 16:12:24.567271 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-07 16:12:24.567384 | controller | Tuesday 07 October 2025 16:12:24 +0000 (0:00:00.015) 0:00:00.015 ******* 2025-10-07 16:12:25.837899 | controller | ok: [instance] 2025-10-07 16:12:25.837967 | controller | 2025-10-07 16:12:25.838120 | controller | TASK [test_deps : Gather facts if they don't exist] **************************** 2025-10-07 16:12:25.838212 | controller | Tuesday 07 October 2025 16:12:25 +0000 (0:00:01.271) 0:00:01.286 ******* 2025-10-07 16:12:25.864244 | controller | skipping: [instance] 2025-10-07 16:12:25.864284 | controller | 2025-10-07 16:12:25.864484 | controller | TASK [test_deps : Gather variables for each operating system] ****************** 2025-10-07 16:12:25.864529 | controller | Tuesday 07 October 2025 16:12:25 +0000 (0:00:00.026) 0:00:01.313 ******* 2025-10-07 16:12:25.915743 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml) 2025-10-07 16:12:25.915817 | controller | 2025-10-07 16:12:25.915919 | controller | TASK [test_deps : Detect mirrors file] ***************************************** 2025-10-07 16:12:25.916021 | controller | Tuesday 07 October 2025 16:12:25 +0000 (0:00:00.051) 0:00:01.364 ******* 2025-10-07 16:12:26.311243 | controller | ok: [instance] 2025-10-07 16:12:26.311295 | controller | 2025-10-07 16:12:26.311434 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] *********** 2025-10-07 16:12:26.311572 | controller | Tuesday 07 October 2025 16:12:26 +0000 (0:00:00.395) 0:00:01.760 ******* 2025-10-07 16:12:26.735285 | controller | ok: [instance] 2025-10-07 16:12:26.735359 | controller | 2025-10-07 16:12:26.735566 | controller | TASK [test_deps : Disable ubi 9 repos] ***************************************** 2025-10-07 16:12:26.735741 | controller | Tuesday 07 October 2025 16:12:26 +0000 (0:00:00.423) 0:00:02.183 ******* 2025-10-07 16:12:27.707325 | controller | ok: [instance] => (item=ubi-9-appstream) 2025-10-07 16:12:27.707542 | controller | ok: [instance] => (item=ubi-9-baseos) 2025-10-07 16:12:27.707704 | controller | ok: [instance] => (item=ubi-9-codeready-builder) 2025-10-07 16:12:27.707718 | controller | 2025-10-07 16:12:27.707809 | controller | TASK [Deploy repo-setup] ******************************************************* 2025-10-07 16:12:27.707924 | controller | Tuesday 07 October 2025 16:12:27 +0000 (0:00:00.972) 0:00:03.156 ******* 2025-10-07 16:12:27.779628 | controller | 2025-10-07 16:12:27.779781 | controller | TASK [repo_setup : Ensure directories are present] ***************************** 2025-10-07 16:12:27.779896 | controller | Tuesday 07 October 2025 16:12:27 +0000 (0:00:00.072) 0:00:03.228 ******* 2025-10-07 16:12:28.520859 | controller | changed: [instance] => (item=tmp) 2025-10-07 16:12:28.521186 | controller | changed: [instance] => (item=artifacts/repositories) 2025-10-07 16:12:28.521205 | controller | changed: [instance] => (item=venv/repo_setup) 2025-10-07 16:12:28.521214 | controller | 2025-10-07 16:12:28.521221 | controller | TASK [repo_setup : Make sure git-core package is installed] ******************** 2025-10-07 16:12:28.521233 | controller | Tuesday 07 October 2025 16:12:28 +0000 (0:00:00.740) 0:00:03.969 ******* 2025-10-07 16:12:29.582367 | controller | ok: [instance] 2025-10-07 16:12:29.582979 | controller | 2025-10-07 16:12:30.649017 | controller | TASK [repo_setup : Get repo-setup repository] ********************************** 2025-10-07 16:12:30.649094 | controller | Tuesday 07 October 2025 16:12:29 +0000 (0:00:01.061) 0:00:05.031 ******* 2025-10-07 16:12:30.649108 | controller | changed: [instance] 2025-10-07 16:12:30.649134 | controller | 2025-10-07 16:12:30.649254 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************ 2025-10-07 16:12:30.649268 | controller | Tuesday 07 October 2025 16:12:30 +0000 (0:00:01.066) 0:00:06.097 ******* 2025-10-07 16:12:41.491148 | controller | changed: [instance] 2025-10-07 16:12:41.491254 | controller | 2025-10-07 16:12:41.491283 | controller | TASK [repo_setup : Install repo-setup package] ********************************* 2025-10-07 16:12:42.422051 | controller | Tuesday 07 October 2025 16:12:41 +0000 (0:00:10.842) 0:00:16.939 ******* 2025-10-07 16:12:42.422093 | controller | changed: [instance] 2025-10-07 16:12:42.422225 | controller | 2025-10-07 16:12:42.422432 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] *** 2025-10-07 16:12:42.422627 | controller | Tuesday 07 October 2025 16:12:42 +0000 (0:00:00.930) 0:00:17.870 ******* 2025-10-07 16:12:42.459779 | controller | skipping: [instance] 2025-10-07 16:12:42.459867 | controller | 2025-10-07 16:12:42.460042 | controller | TASK [repo_setup : Run repo-setup] ********************************************* 2025-10-07 16:12:42.460182 | controller | Tuesday 07 October 2025 16:12:42 +0000 (0:00:00.037) 0:00:17.907 ******* 2025-10-07 16:12:43.202210 | controller | changed: [instance] 2025-10-07 16:12:43.202267 | controller | 2025-10-07 16:12:43.202369 | controller | TASK [repo_setup : Get component repo] ***************************************** 2025-10-07 16:12:43.202754 | controller | Tuesday 07 October 2025 16:12:43 +0000 (0:00:00.742) 0:00:18.650 ******* 2025-10-07 16:12:43.249839 | controller | skipping: [instance] 2025-10-07 16:12:43.249960 | controller | 2025-10-07 16:12:43.250128 | controller | TASK [repo_setup : Rename component repo] ************************************** 2025-10-07 16:12:43.250317 | controller | Tuesday 07 October 2025 16:12:43 +0000 (0:00:00.047) 0:00:18.698 ******* 2025-10-07 16:12:43.292991 | controller | skipping: [instance] 2025-10-07 16:12:43.293043 | controller | 2025-10-07 16:12:43.293179 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] ******* 2025-10-07 16:12:43.293715 | controller | Tuesday 07 October 2025 16:12:43 +0000 (0:00:00.043) 0:00:18.741 ******* 2025-10-07 16:12:43.337592 | controller | skipping: [instance] 2025-10-07 16:12:43.337745 | controller | 2025-10-07 16:12:43.337768 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************ 2025-10-07 16:12:43.337856 | controller | Tuesday 07 October 2025 16:12:43 +0000 (0:00:00.044) 0:00:18.786 ******* 2025-10-07 16:12:43.893259 | controller | changed: [instance] 2025-10-07 16:12:43.893352 | controller | 2025-10-07 16:12:43.893782 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] ******************* 2025-10-07 16:12:44.476650 | controller | Tuesday 07 October 2025 16:12:43 +0000 (0:00:00.555) 0:00:19.342 ******* 2025-10-07 16:12:44.476813 | controller | changed: [instance] 2025-10-07 16:12:44.477040 | controller | 2025-10-07 16:12:44.477059 | controller | TASK [repo_setup : Dump current-podified hash] ********************************* 2025-10-07 16:12:44.477077 | controller | Tuesday 07 October 2025 16:12:44 +0000 (0:00:00.580) 0:00:19.922 ******* 2025-10-07 16:12:44.519115 | controller | skipping: [instance] 2025-10-07 16:12:44.519896 | controller | 2025-10-07 16:12:44.559456 | controller | TASK [repo_setup : Slurp current podified hash] ******************************** 2025-10-07 16:12:44.559520 | controller | Tuesday 07 October 2025 16:12:44 +0000 (0:00:00.044) 0:00:19.967 ******* 2025-10-07 16:12:44.559538 | controller | skipping: [instance] 2025-10-07 16:12:44.559618 | controller | 2025-10-07 16:12:44.559821 | controller | TASK [repo_setup : Update the value of full_hash] ****************************** 2025-10-07 16:12:44.559992 | controller | Tuesday 07 October 2025 16:12:44 +0000 (0:00:00.040) 0:00:20.008 ******* 2025-10-07 16:12:44.617313 | controller | skipping: [instance] 2025-10-07 16:12:44.617422 | controller | 2025-10-07 16:12:44.617671 | controller | TASK [repo_setup : Export hashes facts for further use] ************************ 2025-10-07 16:12:44.617842 | controller | Tuesday 07 October 2025 16:12:44 +0000 (0:00:00.057) 0:00:20.065 ******* 2025-10-07 16:12:44.665041 | controller | ok: [instance] 2025-10-07 16:12:44.665176 | controller | 2025-10-07 16:12:44.665364 | controller | TASK [repo_setup : Create download directory] ********************************** 2025-10-07 16:12:44.665549 | controller | Tuesday 07 October 2025 16:12:44 +0000 (0:00:00.048) 0:00:20.113 ******* 2025-10-07 16:12:44.700745 | controller | skipping: [instance] 2025-10-07 16:12:44.700893 | controller | 2025-10-07 16:12:44.701117 | controller | TASK [repo_setup : Print the URL to request] *********************************** 2025-10-07 16:12:44.701317 | controller | Tuesday 07 October 2025 16:12:44 +0000 (0:00:00.035) 0:00:20.149 ******* 2025-10-07 16:12:44.734161 | controller | skipping: [instance] 2025-10-07 16:12:44.734253 | controller | 2025-10-07 16:12:44.734399 | controller | TASK [Download the RPM] ******************************************************** 2025-10-07 16:12:44.734546 | controller | Tuesday 07 October 2025 16:12:44 +0000 (0:00:00.033) 0:00:20.182 ******* 2025-10-07 16:12:44.770855 | controller | skipping: [instance] 2025-10-07 16:12:44.770938 | controller | 2025-10-07 16:12:44.771067 | controller | TASK [repo_setup : Install RHOS Release tool] ********************************** 2025-10-07 16:12:44.771201 | controller | Tuesday 07 October 2025 16:12:44 +0000 (0:00:00.036) 0:00:20.219 ******* 2025-10-07 16:12:44.805298 | controller | skipping: [instance] 2025-10-07 16:12:44.805344 | controller | 2025-10-07 16:12:44.805515 | controller | TASK [repo_setup : Get rhos-release tool version] ****************************** 2025-10-07 16:12:44.805662 | controller | Tuesday 07 October 2025 16:12:44 +0000 (0:00:00.034) 0:00:20.254 ******* 2025-10-07 16:12:44.840905 | controller | skipping: [instance] 2025-10-07 16:12:44.840991 | controller | 2025-10-07 16:12:44.841059 | controller | TASK [repo_setup : Print rhos-release tool version] **************************** 2025-10-07 16:12:44.841194 | controller | Tuesday 07 October 2025 16:12:44 +0000 (0:00:00.035) 0:00:20.289 ******* 2025-10-07 16:12:44.880551 | controller | skipping: [instance] 2025-10-07 16:12:44.880612 | controller | 2025-10-07 16:12:44.880734 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] *** 2025-10-07 16:12:44.880848 | controller | Tuesday 07 October 2025 16:12:44 +0000 (0:00:00.039) 0:00:20.329 ******* 2025-10-07 16:12:44.920868 | controller | skipping: [instance] 2025-10-07 16:12:44.920913 | controller | 2025-10-07 16:12:44.921069 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] *************************** 2025-10-07 16:12:44.921171 | controller | Tuesday 07 October 2025 16:12:44 +0000 (0:00:00.040) 0:00:20.369 ******* 2025-10-07 16:12:45.163915 | controller | ok: [instance] 2025-10-07 16:12:45.163981 | controller | 2025-10-07 16:12:45.164069 | controller | TASK [repo_setup : Use RDO proxy mirrors] ************************************** 2025-10-07 16:12:45.164173 | controller | Tuesday 07 October 2025 16:12:45 +0000 (0:00:00.242) 0:00:20.612 ******* 2025-10-07 16:12:45.478884 | controller | changed: [instance] 2025-10-07 16:12:45.478933 | controller | 2025-10-07 16:12:45.479068 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] *** 2025-10-07 16:12:45.479177 | controller | Tuesday 07 October 2025 16:12:45 +0000 (0:00:00.315) 0:00:20.927 ******* 2025-10-07 16:12:45.760851 | controller | changed: [instance] 2025-10-07 16:12:45.761099 | controller | 2025-10-07 16:12:45.761510 | controller | TASK [repo_setup : Check for gating.repo file on content provider] ************* 2025-10-07 16:12:45.761798 | controller | Tuesday 07 October 2025 16:12:45 +0000 (0:00:00.281) 0:00:21.209 ******* 2025-10-07 16:12:45.800533 | controller | skipping: [instance] 2025-10-07 16:12:45.800831 | controller | 2025-10-07 16:12:45.801191 | controller | TASK [repo_setup : Populate gating repo from content provider ip] ************** 2025-10-07 16:12:45.801570 | controller | Tuesday 07 October 2025 16:12:45 +0000 (0:00:00.038) 0:00:21.248 ******* 2025-10-07 16:12:45.845900 | controller | skipping: [instance] 2025-10-07 16:12:45.845976 | controller | 2025-10-07 16:12:45.846083 | controller | TASK [repo_setup : Check for DLRN repo at the destination] ********************* 2025-10-07 16:12:45.846213 | controller | Tuesday 07 October 2025 16:12:45 +0000 (0:00:00.045) 0:00:21.294 ******* 2025-10-07 16:12:45.896395 | controller | skipping: [instance] 2025-10-07 16:12:45.896463 | controller | 2025-10-07 16:12:45.896575 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] *** 2025-10-07 16:12:45.896674 | controller | Tuesday 07 October 2025 16:12:45 +0000 (0:00:00.050) 0:00:21.344 ******* 2025-10-07 16:12:45.933138 | controller | skipping: [instance] 2025-10-07 16:12:45.933317 | controller | 2025-10-07 16:12:45.933627 | controller | TASK [repo_setup : Check for DLRN component repo] ****************************** 2025-10-07 16:12:45.933952 | controller | Tuesday 07 October 2025 16:12:45 +0000 (0:00:00.037) 0:00:21.381 ******* 2025-10-07 16:12:45.966897 | controller | skipping: [instance] 2025-10-07 16:12:45.967079 | controller | 2025-10-07 16:12:45.967329 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] *** 2025-10-07 16:12:45.967573 | controller | Tuesday 07 October 2025 16:12:45 +0000 (0:00:00.033) 0:00:21.415 ******* 2025-10-07 16:12:45.999627 | controller | skipping: [instance] 2025-10-07 16:12:45.999782 | controller | 2025-10-07 16:12:46.000013 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ******** 2025-10-07 16:12:46.000589 | controller | Tuesday 07 October 2025 16:12:45 +0000 (0:00:00.030) 0:00:21.446 ******* 2025-10-07 16:12:46.020456 | controller | skipping: [instance] 2025-10-07 16:12:46.020592 | controller | 2025-10-07 16:12:46.020850 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ****** 2025-10-07 16:12:46.021035 | controller | Tuesday 07 October 2025 16:12:46 +0000 (0:00:00.022) 0:00:21.469 ******* 2025-10-07 16:12:46.070300 | controller | skipping: [instance] 2025-10-07 16:12:46.070459 | controller | 2025-10-07 16:12:46.070730 | controller | TASK [repo_setup : Cleanup existing metadata] ********************************** 2025-10-07 16:12:46.070999 | controller | Tuesday 07 October 2025 16:12:46 +0000 (0:00:00.049) 0:00:21.518 ******* 2025-10-07 16:12:46.094195 | controller | skipping: [instance] 2025-10-07 16:12:46.094413 | controller | 2025-10-07 16:12:46.094439 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] ********* 2025-10-07 16:12:46.094621 | controller | Tuesday 07 October 2025 16:12:46 +0000 (0:00:00.024) 0:00:21.543 ******* 2025-10-07 16:12:46.128422 | controller | skipping: [instance] 2025-10-07 16:12:46.128598 | controller | 2025-10-07 16:12:46.128927 | controller | TASK [test_deps : Look for redhat-release rpm] ********************************* 2025-10-07 16:12:46.129004 | controller | Tuesday 07 October 2025 16:12:46 +0000 (0:00:00.033) 0:00:21.577 ******* 2025-10-07 16:12:46.167578 | controller | skipping: [instance] 2025-10-07 16:12:46.167628 | controller | 2025-10-07 16:12:46.167766 | controller | TASK [test_deps : Remove redhat-release] *************************************** 2025-10-07 16:12:46.167898 | controller | Tuesday 07 October 2025 16:12:46 +0000 (0:00:00.039) 0:00:21.616 ******* 2025-10-07 16:12:46.214928 | controller | skipping: [instance] 2025-10-07 16:12:46.214994 | controller | 2025-10-07 16:12:46.215089 | controller | TASK [test_deps : Install centos-stream-release] ******************************* 2025-10-07 16:12:46.215215 | controller | Tuesday 07 October 2025 16:12:46 +0000 (0:00:00.047) 0:00:21.663 ******* 2025-10-07 16:12:46.249992 | controller | skipping: [instance] 2025-10-07 16:12:46.250055 | controller | 2025-10-07 16:12:46.250158 | controller | TASK [test_deps : Install additional packages] ********************************* 2025-10-07 16:12:46.250288 | controller | Tuesday 07 October 2025 16:12:46 +0000 (0:00:00.035) 0:00:21.698 ******* 2025-10-07 16:12:46.292728 | controller | skipping: [instance] 2025-10-07 16:12:46.292777 | controller | 2025-10-07 16:12:46.292889 | controller | TASK [test_deps : Install selinux python libs] ********************************* 2025-10-07 16:12:46.292995 | controller | Tuesday 07 October 2025 16:12:46 +0000 (0:00:00.036) 0:00:21.735 ******* 2025-10-07 16:13:36.396491 | controller | ok: [instance] 2025-10-07 16:13:36.396684 | controller | 2025-10-07 16:13:36.396961 | controller | TASK [test_deps : Install python yaml libs] ************************************ 2025-10-07 16:13:36.397178 | controller | Tuesday 07 October 2025 16:13:36 +0000 (0:00:50.107) 0:01:11.843 ******* 2025-10-07 16:13:38.093927 | controller | ok: [instance] 2025-10-07 16:13:38.093999 | controller | 2025-10-07 16:13:38.094013 | controller | TASK [test_deps : Install extra packages] ************************************** 2025-10-07 16:13:38.094142 | controller | Tuesday 07 October 2025 16:13:38 +0000 (0:00:01.699) 0:01:13.542 ******* 2025-10-07 16:13:38.154569 | controller | skipping: [instance] 2025-10-07 16:13:38.154659 | controller | 2025-10-07 16:13:38.154843 | controller | PLAY RECAP ********************************************************************* 2025-10-07 16:13:38.154881 | controller | instance : ok=19 changed=9 unreachable=0 failed=0 skipped=30 rescued=0 ignored=0 2025-10-07 16:13:38.154930 | controller | 2025-10-07 16:13:38.155049 | controller | Tuesday 07 October 2025 16:13:38 +0000 (0:00:00.060) 0:01:13.603 ******* 2025-10-07 16:13:38.155125 | controller | =============================================================================== 2025-10-07 16:13:38.155231 | controller | test_deps : Install selinux python libs -------------------------------- 50.11s 2025-10-07 16:13:38.155304 | controller | repo_setup : Initialize python venv and install requirements ----------- 10.84s 2025-10-07 16:13:38.155463 | controller | test_deps : Install python yaml libs ------------------------------------ 1.70s 2025-10-07 16:13:38.155566 | controller | Gathering Facts --------------------------------------------------------- 1.27s 2025-10-07 16:13:38.155591 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.07s 2025-10-07 16:13:38.155719 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.06s 2025-10-07 16:13:38.155808 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.97s 2025-10-07 16:13:38.155925 | controller | repo_setup : Install repo-setup package --------------------------------- 0.93s 2025-10-07 16:13:38.156007 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.74s 2025-10-07 16:13:38.156102 | controller | repo_setup : Ensure directories are present ----------------------------- 0.74s 2025-10-07 16:13:38.156193 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.58s 2025-10-07 16:13:38.156290 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.56s 2025-10-07 16:13:38.156386 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.42s 2025-10-07 16:13:38.156516 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.40s 2025-10-07 16:13:38.156590 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.32s 2025-10-07 16:13:38.156700 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.28s 2025-10-07 16:13:38.156809 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.24s 2025-10-07 16:13:38.156910 | controller | Deploy repo-setup ------------------------------------------------------- 0.07s 2025-10-07 16:13:38.156992 | controller | test_deps : Install extra packages -------------------------------------- 0.06s 2025-10-07 16:13:38.157077 | controller | repo_setup : Update the value of full_hash ------------------------------ 0.06s 2025-10-07 16:13:38.244427 | controller | INFO Running default > converge 2025-10-07 16:13:38.811535 | controller | 2025-10-07 16:13:38.811621 | controller | PLAY [Add crc to the inventory] ************************************************ 2025-10-07 16:13:38.811725 | controller | 2025-10-07 16:13:38.811849 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-07 16:13:38.811953 | controller | Tuesday 07 October 2025 16:13:38 +0000 (0:00:00.023) 0:00:00.023 ******* 2025-10-07 16:13:40.848330 | controller | ok: [instance] 2025-10-07 16:13:40.848371 | controller | 2025-10-07 16:13:40.848499 | controller | TASK [Add crc hostname with it's IP to /etc/hosts] ***************************** 2025-10-07 16:13:40.848605 | controller | Tuesday 07 October 2025 16:13:40 +0000 (0:00:02.037) 0:00:02.061 ******* 2025-10-07 16:13:41.226962 | controller | changed: [instance] 2025-10-07 16:13:41.227075 | controller | 2025-10-07 16:13:41.227134 | controller | TASK [Check if new ssh keypair exists] ***************************************** 2025-10-07 16:13:41.227254 | controller | Tuesday 07 October 2025 16:13:41 +0000 (0:00:00.378) 0:00:02.439 ******* 2025-10-07 16:13:41.260367 | controller | 2025-10-07 16:13:41.260534 | controller | TASK [recognize_ssh_keypair : Check if id_ed25519 key exists] ****************** 2025-10-07 16:13:41.260549 | controller | Tuesday 07 October 2025 16:13:41 +0000 (0:00:00.033) 0:00:02.473 ******* 2025-10-07 16:13:41.621864 | controller | ok: [instance] 2025-10-07 16:13:41.621925 | controller | 2025-10-07 16:13:41.622059 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair exists] ****************** 2025-10-07 16:13:41.622213 | controller | Tuesday 07 October 2025 16:13:41 +0000 (0:00:00.361) 0:00:02.835 ******* 2025-10-07 16:13:41.689050 | controller | ok: [instance] 2025-10-07 16:13:41.696609 | controller | 2025-10-07 16:13:41.696663 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair does not exists] ********* 2025-10-07 16:13:41.696675 | controller | Tuesday 07 October 2025 16:13:41 +0000 (0:00:00.031) 0:00:02.866 ******* 2025-10-07 16:13:41.696695 | controller | skipping: [instance] 2025-10-07 16:13:41.697124 | controller | 2025-10-07 16:13:41.697166 | controller | TASK [Add the crc host dynamically] ******************************************** 2025-10-07 16:13:41.760944 | controller | Tuesday 07 October 2025 16:13:41 +0000 (0:00:00.042) 0:00:02.909 ******* 2025-10-07 16:13:41.761055 | controller | changed: [instance] 2025-10-07 16:13:41.761159 | controller | 2025-10-07 16:13:41.761178 | controller | TASK [Add a dummy ethernet interface to target the crc NMstate] **************** 2025-10-07 16:13:41.761309 | controller | Tuesday 07 October 2025 16:13:41 +0000 (0:00:00.064) 0:00:02.974 ******* 2025-10-07 16:13:42.266559 | controller | changed: [instance] 2025-10-07 16:13:47.441253 | controller | 2025-10-07 16:13:47.441290 | controller | PLAY [Get CRC facts] *********************************************************** 2025-10-07 16:13:47.441298 | controller | 2025-10-07 16:13:47.441304 | controller | TASK [Fetch crc network facts] ************************************************* 2025-10-07 16:13:47.441310 | controller | Tuesday 07 October 2025 16:13:42 +0000 (0:00:00.504) 0:00:03.478 ******* 2025-10-07 16:13:47.441321 | controller | ok: [crc] 2025-10-07 16:13:47.484705 | controller | 2025-10-07 16:13:47.484753 | controller | PLAY [Converge] **************************************************************** 2025-10-07 16:13:47.484763 | controller | 2025-10-07 16:13:47.484770 | controller | TASK [Set common molecule test facts] ****************************************** 2025-10-07 16:13:47.484778 | controller | Tuesday 07 October 2025 16:13:47 +0000 (0:00:05.174) 0:00:08.653 ******* 2025-10-07 16:13:47.484794 | controller | ok: [instance] 2025-10-07 16:13:47.484826 | controller | 2025-10-07 16:13:47.484839 | controller | TASK [Prepare the Networking Env Definition for ci_nmstate role] *************** 2025-10-07 16:13:47.485040 | controller | Tuesday 07 October 2025 16:13:47 +0000 (0:00:00.043) 0:00:08.697 ******* 2025-10-07 16:13:47.532524 | controller | 2025-10-07 16:13:47.532587 | controller | TASK [networking_mapper : Check for Networking Definition file existence] ****** 2025-10-07 16:13:47.532729 | controller | Tuesday 07 October 2025 16:13:47 +0000 (0:00:00.047) 0:00:08.745 ******* 2025-10-07 16:13:47.623174 | controller | skipping: [instance] 2025-10-07 16:13:47.719976 | controller | 2025-10-07 16:13:47.720021 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************ 2025-10-07 16:13:47.720030 | controller | Tuesday 07 October 2025 16:13:47 +0000 (0:00:00.090) 0:00:08.835 ******* 2025-10-07 16:13:47.720042 | controller | skipping: [instance] 2025-10-07 16:13:47.720063 | controller | 2025-10-07 16:13:47.720366 | controller | TASK [networking_mapper : Check for interfaces info file existence] ************ 2025-10-07 16:13:47.764770 | controller | Tuesday 07 October 2025 16:13:47 +0000 (0:00:00.096) 0:00:08.932 ******* 2025-10-07 16:13:47.764828 | controller | skipping: [instance] 2025-10-07 16:13:47.807760 | controller | 2025-10-07 16:13:47.807809 | controller | TASK [networking_mapper : Load the interfaces info from file] ****************** 2025-10-07 16:13:47.807818 | controller | Tuesday 07 October 2025 16:13:47 +0000 (0:00:00.044) 0:00:08.977 ******* 2025-10-07 16:13:47.807830 | controller | skipping: [instance] 2025-10-07 16:13:47.807859 | controller | 2025-10-07 16:13:47.807869 | controller | TASK [networking_mapper : Ensure local fact is empty] ************************** 2025-10-07 16:13:47.808025 | controller | Tuesday 07 October 2025 16:13:47 +0000 (0:00:00.043) 0:00:09.020 ******* 2025-10-07 16:13:47.831606 | controller | ok: [instance] 2025-10-07 16:13:47.831776 | controller | 2025-10-07 16:13:47.831810 | controller | TASK [networking_mapper : Set the input Networking Definition and patch it if needed] *** 2025-10-07 16:13:47.831834 | controller | Tuesday 07 October 2025 16:13:47 +0000 (0:00:00.022) 0:00:09.043 ******* 2025-10-07 16:13:47.970812 | controller | ok: [instance] => (item={'networks': {'default': {'network': '38.102.83.0/24', 'gateway': '38.102.83.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.102.83.136'}}}}}) 2025-10-07 16:13:48.004972 | controller | 2025-10-07 16:13:48.005054 | controller | TASK [networking_mapper : Gather facts if requested] *************************** 2025-10-07 16:13:48.005085 | controller | Tuesday 07 October 2025 16:13:47 +0000 (0:00:00.139) 0:00:09.183 ******* 2025-10-07 16:13:48.005108 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/networking_mapper/tasks/_gather_facts.yml for instance 2025-10-07 16:13:48.446938 | controller | 2025-10-07 16:13:48.447016 | controller | TASK [networking_mapper : Gather the facts] ************************************ 2025-10-07 16:13:48.447026 | controller | Tuesday 07 October 2025 16:13:48 +0000 (0:00:00.034) 0:00:09.217 ******* 2025-10-07 16:13:48.447045 | controller | skipping: [instance] => (item=crc) 2025-10-07 16:13:48.447079 | controller | skipping: [instance] => (item=instance) 2025-10-07 16:13:48.447090 | controller | skipping: [instance] 2025-10-07 16:13:48.447189 | controller | 2025-10-07 16:13:48.447338 | controller | TASK [networking_mapper : Save instances refreshed facts for troubleshooting purposes] *** 2025-10-07 16:13:48.447441 | controller | Tuesday 07 October 2025 16:13:48 +0000 (0:00:00.442) 0:00:09.659 ******* 2025-10-07 16:13:49.242277 | controller | changed: [instance] 2025-10-07 16:13:49.242342 | controller | 2025-10-07 16:13:49.242624 | controller | TASK [networking_mapper : Ensure CI infrastructure dir exists] ***************** 2025-10-07 16:13:49.242649 | controller | Tuesday 07 October 2025 16:13:49 +0000 (0:00:00.795) 0:00:10.455 ******* 2025-10-07 16:13:49.656446 | controller | changed: [instance] 2025-10-07 16:13:49.656516 | controller | 2025-10-07 16:13:49.656680 | controller | TASK [networking_mapper : Write the Networking Definition to file] ************* 2025-10-07 16:13:49.656788 | controller | Tuesday 07 October 2025 16:13:49 +0000 (0:00:00.413) 0:00:10.869 ******* 2025-10-07 16:13:50.167713 | controller | changed: [instance] 2025-10-07 16:13:50.167769 | controller | 2025-10-07 16:13:50.167844 | controller | TASK [networking_mapper : Call the networking mapper] ************************** 2025-10-07 16:13:50.167969 | controller | Tuesday 07 October 2025 16:13:50 +0000 (0:00:00.511) 0:00:11.380 ******* 2025-10-07 16:13:50.268577 | controller | ok: [instance] 2025-10-07 16:13:50.268656 | controller | 2025-10-07 16:13:50.268794 | controller | TASK [networking_mapper : Set networking mapper facts] ************************* 2025-10-07 16:13:50.268959 | controller | Tuesday 07 October 2025 16:13:50 +0000 (0:00:00.100) 0:00:11.481 ******* 2025-10-07 16:13:50.298727 | controller | ok: [instance] 2025-10-07 16:13:50.298945 | controller | 2025-10-07 16:13:50.299178 | controller | TASK [networking_mapper : Write the Networking Environment Definition to file] *** 2025-10-07 16:13:50.299411 | controller | Tuesday 07 October 2025 16:13:50 +0000 (0:00:00.029) 0:00:11.511 ******* 2025-10-07 16:13:50.784827 | controller | changed: [instance] 2025-10-07 16:13:50.784931 | controller | 2025-10-07 16:13:50.784968 | controller | TASK [Call the role under test] ************************************************ 2025-10-07 16:13:50.785115 | controller | Tuesday 07 October 2025 16:13:50 +0000 (0:00:00.486) 0:00:11.997 ******* 2025-10-07 16:13:50.847223 | controller | 2025-10-07 16:13:50.847295 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] *** 2025-10-07 16:13:50.847381 | controller | Tuesday 07 October 2025 16:13:50 +0000 (0:00:00.062) 0:00:12.060 ******* 2025-10-07 16:13:50.880017 | controller | skipping: [instance] 2025-10-07 16:13:50.880090 | controller | 2025-10-07 16:13:50.880547 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ****** 2025-10-07 16:13:50.880659 | controller | Tuesday 07 October 2025 16:13:50 +0000 (0:00:00.032) 0:00:12.092 ******* 2025-10-07 16:13:50.918041 | controller | skipping: [instance] 2025-10-07 16:13:50.918108 | controller | 2025-10-07 16:13:50.918181 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************ 2025-10-07 16:13:50.918269 | controller | Tuesday 07 October 2025 16:13:50 +0000 (0:00:00.037) 0:00:12.130 ******* 2025-10-07 16:13:50.948487 | controller | skipping: [instance] 2025-10-07 16:13:50.948595 | controller | 2025-10-07 16:13:50.948742 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ****** 2025-10-07 16:13:50.948879 | controller | Tuesday 07 October 2025 16:13:50 +0000 (0:00:00.031) 0:00:12.161 ******* 2025-10-07 16:13:50.991801 | controller | skipping: [instance] 2025-10-07 16:13:50.991874 | controller | 2025-10-07 16:13:50.991950 | controller | TASK [ci_nmstate : Initialize NMstates for each instance] ********************** 2025-10-07 16:13:50.992058 | controller | Tuesday 07 October 2025 16:13:50 +0000 (0:00:00.043) 0:00:12.205 ******* 2025-10-07 16:13:51.037128 | controller | ok: [instance] 2025-10-07 16:13:51.037246 | controller | 2025-10-07 16:13:51.037372 | controller | TASK [ci_nmstate : Build NMstates from the Networking Environment Definition] *** 2025-10-07 16:13:51.037433 | controller | Tuesday 07 October 2025 16:13:51 +0000 (0:00:00.045) 0:00:12.250 ******* 2025-10-07 16:13:51.110768 | controller | ok: [instance] => (item=crc) 2025-10-07 16:13:51.110828 | controller | ok: [instance] => (item=instance) 2025-10-07 16:13:51.111056 | controller | 2025-10-07 16:13:51.111127 | controller | TASK [ci_nmstate : Get k8s nodes] ********************************************** 2025-10-07 16:13:51.111254 | controller | Tuesday 07 October 2025 16:13:51 +0000 (0:00:00.073) 0:00:12.323 ******* 2025-10-07 16:13:52.254516 | controller | ok: [instance] 2025-10-07 16:13:52.254608 | controller | 2025-10-07 16:13:52.254624 | controller | TASK [ci_nmstate : Set the hosts that will provision nmstate using k8s] ******** 2025-10-07 16:13:52.254736 | controller | Tuesday 07 October 2025 16:13:52 +0000 (0:00:01.143) 0:00:13.467 ******* 2025-10-07 16:13:52.361302 | controller | ok: [instance] 2025-10-07 16:13:52.361371 | controller | 2025-10-07 16:13:52.361447 | controller | TASK [ci_nmstate : Provision k8s workers with nmstate] ************************* 2025-10-07 16:13:52.361648 | controller | Tuesday 07 October 2025 16:13:52 +0000 (0:00:00.106) 0:00:13.574 ******* 2025-10-07 16:13:52.437246 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_nmstate/tasks/nmstate_k8s_install.yml for instance 2025-10-07 16:13:52.437321 | controller | 2025-10-07 16:13:52.437607 | controller | TASK [ci_nmstate : Create the target dir to dump NMstate manifests] ************ 2025-10-07 16:13:52.710002 | controller | Tuesday 07 October 2025 16:13:52 +0000 (0:00:00.075) 0:00:13.650 ******* 2025-10-07 16:13:52.710065 | controller | changed: [instance] 2025-10-07 16:13:52.710146 | controller | 2025-10-07 16:13:52.710156 | controller | TASK [ci_nmstate : Create the nmstate namespace] ******************************* 2025-10-07 16:13:52.710273 | controller | Tuesday 07 October 2025 16:13:52 +0000 (0:00:00.272) 0:00:13.922 ******* 2025-10-07 16:13:53.758910 | controller | changed: [instance] 2025-10-07 16:13:53.759246 | controller | 2025-10-07 16:13:53.844408 | controller | TASK [ci_nmstate : Generate the OperatorGroup final CR] ************************ 2025-10-07 16:13:53.844452 | controller | Tuesday 07 October 2025 16:13:53 +0000 (0:00:01.049) 0:00:14.971 ******* 2025-10-07 16:13:53.844466 | 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-07 16:13:53.936808 | controller | 2025-10-07 16:13:53.936866 | controller | TASK [ci_nmstate : Generate the Subscription final CR] ************************* 2025-10-07 16:13:53.936878 | controller | Tuesday 07 October 2025 16:13:53 +0000 (0:00:00.085) 0:00:15.057 ******* 2025-10-07 16:13:53.936930 | 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-07 16:13:53.936985 | controller | 2025-10-07 16:13:53.937150 | controller | TASK [ci_nmstate : Save k8s nmstate OLM manifests as artifacts] **************** 2025-10-07 16:13:53.937195 | controller | Tuesday 07 October 2025 16:13:53 +0000 (0:00:00.092) 0:00:15.149 ******* 2025-10-07 16:13:54.810938 | controller | changed: [instance] => (item=openshift-nmstate) 2025-10-07 16:13:56.449614 | controller | changed: [instance] => (item=kubernetes-nmstate-operator) 2025-10-07 16:13:56.449668 | controller | 2025-10-07 16:13:56.449675 | controller | TASK [ci_nmstate : Create the OLM subscription resources] ********************** 2025-10-07 16:13:56.449682 | controller | Tuesday 07 October 2025 16:13:54 +0000 (0:00:00.873) 0:00:16.023 ******* 2025-10-07 16:13:56.449752 | controller | changed: [instance] => (item=openshift-nmstate) 2025-10-07 16:13:56.449783 | controller | changed: [instance] => (item=kubernetes-nmstate-operator) 2025-10-07 16:13:56.449790 | controller | 2025-10-07 16:13:56.449798 | controller | TASK [ci_nmstate : Wait for nmstate operator to be installed] ****************** 2025-10-07 16:13:56.449931 | controller | Tuesday 07 October 2025 16:13:56 +0000 (0:00:01.638) 0:00:17.661 ******* 2025-10-07 16:14:17.925356 | controller | FAILED - RETRYING: [instance]: Wait for nmstate operator to be installed (12 retries left). 2025-10-07 16:14:18.473273 | controller | FAILED - RETRYING: [instance]: Wait for nmstate operator to be installed (11 retries left). 2025-10-07 16:14:18.473322 | controller | ok: [instance] 2025-10-07 16:14:18.473334 | controller | 2025-10-07 16:14:18.473342 | controller | TASK [ci_nmstate : Save nmstate operator config as an artifact] **************** 2025-10-07 16:14:18.473350 | controller | Tuesday 07 October 2025 16:14:17 +0000 (0:00:21.475) 0:00:39.137 ******* 2025-10-07 16:14:18.473364 | controller | changed: [instance] 2025-10-07 16:14:19.518662 | controller | 2025-10-07 16:14:19.518737 | controller | TASK [ci_nmstate : Trigger nmstate operator to install nmstate] **************** 2025-10-07 16:14:19.518752 | controller | Tuesday 07 October 2025 16:14:18 +0000 (0:00:00.548) 0:00:39.685 ******* 2025-10-07 16:14:19.518795 | controller | changed: [instance] 2025-10-07 16:14:19.518890 | controller | 2025-10-07 16:14:19.518934 | controller | TASK [ci_nmstate : Wait for nsmtate Deployments to be ready] ******************* 2025-10-07 16:14:55.602571 | controller | Tuesday 07 October 2025 16:14:19 +0000 (0:00:01.045) 0:00:40.731 ******* 2025-10-07 16:14:55.602640 | controller | ok: [instance] 2025-10-07 16:14:56.795888 | controller | 2025-10-07 16:14:56.796046 | controller | TASK [ci_nmstate : Wait for nsmtate DaemonSet to be ready] ********************* 2025-10-07 16:14:56.796054 | controller | Tuesday 07 October 2025 16:14:55 +0000 (0:00:36.084) 0:01:16.815 ******* 2025-10-07 16:14:56.796067 | controller | ok: [instance] 2025-10-07 16:14:56.796228 | controller | 2025-10-07 16:14:56.796262 | controller | TASK [ci_nmstate : Provision nncps] ******************************************** 2025-10-07 16:14:56.796276 | controller | Tuesday 07 October 2025 16:14:56 +0000 (0:00:01.193) 0:01:18.009 ******* 2025-10-07 16:14:56.841217 | 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-07 16:14:56.841268 | controller | 2025-10-07 16:14:56.841280 | controller | TASK [ci_nmstate : Prepare the k8s nmstate nncp to apply] ********************** 2025-10-07 16:14:56.841432 | controller | Tuesday 07 October 2025 16:14:56 +0000 (0:00:00.045) 0:01:18.054 ******* 2025-10-07 16:14:56.932211 | controller | ok: [instance] 2025-10-07 16:14:56.932268 | controller | 2025-10-07 16:14:56.932279 | controller | TASK [ci_nmstate : Save k8s nmstate nncp manifests as artifacts] *************** 2025-10-07 16:14:57.447406 | controller | Tuesday 07 October 2025 16:14:56 +0000 (0:00:00.091) 0:01:18.145 ******* 2025-10-07 16:14:57.447456 | controller | changed: [instance] 2025-10-07 16:14:58.401520 | controller | 2025-10-07 16:14:58.401559 | controller | TASK [ci_nmstate : Provision nmstate nncp for the node worker] ***************** 2025-10-07 16:14:58.401567 | controller | Tuesday 07 October 2025 16:14:57 +0000 (0:00:00.514) 0:01:18.659 ******* 2025-10-07 16:14:58.401577 | controller | changed: [instance] 2025-10-07 16:20:21.870403 | controller | 2025-10-07 16:20:21.870502 | controller | TASK [ci_nmstate : Wait for nsmtate nncp to be ready] ************************** 2025-10-07 16:20:21.870516 | controller | Tuesday 07 October 2025 16:14:58 +0000 (0:00:00.954) 0:01:19.614 ******* 2025-10-07 16:20:21.870560 | controller | fatal: [instance]: FAILED! => changed=false 2025-10-07 16:20:21.876738 | controller | api_found: true 2025-10-07 16:20:21.876791 | controller | attempts: 30 2025-10-07 16:20:21.876802 | controller | resources: 2025-10-07 16:20:21.876812 | controller | - apiVersion: nmstate.io/v1 2025-10-07 16:20:21.876822 | controller | kind: NodeNetworkConfigurationPolicy 2025-10-07 16:20:21.876831 | controller | metadata: 2025-10-07 16:20:21.876840 | controller | annotations: 2025-10-07 16:20:21.876849 | controller | nmstate.io/webhook-mutating-timestamp: '1759853698146019811' 2025-10-07 16:20:21.876859 | controller | creationTimestamp: '2025-10-07T16:14:58Z' 2025-10-07 16:20:21.876868 | controller | generation: 1 2025-10-07 16:20:21.876894 | controller | managedFields: 2025-10-07 16:20:21.876903 | controller | - apiVersion: nmstate.io/v1 2025-10-07 16:20:21.876911 | controller | fieldsType: FieldsV1 2025-10-07 16:20:21.876920 | controller | fieldsV1: 2025-10-07 16:20:21.876929 | controller | f:spec: 2025-10-07 16:20:21.876946 | controller | .: {} 2025-10-07 16:20:21.876955 | controller | f:desiredState: 2025-10-07 16:20:21.876964 | controller | .: {} 2025-10-07 16:20:21.876972 | controller | f:dns-resolver: 2025-10-07 16:20:21.876981 | controller | .: {} 2025-10-07 16:20:21.876990 | controller | f:config: 2025-10-07 16:20:21.876998 | controller | .: {} 2025-10-07 16:20:21.877007 | controller | f:search: {} 2025-10-07 16:20:21.877015 | controller | f:server: {} 2025-10-07 16:20:21.877025 | controller | f:interfaces: {} 2025-10-07 16:20:21.877036 | controller | f:nodeSelector: 2025-10-07 16:20:21.877048 | controller | .: {} 2025-10-07 16:20:21.877060 | controller | f:kubernetes.io/hostname: {} 2025-10-07 16:20:21.877071 | controller | f:node-role.kubernetes.io/worker: {} 2025-10-07 16:20:21.877083 | controller | manager: OpenAPI-Generator 2025-10-07 16:20:21.877094 | controller | operation: Update 2025-10-07 16:20:21.877106 | controller | time: '2025-10-07T16:14:58Z' 2025-10-07 16:20:21.877118 | controller | - apiVersion: nmstate.io/v1 2025-10-07 16:20:21.877129 | controller | fieldsType: FieldsV1 2025-10-07 16:20:21.877141 | controller | fieldsV1: 2025-10-07 16:20:21.877152 | controller | f:status: 2025-10-07 16:20:21.877163 | controller | .: {} 2025-10-07 16:20:21.877175 | controller | f:conditions: {} 2025-10-07 16:20:21.877191 | controller | f:lastUnavailableNodeCountUpdate: {} 2025-10-07 16:20:21.877203 | controller | manager: manager 2025-10-07 16:20:21.877220 | controller | operation: Update 2025-10-07 16:20:21.877450 | controller | subresource: status 2025-10-07 16:20:21.877498 | controller | time: '2025-10-07T16:17:26Z' 2025-10-07 16:20:21.877523 | controller | name: crc 2025-10-07 16:20:21.877684 | controller | resourceVersion: '35861' 2025-10-07 16:20:21.877938 | controller | uid: 423401ea-d8aa-41bb-b2cf-d79d80a2c6b3 2025-10-07 16:20:21.878197 | controller | spec: 2025-10-07 16:20:21.878505 | controller | desiredState: 2025-10-07 16:20:21.878799 | controller | dns-resolver: 2025-10-07 16:20:21.879049 | controller | config: 2025-10-07 16:20:21.879314 | controller | search: 2025-10-07 16:20:21.879644 | controller | - testing-untagged.example.com 2025-10-07 16:20:21.879927 | controller | - testing-vlan.example.com 2025-10-07 16:20:21.880202 | controller | server: 2025-10-07 16:20:21.880491 | controller | - 192.168.130.11 2025-10-07 16:20:21.880715 | controller | - 192.168.130.1 2025-10-07 16:20:21.880962 | controller | interfaces: 2025-10-07 16:20:21.881231 | controller | - description: testing-untagged 2025-10-07 16:20:21.881450 | controller | ipv4: 2025-10-07 16:20:21.881690 | controller | address: 2025-10-07 16:20:21.881898 | controller | - ip: 172.17.20.5 2025-10-07 16:20:21.882100 | controller | prefix-length: 24 2025-10-07 16:20:21.882310 | controller | enabled: true 2025-10-07 16:20:21.882540 | controller | ipv6: 2025-10-07 16:20:21.882748 | controller | enabled: false 2025-10-07 16:20:21.882945 | controller | mtu: 1500 2025-10-07 16:20:21.883178 | controller | name: enp6s0 2025-10-07 16:20:21.883435 | controller | state: up 2025-10-07 16:20:21.883736 | controller | type: ethernet 2025-10-07 16:20:21.883966 | controller | - description: testing-vlan 2025-10-07 16:20:21.884214 | controller | ipv4: 2025-10-07 16:20:21.884458 | controller | address: 2025-10-07 16:20:21.884771 | controller | - ip: 172.17.21.5 2025-10-07 16:20:21.885031 | controller | prefix-length: 24 2025-10-07 16:20:21.885294 | controller | enabled: true 2025-10-07 16:20:21.885541 | controller | ipv6: 2025-10-07 16:20:21.885757 | controller | enabled: false 2025-10-07 16:20:21.885960 | controller | mtu: 1496 2025-10-07 16:20:21.886160 | controller | name: enp6s0.21 2025-10-07 16:20:21.886406 | controller | state: up 2025-10-07 16:20:21.886712 | controller | type: vlan 2025-10-07 16:20:21.886991 | controller | vlan: 2025-10-07 16:20:21.887277 | controller | base-iface: enp6s0 2025-10-07 16:20:21.887602 | controller | id: 21 2025-10-07 16:20:21.887901 | controller | nodeSelector: 2025-10-07 16:20:21.888200 | controller | kubernetes.io/hostname: crc 2025-10-07 16:20:21.888513 | controller | node-role.kubernetes.io/worker: '' 2025-10-07 16:20:21.888817 | controller | status: 2025-10-07 16:20:21.889054 | controller | conditions: 2025-10-07 16:20:21.889276 | controller | - lastHeartbeatTime: '2025-10-07T16:17:26Z' 2025-10-07 16:20:21.889527 | controller | lastTransitionTime: '2025-10-07T16:17:26Z' 2025-10-07 16:20:21.889760 | controller | reason: FailedToConfigure 2025-10-07 16:20:21.889972 | controller | status: 'False' 2025-10-07 16:20:21.890172 | controller | type: Available 2025-10-07 16:20:21.890383 | controller | - lastHeartbeatTime: '2025-10-07T16:17:26Z' 2025-10-07 16:20:21.890625 | controller | lastTransitionTime: '2025-10-07T16:17:26Z' 2025-10-07 16:20:21.890832 | controller | message: 1/1 nodes failed to configure 2025-10-07 16:20:21.891030 | controller | reason: FailedToConfigure 2025-10-07 16:20:21.891228 | controller | status: 'True' 2025-10-07 16:20:21.891423 | controller | type: Degraded 2025-10-07 16:20:21.891685 | controller | - lastHeartbeatTime: '2025-10-07T16:17:26Z' 2025-10-07 16:20:21.892080 | controller | lastTransitionTime: '2025-10-07T16:17:26Z' 2025-10-07 16:20:21.892278 | controller | reason: ConfigurationProgressing 2025-10-07 16:20:21.892497 | controller | status: 'False' 2025-10-07 16:20:21.892714 | controller | type: Progressing 2025-10-07 16:20:21.892947 | controller | lastUnavailableNodeCountUpdate: '2025-10-07T16:17:25Z' 2025-10-07 16:20:21.893328 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (30 retries left). 2025-10-07 16:20:21.893576 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (29 retries left). 2025-10-07 16:20:21.893790 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (28 retries left). 2025-10-07 16:20:21.893997 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (27 retries left). 2025-10-07 16:20:21.894205 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (26 retries left). 2025-10-07 16:20:21.894413 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (25 retries left). 2025-10-07 16:20:21.894650 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (24 retries left). 2025-10-07 16:20:21.894877 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (23 retries left). 2025-10-07 16:20:21.895086 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (22 retries left). 2025-10-07 16:20:21.895291 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (21 retries left). 2025-10-07 16:20:21.895526 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (20 retries left). 2025-10-07 16:20:21.895772 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (19 retries left). 2025-10-07 16:20:21.896026 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (18 retries left). 2025-10-07 16:20:21.896305 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (17 retries left). 2025-10-07 16:20:21.896635 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (16 retries left). 2025-10-07 16:20:21.896920 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (15 retries left). 2025-10-07 16:20:21.897205 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (14 retries left). 2025-10-07 16:20:21.897437 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (13 retries left). 2025-10-07 16:20:21.897688 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (12 retries left). 2025-10-07 16:20:21.897939 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (11 retries left). 2025-10-07 16:20:21.898154 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (10 retries left). 2025-10-07 16:20:21.898400 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (9 retries left). 2025-10-07 16:20:21.898639 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (8 retries left). 2025-10-07 16:20:21.898849 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (7 retries left). 2025-10-07 16:20:21.899065 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (6 retries left). 2025-10-07 16:20:21.899269 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (5 retries left). 2025-10-07 16:20:21.899493 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (4 retries left). 2025-10-07 16:20:21.899742 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (3 retries left). 2025-10-07 16:20:21.899949 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (2 retries left). 2025-10-07 16:20:21.900152 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (1 retries left). 2025-10-07 16:20:21.900340 | controller | 2025-10-07 16:20:21.900552 | controller | PLAY RECAP ********************************************************************* 2025-10-07 16:20:21.900720 | controller | crc : ok=1 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2025-10-07 16:20:21.900867 | controller | instance : ok=36 changed=15 unreachable=0 failed=1 skipped=10 rescued=0 ignored=0 2025-10-07 16:20:21.900997 | controller | 2025-10-07 16:20:21.901135 | controller | Tuesday 07 October 2025 16:20:21 +0000 (0:05:23.480) 0:06:43.094 ******* 2025-10-07 16:20:21.901269 | controller | =============================================================================== 2025-10-07 16:20:21.901404 | controller | ci_nmstate : Wait for nsmtate nncp to be ready ------------------------ 323.48s 2025-10-07 16:20:21.901590 | controller | ci_nmstate : Wait for nsmtate Deployments to be ready ------------------ 36.08s 2025-10-07 16:20:21.901736 | controller | ci_nmstate : Wait for nmstate operator to be installed ----------------- 21.48s 2025-10-07 16:20:21.901873 | controller | Fetch crc network facts ------------------------------------------------- 5.17s 2025-10-07 16:20:21.902009 | controller | Gathering Facts --------------------------------------------------------- 2.04s 2025-10-07 16:20:21.902145 | controller | ci_nmstate : Create the OLM subscription resources ---------------------- 1.64s 2025-10-07 16:20:21.902280 | controller | ci_nmstate : Wait for nsmtate DaemonSet to be ready --------------------- 1.19s 2025-10-07 16:20:21.902415 | controller | ci_nmstate : Get k8s nodes ---------------------------------------------- 1.14s 2025-10-07 16:20:21.902575 | controller | ci_nmstate : Create the nmstate namespace ------------------------------- 1.05s 2025-10-07 16:20:21.902714 | controller | ci_nmstate : Trigger nmstate operator to install nmstate ---------------- 1.05s 2025-10-07 16:20:21.902849 | controller | ci_nmstate : Provision nmstate nncp for the node worker ----------------- 0.95s 2025-10-07 16:20:21.902986 | controller | ci_nmstate : Save k8s nmstate OLM manifests as artifacts ---------------- 0.87s 2025-10-07 16:20:21.903126 | controller | networking_mapper : Save instances refreshed facts for troubleshooting purposes --- 0.80s 2025-10-07 16:20:21.903262 | controller | ci_nmstate : Save nmstate operator config as an artifact ---------------- 0.55s 2025-10-07 16:20:21.903397 | controller | ci_nmstate : Save k8s nmstate nncp manifests as artifacts --------------- 0.51s 2025-10-07 16:20:21.903554 | controller | networking_mapper : Write the Networking Definition to file ------------- 0.51s 2025-10-07 16:20:21.903711 | controller | Add a dummy ethernet interface to target the crc NMstate ---------------- 0.50s 2025-10-07 16:20:21.903851 | controller | networking_mapper : Write the Networking Environment Definition to file --- 0.49s 2025-10-07 16:20:21.903987 | controller | networking_mapper : Gather the facts ------------------------------------ 0.44s 2025-10-07 16:20:21.904128 | controller | networking_mapper : Ensure CI infrastructure dir exists ----------------- 0.41s 2025-10-07 16:20:21.969849 | 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-07 16:20:21.970302 | controller | WARNING An error occurred during the test sequence action: 'converge'. Cleaning up. 2025-10-07 16:20:21.979993 | controller | INFO Running default > cleanup 2025-10-07 16:20:21.980536 | controller | WARNING Skipping, cleanup playbook not configured. 2025-10-07 16:20:21.988708 | controller | INFO Running default > destroy 2025-10-07 16:20:21.989058 | controller | WARNING Skipping, instances are delegated. 2025-10-07 16:20:21.989409 | controller | INFO Pruning extra files from scenario ephemeral directory 2025-10-07 16:20:21.991025 | controller | INFO Writing /tmp/report.html report. 2025-10-07 16:20:22.198133 | controller | ERROR 2025-10-07 16:20:22.198403 | controller | { 2025-10-07 16:20:22.198471 | controller | "delta": "0:08:17.513363", 2025-10-07 16:20:22.198517 | controller | "end": "2025-10-07 16:20:22.053021", 2025-10-07 16:20:22.198559 | controller | "msg": "non-zero return code", 2025-10-07 16:20:22.198596 | controller | "rc": 1, 2025-10-07 16:20:22.198636 | controller | "start": "2025-10-07 16:12:04.539658" 2025-10-07 16:20:22.198726 | controller | } failure 2025-10-07 16:20:22.210388 | 2025-10-07 16:20:22.210493 | PLAY RECAP 2025-10-07 16:20:22.210569 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0 2025-10-07 16:20:22.210612 | 2025-10-07 16:20:22.324660 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-07 16:20:22.326537 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-07 16:20:22.964219 | 2025-10-07 16:20:22.964322 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-07 16:20:22.986499 | 2025-10-07 16:20:22.986599 | TASK [Filter out host if needed] 2025-10-07 16:20:22.995458 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-07 16:20:23.000230 | 2025-10-07 16:20:23.000294 | TASK [Ensure file is present] 2025-10-07 16:20:23.520348 | controller | ok 2025-10-07 16:20:23.535209 | 2025-10-07 16:20:23.535359 | TASK [Manage molecule report file] 2025-10-07 16:20:24.093933 | controller | changed 2025-10-07 16:20:24.106922 | 2025-10-07 16:20:24.107226 | TASK [Check if we get ci-framework-data basedir] 2025-10-07 16:20:24.423163 | controller | ok 2025-10-07 16:20:24.436083 | 2025-10-07 16:20:24.436222 | TASK [Create ci-framework-data log directory for zuul] 2025-10-07 16:20:24.945709 | controller | changed 2025-10-07 16:20:24.959728 | 2025-10-07 16:20:24.960058 | TASK [Copy ci-framework interesting files] 2025-10-07 16:20:25.331121 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-10-07 16:20:25.505120 | controller | changed 2025-10-07 16:20:25.516938 | 2025-10-07 16:20:25.517073 | TASK [Get SELinux listing] 2025-10-07 16:20:26.070386 | controller | changed 2025-10-07 16:20:26.083402 | 2025-10-07 16:20:26.083548 | TASK [Generate log index] 2025-10-07 16:20:27.684805 | controller | changed 2025-10-07 16:20:27.697211 | 2025-10-07 16:20:27.697347 | TASK [Get some env related data] 2025-10-07 16:20:28.510430 | controller | /home/zuul/.local/bin/ansible 2025-10-07 16:20:29.249969 | controller | changed 2025-10-07 16:20:29.297635 | 2025-10-07 16:20:29.297796 | TASK [Generate list of logs to collect in home directory] 2025-10-07 16:20:29.719066 | controller | ok: All paths examined 2025-10-07 16:20:29.732876 | 2025-10-07 16:20:29.733005 | LOOP [Copy logs from home directory] 2025-10-07 16:20:30.554723 | controller | changed: 2025-10-07 16:20:30.555117 | controller | { 2025-10-07 16:20:30.555278 | controller | "atime": 1743544925.4788878, 2025-10-07 16:20:30.555327 | controller | "ctime": 1743545329.1409318, 2025-10-07 16:20:30.555366 | controller | "dev": 64513, 2025-10-07 16:20:30.555403 | controller | "gid": 1000, 2025-10-07 16:20:30.555442 | controller | "gr_name": "zuul", 2025-10-07 16:20:30.555482 | controller | "inode": 4518807, 2025-10-07 16:20:30.555518 | controller | "isblk": false, 2025-10-07 16:20:30.555553 | controller | "ischr": false, 2025-10-07 16:20:30.555604 | controller | "isdir": false, 2025-10-07 16:20:30.555651 | controller | "isfifo": false, 2025-10-07 16:20:30.555929 | controller | "isgid": false, 2025-10-07 16:20:30.555996 | controller | "islnk": false, 2025-10-07 16:20:30.556037 | controller | "isreg": true, 2025-10-07 16:20:30.556074 | controller | "issock": false, 2025-10-07 16:20:30.556109 | controller | "isuid": false, 2025-10-07 16:20:30.556144 | controller | "mode": "0644", 2025-10-07 16:20:30.556178 | controller | "mtime": 1743545329.1409318, 2025-10-07 16:20:30.556213 | controller | "nlink": 1, 2025-10-07 16:20:30.556247 | controller | "path": "/home/zuul/crc-setup.log", 2025-10-07 16:20:30.556287 | controller | "pw_name": "zuul", 2025-10-07 16:20:30.556324 | controller | "rgrp": true, 2025-10-07 16:20:30.556359 | controller | "roth": true, 2025-10-07 16:20:30.556395 | controller | "rusr": true, 2025-10-07 16:20:30.556430 | controller | "size": 4108, 2025-10-07 16:20:30.556465 | controller | "uid": 1000, 2025-10-07 16:20:30.556499 | controller | "wgrp": false, 2025-10-07 16:20:30.556535 | controller | "woth": false, 2025-10-07 16:20:30.556576 | controller | "wusr": true, 2025-10-07 16:20:30.556613 | controller | "xgrp": false, 2025-10-07 16:20:30.556649 | controller | "xoth": false, 2025-10-07 16:20:30.556748 | controller | "xusr": false 2025-10-07 16:20:30.556791 | controller | } 2025-10-07 16:20:31.328878 | controller | changed: 2025-10-07 16:20:31.329027 | controller | { 2025-10-07 16:20:31.329081 | controller | "atime": 1759852780.5117815, 2025-10-07 16:20:31.329131 | controller | "ctime": 1759852778.0357196, 2025-10-07 16:20:31.329176 | controller | "dev": 64513, 2025-10-07 16:20:31.329221 | controller | "gid": 1000, 2025-10-07 16:20:31.329278 | controller | "gr_name": "zuul", 2025-10-07 16:20:31.329331 | controller | "inode": 4194437, 2025-10-07 16:20:31.329376 | controller | "isblk": false, 2025-10-07 16:20:31.329415 | controller | "ischr": false, 2025-10-07 16:20:31.329451 | controller | "isdir": false, 2025-10-07 16:20:31.329491 | controller | "isfifo": false, 2025-10-07 16:20:31.329526 | controller | "isgid": false, 2025-10-07 16:20:31.329562 | controller | "islnk": false, 2025-10-07 16:20:31.329601 | controller | "isreg": true, 2025-10-07 16:20:31.329638 | controller | "issock": false, 2025-10-07 16:20:31.329703 | controller | "isuid": false, 2025-10-07 16:20:31.329754 | controller | "mode": "0644", 2025-10-07 16:20:31.329798 | controller | "mtime": 1759852778.0357196, 2025-10-07 16:20:31.329841 | controller | "nlink": 1, 2025-10-07 16:20:31.329883 | controller | "path": "/home/zuul/crc-start.log", 2025-10-07 16:20:31.329925 | controller | "pw_name": "zuul", 2025-10-07 16:20:31.329967 | controller | "rgrp": true, 2025-10-07 16:20:31.330009 | controller | "roth": true, 2025-10-07 16:20:31.330050 | controller | "rusr": true, 2025-10-07 16:20:31.330092 | controller | "size": 3404, 2025-10-07 16:20:31.330134 | controller | "uid": 1000, 2025-10-07 16:20:31.330177 | controller | "wgrp": false, 2025-10-07 16:20:31.330218 | controller | "woth": false, 2025-10-07 16:20:31.330258 | controller | "wusr": true, 2025-10-07 16:20:31.330299 | controller | "xgrp": false, 2025-10-07 16:20:31.330340 | controller | "xoth": false, 2025-10-07 16:20:31.330381 | controller | "xusr": false 2025-10-07 16:20:31.330422 | controller | } 2025-10-07 16:20:32.117707 | controller | changed: 2025-10-07 16:20:32.117875 | controller | { 2025-10-07 16:20:32.117938 | controller | "atime": 1759853498.5672233, 2025-10-07 16:20:32.117989 | controller | "ctime": 1759853521.9838097, 2025-10-07 16:20:32.118034 | controller | "dev": 64513, 2025-10-07 16:20:32.118080 | controller | "gid": 1000, 2025-10-07 16:20:32.118123 | controller | "gr_name": "zuul", 2025-10-07 16:20:32.118168 | controller | "inode": 4518817, 2025-10-07 16:20:32.118211 | controller | "isblk": false, 2025-10-07 16:20:32.118255 | controller | "ischr": false, 2025-10-07 16:20:32.118298 | controller | "isdir": false, 2025-10-07 16:20:32.118337 | controller | "isfifo": false, 2025-10-07 16:20:32.118375 | controller | "isgid": false, 2025-10-07 16:20:32.118413 | controller | "islnk": false, 2025-10-07 16:20:32.118455 | controller | "isreg": true, 2025-10-07 16:20:32.120658 | controller | "issock": false, 2025-10-07 16:20:32.120749 | controller | "isuid": false, 2025-10-07 16:20:32.120791 | controller | "mode": "0644", 2025-10-07 16:20:32.120829 | controller | "mtime": 1759853521.9838097, 2025-10-07 16:20:32.120866 | controller | "nlink": 1, 2025-10-07 16:20:32.120902 | controller | "path": "/home/zuul/ansible.log", 2025-10-07 16:20:32.120939 | controller | "pw_name": "zuul", 2025-10-07 16:20:32.120976 | controller | "rgrp": true, 2025-10-07 16:20:32.121012 | controller | "roth": true, 2025-10-07 16:20:32.121053 | controller | "rusr": true, 2025-10-07 16:20:32.121091 | controller | "size": 6749, 2025-10-07 16:20:32.121127 | controller | "uid": 1000, 2025-10-07 16:20:32.121162 | controller | "wgrp": false, 2025-10-07 16:20:32.121196 | controller | "woth": false, 2025-10-07 16:20:32.121230 | controller | "wusr": true, 2025-10-07 16:20:32.121423 | controller | "xgrp": false, 2025-10-07 16:20:32.121467 | controller | "xoth": false, 2025-10-07 16:20:32.121505 | controller | "xusr": false 2025-10-07 16:20:32.121542 | controller | } 2025-10-07 16:20:32.145518 | 2025-10-07 16:20:32.145665 | TASK [Copy crio stats log file] 2025-10-07 16:20:32.173570 | controller | skipping: Conditional result was False 2025-10-07 16:20:32.194297 | 2025-10-07 16:20:32.194479 | TASK [Get SELinux related data] 2025-10-07 16:20:32.540763 | controller | 2025-10-07 16:20:32.738817 | controller | ERROR 2025-10-07 16:20:32.739002 | controller | { 2025-10-07 16:20:32.739099 | controller | "delta": "0:00:00.011023", 2025-10-07 16:20:32.739132 | controller | "end": "2025-10-07 16:20:32.541991", 2025-10-07 16:20:32.739184 | controller | "msg": "non-zero return code", 2025-10-07 16:20:32.739204 | controller | "rc": 1, 2025-10-07 16:20:32.739242 | controller | "start": "2025-10-07 16:20:32.530968" 2025-10-07 16:20:32.739267 | controller | } 2025-10-07 16:20:32.739291 | controller | ERROR: Ignoring Errors 2025-10-07 16:20:32.745586 | 2025-10-07 16:20:32.745662 | TASK [Create system configuration directory] 2025-10-07 16:20:33.124176 | controller | changed 2025-10-07 16:20:33.135758 | 2025-10-07 16:20:33.135891 | TASK [Get some of the system configurations] 2025-10-07 16:20:33.686211 | controller | changed 2025-10-07 16:20:33.701958 | 2025-10-07 16:20:33.702249 | TASK [Copy generated documentation if available] 2025-10-07 16:20:33.729573 | controller | skipping: Conditional result was False 2025-10-07 16:20:33.744188 | 2025-10-07 16:20:33.744346 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-07 16:20:33.770764 | controller | skipping: Conditional result was False 2025-10-07 16:20:33.785162 | 2025-10-07 16:20:33.785320 | TASK [Compress logs bigger than 2MB] 2025-10-07 16:20:34.361307 | controller | changed 2025-10-07 16:20:34.373391 | 2025-10-07 16:20:34.373526 | TASK [Copy files from workspace on node] 2025-10-07 16:20:34.401765 | controller | ok 2025-10-07 16:20:34.443583 | 2025-10-07 16:20:34.443824 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-07 16:20:34.479213 | controller | skipping: Conditional result was False 2025-10-07 16:20:34.492196 | 2025-10-07 16:20:34.492338 | TASK [fetch-output : Set log path for single node] 2025-10-07 16:20:34.540586 | controller | ok 2025-10-07 16:20:34.552322 | 2025-10-07 16:20:34.552452 | LOOP [fetch-output : Ensure local output dirs] 2025-10-07 16:20:34.876223 | controller -> localhost | ok: "/var/lib/zuul/builds/36cb99831e744dc499f79029cfe2ad62/work/logs" 2025-10-07 16:20:34.876723 | controller -> localhost | changed: All items complete 2025-10-07 16:20:34.876792 | 2025-10-07 16:20:35.092666 | controller -> localhost | changed: "/var/lib/zuul/builds/36cb99831e744dc499f79029cfe2ad62/work/artifacts" 2025-10-07 16:20:35.344781 | controller -> localhost | changed: "/var/lib/zuul/builds/36cb99831e744dc499f79029cfe2ad62/work/docs" 2025-10-07 16:20:35.365791 | 2025-10-07 16:20:35.365948 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-07 16:20:36.182910 | controller | changed: 2025-10-07 16:20:36.183238 | controller | .d..t...... ./ 2025-10-07 16:20:36.183297 | controller | >f+++++++++ README.html 2025-10-07 16:20:36.183340 | controller | >f+++++++++ ansible-execution.log 2025-10-07 16:20:36.183381 | controller | >f+++++++++ ansible.log 2025-10-07 16:20:36.183420 | controller | >f+++++++++ crc-setup.log 2025-10-07 16:20:36.183458 | controller | >f+++++++++ crc-start.log 2025-10-07 16:20:36.183495 | controller | >f+++++++++ dmesg.log 2025-10-07 16:20:36.183537 | controller | >f+++++++++ installed-pkgs.log 2025-10-07 16:20:36.183575 | controller | >f+++++++++ python.log 2025-10-07 16:20:36.183720 | controller | >f+++++++++ registries.conf 2025-10-07 16:20:36.183776 | controller | >f+++++++++ report.html 2025-10-07 16:20:36.183814 | controller | >f+++++++++ selinux-denials.log 2025-10-07 16:20:36.183850 | controller | >f+++++++++ selinux-listing.log 2025-10-07 16:20:36.183886 | controller | cd+++++++++ ci-framework-data/ 2025-10-07 16:20:36.183922 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-07 16:20:36.183959 | controller | >f+++++++++ ci-framework-data/artifacts/networking-mapper-instances-facts.yml 2025-10-07 16:20:36.183995 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-07 16:20:36.184031 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/ 2025-10-07 16:20:36.184066 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/nmstate/ 2025-10-07 16:20:36.184114 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/crc-nncp.yaml 2025-10-07 16:20:36.184151 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/nmstate-nmstate-olm.yaml 2025-10-07 16:20:36.184187 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/nmstate-operatorgroup-olm.yaml 2025-10-07 16:20:36.184224 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/nmstate-subscription-olm.yaml 2025-10-07 16:20:36.184260 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-07 16:20:36.184296 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-10-07 16:20:36.184331 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/ 2025-10-07 16:20:36.184367 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5 2025-10-07 16:20:36.184403 | controller | cd+++++++++ ci-framework-data/logs/ 2025-10-07 16:20:36.184437 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log 2025-10-07 16:20:36.184473 | controller | cd+++++++++ registries.conf.d/ 2025-10-07 16:20:36.184508 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-10-07 16:20:36.184543 | controller | cd+++++++++ system-config/ 2025-10-07 16:20:36.184578 | controller | cd+++++++++ system-config/libvirt/ 2025-10-07 16:20:36.184613 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-10-07 16:20:36.184648 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-10-07 16:20:36.184712 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf 2025-10-07 16:20:36.184760 | controller | >f+++++++++ system-config/libvirt/network.conf 2025-10-07 16:20:36.184797 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf 2025-10-07 16:20:36.184832 | controller | >f+++++++++ system-config/libvirt/qemu.conf 2025-10-07 16:20:36.184867 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf 2025-10-07 16:20:36.184901 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf 2025-10-07 16:20:36.184936 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf 2025-10-07 16:20:36.184971 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf 2025-10-07 16:20:36.185005 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf 2025-10-07 16:20:36.185040 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf 2025-10-07 16:20:36.185075 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf 2025-10-07 16:20:36.185110 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf 2025-10-07 16:20:36.185144 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf 2025-10-07 16:20:36.185178 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf 2025-10-07 16:20:36.849946 | controller | changed: .d..t...... ./ 2025-10-07 16:20:37.508532 | controller | changed: .d..t...... ./ 2025-10-07 16:20:37.532196 | 2025-10-07 16:20:37.532341 | TASK [Return artifact to Zuul] 2025-10-07 16:20:37.561304 | controller | ok 2025-10-07 16:20:37.580846 | 2025-10-07 16:20:37.580910 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-07 16:20:37.580999 | 2025-10-07 16:20:37.581025 | PLAY RECAP 2025-10-07 16:20:37.581060 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1 2025-10-07 16:20:37.581079 | 2025-10-07 16:20:37.691567 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-07 16:20:37.693490 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master] 2025-10-07 16:20:38.247960 | 2025-10-07 16:20:38.248066 | PLAY [Report DLRN results after job run] 2025-10-07 16:20:38.268397 | 2025-10-07 16:20:38.268503 | TASK [Report job status to DLRN] 2025-10-07 16:20:38.288160 | controller | ok 2025-10-07 16:20:38.312286 | 2025-10-07 16:20:38.312380 | TASK [dlrn_report : Warning if DLRN is not running] 2025-10-07 16:20:38.377421 | controller | skipping: Conditional result was False 2025-10-07 16:20:38.391039 | 2025-10-07 16:20:38.391200 | TASK [dlrn_report : Install dlrnapi-client shyaml package] 2025-10-07 16:20:38.458537 | controller | skipping: Conditional result was False 2025-10-07 16:20:38.471439 | 2025-10-07 16:20:38.471590 | TASK [dlrn_report : Install kinit related package] 2025-10-07 16:20:38.517978 | controller | skipping: Conditional result was False 2025-10-07 16:20:38.530185 | 2025-10-07 16:20:38.530353 | TASK [dlrn_report : Install dlrn kerberos related packages] 2025-10-07 16:20:38.576647 | controller | skipping: Conditional result was False 2025-10-07 16:20:38.588490 | 2025-10-07 16:20:38.588634 | TASK [Set zuul-log-path fact] 2025-10-07 16:20:38.638023 | controller | skipping: Conditional result was False 2025-10-07 16:20:38.650104 | 2025-10-07 16:20:38.650287 | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used] 2025-10-07 16:20:38.697177 | controller | skipping: Conditional result was False 2025-10-07 16:20:38.713001 | 2025-10-07 16:20:38.713186 | TASK [Get hash related data from repo_setup role] 2025-10-07 16:20:38.760761 | controller | skipping: Conditional result was False 2025-10-07 16:20:38.773714 | 2025-10-07 16:20:38.773852 | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication] 2025-10-07 16:20:39.330871 | controller | skipping: Conditional result was False 2025-10-07 16:20:39.346150 | 2025-10-07 16:20:39.346311 | TASK [dlrn_report : Set empty value for dlrnapi password] 2025-10-07 16:20:39.426290 | controller | skipping: Conditional result was False 2025-10-07 16:20:39.440394 | 2025-10-07 16:20:39.440559 | TASK [dlrn_report : Report results to dlrn for the tested hash] 2025-10-07 16:20:39.990219 | controller | skipping: Conditional result was False 2025-10-07 16:20:40.015619 | 2025-10-07 16:20:40.015759 | PLAY RECAP 2025-10-07 16:20:40.015809 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 0 2025-10-07 16:20:40.015832 | 2025-10-07 16:20:40.128109 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master] 2025-10-07 16:20:40.128949 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-07 16:20:40.724981 | 2025-10-07 16:20:40.725090 | PLAY [all] 2025-10-07 16:20:40.744712 | 2025-10-07 16:20:40.744817 | TASK [include_role : fetch-output] 2025-10-07 16:20:40.793573 | controller | ok 2025-10-07 16:20:40.810969 | 2025-10-07 16:20:40.811051 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-07 16:20:40.886392 | controller | skipping: Conditional result was False 2025-10-07 16:20:40.900093 | 2025-10-07 16:20:40.900245 | TASK [fetch-output : Set log path for single node] 2025-10-07 16:20:40.949800 | controller | ok 2025-10-07 16:20:40.962130 | 2025-10-07 16:20:40.962278 | LOOP [fetch-output : Ensure local output dirs] 2025-10-07 16:20:41.389058 | controller -> localhost | ok: "/var/lib/zuul/builds/36cb99831e744dc499f79029cfe2ad62/work/logs" 2025-10-07 16:20:41.682625 | controller -> localhost | ok: "/var/lib/zuul/builds/36cb99831e744dc499f79029cfe2ad62/work/artifacts" 2025-10-07 16:20:41.945564 | controller -> localhost | ok: "/var/lib/zuul/builds/36cb99831e744dc499f79029cfe2ad62/work/docs" 2025-10-07 16:20:41.959070 | 2025-10-07 16:20:41.959177 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-07 16:20:42.719728 | controller | ok 2025-10-07 16:20:42.719971 | controller | ok: All items complete 2025-10-07 16:20:42.720000 | 2025-10-07 16:20:43.303533 | controller | ok 2025-10-07 16:20:43.875360 | controller | ok 2025-10-07 16:20:43.893828 | 2025-10-07 16:20:43.893951 | TASK [include_role : fetch-output-openshift] 2025-10-07 16:20:43.918886 | controller | skipping: Conditional result was False 2025-10-07 16:20:43.932827 | 2025-10-07 16:20:43.932981 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-07 16:20:44.334756 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013198 2025-10-07 16:20:44.648843 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.013397 2025-10-07 16:20:44.681431 | 2025-10-07 16:20:44.681574 | PLAY [all] 2025-10-07 16:20:44.696210 | 2025-10-07 16:20:44.696272 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-07 16:20:45.270981 | controller | changed 2025-10-07 16:20:45.322254 | 2025-10-07 16:20:45.322340 | PLAY RECAP 2025-10-07 16:20:45.322394 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-07 16:20:45.322422 | 2025-10-07 16:20:45.419864 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-07 16:20:45.420660 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-07 16:20:46.076282 | 2025-10-07 16:20:46.076398 | PLAY [localhost] 2025-10-07 16:20:46.098736 | 2025-10-07 16:20:46.099011 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-07 16:20:46.550166 | localhost | changed 2025-10-07 16:20:46.560666 | 2025-10-07 16:20:46.560861 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-07 16:20:46.609664 | localhost | ok 2025-10-07 16:20:46.631578 | 2025-10-07 16:20:46.631816 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-07 16:20:47.028057 | localhost | changed 2025-10-07 16:20:47.033219 | 2025-10-07 16:20:47.033289 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-07 16:20:47.733301 | localhost | changed 2025-10-07 16:20:47.738496 | 2025-10-07 16:20:47.738561 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-07 16:20:48.173634 | localhost | Identity added: /var/lib/zuul/builds/36cb99831e744dc499f79029cfe2ad62/work/tmp/ansible.ucmdts48 (/var/lib/zuul/builds/36cb99831e744dc499f79029cfe2ad62/work/tmp/ansible.ucmdts48) 2025-10-07 16:20:48.173883 | localhost | ok: Runtime: 0:00:00.007628 2025-10-07 16:20:48.178853 | 2025-10-07 16:20:48.178919 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-07 16:20:48.485048 | localhost | ok: Runtime: 0:00:00.009487 2025-10-07 16:20:48.493537 | 2025-10-07 16:20:48.493667 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-07 16:20:48.580254 | localhost | changed 2025-10-07 16:20:48.589652 | 2025-10-07 16:20:48.589822 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-07 16:20:49.071093 | localhost | changed 2025-10-07 16:20:49.096888 | 2025-10-07 16:20:49.096976 | PLAY [localhost] 2025-10-07 16:20:49.119574 | 2025-10-07 16:20:49.119808 | TASK [Generate bulk log download script] 2025-10-07 16:20:49.141339 | localhost | ok 2025-10-07 16:20:49.158311 | 2025-10-07 16:20:49.158403 | TASK [local-log-download : Check API endpoint is defined] 2025-10-07 16:20:49.190565 | localhost | ok: All assertions passed 2025-10-07 16:20:49.197191 | 2025-10-07 16:20:49.197281 | TASK [local-log-download : Create download script] 2025-10-07 16:20:49.687310 | localhost -> localhost | changed 2025-10-07 16:20:49.696123 | 2025-10-07 16:20:49.696188 | TASK [Register quick-download link] 2025-10-07 16:20:49.728328 | localhost | ok 2025-10-07 16:20:49.794426 | 2025-10-07 16:20:49.794558 | PLAY [logserver.rdoproject.org] 2025-10-07 16:20:49.810501 | 2025-10-07 16:20:49.810645 | TASK [Set zuul-log-path fact] 2025-10-07 16:20:49.832559 | logserver.rdoproject.org | ok 2025-10-07 16:20:49.847370 | 2025-10-07 16:20:49.847495 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-07 16:20:49.886123 | logserver.rdoproject.org | ok 2025-10-07 16:20:49.893554 | 2025-10-07 16:20:49.893669 | TASK [upload-logs : Create log directories] 2025-10-07 16:20:50.786734 | logserver.rdoproject.org | changed 2025-10-07 16:20:50.790051 | 2025-10-07 16:20:50.790141 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-07 16:20:51.049866 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.014222 2025-10-07 16:20:51.057452 | 2025-10-07 16:20:51.057581 | TASK [upload-logs : Upload logs to log server] 2025-10-07 16:20:51.792073 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-07 16:20:51.800329 | 2025-10-07 16:20:51.800484 | LOOP [upload-logs : Compress console log and json output] 2025-10-07 16:20:51.871615 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-07 16:20:51.880008 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-07 16:20:51.888771 | 2025-10-07 16:20:51.888848 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-07 16:20:51.961567 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-07 16:20:51.961888 | 2025-10-07 16:20:51.965002 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-07 16:20:51.978996 | 2025-10-07 16:20:51.979124 | LOOP [upload-logs : Upload console log and json output]