2026-03-10 19:11:55.039778 | Job console starting... 2026-03-10 19:11:55.248420 | Updating repositories 2026-03-10 19:11:55.941368 | Preparing job workspace 2026-03-10 19:12:02.759287 | Running Ansible setup... 2026-03-10 19:12:06.207588 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-03-10 19:12:06.673261 | 2026-03-10 19:12:06.673400 | PLAY [localhost] 2026-03-10 19:12:06.681124 | 2026-03-10 19:12:06.681205 | TASK [Gathering Facts] 2026-03-10 19:12:07.435427 | localhost | ok 2026-03-10 19:12:07.447190 | 2026-03-10 19:12:07.447274 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-10 19:12:07.733272 | localhost -> localhost | changed 2026-03-10 19:12:07.738206 | 2026-03-10 19:12:07.738274 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-10 19:12:08.348511 | localhost -> localhost | changed 2026-03-10 19:12:08.356401 | 2026-03-10 19:12:08.356479 | TASK [Setup log path fact] 2026-03-10 19:12:08.373263 | localhost | ok 2026-03-10 19:12:08.383520 | 2026-03-10 19:12:08.383598 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-10 19:12:08.400113 | localhost | ok 2026-03-10 19:12:08.406706 | 2026-03-10 19:12:08.406769 | TASK [emit-job-header : Print job information] 2026-03-10 19:12:08.432835 | # Job Information 2026-03-10 19:12:08.432943 | Ansible Version: 2.15.12 2026-03-10 19:12:08.432969 | Job: cifmw-molecule-reproducer 2026-03-10 19:12:08.432989 | Pipeline: github-check 2026-03-10 19:12:08.433007 | Executor: ibm-bm4-ze.softwarefactory-project.io 2026-03-10 19:12:08.433023 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3755 2026-03-10 19:12:08.433041 | Log URL (when completed): https://logserver.rdoproject.org/779/rdoproject.org/779875e0c9dd4fd7988c7ecf0b34c7d4/ 2026-03-10 19:12:08.433058 | Event ID: df4afa80-1cb4-11f1-87de-3982f02d1d28 2026-03-10 19:12:08.436177 | 2026-03-10 19:12:08.436248 | LOOP [emit-job-header : Print node information] 2026-03-10 19:12:08.517746 | localhost | ok: 2026-03-10 19:12:08.517901 | localhost | # Node Information 2026-03-10 19:12:08.517931 | localhost | Inventory Hostname: controller 2026-03-10 19:12:08.517956 | localhost | Hostname: np0005643653 2026-03-10 19:12:08.517976 | localhost | Username: zuul 2026-03-10 19:12:08.517997 | localhost | Distro: CentOS 9 2026-03-10 19:12:08.518015 | localhost | Provider: ibm-bm4-nodepool 2026-03-10 19:12:08.518032 | localhost | Region: regionOne 2026-03-10 19:12:08.518048 | localhost | Label: centos-9-stream-crc-2-48-0-xxl-ibm 2026-03-10 19:12:08.518065 | localhost | Product Name: OpenStack Compute 2026-03-10 19:12:08.518080 | localhost | Interface IP: 192.168.26.35 2026-03-10 19:12:08.537939 | 2026-03-10 19:12:08.538004 | PLAY [all] 2026-03-10 19:12:08.544003 | 2026-03-10 19:12:08.544070 | TASK [Gather network facts] 2026-03-10 19:12:08.957854 | controller | ok 2026-03-10 19:12:08.971480 | 2026-03-10 19:12:08.971541 | TASK [include_role : start-zuul-console] 2026-03-10 19:12:08.988171 | controller | ok 2026-03-10 19:12:08.999587 | 2026-03-10 19:12:08.999649 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-10 19:12:09.370884 | controller | ok 2026-03-10 19:12:09.379035 | 2026-03-10 19:12:09.379098 | TASK [include_role : add-build-sshkey] 2026-03-10 19:12:09.396210 | controller | ok 2026-03-10 19:12:09.408027 | 2026-03-10 19:12:09.408087 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-10 19:12:09.582972 | controller -> localhost | ok 2026-03-10 19:12:09.588163 | 2026-03-10 19:12:09.588246 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-10 19:12:09.605230 | controller | ok 2026-03-10 19:12:09.618778 | controller | included: /var/lib/zuul/builds/779875e0c9dd4fd7988c7ecf0b34c7d4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-10 19:12:09.624193 | 2026-03-10 19:12:09.624267 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-10 19:12:09.970541 | controller -> localhost | Generating public/private rsa key pair. 2026-03-10 19:12:09.970714 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/779875e0c9dd4fd7988c7ecf0b34c7d4/work/779875e0c9dd4fd7988c7ecf0b34c7d4_id_rsa. 2026-03-10 19:12:09.970750 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/779875e0c9dd4fd7988c7ecf0b34c7d4/work/779875e0c9dd4fd7988c7ecf0b34c7d4_id_rsa.pub. 2026-03-10 19:12:09.970775 | controller -> localhost | The key fingerprint is: 2026-03-10 19:12:09.970799 | controller -> localhost | SHA256:EYzueTD+j56opWQ5EROsM4JwTBcPoDjNVP8NjC3CP7I zuul-build-sshkey 2026-03-10 19:12:09.970820 | controller -> localhost | The key's randomart image is: 2026-03-10 19:12:09.970841 | controller -> localhost | +---[RSA 3072]----+ 2026-03-10 19:12:09.970861 | controller -> localhost | | o++*. o. | 2026-03-10 19:12:09.970880 | controller -> localhost | |o=oo.=.+.. | 2026-03-10 19:12:09.970899 | controller -> localhost | |*.o.=.= = | 2026-03-10 19:12:09.970918 | controller -> localhost | |o.+ =+o + | 2026-03-10 19:12:09.970937 | controller -> localhost | | . oooo+S . | 2026-03-10 19:12:09.970956 | controller -> localhost | | =+.. | 2026-03-10 19:12:09.970974 | controller -> localhost | | E .o | 2026-03-10 19:12:09.970994 | controller -> localhost | | o + ..o | 2026-03-10 19:12:09.971013 | controller -> localhost | | o...+.. | 2026-03-10 19:12:09.971031 | controller -> localhost | +----[SHA256]-----+ 2026-03-10 19:12:09.971080 | controller -> localhost | ok: Runtime: 0:00:00.043643 2026-03-10 19:12:09.976387 | 2026-03-10 19:12:09.976451 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-10 19:12:09.993814 | controller | ok 2026-03-10 19:12:10.002395 | controller | included: /var/lib/zuul/builds/779875e0c9dd4fd7988c7ecf0b34c7d4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-10 19:12:10.009534 | 2026-03-10 19:12:10.009593 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-10 19:12:10.022839 | controller | skipping: Conditional result was False 2026-03-10 19:12:10.027730 | 2026-03-10 19:12:10.027793 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-10 19:12:10.461349 | controller | changed 2026-03-10 19:12:10.466798 | 2026-03-10 19:12:10.466854 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-10 19:12:10.707722 | controller | ok 2026-03-10 19:12:10.712688 | 2026-03-10 19:12:10.712754 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-10 19:12:11.746386 | controller | changed 2026-03-10 19:12:11.750652 | 2026-03-10 19:12:11.750710 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-10 19:12:12.785856 | controller | changed 2026-03-10 19:12:12.790254 | 2026-03-10 19:12:12.790328 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-10 19:12:12.803355 | controller | skipping: Conditional result was False 2026-03-10 19:12:12.808061 | 2026-03-10 19:12:12.808123 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-10 19:12:13.073927 | controller -> localhost | changed 2026-03-10 19:12:13.082994 | 2026-03-10 19:12:13.083055 | TASK [add-build-sshkey : Add back temp key] 2026-03-10 19:12:13.288444 | controller -> localhost | Identity added: /var/lib/zuul/builds/779875e0c9dd4fd7988c7ecf0b34c7d4/work/779875e0c9dd4fd7988c7ecf0b34c7d4_id_rsa (zuul-build-sshkey) 2026-03-10 19:12:13.288599 | controller -> localhost | ok: Runtime: 0:00:00.006467 2026-03-10 19:12:13.293615 | 2026-03-10 19:12:13.293672 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-10 19:12:13.625623 | controller | ok 2026-03-10 19:12:13.629884 | 2026-03-10 19:12:13.629948 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-10 19:12:13.653400 | controller | skipping: Conditional result was False 2026-03-10 19:12:13.663480 | 2026-03-10 19:12:13.663545 | TASK [include_role : validate-host] 2026-03-10 19:12:13.680747 | controller | ok 2026-03-10 19:12:13.697213 | 2026-03-10 19:12:13.697272 | TASK [validate-host : Define zuul_info_dir fact] 2026-03-10 19:12:13.714691 | controller | ok 2026-03-10 19:12:13.718718 | 2026-03-10 19:12:13.718775 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-03-10 19:12:13.893433 | controller -> localhost | ok 2026-03-10 19:12:13.898826 | 2026-03-10 19:12:13.898903 | TASK [validate-host : Collect information about the host] 2026-03-10 19:12:14.548025 | controller | ok 2026-03-10 19:12:14.573463 | 2026-03-10 19:12:14.573539 | TASK [validate-host : Sanitize hostname] 2026-03-10 19:12:14.611154 | controller | ok 2026-03-10 19:12:14.615465 | 2026-03-10 19:12:14.615523 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-03-10 19:12:14.952928 | controller -> localhost | changed 2026-03-10 19:12:14.957829 | 2026-03-10 19:12:14.957891 | TASK [validate-host : Collect information about zuul worker] 2026-03-10 19:12:15.310167 | controller | ok 2026-03-10 19:12:15.314484 | 2026-03-10 19:12:15.314549 | TASK [validate-host : Write out all zuul information for each host] 2026-03-10 19:12:15.631735 | controller -> localhost | changed 2026-03-10 19:12:15.639875 | 2026-03-10 19:12:15.639935 | TASK [include_role : prepare-workspace-openshift] 2026-03-10 19:12:15.653324 | controller | skipping: Conditional result was False 2026-03-10 19:12:15.658086 | 2026-03-10 19:12:15.658150 | TASK [include_role : remove-zuul-sshkey] 2026-03-10 19:12:15.671131 | controller | skipping: Conditional result was False 2026-03-10 19:12:15.675637 | 2026-03-10 19:12:15.675703 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-10 19:12:15.920384 | controller | ok: "logs" 2026-03-10 19:12:15.920589 | controller | ok: All items complete 2026-03-10 19:12:15.920618 | 2026-03-10 19:12:16.135166 | controller | ok: "artifacts" 2026-03-10 19:12:16.356227 | controller | ok: "docs" 2026-03-10 19:12:16.364498 | 2026-03-10 19:12:16.364581 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-10 19:12:16.609137 | controller | changed: "logs" 2026-03-10 19:12:16.833103 | controller | changed: "artifacts" 2026-03-10 19:12:17.054860 | controller | changed: "docs" 2026-03-10 19:12:17.077763 | 2026-03-10 19:12:17.077838 | PLAY RECAP 2026-03-10 19:12:17.077877 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2026-03-10 19:12:17.077903 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-10 19:12:17.077920 | 2026-03-10 19:12:17.146472 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-03-10 19:12:17.147121 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2026-03-10 19:12:17.668204 | 2026-03-10 19:12:17.668317 | PLAY [all] 2026-03-10 19:12:17.686001 | 2026-03-10 19:12:17.686069 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2026-03-10 19:12:17.734230 | controller | ok 2026-03-10 19:12:17.738706 | 2026-03-10 19:12:17.738779 | TASK [mirror-info-fork : Create /etc/ci] 2026-03-10 19:12:18.101656 | controller | changed 2026-03-10 19:12:18.106618 | 2026-03-10 19:12:18.106684 | TASK [mirror-info-fork : Install ci_mirror script] 2026-03-10 19:12:19.341290 | controller | changed 2026-03-10 19:12:19.349755 | 2026-03-10 19:12:19.349817 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2026-03-10 19:12:19.766394 | controller | changed: 2026-03-10 19:12:19.766553 | controller | { 2026-03-10 19:12:19.766580 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2026-03-10 19:12:19.766608 | controller | } 2026-03-10 19:12:20.057258 | controller | changed: 2026-03-10 19:12:20.057359 | controller | { 2026-03-10 19:12:20.057390 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2026-03-10 19:12:20.057411 | controller | } 2026-03-10 19:12:20.348268 | controller | changed: 2026-03-10 19:12:20.348339 | controller | { 2026-03-10 19:12:20.348371 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com" 2026-03-10 19:12:20.348395 | controller | } 2026-03-10 19:12:20.622096 | controller | changed: 2026-03-10 19:12:20.622142 | controller | { 2026-03-10 19:12:20.622167 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2026-03-10 19:12:20.622186 | controller | } 2026-03-10 19:12:20.899469 | controller | changed: 2026-03-10 19:12:20.899520 | controller | { 2026-03-10 19:12:20.899544 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2026-03-10 19:12:20.899563 | controller | } 2026-03-10 19:12:21.174814 | controller | changed: 2026-03-10 19:12:21.174862 | controller | { 2026-03-10 19:12:21.174887 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2026-03-10 19:12:21.174906 | controller | } 2026-03-10 19:12:21.466526 | controller | changed: 2026-03-10 19:12:21.466601 | controller | { 2026-03-10 19:12:21.466628 | 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" 2026-03-10 19:12:21.466648 | controller | } 2026-03-10 19:12:21.747913 | controller | changed: 2026-03-10 19:12:21.747987 | controller | { 2026-03-10 19:12:21.748013 | 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" 2026-03-10 19:12:21.748032 | controller | } 2026-03-10 19:12:22.042694 | controller | changed: 2026-03-10 19:12:22.042763 | controller | { 2026-03-10 19:12:22.042790 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2026-03-10 19:12:22.042810 | controller | } 2026-03-10 19:12:22.344535 | controller | changed: 2026-03-10 19:12:22.344608 | controller | { 2026-03-10 19:12:22.344633 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2026-03-10 19:12:22.344652 | controller | } 2026-03-10 19:12:22.635974 | controller | changed: 2026-03-10 19:12:22.636031 | controller | { 2026-03-10 19:12:22.636057 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2026-03-10 19:12:22.636077 | controller | } 2026-03-10 19:12:22.906651 | controller | changed: 2026-03-10 19:12:22.906703 | controller | { 2026-03-10 19:12:22.906729 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2026-03-10 19:12:22.906749 | controller | } 2026-03-10 19:12:23.190721 | controller | changed: 2026-03-10 19:12:23.190781 | controller | { 2026-03-10 19:12:23.190807 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com" 2026-03-10 19:12:23.190826 | controller | } 2026-03-10 19:12:23.464706 | controller | changed: 2026-03-10 19:12:23.464800 | controller | { 2026-03-10 19:12:23.464824 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2026-03-10 19:12:23.464842 | controller | } 2026-03-10 19:12:23.735550 | controller | changed: 2026-03-10 19:12:23.735612 | controller | { 2026-03-10 19:12:23.735636 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2026-03-10 19:12:23.735655 | controller | } 2026-03-10 19:12:24.018493 | controller | changed: 2026-03-10 19:12:24.018584 | controller | { 2026-03-10 19:12:24.018610 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2026-03-10 19:12:24.018630 | controller | } 2026-03-10 19:12:24.291037 | controller | changed: 2026-03-10 19:12:24.291125 | controller | { 2026-03-10 19:12:24.291152 | 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" 2026-03-10 19:12:24.291174 | controller | } 2026-03-10 19:12:24.568504 | controller | changed: 2026-03-10 19:12:24.568588 | controller | { 2026-03-10 19:12:24.568615 | 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" 2026-03-10 19:12:24.568643 | controller | } 2026-03-10 19:12:24.843954 | controller | changed: 2026-03-10 19:12:24.844095 | controller | { 2026-03-10 19:12:24.844129 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2026-03-10 19:12:24.844150 | controller | } 2026-03-10 19:12:25.110073 | controller | changed: 2026-03-10 19:12:25.110118 | controller | { 2026-03-10 19:12:25.110142 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2026-03-10 19:12:25.110160 | controller | } 2026-03-10 19:12:25.379190 | controller | changed: 2026-03-10 19:12:25.379247 | controller | { 2026-03-10 19:12:25.379271 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2026-03-10 19:12:25.379289 | controller | } 2026-03-10 19:12:25.650635 | controller | changed: 2026-03-10 19:12:25.650684 | controller | { 2026-03-10 19:12:25.650709 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2026-03-10 19:12:25.650728 | controller | } 2026-03-10 19:12:25.915283 | controller | changed: 2026-03-10 19:12:25.915350 | controller | { 2026-03-10 19:12:25.915376 | 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" 2026-03-10 19:12:25.915396 | controller | } 2026-03-10 19:12:26.214738 | controller | changed: 2026-03-10 19:12:26.214824 | controller | { 2026-03-10 19:12:26.214849 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2026-03-10 19:12:26.214868 | controller | } 2026-03-10 19:12:26.482278 | controller | changed: 2026-03-10 19:12:26.482383 | controller | { 2026-03-10 19:12:26.482407 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2026-03-10 19:12:26.482426 | controller | } 2026-03-10 19:12:26.748006 | controller | changed: 2026-03-10 19:12:26.748070 | controller | { 2026-03-10 19:12:26.748093 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2026-03-10 19:12:26.748111 | controller | } 2026-03-10 19:12:26.764378 | 2026-03-10 19:12:26.764441 | TASK [Set timezone to UTC] 2026-03-10 19:12:27.218056 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2026-03-10 19:12:27.222371 | 2026-03-10 19:12:27.222430 | TASK [Create nodepool directory] 2026-03-10 19:12:27.473827 | controller | changed 2026-03-10 19:12:27.479404 | 2026-03-10 19:12:27.479464 | TASK [Create nodepool sub_nodes file] 2026-03-10 19:12:28.366484 | controller | changed 2026-03-10 19:12:28.370853 | 2026-03-10 19:12:28.370913 | TASK [Create nodepool sub_nodes_private file] 2026-03-10 19:12:29.250709 | controller | changed 2026-03-10 19:12:29.255612 | 2026-03-10 19:12:29.255667 | LOOP [Populate nodepool sub_nodes file] 2026-03-10 19:12:29.283365 | 2026-03-10 19:12:29.283477 | LOOP [Populate nodepool sub_nodes_private file] 2026-03-10 19:12:29.310556 | 2026-03-10 19:12:29.310667 | TASK [Create nodepool primary file] 2026-03-10 19:12:29.323083 | controller | skipping: Conditional result was False 2026-03-10 19:12:29.328066 | 2026-03-10 19:12:29.328132 | TASK [Create nodepool node_private for this node] 2026-03-10 19:12:30.246936 | controller | changed 2026-03-10 19:12:30.252800 | 2026-03-10 19:12:30.252855 | LOOP [Copy ssh keys to nodepool directory] 2026-03-10 19:12:30.610739 | controller | ok: Item: id_rsa Runtime: 0:00:00.004069 2026-03-10 19:12:30.611021 | 2026-03-10 19:12:30.826790 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005215 2026-03-10 19:12:30.839259 | 2026-03-10 19:12:30.839391 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2026-03-10 19:12:31.761766 | controller | changed 2026-03-10 19:12:31.766263 | 2026-03-10 19:12:31.766344 | TASK [Validate sudoers config after edits] 2026-03-10 19:12:32.025903 | controller | /etc/sudoers: parsed OK 2026-03-10 19:12:32.025948 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2026-03-10 19:12:32.025956 | controller | /etc/sudoers.d/zuul: parsed OK 2026-03-10 19:12:32.025962 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2026-03-10 19:12:32.289692 | controller | ok: Runtime: 0:00:00.005143 2026-03-10 19:12:32.294550 | 2026-03-10 19:12:32.294612 | TASK [Show the environment passed in to job shell scripts] 2026-03-10 19:12:32.541451 | controller | SHELL=/bin/bash 2026-03-10 19:12:32.541488 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2026-03-10 19:12:32.541497 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2026-03-10 19:12:32.541504 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/55/3755/91294f26969c15166afab43826de6b8fc2e378c9 2026-03-10 19:12:32.541509 | controller | PWD=/home/zuul 2026-03-10 19:12:32.541514 | controller | ZUUL_PIPELINE=github-check 2026-03-10 19:12:32.541519 | controller | LOGNAME=zuul 2026-03-10 19:12:32.541524 | controller | XDG_SESSION_TYPE=tty 2026-03-10 19:12:32.541529 | controller | _=/usr/bin/env 2026-03-10 19:12:32.541534 | controller | MOTD_SHOWN=pam 2026-03-10 19:12:32.541538 | controller | HOME=/home/zuul 2026-03-10 19:12:32.541543 | controller | LANG=en_US.UTF-8 2026-03-10 19:12:32.541548 | controller | SSH_CONNECTION=192.168.26.12 55280 192.168.26.35 22 2026-03-10 19:12:32.541553 | 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 2026-03-10 19:12:32.541560 | controller | ZUUL_CHANGE_IDS=3755,91294f26969c15166afab43826de6b8fc2e378c9 2026-03-10 19:12:32.541565 | controller | WORKSPACE=/home/zuul/workspace 2026-03-10 19:12:32.541570 | controller | XDG_SESSION_CLASS=user 2026-03-10 19:12:32.541575 | controller | SELINUX_ROLE_REQUESTED= 2026-03-10 19:12:32.541580 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2026-03-10 19:12:32.541622 | controller | USER=zuul 2026-03-10 19:12:32.541629 | controller | ZUUL_VOTING=True 2026-03-10 19:12:32.541634 | controller | BUILD_TIMEOUT=5400000 2026-03-10 19:12:32.541639 | controller | SELINUX_USE_CURRENT_RANGE= 2026-03-10 19:12:32.541644 | controller | SHLVL=1 2026-03-10 19:12:32.541649 | controller | ZUUL_PATCHSET=91294f26969c15166afab43826de6b8fc2e378c9 2026-03-10 19:12:32.541654 | controller | XDG_SESSION_ID=1 2026-03-10 19:12:32.541659 | controller | ZUUL_BRANCH=main 2026-03-10 19:12:32.541663 | controller | XDG_RUNTIME_DIR=/run/user/1000 2026-03-10 19:12:32.541668 | controller | SSH_CLIENT=192.168.26.12 55280 22 2026-03-10 19:12:32.541673 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2026-03-10 19:12:32.541678 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2026-03-10 19:12:32.541683 | controller | which_declare=declare -f 2026-03-10 19:12:32.541690 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2026-03-10 19:12:32.541695 | controller | SELINUX_LEVEL_REQUESTED= 2026-03-10 19:12:32.541700 | controller | ZUUL_CHANGE=3755 2026-03-10 19:12:32.541704 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2026-03-10 19:12:32.541709 | controller | ZUUL_UUID=779875e0c9dd4fd7988c7ecf0b34c7d4 2026-03-10 19:12:32.541714 | controller | BASH_FUNC_which%%=() { ( alias; 2026-03-10 19:12:32.541719 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2026-03-10 19:12:32.541724 | controller | } 2026-03-10 19:12:32.818687 | controller | ok: Runtime: 0:00:00.005514 2026-03-10 19:12:32.823282 | 2026-03-10 19:12:32.823354 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2026-03-10 19:12:32.836951 | controller | skipping: Conditional result was False 2026-03-10 19:12:32.841897 | 2026-03-10 19:12:32.841961 | TASK [Symlink /home/zuul-worker/workspace] 2026-03-10 19:12:33.386787 | controller | skipping: Conditional result was False 2026-03-10 19:12:33.391992 | 2026-03-10 19:12:33.392054 | TASK [Ensure legacy workspace directory] 2026-03-10 19:12:33.644468 | controller | changed 2026-03-10 19:12:33.660712 | 2026-03-10 19:12:33.660763 | PLAY RECAP 2026-03-10 19:12:33.660803 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2026-03-10 19:12:33.660825 | 2026-03-10 19:12:33.727213 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2026-03-10 19:12:33.727992 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2026-03-10 19:12:34.198808 | 2026-03-10 19:12:34.198905 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2026-03-10 19:12:34.219111 | 2026-03-10 19:12:34.219182 | TASK [Create zuul-output directory] 2026-03-10 19:12:34.582723 | controller | changed 2026-03-10 19:12:34.587760 | 2026-03-10 19:12:34.587838 | TASK [Slurp Zuul inventory test] 2026-03-10 19:12:34.845969 | controller -> localhost | ok 2026-03-10 19:12:34.852863 | 2026-03-10 19:12:34.852929 | TASK [Save zuul inventory] 2026-03-10 19:12:35.957562 | controller | changed 2026-03-10 19:12:35.962382 | 2026-03-10 19:12:35.962450 | TASK [Save zuul vars without the change_message] 2026-03-10 19:12:36.905427 | controller | changed 2026-03-10 19:12:36.923079 | 2026-03-10 19:12:36.923132 | PLAY RECAP 2026-03-10 19:12:36.923173 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-10 19:12:36.923195 | 2026-03-10 19:12:36.986008 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2026-03-10 19:12:36.986673 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2026-03-10 19:12:37.493556 | 2026-03-10 19:12:37.493663 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2026-03-10 19:12:37.512000 | 2026-03-10 19:12:37.512068 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2026-03-10 19:12:37.530027 | controller | ok 2026-03-10 19:12:37.544992 | 2026-03-10 19:12:37.545071 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2026-03-10 19:12:37.567973 | controller | skipping: Conditional result was False 2026-03-10 19:12:37.573102 | 2026-03-10 19:12:37.573168 | TASK [mirror-info-fork : Create /etc/ci] 2026-03-10 19:12:37.925386 | controller | ok 2026-03-10 19:12:37.930370 | 2026-03-10 19:12:37.930433 | TASK [mirror-info-fork : Install ci_mirror script] 2026-03-10 19:12:38.823614 | controller | ok 2026-03-10 19:12:38.832016 | 2026-03-10 19:12:38.832078 | TASK [Prepare workspace] 2026-03-10 19:12:38.851048 | controller | ok 2026-03-10 19:12:38.865907 | 2026-03-10 19:12:38.865968 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-10 19:12:39.200369 | controller | ok 2026-03-10 19:12:39.205871 | 2026-03-10 19:12:39.205936 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-10 19:12:41.532388 | controller | Output suppressed because no_log was given 2026-03-10 19:12:41.540631 | 2026-03-10 19:12:41.540691 | LOOP [Create zuul-output directory] 2026-03-10 19:12:41.797474 | controller | changed: "/home/zuul/ci-framework-data/logs" 2026-03-10 19:12:42.018337 | controller | ok: "/home/zuul/zuul-output/logs" 2026-03-10 19:12:42.025842 | 2026-03-10 19:12:42.025909 | TASK [Install required packages] 2026-03-10 19:12:58.624764 | controller | ok: Nothing to do 2026-03-10 19:12:58.629537 | 2026-03-10 19:12:58.629596 | TASK [Install venv] 2026-03-10 19:13:55.313515 | controller | changed 2026-03-10 19:13:55.335860 | 2026-03-10 19:13:55.335916 | PLAY RECAP 2026-03-10 19:13:55.335957 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-10 19:13:55.335978 | 2026-03-10 19:13:55.402667 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2026-03-10 19:13:55.403403 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2026-03-10 19:13:55.873049 | 2026-03-10 19:13:55.873169 | PLAY [Run ci/playbooks/molecule-test.yml] 2026-03-10 19:13:55.892339 | 2026-03-10 19:13:55.892416 | TASK [Gather required facts] 2026-03-10 19:13:56.387066 | controller | ok 2026-03-10 19:13:56.391861 | 2026-03-10 19:13:56.391930 | TASK [Load environment var if instructed to] 2026-03-10 19:13:56.415730 | controller | skipping: Conditional result was False 2026-03-10 19:13:56.420948 | 2026-03-10 19:13:56.421021 | TASK [Ensure group_vars dir exists] 2026-03-10 19:13:56.763248 | controller | ok 2026-03-10 19:13:56.768996 | 2026-03-10 19:13:56.769062 | TASK [Print related variables] 2026-03-10 19:13:56.796909 | controller | ok: 2026-03-10 19:13:56.797015 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml 2026-03-10 19:13:56.797044 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer 2026-03-10 19:13:56.801258 | 2026-03-10 19:13:56.801331 | TASK [Run molecule] 2026-03-10 19:13:57.785871 | controller | INFO Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml 2026-03-10 19:13:58.037893 | controller | INFO Performing prerun with role_name_check=0... 2026-03-10 19:14:14.656979 | controller | WARNING Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:14.657376 | controller | WARNING Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:14.657795 | controller | WARNING Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:14.658151 | controller | WARNING Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:14.658591 | controller | WARNING Another version of 'cifmw.general' 1.0.0+91294f26 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.0.0+91294f26 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:14.658987 | controller | WARNING Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:14.659377 | controller | WARNING Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:14.659779 | controller | WARNING Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:14.660131 | controller | WARNING Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:14.660534 | controller | WARNING Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:14.660908 | controller | WARNING Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:14.661299 | controller | WARNING Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:14.661726 | controller | WARNING Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:14.662141 | controller | WARNING Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:14.662587 | controller | WARNING Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:14.671414 | controller | INFO Running crc_layout > prepare 2026-03-10 19:14:15.311977 | controller | 2026-03-10 19:14:15.312188 | controller | PLAY [Prepare] ***************************************************************** 2026-03-10 19:14:15.312325 | controller | 2026-03-10 19:14:15.312540 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-10 19:14:15.312634 | controller | Tuesday 10 March 2026 19:14:15 +0000 (0:00:00.018) 0:00:00.018 ********* 2026-03-10 19:14:16.105687 | controller | ok: [instance] 2026-03-10 19:14:16.403817 | controller | 2026-03-10 19:14:16.403851 | controller | TASK [Create custom basedir] *************************************************** 2026-03-10 19:14:16.403859 | controller | Tuesday 10 March 2026 19:14:16 +0000 (0:00:00.794) 0:00:00.812 ********* 2026-03-10 19:14:16.403870 | controller | changed: [instance] 2026-03-10 19:14:16.403951 | controller | 2026-03-10 19:14:16.403961 | controller | TASK [test_deps : Gather facts if they don't exist] **************************** 2026-03-10 19:14:16.404040 | controller | Tuesday 10 March 2026 19:14:16 +0000 (0:00:00.298) 0:00:01.111 ********* 2026-03-10 19:14:16.421073 | controller | skipping: [instance] 2026-03-10 19:14:16.421146 | controller | 2026-03-10 19:14:16.421260 | controller | TASK [test_deps : Gather variables for each operating system] ****************** 2026-03-10 19:14:16.421360 | controller | Tuesday 10 March 2026 19:14:16 +0000 (0:00:00.017) 0:00:01.128 ********* 2026-03-10 19:14:16.460208 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml) 2026-03-10 19:14:16.460302 | controller | 2026-03-10 19:14:16.460472 | controller | TASK [test_deps : Detect mirrors file] ***************************************** 2026-03-10 19:14:16.460565 | controller | Tuesday 10 March 2026 19:14:16 +0000 (0:00:00.039) 0:00:01.167 ********* 2026-03-10 19:14:16.731798 | controller | ok: [instance] 2026-03-10 19:14:16.731887 | controller | 2026-03-10 19:14:16.731988 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] *********** 2026-03-10 19:14:16.732086 | controller | Tuesday 10 March 2026 19:14:16 +0000 (0:00:00.271) 0:00:01.439 ********* 2026-03-10 19:14:16.930944 | controller | ok: [instance] 2026-03-10 19:14:16.931032 | controller | 2026-03-10 19:14:16.931141 | controller | TASK [test_deps : Disable ubi 9 repos] ***************************************** 2026-03-10 19:14:16.931246 | controller | Tuesday 10 March 2026 19:14:16 +0000 (0:00:00.199) 0:00:01.638 ********* 2026-03-10 19:14:17.586768 | controller | ok: [instance] => (item=ubi-9-appstream) 2026-03-10 19:14:17.586839 | controller | ok: [instance] => (item=ubi-9-baseos) 2026-03-10 19:14:17.586935 | controller | ok: [instance] => (item=ubi-9-codeready-builder) 2026-03-10 19:14:17.587031 | controller | 2026-03-10 19:14:17.587122 | controller | TASK [Deploy repo-setup] ******************************************************* 2026-03-10 19:14:17.587205 | controller | Tuesday 10 March 2026 19:14:17 +0000 (0:00:00.655) 0:00:02.293 ********* 2026-03-10 19:14:17.644100 | controller | 2026-03-10 19:14:17.644139 | controller | TASK [repo_setup : Ensure directories are present] ***************************** 2026-03-10 19:14:17.644275 | controller | Tuesday 10 March 2026 19:14:17 +0000 (0:00:00.057) 0:00:02.351 ********* 2026-03-10 19:14:18.137063 | controller | changed: [instance] => (item=tmp) 2026-03-10 19:14:18.137171 | controller | changed: [instance] => (item=artifacts/repositories) 2026-03-10 19:14:18.137195 | controller | changed: [instance] => (item=venv/repo_setup) 2026-03-10 19:14:18.137231 | controller | 2026-03-10 19:14:18.137357 | controller | TASK [repo_setup : Make sure git-core package is installed] ******************** 2026-03-10 19:14:18.137445 | controller | Tuesday 10 March 2026 19:14:18 +0000 (0:00:00.492) 0:00:02.844 ********* 2026-03-10 19:14:18.930305 | controller | ok: [instance] 2026-03-10 19:14:18.930555 | controller | 2026-03-10 19:14:18.930633 | controller | TASK [repo_setup : Get repo-setup repository] ********************************** 2026-03-10 19:14:18.930653 | controller | Tuesday 10 March 2026 19:14:18 +0000 (0:00:00.793) 0:00:03.637 ********* 2026-03-10 19:14:19.989351 | controller | changed: [instance] 2026-03-10 19:14:19.989416 | controller | 2026-03-10 19:14:19.989650 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************ 2026-03-10 19:14:27.120748 | controller | Tuesday 10 March 2026 19:14:19 +0000 (0:00:01.058) 0:00:04.696 ********* 2026-03-10 19:14:27.120782 | controller | changed: [instance] 2026-03-10 19:14:27.802105 | controller | 2026-03-10 19:14:27.802135 | controller | TASK [repo_setup : Install repo-setup package] ********************************* 2026-03-10 19:14:27.802143 | controller | Tuesday 10 March 2026 19:14:27 +0000 (0:00:07.130) 0:00:11.827 ********* 2026-03-10 19:14:27.802153 | controller | changed: [instance] 2026-03-10 19:14:27.823343 | controller | 2026-03-10 19:14:27.823373 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] *** 2026-03-10 19:14:27.823382 | controller | Tuesday 10 March 2026 19:14:27 +0000 (0:00:00.681) 0:00:12.508 ********* 2026-03-10 19:14:27.823392 | controller | skipping: [instance] 2026-03-10 19:14:27.823429 | controller | 2026-03-10 19:14:27.823670 | controller | TASK [repo_setup : Run repo-setup] ********************************************* 2026-03-10 19:14:28.624855 | controller | Tuesday 10 March 2026 19:14:27 +0000 (0:00:00.021) 0:00:12.530 ********* 2026-03-10 19:14:28.624890 | controller | changed: [instance] 2026-03-10 19:14:28.660469 | controller | 2026-03-10 19:14:28.660512 | controller | TASK [repo_setup : Get component repo] ***************************************** 2026-03-10 19:14:28.660525 | controller | Tuesday 10 March 2026 19:14:28 +0000 (0:00:00.801) 0:00:13.331 ********* 2026-03-10 19:14:28.660541 | controller | skipping: [instance] 2026-03-10 19:14:28.696015 | controller | 2026-03-10 19:14:28.696047 | controller | TASK [repo_setup : Rename component repo] ************************************** 2026-03-10 19:14:28.696055 | controller | Tuesday 10 March 2026 19:14:28 +0000 (0:00:00.035) 0:00:13.367 ********* 2026-03-10 19:14:28.696066 | controller | skipping: [instance] 2026-03-10 19:14:28.731057 | controller | 2026-03-10 19:14:28.731082 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] ******* 2026-03-10 19:14:28.731090 | controller | Tuesday 10 March 2026 19:14:28 +0000 (0:00:00.035) 0:00:13.402 ********* 2026-03-10 19:14:28.731100 | controller | skipping: [instance] 2026-03-10 19:14:29.243306 | controller | 2026-03-10 19:14:29.243335 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************ 2026-03-10 19:14:29.243343 | controller | Tuesday 10 March 2026 19:14:28 +0000 (0:00:00.035) 0:00:13.438 ********* 2026-03-10 19:14:29.243355 | controller | changed: [instance] 2026-03-10 19:14:29.667689 | controller | 2026-03-10 19:14:29.667714 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] ******************* 2026-03-10 19:14:29.667722 | controller | Tuesday 10 March 2026 19:14:29 +0000 (0:00:00.512) 0:00:13.950 ********* 2026-03-10 19:14:29.667732 | controller | changed: [instance] 2026-03-10 19:14:29.697170 | controller | 2026-03-10 19:14:29.697189 | controller | TASK [repo_setup : Dump current-podified hash] ********************************* 2026-03-10 19:14:29.697197 | controller | Tuesday 10 March 2026 19:14:29 +0000 (0:00:00.424) 0:00:14.374 ********* 2026-03-10 19:14:29.697206 | controller | skipping: [instance] 2026-03-10 19:14:29.725372 | controller | 2026-03-10 19:14:29.725397 | controller | TASK [repo_setup : Slurp current podified hash] ******************************** 2026-03-10 19:14:29.725404 | controller | Tuesday 10 March 2026 19:14:29 +0000 (0:00:00.029) 0:00:14.404 ********* 2026-03-10 19:14:29.725416 | controller | skipping: [instance] 2026-03-10 19:14:29.753402 | controller | 2026-03-10 19:14:29.753451 | controller | TASK [repo_setup : Update the value of full_hash] ****************************** 2026-03-10 19:14:29.753462 | controller | Tuesday 10 March 2026 19:14:29 +0000 (0:00:00.028) 0:00:14.432 ********* 2026-03-10 19:14:29.753475 | controller | skipping: [instance] 2026-03-10 19:14:29.753509 | controller | 2026-03-10 19:14:29.753520 | controller | TASK [repo_setup : Export hashes facts for further use] ************************ 2026-03-10 19:14:29.753561 | controller | Tuesday 10 March 2026 19:14:29 +0000 (0:00:00.028) 0:00:14.460 ********* 2026-03-10 19:14:29.790234 | controller | ok: [instance] 2026-03-10 19:14:29.815410 | controller | 2026-03-10 19:14:29.815460 | controller | TASK [repo_setup : Create download directory] ********************************** 2026-03-10 19:14:29.815471 | controller | Tuesday 10 March 2026 19:14:29 +0000 (0:00:00.036) 0:00:14.497 ********* 2026-03-10 19:14:29.815493 | controller | skipping: [instance] 2026-03-10 19:14:29.815526 | controller | 2026-03-10 19:14:29.815541 | controller | TASK [repo_setup : Print the URL to request] *********************************** 2026-03-10 19:14:29.815604 | controller | Tuesday 10 March 2026 19:14:29 +0000 (0:00:00.025) 0:00:14.522 ********* 2026-03-10 19:14:29.841347 | controller | skipping: [instance] 2026-03-10 19:14:29.870706 | controller | 2026-03-10 19:14:29.870724 | controller | TASK [Download the RPM] ******************************************************** 2026-03-10 19:14:29.870731 | controller | Tuesday 10 March 2026 19:14:29 +0000 (0:00:00.026) 0:00:14.548 ********* 2026-03-10 19:14:29.870740 | controller | skipping: [instance] 2026-03-10 19:14:29.897187 | controller | 2026-03-10 19:14:29.897208 | controller | TASK [repo_setup : Install RHOS Release tool] ********************************** 2026-03-10 19:14:29.897217 | controller | Tuesday 10 March 2026 19:14:29 +0000 (0:00:00.029) 0:00:14.577 ********* 2026-03-10 19:14:29.897227 | controller | skipping: [instance] 2026-03-10 19:14:29.922871 | controller | 2026-03-10 19:14:29.922888 | controller | TASK [repo_setup : Get rhos-release tool version] ****************************** 2026-03-10 19:14:29.922894 | controller | Tuesday 10 March 2026 19:14:29 +0000 (0:00:00.026) 0:00:14.604 ********* 2026-03-10 19:14:29.922902 | controller | skipping: [instance] 2026-03-10 19:14:29.950190 | controller | 2026-03-10 19:14:29.950212 | controller | TASK [repo_setup : Print rhos-release tool version] **************************** 2026-03-10 19:14:29.950221 | controller | Tuesday 10 March 2026 19:14:29 +0000 (0:00:00.025) 0:00:14.630 ********* 2026-03-10 19:14:29.950231 | controller | skipping: [instance] 2026-03-10 19:14:29.974957 | controller | 2026-03-10 19:14:29.974977 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] *** 2026-03-10 19:14:29.974986 | controller | Tuesday 10 March 2026 19:14:29 +0000 (0:00:00.027) 0:00:14.657 ********* 2026-03-10 19:14:29.975001 | controller | skipping: [instance] 2026-03-10 19:14:29.975152 | controller | 2026-03-10 19:14:29.975352 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] *************************** 2026-03-10 19:14:29.975530 | controller | Tuesday 10 March 2026 19:14:29 +0000 (0:00:00.024) 0:00:14.682 ********* 2026-03-10 19:14:30.153066 | controller | ok: [instance] 2026-03-10 19:14:30.376340 | controller | 2026-03-10 19:14:30.376367 | controller | TASK [repo_setup : Use RDO proxy mirrors] ************************************** 2026-03-10 19:14:30.376375 | controller | Tuesday 10 March 2026 19:14:30 +0000 (0:00:00.177) 0:00:14.860 ********* 2026-03-10 19:14:30.376385 | controller | changed: [instance] 2026-03-10 19:14:30.376515 | controller | 2026-03-10 19:14:30.376548 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] *** 2026-03-10 19:14:30.376562 | controller | Tuesday 10 March 2026 19:14:30 +0000 (0:00:00.223) 0:00:15.083 ********* 2026-03-10 19:14:30.591327 | controller | changed: [instance] 2026-03-10 19:14:30.591453 | controller | 2026-03-10 19:14:30.591481 | controller | TASK [repo_setup : Check for gating.repo file on content provider] ************* 2026-03-10 19:14:30.591493 | controller | Tuesday 10 March 2026 19:14:30 +0000 (0:00:00.214) 0:00:15.298 ********* 2026-03-10 19:14:30.613309 | controller | skipping: [instance] 2026-03-10 19:14:30.613346 | controller | 2026-03-10 19:14:30.613355 | controller | TASK [repo_setup : Populate gating repo from content provider ip] ************** 2026-03-10 19:14:30.613496 | controller | Tuesday 10 March 2026 19:14:30 +0000 (0:00:00.022) 0:00:15.320 ********* 2026-03-10 19:14:30.635997 | controller | skipping: [instance] 2026-03-10 19:14:30.657305 | controller | 2026-03-10 19:14:30.657322 | controller | TASK [repo_setup : Check for DLRN repo at the destination] ********************* 2026-03-10 19:14:30.657329 | controller | Tuesday 10 March 2026 19:14:30 +0000 (0:00:00.022) 0:00:15.343 ********* 2026-03-10 19:14:30.657338 | controller | skipping: [instance] 2026-03-10 19:14:30.657631 | controller | 2026-03-10 19:14:30.678846 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] *** 2026-03-10 19:14:30.678866 | controller | Tuesday 10 March 2026 19:14:30 +0000 (0:00:00.021) 0:00:15.364 ********* 2026-03-10 19:14:30.678879 | controller | skipping: [instance] 2026-03-10 19:14:30.699661 | controller | 2026-03-10 19:14:30.699681 | controller | TASK [repo_setup : Check for DLRN component repo] ****************************** 2026-03-10 19:14:30.699696 | controller | Tuesday 10 March 2026 19:14:30 +0000 (0:00:00.021) 0:00:15.386 ********* 2026-03-10 19:14:30.699706 | controller | skipping: [instance] 2026-03-10 19:14:30.720353 | controller | 2026-03-10 19:14:30.720372 | controller | TASK [repo_setup : Lower the priority of component repos to allow installation from gating repo] *** 2026-03-10 19:14:30.720380 | controller | Tuesday 10 March 2026 19:14:30 +0000 (0:00:00.020) 0:00:15.406 ********* 2026-03-10 19:14:30.720389 | controller | skipping: [instance] 2026-03-10 19:14:30.720396 | controller | 2026-03-10 19:14:30.720402 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ******** 2026-03-10 19:14:30.720410 | controller | Tuesday 10 March 2026 19:14:30 +0000 (0:00:00.020) 0:00:15.427 ********* 2026-03-10 19:14:30.737389 | controller | skipping: [instance] 2026-03-10 19:14:30.766073 | controller | 2026-03-10 19:14:30.766110 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ****** 2026-03-10 19:14:30.766124 | controller | Tuesday 10 March 2026 19:14:30 +0000 (0:00:00.016) 0:00:15.444 ********* 2026-03-10 19:14:30.766140 | controller | skipping: [instance] 2026-03-10 19:14:30.766193 | controller | 2026-03-10 19:14:30.766366 | controller | TASK [repo_setup : Cleanup existing metadata] ********************************** 2026-03-10 19:14:30.766501 | controller | Tuesday 10 March 2026 19:14:30 +0000 (0:00:00.028) 0:00:15.473 ********* 2026-03-10 19:14:30.783242 | controller | skipping: [instance] 2026-03-10 19:14:30.783284 | controller | 2026-03-10 19:14:30.783294 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] ********* 2026-03-10 19:14:30.783305 | controller | Tuesday 10 March 2026 19:14:30 +0000 (0:00:00.016) 0:00:15.490 ********* 2026-03-10 19:14:30.806370 | controller | skipping: [instance] 2026-03-10 19:14:30.806447 | controller | 2026-03-10 19:14:30.806598 | controller | TASK [test_deps : Look for redhat-release rpm] ********************************* 2026-03-10 19:14:30.806691 | controller | Tuesday 10 March 2026 19:14:30 +0000 (0:00:00.023) 0:00:15.513 ********* 2026-03-10 19:14:30.835660 | controller | skipping: [instance] 2026-03-10 19:14:30.835787 | controller | 2026-03-10 19:14:30.835953 | controller | TASK [test_deps : Remove redhat-release] *************************************** 2026-03-10 19:14:30.836058 | controller | Tuesday 10 March 2026 19:14:30 +0000 (0:00:00.029) 0:00:15.543 ********* 2026-03-10 19:14:30.864872 | controller | skipping: [instance] 2026-03-10 19:14:30.865045 | controller | 2026-03-10 19:14:30.865168 | controller | TASK [test_deps : Install centos-stream-release] ******************************* 2026-03-10 19:14:30.865351 | controller | Tuesday 10 March 2026 19:14:30 +0000 (0:00:00.028) 0:00:15.572 ********* 2026-03-10 19:14:30.892728 | controller | skipping: [instance] 2026-03-10 19:14:30.892852 | controller | 2026-03-10 19:14:30.893032 | controller | TASK [test_deps : Install additional packages] ********************************* 2026-03-10 19:14:30.893136 | controller | Tuesday 10 March 2026 19:14:30 +0000 (0:00:00.028) 0:00:15.600 ********* 2026-03-10 19:14:30.920498 | controller | skipping: [instance] 2026-03-10 19:14:30.920613 | controller | 2026-03-10 19:14:30.920738 | controller | TASK [test_deps : Install selinux python libs] ********************************* 2026-03-10 19:14:30.920854 | controller | Tuesday 10 March 2026 19:14:30 +0000 (0:00:00.027) 0:00:15.627 ********* 2026-03-10 19:15:15.645166 | controller | ok: [instance] 2026-03-10 19:15:16.848948 | controller | 2026-03-10 19:15:16.848979 | controller | TASK [test_deps : Install python yaml libs] ************************************ 2026-03-10 19:15:16.848987 | controller | Tuesday 10 March 2026 19:15:15 +0000 (0:00:44.724) 0:01:00.352 ********* 2026-03-10 19:15:16.848997 | controller | ok: [instance] 2026-03-10 19:15:16.880650 | controller | 2026-03-10 19:15:16.880675 | controller | TASK [test_deps : Install extra packages] ************************************** 2026-03-10 19:15:16.880683 | controller | Tuesday 10 March 2026 19:15:16 +0000 (0:00:01.203) 0:01:01.555 ********* 2026-03-10 19:15:16.880693 | controller | skipping: [instance] 2026-03-10 19:15:16.921379 | controller | 2026-03-10 19:15:16.921413 | controller | TASK [ci_setup : Gather variables for each operating system] ******************* 2026-03-10 19:15:16.921422 | controller | Tuesday 10 March 2026 19:15:16 +0000 (0:00:00.031) 0:01:01.587 ********* 2026-03-10 19:15:16.921434 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml) 2026-03-10 19:15:16.921480 | controller | 2026-03-10 19:15:16.921492 | controller | TASK [ci_setup : List packages to install] ************************************* 2026-03-10 19:15:16.921559 | controller | Tuesday 10 March 2026 19:15:16 +0000 (0:00:00.041) 0:01:01.628 ********* 2026-03-10 19:15:16.939498 | controller | ok: [instance] => 2026-03-10 19:15:16.939581 | controller | cifmw_ci_setup_packages: 2026-03-10 19:15:16.939596 | controller | - bash-completion 2026-03-10 19:15:16.939606 | controller | - ca-certificates 2026-03-10 19:15:16.939616 | controller | - git-core 2026-03-10 19:15:16.939626 | controller | - make 2026-03-10 19:15:16.939640 | controller | - tar 2026-03-10 19:15:16.939678 | controller | - tmux 2026-03-10 19:15:16.940040 | controller | - python3-pip 2026-03-10 19:15:21.326324 | controller | 2026-03-10 19:15:21.326356 | controller | TASK [ci_setup : Install needed packages] ************************************** 2026-03-10 19:15:21.326364 | controller | Tuesday 10 March 2026 19:15:16 +0000 (0:00:00.017) 0:01:01.646 ********* 2026-03-10 19:15:21.326374 | controller | changed: [instance] 2026-03-10 19:15:21.862225 | controller | 2026-03-10 19:15:21.862275 | controller | TASK [ci_setup : Gather version of openshift client] *************************** 2026-03-10 19:15:21.862287 | controller | Tuesday 10 March 2026 19:15:21 +0000 (0:00:04.386) 0:01:06.033 ********* 2026-03-10 19:15:21.862298 | controller | ok: [instance] 2026-03-10 19:15:22.054784 | controller | 2026-03-10 19:15:22.054808 | controller | TASK [ci_setup : Ensure openshift client install path is present] ************** 2026-03-10 19:15:22.054816 | controller | Tuesday 10 March 2026 19:15:21 +0000 (0:00:00.535) 0:01:06.569 ********* 2026-03-10 19:15:22.054826 | controller | changed: [instance] 2026-03-10 19:15:27.950957 | controller | 2026-03-10 19:15:27.950990 | controller | TASK [ci_setup : Install openshift client] ************************************* 2026-03-10 19:15:27.950998 | controller | Tuesday 10 March 2026 19:15:22 +0000 (0:00:00.192) 0:01:06.761 ********* 2026-03-10 19:15:27.951008 | controller | changed: [instance] 2026-03-10 19:15:27.986417 | controller | 2026-03-10 19:15:27.986473 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] ********************** 2026-03-10 19:15:27.986484 | controller | Tuesday 10 March 2026 19:15:27 +0000 (0:00:05.896) 0:01:12.657 ********* 2026-03-10 19:15:27.986496 | controller | ok: [instance] 2026-03-10 19:15:27.986530 | controller | 2026-03-10 19:15:27.986559 | controller | TASK [ci_setup : Create completion file] *************************************** 2026-03-10 19:15:27.986712 | controller | Tuesday 10 March 2026 19:15:27 +0000 (0:00:00.035) 0:01:12.693 ********* 2026-03-10 19:15:28.244347 | controller | changed: [instance] 2026-03-10 19:15:28.514240 | controller | 2026-03-10 19:15:28.514351 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************ 2026-03-10 19:15:28.514361 | controller | Tuesday 10 March 2026 19:15:28 +0000 (0:00:00.257) 0:01:12.951 ********* 2026-03-10 19:15:28.514371 | controller | changed: [instance] 2026-03-10 19:15:28.532031 | controller | 2026-03-10 19:15:28.532049 | controller | TASK [ci_setup : Check rhsm status] ******************************************** 2026-03-10 19:15:28.532056 | controller | Tuesday 10 March 2026 19:15:28 +0000 (0:00:00.270) 0:01:13.221 ********* 2026-03-10 19:15:28.532065 | controller | skipping: [instance] 2026-03-10 19:15:28.549076 | controller | 2026-03-10 19:15:28.549091 | controller | TASK [ci_setup : Gather the repos to be enabled] ******************************* 2026-03-10 19:15:28.549097 | controller | Tuesday 10 March 2026 19:15:28 +0000 (0:00:00.017) 0:01:13.239 ********* 2026-03-10 19:15:28.549105 | controller | skipping: [instance] 2026-03-10 19:15:28.565332 | controller | 2026-03-10 19:15:28.565366 | controller | TASK [ci_setup : Enabling the required repositories.] ************************** 2026-03-10 19:15:28.565374 | controller | Tuesday 10 March 2026 19:15:28 +0000 (0:00:00.016) 0:01:13.256 ********* 2026-03-10 19:15:28.565385 | controller | skipping: [instance] 2026-03-10 19:15:28.565713 | controller | 2026-03-10 19:15:28.565748 | controller | TASK [ci_setup : Get current /etc/redhat-release] ****************************** 2026-03-10 19:15:28.581381 | controller | Tuesday 10 March 2026 19:15:28 +0000 (0:00:00.016) 0:01:13.272 ********* 2026-03-10 19:15:28.581422 | controller | skipping: [instance] 2026-03-10 19:15:28.581466 | controller | 2026-03-10 19:15:28.581478 | controller | TASK [ci_setup : Print current /etc/redhat-release] **************************** 2026-03-10 19:15:28.581567 | controller | Tuesday 10 March 2026 19:15:28 +0000 (0:00:00.015) 0:01:13.288 ********* 2026-03-10 19:15:28.596991 | controller | skipping: [instance] 2026-03-10 19:15:28.617377 | controller | 2026-03-10 19:15:28.617402 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] ********* 2026-03-10 19:15:28.617412 | controller | Tuesday 10 March 2026 19:15:28 +0000 (0:00:00.015) 0:01:13.304 ********* 2026-03-10 19:15:28.617424 | controller | skipping: [instance] 2026-03-10 19:15:28.617497 | controller | 2026-03-10 19:15:28.617512 | controller | TASK [ci_setup : Manage directories] ******************************************* 2026-03-10 19:15:28.617702 | controller | Tuesday 10 March 2026 19:15:28 +0000 (0:00:00.020) 0:01:13.324 ********* 2026-03-10 19:15:29.573339 | controller | changed: [instance] => (item=/opt/basedir/artifacts/manifests/openstack/cr) 2026-03-10 19:15:29.573388 | controller | changed: [instance] => (item=/opt/basedir/logs) 2026-03-10 19:15:29.573399 | controller | ok: [instance] => (item=/opt/basedir/tmp) 2026-03-10 19:15:29.573834 | controller | changed: [instance] => (item=/opt/basedir/volumes) 2026-03-10 19:15:29.900320 | controller | changed: [instance] => (item=/opt/basedir/artifacts/parameters) 2026-03-10 19:15:29.900345 | controller | 2026-03-10 19:15:29.900352 | controller | TASK [Create secret file] ****************************************************** 2026-03-10 19:15:29.900358 | controller | Tuesday 10 March 2026 19:15:29 +0000 (0:00:00.955) 0:01:14.280 ********* 2026-03-10 19:15:29.900368 | controller | changed: [instance] 2026-03-10 19:15:29.900392 | controller | 2026-03-10 19:15:29.900398 | controller | PLAY RECAP ********************************************************************* 2026-03-10 19:15:29.900404 | controller | instance : ok=31 changed=17 unreachable=0 failed=0 skipped=36 rescued=0 ignored=0 2026-03-10 19:15:29.900410 | controller | 2026-03-10 19:15:29.900420 | controller | Tuesday 10 March 2026 19:15:29 +0000 (0:00:00.326) 0:01:14.606 ********* 2026-03-10 19:15:29.900426 | controller | =============================================================================== 2026-03-10 19:15:29.900431 | controller | test_deps : Install selinux python libs -------------------------------- 44.72s 2026-03-10 19:15:29.900437 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.13s 2026-03-10 19:15:29.900452 | controller | ci_setup : Install openshift client ------------------------------------- 5.90s 2026-03-10 19:15:29.900459 | controller | ci_setup : Install needed packages -------------------------------------- 4.39s 2026-03-10 19:15:29.901307 | controller | test_deps : Install python yaml libs ------------------------------------ 1.20s 2026-03-10 19:15:29.901830 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.06s 2026-03-10 19:15:29.901860 | controller | ci_setup : Manage directories ------------------------------------------- 0.96s 2026-03-10 19:15:29.901868 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.80s 2026-03-10 19:15:29.901873 | controller | Gathering Facts --------------------------------------------------------- 0.79s 2026-03-10 19:15:29.901879 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.79s 2026-03-10 19:15:29.901884 | controller | repo_setup : Install repo-setup package --------------------------------- 0.68s 2026-03-10 19:15:29.901889 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.66s 2026-03-10 19:15:29.901895 | controller | ci_setup : Gather version of openshift client --------------------------- 0.54s 2026-03-10 19:15:29.901900 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.51s 2026-03-10 19:15:29.901909 | controller | repo_setup : Ensure directories are present ----------------------------- 0.49s 2026-03-10 19:15:29.955465 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.42s 2026-03-10 19:15:29.955498 | controller | Create secret file ------------------------------------------------------ 0.33s 2026-03-10 19:15:29.955506 | controller | Create custom basedir --------------------------------------------------- 0.30s 2026-03-10 19:15:29.955511 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.27s 2026-03-10 19:15:29.955522 | controller | ci_setup : Source completion from within .bashrc ------------------------ 0.27s 2026-03-10 19:15:29.955533 | controller | INFO Running crc_layout > converge 2026-03-10 19:15:30.546798 | controller | 2026-03-10 19:15:30.546987 | controller | PLAY [Converge] **************************************************************** 2026-03-10 19:15:30.547120 | controller | 2026-03-10 19:15:30.547249 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-10 19:15:30.547426 | controller | Tuesday 10 March 2026 19:15:30 +0000 (0:00:00.027) 0:00:00.027 ********* 2026-03-10 19:15:31.333412 | controller | ok: [instance] 2026-03-10 19:15:31.333511 | controller | 2026-03-10 19:15:31.333556 | controller | TASK [Load networking definition from scenario file] *************************** 2026-03-10 19:15:31.333728 | controller | Tuesday 10 March 2026 19:15:31 +0000 (0:00:00.786) 0:00:00.813 ********* 2026-03-10 19:15:31.354761 | controller | ok: [instance] 2026-03-10 19:15:31.372302 | controller | 2026-03-10 19:15:31.372319 | controller | TASK [discover_latest_image : Set the discovered image vars to empty/null] ***** 2026-03-10 19:15:31.372327 | controller | Tuesday 10 March 2026 19:15:31 +0000 (0:00:00.021) 0:00:00.835 ********* 2026-03-10 19:15:31.372335 | controller | ok: [instance] 2026-03-10 19:15:31.372515 | controller | 2026-03-10 19:15:31.372536 | controller | TASK [discover_latest_image : Discover images] ********************************* 2026-03-10 19:15:31.409990 | controller | Tuesday 10 March 2026 19:15:31 +0000 (0:00:00.017) 0:00:00.853 ********* 2026-03-10 19:15:31.410021 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/discover_latest_image/tasks/per-image.yml for instance => (item={'key': 'default', 'value': {'base_url': 'https://cloud.centos.org/centos/9-stream/x86_64/images/', 'qcow_prefix': 'CentOS-Stream-GenericCloud-', 'images_file': 'CHECKSUM'}}) 2026-03-10 19:15:31.450859 | controller | 2026-03-10 19:15:31.450881 | controller | TASK [discover_latest_image : Ensure the current image dict entry has the expected data] *** 2026-03-10 19:15:31.450891 | controller | Tuesday 10 March 2026 19:15:31 +0000 (0:00:00.037) 0:00:00.890 ********* 2026-03-10 19:15:31.450901 | controller | ok: [instance] => changed=false 2026-03-10 19:15:33.006105 | controller | msg: All assertions passed 2026-03-10 19:15:33.006138 | controller | 2026-03-10 19:15:33.006146 | controller | TASK [discover_latest_image : Get latest image for the current entry] ********** 2026-03-10 19:15:33.006153 | controller | Tuesday 10 March 2026 19:15:31 +0000 (0:00:00.040) 0:00:00.931 ********* 2026-03-10 19:15:33.006163 | controller | changed: [instance] 2026-03-10 19:15:33.036958 | controller | 2026-03-10 19:15:33.036985 | controller | TASK [discover_latest_image : Append the current return value to the discovered images dict] *** 2026-03-10 19:15:33.036994 | controller | Tuesday 10 March 2026 19:15:33 +0000 (0:00:01.555) 0:00:02.486 ********* 2026-03-10 19:15:33.037004 | controller | ok: [instance] 2026-03-10 19:15:33.067706 | controller | 2026-03-10 19:15:33.067728 | controller | TASK [discover_latest_image : Export single-image facts if an image with name 'default' was discovered] *** 2026-03-10 19:15:33.067736 | controller | Tuesday 10 March 2026 19:15:33 +0000 (0:00:00.030) 0:00:02.517 ********* 2026-03-10 19:15:33.067746 | controller | ok: [instance] 2026-03-10 19:15:33.089695 | controller | 2026-03-10 19:15:33.089714 | controller | TASK [reproducer : Load CI job environment] ************************************ 2026-03-10 19:15:33.089722 | controller | Tuesday 10 March 2026 19:15:33 +0000 (0:00:00.031) 0:00:02.548 ********* 2026-03-10 19:15:33.089730 | controller | skipping: [instance] 2026-03-10 19:15:33.137554 | controller | 2026-03-10 19:15:33.137570 | controller | TASK [Build final libvirt layout] ********************************************** 2026-03-10 19:15:33.137577 | controller | Tuesday 10 March 2026 19:15:33 +0000 (0:00:00.021) 0:00:02.570 ********* 2026-03-10 19:15:33.137585 | controller | 2026-03-10 19:15:33.218827 | controller | TASK [libvirt_manager : Chose right parameter for layout definition] *********** 2026-03-10 19:15:33.218850 | controller | Tuesday 10 March 2026 19:15:33 +0000 (0:00:00.048) 0:00:02.618 ********* 2026-03-10 19:15:33.218862 | controller | ok: [instance] 2026-03-10 19:15:33.281849 | controller | 2026-03-10 19:15:33.281867 | controller | TASK [libvirt_manager : Patch the layout if needed] **************************** 2026-03-10 19:15:33.281874 | controller | Tuesday 10 March 2026 19:15:33 +0000 (0:00:00.081) 0:00:02.699 ********* 2026-03-10 19:15:33.281888 | controller | skipping: [instance] 2026-03-10 19:15:33.307037 | controller | 2026-03-10 19:15:33.307057 | controller | TASK [reproducer : Assert no conflicting parameters were passed] *************** 2026-03-10 19:15:33.307066 | controller | Tuesday 10 March 2026 19:15:33 +0000 (0:00:00.063) 0:00:02.762 ********* 2026-03-10 19:15:33.307076 | controller | ok: [instance] 2026-03-10 19:15:33.307227 | controller | 2026-03-10 19:15:33.307407 | controller | TASK [reproducer : Assert that deprecated cifmw_reproducer_internal_ca parameters was not passed] *** 2026-03-10 19:15:33.307584 | controller | Tuesday 10 March 2026 19:15:33 +0000 (0:00:00.025) 0:00:02.787 ********* 2026-03-10 19:15:33.331202 | controller | ok: [instance] => changed=false 2026-03-10 19:15:33.331399 | controller | msg: All assertions passed 2026-03-10 19:15:33.331563 | controller | 2026-03-10 19:15:33.331711 | controller | TASK [reproducer : Set _use_crc based on actual layout] ************************ 2026-03-10 19:15:33.331861 | controller | Tuesday 10 March 2026 19:15:33 +0000 (0:00:00.024) 0:00:02.812 ********* 2026-03-10 19:15:33.372181 | controller | ok: [instance] 2026-03-10 19:15:33.372371 | controller | 2026-03-10 19:15:33.372944 | controller | TASK [reproducer : Ensure directories are present] ***************************** 2026-03-10 19:15:33.373385 | controller | Tuesday 10 March 2026 19:15:33 +0000 (0:00:00.040) 0:00:02.853 ********* 2026-03-10 19:15:33.812304 | controller | ok: [instance] => (item=artifacts) 2026-03-10 19:15:33.884391 | controller | ok: [instance] => (item=logs) 2026-03-10 19:15:33.884426 | controller | 2026-03-10 19:15:33.884446 | controller | TASK [Bootstrap libvirt if needed] ********************************************* 2026-03-10 19:15:33.884454 | controller | Tuesday 10 March 2026 19:15:33 +0000 (0:00:00.439) 0:00:03.292 ********* 2026-03-10 19:15:33.884465 | controller | 2026-03-10 19:15:33.884495 | controller | TASK [libvirt_manager : Ensure output directory exists] ************************ 2026-03-10 19:15:33.884578 | controller | Tuesday 10 March 2026 19:15:33 +0000 (0:00:00.072) 0:00:03.365 ********* 2026-03-10 19:15:34.062333 | controller | ok: [instance] 2026-03-10 19:15:34.327133 | controller | 2026-03-10 19:15:34.327163 | controller | TASK [libvirt_manager : Get vmx/svm extensions from /proc/cpuinfo] ************* 2026-03-10 19:15:34.327171 | controller | Tuesday 10 March 2026 19:15:34 +0000 (0:00:00.177) 0:00:03.542 ********* 2026-03-10 19:15:34.327181 | controller | changed: [instance] 2026-03-10 19:15:34.596668 | controller | 2026-03-10 19:15:34.596694 | controller | TASK [libvirt_manager : Check if KVM is enabled] ******************************* 2026-03-10 19:15:34.596704 | controller | Tuesday 10 March 2026 19:15:34 +0000 (0:00:00.264) 0:00:03.807 ********* 2026-03-10 19:15:34.596715 | controller | ok: [instance] 2026-03-10 19:15:34.626867 | controller | 2026-03-10 19:15:34.626889 | controller | TASK [libvirt_manager : Fail if KVM not enabled] ******************************* 2026-03-10 19:15:34.626898 | controller | Tuesday 10 March 2026 19:15:34 +0000 (0:00:00.269) 0:00:04.076 ********* 2026-03-10 19:15:34.626916 | controller | skipping: [instance] 2026-03-10 19:15:34.645146 | controller | 2026-03-10 19:15:34.645167 | controller | TASK [libvirt_manager : Check if VMX virtualization] *************************** 2026-03-10 19:15:34.645176 | controller | Tuesday 10 March 2026 19:15:34 +0000 (0:00:00.030) 0:00:04.107 ********* 2026-03-10 19:15:34.645187 | controller | skipping: [instance] 2026-03-10 19:15:34.666085 | controller | 2026-03-10 19:15:34.666107 | controller | TASK [libvirt_manager : Check if SVM virtualization] *************************** 2026-03-10 19:15:34.666116 | controller | Tuesday 10 March 2026 19:15:34 +0000 (0:00:00.018) 0:00:04.125 ********* 2026-03-10 19:15:34.666127 | controller | ok: [instance] 2026-03-10 19:15:34.693542 | controller | 2026-03-10 19:15:34.693590 | controller | TASK [libvirt_manager : Enable VMX virtualization] ***************************** 2026-03-10 19:15:34.693604 | controller | Tuesday 10 March 2026 19:15:34 +0000 (0:00:00.020) 0:00:04.146 ********* 2026-03-10 19:15:34.693620 | controller | skipping: [instance] 2026-03-10 19:15:48.571900 | controller | 2026-03-10 19:15:48.571931 | controller | TASK [libvirt_manager : Install packages required for using KVM] *************** 2026-03-10 19:15:48.571939 | controller | Tuesday 10 March 2026 19:15:34 +0000 (0:00:00.026) 0:00:04.173 ********* 2026-03-10 19:15:48.571950 | controller | changed: [instance] 2026-03-10 19:15:48.782566 | controller | 2026-03-10 19:15:48.782603 | controller | TASK [libvirt_manager : Create rules.d polkit folder] ************************** 2026-03-10 19:15:48.782612 | controller | Tuesday 10 March 2026 19:15:48 +0000 (0:00:13.878) 0:00:18.052 ********* 2026-03-10 19:15:48.782622 | controller | changed: [instance] 2026-03-10 19:15:48.782640 | controller | 2026-03-10 19:15:48.782791 | controller | TASK [libvirt_manager : Enable UNIX socket PolicyKit auth] ********************* 2026-03-10 19:15:48.782815 | controller | Tuesday 10 March 2026 19:15:48 +0000 (0:00:00.210) 0:00:18.263 ********* 2026-03-10 19:15:49.277219 | controller | changed: [instance] 2026-03-10 19:15:49.837341 | controller | 2026-03-10 19:15:49.837374 | controller | TASK [libvirt_manager : Restart service polkit service] ************************ 2026-03-10 19:15:49.837382 | controller | Tuesday 10 March 2026 19:15:49 +0000 (0:00:00.494) 0:00:18.757 ********* 2026-03-10 19:15:49.837393 | controller | changed: [instance] 2026-03-10 19:15:49.837421 | controller | 2026-03-10 19:15:49.837429 | controller | TASK [libvirt_manager : Remove directory for service override] ***************** 2026-03-10 19:15:49.837436 | controller | Tuesday 10 March 2026 19:15:49 +0000 (0:00:00.560) 0:00:19.317 ********* 2026-03-10 19:15:50.028684 | controller | ok: [instance] 2026-03-10 19:15:50.653693 | controller | 2026-03-10 19:15:50.653722 | controller | TASK [libvirt_manager : Reload systemctl and start/enable virtproxyd.service] *** 2026-03-10 19:15:50.653730 | controller | Tuesday 10 March 2026 19:15:50 +0000 (0:00:00.191) 0:00:19.508 ********* 2026-03-10 19:15:50.653740 | controller | ok: [instance] 2026-03-10 19:15:51.402155 | controller | 2026-03-10 19:15:51.402184 | controller | TASK [libvirt_manager : Ensure firewalld service is up] ************************ 2026-03-10 19:15:51.402192 | controller | Tuesday 10 March 2026 19:15:50 +0000 (0:00:00.625) 0:00:20.133 ********* 2026-03-10 19:15:51.402202 | controller | changed: [instance] 2026-03-10 19:15:51.483425 | controller | 2026-03-10 19:15:51.483449 | controller | TASK [libvirt_manager : Ensure libvirt modular driver daemons are started.] **** 2026-03-10 19:15:51.483457 | controller | Tuesday 10 March 2026 19:15:51 +0000 (0:00:00.748) 0:00:20.882 ********* 2026-03-10 19:15:51.483467 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=qemu) 2026-03-10 19:15:51.795339 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=interface) 2026-03-10 19:15:51.795374 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=network) 2026-03-10 19:15:51.795381 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nodedev) 2026-03-10 19:15:51.795387 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nwfilter) 2026-03-10 19:15:51.795392 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=secret) 2026-03-10 19:15:51.795397 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=storage) 2026-03-10 19:15:51.795410 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=proxy) 2026-03-10 19:15:51.795416 | controller | 2026-03-10 19:15:51.795422 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for qemu.] ********* 2026-03-10 19:15:51.795427 | controller | Tuesday 10 March 2026 19:15:51 +0000 (0:00:00.080) 0:00:20.963 ********* 2026-03-10 19:15:51.795437 | controller | ok: [instance] 2026-03-10 19:15:51.795461 | controller | 2026-03-10 19:15:51.795655 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for qemu.] ********** 2026-03-10 19:15:52.102320 | controller | Tuesday 10 March 2026 19:15:51 +0000 (0:00:00.312) 0:00:21.276 ********* 2026-03-10 19:15:52.102371 | controller | ok: [instance] 2026-03-10 19:15:52.406119 | controller | 2026-03-10 19:15:52.406152 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for qemu.] ************** 2026-03-10 19:15:52.406167 | controller | Tuesday 10 March 2026 19:15:52 +0000 (0:00:00.306) 0:00:21.582 ********* 2026-03-10 19:15:52.406177 | controller | ok: [instance] 2026-03-10 19:15:52.708331 | controller | 2026-03-10 19:15:52.708364 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for interface.] **** 2026-03-10 19:15:52.708372 | controller | Tuesday 10 March 2026 19:15:52 +0000 (0:00:00.303) 0:00:21.886 ********* 2026-03-10 19:15:52.708383 | controller | ok: [instance] 2026-03-10 19:15:53.012330 | controller | 2026-03-10 19:15:53.012361 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for interface.] ***** 2026-03-10 19:15:53.012384 | controller | Tuesday 10 March 2026 19:15:52 +0000 (0:00:00.302) 0:00:22.188 ********* 2026-03-10 19:15:53.012395 | controller | ok: [instance] 2026-03-10 19:15:53.012431 | controller | 2026-03-10 19:15:53.012659 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for interface.] ********* 2026-03-10 19:15:53.324234 | controller | Tuesday 10 March 2026 19:15:53 +0000 (0:00:00.304) 0:00:22.492 ********* 2026-03-10 19:15:53.324286 | controller | ok: [instance] 2026-03-10 19:15:53.625821 | controller | 2026-03-10 19:15:53.625845 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for network.] ****** 2026-03-10 19:15:53.625854 | controller | Tuesday 10 March 2026 19:15:53 +0000 (0:00:00.311) 0:00:22.804 ********* 2026-03-10 19:15:53.625864 | controller | ok: [instance] 2026-03-10 19:15:53.934327 | controller | 2026-03-10 19:15:53.934359 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for network.] ******* 2026-03-10 19:15:53.934368 | controller | Tuesday 10 March 2026 19:15:53 +0000 (0:00:00.301) 0:00:23.106 ********* 2026-03-10 19:15:53.934378 | controller | ok: [instance] 2026-03-10 19:15:53.934421 | controller | 2026-03-10 19:15:53.934429 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for network.] *********** 2026-03-10 19:15:53.934436 | controller | Tuesday 10 March 2026 19:15:53 +0000 (0:00:00.308) 0:00:23.414 ********* 2026-03-10 19:15:54.286132 | controller | ok: [instance] 2026-03-10 19:15:54.613351 | controller | 2026-03-10 19:15:54.613392 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nodedev.] ****** 2026-03-10 19:15:54.613416 | controller | Tuesday 10 March 2026 19:15:54 +0000 (0:00:00.351) 0:00:23.766 ********* 2026-03-10 19:15:54.613430 | controller | ok: [instance] 2026-03-10 19:15:54.613477 | controller | 2026-03-10 19:15:54.613623 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nodedev.] ******* 2026-03-10 19:15:54.613659 | controller | Tuesday 10 March 2026 19:15:54 +0000 (0:00:00.327) 0:00:24.093 ********* 2026-03-10 19:15:54.926742 | controller | ok: [instance] 2026-03-10 19:15:55.230966 | controller | 2026-03-10 19:15:55.230995 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nodedev.] *********** 2026-03-10 19:15:55.231004 | controller | Tuesday 10 March 2026 19:15:54 +0000 (0:00:00.313) 0:00:24.407 ********* 2026-03-10 19:15:55.231014 | controller | ok: [instance] 2026-03-10 19:15:55.532112 | controller | 2026-03-10 19:15:55.532141 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nwfilter.] ***** 2026-03-10 19:15:55.532149 | controller | Tuesday 10 March 2026 19:15:55 +0000 (0:00:00.304) 0:00:24.711 ********* 2026-03-10 19:15:55.532159 | controller | ok: [instance] 2026-03-10 19:15:55.840959 | controller | 2026-03-10 19:15:55.840994 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nwfilter.] ****** 2026-03-10 19:15:55.841004 | controller | Tuesday 10 March 2026 19:15:55 +0000 (0:00:00.301) 0:00:25.012 ********* 2026-03-10 19:15:55.841016 | controller | ok: [instance] 2026-03-10 19:15:56.141410 | controller | 2026-03-10 19:15:56.141445 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nwfilter.] ********** 2026-03-10 19:15:56.141455 | controller | Tuesday 10 March 2026 19:15:55 +0000 (0:00:00.308) 0:00:25.321 ********* 2026-03-10 19:15:56.141468 | controller | ok: [instance] 2026-03-10 19:15:56.442032 | controller | 2026-03-10 19:15:56.442066 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for secret.] ******* 2026-03-10 19:15:56.442075 | controller | Tuesday 10 March 2026 19:15:56 +0000 (0:00:00.300) 0:00:25.621 ********* 2026-03-10 19:15:56.442087 | controller | ok: [instance] 2026-03-10 19:15:56.741071 | controller | 2026-03-10 19:15:56.741100 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for secret.] ******** 2026-03-10 19:15:56.741114 | controller | Tuesday 10 March 2026 19:15:56 +0000 (0:00:00.300) 0:00:25.922 ********* 2026-03-10 19:15:56.741124 | controller | ok: [instance] 2026-03-10 19:15:57.041369 | controller | 2026-03-10 19:15:57.041415 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for secret.] ************ 2026-03-10 19:15:57.041425 | controller | Tuesday 10 March 2026 19:15:56 +0000 (0:00:00.299) 0:00:26.221 ********* 2026-03-10 19:15:57.041437 | controller | ok: [instance] 2026-03-10 19:15:57.041629 | controller | 2026-03-10 19:15:57.041651 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for storage.] ****** 2026-03-10 19:15:57.352430 | controller | Tuesday 10 March 2026 19:15:57 +0000 (0:00:00.300) 0:00:26.521 ********* 2026-03-10 19:15:57.352484 | controller | ok: [instance] 2026-03-10 19:15:57.658057 | controller | 2026-03-10 19:15:57.658092 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for storage.] ******* 2026-03-10 19:15:57.658100 | controller | Tuesday 10 March 2026 19:15:57 +0000 (0:00:00.311) 0:00:26.832 ********* 2026-03-10 19:15:57.658118 | controller | ok: [instance] 2026-03-10 19:15:57.962820 | controller | 2026-03-10 19:15:57.962852 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for storage.] *********** 2026-03-10 19:15:57.962861 | controller | Tuesday 10 March 2026 19:15:57 +0000 (0:00:00.305) 0:00:27.138 ********* 2026-03-10 19:15:57.962870 | controller | ok: [instance] 2026-03-10 19:15:58.264679 | controller | 2026-03-10 19:15:58.264708 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for proxy.] ******** 2026-03-10 19:15:58.264717 | controller | Tuesday 10 March 2026 19:15:57 +0000 (0:00:00.304) 0:00:27.443 ********* 2026-03-10 19:15:58.264729 | controller | ok: [instance] 2026-03-10 19:15:58.573387 | controller | 2026-03-10 19:15:58.573435 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for proxy.] ********* 2026-03-10 19:15:58.573445 | controller | Tuesday 10 March 2026 19:15:58 +0000 (0:00:00.301) 0:00:27.745 ********* 2026-03-10 19:15:58.573458 | controller | ok: [instance] 2026-03-10 19:15:58.573487 | controller | 2026-03-10 19:15:58.573497 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for proxy.] ************* 2026-03-10 19:15:58.573598 | controller | Tuesday 10 March 2026 19:15:58 +0000 (0:00:00.308) 0:00:28.053 ********* 2026-03-10 19:15:58.876795 | controller | ok: [instance] 2026-03-10 19:15:58.895351 | controller | 2026-03-10 19:15:58.895370 | controller | TASK [libvirt_manager : Manage service for older releases] ********************* 2026-03-10 19:15:58.895378 | controller | Tuesday 10 March 2026 19:15:58 +0000 (0:00:00.303) 0:00:28.357 ********* 2026-03-10 19:15:58.895387 | controller | skipping: [instance] 2026-03-10 19:15:58.895419 | controller | 2026-03-10 19:15:58.895428 | controller | TASK [libvirt_manager : Get libvirt group users] ******************************* 2026-03-10 19:15:58.895538 | controller | Tuesday 10 March 2026 19:15:58 +0000 (0:00:00.018) 0:00:28.375 ********* 2026-03-10 19:15:59.170777 | controller | ok: [instance] 2026-03-10 19:15:59.533850 | controller | 2026-03-10 19:15:59.533878 | controller | TASK [libvirt_manager : Add user if not exists in libvirt group] *************** 2026-03-10 19:15:59.533886 | controller | Tuesday 10 March 2026 19:15:59 +0000 (0:00:00.275) 0:00:28.651 ********* 2026-03-10 19:15:59.533896 | controller | ok: [instance] 2026-03-10 19:15:59.829403 | controller | 2026-03-10 19:15:59.829431 | controller | TASK [libvirt_manager : Allow QEMU on home directory for the storage access] *** 2026-03-10 19:15:59.829440 | controller | Tuesday 10 March 2026 19:15:59 +0000 (0:00:00.363) 0:00:29.014 ********* 2026-03-10 19:15:59.829451 | controller | changed: [instance] 2026-03-10 19:16:00.138594 | controller | 2026-03-10 19:16:00.138623 | controller | TASK [libvirt_manager : Inject system connection parameters in bashrc] ********* 2026-03-10 19:16:00.138634 | controller | Tuesday 10 March 2026 19:15:59 +0000 (0:00:00.295) 0:00:29.309 ********* 2026-03-10 19:16:00.138647 | controller | [WARNING]: Reset is not implemented for this connection 2026-03-10 19:16:00.148401 | controller | changed: [instance] 2026-03-10 19:16:00.148479 | controller | 2026-03-10 19:16:00.148492 | controller | TASK [libvirt_manager : Ensure we reload user env] ***************************** 2026-03-10 19:16:00.148777 | controller | Tuesday 10 March 2026 19:16:00 +0000 (0:00:00.303) 0:00:29.613 ********* 2026-03-10 19:16:00.148799 | controller | 2026-03-10 19:16:00.218209 | controller | TASK [reproducer : Deploy networks in libvirt] ********************************* 2026-03-10 19:16:00.218230 | controller | Tuesday 10 March 2026 19:16:00 +0000 (0:00:00.015) 0:00:29.628 ********* 2026-03-10 19:16:00.218241 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/tasks/prepare_networking.yml for instance 2026-03-10 19:16:00.256440 | controller | 2026-03-10 19:16:00.256481 | controller | TASK [reproducer : Ensure networks are consistent] ***************************** 2026-03-10 19:16:00.256491 | controller | Tuesday 10 March 2026 19:16:00 +0000 (0:00:00.069) 0:00:29.698 ********* 2026-03-10 19:16:00.256504 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/tasks/network_assertions.yml for instance 2026-03-10 19:16:00.339979 | controller | 2026-03-10 19:16:00.340011 | controller | TASK [reproducer : Ensure all VM types have defined nets parameter] ************ 2026-03-10 19:16:00.340019 | controller | Tuesday 10 March 2026 19:16:00 +0000 (0:00:00.038) 0:00:29.736 ********* 2026-03-10 19:16:00.340029 | controller | ok: [instance] => (item=compute) 2026-03-10 19:16:00.379422 | controller | ok: [instance] => (item=controller) 2026-03-10 19:16:00.379466 | controller | ok: [instance] => (item=crc) 2026-03-10 19:16:00.379477 | controller | 2026-03-10 19:16:00.379486 | controller | TASK [reproducer : Ensure all standard attached networks are defined] ********** 2026-03-10 19:16:00.379494 | controller | Tuesday 10 March 2026 19:16:00 +0000 (0:00:00.083) 0:00:29.820 ********* 2026-03-10 19:16:00.379508 | controller | ok: [instance] 2026-03-10 19:16:00.420879 | controller | 2026-03-10 19:16:00.420910 | controller | TASK [reproducer : Ensure we don't have extra network defined] ***************** 2026-03-10 19:16:00.420919 | controller | Tuesday 10 March 2026 19:16:00 +0000 (0:00:00.039) 0:00:29.859 ********* 2026-03-10 19:16:00.420930 | controller | ok: [instance] => changed=false 2026-03-10 19:16:00.460748 | controller | msg: All assertions passed 2026-03-10 19:16:00.460777 | controller | 2026-03-10 19:16:00.460789 | controller | TASK [Ensure we get latest OCP config] ***************************************** 2026-03-10 19:16:00.460808 | controller | Tuesday 10 March 2026 19:16:00 +0000 (0:00:00.041) 0:00:29.901 ********* 2026-03-10 19:16:00.460822 | controller | 2026-03-10 19:16:00.461064 | controller | TASK [devscripts : Gather the configurations to be passed to dev-scripts.] ***** 2026-03-10 19:16:00.461279 | controller | Tuesday 10 March 2026 19:16:00 +0000 (0:00:00.040) 0:00:29.941 ********* 2026-03-10 19:16:00.536965 | controller | ok: [instance] => (item={'working_dir': '/home/dev-scripts', 'assets_extra_folder': '/home/dev-scripts/assets', 'openshift_release_type': 'ga', 'openshift_version': '4.16.54', 'cluster_name': 'ocp', 'base_domain': 'openstack.lab', 'ntp_servers': 'clock.corp.redhat.com', 'provisioning_network_profile': 'Managed', 'provisioning_network': '172.22.0.0/24', 'cluster_subnet_v4': '192.168.16.0/20', 'cluster_host_prefix_v4': '23', 'service_subnet_v4': '172.30.0.0/16', 'external_subnet_v4': '192.168.111.0/24', 'num_masters': 3, 'num_workers': 0, 'ip_stack': 'v4', 'network_type': 'OVNKubernetes'}) 2026-03-10 19:16:00.537158 | controller | ok: [instance] => (item={}) 2026-03-10 19:16:00.537340 | controller | 2026-03-10 19:16:00.537517 | controller | TASK [devscripts : Replace OCP version if "stable-" alias used] **************** 2026-03-10 19:16:00.537649 | controller | Tuesday 10 March 2026 19:16:00 +0000 (0:00:00.076) 0:00:30.017 ********* 2026-03-10 19:16:00.554049 | controller | skipping: [instance] 2026-03-10 19:16:00.554215 | controller | 2026-03-10 19:16:00.554407 | controller | TASK [devscripts : Ensure output directory exists] ***************************** 2026-03-10 19:16:00.554543 | controller | Tuesday 10 March 2026 19:16:00 +0000 (0:00:00.017) 0:00:30.035 ********* 2026-03-10 19:16:00.760459 | controller | ok: [instance] 2026-03-10 19:16:00.760678 | controller | 2026-03-10 19:16:00.760700 | controller | TASK [devscripts : Output built config for easier debugging] ******************* 2026-03-10 19:16:01.101358 | controller | Tuesday 10 March 2026 19:16:00 +0000 (0:00:00.205) 0:00:30.240 ********* 2026-03-10 19:16:01.101411 | controller | changed: [instance] 2026-03-10 19:16:01.837441 | controller | 2026-03-10 19:16:01.837487 | controller | TASK [ci_network : Remove the local dns configuration] ************************* 2026-03-10 19:16:01.837511 | controller | Tuesday 10 March 2026 19:16:01 +0000 (0:00:00.340) 0:00:30.581 ********* 2026-03-10 19:16:01.837530 | controller | ok: [instance] => (item=/etc/NetworkManager/conf.d/00-use-dnsmasq.conf) 2026-03-10 19:16:01.837571 | controller | ok: [instance] => (item=/etc/NetworkManager/dnsmasq.d/98-cifmw-static-leases.conf) 2026-03-10 19:16:01.837582 | controller | ok: [instance] => (item=/etc/NetworkManager/dnsmasq.d/99-cifmw-dns-forwarders.conf) 2026-03-10 19:16:01.837592 | controller | ok: [instance] => (item=/etc/NetworkManager/dnsmasq.d/97-cifmw-local-domain.conf) 2026-03-10 19:16:01.837604 | controller | 2026-03-10 19:16:01.837693 | controller | TASK [ci_network : Reload the NetworkManager to pick the changes] ************** 2026-03-10 19:16:01.837729 | controller | Tuesday 10 March 2026 19:16:01 +0000 (0:00:00.736) 0:00:31.317 ********* 2026-03-10 19:16:01.862755 | controller | skipping: [instance] 2026-03-10 19:16:02.212988 | controller | 2026-03-10 19:16:02.213019 | controller | TASK [reproducer : Ensure NetworkManager is configured to use dnsmasq] ********* 2026-03-10 19:16:02.213027 | controller | Tuesday 10 March 2026 19:16:01 +0000 (0:00:00.025) 0:00:31.343 ********* 2026-03-10 19:16:02.213037 | controller | changed: [instance] 2026-03-10 19:16:02.628166 | controller | 2026-03-10 19:16:02.628204 | controller | TASK [reproducer : Flush handlers for NetworkManager restart] ****************** 2026-03-10 19:16:02.628213 | controller | Tuesday 10 March 2026 19:16:02 +0000 (0:00:00.341) 0:00:31.684 ********* 2026-03-10 19:16:02.628219 | controller | 2026-03-10 19:16:02.628224 | controller | RUNNING HANDLER [reproducer : Restart NetworkManager] ************************** 2026-03-10 19:16:02.628230 | controller | Tuesday 10 March 2026 19:16:02 +0000 (0:00:00.008) 0:00:31.693 ********* 2026-03-10 19:16:02.628240 | controller | changed: [instance] 2026-03-10 19:16:02.703295 | controller | 2026-03-10 19:16:02.703332 | controller | TASK [Ensure no default networks exists] *************************************** 2026-03-10 19:16:02.703342 | controller | Tuesday 10 March 2026 19:16:02 +0000 (0:00:00.415) 0:00:32.108 ********* 2026-03-10 19:16:02.703355 | controller | 2026-03-10 19:16:03.826352 | controller | TASK [libvirt_manager : Deactivate crc] **************************************** 2026-03-10 19:16:03.826394 | controller | Tuesday 10 March 2026 19:16:02 +0000 (0:00:00.075) 0:00:32.183 ********* 2026-03-10 19:16:03.826407 | controller | ok: [instance] 2026-03-10 19:16:03.826433 | controller | 2026-03-10 19:16:03.826442 | controller | TASK [libvirt_manager : Removing crc] ****************************************** 2026-03-10 19:16:03.826585 | controller | Tuesday 10 March 2026 19:16:03 +0000 (0:00:01.123) 0:00:33.306 ********* 2026-03-10 19:16:04.045332 | controller | ok: [instance] 2026-03-10 19:16:04.589459 | controller | 2026-03-10 19:16:04.589494 | controller | TASK [libvirt_manager : Deactivate default] ************************************ 2026-03-10 19:16:04.589504 | controller | Tuesday 10 March 2026 19:16:04 +0000 (0:00:00.218) 0:00:33.525 ********* 2026-03-10 19:16:04.589516 | controller | ok: [instance] 2026-03-10 19:16:04.802178 | controller | 2026-03-10 19:16:04.802212 | controller | TASK [libvirt_manager : Removing default] ************************************** 2026-03-10 19:16:04.802224 | controller | Tuesday 10 March 2026 19:16:04 +0000 (0:00:00.544) 0:00:34.069 ********* 2026-03-10 19:16:04.802234 | controller | ok: [instance] 2026-03-10 19:16:06.000013 | controller | 2026-03-10 19:16:06.000041 | controller | TASK [reproducer : Ensure firewalld is installed] ****************************** 2026-03-10 19:16:06.000050 | controller | Tuesday 10 March 2026 19:16:04 +0000 (0:00:00.212) 0:00:34.282 ********* 2026-03-10 19:16:06.000060 | controller | ok: [instance] 2026-03-10 19:16:06.298914 | controller | 2026-03-10 19:16:06.298946 | controller | TASK [reproducer : Ensure firewalld service is enabled and started] ************ 2026-03-10 19:16:06.298954 | controller | Tuesday 10 March 2026 19:16:05 +0000 (0:00:01.197) 0:00:35.480 ********* 2026-03-10 19:16:06.298964 | controller | ok: [instance] 2026-03-10 19:16:06.600796 | controller | 2026-03-10 19:16:06.600825 | controller | TASK [reproducer : Ensure br_netfilter module is loaded] *********************** 2026-03-10 19:16:06.600833 | controller | Tuesday 10 March 2026 19:16:06 +0000 (0:00:00.298) 0:00:35.779 ********* 2026-03-10 19:16:06.600843 | controller | changed: [instance] 2026-03-10 19:16:06.896592 | controller | 2026-03-10 19:16:06.896622 | controller | TASK [reproducer : Ensure IP forwarding is enabled] **************************** 2026-03-10 19:16:06.896635 | controller | Tuesday 10 March 2026 19:16:06 +0000 (0:00:00.301) 0:00:36.081 ********* 2026-03-10 19:16:06.896645 | controller | changed: [instance] 2026-03-10 19:16:07.453364 | controller | 2026-03-10 19:16:07.453414 | controller | TASK [reproducer : Ensure the required parameters are loaded] ****************** 2026-03-10 19:16:07.453425 | controller | Tuesday 10 March 2026 19:16:06 +0000 (0:00:00.295) 0:00:36.377 ********* 2026-03-10 19:16:07.453439 | controller | changed: [instance] => (item=arptables) 2026-03-10 19:16:07.453460 | controller | changed: [instance] => (item=iptables) 2026-03-10 19:16:07.453468 | controller | changed: [instance] => (item=ip6tables) 2026-03-10 19:16:07.453474 | controller | 2026-03-10 19:16:07.453481 | controller | TASK [reproducer : Ensure firewall service is enabled and started] ************* 2026-03-10 19:16:07.453490 | controller | Tuesday 10 March 2026 19:16:07 +0000 (0:00:00.556) 0:00:36.933 ********* 2026-03-10 19:16:07.773008 | controller | ok: [instance] 2026-03-10 19:16:07.880373 | controller | 2026-03-10 19:16:07.880423 | controller | TASK [reproducer : Set cifmw_ci_nmstate_instance_config if needed] ************* 2026-03-10 19:16:07.880433 | controller | Tuesday 10 March 2026 19:16:07 +0000 (0:00:00.319) 0:00:37.253 ********* 2026-03-10 19:16:07.880446 | controller | ok: [instance] 2026-03-10 19:16:07.921089 | controller | 2026-03-10 19:16:07.921134 | controller | TASK [Ensure the required network configurations are applied] ****************** 2026-03-10 19:16:07.921147 | controller | Tuesday 10 March 2026 19:16:07 +0000 (0:00:00.107) 0:00:37.360 ********* 2026-03-10 19:16:07.921163 | controller | 2026-03-10 19:16:07.942241 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] *** 2026-03-10 19:16:07.942281 | controller | Tuesday 10 March 2026 19:16:07 +0000 (0:00:00.040) 0:00:37.401 ********* 2026-03-10 19:16:07.942295 | controller | skipping: [instance] 2026-03-10 19:16:07.964470 | controller | 2026-03-10 19:16:07.964507 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ****** 2026-03-10 19:16:07.964517 | controller | Tuesday 10 March 2026 19:16:07 +0000 (0:00:00.021) 0:00:37.422 ********* 2026-03-10 19:16:07.964529 | controller | skipping: [instance] 2026-03-10 19:16:07.986014 | controller | 2026-03-10 19:16:07.986046 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************ 2026-03-10 19:16:07.986056 | controller | Tuesday 10 March 2026 19:16:07 +0000 (0:00:00.021) 0:00:37.444 ********* 2026-03-10 19:16:07.986068 | controller | skipping: [instance] 2026-03-10 19:16:08.013005 | controller | 2026-03-10 19:16:08.013028 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ****** 2026-03-10 19:16:08.013037 | controller | Tuesday 10 March 2026 19:16:07 +0000 (0:00:00.021) 0:00:37.466 ********* 2026-03-10 19:16:08.013048 | controller | skipping: [instance] 2026-03-10 19:16:08.079758 | controller | 2026-03-10 19:16:08.079795 | controller | TASK [ci_nmstate : Initialize NMstates for each instance] ********************** 2026-03-10 19:16:08.079805 | controller | Tuesday 10 March 2026 19:16:08 +0000 (0:00:00.027) 0:00:37.493 ********* 2026-03-10 19:16:08.079818 | controller | ok: [instance] 2026-03-10 19:16:08.079985 | controller | 2026-03-10 19:16:08.080151 | controller | TASK [ci_nmstate : Build NMstates from the Networking Environment Definition] *** 2026-03-10 19:16:08.080344 | controller | Tuesday 10 March 2026 19:16:08 +0000 (0:00:00.066) 0:00:37.560 ********* 2026-03-10 19:16:08.112629 | controller | skipping: [instance] 2026-03-10 19:16:08.112780 | controller | 2026-03-10 19:16:08.112916 | controller | TASK [ci_nmstate : Get k8s nodes] ********************************************** 2026-03-10 19:16:08.113049 | controller | Tuesday 10 March 2026 19:16:08 +0000 (0:00:00.032) 0:00:37.593 ********* 2026-03-10 19:16:08.134829 | controller | skipping: [instance] 2026-03-10 19:16:08.134993 | controller | 2026-03-10 19:16:08.135146 | controller | TASK [ci_nmstate : Set the hosts that will provision nmstate using k8s] ******** 2026-03-10 19:16:08.135320 | controller | Tuesday 10 March 2026 19:16:08 +0000 (0:00:00.022) 0:00:37.615 ********* 2026-03-10 19:16:08.191031 | controller | ok: [instance] 2026-03-10 19:16:08.191196 | controller | 2026-03-10 19:16:08.191388 | controller | TASK [ci_nmstate : Provision k8s workers with nmstate] ************************* 2026-03-10 19:16:08.191548 | controller | Tuesday 10 March 2026 19:16:08 +0000 (0:00:00.056) 0:00:37.671 ********* 2026-03-10 19:16:08.212805 | controller | skipping: [instance] 2026-03-10 19:16:08.212967 | controller | 2026-03-10 19:16:08.213118 | controller | TASK [ci_nmstate : Provision unmanaged nodes with nmstate] ********************* 2026-03-10 19:16:08.213295 | controller | Tuesday 10 March 2026 19:16:08 +0000 (0:00:00.021) 0:00:37.693 ********* 2026-03-10 19:16:08.258184 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_nmstate/tasks/nmstate_unmanaged_provision_node.yml for instance => (item=localhost) 2026-03-10 19:16:08.258484 | controller | 2026-03-10 19:16:08.258731 | controller | TASK [ci_nmstate : Ensure we have needed facts] ******************************** 2026-03-10 19:16:08.258935 | controller | Tuesday 10 March 2026 19:16:08 +0000 (0:00:00.045) 0:00:37.738 ********* 2026-03-10 19:16:08.568055 | controller | ok: [instance -> localhost] 2026-03-10 19:16:14.381414 | controller | 2026-03-10 19:16:14.381466 | controller | TASK [ci_nmstate : Install required packages on localhost] ********************* 2026-03-10 19:16:14.381479 | controller | Tuesday 10 March 2026 19:16:08 +0000 (0:00:00.309) 0:00:38.048 ********* 2026-03-10 19:16:14.381497 | controller | changed: [instance -> localhost] 2026-03-10 19:16:14.558783 | controller | 2026-03-10 19:16:14.558815 | controller | TASK [ci_nmstate : Create the target NMstate dump directory] ******************* 2026-03-10 19:16:14.558823 | controller | Tuesday 10 March 2026 19:16:14 +0000 (0:00:05.813) 0:00:43.861 ********* 2026-03-10 19:16:14.558840 | controller | changed: [instance] 2026-03-10 19:16:14.876099 | controller | 2026-03-10 19:16:14.876132 | controller | TASK [ci_nmstate : Save nmstate state for localhost] *************************** 2026-03-10 19:16:14.876140 | controller | Tuesday 10 March 2026 19:16:14 +0000 (0:00:00.177) 0:00:44.039 ********* 2026-03-10 19:16:14.876150 | controller | changed: [instance] 2026-03-10 19:16:16.457765 | controller | 2026-03-10 19:16:16.457796 | controller | TASK [ci_nmstate : Apply the desidered state on localhost] ********************* 2026-03-10 19:16:16.457805 | controller | Tuesday 10 March 2026 19:16:14 +0000 (0:00:00.317) 0:00:44.356 ********* 2026-03-10 19:16:16.457815 | controller | changed: [instance -> localhost] 2026-03-10 19:16:16.786290 | controller | 2026-03-10 19:16:16.786323 | controller | TASK [ci_nmstate : Save nmstate debugging data for localhost] ****************** 2026-03-10 19:16:16.786332 | controller | Tuesday 10 March 2026 19:16:16 +0000 (0:00:01.581) 0:00:45.937 ********* 2026-03-10 19:16:16.786342 | controller | changed: [instance] 2026-03-10 19:16:16.816273 | controller | 2026-03-10 19:16:16.816292 | controller | TASK [reproducer : Select right domain] **************************************** 2026-03-10 19:16:16.816299 | controller | Tuesday 10 March 2026 19:16:16 +0000 (0:00:00.328) 0:00:46.266 ********* 2026-03-10 19:16:16.816307 | controller | ok: [instance] 2026-03-10 19:16:16.930440 | controller | 2026-03-10 19:16:16.930461 | controller | TASK [Create the virtual networks] ********************************************* 2026-03-10 19:16:16.930470 | controller | Tuesday 10 March 2026 19:16:16 +0000 (0:00:00.030) 0:00:46.296 ********* 2026-03-10 19:16:16.930480 | controller | 2026-03-10 19:16:16.949409 | controller | TASK [libvirt_manager : Ensure fact is existing, and empty] ******************** 2026-03-10 19:16:16.949433 | controller | Tuesday 10 March 2026 19:16:16 +0000 (0:00:00.114) 0:00:46.411 ********* 2026-03-10 19:16:16.949455 | controller | ok: [instance] 2026-03-10 19:16:16.949666 | controller | 2026-03-10 19:16:16.949686 | controller | TASK [libvirt_manager : Check if we get an existing interfaces-info.yml] ******* 2026-03-10 19:16:17.133539 | controller | Tuesday 10 March 2026 19:16:16 +0000 (0:00:00.019) 0:00:46.430 ********* 2026-03-10 19:16:17.133577 | controller | ok: [instance] 2026-03-10 19:16:17.163363 | controller | 2026-03-10 19:16:17.163384 | controller | TASK [libvirt_manager : Load file if exists] *********************************** 2026-03-10 19:16:17.163392 | controller | Tuesday 10 March 2026 19:16:17 +0000 (0:00:00.183) 0:00:46.613 ********* 2026-03-10 19:16:17.163401 | controller | skipping: [instance] 2026-03-10 19:16:17.190153 | controller | 2026-03-10 19:16:17.190175 | controller | TASK [libvirt_manager : Update fact with existing content] ********************* 2026-03-10 19:16:17.190183 | controller | Tuesday 10 March 2026 19:16:17 +0000 (0:00:00.030) 0:00:46.644 ********* 2026-03-10 19:16:17.190193 | controller | skipping: [instance] 2026-03-10 19:16:17.240299 | controller | 2026-03-10 19:16:17.240324 | controller | TASK [libvirt_manager : Generate VM list] ************************************** 2026-03-10 19:16:17.240341 | controller | Tuesday 10 March 2026 19:16:17 +0000 (0:00:00.026) 0:00:46.670 ********* 2026-03-10 19:16:17.240363 | controller | ok: [instance] 2026-03-10 19:16:17.357674 | controller | 2026-03-10 19:16:17.357697 | controller | TASK [libvirt_manager : Generate/update mapping all nodes] ********************* 2026-03-10 19:16:17.357705 | controller | Tuesday 10 March 2026 19:16:17 +0000 (0:00:00.049) 0:00:46.720 ********* 2026-03-10 19:16:17.357715 | controller | ok: [instance] => (item={'key': 'compute-0', 'value': 'compute'}) 2026-03-10 19:16:17.392211 | controller | ok: [instance] => (item={'key': 'controller-0', 'value': 'controller'}) 2026-03-10 19:16:17.392231 | controller | ok: [instance] => (item={'key': 'crc-0', 'value': 'crc'}) 2026-03-10 19:16:17.392238 | controller | 2026-03-10 19:16:17.392243 | controller | TASK [libvirt_manager : Append baremetal nodes if needed] ********************** 2026-03-10 19:16:17.392249 | controller | Tuesday 10 March 2026 19:16:17 +0000 (0:00:00.117) 0:00:46.837 ********* 2026-03-10 19:16:17.392274 | controller | skipping: [instance] 2026-03-10 19:16:17.727052 | controller | 2026-03-10 19:16:17.727085 | controller | TASK [libvirt_manager : Dump MAC mapping] ************************************** 2026-03-10 19:16:17.727093 | controller | Tuesday 10 March 2026 19:16:17 +0000 (0:00:00.034) 0:00:46.872 ********* 2026-03-10 19:16:17.727103 | controller | changed: [instance] 2026-03-10 19:16:17.813367 | controller | 2026-03-10 19:16:17.813390 | controller | TASK [libvirt_manager : Inject all VMs in the inventory] *********************** 2026-03-10 19:16:17.813399 | controller | Tuesday 10 March 2026 19:16:17 +0000 (0:00:00.334) 0:00:47.207 ********* 2026-03-10 19:16:17.813410 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/add_vm_to_inventory.yml for instance => (item=Adding compute-0 to computes) 2026-03-10 19:16:17.813437 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/add_vm_to_inventory.yml for instance => (item=Adding controller-0 to controllers) 2026-03-10 19:16:17.813445 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/add_vm_to_inventory.yml for instance => (item=Adding crc-0 to ocps) 2026-03-10 19:16:17.813451 | controller | 2026-03-10 19:16:17.813459 | controller | TASK [libvirt_manager : Add host to runtime inventory] ************************* 2026-03-10 19:16:17.813514 | controller | Tuesday 10 March 2026 19:16:17 +0000 (0:00:00.086) 0:00:47.293 ********* 2026-03-10 19:16:17.864988 | controller | changed: [instance] 2026-03-10 19:16:18.160333 | controller | 2026-03-10 19:16:18.160510 | controller | TASK [libvirt_manager : Ensure group section exists] *************************** 2026-03-10 19:16:18.160521 | controller | Tuesday 10 March 2026 19:16:17 +0000 (0:00:00.051) 0:00:47.345 ********* 2026-03-10 19:16:18.160534 | controller | changed: [instance] 2026-03-10 19:16:18.160889 | controller | 2026-03-10 19:16:18.369964 | controller | TASK [libvirt_manager : Append host under proper group] ************************ 2026-03-10 19:16:18.369993 | controller | Tuesday 10 March 2026 19:16:18 +0000 (0:00:00.295) 0:00:47.641 ********* 2026-03-10 19:16:18.370005 | controller | changed: [instance] 2026-03-10 19:16:18.422890 | controller | 2026-03-10 19:16:18.422916 | controller | TASK [libvirt_manager : Add host to runtime inventory] ************************* 2026-03-10 19:16:18.422926 | controller | Tuesday 10 March 2026 19:16:18 +0000 (0:00:00.209) 0:00:47.850 ********* 2026-03-10 19:16:18.422937 | controller | changed: [instance] 2026-03-10 19:16:18.625160 | controller | 2026-03-10 19:16:18.625189 | controller | TASK [libvirt_manager : Ensure group section exists] *************************** 2026-03-10 19:16:18.625197 | controller | Tuesday 10 March 2026 19:16:18 +0000 (0:00:00.052) 0:00:47.903 ********* 2026-03-10 19:16:18.625207 | controller | changed: [instance] 2026-03-10 19:16:18.836392 | controller | 2026-03-10 19:16:18.836432 | controller | TASK [libvirt_manager : Append host under proper group] ************************ 2026-03-10 19:16:18.836442 | controller | Tuesday 10 March 2026 19:16:18 +0000 (0:00:00.202) 0:00:48.105 ********* 2026-03-10 19:16:18.836454 | controller | changed: [instance] 2026-03-10 19:16:18.836520 | controller | 2026-03-10 19:16:18.836544 | controller | TASK [libvirt_manager : Add host to runtime inventory] ************************* 2026-03-10 19:16:18.836556 | controller | Tuesday 10 March 2026 19:16:18 +0000 (0:00:00.211) 0:00:48.316 ********* 2026-03-10 19:16:18.886377 | controller | changed: [instance] 2026-03-10 19:16:18.886427 | controller | 2026-03-10 19:16:18.886577 | controller | TASK [libvirt_manager : Ensure group section exists] *************************** 2026-03-10 19:16:19.083389 | controller | Tuesday 10 March 2026 19:16:18 +0000 (0:00:00.050) 0:00:48.367 ********* 2026-03-10 19:16:19.083426 | controller | changed: [instance] 2026-03-10 19:16:19.083457 | controller | 2026-03-10 19:16:19.083467 | controller | TASK [libvirt_manager : Append host under proper group] ************************ 2026-03-10 19:16:19.083492 | controller | Tuesday 10 March 2026 19:16:19 +0000 (0:00:00.196) 0:00:48.564 ********* 2026-03-10 19:16:19.293321 | controller | changed: [instance] 2026-03-10 19:16:19.293372 | controller | 2026-03-10 19:16:19.293384 | controller | TASK [libvirt_manager : Check if ini inventory file exists] ******************** 2026-03-10 19:16:19.293537 | controller | Tuesday 10 March 2026 19:16:19 +0000 (0:00:00.209) 0:00:48.773 ********* 2026-03-10 19:16:19.490871 | controller | ok: [instance] 2026-03-10 19:16:19.970122 | controller | 2026-03-10 19:16:19.970158 | controller | TASK [libvirt_manager : Convert ini inventory file into yaml] ****************** 2026-03-10 19:16:19.970166 | controller | Tuesday 10 March 2026 19:16:19 +0000 (0:00:00.197) 0:00:48.971 ********* 2026-03-10 19:16:19.970183 | controller | changed: [instance] 2026-03-10 19:16:20.150324 | controller | 2026-03-10 19:16:20.150359 | controller | TASK [libvirt_manager : Remove ini file after conversion] ********************** 2026-03-10 19:16:20.150367 | controller | Tuesday 10 March 2026 19:16:19 +0000 (0:00:00.479) 0:00:49.450 ********* 2026-03-10 19:16:20.150376 | controller | changed: [instance] 2026-03-10 19:16:20.150469 | controller | 2026-03-10 19:16:20.150496 | controller | TASK [networking_mapper : Check for Networking Definition file existence] ****** 2026-03-10 19:16:20.150507 | controller | Tuesday 10 March 2026 19:16:20 +0000 (0:00:00.180) 0:00:49.630 ********* 2026-03-10 19:16:20.174490 | controller | skipping: [instance] 2026-03-10 19:16:20.174579 | controller | 2026-03-10 19:16:20.174602 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************ 2026-03-10 19:16:20.174613 | controller | Tuesday 10 March 2026 19:16:20 +0000 (0:00:00.024) 0:00:49.655 ********* 2026-03-10 19:16:20.198665 | controller | skipping: [instance] 2026-03-10 19:16:20.225428 | controller | 2026-03-10 19:16:20.225465 | controller | TASK [networking_mapper : Check for interfaces info file existence] ************ 2026-03-10 19:16:20.225479 | controller | Tuesday 10 March 2026 19:16:20 +0000 (0:00:00.024) 0:00:49.679 ********* 2026-03-10 19:16:20.225496 | controller | skipping: [instance] 2026-03-10 19:16:20.225551 | controller | 2026-03-10 19:16:20.225565 | controller | TASK [networking_mapper : Load the interfaces info from file] ****************** 2026-03-10 19:16:20.225579 | controller | Tuesday 10 March 2026 19:16:20 +0000 (0:00:00.026) 0:00:49.705 ********* 2026-03-10 19:16:20.249168 | controller | skipping: [instance] 2026-03-10 19:16:20.267652 | controller | 2026-03-10 19:16:20.267671 | controller | TASK [networking_mapper : Ensure local fact is empty] ************************** 2026-03-10 19:16:20.267679 | controller | Tuesday 10 March 2026 19:16:20 +0000 (0:00:00.023) 0:00:49.729 ********* 2026-03-10 19:16:20.267687 | controller | ok: [instance] 2026-03-10 19:16:20.346274 | controller | 2026-03-10 19:16:20.346300 | controller | TASK [networking_mapper : Set the input Networking Definition and patch it if needed] *** 2026-03-10 19:16:20.346307 | controller | Tuesday 10 March 2026 19:16:20 +0000 (0:00:00.018) 0:00:49.748 ********* 2026-03-10 19:16:20.346319 | controller | ok: [instance] => (item={'networks': {'ctlplane': {'network': '192.168.140.0/24', 'gateway': '192.168.140.1', 'dns': ['192.168.140.1'], 'mtu': 1500, 'tools': {'multus': {'ranges': [{'start': 30, 'end': 70}]}, 'netconfig': {'ranges': [{'start': 100, 'end': 120}, {'start': 150, 'end': 170}]}, 'metallb': {'ranges': [{'start': 80, 'end': 90}]}}}, 'internalapi': {'network': '172.17.0.0/24', 'vlan': 20, 'mtu': 1496, 'tools': {'metallb': {'ranges': [{'start': 80, 'end': 90}]}, 'netconfig': {'ranges': [{'start': 100, 'end': 250}]}, 'multus': {'ranges': [{'start': 30, 'end': 70}]}}}, 'storage': {'network': '172.18.0.0/24', 'vlan': 21, 'mtu': 1496, 'tools': {'metallb': {'ranges': [{'start': 80, 'end': 90}]}, 'netconfig': {'ranges': [{'start': 100, 'end': 250}]}, 'multus': {'ranges': [{'start': 30, 'end': 70}]}}}, 'tenant': {'network': '172.19.0.0/24', 'tools': {'metallb': {'ranges': [{'start': 80, 'end': 90}]}, 'netconfig': {'ranges': [{'start': 100, 'end': 250}]}, 'multus': {'ranges': [{'start': 30, 'end': 70}]}}, 'vlan': 22, 'mtu': 1496}, 'external': {'network': '10.0.0.0/24', 'tools': {'netconfig': {'ranges': [{'start': 100, 'end': 250}]}}, 'vlan': 22, 'mtu': 1500}, 'storagemgmt': {'network': '172.20.0.0/24', 'tools': {'netconfig': {'ranges': [{'start': 100, 'end': 250}]}}, 'vlan': 23, 'mtu': 1500}}, 'group-templates': {'ocps': {'network-template': {'range': {'start': 10, 'length': 10}}, 'networks': {'ctlplane': {}, 'internalapi': {'trunk-parent': 'ctlplane'}, 'tenant': {'trunk-parent': 'ctlplane'}, 'storage': {'trunk-parent': 'ctlplane'}}}, 'computes': {'network-template': {'range': {'start': 100, 'length': 21}}, 'networks': {'ctlplane': {}, 'internalapi': {'trunk-parent': 'ctlplane'}, 'tenant': {'trunk-parent': 'ctlplane'}, 'storage': {'trunk-parent': 'ctlplane'}, 'storagemgmt': {'trunk-parent': 'ctlplane'}}}, 'cephs': {'network-template': {'range': {'start': 150, 'length': 21}}, 'networks': {'ctlplane': {}, 'internalapi': {'trunk-parent': 'ctlplane'}, 'tenant': {'trunk-parent': 'ctlplane'}, 'storage': {'trunk-parent': 'ctlplane'}, 'storagemgmt': {'trunk-parent': 'ctlplane'}}}}, 'instances': {'controller-0': {'networks': {'ctlplane': {'ip': '192.168.140.9'}}}}}) 2026-03-10 19:16:20.363584 | controller | 2026-03-10 19:16:20.363606 | controller | TASK [networking_mapper : Gather facts if requested] *************************** 2026-03-10 19:16:20.363615 | controller | Tuesday 10 March 2026 19:16:20 +0000 (0:00:00.078) 0:00:49.826 ********* 2026-03-10 19:16:20.363625 | controller | skipping: [instance] 2026-03-10 19:16:20.363777 | controller | 2026-03-10 19:16:20.363920 | controller | TASK [networking_mapper : Ensure CI infrastructure dir exists] ***************** 2026-03-10 19:16:20.364039 | controller | Tuesday 10 March 2026 19:16:20 +0000 (0:00:00.017) 0:00:49.844 ********* 2026-03-10 19:16:20.558405 | controller | changed: [instance] 2026-03-10 19:16:20.558628 | controller | 2026-03-10 19:16:20.558659 | controller | TASK [networking_mapper : Write the Networking Definition to file] ************* 2026-03-10 19:16:20.941397 | controller | Tuesday 10 March 2026 19:16:20 +0000 (0:00:00.194) 0:00:50.039 ********* 2026-03-10 19:16:20.941438 | controller | changed: [instance] 2026-03-10 19:16:20.941471 | controller | 2026-03-10 19:16:20.941674 | controller | TASK [networking_mapper : Call the networking mapper] ************************** 2026-03-10 19:16:21.001000 | controller | Tuesday 10 March 2026 19:16:20 +0000 (0:00:00.382) 0:00:50.421 ********* 2026-03-10 19:16:21.001028 | controller | ok: [instance] 2026-03-10 19:16:21.001054 | controller | 2026-03-10 19:16:21.001064 | controller | TASK [networking_mapper : Set networking mapper facts] ************************* 2026-03-10 19:16:21.001131 | controller | Tuesday 10 March 2026 19:16:20 +0000 (0:00:00.059) 0:00:50.481 ********* 2026-03-10 19:16:21.032081 | controller | ok: [instance] 2026-03-10 19:16:21.395351 | controller | 2026-03-10 19:16:21.395383 | controller | TASK [networking_mapper : Write the Networking Environment Definition to file] *** 2026-03-10 19:16:21.395391 | controller | Tuesday 10 March 2026 19:16:21 +0000 (0:00:00.030) 0:00:50.512 ********* 2026-03-10 19:16:21.395402 | controller | changed: [instance] 2026-03-10 19:16:21.395425 | controller | 2026-03-10 19:16:21.395433 | controller | TASK [libvirt_manager : Manage networks if needed] ***************************** 2026-03-10 19:16:21.395577 | controller | Tuesday 10 March 2026 19:16:21 +0000 (0:00:00.363) 0:00:50.875 ********* 2026-03-10 19:16:21.476895 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/create_networks.yml for instance 2026-03-10 19:16:21.524043 | controller | 2026-03-10 19:16:21.524069 | controller | TASK [libvirt_manager : Define the localized variables for performing the tasks here.] *** 2026-03-10 19:16:21.524077 | controller | Tuesday 10 March 2026 19:16:21 +0000 (0:00:00.081) 0:00:50.957 ********* 2026-03-10 19:16:21.524087 | controller | ok: [instance] => (item=public) 2026-03-10 19:16:21.952805 | controller | ok: [instance] => (item=osp_trunk) 2026-03-10 19:16:21.952838 | controller | 2026-03-10 19:16:21.952846 | controller | TASK [libvirt_manager : Ensure networks are defined] *************************** 2026-03-10 19:16:21.952858 | controller | Tuesday 10 March 2026 19:16:21 +0000 (0:00:00.047) 0:00:51.004 ********* 2026-03-10 19:16:21.952868 | controller | changed: [instance] => (item=cifmw-public) 2026-03-10 19:16:22.506379 | controller | changed: [instance] => (item=cifmw-osp_trunk) 2026-03-10 19:16:22.506412 | controller | 2026-03-10 19:16:22.506420 | controller | TASK [libvirt_manager : Ensure networks are created/started] ******************* 2026-03-10 19:16:22.506427 | controller | Tuesday 10 March 2026 19:16:21 +0000 (0:00:00.428) 0:00:51.432 ********* 2026-03-10 19:16:22.506436 | controller | ok: [instance] => (item=cifmw-public) 2026-03-10 19:16:22.506825 | controller | ok: [instance] => (item=cifmw-osp_trunk) 2026-03-10 19:16:22.925324 | controller | 2026-03-10 19:16:22.925363 | controller | TASK [libvirt_manager : Ensure networks are active] **************************** 2026-03-10 19:16:22.925371 | controller | Tuesday 10 March 2026 19:16:22 +0000 (0:00:00.553) 0:00:51.986 ********* 2026-03-10 19:16:22.925381 | controller | ok: [instance] => (item=cifmw-public) 2026-03-10 19:16:22.925405 | controller | ok: [instance] => (item=cifmw-osp_trunk) 2026-03-10 19:16:22.925411 | controller | 2026-03-10 19:16:22.925417 | controller | TASK [libvirt_manager : Ensure networks enabled to autostart] ****************** 2026-03-10 19:16:22.925424 | controller | Tuesday 10 March 2026 19:16:22 +0000 (0:00:00.418) 0:00:52.405 ********* 2026-03-10 19:16:23.369346 | controller | changed: [instance] => (item=cifmw-public) 2026-03-10 19:16:23.369730 | controller | changed: [instance] => (item=cifmw-osp_trunk) 2026-03-10 19:16:23.425461 | controller | 2026-03-10 19:16:23.425496 | controller | TASK [libvirt_manager : Add a dummy interface to bridges if required] ********** 2026-03-10 19:16:23.425509 | controller | Tuesday 10 March 2026 19:16:23 +0000 (0:00:00.444) 0:00:52.850 ********* 2026-03-10 19:16:23.425526 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/create_networks_dummy_interfaces.yml for instance 2026-03-10 19:16:23.425771 | controller | 2026-03-10 19:16:23.425787 | controller | TASK [libvirt_manager : Fetch present bridge interfaces] *********************** 2026-03-10 19:16:23.604494 | controller | Tuesday 10 March 2026 19:16:23 +0000 (0:00:00.055) 0:00:52.905 ********* 2026-03-10 19:16:23.604522 | controller | ok: [instance] 2026-03-10 19:16:23.984757 | controller | 2026-03-10 19:16:23.984788 | controller | TASK [libvirt_manager : Fetch bridges ports link info] ************************* 2026-03-10 19:16:23.984795 | controller | Tuesday 10 March 2026 19:16:23 +0000 (0:00:00.179) 0:00:53.084 ********* 2026-03-10 19:16:23.984805 | controller | ok: [instance] => (item=cifmw-public) 2026-03-10 19:16:24.050173 | controller | ok: [instance] => (item=cifmw-osp_trunk) 2026-03-10 19:16:24.050215 | controller | 2026-03-10 19:16:24.050228 | controller | TASK [Create dummy interfaces to ensure bridges are UP] ************************ 2026-03-10 19:16:24.050238 | controller | Tuesday 10 March 2026 19:16:23 +0000 (0:00:00.380) 0:00:53.465 ********* 2026-03-10 19:16:24.050279 | controller | 2026-03-10 19:16:24.086659 | controller | TASK [ci_nmstate : Ensure we have needed facts] ******************************** 2026-03-10 19:16:24.086678 | controller | Tuesday 10 March 2026 19:16:24 +0000 (0:00:00.065) 0:00:53.530 ********* 2026-03-10 19:16:24.086688 | controller | skipping: [instance] 2026-03-10 19:16:25.303275 | controller | 2026-03-10 19:16:25.303307 | controller | TASK [ci_nmstate : Install required packages on instance] ********************** 2026-03-10 19:16:25.303315 | controller | Tuesday 10 March 2026 19:16:24 +0000 (0:00:00.036) 0:00:53.567 ********* 2026-03-10 19:16:25.303325 | controller | ok: [instance] 2026-03-10 19:16:25.486438 | controller | 2026-03-10 19:16:25.486471 | controller | TASK [ci_nmstate : Create the target NMstate dump directory] ******************* 2026-03-10 19:16:25.486479 | controller | Tuesday 10 March 2026 19:16:25 +0000 (0:00:01.216) 0:00:54.783 ********* 2026-03-10 19:16:25.486491 | controller | ok: [instance] 2026-03-10 19:16:25.830730 | controller | 2026-03-10 19:16:25.830758 | controller | TASK [ci_nmstate : Save nmstate state for instance] **************************** 2026-03-10 19:16:25.830766 | controller | Tuesday 10 March 2026 19:16:25 +0000 (0:00:00.183) 0:00:54.966 ********* 2026-03-10 19:16:25.830785 | controller | changed: [instance] 2026-03-10 19:16:25.830803 | controller | 2026-03-10 19:16:25.830900 | controller | TASK [ci_nmstate : Apply the desidered state on instance] ********************** 2026-03-10 19:16:25.831009 | controller | Tuesday 10 March 2026 19:16:25 +0000 (0:00:00.344) 0:00:55.311 ********* 2026-03-10 19:16:27.475119 | controller | changed: [instance] 2026-03-10 19:16:27.820888 | controller | 2026-03-10 19:16:27.820914 | controller | TASK [ci_nmstate : Save nmstate debugging data for instance] ******************* 2026-03-10 19:16:27.820922 | controller | Tuesday 10 March 2026 19:16:27 +0000 (0:00:01.643) 0:00:56.955 ********* 2026-03-10 19:16:27.820933 | controller | changed: [instance] 2026-03-10 19:16:27.852410 | controller | 2026-03-10 19:16:27.852447 | controller | TASK [Create extra network configuration] ************************************** 2026-03-10 19:16:27.852456 | controller | Tuesday 10 March 2026 19:16:27 +0000 (0:00:00.346) 0:00:57.301 ********* 2026-03-10 19:16:27.852468 | controller | skipping: [instance] 2026-03-10 19:16:27.852499 | controller | 2026-03-10 19:16:27.852673 | controller | TASK [libvirt_manager : Ensure extra networks is in correct zone] ************** 2026-03-10 19:16:27.880369 | controller | Tuesday 10 March 2026 19:16:27 +0000 (0:00:00.031) 0:00:57.333 ********* 2026-03-10 19:16:27.880414 | controller | skipping: [instance] 2026-03-10 19:16:28.313301 | controller | 2026-03-10 19:16:28.313339 | controller | TASK [libvirt_manager : Refresh networking facts on host] ********************** 2026-03-10 19:16:28.313349 | controller | Tuesday 10 March 2026 19:16:27 +0000 (0:00:00.027) 0:00:57.360 ********* 2026-03-10 19:16:28.313360 | controller | ok: [instance] 2026-03-10 19:16:28.451143 | controller | 2026-03-10 19:16:28.451162 | controller | TASK [libvirt_manager : Build needed network/dnsmasq related content] ********** 2026-03-10 19:16:28.451170 | controller | Tuesday 10 March 2026 19:16:28 +0000 (0:00:00.432) 0:00:57.793 ********* 2026-03-10 19:16:28.451178 | controller | ok: [instance] => (item=cifmw-public) 2026-03-10 19:16:29.637078 | controller | ok: [instance] => (item=cifmw-osp_trunk) 2026-03-10 19:16:29.637104 | controller | 2026-03-10 19:16:29.637112 | controller | TASK [dnsmasq : Install needed packages] *************************************** 2026-03-10 19:16:29.637118 | controller | Tuesday 10 March 2026 19:16:28 +0000 (0:00:00.137) 0:00:57.931 ********* 2026-03-10 19:16:29.637128 | controller | ok: [instance] 2026-03-10 19:16:33.269383 | controller | 2026-03-10 19:16:33.269414 | controller | TASK [dnsmasq : Ensure SELinux knows about the new files and directories] ****** 2026-03-10 19:16:33.269423 | controller | Tuesday 10 March 2026 19:16:29 +0000 (0:00:01.186) 0:00:59.117 ********* 2026-03-10 19:16:33.269433 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.conf', 'setype': 'dnsmasq_etc_t'}) 2026-03-10 19:16:33.478028 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.d(/.*)?', 'setype': 'dnsmasq_etc_t'}) 2026-03-10 19:16:33.478052 | controller | changed: [instance] => (item={'target': '/var/run/cifmw-dnsmasq.pid', 'setype': 'dnsmasq_var_run_t'}) 2026-03-10 19:16:33.478060 | controller | 2026-03-10 19:16:33.478066 | controller | TASK [dnsmasq : Manage configuration directory] ******************************** 2026-03-10 19:16:33.478072 | controller | Tuesday 10 March 2026 19:16:33 +0000 (0:00:03.631) 0:01:02.749 ********* 2026-03-10 19:16:33.478081 | controller | changed: [instance] 2026-03-10 19:16:33.830235 | controller | 2026-03-10 19:16:33.830273 | controller | TASK [dnsmasq : Manage systemd unit file] ************************************** 2026-03-10 19:16:33.830284 | controller | Tuesday 10 March 2026 19:16:33 +0000 (0:00:00.209) 0:01:02.958 ********* 2026-03-10 19:16:33.830293 | controller | changed: [instance] 2026-03-10 19:16:34.203397 | controller | 2026-03-10 19:16:34.203433 | controller | TASK [dnsmasq : Manage base configuration file] ******************************** 2026-03-10 19:16:34.203444 | controller | Tuesday 10 March 2026 19:16:33 +0000 (0:00:00.352) 0:01:03.310 ********* 2026-03-10 19:16:34.203456 | controller | changed: [instance] 2026-03-10 19:16:34.203490 | controller | 2026-03-10 19:16:34.203500 | controller | TASK [dnsmasq : Render listener configuration] ********************************* 2026-03-10 19:16:34.203542 | controller | Tuesday 10 March 2026 19:16:34 +0000 (0:00:00.373) 0:01:03.684 ********* 2026-03-10 19:16:34.234975 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/listener.yml for instance 2026-03-10 19:16:34.617326 | controller | 2026-03-10 19:16:34.617356 | controller | TASK [dnsmasq : Create listener configuration file] **************************** 2026-03-10 19:16:34.617363 | controller | Tuesday 10 March 2026 19:16:34 +0000 (0:00:00.031) 0:01:03.715 ********* 2026-03-10 19:16:34.617373 | controller | changed: [instance] 2026-03-10 19:16:34.617627 | controller | 2026-03-10 19:16:34.617659 | controller | TASK [dnsmasq : Remove listener configuration file] **************************** 2026-03-10 19:16:34.617673 | controller | Tuesday 10 March 2026 19:16:34 +0000 (0:00:00.382) 0:01:04.097 ********* 2026-03-10 19:16:34.637344 | controller | skipping: [instance] 2026-03-10 19:16:34.637757 | controller | 2026-03-10 19:16:34.670789 | controller | TASK [dnsmasq : Render dns configuration] ************************************** 2026-03-10 19:16:34.670807 | controller | Tuesday 10 March 2026 19:16:34 +0000 (0:00:00.020) 0:01:04.117 ********* 2026-03-10 19:16:34.670817 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/dns.yml for instance 2026-03-10 19:16:35.028452 | controller | 2026-03-10 19:16:35.028488 | controller | TASK [dnsmasq : Create dns configuration file] ********************************* 2026-03-10 19:16:35.028496 | controller | Tuesday 10 March 2026 19:16:34 +0000 (0:00:00.033) 0:01:04.151 ********* 2026-03-10 19:16:35.028507 | controller | changed: [instance] 2026-03-10 19:16:35.049019 | controller | 2026-03-10 19:16:35.049044 | controller | TASK [dnsmasq : Remove dns configuration file] ********************************* 2026-03-10 19:16:35.049053 | controller | Tuesday 10 March 2026 19:16:35 +0000 (0:00:00.357) 0:01:04.508 ********* 2026-03-10 19:16:35.049064 | controller | skipping: [instance] 2026-03-10 19:16:35.250387 | controller | 2026-03-10 19:16:35.250420 | controller | TASK [dnsmasq : Add localhost addresses from defined dnsmasq listen addresses to loopback interface] *** 2026-03-10 19:16:35.250429 | controller | Tuesday 10 March 2026 19:16:35 +0000 (0:00:00.020) 0:01:04.529 ********* 2026-03-10 19:16:35.250440 | controller | ok: [instance] => (item=127.0.0.2) 2026-03-10 19:16:35.901830 | controller | 2026-03-10 19:16:35.901865 | controller | TASK [dnsmasq : Enable and start service] ************************************** 2026-03-10 19:16:35.901874 | controller | Tuesday 10 March 2026 19:16:35 +0000 (0:00:00.201) 0:01:04.730 ********* 2026-03-10 19:16:35.901885 | controller | changed: [instance] 2026-03-10 19:16:35.945976 | controller | 2026-03-10 19:16:35.946008 | controller | TASK [dnsmasq : Stop service] ************************************************** 2026-03-10 19:16:35.946024 | controller | Tuesday 10 March 2026 19:16:35 +0000 (0:00:00.651) 0:01:05.382 ********* 2026-03-10 19:16:35.946035 | controller | skipping: [instance] 2026-03-10 19:16:35.964310 | controller | 2026-03-10 19:16:35.964336 | controller | TASK [dnsmasq : Remove unit file] ********************************************** 2026-03-10 19:16:35.964343 | controller | Tuesday 10 March 2026 19:16:35 +0000 (0:00:00.044) 0:01:05.426 ********* 2026-03-10 19:16:35.964353 | controller | skipping: [instance] 2026-03-10 19:16:35.994391 | controller | 2026-03-10 19:16:35.994434 | controller | TASK [dnsmasq : Remove main configuration files] ******************************* 2026-03-10 19:16:35.994448 | controller | Tuesday 10 March 2026 19:16:35 +0000 (0:00:00.018) 0:01:05.444 ********* 2026-03-10 19:16:35.994464 | controller | skipping: [instance] => (item=/etc/cifmw-dnsmasq.conf) 2026-03-10 19:16:35.994502 | controller | skipping: [instance] => (item=/etc/cifmw-dnsmasq.d) 2026-03-10 19:16:35.994512 | controller | skipping: [instance] 2026-03-10 19:16:35.994524 | controller | 2026-03-10 19:16:35.994608 | controller | TASK [Create dnsmasq networks] ************************************************* 2026-03-10 19:16:35.994632 | controller | Tuesday 10 March 2026 19:16:35 +0000 (0:00:00.030) 0:01:05.475 ********* 2026-03-10 19:16:36.108191 | controller | 2026-03-10 19:16:36.138401 | controller | TASK [dnsmasq : Assert mandatory parameter] ************************************ 2026-03-10 19:16:36.138422 | controller | Tuesday 10 March 2026 19:16:36 +0000 (0:00:00.113) 0:01:05.588 ********* 2026-03-10 19:16:36.138434 | controller | ok: [instance] => changed=false 2026-03-10 19:16:36.138462 | controller | msg: All assertions passed 2026-03-10 19:16:36.138469 | controller | 2026-03-10 19:16:36.138478 | controller | TASK [dnsmasq : Assert mandatory parameters for new network] ******************* 2026-03-10 19:16:36.138574 | controller | Tuesday 10 March 2026 19:16:36 +0000 (0:00:00.030) 0:01:05.619 ********* 2026-03-10 19:16:36.175919 | controller | ok: [instance] => changed=false 2026-03-10 19:16:36.599895 | controller | msg: All assertions passed 2026-03-10 19:16:36.599919 | controller | 2026-03-10 19:16:36.599927 | controller | TASK [dnsmasq : Create network] ************************************************ 2026-03-10 19:16:36.599933 | controller | Tuesday 10 March 2026 19:16:36 +0000 (0:00:00.037) 0:01:05.656 ********* 2026-03-10 19:16:36.599942 | controller | changed: [instance] 2026-03-10 19:16:36.619413 | controller | 2026-03-10 19:16:36.619455 | controller | TASK [dnsmasq : Remove network] ************************************************ 2026-03-10 19:16:36.619464 | controller | Tuesday 10 March 2026 19:16:36 +0000 (0:00:00.423) 0:01:06.080 ********* 2026-03-10 19:16:36.619475 | controller | skipping: [instance] 2026-03-10 19:16:36.643244 | controller | 2026-03-10 19:16:36.643286 | controller | TASK [dnsmasq : Remove dhcp host files] **************************************** 2026-03-10 19:16:36.643294 | controller | Tuesday 10 March 2026 19:16:36 +0000 (0:00:00.019) 0:01:06.100 ********* 2026-03-10 19:16:36.643313 | controller | skipping: [instance] 2026-03-10 19:16:36.671996 | controller | 2026-03-10 19:16:36.672017 | controller | TASK [dnsmasq : Assert mandatory parameter] ************************************ 2026-03-10 19:16:36.672026 | controller | Tuesday 10 March 2026 19:16:36 +0000 (0:00:00.023) 0:01:06.123 ********* 2026-03-10 19:16:36.672036 | controller | ok: [instance] => changed=false 2026-03-10 19:16:36.709044 | controller | msg: All assertions passed 2026-03-10 19:16:36.709064 | controller | 2026-03-10 19:16:36.709073 | controller | TASK [dnsmasq : Assert mandatory parameters for new network] ******************* 2026-03-10 19:16:36.709080 | controller | Tuesday 10 March 2026 19:16:36 +0000 (0:00:00.028) 0:01:06.152 ********* 2026-03-10 19:16:36.709089 | controller | ok: [instance] => changed=false 2026-03-10 19:16:37.132942 | controller | msg: All assertions passed 2026-03-10 19:16:37.132963 | controller | 2026-03-10 19:16:37.132971 | controller | TASK [dnsmasq : Create network] ************************************************ 2026-03-10 19:16:37.132977 | controller | Tuesday 10 March 2026 19:16:36 +0000 (0:00:00.037) 0:01:06.189 ********* 2026-03-10 19:16:37.132986 | controller | changed: [instance] 2026-03-10 19:16:37.153402 | controller | 2026-03-10 19:16:37.153442 | controller | TASK [dnsmasq : Remove network] ************************************************ 2026-03-10 19:16:37.153452 | controller | Tuesday 10 March 2026 19:16:37 +0000 (0:00:00.423) 0:01:06.613 ********* 2026-03-10 19:16:37.153464 | controller | skipping: [instance] 2026-03-10 19:16:37.178376 | controller | 2026-03-10 19:16:37.178400 | controller | TASK [dnsmasq : Remove dhcp host files] **************************************** 2026-03-10 19:16:37.178409 | controller | Tuesday 10 March 2026 19:16:37 +0000 (0:00:00.020) 0:01:06.633 ********* 2026-03-10 19:16:37.178420 | controller | skipping: [instance] 2026-03-10 19:16:37.178448 | controller | 2026-03-10 19:16:37.178458 | controller | TASK [Deploy radvd for IPv6 router advertisements] ***************************** 2026-03-10 19:16:37.178501 | controller | Tuesday 10 March 2026 19:16:37 +0000 (0:00:00.025) 0:01:06.659 ********* 2026-03-10 19:16:37.211365 | controller | skipping: [instance] 2026-03-10 19:16:37.211388 | controller | 2026-03-10 19:16:37.211397 | controller | TASK [libvirt_manager : Ensure network is in correct zone] ********************* 2026-03-10 19:16:37.211407 | controller | Tuesday 10 March 2026 19:16:37 +0000 (0:00:00.032) 0:01:06.691 ********* 2026-03-10 19:16:37.703155 | controller | changed: [instance] => (item=cifmw-public) 2026-03-10 19:16:37.739380 | controller | changed: [instance] => (item=cifmw-osp_trunk) 2026-03-10 19:16:37.739408 | controller | 2026-03-10 19:16:37.739417 | controller | TASK [libvirt_manager : Extract IP address from network bridges] *************** 2026-03-10 19:16:37.739426 | controller | Tuesday 10 March 2026 19:16:37 +0000 (0:00:00.491) 0:01:07.183 ********* 2026-03-10 19:16:37.739438 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/network_bridge_info_gen.yml for instance => (item=cifmw-public) 2026-03-10 19:16:38.078805 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/network_bridge_info_gen.yml for instance => (item=cifmw-osp_trunk) 2026-03-10 19:16:38.078841 | controller | 2026-03-10 19:16:38.078850 | controller | TASK [libvirt_manager : Extract IP address from network bridges] *************** 2026-03-10 19:16:38.078856 | controller | Tuesday 10 March 2026 19:16:37 +0000 (0:00:00.036) 0:01:07.219 ********* 2026-03-10 19:16:38.078867 | controller | ok: [instance] 2026-03-10 19:16:38.112383 | controller | 2026-03-10 19:16:38.112419 | controller | TASK [libvirt_manager : Set network_bridge_info fact with network and address] *** 2026-03-10 19:16:38.112429 | controller | Tuesday 10 March 2026 19:16:38 +0000 (0:00:00.339) 0:01:07.559 ********* 2026-03-10 19:16:38.112442 | controller | ok: [instance] 2026-03-10 19:16:38.112471 | controller | 2026-03-10 19:16:38.112481 | controller | TASK [libvirt_manager : Extract IP address from network bridges] *************** 2026-03-10 19:16:38.112588 | controller | Tuesday 10 March 2026 19:16:38 +0000 (0:00:00.033) 0:01:07.592 ********* 2026-03-10 19:16:38.310082 | controller | ok: [instance] 2026-03-10 19:16:38.344371 | controller | 2026-03-10 19:16:38.344406 | controller | TASK [libvirt_manager : Set network_bridge_info fact with network and address] *** 2026-03-10 19:16:38.344416 | controller | Tuesday 10 March 2026 19:16:38 +0000 (0:00:00.197) 0:01:07.790 ********* 2026-03-10 19:16:38.344428 | controller | ok: [instance] 2026-03-10 19:16:38.344461 | controller | 2026-03-10 19:16:38.344685 | controller | TASK [libvirt_manager : Output _network_data] ********************************** 2026-03-10 19:16:38.675193 | controller | Tuesday 10 March 2026 19:16:38 +0000 (0:00:00.034) 0:01:07.825 ********* 2026-03-10 19:16:38.675226 | controller | changed: [instance] 2026-03-10 19:16:39.000398 | controller | 2026-03-10 19:16:39.000432 | controller | TASK [libvirt_manager : Output pub_net] **************************************** 2026-03-10 19:16:39.000442 | controller | Tuesday 10 March 2026 19:16:38 +0000 (0:00:00.330) 0:01:08.155 ********* 2026-03-10 19:16:39.000455 | controller | changed: [instance] 2026-03-10 19:16:39.331809 | controller | 2026-03-10 19:16:39.331841 | controller | TASK [libvirt_manager : Output _cifmw_libvirt_manager_layout] ****************** 2026-03-10 19:16:39.331849 | controller | Tuesday 10 March 2026 19:16:38 +0000 (0:00:00.325) 0:01:08.480 ********* 2026-03-10 19:16:39.331860 | controller | changed: [instance] 2026-03-10 19:16:39.424409 | controller | 2026-03-10 19:16:39.424507 | controller | TASK [libvirt_manager : Expose patch for networking_mapper] ******************** 2026-03-10 19:16:39.424518 | controller | Tuesday 10 March 2026 19:16:39 +0000 (0:00:00.331) 0:01:08.812 ********* 2026-03-10 19:16:39.424532 | controller | ok: [instance] 2026-03-10 19:16:39.424569 | controller | 2026-03-10 19:16:39.424662 | controller | TASK [libvirt_manager : Save networking_mapper patch] ************************** 2026-03-10 19:16:39.424690 | controller | Tuesday 10 March 2026 19:16:39 +0000 (0:00:00.092) 0:01:08.904 ********* 2026-03-10 19:16:39.751362 | controller | changed: [instance] 2026-03-10 19:16:39.774368 | controller | 2026-03-10 19:16:39.774391 | controller | TASK [networking_mapper : Check for Networking Definition file existence] ****** 2026-03-10 19:16:39.774401 | controller | Tuesday 10 March 2026 19:16:39 +0000 (0:00:00.326) 0:01:09.231 ********* 2026-03-10 19:16:39.774417 | controller | skipping: [instance] 2026-03-10 19:16:39.774677 | controller | 2026-03-10 19:16:39.797415 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************ 2026-03-10 19:16:39.797452 | controller | Tuesday 10 March 2026 19:16:39 +0000 (0:00:00.023) 0:01:09.255 ********* 2026-03-10 19:16:39.797466 | controller | skipping: [instance] 2026-03-10 19:16:39.797498 | controller | 2026-03-10 19:16:39.797509 | controller | TASK [networking_mapper : Check for interfaces info file existence] ************ 2026-03-10 19:16:39.797644 | controller | Tuesday 10 March 2026 19:16:39 +0000 (0:00:00.022) 0:01:09.277 ********* 2026-03-10 19:16:39.819391 | controller | skipping: [instance] 2026-03-10 19:16:39.819599 | controller | 2026-03-10 19:16:39.819619 | controller | TASK [networking_mapper : Load the interfaces info from file] ****************** 2026-03-10 19:16:39.841456 | controller | Tuesday 10 March 2026 19:16:39 +0000 (0:00:00.022) 0:01:09.300 ********* 2026-03-10 19:16:39.841495 | controller | skipping: [instance] 2026-03-10 19:16:39.841770 | controller | 2026-03-10 19:16:39.841803 | controller | TASK [networking_mapper : Ensure local fact is empty] ************************** 2026-03-10 19:16:39.860334 | controller | Tuesday 10 March 2026 19:16:39 +0000 (0:00:00.021) 0:01:09.321 ********* 2026-03-10 19:16:39.860378 | controller | ok: [instance] 2026-03-10 19:16:39.948348 | controller | 2026-03-10 19:16:39.948377 | controller | TASK [networking_mapper : Set the input Networking Definition and patch it if needed] *** 2026-03-10 19:16:39.948391 | controller | Tuesday 10 March 2026 19:16:39 +0000 (0:00:00.018) 0:01:09.340 ********* 2026-03-10 19:16:39.948406 | controller | ok: [instance] => (item={'networks': {'ctlplane': {'network': '192.168.140.0/24', 'gateway': '192.168.140.1', 'dns': ['192.168.140.1'], 'mtu': 1500, 'tools': {'multus': {'ranges': [{'start': 30, 'end': 70}]}, 'netconfig': {'ranges': [{'start': 100, 'end': 120}, {'start': 150, 'end': 170}]}, 'metallb': {'ranges': [{'start': 80, 'end': 90}]}}}, 'internalapi': {'network': '172.17.0.0/24', 'vlan': 20, 'mtu': 1496, 'tools': {'metallb': {'ranges': [{'start': 80, 'end': 90}]}, 'netconfig': {'ranges': [{'start': 100, 'end': 250}]}, 'multus': {'ranges': [{'start': 30, 'end': 70}]}}}, 'storage': {'network': '172.18.0.0/24', 'vlan': 21, 'mtu': 1496, 'tools': {'metallb': {'ranges': [{'start': 80, 'end': 90}]}, 'netconfig': {'ranges': [{'start': 100, 'end': 250}]}, 'multus': {'ranges': [{'start': 30, 'end': 70}]}}}, 'tenant': {'network': '172.19.0.0/24', 'tools': {'metallb': {'ranges': [{'start': 80, 'end': 90}]}, 'netconfig': {'ranges': [{'start': 100, 'end': 250}]}, 'multus': {'ranges': [{'start': 30, 'end': 70}]}}, 'vlan': 22, 'mtu': 1496}, 'external': {'network': '10.0.0.0/24', 'tools': {'netconfig': {'ranges': [{'start': 100, 'end': 250}]}}, 'vlan': 22, 'mtu': 1500}, 'storagemgmt': {'network': '172.20.0.0/24', 'tools': {'netconfig': {'ranges': [{'start': 100, 'end': 250}]}}, 'vlan': 23, 'mtu': 1500}}, 'group-templates': {'ocps': {'network-template': {'range': {'start': 10, 'length': 10}}, 'networks': {'ctlplane': {}, 'internalapi': {'trunk-parent': 'ctlplane'}, 'tenant': {'trunk-parent': 'ctlplane'}, 'storage': {'trunk-parent': 'ctlplane'}}}, 'computes': {'network-template': {'range': {'start': 100, 'length': 21}}, 'networks': {'ctlplane': {}, 'internalapi': {'trunk-parent': 'ctlplane'}, 'tenant': {'trunk-parent': 'ctlplane'}, 'storage': {'trunk-parent': 'ctlplane'}, 'storagemgmt': {'trunk-parent': 'ctlplane'}}}, 'cephs': {'network-template': {'range': {'start': 150, 'length': 21}}, 'networks': {'ctlplane': {}, 'internalapi': {'trunk-parent': 'ctlplane'}, 'tenant': {'trunk-parent': 'ctlplane'}, 'storage': {'trunk-parent': 'ctlplane'}, 'storagemgmt': {'trunk-parent': 'ctlplane'}}}}, 'instances': {'controller-0': {'networks': {'ctlplane': {'ip': '192.168.140.9'}}}}}) 2026-03-10 19:16:39.948493 | controller | ok: [instance] => (item={'networks': {'public': {'network-v4': '192.168.101.0/24'}}, 'group-templates': {'computes': {'networks': {'public': None}}, 'ocps': {'networks': {'public': None}}}, 'instances': {'controller-0': {'networks': {'public': {'ip-v4': '192.168.101.9'}}}}}) 2026-03-10 19:16:39.948505 | controller | 2026-03-10 19:16:39.948516 | controller | TASK [networking_mapper : Gather facts if requested] *************************** 2026-03-10 19:16:39.948631 | controller | Tuesday 10 March 2026 19:16:39 +0000 (0:00:00.088) 0:01:09.428 ********* 2026-03-10 19:16:39.966517 | controller | skipping: [instance] 2026-03-10 19:16:39.966738 | controller | 2026-03-10 19:16:39.966967 | controller | TASK [networking_mapper : Ensure CI infrastructure dir exists] ***************** 2026-03-10 19:16:39.967137 | controller | Tuesday 10 March 2026 19:16:39 +0000 (0:00:00.018) 0:01:09.447 ********* 2026-03-10 19:16:40.163379 | controller | ok: [instance] 2026-03-10 19:16:40.163437 | controller | 2026-03-10 19:16:40.163622 | controller | TASK [networking_mapper : Write the Networking Definition to file] ************* 2026-03-10 19:16:40.549005 | controller | Tuesday 10 March 2026 19:16:40 +0000 (0:00:00.196) 0:01:09.644 ********* 2026-03-10 19:16:40.549036 | controller | changed: [instance] 2026-03-10 19:16:40.606964 | controller | 2026-03-10 19:16:40.606987 | controller | TASK [networking_mapper : Call the networking mapper] ************************** 2026-03-10 19:16:40.606996 | controller | Tuesday 10 March 2026 19:16:40 +0000 (0:00:00.385) 0:01:10.029 ********* 2026-03-10 19:16:40.607008 | controller | ok: [instance] 2026-03-10 19:16:40.636175 | controller | 2026-03-10 19:16:40.636216 | controller | TASK [networking_mapper : Set networking mapper facts] ************************* 2026-03-10 19:16:40.636236 | controller | Tuesday 10 March 2026 19:16:40 +0000 (0:00:00.058) 0:01:10.087 ********* 2026-03-10 19:16:40.636244 | controller | ok: [instance] 2026-03-10 19:16:41.027009 | controller | 2026-03-10 19:16:41.027040 | controller | TASK [networking_mapper : Write the Networking Environment Definition to file] *** 2026-03-10 19:16:41.027048 | controller | Tuesday 10 March 2026 19:16:40 +0000 (0:00:00.028) 0:01:10.116 ********* 2026-03-10 19:16:41.027059 | controller | changed: [instance] 2026-03-10 19:16:41.053092 | controller | 2026-03-10 19:16:41.053111 | controller | TASK [libvirt_manager : Be sure to save current state of networking_mapper environment] *** 2026-03-10 19:16:41.053118 | controller | Tuesday 10 March 2026 19:16:41 +0000 (0:00:00.391) 0:01:10.507 ********* 2026-03-10 19:16:41.053126 | controller | ok: [instance] 2026-03-10 19:16:41.070783 | controller | 2026-03-10 19:16:41.070805 | controller | TASK [libvirt_manager : Unset patch] ******************************************* 2026-03-10 19:16:41.070815 | controller | Tuesday 10 March 2026 19:16:41 +0000 (0:00:00.026) 0:01:10.533 ********* 2026-03-10 19:16:41.070826 | controller | ok: [instance] 2026-03-10 19:16:41.117743 | controller | 2026-03-10 19:16:41.117765 | controller | TASK [libvirt_manager : Initialize empty _lm_dhcp_entries fact] **************** 2026-03-10 19:16:41.117774 | controller | Tuesday 10 March 2026 19:16:41 +0000 (0:00:00.017) 0:01:10.551 ********* 2026-03-10 19:16:41.117786 | controller | ok: [instance] 2026-03-10 19:16:41.190825 | controller | 2026-03-10 19:16:41.190857 | controller | TASK [libvirt_manager : Reserve IPs on networks] ******************************* 2026-03-10 19:16:41.190866 | controller | Tuesday 10 March 2026 19:16:41 +0000 (0:00:00.047) 0:01:10.598 ********* 2026-03-10 19:16:41.190876 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/reserve_dnsmasq_ips.yml for instance => (item=ctlplane) 2026-03-10 19:16:41.190902 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/reserve_dnsmasq_ips.yml for instance => (item=internalapi) 2026-03-10 19:16:41.191373 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/reserve_dnsmasq_ips.yml for instance => (item=storage) 2026-03-10 19:16:41.191713 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/reserve_dnsmasq_ips.yml for instance => (item=tenant) 2026-03-10 19:16:41.191730 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/reserve_dnsmasq_ips.yml for instance => (item=external) 2026-03-10 19:16:41.191737 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/reserve_dnsmasq_ips.yml for instance => (item=storagemgmt) 2026-03-10 19:16:41.191746 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/reserve_dnsmasq_ips.yml for instance => (item=public) 2026-03-10 19:16:41.332071 | controller | 2026-03-10 19:16:41.332095 | controller | TASK [libvirt_manager : Loop on host IPs] ************************************** 2026-03-10 19:16:41.332105 | controller | Tuesday 10 March 2026 19:16:41 +0000 (0:00:00.073) 0:01:10.671 ********* 2026-03-10 19:16:41.332116 | controller | ok: [instance] => (item=compute-0 - ctlplane) 2026-03-10 19:16:41.412079 | controller | ok: [instance] => (item=controller-0 - ctlplane) 2026-03-10 19:16:41.412111 | controller | ok: [instance] => (item=crc-0 - ctlplane) 2026-03-10 19:16:41.412125 | controller | 2026-03-10 19:16:41.412136 | controller | TASK [libvirt_manager : Loop on host IPs] ************************************** 2026-03-10 19:16:41.412146 | controller | Tuesday 10 March 2026 19:16:41 +0000 (0:00:00.140) 0:01:10.812 ********* 2026-03-10 19:16:41.412161 | controller | skipping: [instance] => (item=compute-0 - internalapi) 2026-03-10 19:16:41.412479 | controller | skipping: [instance] => (item=controller-0 - internalapi) 2026-03-10 19:16:41.412706 | controller | skipping: [instance] => (item=crc-0 - internalapi) 2026-03-10 19:16:41.412822 | controller | skipping: [instance] 2026-03-10 19:16:41.412931 | controller | 2026-03-10 19:16:41.413045 | controller | TASK [libvirt_manager : Loop on host IPs] ************************************** 2026-03-10 19:16:41.413164 | controller | Tuesday 10 March 2026 19:16:41 +0000 (0:00:00.080) 0:01:10.892 ********* 2026-03-10 19:16:41.492679 | controller | skipping: [instance] => (item=compute-0 - storage) 2026-03-10 19:16:41.492931 | controller | skipping: [instance] => (item=controller-0 - storage) 2026-03-10 19:16:41.493166 | controller | skipping: [instance] => (item=crc-0 - storage) 2026-03-10 19:16:41.493457 | controller | skipping: [instance] 2026-03-10 19:16:41.493686 | controller | 2026-03-10 19:16:41.493850 | controller | TASK [libvirt_manager : Loop on host IPs] ************************************** 2026-03-10 19:16:41.493975 | controller | Tuesday 10 March 2026 19:16:41 +0000 (0:00:00.080) 0:01:10.973 ********* 2026-03-10 19:16:41.572316 | controller | skipping: [instance] => (item=compute-0 - tenant) 2026-03-10 19:16:41.572471 | controller | skipping: [instance] => (item=controller-0 - tenant) 2026-03-10 19:16:41.572602 | controller | skipping: [instance] => (item=crc-0 - tenant) 2026-03-10 19:16:41.572728 | controller | skipping: [instance] 2026-03-10 19:16:41.572847 | controller | 2026-03-10 19:16:41.572975 | controller | TASK [libvirt_manager : Loop on host IPs] ************************************** 2026-03-10 19:16:41.573087 | controller | Tuesday 10 March 2026 19:16:41 +0000 (0:00:00.079) 0:01:11.053 ********* 2026-03-10 19:16:41.652942 | controller | skipping: [instance] => (item=compute-0 - external) 2026-03-10 19:16:41.653234 | controller | skipping: [instance] => (item=controller-0 - external) 2026-03-10 19:16:41.653471 | controller | skipping: [instance] => (item=crc-0 - external) 2026-03-10 19:16:41.653605 | controller | skipping: [instance] 2026-03-10 19:16:41.653726 | controller | 2026-03-10 19:16:41.653852 | controller | TASK [libvirt_manager : Loop on host IPs] ************************************** 2026-03-10 19:16:41.653973 | controller | Tuesday 10 March 2026 19:16:41 +0000 (0:00:00.080) 0:01:11.133 ********* 2026-03-10 19:16:41.731370 | controller | skipping: [instance] => (item=compute-0 - storagemgmt) 2026-03-10 19:16:41.731646 | controller | skipping: [instance] => (item=controller-0 - storagemgmt) 2026-03-10 19:16:41.731879 | controller | skipping: [instance] => (item=crc-0 - storagemgmt) 2026-03-10 19:16:41.732102 | controller | skipping: [instance] 2026-03-10 19:16:41.732339 | controller | 2026-03-10 19:16:41.732471 | controller | TASK [libvirt_manager : Loop on host IPs] ************************************** 2026-03-10 19:16:41.732584 | controller | Tuesday 10 March 2026 19:16:41 +0000 (0:00:00.078) 0:01:11.212 ********* 2026-03-10 19:16:41.862948 | controller | ok: [instance] => (item=compute-0 - public) 2026-03-10 19:16:41.863229 | controller | ok: [instance] => (item=controller-0 - public) 2026-03-10 19:16:41.863523 | controller | ok: [instance] => (item=crc-0 - public) 2026-03-10 19:16:41.863638 | controller | 2026-03-10 19:16:41.863753 | controller | TASK [Inject DHCP entries for net {{ net_name }}] ****************************** 2026-03-10 19:16:41.863882 | controller | Tuesday 10 March 2026 19:16:41 +0000 (0:00:00.131) 0:01:11.343 ********* 2026-03-10 19:16:41.968682 | controller | 2026-03-10 19:16:41.968954 | controller | TASK [dnsmasq : Ensure we have the right data and type] ************************ 2026-03-10 19:16:41.969188 | controller | Tuesday 10 March 2026 19:16:41 +0000 (0:00:00.105) 0:01:11.449 ********* 2026-03-10 19:16:42.007011 | controller | ok: [instance] 2026-03-10 19:16:42.007316 | controller | 2026-03-10 19:16:42.007588 | controller | TASK [dnsmasq : Assert we have needed host data] ******************************* 2026-03-10 19:16:42.007814 | controller | Tuesday 10 March 2026 19:16:42 +0000 (0:00:00.038) 0:01:11.487 ********* 2026-03-10 19:16:42.100651 | controller | ok: [instance] => (item={'network': 'osp_trunk', 'name': 'compute-0', 'state': 'present', 'mac': '52:54:00:c5:97:0f', 'ips': ['192.168.140.100', ''], 'tag': 'compute'}) 2026-03-10 19:16:42.100924 | controller | ok: [instance] => (item={'network': 'osp_trunk', 'name': 'controller-0', 'state': 'present', 'mac': '52:54:01:3e:fd:9b', 'ips': ['192.168.140.9', ''], 'tag': 'controller'}) 2026-03-10 19:16:42.101059 | controller | ok: [instance] => (item={'network': 'osp_trunk', 'name': 'crc-0', 'state': 'present', 'mac': '52:54:02:83:2c:40', 'ips': ['192.168.140.10', ''], 'tag': 'ocp'}) 2026-03-10 19:16:42.101188 | controller | ok: [instance] => (item={'network': 'public', 'name': 'compute-0', 'state': 'present', 'mac': '52:54:00:23:e3:f3', 'ips': ['192.168.101.100', ''], 'tag': 'compute'}) 2026-03-10 19:16:42.101357 | controller | ok: [instance] => (item={'network': 'public', 'name': 'controller-0', 'state': 'present', 'mac': '52:54:01:9e:4b:96', 'ips': ['192.168.101.9', ''], 'tag': 'controller'}) 2026-03-10 19:16:42.101491 | controller | ok: [instance] => (item={'network': 'public', 'name': 'crc-0', 'state': 'present', 'mac': '52:54:02:3c:ea:a3', 'ips': ['192.168.101.10', ''], 'tag': 'ocp'}) 2026-03-10 19:16:42.101607 | controller | 2026-03-10 19:16:42.101730 | controller | TASK [dnsmasq : Ensure networks exists] **************************************** 2026-03-10 19:16:42.101859 | controller | Tuesday 10 March 2026 19:16:42 +0000 (0:00:00.093) 0:01:11.581 ********* 2026-03-10 19:16:42.150323 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/_check_net_status.yml for instance => (item=osp_trunk) 2026-03-10 19:16:42.150501 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/_check_net_status.yml for instance => (item=public) 2026-03-10 19:16:42.150641 | controller | 2026-03-10 19:16:42.150788 | controller | TASK [dnsmasq : Check network file status] ************************************* 2026-03-10 19:16:42.150934 | controller | Tuesday 10 March 2026 19:16:42 +0000 (0:00:00.049) 0:01:11.631 ********* 2026-03-10 19:16:42.325113 | controller | ok: [instance] 2026-03-10 19:16:42.350061 | controller | 2026-03-10 19:16:42.350077 | controller | TASK [dnsmasq : Assert network exists] ***************************************** 2026-03-10 19:16:42.350085 | controller | Tuesday 10 March 2026 19:16:42 +0000 (0:00:00.174) 0:01:11.805 ********* 2026-03-10 19:16:42.350093 | controller | ok: [instance] 2026-03-10 19:16:42.528913 | controller | 2026-03-10 19:16:42.528937 | controller | TASK [dnsmasq : Check network file status] ************************************* 2026-03-10 19:16:42.528945 | controller | Tuesday 10 March 2026 19:16:42 +0000 (0:00:00.025) 0:01:11.830 ********* 2026-03-10 19:16:42.528954 | controller | ok: [instance] 2026-03-10 19:16:42.552385 | controller | 2026-03-10 19:16:42.552419 | controller | TASK [dnsmasq : Assert network exists] ***************************************** 2026-03-10 19:16:42.552429 | controller | Tuesday 10 March 2026 19:16:42 +0000 (0:00:00.178) 0:01:12.009 ********* 2026-03-10 19:16:42.552442 | controller | ok: [instance] 2026-03-10 19:16:42.572145 | controller | 2026-03-10 19:16:42.572169 | controller | TASK [dnsmasq : Initialize empty dhcp_host_entries] **************************** 2026-03-10 19:16:42.572179 | controller | Tuesday 10 March 2026 19:16:42 +0000 (0:00:00.023) 0:01:12.032 ********* 2026-03-10 19:16:42.572191 | controller | ok: [instance] 2026-03-10 19:16:42.752363 | controller | 2026-03-10 19:16:42.752402 | controller | TASK [dnsmasq : Compute entry] ************************************************* 2026-03-10 19:16:42.752412 | controller | Tuesday 10 March 2026 19:16:42 +0000 (0:00:00.019) 0:01:12.052 ********* 2026-03-10 19:16:42.752425 | controller | ok: [instance] => (item={'network': 'osp_trunk', 'name': 'compute-0', 'state': 'present', 'mac': '52:54:00:c5:97:0f', 'ips': ['192.168.140.100', ''], 'tag': 'compute'}) 2026-03-10 19:16:42.752458 | controller | ok: [instance] => (item={'network': 'osp_trunk', 'name': 'controller-0', 'state': 'present', 'mac': '52:54:01:3e:fd:9b', 'ips': ['192.168.140.9', ''], 'tag': 'controller'}) 2026-03-10 19:16:42.752468 | controller | ok: [instance] => (item={'network': 'osp_trunk', 'name': 'crc-0', 'state': 'present', 'mac': '52:54:02:83:2c:40', 'ips': ['192.168.140.10', ''], 'tag': 'ocp'}) 2026-03-10 19:16:42.752496 | controller | ok: [instance] => (item={'network': 'public', 'name': 'compute-0', 'state': 'present', 'mac': '52:54:00:23:e3:f3', 'ips': ['192.168.101.100', ''], 'tag': 'compute'}) 2026-03-10 19:16:42.752936 | controller | ok: [instance] => (item={'network': 'public', 'name': 'controller-0', 'state': 'present', 'mac': '52:54:01:9e:4b:96', 'ips': ['192.168.101.9', ''], 'tag': 'controller'}) 2026-03-10 19:16:42.791209 | controller | ok: [instance] => (item={'network': 'public', 'name': 'crc-0', 'state': 'present', 'mac': '52:54:02:3c:ea:a3', 'ips': ['192.168.101.10', ''], 'tag': 'ocp'}) 2026-03-10 19:16:42.791242 | controller | 2026-03-10 19:16:42.791277 | controller | TASK [dnsmasq : Create add/remove sets] **************************************** 2026-03-10 19:16:42.791294 | controller | Tuesday 10 March 2026 19:16:42 +0000 (0:00:00.180) 0:01:12.232 ********* 2026-03-10 19:16:42.791307 | controller | ok: [instance] 2026-03-10 19:16:44.821160 | controller | 2026-03-10 19:16:44.821190 | controller | TASK [dnsmasq : Add DHCP entries] ********************************************** 2026-03-10 19:16:44.821198 | controller | Tuesday 10 March 2026 19:16:42 +0000 (0:00:00.038) 0:01:12.271 ********* 2026-03-10 19:16:44.821208 | controller | changed: [instance] => (item={'file': 'osp_trunk_compute-0_52:54:00:c5:97:0f', 'entry': 'set:compute,52:54:00:c5:97:0f,192.168.140.100,compute-0', 'state': 'present'}) 2026-03-10 19:16:44.845155 | controller | changed: [instance] => (item={'file': 'osp_trunk_controller-0_52:54:01:3e:fd:9b', 'entry': 'set:controller,52:54:01:3e:fd:9b,192.168.140.9,controller-0', 'state': 'present'}) 2026-03-10 19:16:44.845170 | controller | changed: [instance] => (item={'file': 'osp_trunk_crc-0_52:54:02:83:2c:40', 'entry': 'set:ocp,52:54:02:83:2c:40,192.168.140.10,crc-0', 'state': 'present'}) 2026-03-10 19:16:44.845177 | controller | changed: [instance] => (item={'file': 'public_compute-0_52:54:00:23:e3:f3', 'entry': 'set:compute,52:54:00:23:e3:f3,192.168.101.100,compute-0', 'state': 'present'}) 2026-03-10 19:16:44.845182 | controller | changed: [instance] => (item={'file': 'public_controller-0_52:54:01:9e:4b:96', 'entry': 'set:controller,52:54:01:9e:4b:96,192.168.101.9,controller-0', 'state': 'present'}) 2026-03-10 19:16:44.845188 | controller | changed: [instance] => (item={'file': 'public_crc-0_52:54:02:3c:ea:a3', 'entry': 'set:ocp,52:54:02:3c:ea:a3,192.168.101.10,crc-0', 'state': 'present'}) 2026-03-10 19:16:44.845193 | controller | 2026-03-10 19:16:44.845198 | controller | TASK [dnsmasq : Remove DHCP entries] ******************************************* 2026-03-10 19:16:44.845203 | controller | Tuesday 10 March 2026 19:16:44 +0000 (0:00:02.029) 0:01:14.301 ********* 2026-03-10 19:16:44.845211 | controller | skipping: [instance] 2026-03-10 19:16:45.051073 | controller | 2026-03-10 19:16:45.051113 | controller | TASK [libvirt_manager : Create per-network and .utility DNS entries] *********** 2026-03-10 19:16:45.051130 | controller | Tuesday 10 March 2026 19:16:44 +0000 (0:00:00.024) 0:01:14.325 ********* 2026-03-10 19:16:45.051144 | controller | ok: [instance] => (item=compute-0) 2026-03-10 19:16:45.164918 | controller | ok: [instance] => (item=controller-0) 2026-03-10 19:16:45.164942 | controller | ok: [instance] => (item=crc-0) 2026-03-10 19:16:45.164949 | controller | 2026-03-10 19:16:45.164955 | controller | TASK [Inject VMs in the .utility zone] ***************************************** 2026-03-10 19:16:45.164960 | controller | Tuesday 10 March 2026 19:16:45 +0000 (0:00:00.205) 0:01:14.531 ********* 2026-03-10 19:16:45.164969 | controller | 2026-03-10 19:16:45.205459 | controller | TASK [dnsmasq : Assert we have needed host record data] ************************ 2026-03-10 19:16:45.205496 | controller | Tuesday 10 March 2026 19:16:45 +0000 (0:00:00.114) 0:01:14.645 ********* 2026-03-10 19:16:45.205515 | controller | ok: [instance] 2026-03-10 19:16:45.205835 | controller | 2026-03-10 19:16:45.205855 | controller | TASK [dnsmasq : Assert each address element have needed data] ****************** 2026-03-10 19:16:45.400556 | controller | Tuesday 10 March 2026 19:16:45 +0000 (0:00:00.040) 0:01:14.685 ********* 2026-03-10 19:16:45.400600 | controller | ok: [instance] => (item={'names': ['compute-0.utility', 'compute-0.instance', 'compute-0.utility', 'compute-0.instance'], 'ips': ['192.168.140.100', ''], 'state': 'present'}) 2026-03-10 19:16:45.400749 | controller | ok: [instance] => (item={'names': ['compute-0.ctlplane.crc.testing', 'compute-0.ctlplane.crc.testing'], 'ips': ['192.168.140.100', ''], 'state': 'present'}) 2026-03-10 19:16:45.400777 | controller | ok: [instance] => (item={'names': ['compute-0.internalapi.crc.testing', 'compute-0.internalapi.crc.testing'], 'ips': ['172.17.0.100', ''], 'state': 'present'}) 2026-03-10 19:16:45.400787 | controller | ok: [instance] => (item={'names': ['compute-0.public.crc.testing', 'compute-0.public.crc.testing'], 'ips': ['192.168.101.100', ''], 'state': 'present'}) 2026-03-10 19:16:45.400794 | controller | ok: [instance] => (item={'names': ['compute-0.storage.crc.testing', 'compute-0.storage.crc.testing'], 'ips': ['172.18.0.100', ''], 'state': 'present'}) 2026-03-10 19:16:45.400801 | controller | ok: [instance] => (item={'names': ['compute-0.storagemgmt.crc.testing', 'compute-0.storagemgmt.crc.testing'], 'ips': ['172.20.0.100', ''], 'state': 'present'}) 2026-03-10 19:16:45.400814 | controller | ok: [instance] => (item={'names': ['compute-0.tenant.crc.testing', 'compute-0.tenant.crc.testing'], 'ips': ['172.19.0.100', ''], 'state': 'present'}) 2026-03-10 19:16:45.400829 | controller | ok: [instance] => (item={'names': ['controller-0.utility', 'controller-0.instance', 'controller-0.utility', 'controller-0.instance'], 'ips': ['192.168.140.9', ''], 'state': 'present'}) 2026-03-10 19:16:45.400837 | controller | ok: [instance] => (item={'names': ['controller-0.ctlplane.crc.testing', 'controller-0.ctlplane.crc.testing'], 'ips': ['192.168.140.9', ''], 'state': 'present'}) 2026-03-10 19:16:45.400847 | controller | ok: [instance] => (item={'names': ['controller-0.public.crc.testing', 'controller-0.public.crc.testing'], 'ips': ['192.168.101.9', ''], 'state': 'present'}) 2026-03-10 19:16:45.400857 | controller | ok: [instance] => (item={'names': ['crc-0.utility', 'crc-0.instance', 'ocp-0.utility', 'ocp-0.instance'], 'ips': ['192.168.140.10', ''], 'state': 'present'}) 2026-03-10 19:16:45.400863 | controller | ok: [instance] => (item={'names': ['crc-0.ctlplane.crc.testing', 'ocp-0.ctlplane.crc.testing'], 'ips': ['192.168.140.10', ''], 'state': 'present'}) 2026-03-10 19:16:45.400870 | controller | ok: [instance] => (item={'names': ['crc-0.internalapi.crc.testing', 'ocp-0.internalapi.crc.testing'], 'ips': ['172.17.0.10', ''], 'state': 'present'}) 2026-03-10 19:16:45.400877 | controller | ok: [instance] => (item={'names': ['crc-0.public.crc.testing', 'ocp-0.public.crc.testing'], 'ips': ['192.168.101.10', ''], 'state': 'present'}) 2026-03-10 19:16:45.400884 | controller | ok: [instance] => (item={'names': ['crc-0.storage.crc.testing', 'ocp-0.storage.crc.testing'], 'ips': ['172.18.0.10', ''], 'state': 'present'}) 2026-03-10 19:16:45.400896 | controller | ok: [instance] => (item={'names': ['crc-0.tenant.crc.testing', 'ocp-0.tenant.crc.testing'], 'ips': ['172.19.0.10', ''], 'state': 'present'}) 2026-03-10 19:16:45.400917 | controller | 2026-03-10 19:16:45.400928 | controller | TASK [dnsmasq : Add/Remove address] ******************************************** 2026-03-10 19:16:45.401034 | controller | Tuesday 10 March 2026 19:16:45 +0000 (0:00:00.194) 0:01:14.880 ********* 2026-03-10 19:16:48.475359 | controller | changed: [instance] => (item={'names': ['compute-0.utility', 'compute-0.instance', 'compute-0.utility', 'compute-0.instance'], 'ips': ['192.168.140.100', ''], 'state': 'present'}) 2026-03-10 19:16:48.475411 | controller | changed: [instance] => (item={'names': ['compute-0.ctlplane.crc.testing', 'compute-0.ctlplane.crc.testing'], 'ips': ['192.168.140.100', ''], 'state': 'present'}) 2026-03-10 19:16:48.475421 | controller | changed: [instance] => (item={'names': ['compute-0.internalapi.crc.testing', 'compute-0.internalapi.crc.testing'], 'ips': ['172.17.0.100', ''], 'state': 'present'}) 2026-03-10 19:16:48.475428 | controller | changed: [instance] => (item={'names': ['compute-0.public.crc.testing', 'compute-0.public.crc.testing'], 'ips': ['192.168.101.100', ''], 'state': 'present'}) 2026-03-10 19:16:48.475434 | controller | changed: [instance] => (item={'names': ['compute-0.storage.crc.testing', 'compute-0.storage.crc.testing'], 'ips': ['172.18.0.100', ''], 'state': 'present'}) 2026-03-10 19:16:48.475441 | controller | changed: [instance] => (item={'names': ['compute-0.storagemgmt.crc.testing', 'compute-0.storagemgmt.crc.testing'], 'ips': ['172.20.0.100', ''], 'state': 'present'}) 2026-03-10 19:16:48.475447 | controller | changed: [instance] => (item={'names': ['compute-0.tenant.crc.testing', 'compute-0.tenant.crc.testing'], 'ips': ['172.19.0.100', ''], 'state': 'present'}) 2026-03-10 19:16:48.475454 | controller | changed: [instance] => (item={'names': ['controller-0.utility', 'controller-0.instance', 'controller-0.utility', 'controller-0.instance'], 'ips': ['192.168.140.9', ''], 'state': 'present'}) 2026-03-10 19:16:48.475460 | controller | changed: [instance] => (item={'names': ['controller-0.ctlplane.crc.testing', 'controller-0.ctlplane.crc.testing'], 'ips': ['192.168.140.9', ''], 'state': 'present'}) 2026-03-10 19:16:48.475469 | controller | changed: [instance] => (item={'names': ['controller-0.public.crc.testing', 'controller-0.public.crc.testing'], 'ips': ['192.168.101.9', ''], 'state': 'present'}) 2026-03-10 19:16:48.475500 | controller | changed: [instance] => (item={'names': ['crc-0.utility', 'crc-0.instance', 'ocp-0.utility', 'ocp-0.instance'], 'ips': ['192.168.140.10', ''], 'state': 'present'}) 2026-03-10 19:16:48.476209 | controller | changed: [instance] => (item={'names': ['crc-0.ctlplane.crc.testing', 'ocp-0.ctlplane.crc.testing'], 'ips': ['192.168.140.10', ''], 'state': 'present'}) 2026-03-10 19:16:48.813341 | controller | changed: [instance] => (item={'names': ['crc-0.internalapi.crc.testing', 'ocp-0.internalapi.crc.testing'], 'ips': ['172.17.0.10', ''], 'state': 'present'}) 2026-03-10 19:16:48.813373 | controller | changed: [instance] => (item={'names': ['crc-0.public.crc.testing', 'ocp-0.public.crc.testing'], 'ips': ['192.168.101.10', ''], 'state': 'present'}) 2026-03-10 19:16:48.813380 | controller | changed: [instance] => (item={'names': ['crc-0.storage.crc.testing', 'ocp-0.storage.crc.testing'], 'ips': ['172.18.0.10', ''], 'state': 'present'}) 2026-03-10 19:16:48.813386 | controller | changed: [instance] => (item={'names': ['crc-0.tenant.crc.testing', 'ocp-0.tenant.crc.testing'], 'ips': ['172.19.0.10', ''], 'state': 'present'}) 2026-03-10 19:16:48.813392 | controller | 2026-03-10 19:16:48.813398 | controller | TASK [dnsmasq : Add controller-0 short hostname record when 'utility' in the name into /etc/hosts] *** 2026-03-10 19:16:48.813404 | controller | Tuesday 10 March 2026 19:16:48 +0000 (0:00:03.074) 0:01:17.955 ********* 2026-03-10 19:16:48.813414 | controller | skipping: [instance] => (item={'names': ['compute-0.utility', 'compute-0.instance', 'compute-0.utility', 'compute-0.instance'], 'ips': ['192.168.140.100', ''], 'state': 'present'}) 2026-03-10 19:16:48.814469 | controller | skipping: [instance] => (item={'names': ['compute-0.ctlplane.crc.testing', 'compute-0.ctlplane.crc.testing'], 'ips': ['192.168.140.100', ''], 'state': 'present'}) 2026-03-10 19:16:48.814522 | controller | skipping: [instance] => (item={'names': ['compute-0.internalapi.crc.testing', 'compute-0.internalapi.crc.testing'], 'ips': ['172.17.0.100', ''], 'state': 'present'}) 2026-03-10 19:16:48.814536 | controller | skipping: [instance] => (item={'names': ['compute-0.public.crc.testing', 'compute-0.public.crc.testing'], 'ips': ['192.168.101.100', ''], 'state': 'present'}) 2026-03-10 19:16:48.814547 | controller | skipping: [instance] => (item={'names': ['compute-0.storage.crc.testing', 'compute-0.storage.crc.testing'], 'ips': ['172.18.0.100', ''], 'state': 'present'}) 2026-03-10 19:16:48.814566 | controller | skipping: [instance] => (item={'names': ['compute-0.storagemgmt.crc.testing', 'compute-0.storagemgmt.crc.testing'], 'ips': ['172.20.0.100', ''], 'state': 'present'}) 2026-03-10 19:16:48.814630 | controller | skipping: [instance] => (item={'names': ['compute-0.tenant.crc.testing', 'compute-0.tenant.crc.testing'], 'ips': ['172.19.0.100', ''], 'state': 'present'}) 2026-03-10 19:16:48.814645 | controller | changed: [instance] => (item={'names': ['controller-0.utility', 'controller-0.instance', 'controller-0.utility', 'controller-0.instance'], 'ips': ['192.168.140.9', ''], 'state': 'present'}) 2026-03-10 19:16:48.814656 | controller | skipping: [instance] => (item={'names': ['controller-0.ctlplane.crc.testing', 'controller-0.ctlplane.crc.testing'], 'ips': ['192.168.140.9', ''], 'state': 'present'}) 2026-03-10 19:16:48.814666 | controller | skipping: [instance] => (item={'names': ['controller-0.public.crc.testing', 'controller-0.public.crc.testing'], 'ips': ['192.168.101.9', ''], 'state': 'present'}) 2026-03-10 19:16:48.814675 | controller | skipping: [instance] => (item={'names': ['crc-0.utility', 'crc-0.instance', 'ocp-0.utility', 'ocp-0.instance'], 'ips': ['192.168.140.10', ''], 'state': 'present'}) 2026-03-10 19:16:48.814685 | controller | skipping: [instance] => (item={'names': ['crc-0.ctlplane.crc.testing', 'ocp-0.ctlplane.crc.testing'], 'ips': ['192.168.140.10', ''], 'state': 'present'}) 2026-03-10 19:16:48.814695 | controller | skipping: [instance] => (item={'names': ['crc-0.internalapi.crc.testing', 'ocp-0.internalapi.crc.testing'], 'ips': ['172.17.0.10', ''], 'state': 'present'}) 2026-03-10 19:16:48.814705 | controller | skipping: [instance] => (item={'names': ['crc-0.public.crc.testing', 'ocp-0.public.crc.testing'], 'ips': ['192.168.101.10', ''], 'state': 'present'}) 2026-03-10 19:16:48.814715 | controller | skipping: [instance] => (item={'names': ['crc-0.storage.crc.testing', 'ocp-0.storage.crc.testing'], 'ips': ['172.18.0.10', ''], 'state': 'present'}) 2026-03-10 19:16:48.814731 | controller | skipping: [instance] => (item={'names': ['crc-0.tenant.crc.testing', 'ocp-0.tenant.crc.testing'], 'ips': ['172.19.0.10', ''], 'state': 'present'}) 2026-03-10 19:16:48.814748 | controller | 2026-03-10 19:16:48.814763 | controller | TASK [libvirt_manager : Initialize empty _lm_dhcp_options fact] **************** 2026-03-10 19:16:48.831370 | controller | Tuesday 10 March 2026 19:16:48 +0000 (0:00:00.338) 0:01:18.293 ********* 2026-03-10 19:16:48.831407 | controller | ok: [instance] 2026-03-10 19:16:48.831541 | controller | 2026-03-10 19:16:48.831573 | controller | TASK [libvirt_manager : Collect DHCP options from VM definitions] ************** 2026-03-10 19:16:48.831593 | controller | Tuesday 10 March 2026 19:16:48 +0000 (0:00:00.018) 0:01:18.311 ********* 2026-03-10 19:16:48.875451 | controller | skipping: [instance] => (item=compute) 2026-03-10 19:16:48.875521 | controller | skipping: [instance] => (item=controller) 2026-03-10 19:16:48.875537 | controller | skipping: [instance] => (item=crc) 2026-03-10 19:16:48.875549 | controller | skipping: [instance] 2026-03-10 19:16:48.875560 | controller | 2026-03-10 19:16:48.875574 | controller | TASK [libvirt_manager : Generate DHCP option configuration for VM types] ******* 2026-03-10 19:16:48.875614 | controller | Tuesday 10 March 2026 19:16:48 +0000 (0:00:00.044) 0:01:18.355 ********* 2026-03-10 19:16:48.906644 | controller | skipping: [instance] 2026-03-10 19:16:48.906821 | controller | 2026-03-10 19:16:48.907056 | controller | TASK [libvirt_manager : Ensure some domains are local] ************************* 2026-03-10 19:16:48.907092 | controller | Tuesday 10 March 2026 19:16:48 +0000 (0:00:00.031) 0:01:18.387 ********* 2026-03-10 19:16:49.273066 | controller | changed: [instance] 2026-03-10 19:16:49.893890 | controller | 2026-03-10 19:16:49.893923 | controller | TASK [libvirt_manager : Ensure dnsmasq is reloaded now] ************************ 2026-03-10 19:16:49.893931 | controller | Tuesday 10 March 2026 19:16:49 +0000 (0:00:00.354) 0:01:18.741 ********* 2026-03-10 19:16:49.893937 | controller | 2026-03-10 19:16:49.893942 | controller | RUNNING HANDLER [libvirt_manager : Restart firewalld] ************************** 2026-03-10 19:16:49.893947 | controller | Tuesday 10 March 2026 19:16:49 +0000 (0:00:00.011) 0:01:18.753 ********* 2026-03-10 19:16:49.893957 | controller | changed: [instance] 2026-03-10 19:16:50.229936 | controller | 2026-03-10 19:16:50.229967 | controller | RUNNING HANDLER [dnsmasq : Restart dnsmasq] ************************************ 2026-03-10 19:16:50.229975 | controller | Tuesday 10 March 2026 19:16:49 +0000 (0:00:00.621) 0:01:19.374 ********* 2026-03-10 19:16:50.229985 | controller | changed: [instance] 2026-03-10 19:16:50.573374 | controller | 2026-03-10 19:16:50.573412 | controller | RUNNING HANDLER [dnsmasq : Reload dnsmasq] ************************************* 2026-03-10 19:16:50.573423 | controller | Tuesday 10 March 2026 19:16:50 +0000 (0:00:00.336) 0:01:19.710 ********* 2026-03-10 19:16:50.573435 | controller | changed: [instance] 2026-03-10 19:16:50.573466 | controller | 2026-03-10 19:16:50.573617 | controller | TASK [reproducer : Delegate our domains to our own instance] ******************* 2026-03-10 19:16:50.928850 | controller | Tuesday 10 March 2026 19:16:50 +0000 (0:00:00.343) 0:01:20.053 ********* 2026-03-10 19:16:50.928887 | controller | changed: [instance] 2026-03-10 19:16:51.290767 | controller | 2026-03-10 19:16:51.290800 | controller | TASK [reproducer : Ensure "utility" domain is local] *************************** 2026-03-10 19:16:51.290809 | controller | Tuesday 10 March 2026 19:16:50 +0000 (0:00:00.355) 0:01:20.409 ********* 2026-03-10 19:16:51.290821 | controller | changed: [instance] 2026-03-10 19:16:51.324655 | controller | 2026-03-10 19:16:51.324681 | controller | TASK [Build utility records dataset] ******************************************* 2026-03-10 19:16:51.324690 | controller | Tuesday 10 March 2026 19:16:51 +0000 (0:00:00.361) 0:01:20.771 ********* 2026-03-10 19:16:51.324709 | controller | 2026-03-10 19:16:51.413459 | controller | TASK [dnsmasq : Assert we have needed host record data] ************************ 2026-03-10 19:16:51.413495 | controller | Tuesday 10 March 2026 19:16:51 +0000 (0:00:00.034) 0:01:20.805 ********* 2026-03-10 19:16:51.413509 | controller | ok: [instance] 2026-03-10 19:16:51.413529 | controller | 2026-03-10 19:16:51.413538 | controller | TASK [dnsmasq : Assert each address element have needed data] ****************** 2026-03-10 19:16:51.413614 | controller | Tuesday 10 March 2026 19:16:51 +0000 (0:00:00.088) 0:01:20.894 ********* 2026-03-10 19:16:51.507832 | controller | ok: [instance] => (item={'names': ['ipmi.utility', 'instance.utility', 'virthost.ocp.openstack.lab'], 'ips': ['192.168.101.1', ''], 'state': 'present'}) 2026-03-10 19:16:51.962418 | controller | ok: [instance] => (item={'names': ['sushy.utility', 'controller-0.utility'], 'ips': ['192.168.140.9', ''], 'state': 'present'}) 2026-03-10 19:16:51.962454 | controller | 2026-03-10 19:16:51.962464 | controller | TASK [dnsmasq : Add/Remove address] ******************************************** 2026-03-10 19:16:51.962471 | controller | Tuesday 10 March 2026 19:16:51 +0000 (0:00:00.094) 0:01:20.988 ********* 2026-03-10 19:16:51.962483 | controller | changed: [instance] => (item={'names': ['ipmi.utility', 'instance.utility', 'virthost.ocp.openstack.lab'], 'ips': ['192.168.101.1', ''], 'state': 'present'}) 2026-03-10 19:16:51.962514 | controller | changed: [instance] => (item={'names': ['sushy.utility', 'controller-0.utility'], 'ips': ['192.168.140.9', ''], 'state': 'present'}) 2026-03-10 19:16:51.962524 | controller | 2026-03-10 19:16:51.962788 | controller | TASK [dnsmasq : Add controller-0 short hostname record when 'utility' in the name into /etc/hosts] *** 2026-03-10 19:16:52.241935 | controller | Tuesday 10 March 2026 19:16:51 +0000 (0:00:00.454) 0:01:21.442 ********* 2026-03-10 19:16:52.241956 | controller | skipping: [instance] => (item={'names': ['ipmi.utility', 'instance.utility', 'virthost.ocp.openstack.lab'], 'ips': ['192.168.101.1', ''], 'state': 'present'}) 2026-03-10 19:16:52.706313 | controller | changed: [instance] => (item={'names': ['sushy.utility', 'controller-0.utility'], 'ips': ['192.168.140.9', ''], 'state': 'present'}) 2026-03-10 19:16:52.706357 | controller | 2026-03-10 19:16:52.706366 | controller | TASK [reproducer : Flush handlers] ********************************************* 2026-03-10 19:16:52.706372 | controller | Tuesday 10 March 2026 19:16:52 +0000 (0:00:00.269) 0:01:21.712 ********* 2026-03-10 19:16:52.706378 | controller | 2026-03-10 19:16:52.706384 | controller | RUNNING HANDLER [reproducer : Restart NetworkManager] ************************** 2026-03-10 19:16:52.706389 | controller | Tuesday 10 March 2026 19:16:52 +0000 (0:00:00.010) 0:01:21.722 ********* 2026-03-10 19:16:52.706401 | controller | changed: [instance] 2026-03-10 19:16:53.079332 | controller | 2026-03-10 19:16:53.079365 | controller | RUNNING HANDLER [dnsmasq : Restart dnsmasq] ************************************ 2026-03-10 19:16:53.079373 | controller | Tuesday 10 March 2026 19:16:52 +0000 (0:00:00.464) 0:01:22.186 ********* 2026-03-10 19:16:53.079384 | controller | changed: [instance] 2026-03-10 19:16:53.079408 | controller | 2026-03-10 19:16:53.079629 | controller | TASK [reproducer : Bootstrap nat64 if needed] ********************************** 2026-03-10 19:16:53.112815 | controller | Tuesday 10 March 2026 19:16:53 +0000 (0:00:00.373) 0:01:22.559 ********* 2026-03-10 19:16:53.112835 | controller | skipping: [instance] 2026-03-10 19:16:53.185497 | controller | 2026-03-10 19:16:53.185544 | controller | TASK [reproducer : Deploy CRC if needed] *************************************** 2026-03-10 19:16:53.185558 | controller | Tuesday 10 March 2026 19:16:53 +0000 (0:00:00.033) 0:01:22.593 ********* 2026-03-10 19:16:53.185576 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/tasks/crc_layout.yml for instance 2026-03-10 19:16:53.185587 | controller | 2026-03-10 19:16:53.185597 | controller | TASK [reproducer : Check for CRC availability in known path] ******************* 2026-03-10 19:16:53.185610 | controller | Tuesday 10 March 2026 19:16:53 +0000 (0:00:00.072) 0:01:22.665 ********* 2026-03-10 19:16:53.371997 | controller | ok: [instance] 2026-03-10 19:16:53.392710 | controller | 2026-03-10 19:16:53.392733 | controller | TASK [Deploy CRC if needed] **************************************************** 2026-03-10 19:16:53.392742 | controller | Tuesday 10 March 2026 19:16:53 +0000 (0:00:00.186) 0:01:22.852 ********* 2026-03-10 19:16:53.392753 | controller | skipping: [instance] 2026-03-10 19:16:53.734342 | controller | 2026-03-10 19:16:53.734377 | controller | TASK [rhol_crc : Stop CRC VM] ************************************************** 2026-03-10 19:16:53.734384 | controller | Tuesday 10 March 2026 19:16:53 +0000 (0:00:00.020) 0:01:22.873 ********* 2026-03-10 19:16:53.734399 | controller | ok: [instance] 2026-03-10 19:16:53.734423 | controller | 2026-03-10 19:16:53.734429 | controller | TASK [rhol_crc : Undefine CRC VM] ********************************************** 2026-03-10 19:16:53.734436 | controller | Tuesday 10 March 2026 19:16:53 +0000 (0:00:00.341) 0:01:23.214 ********* 2026-03-10 19:16:54.006015 | controller | ok: [instance] 2026-03-10 19:16:54.220772 | controller | 2026-03-10 19:16:54.220804 | controller | TASK [rhol_crc : Destroy CRC network] ****************************************** 2026-03-10 19:16:54.220814 | controller | Tuesday 10 March 2026 19:16:54 +0000 (0:00:00.271) 0:01:23.486 ********* 2026-03-10 19:16:54.220827 | controller | ok: [instance] 2026-03-10 19:16:54.428340 | controller | 2026-03-10 19:16:54.428375 | controller | TASK [rhol_crc : Undefine CRC network] ***************************************** 2026-03-10 19:16:54.428384 | controller | Tuesday 10 March 2026 19:16:54 +0000 (0:00:00.214) 0:01:23.701 ********* 2026-03-10 19:16:54.428394 | controller | ok: [instance] 2026-03-10 19:16:54.428419 | controller | 2026-03-10 19:16:54.428427 | controller | TASK [rhol_crc : Destroy default network] ************************************** 2026-03-10 19:16:54.428577 | controller | Tuesday 10 March 2026 19:16:54 +0000 (0:00:00.207) 0:01:23.908 ********* 2026-03-10 19:16:54.650750 | controller | ok: [instance] 2026-03-10 19:16:54.691776 | controller | 2026-03-10 19:16:54.691818 | controller | TASK [Check if new ssh keypair exists] ***************************************** 2026-03-10 19:16:54.691827 | controller | Tuesday 10 March 2026 19:16:54 +0000 (0:00:00.222) 0:01:24.131 ********* 2026-03-10 19:16:54.691838 | controller | 2026-03-10 19:16:54.872637 | controller | TASK [recognize_ssh_keypair : Check if id_ed25519 key exists] ****************** 2026-03-10 19:16:54.872665 | controller | Tuesday 10 March 2026 19:16:54 +0000 (0:00:00.039) 0:01:24.171 ********* 2026-03-10 19:16:54.872676 | controller | ok: [instance] 2026-03-10 19:16:54.894775 | controller | 2026-03-10 19:16:54.894798 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair exists] ****************** 2026-03-10 19:16:54.894808 | controller | Tuesday 10 March 2026 19:16:54 +0000 (0:00:00.182) 0:01:24.353 ********* 2026-03-10 19:16:54.894819 | controller | ok: [instance] 2026-03-10 19:16:54.920154 | controller | 2026-03-10 19:16:54.920173 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair does not exists] ********* 2026-03-10 19:16:54.920180 | controller | Tuesday 10 March 2026 19:16:54 +0000 (0:00:00.022) 0:01:24.375 ********* 2026-03-10 19:16:54.920189 | controller | skipping: [instance] 2026-03-10 19:16:55.174939 | controller | 2026-03-10 19:16:55.174971 | controller | TASK [reproducer : Slurp ssh key for CRC access] ******************************* 2026-03-10 19:16:55.174980 | controller | Tuesday 10 March 2026 19:16:54 +0000 (0:00:00.025) 0:01:24.400 ********* 2026-03-10 19:16:55.174990 | controller | ok: [instance] 2026-03-10 19:16:55.391993 | controller | 2026-03-10 19:16:55.392013 | controller | TASK [reproducer : Get kubeconfig file from crc directory] ********************* 2026-03-10 19:16:55.392020 | controller | Tuesday 10 March 2026 19:16:55 +0000 (0:00:00.254) 0:01:24.655 ********* 2026-03-10 19:16:55.392029 | controller | ok: [instance] 2026-03-10 19:16:55.562784 | controller | 2026-03-10 19:16:55.562809 | controller | TASK [reproducer : Get kubeadmin-password file from crc directory] ************* 2026-03-10 19:16:55.562818 | controller | Tuesday 10 March 2026 19:16:55 +0000 (0:00:00.217) 0:01:24.872 ********* 2026-03-10 19:16:55.562830 | controller | ok: [instance] 2026-03-10 19:16:55.619755 | controller | 2026-03-10 19:16:55.619773 | controller | TASK [Ensure we expose openshift_login related facts] ************************** 2026-03-10 19:16:55.619781 | controller | Tuesday 10 March 2026 19:16:55 +0000 (0:00:00.170) 0:01:25.043 ********* 2026-03-10 19:16:55.619790 | controller | 2026-03-10 19:16:55.664400 | controller | TASK [openshift_login : Assert we have needed parameters] ********************** 2026-03-10 19:16:55.664418 | controller | Tuesday 10 March 2026 19:16:55 +0000 (0:00:00.057) 0:01:25.100 ********* 2026-03-10 19:16:55.664428 | controller | ok: [instance] => changed=false 2026-03-10 19:16:55.664570 | controller | msg: All assertions passed 2026-03-10 19:16:55.664688 | controller | 2026-03-10 19:16:55.664815 | controller | TASK [openshift_login : Stat kubeconfig] *************************************** 2026-03-10 19:16:55.664937 | controller | Tuesday 10 March 2026 19:16:55 +0000 (0:00:00.044) 0:01:25.145 ********* 2026-03-10 19:16:55.840077 | controller | ok: [instance] 2026-03-10 19:16:56.022073 | controller | 2026-03-10 19:16:56.022093 | controller | TASK [openshift_login : Stat kubeadmin] **************************************** 2026-03-10 19:16:56.022101 | controller | Tuesday 10 March 2026 19:16:55 +0000 (0:00:00.175) 0:01:25.320 ********* 2026-03-10 19:16:56.022110 | controller | ok: [instance] 2026-03-10 19:16:56.044959 | controller | 2026-03-10 19:16:56.044980 | controller | TASK [openshift_login : Assert files exist] ************************************ 2026-03-10 19:16:56.044989 | controller | Tuesday 10 March 2026 19:16:56 +0000 (0:00:00.182) 0:01:25.502 ********* 2026-03-10 19:16:56.045001 | controller | ok: [instance] => changed=false 2026-03-10 19:16:56.225201 | controller | msg: All assertions passed 2026-03-10 19:16:56.225233 | controller | 2026-03-10 19:16:56.225267 | controller | TASK [openshift_login : Gather the password of the deployed cluster.] ********** 2026-03-10 19:16:56.225278 | controller | Tuesday 10 March 2026 19:16:56 +0000 (0:00:00.022) 0:01:25.525 ********* 2026-03-10 19:16:56.225289 | controller | ok: [instance] 2026-03-10 19:16:56.396494 | controller | 2026-03-10 19:16:56.396527 | controller | TASK [openshift_login : Gather the deployed OCP configuration.] **************** 2026-03-10 19:16:56.396535 | controller | Tuesday 10 March 2026 19:16:56 +0000 (0:00:00.180) 0:01:25.705 ********* 2026-03-10 19:16:56.396545 | controller | ok: [instance] 2026-03-10 19:16:56.396615 | controller | 2026-03-10 19:16:56.396637 | controller | TASK [openshift_login : Set the OpenShift platform access information.] ******** 2026-03-10 19:16:56.396649 | controller | Tuesday 10 March 2026 19:16:56 +0000 (0:00:00.171) 0:01:25.876 ********* 2026-03-10 19:16:56.445399 | controller | ok: [instance] 2026-03-10 19:16:56.445482 | controller | 2026-03-10 19:16:56.445503 | controller | TASK [reproducer : Remove CRC managed zone delegation] ************************* 2026-03-10 19:16:56.832376 | controller | Tuesday 10 March 2026 19:16:56 +0000 (0:00:00.049) 0:01:25.926 ********* 2026-03-10 19:16:56.832427 | controller | changed: [instance] => (item=dnsmasq.d/crc.conf) 2026-03-10 19:16:56.851101 | controller | changed: [instance] => (item=conf.d/crc-nm-dnsmasq.conf) 2026-03-10 19:16:56.851125 | controller | 2026-03-10 19:16:56.851132 | controller | TASK [reproducer : Consume dev-scripts for OCP cluster] ************************ 2026-03-10 19:16:56.851138 | controller | Tuesday 10 March 2026 19:16:56 +0000 (0:00:00.386) 0:01:26.312 ********* 2026-03-10 19:16:56.851147 | controller | skipping: [instance] 2026-03-10 19:16:56.875051 | controller | 2026-03-10 19:16:56.875067 | controller | TASK [Load the architecture local kustomize patches] *************************** 2026-03-10 19:16:56.875074 | controller | Tuesday 10 March 2026 19:16:56 +0000 (0:00:00.018) 0:01:26.331 ********* 2026-03-10 19:16:56.875083 | controller | skipping: [instance] 2026-03-10 19:16:56.998349 | controller | 2026-03-10 19:16:56.998369 | controller | TASK [reproducer : Consume libvirt_manager] ************************************ 2026-03-10 19:16:56.998376 | controller | Tuesday 10 March 2026 19:16:56 +0000 (0:00:00.023) 0:01:26.355 ********* 2026-03-10 19:16:56.998384 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/tasks/libvirt_layout.yml for instance 2026-03-10 19:16:57.023185 | controller | 2026-03-10 19:16:57.023202 | controller | TASK [libvirt_manager : Import layout generator if needed] ********************* 2026-03-10 19:16:57.023209 | controller | Tuesday 10 March 2026 19:16:56 +0000 (0:00:00.123) 0:01:26.479 ********* 2026-03-10 19:16:57.023217 | controller | skipping: [instance] 2026-03-10 19:16:57.432026 | controller | 2026-03-10 19:16:57.432045 | controller | TASK [libvirt_manager : Enable forwarding in the libvirt zone] ***************** 2026-03-10 19:16:57.432053 | controller | Tuesday 10 March 2026 19:16:57 +0000 (0:00:00.024) 0:01:26.503 ********* 2026-03-10 19:16:57.432062 | controller | changed: [instance] 2026-03-10 19:16:57.928327 | controller | 2026-03-10 19:16:57.928354 | controller | TASK [libvirt_manager : Enable masquerading for public traffic] **************** 2026-03-10 19:16:57.928363 | controller | Tuesday 10 March 2026 19:16:57 +0000 (0:00:00.408) 0:01:26.912 ********* 2026-03-10 19:16:57.928372 | controller | changed: [instance] 2026-03-10 19:16:57.928855 | controller | 2026-03-10 19:16:57.928875 | controller | TASK [libvirt_manager : Ensure firewalld is restarts] ************************** 2026-03-10 19:16:57.928886 | controller | Tuesday 10 March 2026 19:16:57 +0000 (0:00:00.484) 0:01:27.397 ********* 2026-03-10 19:16:58.344832 | controller | 2026-03-10 19:16:58.344861 | controller | RUNNING HANDLER [reproducer : Restart NetworkManager] ************************** 2026-03-10 19:16:58.344870 | controller | Tuesday 10 March 2026 19:16:57 +0000 (0:00:00.011) 0:01:27.408 ********* 2026-03-10 19:16:58.344879 | controller | changed: [instance] 2026-03-10 19:16:58.981884 | controller | 2026-03-10 19:16:58.981916 | controller | RUNNING HANDLER [libvirt_manager : Restart firewalld] ************************** 2026-03-10 19:16:58.981924 | controller | Tuesday 10 March 2026 19:16:58 +0000 (0:00:00.416) 0:01:27.825 ********* 2026-03-10 19:16:58.981934 | controller | changed: [instance] 2026-03-10 19:16:59.014734 | controller | 2026-03-10 19:16:59.014767 | controller | TASK [Deploy virtualbmc] ******************************************************* 2026-03-10 19:16:59.014775 | controller | Tuesday 10 March 2026 19:16:58 +0000 (0:00:00.636) 0:01:28.462 ********* 2026-03-10 19:16:59.014785 | controller | skipping: [instance] 2026-03-10 19:16:59.015098 | controller | 2026-03-10 19:16:59.047926 | controller | TASK [libvirt_manager : Let the project know we have vbmc available] *********** 2026-03-10 19:16:59.047952 | controller | Tuesday 10 March 2026 19:16:59 +0000 (0:00:00.033) 0:01:28.495 ********* 2026-03-10 19:16:59.047963 | controller | skipping: [instance] 2026-03-10 19:16:59.586353 | controller | 2026-03-10 19:16:59.586389 | controller | TASK [libvirt_manager : Create needed workload directory] ********************** 2026-03-10 19:16:59.586397 | controller | Tuesday 10 March 2026 19:16:59 +0000 (0:00:00.033) 0:01:28.528 ********* 2026-03-10 19:16:59.586407 | controller | changed: [instance] => (item=workload) 2026-03-10 19:16:59.586426 | controller | changed: [instance] => (item=reproducer-inventory) 2026-03-10 19:16:59.586786 | controller | ok: [instance] => (item=volumes) 2026-03-10 19:16:59.982914 | controller | 2026-03-10 19:16:59.982947 | controller | TASK [libvirt_manager : Allow QEMU on workload directory] ********************** 2026-03-10 19:16:59.982955 | controller | Tuesday 10 March 2026 19:16:59 +0000 (0:00:00.538) 0:01:29.066 ********* 2026-03-10 19:16:59.982965 | controller | changed: [instance] => (item=workload) 2026-03-10 19:17:00.007997 | controller | changed: [instance] => (item=volumes) 2026-03-10 19:17:00.008013 | controller | 2026-03-10 19:17:00.008020 | controller | TASK [libvirt_manager : Generate networking data] ****************************** 2026-03-10 19:17:00.008025 | controller | Tuesday 10 March 2026 19:16:59 +0000 (0:00:00.396) 0:01:29.463 ********* 2026-03-10 19:17:00.008033 | controller | skipping: [instance] 2026-03-10 19:17:01.020276 | controller | 2026-03-10 19:17:01.020309 | controller | TASK [libvirt_manager : Create group inventories] ****************************** 2026-03-10 19:17:01.020321 | controller | Tuesday 10 March 2026 19:17:00 +0000 (0:00:00.025) 0:01:29.488 ********* 2026-03-10 19:17:01.020331 | controller | changed: [instance] => (item=compute) 2026-03-10 19:17:01.054439 | controller | changed: [instance] => (item=controller) 2026-03-10 19:17:01.054486 | controller | changed: [instance] => (item=crc) 2026-03-10 19:17:01.054500 | controller | 2026-03-10 19:17:01.054511 | controller | TASK [libvirt_manager : Create "all" group inventory file] ********************* 2026-03-10 19:17:01.054522 | controller | Tuesday 10 March 2026 19:17:01 +0000 (0:00:01.012) 0:01:30.500 ********* 2026-03-10 19:17:01.054539 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/create_all_group_inventory.yml for instance 2026-03-10 19:17:01.229186 | controller | 2026-03-10 19:17:01.229207 | controller | TASK [libvirt_manager : Check if all-group.yml already exists] ***************** 2026-03-10 19:17:01.229214 | controller | Tuesday 10 March 2026 19:17:01 +0000 (0:00:00.034) 0:01:30.534 ********* 2026-03-10 19:17:01.229223 | controller | ok: [instance] 2026-03-10 19:17:01.248347 | controller | 2026-03-10 19:17:01.248372 | controller | TASK [libvirt_manager : Slurp existing all-group.yml if it exists] ************* 2026-03-10 19:17:01.248381 | controller | Tuesday 10 March 2026 19:17:01 +0000 (0:00:00.174) 0:01:30.709 ********* 2026-03-10 19:17:01.248391 | controller | skipping: [instance] 2026-03-10 19:17:01.248690 | controller | 2026-03-10 19:17:01.248726 | controller | TASK [libvirt_manager : Create new "all" group inventory file from template] *** 2026-03-10 19:17:01.604918 | controller | Tuesday 10 March 2026 19:17:01 +0000 (0:00:00.019) 0:01:30.728 ********* 2026-03-10 19:17:01.604948 | controller | changed: [instance] 2026-03-10 19:17:01.625994 | controller | 2026-03-10 19:17:01.626011 | controller | TASK [libvirt_manager : Slurp newly created all-group.yml] ********************* 2026-03-10 19:17:01.626018 | controller | Tuesday 10 March 2026 19:17:01 +0000 (0:00:00.356) 0:01:31.085 ********* 2026-03-10 19:17:01.626026 | controller | skipping: [instance] 2026-03-10 19:17:01.648283 | controller | 2026-03-10 19:17:01.648318 | controller | TASK [libvirt_manager : Write merged all-group.yml] **************************** 2026-03-10 19:17:01.648331 | controller | Tuesday 10 March 2026 19:17:01 +0000 (0:00:00.020) 0:01:31.106 ********* 2026-03-10 19:17:01.648347 | controller | skipping: [instance] 2026-03-10 19:17:01.685822 | controller | 2026-03-10 19:17:01.685844 | controller | TASK [libvirt_manager : Ensure storage pool is present.] *********************** 2026-03-10 19:17:01.685853 | controller | Tuesday 10 March 2026 19:17:01 +0000 (0:00:00.022) 0:01:31.128 ********* 2026-03-10 19:17:01.685864 | controller | skipping: [instance] 2026-03-10 19:17:01.752399 | controller | 2026-03-10 19:17:01.752441 | controller | TASK [libvirt_manager : Ensure images are present] ***************************** 2026-03-10 19:17:01.752452 | controller | Tuesday 10 March 2026 19:17:01 +0000 (0:00:00.037) 0:01:31.166 ********* 2026-03-10 19:17:01.752467 | controller | skipping: [instance] => (item=crc) 2026-03-10 19:17:01.953393 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/get_image.yml for instance => (item=compute) 2026-03-10 19:17:01.953426 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/get_image.yml for instance => (item=controller) 2026-03-10 19:17:01.953433 | controller | 2026-03-10 19:17:01.953439 | controller | TASK [libvirt_manager : Ensure directory exists] ******************************* 2026-03-10 19:17:01.953445 | controller | Tuesday 10 March 2026 19:17:01 +0000 (0:00:00.066) 0:01:31.232 ********* 2026-03-10 19:17:01.953455 | controller | changed: [instance] 2026-03-10 19:17:01.953481 | controller | 2026-03-10 19:17:01.953697 | controller | TASK [libvirt_manager : Check if base image exists] **************************** 2026-03-10 19:17:02.130365 | controller | Tuesday 10 March 2026 19:17:01 +0000 (0:00:00.201) 0:01:31.433 ********* 2026-03-10 19:17:02.130399 | controller | ok: [instance] 2026-03-10 19:17:02.130436 | controller | 2026-03-10 19:17:02.130598 | controller | TASK [libvirt_manager : Download base image] *********************************** 2026-03-10 19:17:34.629171 | controller | Tuesday 10 March 2026 19:17:02 +0000 (0:00:00.177) 0:01:31.611 ********* 2026-03-10 19:17:34.629206 | controller | changed: [instance] 2026-03-10 19:17:34.629233 | controller | 2026-03-10 19:17:34.629461 | controller | TASK [libvirt_manager : Check image] ******************************************* 2026-03-10 19:17:34.821137 | controller | Tuesday 10 March 2026 19:17:34 +0000 (0:00:32.498) 0:02:04.109 ********* 2026-03-10 19:17:34.821167 | controller | ok: [instance] 2026-03-10 19:17:34.860316 | controller | 2026-03-10 19:17:34.860349 | controller | TASK [libvirt_manager : Assert image status] *********************************** 2026-03-10 19:17:34.860362 | controller | Tuesday 10 March 2026 19:17:34 +0000 (0:00:00.191) 0:02:04.301 ********* 2026-03-10 19:17:34.860378 | controller | ok: [instance] => changed=false 2026-03-10 19:17:35.064775 | controller | msg: All assertions passed 2026-03-10 19:17:35.064797 | controller | 2026-03-10 19:17:35.064807 | controller | TASK [libvirt_manager : Ensure image access rights] **************************** 2026-03-10 19:17:35.064814 | controller | Tuesday 10 March 2026 19:17:34 +0000 (0:00:00.038) 0:02:04.340 ********* 2026-03-10 19:17:35.064825 | controller | changed: [instance] 2026-03-10 19:17:35.269223 | controller | 2026-03-10 19:17:35.269244 | controller | TASK [libvirt_manager : Ensure directory exists] ******************************* 2026-03-10 19:17:35.269267 | controller | Tuesday 10 March 2026 19:17:35 +0000 (0:00:00.204) 0:02:04.545 ********* 2026-03-10 19:17:35.269284 | controller | ok: [instance] 2026-03-10 19:17:35.466423 | controller | 2026-03-10 19:17:35.466460 | controller | TASK [libvirt_manager : Check if base image exists] **************************** 2026-03-10 19:17:35.466470 | controller | Tuesday 10 March 2026 19:17:35 +0000 (0:00:00.204) 0:02:04.749 ********* 2026-03-10 19:17:35.466483 | controller | ok: [instance] 2026-03-10 19:17:35.466515 | controller | 2026-03-10 19:17:35.466532 | controller | TASK [libvirt_manager : Download base image] *********************************** 2026-03-10 19:17:35.466630 | controller | Tuesday 10 March 2026 19:17:35 +0000 (0:00:00.197) 0:02:04.946 ********* 2026-03-10 19:17:35.489485 | controller | skipping: [instance] 2026-03-10 19:17:35.489549 | controller | 2026-03-10 19:17:35.489567 | controller | TASK [libvirt_manager : Check image] ******************************************* 2026-03-10 19:17:35.489605 | controller | Tuesday 10 March 2026 19:17:35 +0000 (0:00:00.023) 0:02:04.970 ********* 2026-03-10 19:17:35.682115 | controller | ok: [instance] 2026-03-10 19:17:35.725037 | controller | 2026-03-10 19:17:35.725066 | controller | TASK [libvirt_manager : Assert image status] *********************************** 2026-03-10 19:17:35.725075 | controller | Tuesday 10 March 2026 19:17:35 +0000 (0:00:00.192) 0:02:05.162 ********* 2026-03-10 19:17:35.725087 | controller | ok: [instance] => changed=false 2026-03-10 19:17:35.927216 | controller | msg: All assertions passed 2026-03-10 19:17:35.927240 | controller | 2026-03-10 19:17:35.927250 | controller | TASK [libvirt_manager : Ensure image access rights] **************************** 2026-03-10 19:17:35.927282 | controller | Tuesday 10 March 2026 19:17:35 +0000 (0:00:00.042) 0:02:05.205 ********* 2026-03-10 19:17:35.927293 | controller | ok: [instance] 2026-03-10 19:17:36.101510 | controller | 2026-03-10 19:17:36.101548 | controller | TASK [libvirt_manager : Get ssh key state] ************************************* 2026-03-10 19:17:36.101560 | controller | Tuesday 10 March 2026 19:17:35 +0000 (0:00:00.201) 0:02:05.407 ********* 2026-03-10 19:17:36.101573 | controller | ok: [instance] 2026-03-10 19:17:36.483078 | controller | 2026-03-10 19:17:36.483111 | controller | TASK [libvirt_manager : Create temporary ssh keypair] ************************** 2026-03-10 19:17:36.483133 | controller | Tuesday 10 March 2026 19:17:36 +0000 (0:00:00.174) 0:02:05.582 ********* 2026-03-10 19:17:36.483143 | controller | changed: [instance] 2026-03-10 19:17:36.658385 | controller | 2026-03-10 19:17:36.658421 | controller | TASK [libvirt_manager : Slurp public key for later use] ************************ 2026-03-10 19:17:36.658431 | controller | Tuesday 10 March 2026 19:17:36 +0000 (0:00:00.381) 0:02:05.963 ********* 2026-03-10 19:17:36.658443 | controller | ok: [instance] 2026-03-10 19:17:36.658474 | controller | 2026-03-10 19:17:36.658485 | controller | TASK [libvirt_manager : Slurp private key for later use] *********************** 2026-03-10 19:17:36.658605 | controller | Tuesday 10 March 2026 19:17:36 +0000 (0:00:00.175) 0:02:06.138 ********* 2026-03-10 19:17:36.824097 | controller | ok: [instance] 2026-03-10 19:17:37.211440 | controller | 2026-03-10 19:17:37.211488 | controller | TASK [libvirt_manager : Inject cifmw_reproducer_key.pub in hypervisor authorized_keys] *** 2026-03-10 19:17:37.211502 | controller | Tuesday 10 March 2026 19:17:36 +0000 (0:00:00.165) 0:02:06.304 ********* 2026-03-10 19:17:37.211521 | controller | changed: [instance] 2026-03-10 19:17:37.232385 | controller | 2026-03-10 19:17:37.232420 | controller | TASK [libvirt_manager : Create fact holding network data for VMs] ************** 2026-03-10 19:17:37.232431 | controller | Tuesday 10 March 2026 19:17:37 +0000 (0:00:00.387) 0:02:06.691 ********* 2026-03-10 19:17:37.232443 | controller | ok: [instance] 2026-03-10 19:17:37.232476 | controller | 2026-03-10 19:17:37.232803 | controller | TASK [libvirt_manager : List existing networks] ******************************** 2026-03-10 19:17:37.455385 | controller | Tuesday 10 March 2026 19:17:37 +0000 (0:00:00.021) 0:02:06.713 ********* 2026-03-10 19:17:37.455422 | controller | ok: [instance] 2026-03-10 19:17:37.907029 | controller | 2026-03-10 19:17:37.907069 | controller | TASK [libvirt_manager : Gather pool fact] ************************************** 2026-03-10 19:17:37.907079 | controller | Tuesday 10 March 2026 19:17:37 +0000 (0:00:00.222) 0:02:06.935 ********* 2026-03-10 19:17:37.907092 | controller | ok: [instance] 2026-03-10 19:17:38.026448 | controller | 2026-03-10 19:17:38.026497 | controller | TASK [libvirt_manager : Create and run VMs] ************************************ 2026-03-10 19:17:38.026512 | controller | Tuesday 10 March 2026 19:17:37 +0000 (0:00:00.451) 0:02:07.387 ********* 2026-03-10 19:17:38.026530 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/create_vms.yml for instance => (item={'key': 'compute-0', 'value': 'compute'}) 2026-03-10 19:17:38.026560 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/create_vms.yml for instance => (item={'key': 'controller-0', 'value': 'controller'}) 2026-03-10 19:17:38.026582 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/create_vms.yml for instance => (item={'key': 'crc-0', 'value': 'crc'}) 2026-03-10 19:17:38.026620 | controller | 2026-03-10 19:17:38.026787 | controller | TASK [libvirt_manager : Create VM image for compute-0] ************************* 2026-03-10 19:17:38.026849 | controller | Tuesday 10 March 2026 19:17:38 +0000 (0:00:00.119) 0:02:07.506 ********* 2026-03-10 19:17:38.277099 | controller | changed: [instance] 2026-03-10 19:17:38.498820 | controller | 2026-03-10 19:17:38.498848 | controller | TASK [libvirt_manager : Ensure file ownership and rights for compute-0] ******** 2026-03-10 19:17:38.498858 | controller | Tuesday 10 March 2026 19:17:38 +0000 (0:00:00.250) 0:02:07.757 ********* 2026-03-10 19:17:38.498867 | controller | changed: [instance] 2026-03-10 19:17:38.582494 | controller | 2026-03-10 19:17:38.582529 | controller | TASK [libvirt_manager : Define VMs for type compute-0] ************************* 2026-03-10 19:17:38.582538 | controller | Tuesday 10 March 2026 19:17:38 +0000 (0:00:00.221) 0:02:07.979 ********* 2026-03-10 19:17:38.582548 | controller | fatal: [instance]: FAILED! => 2026-03-10 19:17:38.582573 | controller | msg: |- 2026-03-10 19:17:38.582580 | controller | An unhandled exception occurred while running the lookup plugin 'template'. Error was a , original message: template error while templating string: expected token 'end of statement block', got '%'. String: 2026-03-10 19:17:38.582590 | controller | {{ vm | ansible.builtin.to_uuid }} 2026-03-10 19:17:38.582596 | controller | cifmw-{{ vm }} 2026-03-10 19:17:38.582602 | controller | {{ vm_data.memory | default(2) }} 2026-03-10 19:17:38.582608 | controller | {{ vm_data.cpus | default(2) }} 2026-03-10 19:17:38.582613 | controller | {% if vm_data.uefi | default(false) | bool %} 2026-03-10 19:17:38.582619 | controller | 2026-03-10 19:17:38.582625 | controller | hvm 2026-03-10 19:17:38.582630 | controller | 2026-03-10 19:17:38.582636 | controller | 2026-03-10 19:17:38.582641 | controller | 2026-03-10 19:17:38.582647 | controller | {% else %} 2026-03-10 19:17:38.582652 | controller | 2026-03-10 19:17:38.582657 | controller | hvm 2026-03-10 19:17:38.582663 | controller | {% endif %} 2026-03-10 19:17:38.582668 | controller | 2026-03-10 19:17:38.582674 | controller | 2026-03-10 19:17:38.582679 | controller | 2026-03-10 19:17:38.582686 | controller | 2026-03-10 19:17:38.582708 | controller | 2026-03-10 19:17:38.583367 | controller | 2026-03-10 19:17:38.583402 | controller | 2026-03-10 19:17:38.583428 | controller | 2026-03-10 19:17:38.583435 | controller | 2026-03-10 19:17:38.583441 | controller | 2026-03-10 19:17:38.583447 | controller | 2026-03-10 19:17:38.583452 | controller | 2026-03-10 19:17:38.583458 | controller | 2026-03-10 19:17:38.583463 | controller | 2026-03-10 19:17:38.583470 | controller | /usr/libexec/qemu-kvm 2026-03-10 19:17:38.585302 | controller | 2026-03-10 19:17:38.585388 | controller | 2026-03-10 19:17:38.585397 | controller | 2026-03-10 19:17:38.585403 | controller | {% set disk_bus = vm_data.disk_bus | default('scsi') %} 2026-03-10 19:17:38.585408 | controller | {% if disk_bus == 'scsi' %} 2026-03-10 19:17:38.585413 | controller | 2026-03-10 19:17:38.585418 | controller | {% else %} 2026-03-10 19:17:38.585423 | controller | 2026-03-10 19:17:38.585429 | controller | {% endif %} 2026-03-10 19:17:38.585436 | controller | 2026-03-10 19:17:38.585442 | controller | {% set extra_disks_bus = vm_data.extra_disks_bus | default('virtio') %} 2026-03-10 19:17:38.585447 | controller | {% if disk_bus == 'scsi' or extra_disks_bus == 'scsi' %} 2026-03-10 19:17:38.585452 | controller | 2026-03-10 19:17:38.585457 | controller | 2026-03-10 19:17:38.585462 | controller |
2026-03-10 19:17:38.585467 | controller | 2026-03-10 19:17:38.585472 | controller | {% endif %} 2026-03-10 19:17:38.585477 | controller | 2026-03-10 19:17:38.585482 | controller | 2026-03-10 19:17:38.585487 | controller |
2026-03-10 19:17:38.585492 | controller | 2026-03-10 19:17:38.585497 | controller | 2026-03-10 19:17:38.585502 | controller | 2026-03-10 19:17:38.585507 | controller | 2026-03-10 19:17:38.585514 | controller |
2026-03-10 19:17:38.585581 | controller | 2026-03-10 19:17:38.585591 | controller | 2026-03-10 19:17:38.585679 | controller | 2026-03-10 19:17:38.585774 | controller | 2026-03-10 19:17:38.585870 | controller |
2026-03-10 19:17:38.585964 | controller | 2026-03-10 19:17:38.586055 | controller | 2026-03-10 19:17:38.586161 | controller | 2026-03-10 19:17:38.586248 | controller | 2026-03-10 19:17:38.586360 | controller |
2026-03-10 19:17:38.586459 | controller | 2026-03-10 19:17:38.586551 | controller | 2026-03-10 19:17:38.586643 | controller | 2026-03-10 19:17:38.586738 | controller | 2026-03-10 19:17:38.586832 | controller | 2026-03-10 19:17:38.586923 | controller | 2026-03-10 19:17:38.587019 | controller |
2026-03-10 19:17:38.587120 | controller | 2026-03-10 19:17:38.587213 | controller | 2026-03-10 19:17:38.587321 | controller | 2026-03-10 19:17:38.587423 | controller | 2026-03-10 19:17:38.587515 | controller | 2026-03-10 19:17:38.587606 | controller | 2026-03-10 19:17:38.587695 | controller | 2026-03-10 19:17:38.587786 | controller | 2026-03-10 19:17:38.587875 | controller | 2026-03-10 19:17:38.587965 | controller | 2026-03-10 19:17:38.588057 | controller | 2026-03-10 19:17:38.588158 | controller | 2026-03-10 19:17:38.588263 | controller | 2026-03-10 19:17:38.588361 | controller | 2026-03-10 19:17:38.588461 | controller | 2026-03-10 19:17:38.588554 | controller | 2026-03-10 19:17:38.588647 | controller | 2026-03-10 19:17:38.588739 | controller |
2026-03-10 19:17:38.588829 | controller | 2026-03-10 19:17:38.588920 | controller | 2026-03-10 19:17:38.589010 | controller | 2026-03-10 19:17:38.589099 | controller | 2026-03-10 19:17:38.589200 | controller | 2026-03-10 19:17:38.589306 | controller | 2026-03-10 19:17:38.589403 | controller | 2026-03-10 19:17:38.589493 | controller |