2026-03-17 14:40:51.102197 | Job console starting...
2026-03-17 14:40:51.327222 | Updating repositories
2026-03-17 14:40:52.081027 | Preparing job workspace
2026-03-17 14:40:58.916055 | Running Ansible setup...
2026-03-17 14:41:01.681112 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-17 14:41:02.161369 | 
2026-03-17 14:41:02.161467 | PLAY [localhost]
2026-03-17 14:41:02.170072 | 
2026-03-17 14:41:02.170138 | TASK [Gathering Facts]
2026-03-17 14:41:02.937690 | localhost | ok
2026-03-17 14:41:02.950726 | 
2026-03-17 14:41:02.950799 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-17 14:41:03.257053 | localhost -> localhost | changed
2026-03-17 14:41:03.262526 | 
2026-03-17 14:41:03.262660 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-17 14:41:03.893063 | localhost -> localhost | changed
2026-03-17 14:41:03.901060 | 
2026-03-17 14:41:03.901128 | TASK [Setup log path fact]
2026-03-17 14:41:03.918861 | localhost | ok
2026-03-17 14:41:03.928911 | 
2026-03-17 14:41:03.928976 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-17 14:41:03.955559 | localhost | ok
2026-03-17 14:41:03.962117 | 
2026-03-17 14:41:03.962178 | TASK [emit-job-header : Print job information]
2026-03-17 14:41:03.989206 | # Job Information
2026-03-17 14:41:03.989328 | Ansible Version: 2.15.12
2026-03-17 14:41:03.989357 | Job: cifmw-molecule-libvirt_manager
2026-03-17 14:41:03.989377 | Pipeline: github-check
2026-03-17 14:41:03.989394 | Executor: ibm-bm3-ze.softwarefactory-project.io
2026-03-17 14:41:03.989411 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3129
2026-03-17 14:41:03.989429 | Log URL (when completed): https://logserver.rdoproject.org/702/rdoproject.org/702116f0d33e48f79477b12cf31ce05b/
2026-03-17 14:41:03.989447 | Event ID: 2801daa0-220f-11f1-9114-09717f396cbf
2026-03-17 14:41:03.992807 | 
2026-03-17 14:41:03.992868 | LOOP [emit-job-header : Print node information]
2026-03-17 14:41:04.069876 | localhost | ok:
2026-03-17 14:41:04.070020 | localhost | # Node Information
2026-03-17 14:41:04.070049 | localhost | Inventory Hostname: controller
2026-03-17 14:41:04.070073 | localhost | Hostname: np0005649811
2026-03-17 14:41:04.070093 | localhost | Username: zuul
2026-03-17 14:41:04.070112 | localhost | Distro: CentOS 9
2026-03-17 14:41:04.070130 | localhost | Provider: ibm-bm3-nodepool
2026-03-17 14:41:04.070146 | localhost | Region: regionOne
2026-03-17 14:41:04.070162 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2026-03-17 14:41:04.070177 | localhost | Product Name: OpenStack Compute
2026-03-17 14:41:04.070192 | localhost | Interface IP: 192.168.25.35
2026-03-17 14:41:04.095314 | 
2026-03-17 14:41:04.095393 | PLAY [all]
2026-03-17 14:41:04.101138 | 
2026-03-17 14:41:04.101205 | TASK [Gather network facts]
2026-03-17 14:41:04.454925 | controller | ok
2026-03-17 14:41:04.467430 | 
2026-03-17 14:41:04.467492 | TASK [include_role : start-zuul-console]
2026-03-17 14:41:04.485191 | controller | ok
2026-03-17 14:41:04.495969 | 
2026-03-17 14:41:04.496030 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-17 14:41:04.796763 | controller | ok
2026-03-17 14:41:04.805510 | 
2026-03-17 14:41:04.805571 | TASK [include_role : add-build-sshkey]
2026-03-17 14:41:04.822888 | controller | ok
2026-03-17 14:41:04.835160 | 
2026-03-17 14:41:04.835224 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-17 14:41:05.022807 | controller -> localhost | ok
2026-03-17 14:41:05.027536 | 
2026-03-17 14:41:05.027594 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-17 14:41:05.044788 | controller | ok
2026-03-17 14:41:05.056993 | controller | included: /var/lib/zuul/builds/702116f0d33e48f79477b12cf31ce05b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-17 14:41:05.061973 | 
2026-03-17 14:41:05.062032 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-17 14:41:05.646055 | controller -> localhost | Generating public/private rsa key pair.
2026-03-17 14:41:05.646329 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/702116f0d33e48f79477b12cf31ce05b/work/702116f0d33e48f79477b12cf31ce05b_id_rsa.
2026-03-17 14:41:05.646378 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/702116f0d33e48f79477b12cf31ce05b/work/702116f0d33e48f79477b12cf31ce05b_id_rsa.pub.
2026-03-17 14:41:05.646411 | controller -> localhost | The key fingerprint is:
2026-03-17 14:41:05.646440 | controller -> localhost | SHA256:El+EsHh+TFXb04GSy01UPaGu/+rLw6swaoXpEP/g68M zuul-build-sshkey
2026-03-17 14:41:05.646467 | controller -> localhost | The key's randomart image is:
2026-03-17 14:41:05.646492 | controller -> localhost | +---[RSA 3072]----+
2026-03-17 14:41:05.646518 | controller -> localhost | |      .. .o.+.o+o|
2026-03-17 14:41:05.646545 | controller -> localhost | |     . ..o o =.oo|
2026-03-17 14:41:05.646569 | controller -> localhost | |    . + . o *.o o|
2026-03-17 14:41:05.646594 | controller -> localhost | |     + = . o.. . |
2026-03-17 14:41:05.646610 | controller -> localhost | |      = S    .   |
2026-03-17 14:41:05.646640 | controller -> localhost | |     . B .  .    |
2026-03-17 14:41:05.646657 | controller -> localhost | |      = +o ..    |
2026-03-17 14:41:05.646676 | controller -> localhost | |       E..o oo   |
2026-03-17 14:41:05.646694 | controller -> localhost | |      o+o  .oB*. |
2026-03-17 14:41:05.646711 | controller -> localhost | +----[SHA256]-----+
2026-03-17 14:41:05.646753 | controller -> localhost | ok: Runtime: 0:00:00.284407
2026-03-17 14:41:05.652062 | 
2026-03-17 14:41:05.652118 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-17 14:41:05.669884 | controller | ok
2026-03-17 14:41:05.678349 | controller | included: /var/lib/zuul/builds/702116f0d33e48f79477b12cf31ce05b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-17 14:41:05.685404 | 
2026-03-17 14:41:05.685465 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-17 14:41:05.698843 | controller | skipping: Conditional result was False
2026-03-17 14:41:05.703806 | 
2026-03-17 14:41:05.703866 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-17 14:41:06.045917 | controller | changed
2026-03-17 14:41:06.050259 | 
2026-03-17 14:41:06.050335 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-17 14:41:06.239667 | controller | ok
2026-03-17 14:41:06.243927 | 
2026-03-17 14:41:06.243989 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-17 14:41:06.818502 | controller | changed
2026-03-17 14:41:06.822971 | 
2026-03-17 14:41:06.823034 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-17 14:41:07.377059 | controller | changed
2026-03-17 14:41:07.381428 | 
2026-03-17 14:41:07.381489 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-17 14:41:07.394118 | controller | skipping: Conditional result was False
2026-03-17 14:41:07.399496 | 
2026-03-17 14:41:07.399559 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-17 14:41:07.666406 | controller -> localhost | changed
2026-03-17 14:41:07.683871 | 
2026-03-17 14:41:07.683958 | TASK [add-build-sshkey : Add back temp key]
2026-03-17 14:41:07.889834 | controller -> localhost | Identity added: /var/lib/zuul/builds/702116f0d33e48f79477b12cf31ce05b/work/702116f0d33e48f79477b12cf31ce05b_id_rsa (zuul-build-sshkey)
2026-03-17 14:41:07.890039 | controller -> localhost | ok: Runtime: 0:00:00.006491
2026-03-17 14:41:07.895435 | 
2026-03-17 14:41:07.895495 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-17 14:41:08.174884 | controller | ok
2026-03-17 14:41:08.179066 | 
2026-03-17 14:41:08.179129 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-17 14:41:08.202629 | controller | skipping: Conditional result was False
2026-03-17 14:41:08.211107 | 
2026-03-17 14:41:08.211168 | TASK [include_role : validate-host]
2026-03-17 14:41:08.228672 | controller | ok
2026-03-17 14:41:08.252148 | 
2026-03-17 14:41:08.252212 | TASK [validate-host : Define zuul_info_dir fact]
2026-03-17 14:41:08.279477 | controller | ok
2026-03-17 14:41:08.283509 | 
2026-03-17 14:41:08.283573 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-03-17 14:41:08.469470 | controller -> localhost | ok
2026-03-17 14:41:08.474871 | 
2026-03-17 14:41:08.474932 | TASK [validate-host : Collect information about the host]
2026-03-17 14:41:09.091218 | controller | ok
2026-03-17 14:41:09.099056 | 
2026-03-17 14:41:09.099123 | TASK [validate-host : Sanitize hostname]
2026-03-17 14:41:09.167921 | controller | ok
2026-03-17 14:41:09.173584 | 
2026-03-17 14:41:09.173659 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-03-17 14:41:09.554793 | controller -> localhost | changed
2026-03-17 14:41:09.559628 | 
2026-03-17 14:41:09.559693 | TASK [validate-host : Collect information about zuul worker]
2026-03-17 14:41:09.871414 | controller | ok
2026-03-17 14:41:09.875890 | 
2026-03-17 14:41:09.875952 | TASK [validate-host : Write out all zuul information for each host]
2026-03-17 14:41:10.194320 | controller -> localhost | changed
2026-03-17 14:41:10.202455 | 
2026-03-17 14:41:10.202516 | TASK [include_role : prepare-workspace-openshift]
2026-03-17 14:41:10.215502 | controller | skipping: Conditional result was False
2026-03-17 14:41:10.220249 | 
2026-03-17 14:41:10.220334 | TASK [include_role : remove-zuul-sshkey]
2026-03-17 14:41:10.232804 | controller | skipping: Conditional result was False
2026-03-17 14:41:10.237520 | 
2026-03-17 14:41:10.237588 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-17 14:41:10.440702 | controller | ok: "logs"
2026-03-17 14:41:10.440924 | controller | ok: All items complete
2026-03-17 14:41:10.440953 | 
2026-03-17 14:41:10.608348 | controller | ok: "artifacts"
2026-03-17 14:41:10.758142 | controller | ok: "docs"
2026-03-17 14:41:10.773185 | 
2026-03-17 14:41:10.773304 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-17 14:41:10.957477 | controller | changed: "logs"
2026-03-17 14:41:11.112984 | controller | changed: "artifacts"
2026-03-17 14:41:11.278872 | controller | changed: "docs"
2026-03-17 14:41:11.303041 | 
2026-03-17 14:41:11.303094 | PLAY RECAP
2026-03-17 14:41:11.303137 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-17 14:41:11.303165 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-17 14:41:11.303184 | 
2026-03-17 14:41:11.370878 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-17 14:41:11.371632 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-17 14:41:11.890892 | 
2026-03-17 14:41:11.891005 | PLAY [all]
2026-03-17 14:41:11.908445 | 
2026-03-17 14:41:11.908512 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-17 14:41:11.967882 | controller | ok
2026-03-17 14:41:11.973547 | 
2026-03-17 14:41:11.973636 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-17 14:41:12.348792 | controller | changed
2026-03-17 14:41:12.354432 | 
2026-03-17 14:41:12.354507 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-17 14:41:13.163748 | controller | changed
2026-03-17 14:41:13.173702 | 
2026-03-17 14:41:13.173768 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-03-17 14:41:13.510938 | controller | changed:
2026-03-17 14:41:13.511103 | controller | {
2026-03-17 14:41:13.511132 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-03-17 14:41:13.511161 | controller | }
2026-03-17 14:41:13.698262 | controller | changed:
2026-03-17 14:41:13.698333 | controller | {
2026-03-17 14:41:13.698362 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-03-17 14:41:13.698383 | controller | }
2026-03-17 14:41:13.903343 | controller | changed:
2026-03-17 14:41:13.903429 | controller | {
2026-03-17 14:41:13.903455 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-03-17 14:41:13.903476 | controller | }
2026-03-17 14:41:14.090588 | controller | changed:
2026-03-17 14:41:14.090649 | controller | {
2026-03-17 14:41:14.090675 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-03-17 14:41:14.090693 | controller | }
2026-03-17 14:41:14.296496 | controller | changed:
2026-03-17 14:41:14.296544 | controller | {
2026-03-17 14:41:14.296567 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-03-17 14:41:14.296585 | controller | }
2026-03-17 14:41:14.489228 | controller | changed:
2026-03-17 14:41:14.489294 | controller | {
2026-03-17 14:41:14.489324 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-03-17 14:41:14.489343 | controller | }
2026-03-17 14:41:14.690191 | controller | changed:
2026-03-17 14:41:14.690237 | controller | {
2026-03-17 14:41:14.690261 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2026-03-17 14:41:14.690301 | controller | }
2026-03-17 14:41:14.885733 | controller | changed:
2026-03-17 14:41:14.885778 | controller | {
2026-03-17 14:41:14.885803 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2026-03-17 14:41:14.885821 | controller | }
2026-03-17 14:41:15.081723 | controller | changed:
2026-03-17 14:41:15.081775 | controller | {
2026-03-17 14:41:15.081799 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-03-17 14:41:15.081817 | controller | }
2026-03-17 14:41:15.284721 | controller | changed:
2026-03-17 14:41:15.284780 | controller | {
2026-03-17 14:41:15.284805 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-03-17 14:41:15.284823 | controller | }
2026-03-17 14:41:15.482277 | controller | changed:
2026-03-17 14:41:15.482328 | controller | {
2026-03-17 14:41:15.482353 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-03-17 14:41:15.482372 | controller | }
2026-03-17 14:41:15.696776 | controller | changed:
2026-03-17 14:41:15.696862 | controller | {
2026-03-17 14:41:15.696891 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-03-17 14:41:15.696913 | controller | }
2026-03-17 14:41:15.862249 | controller | changed:
2026-03-17 14:41:15.862318 | controller | {
2026-03-17 14:41:15.862343 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-03-17 14:41:15.862362 | controller | }
2026-03-17 14:41:16.050837 | controller | changed:
2026-03-17 14:41:16.050988 | controller | {
2026-03-17 14:41:16.051024 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-03-17 14:41:16.051047 | controller | }
2026-03-17 14:41:16.240088 | controller | changed:
2026-03-17 14:41:16.240157 | controller | {
2026-03-17 14:41:16.240196 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-03-17 14:41:16.240219 | controller | }
2026-03-17 14:41:16.431011 | controller | changed:
2026-03-17 14:41:16.431061 | controller | {
2026-03-17 14:41:16.431086 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-03-17 14:41:16.431104 | controller | }
2026-03-17 14:41:16.622367 | controller | changed:
2026-03-17 14:41:16.622419 | controller | {
2026-03-17 14:41:16.622443 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2026-03-17 14:41:16.622464 | controller | }
2026-03-17 14:41:16.810494 | controller | changed:
2026-03-17 14:41:16.810544 | controller | {
2026-03-17 14:41:16.810578 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2026-03-17 14:41:16.810707 | controller | }
2026-03-17 14:41:16.998541 | controller | changed:
2026-03-17 14:41:16.998592 | controller | {
2026-03-17 14:41:16.998627 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-03-17 14:41:16.998648 | controller | }
2026-03-17 14:41:17.186540 | controller | changed:
2026-03-17 14:41:17.186634 | controller | {
2026-03-17 14:41:17.186673 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-03-17 14:41:17.186701 | controller | }
2026-03-17 14:41:17.378076 | controller | changed:
2026-03-17 14:41:17.378129 | controller | {
2026-03-17 14:41:17.378154 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-03-17 14:41:17.378172 | controller | }
2026-03-17 14:41:17.563523 | controller | changed:
2026-03-17 14:41:17.563574 | controller | {
2026-03-17 14:41:17.563608 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-03-17 14:41:17.563638 | controller | }
2026-03-17 14:41:17.745878 | controller | changed:
2026-03-17 14:41:17.745934 | controller | {
2026-03-17 14:41:17.745959 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2026-03-17 14:41:17.745979 | controller | }
2026-03-17 14:41:17.931148 | controller | changed:
2026-03-17 14:41:17.931195 | controller | {
2026-03-17 14:41:17.931219 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-03-17 14:41:17.931238 | controller | }
2026-03-17 14:41:18.112001 | controller | changed:
2026-03-17 14:41:18.112050 | controller | {
2026-03-17 14:41:18.112074 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-03-17 14:41:18.112176 | controller | }
2026-03-17 14:41:18.295100 | controller | changed:
2026-03-17 14:41:18.295147 | controller | {
2026-03-17 14:41:18.295171 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-03-17 14:41:18.295189 | controller | }
2026-03-17 14:41:18.318944 | 
2026-03-17 14:41:18.319059 | TASK [Set timezone to UTC]
2026-03-17 14:41:18.681022 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-03-17 14:41:18.685647 | 
2026-03-17 14:41:18.685710 | TASK [Create nodepool directory]
2026-03-17 14:41:18.876891 | controller | changed
2026-03-17 14:41:18.881990 | 
2026-03-17 14:41:18.882049 | TASK [Create nodepool sub_nodes file]
2026-03-17 14:41:19.345669 | controller | changed
2026-03-17 14:41:19.350562 | 
2026-03-17 14:41:19.350638 | TASK [Create nodepool sub_nodes_private file]
2026-03-17 14:41:19.804776 | controller | changed
2026-03-17 14:41:19.809827 | 
2026-03-17 14:41:19.809882 | LOOP [Populate nodepool sub_nodes file]
2026-03-17 14:41:19.838590 | 
2026-03-17 14:41:19.838717 | LOOP [Populate nodepool sub_nodes_private file]
2026-03-17 14:41:19.867155 | 
2026-03-17 14:41:19.867286 | TASK [Create nodepool primary file]
2026-03-17 14:41:19.890524 | controller | skipping: Conditional result was False
2026-03-17 14:41:19.895685 | 
2026-03-17 14:41:19.895756 | TASK [Create nodepool node_private for this node]
2026-03-17 14:41:20.412358 | controller | changed
2026-03-17 14:41:20.417649 | 
2026-03-17 14:41:20.417709 | LOOP [Copy ssh keys to nodepool directory]
2026-03-17 14:41:20.716021 | controller | ok: Item: id_rsa Runtime: 0:00:00.004072
2026-03-17 14:41:20.716219 | 
2026-03-17 14:41:20.879820 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004263
2026-03-17 14:41:20.891916 | 
2026-03-17 14:41:20.892021 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-03-17 14:41:21.406206 | controller | changed
2026-03-17 14:41:21.410752 | 
2026-03-17 14:41:21.410816 | TASK [Validate sudoers config after edits]
2026-03-17 14:41:21.603544 | controller | /etc/sudoers: parsed OK
2026-03-17 14:41:21.603704 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-03-17 14:41:21.603715 | controller | /etc/sudoers.d/zuul: parsed OK
2026-03-17 14:41:21.603722 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-03-17 14:41:21.935431 | controller | ok: Runtime: 0:00:00.005070
2026-03-17 14:41:21.940789 | 
2026-03-17 14:41:21.940851 | TASK [Show the environment passed in to job shell scripts]
2026-03-17 14:41:22.128494 | controller | SHELL=/bin/bash
2026-03-17 14:41:22.128548 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-03-17 14:41:22.128558 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-03-17 14:41:22.128564 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/29/3129/72a96c7b2306d286518aebc09ed8ce2fcd0c3042
2026-03-17 14:41:22.128569 | controller | PWD=/home/zuul
2026-03-17 14:41:22.128574 | controller | ZUUL_PIPELINE=github-check
2026-03-17 14:41:22.128585 | controller | LOGNAME=zuul
2026-03-17 14:41:22.128622 | controller | XDG_SESSION_TYPE=tty
2026-03-17 14:41:22.128629 | controller | _=/usr/bin/env
2026-03-17 14:41:22.128634 | controller | MOTD_SHOWN=pam
2026-03-17 14:41:22.128639 | controller | HOME=/home/zuul
2026-03-17 14:41:22.128653 | controller | LANG=en_US.UTF-8
2026-03-17 14:41:22.128658 | controller | SSH_CONNECTION=192.168.25.12 38682 192.168.25.35 22
2026-03-17 14:41:22.128663 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2026-03-17 14:41:22.128671 | controller | ZUUL_CHANGE_IDS=3129,72a96c7b2306d286518aebc09ed8ce2fcd0c3042
2026-03-17 14:41:22.128676 | controller | WORKSPACE=/home/zuul/workspace
2026-03-17 14:41:22.128681 | controller | XDG_SESSION_CLASS=user
2026-03-17 14:41:22.128686 | controller | SELINUX_ROLE_REQUESTED=
2026-03-17 14:41:22.128691 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-03-17 14:41:22.128696 | controller | USER=zuul
2026-03-17 14:41:22.128701 | controller | ZUUL_VOTING=True
2026-03-17 14:41:22.128706 | controller | BUILD_TIMEOUT=3600000
2026-03-17 14:41:22.128710 | controller | SELINUX_USE_CURRENT_RANGE=
2026-03-17 14:41:22.128715 | controller | SHLVL=1
2026-03-17 14:41:22.128720 | controller | ZUUL_PATCHSET=72a96c7b2306d286518aebc09ed8ce2fcd0c3042
2026-03-17 14:41:22.128725 | controller | XDG_SESSION_ID=1
2026-03-17 14:41:22.128730 | controller | ZUUL_BRANCH=main
2026-03-17 14:41:22.128735 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-03-17 14:41:22.128740 | controller | SSH_CLIENT=192.168.25.12 38682 22
2026-03-17 14:41:22.128745 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-03-17 14:41:22.128750 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-03-17 14:41:22.128756 | controller | which_declare=declare -f
2026-03-17 14:41:22.128762 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-03-17 14:41:22.128767 | controller | SELINUX_LEVEL_REQUESTED=
2026-03-17 14:41:22.128771 | controller | ZUUL_CHANGE=3129
2026-03-17 14:41:22.128776 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-03-17 14:41:22.128781 | controller | ZUUL_UUID=702116f0d33e48f79477b12cf31ce05b
2026-03-17 14:41:22.128786 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-03-17 14:41:22.128791 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-03-17 14:41:22.128796 | controller | }
2026-03-17 14:41:22.464634 | controller | ok: Runtime: 0:00:00.005044
2026-03-17 14:41:22.469243 | 
2026-03-17 14:41:22.469315 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-03-17 14:41:22.482698 | controller | skipping: Conditional result was False
2026-03-17 14:41:22.487887 | 
2026-03-17 14:41:22.487949 | TASK [Symlink /home/zuul-worker/workspace]
2026-03-17 14:41:23.012082 | controller | skipping: Conditional result was False
2026-03-17 14:41:23.016962 | 
2026-03-17 14:41:23.017022 | TASK [Ensure legacy workspace directory]
2026-03-17 14:41:23.187418 | controller | changed
2026-03-17 14:41:23.203419 | 
2026-03-17 14:41:23.203475 | PLAY RECAP
2026-03-17 14:41:23.203520 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-17 14:41:23.203542 | 
2026-03-17 14:41:23.273258 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-17 14:41:23.274012 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-17 14:41:23.771191 | 
2026-03-17 14:41:23.771313 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-03-17 14:41:23.790565 | 
2026-03-17 14:41:23.790648 | TASK [Create zuul-output directory]
2026-03-17 14:41:24.073212 | controller | changed
2026-03-17 14:41:24.078044 | 
2026-03-17 14:41:24.078116 | TASK [Slurp Zuul inventory test]
2026-03-17 14:41:24.335603 | controller -> localhost | ok
2026-03-17 14:41:24.344500 | 
2026-03-17 14:41:24.344597 | TASK [Save zuul inventory]
2026-03-17 14:41:25.050738 | controller | changed
2026-03-17 14:41:25.055716 | 
2026-03-17 14:41:25.055781 | TASK [Save zuul vars without the change_message]
2026-03-17 14:41:25.623062 | controller | changed
2026-03-17 14:41:25.641361 | 
2026-03-17 14:41:25.641420 | PLAY RECAP
2026-03-17 14:41:25.641468 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-17 14:41:25.641490 | 
2026-03-17 14:41:25.708026 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-17 14:41:25.708840 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-17 14:41:26.360457 | 
2026-03-17 14:41:26.360567 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-03-17 14:41:26.380476 | 
2026-03-17 14:41:26.380549 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-03-17 14:41:26.399061 | controller | ok
2026-03-17 14:41:26.415479 | 
2026-03-17 14:41:26.415562 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-17 14:41:26.439161 | controller | skipping: Conditional result was False
2026-03-17 14:41:26.444704 | 
2026-03-17 14:41:26.444775 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-17 14:41:26.737374 | controller | ok
2026-03-17 14:41:26.742767 | 
2026-03-17 14:41:26.742832 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-17 14:41:27.300300 | controller | ok
2026-03-17 14:41:27.310092 | 
2026-03-17 14:41:27.310161 | TASK [Prepare workspace]
2026-03-17 14:41:27.328210 | controller | ok
2026-03-17 14:41:27.344328 | 
2026-03-17 14:41:27.344397 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-17 14:41:27.616359 | controller | ok
2026-03-17 14:41:27.622032 | 
2026-03-17 14:41:27.622119 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-17 14:41:29.863438 | controller | Output suppressed because no_log was given
2026-03-17 14:41:29.872061 | 
2026-03-17 14:41:29.872121 | LOOP [Create zuul-output directory]
2026-03-17 14:41:30.042771 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-03-17 14:41:30.186508 | controller | ok: "/home/zuul/zuul-output/logs"
2026-03-17 14:41:30.195206 | 
2026-03-17 14:41:30.195296 | TASK [Install required packages]
2026-03-17 14:42:19.535284 | controller | changed
2026-03-17 14:42:19.539885 | 
2026-03-17 14:42:19.539942 | TASK [Install venv]
2026-03-17 14:43:19.003019 | controller | changed
2026-03-17 14:43:19.027504 | 
2026-03-17 14:43:19.027567 | PLAY RECAP
2026-03-17 14:43:19.027610 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-17 14:43:19.027648 | 
2026-03-17 14:43:19.090600 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-17 14:43:19.091374 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-03-17 14:43:19.582743 | 
2026-03-17 14:43:19.582844 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-03-17 14:43:19.601414 | 
2026-03-17 14:43:19.601479 | TASK [Gather required facts]
2026-03-17 14:43:20.035220 | controller | ok
2026-03-17 14:43:20.040132 | 
2026-03-17 14:43:20.040200 | TASK [Load environment var if instructed to]
2026-03-17 14:43:20.064250 | controller | skipping: Conditional result was False
2026-03-17 14:43:20.070442 | 
2026-03-17 14:43:20.070541 | TASK [Ensure group_vars dir exists]
2026-03-17 14:43:20.352868 | controller | ok
2026-03-17 14:43:20.358458 | 
2026-03-17 14:43:20.358518 | TASK [Print related variables]
2026-03-17 14:43:20.385966 | controller | ok:
2026-03-17 14:43:20.386122 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-03-17 14:43:20.386154 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager
2026-03-17 14:43:20.390442 | 
2026-03-17 14:43:20.390500 | TASK [Run molecule]
2026-03-17 14:43:21.278697 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-03-17 14:43:21.656389 | controller | INFO     Performing prerun with role_name_check=0...
2026-03-17 14:43:38.996506 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:38.996913 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:38.997318 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:38.997750 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:38.998161 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+a76b21d8 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 1.0.0+a76b21d8 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:38.998557 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:38.998935 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:38.999290 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:38.999680 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:39.000078 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:39.000459 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:39.000869 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:39.001259 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:39.001664 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:39.002058 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/119c88/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:39.011296 | controller | INFO     Running boot_order > prepare
2026-03-17 14:43:39.728921 | controller |
2026-03-17 14:43:39.729063 | controller | PLAY [Prepare] *****************************************************************
2026-03-17 14:43:39.729151 | controller |
2026-03-17 14:43:39.729240 | controller | TASK [Gathering Facts] *********************************************************
2026-03-17 14:43:39.729329 | controller | Tuesday 17 March 2026  14:43:39 +0000 (0:00:00.023)       0:00:00.023 *********
2026-03-17 14:43:40.519468 | controller | ok: [instance]
2026-03-17 14:43:40.519527 | controller |
2026-03-17 14:43:40.519635 | controller | TASK [Create custom basedir] ***************************************************
2026-03-17 14:43:40.519731 | controller | Tuesday 17 March 2026  14:43:40 +0000 (0:00:00.791)       0:00:00.814 *********
2026-03-17 14:43:40.820275 | controller | changed: [instance]
2026-03-17 14:43:40.820338 | controller |
2026-03-17 14:43:40.820427 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2026-03-17 14:43:40.820532 | controller | Tuesday 17 March 2026  14:43:40 +0000 (0:00:00.300)       0:00:01.115 *********
2026-03-17 14:43:40.837317 | controller | skipping: [instance]
2026-03-17 14:43:40.837388 | controller |
2026-03-17 14:43:40.837473 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2026-03-17 14:43:40.837581 | controller | Tuesday 17 March 2026  14:43:40 +0000 (0:00:00.017)       0:00:01.132 *********
2026-03-17 14:43:40.876517 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2026-03-17 14:43:40.876696 | controller |
2026-03-17 14:43:40.876877 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2026-03-17 14:43:40.877039 | controller | Tuesday 17 March 2026  14:43:40 +0000 (0:00:00.038)       0:00:01.171 *********
2026-03-17 14:43:41.128797 | controller | ok: [instance]
2026-03-17 14:43:41.128881 | controller |
2026-03-17 14:43:41.128972 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2026-03-17 14:43:41.129061 | controller | Tuesday 17 March 2026  14:43:41 +0000 (0:00:00.252)       0:00:01.424 *********
2026-03-17 14:43:41.324467 | controller | ok: [instance]
2026-03-17 14:43:41.324556 | controller |
2026-03-17 14:43:41.324660 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2026-03-17 14:43:41.324760 | controller | Tuesday 17 March 2026  14:43:41 +0000 (0:00:00.195)       0:00:01.619 *********
2026-03-17 14:43:41.968006 | controller | ok: [instance] => (item=ubi-9-appstream)
2026-03-17 14:43:41.968058 | controller | ok: [instance] => (item=ubi-9-baseos)
2026-03-17 14:43:41.968158 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2026-03-17 14:43:41.968247 | controller |
2026-03-17 14:43:41.968342 | controller | TASK [Deploy repo-setup] *******************************************************
2026-03-17 14:43:41.968443 | controller | Tuesday 17 March 2026  14:43:41 +0000 (0:00:00.643)       0:00:02.263 *********
2026-03-17 14:43:42.027581 | controller |
2026-03-17 14:43:42.027701 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2026-03-17 14:43:42.027833 | controller | Tuesday 17 March 2026  14:43:42 +0000 (0:00:00.059)       0:00:02.323 *********
2026-03-17 14:43:42.527305 | controller | changed: [instance] => (item=tmp)
2026-03-17 14:43:42.527378 | controller | changed: [instance] => (item=artifacts/repositories)
2026-03-17 14:43:42.527477 | controller | changed: [instance] => (item=venv/repo_setup)
2026-03-17 14:43:42.527611 | controller |
2026-03-17 14:43:42.527727 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2026-03-17 14:43:42.527830 | controller | Tuesday 17 March 2026  14:43:42 +0000 (0:00:00.499)       0:00:02.822 *********
2026-03-17 14:43:43.299195 | controller | ok: [instance]
2026-03-17 14:43:43.299246 | controller |
2026-03-17 14:43:43.299344 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2026-03-17 14:43:43.299427 | controller | Tuesday 17 March 2026  14:43:43 +0000 (0:00:00.771)       0:00:03.594 *********
2026-03-17 14:43:44.392145 | controller | changed: [instance]
2026-03-17 14:43:51.649858 | controller |
2026-03-17 14:43:51.649895 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2026-03-17 14:43:51.649903 | controller | Tuesday 17 March 2026  14:43:44 +0000 (0:00:01.092)       0:00:04.687 *********
2026-03-17 14:43:51.649913 | controller | changed: [instance]
2026-03-17 14:43:52.312918 | controller |
2026-03-17 14:43:52.312947 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2026-03-17 14:43:52.312955 | controller | Tuesday 17 March 2026  14:43:51 +0000 (0:00:07.257)       0:00:11.944 *********
2026-03-17 14:43:52.312965 | controller | changed: [instance]
2026-03-17 14:43:52.336559 | controller |
2026-03-17 14:43:52.336581 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2026-03-17 14:43:52.336589 | controller | Tuesday 17 March 2026  14:43:52 +0000 (0:00:00.663)       0:00:12.607 *********
2026-03-17 14:43:52.336599 | controller | skipping: [instance]
2026-03-17 14:43:52.336627 | controller |
2026-03-17 14:43:52.336636 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2026-03-17 14:43:52.336735 | controller | Tuesday 17 March 2026  14:43:52 +0000 (0:00:00.024)       0:00:12.632 *********
2026-03-17 14:43:53.111135 | controller | changed: [instance]
2026-03-17 14:43:53.145556 | controller |
2026-03-17 14:43:53.145574 | controller | TASK [repo_setup : Get component repo] *****************************************
2026-03-17 14:43:53.145581 | controller | Tuesday 17 March 2026  14:43:53 +0000 (0:00:00.774)       0:00:13.406 *********
2026-03-17 14:43:53.145590 | controller | skipping: [instance]
2026-03-17 14:43:53.180555 | controller |
2026-03-17 14:43:53.180575 | controller | TASK [repo_setup : Rename component repo] **************************************
2026-03-17 14:43:53.180582 | controller | Tuesday 17 March 2026  14:43:53 +0000 (0:00:00.034)       0:00:13.440 *********
2026-03-17 14:43:53.180591 | controller | skipping: [instance]
2026-03-17 14:43:53.180679 | controller |
2026-03-17 14:43:53.180695 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2026-03-17 14:43:53.180704 | controller | Tuesday 17 March 2026  14:43:53 +0000 (0:00:00.034)       0:00:13.475 *********
2026-03-17 14:43:53.214550 | controller | skipping: [instance]
2026-03-17 14:43:53.214581 | controller |
2026-03-17 14:43:53.214831 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2026-03-17 14:43:53.747265 | controller | Tuesday 17 March 2026  14:43:53 +0000 (0:00:00.034)       0:00:13.510 *********
2026-03-17 14:43:53.747299 | controller | changed: [instance]
2026-03-17 14:43:54.149867 | controller |
2026-03-17 14:43:54.149899 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2026-03-17 14:43:54.149906 | controller | Tuesday 17 March 2026  14:43:53 +0000 (0:00:00.532)       0:00:14.042 *********
2026-03-17 14:43:54.149916 | controller | changed: [instance]
2026-03-17 14:43:54.175811 | controller |
2026-03-17 14:43:54.175837 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2026-03-17 14:43:54.175845 | controller | Tuesday 17 March 2026  14:43:54 +0000 (0:00:00.402)       0:00:14.444 *********
2026-03-17 14:43:54.175861 | controller | skipping: [instance]
2026-03-17 14:43:54.204283 | controller |
2026-03-17 14:43:54.204310 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2026-03-17 14:43:54.204319 | controller | Tuesday 17 March 2026  14:43:54 +0000 (0:00:00.026)       0:00:14.471 *********
2026-03-17 14:43:54.204328 | controller | skipping: [instance]
2026-03-17 14:43:54.231564 | controller |
2026-03-17 14:43:54.231587 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2026-03-17 14:43:54.231595 | controller | Tuesday 17 March 2026  14:43:54 +0000 (0:00:00.028)       0:00:14.499 *********
2026-03-17 14:43:54.231605 | controller | skipping: [instance]
2026-03-17 14:43:54.231676 | controller |
2026-03-17 14:43:54.231696 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2026-03-17 14:43:54.231708 | controller | Tuesday 17 March 2026  14:43:54 +0000 (0:00:00.027)       0:00:14.526 *********
2026-03-17 14:43:54.267415 | controller | ok: [instance]
2026-03-17 14:43:54.291316 | controller |
2026-03-17 14:43:54.291339 | controller | TASK [repo_setup : Create download directory] **********************************
2026-03-17 14:43:54.291348 | controller | Tuesday 17 March 2026  14:43:54 +0000 (0:00:00.035)       0:00:14.562 *********
2026-03-17 14:43:54.291365 | controller | skipping: [instance]
2026-03-17 14:43:54.291533 | controller |
2026-03-17 14:43:54.291671 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2026-03-17 14:43:54.291805 | controller | Tuesday 17 March 2026  14:43:54 +0000 (0:00:00.024)       0:00:14.586 *********
2026-03-17 14:43:54.317648 | controller | skipping: [instance]
2026-03-17 14:43:54.317848 | controller |
2026-03-17 14:43:54.318013 | controller | TASK [Download the RPM] ********************************************************
2026-03-17 14:43:54.318169 | controller | Tuesday 17 March 2026  14:43:54 +0000 (0:00:00.026)       0:00:14.613 *********
2026-03-17 14:43:54.342538 | controller | skipping: [instance]
2026-03-17 14:43:54.342727 | controller |
2026-03-17 14:43:54.342910 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2026-03-17 14:43:54.343071 | controller | Tuesday 17 March 2026  14:43:54 +0000 (0:00:00.024)       0:00:14.638 *********
2026-03-17 14:43:54.367241 | controller | skipping: [instance]
2026-03-17 14:43:54.367419 | controller |
2026-03-17 14:43:54.367616 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2026-03-17 14:43:54.367754 | controller | Tuesday 17 March 2026  14:43:54 +0000 (0:00:00.024)       0:00:14.662 *********
2026-03-17 14:43:54.394555 | controller | skipping: [instance]
2026-03-17 14:43:54.394618 | controller |
2026-03-17 14:43:54.394720 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2026-03-17 14:43:54.394815 | controller | Tuesday 17 March 2026  14:43:54 +0000 (0:00:00.027)       0:00:14.690 *********
2026-03-17 14:43:54.423131 | controller | skipping: [instance]
2026-03-17 14:43:54.423193 | controller |
2026-03-17 14:43:54.423303 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2026-03-17 14:43:54.423394 | controller | Tuesday 17 March 2026  14:43:54 +0000 (0:00:00.028)       0:00:14.718 *********
2026-03-17 14:43:54.447730 | controller | skipping: [instance]
2026-03-17 14:43:54.447805 | controller |
2026-03-17 14:43:54.447915 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2026-03-17 14:43:54.448011 | controller | Tuesday 17 March 2026  14:43:54 +0000 (0:00:00.024)       0:00:14.743 *********
2026-03-17 14:43:54.625262 | controller | ok: [instance]
2026-03-17 14:43:54.625313 | controller |
2026-03-17 14:43:54.625413 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2026-03-17 14:43:54.625532 | controller | Tuesday 17 March 2026  14:43:54 +0000 (0:00:00.177)       0:00:14.920 *********
2026-03-17 14:43:54.830983 | controller | changed: [instance]
2026-03-17 14:43:55.054169 | controller |
2026-03-17 14:43:55.054198 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2026-03-17 14:43:55.054207 | controller | Tuesday 17 March 2026  14:43:54 +0000 (0:00:00.205)       0:00:15.126 *********
2026-03-17 14:43:55.054216 | controller | changed: [instance]
2026-03-17 14:43:55.076949 | controller |
2026-03-17 14:43:55.076967 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2026-03-17 14:43:55.076974 | controller | Tuesday 17 March 2026  14:43:55 +0000 (0:00:00.223)       0:00:15.349 *********
2026-03-17 14:43:55.076983 | controller | skipping: [instance]
2026-03-17 14:43:55.099956 | controller |
2026-03-17 14:43:55.099980 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2026-03-17 14:43:55.099987 | controller | Tuesday 17 March 2026  14:43:55 +0000 (0:00:00.022)       0:00:15.372 *********
2026-03-17 14:43:55.099996 | controller | skipping: [instance]
2026-03-17 14:43:55.121895 | controller |
2026-03-17 14:43:55.121913 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2026-03-17 14:43:55.121921 | controller | Tuesday 17 March 2026  14:43:55 +0000 (0:00:00.023)       0:00:15.395 *********
2026-03-17 14:43:55.121930 | controller | skipping: [instance]
2026-03-17 14:43:55.142955 | controller |
2026-03-17 14:43:55.142975 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2026-03-17 14:43:55.142982 | controller | Tuesday 17 March 2026  14:43:55 +0000 (0:00:00.021)       0:00:15.417 *********
2026-03-17 14:43:55.142991 | controller | skipping: [instance]
2026-03-17 14:43:55.163092 | controller |
2026-03-17 14:43:55.163108 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2026-03-17 14:43:55.163121 | controller | Tuesday 17 March 2026  14:43:55 +0000 (0:00:00.021)       0:00:15.438 *********
2026-03-17 14:43:55.163129 | controller | skipping: [instance]
2026-03-17 14:43:55.183311 | controller |
2026-03-17 14:43:55.183329 | controller | TASK [repo_setup : Lower the priority of component repos to allow installation from gating repo] ***
2026-03-17 14:43:55.183336 | controller | Tuesday 17 March 2026  14:43:55 +0000 (0:00:00.020)       0:00:15.458 *********
2026-03-17 14:43:55.183343 | controller | skipping: [instance]
2026-03-17 14:43:55.201298 | controller |
2026-03-17 14:43:55.201315 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2026-03-17 14:43:55.201322 | controller | Tuesday 17 March 2026  14:43:55 +0000 (0:00:00.020)       0:00:15.478 *********
2026-03-17 14:43:55.201330 | controller | skipping: [instance]
2026-03-17 14:43:55.229951 | controller |
2026-03-17 14:43:55.229970 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2026-03-17 14:43:55.229977 | controller | Tuesday 17 March 2026  14:43:55 +0000 (0:00:00.017)       0:00:15.496 *********
2026-03-17 14:43:55.229986 | controller | skipping: [instance]
2026-03-17 14:43:55.246037 | controller |
2026-03-17 14:43:55.246053 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2026-03-17 14:43:55.246060 | controller | Tuesday 17 March 2026  14:43:55 +0000 (0:00:00.028)       0:00:15.525 *********
2026-03-17 14:43:55.246069 | controller | skipping: [instance]
2026-03-17 14:43:55.265897 | controller |
2026-03-17 14:43:55.265914 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2026-03-17 14:43:55.265921 | controller | Tuesday 17 March 2026  14:43:55 +0000 (0:00:00.016)       0:00:15.541 *********
2026-03-17 14:43:55.265929 | controller | skipping: [instance]
2026-03-17 14:43:55.293635 | controller |
2026-03-17 14:43:55.293655 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2026-03-17 14:43:55.293662 | controller | Tuesday 17 March 2026  14:43:55 +0000 (0:00:00.019)       0:00:15.561 *********
2026-03-17 14:43:55.293671 | controller | skipping: [instance]
2026-03-17 14:43:55.320503 | controller |
2026-03-17 14:43:55.320545 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2026-03-17 14:43:55.320552 | controller | Tuesday 17 March 2026  14:43:55 +0000 (0:00:00.027)       0:00:15.589 *********
2026-03-17 14:43:55.320561 | controller | skipping: [instance]
2026-03-17 14:43:55.348614 | controller |
2026-03-17 14:43:55.348648 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2026-03-17 14:43:55.348659 | controller | Tuesday 17 March 2026  14:43:55 +0000 (0:00:00.026)       0:00:15.615 *********
2026-03-17 14:43:55.348671 | controller | skipping: [instance]
2026-03-17 14:43:55.348943 | controller |
2026-03-17 14:43:55.348967 | controller | TASK [test_deps : Install additional packages] *********************************
2026-03-17 14:43:55.377534 | controller | Tuesday 17 March 2026  14:43:55 +0000 (0:00:00.028)       0:00:15.644 *********
2026-03-17 14:43:55.377558 | controller | skipping: [instance]
2026-03-17 14:44:42.902976 | controller |
2026-03-17 14:44:42.903010 | controller | TASK [test_deps : Install selinux python libs] *********************************
2026-03-17 14:44:42.903018 | controller | Tuesday 17 March 2026  14:43:55 +0000 (0:00:00.028)       0:00:15.672 *********
2026-03-17 14:44:42.903028 | controller | ok: [instance]
2026-03-17 14:44:44.028784 | controller |
2026-03-17 14:44:44.028814 | controller | TASK [test_deps : Install python yaml libs] ************************************
2026-03-17 14:44:44.028822 | controller | Tuesday 17 March 2026  14:44:42 +0000 (0:00:47.525)       0:01:03.198 *********
2026-03-17 14:44:44.028831 | controller | ok: [instance]
2026-03-17 14:44:44.059390 | controller |
2026-03-17 14:44:44.059413 | controller | TASK [test_deps : Install extra packages] **************************************
2026-03-17 14:44:44.059420 | controller | Tuesday 17 March 2026  14:44:44 +0000 (0:00:01.125)       0:01:04.323 *********
2026-03-17 14:44:44.059429 | controller | skipping: [instance]
2026-03-17 14:44:44.097175 | controller |
2026-03-17 14:44:44.097199 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2026-03-17 14:44:44.097207 | controller | Tuesday 17 March 2026  14:44:44 +0000 (0:00:00.030)       0:01:04.354 *********
2026-03-17 14:44:44.097216 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2026-03-17 14:44:44.117889 | controller |
2026-03-17 14:44:44.117910 | controller | TASK [ci_setup : List packages to install] *************************************
2026-03-17 14:44:44.117918 | controller | Tuesday 17 March 2026  14:44:44 +0000 (0:00:00.037)       0:01:04.392 *********
2026-03-17 14:44:44.117928 | controller | ok: [instance] =>
2026-03-17 14:44:47.705088 | controller |   cifmw_ci_setup_packages:
2026-03-17 14:44:47.705122 | controller |   - bash-completion
2026-03-17 14:44:47.705130 | controller |   - ca-certificates
2026-03-17 14:44:47.705136 | controller |   - git-core
2026-03-17 14:44:47.705142 | controller |   - make
2026-03-17 14:44:47.705147 | controller |   - tar
2026-03-17 14:44:47.705153 | controller |   - tmux
2026-03-17 14:44:47.705158 | controller |   - python3-pip
2026-03-17 14:44:47.705163 | controller |
2026-03-17 14:44:47.705169 | controller | TASK [ci_setup : Install needed packages] **************************************
2026-03-17 14:44:47.705180 | controller | Tuesday 17 March 2026  14:44:44 +0000 (0:00:00.019)       0:01:04.412 *********
2026-03-17 14:44:47.705190 | controller | changed: [instance]
2026-03-17 14:44:47.883923 | controller |
2026-03-17 14:44:47.883961 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2026-03-17 14:44:47.883970 | controller | Tuesday 17 March 2026  14:44:47 +0000 (0:00:03.586)       0:01:07.998 *********
2026-03-17 14:44:47.883980 | controller | ok: [instance]
2026-03-17 14:44:48.070087 | controller |
2026-03-17 14:44:48.070117 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2026-03-17 14:44:48.070125 | controller | Tuesday 17 March 2026  14:44:47 +0000 (0:00:00.180)       0:01:08.178 *********
2026-03-17 14:44:48.070135 | controller | changed: [instance]
2026-03-17 14:44:53.971776 | controller |
2026-03-17 14:44:53.971806 | controller | TASK [ci_setup : Install openshift client] *************************************
2026-03-17 14:44:53.971814 | controller | Tuesday 17 March 2026  14:44:48 +0000 (0:00:00.186)       0:01:08.365 *********
2026-03-17 14:44:53.971823 | controller | changed: [instance]
2026-03-17 14:44:54.004424 | controller |
2026-03-17 14:44:54.004439 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2026-03-17 14:44:54.004446 | controller | Tuesday 17 March 2026  14:44:53 +0000 (0:00:05.901)       0:01:14.266 *********
2026-03-17 14:44:54.004454 | controller | ok: [instance]
2026-03-17 14:44:54.254991 | controller |
2026-03-17 14:44:54.255013 | controller | TASK [ci_setup : Create completion file] ***************************************
2026-03-17 14:44:54.255020 | controller | Tuesday 17 March 2026  14:44:54 +0000 (0:00:00.033)       0:01:14.299 *********
2026-03-17 14:44:54.255029 | controller | changed: [instance]
2026-03-17 14:44:54.515479 | controller |
2026-03-17 14:44:54.515499 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2026-03-17 14:44:54.515506 | controller | Tuesday 17 March 2026  14:44:54 +0000 (0:00:00.250)       0:01:14.550 *********
2026-03-17 14:44:54.515529 | controller | changed: [instance]
2026-03-17 14:44:54.532259 | controller |
2026-03-17 14:44:54.532276 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2026-03-17 14:44:54.532283 | controller | Tuesday 17 March 2026  14:44:54 +0000 (0:00:00.258)       0:01:14.808 *********
2026-03-17 14:44:54.532291 | controller | skipping: [instance]
2026-03-17 14:44:54.548428 | controller |
2026-03-17 14:44:54.548610 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2026-03-17 14:44:54.548620 | controller | Tuesday 17 March 2026  14:44:54 +0000 (0:00:00.018)       0:01:14.827 *********
2026-03-17 14:44:54.548629 | controller | skipping: [instance]
2026-03-17 14:44:54.566830 | controller |
2026-03-17 14:44:54.566853 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2026-03-17 14:44:54.566862 | controller | Tuesday 17 March 2026  14:44:54 +0000 (0:00:00.015)       0:01:14.843 *********
2026-03-17 14:44:54.566873 | controller | skipping: [instance]
2026-03-17 14:44:54.582764 | controller |
2026-03-17 14:44:54.582782 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2026-03-17 14:44:54.582789 | controller | Tuesday 17 March 2026  14:44:54 +0000 (0:00:00.018)       0:01:14.862 *********
2026-03-17 14:44:54.582798 | controller | skipping: [instance]
2026-03-17 14:44:54.598347 | controller |
2026-03-17 14:44:54.598363 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2026-03-17 14:44:54.598375 | controller | Tuesday 17 March 2026  14:44:54 +0000 (0:00:00.016)       0:01:14.878 *********
2026-03-17 14:44:54.598383 | controller | skipping: [instance]
2026-03-17 14:44:54.618256 | controller |
2026-03-17 14:44:54.618273 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2026-03-17 14:44:54.618280 | controller | Tuesday 17 March 2026  14:44:54 +0000 (0:00:00.015)       0:01:14.893 *********
2026-03-17 14:44:54.618288 | controller | skipping: [instance]
2026-03-17 14:44:55.536621 | controller |
2026-03-17 14:44:55.536653 | controller | TASK [ci_setup : Manage directories] *******************************************
2026-03-17 14:44:55.536661 | controller | Tuesday 17 March 2026  14:44:54 +0000 (0:00:00.019)       0:01:14.913 *********
2026-03-17 14:44:55.536671 | controller | changed: [instance] => (item=/opt/basedir/artifacts/manifests/openstack/cr)
2026-03-17 14:44:55.537214 | controller | changed: [instance] => (item=/opt/basedir/logs)
2026-03-17 14:44:55.537228 | controller | ok: [instance] => (item=/opt/basedir/tmp)
2026-03-17 14:44:56.242546 | controller | changed: [instance] => (item=/opt/basedir/volumes)
2026-03-17 14:44:56.242587 | controller | changed: [instance] => (item=/opt/basedir/artifacts/parameters)
2026-03-17 14:44:56.242598 | controller |
2026-03-17 14:44:56.242606 | controller | TASK [libvirt_manager : Get installed packages list] ***************************
2026-03-17 14:44:56.242614 | controller | Tuesday 17 March 2026  14:44:55 +0000 (0:00:00.918)       0:01:15.831 *********
2026-03-17 14:44:56.242627 | controller | ok: [instance]
2026-03-17 14:44:57.997007 | controller |
2026-03-17 14:44:57.997046 | controller | TASK [libvirt_manager : Populate service facts] ********************************
2026-03-17 14:44:57.997060 | controller | Tuesday 17 March 2026  14:44:56 +0000 (0:00:00.705)       0:01:16.537 *********
2026-03-17 14:44:57.997072 | controller | ok: [instance]
2026-03-17 14:44:58.058640 | controller |
2026-03-17 14:44:58.058675 | controller | TASK [libvirt_manager : Start virtqemud socket service] ************************
2026-03-17 14:44:58.058685 | controller | Tuesday 17 March 2026  14:44:57 +0000 (0:00:01.754)       0:01:18.292 *********
2026-03-17 14:44:58.058698 | controller | skipping: [instance] => (item=virtqemud.service)
2026-03-17 14:44:58.119441 | controller | skipping: [instance] => (item=virtqemud.socket)
2026-03-17 14:44:58.119462 | controller | skipping: [instance]
2026-03-17 14:44:58.119469 | controller |
2026-03-17 14:44:58.119475 | controller | TASK [libvirt_manager : Start virtnetworkd socket service] *********************
2026-03-17 14:44:58.119481 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.061)       0:01:18.353 *********
2026-03-17 14:44:58.119489 | controller | skipping: [instance] => (item=virtnetworkd.service)
2026-03-17 14:44:58.176622 | controller | skipping: [instance] => (item=virtnetworkd.socket)
2026-03-17 14:44:58.176659 | controller | skipping: [instance]
2026-03-17 14:44:58.176669 | controller |
2026-03-17 14:44:58.176677 | controller | TASK [libvirt_manager : Start virtstoraged socket service] *********************
2026-03-17 14:44:58.176685 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.060)       0:01:18.414 *********
2026-03-17 14:44:58.176697 | controller | skipping: [instance] => (item=virtstoraged.service)
2026-03-17 14:44:58.176727 | controller | skipping: [instance] => (item=virtstoraged.socket)
2026-03-17 14:44:58.177195 | controller | skipping: [instance]
2026-03-17 14:44:58.221410 | controller |
2026-03-17 14:44:58.221434 | controller | TASK [libvirt_manager : Set _is_deepscrub internal fact] ***********************
2026-03-17 14:44:58.221444 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.057)       0:01:18.472 *********
2026-03-17 14:44:58.221456 | controller | ok: [instance]
2026-03-17 14:44:58.313623 | controller |
2026-03-17 14:44:58.313662 | controller | TASK [libvirt_manager : List all of the existing virtual machines] *************
2026-03-17 14:44:58.313671 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.044)       0:01:18.516 *********
2026-03-17 14:44:58.313683 | controller | skipping: [instance]
2026-03-17 14:44:58.313713 | controller |
2026-03-17 14:44:58.313737 | controller | TASK [libvirt_manager : Get pool configuration] ********************************
2026-03-17 14:44:58.313852 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.092)       0:01:18.609 *********
2026-03-17 14:44:58.369352 | controller | skipping: [instance]
2026-03-17 14:44:58.426488 | controller |
2026-03-17 14:44:58.426530 | controller | TASK [libvirt_manager : Filter out target environment] *************************
2026-03-17 14:44:58.426551 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.055)       0:01:18.664 *********
2026-03-17 14:44:58.426562 | controller | skipping: [instance]
2026-03-17 14:44:58.482259 | controller |
2026-03-17 14:44:58.482278 | controller | TASK [libvirt_manager : Expose cleanup list] ***********************************
2026-03-17 14:44:58.482286 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.057)       0:01:18.721 *********
2026-03-17 14:44:58.482294 | controller | skipping: [instance]
2026-03-17 14:44:58.536611 | controller |
2026-03-17 14:44:58.536645 | controller | TASK [libvirt_manager : Clean ssh known_hosts] *********************************
2026-03-17 14:44:58.536653 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.055)       0:01:18.777 *********
2026-03-17 14:44:58.536664 | controller | skipping: [instance]
2026-03-17 14:44:58.537139 | controller |
2026-03-17 14:44:58.597947 | controller | TASK [libvirt_manager : Destroy machine] ***************************************
2026-03-17 14:44:58.597966 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.054)       0:01:18.831 *********
2026-03-17 14:44:58.597989 | controller | skipping: [instance]
2026-03-17 14:44:58.657299 | controller |
2026-03-17 14:44:58.657333 | controller | TASK [libvirt_manager : Undefine machine] **************************************
2026-03-17 14:44:58.657346 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.061)       0:01:18.893 *********
2026-03-17 14:44:58.657356 | controller | skipping: [instance]
2026-03-17 14:44:58.722165 | controller |
2026-03-17 14:44:58.722190 | controller | TASK [libvirt_manager : (localhost) Clean ssh jumpers] *************************
2026-03-17 14:44:58.722200 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.058)       0:01:18.952 *********
2026-03-17 14:44:58.722212 | controller | skipping: [instance]
2026-03-17 14:44:58.778120 | controller |
2026-03-17 14:44:58.778139 | controller | TASK [libvirt_manager : (instance) Clean ssh jumpers] **************************
2026-03-17 14:44:58.778146 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.065)       0:01:19.017 *********
2026-03-17 14:44:58.778155 | controller | skipping: [instance]
2026-03-17 14:44:58.836633 | controller |
2026-03-17 14:44:58.836693 | controller | TASK [libvirt_manager : Get network list] **************************************
2026-03-17 14:44:58.836710 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.056)       0:01:19.073 *********
2026-03-17 14:44:58.836725 | controller | skipping: [instance]
2026-03-17 14:44:58.894545 | controller |
2026-03-17 14:44:58.894581 | controller | TASK [libvirt_manager : Filter out target nets] ********************************
2026-03-17 14:44:58.894594 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.058)       0:01:19.131 *********
2026-03-17 14:44:58.894610 | controller | skipping: [instance]
2026-03-17 14:44:58.954404 | controller |
2026-03-17 14:44:58.954435 | controller | TASK [libvirt_manager : Expose cleanup list] ***********************************
2026-03-17 14:44:58.954445 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.057)       0:01:19.189 *********
2026-03-17 14:44:58.954456 | controller | skipping: [instance]
2026-03-17 14:44:58.954653 | controller |
2026-03-17 14:44:58.954825 | controller | TASK [Remove dnsmasq network bits] *********************************************
2026-03-17 14:44:58.954958 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.060)       0:01:19.250 *********
2026-03-17 14:44:59.014057 | controller | skipping: [instance]
2026-03-17 14:44:59.014195 | controller |
2026-03-17 14:44:59.014326 | controller | TASK [libvirt_manager : Destroy networks] **************************************
2026-03-17 14:44:59.014454 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.059)       0:01:19.309 *********
2026-03-17 14:44:59.097115 | controller | skipping: [instance]
2026-03-17 14:44:59.097297 | controller |
2026-03-17 14:44:59.097433 | controller | TASK [libvirt_manager : Undefine networks] *************************************
2026-03-17 14:44:59.097594 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.082)       0:01:19.392 *********
2026-03-17 14:44:59.155445 | controller | skipping: [instance]
2026-03-17 14:44:59.155779 | controller |
2026-03-17 14:44:59.156065 | controller | TASK [libvirt_manager : Ensure no trace of networks lays in nmcli] *************
2026-03-17 14:44:59.156296 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.058)       0:01:19.450 *********
2026-03-17 14:44:59.213025 | controller | skipping: [instance]
2026-03-17 14:44:59.213296 | controller |
2026-03-17 14:44:59.213505 | controller | TASK [libvirt_manager : Find dummy interface connection files] *****************
2026-03-17 14:44:59.213682 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.057)       0:01:19.508 *********
2026-03-17 14:44:59.268110 | controller | skipping: [instance]
2026-03-17 14:44:59.268376 | controller |
2026-03-17 14:44:59.268622 | controller | TASK [libvirt_manager : Remove dummy interface connections] ********************
2026-03-17 14:44:59.268749 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.055)       0:01:19.563 *********
2026-03-17 14:44:59.327287 | controller | skipping: [instance]
2026-03-17 14:44:59.327589 | controller |
2026-03-17 14:44:59.327762 | controller | TASK [libvirt_manager : Clean firewalld libvirt zone] **************************
2026-03-17 14:44:59.327886 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.059)       0:01:19.622 *********
2026-03-17 14:44:59.384397 | controller | skipping: [instance]
2026-03-17 14:44:59.384705 | controller |
2026-03-17 14:44:59.384891 | controller | TASK [libvirt_manager : Ensure firewalld is enabled and started] ***************
2026-03-17 14:44:59.385022 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.057)       0:01:19.679 *********
2026-03-17 14:44:59.440439 | controller | skipping: [instance]
2026-03-17 14:44:59.440747 | controller |
2026-03-17 14:44:59.441027 | controller | TASK [libvirt_manager : Disable masquerade in firewalld default zone] **********
2026-03-17 14:44:59.441277 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.056)       0:01:19.735 *********
2026-03-17 14:44:59.496342 | controller | skipping: [instance]
2026-03-17 14:44:59.496646 | controller |
2026-03-17 14:44:59.496904 | controller | TASK [libvirt_manager : Disable forward in firewalld libvirt zone] *************
2026-03-17 14:44:59.497157 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.055)       0:01:19.791 *********
2026-03-17 14:44:59.553157 | controller | skipping: [instance]
2026-03-17 14:44:59.553320 | controller |
2026-03-17 14:44:59.553474 | controller | TASK [libvirt_manager : Remove cifmw storage pool] *****************************
2026-03-17 14:44:59.553649 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.056)       0:01:19.848 *********
2026-03-17 14:44:59.610927 | controller | skipping: [instance]
2026-03-17 14:44:59.611256 | controller |
2026-03-17 14:44:59.611382 | controller | TASK [libvirt_manager : Remove overlay images from ocp_volume pools if exists] ***
2026-03-17 14:44:59.611503 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.057)       0:01:19.906 *********
2026-03-17 14:44:59.649610 | controller | skipping: [instance]
2026-03-17 14:44:59.649797 | controller |
2026-03-17 14:44:59.650029 | controller | TASK [libvirt_manager : Remove ocp_volumes storage pool] ***********************
2026-03-17 14:44:59.650234 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.038)       0:01:19.945 *********
2026-03-17 14:44:59.706268 | controller | skipping: [instance]
2026-03-17 14:44:59.706452 | controller |
2026-03-17 14:44:59.706574 | controller | TASK [libvirt_manager : Refresh all pools after actions] ***********************
2026-03-17 14:44:59.706684 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.056)       0:01:20.001 *********
2026-03-17 14:44:59.761393 | controller | skipping: [instance]
2026-03-17 14:44:59.761498 | controller |
2026-03-17 14:44:59.761639 | controller | TASK [libvirt_manager : Get temporary key status] ******************************
2026-03-17 14:44:59.761763 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.055)       0:01:20.057 *********
2026-03-17 14:44:59.939059 | controller | ok: [instance]
2026-03-17 14:44:59.995989 | controller |
2026-03-17 14:44:59.996007 | controller | TASK [libvirt_manager : Get public key] ****************************************
2026-03-17 14:44:59.996014 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.177)       0:01:20.234 *********
2026-03-17 14:44:59.996023 | controller | skipping: [instance]
2026-03-17 14:45:00.027612 | controller |
2026-03-17 14:45:00.027654 | controller | TASK [libvirt_manager : Remove public key] *************************************
2026-03-17 14:45:00.027666 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.056)       0:01:20.291 *********
2026-03-17 14:45:00.027680 | controller | skipping: [instance]
2026-03-17 14:45:00.027803 | controller |
2026-03-17 14:45:00.027835 | controller | TASK [libvirt_manager : Remove keypair] ****************************************
2026-03-17 14:45:00.027853 | controller | Tuesday 17 March 2026  14:45:00 +0000 (0:00:00.031)       0:01:20.322 *********
2026-03-17 14:45:00.063668 | controller | skipping: [instance] => (item=cifmw_reproducer_key.pub)
2026-03-17 14:45:00.064196 | controller | skipping: [instance] => (item=cifmw_reproducer_key)
2026-03-17 14:45:00.064218 | controller | skipping: [instance]
2026-03-17 14:45:00.318080 | controller |
2026-03-17 14:45:00.318104 | controller | TASK [libvirt_manager : List libvirt logs] *************************************
2026-03-17 14:45:00.318111 | controller | Tuesday 17 March 2026  14:45:00 +0000 (0:00:00.036)       0:01:20.358 *********
2026-03-17 14:45:00.318120 | controller | [WARNING]: Skipped '/var/log/libvirt/qemu/' path due to this access issue:
2026-03-17 14:45:00.339603 | controller | '/var/log/libvirt/qemu/' is not a directory
2026-03-17 14:45:00.339632 | controller | ok: [instance]
2026-03-17 14:45:00.339652 | controller |
2026-03-17 14:45:00.339813 | controller | TASK [libvirt_manager : Remove detected logs] **********************************
2026-03-17 14:45:00.368657 | controller | Tuesday 17 March 2026  14:45:00 +0000 (0:00:00.276)       0:01:20.635 *********
2026-03-17 14:45:00.368700 | controller | skipping: [instance]
2026-03-17 14:45:00.368747 | controller |
2026-03-17 14:45:00.368762 | controller | TASK [libvirt_manager : Remove workload and volumes] ***************************
2026-03-17 14:45:00.368796 | controller | Tuesday 17 March 2026  14:45:00 +0000 (0:00:00.029)       0:01:20.664 *********
2026-03-17 14:45:01.629634 | controller | ok: [instance] => (item=workload)
2026-03-17 14:45:01.630159 | controller | changed: [instance] => (item=volumes)
2026-03-17 14:45:01.630186 | controller | ok: [instance] => (item=artifacts/debug_network_data.yml)
2026-03-17 14:45:01.630194 | controller | ok: [instance] => (item=artifacts/debug_pub_net_value.txt)
2026-03-17 14:45:01.630200 | controller | ok: [instance] => (item=artifacts/debug_cifmw_libvirt_manager_layout.yml)
2026-03-17 14:45:01.630210 | controller | ok: [instance] => (item=artifacts/interfaces-info.yml)
2026-03-17 14:45:01.990874 | controller | ok: [instance] => (item=artifacts/libvirt-uuids.yml)
2026-03-17 14:45:01.990902 | controller | ok: [instance] => (item=artifacts/net-map-def-patch.yml)
2026-03-17 14:45:01.990909 | controller |
2026-03-17 14:45:01.990915 | controller | TASK [libvirt_manager : Deepscrub data] ****************************************
2026-03-17 14:45:01.990921 | controller | Tuesday 17 March 2026  14:45:01 +0000 (0:00:01.260)       0:01:21.924 *********
2026-03-17 14:45:01.990930 | controller | ok: [instance] => (item=images)
2026-03-17 14:45:03.130770 | controller | ok: [instance] => (item=artifacts/virtual-nodes.yml)
2026-03-17 14:45:03.130797 | controller |
2026-03-17 14:45:03.130804 | controller | TASK [podman : Ensure podman is installed] *************************************
2026-03-17 14:45:03.130811 | controller | Tuesday 17 March 2026  14:45:01 +0000 (0:00:00.361)       0:01:22.285 *********
2026-03-17 14:45:03.130819 | controller | ok: [instance]
2026-03-17 14:45:03.448673 | controller |
2026-03-17 14:45:03.448713 | controller | TASK [podman : Enable loginctl linger for ansible_user_id] *********************
2026-03-17 14:45:03.448724 | controller | Tuesday 17 March 2026  14:45:03 +0000 (0:00:01.140)       0:01:23.426 *********
2026-03-17 14:45:03.448736 | controller | changed: [instance]
2026-03-17 14:45:03.448758 | controller |
2026-03-17 14:45:03.448767 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul] *******************
2026-03-17 14:45:03.448794 | controller | Tuesday 17 March 2026  14:45:03 +0000 (0:00:00.317)       0:01:23.743 *********
2026-03-17 14:45:03.487283 | controller | skipping: [instance]
2026-03-17 14:45:03.487342 | controller |
2026-03-17 14:45:03.487455 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul] *******************
2026-03-17 14:45:03.487549 | controller | Tuesday 17 March 2026  14:45:03 +0000 (0:00:00.038)       0:01:23.782 *********
2026-03-17 14:45:03.523051 | controller | skipping: [instance]
2026-03-17 14:45:03.523115 | controller |
2026-03-17 14:45:03.523226 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed] ***
2026-03-17 14:45:03.523318 | controller | Tuesday 17 March 2026  14:45:03 +0000 (0:00:00.035)       0:01:23.818 *********
2026-03-17 14:45:03.574935 | controller | skipping: [instance]
2026-03-17 14:45:03.575011 | controller |
2026-03-17 14:45:03.575132 | controller | TASK [virtualbmc : Stop and remove vbmc container] *****************************
2026-03-17 14:45:03.575227 | controller | Tuesday 17 March 2026  14:45:03 +0000 (0:00:00.051)       0:01:23.870 *********
2026-03-17 14:45:04.159401 | controller | ok: [instance]
2026-03-17 14:45:04.554090 | controller |
2026-03-17 14:45:04.554118 | controller | TASK [virtualbmc : Remove vbmc container image] ********************************
2026-03-17 14:45:04.554126 | controller | Tuesday 17 March 2026  14:45:04 +0000 (0:00:00.583)       0:01:24.454 *********
2026-03-17 14:45:04.554135 | controller | ok: [instance]
2026-03-17 14:45:04.729625 | controller |
2026-03-17 14:45:04.729661 | controller | TASK [virtualbmc : Check if VBMC key exists] ***********************************
2026-03-17 14:45:04.729670 | controller | Tuesday 17 March 2026  14:45:04 +0000 (0:00:00.394)       0:01:24.849 *********
2026-03-17 14:45:04.729686 | controller | ok: [instance]
2026-03-17 14:45:04.760642 | controller |
2026-03-17 14:45:04.760686 | controller | TASK [virtualbmc : Slurp key] **************************************************
2026-03-17 14:45:04.760700 | controller | Tuesday 17 March 2026  14:45:04 +0000 (0:00:00.175)       0:01:25.024 *********
2026-03-17 14:45:04.760718 | controller | skipping: [instance]
2026-03-17 14:45:04.790600 | controller |
2026-03-17 14:45:04.790637 | controller | TASK [virtualbmc : Revoke VBMC SSH access] *************************************
2026-03-17 14:45:04.790646 | controller | Tuesday 17 March 2026  14:45:04 +0000 (0:00:00.030)       0:01:25.055 *********
2026-03-17 14:45:04.790664 | controller | skipping: [instance]
2026-03-17 14:45:04.791104 | controller |
2026-03-17 14:45:05.476627 | controller | TASK [virtualbmc : Remove vbmc files] ******************************************
2026-03-17 14:45:05.476666 | controller | Tuesday 17 March 2026  14:45:04 +0000 (0:00:00.030)       0:01:25.086 *********
2026-03-17 14:45:05.476682 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmc-key)
2026-03-17 14:45:05.476713 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmc-key.pub)
2026-03-17 14:45:05.476725 | controller | ok: [instance] => (item=/home/zuul/.ssh/virtualbmc.conf)
2026-03-17 14:45:05.477232 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmcd.sh)
2026-03-17 14:45:05.546172 | controller |
2026-03-17 14:45:05.546199 | controller | TASK [Clean remote ssh config] *************************************************
2026-03-17 14:45:05.546208 | controller | Tuesday 17 March 2026  14:45:05 +0000 (0:00:00.685)       0:01:25.771 *********
2026-03-17 14:45:05.546219 | controller |
2026-03-17 14:45:05.843752 | controller | TASK [ssh_jumper : Remove Include cifmw_ssh_config.d] **************************
2026-03-17 14:45:05.843785 | controller | Tuesday 17 March 2026  14:45:05 +0000 (0:00:00.069)       0:01:25.841 *********
2026-03-17 14:45:05.843797 | controller | ok: [instance]
2026-03-17 14:45:06.077358 | controller |
2026-03-17 14:45:06.077394 | controller | TASK [ssh_jumper : Remove cifmw_ssh_config.d directory] ************************
2026-03-17 14:45:06.077402 | controller | Tuesday 17 March 2026  14:45:05 +0000 (0:00:00.297)       0:01:26.138 *********
2026-03-17 14:45:06.077413 | controller | ok: [instance]
2026-03-17 14:45:06.153531 | controller |
2026-03-17 14:45:06.153554 | controller | TASK [Clean local ssh config] **************************************************
2026-03-17 14:45:06.153561 | controller | Tuesday 17 March 2026  14:45:06 +0000 (0:00:00.233)       0:01:26.372 *********
2026-03-17 14:45:06.153571 | controller |
2026-03-17 14:45:06.349443 | controller | TASK [ssh_jumper : Remove Include cifmw_ssh_config.d] **************************
2026-03-17 14:45:06.349474 | controller | Tuesday 17 March 2026  14:45:06 +0000 (0:00:00.076)       0:01:26.448 *********
2026-03-17 14:45:06.349487 | controller | ok: [instance -> localhost]
2026-03-17 14:45:06.558659 | controller |
2026-03-17 14:45:06.558713 | controller | TASK [ssh_jumper : Remove cifmw_ssh_config.d directory] ************************
2026-03-17 14:45:06.558724 | controller | Tuesday 17 March 2026  14:45:06 +0000 (0:00:00.195)       0:01:26.644 *********
2026-03-17 14:45:06.558738 | controller | ok: [instance -> localhost]
2026-03-17 14:45:06.558761 | controller |
2026-03-17 14:45:06.558769 | controller | TASK [dnsmasq : Cleaning dnsmasq] **********************************************
2026-03-17 14:45:06.558779 | controller | Tuesday 17 March 2026  14:45:06 +0000 (0:00:00.209)       0:01:26.854 *********
2026-03-17 14:45:06.616230 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/configure.yml for instance
2026-03-17 14:45:06.616317 | controller |
2026-03-17 14:45:06.616408 | controller | TASK [dnsmasq : Install needed packages] ***************************************
2026-03-17 14:45:06.616495 | controller | Tuesday 17 March 2026  14:45:06 +0000 (0:00:00.057)       0:01:26.911 *********
2026-03-17 14:45:10.216022 | controller | changed: [instance]
2026-03-17 14:45:11.466114 | controller |
2026-03-17 14:45:11.466155 | controller | TASK [dnsmasq : Ensure SELinux knows about the new files and directories] ******
2026-03-17 14:45:11.466164 | controller | Tuesday 17 March 2026  14:45:10 +0000 (0:00:03.599)       0:01:30.510 *********
2026-03-17 14:45:11.466174 | controller | ok: [instance] => (item={'target': '/etc/cifmw-dnsmasq.conf', 'setype': 'dnsmasq_etc_t'})
2026-03-17 14:45:11.671172 | controller | ok: [instance] => (item={'target': '/etc/cifmw-dnsmasq.d(/.*)?', 'setype': 'dnsmasq_etc_t'})
2026-03-17 14:45:11.671199 | controller | ok: [instance] => (item={'target': '/var/run/cifmw-dnsmasq.pid', 'setype': 'dnsmasq_var_run_t'})
2026-03-17 14:45:11.671207 | controller |
2026-03-17 14:45:11.671213 | controller | TASK [dnsmasq : Manage configuration directory] ********************************
2026-03-17 14:45:11.671218 | controller | Tuesday 17 March 2026  14:45:11 +0000 (0:00:01.249)       0:01:31.760 *********
2026-03-17 14:45:11.671228 | controller | ok: [instance]
2026-03-17 14:45:11.703888 | controller |
2026-03-17 14:45:11.703908 | controller | TASK [dnsmasq : Manage systemd unit file] **************************************
2026-03-17 14:45:11.703916 | controller | Tuesday 17 March 2026  14:45:11 +0000 (0:00:00.205)       0:01:31.966 *********
2026-03-17 14:45:11.703925 | controller | skipping: [instance]
2026-03-17 14:45:11.754927 | controller |
2026-03-17 14:45:11.754954 | controller | TASK [dnsmasq : Manage base configuration file] ********************************
2026-03-17 14:45:11.754968 | controller | Tuesday 17 March 2026  14:45:11 +0000 (0:00:00.032)       0:01:31.999 *********
2026-03-17 14:45:11.754978 | controller | skipping: [instance]
2026-03-17 14:45:11.810184 | controller |
2026-03-17 14:45:11.810204 | controller | TASK [dnsmasq : Render listener configuration] *********************************
2026-03-17 14:45:11.810212 | controller | Tuesday 17 March 2026  14:45:11 +0000 (0:00:00.051)       0:01:32.050 *********
2026-03-17 14:45:11.810221 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/listener.yml for instance
2026-03-17 14:45:11.842634 | controller |
2026-03-17 14:45:11.842679 | controller | TASK [dnsmasq : Create listener configuration file] ****************************
2026-03-17 14:45:11.842689 | controller | Tuesday 17 March 2026  14:45:11 +0000 (0:00:00.055)       0:01:32.105 *********
2026-03-17 14:45:11.842703 | controller | skipping: [instance]
2026-03-17 14:45:11.842724 | controller |
2026-03-17 14:45:11.842731 | controller | TASK [dnsmasq : Remove listener configuration file] ****************************
2026-03-17 14:45:11.842740 | controller | Tuesday 17 March 2026  14:45:11 +0000 (0:00:00.032)       0:01:32.138 *********
2026-03-17 14:45:12.047527 | controller | ok: [instance]
2026-03-17 14:45:12.047606 | controller |
2026-03-17 14:45:12.047801 | controller | TASK [dnsmasq : Render dns configuration] **************************************
2026-03-17 14:45:12.102815 | controller | Tuesday 17 March 2026  14:45:12 +0000 (0:00:00.204)       0:01:32.342 *********
2026-03-17 14:45:12.102850 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/dns.yml for instance
2026-03-17 14:45:12.134578 | controller |
2026-03-17 14:45:12.134600 | controller | TASK [dnsmasq : Create dns configuration file] *********************************
2026-03-17 14:45:12.134609 | controller | Tuesday 17 March 2026  14:45:12 +0000 (0:00:00.054)       0:01:32.397 *********
2026-03-17 14:45:12.134619 | controller | skipping: [instance]
2026-03-17 14:45:12.134647 | controller |
2026-03-17 14:45:12.134840 | controller | TASK [dnsmasq : Remove dns configuration file] *********************************
2026-03-17 14:45:12.337229 | controller | Tuesday 17 March 2026  14:45:12 +0000 (0:00:00.032)       0:01:32.430 *********
2026-03-17 14:45:12.337266 | controller | ok: [instance]
2026-03-17 14:45:12.364682 | controller |
2026-03-17 14:45:12.364734 | controller | TASK [dnsmasq : Add localhost addresses from defined dnsmasq listen addresses to loopback interface] ***
2026-03-17 14:45:12.364749 | controller | Tuesday 17 March 2026  14:45:12 +0000 (0:00:00.202)       0:01:32.632 *********
2026-03-17 14:45:12.364767 | controller | skipping: [instance]
2026-03-17 14:45:12.364811 | controller |
2026-03-17 14:45:12.364923 | controller | TASK [dnsmasq : Enable and start service] **************************************
2026-03-17 14:45:12.364949 | controller | Tuesday 17 March 2026  14:45:12 +0000 (0:00:00.027)       0:01:32.660 *********
2026-03-17 14:45:12.393294 | controller | skipping: [instance]
2026-03-17 14:45:12.931600 | controller |
2026-03-17 14:45:12.931637 | controller | TASK [dnsmasq : Stop service] **************************************************
2026-03-17 14:45:12.931645 | controller | Tuesday 17 March 2026  14:45:12 +0000 (0:00:00.028)       0:01:32.688 *********
2026-03-17 14:45:12.931655 | controller | ok: [instance]
2026-03-17 14:45:13.134570 | controller |
2026-03-17 14:45:13.134600 | controller | TASK [dnsmasq : Remove unit file] **********************************************
2026-03-17 14:45:13.134608 | controller | Tuesday 17 March 2026  14:45:12 +0000 (0:00:00.538)       0:01:33.226 *********
2026-03-17 14:45:13.134617 | controller | ok: [instance]
2026-03-17 14:45:13.496181 | controller |
2026-03-17 14:45:13.496214 | controller | TASK [dnsmasq : Remove main configuration files] *******************************
2026-03-17 14:45:13.496222 | controller | Tuesday 17 March 2026  14:45:13 +0000 (0:00:00.203)       0:01:33.429 *********
2026-03-17 14:45:13.496233 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2026-03-17 14:45:13.711633 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.d)
2026-03-17 14:45:13.711669 | controller |
2026-03-17 14:45:13.711678 | controller | TASK [dnsmasq : Remove lease file] *********************************************
2026-03-17 14:45:13.711686 | controller | Tuesday 17 March 2026  14:45:13 +0000 (0:00:00.361)       0:01:33.791 *********
2026-03-17 14:45:13.711697 | controller | ok: [instance]
2026-03-17 14:45:13.711730 | controller |
2026-03-17 14:45:13.711740 | controller | TASK [radvd : Gather package facts] ********************************************
2026-03-17 14:45:14.189228 | controller | Tuesday 17 March 2026  14:45:13 +0000 (0:00:00.215)       0:01:34.006 *********
2026-03-17 14:45:14.189268 | controller | ok: [instance]
2026-03-17 14:45:14.225148 | controller |
2026-03-17 14:45:14.225168 | controller | TASK [radvd : Stop and disable radvd service] **********************************
2026-03-17 14:45:14.225177 | controller | Tuesday 17 March 2026  14:45:14 +0000 (0:00:00.477)       0:01:34.484 *********
2026-03-17 14:45:14.225187 | controller | skipping: [instance]
2026-03-17 14:45:14.423593 | controller |
2026-03-17 14:45:14.423624 | controller | TASK [radvd : Remove radvd configuration file] *********************************
2026-03-17 14:45:14.423631 | controller | Tuesday 17 March 2026  14:45:14 +0000 (0:00:00.036)       0:01:34.520 *********
2026-03-17 14:45:14.423641 | controller | ok: [instance]
2026-03-17 14:45:14.423647 | controller |
2026-03-17 14:45:14.423652 | controller | TASK [radvd : Remove configuration directory] **********************************
2026-03-17 14:45:14.423664 | controller | Tuesday 17 March 2026  14:45:14 +0000 (0:00:00.198)       0:01:34.718 *********
2026-03-17 14:45:14.622098 | controller | ok: [instance]
2026-03-17 14:45:14.686217 | controller |
2026-03-17 14:45:14.686239 | controller | TASK [radvd : Remove radvd package] ********************************************
2026-03-17 14:45:14.686248 | controller | Tuesday 17 March 2026  14:45:14 +0000 (0:00:00.198)       0:01:34.917 *********
2026-03-17 14:45:14.686258 | controller | skipping: [instance]
2026-03-17 14:45:14.871926 | controller |
2026-03-17 14:45:14.871958 | controller | TASK [libvirt_manager : Ensure output directory exists] ************************
2026-03-17 14:45:14.871966 | controller | Tuesday 17 March 2026  14:45:14 +0000 (0:00:00.064)       0:01:34.981 *********
2026-03-17 14:45:14.871976 | controller | ok: [instance]
2026-03-17 14:45:15.054582 | controller |
2026-03-17 14:45:15.054613 | controller | TASK [libvirt_manager : Get vmx/svm extensions from /proc/cpuinfo] *************
2026-03-17 14:45:15.054621 | controller | Tuesday 17 March 2026  14:45:14 +0000 (0:00:00.185)       0:01:35.167 *********
2026-03-17 14:45:15.054631 | controller | changed: [instance]
2026-03-17 14:45:15.054648 | controller |
2026-03-17 14:45:15.054807 | controller | TASK [libvirt_manager : Check if KVM is enabled] *******************************
2026-03-17 14:45:15.252608 | controller | Tuesday 17 March 2026  14:45:15 +0000 (0:00:00.182)       0:01:35.349 *********
2026-03-17 14:45:15.252642 | controller | ok: [instance]
2026-03-17 14:45:15.252665 | controller |
2026-03-17 14:45:15.252672 | controller | TASK [libvirt_manager : Fail if KVM not enabled] *******************************
2026-03-17 14:45:15.252679 | controller | Tuesday 17 March 2026  14:45:15 +0000 (0:00:00.197)       0:01:35.547 *********
2026-03-17 14:45:15.283594 | controller | skipping: [instance]
2026-03-17 14:45:15.283755 | controller |
2026-03-17 14:45:15.283789 | controller | TASK [libvirt_manager : Check if VMX virtualization] ***************************
2026-03-17 14:45:15.314398 | controller | Tuesday 17 March 2026  14:45:15 +0000 (0:00:00.031)       0:01:35.579 *********
2026-03-17 14:45:15.314420 | controller | skipping: [instance]
2026-03-17 14:45:15.346030 | controller |
2026-03-17 14:45:15.346051 | controller | TASK [libvirt_manager : Check if SVM virtualization] ***************************
2026-03-17 14:45:15.346058 | controller | Tuesday 17 March 2026  14:45:15 +0000 (0:00:00.030)       0:01:35.609 *********
2026-03-17 14:45:15.346066 | controller | ok: [instance]
2026-03-17 14:45:15.374192 | controller |
2026-03-17 14:45:15.374213 | controller | TASK [libvirt_manager : Enable VMX virtualization] *****************************
2026-03-17 14:45:15.374220 | controller | Tuesday 17 March 2026  14:45:15 +0000 (0:00:00.031)       0:01:35.641 *********
2026-03-17 14:45:15.374229 | controller | skipping: [instance]
2026-03-17 14:46:23.456575 | controller |
2026-03-17 14:46:23.456608 | controller | TASK [libvirt_manager : Install packages required for using KVM] ***************
2026-03-17 14:46:23.456616 | controller | Tuesday 17 March 2026  14:45:15 +0000 (0:00:00.028)       0:01:35.669 *********
2026-03-17 14:46:23.456626 | controller | changed: [instance]
2026-03-17 14:46:23.675487 | controller |
2026-03-17 14:46:23.675529 | controller | TASK [libvirt_manager : Create rules.d polkit folder] **************************
2026-03-17 14:46:23.675539 | controller | Tuesday 17 March 2026  14:46:23 +0000 (0:01:08.082)       0:02:43.751 *********
2026-03-17 14:46:23.675549 | controller | changed: [instance]
2026-03-17 14:46:24.035417 | controller |
2026-03-17 14:46:24.035448 | controller | TASK [libvirt_manager : Enable UNIX socket PolicyKit auth] *********************
2026-03-17 14:46:24.035456 | controller | Tuesday 17 March 2026  14:46:23 +0000 (0:00:00.218)       0:02:43.970 *********
2026-03-17 14:46:24.035466 | controller | changed: [instance]
2026-03-17 14:46:24.592721 | controller |
2026-03-17 14:46:24.592761 | controller | TASK [libvirt_manager : Restart service polkit service] ************************
2026-03-17 14:46:24.592768 | controller | Tuesday 17 March 2026  14:46:24 +0000 (0:00:00.359)       0:02:44.330 *********
2026-03-17 14:46:24.592778 | controller | changed: [instance]
2026-03-17 14:46:24.800349 | controller |
2026-03-17 14:46:24.800380 | controller | TASK [libvirt_manager : Remove directory for service override] *****************
2026-03-17 14:46:24.800388 | controller | Tuesday 17 March 2026  14:46:24 +0000 (0:00:00.557)       0:02:44.887 *********
2026-03-17 14:46:24.800398 | controller | ok: [instance]
2026-03-17 14:46:25.282931 | controller |
2026-03-17 14:46:25.282964 | controller | TASK [libvirt_manager : Reload systemctl and start/enable virtproxyd.service] ***
2026-03-17 14:46:25.282972 | controller | Tuesday 17 March 2026  14:46:24 +0000 (0:00:00.207)       0:02:45.095 *********
2026-03-17 14:46:25.282982 | controller | ok: [instance]
2026-03-17 14:46:25.999565 | controller |
2026-03-17 14:46:25.999601 | controller | TASK [libvirt_manager : Ensure firewalld service is up] ************************
2026-03-17 14:46:25.999610 | controller | Tuesday 17 March 2026  14:46:25 +0000 (0:00:00.482)       0:02:45.577 *********
2026-03-17 14:46:25.999627 | controller | changed: [instance]
2026-03-17 14:46:26.209951 | controller |
2026-03-17 14:46:26.209984 | controller | TASK [libvirt_manager : Ensure libvirt modular driver daemons are started.] ****
2026-03-17 14:46:26.209992 | controller | Tuesday 17 March 2026  14:46:25 +0000 (0:00:00.716)       0:02:46.294 *********
2026-03-17 14:46:26.210003 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=qemu)
2026-03-17 14:46:26.580179 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=interface)
2026-03-17 14:46:26.580208 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=network)
2026-03-17 14:46:26.580215 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nodedev)
2026-03-17 14:46:26.580221 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nwfilter)
2026-03-17 14:46:26.580226 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=secret)
2026-03-17 14:46:26.580237 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=storage)
2026-03-17 14:46:26.580242 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=proxy)
2026-03-17 14:46:26.580248 | controller |
2026-03-17 14:46:26.580253 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for qemu.] *********
2026-03-17 14:46:26.580259 | controller | Tuesday 17 March 2026  14:46:26 +0000 (0:00:00.210)       0:02:46.504 *********
2026-03-17 14:46:26.580268 | controller | changed: [instance]
2026-03-17 14:46:26.925429 | controller |
2026-03-17 14:46:26.925466 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for qemu.] **********
2026-03-17 14:46:26.925475 | controller | Tuesday 17 March 2026  14:46:26 +0000 (0:00:00.370)       0:02:46.875 *********
2026-03-17 14:46:26.925486 | controller | changed: [instance]
2026-03-17 14:46:27.271783 | controller |
2026-03-17 14:46:27.271818 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for qemu.] **************
2026-03-17 14:46:27.271827 | controller | Tuesday 17 March 2026  14:46:26 +0000 (0:00:00.345)       0:02:47.220 *********
2026-03-17 14:46:27.271839 | controller | changed: [instance]
2026-03-17 14:46:27.637205 | controller |
2026-03-17 14:46:27.637238 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for interface.] ****
2026-03-17 14:46:27.637247 | controller | Tuesday 17 March 2026  14:46:27 +0000 (0:00:00.346)       0:02:47.566 *********
2026-03-17 14:46:27.637259 | controller | changed: [instance]
2026-03-17 14:46:28.006154 | controller |
2026-03-17 14:46:28.006189 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for interface.] *****
2026-03-17 14:46:28.006198 | controller | Tuesday 17 March 2026  14:46:27 +0000 (0:00:00.365)       0:02:47.932 *********
2026-03-17 14:46:28.006209 | controller | changed: [instance]
2026-03-17 14:46:28.359404 | controller |
2026-03-17 14:46:28.359439 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for interface.] *********
2026-03-17 14:46:28.359447 | controller | Tuesday 17 March 2026  14:46:28 +0000 (0:00:00.368)       0:02:48.301 *********
2026-03-17 14:46:28.359456 | controller | changed: [instance]
2026-03-17 14:46:28.722731 | controller |
2026-03-17 14:46:28.722777 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for network.] ******
2026-03-17 14:46:28.722791 | controller | Tuesday 17 March 2026  14:46:28 +0000 (0:00:00.353)       0:02:48.654 *********
2026-03-17 14:46:28.722807 | controller | changed: [instance]
2026-03-17 14:46:29.056186 | controller |
2026-03-17 14:46:29.056219 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for network.] *******
2026-03-17 14:46:29.056227 | controller | Tuesday 17 March 2026  14:46:28 +0000 (0:00:00.363)       0:02:49.017 *********
2026-03-17 14:46:29.056237 | controller | changed: [instance]
2026-03-17 14:46:29.387605 | controller |
2026-03-17 14:46:29.387638 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for network.] ***********
2026-03-17 14:46:29.387646 | controller | Tuesday 17 March 2026  14:46:29 +0000 (0:00:00.333)       0:02:49.351 *********
2026-03-17 14:46:29.387655 | controller | changed: [instance]
2026-03-17 14:46:29.387661 | controller |
2026-03-17 14:46:29.387667 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nodedev.] ******
2026-03-17 14:46:29.387674 | controller | Tuesday 17 March 2026  14:46:29 +0000 (0:00:00.331)       0:02:49.682 *********
2026-03-17 14:46:29.726357 | controller | changed: [instance]
2026-03-17 14:46:30.066621 | controller |
2026-03-17 14:46:30.066659 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nodedev.] *******
2026-03-17 14:46:30.066669 | controller | Tuesday 17 March 2026  14:46:29 +0000 (0:00:00.338)       0:02:50.021 *********
2026-03-17 14:46:30.066681 | controller | changed: [instance]
2026-03-17 14:46:30.393665 | controller |
2026-03-17 14:46:30.393701 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nodedev.] ***********
2026-03-17 14:46:30.393709 | controller | Tuesday 17 March 2026  14:46:30 +0000 (0:00:00.340)       0:02:50.361 *********
2026-03-17 14:46:30.393719 | controller | changed: [instance]
2026-03-17 14:46:30.393738 | controller |
2026-03-17 14:46:30.393956 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nwfilter.] *****
2026-03-17 14:46:30.720617 | controller | Tuesday 17 March 2026  14:46:30 +0000 (0:00:00.327)       0:02:50.689 *********
2026-03-17 14:46:30.720659 | controller | changed: [instance]
2026-03-17 14:46:30.720670 | controller |
2026-03-17 14:46:30.720679 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nwfilter.] ******
2026-03-17 14:46:30.720689 | controller | Tuesday 17 March 2026  14:46:30 +0000 (0:00:00.326)       0:02:51.015 *********
2026-03-17 14:46:31.076169 | controller | changed: [instance]
2026-03-17 14:46:31.419968 | controller |
2026-03-17 14:46:31.419998 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nwfilter.] **********
2026-03-17 14:46:31.420012 | controller | Tuesday 17 March 2026  14:46:31 +0000 (0:00:00.355)       0:02:51.371 *********
2026-03-17 14:46:31.420022 | controller | changed: [instance]
2026-03-17 14:46:31.748602 | controller |
2026-03-17 14:46:31.748636 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for secret.] *******
2026-03-17 14:46:31.748645 | controller | Tuesday 17 March 2026  14:46:31 +0000 (0:00:00.343)       0:02:51.715 *********
2026-03-17 14:46:31.748657 | controller | changed: [instance]
2026-03-17 14:46:31.748683 | controller |
2026-03-17 14:46:31.748693 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for secret.] ********
2026-03-17 14:46:31.748717 | controller | Tuesday 17 March 2026  14:46:31 +0000 (0:00:00.328)       0:02:52.044 *********
2026-03-17 14:46:32.077993 | controller | changed: [instance]
2026-03-17 14:46:32.407209 | controller |
2026-03-17 14:46:32.407240 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for secret.] ************
2026-03-17 14:46:32.407248 | controller | Tuesday 17 March 2026  14:46:32 +0000 (0:00:00.329)       0:02:52.373 *********
2026-03-17 14:46:32.407257 | controller | changed: [instance]
2026-03-17 14:46:32.729585 | controller |
2026-03-17 14:46:32.729616 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for storage.] ******
2026-03-17 14:46:32.729625 | controller | Tuesday 17 March 2026  14:46:32 +0000 (0:00:00.329)       0:02:52.702 *********
2026-03-17 14:46:32.729634 | controller | changed: [instance]
2026-03-17 14:46:32.729659 | controller |
2026-03-17 14:46:32.729870 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for storage.] *******
2026-03-17 14:46:33.055137 | controller | Tuesday 17 March 2026  14:46:32 +0000 (0:00:00.322)       0:02:53.024 *********
2026-03-17 14:46:33.055184 | controller | changed: [instance]
2026-03-17 14:46:33.407565 | controller |
2026-03-17 14:46:33.407590 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for storage.] ***********
2026-03-17 14:46:33.407600 | controller | Tuesday 17 March 2026  14:46:33 +0000 (0:00:00.325)       0:02:53.350 *********
2026-03-17 14:46:33.407610 | controller | changed: [instance]
2026-03-17 14:46:33.731973 | controller |
2026-03-17 14:46:33.731998 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for proxy.] ********
2026-03-17 14:46:33.732005 | controller | Tuesday 17 March 2026  14:46:33 +0000 (0:00:00.352)       0:02:53.702 *********
2026-03-17 14:46:33.732014 | controller | changed: [instance]
2026-03-17 14:46:34.069162 | controller |
2026-03-17 14:46:34.069190 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for proxy.] *********
2026-03-17 14:46:34.069198 | controller | Tuesday 17 March 2026  14:46:33 +0000 (0:00:00.324)       0:02:54.027 *********
2026-03-17 14:46:34.069208 | controller | changed: [instance]
2026-03-17 14:46:34.392589 | controller |
2026-03-17 14:46:34.392614 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for proxy.] *************
2026-03-17 14:46:34.392622 | controller | Tuesday 17 March 2026  14:46:34 +0000 (0:00:00.336)       0:02:54.364 *********
2026-03-17 14:46:34.392632 | controller | changed: [instance]
2026-03-17 14:46:34.435576 | controller |
2026-03-17 14:46:34.435601 | controller | TASK [libvirt_manager : Manage service for older releases] *********************
2026-03-17 14:46:34.435611 | controller | Tuesday 17 March 2026  14:46:34 +0000 (0:00:00.323)       0:02:54.687 *********
2026-03-17 14:46:34.435621 | controller | skipping: [instance]
2026-03-17 14:46:34.435772 | controller |
2026-03-17 14:46:34.435785 | controller | TASK [libvirt_manager : Get libvirt group users] *******************************
2026-03-17 14:46:34.724905 | controller | Tuesday 17 March 2026  14:46:34 +0000 (0:00:00.042)       0:02:54.730 *********
2026-03-17 14:46:34.724925 | controller | ok: [instance]
2026-03-17 14:46:35.124178 | controller |
2026-03-17 14:46:35.124209 | controller | TASK [libvirt_manager : Add user if not exists in libvirt group] ***************
2026-03-17 14:46:35.124217 | controller | Tuesday 17 March 2026  14:46:34 +0000 (0:00:00.289)       0:02:55.020 *********
2026-03-17 14:46:35.124226 | controller | changed: [instance]
2026-03-17 14:46:35.435293 | controller |
2026-03-17 14:46:35.435320 | controller | TASK [libvirt_manager : Allow QEMU on home directory for the storage access] ***
2026-03-17 14:46:35.435328 | controller | Tuesday 17 March 2026  14:46:35 +0000 (0:00:00.398)       0:02:55.419 *********
2026-03-17 14:46:35.435338 | controller | changed: [instance]
2026-03-17 14:46:35.641843 | controller |
2026-03-17 14:46:35.641880 | controller | TASK [libvirt_manager : Inject system connection parameters in bashrc] *********
2026-03-17 14:46:35.641891 | controller | Tuesday 17 March 2026  14:46:35 +0000 (0:00:00.311)       0:02:55.730 *********
2026-03-17 14:46:35.641903 | controller | [WARNING]: Reset is not implemented for this connection
2026-03-17 14:46:35.675576 | controller | changed: [instance]
2026-03-17 14:46:35.676241 | controller |
2026-03-17 14:46:36.621928 | controller | TASK [libvirt_manager : Ensure we reload user env] *****************************
2026-03-17 14:46:36.621958 | controller | Tuesday 17 March 2026  14:46:35 +0000 (0:00:00.189)       0:02:55.919 *********
2026-03-17 14:46:36.621966 | controller |
2026-03-17 14:46:36.621977 | controller | TASK [libvirt_manager : Deactivate default] ************************************
2026-03-17 14:46:36.621982 | controller | Tuesday 17 March 2026  14:46:35 +0000 (0:00:00.051)       0:02:55.971 *********
2026-03-17 14:46:36.621991 | controller | ok: [instance]
2026-03-17 14:46:36.880242 | controller |
2026-03-17 14:46:36.880272 | controller | TASK [libvirt_manager : Removing default] **************************************
2026-03-17 14:46:36.880281 | controller | Tuesday 17 March 2026  14:46:36 +0000 (0:00:00.945)       0:02:56.917 *********
2026-03-17 14:46:36.880291 | controller | ok: [instance]
2026-03-17 14:46:37.245559 | controller |
2026-03-17 14:46:37.245593 | controller | TASK [Ensure we use dnsmasq for NetworkManager resolver] ***********************
2026-03-17 14:46:37.245602 | controller | Tuesday 17 March 2026  14:46:36 +0000 (0:00:00.258)       0:02:57.175 *********
2026-03-17 14:46:37.245614 | controller | changed: [instance]
2026-03-17 14:46:37.638454 | controller |
2026-03-17 14:46:37.638492 | controller | TASK [Ensure we delegate .utility zone to our own instance] ********************
2026-03-17 14:46:37.638502 | controller | Tuesday 17 March 2026  14:46:37 +0000 (0:00:00.365)       0:02:57.540 *********
2026-03-17 14:46:37.638532 | controller | changed: [instance]
2026-03-17 14:46:38.099791 | controller |
2026-03-17 14:46:38.099823 | controller | TASK [Restart NetworkManager] **************************************************
2026-03-17 14:46:38.099832 | controller | Tuesday 17 March 2026  14:46:37 +0000 (0:00:00.392)       0:02:57.933 *********
2026-03-17 14:46:38.099842 | controller | changed: [instance]
2026-03-17 14:46:38.347024 | controller |
2026-03-17 14:46:38.347058 | controller | TASK [Ensure ocp_volumes exists] ***********************************************
2026-03-17 14:46:38.347066 | controller | Tuesday 17 March 2026  14:46:38 +0000 (0:00:00.461)       0:02:58.394 *********
2026-03-17 14:46:38.347076 | controller | changed: [instance]
2026-03-17 14:46:38.415037 | controller |
2026-03-17 14:46:38.415060 | controller | PLAY RECAP *********************************************************************
2026-03-17 14:46:38.415068 | controller | instance                   : ok=115  changed=56   unreachable=0    failed=0    skipped=86   rescued=0    ignored=0
2026-03-17 14:46:38.415074 | controller |
2026-03-17 14:46:38.415080 | controller | Tuesday 17 March 2026  14:46:38 +0000 (0:00:00.245)       0:02:58.640 *********
2026-03-17 14:46:38.415086 | controller | ===============================================================================
2026-03-17 14:46:38.415092 | controller | libvirt_manager : Install packages required for using KVM -------------- 68.08s
2026-03-17 14:46:38.415097 | controller | test_deps : Install selinux python libs -------------------------------- 47.53s
2026-03-17 14:46:38.415103 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.26s
2026-03-17 14:46:38.415108 | controller | ci_setup : Install openshift client ------------------------------------- 5.90s
2026-03-17 14:46:38.415114 | controller | dnsmasq : Install needed packages --------------------------------------- 3.60s
2026-03-17 14:46:38.415123 | controller | ci_setup : Install needed packages -------------------------------------- 3.59s
2026-03-17 14:46:38.415129 | controller | libvirt_manager : Populate service facts -------------------------------- 1.75s
2026-03-17 14:46:38.415179 | controller | libvirt_manager : Remove workload and volumes --------------------------- 1.26s
2026-03-17 14:46:38.415186 | controller | dnsmasq : Ensure SELinux knows about the new files and directories ------ 1.25s
2026-03-17 14:46:38.415192 | controller | podman : Ensure podman is installed ------------------------------------- 1.14s
2026-03-17 14:46:38.415197 | controller | test_deps : Install python yaml libs ------------------------------------ 1.13s
2026-03-17 14:46:38.415203 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.09s
2026-03-17 14:46:38.415208 | controller | libvirt_manager : Deactivate default ------------------------------------ 0.95s
2026-03-17 14:46:38.415213 | controller | ci_setup : Manage directories ------------------------------------------- 0.92s
2026-03-17 14:46:38.415219 | controller | Gathering Facts --------------------------------------------------------- 0.79s
2026-03-17 14:46:38.415224 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.77s
2026-03-17 14:46:38.415230 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.77s
2026-03-17 14:46:38.415239 | controller | libvirt_manager : Ensure firewalld service is up ------------------------ 0.72s
2026-03-17 14:46:38.415245 | controller | libvirt_manager : Get installed packages list --------------------------- 0.71s
2026-03-17 14:46:38.415250 | controller | virtualbmc : Remove vbmc files ------------------------------------------ 0.69s
2026-03-17 14:46:38.415259 | controller | INFO     Running boot_order > converge
2026-03-17 14:46:38.863461 | controller |
2026-03-17 14:46:39.727574 | controller | PLAY [Test boot_order configuration] *******************************************
2026-03-17 14:46:39.727608 | controller |
2026-03-17 14:46:39.727616 | controller | TASK [Gathering Facts] *********************************************************
2026-03-17 14:46:39.727621 | controller | Tuesday 17 March 2026  14:46:38 +0000 (0:00:00.037)       0:00:00.037 *********
2026-03-17 14:46:39.727630 | controller | ok: [instance]
2026-03-17 14:46:39.766117 | controller |
2026-03-17 14:46:39.766139 | controller | TASK [Load networking definition] **********************************************
2026-03-17 14:46:39.766146 | controller | Tuesday 17 March 2026  14:46:39 +0000 (0:00:00.864)       0:00:00.902 *********
2026-03-17 14:46:39.766155 | controller | ok: [instance]
2026-03-17 14:46:39.835578 | controller |
2026-03-17 14:46:39.835603 | controller | TASK [libvirt_manager : Import layout generator if needed] *********************
2026-03-17 14:46:39.835611 | controller | Tuesday 17 March 2026  14:46:39 +0000 (0:00:00.038)       0:00:00.941 *********
2026-03-17 14:46:39.835620 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/generate_layout.yml for instance
2026-03-17 14:46:39.974397 | controller |
2026-03-17 14:46:39.974414 | controller | TASK [libvirt_manager : Chose right parameter for layout definition] ***********
2026-03-17 14:46:39.974421 | controller | Tuesday 17 March 2026  14:46:39 +0000 (0:00:00.069)       0:00:01.010 *********
2026-03-17 14:46:39.974430 | controller | ok: [instance]
2026-03-17 14:46:40.083686 | controller |
2026-03-17 14:46:40.083725 | controller | TASK [libvirt_manager : Patch the layout if needed] ****************************
2026-03-17 14:46:40.083735 | controller | Tuesday 17 March 2026  14:46:39 +0000 (0:00:00.138)       0:00:01.149 *********
2026-03-17 14:46:40.083747 | controller | skipping: [instance]
2026-03-17 14:46:40.083776 | controller |
2026-03-17 14:46:40.084061 | controller | TASK [libvirt_manager : Enable forwarding in the libvirt zone] *****************
2026-03-17 14:46:40.576062 | controller | Tuesday 17 March 2026  14:46:40 +0000 (0:00:00.109)       0:00:01.258 *********
2026-03-17 14:46:40.576096 | controller | changed: [instance]
2026-03-17 14:46:41.136636 | controller |
2026-03-17 14:46:41.136673 | controller | TASK [libvirt_manager : Enable masquerading for public traffic] ****************
2026-03-17 14:46:41.136683 | controller | Tuesday 17 March 2026  14:46:40 +0000 (0:00:00.492)       0:00:01.750 *********
2026-03-17 14:46:41.136695 | controller | changed: [instance]
2026-03-17 14:46:41.136723 | controller |
2026-03-17 14:46:41.136742 | controller | TASK [libvirt_manager : Ensure firewalld is restarts] **************************
2026-03-17 14:46:41.137132 | controller | Tuesday 17 March 2026  14:46:41 +0000 (0:00:00.489)       0:00:02.240 *********
2026-03-17 14:46:41.940669 | controller |
2026-03-17 14:46:41.940700 | controller | RUNNING HANDLER [libvirt_manager : Restart firewalld] **************************
2026-03-17 14:46:41.940708 | controller | Tuesday 17 March 2026  14:46:41 +0000 (0:00:00.070)       0:00:02.311 *********
2026-03-17 14:46:41.940718 | controller | changed: [instance]
2026-03-17 14:46:41.991645 | controller |
2026-03-17 14:46:41.991684 | controller | TASK [Deploy virtualbmc] *******************************************************
2026-03-17 14:46:41.991695 | controller | Tuesday 17 March 2026  14:46:41 +0000 (0:00:00.804)       0:00:03.115 *********
2026-03-17 14:46:41.991710 | controller | skipping: [instance]
2026-03-17 14:46:41.991744 | controller |
2026-03-17 14:46:41.991755 | controller | TASK [libvirt_manager : Let the project know we have vbmc available] ***********
2026-03-17 14:46:41.991837 | controller | Tuesday 17 March 2026  14:46:41 +0000 (0:00:00.051)       0:00:03.166 *********
2026-03-17 14:46:42.039911 | controller | skipping: [instance]
2026-03-17 14:46:42.666325 | controller |
2026-03-17 14:46:42.666357 | controller | TASK [libvirt_manager : Create needed workload directory] **********************
2026-03-17 14:46:42.666365 | controller | Tuesday 17 March 2026  14:46:42 +0000 (0:00:00.048)       0:00:03.214 *********
2026-03-17 14:46:42.666375 | controller | changed: [instance] => (item=workload)
2026-03-17 14:46:43.128629 | controller | changed: [instance] => (item=reproducer-inventory)
2026-03-17 14:46:43.128665 | controller | changed: [instance] => (item=volumes)
2026-03-17 14:46:43.128676 | controller |
2026-03-17 14:46:43.128685 | controller | TASK [libvirt_manager : Allow QEMU on workload directory] **********************
2026-03-17 14:46:43.128694 | controller | Tuesday 17 March 2026  14:46:42 +0000 (0:00:00.625)       0:00:03.840 *********
2026-03-17 14:46:43.128713 | controller | changed: [instance] => (item=workload)
2026-03-17 14:46:43.128746 | controller | changed: [instance] => (item=volumes)
2026-03-17 14:46:43.128758 | controller |
2026-03-17 14:46:43.128856 | controller | TASK [libvirt_manager : Generate networking data] ******************************
2026-03-17 14:46:43.128890 | controller | Tuesday 17 March 2026  14:46:43 +0000 (0:00:00.462)       0:00:04.303 *********
2026-03-17 14:46:43.249196 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/generate_networking_data.yml for instance
2026-03-17 14:46:43.312003 | controller |
2026-03-17 14:46:43.312021 | controller | TASK [libvirt_manager : Ensure fact is existing, and empty] ********************
2026-03-17 14:46:43.312029 | controller | Tuesday 17 March 2026  14:46:43 +0000 (0:00:00.120)       0:00:04.424 *********
2026-03-17 14:46:43.312038 | controller | ok: [instance]
2026-03-17 14:46:43.589297 | controller |
2026-03-17 14:46:43.589314 | controller | TASK [libvirt_manager : Check if we get an existing interfaces-info.yml] *******
2026-03-17 14:46:43.589322 | controller | Tuesday 17 March 2026  14:46:43 +0000 (0:00:00.062)       0:00:04.486 *********
2026-03-17 14:46:43.589330 | controller | ok: [instance]
2026-03-17 14:46:43.638305 | controller |
2026-03-17 14:46:43.638322 | controller | TASK [libvirt_manager : Load file if exists] ***********************************
2026-03-17 14:46:43.638329 | controller | Tuesday 17 March 2026  14:46:43 +0000 (0:00:00.277)       0:00:04.764 *********
2026-03-17 14:46:43.638337 | controller | skipping: [instance]
2026-03-17 14:46:43.688606 | controller |
2026-03-17 14:46:43.688648 | controller | TASK [libvirt_manager : Update fact with existing content] *********************
2026-03-17 14:46:43.688660 | controller | Tuesday 17 March 2026  14:46:43 +0000 (0:00:00.049)       0:00:04.813 *********
2026-03-17 14:46:43.688674 | controller | skipping: [instance]
2026-03-17 14:46:43.762647 | controller |
2026-03-17 14:46:43.762675 | controller | TASK [libvirt_manager : Generate VM list] **************************************
2026-03-17 14:46:43.762686 | controller | Tuesday 17 March 2026  14:46:43 +0000 (0:00:00.050)       0:00:04.863 *********
2026-03-17 14:46:43.762699 | controller | ok: [instance]
2026-03-17 14:46:43.762732 | controller |
2026-03-17 14:46:43.762743 | controller | TASK [libvirt_manager : Generate/update mapping all nodes] *********************
2026-03-17 14:46:43.762785 | controller | Tuesday 17 March 2026  14:46:43 +0000 (0:00:00.074)       0:00:04.937 *********
2026-03-17 14:46:43.942702 | controller | ok: [instance] => (item={'key': 'disk-first-0', 'value': 'disk_first'})
2026-03-17 14:46:43.996306 | controller | ok: [instance] => (item={'key': 'net-first-0', 'value': 'net_first'})
2026-03-17 14:46:43.996327 | controller | ok: [instance] => (item={'key': 'net-only-0', 'value': 'net_only'})
2026-03-17 14:46:43.996335 | controller | ok: [instance] => (item={'key': 'no-boot-order-0', 'value': 'no_boot_order'})
2026-03-17 14:46:43.996340 | controller |
2026-03-17 14:46:43.996346 | controller | TASK [libvirt_manager : Append baremetal nodes if needed] **********************
2026-03-17 14:46:43.996358 | controller | Tuesday 17 March 2026  14:46:43 +0000 (0:00:00.179)       0:00:05.117 *********
2026-03-17 14:46:43.996367 | controller | skipping: [instance]
2026-03-17 14:46:44.408537 | controller |
2026-03-17 14:46:44.408567 | controller | TASK [libvirt_manager : Dump MAC mapping] **************************************
2026-03-17 14:46:44.408575 | controller | Tuesday 17 March 2026  14:46:43 +0000 (0:00:00.053)       0:00:05.171 *********
2026-03-17 14:46:44.408585 | controller | changed: [instance]
2026-03-17 14:46:44.542457 | controller |
2026-03-17 14:46:44.542480 | controller | TASK [libvirt_manager : Inject all VMs in the inventory] ***********************
2026-03-17 14:46:44.542491 | controller | Tuesday 17 March 2026  14:46:44 +0000 (0:00:00.412)       0:00:05.583 *********
2026-03-17 14:46:44.542503 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/add_vm_to_inventory.yml for instance => (item=Adding disk-first-0 to disk_firsts)
2026-03-17 14:46:44.636985 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/add_vm_to_inventory.yml for instance => (item=Adding net-first-0 to net_firsts)
2026-03-17 14:46:44.637001 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/add_vm_to_inventory.yml for instance => (item=Adding net-only-0 to net_onlys)
2026-03-17 14:46:44.637009 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/add_vm_to_inventory.yml for instance => (item=Adding no-boot-order-0 to no_boot_orders)
2026-03-17 14:46:44.637014 | controller |
2026-03-17 14:46:44.637020 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2026-03-17 14:46:44.637025 | controller | Tuesday 17 March 2026  14:46:44 +0000 (0:00:00.133)       0:00:05.716 *********
2026-03-17 14:46:44.637033 | controller | changed: [instance]
2026-03-17 14:46:44.923198 | controller |
2026-03-17 14:46:44.923237 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2026-03-17 14:46:44.923248 | controller | Tuesday 17 March 2026  14:46:44 +0000 (0:00:00.094)       0:00:05.811 *********
2026-03-17 14:46:44.923261 | controller | changed: [instance]
2026-03-17 14:46:45.145071 | controller |
2026-03-17 14:46:45.145112 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2026-03-17 14:46:45.145121 | controller | Tuesday 17 March 2026  14:46:44 +0000 (0:00:00.285)       0:00:06.097 *********
2026-03-17 14:46:45.145131 | controller | changed: [instance]
2026-03-17 14:46:45.516976 | controller |
2026-03-17 14:46:45.517008 | controller | TASK [libvirt_manager : daniel debug] ******************************************
2026-03-17 14:46:45.517017 | controller | Tuesday 17 March 2026  14:46:45 +0000 (0:00:00.222)       0:00:06.319 *********
2026-03-17 14:46:45.517028 | controller | changed: [instance]
2026-03-17 14:46:45.587635 | controller |
2026-03-17 14:46:45.587672 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2026-03-17 14:46:45.587683 | controller | Tuesday 17 March 2026  14:46:45 +0000 (0:00:00.371)       0:00:06.691 *********
2026-03-17 14:46:45.587696 | controller | changed: [instance]
2026-03-17 14:46:45.587740 | controller |
2026-03-17 14:46:45.587760 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2026-03-17 14:46:45.587778 | controller | Tuesday 17 March 2026  14:46:45 +0000 (0:00:00.070)       0:00:06.762 *********
2026-03-17 14:46:45.823652 | controller | changed: [instance]
2026-03-17 14:46:45.823806 | controller |
2026-03-17 14:46:45.823832 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2026-03-17 14:46:45.823850 | controller | Tuesday 17 March 2026  14:46:45 +0000 (0:00:00.235)       0:00:06.998 *********
2026-03-17 14:46:46.045473 | controller | changed: [instance]
2026-03-17 14:46:46.422455 | controller |
2026-03-17 14:46:46.422494 | controller | TASK [libvirt_manager : daniel debug] ******************************************
2026-03-17 14:46:46.422502 | controller | Tuesday 17 March 2026  14:46:46 +0000 (0:00:00.222)       0:00:07.220 *********
2026-03-17 14:46:46.422526 | controller | changed: [instance]
2026-03-17 14:46:46.491959 | controller |
2026-03-17 14:46:46.491983 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2026-03-17 14:46:46.491992 | controller | Tuesday 17 March 2026  14:46:46 +0000 (0:00:00.376)       0:00:07.597 *********
2026-03-17 14:46:46.492003 | controller | changed: [instance]
2026-03-17 14:46:46.708045 | controller |
2026-03-17 14:46:46.708073 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2026-03-17 14:46:46.708081 | controller | Tuesday 17 March 2026  14:46:46 +0000 (0:00:00.069)       0:00:07.666 *********
2026-03-17 14:46:46.708090 | controller | changed: [instance]
2026-03-17 14:46:46.927058 | controller |
2026-03-17 14:46:46.927084 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2026-03-17 14:46:46.927092 | controller | Tuesday 17 March 2026  14:46:46 +0000 (0:00:00.216)       0:00:07.882 *********
2026-03-17 14:46:46.927101 | controller | changed: [instance]
2026-03-17 14:46:47.312667 | controller |
2026-03-17 14:46:47.312699 | controller | TASK [libvirt_manager : daniel debug] ******************************************
2026-03-17 14:46:47.312707 | controller | Tuesday 17 March 2026  14:46:46 +0000 (0:00:00.219)       0:00:08.101 *********
2026-03-17 14:46:47.312718 | controller | changed: [instance]
2026-03-17 14:46:47.384167 | controller |
2026-03-17 14:46:47.384206 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2026-03-17 14:46:47.384214 | controller | Tuesday 17 March 2026  14:46:47 +0000 (0:00:00.385)       0:00:08.487 *********
2026-03-17 14:46:47.384222 | controller | changed: [instance]
2026-03-17 14:46:47.593592 | controller |
2026-03-17 14:46:47.593623 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2026-03-17 14:46:47.593631 | controller | Tuesday 17 March 2026  14:46:47 +0000 (0:00:00.071)       0:00:08.558 *********
2026-03-17 14:46:47.593641 | controller | changed: [instance]
2026-03-17 14:46:47.853199 | controller |
2026-03-17 14:46:47.853231 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2026-03-17 14:46:47.853239 | controller | Tuesday 17 March 2026  14:46:47 +0000 (0:00:00.209)       0:00:08.768 *********
2026-03-17 14:46:47.853249 | controller | changed: [instance]
2026-03-17 14:46:48.233491 | controller |
2026-03-17 14:46:48.233528 | controller | TASK [libvirt_manager : daniel debug] ******************************************
2026-03-17 14:46:48.233540 | controller | Tuesday 17 March 2026  14:46:47 +0000 (0:00:00.259)       0:00:09.027 *********
2026-03-17 14:46:48.233549 | controller | changed: [instance]
2026-03-17 14:46:48.440875 | controller |
2026-03-17 14:46:48.440900 | controller | TASK [libvirt_manager : Check if ini inventory file exists] ********************
2026-03-17 14:46:48.440908 | controller | Tuesday 17 March 2026  14:46:48 +0000 (0:00:00.380)       0:00:09.408 *********
2026-03-17 14:46:48.440917 | controller | ok: [instance]
2026-03-17 14:46:49.058806 | controller |
2026-03-17 14:46:49.058835 | controller | TASK [libvirt_manager : Test] **************************************************
2026-03-17 14:46:49.058843 | controller | Tuesday 17 March 2026  14:46:48 +0000 (0:00:00.207)       0:00:09.615 *********
2026-03-17 14:46:49.058853 | controller | changed: [instance]
2026-03-17 14:46:49.237588 | controller |
2026-03-17 14:46:49.237618 | controller | TASK [libvirt_manager : Convert ini inventory file into yaml] ******************
2026-03-17 14:46:49.237626 | controller | Tuesday 17 March 2026  14:46:49 +0000 (0:00:00.617)       0:00:10.233 *********
2026-03-17 14:46:49.237637 | controller | fatal: [instance]: FAILED! => changed=true
2026-03-17 14:46:49.238063 | controller |   cmd: |-
2026-03-17 14:46:49.238094 | controller |     /usr/local/bin/ansible-inventory -i /home/zuul/libvirt_inventory.ini -y --list > /home/zuul/libvirt_inventory.yaml
2026-03-17 14:46:49.238102 | controller |   delta: '0:00:00.004872'
2026-03-17 14:46:49.238109 | controller |   end: '2026-03-17 14:46:49.214127'
2026-03-17 14:46:49.238115 | controller |   msg: non-zero return code
2026-03-17 14:46:49.238122 | controller |   rc: 127
2026-03-17 14:46:49.238131 | controller |   start: '2026-03-17 14:46:49.209255'
2026-03-17 14:46:49.291420 | controller |   stderr: '/bin/sh: line 1: /usr/local/bin/ansible-inventory: No such file or directory'
2026-03-17 14:46:49.291439 | controller |   stderr_lines: <omitted>
2026-03-17 14:46:49.291447 | controller |   stdout: ''
2026-03-17 14:46:49.291453 | controller |   stdout_lines: <omitted>
2026-03-17 14:46:49.291462 | controller | ...ignoring
2026-03-17 14:46:49.338113 | controller |
2026-03-17 14:46:49.338134 | controller | TASK [networking_mapper : Check for Networking Definition file existence] ******
2026-03-17 14:46:49.338142 | controller | Tuesday 17 March 2026  14:46:49 +0000 (0:00:00.232)       0:00:10.466 *********
2026-03-17 14:46:49.338150 | controller | skipping: [instance]
2026-03-17 14:46:49.382393 | controller |
2026-03-17 14:46:49.382412 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2026-03-17 14:46:49.382426 | controller | Tuesday 17 March 2026  14:46:49 +0000 (0:00:00.046)       0:00:10.513 *********
2026-03-17 14:46:49.382435 | controller | skipping: [instance]
2026-03-17 14:46:49.430096 | controller |
2026-03-17 14:46:49.430115 | controller | TASK [networking_mapper : Check for interfaces info file existence] ************
2026-03-17 14:46:49.430123 | controller | Tuesday 17 March 2026  14:46:49 +0000 (0:00:00.044)       0:00:10.557 *********
2026-03-17 14:46:49.430132 | controller | skipping: [instance]
2026-03-17 14:46:49.477009 | controller |
2026-03-17 14:46:49.477040 | controller | TASK [networking_mapper : Load the interfaces info from file] ******************
2026-03-17 14:46:49.477048 | controller | Tuesday 17 March 2026  14:46:49 +0000 (0:00:00.047)       0:00:10.605 *********
2026-03-17 14:46:49.477058 | controller | skipping: [instance]
2026-03-17 14:46:49.517637 | controller |
2026-03-17 14:46:49.517673 | controller | TASK [networking_mapper : Ensure local fact is empty] **************************
2026-03-17 14:46:49.517683 | controller | Tuesday 17 March 2026  14:46:49 +0000 (0:00:00.046)       0:00:10.651 *********
2026-03-17 14:46:49.517696 | controller | ok: [instance]
2026-03-17 14:46:49.713988 | controller |
2026-03-17 14:46:49.714029 | controller | TASK [networking_mapper : Set the input Networking Definition and patch it if needed] ***
2026-03-17 14:46:49.714039 | controller | Tuesday 17 March 2026  14:46:49 +0000 (0:00:00.040)       0:00:10.692 *********
2026-03-17 14:46:49.714053 | controller | ok: [instance] => (item={'networks': {'ctlplane': {'network': '192.168.140.0/24', 'gateway': '192.168.140.1', 'mtu': 1500}}, 'group-templates': {'disk_firsts': {'network-template': {'range': {'start': 10, 'length': 1}}, 'networks': {'ctlplane': {}}}, 'net_firsts': {'network-template': {'range': {'start': 20, 'length': 1}}, 'networks': {'ctlplane': {}}}, 'net_onlys': {'network-template': {'range': {'start': 30, 'length': 1}}, 'networks': {'ctlplane': {}}}, 'no_boot_orders': {'network-template': {'range': {'start': 40, 'length': 1}}, 'networks': {'ctlplane': {}}}}})
2026-03-17 14:46:49.757131 | controller |
2026-03-17 14:46:49.757152 | controller | TASK [networking_mapper : Gather facts if requested] ***************************
2026-03-17 14:46:49.757160 | controller | Tuesday 17 March 2026  14:46:49 +0000 (0:00:00.195)       0:00:10.888 *********
2026-03-17 14:46:49.757187 | controller | skipping: [instance]
2026-03-17 14:46:49.978684 | controller |
2026-03-17 14:46:49.978711 | controller | TASK [networking_mapper : Ensure CI infrastructure dir exists] *****************
2026-03-17 14:46:49.978719 | controller | Tuesday 17 March 2026  14:46:49 +0000 (0:00:00.043)       0:00:10.932 *********
2026-03-17 14:46:49.978729 | controller | changed: [instance]
2026-03-17 14:46:50.364958 | controller |
2026-03-17 14:46:50.364987 | controller | TASK [networking_mapper : Write the Networking Definition to file] *************
2026-03-17 14:46:50.364996 | controller | Tuesday 17 March 2026  14:46:49 +0000 (0:00:00.221)       0:00:11.153 *********
2026-03-17 14:46:50.365006 | controller | changed: [instance]
2026-03-17 14:46:50.551287 | controller |
2026-03-17 14:46:50.551315 | controller | TASK [networking_mapper : Change cluster network address - daniel] *************
2026-03-17 14:46:50.551323 | controller | Tuesday 17 March 2026  14:46:50 +0000 (0:00:00.386)       0:00:11.539 *********
2026-03-17 14:46:50.551333 | controller | fatal: [instance]: FAILED! => changed=true
2026-03-17 14:46:50.551355 | controller |   cmd: |-
2026-03-17 14:46:50.551362 | controller |     sed -i 's/192.168.122/192.168.111/g' /etc/ci/env/networking-definition.yml
2026-03-17 14:46:50.551368 | controller |   delta: '0:00:00.005110'
2026-03-17 14:46:50.551380 | controller |   end: '2026-03-17 14:46:50.528940'
2026-03-17 14:46:50.551455 | controller |   msg: non-zero return code
2026-03-17 14:46:50.551578 | controller |   rc: 4
2026-03-17 14:46:50.551625 | controller |   start: '2026-03-17 14:46:50.523830'
2026-03-17 14:46:50.551919 | controller |   stderr: 'sed: couldn''t open temporary file /etc/ci/env/sed2RRJ3A: Permission denied'
2026-03-17 14:46:50.552004 | controller |   stderr_lines: <omitted>
2026-03-17 14:46:50.552024 | controller |   stdout: ''
2026-03-17 14:46:50.552036 | controller |   stdout_lines: <omitted>
2026-03-17 14:46:50.555559 | controller |
2026-03-17 14:46:50.555658 | controller | PLAY RECAP *********************************************************************
2026-03-17 14:46:50.555668 | controller | instance                   : ok=42   changed=26   unreachable=0    failed=1    skipped=11   rescued=0    ignored=1
2026-03-17 14:46:50.555675 | controller |
2026-03-17 14:46:50.555681 | controller | Tuesday 17 March 2026  14:46:50 +0000 (0:00:00.188)       0:00:11.728 *********
2026-03-17 14:46:50.555686 | controller | ===============================================================================
2026-03-17 14:46:50.555692 | controller | Gathering Facts --------------------------------------------------------- 0.86s
2026-03-17 14:46:50.555697 | controller | libvirt_manager : Restart firewalld ------------------------------------- 0.80s
2026-03-17 14:46:50.555702 | controller | libvirt_manager : Create needed workload directory ---------------------- 0.63s
2026-03-17 14:46:50.555707 | controller | libvirt_manager : Test -------------------------------------------------- 0.62s
2026-03-17 14:46:50.555713 | controller | libvirt_manager : Enable forwarding in the libvirt zone ----------------- 0.49s
2026-03-17 14:46:50.555718 | controller | libvirt_manager : Enable masquerading for public traffic ---------------- 0.49s
2026-03-17 14:46:50.555723 | controller | libvirt_manager : Allow QEMU on workload directory ---------------------- 0.46s
2026-03-17 14:46:50.555729 | controller | libvirt_manager : Dump MAC mapping -------------------------------------- 0.41s
2026-03-17 14:46:50.555734 | controller | networking_mapper : Write the Networking Definition to file ------------- 0.39s
2026-03-17 14:46:50.555739 | controller | libvirt_manager : daniel debug ------------------------------------------ 0.39s
2026-03-17 14:46:50.555749 | controller | libvirt_manager : daniel debug ------------------------------------------ 0.38s
2026-03-17 14:46:50.555755 | controller | libvirt_manager : daniel debug ------------------------------------------ 0.38s
2026-03-17 14:46:50.555761 | controller | libvirt_manager : daniel debug ------------------------------------------ 0.37s
2026-03-17 14:46:50.555766 | controller | libvirt_manager : Ensure group section exists --------------------------- 0.29s
2026-03-17 14:46:50.555771 | controller | libvirt_manager : Check if we get an existing interfaces-info.yml ------- 0.28s
2026-03-17 14:46:50.555776 | controller | libvirt_manager : Append host under proper group ------------------------ 0.26s
2026-03-17 14:46:50.555782 | controller | libvirt_manager : Ensure group section exists --------------------------- 0.24s
2026-03-17 14:46:50.555789 | controller | libvirt_manager : Convert ini inventory file into yaml ------------------ 0.23s
2026-03-17 14:46:50.555926 | controller | libvirt_manager : Append host under proper group ------------------------ 0.22s
2026-03-17 14:46:50.556059 | controller | libvirt_manager : Append host under proper group ------------------------ 0.22s
2026-03-17 14:46:50.615106 | 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']
2026-03-17 14:46:50.615531 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2026-03-17 14:46:50.623642 | controller | INFO     Running boot_order > cleanup
2026-03-17 14:46:51.100247 | controller |
2026-03-17 14:46:51.100449 | controller | PLAY [Cleanup] *****************************************************************
2026-03-17 14:46:51.100603 | controller |
2026-03-17 14:46:51.100746 | controller | TASK [Gathering Facts] *********************************************************
2026-03-17 14:46:51.100881 | controller | Tuesday 17 March 2026  14:46:51 +0000 (0:00:00.038)       0:00:00.038 *********
2026-03-17 14:46:51.942270 | controller | ok: [instance]
2026-03-17 14:46:52.258257 | controller |
2026-03-17 14:46:52.258291 | controller | TASK [Ensure we have the destination directory] ********************************
2026-03-17 14:46:52.258299 | controller | Tuesday 17 March 2026  14:46:51 +0000 (0:00:00.841)       0:00:00.879 *********
2026-03-17 14:46:52.258309 | controller | changed: [instance]
2026-03-17 14:46:53.571604 | controller |
2026-03-17 14:46:53.571639 | controller | TASK [Copy generated content to proper location] *******************************
2026-03-17 14:46:53.571648 | controller | Tuesday 17 March 2026  14:46:52 +0000 (0:00:00.315)       0:00:01.195 *********
2026-03-17 14:46:53.571660 | controller | changed: [instance] => (item=/opt/basedir/artifacts)
2026-03-17 14:46:53.571684 | controller | changed: [instance] => (item=/opt/basedir/logs)
2026-03-17 14:46:53.571692 | controller | ok: [instance] => (item=/opt/basedir/cifmw_config_drive)
2026-03-17 14:46:53.571698 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2026-03-17 14:46:53.571707 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.d)
2026-03-17 14:46:53.571728 | controller |
2026-03-17 14:46:53.571933 | controller | TASK [libvirt_manager : Get installed packages list] ***************************
2026-03-17 14:46:54.298272 | controller | Tuesday 17 March 2026  14:46:53 +0000 (0:00:01.313)       0:00:02.509 *********
2026-03-17 14:46:54.298310 | controller | ok: [instance]
2026-03-17 14:46:56.259667 | controller |
2026-03-17 14:46:56.259703 | controller | TASK [libvirt_manager : Populate service facts] ********************************
2026-03-17 14:46:56.259713 | controller | Tuesday 17 March 2026  14:46:54 +0000 (0:00:00.726)       0:00:03.235 *********
2026-03-17 14:46:56.259724 | controller | ok: [instance]
2026-03-17 14:46:56.259751 | controller |
2026-03-17 14:46:56.259761 | controller | TASK [libvirt_manager : Start virtqemud socket service] ************************
2026-03-17 14:46:57.077885 | controller | Tuesday 17 March 2026  14:46:56 +0000 (0:00:01.961)       0:00:05.197 *********
2026-03-17 14:46:57.077919 | controller | ok: [instance] => (item=virtqemud.service)
2026-03-17 14:46:57.943340 | controller | ok: [instance] => (item=virtqemud.socket)
2026-03-17 14:46:57.943370 | controller |
2026-03-17 14:46:57.943378 | controller | TASK [libvirt_manager : Start virtnetworkd socket service] *********************
2026-03-17 14:46:57.943384 | controller | Tuesday 17 March 2026  14:46:57 +0000 (0:00:00.817)       0:00:06.015 *********
2026-03-17 14:46:57.943395 | controller | changed: [instance] => (item=virtnetworkd.service)
2026-03-17 14:46:58.842382 | controller | ok: [instance] => (item=virtnetworkd.socket)
2026-03-17 14:46:58.842414 | controller |
2026-03-17 14:46:58.842422 | controller | TASK [libvirt_manager : Start virtstoraged socket service] *********************
2026-03-17 14:46:58.842429 | controller | Tuesday 17 March 2026  14:46:57 +0000 (0:00:00.865)       0:00:06.881 *********
2026-03-17 14:46:58.842439 | controller | changed: [instance] => (item=virtstoraged.service)
2026-03-17 14:46:58.892666 | controller | ok: [instance] => (item=virtstoraged.socket)
2026-03-17 14:46:58.892709 | controller |
2026-03-17 14:46:58.892721 | controller | TASK [libvirt_manager : Set _is_deepscrub internal fact] ***********************
2026-03-17 14:46:58.892730 | controller | Tuesday 17 March 2026  14:46:58 +0000 (0:00:00.898)       0:00:07.779 *********
2026-03-17 14:46:58.892744 | controller | ok: [instance]
2026-03-17 14:46:58.892767 | controller |
2026-03-17 14:46:58.892776 | controller | TASK [libvirt_manager : List all of the existing virtual machines] *************
2026-03-17 14:46:58.892792 | controller | Tuesday 17 March 2026  14:46:58 +0000 (0:00:00.050)       0:00:07.830 *********
2026-03-17 14:46:59.283712 | controller | ok: [instance]
2026-03-17 14:46:59.283765 | controller |
2026-03-17 14:46:59.284030 | controller | TASK [libvirt_manager : Get pool configuration] ********************************
2026-03-17 14:46:59.654632 | controller | Tuesday 17 March 2026  14:46:59 +0000 (0:00:00.391)       0:00:08.221 *********
2026-03-17 14:46:59.654674 | controller | ok: [instance]
2026-03-17 14:46:59.727622 | controller |
2026-03-17 14:46:59.727653 | controller | TASK [libvirt_manager : Filter out target environment] *************************
2026-03-17 14:46:59.727666 | controller | Tuesday 17 March 2026  14:46:59 +0000 (0:00:00.370)       0:00:08.592 *********
2026-03-17 14:46:59.727680 | controller | ok: [instance]
2026-03-17 14:46:59.727719 | controller |
2026-03-17 14:46:59.727732 | controller | TASK [libvirt_manager : Expose cleanup list] ***********************************
2026-03-17 14:46:59.727839 | controller | Tuesday 17 March 2026  14:46:59 +0000 (0:00:00.073)       0:00:08.665 *********
2026-03-17 14:46:59.847146 | controller | ok: [instance] =>
2026-03-17 14:46:59.880979 | controller |   cleanup_vms: []
2026-03-17 14:46:59.881008 | controller |
2026-03-17 14:46:59.881016 | controller | TASK [libvirt_manager : Clean ssh known_hosts] *********************************
2026-03-17 14:46:59.881022 | controller | Tuesday 17 March 2026  14:46:59 +0000 (0:00:00.118)       0:00:08.784 *********
2026-03-17 14:46:59.881032 | controller | skipping: [instance]
2026-03-17 14:46:59.913634 | controller |
2026-03-17 14:46:59.913669 | controller | TASK [libvirt_manager : Destroy machine] ***************************************
2026-03-17 14:46:59.913679 | controller | Tuesday 17 March 2026  14:46:59 +0000 (0:00:00.034)       0:00:08.818 *********
2026-03-17 14:46:59.913690 | controller | skipping: [instance]
2026-03-17 14:46:59.913713 | controller |
2026-03-17 14:46:59.913723 | controller | TASK [libvirt_manager : Undefine machine] **************************************
2026-03-17 14:46:59.913859 | controller | Tuesday 17 March 2026  14:46:59 +0000 (0:00:00.032)       0:00:08.851 *********
2026-03-17 14:46:59.947214 | controller | skipping: [instance]
2026-03-17 14:46:59.947360 | controller |
2026-03-17 14:46:59.947509 | controller | TASK [libvirt_manager : (localhost) Clean ssh jumpers] *************************
2026-03-17 14:46:59.947679 | controller | Tuesday 17 March 2026  14:46:59 +0000 (0:00:00.033)       0:00:08.885 *********
2026-03-17 14:46:59.981795 | controller | skipping: [instance]
2026-03-17 14:46:59.981923 | controller |
2026-03-17 14:46:59.982057 | controller | TASK [libvirt_manager : (instance) Clean ssh jumpers] **************************
2026-03-17 14:46:59.982197 | controller | Tuesday 17 March 2026  14:46:59 +0000 (0:00:00.034)       0:00:08.920 *********
2026-03-17 14:47:00.014142 | controller | skipping: [instance]
2026-03-17 14:47:00.014286 | controller |
2026-03-17 14:47:00.014420 | controller | TASK [libvirt_manager : Get network list] **************************************
2026-03-17 14:47:00.014564 | controller | Tuesday 17 March 2026  14:47:00 +0000 (0:00:00.032)       0:00:08.952 *********
2026-03-17 14:47:00.386536 | controller | ok: [instance]
2026-03-17 14:47:00.476035 | controller |
2026-03-17 14:47:00.476064 | controller | TASK [libvirt_manager : Filter out target nets] ********************************
2026-03-17 14:47:00.476072 | controller | Tuesday 17 March 2026  14:47:00 +0000 (0:00:00.371)       0:00:09.324 *********
2026-03-17 14:47:00.476082 | controller | ok: [instance]
2026-03-17 14:47:00.556151 | controller |
2026-03-17 14:47:00.556174 | controller | TASK [libvirt_manager : Expose cleanup list] ***********************************
2026-03-17 14:47:00.556181 | controller | Tuesday 17 March 2026  14:47:00 +0000 (0:00:00.089)       0:00:09.413 *********
2026-03-17 14:47:00.556200 | controller | ok: [instance] =>
2026-03-17 14:47:00.590121 | controller |   cleanup_nets: []
2026-03-17 14:47:00.590139 | controller |
2026-03-17 14:47:00.590146 | controller | TASK [Remove dnsmasq network bits] *********************************************
2026-03-17 14:47:00.590152 | controller | Tuesday 17 March 2026  14:47:00 +0000 (0:00:00.080)       0:00:09.494 *********
2026-03-17 14:47:00.590161 | controller | skipping: [instance]
2026-03-17 14:47:00.652869 | controller |
2026-03-17 14:47:00.652899 | controller | TASK [libvirt_manager : Destroy networks] **************************************
2026-03-17 14:47:00.652908 | controller | Tuesday 17 March 2026  14:47:00 +0000 (0:00:00.034)       0:00:09.528 *********
2026-03-17 14:47:00.652920 | controller | skipping: [instance]
2026-03-17 14:47:00.685939 | controller |
2026-03-17 14:47:00.685963 | controller | TASK [libvirt_manager : Undefine networks] *************************************
2026-03-17 14:47:00.685973 | controller | Tuesday 17 March 2026  14:47:00 +0000 (0:00:00.062)       0:00:09.590 *********
2026-03-17 14:47:00.685984 | controller | skipping: [instance]
2026-03-17 14:47:00.718501 | controller |
2026-03-17 14:47:00.718541 | controller | TASK [libvirt_manager : Ensure no trace of networks lays in nmcli] *************
2026-03-17 14:47:00.718551 | controller | Tuesday 17 March 2026  14:47:00 +0000 (0:00:00.033)       0:00:09.623 *********
2026-03-17 14:47:00.718566 | controller | skipping: [instance]
2026-03-17 14:47:01.020599 | controller |
2026-03-17 14:47:01.020632 | controller | TASK [libvirt_manager : Find dummy interface connection files] *****************
2026-03-17 14:47:01.020642 | controller | Tuesday 17 March 2026  14:47:00 +0000 (0:00:00.032)       0:00:09.656 *********
2026-03-17 14:47:01.020660 | controller | ok: [instance]
2026-03-17 14:47:01.020686 | controller |
2026-03-17 14:47:01.020695 | controller | TASK [libvirt_manager : Remove dummy interface connections] ********************
2026-03-17 14:47:01.020757 | controller | Tuesday 17 March 2026  14:47:01 +0000 (0:00:00.302)       0:00:09.958 *********
2026-03-17 14:47:01.053065 | controller | skipping: [instance]
2026-03-17 14:47:01.085577 | controller |
2026-03-17 14:47:01.085600 | controller | TASK [libvirt_manager : Clean firewalld libvirt zone] **************************
2026-03-17 14:47:01.085609 | controller | Tuesday 17 March 2026  14:47:01 +0000 (0:00:00.032)       0:00:09.991 *********
2026-03-17 14:47:01.085620 | controller | skipping: [instance]
2026-03-17 14:47:01.085783 | controller |
2026-03-17 14:47:01.085803 | controller | TASK [libvirt_manager : Ensure firewalld is enabled and started] ***************
2026-03-17 14:47:01.647465 | controller | Tuesday 17 March 2026  14:47:01 +0000 (0:00:00.032)       0:00:10.023 *********
2026-03-17 14:47:01.647505 | controller | ok: [instance]
2026-03-17 14:47:02.155662 | controller |
2026-03-17 14:47:02.155778 | controller | TASK [libvirt_manager : Disable masquerade in firewalld default zone] **********
2026-03-17 14:47:02.155789 | controller | Tuesday 17 March 2026  14:47:01 +0000 (0:00:00.561)       0:00:10.585 *********
2026-03-17 14:47:02.155804 | controller | changed: [instance]
2026-03-17 14:47:02.724408 | controller |
2026-03-17 14:47:02.724455 | controller | TASK [libvirt_manager : Disable forward in firewalld libvirt zone] *************
2026-03-17 14:47:02.724465 | controller | Tuesday 17 March 2026  14:47:02 +0000 (0:00:00.506)       0:00:11.091 *********
2026-03-17 14:47:02.724476 | controller | changed: [instance]
2026-03-17 14:47:02.831029 | controller |
2026-03-17 14:47:02.831054 | controller | TASK [libvirt_manager : Remove cifmw storage pool] *****************************
2026-03-17 14:47:02.831063 | controller | Tuesday 17 March 2026  14:47:02 +0000 (0:00:00.570)       0:00:11.661 *********
2026-03-17 14:47:02.831074 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/storage_pool.yml for instance
2026-03-17 14:47:02.910596 | controller |
2026-03-17 14:47:02.910619 | controller | TASK [libvirt_manager : Ensure we have a known action] *************************
2026-03-17 14:47:02.910628 | controller | Tuesday 17 March 2026  14:47:02 +0000 (0:00:00.106)       0:00:11.768 *********
2026-03-17 14:47:02.910639 | controller | ok: [instance] => changed=false
2026-03-17 14:47:02.910666 | controller |   msg: All assertions passed
2026-03-17 14:47:02.910674 | controller |
2026-03-17 14:47:02.910693 | controller | TASK [libvirt_manager : Gather the status of cifmw-pool storage pool.] *********
2026-03-17 14:47:02.910718 | controller | Tuesday 17 March 2026  14:47:02 +0000 (0:00:00.079)       0:00:11.848 *********
2026-03-17 14:47:03.193415 | controller | changed: [instance]
2026-03-17 14:47:03.270628 | controller |
2026-03-17 14:47:03.270669 | controller | TASK [libvirt_manager : Create the storage pool] *******************************
2026-03-17 14:47:03.270680 | controller | Tuesday 17 March 2026  14:47:03 +0000 (0:00:00.282)       0:00:12.130 *********
2026-03-17 14:47:03.270692 | controller | skipping: [instance]
2026-03-17 14:47:03.270722 | controller |
2026-03-17 14:47:03.270733 | controller | TASK [libvirt_manager : Ensure the storage pool is started] ********************
2026-03-17 14:47:03.270853 | controller | Tuesday 17 March 2026  14:47:03 +0000 (0:00:00.077)       0:00:12.208 *********
2026-03-17 14:47:03.380634 | controller | skipping: [instance] => (item=pool-start)
2026-03-17 14:47:03.380703 | controller | skipping: [instance] => (item=pool-autostart)
2026-03-17 14:47:03.380713 | controller | skipping: [instance]
2026-03-17 14:47:03.380722 | controller |
2026-03-17 14:47:03.380890 | controller | TASK [libvirt_manager : Gather all the volumes in the pool to delete] **********
2026-03-17 14:47:03.462103 | controller | Tuesday 17 March 2026  14:47:03 +0000 (0:00:00.109)       0:00:12.318 *********
2026-03-17 14:47:03.462155 | controller | skipping: [instance]
2026-03-17 14:47:03.462345 | controller |
2026-03-17 14:47:03.462508 | controller | TASK [libvirt_manager : Remove the volumes from pool to delete] ****************
2026-03-17 14:47:03.462682 | controller | Tuesday 17 March 2026  14:47:03 +0000 (0:00:00.081)       0:00:12.400 *********
2026-03-17 14:47:03.540876 | controller | skipping: [instance]
2026-03-17 14:47:03.541392 | controller |
2026-03-17 14:47:03.541594 | controller | TASK [libvirt_manager : Delete storage pool] ***********************************
2026-03-17 14:47:03.541745 | controller | Tuesday 17 March 2026  14:47:03 +0000 (0:00:00.078)       0:00:12.478 *********
2026-03-17 14:47:03.674233 | controller | skipping: [instance] => (item=pool-destroy)
2026-03-17 14:47:03.674458 | controller | skipping: [instance] => (item=pool-undefine)
2026-03-17 14:47:03.674658 | controller | skipping: [instance]
2026-03-17 14:47:03.674817 | controller |
2026-03-17 14:47:03.674956 | controller | TASK [libvirt_manager : Refresh pools facts] ***********************************
2026-03-17 14:47:03.675091 | controller | Tuesday 17 March 2026  14:47:03 +0000 (0:00:00.133)       0:00:12.612 *********
2026-03-17 14:47:03.958020 | controller | ok: [instance]
2026-03-17 14:47:04.005641 | controller |
2026-03-17 14:47:04.005676 | controller | TASK [libvirt_manager : Remove overlay images from ocp_volume pools if exists] ***
2026-03-17 14:47:04.005692 | controller | Tuesday 17 March 2026  14:47:03 +0000 (0:00:00.283)       0:00:12.895 *********
2026-03-17 14:47:04.005703 | controller | skipping: [instance]
2026-03-17 14:47:04.005729 | controller |
2026-03-17 14:47:04.005737 | controller | TASK [libvirt_manager : Remove ocp_volumes storage pool] ***********************
2026-03-17 14:47:04.005757 | controller | Tuesday 17 March 2026  14:47:04 +0000 (0:00:00.047)       0:00:12.943 *********
2026-03-17 14:47:04.114670 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/storage_pool.yml for instance
2026-03-17 14:47:04.114729 | controller |
2026-03-17 14:47:04.114741 | controller | TASK [libvirt_manager : Ensure we have a known action] *************************
2026-03-17 14:47:04.114803 | controller | Tuesday 17 March 2026  14:47:04 +0000 (0:00:00.108)       0:00:13.052 *********
2026-03-17 14:47:04.188222 | controller | ok: [instance] => changed=false
2026-03-17 14:47:04.451539 | controller |   msg: All assertions passed
2026-03-17 14:47:04.451575 | controller |
2026-03-17 14:47:04.451583 | controller | TASK [libvirt_manager : Gather the status of cifmw-pool storage pool.] *********
2026-03-17 14:47:04.451589 | controller | Tuesday 17 March 2026  14:47:04 +0000 (0:00:00.073)       0:00:13.126 *********
2026-03-17 14:47:04.451599 | controller | changed: [instance]
2026-03-17 14:47:04.527635 | controller |
2026-03-17 14:47:04.527672 | controller | TASK [libvirt_manager : Create the storage pool] *******************************
2026-03-17 14:47:04.527681 | controller | Tuesday 17 March 2026  14:47:04 +0000 (0:00:00.263)       0:00:13.389 *********
2026-03-17 14:47:04.527693 | controller | skipping: [instance]
2026-03-17 14:47:04.527720 | controller |
2026-03-17 14:47:04.527729 | controller | TASK [libvirt_manager : Ensure the storage pool is started] ********************
2026-03-17 14:47:04.527752 | controller | Tuesday 17 March 2026  14:47:04 +0000 (0:00:00.076)       0:00:13.465 *********
2026-03-17 14:47:04.665440 | controller | skipping: [instance] => (item=pool-start)
2026-03-17 14:47:04.771666 | controller | skipping: [instance] => (item=pool-autostart)
2026-03-17 14:47:04.771711 | controller | skipping: [instance]
2026-03-17 14:47:04.771722 | controller |
2026-03-17 14:47:04.771730 | controller | TASK [libvirt_manager : Gather all the volumes in the pool to delete] **********
2026-03-17 14:47:04.771737 | controller | Tuesday 17 March 2026  14:47:04 +0000 (0:00:00.137)       0:00:13.603 *********
2026-03-17 14:47:04.771749 | controller | skipping: [instance]
2026-03-17 14:47:04.771787 | controller |
2026-03-17 14:47:04.771796 | controller | TASK [libvirt_manager : Remove the volumes from pool to delete] ****************
2026-03-17 14:47:04.771806 | controller | Tuesday 17 March 2026  14:47:04 +0000 (0:00:00.106)       0:00:13.709 *********
2026-03-17 14:47:04.847074 | controller | skipping: [instance]
2026-03-17 14:47:04.847207 | controller |
2026-03-17 14:47:04.847329 | controller | TASK [libvirt_manager : Delete storage pool] ***********************************
2026-03-17 14:47:04.847444 | controller | Tuesday 17 March 2026  14:47:04 +0000 (0:00:00.075)       0:00:13.785 *********
2026-03-17 14:47:04.945627 | controller | skipping: [instance] => (item=pool-destroy)
2026-03-17 14:47:04.945784 | controller | skipping: [instance] => (item=pool-undefine)
2026-03-17 14:47:04.945888 | controller | skipping: [instance]
2026-03-17 14:47:04.946044 | controller |
2026-03-17 14:47:04.946144 | controller | TASK [libvirt_manager : Refresh pools facts] ***********************************
2026-03-17 14:47:04.946284 | controller | Tuesday 17 March 2026  14:47:04 +0000 (0:00:00.098)       0:00:13.883 *********
2026-03-17 14:47:05.214250 | controller | ok: [instance]
2026-03-17 14:47:05.260141 | controller |
2026-03-17 14:47:05.260179 | controller | TASK [libvirt_manager : Refresh all pools after actions] ***********************
2026-03-17 14:47:05.260209 | controller | Tuesday 17 March 2026  14:47:05 +0000 (0:00:00.268)       0:00:14.152 *********
2026-03-17 14:47:05.260224 | controller | skipping: [instance]
2026-03-17 14:47:05.567319 | controller |
2026-03-17 14:47:05.567357 | controller | TASK [libvirt_manager : Get temporary key status] ******************************
2026-03-17 14:47:05.567367 | controller | Tuesday 17 March 2026  14:47:05 +0000 (0:00:00.045)       0:00:14.197 *********
2026-03-17 14:47:05.567379 | controller | ok: [instance]
2026-03-17 14:47:05.602368 | controller |
2026-03-17 14:47:05.602388 | controller | TASK [libvirt_manager : Get public key] ****************************************
2026-03-17 14:47:05.602397 | controller | Tuesday 17 March 2026  14:47:05 +0000 (0:00:00.307)       0:00:14.504 *********
2026-03-17 14:47:05.602407 | controller | skipping: [instance]
2026-03-17 14:47:05.635671 | controller |
2026-03-17 14:47:05.635711 | controller | TASK [libvirt_manager : Remove public key] *************************************
2026-03-17 14:47:05.635723 | controller | Tuesday 17 March 2026  14:47:05 +0000 (0:00:00.035)       0:00:14.540 *********
2026-03-17 14:47:05.635737 | controller | skipping: [instance]
2026-03-17 14:47:05.635770 | controller |
2026-03-17 14:47:05.635781 | controller | TASK [libvirt_manager : Remove keypair] ****************************************
2026-03-17 14:47:05.635868 | controller | Tuesday 17 March 2026  14:47:05 +0000 (0:00:00.033)       0:00:14.573 *********
2026-03-17 14:47:05.675784 | controller | skipping: [instance] => (item=cifmw_reproducer_key.pub)
2026-03-17 14:47:05.899688 | controller | skipping: [instance] => (item=cifmw_reproducer_key)
2026-03-17 14:47:05.899732 | controller | skipping: [instance]
2026-03-17 14:47:05.899741 | controller |
2026-03-17 14:47:05.899747 | controller | TASK [libvirt_manager : List libvirt logs] *************************************
2026-03-17 14:47:05.899754 | controller | Tuesday 17 March 2026  14:47:05 +0000 (0:00:00.039)       0:00:14.613 *********
2026-03-17 14:47:05.899770 | controller | ok: [instance]
2026-03-17 14:47:05.899858 | controller |
2026-03-17 14:47:05.899891 | controller | TASK [libvirt_manager : Remove detected logs] **********************************
2026-03-17 14:47:05.899911 | controller | Tuesday 17 March 2026  14:47:05 +0000 (0:00:00.223)       0:00:14.837 *********
2026-03-17 14:47:05.933236 | controller | skipping: [instance]
2026-03-17 14:47:07.208629 | controller |
2026-03-17 14:47:07.208665 | controller | TASK [libvirt_manager : Remove workload and volumes] ***************************
2026-03-17 14:47:07.208674 | controller | Tuesday 17 March 2026  14:47:05 +0000 (0:00:00.033)       0:00:14.870 *********
2026-03-17 14:47:07.208686 | controller | changed: [instance] => (item=workload)
2026-03-17 14:47:07.208722 | controller | changed: [instance] => (item=volumes)
2026-03-17 14:47:07.208731 | controller | ok: [instance] => (item=artifacts/debug_network_data.yml)
2026-03-17 14:47:07.208738 | controller | ok: [instance] => (item=artifacts/debug_pub_net_value.txt)
2026-03-17 14:47:07.208744 | controller | ok: [instance] => (item=artifacts/debug_cifmw_libvirt_manager_layout.yml)
2026-03-17 14:47:07.208751 | controller | changed: [instance] => (item=artifacts/interfaces-info.yml)
2026-03-17 14:47:07.208757 | controller | ok: [instance] => (item=artifacts/libvirt-uuids.yml)
2026-03-17 14:47:07.208763 | controller | ok: [instance] => (item=artifacts/net-map-def-patch.yml)
2026-03-17 14:47:07.208770 | controller |
2026-03-17 14:47:07.208776 | controller | TASK [libvirt_manager : Deepscrub data] ****************************************
2026-03-17 14:47:07.208785 | controller | Tuesday 17 March 2026  14:47:07 +0000 (0:00:01.274)       0:00:16.145 *********
2026-03-17 14:47:07.574413 | controller | ok: [instance] => (item=images)
2026-03-17 14:47:12.013918 | controller | ok: [instance] => (item=artifacts/virtual-nodes.yml)
2026-03-17 14:47:12.013948 | controller |
2026-03-17 14:47:12.013956 | controller | TASK [podman : Ensure podman is installed] *************************************
2026-03-17 14:47:12.013963 | controller | Tuesday 17 March 2026  14:47:07 +0000 (0:00:00.366)       0:00:16.511 *********
2026-03-17 14:47:12.013972 | controller | ok: [instance]
2026-03-17 14:47:12.258317 | controller |
2026-03-17 14:47:12.258346 | controller | TASK [podman : Enable loginctl linger for ansible_user_id] *********************
2026-03-17 14:47:12.258355 | controller | Tuesday 17 March 2026  14:47:12 +0000 (0:00:04.439)       0:00:20.951 *********
2026-03-17 14:47:12.258365 | controller | changed: [instance]
2026-03-17 14:47:12.318643 | controller |
2026-03-17 14:47:12.318682 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul] *******************
2026-03-17 14:47:12.318693 | controller | Tuesday 17 March 2026  14:47:12 +0000 (0:00:00.244)       0:00:21.196 *********
2026-03-17 14:47:12.318705 | controller | skipping: [instance]
2026-03-17 14:47:12.318738 | controller |
2026-03-17 14:47:12.318766 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul] *******************
2026-03-17 14:47:12.318930 | controller | Tuesday 17 March 2026  14:47:12 +0000 (0:00:00.060)       0:00:21.256 *********
2026-03-17 14:47:12.358285 | controller | skipping: [instance]
2026-03-17 14:47:12.418459 | controller |
2026-03-17 14:47:12.418484 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed] ***
2026-03-17 14:47:12.418491 | controller | Tuesday 17 March 2026  14:47:12 +0000 (0:00:00.039)       0:00:21.296 *********
2026-03-17 14:47:12.418500 | controller | skipping: [instance]
2026-03-17 14:47:12.860293 | controller |
2026-03-17 14:47:12.860324 | controller | TASK [virtualbmc : Stop and remove vbmc container] *****************************
2026-03-17 14:47:12.860332 | controller | Tuesday 17 March 2026  14:47:12 +0000 (0:00:00.060)       0:00:21.356 *********
2026-03-17 14:47:12.860342 | controller | ok: [instance]
2026-03-17 14:47:13.273590 | controller |
2026-03-17 14:47:13.273636 | controller | TASK [virtualbmc : Remove vbmc container image] ********************************
2026-03-17 14:47:13.273645 | controller | Tuesday 17 March 2026  14:47:12 +0000 (0:00:00.441)       0:00:21.798 *********
2026-03-17 14:47:13.273655 | controller | ok: [instance]
2026-03-17 14:47:13.273674 | controller |
2026-03-17 14:47:13.274049 | controller | TASK [virtualbmc : Check if VBMC key exists] ***********************************
2026-03-17 14:47:13.459456 | controller | Tuesday 17 March 2026  14:47:13 +0000 (0:00:00.413)       0:00:22.211 *********
2026-03-17 14:47:13.459494 | controller | ok: [instance]
2026-03-17 14:47:13.494289 | controller |
2026-03-17 14:47:13.494314 | controller | TASK [virtualbmc : Slurp key] **************************************************
2026-03-17 14:47:13.494324 | controller | Tuesday 17 March 2026  14:47:13 +0000 (0:00:00.185)       0:00:22.397 *********
2026-03-17 14:47:13.494336 | controller | skipping: [instance]
2026-03-17 14:47:13.528182 | controller |
2026-03-17 14:47:13.528219 | controller | TASK [virtualbmc : Revoke VBMC SSH access] *************************************
2026-03-17 14:47:13.528230 | controller | Tuesday 17 March 2026  14:47:13 +0000 (0:00:00.034)       0:00:22.432 *********
2026-03-17 14:47:13.528242 | controller | skipping: [instance]
2026-03-17 14:47:14.239651 | controller |
2026-03-17 14:47:14.239685 | controller | TASK [virtualbmc : Remove vbmc files] ******************************************
2026-03-17 14:47:14.239696 | controller | Tuesday 17 March 2026  14:47:13 +0000 (0:00:00.034)       0:00:22.466 *********
2026-03-17 14:47:14.239716 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmc-key)
2026-03-17 14:47:14.239746 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmc-key.pub)
2026-03-17 14:47:14.239754 | controller | ok: [instance] => (item=/home/zuul/.ssh/virtualbmc.conf)
2026-03-17 14:47:14.239761 | controller | ok: [instance] => (item=/home/zuul/.ssh/vbmcd.sh)
2026-03-17 14:47:14.239770 | controller |
2026-03-17 14:47:14.239796 | controller | TASK [Clean remote ssh config] *************************************************
2026-03-17 14:47:14.239985 | controller | Tuesday 17 March 2026  14:47:14 +0000 (0:00:00.711)       0:00:23.177 *********
2026-03-17 14:47:14.321230 | controller |
2026-03-17 14:47:14.624141 | controller | TASK [ssh_jumper : Remove Include cifmw_ssh_config.d] **************************
2026-03-17 14:47:14.624172 | controller | Tuesday 17 March 2026  14:47:14 +0000 (0:00:00.082)       0:00:23.259 *********
2026-03-17 14:47:14.624187 | controller | ok: [instance]
2026-03-17 14:47:14.848622 | controller |
2026-03-17 14:47:14.848653 | controller | TASK [ssh_jumper : Remove cifmw_ssh_config.d directory] ************************
2026-03-17 14:47:14.848661 | controller | Tuesday 17 March 2026  14:47:14 +0000 (0:00:00.302)       0:00:23.561 *********
2026-03-17 14:47:14.848670 | controller | ok: [instance]
2026-03-17 14:47:14.950968 | controller |
2026-03-17 14:47:14.951000 | controller | TASK [Clean local ssh config] **************************************************
2026-03-17 14:47:14.951010 | controller | Tuesday 17 March 2026  14:47:14 +0000 (0:00:00.224)       0:00:23.786 *********
2026-03-17 14:47:14.951021 | controller |
2026-03-17 14:47:15.152367 | controller | TASK [ssh_jumper : Remove Include cifmw_ssh_config.d] **************************
2026-03-17 14:47:15.152404 | controller | Tuesday 17 March 2026  14:47:14 +0000 (0:00:00.102)       0:00:23.888 *********
2026-03-17 14:47:15.152420 | controller | ok: [instance -> localhost]
2026-03-17 14:47:15.375627 | controller |
2026-03-17 14:47:15.375666 | controller | TASK [ssh_jumper : Remove cifmw_ssh_config.d directory] ************************
2026-03-17 14:47:15.375678 | controller | Tuesday 17 March 2026  14:47:15 +0000 (0:00:00.201)       0:00:24.090 *********
2026-03-17 14:47:15.375690 | controller | ok: [instance -> localhost]
2026-03-17 14:47:15.375723 | controller |
2026-03-17 14:47:15.375753 | controller | TASK [dnsmasq : Cleaning dnsmasq] **********************************************
2026-03-17 14:47:15.375909 | controller | Tuesday 17 March 2026  14:47:15 +0000 (0:00:00.223)       0:00:24.313 *********
2026-03-17 14:47:15.444033 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/configure.yml for instance
2026-03-17 14:47:16.705609 | controller |
2026-03-17 14:47:16.705639 | controller | TASK [dnsmasq : Install needed packages] ***************************************
2026-03-17 14:47:16.705647 | controller | Tuesday 17 March 2026  14:47:15 +0000 (0:00:00.068)       0:00:24.382 *********
2026-03-17 14:47:16.705657 | controller | ok: [instance]
2026-03-17 14:47:17.900978 | controller |
2026-03-17 14:47:17.901009 | controller | TASK [dnsmasq : Ensure SELinux knows about the new files and directories] ******
2026-03-17 14:47:17.901016 | controller | Tuesday 17 March 2026  14:47:16 +0000 (0:00:01.261)       0:00:25.643 *********
2026-03-17 14:47:17.901026 | controller | ok: [instance] => (item={'target': '/etc/cifmw-dnsmasq.conf', 'setype': 'dnsmasq_etc_t'})
2026-03-17 14:47:18.110619 | controller | ok: [instance] => (item={'target': '/etc/cifmw-dnsmasq.d(/.*)?', 'setype': 'dnsmasq_etc_t'})
2026-03-17 14:47:18.110657 | controller | ok: [instance] => (item={'target': '/var/run/cifmw-dnsmasq.pid', 'setype': 'dnsmasq_var_run_t'})
2026-03-17 14:47:18.110666 | controller |
2026-03-17 14:47:18.110674 | controller | TASK [dnsmasq : Manage configuration directory] ********************************
2026-03-17 14:47:18.110681 | controller | Tuesday 17 March 2026  14:47:17 +0000 (0:00:01.195)       0:00:26.838 *********
2026-03-17 14:47:18.110692 | controller | ok: [instance]
2026-03-17 14:47:18.110720 | controller |
2026-03-17 14:47:18.110730 | controller | TASK [dnsmasq : Manage systemd unit file] **************************************
2026-03-17 14:47:18.110756 | controller | Tuesday 17 March 2026  14:47:18 +0000 (0:00:00.209)       0:00:27.048 *********
2026-03-17 14:47:18.146728 | controller | skipping: [instance]
2026-03-17 14:47:18.180524 | controller |
2026-03-17 14:47:18.180549 | controller | TASK [dnsmasq : Manage base configuration file] ********************************
2026-03-17 14:47:18.180556 | controller | Tuesday 17 March 2026  14:47:18 +0000 (0:00:00.035)       0:00:27.084 *********
2026-03-17 14:47:18.180566 | controller | skipping: [instance]
2026-03-17 14:47:18.236339 | controller |
2026-03-17 14:47:18.236374 | controller | TASK [dnsmasq : Render listener configuration] *********************************
2026-03-17 14:47:18.236387 | controller | Tuesday 17 March 2026  14:47:18 +0000 (0:00:00.033)       0:00:27.118 *********
2026-03-17 14:47:18.236403 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/listener.yml for instance
2026-03-17 14:47:18.269548 | controller |
2026-03-17 14:47:18.269579 | controller | TASK [dnsmasq : Create listener configuration file] ****************************
2026-03-17 14:47:18.269592 | controller | Tuesday 17 March 2026  14:47:18 +0000 (0:00:00.055)       0:00:27.173 *********
2026-03-17 14:47:18.269607 | controller | skipping: [instance]
2026-03-17 14:47:18.269848 | controller |
2026-03-17 14:47:18.270083 | controller | TASK [dnsmasq : Remove listener configuration file] ****************************
2026-03-17 14:47:18.270323 | controller | Tuesday 17 March 2026  14:47:18 +0000 (0:00:00.033)       0:00:27.207 *********
2026-03-17 14:47:18.506696 | controller | ok: [instance]
2026-03-17 14:47:18.506759 | controller |
2026-03-17 14:47:18.506799 | controller | TASK [dnsmasq : Render dns configuration] **************************************
2026-03-17 14:47:18.506949 | controller | Tuesday 17 March 2026  14:47:18 +0000 (0:00:00.236)       0:00:27.444 *********
2026-03-17 14:47:18.576277 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/dns.yml for instance
2026-03-17 14:47:18.612562 | controller |
2026-03-17 14:47:18.612581 | controller | TASK [dnsmasq : Create dns configuration file] *********************************
2026-03-17 14:47:18.612589 | controller | Tuesday 17 March 2026  14:47:18 +0000 (0:00:00.069)       0:00:27.514 *********
2026-03-17 14:47:18.612598 | controller | skipping: [instance]
2026-03-17 14:47:18.818722 | controller |
2026-03-17 14:47:18.818754 | controller | TASK [dnsmasq : Remove dns configuration file] *********************************
2026-03-17 14:47:18.818762 | controller | Tuesday 17 March 2026  14:47:18 +0000 (0:00:00.036)       0:00:27.550 *********
2026-03-17 14:47:18.818773 | controller | ok: [instance]
2026-03-17 14:47:18.853580 | controller |
2026-03-17 14:47:18.853599 | controller | TASK [dnsmasq : Add localhost addresses from defined dnsmasq listen addresses to loopback interface] ***
2026-03-17 14:47:18.853607 | controller | Tuesday 17 March 2026  14:47:18 +0000 (0:00:00.205)       0:00:27.756 *********
2026-03-17 14:47:18.853615 | controller | skipping: [instance]
2026-03-17 14:47:18.853851 | controller |
2026-03-17 14:47:18.853865 | controller | TASK [dnsmasq : Enable and start service] **************************************
2026-03-17 14:47:18.890686 | controller | Tuesday 17 March 2026  14:47:18 +0000 (0:00:00.035)       0:00:27.791 *********
2026-03-17 14:47:18.890738 | controller | skipping: [instance]
2026-03-17 14:47:19.192309 | controller |
2026-03-17 14:47:19.192333 | controller | TASK [dnsmasq : Stop service] **************************************************
2026-03-17 14:47:19.192341 | controller | Tuesday 17 March 2026  14:47:18 +0000 (0:00:00.036)       0:00:27.828 *********
2026-03-17 14:47:19.192351 | controller | ok: [instance]
2026-03-17 14:47:19.398607 | controller |
2026-03-17 14:47:19.398643 | controller | TASK [dnsmasq : Remove unit file] **********************************************
2026-03-17 14:47:19.398652 | controller | Tuesday 17 March 2026  14:47:19 +0000 (0:00:00.301)       0:00:28.130 *********
2026-03-17 14:47:19.398663 | controller | ok: [instance]
2026-03-17 14:47:19.787023 | controller |
2026-03-17 14:47:19.787066 | controller | TASK [dnsmasq : Remove main configuration files] *******************************
2026-03-17 14:47:19.787075 | controller | Tuesday 17 March 2026  14:47:19 +0000 (0:00:00.206)       0:00:28.336 *********
2026-03-17 14:47:19.787086 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2026-03-17 14:47:19.787106 | controller | ok: [instance] => (item=/etc/cifmw-dnsmasq.d)
2026-03-17 14:47:19.787160 | controller |
2026-03-17 14:47:19.787400 | controller | TASK [dnsmasq : Remove lease file] *********************************************
2026-03-17 14:47:20.025364 | controller | Tuesday 17 March 2026  14:47:19 +0000 (0:00:00.388)       0:00:28.725 *********
2026-03-17 14:47:20.025399 | controller | ok: [instance]
2026-03-17 14:47:20.613528 | controller |
2026-03-17 14:47:20.613559 | controller | TASK [radvd : Gather package facts] ********************************************
2026-03-17 14:47:20.613567 | controller | Tuesday 17 March 2026  14:47:20 +0000 (0:00:00.237)       0:00:28.963 *********
2026-03-17 14:47:20.613577 | controller | ok: [instance]
2026-03-17 14:47:20.659100 | controller |
2026-03-17 14:47:20.659123 | controller | TASK [radvd : Stop and disable radvd service] **********************************
2026-03-17 14:47:20.659130 | controller | Tuesday 17 March 2026  14:47:20 +0000 (0:00:00.588)       0:00:29.551 *********
2026-03-17 14:47:20.659140 | controller | skipping: [instance]
2026-03-17 14:47:20.893275 | controller |
2026-03-17 14:47:20.893308 | controller | TASK [radvd : Remove radvd configuration file] *********************************
2026-03-17 14:47:20.893318 | controller | Tuesday 17 March 2026  14:47:20 +0000 (0:00:00.045)       0:00:29.597 *********
2026-03-17 14:47:20.893329 | controller | ok: [instance]
2026-03-17 14:47:21.105628 | controller |
2026-03-17 14:47:21.105669 | controller | TASK [radvd : Remove configuration directory] **********************************
2026-03-17 14:47:21.105680 | controller | Tuesday 17 March 2026  14:47:20 +0000 (0:00:00.234)       0:00:29.831 *********
2026-03-17 14:47:21.105694 | controller | ok: [instance]
2026-03-17 14:47:21.105731 | controller |
2026-03-17 14:47:21.105742 | controller | TASK [radvd : Remove radvd package] ********************************************
2026-03-17 14:47:21.182630 | controller | Tuesday 17 March 2026  14:47:21 +0000 (0:00:00.212)       0:00:30.043 *********
2026-03-17 14:47:21.182678 | controller | skipping: [instance]
2026-03-17 14:47:21.182714 | controller |
2026-03-17 14:47:21.182726 | controller | TASK [Ensure work directory is removed] ****************************************
2026-03-17 14:47:21.182800 | controller | Tuesday 17 March 2026  14:47:21 +0000 (0:00:00.077)       0:00:30.120 *********
2026-03-17 14:47:21.520600 | controller | changed: [instance]
2026-03-17 14:47:22.179612 | controller |
2026-03-17 14:47:22.179646 | controller | RUNNING HANDLER [libvirt_manager : Restart firewalld] **************************
2026-03-17 14:47:22.179662 | controller | Tuesday 17 March 2026  14:47:21 +0000 (0:00:00.337)       0:00:30.458 *********
2026-03-17 14:47:22.179673 | controller | changed: [instance]
2026-03-17 14:47:22.180688 | controller |
2026-03-17 14:47:22.180762 | controller | PLAY RECAP *********************************************************************
2026-03-17 14:47:22.180775 | controller | instance                   : ok=59   changed=12   unreachable=0    failed=0    skipped=40   rescued=0    ignored=0
2026-03-17 14:47:22.180784 | controller |
2026-03-17 14:47:22.180792 | controller | Tuesday 17 March 2026  14:47:22 +0000 (0:00:00.659)       0:00:31.117 *********
2026-03-17 14:47:22.180804 | controller | ===============================================================================
2026-03-17 14:47:22.180812 | controller | podman : Ensure podman is installed ------------------------------------- 4.44s
2026-03-17 14:47:22.180819 | controller | libvirt_manager : Populate service facts -------------------------------- 1.96s
2026-03-17 14:47:22.180826 | controller | Copy generated content to proper location ------------------------------- 1.31s
2026-03-17 14:47:22.180834 | controller | libvirt_manager : Remove workload and volumes --------------------------- 1.27s
2026-03-17 14:47:22.180841 | controller | dnsmasq : Install needed packages --------------------------------------- 1.26s
2026-03-17 14:47:22.180851 | controller | dnsmasq : Ensure SELinux knows about the new files and directories ------ 1.20s
2026-03-17 14:47:22.180879 | controller | libvirt_manager : Start virtstoraged socket service --------------------- 0.90s
2026-03-17 14:47:22.180890 | controller | libvirt_manager : Start virtnetworkd socket service --------------------- 0.87s
2026-03-17 14:47:22.181570 | controller | Gathering Facts --------------------------------------------------------- 0.84s
2026-03-17 14:47:22.181596 | controller | libvirt_manager : Start virtqemud socket service ------------------------ 0.82s
2026-03-17 14:47:22.181603 | controller | libvirt_manager : Get installed packages list --------------------------- 0.73s
2026-03-17 14:47:22.181608 | controller | virtualbmc : Remove vbmc files ------------------------------------------ 0.71s
2026-03-17 14:47:22.181613 | controller | libvirt_manager : Restart firewalld ------------------------------------- 0.66s
2026-03-17 14:47:22.181618 | controller | radvd : Gather package facts -------------------------------------------- 0.59s
2026-03-17 14:47:22.181623 | controller | libvirt_manager : Disable forward in firewalld libvirt zone ------------- 0.57s
2026-03-17 14:47:22.181628 | controller | libvirt_manager : Ensure firewalld is enabled and started --------------- 0.56s
2026-03-17 14:47:22.181634 | controller | libvirt_manager : Disable masquerade in firewalld default zone ---------- 0.51s
2026-03-17 14:47:22.181891 | controller | virtualbmc : Stop and remove vbmc container ----------------------------- 0.44s
2026-03-17 14:47:22.239679 | controller | virtualbmc : Remove vbmc container image -------------------------------- 0.41s
2026-03-17 14:47:22.239713 | controller | libvirt_manager : List all of the existing virtual machines ------------- 0.39s
2026-03-17 14:47:22.239725 | controller | INFO     Running boot_order > destroy
2026-03-17 14:47:22.240073 | controller | WARNING  Skipping, instances are delegated.
2026-03-17 14:47:22.240419 | controller | INFO     Pruning extra files from scenario ephemeral directory
2026-03-17 14:47:22.241968 | controller | INFO     Writing /tmp/report.html report.
2026-03-17 14:47:22.697075 | [controller] Waiting on logger
2026-03-17 14:47:27.862446 | [controller] Waiting on logger
2026-03-17 14:47:38.293880 | [controller] Waiting on logger
2026-03-17 14:47:48.725743 | [controller] Waiting on logger
2026-03-17 14:47:52.320748 | [Zuul] Log Stream did not terminate
2026-03-17 14:47:52.321042 | controller | ERROR
2026-03-17 14:47:52.321147 | controller | {
2026-03-17 14:47:52.321182 | controller |   "delta": "0:04:01.619990",
2026-03-17 14:47:52.321206 | controller |   "end": "2026-03-17 14:47:22.291455",
2026-03-17 14:47:52.321232 | controller |   "msg": "non-zero return code",
2026-03-17 14:47:52.321247 | controller |   "rc": 1,
2026-03-17 14:47:52.321280 | controller |   "start": "2026-03-17 14:43:20.671465"
2026-03-17 14:47:52.321304 | controller | }
failure
2026-03-17 14:47:52.367737 | 
2026-03-17 14:47:52.367784 | PLAY RECAP
2026-03-17 14:47:52.367826 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-03-17 14:47:52.367848 | 
2026-03-17 14:47:52.423379 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-03-17 14:47:52.424106 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-17 14:47:52.919063 | 
2026-03-17 14:47:52.919171 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-03-17 14:47:52.938329 | 
2026-03-17 14:47:52.938409 | TASK [Filter out host if needed]
2026-03-17 14:47:52.946678 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-03-17 14:47:52.950745 | 
2026-03-17 14:47:52.950812 | TASK [Ensure file is present]
2026-03-17 14:47:53.232989 | controller | ok
2026-03-17 14:47:53.239906 | 
2026-03-17 14:47:53.239977 | TASK [Manage molecule report file]
2026-03-17 14:47:53.260854 | [controller] Waiting on logger
2026-03-17 14:48:03.702083 | [controller] Waiting on logger
2026-03-17 14:48:13.046207 | [controller] Waiting on logger
2026-03-17 14:48:23.477771 | [controller] Waiting on logger
2026-03-17 14:48:23.552948 | [Zuul] Log Stream did not terminate
2026-03-17 14:48:23.553186 | controller | changed
2026-03-17 14:48:23.558491 | 
2026-03-17 14:48:23.558551 | TASK [Check if we get ci-framework-data basedir]
2026-03-17 14:48:23.729617 | controller | ok
2026-03-17 14:48:23.734568 | 
2026-03-17 14:48:23.734638 | TASK [Create ci-framework-data log directory for zuul]
2026-03-17 14:48:24.015751 | controller | changed
2026-03-17 14:48:24.020992 | 
2026-03-17 14:48:24.021057 | TASK [Copy ci-framework interesting files]
2026-03-17 14:48:24.041722 | [controller] Waiting on logger
2026-03-17 14:48:34.485952 | [controller] Waiting on logger
2026-03-17 14:48:43.829869 | [controller] Waiting on logger
2026-03-17 14:48:54.220917 | [Zuul] Log Stream did not terminate
2026-03-17 14:48:54.221113 | controller | changed
2026-03-17 14:48:54.225922 | 
2026-03-17 14:48:54.225983 | TASK [Get SELinux listing]
2026-03-17 14:48:54.244312 | [controller] Waiting on logger
2026-03-17 14:48:56.309753 | [controller] Waiting on logger
2026-03-17 14:49:06.677791 | [controller] Waiting on logger
2026-03-17 14:49:16.021954 | [controller] Waiting on logger
2026-03-17 14:49:24.433793 | [Zuul] Log Stream did not terminate
2026-03-17 14:49:24.433995 | controller | changed
2026-03-17 14:49:24.439549 | 
2026-03-17 14:49:24.439626 | TASK [Generate log index]
2026-03-17 14:49:25.016198 | controller | changed
2026-03-17 14:49:25.020844 | 
2026-03-17 14:49:25.020905 | TASK [Get some env related data]
2026-03-17 14:49:25.040679 | [controller] Waiting on logger
2026-03-17 14:49:35.477869 | [controller] Waiting on logger
2026-03-17 14:49:44.821918 | [controller] Waiting on logger
2026-03-17 14:49:55.253697 | [controller] Waiting on logger
2026-03-17 14:49:56.086706 | [Zuul] Log Stream did not terminate
2026-03-17 14:49:56.086970 | controller | changed
2026-03-17 14:49:56.091707 | 
2026-03-17 14:49:56.091761 | TASK [Generate list of logs to collect in home directory]
2026-03-17 14:49:56.353479 | controller | ok: All paths examined
2026-03-17 14:49:56.358600 | 
2026-03-17 14:49:56.358681 | LOOP [Copy logs from home directory]
2026-03-17 14:49:56.647242 | controller | changed:
2026-03-17 14:49:56.647369 | controller | {
2026-03-17 14:49:56.647395 | controller |   "atime": 1773758581.1473773,
2026-03-17 14:49:56.647414 | controller |   "ctime": 1773758598.578557,
2026-03-17 14:49:56.647431 | controller |   "dev": 64513,
2026-03-17 14:49:56.647447 | controller |   "gid": 1000,
2026-03-17 14:49:56.647461 | controller |   "gr_name": "zuul",
2026-03-17 14:49:56.647475 | controller |   "inode": 1172225,
2026-03-17 14:49:56.647490 | controller |   "isblk": false,
2026-03-17 14:49:56.647503 | controller |   "ischr": false,
2026-03-17 14:49:56.647517 | controller |   "isdir": false,
2026-03-17 14:49:56.647530 | controller |   "isfifo": false,
2026-03-17 14:49:56.647546 | controller |   "isgid": false,
2026-03-17 14:49:56.647561 | controller |   "islnk": false,
2026-03-17 14:49:56.647575 | controller |   "isreg": true,
2026-03-17 14:49:56.647588 | controller |   "issock": false,
2026-03-17 14:49:56.647600 | controller |   "isuid": false,
2026-03-17 14:49:56.647624 | controller |   "mode": "0644",
2026-03-17 14:49:56.647637 | controller |   "mtime": 1773758598.578557,
2026-03-17 14:49:56.647650 | controller |   "nlink": 1,
2026-03-17 14:49:56.647664 | controller |   "path": "/home/zuul/ansible.log",
2026-03-17 14:49:56.647677 | controller |   "pw_name": "zuul",
2026-03-17 14:49:56.647690 | controller |   "rgrp": true,
2026-03-17 14:49:56.647703 | controller |   "roth": true,
2026-03-17 14:49:56.647715 | controller |   "rusr": true,
2026-03-17 14:49:56.647728 | controller |   "size": 6749,
2026-03-17 14:49:56.647740 | controller |   "uid": 1000,
2026-03-17 14:49:56.647753 | controller |   "wgrp": false,
2026-03-17 14:49:56.647766 | controller |   "woth": false,
2026-03-17 14:49:56.647778 | controller |   "wusr": true,
2026-03-17 14:49:56.647791 | controller |   "xgrp": false,
2026-03-17 14:49:56.647804 | controller |   "xoth": false,
2026-03-17 14:49:56.647817 | controller |   "xusr": false
2026-03-17 14:49:56.647829 | controller | }
2026-03-17 14:49:56.660748 | 
2026-03-17 14:49:56.660807 | TASK [Copy crio stats log file]
2026-03-17 14:49:56.674549 | controller | skipping: Conditional result was False
2026-03-17 14:49:56.679242 | 
2026-03-17 14:49:56.679316 | TASK [Get SELinux related data]
2026-03-17 14:49:56.697990 | [controller] Waiting on logger
2026-03-17 14:50:07.093682 | [controller] Waiting on logger
2026-03-17 14:50:17.525796 | [controller] Waiting on logger
2026-03-17 14:50:26.870029 | [controller] Waiting on logger
2026-03-17 14:50:26.887902 | [Zuul] Log Stream did not terminate
2026-03-17 14:50:26.888850 | controller | ERROR
2026-03-17 14:50:26.888963 | controller | {
2026-03-17 14:50:26.888993 | controller |   "delta": "0:00:00.008014",
2026-03-17 14:50:26.889012 | controller |   "end": "2026-03-17 14:49:56.864133",
2026-03-17 14:50:26.889028 | controller |   "msg": "non-zero return code",
2026-03-17 14:50:26.889044 | controller |   "rc": 1,
2026-03-17 14:50:26.889059 | controller |   "start": "2026-03-17 14:49:56.856119"
2026-03-17 14:50:26.889073 | controller | }
2026-03-17 14:50:26.889094 | controller | ERROR: Ignoring Errors
2026-03-17 14:50:26.898556 | 
2026-03-17 14:50:26.898622 | TASK [Create system configuration directory]
2026-03-17 14:50:27.079939 | controller | changed
2026-03-17 14:50:27.084257 | 
2026-03-17 14:50:27.084344 | TASK [Get some of the system configurations]
2026-03-17 14:50:30.197908 | [controller] Waiting on logger
2026-03-17 14:50:46.581869 | [controller] Waiting on logger
2026-03-17 14:50:48.821806 | [controller] Waiting on logger
2026-03-17 14:50:57.302141 | [Zuul] Log Stream did not terminate
2026-03-17 14:50:57.303632 | controller | changed
2026-03-17 14:50:57.308581 | 
2026-03-17 14:50:57.308652 | TASK [Copy generated documentation if available]
2026-03-17 14:50:57.321712 | controller | skipping: Conditional result was False
2026-03-17 14:50:57.326564 | 
2026-03-17 14:50:57.326630 | TASK [Copy generated AsciiDoc documentation if available]
2026-03-17 14:50:57.338879 | controller | skipping: Conditional result was False
2026-03-17 14:50:57.343620 | 
2026-03-17 14:50:57.343676 | TASK [Compress logs bigger than 2MB]
2026-03-17 14:50:57.363934 | [controller] Waiting on logger
2026-03-17 14:51:06.714330 | [controller] Waiting on logger
2026-03-17 14:51:17.109972 | [controller] Waiting on logger
2026-03-17 14:51:27.540587 | [Zuul] Log Stream did not terminate
2026-03-17 14:51:27.540784 | controller | changed
2026-03-17 14:51:27.541776 | [controller] Waiting on logger
2026-03-17 14:51:27.546320 | 
2026-03-17 14:51:27.546399 | TASK [Copy files from workspace on node]
2026-03-17 14:51:27.565080 | controller | ok
2026-03-17 14:51:27.586339 | 
2026-03-17 14:51:27.586406 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-17 14:51:27.599952 | controller | skipping: Conditional result was False
2026-03-17 14:51:27.605551 | 
2026-03-17 14:51:27.605625 | TASK [fetch-output : Set log path for single node]
2026-03-17 14:51:27.633808 | controller | ok
2026-03-17 14:51:27.638539 | 
2026-03-17 14:51:27.638613 | LOOP [fetch-output : Ensure local output dirs]
2026-03-17 14:51:27.823450 | controller -> localhost | ok: "/var/lib/zuul/builds/702116f0d33e48f79477b12cf31ce05b/work/logs"
2026-03-17 14:51:27.823691 | controller -> localhost | changed: All items complete
2026-03-17 14:51:27.823723 | 
2026-03-17 14:51:27.990431 | controller -> localhost | changed: "/var/lib/zuul/builds/702116f0d33e48f79477b12cf31ce05b/work/artifacts"
2026-03-17 14:51:28.148759 | controller -> localhost | changed: "/var/lib/zuul/builds/702116f0d33e48f79477b12cf31ce05b/work/docs"
2026-03-17 14:51:28.156045 | 
2026-03-17 14:51:28.156133 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-17 14:51:28.661285 | controller | changed:
2026-03-17 14:51:28.661450 | controller | .d..t...... ./
2026-03-17 14:51:28.661478 | controller | >f+++++++++ README.html
2026-03-17 14:51:28.661499 | controller | >f+++++++++ ansible-execution.log
2026-03-17 14:51:28.661518 | controller | >f+++++++++ ansible.log
2026-03-17 14:51:28.661536 | controller | >f+++++++++ dmesg.log
2026-03-17 14:51:28.661553 | controller | >f+++++++++ installed-pkgs.log
2026-03-17 14:51:28.661569 | controller | >f+++++++++ python.log
2026-03-17 14:51:28.661585 | controller | >f+++++++++ registries.conf
2026-03-17 14:51:28.661611 | controller | >f+++++++++ report.html
2026-03-17 14:51:28.661631 | controller | >f+++++++++ selinux-denials.log
2026-03-17 14:51:28.661656 | controller | >f+++++++++ selinux-listing.log
2026-03-17 14:51:28.661675 | controller | cd+++++++++ ci-framework-data/
2026-03-17 14:51:28.661692 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-03-17 14:51:28.661709 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-03-17 14:51:28.661727 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/
2026-03-17 14:51:28.661744 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/artifacts/
2026-03-17 14:51:28.661760 | controller | >f+++++++++ ci-framework-data/artifacts/boot_order/artifacts/interfaces-info.yml
2026-03-17 14:51:28.661777 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/artifacts/manifests/
2026-03-17 14:51:28.661793 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/artifacts/manifests/openstack/
2026-03-17 14:51:28.661810 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/artifacts/manifests/openstack/cr/
2026-03-17 14:51:28.661827 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/artifacts/parameters/
2026-03-17 14:51:28.661845 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/artifacts/repositories/
2026-03-17 14:51:28.661862 | controller | >f+++++++++ ci-framework-data/artifacts/boot_order/artifacts/repositories/delorean.repo.md5
2026-03-17 14:51:28.661879 | controller | cd+++++++++ ci-framework-data/artifacts/boot_order/logs/
2026-03-17 14:51:28.661896 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-03-17 14:51:28.661917 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-03-17 14:51:28.661934 | controller | cd+++++++++ ci-framework-data/logs/
2026-03-17 14:51:28.661953 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-03-17 14:51:28.661971 | controller | cd+++++++++ registries.conf.d/
2026-03-17 14:51:28.661988 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-03-17 14:51:28.662005 | controller | cd+++++++++ system-config/
2026-03-17 14:51:28.662022 | controller | cd+++++++++ system-config/libvirt/
2026-03-17 14:51:28.662069 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-03-17 14:51:28.662096 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-03-17 14:51:28.662115 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2026-03-17 14:51:28.662133 | controller | >f+++++++++ system-config/libvirt/network.conf
2026-03-17 14:51:28.662150 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2026-03-17 14:51:28.662167 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2026-03-17 14:51:28.662183 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2026-03-17 14:51:28.662200 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2026-03-17 14:51:28.662216 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2026-03-17 14:51:28.662233 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2026-03-17 14:51:28.662250 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2026-03-17 14:51:28.662302 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2026-03-17 14:51:28.662328 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2026-03-17 14:51:28.662348 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2026-03-17 14:51:28.662366 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2026-03-17 14:51:28.662410 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2026-03-17 14:51:29.006619 | controller | changed: .d..t...... ./
2026-03-17 14:51:29.349036 | controller | changed: .d..t...... ./
2026-03-17 14:51:29.381707 | 
2026-03-17 14:51:29.381805 | TASK [Return artifact to Zuul]
2026-03-17 14:51:29.409655 | controller | ok
2026-03-17 14:51:29.426992 | 
2026-03-17 14:51:29.427042 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-03-17 14:51:29.427138 | 
2026-03-17 14:51:29.427165 | PLAY RECAP
2026-03-17 14:51:29.427210 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-03-17 14:51:29.427230 | 
2026-03-17 14:51:29.498054 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-17 14:51:29.498813 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-17 14:51:29.967663 | 
2026-03-17 14:51:29.967759 | PLAY [all]
2026-03-17 14:51:29.984525 | 
2026-03-17 14:51:29.984595 | TASK [include_role : fetch-output]
2026-03-17 14:51:30.012544 | controller | ok
2026-03-17 14:51:30.027590 | 
2026-03-17 14:51:30.027672 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-17 14:51:30.062207 | controller | skipping: Conditional result was False
2026-03-17 14:51:30.067528 | 
2026-03-17 14:51:30.067597 | TASK [fetch-output : Set log path for single node]
2026-03-17 14:51:30.095681 | controller | ok
2026-03-17 14:51:30.100111 | 
2026-03-17 14:51:30.100175 | LOOP [fetch-output : Ensure local output dirs]
2026-03-17 14:51:30.391434 | controller -> localhost | ok: "/var/lib/zuul/builds/702116f0d33e48f79477b12cf31ce05b/work/logs"
2026-03-17 14:51:30.558088 | controller -> localhost | ok: "/var/lib/zuul/builds/702116f0d33e48f79477b12cf31ce05b/work/artifacts"
2026-03-17 14:51:30.722777 | controller -> localhost | ok: "/var/lib/zuul/builds/702116f0d33e48f79477b12cf31ce05b/work/docs"
2026-03-17 14:51:30.738513 | 
2026-03-17 14:51:30.738617 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-17 14:51:31.213444 | controller | ok
2026-03-17 14:51:31.213658 | controller | ok: All items complete
2026-03-17 14:51:31.213689 | 
2026-03-17 14:51:31.565981 | controller | ok
2026-03-17 14:51:31.913542 | controller | ok
2026-03-17 14:51:31.925797 | 
2026-03-17 14:51:31.925905 | TASK [include_role : fetch-output-openshift]
2026-03-17 14:51:31.939420 | controller | skipping: Conditional result was False
2026-03-17 14:51:31.944851 | 
2026-03-17 14:51:31.944920 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-17 14:51:32.254482 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.005957
2026-03-17 14:51:32.424213 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006183
2026-03-17 14:51:32.456063 | 
2026-03-17 14:51:32.456147 | PLAY [all]
2026-03-17 14:51:32.468757 | 
2026-03-17 14:51:32.468825 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-17 14:51:32.830927 | controller | changed
2026-03-17 14:51:32.853080 | 
2026-03-17 14:51:32.853136 | PLAY RECAP
2026-03-17 14:51:32.853181 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-03-17 14:51:32.853204 | 
2026-03-17 14:51:32.917815 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-17 14:51:32.918539 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-03-17 14:51:33.415302 | 
2026-03-17 14:51:33.415397 | PLAY [localhost]
2026-03-17 14:51:33.431132 | 
2026-03-17 14:51:33.431204 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-17 14:51:33.701254 | localhost | changed
2026-03-17 14:51:33.705438 | 
2026-03-17 14:51:33.705513 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-17 14:51:33.723078 | localhost | ok
2026-03-17 14:51:33.730296 | 
2026-03-17 14:51:33.730359 | TASK [add-fileserver : Create SSH private key tempfile]
2026-03-17 14:51:33.999848 | localhost | changed
2026-03-17 14:51:34.005040 | 
2026-03-17 14:51:34.005104 | TASK [add-fileserver : Create SSH private key from secret]
2026-03-17 14:51:34.498867 | localhost | changed
2026-03-17 14:51:34.503715 | 
2026-03-17 14:51:34.503780 | TASK [add-fileserver : Add fileserver ssh key]
2026-03-17 14:51:34.804328 | localhost | Identity added: /var/lib/zuul/builds/702116f0d33e48f79477b12cf31ce05b/work/tmp/ansible.zfjilb97 (/var/lib/zuul/builds/702116f0d33e48f79477b12cf31ce05b/work/tmp/ansible.zfjilb97)
2026-03-17 14:51:34.804508 | localhost | ok: Runtime: 0:00:00.005827
2026-03-17 14:51:34.808483 | 
2026-03-17 14:51:34.808548 | TASK [add-fileserver : Remove SSH private key from disk]
2026-03-17 14:51:35.007378 | localhost | ok: Runtime: 0:00:00.003854
2026-03-17 14:51:35.011695 | 
2026-03-17 14:51:35.011760 | TASK [add-fileserver : Add fileserver to inventory]
2026-03-17 14:51:35.059303 | localhost | changed
2026-03-17 14:51:35.063832 | 
2026-03-17 14:51:35.063893 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-03-17 14:51:35.353732 | localhost | changed
2026-03-17 14:51:35.371738 | 
2026-03-17 14:51:35.371795 | PLAY [localhost]
2026-03-17 14:51:35.382052 | 
2026-03-17 14:51:35.382111 | TASK [Generate bulk log download script]
2026-03-17 14:51:35.399769 | localhost | ok
2026-03-17 14:51:35.409899 | 
2026-03-17 14:51:35.409963 | TASK [local-log-download : Check API endpoint is defined]
2026-03-17 14:51:35.436750 | localhost | ok: All assertions passed
2026-03-17 14:51:35.440707 | 
2026-03-17 14:51:35.440765 | TASK [local-log-download : Create download script]
2026-03-17 14:51:35.757107 | localhost -> localhost | changed
2026-03-17 14:51:35.765716 | 
2026-03-17 14:51:35.765782 | TASK [Register quick-download link]
2026-03-17 14:51:35.782652 | localhost | ok
2026-03-17 14:51:35.813686 | 
2026-03-17 14:51:35.813747 | PLAY [logserver.rdoproject.org]
2026-03-17 14:51:35.822047 | 
2026-03-17 14:51:35.822103 | TASK [Set zuul-log-path fact]
2026-03-17 14:51:35.837217 | logserver.rdoproject.org | ok
2026-03-17 14:51:35.845012 | 
2026-03-17 14:51:35.845074 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-17 14:51:35.859869 | logserver.rdoproject.org | ok
2026-03-17 14:51:35.864559 | 
2026-03-17 14:51:35.864628 | TASK [upload-logs : Create log directories]
2026-03-17 14:51:38.893718 | logserver.rdoproject.org | changed
2026-03-17 14:51:38.897084 | 
2026-03-17 14:51:38.897157 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-17 14:51:39.106973 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004748
2026-03-17 14:51:39.112480 | 
2026-03-17 14:51:39.112555 | TASK [upload-logs : Upload logs to log server]
2026-03-17 14:51:40.875759 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-03-17 14:51:40.878696 | 
2026-03-17 14:51:40.878762 | LOOP [upload-logs : Compress console log and json output]
2026-03-17 14:51:40.914861 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-17 14:51:40.922241 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-17 14:51:40.926310 | 
2026-03-17 14:51:40.926379 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-17 14:51:40.959393 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-17 14:51:40.959594 | 
2026-03-17 14:51:40.962727 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-17 14:51:40.973248 | 
2026-03-17 14:51:40.973335 | LOOP [upload-logs : Upload console log and json output]