2025-09-29 09:58:39.677148 | Job console starting...
2025-09-29 09:58:39.913843 | Updating repositories
2025-09-29 09:58:40.637339 | Preparing job workspace
2025-09-29 09:58:48.075759 | Running Ansible setup...
2025-09-29 09:58:51.407103 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-29 09:58:52.003425 | 
2025-09-29 09:58:52.003557 | PLAY [localhost]
2025-09-29 09:58:52.013327 | 
2025-09-29 09:58:52.013409 | TASK [Gathering Facts]
2025-09-29 09:58:52.933215 | localhost | ok
2025-09-29 09:58:52.947626 | 
2025-09-29 09:58:52.947750 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-09-29 09:58:53.368124 | localhost -> localhost | changed
2025-09-29 09:58:53.374165 | 
2025-09-29 09:58:53.374248 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-09-29 09:58:54.244232 | localhost -> localhost | changed
2025-09-29 09:58:54.253081 | 
2025-09-29 09:58:54.253185 | TASK [Setup log path fact]
2025-09-29 09:58:54.271789 | localhost | ok
2025-09-29 09:58:54.283205 | 
2025-09-29 09:58:54.283303 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-29 09:58:54.310373 | localhost | ok
2025-09-29 09:58:54.318030 | 
2025-09-29 09:58:54.318118 | TASK [emit-job-header : Print job information]
2025-09-29 09:58:54.345667 | # Job Information
2025-09-29 09:58:54.345811 | Ansible Version: 2.15.12
2025-09-29 09:58:54.345845 | Job: edpm-ansible-molecule-edpm_nova
2025-09-29 09:58:54.345868 | Pipeline: github-check
2025-09-29 09:58:54.345888 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-09-29 09:58:54.345926 | Triggered by: https://github.com/openstack-k8s-operators/edpm-ansible/pull/1032
2025-09-29 09:58:54.345957 | Log URL (when completed): https://logserver.rdoproject.org/7f1/rdoproject.org/7f11a15d9b01404283b6c04d31b0602f/
2025-09-29 09:58:54.345979 | Event ID: 20fd2690-9d19-11f0-9583-ab49256f285c
2025-09-29 09:58:54.349649 | 
2025-09-29 09:58:54.349726 | LOOP [emit-job-header : Print node information]
2025-09-29 09:58:54.453050 | localhost | ok:
2025-09-29 09:58:54.453228 | localhost | # Node Information
2025-09-29 09:58:54.453263 | localhost | Inventory Hostname: controller
2025-09-29 09:58:54.453290 | localhost | Hostname: np0005458814
2025-09-29 09:58:54.453313 | localhost | Username: zuul
2025-09-29 09:58:54.453334 | localhost | Distro: CentOS 9
2025-09-29 09:58:54.453353 | localhost | Provider: ibm-bm4-nodepool
2025-09-29 09:58:54.453371 | localhost | Region: regionOne
2025-09-29 09:58:54.453388 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-09-29 09:58:54.453405 | localhost | Product Name: OpenStack Compute
2025-09-29 09:58:54.453422 | localhost | Interface IP: 192.168.26.50
2025-09-29 09:58:54.496245 | 
2025-09-29 09:58:54.496340 | PLAY [all]
2025-09-29 09:58:54.504066 | 
2025-09-29 09:58:54.504154 | TASK [Gather network facts]
2025-09-29 09:58:54.878640 | controller | ok
2025-09-29 09:58:54.899734 | 
2025-09-29 09:58:54.899817 | TASK [include_role : start-zuul-console]
2025-09-29 09:58:54.916882 | controller | ok
2025-09-29 09:58:54.928966 | 
2025-09-29 09:58:54.929038 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-09-29 09:58:55.240656 | controller | ok
2025-09-29 09:58:55.250133 | 
2025-09-29 09:58:55.250225 | TASK [include_role : add-build-sshkey]
2025-09-29 09:58:55.268427 | controller | ok
2025-09-29 09:58:55.281904 | 
2025-09-29 09:58:55.282016 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-09-29 09:58:55.488901 | controller -> localhost | ok
2025-09-29 09:58:55.495442 | 
2025-09-29 09:58:55.495649 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-09-29 09:58:55.525101 | controller | ok
2025-09-29 09:58:55.541221 | controller | included: /var/lib/zuul/builds/7f11a15d9b01404283b6c04d31b0602f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-09-29 09:58:55.548453 | 
2025-09-29 09:58:55.548557 | TASK [add-build-sshkey : Create Temp SSH key]
2025-09-29 09:58:56.024214 | controller -> localhost | Generating public/private rsa key pair.
2025-09-29 09:58:56.024402 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7f11a15d9b01404283b6c04d31b0602f/work/7f11a15d9b01404283b6c04d31b0602f_id_rsa.
2025-09-29 09:58:56.024439 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7f11a15d9b01404283b6c04d31b0602f/work/7f11a15d9b01404283b6c04d31b0602f_id_rsa.pub.
2025-09-29 09:58:56.024466 | controller -> localhost | The key fingerprint is:
2025-09-29 09:58:56.024489 | controller -> localhost | SHA256:ADI0Q/X4zG7lx5MruyaQ3eL5uFGQyi7hJd0j4PrwmZ8 zuul-build-sshkey
2025-09-29 09:58:56.024524 | controller -> localhost | The key's randomart image is:
2025-09-29 09:58:56.024548 | controller -> localhost | +---[RSA 3072]----+
2025-09-29 09:58:56.024567 | controller -> localhost | | oB.o            |
2025-09-29 09:58:56.024587 | controller -> localhost | |   = + .         |
2025-09-29 09:58:56.024623 | controller -> localhost | |  . . =          |
2025-09-29 09:58:56.024644 | controller -> localhost | | . + * o         |
2025-09-29 09:58:56.024663 | controller -> localhost | |  + *o*.S        |
2025-09-29 09:58:56.024681 | controller -> localhost | | o =ooo=.. .     |
2025-09-29 09:58:56.024700 | controller -> localhost | |o o .o+o. =      |
2025-09-29 09:58:56.024719 | controller -> localhost | | + + o+oo. o     |
2025-09-29 09:58:56.024737 | controller -> localhost | |  =.E o=++.      |
2025-09-29 09:58:56.024755 | controller -> localhost | +----[SHA256]-----+
2025-09-29 09:58:56.024798 | controller -> localhost | ok: Runtime: 0:00:00.109661
2025-09-29 09:58:56.030263 | 
2025-09-29 09:58:56.030329 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-09-29 09:58:56.048633 | controller | ok
2025-09-29 09:58:56.058527 | controller | included: /var/lib/zuul/builds/7f11a15d9b01404283b6c04d31b0602f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-09-29 09:58:56.066853 | 
2025-09-29 09:58:56.066936 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-09-29 09:58:56.079878 | controller | skipping: Conditional result was False
2025-09-29 09:58:56.085491 | 
2025-09-29 09:58:56.085572 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-09-29 09:58:56.458243 | controller | changed
2025-09-29 09:58:56.463254 | 
2025-09-29 09:58:56.463328 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-09-29 09:58:56.655237 | controller | ok
2025-09-29 09:58:56.660362 | 
2025-09-29 09:58:56.660444 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-09-29 09:58:57.258551 | controller | changed
2025-09-29 09:58:57.264835 | 
2025-09-29 09:58:57.264959 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-09-29 09:58:57.877292 | controller | changed
2025-09-29 09:58:57.882583 | 
2025-09-29 09:58:57.882680 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-09-29 09:58:57.920295 | controller | skipping: Conditional result was False
2025-09-29 09:58:57.929084 | 
2025-09-29 09:58:57.929200 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-09-29 09:58:58.350405 | controller -> localhost | changed
2025-09-29 09:58:58.361351 | 
2025-09-29 09:58:58.361446 | TASK [add-build-sshkey : Add back temp key]
2025-09-29 09:58:58.621966 | controller -> localhost | Identity added: /var/lib/zuul/builds/7f11a15d9b01404283b6c04d31b0602f/work/7f11a15d9b01404283b6c04d31b0602f_id_rsa (zuul-build-sshkey)
2025-09-29 09:58:58.622202 | controller -> localhost | ok: Runtime: 0:00:00.010614
2025-09-29 09:58:58.628797 | 
2025-09-29 09:58:58.628885 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-09-29 09:58:58.960865 | controller | ok
2025-09-29 09:58:58.966384 | 
2025-09-29 09:58:58.966462 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-09-29 09:58:58.991323 | controller | skipping: Conditional result was False
2025-09-29 09:58:59.001397 | 
2025-09-29 09:58:59.001476 | TASK [include_role : validate-host]
2025-09-29 09:58:59.033387 | controller | ok
2025-09-29 09:58:59.054101 | 
2025-09-29 09:58:59.054216 | TASK [validate-host : Define zuul_info_dir fact]
2025-09-29 09:58:59.083993 | controller | ok
2025-09-29 09:58:59.089288 | 
2025-09-29 09:58:59.089376 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-09-29 09:58:59.327826 | controller -> localhost | ok
2025-09-29 09:58:59.333684 | 
2025-09-29 09:58:59.333753 | TASK [validate-host : Collect information about the host]
2025-09-29 09:58:59.931127 | controller | ok
2025-09-29 09:58:59.959877 | 
2025-09-29 09:58:59.959978 | TASK [validate-host : Sanitize hostname]
2025-09-29 09:58:59.998709 | controller | ok
2025-09-29 09:59:00.004087 | 
2025-09-29 09:59:00.004178 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-09-29 09:59:00.388047 | controller -> localhost | changed
2025-09-29 09:59:00.394898 | 
2025-09-29 09:59:00.395009 | TASK [validate-host : Collect information about zuul worker]
2025-09-29 09:59:00.719981 | controller | ok
2025-09-29 09:59:00.724708 | 
2025-09-29 09:59:00.724778 | TASK [validate-host : Write out all zuul information for each host]
2025-09-29 09:59:01.127363 | controller -> localhost | changed
2025-09-29 09:59:01.137495 | 
2025-09-29 09:59:01.137601 | TASK [include_role : prepare-workspace-openshift]
2025-09-29 09:59:01.151694 | controller | skipping: Conditional result was False
2025-09-29 09:59:01.158872 | 
2025-09-29 09:59:01.158996 | TASK [include_role : remove-zuul-sshkey]
2025-09-29 09:59:01.174560 | controller | skipping: Conditional result was False
2025-09-29 09:59:01.180431 | 
2025-09-29 09:59:01.180529 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-09-29 09:59:01.378960 | controller | ok: "logs"
2025-09-29 09:59:01.379219 | controller | ok: All items complete
2025-09-29 09:59:01.379256 | 
2025-09-29 09:59:01.545546 | controller | ok: "artifacts"
2025-09-29 09:59:01.714827 | controller | ok: "docs"
2025-09-29 09:59:01.730489 | 
2025-09-29 09:59:01.730606 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-09-29 09:59:01.935577 | controller | changed: "logs"
2025-09-29 09:59:02.110040 | controller | changed: "artifacts"
2025-09-29 09:59:02.286092 | controller | changed: "docs"
2025-09-29 09:59:02.316935 | 
2025-09-29 09:59:02.317036 | PLAY RECAP
2025-09-29 09:59:02.317084 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-29 09:59:02.317112 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-29 09:59:02.317214 | 
2025-09-29 09:59:02.421025 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-29 09:59:02.421754 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-29 09:59:03.023696 | 
2025-09-29 09:59:03.023811 | PLAY [all]
2025-09-29 09:59:03.043341 | 
2025-09-29 09:59:03.043426 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-29 09:59:03.099167 | controller | ok
2025-09-29 09:59:03.104194 | 
2025-09-29 09:59:03.104297 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-29 09:59:03.477295 | controller | changed
2025-09-29 09:59:03.482964 | 
2025-09-29 09:59:03.483053 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-29 09:59:04.316267 | controller | changed
2025-09-29 09:59:04.326727 | 
2025-09-29 09:59:04.326809 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-09-29 09:59:04.714038 | controller | changed:
2025-09-29 09:59:04.714210 | controller | {
2025-09-29 09:59:04.714243 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-09-29 09:59:04.714274 | controller | }
2025-09-29 09:59:04.926138 | controller | changed:
2025-09-29 09:59:04.926225 | controller | {
2025-09-29 09:59:04.926256 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-09-29 09:59:04.926279 | controller | }
2025-09-29 09:59:05.137634 | controller | changed:
2025-09-29 09:59:05.137718 | controller | {
2025-09-29 09:59:05.137749 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-09-29 09:59:05.137772 | controller | }
2025-09-29 09:59:05.350246 | controller | changed:
2025-09-29 09:59:05.350346 | controller | {
2025-09-29 09:59:05.350375 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-09-29 09:59:05.350398 | controller | }
2025-09-29 09:59:05.560171 | controller | changed:
2025-09-29 09:59:05.560257 | controller | {
2025-09-29 09:59:05.560283 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-09-29 09:59:05.560304 | controller | }
2025-09-29 09:59:05.760987 | controller | changed:
2025-09-29 09:59:05.762020 | controller | {
2025-09-29 09:59:05.762062 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-09-29 09:59:05.762088 | controller | }
2025-09-29 09:59:05.967274 | controller | changed:
2025-09-29 09:59:05.967408 | controller | {
2025-09-29 09:59:05.967441 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-09-29 09:59:05.967465 | controller | }
2025-09-29 09:59:06.194583 | controller | changed:
2025-09-29 09:59:06.194749 | controller | {
2025-09-29 09:59:06.194808 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-09-29 09:59:06.194839 | controller | }
2025-09-29 09:59:06.422950 | controller | changed:
2025-09-29 09:59:06.423164 | controller | {
2025-09-29 09:59:06.423200 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-09-29 09:59:06.423224 | controller | }
2025-09-29 09:59:06.646049 | controller | changed:
2025-09-29 09:59:06.646161 | controller | {
2025-09-29 09:59:06.646190 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-09-29 09:59:06.646212 | controller | }
2025-09-29 09:59:06.857705 | controller | changed:
2025-09-29 09:59:06.857807 | controller | {
2025-09-29 09:59:06.857839 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-09-29 09:59:06.857860 | controller | }
2025-09-29 09:59:07.073843 | controller | changed:
2025-09-29 09:59:07.073964 | controller | {
2025-09-29 09:59:07.073994 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-09-29 09:59:07.074017 | controller | }
2025-09-29 09:59:07.272038 | controller | changed:
2025-09-29 09:59:07.272155 | controller | {
2025-09-29 09:59:07.272191 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-09-29 09:59:07.272214 | controller | }
2025-09-29 09:59:07.509070 | controller | changed:
2025-09-29 09:59:07.509160 | controller | {
2025-09-29 09:59:07.509187 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-09-29 09:59:07.509208 | controller | }
2025-09-29 09:59:07.718577 | controller | changed:
2025-09-29 09:59:07.718713 | controller | {
2025-09-29 09:59:07.718750 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-09-29 09:59:07.718773 | controller | }
2025-09-29 09:59:07.926096 | controller | changed:
2025-09-29 09:59:07.926195 | controller | {
2025-09-29 09:59:07.926329 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-09-29 09:59:07.926363 | controller | }
2025-09-29 09:59:08.130643 | controller | changed:
2025-09-29 09:59:08.130733 | controller | {
2025-09-29 09:59:08.130762 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-09-29 09:59:08.130784 | controller | }
2025-09-29 09:59:08.336970 | controller | changed:
2025-09-29 09:59:08.337057 | controller | {
2025-09-29 09:59:08.337087 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-09-29 09:59:08.339645 | controller | }
2025-09-29 09:59:08.573319 | controller | changed:
2025-09-29 09:59:08.573411 | controller | {
2025-09-29 09:59:08.573453 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-09-29 09:59:08.573483 | controller | }
2025-09-29 09:59:08.787099 | controller | changed:
2025-09-29 09:59:08.788749 | controller | {
2025-09-29 09:59:08.788804 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-09-29 09:59:08.788830 | controller | }
2025-09-29 09:59:08.994063 | controller | changed:
2025-09-29 09:59:08.994151 | controller | {
2025-09-29 09:59:08.994178 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-09-29 09:59:08.994199 | controller | }
2025-09-29 09:59:09.201100 | controller | changed:
2025-09-29 09:59:09.201193 | controller | {
2025-09-29 09:59:09.201225 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-09-29 09:59:09.201246 | controller | }
2025-09-29 09:59:09.407242 | controller | changed:
2025-09-29 09:59:09.407337 | controller | {
2025-09-29 09:59:09.407364 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-09-29 09:59:09.407384 | controller | }
2025-09-29 09:59:09.614647 | controller | changed:
2025-09-29 09:59:09.614724 | controller | {
2025-09-29 09:59:09.614752 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-09-29 09:59:09.614773 | controller | }
2025-09-29 09:59:09.841809 | controller | changed:
2025-09-29 09:59:09.841888 | controller | {
2025-09-29 09:59:09.841943 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-09-29 09:59:09.841969 | controller | }
2025-09-29 09:59:10.043113 | controller | changed:
2025-09-29 09:59:10.043214 | controller | {
2025-09-29 09:59:10.043244 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-09-29 09:59:10.043272 | controller | }
2025-09-29 09:59:10.071131 | 
2025-09-29 09:59:10.071236 | TASK [Set timezone to UTC]
2025-09-29 09:59:10.480597 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-09-29 09:59:10.486345 | 
2025-09-29 09:59:10.486422 | TASK [Create nodepool directory]
2025-09-29 09:59:10.698649 | controller | changed
2025-09-29 09:59:10.704639 | 
2025-09-29 09:59:10.704728 | TASK [Create nodepool sub_nodes file]
2025-09-29 09:59:11.222618 | controller | changed
2025-09-29 09:59:11.227832 | 
2025-09-29 09:59:11.227950 | TASK [Create nodepool sub_nodes_private file]
2025-09-29 09:59:11.745207 | controller | changed
2025-09-29 09:59:11.750027 | 
2025-09-29 09:59:11.750093 | LOOP [Populate nodepool sub_nodes file]
2025-09-29 09:59:11.779164 | 
2025-09-29 09:59:11.779314 | LOOP [Populate nodepool sub_nodes_private file]
2025-09-29 09:59:11.807803 | 
2025-09-29 09:59:11.807968 | TASK [Create nodepool primary file]
2025-09-29 09:59:11.831533 | controller | skipping: Conditional result was False
2025-09-29 09:59:11.837213 | 
2025-09-29 09:59:11.837295 | TASK [Create nodepool node_private for this node]
2025-09-29 09:59:12.364129 | controller | changed
2025-09-29 09:59:12.371179 | 
2025-09-29 09:59:12.371266 | LOOP [Copy ssh keys to nodepool directory]
2025-09-29 09:59:12.716705 | controller | ok: Item: id_rsa Runtime: 0:00:00.005347
2025-09-29 09:59:12.716943 | 
2025-09-29 09:59:12.878634 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004339
2025-09-29 09:59:12.887485 | 
2025-09-29 09:59:12.887587 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-09-29 09:59:13.424266 | controller | changed
2025-09-29 09:59:13.430136 | 
2025-09-29 09:59:13.430219 | TASK [Validate sudoers config after edits]
2025-09-29 09:59:13.659112 | controller | /etc/sudoers: parsed OK
2025-09-29 09:59:13.659190 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-09-29 09:59:13.659201 | controller | /etc/sudoers.d/zuul: parsed OK
2025-09-29 09:59:13.659208 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-09-29 09:59:13.968740 | controller | ok: Runtime: 0:00:00.005188
2025-09-29 09:59:13.974209 | 
2025-09-29 09:59:13.974273 | TASK [Show the environment passed in to job shell scripts]
2025-09-29 09:59:14.199851 | controller | SHELL=/bin/bash
2025-09-29 09:59:14.199900 | controller | ZUUL_PROJECT=openstack-k8s-operators/edpm-ansible
2025-09-29 09:59:14.199909 | controller | ZUUL_SHORT_PROJECT_NAME=edpm-ansible
2025-09-29 09:59:14.199916 | controller | ZUUL_CHANGES=openstack-k8s-operators/edpm-ansible:main:refs/changes/32/1032/8ac6337b919611d995b295cc0139897da2b1b5e2
2025-09-29 09:59:14.199921 | controller | PWD=/home/zuul
2025-09-29 09:59:14.199927 | controller | ZUUL_PIPELINE=github-check
2025-09-29 09:59:14.199932 | controller | LOGNAME=zuul
2025-09-29 09:59:14.199984 | controller | XDG_SESSION_TYPE=tty
2025-09-29 09:59:14.199992 | controller | _=/usr/bin/env
2025-09-29 09:59:14.199998 | controller | MOTD_SHOWN=pam
2025-09-29 09:59:14.200003 | controller | HOME=/home/zuul
2025-09-29 09:59:14.200009 | controller | LANG=en_US.UTF-8
2025-09-29 09:59:14.200014 | controller | SSH_CONNECTION=192.168.26.12 45196 192.168.26.50 22
2025-09-29 09:59:14.200020 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-09-29 09:59:14.200028 | controller | ZUUL_CHANGE_IDS=1032,8ac6337b919611d995b295cc0139897da2b1b5e2
2025-09-29 09:59:14.200034 | controller | WORKSPACE=/home/zuul/workspace
2025-09-29 09:59:14.200039 | controller | XDG_SESSION_CLASS=user
2025-09-29 09:59:14.200045 | controller | SELINUX_ROLE_REQUESTED=
2025-09-29 09:59:14.200050 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-09-29 09:59:14.200055 | controller | USER=zuul
2025-09-29 09:59:14.200061 | controller | ZUUL_VOTING=True
2025-09-29 09:59:14.200066 | controller | BUILD_TIMEOUT=1800000
2025-09-29 09:59:14.200071 | controller | SELINUX_USE_CURRENT_RANGE=
2025-09-29 09:59:14.200076 | controller | SHLVL=1
2025-09-29 09:59:14.200082 | controller | ZUUL_PATCHSET=8ac6337b919611d995b295cc0139897da2b1b5e2
2025-09-29 09:59:14.200087 | controller | XDG_SESSION_ID=1
2025-09-29 09:59:14.200093 | controller | ZUUL_BRANCH=main
2025-09-29 09:59:14.200098 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-09-29 09:59:14.200103 | controller | SSH_CLIENT=192.168.26.12 45196 22
2025-09-29 09:59:14.200109 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-09-29 09:59:14.200114 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-09-29 09:59:14.200119 | controller | which_declare=declare -f
2025-09-29 09:59:14.200125 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-09-29 09:59:14.200131 | controller | SELINUX_LEVEL_REQUESTED=
2025-09-29 09:59:14.200136 | controller | ZUUL_CHANGE=1032
2025-09-29 09:59:14.200141 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-09-29 09:59:14.200147 | controller | ZUUL_UUID=7f11a15d9b01404283b6c04d31b0602f
2025-09-29 09:59:14.200152 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-09-29 09:59:14.200158 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-09-29 09:59:14.200163 | controller | }
2025-09-29 09:59:14.501920 | controller | ok: Runtime: 0:00:00.005452
2025-09-29 09:59:14.507463 | 
2025-09-29 09:59:14.507544 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-09-29 09:59:14.521040 | controller | skipping: Conditional result was False
2025-09-29 09:59:14.526428 | 
2025-09-29 09:59:14.526505 | TASK [Symlink /home/zuul-worker/workspace]
2025-09-29 09:59:15.051484 | controller | skipping: Conditional result was False
2025-09-29 09:59:15.057721 | 
2025-09-29 09:59:15.057814 | TASK [Ensure legacy workspace directory]
2025-09-29 09:59:15.259635 | controller | changed
2025-09-29 09:59:15.278520 | 
2025-09-29 09:59:15.278598 | PLAY RECAP
2025-09-29 09:59:15.278645 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-29 09:59:15.278670 | 
2025-09-29 09:59:15.370715 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-29 09:59:15.371530 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/read_global_vars.yml@main]
2025-09-29 09:59:16.048689 | 
2025-09-29 09:59:16.048928 | PLAY [Load global variables]
2025-09-29 09:59:16.070062 | 
2025-09-29 09:59:16.070181 | TASK [Read group_vars all file]
2025-09-29 09:59:16.109525 | controller | ok
2025-09-29 09:59:16.128626 | 
2025-09-29 09:59:16.128746 | TASK [cifmw_helpers : Check if file is available]
2025-09-29 09:59:16.384271 | controller | ok
2025-09-29 09:59:16.389638 | 
2025-09-29 09:59:16.389730 | TASK [cifmw_helpers : Read vars]
2025-09-29 09:59:16.409194 | 
2025-09-29 09:59:16.409336 | LOOP [cifmw_helpers : Set vars as fact]
2025-09-29 09:59:16.468958 | 
2025-09-29 09:59:16.469066 | PLAY RECAP
2025-09-29 09:59:16.469109 | controller | ok: 1 changed: 0 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-29 09:59:16.469134 | 
2025-09-29 09:59:16.584444 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/read_global_vars.yml@main]
2025-09-29 09:59:16.585222 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-29 09:59:17.251146 | 
2025-09-29 09:59:17.251250 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-09-29 09:59:17.279373 | 
2025-09-29 09:59:17.279461 | TASK [Create zuul-output directory]
2025-09-29 09:59:17.618057 | controller | changed
2025-09-29 09:59:17.623641 | 
2025-09-29 09:59:17.623747 | TASK [Slurp Zuul inventory test]
2025-09-29 09:59:17.999603 | controller -> localhost | ok
2025-09-29 09:59:18.006244 | 
2025-09-29 09:59:18.006340 | TASK [Save zuul inventory]
2025-09-29 09:59:18.907981 | controller | changed
2025-09-29 09:59:18.913804 | 
2025-09-29 09:59:18.913892 | TASK [Save zuul vars without the change_message]
2025-09-29 09:59:19.565781 | controller | changed
2025-09-29 09:59:19.584298 | 
2025-09-29 09:59:19.584366 | PLAY RECAP
2025-09-29 09:59:19.584410 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-29 09:59:19.584434 | 
2025-09-29 09:59:19.659378 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-29 09:59:19.660152 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-29 09:59:20.296990 | 
2025-09-29 09:59:20.297093 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-09-29 09:59:20.337283 | 
2025-09-29 09:59:20.337392 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-09-29 09:59:20.371390 | controller | ok
2025-09-29 09:59:20.388644 | 
2025-09-29 09:59:20.388761 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-29 09:59:20.412686 | controller | skipping: Conditional result was False
2025-09-29 09:59:20.418420 | 
2025-09-29 09:59:20.418505 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-29 09:59:20.790748 | controller | ok
2025-09-29 09:59:20.796728 | 
2025-09-29 09:59:20.796818 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-29 09:59:21.585486 | controller | ok
2025-09-29 09:59:21.614116 | 
2025-09-29 09:59:21.614234 | TASK [Prepare workspace]
2025-09-29 09:59:21.672701 | controller | ok
2025-09-29 09:59:21.691208 | 
2025-09-29 09:59:21.691300 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-09-29 09:59:22.047002 | controller | ok
2025-09-29 09:59:22.053829 | 
2025-09-29 09:59:22.053938 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-09-29 09:59:25.311377 | controller | Output suppressed because no_log was given
2025-09-29 09:59:25.321947 | 
2025-09-29 09:59:25.322057 | LOOP [Create zuul-output directory]
2025-09-29 09:59:25.533700 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-09-29 09:59:25.696880 | controller | ok: "/home/zuul/zuul-output/logs"
2025-09-29 09:59:25.715978 | 
2025-09-29 09:59:25.716086 | TASK [Install required packages]
2025-09-29 10:00:26.897489 | controller | changed
2025-09-29 10:00:26.903017 | 
2025-09-29 10:00:26.903082 | TASK [Install venv]
2025-09-29 10:01:27.924871 | controller | changed
2025-09-29 10:01:27.953356 | 
2025-09-29 10:01:27.953451 | PLAY RECAP
2025-09-29 10:01:27.953497 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-29 10:01:27.953522 | 
2025-09-29 10:01:28.049173 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-29 10:01:28.050044 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-29 10:01:28.631257 | 
2025-09-29 10:01:28.631366 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-09-29 10:01:28.653903 | 
2025-09-29 10:01:28.654054 | TASK [Gather required facts]
2025-09-29 10:01:29.180824 | controller | ok
2025-09-29 10:01:29.186077 | 
2025-09-29 10:01:29.186169 | TASK [Load environment var if instructed to]
2025-09-29 10:01:29.210654 | controller | skipping: Conditional result was False
2025-09-29 10:01:29.217237 | 
2025-09-29 10:01:29.217321 | TASK [Run molecule]
2025-09-29 10:01:30.330302 | controller | WARNING  Driver vagrant does not provide a schema.
2025-09-29 10:01:30.332829 | controller | INFO     Performing prerun with role_name_check=0...
2025-09-29 10:01:31.158058 | controller | INFO     Running default > destroy
2025-09-29 10:01:31.158293 | controller | WARNING  Skipping, instances are delegated.
2025-09-29 10:01:31.166338 | controller | INFO     Running default > create
2025-09-29 10:01:31.167255 | controller | WARNING  Skipping, instances are delegated.
2025-09-29 10:01:31.175311 | controller | INFO     Running default > prepare
2025-09-29 10:01:31.928588 | controller |
2025-09-29 10:01:31.928644 | controller | PLAY [Prepare] *****************************************************************
2025-09-29 10:01:31.928742 | controller |
2025-09-29 10:01:31.928876 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 10:01:31.928986 | controller | Monday 29 September 2025  10:01:31 +0000 (0:00:00.012)       0:00:00.012 ******
2025-09-29 10:01:32.778656 | controller | ok: [compute-1]
2025-09-29 10:01:32.778726 | controller |
2025-09-29 10:01:32.778900 | controller | TASK [../../../../molecule/common/test_deps : Validating arguments against arg spec 'main' - The main entry point for the test_deps role.] ***
2025-09-29 10:01:32.779029 | controller | Monday 29 September 2025  10:01:32 +0000 (0:00:00.850)       0:00:00.863 ******
2025-09-29 10:01:32.794916 | controller | ok: [compute-1]
2025-09-29 10:01:32.795003 | controller |
2025-09-29 10:01:32.795110 | controller | TASK [../../../../molecule/common/test_deps : Gather facts if they don't exist] ***
2025-09-29 10:01:32.795239 | controller | Monday 29 September 2025  10:01:32 +0000 (0:00:00.016)       0:00:00.880 ******
2025-09-29 10:01:32.811740 | controller | skipping: [compute-1]
2025-09-29 10:01:32.811842 | controller |
2025-09-29 10:01:32.811947 | controller | TASK [../../../../molecule/common/test_deps : Gather variables for each operating system] ***
2025-09-29 10:01:32.812054 | controller | Monday 29 September 2025  10:01:32 +0000 (0:00:00.016)       0:00:00.896 ******
2025-09-29 10:01:32.851958 | controller | ok: [compute-1] => (item=/home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/molecule/common/test_deps/vars/centos-9.yml)
2025-09-29 10:01:32.852038 | controller |
2025-09-29 10:01:32.852142 | controller | TASK [../../../../molecule/common/test_deps : Detect mirrors file] *************
2025-09-29 10:01:32.852248 | controller | Monday 29 September 2025  10:01:32 +0000 (0:00:00.040)       0:00:00.936 ******
2025-09-29 10:01:33.139192 | controller | ok: [compute-1]
2025-09-29 10:01:33.139264 | controller |
2025-09-29 10:01:33.139380 | controller | TASK [../../../../molecule/common/test_deps : Disable ubi host subscription-manager integration] ***
2025-09-29 10:01:33.139481 | controller | Monday 29 September 2025  10:01:33 +0000 (0:00:00.287)       0:00:01.224 ******
2025-09-29 10:01:33.436049 | controller | ok: [compute-1]
2025-09-29 10:01:33.436115 | controller |
2025-09-29 10:01:33.436227 | controller | TASK [../../../../molecule/common/test_deps : Remove ubi 9 repos] **************
2025-09-29 10:01:33.436326 | controller | Monday 29 September 2025  10:01:33 +0000 (0:00:00.296)       0:00:01.521 ******
2025-09-29 10:01:33.655924 | controller | ok: [compute-1]
2025-09-29 10:01:33.656002 | controller |
2025-09-29 10:01:33.656115 | controller | TASK [../../../../molecule/common/test_deps : Fetch latest repo version] *******
2025-09-29 10:01:33.656221 | controller | Monday 29 September 2025  10:01:33 +0000 (0:00:00.219)       0:00:01.740 ******
2025-09-29 10:01:34.210915 | controller | ok: [compute-1]
2025-09-29 10:01:34.210986 | controller |
2025-09-29 10:01:34.211093 | controller | TASK [../../../../molecule/common/test_deps : Create default repo file] ********
2025-09-29 10:01:34.211193 | controller | Monday 29 September 2025  10:01:34 +0000 (0:00:00.554)       0:00:02.295 ******
2025-09-29 10:01:34.671077 | controller | changed: [compute-1]
2025-09-29 10:01:34.671146 | controller |
2025-09-29 10:01:34.671263 | controller | TASK [../../../../molecule/common/test_deps : Install repo-setup pre-requirements] ***
2025-09-29 10:01:34.671383 | controller | Monday 29 September 2025  10:01:34 +0000 (0:00:00.460)       0:00:02.755 ******
2025-09-29 10:01:44.503844 | controller | ok: [compute-1]
2025-09-29 10:01:44.504163 | controller |
2025-09-29 10:01:45.704320 | controller | TASK [../../../../molecule/common/test_deps : Get repo-setup repository] *******
2025-09-29 10:01:45.704350 | controller | Monday 29 September 2025  10:01:44 +0000 (0:00:09.832)       0:00:12.588 ******
2025-09-29 10:01:45.704366 | controller | changed: [compute-1]
2025-09-29 10:01:49.090901 | controller |
2025-09-29 10:01:49.090931 | controller | TASK [../../../../molecule/common/test_deps : Install repo-setup] **************
2025-09-29 10:01:49.090943 | controller | Monday 29 September 2025  10:01:45 +0000 (0:00:01.200)       0:00:13.788 ******
2025-09-29 10:01:49.090953 | controller | changed: [compute-1]
2025-09-29 10:01:49.921278 | controller |
2025-09-29 10:01:49.921310 | controller | TASK [../../../../molecule/common/test_deps : Create repos] ********************
2025-09-29 10:01:49.921318 | controller | Monday 29 September 2025  10:01:49 +0000 (0:00:03.386)       0:00:17.175 ******
2025-09-29 10:01:49.921329 | controller | changed: [compute-1]
2025-09-29 10:01:50.150376 | controller |
2025-09-29 10:01:50.150408 | controller | TASK [../../../../molecule/common/test_deps : Look for redhat-release rpm] *****
2025-09-29 10:01:50.150416 | controller | Monday 29 September 2025  10:01:49 +0000 (0:00:00.830)       0:00:18.005 ******
2025-09-29 10:01:50.150432 | controller | fatal: [compute-1]: FAILED! => changed=true
2025-09-29 10:01:50.150455 | controller |   cmd: |-
2025-09-29 10:01:50.150461 | controller |     rpm -qe redhat-release
2025-09-29 10:01:50.150469 | controller |   delta: '0:00:00.011774'
2025-09-29 10:01:50.150490 | controller |   end: '2025-09-29 10:01:50.120983'
2025-09-29 10:01:50.151206 | controller |   msg: non-zero return code
2025-09-29 10:01:50.156254 | controller |   rc: 1
2025-09-29 10:01:50.156278 | controller |   start: '2025-09-29 10:01:50.109209'
2025-09-29 10:01:50.156286 | controller |   stderr: ''
2025-09-29 10:01:50.156292 | controller |   stderr_lines: <omitted>
2025-09-29 10:01:50.156298 | controller |   stdout: package redhat-release is not installed
2025-09-29 10:01:50.156303 | controller |   stdout_lines: <omitted>
2025-09-29 10:01:50.156313 | controller | ...ignoring
2025-09-29 10:01:50.187581 | controller |
2025-09-29 10:01:50.187616 | controller | TASK [../../../../molecule/common/test_deps : Workaround of redhat-release binary on ubi9] ***
2025-09-29 10:01:50.187625 | controller | Monday 29 September 2025  10:01:50 +0000 (0:00:00.235)       0:00:18.241 ******
2025-09-29 10:01:50.187635 | controller | skipping: [compute-1]
2025-09-29 10:03:20.695604 | controller |
2025-09-29 10:03:20.695639 | controller | TASK [../../../../molecule/common/test_deps : Install edpm packages] ***********
2025-09-29 10:03:20.695647 | controller | Monday 29 September 2025  10:01:50 +0000 (0:00:00.031)       0:00:18.272 ******
2025-09-29 10:03:20.695657 | controller | changed: [compute-1]
2025-09-29 10:03:21.917257 | controller |
2025-09-29 10:03:21.917287 | controller | TASK [../../../../molecule/common/test_deps : Install selinux python libs] *****
2025-09-29 10:03:21.917295 | controller | Monday 29 September 2025  10:03:20 +0000 (0:01:30.507)       0:01:48.780 ******
2025-09-29 10:03:21.917306 | controller | ok: [compute-1]
2025-09-29 10:03:23.126629 | controller |
2025-09-29 10:03:23.126660 | controller | TASK [../../../../molecule/common/test_deps : Install python yaml libs] ********
2025-09-29 10:03:23.126668 | controller | Monday 29 September 2025  10:03:21 +0000 (0:00:01.221)       0:01:50.001 ******
2025-09-29 10:03:23.126679 | controller | ok: [compute-1]
2025-09-29 10:03:23.126700 | controller |
2025-09-29 10:03:23.126709 | controller | TASK [../../../../molecule/common/test_deps : Install extra packages] **********
2025-09-29 10:03:23.126809 | controller | Monday 29 September 2025  10:03:23 +0000 (0:00:01.209)       0:01:51.211 ******
2025-09-29 10:03:23.155759 | controller | skipping: [compute-1]
2025-09-29 10:03:23.348906 | controller |
2025-09-29 10:03:23.348941 | controller | TASK [../../../../molecule/common/test_deps : Check for /etc/ansible/facts.d/bootc.fact] ***
2025-09-29 10:03:23.348949 | controller | Monday 29 September 2025  10:03:23 +0000 (0:00:00.028)       0:01:51.240 ******
2025-09-29 10:03:23.348960 | controller | ok: [compute-1]
2025-09-29 10:03:23.557439 | controller |
2025-09-29 10:03:23.557478 | controller | TASK [../../../../molecule/common/test_deps : Create /etc/ansible/facts.d] *****
2025-09-29 10:03:23.557488 | controller | Monday 29 September 2025  10:03:23 +0000 (0:00:00.193)       0:01:51.433 ******
2025-09-29 10:03:23.557524 | controller | changed: [compute-1]
2025-09-29 10:03:23.937878 | controller |
2025-09-29 10:03:23.937907 | controller | TASK [../../../../molecule/common/test_deps : Create /etc/ansible/facts.d/bootc.fact] ***
2025-09-29 10:03:23.937915 | controller | Monday 29 September 2025  10:03:23 +0000 (0:00:00.208)       0:01:51.641 ******
2025-09-29 10:03:23.937926 | controller | changed: [compute-1]
2025-09-29 10:03:23.956610 | controller |
2025-09-29 10:03:23.956646 | controller | TASK [osp.edpm.env_data : Validating arguments against arg spec 'main' - The main entry point for the osp.edpm.env_data role.] ***
2025-09-29 10:03:23.956665 | controller | Monday 29 September 2025  10:03:23 +0000 (0:00:00.380)       0:01:52.022 ******
2025-09-29 10:03:23.956678 | controller | ok: [compute-1]
2025-09-29 10:03:23.956709 | controller |
2025-09-29 10:03:23.956891 | controller | TASK [osp.edpm.env_data : Gather all installed packages] ***********************
2025-09-29 10:03:24.701365 | controller | Monday 29 September 2025  10:03:23 +0000 (0:00:00.018)       0:01:52.041 ******
2025-09-29 10:03:24.701401 | controller | ok: [compute-1]
2025-09-29 10:03:25.081319 | controller |
2025-09-29 10:03:25.081347 | controller | TASK [osp.edpm.env_data : Gather repository list] ******************************
2025-09-29 10:03:25.081356 | controller | Monday 29 September 2025  10:03:24 +0000 (0:00:00.744)       0:01:52.786 ******
2025-09-29 10:03:25.081366 | controller | ok: [compute-1]
2025-09-29 10:03:25.123610 | controller |
2025-09-29 10:03:25.123642 | controller | TASK [osp.edpm.env_data : Output installed packages] ***************************
2025-09-29 10:03:25.123651 | controller | Monday 29 September 2025  10:03:25 +0000 (0:00:00.379)       0:01:53.165 ******
2025-09-29 10:03:25.123661 | controller | ok: [compute-1] =>
2025-09-29 10:03:25.123685 | controller |   msg:
2025-09-29 10:03:25.123691 | controller |   - tzdata
2025-09-29 10:03:25.123697 | controller |   - fonts-filesystem
2025-09-29 10:03:25.123703 | controller |   - adobe-source-code-pro-fonts
2025-09-29 10:03:25.123708 | controller |   - dejavu-sans-fonts
2025-09-29 10:03:25.123713 | controller |   - langpacks-core-font-en
2025-09-29 10:03:25.123719 | controller |   - abattis-cantarell-fonts
2025-09-29 10:03:25.123724 | controller |   - geolite2-country
2025-09-29 10:03:25.123729 | controller |   - geolite2-city
2025-09-29 10:03:25.123734 | controller |   - gawk-all-langpacks
2025-09-29 10:03:25.123739 | controller |   - quota-nls
2025-09-29 10:03:25.123745 | controller |   - python3-setuptools-wheel
2025-09-29 10:03:25.123750 | controller |   - publicsuffix-list-dafsa
2025-09-29 10:03:25.123757 | controller |   - pcre2-syntax
2025-09-29 10:03:25.123777 | controller |   - ncurses-base
2025-09-29 10:03:25.123798 | controller |   - libssh-config
2025-09-29 10:03:25.124619 | controller |   - libreport-filesystem
2025-09-29 10:03:25.124670 | controller |   - dnf-data
2025-09-29 10:03:25.124678 | controller |   - kbd-misc
2025-09-29 10:03:25.124684 | controller |   - kbd-legacy
2025-09-29 10:03:25.124690 | controller |   - hwdata
2025-09-29 10:03:25.124695 | controller |   - dhcp-common
2025-09-29 10:03:25.124700 | controller |   - coreutils-common
2025-09-29 10:03:25.124706 | controller |   - centos-gpg-keys
2025-09-29 10:03:25.124713 | controller |   - centos-stream-repos
2025-09-29 10:03:25.124734 | controller |   - centos-stream-release
2025-09-29 10:03:25.125563 | controller |   - setup
2025-09-29 10:03:25.125613 | controller |   - filesystem
2025-09-29 10:03:25.125622 | controller |   - basesystem
2025-09-29 10:03:25.125628 | controller |   - ncurses-libs
2025-09-29 10:03:25.125633 | controller |   - bash
2025-09-29 10:03:25.125638 | controller |   - libgcc
2025-09-29 10:03:25.125643 | controller |   - glibc-gconv-extra
2025-09-29 10:03:25.125648 | controller |   - glibc-langpack-en
2025-09-29 10:03:25.125653 | controller |   - glibc-common
2025-09-29 10:03:25.125661 | controller |   - glibc
2025-09-29 10:03:25.125734 | controller |   - zlib
2025-09-29 10:03:25.125846 | controller |   - popt
2025-09-29 10:03:25.125957 | controller |   - xz-libs
2025-09-29 10:03:25.126086 | controller |   - libcap
2025-09-29 10:03:25.126188 | controller |   - libzstd
2025-09-29 10:03:25.126299 | controller |   - libstdc++
2025-09-29 10:03:25.126410 | controller |   - libuuid
2025-09-29 10:03:25.126538 | controller |   - libxcrypt
2025-09-29 10:03:25.126668 | controller |   - sqlite-libs
2025-09-29 10:03:25.126778 | controller |   - bzip2-libs
2025-09-29 10:03:25.126885 | controller |   - libcom_err
2025-09-29 10:03:25.127007 | controller |   - libxml2
2025-09-29 10:03:25.127116 | controller |   - libcap-ng
2025-09-29 10:03:25.127223 | controller |   - audit-libs
2025-09-29 10:03:25.127331 | controller |   - libmnl
2025-09-29 10:03:25.127440 | controller |   - libtalloc
2025-09-29 10:03:25.127565 | controller |   - elfutils-libelf
2025-09-29 10:03:25.127678 | controller |   - readline
2025-09-29 10:03:25.127788 | controller |   - crypto-policies
2025-09-29 10:03:25.127909 | controller |   - alternatives
2025-09-29 10:03:25.128037 | controller |   - keyutils-libs
2025-09-29 10:03:25.128149 | controller |   - libunistring
2025-09-29 10:03:25.128258 | controller |   - lua-libs
2025-09-29 10:03:25.128365 | controller |   - expat
2025-09-29 10:03:25.128472 | controller |   - libffi
2025-09-29 10:03:25.128601 | controller |   - libgpg-error
2025-09-29 10:03:25.128715 | controller |   - libnl3
2025-09-29 10:03:25.128822 | controller |   - libsepol
2025-09-29 10:03:25.128930 | controller |   - libtdb
2025-09-29 10:03:25.129058 | controller |   - lz4-libs
2025-09-29 10:03:25.129166 | controller |   - libidn2
2025-09-29 10:03:25.129273 | controller |   - libtevent
2025-09-29 10:03:25.129379 | controller |   - gmp
2025-09-29 10:03:25.129487 | controller |   - jansson
2025-09-29 10:03:25.129611 | controller |   - json-c
2025-09-29 10:03:25.129725 | controller |   - libattr
2025-09-29 10:03:25.129831 | controller |   - libacl
2025-09-29 10:03:25.129938 | controller |   - libsmartcols
2025-09-29 10:03:25.130072 | controller |   - libtasn1
2025-09-29 10:03:25.130190 | controller |   - p11-kit
2025-09-29 10:03:25.130297 | controller |   - pcre2
2025-09-29 10:03:25.130413 | controller |   - libselinux
2025-09-29 10:03:25.130548 | controller |   - sed
2025-09-29 10:03:25.130748 | controller |   - libsemanage
2025-09-29 10:03:25.130781 | controller |   - shadow-utils
2025-09-29 10:03:25.130888 | controller |   - findutils
2025-09-29 10:03:25.131009 | controller |   - libgcrypt
2025-09-29 10:03:25.131123 | controller |   - file-libs
2025-09-29 10:03:25.131229 | controller |   - file
2025-09-29 10:03:25.131335 | controller |   - gdbm-libs
2025-09-29 10:03:25.131442 | controller |   - libedit
2025-09-29 10:03:25.131577 | controller |   - libref_array
2025-09-29 10:03:25.131686 | controller |   - libverto
2025-09-29 10:03:25.131790 | controller |   - psmisc
2025-09-29 10:03:25.131903 | controller |   - libselinux-utils
2025-09-29 10:03:25.132020 | controller |   - p11-kit-trust
2025-09-29 10:03:25.132127 | controller |   - libpsl
2025-09-29 10:03:25.132233 | controller |   - libassuan
2025-09-29 10:03:25.132338 | controller |   - keyutils
2025-09-29 10:03:25.132443 | controller |   - libbpf
2025-09-29 10:03:25.132568 | controller |   - iproute
2025-09-29 10:03:25.132706 | controller |   - e2fsprogs-libs
2025-09-29 10:03:25.132798 | controller |   - libicu
2025-09-29 10:03:25.132904 | controller |   - libproxy
2025-09-29 10:03:25.133023 | controller |   - fuse-libs
2025-09-29 10:03:25.133132 | controller |   - libbasicobjects
2025-09-29 10:03:25.133237 | controller |   - libbrotli
2025-09-29 10:03:25.133342 | controller |   - libcollection
2025-09-29 10:03:25.133448 | controller |   - libdhash
2025-09-29 10:03:25.133575 | controller |   - libgomp
2025-09-29 10:03:25.133692 | controller |   - libpath_utils
2025-09-29 10:03:25.133800 | controller |   - libini_config
2025-09-29 10:03:25.133907 | controller |   - libseccomp
2025-09-29 10:03:25.134025 | controller |   - libsigsegv
2025-09-29 10:03:25.134133 | controller |   - libsss_idmap
2025-09-29 10:03:25.134239 | controller |   - libyaml
2025-09-29 10:03:25.134344 | controller |   - lzo
2025-09-29 10:03:25.134451 | controller |   - numactl-libs
2025-09-29 10:03:25.134578 | controller |   - pcre
2025-09-29 10:03:25.134690 | controller |   - grep
2025-09-29 10:03:25.134809 | controller |   - openssl-fips-provider
2025-09-29 10:03:25.134914 | controller |   - openssl-libs
2025-09-29 10:03:25.135033 | controller |   - coreutils
2025-09-29 10:03:25.135140 | controller |   - ca-certificates
2025-09-29 10:03:25.135246 | controller |   - systemd-libs
2025-09-29 10:03:25.135352 | controller |   - libblkid
2025-09-29 10:03:25.135457 | controller |   - dbus-libs
2025-09-29 10:03:25.135582 | controller |   - libmount
2025-09-29 10:03:25.135695 | controller |   - kmod
2025-09-29 10:03:25.135802 | controller |   - util-linux-core
2025-09-29 10:03:25.135909 | controller |   - libfdisk
2025-09-29 10:03:25.136030 | controller |   - gzip
2025-09-29 10:03:25.136137 | controller |   - openssl
2025-09-29 10:03:25.136243 | controller |   - kmod-libs
2025-09-29 10:03:25.136350 | controller |   - libevent
2025-09-29 10:03:25.136456 | controller |   - cracklib
2025-09-29 10:03:25.136581 | controller |   - procps-ng
2025-09-29 10:03:25.136695 | controller |   - libarchive
2025-09-29 10:03:25.136804 | controller |   - which
2025-09-29 10:03:25.136922 | controller |   - cracklib-dicts
2025-09-29 10:03:25.137052 | controller |   - avahi-libs
2025-09-29 10:03:25.137161 | controller |   - dbus-tools
2025-09-29 10:03:25.137268 | controller |   - centos-logos
2025-09-29 10:03:25.137375 | controller |   - libsss_certmap
2025-09-29 10:03:25.137480 | controller |   - sscg
2025-09-29 10:03:25.137605 | controller |   - xz
2025-09-29 10:03:25.137718 | controller |   - squashfs-tools
2025-09-29 10:03:25.137827 | controller |   - libutempter
2025-09-29 10:03:25.137933 | controller |   - acl
2025-09-29 10:03:25.138057 | controller |   - gettext-libs
2025-09-29 10:03:25.138165 | controller |   - gettext
2025-09-29 10:03:25.138271 | controller |   - attr
2025-09-29 10:03:25.138378 | controller |   - mpfr
2025-09-29 10:03:25.138484 | controller |   - gawk
2025-09-29 10:03:25.138608 | controller |   - krb5-libs
2025-09-29 10:03:25.138720 | controller |   - libtirpc
2025-09-29 10:03:25.138828 | controller |   - quota
2025-09-29 10:03:25.138933 | controller |   - cyrus-sasl-lib
2025-09-29 10:03:25.139051 | controller |   - libssh
2025-09-29 10:03:25.139165 | controller |   - libibverbs
2025-09-29 10:03:25.139274 | controller |   - libpcap
2025-09-29 10:03:25.139379 | controller |   - libnl3-cli
2025-09-29 10:03:25.139492 | controller |   - libteam
2025-09-29 10:03:25.139624 | controller |   - libksba
2025-09-29 10:03:25.139737 | controller |   - libcomps
2025-09-29 10:03:25.139844 | controller |   - ethtool
2025-09-29 10:03:25.139953 | controller |   - libss
2025-09-29 10:03:25.140072 | controller |   - e2fsprogs
2025-09-29 10:03:25.140179 | controller |   - libxcrypt-compat
2025-09-29 10:03:25.140287 | controller |   - python3-pip-wheel
2025-09-29 10:03:25.140395 | controller |   - python-unversioned-command
2025-09-29 10:03:25.140511 | controller |   - python3
2025-09-29 10:03:25.140630 | controller |   - python3-libs
2025-09-29 10:03:25.140743 | controller |   - python3-setuptools
2025-09-29 10:03:25.140859 | controller |   - python3-six
2025-09-29 10:03:25.140958 | controller |   - python3-libselinux
2025-09-29 10:03:25.141084 | controller |   - python3-pyyaml
2025-09-29 10:03:25.141192 | controller |   - python3-distro
2025-09-29 10:03:25.141300 | controller |   - python3-idna
2025-09-29 10:03:25.141406 | controller |   - python3-systemd
2025-09-29 10:03:25.141518 | controller |   - python3-audit
2025-09-29 10:03:25.141651 | controller |   - python3-libsemanage
2025-09-29 10:03:25.141761 | controller |   - python3-dateutil
2025-09-29 10:03:25.141870 | controller |   - python3-configobj
2025-09-29 10:03:25.141985 | controller |   - python3-setools
2025-09-29 10:03:25.142097 | controller |   - python3-libcomps
2025-09-29 10:03:25.142204 | controller |   - cockpit-bridge
2025-09-29 10:03:25.142321 | controller |   - python3-chardet
2025-09-29 10:03:25.142430 | controller |   - python3-libxml2
2025-09-29 10:03:25.142561 | controller |   - python3-ptyprocess
2025-09-29 10:03:25.142681 | controller |   - python3-pexpect
2025-09-29 10:03:25.142787 | controller |   - python3-pysocks
2025-09-29 10:03:25.142894 | controller |   - python3-urllib3
2025-09-29 10:03:25.143014 | controller |   - python3-requests
2025-09-29 10:03:25.143122 | controller |   - python3-attrs
2025-09-29 10:03:25.143228 | controller |   - python3-file-magic
2025-09-29 10:03:25.143334 | controller |   - sos
2025-09-29 10:03:25.143442 | controller |   - python3-jsonpointer
2025-09-29 10:03:25.143570 | controller |   - python3-jsonpatch
2025-09-29 10:03:25.143685 | controller |   - python3-markupsafe
2025-09-29 10:03:25.143791 | controller |   - python3-netifaces
2025-09-29 10:03:25.143898 | controller |   - python3-oauthlib
2025-09-29 10:03:25.144024 | controller |   - python3-prettytable
2025-09-29 10:03:25.144133 | controller |   - python3-pyrsistent
2025-09-29 10:03:25.144240 | controller |   - python3-jsonschema
2025-09-29 10:03:25.144346 | controller |   - python3-pyserial
2025-09-29 10:03:25.144455 | controller |   - python3-pytz
2025-09-29 10:03:25.144578 | controller |   - python3-babel
2025-09-29 10:03:25.144694 | controller |   - python3-jinja2
2025-09-29 10:03:25.144799 | controller |   - gdisk
2025-09-29 10:03:25.144907 | controller |   - groff-base
2025-09-29 10:03:25.145028 | controller |   - snappy
2025-09-29 10:03:25.145135 | controller |   - libpng
2025-09-29 10:03:25.145240 | controller |   - pigz
2025-09-29 10:03:25.145346 | controller |   - c-ares
2025-09-29 10:03:25.145453 | controller |   - cpio
2025-09-29 10:03:25.145577 | controller |   - diffutils
2025-09-29 10:03:25.145691 | controller |   - hostname
2025-09-29 10:03:25.145798 | controller |   - inih
2025-09-29 10:03:25.145913 | controller |   - kernel-tools-libs
2025-09-29 10:03:25.146033 | controller |   - less
2025-09-29 10:03:25.146139 | controller |   - libatomic
2025-09-29 10:03:25.146247 | controller |   - libcbor
2025-09-29 10:03:25.146354 | controller |   - libdaemon
2025-09-29 10:03:25.146459 | controller |   - teamd
2025-09-29 10:03:25.146588 | controller |   - libdb
2025-09-29 10:03:25.146705 | controller |   - libeconf
2025-09-29 10:03:25.146810 | controller |   - libpwquality
2025-09-29 10:03:25.146916 | controller |   - pam
2025-09-29 10:03:25.147038 | controller |   - util-linux
2025-09-29 10:03:25.147146 | controller |   - grub2-common
2025-09-29 10:03:25.147253 | controller |   - openssh
2025-09-29 10:03:25.147360 | controller |   - grub2-pc-modules
2025-09-29 10:03:25.147466 | controller |   - kbd
2025-09-29 10:03:25.147591 | controller |   - libev
2025-09-29 10:03:25.147702 | controller |   - libverto-libev
2025-09-29 10:03:25.147808 | controller |   - libndp
2025-09-29 10:03:25.147914 | controller |   - libnfnetlink
2025-09-29 10:03:25.148040 | controller |   - libnetfilter_conntrack
2025-09-29 10:03:25.148148 | controller |   - iptables-libs
2025-09-29 10:03:25.148255 | controller |   - libnghttp2
2025-09-29 10:03:25.148360 | controller |   - libpipeline
2025-09-29 10:03:25.148467 | controller |   - libsss_nss_idmap
2025-09-29 10:03:25.148592 | controller |   - sssd-client
2025-09-29 10:03:25.148701 | controller |   - libsss_sudo
2025-09-29 10:03:25.148809 | controller |   - libtool-ltdl
2025-09-29 10:03:25.148913 | controller |   - openldap
2025-09-29 10:03:25.149032 | controller |   - libcurl
2025-09-29 10:03:25.149140 | controller |   - tpm2-tss
2025-09-29 10:03:25.149247 | controller |   - libnfsidmap
2025-09-29 10:03:25.149356 | controller |   - sssd-nfs-idmap
2025-09-29 10:03:25.149463 | controller |   - ima-evm-utils
2025-09-29 10:03:25.149591 | controller |   - curl
2025-09-29 10:03:25.149702 | controller |   - rpm
2025-09-29 10:03:25.149807 | controller |   - rpm-libs
2025-09-29 10:03:25.149915 | controller |   - policycoreutils
2025-09-29 10:03:25.150045 | controller |   - selinux-policy
2025-09-29 10:03:25.150153 | controller |   - selinux-policy-targeted
2025-09-29 10:03:25.150259 | controller |   - libsolv
2025-09-29 10:03:25.150367 | controller |   - cockpit-ws-selinux
2025-09-29 10:03:25.150472 | controller |   - rpm-plugin-systemd-inhibit
2025-09-29 10:03:25.150598 | controller |   - sudo
2025-09-29 10:03:25.150710 | controller |   - lmdb-libs
2025-09-29 10:03:25.150818 | controller |   - nettle
2025-09-29 10:03:25.150921 | controller |   - gnutls
2025-09-29 10:03:25.151040 | controller |   - glib2
2025-09-29 10:03:25.151146 | controller |   - python3-dbus
2025-09-29 10:03:25.151251 | controller |   - polkit-libs
2025-09-29 10:03:25.151358 | controller |   - NetworkManager-libnm
2025-09-29 10:03:25.151463 | controller |   - gobject-introspection
2025-09-29 10:03:25.151588 | controller |   - python3-gobject-base-noarch
2025-09-29 10:03:25.151700 | controller |   - python3-gobject-base
2025-09-29 10:03:25.151807 | controller |   - json-glib
2025-09-29 10:03:25.151918 | controller |   - libmodulemd
2025-09-29 10:03:25.152042 | controller |   - shared-mime-info
2025-09-29 10:03:25.152150 | controller |   - python3-dasbus
2025-09-29 10:03:25.152256 | controller |   - gsettings-desktop-schemas
2025-09-29 10:03:25.152363 | controller |   - libuser
2025-09-29 10:03:25.152469 | controller |   - webkit2gtk3-jsc
2025-09-29 10:03:25.152596 | controller |   - libproxy-webkitgtk4
2025-09-29 10:03:25.152709 | controller |   - glib-networking
2025-09-29 10:03:25.152817 | controller |   - libsoup
2025-09-29 10:03:25.152919 | controller |   - npth
2025-09-29 10:03:25.153061 | controller |   - gnupg2
2025-09-29 10:03:25.153151 | controller |   - gpgme
2025-09-29 10:03:25.153258 | controller |   - librepo
2025-09-29 10:03:25.153387 | controller |   - libdnf
2025-09-29 10:03:25.153565 | controller |   - python3-libdnf
2025-09-29 10:03:25.153708 | controller |   - python3-hawkey
2025-09-29 10:03:25.153837 | controller |   - python3-gpg
2025-09-29 10:03:25.153973 | controller |   - rpm-sign-libs
2025-09-29 10:03:25.154120 | controller |   - oniguruma
2025-09-29 10:03:25.154249 | controller |   - jq
2025-09-29 10:03:25.154377 | controller |   - pciutils-libs
2025-09-29 10:03:25.154530 | controller |   - sg3_utils-libs
2025-09-29 10:03:25.154674 | controller |   - slang
2025-09-29 10:03:25.154807 | controller |   - newt
2025-09-29 10:03:25.154935 | controller |   - userspace-rcu
2025-09-29 10:03:25.155078 | controller |   - checkpolicy
2025-09-29 10:03:25.155206 | controller |   - python3-policycoreutils
2025-09-29 10:03:25.155332 | controller |   - policycoreutils-python-utils
2025-09-29 10:03:25.155459 | controller |   - libestr
2025-09-29 10:03:25.155614 | controller |   - libfastjson
2025-09-29 10:03:25.155749 | controller |   - libjpeg-turbo
2025-09-29 10:03:25.155877 | controller |   - gdk-pixbuf2
2025-09-29 10:03:25.156017 | controller |   - libmaxminddb
2025-09-29 10:03:25.156146 | controller |   - ipcalc
2025-09-29 10:03:25.156272 | controller |   - libstemmer
2025-09-29 10:03:25.156405 | controller |   - libappstream-glib
2025-09-29 10:03:25.156556 | controller |   - liburing
2025-09-29 10:03:25.156692 | controller |   - langpacks-core-en_GB
2025-09-29 10:03:25.156819 | controller |   - systemd-rpm-macros
2025-09-29 10:03:25.156946 | controller |   - dbus
2025-09-29 10:03:25.157088 | controller |   - systemd-pam
2025-09-29 10:03:25.157213 | controller |   - systemd
2025-09-29 10:03:25.157338 | controller |   - dbus-common
2025-09-29 10:03:25.157465 | controller |   - dbus-broker
2025-09-29 10:03:25.157613 | controller |   - iputils
2025-09-29 10:03:25.157745 | controller |   - dhcp-client
2025-09-29 10:03:25.157871 | controller |   - cronie-anacron
2025-09-29 10:03:25.158009 | controller |   - cronie
2025-09-29 10:03:25.158138 | controller |   - crontabs
2025-09-29 10:03:25.158264 | controller |   - initscripts-service
2025-09-29 10:03:25.158388 | controller |   - polkit
2025-09-29 10:03:25.158537 | controller |   - polkit-pkla-compat
2025-09-29 10:03:25.158684 | controller |   - samba-common
2025-09-29 10:03:25.158815 | controller |   - libldb
2025-09-29 10:03:25.158943 | controller |   - libwbclient
2025-09-29 10:03:25.159087 | controller |   - samba-client-libs
2025-09-29 10:03:25.159216 | controller |   - samba-common-libs
2025-09-29 10:03:25.159342 | controller |   - sssd-common
2025-09-29 10:03:25.159466 | controller |   - audit
2025-09-29 10:03:25.159615 | controller |   - authselect-libs
2025-09-29 10:03:25.159746 | controller |   - device-mapper-libs
2025-09-29 10:03:25.159873 | controller |   - device-mapper
2025-09-29 10:03:25.160019 | controller |   - grub2-tools-minimal
2025-09-29 10:03:25.160149 | controller |   - cryptsetup-libs
2025-09-29 10:03:25.160274 | controller |   - systemd-udev
2025-09-29 10:03:25.160401 | controller |   - NetworkManager
2025-09-29 10:03:25.160546 | controller |   - NetworkManager-team
2025-09-29 10:03:25.160683 | controller |   - libfido2
2025-09-29 10:03:25.160816 | controller |   - os-prober
2025-09-29 10:03:25.160945 | controller |   - kpartx
2025-09-29 10:03:25.161088 | controller |   - xfsprogs
2025-09-29 10:03:25.161262 | controller |   - elfutils-default-yama-scope
2025-09-29 10:03:25.161391 | controller |   - elfutils-libs
2025-09-29 10:03:25.161538 | controller |   - elfutils-debuginfod-client
2025-09-29 10:03:25.161677 | controller |   - binutils-gold
2025-09-29 10:03:25.161805 | controller |   - binutils
2025-09-29 10:03:25.161938 | controller |   - rpm-build-libs
2025-09-29 10:03:25.162080 | controller |   - python3-rpm
2025-09-29 10:03:25.162206 | controller |   - python3-dnf
2025-09-29 10:03:25.162330 | controller |   - dnf
2025-09-29 10:03:25.162465 | controller |   - setroubleshoot-plugins
2025-09-29 10:03:25.162617 | controller |   - setroubleshoot-server
2025-09-29 10:03:25.162757 | controller |   - python3-dnf-plugins-core
2025-09-29 10:03:25.162896 | controller |   - dnf-plugins-core
2025-09-29 10:03:25.163041 | controller |   - gssproxy
2025-09-29 10:03:25.163183 | controller |   - libkcapi
2025-09-29 10:03:25.163312 | controller |   - libkcapi-hmaccalc
2025-09-29 10:03:25.163440 | controller |   - dracut
2025-09-29 10:03:25.163604 | controller |   - kernel-modules-core
2025-09-29 10:03:25.163743 | controller |   - kernel-core
2025-09-29 10:03:25.163871 | controller |   - grub2-tools
2025-09-29 10:03:25.164013 | controller |   - grubby
2025-09-29 10:03:25.164143 | controller |   - kernel-modules
2025-09-29 10:03:25.164279 | controller |   - dracut-network
2025-09-29 10:03:25.164406 | controller |   - dracut-squash
2025-09-29 10:03:25.164553 | controller |   - kexec-tools
2025-09-29 10:03:25.164687 | controller |   - logrotate
2025-09-29 10:03:25.164815 | controller |   - rsyslog-logrotate
2025-09-29 10:03:25.164940 | controller |   - rsyslog
2025-09-29 10:03:25.165081 | controller |   - rpcbind
2025-09-29 10:03:25.165208 | controller |   - oddjob
2025-09-29 10:03:25.165352 | controller |   - oddjob-mkhomedir
2025-09-29 10:03:25.165485 | controller |   - authselect
2025-09-29 10:03:25.165637 | controller |   - PackageKit-glib
2025-09-29 10:03:25.165770 | controller |   - PackageKit
2025-09-29 10:03:25.165898 | controller |   - cockpit-system
2025-09-29 10:03:25.166039 | controller |   - authselect-compat
2025-09-29 10:03:25.166169 | controller |   - nfs-utils
2025-09-29 10:03:25.166296 | controller |   - kernel
2025-09-29 10:03:25.166422 | controller |   - crypto-policies-scripts
2025-09-29 10:03:25.166572 | controller |   - grub2-pc
2025-09-29 10:03:25.166713 | controller |   - dracut-config-generic
2025-09-29 10:03:25.166840 | controller |   - yum-utils
2025-09-29 10:03:25.166966 | controller |   - yum
2025-09-29 10:03:25.167109 | controller |   - cloud-init
2025-09-29 10:03:25.167238 | controller |   - openssh-clients
2025-09-29 10:03:25.167365 | controller |   - NetworkManager-tui
2025-09-29 10:03:25.167497 | controller |   - parted
2025-09-29 10:03:25.167662 | controller |   - sssd-kcm
2025-09-29 10:03:25.167790 | controller |   - chrony
2025-09-29 10:03:25.167925 | controller |   - microcode_ctl
2025-09-29 10:03:25.168067 | controller |   - openssh-server
2025-09-29 10:03:25.168195 | controller |   - qemu-guest-agent
2025-09-29 10:03:25.168320 | controller |   - langpacks-en_GB
2025-09-29 10:03:25.168446 | controller |   - sg3_utils
2025-09-29 10:03:25.168594 | controller |   - kernel-tools
2025-09-29 10:03:25.168729 | controller |   - cockpit-ws
2025-09-29 10:03:25.168857 | controller |   - passwd
2025-09-29 10:03:25.168993 | controller |   - initscripts-rename-device
2025-09-29 10:03:25.169126 | controller |   - irqbalance
2025-09-29 10:03:25.169254 | controller |   - rpm-plugin-selinux
2025-09-29 10:03:25.169379 | controller |   - rpm-plugin-audit
2025-09-29 10:03:25.169517 | controller |   - man-db
2025-09-29 10:03:25.169659 | controller |   - iproute-tc
2025-09-29 10:03:25.169797 | controller |   - cloud-utils-growpart
2025-09-29 10:03:25.169933 | controller |   - tcpdump
2025-09-29 10:03:25.170085 | controller |   - prefixdevname
2025-09-29 10:03:25.170229 | controller |   - rsync
2025-09-29 10:03:25.170367 | controller |   - tar
2025-09-29 10:03:25.170492 | controller |   - vim-minimal
2025-09-29 10:03:25.170646 | controller |   - lshw
2025-09-29 10:03:25.170774 | controller |   - libsysfs
2025-09-29 10:03:25.170898 | controller |   - lsscsi
2025-09-29 10:03:25.171041 | controller |   - ncurses
2025-09-29 10:03:25.171169 | controller |   - rootfiles
2025-09-29 10:03:25.171295 | controller |   - gpg-pubkey
2025-09-29 10:03:25.171420 | controller |   - git-core
2025-09-29 10:03:25.171568 | controller |   - git-core-doc
2025-09-29 10:03:25.171700 | controller |   - perl-Digest
2025-09-29 10:03:25.171827 | controller |   - perl-Digest-MD5
2025-09-29 10:03:25.171951 | controller |   - perl-B
2025-09-29 10:03:25.172095 | controller |   - perl-FileHandle
2025-09-29 10:03:25.172221 | controller |   - perl-Data-Dumper
2025-09-29 10:03:25.172347 | controller |   - perl-libnet
2025-09-29 10:03:25.172472 | controller |   - perl-base
2025-09-29 10:03:25.172618 | controller |   - perl-URI
2025-09-29 10:03:25.172757 | controller |   - perl-AutoLoader
2025-09-29 10:03:25.172893 | controller |   - perl-Mozilla-CA
2025-09-29 10:03:25.173038 | controller |   - perl-if
2025-09-29 10:03:25.173167 | controller |   - perl-IO-Socket-IP
2025-09-29 10:03:25.173300 | controller |   - perl-Time-Local
2025-09-29 10:03:25.173426 | controller |   - perl-File-Path
2025-09-29 10:03:25.173576 | controller |   - perl-Pod-Escapes
2025-09-29 10:03:25.173711 | controller |   - perl-Text-Tabs+Wrap
2025-09-29 10:03:25.173836 | controller |   - perl-IO-Socket-SSL
2025-09-29 10:03:25.173961 | controller |   - perl-Net-SSLeay
2025-09-29 10:03:25.174100 | controller |   - perl-Class-Struct
2025-09-29 10:03:25.174226 | controller |   - perl-POSIX
2025-09-29 10:03:25.174354 | controller |   - perl-Term-ANSIColor
2025-09-29 10:03:25.174486 | controller |   - perl-IPC-Open3
2025-09-29 10:03:25.174643 | controller |   - perl-subs
2025-09-29 10:03:25.174777 | controller |   - perl-File-Temp
2025-09-29 10:03:25.174903 | controller |   - perl-Term-Cap
2025-09-29 10:03:25.175044 | controller |   - perl-Pod-Simple
2025-09-29 10:03:25.175172 | controller |   - perl-HTTP-Tiny
2025-09-29 10:03:25.175298 | controller |   - perl-Socket
2025-09-29 10:03:25.175425 | controller |   - perl-SelectSaver
2025-09-29 10:03:25.175573 | controller |   - perl-Symbol
2025-09-29 10:03:25.175707 | controller |   - perl-File-stat
2025-09-29 10:03:25.175832 | controller |   - perl-podlators
2025-09-29 10:03:25.175957 | controller |   - perl-Pod-Perldoc
2025-09-29 10:03:25.176095 | controller |   - perl-Fcntl
2025-09-29 10:03:25.176223 | controller |   - perl-Text-ParseWords
2025-09-29 10:03:25.176351 | controller |   - perl-mro
2025-09-29 10:03:25.176476 | controller |   - perl-IO
2025-09-29 10:03:25.176633 | controller |   - perl-overloading
2025-09-29 10:03:25.176766 | controller |   - perl-Pod-Usage
2025-09-29 10:03:25.176892 | controller |   - perl-Errno
2025-09-29 10:03:25.177036 | controller |   - perl-File-Basename
2025-09-29 10:03:25.177169 | controller |   - perl-Getopt-Std
2025-09-29 10:03:25.177294 | controller |   - perl-MIME-Base64
2025-09-29 10:03:25.177420 | controller |   - perl-Scalar-List-Utils
2025-09-29 10:03:25.177567 | controller |   - perl-constant
2025-09-29 10:03:25.177699 | controller |   - perl-Storable
2025-09-29 10:03:25.177823 | controller |   - perl-overload
2025-09-29 10:03:25.177948 | controller |   - perl-parent
2025-09-29 10:03:25.178089 | controller |   - perl-vars
2025-09-29 10:03:25.178218 | controller |   - perl-Getopt-Long
2025-09-29 10:03:25.178352 | controller |   - perl-Carp
2025-09-29 10:03:25.178484 | controller |   - perl-Exporter
2025-09-29 10:03:25.178636 | controller |   - perl-NDBM_File
2025-09-29 10:03:25.178768 | controller |   - perl-PathTools
2025-09-29 10:03:25.178894 | controller |   - perl-Encode
2025-09-29 10:03:25.179038 | controller |   - perl-libs
2025-09-29 10:03:25.179166 | controller |   - perl-interpreter
2025-09-29 10:03:25.179301 | controller |   - perl-DynaLoader
2025-09-29 10:03:25.179435 | controller |   - perl-TermReadKey
2025-09-29 10:03:25.179591 | controller |   - perl-Error
2025-09-29 10:03:25.179723 | controller |   - perl-File-Find
2025-09-29 10:03:25.179849 | controller |   - perl-lib
2025-09-29 10:03:25.179975 | controller |   - emacs-filesystem
2025-09-29 10:03:25.180125 | controller |   - perl-Git
2025-09-29 10:03:25.180252 | controller |   - git
2025-09-29 10:03:25.180377 | controller |   - traceroute
2025-09-29 10:03:25.180519 | controller |   - ruby-libs
2025-09-29 10:03:25.180673 | controller |   - rubygem-bigdecimal
2025-09-29 10:03:25.180806 | controller |   - ruby-default-gems
2025-09-29 10:03:25.180939 | controller |   - rubygem-bundler
2025-09-29 10:03:25.181079 | controller |   - rubygem-io-console
2025-09-29 10:03:25.181206 | controller |   - rubygem-json
2025-09-29 10:03:25.181331 | controller |   - rubygem-psych
2025-09-29 10:03:25.181456 | controller |   - rubygem-rdoc
2025-09-29 10:03:25.181604 | controller |   - rubygems
2025-09-29 10:03:25.181734 | controller |   - ruby
2025-09-29 10:03:25.181858 | controller |   - libmpc
2025-09-29 10:03:25.181994 | controller |   - gdb-minimal
2025-09-29 10:03:25.182124 | controller |   - dwz
2025-09-29 10:03:25.182248 | controller |   - unzip
2025-09-29 10:03:25.182373 | controller |   - elfutils
2025-09-29 10:03:25.182497 | controller |   - bzip2
2025-09-29 10:03:25.182660 | controller |   - debugedit
2025-09-29 10:03:25.182790 | controller |   - zip
2025-09-29 10:03:25.182916 | controller |   - cpp
2025-09-29 10:03:25.183065 | controller |   - rust-srpm-macros
2025-09-29 10:03:25.183197 | controller |   - qt5-srpm-macros
2025-09-29 10:03:25.183323 | controller |   - python3-pip
2025-09-29 10:03:25.183448 | controller |   - perl-srpm-macros
2025-09-29 10:03:25.183595 | controller |   - openblas-srpm-macros
2025-09-29 10:03:25.183727 | controller |   - ocaml-srpm-macros
2025-09-29 10:03:25.183854 | controller |   - ncurses-c++-libs
2025-09-29 10:03:25.183992 | controller |   - lua-srpm-macros
2025-09-29 10:03:25.184123 | controller |   - llvm-filesystem
2025-09-29 10:03:25.184249 | controller |   - llvm-libs
2025-09-29 10:03:25.184374 | controller |   - libxslt
2025-09-29 10:03:25.184516 | controller |   - libstdc++-devel
2025-09-29 10:03:25.184920 | controller |   - kernel-srpm-macros
2025-09-29 10:03:25.185107 | controller |   - kernel-headers
2025-09-29 10:03:25.185262 | controller |   - hunspell-filesystem
2025-09-29 10:03:25.185404 | controller |   - hunspell-en-US
2025-09-29 10:03:25.185552 | controller |   - hunspell
2025-09-29 10:03:25.185703 | controller |   - enchant
2025-09-29 10:03:25.185809 | controller |   - python3-enchant
2025-09-29 10:03:25.185920 | controller |   - glibc-headers
2025-09-29 10:03:25.186046 | controller |   - ghc-srpm-macros
2025-09-29 10:03:25.186156 | controller |   - efi-srpm-macros
2025-09-29 10:03:25.186267 | controller |   - desktop-file-utils
2025-09-29 10:03:25.186376 | controller |   - createrepo_c-libs
2025-09-29 10:03:25.186483 | controller |   - cmake-filesystem
2025-09-29 10:03:25.186614 | controller |   - zstd
2025-09-29 10:03:25.186729 | controller |   - tcl
2025-09-29 10:03:25.186838 | controller |   - python3-pyparsing
2025-09-29 10:03:25.186944 | controller |   - python3-packaging
2025-09-29 10:03:25.187071 | controller |   - pkgconf-m4
2025-09-29 10:03:25.187184 | controller |   - make
2025-09-29 10:03:25.187291 | controller |   - libpkgconf
2025-09-29 10:03:25.187397 | controller |   - pkgconf
2025-09-29 10:03:25.187513 | controller |   - pkgconf-pkg-config
2025-09-29 10:03:25.187628 | controller |   - libgpg-error-devel
2025-09-29 10:03:25.187739 | controller |   - glibc-devel
2025-09-29 10:03:25.187846 | controller |   - libxcrypt-devel
2025-09-29 10:03:25.187954 | controller |   - gcc
2025-09-29 10:03:25.188081 | controller |   - annobin
2025-09-29 10:03:25.188189 | controller |   - gcc-plugin-annobin
2025-09-29 10:03:25.188295 | controller |   - ncurses-devel
2025-09-29 10:03:25.188402 | controller |   - xz-devel
2025-09-29 10:03:25.188517 | controller |   - zlib-devel
2025-09-29 10:03:25.188637 | controller |   - libxml2-devel
2025-09-29 10:03:25.188748 | controller |   - libnftnl
2025-09-29 10:03:25.188855 | controller |   - iptables-nft
2025-09-29 10:03:25.188959 | controller |   - info
2025-09-29 10:03:25.189082 | controller |   - ed
2025-09-29 10:03:25.189195 | controller |   - patch
2025-09-29 10:03:25.189301 | controller |   - fonts-srpm-macros
2025-09-29 10:03:25.189409 | controller |   - go-srpm-macros
2025-09-29 10:03:25.189530 | controller |   - python-srpm-macros
2025-09-29 10:03:25.189650 | controller |   - redhat-rpm-config
2025-09-29 10:03:25.189759 | controller |   - rpm-build
2025-09-29 10:03:25.189867 | controller |   - pyproject-srpm-macros
2025-09-29 10:03:25.189971 | controller |   - python-rpm-macros
2025-09-29 10:03:25.190094 | controller |   - python3-rpm-macros
2025-09-29 10:03:25.190198 | controller |   - python3-rpm-generators
2025-09-29 10:03:25.190303 | controller |   - cyrus-sasl
2025-09-29 10:03:25.190408 | controller |   - cyrus-sasl-devel
2025-09-29 10:03:25.190513 | controller |   - openldap-devel
2025-09-29 10:03:25.190653 | controller |   - python3-devel
2025-09-29 10:03:25.190761 | controller |   - ruby-devel
2025-09-29 10:03:25.190867 | controller |   - iptables-nft-services
2025-09-29 10:03:25.190973 | controller |   - libxslt-devel
2025-09-29 10:03:25.191093 | controller |   - readline-devel
2025-09-29 10:03:25.191200 | controller |   - gcc-c++
2025-09-29 10:03:25.191308 | controller |   - libffi-devel
2025-09-29 10:03:25.191416 | controller |   - openssl-devel
2025-09-29 10:03:25.191535 | controller |   - expect
2025-09-29 10:03:25.191653 | controller |   - createrepo_c
2025-09-29 10:03:25.191761 | controller |   - rpmlint
2025-09-29 10:03:25.191867 | controller |   - hunspell-en-GB
2025-09-29 10:03:25.191971 | controller |   - wget
2025-09-29 10:03:25.192090 | controller |   - systemd-devel
2025-09-29 10:03:25.192194 | controller |   - time
2025-09-29 10:03:25.192309 | controller |   - rpm-sign
2025-09-29 10:03:25.192414 | controller |   - passt
2025-09-29 10:03:25.192542 | controller |   - passt-selinux
2025-09-29 10:03:25.192657 | controller |   - fuse3-libs
2025-09-29 10:03:25.192764 | controller |   - container-selinux
2025-09-29 10:03:25.192869 | controller |   - protobuf-c
2025-09-29 10:03:25.192972 | controller |   - yajl
2025-09-29 10:03:25.193095 | controller |   - libslirp
2025-09-29 10:03:25.193203 | controller |   - slirp4netns
2025-09-29 10:03:25.193309 | controller |   - libnet
2025-09-29 10:03:25.193413 | controller |   - criu
2025-09-29 10:03:25.193532 | controller |   - criu-libs
2025-09-29 10:03:25.193652 | controller |   - crun
2025-09-29 10:03:25.193760 | controller |   - conmon
2025-09-29 10:03:25.193868 | controller |   - aardvark-dns
2025-09-29 10:03:25.193973 | controller |   - netavark
2025-09-29 10:03:25.194096 | controller |   - shadow-utils-subid
2025-09-29 10:03:25.194201 | controller |   - nftables
2025-09-29 10:03:25.194307 | controller |   - fuse-common
2025-09-29 10:03:25.194412 | controller |   - fuse3
2025-09-29 10:03:25.194531 | controller |   - fuse-overlayfs
2025-09-29 10:03:25.194654 | controller |   - containers-common
2025-09-29 10:03:25.194762 | controller |   - podman
2025-09-29 10:03:25.194868 | controller |   - cyrus-sasl-gssapi
2025-09-29 10:03:25.194972 | controller |   - libvirt-libs
2025-09-29 10:03:25.195098 | controller |   - python3-libvirt
2025-09-29 10:03:25.195203 | controller |   - python3-netaddr
2025-09-29 10:03:25.195307 | controller |   - python3-lxml
2025-09-29 10:03:25.195412 | controller |   - python3-jmespath
2025-09-29 10:03:25.195529 | controller |   - python3-pbr
2025-09-29 10:03:25.195646 | controller |   - python3-typing-extensions
2025-09-29 10:03:25.195761 | controller |   - python3-os-service-types
2025-09-29 10:03:25.195867 | controller |   - python3-stevedore
2025-09-29 10:03:25.195972 | controller |   - python3-iso8601
2025-09-29 10:03:25.196096 | controller |   - python3-decorator
2025-09-29 10:03:25.196208 | controller |   - python3-keystoneauth1
2025-09-29 10:03:25.196314 | controller |   - python3-platformdirs
2025-09-29 10:03:25.196420 | controller |   - python3-psutil
2025-09-29 10:03:25.196537 | controller |   - python3-mako
2025-09-29 10:03:25.196652 | controller |   - python3-dogpile-cache
2025-09-29 10:03:25.196757 | controller |   - python3-ply
2025-09-29 10:03:25.196861 | controller |   - python3-pycparser
2025-09-29 10:03:25.196964 | controller |   - python3-cffi
2025-09-29 10:03:25.197084 | controller |   - python3-cryptography
2025-09-29 10:03:25.197189 | controller |   - python3-requestsexceptions
2025-09-29 10:03:25.197293 | controller |   - python3-openstacksdk
2025-09-29 10:03:25.197521 | controller |
2025-09-29 10:03:25.197635 | controller | TASK [osp.edpm.env_data : Output installed repositories] ***********************
2025-09-29 10:03:25.197752 | controller | Monday 29 September 2025  10:03:25 +0000 (0:00:00.061)       0:01:53.227 ******
2025-09-29 10:03:25.259026 | controller | ok: [compute-1] =>
2025-09-29 10:03:25.259149 | controller |   msg: |-
2025-09-29 10:03:25.259273 | controller |     #### REPOSITORIES
2025-09-29 10:03:25.259386 | controller |     repo id                       repo name
2025-09-29 10:03:25.259516 | controller |     appstream                     CentOS Stream 9 - AppStream
2025-09-29 10:03:25.259623 | controller |     baseos                        CentOS Stream 9 - BaseOS
2025-09-29 10:03:25.259748 | controller |     crb                           CentOS Stream 9 - CRB
2025-09-29 10:03:25.259849 | controller |     extras-common                 CentOS Stream 9 - Extras packages
2025-09-29 10:03:25.259953 | controller |
2025-09-29 10:03:25.260084 | controller | PLAY [Setup DUT] ***************************************************************
2025-09-29 10:03:25.260190 | controller |
2025-09-29 10:03:25.260296 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 10:03:25.260420 | controller | Monday 29 September 2025  10:03:25 +0000 (0:00:00.116)       0:01:53.344 ******
2025-09-29 10:03:25.872093 | controller | ok: [compute-1]
2025-09-29 10:03:25.910916 | controller |
2025-09-29 10:03:25.910938 | controller | TASK [set basic user fact] *****************************************************
2025-09-29 10:03:25.910947 | controller | Monday 29 September 2025  10:03:25 +0000 (0:00:00.612)       0:01:53.956 ******
2025-09-29 10:03:25.910956 | controller | ok: [compute-1]
2025-09-29 10:03:26.001281 | controller |
2025-09-29 10:03:26.001310 | controller | TASK [set basic home fact] *****************************************************
2025-09-29 10:03:26.001318 | controller | Monday 29 September 2025  10:03:25 +0000 (0:00:00.039)       0:01:53.995 ******
2025-09-29 10:03:26.001329 | controller | skipping: [compute-1]
2025-09-29 10:03:33.278028 | controller |
2025-09-29 10:03:33.278058 | controller | TASK [Enable package repos] ****************************************************
2025-09-29 10:03:33.278066 | controller | Monday 29 September 2025  10:03:25 +0000 (0:00:00.089)       0:01:54.085 ******
2025-09-29 10:03:33.278077 | controller | changed: [compute-1]
2025-09-29 10:05:27.580733 | controller |
2025-09-29 10:05:27.580767 | controller | TASK [Install openstack-selinux] ***********************************************
2025-09-29 10:05:27.580776 | controller | Monday 29 September 2025  10:03:33 +0000 (0:00:07.276)       0:02:01.362 ******
2025-09-29 10:05:27.580786 | controller | changed: [compute-1]
2025-09-29 10:05:27.802264 | controller |
2025-09-29 10:05:27.802299 | controller | TASK [set /etc/localtime] ******************************************************
2025-09-29 10:05:27.802307 | controller | Monday 29 September 2025  10:05:27 +0000 (0:01:54.302)       0:03:55.665 ******
2025-09-29 10:05:27.802318 | controller | changed: [compute-1]
2025-09-29 10:05:27.832640 | controller |
2025-09-29 10:05:27.832682 | controller | TASK [osp.edpm.edpm_timezone : Validating arguments against arg spec 'main' - The main entry point for the edpm_timezone role.] ***
2025-09-29 10:05:27.832692 | controller | Monday 29 September 2025  10:05:27 +0000 (0:00:00.221)       0:03:55.886 ******
2025-09-29 10:05:27.832706 | controller | ok: [compute-1]
2025-09-29 10:05:27.832736 | controller |
2025-09-29 10:05:27.832746 | controller | TASK [osp.edpm.edpm_timezone : Gather ansible_local facts] *********************
2025-09-29 10:05:27.832872 | controller | Monday 29 September 2025  10:05:27 +0000 (0:00:00.030)       0:03:55.917 ******
2025-09-29 10:05:27.867407 | controller | skipping: [compute-1]
2025-09-29 10:05:27.916199 | controller |
2025-09-29 10:05:27.916226 | controller | TASK [osp.edpm.edpm_timezone : Check for /etc/localtime] ***********************
2025-09-29 10:05:27.916236 | controller | Monday 29 September 2025  10:05:27 +0000 (0:00:00.034)       0:03:55.952 ******
2025-09-29 10:05:27.916248 | controller | skipping: [compute-1]
2025-09-29 10:05:27.946292 | controller |
2025-09-29 10:05:27.946313 | controller | TASK [osp.edpm.edpm_timezone : Symlink /etc/localtime to timezone file if it doesn't exist] ***
2025-09-29 10:05:27.946322 | controller | Monday 29 September 2025  10:05:27 +0000 (0:00:00.048)       0:03:56.000 ******
2025-09-29 10:05:27.946333 | controller | skipping: [compute-1]
2025-09-29 10:05:28.328576 | controller |
2025-09-29 10:05:28.328612 | controller | TASK [osp.edpm.edpm_timezone : Set timezone] ***********************************
2025-09-29 10:05:28.328621 | controller | Monday 29 September 2025  10:05:27 +0000 (0:00:00.030)       0:03:56.030 ******
2025-09-29 10:05:28.328637 | controller | ok: [compute-1]
2025-09-29 10:05:28.359044 | controller |
2025-09-29 10:05:28.359093 | controller | TASK [osp.edpm.edpm_timezone : Set fact for timezone result] *******************
2025-09-29 10:05:28.359103 | controller | Monday 29 September 2025  10:05:28 +0000 (0:00:00.382)       0:03:56.412 ******
2025-09-29 10:05:28.359115 | controller | ok: [compute-1]
2025-09-29 10:05:29.308654 | controller |
2025-09-29 10:05:29.308687 | controller | TASK [osp.edpm.edpm_timezone : Restart time services] **************************
2025-09-29 10:05:29.308695 | controller | Monday 29 September 2025  10:05:28 +0000 (0:00:00.030)       0:03:56.443 ******
2025-09-29 10:05:29.308705 | controller | changed: [compute-1] => (item=rsyslog)
2025-09-29 10:05:29.308712 | controller | changed: [compute-1] => (item=crond)
2025-09-29 10:05:29.308718 | controller |
2025-09-29 10:05:29.308723 | controller | TASK [osp.edpm.edpm_podman : Validating arguments against arg spec 'main' - The main entry point for the edpm_podman role.] ***
2025-09-29 10:05:29.308731 | controller | Monday 29 September 2025  10:05:29 +0000 (0:00:00.949)       0:03:57.392 ******
2025-09-29 10:05:29.354457 | controller | ok: [compute-1]
2025-09-29 10:05:29.644624 | controller |
2025-09-29 10:05:29.644657 | controller | TASK [osp.edpm.edpm_podman : Gather ansible_local facts] ***********************
2025-09-29 10:05:29.644667 | controller | Monday 29 September 2025  10:05:29 +0000 (0:00:00.046)       0:03:57.439 ******
2025-09-29 10:05:29.644679 | controller | ok: [compute-1]
2025-09-29 10:05:29.644710 | controller |
2025-09-29 10:05:29.644907 | controller | TASK [osp.edpm.edpm_podman : Ensure podman and deps are installed] *************
2025-09-29 10:05:30.917317 | controller | Monday 29 September 2025  10:05:29 +0000 (0:00:00.290)       0:03:57.729 ******
2025-09-29 10:05:30.917351 | controller | ok: [compute-1]
2025-09-29 10:05:30.954083 | controller |
2025-09-29 10:05:30.954109 | controller | TASK [osp.edpm.edpm_podman : Ensure we get the ansible interfaces facts] *******
2025-09-29 10:05:30.954117 | controller | Monday 29 September 2025  10:05:30 +0000 (0:00:01.272)       0:03:59.001 ******
2025-09-29 10:05:30.954127 | controller | skipping: [compute-1]
2025-09-29 10:05:30.991600 | controller |
2025-09-29 10:05:30.991635 | controller | TASK [osp.edpm.edpm_podman : Delete legacy cni0 interface (podman < 1.6)] ******
2025-09-29 10:05:30.991645 | controller | Monday 29 September 2025  10:05:30 +0000 (0:00:00.036)       0:03:59.038 ******
2025-09-29 10:05:30.991656 | controller | skipping: [compute-1]
2025-09-29 10:05:30.991686 | controller |
2025-09-29 10:05:30.991951 | controller | TASK [osp.edpm.edpm_podman : Make sure /etc/cni/net.d folder exists] ***********
2025-09-29 10:05:31.029285 | controller | Monday 29 September 2025  10:05:30 +0000 (0:00:00.037)       0:03:59.076 ******
2025-09-29 10:05:31.029318 | controller | skipping: [compute-1]
2025-09-29 10:05:31.067786 | controller |
2025-09-29 10:05:31.067815 | controller | TASK [osp.edpm.edpm_podman : Update default network configuration if possible] ***
2025-09-29 10:05:31.067823 | controller | Monday 29 September 2025  10:05:31 +0000 (0:00:00.037)       0:03:59.113 ******
2025-09-29 10:05:31.067833 | controller | skipping: [compute-1]
2025-09-29 10:05:31.293135 | controller |
2025-09-29 10:05:31.293165 | controller | TASK [osp.edpm.edpm_podman : Make sure /etc/containers/networks exists] ********
2025-09-29 10:05:31.293173 | controller | Monday 29 September 2025  10:05:31 +0000 (0:00:00.038)       0:03:59.152 ******
2025-09-29 10:05:31.293187 | controller | changed: [compute-1]
2025-09-29 10:05:31.582593 | controller |
2025-09-29 10:05:31.582623 | controller | TASK [osp.edpm.edpm_podman : Get current podman network] ***********************
2025-09-29 10:05:31.582632 | controller | Monday 29 September 2025  10:05:31 +0000 (0:00:00.225)       0:03:59.377 ******
2025-09-29 10:05:31.582642 | controller | changed: [compute-1]
2025-09-29 10:05:31.582665 | controller |
2025-09-29 10:05:31.582673 | controller | TASK [osp.edpm.edpm_podman : Update the default network configuration] *********
2025-09-29 10:05:31.582819 | controller | Monday 29 September 2025  10:05:31 +0000 (0:00:00.289)       0:03:59.667 ******
2025-09-29 10:05:31.981399 | controller | changed: [compute-1]
2025-09-29 10:05:32.414133 | controller |
2025-09-29 10:05:32.414162 | controller | TASK [osp.edpm.edpm_podman : Write containers registries.conf] *****************
2025-09-29 10:05:32.414171 | controller | Monday 29 September 2025  10:05:31 +0000 (0:00:00.398)       0:04:00.065 ******
2025-09-29 10:05:32.414181 | controller | changed: [compute-1]
2025-09-29 10:05:32.444597 | controller |
2025-09-29 10:05:32.444623 | controller | TASK [osp.edpm.edpm_podman : Write containers registries.conf for disconnected ocp] ***
2025-09-29 10:05:32.444632 | controller | Monday 29 September 2025  10:05:32 +0000 (0:00:00.432)       0:04:00.498 ******
2025-09-29 10:05:32.444643 | controller | skipping: [compute-1]
2025-09-29 10:05:33.283386 | controller |
2025-09-29 10:05:33.283415 | controller | TASK [osp.edpm.edpm_podman : Write containers.conf] ****************************
2025-09-29 10:05:33.283423 | controller | Monday 29 September 2025  10:05:32 +0000 (0:00:00.030)       0:04:00.529 ******
2025-09-29 10:05:33.283433 | controller | changed: [compute-1] => (item={'section': 'containers', 'option': 'pids_limit', 'value': 4096})
2025-09-29 10:05:33.322170 | controller | changed: [compute-1] => (item={'section': 'engine', 'option': 'events_logger', 'value': '"journald"'})
2025-09-29 10:05:33.322197 | controller | changed: [compute-1] => (item={'section': 'engine', 'option': 'runtime', 'value': '"crun"'})
2025-09-29 10:05:33.322206 | controller | changed: [compute-1] => (item={'section': 'network', 'option': 'network_backend', 'value': '"netavark"'})
2025-09-29 10:05:33.322213 | controller |
2025-09-29 10:05:33.322221 | controller | TASK [osp.edpm.edpm_podman : Enable podman.socket service] *********************
2025-09-29 10:05:33.322234 | controller | Monday 29 September 2025  10:05:33 +0000 (0:00:00.838)       0:04:01.367 ******
2025-09-29 10:05:33.322246 | controller | skipping: [compute-1]
2025-09-29 10:05:33.634967 | controller |
2025-09-29 10:05:33.635003 | controller | TASK [osp.edpm.edpm_podman : Check rsyslog service state] **********************
2025-09-29 10:05:33.635011 | controller | Monday 29 September 2025  10:05:33 +0000 (0:00:00.039)       0:04:01.406 ******
2025-09-29 10:05:33.635021 | controller | ok: [compute-1]
2025-09-29 10:05:33.954114 | controller |
2025-09-29 10:05:33.954144 | controller | TASK [osp.edpm.edpm_podman : Forward logging to podman.log file] ***************
2025-09-29 10:05:33.954152 | controller | Monday 29 September 2025  10:05:33 +0000 (0:00:00.312)       0:04:01.719 ******
2025-09-29 10:05:33.954162 | controller | changed: [compute-1]
2025-09-29 10:05:34.185229 | controller |
2025-09-29 10:05:34.185258 | controller | TASK [osp.edpm.edpm_podman : Clean podman images] ******************************
2025-09-29 10:05:34.185266 | controller | Monday 29 September 2025  10:05:33 +0000 (0:00:00.319)       0:04:02.038 ******
2025-09-29 10:05:34.185276 | controller | changed: [compute-1]
2025-09-29 10:05:34.415137 | controller |
2025-09-29 10:05:34.415167 | controller | TASK [osp.edpm.edpm_podman : Clean podman volumes] *****************************
2025-09-29 10:05:34.415175 | controller | Monday 29 September 2025  10:05:34 +0000 (0:00:00.230)       0:04:02.269 ******
2025-09-29 10:05:34.415186 | controller | changed: [compute-1]
2025-09-29 10:05:34.454596 | controller |
2025-09-29 10:05:34.454615 | controller | TASK [osp.edpm.edpm_podman : Gather user fact] *********************************
2025-09-29 10:05:34.454623 | controller | Monday 29 September 2025  10:05:34 +0000 (0:00:00.230)       0:04:02.499 ******
2025-09-29 10:05:34.454632 | controller | skipping: [compute-1]
2025-09-29 10:05:34.454657 | controller |
2025-09-29 10:05:34.454664 | controller | TASK [osp.edpm.edpm_podman : Ensure presence of authfile dir] ******************
2025-09-29 10:05:34.454671 | controller | Monday 29 September 2025  10:05:34 +0000 (0:00:00.039)       0:04:02.539 ******
2025-09-29 10:05:34.515902 | controller | skipping: [compute-1]
2025-09-29 10:05:34.515961 | controller |
2025-09-29 10:05:34.516203 | controller | TASK [osp.edpm.edpm_podman : Ensure presence of authfile] **********************
2025-09-29 10:05:34.558007 | controller | Monday 29 September 2025  10:05:34 +0000 (0:00:00.061)       0:04:02.600 ******
2025-09-29 10:05:34.558027 | controller | skipping: [compute-1]
2025-09-29 10:05:34.587601 | controller |
2025-09-29 10:05:34.587624 | controller | TASK [osp.edpm.edpm_podman : Perform container registry login(s) with podman] ***
2025-09-29 10:05:34.587633 | controller | Monday 29 September 2025  10:05:34 +0000 (0:00:00.041)       0:04:02.642 ******
2025-09-29 10:05:34.587644 | controller | skipping: [compute-1]
2025-09-29 10:05:34.587670 | controller |
2025-09-29 10:05:34.587680 | controller | TASK [osp.edpm.edpm_podman : Ensure buildah is installed] **********************
2025-09-29 10:05:34.587825 | controller | Monday 29 September 2025  10:05:34 +0000 (0:00:00.029)       0:04:02.672 ******
2025-09-29 10:05:34.629798 | controller | skipping: [compute-1]
2025-09-29 10:05:34.629847 | controller |
2025-09-29 10:05:34.630122 | controller | TASK [osp.edpm.edpm_podman : Gather user fact] *********************************
2025-09-29 10:05:34.669427 | controller | Monday 29 September 2025  10:05:34 +0000 (0:00:00.042)       0:04:02.714 ******
2025-09-29 10:05:34.669457 | controller | skipping: [compute-1]
2025-09-29 10:05:34.708252 | controller |
2025-09-29 10:05:34.708271 | controller | TASK [osp.edpm.edpm_podman : Ensure presence of authfile dir] ******************
2025-09-29 10:05:34.708278 | controller | Monday 29 September 2025  10:05:34 +0000 (0:00:00.039)       0:04:02.754 ******
2025-09-29 10:05:34.708286 | controller | skipping: [compute-1]
2025-09-29 10:05:34.750464 | controller |
2025-09-29 10:05:34.750483 | controller | TASK [osp.edpm.edpm_podman : Ensure presence of authfile] **********************
2025-09-29 10:05:34.750491 | controller | Monday 29 September 2025  10:05:34 +0000 (0:00:00.038)       0:04:02.793 ******
2025-09-29 10:05:34.750500 | controller | skipping: [compute-1]
2025-09-29 10:05:34.796588 | controller |
2025-09-29 10:05:34.796651 | controller | TASK [osp.edpm.edpm_podman : Perform container registry login(s) with buildah] ***
2025-09-29 10:05:34.796661 | controller | Monday 29 September 2025  10:05:34 +0000 (0:00:00.042)       0:04:02.835 ******
2025-09-29 10:05:34.796677 | controller | skipping: [compute-1]
2025-09-29 10:05:35.006490 | controller |
2025-09-29 10:05:35.006540 | controller | TASK [Create firewall directory] ***********************************************
2025-09-29 10:05:35.006551 | controller | Monday 29 September 2025  10:05:34 +0000 (0:00:00.045)       0:04:02.881 ******
2025-09-29 10:05:35.006564 | controller | changed: [compute-1]
2025-09-29 10:05:35.388337 | controller |
2025-09-29 10:05:35.388382 | controller | TASK [open port 22 (edpm_nftables will active this later)] *********************
2025-09-29 10:05:35.388391 | controller | Monday 29 September 2025  10:05:35 +0000 (0:00:00.210)       0:04:03.091 ******
2025-09-29 10:05:35.388409 | controller | changed: [compute-1]
2025-09-29 10:05:35.443926 | controller |
2025-09-29 10:05:35.443958 | controller | TASK [osp.edpm.edpm_libvirt : Validating arguments against arg spec 'main' - The main entry point for the edpm_libvirt role.] ***
2025-09-29 10:05:35.443968 | controller | Monday 29 September 2025  10:05:35 +0000 (0:00:00.381)       0:04:03.472 ******
2025-09-29 10:05:35.443981 | controller | ok: [compute-1]
2025-09-29 10:05:35.490609 | controller |
2025-09-29 10:05:35.490650 | controller | TASK [osp.edpm.edpm_libvirt : Adopt libvirt from tripleo] **********************
2025-09-29 10:05:35.490667 | controller | Monday 29 September 2025  10:05:35 +0000 (0:00:00.055)       0:04:03.528 ******
2025-09-29 10:05:35.490681 | controller | included: /home/zuul/.cache/ansible-compat/7dd456/collections/ansible_collections/osp/edpm/roles/edpm_libvirt/tasks/adoption.yml for compute-1
2025-09-29 10:05:35.716459 | controller |
2025-09-29 10:05:35.716489 | controller | TASK [osp.edpm.edpm_libvirt : Check if nova_virtlogd container exists] *********
2025-09-29 10:05:35.716498 | controller | Monday 29 September 2025  10:05:35 +0000 (0:00:00.046)       0:04:03.575 ******
2025-09-29 10:05:35.716538 | controller | ok: [compute-1]
2025-09-29 10:05:35.759167 | controller |
2025-09-29 10:05:35.759192 | controller | TASK [osp.edpm.edpm_libvirt : Stop nova_virtlogd containers not managed by service units] ***
2025-09-29 10:05:35.759200 | controller | Monday 29 September 2025  10:05:35 +0000 (0:00:00.225)       0:04:03.801 ******
2025-09-29 10:05:35.759217 | controller | skipping: [compute-1]
2025-09-29 10:05:35.833073 | controller |
2025-09-29 10:05:35.833105 | controller | TASK [Stop and disable libvirt services] ***************************************
2025-09-29 10:05:35.833115 | controller | Monday 29 September 2025  10:05:35 +0000 (0:00:00.042)       0:04:03.843 ******
2025-09-29 10:05:35.833127 | controller |
2025-09-29 10:05:35.866311 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Validating arguments against arg spec 'main' - The main entry point for the edpm_tripleo_cleanup role.] ***
2025-09-29 10:05:35.866337 | controller | Monday 29 September 2025  10:05:35 +0000 (0:00:00.074)       0:04:03.917 ******
2025-09-29 10:05:35.866348 | controller | ok: [compute-1]
2025-09-29 10:05:36.533003 | controller |
2025-09-29 10:05:36.533032 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reload units to check for new changes] ***
2025-09-29 10:05:36.533040 | controller | Monday 29 September 2025  10:05:35 +0000 (0:00:00.032)       0:04:03.950 ******
2025-09-29 10:05:36.533050 | controller | ok: [compute-1]
2025-09-29 10:05:38.328304 | controller |
2025-09-29 10:05:38.328333 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Gather service facts] ********************
2025-09-29 10:05:38.328341 | controller | Monday 29 September 2025  10:05:36 +0000 (0:00:00.666)       0:04:04.617 ******
2025-09-29 10:05:38.328351 | controller | ok: [compute-1]
2025-09-29 10:05:38.362666 | controller |
2025-09-29 10:05:38.362710 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Set list of tripleo services] ************
2025-09-29 10:05:38.362720 | controller | Monday 29 September 2025  10:05:38 +0000 (0:00:01.795)       0:04:06.412 ******
2025-09-29 10:05:38.362734 | controller | ok: [compute-1]
2025-09-29 10:05:38.362756 | controller |
2025-09-29 10:05:38.362764 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Get all services] ************************
2025-09-29 10:05:38.362773 | controller | Monday 29 September 2025  10:05:38 +0000 (0:00:00.034)       0:04:06.447 ******
2025-09-29 10:05:38.404558 | controller | skipping: [compute-1]
2025-09-29 10:05:38.448630 | controller |
2025-09-29 10:05:38.448668 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Filter for tripleo services and skip the ones in skip list] ***
2025-09-29 10:05:38.448677 | controller | Monday 29 September 2025  10:05:38 +0000 (0:00:00.041)       0:04:06.489 ******
2025-09-29 10:05:38.448691 | controller | skipping: [compute-1]
2025-09-29 10:05:40.533622 | controller |
2025-09-29 10:05:40.533656 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Stop and disable tripleo services] *******
2025-09-29 10:05:40.533665 | controller | Monday 29 September 2025  10:05:38 +0000 (0:00:00.044)       0:04:06.533 ******
2025-09-29 10:05:40.533675 | controller | ok: [compute-1] => (item=tripleo_nova_libvirt.target)
2025-09-29 10:05:40.533699 | controller | ok: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2025-09-29 10:05:40.533708 | controller | ok: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2025-09-29 10:05:40.534475 | controller | ok: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2025-09-29 10:05:40.617662 | controller | ok: [compute-1] => (item=tripleo_nova_virtqemud.service)
2025-09-29 10:05:40.617707 | controller | ok: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2025-09-29 10:05:40.617716 | controller | ok: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2025-09-29 10:05:40.617724 | controller |
2025-09-29 10:05:40.617731 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Check for errors] ************************
2025-09-29 10:05:40.617738 | controller | Monday 29 September 2025  10:05:40 +0000 (0:00:02.084)       0:04:08.618 ******
2025-09-29 10:05:40.617752 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_libvirt.target: host', 'invocation': {'module_args': {'name': 'tripleo_nova_libvirt.target', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_libvirt.target', 'ansible_loop_var': 'item'})
2025-09-29 10:05:40.617785 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_virtlogd_wrapper.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_virtlogd_wrapper.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_virtlogd_wrapper.service', 'ansible_loop_var': 'item'})
2025-09-29 10:05:40.617800 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_virtnodedevd.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_virtnodedevd.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_virtnodedevd.service', 'ansible_loop_var': 'item'})
2025-09-29 10:05:40.617807 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_virtproxyd.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_virtproxyd.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_virtproxyd.service', 'ansible_loop_var': 'item'})
2025-09-29 10:05:40.617815 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_virtqemud.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_virtqemud.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_virtqemud.service', 'ansible_loop_var': 'item'})
2025-09-29 10:05:40.617822 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_virtsecretd.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_virtsecretd.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_virtsecretd.service', 'ansible_loop_var': 'item'})
2025-09-29 10:05:40.617836 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_virtstoraged.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_virtstoraged.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_virtstoraged.service', 'ansible_loop_var': 'item'})
2025-09-29 10:05:40.617843 | controller | skipping: [compute-1]
2025-09-29 10:05:40.617852 | controller |
2025-09-29 10:05:40.617880 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files] ***********************
2025-09-29 10:05:40.617961 | controller | Monday 29 September 2025  10:05:40 +0000 (0:00:00.083)       0:04:08.701 ******
2025-09-29 10:05:40.680014 | controller | included: /home/zuul/.cache/ansible-compat/7dd456/collections/ansible_collections/osp/edpm/roles/edpm_tripleo_cleanup/tasks/remove_unit_files.yml for compute-1 => (item=/usr/lib/systemd/system)
2025-09-29 10:05:40.680294 | controller | included: /home/zuul/.cache/ansible-compat/7dd456/collections/ansible_collections/osp/edpm/roles/edpm_tripleo_cleanup/tasks/remove_unit_files.yml for compute-1 => (item=/etc/systemd/system)
2025-09-29 10:05:40.680475 | controller |
2025-09-29 10:05:40.680653 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /usr/lib/systemd/system] ***
2025-09-29 10:05:40.680804 | controller | Monday 29 September 2025  10:05:40 +0000 (0:00:00.063)       0:04:08.764 ******
2025-09-29 10:05:42.043632 | controller | ok: [compute-1] => (item=tripleo_nova_libvirt.target)
2025-09-29 10:05:42.043688 | controller | ok: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2025-09-29 10:05:42.043698 | controller | ok: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2025-09-29 10:05:42.043706 | controller | ok: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2025-09-29 10:05:42.043722 | controller | ok: [compute-1] => (item=tripleo_nova_virtqemud.service)
2025-09-29 10:05:42.043729 | controller | ok: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2025-09-29 10:05:42.043738 | controller | ok: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2025-09-29 10:05:42.043765 | controller |
2025-09-29 10:05:42.044013 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /etc/systemd/system] ***
2025-09-29 10:05:43.379662 | controller | Monday 29 September 2025  10:05:42 +0000 (0:00:01.362)       0:04:10.127 ******
2025-09-29 10:05:43.379701 | controller | ok: [compute-1] => (item=tripleo_nova_libvirt.target)
2025-09-29 10:05:43.379732 | controller | ok: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2025-09-29 10:05:43.379740 | controller | ok: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2025-09-29 10:05:43.379747 | controller | ok: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2025-09-29 10:05:43.379756 | controller | ok: [compute-1] => (item=tripleo_nova_virtqemud.service)
2025-09-29 10:05:43.380265 | controller | ok: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2025-09-29 10:05:43.443018 | controller | ok: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2025-09-29 10:05:43.443058 | controller |
2025-09-29 10:05:43.443069 | controller | TASK [Adopt (stop tracking) certs from tripleo] ********************************
2025-09-29 10:05:43.443077 | controller | Monday 29 September 2025  10:05:43 +0000 (0:00:01.336)       0:04:11.464 ******
2025-09-29 10:05:43.443089 | controller |
2025-09-29 10:05:43.670144 | controller | TASK [osp.edpm.edpm_install_certs : Stop and disable certmonger] ***************
2025-09-29 10:05:43.670174 | controller | Monday 29 September 2025  10:05:43 +0000 (0:00:00.063)       0:04:11.527 ******
2025-09-29 10:05:43.670192 | controller | ok: [compute-1]
2025-09-29 10:05:43.931579 | controller |
2025-09-29 10:05:43.931643 | controller | TASK [osp.edpm.edpm_install_certs : Find certmonger requests] ******************
2025-09-29 10:05:43.931652 | controller | Monday 29 September 2025  10:05:43 +0000 (0:00:00.226)       0:04:11.754 ******
2025-09-29 10:05:43.931663 | controller | [WARNING]: Skipped '/var/lib/certmonger/requests' path due to this access
2025-09-29 10:05:43.953588 | controller | issue: '/var/lib/certmonger/requests' is not a directory
2025-09-29 10:05:43.953631 | controller | ok: [compute-1]
2025-09-29 10:05:43.953662 | controller |
2025-09-29 10:05:43.953865 | controller | TASK [osp.edpm.edpm_install_certs : Backup certificate requests] ***************
2025-09-29 10:05:43.985989 | controller | Monday 29 September 2025  10:05:43 +0000 (0:00:00.284)       0:04:12.038 ******
2025-09-29 10:05:43.986013 | controller | skipping: [compute-1]
2025-09-29 10:05:44.034616 | controller |
2025-09-29 10:05:44.034640 | controller | TASK [osp.edpm.edpm_install_certs : Remove certificate requests] ***************
2025-09-29 10:05:44.034650 | controller | Monday 29 September 2025  10:05:43 +0000 (0:00:00.032)       0:04:12.070 ******
2025-09-29 10:05:44.034661 | controller | skipping: [compute-1]
2025-09-29 10:05:44.034690 | controller |
2025-09-29 10:05:44.034899 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reload units] ****************************
2025-09-29 10:05:44.496155 | controller | Monday 29 September 2025  10:05:44 +0000 (0:00:00.048)       0:04:12.119 ******
2025-09-29 10:05:44.496189 | controller | ok: [compute-1]
2025-09-29 10:05:45.908640 | controller |
2025-09-29 10:05:45.908675 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reset state of failed systemd units] *****
2025-09-29 10:05:45.908685 | controller | Monday 29 September 2025  10:05:44 +0000 (0:00:00.461)       0:04:12.580 ******
2025-09-29 10:05:45.908697 | controller | changed: [compute-1] => (item=tripleo_nova_libvirt.target)
2025-09-29 10:05:45.908726 | controller | changed: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2025-09-29 10:05:45.908736 | controller | changed: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2025-09-29 10:05:45.909450 | controller | changed: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2025-09-29 10:05:45.970762 | controller | changed: [compute-1] => (item=tripleo_nova_virtqemud.service)
2025-09-29 10:05:45.970793 | controller | changed: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2025-09-29 10:05:45.970801 | controller | changed: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2025-09-29 10:05:45.970807 | controller |
2025-09-29 10:05:45.970812 | controller | TASK [osp.edpm.edpm_libvirt : Ensure libvirt user] *****************************
2025-09-29 10:05:45.970828 | controller | Monday 29 September 2025  10:05:45 +0000 (0:00:01.412)       0:04:13.993 ******
2025-09-29 10:05:45.970839 | controller | included: /home/zuul/.cache/ansible-compat/7dd456/collections/ansible_collections/osp/edpm/roles/edpm_libvirt/tasks/user.yml for compute-1
2025-09-29 10:05:46.003625 | controller |
2025-09-29 10:05:46.003651 | controller | TASK [osp.edpm.edpm_users : Validating arguments against arg spec 'main' - The main entry point for the edpm_users role.] ***
2025-09-29 10:05:46.003660 | controller | Monday 29 September 2025  10:05:45 +0000 (0:00:00.061)       0:04:14.055 ******
2025-09-29 10:05:46.003670 | controller | ok: [compute-1]
2025-09-29 10:05:46.039618 | controller |
2025-09-29 10:05:46.039642 | controller | TASK [osp.edpm.edpm_users : Gather user fact] **********************************
2025-09-29 10:05:46.039652 | controller | Monday 29 September 2025  10:05:46 +0000 (0:00:00.033)       0:04:14.088 ******
2025-09-29 10:05:46.039664 | controller | skipping: [compute-1]
2025-09-29 10:05:46.095779 | controller |
2025-09-29 10:05:46.095800 | controller | TASK [osp.edpm.edpm_users : Create host users and groups] **********************
2025-09-29 10:05:46.095807 | controller | Monday 29 September 2025  10:05:46 +0000 (0:00:00.035)       0:04:14.124 ******
2025-09-29 10:05:46.095817 | controller | included: /home/zuul/.cache/ansible-compat/7dd456/collections/ansible_collections/osp/edpm/roles/edpm_users/tasks/create_users_and_groups.yml for compute-1 => (item={'name': 'libvirt', 'uid': '42473', 'gid': '42473', 'shell': '/sbin/nologin', 'comment': 'libvirt user'})
2025-09-29 10:05:46.404652 | controller |
2025-09-29 10:05:46.404681 | controller | TASK [osp.edpm.edpm_users : Detect when user exists and cannot be created] *****
2025-09-29 10:05:46.404689 | controller | Monday 29 September 2025  10:05:46 +0000 (0:00:00.055)       0:04:14.180 ******
2025-09-29 10:05:46.404699 | controller | fatal: [compute-1]: FAILED! => changed=false
2025-09-29 10:05:46.428336 | controller |   msg: One or more supplied key could not be found in the database.
2025-09-29 10:05:46.428361 | controller | ...ignoring
2025-09-29 10:05:46.783348 | controller |
2025-09-29 10:05:46.783377 | controller | TASK [osp.edpm.edpm_users : Ensure group is present on the host [ libvirt ]] ***
2025-09-29 10:05:46.783385 | controller | Monday 29 September 2025  10:05:46 +0000 (0:00:00.332)       0:04:14.513 ******
2025-09-29 10:05:46.783395 | controller | changed: [compute-1]
2025-09-29 10:05:47.225917 | controller |
2025-09-29 10:05:47.225947 | controller | TASK [osp.edpm.edpm_users : Ensure user is present on the host [ libvirt ]] ****
2025-09-29 10:05:47.225955 | controller | Monday 29 September 2025  10:05:46 +0000 (0:00:00.354)       0:04:14.867 ******
2025-09-29 10:05:47.225965 | controller | changed: [compute-1]
2025-09-29 10:05:47.300065 | controller |
2025-09-29 10:05:47.300093 | controller | TASK [osp.edpm.edpm_users : Create host directories] ***************************
2025-09-29 10:05:47.300100 | controller | Monday 29 September 2025  10:05:47 +0000 (0:00:00.442)       0:04:15.310 ******
2025-09-29 10:05:47.300110 | controller | skipping: [compute-1]
2025-09-29 10:05:47.361272 | controller |
2025-09-29 10:05:47.361292 | controller | TASK [osp.edpm.edpm_libvirt : Install libvirt] *********************************
2025-09-29 10:05:47.361300 | controller | Monday 29 September 2025  10:05:47 +0000 (0:00:00.074)       0:04:15.384 ******
2025-09-29 10:05:47.361309 | controller | included: /home/zuul/.cache/ansible-compat/7dd456/collections/ansible_collections/osp/edpm/roles/edpm_libvirt/tasks/install.yml for compute-1
2025-09-29 10:05:47.398569 | controller |
2025-09-29 10:05:47.398591 | controller | TASK [osp.edpm.edpm_libvirt : Gather ansible_local facts] **********************
2025-09-29 10:05:47.398600 | controller | Monday 29 September 2025  10:05:47 +0000 (0:00:00.061)       0:04:15.445 ******
2025-09-29 10:05:47.398611 | controller | skipping: [compute-1]
2025-09-29 10:05:47.398987 | controller |
2025-09-29 10:07:20.581097 | controller | TASK [osp.edpm.edpm_libvirt : Install libvirt packages] ************************
2025-09-29 10:07:20.581131 | controller | Monday 29 September 2025  10:05:47 +0000 (0:00:00.037)       0:04:15.483 ******
2025-09-29 10:07:20.581150 | controller | changed: [compute-1]
2025-09-29 10:07:20.581203 | controller |
2025-09-29 10:07:20.581227 | controller | TASK [osp.edpm.edpm_libvirt : Ensure monolithic libvirt and tcp socket activation is not enabled or running] ***
2025-09-29 10:07:20.581401 | controller | Monday 29 September 2025  10:07:20 +0000 (0:01:33.182)       0:05:48.665 ******
2025-09-29 10:07:22.673273 | controller | changed: [compute-1] => (item=libvirtd)
2025-09-29 10:07:22.673607 | controller | changed: [compute-1] => (item=libvirtd-tcp.socket)
2025-09-29 10:07:22.673654 | controller | changed: [compute-1] => (item=libvirtd-tls.socket)
2025-09-29 10:07:22.673663 | controller | changed: [compute-1] => (item=virtproxyd-tcp.socket)
2025-09-29 10:07:22.673670 | controller |
2025-09-29 10:07:22.673808 | controller | TASK [osp.edpm.edpm_libvirt : Ensure libvirt services are enabled and running] ***
2025-09-29 10:07:22.673843 | controller | Monday 29 September 2025  10:07:22 +0000 (0:00:02.092)       0:05:50.758 ******
2025-09-29 10:07:25.137817 | controller | changed: [compute-1] => (item=virtlogd)
2025-09-29 10:07:25.137959 | controller | changed: [compute-1] => (item=virtnodedevd)
2025-09-29 10:07:25.138141 | controller | changed: [compute-1] => (item=virtproxyd)
2025-09-29 10:07:25.138177 | controller | ok: [compute-1] => (item=virtqemud)
2025-09-29 10:07:25.138206 | controller | changed: [compute-1] => (item=virtsecretd)
2025-09-29 10:07:25.138490 | controller |
2025-09-29 10:07:25.710892 | controller | TASK [osp.edpm.edpm_libvirt : Configure virtproxyd-tls.socket] *****************
2025-09-29 10:07:25.710926 | controller | Monday 29 September 2025  10:07:25 +0000 (0:00:02.464)       0:05:53.222 ******
2025-09-29 10:07:25.710938 | controller | changed: [compute-1]
2025-09-29 10:07:25.710963 | controller |
2025-09-29 10:07:25.710971 | controller | TASK [osp.edpm.edpm_libvirt : Configure socket activation for libvirt services] ***
2025-09-29 10:07:25.711101 | controller | Monday 29 September 2025  10:07:25 +0000 (0:00:00.573)       0:05:53.795 ******
2025-09-29 10:07:30.248597 | controller | ok: [compute-1] => (item=['virtlogd', ''])
2025-09-29 10:07:30.250108 | controller | skipping: [compute-1] => (item=['virtlogd', '-ro'])
2025-09-29 10:07:30.250126 | controller | ok: [compute-1] => (item=['virtlogd', '-admin'])
2025-09-29 10:07:30.250133 | controller | ok: [compute-1] => (item=['virtnodedevd', ''])
2025-09-29 10:07:30.250143 | controller | ok: [compute-1] => (item=['virtnodedevd', '-ro'])
2025-09-29 10:07:30.332563 | controller | ok: [compute-1] => (item=['virtnodedevd', '-admin'])
2025-09-29 10:07:30.332586 | controller | ok: [compute-1] => (item=['virtproxyd', ''])
2025-09-29 10:07:30.332594 | controller | ok: [compute-1] => (item=['virtproxyd', '-ro'])
2025-09-29 10:07:30.332600 | controller | ok: [compute-1] => (item=['virtproxyd', '-admin'])
2025-09-29 10:07:30.332605 | controller | ok: [compute-1] => (item=['virtqemud', ''])
2025-09-29 10:07:30.332610 | controller | ok: [compute-1] => (item=['virtqemud', '-ro'])
2025-09-29 10:07:30.332616 | controller | ok: [compute-1] => (item=['virtqemud', '-admin'])
2025-09-29 10:07:30.332621 | controller | ok: [compute-1] => (item=['virtsecretd', ''])
2025-09-29 10:07:30.332626 | controller | ok: [compute-1] => (item=['virtsecretd', '-ro'])
2025-09-29 10:07:30.332632 | controller | ok: [compute-1] => (item=['virtsecretd', '-admin'])
2025-09-29 10:07:30.332637 | controller |
2025-09-29 10:07:30.332643 | controller | TASK [osp.edpm.edpm_libvirt : Configure libvirt] *******************************
2025-09-29 10:07:30.332649 | controller | Monday 29 September 2025  10:07:30 +0000 (0:00:04.537)       0:05:58.333 ******
2025-09-29 10:07:30.332657 | controller | included: /home/zuul/.cache/ansible-compat/7dd456/collections/ansible_collections/osp/edpm/roles/edpm_libvirt/tasks/configure.yml for compute-1
2025-09-29 10:07:30.332964 | controller |
2025-09-29 10:07:30.378638 | controller | TASK [osp.edpm.edpm_libvirt : Get libvirt password] ****************************
2025-09-29 10:07:30.378658 | controller | Monday 29 September 2025  10:07:30 +0000 (0:00:00.084)       0:05:58.417 ******
2025-09-29 10:07:30.378670 | controller | skipping: [compute-1]
2025-09-29 10:07:30.378835 | controller |
2025-09-29 10:07:30.379020 | controller | TASK [osp.edpm.edpm_libvirt : Gather user fact] ********************************
2025-09-29 10:07:30.379172 | controller | Monday 29 September 2025  10:07:30 +0000 (0:00:00.046)       0:05:58.463 ******
2025-09-29 10:07:30.416760 | controller | skipping: [compute-1]
2025-09-29 10:07:30.416947 | controller |
2025-09-29 10:07:30.417111 | controller | TASK [osp.edpm.edpm_libvirt : Create libvirt config dir] ***********************
2025-09-29 10:07:30.417277 | controller | Monday 29 September 2025  10:07:30 +0000 (0:00:00.038)       0:05:58.501 ******
2025-09-29 10:07:31.576631 | controller | changed: [compute-1] => (item={'path': '/etc/tmpfiles.d/', 'owner': 'root', 'group': 'root'})
2025-09-29 10:07:31.576691 | controller | changed: [compute-1] => (item={'path': '/var/lib/edpm-config/firewall', 'owner': 'root', 'group': 'root'})
2025-09-29 10:07:31.577384 | controller | changed: [compute-1] => (item={'path': '/etc/pki/libvirt', 'owner': 'root', 'group': 'root', 'mode': '0755'})
2025-09-29 10:07:34.528607 | controller | changed: [compute-1] => (item={'path': '/etc/pki/libvirt/private', 'owner': 'root', 'group': 'root', 'mode': '0755'})
2025-09-29 10:07:34.528640 | controller | changed: [compute-1] => (item={'path': '/etc/pki/CA', 'owner': 'root', 'group': 'root', 'mode': '0755'})
2025-09-29 10:07:34.528650 | controller | changed: [compute-1] => (item={'path': '/etc/pki/qemu', 'owner': 'root', 'group': 'qemu'})
2025-09-29 10:07:34.528658 | controller |
2025-09-29 10:07:34.528665 | controller | TASK [osp.edpm.edpm_libvirt : Render libvirt config files] *********************
2025-09-29 10:07:34.528672 | controller | Monday 29 September 2025  10:07:31 +0000 (0:00:01.159)       0:05:59.661 ******
2025-09-29 10:07:34.528684 | controller | changed: [compute-1] => (item={'src': 'virtlogd.conf', 'dest': 'libvirt/virtlogd.conf'})
2025-09-29 10:07:34.528713 | controller | changed: [compute-1] => (item={'src': 'virtnodedevd.conf', 'dest': 'libvirt/virtnodedevd.conf'})
2025-09-29 10:07:34.528724 | controller | changed: [compute-1] => (item={'src': 'virtproxyd.conf', 'dest': 'libvirt/virtproxyd.conf'})
2025-09-29 10:07:34.529581 | controller | changed: [compute-1] => (item={'src': 'virtqemud.conf', 'dest': 'libvirt/virtqemud.conf'})
2025-09-29 10:07:34.573326 | controller | changed: [compute-1] => (item={'src': 'qemu.conf.j2', 'dest': 'libvirt/qemu.conf'})
2025-09-29 10:07:34.573352 | controller | changed: [compute-1] => (item={'src': 'virtsecretd.conf', 'dest': 'libvirt/virtsecretd.conf'})
2025-09-29 10:07:34.573359 | controller | changed: [compute-1] => (item={'src': 'auth.conf', 'dest': 'libvirt/auth.conf', 'mode': '0600'})
2025-09-29 10:07:34.573365 | controller | changed: [compute-1] => (item={'src': 'sasl_libvirt.conf', 'dest': 'sasl2/libvirt.conf'})
2025-09-29 10:07:34.573371 | controller |
2025-09-29 10:07:34.573376 | controller | TASK [osp.edpm.edpm_libvirt : Add libvirt password to sasl db] *****************
2025-09-29 10:07:34.573382 | controller | Monday 29 September 2025  10:07:34 +0000 (0:00:02.951)       0:06:02.613 ******
2025-09-29 10:07:34.573391 | controller | skipping: [compute-1]
2025-09-29 10:07:34.794614 | controller |
2025-09-29 10:07:34.794646 | controller | TASK [osp.edpm.edpm_libvirt : Remove libvirt sasl db] **************************
2025-09-29 10:07:34.794656 | controller | Monday 29 September 2025  10:07:34 +0000 (0:00:00.044)       0:06:02.658 ******
2025-09-29 10:07:34.794669 | controller | ok: [compute-1]
2025-09-29 10:07:34.794725 | controller |
2025-09-29 10:07:34.794753 | controller | TASK [osp.edpm.edpm_libvirt : Create libvirt socket activation drop-in directories] ***
2025-09-29 10:07:34.794765 | controller | Monday 29 September 2025  10:07:34 +0000 (0:00:00.221)       0:06:02.879 ******
2025-09-29 10:07:37.489624 | controller | changed: [compute-1] => (item=['virtlogd', ''])
2025-09-29 10:07:37.490807 | controller | skipping: [compute-1] => (item=['virtlogd', '-ro'])
2025-09-29 10:07:37.490837 | controller | changed: [compute-1] => (item=['virtlogd', '-admin'])
2025-09-29 10:07:37.490845 | controller | changed: [compute-1] => (item=['virtnodedevd', ''])
2025-09-29 10:07:37.490851 | controller | changed: [compute-1] => (item=['virtnodedevd', '-ro'])
2025-09-29 10:07:37.490860 | controller | changed: [compute-1] => (item=['virtnodedevd', '-admin'])
2025-09-29 10:07:42.473590 | controller | changed: [compute-1] => (item=['virtproxyd', ''])
2025-09-29 10:07:42.473622 | controller | changed: [compute-1] => (item=['virtproxyd', '-ro'])
2025-09-29 10:07:42.473632 | controller | changed: [compute-1] => (item=['virtproxyd', '-admin'])
2025-09-29 10:07:42.473640 | controller | changed: [compute-1] => (item=['virtqemud', ''])
2025-09-29 10:07:42.473647 | controller | changed: [compute-1] => (item=['virtqemud', '-ro'])
2025-09-29 10:07:42.473653 | controller | changed: [compute-1] => (item=['virtqemud', '-admin'])
2025-09-29 10:07:42.473660 | controller | changed: [compute-1] => (item=['virtsecretd', ''])
2025-09-29 10:07:42.473670 | controller | changed: [compute-1] => (item=['virtsecretd', '-ro'])
2025-09-29 10:07:42.473677 | controller | changed: [compute-1] => (item=['virtsecretd', '-admin'])
2025-09-29 10:07:42.473684 | controller |
2025-09-29 10:07:42.473691 | controller | TASK [osp.edpm.edpm_libvirt : Render libvirt socket activation drop-in files] ***
2025-09-29 10:07:42.473704 | controller | Monday 29 September 2025  10:07:37 +0000 (0:00:02.694)       0:06:05.573 ******
2025-09-29 10:07:42.473716 | controller | changed: [compute-1] => (item=['virtlogd', ''])
2025-09-29 10:07:42.473742 | controller | skipping: [compute-1] => (item=['virtlogd', '-ro'])
2025-09-29 10:07:42.473752 | controller | changed: [compute-1] => (item=['virtlogd', '-admin'])
2025-09-29 10:07:42.473776 | controller | changed: [compute-1] => (item=['virtnodedevd', ''])
2025-09-29 10:07:42.475053 | controller | changed: [compute-1] => (item=['virtnodedevd', '-ro'])
2025-09-29 10:07:42.689652 | controller | changed: [compute-1] => (item=['virtnodedevd', '-admin'])
2025-09-29 10:07:42.689686 | controller | changed: [compute-1] => (item=['virtproxyd', ''])
2025-09-29 10:07:42.689694 | controller | changed: [compute-1] => (item=['virtproxyd', '-ro'])
2025-09-29 10:07:42.689699 | controller | changed: [compute-1] => (item=['virtproxyd', '-admin'])
2025-09-29 10:07:42.689705 | controller | changed: [compute-1] => (item=['virtqemud', ''])
2025-09-29 10:07:42.689710 | controller | changed: [compute-1] => (item=['virtqemud', '-ro'])
2025-09-29 10:07:42.689716 | controller | changed: [compute-1] => (item=['virtqemud', '-admin'])
2025-09-29 10:07:42.689721 | controller | changed: [compute-1] => (item=['virtsecretd', ''])
2025-09-29 10:07:42.689726 | controller | changed: [compute-1] => (item=['virtsecretd', '-ro'])
2025-09-29 10:07:42.689732 | controller | changed: [compute-1] => (item=['virtsecretd', '-admin'])
2025-09-29 10:07:42.689737 | controller |
2025-09-29 10:07:42.689743 | controller | TASK [osp.edpm.edpm_libvirt : Check if /run/libvirt has container_file_t selinux label] ***
2025-09-29 10:07:42.689749 | controller | Monday 29 September 2025  10:07:42 +0000 (0:00:04.984)       0:06:10.558 ******
2025-09-29 10:07:42.689759 | controller | ok: [compute-1]
2025-09-29 10:07:42.689783 | controller |
2025-09-29 10:07:42.689936 | controller | TASK [osp.edpm.edpm_libvirt : Restore selinux label for /run/libvirt to virt_var_run_t] ***
2025-09-29 10:07:42.689969 | controller | Monday 29 September 2025  10:07:42 +0000 (0:00:00.216)       0:06:10.774 ******
2025-09-29 10:07:42.723525 | controller | skipping: [compute-1]
2025-09-29 10:07:43.754039 | controller |
2025-09-29 10:07:43.754068 | controller | TASK [osp.edpm.edpm_libvirt : Enable os_enable_vtpm SELinux boolean for vTPM] ***
2025-09-29 10:07:43.754076 | controller | Monday 29 September 2025  10:07:42 +0000 (0:00:00.033)       0:06:10.808 ******
2025-09-29 10:07:43.754086 | controller | changed: [compute-1]
2025-09-29 10:07:43.817566 | controller |
2025-09-29 10:07:43.817593 | controller | TASK [osp.edpm.edpm_libvirt : Move libvirt TLS files to the right location on the compute node] ***
2025-09-29 10:07:43.817602 | controller | Monday 29 September 2025  10:07:43 +0000 (0:00:01.030)       0:06:11.838 ******
2025-09-29 10:07:43.817611 | controller | skipping: [compute-1] => (item={'src': '/var/lib/openstack/certs/libvirt/default/tls.crt', 'dest': '/etc/pki/libvirt/servercert.pem'})
2025-09-29 10:07:43.818448 | controller | skipping: [compute-1] => (item={'src': '/var/lib/openstack/certs/libvirt/default/tls.key', 'dest': '/etc/pki/libvirt/private/serverkey.pem', 'mode': '0600'})
2025-09-29 10:07:43.918779 | controller | skipping: [compute-1] => (item={'src': '/var/lib/openstack/certs/libvirt/default/tls.crt', 'dest': '/etc/pki/libvirt/clientcert.pem'})
2025-09-29 10:07:43.918803 | controller | skipping: [compute-1] => (item={'src': '/var/lib/openstack/certs/libvirt/default/tls.key', 'dest': '/etc/pki/libvirt/private/clientkey.pem'})
2025-09-29 10:07:43.918823 | controller | skipping: [compute-1] => (item={'src': '/var/lib/openstack/certs/libvirt/default/ca.crt', 'dest': '/etc/pki/CA/cacert.pem'})
2025-09-29 10:07:43.918830 | controller | skipping: [compute-1]
2025-09-29 10:07:43.918836 | controller |
2025-09-29 10:07:43.918842 | controller | TASK [osp.edpm.edpm_libvirt : Move qemu TLS files to the right location on the compute node] ***
2025-09-29 10:07:43.918848 | controller | Monday 29 September 2025  10:07:43 +0000 (0:00:00.063)       0:06:11.902 ******
2025-09-29 10:07:43.918860 | controller | skipping: [compute-1] => (item={'src': '/var/lib/openstack/certs/libvirt/default/tls.crt', 'dest': '/etc/pki/qemu/server-cert.pem'})
2025-09-29 10:07:44.486859 | controller | skipping: [compute-1] => (item={'src': '/var/lib/openstack/certs/libvirt/default/tls.key', 'dest': '/etc/pki/qemu/server-key.pem'})
2025-09-29 10:07:44.486888 | controller | skipping: [compute-1] => (item={'src': '/var/lib/openstack/certs/libvirt/default/tls.crt', 'dest': '/etc/pki/qemu/client-cert.pem'})
2025-09-29 10:07:44.486901 | controller | skipping: [compute-1] => (item={'src': '/var/lib/openstack/certs/libvirt/default/tls.key', 'dest': '/etc/pki/qemu/client-key.pem'})
2025-09-29 10:07:44.486908 | controller | skipping: [compute-1] => (item={'src': '/var/lib/openstack/certs/libvirt/default/ca.crt', 'dest': '/etc/pki/qemu/ca-cert.pem'})
2025-09-29 10:07:44.486913 | controller | skipping: [compute-1]
2025-09-29 10:07:44.486919 | controller |
2025-09-29 10:07:44.486925 | controller | TASK [osp.edpm.edpm_libvirt : Flush handlers] **********************************
2025-09-29 10:07:44.486931 | controller | Monday 29 September 2025  10:07:43 +0000 (0:00:00.064)       0:06:11.967 ******
2025-09-29 10:07:44.486936 | controller |
2025-09-29 10:07:44.486941 | controller | RUNNING HANDLER [osp.edpm.edpm_podman : Restart rsyslog] ***********************
2025-09-29 10:07:44.486947 | controller | Monday 29 September 2025  10:07:43 +0000 (0:00:00.035)       0:06:12.002 ******
2025-09-29 10:07:44.486956 | controller | changed: [compute-1]
2025-09-29 10:07:47.163966 | controller |
2025-09-29 10:07:47.163996 | controller | RUNNING HANDLER [osp.edpm.edpm_libvirt : Restart libvirt services] *************
2025-09-29 10:07:47.164004 | controller | Monday 29 September 2025  10:07:44 +0000 (0:00:00.568)       0:06:12.571 ******
2025-09-29 10:07:47.164014 | controller | changed: [compute-1] => (item=virtlogd)
2025-09-29 10:07:47.231197 | controller | changed: [compute-1] => (item=virtnodedevd)
2025-09-29 10:07:47.231224 | controller | changed: [compute-1] => (item=virtproxyd)
2025-09-29 10:07:47.231231 | controller | changed: [compute-1] => (item=virtqemud)
2025-09-29 10:07:47.231237 | controller | changed: [compute-1] => (item=virtsecretd)
2025-09-29 10:07:47.231243 | controller |
2025-09-29 10:07:47.231249 | controller | TASK [osp.edpm.edpm_libvirt : Post-install libvirt] ****************************
2025-09-29 10:07:47.231254 | controller | Monday 29 September 2025  10:07:47 +0000 (0:00:02.676)       0:06:15.247 ******
2025-09-29 10:07:47.231264 | controller | included: /home/zuul/.cache/ansible-compat/7dd456/collections/ansible_collections/osp/edpm/roles/edpm_libvirt/tasks/post-install.yml for compute-1
2025-09-29 10:07:47.473362 | controller |
2025-09-29 10:07:47.473393 | controller | TASK [osp.edpm.edpm_libvirt : Ensure ceph config directory exists] *************
2025-09-29 10:07:47.473401 | controller | Monday 29 September 2025  10:07:47 +0000 (0:00:00.067)       0:06:15.315 ******
2025-09-29 10:07:47.473411 | controller | changed: [compute-1]
2025-09-29 10:07:47.686994 | controller |
2025-09-29 10:07:47.687023 | controller | TASK [osp.edpm.edpm_libvirt : Gather Ceph configuration files] *****************
2025-09-29 10:07:47.687032 | controller | Monday 29 September 2025  10:07:47 +0000 (0:00:00.242)       0:06:15.558 ******
2025-09-29 10:07:47.687042 | controller | ok: [compute-1]
2025-09-29 10:07:47.728592 | controller |
2025-09-29 10:07:47.728622 | controller | TASK [osp.edpm.edpm_libvirt : Extract FSIDs from Ceph configuration files] *****
2025-09-29 10:07:47.728632 | controller | Monday 29 September 2025  10:07:47 +0000 (0:00:00.213)       0:06:15.771 ******
2025-09-29 10:07:47.728644 | controller | skipping: [compute-1]
2025-09-29 10:07:47.728664 | controller |
2025-09-29 10:07:47.728674 | controller | TASK [osp.edpm.edpm_libvirt : Map Ceph clusters to FSIDs] **********************
2025-09-29 10:07:47.728728 | controller | Monday 29 September 2025  10:07:47 +0000 (0:00:00.041)       0:06:15.813 ******
2025-09-29 10:07:47.758150 | controller | skipping: [compute-1]
2025-09-29 10:07:47.808628 | controller |
2025-09-29 10:07:47.808652 | controller | TASK [osp.edpm.edpm_libvirt : Gather Ceph keyring files] ***********************
2025-09-29 10:07:47.808661 | controller | Monday 29 September 2025  10:07:47 +0000 (0:00:00.029)       0:06:15.842 ******
2025-09-29 10:07:47.808672 | controller | skipping: [compute-1]
2025-09-29 10:07:47.857331 | controller |
2025-09-29 10:07:47.857349 | controller | TASK [osp.edpm.edpm_libvirt : Map Ceph clusters to cephx users] ****************
2025-09-29 10:07:47.857357 | controller | Monday 29 September 2025  10:07:47 +0000 (0:00:00.050)       0:06:15.893 ******
2025-09-29 10:07:47.857366 | controller | skipping: [compute-1]
2025-09-29 10:07:47.906192 | controller |
2025-09-29 10:07:47.906210 | controller | TASK [osp.edpm.edpm_libvirt : Use maps to create libvirt secrets] **************
2025-09-29 10:07:47.906222 | controller | Monday 29 September 2025  10:07:47 +0000 (0:00:00.048)       0:06:15.942 ******
2025-09-29 10:07:47.906235 | controller | skipping: [compute-1]
2025-09-29 10:07:47.936022 | controller |
2025-09-29 10:07:47.936044 | controller | TASK [osp.edpm.edpm_libvirt : Copy found_confs to /etc/ceph for qemu] **********
2025-09-29 10:07:47.936053 | controller | Monday 29 September 2025  10:07:47 +0000 (0:00:00.048)       0:06:15.991 ******
2025-09-29 10:07:47.936064 | controller | skipping: [compute-1]
2025-09-29 10:07:48.321575 | controller |
2025-09-29 10:07:48.321606 | controller | TASK [osp.edpm.edpm_libvirt : Copy libvirt firewall config] ********************
2025-09-29 10:07:48.321614 | controller | Monday 29 September 2025  10:07:47 +0000 (0:00:00.029)       0:06:16.020 ******
2025-09-29 10:07:48.321624 | controller | changed: [compute-1]
2025-09-29 10:07:48.321839 | controller |
2025-09-29 10:07:48.321863 | controller | TASK [Configure firewall for the libvirt] **************************************
2025-09-29 10:07:48.387140 | controller | Monday 29 September 2025  10:07:48 +0000 (0:00:00.385)       0:06:16.406 ******
2025-09-29 10:07:48.387169 | controller |
2025-09-29 10:07:48.608531 | controller | TASK [osp.edpm.edpm_nftables : Create snippets directory] **********************
2025-09-29 10:07:48.608560 | controller | Monday 29 September 2025  10:07:48 +0000 (0:00:00.065)       0:06:16.471 ******
2025-09-29 10:07:48.608572 | controller | ok: [compute-1]
2025-09-29 10:07:49.013949 | controller |
2025-09-29 10:07:49.013978 | controller | TASK [osp.edpm.edpm_nftables : Push default ruleset snipet] ********************
2025-09-29 10:07:49.013986 | controller | Monday 29 September 2025  10:07:48 +0000 (0:00:00.221)       0:06:16.693 ******
2025-09-29 10:07:49.013996 | controller | changed: [compute-1]
2025-09-29 10:07:49.390173 | controller |
2025-09-29 10:07:49.390202 | controller | TASK [osp.edpm.edpm_nftables : Write user rules snippet] ***********************
2025-09-29 10:07:49.390210 | controller | Monday 29 September 2025  10:07:49 +0000 (0:00:00.405)       0:06:17.098 ******
2025-09-29 10:07:49.390220 | controller | changed: [compute-1]
2025-09-29 10:07:49.764076 | controller |
2025-09-29 10:07:49.764106 | controller | TASK [osp.edpm.edpm_nftables : Push empty ruleset] *****************************
2025-09-29 10:07:49.764114 | controller | Monday 29 September 2025  10:07:49 +0000 (0:00:00.376)       0:06:17.474 ******
2025-09-29 10:07:49.764124 | controller | changed: [compute-1]
2025-09-29 10:07:50.020601 | controller |
2025-09-29 10:07:50.020632 | controller | TASK [osp.edpm.edpm_nftables : Load empty ruleset] *****************************
2025-09-29 10:07:50.020641 | controller | Monday 29 September 2025  10:07:49 +0000 (0:00:00.373)       0:06:17.848 ******
2025-09-29 10:07:50.020651 | controller | changed: [compute-1]
2025-09-29 10:07:50.020677 | controller |
2025-09-29 10:07:50.020926 | controller | TASK [osp.edpm.edpm_nftables : Get current nftables content] *******************
2025-09-29 10:07:50.244593 | controller | Monday 29 September 2025  10:07:50 +0000 (0:00:00.256)       0:06:18.105 ******
2025-09-29 10:07:50.244627 | controller | changed: [compute-1]
2025-09-29 10:07:50.244688 | controller |
2025-09-29 10:07:50.244718 | controller | TASK [osp.edpm.edpm_nftables : Load firewall snippets] *************************
2025-09-29 10:07:50.244732 | controller | Monday 29 September 2025  10:07:50 +0000 (0:00:00.223)       0:06:18.329 ******
2025-09-29 10:07:50.575112 | controller | ok: [compute-1]
2025-09-29 10:07:51.008649 | controller |
2025-09-29 10:07:51.008690 | controller | TASK [osp.edpm.edpm_nftables : Generate chain jumps] ***************************
2025-09-29 10:07:51.008700 | controller | Monday 29 September 2025  10:07:50 +0000 (0:00:00.330)       0:06:18.659 ******
2025-09-29 10:07:51.008712 | controller | changed: [compute-1]
2025-09-29 10:07:51.417153 | controller |
2025-09-29 10:07:51.417182 | controller | TASK [osp.edpm.edpm_nftables : Generate chain jumps] ***************************
2025-09-29 10:07:51.417190 | controller | Monday 29 September 2025  10:07:51 +0000 (0:00:00.433)       0:06:19.093 ******
2025-09-29 10:07:51.417200 | controller | changed: [compute-1]
2025-09-29 10:07:51.800973 | controller |
2025-09-29 10:07:51.801000 | controller | TASK [osp.edpm.edpm_nftables : Generate nft flushes] ***************************
2025-09-29 10:07:51.801009 | controller | Monday 29 September 2025  10:07:51 +0000 (0:00:00.408)       0:06:19.501 ******
2025-09-29 10:07:51.801018 | controller | changed: [compute-1]
2025-09-29 10:07:52.200294 | controller |
2025-09-29 10:07:52.200324 | controller | TASK [osp.edpm.edpm_nftables : Generate nft edpm chains] ***********************
2025-09-29 10:07:52.200334 | controller | Monday 29 September 2025  10:07:51 +0000 (0:00:00.383)       0:06:19.885 ******
2025-09-29 10:07:52.200351 | controller | changed: [compute-1]
2025-09-29 10:07:52.646426 | controller |
2025-09-29 10:07:52.646457 | controller | TASK [osp.edpm.edpm_nftables : Generate nft ruleset in static file] ************
2025-09-29 10:07:52.646465 | controller | Monday 29 September 2025  10:07:52 +0000 (0:00:00.399)       0:06:20.284 ******
2025-09-29 10:07:52.646480 | controller | changed: [compute-1]
2025-09-29 10:07:52.868448 | controller |
2025-09-29 10:07:52.868475 | controller | TASK [osp.edpm.edpm_nftables : Create a sentinel file when nft rules are changed] ***
2025-09-29 10:07:52.868483 | controller | Monday 29 September 2025  10:07:52 +0000 (0:00:00.446)       0:06:20.730 ******
2025-09-29 10:07:52.868493 | controller | changed: [compute-1]
2025-09-29 10:07:53.116547 | controller |
2025-09-29 10:07:53.116579 | controller | TASK [osp.edpm.edpm_nftables : Validate all of the generated content before loading] ***
2025-09-29 10:07:53.116588 | controller | Monday 29 September 2025  10:07:52 +0000 (0:00:00.222)       0:06:20.953 ******
2025-09-29 10:07:53.116599 | controller | changed: [compute-1]
2025-09-29 10:07:53.393236 | controller |
2025-09-29 10:07:53.393269 | controller | TASK [osp.edpm.edpm_nftables : Ensure we load our different nft rulesets on boot] ***
2025-09-29 10:07:53.393277 | controller | Monday 29 September 2025  10:07:53 +0000 (0:00:00.248)       0:06:21.201 ******
2025-09-29 10:07:53.393287 | controller | changed: [compute-1]
2025-09-29 10:07:53.470013 | controller |
2025-09-29 10:07:53.470041 | controller | TASK [Reload firewall for new libvirt rules] ***********************************
2025-09-29 10:07:53.470049 | controller | Monday 29 September 2025  10:07:53 +0000 (0:00:00.276)       0:06:21.477 ******
2025-09-29 10:07:53.470059 | controller |
2025-09-29 10:07:53.699426 | controller | TASK [osp.edpm.edpm_nftables : Inject our custom chains in nftables] ***********
2025-09-29 10:07:53.699456 | controller | Monday 29 September 2025  10:07:53 +0000 (0:00:00.077)       0:06:21.554 ******
2025-09-29 10:07:53.699468 | controller | changed: [compute-1]
2025-09-29 10:07:53.918632 | controller |
2025-09-29 10:07:53.918662 | controller | TASK [osp.edpm.edpm_nftables : Check if rules are changed] *********************
2025-09-29 10:07:53.918670 | controller | Monday 29 September 2025  10:07:53 +0000 (0:00:00.229)       0:06:21.783 ******
2025-09-29 10:07:53.918680 | controller | ok: [compute-1]
2025-09-29 10:07:53.918704 | controller |
2025-09-29 10:07:53.918933 | controller | TASK [osp.edpm.edpm_nftables : Reload ruleset] *********************************
2025-09-29 10:07:54.138605 | controller | Monday 29 September 2025  10:07:53 +0000 (0:00:00.219)       0:06:22.003 ******
2025-09-29 10:07:54.138643 | controller | changed: [compute-1]
2025-09-29 10:07:54.363416 | controller |
2025-09-29 10:07:54.363445 | controller | TASK [osp.edpm.edpm_nftables : Delete nft_ruleset_changed file] ****************
2025-09-29 10:07:54.363453 | controller | Monday 29 September 2025  10:07:54 +0000 (0:00:00.219)       0:06:22.223 ******
2025-09-29 10:07:54.363463 | controller | changed: [compute-1]
2025-09-29 10:07:54.750600 | controller |
2025-09-29 10:07:54.750631 | controller | TASK [osp.edpm.edpm_libvirt : Set up systemd target for libvirt services] ******
2025-09-29 10:07:54.750639 | controller | Monday 29 September 2025  10:07:54 +0000 (0:00:00.224)       0:06:22.448 ******
2025-09-29 10:07:54.750649 | controller | changed: [compute-1]
2025-09-29 10:07:54.750672 | controller |
2025-09-29 10:07:54.750898 | controller | TASK [osp.edpm.edpm_libvirt : Install libvirt-guests unit to stop nova_compute container before shutdown VMs] ***
2025-09-29 10:07:55.124490 | controller | Monday 29 September 2025  10:07:54 +0000 (0:00:00.387)       0:06:22.835 ******
2025-09-29 10:07:55.124547 | controller | changed: [compute-1]
2025-09-29 10:07:55.541413 | controller |
2025-09-29 10:07:55.541443 | controller | TASK [osp.edpm.edpm_libvirt : Making sure virt-guest-shutdown.target is present] ***
2025-09-29 10:07:55.541452 | controller | Monday 29 September 2025  10:07:55 +0000 (0:00:00.373)       0:06:23.209 ******
2025-09-29 10:07:55.541461 | controller | changed: [compute-1]
2025-09-29 10:07:56.061963 | controller |
2025-09-29 10:07:56.061992 | controller | TASK [osp.edpm.edpm_libvirt : Flush post-install handlers] *********************
2025-09-29 10:07:56.062000 | controller | Monday 29 September 2025  10:07:55 +0000 (0:00:00.378)       0:06:23.587 ******
2025-09-29 10:07:56.062006 | controller |
2025-09-29 10:07:56.062011 | controller | RUNNING HANDLER [osp.edpm.edpm_libvirt : Restart edpm_libvirt.target] **********
2025-09-29 10:07:56.062017 | controller | Monday 29 September 2025  10:07:55 +0000 (0:00:00.037)       0:06:23.625 ******
2025-09-29 10:07:56.062031 | controller | changed: [compute-1]
2025-09-29 10:07:56.759116 | controller |
2025-09-29 10:07:56.759151 | controller | RUNNING HANDLER [osp.edpm.edpm_libvirt : Reload edpm_libvirt_guests] ***********
2025-09-29 10:07:56.759160 | controller | Monday 29 September 2025  10:07:56 +0000 (0:00:00.520)       0:06:24.146 ******
2025-09-29 10:07:56.759169 | controller | changed: [compute-1]
2025-09-29 10:07:56.999659 | controller |
2025-09-29 10:07:56.999692 | controller | TASK [ensure /etc/multipath.conf exists] ***************************************
2025-09-29 10:07:56.999702 | controller | Monday 29 September 2025  10:07:56 +0000 (0:00:00.697)       0:06:24.843 ******
2025-09-29 10:07:56.999714 | controller | changed: [compute-1]
2025-09-29 10:07:56.999744 | controller |
2025-09-29 10:07:56.999754 | controller | TASK [Create Ceph client file directory] ***************************************
2025-09-29 10:07:56.999865 | controller | Monday 29 September 2025  10:07:56 +0000 (0:00:00.240)       0:06:25.084 ******
2025-09-29 10:07:57.222455 | controller | ok: [compute-1]
2025-09-29 10:07:57.453044 | controller |
2025-09-29 10:07:57.453078 | controller | TASK [Create ssh_known_hosts file] *********************************************
2025-09-29 10:07:57.453087 | controller | Monday 29 September 2025  10:07:57 +0000 (0:00:00.222)       0:06:25.307 ******
2025-09-29 10:07:57.453099 | controller | changed: [compute-1]
2025-09-29 10:07:57.667259 | controller |
2025-09-29 10:07:57.667291 | controller | TASK [create pki temp directory] ***********************************************
2025-09-29 10:07:57.667300 | controller | Monday 29 September 2025  10:07:57 +0000 (0:00:00.230)       0:06:25.537 ******
2025-09-29 10:07:57.667312 | controller | changed: [compute-1]
2025-09-29 10:07:58.763622 | controller |
2025-09-29 10:07:58.763655 | controller | TASK [Create private key (RSA, 4096 bits)] *************************************
2025-09-29 10:07:58.763665 | controller | Monday 29 September 2025  10:07:57 +0000 (0:00:00.214)       0:06:25.751 ******
2025-09-29 10:07:58.763677 | controller | changed: [compute-1]
2025-09-29 10:07:59.490291 | controller |
2025-09-29 10:07:59.490333 | controller | TASK [Create a certificate signing request for ca] *****************************
2025-09-29 10:07:59.490341 | controller | Monday 29 September 2025  10:07:58 +0000 (0:00:01.096)       0:06:26.847 ******
2025-09-29 10:07:59.490351 | controller | changed: [compute-1]
2025-09-29 10:08:00.337956 | controller |
2025-09-29 10:08:00.337986 | controller | TASK [Create a certificate athority] *******************************************
2025-09-29 10:08:00.337994 | controller | Monday 29 September 2025  10:07:59 +0000 (0:00:00.726)       0:06:27.574 ******
2025-09-29 10:08:00.338005 | controller | changed: [compute-1]
2025-09-29 10:08:00.537951 | controller |
2025-09-29 10:08:00.537979 | controller | TASK [Set permissions for certificate authority public key] ********************
2025-09-29 10:08:00.537987 | controller | Monday 29 September 2025  10:08:00 +0000 (0:00:00.847)       0:06:28.422 ******
2025-09-29 10:08:00.537997 | controller | changed: [compute-1]
2025-09-29 10:08:00.895012 | controller |
2025-09-29 10:08:00.895042 | controller | TASK [Gather required facts] ***************************************************
2025-09-29 10:08:00.895050 | controller | Monday 29 September 2025  10:08:00 +0000 (0:00:00.199)       0:06:28.622 ******
2025-09-29 10:08:00.895060 | controller | ok: [compute-1]
2025-09-29 10:08:01.242583 | controller |
2025-09-29 10:08:01.242611 | controller | TASK [Ensure hostname is resolvable in /etc/hosts] *****************************
2025-09-29 10:08:01.242620 | controller | Monday 29 September 2025  10:08:00 +0000 (0:00:00.356)       0:06:28.979 ******
2025-09-29 10:08:01.242630 | controller | changed: [compute-1]
2025-09-29 10:08:01.242983 | controller |
2025-09-29 10:08:01.489260 | controller | TASK [Create container config dirs for test] ***********************************
2025-09-29 10:08:01.489288 | controller | Monday 29 September 2025  10:08:01 +0000 (0:00:00.347)       0:06:29.327 ******
2025-09-29 10:08:01.489300 | controller | changed: [compute-1] => (item={'path': '/var/lib/openstack/config/nova', 'mode': '0755'})
2025-09-29 10:08:01.706360 | controller |
2025-09-29 10:08:01.706389 | controller | TASK [Create a test file to be deleted] ****************************************
2025-09-29 10:08:01.706397 | controller | Monday 29 September 2025  10:08:01 +0000 (0:00:00.246)       0:06:29.573 ******
2025-09-29 10:08:01.706407 | controller | changed: [compute-1]
2025-09-29 10:08:02.169480 | controller |
2025-09-29 10:08:02.169529 | controller | TASK [Create vendor integration directories] ***********************************
2025-09-29 10:08:02.169541 | controller | Monday 29 September 2025  10:08:01 +0000 (0:00:00.217)       0:06:29.790 ******
2025-09-29 10:08:02.169552 | controller | changed: [compute-1] => (item={'path': '/var/lib/vendor_integration'})
2025-09-29 10:08:02.170542 | controller | changed: [compute-1] => (item={'path': '/opt/vendor_integration_2'})
2025-09-29 10:08:02.170557 | controller |
2025-09-29 10:08:02.170579 | controller | PLAY RECAP *********************************************************************
2025-09-29 10:08:02.170585 | controller | compute-1                  : ok=126  changed=70   unreachable=0    failed=0    skipped=43   rescued=0    ignored=2
2025-09-29 10:08:02.170591 | controller |
2025-09-29 10:08:02.170596 | controller | Monday 29 September 2025  10:08:02 +0000 (0:00:00.463)       0:06:30.254 ******
2025-09-29 10:08:02.170602 | controller | ===============================================================================
2025-09-29 10:08:02.170607 | controller | Install openstack-selinux --------------------------------------------- 114.30s
2025-09-29 10:08:02.170612 | controller | osp.edpm.edpm_libvirt : Install libvirt packages ----------------------- 93.18s
2025-09-29 10:08:02.170617 | controller | ../../../../molecule/common/test_deps : Install edpm packages ---------- 90.51s
2025-09-29 10:08:02.170624 | controller | ../../../../molecule/common/test_deps : Install repo-setup pre-requirements --- 9.83s
2025-09-29 10:08:02.172123 | controller | Enable package repos ---------------------------------------------------- 7.28s
2025-09-29 10:08:02.237944 | controller | osp.edpm.edpm_libvirt : Render libvirt socket activation drop-in files --- 4.98s
2025-09-29 10:08:02.237977 | controller | osp.edpm.edpm_libvirt : Configure socket activation for libvirt services --- 4.54s
2025-09-29 10:08:02.237985 | controller | ../../../../molecule/common/test_deps : Install repo-setup -------------- 3.39s
2025-09-29 10:08:02.237991 | controller | osp.edpm.edpm_libvirt : Render libvirt config files --------------------- 2.95s
2025-09-29 10:08:02.237997 | controller | osp.edpm.edpm_libvirt : Create libvirt socket activation drop-in directories --- 2.69s
2025-09-29 10:08:02.238002 | controller | osp.edpm.edpm_libvirt : Restart libvirt services ------------------------ 2.68s
2025-09-29 10:08:02.238007 | controller | osp.edpm.edpm_libvirt : Ensure libvirt services are enabled and running --- 2.46s
2025-09-29 10:08:02.238013 | controller | osp.edpm.edpm_libvirt : Ensure monolithic libvirt and tcp socket activation is not enabled or running --- 2.09s
2025-09-29 10:08:02.238019 | controller | osp.edpm.edpm_tripleo_cleanup : Stop and disable tripleo services ------- 2.08s
2025-09-29 10:08:02.238024 | controller | osp.edpm.edpm_tripleo_cleanup : Gather service facts -------------------- 1.80s
2025-09-29 10:08:02.238029 | controller | osp.edpm.edpm_tripleo_cleanup : Reset state of failed systemd units ----- 1.41s
2025-09-29 10:08:02.238035 | controller | osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /usr/lib/systemd/system --- 1.36s
2025-09-29 10:08:02.238040 | controller | osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /etc/systemd/system --- 1.34s
2025-09-29 10:08:02.238045 | controller | osp.edpm.edpm_podman : Ensure podman and deps are installed ------------- 1.27s
2025-09-29 10:08:02.238051 | controller | ../../../../molecule/common/test_deps : Install selinux python libs ----- 1.22s
2025-09-29 10:08:02.238061 | controller | INFO     Running default > converge
2025-09-29 10:08:02.687978 | controller |
2025-09-29 10:08:02.688208 | controller | PLAY [Converge] ****************************************************************
2025-09-29 10:08:02.688361 | controller |
2025-09-29 10:08:02.688498 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 10:08:02.688687 | controller | Monday 29 September 2025  10:08:02 +0000 (0:00:00.037)       0:00:00.037 ******
2025-09-29 10:08:03.543593 | controller | ok: [compute-1]
2025-09-29 10:08:03.596068 | controller |
2025-09-29 10:08:03.596097 | controller | TASK [set basic user fact] *****************************************************
2025-09-29 10:08:03.596106 | controller | Monday 29 September 2025  10:08:03 +0000 (0:00:00.855)       0:00:00.893 ******
2025-09-29 10:08:03.596116 | controller | ok: [compute-1]
2025-09-29 10:08:03.692754 | controller |
2025-09-29 10:08:03.692795 | controller | TASK [set basic home fact] *****************************************************
2025-09-29 10:08:03.692810 | controller | Monday 29 September 2025  10:08:03 +0000 (0:00:00.052)       0:00:00.945 ******
2025-09-29 10:08:03.692822 | controller | skipping: [compute-1]
2025-09-29 10:08:03.748606 | controller |
2025-09-29 10:08:03.748641 | controller | TASK [osp.edpm.edpm_nova : Validating arguments against arg spec 'main' - The main entry point for the edpm_nova role.] ***
2025-09-29 10:08:03.748649 | controller | Monday 29 September 2025  10:08:03 +0000 (0:00:00.096)       0:00:01.042 ******
2025-09-29 10:08:03.748660 | controller | ok: [compute-1]
2025-09-29 10:08:03.748684 | controller |
2025-09-29 10:08:03.748945 | controller | TASK [osp.edpm.edpm_nova : Ensure ssh_known_hosts file exists] *****************
2025-09-29 10:08:04.079288 | controller | Monday 29 September 2025  10:08:03 +0000 (0:00:00.056)       0:00:01.098 ******
2025-09-29 10:08:04.079323 | controller | changed: [compute-1]
2025-09-29 10:08:04.146045 | controller |
2025-09-29 10:08:04.146071 | controller | TASK [osp.edpm.edpm_nova : Adopt nova from tripleo] ****************************
2025-09-29 10:08:04.146079 | controller | Monday 29 September 2025  10:08:04 +0000 (0:00:00.330)       0:00:01.429 ******
2025-09-29 10:08:04.146089 | controller | included: /home/zuul/.cache/ansible-compat/7dd456/collections/ansible_collections/osp/edpm/roles/edpm_nova/tasks/adoption.yml for compute-1
2025-09-29 10:08:04.242827 | controller |
2025-09-29 10:08:04.242854 | controller | TASK [Stop and disable compute services] ***************************************
2025-09-29 10:08:04.242863 | controller | Monday 29 September 2025  10:08:04 +0000 (0:00:00.066)       0:00:01.495 ******
2025-09-29 10:08:04.242875 | controller |
2025-09-29 10:08:04.313459 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Validating arguments against arg spec 'main' - The main entry point for the edpm_tripleo_cleanup role.] ***
2025-09-29 10:08:04.313482 | controller | Monday 29 September 2025  10:08:04 +0000 (0:00:00.096)       0:00:01.592 ******
2025-09-29 10:08:04.313494 | controller | ok: [compute-1]
2025-09-29 10:08:05.003615 | controller |
2025-09-29 10:08:05.003655 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reload units to check for new changes] ***
2025-09-29 10:08:05.003666 | controller | Monday 29 September 2025  10:08:04 +0000 (0:00:00.070)       0:00:01.663 ******
2025-09-29 10:08:05.003678 | controller | ok: [compute-1]
2025-09-29 10:08:05.003701 | controller |
2025-09-29 10:08:05.003907 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Gather service facts] ********************
2025-09-29 10:08:07.211556 | controller | Monday 29 September 2025  10:08:05 +0000 (0:00:00.690)       0:00:02.353 ******
2025-09-29 10:08:07.211605 | controller | ok: [compute-1]
2025-09-29 10:08:07.253629 | controller |
2025-09-29 10:08:07.253680 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Set list of tripleo services] ************
2025-09-29 10:08:07.253691 | controller | Monday 29 September 2025  10:08:07 +0000 (0:00:02.207)       0:00:04.561 ******
2025-09-29 10:08:07.253706 | controller | ok: [compute-1]
2025-09-29 10:08:07.304170 | controller |
2025-09-29 10:08:07.304199 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Get all services] ************************
2025-09-29 10:08:07.304207 | controller | Monday 29 September 2025  10:08:07 +0000 (0:00:00.042)       0:00:04.603 ******
2025-09-29 10:08:07.304217 | controller | skipping: [compute-1]
2025-09-29 10:08:07.354258 | controller |
2025-09-29 10:08:07.354284 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Filter for tripleo services and skip the ones in skip list] ***
2025-09-29 10:08:07.354292 | controller | Monday 29 September 2025  10:08:07 +0000 (0:00:00.050)       0:00:04.654 ******
2025-09-29 10:08:07.354302 | controller | skipping: [compute-1]
2025-09-29 10:08:09.726632 | controller |
2025-09-29 10:08:09.726665 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Stop and disable tripleo services] *******
2025-09-29 10:08:09.726673 | controller | Monday 29 September 2025  10:08:07 +0000 (0:00:00.050)       0:00:04.704 ******
2025-09-29 10:08:09.726689 | controller | ok: [compute-1] => (item=tripleo_nova_compute.service)
2025-09-29 10:08:09.726715 | controller | ok: [compute-1] => (item=tripleo_nova_migration_target.service)
2025-09-29 10:08:09.726722 | controller | ok: [compute-1] => (item=tripleo_nova_api_cron.service)
2025-09-29 10:08:09.726728 | controller | ok: [compute-1] => (item=tripleo_nova_api.service)
2025-09-29 10:08:09.726737 | controller | ok: [compute-1] => (item=tripleo_nova_conductor.service)
2025-09-29 10:08:09.726778 | controller | ok: [compute-1] => (item=tripleo_nova_metadata.service)
2025-09-29 10:08:09.727283 | controller | ok: [compute-1] => (item=tripleo_nova_scheduler.service)
2025-09-29 10:08:09.797616 | controller | ok: [compute-1] => (item=tripleo_nova_vnc_proxy.service)
2025-09-29 10:08:09.797653 | controller |
2025-09-29 10:08:09.797662 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Check for errors] ************************
2025-09-29 10:08:09.797670 | controller | Monday 29 September 2025  10:08:09 +0000 (0:00:02.372)       0:00:07.076 ******
2025-09-29 10:08:09.797684 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_compute.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_compute.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_compute.service', 'ansible_loop_var': 'item'})
2025-09-29 10:08:09.797716 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_migration_target.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_migration_target.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_migration_target.service', 'ansible_loop_var': 'item'})
2025-09-29 10:08:09.797724 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_api_cron.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_api_cron.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_api_cron.service', 'ansible_loop_var': 'item'})
2025-09-29 10:08:09.797733 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_api.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_api.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_api.service', 'ansible_loop_var': 'item'})
2025-09-29 10:08:09.797740 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_conductor.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_conductor.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_conductor.service', 'ansible_loop_var': 'item'})
2025-09-29 10:08:09.797745 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_metadata.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_metadata.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_metadata.service', 'ansible_loop_var': 'item'})
2025-09-29 10:08:09.797751 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_scheduler.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_scheduler.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_scheduler.service', 'ansible_loop_var': 'item'})
2025-09-29 10:08:09.797768 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_vnc_proxy.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_vnc_proxy.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_vnc_proxy.service', 'ansible_loop_var': 'item'})
2025-09-29 10:08:09.797796 | controller | skipping: [compute-1]
2025-09-29 10:08:09.798115 | controller |
2025-09-29 10:08:09.871672 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files] ***********************
2025-09-29 10:08:09.871696 | controller | Monday 29 September 2025  10:08:09 +0000 (0:00:00.070)       0:00:07.147 ******
2025-09-29 10:08:09.871707 | controller | included: /home/zuul/.cache/ansible-compat/7dd456/collections/ansible_collections/osp/edpm/roles/edpm_tripleo_cleanup/tasks/remove_unit_files.yml for compute-1 => (item=/usr/lib/systemd/system)
2025-09-29 10:08:11.348643 | controller | included: /home/zuul/.cache/ansible-compat/7dd456/collections/ansible_collections/osp/edpm/roles/edpm_tripleo_cleanup/tasks/remove_unit_files.yml for compute-1 => (item=/etc/systemd/system)
2025-09-29 10:08:11.348682 | controller |
2025-09-29 10:08:11.348691 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /usr/lib/systemd/system] ***
2025-09-29 10:08:11.348699 | controller | Monday 29 September 2025  10:08:09 +0000 (0:00:00.074)       0:00:07.221 ******
2025-09-29 10:08:11.348711 | controller | ok: [compute-1] => (item=tripleo_nova_compute.service)
2025-09-29 10:08:11.348740 | controller | ok: [compute-1] => (item=tripleo_nova_migration_target.service)
2025-09-29 10:08:11.348749 | controller | ok: [compute-1] => (item=tripleo_nova_api_cron.service)
2025-09-29 10:08:11.348773 | controller | ok: [compute-1] => (item=tripleo_nova_api.service)
2025-09-29 10:08:11.349421 | controller | ok: [compute-1] => (item=tripleo_nova_conductor.service)
2025-09-29 10:08:12.887632 | controller | ok: [compute-1] => (item=tripleo_nova_metadata.service)
2025-09-29 10:08:12.887672 | controller | ok: [compute-1] => (item=tripleo_nova_scheduler.service)
2025-09-29 10:08:12.887688 | controller | ok: [compute-1] => (item=tripleo_nova_vnc_proxy.service)
2025-09-29 10:08:12.887696 | controller |
2025-09-29 10:08:12.887703 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /etc/systemd/system] ***
2025-09-29 10:08:12.887711 | controller | Monday 29 September 2025  10:08:11 +0000 (0:00:01.476)       0:00:08.698 ******
2025-09-29 10:08:12.887724 | controller | ok: [compute-1] => (item=tripleo_nova_compute.service)
2025-09-29 10:08:12.887767 | controller | ok: [compute-1] => (item=tripleo_nova_migration_target.service)
2025-09-29 10:08:12.887777 | controller | ok: [compute-1] => (item=tripleo_nova_api_cron.service)
2025-09-29 10:08:12.887784 | controller | ok: [compute-1] => (item=tripleo_nova_api.service)
2025-09-29 10:08:12.887791 | controller | ok: [compute-1] => (item=tripleo_nova_conductor.service)
2025-09-29 10:08:12.887797 | controller | ok: [compute-1] => (item=tripleo_nova_metadata.service)
2025-09-29 10:08:12.887804 | controller | ok: [compute-1] => (item=tripleo_nova_scheduler.service)
2025-09-29 10:08:12.887813 | controller | ok: [compute-1] => (item=tripleo_nova_vnc_proxy.service)
2025-09-29 10:08:12.888007 | controller |
2025-09-29 10:08:12.888041 | controller | TASK [Adopt (stop tracking) certs from tripleo] ********************************
2025-09-29 10:08:12.977775 | controller | Monday 29 September 2025  10:08:12 +0000 (0:00:01.538)       0:00:10.237 ******
2025-09-29 10:08:12.977816 | controller |
2025-09-29 10:08:13.280995 | controller | TASK [osp.edpm.edpm_install_certs : Stop and disable certmonger] ***************
2025-09-29 10:08:13.281029 | controller | Monday 29 September 2025  10:08:12 +0000 (0:00:00.090)       0:00:10.327 ******
2025-09-29 10:08:13.281043 | controller | ok: [compute-1]
2025-09-29 10:08:13.567093 | controller |
2025-09-29 10:08:13.567122 | controller | TASK [osp.edpm.edpm_install_certs : Find certmonger requests] ******************
2025-09-29 10:08:13.567130 | controller | Monday 29 September 2025  10:08:13 +0000 (0:00:00.303)       0:00:10.630 ******
2025-09-29 10:08:13.567140 | controller | [WARNING]: Skipped '/var/lib/certmonger/requests' path due to this access
2025-09-29 10:08:13.596364 | controller | issue: '/var/lib/certmonger/requests' is not a directory
2025-09-29 10:08:13.596383 | controller | ok: [compute-1]
2025-09-29 10:08:13.635656 | controller |
2025-09-29 10:08:13.635699 | controller | TASK [osp.edpm.edpm_install_certs : Backup certificate requests] ***************
2025-09-29 10:08:13.635709 | controller | Monday 29 September 2025  10:08:13 +0000 (0:00:00.315)       0:00:10.946 ******
2025-09-29 10:08:13.635723 | controller | skipping: [compute-1]
2025-09-29 10:08:13.724595 | controller |
2025-09-29 10:08:13.724626 | controller | TASK [osp.edpm.edpm_install_certs : Remove certificate requests] ***************
2025-09-29 10:08:13.724635 | controller | Monday 29 September 2025  10:08:13 +0000 (0:00:00.039)       0:00:10.985 ******
2025-09-29 10:08:13.724644 | controller | skipping: [compute-1]
2025-09-29 10:08:14.218643 | controller |
2025-09-29 10:08:14.218677 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reload units] ****************************
2025-09-29 10:08:14.218687 | controller | Monday 29 September 2025  10:08:13 +0000 (0:00:00.088)       0:00:11.073 ******
2025-09-29 10:08:14.218699 | controller | ok: [compute-1]
2025-09-29 10:08:15.843651 | controller |
2025-09-29 10:08:15.843689 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reset state of failed systemd units] *****
2025-09-29 10:08:15.843699 | controller | Monday 29 September 2025  10:08:14 +0000 (0:00:00.494)       0:00:11.568 ******
2025-09-29 10:08:15.843712 | controller | changed: [compute-1] => (item=tripleo_nova_compute.service)
2025-09-29 10:08:15.843743 | controller | changed: [compute-1] => (item=tripleo_nova_migration_target.service)
2025-09-29 10:08:15.843764 | controller | changed: [compute-1] => (item=tripleo_nova_api_cron.service)
2025-09-29 10:08:15.843772 | controller | changed: [compute-1] => (item=tripleo_nova_api.service)
2025-09-29 10:08:15.843778 | controller | changed: [compute-1] => (item=tripleo_nova_conductor.service)
2025-09-29 10:08:15.843787 | controller | changed: [compute-1] => (item=tripleo_nova_metadata.service)
2025-09-29 10:08:15.844317 | controller | changed: [compute-1] => (item=tripleo_nova_scheduler.service)
2025-09-29 10:08:15.929619 | controller | changed: [compute-1] => (item=tripleo_nova_vnc_proxy.service)
2025-09-29 10:08:15.929655 | controller |
2025-09-29 10:08:15.929665 | controller | TASK [osp.edpm.edpm_nova : Configure nova] *************************************
2025-09-29 10:08:15.929672 | controller | Monday 29 September 2025  10:08:15 +0000 (0:00:01.624)       0:00:13.192 ******
2025-09-29 10:08:15.929685 | controller | included: /home/zuul/.cache/ansible-compat/7dd456/collections/ansible_collections/osp/edpm/roles/edpm_nova/tasks/configure.yml for compute-1
2025-09-29 10:08:15.929708 | controller |
2025-09-29 10:08:15.929736 | controller | TASK [osp.edpm.edpm_nova : Get source directory details] ***********************
2025-09-29 10:08:15.929883 | controller | Monday 29 September 2025  10:08:15 +0000 (0:00:00.087)       0:00:13.279 ******
2025-09-29 10:08:16.461783 | controller | ok: [compute-1] => (item={'src': '/var/lib/vendor_integration', 'dest': '/var/lib/vendor_integration'})
2025-09-29 10:08:16.461867 | controller | ok: [compute-1] => (item={'src': '/opt/vendor_integration_2', 'dest': '/opt/vendor_integration_2', 'options': 'shared'})
2025-09-29 10:08:16.461880 | controller |
2025-09-29 10:08:16.462143 | controller | TASK [osp.edpm.edpm_nova : Assert edpm_nova_extra_bind_mounts source directories exist] ***
2025-09-29 10:08:16.520575 | controller | Monday 29 September 2025  10:08:16 +0000 (0:00:00.531)       0:00:13.811 ******
2025-09-29 10:08:16.520608 | controller | ok: [compute-1] => changed=false
2025-09-29 10:08:16.520618 | controller |   msg: All assertions passed
2025-09-29 10:08:16.520625 | controller |
2025-09-29 10:08:16.520632 | controller | TASK [osp.edpm.edpm_nova : Assert edpm_nova_extra_bind_mounts src paths are valid] ***
2025-09-29 10:08:16.520641 | controller | Monday 29 September 2025  10:08:16 +0000 (0:00:00.058)       0:00:13.870 ******
2025-09-29 10:08:16.546636 | controller | [WARNING]: conditional statements should not include jinja2 templating
2025-09-29 10:08:16.546703 | controller | delimiters such as {{ }} or {% %}. Found: '{{ item }}' not in  {{
2025-09-29 10:08:16.546715 | controller | edpm_nova_extra_bind_mounts }} | map(attribute='src') | list
2025-09-29 10:08:16.613175 | controller | ok: [compute-1] => (item=/var/lib/nova) => changed=false
2025-09-29 10:08:16.613274 | controller |   ansible_loop_var: item
2025-09-29 10:08:16.613413 | controller |   item: /var/lib/nova
2025-09-29 10:08:16.613593 | controller |   msg: All assertions passed
2025-09-29 10:08:16.613728 | controller | ok: [compute-1] => (item=/var/lib/openstack) => changed=false
2025-09-29 10:08:16.613847 | controller |   ansible_loop_var: item
2025-09-29 10:08:16.613952 | controller |   item: /var/lib/openstack
2025-09-29 10:08:16.614049 | controller |   msg: All assertions passed
2025-09-29 10:08:16.614142 | controller |
2025-09-29 10:08:16.614246 | controller | TASK [osp.edpm.edpm_nova : Assert edpm_nova_extra_bind_mounts dest paths do not collide with standard mounts] ***
2025-09-29 10:08:16.614346 | controller | Monday 29 September 2025  10:08:16 +0000 (0:00:00.093)       0:00:13.963 ******
2025-09-29 10:08:16.640495 | controller | [WARNING]: conditional statements should not include jinja2 templating
2025-09-29 10:08:16.640625 | controller | delimiters such as {{ }} or {% %}. Found: '{{ item }}' not in {{
2025-09-29 10:08:16.640738 | controller | edpm_nova_extra_bind_mounts }} | map(attribute='dest') | list
2025-09-29 10:08:16.695102 | controller | ok: [compute-1] => (item=/var/lib/nova) => changed=false
2025-09-29 10:08:16.695183 | controller |   ansible_loop_var: item
2025-09-29 10:08:16.695278 | controller |   item: /var/lib/nova
2025-09-29 10:08:16.695380 | controller |   msg: All assertions passed
2025-09-29 10:08:16.695479 | controller | ok: [compute-1] => (item=/var/lib/openstack) => changed=false
2025-09-29 10:08:16.695603 | controller |   ansible_loop_var: item
2025-09-29 10:08:16.695694 | controller |   item: /var/lib/openstack
2025-09-29 10:08:16.695801 | controller |   msg: All assertions passed
2025-09-29 10:08:16.695902 | controller | ok: [compute-1] => (item=/etc/nova) => changed=false
2025-09-29 10:08:16.695992 | controller |   ansible_loop_var: item
2025-09-29 10:08:16.696084 | controller |   item: /etc/nova
2025-09-29 10:08:16.696175 | controller |   msg: All assertions passed
2025-09-29 10:08:16.696263 | controller |
2025-09-29 10:08:16.696362 | controller | TASK [osp.edpm.edpm_nova : Assert edpm_nova_extra_bind_mounts dest paths are subdirectory of valid paths] ***
2025-09-29 10:08:16.696456 | controller | Monday 29 September 2025  10:08:16 +0000 (0:00:00.081)       0:00:14.045 ******
2025-09-29 10:08:16.721945 | controller | [WARNING]: conditional statements should not include jinja2 templating
2025-09-29 10:08:16.722065 | controller | delimiters such as {{ }} or {% %}. Found: '{{ item.dest |
2025-09-29 10:08:16.722203 | controller | regex_search('^(/etc|/opt|/var/lib)') }}' is not none
2025-09-29 10:08:16.766037 | controller | ok: [compute-1] => (item={'src': '/var/lib/vendor_integration', 'dest': '/var/lib/vendor_integration'}) => changed=false
2025-09-29 10:08:16.766132 | controller |   ansible_loop_var: item
2025-09-29 10:08:16.766233 | controller |   item:
2025-09-29 10:08:16.766334 | controller |     dest: /var/lib/vendor_integration
2025-09-29 10:08:16.766426 | controller |     src: /var/lib/vendor_integration
2025-09-29 10:08:16.766554 | controller |   msg: All assertions passed
2025-09-29 10:08:16.766660 | controller | ok: [compute-1] => (item={'src': '/opt/vendor_integration_2', 'dest': '/opt/vendor_integration_2', 'options': 'shared'}) => changed=false
2025-09-29 10:08:16.766768 | controller |   ansible_loop_var: item
2025-09-29 10:08:16.766863 | controller |   item:
2025-09-29 10:08:16.766954 | controller |     dest: /opt/vendor_integration_2
2025-09-29 10:08:16.767047 | controller |     options: shared
2025-09-29 10:08:16.767140 | controller |     src: /opt/vendor_integration_2
2025-09-29 10:08:16.767232 | controller |   msg: All assertions passed
2025-09-29 10:08:16.767320 | controller |
2025-09-29 10:08:16.767413 | controller | TASK [osp.edpm.edpm_nova : Create sync config dirs] ****************************
2025-09-29 10:08:16.767525 | controller | Monday 29 September 2025  10:08:16 +0000 (0:00:00.070)       0:00:14.116 ******
2025-09-29 10:08:16.971974 | controller | changed: [compute-1 -> localhost]
2025-09-29 10:08:16.972043 | controller |
2025-09-29 10:08:16.972185 | controller | TASK [osp.edpm.edpm_nova : Gather user fact] ***********************************
2025-09-29 10:08:16.972307 | controller | Monday 29 September 2025  10:08:16 +0000 (0:00:00.205)       0:00:14.322 ******
2025-09-29 10:08:17.015707 | controller | skipping: [compute-1]
2025-09-29 10:08:17.015832 | controller |
2025-09-29 10:08:17.015960 | controller | TASK [osp.edpm.edpm_nova : Create container config dirs] ***********************
2025-09-29 10:08:17.016377 | controller | Monday 29 September 2025  10:08:17 +0000 (0:00:00.043)       0:00:14.366 ******
2025-09-29 10:08:17.662465 | controller | changed: [compute-1] => (item={'path': '/var/lib/config-data/nova', 'mode': '0755'})
2025-09-29 10:08:17.662599 | controller | changed: [compute-1] => (item={'path': '/var/lib/openstack/config/containers', 'mode': '0755'})
2025-09-29 10:08:17.662723 | controller | changed: [compute-1] => (item={'path': '/var/lib/config-data/nova_nvme_cleaner', 'mode': '0755'})
2025-09-29 10:08:17.662833 | controller |
2025-09-29 10:08:17.662938 | controller | TASK [osp.edpm.edpm_nova : Create persistent directories] **********************
2025-09-29 10:08:17.663040 | controller | Monday 29 September 2025  10:08:17 +0000 (0:00:00.646)       0:00:15.012 ******
2025-09-29 10:08:19.451681 | controller | changed: [compute-1] => (item={'path': '/var/lib/nova', 'mode': '0755'})
2025-09-29 10:08:19.451736 | controller | changed: [compute-1] => (item={'path': '/var/lib/_nova_secontext', 'mode': '0755'})
2025-09-29 10:08:19.452828 | controller | changed: [compute-1] => (item={'path': '/var/lib/nova/instances', 'mode': '0755'})
2025-09-29 10:08:19.653315 | controller | changed: [compute-1] => (item={'path': '/etc/ceph', 'mode': '0750', 'owner': 'root', 'group': 'root'})
2025-09-29 10:08:19.653349 | controller | changed: [compute-1] => (item={'path': '/etc/multipath'})
2025-09-29 10:08:19.653358 | controller | changed: [compute-1] => (item={'path': '/etc/iscsi'})
2025-09-29 10:08:19.653365 | controller | changed: [compute-1] => (item={'path': '/var/lib/iscsi'})
2025-09-29 10:08:19.653372 | controller | changed: [compute-1] => (item={'path': '/etc/nvme'})
2025-09-29 10:08:19.653379 | controller | changed: [compute-1] => (item={'path': '/run/openvswitch'})
2025-09-29 10:08:19.653385 | controller |
2025-09-29 10:08:19.653391 | controller | TASK [osp.edpm.edpm_nova : Discover configmaps in /home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/roles/edpm_nova/molecule/default/test-data] ***
2025-09-29 10:08:19.653397 | controller | Monday 29 September 2025  10:08:19 +0000 (0:00:01.789)       0:00:16.801 ******
2025-09-29 10:08:19.653407 | controller | ok: [compute-1 -> localhost]
2025-09-29 10:08:21.037635 | controller |
2025-09-29 10:08:21.037670 | controller | TASK [osp.edpm.edpm_nova : Flatten configmaps] *********************************
2025-09-29 10:08:21.037679 | controller | Monday 29 September 2025  10:08:19 +0000 (0:00:00.201)       0:00:17.003 ******
2025-09-29 10:08:21.037693 | controller | changed: [compute-1 -> localhost] => (item={'path': '/home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/roles/edpm_nova/molecule/default/test-data/provider1.yaml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 142, 'inode': 79692208, 'dev': 64513, 'nlink': 1, 'atime': 1759139964.0486567, 'mtime': 1759139922.4692063, 'ctime': 1759139964.9266567, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-09-29 10:08:21.037721 | controller | changed: [compute-1 -> localhost] => (item={'path': '/home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/roles/edpm_nova/molecule/default/test-data/migration-key/ssh-privatekey', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 27, 'inode': 83886259, 'dev': 64513, 'nlink': 1, 'atime': 1759139964.0496566, 'mtime': 1759139922.4652064, 'ctime': 1759139964.9266567, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-09-29 10:08:21.037730 | controller | changed: [compute-1 -> localhost] => (item={'path': '/home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/roles/edpm_nova/molecule/default/test-data/migration-key/ssh-publickey', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 26, 'inode': 83886260, 'dev': 64513, 'nlink': 1, 'atime': 1759139964.0496566, 'mtime': 1759139922.4652064, 'ctime': 1759139964.9266567, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-09-29 10:08:21.037739 | controller | changed: [compute-1 -> localhost] => (item={'path': '/home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/roles/edpm_nova/molecule/default/test-data/nova-cell1/01-nova.conf', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 85, 'inode': 88080873, 'dev': 64513, 'nlink': 1, 'atime': 1759139964.0496566, 'mtime': 1759139922.4692063, 'ctime': 1759139964.9266567, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-09-29 10:08:21.038077 | controller |
2025-09-29 10:08:21.078389 | controller | TASK [osp.edpm.edpm_users : Validating arguments against arg spec 'main' - The main entry point for the edpm_users role.] ***
2025-09-29 10:08:21.078408 | controller | Monday 29 September 2025  10:08:21 +0000 (0:00:01.384)       0:00:18.387 ******
2025-09-29 10:08:21.078418 | controller | ok: [compute-1]
2025-09-29 10:08:21.121582 | controller |
2025-09-29 10:08:21.121619 | controller | TASK [osp.edpm.edpm_users : Gather user fact] **********************************
2025-09-29 10:08:21.121629 | controller | Monday 29 September 2025  10:08:21 +0000 (0:00:00.040)       0:00:18.428 ******
2025-09-29 10:08:21.121642 | controller | skipping: [compute-1]
2025-09-29 10:08:21.215640 | controller |
2025-09-29 10:08:21.215673 | controller | TASK [osp.edpm.edpm_users : Create host users and groups] **********************
2025-09-29 10:08:21.215681 | controller | Monday 29 September 2025  10:08:21 +0000 (0:00:00.043)       0:00:18.471 ******
2025-09-29 10:08:21.215691 | controller | included: /home/zuul/.cache/ansible-compat/7dd456/collections/ansible_collections/osp/edpm/roles/edpm_users/tasks/create_users_and_groups.yml for compute-1 => (item={'name': 'nova', 'uid': '42436', 'gid': '42436', 'shell': '/bin/sh', 'comment': 'nova user', 'groups': 'libvirt'})
2025-09-29 10:08:21.483383 | controller |
2025-09-29 10:08:21.483415 | controller | TASK [osp.edpm.edpm_users : Detect when user exists and cannot be created] *****
2025-09-29 10:08:21.483423 | controller | Monday 29 September 2025  10:08:21 +0000 (0:00:00.093)       0:00:18.565 ******
2025-09-29 10:08:21.483433 | controller | fatal: [compute-1]: FAILED! => changed=false
2025-09-29 10:08:21.515344 | controller |   msg: One or more supplied key could not be found in the database.
2025-09-29 10:08:21.515373 | controller | ...ignoring
2025-09-29 10:08:21.851141 | controller |
2025-09-29 10:08:21.851173 | controller | TASK [osp.edpm.edpm_users : Ensure group is present on the host [ nova ]] ******
2025-09-29 10:08:21.851181 | controller | Monday 29 September 2025  10:08:21 +0000 (0:00:00.299)       0:00:18.865 ******
2025-09-29 10:08:21.851192 | controller | changed: [compute-1]
2025-09-29 10:08:22.308008 | controller |
2025-09-29 10:08:22.308039 | controller | TASK [osp.edpm.edpm_users : Ensure user is present on the host [ nova ]] *******
2025-09-29 10:08:22.308047 | controller | Monday 29 September 2025  10:08:21 +0000 (0:00:00.335)       0:00:19.200 ******
2025-09-29 10:08:22.308061 | controller | changed: [compute-1]
2025-09-29 10:08:22.401768 | controller |
2025-09-29 10:08:22.401798 | controller | TASK [osp.edpm.edpm_users : Create host directories] ***************************
2025-09-29 10:08:22.401806 | controller | Monday 29 September 2025  10:08:22 +0000 (0:00:00.457)       0:00:19.657 ******
2025-09-29 10:08:22.401816 | controller | skipping: [compute-1]
2025-09-29 10:08:22.781618 | controller |
2025-09-29 10:08:22.781649 | controller | TASK [osp.edpm.edpm_nova : Sync nova config files] *****************************
2025-09-29 10:08:22.781657 | controller | Monday 29 September 2025  10:08:22 +0000 (0:00:00.093)       0:00:19.751 ******
2025-09-29 10:08:22.781668 | controller | changed: [compute-1]
2025-09-29 10:08:22.781674 | controller |
2025-09-29 10:08:22.781680 | controller | TASK [osp.edpm.edpm_nova : Render nova config files] ***************************
2025-09-29 10:08:22.781687 | controller | Monday 29 September 2025  10:08:22 +0000 (0:00:00.380)       0:00:20.131 ******
2025-09-29 10:08:24.453627 | controller | changed: [compute-1] => (item={'src': 'config.json.j2', 'dest': 'config.json'})
2025-09-29 10:08:24.453678 | controller | changed: [compute-1] => (item={'src': 'nova-blank.conf', 'dest': 'nova-blank.conf'})
2025-09-29 10:08:24.453686 | controller | changed: [compute-1] => (item={'src': 'ssh-config', 'dest': 'ssh-config'})
2025-09-29 10:08:24.453692 | controller | changed: [compute-1] => (item={'src': '02-nova-host-specific.conf.j2', 'dest': '02-nova-host-specific.conf'})
2025-09-29 10:08:24.453707 | controller | changed: [compute-1] => (item={'src': 'nova_statedir_ownership.py', 'dest': 'nova_statedir_ownership.py'})
2025-09-29 10:08:24.453715 | controller |
2025-09-29 10:08:24.453975 | controller | TASK [osp.edpm.edpm_nova : Render nvme cleaner config files] *******************
2025-09-29 10:08:24.454100 | controller | Monday 29 September 2025  10:08:24 +0000 (0:00:01.671)       0:00:21.803 ******
2025-09-29 10:08:24.835402 | controller | changed: [compute-1]
2025-09-29 10:08:25.057580 | controller |
2025-09-29 10:08:25.057614 | controller | TASK [osp.edpm.edpm_nova : Create .ssh directory for the nova user on the host] ***
2025-09-29 10:08:25.057624 | controller | Monday 29 September 2025  10:08:24 +0000 (0:00:00.381)       0:00:22.185 ******
2025-09-29 10:08:25.057634 | controller | changed: [compute-1]
2025-09-29 10:08:25.283311 | controller |
2025-09-29 10:08:25.283340 | controller | TASK [osp.edpm.edpm_nova : Copy the migration ssh public key as authorized_keys to the nova user] ***
2025-09-29 10:08:25.283348 | controller | Monday 29 September 2025  10:08:25 +0000 (0:00:00.222)       0:00:22.407 ******
2025-09-29 10:08:25.283358 | controller | changed: [compute-1]
2025-09-29 10:08:25.495597 | controller |
2025-09-29 10:08:25.495625 | controller | TASK [osp.edpm.edpm_nova : Check if compute_id exists in state_path] ***********
2025-09-29 10:08:25.495633 | controller | Monday 29 September 2025  10:08:25 +0000 (0:00:00.225)       0:00:22.633 ******
2025-09-29 10:08:25.495643 | controller | ok: [compute-1]
2025-09-29 10:08:25.495756 | controller |
2025-09-29 10:08:25.495784 | controller | TASK [osp.edpm.edpm_nova : Pre-generate compute_id if not already exists] ******
2025-09-29 10:08:25.495803 | controller | Monday 29 September 2025  10:08:25 +0000 (0:00:00.212)       0:00:22.845 ******
2025-09-29 10:08:25.911651 | controller | changed: [compute-1]
2025-09-29 10:08:25.911755 | controller |
2025-09-29 10:08:25.911776 | controller | TASK [osp.edpm.edpm_nova : Make compute_id ready-only] *************************
2025-09-29 10:08:25.911903 | controller | Monday 29 September 2025  10:08:25 +0000 (0:00:00.416)       0:00:23.261 ******
2025-09-29 10:08:25.976439 | controller | skipping: [compute-1]
2025-09-29 10:08:26.050428 | controller |
2025-09-29 10:08:26.050455 | controller | TASK [osp.edpm.edpm_nova : Install nova] ***************************************
2025-09-29 10:08:26.050463 | controller | Monday 29 September 2025  10:08:25 +0000 (0:00:00.064)       0:00:23.326 ******
2025-09-29 10:08:26.050473 | controller | included: /home/zuul/.cache/ansible-compat/7dd456/collections/ansible_collections/osp/edpm/roles/edpm_nova/tasks/install.yml for compute-1
2025-09-29 10:08:26.259615 | controller |
2025-09-29 10:08:26.259647 | controller | TASK [osp.edpm.edpm_nova : Check if ca bundle exists] **************************
2025-09-29 10:08:26.259657 | controller | Monday 29 September 2025  10:08:26 +0000 (0:00:00.074)       0:00:23.400 ******
2025-09-29 10:08:26.259670 | controller | ok: [compute-1]
2025-09-29 10:08:26.259699 | controller |
2025-09-29 10:08:26.259710 | controller | TASK [osp.edpm.edpm_nova : Render nova container] ******************************
2025-09-29 10:08:26.259823 | controller | Monday 29 September 2025  10:08:26 +0000 (0:00:00.208)       0:00:23.609 ******
2025-09-29 10:08:26.619387 | controller | changed: [compute-1]
2025-09-29 10:08:26.994377 | controller |
2025-09-29 10:08:26.994405 | controller | TASK [osp.edpm.edpm_nova : Render nova init container] *************************
2025-09-29 10:08:26.994413 | controller | Monday 29 September 2025  10:08:26 +0000 (0:00:00.359)       0:00:23.969 ******
2025-09-29 10:08:26.994423 | controller | changed: [compute-1]
2025-09-29 10:08:27.067571 | controller |
2025-09-29 10:08:27.067599 | controller | TASK [Deploy nova init container] **********************************************
2025-09-29 10:08:27.067608 | controller | Monday 29 September 2025  10:08:26 +0000 (0:00:00.375)       0:00:24.344 ******
2025-09-29 10:08:27.067620 | controller |
2025-09-29 10:08:27.067903 | controller | TASK [osp.edpm.edpm_container_manage : Validating arguments against arg spec 'main' - The main entry point for the edpm_container_manage role.] ***
2025-09-29 10:08:27.121680 | controller | Monday 29 September 2025  10:08:27 +0000 (0:00:00.073)       0:00:24.417 ******
2025-09-29 10:08:27.121721 | controller | ok: [compute-1]
2025-09-29 10:08:27.460164 | controller |
2025-09-29 10:08:27.460199 | controller | TASK [osp.edpm.edpm_container_manage : Find all matching configs configs for in /var/lib/openstack/config/containers] ***
2025-09-29 10:08:27.460212 | controller | Monday 29 September 2025  10:08:27 +0000 (0:00:00.053)       0:00:24.471 ******
2025-09-29 10:08:27.460226 | controller | ok: [compute-1]
2025-09-29 10:08:27.500287 | controller |
2025-09-29 10:08:27.500321 | controller | TASK [osp.edpm.edpm_container_manage : Finalise hashes for all containers] *****
2025-09-29 10:08:27.500330 | controller | Monday 29 September 2025  10:08:27 +0000 (0:00:00.338)       0:00:24.809 ******
2025-09-29 10:08:27.500339 | controller | ok: [compute-1]
2025-09-29 10:08:27.819805 | controller |
2025-09-29 10:08:27.819835 | controller | TASK [osp.edpm.edpm_container_manage : Update container configs with new config hashes] ***
2025-09-29 10:08:27.819843 | controller | Monday 29 September 2025  10:08:27 +0000 (0:00:00.040)       0:00:24.850 ******
2025-09-29 10:08:27.819854 | controller | [WARNING]: /var/lib/edpm-config/container-startup-config does not exists
2025-09-29 10:08:27.875633 | controller | ok: [compute-1]
2025-09-29 10:08:27.928305 | controller |
2025-09-29 10:08:27.928335 | controller | TASK [osp.edpm.edpm_container_manage : Delete orphan containers from /var/lib/openstack/config/containers] ***
2025-09-29 10:08:27.928344 | controller | Monday 29 September 2025  10:08:27 +0000 (0:00:00.375)       0:00:25.225 ******
2025-09-29 10:08:27.928354 | controller | skipping: [compute-1]
2025-09-29 10:08:28.007487 | controller |
2025-09-29 10:08:28.007530 | controller | TASK [osp.edpm.edpm_container_manage : Create containers from /var/lib/openstack/config/containers] ***
2025-09-29 10:08:28.007539 | controller | Monday 29 September 2025  10:08:27 +0000 (0:00:00.052)       0:00:25.278 ******
2025-09-29 10:08:28.007550 | controller | included: /home/zuul/.cache/ansible-compat/7dd456/collections/ansible_collections/osp/edpm/roles/edpm_container_manage/tasks/create.yml for compute-1
2025-09-29 10:08:42.454745 | controller |
2025-09-29 10:08:42.454780 | controller | TASK [osp.edpm.edpm_container_manage : Create containers managed by Podman for /var/lib/openstack/config/containers] ***
2025-09-29 10:08:42.454790 | controller | Monday 29 September 2025  10:08:28 +0000 (0:00:00.079)       0:00:25.357 ******
2025-09-29 10:08:42.454803 | controller | changed: [compute-1]
2025-09-29 10:08:42.454838 | controller |
2025-09-29 10:08:42.454848 | controller | TASK [osp.edpm.edpm_container_manage : Check if /etc/sysconfig/podman_drop_in exists] ***
2025-09-29 10:08:42.700598 | controller | Monday 29 September 2025  10:08:42 +0000 (0:00:14.447)       0:00:39.804 ******
2025-09-29 10:08:42.700637 | controller | ok: [compute-1]
2025-09-29 10:08:42.700830 | controller |
2025-09-29 10:08:42.700868 | controller | TASK [osp.edpm.edpm_container_manage : Set podman_drop_in fact] ****************
2025-09-29 10:08:42.700887 | controller | Monday 29 September 2025  10:08:42 +0000 (0:00:00.245)       0:00:40.050 ******
2025-09-29 10:08:42.761331 | controller | skipping: [compute-1]
2025-09-29 10:08:42.842477 | controller |
2025-09-29 10:08:42.842495 | controller | TASK [osp.edpm.edpm_container_manage : Manage container systemd services and cleanup old systemd healthchecks for /var/lib/openstack/config/containers] ***
2025-09-29 10:08:42.842520 | controller | Monday 29 September 2025  10:08:42 +0000 (0:00:00.060)       0:00:40.111 ******
2025-09-29 10:08:42.842533 | controller | skipping: [compute-1]
2025-09-29 10:08:42.921144 | controller |
2025-09-29 10:08:42.921177 | controller | TASK [Deploy nova container] ***************************************************
2025-09-29 10:08:42.921190 | controller | Monday 29 September 2025  10:08:42 +0000 (0:00:00.080)       0:00:40.192 ******
2025-09-29 10:08:42.921206 | controller |
2025-09-29 10:08:43.006435 | controller | TASK [osp.edpm.edpm_container_manage : Validating arguments against arg spec 'main' - The main entry point for the edpm_container_manage role.] ***
2025-09-29 10:08:43.006468 | controller | Monday 29 September 2025  10:08:42 +0000 (0:00:00.078)       0:00:40.271 ******
2025-09-29 10:08:43.006487 | controller | ok: [compute-1]
2025-09-29 10:08:43.242270 | controller |
2025-09-29 10:08:43.242299 | controller | TASK [osp.edpm.edpm_container_manage : Find all matching configs configs for in /var/lib/openstack/config/containers] ***
2025-09-29 10:08:43.242307 | controller | Monday 29 September 2025  10:08:43 +0000 (0:00:00.085)       0:00:40.356 ******
2025-09-29 10:08:43.242317 | controller | ok: [compute-1]
2025-09-29 10:08:43.284612 | controller |
2025-09-29 10:08:43.284647 | controller | TASK [osp.edpm.edpm_container_manage : Finalise hashes for all containers] *****
2025-09-29 10:08:43.284663 | controller | Monday 29 September 2025  10:08:43 +0000 (0:00:00.236)       0:00:40.592 ******
2025-09-29 10:08:43.284675 | controller | ok: [compute-1]
2025-09-29 10:08:43.533952 | controller |
2025-09-29 10:08:43.533981 | controller | TASK [osp.edpm.edpm_container_manage : Update container configs with new config hashes] ***
2025-09-29 10:08:43.533990 | controller | Monday 29 September 2025  10:08:43 +0000 (0:00:00.042)       0:00:40.634 ******
2025-09-29 10:08:43.534000 | controller | ok: [compute-1]
2025-09-29 10:08:43.588591 | controller |
2025-09-29 10:08:43.588613 | controller | TASK [osp.edpm.edpm_container_manage : Delete orphan containers from /var/lib/openstack/config/containers] ***
2025-09-29 10:08:43.588623 | controller | Monday 29 September 2025  10:08:43 +0000 (0:00:00.249)       0:00:40.883 ******
2025-09-29 10:08:43.588634 | controller | skipping: [compute-1]
2025-09-29 10:08:43.588662 | controller |
2025-09-29 10:08:43.588670 | controller | TASK [osp.edpm.edpm_container_manage : Create containers from /var/lib/openstack/config/containers] ***
2025-09-29 10:08:43.588679 | controller | Monday 29 September 2025  10:08:43 +0000 (0:00:00.054)       0:00:40.938 ******
2025-09-29 10:08:43.698170 | controller | included: /home/zuul/.cache/ansible-compat/7dd456/collections/ansible_collections/osp/edpm/roles/edpm_container_manage/tasks/create.yml for compute-1
2025-09-29 10:08:44.086691 | controller |
2025-09-29 10:08:44.086737 | controller | TASK [osp.edpm.edpm_container_manage : Create containers managed by Podman for /var/lib/openstack/config/containers] ***
2025-09-29 10:08:44.086746 | controller | Monday 29 September 2025  10:08:43 +0000 (0:00:00.109)       0:00:41.048 ******
2025-09-29 10:08:44.086757 | controller | [WARNING]: ERROR: Container nova_compute exited with code 125 when createed
2025-09-29 10:08:44.086834 | controller | stderr: time="2025-09-29T10:08:44Z" level=info msg="podman filtering at log
2025-09-29 10:08:44.086864 | controller | level info" time="2025-09-29T10:08:44Z" level=info msg="Setting parallel job
2025-09-29 10:08:44.086872 | controller | count to 13" time="2025-09-29T10:08:44Z" level=info msg="Using sqlite as
2025-09-29 10:08:44.086878 | controller | database backend" time="2025-09-29T10:08:44Z" level=info msg="Not using native
2025-09-29 10:08:44.086884 | controller | diff for overlay, this may cause degraded performance for building images:
2025-09-29 10:08:44.086889 | controller | kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" time="2025-09-29T10:08:44Z"
2025-09-29 10:08:44.086895 | controller | level=info msg="Sysctl net.ipv4.ping_group_range=0 0 ignored in
2025-09-29 10:08:44.086904 | controller | containers.conf, since Network Namespace set to host" Error: statfs
2025-09-29 10:08:44.086914 | controller | /var/lib/config-data/ceph: no such file or directory
2025-09-29 10:08:44.086993 | controller | time="2025-09-29T10:08:44Z" level=info msg="Received shutdown.Stop(),
2025-09-29 10:08:44.087203 | controller | terminating!" PID=60592
2025-09-29 10:08:44.087222 | controller | fatal: [compute-1]: FAILED! => changed=false
2025-09-29 10:08:44.089546 | controller |   msg: 'Failed containers: nova_compute'
2025-09-29 10:08:44.089574 | controller |
2025-09-29 10:08:44.089604 | controller | PLAY RECAP *********************************************************************
2025-09-29 10:08:44.091144 | controller | compute-1                  : ok=57   changed=17   unreachable=0    failed=1    skipped=14   rescued=0    ignored=1
2025-09-29 10:08:44.091161 | controller |
2025-09-29 10:08:44.091168 | controller | Monday 29 September 2025  10:08:44 +0000 (0:00:00.391)       0:00:41.439 ******
2025-09-29 10:08:44.091174 | controller | ===============================================================================
2025-09-29 10:08:44.091180 | controller | osp.edpm.edpm_container_manage : Create containers managed by Podman for /var/lib/openstack/config/containers -- 14.45s
2025-09-29 10:08:44.091186 | controller | osp.edpm.edpm_tripleo_cleanup : Stop and disable tripleo services ------- 2.37s
2025-09-29 10:08:44.091191 | controller | osp.edpm.edpm_tripleo_cleanup : Gather service facts -------------------- 2.21s
2025-09-29 10:08:44.091199 | controller | osp.edpm.edpm_nova : Create persistent directories ---------------------- 1.79s
2025-09-29 10:08:44.145962 | controller | osp.edpm.edpm_nova : Render nova config files --------------------------- 1.67s
2025-09-29 10:08:44.145983 | controller | osp.edpm.edpm_tripleo_cleanup : Reset state of failed systemd units ----- 1.62s
2025-09-29 10:08:44.145990 | controller | osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /etc/systemd/system --- 1.54s
2025-09-29 10:08:44.146001 | controller | osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /usr/lib/systemd/system --- 1.48s
2025-09-29 10:08:44.146009 | controller | osp.edpm.edpm_nova : Flatten configmaps --------------------------------- 1.38s
2025-09-29 10:08:44.146015 | controller | Gathering Facts --------------------------------------------------------- 0.86s
2025-09-29 10:08:44.146021 | controller | osp.edpm.edpm_tripleo_cleanup : Reload units to check for new changes --- 0.69s
2025-09-29 10:08:44.146026 | controller | osp.edpm.edpm_nova : Create container config dirs ----------------------- 0.65s
2025-09-29 10:08:44.146031 | controller | osp.edpm.edpm_nova : Get source directory details ----------------------- 0.53s
2025-09-29 10:08:44.146037 | controller | osp.edpm.edpm_tripleo_cleanup : Reload units ---------------------------- 0.49s
2025-09-29 10:08:44.146042 | controller | osp.edpm.edpm_users : Ensure user is present on the host [ nova ] ------- 0.46s
2025-09-29 10:08:44.146047 | controller | osp.edpm.edpm_nova : Pre-generate compute_id if not already exists ------ 0.42s
2025-09-29 10:08:44.146052 | controller | osp.edpm.edpm_container_manage : Create containers managed by Podman for /var/lib/openstack/config/containers --- 0.39s
2025-09-29 10:08:44.146058 | controller | osp.edpm.edpm_nova : Render nvme cleaner config files ------------------- 0.38s
2025-09-29 10:08:44.146063 | controller | osp.edpm.edpm_nova : Sync nova config files ----------------------------- 0.38s
2025-09-29 10:08:44.146068 | controller | osp.edpm.edpm_container_manage : Update container configs with new config hashes --- 0.38s
2025-09-29 10:08:44.146077 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/edpm_nova/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/roles/edpm_nova/molecule/default/converge.yml']
2025-09-29 10:08:44.146519 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-09-29 10:08:44.156108 | controller | INFO     Running default > cleanup
2025-09-29 10:08:44.156594 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-09-29 10:08:44.165106 | controller | INFO     Running default > destroy
2025-09-29 10:08:44.165463 | controller | WARNING  Skipping, instances are delegated.
2025-09-29 10:08:44.165903 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-09-29 10:08:44.167476 | controller | INFO     Writing /tmp/report.html report.
2025-09-29 10:08:49.221437 | controller | ERROR
2025-09-29 10:08:49.222266 | controller | {
2025-09-29 10:08:49.222887 | controller |   "delta": "0:07:14.660598",
2025-09-29 10:08:49.223091 | controller |   "end": "2025-09-29 10:08:44.216911",
2025-09-29 10:08:49.223297 | controller |   "msg": "non-zero return code",
2025-09-29 10:08:49.223315 | controller |   "rc": 1,
2025-09-29 10:08:49.223338 | controller |   "start": "2025-09-29 10:01:29.556313"
2025-09-29 10:08:49.223506 | controller | }
failure
2025-09-29 10:08:49.274902 | 
2025-09-29 10:08:49.275000 | PLAY RECAP
2025-09-29 10:08:49.275044 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-09-29 10:08:49.275068 | 
2025-09-29 10:08:49.341962 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-29 10:08:49.342706 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-29 10:08:49.874283 | 
2025-09-29 10:08:49.874380 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-09-29 10:08:49.894483 | 
2025-09-29 10:08:49.894556 | TASK [Filter out host if needed]
2025-09-29 10:08:49.902846 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-09-29 10:08:49.908344 | 
2025-09-29 10:08:49.908412 | TASK [Ensure file is present]
2025-09-29 10:08:50.214789 | controller | ok
2025-09-29 10:08:50.221854 | 
2025-09-29 10:08:50.221955 | TASK [Manage molecule report file]
2025-09-29 10:08:55.247375 | controller | changed
2025-09-29 10:08:55.252613 | 
2025-09-29 10:08:55.252679 | TASK [Check if we get ci-framework-data basedir]
2025-09-29 10:08:55.434714 | controller | ok
2025-09-29 10:08:55.439722 | 
2025-09-29 10:08:55.439808 | TASK [Create ci-framework-data log directory for zuul]
2025-09-29 10:08:55.741346 | controller | changed
2025-09-29 10:08:55.746511 | 
2025-09-29 10:08:55.746577 | TASK [Copy ci-framework interesting files]
2025-09-29 10:09:00.772275 | controller | changed
2025-09-29 10:09:00.777787 | 
2025-09-29 10:09:00.777877 | TASK [Get SELinux listing]
2025-09-29 10:09:05.804499 | controller | changed
2025-09-29 10:09:05.810592 | 
2025-09-29 10:09:05.810666 | TASK [Generate log index]
2025-09-29 10:09:06.396452 | controller | changed
2025-09-29 10:09:06.401164 | 
2025-09-29 10:09:06.401233 | TASK [Get some env related data]
2025-09-29 10:09:11.426671 | controller | changed
2025-09-29 10:09:11.431844 | 
2025-09-29 10:09:11.431929 | TASK [Generate list of logs to collect in home directory]
2025-09-29 10:09:11.703825 | controller | ok: All paths examined
2025-09-29 10:09:11.708702 | 
2025-09-29 10:09:11.708782 | LOOP [Copy logs from home directory]
2025-09-29 10:09:12.004339 | controller | changed:
2025-09-29 10:09:12.004453 | controller | {
2025-09-29 10:09:12.004480 | controller |   "atime": 1759140071.5320308,
2025-09-29 10:09:12.004500 | controller |   "ctime": 1759140087.5041268,
2025-09-29 10:09:12.004523 | controller |   "dev": 64513,
2025-09-29 10:09:12.004540 | controller |   "gid": 1000,
2025-09-29 10:09:12.004556 | controller |   "gr_name": "zuul",
2025-09-29 10:09:12.004572 | controller |   "inode": 4409560,
2025-09-29 10:09:12.004587 | controller |   "isblk": false,
2025-09-29 10:09:12.004604 | controller |   "ischr": false,
2025-09-29 10:09:12.004620 | controller |   "isdir": false,
2025-09-29 10:09:12.004634 | controller |   "isfifo": false,
2025-09-29 10:09:12.004649 | controller |   "isgid": false,
2025-09-29 10:09:12.004749 | controller |   "islnk": false,
2025-09-29 10:09:12.004785 | controller |   "isreg": true,
2025-09-29 10:09:12.004803 | controller |   "issock": false,
2025-09-29 10:09:12.004819 | controller |   "isuid": false,
2025-09-29 10:09:12.004834 | controller |   "mode": "0644",
2025-09-29 10:09:12.004848 | controller |   "mtime": 1759140087.5041268,
2025-09-29 10:09:12.004862 | controller |   "nlink": 1,
2025-09-29 10:09:12.004877 | controller |   "path": "/home/zuul/ansible.log",
2025-09-29 10:09:12.004891 | controller |   "pw_name": "zuul",
2025-09-29 10:09:12.004905 | controller |   "rgrp": true,
2025-09-29 10:09:12.004943 | controller |   "roth": true,
2025-09-29 10:09:12.004960 | controller |   "rusr": true,
2025-09-29 10:09:12.004976 | controller |   "size": 6749,
2025-09-29 10:09:12.004990 | controller |   "uid": 1000,
2025-09-29 10:09:12.005004 | controller |   "wgrp": false,
2025-09-29 10:09:12.005019 | controller |   "woth": false,
2025-09-29 10:09:12.005033 | controller |   "wusr": true,
2025-09-29 10:09:12.005048 | controller |   "xgrp": false,
2025-09-29 10:09:12.005066 | controller |   "xoth": false,
2025-09-29 10:09:12.005082 | controller |   "xusr": false
2025-09-29 10:09:12.005096 | controller | }
2025-09-29 10:09:12.017863 | 
2025-09-29 10:09:12.017992 | TASK [Copy crio stats log file]
2025-09-29 10:09:12.032097 | controller | skipping: Conditional result was False
2025-09-29 10:09:12.037869 | 
2025-09-29 10:09:12.037959 | TASK [Get SELinux related data]
2025-09-29 10:09:17.064009 | controller | ERROR
2025-09-29 10:09:17.064294 | controller | {
2025-09-29 10:09:17.064331 | controller |   "delta": "0:00:00.009190",
2025-09-29 10:09:17.064353 | controller |   "end": "2025-09-29 10:09:12.238872",
2025-09-29 10:09:17.064372 | controller |   "msg": "non-zero return code",
2025-09-29 10:09:17.064388 | controller |   "rc": 1,
2025-09-29 10:09:17.064404 | controller |   "start": "2025-09-29 10:09:12.229682"
2025-09-29 10:09:17.064418 | controller | }
2025-09-29 10:09:17.064441 | controller | ERROR: Ignoring Errors
2025-09-29 10:09:17.069745 | 
2025-09-29 10:09:17.069824 | TASK [Create system configuration directory]
2025-09-29 10:09:17.249574 | controller | changed
2025-09-29 10:09:17.254313 | 
2025-09-29 10:09:17.254382 | TASK [Get some of the system configurations]
2025-09-29 10:09:22.276355 | controller | changed
2025-09-29 10:09:22.281536 | 
2025-09-29 10:09:22.281618 | TASK [Copy generated documentation if available]
2025-09-29 10:09:22.295032 | controller | skipping: Conditional result was False
2025-09-29 10:09:22.300494 | 
2025-09-29 10:09:22.300559 | TASK [Copy generated AsciiDoc documentation if available]
2025-09-29 10:09:22.313801 | controller | skipping: Conditional result was False
2025-09-29 10:09:22.319325 | 
2025-09-29 10:09:22.319390 | TASK [Compress logs bigger than 2MB]
2025-09-29 10:09:27.345489 | controller | changed
2025-09-29 10:09:27.350725 | 
2025-09-29 10:09:27.350803 | TASK [Copy files from workspace on node]
2025-09-29 10:09:27.368152 | controller | ok
2025-09-29 10:09:27.389277 | 
2025-09-29 10:09:27.389342 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-29 10:09:27.401950 | controller | skipping: Conditional result was False
2025-09-29 10:09:27.407312 | 
2025-09-29 10:09:27.407379 | TASK [fetch-output : Set log path for single node]
2025-09-29 10:09:27.434518 | controller | ok
2025-09-29 10:09:27.440293 | 
2025-09-29 10:09:27.440355 | LOOP [fetch-output : Ensure local output dirs]
2025-09-29 10:09:27.627727 | controller -> localhost | ok: "/var/lib/zuul/builds/7f11a15d9b01404283b6c04d31b0602f/work/logs"
2025-09-29 10:09:27.627991 | controller -> localhost | changed: All items complete
2025-09-29 10:09:27.628023 | 
2025-09-29 10:09:27.800033 | controller -> localhost | changed: "/var/lib/zuul/builds/7f11a15d9b01404283b6c04d31b0602f/work/artifacts"
2025-09-29 10:09:27.971999 | controller -> localhost | changed: "/var/lib/zuul/builds/7f11a15d9b01404283b6c04d31b0602f/work/docs"
2025-09-29 10:09:27.986269 | 
2025-09-29 10:09:27.986354 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-29 10:09:28.505074 | controller | changed:
2025-09-29 10:09:28.505253 | controller | .d..t...... ./
2025-09-29 10:09:28.505293 | controller | >f+++++++++ README.html
2025-09-29 10:09:28.505322 | controller | >f+++++++++ ansible-execution.log
2025-09-29 10:09:28.505349 | controller | >f+++++++++ ansible.log
2025-09-29 10:09:28.505434 | controller | >f+++++++++ dmesg.log
2025-09-29 10:09:28.505464 | controller | >f+++++++++ installed-pkgs.log
2025-09-29 10:09:28.505486 | controller | >f+++++++++ python.log
2025-09-29 10:09:28.505505 | controller | >f+++++++++ registries.conf
2025-09-29 10:09:28.505523 | controller | >f+++++++++ report.html
2025-09-29 10:09:28.505543 | controller | >f+++++++++ selinux-denials.log
2025-09-29 10:09:28.505562 | controller | >f+++++++++ selinux-listing.log
2025-09-29 10:09:28.505579 | controller | cd+++++++++ ci-framework-data/
2025-09-29 10:09:28.505598 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-09-29 10:09:28.505616 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-09-29 10:09:28.505633 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-09-29 10:09:28.505650 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-09-29 10:09:28.505667 | controller | cd+++++++++ ci-framework-data/logs/
2025-09-29 10:09:28.505683 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-09-29 10:09:28.505700 | controller | cd+++++++++ registries.conf.d/
2025-09-29 10:09:28.505715 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-09-29 10:09:28.505731 | controller | cd+++++++++ system-config/
2025-09-29 10:09:28.505746 | controller | cd+++++++++ system-config/libvirt/
2025-09-29 10:09:28.505775 | controller | >f+++++++++ system-config/libvirt/auth.conf
2025-09-29 10:09:28.505794 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-09-29 10:09:28.505809 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-09-29 10:09:28.505825 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-09-29 10:09:28.505840 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-09-29 10:09:28.505855 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-09-29 10:09:28.505871 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-09-29 10:09:28.505887 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-09-29 10:09:28.505902 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-09-29 10:09:28.505950 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-09-29 10:09:28.505970 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-09-29 10:09:28.505987 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-09-29 10:09:28.506002 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-09-29 10:09:28.506018 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-09-29 10:09:28.506033 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-09-29 10:09:28.506048 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-09-29 10:09:28.506063 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-09-29 10:09:28.862433 | controller | changed: .d..t...... ./
2025-09-29 10:09:29.210041 | controller | changed: .d..t...... ./
2025-09-29 10:09:29.225207 | 
2025-09-29 10:09:29.225302 | TASK [Return artifact to Zuul]
2025-09-29 10:09:29.253205 | controller | ok
2025-09-29 10:09:29.271416 | 
2025-09-29 10:09:29.271472 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-09-29 10:09:29.271569 | 
2025-09-29 10:09:29.271598 | PLAY RECAP
2025-09-29 10:09:29.271633 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-09-29 10:09:29.271655 | 
2025-09-29 10:09:29.343456 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-29 10:09:29.344209 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-29 10:09:29.844278 | 
2025-09-29 10:09:29.844379 | PLAY [all]
2025-09-29 10:09:29.862610 | 
2025-09-29 10:09:29.862686 | TASK [include_role : fetch-output]
2025-09-29 10:09:29.891011 | controller | ok
2025-09-29 10:09:29.907726 | 
2025-09-29 10:09:29.907823 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-29 10:09:29.951724 | controller | skipping: Conditional result was False
2025-09-29 10:09:29.957233 | 
2025-09-29 10:09:29.957312 | TASK [fetch-output : Set log path for single node]
2025-09-29 10:09:29.985380 | controller | ok
2025-09-29 10:09:29.990520 | 
2025-09-29 10:09:29.990596 | LOOP [fetch-output : Ensure local output dirs]
2025-09-29 10:09:30.308157 | controller -> localhost | ok: "/var/lib/zuul/builds/7f11a15d9b01404283b6c04d31b0602f/work/logs"
2025-09-29 10:09:30.498276 | controller -> localhost | ok: "/var/lib/zuul/builds/7f11a15d9b01404283b6c04d31b0602f/work/artifacts"
2025-09-29 10:09:30.679415 | controller -> localhost | ok: "/var/lib/zuul/builds/7f11a15d9b01404283b6c04d31b0602f/work/docs"
2025-09-29 10:09:30.689816 | 
2025-09-29 10:09:30.689943 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-29 10:09:31.186034 | controller | ok
2025-09-29 10:09:31.186248 | controller | ok: All items complete
2025-09-29 10:09:31.186278 | 
2025-09-29 10:09:31.550616 | controller | ok
2025-09-29 10:09:31.906813 | controller | ok
2025-09-29 10:09:31.927317 | 
2025-09-29 10:09:31.927418 | TASK [include_role : fetch-output-openshift]
2025-09-29 10:09:31.940872 | controller | skipping: Conditional result was False
2025-09-29 10:09:31.946240 | 
2025-09-29 10:09:31.946312 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-09-29 10:09:32.279200 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006126
2025-09-29 10:09:32.470309 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006534
2025-09-29 10:09:32.499711 | 
2025-09-29 10:09:32.499809 | PLAY [all]
2025-09-29 10:09:32.513535 | 
2025-09-29 10:09:32.513603 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-09-29 10:09:32.897413 | controller | changed
2025-09-29 10:09:32.918222 | 
2025-09-29 10:09:32.918277 | PLAY RECAP
2025-09-29 10:09:32.918323 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-29 10:09:32.918346 | 
2025-09-29 10:09:32.983433 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-29 10:09:32.984195 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-09-29 10:09:33.521801 | 
2025-09-29 10:09:33.521897 | PLAY [localhost]
2025-09-29 10:09:33.538976 | 
2025-09-29 10:09:33.539053 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-09-29 10:09:33.830041 | localhost | changed
2025-09-29 10:09:33.834459 | 
2025-09-29 10:09:33.834539 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-09-29 10:09:33.852250 | localhost | ok
2025-09-29 10:09:33.859941 | 
2025-09-29 10:09:33.860016 | TASK [add-fileserver : Create SSH private key tempfile]
2025-09-29 10:09:34.150864 | localhost | changed
2025-09-29 10:09:34.156293 | 
2025-09-29 10:09:34.156361 | TASK [add-fileserver : Create SSH private key from secret]
2025-09-29 10:09:34.680134 | localhost | changed
2025-09-29 10:09:34.684829 | 
2025-09-29 10:09:34.684895 | TASK [add-fileserver : Add fileserver ssh key]
2025-09-29 10:09:35.006018 | localhost | Identity added: /var/lib/zuul/builds/7f11a15d9b01404283b6c04d31b0602f/work/tmp/ansible.o9pe13pl (/var/lib/zuul/builds/7f11a15d9b01404283b6c04d31b0602f/work/tmp/ansible.o9pe13pl)
2025-09-29 10:09:35.006185 | localhost | ok: Runtime: 0:00:00.006173
2025-09-29 10:09:35.010394 | 
2025-09-29 10:09:35.010466 | TASK [add-fileserver : Remove SSH private key from disk]
2025-09-29 10:09:35.220296 | localhost | ok: Runtime: 0:00:00.004094
2025-09-29 10:09:35.225039 | 
2025-09-29 10:09:35.225124 | TASK [add-fileserver : Add fileserver to inventory]
2025-09-29 10:09:35.273015 | localhost | changed
2025-09-29 10:09:35.278082 | 
2025-09-29 10:09:35.278205 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-09-29 10:09:35.589058 | localhost | changed
2025-09-29 10:09:35.607899 | 
2025-09-29 10:09:35.607995 | PLAY [localhost]
2025-09-29 10:09:35.619046 | 
2025-09-29 10:09:35.619111 | TASK [Generate bulk log download script]
2025-09-29 10:09:35.636903 | localhost | ok
2025-09-29 10:09:35.648311 | 
2025-09-29 10:09:35.648383 | TASK [local-log-download : Check API endpoint is defined]
2025-09-29 10:09:35.675442 | localhost | ok: All assertions passed
2025-09-29 10:09:35.680070 | 
2025-09-29 10:09:35.680136 | TASK [local-log-download : Create download script]
2025-09-29 10:09:36.017539 | localhost -> localhost | changed
2025-09-29 10:09:36.026332 | 
2025-09-29 10:09:36.026410 | TASK [Register quick-download link]
2025-09-29 10:09:36.043627 | localhost | ok
2025-09-29 10:09:36.076396 | 
2025-09-29 10:09:36.076465 | PLAY [logserver.rdoproject.org]
2025-09-29 10:09:36.085713 | 
2025-09-29 10:09:36.085784 | TASK [Set zuul-log-path fact]
2025-09-29 10:09:36.101164 | logserver.rdoproject.org | ok
2025-09-29 10:09:36.110116 | 
2025-09-29 10:09:36.110182 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-29 10:09:36.126220 | logserver.rdoproject.org | ok
2025-09-29 10:09:36.131629 | 
2025-09-29 10:09:36.131694 | TASK [upload-logs : Create log directories]
2025-09-29 10:09:37.992877 | logserver.rdoproject.org | changed
2025-09-29 10:09:37.996216 | 
2025-09-29 10:09:37.996296 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-09-29 10:09:38.222455 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005636
2025-09-29 10:09:38.227043 | 
2025-09-29 10:09:38.227111 | TASK [upload-logs : Upload logs to log server]
2025-09-29 10:09:39.781437 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-09-29 10:09:39.784365 | 
2025-09-29 10:09:39.784438 | LOOP [upload-logs : Compress console log and json output]
2025-09-29 10:09:39.824604 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 10:09:39.832550 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 10:09:39.842067 | 
2025-09-29 10:09:39.842155 | LOOP [upload-logs : Upload compressed console log and json output]
2025-09-29 10:09:39.878238 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 10:09:39.878467 | 
2025-09-29 10:09:39.881667 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 10:09:39.889963 | 
2025-09-29 10:09:39.890052 | LOOP [upload-logs : Upload console log and json output]