2026-03-10 19:11:56.360441 | Job console starting... 2026-03-10 19:11:56.560663 | Updating repositories 2026-03-10 19:11:57.278949 | Preparing job workspace 2026-03-10 19:12:03.959673 | Running Ansible setup... 2026-03-10 19:12:06.711548 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-03-10 19:12:07.178189 | 2026-03-10 19:12:07.178300 | PLAY [localhost] 2026-03-10 19:12:07.185978 | 2026-03-10 19:12:07.186041 | TASK [Gathering Facts] 2026-03-10 19:12:07.931150 | localhost | ok 2026-03-10 19:12:07.942864 | 2026-03-10 19:12:07.942939 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-10 19:12:08.239814 | localhost -> localhost | changed 2026-03-10 19:12:08.245370 | 2026-03-10 19:12:08.245444 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-10 19:12:08.865754 | localhost -> localhost | changed 2026-03-10 19:12:08.873097 | 2026-03-10 19:12:08.873162 | TASK [Setup log path fact] 2026-03-10 19:12:08.889640 | localhost | ok 2026-03-10 19:12:08.899621 | 2026-03-10 19:12:08.899691 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-10 19:12:08.916456 | localhost | ok 2026-03-10 19:12:08.923051 | 2026-03-10 19:12:08.923111 | TASK [emit-job-header : Print job information] 2026-03-10 19:12:08.949455 | # Job Information 2026-03-10 19:12:08.949563 | Ansible Version: 2.15.12 2026-03-10 19:12:08.949590 | Job: cifmw-molecule-libvirt_manager 2026-03-10 19:12:08.949610 | Pipeline: github-check 2026-03-10 19:12:08.949628 | Executor: ibm-bm3-ze.softwarefactory-project.io 2026-03-10 19:12:08.949645 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3755 2026-03-10 19:12:08.949662 | Log URL (when completed): https://logserver.rdoproject.org/498/rdoproject.org/498da73d491943ef9c57ec67cd44eeca/ 2026-03-10 19:12:08.949679 | Event ID: df4afa80-1cb4-11f1-87de-3982f02d1d28 2026-03-10 19:12:08.953037 | 2026-03-10 19:12:08.953095 | LOOP [emit-job-header : Print node information] 2026-03-10 19:12:09.031089 | localhost | ok: 2026-03-10 19:12:09.031246 | localhost | # Node Information 2026-03-10 19:12:09.031299 | localhost | Inventory Hostname: controller 2026-03-10 19:12:09.031328 | localhost | Hostname: np0005643652 2026-03-10 19:12:09.031349 | localhost | Username: zuul 2026-03-10 19:12:09.031370 | localhost | Distro: CentOS 9 2026-03-10 19:12:09.031388 | localhost | Provider: ibm-bm3-nodepool 2026-03-10 19:12:09.031406 | localhost | Region: regionOne 2026-03-10 19:12:09.031423 | localhost | Label: cloud-centos-9-stream-tripleo-ibm 2026-03-10 19:12:09.031440 | localhost | Product Name: OpenStack Compute 2026-03-10 19:12:09.031456 | localhost | Interface IP: 192.168.25.180 2026-03-10 19:12:09.054825 | 2026-03-10 19:12:09.054902 | PLAY [all] 2026-03-10 19:12:09.060483 | 2026-03-10 19:12:09.060543 | TASK [Gather network facts] 2026-03-10 19:12:09.413773 | controller | ok 2026-03-10 19:12:09.425987 | 2026-03-10 19:12:09.426046 | TASK [include_role : start-zuul-console] 2026-03-10 19:12:09.442908 | controller | ok 2026-03-10 19:12:09.453612 | 2026-03-10 19:12:09.453672 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-10 19:12:09.735215 | controller | ok 2026-03-10 19:12:09.742650 | 2026-03-10 19:12:09.742705 | TASK [include_role : add-build-sshkey] 2026-03-10 19:12:09.759369 | controller | ok 2026-03-10 19:12:09.770508 | 2026-03-10 19:12:09.770568 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-10 19:12:09.945482 | controller -> localhost | ok 2026-03-10 19:12:09.950689 | 2026-03-10 19:12:09.950759 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-10 19:12:09.968219 | controller | ok 2026-03-10 19:12:09.981034 | controller | included: /var/lib/zuul/builds/498da73d491943ef9c57ec67cd44eeca/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-10 19:12:09.986155 | 2026-03-10 19:12:09.986218 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-10 19:12:10.585531 | controller -> localhost | Generating public/private rsa key pair. 2026-03-10 19:12:10.585673 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/498da73d491943ef9c57ec67cd44eeca/work/498da73d491943ef9c57ec67cd44eeca_id_rsa. 2026-03-10 19:12:10.585703 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/498da73d491943ef9c57ec67cd44eeca/work/498da73d491943ef9c57ec67cd44eeca_id_rsa.pub. 2026-03-10 19:12:10.585725 | controller -> localhost | The key fingerprint is: 2026-03-10 19:12:10.585746 | controller -> localhost | SHA256:n4BWiDbXs+woCO3EvKh8U5r6w7pbRrccllDmUr/TTso zuul-build-sshkey 2026-03-10 19:12:10.585764 | controller -> localhost | The key's randomart image is: 2026-03-10 19:12:10.585781 | controller -> localhost | +---[RSA 3072]----+ 2026-03-10 19:12:10.585797 | controller -> localhost | | + | 2026-03-10 19:12:10.585812 | controller -> localhost | | =..o | 2026-03-10 19:12:10.585839 | controller -> localhost | | o+.o.+ | 2026-03-10 19:12:10.585858 | controller -> localhost | | + .oo.+oo | 2026-03-10 19:12:10.585875 | controller -> localhost | |. =. =ooSo | 2026-03-10 19:12:10.585891 | controller -> localhost | | =.o+o+o=o . | 2026-03-10 19:12:10.585906 | controller -> localhost | |. =o=o.E..o | 2026-03-10 19:12:10.585925 | controller -> localhost | |o oB . | 2026-03-10 19:12:10.585942 | controller -> localhost | |.*B.o | 2026-03-10 19:12:10.585957 | controller -> localhost | +----[SHA256]-----+ 2026-03-10 19:12:10.585995 | controller -> localhost | ok: Runtime: 0:00:00.295384 2026-03-10 19:12:10.590936 | 2026-03-10 19:12:10.590999 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-10 19:12:10.607765 | controller | ok 2026-03-10 19:12:10.615937 | controller | included: /var/lib/zuul/builds/498da73d491943ef9c57ec67cd44eeca/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-10 19:12:10.622854 | 2026-03-10 19:12:10.622913 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-10 19:12:10.636150 | controller | skipping: Conditional result was False 2026-03-10 19:12:10.640823 | 2026-03-10 19:12:10.640893 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-10 19:12:10.993585 | controller | changed 2026-03-10 19:12:10.997728 | 2026-03-10 19:12:10.997785 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-10 19:12:11.177581 | controller | ok 2026-03-10 19:12:11.181880 | 2026-03-10 19:12:11.181939 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-10 19:12:11.736355 | controller | changed 2026-03-10 19:12:11.741354 | 2026-03-10 19:12:11.741427 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-10 19:12:12.286732 | controller | changed 2026-03-10 19:12:12.291114 | 2026-03-10 19:12:12.291175 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-10 19:12:12.304340 | controller | skipping: Conditional result was False 2026-03-10 19:12:12.308987 | 2026-03-10 19:12:12.309048 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-10 19:12:12.576995 | controller -> localhost | changed 2026-03-10 19:12:12.585048 | 2026-03-10 19:12:12.585105 | TASK [add-build-sshkey : Add back temp key] 2026-03-10 19:12:12.790792 | controller -> localhost | Identity added: /var/lib/zuul/builds/498da73d491943ef9c57ec67cd44eeca/work/498da73d491943ef9c57ec67cd44eeca_id_rsa (zuul-build-sshkey) 2026-03-10 19:12:12.790980 | controller -> localhost | ok: Runtime: 0:00:00.006651 2026-03-10 19:12:12.796094 | 2026-03-10 19:12:12.796154 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-10 19:12:13.056568 | controller | ok 2026-03-10 19:12:13.060674 | 2026-03-10 19:12:13.060733 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-10 19:12:13.084312 | controller | skipping: Conditional result was False 2026-03-10 19:12:13.092840 | 2026-03-10 19:12:13.092901 | TASK [include_role : validate-host] 2026-03-10 19:12:13.109812 | controller | ok 2026-03-10 19:12:13.127296 | 2026-03-10 19:12:13.127356 | TASK [validate-host : Define zuul_info_dir fact] 2026-03-10 19:12:13.154755 | controller | ok 2026-03-10 19:12:13.158823 | 2026-03-10 19:12:13.158895 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-03-10 19:12:13.333793 | controller -> localhost | ok 2026-03-10 19:12:13.339294 | 2026-03-10 19:12:13.339352 | TASK [validate-host : Collect information about the host] 2026-03-10 19:12:13.935925 | controller | ok 2026-03-10 19:12:13.942999 | 2026-03-10 19:12:13.943057 | TASK [validate-host : Sanitize hostname] 2026-03-10 19:12:14.000619 | controller | ok 2026-03-10 19:12:14.004934 | 2026-03-10 19:12:14.004992 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-03-10 19:12:14.363845 | controller -> localhost | changed 2026-03-10 19:12:14.387352 | 2026-03-10 19:12:14.387421 | TASK [validate-host : Collect information about zuul worker] 2026-03-10 19:12:14.688977 | controller | ok 2026-03-10 19:12:14.693147 | 2026-03-10 19:12:14.693208 | TASK [validate-host : Write out all zuul information for each host] 2026-03-10 19:12:15.020841 | controller -> localhost | changed 2026-03-10 19:12:15.029343 | 2026-03-10 19:12:15.029401 | TASK [include_role : prepare-workspace-openshift] 2026-03-10 19:12:15.042718 | controller | skipping: Conditional result was False 2026-03-10 19:12:15.047414 | 2026-03-10 19:12:15.047474 | TASK [include_role : remove-zuul-sshkey] 2026-03-10 19:12:15.060169 | controller | skipping: Conditional result was False 2026-03-10 19:12:15.064849 | 2026-03-10 19:12:15.064913 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-10 19:12:15.256354 | controller | ok: "logs" 2026-03-10 19:12:15.256607 | controller | ok: All items complete 2026-03-10 19:12:15.256634 | 2026-03-10 19:12:15.418654 | controller | ok: "artifacts" 2026-03-10 19:12:15.573761 | controller | ok: "docs" 2026-03-10 19:12:15.579917 | 2026-03-10 19:12:15.580031 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-10 19:12:15.761303 | controller | changed: "logs" 2026-03-10 19:12:15.926947 | controller | changed: "artifacts" 2026-03-10 19:12:16.087744 | controller | changed: "docs" 2026-03-10 19:12:16.110907 | 2026-03-10 19:12:16.110978 | PLAY RECAP 2026-03-10 19:12:16.111016 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2026-03-10 19:12:16.111042 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-10 19:12:16.111059 | 2026-03-10 19:12:16.179870 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-03-10 19:12:16.180574 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2026-03-10 19:12:16.675825 | 2026-03-10 19:12:16.675935 | PLAY [all] 2026-03-10 19:12:16.693395 | 2026-03-10 19:12:16.693462 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2026-03-10 19:12:16.742244 | controller | ok 2026-03-10 19:12:16.746753 | 2026-03-10 19:12:16.746823 | TASK [mirror-info-fork : Create /etc/ci] 2026-03-10 19:12:17.069033 | controller | changed 2026-03-10 19:12:17.074120 | 2026-03-10 19:12:17.074191 | TASK [mirror-info-fork : Install ci_mirror script] 2026-03-10 19:12:17.795425 | controller | changed 2026-03-10 19:12:17.804480 | 2026-03-10 19:12:17.804548 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2026-03-10 19:12:18.142786 | controller | changed: 2026-03-10 19:12:18.142935 | controller | { 2026-03-10 19:12:18.142964 | 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:18.142993 | controller | } 2026-03-10 19:12:18.327313 | controller | changed: 2026-03-10 19:12:18.327421 | controller | { 2026-03-10 19:12:18.327453 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2026-03-10 19:12:18.327474 | controller | } 2026-03-10 19:12:18.514169 | controller | changed: 2026-03-10 19:12:18.514213 | controller | { 2026-03-10 19:12:18.514236 | 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:18.514256 | controller | } 2026-03-10 19:12:18.696741 | controller | changed: 2026-03-10 19:12:18.696787 | controller | { 2026-03-10 19:12:18.696811 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2026-03-10 19:12:18.696842 | controller | } 2026-03-10 19:12:18.880878 | controller | changed: 2026-03-10 19:12:18.880928 | controller | { 2026-03-10 19:12:18.880955 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2026-03-10 19:12:18.880974 | controller | } 2026-03-10 19:12:19.071517 | controller | changed: 2026-03-10 19:12:19.071562 | controller | { 2026-03-10 19:12:19.071586 | 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:19.071604 | controller | } 2026-03-10 19:12:19.259490 | controller | changed: 2026-03-10 19:12:19.259539 | controller | { 2026-03-10 19:12:19.259565 | 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:19.259585 | controller | } 2026-03-10 19:12:19.443984 | controller | changed: 2026-03-10 19:12:19.444030 | controller | { 2026-03-10 19:12:19.444054 | 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:19.444073 | controller | } 2026-03-10 19:12:19.629642 | controller | changed: 2026-03-10 19:12:19.629695 | controller | { 2026-03-10 19:12:19.629720 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2026-03-10 19:12:19.629739 | controller | } 2026-03-10 19:12:19.812084 | controller | changed: 2026-03-10 19:12:19.812139 | controller | { 2026-03-10 19:12:19.812173 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2026-03-10 19:12:19.812201 | controller | } 2026-03-10 19:12:19.998519 | controller | changed: 2026-03-10 19:12:19.998568 | controller | { 2026-03-10 19:12:19.998593 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2026-03-10 19:12:19.998612 | controller | } 2026-03-10 19:12:20.204672 | controller | changed: 2026-03-10 19:12:20.204740 | controller | { 2026-03-10 19:12:20.204765 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2026-03-10 19:12:20.204785 | controller | } 2026-03-10 19:12:20.376787 | controller | changed: 2026-03-10 19:12:20.376851 | controller | { 2026-03-10 19:12:20.376881 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com" 2026-03-10 19:12:20.376906 | controller | } 2026-03-10 19:12:20.562651 | controller | changed: 2026-03-10 19:12:20.562699 | controller | { 2026-03-10 19:12:20.562723 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2026-03-10 19:12:20.562742 | controller | } 2026-03-10 19:12:20.746379 | controller | changed: 2026-03-10 19:12:20.746564 | controller | { 2026-03-10 19:12:20.746606 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2026-03-10 19:12:20.746629 | controller | } 2026-03-10 19:12:20.933315 | controller | changed: 2026-03-10 19:12:20.933365 | controller | { 2026-03-10 19:12:20.933391 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2026-03-10 19:12:20.933411 | controller | } 2026-03-10 19:12:21.119788 | controller | changed: 2026-03-10 19:12:21.119848 | controller | { 2026-03-10 19:12:21.119876 | 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:21.119897 | controller | } 2026-03-10 19:12:21.308222 | controller | changed: 2026-03-10 19:12:21.308288 | controller | { 2026-03-10 19:12:21.308319 | 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:21.308348 | controller | } 2026-03-10 19:12:21.493517 | controller | changed: 2026-03-10 19:12:21.493561 | controller | { 2026-03-10 19:12:21.493585 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2026-03-10 19:12:21.493602 | controller | } 2026-03-10 19:12:21.683285 | controller | changed: 2026-03-10 19:12:21.683334 | controller | { 2026-03-10 19:12:21.683360 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2026-03-10 19:12:21.683380 | controller | } 2026-03-10 19:12:21.869878 | controller | changed: 2026-03-10 19:12:21.869922 | controller | { 2026-03-10 19:12:21.869946 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2026-03-10 19:12:21.869964 | controller | } 2026-03-10 19:12:22.061520 | controller | changed: 2026-03-10 19:12:22.061568 | controller | { 2026-03-10 19:12:22.061593 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2026-03-10 19:12:22.061612 | controller | } 2026-03-10 19:12:22.247714 | controller | changed: 2026-03-10 19:12:22.247757 | controller | { 2026-03-10 19:12:22.247781 | 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:22.247799 | controller | } 2026-03-10 19:12:22.432740 | controller | changed: 2026-03-10 19:12:22.432786 | controller | { 2026-03-10 19:12:22.432810 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2026-03-10 19:12:22.432835 | controller | } 2026-03-10 19:12:22.620341 | controller | changed: 2026-03-10 19:12:22.620391 | controller | { 2026-03-10 19:12:22.620417 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2026-03-10 19:12:22.620437 | controller | } 2026-03-10 19:12:22.807189 | controller | changed: 2026-03-10 19:12:22.807234 | controller | { 2026-03-10 19:12:22.807259 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2026-03-10 19:12:22.807300 | controller | } 2026-03-10 19:12:22.825789 | 2026-03-10 19:12:22.825943 | TASK [Set timezone to UTC] 2026-03-10 19:12:23.179308 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2026-03-10 19:12:23.183796 | 2026-03-10 19:12:23.183864 | TASK [Create nodepool directory] 2026-03-10 19:12:23.364805 | controller | changed 2026-03-10 19:12:23.369847 | 2026-03-10 19:12:23.369906 | TASK [Create nodepool sub_nodes file] 2026-03-10 19:12:23.834885 | controller | changed 2026-03-10 19:12:23.839391 | 2026-03-10 19:12:23.839453 | TASK [Create nodepool sub_nodes_private file] 2026-03-10 19:12:24.330332 | controller | changed 2026-03-10 19:12:24.335661 | 2026-03-10 19:12:24.335721 | LOOP [Populate nodepool sub_nodes file] 2026-03-10 19:12:24.364252 | 2026-03-10 19:12:24.364391 | LOOP [Populate nodepool sub_nodes_private file] 2026-03-10 19:12:24.392086 | 2026-03-10 19:12:24.392198 | TASK [Create nodepool primary file] 2026-03-10 19:12:24.415073 | controller | skipping: Conditional result was False 2026-03-10 19:12:24.419768 | 2026-03-10 19:12:24.419846 | TASK [Create nodepool node_private for this node] 2026-03-10 19:12:24.933407 | controller | changed 2026-03-10 19:12:24.938324 | 2026-03-10 19:12:24.938379 | LOOP [Copy ssh keys to nodepool directory] 2026-03-10 19:12:25.233191 | controller | ok: Item: id_rsa Runtime: 0:00:00.004671 2026-03-10 19:12:25.233356 | 2026-03-10 19:12:25.379541 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004543 2026-03-10 19:12:25.385951 | 2026-03-10 19:12:25.386057 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2026-03-10 19:12:25.888904 | controller | changed 2026-03-10 19:12:25.893961 | 2026-03-10 19:12:25.894042 | TASK [Validate sudoers config after edits] 2026-03-10 19:12:26.098297 | controller | /etc/sudoers: parsed OK 2026-03-10 19:12:26.098379 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2026-03-10 19:12:26.098390 | controller | /etc/sudoers.d/zuul: parsed OK 2026-03-10 19:12:26.098396 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2026-03-10 19:12:26.420403 | controller | ok: Runtime: 0:00:00.005639 2026-03-10 19:12:26.425103 | 2026-03-10 19:12:26.425158 | TASK [Show the environment passed in to job shell scripts] 2026-03-10 19:12:26.610365 | controller | SHELL=/bin/bash 2026-03-10 19:12:26.610417 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2026-03-10 19:12:26.610426 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2026-03-10 19:12:26.610432 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/55/3755/91294f26969c15166afab43826de6b8fc2e378c9 2026-03-10 19:12:26.610479 | controller | PWD=/home/zuul 2026-03-10 19:12:26.610487 | controller | ZUUL_PIPELINE=github-check 2026-03-10 19:12:26.610492 | controller | LOGNAME=zuul 2026-03-10 19:12:26.610497 | controller | XDG_SESSION_TYPE=tty 2026-03-10 19:12:26.610502 | controller | _=/usr/bin/env 2026-03-10 19:12:26.610507 | controller | MOTD_SHOWN=pam 2026-03-10 19:12:26.610512 | controller | HOME=/home/zuul 2026-03-10 19:12:26.610517 | controller | LANG=en_US.UTF-8 2026-03-10 19:12:26.610522 | controller | SSH_CONNECTION=192.168.25.12 35062 192.168.25.180 22 2026-03-10 19:12:26.610527 | 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:26.610535 | controller | ZUUL_CHANGE_IDS=3755,91294f26969c15166afab43826de6b8fc2e378c9 2026-03-10 19:12:26.610540 | controller | WORKSPACE=/home/zuul/workspace 2026-03-10 19:12:26.610545 | controller | XDG_SESSION_CLASS=user 2026-03-10 19:12:26.610550 | controller | SELINUX_ROLE_REQUESTED= 2026-03-10 19:12:26.610555 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2026-03-10 19:12:26.610560 | controller | USER=zuul 2026-03-10 19:12:26.610565 | controller | ZUUL_VOTING=True 2026-03-10 19:12:26.610569 | controller | BUILD_TIMEOUT=3600000 2026-03-10 19:12:26.610574 | controller | SELINUX_USE_CURRENT_RANGE= 2026-03-10 19:12:26.610579 | controller | SHLVL=1 2026-03-10 19:12:26.610584 | controller | ZUUL_PATCHSET=91294f26969c15166afab43826de6b8fc2e378c9 2026-03-10 19:12:26.610589 | controller | XDG_SESSION_ID=1 2026-03-10 19:12:26.610594 | controller | ZUUL_BRANCH=main 2026-03-10 19:12:26.610599 | controller | XDG_RUNTIME_DIR=/run/user/1000 2026-03-10 19:12:26.610604 | controller | SSH_CLIENT=192.168.25.12 35062 22 2026-03-10 19:12:26.610610 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2026-03-10 19:12:26.610615 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2026-03-10 19:12:26.610621 | controller | which_declare=declare -f 2026-03-10 19:12:26.610626 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2026-03-10 19:12:26.610631 | controller | SELINUX_LEVEL_REQUESTED= 2026-03-10 19:12:26.610636 | controller | ZUUL_CHANGE=3755 2026-03-10 19:12:26.610641 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2026-03-10 19:12:26.610646 | controller | ZUUL_UUID=498da73d491943ef9c57ec67cd44eeca 2026-03-10 19:12:26.610651 | controller | BASH_FUNC_which%%=() { ( alias; 2026-03-10 19:12:26.610656 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2026-03-10 19:12:26.610661 | controller | } 2026-03-10 19:12:26.948744 | controller | ok: Runtime: 0:00:00.005387 2026-03-10 19:12:26.953725 | 2026-03-10 19:12:26.953780 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2026-03-10 19:12:26.966708 | controller | skipping: Conditional result was False 2026-03-10 19:12:26.971457 | 2026-03-10 19:12:26.971516 | TASK [Symlink /home/zuul-worker/workspace] 2026-03-10 19:12:27.495146 | controller | skipping: Conditional result was False 2026-03-10 19:12:27.500740 | 2026-03-10 19:12:27.500803 | TASK [Ensure legacy workspace directory] 2026-03-10 19:12:27.681730 | controller | changed 2026-03-10 19:12:27.697637 | 2026-03-10 19:12:27.697686 | PLAY RECAP 2026-03-10 19:12:27.697727 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2026-03-10 19:12:27.697748 | 2026-03-10 19:12:27.767982 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2026-03-10 19:12:27.768763 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2026-03-10 19:12:28.230579 | 2026-03-10 19:12:28.230731 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2026-03-10 19:12:28.249485 | 2026-03-10 19:12:28.249551 | TASK [Create zuul-output directory] 2026-03-10 19:12:28.541633 | controller | changed 2026-03-10 19:12:28.546174 | 2026-03-10 19:12:28.546242 | TASK [Slurp Zuul inventory test] 2026-03-10 19:12:28.792232 | controller -> localhost | ok 2026-03-10 19:12:28.797943 | 2026-03-10 19:12:28.798018 | TASK [Save zuul inventory] 2026-03-10 19:12:29.479142 | controller | changed 2026-03-10 19:12:29.483503 | 2026-03-10 19:12:29.483565 | TASK [Save zuul vars without the change_message] 2026-03-10 19:12:30.008540 | controller | changed 2026-03-10 19:12:30.025621 | 2026-03-10 19:12:30.025675 | PLAY RECAP 2026-03-10 19:12:30.025718 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-10 19:12:30.025741 | 2026-03-10 19:12:30.088666 | 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:30.089403 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2026-03-10 19:12:30.611794 | 2026-03-10 19:12:30.611897 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2026-03-10 19:12:30.630434 | 2026-03-10 19:12:30.630501 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2026-03-10 19:12:30.648274 | controller | ok 2026-03-10 19:12:30.663246 | 2026-03-10 19:12:30.663340 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2026-03-10 19:12:30.686613 | controller | skipping: Conditional result was False 2026-03-10 19:12:30.691716 | 2026-03-10 19:12:30.691782 | TASK [mirror-info-fork : Create /etc/ci] 2026-03-10 19:12:30.982668 | controller | ok 2026-03-10 19:12:30.987922 | 2026-03-10 19:12:30.987993 | TASK [mirror-info-fork : Install ci_mirror script] 2026-03-10 19:12:31.545317 | controller | ok 2026-03-10 19:12:31.554989 | 2026-03-10 19:12:31.555052 | TASK [Prepare workspace] 2026-03-10 19:12:31.572502 | controller | ok 2026-03-10 19:12:31.588023 | 2026-03-10 19:12:31.588092 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-10 19:12:31.858723 | controller | ok 2026-03-10 19:12:31.864373 | 2026-03-10 19:12:31.864439 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-10 19:12:34.066117 | controller | Output suppressed because no_log was given 2026-03-10 19:12:34.075028 | 2026-03-10 19:12:34.075093 | LOOP [Create zuul-output directory] 2026-03-10 19:12:34.249676 | controller | changed: "/home/zuul/ci-framework-data/logs" 2026-03-10 19:12:34.388338 | controller | ok: "/home/zuul/zuul-output/logs" 2026-03-10 19:12:34.400622 | 2026-03-10 19:12:34.400701 | TASK [Install required packages] 2026-03-10 19:13:06.499002 | controller | changed 2026-03-10 19:13:06.504092 | 2026-03-10 19:13:06.504153 | TASK [Install venv] 2026-03-10 19:14:05.805750 | controller | changed 2026-03-10 19:14:05.829110 | 2026-03-10 19:14:05.829196 | PLAY RECAP 2026-03-10 19:14:05.829239 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-10 19:14:05.829306 | 2026-03-10 19:14:05.894292 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2026-03-10 19:14:05.895032 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2026-03-10 19:14:06.368894 | 2026-03-10 19:14:06.368987 | PLAY [Run ci/playbooks/molecule-test.yml] 2026-03-10 19:14:06.387568 | 2026-03-10 19:14:06.387637 | TASK [Gather required facts] 2026-03-10 19:14:06.822486 | controller | ok 2026-03-10 19:14:06.827213 | 2026-03-10 19:14:06.827309 | TASK [Load environment var if instructed to] 2026-03-10 19:14:06.851145 | controller | skipping: Conditional result was False 2026-03-10 19:14:06.856089 | 2026-03-10 19:14:06.856151 | TASK [Ensure group_vars dir exists] 2026-03-10 19:14:07.137703 | controller | ok 2026-03-10 19:14:07.142969 | 2026-03-10 19:14:07.143029 | TASK [Print related variables] 2026-03-10 19:14:07.171320 | controller | ok: 2026-03-10 19:14:07.171434 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml 2026-03-10 19:14:07.171464 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager 2026-03-10 19:14:07.176084 | 2026-03-10 19:14:07.176148 | TASK [Run molecule] 2026-03-10 19:14:08.042447 | controller | INFO Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml 2026-03-10 19:14:08.428819 | controller | INFO Performing prerun with role_name_check=0... 2026-03-10 19:14:25.430757 | controller | WARNING Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:25.431144 | controller | WARNING Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:25.431558 | controller | WARNING Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:25.431983 | controller | WARNING Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:25.432415 | controller | WARNING Another version of 'cifmw.general' 1.0.0+91294f26 was found installed in /home/zuul/.cache/ansible-compat/119c88/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:25.432831 | controller | WARNING Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:25.433234 | controller | WARNING Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:25.433617 | controller | WARNING Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:25.434023 | controller | WARNING Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:25.434407 | controller | WARNING Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:25.434838 | controller | WARNING Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:25.435236 | controller | WARNING Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:25.435621 | controller | WARNING Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:25.436052 | controller | WARNING Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:25.436469 | controller | WARNING Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections). 2026-03-10 19:14:25.446692 | controller | INFO Running boot_order > prepare 2026-03-10 19:14:26.187643 | controller | 2026-03-10 19:14:26.187754 | controller | PLAY [Prepare] ***************************************************************** 2026-03-10 19:14:26.187852 | controller | 2026-03-10 19:14:26.187953 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-10 19:14:26.188047 | controller | Tuesday 10 March 2026 19:14:26 +0000 (0:00:00.024) 0:00:00.024 ********* 2026-03-10 19:14:27.039320 | controller | ok: [instance] 2026-03-10 19:14:27.039379 | controller | 2026-03-10 19:14:27.039460 | controller | TASK [Create custom basedir] *************************************************** 2026-03-10 19:14:27.039549 | controller | Tuesday 10 March 2026 19:14:27 +0000 (0:00:00.851) 0:00:00.876 ********* 2026-03-10 19:14:27.370158 | controller | changed: [instance] 2026-03-10 19:14:27.370216 | controller | 2026-03-10 19:14:27.370296 | controller | TASK [test_deps : Gather facts if they don't exist] **************************** 2026-03-10 19:14:27.370394 | controller | Tuesday 10 March 2026 19:14:27 +0000 (0:00:00.331) 0:00:01.207 ********* 2026-03-10 19:14:27.389465 | controller | skipping: [instance] 2026-03-10 19:14:27.389555 | controller | 2026-03-10 19:14:27.389649 | controller | TASK [test_deps : Gather variables for each operating system] ****************** 2026-03-10 19:14:27.389766 | controller | Tuesday 10 March 2026 19:14:27 +0000 (0:00:00.019) 0:00:01.227 ********* 2026-03-10 19:14:27.436945 | 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:27.437046 | controller | 2026-03-10 19:14:27.437159 | controller | TASK [test_deps : Detect mirrors file] ***************************************** 2026-03-10 19:14:27.437267 | controller | Tuesday 10 March 2026 19:14:27 +0000 (0:00:00.046) 0:00:01.274 ********* 2026-03-10 19:14:27.703971 | controller | ok: [instance] 2026-03-10 19:14:27.704016 | controller | 2026-03-10 19:14:27.704082 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] *********** 2026-03-10 19:14:27.704166 | controller | Tuesday 10 March 2026 19:14:27 +0000 (0:00:00.267) 0:00:01.542 ********* 2026-03-10 19:14:27.923674 | controller | ok: [instance] 2026-03-10 19:14:27.923764 | controller | 2026-03-10 19:14:27.923869 | controller | TASK [test_deps : Disable ubi 9 repos] ***************************************** 2026-03-10 19:14:27.923959 | controller | Tuesday 10 March 2026 19:14:27 +0000 (0:00:00.219) 0:00:01.761 ********* 2026-03-10 19:14:28.604022 | controller | ok: [instance] => (item=ubi-9-appstream) 2026-03-10 19:14:28.604077 | controller | ok: [instance] => (item=ubi-9-baseos) 2026-03-10 19:14:28.604152 | controller | ok: [instance] => (item=ubi-9-codeready-builder) 2026-03-10 19:14:28.604238 | controller | 2026-03-10 19:14:28.604328 | controller | TASK [Deploy repo-setup] ******************************************************* 2026-03-10 19:14:28.604421 | controller | Tuesday 10 March 2026 19:14:28 +0000 (0:00:00.679) 0:00:02.441 ********* 2026-03-10 19:14:28.660254 | controller | 2026-03-10 19:14:28.660366 | controller | TASK [repo_setup : Ensure directories are present] ***************************** 2026-03-10 19:14:28.660471 | controller | Tuesday 10 March 2026 19:14:28 +0000 (0:00:00.057) 0:00:02.498 ********* 2026-03-10 19:14:29.149154 | controller | changed: [instance] => (item=tmp) 2026-03-10 19:14:29.149216 | controller | changed: [instance] => (item=artifacts/repositories) 2026-03-10 19:14:29.149326 | controller | changed: [instance] => (item=venv/repo_setup) 2026-03-10 19:14:29.149409 | controller | 2026-03-10 19:14:29.149508 | controller | TASK [repo_setup : Make sure git-core package is installed] ******************** 2026-03-10 19:14:29.149594 | controller | Tuesday 10 March 2026 19:14:29 +0000 (0:00:00.488) 0:00:02.986 ********* 2026-03-10 19:14:29.917355 | controller | ok: [instance] 2026-03-10 19:14:29.917406 | controller | 2026-03-10 19:14:29.917626 | controller | TASK [repo_setup : Get repo-setup repository] ********************************** 2026-03-10 19:14:30.995787 | controller | Tuesday 10 March 2026 19:14:29 +0000 (0:00:00.768) 0:00:03.755 ********* 2026-03-10 19:14:30.995835 | controller | changed: [instance] 2026-03-10 19:14:30.996151 | controller | 2026-03-10 19:14:38.179442 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************ 2026-03-10 19:14:38.179473 | controller | Tuesday 10 March 2026 19:14:30 +0000 (0:00:01.078) 0:00:04.833 ********* 2026-03-10 19:14:38.179485 | controller | changed: [instance] 2026-03-10 19:14:38.812809 | controller | 2026-03-10 19:14:38.812853 | controller | TASK [repo_setup : Install repo-setup package] ********************************* 2026-03-10 19:14:38.812865 | controller | Tuesday 10 March 2026 19:14:38 +0000 (0:00:07.183) 0:00:12.017 ********* 2026-03-10 19:14:38.812877 | controller | changed: [instance] 2026-03-10 19:14:38.812908 | controller | 2026-03-10 19:14:38.812929 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] *** 2026-03-10 19:14:38.813099 | controller | Tuesday 10 March 2026 19:14:38 +0000 (0:00:00.633) 0:00:12.650 ********* 2026-03-10 19:14:38.831862 | controller | skipping: [instance] 2026-03-10 19:14:39.609428 | controller | 2026-03-10 19:14:39.609458 | controller | TASK [repo_setup : Run repo-setup] ********************************************* 2026-03-10 19:14:39.609466 | controller | Tuesday 10 March 2026 19:14:38 +0000 (0:00:00.019) 0:00:12.669 ********* 2026-03-10 19:14:39.609476 | controller | changed: [instance] 2026-03-10 19:14:39.645063 | controller | 2026-03-10 19:14:39.645090 | controller | TASK [repo_setup : Get component repo] ***************************************** 2026-03-10 19:14:39.645098 | controller | Tuesday 10 March 2026 19:14:39 +0000 (0:00:00.777) 0:00:13.447 ********* 2026-03-10 19:14:39.645108 | controller | skipping: [instance] 2026-03-10 19:14:39.680739 | controller | 2026-03-10 19:14:39.680763 | controller | TASK [repo_setup : Rename component repo] ************************************** 2026-03-10 19:14:39.680771 | controller | Tuesday 10 March 2026 19:14:39 +0000 (0:00:00.035) 0:00:13.482 ********* 2026-03-10 19:14:39.680781 | controller | skipping: [instance] 2026-03-10 19:14:39.716249 | controller | 2026-03-10 19:14:39.716272 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] ******* 2026-03-10 19:14:39.716280 | controller | Tuesday 10 March 2026 19:14:39 +0000 (0:00:00.035) 0:00:13.518 ********* 2026-03-10 19:14:39.716290 | controller | skipping: [instance] 2026-03-10 19:14:40.265325 | controller | 2026-03-10 19:14:40.265354 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************ 2026-03-10 19:14:40.265361 | controller | Tuesday 10 March 2026 19:14:39 +0000 (0:00:00.035) 0:00:13.554 ********* 2026-03-10 19:14:40.265370 | controller | changed: [instance] 2026-03-10 19:14:40.673658 | controller | 2026-03-10 19:14:40.673688 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] ******************* 2026-03-10 19:14:40.673696 | controller | Tuesday 10 March 2026 19:14:40 +0000 (0:00:00.548) 0:00:14.103 ********* 2026-03-10 19:14:40.673706 | controller | changed: [instance] 2026-03-10 19:14:40.701233 | controller | 2026-03-10 19:14:40.701265 | controller | TASK [repo_setup : Dump current-podified hash] ********************************* 2026-03-10 19:14:40.701272 | controller | Tuesday 10 March 2026 19:14:40 +0000 (0:00:00.408) 0:00:14.511 ********* 2026-03-10 19:14:40.701285 | controller | skipping: [instance] 2026-03-10 19:14:40.728453 | controller | 2026-03-10 19:14:40.728478 | controller | TASK [repo_setup : Slurp current podified hash] ******************************** 2026-03-10 19:14:40.728488 | controller | Tuesday 10 March 2026 19:14:40 +0000 (0:00:00.027) 0:00:14.539 ********* 2026-03-10 19:14:40.728498 | controller | skipping: [instance] 2026-03-10 19:14:40.756369 | controller | 2026-03-10 19:14:40.756388 | controller | TASK [repo_setup : Update the value of full_hash] ****************************** 2026-03-10 19:14:40.756395 | controller | Tuesday 10 March 2026 19:14:40 +0000 (0:00:00.027) 0:00:14.566 ********* 2026-03-10 19:14:40.756404 | controller | skipping: [instance] 2026-03-10 19:14:40.791620 | controller | 2026-03-10 19:14:40.791640 | controller | TASK [repo_setup : Export hashes facts for further use] ************************ 2026-03-10 19:14:40.791649 | controller | Tuesday 10 March 2026 19:14:40 +0000 (0:00:00.028) 0:00:14.594 ********* 2026-03-10 19:14:40.791659 | controller | ok: [instance] 2026-03-10 19:14:40.815575 | controller | 2026-03-10 19:14:40.815595 | controller | TASK [repo_setup : Create download directory] ********************************** 2026-03-10 19:14:40.815603 | controller | Tuesday 10 March 2026 19:14:40 +0000 (0:00:00.035) 0:00:14.629 ********* 2026-03-10 19:14:40.815621 | controller | skipping: [instance] 2026-03-10 19:14:40.839464 | controller | 2026-03-10 19:14:40.839484 | controller | TASK [repo_setup : Print the URL to request] *********************************** 2026-03-10 19:14:40.839493 | controller | Tuesday 10 March 2026 19:14:40 +0000 (0:00:00.023) 0:00:14.653 ********* 2026-03-10 19:14:40.839503 | controller | skipping: [instance] 2026-03-10 19:14:40.839651 | controller | 2026-03-10 19:14:40.839829 | controller | TASK [Download the RPM] ******************************************************** 2026-03-10 19:14:40.839993 | controller | Tuesday 10 March 2026 19:14:40 +0000 (0:00:00.024) 0:00:14.677 ********* 2026-03-10 19:14:40.863675 | controller | skipping: [instance] 2026-03-10 19:14:40.863874 | controller | 2026-03-10 19:14:40.864036 | controller | TASK [repo_setup : Install RHOS Release tool] ********************************** 2026-03-10 19:14:40.864179 | controller | Tuesday 10 March 2026 19:14:40 +0000 (0:00:00.024) 0:00:14.702 ********* 2026-03-10 19:14:40.888327 | controller | skipping: [instance] 2026-03-10 19:14:40.888490 | controller | 2026-03-10 19:14:40.888626 | controller | TASK [repo_setup : Get rhos-release tool version] ****************************** 2026-03-10 19:14:40.888770 | controller | Tuesday 10 March 2026 19:14:40 +0000 (0:00:00.024) 0:00:14.726 ********* 2026-03-10 19:14:40.913229 | controller | skipping: [instance] 2026-03-10 19:14:40.913400 | controller | 2026-03-10 19:14:40.913550 | controller | TASK [repo_setup : Print rhos-release tool version] **************************** 2026-03-10 19:14:40.913686 | controller | Tuesday 10 March 2026 19:14:40 +0000 (0:00:00.024) 0:00:14.751 ********* 2026-03-10 19:14:40.939076 | controller | skipping: [instance] 2026-03-10 19:14:40.939238 | controller | 2026-03-10 19:14:40.939396 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] *** 2026-03-10 19:14:40.939542 | controller | Tuesday 10 March 2026 19:14:40 +0000 (0:00:00.025) 0:00:14.777 ********* 2026-03-10 19:14:40.961557 | controller | skipping: [instance] 2026-03-10 19:14:40.961747 | controller | 2026-03-10 19:14:40.961931 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] *************************** 2026-03-10 19:14:40.962081 | controller | Tuesday 10 March 2026 19:14:40 +0000 (0:00:00.022) 0:00:14.799 ********* 2026-03-10 19:14:41.127232 | controller | ok: [instance] 2026-03-10 19:14:41.329664 | controller | 2026-03-10 19:14:41.329690 | controller | TASK [repo_setup : Use RDO proxy mirrors] ************************************** 2026-03-10 19:14:41.329698 | controller | Tuesday 10 March 2026 19:14:41 +0000 (0:00:00.165) 0:00:14.965 ********* 2026-03-10 19:14:41.329707 | controller | changed: [instance] 2026-03-10 19:14:41.536303 | controller | 2026-03-10 19:14:41.536331 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] *** 2026-03-10 19:14:41.536338 | controller | Tuesday 10 March 2026 19:14:41 +0000 (0:00:00.202) 0:00:15.167 ********* 2026-03-10 19:14:41.536348 | controller | changed: [instance] 2026-03-10 19:14:41.556792 | controller | 2026-03-10 19:14:41.556823 | controller | TASK [repo_setup : Check for gating.repo file on content provider] ************* 2026-03-10 19:14:41.556831 | controller | Tuesday 10 March 2026 19:14:41 +0000 (0:00:00.206) 0:00:15.374 ********* 2026-03-10 19:14:41.556841 | controller | skipping: [instance] 2026-03-10 19:14:41.577543 | controller | 2026-03-10 19:14:41.577571 | controller | TASK [repo_setup : Populate gating repo from content provider ip] ************** 2026-03-10 19:14:41.577580 | controller | Tuesday 10 March 2026 19:14:41 +0000 (0:00:00.020) 0:00:15.394 ********* 2026-03-10 19:14:41.577591 | controller | skipping: [instance] 2026-03-10 19:14:41.598078 | controller | 2026-03-10 19:14:41.598097 | controller | TASK [repo_setup : Check for DLRN repo at the destination] ********************* 2026-03-10 19:14:41.598105 | controller | Tuesday 10 March 2026 19:14:41 +0000 (0:00:00.020) 0:00:15.415 ********* 2026-03-10 19:14:41.598115 | controller | skipping: [instance] 2026-03-10 19:14:41.618262 | controller | 2026-03-10 19:14:41.618281 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] *** 2026-03-10 19:14:41.618290 | controller | Tuesday 10 March 2026 19:14:41 +0000 (0:00:00.020) 0:00:15.436 ********* 2026-03-10 19:14:41.618300 | controller | skipping: [instance] 2026-03-10 19:14:41.638808 | controller | 2026-03-10 19:14:41.638844 | controller | TASK [repo_setup : Check for DLRN component repo] ****************************** 2026-03-10 19:14:41.638874 | controller | Tuesday 10 March 2026 19:14:41 +0000 (0:00:00.020) 0:00:15.456 ********* 2026-03-10 19:14:41.638886 | controller | skipping: [instance] 2026-03-10 19:14:41.660121 | controller | 2026-03-10 19:14:41.660145 | controller | TASK [repo_setup : Lower the priority of component repos to allow installation from gating repo] *** 2026-03-10 19:14:41.660153 | controller | Tuesday 10 March 2026 19:14:41 +0000 (0:00:00.020) 0:00:15.476 ********* 2026-03-10 19:14:41.660164 | controller | skipping: [instance] 2026-03-10 19:14:41.660254 | controller | 2026-03-10 19:14:41.660389 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ******** 2026-03-10 19:14:41.660518 | controller | Tuesday 10 March 2026 19:14:41 +0000 (0:00:00.021) 0:00:15.498 ********* 2026-03-10 19:14:41.675889 | controller | skipping: [instance] 2026-03-10 19:14:41.676023 | controller | 2026-03-10 19:14:41.676164 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ****** 2026-03-10 19:14:41.676287 | controller | Tuesday 10 March 2026 19:14:41 +0000 (0:00:00.015) 0:00:15.514 ********* 2026-03-10 19:14:41.703220 | controller | skipping: [instance] 2026-03-10 19:14:41.703354 | controller | 2026-03-10 19:14:41.703481 | controller | TASK [repo_setup : Cleanup existing metadata] ********************************** 2026-03-10 19:14:41.703602 | controller | Tuesday 10 March 2026 19:14:41 +0000 (0:00:00.027) 0:00:15.541 ********* 2026-03-10 19:14:41.718988 | controller | skipping: [instance] 2026-03-10 19:14:41.719120 | controller | 2026-03-10 19:14:41.719249 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] ********* 2026-03-10 19:14:41.719374 | controller | Tuesday 10 March 2026 19:14:41 +0000 (0:00:00.015) 0:00:15.557 ********* 2026-03-10 19:14:41.738675 | controller | skipping: [instance] 2026-03-10 19:14:41.738799 | controller | 2026-03-10 19:14:41.738922 | controller | TASK [test_deps : Look for redhat-release rpm] ********************************* 2026-03-10 19:14:41.739025 | controller | Tuesday 10 March 2026 19:14:41 +0000 (0:00:00.019) 0:00:15.577 ********* 2026-03-10 19:14:41.766337 | controller | skipping: [instance] 2026-03-10 19:14:41.766468 | controller | 2026-03-10 19:14:41.766596 | controller | TASK [test_deps : Remove redhat-release] *************************************** 2026-03-10 19:14:41.766738 | controller | Tuesday 10 March 2026 19:14:41 +0000 (0:00:00.027) 0:00:15.604 ********* 2026-03-10 19:14:41.793689 | controller | skipping: [instance] 2026-03-10 19:14:41.793852 | controller | 2026-03-10 19:14:41.793991 | controller | TASK [test_deps : Install centos-stream-release] ******************************* 2026-03-10 19:14:41.794117 | controller | Tuesday 10 March 2026 19:14:41 +0000 (0:00:00.027) 0:00:15.632 ********* 2026-03-10 19:14:41.820988 | controller | skipping: [instance] 2026-03-10 19:14:41.821133 | controller | 2026-03-10 19:14:41.821271 | controller | TASK [test_deps : Install additional packages] ********************************* 2026-03-10 19:14:41.821385 | controller | Tuesday 10 March 2026 19:14:41 +0000 (0:00:00.027) 0:00:15.659 ********* 2026-03-10 19:14:41.848166 | controller | skipping: [instance] 2026-03-10 19:14:41.848307 | controller | 2026-03-10 19:14:41.848434 | controller | TASK [test_deps : Install selinux python libs] ********************************* 2026-03-10 19:14:41.848554 | controller | Tuesday 10 March 2026 19:14:41 +0000 (0:00:00.027) 0:00:15.686 ********* 2026-03-10 19:15:28.160815 | controller | ok: [instance] 2026-03-10 19:15:29.300440 | controller | 2026-03-10 19:15:29.300468 | controller | TASK [test_deps : Install python yaml libs] ************************************ 2026-03-10 19:15:29.300476 | controller | Tuesday 10 March 2026 19:15:28 +0000 (0:00:46.310) 0:01:01.997 ********* 2026-03-10 19:15:29.300486 | controller | ok: [instance] 2026-03-10 19:15:29.330625 | controller | 2026-03-10 19:15:29.330644 | controller | TASK [test_deps : Install extra packages] ************************************** 2026-03-10 19:15:29.330651 | controller | Tuesday 10 March 2026 19:15:29 +0000 (0:00:01.141) 0:01:03.138 ********* 2026-03-10 19:15:29.330660 | controller | skipping: [instance] 2026-03-10 19:15:29.373118 | controller | 2026-03-10 19:15:29.373138 | controller | TASK [ci_setup : Gather variables for each operating system] ******************* 2026-03-10 19:15:29.373146 | controller | Tuesday 10 March 2026 19:15:29 +0000 (0:00:00.030) 0:01:03.168 ********* 2026-03-10 19:15:29.373157 | 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:29.392756 | controller | 2026-03-10 19:15:29.392773 | controller | TASK [ci_setup : List packages to install] ************************************* 2026-03-10 19:15:29.392779 | controller | Tuesday 10 March 2026 19:15:29 +0000 (0:00:00.042) 0:01:03.211 ********* 2026-03-10 19:15:29.392792 | controller | ok: [instance] => 2026-03-10 19:15:29.393647 | controller | cifmw_ci_setup_packages: 2026-03-10 19:15:32.989964 | controller | - bash-completion 2026-03-10 19:15:32.989994 | controller | - ca-certificates 2026-03-10 19:15:32.990001 | controller | - git-core 2026-03-10 19:15:32.990007 | controller | - make 2026-03-10 19:15:32.990012 | controller | - tar 2026-03-10 19:15:32.990017 | controller | - tmux 2026-03-10 19:15:32.990022 | controller | - python3-pip 2026-03-10 19:15:32.990027 | controller | 2026-03-10 19:15:32.990033 | controller | TASK [ci_setup : Install needed packages] ************************************** 2026-03-10 19:15:32.990038 | controller | Tuesday 10 March 2026 19:15:29 +0000 (0:00:00.020) 0:01:03.231 ********* 2026-03-10 19:15:32.990047 | controller | changed: [instance] 2026-03-10 19:15:33.160168 | controller | 2026-03-10 19:15:33.160197 | controller | TASK [ci_setup : Gather version of openshift client] *************************** 2026-03-10 19:15:33.160204 | controller | Tuesday 10 March 2026 19:15:32 +0000 (0:00:03.596) 0:01:06.827 ********* 2026-03-10 19:15:33.160214 | controller | ok: [instance] 2026-03-10 19:15:33.346954 | controller | 2026-03-10 19:15:33.346984 | controller | TASK [ci_setup : Ensure openshift client install path is present] ************** 2026-03-10 19:15:33.346991 | controller | Tuesday 10 March 2026 19:15:33 +0000 (0:00:00.170) 0:01:06.998 ********* 2026-03-10 19:15:33.347001 | controller | changed: [instance] 2026-03-10 19:15:39.247423 | controller | 2026-03-10 19:15:39.247454 | controller | TASK [ci_setup : Install openshift client] ************************************* 2026-03-10 19:15:39.247461 | controller | Tuesday 10 March 2026 19:15:33 +0000 (0:00:00.186) 0:01:07.184 ********* 2026-03-10 19:15:39.247471 | controller | changed: [instance] 2026-03-10 19:15:39.280790 | controller | 2026-03-10 19:15:39.280820 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] ********************** 2026-03-10 19:15:39.280828 | controller | Tuesday 10 March 2026 19:15:39 +0000 (0:00:05.900) 0:01:13.085 ********* 2026-03-10 19:15:39.280838 | controller | ok: [instance] 2026-03-10 19:15:39.280861 | controller | 2026-03-10 19:15:39.281113 | controller | TASK [ci_setup : Create completion file] *************************************** 2026-03-10 19:15:39.525098 | controller | Tuesday 10 March 2026 19:15:39 +0000 (0:00:00.033) 0:01:13.119 ********* 2026-03-10 19:15:39.525127 | controller | changed: [instance] 2026-03-10 19:15:39.787819 | controller | 2026-03-10 19:15:39.787855 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************ 2026-03-10 19:15:39.787864 | controller | Tuesday 10 March 2026 19:15:39 +0000 (0:00:00.243) 0:01:13.362 ********* 2026-03-10 19:15:39.787883 | controller | changed: [instance] 2026-03-10 19:15:39.787903 | controller | 2026-03-10 19:15:39.787923 | controller | TASK [ci_setup : Check rhsm status] ******************************************** 2026-03-10 19:15:39.787933 | controller | Tuesday 10 March 2026 19:15:39 +0000 (0:00:00.262) 0:01:13.625 ********* 2026-03-10 19:15:39.805178 | controller | skipping: [instance] 2026-03-10 19:15:39.805253 | controller | 2026-03-10 19:15:39.805352 | controller | TASK [ci_setup : Gather the repos to be enabled] ******************************* 2026-03-10 19:15:39.805449 | controller | Tuesday 10 March 2026 19:15:39 +0000 (0:00:00.017) 0:01:13.643 ********* 2026-03-10 19:15:39.821673 | controller | skipping: [instance] 2026-03-10 19:15:39.821768 | controller | 2026-03-10 19:15:39.821870 | controller | TASK [ci_setup : Enabling the required repositories.] ************************** 2026-03-10 19:15:39.821984 | controller | Tuesday 10 March 2026 19:15:39 +0000 (0:00:00.016) 0:01:13.660 ********* 2026-03-10 19:15:39.838451 | controller | skipping: [instance] 2026-03-10 19:15:39.838528 | controller | 2026-03-10 19:15:39.838620 | controller | TASK [ci_setup : Get current /etc/redhat-release] ****************************** 2026-03-10 19:15:39.838736 | controller | Tuesday 10 March 2026 19:15:39 +0000 (0:00:00.016) 0:01:13.676 ********* 2026-03-10 19:15:39.854785 | controller | skipping: [instance] 2026-03-10 19:15:39.854878 | controller | 2026-03-10 19:15:39.854985 | controller | TASK [ci_setup : Print current /etc/redhat-release] **************************** 2026-03-10 19:15:39.855070 | controller | Tuesday 10 March 2026 19:15:39 +0000 (0:00:00.016) 0:01:13.693 ********* 2026-03-10 19:15:39.870839 | controller | skipping: [instance] 2026-03-10 19:15:39.870935 | controller | 2026-03-10 19:15:39.871028 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] ********* 2026-03-10 19:15:39.871110 | controller | Tuesday 10 March 2026 19:15:39 +0000 (0:00:00.016) 0:01:13.709 ********* 2026-03-10 19:15:39.891594 | controller | skipping: [instance] 2026-03-10 19:15:39.891682 | controller | 2026-03-10 19:15:39.891803 | controller | TASK [ci_setup : Manage directories] ******************************************* 2026-03-10 19:15:39.891886 | controller | Tuesday 10 March 2026 19:15:39 +0000 (0:00:00.020) 0:01:13.730 ********* 2026-03-10 19:15:40.789492 | controller | changed: [instance] => (item=/opt/basedir/artifacts/manifests/openstack/cr) 2026-03-10 19:15:41.516818 | controller | changed: [instance] => (item=/opt/basedir/logs) 2026-03-10 19:15:41.516854 | controller | ok: [instance] => (item=/opt/basedir/tmp) 2026-03-10 19:15:41.516863 | controller | changed: [instance] => (item=/opt/basedir/volumes) 2026-03-10 19:15:41.516871 | controller | changed: [instance] => (item=/opt/basedir/artifacts/parameters) 2026-03-10 19:15:41.516878 | controller | 2026-03-10 19:15:41.516885 | controller | TASK [libvirt_manager : Get installed packages list] *************************** 2026-03-10 19:15:41.516892 | controller | Tuesday 10 March 2026 19:15:40 +0000 (0:00:00.896) 0:01:14.626 ********* 2026-03-10 19:15:41.516904 | controller | ok: [instance] 2026-03-10 19:15:41.516943 | controller | 2026-03-10 19:15:41.516954 | controller | TASK [libvirt_manager : Populate service facts] ******************************** 2026-03-10 19:15:41.517063 | controller | Tuesday 10 March 2026 19:15:41 +0000 (0:00:00.727) 0:01:15.354 ********* 2026-03-10 19:15:43.215385 | controller | ok: [instance] 2026-03-10 19:15:43.273745 | controller | 2026-03-10 19:15:43.273763 | controller | TASK [libvirt_manager : Start virtqemud socket service] ************************ 2026-03-10 19:15:43.273770 | controller | Tuesday 10 March 2026 19:15:43 +0000 (0:00:01.698) 0:01:17.053 ********* 2026-03-10 19:15:43.273778 | controller | skipping: [instance] => (item=virtqemud.service) 2026-03-10 19:15:43.330835 | controller | skipping: [instance] => (item=virtqemud.socket) 2026-03-10 19:15:43.330885 | controller | skipping: [instance] 2026-03-10 19:15:43.330896 | controller | 2026-03-10 19:15:43.330905 | controller | TASK [libvirt_manager : Start virtnetworkd socket service] ********************* 2026-03-10 19:15:43.330926 | controller | Tuesday 10 March 2026 19:15:43 +0000 (0:00:00.058) 0:01:17.111 ********* 2026-03-10 19:15:43.330941 | controller | skipping: [instance] => (item=virtnetworkd.service) 2026-03-10 19:15:43.331318 | controller | skipping: [instance] => (item=virtnetworkd.socket) 2026-03-10 19:15:43.331346 | controller | skipping: [instance] 2026-03-10 19:15:43.389868 | controller | 2026-03-10 19:15:43.389904 | controller | TASK [libvirt_manager : Start virtstoraged socket service] ********************* 2026-03-10 19:15:43.389926 | controller | Tuesday 10 March 2026 19:15:43 +0000 (0:00:00.057) 0:01:17.169 ********* 2026-03-10 19:15:43.389941 | controller | skipping: [instance] => (item=virtstoraged.service) 2026-03-10 19:15:43.389985 | controller | skipping: [instance] => (item=virtstoraged.socket) 2026-03-10 19:15:43.390003 | controller | skipping: [instance] 2026-03-10 19:15:43.390010 | controller | 2026-03-10 19:15:43.390018 | controller | TASK [libvirt_manager : Set _is_deepscrub internal fact] *********************** 2026-03-10 19:15:43.434854 | controller | Tuesday 10 March 2026 19:15:43 +0000 (0:00:00.058) 0:01:17.227 ********* 2026-03-10 19:15:43.434893 | controller | ok: [instance] 2026-03-10 19:15:43.434949 | controller | 2026-03-10 19:15:43.434963 | controller | TASK [libvirt_manager : List all of the existing virtual machines] ************* 2026-03-10 19:15:43.434986 | controller | Tuesday 10 March 2026 19:15:43 +0000 (0:00:00.045) 0:01:17.273 ********* 2026-03-10 19:15:43.513284 | controller | skipping: [instance] 2026-03-10 19:15:43.568176 | controller | 2026-03-10 19:15:43.568193 | controller | TASK [libvirt_manager : Get pool configuration] ******************************** 2026-03-10 19:15:43.568200 | controller | Tuesday 10 March 2026 19:15:43 +0000 (0:00:00.077) 0:01:17.350 ********* 2026-03-10 19:15:43.568208 | controller | skipping: [instance] 2026-03-10 19:15:43.620184 | controller | 2026-03-10 19:15:43.620201 | controller | TASK [libvirt_manager : Filter out target environment] ************************* 2026-03-10 19:15:43.620214 | controller | Tuesday 10 March 2026 19:15:43 +0000 (0:00:00.055) 0:01:17.406 ********* 2026-03-10 19:15:43.620223 | controller | skipping: [instance] 2026-03-10 19:15:43.675291 | controller | 2026-03-10 19:15:43.675317 | controller | TASK [libvirt_manager : Expose cleanup list] *********************************** 2026-03-10 19:15:43.675325 | controller | Tuesday 10 March 2026 19:15:43 +0000 (0:00:00.052) 0:01:17.458 ********* 2026-03-10 19:15:43.675333 | controller | skipping: [instance] 2026-03-10 19:15:43.730645 | controller | 2026-03-10 19:15:43.730667 | controller | TASK [libvirt_manager : Clean ssh known_hosts] ********************************* 2026-03-10 19:15:43.730677 | controller | Tuesday 10 March 2026 19:15:43 +0000 (0:00:00.054) 0:01:17.513 ********* 2026-03-10 19:15:43.730688 | controller | skipping: [instance] 2026-03-10 19:15:43.789637 | controller | 2026-03-10 19:15:43.789656 | controller | TASK [libvirt_manager : Destroy machine] *************************************** 2026-03-10 19:15:43.789664 | controller | Tuesday 10 March 2026 19:15:43 +0000 (0:00:00.055) 0:01:17.568 ********* 2026-03-10 19:15:43.789673 | controller | skipping: [instance] 2026-03-10 19:15:43.847971 | controller | 2026-03-10 19:15:43.847989 | controller | TASK [libvirt_manager : Undefine machine] ************************************** 2026-03-10 19:15:43.847995 | controller | Tuesday 10 March 2026 19:15:43 +0000 (0:00:00.058) 0:01:17.627 ********* 2026-03-10 19:15:43.848004 | controller | skipping: [instance] 2026-03-10 19:15:43.910636 | controller | 2026-03-10 19:15:43.910651 | controller | TASK [libvirt_manager : (localhost) Clean ssh jumpers] ************************* 2026-03-10 19:15:43.910658 | controller | Tuesday 10 March 2026 19:15:43 +0000 (0:00:00.058) 0:01:17.686 ********* 2026-03-10 19:15:43.910666 | controller | skipping: [instance] 2026-03-10 19:15:43.968158 | controller | 2026-03-10 19:15:43.968174 | controller | TASK [libvirt_manager : (instance) Clean ssh jumpers] ************************** 2026-03-10 19:15:43.968181 | controller | Tuesday 10 March 2026 19:15:43 +0000 (0:00:00.062) 0:01:17.748 ********* 2026-03-10 19:15:43.968189 | controller | skipping: [instance] 2026-03-10 19:15:44.024792 | controller | 2026-03-10 19:15:44.024809 | controller | TASK [libvirt_manager : Get network list] ************************************** 2026-03-10 19:15:44.024816 | controller | Tuesday 10 March 2026 19:15:43 +0000 (0:00:00.057) 0:01:17.806 ********* 2026-03-10 19:15:44.024824 | controller | skipping: [instance] 2026-03-10 19:15:44.079410 | controller | 2026-03-10 19:15:44.079427 | controller | TASK [libvirt_manager : Filter out target nets] ******************************** 2026-03-10 19:15:44.079434 | controller | Tuesday 10 March 2026 19:15:44 +0000 (0:00:00.056) 0:01:17.862 ********* 2026-03-10 19:15:44.079448 | controller | skipping: [instance] 2026-03-10 19:15:44.132309 | controller | 2026-03-10 19:15:44.132333 | controller | TASK [libvirt_manager : Expose cleanup list] *********************************** 2026-03-10 19:15:44.132344 | controller | Tuesday 10 March 2026 19:15:44 +0000 (0:00:00.054) 0:01:17.917 ********* 2026-03-10 19:15:44.132355 | controller | skipping: [instance] 2026-03-10 19:15:44.186359 | controller | 2026-03-10 19:15:44.186377 | controller | TASK [Remove dnsmasq network bits] ********************************************* 2026-03-10 19:15:44.186384 | controller | Tuesday 10 March 2026 19:15:44 +0000 (0:00:00.052) 0:01:17.970 ********* 2026-03-10 19:15:44.186392 | controller | skipping: [instance] 2026-03-10 19:15:44.240469 | controller | 2026-03-10 19:15:44.240486 | controller | TASK [libvirt_manager : Destroy networks] ************************************** 2026-03-10 19:15:44.240493 | controller | Tuesday 10 March 2026 19:15:44 +0000 (0:00:00.053) 0:01:18.024 ********* 2026-03-10 19:15:44.240501 | controller | skipping: [instance] 2026-03-10 19:15:44.323618 | controller | 2026-03-10 19:15:44.323636 | controller | TASK [libvirt_manager : Undefine networks] ************************************* 2026-03-10 19:15:44.323643 | controller | Tuesday 10 March 2026 19:15:44 +0000 (0:00:00.054) 0:01:18.078 ********* 2026-03-10 19:15:44.323651 | controller | skipping: [instance] 2026-03-10 19:15:44.378462 | controller | 2026-03-10 19:15:44.378495 | controller | TASK [libvirt_manager : Ensure no trace of networks lays in nmcli] ************* 2026-03-10 19:15:44.378508 | controller | Tuesday 10 March 2026 19:15:44 +0000 (0:00:00.083) 0:01:18.161 ********* 2026-03-10 19:15:44.378534 | controller | skipping: [instance] 2026-03-10 19:15:44.378743 | controller | 2026-03-10 19:15:44.378882 | controller | TASK [libvirt_manager : Find dummy interface connection files] ***************** 2026-03-10 19:15:44.379019 | controller | Tuesday 10 March 2026 19:15:44 +0000 (0:00:00.055) 0:01:18.216 ********* 2026-03-10 19:15:44.435066 | controller | skipping: [instance] 2026-03-10 19:15:44.435327 | controller | 2026-03-10 19:15:44.435573 | controller | TASK [libvirt_manager : Remove dummy interface connections] ******************** 2026-03-10 19:15:44.435811 | controller | Tuesday 10 March 2026 19:15:44 +0000 (0:00:00.056) 0:01:18.273 ********* 2026-03-10 19:15:44.493630 | controller | skipping: [instance] 2026-03-10 19:15:44.493908 | controller | 2026-03-10 19:15:44.494055 | controller | TASK [libvirt_manager : Clean firewalld libvirt zone] ************************** 2026-03-10 19:15:44.494176 | controller | Tuesday 10 March 2026 19:15:44 +0000 (0:00:00.058) 0:01:18.331 ********* 2026-03-10 19:15:44.551829 | controller | skipping: [instance] 2026-03-10 19:15:44.551987 | controller | 2026-03-10 19:15:44.552122 | controller | TASK [libvirt_manager : Ensure firewalld is enabled and started] *************** 2026-03-10 19:15:44.552252 | controller | Tuesday 10 March 2026 19:15:44 +0000 (0:00:00.058) 0:01:18.390 ********* 2026-03-10 19:15:44.609507 | controller | skipping: [instance] 2026-03-10 19:15:44.609653 | controller | 2026-03-10 19:15:44.609818 | controller | TASK [libvirt_manager : Disable masquerade in firewalld default zone] ********** 2026-03-10 19:15:44.609975 | controller | Tuesday 10 March 2026 19:15:44 +0000 (0:00:00.057) 0:01:18.447 ********* 2026-03-10 19:15:44.667099 | controller | skipping: [instance] 2026-03-10 19:15:44.667316 | controller | 2026-03-10 19:15:44.667560 | controller | TASK [libvirt_manager : Disable forward in firewalld libvirt zone] ************* 2026-03-10 19:15:44.667831 | controller | Tuesday 10 March 2026 19:15:44 +0000 (0:00:00.057) 0:01:18.505 ********* 2026-03-10 19:15:44.722353 | controller | skipping: [instance] 2026-03-10 19:15:44.722615 | controller | 2026-03-10 19:15:44.722903 | controller | TASK [libvirt_manager : Remove cifmw storage pool] ***************************** 2026-03-10 19:15:44.723180 | controller | Tuesday 10 March 2026 19:15:44 +0000 (0:00:00.054) 0:01:18.560 ********* 2026-03-10 19:15:44.780167 | controller | skipping: [instance] 2026-03-10 19:15:44.780379 | controller | 2026-03-10 19:15:44.780524 | controller | TASK [libvirt_manager : Remove overlay images from ocp_volume pools if exists] *** 2026-03-10 19:15:44.780657 | controller | Tuesday 10 March 2026 19:15:44 +0000 (0:00:00.057) 0:01:18.618 ********* 2026-03-10 19:15:44.820839 | controller | skipping: [instance] 2026-03-10 19:15:44.821151 | controller | 2026-03-10 19:15:44.821401 | controller | TASK [libvirt_manager : Remove ocp_volumes storage pool] *********************** 2026-03-10 19:15:44.821578 | controller | Tuesday 10 March 2026 19:15:44 +0000 (0:00:00.040) 0:01:18.658 ********* 2026-03-10 19:15:44.875929 | controller | skipping: [instance] 2026-03-10 19:15:44.876095 | controller | 2026-03-10 19:15:44.876247 | controller | TASK [libvirt_manager : Refresh all pools after actions] *********************** 2026-03-10 19:15:44.876392 | controller | Tuesday 10 March 2026 19:15:44 +0000 (0:00:00.055) 0:01:18.714 ********* 2026-03-10 19:15:44.933142 | controller | skipping: [instance] 2026-03-10 19:15:44.933401 | controller | 2026-03-10 19:15:44.933645 | controller | TASK [libvirt_manager : Get temporary key status] ****************************** 2026-03-10 19:15:44.933840 | controller | Tuesday 10 March 2026 19:15:44 +0000 (0:00:00.057) 0:01:18.771 ********* 2026-03-10 19:15:45.111690 | controller | ok: [instance] 2026-03-10 19:15:45.140583 | controller | 2026-03-10 19:15:45.140608 | controller | TASK [libvirt_manager : Get public key] **************************************** 2026-03-10 19:15:45.140618 | controller | Tuesday 10 March 2026 19:15:45 +0000 (0:00:00.178) 0:01:18.949 ********* 2026-03-10 19:15:45.140630 | controller | skipping: [instance] 2026-03-10 19:15:45.197710 | controller | 2026-03-10 19:15:45.197755 | controller | TASK [libvirt_manager : Remove public key] ************************************* 2026-03-10 19:15:45.197765 | controller | Tuesday 10 March 2026 19:15:45 +0000 (0:00:00.028) 0:01:18.978 ********* 2026-03-10 19:15:45.197777 | controller | skipping: [instance] 2026-03-10 19:15:45.231410 | controller | 2026-03-10 19:15:45.231427 | controller | TASK [libvirt_manager : Remove keypair] **************************************** 2026-03-10 19:15:45.231433 | controller | Tuesday 10 March 2026 19:15:45 +0000 (0:00:00.057) 0:01:19.035 ********* 2026-03-10 19:15:45.231447 | controller | skipping: [instance] => (item=cifmw_reproducer_key.pub) 2026-03-10 19:15:45.487748 | controller | skipping: [instance] => (item=cifmw_reproducer_key) 2026-03-10 19:15:45.487775 | controller | skipping: [instance] 2026-03-10 19:15:45.487783 | controller | 2026-03-10 19:15:45.487792 | controller | TASK [libvirt_manager : List libvirt logs] ************************************* 2026-03-10 19:15:45.487797 | controller | Tuesday 10 March 2026 19:15:45 +0000 (0:00:00.033) 0:01:19.069 ********* 2026-03-10 19:15:45.487806 | controller | [WARNING]: Skipped '/var/log/libvirt/qemu/' path due to this access issue: 2026-03-10 19:15:45.508008 | controller | '/var/log/libvirt/qemu/' is not a directory 2026-03-10 19:15:45.508030 | controller | ok: [instance] 2026-03-10 19:15:45.536197 | controller | 2026-03-10 19:15:45.536221 | controller | TASK [libvirt_manager : Remove detected logs] ********************************** 2026-03-10 19:15:45.536231 | controller | Tuesday 10 March 2026 19:15:45 +0000 (0:00:00.276) 0:01:19.346 ********* 2026-03-10 19:15:45.536242 | controller | skipping: [instance] 2026-03-10 19:15:46.793846 | controller | 2026-03-10 19:15:46.793882 | controller | TASK [libvirt_manager : Remove workload and volumes] *************************** 2026-03-10 19:15:46.793892 | controller | Tuesday 10 March 2026 19:15:45 +0000 (0:00:00.028) 0:01:19.374 ********* 2026-03-10 19:15:46.793904 | controller | ok: [instance] => (item=workload) 2026-03-10 19:15:46.793948 | controller | changed: [instance] => (item=volumes) 2026-03-10 19:15:46.793956 | controller | ok: [instance] => (item=artifacts/debug_network_data.yml) 2026-03-10 19:15:46.793965 | controller | ok: [instance] => (item=artifacts/debug_pub_net_value.txt) 2026-03-10 19:15:46.794461 | controller | ok: [instance] => (item=artifacts/debug_cifmw_libvirt_manager_layout.yml) 2026-03-10 19:15:46.794491 | controller | ok: [instance] => (item=artifacts/interfaces-info.yml) 2026-03-10 19:15:47.145196 | controller | ok: [instance] => (item=artifacts/libvirt-uuids.yml) 2026-03-10 19:15:47.145221 | controller | ok: [instance] => (item=artifacts/net-map-def-patch.yml) 2026-03-10 19:15:47.145228 | controller | 2026-03-10 19:15:47.145234 | controller | TASK [libvirt_manager : Deepscrub data] **************************************** 2026-03-10 19:15:47.145239 | controller | Tuesday 10 March 2026 19:15:46 +0000 (0:00:01.257) 0:01:20.631 ********* 2026-03-10 19:15:47.145248 | controller | ok: [instance] => (item=images) 2026-03-10 19:15:48.304486 | controller | ok: [instance] => (item=artifacts/virtual-nodes.yml) 2026-03-10 19:15:48.304520 | controller | 2026-03-10 19:15:48.304528 | controller | TASK [podman : Ensure podman is installed] ************************************* 2026-03-10 19:15:48.304534 | controller | Tuesday 10 March 2026 19:15:47 +0000 (0:00:00.351) 0:01:20.983 ********* 2026-03-10 19:15:48.304544 | controller | ok: [instance] 2026-03-10 19:15:48.635852 | controller | 2026-03-10 19:15:48.635893 | controller | TASK [podman : Enable loginctl linger for ansible_user_id] ********************* 2026-03-10 19:15:48.635904 | controller | Tuesday 10 March 2026 19:15:48 +0000 (0:00:01.159) 0:01:22.142 ********* 2026-03-10 19:15:48.635935 | controller | changed: [instance] 2026-03-10 19:15:48.635963 | controller | 2026-03-10 19:15:48.636075 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul] ******************* 2026-03-10 19:15:48.636106 | controller | Tuesday 10 March 2026 19:15:48 +0000 (0:00:00.331) 0:01:22.473 ********* 2026-03-10 19:15:48.674838 | controller | skipping: [instance] 2026-03-10 19:15:48.674866 | controller | 2026-03-10 19:15:48.674876 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul] ******************* 2026-03-10 19:15:48.674887 | controller | Tuesday 10 March 2026 19:15:48 +0000 (0:00:00.038) 0:01:22.512 ********* 2026-03-10 19:15:48.707840 | controller | skipping: [instance] 2026-03-10 19:15:48.757215 | controller | 2026-03-10 19:15:48.757237 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed] *** 2026-03-10 19:15:48.757244 | controller | Tuesday 10 March 2026 19:15:48 +0000 (0:00:00.033) 0:01:22.545 ********* 2026-03-10 19:15:48.757252 | controller | skipping: [instance] 2026-03-10 19:15:49.299165 | controller | 2026-03-10 19:15:49.299197 | controller | TASK [virtualbmc : Stop and remove vbmc container] ***************************** 2026-03-10 19:15:49.299205 | controller | Tuesday 10 March 2026 19:15:48 +0000 (0:00:00.049) 0:01:22.595 ********* 2026-03-10 19:15:49.299220 | controller | ok: [instance] 2026-03-10 19:15:49.713148 | controller | 2026-03-10 19:15:49.713182 | controller | TASK [virtualbmc : Remove vbmc container image] ******************************** 2026-03-10 19:15:49.713190 | controller | Tuesday 10 March 2026 19:15:49 +0000 (0:00:00.541) 0:01:23.136 ********* 2026-03-10 19:15:49.713199 | controller | ok: [instance] 2026-03-10 19:15:49.892486 | controller | 2026-03-10 19:15:49.892512 | controller | TASK [virtualbmc : Check if VBMC key exists] *********************************** 2026-03-10 19:15:49.892521 | controller | Tuesday 10 March 2026 19:15:49 +0000 (0:00:00.414) 0:01:23.550 ********* 2026-03-10 19:15:49.892536 | controller | ok: [instance] 2026-03-10 19:15:49.924241 | controller | 2026-03-10 19:15:49.924261 | controller | TASK [virtualbmc : Slurp key] ************************************************** 2026-03-10 19:15:49.924268 | controller | Tuesday 10 March 2026 19:15:49 +0000 (0:00:00.179) 0:01:23.730 ********* 2026-03-10 19:15:49.924276 | controller | skipping: [instance] 2026-03-10 19:15:49.954817 | controller | 2026-03-10 19:15:49.954850 | controller | TASK [virtualbmc : Revoke VBMC SSH access] ************************************* 2026-03-10 19:15:49.954858 | controller | Tuesday 10 March 2026 19:15:49 +0000 (0:00:00.031) 0:01:23.762 ********* 2026-03-10 19:15:49.954875 | controller | skipping: [instance] 2026-03-10 19:15:50.635790 | controller | 2026-03-10 19:15:50.635822 | controller | TASK [virtualbmc : Remove vbmc files] ****************************************** 2026-03-10 19:15:50.635829 | controller | Tuesday 10 March 2026 19:15:49 +0000 (0:00:00.030) 0:01:23.792 ********* 2026-03-10 19:15:50.635840 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmc-key) 2026-03-10 19:15:50.705634 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmc-key.pub) 2026-03-10 19:15:50.705667 | controller | ok: [instance] => (item=/home/zuul/.ssh/virtualbmc.conf) 2026-03-10 19:15:50.705675 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmcd.sh) 2026-03-10 19:15:50.705681 | controller | 2026-03-10 19:15:50.705686 | controller | TASK [Clean remote ssh config] ************************************************* 2026-03-10 19:15:50.705692 | controller | Tuesday 10 March 2026 19:15:50 +0000 (0:00:00.680) 0:01:24.473 ********* 2026-03-10 19:15:50.705701 | controller | 2026-03-10 19:15:50.994785 | controller | TASK [ssh_jumper : Remove Include cifmw_ssh_config.d] ************************** 2026-03-10 19:15:50.994811 | controller | Tuesday 10 March 2026 19:15:50 +0000 (0:00:00.070) 0:01:24.543 ********* 2026-03-10 19:15:50.994823 | controller | ok: [instance] 2026-03-10 19:15:51.200835 | controller | 2026-03-10 19:15:51.200874 | controller | TASK [ssh_jumper : Remove cifmw_ssh_config.d directory] ************************ 2026-03-10 19:15:51.200885 | controller | Tuesday 10 March 2026 19:15:50 +0000 (0:00:00.289) 0:01:24.832 ********* 2026-03-10 19:15:51.200897 | controller | ok: [instance] 2026-03-10 19:15:51.200938 | controller | 2026-03-10 19:15:51.201042 | controller | TASK [Clean local ssh config] ************************************************** 2026-03-10 19:15:51.201160 | controller | Tuesday 10 March 2026 19:15:51 +0000 (0:00:00.206) 0:01:25.039 ********* 2026-03-10 19:15:51.301241 | controller | 2026-03-10 19:15:51.489702 | controller | TASK [ssh_jumper : Remove Include cifmw_ssh_config.d] ************************** 2026-03-10 19:15:51.489743 | controller | Tuesday 10 March 2026 19:15:51 +0000 (0:00:00.100) 0:01:25.139 ********* 2026-03-10 19:15:51.489754 | controller | ok: [instance -> localhost] 2026-03-10 19:15:51.694824 | controller | 2026-03-10 19:15:51.694861 | controller | TASK [ssh_jumper : Remove cifmw_ssh_config.d directory] ************************ 2026-03-10 19:15:51.694871 | controller | Tuesday 10 March 2026 19:15:51 +0000 (0:00:00.188) 0:01:25.327 ********* 2026-03-10 19:15:51.694882 | controller | ok: [instance -> localhost] 2026-03-10 19:15:51.750863 | controller | 2026-03-10 19:15:51.750907 | controller | TASK [dnsmasq : Cleaning dnsmasq] ********************************************** 2026-03-10 19:15:51.750932 | controller | Tuesday 10 March 2026 19:15:51 +0000 (0:00:00.204) 0:01:25.532 ********* 2026-03-10 19:15:51.750947 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/configure.yml for instance 2026-03-10 19:15:55.424827 | controller | 2026-03-10 19:15:55.424870 | controller | TASK [dnsmasq : Install needed packages] *************************************** 2026-03-10 19:15:55.424881 | controller | Tuesday 10 March 2026 19:15:51 +0000 (0:00:00.056) 0:01:25.588 ********* 2026-03-10 19:15:55.424902 | controller | changed: [instance] 2026-03-10 19:15:55.424951 | controller | 2026-03-10 19:15:55.425082 | controller | TASK [dnsmasq : Ensure SELinux knows about the new files and directories] ****** 2026-03-10 19:15:55.425119 | controller | Tuesday 10 March 2026 19:15:55 +0000 (0:00:03.673) 0:01:29.262 ********* 2026-03-10 19:15:56.666177 | controller | ok: [instance] => (item={'target': '/etc/cifmw-dnsmasq.conf', 'setype': 'dnsmasq_etc_t'}) 2026-03-10 19:15:56.878270 | controller | ok: [instance] => (item={'target': '/etc/cifmw-dnsmasq.d(/.*)?', 'setype': 'dnsmasq_etc_t'}) 2026-03-10 19:15:56.878299 | controller | ok: [instance] => (item={'target': '/var/run/cifmw-dnsmasq.pid', 'setype': 'dnsmasq_var_run_t'}) 2026-03-10 19:15:56.878306 | controller | 2026-03-10 19:15:56.878312 | controller | TASK [dnsmasq : Manage configuration directory] ******************************** 2026-03-10 19:15:56.878317 | controller | Tuesday 10 March 2026 19:15:56 +0000 (0:00:01.241) 0:01:30.503 ********* 2026-03-10 19:15:56.878326 | controller | ok: [instance] 2026-03-10 19:15:56.933379 | controller | 2026-03-10 19:15:56.933408 | controller | TASK [dnsmasq : Manage systemd unit file] ************************************** 2026-03-10 19:15:56.933416 | controller | Tuesday 10 March 2026 19:15:56 +0000 (0:00:00.212) 0:01:30.716 ********* 2026-03-10 19:15:56.933426 | controller | skipping: [instance] 2026-03-10 19:15:56.965835 | controller | 2026-03-10 19:15:56.965884 | controller | TASK [dnsmasq : Manage base configuration file] ******************************** 2026-03-10 19:15:56.965903 | controller | Tuesday 10 March 2026 19:15:56 +0000 (0:00:00.055) 0:01:30.771 ********* 2026-03-10 19:15:56.965929 | controller | skipping: [instance] 2026-03-10 19:15:56.965955 | controller | 2026-03-10 19:15:56.965973 | controller | TASK [dnsmasq : Render listener configuration] ********************************* 2026-03-10 19:15:56.965996 | controller | Tuesday 10 March 2026 19:15:56 +0000 (0:00:00.032) 0:01:30.804 ********* 2026-03-10 19:15:57.016845 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/listener.yml for instance 2026-03-10 19:15:57.016927 | controller | 2026-03-10 19:15:57.017032 | controller | TASK [dnsmasq : Create listener configuration file] **************************** 2026-03-10 19:15:57.017118 | controller | Tuesday 10 March 2026 19:15:57 +0000 (0:00:00.051) 0:01:30.855 ********* 2026-03-10 19:15:57.048154 | controller | skipping: [instance] 2026-03-10 19:15:57.048241 | controller | 2026-03-10 19:15:57.048333 | controller | TASK [dnsmasq : Remove listener configuration file] **************************** 2026-03-10 19:15:57.048423 | controller | Tuesday 10 March 2026 19:15:57 +0000 (0:00:00.031) 0:01:30.886 ********* 2026-03-10 19:15:57.259588 | controller | ok: [instance] 2026-03-10 19:15:57.259670 | controller | 2026-03-10 19:15:57.259802 | controller | TASK [dnsmasq : Render dns configuration] ************************************** 2026-03-10 19:15:57.259949 | controller | Tuesday 10 March 2026 19:15:57 +0000 (0:00:00.211) 0:01:31.097 ********* 2026-03-10 19:15:57.313870 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/dns.yml for instance 2026-03-10 19:15:57.344518 | controller | 2026-03-10 19:15:57.344540 | controller | TASK [dnsmasq : Create dns configuration file] ********************************* 2026-03-10 19:15:57.344547 | controller | Tuesday 10 March 2026 19:15:57 +0000 (0:00:00.053) 0:01:31.151 ********* 2026-03-10 19:15:57.344556 | controller | skipping: [instance] 2026-03-10 19:15:57.559139 | controller | 2026-03-10 19:15:57.559162 | controller | TASK [dnsmasq : Remove dns configuration file] ********************************* 2026-03-10 19:15:57.559169 | controller | Tuesday 10 March 2026 19:15:57 +0000 (0:00:00.030) 0:01:31.182 ********* 2026-03-10 19:15:57.559178 | controller | ok: [instance] 2026-03-10 19:15:57.587831 | controller | 2026-03-10 19:15:57.587877 | controller | TASK [dnsmasq : Add localhost addresses from defined dnsmasq listen addresses to loopback interface] *** 2026-03-10 19:15:57.587887 | controller | Tuesday 10 March 2026 19:15:57 +0000 (0:00:00.214) 0:01:31.397 ********* 2026-03-10 19:15:57.587899 | controller | skipping: [instance] 2026-03-10 19:15:57.587938 | controller | 2026-03-10 19:15:57.587948 | controller | TASK [dnsmasq : Enable and start service] ************************************** 2026-03-10 19:15:57.588040 | controller | Tuesday 10 March 2026 19:15:57 +0000 (0:00:00.028) 0:01:31.425 ********* 2026-03-10 19:15:57.618894 | controller | skipping: [instance] 2026-03-10 19:15:58.167288 | controller | 2026-03-10 19:15:58.167312 | controller | TASK [dnsmasq : Stop service] ************************************************** 2026-03-10 19:15:58.167320 | controller | Tuesday 10 March 2026 19:15:57 +0000 (0:00:00.030) 0:01:31.456 ********* 2026-03-10 19:15:58.167328 | controller | ok: [instance] 2026-03-10 19:15:58.371401 | controller | 2026-03-10 19:15:58.371423 | controller | TASK [dnsmasq : Remove unit file] ********************************************** 2026-03-10 19:15:58.371431 | controller | Tuesday 10 March 2026 19:15:58 +0000 (0:00:00.548) 0:01:32.005 ********* 2026-03-10 19:15:58.371440 | controller | ok: [instance] 2026-03-10 19:15:58.750872 | controller | 2026-03-10 19:15:58.750897 | controller | TASK [dnsmasq : Remove main configuration files] ******************************* 2026-03-10 19:15:58.750904 | controller | Tuesday 10 March 2026 19:15:58 +0000 (0:00:00.204) 0:01:32.209 ********* 2026-03-10 19:15:58.750923 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.conf) 2026-03-10 19:15:58.969792 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.d) 2026-03-10 19:15:58.969839 | controller | 2026-03-10 19:15:58.969851 | controller | TASK [dnsmasq : Remove lease file] ********************************************* 2026-03-10 19:15:58.969859 | controller | Tuesday 10 March 2026 19:15:58 +0000 (0:00:00.379) 0:01:32.588 ********* 2026-03-10 19:15:58.969874 | controller | ok: [instance] 2026-03-10 19:15:59.452865 | controller | 2026-03-10 19:15:59.452907 | controller | TASK [radvd : Gather package facts] ******************************************** 2026-03-10 19:15:59.452932 | controller | Tuesday 10 March 2026 19:15:58 +0000 (0:00:00.218) 0:01:32.807 ********* 2026-03-10 19:15:59.452947 | controller | ok: [instance] 2026-03-10 19:15:59.489883 | controller | 2026-03-10 19:15:59.489939 | controller | TASK [radvd : Stop and disable radvd service] ********************************** 2026-03-10 19:15:59.489951 | controller | Tuesday 10 March 2026 19:15:59 +0000 (0:00:00.482) 0:01:33.290 ********* 2026-03-10 19:15:59.489965 | controller | skipping: [instance] 2026-03-10 19:15:59.490001 | controller | 2026-03-10 19:15:59.490314 | controller | TASK [radvd : Remove radvd configuration file] ********************************* 2026-03-10 19:15:59.728821 | controller | Tuesday 10 March 2026 19:15:59 +0000 (0:00:00.037) 0:01:33.327 ********* 2026-03-10 19:15:59.728860 | controller | ok: [instance] 2026-03-10 19:15:59.728871 | controller | 2026-03-10 19:15:59.728880 | controller | TASK [radvd : Remove configuration directory] ********************************** 2026-03-10 19:15:59.728898 | controller | Tuesday 10 March 2026 19:15:59 +0000 (0:00:00.238) 0:01:33.566 ********* 2026-03-10 19:15:59.935349 | controller | ok: [instance] 2026-03-10 19:16:00.000535 | controller | 2026-03-10 19:16:00.000562 | controller | TASK [radvd : Remove radvd package] ******************************************** 2026-03-10 19:16:00.000573 | controller | Tuesday 10 March 2026 19:15:59 +0000 (0:00:00.206) 0:01:33.773 ********* 2026-03-10 19:16:00.000586 | controller | skipping: [instance] 2026-03-10 19:16:00.187662 | controller | 2026-03-10 19:16:00.187682 | controller | TASK [libvirt_manager : Ensure output directory exists] ************************ 2026-03-10 19:16:00.187689 | controller | Tuesday 10 March 2026 19:15:59 +0000 (0:00:00.065) 0:01:33.838 ********* 2026-03-10 19:16:00.187698 | controller | ok: [instance] 2026-03-10 19:16:00.380225 | controller | 2026-03-10 19:16:00.380249 | controller | TASK [libvirt_manager : Get vmx/svm extensions from /proc/cpuinfo] ************* 2026-03-10 19:16:00.380257 | controller | Tuesday 10 March 2026 19:16:00 +0000 (0:00:00.187) 0:01:34.025 ********* 2026-03-10 19:16:00.380266 | controller | changed: [instance] 2026-03-10 19:16:00.582396 | controller | 2026-03-10 19:16:00.582416 | controller | TASK [libvirt_manager : Check if KVM is enabled] ******************************* 2026-03-10 19:16:00.582423 | controller | Tuesday 10 March 2026 19:16:00 +0000 (0:00:00.192) 0:01:34.218 ********* 2026-03-10 19:16:00.582431 | controller | ok: [instance] 2026-03-10 19:16:00.614861 | controller | 2026-03-10 19:16:00.614902 | controller | TASK [libvirt_manager : Fail if KVM not enabled] ******************************* 2026-03-10 19:16:00.614925 | controller | Tuesday 10 March 2026 19:16:00 +0000 (0:00:00.202) 0:01:34.420 ********* 2026-03-10 19:16:00.614940 | controller | skipping: [instance] 2026-03-10 19:16:00.614974 | controller | 2026-03-10 19:16:00.614985 | controller | TASK [libvirt_manager : Check if VMX virtualization] *************************** 2026-03-10 19:16:00.615025 | controller | Tuesday 10 March 2026 19:16:00 +0000 (0:00:00.032) 0:01:34.453 ********* 2026-03-10 19:16:00.647593 | controller | skipping: [instance] 2026-03-10 19:16:00.681863 | controller | 2026-03-10 19:16:00.681893 | controller | TASK [libvirt_manager : Check if SVM virtualization] *************************** 2026-03-10 19:16:00.681903 | controller | Tuesday 10 March 2026 19:16:00 +0000 (0:00:00.032) 0:01:34.485 ********* 2026-03-10 19:16:00.681931 | controller | ok: [instance] 2026-03-10 19:16:00.711882 | controller | 2026-03-10 19:16:00.711938 | controller | TASK [libvirt_manager : Enable VMX virtualization] ***************************** 2026-03-10 19:16:00.711953 | controller | Tuesday 10 March 2026 19:16:00 +0000 (0:00:00.034) 0:01:34.519 ********* 2026-03-10 19:16:00.711971 | controller | skipping: [instance] 2026-03-10 19:16:00.712033 | controller | 2026-03-10 19:16:00.712047 | controller | TASK [libvirt_manager : Install packages required for using KVM] *************** 2026-03-10 19:16:00.712061 | controller | Tuesday 10 March 2026 19:16:00 +0000 (0:00:00.030) 0:01:34.549 ********* 2026-03-10 19:17:45.691131 | controller | changed: [instance] 2026-03-10 19:17:45.691384 | controller | 2026-03-10 19:17:45.929286 | controller | TASK [libvirt_manager : Create rules.d polkit folder] ************************** 2026-03-10 19:17:45.929314 | controller | Tuesday 10 March 2026 19:17:45 +0000 (0:01:44.979) 0:03:19.529 ********* 2026-03-10 19:17:45.929325 | controller | changed: [instance] 2026-03-10 19:17:46.292152 | controller | 2026-03-10 19:17:46.292181 | controller | TASK [libvirt_manager : Enable UNIX socket PolicyKit auth] ********************* 2026-03-10 19:17:46.292189 | controller | Tuesday 10 March 2026 19:17:45 +0000 (0:00:00.238) 0:03:19.767 ********* 2026-03-10 19:17:46.292199 | controller | changed: [instance] 2026-03-10 19:17:46.292226 | controller | 2026-03-10 19:17:46.292320 | controller | TASK [libvirt_manager : Restart service polkit service] ************************ 2026-03-10 19:17:46.292408 | controller | Tuesday 10 March 2026 19:17:46 +0000 (0:00:00.362) 0:03:20.130 ********* 2026-03-10 19:17:46.837670 | controller | changed: [instance] 2026-03-10 19:17:47.049611 | controller | 2026-03-10 19:17:47.049641 | controller | TASK [libvirt_manager : Remove directory for service override] ***************** 2026-03-10 19:17:47.049649 | controller | Tuesday 10 March 2026 19:17:46 +0000 (0:00:00.545) 0:03:20.675 ********* 2026-03-10 19:17:47.049659 | controller | ok: [instance] 2026-03-10 19:17:47.558112 | controller | 2026-03-10 19:17:47.558147 | controller | TASK [libvirt_manager : Reload systemctl and start/enable virtproxyd.service] *** 2026-03-10 19:17:47.558155 | controller | Tuesday 10 March 2026 19:17:47 +0000 (0:00:00.211) 0:03:20.887 ********* 2026-03-10 19:17:47.558165 | controller | ok: [instance] 2026-03-10 19:17:48.262252 | controller | 2026-03-10 19:17:48.262287 | controller | TASK [libvirt_manager : Ensure firewalld service is up] ************************ 2026-03-10 19:17:48.262295 | controller | Tuesday 10 March 2026 19:17:47 +0000 (0:00:00.508) 0:03:21.395 ********* 2026-03-10 19:17:48.262311 | controller | changed: [instance] 2026-03-10 19:17:48.262337 | controller | 2026-03-10 19:17:48.262479 | controller | TASK [libvirt_manager : Ensure libvirt modular driver daemons are started.] **** 2026-03-10 19:17:48.262513 | controller | Tuesday 10 March 2026 19:17:48 +0000 (0:00:00.704) 0:03:22.100 ********* 2026-03-10 19:17:48.484211 | 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:17:48.484261 | 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:17:48.484270 | 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:17:48.484276 | 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:17:48.484281 | 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:17:48.484294 | 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:17:48.484305 | 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:17:48.484313 | 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:17:48.484352 | controller | 2026-03-10 19:17:48.484553 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for qemu.] ********* 2026-03-10 19:17:48.834679 | controller | Tuesday 10 March 2026 19:17:48 +0000 (0:00:00.221) 0:03:22.321 ********* 2026-03-10 19:17:48.834758 | controller | changed: [instance] 2026-03-10 19:17:48.834786 | controller | 2026-03-10 19:17:48.834858 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for qemu.] ********** 2026-03-10 19:17:48.835009 | controller | Tuesday 10 March 2026 19:17:48 +0000 (0:00:00.350) 0:03:22.672 ********* 2026-03-10 19:17:49.188792 | controller | changed: [instance] 2026-03-10 19:17:49.536106 | controller | 2026-03-10 19:17:49.536143 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for qemu.] ************** 2026-03-10 19:17:49.536151 | controller | Tuesday 10 March 2026 19:17:49 +0000 (0:00:00.353) 0:03:23.026 ********* 2026-03-10 19:17:49.536162 | controller | changed: [instance] 2026-03-10 19:17:49.878844 | controller | 2026-03-10 19:17:49.878888 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for interface.] **** 2026-03-10 19:17:49.878896 | controller | Tuesday 10 March 2026 19:17:49 +0000 (0:00:00.347) 0:03:23.373 ********* 2026-03-10 19:17:49.878907 | controller | changed: [instance] 2026-03-10 19:17:49.878914 | controller | 2026-03-10 19:17:49.879022 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for interface.] ***** 2026-03-10 19:17:49.879135 | controller | Tuesday 10 March 2026 19:17:49 +0000 (0:00:00.343) 0:03:23.716 ********* 2026-03-10 19:17:50.226839 | controller | changed: [instance] 2026-03-10 19:17:50.570127 | controller | 2026-03-10 19:17:50.570156 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for interface.] ********* 2026-03-10 19:17:50.570164 | controller | Tuesday 10 March 2026 19:17:50 +0000 (0:00:00.347) 0:03:24.064 ********* 2026-03-10 19:17:50.570173 | controller | changed: [instance] 2026-03-10 19:17:50.903644 | controller | 2026-03-10 19:17:50.903685 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for network.] ****** 2026-03-10 19:17:50.903699 | controller | Tuesday 10 March 2026 19:17:50 +0000 (0:00:00.343) 0:03:24.407 ********* 2026-03-10 19:17:50.903749 | controller | changed: [instance] 2026-03-10 19:17:51.248703 | controller | 2026-03-10 19:17:51.248737 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for network.] ******* 2026-03-10 19:17:51.248744 | controller | Tuesday 10 March 2026 19:17:50 +0000 (0:00:00.333) 0:03:24.741 ********* 2026-03-10 19:17:51.248753 | controller | changed: [instance] 2026-03-10 19:17:51.586707 | controller | 2026-03-10 19:17:51.586748 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for network.] *********** 2026-03-10 19:17:51.586755 | controller | Tuesday 10 March 2026 19:17:51 +0000 (0:00:00.345) 0:03:25.086 ********* 2026-03-10 19:17:51.586764 | controller | changed: [instance] 2026-03-10 19:17:51.920808 | controller | 2026-03-10 19:17:51.920827 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nodedev.] ****** 2026-03-10 19:17:51.920833 | controller | Tuesday 10 March 2026 19:17:51 +0000 (0:00:00.337) 0:03:25.424 ********* 2026-03-10 19:17:51.920842 | controller | changed: [instance] 2026-03-10 19:17:52.262670 | controller | 2026-03-10 19:17:52.262697 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nodedev.] ******* 2026-03-10 19:17:52.262706 | controller | Tuesday 10 March 2026 19:17:51 +0000 (0:00:00.333) 0:03:25.758 ********* 2026-03-10 19:17:52.262734 | controller | changed: [instance] 2026-03-10 19:17:52.597791 | controller | 2026-03-10 19:17:52.597828 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nodedev.] *********** 2026-03-10 19:17:52.597837 | controller | Tuesday 10 March 2026 19:17:52 +0000 (0:00:00.341) 0:03:26.100 ********* 2026-03-10 19:17:52.597855 | controller | changed: [instance] 2026-03-10 19:17:52.597876 | controller | 2026-03-10 19:17:52.597882 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nwfilter.] ***** 2026-03-10 19:17:52.597894 | controller | Tuesday 10 March 2026 19:17:52 +0000 (0:00:00.335) 0:03:26.435 ********* 2026-03-10 19:17:52.928888 | controller | changed: [instance] 2026-03-10 19:17:52.928947 | controller | 2026-03-10 19:17:52.929053 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nwfilter.] ****** 2026-03-10 19:17:52.929175 | controller | Tuesday 10 March 2026 19:17:52 +0000 (0:00:00.331) 0:03:26.767 ********* 2026-03-10 19:17:53.288258 | controller | changed: [instance] 2026-03-10 19:17:53.636469 | controller | 2026-03-10 19:17:53.636497 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nwfilter.] ********** 2026-03-10 19:17:53.636510 | controller | Tuesday 10 March 2026 19:17:53 +0000 (0:00:00.359) 0:03:27.126 ********* 2026-03-10 19:17:53.636520 | controller | changed: [instance] 2026-03-10 19:17:54.001873 | controller | 2026-03-10 19:17:54.001901 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for secret.] ******* 2026-03-10 19:17:54.001909 | controller | Tuesday 10 March 2026 19:17:53 +0000 (0:00:00.348) 0:03:27.474 ********* 2026-03-10 19:17:54.001918 | controller | changed: [instance] 2026-03-10 19:17:54.337678 | controller | 2026-03-10 19:17:54.337735 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for secret.] ******** 2026-03-10 19:17:54.337751 | controller | Tuesday 10 March 2026 19:17:53 +0000 (0:00:00.365) 0:03:27.839 ********* 2026-03-10 19:17:54.337765 | controller | changed: [instance] 2026-03-10 19:17:54.676354 | controller | 2026-03-10 19:17:54.676380 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for secret.] ************ 2026-03-10 19:17:54.676388 | controller | Tuesday 10 March 2026 19:17:54 +0000 (0:00:00.335) 0:03:28.175 ********* 2026-03-10 19:17:54.676397 | controller | changed: [instance] 2026-03-10 19:17:55.015787 | controller | 2026-03-10 19:17:55.015813 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for storage.] ****** 2026-03-10 19:17:55.015820 | controller | Tuesday 10 March 2026 19:17:54 +0000 (0:00:00.338) 0:03:28.514 ********* 2026-03-10 19:17:55.015829 | controller | changed: [instance] 2026-03-10 19:17:55.374888 | controller | 2026-03-10 19:17:55.374923 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for storage.] ******* 2026-03-10 19:17:55.374932 | controller | Tuesday 10 March 2026 19:17:55 +0000 (0:00:00.339) 0:03:28.853 ********* 2026-03-10 19:17:55.374944 | controller | changed: [instance] 2026-03-10 19:17:55.700226 | controller | 2026-03-10 19:17:55.700253 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for storage.] *********** 2026-03-10 19:17:55.700260 | controller | Tuesday 10 March 2026 19:17:55 +0000 (0:00:00.358) 0:03:29.212 ********* 2026-03-10 19:17:55.700270 | controller | changed: [instance] 2026-03-10 19:17:56.027691 | controller | 2026-03-10 19:17:56.027736 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for proxy.] ******** 2026-03-10 19:17:56.027747 | controller | Tuesday 10 March 2026 19:17:55 +0000 (0:00:00.325) 0:03:29.538 ********* 2026-03-10 19:17:56.027756 | controller | changed: [instance] 2026-03-10 19:17:56.361399 | controller | 2026-03-10 19:17:56.361430 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for proxy.] ********* 2026-03-10 19:17:56.361438 | controller | Tuesday 10 March 2026 19:17:56 +0000 (0:00:00.327) 0:03:29.865 ********* 2026-03-10 19:17:56.361449 | controller | changed: [instance] 2026-03-10 19:17:56.701760 | controller | 2026-03-10 19:17:56.701794 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for proxy.] ************* 2026-03-10 19:17:56.701802 | controller | Tuesday 10 March 2026 19:17:56 +0000 (0:00:00.333) 0:03:30.199 ********* 2026-03-10 19:17:56.701811 | controller | changed: [instance] 2026-03-10 19:17:56.745516 | controller | 2026-03-10 19:17:56.745538 | controller | TASK [libvirt_manager : Manage service for older releases] ********************* 2026-03-10 19:17:56.745547 | controller | Tuesday 10 March 2026 19:17:56 +0000 (0:00:00.340) 0:03:30.539 ********* 2026-03-10 19:17:56.745557 | controller | skipping: [instance] 2026-03-10 19:17:57.047476 | controller | 2026-03-10 19:17:57.047506 | controller | TASK [libvirt_manager : Get libvirt group users] ******************************* 2026-03-10 19:17:57.047514 | controller | Tuesday 10 March 2026 19:17:56 +0000 (0:00:00.043) 0:03:30.583 ********* 2026-03-10 19:17:57.047523 | controller | ok: [instance] 2026-03-10 19:17:57.443959 | controller | 2026-03-10 19:17:57.443986 | controller | TASK [libvirt_manager : Add user if not exists in libvirt group] *************** 2026-03-10 19:17:57.443994 | controller | Tuesday 10 March 2026 19:17:57 +0000 (0:00:00.301) 0:03:30.885 ********* 2026-03-10 19:17:57.444003 | controller | changed: [instance] 2026-03-10 19:17:57.750327 | controller | 2026-03-10 19:17:57.750357 | controller | TASK [libvirt_manager : Allow QEMU on home directory for the storage access] *** 2026-03-10 19:17:57.750364 | controller | Tuesday 10 March 2026 19:17:57 +0000 (0:00:00.396) 0:03:31.281 ********* 2026-03-10 19:17:57.750374 | controller | changed: [instance] 2026-03-10 19:17:57.948193 | controller | 2026-03-10 19:17:57.948225 | controller | TASK [libvirt_manager : Inject system connection parameters in bashrc] ********* 2026-03-10 19:17:57.948234 | controller | Tuesday 10 March 2026 19:17:57 +0000 (0:00:00.306) 0:03:31.588 ********* 2026-03-10 19:17:57.948246 | controller | [WARNING]: Reset is not implemented for this connection 2026-03-10 19:17:57.984131 | controller | changed: [instance] 2026-03-10 19:17:57.984218 | controller | 2026-03-10 19:17:57.984233 | controller | TASK [libvirt_manager : Ensure we reload user env] ***************************** 2026-03-10 19:17:57.984245 | controller | Tuesday 10 March 2026 19:17:57 +0000 (0:00:00.180) 0:03:31.768 ********* 2026-03-10 19:17:57.984255 | controller | 2026-03-10 19:17:57.984277 | controller | TASK [libvirt_manager : Deactivate default] ************************************ 2026-03-10 19:17:58.962791 | controller | Tuesday 10 March 2026 19:17:57 +0000 (0:00:00.052) 0:03:31.821 ********* 2026-03-10 19:17:58.962829 | controller | ok: [instance] 2026-03-10 19:17:58.963128 | controller | 2026-03-10 19:17:59.212646 | controller | TASK [libvirt_manager : Removing default] ************************************** 2026-03-10 19:17:59.212677 | controller | Tuesday 10 March 2026 19:17:58 +0000 (0:00:00.979) 0:03:32.800 ********* 2026-03-10 19:17:59.212689 | controller | ok: [instance] 2026-03-10 19:17:59.618437 | controller | 2026-03-10 19:17:59.618466 | controller | TASK [Ensure we use dnsmasq for NetworkManager resolver] *********************** 2026-03-10 19:17:59.618474 | controller | Tuesday 10 March 2026 19:17:59 +0000 (0:00:00.249) 0:03:33.050 ********* 2026-03-10 19:17:59.618484 | controller | changed: [instance] 2026-03-10 19:17:59.965821 | controller | 2026-03-10 19:17:59.965860 | controller | TASK [Ensure we delegate .utility zone to our own instance] ******************** 2026-03-10 19:17:59.965868 | controller | Tuesday 10 March 2026 19:17:59 +0000 (0:00:00.405) 0:03:33.456 ********* 2026-03-10 19:17:59.965878 | controller | changed: [instance] 2026-03-10 19:18:00.401433 | controller | 2026-03-10 19:18:00.401461 | controller | TASK [Restart NetworkManager] ************************************************** 2026-03-10 19:18:00.401469 | controller | Tuesday 10 March 2026 19:17:59 +0000 (0:00:00.347) 0:03:33.803 ********* 2026-03-10 19:18:00.401479 | controller | changed: [instance] 2026-03-10 19:18:00.401501 | controller | 2026-03-10 19:18:00.401654 | controller | TASK [Ensure ocp_volumes exists] *********************************************** 2026-03-10 19:18:00.640816 | controller | Tuesday 10 March 2026 19:18:00 +0000 (0:00:00.435) 0:03:34.239 ********* 2026-03-10 19:18:00.640858 | controller | changed: [instance] 2026-03-10 19:18:00.641827 | controller | 2026-03-10 19:18:00.641869 | controller | PLAY RECAP ********************************************************************* 2026-03-10 19:18:00.641878 | controller | instance : ok=115 changed=56 unreachable=0 failed=0 skipped=86 rescued=0 ignored=0 2026-03-10 19:18:00.641883 | controller | 2026-03-10 19:18:00.641890 | controller | Tuesday 10 March 2026 19:18:00 +0000 (0:00:00.238) 0:03:34.477 ********* 2026-03-10 19:18:00.641895 | controller | =============================================================================== 2026-03-10 19:18:00.641900 | controller | libvirt_manager : Install packages required for using KVM ------------- 104.98s 2026-03-10 19:18:00.641905 | controller | test_deps : Install selinux python libs -------------------------------- 46.31s 2026-03-10 19:18:00.641910 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.18s 2026-03-10 19:18:00.641915 | controller | ci_setup : Install openshift client ------------------------------------- 5.90s 2026-03-10 19:18:00.641920 | controller | dnsmasq : Install needed packages --------------------------------------- 3.67s 2026-03-10 19:18:00.641929 | controller | ci_setup : Install needed packages -------------------------------------- 3.60s 2026-03-10 19:18:00.641934 | controller | libvirt_manager : Populate service facts -------------------------------- 1.70s 2026-03-10 19:18:00.641944 | controller | libvirt_manager : Remove workload and volumes --------------------------- 1.26s 2026-03-10 19:18:00.709427 | controller | dnsmasq : Ensure SELinux knows about the new files and directories ------ 1.24s 2026-03-10 19:18:00.709471 | controller | podman : Ensure podman is installed ------------------------------------- 1.16s 2026-03-10 19:18:00.709478 | controller | test_deps : Install python yaml libs ------------------------------------ 1.14s 2026-03-10 19:18:00.709483 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.08s 2026-03-10 19:18:00.709489 | controller | libvirt_manager : Deactivate default ------------------------------------ 0.98s 2026-03-10 19:18:00.709494 | controller | ci_setup : Manage directories ------------------------------------------- 0.90s 2026-03-10 19:18:00.709498 | controller | Gathering Facts --------------------------------------------------------- 0.85s 2026-03-10 19:18:00.709503 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.78s 2026-03-10 19:18:00.709509 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.77s 2026-03-10 19:18:00.709520 | controller | libvirt_manager : Get installed packages list --------------------------- 0.73s 2026-03-10 19:18:00.709525 | controller | libvirt_manager : Ensure firewalld service is up ------------------------ 0.70s 2026-03-10 19:18:00.709530 | controller | virtualbmc : Remove vbmc files ------------------------------------------ 0.68s 2026-03-10 19:18:00.709541 | controller | INFO Running boot_order > converge 2026-03-10 19:18:01.149493 | controller | 2026-03-10 19:18:02.000051 | controller | PLAY [Test boot_order configuration] ******************************************* 2026-03-10 19:18:02.000082 | controller | 2026-03-10 19:18:02.000089 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-10 19:18:02.000095 | controller | Tuesday 10 March 2026 19:18:01 +0000 (0:00:00.037) 0:00:00.037 ********* 2026-03-10 19:18:02.000104 | controller | ok: [instance] 2026-03-10 19:18:02.039755 | controller | 2026-03-10 19:18:02.039794 | controller | TASK [Load networking definition] ********************************************** 2026-03-10 19:18:02.039808 | controller | Tuesday 10 March 2026 19:18:01 +0000 (0:00:00.851) 0:00:00.888 ********* 2026-03-10 19:18:02.039820 | controller | ok: [instance] 2026-03-10 19:18:02.111114 | controller | 2026-03-10 19:18:02.111147 | controller | TASK [libvirt_manager : Import layout generator if needed] ********************* 2026-03-10 19:18:02.111161 | controller | Tuesday 10 March 2026 19:18:02 +0000 (0:00:00.039) 0:00:00.927 ********* 2026-03-10 19:18:02.111178 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/generate_layout.yml for instance 2026-03-10 19:18:02.264801 | controller | 2026-03-10 19:18:02.264832 | controller | TASK [libvirt_manager : Chose right parameter for layout definition] *********** 2026-03-10 19:18:02.264849 | controller | Tuesday 10 March 2026 19:18:02 +0000 (0:00:00.071) 0:00:00.999 ********* 2026-03-10 19:18:02.264859 | controller | ok: [instance] 2026-03-10 19:18:02.377545 | controller | 2026-03-10 19:18:02.377587 | controller | TASK [libvirt_manager : Patch the layout if needed] **************************** 2026-03-10 19:18:02.377602 | controller | Tuesday 10 March 2026 19:18:02 +0000 (0:00:00.154) 0:00:01.153 ********* 2026-03-10 19:18:02.377618 | controller | skipping: [instance] 2026-03-10 19:18:02.377926 | controller | 2026-03-10 19:18:02.378129 | controller | TASK [libvirt_manager : Enable forwarding in the libvirt zone] ***************** 2026-03-10 19:18:02.378255 | controller | Tuesday 10 March 2026 19:18:02 +0000 (0:00:00.112) 0:00:01.266 ********* 2026-03-10 19:18:02.875789 | controller | changed: [instance] 2026-03-10 19:18:02.875831 | controller | 2026-03-10 19:18:02.875850 | controller | TASK [libvirt_manager : Enable masquerading for public traffic] **************** 2026-03-10 19:18:02.875959 | controller | Tuesday 10 March 2026 19:18:02 +0000 (0:00:00.498) 0:00:01.764 ********* 2026-03-10 19:18:03.426902 | controller | changed: [instance] 2026-03-10 19:18:04.210288 | controller | 2026-03-10 19:18:04.210327 | controller | TASK [libvirt_manager : Ensure firewalld is restarts] ************************** 2026-03-10 19:18:04.210340 | controller | Tuesday 10 March 2026 19:18:03 +0000 (0:00:00.480) 0:00:02.245 ********* 2026-03-10 19:18:04.210393 | controller | 2026-03-10 19:18:04.210398 | controller | RUNNING HANDLER [libvirt_manager : Restart firewalld] ************************** 2026-03-10 19:18:04.210403 | controller | Tuesday 10 March 2026 19:18:03 +0000 (0:00:00.068) 0:00:02.314 ********* 2026-03-10 19:18:04.210413 | controller | changed: [instance] 2026-03-10 19:18:04.259189 | controller | 2026-03-10 19:18:04.259209 | controller | TASK [Deploy virtualbmc] ******************************************************* 2026-03-10 19:18:04.259216 | controller | Tuesday 10 March 2026 19:18:04 +0000 (0:00:00.784) 0:00:03.098 ********* 2026-03-10 19:18:04.259225 | controller | skipping: [instance] 2026-03-10 19:18:04.308498 | controller | 2026-03-10 19:18:04.308518 | controller | TASK [libvirt_manager : Let the project know we have vbmc available] *********** 2026-03-10 19:18:04.308526 | controller | Tuesday 10 March 2026 19:18:04 +0000 (0:00:00.049) 0:00:03.147 ********* 2026-03-10 19:18:04.308536 | controller | skipping: [instance] 2026-03-10 19:18:04.933592 | controller | 2026-03-10 19:18:04.933623 | controller | TASK [libvirt_manager : Create needed workload directory] ********************** 2026-03-10 19:18:04.933631 | controller | Tuesday 10 March 2026 19:18:04 +0000 (0:00:00.049) 0:00:03.197 ********* 2026-03-10 19:18:04.933640 | controller | changed: [instance] => (item=workload) 2026-03-10 19:18:05.401812 | controller | changed: [instance] => (item=reproducer-inventory) 2026-03-10 19:18:05.401860 | controller | changed: [instance] => (item=volumes) 2026-03-10 19:18:05.401870 | controller | 2026-03-10 19:18:05.401877 | controller | TASK [libvirt_manager : Allow QEMU on workload directory] ********************** 2026-03-10 19:18:05.401884 | controller | Tuesday 10 March 2026 19:18:04 +0000 (0:00:00.624) 0:00:03.821 ********* 2026-03-10 19:18:05.401903 | controller | changed: [instance] => (item=workload) 2026-03-10 19:18:05.518678 | controller | changed: [instance] => (item=volumes) 2026-03-10 19:18:05.518709 | controller | 2026-03-10 19:18:05.518735 | controller | TASK [libvirt_manager : Generate networking data] ****************************** 2026-03-10 19:18:05.518742 | controller | Tuesday 10 March 2026 19:18:05 +0000 (0:00:00.468) 0:00:04.290 ********* 2026-03-10 19:18:05.518752 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/generate_networking_data.yml for instance 2026-03-10 19:18:05.579079 | controller | 2026-03-10 19:18:05.579112 | controller | TASK [libvirt_manager : Ensure fact is existing, and empty] ******************** 2026-03-10 19:18:05.579126 | controller | Tuesday 10 March 2026 19:18:05 +0000 (0:00:00.116) 0:00:04.407 ********* 2026-03-10 19:18:05.579142 | controller | ok: [instance] 2026-03-10 19:18:05.857071 | controller | 2026-03-10 19:18:05.857088 | controller | TASK [libvirt_manager : Check if we get an existing interfaces-info.yml] ******* 2026-03-10 19:18:05.857095 | controller | Tuesday 10 March 2026 19:18:05 +0000 (0:00:00.060) 0:00:04.467 ********* 2026-03-10 19:18:05.857103 | controller | ok: [instance] 2026-03-10 19:18:05.905569 | controller | 2026-03-10 19:18:05.905587 | controller | TASK [libvirt_manager : Load file if exists] *********************************** 2026-03-10 19:18:05.905594 | controller | Tuesday 10 March 2026 19:18:05 +0000 (0:00:00.278) 0:00:04.745 ********* 2026-03-10 19:18:05.905602 | controller | skipping: [instance] 2026-03-10 19:18:05.953227 | controller | 2026-03-10 19:18:05.953244 | controller | TASK [libvirt_manager : Update fact with existing content] ********************* 2026-03-10 19:18:05.953251 | controller | Tuesday 10 March 2026 19:18:05 +0000 (0:00:00.048) 0:00:04.794 ********* 2026-03-10 19:18:05.953259 | controller | skipping: [instance] 2026-03-10 19:18:06.026850 | controller | 2026-03-10 19:18:06.026889 | controller | TASK [libvirt_manager : Generate VM list] ************************************** 2026-03-10 19:18:06.026899 | controller | Tuesday 10 March 2026 19:18:05 +0000 (0:00:00.047) 0:00:04.842 ********* 2026-03-10 19:18:06.026912 | controller | ok: [instance] 2026-03-10 19:18:06.208866 | controller | 2026-03-10 19:18:06.208892 | controller | TASK [libvirt_manager : Generate/update mapping all nodes] ********************* 2026-03-10 19:18:06.208900 | controller | Tuesday 10 March 2026 19:18:06 +0000 (0:00:00.073) 0:00:04.915 ********* 2026-03-10 19:18:06.208910 | controller | ok: [instance] => (item={'key': 'disk-first-0', 'value': 'disk_first'}) 2026-03-10 19:18:06.261099 | controller | ok: [instance] => (item={'key': 'net-first-0', 'value': 'net_first'}) 2026-03-10 19:18:06.261117 | controller | ok: [instance] => (item={'key': 'net-only-0', 'value': 'net_only'}) 2026-03-10 19:18:06.261124 | controller | ok: [instance] => (item={'key': 'no-boot-order-0', 'value': 'no_boot_order'}) 2026-03-10 19:18:06.261129 | controller | 2026-03-10 19:18:06.261134 | controller | TASK [libvirt_manager : Append baremetal nodes if needed] ********************** 2026-03-10 19:18:06.261145 | controller | Tuesday 10 March 2026 19:18:06 +0000 (0:00:00.181) 0:00:05.097 ********* 2026-03-10 19:18:06.261154 | controller | skipping: [instance] 2026-03-10 19:18:06.691687 | controller | 2026-03-10 19:18:06.691741 | controller | TASK [libvirt_manager : Dump MAC mapping] ************************************** 2026-03-10 19:18:06.691757 | controller | Tuesday 10 March 2026 19:18:06 +0000 (0:00:00.052) 0:00:05.149 ********* 2026-03-10 19:18:06.691771 | controller | changed: [instance] 2026-03-10 19:18:06.819567 | controller | 2026-03-10 19:18:06.819585 | controller | TASK [libvirt_manager : Inject all VMs in the inventory] *********************** 2026-03-10 19:18:06.819591 | controller | Tuesday 10 March 2026 19:18:06 +0000 (0:00:00.430) 0:00:05.580 ********* 2026-03-10 19:18:06.819600 | 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 disk-first-0 to disk_firsts) 2026-03-10 19:18:06.912368 | 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 net-first-0 to net_firsts) 2026-03-10 19:18:06.912400 | 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 net-only-0 to net_onlys) 2026-03-10 19:18:06.912414 | 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 no-boot-order-0 to no_boot_orders) 2026-03-10 19:18:06.912424 | controller | 2026-03-10 19:18:06.912434 | controller | TASK [libvirt_manager : Add host to runtime inventory] ************************* 2026-03-10 19:18:06.912444 | controller | Tuesday 10 March 2026 19:18:06 +0000 (0:00:00.127) 0:00:05.707 ********* 2026-03-10 19:18:06.912459 | controller | changed: [instance] 2026-03-10 19:18:07.206290 | controller | 2026-03-10 19:18:07.206316 | controller | TASK [libvirt_manager : Ensure group section exists] *************************** 2026-03-10 19:18:07.206326 | controller | Tuesday 10 March 2026 19:18:06 +0000 (0:00:00.092) 0:00:05.800 ********* 2026-03-10 19:18:07.206337 | controller | changed: [instance] 2026-03-10 19:18:07.435262 | controller | 2026-03-10 19:18:07.435292 | controller | TASK [libvirt_manager : Append host under proper group] ************************ 2026-03-10 19:18:07.435300 | controller | Tuesday 10 March 2026 19:18:07 +0000 (0:00:00.294) 0:00:06.094 ********* 2026-03-10 19:18:07.435310 | controller | changed: [instance] 2026-03-10 19:18:07.505184 | controller | 2026-03-10 19:18:07.505202 | controller | TASK [libvirt_manager : Add host to runtime inventory] ************************* 2026-03-10 19:18:07.505209 | controller | Tuesday 10 March 2026 19:18:07 +0000 (0:00:00.228) 0:00:06.323 ********* 2026-03-10 19:18:07.505217 | controller | changed: [instance] 2026-03-10 19:18:07.716517 | controller | 2026-03-10 19:18:07.716555 | controller | TASK [libvirt_manager : Ensure group section exists] *************************** 2026-03-10 19:18:07.716563 | controller | Tuesday 10 March 2026 19:18:07 +0000 (0:00:00.070) 0:00:06.393 ********* 2026-03-10 19:18:07.716572 | controller | changed: [instance] 2026-03-10 19:18:07.964911 | controller | 2026-03-10 19:18:07.964944 | controller | TASK [libvirt_manager : Append host under proper group] ************************ 2026-03-10 19:18:07.964959 | controller | Tuesday 10 March 2026 19:18:07 +0000 (0:00:00.211) 0:00:06.605 ********* 2026-03-10 19:18:07.964971 | controller | changed: [instance] 2026-03-10 19:18:07.964993 | controller | 2026-03-10 19:18:07.965022 | controller | TASK [libvirt_manager : Add host to runtime inventory] ************************* 2026-03-10 19:18:07.965169 | controller | Tuesday 10 March 2026 19:18:07 +0000 (0:00:00.248) 0:00:06.853 ********* 2026-03-10 19:18:08.038681 | controller | changed: [instance] 2026-03-10 19:18:08.249623 | controller | 2026-03-10 19:18:08.249641 | controller | TASK [libvirt_manager : Ensure group section exists] *************************** 2026-03-10 19:18:08.249648 | controller | Tuesday 10 March 2026 19:18:08 +0000 (0:00:00.073) 0:00:06.927 ********* 2026-03-10 19:18:08.249657 | controller | changed: [instance] 2026-03-10 19:18:08.480788 | controller | 2026-03-10 19:18:08.480818 | controller | TASK [libvirt_manager : Append host under proper group] ************************ 2026-03-10 19:18:08.480825 | controller | Tuesday 10 March 2026 19:18:08 +0000 (0:00:00.211) 0:00:07.138 ********* 2026-03-10 19:18:08.480843 | controller | changed: [instance] 2026-03-10 19:18:08.480866 | controller | 2026-03-10 19:18:08.480873 | controller | TASK [libvirt_manager : Add host to runtime inventory] ************************* 2026-03-10 19:18:08.480972 | controller | Tuesday 10 March 2026 19:18:08 +0000 (0:00:00.231) 0:00:07.369 ********* 2026-03-10 19:18:08.549562 | controller | changed: [instance] 2026-03-10 19:18:08.791153 | controller | 2026-03-10 19:18:08.791179 | controller | TASK [libvirt_manager : Ensure group section exists] *************************** 2026-03-10 19:18:08.791188 | controller | Tuesday 10 March 2026 19:18:08 +0000 (0:00:00.068) 0:00:07.438 ********* 2026-03-10 19:18:08.791197 | controller | changed: [instance] 2026-03-10 19:18:09.015816 | controller | 2026-03-10 19:18:09.015863 | controller | TASK [libvirt_manager : Append host under proper group] ************************ 2026-03-10 19:18:09.015873 | controller | Tuesday 10 March 2026 19:18:08 +0000 (0:00:00.241) 0:00:07.679 ********* 2026-03-10 19:18:09.015886 | controller | changed: [instance] 2026-03-10 19:18:09.015915 | controller | 2026-03-10 19:18:09.016143 | controller | TASK [libvirt_manager : Check if ini inventory file exists] ******************** 2026-03-10 19:18:09.225687 | controller | Tuesday 10 March 2026 19:18:09 +0000 (0:00:00.224) 0:00:07.904 ********* 2026-03-10 19:18:09.225735 | controller | ok: [instance] 2026-03-10 19:18:09.723608 | controller | 2026-03-10 19:18:09.723633 | controller | TASK [libvirt_manager : Convert ini inventory file into yaml] ****************** 2026-03-10 19:18:09.723641 | controller | Tuesday 10 March 2026 19:18:09 +0000 (0:00:00.210) 0:00:08.114 ********* 2026-03-10 19:18:09.723650 | controller | changed: [instance] 2026-03-10 19:18:09.917816 | controller | 2026-03-10 19:18:09.917862 | controller | TASK [libvirt_manager : Remove ini file after conversion] ********************** 2026-03-10 19:18:09.917872 | controller | Tuesday 10 March 2026 19:18:09 +0000 (0:00:00.497) 0:00:08.612 ********* 2026-03-10 19:18:09.917884 | controller | changed: [instance] 2026-03-10 19:18:09.990531 | controller | 2026-03-10 19:18:09.990557 | controller | TASK [networking_mapper : Check for Networking Definition file existence] ****** 2026-03-10 19:18:09.990567 | controller | Tuesday 10 March 2026 19:18:09 +0000 (0:00:00.194) 0:00:08.806 ********* 2026-03-10 19:18:09.990578 | controller | skipping: [instance] 2026-03-10 19:18:10.033660 | controller | 2026-03-10 19:18:10.033692 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************ 2026-03-10 19:18:10.033706 | controller | Tuesday 10 March 2026 19:18:09 +0000 (0:00:00.072) 0:00:08.879 ********* 2026-03-10 19:18:10.033752 | controller | skipping: [instance] 2026-03-10 19:18:10.076886 | controller | 2026-03-10 19:18:10.076928 | controller | TASK [networking_mapper : Check for interfaces info file existence] ************ 2026-03-10 19:18:10.076940 | controller | Tuesday 10 March 2026 19:18:10 +0000 (0:00:00.042) 0:00:08.922 ********* 2026-03-10 19:18:10.076954 | controller | skipping: [instance] 2026-03-10 19:18:10.076986 | controller | 2026-03-10 19:18:10.076997 | controller | TASK [networking_mapper : Load the interfaces info from file] ****************** 2026-03-10 19:18:10.077172 | controller | Tuesday 10 March 2026 19:18:10 +0000 (0:00:00.043) 0:00:08.965 ********* 2026-03-10 19:18:10.120395 | controller | skipping: [instance] 2026-03-10 19:18:10.120653 | controller | 2026-03-10 19:18:10.120963 | controller | TASK [networking_mapper : Ensure local fact is empty] ************************** 2026-03-10 19:18:10.121150 | controller | Tuesday 10 March 2026 19:18:10 +0000 (0:00:00.043) 0:00:09.009 ********* 2026-03-10 19:18:10.158636 | controller | ok: [instance] 2026-03-10 19:18:10.158954 | controller | 2026-03-10 19:18:10.159185 | controller | TASK [networking_mapper : Set the input Networking Definition and patch it if needed] *** 2026-03-10 19:18:10.159306 | controller | Tuesday 10 March 2026 19:18:10 +0000 (0:00:00.038) 0:00:09.047 ********* 2026-03-10 19:18:10.311868 | controller | ok: [instance] => (item={'networks': {'ctlplane': {'network': '192.168.140.0/24', 'gateway': '192.168.140.1', 'mtu': 1500}}, 'group-templates': {'disk_firsts': {'network-template': {'range': {'start': 10, 'length': 1}}, 'networks': {'ctlplane': {}}}, 'net_firsts': {'network-template': {'range': {'start': 20, 'length': 1}}, 'networks': {'ctlplane': {}}}, 'net_onlys': {'network-template': {'range': {'start': 30, 'length': 1}}, 'networks': {'ctlplane': {}}}, 'no_boot_orders': {'network-template': {'range': {'start': 40, 'length': 1}}, 'networks': {'ctlplane': {}}}}}) 2026-03-10 19:18:10.312035 | controller | 2026-03-10 19:18:10.312188 | controller | TASK [networking_mapper : Gather facts if requested] *************************** 2026-03-10 19:18:10.312331 | controller | Tuesday 10 March 2026 19:18:10 +0000 (0:00:00.153) 0:00:09.200 ********* 2026-03-10 19:18:10.387231 | controller | skipping: [instance] 2026-03-10 19:18:10.387488 | controller | 2026-03-10 19:18:10.387770 | controller | TASK [networking_mapper : Ensure CI infrastructure dir exists] ***************** 2026-03-10 19:18:10.388051 | controller | Tuesday 10 March 2026 19:18:10 +0000 (0:00:00.075) 0:00:09.276 ********* 2026-03-10 19:18:10.613236 | controller | changed: [instance] 2026-03-10 19:18:11.009661 | controller | 2026-03-10 19:18:11.009686 | controller | TASK [networking_mapper : Write the Networking Definition to file] ************* 2026-03-10 19:18:11.009694 | controller | Tuesday 10 March 2026 19:18:10 +0000 (0:00:00.225) 0:00:09.501 ********* 2026-03-10 19:18:11.009704 | controller | changed: [instance] 2026-03-10 19:18:11.103334 | controller | 2026-03-10 19:18:11.103351 | controller | TASK [networking_mapper : Call the networking mapper] ************************** 2026-03-10 19:18:11.103358 | controller | Tuesday 10 March 2026 19:18:11 +0000 (0:00:00.396) 0:00:09.898 ********* 2026-03-10 19:18:11.103367 | controller | ok: [instance] 2026-03-10 19:18:11.177602 | controller | 2026-03-10 19:18:11.177629 | controller | TASK [networking_mapper : Set networking mapper facts] ************************* 2026-03-10 19:18:11.177637 | controller | Tuesday 10 March 2026 19:18:11 +0000 (0:00:00.093) 0:00:09.992 ********* 2026-03-10 19:18:11.177647 | controller | ok: [instance] 2026-03-10 19:18:11.586854 | controller | 2026-03-10 19:18:11.586891 | controller | TASK [networking_mapper : Write the Networking Environment Definition to file] *** 2026-03-10 19:18:11.586901 | controller | Tuesday 10 March 2026 19:18:11 +0000 (0:00:00.074) 0:00:10.066 ********* 2026-03-10 19:18:11.586915 | controller | changed: [instance] 2026-03-10 19:18:11.586947 | controller | 2026-03-10 19:18:11.586974 | controller | TASK [libvirt_manager : Manage networks if needed] ***************************** 2026-03-10 19:18:11.587121 | controller | Tuesday 10 March 2026 19:18:11 +0000 (0:00:00.409) 0:00:10.475 ********* 2026-03-10 19:18:11.701587 | 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:18:11.797772 | controller | 2026-03-10 19:18:11.797802 | controller | TASK [libvirt_manager : Define the localized variables for performing the tasks here.] *** 2026-03-10 19:18:11.797813 | controller | Tuesday 10 March 2026 19:18:11 +0000 (0:00:00.114) 0:00:10.590 ********* 2026-03-10 19:18:11.797852 | controller | ok: [instance] => (item=public) 2026-03-10 19:18:12.305837 | controller | ok: [instance] => (item=osp_trunk) 2026-03-10 19:18:12.305872 | controller | 2026-03-10 19:18:12.305882 | controller | TASK [libvirt_manager : Ensure networks are defined] *************************** 2026-03-10 19:18:12.305889 | controller | Tuesday 10 March 2026 19:18:11 +0000 (0:00:00.095) 0:00:10.686 ********* 2026-03-10 19:18:12.305909 | controller | changed: [instance] => (item=cifmw-public) 2026-03-10 19:18:12.305928 | controller | changed: [instance] => (item=cifmw-osp_trunk) 2026-03-10 19:18:12.305937 | controller | 2026-03-10 19:18:12.306153 | controller | TASK [libvirt_manager : Ensure networks are created/started] ******************* 2026-03-10 19:18:12.909871 | controller | Tuesday 10 March 2026 19:18:12 +0000 (0:00:00.508) 0:00:11.194 ********* 2026-03-10 19:18:12.909904 | controller | ok: [instance] => (item=cifmw-public) 2026-03-10 19:18:13.340625 | controller | ok: [instance] => (item=cifmw-osp_trunk) 2026-03-10 19:18:13.340655 | controller | 2026-03-10 19:18:13.340663 | controller | TASK [libvirt_manager : Ensure networks are active] **************************** 2026-03-10 19:18:13.340674 | controller | Tuesday 10 March 2026 19:18:12 +0000 (0:00:00.603) 0:00:11.798 ********* 2026-03-10 19:18:13.340685 | controller | ok: [instance] => (item=cifmw-public) 2026-03-10 19:18:13.783860 | controller | ok: [instance] => (item=cifmw-osp_trunk) 2026-03-10 19:18:13.783895 | controller | 2026-03-10 19:18:13.783905 | controller | TASK [libvirt_manager : Ensure networks enabled to autostart] ****************** 2026-03-10 19:18:13.783912 | controller | Tuesday 10 March 2026 19:18:13 +0000 (0:00:00.430) 0:00:12.228 ********* 2026-03-10 19:18:13.783924 | controller | changed: [instance] => (item=cifmw-public) 2026-03-10 19:18:13.783953 | controller | changed: [instance] => (item=cifmw-osp_trunk) 2026-03-10 19:18:13.783962 | controller | 2026-03-10 19:18:13.784185 | controller | TASK [libvirt_manager : Add a dummy interface to bridges if required] ********** 2026-03-10 19:18:13.876104 | controller | Tuesday 10 March 2026 19:18:13 +0000 (0:00:00.443) 0:00:12.672 ********* 2026-03-10 19:18:13.876130 | 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:18:14.072819 | controller | 2026-03-10 19:18:14.072858 | controller | TASK [libvirt_manager : Fetch present bridge interfaces] *********************** 2026-03-10 19:18:14.072866 | controller | Tuesday 10 March 2026 19:18:13 +0000 (0:00:00.092) 0:00:12.764 ********* 2026-03-10 19:18:14.072876 | controller | ok: [instance] 2026-03-10 19:18:14.445241 | controller | 2026-03-10 19:18:14.445269 | controller | TASK [libvirt_manager : Fetch bridges ports link info] ************************* 2026-03-10 19:18:14.445277 | controller | Tuesday 10 March 2026 19:18:14 +0000 (0:00:00.196) 0:00:12.961 ********* 2026-03-10 19:18:14.445287 | controller | ok: [instance] => (item=cifmw-public) 2026-03-10 19:18:14.579562 | controller | ok: [instance] => (item=cifmw-osp_trunk) 2026-03-10 19:18:14.579580 | controller | 2026-03-10 19:18:14.579587 | controller | TASK [Create dummy interfaces to ensure bridges are UP] ************************ 2026-03-10 19:18:14.579597 | controller | Tuesday 10 March 2026 19:18:14 +0000 (0:00:00.372) 0:00:13.333 ********* 2026-03-10 19:18:14.579606 | controller | 2026-03-10 19:18:14.685842 | controller | TASK [ci_nmstate : Ensure we have needed facts] ******************************** 2026-03-10 19:18:14.685863 | controller | Tuesday 10 March 2026 19:18:14 +0000 (0:00:00.134) 0:00:13.467 ********* 2026-03-10 19:18:14.685874 | controller | skipping: [instance] 2026-03-10 19:18:22.842418 | controller | 2026-03-10 19:18:22.842449 | controller | TASK [ci_nmstate : Install required packages on instance] ********************** 2026-03-10 19:18:22.842457 | controller | Tuesday 10 March 2026 19:18:14 +0000 (0:00:00.106) 0:00:13.574 ********* 2026-03-10 19:18:22.842467 | controller | changed: [instance] 2026-03-10 19:18:23.055624 | controller | 2026-03-10 19:18:23.055652 | controller | TASK [ci_nmstate : Create the target NMstate dump directory] ******************* 2026-03-10 19:18:23.055659 | controller | Tuesday 10 March 2026 19:18:22 +0000 (0:00:08.156) 0:00:21.730 ********* 2026-03-10 19:18:23.055668 | controller | changed: [instance] 2026-03-10 19:18:23.408452 | controller | 2026-03-10 19:18:23.408484 | controller | TASK [ci_nmstate : Save nmstate state for instance] **************************** 2026-03-10 19:18:23.408491 | controller | Tuesday 10 March 2026 19:18:23 +0000 (0:00:00.213) 0:00:21.944 ********* 2026-03-10 19:18:23.408501 | controller | changed: [instance] 2026-03-10 19:18:25.204858 | controller | 2026-03-10 19:18:25.204892 | controller | TASK [ci_nmstate : Apply the desidered state on instance] ********************** 2026-03-10 19:18:25.204901 | controller | Tuesday 10 March 2026 19:18:23 +0000 (0:00:00.352) 0:00:22.296 ********* 2026-03-10 19:18:25.204913 | controller | changed: [instance] 2026-03-10 19:18:25.204944 | controller | 2026-03-10 19:18:25.205082 | controller | TASK [ci_nmstate : Save nmstate debugging data for instance] ******************* 2026-03-10 19:18:25.205115 | controller | Tuesday 10 March 2026 19:18:25 +0000 (0:00:01.796) 0:00:24.093 ********* 2026-03-10 19:18:25.593460 | controller | changed: [instance] 2026-03-10 19:18:25.644848 | controller | 2026-03-10 19:18:25.644883 | controller | TASK [Create extra network configuration] ************************************** 2026-03-10 19:18:25.644894 | controller | Tuesday 10 March 2026 19:18:25 +0000 (0:00:00.388) 0:00:24.481 ********* 2026-03-10 19:18:25.644914 | controller | skipping: [instance] 2026-03-10 19:18:25.645206 | controller | 2026-03-10 19:18:25.645243 | controller | TASK [libvirt_manager : Ensure extra networks is in correct zone] ************** 2026-03-10 19:18:25.690101 | controller | Tuesday 10 March 2026 19:18:25 +0000 (0:00:00.051) 0:00:24.533 ********* 2026-03-10 19:18:25.690121 | controller | skipping: [instance] 2026-03-10 19:18:26.147888 | controller | 2026-03-10 19:18:26.147929 | controller | TASK [libvirt_manager : Refresh networking facts on host] ********************** 2026-03-10 19:18:26.147942 | controller | Tuesday 10 March 2026 19:18:25 +0000 (0:00:00.045) 0:00:24.578 ********* 2026-03-10 19:18:26.147958 | controller | ok: [instance] 2026-03-10 19:18:26.363480 | controller | 2026-03-10 19:18:26.363518 | controller | TASK [libvirt_manager : Build needed network/dnsmasq related content] ********** 2026-03-10 19:18:26.363527 | controller | Tuesday 10 March 2026 19:18:26 +0000 (0:00:00.457) 0:00:25.036 ********* 2026-03-10 19:18:26.363539 | controller | ok: [instance] => (item=cifmw-public) 2026-03-10 19:18:27.562828 | controller | ok: [instance] => (item=cifmw-osp_trunk) 2026-03-10 19:18:27.562861 | controller | 2026-03-10 19:18:27.562868 | controller | TASK [dnsmasq : Install needed packages] *************************************** 2026-03-10 19:18:27.562874 | controller | Tuesday 10 March 2026 19:18:26 +0000 (0:00:00.215) 0:00:25.251 ********* 2026-03-10 19:18:27.562884 | controller | ok: [instance] 2026-03-10 19:18:27.562902 | controller | 2026-03-10 19:18:27.563072 | controller | TASK [dnsmasq : Ensure SELinux knows about the new files and directories] ****** 2026-03-10 19:18:30.860451 | controller | Tuesday 10 March 2026 19:18:27 +0000 (0:00:01.199) 0:00:26.451 ********* 2026-03-10 19:18:30.860517 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.conf', 'setype': 'dnsmasq_etc_t'}) 2026-03-10 19:18:31.106496 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.d(/.*)?', 'setype': 'dnsmasq_etc_t'}) 2026-03-10 19:18:31.106533 | controller | changed: [instance] => (item={'target': '/var/run/cifmw-dnsmasq.pid', 'setype': 'dnsmasq_var_run_t'}) 2026-03-10 19:18:31.106542 | controller | 2026-03-10 19:18:31.106549 | controller | TASK [dnsmasq : Manage configuration directory] ******************************** 2026-03-10 19:18:31.106555 | controller | Tuesday 10 March 2026 19:18:30 +0000 (0:00:03.296) 0:00:29.748 ********* 2026-03-10 19:18:31.106565 | controller | changed: [instance] 2026-03-10 19:18:31.512400 | controller | 2026-03-10 19:18:31.512455 | controller | TASK [dnsmasq : Manage systemd unit file] ************************************** 2026-03-10 19:18:31.512466 | controller | Tuesday 10 March 2026 19:18:31 +0000 (0:00:00.246) 0:00:29.994 ********* 2026-03-10 19:18:31.512478 | controller | changed: [instance] 2026-03-10 19:18:31.927338 | controller | 2026-03-10 19:18:31.927386 | controller | TASK [dnsmasq : Manage base configuration file] ******************************** 2026-03-10 19:18:31.927405 | controller | Tuesday 10 March 2026 19:18:31 +0000 (0:00:00.405) 0:00:30.400 ********* 2026-03-10 19:18:31.927417 | controller | changed: [instance] 2026-03-10 19:18:31.995840 | controller | 2026-03-10 19:18:31.995874 | controller | TASK [dnsmasq : Render listener configuration] ********************************* 2026-03-10 19:18:31.995883 | controller | Tuesday 10 March 2026 19:18:31 +0000 (0:00:00.414) 0:00:30.815 ********* 2026-03-10 19:18:31.995895 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/listener.yml for instance 2026-03-10 19:18:31.995922 | controller | 2026-03-10 19:18:31.995932 | controller | TASK [dnsmasq : Create listener configuration file] **************************** 2026-03-10 19:18:31.996008 | controller | Tuesday 10 March 2026 19:18:31 +0000 (0:00:00.069) 0:00:30.884 ********* 2026-03-10 19:18:32.424535 | controller | changed: [instance] 2026-03-10 19:18:32.466662 | controller | 2026-03-10 19:18:32.466682 | controller | TASK [dnsmasq : Remove listener configuration file] **************************** 2026-03-10 19:18:32.466691 | controller | Tuesday 10 March 2026 19:18:32 +0000 (0:00:00.427) 0:00:31.312 ********* 2026-03-10 19:18:32.466701 | controller | skipping: [instance] 2026-03-10 19:18:32.537430 | controller | 2026-03-10 19:18:32.537450 | controller | TASK [dnsmasq : Render dns configuration] ************************************** 2026-03-10 19:18:32.537458 | controller | Tuesday 10 March 2026 19:18:32 +0000 (0:00:00.042) 0:00:31.355 ********* 2026-03-10 19:18:32.537474 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/dns.yml for instance 2026-03-10 19:18:32.934852 | controller | 2026-03-10 19:18:32.934893 | controller | TASK [dnsmasq : Create dns configuration file] ********************************* 2026-03-10 19:18:32.934900 | controller | Tuesday 10 March 2026 19:18:32 +0000 (0:00:00.070) 0:00:31.426 ********* 2026-03-10 19:18:32.934912 | controller | changed: [instance] 2026-03-10 19:18:32.973461 | controller | 2026-03-10 19:18:32.973492 | controller | TASK [dnsmasq : Remove dns configuration file] ********************************* 2026-03-10 19:18:32.973499 | controller | Tuesday 10 March 2026 19:18:32 +0000 (0:00:00.396) 0:00:31.823 ********* 2026-03-10 19:18:32.973509 | controller | skipping: [instance] 2026-03-10 19:18:33.238492 | controller | 2026-03-10 19:18:33.238530 | controller | TASK [dnsmasq : Add localhost addresses from defined dnsmasq listen addresses to loopback interface] *** 2026-03-10 19:18:33.238539 | controller | Tuesday 10 March 2026 19:18:32 +0000 (0:00:00.038) 0:00:31.861 ********* 2026-03-10 19:18:33.238550 | controller | ok: [instance] => (item=127.0.0.2) 2026-03-10 19:18:33.963785 | controller | 2026-03-10 19:18:33.963831 | controller | TASK [dnsmasq : Enable and start service] ************************************** 2026-03-10 19:18:33.963839 | controller | Tuesday 10 March 2026 19:18:33 +0000 (0:00:00.264) 0:00:32.126 ********* 2026-03-10 19:18:33.963849 | controller | changed: [instance] 2026-03-10 19:18:34.004882 | controller | 2026-03-10 19:18:34.004924 | controller | TASK [dnsmasq : Stop service] ************************************************** 2026-03-10 19:18:34.004945 | controller | Tuesday 10 March 2026 19:18:33 +0000 (0:00:00.725) 0:00:32.852 ********* 2026-03-10 19:18:34.004959 | controller | skipping: [instance] 2026-03-10 19:18:34.004984 | controller | 2026-03-10 19:18:34.004994 | controller | TASK [dnsmasq : Remove unit file] ********************************************** 2026-03-10 19:18:34.005044 | controller | Tuesday 10 March 2026 19:18:34 +0000 (0:00:00.041) 0:00:32.893 ********* 2026-03-10 19:18:34.044387 | controller | skipping: [instance] 2026-03-10 19:18:34.143136 | controller | 2026-03-10 19:18:34.143163 | controller | TASK [dnsmasq : Remove main configuration files] ******************************* 2026-03-10 19:18:34.143171 | controller | Tuesday 10 March 2026 19:18:34 +0000 (0:00:00.039) 0:00:32.933 ********* 2026-03-10 19:18:34.143189 | controller | skipping: [instance] => (item=/etc/cifmw-dnsmasq.conf) 2026-03-10 19:18:34.280846 | controller | skipping: [instance] => (item=/etc/cifmw-dnsmasq.d) 2026-03-10 19:18:34.280871 | controller | skipping: [instance] 2026-03-10 19:18:34.280880 | controller | 2026-03-10 19:18:34.280887 | controller | TASK [Create dnsmasq networks] ************************************************* 2026-03-10 19:18:34.280893 | controller | Tuesday 10 March 2026 19:18:34 +0000 (0:00:00.098) 0:00:33.031 ********* 2026-03-10 19:18:34.280904 | controller | 2026-03-10 19:18:34.281103 | controller | TASK [dnsmasq : Assert mandatory parameter] ************************************ 2026-03-10 19:18:34.329846 | controller | Tuesday 10 March 2026 19:18:34 +0000 (0:00:00.138) 0:00:33.169 ********* 2026-03-10 19:18:34.329895 | controller | ok: [instance] => changed=false 2026-03-10 19:18:34.329930 | controller | msg: All assertions passed 2026-03-10 19:18:34.329939 | controller | 2026-03-10 19:18:34.329950 | controller | TASK [dnsmasq : Assert mandatory parameters for new network] ******************* 2026-03-10 19:18:34.330016 | controller | Tuesday 10 March 2026 19:18:34 +0000 (0:00:00.049) 0:00:33.218 ********* 2026-03-10 19:18:34.387294 | controller | ok: [instance] => changed=false 2026-03-10 19:18:34.867380 | controller | msg: All assertions passed 2026-03-10 19:18:34.867418 | controller | 2026-03-10 19:18:34.867426 | controller | TASK [dnsmasq : Create network] ************************************************ 2026-03-10 19:18:34.867433 | controller | Tuesday 10 March 2026 19:18:34 +0000 (0:00:00.057) 0:00:33.275 ********* 2026-03-10 19:18:34.867443 | controller | changed: [instance] 2026-03-10 19:18:34.908697 | controller | 2026-03-10 19:18:34.908753 | controller | TASK [dnsmasq : Remove network] ************************************************ 2026-03-10 19:18:34.908771 | controller | Tuesday 10 March 2026 19:18:34 +0000 (0:00:00.479) 0:00:33.755 ********* 2026-03-10 19:18:34.908784 | controller | skipping: [instance] 2026-03-10 19:18:34.974921 | controller | 2026-03-10 19:18:34.974950 | controller | TASK [dnsmasq : Remove dhcp host files] **************************************** 2026-03-10 19:18:34.974965 | controller | Tuesday 10 March 2026 19:18:34 +0000 (0:00:00.041) 0:00:33.797 ********* 2026-03-10 19:18:34.974977 | controller | skipping: [instance] 2026-03-10 19:18:35.023878 | controller | 2026-03-10 19:18:35.023920 | controller | TASK [dnsmasq : Assert mandatory parameter] ************************************ 2026-03-10 19:18:35.023931 | controller | Tuesday 10 March 2026 19:18:34 +0000 (0:00:00.066) 0:00:33.863 ********* 2026-03-10 19:18:35.023946 | controller | ok: [instance] => changed=false 2026-03-10 19:18:35.114791 | controller | msg: All assertions passed 2026-03-10 19:18:35.114831 | controller | 2026-03-10 19:18:35.114840 | controller | TASK [dnsmasq : Assert mandatory parameters for new network] ******************* 2026-03-10 19:18:35.114846 | controller | Tuesday 10 March 2026 19:18:35 +0000 (0:00:00.048) 0:00:33.912 ********* 2026-03-10 19:18:35.114856 | controller | ok: [instance] => changed=false 2026-03-10 19:18:35.114879 | controller | msg: All assertions passed 2026-03-10 19:18:35.114885 | controller | 2026-03-10 19:18:35.114892 | controller | TASK [dnsmasq : Create network] ************************************************ 2026-03-10 19:18:35.115002 | controller | Tuesday 10 March 2026 19:18:35 +0000 (0:00:00.090) 0:00:34.003 ********* 2026-03-10 19:18:35.546596 | controller | changed: [instance] 2026-03-10 19:18:35.584770 | controller | 2026-03-10 19:18:35.584791 | controller | TASK [dnsmasq : Remove network] ************************************************ 2026-03-10 19:18:35.584800 | controller | Tuesday 10 March 2026 19:18:35 +0000 (0:00:00.431) 0:00:34.434 ********* 2026-03-10 19:18:35.584810 | controller | skipping: [instance] 2026-03-10 19:18:35.584848 | controller | 2026-03-10 19:18:35.585046 | controller | TASK [dnsmasq : Remove dhcp host files] **************************************** 2026-03-10 19:18:35.648182 | controller | Tuesday 10 March 2026 19:18:35 +0000 (0:00:00.038) 0:00:34.473 ********* 2026-03-10 19:18:35.648203 | controller | skipping: [instance] 2026-03-10 19:18:35.728927 | controller | 2026-03-10 19:18:35.728954 | controller | TASK [Deploy radvd for IPv6 router advertisements] ***************************** 2026-03-10 19:18:35.728962 | controller | Tuesday 10 March 2026 19:18:35 +0000 (0:00:00.063) 0:00:34.536 ********* 2026-03-10 19:18:35.728972 | controller | skipping: [instance] 2026-03-10 19:18:36.215708 | controller | 2026-03-10 19:18:36.215753 | controller | TASK [libvirt_manager : Ensure network is in correct zone] ********************* 2026-03-10 19:18:36.215761 | controller | Tuesday 10 March 2026 19:18:35 +0000 (0:00:00.080) 0:00:34.617 ********* 2026-03-10 19:18:36.215770 | controller | changed: [instance] => (item=cifmw-public) 2026-03-10 19:18:36.295484 | controller | changed: [instance] => (item=cifmw-osp_trunk) 2026-03-10 19:18:36.295514 | controller | 2026-03-10 19:18:36.295521 | controller | TASK [libvirt_manager : Extract IP address from network bridges] *************** 2026-03-10 19:18:36.295527 | controller | Tuesday 10 March 2026 19:18:36 +0000 (0:00:00.486) 0:00:35.104 ********* 2026-03-10 19:18:36.295536 | 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:18:36.633296 | 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:18:36.633327 | controller | 2026-03-10 19:18:36.633335 | controller | TASK [libvirt_manager : Extract IP address from network bridges] *************** 2026-03-10 19:18:36.633341 | controller | Tuesday 10 March 2026 19:18:36 +0000 (0:00:00.079) 0:00:35.183 ********* 2026-03-10 19:18:36.633351 | controller | ok: [instance] 2026-03-10 19:18:36.725028 | controller | 2026-03-10 19:18:36.725053 | controller | TASK [libvirt_manager : Set network_bridge_info fact with network and address] *** 2026-03-10 19:18:36.725061 | controller | Tuesday 10 March 2026 19:18:36 +0000 (0:00:00.337) 0:00:35.521 ********* 2026-03-10 19:18:36.725070 | controller | ok: [instance] 2026-03-10 19:18:36.942839 | controller | 2026-03-10 19:18:36.942875 | controller | TASK [libvirt_manager : Extract IP address from network bridges] *************** 2026-03-10 19:18:36.942885 | controller | Tuesday 10 March 2026 19:18:36 +0000 (0:00:00.091) 0:00:35.613 ********* 2026-03-10 19:18:36.942896 | controller | ok: [instance] 2026-03-10 19:18:36.942929 | controller | 2026-03-10 19:18:36.942938 | controller | TASK [libvirt_manager : Set network_bridge_info fact with network and address] *** 2026-03-10 19:18:36.942963 | controller | Tuesday 10 March 2026 19:18:36 +0000 (0:00:00.217) 0:00:35.831 ********* 2026-03-10 19:18:36.996635 | controller | ok: [instance] 2026-03-10 19:18:37.334355 | controller | 2026-03-10 19:18:37.334385 | controller | TASK [libvirt_manager : Output _network_data] ********************************** 2026-03-10 19:18:37.334393 | controller | Tuesday 10 March 2026 19:18:36 +0000 (0:00:00.053) 0:00:35.885 ********* 2026-03-10 19:18:37.334403 | controller | changed: [instance] 2026-03-10 19:18:37.668365 | controller | 2026-03-10 19:18:37.668395 | controller | TASK [libvirt_manager : Output pub_net] **************************************** 2026-03-10 19:18:37.668405 | controller | Tuesday 10 March 2026 19:18:37 +0000 (0:00:00.337) 0:00:36.222 ********* 2026-03-10 19:18:37.668422 | controller | changed: [instance] 2026-03-10 19:18:38.045490 | controller | 2026-03-10 19:18:38.045521 | controller | TASK [libvirt_manager : Output _cifmw_libvirt_manager_layout] ****************** 2026-03-10 19:18:38.045529 | controller | Tuesday 10 March 2026 19:18:37 +0000 (0:00:00.333) 0:00:36.556 ********* 2026-03-10 19:18:38.045539 | controller | changed: [instance] 2026-03-10 19:18:38.158416 | controller | 2026-03-10 19:18:38.158438 | controller | TASK [libvirt_manager : Expose patch for networking_mapper] ******************** 2026-03-10 19:18:38.158446 | controller | Tuesday 10 March 2026 19:18:38 +0000 (0:00:00.377) 0:00:36.933 ********* 2026-03-10 19:18:38.158455 | controller | ok: [instance] 2026-03-10 19:18:38.492865 | controller | 2026-03-10 19:18:38.492904 | controller | TASK [libvirt_manager : Save networking_mapper patch] ************************** 2026-03-10 19:18:38.492914 | controller | Tuesday 10 March 2026 19:18:38 +0000 (0:00:00.113) 0:00:37.047 ********* 2026-03-10 19:18:38.492927 | controller | changed: [instance] 2026-03-10 19:18:38.492964 | controller | 2026-03-10 19:18:38.492974 | controller | TASK [networking_mapper : Check for Networking Definition file existence] ****** 2026-03-10 19:18:38.538860 | controller | Tuesday 10 March 2026 19:18:38 +0000 (0:00:00.334) 0:00:37.381 ********* 2026-03-10 19:18:38.538882 | controller | skipping: [instance] 2026-03-10 19:18:38.538953 | controller | 2026-03-10 19:18:38.539048 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************ 2026-03-10 19:18:38.539142 | controller | Tuesday 10 March 2026 19:18:38 +0000 (0:00:00.046) 0:00:37.427 ********* 2026-03-10 19:18:38.583802 | controller | skipping: [instance] 2026-03-10 19:18:38.583868 | controller | 2026-03-10 19:18:38.583956 | controller | TASK [networking_mapper : Check for interfaces info file existence] ************ 2026-03-10 19:18:38.584051 | controller | Tuesday 10 March 2026 19:18:38 +0000 (0:00:00.044) 0:00:37.472 ********* 2026-03-10 19:18:38.661733 | controller | skipping: [instance] 2026-03-10 19:18:38.661795 | controller | 2026-03-10 19:18:38.661918 | controller | TASK [networking_mapper : Load the interfaces info from file] ****************** 2026-03-10 19:18:38.662033 | controller | Tuesday 10 March 2026 19:18:38 +0000 (0:00:00.077) 0:00:37.550 ********* 2026-03-10 19:18:38.710694 | controller | skipping: [instance] 2026-03-10 19:18:38.751327 | controller | 2026-03-10 19:18:38.751344 | controller | TASK [networking_mapper : Ensure local fact is empty] ************************** 2026-03-10 19:18:38.751351 | controller | Tuesday 10 March 2026 19:18:38 +0000 (0:00:00.048) 0:00:37.599 ********* 2026-03-10 19:18:38.751360 | controller | ok: [instance] 2026-03-10 19:18:38.878145 | controller | 2026-03-10 19:18:38.878164 | controller | TASK [networking_mapper : Set the input Networking Definition and patch it if needed] *** 2026-03-10 19:18:38.878173 | controller | Tuesday 10 March 2026 19:18:38 +0000 (0:00:00.040) 0:00:37.639 ********* 2026-03-10 19:18:38.878185 | controller | ok: [instance] => (item={'networks': {'ctlplane': {'network': '192.168.140.0/24', 'gateway': '192.168.140.1', 'mtu': 1500}}, 'group-templates': {'disk_firsts': {'network-template': {'range': {'start': 10, 'length': 1}}, 'networks': {'ctlplane': {}}}, 'net_firsts': {'network-template': {'range': {'start': 20, 'length': 1}}, 'networks': {'ctlplane': {}}}, 'net_onlys': {'network-template': {'range': {'start': 30, 'length': 1}}, 'networks': {'ctlplane': {}}}, 'no_boot_orders': {'network-template': {'range': {'start': 40, 'length': 1}}, 'networks': {'ctlplane': {}}}}}) 2026-03-10 19:18:38.918632 | controller | ok: [instance] => (item={'networks': {'public': {'network-v4': '192.168.110.0/24'}}, 'group-templates': {'disk_firsts': {'networks': {'public': None}}, 'net_firsts': {'networks': {'public': None}}, 'net_onlys': {'networks': {'public': None}}, 'no_boot_orders': {'networks': {'public': None}}}}) 2026-03-10 19:18:38.918651 | controller | 2026-03-10 19:18:38.918658 | controller | TASK [networking_mapper : Gather facts if requested] *************************** 2026-03-10 19:18:38.918663 | controller | Tuesday 10 March 2026 19:18:38 +0000 (0:00:00.126) 0:00:37.766 ********* 2026-03-10 19:18:38.918671 | controller | skipping: [instance] 2026-03-10 19:18:39.134759 | controller | 2026-03-10 19:18:39.134791 | controller | TASK [networking_mapper : Ensure CI infrastructure dir exists] ***************** 2026-03-10 19:18:39.134802 | controller | Tuesday 10 March 2026 19:18:38 +0000 (0:00:00.040) 0:00:37.807 ********* 2026-03-10 19:18:39.134825 | controller | ok: [instance] 2026-03-10 19:18:39.567180 | controller | 2026-03-10 19:18:39.567210 | controller | TASK [networking_mapper : Write the Networking Definition to file] ************* 2026-03-10 19:18:39.567218 | controller | Tuesday 10 March 2026 19:18:39 +0000 (0:00:00.216) 0:00:38.023 ********* 2026-03-10 19:18:39.567227 | controller | changed: [instance] 2026-03-10 19:18:39.664163 | controller | 2026-03-10 19:18:39.664192 | controller | TASK [networking_mapper : Call the networking mapper] ************************** 2026-03-10 19:18:39.664199 | controller | Tuesday 10 March 2026 19:18:39 +0000 (0:00:00.432) 0:00:38.455 ********* 2026-03-10 19:18:39.664209 | controller | ok: [instance] 2026-03-10 19:18:39.709417 | controller | 2026-03-10 19:18:39.709436 | controller | TASK [networking_mapper : Set networking mapper facts] ************************* 2026-03-10 19:18:39.709443 | controller | Tuesday 10 March 2026 19:18:39 +0000 (0:00:00.097) 0:00:38.552 ********* 2026-03-10 19:18:39.709452 | controller | ok: [instance] 2026-03-10 19:18:40.173867 | controller | 2026-03-10 19:18:40.173908 | controller | TASK [networking_mapper : Write the Networking Environment Definition to file] *** 2026-03-10 19:18:40.173917 | controller | Tuesday 10 March 2026 19:18:39 +0000 (0:00:00.045) 0:00:38.598 ********* 2026-03-10 19:18:40.173930 | controller | changed: [instance] 2026-03-10 19:18:40.173955 | controller | 2026-03-10 19:18:40.173964 | controller | TASK [libvirt_manager : Be sure to save current state of networking_mapper environment] *** 2026-03-10 19:18:40.173995 | controller | Tuesday 10 March 2026 19:18:40 +0000 (0:00:00.463) 0:00:39.061 ********* 2026-03-10 19:18:40.221435 | controller | ok: [instance] 2026-03-10 19:18:40.261504 | controller | 2026-03-10 19:18:40.261530 | controller | TASK [libvirt_manager : Unset patch] ******************************************* 2026-03-10 19:18:40.261538 | controller | Tuesday 10 March 2026 19:18:40 +0000 (0:00:00.048) 0:00:39.109 ********* 2026-03-10 19:18:40.261550 | controller | ok: [instance] 2026-03-10 19:18:40.299840 | controller | 2026-03-10 19:18:40.299871 | controller | TASK [libvirt_manager : Initialize empty _lm_dhcp_entries fact] **************** 2026-03-10 19:18:40.299882 | controller | Tuesday 10 March 2026 19:18:40 +0000 (0:00:00.040) 0:00:39.150 ********* 2026-03-10 19:18:40.299896 | controller | ok: [instance] 2026-03-10 19:18:40.299970 | controller | 2026-03-10 19:18:40.299983 | controller | TASK [libvirt_manager : Reserve IPs on networks] ******************************* 2026-03-10 19:18:40.299994 | controller | Tuesday 10 March 2026 19:18:40 +0000 (0:00:00.038) 0:00:39.188 ********* 2026-03-10 19:18:40.374053 | 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:18:40.586146 | 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:18:40.586182 | controller | 2026-03-10 19:18:40.586192 | controller | TASK [libvirt_manager : Loop on host IPs] ************************************** 2026-03-10 19:18:40.586200 | controller | Tuesday 10 March 2026 19:18:40 +0000 (0:00:00.074) 0:00:39.262 ********* 2026-03-10 19:18:40.586213 | controller | ok: [instance] => (item=disk-first-0 - ctlplane) 2026-03-10 19:18:40.586246 | controller | ok: [instance] => (item=net-first-0 - ctlplane) 2026-03-10 19:18:40.586364 | controller | ok: [instance] => (item=net-only-0 - ctlplane) 2026-03-10 19:18:40.586472 | controller | ok: [instance] => (item=no-boot-order-0 - ctlplane) 2026-03-10 19:18:40.586582 | controller | 2026-03-10 19:18:40.586683 | controller | TASK [libvirt_manager : Loop on host IPs] ************************************** 2026-03-10 19:18:40.586811 | controller | Tuesday 10 March 2026 19:18:40 +0000 (0:00:00.212) 0:00:39.474 ********* 2026-03-10 19:18:40.766048 | controller | ok: [instance] => (item=disk-first-0 - public) 2026-03-10 19:18:40.766248 | controller | ok: [instance] => (item=net-first-0 - public) 2026-03-10 19:18:40.766429 | controller | ok: [instance] => (item=net-only-0 - public) 2026-03-10 19:18:40.766605 | controller | ok: [instance] => (item=no-boot-order-0 - public) 2026-03-10 19:18:40.766792 | controller | 2026-03-10 19:18:40.766997 | controller | TASK [Inject DHCP entries for net {{ net_name }}] ****************************** 2026-03-10 19:18:40.767096 | controller | Tuesday 10 March 2026 19:18:40 +0000 (0:00:00.179) 0:00:39.654 ********* 2026-03-10 19:18:40.859164 | controller | 2026-03-10 19:18:40.859300 | controller | TASK [dnsmasq : Ensure we have the right data and type] ************************ 2026-03-10 19:18:40.859401 | controller | Tuesday 10 March 2026 19:18:40 +0000 (0:00:00.093) 0:00:39.748 ********* 2026-03-10 19:18:40.917263 | controller | ok: [instance] 2026-03-10 19:18:40.917370 | controller | 2026-03-10 19:18:40.917471 | controller | TASK [dnsmasq : Assert we have needed host data] ******************************* 2026-03-10 19:18:40.917569 | controller | Tuesday 10 March 2026 19:18:40 +0000 (0:00:00.058) 0:00:39.806 ********* 2026-03-10 19:18:41.084477 | controller | ok: [instance] => (item={'network': 'osp_trunk', 'name': 'disk-first-0', 'state': 'present', 'mac': '52:54:00:6b:35:72', 'ips': ['192.168.140.10', ''], 'tag': 'disk_first'}) 2026-03-10 19:18:41.084606 | controller | ok: [instance] => (item={'network': 'osp_trunk', 'name': 'net-first-0', 'state': 'present', 'mac': '52:54:01:16:7f:34', 'ips': ['192.168.140.20', ''], 'tag': 'net_first'}) 2026-03-10 19:18:41.084709 | controller | ok: [instance] => (item={'network': 'osp_trunk', 'name': 'net-only-0', 'state': 'present', 'mac': '52:54:00:36:cb:6d', 'ips': ['192.168.140.30', ''], 'tag': 'net_only'}) 2026-03-10 19:18:41.084837 | controller | ok: [instance] => (item={'network': 'osp_trunk', 'name': 'no-boot-order-0', 'state': 'present', 'mac': '52:54:00:6e:30:bb', 'ips': ['192.168.140.40', ''], 'tag': 'no_boot_order'}) 2026-03-10 19:18:41.084955 | controller | ok: [instance] => (item={'network': 'public', 'name': 'disk-first-0', 'state': 'present', 'mac': '52:54:00:5b:51:b7', 'ips': ['192.168.110.10', ''], 'tag': 'disk_first'}) 2026-03-10 19:18:41.085047 | controller | ok: [instance] => (item={'network': 'public', 'name': 'net-first-0', 'state': 'present', 'mac': '52:54:01:2b:51:cf', 'ips': ['192.168.110.20', ''], 'tag': 'net_first'}) 2026-03-10 19:18:41.085146 | controller | ok: [instance] => (item={'network': 'public', 'name': 'net-only-0', 'state': 'present', 'mac': '52:54:02:83:fb:2d', 'ips': ['192.168.110.30', ''], 'tag': 'net_only'}) 2026-03-10 19:18:41.085236 | controller | ok: [instance] => (item={'network': 'public', 'name': 'no-boot-order-0', 'state': 'present', 'mac': '52:54:03:1b:57:87', 'ips': ['192.168.110.40', ''], 'tag': 'no_boot_order'}) 2026-03-10 19:18:41.085321 | controller | 2026-03-10 19:18:41.085413 | controller | TASK [dnsmasq : Ensure networks exists] **************************************** 2026-03-10 19:18:41.085505 | controller | Tuesday 10 March 2026 19:18:41 +0000 (0:00:00.167) 0:00:39.973 ********* 2026-03-10 19:18:41.175084 | 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:18:41.175229 | 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:18:41.175344 | controller | 2026-03-10 19:18:41.175463 | controller | TASK [dnsmasq : Check network file status] ************************************* 2026-03-10 19:18:41.175577 | controller | Tuesday 10 March 2026 19:18:41 +0000 (0:00:00.090) 0:00:40.064 ********* 2026-03-10 19:18:41.361473 | controller | ok: [instance] 2026-03-10 19:18:41.403268 | controller | 2026-03-10 19:18:41.403290 | controller | TASK [dnsmasq : Assert network exists] ***************************************** 2026-03-10 19:18:41.403301 | controller | Tuesday 10 March 2026 19:18:41 +0000 (0:00:00.186) 0:00:40.250 ********* 2026-03-10 19:18:41.403310 | controller | ok: [instance] 2026-03-10 19:18:41.589623 | controller | 2026-03-10 19:18:41.589651 | controller | TASK [dnsmasq : Check network file status] ************************************* 2026-03-10 19:18:41.589658 | controller | Tuesday 10 March 2026 19:18:41 +0000 (0:00:00.041) 0:00:40.291 ********* 2026-03-10 19:18:41.589668 | controller | ok: [instance] 2026-03-10 19:18:41.668404 | controller | 2026-03-10 19:18:41.668426 | controller | TASK [dnsmasq : Assert network exists] ***************************************** 2026-03-10 19:18:41.668434 | controller | Tuesday 10 March 2026 19:18:41 +0000 (0:00:00.186) 0:00:40.478 ********* 2026-03-10 19:18:41.668445 | controller | ok: [instance] 2026-03-10 19:18:41.708797 | controller | 2026-03-10 19:18:41.708827 | controller | TASK [dnsmasq : Initialize empty dhcp_host_entries] **************************** 2026-03-10 19:18:41.708836 | controller | Tuesday 10 March 2026 19:18:41 +0000 (0:00:00.078) 0:00:40.557 ********* 2026-03-10 19:18:41.708846 | controller | ok: [instance] 2026-03-10 19:18:41.708905 | controller | 2026-03-10 19:18:41.708920 | controller | TASK [dnsmasq : Compute entry] ************************************************* 2026-03-10 19:18:41.708929 | controller | Tuesday 10 March 2026 19:18:41 +0000 (0:00:00.040) 0:00:40.597 ********* 2026-03-10 19:18:41.933902 | controller | ok: [instance] => (item={'network': 'osp_trunk', 'name': 'disk-first-0', 'state': 'present', 'mac': '52:54:00:6b:35:72', 'ips': ['192.168.140.10', ''], 'tag': 'disk_first'}) 2026-03-10 19:18:41.934055 | controller | ok: [instance] => (item={'network': 'osp_trunk', 'name': 'net-first-0', 'state': 'present', 'mac': '52:54:01:16:7f:34', 'ips': ['192.168.140.20', ''], 'tag': 'net_first'}) 2026-03-10 19:18:41.934075 | controller | ok: [instance] => (item={'network': 'osp_trunk', 'name': 'net-only-0', 'state': 'present', 'mac': '52:54:00:36:cb:6d', 'ips': ['192.168.140.30', ''], 'tag': 'net_only'}) 2026-03-10 19:18:41.934087 | controller | ok: [instance] => (item={'network': 'osp_trunk', 'name': 'no-boot-order-0', 'state': 'present', 'mac': '52:54:00:6e:30:bb', 'ips': ['192.168.140.40', ''], 'tag': 'no_boot_order'}) 2026-03-10 19:18:41.934097 | controller | ok: [instance] => (item={'network': 'public', 'name': 'disk-first-0', 'state': 'present', 'mac': '52:54:00:5b:51:b7', 'ips': ['192.168.110.10', ''], 'tag': 'disk_first'}) 2026-03-10 19:18:41.934106 | controller | ok: [instance] => (item={'network': 'public', 'name': 'net-first-0', 'state': 'present', 'mac': '52:54:01:2b:51:cf', 'ips': ['192.168.110.20', ''], 'tag': 'net_first'}) 2026-03-10 19:18:41.934121 | controller | ok: [instance] => (item={'network': 'public', 'name': 'net-only-0', 'state': 'present', 'mac': '52:54:02:83:fb:2d', 'ips': ['192.168.110.30', ''], 'tag': 'net_only'}) 2026-03-10 19:18:41.934250 | controller | ok: [instance] => (item={'network': 'public', 'name': 'no-boot-order-0', 'state': 'present', 'mac': '52:54:03:1b:57:87', 'ips': ['192.168.110.40', ''], 'tag': 'no_boot_order'}) 2026-03-10 19:18:41.934341 | controller | 2026-03-10 19:18:41.934437 | controller | TASK [dnsmasq : Create add/remove sets] **************************************** 2026-03-10 19:18:41.934525 | controller | Tuesday 10 March 2026 19:18:41 +0000 (0:00:00.224) 0:00:40.821 ********* 2026-03-10 19:18:41.993760 | controller | ok: [instance] 2026-03-10 19:18:44.688826 | controller | 2026-03-10 19:18:44.688863 | controller | TASK [dnsmasq : Add DHCP entries] ********************************************** 2026-03-10 19:18:44.688873 | controller | Tuesday 10 March 2026 19:18:41 +0000 (0:00:00.060) 0:00:40.882 ********* 2026-03-10 19:18:44.688885 | controller | changed: [instance] => (item={'file': 'osp_trunk_disk-first-0_52:54:00:6b:35:72', 'entry': 'set:disk_first,52:54:00:6b:35:72,192.168.140.10,disk-first-0', 'state': 'present'}) 2026-03-10 19:18:44.688912 | controller | changed: [instance] => (item={'file': 'osp_trunk_net-first-0_52:54:01:16:7f:34', 'entry': 'set:net_first,52:54:01:16:7f:34,192.168.140.20,net-first-0', 'state': 'present'}) 2026-03-10 19:18:44.688919 | controller | changed: [instance] => (item={'file': 'osp_trunk_net-only-0_52:54:00:36:cb:6d', 'entry': 'set:net_only,52:54:00:36:cb:6d,192.168.140.30,net-only-0', 'state': 'present'}) 2026-03-10 19:18:44.688925 | controller | changed: [instance] => (item={'file': 'osp_trunk_no-boot-order-0_52:54:00:6e:30:bb', 'entry': 'set:no_boot_order,52:54:00:6e:30:bb,192.168.140.40,no-boot-order-0', 'state': 'present'}) 2026-03-10 19:18:44.688937 | controller | changed: [instance] => (item={'file': 'public_disk-first-0_52:54:00:5b:51:b7', 'entry': 'set:disk_first,52:54:00:5b:51:b7,192.168.110.10,disk-first-0', 'state': 'present'}) 2026-03-10 19:18:44.688943 | controller | changed: [instance] => (item={'file': 'public_net-first-0_52:54:01:2b:51:cf', 'entry': 'set:net_first,52:54:01:2b:51:cf,192.168.110.20,net-first-0', 'state': 'present'}) 2026-03-10 19:18:44.688949 | controller | changed: [instance] => (item={'file': 'public_net-only-0_52:54:02:83:fb:2d', 'entry': 'set:net_only,52:54:02:83:fb:2d,192.168.110.30,net-only-0', 'state': 'present'}) 2026-03-10 19:18:44.688956 | controller | changed: [instance] => (item={'file': 'public_no-boot-order-0_52:54:03:1b:57:87', 'entry': 'set:no_boot_order,52:54:03:1b:57:87,192.168.110.40,no-boot-order-0', 'state': 'present'}) 2026-03-10 19:18:44.688962 | controller | 2026-03-10 19:18:44.688970 | controller | TASK [dnsmasq : Remove DHCP entries] ******************************************* 2026-03-10 19:18:44.750780 | controller | Tuesday 10 March 2026 19:18:44 +0000 (0:00:02.694) 0:00:43.576 ********* 2026-03-10 19:18:44.750805 | controller | skipping: [instance] 2026-03-10 19:18:44.751105 | controller | 2026-03-10 19:18:45.001843 | controller | TASK [libvirt_manager : Create per-network and .utility DNS entries] *********** 2026-03-10 19:18:45.001869 | controller | Tuesday 10 March 2026 19:18:44 +0000 (0:00:00.063) 0:00:43.639 ********* 2026-03-10 19:18:45.001883 | controller | ok: [instance] => (item=disk-first-0) 2026-03-10 19:18:45.001913 | controller | ok: [instance] => (item=net-first-0) 2026-03-10 19:18:45.001920 | controller | ok: [instance] => (item=net-only-0) 2026-03-10 19:18:45.001926 | controller | ok: [instance] => (item=no-boot-order-0) 2026-03-10 19:18:45.001935 | controller | 2026-03-10 19:18:45.002120 | controller | TASK [Inject VMs in the .utility zone] ***************************************** 2026-03-10 19:18:45.099675 | controller | Tuesday 10 March 2026 19:18:44 +0000 (0:00:00.250) 0:00:43.890 ********* 2026-03-10 19:18:45.099702 | controller | 2026-03-10 19:18:45.160210 | controller | TASK [dnsmasq : Assert we have needed host record data] ************************ 2026-03-10 19:18:45.160240 | controller | Tuesday 10 March 2026 19:18:45 +0000 (0:00:00.097) 0:00:43.988 ********* 2026-03-10 19:18:45.160256 | controller | ok: [instance] 2026-03-10 19:18:45.343823 | controller | 2026-03-10 19:18:45.343858 | controller | TASK [dnsmasq : Assert each address element have needed data] ****************** 2026-03-10 19:18:45.343866 | controller | Tuesday 10 March 2026 19:18:45 +0000 (0:00:00.060) 0:00:44.048 ********* 2026-03-10 19:18:45.343878 | controller | ok: [instance] => (item={'names': ['disk-first-0.utility', 'disk-first-0.instance', 'disk_first-0.utility', 'disk_first-0.instance'], 'ips': ['192.168.140.10', ''], 'state': 'present'}) 2026-03-10 19:18:45.344771 | controller | ok: [instance] => (item={'names': ['disk-first-0.ctlplane.local', 'disk_first-0.ctlplane.local'], 'ips': ['192.168.140.10', ''], 'state': 'present'}) 2026-03-10 19:18:45.344786 | controller | ok: [instance] => (item={'names': ['disk-first-0.public.local', 'disk_first-0.public.local'], 'ips': ['192.168.110.10', ''], 'state': 'present'}) 2026-03-10 19:18:45.344930 | controller | ok: [instance] => (item={'names': ['net-first-0.utility', 'net-first-0.instance', 'net_first-0.utility', 'net_first-0.instance'], 'ips': ['192.168.140.20', ''], 'state': 'present'}) 2026-03-10 19:18:45.344939 | controller | ok: [instance] => (item={'names': ['net-first-0.ctlplane.local', 'net_first-0.ctlplane.local'], 'ips': ['192.168.140.20', ''], 'state': 'present'}) 2026-03-10 19:18:45.344944 | controller | ok: [instance] => (item={'names': ['net-first-0.public.local', 'net_first-0.public.local'], 'ips': ['192.168.110.20', ''], 'state': 'present'}) 2026-03-10 19:18:45.344949 | controller | ok: [instance] => (item={'names': ['net-only-0.utility', 'net-only-0.instance', 'net_only-0.utility', 'net_only-0.instance'], 'ips': ['192.168.140.30', ''], 'state': 'present'}) 2026-03-10 19:18:45.344955 | controller | ok: [instance] => (item={'names': ['net-only-0.ctlplane.local', 'net_only-0.ctlplane.local'], 'ips': ['192.168.140.30', ''], 'state': 'present'}) 2026-03-10 19:18:45.344964 | controller | ok: [instance] => (item={'names': ['net-only-0.public.local', 'net_only-0.public.local'], 'ips': ['192.168.110.30', ''], 'state': 'present'}) 2026-03-10 19:18:45.344970 | controller | ok: [instance] => (item={'names': ['no-boot-order-0.utility', 'no-boot-order-0.instance', 'no_boot_order-0.utility', 'no_boot_order-0.instance'], 'ips': ['192.168.140.40', ''], 'state': 'present'}) 2026-03-10 19:18:45.344975 | controller | ok: [instance] => (item={'names': ['no-boot-order-0.ctlplane.local', 'no_boot_order-0.ctlplane.local'], 'ips': ['192.168.140.40', ''], 'state': 'present'}) 2026-03-10 19:18:45.344981 | controller | ok: [instance] => (item={'names': ['no-boot-order-0.public.local', 'no_boot_order-0.public.local'], 'ips': ['192.168.110.40', ''], 'state': 'present'}) 2026-03-10 19:18:45.344986 | controller | 2026-03-10 19:18:45.344994 | controller | TASK [dnsmasq : Add/Remove address] ******************************************** 2026-03-10 19:18:47.629855 | controller | Tuesday 10 March 2026 19:18:45 +0000 (0:00:00.183) 0:00:44.232 ********* 2026-03-10 19:18:47.629895 | controller | changed: [instance] => (item={'names': ['disk-first-0.utility', 'disk-first-0.instance', 'disk_first-0.utility', 'disk_first-0.instance'], 'ips': ['192.168.140.10', ''], 'state': 'present'}) 2026-03-10 19:18:47.629927 | controller | changed: [instance] => (item={'names': ['disk-first-0.ctlplane.local', 'disk_first-0.ctlplane.local'], 'ips': ['192.168.140.10', ''], 'state': 'present'}) 2026-03-10 19:18:47.629936 | controller | changed: [instance] => (item={'names': ['disk-first-0.public.local', 'disk_first-0.public.local'], 'ips': ['192.168.110.10', ''], 'state': 'present'}) 2026-03-10 19:18:47.629943 | controller | changed: [instance] => (item={'names': ['net-first-0.utility', 'net-first-0.instance', 'net_first-0.utility', 'net_first-0.instance'], 'ips': ['192.168.140.20', ''], 'state': 'present'}) 2026-03-10 19:18:47.629950 | controller | changed: [instance] => (item={'names': ['net-first-0.ctlplane.local', 'net_first-0.ctlplane.local'], 'ips': ['192.168.140.20', ''], 'state': 'present'}) 2026-03-10 19:18:47.629957 | controller | changed: [instance] => (item={'names': ['net-first-0.public.local', 'net_first-0.public.local'], 'ips': ['192.168.110.20', ''], 'state': 'present'}) 2026-03-10 19:18:47.629963 | controller | changed: [instance] => (item={'names': ['net-only-0.utility', 'net-only-0.instance', 'net_only-0.utility', 'net_only-0.instance'], 'ips': ['192.168.140.30', ''], 'state': 'present'}) 2026-03-10 19:18:47.629973 | controller | changed: [instance] => (item={'names': ['net-only-0.ctlplane.local', 'net_only-0.ctlplane.local'], 'ips': ['192.168.140.30', ''], 'state': 'present'}) 2026-03-10 19:18:47.630005 | controller | changed: [instance] => (item={'names': ['net-only-0.public.local', 'net_only-0.public.local'], 'ips': ['192.168.110.30', ''], 'state': 'present'}) 2026-03-10 19:18:47.630552 | controller | changed: [instance] => (item={'names': ['no-boot-order-0.utility', 'no-boot-order-0.instance', 'no_boot_order-0.utility', 'no_boot_order-0.instance'], 'ips': ['192.168.140.40', ''], 'state': 'present'}) 2026-03-10 19:18:47.798919 | controller | changed: [instance] => (item={'names': ['no-boot-order-0.ctlplane.local', 'no_boot_order-0.ctlplane.local'], 'ips': ['192.168.140.40', ''], 'state': 'present'}) 2026-03-10 19:18:47.798973 | controller | changed: [instance] => (item={'names': ['no-boot-order-0.public.local', 'no_boot_order-0.public.local'], 'ips': ['192.168.110.40', ''], 'state': 'present'}) 2026-03-10 19:18:47.798987 | controller | 2026-03-10 19:18:47.798998 | controller | TASK [dnsmasq : Add controller-0 short hostname record when 'utility' in the name into /etc/hosts] *** 2026-03-10 19:18:47.799009 | controller | Tuesday 10 March 2026 19:18:47 +0000 (0:00:02.285) 0:00:46.518 ********* 2026-03-10 19:18:47.799026 | controller | skipping: [instance] => (item={'names': ['disk-first-0.utility', 'disk-first-0.instance', 'disk_first-0.utility', 'disk_first-0.instance'], 'ips': ['192.168.140.10', ''], 'state': 'present'}) 2026-03-10 19:18:47.799068 | controller | skipping: [instance] => (item={'names': ['disk-first-0.ctlplane.local', 'disk_first-0.ctlplane.local'], 'ips': ['192.168.140.10', ''], 'state': 'present'}) 2026-03-10 19:18:47.799079 | controller | skipping: [instance] => (item={'names': ['disk-first-0.public.local', 'disk_first-0.public.local'], 'ips': ['192.168.110.10', ''], 'state': 'present'}) 2026-03-10 19:18:47.799100 | controller | skipping: [instance] => (item={'names': ['net-first-0.utility', 'net-first-0.instance', 'net_first-0.utility', 'net_first-0.instance'], 'ips': ['192.168.140.20', ''], 'state': 'present'}) 2026-03-10 19:18:47.799111 | controller | skipping: [instance] => (item={'names': ['net-first-0.ctlplane.local', 'net_first-0.ctlplane.local'], 'ips': ['192.168.140.20', ''], 'state': 'present'}) 2026-03-10 19:18:47.799121 | controller | skipping: [instance] => (item={'names': ['net-first-0.public.local', 'net_first-0.public.local'], 'ips': ['192.168.110.20', ''], 'state': 'present'}) 2026-03-10 19:18:47.799130 | controller | skipping: [instance] => (item={'names': ['net-only-0.utility', 'net-only-0.instance', 'net_only-0.utility', 'net_only-0.instance'], 'ips': ['192.168.140.30', ''], 'state': 'present'}) 2026-03-10 19:18:47.799141 | controller | skipping: [instance] => (item={'names': ['net-only-0.ctlplane.local', 'net_only-0.ctlplane.local'], 'ips': ['192.168.140.30', ''], 'state': 'present'}) 2026-03-10 19:18:47.799151 | controller | skipping: [instance] => (item={'names': ['net-only-0.public.local', 'net_only-0.public.local'], 'ips': ['192.168.110.30', ''], 'state': 'present'}) 2026-03-10 19:18:47.799161 | controller | skipping: [instance] => (item={'names': ['no-boot-order-0.utility', 'no-boot-order-0.instance', 'no_boot_order-0.utility', 'no_boot_order-0.instance'], 'ips': ['192.168.140.40', ''], 'state': 'present'}) 2026-03-10 19:18:47.799171 | controller | skipping: [instance] => (item={'names': ['no-boot-order-0.ctlplane.local', 'no_boot_order-0.ctlplane.local'], 'ips': ['192.168.140.40', ''], 'state': 'present'}) 2026-03-10 19:18:47.799181 | controller | skipping: [instance] => (item={'names': ['no-boot-order-0.public.local', 'no_boot_order-0.public.local'], 'ips': ['192.168.110.40', ''], 'state': 'present'}) 2026-03-10 19:18:47.799190 | controller | skipping: [instance] 2026-03-10 19:18:47.799204 | controller | 2026-03-10 19:18:47.864194 | controller | TASK [libvirt_manager : Initialize empty _lm_dhcp_options fact] **************** 2026-03-10 19:18:47.864225 | controller | Tuesday 10 March 2026 19:18:47 +0000 (0:00:00.168) 0:00:46.686 ********* 2026-03-10 19:18:47.864239 | controller | ok: [instance] 2026-03-10 19:18:47.864301 | controller | 2026-03-10 19:18:47.864458 | controller | TASK [libvirt_manager : Collect DHCP options from VM definitions] ************** 2026-03-10 19:18:47.864649 | controller | Tuesday 10 March 2026 19:18:47 +0000 (0:00:00.066) 0:00:46.753 ********* 2026-03-10 19:18:47.928238 | controller | skipping: [instance] => (item=disk_first) 2026-03-10 19:18:47.928345 | controller | skipping: [instance] => (item=net_first) 2026-03-10 19:18:47.928445 | controller | skipping: [instance] => (item=net_only) 2026-03-10 19:18:47.928545 | controller | skipping: [instance] => (item=no_boot_order) 2026-03-10 19:18:47.928640 | controller | skipping: [instance] 2026-03-10 19:18:47.928734 | controller | 2026-03-10 19:18:47.928877 | controller | TASK [libvirt_manager : Generate DHCP option configuration for VM types] ******* 2026-03-10 19:18:47.928974 | controller | Tuesday 10 March 2026 19:18:47 +0000 (0:00:00.064) 0:00:46.817 ********* 2026-03-10 19:18:47.978563 | controller | skipping: [instance] 2026-03-10 19:18:48.434547 | controller | 2026-03-10 19:18:48.434583 | controller | TASK [libvirt_manager : Ensure some domains are local] ************************* 2026-03-10 19:18:48.434593 | controller | Tuesday 10 March 2026 19:18:47 +0000 (0:00:00.050) 0:00:46.867 ********* 2026-03-10 19:18:48.434606 | controller | changed: [instance] 2026-03-10 19:18:49.050211 | controller | 2026-03-10 19:18:49.050244 | controller | TASK [libvirt_manager : Ensure dnsmasq is reloaded now] ************************ 2026-03-10 19:18:49.050253 | controller | Tuesday 10 March 2026 19:18:48 +0000 (0:00:00.372) 0:00:47.240 ********* 2026-03-10 19:18:49.050258 | controller | 2026-03-10 19:18:49.050263 | controller | RUNNING HANDLER [libvirt_manager : Restart firewalld] ************************** 2026-03-10 19:18:49.050268 | controller | Tuesday 10 March 2026 19:18:48 +0000 (0:00:00.081) 0:00:47.322 ********* 2026-03-10 19:18:49.050278 | controller | changed: [instance] 2026-03-10 19:18:49.407445 | controller | 2026-03-10 19:18:49.407476 | controller | RUNNING HANDLER [dnsmasq : Restart dnsmasq] ************************************ 2026-03-10 19:18:49.407483 | controller | Tuesday 10 March 2026 19:18:49 +0000 (0:00:00.616) 0:00:47.938 ********* 2026-03-10 19:18:49.407501 | controller | changed: [instance] 2026-03-10 19:18:49.753838 | controller | 2026-03-10 19:18:49.753872 | controller | RUNNING HANDLER [dnsmasq : Reload dnsmasq] ************************************* 2026-03-10 19:18:49.753880 | controller | Tuesday 10 March 2026 19:18:49 +0000 (0:00:00.357) 0:00:48.295 ********* 2026-03-10 19:18:49.753891 | controller | changed: [instance] 2026-03-10 19:18:49.753970 | controller | 2026-03-10 19:18:49.753977 | controller | TASK [libvirt_manager : Create group inventories] ****************************** 2026-03-10 19:18:49.753984 | controller | Tuesday 10 March 2026 19:18:49 +0000 (0:00:00.346) 0:00:48.642 ********* 2026-03-10 19:18:51.156632 | controller | changed: [instance] => (item=disk_first) 2026-03-10 19:18:51.252525 | controller | changed: [instance] => (item=net_first) 2026-03-10 19:18:51.252556 | controller | changed: [instance] => (item=net_only) 2026-03-10 19:18:51.252564 | controller | changed: [instance] => (item=no_boot_order) 2026-03-10 19:18:51.252571 | controller | 2026-03-10 19:18:51.252578 | controller | TASK [libvirt_manager : Create "all" group inventory file] ********************* 2026-03-10 19:18:51.252585 | controller | Tuesday 10 March 2026 19:18:51 +0000 (0:00:01.402) 0:00:50.044 ********* 2026-03-10 19:18:51.252596 | 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:18:51.433437 | controller | 2026-03-10 19:18:51.433466 | controller | TASK [libvirt_manager : Check if all-group.yml already exists] ***************** 2026-03-10 19:18:51.433474 | controller | Tuesday 10 March 2026 19:18:51 +0000 (0:00:00.096) 0:00:50.141 ********* 2026-03-10 19:18:51.433484 | controller | ok: [instance] 2026-03-10 19:18:51.494875 | controller | 2026-03-10 19:18:51.494906 | controller | TASK [libvirt_manager : Slurp existing all-group.yml if it exists] ************* 2026-03-10 19:18:51.494922 | controller | Tuesday 10 March 2026 19:18:51 +0000 (0:00:00.180) 0:00:50.322 ********* 2026-03-10 19:18:51.494933 | controller | skipping: [instance] 2026-03-10 19:18:51.494952 | controller | 2026-03-10 19:18:51.494960 | controller | TASK [libvirt_manager : Create new "all" group inventory file from template] *** 2026-03-10 19:18:51.495034 | controller | Tuesday 10 March 2026 19:18:51 +0000 (0:00:00.061) 0:00:50.383 ********* 2026-03-10 19:18:51.875837 | controller | changed: [instance] 2026-03-10 19:18:51.875889 | controller | 2026-03-10 19:18:51.875910 | controller | TASK [libvirt_manager : Slurp newly created all-group.yml] ********************* 2026-03-10 19:18:51.875933 | controller | Tuesday 10 March 2026 19:18:51 +0000 (0:00:00.380) 0:00:50.764 ********* 2026-03-10 19:18:51.916503 | controller | skipping: [instance] 2026-03-10 19:18:51.916545 | controller | 2026-03-10 19:18:51.916616 | controller | TASK [libvirt_manager : Write merged all-group.yml] **************************** 2026-03-10 19:18:51.916701 | controller | Tuesday 10 March 2026 19:18:51 +0000 (0:00:00.040) 0:00:50.805 ********* 2026-03-10 19:18:51.954336 | controller | skipping: [instance] 2026-03-10 19:18:51.954417 | controller | 2026-03-10 19:18:51.954508 | controller | TASK [libvirt_manager : Ensure storage pool is present.] *********************** 2026-03-10 19:18:51.954603 | controller | Tuesday 10 March 2026 19:18:51 +0000 (0:00:00.037) 0:00:50.843 ********* 2026-03-10 19:18:52.010527 | controller | skipping: [instance] 2026-03-10 19:18:52.010613 | controller | 2026-03-10 19:18:52.010710 | controller | TASK [libvirt_manager : Ensure images are present] ***************************** 2026-03-10 19:18:52.010877 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.056) 0:00:50.899 ********* 2026-03-10 19:18:52.179003 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/get_image.yml for instance => (item=disk_first) 2026-03-10 19:18:52.217788 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/get_image.yml for instance => (item=net_first) 2026-03-10 19:18:52.217804 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/get_image.yml for instance => (item=net_only) 2026-03-10 19:18:52.217820 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/get_image.yml for instance => (item=no_boot_order) 2026-03-10 19:18:52.217831 | controller | 2026-03-10 19:18:52.217837 | controller | TASK [libvirt_manager : Ensure directory exists] ******************************* 2026-03-10 19:18:52.217843 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.167) 0:00:51.067 ********* 2026-03-10 19:18:52.217850 | controller | skipping: [instance] 2026-03-10 19:18:52.255535 | controller | 2026-03-10 19:18:52.255555 | controller | TASK [libvirt_manager : Check if base image exists] **************************** 2026-03-10 19:18:52.255563 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.039) 0:00:51.106 ********* 2026-03-10 19:18:52.255572 | controller | skipping: [instance] 2026-03-10 19:18:52.292314 | controller | 2026-03-10 19:18:52.292344 | controller | TASK [libvirt_manager : Download base image] *********************************** 2026-03-10 19:18:52.292356 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.037) 0:00:51.144 ********* 2026-03-10 19:18:52.292371 | controller | skipping: [instance] 2026-03-10 19:18:52.329937 | controller | 2026-03-10 19:18:52.329969 | controller | TASK [libvirt_manager : Check image] ******************************************* 2026-03-10 19:18:52.329982 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.036) 0:00:51.180 ********* 2026-03-10 19:18:52.329998 | controller | skipping: [instance] 2026-03-10 19:18:52.330237 | controller | 2026-03-10 19:18:52.330471 | controller | TASK [libvirt_manager : Assert image status] *********************************** 2026-03-10 19:18:52.330697 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.038) 0:00:51.218 ********* 2026-03-10 19:18:52.399314 | controller | skipping: [instance] 2026-03-10 19:18:52.399472 | controller | 2026-03-10 19:18:52.399618 | controller | TASK [libvirt_manager : Ensure image access rights] **************************** 2026-03-10 19:18:52.399790 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.069) 0:00:51.288 ********* 2026-03-10 19:18:52.438350 | controller | skipping: [instance] 2026-03-10 19:18:52.438481 | controller | 2026-03-10 19:18:52.438601 | controller | TASK [libvirt_manager : Ensure directory exists] ******************************* 2026-03-10 19:18:52.438737 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.039) 0:00:51.327 ********* 2026-03-10 19:18:52.476800 | controller | skipping: [instance] 2026-03-10 19:18:52.477082 | controller | 2026-03-10 19:18:52.477223 | controller | TASK [libvirt_manager : Check if base image exists] **************************** 2026-03-10 19:18:52.477338 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.038) 0:00:51.365 ********* 2026-03-10 19:18:52.514698 | controller | skipping: [instance] 2026-03-10 19:18:52.514907 | controller | 2026-03-10 19:18:52.515065 | controller | TASK [libvirt_manager : Download base image] *********************************** 2026-03-10 19:18:52.515197 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.038) 0:00:51.403 ********* 2026-03-10 19:18:52.552775 | controller | skipping: [instance] 2026-03-10 19:18:52.552950 | controller | 2026-03-10 19:18:52.553099 | controller | TASK [libvirt_manager : Check image] ******************************************* 2026-03-10 19:18:52.553241 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.038) 0:00:51.441 ********* 2026-03-10 19:18:52.591378 | controller | skipping: [instance] 2026-03-10 19:18:52.591549 | controller | 2026-03-10 19:18:52.591707 | controller | TASK [libvirt_manager : Assert image status] *********************************** 2026-03-10 19:18:52.591926 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.038) 0:00:51.480 ********* 2026-03-10 19:18:52.670827 | controller | skipping: [instance] 2026-03-10 19:18:52.670992 | controller | 2026-03-10 19:18:52.671140 | controller | TASK [libvirt_manager : Ensure image access rights] **************************** 2026-03-10 19:18:52.671287 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.079) 0:00:51.559 ********* 2026-03-10 19:18:52.711342 | controller | skipping: [instance] 2026-03-10 19:18:52.751048 | controller | 2026-03-10 19:18:52.751068 | controller | TASK [libvirt_manager : Ensure directory exists] ******************************* 2026-03-10 19:18:52.751076 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.040) 0:00:51.599 ********* 2026-03-10 19:18:52.751086 | controller | skipping: [instance] 2026-03-10 19:18:52.790041 | controller | 2026-03-10 19:18:52.790062 | controller | TASK [libvirt_manager : Check if base image exists] **************************** 2026-03-10 19:18:52.790070 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.039) 0:00:51.639 ********* 2026-03-10 19:18:52.790085 | controller | skipping: [instance] 2026-03-10 19:18:52.828835 | controller | 2026-03-10 19:18:52.828870 | controller | TASK [libvirt_manager : Download base image] *********************************** 2026-03-10 19:18:52.828880 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.038) 0:00:51.678 ********* 2026-03-10 19:18:52.828892 | controller | skipping: [instance] 2026-03-10 19:18:52.828925 | controller | 2026-03-10 19:18:52.829108 | controller | TASK [libvirt_manager : Check image] ******************************************* 2026-03-10 19:18:52.866837 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.038) 0:00:51.717 ********* 2026-03-10 19:18:52.866871 | controller | skipping: [instance] 2026-03-10 19:18:52.867099 | controller | 2026-03-10 19:18:52.867122 | controller | TASK [libvirt_manager : Assert image status] *********************************** 2026-03-10 19:18:52.938277 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.038) 0:00:51.755 ********* 2026-03-10 19:18:52.938296 | controller | skipping: [instance] 2026-03-10 19:18:52.978433 | controller | 2026-03-10 19:18:52.978453 | controller | TASK [libvirt_manager : Ensure image access rights] **************************** 2026-03-10 19:18:52.978462 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.070) 0:00:51.826 ********* 2026-03-10 19:18:52.978471 | controller | skipping: [instance] 2026-03-10 19:18:53.016125 | controller | 2026-03-10 19:18:53.016145 | controller | TASK [libvirt_manager : Ensure directory exists] ******************************* 2026-03-10 19:18:53.016153 | controller | Tuesday 10 March 2026 19:18:52 +0000 (0:00:00.040) 0:00:51.866 ********* 2026-03-10 19:18:53.016163 | controller | skipping: [instance] 2026-03-10 19:18:53.016528 | controller | 2026-03-10 19:18:53.053915 | controller | TASK [libvirt_manager : Check if base image exists] **************************** 2026-03-10 19:18:53.053935 | controller | Tuesday 10 March 2026 19:18:53 +0000 (0:00:00.038) 0:00:51.905 ********* 2026-03-10 19:18:53.053946 | controller | skipping: [instance] 2026-03-10 19:18:53.091054 | controller | 2026-03-10 19:18:53.091074 | controller | TASK [libvirt_manager : Download base image] *********************************** 2026-03-10 19:18:53.091082 | controller | Tuesday 10 March 2026 19:18:53 +0000 (0:00:00.037) 0:00:51.942 ********* 2026-03-10 19:18:53.091097 | controller | skipping: [instance] 2026-03-10 19:18:53.091244 | controller | 2026-03-10 19:18:53.091367 | controller | TASK [libvirt_manager : Check image] ******************************************* 2026-03-10 19:18:53.091484 | controller | Tuesday 10 March 2026 19:18:53 +0000 (0:00:00.037) 0:00:51.980 ********* 2026-03-10 19:18:53.132200 | controller | skipping: [instance] 2026-03-10 19:18:53.132358 | controller | 2026-03-10 19:18:53.132506 | controller | TASK [libvirt_manager : Assert image status] *********************************** 2026-03-10 19:18:53.132630 | controller | Tuesday 10 March 2026 19:18:53 +0000 (0:00:00.041) 0:00:52.021 ********* 2026-03-10 19:18:53.215066 | controller | skipping: [instance] 2026-03-10 19:18:53.215243 | controller | 2026-03-10 19:18:53.215395 | controller | TASK [libvirt_manager : Ensure image access rights] **************************** 2026-03-10 19:18:53.215531 | controller | Tuesday 10 March 2026 19:18:53 +0000 (0:00:00.082) 0:00:52.103 ********* 2026-03-10 19:18:53.256487 | controller | skipping: [instance] 2026-03-10 19:18:53.256647 | controller | 2026-03-10 19:18:53.256868 | controller | TASK [libvirt_manager : Get ssh key state] ************************************* 2026-03-10 19:18:53.257034 | controller | Tuesday 10 March 2026 19:18:53 +0000 (0:00:00.041) 0:00:52.145 ********* 2026-03-10 19:18:53.442898 | controller | ok: [instance] 2026-03-10 19:18:53.837834 | controller | 2026-03-10 19:18:53.837873 | controller | TASK [libvirt_manager : Create temporary ssh keypair] ************************** 2026-03-10 19:18:53.837884 | controller | Tuesday 10 March 2026 19:18:53 +0000 (0:00:00.186) 0:00:52.331 ********* 2026-03-10 19:18:53.837898 | controller | changed: [instance] 2026-03-10 19:18:53.837930 | controller | 2026-03-10 19:18:53.838170 | controller | TASK [libvirt_manager : Slurp public key for later use] ************************ 2026-03-10 19:18:54.154828 | controller | Tuesday 10 March 2026 19:18:53 +0000 (0:00:00.394) 0:00:52.726 ********* 2026-03-10 19:18:54.154866 | controller | ok: [instance] 2026-03-10 19:18:54.377170 | controller | 2026-03-10 19:18:54.377203 | controller | TASK [libvirt_manager : Slurp private key for later use] *********************** 2026-03-10 19:18:54.377216 | controller | Tuesday 10 March 2026 19:18:54 +0000 (0:00:00.316) 0:00:53.042 ********* 2026-03-10 19:18:54.377226 | controller | ok: [instance] 2026-03-10 19:18:54.739337 | controller | 2026-03-10 19:18:54.739366 | controller | TASK [libvirt_manager : Inject cifmw_reproducer_key.pub in hypervisor authorized_keys] *** 2026-03-10 19:18:54.739374 | controller | Tuesday 10 March 2026 19:18:54 +0000 (0:00:00.222) 0:00:53.265 ********* 2026-03-10 19:18:54.739384 | controller | changed: [instance] 2026-03-10 19:18:54.779192 | controller | 2026-03-10 19:18:54.779213 | controller | TASK [libvirt_manager : Create fact holding network data for VMs] ************** 2026-03-10 19:18:54.779220 | controller | Tuesday 10 March 2026 19:18:54 +0000 (0:00:00.362) 0:00:53.627 ********* 2026-03-10 19:18:54.779228 | controller | ok: [instance] 2026-03-10 19:18:55.017249 | controller | 2026-03-10 19:18:55.017274 | controller | TASK [libvirt_manager : List existing networks] ******************************** 2026-03-10 19:18:55.017281 | controller | Tuesday 10 March 2026 19:18:54 +0000 (0:00:00.040) 0:00:53.667 ********* 2026-03-10 19:18:55.017290 | controller | ok: [instance] 2026-03-10 19:18:55.456006 | controller | 2026-03-10 19:18:55.456035 | controller | TASK [libvirt_manager : Gather pool fact] ************************************** 2026-03-10 19:18:55.456043 | controller | Tuesday 10 March 2026 19:18:55 +0000 (0:00:00.237) 0:00:53.905 ********* 2026-03-10 19:18:55.456054 | controller | ok: [instance] 2026-03-10 19:18:55.638552 | controller | 2026-03-10 19:18:55.638575 | controller | TASK [libvirt_manager : Create and run VMs] ************************************ 2026-03-10 19:18:55.638582 | controller | Tuesday 10 March 2026 19:18:55 +0000 (0:00:00.438) 0:00:54.344 ********* 2026-03-10 19:18:55.638592 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/create_vms.yml for instance => (item={'key': 'disk-first-0', 'value': 'disk_first'}) 2026-03-10 19:18:55.898205 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/create_vms.yml for instance => (item={'key': 'net-first-0', 'value': 'net_first'}) 2026-03-10 19:18:55.898234 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/create_vms.yml for instance => (item={'key': 'net-only-0', 'value': 'net_only'}) 2026-03-10 19:18:55.898242 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/create_vms.yml for instance => (item={'key': 'no-boot-order-0', 'value': 'no_boot_order'}) 2026-03-10 19:18:55.898247 | controller | 2026-03-10 19:18:55.898254 | controller | TASK [libvirt_manager : Create VM image for disk-first-0] ********************** 2026-03-10 19:18:55.898259 | controller | Tuesday 10 March 2026 19:18:55 +0000 (0:00:00.182) 0:00:54.526 ********* 2026-03-10 19:18:55.898269 | controller | changed: [instance] 2026-03-10 19:18:56.145833 | controller | 2026-03-10 19:18:56.145865 | controller | TASK [libvirt_manager : Ensure file ownership and rights for disk-first-0] ***** 2026-03-10 19:18:56.145882 | controller | Tuesday 10 March 2026 19:18:55 +0000 (0:00:00.260) 0:00:54.786 ********* 2026-03-10 19:18:56.145893 | controller | changed: [instance] 2026-03-10 19:18:56.145911 | controller | 2026-03-10 19:18:56.145919 | controller | TASK [libvirt_manager : Define VMs for type disk-first-0] ********************** 2026-03-10 19:18:56.146036 | controller | Tuesday 10 March 2026 19:18:56 +0000 (0:00:00.247) 0:00:55.034 ********* 2026-03-10 19:18:56.245729 | controller | fatal: [instance]: FAILED! => 2026-03-10 19:18:56.247758 | controller | msg: |- 2026-03-10 19:18:56.247789 | 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:18:56.247798 | controller | {{ vm | ansible.builtin.to_uuid }} 2026-03-10 19:18:56.247804 | controller | cifmw-{{ vm }} 2026-03-10 19:18:56.247821 | controller | {{ vm_data.memory | default(2) }} 2026-03-10 19:18:56.247831 | controller | {{ vm_data.cpus | default(2) }} 2026-03-10 19:18:56.247857 | controller | {% if vm_data.uefi | default(false) | bool %} 2026-03-10 19:18:56.247875 | controller | 2026-03-10 19:18:56.247881 | controller | hvm 2026-03-10 19:18:56.247887 | controller | 2026-03-10 19:18:56.247892 | controller | 2026-03-10 19:18:56.247898 | controller | 2026-03-10 19:18:56.247903 | controller | {% else %} 2026-03-10 19:18:56.247909 | controller | 2026-03-10 19:18:56.247914 | controller | hvm 2026-03-10 19:18:56.247919 | controller | {% endif %} 2026-03-10 19:18:56.247925 | controller | 2026-03-10 19:18:56.247930 | controller | 2026-03-10 19:18:56.247935 | controller | 2026-03-10 19:18:56.247941 | controller | 2026-03-10 19:18:56.247946 | controller | 2026-03-10 19:18:56.247951 | controller | 2026-03-10 19:18:56.247956 | controller | 2026-03-10 19:18:56.247962 | controller | 2026-03-10 19:18:56.247967 | controller | 2026-03-10 19:18:56.247973 | controller | 2026-03-10 19:18:56.247978 | controller | 2026-03-10 19:18:56.247983 | controller | 2026-03-10 19:18:56.247988 | controller | 2026-03-10 19:18:56.247999 | controller | 2026-03-10 19:18:56.248093 | controller | /usr/libexec/qemu-kvm 2026-03-10 19:18:56.248101 | controller | 2026-03-10 19:18:56.248112 | controller | 2026-03-10 19:18:56.248202 | controller | 2026-03-10 19:18:56.248306 | controller | {% set disk_bus = vm_data.disk_bus | default('scsi') %} 2026-03-10 19:18:56.248409 | controller | {% if disk_bus == 'scsi' %} 2026-03-10 19:18:56.248505 | controller | 2026-03-10 19:18:56.248612 | controller | {% else %} 2026-03-10 19:18:56.248708 | controller | 2026-03-10 19:18:56.248837 | controller | {% endif %} 2026-03-10 19:18:56.248950 | controller | 2026-03-10 19:18:56.249057 | controller | {% set extra_disks_bus = vm_data.extra_disks_bus | default('virtio') %} 2026-03-10 19:18:56.249163 | controller | {% if disk_bus == 'scsi' or extra_disks_bus == 'scsi' %} 2026-03-10 19:18:56.249263 | controller | 2026-03-10 19:18:56.249363 | controller | 2026-03-10 19:18:56.249464 | controller |
2026-03-10 19:18:56.249564 | controller | 2026-03-10 19:18:56.249663 | controller | {% endif %} 2026-03-10 19:18:56.249789 | controller | 2026-03-10 19:18:56.249906 | controller | 2026-03-10 19:18:56.250008 | controller |
2026-03-10 19:18:56.250112 | controller | 2026-03-10 19:18:56.250212 | controller | 2026-03-10 19:18:56.250310 | controller | 2026-03-10 19:18:56.250408 | controller | 2026-03-10 19:18:56.250510 | controller |
2026-03-10 19:18:56.250609 | controller | 2026-03-10 19:18:56.250708 | controller | 2026-03-10 19:18:56.250845 | controller | 2026-03-10 19:18:56.250953 | controller | 2026-03-10 19:18:56.251054 | controller |
2026-03-10 19:18:56.251153 | controller | 2026-03-10 19:18:56.251252 | controller | 2026-03-10 19:18:56.251349 | controller | 2026-03-10 19:18:56.251448 | controller | 2026-03-10 19:18:56.251548 | controller |
2026-03-10 19:18:56.251645 | controller | 2026-03-10 19:18:56.251770 | controller | 2026-03-10 19:18:56.251906 | controller | 2026-03-10 19:18:56.252006 | controller | 2026-03-10 19:18:56.252107 | controller | 2026-03-10 19:18:56.252206 | controller | 2026-03-10 19:18:56.252305 | controller |
2026-03-10 19:18:56.252402 | controller | 2026-03-10 19:18:56.252499 | controller | 2026-03-10 19:18:56.252599 | controller | 2026-03-10 19:18:56.252699 | controller | 2026-03-10 19:18:56.252826 | controller | 2026-03-10 19:18:56.252936 | controller | 2026-03-10 19:18:56.253035 | controller | 2026-03-10 19:18:56.253133 | controller | 2026-03-10 19:18:56.253230 | controller | 2026-03-10 19:18:56.253328 | controller | 2026-03-10 19:18:56.253428 | controller | 2026-03-10 19:18:56.253527 | controller | 2026-03-10 19:18:56.253626 | controller | 2026-03-10 19:18:56.253738 | controller | 2026-03-10 19:18:56.253864 | controller | 2026-03-10 19:18:56.253963 | controller | 2026-03-10 19:18:56.254060 | controller | 2026-03-10 19:18:56.254159 | controller |
2026-03-10 19:18:56.254258 | controller | 2026-03-10 19:18:56.254356 | controller | 2026-03-10 19:18:56.254453 | controller | 2026-03-10 19:18:56.254550 | controller | 2026-03-10 19:18:56.254648 | controller | 2026-03-10 19:18:56.254762 | controller | 2026-03-10 19:18:56.254881 | controller | 2026-03-10 19:18:56.254977 | controller |