2025-12-01 09:02:01.527719 | Job console starting...
2025-12-01 09:02:01.751501 | Updating repositories
2025-12-01 09:02:02.481832 | Preparing job workspace
2025-12-01 09:02:09.804642 | Running Ansible setup...
2025-12-01 09:02:12.789184 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-01 09:02:13.314914 | 
2025-12-01 09:02:13.315021 | PLAY [localhost]
2025-12-01 09:02:13.323358 | 
2025-12-01 09:02:13.323428 | TASK [Gathering Facts]
2025-12-01 09:02:14.107411 | localhost | ok
2025-12-01 09:02:14.121656 | 
2025-12-01 09:02:14.121785 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-01 09:02:14.439962 | localhost -> localhost | changed
2025-12-01 09:02:14.445255 | 
2025-12-01 09:02:14.445325 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-01 09:02:15.137653 | localhost -> localhost | changed
2025-12-01 09:02:15.146048 | 
2025-12-01 09:02:15.146137 | TASK [Setup log path fact]
2025-12-01 09:02:15.163080 | localhost | ok
2025-12-01 09:02:15.173895 | 
2025-12-01 09:02:15.173978 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-01 09:02:15.190778 | localhost | ok
2025-12-01 09:02:15.198323 | 
2025-12-01 09:02:15.198399 | TASK [emit-job-header : Print job information]
2025-12-01 09:02:15.225135 | # Job Information
2025-12-01 09:02:15.225275 | Ansible Version: 2.15.12
2025-12-01 09:02:15.225306 | Job: cifmw-molecule-libvirt_manager
2025-12-01 09:02:15.225328 | Pipeline: github-check
2025-12-01 09:02:15.225348 | Executor: ibm-bm3-ze.softwarefactory-project.io
2025-12-01 09:02:15.225367 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3505
2025-12-01 09:02:15.225386 | Log URL (when completed): https://logserver.rdoproject.org/37e/rdoproject.org/37eeae1097ea47929bb36eacdd5eb154/
2025-12-01 09:02:15.225405 | Event ID: 44f1e2f0-ce94-11f0-8bee-9ee9a0e8ee70
2025-12-01 09:02:15.228929 | 
2025-12-01 09:02:15.229011 | LOOP [emit-job-header : Print node information]
2025-12-01 09:02:15.314361 | localhost | ok:
2025-12-01 09:02:15.314506 | localhost | # Node Information
2025-12-01 09:02:15.314537 | localhost | Inventory Hostname: controller
2025-12-01 09:02:15.314562 | localhost | Hostname: np0005540817
2025-12-01 09:02:15.314584 | localhost | Username: zuul
2025-12-01 09:02:15.314605 | localhost | Distro: CentOS 9
2025-12-01 09:02:15.314624 | localhost | Provider: ibm-bm3-nodepool
2025-12-01 09:02:15.314642 | localhost | Region: regionOne
2025-12-01 09:02:15.314660 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-12-01 09:02:15.314678 | localhost | Product Name: OpenStack Compute
2025-12-01 09:02:15.314712 | localhost | Interface IP: 192.168.25.225
2025-12-01 09:02:15.340188 | 
2025-12-01 09:02:15.340256 | PLAY [all]
2025-12-01 09:02:15.346311 | 
2025-12-01 09:02:15.346375 | TASK [Gather network facts]
2025-12-01 09:02:15.689676 | controller | ok
2025-12-01 09:02:15.703054 | 
2025-12-01 09:02:15.703133 | TASK [include_role : start-zuul-console]
2025-12-01 09:02:15.720284 | controller | ok
2025-12-01 09:02:15.731737 | 
2025-12-01 09:02:15.731803 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-01 09:02:16.033194 | controller | ok
2025-12-01 09:02:16.041417 | 
2025-12-01 09:02:16.041478 | TASK [include_role : add-build-sshkey]
2025-12-01 09:02:16.069319 | controller | ok
2025-12-01 09:02:16.081729 | 
2025-12-01 09:02:16.081818 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-01 09:02:16.277067 | controller -> localhost | ok
2025-12-01 09:02:16.282209 | 
2025-12-01 09:02:16.282285 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-01 09:02:16.320248 | controller | ok
2025-12-01 09:02:16.333224 | controller | included: /var/lib/zuul/builds/37eeae1097ea47929bb36eacdd5eb154/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-01 09:02:16.338567 | 
2025-12-01 09:02:16.338631 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-01 09:02:16.881112 | controller -> localhost | Generating public/private rsa key pair.
2025-12-01 09:02:16.881284 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/37eeae1097ea47929bb36eacdd5eb154/work/37eeae1097ea47929bb36eacdd5eb154_id_rsa.
2025-12-01 09:02:16.881319 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/37eeae1097ea47929bb36eacdd5eb154/work/37eeae1097ea47929bb36eacdd5eb154_id_rsa.pub.
2025-12-01 09:02:16.881343 | controller -> localhost | The key fingerprint is:
2025-12-01 09:02:16.881365 | controller -> localhost | SHA256:HaCr5TVTZgCfrIfxk7+rKYTplAVOV8bVed0jITQe7Wg zuul-build-sshkey
2025-12-01 09:02:16.881387 | controller -> localhost | The key's randomart image is:
2025-12-01 09:02:16.881408 | controller -> localhost | +---[RSA 3072]----+
2025-12-01 09:02:16.881428 | controller -> localhost | |      .+=.o*.o...|
2025-12-01 09:02:16.881447 | controller -> localhost | |    o .=.+. *o..o|
2025-12-01 09:02:16.881466 | controller -> localhost | |   o oo + =.o.. .|
2025-12-01 09:02:16.881484 | controller -> localhost | |    . .* * E .   |
2025-12-01 09:02:16.881502 | controller -> localhost | |     == S o      |
2025-12-01 09:02:16.881521 | controller -> localhost | |    =+.o =       |
2025-12-01 09:02:16.881539 | controller -> localhost | |   o...   .      |
2025-12-01 09:02:16.881557 | controller -> localhost | |    . .  . .     |
2025-12-01 09:02:16.881574 | controller -> localhost | |       .o.o.     |
2025-12-01 09:02:16.881591 | controller -> localhost | +----[SHA256]-----+
2025-12-01 09:02:16.881632 | controller -> localhost | ok: Runtime: 0:00:00.214058
2025-12-01 09:02:16.886949 | 
2025-12-01 09:02:16.887025 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-01 09:02:16.905108 | controller | ok
2025-12-01 09:02:16.914514 | controller | included: /var/lib/zuul/builds/37eeae1097ea47929bb36eacdd5eb154/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-01 09:02:16.922177 | 
2025-12-01 09:02:16.922240 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-01 09:02:16.935597 | controller | skipping: Conditional result was False
2025-12-01 09:02:16.940945 | 
2025-12-01 09:02:16.941028 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-01 09:02:17.312986 | controller | changed
2025-12-01 09:02:17.318506 | 
2025-12-01 09:02:17.318571 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-01 09:02:17.519129 | controller | ok
2025-12-01 09:02:17.523897 | 
2025-12-01 09:02:17.523961 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-01 09:02:18.120963 | controller | changed
2025-12-01 09:02:18.125748 | 
2025-12-01 09:02:18.125814 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-01 09:02:18.730124 | controller | changed
2025-12-01 09:02:18.734960 | 
2025-12-01 09:02:18.735036 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-01 09:02:18.748617 | controller | skipping: Conditional result was False
2025-12-01 09:02:18.754594 | 
2025-12-01 09:02:18.754658 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-01 09:02:19.051479 | controller -> localhost | changed
2025-12-01 09:02:19.060584 | 
2025-12-01 09:02:19.060654 | TASK [add-build-sshkey : Add back temp key]
2025-12-01 09:02:19.288333 | controller -> localhost | Identity added: /var/lib/zuul/builds/37eeae1097ea47929bb36eacdd5eb154/work/37eeae1097ea47929bb36eacdd5eb154_id_rsa (zuul-build-sshkey)
2025-12-01 09:02:19.288573 | controller -> localhost | ok: Runtime: 0:00:00.006829
2025-12-01 09:02:19.294178 | 
2025-12-01 09:02:19.294243 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-01 09:02:19.586324 | controller | ok
2025-12-01 09:02:19.590879 | 
2025-12-01 09:02:19.590943 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-01 09:02:19.614622 | controller | skipping: Conditional result was False
2025-12-01 09:02:19.623864 | 
2025-12-01 09:02:19.623928 | TASK [include_role : validate-host]
2025-12-01 09:02:19.640712 | controller | ok
2025-12-01 09:02:19.660393 | 
2025-12-01 09:02:19.660463 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-01 09:02:19.689162 | controller | ok
2025-12-01 09:02:19.693562 | 
2025-12-01 09:02:19.693627 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-01 09:02:19.899541 | controller -> localhost | ok
2025-12-01 09:02:19.905167 | 
2025-12-01 09:02:19.905231 | TASK [validate-host : Collect information about the host]
2025-12-01 09:02:20.510900 | controller | ok
2025-12-01 09:02:20.520455 | 
2025-12-01 09:02:20.520522 | TASK [validate-host : Sanitize hostname]
2025-12-01 09:02:20.588808 | controller | ok
2025-12-01 09:02:20.594397 | 
2025-12-01 09:02:20.594461 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-01 09:02:21.002350 | controller -> localhost | changed
2025-12-01 09:02:21.007287 | 
2025-12-01 09:02:21.007350 | TASK [validate-host : Collect information about zuul worker]
2025-12-01 09:02:21.328964 | controller | ok
2025-12-01 09:02:21.333682 | 
2025-12-01 09:02:21.333774 | TASK [validate-host : Write out all zuul information for each host]
2025-12-01 09:02:21.691435 | controller -> localhost | changed
2025-12-01 09:02:21.700963 | 
2025-12-01 09:02:21.701070 | TASK [include_role : prepare-workspace-openshift]
2025-12-01 09:02:21.714581 | controller | skipping: Conditional result was False
2025-12-01 09:02:21.719882 | 
2025-12-01 09:02:21.719956 | TASK [include_role : remove-zuul-sshkey]
2025-12-01 09:02:21.733284 | controller | skipping: Conditional result was False
2025-12-01 09:02:21.738480 | 
2025-12-01 09:02:21.738547 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-01 09:02:21.942787 | controller | ok: "logs"
2025-12-01 09:02:21.943061 | controller | ok: All items complete
2025-12-01 09:02:21.943093 | 
2025-12-01 09:02:22.103472 | controller | ok: "artifacts"
2025-12-01 09:02:22.278409 | controller | ok: "docs"
2025-12-01 09:02:22.285094 | 
2025-12-01 09:02:22.285188 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-01 09:02:22.476181 | controller | changed: "logs"
2025-12-01 09:02:22.650503 | controller | changed: "artifacts"
2025-12-01 09:02:22.821097 | controller | changed: "docs"
2025-12-01 09:02:22.844953 | 
2025-12-01 09:02:22.845062 | PLAY RECAP
2025-12-01 09:02:22.845112 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-01 09:02:22.845142 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-01 09:02:22.845162 | 
2025-12-01 09:02:22.948169 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-01 09:02:22.948935 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-01 09:02:23.512160 | 
2025-12-01 09:02:23.512270 | PLAY [all]
2025-12-01 09:02:23.530921 | 
2025-12-01 09:02:23.531005 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-01 09:02:23.579722 | controller | ok
2025-12-01 09:02:23.584481 | 
2025-12-01 09:02:23.584558 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-01 09:02:23.926918 | controller | changed
2025-12-01 09:02:23.932171 | 
2025-12-01 09:02:23.932253 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-01 09:02:24.769789 | controller | changed
2025-12-01 09:02:24.779258 | 
2025-12-01 09:02:24.779337 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-01 09:02:25.134110 | controller | changed:
2025-12-01 09:02:25.134281 | controller | {
2025-12-01 09:02:25.134314 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-01 09:02:25.134345 | controller | }
2025-12-01 09:02:25.341361 | controller | changed:
2025-12-01 09:02:25.341431 | controller | {
2025-12-01 09:02:25.341462 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-01 09:02:25.341485 | controller | }
2025-12-01 09:02:25.562492 | controller | changed:
2025-12-01 09:02:25.562575 | controller | {
2025-12-01 09:02:25.562604 | 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 09:02:25.562626 | controller | }
2025-12-01 09:02:25.768960 | controller | changed:
2025-12-01 09:02:25.769070 | controller | {
2025-12-01 09:02:25.769100 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-01 09:02:25.769123 | controller | }
2025-12-01 09:02:25.985556 | controller | changed:
2025-12-01 09:02:25.985620 | controller | {
2025-12-01 09:02:25.985649 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-01 09:02:25.985671 | controller | }
2025-12-01 09:02:26.202747 | controller | changed:
2025-12-01 09:02:26.202816 | controller | {
2025-12-01 09:02:26.202845 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-01 09:02:26.202867 | controller | }
2025-12-01 09:02:26.415548 | controller | changed:
2025-12-01 09:02:26.415627 | controller | {
2025-12-01 09:02:26.415654 | 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 09:02:26.415677 | controller | }
2025-12-01 09:02:26.638960 | controller | changed:
2025-12-01 09:02:26.639057 | controller | {
2025-12-01 09:02:26.639085 | 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 09:02:26.639109 | controller | }
2025-12-01 09:02:26.856506 | controller | changed:
2025-12-01 09:02:26.856579 | controller | {
2025-12-01 09:02:26.856607 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-01 09:02:26.856628 | controller | }
2025-12-01 09:02:27.070157 | controller | changed:
2025-12-01 09:02:27.070241 | controller | {
2025-12-01 09:02:27.070268 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-01 09:02:27.070289 | controller | }
2025-12-01 09:02:27.290766 | controller | changed:
2025-12-01 09:02:27.290835 | controller | {
2025-12-01 09:02:27.290861 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-01 09:02:27.290881 | controller | }
2025-12-01 09:02:27.498339 | controller | changed:
2025-12-01 09:02:27.498418 | controller | {
2025-12-01 09:02:27.498454 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-01 09:02:27.498482 | controller | }
2025-12-01 09:02:27.707636 | controller | changed:
2025-12-01 09:02:27.707692 | controller | {
2025-12-01 09:02:27.707739 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-01 09:02:27.707761 | controller | }
2025-12-01 09:02:27.926322 | controller | changed:
2025-12-01 09:02:27.926374 | controller | {
2025-12-01 09:02:27.926400 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-01 09:02:27.926421 | controller | }
2025-12-01 09:02:28.136283 | controller | changed:
2025-12-01 09:02:28.136341 | controller | {
2025-12-01 09:02:28.136367 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-01 09:02:28.136387 | controller | }
2025-12-01 09:02:28.344644 | controller | changed:
2025-12-01 09:02:28.344900 | controller | {
2025-12-01 09:02:28.344936 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-01 09:02:28.344958 | controller | }
2025-12-01 09:02:28.551348 | controller | changed:
2025-12-01 09:02:28.551439 | controller | {
2025-12-01 09:02:28.551465 | 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 09:02:28.551487 | controller | }
2025-12-01 09:02:28.761067 | controller | changed:
2025-12-01 09:02:28.761239 | controller | {
2025-12-01 09:02:28.761275 | 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 09:02:28.761308 | controller | }
2025-12-01 09:02:28.978407 | controller | changed:
2025-12-01 09:02:28.978497 | controller | {
2025-12-01 09:02:28.978531 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-01 09:02:28.978558 | controller | }
2025-12-01 09:02:29.193730 | controller | changed:
2025-12-01 09:02:29.193806 | controller | {
2025-12-01 09:02:29.193834 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-01 09:02:29.193856 | controller | }
2025-12-01 09:02:29.402342 | controller | changed:
2025-12-01 09:02:29.402429 | controller | {
2025-12-01 09:02:29.402465 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-01 09:02:29.402494 | controller | }
2025-12-01 09:02:29.610375 | controller | changed:
2025-12-01 09:02:29.610451 | controller | {
2025-12-01 09:02:29.610478 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-01 09:02:29.610498 | controller | }
2025-12-01 09:02:29.829671 | controller | changed:
2025-12-01 09:02:29.829778 | controller | {
2025-12-01 09:02:29.829837 | 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 09:02:29.829863 | controller | }
2025-12-01 09:02:30.042254 | controller | changed:
2025-12-01 09:02:30.042354 | controller | {
2025-12-01 09:02:30.042386 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-01 09:02:30.042411 | controller | }
2025-12-01 09:02:30.256731 | controller | changed:
2025-12-01 09:02:30.256816 | controller | {
2025-12-01 09:02:30.256843 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-01 09:02:30.256864 | controller | }
2025-12-01 09:02:30.463647 | controller | changed:
2025-12-01 09:02:30.463762 | controller | {
2025-12-01 09:02:30.463791 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-01 09:02:30.463813 | controller | }
2025-12-01 09:02:30.487001 | 
2025-12-01 09:02:30.487072 | TASK [Set timezone to UTC]
2025-12-01 09:02:30.890295 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-01 09:02:30.895656 | 
2025-12-01 09:02:30.895756 | TASK [Create nodepool directory]
2025-12-01 09:02:31.119584 | controller | changed
2025-12-01 09:02:31.126728 | 
2025-12-01 09:02:31.126810 | TASK [Create nodepool sub_nodes file]
2025-12-01 09:02:31.650645 | controller | changed
2025-12-01 09:02:31.655497 | 
2025-12-01 09:02:31.655581 | TASK [Create nodepool sub_nodes_private file]
2025-12-01 09:02:32.220486 | controller | changed
2025-12-01 09:02:32.225288 | 
2025-12-01 09:02:32.225348 | LOOP [Populate nodepool sub_nodes file]
2025-12-01 09:02:32.275292 | 
2025-12-01 09:02:32.275421 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-01 09:02:32.326311 | 
2025-12-01 09:02:32.326435 | TASK [Create nodepool primary file]
2025-12-01 09:02:32.349840 | controller | skipping: Conditional result was False
2025-12-01 09:02:32.354943 | 
2025-12-01 09:02:32.355035 | TASK [Create nodepool node_private for this node]
2025-12-01 09:02:32.920740 | controller | changed
2025-12-01 09:02:32.928387 | 
2025-12-01 09:02:32.928468 | LOOP [Copy ssh keys to nodepool directory]
2025-12-01 09:02:33.255898 | controller | ok: Item: id_rsa Runtime: 0:00:00.006067
2025-12-01 09:02:33.258959 | 
2025-12-01 09:02:33.431791 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005270
2025-12-01 09:02:33.443250 | 
2025-12-01 09:02:33.443350 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-01 09:02:34.030803 | controller | changed
2025-12-01 09:02:34.035392 | 
2025-12-01 09:02:34.035452 | TASK [Validate sudoers config after edits]
2025-12-01 09:02:34.266328 | controller | /etc/sudoers: parsed OK
2025-12-01 09:02:34.266404 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-01 09:02:34.266414 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-01 09:02:34.266420 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-01 09:02:34.561211 | controller | ok: Runtime: 0:00:00.006773
2025-12-01 09:02:34.566762 | 
2025-12-01 09:02:34.566823 | TASK [Show the environment passed in to job shell scripts]
2025-12-01 09:02:34.784375 | controller | SHELL=/bin/bash
2025-12-01 09:02:34.784446 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-01 09:02:34.784466 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-01 09:02:34.784534 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/05/3505/ac200803ea9acc6548ecc5f46f5c52fe49b8e1e4
2025-12-01 09:02:34.784543 | controller | PWD=/home/zuul
2025-12-01 09:02:34.784550 | controller | ZUUL_PIPELINE=github-check
2025-12-01 09:02:34.784555 | controller | LOGNAME=zuul
2025-12-01 09:02:34.784561 | controller | XDG_SESSION_TYPE=tty
2025-12-01 09:02:34.784567 | controller | _=/usr/bin/env
2025-12-01 09:02:34.784573 | controller | MOTD_SHOWN=pam
2025-12-01 09:02:34.784578 | controller | HOME=/home/zuul
2025-12-01 09:02:34.784584 | controller | LANG=en_US.UTF-8
2025-12-01 09:02:34.784590 | controller | SSH_CONNECTION=192.168.25.12 40824 192.168.25.225 22
2025-12-01 09:02:34.784596 | 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 09:02:34.784606 | controller | ZUUL_CHANGE_IDS=3505,ac200803ea9acc6548ecc5f46f5c52fe49b8e1e4
2025-12-01 09:02:34.784612 | controller | WORKSPACE=/home/zuul/workspace
2025-12-01 09:02:34.784618 | controller | XDG_SESSION_CLASS=user
2025-12-01 09:02:34.784624 | controller | SELINUX_ROLE_REQUESTED=
2025-12-01 09:02:34.784629 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-01 09:02:34.784634 | controller | USER=zuul
2025-12-01 09:02:34.784640 | controller | ZUUL_VOTING=True
2025-12-01 09:02:34.784645 | controller | BUILD_TIMEOUT=3600000
2025-12-01 09:02:34.784659 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-01 09:02:34.784665 | controller | SHLVL=1
2025-12-01 09:02:34.784671 | controller | ZUUL_PATCHSET=ac200803ea9acc6548ecc5f46f5c52fe49b8e1e4
2025-12-01 09:02:34.784676 | controller | XDG_SESSION_ID=1
2025-12-01 09:02:34.784682 | controller | ZUUL_BRANCH=main
2025-12-01 09:02:34.784687 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-01 09:02:34.784693 | controller | SSH_CLIENT=192.168.25.12 40824 22
2025-12-01 09:02:34.784698 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-01 09:02:34.784704 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-01 09:02:34.784709 | controller | which_declare=declare -f
2025-12-01 09:02:34.784716 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-01 09:02:34.784722 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-01 09:02:34.784728 | controller | ZUUL_CHANGE=3505
2025-12-01 09:02:34.784733 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-01 09:02:34.784738 | controller | ZUUL_UUID=37eeae1097ea47929bb36eacdd5eb154
2025-12-01 09:02:34.784744 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-01 09:02:34.784750 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-01 09:02:34.784755 | controller | }
2025-12-01 09:02:35.093812 | controller | ok: Runtime: 0:00:00.007362
2025-12-01 09:02:35.104128 | 
2025-12-01 09:02:35.104189 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-01 09:02:35.117506 | controller | skipping: Conditional result was False
2025-12-01 09:02:35.122527 | 
2025-12-01 09:02:35.122588 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-01 09:02:35.646302 | controller | skipping: Conditional result was False
2025-12-01 09:02:35.652410 | 
2025-12-01 09:02:35.652485 | TASK [Ensure legacy workspace directory]
2025-12-01 09:02:35.833936 | controller | changed
2025-12-01 09:02:35.851294 | 
2025-12-01 09:02:35.851390 | PLAY RECAP
2025-12-01 09:02:35.851465 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-01 09:02:35.851495 | 
2025-12-01 09:02:35.922393 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-01 09:02:35.923243 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-01 09:02:36.419834 | 
2025-12-01 09:02:36.419927 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-01 09:02:36.439040 | 
2025-12-01 09:02:36.439118 | TASK [Create zuul-output directory]
2025-12-01 09:02:36.741789 | controller | changed
2025-12-01 09:02:36.746652 | 
2025-12-01 09:02:36.746773 | TASK [Slurp Zuul inventory test]
2025-12-01 09:02:37.023328 | controller -> localhost | ok
2025-12-01 09:02:37.029612 | 
2025-12-01 09:02:37.029719 | TASK [Save zuul inventory]
2025-12-01 09:02:37.740525 | controller | changed
2025-12-01 09:02:37.745405 | 
2025-12-01 09:02:37.745483 | TASK [Save zuul vars without the change_message]
2025-12-01 09:02:38.328242 | controller | changed
2025-12-01 09:02:38.368756 | 
2025-12-01 09:02:38.368841 | PLAY RECAP
2025-12-01 09:02:38.368888 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-01 09:02:38.368912 | 
2025-12-01 09:02:38.439626 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-01 09:02:38.440365 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-01 09:02:39.046391 | 
2025-12-01 09:02:39.046498 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-01 09:02:39.067533 | 
2025-12-01 09:02:39.067610 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-01 09:02:39.086036 | controller | ok
2025-12-01 09:02:39.102505 | 
2025-12-01 09:02:39.102589 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-01 09:02:39.125933 | controller | skipping: Conditional result was False
2025-12-01 09:02:39.131563 | 
2025-12-01 09:02:39.131641 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-01 09:02:39.443550 | controller | ok
2025-12-01 09:02:39.449320 | 
2025-12-01 09:02:39.449403 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-01 09:02:40.035798 | controller | ok
2025-12-01 09:02:40.045821 | 
2025-12-01 09:02:40.045886 | TASK [Prepare workspace]
2025-12-01 09:02:40.063829 | controller | ok
2025-12-01 09:02:40.080190 | 
2025-12-01 09:02:40.080258 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-01 09:02:40.365259 | controller | ok
2025-12-01 09:02:40.370994 | 
2025-12-01 09:02:40.371059 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-01 09:02:42.819111 | controller | Output suppressed because no_log was given
2025-12-01 09:02:42.834573 | 
2025-12-01 09:02:42.834638 | LOOP [Create zuul-output directory]
2025-12-01 09:02:43.028529 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-01 09:02:43.183341 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-01 09:02:43.190568 | 
2025-12-01 09:02:43.190673 | TASK [Install required packages]
2025-12-01 09:03:33.474792 | controller | changed
2025-12-01 09:03:33.479961 | 
2025-12-01 09:03:33.480050 | TASK [Install venv]
2025-12-01 09:04:37.614430 | controller | changed
2025-12-01 09:04:37.639670 | 
2025-12-01 09:04:37.639857 | PLAY RECAP
2025-12-01 09:04:37.639917 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-01 09:04:37.639944 | 
2025-12-01 09:04:37.760770 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-01 09:04:37.761616 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-01 09:04:38.279717 | 
2025-12-01 09:04:38.279831 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-12-01 09:04:38.300488 | 
2025-12-01 09:04:38.300569 | TASK [Gather required facts]
2025-12-01 09:04:38.846733 | controller | ok
2025-12-01 09:04:38.852084 | 
2025-12-01 09:04:38.852178 | TASK [Load environment var if instructed to]
2025-12-01 09:04:38.877310 | controller | skipping: Conditional result was False
2025-12-01 09:04:38.882867 | 
2025-12-01 09:04:38.882938 | TASK [Ensure group_vars dir exists]
2025-12-01 09:04:39.195604 | controller | ok
2025-12-01 09:04:39.201652 | 
2025-12-01 09:04:39.201755 | TASK [Print related variables]
2025-12-01 09:04:39.231360 | controller | ok:
2025-12-01 09:04:39.231490 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-12-01 09:04:39.231522 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager
2025-12-01 09:04:39.236324 | 
2025-12-01 09:04:39.236388 | TASK [Run molecule]
2025-12-01 09:04:40.171402 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-12-01 09:04:40.570365 | controller | INFO     Performing prerun with role_name_check=0...
2025-12-01 09:04:57.299334 | 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 09:04:57.299809 | 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 09:04:57.300267 | 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 09:04:57.300704 | 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 09:04:57.301211 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+60d5d122 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 1.0.0+60d5d122 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 09:04:57.301658 | 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 09:04:57.302114 | 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 09:04:57.302549 | 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 09:04:57.302977 | 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 09:04:57.303410 | 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 09:04:57.303895 | 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 09:04:57.304331 | 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 09:04:57.304782 | 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 09:04:57.305240 | 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 09:04:57.305677 | 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 09:04:57.315195 | controller | INFO     Running boot_order > prepare
2025-12-01 09:04:58.094886 | controller |
2025-12-01 09:04:58.094941 | controller | PLAY [Prepare] *****************************************************************
2025-12-01 09:04:58.094974 | controller |
2025-12-01 09:04:58.095093 | controller | TASK [Gathering Facts] *********************************************************
2025-12-01 09:04:58.095192 | controller | Monday 01 December 2025  09:04:58 +0000 (0:00:00.023)       0:00:00.023 *******
2025-12-01 09:04:58.922727 | controller | ok: [instance]
2025-12-01 09:04:58.922836 | controller |
2025-12-01 09:04:58.922950 | controller | TASK [Create custom basedir] ***************************************************
2025-12-01 09:04:58.923063 | controller | Monday 01 December 2025  09:04:58 +0000 (0:00:00.828)       0:00:00.852 *******
2025-12-01 09:04:59.224445 | controller | changed: [instance]
2025-12-01 09:04:59.224517 | controller |
2025-12-01 09:04:59.224636 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-12-01 09:04:59.224737 | controller | Monday 01 December 2025  09:04:59 +0000 (0:00:00.301)       0:00:01.154 *******
2025-12-01 09:04:59.242702 | controller | skipping: [instance]
2025-12-01 09:04:59.242815 | controller |
2025-12-01 09:04:59.242926 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-12-01 09:04:59.243032 | controller | Monday 01 December 2025  09:04:59 +0000 (0:00:00.018)       0:00:01.172 *******
2025-12-01 09:04:59.283523 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-12-01 09:04:59.283611 | controller |
2025-12-01 09:04:59.283723 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-12-01 09:04:59.283867 | controller | Monday 01 December 2025  09:04:59 +0000 (0:00:00.040)       0:00:01.213 *******
2025-12-01 09:04:59.548396 | controller | ok: [instance]
2025-12-01 09:04:59.548489 | controller |
2025-12-01 09:04:59.548601 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-12-01 09:04:59.548699 | controller | Monday 01 December 2025  09:04:59 +0000 (0:00:00.264)       0:00:01.478 *******
2025-12-01 09:04:59.753361 | controller | ok: [instance]
2025-12-01 09:04:59.753448 | controller |
2025-12-01 09:04:59.753555 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-12-01 09:04:59.753659 | controller | Monday 01 December 2025  09:04:59 +0000 (0:00:00.204)       0:00:01.683 *******
2025-12-01 09:05:00.414036 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-12-01 09:05:00.414110 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-12-01 09:05:00.414214 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-12-01 09:05:00.414311 | controller |
2025-12-01 09:05:00.414406 | controller | TASK [Deploy repo-setup] *******************************************************
2025-12-01 09:05:00.414507 | controller | Monday 01 December 2025  09:05:00 +0000 (0:00:00.660)       0:00:02.343 *******
2025-12-01 09:05:00.472599 | controller |
2025-12-01 09:05:00.472811 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-12-01 09:05:00.473029 | controller | Monday 01 December 2025  09:05:00 +0000 (0:00:00.058)       0:00:02.402 *******
2025-12-01 09:05:01.001561 | controller | changed: [instance] => (item=tmp)
2025-12-01 09:05:01.001653 | controller | changed: [instance] => (item=artifacts/repositories)
2025-12-01 09:05:01.001786 | controller | changed: [instance] => (item=venv/repo_setup)
2025-12-01 09:05:01.001911 | controller |
2025-12-01 09:05:01.002028 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-12-01 09:05:01.002129 | controller | Monday 01 December 2025  09:05:00 +0000 (0:00:00.528)       0:00:02.931 *******
2025-12-01 09:05:01.751271 | controller | ok: [instance]
2025-12-01 09:05:01.751329 | controller |
2025-12-01 09:05:01.751579 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-12-01 09:05:02.879837 | controller | Monday 01 December 2025  09:05:01 +0000 (0:00:00.749)       0:00:03.681 *******
2025-12-01 09:05:02.879873 | controller | changed: [instance]
2025-12-01 09:05:10.655585 | controller |
2025-12-01 09:05:10.655622 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-12-01 09:05:10.655631 | controller | Monday 01 December 2025  09:05:02 +0000 (0:00:01.128)       0:00:04.809 *******
2025-12-01 09:05:10.655641 | controller | changed: [instance]
2025-12-01 09:05:11.365361 | controller |
2025-12-01 09:05:11.365390 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-12-01 09:05:11.365398 | controller | Monday 01 December 2025  09:05:10 +0000 (0:00:07.775)       0:00:12.585 *******
2025-12-01 09:05:11.365409 | controller | changed: [instance]
2025-12-01 09:05:11.387815 | controller |
2025-12-01 09:05:11.387840 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-12-01 09:05:11.387848 | controller | Monday 01 December 2025  09:05:11 +0000 (0:00:00.709)       0:00:13.294 *******
2025-12-01 09:05:11.387858 | controller | skipping: [instance]
2025-12-01 09:05:12.183186 | controller |
2025-12-01 09:05:12.183214 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-12-01 09:05:12.183223 | controller | Monday 01 December 2025  09:05:11 +0000 (0:00:00.022)       0:00:13.317 *******
2025-12-01 09:05:12.183233 | controller | changed: [instance]
2025-12-01 09:05:12.219823 | controller |
2025-12-01 09:05:12.219849 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-12-01 09:05:12.219857 | controller | Monday 01 December 2025  09:05:12 +0000 (0:00:00.795)       0:00:14.112 *******
2025-12-01 09:05:12.219867 | controller | skipping: [instance]
2025-12-01 09:05:12.220170 | controller |
2025-12-01 09:05:12.220185 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-12-01 09:05:12.257330 | controller | Monday 01 December 2025  09:05:12 +0000 (0:00:00.036)       0:00:14.149 *******
2025-12-01 09:05:12.257354 | controller | skipping: [instance]
2025-12-01 09:05:12.293838 | controller |
2025-12-01 09:05:12.293861 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-12-01 09:05:12.293871 | controller | Monday 01 December 2025  09:05:12 +0000 (0:00:00.037)       0:00:14.187 *******
2025-12-01 09:05:12.293881 | controller | skipping: [instance]
2025-12-01 09:05:12.293908 | controller |
2025-12-01 09:05:12.293918 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-12-01 09:05:12.294066 | controller | Monday 01 December 2025  09:05:12 +0000 (0:00:00.036)       0:00:14.223 *******
2025-12-01 09:05:12.824070 | controller | changed: [instance]
2025-12-01 09:05:13.278659 | controller |
2025-12-01 09:05:13.278691 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-12-01 09:05:13.278700 | controller | Monday 01 December 2025  09:05:12 +0000 (0:00:00.529)       0:00:14.753 *******
2025-12-01 09:05:13.278710 | controller | changed: [instance]
2025-12-01 09:05:13.306569 | controller |
2025-12-01 09:05:13.306586 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-12-01 09:05:13.306592 | controller | Monday 01 December 2025  09:05:13 +0000 (0:00:00.454)       0:00:15.208 *******
2025-12-01 09:05:13.306604 | controller | skipping: [instance]
2025-12-01 09:05:13.334483 | controller |
2025-12-01 09:05:13.334500 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-12-01 09:05:13.334507 | controller | Monday 01 December 2025  09:05:13 +0000 (0:00:00.028)       0:00:15.236 *******
2025-12-01 09:05:13.334516 | controller | skipping: [instance]
2025-12-01 09:05:13.364039 | controller |
2025-12-01 09:05:13.364063 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-12-01 09:05:13.364073 | controller | Monday 01 December 2025  09:05:13 +0000 (0:00:00.028)       0:00:15.264 *******
2025-12-01 09:05:13.364083 | controller | skipping: [instance]
2025-12-01 09:05:13.401438 | controller |
2025-12-01 09:05:13.401458 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-12-01 09:05:13.401466 | controller | Monday 01 December 2025  09:05:13 +0000 (0:00:00.029)       0:00:15.293 *******
2025-12-01 09:05:13.401475 | controller | ok: [instance]
2025-12-01 09:05:13.428622 | controller |
2025-12-01 09:05:13.428640 | controller | TASK [repo_setup : Create download directory] **********************************
2025-12-01 09:05:13.428648 | controller | Monday 01 December 2025  09:05:13 +0000 (0:00:00.037)       0:00:15.331 *******
2025-12-01 09:05:13.428663 | controller | skipping: [instance]
2025-12-01 09:05:13.456605 | controller |
2025-12-01 09:05:13.456628 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-12-01 09:05:13.456637 | controller | Monday 01 December 2025  09:05:13 +0000 (0:00:00.027)       0:00:15.358 *******
2025-12-01 09:05:13.456648 | controller | skipping: [instance]
2025-12-01 09:05:13.483368 | controller |
2025-12-01 09:05:13.483391 | controller | TASK [Download the RPM] ********************************************************
2025-12-01 09:05:13.483401 | controller | Monday 01 December 2025  09:05:13 +0000 (0:00:00.027)       0:00:15.386 *******
2025-12-01 09:05:13.483412 | controller | skipping: [instance]
2025-12-01 09:05:13.509849 | controller |
2025-12-01 09:05:13.509883 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-12-01 09:05:13.509892 | controller | Monday 01 December 2025  09:05:13 +0000 (0:00:00.026)       0:00:15.413 *******
2025-12-01 09:05:13.509903 | controller | skipping: [instance]
2025-12-01 09:05:13.509928 | controller |
2025-12-01 09:05:13.510119 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-12-01 09:05:13.537306 | controller | Monday 01 December 2025  09:05:13 +0000 (0:00:00.026)       0:00:15.439 *******
2025-12-01 09:05:13.537332 | controller | skipping: [instance]
2025-12-01 09:05:13.565390 | controller |
2025-12-01 09:05:13.565413 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-12-01 09:05:13.565422 | controller | Monday 01 December 2025  09:05:13 +0000 (0:00:00.027)       0:00:15.467 *******
2025-12-01 09:05:13.565433 | controller | skipping: [instance]
2025-12-01 09:05:13.590843 | controller |
2025-12-01 09:05:13.590876 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-12-01 09:05:13.590884 | controller | Monday 01 December 2025  09:05:13 +0000 (0:00:00.028)       0:00:15.495 *******
2025-12-01 09:05:13.590895 | controller | skipping: [instance]
2025-12-01 09:05:13.768575 | controller |
2025-12-01 09:05:13.768606 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-12-01 09:05:13.768614 | controller | Monday 01 December 2025  09:05:13 +0000 (0:00:00.025)       0:00:15.520 *******
2025-12-01 09:05:13.768625 | controller | ok: [instance]
2025-12-01 09:05:13.970616 | controller |
2025-12-01 09:05:13.970645 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-12-01 09:05:13.970653 | controller | Monday 01 December 2025  09:05:13 +0000 (0:00:00.177)       0:00:15.698 *******
2025-12-01 09:05:13.970663 | controller | changed: [instance]
2025-12-01 09:05:14.195217 | controller |
2025-12-01 09:05:14.195249 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-12-01 09:05:14.195258 | controller | Monday 01 December 2025  09:05:13 +0000 (0:00:00.202)       0:00:15.900 *******
2025-12-01 09:05:14.195268 | controller | changed: [instance]
2025-12-01 09:05:14.216210 | controller |
2025-12-01 09:05:14.216232 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-12-01 09:05:14.216240 | controller | Monday 01 December 2025  09:05:14 +0000 (0:00:00.224)       0:00:16.124 *******
2025-12-01 09:05:14.216249 | controller | skipping: [instance]
2025-12-01 09:05:14.237843 | controller |
2025-12-01 09:05:14.237877 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-12-01 09:05:14.237886 | controller | Monday 01 December 2025  09:05:14 +0000 (0:00:00.021)       0:00:16.146 *******
2025-12-01 09:05:14.237896 | controller | skipping: [instance]
2025-12-01 09:05:14.258912 | controller |
2025-12-01 09:05:14.258938 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-12-01 09:05:14.258947 | controller | Monday 01 December 2025  09:05:14 +0000 (0:00:00.021)       0:00:16.167 *******
2025-12-01 09:05:14.258964 | controller | skipping: [instance]
2025-12-01 09:05:14.279849 | controller |
2025-12-01 09:05:14.279884 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-12-01 09:05:14.279892 | controller | Monday 01 December 2025  09:05:14 +0000 (0:00:00.021)       0:00:16.188 *******
2025-12-01 09:05:14.279903 | controller | skipping: [instance]
2025-12-01 09:05:14.279928 | controller |
2025-12-01 09:05:14.280126 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-12-01 09:05:14.302298 | controller | Monday 01 December 2025  09:05:14 +0000 (0:00:00.021)       0:00:16.210 *******
2025-12-01 09:05:14.302358 | controller | skipping: [instance]
2025-12-01 09:05:14.323714 | controller |
2025-12-01 09:05:14.323749 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-12-01 09:05:14.323759 | controller | Monday 01 December 2025  09:05:14 +0000 (0:00:00.022)       0:00:16.232 *******
2025-12-01 09:05:14.323791 | controller | skipping: [instance]
2025-12-01 09:05:14.338495 | controller |
2025-12-01 09:05:14.338513 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-12-01 09:05:14.338520 | controller | Monday 01 December 2025  09:05:14 +0000 (0:00:00.021)       0:00:16.253 *******
2025-12-01 09:05:14.338529 | controller | skipping: [instance]
2025-12-01 09:05:14.365817 | controller |
2025-12-01 09:05:14.365846 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-12-01 09:05:14.365856 | controller | Monday 01 December 2025  09:05:14 +0000 (0:00:00.014)       0:00:16.268 *******
2025-12-01 09:05:14.365868 | controller | skipping: [instance]
2025-12-01 09:05:14.382019 | controller |
2025-12-01 09:05:14.382037 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-12-01 09:05:14.382044 | controller | Monday 01 December 2025  09:05:14 +0000 (0:00:00.027)       0:00:16.295 *******
2025-12-01 09:05:14.382053 | controller | skipping: [instance]
2025-12-01 09:05:14.402063 | controller |
2025-12-01 09:05:14.402084 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-12-01 09:05:14.402093 | controller | Monday 01 December 2025  09:05:14 +0000 (0:00:00.016)       0:00:16.311 *******
2025-12-01 09:05:14.402104 | controller | skipping: [instance]
2025-12-01 09:05:14.402275 | controller |
2025-12-01 09:05:14.402451 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-12-01 09:05:14.402610 | controller | Monday 01 December 2025  09:05:14 +0000 (0:00:00.020)       0:00:16.332 *******
2025-12-01 09:05:14.432189 | controller | skipping: [instance]
2025-12-01 09:05:14.432366 | controller |
2025-12-01 09:05:14.432540 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-12-01 09:05:14.432694 | controller | Monday 01 December 2025  09:05:14 +0000 (0:00:00.030)       0:00:16.362 *******
2025-12-01 09:05:14.461752 | controller | skipping: [instance]
2025-12-01 09:05:14.461964 | controller |
2025-12-01 09:05:14.462142 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-12-01 09:05:14.462314 | controller | Monday 01 December 2025  09:05:14 +0000 (0:00:00.029)       0:00:16.391 *******
2025-12-01 09:05:14.490574 | controller | skipping: [instance]
2025-12-01 09:05:14.490759 | controller |
2025-12-01 09:05:14.490940 | controller | TASK [test_deps : Install additional packages] *********************************
2025-12-01 09:05:14.491085 | controller | Monday 01 December 2025  09:05:14 +0000 (0:00:00.028)       0:00:16.420 *******
2025-12-01 09:05:14.519438 | controller | skipping: [instance]
2025-12-01 09:05:14.519624 | controller |
2025-12-01 09:05:14.519823 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-12-01 09:05:14.519978 | controller | Monday 01 December 2025  09:05:14 +0000 (0:00:00.028)       0:00:16.449 *******
2025-12-01 09:06:01.313578 | controller | ok: [instance]
2025-12-01 09:06:02.524251 | controller |
2025-12-01 09:06:02.524281 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-12-01 09:06:02.524289 | controller | Monday 01 December 2025  09:06:01 +0000 (0:00:46.793)       0:01:03.243 *******
2025-12-01 09:06:02.524300 | controller | ok: [instance]
2025-12-01 09:06:02.556418 | controller |
2025-12-01 09:06:02.556445 | controller | TASK [test_deps : Install extra packages] **************************************
2025-12-01 09:06:02.556453 | controller | Monday 01 December 2025  09:06:02 +0000 (0:00:01.210)       0:01:04.453 *******
2025-12-01 09:06:02.556465 | controller | skipping: [instance]
2025-12-01 09:06:02.598272 | controller |
2025-12-01 09:06:02.598299 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-12-01 09:06:02.598307 | controller | Monday 01 December 2025  09:06:02 +0000 (0:00:00.032)       0:01:04.486 *******
2025-12-01 09:06:02.598317 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-12-01 09:06:02.618986 | controller |
2025-12-01 09:06:02.619032 | controller | TASK [ci_setup : List packages to install] *************************************
2025-12-01 09:06:02.619041 | controller | Monday 01 December 2025  09:06:02 +0000 (0:00:00.041)       0:01:04.527 *******
2025-12-01 09:06:02.619051 | controller | ok: [instance] =>
2025-12-01 09:06:06.369497 | controller |   cifmw_ci_setup_packages:
2025-12-01 09:06:06.369531 | controller |   - bash-completion
2025-12-01 09:06:06.369539 | controller |   - ca-certificates
2025-12-01 09:06:06.369545 | controller |   - git-core
2025-12-01 09:06:06.369551 | controller |   - make
2025-12-01 09:06:06.369556 | controller |   - tar
2025-12-01 09:06:06.369562 | controller |   - tmux
2025-12-01 09:06:06.369567 | controller |   - python3-pip
2025-12-01 09:06:06.369572 | controller |
2025-12-01 09:06:06.369578 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-12-01 09:06:06.369589 | controller | Monday 01 December 2025  09:06:02 +0000 (0:00:00.020)       0:01:04.548 *******
2025-12-01 09:06:06.369600 | controller | changed: [instance]
2025-12-01 09:06:06.554506 | controller |
2025-12-01 09:06:06.554540 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-12-01 09:06:06.554549 | controller | Monday 01 December 2025  09:06:06 +0000 (0:00:03.751)       0:01:08.299 *******
2025-12-01 09:06:06.554559 | controller | ok: [instance]
2025-12-01 09:06:06.761075 | controller |
2025-12-01 09:06:06.761107 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-12-01 09:06:06.761115 | controller | Monday 01 December 2025  09:06:06 +0000 (0:00:00.185)       0:01:08.484 *******
2025-12-01 09:06:06.761125 | controller | changed: [instance]
2025-12-01 09:06:13.144114 | controller |
2025-12-01 09:06:13.144147 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-12-01 09:06:13.144156 | controller | Monday 01 December 2025  09:06:06 +0000 (0:00:00.206)       0:01:08.690 *******
2025-12-01 09:06:13.144166 | controller | changed: [instance]
2025-12-01 09:06:13.180629 | controller |
2025-12-01 09:06:13.180650 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-12-01 09:06:13.180658 | controller | Monday 01 December 2025  09:06:13 +0000 (0:00:06.382)       0:01:15.073 *******
2025-12-01 09:06:13.180667 | controller | ok: [instance]
2025-12-01 09:06:13.436636 | controller |
2025-12-01 09:06:13.436668 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-12-01 09:06:13.436677 | controller | Monday 01 December 2025  09:06:13 +0000 (0:00:00.036)       0:01:15.110 *******
2025-12-01 09:06:13.436687 | controller | changed: [instance]
2025-12-01 09:06:13.703739 | controller |
2025-12-01 09:06:13.703762 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-12-01 09:06:13.703789 | controller | Monday 01 December 2025  09:06:13 +0000 (0:00:00.255)       0:01:15.366 *******
2025-12-01 09:06:13.703798 | controller | changed: [instance]
2025-12-01 09:06:13.721356 | controller |
2025-12-01 09:06:13.721377 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-12-01 09:06:13.721386 | controller | Monday 01 December 2025  09:06:13 +0000 (0:00:00.267)       0:01:15.633 *******
2025-12-01 09:06:13.721397 | controller | skipping: [instance]
2025-12-01 09:06:13.738144 | controller |
2025-12-01 09:06:13.738160 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-12-01 09:06:13.738167 | controller | Monday 01 December 2025  09:06:13 +0000 (0:00:00.017)       0:01:15.651 *******
2025-12-01 09:06:13.738175 | controller | skipping: [instance]
2025-12-01 09:06:13.754841 | controller |
2025-12-01 09:06:13.754860 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-12-01 09:06:13.754868 | controller | Monday 01 December 2025  09:06:13 +0000 (0:00:00.016)       0:01:15.667 *******
2025-12-01 09:06:13.754877 | controller | skipping: [instance]
2025-12-01 09:06:13.754898 | controller |
2025-12-01 09:06:13.754907 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-12-01 09:06:13.755005 | controller | Monday 01 December 2025  09:06:13 +0000 (0:00:00.016)       0:01:15.684 *******
2025-12-01 09:06:13.771374 | controller | skipping: [instance]
2025-12-01 09:06:13.788563 | controller |
2025-12-01 09:06:13.788584 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-12-01 09:06:13.788600 | controller | Monday 01 December 2025  09:06:13 +0000 (0:00:00.016)       0:01:15.701 *******
2025-12-01 09:06:13.788611 | controller | skipping: [instance]
2025-12-01 09:06:13.809619 | controller |
2025-12-01 09:06:13.809637 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-12-01 09:06:13.809644 | controller | Monday 01 December 2025  09:06:13 +0000 (0:00:00.017)       0:01:15.718 *******
2025-12-01 09:06:13.809653 | controller | skipping: [instance]
2025-12-01 09:06:14.760861 | controller |
2025-12-01 09:06:14.760893 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-12-01 09:06:14.760901 | controller | Monday 01 December 2025  09:06:13 +0000 (0:00:00.021)       0:01:15.739 *******
2025-12-01 09:06:14.760911 | controller | changed: [instance] => (item=/opt/basedir/artifacts/manifests/openstack/cr)
2025-12-01 09:06:14.760935 | controller | changed: [instance] => (item=/opt/basedir/logs)
2025-12-01 09:06:14.761525 | controller | ok: [instance] => (item=/opt/basedir/tmp)
2025-12-01 09:06:15.463485 | controller | changed: [instance] => (item=/opt/basedir/volumes)
2025-12-01 09:06:15.463518 | controller | changed: [instance] => (item=/opt/basedir/artifacts/parameters)
2025-12-01 09:06:15.463526 | controller |
2025-12-01 09:06:15.463533 | controller | TASK [libvirt_manager : Get installed packages list] ***************************
2025-12-01 09:06:15.463539 | controller | Monday 01 December 2025  09:06:14 +0000 (0:00:00.951)       0:01:16.690 *******
2025-12-01 09:06:15.463548 | controller | ok: [instance]
2025-12-01 09:06:15.463837 | controller |
2025-12-01 09:06:15.463856 | controller | TASK [libvirt_manager : Populate service facts] ********************************
2025-12-01 09:06:17.224916 | controller | Monday 01 December 2025  09:06:15 +0000 (0:00:00.702)       0:01:17.393 *******
2025-12-01 09:06:17.224957 | controller | ok: [instance]
2025-12-01 09:06:17.287922 | controller |
2025-12-01 09:06:17.287961 | controller | TASK [libvirt_manager : Start virtqemud socket service] ************************
2025-12-01 09:06:17.287971 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:01.761)       0:01:19.154 *******
2025-12-01 09:06:17.287983 | controller | skipping: [instance] => (item=virtqemud.service)
2025-12-01 09:06:17.288013 | controller | skipping: [instance] => (item=virtqemud.socket)
2025-12-01 09:06:17.288020 | controller | skipping: [instance]
2025-12-01 09:06:17.288030 | controller |
2025-12-01 09:06:17.288227 | controller | TASK [libvirt_manager : Set _is_deepscrub internal fact] ***********************
2025-12-01 09:06:17.335884 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.063)       0:01:19.217 *******
2025-12-01 09:06:17.335925 | controller | ok: [instance]
2025-12-01 09:06:17.335965 | controller |
2025-12-01 09:06:17.335975 | controller | TASK [libvirt_manager : List all of the existing virtual machines] *************
2025-12-01 09:06:17.335985 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.047)       0:01:19.265 *******
2025-12-01 09:06:17.395549 | controller | skipping: [instance]
2025-12-01 09:06:17.457570 | controller |
2025-12-01 09:06:17.457609 | controller | TASK [libvirt_manager : Get pool configuration] ********************************
2025-12-01 09:06:17.457619 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.059)       0:01:19.325 *******
2025-12-01 09:06:17.457632 | controller | skipping: [instance]
2025-12-01 09:06:17.562439 | controller |
2025-12-01 09:06:17.562480 | controller | TASK [libvirt_manager : Filter out target environment] *************************
2025-12-01 09:06:17.562490 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.062)       0:01:19.387 *******
2025-12-01 09:06:17.562503 | controller | skipping: [instance]
2025-12-01 09:06:17.624254 | controller |
2025-12-01 09:06:17.624303 | controller | TASK [libvirt_manager : Expose cleanup list] ***********************************
2025-12-01 09:06:17.624314 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.104)       0:01:19.492 *******
2025-12-01 09:06:17.624329 | controller | skipping: [instance]
2025-12-01 09:06:17.686344 | controller |
2025-12-01 09:06:17.686373 | controller | TASK [libvirt_manager : Clean ssh known_hosts] *********************************
2025-12-01 09:06:17.686381 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.061)       0:01:19.554 *******
2025-12-01 09:06:17.686393 | controller | skipping: [instance]
2025-12-01 09:06:17.747864 | controller |
2025-12-01 09:06:17.747886 | controller | TASK [libvirt_manager : Destroy machine] ***************************************
2025-12-01 09:06:17.747903 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.062)       0:01:19.616 *******
2025-12-01 09:06:17.747914 | controller | skipping: [instance]
2025-12-01 09:06:17.810871 | controller |
2025-12-01 09:06:17.810909 | controller | TASK [libvirt_manager : Undefine machine] **************************************
2025-12-01 09:06:17.810919 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.061)       0:01:19.677 *******
2025-12-01 09:06:17.810932 | controller | skipping: [instance]
2025-12-01 09:06:17.810961 | controller |
2025-12-01 09:06:17.810971 | controller | TASK [libvirt_manager : (localhost) Clean ssh jumpers] *************************
2025-12-01 09:06:17.811161 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.063)       0:01:19.740 *******
2025-12-01 09:06:17.876295 | controller | skipping: [instance]
2025-12-01 09:06:17.938472 | controller |
2025-12-01 09:06:17.938499 | controller | TASK [libvirt_manager : (instance) Clean ssh jumpers] **************************
2025-12-01 09:06:17.938509 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.065)       0:01:19.806 *******
2025-12-01 09:06:17.938521 | controller | skipping: [instance]
2025-12-01 09:06:17.998855 | controller |
2025-12-01 09:06:17.998906 | controller | TASK [libvirt_manager : Get network list] **************************************
2025-12-01 09:06:17.998917 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.062)       0:01:19.868 *******
2025-12-01 09:06:17.998931 | controller | skipping: [instance]
2025-12-01 09:06:18.058882 | controller |
2025-12-01 09:06:18.058914 | controller | TASK [libvirt_manager : Filter out target nets] ********************************
2025-12-01 09:06:18.058925 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.060)       0:01:19.928 *******
2025-12-01 09:06:18.058937 | controller | skipping: [instance]
2025-12-01 09:06:18.117710 | controller |
2025-12-01 09:06:18.117750 | controller | TASK [libvirt_manager : Expose cleanup list] ***********************************
2025-12-01 09:06:18.117761 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.060)       0:01:19.988 *******
2025-12-01 09:06:18.117795 | controller | skipping: [instance]
2025-12-01 09:06:18.117879 | controller |
2025-12-01 09:06:18.118044 | controller | TASK [Remove dnsmasq network bits] *********************************************
2025-12-01 09:06:18.118208 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.059)       0:01:20.047 *******
2025-12-01 09:06:18.179553 | controller | skipping: [instance]
2025-12-01 09:06:18.179687 | controller |
2025-12-01 09:06:18.179875 | controller | TASK [libvirt_manager : Destroy networks] **************************************
2025-12-01 09:06:18.180051 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.061)       0:01:20.109 *******
2025-12-01 09:06:18.241824 | controller | skipping: [instance]
2025-12-01 09:06:18.241947 | controller |
2025-12-01 09:06:18.242089 | controller | TASK [libvirt_manager : Undefine networks] *************************************
2025-12-01 09:06:18.242214 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.062)       0:01:20.171 *******
2025-12-01 09:06:18.305431 | controller | skipping: [instance]
2025-12-01 09:06:18.305617 | controller |
2025-12-01 09:06:18.305786 | controller | TASK [libvirt_manager : Ensure no trace of networks lays in nmcli] *************
2025-12-01 09:06:18.305915 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.063)       0:01:20.235 *******
2025-12-01 09:06:18.395122 | controller | skipping: [instance]
2025-12-01 09:06:18.395251 | controller |
2025-12-01 09:06:18.395399 | controller | TASK [libvirt_manager : Find dummy interface connection files] *****************
2025-12-01 09:06:18.395541 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.089)       0:01:20.325 *******
2025-12-01 09:06:18.456130 | controller | skipping: [instance]
2025-12-01 09:06:18.456265 | controller |
2025-12-01 09:06:18.456418 | controller | TASK [libvirt_manager : Remove dummy interface connections] ********************
2025-12-01 09:06:18.456560 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.060)       0:01:20.386 *******
2025-12-01 09:06:18.516633 | controller | skipping: [instance]
2025-12-01 09:06:18.516796 | controller |
2025-12-01 09:06:18.516969 | controller | TASK [libvirt_manager : Clean firewalld libvirt zone] **************************
2025-12-01 09:06:18.517128 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.060)       0:01:20.446 *******
2025-12-01 09:06:18.579759 | controller | skipping: [instance]
2025-12-01 09:06:18.579900 | controller |
2025-12-01 09:06:18.580057 | controller | TASK [libvirt_manager : Ensure firewalld is enabled and started] ***************
2025-12-01 09:06:18.580207 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.062)       0:01:20.509 *******
2025-12-01 09:06:18.640094 | controller | skipping: [instance]
2025-12-01 09:06:18.640237 | controller |
2025-12-01 09:06:18.640373 | controller | TASK [libvirt_manager : Disable masquerade in firewalld default zone] **********
2025-12-01 09:06:18.640498 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.060)       0:01:20.570 *******
2025-12-01 09:06:18.700800 | controller | skipping: [instance]
2025-12-01 09:06:18.700907 | controller |
2025-12-01 09:06:18.701041 | controller | TASK [libvirt_manager : Disable forward in firewalld libvirt zone] *************
2025-12-01 09:06:18.701175 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.060)       0:01:20.630 *******
2025-12-01 09:06:18.761257 | controller | skipping: [instance]
2025-12-01 09:06:18.761410 | controller |
2025-12-01 09:06:18.761558 | controller | TASK [libvirt_manager : Remove cifmw storage pool] *****************************
2025-12-01 09:06:18.761681 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.060)       0:01:20.691 *******
2025-12-01 09:06:18.821907 | controller | skipping: [instance]
2025-12-01 09:06:18.822055 | controller |
2025-12-01 09:06:18.822231 | controller | TASK [libvirt_manager : Remove overlay images from ocp_volume pools if exists] ***
2025-12-01 09:06:18.822378 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.060)       0:01:20.751 *******
2025-12-01 09:06:18.866350 | controller | skipping: [instance]
2025-12-01 09:06:18.866467 | controller |
2025-12-01 09:06:18.866595 | controller | TASK [libvirt_manager : Remove ocp_volumes storage pool] ***********************
2025-12-01 09:06:18.866716 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.044)       0:01:20.796 *******
2025-12-01 09:06:18.927807 | controller | skipping: [instance]
2025-12-01 09:06:18.927992 | controller |
2025-12-01 09:06:18.928142 | controller | TASK [libvirt_manager : Refresh all pools after actions] ***********************
2025-12-01 09:06:18.928263 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.061)       0:01:20.857 *******
2025-12-01 09:06:18.990044 | controller | skipping: [instance]
2025-12-01 09:06:18.990181 | controller |
2025-12-01 09:06:18.990321 | controller | TASK [libvirt_manager : Get temporary key status] ******************************
2025-12-01 09:06:18.990444 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.062)       0:01:20.919 *******
2025-12-01 09:06:19.186899 | controller | ok: [instance]
2025-12-01 09:06:19.219740 | controller |
2025-12-01 09:06:19.219790 | controller | TASK [libvirt_manager : Get public key] ****************************************
2025-12-01 09:06:19.219801 | controller | Monday 01 December 2025  09:06:19 +0000 (0:00:00.196)       0:01:21.116 *******
2025-12-01 09:06:19.219812 | controller | skipping: [instance]
2025-12-01 09:06:19.251890 | controller |
2025-12-01 09:06:19.251927 | controller | TASK [libvirt_manager : Remove public key] *************************************
2025-12-01 09:06:19.251937 | controller | Monday 01 December 2025  09:06:19 +0000 (0:00:00.032)       0:01:21.149 *******
2025-12-01 09:06:19.251955 | controller | skipping: [instance]
2025-12-01 09:06:19.251983 | controller |
2025-12-01 09:06:19.251994 | controller | TASK [libvirt_manager : Remove keypair] ****************************************
2025-12-01 09:06:19.252066 | controller | Monday 01 December 2025  09:06:19 +0000 (0:00:00.032)       0:01:21.181 *******
2025-12-01 09:06:19.313574 | controller | skipping: [instance] => (item=cifmw_reproducer_key.pub)
2025-12-01 09:06:19.579839 | controller | skipping: [instance] => (item=cifmw_reproducer_key)
2025-12-01 09:06:19.579869 | controller | skipping: [instance]
2025-12-01 09:06:19.579877 | controller |
2025-12-01 09:06:19.579884 | controller | TASK [libvirt_manager : List libvirt logs] *************************************
2025-12-01 09:06:19.579890 | controller | Monday 01 December 2025  09:06:19 +0000 (0:00:00.060)       0:01:21.242 *******
2025-12-01 09:06:19.579899 | controller | [WARNING]: Skipped '/var/log/libvirt/qemu/' path due to this access issue:
2025-12-01 09:06:19.602561 | controller | '/var/log/libvirt/qemu/' is not a directory
2025-12-01 09:06:19.602589 | controller | ok: [instance]
2025-12-01 09:06:19.632889 | controller |
2025-12-01 09:06:19.632914 | controller | TASK [libvirt_manager : Remove detected logs] **********************************
2025-12-01 09:06:19.632924 | controller | Monday 01 December 2025  09:06:19 +0000 (0:00:00.289)       0:01:21.532 *******
2025-12-01 09:06:19.632945 | controller | skipping: [instance]
2025-12-01 09:06:19.633044 | controller |
2025-12-01 09:06:19.633067 | controller | TASK [libvirt_manager : Remove workload and volumes] ***************************
2025-12-01 09:06:19.633096 | controller | Monday 01 December 2025  09:06:19 +0000 (0:00:00.030)       0:01:21.562 *******
2025-12-01 09:06:20.999895 | controller | ok: [instance] => (item=workload)
2025-12-01 09:06:20.999958 | controller | changed: [instance] => (item=volumes)
2025-12-01 09:06:20.999971 | controller | ok: [instance] => (item=artifacts/debug_network_data.yml)
2025-12-01 09:06:21.000818 | controller | ok: [instance] => (item=artifacts/debug_pub_net_value.txt)
2025-12-01 09:06:21.381710 | controller | ok: [instance] => (item=artifacts/debug_cifmw_libvirt_manager_layout.yml)
2025-12-01 09:06:21.381738 | controller | ok: [instance] => (item=artifacts/interfaces-info.yml)
2025-12-01 09:06:21.381746 | controller | ok: [instance] => (item=artifacts/libvirt-uuids.yml)
2025-12-01 09:06:21.381752 | controller | ok: [instance] => (item=artifacts/net-map-def-patch.yml)
2025-12-01 09:06:21.381757 | controller |
2025-12-01 09:06:21.381778 | controller | TASK [libvirt_manager : Deepscrub data] ****************************************
2025-12-01 09:06:21.381787 | controller | Monday 01 December 2025  09:06:20 +0000 (0:00:01.366)       0:01:22.929 *******
2025-12-01 09:06:21.381797 | controller | ok: [instance] => (item=images)
2025-12-01 09:06:22.594864 | controller | ok: [instance] => (item=artifacts/virtual-nodes.yml)
2025-12-01 09:06:22.594900 | controller |
2025-12-01 09:06:22.594909 | controller | TASK [podman : Ensure podman is installed] *************************************
2025-12-01 09:06:22.594916 | controller | Monday 01 December 2025  09:06:21 +0000 (0:00:00.381)       0:01:23.311 *******
2025-12-01 09:06:22.594927 | controller | ok: [instance]
2025-12-01 09:06:22.930682 | controller |
2025-12-01 09:06:22.930712 | controller | TASK [podman : Enable loginctl linger for ansible_user_id] *********************
2025-12-01 09:06:22.930720 | controller | Monday 01 December 2025  09:06:22 +0000 (0:00:01.213)       0:01:24.524 *******
2025-12-01 09:06:22.930730 | controller | changed: [instance]
2025-12-01 09:06:22.969902 | controller |
2025-12-01 09:06:22.969941 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul] *******************
2025-12-01 09:06:22.969951 | controller | Monday 01 December 2025  09:06:22 +0000 (0:00:00.335)       0:01:24.860 *******
2025-12-01 09:06:22.969964 | controller | skipping: [instance]
2025-12-01 09:06:22.969994 | controller |
2025-12-01 09:06:22.970002 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul] *******************
2025-12-01 09:06:22.970011 | controller | Monday 01 December 2025  09:06:22 +0000 (0:00:00.039)       0:01:24.899 *******
2025-12-01 09:06:23.006889 | controller | skipping: [instance]
2025-12-01 09:06:23.006936 | controller |
2025-12-01 09:06:23.006947 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed] ***
2025-12-01 09:06:23.006957 | controller | Monday 01 December 2025  09:06:23 +0000 (0:00:00.036)       0:01:24.936 *******
2025-12-01 09:06:23.061601 | controller | skipping: [instance]
2025-12-01 09:06:23.675258 | controller |
2025-12-01 09:06:23.675288 | controller | TASK [virtualbmc : Stop and remove vbmc container] *****************************
2025-12-01 09:06:23.675297 | controller | Monday 01 December 2025  09:06:23 +0000 (0:00:00.054)       0:01:24.991 *******
2025-12-01 09:06:23.675307 | controller | ok: [instance]
2025-12-01 09:06:24.119883 | controller |
2025-12-01 09:06:24.119918 | controller | TASK [virtualbmc : Remove vbmc container image] ********************************
2025-12-01 09:06:24.119929 | controller | Monday 01 December 2025  09:06:23 +0000 (0:00:00.613)       0:01:25.604 *******
2025-12-01 09:06:24.119943 | controller | ok: [instance]
2025-12-01 09:06:24.119979 | controller |
2025-12-01 09:06:24.120248 | controller | TASK [virtualbmc : Check if VBMC key exists] ***********************************
2025-12-01 09:06:24.305383 | controller | Monday 01 December 2025  09:06:24 +0000 (0:00:00.445)       0:01:26.049 *******
2025-12-01 09:06:24.305420 | controller | ok: [instance]
2025-12-01 09:06:24.336920 | controller |
2025-12-01 09:06:24.336956 | controller | TASK [virtualbmc : Slurp key] **************************************************
2025-12-01 09:06:24.336970 | controller | Monday 01 December 2025  09:06:24 +0000 (0:00:00.185)       0:01:26.234 *******
2025-12-01 09:06:24.336986 | controller | skipping: [instance]
2025-12-01 09:06:24.337053 | controller |
2025-12-01 09:06:24.337091 | controller | TASK [virtualbmc : Revoke VBMC SSH access] *************************************
2025-12-01 09:06:24.337103 | controller | Monday 01 December 2025  09:06:24 +0000 (0:00:00.031)       0:01:26.266 *******
2025-12-01 09:06:24.367882 | controller | skipping: [instance]
2025-12-01 09:06:24.367929 | controller |
2025-12-01 09:06:24.367942 | controller | TASK [virtualbmc : Remove vbmc files] ******************************************
2025-12-01 09:06:24.368097 | controller | Monday 01 December 2025  09:06:24 +0000 (0:00:00.031)       0:01:26.297 *******
2025-12-01 09:06:25.076685 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmc-key)
2025-12-01 09:06:25.149184 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmc-key.pub)
2025-12-01 09:06:25.149215 | controller | ok: [instance] => (item=/home/zuul/.ssh/virtualbmc.conf)
2025-12-01 09:06:25.149225 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmcd.sh)
2025-12-01 09:06:25.149232 | controller |
2025-12-01 09:06:25.149239 | controller | TASK [Clean remote ssh config] *************************************************
2025-12-01 09:06:25.149246 | controller | Monday 01 December 2025  09:06:25 +0000 (0:00:00.708)       0:01:27.005 *******
2025-12-01 09:06:25.149258 | controller |
2025-12-01 09:06:25.449381 | controller | TASK [ssh_jumper : Remove Include cifmw_ssh_config.d] **************************
2025-12-01 09:06:25.449411 | controller | Monday 01 December 2025  09:06:25 +0000 (0:00:00.073)       0:01:27.079 *******
2025-12-01 09:06:25.449424 | controller | ok: [instance]
2025-12-01 09:06:25.665456 | controller |
2025-12-01 09:06:25.665487 | controller | TASK [ssh_jumper : Remove cifmw_ssh_config.d directory] ************************
2025-12-01 09:06:25.665495 | controller | Monday 01 December 2025  09:06:25 +0000 (0:00:00.300)       0:01:27.379 *******
2025-12-01 09:06:25.665505 | controller | ok: [instance]
2025-12-01 09:06:25.766667 | controller |
2025-12-01 09:06:25.766698 | controller | TASK [Clean local ssh config] **************************************************
2025-12-01 09:06:25.766705 | controller | Monday 01 December 2025  09:06:25 +0000 (0:00:00.216)       0:01:27.595 *******
2025-12-01 09:06:25.766715 | controller |
2025-12-01 09:06:25.956900 | controller | TASK [ssh_jumper : Remove Include cifmw_ssh_config.d] **************************
2025-12-01 09:06:25.956939 | controller | Monday 01 December 2025  09:06:25 +0000 (0:00:00.101)       0:01:27.696 *******
2025-12-01 09:06:25.956958 | controller | ok: [instance -> localhost]
2025-12-01 09:06:25.957221 | controller |
2025-12-01 09:06:25.957241 | controller | TASK [ssh_jumper : Remove cifmw_ssh_config.d directory] ************************
2025-12-01 09:06:26.166867 | controller | Monday 01 December 2025  09:06:25 +0000 (0:00:00.189)       0:01:27.886 *******
2025-12-01 09:06:26.166903 | controller | ok: [instance -> localhost]
2025-12-01 09:06:26.227760 | controller |
2025-12-01 09:06:26.227802 | controller | TASK [dnsmasq : Cleaning dnsmasq] **********************************************
2025-12-01 09:06:26.227810 | controller | Monday 01 December 2025  09:06:26 +0000 (0:00:00.209)       0:01:28.096 *******
2025-12-01 09:06:26.227820 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/configure.yml for instance
2025-12-01 09:06:30.774476 | controller |
2025-12-01 09:06:30.774507 | controller | TASK [dnsmasq : Install needed packages] ***************************************
2025-12-01 09:06:30.774515 | controller | Monday 01 December 2025  09:06:26 +0000 (0:00:00.061)       0:01:28.157 *******
2025-12-01 09:06:30.774526 | controller | changed: [instance]
2025-12-01 09:06:32.089954 | controller |
2025-12-01 09:06:32.089987 | controller | TASK [dnsmasq : Ensure SELinux knows about the new files and directories] ******
2025-12-01 09:06:32.089997 | controller | Monday 01 December 2025  09:06:30 +0000 (0:00:04.546)       0:01:32.703 *******
2025-12-01 09:06:32.090009 | controller | ok: [instance] => (item={'target': '/etc/cifmw-dnsmasq.conf', 'setype': 'dnsmasq_etc_t'})
2025-12-01 09:06:32.090037 | controller | ok: [instance] => (item={'target': '/etc/cifmw-dnsmasq.d(/.*)?', 'setype': 'dnsmasq_etc_t'})
2025-12-01 09:06:32.090045 | controller | ok: [instance] => (item={'target': '/var/run/cifmw-dnsmasq.pid', 'setype': 'dnsmasq_var_run_t'})
2025-12-01 09:06:32.090054 | controller |
2025-12-01 09:06:32.090302 | controller | TASK [dnsmasq : Manage configuration directory] ********************************
2025-12-01 09:06:32.301799 | controller | Monday 01 December 2025  09:06:32 +0000 (0:00:01.315)       0:01:34.019 *******
2025-12-01 09:06:32.301847 | controller | ok: [instance]
2025-12-01 09:06:32.333882 | controller |
2025-12-01 09:06:32.333916 | controller | TASK [dnsmasq : Manage systemd unit file] **************************************
2025-12-01 09:06:32.333926 | controller | Monday 01 December 2025  09:06:32 +0000 (0:00:00.211)       0:01:34.231 *******
2025-12-01 09:06:32.333939 | controller | skipping: [instance]
2025-12-01 09:06:32.333969 | controller |
2025-12-01 09:06:32.333979 | controller | TASK [dnsmasq : Manage base configuration file] ********************************
2025-12-01 09:06:32.334095 | controller | Monday 01 December 2025  09:06:32 +0000 (0:00:00.032)       0:01:34.263 *******
2025-12-01 09:06:32.386663 | controller | skipping: [instance]
2025-12-01 09:06:32.440046 | controller |
2025-12-01 09:06:32.440085 | controller | TASK [dnsmasq : Render listener configuration] *********************************
2025-12-01 09:06:32.440095 | controller | Monday 01 December 2025  09:06:32 +0000 (0:00:00.052)       0:01:34.316 *******
2025-12-01 09:06:32.440107 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/listener.yml for instance
2025-12-01 09:06:32.472103 | controller |
2025-12-01 09:06:32.472126 | controller | TASK [dnsmasq : Create listener configuration file] ****************************
2025-12-01 09:06:32.472136 | controller | Monday 01 December 2025  09:06:32 +0000 (0:00:00.053)       0:01:34.369 *******
2025-12-01 09:06:32.472154 | controller | skipping: [instance]
2025-12-01 09:06:32.472334 | controller |
2025-12-01 09:06:32.472487 | controller | TASK [dnsmasq : Remove listener configuration file] ****************************
2025-12-01 09:06:32.472623 | controller | Monday 01 December 2025  09:06:32 +0000 (0:00:00.032)       0:01:34.402 *******
2025-12-01 09:06:32.693399 | controller | ok: [instance]
2025-12-01 09:06:32.749508 | controller |
2025-12-01 09:06:32.749532 | controller | TASK [dnsmasq : Render dns configuration] **************************************
2025-12-01 09:06:32.749541 | controller | Monday 01 December 2025  09:06:32 +0000 (0:00:00.220)       0:01:34.623 *******
2025-12-01 09:06:32.749553 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/dns.yml for instance
2025-12-01 09:06:32.781396 | controller |
2025-12-01 09:06:32.781414 | controller | TASK [dnsmasq : Create dns configuration file] *********************************
2025-12-01 09:06:32.781422 | controller | Monday 01 December 2025  09:06:32 +0000 (0:00:00.056)       0:01:34.679 *******
2025-12-01 09:06:32.781431 | controller | skipping: [instance]
2025-12-01 09:06:32.993866 | controller |
2025-12-01 09:06:32.993902 | controller | TASK [dnsmasq : Remove dns configuration file] *********************************
2025-12-01 09:06:32.993912 | controller | Monday 01 December 2025  09:06:32 +0000 (0:00:00.031)       0:01:34.711 *******
2025-12-01 09:06:32.993924 | controller | ok: [instance]
2025-12-01 09:06:32.993954 | controller |
2025-12-01 09:06:32.994205 | controller | TASK [dnsmasq : Add localhost addresses from defined dnsmasq listen addresses to loopback interface] ***
2025-12-01 09:06:33.023509 | controller | Monday 01 December 2025  09:06:32 +0000 (0:00:00.212)       0:01:34.923 *******
2025-12-01 09:06:33.023534 | controller | skipping: [instance]
2025-12-01 09:06:33.055458 | controller |
2025-12-01 09:06:33.055479 | controller | TASK [dnsmasq : Enable and start service] **************************************
2025-12-01 09:06:33.055488 | controller | Monday 01 December 2025  09:06:33 +0000 (0:00:00.029)       0:01:34.953 *******
2025-12-01 09:06:33.055499 | controller | skipping: [instance]
2025-12-01 09:06:33.597588 | controller |
2025-12-01 09:06:33.597618 | controller | TASK [dnsmasq : Stop service] **************************************************
2025-12-01 09:06:33.597627 | controller | Monday 01 December 2025  09:06:33 +0000 (0:00:00.031)       0:01:34.985 *******
2025-12-01 09:06:33.597637 | controller | ok: [instance]
2025-12-01 09:06:33.806918 | controller |
2025-12-01 09:06:33.806965 | controller | TASK [dnsmasq : Remove unit file] **********************************************
2025-12-01 09:06:33.806973 | controller | Monday 01 December 2025  09:06:33 +0000 (0:00:00.541)       0:01:35.527 *******
2025-12-01 09:06:33.806983 | controller | ok: [instance]
2025-12-01 09:06:33.807007 | controller |
2025-12-01 09:06:33.807015 | controller | TASK [dnsmasq : Remove main configuration files] *******************************
2025-12-01 09:06:33.807106 | controller | Monday 01 December 2025  09:06:33 +0000 (0:00:00.209)       0:01:35.736 *******
2025-12-01 09:06:34.203679 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2025-12-01 09:06:34.428866 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.d)
2025-12-01 09:06:34.428898 | controller |
2025-12-01 09:06:34.428907 | controller | TASK [dnsmasq : Remove lease file] *********************************************
2025-12-01 09:06:34.428913 | controller | Monday 01 December 2025  09:06:34 +0000 (0:00:00.396)       0:01:36.133 *******
2025-12-01 09:06:34.428923 | controller | ok: [instance]
2025-12-01 09:06:34.429016 | controller |
2025-12-01 09:06:34.429045 | controller | TASK [radvd : Gather package facts] ********************************************
2025-12-01 09:06:34.429071 | controller | Monday 01 December 2025  09:06:34 +0000 (0:00:00.225)       0:01:36.358 *******
2025-12-01 09:06:34.926385 | controller | ok: [instance]
2025-12-01 09:06:34.964182 | controller |
2025-12-01 09:06:34.964200 | controller | TASK [radvd : Stop and disable radvd service] **********************************
2025-12-01 09:06:34.964209 | controller | Monday 01 December 2025  09:06:34 +0000 (0:00:00.497)       0:01:36.855 *******
2025-12-01 09:06:34.964218 | controller | skipping: [instance]
2025-12-01 09:06:35.167303 | controller |
2025-12-01 09:06:35.167329 | controller | TASK [radvd : Remove radvd configuration file] *********************************
2025-12-01 09:06:35.167337 | controller | Monday 01 December 2025  09:06:34 +0000 (0:00:00.038)       0:01:36.894 *******
2025-12-01 09:06:35.167347 | controller | ok: [instance]
2025-12-01 09:06:35.374696 | controller |
2025-12-01 09:06:35.374719 | controller | TASK [radvd : Remove configuration directory] **********************************
2025-12-01 09:06:35.374727 | controller | Monday 01 December 2025  09:06:35 +0000 (0:00:00.202)       0:01:37.097 *******
2025-12-01 09:06:35.374737 | controller | ok: [instance]
2025-12-01 09:06:35.444459 | controller |
2025-12-01 09:06:35.444484 | controller | TASK [radvd : Remove radvd package] ********************************************
2025-12-01 09:06:35.444493 | controller | Monday 01 December 2025  09:06:35 +0000 (0:00:00.207)       0:01:37.304 *******
2025-12-01 09:06:35.444504 | controller | skipping: [instance]
2025-12-01 09:06:35.641682 | controller |
2025-12-01 09:06:35.641710 | controller | TASK [libvirt_manager : Ensure output directory exists] ************************
2025-12-01 09:06:35.641718 | controller | Monday 01 December 2025  09:06:35 +0000 (0:00:00.069)       0:01:37.374 *******
2025-12-01 09:06:35.641734 | controller | ok: [instance]
2025-12-01 09:06:35.834220 | controller |
2025-12-01 09:06:35.834248 | controller | TASK [libvirt_manager : Get vmx/svm extensions from /proc/cpuinfo] *************
2025-12-01 09:06:35.834256 | controller | Monday 01 December 2025  09:06:35 +0000 (0:00:00.197)       0:01:37.571 *******
2025-12-01 09:06:35.834266 | controller | changed: [instance]
2025-12-01 09:06:36.035387 | controller |
2025-12-01 09:06:36.035414 | controller | TASK [libvirt_manager : Check if KVM is enabled] *******************************
2025-12-01 09:06:36.035422 | controller | Monday 01 December 2025  09:06:35 +0000 (0:00:00.192)       0:01:37.763 *******
2025-12-01 09:06:36.035432 | controller | ok: [instance]
2025-12-01 09:06:36.068862 | controller |
2025-12-01 09:06:36.068896 | controller | TASK [libvirt_manager : Fail if KVM not enabled] *******************************
2025-12-01 09:06:36.068906 | controller | Monday 01 December 2025  09:06:36 +0000 (0:00:00.201)       0:01:37.964 *******
2025-12-01 09:06:36.068918 | controller | skipping: [instance]
2025-12-01 09:06:36.068947 | controller |
2025-12-01 09:06:36.068957 | controller | TASK [libvirt_manager : Check if VMX virtualization] ***************************
2025-12-01 09:06:36.069111 | controller | Monday 01 December 2025  09:06:36 +0000 (0:00:00.033)       0:01:37.998 *******
2025-12-01 09:06:36.101286 | controller | skipping: [instance]
2025-12-01 09:06:36.135855 | controller |
2025-12-01 09:06:36.135885 | controller | TASK [libvirt_manager : Check if SVM virtualization] ***************************
2025-12-01 09:06:36.135895 | controller | Monday 01 December 2025  09:06:36 +0000 (0:00:00.032)       0:01:38.031 *******
2025-12-01 09:06:36.135907 | controller | ok: [instance]
2025-12-01 09:06:36.135935 | controller |
2025-12-01 09:06:36.135946 | controller | TASK [libvirt_manager : Enable VMX virtualization] *****************************
2025-12-01 09:06:36.136119 | controller | Monday 01 December 2025  09:06:36 +0000 (0:00:00.034)       0:01:38.065 *******
2025-12-01 09:06:36.167852 | controller | skipping: [instance]
2025-12-01 09:06:36.167999 | controller |
2025-12-01 09:06:36.168017 | controller | TASK [libvirt_manager : Install packages required for using KVM] ***************
2025-12-01 09:06:36.168191 | controller | Monday 01 December 2025  09:06:36 +0000 (0:00:00.032)       0:01:38.097 *******
2025-12-01 09:07:51.181554 | controller | changed: [instance]
2025-12-01 09:07:51.407832 | controller |
2025-12-01 09:07:51.407862 | controller | TASK [libvirt_manager : Create rules.d polkit folder] **************************
2025-12-01 09:07:51.407870 | controller | Monday 01 December 2025  09:07:51 +0000 (0:01:15.013)       0:02:53.111 *******
2025-12-01 09:07:51.407880 | controller | changed: [instance]
2025-12-01 09:07:51.789925 | controller |
2025-12-01 09:07:51.789961 | controller | TASK [libvirt_manager : Enable UNIX socket PolicyKit auth] *********************
2025-12-01 09:07:51.789980 | controller | Monday 01 December 2025  09:07:51 +0000 (0:00:00.226)       0:02:53.337 *******
2025-12-01 09:07:51.789993 | controller | changed: [instance]
2025-12-01 09:07:51.790020 | controller |
2025-12-01 09:07:51.790143 | controller | TASK [libvirt_manager : Restart service polkit service] ************************
2025-12-01 09:07:51.790248 | controller | Monday 01 December 2025  09:07:51 +0000 (0:00:00.382)       0:02:53.719 *******
2025-12-01 09:07:52.403113 | controller | changed: [instance]
2025-12-01 09:07:52.403186 | controller |
2025-12-01 09:07:52.403294 | controller | TASK [libvirt_manager : Remove directory for service override] *****************
2025-12-01 09:07:52.403401 | controller | Monday 01 December 2025  09:07:52 +0000 (0:00:00.613)       0:02:54.333 *******
2025-12-01 09:07:52.617472 | controller | ok: [instance]
2025-12-01 09:07:53.122059 | controller |
2025-12-01 09:07:53.122090 | controller | TASK [libvirt_manager : Reload systemctl and start/enable virtproxyd.service] ***
2025-12-01 09:07:53.122098 | controller | Monday 01 December 2025  09:07:52 +0000 (0:00:00.213)       0:02:54.547 *******
2025-12-01 09:07:53.122109 | controller | ok: [instance]
2025-12-01 09:07:54.003356 | controller |
2025-12-01 09:07:54.003386 | controller | TASK [libvirt_manager : Ensure firewalld service is up] ************************
2025-12-01 09:07:54.003395 | controller | Monday 01 December 2025  09:07:53 +0000 (0:00:00.504)       0:02:55.051 *******
2025-12-01 09:07:54.003405 | controller | changed: [instance]
2025-12-01 09:07:54.185982 | controller |
2025-12-01 09:07:54.186023 | controller | TASK [libvirt_manager : Ensure libvirt modular driver daemons are started.] ****
2025-12-01 09:07:54.186033 | controller | Monday 01 December 2025  09:07:54 +0000 (0:00:00.881)       0:02:55.932 *******
2025-12-01 09:07:54.186045 | 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 09:07:54.543324 | 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 09:07:54.543355 | 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 09:07:54.543363 | 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 09:07:54.543369 | 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 09:07:54.543381 | 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 09:07:54.543387 | 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 09:07:54.543393 | 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 09:07:54.543398 | controller |
2025-12-01 09:07:54.543404 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for qemu.] *********
2025-12-01 09:07:54.543410 | controller | Monday 01 December 2025  09:07:54 +0000 (0:00:00.181)       0:02:56.114 *******
2025-12-01 09:07:54.543420 | controller | changed: [instance]
2025-12-01 09:07:54.543439 | controller |
2025-12-01 09:07:54.543510 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for qemu.] **********
2025-12-01 09:07:54.543620 | controller | Monday 01 December 2025  09:07:54 +0000 (0:00:00.358)       0:02:56.473 *******
2025-12-01 09:07:54.901555 | controller | changed: [instance]
2025-12-01 09:07:54.901653 | controller |
2025-12-01 09:07:54.901801 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for qemu.] **************
2025-12-01 09:07:54.901954 | controller | Monday 01 December 2025  09:07:54 +0000 (0:00:00.358)       0:02:56.831 *******
2025-12-01 09:07:55.260832 | controller | changed: [instance]
2025-12-01 09:07:55.626620 | controller |
2025-12-01 09:07:55.626651 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for interface.] ****
2025-12-01 09:07:55.626660 | controller | Monday 01 December 2025  09:07:55 +0000 (0:00:00.359)       0:02:57.190 *******
2025-12-01 09:07:55.626670 | controller | changed: [instance]
2025-12-01 09:07:55.989093 | controller |
2025-12-01 09:07:55.989123 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for interface.] *****
2025-12-01 09:07:55.989132 | controller | Monday 01 December 2025  09:07:55 +0000 (0:00:00.365)       0:02:57.556 *******
2025-12-01 09:07:55.989143 | controller | changed: [instance]
2025-12-01 09:07:56.347841 | controller |
2025-12-01 09:07:56.347873 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for interface.] *********
2025-12-01 09:07:56.347881 | controller | Monday 01 December 2025  09:07:55 +0000 (0:00:00.362)       0:02:57.918 *******
2025-12-01 09:07:56.347891 | controller | changed: [instance]
2025-12-01 09:07:56.347917 | controller |
2025-12-01 09:07:56.348190 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for network.] ******
2025-12-01 09:07:56.715524 | controller | Monday 01 December 2025  09:07:56 +0000 (0:00:00.358)       0:02:58.277 *******
2025-12-01 09:07:56.715574 | controller | changed: [instance]
2025-12-01 09:07:57.091310 | controller |
2025-12-01 09:07:57.091335 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for network.] *******
2025-12-01 09:07:57.091343 | controller | Monday 01 December 2025  09:07:56 +0000 (0:00:00.367)       0:02:58.644 *******
2025-12-01 09:07:57.091352 | controller | changed: [instance]
2025-12-01 09:07:57.437893 | controller |
2025-12-01 09:07:57.437929 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for network.] ***********
2025-12-01 09:07:57.437940 | controller | Monday 01 December 2025  09:07:57 +0000 (0:00:00.375)       0:02:59.020 *******
2025-12-01 09:07:57.437952 | controller | changed: [instance]
2025-12-01 09:07:57.795642 | controller |
2025-12-01 09:07:57.795670 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nodedev.] ******
2025-12-01 09:07:57.795679 | controller | Monday 01 December 2025  09:07:57 +0000 (0:00:00.346)       0:02:59.367 *******
2025-12-01 09:07:57.795689 | controller | changed: [instance]
2025-12-01 09:07:58.156935 | controller |
2025-12-01 09:07:58.156981 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nodedev.] *******
2025-12-01 09:07:58.156992 | controller | Monday 01 December 2025  09:07:57 +0000 (0:00:00.357)       0:02:59.725 *******
2025-12-01 09:07:58.157005 | controller | changed: [instance]
2025-12-01 09:07:58.157032 | controller |
2025-12-01 09:07:58.157042 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nodedev.] ***********
2025-12-01 09:07:58.518906 | controller | Monday 01 December 2025  09:07:58 +0000 (0:00:00.361)       0:03:00.086 *******
2025-12-01 09:07:58.518945 | controller | changed: [instance]
2025-12-01 09:07:58.870885 | controller |
2025-12-01 09:07:58.870926 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nwfilter.] *****
2025-12-01 09:07:58.870937 | controller | Monday 01 December 2025  09:07:58 +0000 (0:00:00.361)       0:03:00.448 *******
2025-12-01 09:07:58.870950 | controller | changed: [instance]
2025-12-01 09:07:58.870988 | controller |
2025-12-01 09:07:58.871261 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nwfilter.] ******
2025-12-01 09:07:59.260904 | controller | Monday 01 December 2025  09:07:58 +0000 (0:00:00.352)       0:03:00.800 *******
2025-12-01 09:07:59.260945 | controller | changed: [instance]
2025-12-01 09:07:59.261038 | controller |
2025-12-01 09:07:59.261066 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nwfilter.] **********
2025-12-01 09:07:59.261078 | controller | Monday 01 December 2025  09:07:59 +0000 (0:00:00.389)       0:03:01.190 *******
2025-12-01 09:07:59.626847 | controller | changed: [instance]
2025-12-01 09:07:59.627294 | controller |
2025-12-01 09:07:59.982425 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for secret.] *******
2025-12-01 09:07:59.982463 | controller | Monday 01 December 2025  09:07:59 +0000 (0:00:00.366)       0:03:01.556 *******
2025-12-01 09:07:59.982479 | controller | changed: [instance]
2025-12-01 09:08:00.342286 | controller |
2025-12-01 09:08:00.342318 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for secret.] ********
2025-12-01 09:08:00.342328 | controller | Monday 01 December 2025  09:07:59 +0000 (0:00:00.355)       0:03:01.911 *******
2025-12-01 09:08:00.342347 | controller | changed: [instance]
2025-12-01 09:08:00.701897 | controller |
2025-12-01 09:08:00.701932 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for secret.] ************
2025-12-01 09:08:00.701943 | controller | Monday 01 December 2025  09:08:00 +0000 (0:00:00.359)       0:03:02.271 *******
2025-12-01 09:08:00.701967 | controller | changed: [instance]
2025-12-01 09:08:00.702000 | controller |
2025-12-01 09:08:00.702008 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for storage.] ******
2025-12-01 09:08:00.702018 | controller | Monday 01 December 2025  09:08:00 +0000 (0:00:00.359)       0:03:02.631 *******
2025-12-01 09:08:01.058935 | controller | changed: [instance]
2025-12-01 09:08:01.059015 | controller |
2025-12-01 09:08:01.059028 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for storage.] *******
2025-12-01 09:08:01.059039 | controller | Monday 01 December 2025  09:08:01 +0000 (0:00:00.356)       0:03:02.988 *******
2025-12-01 09:08:01.422610 | controller | changed: [instance]
2025-12-01 09:08:01.780842 | controller |
2025-12-01 09:08:01.780874 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for storage.] ***********
2025-12-01 09:08:01.780882 | controller | Monday 01 December 2025  09:08:01 +0000 (0:00:00.363)       0:03:03.352 *******
2025-12-01 09:08:01.780893 | controller | changed: [instance]
2025-12-01 09:08:01.781259 | controller |
2025-12-01 09:08:02.132925 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for proxy.] ********
2025-12-01 09:08:02.132984 | controller | Monday 01 December 2025  09:08:01 +0000 (0:00:00.358)       0:03:03.710 *******
2025-12-01 09:08:02.133006 | controller | changed: [instance]
2025-12-01 09:08:02.488034 | controller |
2025-12-01 09:08:02.488075 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for proxy.] *********
2025-12-01 09:08:02.488087 | controller | Monday 01 December 2025  09:08:02 +0000 (0:00:00.351)       0:03:04.062 *******
2025-12-01 09:08:02.488101 | controller | changed: [instance]
2025-12-01 09:08:02.488125 | controller |
2025-12-01 09:08:02.488349 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for proxy.] *************
2025-12-01 09:08:02.830837 | controller | Monday 01 December 2025  09:08:02 +0000 (0:00:00.355)       0:03:04.418 *******
2025-12-01 09:08:02.830871 | controller | changed: [instance]
2025-12-01 09:08:02.831194 | controller |
2025-12-01 09:08:02.876816 | controller | TASK [libvirt_manager : Manage service for older releases] *********************
2025-12-01 09:08:02.876835 | controller | Monday 01 December 2025  09:08:02 +0000 (0:00:00.342)       0:03:04.760 *******
2025-12-01 09:08:02.876846 | controller | skipping: [instance]
2025-12-01 09:08:02.877064 | controller |
2025-12-01 09:08:02.877079 | controller | TASK [libvirt_manager : Get libvirt group users] *******************************
2025-12-01 09:08:03.185925 | controller | Monday 01 December 2025  09:08:02 +0000 (0:00:00.046)       0:03:04.806 *******
2025-12-01 09:08:03.185965 | controller | ok: [instance]
2025-12-01 09:08:03.599234 | controller |
2025-12-01 09:08:03.599262 | controller | TASK [libvirt_manager : Add user if not exists in libvirt group] ***************
2025-12-01 09:08:03.599270 | controller | Monday 01 December 2025  09:08:03 +0000 (0:00:00.308)       0:03:05.115 *******
2025-12-01 09:08:03.599281 | controller | changed: [instance]
2025-12-01 09:08:03.947941 | controller |
2025-12-01 09:08:03.947990 | controller | TASK [libvirt_manager : Allow QEMU on home directory for the storage access] ***
2025-12-01 09:08:03.948001 | controller | Monday 01 December 2025  09:08:03 +0000 (0:00:00.413)       0:03:05.528 *******
2025-12-01 09:08:03.948015 | controller | changed: [instance]
2025-12-01 09:08:04.159267 | controller |
2025-12-01 09:08:04.159299 | controller | TASK [libvirt_manager : Inject system connection parameters in bashrc] *********
2025-12-01 09:08:04.159310 | controller | Monday 01 December 2025  09:08:03 +0000 (0:00:00.348)       0:03:05.877 *******
2025-12-01 09:08:04.159322 | controller | [WARNING]: Reset is not implemented for this connection
2025-12-01 09:08:04.197801 | controller | changed: [instance]
2025-12-01 09:08:05.152428 | controller |
2025-12-01 09:08:05.152461 | controller | TASK [libvirt_manager : Ensure we reload user env] *****************************
2025-12-01 09:08:05.152473 | controller | Monday 01 December 2025  09:08:04 +0000 (0:00:00.192)       0:03:06.069 *******
2025-12-01 09:08:05.152479 | controller |
2025-12-01 09:08:05.152484 | controller | TASK [libvirt_manager : Deactivate default] ************************************
2025-12-01 09:08:05.152490 | controller | Monday 01 December 2025  09:08:04 +0000 (0:00:00.057)       0:03:06.127 *******
2025-12-01 09:08:05.152499 | controller | ok: [instance]
2025-12-01 09:08:05.417857 | controller |
2025-12-01 09:08:05.417892 | controller | TASK [libvirt_manager : Removing default] **************************************
2025-12-01 09:08:05.417903 | controller | Monday 01 December 2025  09:08:05 +0000 (0:00:00.954)       0:03:07.081 *******
2025-12-01 09:08:05.417915 | controller | ok: [instance]
2025-12-01 09:08:05.806874 | controller |
2025-12-01 09:08:05.806911 | controller | TASK [Ensure we use dnsmasq for NetworkManager resolver] ***********************
2025-12-01 09:08:05.806929 | controller | Monday 01 December 2025  09:08:05 +0000 (0:00:00.265)       0:03:07.347 *******
2025-12-01 09:08:05.806958 | controller | changed: [instance]
2025-12-01 09:08:06.188865 | controller |
2025-12-01 09:08:06.188897 | controller | TASK [Ensure we delegate .utility zone to our own instance] ********************
2025-12-01 09:08:06.188907 | controller | Monday 01 December 2025  09:08:05 +0000 (0:00:00.389)       0:03:07.736 *******
2025-12-01 09:08:06.188919 | controller | changed: [instance]
2025-12-01 09:08:06.188927 | controller |
2025-12-01 09:08:06.188934 | controller | TASK [Restart NetworkManager] **************************************************
2025-12-01 09:08:06.188955 | controller | Monday 01 December 2025  09:08:06 +0000 (0:00:00.381)       0:03:08.118 *******
2025-12-01 09:08:06.643797 | controller | changed: [instance]
2025-12-01 09:08:06.896520 | controller |
2025-12-01 09:08:06.896555 | controller | TASK [Ensure ocp_volumes exists] ***********************************************
2025-12-01 09:08:06.896563 | controller | Monday 01 December 2025  09:08:06 +0000 (0:00:00.454)       0:03:08.573 *******
2025-12-01 09:08:06.896574 | controller | changed: [instance]
2025-12-01 09:08:06.963460 | controller |
2025-12-01 09:08:06.963485 | controller | PLAY RECAP *********************************************************************
2025-12-01 09:08:06.963494 | controller | instance                   : ok=115  changed=56   unreachable=0    failed=0    skipped=84   rescued=0    ignored=0
2025-12-01 09:08:06.963500 | controller |
2025-12-01 09:08:06.963506 | controller | Monday 01 December 2025  09:08:06 +0000 (0:00:00.250)       0:03:08.823 *******
2025-12-01 09:08:06.963511 | controller | ===============================================================================
2025-12-01 09:08:06.963517 | controller | libvirt_manager : Install packages required for using KVM -------------- 75.01s
2025-12-01 09:08:06.963522 | controller | test_deps : Install selinux python libs -------------------------------- 46.79s
2025-12-01 09:08:06.963527 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.78s
2025-12-01 09:08:06.963533 | controller | ci_setup : Install openshift client ------------------------------------- 6.38s
2025-12-01 09:08:06.963538 | controller | dnsmasq : Install needed packages --------------------------------------- 4.55s
2025-12-01 09:08:06.963544 | controller | ci_setup : Install needed packages -------------------------------------- 3.75s
2025-12-01 09:08:06.963549 | controller | libvirt_manager : Populate service facts -------------------------------- 1.76s
2025-12-01 09:08:06.963554 | controller | libvirt_manager : Remove workload and volumes --------------------------- 1.37s
2025-12-01 09:08:06.963560 | controller | dnsmasq : Ensure SELinux knows about the new files and directories ------ 1.32s
2025-12-01 09:08:06.963565 | controller | podman : Ensure podman is installed ------------------------------------- 1.21s
2025-12-01 09:08:06.963571 | controller | test_deps : Install python yaml libs ------------------------------------ 1.21s
2025-12-01 09:08:06.963577 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.13s
2025-12-01 09:08:06.963582 | controller | libvirt_manager : Deactivate default ------------------------------------ 0.95s
2025-12-01 09:08:06.963587 | controller | ci_setup : Manage directories ------------------------------------------- 0.95s
2025-12-01 09:08:06.963597 | controller | libvirt_manager : Ensure firewalld service is up ------------------------ 0.88s
2025-12-01 09:08:06.963603 | controller | Gathering Facts --------------------------------------------------------- 0.83s
2025-12-01 09:08:06.963608 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.80s
2025-12-01 09:08:06.963614 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.75s
2025-12-01 09:08:06.963619 | controller | repo_setup : Install repo-setup package --------------------------------- 0.71s
2025-12-01 09:08:06.963624 | controller | virtualbmc : Remove vbmc files ------------------------------------------ 0.71s
2025-12-01 09:08:06.963634 | controller | INFO     Running boot_order > converge
2025-12-01 09:08:07.436045 | controller |
2025-12-01 09:08:07.436242 | controller | PLAY [Test boot_order configuration] *******************************************
2025-12-01 09:08:07.436379 | controller |
2025-12-01 09:08:07.436518 | controller | TASK [Gathering Facts] *********************************************************
2025-12-01 09:08:07.436670 | controller | Monday 01 December 2025  09:08:07 +0000 (0:00:00.039)       0:00:00.039 *******
2025-12-01 09:08:08.341964 | controller | ok: [instance]
2025-12-01 09:08:08.342183 | controller |
2025-12-01 09:08:08.342217 | controller | TASK [Load networking definition] **********************************************
2025-12-01 09:08:08.385190 | controller | Monday 01 December 2025  09:08:08 +0000 (0:00:00.906)       0:00:00.946 *******
2025-12-01 09:08:08.385226 | controller | ok: [instance]
2025-12-01 09:08:08.458866 | controller |
2025-12-01 09:08:08.458891 | controller | TASK [libvirt_manager : Import layout generator if needed] *********************
2025-12-01 09:08:08.458898 | controller | Monday 01 December 2025  09:08:08 +0000 (0:00:00.042)       0:00:00.989 *******
2025-12-01 09:08:08.458908 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/generate_layout.yml for instance
2025-12-01 09:08:08.596864 | controller |
2025-12-01 09:08:08.596902 | controller | TASK [libvirt_manager : Chose right parameter for layout definition] ***********
2025-12-01 09:08:08.596915 | controller | Monday 01 December 2025  09:08:08 +0000 (0:00:00.073)       0:00:01.062 *******
2025-12-01 09:08:08.596929 | controller | ok: [instance]
2025-12-01 09:08:08.712613 | controller |
2025-12-01 09:08:08.712663 | controller | TASK [libvirt_manager : Patch the layout if needed] ****************************
2025-12-01 09:08:08.712673 | controller | Monday 01 December 2025  09:08:08 +0000 (0:00:00.137)       0:00:01.200 *******
2025-12-01 09:08:08.712688 | controller | skipping: [instance]
2025-12-01 09:08:08.712698 | controller |
2025-12-01 09:08:08.712870 | controller | TASK [libvirt_manager : Enable forwarding in the libvirt zone] *****************
2025-12-01 09:08:08.713004 | controller | Monday 01 December 2025  09:08:08 +0000 (0:00:00.116)       0:00:01.316 *******
2025-12-01 09:08:09.228381 | controller | changed: [instance]
2025-12-01 09:08:09.827964 | controller |
2025-12-01 09:08:09.827995 | controller | TASK [libvirt_manager : Enable masquerading for public traffic] ****************
2025-12-01 09:08:09.828004 | controller | Monday 01 December 2025  09:08:09 +0000 (0:00:00.515)       0:00:01.832 *******
2025-12-01 09:08:09.828014 | controller | changed: [instance]
2025-12-01 09:08:10.814571 | controller |
2025-12-01 09:08:10.814601 | controller | TASK [libvirt_manager : Ensure firewalld is restarts] **************************
2025-12-01 09:08:10.814610 | controller | Monday 01 December 2025  09:08:09 +0000 (0:00:00.525)       0:00:02.357 *******
2025-12-01 09:08:10.814616 | controller |
2025-12-01 09:08:10.814622 | controller | RUNNING HANDLER [libvirt_manager : Restart firewalld] **************************
2025-12-01 09:08:10.814627 | controller | Monday 01 December 2025  09:08:09 +0000 (0:00:00.073)       0:00:02.430 *******
2025-12-01 09:08:10.814637 | controller | changed: [instance]
2025-12-01 09:08:10.866903 | controller |
2025-12-01 09:08:10.866950 | controller | TASK [Deploy virtualbmc] *******************************************************
2025-12-01 09:08:10.866961 | controller | Monday 01 December 2025  09:08:10 +0000 (0:00:00.987)       0:00:03.418 *******
2025-12-01 09:08:10.866974 | controller | skipping: [instance]
2025-12-01 09:08:10.867007 | controller |
2025-12-01 09:08:10.867018 | controller | TASK [libvirt_manager : Let the project know we have vbmc available] ***********
2025-12-01 09:08:10.867141 | controller | Monday 01 December 2025  09:08:10 +0000 (0:00:00.052)       0:00:03.471 *******
2025-12-01 09:08:10.940886 | controller | skipping: [instance]
2025-12-01 09:08:11.602894 | controller |
2025-12-01 09:08:11.602940 | controller | TASK [libvirt_manager : Create needed workload directory] **********************
2025-12-01 09:08:11.602951 | controller | Monday 01 December 2025  09:08:10 +0000 (0:00:00.073)       0:00:03.544 *******
2025-12-01 09:08:11.602964 | controller | changed: [instance] => (item=workload)
2025-12-01 09:08:11.602994 | controller | changed: [instance] => (item=reproducer-inventory)
2025-12-01 09:08:11.603002 | controller | changed: [instance] => (item=volumes)
2025-12-01 09:08:11.603011 | controller |
2025-12-01 09:08:11.603261 | controller | TASK [libvirt_manager : Allow QEMU on workload directory] **********************
2025-12-01 09:08:12.163912 | controller | Monday 01 December 2025  09:08:11 +0000 (0:00:00.661)       0:00:04.206 *******
2025-12-01 09:08:12.163967 | controller | changed: [instance] => (item=workload)
2025-12-01 09:08:12.164011 | controller | changed: [instance] => (item=volumes)
2025-12-01 09:08:12.164022 | controller |
2025-12-01 09:08:12.164029 | controller | TASK [libvirt_manager : Generate networking data] ******************************
2025-12-01 09:08:12.164039 | controller | Monday 01 December 2025  09:08:12 +0000 (0:00:00.560)       0:00:04.767 *******
2025-12-01 09:08:12.315655 | 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 09:08:12.356952 | controller |
2025-12-01 09:08:12.356985 | controller | TASK [libvirt_manager : Ensure fact is existing, and empty] ********************
2025-12-01 09:08:12.356995 | controller | Monday 01 December 2025  09:08:12 +0000 (0:00:00.151)       0:00:04.919 *******
2025-12-01 09:08:12.357007 | controller | ok: [instance]
2025-12-01 09:08:12.643851 | controller |
2025-12-01 09:08:12.643923 | controller | TASK [libvirt_manager : Check if we get an existing interfaces-info.yml] *******
2025-12-01 09:08:12.643952 | controller | Monday 01 December 2025  09:08:12 +0000 (0:00:00.041)       0:00:04.960 *******
2025-12-01 09:08:12.643967 | controller | ok: [instance]
2025-12-01 09:08:12.695498 | controller |
2025-12-01 09:08:12.695537 | controller | TASK [libvirt_manager : Load file if exists] ***********************************
2025-12-01 09:08:12.695548 | controller | Monday 01 December 2025  09:08:12 +0000 (0:00:00.286)       0:00:05.247 *******
2025-12-01 09:08:12.695560 | controller | skipping: [instance]
2025-12-01 09:08:12.747395 | controller |
2025-12-01 09:08:12.747423 | controller | TASK [libvirt_manager : Update fact with existing content] *********************
2025-12-01 09:08:12.747437 | controller | Monday 01 December 2025  09:08:12 +0000 (0:00:00.051)       0:00:05.299 *******
2025-12-01 09:08:12.747448 | controller | skipping: [instance]
2025-12-01 09:08:12.851752 | controller |
2025-12-01 09:08:12.851810 | controller | TASK [libvirt_manager : Generate VM list] **************************************
2025-12-01 09:08:12.851820 | controller | Monday 01 December 2025  09:08:12 +0000 (0:00:00.052)       0:00:05.351 *******
2025-12-01 09:08:12.851832 | controller | ok: [instance]
2025-12-01 09:08:13.017533 | controller |
2025-12-01 09:08:13.017569 | controller | TASK [libvirt_manager : Generate/update mapping all nodes] *********************
2025-12-01 09:08:13.017580 | controller | Monday 01 December 2025  09:08:12 +0000 (0:00:00.104)       0:00:05.455 *******
2025-12-01 09:08:13.017592 | controller | ok: [instance] => (item={'key': 'disk-first-0', 'value': 'disk_first'})
2025-12-01 09:08:13.017817 | controller | ok: [instance] => (item={'key': 'net-first-0', 'value': 'net_first'})
2025-12-01 09:08:13.018029 | controller | ok: [instance] => (item={'key': 'net-only-0', 'value': 'net_only'})
2025-12-01 09:08:13.018205 | controller | ok: [instance] => (item={'key': 'no-boot-order-0', 'value': 'no_boot_order'})
2025-12-01 09:08:13.018358 | controller |
2025-12-01 09:08:13.018494 | controller | TASK [libvirt_manager : Append baremetal nodes if needed] **********************
2025-12-01 09:08:13.018628 | controller | Monday 01 December 2025  09:08:13 +0000 (0:00:00.166)       0:00:05.621 *******
2025-12-01 09:08:13.073513 | controller | skipping: [instance]
2025-12-01 09:08:13.073696 | controller |
2025-12-01 09:08:13.073940 | controller | TASK [libvirt_manager : Dump MAC mapping] **************************************
2025-12-01 09:08:13.074126 | controller | Monday 01 December 2025  09:08:13 +0000 (0:00:00.056)       0:00:05.677 *******
2025-12-01 09:08:13.551888 | controller | changed: [instance]
2025-12-01 09:08:13.551954 | controller |
2025-12-01 09:08:13.551968 | controller | TASK [libvirt_manager : Add libvirt hosts into inventory and dump inventory file] ***
2025-12-01 09:08:13.552133 | controller | Monday 01 December 2025  09:08:13 +0000 (0:00:00.477)       0:00:06.155 *******
2025-12-01 09:08:13.710842 | 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 09:08:13.790944 | 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 09:08:13.790981 | 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 09:08:13.790998 | 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 09:08:13.791007 | controller |
2025-12-01 09:08:13.791014 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2025-12-01 09:08:13.791022 | controller | Monday 01 December 2025  09:08:13 +0000 (0:00:00.158)       0:00:06.313 *******
2025-12-01 09:08:13.791034 | controller | changed: [instance]
2025-12-01 09:08:14.105623 | controller |
2025-12-01 09:08:14.105663 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2025-12-01 09:08:14.105674 | controller | Monday 01 December 2025  09:08:13 +0000 (0:00:00.080)       0:00:06.394 *******
2025-12-01 09:08:14.105688 | controller | changed: [instance]
2025-12-01 09:08:14.348734 | controller |
2025-12-01 09:08:14.348791 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2025-12-01 09:08:14.348805 | controller | Monday 01 December 2025  09:08:14 +0000 (0:00:00.314)       0:00:06.709 *******
2025-12-01 09:08:14.348819 | controller | changed: [instance]
2025-12-01 09:08:14.430344 | controller |
2025-12-01 09:08:14.430383 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2025-12-01 09:08:14.430394 | controller | Monday 01 December 2025  09:08:14 +0000 (0:00:00.243)       0:00:06.952 *******
2025-12-01 09:08:14.430408 | controller | changed: [instance]
2025-12-01 09:08:14.690015 | controller |
2025-12-01 09:08:14.690059 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2025-12-01 09:08:14.690073 | controller | Monday 01 December 2025  09:08:14 +0000 (0:00:00.081)       0:00:07.034 *******
2025-12-01 09:08:14.690091 | controller | changed: [instance]
2025-12-01 09:08:14.927422 | controller |
2025-12-01 09:08:14.927452 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2025-12-01 09:08:14.927461 | controller | Monday 01 December 2025  09:08:14 +0000 (0:00:00.259)       0:00:07.294 *******
2025-12-01 09:08:14.927471 | controller | changed: [instance]
2025-12-01 09:08:15.005374 | controller |
2025-12-01 09:08:15.005396 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2025-12-01 09:08:15.005404 | controller | Monday 01 December 2025  09:08:14 +0000 (0:00:00.237)       0:00:07.531 *******
2025-12-01 09:08:15.005413 | controller | changed: [instance]
2025-12-01 09:08:15.227896 | controller |
2025-12-01 09:08:15.227946 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2025-12-01 09:08:15.227957 | controller | Monday 01 December 2025  09:08:15 +0000 (0:00:00.078)       0:00:07.609 *******
2025-12-01 09:08:15.227977 | controller | changed: [instance]
2025-12-01 09:08:15.228008 | controller |
2025-12-01 09:08:15.228019 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2025-12-01 09:08:15.228160 | controller | Monday 01 December 2025  09:08:15 +0000 (0:00:00.222)       0:00:07.832 *******
2025-12-01 09:08:15.461886 | controller | changed: [instance]
2025-12-01 09:08:15.462117 | controller |
2025-12-01 09:08:15.462153 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2025-12-01 09:08:15.568429 | controller | Monday 01 December 2025  09:08:15 +0000 (0:00:00.233)       0:00:08.066 *******
2025-12-01 09:08:15.568479 | controller | changed: [instance]
2025-12-01 09:08:15.794175 | controller |
2025-12-01 09:08:15.794199 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2025-12-01 09:08:15.794207 | controller | Monday 01 December 2025  09:08:15 +0000 (0:00:00.106)       0:00:08.172 *******
2025-12-01 09:08:15.794216 | controller | changed: [instance]
2025-12-01 09:08:16.030876 | controller |
2025-12-01 09:08:16.030908 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2025-12-01 09:08:16.030928 | controller | Monday 01 December 2025  09:08:15 +0000 (0:00:00.225)       0:00:08.398 *******
2025-12-01 09:08:16.030941 | controller | changed: [instance]
2025-12-01 09:08:16.031012 | controller |
2025-12-01 09:08:16.031028 | controller | TASK [libvirt_manager : Convert ini inventory file into yaml] ******************
2025-12-01 09:08:16.031039 | controller | Monday 01 December 2025  09:08:16 +0000 (0:00:00.236)       0:00:08.634 *******
2025-12-01 09:08:16.058325 | controller | fatal: [instance]: FAILED! =>
2025-12-01 09:08:16.058663 | controller |   msg: |-
2025-12-01 09:08:16.058686 | 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): '_vm_type' is undefined. '_vm_type' is undefined
2025-12-01 09:08:16.058697 | controller |
2025-12-01 09:08:16.058705 | 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 137, column 7, but may
2025-12-01 09:08:16.058716 | controller |     be elsewhere in the file depending on the exact syntax problem.
2025-12-01 09:08:16.058742 | controller |
2025-12-01 09:08:16.058752 | controller |     The offending line appears to be:
2025-12-01 09:08:16.058873 | controller |
2025-12-01 09:08:16.059055 | controller |
2025-12-01 09:08:16.059154 | controller |         - name: Convert ini inventory file into yaml
2025-12-01 09:08:16.059172 | controller |           ^ here
2025-12-01 09:08:16.060805 | controller |
2025-12-01 09:08:16.060867 | controller | PLAY RECAP *********************************************************************
2025-12-01 09:08:16.060875 | controller | instance                   : ok=31   changed=18   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2025-12-01 09:08:16.060882 | controller |
2025-12-01 09:08:16.060887 | controller | Monday 01 December 2025  09:08:16 +0000 (0:00:00.029)       0:00:08.664 *******
2025-12-01 09:08:16.060893 | controller | ===============================================================================
2025-12-01 09:08:16.060898 | controller | libvirt_manager : Restart firewalld ------------------------------------- 0.99s
2025-12-01 09:08:16.060903 | controller | Gathering Facts --------------------------------------------------------- 0.91s
2025-12-01 09:08:16.060909 | controller | libvirt_manager : Create needed workload directory ---------------------- 0.66s
2025-12-01 09:08:16.060916 | controller | libvirt_manager : Allow QEMU on workload directory ---------------------- 0.56s
2025-12-01 09:08:16.060998 | controller | libvirt_manager : Enable masquerading for public traffic ---------------- 0.53s
2025-12-01 09:08:16.061120 | controller | libvirt_manager : Enable forwarding in the libvirt zone ----------------- 0.52s
2025-12-01 09:08:16.061231 | controller | libvirt_manager : Dump MAC mapping -------------------------------------- 0.48s
2025-12-01 09:08:16.061346 | controller | libvirt_manager : Ensure group section exists --------------------------- 0.31s
2025-12-01 09:08:16.061451 | controller | libvirt_manager : Check if we get an existing interfaces-info.yml ------- 0.29s
2025-12-01 09:08:16.061559 | controller | libvirt_manager : Ensure group section exists --------------------------- 0.26s
2025-12-01 09:08:16.061668 | controller | libvirt_manager : Append host under proper group ------------------------ 0.24s
2025-12-01 09:08:16.061790 | controller | libvirt_manager : Append host under proper group ------------------------ 0.24s
2025-12-01 09:08:16.061917 | controller | libvirt_manager : Append host under proper group ------------------------ 0.24s
2025-12-01 09:08:16.062041 | controller | libvirt_manager : Append host under proper group ------------------------ 0.23s
2025-12-01 09:08:16.062149 | controller | libvirt_manager : Ensure group section exists --------------------------- 0.23s
2025-12-01 09:08:16.062260 | controller | libvirt_manager : Ensure group section exists --------------------------- 0.22s
2025-12-01 09:08:16.062376 | controller | libvirt_manager : Generate/update mapping all nodes --------------------- 0.17s
2025-12-01 09:08:16.062492 | controller | libvirt_manager : Add libvirt hosts into inventory and dump inventory file --- 0.16s
2025-12-01 09:08:16.062604 | controller | libvirt_manager : Generate networking data ------------------------------ 0.15s
2025-12-01 09:08:16.062711 | controller | libvirt_manager : Chose right parameter for layout definition ----------- 0.14s
2025-12-01 09:08:16.119546 | 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 09:08:16.119955 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-12-01 09:08:16.128372 | controller | INFO     Running boot_order > cleanup
2025-12-01 09:08:16.628025 | controller |
2025-12-01 09:08:16.628144 | controller | PLAY [Cleanup] *****************************************************************
2025-12-01 09:08:16.628308 | controller |
2025-12-01 09:08:16.628403 | controller | TASK [Gathering Facts] *********************************************************
2025-12-01 09:08:16.628515 | controller | Monday 01 December 2025  09:08:16 +0000 (0:00:00.039)       0:00:00.039 *******
2025-12-01 09:08:17.520647 | controller | ok: [instance]
2025-12-01 09:08:17.845862 | controller |
2025-12-01 09:08:17.845896 | controller | TASK [Ensure we have the destination directory] ********************************
2025-12-01 09:08:17.845906 | controller | Monday 01 December 2025  09:08:17 +0000 (0:00:00.892)       0:00:00.932 *******
2025-12-01 09:08:17.845930 | controller | changed: [instance]
2025-12-01 09:08:17.845982 | controller |
2025-12-01 09:08:17.846010 | controller | TASK [Copy generated content to proper location] *******************************
2025-12-01 09:08:17.846023 | controller | Monday 01 December 2025  09:08:17 +0000 (0:00:00.325)       0:00:01.257 *******
2025-12-01 09:08:19.235926 | controller | changed: [instance] => (item=/opt/basedir/artifacts)
2025-12-01 09:08:19.235984 | controller | changed: [instance] => (item=/opt/basedir/logs)
2025-12-01 09:08:19.235999 | controller | ok: [instance] => (item=/opt/basedir/cifmw_config_drive)
2025-12-01 09:08:19.236498 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2025-12-01 09:08:20.052873 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.d)
2025-12-01 09:08:20.052906 | controller |
2025-12-01 09:08:20.052930 | controller | TASK [libvirt_manager : Get installed packages list] ***************************
2025-12-01 09:08:20.052938 | controller | Monday 01 December 2025  09:08:19 +0000 (0:00:01.390)       0:00:02.647 *******
2025-12-01 09:08:20.052950 | controller | ok: [instance]
2025-12-01 09:08:20.052989 | controller |
2025-12-01 09:08:20.052999 | controller | TASK [libvirt_manager : Populate service facts] ********************************
2025-12-01 09:08:20.053008 | controller | Monday 01 December 2025  09:08:20 +0000 (0:00:00.816)       0:00:03.464 *******
2025-12-01 09:08:22.083285 | controller | ok: [instance]
2025-12-01 09:08:22.954456 | controller |
2025-12-01 09:08:22.954487 | controller | TASK [libvirt_manager : Start virtqemud socket service] ************************
2025-12-01 09:08:22.954495 | controller | Monday 01 December 2025  09:08:22 +0000 (0:00:02.030)       0:00:05.494 *******
2025-12-01 09:08:22.954505 | controller | ok: [instance] => (item=virtqemud.service)
2025-12-01 09:08:23.004860 | controller | ok: [instance] => (item=virtqemud.socket)
2025-12-01 09:08:23.004886 | controller |
2025-12-01 09:08:23.004894 | controller | TASK [libvirt_manager : Set _is_deepscrub internal fact] ***********************
2025-12-01 09:08:23.004900 | controller | Monday 01 December 2025  09:08:22 +0000 (0:00:00.870)       0:00:06.365 *******
2025-12-01 09:08:23.004921 | controller | ok: [instance]
2025-12-01 09:08:23.388875 | controller |
2025-12-01 09:08:23.388916 | controller | TASK [libvirt_manager : List all of the existing virtual machines] *************
2025-12-01 09:08:23.388927 | controller | Monday 01 December 2025  09:08:23 +0000 (0:00:00.050)       0:00:06.416 *******
2025-12-01 09:08:23.388940 | controller | ok: [instance]
2025-12-01 09:08:23.803863 | controller |
2025-12-01 09:08:23.803893 | controller | TASK [libvirt_manager : Get pool configuration] ********************************
2025-12-01 09:08:23.803919 | controller | Monday 01 December 2025  09:08:23 +0000 (0:00:00.383)       0:00:06.800 *******
2025-12-01 09:08:23.803931 | controller | ok: [instance]
2025-12-01 09:08:23.882869 | controller |
2025-12-01 09:08:23.882899 | controller | TASK [libvirt_manager : Filter out target environment] *************************
2025-12-01 09:08:23.882918 | controller | Monday 01 December 2025  09:08:23 +0000 (0:00:00.414)       0:00:07.215 *******
2025-12-01 09:08:23.882931 | controller | ok: [instance]
2025-12-01 09:08:23.883007 | controller |
2025-12-01 09:08:23.883022 | controller | TASK [libvirt_manager : Expose cleanup list] ***********************************
2025-12-01 09:08:23.883032 | controller | Monday 01 December 2025  09:08:23 +0000 (0:00:00.079)       0:00:07.294 *******
2025-12-01 09:08:23.960409 | controller | ok: [instance] =>
2025-12-01 09:08:23.993888 | controller |   cleanup_vms: []
2025-12-01 09:08:23.993927 | controller |
2025-12-01 09:08:23.993937 | controller | TASK [libvirt_manager : Clean ssh known_hosts] *********************************
2025-12-01 09:08:23.993945 | controller | Monday 01 December 2025  09:08:23 +0000 (0:00:00.077)       0:00:07.371 *******
2025-12-01 09:08:23.993956 | controller | skipping: [instance]
2025-12-01 09:08:23.993985 | controller |
2025-12-01 09:08:23.993992 | controller | TASK [libvirt_manager : Destroy machine] ***************************************
2025-12-01 09:08:23.994001 | controller | Monday 01 December 2025  09:08:23 +0000 (0:00:00.033)       0:00:07.405 *******
2025-12-01 09:08:24.027114 | controller | skipping: [instance]
2025-12-01 09:08:24.027266 | controller |
2025-12-01 09:08:24.027419 | controller | TASK [libvirt_manager : Undefine machine] **************************************
2025-12-01 09:08:24.027575 | controller | Monday 01 December 2025  09:08:24 +0000 (0:00:00.033)       0:00:07.439 *******
2025-12-01 09:08:24.060444 | controller | skipping: [instance]
2025-12-01 09:08:24.060566 | controller |
2025-12-01 09:08:24.060692 | controller | TASK [libvirt_manager : (localhost) Clean ssh jumpers] *************************
2025-12-01 09:08:24.060834 | controller | Monday 01 December 2025  09:08:24 +0000 (0:00:00.033)       0:00:07.472 *******
2025-12-01 09:08:24.094923 | controller | skipping: [instance]
2025-12-01 09:08:24.095097 | controller |
2025-12-01 09:08:24.095270 | controller | TASK [libvirt_manager : (instance) Clean ssh jumpers] **************************
2025-12-01 09:08:24.095390 | controller | Monday 01 December 2025  09:08:24 +0000 (0:00:00.034)       0:00:07.506 *******
2025-12-01 09:08:24.127318 | controller | skipping: [instance]
2025-12-01 09:08:24.127439 | controller |
2025-12-01 09:08:24.127592 | controller | TASK [libvirt_manager : Get network list] **************************************
2025-12-01 09:08:24.127740 | controller | Monday 01 December 2025  09:08:24 +0000 (0:00:00.032)       0:00:07.539 *******
2025-12-01 09:08:24.520871 | controller | ok: [instance]
2025-12-01 09:08:24.521131 | controller |
2025-12-01 09:08:24.521152 | controller | TASK [libvirt_manager : Filter out target nets] ********************************
2025-12-01 09:08:24.610753 | controller | Monday 01 December 2025  09:08:24 +0000 (0:00:00.393)       0:00:07.932 *******
2025-12-01 09:08:24.610811 | controller | ok: [instance]
2025-12-01 09:08:24.687859 | controller |
2025-12-01 09:08:24.687887 | controller | TASK [libvirt_manager : Expose cleanup list] ***********************************
2025-12-01 09:08:24.687897 | controller | Monday 01 December 2025  09:08:24 +0000 (0:00:00.089)       0:00:08.022 *******
2025-12-01 09:08:24.687920 | controller | ok: [instance] =>
2025-12-01 09:08:24.687948 | controller |   cleanup_nets: []
2025-12-01 09:08:24.687958 | controller |
2025-12-01 09:08:24.688203 | controller | TASK [Remove dnsmasq network bits] *********************************************
2025-12-01 09:08:24.719871 | controller | Monday 01 December 2025  09:08:24 +0000 (0:00:00.077)       0:00:08.099 *******
2025-12-01 09:08:24.719920 | controller | skipping: [instance]
2025-12-01 09:08:24.751876 | controller |
2025-12-01 09:08:24.751900 | controller | TASK [libvirt_manager : Destroy networks] **************************************
2025-12-01 09:08:24.751922 | controller | Monday 01 December 2025  09:08:24 +0000 (0:00:00.031)       0:00:08.131 *******
2025-12-01 09:08:24.751933 | controller | skipping: [instance]
2025-12-01 09:08:24.783847 | controller |
2025-12-01 09:08:24.783874 | controller | TASK [libvirt_manager : Undefine networks] *************************************
2025-12-01 09:08:24.783883 | controller | Monday 01 December 2025  09:08:24 +0000 (0:00:00.032)       0:00:08.163 *******
2025-12-01 09:08:24.783900 | controller | skipping: [instance]
2025-12-01 09:08:24.783942 | controller |
2025-12-01 09:08:24.784225 | controller | TASK [libvirt_manager : Ensure no trace of networks lays in nmcli] *************
2025-12-01 09:08:24.816144 | controller | Monday 01 December 2025  09:08:24 +0000 (0:00:00.032)       0:00:08.195 *******
2025-12-01 09:08:24.816175 | controller | skipping: [instance]
2025-12-01 09:08:25.156635 | controller |
2025-12-01 09:08:25.156665 | controller | TASK [libvirt_manager : Find dummy interface connection files] *****************
2025-12-01 09:08:25.156673 | controller | Monday 01 December 2025  09:08:24 +0000 (0:00:00.031)       0:00:08.227 *******
2025-12-01 09:08:25.156683 | controller | ok: [instance]
2025-12-01 09:08:25.190440 | controller |
2025-12-01 09:08:25.190463 | controller | TASK [libvirt_manager : Remove dummy interface connections] ********************
2025-12-01 09:08:25.190471 | controller | Monday 01 December 2025  09:08:25 +0000 (0:00:00.340)       0:00:08.568 *******
2025-12-01 09:08:25.190480 | controller | skipping: [instance]
2025-12-01 09:08:25.222727 | controller |
2025-12-01 09:08:25.222752 | controller | TASK [libvirt_manager : Clean firewalld libvirt zone] **************************
2025-12-01 09:08:25.222799 | controller | Monday 01 December 2025  09:08:25 +0000 (0:00:00.033)       0:00:08.601 *******
2025-12-01 09:08:25.222819 | controller | skipping: [instance]
2025-12-01 09:08:25.823877 | controller |
2025-12-01 09:08:25.823918 | controller | TASK [libvirt_manager : Ensure firewalld is enabled and started] ***************
2025-12-01 09:08:25.823926 | controller | Monday 01 December 2025  09:08:25 +0000 (0:00:00.032)       0:00:08.634 *******
2025-12-01 09:08:25.823936 | controller | ok: [instance]
2025-12-01 09:08:26.301558 | controller |
2025-12-01 09:08:26.301588 | controller | TASK [libvirt_manager : Disable masquerade in firewalld default zone] **********
2025-12-01 09:08:26.301597 | controller | Monday 01 December 2025  09:08:25 +0000 (0:00:00.601)       0:00:09.235 *******
2025-12-01 09:08:26.301607 | controller | changed: [instance]
2025-12-01 09:08:26.854445 | controller |
2025-12-01 09:08:26.854477 | controller | TASK [libvirt_manager : Disable forward in firewalld libvirt zone] *************
2025-12-01 09:08:26.854488 | controller | Monday 01 December 2025  09:08:26 +0000 (0:00:00.477)       0:00:09.712 *******
2025-12-01 09:08:26.854503 | controller | changed: [instance]
2025-12-01 09:08:26.966336 | controller |
2025-12-01 09:08:26.966370 | controller | TASK [libvirt_manager : Remove cifmw storage pool] *****************************
2025-12-01 09:08:26.966380 | controller | Monday 01 December 2025  09:08:26 +0000 (0:00:00.552)       0:00:10.265 *******
2025-12-01 09:08:26.966392 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/storage_pool.yml for instance
2025-12-01 09:08:27.046872 | controller |
2025-12-01 09:08:27.046909 | controller | TASK [libvirt_manager : Ensure we have a known action] *************************
2025-12-01 09:08:27.046919 | controller | Monday 01 December 2025  09:08:26 +0000 (0:00:00.111)       0:00:10.377 *******
2025-12-01 09:08:27.046929 | controller | ok: [instance] => changed=false
2025-12-01 09:08:27.046955 | controller |   msg: All assertions passed
2025-12-01 09:08:27.047215 | controller |
2025-12-01 09:08:27.342369 | controller | TASK [libvirt_manager : Gather the status of cifmw-pool storage pool.] *********
2025-12-01 09:08:27.342403 | controller | Monday 01 December 2025  09:08:27 +0000 (0:00:00.080)       0:00:10.458 *******
2025-12-01 09:08:27.342415 | controller | changed: [instance]
2025-12-01 09:08:27.419946 | controller |
2025-12-01 09:08:27.419981 | controller | TASK [libvirt_manager : Create the storage pool] *******************************
2025-12-01 09:08:27.419989 | controller | Monday 01 December 2025  09:08:27 +0000 (0:00:00.295)       0:00:10.753 *******
2025-12-01 09:08:27.419999 | controller | skipping: [instance]
2025-12-01 09:08:27.528559 | controller |
2025-12-01 09:08:27.528636 | controller | TASK [libvirt_manager : Ensure the storage pool is started] ********************
2025-12-01 09:08:27.528646 | controller | Monday 01 December 2025  09:08:27 +0000 (0:00:00.077)       0:00:10.831 *******
2025-12-01 09:08:27.528659 | controller | skipping: [instance] => (item=pool-start)
2025-12-01 09:08:27.607381 | controller | skipping: [instance] => (item=pool-autostart)
2025-12-01 09:08:27.607434 | controller | skipping: [instance]
2025-12-01 09:08:27.607444 | controller |
2025-12-01 09:08:27.607451 | controller | TASK [libvirt_manager : Gather all the volumes in the pool to delete] **********
2025-12-01 09:08:27.607469 | controller | Monday 01 December 2025  09:08:27 +0000 (0:00:00.107)       0:00:10.938 *******
2025-12-01 09:08:27.607482 | controller | skipping: [instance]
2025-12-01 09:08:27.686551 | controller |
2025-12-01 09:08:27.686590 | controller | TASK [libvirt_manager : Remove the volumes from pool to delete] ****************
2025-12-01 09:08:27.686601 | controller | Monday 01 December 2025  09:08:27 +0000 (0:00:00.079)       0:00:11.018 *******
2025-12-01 09:08:27.686614 | controller | skipping: [instance]
2025-12-01 09:08:27.686823 | controller |
2025-12-01 09:08:27.687044 | controller | TASK [libvirt_manager : Delete storage pool] ***********************************
2025-12-01 09:08:27.687229 | controller | Monday 01 December 2025  09:08:27 +0000 (0:00:00.079)       0:00:11.098 *******
2025-12-01 09:08:27.796699 | controller | skipping: [instance] => (item=pool-destroy)
2025-12-01 09:08:27.796957 | controller | skipping: [instance] => (item=pool-undefine)
2025-12-01 09:08:27.797144 | controller | skipping: [instance]
2025-12-01 09:08:27.797307 | controller |
2025-12-01 09:08:27.797479 | controller | TASK [libvirt_manager : Refresh pools facts] ***********************************
2025-12-01 09:08:27.797624 | controller | Monday 01 December 2025  09:08:27 +0000 (0:00:00.110)       0:00:11.208 *******
2025-12-01 09:08:28.094934 | controller | ok: [instance]
2025-12-01 09:08:28.095031 | controller |
2025-12-01 09:08:28.095061 | controller | TASK [libvirt_manager : Remove overlay images from ocp_volume pools if exists] ***
2025-12-01 09:08:28.095074 | controller | Monday 01 December 2025  09:08:28 +0000 (0:00:00.297)       0:00:11.506 *******
2025-12-01 09:08:28.141931 | controller | skipping: [instance]
2025-12-01 09:08:28.142122 | controller |
2025-12-01 09:08:28.142144 | controller | TASK [libvirt_manager : Remove ocp_volumes storage pool] ***********************
2025-12-01 09:08:28.255401 | controller | Monday 01 December 2025  09:08:28 +0000 (0:00:00.047)       0:00:11.553 *******
2025-12-01 09:08:28.255430 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/storage_pool.yml for instance
2025-12-01 09:08:28.393929 | controller |
2025-12-01 09:08:28.393974 | controller | TASK [libvirt_manager : Ensure we have a known action] *************************
2025-12-01 09:08:28.393983 | controller | Monday 01 December 2025  09:08:28 +0000 (0:00:00.113)       0:00:11.666 *******
2025-12-01 09:08:28.393995 | controller | ok: [instance] => changed=false
2025-12-01 09:08:28.394282 | controller |   msg: All assertions passed
2025-12-01 09:08:28.394311 | controller |
2025-12-01 09:08:28.394329 | controller | TASK [libvirt_manager : Gather the status of cifmw-pool storage pool.] *********
2025-12-01 09:08:28.679582 | controller | Monday 01 December 2025  09:08:28 +0000 (0:00:00.138)       0:00:11.805 *******
2025-12-01 09:08:28.679611 | controller | changed: [instance]
2025-12-01 09:08:28.759581 | controller |
2025-12-01 09:08:28.759606 | controller | TASK [libvirt_manager : Create the storage pool] *******************************
2025-12-01 09:08:28.759616 | controller | Monday 01 December 2025  09:08:28 +0000 (0:00:00.285)       0:00:12.091 *******
2025-12-01 09:08:28.759627 | controller | skipping: [instance]
2025-12-01 09:08:28.866578 | controller |
2025-12-01 09:08:28.866599 | controller | TASK [libvirt_manager : Ensure the storage pool is started] ********************
2025-12-01 09:08:28.866606 | controller | Monday 01 December 2025  09:08:28 +0000 (0:00:00.079)       0:00:12.171 *******
2025-12-01 09:08:28.866615 | controller | skipping: [instance] => (item=pool-start)
2025-12-01 09:08:28.945422 | controller | skipping: [instance] => (item=pool-autostart)
2025-12-01 09:08:28.945450 | controller | skipping: [instance]
2025-12-01 09:08:28.945458 | controller |
2025-12-01 09:08:28.945464 | controller | TASK [libvirt_manager : Gather all the volumes in the pool to delete] **********
2025-12-01 09:08:28.945476 | controller | Monday 01 December 2025  09:08:28 +0000 (0:00:00.106)       0:00:12.277 *******
2025-12-01 09:08:28.945485 | controller | skipping: [instance]
2025-12-01 09:08:29.024277 | controller |
2025-12-01 09:08:29.024320 | controller | TASK [libvirt_manager : Remove the volumes from pool to delete] ****************
2025-12-01 09:08:29.024333 | controller | Monday 01 December 2025  09:08:28 +0000 (0:00:00.078)       0:00:12.356 *******
2025-12-01 09:08:29.024350 | controller | skipping: [instance]
2025-12-01 09:08:29.024589 | controller |
2025-12-01 09:08:29.024740 | controller | TASK [libvirt_manager : Delete storage pool] ***********************************
2025-12-01 09:08:29.024919 | controller | Monday 01 December 2025  09:08:29 +0000 (0:00:00.079)       0:00:12.435 *******
2025-12-01 09:08:29.133239 | controller | skipping: [instance] => (item=pool-destroy)
2025-12-01 09:08:29.133879 | controller | skipping: [instance] => (item=pool-undefine)
2025-12-01 09:08:29.134065 | controller | skipping: [instance]
2025-12-01 09:08:29.134205 | controller |
2025-12-01 09:08:29.134347 | controller | TASK [libvirt_manager : Refresh pools facts] ***********************************
2025-12-01 09:08:29.134487 | controller | Monday 01 December 2025  09:08:29 +0000 (0:00:00.109)       0:00:12.545 *******
2025-12-01 09:08:29.422895 | controller | ok: [instance]
2025-12-01 09:08:29.423215 | controller |
2025-12-01 09:08:29.423253 | controller | TASK [libvirt_manager : Refresh all pools after actions] ***********************
2025-12-01 09:08:29.467932 | controller | Monday 01 December 2025  09:08:29 +0000 (0:00:00.289)       0:00:12.834 *******
2025-12-01 09:08:29.467974 | controller | skipping: [instance]
2025-12-01 09:08:29.766240 | controller |
2025-12-01 09:08:29.766272 | controller | TASK [libvirt_manager : Get temporary key status] ******************************
2025-12-01 09:08:29.766280 | controller | Monday 01 December 2025  09:08:29 +0000 (0:00:00.044)       0:00:12.879 *******
2025-12-01 09:08:29.766290 | controller | ok: [instance]
2025-12-01 09:08:29.803291 | controller |
2025-12-01 09:08:29.803312 | controller | TASK [libvirt_manager : Get public key] ****************************************
2025-12-01 09:08:29.803319 | controller | Monday 01 December 2025  09:08:29 +0000 (0:00:00.297)       0:00:13.177 *******
2025-12-01 09:08:29.803328 | controller | skipping: [instance]
2025-12-01 09:08:29.839894 | controller |
2025-12-01 09:08:29.839944 | controller | TASK [libvirt_manager : Remove public key] *************************************
2025-12-01 09:08:29.839955 | controller | Monday 01 December 2025  09:08:29 +0000 (0:00:00.037)       0:00:13.214 *******
2025-12-01 09:08:29.839969 | controller | skipping: [instance]
2025-12-01 09:08:29.881158 | controller |
2025-12-01 09:08:29.881191 | controller | TASK [libvirt_manager : Remove keypair] ****************************************
2025-12-01 09:08:29.881201 | controller | Monday 01 December 2025  09:08:29 +0000 (0:00:00.036)       0:00:13.251 *******
2025-12-01 09:08:29.881223 | controller | skipping: [instance] => (item=cifmw_reproducer_key.pub)
2025-12-01 09:08:29.881253 | controller | skipping: [instance] => (item=cifmw_reproducer_key)
2025-12-01 09:08:29.881369 | controller | skipping: [instance]
2025-12-01 09:08:29.881693 | controller |
2025-12-01 09:08:30.105507 | controller | TASK [libvirt_manager : List libvirt logs] *************************************
2025-12-01 09:08:30.105542 | controller | Monday 01 December 2025  09:08:29 +0000 (0:00:00.041)       0:00:13.293 *******
2025-12-01 09:08:30.105557 | controller | ok: [instance]
2025-12-01 09:08:30.138580 | controller |
2025-12-01 09:08:30.138604 | controller | TASK [libvirt_manager : Remove detected logs] **********************************
2025-12-01 09:08:30.138613 | controller | Monday 01 December 2025  09:08:30 +0000 (0:00:00.223)       0:00:13.516 *******
2025-12-01 09:08:30.138624 | controller | skipping: [instance]
2025-12-01 09:08:31.550918 | controller |
2025-12-01 09:08:31.550953 | controller | TASK [libvirt_manager : Remove workload and volumes] ***************************
2025-12-01 09:08:31.550962 | controller | Monday 01 December 2025  09:08:30 +0000 (0:00:00.033)       0:00:13.550 *******
2025-12-01 09:08:31.550973 | controller | changed: [instance] => (item=workload)
2025-12-01 09:08:31.550998 | controller | changed: [instance] => (item=volumes)
2025-12-01 09:08:31.551005 | controller | ok: [instance] => (item=artifacts/debug_network_data.yml)
2025-12-01 09:08:31.551011 | controller | ok: [instance] => (item=artifacts/debug_pub_net_value.txt)
2025-12-01 09:08:31.551016 | controller | ok: [instance] => (item=artifacts/debug_cifmw_libvirt_manager_layout.yml)
2025-12-01 09:08:31.551022 | controller | changed: [instance] => (item=artifacts/interfaces-info.yml)
2025-12-01 09:08:31.551027 | controller | ok: [instance] => (item=artifacts/libvirt-uuids.yml)
2025-12-01 09:08:31.551033 | controller | ok: [instance] => (item=artifacts/net-map-def-patch.yml)
2025-12-01 09:08:31.551040 | controller |
2025-12-01 09:08:31.551243 | controller | TASK [libvirt_manager : Deepscrub data] ****************************************
2025-12-01 09:08:31.935884 | controller | Monday 01 December 2025  09:08:31 +0000 (0:00:01.411)       0:00:14.961 *******
2025-12-01 09:08:31.935942 | controller | ok: [instance] => (item=images)
2025-12-01 09:08:36.339314 | controller | ok: [instance] => (item=artifacts/virtual-nodes.yml)
2025-12-01 09:08:36.339350 | controller |
2025-12-01 09:08:36.339357 | controller | TASK [podman : Ensure podman is installed] *************************************
2025-12-01 09:08:36.339363 | controller | Monday 01 December 2025  09:08:31 +0000 (0:00:00.385)       0:00:15.347 *******
2025-12-01 09:08:36.339373 | controller | ok: [instance]
2025-12-01 09:08:36.339391 | controller |
2025-12-01 09:08:36.339417 | controller | TASK [podman : Enable loginctl linger for ansible_user_id] *********************
2025-12-01 09:08:36.339573 | controller | Monday 01 December 2025  09:08:36 +0000 (0:00:04.403)       0:00:19.751 *******
2025-12-01 09:08:36.597053 | controller | changed: [instance]
2025-12-01 09:08:36.639469 | controller |
2025-12-01 09:08:36.639487 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul] *******************
2025-12-01 09:08:36.639494 | controller | Monday 01 December 2025  09:08:36 +0000 (0:00:00.257)       0:00:20.008 *******
2025-12-01 09:08:36.639512 | controller | skipping: [instance]
2025-12-01 09:08:36.677440 | controller |
2025-12-01 09:08:36.677463 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul] *******************
2025-12-01 09:08:36.677474 | controller | Monday 01 December 2025  09:08:36 +0000 (0:00:00.042)       0:00:20.051 *******
2025-12-01 09:08:36.677486 | controller | skipping: [instance]
2025-12-01 09:08:36.737201 | controller |
2025-12-01 09:08:36.737217 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed] ***
2025-12-01 09:08:36.737224 | controller | Monday 01 December 2025  09:08:36 +0000 (0:00:00.037)       0:00:20.089 *******
2025-12-01 09:08:36.737232 | controller | skipping: [instance]
2025-12-01 09:08:37.170917 | controller |
2025-12-01 09:08:37.170955 | controller | TASK [virtualbmc : Stop and remove vbmc container] *****************************
2025-12-01 09:08:37.170966 | controller | Monday 01 December 2025  09:08:36 +0000 (0:00:00.059)       0:00:20.148 *******
2025-12-01 09:08:37.170979 | controller | ok: [instance]
2025-12-01 09:08:37.587302 | controller |
2025-12-01 09:08:37.587331 | controller | TASK [virtualbmc : Remove vbmc container image] ********************************
2025-12-01 09:08:37.587339 | controller | Monday 01 December 2025  09:08:37 +0000 (0:00:00.433)       0:00:20.582 *******
2025-12-01 09:08:37.587348 | controller | ok: [instance]
2025-12-01 09:08:37.771210 | controller |
2025-12-01 09:08:37.771237 | controller | TASK [virtualbmc : Check if VBMC key exists] ***********************************
2025-12-01 09:08:37.771244 | controller | Monday 01 December 2025  09:08:37 +0000 (0:00:00.416)       0:00:20.999 *******
2025-12-01 09:08:37.771254 | controller | ok: [instance]
2025-12-01 09:08:37.831761 | controller |
2025-12-01 09:08:37.831808 | controller | TASK [virtualbmc : Slurp key] **************************************************
2025-12-01 09:08:37.831818 | controller | Monday 01 December 2025  09:08:37 +0000 (0:00:00.183)       0:00:21.182 *******
2025-12-01 09:08:37.831830 | controller | skipping: [instance]
2025-12-01 09:08:37.869647 | controller |
2025-12-01 09:08:37.869665 | controller | TASK [virtualbmc : Revoke VBMC SSH access] *************************************
2025-12-01 09:08:37.869673 | controller | Monday 01 December 2025  09:08:37 +0000 (0:00:00.060)       0:00:21.243 *******
2025-12-01 09:08:37.869683 | controller | skipping: [instance]
2025-12-01 09:08:38.544971 | controller |
2025-12-01 09:08:38.545025 | controller | TASK [virtualbmc : Remove vbmc files] ******************************************
2025-12-01 09:08:38.545039 | controller | Monday 01 December 2025  09:08:37 +0000 (0:00:00.037)       0:00:21.281 *******
2025-12-01 09:08:38.545056 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmc-key)
2025-12-01 09:08:38.545098 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmc-key.pub)
2025-12-01 09:08:38.545109 | controller | ok: [instance] => (item=/home/zuul/.ssh/virtualbmc.conf)
2025-12-01 09:08:38.545120 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmcd.sh)
2025-12-01 09:08:38.545129 | controller |
2025-12-01 09:08:38.545142 | controller | TASK [Clean remote ssh config] *************************************************
2025-12-01 09:08:38.623203 | controller | Monday 01 December 2025  09:08:38 +0000 (0:00:00.674)       0:00:21.956 *******
2025-12-01 09:08:38.623237 | controller |
2025-12-01 09:08:38.922518 | controller | TASK [ssh_jumper : Remove Include cifmw_ssh_config.d] **************************
2025-12-01 09:08:38.922546 | controller | Monday 01 December 2025  09:08:38 +0000 (0:00:00.078)       0:00:22.035 *******
2025-12-01 09:08:38.922557 | controller | ok: [instance]
2025-12-01 09:08:39.138797 | controller |
2025-12-01 09:08:39.138826 | controller | TASK [ssh_jumper : Remove cifmw_ssh_config.d directory] ************************
2025-12-01 09:08:39.138834 | controller | Monday 01 December 2025  09:08:38 +0000 (0:00:00.298)       0:00:22.333 *******
2025-12-01 09:08:39.138844 | controller | ok: [instance]
2025-12-01 09:08:39.245207 | controller |
2025-12-01 09:08:39.245227 | controller | TASK [Clean local ssh config] **************************************************
2025-12-01 09:08:39.245235 | controller | Monday 01 December 2025  09:08:39 +0000 (0:00:00.216)       0:00:22.549 *******
2025-12-01 09:08:39.245245 | controller |
2025-12-01 09:08:39.433818 | controller | TASK [ssh_jumper : Remove Include cifmw_ssh_config.d] **************************
2025-12-01 09:08:39.433851 | controller | Monday 01 December 2025  09:08:39 +0000 (0:00:00.107)       0:00:22.657 *******
2025-12-01 09:08:39.433866 | controller | ok: [instance -> localhost]
2025-12-01 09:08:39.649633 | controller |
2025-12-01 09:08:39.649669 | controller | TASK [ssh_jumper : Remove cifmw_ssh_config.d directory] ************************
2025-12-01 09:08:39.649682 | controller | Monday 01 December 2025  09:08:39 +0000 (0:00:00.188)       0:00:22.845 *******
2025-12-01 09:08:39.649698 | controller | ok: [instance -> localhost]
2025-12-01 09:08:39.713324 | controller |
2025-12-01 09:08:39.713342 | controller | TASK [dnsmasq : Cleaning dnsmasq] **********************************************
2025-12-01 09:08:39.713349 | controller | Monday 01 December 2025  09:08:39 +0000 (0:00:00.215)       0:00:23.060 *******
2025-12-01 09:08:39.713357 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/configure.yml for instance
2025-12-01 09:08:40.913421 | controller |
2025-12-01 09:08:40.913448 | controller | TASK [dnsmasq : Install needed packages] ***************************************
2025-12-01 09:08:40.913460 | controller | Monday 01 December 2025  09:08:39 +0000 (0:00:00.063)       0:00:23.124 *******
2025-12-01 09:08:40.913470 | controller | ok: [instance]
2025-12-01 09:08:42.139797 | controller |
2025-12-01 09:08:42.139828 | controller | TASK [dnsmasq : Ensure SELinux knows about the new files and directories] ******
2025-12-01 09:08:42.139836 | controller | Monday 01 December 2025  09:08:40 +0000 (0:00:01.200)       0:00:24.324 *******
2025-12-01 09:08:42.139846 | controller | ok: [instance] => (item={'target': '/etc/cifmw-dnsmasq.conf', 'setype': 'dnsmasq_etc_t'})
2025-12-01 09:08:42.348561 | controller | ok: [instance] => (item={'target': '/etc/cifmw-dnsmasq.d(/.*)?', 'setype': 'dnsmasq_etc_t'})
2025-12-01 09:08:42.348590 | controller | ok: [instance] => (item={'target': '/var/run/cifmw-dnsmasq.pid', 'setype': 'dnsmasq_var_run_t'})
2025-12-01 09:08:42.348601 | controller |
2025-12-01 09:08:42.348609 | controller | TASK [dnsmasq : Manage configuration directory] ********************************
2025-12-01 09:08:42.348616 | controller | Monday 01 December 2025  09:08:42 +0000 (0:00:01.226)       0:00:25.551 *******
2025-12-01 09:08:42.348628 | controller | ok: [instance]
2025-12-01 09:08:42.384898 | controller |
2025-12-01 09:08:42.384938 | controller | TASK [dnsmasq : Manage systemd unit file] **************************************
2025-12-01 09:08:42.384948 | controller | Monday 01 December 2025  09:08:42 +0000 (0:00:00.208)       0:00:25.759 *******
2025-12-01 09:08:42.384960 | controller | skipping: [instance]
2025-12-01 09:08:42.384988 | controller |
2025-12-01 09:08:42.384998 | controller | TASK [dnsmasq : Manage base configuration file] ********************************
2025-12-01 09:08:42.385078 | controller | Monday 01 December 2025  09:08:42 +0000 (0:00:00.036)       0:00:25.796 *******
2025-12-01 09:08:42.419900 | controller | skipping: [instance]
2025-12-01 09:08:42.420232 | controller |
2025-12-01 09:08:42.481402 | controller | TASK [dnsmasq : Render listener configuration] *********************************
2025-12-01 09:08:42.481419 | controller | Monday 01 December 2025  09:08:42 +0000 (0:00:00.035)       0:00:25.831 *******
2025-12-01 09:08:42.481429 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/listener.yml for instance
2025-12-01 09:08:42.536837 | controller |
2025-12-01 09:08:42.536871 | controller | TASK [dnsmasq : Create listener configuration file] ****************************
2025-12-01 09:08:42.536899 | controller | Monday 01 December 2025  09:08:42 +0000 (0:00:00.061)       0:00:25.892 *******
2025-12-01 09:08:42.536916 | controller | skipping: [instance]
2025-12-01 09:08:42.744420 | controller |
2025-12-01 09:08:42.744444 | controller | TASK [dnsmasq : Remove listener configuration file] ****************************
2025-12-01 09:08:42.744451 | controller | Monday 01 December 2025  09:08:42 +0000 (0:00:00.055)       0:00:25.948 *******
2025-12-01 09:08:42.744460 | controller | ok: [instance]
2025-12-01 09:08:42.806713 | controller |
2025-12-01 09:08:42.806731 | controller | TASK [dnsmasq : Render dns configuration] **************************************
2025-12-01 09:08:42.806738 | controller | Monday 01 December 2025  09:08:42 +0000 (0:00:00.208)       0:00:26.156 *******
2025-12-01 09:08:42.806747 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/dns.yml for instance
2025-12-01 09:08:42.843961 | controller |
2025-12-01 09:08:42.844004 | controller | TASK [dnsmasq : Create dns configuration file] *********************************
2025-12-01 09:08:42.844015 | controller | Monday 01 December 2025  09:08:42 +0000 (0:00:00.062)       0:00:26.218 *******
2025-12-01 09:08:42.844030 | controller | skipping: [instance]
2025-12-01 09:08:43.053874 | controller |
2025-12-01 09:08:43.053919 | controller | TASK [dnsmasq : Remove dns configuration file] *********************************
2025-12-01 09:08:43.053929 | controller | Monday 01 December 2025  09:08:42 +0000 (0:00:00.037)       0:00:26.255 *******
2025-12-01 09:08:43.053941 | controller | ok: [instance]
2025-12-01 09:08:43.053970 | controller |
2025-12-01 09:08:43.053979 | controller | TASK [dnsmasq : Add localhost addresses from defined dnsmasq listen addresses to loopback interface] ***
2025-12-01 09:08:43.054139 | controller | Monday 01 December 2025  09:08:43 +0000 (0:00:00.209)       0:00:26.465 *******
2025-12-01 09:08:43.088490 | controller | skipping: [instance]
2025-12-01 09:08:43.124902 | controller |
2025-12-01 09:08:43.124936 | controller | TASK [dnsmasq : Enable and start service] **************************************
2025-12-01 09:08:43.124945 | controller | Monday 01 December 2025  09:08:43 +0000 (0:00:00.034)       0:00:26.500 *******
2025-12-01 09:08:43.124956 | controller | skipping: [instance]
2025-12-01 09:08:43.446914 | controller |
2025-12-01 09:08:43.446947 | controller | TASK [dnsmasq : Stop service] **************************************************
2025-12-01 09:08:43.446957 | controller | Monday 01 December 2025  09:08:43 +0000 (0:00:00.036)       0:00:26.536 *******
2025-12-01 09:08:43.446970 | controller | ok: [instance]
2025-12-01 09:08:43.447148 | controller |
2025-12-01 09:08:43.447182 | controller | TASK [dnsmasq : Remove unit file] **********************************************
2025-12-01 09:08:43.678570 | controller | Monday 01 December 2025  09:08:43 +0000 (0:00:00.322)       0:00:26.858 *******
2025-12-01 09:08:43.678601 | controller | ok: [instance]
2025-12-01 09:08:44.071689 | controller |
2025-12-01 09:08:44.071711 | controller | TASK [dnsmasq : Remove main configuration files] *******************************
2025-12-01 09:08:44.071723 | controller | Monday 01 December 2025  09:08:43 +0000 (0:00:00.231)       0:00:27.089 *******
2025-12-01 09:08:44.071734 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2025-12-01 09:08:44.307567 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.d)
2025-12-01 09:08:44.307594 | controller |
2025-12-01 09:08:44.307602 | controller | TASK [dnsmasq : Remove lease file] *********************************************
2025-12-01 09:08:44.307608 | controller | Monday 01 December 2025  09:08:44 +0000 (0:00:00.393)       0:00:27.483 *******
2025-12-01 09:08:44.307617 | controller | ok: [instance]
2025-12-01 09:08:44.871918 | controller |
2025-12-01 09:08:44.871956 | controller | TASK [radvd : Gather package facts] ********************************************
2025-12-01 09:08:44.871967 | controller | Monday 01 December 2025  09:08:44 +0000 (0:00:00.235)       0:00:27.719 *******
2025-12-01 09:08:44.871980 | controller | ok: [instance]
2025-12-01 09:08:44.944527 | controller |
2025-12-01 09:08:44.944566 | controller | TASK [radvd : Stop and disable radvd service] **********************************
2025-12-01 09:08:44.944577 | controller | Monday 01 December 2025  09:08:44 +0000 (0:00:00.564)       0:00:28.283 *******
2025-12-01 09:08:44.944591 | controller | skipping: [instance]
2025-12-01 09:08:45.161621 | controller |
2025-12-01 09:08:45.161652 | controller | TASK [radvd : Remove radvd configuration file] *********************************
2025-12-01 09:08:45.161660 | controller | Monday 01 December 2025  09:08:44 +0000 (0:00:00.072)       0:00:28.356 *******
2025-12-01 09:08:45.161671 | controller | ok: [instance]
2025-12-01 09:08:45.375925 | controller |
2025-12-01 09:08:45.375968 | controller | TASK [radvd : Remove configuration directory] **********************************
2025-12-01 09:08:45.375984 | controller | Monday 01 December 2025  09:08:45 +0000 (0:00:00.216)       0:00:28.573 *******
2025-12-01 09:08:45.375998 | controller | ok: [instance]
2025-12-01 09:08:45.376029 | controller |
2025-12-01 09:08:45.376187 | controller | TASK [radvd : Remove radvd package] ********************************************
2025-12-01 09:08:45.376220 | controller | Monday 01 December 2025  09:08:45 +0000 (0:00:00.214)       0:00:28.787 *******
2025-12-01 09:08:45.461334 | controller | skipping: [instance]
2025-12-01 09:08:45.824581 | controller |
2025-12-01 09:08:45.824611 | controller | TASK [Ensure work directory is removed] ****************************************
2025-12-01 09:08:45.824619 | controller | Monday 01 December 2025  09:08:45 +0000 (0:00:00.085)       0:00:28.872 *******
2025-12-01 09:08:45.824630 | controller | changed: [instance]
2025-12-01 09:08:46.618983 | controller |
2025-12-01 09:08:46.619026 | controller | RUNNING HANDLER [libvirt_manager : Restart firewalld] **************************
2025-12-01 09:08:46.619039 | controller | Monday 01 December 2025  09:08:45 +0000 (0:00:00.362)       0:00:29.235 *******
2025-12-01 09:08:46.619055 | controller | changed: [instance]
2025-12-01 09:08:46.619094 | controller |
2025-12-01 09:08:46.619107 | controller | PLAY RECAP *********************************************************************
2025-12-01 09:08:46.619142 | controller | instance                   : ok=57   changed=10   unreachable=0    failed=0    skipped=40   rescued=0    ignored=0
2025-12-01 09:08:46.621207 | controller |
2025-12-01 09:08:46.678586 | controller | Monday 01 December 2025  09:08:46 +0000 (0:00:00.794)       0:00:30.030 *******
2025-12-01 09:08:46.678623 | controller | ===============================================================================
2025-12-01 09:08:46.678631 | controller | podman : Ensure podman is installed ------------------------------------- 4.40s
2025-12-01 09:08:46.678636 | controller | libvirt_manager : Populate service facts -------------------------------- 2.03s
2025-12-01 09:08:46.678641 | controller | libvirt_manager : Remove workload and volumes --------------------------- 1.41s
2025-12-01 09:08:46.678646 | controller | Copy generated content to proper location ------------------------------- 1.39s
2025-12-01 09:08:46.678651 | controller | dnsmasq : Ensure SELinux knows about the new files and directories ------ 1.23s
2025-12-01 09:08:46.678656 | controller | dnsmasq : Install needed packages --------------------------------------- 1.20s
2025-12-01 09:08:46.678661 | controller | Gathering Facts --------------------------------------------------------- 0.89s
2025-12-01 09:08:46.678666 | controller | libvirt_manager : Start virtqemud socket service ------------------------ 0.87s
2025-12-01 09:08:46.678671 | controller | libvirt_manager : Get installed packages list --------------------------- 0.82s
2025-12-01 09:08:46.678676 | controller | libvirt_manager : Restart firewalld ------------------------------------- 0.79s
2025-12-01 09:08:46.678681 | controller | virtualbmc : Remove vbmc files ------------------------------------------ 0.67s
2025-12-01 09:08:46.678690 | controller | libvirt_manager : Ensure firewalld is enabled and started --------------- 0.60s
2025-12-01 09:08:46.678695 | controller | radvd : Gather package facts -------------------------------------------- 0.56s
2025-12-01 09:08:46.678700 | controller | libvirt_manager : Disable forward in firewalld libvirt zone ------------- 0.55s
2025-12-01 09:08:46.678705 | controller | libvirt_manager : Disable masquerade in firewalld default zone ---------- 0.48s
2025-12-01 09:08:46.678710 | controller | virtualbmc : Stop and remove vbmc container ----------------------------- 0.43s
2025-12-01 09:08:46.678715 | controller | virtualbmc : Remove vbmc container image -------------------------------- 0.42s
2025-12-01 09:08:46.678720 | controller | libvirt_manager : Get pool configuration -------------------------------- 0.41s
2025-12-01 09:08:46.678725 | controller | dnsmasq : Remove main configuration files ------------------------------- 0.39s
2025-12-01 09:08:46.678730 | controller | libvirt_manager : Get network list -------------------------------------- 0.39s
2025-12-01 09:08:46.678740 | controller | INFO     Running boot_order > destroy
2025-12-01 09:08:46.679109 | controller | WARNING  Skipping, instances are delegated.
2025-12-01 09:08:46.679432 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-12-01 09:08:46.680903 | controller | INFO     Writing /tmp/report.html report.
2025-12-01 09:08:47.044496 | [controller] Waiting on logger
2025-12-01 09:08:52.171234 | [controller] Waiting on logger
2025-12-01 09:09:02.603304 | [controller] Waiting on logger
2025-12-01 09:09:13.035951 | [controller] Waiting on logger
2025-12-01 09:09:16.758653 | [Zuul] Log Stream did not terminate
2025-12-01 09:09:16.759306 | controller | ERROR
2025-12-01 09:09:16.760242 | controller | {
2025-12-01 09:09:16.760316 | controller |   "delta": "0:04:07.172456",
2025-12-01 09:09:16.760342 | controller |   "end": "2025-12-01 09:08:46.726820",
2025-12-01 09:09:16.760368 | controller |   "msg": "non-zero return code",
2025-12-01 09:09:16.760387 | controller |   "rc": 1,
2025-12-01 09:09:16.760403 | controller |   "start": "2025-12-01 09:04:39.554364"
2025-12-01 09:09:16.760418 | controller | }
failure
2025-12-01 09:09:16.814571 | 
2025-12-01 09:09:16.814629 | PLAY RECAP
2025-12-01 09:09:16.814675 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-12-01 09:09:16.814721 | 
2025-12-01 09:09:16.903743 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-01 09:09:16.904638 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-01 09:09:17.428806 | 
2025-12-01 09:09:17.428943 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-01 09:09:17.448173 | 
2025-12-01 09:09:17.448243 | TASK [Filter out host if needed]
2025-12-01 09:09:17.456265 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-01 09:09:17.460364 | 
2025-12-01 09:09:17.460426 | TASK [Ensure file is present]
2025-12-01 09:09:17.784709 | controller | ok
2025-12-01 09:09:17.792296 | 
2025-12-01 09:09:17.792368 | TASK [Manage molecule report file]
2025-12-01 09:09:17.812445 | [controller] Waiting on logger
2025-12-01 09:09:27.152765 | [controller] Waiting on logger
2025-12-01 09:09:37.547345 | [controller] Waiting on logger
2025-12-01 09:09:47.979370 | [controller] Waiting on logger
2025-12-01 09:09:48.157759 | [Zuul] Log Stream did not terminate
2025-12-01 09:09:48.158045 | controller | changed
2025-12-01 09:09:48.164442 | 
2025-12-01 09:09:48.164532 | TASK [Check if we get ci-framework-data basedir]
2025-12-01 09:09:48.357868 | controller | ok
2025-12-01 09:09:48.363911 | 
2025-12-01 09:09:48.363991 | TASK [Create ci-framework-data log directory for zuul]
2025-12-01 09:09:48.679732 | controller | changed
2025-12-01 09:09:48.685345 | 
2025-12-01 09:09:48.685417 | TASK [Copy ci-framework interesting files]
2025-12-01 09:09:53.711413 | controller | changed
2025-12-01 09:09:53.717266 | 
2025-12-01 09:09:53.717373 | TASK [Get SELinux listing]
2025-12-01 09:09:53.740979 | [controller] Waiting on logger
2025-12-01 09:10:01.419326 | [controller] Waiting on logger
2025-12-01 09:10:10.315383 | [controller] Waiting on logger
2025-12-01 09:10:20.749130 | [controller] Waiting on logger
2025-12-01 09:10:23.918352 | [Zuul] Log Stream did not terminate
2025-12-01 09:10:23.919407 | controller | changed
2025-12-01 09:10:23.934312 | 
2025-12-01 09:10:23.934442 | TASK [Generate log index]
2025-12-01 09:10:24.544095 | controller | changed
2025-12-01 09:10:24.548837 | 
2025-12-01 09:10:24.548913 | TASK [Get some env related data]
2025-12-01 09:10:27.659856 | [controller] Waiting on logger
2025-12-01 09:10:35.275353 | [controller] Waiting on logger
2025-12-01 09:10:44.238320 | [controller] Waiting on logger
2025-12-01 09:10:54.667302 | [controller] Waiting on logger
2025-12-01 09:10:55.663739 | [Zuul] Log Stream did not terminate
2025-12-01 09:10:55.664043 | controller | changed
2025-12-01 09:10:55.669415 | 
2025-12-01 09:10:55.669476 | TASK [Generate list of logs to collect in home directory]
2025-12-01 09:10:55.932077 | controller | ok: All paths examined
2025-12-01 09:10:55.936872 | 
2025-12-01 09:10:55.936967 | LOOP [Copy logs from home directory]
2025-12-01 09:10:56.243450 | controller | changed:
2025-12-01 09:10:56.243568 | controller | {
2025-12-01 09:10:56.243596 | controller |   "atime": 1764579861.484457,
2025-12-01 09:10:56.243617 | controller |   "ctime": 1764579877.1874294,
2025-12-01 09:10:56.243635 | controller |   "dev": 64513,
2025-12-01 09:10:56.243652 | controller |   "gid": 1000,
2025-12-01 09:10:56.243668 | controller |   "gr_name": "zuul",
2025-12-01 09:10:56.243683 | controller |   "inode": 4328623,
2025-12-01 09:10:56.243722 | controller |   "isblk": false,
2025-12-01 09:10:56.243743 | controller |   "ischr": false,
2025-12-01 09:10:56.243760 | controller |   "isdir": false,
2025-12-01 09:10:56.243774 | controller |   "isfifo": false,
2025-12-01 09:10:56.243789 | controller |   "isgid": false,
2025-12-01 09:10:56.243802 | controller |   "islnk": false,
2025-12-01 09:10:56.243816 | controller |   "isreg": true,
2025-12-01 09:10:56.243829 | controller |   "issock": false,
2025-12-01 09:10:56.243847 | controller |   "isuid": false,
2025-12-01 09:10:56.243862 | controller |   "mode": "0644",
2025-12-01 09:10:56.243876 | controller |   "mtime": 1764579877.1874294,
2025-12-01 09:10:56.243899 | controller |   "nlink": 1,
2025-12-01 09:10:56.243915 | controller |   "path": "/home/zuul/ansible.log",
2025-12-01 09:10:56.243930 | controller |   "pw_name": "zuul",
2025-12-01 09:10:56.243943 | controller |   "rgrp": true,
2025-12-01 09:10:56.243957 | controller |   "roth": true,
2025-12-01 09:10:56.243971 | controller |   "rusr": true,
2025-12-01 09:10:56.243985 | controller |   "size": 6749,
2025-12-01 09:10:56.243998 | controller |   "uid": 1000,
2025-12-01 09:10:56.244012 | controller |   "wgrp": false,
2025-12-01 09:10:56.244025 | controller |   "woth": false,
2025-12-01 09:10:56.244037 | controller |   "wusr": true,
2025-12-01 09:10:56.244050 | controller |   "xgrp": false,
2025-12-01 09:10:56.244062 | controller |   "xoth": false,
2025-12-01 09:10:56.244075 | controller |   "xusr": false
2025-12-01 09:10:56.244088 | controller | }
2025-12-01 09:10:56.257230 | 
2025-12-01 09:10:56.257344 | TASK [Copy crio stats log file]
2025-12-01 09:10:56.271114 | controller | skipping: Conditional result was False
2025-12-01 09:10:56.276369 | 
2025-12-01 09:10:56.276434 | TASK [Get SELinux related data]
2025-12-01 09:10:56.296682 | [controller] Waiting on logger
2025-12-01 09:11:06.700212 | [controller] Waiting on logger
2025-12-01 09:11:16.079839 | [controller] Waiting on logger
2025-12-01 09:11:26.475223 | [controller] Waiting on logger
2025-12-01 09:11:26.508484 | [Zuul] Log Stream did not terminate
2025-12-01 09:11:26.508805 | controller | ERROR
2025-12-01 09:11:26.508990 | controller | {
2025-12-01 09:11:26.509045 | controller |   "delta": "0:00:00.008703",
2025-12-01 09:11:26.509081 | controller |   "end": "2025-12-01 09:10:56.474916",
2025-12-01 09:11:26.509111 | controller |   "msg": "non-zero return code",
2025-12-01 09:11:26.509139 | controller |   "rc": 1,
2025-12-01 09:11:26.509169 | controller |   "start": "2025-12-01 09:10:56.466213"
2025-12-01 09:11:26.509197 | controller | }
2025-12-01 09:11:26.509234 | controller | ERROR: Ignoring Errors
2025-12-01 09:11:26.513997 | 
2025-12-01 09:11:26.514053 | TASK [Create system configuration directory]
2025-12-01 09:11:26.684270 | controller | changed
2025-12-01 09:11:26.688928 | 
2025-12-01 09:11:26.688992 | TASK [Get some of the system configurations]
2025-12-01 09:11:29.803642 | [controller] Waiting on logger
2025-12-01 09:11:39.184082 | [controller] Waiting on logger
2025-12-01 09:11:49.579411 | [controller] Waiting on logger
2025-12-01 09:11:56.919208 | [Zuul] Log Stream did not terminate
2025-12-01 09:11:56.919499 | controller | changed
2025-12-01 09:11:56.924365 | 
2025-12-01 09:11:56.924428 | TASK [Copy generated documentation if available]
2025-12-01 09:11:56.937801 | controller | skipping: Conditional result was False
2025-12-01 09:11:56.942825 | 
2025-12-01 09:11:56.942892 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-01 09:11:56.956037 | controller | skipping: Conditional result was False
2025-12-01 09:11:56.961365 | 
2025-12-01 09:11:56.961428 | TASK [Compress logs bigger than 2MB]
2025-12-01 09:12:00.011320 | [controller] Waiting on logger
2025-12-01 09:12:00.075449 | [controller] Waiting on logger
2025-12-01 09:12:13.885014 | controller | changed
2025-12-01 09:12:13.890405 | 
2025-12-01 09:12:13.890471 | TASK [Copy files from workspace on node]
2025-12-01 09:12:13.907943 | controller | ok
2025-12-01 09:12:13.927501 | 
2025-12-01 09:12:13.927565 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-01 09:12:13.940753 | controller | skipping: Conditional result was False
2025-12-01 09:12:13.945379 | 
2025-12-01 09:12:13.945441 | TASK [fetch-output : Set log path for single node]
2025-12-01 09:12:13.972818 | controller | ok
2025-12-01 09:12:13.978769 | 
2025-12-01 09:12:13.978833 | LOOP [fetch-output : Ensure local output dirs]
2025-12-01 09:12:14.153962 | controller -> localhost | ok: "/var/lib/zuul/builds/37eeae1097ea47929bb36eacdd5eb154/work/logs"
2025-12-01 09:12:14.154198 | controller -> localhost | changed: All items complete
2025-12-01 09:12:14.154233 | 
2025-12-01 09:12:14.318561 | controller -> localhost | changed: "/var/lib/zuul/builds/37eeae1097ea47929bb36eacdd5eb154/work/artifacts"
2025-12-01 09:12:14.484057 | controller -> localhost | changed: "/var/lib/zuul/builds/37eeae1097ea47929bb36eacdd5eb154/work/docs"
2025-12-01 09:12:14.495725 | 
2025-12-01 09:12:14.495826 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-01 09:12:14.539188 | [controller] Waiting on logger
2025-12-01 09:12:14.993989 | controller | changed:
2025-12-01 09:12:14.994076 | controller | .d..t...... ./
2025-12-01 09:12:14.994104 | controller | >f+++++++++ README.html
2025-12-01 09:12:14.994124 | controller | >f+++++++++ ansible-execution.log
2025-12-01 09:12:14.994143 | controller | >f+++++++++ ansible.log
2025-12-01 09:12:14.994160 | controller | >f+++++++++ dmesg.log
2025-12-01 09:12:14.994178 | controller | >f+++++++++ installed-pkgs.log
2025-12-01 09:12:14.994195 | controller | >f+++++++++ python.log
2025-12-01 09:12:14.994210 | controller | >f+++++++++ registries.conf
2025-12-01 09:12:14.994225 | controller | >f+++++++++ report.html
2025-12-01 09:12:14.994240 | controller | >f+++++++++ selinux-denials.log
2025-12-01 09:12:14.994254 | controller | >f+++++++++ selinux-listing.log
2025-12-01 09:12:14.994268 | controller | cd+++++++++ ci-framework-data/
2025-12-01 09:12:14.994282 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-01 09:12:14.994296 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-01 09:12:14.994310 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/
2025-12-01 09:12:14.994324 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/artifacts/
2025-12-01 09:12:14.994338 | controller | >f+++++++++ ci-framework-data/artifacts/boot_order/artifacts/interfaces-info.yml
2025-12-01 09:12:14.994353 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/artifacts/manifests/
2025-12-01 09:12:14.994367 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/artifacts/manifests/openstack/
2025-12-01 09:12:14.994381 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/artifacts/manifests/openstack/cr/
2025-12-01 09:12:14.994400 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/artifacts/parameters/
2025-12-01 09:12:14.994414 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/artifacts/repositories/
2025-12-01 09:12:14.994428 | controller | >f+++++++++ ci-framework-data/artifacts/boot_order/artifacts/repositories/delorean.repo.md5
2025-12-01 09:12:14.994442 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/logs/
2025-12-01 09:12:14.994457 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-01 09:12:14.994470 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-01 09:12:14.994484 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-01 09:12:14.994498 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-12-01 09:12:14.994512 | controller | cd+++++++++ registries.conf.d/
2025-12-01 09:12:14.994527 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-01 09:12:14.994541 | controller | cd+++++++++ system-config/
2025-12-01 09:12:14.994554 | controller | cd+++++++++ system-config/libvirt/
2025-12-01 09:12:14.994568 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-01 09:12:14.994582 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-01 09:12:14.994596 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-12-01 09:12:14.994609 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-12-01 09:12:14.994623 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-12-01 09:12:14.994636 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-12-01 09:12:14.994650 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-12-01 09:12:14.994663 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-12-01 09:12:14.994678 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-12-01 09:12:14.994712 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-12-01 09:12:14.994737 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-12-01 09:12:14.994754 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-12-01 09:12:14.994769 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-12-01 09:12:14.994783 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-12-01 09:12:14.994797 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-12-01 09:12:14.994811 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-12-01 09:12:15.354080 | controller | changed: .d..t...... ./
2025-12-01 09:12:15.698836 | controller | changed: .d..t...... ./
2025-12-01 09:12:15.715362 | 
2025-12-01 09:12:15.715513 | TASK [Return artifact to Zuul]
2025-12-01 09:12:15.743466 | controller | ok
2025-12-01 09:12:15.760280 | 
2025-12-01 09:12:15.760333 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-01 09:12:15.760425 | 
2025-12-01 09:12:15.760451 | PLAY RECAP
2025-12-01 09:12:15.760484 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-12-01 09:12:15.760503 | 
2025-12-01 09:12:15.829668 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-01 09:12:15.830384 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-01 09:12:16.321308 | 
2025-12-01 09:12:16.321406 | PLAY [all]
2025-12-01 09:12:16.339193 | 
2025-12-01 09:12:16.339265 | TASK [include_role : fetch-output]
2025-12-01 09:12:16.367149 | controller | ok
2025-12-01 09:12:16.382815 | 
2025-12-01 09:12:16.382904 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-01 09:12:16.417332 | controller | skipping: Conditional result was False
2025-12-01 09:12:16.423121 | 
2025-12-01 09:12:16.423194 | TASK [fetch-output : Set log path for single node]
2025-12-01 09:12:16.450436 | controller | ok
2025-12-01 09:12:16.454910 | 
2025-12-01 09:12:16.454977 | LOOP [fetch-output : Ensure local output dirs]
2025-12-01 09:12:16.750906 | controller -> localhost | ok: "/var/lib/zuul/builds/37eeae1097ea47929bb36eacdd5eb154/work/logs"
2025-12-01 09:12:16.929142 | controller -> localhost | ok: "/var/lib/zuul/builds/37eeae1097ea47929bb36eacdd5eb154/work/artifacts"
2025-12-01 09:12:17.098070 | controller -> localhost | ok: "/var/lib/zuul/builds/37eeae1097ea47929bb36eacdd5eb154/work/docs"
2025-12-01 09:12:17.111895 | 
2025-12-01 09:12:17.111999 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-01 09:12:17.604688 | controller | ok
2025-12-01 09:12:17.604884 | controller | ok: All items complete
2025-12-01 09:12:17.604913 | 
2025-12-01 09:12:17.949250 | controller | ok
2025-12-01 09:12:18.314934 | controller | ok
2025-12-01 09:12:18.346976 | 
2025-12-01 09:12:18.347114 | TASK [include_role : fetch-output-openshift]
2025-12-01 09:12:18.365076 | controller | skipping: Conditional result was False
2025-12-01 09:12:18.372282 | 
2025-12-01 09:12:18.372350 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-01 09:12:18.687594 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.005447
2025-12-01 09:12:18.866506 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006393
2025-12-01 09:12:18.893748 | 
2025-12-01 09:12:18.893831 | PLAY [all]
2025-12-01 09:12:18.906256 | 
2025-12-01 09:12:18.906320 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-01 09:12:19.259673 | controller | changed
2025-12-01 09:12:19.278980 | 
2025-12-01 09:12:19.279030 | PLAY RECAP
2025-12-01 09:12:19.279071 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-01 09:12:19.279092 | 
2025-12-01 09:12:19.346965 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-01 09:12:19.347734 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-01 09:12:19.852280 | 
2025-12-01 09:12:19.852383 | PLAY [localhost]
2025-12-01 09:12:19.868041 | 
2025-12-01 09:12:19.868110 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-01 09:12:20.148502 | localhost | changed
2025-12-01 09:12:20.152855 | 
2025-12-01 09:12:20.152941 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-01 09:12:20.170358 | localhost | ok
2025-12-01 09:12:20.178097 | 
2025-12-01 09:12:20.178161 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-01 09:12:20.448039 | localhost | changed
2025-12-01 09:12:20.453320 | 
2025-12-01 09:12:20.453385 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-01 09:12:20.947057 | localhost | changed
2025-12-01 09:12:20.951405 | 
2025-12-01 09:12:20.951464 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-01 09:12:21.262827 | localhost | Identity added: /var/lib/zuul/builds/37eeae1097ea47929bb36eacdd5eb154/work/tmp/ansible.9km9ac5c (/var/lib/zuul/builds/37eeae1097ea47929bb36eacdd5eb154/work/tmp/ansible.9km9ac5c)
2025-12-01 09:12:21.262973 | localhost | ok: Runtime: 0:00:00.005384
2025-12-01 09:12:21.266670 | 
2025-12-01 09:12:21.266747 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-01 09:12:21.465809 | localhost | ok: Runtime: 0:00:00.003750
2025-12-01 09:12:21.469883 | 
2025-12-01 09:12:21.469944 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-01 09:12:21.517444 | localhost | changed
2025-12-01 09:12:21.521980 | 
2025-12-01 09:12:21.522041 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-01 09:12:21.812274 | localhost | changed
2025-12-01 09:12:21.830925 | 
2025-12-01 09:12:21.830981 | PLAY [localhost]
2025-12-01 09:12:21.841037 | 
2025-12-01 09:12:21.841094 | TASK [Generate bulk log download script]
2025-12-01 09:12:21.859047 | localhost | ok
2025-12-01 09:12:21.869463 | 
2025-12-01 09:12:21.869528 | TASK [local-log-download : Check API endpoint is defined]
2025-12-01 09:12:21.896867 | localhost | ok: All assertions passed
2025-12-01 09:12:21.901180 | 
2025-12-01 09:12:21.901245 | TASK [local-log-download : Create download script]
2025-12-01 09:12:22.218163 | localhost -> localhost | changed
2025-12-01 09:12:22.225930 | 
2025-12-01 09:12:22.225995 | TASK [Register quick-download link]
2025-12-01 09:12:22.242815 | localhost | ok
2025-12-01 09:12:22.273713 | 
2025-12-01 09:12:22.273782 | PLAY [logserver.rdoproject.org]
2025-12-01 09:12:22.282879 | 
2025-12-01 09:12:22.282939 | TASK [Set zuul-log-path fact]
2025-12-01 09:12:22.298610 | logserver.rdoproject.org | ok
2025-12-01 09:12:22.307281 | 
2025-12-01 09:12:22.307345 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-01 09:12:22.322770 | logserver.rdoproject.org | ok
2025-12-01 09:12:22.327463 | 
2025-12-01 09:12:22.327521 | TASK [upload-logs : Create log directories]
2025-12-01 09:12:24.562179 | logserver.rdoproject.org | changed
2025-12-01 09:12:24.564959 | 
2025-12-01 09:12:24.565018 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-01 09:12:24.771527 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004913
2025-12-01 09:12:24.775605 | 
2025-12-01 09:12:24.775662 | TASK [upload-logs : Upload logs to log server]
2025-12-01 09:12:26.193511 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-01 09:12:26.196307 | 
2025-12-01 09:12:26.196372 | LOOP [upload-logs : Compress console log and json output]
2025-12-01 09:12:26.231328 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 09:12:26.238999 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 09:12:26.243487 | 
2025-12-01 09:12:26.243568 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-01 09:12:26.275451 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 09:12:26.275643 | 
2025-12-01 09:12:26.278311 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 09:12:26.291013 | 
2025-12-01 09:12:26.291095 | LOOP [upload-logs : Upload console log and json output]