2025-09-29 09:07:14.894053 | Job console starting...
2025-09-29 09:07:15.129649 | Updating repositories
2025-09-29 09:07:15.843735 | Preparing job workspace
2025-09-29 09:07:23.945905 | Running Ansible setup...
2025-09-29 09:07:26.982629 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-29 09:07:27.552005 | 
2025-09-29 09:07:27.552119 | PLAY [localhost]
2025-09-29 09:07:27.560772 | 
2025-09-29 09:07:27.560850 | TASK [Gathering Facts]
2025-09-29 09:07:28.408840 | localhost | ok
2025-09-29 09:07:28.422761 | 
2025-09-29 09:07:28.422844 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-09-29 09:07:28.771986 | localhost -> localhost | changed
2025-09-29 09:07:28.779404 | 
2025-09-29 09:07:28.779483 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-09-29 09:07:29.557727 | localhost -> localhost | changed
2025-09-29 09:07:29.567490 | 
2025-09-29 09:07:29.567566 | TASK [Setup log path fact]
2025-09-29 09:07:29.585942 | localhost | ok
2025-09-29 09:07:29.597239 | 
2025-09-29 09:07:29.597306 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-29 09:07:29.615003 | localhost | ok
2025-09-29 09:07:29.622071 | 
2025-09-29 09:07:29.622139 | TASK [emit-job-header : Print job information]
2025-09-29 09:07:29.649201 | # Job Information
2025-09-29 09:07:29.649317 | Ansible Version: 2.15.12
2025-09-29 09:07:29.649347 | Job: edpm-ansible-molecule-edpm_nova
2025-09-29 09:07:29.649370 | Pipeline: github-check
2025-09-29 09:07:29.649390 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-09-29 09:07:29.649409 | Triggered by: https://github.com/openstack-k8s-operators/edpm-ansible/pull/1032
2025-09-29 09:07:29.649428 | Log URL (when completed): https://logserver.rdoproject.org/320/rdoproject.org/3206f99370a747a1b209bc4f426808fb/
2025-09-29 09:07:29.649447 | Event ID: 841a7030-9d13-11f0-990d-60304faacb5e
2025-09-29 09:07:29.652931 | 
2025-09-29 09:07:29.652994 | LOOP [emit-job-header : Print node information]
2025-09-29 09:07:29.757201 | localhost | ok:
2025-09-29 09:07:29.757410 | localhost | # Node Information
2025-09-29 09:07:29.757440 | localhost | Inventory Hostname: controller
2025-09-29 09:07:29.757463 | localhost | Hostname: np0005458520
2025-09-29 09:07:29.757482 | localhost | Username: zuul
2025-09-29 09:07:29.757502 | localhost | Distro: CentOS 9
2025-09-29 09:07:29.757518 | localhost | Provider: ibm-bm4-nodepool
2025-09-29 09:07:29.757535 | localhost | Region: regionOne
2025-09-29 09:07:29.757551 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-09-29 09:07:29.757572 | localhost | Product Name: OpenStack Compute
2025-09-29 09:07:29.757589 | localhost | Interface IP: 192.168.26.192
2025-09-29 09:07:29.781268 | 
2025-09-29 09:07:29.781333 | PLAY [all]
2025-09-29 09:07:29.787937 | 
2025-09-29 09:07:29.788007 | TASK [Gather network facts]
2025-09-29 09:07:30.133824 | controller | ok
2025-09-29 09:07:30.157487 | 
2025-09-29 09:07:30.157574 | TASK [include_role : start-zuul-console]
2025-09-29 09:07:30.196303 | controller | ok
2025-09-29 09:07:30.210060 | 
2025-09-29 09:07:30.210163 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-09-29 09:07:30.570384 | controller | ok
2025-09-29 09:07:30.578676 | 
2025-09-29 09:07:30.578744 | TASK [include_role : add-build-sshkey]
2025-09-29 09:07:30.599768 | controller | ok
2025-09-29 09:07:30.623600 | 
2025-09-29 09:07:30.623666 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-09-29 09:07:30.852726 | controller -> localhost | ok
2025-09-29 09:07:30.859587 | 
2025-09-29 09:07:30.859653 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-09-29 09:07:30.890128 | controller | ok
2025-09-29 09:07:30.906710 | controller | included: /var/lib/zuul/builds/3206f99370a747a1b209bc4f426808fb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-09-29 09:07:30.912469 | 
2025-09-29 09:07:30.912532 | TASK [add-build-sshkey : Create Temp SSH key]
2025-09-29 09:07:31.504205 | controller -> localhost | Generating public/private rsa key pair.
2025-09-29 09:07:31.504561 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3206f99370a747a1b209bc4f426808fb/work/3206f99370a747a1b209bc4f426808fb_id_rsa.
2025-09-29 09:07:31.504597 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3206f99370a747a1b209bc4f426808fb/work/3206f99370a747a1b209bc4f426808fb_id_rsa.pub.
2025-09-29 09:07:31.504620 | controller -> localhost | The key fingerprint is:
2025-09-29 09:07:31.504640 | controller -> localhost | SHA256:/JyJpoKZy9wlMvMXLHC1hWx0vN15PHBYzCD83J4b+T0 zuul-build-sshkey
2025-09-29 09:07:31.504660 | controller -> localhost | The key's randomart image is:
2025-09-29 09:07:31.504679 | controller -> localhost | +---[RSA 3072]----+
2025-09-29 09:07:31.504697 | controller -> localhost | |     o.o... .*.  |
2025-09-29 09:07:31.504716 | controller -> localhost | |      =.o ..o +  |
2025-09-29 09:07:31.504735 | controller -> localhost | |     o o o + *   |
2025-09-29 09:07:31.504753 | controller -> localhost | |  . . ... . = =  |
2025-09-29 09:07:31.504770 | controller -> localhost | |   o .  S    o + |
2025-09-29 09:07:31.504787 | controller -> localhost | |    . o  + o  =  |
2025-09-29 09:07:31.504804 | controller -> localhost | |  ++....o =    +.|
2025-09-29 09:07:31.504823 | controller -> localhost | | o+*.o.o      .Eo|
2025-09-29 09:07:31.504840 | controller -> localhost | |  +.oo.         .|
2025-09-29 09:07:31.504857 | controller -> localhost | +----[SHA256]-----+
2025-09-29 09:07:31.504931 | controller -> localhost | ok: Runtime: 0:00:00.166624
2025-09-29 09:07:31.511146 | 
2025-09-29 09:07:31.511216 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-09-29 09:07:31.542999 | controller | ok
2025-09-29 09:07:31.554255 | controller | included: /var/lib/zuul/builds/3206f99370a747a1b209bc4f426808fb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-09-29 09:07:31.561508 | 
2025-09-29 09:07:31.561572 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-09-29 09:07:31.587662 | controller | skipping: Conditional result was False
2025-09-29 09:07:31.593155 | 
2025-09-29 09:07:31.593235 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-09-29 09:07:31.958425 | controller | changed
2025-09-29 09:07:31.963600 | 
2025-09-29 09:07:31.963675 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-09-29 09:07:32.157562 | controller | ok
2025-09-29 09:07:32.162810 | 
2025-09-29 09:07:32.162887 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-09-29 09:07:32.733648 | controller | changed
2025-09-29 09:07:32.738399 | 
2025-09-29 09:07:32.738473 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-09-29 09:07:33.316429 | controller | changed
2025-09-29 09:07:33.321405 | 
2025-09-29 09:07:33.321469 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-09-29 09:07:33.347001 | controller | skipping: Conditional result was False
2025-09-29 09:07:33.352924 | 
2025-09-29 09:07:33.353297 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-09-29 09:07:33.696676 | controller -> localhost | changed
2025-09-29 09:07:33.706995 | 
2025-09-29 09:07:33.707090 | TASK [add-build-sshkey : Add back temp key]
2025-09-29 09:07:33.959719 | controller -> localhost | Identity added: /var/lib/zuul/builds/3206f99370a747a1b209bc4f426808fb/work/3206f99370a747a1b209bc4f426808fb_id_rsa (zuul-build-sshkey)
2025-09-29 09:07:33.959947 | controller -> localhost | ok: Runtime: 0:00:00.007102
2025-09-29 09:07:33.966305 | 
2025-09-29 09:07:33.966372 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-09-29 09:07:34.270299 | controller | ok
2025-09-29 09:07:34.274899 | 
2025-09-29 09:07:34.276244 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-09-29 09:07:34.322121 | controller | skipping: Conditional result was False
2025-09-29 09:07:34.331643 | 
2025-09-29 09:07:34.331711 | TASK [include_role : validate-host]
2025-09-29 09:07:34.348725 | controller | ok
2025-09-29 09:07:34.370117 | 
2025-09-29 09:07:34.370183 | TASK [validate-host : Define zuul_info_dir fact]
2025-09-29 09:07:34.396861 | controller | ok
2025-09-29 09:07:34.401307 | 
2025-09-29 09:07:34.401370 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-09-29 09:07:34.616258 | controller -> localhost | ok
2025-09-29 09:07:34.622835 | 
2025-09-29 09:07:34.622943 | TASK [validate-host : Collect information about the host]
2025-09-29 09:07:35.217829 | controller | ok
2025-09-29 09:07:35.241817 | 
2025-09-29 09:07:35.241903 | TASK [validate-host : Sanitize hostname]
2025-09-29 09:07:35.402837 | controller | ok
2025-09-29 09:07:35.453468 | 
2025-09-29 09:07:35.453565 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-09-29 09:07:36.188816 | controller -> localhost | changed
2025-09-29 09:07:36.194202 | 
2025-09-29 09:07:36.194270 | TASK [validate-host : Collect information about zuul worker]
2025-09-29 09:07:36.541872 | controller | ok
2025-09-29 09:07:36.546478 | 
2025-09-29 09:07:36.546543 | TASK [validate-host : Write out all zuul information for each host]
2025-09-29 09:07:37.097558 | controller -> localhost | changed
2025-09-29 09:07:37.107304 | 
2025-09-29 09:07:37.107371 | TASK [include_role : prepare-workspace-openshift]
2025-09-29 09:07:37.150476 | controller | skipping: Conditional result was False
2025-09-29 09:07:37.155887 | 
2025-09-29 09:07:37.155977 | TASK [include_role : remove-zuul-sshkey]
2025-09-29 09:07:37.199762 | controller | skipping: Conditional result was False
2025-09-29 09:07:37.206014 | 
2025-09-29 09:07:37.206099 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-09-29 09:07:37.415785 | controller | ok: "logs"
2025-09-29 09:07:37.416087 | controller | ok: All items complete
2025-09-29 09:07:37.416121 | 
2025-09-29 09:07:37.574890 | controller | ok: "artifacts"
2025-09-29 09:07:37.737868 | controller | ok: "docs"
2025-09-29 09:07:37.745183 | 
2025-09-29 09:07:37.745283 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-09-29 09:07:37.953479 | controller | changed: "logs"
2025-09-29 09:07:38.119725 | controller | changed: "artifacts"
2025-09-29 09:07:38.283491 | controller | changed: "docs"
2025-09-29 09:07:38.311724 | 
2025-09-29 09:07:38.311815 | PLAY RECAP
2025-09-29 09:07:38.311860 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-29 09:07:38.311889 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-29 09:07:38.311925 | 
2025-09-29 09:07:38.395697 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-29 09:07:38.396456 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-29 09:07:38.975425 | 
2025-09-29 09:07:38.975542 | PLAY [all]
2025-09-29 09:07:39.029279 | 
2025-09-29 09:07:39.029365 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-29 09:07:39.089591 | controller | ok
2025-09-29 09:07:39.094520 | 
2025-09-29 09:07:39.094596 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-29 09:07:39.546390 | controller | changed
2025-09-29 09:07:39.556243 | 
2025-09-29 09:07:39.556348 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-29 09:07:40.506989 | controller | changed
2025-09-29 09:07:40.516555 | 
2025-09-29 09:07:40.516628 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-09-29 09:07:40.941698 | controller | changed:
2025-09-29 09:07:40.941886 | controller | {
2025-09-29 09:07:40.941946 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-09-29 09:07:40.941983 | controller | }
2025-09-29 09:07:41.164985 | controller | changed:
2025-09-29 09:07:41.165105 | controller | {
2025-09-29 09:07:41.165136 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-09-29 09:07:41.165159 | controller | }
2025-09-29 09:07:41.383021 | controller | changed:
2025-09-29 09:07:41.383169 | controller | {
2025-09-29 09:07:41.383201 | 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:07:41.383225 | controller | }
2025-09-29 09:07:41.592893 | controller | changed:
2025-09-29 09:07:41.592994 | controller | {
2025-09-29 09:07:41.593043 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-09-29 09:07:41.593066 | controller | }
2025-09-29 09:07:41.834602 | controller | changed:
2025-09-29 09:07:41.834687 | controller | {
2025-09-29 09:07:41.834714 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-09-29 09:07:41.834735 | controller | }
2025-09-29 09:07:42.052422 | controller | changed:
2025-09-29 09:07:42.052518 | controller | {
2025-09-29 09:07:42.052549 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-09-29 09:07:42.052573 | controller | }
2025-09-29 09:07:42.250971 | controller | changed:
2025-09-29 09:07:42.251083 | controller | {
2025-09-29 09:07:42.251111 | 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:07:42.251132 | controller | }
2025-09-29 09:07:42.452021 | controller | changed:
2025-09-29 09:07:42.452126 | controller | {
2025-09-29 09:07:42.452154 | 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:07:42.452176 | controller | }
2025-09-29 09:07:42.655166 | controller | changed:
2025-09-29 09:07:42.655240 | controller | {
2025-09-29 09:07:42.655267 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-09-29 09:07:42.655287 | controller | }
2025-09-29 09:07:42.861709 | controller | changed:
2025-09-29 09:07:42.861806 | controller | {
2025-09-29 09:07:42.861834 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-09-29 09:07:42.861854 | controller | }
2025-09-29 09:07:43.062686 | controller | changed:
2025-09-29 09:07:43.062755 | controller | {
2025-09-29 09:07:43.062781 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-09-29 09:07:43.062802 | controller | }
2025-09-29 09:07:43.279327 | controller | changed:
2025-09-29 09:07:43.279420 | controller | {
2025-09-29 09:07:43.279456 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-09-29 09:07:43.279478 | controller | }
2025-09-29 09:07:43.477851 | controller | changed:
2025-09-29 09:07:43.477922 | controller | {
2025-09-29 09:07:43.477956 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-09-29 09:07:43.477979 | controller | }
2025-09-29 09:07:43.697771 | controller | changed:
2025-09-29 09:07:43.697826 | controller | {
2025-09-29 09:07:43.697852 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-09-29 09:07:43.697873 | controller | }
2025-09-29 09:07:43.917382 | controller | changed:
2025-09-29 09:07:43.917467 | controller | {
2025-09-29 09:07:43.917494 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-09-29 09:07:43.917514 | controller | }
2025-09-29 09:07:44.118703 | controller | changed:
2025-09-29 09:07:44.118778 | controller | {
2025-09-29 09:07:44.118804 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-09-29 09:07:44.118825 | controller | }
2025-09-29 09:07:44.319946 | controller | changed:
2025-09-29 09:07:44.320004 | controller | {
2025-09-29 09:07:44.320044 | 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:07:44.320069 | controller | }
2025-09-29 09:07:44.523638 | controller | changed:
2025-09-29 09:07:44.523729 | controller | {
2025-09-29 09:07:44.523756 | 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:07:44.523786 | controller | }
2025-09-29 09:07:44.726091 | controller | changed:
2025-09-29 09:07:44.726182 | controller | {
2025-09-29 09:07:44.726209 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-09-29 09:07:44.726229 | controller | }
2025-09-29 09:07:44.960343 | controller | changed:
2025-09-29 09:07:44.960430 | controller | {
2025-09-29 09:07:44.960457 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-09-29 09:07:44.960479 | controller | }
2025-09-29 09:07:45.145144 | controller | changed:
2025-09-29 09:07:45.145231 | controller | {
2025-09-29 09:07:45.145257 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-09-29 09:07:45.145277 | controller | }
2025-09-29 09:07:45.343610 | controller | changed:
2025-09-29 09:07:45.343689 | controller | {
2025-09-29 09:07:45.343715 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-09-29 09:07:45.343737 | controller | }
2025-09-29 09:07:45.539890 | controller | changed:
2025-09-29 09:07:45.539993 | controller | {
2025-09-29 09:07:45.540022 | 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:07:45.540054 | controller | }
2025-09-29 09:07:45.739001 | controller | changed:
2025-09-29 09:07:45.739094 | controller | {
2025-09-29 09:07:45.739136 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-09-29 09:07:45.739158 | controller | }
2025-09-29 09:07:45.928643 | controller | changed:
2025-09-29 09:07:45.928721 | controller | {
2025-09-29 09:07:45.928748 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-09-29 09:07:45.928770 | controller | }
2025-09-29 09:07:46.116520 | controller | changed:
2025-09-29 09:07:46.116621 | controller | {
2025-09-29 09:07:46.116649 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-09-29 09:07:46.116670 | controller | }
2025-09-29 09:07:46.138514 | 
2025-09-29 09:07:46.138617 | TASK [Set timezone to UTC]
2025-09-29 09:07:46.522729 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-09-29 09:07:46.528284 | 
2025-09-29 09:07:46.528352 | TASK [Create nodepool directory]
2025-09-29 09:07:46.740512 | controller | changed
2025-09-29 09:07:46.745715 | 
2025-09-29 09:07:46.745781 | TASK [Create nodepool sub_nodes file]
2025-09-29 09:07:47.230080 | controller | changed
2025-09-29 09:07:47.234846 | 
2025-09-29 09:07:47.234929 | TASK [Create nodepool sub_nodes_private file]
2025-09-29 09:07:47.712158 | controller | changed
2025-09-29 09:07:47.717531 | 
2025-09-29 09:07:47.717599 | LOOP [Populate nodepool sub_nodes file]
2025-09-29 09:07:47.760559 | 
2025-09-29 09:07:47.760754 | LOOP [Populate nodepool sub_nodes_private file]
2025-09-29 09:07:47.799772 | 
2025-09-29 09:07:47.800371 | TASK [Create nodepool primary file]
2025-09-29 09:07:47.819463 | controller | skipping: Conditional result was False
2025-09-29 09:07:47.826025 | 
2025-09-29 09:07:47.826117 | TASK [Create nodepool node_private for this node]
2025-09-29 09:07:48.375016 | controller | changed
2025-09-29 09:07:48.383564 | 
2025-09-29 09:07:48.383691 | LOOP [Copy ssh keys to nodepool directory]
2025-09-29 09:07:48.727038 | controller | ok: Item: id_rsa Runtime: 0:00:00.005128
2025-09-29 09:07:48.727271 | 
2025-09-29 09:07:48.884288 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004293
2025-09-29 09:07:48.897374 | 
2025-09-29 09:07:48.897483 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-09-29 09:07:49.422549 | controller | changed
2025-09-29 09:07:49.427886 | 
2025-09-29 09:07:49.427963 | TASK [Validate sudoers config after edits]
2025-09-29 09:07:49.643066 | controller | /etc/sudoers: parsed OK
2025-09-29 09:07:49.643163 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-09-29 09:07:49.643175 | controller | /etc/sudoers.d/zuul: parsed OK
2025-09-29 09:07:49.643181 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-09-29 09:07:49.951859 | controller | ok: Runtime: 0:00:00.007270
2025-09-29 09:07:49.957277 | 
2025-09-29 09:07:49.957341 | TASK [Show the environment passed in to job shell scripts]
2025-09-29 09:07:50.164812 | controller | SHELL=/bin/bash
2025-09-29 09:07:50.164852 | controller | ZUUL_PROJECT=openstack-k8s-operators/edpm-ansible
2025-09-29 09:07:50.164862 | controller | ZUUL_SHORT_PROJECT_NAME=edpm-ansible
2025-09-29 09:07:50.164868 | controller | ZUUL_CHANGES=openstack-k8s-operators/edpm-ansible:main:refs/changes/32/1032/b3b694a4829e5149bbe139d1b1d431d1aa126e87
2025-09-29 09:07:50.164874 | controller | PWD=/home/zuul
2025-09-29 09:07:50.164879 | controller | ZUUL_PIPELINE=github-check
2025-09-29 09:07:50.164885 | controller | LOGNAME=zuul
2025-09-29 09:07:50.164914 | controller | XDG_SESSION_TYPE=tty
2025-09-29 09:07:50.164977 | controller | _=/usr/bin/env
2025-09-29 09:07:50.164987 | controller | MOTD_SHOWN=pam
2025-09-29 09:07:50.164993 | controller | HOME=/home/zuul
2025-09-29 09:07:50.164998 | controller | LANG=en_US.UTF-8
2025-09-29 09:07:50.165004 | controller | SSH_CONNECTION=192.168.26.12 47092 192.168.26.192 22
2025-09-29 09:07:50.165009 | 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:07:50.165018 | controller | ZUUL_CHANGE_IDS=1032,b3b694a4829e5149bbe139d1b1d431d1aa126e87
2025-09-29 09:07:50.165023 | controller | WORKSPACE=/home/zuul/workspace
2025-09-29 09:07:50.165029 | controller | XDG_SESSION_CLASS=user
2025-09-29 09:07:50.165034 | controller | SELINUX_ROLE_REQUESTED=
2025-09-29 09:07:50.165039 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-09-29 09:07:50.165045 | controller | USER=zuul
2025-09-29 09:07:50.165050 | controller | ZUUL_VOTING=True
2025-09-29 09:07:50.165055 | controller | BUILD_TIMEOUT=1800000
2025-09-29 09:07:50.165060 | controller | SELINUX_USE_CURRENT_RANGE=
2025-09-29 09:07:50.165065 | controller | SHLVL=1
2025-09-29 09:07:50.165071 | controller | ZUUL_PATCHSET=b3b694a4829e5149bbe139d1b1d431d1aa126e87
2025-09-29 09:07:50.165076 | controller | XDG_SESSION_ID=1
2025-09-29 09:07:50.165081 | controller | ZUUL_BRANCH=main
2025-09-29 09:07:50.165087 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-09-29 09:07:50.165092 | controller | SSH_CLIENT=192.168.26.12 47092 22
2025-09-29 09:07:50.165097 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-09-29 09:07:50.165103 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-09-29 09:07:50.165108 | controller | which_declare=declare -f
2025-09-29 09:07:50.165114 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-09-29 09:07:50.165119 | controller | SELINUX_LEVEL_REQUESTED=
2025-09-29 09:07:50.165124 | controller | ZUUL_CHANGE=1032
2025-09-29 09:07:50.165129 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-09-29 09:07:50.165145 | controller | ZUUL_UUID=3206f99370a747a1b209bc4f426808fb
2025-09-29 09:07:50.165151 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-09-29 09:07:50.165158 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-09-29 09:07:50.165163 | controller | }
2025-09-29 09:07:50.490082 | controller | ok: Runtime: 0:00:00.005985
2025-09-29 09:07:50.495850 | 
2025-09-29 09:07:50.495933 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-09-29 09:07:50.509431 | controller | skipping: Conditional result was False
2025-09-29 09:07:50.514740 | 
2025-09-29 09:07:50.514805 | TASK [Symlink /home/zuul-worker/workspace]
2025-09-29 09:07:51.043061 | controller | skipping: Conditional result was False
2025-09-29 09:07:51.049085 | 
2025-09-29 09:07:51.049175 | TASK [Ensure legacy workspace directory]
2025-09-29 09:07:51.230127 | controller | changed
2025-09-29 09:07:51.247248 | 
2025-09-29 09:07:51.247310 | PLAY RECAP
2025-09-29 09:07:51.247361 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-29 09:07:51.247385 | 
2025-09-29 09:07:51.320290 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-29 09:07:51.321120 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/read_global_vars.yml@main]
2025-09-29 09:07:51.830616 | 
2025-09-29 09:07:51.830711 | PLAY [Load global variables]
2025-09-29 09:07:51.849864 | 
2025-09-29 09:07:51.849966 | TASK [Read group_vars all file]
2025-09-29 09:07:51.879803 | controller | ok
2025-09-29 09:07:51.894506 | 
2025-09-29 09:07:51.894581 | TASK [cifmw_helpers : Check if file is available]
2025-09-29 09:07:52.182653 | controller | ok
2025-09-29 09:07:52.187970 | 
2025-09-29 09:07:52.188063 | TASK [cifmw_helpers : Read vars]
2025-09-29 09:07:52.217596 | 
2025-09-29 09:07:52.217720 | LOOP [cifmw_helpers : Set vars as fact]
2025-09-29 09:07:52.276505 | 
2025-09-29 09:07:52.276655 | PLAY RECAP
2025-09-29 09:07:52.276703 | controller | ok: 1 changed: 0 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-29 09:07:52.276726 | 
2025-09-29 09:07:52.348664 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/read_global_vars.yml@main]
2025-09-29 09:07:52.349445 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-29 09:07:52.865268 | 
2025-09-29 09:07:52.865365 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-09-29 09:07:52.884872 | 
2025-09-29 09:07:52.884960 | TASK [Create zuul-output directory]
2025-09-29 09:07:53.177077 | controller | changed
2025-09-29 09:07:53.183091 | 
2025-09-29 09:07:53.183166 | TASK [Slurp Zuul inventory test]
2025-09-29 09:07:53.460023 | controller -> localhost | ok
2025-09-29 09:07:53.466855 | 
2025-09-29 09:07:53.466942 | TASK [Save zuul inventory]
2025-09-29 09:07:54.173727 | controller | changed
2025-09-29 09:07:54.178747 | 
2025-09-29 09:07:54.178824 | TASK [Save zuul vars without the change_message]
2025-09-29 09:07:54.740158 | controller | changed
2025-09-29 09:07:54.759298 | 
2025-09-29 09:07:54.759371 | PLAY RECAP
2025-09-29 09:07:54.759439 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-29 09:07:54.759466 | 
2025-09-29 09:07:54.826185 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-29 09:07:54.826868 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-29 09:07:55.371097 | 
2025-09-29 09:07:55.371203 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-09-29 09:07:55.391985 | 
2025-09-29 09:07:55.392068 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-09-29 09:07:55.410275 | controller | ok
2025-09-29 09:07:55.426802 | 
2025-09-29 09:07:55.426885 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-29 09:07:55.450100 | controller | skipping: Conditional result was False
2025-09-29 09:07:55.455839 | 
2025-09-29 09:07:55.455923 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-29 09:07:55.757837 | controller | ok
2025-09-29 09:07:55.763466 | 
2025-09-29 09:07:55.763537 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-29 09:07:56.339007 | controller | ok
2025-09-29 09:07:56.351553 | 
2025-09-29 09:07:56.351620 | TASK [Prepare workspace]
2025-09-29 09:07:56.369683 | controller | ok
2025-09-29 09:07:56.386241 | 
2025-09-29 09:07:56.386305 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-09-29 09:07:56.679479 | controller | ok
2025-09-29 09:07:56.685433 | 
2025-09-29 09:07:56.685515 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-09-29 09:07:58.991693 | controller | Output suppressed because no_log was given
2025-09-29 09:07:59.005725 | 
2025-09-29 09:07:59.005821 | LOOP [Create zuul-output directory]
2025-09-29 09:07:59.191722 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-09-29 09:07:59.354093 | controller | ok: "/home/zuul/zuul-output/logs"
2025-09-29 09:07:59.361310 | 
2025-09-29 09:07:59.361404 | TASK [Install required packages]
2025-09-29 09:08:57.525326 | controller | changed
2025-09-29 09:08:57.530311 | 
2025-09-29 09:08:57.530371 | TASK [Install venv]
2025-09-29 09:09:58.463824 | controller | changed
2025-09-29 09:09:58.506563 | 
2025-09-29 09:09:58.506642 | PLAY RECAP
2025-09-29 09:09:58.506687 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-29 09:09:58.506711 | 
2025-09-29 09:09:58.564106 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-29 09:09:58.564902 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-29 09:09:59.066714 | 
2025-09-29 09:09:59.066813 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-09-29 09:09:59.086322 | 
2025-09-29 09:09:59.086394 | TASK [Gather required facts]
2025-09-29 09:09:59.550831 | controller | ok
2025-09-29 09:09:59.556236 | 
2025-09-29 09:09:59.556316 | TASK [Load environment var if instructed to]
2025-09-29 09:09:59.580652 | controller | skipping: Conditional result was False
2025-09-29 09:09:59.586076 | 
2025-09-29 09:09:59.586141 | TASK [Run molecule]
2025-09-29 09:10:00.665470 | controller | WARNING  Driver vagrant does not provide a schema.
2025-09-29 09:10:00.668094 | controller | INFO     Performing prerun with role_name_check=0...
2025-09-29 09:10:01.472529 | controller | INFO     Running default > destroy
2025-09-29 09:10:01.472888 | controller | WARNING  Skipping, instances are delegated.
2025-09-29 09:10:01.480808 | controller | INFO     Running default > create
2025-09-29 09:10:01.481759 | controller | WARNING  Skipping, instances are delegated.
2025-09-29 09:10:01.495265 | controller | INFO     Running default > prepare
2025-09-29 09:10:02.216974 | controller |
2025-09-29 09:10:02.217188 | controller | PLAY [Prepare] *****************************************************************
2025-09-29 09:10:02.217329 | controller |
2025-09-29 09:10:02.217448 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 09:10:02.217568 | controller | Monday 29 September 2025  09:10:02 +0000 (0:00:00.012)       0:00:00.012 ******
2025-09-29 09:10:03.050997 | controller | ok: [compute-1]
2025-09-29 09:10:03.051043 | controller |
2025-09-29 09:10:03.051054 | controller | TASK [../../../../molecule/common/test_deps : Validating arguments against arg spec 'main' - The main entry point for the test_deps role.] ***
2025-09-29 09:10:03.051066 | controller | Monday 29 September 2025  09:10:03 +0000 (0:00:00.834)       0:00:00.846 ******
2025-09-29 09:10:03.067869 | controller | ok: [compute-1]
2025-09-29 09:10:03.082980 | controller |
2025-09-29 09:10:03.083010 | controller | TASK [../../../../molecule/common/test_deps : Gather facts if they don't exist] ***
2025-09-29 09:10:03.083020 | controller | Monday 29 September 2025  09:10:03 +0000 (0:00:00.016)       0:00:00.863 ******
2025-09-29 09:10:03.083032 | controller | skipping: [compute-1]
2025-09-29 09:10:03.083054 | controller |
2025-09-29 09:10:03.083064 | controller | TASK [../../../../molecule/common/test_deps : Gather variables for each operating system] ***
2025-09-29 09:10:03.083356 | controller | Monday 29 September 2025  09:10:03 +0000 (0:00:00.015)       0:00:00.878 ******
2025-09-29 09:10:03.121286 | 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 09:10:03.121345 | controller |
2025-09-29 09:10:03.121457 | controller | TASK [../../../../molecule/common/test_deps : Detect mirrors file] *************
2025-09-29 09:10:03.121556 | controller | Monday 29 September 2025  09:10:03 +0000 (0:00:00.038)       0:00:00.917 ******
2025-09-29 09:10:03.396748 | controller | ok: [compute-1]
2025-09-29 09:10:03.396849 | controller |
2025-09-29 09:10:03.397039 | controller | TASK [../../../../molecule/common/test_deps : Disable ubi host subscription-manager integration] ***
2025-09-29 09:10:03.397154 | controller | Monday 29 September 2025  09:10:03 +0000 (0:00:00.275)       0:00:01.192 ******
2025-09-29 09:10:03.682090 | controller | ok: [compute-1]
2025-09-29 09:10:03.682195 | controller |
2025-09-29 09:10:03.682313 | controller | TASK [../../../../molecule/common/test_deps : Remove ubi 9 repos] **************
2025-09-29 09:10:03.682412 | controller | Monday 29 September 2025  09:10:03 +0000 (0:00:00.285)       0:00:01.477 ******
2025-09-29 09:10:03.890380 | controller | ok: [compute-1]
2025-09-29 09:10:03.890465 | controller |
2025-09-29 09:10:03.890577 | controller | TASK [../../../../molecule/common/test_deps : Fetch latest repo version] *******
2025-09-29 09:10:03.890691 | controller | Monday 29 September 2025  09:10:03 +0000 (0:00:00.208)       0:00:01.686 ******
2025-09-29 09:10:04.426573 | controller | ok: [compute-1]
2025-09-29 09:10:04.426662 | controller |
2025-09-29 09:10:04.426774 | controller | TASK [../../../../molecule/common/test_deps : Create default repo file] ********
2025-09-29 09:10:04.426881 | controller | Monday 29 September 2025  09:10:04 +0000 (0:00:00.536)       0:00:02.222 ******
2025-09-29 09:10:04.862591 | controller | changed: [compute-1]
2025-09-29 09:10:04.862704 | controller |
2025-09-29 09:10:04.862826 | controller | TASK [../../../../molecule/common/test_deps : Install repo-setup pre-requirements] ***
2025-09-29 09:10:04.862979 | controller | Monday 29 September 2025  09:10:04 +0000 (0:00:00.436)       0:00:02.658 ******
2025-09-29 09:10:14.551121 | controller | ok: [compute-1]
2025-09-29 09:10:14.551185 | controller |
2025-09-29 09:10:14.551447 | controller | TASK [../../../../molecule/common/test_deps : Get repo-setup repository] *******
2025-09-29 09:10:15.726916 | controller | Monday 29 September 2025  09:10:14 +0000 (0:00:09.688)       0:00:12.346 ******
2025-09-29 09:10:15.726953 | controller | changed: [compute-1]
2025-09-29 09:10:19.070607 | controller |
2025-09-29 09:10:19.070636 | controller | TASK [../../../../molecule/common/test_deps : Install repo-setup] **************
2025-09-29 09:10:19.070648 | controller | Monday 29 September 2025  09:10:15 +0000 (0:00:01.175)       0:00:13.522 ******
2025-09-29 09:10:19.070658 | controller | changed: [compute-1]
2025-09-29 09:10:19.902883 | controller |
2025-09-29 09:10:19.902931 | controller | TASK [../../../../molecule/common/test_deps : Create repos] ********************
2025-09-29 09:10:19.902939 | controller | Monday 29 September 2025  09:10:19 +0000 (0:00:03.343)       0:00:16.866 ******
2025-09-29 09:10:19.902949 | controller | changed: [compute-1]
2025-09-29 09:10:20.122011 | controller |
2025-09-29 09:10:20.122045 | controller | TASK [../../../../molecule/common/test_deps : Look for redhat-release rpm] *****
2025-09-29 09:10:20.122053 | controller | Monday 29 September 2025  09:10:19 +0000 (0:00:00.832)       0:00:17.698 ******
2025-09-29 09:10:20.122071 | controller | fatal: [compute-1]: FAILED! => changed=true
2025-09-29 09:10:20.122100 | controller |   cmd: |-
2025-09-29 09:10:20.122107 | controller |     rpm -qe redhat-release
2025-09-29 09:10:20.122113 | controller |   delta: '0:00:00.011095'
2025-09-29 09:10:20.122119 | controller |   end: '2025-09-29 09:10:20.095220'
2025-09-29 09:10:20.122125 | controller |   msg: non-zero return code
2025-09-29 09:10:20.122131 | controller |   rc: 1
2025-09-29 09:10:20.122137 | controller |   start: '2025-09-29 09:10:20.084125'
2025-09-29 09:10:20.122143 | controller |   stderr: ''
2025-09-29 09:10:20.122148 | controller |   stderr_lines: <omitted>
2025-09-29 09:10:20.122156 | controller |   stdout: package redhat-release is not installed
2025-09-29 09:10:20.122178 | controller |   stdout_lines: <omitted>
2025-09-29 09:10:20.127754 | controller | ...ignoring
2025-09-29 09:10:20.158450 | controller |
2025-09-29 09:10:20.158469 | controller | TASK [../../../../molecule/common/test_deps : Workaround of redhat-release binary on ubi9] ***
2025-09-29 09:10:20.158477 | controller | Monday 29 September 2025  09:10:20 +0000 (0:00:00.225)       0:00:17.923 ******
2025-09-29 09:10:20.158486 | controller | skipping: [compute-1]
2025-09-29 09:11:32.960950 | controller |
2025-09-29 09:11:32.960985 | controller | TASK [../../../../molecule/common/test_deps : Install edpm packages] ***********
2025-09-29 09:11:32.961033 | controller | Monday 29 September 2025  09:10:20 +0000 (0:00:00.030)       0:00:17.954 ******
2025-09-29 09:11:32.961045 | controller | changed: [compute-1]
2025-09-29 09:11:34.152615 | controller |
2025-09-29 09:11:34.152644 | controller | TASK [../../../../molecule/common/test_deps : Install selinux python libs] *****
2025-09-29 09:11:34.152652 | controller | Monday 29 September 2025  09:11:32 +0000 (0:01:12.802)       0:01:30.756 ******
2025-09-29 09:11:34.152662 | controller | ok: [compute-1]
2025-09-29 09:11:35.324722 | controller |
2025-09-29 09:11:35.324751 | controller | TASK [../../../../molecule/common/test_deps : Install python yaml libs] ********
2025-09-29 09:11:35.324759 | controller | Monday 29 September 2025  09:11:34 +0000 (0:00:01.191)       0:01:31.948 ******
2025-09-29 09:11:35.324770 | controller | ok: [compute-1]
2025-09-29 09:11:35.353556 | controller |
2025-09-29 09:11:35.353575 | controller | TASK [../../../../molecule/common/test_deps : Install extra packages] **********
2025-09-29 09:11:35.353582 | controller | Monday 29 September 2025  09:11:35 +0000 (0:00:01.172)       0:01:33.120 ******
2025-09-29 09:11:35.353591 | controller | skipping: [compute-1]
2025-09-29 09:11:35.537487 | controller |
2025-09-29 09:11:35.537516 | controller | TASK [../../../../molecule/common/test_deps : Check for /etc/ansible/facts.d/bootc.fact] ***
2025-09-29 09:11:35.537523 | controller | Monday 29 September 2025  09:11:35 +0000 (0:00:00.029)       0:01:33.149 ******
2025-09-29 09:11:35.537532 | controller | ok: [compute-1]
2025-09-29 09:11:35.729534 | controller |
2025-09-29 09:11:35.729568 | controller | TASK [../../../../molecule/common/test_deps : Create /etc/ansible/facts.d] *****
2025-09-29 09:11:35.729576 | controller | Monday 29 September 2025  09:11:35 +0000 (0:00:00.183)       0:01:33.333 ******
2025-09-29 09:11:35.729585 | controller | changed: [compute-1]
2025-09-29 09:11:36.104987 | controller |
2025-09-29 09:11:36.105022 | controller | TASK [../../../../molecule/common/test_deps : Create /etc/ansible/facts.d/bootc.fact] ***
2025-09-29 09:11:36.105030 | controller | Monday 29 September 2025  09:11:35 +0000 (0:00:00.191)       0:01:33.525 ******
2025-09-29 09:11:36.105042 | controller | changed: [compute-1]
2025-09-29 09:11:36.123811 | controller |
2025-09-29 09:11:36.123835 | 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 09:11:36.123854 | controller | Monday 29 September 2025  09:11:36 +0000 (0:00:00.375)       0:01:33.900 ******
2025-09-29 09:11:36.123865 | controller | ok: [compute-1]
2025-09-29 09:11:36.860806 | controller |
2025-09-29 09:11:36.860839 | controller | TASK [osp.edpm.env_data : Gather all installed packages] ***********************
2025-09-29 09:11:36.860850 | controller | Monday 29 September 2025  09:11:36 +0000 (0:00:00.018)       0:01:33.919 ******
2025-09-29 09:11:36.860860 | controller | ok: [compute-1]
2025-09-29 09:11:37.230970 | controller |
2025-09-29 09:11:37.231001 | controller | TASK [osp.edpm.env_data : Gather repository list] ******************************
2025-09-29 09:11:37.231009 | controller | Monday 29 September 2025  09:11:36 +0000 (0:00:00.736)       0:01:34.656 ******
2025-09-29 09:11:37.231018 | controller | ok: [compute-1]
2025-09-29 09:11:37.231267 | controller |
2025-09-29 09:11:37.231281 | controller | TASK [osp.edpm.env_data : Output installed packages] ***************************
2025-09-29 09:11:37.274941 | controller | Monday 29 September 2025  09:11:37 +0000 (0:00:00.370)       0:01:35.026 ******
2025-09-29 09:11:37.274962 | controller | ok: [compute-1] =>
2025-09-29 09:11:37.275018 | controller |   msg:
2025-09-29 09:11:37.275026 | controller |   - tzdata
2025-09-29 09:11:37.275032 | controller |   - fonts-filesystem
2025-09-29 09:11:37.275038 | controller |   - adobe-source-code-pro-fonts
2025-09-29 09:11:37.275044 | controller |   - dejavu-sans-fonts
2025-09-29 09:11:37.275049 | controller |   - langpacks-core-font-en
2025-09-29 09:11:37.275055 | controller |   - abattis-cantarell-fonts
2025-09-29 09:11:37.275060 | controller |   - geolite2-country
2025-09-29 09:11:37.275065 | controller |   - geolite2-city
2025-09-29 09:11:37.275070 | controller |   - gawk-all-langpacks
2025-09-29 09:11:37.275075 | controller |   - quota-nls
2025-09-29 09:11:37.275081 | controller |   - python3-setuptools-wheel
2025-09-29 09:11:37.275086 | controller |   - publicsuffix-list-dafsa
2025-09-29 09:11:37.275091 | controller |   - pcre2-syntax
2025-09-29 09:11:37.275096 | controller |   - ncurses-base
2025-09-29 09:11:37.275101 | controller |   - libssh-config
2025-09-29 09:11:37.275106 | controller |   - libreport-filesystem
2025-09-29 09:11:37.275111 | controller |   - dnf-data
2025-09-29 09:11:37.275117 | controller |   - kbd-misc
2025-09-29 09:11:37.275122 | controller |   - kbd-legacy
2025-09-29 09:11:37.275127 | controller |   - hwdata
2025-09-29 09:11:37.275132 | controller |   - dhcp-common
2025-09-29 09:11:37.275138 | controller |   - coreutils-common
2025-09-29 09:11:37.275143 | controller |   - centos-gpg-keys
2025-09-29 09:11:37.275148 | controller |   - centos-stream-repos
2025-09-29 09:11:37.275153 | controller |   - centos-stream-release
2025-09-29 09:11:37.275158 | controller |   - setup
2025-09-29 09:11:37.275163 | controller |   - filesystem
2025-09-29 09:11:37.275172 | controller |   - basesystem
2025-09-29 09:11:37.275178 | controller |   - ncurses-libs
2025-09-29 09:11:37.275183 | controller |   - bash
2025-09-29 09:11:37.275188 | controller |   - libgcc
2025-09-29 09:11:37.275193 | controller |   - glibc-gconv-extra
2025-09-29 09:11:37.275198 | controller |   - glibc-langpack-en
2025-09-29 09:11:37.275204 | controller |   - glibc-common
2025-09-29 09:11:37.275209 | controller |   - glibc
2025-09-29 09:11:37.275214 | controller |   - zlib
2025-09-29 09:11:37.275219 | controller |   - popt
2025-09-29 09:11:37.275224 | controller |   - xz-libs
2025-09-29 09:11:37.275229 | controller |   - libcap
2025-09-29 09:11:37.275237 | controller |   - libzstd
2025-09-29 09:11:37.275387 | controller |   - libstdc++
2025-09-29 09:11:37.275519 | controller |   - libuuid
2025-09-29 09:11:37.275647 | controller |   - libxcrypt
2025-09-29 09:11:37.275773 | controller |   - sqlite-libs
2025-09-29 09:11:37.275923 | controller |   - bzip2-libs
2025-09-29 09:11:37.276074 | controller |   - libcom_err
2025-09-29 09:11:37.276205 | controller |   - libxml2
2025-09-29 09:11:37.276348 | controller |   - libcap-ng
2025-09-29 09:11:37.276476 | controller |   - audit-libs
2025-09-29 09:11:37.276600 | controller |   - libmnl
2025-09-29 09:11:37.276723 | controller |   - libtalloc
2025-09-29 09:11:37.276846 | controller |   - elfutils-libelf
2025-09-29 09:11:37.276994 | controller |   - readline
2025-09-29 09:11:37.277130 | controller |   - crypto-policies
2025-09-29 09:11:37.277257 | controller |   - alternatives
2025-09-29 09:11:37.277396 | controller |   - keyutils-libs
2025-09-29 09:11:37.277521 | controller |   - libunistring
2025-09-29 09:11:37.277644 | controller |   - lua-libs
2025-09-29 09:11:37.277766 | controller |   - expat
2025-09-29 09:11:37.277920 | controller |   - libffi
2025-09-29 09:11:37.278064 | controller |   - libgpg-error
2025-09-29 09:11:37.278190 | controller |   - libnl3
2025-09-29 09:11:37.278324 | controller |   - libsepol
2025-09-29 09:11:37.278452 | controller |   - libtdb
2025-09-29 09:11:37.278576 | controller |   - lz4-libs
2025-09-29 09:11:37.278711 | controller |   - libidn2
2025-09-29 09:11:37.278834 | controller |   - libtevent
2025-09-29 09:11:37.278978 | controller |   - gmp
2025-09-29 09:11:37.279107 | controller |   - jansson
2025-09-29 09:11:37.279232 | controller |   - json-c
2025-09-29 09:11:37.279365 | controller |   - libattr
2025-09-29 09:11:37.279491 | controller |   - libacl
2025-09-29 09:11:37.279615 | controller |   - libsmartcols
2025-09-29 09:11:37.279738 | controller |   - libtasn1
2025-09-29 09:11:37.279867 | controller |   - p11-kit
2025-09-29 09:11:37.280022 | controller |   - pcre2
2025-09-29 09:11:37.280154 | controller |   - libselinux
2025-09-29 09:11:37.280280 | controller |   - sed
2025-09-29 09:11:37.280417 | controller |   - libsemanage
2025-09-29 09:11:37.280542 | controller |   - shadow-utils
2025-09-29 09:11:37.280667 | controller |   - findutils
2025-09-29 09:11:37.280790 | controller |   - libgcrypt
2025-09-29 09:11:37.280940 | controller |   - file-libs
2025-09-29 09:11:37.281074 | controller |   - file
2025-09-29 09:11:37.281198 | controller |   - gdbm-libs
2025-09-29 09:11:37.281332 | controller |   - libedit
2025-09-29 09:11:37.281458 | controller |   - libref_array
2025-09-29 09:11:37.281581 | controller |   - libverto
2025-09-29 09:11:37.281706 | controller |   - psmisc
2025-09-29 09:11:37.281828 | controller |   - libselinux-utils
2025-09-29 09:11:37.281972 | controller |   - p11-kit-trust
2025-09-29 09:11:37.282099 | controller |   - libpsl
2025-09-29 09:11:37.282220 | controller |   - libassuan
2025-09-29 09:11:37.282354 | controller |   - keyutils
2025-09-29 09:11:37.282476 | controller |   - libbpf
2025-09-29 09:11:37.282606 | controller |   - iproute
2025-09-29 09:11:37.282730 | controller |   - e2fsprogs-libs
2025-09-29 09:11:37.282851 | controller |   - libicu
2025-09-29 09:11:37.282996 | controller |   - libproxy
2025-09-29 09:11:37.283124 | controller |   - fuse-libs
2025-09-29 09:11:37.283246 | controller |   - libbasicobjects
2025-09-29 09:11:37.283382 | controller |   - libbrotli
2025-09-29 09:11:37.283506 | controller |   - libcollection
2025-09-29 09:11:37.283628 | controller |   - libdhash
2025-09-29 09:11:37.283749 | controller |   - libgomp
2025-09-29 09:11:37.283871 | controller |   - libpath_utils
2025-09-29 09:11:37.284015 | controller |   - libini_config
2025-09-29 09:11:37.284144 | controller |   - libseccomp
2025-09-29 09:11:37.284268 | controller |   - libsigsegv
2025-09-29 09:11:37.284403 | controller |   - libsss_idmap
2025-09-29 09:11:37.284527 | controller |   - libyaml
2025-09-29 09:11:37.284651 | controller |   - lzo
2025-09-29 09:11:37.284779 | controller |   - numactl-libs
2025-09-29 09:11:37.284938 | controller |   - pcre
2025-09-29 09:11:37.285083 | controller |   - grep
2025-09-29 09:11:37.285212 | controller |   - openssl-fips-provider
2025-09-29 09:11:37.285350 | controller |   - openssl-libs
2025-09-29 09:11:37.285478 | controller |   - coreutils
2025-09-29 09:11:37.285606 | controller |   - ca-certificates
2025-09-29 09:11:37.285729 | controller |   - systemd-libs
2025-09-29 09:11:37.285855 | controller |   - libblkid
2025-09-29 09:11:37.286005 | controller |   - dbus-libs
2025-09-29 09:11:37.286138 | controller |   - libmount
2025-09-29 09:11:37.286263 | controller |   - kmod
2025-09-29 09:11:37.286400 | controller |   - util-linux-core
2025-09-29 09:11:37.286526 | controller |   - libfdisk
2025-09-29 09:11:37.286650 | controller |   - gzip
2025-09-29 09:11:37.286778 | controller |   - openssl
2025-09-29 09:11:37.286926 | controller |   - kmod-libs
2025-09-29 09:11:37.287067 | controller |   - libevent
2025-09-29 09:11:37.287199 | controller |   - cracklib
2025-09-29 09:11:37.287336 | controller |   - procps-ng
2025-09-29 09:11:37.287464 | controller |   - libarchive
2025-09-29 09:11:37.287599 | controller |   - which
2025-09-29 09:11:37.287728 | controller |   - cracklib-dicts
2025-09-29 09:11:37.287855 | controller |   - avahi-libs
2025-09-29 09:11:37.288002 | controller |   - dbus-tools
2025-09-29 09:11:37.288134 | controller |   - centos-logos
2025-09-29 09:11:37.288260 | controller |   - libsss_certmap
2025-09-29 09:11:37.288393 | controller |   - sscg
2025-09-29 09:11:37.288517 | controller |   - xz
2025-09-29 09:11:37.288641 | controller |   - squashfs-tools
2025-09-29 09:11:37.288773 | controller |   - libutempter
2025-09-29 09:11:37.288924 | controller |   - acl
2025-09-29 09:11:37.289334 | controller |   - gettext-libs
2025-09-29 09:11:37.289477 | controller |   - gettext
2025-09-29 09:11:37.289596 | controller |   - attr
2025-09-29 09:11:37.289694 | controller |   - mpfr
2025-09-29 09:11:37.289793 | controller |   - gawk
2025-09-29 09:11:37.289881 | controller |   - krb5-libs
2025-09-29 09:11:37.290000 | controller |   - libtirpc
2025-09-29 09:11:37.290098 | controller |   - quota
2025-09-29 09:11:37.290202 | controller |   - cyrus-sasl-lib
2025-09-29 09:11:37.290307 | controller |   - libssh
2025-09-29 09:11:37.290403 | controller |   - libibverbs
2025-09-29 09:11:37.290498 | controller |   - libpcap
2025-09-29 09:11:37.290593 | controller |   - libnl3-cli
2025-09-29 09:11:37.290685 | controller |   - libteam
2025-09-29 09:11:37.290778 | controller |   - libksba
2025-09-29 09:11:37.290871 | controller |   - libcomps
2025-09-29 09:11:37.290989 | controller |   - ethtool
2025-09-29 09:11:37.291086 | controller |   - libss
2025-09-29 09:11:37.291181 | controller |   - e2fsprogs
2025-09-29 09:11:37.291276 | controller |   - libxcrypt-compat
2025-09-29 09:11:37.291384 | controller |   - python3-pip-wheel
2025-09-29 09:11:37.291477 | controller |   - python-unversioned-command
2025-09-29 09:11:37.291580 | controller |   - python3
2025-09-29 09:11:37.291675 | controller |   - python3-libs
2025-09-29 09:11:37.291767 | controller |   - python3-setuptools
2025-09-29 09:11:37.291862 | controller |   - python3-six
2025-09-29 09:11:37.291983 | controller |   - python3-libselinux
2025-09-29 09:11:37.292079 | controller |   - python3-pyyaml
2025-09-29 09:11:37.292174 | controller |   - python3-distro
2025-09-29 09:11:37.292271 | controller |   - python3-idna
2025-09-29 09:11:37.292377 | controller |   - python3-systemd
2025-09-29 09:11:37.292470 | controller |   - python3-audit
2025-09-29 09:11:37.292563 | controller |   - python3-libsemanage
2025-09-29 09:11:37.292656 | controller |   - python3-dateutil
2025-09-29 09:11:37.292749 | controller |   - python3-configobj
2025-09-29 09:11:37.292841 | controller |   - python3-setools
2025-09-29 09:11:37.292952 | controller |   - python3-libcomps
2025-09-29 09:11:37.293046 | controller |   - cockpit-bridge
2025-09-29 09:11:37.293134 | controller |   - python3-chardet
2025-09-29 09:11:37.293233 | controller |   - python3-libxml2
2025-09-29 09:11:37.293338 | controller |   - python3-ptyprocess
2025-09-29 09:11:37.293433 | controller |   - python3-pexpect
2025-09-29 09:11:37.293527 | controller |   - python3-pysocks
2025-09-29 09:11:37.293621 | controller |   - python3-urllib3
2025-09-29 09:11:37.293715 | controller |   - python3-requests
2025-09-29 09:11:37.293808 | controller |   - python3-attrs
2025-09-29 09:11:37.293916 | controller |   - python3-file-magic
2025-09-29 09:11:37.294013 | controller |   - sos
2025-09-29 09:11:37.294108 | controller |   - python3-jsonpointer
2025-09-29 09:11:37.294201 | controller |   - python3-jsonpatch
2025-09-29 09:11:37.294294 | controller |   - python3-markupsafe
2025-09-29 09:11:37.294398 | controller |   - python3-netifaces
2025-09-29 09:11:37.294496 | controller |   - python3-oauthlib
2025-09-29 09:11:37.294592 | controller |   - python3-prettytable
2025-09-29 09:11:37.294685 | controller |   - python3-pyrsistent
2025-09-29 09:11:37.294778 | controller |   - python3-jsonschema
2025-09-29 09:11:37.294873 | controller |   - python3-pyserial
2025-09-29 09:11:37.294990 | controller |   - python3-pytz
2025-09-29 09:11:37.295084 | controller |   - python3-babel
2025-09-29 09:11:37.295177 | controller |   - python3-jinja2
2025-09-29 09:11:37.295274 | controller |   - gdisk
2025-09-29 09:11:37.295829 | controller |   - groff-base
2025-09-29 09:11:37.295937 | controller |   - snappy
2025-09-29 09:11:37.296033 | controller |   - libpng
2025-09-29 09:11:37.296130 | controller |   - pigz
2025-09-29 09:11:37.296224 | controller |   - c-ares
2025-09-29 09:11:37.296321 | controller |   - cpio
2025-09-29 09:11:37.296418 | controller |   - diffutils
2025-09-29 09:11:37.296511 | controller |   - hostname
2025-09-29 09:11:37.296612 | controller |   - inih
2025-09-29 09:11:37.296716 | controller |   - kernel-tools-libs
2025-09-29 09:11:37.296812 | controller |   - less
2025-09-29 09:11:37.296919 | controller |   - libatomic
2025-09-29 09:11:37.297019 | controller |   - libcbor
2025-09-29 09:11:37.297114 | controller |   - libdaemon
2025-09-29 09:11:37.297206 | controller |   - teamd
2025-09-29 09:11:37.297308 | controller |   - libdb
2025-09-29 09:11:37.297414 | controller |   - libeconf
2025-09-29 09:11:37.297507 | controller |   - libpwquality
2025-09-29 09:11:37.297598 | controller |   - pam
2025-09-29 09:11:37.297691 | controller |   - util-linux
2025-09-29 09:11:37.297783 | controller |   - grub2-common
2025-09-29 09:11:37.297879 | controller |   - openssh
2025-09-29 09:11:37.297998 | controller |   - grub2-pc-modules
2025-09-29 09:11:37.298091 | controller |   - kbd
2025-09-29 09:11:37.298183 | controller |   - libev
2025-09-29 09:11:37.298275 | controller |   - libverto-libev
2025-09-29 09:11:37.298381 | controller |   - libndp
2025-09-29 09:11:37.298479 | controller |   - libnfnetlink
2025-09-29 09:11:37.298575 | controller |   - libnetfilter_conntrack
2025-09-29 09:11:37.298672 | controller |   - iptables-libs
2025-09-29 09:11:37.298765 | controller |   - libnghttp2
2025-09-29 09:11:37.298859 | controller |   - libpipeline
2025-09-29 09:11:37.298971 | controller |   - libsss_nss_idmap
2025-09-29 09:11:37.299065 | controller |   - sssd-client
2025-09-29 09:11:37.299156 | controller |   - libsss_sudo
2025-09-29 09:11:37.299247 | controller |   - libtool-ltdl
2025-09-29 09:11:37.299352 | controller |   - openldap
2025-09-29 09:11:37.299448 | controller |   - libcurl
2025-09-29 09:11:37.299538 | controller |   - tpm2-tss
2025-09-29 09:11:37.299630 | controller |   - libnfsidmap
2025-09-29 09:11:37.299723 | controller |   - sssd-nfs-idmap
2025-09-29 09:11:37.299815 | controller |   - ima-evm-utils
2025-09-29 09:11:37.299920 | controller |   - curl
2025-09-29 09:11:37.300016 | controller |   - rpm
2025-09-29 09:11:37.300108 | controller |   - rpm-libs
2025-09-29 09:11:37.300208 | controller |   - policycoreutils
2025-09-29 09:11:37.300311 | controller |   - selinux-policy
2025-09-29 09:11:37.300407 | controller |   - selinux-policy-targeted
2025-09-29 09:11:37.300499 | controller |   - libsolv
2025-09-29 09:11:37.300591 | controller |   - cockpit-ws-selinux
2025-09-29 09:11:37.300684 | controller |   - rpm-plugin-systemd-inhibit
2025-09-29 09:11:37.300777 | controller |   - sudo
2025-09-29 09:11:37.300868 | controller |   - lmdb-libs
2025-09-29 09:11:37.300985 | controller |   - nettle
2025-09-29 09:11:37.301075 | controller |   - gnutls
2025-09-29 09:11:37.301171 | controller |   - glib2
2025-09-29 09:11:37.301259 | controller |   - python3-dbus
2025-09-29 09:11:37.301364 | controller |   - polkit-libs
2025-09-29 09:11:37.301459 | controller |   - NetworkManager-libnm
2025-09-29 09:11:37.301551 | controller |   - gobject-introspection
2025-09-29 09:11:37.301654 | controller |   - python3-gobject-base-noarch
2025-09-29 09:11:37.301754 | controller |   - python3-gobject-base
2025-09-29 09:11:37.301851 | controller |   - json-glib
2025-09-29 09:11:37.301964 | controller |   - libmodulemd
2025-09-29 09:11:37.302052 | controller |   - shared-mime-info
2025-09-29 09:11:37.302144 | controller |   - python3-dasbus
2025-09-29 09:11:37.302237 | controller |   - gsettings-desktop-schemas
2025-09-29 09:11:37.302341 | controller |   - libuser
2025-09-29 09:11:37.302447 | controller |   - webkit2gtk3-jsc
2025-09-29 09:11:37.302539 | controller |   - libproxy-webkitgtk4
2025-09-29 09:11:37.302629 | controller |   - glib-networking
2025-09-29 09:11:37.302721 | controller |   - libsoup
2025-09-29 09:11:37.302813 | controller |   - npth
2025-09-29 09:11:37.302939 | controller |   - gnupg2
2025-09-29 09:11:37.303016 | controller |   - gpgme
2025-09-29 09:11:37.303110 | controller |   - librepo
2025-09-29 09:11:37.303201 | controller |   - libdnf
2025-09-29 09:11:37.303294 | controller |   - python3-libdnf
2025-09-29 09:11:37.303405 | controller |   - python3-hawkey
2025-09-29 09:11:37.303500 | controller |   - python3-gpg
2025-09-29 09:11:37.303595 | controller |   - rpm-sign-libs
2025-09-29 09:11:37.303687 | controller |   - oniguruma
2025-09-29 09:11:37.303778 | controller |   - jq
2025-09-29 09:11:37.303869 | controller |   - pciutils-libs
2025-09-29 09:11:37.303987 | controller |   - sg3_utils-libs
2025-09-29 09:11:37.304080 | controller |   - slang
2025-09-29 09:11:37.304169 | controller |   - newt
2025-09-29 09:11:37.304262 | controller |   - userspace-rcu
2025-09-29 09:11:37.304365 | controller |   - checkpolicy
2025-09-29 09:11:37.304458 | controller |   - python3-policycoreutils
2025-09-29 09:11:37.304555 | controller |   - policycoreutils-python-utils
2025-09-29 09:11:37.304647 | controller |   - libestr
2025-09-29 09:11:37.304740 | controller |   - libfastjson
2025-09-29 09:11:37.304833 | controller |   - libjpeg-turbo
2025-09-29 09:11:37.304946 | controller |   - gdk-pixbuf2
2025-09-29 09:11:37.305042 | controller |   - libmaxminddb
2025-09-29 09:11:37.305133 | controller |   - ipcalc
2025-09-29 09:11:37.305232 | controller |   - libstemmer
2025-09-29 09:11:37.305338 | controller |   - libappstream-glib
2025-09-29 09:11:37.305433 | controller |   - liburing
2025-09-29 09:11:37.305526 | controller |   - langpacks-core-en_GB
2025-09-29 09:11:37.305621 | controller |   - systemd-rpm-macros
2025-09-29 09:11:37.305711 | controller |   - dbus
2025-09-29 09:11:37.305801 | controller |   - systemd-pam
2025-09-29 09:11:37.305905 | controller |   - systemd
2025-09-29 09:11:37.306009 | controller |   - dbus-common
2025-09-29 09:11:37.306103 | controller |   - dbus-broker
2025-09-29 09:11:37.306197 | controller |   - iputils
2025-09-29 09:11:37.306293 | controller |   - dhcp-client
2025-09-29 09:11:37.306400 | controller |   - cronie-anacron
2025-09-29 09:11:37.306502 | controller |   - cronie
2025-09-29 09:11:37.306593 | controller |   - crontabs
2025-09-29 09:11:37.306686 | controller |   - initscripts-service
2025-09-29 09:11:37.306775 | controller |   - polkit
2025-09-29 09:11:37.306871 | controller |   - polkit-pkla-compat
2025-09-29 09:11:37.306989 | controller |   - samba-common
2025-09-29 09:11:37.307079 | controller |   - libldb
2025-09-29 09:11:37.307171 | controller |   - libwbclient
2025-09-29 09:11:37.307264 | controller |   - samba-client-libs
2025-09-29 09:11:37.307371 | controller |   - samba-common-libs
2025-09-29 09:11:37.307467 | controller |   - sssd-common
2025-09-29 09:11:37.307568 | controller |   - audit
2025-09-29 09:11:37.307660 | controller |   - authselect-libs
2025-09-29 09:11:37.307752 | controller |   - device-mapper-libs
2025-09-29 09:11:37.307846 | controller |   - device-mapper
2025-09-29 09:11:37.307958 | controller |   - grub2-tools-minimal
2025-09-29 09:11:37.308053 | controller |   - cryptsetup-libs
2025-09-29 09:11:37.308144 | controller |   - systemd-udev
2025-09-29 09:11:37.308235 | controller |   - NetworkManager
2025-09-29 09:11:37.308341 | controller |   - NetworkManager-team
2025-09-29 09:11:37.308440 | controller |   - libfido2
2025-09-29 09:11:37.308531 | controller |   - os-prober
2025-09-29 09:11:37.308623 | controller |   - kpartx
2025-09-29 09:11:37.308718 | controller |   - xfsprogs
2025-09-29 09:11:37.308847 | controller |   - elfutils-default-yama-scope
2025-09-29 09:11:37.308963 | controller |   - elfutils-libs
2025-09-29 09:11:37.309053 | controller |   - elfutils-debuginfod-client
2025-09-29 09:11:37.309144 | controller |   - binutils-gold
2025-09-29 09:11:37.309239 | controller |   - binutils
2025-09-29 09:11:37.309339 | controller |   - rpm-build-libs
2025-09-29 09:11:37.309433 | controller |   - python3-rpm
2025-09-29 09:11:37.309524 | controller |   - python3-dnf
2025-09-29 09:11:37.309614 | controller |   - dnf
2025-09-29 09:11:37.309707 | controller |   - setroubleshoot-plugins
2025-09-29 09:11:37.309802 | controller |   - setroubleshoot-server
2025-09-29 09:11:37.309918 | controller |   - python3-dnf-plugins-core
2025-09-29 09:11:37.310033 | controller |   - dnf-plugins-core
2025-09-29 09:11:37.310125 | controller |   - gssproxy
2025-09-29 09:11:37.310218 | controller |   - libkcapi
2025-09-29 09:11:37.310318 | controller |   - libkcapi-hmaccalc
2025-09-29 09:11:37.310414 | controller |   - dracut
2025-09-29 09:11:37.310514 | controller |   - kernel-modules-core
2025-09-29 09:11:37.310607 | controller |   - kernel-core
2025-09-29 09:11:37.310699 | controller |   - grub2-tools
2025-09-29 09:11:37.311003 | controller |   - grubby
2025-09-29 09:11:37.311095 | controller |   - kernel-modules
2025-09-29 09:11:37.311187 | controller |   - dracut-network
2025-09-29 09:11:37.311278 | controller |   - dracut-squash
2025-09-29 09:11:37.311387 | controller |   - kexec-tools
2025-09-29 09:11:37.311480 | controller |   - logrotate
2025-09-29 09:11:37.311574 | controller |   - rsyslog-logrotate
2025-09-29 09:11:37.311667 | controller |   - rsyslog
2025-09-29 09:11:37.311769 | controller |   - rpcbind
2025-09-29 09:11:37.311861 | controller |   - oddjob
2025-09-29 09:11:37.311987 | controller |   - oddjob-mkhomedir
2025-09-29 09:11:37.312077 | controller |   - authselect
2025-09-29 09:11:37.312171 | controller |   - PackageKit-glib
2025-09-29 09:11:37.312265 | controller |   - PackageKit
2025-09-29 09:11:37.312368 | controller |   - cockpit-system
2025-09-29 09:11:37.312462 | controller |   - authselect-compat
2025-09-29 09:11:37.312553 | controller |   - nfs-utils
2025-09-29 09:11:37.312643 | controller |   - kernel
2025-09-29 09:11:37.312736 | controller |   - crypto-policies-scripts
2025-09-29 09:11:37.312828 | controller |   - grub2-pc
2025-09-29 09:11:37.312941 | controller |   - dracut-config-generic
2025-09-29 09:11:37.313035 | controller |   - yum-utils
2025-09-29 09:11:37.313127 | controller |   - yum
2025-09-29 09:11:37.313218 | controller |   - cloud-init
2025-09-29 09:11:37.313318 | controller |   - openssh-clients
2025-09-29 09:11:37.313414 | controller |   - NetworkManager-tui
2025-09-29 09:11:37.313513 | controller |   - parted
2025-09-29 09:11:37.313606 | controller |   - sssd-kcm
2025-09-29 09:11:37.313729 | controller |   - chrony
2025-09-29 09:11:37.313825 | controller |   - microcode_ctl
2025-09-29 09:11:37.313939 | controller |   - openssh-server
2025-09-29 09:11:37.314036 | controller |   - qemu-guest-agent
2025-09-29 09:11:37.314128 | controller |   - langpacks-en_GB
2025-09-29 09:11:37.314224 | controller |   - sg3_utils
2025-09-29 09:11:37.314326 | controller |   - kernel-tools
2025-09-29 09:11:37.314426 | controller |   - cockpit-ws
2025-09-29 09:11:37.314513 | controller |   - passwd
2025-09-29 09:11:37.314609 | controller |   - initscripts-rename-device
2025-09-29 09:11:37.314702 | controller |   - irqbalance
2025-09-29 09:11:37.314795 | controller |   - rpm-plugin-selinux
2025-09-29 09:11:37.314887 | controller |   - rpm-plugin-audit
2025-09-29 09:11:37.315004 | controller |   - man-db
2025-09-29 09:11:37.315095 | controller |   - iproute-tc
2025-09-29 09:11:37.315193 | controller |   - cloud-utils-growpart
2025-09-29 09:11:37.315287 | controller |   - tcpdump
2025-09-29 09:11:37.315394 | controller |   - prefixdevname
2025-09-29 09:11:37.315487 | controller |   - rsync
2025-09-29 09:11:37.315579 | controller |   - tar
2025-09-29 09:11:37.315673 | controller |   - vim-minimal
2025-09-29 09:11:37.315768 | controller |   - lshw
2025-09-29 09:11:37.315860 | controller |   - libsysfs
2025-09-29 09:11:37.315975 | controller |   - lsscsi
2025-09-29 09:11:37.316069 | controller |   - ncurses
2025-09-29 09:11:37.316160 | controller |   - rootfiles
2025-09-29 09:11:37.316255 | controller |   - gpg-pubkey
2025-09-29 09:11:37.316363 | controller |   - git-core
2025-09-29 09:11:37.316458 | controller |   - git-core-doc
2025-09-29 09:11:37.316551 | controller |   - perl-Digest
2025-09-29 09:11:37.316645 | controller |   - perl-Digest-MD5
2025-09-29 09:11:37.316739 | controller |   - perl-B
2025-09-29 09:11:37.316834 | controller |   - perl-FileHandle
2025-09-29 09:11:37.316944 | controller |   - perl-Data-Dumper
2025-09-29 09:11:37.317037 | controller |   - perl-libnet
2025-09-29 09:11:37.317130 | controller |   - perl-base
2025-09-29 09:11:37.317229 | controller |   - perl-URI
2025-09-29 09:11:37.317334 | controller |   - perl-AutoLoader
2025-09-29 09:11:37.317439 | controller |   - perl-Mozilla-CA
2025-09-29 09:11:37.317523 | controller |   - perl-if
2025-09-29 09:11:37.317630 | controller |   - perl-IO-Socket-IP
2025-09-29 09:11:37.317723 | controller |   - perl-Time-Local
2025-09-29 09:11:37.317817 | controller |   - perl-File-Path
2025-09-29 09:11:37.317931 | controller |   - perl-Pod-Escapes
2025-09-29 09:11:37.318025 | controller |   - perl-Text-Tabs+Wrap
2025-09-29 09:11:37.318120 | controller |   - perl-IO-Socket-SSL
2025-09-29 09:11:37.318215 | controller |   - perl-Net-SSLeay
2025-09-29 09:11:37.318316 | controller |   - perl-Class-Struct
2025-09-29 09:11:37.318414 | controller |   - perl-POSIX
2025-09-29 09:11:37.318514 | controller |   - perl-Term-ANSIColor
2025-09-29 09:11:37.318607 | controller |   - perl-IPC-Open3
2025-09-29 09:11:37.318699 | controller |   - perl-subs
2025-09-29 09:11:37.318792 | controller |   - perl-File-Temp
2025-09-29 09:11:37.318884 | controller |   - perl-Term-Cap
2025-09-29 09:11:37.319003 | controller |   - perl-Pod-Simple
2025-09-29 09:11:37.319094 | controller |   - perl-HTTP-Tiny
2025-09-29 09:11:37.319188 | controller |   - perl-Socket
2025-09-29 09:11:37.319279 | controller |   - perl-SelectSaver
2025-09-29 09:11:37.319387 | controller |   - perl-Symbol
2025-09-29 09:11:37.319479 | controller |   - perl-File-stat
2025-09-29 09:11:37.319571 | controller |   - perl-podlators
2025-09-29 09:11:37.319663 | controller |   - perl-Pod-Perldoc
2025-09-29 09:11:37.319754 | controller |   - perl-Fcntl
2025-09-29 09:11:37.319847 | controller |   - perl-Text-ParseWords
2025-09-29 09:11:37.319964 | controller |   - perl-mro
2025-09-29 09:11:37.320071 | controller |   - perl-IO
2025-09-29 09:11:37.320164 | controller |   - perl-overloading
2025-09-29 09:11:37.320258 | controller |   - perl-Pod-Usage
2025-09-29 09:11:37.320361 | controller |   - perl-Errno
2025-09-29 09:11:37.320452 | controller |   - perl-File-Basename
2025-09-29 09:11:37.320546 | controller |   - perl-Getopt-Std
2025-09-29 09:11:37.320639 | controller |   - perl-MIME-Base64
2025-09-29 09:11:37.320733 | controller |   - perl-Scalar-List-Utils
2025-09-29 09:11:37.320824 | controller |   - perl-constant
2025-09-29 09:11:37.320936 | controller |   - perl-Storable
2025-09-29 09:11:37.321031 | controller |   - perl-overload
2025-09-29 09:11:37.321125 | controller |   - perl-parent
2025-09-29 09:11:37.321217 | controller |   - perl-vars
2025-09-29 09:11:37.321321 | controller |   - perl-Getopt-Long
2025-09-29 09:11:37.321414 | controller |   - perl-Carp
2025-09-29 09:11:37.321512 | controller |   - perl-Exporter
2025-09-29 09:11:37.321606 | controller |   - perl-NDBM_File
2025-09-29 09:11:37.321699 | controller |   - perl-PathTools
2025-09-29 09:11:37.321792 | controller |   - perl-Encode
2025-09-29 09:11:37.321918 | controller |   - perl-libs
2025-09-29 09:11:37.322015 | controller |   - perl-interpreter
2025-09-29 09:11:37.322107 | controller |   - perl-DynaLoader
2025-09-29 09:11:37.322200 | controller |   - perl-TermReadKey
2025-09-29 09:11:37.322292 | controller |   - perl-Error
2025-09-29 09:11:37.322398 | controller |   - perl-File-Find
2025-09-29 09:11:37.322494 | controller |   - perl-lib
2025-09-29 09:11:37.322586 | controller |   - emacs-filesystem
2025-09-29 09:11:37.322678 | controller |   - perl-Git
2025-09-29 09:11:37.322769 | controller |   - git
2025-09-29 09:11:37.322861 | controller |   - traceroute
2025-09-29 09:11:37.322979 | controller |   - ruby-libs
2025-09-29 09:11:37.323078 | controller |   - rubygem-bigdecimal
2025-09-29 09:11:37.323172 | controller |   - ruby-default-gems
2025-09-29 09:11:37.323266 | controller |   - rubygem-bundler
2025-09-29 09:11:37.323372 | controller |   - rubygem-io-console
2025-09-29 09:11:37.323465 | controller |   - rubygem-json
2025-09-29 09:11:37.323557 | controller |   - rubygem-psych
2025-09-29 09:11:37.323648 | controller |   - rubygem-rdoc
2025-09-29 09:11:37.323740 | controller |   - rubygems
2025-09-29 09:11:37.323831 | controller |   - ruby
2025-09-29 09:11:37.323944 | controller |   - libmpc
2025-09-29 09:11:37.324037 | controller |   - gdb-minimal
2025-09-29 09:11:37.324139 | controller |   - dwz
2025-09-29 09:11:37.324231 | controller |   - unzip
2025-09-29 09:11:37.324334 | controller |   - elfutils
2025-09-29 09:11:37.324434 | controller |   - bzip2
2025-09-29 09:11:37.324527 | controller |   - debugedit
2025-09-29 09:11:37.324617 | controller |   - zip
2025-09-29 09:11:37.324709 | controller |   - cpp
2025-09-29 09:11:37.324808 | controller |   - rust-srpm-macros
2025-09-29 09:11:37.324924 | controller |   - qt5-srpm-macros
2025-09-29 09:11:37.325020 | controller |   - python3-pip
2025-09-29 09:11:37.325115 | controller |   - perl-srpm-macros
2025-09-29 09:11:37.325206 | controller |   - openblas-srpm-macros
2025-09-29 09:11:37.325308 | controller |   - ocaml-srpm-macros
2025-09-29 09:11:37.325403 | controller |   - ncurses-c++-libs
2025-09-29 09:11:37.325496 | controller |   - lua-srpm-macros
2025-09-29 09:11:37.325589 | controller |   - llvm-filesystem
2025-09-29 09:11:37.325681 | controller |   - llvm-libs
2025-09-29 09:11:37.325774 | controller |   - libxslt
2025-09-29 09:11:37.325865 | controller |   - libstdc++-devel
2025-09-29 09:11:37.325985 | controller |   - kernel-srpm-macros
2025-09-29 09:11:37.326077 | controller |   - kernel-headers
2025-09-29 09:11:37.326172 | controller |   - hunspell-filesystem
2025-09-29 09:11:37.326266 | controller |   - hunspell-en-US
2025-09-29 09:11:37.326368 | controller |   - hunspell
2025-09-29 09:11:37.326460 | controller |   - enchant
2025-09-29 09:11:37.326562 | controller |   - python3-enchant
2025-09-29 09:11:37.326656 | controller |   - glibc-headers
2025-09-29 09:11:37.326748 | controller |   - ghc-srpm-macros
2025-09-29 09:11:37.326839 | controller |   - efi-srpm-macros
2025-09-29 09:11:37.326953 | controller |   - desktop-file-utils
2025-09-29 09:11:37.327047 | controller |   - createrepo_c-libs
2025-09-29 09:11:37.327138 | controller |   - cmake-filesystem
2025-09-29 09:11:37.327232 | controller |   - zstd
2025-09-29 09:11:37.327330 | controller |   - tcl
2025-09-29 09:11:37.327425 | controller |   - python3-pyparsing
2025-09-29 09:11:37.327526 | controller |   - python3-packaging
2025-09-29 09:11:37.327624 | controller |   - pkgconf-m4
2025-09-29 09:11:37.327725 | controller |   - make
2025-09-29 09:11:37.327820 | controller |   - libpkgconf
2025-09-29 09:11:37.327933 | controller |   - pkgconf
2025-09-29 09:11:37.328023 | controller |   - pkgconf-pkg-config
2025-09-29 09:11:37.328116 | controller |   - libgpg-error-devel
2025-09-29 09:11:37.328211 | controller |   - glibc-devel
2025-09-29 09:11:37.328313 | controller |   - libxcrypt-devel
2025-09-29 09:11:37.328405 | controller |   - gcc
2025-09-29 09:11:37.328499 | controller |   - annobin
2025-09-29 09:11:37.328590 | controller |   - gcc-plugin-annobin
2025-09-29 09:11:37.328682 | controller |   - ncurses-devel
2025-09-29 09:11:37.328774 | controller |   - xz-devel
2025-09-29 09:11:37.328866 | controller |   - zlib-devel
2025-09-29 09:11:37.328982 | controller |   - libxml2-devel
2025-09-29 09:11:37.329073 | controller |   - libnftnl
2025-09-29 09:11:37.329166 | controller |   - iptables-nft
2025-09-29 09:11:37.329258 | controller |   - info
2025-09-29 09:11:37.329371 | controller |   - ed
2025-09-29 09:11:37.329464 | controller |   - patch
2025-09-29 09:11:37.329556 | controller |   - fonts-srpm-macros
2025-09-29 09:11:37.329649 | controller |   - go-srpm-macros
2025-09-29 09:11:37.329742 | controller |   - python-srpm-macros
2025-09-29 09:11:37.329833 | controller |   - redhat-rpm-config
2025-09-29 09:11:37.329945 | controller |   - rpm-build
2025-09-29 09:11:37.330040 | controller |   - pyproject-srpm-macros
2025-09-29 09:11:37.330130 | controller |   - python-rpm-macros
2025-09-29 09:11:37.330220 | controller |   - python3-rpm-macros
2025-09-29 09:11:37.330321 | controller |   - python3-rpm-generators
2025-09-29 09:11:37.330412 | controller |   - cyrus-sasl
2025-09-29 09:11:37.330504 | controller |   - cyrus-sasl-devel
2025-09-29 09:11:37.330595 | controller |   - openldap-devel
2025-09-29 09:11:37.330693 | controller |   - python3-devel
2025-09-29 09:11:37.330786 | controller |   - ruby-devel
2025-09-29 09:11:37.330878 | controller |   - iptables-nft-services
2025-09-29 09:11:37.330995 | controller |   - libxslt-devel
2025-09-29 09:11:37.331085 | controller |   - readline-devel
2025-09-29 09:11:37.331173 | controller |   - gcc-c++
2025-09-29 09:11:37.331265 | controller |   - libffi-devel
2025-09-29 09:11:37.331364 | controller |   - openssl-devel
2025-09-29 09:11:37.331465 | controller |   - expect
2025-09-29 09:11:37.331558 | controller |   - createrepo_c
2025-09-29 09:11:37.331649 | controller |   - rpmlint
2025-09-29 09:11:37.331740 | controller |   - hunspell-en-GB
2025-09-29 09:11:37.331831 | controller |   - wget
2025-09-29 09:11:37.331953 | controller |   - systemd-devel
2025-09-29 09:11:37.332045 | controller |   - time
2025-09-29 09:11:37.332135 | controller |   - rpm-sign
2025-09-29 09:11:37.332225 | controller |   - passt
2025-09-29 09:11:37.332335 | controller |   - passt-selinux
2025-09-29 09:11:37.332431 | controller |   - fuse3-libs
2025-09-29 09:11:37.332522 | controller |   - container-selinux
2025-09-29 09:11:37.332613 | controller |   - protobuf-c
2025-09-29 09:11:37.332702 | controller |   - yajl
2025-09-29 09:11:37.332796 | controller |   - libslirp
2025-09-29 09:11:37.332885 | controller |   - slirp4netns
2025-09-29 09:11:37.332999 | controller |   - libnet
2025-09-29 09:11:37.333099 | controller |   - criu
2025-09-29 09:11:37.333191 | controller |   - criu-libs
2025-09-29 09:11:37.333284 | controller |   - crun
2025-09-29 09:11:37.333385 | controller |   - conmon
2025-09-29 09:11:37.333479 | controller |   - aardvark-dns
2025-09-29 09:11:37.333569 | controller |   - netavark
2025-09-29 09:11:37.333661 | controller |   - shadow-utils-subid
2025-09-29 09:11:37.333753 | controller |   - nftables
2025-09-29 09:11:37.333848 | controller |   - fuse-common
2025-09-29 09:11:37.333957 | controller |   - fuse3
2025-09-29 09:11:37.334049 | controller |   - fuse-overlayfs
2025-09-29 09:11:37.334147 | controller |   - containers-common
2025-09-29 09:11:37.334240 | controller |   - podman
2025-09-29 09:11:37.334342 | controller |   - cyrus-sasl-gssapi
2025-09-29 09:11:37.334437 | controller |   - libvirt-libs
2025-09-29 09:11:37.334530 | controller |   - python3-libvirt
2025-09-29 09:11:37.334619 | controller |   - python3-netaddr
2025-09-29 09:11:37.334711 | controller |   - python3-lxml
2025-09-29 09:11:37.334802 | controller |   - python3-jmespath
2025-09-29 09:11:37.334909 | controller |   - python3-pbr
2025-09-29 09:11:37.335011 | controller |   - python3-typing-extensions
2025-09-29 09:11:37.335106 | controller |   - python3-os-service-types
2025-09-29 09:11:37.335197 | controller |   - python3-stevedore
2025-09-29 09:11:37.335289 | controller |   - python3-iso8601
2025-09-29 09:11:37.335399 | controller |   - python3-decorator
2025-09-29 09:11:37.335492 | controller |   - python3-keystoneauth1
2025-09-29 09:11:37.335587 | controller |   - python3-platformdirs
2025-09-29 09:11:37.335676 | controller |   - python3-psutil
2025-09-29 09:11:37.335766 | controller |   - python3-mako
2025-09-29 09:11:37.335859 | controller |   - python3-dogpile-cache
2025-09-29 09:11:37.335972 | controller |   - python3-ply
2025-09-29 09:11:37.336065 | controller |   - python3-pycparser
2025-09-29 09:11:37.336157 | controller |   - python3-cffi
2025-09-29 09:11:37.336247 | controller |   - python3-cryptography
2025-09-29 09:11:37.336348 | controller |   - python3-requestsexceptions
2025-09-29 09:11:37.336438 | controller |   - python3-openstacksdk
2025-09-29 09:11:37.336644 | controller |
2025-09-29 09:11:37.336748 | controller | TASK [osp.edpm.env_data : Output installed repositories] ***********************
2025-09-29 09:11:37.336847 | controller | Monday 29 September 2025  09:11:37 +0000 (0:00:00.059)       0:01:35.085 ******
2025-09-29 09:11:37.419167 | controller | ok: [compute-1] =>
2025-09-29 09:11:37.419263 | controller |   msg: |-
2025-09-29 09:11:37.419411 | controller |     #### REPOSITORIES
2025-09-29 09:11:37.419542 | controller |     repo id                       repo name
2025-09-29 09:11:37.419671 | controller |     appstream                     CentOS Stream 9 - AppStream
2025-09-29 09:11:37.419801 | controller |     baseos                        CentOS Stream 9 - BaseOS
2025-09-29 09:11:37.419917 | controller |     crb                           CentOS Stream 9 - CRB
2025-09-29 09:11:37.420018 | controller |     extras-common                 CentOS Stream 9 - Extras packages
2025-09-29 09:11:37.420105 | controller |
2025-09-29 09:11:37.420201 | controller | PLAY [Setup DUT] ***************************************************************
2025-09-29 09:11:37.420288 | controller |
2025-09-29 09:11:37.420402 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 09:11:37.420491 | controller | Monday 29 September 2025  09:11:37 +0000 (0:00:00.129)       0:01:35.215 ******
2025-09-29 09:11:38.033978 | controller | ok: [compute-1]
2025-09-29 09:11:38.034056 | controller |
2025-09-29 09:11:38.034166 | controller | TASK [set basic user fact] *****************************************************
2025-09-29 09:11:38.034270 | controller | Monday 29 September 2025  09:11:38 +0000 (0:00:00.614)       0:01:35.829 ******
2025-09-29 09:11:38.071552 | controller | ok: [compute-1]
2025-09-29 09:11:38.071663 | controller |
2025-09-29 09:11:38.071787 | controller | TASK [set basic home fact] *****************************************************
2025-09-29 09:11:38.071936 | controller | Monday 29 September 2025  09:11:38 +0000 (0:00:00.037)       0:01:35.867 ******
2025-09-29 09:11:38.156707 | controller | skipping: [compute-1]
2025-09-29 09:11:38.156782 | controller |
2025-09-29 09:11:38.156932 | controller | TASK [Enable package repos] ****************************************************
2025-09-29 09:11:38.157057 | controller | Monday 29 September 2025  09:11:38 +0000 (0:00:00.084)       0:01:35.952 ******
2025-09-29 09:11:45.241599 | controller | changed: [compute-1]
2025-09-29 09:11:45.241651 | controller |
2025-09-29 09:11:45.241766 | controller | TASK [Install openstack-selinux] ***********************************************
2025-09-29 09:11:45.241866 | controller | Monday 29 September 2025  09:11:45 +0000 (0:00:07.084)       0:01:43.037 ******
2025-09-29 09:13:42.397808 | controller | changed: [compute-1]
2025-09-29 09:13:42.397869 | controller |
2025-09-29 09:13:42.398149 | controller | TASK [set /etc/localtime] ******************************************************
2025-09-29 09:13:42.621010 | controller | Monday 29 September 2025  09:13:42 +0000 (0:01:57.156)       0:03:40.193 ******
2025-09-29 09:13:42.621050 | controller | changed: [compute-1]
2025-09-29 09:13:42.621080 | controller |
2025-09-29 09:13:42.621090 | controller | TASK [osp.edpm.edpm_timezone : Validating arguments against arg spec 'main' - The main entry point for the edpm_timezone role.] ***
2025-09-29 09:13:42.621241 | controller | Monday 29 September 2025  09:13:42 +0000 (0:00:00.223)       0:03:40.416 ******
2025-09-29 09:13:42.653534 | controller | ok: [compute-1]
2025-09-29 09:13:42.686686 | controller |
2025-09-29 09:13:42.686706 | controller | TASK [osp.edpm.edpm_timezone : Gather ansible_local facts] *********************
2025-09-29 09:13:42.686720 | controller | Monday 29 September 2025  09:13:42 +0000 (0:00:00.032)       0:03:40.449 ******
2025-09-29 09:13:42.686729 | controller | skipping: [compute-1]
2025-09-29 09:13:42.733828 | controller |
2025-09-29 09:13:42.733859 | controller | TASK [osp.edpm.edpm_timezone : Check for /etc/localtime] ***********************
2025-09-29 09:13:42.733867 | controller | Monday 29 September 2025  09:13:42 +0000 (0:00:00.033)       0:03:40.482 ******
2025-09-29 09:13:42.733877 | controller | skipping: [compute-1]
2025-09-29 09:13:42.764595 | controller |
2025-09-29 09:13:42.764619 | controller | TASK [osp.edpm.edpm_timezone : Symlink /etc/localtime to timezone file if it doesn't exist] ***
2025-09-29 09:13:42.764627 | controller | Monday 29 September 2025  09:13:42 +0000 (0:00:00.046)       0:03:40.529 ******
2025-09-29 09:13:42.764636 | controller | skipping: [compute-1]
2025-09-29 09:13:43.142790 | controller |
2025-09-29 09:13:43.142822 | controller | TASK [osp.edpm.edpm_timezone : Set timezone] ***********************************
2025-09-29 09:13:43.142830 | controller | Monday 29 September 2025  09:13:42 +0000 (0:00:00.030)       0:03:40.560 ******
2025-09-29 09:13:43.142845 | controller | ok: [compute-1]
2025-09-29 09:13:43.171966 | controller |
2025-09-29 09:13:43.171994 | controller | TASK [osp.edpm.edpm_timezone : Set fact for timezone result] *******************
2025-09-29 09:13:43.172004 | controller | Monday 29 September 2025  09:13:43 +0000 (0:00:00.377)       0:03:40.938 ******
2025-09-29 09:13:43.172015 | controller | ok: [compute-1]
2025-09-29 09:13:43.172461 | controller |
2025-09-29 09:13:44.349670 | controller | TASK [osp.edpm.edpm_timezone : Restart time services] **************************
2025-09-29 09:13:44.349703 | controller | Monday 29 September 2025  09:13:43 +0000 (0:00:00.029)       0:03:40.967 ******
2025-09-29 09:13:44.349715 | controller | changed: [compute-1] => (item=rsyslog)
2025-09-29 09:13:44.395039 | controller | changed: [compute-1] => (item=crond)
2025-09-29 09:13:44.395080 | controller |
2025-09-29 09:13:44.395090 | controller | TASK [osp.edpm.edpm_podman : Validating arguments against arg spec 'main' - The main entry point for the edpm_podman role.] ***
2025-09-29 09:13:44.395098 | controller | Monday 29 September 2025  09:13:44 +0000 (0:00:01.177)       0:03:42.144 ******
2025-09-29 09:13:44.395122 | controller | ok: [compute-1]
2025-09-29 09:13:44.395152 | controller |
2025-09-29 09:13:44.395162 | controller | TASK [osp.edpm.edpm_podman : Gather ansible_local facts] ***********************
2025-09-29 09:13:44.395298 | controller | Monday 29 September 2025  09:13:44 +0000 (0:00:00.045)       0:03:42.190 ******
2025-09-29 09:13:44.685062 | controller | ok: [compute-1]
2025-09-29 09:13:44.685317 | controller |
2025-09-29 09:13:44.685343 | controller | TASK [osp.edpm.edpm_podman : Ensure podman and deps are installed] *************
2025-09-29 09:13:45.962974 | controller | Monday 29 September 2025  09:13:44 +0000 (0:00:00.289)       0:03:42.480 ******
2025-09-29 09:13:45.963013 | controller | ok: [compute-1]
2025-09-29 09:13:45.963038 | controller |
2025-09-29 09:13:45.963046 | controller | TASK [osp.edpm.edpm_podman : Ensure we get the ansible interfaces facts] *******
2025-09-29 09:13:45.963197 | controller | Monday 29 September 2025  09:13:45 +0000 (0:00:01.278)       0:03:43.758 ******
2025-09-29 09:13:46.000001 | controller | skipping: [compute-1]
2025-09-29 09:13:46.000098 | controller |
2025-09-29 09:13:46.000123 | controller | TASK [osp.edpm.edpm_podman : Delete legacy cni0 interface (podman < 1.6)] ******
2025-09-29 09:13:46.000133 | controller | Monday 29 September 2025  09:13:45 +0000 (0:00:00.036)       0:03:43.795 ******
2025-09-29 09:13:46.037485 | controller | skipping: [compute-1]
2025-09-29 09:13:46.076962 | controller |
2025-09-29 09:13:46.076981 | controller | TASK [osp.edpm.edpm_podman : Make sure /etc/cni/net.d folder exists] ***********
2025-09-29 09:13:46.076989 | controller | Monday 29 September 2025  09:13:46 +0000 (0:00:00.036)       0:03:43.832 ******
2025-09-29 09:13:46.076998 | controller | skipping: [compute-1]
2025-09-29 09:13:46.077103 | controller |
2025-09-29 09:13:46.077130 | controller | TASK [osp.edpm.edpm_podman : Update default network configuration if possible] ***
2025-09-29 09:13:46.077140 | controller | Monday 29 September 2025  09:13:46 +0000 (0:00:00.040)       0:03:43.872 ******
2025-09-29 09:13:46.115061 | controller | skipping: [compute-1]
2025-09-29 09:13:46.332637 | controller |
2025-09-29 09:13:46.332658 | controller | TASK [osp.edpm.edpm_podman : Make sure /etc/containers/networks exists] ********
2025-09-29 09:13:46.332666 | controller | Monday 29 September 2025  09:13:46 +0000 (0:00:00.037)       0:03:43.910 ******
2025-09-29 09:13:46.332682 | controller | changed: [compute-1]
2025-09-29 09:13:46.332736 | controller |
2025-09-29 09:13:46.333029 | controller | TASK [osp.edpm.edpm_podman : Get current podman network] ***********************
2025-09-29 09:13:46.638784 | controller | Monday 29 September 2025  09:13:46 +0000 (0:00:00.217)       0:03:44.128 ******
2025-09-29 09:13:46.638831 | controller | changed: [compute-1]
2025-09-29 09:13:47.057622 | controller |
2025-09-29 09:13:47.057665 | controller | TASK [osp.edpm.edpm_podman : Update the default network configuration] *********
2025-09-29 09:13:47.057676 | controller | Monday 29 September 2025  09:13:46 +0000 (0:00:00.305)       0:03:44.433 ******
2025-09-29 09:13:47.057689 | controller | changed: [compute-1]
2025-09-29 09:13:47.527973 | controller |
2025-09-29 09:13:47.528011 | controller | TASK [osp.edpm.edpm_podman : Write containers registries.conf] *****************
2025-09-29 09:13:47.528019 | controller | Monday 29 September 2025  09:13:47 +0000 (0:00:00.418)       0:03:44.852 ******
2025-09-29 09:13:47.528029 | controller | changed: [compute-1]
2025-09-29 09:13:47.528045 | controller |
2025-09-29 09:13:47.528052 | controller | TASK [osp.edpm.edpm_podman : Write containers registries.conf for disconnected ocp] ***
2025-09-29 09:13:47.528059 | controller | Monday 29 September 2025  09:13:47 +0000 (0:00:00.470)       0:03:45.323 ******
2025-09-29 09:13:47.561968 | controller | skipping: [compute-1]
2025-09-29 09:13:47.562280 | controller |
2025-09-29 09:13:47.562303 | controller | TASK [osp.edpm.edpm_podman : Write containers.conf] ****************************
2025-09-29 09:13:48.503991 | controller | Monday 29 September 2025  09:13:47 +0000 (0:00:00.034)       0:03:45.357 ******
2025-09-29 09:13:48.504033 | controller | changed: [compute-1] => (item={'section': 'containers', 'option': 'pids_limit', 'value': 4096})
2025-09-29 09:13:48.546752 | controller | changed: [compute-1] => (item={'section': 'engine', 'option': 'events_logger', 'value': '"journald"'})
2025-09-29 09:13:48.546776 | controller | changed: [compute-1] => (item={'section': 'engine', 'option': 'runtime', 'value': '"crun"'})
2025-09-29 09:13:48.546783 | controller | changed: [compute-1] => (item={'section': 'network', 'option': 'network_backend', 'value': '"netavark"'})
2025-09-29 09:13:48.546790 | controller |
2025-09-29 09:13:48.546795 | controller | TASK [osp.edpm.edpm_podman : Enable podman.socket service] *********************
2025-09-29 09:13:48.546809 | controller | Monday 29 September 2025  09:13:48 +0000 (0:00:00.939)       0:03:46.297 ******
2025-09-29 09:13:48.546818 | controller | skipping: [compute-1]
2025-09-29 09:13:48.879867 | controller |
2025-09-29 09:13:48.879930 | controller | TASK [osp.edpm.edpm_podman : Check rsyslog service state] **********************
2025-09-29 09:13:48.879940 | controller | Monday 29 September 2025  09:13:48 +0000 (0:00:00.044)       0:03:46.342 ******
2025-09-29 09:13:48.879950 | controller | ok: [compute-1]
2025-09-29 09:13:49.234918 | controller |
2025-09-29 09:13:49.234945 | controller | TASK [osp.edpm.edpm_podman : Forward logging to podman.log file] ***************
2025-09-29 09:13:49.234953 | controller | Monday 29 September 2025  09:13:48 +0000 (0:00:00.332)       0:03:46.675 ******
2025-09-29 09:13:49.234962 | controller | changed: [compute-1]
2025-09-29 09:13:49.487718 | controller |
2025-09-29 09:13:49.487753 | controller | TASK [osp.edpm.edpm_podman : Clean podman images] ******************************
2025-09-29 09:13:49.487761 | controller | Monday 29 September 2025  09:13:49 +0000 (0:00:00.355)       0:03:47.030 ******
2025-09-29 09:13:49.487771 | controller | changed: [compute-1]
2025-09-29 09:13:49.770704 | controller |
2025-09-29 09:13:49.770741 | controller | TASK [osp.edpm.edpm_podman : Clean podman volumes] *****************************
2025-09-29 09:13:49.770750 | controller | Monday 29 September 2025  09:13:49 +0000 (0:00:00.252)       0:03:47.283 ******
2025-09-29 09:13:49.770760 | controller | changed: [compute-1]
2025-09-29 09:13:49.811758 | controller |
2025-09-29 09:13:49.811790 | controller | TASK [osp.edpm.edpm_podman : Gather user fact] *********************************
2025-09-29 09:13:49.811799 | controller | Monday 29 September 2025  09:13:49 +0000 (0:00:00.282)       0:03:47.565 ******
2025-09-29 09:13:49.811810 | controller | skipping: [compute-1]
2025-09-29 09:13:49.850989 | controller |
2025-09-29 09:13:49.851017 | controller | TASK [osp.edpm.edpm_podman : Ensure presence of authfile dir] ******************
2025-09-29 09:13:49.851027 | controller | Monday 29 September 2025  09:13:49 +0000 (0:00:00.041)       0:03:47.607 ******
2025-09-29 09:13:49.851047 | controller | skipping: [compute-1]
2025-09-29 09:13:49.893014 | controller |
2025-09-29 09:13:49.893053 | controller | TASK [osp.edpm.edpm_podman : Ensure presence of authfile] **********************
2025-09-29 09:13:49.893063 | controller | Monday 29 September 2025  09:13:49 +0000 (0:00:00.039)       0:03:47.646 ******
2025-09-29 09:13:49.893074 | controller | skipping: [compute-1]
2025-09-29 09:13:49.893103 | controller |
2025-09-29 09:13:49.893123 | controller | TASK [osp.edpm.edpm_podman : Perform container registry login(s) with podman] ***
2025-09-29 09:13:49.893239 | controller | Monday 29 September 2025  09:13:49 +0000 (0:00:00.042)       0:03:47.688 ******
2025-09-29 09:13:49.922613 | controller | skipping: [compute-1]
2025-09-29 09:13:49.962529 | controller |
2025-09-29 09:13:49.962553 | controller | TASK [osp.edpm.edpm_podman : Ensure buildah is installed] **********************
2025-09-29 09:13:49.962561 | controller | Monday 29 September 2025  09:13:49 +0000 (0:00:00.029)       0:03:47.718 ******
2025-09-29 09:13:49.962570 | controller | skipping: [compute-1]
2025-09-29 09:13:50.003729 | controller |
2025-09-29 09:13:50.003750 | controller | TASK [osp.edpm.edpm_podman : Gather user fact] *********************************
2025-09-29 09:13:50.003757 | controller | Monday 29 September 2025  09:13:49 +0000 (0:00:00.039)       0:03:47.758 ******
2025-09-29 09:13:50.003766 | controller | skipping: [compute-1]
2025-09-29 09:13:50.064970 | controller |
2025-09-29 09:13:50.064996 | controller | TASK [osp.edpm.edpm_podman : Ensure presence of authfile dir] ******************
2025-09-29 09:13:50.065005 | controller | Monday 29 September 2025  09:13:50 +0000 (0:00:00.041)       0:03:47.799 ******
2025-09-29 09:13:50.065016 | controller | skipping: [compute-1]
2025-09-29 09:13:50.065215 | controller |
2025-09-29 09:13:50.065238 | controller | TASK [osp.edpm.edpm_podman : Ensure presence of authfile] **********************
2025-09-29 09:13:50.104416 | controller | Monday 29 September 2025  09:13:50 +0000 (0:00:00.061)       0:03:47.860 ******
2025-09-29 09:13:50.104448 | controller | skipping: [compute-1]
2025-09-29 09:13:50.149501 | controller |
2025-09-29 09:13:50.149525 | controller | TASK [osp.edpm.edpm_podman : Perform container registry login(s) with buildah] ***
2025-09-29 09:13:50.149535 | controller | Monday 29 September 2025  09:13:50 +0000 (0:00:00.039)       0:03:47.899 ******
2025-09-29 09:13:50.149546 | controller | skipping: [compute-1]
2025-09-29 09:13:50.366755 | controller |
2025-09-29 09:13:50.366790 | controller | TASK [Create firewall directory] ***********************************************
2025-09-29 09:13:50.366799 | controller | Monday 29 September 2025  09:13:50 +0000 (0:00:00.045)       0:03:47.945 ******
2025-09-29 09:13:50.366809 | controller | changed: [compute-1]
2025-09-29 09:13:50.760998 | controller |
2025-09-29 09:13:50.761032 | controller | TASK [open port 22 (edpm_nftables will active this later)] *********************
2025-09-29 09:13:50.761040 | controller | Monday 29 September 2025  09:13:50 +0000 (0:00:00.217)       0:03:48.162 ******
2025-09-29 09:13:50.761056 | controller | changed: [compute-1]
2025-09-29 09:13:50.761088 | controller |
2025-09-29 09:13:50.761097 | controller | TASK [osp.edpm.edpm_libvirt : Validating arguments against arg spec 'main' - The main entry point for the edpm_libvirt role.] ***
2025-09-29 09:13:50.761225 | controller | Monday 29 September 2025  09:13:50 +0000 (0:00:00.394)       0:03:48.556 ******
2025-09-29 09:13:50.817830 | controller | ok: [compute-1]
2025-09-29 09:13:50.886058 | controller |
2025-09-29 09:13:50.886099 | controller | TASK [osp.edpm.edpm_libvirt : Adopt libvirt from tripleo] **********************
2025-09-29 09:13:50.886109 | controller | Monday 29 September 2025  09:13:50 +0000 (0:00:00.056)       0:03:48.613 ******
2025-09-29 09:13:50.886135 | 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 09:13:51.123452 | controller |
2025-09-29 09:13:51.123490 | controller | TASK [osp.edpm.edpm_libvirt : Check if nova_virtlogd container exists] *********
2025-09-29 09:13:51.123500 | controller | Monday 29 September 2025  09:13:50 +0000 (0:00:00.068)       0:03:48.681 ******
2025-09-29 09:13:51.123513 | controller | ok: [compute-1]
2025-09-29 09:13:51.165536 | controller |
2025-09-29 09:13:51.165565 | controller | TASK [osp.edpm.edpm_libvirt : Stop nova_virtlogd containers not managed by service units] ***
2025-09-29 09:13:51.165574 | controller | Monday 29 September 2025  09:13:51 +0000 (0:00:00.237)       0:03:48.918 ******
2025-09-29 09:13:51.165593 | controller | skipping: [compute-1]
2025-09-29 09:13:51.240512 | controller |
2025-09-29 09:13:51.240544 | controller | TASK [Stop and disable libvirt services] ***************************************
2025-09-29 09:13:51.240553 | controller | Monday 29 September 2025  09:13:51 +0000 (0:00:00.042)       0:03:48.961 ******
2025-09-29 09:13:51.240564 | controller |
2025-09-29 09:13:51.273747 | 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 09:13:51.273777 | controller | Monday 29 September 2025  09:13:51 +0000 (0:00:00.075)       0:03:49.036 ******
2025-09-29 09:13:51.273791 | controller | ok: [compute-1]
2025-09-29 09:13:51.949803 | controller |
2025-09-29 09:13:51.949839 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reload units to check for new changes] ***
2025-09-29 09:13:51.949849 | controller | Monday 29 September 2025  09:13:51 +0000 (0:00:00.033)       0:03:49.069 ******
2025-09-29 09:13:51.949861 | controller | ok: [compute-1]
2025-09-29 09:13:53.877829 | controller |
2025-09-29 09:13:53.877868 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Gather service facts] ********************
2025-09-29 09:13:53.877878 | controller | Monday 29 September 2025  09:13:51 +0000 (0:00:00.675)       0:03:49.745 ******
2025-09-29 09:13:53.877890 | controller | ok: [compute-1]
2025-09-29 09:13:53.914984 | controller |
2025-09-29 09:13:53.915006 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Set list of tripleo services] ************
2025-09-29 09:13:53.915015 | controller | Monday 29 September 2025  09:13:53 +0000 (0:00:01.928)       0:03:51.673 ******
2025-09-29 09:13:53.915026 | controller | ok: [compute-1]
2025-09-29 09:13:53.960771 | controller |
2025-09-29 09:13:53.960794 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Get all services] ************************
2025-09-29 09:13:53.960803 | controller | Monday 29 September 2025  09:13:53 +0000 (0:00:00.037)       0:03:51.710 ******
2025-09-29 09:13:53.960821 | controller | skipping: [compute-1]
2025-09-29 09:13:54.006645 | controller |
2025-09-29 09:13:54.006666 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Filter for tripleo services and skip the ones in skip list] ***
2025-09-29 09:13:54.006675 | controller | Monday 29 September 2025  09:13:53 +0000 (0:00:00.045)       0:03:51.756 ******
2025-09-29 09:13:54.006686 | controller | skipping: [compute-1]
2025-09-29 09:13:56.086993 | controller |
2025-09-29 09:13:56.087029 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Stop and disable tripleo services] *******
2025-09-29 09:13:56.087039 | controller | Monday 29 September 2025  09:13:54 +0000 (0:00:00.045)       0:03:51.802 ******
2025-09-29 09:13:56.087051 | controller | ok: [compute-1] => (item=tripleo_nova_libvirt.target)
2025-09-29 09:13:56.087078 | controller | ok: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2025-09-29 09:13:56.087088 | controller | ok: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2025-09-29 09:13:56.087112 | controller | ok: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2025-09-29 09:13:56.087701 | controller | ok: [compute-1] => (item=tripleo_nova_virtqemud.service)
2025-09-29 09:13:56.152008 | controller | ok: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2025-09-29 09:13:56.152043 | controller | ok: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2025-09-29 09:13:56.152051 | controller |
2025-09-29 09:13:56.152057 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Check for errors] ************************
2025-09-29 09:13:56.152064 | controller | Monday 29 September 2025  09:13:56 +0000 (0:00:02.080)       0:03:53.882 ******
2025-09-29 09:13:56.152075 | 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 09:13:56.152101 | 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 09:13:56.152113 | 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 09:13:56.152131 | 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 09:13:56.152138 | 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 09:13:56.152145 | 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 09:13:56.152174 | 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 09:13:56.152544 | controller | skipping: [compute-1]
2025-09-29 09:13:56.215594 | controller |
2025-09-29 09:13:56.215615 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files] ***********************
2025-09-29 09:13:56.215623 | controller | Monday 29 September 2025  09:13:56 +0000 (0:00:00.064)       0:03:53.947 ******
2025-09-29 09:13:56.215633 | 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 09:13:57.552037 | 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 09:13:57.552074 | controller |
2025-09-29 09:13:57.552084 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /usr/lib/systemd/system] ***
2025-09-29 09:13:57.552092 | controller | Monday 29 September 2025  09:13:56 +0000 (0:00:00.063)       0:03:54.011 ******
2025-09-29 09:13:57.552104 | controller | ok: [compute-1] => (item=tripleo_nova_libvirt.target)
2025-09-29 09:13:57.552144 | controller | ok: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2025-09-29 09:13:57.552152 | controller | ok: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2025-09-29 09:13:57.552159 | controller | ok: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2025-09-29 09:13:57.552173 | controller | ok: [compute-1] => (item=tripleo_nova_virtqemud.service)
2025-09-29 09:13:57.552180 | controller | ok: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2025-09-29 09:13:57.552187 | controller | ok: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2025-09-29 09:13:57.552196 | controller |
2025-09-29 09:13:57.552392 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /etc/systemd/system] ***
2025-09-29 09:13:58.871241 | controller | Monday 29 September 2025  09:13:57 +0000 (0:00:01.335)       0:03:55.346 ******
2025-09-29 09:13:58.871278 | controller | ok: [compute-1] => (item=tripleo_nova_libvirt.target)
2025-09-29 09:13:58.933312 | controller | ok: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2025-09-29 09:13:58.933338 | controller | ok: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2025-09-29 09:13:58.933347 | controller | ok: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2025-09-29 09:13:58.933354 | controller | ok: [compute-1] => (item=tripleo_nova_virtqemud.service)
2025-09-29 09:13:58.933360 | controller | ok: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2025-09-29 09:13:58.933366 | controller | ok: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2025-09-29 09:13:58.933373 | controller |
2025-09-29 09:13:58.933380 | controller | TASK [Adopt (stop tracking) certs from tripleo] ********************************
2025-09-29 09:13:58.933386 | controller | Monday 29 September 2025  09:13:58 +0000 (0:00:01.319)       0:03:56.666 ******
2025-09-29 09:13:58.933397 | controller |
2025-09-29 09:13:59.148847 | controller | TASK [osp.edpm.edpm_install_certs : Stop and disable certmonger] ***************
2025-09-29 09:13:59.148880 | controller | Monday 29 September 2025  09:13:58 +0000 (0:00:00.062)       0:03:56.729 ******
2025-09-29 09:13:59.148911 | controller | ok: [compute-1]
2025-09-29 09:13:59.431565 | controller |
2025-09-29 09:13:59.431597 | controller | TASK [osp.edpm.edpm_install_certs : Find certmonger requests] ******************
2025-09-29 09:13:59.431606 | controller | Monday 29 September 2025  09:13:59 +0000 (0:00:00.214)       0:03:56.944 ******
2025-09-29 09:13:59.431615 | controller | [WARNING]: Skipped '/var/lib/certmonger/requests' path due to this access
2025-09-29 09:13:59.451634 | controller | issue: '/var/lib/certmonger/requests' is not a directory
2025-09-29 09:13:59.451658 | controller | ok: [compute-1]
2025-09-29 09:13:59.483595 | controller |
2025-09-29 09:13:59.483611 | controller | TASK [osp.edpm.edpm_install_certs : Backup certificate requests] ***************
2025-09-29 09:13:59.483617 | controller | Monday 29 September 2025  09:13:59 +0000 (0:00:00.303)       0:03:57.247 ******
2025-09-29 09:13:59.483625 | controller | skipping: [compute-1]
2025-09-29 09:13:59.529977 | controller |
2025-09-29 09:13:59.530045 | controller | TASK [osp.edpm.edpm_install_certs : Remove certificate requests] ***************
2025-09-29 09:13:59.530057 | controller | Monday 29 September 2025  09:13:59 +0000 (0:00:00.032)       0:03:57.279 ******
2025-09-29 09:13:59.530071 | controller | skipping: [compute-1]
2025-09-29 09:13:59.976616 | controller |
2025-09-29 09:13:59.976659 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reload units] ****************************
2025-09-29 09:13:59.976672 | controller | Monday 29 September 2025  09:13:59 +0000 (0:00:00.046)       0:03:57.325 ******
2025-09-29 09:13:59.976688 | controller | ok: [compute-1]
2025-09-29 09:14:01.372966 | controller |
2025-09-29 09:14:01.373004 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reset state of failed systemd units] *****
2025-09-29 09:14:01.373015 | controller | Monday 29 September 2025  09:13:59 +0000 (0:00:00.445)       0:03:57.771 ******
2025-09-29 09:14:01.373028 | controller | changed: [compute-1] => (item=tripleo_nova_libvirt.target)
2025-09-29 09:14:01.434007 | controller | changed: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2025-09-29 09:14:01.434036 | controller | changed: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2025-09-29 09:14:01.434046 | controller | changed: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2025-09-29 09:14:01.434053 | controller | changed: [compute-1] => (item=tripleo_nova_virtqemud.service)
2025-09-29 09:14:01.434060 | controller | changed: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2025-09-29 09:14:01.434067 | controller | changed: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2025-09-29 09:14:01.434073 | controller |
2025-09-29 09:14:01.434080 | controller | TASK [osp.edpm.edpm_libvirt : Ensure libvirt user] *****************************
2025-09-29 09:14:01.434097 | controller | Monday 29 September 2025  09:14:01 +0000 (0:00:01.396)       0:03:59.167 ******
2025-09-29 09:14:01.434107 | 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 09:14:01.434151 | controller |
2025-09-29 09:14:01.434161 | controller | TASK [osp.edpm.edpm_users : Validating arguments against arg spec 'main' - The main entry point for the edpm_users role.] ***
2025-09-29 09:14:01.434298 | controller | Monday 29 September 2025  09:14:01 +0000 (0:00:00.061)       0:03:59.229 ******
2025-09-29 09:14:01.468757 | controller | ok: [compute-1]
2025-09-29 09:14:01.506917 | controller |
2025-09-29 09:14:01.506940 | controller | TASK [osp.edpm.edpm_users : Gather user fact] **********************************
2025-09-29 09:14:01.506949 | controller | Monday 29 September 2025  09:14:01 +0000 (0:00:00.034)       0:03:59.264 ******
2025-09-29 09:14:01.506960 | controller | skipping: [compute-1]
2025-09-29 09:14:01.564965 | controller |
2025-09-29 09:14:01.564987 | controller | TASK [osp.edpm.edpm_users : Create host users and groups] **********************
2025-09-29 09:14:01.564996 | controller | Monday 29 September 2025  09:14:01 +0000 (0:00:00.038)       0:03:59.302 ******
2025-09-29 09:14:01.565008 | 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 09:14:01.565199 | controller |
2025-09-29 09:14:01.565366 | controller | TASK [osp.edpm.edpm_users : Detect when user exists and cannot be created] *****
2025-09-29 09:14:01.565498 | controller | Monday 29 September 2025  09:14:01 +0000 (0:00:00.058)       0:03:59.360 ******
2025-09-29 09:14:01.878381 | controller | fatal: [compute-1]: FAILED! => changed=false
2025-09-29 09:14:01.901029 | controller |   msg: One or more supplied key could not be found in the database.
2025-09-29 09:14:01.901071 | controller | ...ignoring
2025-09-29 09:14:02.263252 | controller |
2025-09-29 09:14:02.263285 | controller | TASK [osp.edpm.edpm_users : Ensure group is present on the host [ libvirt ]] ***
2025-09-29 09:14:02.263303 | controller | Monday 29 September 2025  09:14:01 +0000 (0:00:00.335)       0:03:59.696 ******
2025-09-29 09:14:02.263316 | controller | changed: [compute-1]
2025-09-29 09:14:02.263338 | controller |
2025-09-29 09:14:02.263589 | controller | TASK [osp.edpm.edpm_users : Ensure user is present on the host [ libvirt ]] ****
2025-09-29 09:14:02.759894 | controller | Monday 29 September 2025  09:14:02 +0000 (0:00:00.362)       0:04:00.058 ******
2025-09-29 09:14:02.759951 | controller | changed: [compute-1]
2025-09-29 09:14:02.810596 | controller |
2025-09-29 09:14:02.810622 | controller | TASK [osp.edpm.edpm_users : Create host directories] ***************************
2025-09-29 09:14:02.810632 | controller | Monday 29 September 2025  09:14:02 +0000 (0:00:00.496)       0:04:00.555 ******
2025-09-29 09:14:02.810643 | controller | skipping: [compute-1]
2025-09-29 09:14:02.870574 | controller |
2025-09-29 09:14:02.870599 | controller | TASK [osp.edpm.edpm_libvirt : Install libvirt] *********************************
2025-09-29 09:14:02.870609 | controller | Monday 29 September 2025  09:14:02 +0000 (0:00:00.050)       0:04:00.605 ******
2025-09-29 09:14:02.870620 | 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 09:14:02.908880 | controller |
2025-09-29 09:14:02.908924 | controller | TASK [osp.edpm.edpm_libvirt : Gather ansible_local facts] **********************
2025-09-29 09:14:02.908934 | controller | Monday 29 September 2025  09:14:02 +0000 (0:00:00.060)       0:04:00.666 ******
2025-09-29 09:14:02.908944 | controller | skipping: [compute-1]
2025-09-29 09:16:13.881715 | controller |
2025-09-29 09:16:13.881750 | controller | TASK [osp.edpm.edpm_libvirt : Install libvirt packages] ************************
2025-09-29 09:16:13.881758 | controller | Monday 29 September 2025  09:14:02 +0000 (0:00:00.038)       0:04:00.704 ******
2025-09-29 09:16:13.881771 | controller | changed: [compute-1]
2025-09-29 09:16:16.135210 | controller |
2025-09-29 09:16:16.135243 | controller | TASK [osp.edpm.edpm_libvirt : Ensure monolithic libvirt and tcp socket activation is not enabled or running] ***
2025-09-29 09:16:16.135251 | controller | Monday 29 September 2025  09:16:13 +0000 (0:02:10.972)       0:06:11.676 ******
2025-09-29 09:16:16.135268 | controller | changed: [compute-1] => (item=libvirtd)
2025-09-29 09:16:18.686066 | controller | changed: [compute-1] => (item=libvirtd-tcp.socket)
2025-09-29 09:16:18.686098 | controller | changed: [compute-1] => (item=libvirtd-tls.socket)
2025-09-29 09:16:18.686106 | controller | changed: [compute-1] => (item=virtproxyd-tcp.socket)
2025-09-29 09:16:18.686111 | controller |
2025-09-29 09:16:18.686117 | controller | TASK [osp.edpm.edpm_libvirt : Ensure libvirt services are enabled and running] ***
2025-09-29 09:16:18.686123 | controller | Monday 29 September 2025  09:16:16 +0000 (0:00:02.253)       0:06:13.929 ******
2025-09-29 09:16:18.686133 | controller | changed: [compute-1] => (item=virtlogd)
2025-09-29 09:16:19.308752 | controller | changed: [compute-1] => (item=virtnodedevd)
2025-09-29 09:16:19.308795 | controller | changed: [compute-1] => (item=virtproxyd)
2025-09-29 09:16:19.308802 | controller | ok: [compute-1] => (item=virtqemud)
2025-09-29 09:16:19.308808 | controller | changed: [compute-1] => (item=virtsecretd)
2025-09-29 09:16:19.308813 | controller |
2025-09-29 09:16:19.308819 | controller | TASK [osp.edpm.edpm_libvirt : Configure virtproxyd-tls.socket] *****************
2025-09-29 09:16:19.308825 | controller | Monday 29 September 2025  09:16:18 +0000 (0:00:02.550)       0:06:16.480 ******
2025-09-29 09:16:19.308835 | controller | changed: [compute-1]
2025-09-29 09:16:19.309006 | controller |
2025-09-29 09:16:19.309065 | controller | TASK [osp.edpm.edpm_libvirt : Configure socket activation for libvirt services] ***
2025-09-29 09:16:23.990019 | controller | Monday 29 September 2025  09:16:19 +0000 (0:00:00.623)       0:06:17.104 ******
2025-09-29 09:16:23.990059 | controller | ok: [compute-1] => (item=['virtlogd', ''])
2025-09-29 09:16:23.991789 | controller | skipping: [compute-1] => (item=['virtlogd', '-ro'])
2025-09-29 09:16:23.991832 | controller | ok: [compute-1] => (item=['virtlogd', '-admin'])
2025-09-29 09:16:24.059728 | controller | ok: [compute-1] => (item=['virtnodedevd', ''])
2025-09-29 09:16:24.059753 | controller | ok: [compute-1] => (item=['virtnodedevd', '-ro'])
2025-09-29 09:16:24.059761 | controller | ok: [compute-1] => (item=['virtnodedevd', '-admin'])
2025-09-29 09:16:24.059768 | controller | ok: [compute-1] => (item=['virtproxyd', ''])
2025-09-29 09:16:24.059774 | controller | ok: [compute-1] => (item=['virtproxyd', '-ro'])
2025-09-29 09:16:24.059780 | controller | ok: [compute-1] => (item=['virtproxyd', '-admin'])
2025-09-29 09:16:24.059787 | controller | ok: [compute-1] => (item=['virtqemud', ''])
2025-09-29 09:16:24.059793 | controller | ok: [compute-1] => (item=['virtqemud', '-ro'])
2025-09-29 09:16:24.059799 | controller | ok: [compute-1] => (item=['virtqemud', '-admin'])
2025-09-29 09:16:24.059805 | controller | ok: [compute-1] => (item=['virtsecretd', ''])
2025-09-29 09:16:24.059811 | controller | ok: [compute-1] => (item=['virtsecretd', '-ro'])
2025-09-29 09:16:24.059817 | controller | ok: [compute-1] => (item=['virtsecretd', '-admin'])
2025-09-29 09:16:24.059823 | controller |
2025-09-29 09:16:24.059830 | controller | TASK [osp.edpm.edpm_libvirt : Configure libvirt] *******************************
2025-09-29 09:16:24.059837 | controller | Monday 29 September 2025  09:16:23 +0000 (0:00:04.681)       0:06:21.785 ******
2025-09-29 09:16:24.059848 | 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 09:16:24.103211 | controller |
2025-09-29 09:16:24.103233 | controller | TASK [osp.edpm.edpm_libvirt : Get libvirt password] ****************************
2025-09-29 09:16:24.103242 | controller | Monday 29 September 2025  09:16:24 +0000 (0:00:00.069)       0:06:21.855 ******
2025-09-29 09:16:24.103253 | controller | skipping: [compute-1]
2025-09-29 09:16:24.139033 | controller |
2025-09-29 09:16:24.139072 | controller | TASK [osp.edpm.edpm_libvirt : Gather user fact] ********************************
2025-09-29 09:16:24.139084 | controller | Monday 29 September 2025  09:16:24 +0000 (0:00:00.043)       0:06:21.898 ******
2025-09-29 09:16:24.139097 | controller | skipping: [compute-1]
2025-09-29 09:16:24.139130 | controller |
2025-09-29 09:16:24.139141 | controller | TASK [osp.edpm.edpm_libvirt : Create libvirt config dir] ***********************
2025-09-29 09:16:24.139326 | controller | Monday 29 September 2025  09:16:24 +0000 (0:00:00.035)       0:06:21.934 ******
2025-09-29 09:16:25.317059 | controller | changed: [compute-1] => (item={'path': '/etc/tmpfiles.d/', 'owner': 'root', 'group': 'root'})
2025-09-29 09:16:25.317159 | controller | changed: [compute-1] => (item={'path': '/var/lib/edpm-config/firewall', 'owner': 'root', 'group': 'root'})
2025-09-29 09:16:25.317187 | controller | changed: [compute-1] => (item={'path': '/etc/pki/libvirt', 'owner': 'root', 'group': 'root', 'mode': '0755'})
2025-09-29 09:16:25.317195 | controller | changed: [compute-1] => (item={'path': '/etc/pki/libvirt/private', 'owner': 'root', 'group': 'root', 'mode': '0755'})
2025-09-29 09:16:25.317201 | controller | changed: [compute-1] => (item={'path': '/etc/pki/CA', 'owner': 'root', 'group': 'root', 'mode': '0755'})
2025-09-29 09:16:25.317206 | controller | changed: [compute-1] => (item={'path': '/etc/pki/qemu', 'owner': 'root', 'group': 'qemu'})
2025-09-29 09:16:25.317212 | controller |
2025-09-29 09:16:25.317217 | controller | TASK [osp.edpm.edpm_libvirt : Render libvirt config files] *********************
2025-09-29 09:16:25.317227 | controller | Monday 29 September 2025  09:16:25 +0000 (0:00:01.177)       0:06:23.111 ******
2025-09-29 09:16:28.486098 | controller | changed: [compute-1] => (item={'src': 'virtlogd.conf', 'dest': 'libvirt/virtlogd.conf'})
2025-09-29 09:16:28.533531 | controller | changed: [compute-1] => (item={'src': 'virtnodedevd.conf', 'dest': 'libvirt/virtnodedevd.conf'})
2025-09-29 09:16:28.533558 | controller | changed: [compute-1] => (item={'src': 'virtproxyd.conf', 'dest': 'libvirt/virtproxyd.conf'})
2025-09-29 09:16:28.533566 | controller | changed: [compute-1] => (item={'src': 'virtqemud.conf', 'dest': 'libvirt/virtqemud.conf'})
2025-09-29 09:16:28.533573 | controller | changed: [compute-1] => (item={'src': 'qemu.conf.j2', 'dest': 'libvirt/qemu.conf'})
2025-09-29 09:16:28.533580 | controller | changed: [compute-1] => (item={'src': 'virtsecretd.conf', 'dest': 'libvirt/virtsecretd.conf'})
2025-09-29 09:16:28.533586 | controller | changed: [compute-1] => (item={'src': 'auth.conf', 'dest': 'libvirt/auth.conf', 'mode': '0600'})
2025-09-29 09:16:28.533593 | controller | changed: [compute-1] => (item={'src': 'sasl_libvirt.conf', 'dest': 'sasl2/libvirt.conf'})
2025-09-29 09:16:28.533600 | controller |
2025-09-29 09:16:28.533607 | controller | TASK [osp.edpm.edpm_libvirt : Add libvirt password to sasl db] *****************
2025-09-29 09:16:28.533613 | controller | Monday 29 September 2025  09:16:28 +0000 (0:00:03.169)       0:06:26.280 ******
2025-09-29 09:16:28.533623 | controller | skipping: [compute-1]
2025-09-29 09:16:28.771910 | controller |
2025-09-29 09:16:28.771945 | controller | TASK [osp.edpm.edpm_libvirt : Remove libvirt sasl db] **************************
2025-09-29 09:16:28.771954 | controller | Monday 29 September 2025  09:16:28 +0000 (0:00:00.048)       0:06:26.329 ******
2025-09-29 09:16:28.771964 | controller | ok: [compute-1]
2025-09-29 09:16:31.447024 | controller |
2025-09-29 09:16:31.447059 | controller | TASK [osp.edpm.edpm_libvirt : Create libvirt socket activation drop-in directories] ***
2025-09-29 09:16:31.447069 | controller | Monday 29 September 2025  09:16:28 +0000 (0:00:00.238)       0:06:26.567 ******
2025-09-29 09:16:31.447087 | controller | changed: [compute-1] => (item=['virtlogd', ''])
2025-09-29 09:16:31.447116 | controller | skipping: [compute-1] => (item=['virtlogd', '-ro'])
2025-09-29 09:16:31.447124 | controller | changed: [compute-1] => (item=['virtlogd', '-admin'])
2025-09-29 09:16:31.447131 | controller | changed: [compute-1] => (item=['virtnodedevd', ''])
2025-09-29 09:16:31.447137 | controller | changed: [compute-1] => (item=['virtnodedevd', '-ro'])
2025-09-29 09:16:31.447143 | controller | changed: [compute-1] => (item=['virtnodedevd', '-admin'])
2025-09-29 09:16:31.447150 | controller | changed: [compute-1] => (item=['virtproxyd', ''])
2025-09-29 09:16:31.447156 | controller | changed: [compute-1] => (item=['virtproxyd', '-ro'])
2025-09-29 09:16:31.447162 | controller | changed: [compute-1] => (item=['virtproxyd', '-admin'])
2025-09-29 09:16:31.447168 | controller | changed: [compute-1] => (item=['virtqemud', ''])
2025-09-29 09:16:31.447175 | controller | changed: [compute-1] => (item=['virtqemud', '-ro'])
2025-09-29 09:16:31.447183 | controller | changed: [compute-1] => (item=['virtqemud', '-admin'])
2025-09-29 09:16:31.447206 | controller | changed: [compute-1] => (item=['virtsecretd', ''])
2025-09-29 09:16:31.447676 | controller | changed: [compute-1] => (item=['virtsecretd', '-ro'])
2025-09-29 09:16:36.313040 | controller | changed: [compute-1] => (item=['virtsecretd', '-admin'])
2025-09-29 09:16:36.313083 | controller |
2025-09-29 09:16:36.313094 | controller | TASK [osp.edpm.edpm_libvirt : Render libvirt socket activation drop-in files] ***
2025-09-29 09:16:36.313110 | controller | Monday 29 September 2025  09:16:31 +0000 (0:00:02.674)       0:06:29.241 ******
2025-09-29 09:16:36.313123 | controller | changed: [compute-1] => (item=['virtlogd', ''])
2025-09-29 09:16:36.313156 | controller | skipping: [compute-1] => (item=['virtlogd', '-ro'])
2025-09-29 09:16:36.313165 | controller | changed: [compute-1] => (item=['virtlogd', '-admin'])
2025-09-29 09:16:36.313172 | controller | changed: [compute-1] => (item=['virtnodedevd', ''])
2025-09-29 09:16:36.313182 | controller | changed: [compute-1] => (item=['virtnodedevd', '-ro'])
2025-09-29 09:16:36.314234 | controller | changed: [compute-1] => (item=['virtnodedevd', '-admin'])
2025-09-29 09:16:36.314274 | controller | changed: [compute-1] => (item=['virtproxyd', ''])
2025-09-29 09:16:36.502544 | controller | changed: [compute-1] => (item=['virtproxyd', '-ro'])
2025-09-29 09:16:36.502579 | controller | changed: [compute-1] => (item=['virtproxyd', '-admin'])
2025-09-29 09:16:36.502586 | controller | changed: [compute-1] => (item=['virtqemud', ''])
2025-09-29 09:16:36.502593 | controller | changed: [compute-1] => (item=['virtqemud', '-ro'])
2025-09-29 09:16:36.502598 | controller | changed: [compute-1] => (item=['virtqemud', '-admin'])
2025-09-29 09:16:36.502603 | controller | changed: [compute-1] => (item=['virtsecretd', ''])
2025-09-29 09:16:36.502608 | controller | changed: [compute-1] => (item=['virtsecretd', '-ro'])
2025-09-29 09:16:36.502613 | controller | changed: [compute-1] => (item=['virtsecretd', '-admin'])
2025-09-29 09:16:36.502618 | controller |
2025-09-29 09:16:36.502623 | controller | TASK [osp.edpm.edpm_libvirt : Check if /run/libvirt has container_file_t selinux label] ***
2025-09-29 09:16:36.502629 | controller | Monday 29 September 2025  09:16:36 +0000 (0:00:04.866)       0:06:34.108 ******
2025-09-29 09:16:36.502640 | controller | ok: [compute-1]
2025-09-29 09:16:36.554101 | controller |
2025-09-29 09:16:36.554140 | controller | TASK [osp.edpm.edpm_libvirt : Restore selinux label for /run/libvirt to virt_var_run_t] ***
2025-09-29 09:16:36.554152 | controller | Monday 29 September 2025  09:16:36 +0000 (0:00:00.189)       0:06:34.298 ******
2025-09-29 09:16:36.554168 | controller | skipping: [compute-1]
2025-09-29 09:16:36.554194 | controller |
2025-09-29 09:16:36.554203 | controller | TASK [osp.edpm.edpm_libvirt : Enable os_enable_vtpm SELinux boolean for vTPM] ***
2025-09-29 09:16:36.554294 | controller | Monday 29 September 2025  09:16:36 +0000 (0:00:00.051)       0:06:34.349 ******
2025-09-29 09:16:37.552975 | controller | changed: [compute-1]
2025-09-29 09:16:37.620011 | controller |
2025-09-29 09:16:37.620047 | controller | TASK [osp.edpm.edpm_libvirt : Move libvirt TLS files to the right location on the compute node] ***
2025-09-29 09:16:37.620056 | controller | Monday 29 September 2025  09:16:37 +0000 (0:00:00.998)       0:06:35.348 ******
2025-09-29 09:16:37.620066 | controller | skipping: [compute-1] => (item={'src': '/var/lib/openstack/certs/libvirt/default/tls.crt', 'dest': '/etc/pki/libvirt/servercert.pem'})
2025-09-29 09:16:37.718021 | 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 09:16:37.718057 | controller | skipping: [compute-1] => (item={'src': '/var/lib/openstack/certs/libvirt/default/tls.crt', 'dest': '/etc/pki/libvirt/clientcert.pem'})
2025-09-29 09:16:37.718067 | controller | skipping: [compute-1] => (item={'src': '/var/lib/openstack/certs/libvirt/default/tls.key', 'dest': '/etc/pki/libvirt/private/clientkey.pem'})
2025-09-29 09:16:37.718075 | controller | skipping: [compute-1] => (item={'src': '/var/lib/openstack/certs/libvirt/default/ca.crt', 'dest': '/etc/pki/CA/cacert.pem'})
2025-09-29 09:16:37.718082 | controller | skipping: [compute-1]
2025-09-29 09:16:37.718090 | controller |
2025-09-29 09:16:37.718098 | controller | TASK [osp.edpm.edpm_libvirt : Move qemu TLS files to the right location on the compute node] ***
2025-09-29 09:16:37.718105 | controller | Monday 29 September 2025  09:16:37 +0000 (0:00:00.066)       0:06:35.414 ******
2025-09-29 09:16:37.718121 | controller | skipping: [compute-1] => (item={'src': '/var/lib/openstack/certs/libvirt/default/tls.crt', 'dest': '/etc/pki/qemu/server-cert.pem'})
2025-09-29 09:16:37.718149 | controller | skipping: [compute-1] => (item={'src': '/var/lib/openstack/certs/libvirt/default/tls.key', 'dest': '/etc/pki/qemu/server-key.pem'})
2025-09-29 09:16:37.718157 | controller | skipping: [compute-1] => (item={'src': '/var/lib/openstack/certs/libvirt/default/tls.crt', 'dest': '/etc/pki/qemu/client-cert.pem'})
2025-09-29 09:16:37.718171 | controller | skipping: [compute-1] => (item={'src': '/var/lib/openstack/certs/libvirt/default/tls.key', 'dest': '/etc/pki/qemu/client-key.pem'})
2025-09-29 09:16:37.718178 | controller | skipping: [compute-1] => (item={'src': '/var/lib/openstack/certs/libvirt/default/ca.crt', 'dest': '/etc/pki/qemu/ca-cert.pem'})
2025-09-29 09:16:37.718185 | controller | skipping: [compute-1]
2025-09-29 09:16:37.718192 | controller |
2025-09-29 09:16:37.718199 | controller | TASK [osp.edpm.edpm_libvirt : Flush handlers] **********************************
2025-09-29 09:16:37.718206 | controller | Monday 29 September 2025  09:16:37 +0000 (0:00:00.062)       0:06:35.477 ******
2025-09-29 09:16:37.718214 | controller |
2025-09-29 09:16:37.718242 | controller | RUNNING HANDLER [osp.edpm.edpm_podman : Restart rsyslog] ***********************
2025-09-29 09:16:37.718261 | controller | Monday 29 September 2025  09:16:37 +0000 (0:00:00.035)       0:06:35.512 ******
2025-09-29 09:16:38.267757 | controller | changed: [compute-1]
2025-09-29 09:16:40.882493 | controller |
2025-09-29 09:16:40.882526 | controller | RUNNING HANDLER [osp.edpm.edpm_libvirt : Restart libvirt services] *************
2025-09-29 09:16:40.882534 | controller | Monday 29 September 2025  09:16:38 +0000 (0:00:00.550)       0:06:36.063 ******
2025-09-29 09:16:40.882543 | controller | changed: [compute-1] => (item=virtlogd)
2025-09-29 09:16:40.949102 | controller | changed: [compute-1] => (item=virtnodedevd)
2025-09-29 09:16:40.949131 | controller | changed: [compute-1] => (item=virtproxyd)
2025-09-29 09:16:40.949141 | controller | changed: [compute-1] => (item=virtqemud)
2025-09-29 09:16:40.949148 | controller | changed: [compute-1] => (item=virtsecretd)
2025-09-29 09:16:40.949156 | controller |
2025-09-29 09:16:40.949164 | controller | TASK [osp.edpm.edpm_libvirt : Post-install libvirt] ****************************
2025-09-29 09:16:40.949172 | controller | Monday 29 September 2025  09:16:40 +0000 (0:00:02.614)       0:06:38.677 ******
2025-09-29 09:16:40.949184 | 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 09:16:41.163173 | controller |
2025-09-29 09:16:41.163197 | controller | TASK [osp.edpm.edpm_libvirt : Ensure ceph config directory exists] *************
2025-09-29 09:16:41.163205 | controller | Monday 29 September 2025  09:16:40 +0000 (0:00:00.067)       0:06:38.744 ******
2025-09-29 09:16:41.163214 | controller | changed: [compute-1]
2025-09-29 09:16:41.378008 | controller |
2025-09-29 09:16:41.378043 | controller | TASK [osp.edpm.edpm_libvirt : Gather Ceph configuration files] *****************
2025-09-29 09:16:41.378052 | controller | Monday 29 September 2025  09:16:41 +0000 (0:00:00.214)       0:06:38.958 ******
2025-09-29 09:16:41.378063 | controller | ok: [compute-1]
2025-09-29 09:16:41.378159 | controller |
2025-09-29 09:16:41.378191 | controller | TASK [osp.edpm.edpm_libvirt : Extract FSIDs from Ceph configuration files] *****
2025-09-29 09:16:41.378205 | controller | Monday 29 September 2025  09:16:41 +0000 (0:00:00.214)       0:06:39.173 ******
2025-09-29 09:16:41.450447 | controller | skipping: [compute-1]
2025-09-29 09:16:41.480956 | controller |
2025-09-29 09:16:41.480976 | controller | TASK [osp.edpm.edpm_libvirt : Map Ceph clusters to FSIDs] **********************
2025-09-29 09:16:41.480984 | controller | Monday 29 September 2025  09:16:41 +0000 (0:00:00.072)       0:06:39.245 ******
2025-09-29 09:16:41.480994 | controller | skipping: [compute-1]
2025-09-29 09:16:41.481218 | controller |
2025-09-29 09:16:41.481233 | controller | TASK [osp.edpm.edpm_libvirt : Gather Ceph keyring files] ***********************
2025-09-29 09:16:41.529969 | controller | Monday 29 September 2025  09:16:41 +0000 (0:00:00.030)       0:06:39.276 ******
2025-09-29 09:16:41.530012 | controller | skipping: [compute-1]
2025-09-29 09:16:41.530285 | controller |
2025-09-29 09:16:41.581666 | controller | TASK [osp.edpm.edpm_libvirt : Map Ceph clusters to cephx users] ****************
2025-09-29 09:16:41.581686 | controller | Monday 29 September 2025  09:16:41 +0000 (0:00:00.049)       0:06:39.325 ******
2025-09-29 09:16:41.581696 | controller | skipping: [compute-1]
2025-09-29 09:16:41.632045 | controller |
2025-09-29 09:16:41.632085 | controller | TASK [osp.edpm.edpm_libvirt : Use maps to create libvirt secrets] **************
2025-09-29 09:16:41.632102 | controller | Monday 29 September 2025  09:16:41 +0000 (0:00:00.051)       0:06:39.377 ******
2025-09-29 09:16:41.632120 | controller | skipping: [compute-1]
2025-09-29 09:16:41.661258 | controller |
2025-09-29 09:16:41.661291 | controller | TASK [osp.edpm.edpm_libvirt : Copy found_confs to /etc/ceph for qemu] **********
2025-09-29 09:16:41.661302 | controller | Monday 29 September 2025  09:16:41 +0000 (0:00:00.050)       0:06:39.427 ******
2025-09-29 09:16:41.661327 | controller | skipping: [compute-1]
2025-09-29 09:16:41.661511 | controller |
2025-09-29 09:16:41.661755 | controller | TASK [osp.edpm.edpm_libvirt : Copy libvirt firewall config] ********************
2025-09-29 09:16:41.661911 | controller | Monday 29 September 2025  09:16:41 +0000 (0:00:00.029)       0:06:39.457 ******
2025-09-29 09:16:42.059840 | controller | changed: [compute-1]
2025-09-29 09:16:42.122347 | controller |
2025-09-29 09:16:42.122381 | controller | TASK [Configure firewall for the libvirt] **************************************
2025-09-29 09:16:42.122392 | controller | Monday 29 September 2025  09:16:42 +0000 (0:00:00.398)       0:06:39.855 ******
2025-09-29 09:16:42.122405 | controller |
2025-09-29 09:16:42.367953 | controller | TASK [osp.edpm.edpm_nftables : Create snippets directory] **********************
2025-09-29 09:16:42.367988 | controller | Monday 29 September 2025  09:16:42 +0000 (0:00:00.062)       0:06:39.918 ******
2025-09-29 09:16:42.368004 | controller | ok: [compute-1]
2025-09-29 09:16:42.767687 | controller |
2025-09-29 09:16:42.767720 | controller | TASK [osp.edpm.edpm_nftables : Push default ruleset snipet] ********************
2025-09-29 09:16:42.767730 | controller | Monday 29 September 2025  09:16:42 +0000 (0:00:00.245)       0:06:40.163 ******
2025-09-29 09:16:42.767742 | controller | changed: [compute-1]
2025-09-29 09:16:43.155064 | controller |
2025-09-29 09:16:43.155105 | controller | TASK [osp.edpm.edpm_nftables : Write user rules snippet] ***********************
2025-09-29 09:16:43.155115 | controller | Monday 29 September 2025  09:16:42 +0000 (0:00:00.399)       0:06:40.563 ******
2025-09-29 09:16:43.155127 | controller | changed: [compute-1]
2025-09-29 09:16:43.155160 | controller |
2025-09-29 09:16:43.155394 | controller | TASK [osp.edpm.edpm_nftables : Push empty ruleset] *****************************
2025-09-29 09:16:43.531347 | controller | Monday 29 September 2025  09:16:43 +0000 (0:00:00.387)       0:06:40.950 ******
2025-09-29 09:16:43.531379 | controller | changed: [compute-1]
2025-09-29 09:16:43.792015 | controller |
2025-09-29 09:16:43.792054 | controller | TASK [osp.edpm.edpm_nftables : Load empty ruleset] *****************************
2025-09-29 09:16:43.792065 | controller | Monday 29 September 2025  09:16:43 +0000 (0:00:00.376)       0:06:41.326 ******
2025-09-29 09:16:43.792088 | controller | changed: [compute-1]
2025-09-29 09:16:43.792112 | controller |
2025-09-29 09:16:43.792129 | controller | TASK [osp.edpm.edpm_nftables : Get current nftables content] *******************
2025-09-29 09:16:43.792232 | controller | Monday 29 September 2025  09:16:43 +0000 (0:00:00.261)       0:06:41.587 ******
2025-09-29 09:16:44.010579 | controller | changed: [compute-1]
2025-09-29 09:16:44.334647 | controller |
2025-09-29 09:16:44.334677 | controller | TASK [osp.edpm.edpm_nftables : Load firewall snippets] *************************
2025-09-29 09:16:44.334685 | controller | Monday 29 September 2025  09:16:44 +0000 (0:00:00.218)       0:06:41.805 ******
2025-09-29 09:16:44.334694 | controller | ok: [compute-1]
2025-09-29 09:16:44.732753 | controller |
2025-09-29 09:16:44.732784 | controller | TASK [osp.edpm.edpm_nftables : Generate chain jumps] ***************************
2025-09-29 09:16:44.732793 | controller | Monday 29 September 2025  09:16:44 +0000 (0:00:00.324)       0:06:42.130 ******
2025-09-29 09:16:44.732803 | controller | changed: [compute-1]
2025-09-29 09:16:45.133821 | controller |
2025-09-29 09:16:45.133855 | controller | TASK [osp.edpm.edpm_nftables : Generate chain jumps] ***************************
2025-09-29 09:16:45.133863 | controller | Monday 29 September 2025  09:16:44 +0000 (0:00:00.397)       0:06:42.528 ******
2025-09-29 09:16:45.133873 | controller | changed: [compute-1]
2025-09-29 09:16:45.521007 | controller |
2025-09-29 09:16:45.521042 | controller | TASK [osp.edpm.edpm_nftables : Generate nft flushes] ***************************
2025-09-29 09:16:45.521052 | controller | Monday 29 September 2025  09:16:45 +0000 (0:00:00.401)       0:06:42.929 ******
2025-09-29 09:16:45.521064 | controller | changed: [compute-1]
2025-09-29 09:16:45.903870 | controller |
2025-09-29 09:16:45.903922 | controller | TASK [osp.edpm.edpm_nftables : Generate nft edpm chains] ***********************
2025-09-29 09:16:45.903933 | controller | Monday 29 September 2025  09:16:45 +0000 (0:00:00.386)       0:06:43.316 ******
2025-09-29 09:16:45.903948 | controller | changed: [compute-1]
2025-09-29 09:16:46.333607 | controller |
2025-09-29 09:16:46.333644 | controller | TASK [osp.edpm.edpm_nftables : Generate nft ruleset in static file] ************
2025-09-29 09:16:46.333654 | controller | Monday 29 September 2025  09:16:45 +0000 (0:00:00.382)       0:06:43.699 ******
2025-09-29 09:16:46.333673 | controller | changed: [compute-1]
2025-09-29 09:16:46.572930 | controller |
2025-09-29 09:16:46.572959 | controller | TASK [osp.edpm.edpm_nftables : Create a sentinel file when nft rules are changed] ***
2025-09-29 09:16:46.572966 | controller | Monday 29 September 2025  09:16:46 +0000 (0:00:00.429)       0:06:44.128 ******
2025-09-29 09:16:46.572977 | controller | changed: [compute-1]
2025-09-29 09:16:46.852016 | controller |
2025-09-29 09:16:46.852054 | controller | TASK [osp.edpm.edpm_nftables : Validate all of the generated content before loading] ***
2025-09-29 09:16:46.852065 | controller | Monday 29 September 2025  09:16:46 +0000 (0:00:00.239)       0:06:44.368 ******
2025-09-29 09:16:46.852079 | controller | changed: [compute-1]
2025-09-29 09:16:46.852113 | controller |
2025-09-29 09:16:46.852342 | controller | TASK [osp.edpm.edpm_nftables : Ensure we load our different nft rulesets on boot] ***
2025-09-29 09:16:47.156509 | controller | Monday 29 September 2025  09:16:46 +0000 (0:00:00.279)       0:06:44.647 ******
2025-09-29 09:16:47.156550 | controller | changed: [compute-1]
2025-09-29 09:16:47.229071 | controller |
2025-09-29 09:16:47.229131 | controller | TASK [Reload firewall for new libvirt rules] ***********************************
2025-09-29 09:16:47.229146 | controller | Monday 29 September 2025  09:16:47 +0000 (0:00:00.304)       0:06:44.951 ******
2025-09-29 09:16:47.229163 | controller |
2025-09-29 09:16:47.229193 | controller | TASK [osp.edpm.edpm_nftables : Inject our custom chains in nftables] ***********
2025-09-29 09:16:47.229207 | controller | Monday 29 September 2025  09:16:47 +0000 (0:00:00.073)       0:06:45.025 ******
2025-09-29 09:16:47.458387 | controller | changed: [compute-1]
2025-09-29 09:16:47.458467 | controller |
2025-09-29 09:16:47.458734 | controller | TASK [osp.edpm.edpm_nftables : Check if rules are changed] *********************
2025-09-29 09:16:47.688041 | controller | Monday 29 September 2025  09:16:47 +0000 (0:00:00.229)       0:06:45.254 ******
2025-09-29 09:16:47.688086 | controller | ok: [compute-1]
2025-09-29 09:16:47.688118 | controller |
2025-09-29 09:16:47.688126 | controller | TASK [osp.edpm.edpm_nftables : Reload ruleset] *********************************
2025-09-29 09:16:47.688134 | controller | Monday 29 September 2025  09:16:47 +0000 (0:00:00.229)       0:06:45.483 ******
2025-09-29 09:16:47.919624 | controller | changed: [compute-1]
2025-09-29 09:16:47.919686 | controller |
2025-09-29 09:16:47.919785 | controller | TASK [osp.edpm.edpm_nftables : Delete nft_ruleset_changed file] ****************
2025-09-29 09:16:47.919933 | controller | Monday 29 September 2025  09:16:47 +0000 (0:00:00.232)       0:06:45.715 ******
2025-09-29 09:16:48.161013 | controller | changed: [compute-1]
2025-09-29 09:16:48.161252 | controller |
2025-09-29 09:16:48.161285 | controller | TASK [osp.edpm.edpm_libvirt : Set up systemd target for libvirt services] ******
2025-09-29 09:16:48.553801 | controller | Monday 29 September 2025  09:16:48 +0000 (0:00:00.240)       0:06:45.956 ******
2025-09-29 09:16:48.553841 | controller | changed: [compute-1]
2025-09-29 09:16:48.962973 | controller |
2025-09-29 09:16:48.963004 | controller | TASK [osp.edpm.edpm_libvirt : Install libvirt-guests unit to stop nova_compute container before shutdown VMs] ***
2025-09-29 09:16:48.963012 | controller | Monday 29 September 2025  09:16:48 +0000 (0:00:00.392)       0:06:46.349 ******
2025-09-29 09:16:48.963022 | controller | changed: [compute-1]
2025-09-29 09:16:48.963379 | controller |
2025-09-29 09:16:49.381082 | controller | TASK [osp.edpm.edpm_libvirt : Making sure virt-guest-shutdown.target is present] ***
2025-09-29 09:16:49.381120 | controller | Monday 29 September 2025  09:16:48 +0000 (0:00:00.409)       0:06:46.758 ******
2025-09-29 09:16:49.381135 | controller | changed: [compute-1]
2025-09-29 09:16:49.381164 | controller |
2025-09-29 09:16:49.381172 | controller | TASK [osp.edpm.edpm_libvirt : Flush post-install handlers] *********************
2025-09-29 09:16:49.381180 | controller | Monday 29 September 2025  09:16:49 +0000 (0:00:00.384)       0:06:47.143 ******
2025-09-29 09:16:49.381369 | controller |
2025-09-29 09:16:49.381386 | controller | RUNNING HANDLER [osp.edpm.edpm_libvirt : Restart edpm_libvirt.target] **********
2025-09-29 09:16:49.895958 | controller | Monday 29 September 2025  09:16:49 +0000 (0:00:00.033)       0:06:47.176 ******
2025-09-29 09:16:49.896003 | controller | changed: [compute-1]
2025-09-29 09:16:50.612977 | controller |
2025-09-29 09:16:50.613014 | controller | RUNNING HANDLER [osp.edpm.edpm_libvirt : Reload edpm_libvirt_guests] ***********
2025-09-29 09:16:50.613023 | controller | Monday 29 September 2025  09:16:49 +0000 (0:00:00.514)       0:06:47.691 ******
2025-09-29 09:16:50.613032 | controller | changed: [compute-1]
2025-09-29 09:16:50.829577 | controller |
2025-09-29 09:16:50.829604 | controller | TASK [ensure /etc/multipath.conf exists] ***************************************
2025-09-29 09:16:50.829612 | controller | Monday 29 September 2025  09:16:50 +0000 (0:00:00.717)       0:06:48.408 ******
2025-09-29 09:16:50.829622 | controller | changed: [compute-1]
2025-09-29 09:16:51.058657 | controller |
2025-09-29 09:16:51.058690 | controller | TASK [Create Ceph client file directory] ***************************************
2025-09-29 09:16:51.058698 | controller | Monday 29 September 2025  09:16:50 +0000 (0:00:00.216)       0:06:48.625 ******
2025-09-29 09:16:51.058713 | controller | ok: [compute-1]
2025-09-29 09:16:51.278384 | controller |
2025-09-29 09:16:51.278416 | controller | TASK [Create ssh_known_hosts file] *********************************************
2025-09-29 09:16:51.278424 | controller | Monday 29 September 2025  09:16:51 +0000 (0:00:00.229)       0:06:48.854 ******
2025-09-29 09:16:51.278434 | controller | changed: [compute-1]
2025-09-29 09:16:51.496836 | controller |
2025-09-29 09:16:51.496868 | controller | TASK [create pki temp directory] ***********************************************
2025-09-29 09:16:51.496875 | controller | Monday 29 September 2025  09:16:51 +0000 (0:00:00.219)       0:06:49.073 ******
2025-09-29 09:16:51.496885 | controller | changed: [compute-1]
2025-09-29 09:16:52.382428 | controller |
2025-09-29 09:16:52.382463 | controller | TASK [Create private key (RSA, 4096 bits)] *************************************
2025-09-29 09:16:52.382471 | controller | Monday 29 September 2025  09:16:51 +0000 (0:00:00.218)       0:06:49.292 ******
2025-09-29 09:16:52.382481 | controller | changed: [compute-1]
2025-09-29 09:16:53.089929 | controller |
2025-09-29 09:16:53.089962 | controller | TASK [Create a certificate signing request for ca] *****************************
2025-09-29 09:16:53.089971 | controller | Monday 29 September 2025  09:16:52 +0000 (0:00:00.885)       0:06:50.177 ******
2025-09-29 09:16:53.089980 | controller | changed: [compute-1]
2025-09-29 09:16:53.899740 | controller |
2025-09-29 09:16:53.899777 | controller | TASK [Create a certificate athority] *******************************************
2025-09-29 09:16:53.899787 | controller | Monday 29 September 2025  09:16:53 +0000 (0:00:00.707)       0:06:50.885 ******
2025-09-29 09:16:53.899798 | controller | changed: [compute-1]
2025-09-29 09:16:54.091348 | controller |
2025-09-29 09:16:54.091383 | controller | TASK [Set permissions for certificate authority public key] ********************
2025-09-29 09:16:54.091391 | controller | Monday 29 September 2025  09:16:53 +0000 (0:00:00.809)       0:06:51.695 ******
2025-09-29 09:16:54.091401 | controller | changed: [compute-1]
2025-09-29 09:16:54.443027 | controller |
2025-09-29 09:16:54.443065 | controller | TASK [Gather required facts] ***************************************************
2025-09-29 09:16:54.443074 | controller | Monday 29 September 2025  09:16:54 +0000 (0:00:00.191)       0:06:51.886 ******
2025-09-29 09:16:54.443086 | controller | ok: [compute-1]
2025-09-29 09:16:54.443118 | controller |
2025-09-29 09:16:54.443146 | controller | TASK [Ensure hostname is resolvable in /etc/hosts] *****************************
2025-09-29 09:16:54.443294 | controller | Monday 29 September 2025  09:16:54 +0000 (0:00:00.351)       0:06:52.238 ******
2025-09-29 09:16:54.771862 | controller | changed: [compute-1]
2025-09-29 09:16:55.014349 | controller |
2025-09-29 09:16:55.014383 | controller | TASK [Create container config dirs for test] ***********************************
2025-09-29 09:16:55.014391 | controller | Monday 29 September 2025  09:16:54 +0000 (0:00:00.328)       0:06:52.567 ******
2025-09-29 09:16:55.014401 | controller | changed: [compute-1] => (item={'path': '/var/lib/openstack/config/nova', 'mode': '0755'})
2025-09-29 09:16:55.224644 | controller |
2025-09-29 09:16:55.224677 | controller | TASK [Create a test file to be deleted] ****************************************
2025-09-29 09:16:55.224686 | controller | Monday 29 September 2025  09:16:55 +0000 (0:00:00.242)       0:06:52.809 ******
2025-09-29 09:16:55.224696 | controller | changed: [compute-1]
2025-09-29 09:16:55.711559 | controller |
2025-09-29 09:16:55.711594 | controller | TASK [Create vendor integration directories] ***********************************
2025-09-29 09:16:55.711602 | controller | Monday 29 September 2025  09:16:55 +0000 (0:00:00.210)       0:06:53.020 ******
2025-09-29 09:16:55.711611 | controller | changed: [compute-1] => (item={'path': '/var/lib/vendor_integration'})
2025-09-29 09:16:55.777504 | controller | changed: [compute-1] => (item={'path': '/opt/vendor_integration_2'})
2025-09-29 09:16:55.777526 | controller |
2025-09-29 09:16:55.777533 | controller | PLAY RECAP *********************************************************************
2025-09-29 09:16:55.777539 | controller | compute-1                  : ok=126  changed=70   unreachable=0    failed=0    skipped=43   rescued=0    ignored=2
2025-09-29 09:16:55.777544 | controller |
2025-09-29 09:16:55.777549 | controller | Monday 29 September 2025  09:16:55 +0000 (0:00:00.484)       0:06:53.504 ******
2025-09-29 09:16:55.777554 | controller | ===============================================================================
2025-09-29 09:16:55.777559 | controller | osp.edpm.edpm_libvirt : Install libvirt packages ---------------------- 130.97s
2025-09-29 09:16:55.777564 | controller | Install openstack-selinux --------------------------------------------- 117.16s
2025-09-29 09:16:55.777569 | controller | ../../../../molecule/common/test_deps : Install edpm packages ---------- 72.80s
2025-09-29 09:16:55.777574 | controller | ../../../../molecule/common/test_deps : Install repo-setup pre-requirements --- 9.69s
2025-09-29 09:16:55.777579 | controller | Enable package repos ---------------------------------------------------- 7.08s
2025-09-29 09:16:55.777589 | controller | osp.edpm.edpm_libvirt : Render libvirt socket activation drop-in files --- 4.87s
2025-09-29 09:16:55.777594 | controller | osp.edpm.edpm_libvirt : Configure socket activation for libvirt services --- 4.68s
2025-09-29 09:16:55.777599 | controller | ../../../../molecule/common/test_deps : Install repo-setup -------------- 3.34s
2025-09-29 09:16:55.777604 | controller | osp.edpm.edpm_libvirt : Render libvirt config files --------------------- 3.17s
2025-09-29 09:16:55.777609 | controller | osp.edpm.edpm_libvirt : Create libvirt socket activation drop-in directories --- 2.67s
2025-09-29 09:16:55.777614 | controller | osp.edpm.edpm_libvirt : Restart libvirt services ------------------------ 2.61s
2025-09-29 09:16:55.777619 | controller | osp.edpm.edpm_libvirt : Ensure libvirt services are enabled and running --- 2.55s
2025-09-29 09:16:55.777624 | controller | osp.edpm.edpm_libvirt : Ensure monolithic libvirt and tcp socket activation is not enabled or running --- 2.25s
2025-09-29 09:16:55.777629 | controller | osp.edpm.edpm_tripleo_cleanup : Stop and disable tripleo services ------- 2.08s
2025-09-29 09:16:55.777634 | controller | osp.edpm.edpm_tripleo_cleanup : Gather service facts -------------------- 1.93s
2025-09-29 09:16:55.777639 | controller | osp.edpm.edpm_tripleo_cleanup : Reset state of failed systemd units ----- 1.40s
2025-09-29 09:16:55.777643 | controller | osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /usr/lib/systemd/system --- 1.34s
2025-09-29 09:16:55.777649 | controller | osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /etc/systemd/system --- 1.32s
2025-09-29 09:16:55.777654 | controller | osp.edpm.edpm_podman : Ensure podman and deps are installed ------------- 1.28s
2025-09-29 09:16:55.777659 | controller | ../../../../molecule/common/test_deps : Install selinux python libs ----- 1.19s
2025-09-29 09:16:55.777667 | controller | INFO     Running default > converge
2025-09-29 09:16:56.199989 | controller |
2025-09-29 09:16:56.200264 | controller | PLAY [Converge] ****************************************************************
2025-09-29 09:16:56.200277 | controller |
2025-09-29 09:16:56.200285 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 09:16:57.036621 | controller | Monday 29 September 2025  09:16:56 +0000 (0:00:00.034)       0:00:00.034 ******
2025-09-29 09:16:57.036682 | controller | ok: [compute-1]
2025-09-29 09:16:57.086574 | controller |
2025-09-29 09:16:57.086597 | controller | TASK [set basic user fact] *****************************************************
2025-09-29 09:16:57.086606 | controller | Monday 29 September 2025  09:16:57 +0000 (0:00:00.836)       0:00:00.871 ******
2025-09-29 09:16:57.086619 | controller | ok: [compute-1]
2025-09-29 09:16:57.177304 | controller |
2025-09-29 09:16:57.177323 | controller | TASK [set basic home fact] *****************************************************
2025-09-29 09:16:57.177346 | controller | Monday 29 September 2025  09:16:57 +0000 (0:00:00.050)       0:00:00.921 ******
2025-09-29 09:16:57.177355 | controller | skipping: [compute-1]
2025-09-29 09:16:57.230572 | controller |
2025-09-29 09:16:57.230605 | controller | TASK [osp.edpm.edpm_nova : Validating arguments against arg spec 'main' - The main entry point for the edpm_nova role.] ***
2025-09-29 09:16:57.230618 | controller | Monday 29 September 2025  09:16:57 +0000 (0:00:00.090)       0:00:01.012 ******
2025-09-29 09:16:57.230634 | controller | ok: [compute-1]
2025-09-29 09:16:57.552007 | controller |
2025-09-29 09:16:57.552041 | controller | TASK [osp.edpm.edpm_nova : Ensure ssh_known_hosts file exists] *****************
2025-09-29 09:16:57.552050 | controller | Monday 29 September 2025  09:16:57 +0000 (0:00:00.053)       0:00:01.065 ******
2025-09-29 09:16:57.552062 | controller | changed: [compute-1]
2025-09-29 09:16:57.552091 | controller |
2025-09-29 09:16:57.552101 | controller | TASK [osp.edpm.edpm_nova : Adopt nova from tripleo] ****************************
2025-09-29 09:16:57.552168 | controller | Monday 29 September 2025  09:16:57 +0000 (0:00:00.321)       0:00:01.387 ******
2025-09-29 09:16:57.618066 | 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 09:16:57.711972 | controller |
2025-09-29 09:16:57.712005 | controller | TASK [Stop and disable compute services] ***************************************
2025-09-29 09:16:57.712019 | controller | Monday 29 September 2025  09:16:57 +0000 (0:00:00.066)       0:00:01.453 ******
2025-09-29 09:16:57.712034 | controller |
2025-09-29 09:16:57.777839 | 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 09:16:57.777872 | controller | Monday 29 September 2025  09:16:57 +0000 (0:00:00.093)       0:00:01.547 ******
2025-09-29 09:16:57.777891 | controller | ok: [compute-1]
2025-09-29 09:16:58.466870 | controller |
2025-09-29 09:16:58.466919 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reload units to check for new changes] ***
2025-09-29 09:16:58.466931 | controller | Monday 29 September 2025  09:16:57 +0000 (0:00:00.065)       0:00:01.613 ******
2025-09-29 09:16:58.466942 | controller | ok: [compute-1]
2025-09-29 09:17:00.647037 | controller |
2025-09-29 09:17:00.647074 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Gather service facts] ********************
2025-09-29 09:17:00.647083 | controller | Monday 29 September 2025  09:16:58 +0000 (0:00:00.688)       0:00:02.301 ******
2025-09-29 09:17:00.647099 | controller | ok: [compute-1]
2025-09-29 09:17:00.647106 | controller |
2025-09-29 09:17:00.647112 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Set list of tripleo services] ************
2025-09-29 09:17:00.647120 | controller | Monday 29 September 2025  09:17:00 +0000 (0:00:02.180)       0:00:04.482 ******
2025-09-29 09:17:00.689009 | controller | ok: [compute-1]
2025-09-29 09:17:00.739980 | controller |
2025-09-29 09:17:00.740016 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Get all services] ************************
2025-09-29 09:17:00.740025 | controller | Monday 29 September 2025  09:17:00 +0000 (0:00:00.042)       0:00:04.524 ******
2025-09-29 09:17:00.740035 | controller | skipping: [compute-1]
2025-09-29 09:17:00.791827 | controller |
2025-09-29 09:17:00.791857 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Filter for tripleo services and skip the ones in skip list] ***
2025-09-29 09:17:00.791865 | controller | Monday 29 September 2025  09:17:00 +0000 (0:00:00.051)       0:00:04.575 ******
2025-09-29 09:17:00.791876 | controller | skipping: [compute-1]
2025-09-29 09:17:03.074971 | controller |
2025-09-29 09:17:03.075004 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Stop and disable tripleo services] *******
2025-09-29 09:17:03.075013 | controller | Monday 29 September 2025  09:17:00 +0000 (0:00:00.051)       0:00:04.627 ******
2025-09-29 09:17:03.075023 | controller | ok: [compute-1] => (item=tripleo_nova_compute.service)
2025-09-29 09:17:03.075324 | controller | ok: [compute-1] => (item=tripleo_nova_migration_target.service)
2025-09-29 09:17:03.075347 | controller | ok: [compute-1] => (item=tripleo_nova_api_cron.service)
2025-09-29 09:17:03.075354 | controller | ok: [compute-1] => (item=tripleo_nova_api.service)
2025-09-29 09:17:03.075360 | controller | ok: [compute-1] => (item=tripleo_nova_conductor.service)
2025-09-29 09:17:03.075365 | controller | ok: [compute-1] => (item=tripleo_nova_metadata.service)
2025-09-29 09:17:03.075374 | controller | ok: [compute-1] => (item=tripleo_nova_scheduler.service)
2025-09-29 09:17:03.075380 | controller | ok: [compute-1] => (item=tripleo_nova_vnc_proxy.service)
2025-09-29 09:17:03.075388 | controller |
2025-09-29 09:17:03.144046 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Check for errors] ************************
2025-09-29 09:17:03.144103 | controller | Monday 29 September 2025  09:17:03 +0000 (0:00:02.282)       0:00:06.909 ******
2025-09-29 09:17:03.144128 | 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 09:17:03.145177 | 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 09:17:03.215081 | 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 09:17:03.215130 | 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 09:17:03.215146 | 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 09:17:03.215157 | 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 09:17:03.215168 | 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 09:17:03.215178 | 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 09:17:03.215195 | controller | skipping: [compute-1]
2025-09-29 09:17:03.215207 | controller |
2025-09-29 09:17:03.215217 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files] ***********************
2025-09-29 09:17:03.215228 | controller | Monday 29 September 2025  09:17:03 +0000 (0:00:00.069)       0:00:06.979 ******
2025-09-29 09:17:03.215243 | 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 09:17:04.672369 | 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 09:17:04.672406 | controller |
2025-09-29 09:17:04.672416 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /usr/lib/systemd/system] ***
2025-09-29 09:17:04.672431 | controller | Monday 29 September 2025  09:17:03 +0000 (0:00:00.070)       0:00:07.050 ******
2025-09-29 09:17:04.672443 | controller | ok: [compute-1] => (item=tripleo_nova_compute.service)
2025-09-29 09:17:04.672465 | controller | ok: [compute-1] => (item=tripleo_nova_migration_target.service)
2025-09-29 09:17:04.673378 | controller | ok: [compute-1] => (item=tripleo_nova_api_cron.service)
2025-09-29 09:17:06.112042 | controller | ok: [compute-1] => (item=tripleo_nova_api.service)
2025-09-29 09:17:06.112087 | controller | ok: [compute-1] => (item=tripleo_nova_conductor.service)
2025-09-29 09:17:06.112100 | controller | ok: [compute-1] => (item=tripleo_nova_metadata.service)
2025-09-29 09:17:06.112110 | controller | ok: [compute-1] => (item=tripleo_nova_scheduler.service)
2025-09-29 09:17:06.112127 | controller | ok: [compute-1] => (item=tripleo_nova_vnc_proxy.service)
2025-09-29 09:17:06.112137 | controller |
2025-09-29 09:17:06.112147 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /etc/systemd/system] ***
2025-09-29 09:17:06.112157 | controller | Monday 29 September 2025  09:17:04 +0000 (0:00:01.457)       0:00:08.507 ******
2025-09-29 09:17:06.112173 | controller | ok: [compute-1] => (item=tripleo_nova_compute.service)
2025-09-29 09:17:06.112212 | controller | ok: [compute-1] => (item=tripleo_nova_migration_target.service)
2025-09-29 09:17:06.112226 | controller | ok: [compute-1] => (item=tripleo_nova_api_cron.service)
2025-09-29 09:17:06.113027 | controller | ok: [compute-1] => (item=tripleo_nova_api.service)
2025-09-29 09:17:06.113068 | controller | ok: [compute-1] => (item=tripleo_nova_conductor.service)
2025-09-29 09:17:06.201867 | controller | ok: [compute-1] => (item=tripleo_nova_metadata.service)
2025-09-29 09:17:06.201925 | controller | ok: [compute-1] => (item=tripleo_nova_scheduler.service)
2025-09-29 09:17:06.201970 | controller | ok: [compute-1] => (item=tripleo_nova_vnc_proxy.service)
2025-09-29 09:17:06.201979 | controller |
2025-09-29 09:17:06.201987 | controller | TASK [Adopt (stop tracking) certs from tripleo] ********************************
2025-09-29 09:17:06.201995 | controller | Monday 29 September 2025  09:17:06 +0000 (0:00:01.439)       0:00:09.947 ******
2025-09-29 09:17:06.202007 | controller |
2025-09-29 09:17:06.498562 | controller | TASK [osp.edpm.edpm_install_certs : Stop and disable certmonger] ***************
2025-09-29 09:17:06.498596 | controller | Monday 29 September 2025  09:17:06 +0000 (0:00:00.089)       0:00:10.037 ******
2025-09-29 09:17:06.498609 | controller | ok: [compute-1]
2025-09-29 09:17:06.765504 | controller |
2025-09-29 09:17:06.765534 | controller | TASK [osp.edpm.edpm_install_certs : Find certmonger requests] ******************
2025-09-29 09:17:06.765541 | controller | Monday 29 September 2025  09:17:06 +0000 (0:00:00.296)       0:00:10.333 ******
2025-09-29 09:17:06.765551 | controller | [WARNING]: Skipped '/var/lib/certmonger/requests' path due to this access
2025-09-29 09:17:06.792818 | controller | issue: '/var/lib/certmonger/requests' is not a directory
2025-09-29 09:17:06.792841 | controller | ok: [compute-1]
2025-09-29 09:17:06.829863 | controller |
2025-09-29 09:17:06.829882 | controller | TASK [osp.edpm.edpm_install_certs : Backup certificate requests] ***************
2025-09-29 09:17:06.829890 | controller | Monday 29 September 2025  09:17:06 +0000 (0:00:00.294)       0:00:10.628 ******
2025-09-29 09:17:06.829917 | controller | skipping: [compute-1]
2025-09-29 09:17:06.917470 | controller |
2025-09-29 09:17:06.917491 | controller | TASK [osp.edpm.edpm_install_certs : Remove certificate requests] ***************
2025-09-29 09:17:06.917500 | controller | Monday 29 September 2025  09:17:06 +0000 (0:00:00.037)       0:00:10.665 ******
2025-09-29 09:17:06.917510 | controller | skipping: [compute-1]
2025-09-29 09:17:07.383010 | controller |
2025-09-29 09:17:07.383048 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reload units] ****************************
2025-09-29 09:17:07.383059 | controller | Monday 29 September 2025  09:17:06 +0000 (0:00:00.086)       0:00:10.752 ******
2025-09-29 09:17:07.383071 | controller | ok: [compute-1]
2025-09-29 09:17:07.383100 | controller |
2025-09-29 09:17:07.383121 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reset state of failed systemd units] *****
2025-09-29 09:17:07.383243 | controller | Monday 29 September 2025  09:17:07 +0000 (0:00:00.466)       0:00:11.218 ******
2025-09-29 09:17:08.949158 | controller | changed: [compute-1] => (item=tripleo_nova_compute.service)
2025-09-29 09:17:08.949204 | controller | changed: [compute-1] => (item=tripleo_nova_migration_target.service)
2025-09-29 09:17:08.949214 | controller | changed: [compute-1] => (item=tripleo_nova_api_cron.service)
2025-09-29 09:17:08.949935 | controller | changed: [compute-1] => (item=tripleo_nova_api.service)
2025-09-29 09:17:09.031952 | controller | changed: [compute-1] => (item=tripleo_nova_conductor.service)
2025-09-29 09:17:09.031972 | controller | changed: [compute-1] => (item=tripleo_nova_metadata.service)
2025-09-29 09:17:09.031982 | controller | changed: [compute-1] => (item=tripleo_nova_scheduler.service)
2025-09-29 09:17:09.031990 | controller | changed: [compute-1] => (item=tripleo_nova_vnc_proxy.service)
2025-09-29 09:17:09.031997 | controller |
2025-09-29 09:17:09.032004 | controller | TASK [osp.edpm.edpm_nova : Configure nova] *************************************
2025-09-29 09:17:09.032011 | controller | Monday 29 September 2025  09:17:08 +0000 (0:00:01.565)       0:00:12.783 ******
2025-09-29 09:17:09.032022 | 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 09:17:09.032470 | controller |
2025-09-29 09:17:09.554103 | controller | TASK [osp.edpm.edpm_nova : Get source directory details] ***********************
2025-09-29 09:17:09.554134 | controller | Monday 29 September 2025  09:17:09 +0000 (0:00:00.083)       0:00:12.867 ******
2025-09-29 09:17:09.554149 | controller | ok: [compute-1] => (item={'src': '/var/lib/vendor_integration', 'dest': '/var/lib/vendor_integration'})
2025-09-29 09:17:09.611613 | controller | ok: [compute-1] => (item={'src': '/opt/vendor_integration_2', 'dest': '/opt/vendor_integration_2', 'options': 'shared'})
2025-09-29 09:17:09.611637 | controller |
2025-09-29 09:17:09.611645 | controller | TASK [osp.edpm.edpm_nova : Assert edpm_nova_extra_bind_mounts source directories exist] ***
2025-09-29 09:17:09.611652 | controller | Monday 29 September 2025  09:17:09 +0000 (0:00:00.521)       0:00:13.389 ******
2025-09-29 09:17:09.611665 | controller | ok: [compute-1] => changed=false
2025-09-29 09:17:09.638161 | controller |   msg: All assertions passed
2025-09-29 09:17:09.638181 | controller |
2025-09-29 09:17:09.638188 | controller | TASK [osp.edpm.edpm_nova : Assert edpm_nova_extra_bind_mounts src paths are valid] ***
2025-09-29 09:17:09.638194 | controller | Monday 29 September 2025  09:17:09 +0000 (0:00:00.057)       0:00:13.446 ******
2025-09-29 09:17:09.638204 | controller | [WARNING]: conditional statements should not include jinja2 templating
2025-09-29 09:17:09.638251 | controller | delimiters such as {{ }} or {% %}. Found: '{{ item }}' not in  {{
2025-09-29 09:17:09.638269 | controller | edpm_nova_extra_bind_mounts }} | map(attribute='src') | list
2025-09-29 09:17:09.680073 | controller | ok: [compute-1] => (item=/var/lib/nova) => changed=false
2025-09-29 09:17:09.680112 | controller |   ansible_loop_var: item
2025-09-29 09:17:09.680123 | controller |   item: /var/lib/nova
2025-09-29 09:17:09.680131 | controller |   msg: All assertions passed
2025-09-29 09:17:09.680139 | controller | ok: [compute-1] => (item=/var/lib/openstack) => changed=false
2025-09-29 09:17:09.680153 | controller |   ansible_loop_var: item
2025-09-29 09:17:09.680161 | controller |   item: /var/lib/openstack
2025-09-29 09:17:09.680169 | controller |   msg: All assertions passed
2025-09-29 09:17:09.680179 | controller |
2025-09-29 09:17:09.680190 | controller | TASK [osp.edpm.edpm_nova : Assert edpm_nova_extra_bind_mounts dest paths do not collide with standard mounts] ***
2025-09-29 09:17:09.680343 | controller | Monday 29 September 2025  09:17:09 +0000 (0:00:00.068)       0:00:13.514 ******
2025-09-29 09:17:09.763566 | controller | [WARNING]: conditional statements should not include jinja2 templating
2025-09-29 09:17:09.763711 | controller | delimiters such as {{ }} or {% %}. Found: '{{ item }}' not in {{
2025-09-29 09:17:09.763855 | controller | edpm_nova_extra_bind_mounts }} | map(attribute='dest') | list
2025-09-29 09:17:09.791456 | controller | ok: [compute-1] => (item=/var/lib/nova) => changed=false
2025-09-29 09:17:09.791608 | controller |   ansible_loop_var: item
2025-09-29 09:17:09.791743 | controller |   item: /var/lib/nova
2025-09-29 09:17:09.791878 | controller |   msg: All assertions passed
2025-09-29 09:17:09.792032 | controller | ok: [compute-1] => (item=/var/lib/openstack) => changed=false
2025-09-29 09:17:09.792145 | controller |   ansible_loop_var: item
2025-09-29 09:17:09.792247 | controller |   item: /var/lib/openstack
2025-09-29 09:17:09.792376 | controller |   msg: All assertions passed
2025-09-29 09:17:09.792474 | controller | ok: [compute-1] => (item=/etc/nova) => changed=false
2025-09-29 09:17:09.792575 | controller |   ansible_loop_var: item
2025-09-29 09:17:09.792675 | controller |   item: /etc/nova
2025-09-29 09:17:09.792774 | controller |   msg: All assertions passed
2025-09-29 09:17:09.792869 | controller |
2025-09-29 09:17:09.793029 | controller | TASK [osp.edpm.edpm_nova : Assert edpm_nova_extra_bind_mounts dest paths are subdirectory of valid paths] ***
2025-09-29 09:17:09.793179 | controller | Monday 29 September 2025  09:17:09 +0000 (0:00:00.112)       0:00:13.627 ******
2025-09-29 09:17:09.818209 | controller | [WARNING]: conditional statements should not include jinja2 templating
2025-09-29 09:17:09.818402 | controller | delimiters such as {{ }} or {% %}. Found: '{{ item.dest |
2025-09-29 09:17:09.818558 | controller | regex_search('^(/etc|/opt|/var/lib)') }}' is not none
2025-09-29 09:17:09.859755 | controller | ok: [compute-1] => (item={'src': '/var/lib/vendor_integration', 'dest': '/var/lib/vendor_integration'}) => changed=false
2025-09-29 09:17:09.859958 | controller |   ansible_loop_var: item
2025-09-29 09:17:09.860124 | controller |   item:
2025-09-29 09:17:09.860261 | controller |     dest: /var/lib/vendor_integration
2025-09-29 09:17:09.860394 | controller |     src: /var/lib/vendor_integration
2025-09-29 09:17:09.860522 | controller |   msg: All assertions passed
2025-09-29 09:17:09.860649 | controller | ok: [compute-1] => (item={'src': '/opt/vendor_integration_2', 'dest': '/opt/vendor_integration_2', 'options': 'shared'}) => changed=false
2025-09-29 09:17:09.860767 | controller |   ansible_loop_var: item
2025-09-29 09:17:09.860885 | controller |   item:
2025-09-29 09:17:09.861037 | controller |     dest: /opt/vendor_integration_2
2025-09-29 09:17:09.861162 | controller |     options: shared
2025-09-29 09:17:09.861280 | controller |     src: /opt/vendor_integration_2
2025-09-29 09:17:09.861405 | controller |   msg: All assertions passed
2025-09-29 09:17:09.861516 | controller |
2025-09-29 09:17:09.861641 | controller | TASK [osp.edpm.edpm_nova : Create sync config dirs] ****************************
2025-09-29 09:17:09.861772 | controller | Monday 29 September 2025  09:17:09 +0000 (0:00:00.068)       0:00:13.695 ******
2025-09-29 09:17:10.056758 | controller | changed: [compute-1 -> localhost]
2025-09-29 09:17:10.100008 | controller |
2025-09-29 09:17:10.100045 | controller | TASK [osp.edpm.edpm_nova : Gather user fact] ***********************************
2025-09-29 09:17:10.100056 | controller | Monday 29 September 2025  09:17:10 +0000 (0:00:00.196)       0:00:13.891 ******
2025-09-29 09:17:10.100068 | controller | skipping: [compute-1]
2025-09-29 09:17:10.100097 | controller |
2025-09-29 09:17:10.100107 | controller | TASK [osp.edpm.edpm_nova : Create container config dirs] ***********************
2025-09-29 09:17:10.100512 | controller | Monday 29 September 2025  09:17:10 +0000 (0:00:00.043)       0:00:13.935 ******
2025-09-29 09:17:10.704013 | controller | changed: [compute-1] => (item={'path': '/var/lib/config-data/nova', 'mode': '0755'})
2025-09-29 09:17:10.704069 | controller | changed: [compute-1] => (item={'path': '/var/lib/openstack/config/containers', 'mode': '0755'})
2025-09-29 09:17:10.704085 | controller | changed: [compute-1] => (item={'path': '/var/lib/config-data/nova_nvme_cleaner', 'mode': '0755'})
2025-09-29 09:17:10.704100 | controller |
2025-09-29 09:17:10.704127 | controller | TASK [osp.edpm.edpm_nova : Create persistent directories] **********************
2025-09-29 09:17:10.704235 | controller | Monday 29 September 2025  09:17:10 +0000 (0:00:00.603)       0:00:14.539 ******
2025-09-29 09:17:12.476103 | controller | changed: [compute-1] => (item={'path': '/var/lib/nova', 'mode': '0755'})
2025-09-29 09:17:12.476169 | controller | changed: [compute-1] => (item={'path': '/var/lib/_nova_secontext', 'mode': '0755'})
2025-09-29 09:17:12.476180 | controller | changed: [compute-1] => (item={'path': '/var/lib/nova/instances', 'mode': '0755'})
2025-09-29 09:17:12.476188 | controller | changed: [compute-1] => (item={'path': '/etc/ceph', 'mode': '0750', 'owner': 'root', 'group': 'root'})
2025-09-29 09:17:12.476196 | controller | changed: [compute-1] => (item={'path': '/etc/multipath'})
2025-09-29 09:17:12.476203 | controller | changed: [compute-1] => (item={'path': '/etc/iscsi'})
2025-09-29 09:17:12.476210 | controller | changed: [compute-1] => (item={'path': '/var/lib/iscsi'})
2025-09-29 09:17:12.476217 | controller | changed: [compute-1] => (item={'path': '/etc/nvme'})
2025-09-29 09:17:12.476225 | controller | changed: [compute-1] => (item={'path': '/run/openvswitch'})
2025-09-29 09:17:12.476257 | controller |
2025-09-29 09:17:12.476506 | 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 09:17:12.683060 | controller | Monday 29 September 2025  09:17:12 +0000 (0:00:01.771)       0:00:16.310 ******
2025-09-29 09:17:12.683111 | controller | ok: [compute-1 -> localhost]
2025-09-29 09:17:14.072057 | controller |
2025-09-29 09:17:14.072101 | controller | TASK [osp.edpm.edpm_nova : Flatten configmaps] *********************************
2025-09-29 09:17:14.072112 | controller | Monday 29 September 2025  09:17:12 +0000 (0:00:00.207)       0:00:16.517 ******
2025-09-29 09:17:14.072127 | 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': 75497787, 'dev': 64513, 'nlink': 1, 'atime': 1759136878.067977, 'mtime': 1759136837.522136, 'ctime': 1759136878.6549768, '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 09:17:14.072163 | 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': 79692160, 'dev': 64513, 'nlink': 1, 'atime': 1759136878.067977, 'mtime': 1759136837.522136, 'ctime': 1759136878.6549768, '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 09:17:14.072172 | 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': 79692161, 'dev': 64513, 'nlink': 1, 'atime': 1759136878.0689769, 'mtime': 1759136837.522136, 'ctime': 1759136878.6549768, '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 09:17:14.072180 | 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': 83886583, 'dev': 64513, 'nlink': 1, 'atime': 1759136878.0689769, 'mtime': 1759136837.522136, 'ctime': 1759136878.6549768, '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 09:17:14.072194 | controller |
2025-09-29 09:17:14.072204 | controller | TASK [osp.edpm.edpm_users : Validating arguments against arg spec 'main' - The main entry point for the edpm_users role.] ***
2025-09-29 09:17:14.072235 | controller | Monday 29 September 2025  09:17:14 +0000 (0:00:01.388)       0:00:17.906 ******
2025-09-29 09:17:14.112058 | controller | ok: [compute-1]
2025-09-29 09:17:14.156039 | controller |
2025-09-29 09:17:14.156088 | controller | TASK [osp.edpm.edpm_users : Gather user fact] **********************************
2025-09-29 09:17:14.156098 | controller | Monday 29 September 2025  09:17:14 +0000 (0:00:00.040)       0:00:17.947 ******
2025-09-29 09:17:14.156112 | controller | skipping: [compute-1]
2025-09-29 09:17:14.156148 | controller |
2025-09-29 09:17:14.156158 | controller | TASK [osp.edpm.edpm_users : Create host users and groups] **********************
2025-09-29 09:17:14.156317 | controller | Monday 29 September 2025  09:17:14 +0000 (0:00:00.044)       0:00:17.991 ******
2025-09-29 09:17:14.249098 | 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 09:17:14.249152 | controller |
2025-09-29 09:17:14.249162 | controller | TASK [osp.edpm.edpm_users : Detect when user exists and cannot be created] *****
2025-09-29 09:17:14.249173 | controller | Monday 29 September 2025  09:17:14 +0000 (0:00:00.092)       0:00:18.083 ******
2025-09-29 09:17:14.564021 | controller | fatal: [compute-1]: FAILED! => changed=false
2025-09-29 09:17:14.633274 | controller |   msg: One or more supplied key could not be found in the database.
2025-09-29 09:17:14.633311 | controller | ...ignoring
2025-09-29 09:17:14.977873 | controller |
2025-09-29 09:17:14.977944 | controller | TASK [osp.edpm.edpm_users : Ensure group is present on the host [ nova ]] ******
2025-09-29 09:17:14.977952 | controller | Monday 29 September 2025  09:17:14 +0000 (0:00:00.384)       0:00:18.468 ******
2025-09-29 09:17:14.977964 | controller | changed: [compute-1]
2025-09-29 09:17:15.441917 | controller |
2025-09-29 09:17:15.441962 | controller | TASK [osp.edpm.edpm_users : Ensure user is present on the host [ nova ]] *******
2025-09-29 09:17:15.441970 | controller | Monday 29 September 2025  09:17:14 +0000 (0:00:00.343)       0:00:18.812 ******
2025-09-29 09:17:15.441988 | controller | changed: [compute-1]
2025-09-29 09:17:15.509962 | controller |
2025-09-29 09:17:15.510002 | controller | TASK [osp.edpm.edpm_users : Create host directories] ***************************
2025-09-29 09:17:15.510012 | controller | Monday 29 September 2025  09:17:15 +0000 (0:00:00.464)       0:00:19.276 ******
2025-09-29 09:17:15.510024 | controller | skipping: [compute-1]
2025-09-29 09:17:15.929381 | controller |
2025-09-29 09:17:15.929427 | controller | TASK [osp.edpm.edpm_nova : Sync nova config files] *****************************
2025-09-29 09:17:15.929435 | controller | Monday 29 September 2025  09:17:15 +0000 (0:00:00.068)       0:00:19.344 ******
2025-09-29 09:17:15.929447 | controller | changed: [compute-1]
2025-09-29 09:17:17.541009 | controller |
2025-09-29 09:17:17.541048 | controller | TASK [osp.edpm.edpm_nova : Render nova config files] ***************************
2025-09-29 09:17:17.541058 | controller | Monday 29 September 2025  09:17:15 +0000 (0:00:00.419)       0:00:19.764 ******
2025-09-29 09:17:17.541070 | controller | changed: [compute-1] => (item={'src': 'config.json.j2', 'dest': 'config.json'})
2025-09-29 09:17:17.541097 | controller | changed: [compute-1] => (item={'src': 'nova-blank.conf', 'dest': 'nova-blank.conf'})
2025-09-29 09:17:17.541107 | controller | changed: [compute-1] => (item={'src': 'ssh-config', 'dest': 'ssh-config'})
2025-09-29 09:17:17.541568 | controller | changed: [compute-1] => (item={'src': '02-nova-host-specific.conf.j2', 'dest': '02-nova-host-specific.conf'})
2025-09-29 09:17:17.888741 | controller | changed: [compute-1] => (item={'src': 'nova_statedir_ownership.py', 'dest': 'nova_statedir_ownership.py'})
2025-09-29 09:17:17.888779 | controller |
2025-09-29 09:17:17.888786 | controller | TASK [osp.edpm.edpm_nova : Render nvme cleaner config files] *******************
2025-09-29 09:17:17.888792 | controller | Monday 29 September 2025  09:17:17 +0000 (0:00:01.612)       0:00:21.376 ******
2025-09-29 09:17:17.888802 | controller | changed: [compute-1]
2025-09-29 09:17:18.110435 | controller |
2025-09-29 09:17:18.110477 | controller | TASK [osp.edpm.edpm_nova : Create .ssh directory for the nova user on the host] ***
2025-09-29 09:17:18.110485 | controller | Monday 29 September 2025  09:17:17 +0000 (0:00:00.347)       0:00:21.723 ******
2025-09-29 09:17:18.110496 | controller | changed: [compute-1]
2025-09-29 09:17:18.337381 | controller |
2025-09-29 09:17:18.337420 | controller | TASK [osp.edpm.edpm_nova : Copy the migration ssh public key as authorized_keys to the nova user] ***
2025-09-29 09:17:18.337429 | controller | Monday 29 September 2025  09:17:18 +0000 (0:00:00.221)       0:00:21.945 ******
2025-09-29 09:17:18.337440 | controller | changed: [compute-1]
2025-09-29 09:17:18.550696 | controller |
2025-09-29 09:17:18.550736 | controller | TASK [osp.edpm.edpm_nova : Check if compute_id exists in state_path] ***********
2025-09-29 09:17:18.550743 | controller | Monday 29 September 2025  09:17:18 +0000 (0:00:00.226)       0:00:22.172 ******
2025-09-29 09:17:18.550754 | controller | ok: [compute-1]
2025-09-29 09:17:18.960010 | controller |
2025-09-29 09:17:18.960048 | controller | TASK [osp.edpm.edpm_nova : Pre-generate compute_id if not already exists] ******
2025-09-29 09:17:18.960064 | controller | Monday 29 September 2025  09:17:18 +0000 (0:00:00.213)       0:00:22.385 ******
2025-09-29 09:17:18.960076 | controller | changed: [compute-1]
2025-09-29 09:17:18.998831 | controller |
2025-09-29 09:17:18.998854 | controller | TASK [osp.edpm.edpm_nova : Make compute_id ready-only] *************************
2025-09-29 09:17:18.998861 | controller | Monday 29 September 2025  09:17:18 +0000 (0:00:00.409)       0:00:22.794 ******
2025-09-29 09:17:18.998871 | controller | skipping: [compute-1]
2025-09-29 09:17:19.095254 | controller |
2025-09-29 09:17:19.095290 | controller | TASK [osp.edpm.edpm_nova : Install nova] ***************************************
2025-09-29 09:17:19.095298 | controller | Monday 29 September 2025  09:17:18 +0000 (0:00:00.039)       0:00:22.834 ******
2025-09-29 09:17:19.095309 | 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 09:17:19.300982 | controller |
2025-09-29 09:17:19.301020 | controller | TASK [osp.edpm.edpm_nova : Check if ca bundle exists] **************************
2025-09-29 09:17:19.301027 | controller | Monday 29 September 2025  09:17:19 +0000 (0:00:00.096)       0:00:22.930 ******
2025-09-29 09:17:19.301038 | controller | ok: [compute-1]
2025-09-29 09:17:19.301061 | controller |
2025-09-29 09:17:19.301068 | controller | TASK [osp.edpm.edpm_nova : Render nova container] ******************************
2025-09-29 09:17:19.301221 | controller | Monday 29 September 2025  09:17:19 +0000 (0:00:00.205)       0:00:23.136 ******
2025-09-29 09:17:19.661985 | controller | changed: [compute-1]
2025-09-29 09:17:19.662321 | controller |
2025-09-29 09:17:20.005059 | controller | TASK [osp.edpm.edpm_nova : Render nova init container] *************************
2025-09-29 09:17:20.005104 | controller | Monday 29 September 2025  09:17:19 +0000 (0:00:00.360)       0:00:23.496 ******
2025-09-29 09:17:20.005120 | controller | changed: [compute-1]
2025-09-29 09:17:20.005150 | controller |
2025-09-29 09:17:20.005161 | controller | TASK [Deploy nova init container] **********************************************
2025-09-29 09:17:20.099856 | controller | Monday 29 September 2025  09:17:20 +0000 (0:00:00.343)       0:00:23.840 ******
2025-09-29 09:17:20.099890 | controller |
2025-09-29 09:17:20.157743 | 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 09:17:20.157773 | controller | Monday 29 September 2025  09:17:20 +0000 (0:00:00.095)       0:00:23.935 ******
2025-09-29 09:17:20.157785 | controller | ok: [compute-1]
2025-09-29 09:17:20.488479 | controller |
2025-09-29 09:17:20.488516 | controller | TASK [osp.edpm.edpm_container_manage : Find all matching configs configs for in /var/lib/openstack/config/containers] ***
2025-09-29 09:17:20.488529 | controller | Monday 29 September 2025  09:17:20 +0000 (0:00:00.057)       0:00:23.992 ******
2025-09-29 09:17:20.488544 | controller | ok: [compute-1]
2025-09-29 09:17:20.527630 | controller |
2025-09-29 09:17:20.527656 | controller | TASK [osp.edpm.edpm_container_manage : Finalise hashes for all containers] *****
2025-09-29 09:17:20.527664 | controller | Monday 29 September 2025  09:17:20 +0000 (0:00:00.330)       0:00:24.323 ******
2025-09-29 09:17:20.527673 | controller | ok: [compute-1]
2025-09-29 09:17:20.837633 | controller |
2025-09-29 09:17:20.837667 | controller | TASK [osp.edpm.edpm_container_manage : Update container configs with new config hashes] ***
2025-09-29 09:17:20.837675 | controller | Monday 29 September 2025  09:17:20 +0000 (0:00:00.039)       0:00:24.362 ******
2025-09-29 09:17:20.837686 | controller | [WARNING]: /var/lib/edpm-config/container-startup-config does not exists
2025-09-29 09:17:20.893100 | controller | ok: [compute-1]
2025-09-29 09:17:20.893354 | controller |
2025-09-29 09:17:20.893372 | controller | TASK [osp.edpm.edpm_container_manage : Delete orphan containers from /var/lib/openstack/config/containers] ***
2025-09-29 09:17:20.945024 | controller | Monday 29 September 2025  09:17:20 +0000 (0:00:00.365)       0:00:24.728 ******
2025-09-29 09:17:20.945079 | controller | skipping: [compute-1]
2025-09-29 09:17:20.945123 | controller |
2025-09-29 09:17:20.945138 | controller | TASK [osp.edpm.edpm_container_manage : Create containers from /var/lib/openstack/config/containers] ***
2025-09-29 09:17:20.945223 | controller | Monday 29 September 2025  09:17:20 +0000 (0:00:00.052)       0:00:24.780 ******
2025-09-29 09:17:21.023539 | 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 09:17:35.771041 | controller |
2025-09-29 09:17:35.771077 | controller | TASK [osp.edpm.edpm_container_manage : Create containers managed by Podman for /var/lib/openstack/config/containers] ***
2025-09-29 09:17:35.771086 | controller | Monday 29 September 2025  09:17:21 +0000 (0:00:00.078)       0:00:24.858 ******
2025-09-29 09:17:35.771097 | controller | changed: [compute-1]
2025-09-29 09:17:36.022427 | controller |
2025-09-29 09:17:36.022473 | controller | TASK [osp.edpm.edpm_container_manage : Check if /etc/sysconfig/podman_drop_in exists] ***
2025-09-29 09:17:36.022487 | controller | Monday 29 September 2025  09:17:35 +0000 (0:00:14.747)       0:00:39.606 ******
2025-09-29 09:17:36.022505 | controller | ok: [compute-1]
2025-09-29 09:17:36.076041 | controller |
2025-09-29 09:17:36.076075 | controller | TASK [osp.edpm.edpm_container_manage : Set podman_drop_in fact] ****************
2025-09-29 09:17:36.076088 | controller | Monday 29 September 2025  09:17:36 +0000 (0:00:00.250)       0:00:39.856 ******
2025-09-29 09:17:36.076105 | controller | skipping: [compute-1]
2025-09-29 09:17:36.155320 | controller |
2025-09-29 09:17:36.155416 | 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 09:17:36.155425 | controller | Monday 29 September 2025  09:17:36 +0000 (0:00:00.054)       0:00:39.911 ******
2025-09-29 09:17:36.155437 | controller | skipping: [compute-1]
2025-09-29 09:17:36.232787 | controller |
2025-09-29 09:17:36.232822 | controller | TASK [Deploy nova container] ***************************************************
2025-09-29 09:17:36.232835 | controller | Monday 29 September 2025  09:17:36 +0000 (0:00:00.079)       0:00:39.990 ******
2025-09-29 09:17:36.232851 | controller |
2025-09-29 09:17:36.285763 | 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 09:17:36.285788 | controller | Monday 29 September 2025  09:17:36 +0000 (0:00:00.077)       0:00:40.067 ******
2025-09-29 09:17:36.285802 | controller | ok: [compute-1]
2025-09-29 09:17:36.515009 | controller |
2025-09-29 09:17:36.515044 | controller | TASK [osp.edpm.edpm_container_manage : Find all matching configs configs for in /var/lib/openstack/config/containers] ***
2025-09-29 09:17:36.515062 | controller | Monday 29 September 2025  09:17:36 +0000 (0:00:00.053)       0:00:40.121 ******
2025-09-29 09:17:36.515075 | controller | ok: [compute-1]
2025-09-29 09:17:36.515096 | controller |
2025-09-29 09:17:36.515105 | controller | TASK [osp.edpm.edpm_container_manage : Finalise hashes for all containers] *****
2025-09-29 09:17:36.515222 | controller | Monday 29 September 2025  09:17:36 +0000 (0:00:00.229)       0:00:40.350 ******
2025-09-29 09:17:36.558713 | controller | ok: [compute-1]
2025-09-29 09:17:36.811414 | controller |
2025-09-29 09:17:36.811442 | controller | TASK [osp.edpm.edpm_container_manage : Update container configs with new config hashes] ***
2025-09-29 09:17:36.811450 | controller | Monday 29 September 2025  09:17:36 +0000 (0:00:00.043)       0:00:40.394 ******
2025-09-29 09:17:36.811460 | controller | ok: [compute-1]
2025-09-29 09:17:36.866743 | controller |
2025-09-29 09:17:36.866766 | controller | TASK [osp.edpm.edpm_container_manage : Delete orphan containers from /var/lib/openstack/config/containers] ***
2025-09-29 09:17:36.866773 | controller | Monday 29 September 2025  09:17:36 +0000 (0:00:00.252)       0:00:40.646 ******
2025-09-29 09:17:36.866783 | controller | skipping: [compute-1]
2025-09-29 09:17:36.976513 | controller |
2025-09-29 09:17:36.976540 | controller | TASK [osp.edpm.edpm_container_manage : Create containers from /var/lib/openstack/config/containers] ***
2025-09-29 09:17:36.976548 | controller | Monday 29 September 2025  09:17:36 +0000 (0:00:00.055)       0:00:40.702 ******
2025-09-29 09:17:36.976557 | 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 09:17:37.355470 | controller |
2025-09-29 09:17:37.355501 | controller | TASK [osp.edpm.edpm_container_manage : Create containers managed by Podman for /var/lib/openstack/config/containers] ***
2025-09-29 09:17:37.355509 | controller | Monday 29 September 2025  09:17:36 +0000 (0:00:00.109)       0:00:40.811 ******
2025-09-29 09:17:37.355520 | controller | [WARNING]: ERROR: Container nova_compute exited with code 125 when createed
2025-09-29 09:17:37.355599 | controller | stderr: time="2025-09-29T09:17:37Z" level=info msg="podman filtering at log
2025-09-29 09:17:37.355608 | controller | level info" time="2025-09-29T09:17:37Z" level=info msg="Setting parallel job
2025-09-29 09:17:37.355613 | controller | count to 13" time="2025-09-29T09:17:37Z" level=info msg="Using sqlite as
2025-09-29 09:17:37.355619 | controller | database backend" time="2025-09-29T09:17:37Z" level=info msg="Not using native
2025-09-29 09:17:37.355624 | controller | diff for overlay, this may cause degraded performance for building images:
2025-09-29 09:17:37.355629 | controller | kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" time="2025-09-29T09:17:37Z"
2025-09-29 09:17:37.355634 | controller | level=info msg="Sysctl net.ipv4.ping_group_range=0 0 ignored in
2025-09-29 09:17:37.355642 | controller | containers.conf, since Network Namespace set to host" Error: statfs
2025-09-29 09:17:37.355647 | controller | /var/lib/config-data/ceph: no such file or directory
2025-09-29 09:17:37.355655 | controller | time="2025-09-29T09:17:37Z" level=info msg="Received shutdown.Stop(),
2025-09-29 09:17:37.355676 | controller | terminating!" PID=60668
2025-09-29 09:17:37.355958 | controller | fatal: [compute-1]: FAILED! => changed=false
2025-09-29 09:17:37.357632 | controller |   msg: 'Failed containers: nova_compute'
2025-09-29 09:17:37.357678 | controller |
2025-09-29 09:17:37.357708 | controller | PLAY RECAP *********************************************************************
2025-09-29 09:17:37.357717 | controller | compute-1                  : ok=57   changed=17   unreachable=0    failed=1    skipped=14   rescued=0    ignored=1
2025-09-29 09:17:37.357842 | controller |
2025-09-29 09:17:37.357883 | controller | Monday 29 September 2025  09:17:37 +0000 (0:00:00.380)       0:00:41.192 ******
2025-09-29 09:17:37.359770 | controller | ===============================================================================
2025-09-29 09:17:37.415776 | controller | osp.edpm.edpm_container_manage : Create containers managed by Podman for /var/lib/openstack/config/containers -- 14.75s
2025-09-29 09:17:37.415797 | controller | osp.edpm.edpm_tripleo_cleanup : Stop and disable tripleo services ------- 2.28s
2025-09-29 09:17:37.415804 | controller | osp.edpm.edpm_tripleo_cleanup : Gather service facts -------------------- 2.18s
2025-09-29 09:17:37.415810 | controller | osp.edpm.edpm_nova : Create persistent directories ---------------------- 1.77s
2025-09-29 09:17:37.415816 | controller | osp.edpm.edpm_nova : Render nova config files --------------------------- 1.61s
2025-09-29 09:17:37.415821 | controller | osp.edpm.edpm_tripleo_cleanup : Reset state of failed systemd units ----- 1.57s
2025-09-29 09:17:37.415826 | controller | osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /usr/lib/systemd/system --- 1.46s
2025-09-29 09:17:37.415836 | controller | osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /etc/systemd/system --- 1.44s
2025-09-29 09:17:37.415844 | controller | osp.edpm.edpm_nova : Flatten configmaps --------------------------------- 1.39s
2025-09-29 09:17:37.415850 | controller | Gathering Facts --------------------------------------------------------- 0.84s
2025-09-29 09:17:37.415855 | controller | osp.edpm.edpm_tripleo_cleanup : Reload units to check for new changes --- 0.69s
2025-09-29 09:17:37.415860 | controller | osp.edpm.edpm_nova : Create container config dirs ----------------------- 0.60s
2025-09-29 09:17:37.415865 | controller | osp.edpm.edpm_nova : Get source directory details ----------------------- 0.52s
2025-09-29 09:17:37.415870 | controller | osp.edpm.edpm_tripleo_cleanup : Reload units ---------------------------- 0.47s
2025-09-29 09:17:37.415875 | controller | osp.edpm.edpm_users : Ensure user is present on the host [ nova ] ------- 0.46s
2025-09-29 09:17:37.415879 | controller | osp.edpm.edpm_nova : Sync nova config files ----------------------------- 0.42s
2025-09-29 09:17:37.415884 | controller | osp.edpm.edpm_nova : Pre-generate compute_id if not already exists ------ 0.41s
2025-09-29 09:17:37.415889 | controller | osp.edpm.edpm_users : Detect when user exists and cannot be created ----- 0.38s
2025-09-29 09:17:37.415894 | controller | osp.edpm.edpm_container_manage : Create containers managed by Podman for /var/lib/openstack/config/containers --- 0.38s
2025-09-29 09:17:37.415923 | controller | osp.edpm.edpm_container_manage : Update container configs with new config hashes --- 0.37s
2025-09-29 09:17:37.415933 | 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 09:17:37.416357 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-09-29 09:17:37.425133 | controller | INFO     Running default > cleanup
2025-09-29 09:17:37.425576 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-09-29 09:17:37.433526 | controller | INFO     Running default > destroy
2025-09-29 09:17:37.433847 | controller | WARNING  Skipping, instances are delegated.
2025-09-29 09:17:37.434190 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-09-29 09:17:37.435843 | controller | INFO     Writing /tmp/report.html report.
2025-09-29 09:17:42.640129 | controller | ERROR
2025-09-29 09:17:42.640363 | controller | {
2025-09-29 09:17:42.640418 | controller |   "delta": "0:07:37.597614",
2025-09-29 09:17:42.640458 | controller |   "end": "2025-09-29 09:17:37.482619",
2025-09-29 09:17:42.640490 | controller |   "msg": "non-zero return code",
2025-09-29 09:17:42.640521 | controller |   "rc": 1,
2025-09-29 09:17:42.640558 | controller |   "start": "2025-09-29 09:09:59.885005"
2025-09-29 09:17:42.640589 | controller | }
failure
2025-09-29 09:17:42.691601 | 
2025-09-29 09:17:42.691658 | PLAY RECAP
2025-09-29 09:17:42.691702 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-09-29 09:17:42.691728 | 
2025-09-29 09:17:42.746371 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-29 09:17:42.747245 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-29 09:17:43.234361 | 
2025-09-29 09:17:43.234459 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-09-29 09:17:43.252572 | 
2025-09-29 09:17:43.252641 | TASK [Filter out host if needed]
2025-09-29 09:17:43.260594 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-09-29 09:17:43.264724 | 
2025-09-29 09:17:43.264791 | TASK [Ensure file is present]
2025-09-29 09:17:43.546001 | controller | ok
2025-09-29 09:17:43.552461 | 
2025-09-29 09:17:43.552533 | TASK [Manage molecule report file]
2025-09-29 09:17:48.578735 | controller | changed
2025-09-29 09:17:48.583872 | 
2025-09-29 09:17:48.583954 | TASK [Check if we get ci-framework-data basedir]
2025-09-29 09:17:48.774121 | controller | ok
2025-09-29 09:17:48.779056 | 
2025-09-29 09:17:48.779117 | TASK [Create ci-framework-data log directory for zuul]
2025-09-29 09:17:49.070681 | controller | changed
2025-09-29 09:17:49.075783 | 
2025-09-29 09:17:49.075847 | TASK [Copy ci-framework interesting files]
2025-09-29 09:17:54.099368 | controller | changed
2025-09-29 09:17:54.104682 | 
2025-09-29 09:17:54.104762 | TASK [Get SELinux listing]
2025-09-29 09:17:59.129476 | controller | changed
2025-09-29 09:17:59.135013 | 
2025-09-29 09:17:59.135080 | TASK [Generate log index]
2025-09-29 09:17:59.732039 | controller | changed
2025-09-29 09:17:59.736634 | 
2025-09-29 09:17:59.736699 | TASK [Get some env related data]
2025-09-29 09:18:04.761866 | controller | changed
2025-09-29 09:18:04.767142 | 
2025-09-29 09:18:04.767205 | TASK [Generate list of logs to collect in home directory]
2025-09-29 09:18:05.039457 | controller | ok: All paths examined
2025-09-29 09:18:05.044206 | 
2025-09-29 09:18:05.044269 | LOOP [Copy logs from home directory]
2025-09-29 09:18:05.357476 | controller | changed:
2025-09-29 09:18:05.357585 | controller | {
2025-09-29 09:18:05.357613 | controller |   "atime": 1759136982.044029,
2025-09-29 09:18:05.357634 | controller |   "ctime": 1759136998.0300908,
2025-09-29 09:18:05.357658 | controller |   "dev": 64513,
2025-09-29 09:18:05.357677 | controller |   "gid": 1000,
2025-09-29 09:18:05.357693 | controller |   "gr_name": "zuul",
2025-09-29 09:18:05.357708 | controller |   "inode": 4329951,
2025-09-29 09:18:05.357723 | controller |   "isblk": false,
2025-09-29 09:18:05.357738 | controller |   "ischr": false,
2025-09-29 09:18:05.357753 | controller |   "isdir": false,
2025-09-29 09:18:05.357768 | controller |   "isfifo": false,
2025-09-29 09:18:05.357782 | controller |   "isgid": false,
2025-09-29 09:18:05.357796 | controller |   "islnk": false,
2025-09-29 09:18:05.357810 | controller |   "isreg": true,
2025-09-29 09:18:05.357823 | controller |   "issock": false,
2025-09-29 09:18:05.357837 | controller |   "isuid": false,
2025-09-29 09:18:05.357850 | controller |   "mode": "0644",
2025-09-29 09:18:05.357863 | controller |   "mtime": 1759136998.0300908,
2025-09-29 09:18:05.357877 | controller |   "nlink": 1,
2025-09-29 09:18:05.357891 | controller |   "path": "/home/zuul/ansible.log",
2025-09-29 09:18:05.357904 | controller |   "pw_name": "zuul",
2025-09-29 09:18:05.357955 | controller |   "rgrp": true,
2025-09-29 09:18:05.357975 | controller |   "roth": true,
2025-09-29 09:18:05.357992 | controller |   "rusr": true,
2025-09-29 09:18:05.358008 | controller |   "size": 6749,
2025-09-29 09:18:05.358022 | controller |   "uid": 1000,
2025-09-29 09:18:05.358036 | controller |   "wgrp": false,
2025-09-29 09:18:05.358050 | controller |   "woth": false,
2025-09-29 09:18:05.358063 | controller |   "wusr": true,
2025-09-29 09:18:05.358076 | controller |   "xgrp": false,
2025-09-29 09:18:05.358092 | controller |   "xoth": false,
2025-09-29 09:18:05.358106 | controller |   "xusr": false
2025-09-29 09:18:05.358119 | controller | }
2025-09-29 09:18:05.371788 | 
2025-09-29 09:18:05.371893 | TASK [Copy crio stats log file]
2025-09-29 09:18:05.385241 | controller | skipping: Conditional result was False
2025-09-29 09:18:05.390700 | 
2025-09-29 09:18:05.390769 | TASK [Get SELinux related data]
2025-09-29 09:18:10.416108 | controller | ERROR
2025-09-29 09:18:10.416369 | controller | {
2025-09-29 09:18:10.416404 | controller |   "delta": "0:00:00.008315",
2025-09-29 09:18:10.416427 | controller |   "end": "2025-09-29 09:18:05.589325",
2025-09-29 09:18:10.416447 | controller |   "msg": "non-zero return code",
2025-09-29 09:18:10.416461 | controller |   "rc": 1,
2025-09-29 09:18:10.416474 | controller |   "start": "2025-09-29 09:18:05.581010"
2025-09-29 09:18:10.416487 | controller | }
2025-09-29 09:18:10.416505 | controller | ERROR: Ignoring Errors
2025-09-29 09:18:10.421461 | 
2025-09-29 09:18:10.421520 | TASK [Create system configuration directory]
2025-09-29 09:18:10.602138 | controller | changed
2025-09-29 09:18:10.606885 | 
2025-09-29 09:18:10.607012 | TASK [Get some of the system configurations]
2025-09-29 09:18:15.632825 | controller | changed
2025-09-29 09:18:15.637764 | 
2025-09-29 09:18:15.637824 | TASK [Copy generated documentation if available]
2025-09-29 09:18:15.651098 | controller | skipping: Conditional result was False
2025-09-29 09:18:15.655979 | 
2025-09-29 09:18:15.656038 | TASK [Copy generated AsciiDoc documentation if available]
2025-09-29 09:18:15.669118 | controller | skipping: Conditional result was False
2025-09-29 09:18:15.674322 | 
2025-09-29 09:18:15.674379 | TASK [Compress logs bigger than 2MB]
2025-09-29 09:18:20.698486 | controller | changed
2025-09-29 09:18:20.704001 | 
2025-09-29 09:18:20.704075 | TASK [Copy files from workspace on node]
2025-09-29 09:18:20.722257 | controller | ok
2025-09-29 09:18:20.743371 | 
2025-09-29 09:18:20.743449 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-29 09:18:20.756754 | controller | skipping: Conditional result was False
2025-09-29 09:18:20.761970 | 
2025-09-29 09:18:20.762037 | TASK [fetch-output : Set log path for single node]
2025-09-29 09:18:20.790171 | controller | ok
2025-09-29 09:18:20.796189 | 
2025-09-29 09:18:20.796252 | LOOP [fetch-output : Ensure local output dirs]
2025-09-29 09:18:20.980552 | controller -> localhost | ok: "/var/lib/zuul/builds/3206f99370a747a1b209bc4f426808fb/work/logs"
2025-09-29 09:18:20.980798 | controller -> localhost | changed: All items complete
2025-09-29 09:18:20.980826 | 
2025-09-29 09:18:21.146473 | controller -> localhost | changed: "/var/lib/zuul/builds/3206f99370a747a1b209bc4f426808fb/work/artifacts"
2025-09-29 09:18:21.312716 | controller -> localhost | changed: "/var/lib/zuul/builds/3206f99370a747a1b209bc4f426808fb/work/docs"
2025-09-29 09:18:21.321837 | 
2025-09-29 09:18:21.321964 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-29 09:18:21.833188 | controller | changed:
2025-09-29 09:18:21.833364 | controller | .d..t...... ./
2025-09-29 09:18:21.833394 | controller | >f+++++++++ README.html
2025-09-29 09:18:21.833415 | controller | >f+++++++++ ansible-execution.log
2025-09-29 09:18:21.833435 | controller | >f+++++++++ ansible.log
2025-09-29 09:18:21.833453 | controller | >f+++++++++ dmesg.log
2025-09-29 09:18:21.833469 | controller | >f+++++++++ installed-pkgs.log
2025-09-29 09:18:21.833488 | controller | >f+++++++++ python.log
2025-09-29 09:18:21.833505 | controller | >f+++++++++ registries.conf
2025-09-29 09:18:21.833521 | controller | >f+++++++++ report.html
2025-09-29 09:18:21.833540 | controller | >f+++++++++ selinux-denials.log
2025-09-29 09:18:21.833561 | controller | >f+++++++++ selinux-listing.log
2025-09-29 09:18:21.833578 | controller | cd+++++++++ ci-framework-data/
2025-09-29 09:18:21.833596 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-09-29 09:18:21.833612 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-09-29 09:18:21.833629 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-09-29 09:18:21.833644 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-09-29 09:18:21.833659 | controller | cd+++++++++ ci-framework-data/logs/
2025-09-29 09:18:21.833674 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-09-29 09:18:21.833688 | controller | cd+++++++++ registries.conf.d/
2025-09-29 09:18:21.833702 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-09-29 09:18:21.833717 | controller | cd+++++++++ system-config/
2025-09-29 09:18:21.833733 | controller | cd+++++++++ system-config/libvirt/
2025-09-29 09:18:21.833750 | controller | >f+++++++++ system-config/libvirt/auth.conf
2025-09-29 09:18:21.833765 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-09-29 09:18:21.833780 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-09-29 09:18:21.833795 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-09-29 09:18:21.833811 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-09-29 09:18:21.833826 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-09-29 09:18:21.833841 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-09-29 09:18:21.833856 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-09-29 09:18:21.833871 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-09-29 09:18:21.833885 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-09-29 09:18:21.833899 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-09-29 09:18:21.833942 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-09-29 09:18:21.833962 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-09-29 09:18:21.833979 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-09-29 09:18:21.833994 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-09-29 09:18:21.834008 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-09-29 09:18:21.834023 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-09-29 09:18:22.185415 | controller | changed: .d..t...... ./
2025-09-29 09:18:22.537614 | controller | changed: .d..t...... ./
2025-09-29 09:18:22.552856 | 
2025-09-29 09:18:22.552979 | TASK [Return artifact to Zuul]
2025-09-29 09:18:22.581253 | controller | ok
2025-09-29 09:18:22.598611 | 
2025-09-29 09:18:22.598665 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-09-29 09:18:22.598752 | 
2025-09-29 09:18:22.598776 | PLAY RECAP
2025-09-29 09:18:22.598808 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-09-29 09:18:22.598827 | 
2025-09-29 09:18:22.666773 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-29 09:18:22.667496 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-29 09:18:23.145254 | 
2025-09-29 09:18:23.145384 | PLAY [all]
2025-09-29 09:18:23.162269 | 
2025-09-29 09:18:23.162341 | TASK [include_role : fetch-output]
2025-09-29 09:18:23.190535 | controller | ok
2025-09-29 09:18:23.205946 | 
2025-09-29 09:18:23.206021 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-29 09:18:23.249962 | controller | skipping: Conditional result was False
2025-09-29 09:18:23.255597 | 
2025-09-29 09:18:23.255668 | TASK [fetch-output : Set log path for single node]
2025-09-29 09:18:23.283689 | controller | ok
2025-09-29 09:18:23.288534 | 
2025-09-29 09:18:23.288600 | LOOP [fetch-output : Ensure local output dirs]
2025-09-29 09:18:23.597009 | controller -> localhost | ok: "/var/lib/zuul/builds/3206f99370a747a1b209bc4f426808fb/work/logs"
2025-09-29 09:18:23.771040 | controller -> localhost | ok: "/var/lib/zuul/builds/3206f99370a747a1b209bc4f426808fb/work/artifacts"
2025-09-29 09:18:23.939170 | controller -> localhost | ok: "/var/lib/zuul/builds/3206f99370a747a1b209bc4f426808fb/work/docs"
2025-09-29 09:18:23.946600 | 
2025-09-29 09:18:23.946695 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-29 09:18:24.427516 | controller | ok
2025-09-29 09:18:24.427809 | controller | ok: All items complete
2025-09-29 09:18:24.427847 | 
2025-09-29 09:18:24.784487 | controller | ok
2025-09-29 09:18:25.141115 | controller | ok
2025-09-29 09:18:25.160260 | 
2025-09-29 09:18:25.160366 | TASK [include_role : fetch-output-openshift]
2025-09-29 09:18:25.173757 | controller | skipping: Conditional result was False
2025-09-29 09:18:25.179486 | 
2025-09-29 09:18:25.179553 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-09-29 09:18:25.497890 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007292
2025-09-29 09:18:25.681148 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006358
2025-09-29 09:18:25.713431 | 
2025-09-29 09:18:25.713519 | PLAY [all]
2025-09-29 09:18:25.727156 | 
2025-09-29 09:18:25.727228 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-09-29 09:18:26.120529 | controller | changed
2025-09-29 09:18:26.142641 | 
2025-09-29 09:18:26.142700 | PLAY RECAP
2025-09-29 09:18:26.142745 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-29 09:18:26.142767 | 
2025-09-29 09:18:26.208851 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-29 09:18:26.209584 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-09-29 09:18:26.745872 | 
2025-09-29 09:18:26.746033 | PLAY [localhost]
2025-09-29 09:18:26.761609 | 
2025-09-29 09:18:26.761681 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-09-29 09:18:27.041728 | localhost | changed
2025-09-29 09:18:27.045970 | 
2025-09-29 09:18:27.046053 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-09-29 09:18:27.063313 | localhost | ok
2025-09-29 09:18:27.070731 | 
2025-09-29 09:18:27.070797 | TASK [add-fileserver : Create SSH private key tempfile]
2025-09-29 09:18:27.351078 | localhost | changed
2025-09-29 09:18:27.356508 | 
2025-09-29 09:18:27.356575 | TASK [add-fileserver : Create SSH private key from secret]
2025-09-29 09:18:27.858760 | localhost | changed
2025-09-29 09:18:27.863497 | 
2025-09-29 09:18:27.863564 | TASK [add-fileserver : Add fileserver ssh key]
2025-09-29 09:18:28.174862 | localhost | Identity added: /var/lib/zuul/builds/3206f99370a747a1b209bc4f426808fb/work/tmp/ansible.1v6evn_j (/var/lib/zuul/builds/3206f99370a747a1b209bc4f426808fb/work/tmp/ansible.1v6evn_j)
2025-09-29 09:18:28.175028 | localhost | ok: Runtime: 0:00:00.006280
2025-09-29 09:18:28.178961 | 
2025-09-29 09:18:28.179022 | TASK [add-fileserver : Remove SSH private key from disk]
2025-09-29 09:18:28.378245 | localhost | ok: Runtime: 0:00:00.004128
2025-09-29 09:18:28.382326 | 
2025-09-29 09:18:28.382387 | TASK [add-fileserver : Add fileserver to inventory]
2025-09-29 09:18:28.429901 | localhost | changed
2025-09-29 09:18:28.434739 | 
2025-09-29 09:18:28.434804 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-09-29 09:18:28.735209 | localhost | changed
2025-09-29 09:18:28.754345 | 
2025-09-29 09:18:28.754404 | PLAY [localhost]
2025-09-29 09:18:28.765541 | 
2025-09-29 09:18:28.765603 | TASK [Generate bulk log download script]
2025-09-29 09:18:28.783581 | localhost | ok
2025-09-29 09:18:28.794695 | 
2025-09-29 09:18:28.794762 | TASK [local-log-download : Check API endpoint is defined]
2025-09-29 09:18:28.821974 | localhost | ok: All assertions passed
2025-09-29 09:18:28.826168 | 
2025-09-29 09:18:28.826225 | TASK [local-log-download : Create download script]
2025-09-29 09:18:29.154432 | localhost -> localhost | changed
2025-09-29 09:18:29.162298 | 
2025-09-29 09:18:29.162356 | TASK [Register quick-download link]
2025-09-29 09:18:29.178941 | localhost | ok
2025-09-29 09:18:29.210563 | 
2025-09-29 09:18:29.210631 | PLAY [logserver.rdoproject.org]
2025-09-29 09:18:29.219543 | 
2025-09-29 09:18:29.219597 | TASK [Set zuul-log-path fact]
2025-09-29 09:18:29.235140 | logserver.rdoproject.org | ok
2025-09-29 09:18:29.243128 | 
2025-09-29 09:18:29.243188 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-29 09:18:29.259527 | logserver.rdoproject.org | ok
2025-09-29 09:18:29.264190 | 
2025-09-29 09:18:29.264247 | TASK [upload-logs : Create log directories]
2025-09-29 09:18:31.091348 | logserver.rdoproject.org | changed
2025-09-29 09:18:31.094357 | 
2025-09-29 09:18:31.094421 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-09-29 09:18:31.310322 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004321
2025-09-29 09:18:31.314766 | 
2025-09-29 09:18:31.314829 | TASK [upload-logs : Upload logs to log server]
2025-09-29 09:18:32.842205 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-09-29 09:18:32.845255 | 
2025-09-29 09:18:32.845325 | LOOP [upload-logs : Compress console log and json output]
2025-09-29 09:18:32.883942 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 09:18:32.893943 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 09:18:32.904997 | 
2025-09-29 09:18:32.905093 | LOOP [upload-logs : Upload compressed console log and json output]
2025-09-29 09:18:32.939073 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 09:18:32.939303 | 
2025-09-29 09:18:32.941869 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 09:18:32.953026 | 
2025-09-29 09:18:32.953114 | LOOP [upload-logs : Upload console log and json output]