2025-12-01 08:18:25.723560 | Job console starting...
2025-12-01 08:18:25.946744 | Updating repositories
2025-12-01 08:18:26.675583 | Preparing job workspace
2025-12-01 08:18:33.961155 | Running Ansible setup...
2025-12-01 08:18:37.024129 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-01 08:18:37.593336 | 
2025-12-01 08:18:37.593488 | PLAY [localhost]
2025-12-01 08:18:37.602688 | 
2025-12-01 08:18:37.602791 | TASK [Gathering Facts]
2025-12-01 08:18:38.482381 | localhost | ok
2025-12-01 08:18:38.495711 | 
2025-12-01 08:18:38.495794 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-01 08:18:38.856211 | localhost -> localhost | changed
2025-12-01 08:18:38.861906 | 
2025-12-01 08:18:38.861981 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-01 08:18:39.628518 | localhost -> localhost | changed
2025-12-01 08:18:39.646498 | 
2025-12-01 08:18:39.646592 | TASK [Setup log path fact]
2025-12-01 08:18:39.669744 | localhost | ok
2025-12-01 08:18:39.684733 | 
2025-12-01 08:18:39.684808 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-01 08:18:39.712737 | localhost | ok
2025-12-01 08:18:39.720231 | 
2025-12-01 08:18:39.720302 | TASK [emit-job-header : Print job information]
2025-12-01 08:18:39.759273 | # Job Information
2025-12-01 08:18:39.759413 | Ansible Version: 2.15.12
2025-12-01 08:18:39.759443 | Job: cifmw-molecule-libvirt_manager
2025-12-01 08:18:39.759465 | Pipeline: github-check
2025-12-01 08:18:39.759486 | Executor: ibm-bm3-ze.softwarefactory-project.io
2025-12-01 08:18:39.759505 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3505
2025-12-01 08:18:39.759525 | Log URL (when completed): https://logserver.rdoproject.org/d58/rdoproject.org/d589bf7cb9a34c958883eab0bbbbee0b/
2025-12-01 08:18:39.759544 | Event ID: 2cb40ac0-ce8e-11f0-9e93-bda27212598c
2025-12-01 08:18:39.763174 | 
2025-12-01 08:18:39.763244 | LOOP [emit-job-header : Print node information]
2025-12-01 08:18:39.858630 | localhost | ok:
2025-12-01 08:18:39.859070 | localhost | # Node Information
2025-12-01 08:18:39.859113 | localhost | Inventory Hostname: controller
2025-12-01 08:18:39.859155 | localhost | Hostname: np0005540718
2025-12-01 08:18:39.859178 | localhost | Username: zuul
2025-12-01 08:18:39.859199 | localhost | Distro: CentOS 9
2025-12-01 08:18:39.859218 | localhost | Provider: ibm-bm3-nodepool
2025-12-01 08:18:39.859236 | localhost | Region: regionOne
2025-12-01 08:18:39.859253 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-12-01 08:18:39.859269 | localhost | Product Name: OpenStack Compute
2025-12-01 08:18:39.859284 | localhost | Interface IP: 192.168.25.82
2025-12-01 08:18:39.888983 | 
2025-12-01 08:18:39.889062 | PLAY [all]
2025-12-01 08:18:39.895088 | 
2025-12-01 08:18:39.895157 | TASK [Gather network facts]
2025-12-01 08:18:40.279600 | controller | ok
2025-12-01 08:18:40.293959 | 
2025-12-01 08:18:40.294041 | TASK [include_role : start-zuul-console]
2025-12-01 08:18:40.313894 | controller | ok
2025-12-01 08:18:40.325487 | 
2025-12-01 08:18:40.325589 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-01 08:18:40.679432 | controller | ok
2025-12-01 08:18:40.687982 | 
2025-12-01 08:18:40.688044 | TASK [include_role : add-build-sshkey]
2025-12-01 08:18:40.718028 | controller | ok
2025-12-01 08:18:40.730572 | 
2025-12-01 08:18:40.730641 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-01 08:18:40.948205 | controller -> localhost | ok
2025-12-01 08:18:40.953850 | 
2025-12-01 08:18:40.953909 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-01 08:18:40.984744 | controller | ok
2025-12-01 08:18:40.997736 | controller | included: /var/lib/zuul/builds/d589bf7cb9a34c958883eab0bbbbee0b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-01 08:18:41.003443 | 
2025-12-01 08:18:41.003508 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-01 08:18:41.484281 | controller -> localhost | Generating public/private rsa key pair.
2025-12-01 08:18:41.484508 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/d589bf7cb9a34c958883eab0bbbbee0b/work/d589bf7cb9a34c958883eab0bbbbee0b_id_rsa.
2025-12-01 08:18:41.484542 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/d589bf7cb9a34c958883eab0bbbbee0b/work/d589bf7cb9a34c958883eab0bbbbee0b_id_rsa.pub.
2025-12-01 08:18:41.484566 | controller -> localhost | The key fingerprint is:
2025-12-01 08:18:41.484588 | controller -> localhost | SHA256:fk9B+Du7aKAFf+w4pGYMi0loLQuJlqC/W/BtfzlS9w8 zuul-build-sshkey
2025-12-01 08:18:41.484609 | controller -> localhost | The key's randomart image is:
2025-12-01 08:18:41.484628 | controller -> localhost | +---[RSA 3072]----+
2025-12-01 08:18:41.484648 | controller -> localhost | |                 |
2025-12-01 08:18:41.484669 | controller -> localhost | |           .     |
2025-12-01 08:18:41.484688 | controller -> localhost | |          . .    |
2025-12-01 08:18:41.484730 | controller -> localhost | |.     .    o     |
2025-12-01 08:18:41.484752 | controller -> localhost | |+o+    oS.  o    |
2025-12-01 08:18:41.484772 | controller -> localhost | |*=.+.. .= + .o   |
2025-12-01 08:18:41.484791 | controller -> localhost | |+oooo+o=.*.o+.E  |
2025-12-01 08:18:41.484812 | controller -> localhost | | .+...*.+.*+ o.. |
2025-12-01 08:18:41.484831 | controller -> localhost | |  oo o  .=..+. ..|
2025-12-01 08:18:41.484849 | controller -> localhost | +----[SHA256]-----+
2025-12-01 08:18:41.484895 | controller -> localhost | ok: Runtime: 0:00:00.111828
2025-12-01 08:18:41.490712 | 
2025-12-01 08:18:41.490782 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-01 08:18:41.519992 | controller | ok
2025-12-01 08:18:41.529636 | controller | included: /var/lib/zuul/builds/d589bf7cb9a34c958883eab0bbbbee0b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-01 08:18:41.537657 | 
2025-12-01 08:18:41.537741 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-01 08:18:41.552185 | controller | skipping: Conditional result was False
2025-12-01 08:18:41.557861 | 
2025-12-01 08:18:41.557928 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-01 08:18:41.922290 | controller | changed
2025-12-01 08:18:41.927164 | 
2025-12-01 08:18:41.927268 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-01 08:18:42.120136 | controller | ok
2025-12-01 08:18:42.125149 | 
2025-12-01 08:18:42.125217 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-01 08:18:42.720228 | controller | changed
2025-12-01 08:18:42.725616 | 
2025-12-01 08:18:42.725728 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-01 08:18:43.278458 | controller | changed
2025-12-01 08:18:43.283451 | 
2025-12-01 08:18:43.283517 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-01 08:18:43.307547 | controller | skipping: Conditional result was False
2025-12-01 08:18:43.315035 | 
2025-12-01 08:18:43.315106 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-01 08:18:43.665269 | controller -> localhost | changed
2025-12-01 08:18:43.675433 | 
2025-12-01 08:18:43.675526 | TASK [add-build-sshkey : Add back temp key]
2025-12-01 08:18:43.912865 | controller -> localhost | Identity added: /var/lib/zuul/builds/d589bf7cb9a34c958883eab0bbbbee0b/work/d589bf7cb9a34c958883eab0bbbbee0b_id_rsa (zuul-build-sshkey)
2025-12-01 08:18:43.913093 | controller -> localhost | ok: Runtime: 0:00:00.009399
2025-12-01 08:18:43.919219 | 
2025-12-01 08:18:43.919290 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-01 08:18:44.236040 | controller | ok
2025-12-01 08:18:44.240574 | 
2025-12-01 08:18:44.240639 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-01 08:18:44.266130 | controller | skipping: Conditional result was False
2025-12-01 08:18:44.275089 | 
2025-12-01 08:18:44.275166 | TASK [include_role : validate-host]
2025-12-01 08:18:44.294442 | controller | ok
2025-12-01 08:18:44.313596 | 
2025-12-01 08:18:44.313664 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-01 08:18:44.342225 | controller | ok
2025-12-01 08:18:44.346522 | 
2025-12-01 08:18:44.346584 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-01 08:18:44.544568 | controller -> localhost | ok
2025-12-01 08:18:44.550080 | 
2025-12-01 08:18:44.550155 | TASK [validate-host : Collect information about the host]
2025-12-01 08:18:45.183218 | controller | ok
2025-12-01 08:18:45.191428 | 
2025-12-01 08:18:45.191508 | TASK [validate-host : Sanitize hostname]
2025-12-01 08:18:45.271965 | controller | ok
2025-12-01 08:18:45.298274 | 
2025-12-01 08:18:45.298353 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-01 08:18:45.698063 | controller -> localhost | changed
2025-12-01 08:18:45.704350 | 
2025-12-01 08:18:45.704441 | TASK [validate-host : Collect information about zuul worker]
2025-12-01 08:18:46.057301 | controller | ok
2025-12-01 08:18:46.062057 | 
2025-12-01 08:18:46.062134 | TASK [validate-host : Write out all zuul information for each host]
2025-12-01 08:18:46.463178 | controller -> localhost | changed
2025-12-01 08:18:46.472775 | 
2025-12-01 08:18:46.472873 | TASK [include_role : prepare-workspace-openshift]
2025-12-01 08:18:46.487359 | controller | skipping: Conditional result was False
2025-12-01 08:18:46.493033 | 
2025-12-01 08:18:46.493102 | TASK [include_role : remove-zuul-sshkey]
2025-12-01 08:18:46.507721 | controller | skipping: Conditional result was False
2025-12-01 08:18:46.513147 | 
2025-12-01 08:18:46.513214 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-01 08:18:46.716211 | controller | ok: "logs"
2025-12-01 08:18:46.716450 | controller | ok: All items complete
2025-12-01 08:18:46.716480 | 
2025-12-01 08:18:46.878381 | controller | ok: "artifacts"
2025-12-01 08:18:47.042347 | controller | ok: "docs"
2025-12-01 08:18:47.047787 | 
2025-12-01 08:18:47.047859 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-01 08:18:47.247131 | controller | changed: "logs"
2025-12-01 08:18:47.433344 | controller | changed: "artifacts"
2025-12-01 08:18:47.616226 | controller | changed: "docs"
2025-12-01 08:18:47.642799 | 
2025-12-01 08:18:47.642862 | PLAY RECAP
2025-12-01 08:18:47.642908 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-01 08:18:47.642937 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-01 08:18:47.642957 | 
2025-12-01 08:18:47.727458 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-01 08:18:47.728301 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-01 08:18:48.266610 | 
2025-12-01 08:18:48.266772 | PLAY [all]
2025-12-01 08:18:48.286196 | 
2025-12-01 08:18:48.286270 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-01 08:18:48.346492 | controller | ok
2025-12-01 08:18:48.351323 | 
2025-12-01 08:18:48.351400 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-01 08:18:48.685056 | controller | changed
2025-12-01 08:18:48.690636 | 
2025-12-01 08:18:48.690738 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-01 08:18:49.551618 | controller | changed
2025-12-01 08:18:49.561749 | 
2025-12-01 08:18:49.561824 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-01 08:18:49.924290 | controller | changed:
2025-12-01 08:18:49.924818 | controller | {
2025-12-01 08:18:49.924851 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-01 08:18:49.924888 | controller | }
2025-12-01 08:18:50.131609 | controller | changed:
2025-12-01 08:18:50.131768 | controller | {
2025-12-01 08:18:50.131799 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-01 08:18:50.131821 | controller | }
2025-12-01 08:18:50.336668 | controller | changed:
2025-12-01 08:18:50.336818 | controller | {
2025-12-01 08:18:50.336854 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-12-01 08:18:50.336882 | controller | }
2025-12-01 08:18:50.550842 | controller | changed:
2025-12-01 08:18:50.550912 | controller | {
2025-12-01 08:18:50.550949 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-01 08:18:50.550979 | controller | }
2025-12-01 08:18:50.759992 | controller | changed:
2025-12-01 08:18:50.760056 | controller | {
2025-12-01 08:18:50.760082 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-01 08:18:50.760102 | controller | }
2025-12-01 08:18:50.966425 | controller | changed:
2025-12-01 08:18:50.966489 | controller | {
2025-12-01 08:18:50.966517 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-01 08:18:50.966538 | controller | }
2025-12-01 08:18:51.197512 | controller | changed:
2025-12-01 08:18:51.200732 | controller | {
2025-12-01 08:18:51.200778 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-12-01 08:18:51.200803 | controller | }
2025-12-01 08:18:51.396768 | controller | changed:
2025-12-01 08:18:51.396854 | controller | {
2025-12-01 08:18:51.396882 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-12-01 08:18:51.396903 | controller | }
2025-12-01 08:18:51.604203 | controller | changed:
2025-12-01 08:18:51.604263 | controller | {
2025-12-01 08:18:51.604291 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-01 08:18:51.604313 | controller | }
2025-12-01 08:18:51.809130 | controller | changed:
2025-12-01 08:18:51.809244 | controller | {
2025-12-01 08:18:51.809274 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-01 08:18:51.809295 | controller | }
2025-12-01 08:18:52.016943 | controller | changed:
2025-12-01 08:18:52.016996 | controller | {
2025-12-01 08:18:52.017022 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-01 08:18:52.017043 | controller | }
2025-12-01 08:18:52.254035 | controller | changed:
2025-12-01 08:18:52.254113 | controller | {
2025-12-01 08:18:52.254152 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-01 08:18:52.254173 | controller | }
2025-12-01 08:18:52.437824 | controller | changed:
2025-12-01 08:18:52.437887 | controller | {
2025-12-01 08:18:52.437915 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-01 08:18:52.437936 | controller | }
2025-12-01 08:18:52.637163 | controller | changed:
2025-12-01 08:18:52.637214 | controller | {
2025-12-01 08:18:52.637241 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-01 08:18:52.637260 | controller | }
2025-12-01 08:18:52.847549 | controller | changed:
2025-12-01 08:18:52.847683 | controller | {
2025-12-01 08:18:52.847731 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-01 08:18:52.847755 | controller | }
2025-12-01 08:18:53.078075 | controller | changed:
2025-12-01 08:18:53.078217 | controller | {
2025-12-01 08:18:53.078244 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-01 08:18:53.078264 | controller | }
2025-12-01 08:18:53.285322 | controller | changed:
2025-12-01 08:18:53.285403 | controller | {
2025-12-01 08:18:53.285432 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-12-01 08:18:53.285453 | controller | }
2025-12-01 08:18:53.486799 | controller | changed:
2025-12-01 08:18:53.486876 | controller | {
2025-12-01 08:18:53.486902 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-12-01 08:18:53.486931 | controller | }
2025-12-01 08:18:53.690649 | controller | changed:
2025-12-01 08:18:53.690756 | controller | {
2025-12-01 08:18:53.690786 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-01 08:18:53.690806 | controller | }
2025-12-01 08:18:53.889361 | controller | changed:
2025-12-01 08:18:53.889424 | controller | {
2025-12-01 08:18:53.889450 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-01 08:18:53.889470 | controller | }
2025-12-01 08:18:54.090795 | controller | changed:
2025-12-01 08:18:54.090856 | controller | {
2025-12-01 08:18:54.090882 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-01 08:18:54.090903 | controller | }
2025-12-01 08:18:54.291648 | controller | changed:
2025-12-01 08:18:54.291805 | controller | {
2025-12-01 08:18:54.291840 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-01 08:18:54.291862 | controller | }
2025-12-01 08:18:54.492677 | controller | changed:
2025-12-01 08:18:54.492758 | controller | {
2025-12-01 08:18:54.492784 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-12-01 08:18:54.492805 | controller | }
2025-12-01 08:18:54.691129 | controller | changed:
2025-12-01 08:18:54.691198 | controller | {
2025-12-01 08:18:54.691225 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-01 08:18:54.691245 | controller | }
2025-12-01 08:18:54.899063 | controller | changed:
2025-12-01 08:18:54.899277 | controller | {
2025-12-01 08:18:54.899315 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-01 08:18:54.899338 | controller | }
2025-12-01 08:18:55.105032 | controller | changed:
2025-12-01 08:18:55.105084 | controller | {
2025-12-01 08:18:55.105110 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-01 08:18:55.105146 | controller | }
2025-12-01 08:18:55.128711 | 
2025-12-01 08:18:55.128809 | TASK [Set timezone to UTC]
2025-12-01 08:18:55.511542 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-01 08:18:55.516201 | 
2025-12-01 08:18:55.516331 | TASK [Create nodepool directory]
2025-12-01 08:18:55.719867 | controller | changed
2025-12-01 08:18:55.725441 | 
2025-12-01 08:18:55.725507 | TASK [Create nodepool sub_nodes file]
2025-12-01 08:18:56.242859 | controller | changed
2025-12-01 08:18:56.247861 | 
2025-12-01 08:18:56.247929 | TASK [Create nodepool sub_nodes_private file]
2025-12-01 08:18:56.743364 | controller | changed
2025-12-01 08:18:56.748423 | 
2025-12-01 08:18:56.748519 | LOOP [Populate nodepool sub_nodes file]
2025-12-01 08:18:56.778247 | 
2025-12-01 08:18:56.778387 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-01 08:18:56.806265 | 
2025-12-01 08:18:56.806410 | TASK [Create nodepool primary file]
2025-12-01 08:18:56.829291 | controller | skipping: Conditional result was False
2025-12-01 08:18:56.834682 | 
2025-12-01 08:18:56.834775 | TASK [Create nodepool node_private for this node]
2025-12-01 08:18:57.368783 | controller | changed
2025-12-01 08:18:57.374960 | 
2025-12-01 08:18:57.375052 | LOOP [Copy ssh keys to nodepool directory]
2025-12-01 08:18:57.701237 | controller | ok: Item: id_rsa Runtime: 0:00:00.004570
2025-12-01 08:18:57.701453 | 
2025-12-01 08:18:57.859874 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004287
2025-12-01 08:18:57.873957 | 
2025-12-01 08:18:57.874027 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-01 08:18:58.401825 | controller | changed
2025-12-01 08:18:58.406790 | 
2025-12-01 08:18:58.406858 | TASK [Validate sudoers config after edits]
2025-12-01 08:18:58.627985 | controller | /etc/sudoers: parsed OK
2025-12-01 08:18:58.628066 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-01 08:18:58.628078 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-01 08:18:58.628084 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-01 08:18:58.934798 | controller | ok: Runtime: 0:00:00.005324
2025-12-01 08:18:58.940389 | 
2025-12-01 08:18:58.940452 | TASK [Show the environment passed in to job shell scripts]
2025-12-01 08:18:59.141204 | controller | SHELL=/bin/bash
2025-12-01 08:18:59.141244 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-01 08:18:59.141253 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-01 08:18:59.141260 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/05/3505/299eec7e5ad90f400f0349fc019ba0a4dfaf4281
2025-12-01 08:18:59.141266 | controller | PWD=/home/zuul
2025-12-01 08:18:59.141282 | controller | ZUUL_PIPELINE=github-check
2025-12-01 08:18:59.141288 | controller | LOGNAME=zuul
2025-12-01 08:18:59.141294 | controller | XDG_SESSION_TYPE=tty
2025-12-01 08:18:59.141299 | controller | _=/usr/bin/env
2025-12-01 08:18:59.141305 | controller | MOTD_SHOWN=pam
2025-12-01 08:18:59.141316 | controller | HOME=/home/zuul
2025-12-01 08:18:59.141363 | controller | LANG=en_US.UTF-8
2025-12-01 08:18:59.141373 | controller | SSH_CONNECTION=192.168.25.12 39322 192.168.25.82 22
2025-12-01 08:18:59.141379 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-12-01 08:18:59.141388 | controller | ZUUL_CHANGE_IDS=3505,299eec7e5ad90f400f0349fc019ba0a4dfaf4281
2025-12-01 08:18:59.141394 | controller | WORKSPACE=/home/zuul/workspace
2025-12-01 08:18:59.141399 | controller | XDG_SESSION_CLASS=user
2025-12-01 08:18:59.141405 | controller | SELINUX_ROLE_REQUESTED=
2025-12-01 08:18:59.141410 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-01 08:18:59.141415 | controller | USER=zuul
2025-12-01 08:18:59.141421 | controller | ZUUL_VOTING=True
2025-12-01 08:18:59.141426 | controller | BUILD_TIMEOUT=3600000
2025-12-01 08:18:59.141431 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-01 08:18:59.141436 | controller | SHLVL=1
2025-12-01 08:18:59.141442 | controller | ZUUL_PATCHSET=299eec7e5ad90f400f0349fc019ba0a4dfaf4281
2025-12-01 08:18:59.141447 | controller | XDG_SESSION_ID=1
2025-12-01 08:18:59.141452 | controller | ZUUL_BRANCH=main
2025-12-01 08:18:59.141457 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-01 08:18:59.141463 | controller | SSH_CLIENT=192.168.25.12 39322 22
2025-12-01 08:18:59.141468 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-01 08:18:59.141473 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-01 08:18:59.141479 | controller | which_declare=declare -f
2025-12-01 08:18:59.141484 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-01 08:18:59.141490 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-01 08:18:59.141495 | controller | ZUUL_CHANGE=3505
2025-12-01 08:18:59.141501 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-01 08:18:59.141506 | controller | ZUUL_UUID=d589bf7cb9a34c958883eab0bbbbee0b
2025-12-01 08:18:59.141513 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-01 08:18:59.141519 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-01 08:18:59.141524 | controller | }
2025-12-01 08:18:59.467308 | controller | ok: Runtime: 0:00:00.006146
2025-12-01 08:18:59.473023 | 
2025-12-01 08:18:59.473084 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-01 08:18:59.488059 | controller | skipping: Conditional result was False
2025-12-01 08:18:59.494543 | 
2025-12-01 08:18:59.494614 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-01 08:19:00.020433 | controller | skipping: Conditional result was False
2025-12-01 08:19:00.027652 | 
2025-12-01 08:19:00.027793 | TASK [Ensure legacy workspace directory]
2025-12-01 08:19:00.222131 | controller | changed
2025-12-01 08:19:00.241038 | 
2025-12-01 08:19:00.241088 | PLAY RECAP
2025-12-01 08:19:00.241147 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-01 08:19:00.241174 | 
2025-12-01 08:19:00.319362 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-01 08:19:00.320391 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-01 08:19:00.857915 | 
2025-12-01 08:19:00.858020 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-01 08:19:00.878256 | 
2025-12-01 08:19:00.878332 | TASK [Create zuul-output directory]
2025-12-01 08:19:01.203837 | controller | changed
2025-12-01 08:19:01.209500 | 
2025-12-01 08:19:01.209584 | TASK [Slurp Zuul inventory test]
2025-12-01 08:19:01.530657 | controller -> localhost | ok
2025-12-01 08:19:01.537072 | 
2025-12-01 08:19:01.537155 | TASK [Save zuul inventory]
2025-12-01 08:19:02.264960 | controller | changed
2025-12-01 08:19:02.269818 | 
2025-12-01 08:19:02.269887 | TASK [Save zuul vars without the change_message]
2025-12-01 08:19:02.857454 | controller | changed
2025-12-01 08:19:02.875868 | 
2025-12-01 08:19:02.875938 | PLAY RECAP
2025-12-01 08:19:02.875987 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-01 08:19:02.876009 | 
2025-12-01 08:19:02.956976 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-01 08:19:02.958113 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-01 08:19:03.529949 | 
2025-12-01 08:19:03.530076 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-01 08:19:03.550911 | 
2025-12-01 08:19:03.550991 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-01 08:19:03.569958 | controller | ok
2025-12-01 08:19:03.586953 | 
2025-12-01 08:19:03.587052 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-01 08:19:03.611219 | controller | skipping: Conditional result was False
2025-12-01 08:19:03.617064 | 
2025-12-01 08:19:03.617149 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-01 08:19:03.940244 | controller | ok
2025-12-01 08:19:03.946371 | 
2025-12-01 08:19:03.946467 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-01 08:19:04.556733 | controller | ok
2025-12-01 08:19:04.574506 | 
2025-12-01 08:19:04.574646 | TASK [Prepare workspace]
2025-12-01 08:19:04.598529 | controller | ok
2025-12-01 08:19:04.616080 | 
2025-12-01 08:19:04.616192 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-01 08:19:04.942464 | controller | ok
2025-12-01 08:19:04.959479 | 
2025-12-01 08:19:04.959575 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-01 08:19:07.603438 | controller | Output suppressed because no_log was given
2025-12-01 08:19:07.612775 | 
2025-12-01 08:19:07.612855 | LOOP [Create zuul-output directory]
2025-12-01 08:19:07.808893 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-01 08:19:07.970000 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-01 08:19:07.982735 | 
2025-12-01 08:19:07.982878 | TASK [Install required packages]
2025-12-01 08:19:58.259900 | controller | changed
2025-12-01 08:19:58.265096 | 
2025-12-01 08:19:58.265168 | TASK [Install venv]
2025-12-01 08:21:01.833656 | controller | changed
2025-12-01 08:21:01.858657 | 
2025-12-01 08:21:01.858743 | PLAY RECAP
2025-12-01 08:21:01.858788 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-01 08:21:01.858810 | 
2025-12-01 08:21:01.926599 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-01 08:21:01.927427 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-01 08:21:02.425623 | 
2025-12-01 08:21:02.425730 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-12-01 08:21:02.445168 | 
2025-12-01 08:21:02.445234 | TASK [Gather required facts]
2025-12-01 08:21:02.902757 | controller | ok
2025-12-01 08:21:02.907875 | 
2025-12-01 08:21:02.907950 | TASK [Load environment var if instructed to]
2025-12-01 08:21:02.931980 | controller | skipping: Conditional result was False
2025-12-01 08:21:02.937117 | 
2025-12-01 08:21:02.937195 | TASK [Ensure group_vars dir exists]
2025-12-01 08:21:03.227882 | controller | ok
2025-12-01 08:21:03.233589 | 
2025-12-01 08:21:03.233655 | TASK [Print related variables]
2025-12-01 08:21:03.262076 | controller | ok:
2025-12-01 08:21:03.262196 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-12-01 08:21:03.262227 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager
2025-12-01 08:21:03.266801 | 
2025-12-01 08:21:03.266863 | TASK [Run molecule]
2025-12-01 08:21:04.173709 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-12-01 08:21:04.573103 | controller | INFO     Performing prerun with role_name_check=0...
2025-12-01 08:21:20.980522 | 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).
2025-12-01 08:21:20.980872 | 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).
2025-12-01 08:21:20.981328 | 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).
2025-12-01 08:21:20.982431 | 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).
2025-12-01 08:21:20.982714 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+96665145 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 1.0.0+96665145 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 08:21:20.982743 | 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).
2025-12-01 08:21:20.983173 | 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).
2025-12-01 08:21:20.983632 | 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).
2025-12-01 08:21:20.984048 | 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).
2025-12-01 08:21:20.984490 | 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).
2025-12-01 08:21:20.984956 | 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).
2025-12-01 08:21:20.985399 | 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).
2025-12-01 08:21:20.985860 | 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).
2025-12-01 08:21:20.986303 | 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).
2025-12-01 08:21:20.986761 | 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).
2025-12-01 08:21:20.996127 | controller | INFO     Running boot_order > prepare
2025-12-01 08:21:21.742726 | controller |
2025-12-01 08:21:21.742787 | controller | PLAY [Prepare] *****************************************************************
2025-12-01 08:21:21.742799 | controller |
2025-12-01 08:21:21.742870 | controller | TASK [Gathering Facts] *********************************************************
2025-12-01 08:21:21.742966 | controller | Monday 01 December 2025  08:21:21 +0000 (0:00:00.023)       0:00:00.023 *******
2025-12-01 08:21:22.571828 | controller | ok: [instance]
2025-12-01 08:21:22.571909 | controller |
2025-12-01 08:21:22.572021 | controller | TASK [Create custom basedir] ***************************************************
2025-12-01 08:21:22.572135 | controller | Monday 01 December 2025  08:21:22 +0000 (0:00:00.830)       0:00:00.854 *******
2025-12-01 08:21:22.880151 | controller | changed: [instance]
2025-12-01 08:21:22.880225 | controller |
2025-12-01 08:21:22.880349 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-12-01 08:21:22.880465 | controller | Monday 01 December 2025  08:21:22 +0000 (0:00:00.308)       0:00:01.162 *******
2025-12-01 08:21:22.898999 | controller | skipping: [instance]
2025-12-01 08:21:22.899110 | controller |
2025-12-01 08:21:22.899236 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-12-01 08:21:22.899380 | controller | Monday 01 December 2025  08:21:22 +0000 (0:00:00.019)       0:00:01.181 *******
2025-12-01 08:21:22.940349 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-12-01 08:21:22.940440 | controller |
2025-12-01 08:21:22.940545 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-12-01 08:21:22.940666 | controller | Monday 01 December 2025  08:21:22 +0000 (0:00:00.041)       0:00:01.222 *******
2025-12-01 08:21:23.198644 | controller | ok: [instance]
2025-12-01 08:21:23.198742 | controller |
2025-12-01 08:21:23.198853 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-12-01 08:21:23.198956 | controller | Monday 01 December 2025  08:21:23 +0000 (0:00:00.258)       0:00:01.480 *******
2025-12-01 08:21:23.419015 | controller | ok: [instance]
2025-12-01 08:21:23.419073 | controller |
2025-12-01 08:21:23.419180 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-12-01 08:21:23.419272 | controller | Monday 01 December 2025  08:21:23 +0000 (0:00:00.220)       0:00:01.701 *******
2025-12-01 08:21:24.079628 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-12-01 08:21:24.080190 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-12-01 08:21:24.140387 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-12-01 08:21:24.140417 | controller |
2025-12-01 08:21:24.140428 | controller | TASK [Deploy repo-setup] *******************************************************
2025-12-01 08:21:24.140438 | controller | Monday 01 December 2025  08:21:24 +0000 (0:00:00.660)       0:00:02.361 *******
2025-12-01 08:21:24.140450 | controller |
2025-12-01 08:21:24.679380 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-12-01 08:21:24.679410 | controller | Monday 01 December 2025  08:21:24 +0000 (0:00:00.060)       0:00:02.422 *******
2025-12-01 08:21:24.679422 | controller | changed: [instance] => (item=tmp)
2025-12-01 08:21:24.680127 | controller | changed: [instance] => (item=artifacts/repositories)
2025-12-01 08:21:24.680158 | controller | changed: [instance] => (item=venv/repo_setup)
2025-12-01 08:21:24.680165 | controller |
2025-12-01 08:21:24.680172 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-12-01 08:21:24.680182 | controller | Monday 01 December 2025  08:21:24 +0000 (0:00:00.537)       0:00:02.959 *******
2025-12-01 08:21:25.429696 | controller | ok: [instance]
2025-12-01 08:21:25.430087 | controller |
2025-12-01 08:21:26.503726 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-12-01 08:21:26.503761 | controller | Monday 01 December 2025  08:21:25 +0000 (0:00:00.752)       0:00:03.711 *******
2025-12-01 08:21:26.503773 | controller | changed: [instance]
2025-12-01 08:21:35.330039 | controller |
2025-12-01 08:21:35.330076 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-12-01 08:21:35.330085 | controller | Monday 01 December 2025  08:21:26 +0000 (0:00:01.073)       0:00:04.785 *******
2025-12-01 08:21:35.330094 | controller | changed: [instance]
2025-12-01 08:21:36.037167 | controller |
2025-12-01 08:21:36.037198 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-12-01 08:21:36.037207 | controller | Monday 01 December 2025  08:21:35 +0000 (0:00:08.826)       0:00:13.611 *******
2025-12-01 08:21:36.037217 | controller | changed: [instance]
2025-12-01 08:21:36.059589 | controller |
2025-12-01 08:21:36.059609 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-12-01 08:21:36.059635 | controller | Monday 01 December 2025  08:21:36 +0000 (0:00:00.707)       0:00:14.318 *******
2025-12-01 08:21:36.059645 | controller | skipping: [instance]
2025-12-01 08:21:36.856035 | controller |
2025-12-01 08:21:36.856065 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-12-01 08:21:36.856073 | controller | Monday 01 December 2025  08:21:36 +0000 (0:00:00.022)       0:00:14.341 *******
2025-12-01 08:21:36.856083 | controller | changed: [instance]
2025-12-01 08:21:36.892708 | controller |
2025-12-01 08:21:36.892742 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-12-01 08:21:36.892752 | controller | Monday 01 December 2025  08:21:36 +0000 (0:00:00.796)       0:00:15.137 *******
2025-12-01 08:21:36.892765 | controller | skipping: [instance]
2025-12-01 08:21:36.893100 | controller |
2025-12-01 08:21:36.893127 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-12-01 08:21:36.926710 | controller | Monday 01 December 2025  08:21:36 +0000 (0:00:00.037)       0:00:15.174 *******
2025-12-01 08:21:36.926742 | controller | skipping: [instance]
2025-12-01 08:21:36.926917 | controller |
2025-12-01 08:21:36.926941 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-12-01 08:21:36.962315 | controller | Monday 01 December 2025  08:21:36 +0000 (0:00:00.034)       0:00:15.208 *******
2025-12-01 08:21:36.962350 | controller | skipping: [instance]
2025-12-01 08:21:37.510152 | controller |
2025-12-01 08:21:37.510182 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-12-01 08:21:37.510190 | controller | Monday 01 December 2025  08:21:36 +0000 (0:00:00.035)       0:00:15.244 *******
2025-12-01 08:21:37.510200 | controller | changed: [instance]
2025-12-01 08:21:37.985118 | controller |
2025-12-01 08:21:37.985150 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-12-01 08:21:37.985158 | controller | Monday 01 December 2025  08:21:37 +0000 (0:00:00.547)       0:00:15.791 *******
2025-12-01 08:21:37.985168 | controller | changed: [instance]
2025-12-01 08:21:38.012774 | controller |
2025-12-01 08:21:38.012791 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-12-01 08:21:38.012798 | controller | Monday 01 December 2025  08:21:37 +0000 (0:00:00.475)       0:00:16.267 *******
2025-12-01 08:21:38.012810 | controller | skipping: [instance]
2025-12-01 08:21:38.040096 | controller |
2025-12-01 08:21:38.040115 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-12-01 08:21:38.040123 | controller | Monday 01 December 2025  08:21:38 +0000 (0:00:00.027)       0:00:16.294 *******
2025-12-01 08:21:38.040132 | controller | skipping: [instance]
2025-12-01 08:21:38.067548 | controller |
2025-12-01 08:21:38.067571 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-12-01 08:21:38.067580 | controller | Monday 01 December 2025  08:21:38 +0000 (0:00:00.027)       0:00:16.322 *******
2025-12-01 08:21:38.067591 | controller | skipping: [instance]
2025-12-01 08:21:38.106703 | controller |
2025-12-01 08:21:38.106734 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-12-01 08:21:38.106742 | controller | Monday 01 December 2025  08:21:38 +0000 (0:00:00.027)       0:00:16.349 *******
2025-12-01 08:21:38.106753 | controller | ok: [instance]
2025-12-01 08:21:38.106776 | controller |
2025-12-01 08:21:38.106784 | controller | TASK [repo_setup : Create download directory] **********************************
2025-12-01 08:21:38.106878 | controller | Monday 01 December 2025  08:21:38 +0000 (0:00:00.039)       0:00:16.388 *******
2025-12-01 08:21:38.134442 | controller | skipping: [instance]
2025-12-01 08:21:38.134662 | controller |
2025-12-01 08:21:38.134848 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-12-01 08:21:38.135017 | controller | Monday 01 December 2025  08:21:38 +0000 (0:00:00.027)       0:00:16.416 *******
2025-12-01 08:21:38.161669 | controller | skipping: [instance]
2025-12-01 08:21:38.161811 | controller |
2025-12-01 08:21:38.161964 | controller | TASK [Download the RPM] ********************************************************
2025-12-01 08:21:38.162106 | controller | Monday 01 December 2025  08:21:38 +0000 (0:00:00.027)       0:00:16.444 *******
2025-12-01 08:21:38.188111 | controller | skipping: [instance]
2025-12-01 08:21:38.188264 | controller |
2025-12-01 08:21:38.188424 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-12-01 08:21:38.188544 | controller | Monday 01 December 2025  08:21:38 +0000 (0:00:00.026)       0:00:16.470 *******
2025-12-01 08:21:38.214609 | controller | skipping: [instance]
2025-12-01 08:21:38.214769 | controller |
2025-12-01 08:21:38.214921 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-12-01 08:21:38.215065 | controller | Monday 01 December 2025  08:21:38 +0000 (0:00:00.026)       0:00:16.497 *******
2025-12-01 08:21:38.241711 | controller | skipping: [instance]
2025-12-01 08:21:38.241868 | controller |
2025-12-01 08:21:38.242016 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-12-01 08:21:38.242155 | controller | Monday 01 December 2025  08:21:38 +0000 (0:00:00.027)       0:00:16.524 *******
2025-12-01 08:21:38.269805 | controller | skipping: [instance]
2025-12-01 08:21:38.269950 | controller |
2025-12-01 08:21:38.270104 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-12-01 08:21:38.270246 | controller | Monday 01 December 2025  08:21:38 +0000 (0:00:00.028)       0:00:16.552 *******
2025-12-01 08:21:38.297433 | controller | skipping: [instance]
2025-12-01 08:21:38.297588 | controller |
2025-12-01 08:21:38.297737 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-12-01 08:21:38.297861 | controller | Monday 01 December 2025  08:21:38 +0000 (0:00:00.027)       0:00:16.579 *******
2025-12-01 08:21:38.482687 | controller | ok: [instance]
2025-12-01 08:21:38.482736 | controller |
2025-12-01 08:21:38.482969 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-12-01 08:21:38.691292 | controller | Monday 01 December 2025  08:21:38 +0000 (0:00:00.184)       0:00:16.764 *******
2025-12-01 08:21:38.691326 | controller | changed: [instance]
2025-12-01 08:21:38.924298 | controller |
2025-12-01 08:21:38.924328 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-12-01 08:21:38.924364 | controller | Monday 01 December 2025  08:21:38 +0000 (0:00:00.208)       0:00:16.973 *******
2025-12-01 08:21:38.924375 | controller | changed: [instance]
2025-12-01 08:21:38.945727 | controller |
2025-12-01 08:21:38.945767 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-12-01 08:21:38.945778 | controller | Monday 01 December 2025  08:21:38 +0000 (0:00:00.232)       0:00:17.206 *******
2025-12-01 08:21:38.945793 | controller | skipping: [instance]
2025-12-01 08:21:38.966997 | controller |
2025-12-01 08:21:38.967025 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-12-01 08:21:38.967033 | controller | Monday 01 December 2025  08:21:38 +0000 (0:00:00.021)       0:00:17.227 *******
2025-12-01 08:21:38.967043 | controller | skipping: [instance]
2025-12-01 08:21:38.987663 | controller |
2025-12-01 08:21:38.987682 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-12-01 08:21:38.987690 | controller | Monday 01 December 2025  08:21:38 +0000 (0:00:00.021)       0:00:17.249 *******
2025-12-01 08:21:38.987704 | controller | skipping: [instance]
2025-12-01 08:21:38.987833 | controller |
2025-12-01 08:21:38.987845 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-12-01 08:21:38.987853 | controller | Monday 01 December 2025  08:21:38 +0000 (0:00:00.020)       0:00:17.269 *******
2025-12-01 08:21:39.008449 | controller | skipping: [instance]
2025-12-01 08:21:39.030684 | controller |
2025-12-01 08:21:39.030706 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-12-01 08:21:39.030720 | controller | Monday 01 December 2025  08:21:39 +0000 (0:00:00.020)       0:00:17.290 *******
2025-12-01 08:21:39.030730 | controller | skipping: [instance]
2025-12-01 08:21:39.030936 | controller |
2025-12-01 08:21:39.030961 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-12-01 08:21:39.051642 | controller | Monday 01 December 2025  08:21:39 +0000 (0:00:00.022)       0:00:17.312 *******
2025-12-01 08:21:39.051664 | controller | skipping: [instance]
2025-12-01 08:21:39.067524 | controller |
2025-12-01 08:21:39.067542 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-12-01 08:21:39.067549 | controller | Monday 01 December 2025  08:21:39 +0000 (0:00:00.020)       0:00:17.333 *******
2025-12-01 08:21:39.067558 | controller | skipping: [instance]
2025-12-01 08:21:39.095235 | controller |
2025-12-01 08:21:39.095253 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-12-01 08:21:39.095260 | controller | Monday 01 December 2025  08:21:39 +0000 (0:00:00.015)       0:00:17.349 *******
2025-12-01 08:21:39.095269 | controller | skipping: [instance]
2025-12-01 08:21:39.111347 | controller |
2025-12-01 08:21:39.111369 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-12-01 08:21:39.111377 | controller | Monday 01 December 2025  08:21:39 +0000 (0:00:00.027)       0:00:17.377 *******
2025-12-01 08:21:39.111388 | controller | skipping: [instance]
2025-12-01 08:21:39.131023 | controller |
2025-12-01 08:21:39.131044 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-12-01 08:21:39.131053 | controller | Monday 01 December 2025  08:21:39 +0000 (0:00:00.016)       0:00:17.393 *******
2025-12-01 08:21:39.131063 | controller | skipping: [instance]
2025-12-01 08:21:39.159066 | controller |
2025-12-01 08:21:39.159085 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-12-01 08:21:39.159092 | controller | Monday 01 December 2025  08:21:39 +0000 (0:00:00.019)       0:00:17.413 *******
2025-12-01 08:21:39.159101 | controller | skipping: [instance]
2025-12-01 08:21:39.187044 | controller |
2025-12-01 08:21:39.187062 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-12-01 08:21:39.187069 | controller | Monday 01 December 2025  08:21:39 +0000 (0:00:00.028)       0:00:17.441 *******
2025-12-01 08:21:39.187077 | controller | skipping: [instance]
2025-12-01 08:21:39.215054 | controller |
2025-12-01 08:21:39.215071 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-12-01 08:21:39.215078 | controller | Monday 01 December 2025  08:21:39 +0000 (0:00:00.027)       0:00:17.469 *******
2025-12-01 08:21:39.215087 | controller | skipping: [instance]
2025-12-01 08:21:39.243693 | controller |
2025-12-01 08:21:39.243724 | controller | TASK [test_deps : Install additional packages] *********************************
2025-12-01 08:21:39.243734 | controller | Monday 01 December 2025  08:21:39 +0000 (0:00:00.028)       0:00:17.497 *******
2025-12-01 08:21:39.243747 | controller | skipping: [instance]
2025-12-01 08:26:10.583707 | controller |
2025-12-01 08:26:10.583741 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-12-01 08:26:10.583750 | controller | Monday 01 December 2025  08:21:39 +0000 (0:00:00.028)       0:00:17.525 *******
2025-12-01 08:26:10.583760 | controller | ok: [instance]
2025-12-01 08:26:11.831983 | controller |
2025-12-01 08:26:11.832014 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-12-01 08:26:11.832023 | controller | Monday 01 December 2025  08:26:10 +0000 (0:04:31.339)       0:04:48.865 *******
2025-12-01 08:26:11.832040 | controller | ok: [instance]
2025-12-01 08:26:11.832058 | controller |
2025-12-01 08:26:11.832296 | controller | TASK [test_deps : Install extra packages] **************************************
2025-12-01 08:26:11.865505 | controller | Monday 01 December 2025  08:26:11 +0000 (0:00:01.248)       0:04:50.114 *******
2025-12-01 08:26:11.865526 | controller | skipping: [instance]
2025-12-01 08:26:11.907714 | controller |
2025-12-01 08:26:11.907750 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-12-01 08:26:11.907760 | controller | Monday 01 December 2025  08:26:11 +0000 (0:00:00.033)       0:04:50.147 *******
2025-12-01 08:26:11.907772 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-12-01 08:26:11.907811 | controller |
2025-12-01 08:26:11.907823 | controller | TASK [ci_setup : List packages to install] *************************************
2025-12-01 08:26:11.907849 | controller | Monday 01 December 2025  08:26:11 +0000 (0:00:00.042)       0:04:50.189 *******
2025-12-01 08:26:11.927332 | controller | ok: [instance] =>
2025-12-01 08:26:15.408071 | controller |   cifmw_ci_setup_packages:
2025-12-01 08:26:15.408101 | controller |   - bash-completion
2025-12-01 08:26:15.408109 | controller |   - ca-certificates
2025-12-01 08:26:15.408115 | controller |   - git-core
2025-12-01 08:26:15.408121 | controller |   - make
2025-12-01 08:26:15.408126 | controller |   - tar
2025-12-01 08:26:15.408132 | controller |   - tmux
2025-12-01 08:26:15.408137 | controller |   - python3-pip
2025-12-01 08:26:15.408143 | controller |
2025-12-01 08:26:15.408148 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-12-01 08:26:15.408159 | controller | Monday 01 December 2025  08:26:11 +0000 (0:00:00.018)       0:04:50.208 *******
2025-12-01 08:26:15.408169 | controller | changed: [instance]
2025-12-01 08:26:15.594483 | controller |
2025-12-01 08:26:15.594509 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-12-01 08:26:15.594517 | controller | Monday 01 December 2025  08:26:15 +0000 (0:00:03.481)       0:04:53.689 *******
2025-12-01 08:26:15.594527 | controller | ok: [instance]
2025-12-01 08:26:15.794468 | controller |
2025-12-01 08:26:15.794492 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-12-01 08:26:15.794500 | controller | Monday 01 December 2025  08:26:15 +0000 (0:00:00.186)       0:04:53.876 *******
2025-12-01 08:26:15.794509 | controller | changed: [instance]
2025-12-01 08:26:21.842041 | controller |
2025-12-01 08:26:21.842070 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-12-01 08:26:21.842078 | controller | Monday 01 December 2025  08:26:15 +0000 (0:00:00.199)       0:04:54.076 *******
2025-12-01 08:26:21.842088 | controller | changed: [instance]
2025-12-01 08:26:21.878304 | controller |
2025-12-01 08:26:21.878329 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-12-01 08:26:21.878337 | controller | Monday 01 December 2025  08:26:21 +0000 (0:00:06.047)       0:05:00.123 *******
2025-12-01 08:26:21.878347 | controller | ok: [instance]
2025-12-01 08:26:22.134042 | controller |
2025-12-01 08:26:22.134071 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-12-01 08:26:22.134079 | controller | Monday 01 December 2025  08:26:21 +0000 (0:00:00.036)       0:05:00.160 *******
2025-12-01 08:26:22.134090 | controller | changed: [instance]
2025-12-01 08:26:22.409799 | controller |
2025-12-01 08:26:22.409827 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-12-01 08:26:22.409835 | controller | Monday 01 December 2025  08:26:22 +0000 (0:00:00.254)       0:05:00.415 *******
2025-12-01 08:26:22.409845 | controller | changed: [instance]
2025-12-01 08:26:22.427283 | controller |
2025-12-01 08:26:22.427299 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-12-01 08:26:22.427306 | controller | Monday 01 December 2025  08:26:22 +0000 (0:00:00.276)       0:05:00.691 *******
2025-12-01 08:26:22.427315 | controller | skipping: [instance]
2025-12-01 08:26:22.444201 | controller |
2025-12-01 08:26:22.444217 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-12-01 08:26:22.444224 | controller | Monday 01 December 2025  08:26:22 +0000 (0:00:00.017)       0:05:00.709 *******
2025-12-01 08:26:22.444232 | controller | skipping: [instance]
2025-12-01 08:26:22.463418 | controller |
2025-12-01 08:26:22.463434 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-12-01 08:26:22.463441 | controller | Monday 01 December 2025  08:26:22 +0000 (0:00:00.016)       0:05:00.726 *******
2025-12-01 08:26:22.463449 | controller | skipping: [instance]
2025-12-01 08:26:22.480680 | controller |
2025-12-01 08:26:22.480703 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-12-01 08:26:22.480712 | controller | Monday 01 December 2025  08:26:22 +0000 (0:00:00.019)       0:05:00.745 *******
2025-12-01 08:26:22.480723 | controller | skipping: [instance]
2025-12-01 08:26:22.480749 | controller |
2025-12-01 08:26:22.480759 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-12-01 08:26:22.480853 | controller | Monday 01 December 2025  08:26:22 +0000 (0:00:00.017)       0:05:00.762 *******
2025-12-01 08:26:22.497677 | controller | skipping: [instance]
2025-12-01 08:26:22.497912 | controller |
2025-12-01 08:26:22.497931 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-12-01 08:26:22.519304 | controller | Monday 01 December 2025  08:26:22 +0000 (0:00:00.016)       0:05:00.779 *******
2025-12-01 08:26:22.519323 | controller | skipping: [instance]
2025-12-01 08:26:23.496681 | controller |
2025-12-01 08:26:23.496710 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-12-01 08:26:23.496718 | controller | Monday 01 December 2025  08:26:22 +0000 (0:00:00.021)       0:05:00.801 *******
2025-12-01 08:26:23.496728 | controller | changed: [instance] => (item=/opt/basedir/artifacts/manifests/openstack/cr)
2025-12-01 08:26:23.496753 | controller | changed: [instance] => (item=/opt/basedir/logs)
2025-12-01 08:26:23.497306 | controller | ok: [instance] => (item=/opt/basedir/tmp)
2025-12-01 08:26:24.220724 | controller | changed: [instance] => (item=/opt/basedir/volumes)
2025-12-01 08:26:24.220789 | controller | changed: [instance] => (item=/opt/basedir/artifacts/parameters)
2025-12-01 08:26:24.220797 | controller |
2025-12-01 08:26:24.220803 | controller | TASK [libvirt_manager : Get installed packages list] ***************************
2025-12-01 08:26:24.220809 | controller | Monday 01 December 2025  08:26:23 +0000 (0:00:00.977)       0:05:01.778 *******
2025-12-01 08:26:24.220880 | controller | ok: [instance]
2025-12-01 08:26:25.993714 | controller |
2025-12-01 08:26:25.993749 | controller | TASK [libvirt_manager : Populate service facts] ********************************
2025-12-01 08:26:25.993759 | controller | Monday 01 December 2025  08:26:24 +0000 (0:00:00.722)       0:05:02.501 *******
2025-12-01 08:26:25.993776 | controller | ok: [instance]
2025-12-01 08:26:25.993807 | controller |
2025-12-01 08:26:25.994058 | controller | TASK [libvirt_manager : Start virtqemud socket service] ************************
2025-12-01 08:26:26.054739 | controller | Monday 01 December 2025  08:26:25 +0000 (0:00:01.774)       0:05:04.275 *******
2025-12-01 08:26:26.054775 | controller | skipping: [instance] => (item=virtqemud.service)
2025-12-01 08:26:26.054854 | controller | skipping: [instance] => (item=virtqemud.socket)
2025-12-01 08:26:26.054876 | controller | skipping: [instance]
2025-12-01 08:26:26.054885 | controller |
2025-12-01 08:26:26.054893 | controller | TASK [libvirt_manager : Set _is_deepscrub internal fact] ***********************
2025-12-01 08:26:26.054904 | controller | Monday 01 December 2025  08:26:26 +0000 (0:00:00.060)       0:05:04.336 *******
2025-12-01 08:26:26.101698 | controller | ok: [instance]
2025-12-01 08:26:26.101776 | controller |
2025-12-01 08:26:26.101798 | controller | TASK [libvirt_manager : List all of the existing virtual machines] *************
2025-12-01 08:26:26.101811 | controller | Monday 01 December 2025  08:26:26 +0000 (0:00:00.047)       0:05:04.383 *******
2025-12-01 08:26:26.161157 | controller | skipping: [instance]
2025-12-01 08:26:26.221412 | controller |
2025-12-01 08:26:26.221434 | controller | TASK [libvirt_manager : Get pool configuration] ********************************
2025-12-01 08:26:26.221443 | controller | Monday 01 December 2025  08:26:26 +0000 (0:00:00.059)       0:05:04.443 *******
2025-12-01 08:26:26.221455 | controller | skipping: [instance]
2025-12-01 08:26:26.279519 | controller |
2025-12-01 08:26:26.279542 | controller | TASK [libvirt_manager : Filter out target environment] *************************
2025-12-01 08:26:26.279551 | controller | Monday 01 December 2025  08:26:26 +0000 (0:00:00.060)       0:05:04.503 *******
2025-12-01 08:26:26.279562 | controller | skipping: [instance]
2025-12-01 08:26:26.377557 | controller |
2025-12-01 08:26:26.377590 | controller | TASK [libvirt_manager : Expose cleanup list] ***********************************
2025-12-01 08:26:26.377600 | controller | Monday 01 December 2025  08:26:26 +0000 (0:00:00.058)       0:05:04.561 *******
2025-12-01 08:26:26.377631 | controller | skipping: [instance]
2025-12-01 08:26:26.436726 | controller |
2025-12-01 08:26:26.436762 | controller | TASK [libvirt_manager : Clean ssh known_hosts] *********************************
2025-12-01 08:26:26.436773 | controller | Monday 01 December 2025  08:26:26 +0000 (0:00:00.097)       0:05:04.659 *******
2025-12-01 08:26:26.436785 | controller | skipping: [instance]
2025-12-01 08:26:26.436825 | controller |
2025-12-01 08:26:26.436835 | controller | TASK [libvirt_manager : Destroy machine] ***************************************
2025-12-01 08:26:26.436852 | controller | Monday 01 December 2025  08:26:26 +0000 (0:00:00.059)       0:05:04.718 *******
2025-12-01 08:26:26.497229 | controller | skipping: [instance]
2025-12-01 08:26:26.556182 | controller |
2025-12-01 08:26:26.556201 | controller | TASK [libvirt_manager : Undefine machine] **************************************
2025-12-01 08:26:26.556209 | controller | Monday 01 December 2025  08:26:26 +0000 (0:00:00.060)       0:05:04.779 *******
2025-12-01 08:26:26.556218 | controller | skipping: [instance]
2025-12-01 08:26:26.619266 | controller |
2025-12-01 08:26:26.619285 | controller | TASK [libvirt_manager : (localhost) Clean ssh jumpers] *************************
2025-12-01 08:26:26.619292 | controller | Monday 01 December 2025  08:26:26 +0000 (0:00:00.058)       0:05:04.838 *******
2025-12-01 08:26:26.619301 | controller | skipping: [instance]
2025-12-01 08:26:26.678462 | controller |
2025-12-01 08:26:26.678481 | controller | TASK [libvirt_manager : (instance) Clean ssh jumpers] **************************
2025-12-01 08:26:26.678488 | controller | Monday 01 December 2025  08:26:26 +0000 (0:00:00.063)       0:05:04.901 *******
2025-12-01 08:26:26.678497 | controller | skipping: [instance]
2025-12-01 08:26:26.736054 | controller |
2025-12-01 08:26:26.736076 | controller | TASK [libvirt_manager : Get network list] **************************************
2025-12-01 08:26:26.736085 | controller | Monday 01 December 2025  08:26:26 +0000 (0:00:00.059)       0:05:04.960 *******
2025-12-01 08:26:26.736096 | controller | skipping: [instance]
2025-12-01 08:26:26.794731 | controller |
2025-12-01 08:26:26.794757 | controller | TASK [libvirt_manager : Filter out target nets] ********************************
2025-12-01 08:26:26.794767 | controller | Monday 01 December 2025  08:26:26 +0000 (0:00:00.057)       0:05:05.018 *******
2025-12-01 08:26:26.794778 | controller | skipping: [instance]
2025-12-01 08:26:26.794810 | controller |
2025-12-01 08:26:26.795032 | controller | TASK [libvirt_manager : Expose cleanup list] ***********************************
2025-12-01 08:26:26.853701 | controller | Monday 01 December 2025  08:26:26 +0000 (0:00:00.058)       0:05:05.077 *******
2025-12-01 08:26:26.853729 | controller | skipping: [instance]
2025-12-01 08:26:26.853769 | controller |
2025-12-01 08:26:26.853787 | controller | TASK [Remove dnsmasq network bits] *********************************************
2025-12-01 08:26:26.853798 | controller | Monday 01 December 2025  08:26:26 +0000 (0:00:00.058)       0:05:05.135 *******
2025-12-01 08:26:26.913565 | controller | skipping: [instance]
2025-12-01 08:26:26.973564 | controller |
2025-12-01 08:26:26.973587 | controller | TASK [libvirt_manager : Destroy networks] **************************************
2025-12-01 08:26:26.973606 | controller | Monday 01 December 2025  08:26:26 +0000 (0:00:00.059)       0:05:05.195 *******
2025-12-01 08:26:26.973636 | controller | skipping: [instance]
2025-12-01 08:26:27.033062 | controller |
2025-12-01 08:26:27.033080 | controller | TASK [libvirt_manager : Undefine networks] *************************************
2025-12-01 08:26:27.033088 | controller | Monday 01 December 2025  08:26:26 +0000 (0:00:00.059)       0:05:05.255 *******
2025-12-01 08:26:27.033097 | controller | skipping: [instance]
2025-12-01 08:26:27.091717 | controller |
2025-12-01 08:26:27.091746 | controller | TASK [libvirt_manager : Ensure no trace of networks lays in nmcli] *************
2025-12-01 08:26:27.091756 | controller | Monday 01 December 2025  08:26:27 +0000 (0:00:00.059)       0:05:05.315 *******
2025-12-01 08:26:27.091767 | controller | skipping: [instance]
2025-12-01 08:26:27.091833 | controller |
2025-12-01 08:26:27.091855 | controller | TASK [libvirt_manager : Find dummy interface connection files] *****************
2025-12-01 08:26:27.091868 | controller | Monday 01 December 2025  08:26:27 +0000 (0:00:00.058)       0:05:05.373 *******
2025-12-01 08:26:27.204748 | controller | skipping: [instance]
2025-12-01 08:26:27.204786 | controller |
2025-12-01 08:26:27.204798 | controller | TASK [libvirt_manager : Remove dummy interface connections] ********************
2025-12-01 08:26:27.204923 | controller | Monday 01 December 2025  08:26:27 +0000 (0:00:00.112)       0:05:05.486 *******
2025-12-01 08:26:27.266462 | controller | skipping: [instance]
2025-12-01 08:26:27.326712 | controller |
2025-12-01 08:26:27.326734 | controller | TASK [libvirt_manager : Clean firewalld libvirt zone] **************************
2025-12-01 08:26:27.326742 | controller | Monday 01 December 2025  08:26:27 +0000 (0:00:00.061)       0:05:05.548 *******
2025-12-01 08:26:27.326751 | controller | skipping: [instance]
2025-12-01 08:26:27.326992 | controller |
2025-12-01 08:26:27.327018 | controller | TASK [libvirt_manager : Ensure firewalld is enabled and started] ***************
2025-12-01 08:26:27.386597 | controller | Monday 01 December 2025  08:26:27 +0000 (0:00:00.060)       0:05:05.608 *******
2025-12-01 08:26:27.386638 | controller | skipping: [instance]
2025-12-01 08:26:27.445774 | controller |
2025-12-01 08:26:27.445792 | controller | TASK [libvirt_manager : Disable masquerade in firewalld default zone] **********
2025-12-01 08:26:27.445800 | controller | Monday 01 December 2025  08:26:27 +0000 (0:00:00.059)       0:05:05.668 *******
2025-12-01 08:26:27.445809 | controller | skipping: [instance]
2025-12-01 08:26:27.503407 | controller |
2025-12-01 08:26:27.503429 | controller | TASK [libvirt_manager : Disable forward in firewalld libvirt zone] *************
2025-12-01 08:26:27.503438 | controller | Monday 01 December 2025  08:26:27 +0000 (0:00:00.059)       0:05:05.727 *******
2025-12-01 08:26:27.503449 | controller | skipping: [instance]
2025-12-01 08:26:27.563219 | controller |
2025-12-01 08:26:27.563237 | controller | TASK [libvirt_manager : Remove cifmw storage pool] *****************************
2025-12-01 08:26:27.563245 | controller | Monday 01 December 2025  08:26:27 +0000 (0:00:00.057)       0:05:05.785 *******
2025-12-01 08:26:27.563253 | controller | skipping: [instance]
2025-12-01 08:26:27.604595 | controller |
2025-12-01 08:26:27.604638 | controller | TASK [libvirt_manager : Remove overlay images from ocp_volume pools if exists] ***
2025-12-01 08:26:27.604650 | controller | Monday 01 December 2025  08:26:27 +0000 (0:00:00.059)       0:05:05.845 *******
2025-12-01 08:26:27.604662 | controller | skipping: [instance]
2025-12-01 08:26:27.663728 | controller |
2025-12-01 08:26:27.663763 | controller | TASK [libvirt_manager : Remove ocp_volumes storage pool] ***********************
2025-12-01 08:26:27.663772 | controller | Monday 01 December 2025  08:26:27 +0000 (0:00:00.041)       0:05:05.886 *******
2025-12-01 08:26:27.663785 | controller | skipping: [instance]
2025-12-01 08:26:27.663816 | controller |
2025-12-01 08:26:27.664050 | controller | TASK [libvirt_manager : Refresh all pools after actions] ***********************
2025-12-01 08:26:27.723824 | controller | Monday 01 December 2025  08:26:27 +0000 (0:00:00.059)       0:05:05.945 *******
2025-12-01 08:26:27.723850 | controller | skipping: [instance]
2025-12-01 08:26:27.912668 | controller |
2025-12-01 08:26:27.912694 | controller | TASK [libvirt_manager : Get temporary key status] ******************************
2025-12-01 08:26:27.912702 | controller | Monday 01 December 2025  08:26:27 +0000 (0:00:00.059)       0:05:06.005 *******
2025-12-01 08:26:27.912711 | controller | ok: [instance]
2025-12-01 08:26:27.912810 | controller |
2025-12-01 08:26:27.912837 | controller | TASK [libvirt_manager : Get public key] ****************************************
2025-12-01 08:26:27.912849 | controller | Monday 01 December 2025  08:26:27 +0000 (0:00:00.188)       0:05:06.194 *******
2025-12-01 08:26:27.943474 | controller | skipping: [instance]
2025-12-01 08:26:27.976731 | controller |
2025-12-01 08:26:27.976758 | controller | TASK [libvirt_manager : Remove public key] *************************************
2025-12-01 08:26:27.976767 | controller | Monday 01 December 2025  08:26:27 +0000 (0:00:00.030)       0:05:06.225 *******
2025-12-01 08:26:27.976782 | controller | skipping: [instance]
2025-12-01 08:26:28.037773 | controller |
2025-12-01 08:26:28.037815 | controller | TASK [libvirt_manager : Remove keypair] ****************************************
2025-12-01 08:26:28.037825 | controller | Monday 01 December 2025  08:26:27 +0000 (0:00:00.033)       0:05:06.258 *******
2025-12-01 08:26:28.037838 | controller | skipping: [instance] => (item=cifmw_reproducer_key.pub)
2025-12-01 08:26:28.299631 | controller | skipping: [instance] => (item=cifmw_reproducer_key)
2025-12-01 08:26:28.299662 | controller | skipping: [instance]
2025-12-01 08:26:28.299670 | controller |
2025-12-01 08:26:28.299676 | controller | TASK [libvirt_manager : List libvirt logs] *************************************
2025-12-01 08:26:28.299682 | controller | Monday 01 December 2025  08:26:28 +0000 (0:00:00.060)       0:05:06.319 *******
2025-12-01 08:26:28.299692 | controller | [WARNING]: Skipped '/var/log/libvirt/qemu/' path due to this access issue:
2025-12-01 08:26:28.321671 | controller | '/var/log/libvirt/qemu/' is not a directory
2025-12-01 08:26:28.321699 | controller | ok: [instance]
2025-12-01 08:26:28.322096 | controller |
2025-12-01 08:26:28.351678 | controller | TASK [libvirt_manager : Remove detected logs] **********************************
2025-12-01 08:26:28.351702 | controller | Monday 01 December 2025  08:26:28 +0000 (0:00:00.284)       0:05:06.603 *******
2025-12-01 08:26:28.351724 | controller | skipping: [instance]
2025-12-01 08:26:28.352156 | controller |
2025-12-01 08:26:29.692764 | controller | TASK [libvirt_manager : Remove workload and volumes] ***************************
2025-12-01 08:26:29.692797 | controller | Monday 01 December 2025  08:26:28 +0000 (0:00:00.030)       0:05:06.634 *******
2025-12-01 08:26:29.692811 | controller | ok: [instance] => (item=workload)
2025-12-01 08:26:29.692887 | controller | changed: [instance] => (item=volumes)
2025-12-01 08:26:29.692915 | controller | ok: [instance] => (item=artifacts/debug_network_data.yml)
2025-12-01 08:26:29.692922 | controller | ok: [instance] => (item=artifacts/debug_pub_net_value.txt)
2025-12-01 08:26:29.692928 | controller | ok: [instance] => (item=artifacts/debug_cifmw_libvirt_manager_layout.yml)
2025-12-01 08:26:29.692934 | controller | ok: [instance] => (item=artifacts/interfaces-info.yml)
2025-12-01 08:26:29.692940 | controller | ok: [instance] => (item=artifacts/libvirt-uuids.yml)
2025-12-01 08:26:29.692945 | controller | ok: [instance] => (item=artifacts/net-map-def-patch.yml)
2025-12-01 08:26:29.692950 | controller |
2025-12-01 08:26:29.692956 | controller | TASK [libvirt_manager : Deepscrub data] ****************************************
2025-12-01 08:26:29.692974 | controller | Monday 01 December 2025  08:26:29 +0000 (0:00:01.339)       0:05:07.973 *******
2025-12-01 08:26:30.074398 | controller | ok: [instance] => (item=images)
2025-12-01 08:26:31.287342 | controller | ok: [instance] => (item=artifacts/virtual-nodes.yml)
2025-12-01 08:26:31.287372 | controller |
2025-12-01 08:26:31.287380 | controller | TASK [podman : Ensure podman is installed] *************************************
2025-12-01 08:26:31.287387 | controller | Monday 01 December 2025  08:26:30 +0000 (0:00:00.382)       0:05:08.356 *******
2025-12-01 08:26:31.287396 | controller | ok: [instance]
2025-12-01 08:26:31.610535 | controller |
2025-12-01 08:26:31.610569 | controller | TASK [podman : Enable loginctl linger for ansible_user_id] *********************
2025-12-01 08:26:31.610579 | controller | Monday 01 December 2025  08:26:31 +0000 (0:00:01.213)       0:05:09.569 *******
2025-12-01 08:26:31.610590 | controller | changed: [instance]
2025-12-01 08:26:31.647706 | controller |
2025-12-01 08:26:31.647738 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul] *******************
2025-12-01 08:26:31.647748 | controller | Monday 01 December 2025  08:26:31 +0000 (0:00:00.322)       0:05:09.892 *******
2025-12-01 08:26:31.647760 | controller | skipping: [instance]
2025-12-01 08:26:31.647790 | controller |
2025-12-01 08:26:31.647954 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul] *******************
2025-12-01 08:26:31.682515 | controller | Monday 01 December 2025  08:26:31 +0000 (0:00:00.037)       0:05:09.929 *******
2025-12-01 08:26:31.682547 | controller | skipping: [instance]
2025-12-01 08:26:31.733697 | controller |
2025-12-01 08:26:31.733728 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed] ***
2025-12-01 08:26:31.733737 | controller | Monday 01 December 2025  08:26:31 +0000 (0:00:00.034)       0:05:09.964 *******
2025-12-01 08:26:31.733749 | controller | skipping: [instance]
2025-12-01 08:26:31.733777 | controller |
2025-12-01 08:26:31.733786 | controller | TASK [virtualbmc : Stop and remove vbmc container] *****************************
2025-12-01 08:26:31.733911 | controller | Monday 01 December 2025  08:26:31 +0000 (0:00:00.050)       0:05:10.015 *******
2025-12-01 08:26:32.342157 | controller | ok: [instance]
2025-12-01 08:26:32.762485 | controller |
2025-12-01 08:26:32.762518 | controller | TASK [virtualbmc : Remove vbmc container image] ********************************
2025-12-01 08:26:32.762528 | controller | Monday 01 December 2025  08:26:32 +0000 (0:00:00.608)       0:05:10.623 *******
2025-12-01 08:26:32.762541 | controller | ok: [instance]
2025-12-01 08:26:32.946710 | controller |
2025-12-01 08:26:32.946746 | controller | TASK [virtualbmc : Check if VBMC key exists] ***********************************
2025-12-01 08:26:32.946755 | controller | Monday 01 December 2025  08:26:32 +0000 (0:00:00.420)       0:05:11.044 *******
2025-12-01 08:26:32.946766 | controller | ok: [instance]
2025-12-01 08:26:32.946773 | controller |
2025-12-01 08:26:32.946779 | controller | TASK [virtualbmc : Slurp key] **************************************************
2025-12-01 08:26:32.946786 | controller | Monday 01 December 2025  08:26:32 +0000 (0:00:00.184)       0:05:11.228 *******
2025-12-01 08:26:32.977352 | controller | skipping: [instance]
2025-12-01 08:26:33.008687 | controller |
2025-12-01 08:26:33.008714 | controller | TASK [virtualbmc : Revoke VBMC SSH access] *************************************
2025-12-01 08:26:33.008724 | controller | Monday 01 December 2025  08:26:32 +0000 (0:00:00.030)       0:05:11.259 *******
2025-12-01 08:26:33.008735 | controller | skipping: [instance]
2025-12-01 08:26:33.008763 | controller |
2025-12-01 08:26:33.008773 | controller | TASK [virtualbmc : Remove vbmc files] ******************************************
2025-12-01 08:26:33.008923 | controller | Monday 01 December 2025  08:26:33 +0000 (0:00:00.031)       0:05:11.290 *******
2025-12-01 08:26:33.720747 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmc-key)
2025-12-01 08:26:33.721050 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmc-key.pub)
2025-12-01 08:26:33.721066 | controller | ok: [instance] => (item=/home/zuul/.ssh/virtualbmc.conf)
2025-12-01 08:26:33.721073 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmcd.sh)
2025-12-01 08:26:33.721082 | controller |
2025-12-01 08:26:33.795755 | controller | TASK [Clean remote ssh config] *************************************************
2025-12-01 08:26:33.795793 | controller | Monday 01 December 2025  08:26:33 +0000 (0:00:00.711)       0:05:12.002 *******
2025-12-01 08:26:33.795805 | controller |
2025-12-01 08:26:34.102292 | controller | TASK [ssh_jumper : Remove Include cifmw_ssh_config.d] **************************
2025-12-01 08:26:34.102326 | controller | Monday 01 December 2025  08:26:33 +0000 (0:00:00.075)       0:05:12.077 *******
2025-12-01 08:26:34.102338 | controller | ok: [instance]
2025-12-01 08:26:34.319745 | controller |
2025-12-01 08:26:34.319781 | controller | TASK [ssh_jumper : Remove cifmw_ssh_config.d directory] ************************
2025-12-01 08:26:34.319793 | controller | Monday 01 December 2025  08:26:34 +0000 (0:00:00.306)       0:05:12.384 *******
2025-12-01 08:26:34.319806 | controller | ok: [instance]
2025-12-01 08:26:34.420601 | controller |
2025-12-01 08:26:34.420656 | controller | TASK [Clean local ssh config] **************************************************
2025-12-01 08:26:34.420666 | controller | Monday 01 December 2025  08:26:34 +0000 (0:00:00.217)       0:05:12.601 *******
2025-12-01 08:26:34.420678 | controller |
2025-12-01 08:26:34.623685 | controller | TASK [ssh_jumper : Remove Include cifmw_ssh_config.d] **************************
2025-12-01 08:26:34.623715 | controller | Monday 01 December 2025  08:26:34 +0000 (0:00:00.100)       0:05:12.702 *******
2025-12-01 08:26:34.623727 | controller | ok: [instance -> localhost]
2025-12-01 08:26:34.623993 | controller |
2025-12-01 08:26:34.624008 | controller | TASK [ssh_jumper : Remove cifmw_ssh_config.d directory] ************************
2025-12-01 08:26:34.845795 | controller | Monday 01 December 2025  08:26:34 +0000 (0:00:00.203)       0:05:12.905 *******
2025-12-01 08:26:34.845836 | controller | ok: [instance -> localhost]
2025-12-01 08:26:34.904785 | controller |
2025-12-01 08:26:34.904815 | controller | TASK [dnsmasq : Cleaning dnsmasq] **********************************************
2025-12-01 08:26:34.904824 | controller | Monday 01 December 2025  08:26:34 +0000 (0:00:00.221)       0:05:13.127 *******
2025-12-01 08:26:34.904836 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/configure.yml for instance
2025-12-01 08:26:34.904844 | controller |
2025-12-01 08:26:34.904851 | controller | TASK [dnsmasq : Install needed packages] ***************************************
2025-12-01 08:26:34.904860 | controller | Monday 01 December 2025  08:26:34 +0000 (0:00:00.059)       0:05:13.186 *******
2025-12-01 08:26:38.679823 | controller | changed: [instance]
2025-12-01 08:26:39.984522 | controller |
2025-12-01 08:26:39.984552 | controller | TASK [dnsmasq : Ensure SELinux knows about the new files and directories] ******
2025-12-01 08:26:39.984560 | controller | Monday 01 December 2025  08:26:38 +0000 (0:00:03.774)       0:05:16.961 *******
2025-12-01 08:26:39.984570 | controller | ok: [instance] => (item={'target': '/etc/cifmw-dnsmasq.conf', 'setype': 'dnsmasq_etc_t'})
2025-12-01 08:26:40.203715 | controller | ok: [instance] => (item={'target': '/etc/cifmw-dnsmasq.d(/.*)?', 'setype': 'dnsmasq_etc_t'})
2025-12-01 08:26:40.203748 | controller | ok: [instance] => (item={'target': '/var/run/cifmw-dnsmasq.pid', 'setype': 'dnsmasq_var_run_t'})
2025-12-01 08:26:40.203757 | controller |
2025-12-01 08:26:40.203765 | controller | TASK [dnsmasq : Manage configuration directory] ********************************
2025-12-01 08:26:40.203772 | controller | Monday 01 December 2025  08:26:39 +0000 (0:00:01.304)       0:05:18.266 *******
2025-12-01 08:26:40.203791 | controller | ok: [instance]
2025-12-01 08:26:40.235684 | controller |
2025-12-01 08:26:40.235708 | controller | TASK [dnsmasq : Manage systemd unit file] **************************************
2025-12-01 08:26:40.235715 | controller | Monday 01 December 2025  08:26:40 +0000 (0:00:00.219)       0:05:18.485 *******
2025-12-01 08:26:40.235726 | controller | skipping: [instance]
2025-12-01 08:26:40.235964 | controller |
2025-12-01 08:26:40.235992 | controller | TASK [dnsmasq : Manage base configuration file] ********************************
2025-12-01 08:26:40.268115 | controller | Monday 01 December 2025  08:26:40 +0000 (0:00:00.032)       0:05:18.517 *******
2025-12-01 08:26:40.268136 | controller | skipping: [instance]
2025-12-01 08:26:40.341461 | controller |
2025-12-01 08:26:40.341490 | controller | TASK [dnsmasq : Render listener configuration] *********************************
2025-12-01 08:26:40.341497 | controller | Monday 01 December 2025  08:26:40 +0000 (0:00:00.032)       0:05:18.550 *******
2025-12-01 08:26:40.341507 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/listener.yml for instance
2025-12-01 08:26:40.373872 | controller |
2025-12-01 08:26:40.373896 | controller | TASK [dnsmasq : Create listener configuration file] ****************************
2025-12-01 08:26:40.373905 | controller | Monday 01 December 2025  08:26:40 +0000 (0:00:00.073)       0:05:18.623 *******
2025-12-01 08:26:40.373923 | controller | skipping: [instance]
2025-12-01 08:26:40.583327 | controller |
2025-12-01 08:26:40.583359 | controller | TASK [dnsmasq : Remove listener configuration file] ****************************
2025-12-01 08:26:40.583368 | controller | Monday 01 December 2025  08:26:40 +0000 (0:00:00.032)       0:05:18.655 *******
2025-12-01 08:26:40.583380 | controller | ok: [instance]
2025-12-01 08:26:40.637694 | controller |
2025-12-01 08:26:40.637722 | controller | TASK [dnsmasq : Render dns configuration] **************************************
2025-12-01 08:26:40.637730 | controller | Monday 01 December 2025  08:26:40 +0000 (0:00:00.209)       0:05:18.865 *******
2025-12-01 08:26:40.637740 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/dns.yml for instance
2025-12-01 08:26:40.637912 | controller |
2025-12-01 08:26:40.637931 | controller | TASK [dnsmasq : Create dns configuration file] *********************************
2025-12-01 08:26:40.670378 | controller | Monday 01 December 2025  08:26:40 +0000 (0:00:00.054)       0:05:18.919 *******
2025-12-01 08:26:40.670404 | controller | skipping: [instance]
2025-12-01 08:26:40.880475 | controller |
2025-12-01 08:26:40.880503 | controller | TASK [dnsmasq : Remove dns configuration file] *********************************
2025-12-01 08:26:40.880511 | controller | Monday 01 December 2025  08:26:40 +0000 (0:00:00.032)       0:05:18.952 *******
2025-12-01 08:26:40.880521 | controller | ok: [instance]
2025-12-01 08:26:40.910369 | controller |
2025-12-01 08:26:40.910388 | controller | TASK [dnsmasq : Add localhost addresses from defined dnsmasq listen addresses to loopback interface] ***
2025-12-01 08:26:40.910396 | controller | Monday 01 December 2025  08:26:40 +0000 (0:00:00.209)       0:05:19.162 *******
2025-12-01 08:26:40.910405 | controller | skipping: [instance]
2025-12-01 08:26:40.942593 | controller |
2025-12-01 08:26:40.942635 | controller | TASK [dnsmasq : Enable and start service] **************************************
2025-12-01 08:26:40.942648 | controller | Monday 01 December 2025  08:26:40 +0000 (0:00:00.030)       0:05:19.192 *******
2025-12-01 08:26:40.942659 | controller | skipping: [instance]
2025-12-01 08:26:41.489767 | controller |
2025-12-01 08:26:41.489803 | controller | TASK [dnsmasq : Stop service] **************************************************
2025-12-01 08:26:41.489815 | controller | Monday 01 December 2025  08:26:40 +0000 (0:00:00.032)       0:05:19.224 *******
2025-12-01 08:26:41.489828 | controller | ok: [instance]
2025-12-01 08:26:41.489863 | controller |
2025-12-01 08:26:41.490087 | controller | TASK [dnsmasq : Remove unit file] **********************************************
2025-12-01 08:26:41.721750 | controller | Monday 01 December 2025  08:26:41 +0000 (0:00:00.547)       0:05:19.771 *******
2025-12-01 08:26:41.721786 | controller | ok: [instance]
2025-12-01 08:26:41.721992 | controller |
2025-12-01 08:26:41.722011 | controller | TASK [dnsmasq : Remove main configuration files] *******************************
2025-12-01 08:26:42.108711 | controller | Monday 01 December 2025  08:26:41 +0000 (0:00:00.232)       0:05:20.003 *******
2025-12-01 08:26:42.108755 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2025-12-01 08:26:42.108785 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.d)
2025-12-01 08:26:42.108794 | controller |
2025-12-01 08:26:42.108801 | controller | TASK [dnsmasq : Remove lease file] *********************************************
2025-12-01 08:26:42.108810 | controller | Monday 01 December 2025  08:26:42 +0000 (0:00:00.386)       0:05:20.390 *******
2025-12-01 08:26:42.329564 | controller | ok: [instance]
2025-12-01 08:26:42.329633 | controller |
2025-12-01 08:26:42.329863 | controller | TASK [radvd : Gather package facts] ********************************************
2025-12-01 08:26:42.829476 | controller | Monday 01 December 2025  08:26:42 +0000 (0:00:00.221)       0:05:20.611 *******
2025-12-01 08:26:42.829514 | controller | ok: [instance]
2025-12-01 08:26:42.868346 | controller |
2025-12-01 08:26:42.868368 | controller | TASK [radvd : Stop and disable radvd service] **********************************
2025-12-01 08:26:42.868376 | controller | Monday 01 December 2025  08:26:42 +0000 (0:00:00.499)       0:05:21.111 *******
2025-12-01 08:26:42.868385 | controller | skipping: [instance]
2025-12-01 08:26:43.083595 | controller |
2025-12-01 08:26:43.083640 | controller | TASK [radvd : Remove radvd configuration file] *********************************
2025-12-01 08:26:43.083651 | controller | Monday 01 December 2025  08:26:42 +0000 (0:00:00.039)       0:05:21.150 *******
2025-12-01 08:26:43.083661 | controller | ok: [instance]
2025-12-01 08:26:43.322645 | controller |
2025-12-01 08:26:43.322679 | controller | TASK [radvd : Remove configuration directory] **********************************
2025-12-01 08:26:43.322689 | controller | Monday 01 December 2025  08:26:43 +0000 (0:00:00.215)       0:05:21.365 *******
2025-12-01 08:26:43.322701 | controller | ok: [instance]
2025-12-01 08:26:43.390635 | controller |
2025-12-01 08:26:43.390659 | controller | TASK [radvd : Remove radvd package] ********************************************
2025-12-01 08:26:43.390668 | controller | Monday 01 December 2025  08:26:43 +0000 (0:00:00.238)       0:05:21.604 *******
2025-12-01 08:26:43.390679 | controller | skipping: [instance]
2025-12-01 08:26:43.587411 | controller |
2025-12-01 08:26:43.587442 | controller | TASK [libvirt_manager : Ensure output directory exists] ************************
2025-12-01 08:26:43.587450 | controller | Monday 01 December 2025  08:26:43 +0000 (0:00:00.068)       0:05:21.672 *******
2025-12-01 08:26:43.587465 | controller | ok: [instance]
2025-12-01 08:26:43.778697 | controller |
2025-12-01 08:26:43.778729 | controller | TASK [libvirt_manager : Get vmx/svm extensions from /proc/cpuinfo] *************
2025-12-01 08:26:43.778739 | controller | Monday 01 December 2025  08:26:43 +0000 (0:00:00.196)       0:05:21.869 *******
2025-12-01 08:26:43.778751 | controller | changed: [instance]
2025-12-01 08:26:43.778779 | controller |
2025-12-01 08:26:43.778997 | controller | TASK [libvirt_manager : Check if KVM is enabled] *******************************
2025-12-01 08:26:43.974409 | controller | Monday 01 December 2025  08:26:43 +0000 (0:00:00.191)       0:05:22.060 *******
2025-12-01 08:26:43.974455 | controller | ok: [instance]
2025-12-01 08:26:44.008568 | controller |
2025-12-01 08:26:44.008596 | controller | TASK [libvirt_manager : Fail if KVM not enabled] *******************************
2025-12-01 08:26:44.008606 | controller | Monday 01 December 2025  08:26:43 +0000 (0:00:00.195)       0:05:22.256 *******
2025-12-01 08:26:44.008640 | controller | skipping: [instance]
2025-12-01 08:26:44.040610 | controller |
2025-12-01 08:26:44.040654 | controller | TASK [libvirt_manager : Check if VMX virtualization] ***************************
2025-12-01 08:26:44.040664 | controller | Monday 01 December 2025  08:26:44 +0000 (0:00:00.034)       0:05:22.290 *******
2025-12-01 08:26:44.040675 | controller | skipping: [instance]
2025-12-01 08:26:44.076238 | controller |
2025-12-01 08:26:44.076256 | controller | TASK [libvirt_manager : Check if SVM virtualization] ***************************
2025-12-01 08:26:44.076264 | controller | Monday 01 December 2025  08:26:44 +0000 (0:00:00.032)       0:05:22.322 *******
2025-12-01 08:26:44.076273 | controller | ok: [instance]
2025-12-01 08:26:44.109251 | controller |
2025-12-01 08:26:44.109273 | controller | TASK [libvirt_manager : Enable VMX virtualization] *****************************
2025-12-01 08:26:44.109282 | controller | Monday 01 December 2025  08:26:44 +0000 (0:00:00.035)       0:05:22.358 *******
2025-12-01 08:26:44.109294 | controller | skipping: [instance]
2025-12-01 08:27:55.597033 | controller |
2025-12-01 08:27:55.597065 | controller | TASK [libvirt_manager : Install packages required for using KVM] ***************
2025-12-01 08:27:55.597080 | controller | Monday 01 December 2025  08:26:44 +0000 (0:00:00.033)       0:05:22.391 *******
2025-12-01 08:27:55.597091 | controller | changed: [instance]
2025-12-01 08:27:55.827116 | controller |
2025-12-01 08:27:55.827146 | controller | TASK [libvirt_manager : Create rules.d polkit folder] **************************
2025-12-01 08:27:55.827155 | controller | Monday 01 December 2025  08:27:55 +0000 (0:01:11.487)       0:06:33.878 *******
2025-12-01 08:27:55.827165 | controller | changed: [instance]
2025-12-01 08:27:56.226695 | controller |
2025-12-01 08:27:56.226729 | controller | TASK [libvirt_manager : Enable UNIX socket PolicyKit auth] *********************
2025-12-01 08:27:56.226738 | controller | Monday 01 December 2025  08:27:55 +0000 (0:00:00.229)       0:06:34.108 *******
2025-12-01 08:27:56.226749 | controller | changed: [instance]
2025-12-01 08:27:56.835418 | controller |
2025-12-01 08:27:56.835448 | controller | TASK [libvirt_manager : Restart service polkit service] ************************
2025-12-01 08:27:56.835456 | controller | Monday 01 December 2025  08:27:56 +0000 (0:00:00.399)       0:06:34.508 *******
2025-12-01 08:27:56.835466 | controller | changed: [instance]
2025-12-01 08:27:57.056549 | controller |
2025-12-01 08:27:57.056578 | controller | TASK [libvirt_manager : Remove directory for service override] *****************
2025-12-01 08:27:57.056586 | controller | Monday 01 December 2025  08:27:56 +0000 (0:00:00.608)       0:06:35.117 *******
2025-12-01 08:27:57.056597 | controller | ok: [instance]
2025-12-01 08:27:57.564334 | controller |
2025-12-01 08:27:57.564365 | controller | TASK [libvirt_manager : Reload systemctl and start/enable virtproxyd.service] ***
2025-12-01 08:27:57.564373 | controller | Monday 01 December 2025  08:27:57 +0000 (0:00:00.221)       0:06:35.338 *******
2025-12-01 08:27:57.564384 | controller | ok: [instance]
2025-12-01 08:27:57.564403 | controller |
2025-12-01 08:27:57.564596 | controller | TASK [libvirt_manager : Ensure firewalld service is up] ************************
2025-12-01 08:27:58.436009 | controller | Monday 01 December 2025  08:27:57 +0000 (0:00:00.507)       0:06:35.846 *******
2025-12-01 08:27:58.436045 | controller | changed: [instance]
2025-12-01 08:27:58.643842 | controller |
2025-12-01 08:27:58.643874 | controller | TASK [libvirt_manager : Ensure libvirt modular driver daemons are started.] ****
2025-12-01 08:27:58.643882 | controller | Monday 01 December 2025  08:27:58 +0000 (0:00:00.871)       0:06:36.717 *******
2025-12-01 08:27:58.643893 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=qemu)
2025-12-01 08:27:59.004860 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=interface)
2025-12-01 08:27:59.004890 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=network)
2025-12-01 08:27:59.004898 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nodedev)
2025-12-01 08:27:59.004904 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nwfilter)
2025-12-01 08:27:59.004909 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=secret)
2025-12-01 08:27:59.004915 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=storage)
2025-12-01 08:27:59.004921 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=proxy)
2025-12-01 08:27:59.004926 | controller |
2025-12-01 08:27:59.004932 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for qemu.] *********
2025-12-01 08:27:59.004938 | controller | Monday 01 December 2025  08:27:58 +0000 (0:00:00.207)       0:06:36.924 *******
2025-12-01 08:27:59.004948 | controller | changed: [instance]
2025-12-01 08:27:59.365998 | controller |
2025-12-01 08:27:59.366027 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for qemu.] **********
2025-12-01 08:27:59.366041 | controller | Monday 01 December 2025  08:27:59 +0000 (0:00:00.361)       0:06:37.286 *******
2025-12-01 08:27:59.366057 | controller | changed: [instance]
2025-12-01 08:27:59.722505 | controller |
2025-12-01 08:27:59.722534 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for qemu.] **************
2025-12-01 08:27:59.722542 | controller | Monday 01 December 2025  08:27:59 +0000 (0:00:00.360)       0:06:37.647 *******
2025-12-01 08:27:59.722553 | controller | changed: [instance]
2025-12-01 08:28:00.076555 | controller |
2025-12-01 08:28:00.076585 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for interface.] ****
2025-12-01 08:28:00.076593 | controller | Monday 01 December 2025  08:27:59 +0000 (0:00:00.356)       0:06:38.004 *******
2025-12-01 08:28:00.076603 | controller | changed: [instance]
2025-12-01 08:28:00.440796 | controller |
2025-12-01 08:28:00.440824 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for interface.] *****
2025-12-01 08:28:00.440831 | controller | Monday 01 December 2025  08:28:00 +0000 (0:00:00.354)       0:06:38.358 *******
2025-12-01 08:28:00.440841 | controller | changed: [instance]
2025-12-01 08:28:00.819264 | controller |
2025-12-01 08:28:00.819299 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for interface.] *********
2025-12-01 08:28:00.819310 | controller | Monday 01 December 2025  08:28:00 +0000 (0:00:00.364)       0:06:38.722 *******
2025-12-01 08:28:00.819322 | controller | changed: [instance]
2025-12-01 08:28:01.192886 | controller |
2025-12-01 08:28:01.192916 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for network.] ******
2025-12-01 08:28:01.192924 | controller | Monday 01 December 2025  08:28:00 +0000 (0:00:00.378)       0:06:39.101 *******
2025-12-01 08:28:01.192935 | controller | changed: [instance]
2025-12-01 08:28:01.549722 | controller |
2025-12-01 08:28:01.549757 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for network.] *******
2025-12-01 08:28:01.549768 | controller | Monday 01 December 2025  08:28:01 +0000 (0:00:00.373)       0:06:39.474 *******
2025-12-01 08:28:01.549781 | controller | changed: [instance]
2025-12-01 08:28:01.549815 | controller |
2025-12-01 08:28:01.550102 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for network.] ***********
2025-12-01 08:28:01.550231 | controller | Monday 01 December 2025  08:28:01 +0000 (0:00:00.357)       0:06:39.831 *******
2025-12-01 08:28:01.901562 | controller | changed: [instance]
2025-12-01 08:28:02.263553 | controller |
2025-12-01 08:28:02.263583 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nodedev.] ******
2025-12-01 08:28:02.263591 | controller | Monday 01 December 2025  08:28:01 +0000 (0:00:00.351)       0:06:40.183 *******
2025-12-01 08:28:02.263601 | controller | changed: [instance]
2025-12-01 08:28:02.621714 | controller |
2025-12-01 08:28:02.621746 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nodedev.] *******
2025-12-01 08:28:02.621756 | controller | Monday 01 December 2025  08:28:02 +0000 (0:00:00.361)       0:06:40.545 *******
2025-12-01 08:28:02.621768 | controller | changed: [instance]
2025-12-01 08:28:02.621797 | controller |
2025-12-01 08:28:02.621985 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nodedev.] ***********
2025-12-01 08:28:02.970708 | controller | Monday 01 December 2025  08:28:02 +0000 (0:00:00.358)       0:06:40.903 *******
2025-12-01 08:28:02.970742 | controller | changed: [instance]
2025-12-01 08:28:02.970773 | controller |
2025-12-01 08:28:02.970994 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nwfilter.] *****
2025-12-01 08:28:03.320600 | controller | Monday 01 December 2025  08:28:02 +0000 (0:00:00.348)       0:06:41.252 *******
2025-12-01 08:28:03.320647 | controller | changed: [instance]
2025-12-01 08:28:03.684708 | controller |
2025-12-01 08:28:03.684739 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nwfilter.] ******
2025-12-01 08:28:03.684747 | controller | Monday 01 December 2025  08:28:03 +0000 (0:00:00.349)       0:06:41.602 *******
2025-12-01 08:28:03.684758 | controller | changed: [instance]
2025-12-01 08:28:03.684783 | controller |
2025-12-01 08:28:03.685070 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nwfilter.] **********
2025-12-01 08:28:04.033731 | controller | Monday 01 December 2025  08:28:03 +0000 (0:00:00.364)       0:06:41.966 *******
2025-12-01 08:28:04.033769 | controller | changed: [instance]
2025-12-01 08:28:04.033780 | controller |
2025-12-01 08:28:04.033788 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for secret.] *******
2025-12-01 08:28:04.033805 | controller | Monday 01 December 2025  08:28:04 +0000 (0:00:00.348)       0:06:42.315 *******
2025-12-01 08:28:04.386082 | controller | changed: [instance]
2025-12-01 08:28:04.731355 | controller |
2025-12-01 08:28:04.731384 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for secret.] ********
2025-12-01 08:28:04.731393 | controller | Monday 01 December 2025  08:28:04 +0000 (0:00:00.352)       0:06:42.667 *******
2025-12-01 08:28:04.731411 | controller | changed: [instance]
2025-12-01 08:28:05.079468 | controller |
2025-12-01 08:28:05.079497 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for secret.] ************
2025-12-01 08:28:05.079506 | controller | Monday 01 December 2025  08:28:04 +0000 (0:00:00.345)       0:06:43.013 *******
2025-12-01 08:28:05.079516 | controller | changed: [instance]
2025-12-01 08:28:05.425287 | controller |
2025-12-01 08:28:05.425317 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for storage.] ******
2025-12-01 08:28:05.425326 | controller | Monday 01 December 2025  08:28:05 +0000 (0:00:00.348)       0:06:43.361 *******
2025-12-01 08:28:05.425337 | controller | changed: [instance]
2025-12-01 08:28:05.780669 | controller |
2025-12-01 08:28:05.780702 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for storage.] *******
2025-12-01 08:28:05.780712 | controller | Monday 01 December 2025  08:28:05 +0000 (0:00:00.345)       0:06:43.707 *******
2025-12-01 08:28:05.780724 | controller | changed: [instance]
2025-12-01 08:28:06.130184 | controller |
2025-12-01 08:28:06.130213 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for storage.] ***********
2025-12-01 08:28:06.130221 | controller | Monday 01 December 2025  08:28:05 +0000 (0:00:00.355)       0:06:44.062 *******
2025-12-01 08:28:06.130231 | controller | changed: [instance]
2025-12-01 08:28:06.485528 | controller |
2025-12-01 08:28:06.485557 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for proxy.] ********
2025-12-01 08:28:06.485565 | controller | Monday 01 December 2025  08:28:06 +0000 (0:00:00.349)       0:06:44.412 *******
2025-12-01 08:28:06.485576 | controller | changed: [instance]
2025-12-01 08:28:06.832162 | controller |
2025-12-01 08:28:06.832191 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for proxy.] *********
2025-12-01 08:28:06.832200 | controller | Monday 01 December 2025  08:28:06 +0000 (0:00:00.355)       0:06:44.767 *******
2025-12-01 08:28:06.832210 | controller | changed: [instance]
2025-12-01 08:28:07.181760 | controller |
2025-12-01 08:28:07.181792 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for proxy.] *************
2025-12-01 08:28:07.181802 | controller | Monday 01 December 2025  08:28:06 +0000 (0:00:00.346)       0:06:45.114 *******
2025-12-01 08:28:07.181814 | controller | changed: [instance]
2025-12-01 08:28:07.181837 | controller |
2025-12-01 08:28:07.181845 | controller | TASK [libvirt_manager : Manage service for older releases] *********************
2025-12-01 08:28:07.181854 | controller | Monday 01 December 2025  08:28:07 +0000 (0:00:00.349)       0:06:45.463 *******
2025-12-01 08:28:07.228019 | controller | skipping: [instance]
2025-12-01 08:28:07.536511 | controller |
2025-12-01 08:28:07.536539 | controller | TASK [libvirt_manager : Get libvirt group users] *******************************
2025-12-01 08:28:07.536547 | controller | Monday 01 December 2025  08:28:07 +0000 (0:00:00.046)       0:06:45.509 *******
2025-12-01 08:28:07.536557 | controller | ok: [instance]
2025-12-01 08:28:07.942703 | controller |
2025-12-01 08:28:07.942732 | controller | TASK [libvirt_manager : Add user if not exists in libvirt group] ***************
2025-12-01 08:28:07.942739 | controller | Monday 01 December 2025  08:28:07 +0000 (0:00:00.308)       0:06:45.818 *******
2025-12-01 08:28:07.942749 | controller | changed: [instance]
2025-12-01 08:28:08.262082 | controller |
2025-12-01 08:28:08.262112 | controller | TASK [libvirt_manager : Allow QEMU on home directory for the storage access] ***
2025-12-01 08:28:08.262122 | controller | Monday 01 December 2025  08:28:07 +0000 (0:00:00.406)       0:06:46.224 *******
2025-12-01 08:28:08.262131 | controller | changed: [instance]
2025-12-01 08:28:08.471591 | controller |
2025-12-01 08:28:08.471642 | controller | TASK [libvirt_manager : Inject system connection parameters in bashrc] *********
2025-12-01 08:28:08.471655 | controller | Monday 01 December 2025  08:28:08 +0000 (0:00:00.319)       0:06:46.544 *******
2025-12-01 08:28:08.471668 | controller | [WARNING]: Reset is not implemented for this connection
2025-12-01 08:28:08.509757 | controller | changed: [instance]
2025-12-01 08:28:08.509812 | controller |
2025-12-01 08:28:08.509822 | controller | TASK [libvirt_manager : Ensure we reload user env] *****************************
2025-12-01 08:28:08.509832 | controller | Monday 01 December 2025  08:28:08 +0000 (0:00:00.190)       0:06:46.734 *******
2025-12-01 08:28:08.510185 | controller |
2025-12-01 08:28:09.508731 | controller | TASK [libvirt_manager : Deactivate default] ************************************
2025-12-01 08:28:09.508766 | controller | Monday 01 December 2025  08:28:08 +0000 (0:00:00.057)       0:06:46.791 *******
2025-12-01 08:28:09.508781 | controller | ok: [instance]
2025-12-01 08:28:09.508811 | controller |
2025-12-01 08:28:09.508822 | controller | TASK [libvirt_manager : Removing default] **************************************
2025-12-01 08:28:09.508850 | controller | Monday 01 December 2025  08:28:09 +0000 (0:00:00.998)       0:06:47.790 *******
2025-12-01 08:28:09.759731 | controller | ok: [instance]
2025-12-01 08:28:09.759764 | controller |
2025-12-01 08:28:09.759773 | controller | TASK [Ensure we use dnsmasq for NetworkManager resolver] ***********************
2025-12-01 08:28:09.759789 | controller | Monday 01 December 2025  08:28:09 +0000 (0:00:00.250)       0:06:48.041 *******
2025-12-01 08:28:10.156224 | controller | changed: [instance]
2025-12-01 08:28:10.534041 | controller |
2025-12-01 08:28:10.534070 | controller | TASK [Ensure we delegate .utility zone to our own instance] ********************
2025-12-01 08:28:10.534078 | controller | Monday 01 December 2025  08:28:10 +0000 (0:00:00.396)       0:06:48.438 *******
2025-12-01 08:28:10.534088 | controller | changed: [instance]
2025-12-01 08:28:10.992483 | controller |
2025-12-01 08:28:10.992513 | controller | TASK [Restart NetworkManager] **************************************************
2025-12-01 08:28:10.992521 | controller | Monday 01 December 2025  08:28:10 +0000 (0:00:00.377)       0:06:48.815 *******
2025-12-01 08:28:10.992531 | controller | changed: [instance]
2025-12-01 08:28:11.255721 | controller |
2025-12-01 08:28:11.255755 | controller | TASK [Ensure ocp_volumes exists] ***********************************************
2025-12-01 08:28:11.255765 | controller | Monday 01 December 2025  08:28:10 +0000 (0:00:00.458)       0:06:49.274 *******
2025-12-01 08:28:11.255777 | controller | changed: [instance]
2025-12-01 08:28:11.255806 | controller |
2025-12-01 08:28:11.255814 | controller | PLAY RECAP *********************************************************************
2025-12-01 08:28:11.255821 | controller | instance                   : ok=115  changed=56   unreachable=0    failed=0    skipped=84   rescued=0    ignored=0
2025-12-01 08:28:11.255828 | controller |
2025-12-01 08:28:11.255835 | controller | Monday 01 December 2025  08:28:11 +0000 (0:00:00.262)       0:06:49.536 *******
2025-12-01 08:28:11.255842 | controller | ===============================================================================
2025-12-01 08:28:11.255848 | controller | test_deps : Install selinux python libs ------------------------------- 271.34s
2025-12-01 08:28:11.255855 | controller | libvirt_manager : Install packages required for using KVM -------------- 71.49s
2025-12-01 08:28:11.255862 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.83s
2025-12-01 08:28:11.255868 | controller | ci_setup : Install openshift client ------------------------------------- 6.05s
2025-12-01 08:28:11.255875 | controller | dnsmasq : Install needed packages --------------------------------------- 3.77s
2025-12-01 08:28:11.255882 | controller | ci_setup : Install needed packages -------------------------------------- 3.48s
2025-12-01 08:28:11.255888 | controller | libvirt_manager : Populate service facts -------------------------------- 1.77s
2025-12-01 08:28:11.255897 | controller | libvirt_manager : Remove workload and volumes --------------------------- 1.34s
2025-12-01 08:28:11.255924 | controller | dnsmasq : Ensure SELinux knows about the new files and directories ------ 1.30s
2025-12-01 08:28:11.256879 | controller | test_deps : Install python yaml libs ------------------------------------ 1.25s
2025-12-01 08:28:11.256915 | controller | podman : Ensure podman is installed ------------------------------------- 1.21s
2025-12-01 08:28:11.323999 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.07s
2025-12-01 08:28:11.324033 | controller | libvirt_manager : Deactivate default ------------------------------------ 1.00s
2025-12-01 08:28:11.324041 | controller | ci_setup : Manage directories ------------------------------------------- 0.98s
2025-12-01 08:28:11.324051 | controller | libvirt_manager : Ensure firewalld service is up ------------------------ 0.87s
2025-12-01 08:28:11.324057 | controller | Gathering Facts --------------------------------------------------------- 0.83s
2025-12-01 08:28:11.324062 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.80s
2025-12-01 08:28:11.324067 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.75s
2025-12-01 08:28:11.324073 | controller | libvirt_manager : Get installed packages list --------------------------- 0.72s
2025-12-01 08:28:11.324078 | controller | virtualbmc : Remove vbmc files ------------------------------------------ 0.71s
2025-12-01 08:28:11.324088 | controller | INFO     Running boot_order > converge
2025-12-01 08:28:11.797793 | controller |
2025-12-01 08:28:12.703734 | controller | PLAY [Test boot_order configuration] *******************************************
2025-12-01 08:28:12.703768 | controller |
2025-12-01 08:28:12.703783 | controller | TASK [Gathering Facts] *********************************************************
2025-12-01 08:28:12.703790 | controller | Monday 01 December 2025  08:28:11 +0000 (0:00:00.039)       0:00:00.039 *******
2025-12-01 08:28:12.703800 | controller | ok: [instance]
2025-12-01 08:28:12.704053 | controller |
2025-12-01 08:28:12.704068 | controller | TASK [Load networking definition] **********************************************
2025-12-01 08:28:12.745778 | controller | Monday 01 December 2025  08:28:12 +0000 (0:00:00.906)       0:00:00.946 *******
2025-12-01 08:28:12.745821 | controller | ok: [instance]
2025-12-01 08:28:12.745858 | controller |
2025-12-01 08:28:12.745870 | controller | TASK [libvirt_manager : Import layout generator if needed] *********************
2025-12-01 08:28:12.745990 | controller | Monday 01 December 2025  08:28:12 +0000 (0:00:00.042)       0:00:00.988 *******
2025-12-01 08:28:12.820341 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/generate_layout.yml for instance
2025-12-01 08:28:12.975733 | controller |
2025-12-01 08:28:12.975772 | controller | TASK [libvirt_manager : Chose right parameter for layout definition] ***********
2025-12-01 08:28:12.975784 | controller | Monday 01 December 2025  08:28:12 +0000 (0:00:00.074)       0:00:01.062 *******
2025-12-01 08:28:12.975797 | controller | ok: [instance]
2025-12-01 08:28:12.975829 | controller |
2025-12-01 08:28:12.975840 | controller | TASK [libvirt_manager : Patch the layout if needed] ****************************
2025-12-01 08:28:12.975944 | controller | Monday 01 December 2025  08:28:12 +0000 (0:00:00.155)       0:00:01.218 *******
2025-12-01 08:28:13.087722 | controller | skipping: [instance]
2025-12-01 08:28:13.088021 | controller |
2025-12-01 08:28:13.606325 | controller | TASK [libvirt_manager : Enable forwarding in the libvirt zone] *****************
2025-12-01 08:28:13.606354 | controller | Monday 01 December 2025  08:28:13 +0000 (0:00:00.111)       0:00:01.330 *******
2025-12-01 08:28:13.606366 | controller | changed: [instance]
2025-12-01 08:28:14.211028 | controller |
2025-12-01 08:28:14.211059 | controller | TASK [libvirt_manager : Enable masquerading for public traffic] ****************
2025-12-01 08:28:14.211068 | controller | Monday 01 December 2025  08:28:13 +0000 (0:00:00.518)       0:00:01.848 *******
2025-12-01 08:28:14.211079 | controller | changed: [instance]
2025-12-01 08:28:15.172727 | controller |
2025-12-01 08:28:15.172763 | controller | TASK [libvirt_manager : Ensure firewalld is restarts] **************************
2025-12-01 08:28:15.172774 | controller | Monday 01 December 2025  08:28:14 +0000 (0:00:00.527)       0:00:02.376 *******
2025-12-01 08:28:15.172782 | controller |
2025-12-01 08:28:15.172790 | controller | RUNNING HANDLER [libvirt_manager : Restart firewalld] **************************
2025-12-01 08:28:15.172797 | controller | Monday 01 December 2025  08:28:14 +0000 (0:00:00.076)       0:00:02.452 *******
2025-12-01 08:28:15.172810 | controller | changed: [instance]
2025-12-01 08:28:15.172843 | controller |
2025-12-01 08:28:15.173089 | controller | TASK [Deploy virtualbmc] *******************************************************
2025-12-01 08:28:15.226149 | controller | Monday 01 December 2025  08:28:15 +0000 (0:00:00.962)       0:00:03.415 *******
2025-12-01 08:28:15.226182 | controller | skipping: [instance]
2025-12-01 08:28:15.301393 | controller |
2025-12-01 08:28:15.301414 | controller | TASK [libvirt_manager : Let the project know we have vbmc available] ***********
2025-12-01 08:28:15.301428 | controller | Monday 01 December 2025  08:28:15 +0000 (0:00:00.053)       0:00:03.468 *******
2025-12-01 08:28:15.301437 | controller | skipping: [instance]
2025-12-01 08:28:15.951705 | controller |
2025-12-01 08:28:15.951734 | controller | TASK [libvirt_manager : Create needed workload directory] **********************
2025-12-01 08:28:15.951743 | controller | Monday 01 December 2025  08:28:15 +0000 (0:00:00.075)       0:00:03.543 *******
2025-12-01 08:28:15.951753 | controller | changed: [instance] => (item=workload)
2025-12-01 08:28:15.951777 | controller | changed: [instance] => (item=reproducer-inventory)
2025-12-01 08:28:15.951785 | controller | changed: [instance] => (item=volumes)
2025-12-01 08:28:15.952118 | controller |
2025-12-01 08:28:16.453727 | controller | TASK [libvirt_manager : Allow QEMU on workload directory] **********************
2025-12-01 08:28:16.453761 | controller | Monday 01 December 2025  08:28:15 +0000 (0:00:00.650)       0:00:04.194 *******
2025-12-01 08:28:16.453776 | controller | changed: [instance] => (item=workload)
2025-12-01 08:28:16.453809 | controller | changed: [instance] => (item=volumes)
2025-12-01 08:28:16.454210 | controller |
2025-12-01 08:28:16.603472 | controller | TASK [libvirt_manager : Generate networking data] ******************************
2025-12-01 08:28:16.603495 | controller | Monday 01 December 2025  08:28:16 +0000 (0:00:00.502)       0:00:04.696 *******
2025-12-01 08:28:16.603507 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/generate_networking_data.yml for instance
2025-12-01 08:28:16.644515 | controller |
2025-12-01 08:28:16.644535 | controller | TASK [libvirt_manager : Ensure fact is existing, and empty] ********************
2025-12-01 08:28:16.644542 | controller | Monday 01 December 2025  08:28:16 +0000 (0:00:00.149)       0:00:04.846 *******
2025-12-01 08:28:16.644551 | controller | ok: [instance]
2025-12-01 08:28:16.928726 | controller |
2025-12-01 08:28:16.928756 | controller | TASK [libvirt_manager : Check if we get an existing interfaces-info.yml] *******
2025-12-01 08:28:16.928770 | controller | Monday 01 December 2025  08:28:16 +0000 (0:00:00.040)       0:00:04.886 *******
2025-12-01 08:28:16.928780 | controller | ok: [instance]
2025-12-01 08:28:16.929155 | controller |
2025-12-01 08:28:16.980745 | controller | TASK [libvirt_manager : Load file if exists] ***********************************
2025-12-01 08:28:16.980775 | controller | Monday 01 December 2025  08:28:16 +0000 (0:00:00.284)       0:00:05.171 *******
2025-12-01 08:28:16.980791 | controller | skipping: [instance]
2025-12-01 08:28:17.031107 | controller |
2025-12-01 08:28:17.031131 | controller | TASK [libvirt_manager : Update fact with existing content] *********************
2025-12-01 08:28:17.031141 | controller | Monday 01 December 2025  08:28:16 +0000 (0:00:00.051)       0:00:05.223 *******
2025-12-01 08:28:17.031153 | controller | skipping: [instance]
2025-12-01 08:28:17.110487 | controller |
2025-12-01 08:28:17.110526 | controller | TASK [libvirt_manager : Generate VM list] **************************************
2025-12-01 08:28:17.110534 | controller | Monday 01 December 2025  08:28:17 +0000 (0:00:00.050)       0:00:05.273 *******
2025-12-01 08:28:17.110545 | controller | ok: [instance]
2025-12-01 08:28:17.311734 | controller |
2025-12-01 08:28:17.311768 | controller | TASK [libvirt_manager : Generate/update mapping all nodes] *********************
2025-12-01 08:28:17.311776 | controller | Monday 01 December 2025  08:28:17 +0000 (0:00:00.079)       0:00:05.352 *******
2025-12-01 08:28:17.311787 | controller | ok: [instance] => (item={'key': 'disk-first-0', 'value': 'disk_first'})
2025-12-01 08:28:17.311945 | controller | ok: [instance] => (item={'key': 'net-first-0', 'value': 'net_first'})
2025-12-01 08:28:17.312091 | controller | ok: [instance] => (item={'key': 'net-only-0', 'value': 'net_only'})
2025-12-01 08:28:17.312229 | controller | ok: [instance] => (item={'key': 'no-boot-order-0', 'value': 'no_boot_order'})
2025-12-01 08:28:17.312371 | controller |
2025-12-01 08:28:17.312507 | controller | TASK [libvirt_manager : Append baremetal nodes if needed] **********************
2025-12-01 08:28:17.312661 | controller | Monday 01 December 2025  08:28:17 +0000 (0:00:00.201)       0:00:05.554 *******
2025-12-01 08:28:17.367139 | controller | skipping: [instance]
2025-12-01 08:28:17.367312 | controller |
2025-12-01 08:28:17.367462 | controller | TASK [libvirt_manager : Dump MAC mapping] **************************************
2025-12-01 08:28:17.367599 | controller | Monday 01 December 2025  08:28:17 +0000 (0:00:00.055)       0:00:05.610 *******
2025-12-01 08:28:17.848752 | controller | changed: [instance]
2025-12-01 08:28:17.848970 | controller |
2025-12-01 08:28:17.849002 | controller | TASK [libvirt_manager : Add libvirt hosts into inventory and dump inventory file] ***
2025-12-01 08:28:17.984648 | controller | Monday 01 December 2025  08:28:17 +0000 (0:00:00.481)       0:00:06.091 *******
2025-12-01 08:28:17.984678 | 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)
2025-12-01 08:28:18.090744 | 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)
2025-12-01 08:28:18.090781 | 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)
2025-12-01 08:28:18.090798 | 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)
2025-12-01 08:28:18.090807 | controller |
2025-12-01 08:28:18.090815 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2025-12-01 08:28:18.090822 | controller | Monday 01 December 2025  08:28:17 +0000 (0:00:00.135)       0:00:06.226 *******
2025-12-01 08:28:18.090834 | controller | changed: [instance]
2025-12-01 08:28:18.090877 | controller |
2025-12-01 08:28:18.090902 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2025-12-01 08:28:18.090916 | controller | Monday 01 December 2025  08:28:18 +0000 (0:00:00.106)       0:00:06.333 *******
2025-12-01 08:28:18.403630 | controller | changed: [instance]
2025-12-01 08:28:18.653441 | controller |
2025-12-01 08:28:18.653475 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2025-12-01 08:28:18.653483 | controller | Monday 01 December 2025  08:28:18 +0000 (0:00:00.312)       0:00:06.646 *******
2025-12-01 08:28:18.653493 | controller | changed: [instance]
2025-12-01 08:28:18.734562 | controller |
2025-12-01 08:28:18.734589 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2025-12-01 08:28:18.734597 | controller | Monday 01 December 2025  08:28:18 +0000 (0:00:00.249)       0:00:06.895 *******
2025-12-01 08:28:18.734607 | controller | changed: [instance]
2025-12-01 08:28:18.991629 | controller |
2025-12-01 08:28:18.991664 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2025-12-01 08:28:18.991676 | controller | Monday 01 December 2025  08:28:18 +0000 (0:00:00.081)       0:00:06.977 *******
2025-12-01 08:28:18.991689 | controller | changed: [instance]
2025-12-01 08:28:19.233747 | controller |
2025-12-01 08:28:19.233787 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2025-12-01 08:28:19.233799 | controller | Monday 01 December 2025  08:28:18 +0000 (0:00:00.256)       0:00:07.233 *******
2025-12-01 08:28:19.233812 | controller | changed: [instance]
2025-12-01 08:28:19.233849 | controller |
2025-12-01 08:28:19.234288 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2025-12-01 08:28:19.315648 | controller | Monday 01 December 2025  08:28:19 +0000 (0:00:00.242)       0:00:07.476 *******
2025-12-01 08:28:19.315671 | controller | changed: [instance]
2025-12-01 08:28:19.547143 | controller |
2025-12-01 08:28:19.547174 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2025-12-01 08:28:19.547183 | controller | Monday 01 December 2025  08:28:19 +0000 (0:00:00.081)       0:00:07.558 *******
2025-12-01 08:28:19.547198 | controller | changed: [instance]
2025-12-01 08:28:19.790731 | controller |
2025-12-01 08:28:19.790768 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2025-12-01 08:28:19.790779 | controller | Monday 01 December 2025  08:28:19 +0000 (0:00:00.231)       0:00:07.789 *******
2025-12-01 08:28:19.790793 | controller | changed: [instance]
2025-12-01 08:28:19.790827 | controller |
2025-12-01 08:28:19.791086 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2025-12-01 08:28:19.900101 | controller | Monday 01 December 2025  08:28:19 +0000 (0:00:00.243)       0:00:08.033 *******
2025-12-01 08:28:19.900138 | controller | changed: [instance]
2025-12-01 08:28:20.128758 | controller |
2025-12-01 08:28:20.128797 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2025-12-01 08:28:20.128808 | controller | Monday 01 December 2025  08:28:19 +0000 (0:00:00.109)       0:00:08.142 *******
2025-12-01 08:28:20.128822 | controller | changed: [instance]
2025-12-01 08:28:20.128854 | controller |
2025-12-01 08:28:20.128865 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2025-12-01 08:28:20.129034 | controller | Monday 01 December 2025  08:28:20 +0000 (0:00:00.228)       0:00:08.371 *******
2025-12-01 08:28:20.370529 | controller | changed: [instance]
2025-12-01 08:28:20.397920 | controller |
2025-12-01 08:28:20.397959 | controller | TASK [libvirt_manager : Convert ini inventory file into yaml] ******************
2025-12-01 08:28:20.397968 | controller | Monday 01 December 2025  08:28:20 +0000 (0:00:00.241)       0:00:08.612 *******
2025-12-01 08:28:20.397979 | controller | fatal: [instance]: FAILED! =>
2025-12-01 08:28:20.398348 | controller |   msg: |-
2025-12-01 08:28:20.398377 | controller |     The conditional check '(_cifmw_libvirt_manager_layout.vms[_vm_type].amount | default(1) | int) > 0' failed. The error was: error while evaluating conditional ((_cifmw_libvirt_manager_layout.vms[_vm_type].amount | default(1) | int) > 0): {{ cifmw_libvirt_manager_all_vms[item.key] | default('none') }}: 'item' is undefined. 'item' is undefined. {{ cifmw_libvirt_manager_all_vms[item.key] | default('none') }}: 'item' is undefined. 'item' is undefined
2025-12-01 08:28:20.398386 | controller |
2025-12-01 08:28:20.398393 | controller |     The error appears to be in '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/generate_networking_data.yml': line 136, column 7, but may
2025-12-01 08:28:20.398404 | controller |     be elsewhere in the file depending on the exact syntax problem.
2025-12-01 08:28:20.398428 | controller |
2025-12-01 08:28:20.398705 | controller |     The offending line appears to be:
2025-12-01 08:28:20.398924 | controller |
2025-12-01 08:28:20.398943 | controller |
2025-12-01 08:28:20.398954 | controller |         - name: Convert ini inventory file into yaml
2025-12-01 08:28:20.402216 | controller |           ^ here
2025-12-01 08:28:20.402237 | controller |
2025-12-01 08:28:20.463807 | controller | PLAY RECAP *********************************************************************
2025-12-01 08:28:20.463828 | controller | instance                   : ok=31   changed=18   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2025-12-01 08:28:20.463836 | controller |
2025-12-01 08:28:20.463842 | controller | Monday 01 December 2025  08:28:20 +0000 (0:00:00.029)       0:00:08.642 *******
2025-12-01 08:28:20.463848 | controller | ===============================================================================
2025-12-01 08:28:20.463854 | controller | libvirt_manager : Restart firewalld ------------------------------------- 0.96s
2025-12-01 08:28:20.463859 | controller | Gathering Facts --------------------------------------------------------- 0.91s
2025-12-01 08:28:20.463864 | controller | libvirt_manager : Create needed workload directory ---------------------- 0.65s
2025-12-01 08:28:20.463870 | controller | libvirt_manager : Enable masquerading for public traffic ---------------- 0.53s
2025-12-01 08:28:20.463875 | controller | libvirt_manager : Enable forwarding in the libvirt zone ----------------- 0.52s
2025-12-01 08:28:20.463880 | controller | libvirt_manager : Allow QEMU on workload directory ---------------------- 0.50s
2025-12-01 08:28:20.463885 | controller | libvirt_manager : Dump MAC mapping -------------------------------------- 0.48s
2025-12-01 08:28:20.463891 | controller | libvirt_manager : Ensure group section exists --------------------------- 0.31s
2025-12-01 08:28:20.463896 | controller | libvirt_manager : Check if we get an existing interfaces-info.yml ------- 0.28s
2025-12-01 08:28:20.463901 | controller | libvirt_manager : Ensure group section exists --------------------------- 0.26s
2025-12-01 08:28:20.463907 | controller | libvirt_manager : Append host under proper group ------------------------ 0.25s
2025-12-01 08:28:20.463912 | controller | libvirt_manager : Append host under proper group ------------------------ 0.24s
2025-12-01 08:28:20.463917 | controller | libvirt_manager : Append host under proper group ------------------------ 0.24s
2025-12-01 08:28:20.463922 | controller | libvirt_manager : Append host under proper group ------------------------ 0.24s
2025-12-01 08:28:20.463932 | controller | libvirt_manager : Ensure group section exists --------------------------- 0.23s
2025-12-01 08:28:20.463937 | controller | libvirt_manager : Ensure group section exists --------------------------- 0.23s
2025-12-01 08:28:20.463943 | controller | libvirt_manager : Generate/update mapping all nodes --------------------- 0.20s
2025-12-01 08:28:20.463948 | controller | libvirt_manager : Chose right parameter for layout definition ----------- 0.16s
2025-12-01 08:28:20.463957 | controller | libvirt_manager : Generate networking data ------------------------------ 0.15s
2025-12-01 08:28:20.463963 | controller | libvirt_manager : Add libvirt hosts into inventory and dump inventory file --- 0.14s
2025-12-01 08:28:20.463974 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/libvirt_manager/boot_order/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/molecule/boot_order/converge.yml']
2025-12-01 08:28:20.464445 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-12-01 08:28:20.473460 | controller | INFO     Running boot_order > cleanup
2025-12-01 08:28:20.994388 | controller |
2025-12-01 08:28:21.892155 | controller | PLAY [Cleanup] *****************************************************************
2025-12-01 08:28:21.892188 | controller |
2025-12-01 08:28:21.892197 | controller | TASK [Gathering Facts] *********************************************************
2025-12-01 08:28:21.892205 | controller | Monday 01 December 2025  08:28:20 +0000 (0:00:00.040)       0:00:00.040 *******
2025-12-01 08:28:21.892217 | controller | ok: [instance]
2025-12-01 08:28:22.224644 | controller |
2025-12-01 08:28:22.224678 | controller | TASK [Ensure we have the destination directory] ********************************
2025-12-01 08:28:22.224688 | controller | Monday 01 December 2025  08:28:21 +0000 (0:00:00.898)       0:00:00.938 *******
2025-12-01 08:28:22.224700 | controller | changed: [instance]
2025-12-01 08:28:23.640532 | controller |
2025-12-01 08:28:23.640563 | controller | TASK [Copy generated content to proper location] *******************************
2025-12-01 08:28:23.640571 | controller | Monday 01 December 2025  08:28:22 +0000 (0:00:00.332)       0:00:01.270 *******
2025-12-01 08:28:23.640581 | controller | changed: [instance] => (item=/opt/basedir/artifacts)
2025-12-01 08:28:24.431235 | controller | changed: [instance] => (item=/opt/basedir/logs)
2025-12-01 08:28:24.431269 | controller | ok: [instance] => (item=/opt/basedir/cifmw_config_drive)
2025-12-01 08:28:24.431277 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2025-12-01 08:28:24.431282 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.d)
2025-12-01 08:28:24.431288 | controller |
2025-12-01 08:28:24.431294 | controller | TASK [libvirt_manager : Get installed packages list] ***************************
2025-12-01 08:28:24.431312 | controller | Monday 01 December 2025  08:28:23 +0000 (0:00:01.415)       0:00:02.686 *******
2025-12-01 08:28:24.431323 | controller | ok: [instance]
2025-12-01 08:28:26.516632 | controller |
2025-12-01 08:28:26.516663 | controller | TASK [libvirt_manager : Populate service facts] ********************************
2025-12-01 08:28:26.516672 | controller | Monday 01 December 2025  08:28:24 +0000 (0:00:00.791)       0:00:03.477 *******
2025-12-01 08:28:26.516682 | controller | ok: [instance]
2025-12-01 08:28:27.395527 | controller |
2025-12-01 08:28:27.395556 | controller | TASK [libvirt_manager : Start virtqemud socket service] ************************
2025-12-01 08:28:27.395564 | controller | Monday 01 December 2025  08:28:26 +0000 (0:00:02.085)       0:00:05.562 *******
2025-12-01 08:28:27.395575 | controller | ok: [instance] => (item=virtqemud.service)
2025-12-01 08:28:27.448594 | controller | ok: [instance] => (item=virtqemud.socket)
2025-12-01 08:28:27.448640 | controller |
2025-12-01 08:28:27.448651 | controller | TASK [libvirt_manager : Set _is_deepscrub internal fact] ***********************
2025-12-01 08:28:27.448657 | controller | Monday 01 December 2025  08:28:27 +0000 (0:00:00.878)       0:00:06.441 *******
2025-12-01 08:28:27.448667 | controller | ok: [instance]
2025-12-01 08:28:27.831718 | controller |
2025-12-01 08:28:27.831762 | controller | TASK [libvirt_manager : List all of the existing virtual machines] *************
2025-12-01 08:28:27.831773 | controller | Monday 01 December 2025  08:28:27 +0000 (0:00:00.053)       0:00:06.494 *******
2025-12-01 08:28:27.831794 | controller | ok: [instance]
2025-12-01 08:28:28.256198 | controller |
2025-12-01 08:28:28.256239 | controller | TASK [libvirt_manager : Get pool configuration] ********************************
2025-12-01 08:28:28.256250 | controller | Monday 01 December 2025  08:28:27 +0000 (0:00:00.382)       0:00:06.877 *******
2025-12-01 08:28:28.256263 | controller | ok: [instance]
2025-12-01 08:28:28.337560 | controller |
2025-12-01 08:28:28.337590 | controller | TASK [libvirt_manager : Filter out target environment] *************************
2025-12-01 08:28:28.337601 | controller | Monday 01 December 2025  08:28:28 +0000 (0:00:00.424)       0:00:07.302 *******
2025-12-01 08:28:28.337635 | controller | ok: [instance]
2025-12-01 08:28:28.418719 | controller |
2025-12-01 08:28:28.418757 | controller | TASK [libvirt_manager : Expose cleanup list] ***********************************
2025-12-01 08:28:28.418768 | controller | Monday 01 December 2025  08:28:28 +0000 (0:00:00.081)       0:00:07.383 *******
2025-12-01 08:28:28.418781 | controller | ok: [instance] =>
2025-12-01 08:28:28.453719 | controller |   cleanup_vms: []
2025-12-01 08:28:28.453769 | controller |
2025-12-01 08:28:28.453779 | controller | TASK [libvirt_manager : Clean ssh known_hosts] *********************************
2025-12-01 08:28:28.453788 | controller | Monday 01 December 2025  08:28:28 +0000 (0:00:00.080)       0:00:07.464 *******
2025-12-01 08:28:28.453802 | controller | skipping: [instance]
2025-12-01 08:28:28.454044 | controller |
2025-12-01 08:28:28.488184 | controller | TASK [libvirt_manager : Destroy machine] ***************************************
2025-12-01 08:28:28.488202 | controller | Monday 01 December 2025  08:28:28 +0000 (0:00:00.035)       0:00:07.500 *******
2025-12-01 08:28:28.488211 | controller | skipping: [instance]
2025-12-01 08:28:28.522690 | controller |
2025-12-01 08:28:28.522732 | controller | TASK [libvirt_manager : Undefine machine] **************************************
2025-12-01 08:28:28.522750 | controller | Monday 01 December 2025  08:28:28 +0000 (0:00:00.034)       0:00:07.534 *******
2025-12-01 08:28:28.522765 | controller | skipping: [instance]
2025-12-01 08:28:28.556982 | controller |
2025-12-01 08:28:28.557014 | controller | TASK [libvirt_manager : (localhost) Clean ssh jumpers] *************************
2025-12-01 08:28:28.557025 | controller | Monday 01 December 2025  08:28:28 +0000 (0:00:00.034)       0:00:07.568 *******
2025-12-01 08:28:28.557038 | controller | skipping: [instance]
2025-12-01 08:28:28.557166 | controller |
2025-12-01 08:28:28.557421 | controller | TASK [libvirt_manager : (instance) Clean ssh jumpers] **************************
2025-12-01 08:28:28.557684 | controller | Monday 01 December 2025  08:28:28 +0000 (0:00:00.035)       0:00:07.603 *******
2025-12-01 08:28:28.590238 | controller | skipping: [instance]
2025-12-01 08:28:28.590360 | controller |
2025-12-01 08:28:28.590485 | controller | TASK [libvirt_manager : Get network list] **************************************
2025-12-01 08:28:28.590604 | controller | Monday 01 December 2025  08:28:28 +0000 (0:00:00.033)       0:00:07.636 *******
2025-12-01 08:28:28.972691 | controller | ok: [instance]
2025-12-01 08:28:29.064731 | controller |
2025-12-01 08:28:29.064768 | controller | TASK [libvirt_manager : Filter out target nets] ********************************
2025-12-01 08:28:29.064778 | controller | Monday 01 December 2025  08:28:28 +0000 (0:00:00.382)       0:00:08.018 *******
2025-12-01 08:28:29.064790 | controller | ok: [instance]
2025-12-01 08:28:29.143735 | controller |
2025-12-01 08:28:29.143759 | controller | TASK [libvirt_manager : Expose cleanup list] ***********************************
2025-12-01 08:28:29.143769 | controller | Monday 01 December 2025  08:28:29 +0000 (0:00:00.092)       0:00:08.110 *******
2025-12-01 08:28:29.143780 | controller | ok: [instance] =>
2025-12-01 08:28:29.144264 | controller |   cleanup_nets: []
2025-12-01 08:28:29.144289 | controller |
2025-12-01 08:28:29.177737 | controller | TASK [Remove dnsmasq network bits] *********************************************
2025-12-01 08:28:29.177762 | controller | Monday 01 December 2025  08:28:29 +0000 (0:00:00.079)       0:00:08.189 *******
2025-12-01 08:28:29.177775 | controller | skipping: [instance]
2025-12-01 08:28:29.177923 | controller |
2025-12-01 08:28:29.177935 | controller | TASK [libvirt_manager : Destroy networks] **************************************
2025-12-01 08:28:29.177950 | controller | Monday 01 December 2025  08:28:29 +0000 (0:00:00.033)       0:00:08.223 *******
2025-12-01 08:28:29.211163 | controller | skipping: [instance]
2025-12-01 08:28:29.211401 | controller |
2025-12-01 08:28:29.211502 | controller | TASK [libvirt_manager : Undefine networks] *************************************
2025-12-01 08:28:29.211645 | controller | Monday 01 December 2025  08:28:29 +0000 (0:00:00.033)       0:00:08.257 *******
2025-12-01 08:28:29.244975 | controller | skipping: [instance]
2025-12-01 08:28:29.245210 | controller |
2025-12-01 08:28:29.245465 | controller | TASK [libvirt_manager : Ensure no trace of networks lays in nmcli] *************
2025-12-01 08:28:29.245597 | controller | Monday 01 December 2025  08:28:29 +0000 (0:00:00.033)       0:00:08.291 *******
2025-12-01 08:28:29.278210 | controller | skipping: [instance]
2025-12-01 08:28:29.278448 | controller |
2025-12-01 08:28:29.278554 | controller | TASK [libvirt_manager : Find dummy interface connection files] *****************
2025-12-01 08:28:29.278735 | controller | Monday 01 December 2025  08:28:29 +0000 (0:00:00.033)       0:00:08.324 *******
2025-12-01 08:28:29.617739 | controller | ok: [instance]
2025-12-01 08:28:29.617798 | controller |
2025-12-01 08:28:29.617813 | controller | TASK [libvirt_manager : Remove dummy interface connections] ********************
2025-12-01 08:28:29.617941 | controller | Monday 01 December 2025  08:28:29 +0000 (0:00:00.339)       0:00:08.664 *******
2025-12-01 08:28:29.654418 | controller | skipping: [instance]
2025-12-01 08:28:29.688195 | controller |
2025-12-01 08:28:29.688218 | controller | TASK [libvirt_manager : Clean firewalld libvirt zone] **************************
2025-12-01 08:28:29.688226 | controller | Monday 01 December 2025  08:28:29 +0000 (0:00:00.036)       0:00:08.700 *******
2025-12-01 08:28:29.688236 | controller | skipping: [instance]
2025-12-01 08:28:30.272336 | controller |
2025-12-01 08:28:30.272366 | controller | TASK [libvirt_manager : Ensure firewalld is enabled and started] ***************
2025-12-01 08:28:30.272375 | controller | Monday 01 December 2025  08:28:29 +0000 (0:00:00.033)       0:00:08.734 *******
2025-12-01 08:28:30.272385 | controller | ok: [instance]
2025-12-01 08:28:30.780780 | controller |
2025-12-01 08:28:30.780814 | controller | TASK [libvirt_manager : Disable masquerade in firewalld default zone] **********
2025-12-01 08:28:30.780825 | controller | Monday 01 December 2025  08:28:30 +0000 (0:00:00.583)       0:00:09.318 *******
2025-12-01 08:28:30.780837 | controller | changed: [instance]
2025-12-01 08:28:30.780866 | controller |
2025-12-01 08:28:30.780876 | controller | TASK [libvirt_manager : Disable forward in firewalld libvirt zone] *************
2025-12-01 08:28:30.780975 | controller | Monday 01 December 2025  08:28:30 +0000 (0:00:00.508)       0:00:09.827 *******
2025-12-01 08:28:31.328759 | controller | changed: [instance]
2025-12-01 08:28:31.328957 | controller |
2025-12-01 08:28:31.328977 | controller | TASK [libvirt_manager : Remove cifmw storage pool] *****************************
2025-12-01 08:28:31.441979 | controller | Monday 01 December 2025  08:28:31 +0000 (0:00:00.547)       0:00:10.375 *******
2025-12-01 08:28:31.442002 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/storage_pool.yml for instance
2025-12-01 08:28:31.524747 | controller |
2025-12-01 08:28:31.524770 | controller | TASK [libvirt_manager : Ensure we have a known action] *************************
2025-12-01 08:28:31.524779 | controller | Monday 01 December 2025  08:28:31 +0000 (0:00:00.113)       0:00:10.488 *******
2025-12-01 08:28:31.524789 | controller | ok: [instance] => changed=false
2025-12-01 08:28:31.829751 | controller |   msg: All assertions passed
2025-12-01 08:28:31.829786 | controller |
2025-12-01 08:28:31.829797 | controller | TASK [libvirt_manager : Gather the status of cifmw-pool storage pool.] *********
2025-12-01 08:28:31.829806 | controller | Monday 01 December 2025  08:28:31 +0000 (0:00:00.082)       0:00:10.570 *******
2025-12-01 08:28:31.829818 | controller | changed: [instance]
2025-12-01 08:28:31.829892 | controller |
2025-12-01 08:28:31.829915 | controller | TASK [libvirt_manager : Create the storage pool] *******************************
2025-12-01 08:28:31.829930 | controller | Monday 01 December 2025  08:28:31 +0000 (0:00:00.305)       0:00:10.875 *******
2025-12-01 08:28:31.908584 | controller | skipping: [instance]
2025-12-01 08:28:32.015723 | controller |
2025-12-01 08:28:32.015750 | controller | TASK [libvirt_manager : Ensure the storage pool is started] ********************
2025-12-01 08:28:32.015760 | controller | Monday 01 December 2025  08:28:31 +0000 (0:00:00.078)       0:00:10.954 *******
2025-12-01 08:28:32.015771 | controller | skipping: [instance] => (item=pool-start)
2025-12-01 08:28:32.015801 | controller | skipping: [instance] => (item=pool-autostart)
2025-12-01 08:28:32.015816 | controller | skipping: [instance]
2025-12-01 08:28:32.016246 | controller |
2025-12-01 08:28:32.094754 | controller | TASK [libvirt_manager : Gather all the volumes in the pool to delete] **********
2025-12-01 08:28:32.094777 | controller | Monday 01 December 2025  08:28:32 +0000 (0:00:00.107)       0:00:11.062 *******
2025-12-01 08:28:32.094790 | controller | skipping: [instance]
2025-12-01 08:28:32.094963 | controller |
2025-12-01 08:28:32.094982 | controller | TASK [libvirt_manager : Remove the volumes from pool to delete] ****************
2025-12-01 08:28:32.173607 | controller | Monday 01 December 2025  08:28:32 +0000 (0:00:00.079)       0:00:11.141 *******
2025-12-01 08:28:32.173651 | controller | skipping: [instance]
2025-12-01 08:28:32.173805 | controller |
2025-12-01 08:28:32.173959 | controller | TASK [libvirt_manager : Delete storage pool] ***********************************
2025-12-01 08:28:32.174101 | controller | Monday 01 December 2025  08:28:32 +0000 (0:00:00.078)       0:00:11.220 *******
2025-12-01 08:28:32.282212 | controller | skipping: [instance] => (item=pool-destroy)
2025-12-01 08:28:32.282399 | controller | skipping: [instance] => (item=pool-undefine)
2025-12-01 08:28:32.282542 | controller | skipping: [instance]
2025-12-01 08:28:32.282697 | controller |
2025-12-01 08:28:32.282843 | controller | TASK [libvirt_manager : Refresh pools facts] ***********************************
2025-12-01 08:28:32.282984 | controller | Monday 01 December 2025  08:28:32 +0000 (0:00:00.108)       0:00:11.328 *******
2025-12-01 08:28:32.570640 | controller | ok: [instance]
2025-12-01 08:28:32.622752 | controller |
2025-12-01 08:28:32.622785 | controller | TASK [libvirt_manager : Remove overlay images from ocp_volume pools if exists] ***
2025-12-01 08:28:32.622795 | controller | Monday 01 December 2025  08:28:32 +0000 (0:00:00.287)       0:00:11.616 *******
2025-12-01 08:28:32.622808 | controller | skipping: [instance]
2025-12-01 08:28:32.622840 | controller |
2025-12-01 08:28:32.622848 | controller | TASK [libvirt_manager : Remove ocp_volumes storage pool] ***********************
2025-12-01 08:28:32.622857 | controller | Monday 01 December 2025  08:28:32 +0000 (0:00:00.052)       0:00:11.669 *******
2025-12-01 08:28:32.764439 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/storage_pool.yml for instance
2025-12-01 08:28:32.844784 | controller |
2025-12-01 08:28:32.844820 | controller | TASK [libvirt_manager : Ensure we have a known action] *************************
2025-12-01 08:28:32.844834 | controller | Monday 01 December 2025  08:28:32 +0000 (0:00:00.141)       0:00:11.810 *******
2025-12-01 08:28:32.844851 | controller | ok: [instance] => changed=false
2025-12-01 08:28:32.844934 | controller |   msg: All assertions passed
2025-12-01 08:28:32.844952 | controller |
2025-12-01 08:28:32.844962 | controller | TASK [libvirt_manager : Gather the status of cifmw-pool storage pool.] *********
2025-12-01 08:28:32.844976 | controller | Monday 01 December 2025  08:28:32 +0000 (0:00:00.080)       0:00:11.890 *******
2025-12-01 08:28:33.121707 | controller | changed: [instance]
2025-12-01 08:28:33.121994 | controller |
2025-12-01 08:28:33.200303 | controller | TASK [libvirt_manager : Create the storage pool] *******************************
2025-12-01 08:28:33.200345 | controller | Monday 01 December 2025  08:28:33 +0000 (0:00:00.277)       0:00:12.168 *******
2025-12-01 08:28:33.200361 | controller | skipping: [instance]
2025-12-01 08:28:33.307629 | controller |
2025-12-01 08:28:33.307655 | controller | TASK [libvirt_manager : Ensure the storage pool is started] ********************
2025-12-01 08:28:33.307664 | controller | Monday 01 December 2025  08:28:33 +0000 (0:00:00.078)       0:00:12.246 *******
2025-12-01 08:28:33.307675 | controller | skipping: [instance] => (item=pool-start)
2025-12-01 08:28:33.382096 | controller | skipping: [instance] => (item=pool-autostart)
2025-12-01 08:28:33.382119 | controller | skipping: [instance]
2025-12-01 08:28:33.382128 | controller |
2025-12-01 08:28:33.382135 | controller | TASK [libvirt_manager : Gather all the volumes in the pool to delete] **********
2025-12-01 08:28:33.382148 | controller | Monday 01 December 2025  08:28:33 +0000 (0:00:00.107)       0:00:12.353 *******
2025-12-01 08:28:33.382159 | controller | skipping: [instance]
2025-12-01 08:28:33.456050 | controller |
2025-12-01 08:28:33.456071 | controller | TASK [libvirt_manager : Remove the volumes from pool to delete] ****************
2025-12-01 08:28:33.456080 | controller | Monday 01 December 2025  08:28:33 +0000 (0:00:00.074)       0:00:12.428 *******
2025-12-01 08:28:33.456090 | controller | skipping: [instance]
2025-12-01 08:28:33.456256 | controller |
2025-12-01 08:28:33.456419 | controller | TASK [libvirt_manager : Delete storage pool] ***********************************
2025-12-01 08:28:33.456554 | controller | Monday 01 December 2025  08:28:33 +0000 (0:00:00.074)       0:00:12.502 *******
2025-12-01 08:28:33.561773 | controller | skipping: [instance] => (item=pool-destroy)
2025-12-01 08:28:33.562209 | controller | skipping: [instance] => (item=pool-undefine)
2025-12-01 08:28:33.562415 | controller | skipping: [instance]
2025-12-01 08:28:33.562574 | controller |
2025-12-01 08:28:33.562743 | controller | TASK [libvirt_manager : Refresh pools facts] ***********************************
2025-12-01 08:28:33.562890 | controller | Monday 01 December 2025  08:28:33 +0000 (0:00:00.105)       0:00:12.608 *******
2025-12-01 08:28:33.851730 | controller | ok: [instance]
2025-12-01 08:28:33.851815 | controller |
2025-12-01 08:28:33.851832 | controller | TASK [libvirt_manager : Refresh all pools after actions] ***********************
2025-12-01 08:28:33.851843 | controller | Monday 01 December 2025  08:28:33 +0000 (0:00:00.289)       0:00:12.898 *******
2025-12-01 08:28:33.925719 | controller | skipping: [instance]
2025-12-01 08:28:34.196105 | controller |
2025-12-01 08:28:34.196133 | controller | TASK [libvirt_manager : Get temporary key status] ******************************
2025-12-01 08:28:34.196141 | controller | Monday 01 December 2025  08:28:33 +0000 (0:00:00.073)       0:00:12.971 *******
2025-12-01 08:28:34.196151 | controller | ok: [instance]
2025-12-01 08:28:34.232696 | controller |
2025-12-01 08:28:34.232723 | controller | TASK [libvirt_manager : Get public key] ****************************************
2025-12-01 08:28:34.232733 | controller | Monday 01 December 2025  08:28:34 +0000 (0:00:00.270)       0:00:13.242 *******
2025-12-01 08:28:34.232745 | controller | skipping: [instance]
2025-12-01 08:28:34.233026 | controller |
2025-12-01 08:28:34.269632 | controller | TASK [libvirt_manager : Remove public key] *************************************
2025-12-01 08:28:34.269659 | controller | Monday 01 December 2025  08:28:34 +0000 (0:00:00.037)       0:00:13.279 *******
2025-12-01 08:28:34.269674 | controller | skipping: [instance]
2025-12-01 08:28:34.310718 | controller |
2025-12-01 08:28:34.310743 | controller | TASK [libvirt_manager : Remove keypair] ****************************************
2025-12-01 08:28:34.310754 | controller | Monday 01 December 2025  08:28:34 +0000 (0:00:00.036)       0:00:13.315 *******
2025-12-01 08:28:34.310766 | controller | skipping: [instance] => (item=cifmw_reproducer_key.pub)
2025-12-01 08:28:34.310809 | controller | skipping: [instance] => (item=cifmw_reproducer_key)
2025-12-01 08:28:34.310829 | controller | skipping: [instance]
2025-12-01 08:28:34.310839 | controller |
2025-12-01 08:28:34.310846 | controller | TASK [libvirt_manager : List libvirt logs] *************************************
2025-12-01 08:28:34.310858 | controller | Monday 01 December 2025  08:28:34 +0000 (0:00:00.040)       0:00:13.356 *******
2025-12-01 08:28:34.529372 | controller | ok: [instance]
2025-12-01 08:28:34.564731 | controller |
2025-12-01 08:28:34.564766 | controller | TASK [libvirt_manager : Remove detected logs] **********************************
2025-12-01 08:28:34.564777 | controller | Monday 01 December 2025  08:28:34 +0000 (0:00:00.218)       0:00:13.575 *******
2025-12-01 08:28:34.564790 | controller | skipping: [instance]
2025-12-01 08:28:35.949742 | controller |
2025-12-01 08:28:35.949772 | controller | TASK [libvirt_manager : Remove workload and volumes] ***************************
2025-12-01 08:28:35.949781 | controller | Monday 01 December 2025  08:28:34 +0000 (0:00:00.035)       0:00:13.611 *******
2025-12-01 08:28:35.949791 | controller | changed: [instance] => (item=workload)
2025-12-01 08:28:35.949814 | controller | changed: [instance] => (item=volumes)
2025-12-01 08:28:35.949821 | controller | ok: [instance] => (item=artifacts/debug_network_data.yml)
2025-12-01 08:28:35.949826 | controller | ok: [instance] => (item=artifacts/debug_pub_net_value.txt)
2025-12-01 08:28:35.949832 | controller | ok: [instance] => (item=artifacts/debug_cifmw_libvirt_manager_layout.yml)
2025-12-01 08:28:35.949838 | controller | changed: [instance] => (item=artifacts/interfaces-info.yml)
2025-12-01 08:28:35.949848 | controller | ok: [instance] => (item=artifacts/libvirt-uuids.yml)
2025-12-01 08:28:35.950212 | controller | ok: [instance] => (item=artifacts/net-map-def-patch.yml)
2025-12-01 08:28:36.333758 | controller |
2025-12-01 08:28:36.333793 | controller | TASK [libvirt_manager : Deepscrub data] ****************************************
2025-12-01 08:28:36.333802 | controller | Monday 01 December 2025  08:28:35 +0000 (0:00:01.384)       0:00:14.995 *******
2025-12-01 08:28:36.333820 | controller | ok: [instance] => (item=images)
2025-12-01 08:28:36.333852 | controller | ok: [instance] => (item=artifacts/virtual-nodes.yml)
2025-12-01 08:28:36.333862 | controller |
2025-12-01 08:28:36.334108 | controller | TASK [podman : Ensure podman is installed] *************************************
2025-12-01 08:28:40.746467 | controller | Monday 01 December 2025  08:28:36 +0000 (0:00:00.384)       0:00:15.380 *******
2025-12-01 08:28:40.746501 | controller | ok: [instance]
2025-12-01 08:28:41.012729 | controller |
2025-12-01 08:28:41.012763 | controller | TASK [podman : Enable loginctl linger for ansible_user_id] *********************
2025-12-01 08:28:41.012771 | controller | Monday 01 December 2025  08:28:40 +0000 (0:00:04.412)       0:00:19.792 *******
2025-12-01 08:28:41.012782 | controller | changed: [instance]
2025-12-01 08:28:41.056993 | controller |
2025-12-01 08:28:41.057018 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul] *******************
2025-12-01 08:28:41.057026 | controller | Monday 01 December 2025  08:28:41 +0000 (0:00:00.265)       0:00:20.058 *******
2025-12-01 08:28:41.057035 | controller | skipping: [instance]
2025-12-01 08:28:41.097716 | controller |
2025-12-01 08:28:41.097739 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul] *******************
2025-12-01 08:28:41.097754 | controller | Monday 01 December 2025  08:28:41 +0000 (0:00:00.044)       0:00:20.103 *******
2025-12-01 08:28:41.097766 | controller | skipping: [instance]
2025-12-01 08:28:41.097945 | controller |
2025-12-01 08:28:41.097963 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed] ***
2025-12-01 08:28:41.160747 | controller | Monday 01 December 2025  08:28:41 +0000 (0:00:00.040)       0:00:20.144 *******
2025-12-01 08:28:41.160786 | controller | skipping: [instance]
2025-12-01 08:28:41.611270 | controller |
2025-12-01 08:28:41.611304 | controller | TASK [virtualbmc : Stop and remove vbmc container] *****************************
2025-12-01 08:28:41.611314 | controller | Monday 01 December 2025  08:28:41 +0000 (0:00:00.062)       0:00:20.206 *******
2025-12-01 08:28:41.611326 | controller | ok: [instance]
2025-12-01 08:28:42.065355 | controller |
2025-12-01 08:28:42.065388 | controller | TASK [virtualbmc : Remove vbmc container image] ********************************
2025-12-01 08:28:42.065396 | controller | Monday 01 December 2025  08:28:41 +0000 (0:00:00.450)       0:00:20.657 *******
2025-12-01 08:28:42.065406 | controller | ok: [instance]
2025-12-01 08:28:42.275542 | controller |
2025-12-01 08:28:42.275569 | controller | TASK [virtualbmc : Check if VBMC key exists] ***********************************
2025-12-01 08:28:42.275577 | controller | Monday 01 December 2025  08:28:42 +0000 (0:00:00.454)       0:00:21.111 *******
2025-12-01 08:28:42.275588 | controller | ok: [instance]
2025-12-01 08:28:42.311772 | controller |
2025-12-01 08:28:42.311805 | controller | TASK [virtualbmc : Slurp key] **************************************************
2025-12-01 08:28:42.311819 | controller | Monday 01 December 2025  08:28:42 +0000 (0:00:00.210)       0:00:21.321 *******
2025-12-01 08:28:42.311835 | controller | skipping: [instance]
2025-12-01 08:28:42.311999 | controller |
2025-12-01 08:28:42.312029 | controller | TASK [virtualbmc : Revoke VBMC SSH access] *************************************
2025-12-01 08:28:42.312047 | controller | Monday 01 December 2025  08:28:42 +0000 (0:00:00.036)       0:00:21.357 *******
2025-12-01 08:28:42.369740 | controller | skipping: [instance]
2025-12-01 08:28:42.369852 | controller |
2025-12-01 08:28:42.369871 | controller | TASK [virtualbmc : Remove vbmc files] ******************************************
2025-12-01 08:28:42.369884 | controller | Monday 01 December 2025  08:28:42 +0000 (0:00:00.058)       0:00:21.416 *******
2025-12-01 08:28:43.084723 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmc-key)
2025-12-01 08:28:43.084767 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmc-key.pub)
2025-12-01 08:28:43.084777 | controller | ok: [instance] => (item=/home/zuul/.ssh/virtualbmc.conf)
2025-12-01 08:28:43.085165 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmcd.sh)
2025-12-01 08:28:43.170148 | controller |
2025-12-01 08:28:43.170169 | controller | TASK [Clean remote ssh config] *************************************************
2025-12-01 08:28:43.170177 | controller | Monday 01 December 2025  08:28:43 +0000 (0:00:00.714)       0:00:22.130 *******
2025-12-01 08:28:43.170186 | controller |
2025-12-01 08:28:43.487602 | controller | TASK [ssh_jumper : Remove Include cifmw_ssh_config.d] **************************
2025-12-01 08:28:43.487655 | controller | Monday 01 December 2025  08:28:43 +0000 (0:00:00.085)       0:00:22.216 *******
2025-12-01 08:28:43.487668 | controller | ok: [instance]
2025-12-01 08:28:43.721117 | controller |
2025-12-01 08:28:43.721148 | controller | TASK [ssh_jumper : Remove cifmw_ssh_config.d directory] ************************
2025-12-01 08:28:43.721156 | controller | Monday 01 December 2025  08:28:43 +0000 (0:00:00.317)       0:00:22.533 *******
2025-12-01 08:28:43.721166 | controller | ok: [instance]
2025-12-01 08:28:43.826385 | controller |
2025-12-01 08:28:43.826420 | controller | TASK [Clean local ssh config] **************************************************
2025-12-01 08:28:43.826430 | controller | Monday 01 December 2025  08:28:43 +0000 (0:00:00.233)       0:00:22.767 *******
2025-12-01 08:28:43.826442 | controller |
2025-12-01 08:28:44.021493 | controller | TASK [ssh_jumper : Remove Include cifmw_ssh_config.d] **************************
2025-12-01 08:28:44.021518 | controller | Monday 01 December 2025  08:28:43 +0000 (0:00:00.105)       0:00:22.872 *******
2025-12-01 08:28:44.021529 | controller | ok: [instance -> localhost]
2025-12-01 08:28:44.255785 | controller |
2025-12-01 08:28:44.255818 | controller | TASK [ssh_jumper : Remove cifmw_ssh_config.d directory] ************************
2025-12-01 08:28:44.255827 | controller | Monday 01 December 2025  08:28:44 +0000 (0:00:00.195)       0:00:23.067 *******
2025-12-01 08:28:44.255838 | controller | ok: [instance -> localhost]
2025-12-01 08:28:44.324426 | controller |
2025-12-01 08:28:44.324455 | controller | TASK [dnsmasq : Cleaning dnsmasq] **********************************************
2025-12-01 08:28:44.324464 | controller | Monday 01 December 2025  08:28:44 +0000 (0:00:00.234)       0:00:23.301 *******
2025-12-01 08:28:44.324476 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/configure.yml for instance
2025-12-01 08:28:45.626745 | controller |
2025-12-01 08:28:45.626777 | controller | TASK [dnsmasq : Install needed packages] ***************************************
2025-12-01 08:28:45.626790 | controller | Monday 01 December 2025  08:28:44 +0000 (0:00:00.068)       0:00:23.370 *******
2025-12-01 08:28:45.626801 | controller | ok: [instance]
2025-12-01 08:28:45.626907 | controller |
2025-12-01 08:28:45.626940 | controller | TASK [dnsmasq : Ensure SELinux knows about the new files and directories] ******
2025-12-01 08:28:45.626955 | controller | Monday 01 December 2025  08:28:45 +0000 (0:00:01.302)       0:00:24.672 *******
2025-12-01 08:28:46.884749 | controller | ok: [instance] => (item={'target': '/etc/cifmw-dnsmasq.conf', 'setype': 'dnsmasq_etc_t'})
2025-12-01 08:28:46.884801 | controller | ok: [instance] => (item={'target': '/etc/cifmw-dnsmasq.d(/.*)?', 'setype': 'dnsmasq_etc_t'})
2025-12-01 08:28:46.884819 | controller | ok: [instance] => (item={'target': '/var/run/cifmw-dnsmasq.pid', 'setype': 'dnsmasq_var_run_t'})
2025-12-01 08:28:46.884827 | controller |
2025-12-01 08:28:46.884838 | controller | TASK [dnsmasq : Manage configuration directory] ********************************
2025-12-01 08:28:46.884902 | controller | Monday 01 December 2025  08:28:46 +0000 (0:00:01.257)       0:00:25.930 *******
2025-12-01 08:28:47.114725 | controller | ok: [instance]
2025-12-01 08:28:47.115065 | controller |
2025-12-01 08:28:47.152538 | controller | TASK [dnsmasq : Manage systemd unit file] **************************************
2025-12-01 08:28:47.152560 | controller | Monday 01 December 2025  08:28:47 +0000 (0:00:00.230)       0:00:26.161 *******
2025-12-01 08:28:47.152571 | controller | skipping: [instance]
2025-12-01 08:28:47.189341 | controller |
2025-12-01 08:28:47.189375 | controller | TASK [dnsmasq : Manage base configuration file] ********************************
2025-12-01 08:28:47.189384 | controller | Monday 01 December 2025  08:28:47 +0000 (0:00:00.037)       0:00:26.198 *******
2025-12-01 08:28:47.189393 | controller | skipping: [instance]
2025-12-01 08:28:47.250739 | controller |
2025-12-01 08:28:47.250759 | controller | TASK [dnsmasq : Render listener configuration] *********************************
2025-12-01 08:28:47.250767 | controller | Monday 01 December 2025  08:28:47 +0000 (0:00:00.036)       0:00:26.235 *******
2025-12-01 08:28:47.250776 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/listener.yml for instance
2025-12-01 08:28:47.309804 | controller |
2025-12-01 08:28:47.309829 | controller | TASK [dnsmasq : Create listener configuration file] ****************************
2025-12-01 08:28:47.309839 | controller | Monday 01 December 2025  08:28:47 +0000 (0:00:00.061)       0:00:26.297 *******
2025-12-01 08:28:47.309856 | controller | skipping: [instance]
2025-12-01 08:28:47.535456 | controller |
2025-12-01 08:28:47.535486 | controller | TASK [dnsmasq : Remove listener configuration file] ****************************
2025-12-01 08:28:47.535496 | controller | Monday 01 December 2025  08:28:47 +0000 (0:00:00.058)       0:00:26.356 *******
2025-12-01 08:28:47.535508 | controller | ok: [instance]
2025-12-01 08:28:47.599514 | controller |
2025-12-01 08:28:47.599535 | controller | TASK [dnsmasq : Render dns configuration] **************************************
2025-12-01 08:28:47.599543 | controller | Monday 01 December 2025  08:28:47 +0000 (0:00:00.225)       0:00:26.581 *******
2025-12-01 08:28:47.599553 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/dns.yml for instance
2025-12-01 08:28:47.636528 | controller |
2025-12-01 08:28:47.636548 | controller | TASK [dnsmasq : Create dns configuration file] *********************************
2025-12-01 08:28:47.636556 | controller | Monday 01 December 2025  08:28:47 +0000 (0:00:00.064)       0:00:26.645 *******
2025-12-01 08:28:47.636565 | controller | skipping: [instance]
2025-12-01 08:28:47.865275 | controller |
2025-12-01 08:28:47.865304 | controller | TASK [dnsmasq : Remove dns configuration file] *********************************
2025-12-01 08:28:47.865312 | controller | Monday 01 December 2025  08:28:47 +0000 (0:00:00.037)       0:00:26.682 *******
2025-12-01 08:28:47.865322 | controller | ok: [instance]
2025-12-01 08:28:47.900736 | controller |
2025-12-01 08:28:47.900772 | controller | TASK [dnsmasq : Add localhost addresses from defined dnsmasq listen addresses to loopback interface] ***
2025-12-01 08:28:47.900782 | controller | Monday 01 December 2025  08:28:47 +0000 (0:00:00.228)       0:00:26.911 *******
2025-12-01 08:28:47.900794 | controller | skipping: [instance]
2025-12-01 08:28:47.937263 | controller |
2025-12-01 08:28:47.937295 | controller | TASK [dnsmasq : Enable and start service] **************************************
2025-12-01 08:28:47.937305 | controller | Monday 01 December 2025  08:28:47 +0000 (0:00:00.035)       0:00:26.946 *******
2025-12-01 08:28:47.937316 | controller | skipping: [instance]
2025-12-01 08:28:48.294364 | controller |
2025-12-01 08:28:48.294395 | controller | TASK [dnsmasq : Stop service] **************************************************
2025-12-01 08:28:48.294404 | controller | Monday 01 December 2025  08:28:47 +0000 (0:00:00.036)       0:00:26.983 *******
2025-12-01 08:28:48.294414 | controller | ok: [instance]
2025-12-01 08:28:48.537492 | controller |
2025-12-01 08:28:48.537522 | controller | TASK [dnsmasq : Remove unit file] **********************************************
2025-12-01 08:28:48.537530 | controller | Monday 01 December 2025  08:28:48 +0000 (0:00:00.356)       0:00:27.340 *******
2025-12-01 08:28:48.537541 | controller | ok: [instance]
2025-12-01 08:28:48.923678 | controller |
2025-12-01 08:28:48.923705 | controller | TASK [dnsmasq : Remove main configuration files] *******************************
2025-12-01 08:28:48.923716 | controller | Monday 01 December 2025  08:28:48 +0000 (0:00:00.243)       0:00:27.583 *******
2025-12-01 08:28:48.923727 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2025-12-01 08:28:48.923749 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.d)
2025-12-01 08:28:48.923757 | controller |
2025-12-01 08:28:48.923980 | controller | TASK [dnsmasq : Remove lease file] *********************************************
2025-12-01 08:28:49.171472 | controller | Monday 01 December 2025  08:28:48 +0000 (0:00:00.386)       0:00:27.970 *******
2025-12-01 08:28:49.171506 | controller | ok: [instance]
2025-12-01 08:28:49.739694 | controller |
2025-12-01 08:28:49.739734 | controller | TASK [radvd : Gather package facts] ********************************************
2025-12-01 08:28:49.739745 | controller | Monday 01 December 2025  08:28:49 +0000 (0:00:00.247)       0:00:28.217 *******
2025-12-01 08:28:49.739758 | controller | ok: [instance]
2025-12-01 08:28:49.807331 | controller |
2025-12-01 08:28:49.807385 | controller | TASK [radvd : Stop and disable radvd service] **********************************
2025-12-01 08:28:49.807394 | controller | Monday 01 December 2025  08:28:49 +0000 (0:00:00.568)       0:00:28.785 *******
2025-12-01 08:28:49.807405 | controller | skipping: [instance]
2025-12-01 08:28:50.012722 | controller |
2025-12-01 08:28:50.012757 | controller | TASK [radvd : Remove radvd configuration file] *********************************
2025-12-01 08:28:50.012767 | controller | Monday 01 December 2025  08:28:49 +0000 (0:00:00.067)       0:00:28.853 *******
2025-12-01 08:28:50.012784 | controller | ok: [instance]
2025-12-01 08:28:50.221262 | controller |
2025-12-01 08:28:50.221291 | controller | TASK [radvd : Remove configuration directory] **********************************
2025-12-01 08:28:50.221300 | controller | Monday 01 December 2025  08:28:50 +0000 (0:00:00.205)       0:00:29.058 *******
2025-12-01 08:28:50.221310 | controller | ok: [instance]
2025-12-01 08:28:50.302581 | controller |
2025-12-01 08:28:50.302626 | controller | TASK [radvd : Remove radvd package] ********************************************
2025-12-01 08:28:50.302638 | controller | Monday 01 December 2025  08:28:50 +0000 (0:00:00.208)       0:00:29.267 *******
2025-12-01 08:28:50.302649 | controller | skipping: [instance]
2025-12-01 08:28:50.647105 | controller |
2025-12-01 08:28:50.647133 | controller | TASK [Ensure work directory is removed] ****************************************
2025-12-01 08:28:50.647141 | controller | Monday 01 December 2025  08:28:50 +0000 (0:00:00.081)       0:00:29.348 *******
2025-12-01 08:28:50.647150 | controller | changed: [instance]
2025-12-01 08:28:51.456845 | controller |
2025-12-01 08:28:51.456878 | controller | RUNNING HANDLER [libvirt_manager : Restart firewalld] **************************
2025-12-01 08:28:51.456888 | controller | Monday 01 December 2025  08:28:50 +0000 (0:00:00.344)       0:00:29.693 *******
2025-12-01 08:28:51.456908 | controller | changed: [instance]
2025-12-01 08:28:51.456929 | controller |
2025-12-01 08:28:51.456937 | controller | PLAY RECAP *********************************************************************
2025-12-01 08:28:51.456946 | controller | instance                   : ok=57   changed=10   unreachable=0    failed=0    skipped=40   rescued=0    ignored=0
2025-12-01 08:28:51.458694 | controller |
2025-12-01 08:28:51.459349 | controller | Monday 01 December 2025  08:28:51 +0000 (0:00:00.809)       0:00:30.502 *******
2025-12-01 08:28:51.459375 | controller | ===============================================================================
2025-12-01 08:28:51.459382 | controller | podman : Ensure podman is installed ------------------------------------- 4.41s
2025-12-01 08:28:51.459388 | controller | libvirt_manager : Populate service facts -------------------------------- 2.09s
2025-12-01 08:28:51.459393 | controller | Copy generated content to proper location ------------------------------- 1.42s
2025-12-01 08:28:51.459399 | controller | libvirt_manager : Remove workload and volumes --------------------------- 1.38s
2025-12-01 08:28:51.459404 | controller | dnsmasq : Install needed packages --------------------------------------- 1.30s
2025-12-01 08:28:51.459410 | controller | dnsmasq : Ensure SELinux knows about the new files and directories ------ 1.26s
2025-12-01 08:28:51.459415 | controller | Gathering Facts --------------------------------------------------------- 0.90s
2025-12-01 08:28:51.459420 | controller | libvirt_manager : Start virtqemud socket service ------------------------ 0.88s
2025-12-01 08:28:51.459426 | controller | libvirt_manager : Restart firewalld ------------------------------------- 0.81s
2025-12-01 08:28:51.459431 | controller | libvirt_manager : Get installed packages list --------------------------- 0.79s
2025-12-01 08:28:51.459436 | controller | virtualbmc : Remove vbmc files ------------------------------------------ 0.71s
2025-12-01 08:28:51.459445 | controller | libvirt_manager : Ensure firewalld is enabled and started --------------- 0.58s
2025-12-01 08:28:51.459451 | controller | radvd : Gather package facts -------------------------------------------- 0.57s
2025-12-01 08:28:51.459456 | controller | libvirt_manager : Disable forward in firewalld libvirt zone ------------- 0.55s
2025-12-01 08:28:51.459464 | controller | libvirt_manager : Disable masquerade in firewalld default zone ---------- 0.51s
2025-12-01 08:28:51.522122 | controller | virtualbmc : Remove vbmc container image -------------------------------- 0.45s
2025-12-01 08:28:51.522155 | controller | virtualbmc : Stop and remove vbmc container ----------------------------- 0.45s
2025-12-01 08:28:51.522163 | controller | libvirt_manager : Get pool configuration -------------------------------- 0.42s
2025-12-01 08:28:51.522169 | controller | dnsmasq : Remove main configuration files ------------------------------- 0.39s
2025-12-01 08:28:51.522175 | controller | libvirt_manager : Deepscrub data ---------------------------------------- 0.38s
2025-12-01 08:28:51.522185 | controller | INFO     Running boot_order > destroy
2025-12-01 08:28:51.522575 | controller | WARNING  Skipping, instances are delegated.
2025-12-01 08:28:51.522954 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-12-01 08:28:51.524556 | controller | INFO     Writing /tmp/report.html report.
2025-12-01 08:28:51.839135 | [controller] Waiting on logger
2025-12-01 08:28:56.971392 | [controller] Waiting on logger
2025-12-01 08:29:07.403332 | [controller] Waiting on logger
2025-12-01 08:29:17.835303 | [controller] Waiting on logger
2025-12-01 08:29:21.606082 | [Zuul] Log Stream did not terminate
2025-12-01 08:29:21.606316 | controller | ERROR
2025-12-01 08:29:21.606422 | controller | {
2025-12-01 08:29:21.606461 | controller |   "delta": "0:07:48.018350",
2025-12-01 08:29:21.606488 | controller |   "end": "2025-12-01 08:28:51.572952",
2025-12-01 08:29:21.606510 | controller |   "msg": "non-zero return code",
2025-12-01 08:29:21.606532 | controller |   "rc": 1,
2025-12-01 08:29:21.606552 | controller |   "start": "2025-12-01 08:21:03.554602"
2025-12-01 08:29:21.606572 | controller | }
failure
2025-12-01 08:29:21.654766 | 
2025-12-01 08:29:21.654817 | PLAY RECAP
2025-12-01 08:29:21.654855 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-12-01 08:29:21.654878 | 
2025-12-01 08:29:21.707906 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-01 08:29:21.708729 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-01 08:29:22.194255 | 
2025-12-01 08:29:22.194365 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-01 08:29:22.214339 | 
2025-12-01 08:29:22.214417 | TASK [Filter out host if needed]
2025-12-01 08:29:22.222659 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-01 08:29:22.227019 | 
2025-12-01 08:29:22.227084 | TASK [Ensure file is present]
2025-12-01 08:29:22.517739 | controller | ok
2025-12-01 08:29:22.523847 | 
2025-12-01 08:29:22.523910 | TASK [Manage molecule report file]
2025-12-01 08:29:22.545102 | [controller] Waiting on logger
2025-12-01 08:29:31.888335 | [controller] Waiting on logger
2025-12-01 08:29:42.283197 | [controller] Waiting on logger
2025-12-01 08:29:52.715186 | [controller] Waiting on logger
2025-12-01 08:29:52.833386 | [Zuul] Log Stream did not terminate
2025-12-01 08:29:52.833519 | controller | changed
2025-12-01 08:29:52.838826 | 
2025-12-01 08:29:52.838888 | TASK [Check if we get ci-framework-data basedir]
2025-12-01 08:29:53.018592 | controller | ok
2025-12-01 08:29:53.023566 | 
2025-12-01 08:29:53.023630 | TASK [Create ci-framework-data log directory for zuul]
2025-12-01 08:29:53.314199 | controller | changed
2025-12-01 08:29:53.319208 | 
2025-12-01 08:29:53.319283 | TASK [Copy ci-framework interesting files]
2025-12-01 08:29:53.339819 | [controller] Waiting on logger
2025-12-01 08:29:58.445886 | controller | changed
2025-12-01 08:29:58.451299 | 
2025-12-01 08:29:58.451384 | TASK [Get SELinux listing]
2025-12-01 08:29:58.472162 | [controller] Waiting on logger
2025-12-01 08:30:04.732653 | controller | changed
2025-12-01 08:30:04.738195 | 
2025-12-01 08:30:04.738258 | TASK [Generate log index]
2025-12-01 08:30:05.195162 | [controller] Waiting on logger
2025-12-01 08:30:05.337761 | controller | changed
2025-12-01 08:30:05.342146 | 
2025-12-01 08:30:05.342203 | TASK [Get some env related data]
2025-12-01 08:30:08.459359 | [controller] Waiting on logger
2025-12-01 08:30:14.713263 | controller | changed
2025-12-01 08:30:14.718329 | 
2025-12-01 08:30:14.718396 | TASK [Generate list of logs to collect in home directory]
2025-12-01 08:30:14.999211 | controller | ok: All paths examined
2025-12-01 08:30:15.004174 | 
2025-12-01 08:30:15.004249 | LOOP [Copy logs from home directory]
2025-12-01 08:30:15.299344 | controller | changed:
2025-12-01 08:30:15.299477 | controller | {
2025-12-01 08:30:15.299506 | controller |   "atime": 1764577245.7764268,
2025-12-01 08:30:15.299526 | controller |   "ctime": 1764577261.407769,
2025-12-01 08:30:15.299543 | controller |   "dev": 64513,
2025-12-01 08:30:15.299559 | controller |   "gid": 1000,
2025-12-01 08:30:15.299574 | controller |   "gr_name": "zuul",
2025-12-01 08:30:15.299589 | controller |   "inode": 4332660,
2025-12-01 08:30:15.299604 | controller |   "isblk": false,
2025-12-01 08:30:15.299618 | controller |   "ischr": false,
2025-12-01 08:30:15.299632 | controller |   "isdir": false,
2025-12-01 08:30:15.299647 | controller |   "isfifo": false,
2025-12-01 08:30:15.299661 | controller |   "isgid": false,
2025-12-01 08:30:15.299674 | controller |   "islnk": false,
2025-12-01 08:30:15.299690 | controller |   "isreg": true,
2025-12-01 08:30:15.299738 | controller |   "issock": false,
2025-12-01 08:30:15.299755 | controller |   "isuid": false,
2025-12-01 08:30:15.299770 | controller |   "mode": "0644",
2025-12-01 08:30:15.299786 | controller |   "mtime": 1764577261.407769,
2025-12-01 08:30:15.299800 | controller |   "nlink": 1,
2025-12-01 08:30:15.299814 | controller |   "path": "/home/zuul/ansible.log",
2025-12-01 08:30:15.299828 | controller |   "pw_name": "zuul",
2025-12-01 08:30:15.299845 | controller |   "rgrp": true,
2025-12-01 08:30:15.299859 | controller |   "roth": true,
2025-12-01 08:30:15.299873 | controller |   "rusr": true,
2025-12-01 08:30:15.299886 | controller |   "size": 6749,
2025-12-01 08:30:15.299900 | controller |   "uid": 1000,
2025-12-01 08:30:15.299914 | controller |   "wgrp": false,
2025-12-01 08:30:15.299927 | controller |   "woth": false,
2025-12-01 08:30:15.299940 | controller |   "wusr": true,
2025-12-01 08:30:15.299954 | controller |   "xgrp": false,
2025-12-01 08:30:15.299967 | controller |   "xoth": false,
2025-12-01 08:30:15.299980 | controller |   "xusr": false
2025-12-01 08:30:15.299993 | controller | }
2025-12-01 08:30:15.312524 | 
2025-12-01 08:30:15.312621 | TASK [Copy crio stats log file]
2025-12-01 08:30:15.326326 | controller | skipping: Conditional result was False
2025-12-01 08:30:15.331611 | 
2025-12-01 08:30:15.331677 | TASK [Get SELinux related data]
2025-12-01 08:30:15.354464 | [controller] Waiting on logger
2025-12-01 08:30:27.787370 | [controller] Waiting on logger
2025-12-01 08:30:37.131122 | [controller] Waiting on logger
2025-12-01 08:30:45.561433 | [Zuul] Log Stream did not terminate
2025-12-01 08:30:45.561683 | controller | ERROR
2025-12-01 08:30:45.561851 | controller | {
2025-12-01 08:30:45.561882 | controller |   "delta": "0:00:00.008452",
2025-12-01 08:30:45.561902 | controller |   "end": "2025-12-01 08:30:15.532436",
2025-12-01 08:30:45.561921 | controller |   "msg": "non-zero return code",
2025-12-01 08:30:45.561937 | controller |   "rc": 1,
2025-12-01 08:30:45.561955 | controller |   "start": "2025-12-01 08:30:15.523984"
2025-12-01 08:30:45.561969 | controller | }
2025-12-01 08:30:45.561993 | controller | ERROR: Ignoring Errors
2025-12-01 08:30:45.567257 | 
2025-12-01 08:30:45.567321 | TASK [Create system configuration directory]
2025-12-01 08:30:45.757034 | controller | changed
2025-12-01 08:30:45.761719 | 
2025-12-01 08:30:45.761789 | TASK [Get some of the system configurations]
2025-12-01 08:30:47.563298 | [controller] Waiting on logger
2025-12-01 08:30:48.843033 | [controller] Waiting on logger
2025-12-01 08:31:07.275330 | [controller] Waiting on logger
2025-12-01 08:31:09.514974 | [controller] Waiting on logger
2025-12-01 08:31:16.002646 | [Zuul] Log Stream did not terminate
2025-12-01 08:31:16.002855 | controller | changed
2025-12-01 08:31:16.007711 | 
2025-12-01 08:31:16.007771 | TASK [Copy generated documentation if available]
2025-12-01 08:31:16.020661 | controller | skipping: Conditional result was False
2025-12-01 08:31:16.025661 | 
2025-12-01 08:31:16.025739 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-01 08:31:16.038213 | controller | skipping: Conditional result was False
2025-12-01 08:31:16.043652 | 
2025-12-01 08:31:16.043765 | TASK [Compress logs bigger than 2MB]
2025-12-01 08:31:17.067246 | [controller] Waiting on logger
2025-12-01 08:31:32.043542 | [controller] Waiting on logger
2025-12-01 08:31:42.923234 | [controller] Waiting on logger
2025-12-01 08:31:46.251894 | [Zuul] Log Stream did not terminate
2025-12-01 08:31:46.252193 | controller | changed
2025-12-01 08:31:46.257529 | 
2025-12-01 08:31:46.257595 | TASK [Copy files from workspace on node]
2025-12-01 08:31:46.275418 | controller | ok
2025-12-01 08:31:46.296648 | 
2025-12-01 08:31:46.296733 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-01 08:31:46.309901 | controller | skipping: Conditional result was False
2025-12-01 08:31:46.315268 | 
2025-12-01 08:31:46.315345 | TASK [fetch-output : Set log path for single node]
2025-12-01 08:31:46.343438 | controller | ok
2025-12-01 08:31:46.349060 | 
2025-12-01 08:31:46.349132 | LOOP [fetch-output : Ensure local output dirs]
2025-12-01 08:31:46.536541 | controller -> localhost | ok: "/var/lib/zuul/builds/d589bf7cb9a34c958883eab0bbbbee0b/work/logs"
2025-12-01 08:31:46.536782 | controller -> localhost | changed: All items complete
2025-12-01 08:31:46.536819 | 
2025-12-01 08:31:46.703000 | controller -> localhost | changed: "/var/lib/zuul/builds/d589bf7cb9a34c958883eab0bbbbee0b/work/artifacts"
2025-12-01 08:31:46.876274 | controller -> localhost | changed: "/var/lib/zuul/builds/d589bf7cb9a34c958883eab0bbbbee0b/work/docs"
2025-12-01 08:31:46.885379 | 
2025-12-01 08:31:46.885475 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-01 08:31:47.404401 | controller | changed:
2025-12-01 08:31:47.404585 | controller | .d..t...... ./
2025-12-01 08:31:47.404615 | controller | >f+++++++++ README.html
2025-12-01 08:31:47.404637 | controller | >f+++++++++ ansible-execution.log
2025-12-01 08:31:47.404659 | controller | >f+++++++++ ansible.log
2025-12-01 08:31:47.404678 | controller | >f+++++++++ dmesg.log
2025-12-01 08:31:47.404714 | controller | >f+++++++++ installed-pkgs.log
2025-12-01 08:31:47.404737 | controller | >f+++++++++ python.log
2025-12-01 08:31:47.404755 | controller | >f+++++++++ registries.conf
2025-12-01 08:31:47.404771 | controller | >f+++++++++ report.html
2025-12-01 08:31:47.404786 | controller | >f+++++++++ selinux-denials.log
2025-12-01 08:31:47.404801 | controller | >f+++++++++ selinux-listing.log
2025-12-01 08:31:47.404817 | controller | cd+++++++++ ci-framework-data/
2025-12-01 08:31:47.404833 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-01 08:31:47.404848 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-01 08:31:47.404863 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/
2025-12-01 08:31:47.404878 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/artifacts/
2025-12-01 08:31:47.404893 | controller | >f+++++++++ ci-framework-data/artifacts/boot_order/artifacts/interfaces-info.yml
2025-12-01 08:31:47.404907 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/artifacts/manifests/
2025-12-01 08:31:47.404921 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/artifacts/manifests/openstack/
2025-12-01 08:31:47.404935 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/artifacts/manifests/openstack/cr/
2025-12-01 08:31:47.404949 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/artifacts/parameters/
2025-12-01 08:31:47.404970 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/artifacts/repositories/
2025-12-01 08:31:47.404985 | controller | >f+++++++++ ci-framework-data/artifacts/boot_order/artifacts/repositories/delorean.repo.md5
2025-12-01 08:31:47.404999 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/logs/
2025-12-01 08:31:47.405013 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-01 08:31:47.405027 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-01 08:31:47.405040 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-01 08:31:47.405053 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-12-01 08:31:47.405067 | controller | cd+++++++++ registries.conf.d/
2025-12-01 08:31:47.405080 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-01 08:31:47.405094 | controller | cd+++++++++ system-config/
2025-12-01 08:31:47.405117 | controller | cd+++++++++ system-config/libvirt/
2025-12-01 08:31:47.405135 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-01 08:31:47.405152 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-01 08:31:47.405167 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-12-01 08:31:47.405182 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-12-01 08:31:47.405196 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-12-01 08:31:47.405211 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-12-01 08:31:47.405226 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-12-01 08:31:47.405242 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-12-01 08:31:47.405256 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-12-01 08:31:47.405270 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-12-01 08:31:47.405284 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-12-01 08:31:47.405299 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-12-01 08:31:47.405313 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-12-01 08:31:47.405328 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-12-01 08:31:47.405342 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-12-01 08:31:47.405356 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-12-01 08:31:47.760615 | controller | changed: .d..t...... ./
2025-12-01 08:31:48.103781 | controller | changed: .d..t...... ./
2025-12-01 08:31:48.116788 | 
2025-12-01 08:31:48.116881 | TASK [Return artifact to Zuul]
2025-12-01 08:31:48.145505 | controller | ok
2025-12-01 08:31:48.164501 | 
2025-12-01 08:31:48.164557 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-01 08:31:48.164646 | 
2025-12-01 08:31:48.164674 | PLAY RECAP
2025-12-01 08:31:48.164732 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-12-01 08:31:48.164758 | 
2025-12-01 08:31:48.235965 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-01 08:31:48.236768 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-01 08:31:48.731922 | 
2025-12-01 08:31:48.732023 | PLAY [all]
2025-12-01 08:31:48.749953 | 
2025-12-01 08:31:48.750027 | TASK [include_role : fetch-output]
2025-12-01 08:31:48.778214 | controller | ok
2025-12-01 08:31:48.794275 | 
2025-12-01 08:31:48.794358 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-01 08:31:48.838484 | controller | skipping: Conditional result was False
2025-12-01 08:31:48.843981 | 
2025-12-01 08:31:48.844052 | TASK [fetch-output : Set log path for single node]
2025-12-01 08:31:48.872285 | controller | ok
2025-12-01 08:31:48.877097 | 
2025-12-01 08:31:48.877177 | LOOP [fetch-output : Ensure local output dirs]
2025-12-01 08:31:49.185578 | controller -> localhost | ok: "/var/lib/zuul/builds/d589bf7cb9a34c958883eab0bbbbee0b/work/logs"
2025-12-01 08:31:49.363952 | controller -> localhost | ok: "/var/lib/zuul/builds/d589bf7cb9a34c958883eab0bbbbee0b/work/artifacts"
2025-12-01 08:31:49.537247 | controller -> localhost | ok: "/var/lib/zuul/builds/d589bf7cb9a34c958883eab0bbbbee0b/work/docs"
2025-12-01 08:31:49.544688 | 
2025-12-01 08:31:49.544818 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-01 08:31:50.041335 | controller | ok
2025-12-01 08:31:50.041514 | controller | ok: All items complete
2025-12-01 08:31:50.041544 | 
2025-12-01 08:31:50.397465 | controller | ok
2025-12-01 08:31:50.744953 | controller | ok
2025-12-01 08:31:50.764456 | 
2025-12-01 08:31:50.764587 | TASK [include_role : fetch-output-openshift]
2025-12-01 08:31:50.778244 | controller | skipping: Conditional result was False
2025-12-01 08:31:50.783715 | 
2025-12-01 08:31:50.783784 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-01 08:31:51.104415 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006188
2025-12-01 08:31:51.290486 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006195
2025-12-01 08:31:51.317478 | 
2025-12-01 08:31:51.317562 | PLAY [all]
2025-12-01 08:31:51.331344 | 
2025-12-01 08:31:51.331419 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-01 08:31:51.704820 | controller | changed
2025-12-01 08:31:51.725831 | 
2025-12-01 08:31:51.725885 | PLAY RECAP
2025-12-01 08:31:51.725928 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-01 08:31:51.725951 | 
2025-12-01 08:31:51.789331 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-01 08:31:51.790056 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-01 08:31:52.315291 | 
2025-12-01 08:31:52.315388 | PLAY [localhost]
2025-12-01 08:31:52.332226 | 
2025-12-01 08:31:52.332299 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-01 08:31:52.622670 | localhost | changed
2025-12-01 08:31:52.627156 | 
2025-12-01 08:31:52.627238 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-01 08:31:52.645015 | localhost | ok
2025-12-01 08:31:52.652611 | 
2025-12-01 08:31:52.652678 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-01 08:31:52.932300 | localhost | changed
2025-12-01 08:31:52.937602 | 
2025-12-01 08:31:52.937669 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-01 08:31:53.460531 | localhost | changed
2025-12-01 08:31:53.465292 | 
2025-12-01 08:31:53.465356 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-01 08:31:53.786164 | localhost | Identity added: /var/lib/zuul/builds/d589bf7cb9a34c958883eab0bbbbee0b/work/tmp/ansible.nfd_ueyn (/var/lib/zuul/builds/d589bf7cb9a34c958883eab0bbbbee0b/work/tmp/ansible.nfd_ueyn)
2025-12-01 08:31:53.786324 | localhost | ok: Runtime: 0:00:00.005765
2025-12-01 08:31:53.790661 | 
2025-12-01 08:31:53.790747 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-01 08:31:54.000555 | localhost | ok: Runtime: 0:00:00.003464
2025-12-01 08:31:54.005021 | 
2025-12-01 08:31:54.005085 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-01 08:31:54.052279 | localhost | changed
2025-12-01 08:31:54.056975 | 
2025-12-01 08:31:54.057037 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-01 08:31:54.357164 | localhost | changed
2025-12-01 08:31:54.375834 | 
2025-12-01 08:31:54.375887 | PLAY [localhost]
2025-12-01 08:31:54.386729 | 
2025-12-01 08:31:54.386790 | TASK [Generate bulk log download script]
2025-12-01 08:31:54.404164 | localhost | ok
2025-12-01 08:31:54.415030 | 
2025-12-01 08:31:54.415094 | TASK [local-log-download : Check API endpoint is defined]
2025-12-01 08:31:54.441483 | localhost | ok: All assertions passed
2025-12-01 08:31:54.445610 | 
2025-12-01 08:31:54.445671 | TASK [local-log-download : Create download script]
2025-12-01 08:31:54.781933 | localhost -> localhost | changed
2025-12-01 08:31:54.790331 | 
2025-12-01 08:31:54.790403 | TASK [Register quick-download link]
2025-12-01 08:31:54.806710 | localhost | ok
2025-12-01 08:31:54.838451 | 
2025-12-01 08:31:54.838518 | PLAY [logserver.rdoproject.org]
2025-12-01 08:31:54.847573 | 
2025-12-01 08:31:54.847632 | TASK [Set zuul-log-path fact]
2025-12-01 08:31:54.862666 | logserver.rdoproject.org | ok
2025-12-01 08:31:54.871045 | 
2025-12-01 08:31:54.871123 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-01 08:31:54.898230 | logserver.rdoproject.org | ok
2025-12-01 08:31:54.904133 | 
2025-12-01 08:31:54.904198 | TASK [upload-logs : Create log directories]
2025-12-01 08:31:56.601630 | logserver.rdoproject.org | changed
2025-12-01 08:31:56.604676 | 
2025-12-01 08:31:56.604769 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-01 08:31:56.820393 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004473
2025-12-01 08:31:56.825075 | 
2025-12-01 08:31:56.825167 | TASK [upload-logs : Upload logs to log server]
2025-12-01 08:31:58.238181 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-01 08:31:58.240889 | 
2025-12-01 08:31:58.240951 | LOOP [upload-logs : Compress console log and json output]
2025-12-01 08:31:58.277169 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 08:31:58.285187 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 08:31:58.289375 | 
2025-12-01 08:31:58.289482 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-01 08:31:58.323838 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 08:31:58.324093 | 
2025-12-01 08:31:58.326940 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 08:31:58.337800 | 
2025-12-01 08:31:58.337882 | LOOP [upload-logs : Upload console log and json output]