2026-03-19 06:01:24.109331 | Job console starting...
2026-03-19 06:01:24.319860 | Updating repositories
2026-03-19 06:01:25.045864 | Preparing job workspace
2026-03-19 06:01:32.666280 | Running Ansible setup...
2026-03-19 06:01:35.437481 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-19 06:01:35.951521 | 
2026-03-19 06:01:35.951633 | PLAY [localhost]
2026-03-19 06:01:35.960186 | 
2026-03-19 06:01:35.960256 | TASK [Gathering Facts]
2026-03-19 06:01:36.755357 | localhost | ok
2026-03-19 06:01:36.768549 | 
2026-03-19 06:01:36.768645 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-19 06:01:37.086313 | localhost -> localhost | changed
2026-03-19 06:01:37.091919 | 
2026-03-19 06:01:37.092002 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-19 06:01:37.764679 | localhost -> localhost | changed
2026-03-19 06:01:37.772800 | 
2026-03-19 06:01:37.772869 | TASK [Setup log path fact]
2026-03-19 06:01:37.789774 | localhost | ok
2026-03-19 06:01:37.800666 | 
2026-03-19 06:01:37.800733 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-19 06:01:37.817932 | localhost | ok
2026-03-19 06:01:37.825145 | 
2026-03-19 06:01:37.825209 | TASK [emit-job-header : Print job information]
2026-03-19 06:01:37.851717 | # Job Information
2026-03-19 06:01:37.851832 | Ansible Version: 2.15.12
2026-03-19 06:01:37.851860 | Job: edpm-ansible-molecule-edpm_libvirt
2026-03-19 06:01:37.851884 | Pipeline: github-check
2026-03-19 06:01:37.851904 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-03-19 06:01:37.851923 | Triggered by: https://github.com/openstack-k8s-operators/edpm-ansible/pull/1114
2026-03-19 06:01:37.851944 | Log URL (when completed): https://logserver.rdoproject.org/fcb/rdoproject.org/fcb564afd1274d53aac92c27178f19e3/
2026-03-19 06:01:37.851962 | Event ID: e983a8d0-2358-11f1-97c5-7b1fa460743b
2026-03-19 06:01:37.855655 | 
2026-03-19 06:01:37.855720 | LOOP [emit-job-header : Print node information]
2026-03-19 06:01:37.940223 | localhost | ok:
2026-03-19 06:01:37.940414 | localhost | # Node Information
2026-03-19 06:01:37.940454 | localhost | Inventory Hostname: controller
2026-03-19 06:01:37.940485 | localhost | Hostname: np0005651476
2026-03-19 06:01:37.940511 | localhost | Username: zuul
2026-03-19 06:01:37.940536 | localhost | Distro: CentOS 9
2026-03-19 06:01:37.940569 | localhost | Provider: ibm-bm4-nodepool
2026-03-19 06:01:37.940594 | localhost | Region: regionOne
2026-03-19 06:01:37.940617 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2026-03-19 06:01:37.940640 | localhost | Product Name: OpenStack Compute
2026-03-19 06:01:37.940662 | localhost | Interface IP: 192.168.26.187
2026-03-19 06:01:37.959277 | 
2026-03-19 06:01:37.959364 | PLAY [all]
2026-03-19 06:01:37.966008 | 
2026-03-19 06:01:37.966070 | TASK [Gather network facts]
2026-03-19 06:01:38.298510 | controller | ok
2026-03-19 06:01:38.312041 | 
2026-03-19 06:01:38.312099 | TASK [include_role : start-zuul-console]
2026-03-19 06:01:38.329391 | controller | ok
2026-03-19 06:01:38.341166 | 
2026-03-19 06:01:38.341230 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-19 06:01:38.632318 | controller | ok
2026-03-19 06:01:38.640463 | 
2026-03-19 06:01:38.640524 | TASK [include_role : add-build-sshkey]
2026-03-19 06:01:38.658042 | controller | ok
2026-03-19 06:01:38.670283 | 
2026-03-19 06:01:38.670363 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-19 06:01:38.865737 | controller -> localhost | ok
2026-03-19 06:01:38.871524 | 
2026-03-19 06:01:38.871629 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-19 06:01:38.889944 | controller | ok
2026-03-19 06:01:38.903817 | controller | included: /var/lib/zuul/builds/fcb564afd1274d53aac92c27178f19e3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-19 06:01:38.909626 | 
2026-03-19 06:01:38.909698 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-19 06:01:39.378366 | controller -> localhost | Generating public/private rsa key pair.
2026-03-19 06:01:39.378548 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/fcb564afd1274d53aac92c27178f19e3/work/fcb564afd1274d53aac92c27178f19e3_id_rsa.
2026-03-19 06:01:39.378592 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/fcb564afd1274d53aac92c27178f19e3/work/fcb564afd1274d53aac92c27178f19e3_id_rsa.pub.
2026-03-19 06:01:39.378617 | controller -> localhost | The key fingerprint is:
2026-03-19 06:01:39.378637 | controller -> localhost | SHA256:jJHOeiBDd+LYSM5Z+OXSfO2nYJaIL5rpnEBo8xoKh1w zuul-build-sshkey
2026-03-19 06:01:39.378655 | controller -> localhost | The key's randomart image is:
2026-03-19 06:01:39.378673 | controller -> localhost | +---[RSA 3072]----+
2026-03-19 06:01:39.378689 | controller -> localhost | |                 |
2026-03-19 06:01:39.378705 | controller -> localhost | |   .   .         |
2026-03-19 06:01:39.378721 | controller -> localhost | |  + + =          |
2026-03-19 06:01:39.378738 | controller -> localhost | |.= X X + .       |
2026-03-19 06:01:39.378755 | controller -> localhost | |.+OE* B S .      |
2026-03-19 06:01:39.378772 | controller -> localhost | |+.+o = o o       |
2026-03-19 06:01:39.378789 | controller -> localhost | |+o..o o = . .    |
2026-03-19 06:01:39.378805 | controller -> localhost | |o+o+.o o . o     |
2026-03-19 06:01:39.378823 | controller -> localhost | |.oB. ..   .      |
2026-03-19 06:01:39.378840 | controller -> localhost | +----[SHA256]-----+
2026-03-19 06:01:39.378880 | controller -> localhost | ok: Runtime: 0:00:00.143497
2026-03-19 06:01:39.384428 | 
2026-03-19 06:01:39.384495 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-19 06:01:39.402277 | controller | ok
2026-03-19 06:01:39.411287 | controller | included: /var/lib/zuul/builds/fcb564afd1274d53aac92c27178f19e3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-19 06:01:39.419059 | 
2026-03-19 06:01:39.419124 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-19 06:01:39.442954 | controller | skipping: Conditional result was False
2026-03-19 06:01:39.448170 | 
2026-03-19 06:01:39.448242 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-19 06:01:39.800791 | controller | changed
2026-03-19 06:01:39.806546 | 
2026-03-19 06:01:39.806624 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-19 06:01:39.997789 | controller | ok
2026-03-19 06:01:40.002423 | 
2026-03-19 06:01:40.002491 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-19 06:01:40.519600 | controller | changed
2026-03-19 06:01:40.524332 | 
2026-03-19 06:01:40.524396 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-19 06:01:41.033987 | controller | changed
2026-03-19 06:01:41.038825 | 
2026-03-19 06:01:41.038891 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-19 06:01:41.053275 | controller | skipping: Conditional result was False
2026-03-19 06:01:41.059083 | 
2026-03-19 06:01:41.059152 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-19 06:01:41.401521 | controller -> localhost | changed
2026-03-19 06:01:41.410883 | 
2026-03-19 06:01:41.410953 | TASK [add-build-sshkey : Add back temp key]
2026-03-19 06:01:41.637223 | controller -> localhost | Identity added: /var/lib/zuul/builds/fcb564afd1274d53aac92c27178f19e3/work/fcb564afd1274d53aac92c27178f19e3_id_rsa (zuul-build-sshkey)
2026-03-19 06:01:41.637450 | controller -> localhost | ok: Runtime: 0:00:00.007403
2026-03-19 06:01:41.643291 | 
2026-03-19 06:01:41.643369 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-19 06:01:41.925957 | controller | ok
2026-03-19 06:01:41.930390 | 
2026-03-19 06:01:41.930455 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-19 06:01:41.953564 | controller | skipping: Conditional result was False
2026-03-19 06:01:41.962285 | 
2026-03-19 06:01:41.962364 | TASK [include_role : validate-host]
2026-03-19 06:01:41.979071 | controller | ok
2026-03-19 06:01:41.997853 | 
2026-03-19 06:01:41.997916 | TASK [validate-host : Define zuul_info_dir fact]
2026-03-19 06:01:42.025193 | controller | ok
2026-03-19 06:01:42.029606 | 
2026-03-19 06:01:42.029670 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-03-19 06:01:42.224716 | controller -> localhost | ok
2026-03-19 06:01:42.230228 | 
2026-03-19 06:01:42.230291 | TASK [validate-host : Collect information about the host]
2026-03-19 06:01:42.798939 | controller | ok
2026-03-19 06:01:42.806711 | 
2026-03-19 06:01:42.806776 | TASK [validate-host : Sanitize hostname]
2026-03-19 06:01:42.875080 | controller | ok
2026-03-19 06:01:42.879975 | 
2026-03-19 06:01:42.880039 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-03-19 06:01:43.310232 | controller -> localhost | changed
2026-03-19 06:01:43.341379 | 
2026-03-19 06:01:43.341475 | TASK [validate-host : Collect information about zuul worker]
2026-03-19 06:01:43.644048 | controller | ok
2026-03-19 06:01:43.648681 | 
2026-03-19 06:01:43.648749 | TASK [validate-host : Write out all zuul information for each host]
2026-03-19 06:01:44.008446 | controller -> localhost | changed
2026-03-19 06:01:44.017749 | 
2026-03-19 06:01:44.017814 | TASK [include_role : prepare-workspace-openshift]
2026-03-19 06:01:44.031687 | controller | skipping: Conditional result was False
2026-03-19 06:01:44.037351 | 
2026-03-19 06:01:44.037416 | TASK [include_role : remove-zuul-sshkey]
2026-03-19 06:01:44.061002 | controller | skipping: Conditional result was False
2026-03-19 06:01:44.066608 | 
2026-03-19 06:01:44.066677 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-19 06:01:44.258259 | controller | ok: "logs"
2026-03-19 06:01:44.258578 | controller | ok: All items complete
2026-03-19 06:01:44.258617 | 
2026-03-19 06:01:44.417391 | controller | ok: "artifacts"
2026-03-19 06:01:44.574201 | controller | ok: "docs"
2026-03-19 06:01:44.583651 | 
2026-03-19 06:01:44.583727 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-19 06:01:44.781978 | controller | changed: "logs"
2026-03-19 06:01:44.943736 | controller | changed: "artifacts"
2026-03-19 06:01:45.108861 | controller | changed: "docs"
2026-03-19 06:01:45.140040 | 
2026-03-19 06:01:45.140150 | PLAY RECAP
2026-03-19 06:01:45.140200 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-19 06:01:45.140229 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-19 06:01:45.140249 | 
2026-03-19 06:01:45.243684 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-19 06:01:45.244508 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-19 06:01:45.820972 | 
2026-03-19 06:01:45.821154 | PLAY [all]
2026-03-19 06:01:45.840780 | 
2026-03-19 06:01:45.840855 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-19 06:01:45.900817 | controller | ok
2026-03-19 06:01:45.905795 | 
2026-03-19 06:01:45.905879 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-19 06:01:46.250227 | controller | changed
2026-03-19 06:01:46.255921 | 
2026-03-19 06:01:46.256001 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-19 06:01:47.015091 | controller | changed
2026-03-19 06:01:47.024475 | 
2026-03-19 06:01:47.024620 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-03-19 06:01:47.371715 | controller | changed:
2026-03-19 06:01:47.371914 | controller | {
2026-03-19 06:01:47.371943 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-03-19 06:01:47.371976 | controller | }
2026-03-19 06:01:47.572136 | controller | changed:
2026-03-19 06:01:47.572233 | controller | {
2026-03-19 06:01:47.572260 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-03-19 06:01:47.572282 | controller | }
2026-03-19 06:01:47.770905 | controller | changed:
2026-03-19 06:01:47.770982 | controller | {
2026-03-19 06:01:47.771008 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-03-19 06:01:47.771029 | controller | }
2026-03-19 06:01:47.965403 | controller | changed:
2026-03-19 06:01:47.965482 | controller | {
2026-03-19 06:01:47.965508 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-03-19 06:01:47.965529 | controller | }
2026-03-19 06:01:48.164199 | controller | changed:
2026-03-19 06:01:48.164281 | controller | {
2026-03-19 06:01:48.164334 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-03-19 06:01:48.164357 | controller | }
2026-03-19 06:01:48.359163 | controller | changed:
2026-03-19 06:01:48.359310 | controller | {
2026-03-19 06:01:48.359365 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-03-19 06:01:48.359390 | controller | }
2026-03-19 06:01:48.558358 | controller | changed:
2026-03-19 06:01:48.558464 | controller | {
2026-03-19 06:01:48.558490 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2026-03-19 06:01:48.558512 | controller | }
2026-03-19 06:01:48.758669 | controller | changed:
2026-03-19 06:01:48.758766 | controller | {
2026-03-19 06:01:48.758792 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2026-03-19 06:01:48.758813 | controller | }
2026-03-19 06:01:48.957896 | controller | changed:
2026-03-19 06:01:48.958101 | controller | {
2026-03-19 06:01:48.958181 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-03-19 06:01:48.958208 | controller | }
2026-03-19 06:01:49.158096 | controller | changed:
2026-03-19 06:01:49.158198 | controller | {
2026-03-19 06:01:49.158223 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-03-19 06:01:49.158242 | controller | }
2026-03-19 06:01:49.356173 | controller | changed:
2026-03-19 06:01:49.356506 | controller | {
2026-03-19 06:01:49.356547 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-03-19 06:01:49.356583 | controller | }
2026-03-19 06:01:49.575187 | controller | changed:
2026-03-19 06:01:49.575329 | controller | {
2026-03-19 06:01:49.575361 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-03-19 06:01:49.575383 | controller | }
2026-03-19 06:01:49.748441 | controller | changed:
2026-03-19 06:01:49.748741 | controller | {
2026-03-19 06:01:49.748785 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-03-19 06:01:49.748811 | controller | }
2026-03-19 06:01:49.946750 | controller | changed:
2026-03-19 06:01:49.946917 | controller | {
2026-03-19 06:01:49.946947 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-03-19 06:01:49.946968 | controller | }
2026-03-19 06:01:50.145433 | controller | changed:
2026-03-19 06:01:50.145584 | controller | {
2026-03-19 06:01:50.145611 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-03-19 06:01:50.145632 | controller | }
2026-03-19 06:01:50.348584 | controller | changed:
2026-03-19 06:01:50.348786 | controller | {
2026-03-19 06:01:50.348811 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-03-19 06:01:50.348831 | controller | }
2026-03-19 06:01:50.551874 | controller | changed:
2026-03-19 06:01:50.552282 | controller | {
2026-03-19 06:01:50.552359 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2026-03-19 06:01:50.552385 | controller | }
2026-03-19 06:01:50.751072 | controller | changed:
2026-03-19 06:01:50.751198 | controller | {
2026-03-19 06:01:50.751225 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2026-03-19 06:01:50.751254 | controller | }
2026-03-19 06:01:50.949290 | controller | changed:
2026-03-19 06:01:50.949425 | controller | {
2026-03-19 06:01:50.949455 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-03-19 06:01:50.949475 | controller | }
2026-03-19 06:01:51.147874 | controller | changed:
2026-03-19 06:01:51.147978 | controller | {
2026-03-19 06:01:51.148004 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-03-19 06:01:51.148024 | controller | }
2026-03-19 06:01:51.347779 | controller | changed:
2026-03-19 06:01:51.347884 | controller | {
2026-03-19 06:01:51.347910 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-03-19 06:01:51.347929 | controller | }
2026-03-19 06:01:51.545380 | controller | changed:
2026-03-19 06:01:51.545484 | controller | {
2026-03-19 06:01:51.545509 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-03-19 06:01:51.545528 | controller | }
2026-03-19 06:01:51.746194 | controller | changed:
2026-03-19 06:01:51.746315 | controller | {
2026-03-19 06:01:51.746347 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2026-03-19 06:01:51.746370 | controller | }
2026-03-19 06:01:51.947917 | controller | changed:
2026-03-19 06:01:51.948022 | controller | {
2026-03-19 06:01:51.948048 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-03-19 06:01:51.948070 | controller | }
2026-03-19 06:01:52.147863 | controller | changed:
2026-03-19 06:01:52.147961 | controller | {
2026-03-19 06:01:52.147986 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-03-19 06:01:52.148014 | controller | }
2026-03-19 06:01:52.343809 | controller | changed:
2026-03-19 06:01:52.343923 | controller | {
2026-03-19 06:01:52.343947 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-03-19 06:01:52.343968 | controller | }
2026-03-19 06:01:52.369399 | 
2026-03-19 06:01:52.369507 | TASK [Set timezone to UTC]
2026-03-19 06:01:52.744390 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-03-19 06:01:52.748958 | 
2026-03-19 06:01:52.749022 | TASK [Create nodepool directory]
2026-03-19 06:01:52.940663 | controller | changed
2026-03-19 06:01:52.945838 | 
2026-03-19 06:01:52.945902 | TASK [Create nodepool sub_nodes file]
2026-03-19 06:01:53.441762 | controller | changed
2026-03-19 06:01:53.446437 | 
2026-03-19 06:01:53.446498 | TASK [Create nodepool sub_nodes_private file]
2026-03-19 06:01:53.934666 | controller | changed
2026-03-19 06:01:53.940569 | 
2026-03-19 06:01:53.940631 | LOOP [Populate nodepool sub_nodes file]
2026-03-19 06:01:53.969959 | 
2026-03-19 06:01:53.970089 | LOOP [Populate nodepool sub_nodes_private file]
2026-03-19 06:01:53.999139 | 
2026-03-19 06:01:53.999275 | TASK [Create nodepool primary file]
2026-03-19 06:01:54.022753 | controller | skipping: Conditional result was False
2026-03-19 06:01:54.028128 | 
2026-03-19 06:01:54.028204 | TASK [Create nodepool node_private for this node]
2026-03-19 06:01:54.552346 | controller | changed
2026-03-19 06:01:54.558034 | 
2026-03-19 06:01:54.558113 | LOOP [Copy ssh keys to nodepool directory]
2026-03-19 06:01:54.885897 | controller | ok: Item: id_rsa Runtime: 0:00:00.004649
2026-03-19 06:01:54.886110 | 
2026-03-19 06:01:55.045162 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004305
2026-03-19 06:01:55.053482 | 
2026-03-19 06:01:55.053558 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-03-19 06:01:55.560222 | controller | changed
2026-03-19 06:01:55.565409 | 
2026-03-19 06:01:55.565503 | TASK [Validate sudoers config after edits]
2026-03-19 06:01:55.784049 | controller | /etc/sudoers: parsed OK
2026-03-19 06:01:55.784138 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-03-19 06:01:55.784149 | controller | /etc/sudoers.d/zuul: parsed OK
2026-03-19 06:01:55.784155 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-03-19 06:01:56.095129 | controller | ok: Runtime: 0:00:00.004504
2026-03-19 06:01:56.100528 | 
2026-03-19 06:01:56.100601 | TASK [Show the environment passed in to job shell scripts]
2026-03-19 06:01:56.307071 | controller | SHELL=/bin/bash
2026-03-19 06:01:56.307117 | controller | ZUUL_PROJECT=openstack-k8s-operators/edpm-ansible
2026-03-19 06:01:56.307126 | controller | ZUUL_SHORT_PROJECT_NAME=edpm-ansible
2026-03-19 06:01:56.307133 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/80/3780/77caeb22c6eaa3e8f8f71bf7629b941330ab94e9^openstack-k8s-operators/edpm-ansible:main:refs/changes/14/1114/2e1be7179d11cf9a1af84970f52b70dde49b472c
2026-03-19 06:01:56.307152 | controller | PWD=/home/zuul
2026-03-19 06:01:56.307216 | controller | ZUUL_PIPELINE=github-check
2026-03-19 06:01:56.307228 | controller | LOGNAME=zuul
2026-03-19 06:01:56.307234 | controller | XDG_SESSION_TYPE=tty
2026-03-19 06:01:56.307239 | controller | _=/usr/bin/env
2026-03-19 06:01:56.307245 | controller | MOTD_SHOWN=pam
2026-03-19 06:01:56.307250 | controller | HOME=/home/zuul
2026-03-19 06:01:56.307255 | controller | LANG=en_US.UTF-8
2026-03-19 06:01:56.307261 | controller | SSH_CONNECTION=192.168.26.12 58262 192.168.26.187 22
2026-03-19 06:01:56.307266 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2026-03-19 06:01:56.307272 | controller | ZUUL_CHANGE_IDS=3780,77caeb22c6eaa3e8f8f71bf7629b941330ab94e9 1114,2e1be7179d11cf9a1af84970f52b70dde49b472c
2026-03-19 06:01:56.307278 | controller | WORKSPACE=/home/zuul/workspace
2026-03-19 06:01:56.307283 | controller | XDG_SESSION_CLASS=user
2026-03-19 06:01:56.307289 | controller | SELINUX_ROLE_REQUESTED=
2026-03-19 06:01:56.307294 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-03-19 06:01:56.307299 | controller | USER=zuul
2026-03-19 06:01:56.307305 | controller | ZUUL_VOTING=True
2026-03-19 06:01:56.307310 | controller | BUILD_TIMEOUT=1800000
2026-03-19 06:01:56.307315 | controller | SELINUX_USE_CURRENT_RANGE=
2026-03-19 06:01:56.307320 | controller | SHLVL=1
2026-03-19 06:01:56.307326 | controller | ZUUL_PATCHSET=2e1be7179d11cf9a1af84970f52b70dde49b472c
2026-03-19 06:01:56.307331 | controller | XDG_SESSION_ID=1
2026-03-19 06:01:56.307337 | controller | ZUUL_BRANCH=main
2026-03-19 06:01:56.307342 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-03-19 06:01:56.307347 | controller | SSH_CLIENT=192.168.26.12 58262 22
2026-03-19 06:01:56.307353 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-03-19 06:01:56.307358 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-03-19 06:01:56.307366 | controller | which_declare=declare -f
2026-03-19 06:01:56.307371 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-03-19 06:01:56.307377 | controller | SELINUX_LEVEL_REQUESTED=
2026-03-19 06:01:56.307384 | controller | ZUUL_CHANGE=1114
2026-03-19 06:01:56.307389 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-03-19 06:01:56.307395 | controller | ZUUL_UUID=fcb564afd1274d53aac92c27178f19e3
2026-03-19 06:01:56.307400 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-03-19 06:01:56.307406 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-03-19 06:01:56.307411 | controller | }
2026-03-19 06:01:56.627696 | controller | ok: Runtime: 0:00:00.005170
2026-03-19 06:01:56.633146 | 
2026-03-19 06:01:56.633205 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-03-19 06:01:56.647829 | controller | skipping: Conditional result was False
2026-03-19 06:01:56.653585 | 
2026-03-19 06:01:56.653655 | TASK [Symlink /home/zuul-worker/workspace]
2026-03-19 06:01:57.179444 | controller | skipping: Conditional result was False
2026-03-19 06:01:57.186273 | 
2026-03-19 06:01:57.186370 | TASK [Ensure legacy workspace directory]
2026-03-19 06:01:57.378791 | controller | changed
2026-03-19 06:01:57.397821 | 
2026-03-19 06:01:57.397882 | PLAY RECAP
2026-03-19 06:01:57.397931 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-19 06:01:57.397955 | 
2026-03-19 06:01:57.521181 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-19 06:01:57.522086 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-19 06:01:58.080027 | 
2026-03-19 06:01:58.080186 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-03-19 06:01:58.102666 | 
2026-03-19 06:01:58.102746 | TASK [Create zuul-output directory]
2026-03-19 06:01:58.430175 | controller | changed
2026-03-19 06:01:58.435799 | 
2026-03-19 06:01:58.435888 | TASK [Slurp Zuul inventory test]
2026-03-19 06:01:58.717095 | controller -> localhost | ok
2026-03-19 06:01:58.726222 | 
2026-03-19 06:01:58.726703 | TASK [Save zuul inventory]
2026-03-19 06:01:59.460774 | controller | changed
2026-03-19 06:01:59.466039 | 
2026-03-19 06:01:59.466120 | TASK [Save zuul vars without the change_message]
2026-03-19 06:02:00.101946 | controller | changed
2026-03-19 06:02:00.121415 | 
2026-03-19 06:02:00.121489 | PLAY RECAP
2026-03-19 06:02:00.121563 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-19 06:02:00.121587 | 
2026-03-19 06:02:00.200704 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-19 06:02:00.202109 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-19 06:02:00.779187 | 
2026-03-19 06:02:00.779341 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-03-19 06:02:00.799891 | 
2026-03-19 06:02:00.799967 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-03-19 06:02:00.819643 | controller | ok
2026-03-19 06:02:00.836394 | 
2026-03-19 06:02:00.836479 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-19 06:02:00.860406 | controller | skipping: Conditional result was False
2026-03-19 06:02:00.866436 | 
2026-03-19 06:02:00.866509 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-19 06:02:01.179590 | controller | ok
2026-03-19 06:02:01.185403 | 
2026-03-19 06:02:01.185477 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-19 06:02:01.804134 | controller | ok
2026-03-19 06:02:01.814327 | 
2026-03-19 06:02:01.814396 | TASK [Prepare workspace]
2026-03-19 06:02:01.833838 | controller | ok
2026-03-19 06:02:01.850322 | 
2026-03-19 06:02:01.850391 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-19 06:02:02.152272 | controller | ok
2026-03-19 06:02:02.158612 | 
2026-03-19 06:02:02.158680 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-19 06:02:04.519954 | controller | Output suppressed because no_log was given
2026-03-19 06:02:04.529195 | 
2026-03-19 06:02:04.529283 | LOOP [Create zuul-output directory]
2026-03-19 06:02:04.715496 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-03-19 06:02:04.875370 | controller | ok: "/home/zuul/zuul-output/logs"
2026-03-19 06:02:04.888596 | 
2026-03-19 06:02:04.888728 | TASK [Install required packages]
2026-03-19 06:02:40.793377 | controller | changed
2026-03-19 06:02:40.798645 | 
2026-03-19 06:02:40.798710 | TASK [Install venv]
2026-03-19 06:03:40.789414 | controller | changed
2026-03-19 06:03:40.814718 | 
2026-03-19 06:03:40.814979 | PLAY RECAP
2026-03-19 06:03:40.815034 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-19 06:03:40.815064 | 
2026-03-19 06:03:40.888564 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-19 06:03:40.889632 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-03-19 06:03:41.403463 | 
2026-03-19 06:03:41.403568 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-03-19 06:03:41.423463 | 
2026-03-19 06:03:41.423544 | TASK [Gather required facts]
2026-03-19 06:03:41.878904 | controller | ok
2026-03-19 06:03:41.883621 | 
2026-03-19 06:03:41.883690 | TASK [Load environment var if instructed to]
2026-03-19 06:03:41.907169 | controller | skipping: Conditional result was False
2026-03-19 06:03:41.912975 | 
2026-03-19 06:03:41.913034 | TASK [Ensure group_vars dir exists]
2026-03-19 06:03:42.205011 | controller | changed
2026-03-19 06:03:42.210946 | 
2026-03-19 06:03:42.211009 | TASK [Print related variables]
2026-03-19 06:03:42.238849 | controller | ok:
2026-03-19 06:03:42.238956 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_edpm_ansible.yml
2026-03-19 06:03:42.238985 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/roles/edpm_libvirt
2026-03-19 06:03:42.243552 | 
2026-03-19 06:03:42.243617 | TASK [Run molecule]
2026-03-19 06:03:43.334490 | controller | WARNING  Driver vagrant does not provide a schema.
2026-03-19 06:03:43.336886 | controller | INFO     Performing prerun with role_name_check=0...
2026-03-19 06:03:44.136382 | controller | INFO     Running default > destroy
2026-03-19 06:03:44.136658 | controller | WARNING  Skipping, instances are delegated.
2026-03-19 06:03:44.143891 | controller | INFO     Running default > create
2026-03-19 06:03:44.144798 | controller | WARNING  Skipping, instances are delegated.
2026-03-19 06:03:44.151961 | controller | INFO     Running default > prepare
2026-03-19 06:03:44.802781 | controller |
2026-03-19 06:03:44.802865 | controller | PLAY [Setup DUT] ***************************************************************
2026-03-19 06:03:44.802969 | controller |
2026-03-19 06:03:44.803063 | controller | TASK [set basic user fact] *****************************************************
2026-03-19 06:03:44.803181 | controller | Thursday 19 March 2026  06:03:44 +0000 (0:00:00.009)       0:00:00.009 ********
2026-03-19 06:03:44.826673 | controller | ok: [compute-1]
2026-03-19 06:03:44.826778 | controller |
2026-03-19 06:03:44.826895 | controller | TASK [set basic home fact] *****************************************************
2026-03-19 06:03:44.827020 | controller | Thursday 19 March 2026  06:03:44 +0000 (0:00:00.024)       0:00:00.033 ********
2026-03-19 06:03:44.849801 | controller | ok: [compute-1]
2026-03-19 06:03:44.849931 | controller |
2026-03-19 06:03:44.850059 | controller | TASK [set bootc fact] **********************************************************
2026-03-19 06:03:44.850197 | controller | Thursday 19 March 2026  06:03:44 +0000 (0:00:00.023)       0:00:00.056 ********
2026-03-19 06:03:44.868667 | controller | ok: [compute-1]
2026-03-19 06:03:44.868777 | controller |
2026-03-19 06:03:44.868894 | controller | TASK [Enable package repos] ****************************************************
2026-03-19 06:03:44.869023 | controller | Thursday 19 March 2026  06:03:44 +0000 (0:00:00.018)       0:00:00.075 ********
2026-03-19 06:03:53.124482 | controller | changed: [compute-1]
2026-03-19 06:03:53.124559 | controller |
2026-03-19 06:03:53.124664 | controller | TASK [Install host packages] ***************************************************
2026-03-19 06:03:53.124764 | controller | Thursday 19 March 2026  06:03:53 +0000 (0:00:08.255)       0:00:08.331 ********
2026-03-19 06:05:55.659341 | controller | changed: [compute-1] => (item=openstack-selinux)
2026-03-19 06:05:55.659392 | controller | changed: [compute-1] => (item=python3-cryptography)
2026-03-19 06:05:55.659485 | controller |
2026-03-19 06:05:55.659579 | controller | TASK [set /etc/localtime] ******************************************************
2026-03-19 06:05:55.659676 | controller | Thursday 19 March 2026  06:05:55 +0000 (0:02:02.534)       0:02:10.866 ********
2026-03-19 06:05:55.949642 | controller | changed: [compute-1]
2026-03-19 06:05:55.949692 | controller |
2026-03-19 06:05:55.949796 | controller | TASK [osp.edpm.edpm_timezone : Validating arguments against arg spec 'main' - The main entry point for the edpm_timezone role.] ***
2026-03-19 06:05:55.949889 | controller | Thursday 19 March 2026  06:05:55 +0000 (0:00:00.289)       0:02:11.156 ********
2026-03-19 06:05:55.964456 | controller | ok: [compute-1]
2026-03-19 06:05:55.964540 | controller |
2026-03-19 06:05:55.964646 | controller | TASK [osp.edpm.edpm_timezone : Gather ansible_local facts] *********************
2026-03-19 06:05:55.964744 | controller | Thursday 19 March 2026  06:05:55 +0000 (0:00:00.015)       0:02:11.171 ********
2026-03-19 06:05:55.983729 | controller | skipping: [compute-1]
2026-03-19 06:05:55.983817 | controller |
2026-03-19 06:05:55.983913 | controller | TASK [osp.edpm.edpm_timezone : Check for /etc/localtime] ***********************
2026-03-19 06:05:55.984011 | controller | Thursday 19 March 2026  06:05:55 +0000 (0:00:00.019)       0:02:11.190 ********
2026-03-19 06:05:55.999064 | controller | skipping: [compute-1]
2026-03-19 06:05:55.999175 | controller |
2026-03-19 06:05:55.999289 | controller | TASK [osp.edpm.edpm_timezone : Symlink /etc/localtime to timezone file if it doesn't exist] ***
2026-03-19 06:05:55.999387 | controller | Thursday 19 March 2026  06:05:55 +0000 (0:00:00.015)       0:02:11.206 ********
2026-03-19 06:05:56.015567 | controller | skipping: [compute-1]
2026-03-19 06:05:56.015662 | controller |
2026-03-19 06:05:56.015762 | controller | TASK [osp.edpm.edpm_timezone : Set timezone] ***********************************
2026-03-19 06:05:56.015868 | controller | Thursday 19 March 2026  06:05:56 +0000 (0:00:00.016)       0:02:11.222 ********
2026-03-19 06:05:56.388943 | controller | ok: [compute-1]
2026-03-19 06:05:56.389032 | controller |
2026-03-19 06:05:56.389137 | controller | TASK [osp.edpm.edpm_timezone : Set fact for timezone result] *******************
2026-03-19 06:05:56.389263 | controller | Thursday 19 March 2026  06:05:56 +0000 (0:00:00.373)       0:02:11.595 ********
2026-03-19 06:05:56.403680 | controller | ok: [compute-1]
2026-03-19 06:05:56.403762 | controller |
2026-03-19 06:05:56.403865 | controller | TASK [osp.edpm.edpm_timezone : Restart time services] **************************
2026-03-19 06:05:56.403959 | controller | Thursday 19 March 2026  06:05:56 +0000 (0:00:00.014)       0:02:11.610 ********
2026-03-19 06:05:57.354200 | controller | changed: [compute-1] => (item=rsyslog)
2026-03-19 06:05:57.354633 | controller | changed: [compute-1] => (item=crond)
2026-03-19 06:05:58.569456 | controller |
2026-03-19 06:05:58.569493 | controller | TASK [remove vagrant image packages] *******************************************
2026-03-19 06:05:58.569503 | controller | Thursday 19 March 2026  06:05:57 +0000 (0:00:00.950)       0:02:12.561 ********
2026-03-19 06:05:58.569513 | controller | ok: [compute-1] => (item=firewalld)
2026-03-19 06:05:58.763957 | controller |
2026-03-19 06:05:58.763986 | controller | TASK [Create firewall directory] ***********************************************
2026-03-19 06:05:58.764007 | controller | Thursday 19 March 2026  06:05:58 +0000 (0:00:01.215)       0:02:13.776 ********
2026-03-19 06:05:58.764018 | controller | changed: [compute-1]
2026-03-19 06:05:59.325774 | controller |
2026-03-19 06:05:59.325804 | controller | TASK [open port 22 (edpm_nftables will active this later)] *********************
2026-03-19 06:05:59.325813 | controller | Thursday 19 March 2026  06:05:58 +0000 (0:00:00.194)       0:02:13.970 ********
2026-03-19 06:05:59.325823 | controller | changed: [compute-1]
2026-03-19 06:05:59.531303 | controller |
2026-03-19 06:05:59.531333 | controller | TASK [Create Ceph client file directory] ***************************************
2026-03-19 06:05:59.531342 | controller | Thursday 19 March 2026  06:05:59 +0000 (0:00:00.561)       0:02:14.532 ********
2026-03-19 06:05:59.531352 | controller | changed: [compute-1]
2026-03-19 06:05:59.704514 | controller |
2026-03-19 06:05:59.704548 | controller | TASK [Create random mock Ceph FSID] ********************************************
2026-03-19 06:05:59.704561 | controller | Thursday 19 March 2026  06:05:59 +0000 (0:00:00.205)       0:02:14.737 ********
2026-03-19 06:05:59.704572 | controller | changed: [compute-1]
2026-03-19 06:05:59.895243 | controller |
2026-03-19 06:05:59.895271 | controller | TASK [Create random mock Cephx key] ********************************************
2026-03-19 06:05:59.895280 | controller | Thursday 19 March 2026  06:05:59 +0000 (0:00:00.173)       0:02:14.911 ********
2026-03-19 06:05:59.895290 | controller | changed: [compute-1]
2026-03-19 06:06:00.248042 | controller |
2026-03-19 06:06:00.248072 | controller | TASK [Create a mock Ceph conf file] ********************************************
2026-03-19 06:06:00.248081 | controller | Thursday 19 March 2026  06:05:59 +0000 (0:00:00.190)       0:02:15.101 ********
2026-03-19 06:06:00.248091 | controller | changed: [compute-1]
2026-03-19 06:06:00.621902 | controller |
2026-03-19 06:06:00.621931 | controller | TASK [Create a mock Cephx keyring file] ****************************************
2026-03-19 06:06:00.621940 | controller | Thursday 19 March 2026  06:06:00 +0000 (0:00:00.352)       0:02:15.454 ********
2026-03-19 06:06:00.621950 | controller | changed: [compute-1]
2026-03-19 06:06:00.815182 | controller |
2026-03-19 06:06:00.815211 | controller | TASK [create pki temp directory] ***********************************************
2026-03-19 06:06:00.815219 | controller | Thursday 19 March 2026  06:06:00 +0000 (0:00:00.373)       0:02:15.828 ********
2026-03-19 06:06:00.815233 | controller | changed: [compute-1]
2026-03-19 06:06:01.697134 | controller |
2026-03-19 06:06:01.697180 | controller | TASK [Create private key (RSA, 4096 bits)] *************************************
2026-03-19 06:06:01.697191 | controller | Thursday 19 March 2026  06:06:00 +0000 (0:00:00.193)       0:02:16.021 ********
2026-03-19 06:06:01.697202 | controller | changed: [compute-1]
2026-03-19 06:06:02.392257 | controller |
2026-03-19 06:06:02.392288 | controller | TASK [Create a certificate signing request for ca] *****************************
2026-03-19 06:06:02.392296 | controller | Thursday 19 March 2026  06:06:01 +0000 (0:00:00.881)       0:02:16.903 ********
2026-03-19 06:06:02.392306 | controller | changed: [compute-1]
2026-03-19 06:06:03.191480 | controller |
2026-03-19 06:06:03.191512 | controller | TASK [Create a certificate athority] *******************************************
2026-03-19 06:06:03.191526 | controller | Thursday 19 March 2026  06:06:02 +0000 (0:00:00.695)       0:02:17.598 ********
2026-03-19 06:06:03.191537 | controller | changed: [compute-1]
2026-03-19 06:06:04.274092 | controller |
2026-03-19 06:06:04.274123 | controller | TASK [Create private key (RSA, 4096 bits)] *************************************
2026-03-19 06:06:04.274131 | controller | Thursday 19 March 2026  06:06:03 +0000 (0:00:00.799)       0:02:18.398 ********
2026-03-19 06:06:04.274142 | controller | changed: [compute-1]
2026-03-19 06:06:04.831515 | controller |
2026-03-19 06:06:04.831546 | controller | TASK [Create a certificate signing request for libvirt server and client cert] ***
2026-03-19 06:06:04.831555 | controller | Thursday 19 March 2026  06:06:04 +0000 (0:00:01.082)       0:02:19.480 ********
2026-03-19 06:06:04.831566 | controller | changed: [compute-1]
2026-03-19 06:06:05.457196 | controller |
2026-03-19 06:06:05.457232 | controller | TASK [Sign the certificate signing request] ************************************
2026-03-19 06:06:05.457241 | controller | Thursday 19 March 2026  06:06:04 +0000 (0:00:00.557)       0:02:20.038 ********
2026-03-19 06:06:05.457252 | controller | changed: [compute-1]
2026-03-19 06:06:05.772815 | controller |
2026-03-19 06:06:05.772845 | controller | TASK [Create password secret] **************************************************
2026-03-19 06:06:05.772854 | controller | Thursday 19 March 2026  06:06:05 +0000 (0:00:00.625)       0:02:20.663 ********
2026-03-19 06:06:05.772864 | controller | changed: [compute-1 -> localhost]
2026-03-19 06:06:06.105473 | controller |
2026-03-19 06:06:06.105503 | controller | TASK [Gather required facts] ***************************************************
2026-03-19 06:06:06.105511 | controller | Thursday 19 March 2026  06:06:05 +0000 (0:00:00.315)       0:02:20.979 ********
2026-03-19 06:06:06.105522 | controller | ok: [compute-1]
2026-03-19 06:06:06.418233 | controller |
2026-03-19 06:06:06.418262 | controller | TASK [Ensure hostname is resolvable in /etc/hosts] *****************************
2026-03-19 06:06:06.418271 | controller | Thursday 19 March 2026  06:06:06 +0000 (0:00:00.332)       0:02:21.312 ********
2026-03-19 06:06:06.418282 | controller | changed: [compute-1]
2026-03-19 06:06:06.418307 | controller |
2026-03-19 06:06:06.418316 | controller | PLAY RECAP *********************************************************************
2026-03-19 06:06:06.420391 | controller | compute-1                  : ok=28   changed=20   unreachable=0    failed=0    skipped=3    rescued=0    ignored=0
2026-03-19 06:06:06.474235 | controller |
2026-03-19 06:06:06.474269 | controller | Thursday 19 March 2026  06:06:06 +0000 (0:00:00.312)       0:02:21.624 ********
2026-03-19 06:06:06.474278 | controller | ===============================================================================
2026-03-19 06:06:06.474284 | controller | Install host packages ------------------------------------------------- 122.53s
2026-03-19 06:06:06.474290 | controller | Enable package repos ---------------------------------------------------- 8.26s
2026-03-19 06:06:06.474296 | controller | remove vagrant image packages ------------------------------------------- 1.22s
2026-03-19 06:06:06.474301 | controller | Create private key (RSA, 4096 bits) ------------------------------------- 1.08s
2026-03-19 06:06:06.474307 | controller | osp.edpm.edpm_timezone : Restart time services -------------------------- 0.95s
2026-03-19 06:06:06.474312 | controller | Create private key (RSA, 4096 bits) ------------------------------------- 0.88s
2026-03-19 06:06:06.474318 | controller | Create a certificate athority ------------------------------------------- 0.80s
2026-03-19 06:06:06.474324 | controller | Create a certificate signing request for ca ----------------------------- 0.70s
2026-03-19 06:06:06.474330 | controller | Sign the certificate signing request ------------------------------------ 0.63s
2026-03-19 06:06:06.474335 | controller | open port 22 (edpm_nftables will active this later) --------------------- 0.56s
2026-03-19 06:06:06.474341 | controller | Create a certificate signing request for libvirt server and client cert --- 0.56s
2026-03-19 06:06:06.474346 | controller | Create a mock Cephx keyring file ---------------------------------------- 0.37s
2026-03-19 06:06:06.474351 | controller | osp.edpm.edpm_timezone : Set timezone ----------------------------------- 0.37s
2026-03-19 06:06:06.474357 | controller | Create a mock Ceph conf file -------------------------------------------- 0.35s
2026-03-19 06:06:06.474362 | controller | Gather required facts --------------------------------------------------- 0.33s
2026-03-19 06:06:06.474373 | controller | Create password secret -------------------------------------------------- 0.32s
2026-03-19 06:06:06.474379 | controller | Ensure hostname is resolvable in /etc/hosts ----------------------------- 0.31s
2026-03-19 06:06:06.474384 | controller | set /etc/localtime ------------------------------------------------------ 0.29s
2026-03-19 06:06:06.474390 | controller | Create Ceph client file directory --------------------------------------- 0.21s
2026-03-19 06:06:06.474395 | controller | Create firewall directory ----------------------------------------------- 0.19s
2026-03-19 06:06:06.474406 | controller | INFO     Running default > converge
2026-03-19 06:06:06.889514 | controller |
2026-03-19 06:06:06.889714 | controller | PLAY [Converge] ****************************************************************
2026-03-19 06:06:06.889852 | controller |
2026-03-19 06:06:06.889986 | controller | TASK [Gathering Facts] *********************************************************
2026-03-19 06:06:06.890129 | controller | Thursday 19 March 2026  06:06:06 +0000 (0:00:00.011)       0:00:00.011 ********
2026-03-19 06:06:07.648687 | controller | ok: [compute-1]
2026-03-19 06:06:07.675277 | controller |
2026-03-19 06:06:07.675295 | controller | TASK [set basic user fact] *****************************************************
2026-03-19 06:06:07.675303 | controller | Thursday 19 March 2026  06:06:07 +0000 (0:00:00.759)       0:00:00.770 ********
2026-03-19 06:06:07.675312 | controller | ok: [compute-1]
2026-03-19 06:06:07.695077 | controller |
2026-03-19 06:06:07.695097 | controller | TASK [set basic home fact] *****************************************************
2026-03-19 06:06:07.695106 | controller | Thursday 19 March 2026  06:06:07 +0000 (0:00:00.026)       0:00:00.797 ********
2026-03-19 06:06:07.695116 | controller | skipping: [compute-1]
2026-03-19 06:06:07.720093 | controller |
2026-03-19 06:06:07.720112 | controller | TASK [set bootc fact] **********************************************************
2026-03-19 06:06:07.720120 | controller | Thursday 19 March 2026  06:06:07 +0000 (0:00:00.019)       0:00:00.817 ********
2026-03-19 06:06:07.720129 | controller | ok: [compute-1]
2026-03-19 06:06:07.760898 | controller |
2026-03-19 06:06:07.760920 | controller | TASK [osp.edpm.edpm_libvirt : Validating arguments against arg spec 'main' - The main entry point for the edpm_libvirt role.] ***
2026-03-19 06:06:07.760929 | controller | Thursday 19 March 2026  06:06:07 +0000 (0:00:00.024)       0:00:00.842 ********
2026-03-19 06:06:07.760940 | controller | ok: [compute-1]
2026-03-19 06:06:07.782114 | controller |
2026-03-19 06:06:07.782132 | controller | TASK [osp.edpm.edpm_libvirt : Adopt libvirt from tripleo] **********************
2026-03-19 06:06:07.782140 | controller | Thursday 19 March 2026  06:06:07 +0000 (0:00:00.040)       0:00:00.882 ********
2026-03-19 06:06:07.782149 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/collections/ansible_collections/osp/edpm/roles/edpm_libvirt/tasks/adoption.yml for compute-1
2026-03-19 06:06:08.164253 | controller |
2026-03-19 06:06:08.164293 | controller | TASK [osp.edpm.edpm_libvirt : Check if nova_virtlogd container exists] *********
2026-03-19 06:06:08.164302 | controller | Thursday 19 March 2026  06:06:07 +0000 (0:00:00.021)       0:00:00.904 ********
2026-03-19 06:06:08.164318 | controller | ok: [compute-1]
2026-03-19 06:06:08.164351 | controller |
2026-03-19 06:06:08.164360 | controller | TASK [osp.edpm.edpm_libvirt : Stop nova_virtlogd containers not managed by service units] ***
2026-03-19 06:06:08.164696 | controller | Thursday 19 March 2026  06:06:08 +0000 (0:00:00.382)       0:00:01.286 ********
2026-03-19 06:06:08.193218 | controller | skipping: [compute-1]
2026-03-19 06:06:08.240282 | controller |
2026-03-19 06:06:08.240309 | controller | TASK [Stop and disable libvirt services] ***************************************
2026-03-19 06:06:08.240319 | controller | Thursday 19 March 2026  06:06:08 +0000 (0:00:00.028)       0:00:01.315 ********
2026-03-19 06:06:08.240331 | controller |
2026-03-19 06:06:08.240395 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Validating arguments against arg spec 'main' - The main entry point for the edpm_tripleo_cleanup role.] ***
2026-03-19 06:06:08.240408 | controller | Thursday 19 March 2026  06:06:08 +0000 (0:00:00.047)       0:00:01.362 ********
2026-03-19 06:06:08.260000 | controller | ok: [compute-1]
2026-03-19 06:06:08.260057 | controller |
2026-03-19 06:06:08.260183 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reload units to check for new changes] ***
2026-03-19 06:06:08.260293 | controller | Thursday 19 March 2026  06:06:08 +0000 (0:00:00.019)       0:00:01.382 ********
2026-03-19 06:06:08.933562 | controller | ok: [compute-1]
2026-03-19 06:06:08.933630 | controller |
2026-03-19 06:06:08.933733 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Gather service facts] ********************
2026-03-19 06:06:08.933832 | controller | Thursday 19 March 2026  06:06:08 +0000 (0:00:00.673)       0:00:02.055 ********
2026-03-19 06:06:10.746437 | controller | ok: [compute-1]
2026-03-19 06:06:10.746495 | controller |
2026-03-19 06:06:10.746633 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Set list of tripleo services] ************
2026-03-19 06:06:10.746761 | controller | Thursday 19 March 2026  06:06:10 +0000 (0:00:01.812)       0:00:03.868 ********
2026-03-19 06:06:10.768826 | controller | ok: [compute-1]
2026-03-19 06:06:10.768919 | controller |
2026-03-19 06:06:10.769037 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Get all services] ************************
2026-03-19 06:06:10.769180 | controller | Thursday 19 March 2026  06:06:10 +0000 (0:00:00.022)       0:00:03.891 ********
2026-03-19 06:06:10.800712 | controller | skipping: [compute-1]
2026-03-19 06:06:10.800807 | controller |
2026-03-19 06:06:10.800927 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Filter for tripleo services and skip the ones in skip list] ***
2026-03-19 06:06:10.801044 | controller | Thursday 19 March 2026  06:06:10 +0000 (0:00:00.031)       0:00:03.923 ********
2026-03-19 06:06:10.831349 | controller | skipping: [compute-1]
2026-03-19 06:06:10.831455 | controller |
2026-03-19 06:06:10.831578 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Stop and disable tripleo services] *******
2026-03-19 06:06:10.831701 | controller | Thursday 19 March 2026  06:06:10 +0000 (0:00:00.030)       0:00:03.953 ********
2026-03-19 06:06:12.807055 | controller | ok: [compute-1] => (item=tripleo_nova_libvirt.target)
2026-03-19 06:06:12.851244 | controller | ok: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2026-03-19 06:06:12.851275 | controller | ok: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2026-03-19 06:06:12.851283 | controller | ok: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2026-03-19 06:06:12.851289 | controller | ok: [compute-1] => (item=tripleo_nova_virtqemud.service)
2026-03-19 06:06:12.851294 | controller | ok: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2026-03-19 06:06:12.851300 | controller | ok: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2026-03-19 06:06:12.851306 | controller |
2026-03-19 06:06:12.851312 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Check for errors] ************************
2026-03-19 06:06:12.851318 | controller | Thursday 19 March 2026  06:06:12 +0000 (0:00:01.974)       0:00:05.928 ********
2026-03-19 06:06:12.851333 | 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'})
2026-03-19 06:06:12.851360 | 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'})
2026-03-19 06:06:12.851368 | 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'})
2026-03-19 06:06:12.851378 | 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'})
2026-03-19 06:06:12.851387 | 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'})
2026-03-19 06:06:12.851393 | 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'})
2026-03-19 06:06:12.851398 | 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'})
2026-03-19 06:06:12.851406 | controller | skipping: [compute-1]
2026-03-19 06:06:12.851568 | controller |
2026-03-19 06:06:12.851600 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files] ***********************
2026-03-19 06:06:12.886920 | controller | Thursday 19 March 2026  06:06:12 +0000 (0:00:00.044)       0:00:05.972 ********
2026-03-19 06:06:12.886953 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/collections/ansible_collections/osp/edpm/roles/edpm_tripleo_cleanup/tasks/remove_unit_files.yml for compute-1 => (item=/usr/lib/systemd/system)
2026-03-19 06:06:14.413207 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/collections/ansible_collections/osp/edpm/roles/edpm_tripleo_cleanup/tasks/remove_unit_files.yml for compute-1 => (item=/etc/systemd/system)
2026-03-19 06:06:14.413242 | controller |
2026-03-19 06:06:14.413251 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /usr/lib/systemd/system] ***
2026-03-19 06:06:14.413258 | controller | Thursday 19 March 2026  06:06:12 +0000 (0:00:00.035)       0:00:06.008 ********
2026-03-19 06:06:14.413269 | controller | ok: [compute-1] => (item=tripleo_nova_libvirt.target)
2026-03-19 06:06:15.710236 | controller | ok: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2026-03-19 06:06:15.710266 | controller | ok: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2026-03-19 06:06:15.710274 | controller | ok: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2026-03-19 06:06:15.710280 | controller | ok: [compute-1] => (item=tripleo_nova_virtqemud.service)
2026-03-19 06:06:15.710285 | controller | ok: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2026-03-19 06:06:15.710291 | controller | ok: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2026-03-19 06:06:15.710296 | controller |
2026-03-19 06:06:15.710303 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /etc/systemd/system] ***
2026-03-19 06:06:15.710309 | controller | Thursday 19 March 2026  06:06:14 +0000 (0:00:01.525)       0:00:07.534 ********
2026-03-19 06:06:15.710319 | controller | ok: [compute-1] => (item=tripleo_nova_libvirt.target)
2026-03-19 06:06:15.746109 | controller | ok: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2026-03-19 06:06:15.746130 | controller | ok: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2026-03-19 06:06:15.746146 | controller | ok: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2026-03-19 06:06:15.746154 | controller | ok: [compute-1] => (item=tripleo_nova_virtqemud.service)
2026-03-19 06:06:15.746181 | controller | ok: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2026-03-19 06:06:15.746198 | controller | ok: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2026-03-19 06:06:15.746206 | controller |
2026-03-19 06:06:15.746213 | controller | TASK [Adopt (stop tracking) certs from tripleo] ********************************
2026-03-19 06:06:15.746220 | controller | Thursday 19 March 2026  06:06:15 +0000 (0:00:01.297)       0:00:08.831 ********
2026-03-19 06:06:15.746231 | controller |
2026-03-19 06:06:15.945976 | controller | TASK [osp.edpm.edpm_install_certs : Stop and disable certmonger] ***************
2026-03-19 06:06:15.945996 | controller | Thursday 19 March 2026  06:06:15 +0000 (0:00:00.036)       0:00:08.868 ********
2026-03-19 06:06:15.946007 | controller | ok: [compute-1]
2026-03-19 06:06:16.195917 | controller |
2026-03-19 06:06:16.195938 | controller | TASK [osp.edpm.edpm_install_certs : Find certmonger requests] ******************
2026-03-19 06:06:16.195946 | controller | Thursday 19 March 2026  06:06:15 +0000 (0:00:00.199)       0:00:09.067 ********
2026-03-19 06:06:16.195955 | controller | [WARNING]: Skipped '/var/lib/certmonger/requests' path due to this access
2026-03-19 06:06:16.205476 | controller | issue: '/var/lib/certmonger/requests' is not a directory
2026-03-19 06:06:16.205495 | controller | ok: [compute-1]
2026-03-19 06:06:16.224049 | controller |
2026-03-19 06:06:16.224069 | controller | TASK [osp.edpm.edpm_install_certs : Backup certificate requests] ***************
2026-03-19 06:06:16.224090 | controller | Thursday 19 March 2026  06:06:16 +0000 (0:00:00.259)       0:00:09.327 ********
2026-03-19 06:06:16.224101 | controller | skipping: [compute-1]
2026-03-19 06:06:16.246641 | controller |
2026-03-19 06:06:16.246657 | controller | TASK [osp.edpm.edpm_install_certs : Remove certificate requests] ***************
2026-03-19 06:06:16.246663 | controller | Thursday 19 March 2026  06:06:16 +0000 (0:00:00.018)       0:00:09.346 ********
2026-03-19 06:06:16.246672 | controller | skipping: [compute-1]
2026-03-19 06:06:16.687777 | controller |
2026-03-19 06:06:16.687805 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reload units] ****************************
2026-03-19 06:06:16.687813 | controller | Thursday 19 March 2026  06:06:16 +0000 (0:00:00.022)       0:00:09.368 ********
2026-03-19 06:06:16.687824 | controller | ok: [compute-1]
2026-03-19 06:06:17.991927 | controller |
2026-03-19 06:06:17.991957 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reset state of failed systemd units] *****
2026-03-19 06:06:17.991965 | controller | Thursday 19 March 2026  06:06:16 +0000 (0:00:00.440)       0:00:09.809 ********
2026-03-19 06:06:17.991981 | controller | changed: [compute-1] => (item=tripleo_nova_libvirt.target)
2026-03-19 06:06:18.024733 | controller | changed: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2026-03-19 06:06:18.024759 | controller | changed: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2026-03-19 06:06:18.024769 | controller | changed: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2026-03-19 06:06:18.024777 | controller | changed: [compute-1] => (item=tripleo_nova_virtqemud.service)
2026-03-19 06:06:18.024785 | controller | changed: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2026-03-19 06:06:18.024793 | controller | changed: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2026-03-19 06:06:18.024801 | controller |
2026-03-19 06:06:18.024809 | controller | TASK [osp.edpm.edpm_libvirt : Ensure libvirt user] *****************************
2026-03-19 06:06:18.024816 | controller | Thursday 19 March 2026  06:06:17 +0000 (0:00:01.303)       0:00:11.113 ********
2026-03-19 06:06:18.024829 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/collections/ansible_collections/osp/edpm/roles/edpm_libvirt/tasks/user.yml for compute-1
2026-03-19 06:06:18.045143 | controller |
2026-03-19 06:06:18.045177 | controller | TASK [osp.edpm.edpm_users : Validating arguments against arg spec 'main' - The main entry point for the edpm_users role.] ***
2026-03-19 06:06:18.045188 | controller | Thursday 19 March 2026  06:06:18 +0000 (0:00:00.033)       0:00:11.146 ********
2026-03-19 06:06:18.045198 | controller | ok: [compute-1]
2026-03-19 06:06:18.067864 | controller |
2026-03-19 06:06:18.067883 | controller | TASK [osp.edpm.edpm_users : Gather user fact] **********************************
2026-03-19 06:06:18.067899 | controller | Thursday 19 March 2026  06:06:18 +0000 (0:00:00.020)       0:00:11.167 ********
2026-03-19 06:06:18.067911 | controller | skipping: [compute-1]
2026-03-19 06:06:18.099529 | controller |
2026-03-19 06:06:18.099548 | controller | TASK [osp.edpm.edpm_users : Create host users and groups] **********************
2026-03-19 06:06:18.099556 | controller | Thursday 19 March 2026  06:06:18 +0000 (0:00:00.022)       0:00:11.189 ********
2026-03-19 06:06:18.099568 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/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'})
2026-03-19 06:06:18.099728 | controller |
2026-03-19 06:06:18.099892 | controller | TASK [osp.edpm.edpm_users : Detect when user exists and cannot be created] *****
2026-03-19 06:06:18.100059 | controller | Thursday 19 March 2026  06:06:18 +0000 (0:00:00.031)       0:00:11.221 ********
2026-03-19 06:06:18.384765 | controller | fatal: [compute-1]: FAILED! => changed=false
2026-03-19 06:06:18.394931 | controller |   msg: One or more supplied key could not be found in the database.
2026-03-19 06:06:18.394951 | controller | ...ignoring
2026-03-19 06:06:18.737122 | controller |
2026-03-19 06:06:18.737152 | controller | TASK [osp.edpm.edpm_users : Ensure group is present on the host [ libvirt ]] ***
2026-03-19 06:06:18.737177 | controller | Thursday 19 March 2026  06:06:18 +0000 (0:00:00.295)       0:00:11.516 ********
2026-03-19 06:06:18.737192 | controller | changed: [compute-1]
2026-03-19 06:06:19.144781 | controller |
2026-03-19 06:06:19.144818 | controller | TASK [osp.edpm.edpm_users : Ensure user is present on the host [ libvirt ]] ****
2026-03-19 06:06:19.144826 | controller | Thursday 19 March 2026  06:06:18 +0000 (0:00:00.341)       0:00:11.858 ********
2026-03-19 06:06:19.144837 | controller | changed: [compute-1]
2026-03-19 06:06:19.167985 | controller |
2026-03-19 06:06:19.168008 | controller | TASK [osp.edpm.edpm_users : Create host directories] ***************************
2026-03-19 06:06:19.168016 | controller | Thursday 19 March 2026  06:06:19 +0000 (0:00:00.407)       0:00:12.266 ********
2026-03-19 06:06:19.168026 | controller | skipping: [compute-1]
2026-03-19 06:06:19.217022 | controller |
2026-03-19 06:06:19.217048 | controller | TASK [osp.edpm.edpm_libvirt : Install libvirt] *********************************
2026-03-19 06:06:19.217056 | controller | Thursday 19 March 2026  06:06:19 +0000 (0:00:00.023)       0:00:12.290 ********
2026-03-19 06:06:19.217066 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/collections/ansible_collections/osp/edpm/roles/edpm_libvirt/tasks/install.yml for compute-1
2026-03-19 06:06:19.241681 | controller |
2026-03-19 06:06:19.241701 | controller | TASK [osp.edpm.edpm_libvirt : Gather ansible_local facts] **********************
2026-03-19 06:06:19.241709 | controller | Thursday 19 March 2026  06:06:19 +0000 (0:00:00.048)       0:00:12.339 ********
2026-03-19 06:06:19.241718 | controller | skipping: [compute-1]
2026-03-19 06:07:31.751421 | controller |
2026-03-19 06:07:31.751465 | controller | TASK [osp.edpm.edpm_libvirt : Install libvirt packages] ************************
2026-03-19 06:07:31.751474 | controller | Thursday 19 March 2026  06:06:19 +0000 (0:00:00.024)       0:00:12.363 ********
2026-03-19 06:07:31.751484 | controller | changed: [compute-1]
2026-03-19 06:07:34.042174 | controller |
2026-03-19 06:07:34.042204 | controller | TASK [osp.edpm.edpm_libvirt : Ensure monolithic libvirt and tcp socket activation is not enabled or running] ***
2026-03-19 06:07:34.042213 | controller | Thursday 19 March 2026  06:07:31 +0000 (0:01:12.509)       0:01:24.872 ********
2026-03-19 06:07:34.042224 | controller | changed: [compute-1] => (item=libvirtd)
2026-03-19 06:07:36.454870 | controller | changed: [compute-1] => (item=libvirtd-tcp.socket)
2026-03-19 06:07:36.454900 | controller | changed: [compute-1] => (item=libvirtd-tls.socket)
2026-03-19 06:07:36.454907 | controller | changed: [compute-1] => (item=virtproxyd-tcp.socket)
2026-03-19 06:07:36.454913 | controller |
2026-03-19 06:07:36.454919 | controller | TASK [osp.edpm.edpm_libvirt : Ensure libvirt services are enabled and running] ***
2026-03-19 06:07:36.454925 | controller | Thursday 19 March 2026  06:07:34 +0000 (0:00:02.290)       0:01:27.163 ********
2026-03-19 06:07:36.454935 | controller | changed: [compute-1] => (item=virtlogd)
2026-03-19 06:07:36.455546 | controller | changed: [compute-1] => (item=virtnodedevd)
2026-03-19 06:07:37.032114 | controller | changed: [compute-1] => (item=virtproxyd)
2026-03-19 06:07:37.032146 | controller | ok: [compute-1] => (item=virtqemud)
2026-03-19 06:07:37.032154 | controller | changed: [compute-1] => (item=virtsecretd)
2026-03-19 06:07:37.032197 | controller |
2026-03-19 06:07:37.032209 | controller | TASK [osp.edpm.edpm_libvirt : Configure virtproxyd-tls.socket] *****************
2026-03-19 06:07:37.032215 | controller | Thursday 19 March 2026  06:07:36 +0000 (0:00:02.413)       0:01:29.576 ********
2026-03-19 06:07:37.032226 | controller | changed: [compute-1]
2026-03-19 06:07:37.032248 | controller |
2026-03-19 06:07:37.032322 | controller | TASK [osp.edpm.edpm_libvirt : Configure socket activation for libvirt services] ***
2026-03-19 06:07:37.032475 | controller | Thursday 19 March 2026  06:07:37 +0000 (0:00:00.577)       0:01:30.154 ********
2026-03-19 06:07:41.468247 | controller | ok: [compute-1] => (item=['virtlogd', ''])
2026-03-19 06:07:41.469766 | controller | skipping: [compute-1] => (item=['virtlogd', '-ro'])
2026-03-19 06:07:41.510068 | controller | ok: [compute-1] => (item=['virtlogd', '-admin'])
2026-03-19 06:07:41.510091 | controller | ok: [compute-1] => (item=['virtnodedevd', ''])
2026-03-19 06:07:41.510101 | controller | ok: [compute-1] => (item=['virtnodedevd', '-ro'])
2026-03-19 06:07:41.510108 | controller | ok: [compute-1] => (item=['virtnodedevd', '-admin'])
2026-03-19 06:07:41.510115 | controller | ok: [compute-1] => (item=['virtproxyd', ''])
2026-03-19 06:07:41.510121 | controller | ok: [compute-1] => (item=['virtproxyd', '-ro'])
2026-03-19 06:07:41.510128 | controller | ok: [compute-1] => (item=['virtproxyd', '-admin'])
2026-03-19 06:07:41.510135 | controller | ok: [compute-1] => (item=['virtqemud', ''])
2026-03-19 06:07:41.510142 | controller | ok: [compute-1] => (item=['virtqemud', '-ro'])
2026-03-19 06:07:41.510148 | controller | ok: [compute-1] => (item=['virtqemud', '-admin'])
2026-03-19 06:07:41.510155 | controller | ok: [compute-1] => (item=['virtsecretd', ''])
2026-03-19 06:07:41.510185 | controller | ok: [compute-1] => (item=['virtsecretd', '-ro'])
2026-03-19 06:07:41.510195 | controller | ok: [compute-1] => (item=['virtsecretd', '-admin'])
2026-03-19 06:07:41.510202 | controller |
2026-03-19 06:07:41.510209 | controller | TASK [osp.edpm.edpm_libvirt : Configure libvirt] *******************************
2026-03-19 06:07:41.510217 | controller | Thursday 19 March 2026  06:07:41 +0000 (0:00:04.436)       0:01:34.590 ********
2026-03-19 06:07:41.510228 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/collections/ansible_collections/osp/edpm/roles/edpm_libvirt/tasks/configure.yml for compute-1
2026-03-19 06:07:41.543686 | controller |
2026-03-19 06:07:41.543706 | controller | TASK [osp.edpm.edpm_libvirt : Get libvirt password] ****************************
2026-03-19 06:07:41.543718 | controller | Thursday 19 March 2026  06:07:41 +0000 (0:00:00.041)       0:01:34.631 ********
2026-03-19 06:07:41.543727 | controller | ok: [compute-1]
2026-03-19 06:07:41.567261 | controller |
2026-03-19 06:07:41.567282 | controller | TASK [osp.edpm.edpm_libvirt : Gather user fact] ********************************
2026-03-19 06:07:41.567291 | controller | Thursday 19 March 2026  06:07:41 +0000 (0:00:00.033)       0:01:34.665 ********
2026-03-19 06:07:41.567301 | controller | skipping: [compute-1]
2026-03-19 06:07:42.707283 | controller |
2026-03-19 06:07:42.707312 | controller | TASK [osp.edpm.edpm_libvirt : Create libvirt config dir] ***********************
2026-03-19 06:07:42.707320 | controller | Thursday 19 March 2026  06:07:41 +0000 (0:00:00.023)       0:01:34.689 ********
2026-03-19 06:07:42.707331 | controller | changed: [compute-1] => (item={'path': '/etc/tmpfiles.d/', 'owner': 'root', 'group': 'root'})
2026-03-19 06:07:42.728122 | controller | changed: [compute-1] => (item={'path': '/var/lib/edpm-config/firewall', 'owner': 'root', 'group': 'root'})
2026-03-19 06:07:42.728140 | controller | changed: [compute-1] => (item={'path': '/etc/pki/libvirt', 'owner': 'root', 'group': 'root', 'mode': '0755'})
2026-03-19 06:07:42.728148 | controller | changed: [compute-1] => (item={'path': '/etc/pki/libvirt/private', 'owner': 'root', 'group': 'root', 'mode': '0755'})
2026-03-19 06:07:42.728154 | controller | changed: [compute-1] => (item={'path': '/etc/pki/CA', 'owner': 'root', 'group': 'root', 'mode': '0755'})
2026-03-19 06:07:42.728186 | controller | changed: [compute-1] => (item={'path': '/etc/pki/qemu', 'owner': 'root', 'group': 'qemu'})
2026-03-19 06:07:42.728196 | controller |
2026-03-19 06:07:42.728202 | controller | TASK [osp.edpm.edpm_libvirt : Gather SELinux fact if needed] *******************
2026-03-19 06:07:42.728213 | controller | Thursday 19 March 2026  06:07:42 +0000 (0:00:01.139)       0:01:35.828 ********
2026-03-19 06:07:42.728222 | controller | skipping: [compute-1]
2026-03-19 06:07:45.890244 | controller |
2026-03-19 06:07:45.890274 | controller | TASK [osp.edpm.edpm_libvirt : Render libvirt config files] *********************
2026-03-19 06:07:45.890283 | controller | Thursday 19 March 2026  06:07:42 +0000 (0:00:00.021)       0:01:35.850 ********
2026-03-19 06:07:45.890293 | controller | changed: [compute-1] => (item={'src': 'virtlogd.conf', 'dest': 'libvirt/virtlogd.conf'})
2026-03-19 06:07:45.891032 | controller | changed: [compute-1] => (item={'src': 'virtnodedevd.conf', 'dest': 'libvirt/virtnodedevd.conf'})
2026-03-19 06:07:45.891060 | controller | changed: [compute-1] => (item={'src': 'virtproxyd.conf', 'dest': 'libvirt/virtproxyd.conf'})
2026-03-19 06:07:45.891071 | controller | changed: [compute-1] => (item={'src': 'virtqemud.conf', 'dest': 'libvirt/virtqemud.conf'})
2026-03-19 06:07:46.113243 | controller | changed: [compute-1] => (item={'src': 'qemu.conf.j2', 'dest': 'libvirt/qemu.conf'})
2026-03-19 06:07:46.113272 | controller | changed: [compute-1] => (item={'src': 'virtsecretd.conf', 'dest': 'libvirt/virtsecretd.conf'})
2026-03-19 06:07:46.113280 | controller | changed: [compute-1] => (item={'src': 'auth.conf', 'dest': 'libvirt/auth.conf', 'mode': '0600'})
2026-03-19 06:07:46.113286 | controller | changed: [compute-1] => (item={'src': 'sasl_libvirt.conf', 'dest': 'sasl2/libvirt.conf'})
2026-03-19 06:07:46.113292 | controller |
2026-03-19 06:07:46.113297 | controller | TASK [osp.edpm.edpm_libvirt : Add libvirt password to sasl db] *****************
2026-03-19 06:07:46.113303 | controller | Thursday 19 March 2026  06:07:45 +0000 (0:00:03.161)       0:01:39.012 ********
2026-03-19 06:07:46.113313 | controller | changed: [compute-1]
2026-03-19 06:07:46.143552 | controller |
2026-03-19 06:07:46.143579 | controller | TASK [osp.edpm.edpm_libvirt : Remove libvirt sasl db] **************************
2026-03-19 06:07:46.143587 | controller | Thursday 19 March 2026  06:07:46 +0000 (0:00:00.223)       0:01:39.235 ********
2026-03-19 06:07:46.143598 | controller | skipping: [compute-1]
2026-03-19 06:07:48.840233 | controller |
2026-03-19 06:07:48.840263 | controller | TASK [osp.edpm.edpm_libvirt : Create libvirt socket activation drop-in directories] ***
2026-03-19 06:07:48.840271 | controller | Thursday 19 March 2026  06:07:46 +0000 (0:00:00.030)       0:01:39.265 ********
2026-03-19 06:07:48.840282 | controller | changed: [compute-1] => (item=['virtlogd', ''])
2026-03-19 06:07:48.841635 | controller | skipping: [compute-1] => (item=['virtlogd', '-ro'])
2026-03-19 06:07:48.841669 | controller | changed: [compute-1] => (item=['virtlogd', '-admin'])
2026-03-19 06:07:53.764250 | controller | changed: [compute-1] => (item=['virtnodedevd', ''])
2026-03-19 06:07:53.764280 | controller | changed: [compute-1] => (item=['virtnodedevd', '-ro'])
2026-03-19 06:07:53.764288 | controller | changed: [compute-1] => (item=['virtnodedevd', '-admin'])
2026-03-19 06:07:53.764294 | controller | changed: [compute-1] => (item=['virtproxyd', ''])
2026-03-19 06:07:53.764299 | controller | changed: [compute-1] => (item=['virtproxyd', '-ro'])
2026-03-19 06:07:53.764305 | controller | changed: [compute-1] => (item=['virtproxyd', '-admin'])
2026-03-19 06:07:53.764310 | controller | changed: [compute-1] => (item=['virtqemud', ''])
2026-03-19 06:07:53.764316 | controller | changed: [compute-1] => (item=['virtqemud', '-ro'])
2026-03-19 06:07:53.764321 | controller | changed: [compute-1] => (item=['virtqemud', '-admin'])
2026-03-19 06:07:53.764327 | controller | changed: [compute-1] => (item=['virtsecretd', ''])
2026-03-19 06:07:53.764332 | controller | changed: [compute-1] => (item=['virtsecretd', '-ro'])
2026-03-19 06:07:53.764337 | controller | changed: [compute-1] => (item=['virtsecretd', '-admin'])
2026-03-19 06:07:53.764343 | controller |
2026-03-19 06:07:53.764349 | controller | TASK [osp.edpm.edpm_libvirt : Render libvirt socket activation drop-in files] ***
2026-03-19 06:07:53.764355 | controller | Thursday 19 March 2026  06:07:48 +0000 (0:00:02.696)       0:01:41.962 ********
2026-03-19 06:07:53.764368 | controller | changed: [compute-1] => (item=['virtlogd', ''])
2026-03-19 06:07:53.764391 | controller | skipping: [compute-1] => (item=['virtlogd', '-ro'])
2026-03-19 06:07:53.764397 | controller | changed: [compute-1] => (item=['virtlogd', '-admin'])
2026-03-19 06:07:53.764403 | controller | changed: [compute-1] => (item=['virtnodedevd', ''])
2026-03-19 06:07:53.764409 | controller | changed: [compute-1] => (item=['virtnodedevd', '-ro'])
2026-03-19 06:07:53.764421 | controller | changed: [compute-1] => (item=['virtnodedevd', '-admin'])
2026-03-19 06:07:53.764428 | controller | changed: [compute-1] => (item=['virtproxyd', ''])
2026-03-19 06:07:53.765235 | controller | changed: [compute-1] => (item=['virtproxyd', '-ro'])
2026-03-19 06:07:53.765271 | controller | changed: [compute-1] => (item=['virtproxyd', '-admin'])
2026-03-19 06:07:53.956827 | controller | changed: [compute-1] => (item=['virtqemud', ''])
2026-03-19 06:07:53.956856 | controller | changed: [compute-1] => (item=['virtqemud', '-ro'])
2026-03-19 06:07:53.956864 | controller | changed: [compute-1] => (item=['virtqemud', '-admin'])
2026-03-19 06:07:53.956870 | controller | changed: [compute-1] => (item=['virtsecretd', ''])
2026-03-19 06:07:53.956875 | controller | changed: [compute-1] => (item=['virtsecretd', '-ro'])
2026-03-19 06:07:53.956880 | controller | changed: [compute-1] => (item=['virtsecretd', '-admin'])
2026-03-19 06:07:53.956886 | controller |
2026-03-19 06:07:53.956892 | controller | TASK [osp.edpm.edpm_libvirt : Check if /run/libvirt has container_file_t selinux label] ***
2026-03-19 06:07:53.956897 | controller | Thursday 19 March 2026  06:07:53 +0000 (0:00:04.923)       0:01:46.885 ********
2026-03-19 06:07:53.956908 | controller | ok: [compute-1]
2026-03-19 06:07:53.985730 | controller |
2026-03-19 06:07:53.985747 | controller | TASK [osp.edpm.edpm_libvirt : Restore selinux label for /run/libvirt to virt_var_run_t] ***
2026-03-19 06:07:53.985754 | controller | Thursday 19 March 2026  06:07:53 +0000 (0:00:00.192)       0:01:47.078 ********
2026-03-19 06:07:53.985763 | controller | skipping: [compute-1]
2026-03-19 06:07:55.029591 | controller |
2026-03-19 06:07:55.029622 | controller | TASK [osp.edpm.edpm_libvirt : Enable os_enable_vtpm SELinux boolean for vTPM] ***
2026-03-19 06:07:55.029631 | controller | Thursday 19 March 2026  06:07:53 +0000 (0:00:00.028)       0:01:47.107 ********
2026-03-19 06:07:55.029647 | controller | changed: [compute-1]
2026-03-19 06:07:56.004155 | controller |
2026-03-19 06:07:56.004202 | controller | TASK [osp.edpm.edpm_libvirt : Move libvirt TLS files to the right location on the compute node] ***
2026-03-19 06:07:56.004210 | controller | Thursday 19 March 2026  06:07:55 +0000 (0:00:01.043)       0:01:48.151 ********
2026-03-19 06:07:56.004221 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.crt', 'dest': '/etc/pki/libvirt/servercert.pem'})
2026-03-19 06:07:56.976311 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.key', 'dest': '/etc/pki/libvirt/private/serverkey.pem', 'mode': '0600'})
2026-03-19 06:07:56.976345 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.crt', 'dest': '/etc/pki/libvirt/clientcert.pem'})
2026-03-19 06:07:56.976355 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.key', 'dest': '/etc/pki/libvirt/private/clientkey.pem'})
2026-03-19 06:07:56.976362 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/ca.crt', 'dest': '/etc/pki/CA/cacert.pem'})
2026-03-19 06:07:56.976369 | controller |
2026-03-19 06:07:56.976377 | controller | TASK [osp.edpm.edpm_libvirt : Move qemu TLS files to the right location on the compute node] ***
2026-03-19 06:07:56.976384 | controller | Thursday 19 March 2026  06:07:56 +0000 (0:00:00.974)       0:01:49.125 ********
2026-03-19 06:07:56.976396 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.crt', 'dest': '/etc/pki/qemu/server-cert.pem'})
2026-03-19 06:07:56.976425 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.key', 'dest': '/etc/pki/qemu/server-key.pem'})
2026-03-19 06:07:56.976433 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.crt', 'dest': '/etc/pki/qemu/client-cert.pem'})
2026-03-19 06:07:56.976440 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.key', 'dest': '/etc/pki/qemu/client-key.pem'})
2026-03-19 06:07:56.976447 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/ca.crt', 'dest': '/etc/pki/qemu/ca-cert.pem'})
2026-03-19 06:07:56.976454 | controller |
2026-03-19 06:07:56.976460 | controller | TASK [osp.edpm.edpm_libvirt : Flush handlers] **********************************
2026-03-19 06:07:56.976467 | controller | Thursday 19 March 2026  06:07:56 +0000 (0:00:00.959)       0:01:50.085 ********
2026-03-19 06:07:56.976476 | controller |
2026-03-19 06:07:56.976553 | controller | RUNNING HANDLER [osp.edpm.edpm_libvirt : Restart libvirt services] *************
2026-03-19 06:07:56.976590 | controller | Thursday 19 March 2026  06:07:56 +0000 (0:00:00.012)       0:01:50.097 ********
2026-03-19 06:07:59.714762 | controller | changed: [compute-1] => (item=virtlogd)
2026-03-19 06:07:59.758063 | controller | changed: [compute-1] => (item=virtnodedevd)
2026-03-19 06:07:59.758089 | controller | changed: [compute-1] => (item=virtproxyd)
2026-03-19 06:07:59.758097 | controller | changed: [compute-1] => (item=virtqemud)
2026-03-19 06:07:59.758103 | controller | changed: [compute-1] => (item=virtsecretd)
2026-03-19 06:07:59.758108 | controller |
2026-03-19 06:07:59.758115 | controller | TASK [osp.edpm.edpm_libvirt : Post-install libvirt] ****************************
2026-03-19 06:07:59.758120 | controller | Thursday 19 March 2026  06:07:59 +0000 (0:00:02.738)       0:01:52.836 ********
2026-03-19 06:07:59.758130 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/collections/ansible_collections/osp/edpm/roles/edpm_libvirt/tasks/post-install.yml for compute-1
2026-03-19 06:07:59.973149 | controller |
2026-03-19 06:07:59.973192 | controller | TASK [osp.edpm.edpm_libvirt : Disable libvirt default network autostart] *******
2026-03-19 06:07:59.973201 | controller | Thursday 19 March 2026  06:07:59 +0000 (0:00:00.043)       0:01:52.880 ********
2026-03-19 06:07:59.973211 | controller | fatal: [compute-1]: FAILED! => changed=false
2026-03-19 06:07:59.976224 | controller |   cmd:
2026-03-19 06:07:59.976244 | controller |   - virsh
2026-03-19 06:07:59.976251 | controller |   - net-autostart
2026-03-19 06:07:59.976257 | controller |   - --network
2026-03-19 06:07:59.976263 | controller |   - default
2026-03-19 06:07:59.976268 | controller |   - --disable
2026-03-19 06:07:59.976274 | controller |   delta: '0:00:00.019725'
2026-03-19 06:07:59.976280 | controller |   end: '2026-03-19 06:07:59.942267'
2026-03-19 06:07:59.976286 | controller |   failed_when_result: true
2026-03-19 06:07:59.976291 | controller |   msg: non-zero return code
2026-03-19 06:07:59.976297 | controller |   rc: 1
2026-03-19 06:07:59.976303 | controller |   start: '2026-03-19 06:07:59.922542'
2026-03-19 06:07:59.976308 | controller |   stderr: |-
2026-03-19 06:07:59.976313 | controller |     error: failed to get network 'default'
2026-03-19 06:07:59.976319 | controller |     error: Failed to connect socket to '/var/run/libvirt/virtnetworkd-sock': No such file or directory
2026-03-19 06:07:59.976325 | controller |   stderr_lines: <omitted>
2026-03-19 06:07:59.976331 | controller |   stdout: ''
2026-03-19 06:07:59.976336 | controller |   stdout_lines: <omitted>
2026-03-19 06:07:59.976345 | controller |
2026-03-19 06:07:59.976434 | controller | PLAY RECAP *********************************************************************
2026-03-19 06:07:59.976444 | controller | compute-1                  : ok=44   changed=16   unreachable=0    failed=1    skipped=14   rescued=0    ignored=1
2026-03-19 06:07:59.976451 | controller |
2026-03-19 06:07:59.976456 | controller | Thursday 19 March 2026  06:07:59 +0000 (0:00:00.217)       0:01:53.097 ********
2026-03-19 06:07:59.976462 | controller | ===============================================================================
2026-03-19 06:07:59.976467 | controller | osp.edpm.edpm_libvirt : Install libvirt packages ----------------------- 72.51s
2026-03-19 06:07:59.976473 | controller | osp.edpm.edpm_libvirt : Render libvirt socket activation drop-in files --- 4.92s
2026-03-19 06:07:59.976478 | controller | osp.edpm.edpm_libvirt : Configure socket activation for libvirt services --- 4.44s
2026-03-19 06:07:59.976483 | controller | osp.edpm.edpm_libvirt : Render libvirt config files --------------------- 3.16s
2026-03-19 06:07:59.976489 | controller | osp.edpm.edpm_libvirt : Restart libvirt services ------------------------ 2.74s
2026-03-19 06:07:59.976497 | controller | osp.edpm.edpm_libvirt : Create libvirt socket activation drop-in directories --- 2.70s
2026-03-19 06:07:59.976653 | controller | osp.edpm.edpm_libvirt : Ensure libvirt services are enabled and running --- 2.41s
2026-03-19 06:07:59.976792 | controller | osp.edpm.edpm_libvirt : Ensure monolithic libvirt and tcp socket activation is not enabled or running --- 2.29s
2026-03-19 06:07:59.976925 | controller | osp.edpm.edpm_tripleo_cleanup : Stop and disable tripleo services ------- 1.97s
2026-03-19 06:07:59.977053 | controller | osp.edpm.edpm_tripleo_cleanup : Gather service facts -------------------- 1.81s
2026-03-19 06:07:59.977207 | controller | osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /usr/lib/systemd/system --- 1.53s
2026-03-19 06:07:59.977358 | controller | osp.edpm.edpm_tripleo_cleanup : Reset state of failed systemd units ----- 1.30s
2026-03-19 06:07:59.981635 | controller | osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /etc/systemd/system --- 1.30s
2026-03-19 06:07:59.981794 | controller | osp.edpm.edpm_libvirt : Create libvirt config dir ----------------------- 1.14s
2026-03-19 06:07:59.981930 | controller | osp.edpm.edpm_libvirt : Enable os_enable_vtpm SELinux boolean for vTPM --- 1.04s
2026-03-19 06:07:59.982063 | controller | osp.edpm.edpm_libvirt : Move libvirt TLS files to the right location on the compute node --- 0.97s
2026-03-19 06:07:59.982224 | controller | osp.edpm.edpm_libvirt : Move qemu TLS files to the right location on the compute node --- 0.96s
2026-03-19 06:07:59.982362 | controller | Gathering Facts --------------------------------------------------------- 0.76s
2026-03-19 06:07:59.982490 | controller | osp.edpm.edpm_tripleo_cleanup : Reload units to check for new changes --- 0.67s
2026-03-19 06:07:59.982631 | controller | osp.edpm.edpm_libvirt : Configure virtproxyd-tls.socket ----------------- 0.58s
2026-03-19 06:08:00.037116 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/edpm_libvirt/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/roles/edpm_libvirt/molecule/default/converge.yml']
2026-03-19 06:08:00.041126 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2026-03-19 06:08:00.041236 | controller | INFO     Running default > cleanup
2026-03-19 06:08:00.041807 | controller | WARNING  Skipping, cleanup playbook not configured.
2026-03-19 06:08:00.049328 | controller | INFO     Running default > destroy
2026-03-19 06:08:00.049708 | controller | WARNING  Skipping, instances are delegated.
2026-03-19 06:08:00.050045 | controller | INFO     Pruning extra files from scenario ephemeral directory
2026-03-19 06:08:00.051575 | controller | INFO     Writing /tmp/report.html report.
2026-03-19 06:08:00.540572 | controller | ERROR
2026-03-19 06:08:00.540690 | controller | {
2026-03-19 06:08:00.540723 | controller |   "delta": "0:04:17.552797",
2026-03-19 06:08:00.540745 | controller |   "end": "2026-03-19 06:08:00.093599",
2026-03-19 06:08:00.540764 | controller |   "msg": "non-zero return code",
2026-03-19 06:08:00.540782 | controller |   "rc": 1,
2026-03-19 06:08:00.540799 | controller |   "start": "2026-03-19 06:03:42.540802"
2026-03-19 06:08:00.540815 | controller | }
failure
2026-03-19 06:08:00.586966 | 
2026-03-19 06:08:00.587017 | PLAY RECAP
2026-03-19 06:08:00.587060 | controller | ok: 3 changed: 1 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-03-19 06:08:00.587082 | 
2026-03-19 06:08:00.653751 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-03-19 06:08:00.654566 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-19 06:08:01.162216 | 
2026-03-19 06:08:01.162338 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-03-19 06:08:01.182158 | 
2026-03-19 06:08:01.182229 | TASK [Filter out host if needed]
2026-03-19 06:08:01.190504 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-03-19 06:08:01.195026 | 
2026-03-19 06:08:01.195091 | TASK [Ensure file is present]
2026-03-19 06:08:01.486292 | controller | ok
2026-03-19 06:08:01.492702 | 
2026-03-19 06:08:01.492768 | TASK [Manage molecule report file]
2026-03-19 06:08:02.017515 | controller | changed
2026-03-19 06:08:02.023069 | 
2026-03-19 06:08:02.023132 | TASK [Check if we get ci-framework-data basedir]
2026-03-19 06:08:02.202953 | controller | ok
2026-03-19 06:08:02.207960 | 
2026-03-19 06:08:02.208021 | TASK [Create ci-framework-data log directory for zuul]
2026-03-19 06:08:02.509281 | controller | changed
2026-03-19 06:08:02.514404 | 
2026-03-19 06:08:02.514468 | TASK [Copy ci-framework interesting files]
2026-03-19 06:08:02.711885 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-03-19 06:08:03.040266 | controller | changed
2026-03-19 06:08:03.045530 | 
2026-03-19 06:08:03.045600 | TASK [Get SELinux listing]
2026-03-19 06:08:03.570153 | controller | changed
2026-03-19 06:08:03.575737 | 
2026-03-19 06:08:03.575805 | TASK [Generate log index]
2026-03-19 06:08:04.192028 | controller | changed
2026-03-19 06:08:04.196940 | 
2026-03-19 06:08:04.197012 | TASK [Get some env related data]
2026-03-19 06:08:04.780423 | controller | /home/zuul/.local/bin/ansible
2026-03-19 06:08:05.724448 | controller | changed
2026-03-19 06:08:05.731428 | 
2026-03-19 06:08:05.731512 | TASK [Generate list of logs to collect in home directory]
2026-03-19 06:08:06.018800 | controller | ok: All paths examined
2026-03-19 06:08:06.025041 | 
2026-03-19 06:08:06.025133 | LOOP [Copy logs from home directory]
2026-03-19 06:08:06.362741 | controller | changed:
2026-03-19 06:08:06.362968 | controller | {
2026-03-19 06:08:06.363006 | controller |   "atime": 1773900204.5119388,
2026-03-19 06:08:06.363027 | controller |   "ctime": 1773900220.3685725,
2026-03-19 06:08:06.363044 | controller |   "dev": 64513,
2026-03-19 06:08:06.363062 | controller |   "gid": 1000,
2026-03-19 06:08:06.363078 | controller |   "gr_name": "zuul",
2026-03-19 06:08:06.363095 | controller |   "inode": 82938,
2026-03-19 06:08:06.363110 | controller |   "isblk": false,
2026-03-19 06:08:06.363125 | controller |   "ischr": false,
2026-03-19 06:08:06.363140 | controller |   "isdir": false,
2026-03-19 06:08:06.363155 | controller |   "isfifo": false,
2026-03-19 06:08:06.363171 | controller |   "isgid": false,
2026-03-19 06:08:06.363185 | controller |   "islnk": false,
2026-03-19 06:08:06.363199 | controller |   "isreg": true,
2026-03-19 06:08:06.363213 | controller |   "issock": false,
2026-03-19 06:08:06.363226 | controller |   "isuid": false,
2026-03-19 06:08:06.363240 | controller |   "mode": "0644",
2026-03-19 06:08:06.363253 | controller |   "mtime": 1773900220.3685725,
2026-03-19 06:08:06.363267 | controller |   "nlink": 1,
2026-03-19 06:08:06.363281 | controller |   "path": "/home/zuul/ansible.log",
2026-03-19 06:08:06.363346 | controller |   "pw_name": "zuul",
2026-03-19 06:08:06.363369 | controller |   "rgrp": true,
2026-03-19 06:08:06.363386 | controller |   "roth": true,
2026-03-19 06:08:06.363402 | controller |   "rusr": true,
2026-03-19 06:08:06.363418 | controller |   "size": 6749,
2026-03-19 06:08:06.363432 | controller |   "uid": 1000,
2026-03-19 06:08:06.363448 | controller |   "wgrp": false,
2026-03-19 06:08:06.363462 | controller |   "woth": false,
2026-03-19 06:08:06.363479 | controller |   "wusr": true,
2026-03-19 06:08:06.363494 | controller |   "xgrp": false,
2026-03-19 06:08:06.363508 | controller |   "xoth": false,
2026-03-19 06:08:06.363522 | controller |   "xusr": false
2026-03-19 06:08:06.363536 | controller | }
2026-03-19 06:08:06.377072 | 
2026-03-19 06:08:06.377235 | TASK [Copy crio stats log file]
2026-03-19 06:08:06.405407 | controller | skipping: Conditional result was False
2026-03-19 06:08:06.412059 | 
2026-03-19 06:08:06.412147 | TASK [Get SELinux related data]
2026-03-19 06:08:06.631410 | controller | <no matches>
2026-03-19 06:08:06.937888 | controller | ERROR
2026-03-19 06:08:06.938126 | controller | {
2026-03-19 06:08:06.938158 | controller |   "delta": "0:00:00.008736",
2026-03-19 06:08:06.938179 | controller |   "end": "2026-03-19 06:08:06.632330",
2026-03-19 06:08:06.938197 | controller |   "msg": "non-zero return code",
2026-03-19 06:08:06.938212 | controller |   "rc": 1,
2026-03-19 06:08:06.938228 | controller |   "start": "2026-03-19 06:08:06.623594"
2026-03-19 06:08:06.938243 | controller | }
2026-03-19 06:08:06.938263 | controller | ERROR: Ignoring Errors
2026-03-19 06:08:06.943498 | 
2026-03-19 06:08:06.943569 | TASK [Create system configuration directory]
2026-03-19 06:08:07.139327 | controller | changed
2026-03-19 06:08:07.144525 | 
2026-03-19 06:08:07.144609 | TASK [Get some of the system configurations]
2026-03-19 06:08:07.670133 | controller | changed
2026-03-19 06:08:07.676075 | 
2026-03-19 06:08:07.676158 | TASK [Copy generated documentation if available]
2026-03-19 06:08:07.691252 | controller | skipping: Conditional result was False
2026-03-19 06:08:07.697947 | 
2026-03-19 06:08:07.698031 | TASK [Copy generated AsciiDoc documentation if available]
2026-03-19 06:08:07.712988 | controller | skipping: Conditional result was False
2026-03-19 06:08:07.720667 | 
2026-03-19 06:08:07.720787 | TASK [Compress logs bigger than 2MB]
2026-03-19 06:08:08.245877 | controller | changed
2026-03-19 06:08:08.251286 | 
2026-03-19 06:08:08.251410 | TASK [Copy files from workspace on node]
2026-03-19 06:08:08.271668 | controller | ok
2026-03-19 06:08:08.294431 | 
2026-03-19 06:08:08.294508 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-19 06:08:08.309594 | controller | skipping: Conditional result was False
2026-03-19 06:08:08.315738 | 
2026-03-19 06:08:08.315808 | TASK [fetch-output : Set log path for single node]
2026-03-19 06:08:08.345373 | controller | ok
2026-03-19 06:08:08.351663 | 
2026-03-19 06:08:08.351732 | LOOP [fetch-output : Ensure local output dirs]
2026-03-19 06:08:08.554248 | controller -> localhost | ok: "/var/lib/zuul/builds/fcb564afd1274d53aac92c27178f19e3/work/logs"
2026-03-19 06:08:08.554634 | controller -> localhost | changed: All items complete
2026-03-19 06:08:08.554664 | 
2026-03-19 06:08:08.737794 | controller -> localhost | changed: "/var/lib/zuul/builds/fcb564afd1274d53aac92c27178f19e3/work/artifacts"
2026-03-19 06:08:08.919976 | controller -> localhost | changed: "/var/lib/zuul/builds/fcb564afd1274d53aac92c27178f19e3/work/docs"
2026-03-19 06:08:08.934903 | 
2026-03-19 06:08:08.934988 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-19 06:08:09.474716 | controller | changed:
2026-03-19 06:08:09.474913 | controller | .d..t...... ./
2026-03-19 06:08:09.474944 | controller | >f+++++++++ README.html
2026-03-19 06:08:09.474967 | controller | >f+++++++++ ansible-execution.log
2026-03-19 06:08:09.474987 | controller | >f+++++++++ ansible.log
2026-03-19 06:08:09.475005 | controller | >f+++++++++ dmesg.log
2026-03-19 06:08:09.475030 | controller | >f+++++++++ installed-pkgs.log
2026-03-19 06:08:09.475048 | controller | >f+++++++++ python.log
2026-03-19 06:08:09.475069 | controller | >f+++++++++ registries.conf
2026-03-19 06:08:09.475087 | controller | >f+++++++++ report.html
2026-03-19 06:08:09.475106 | controller | >f+++++++++ selinux-denials.log
2026-03-19 06:08:09.475123 | controller | >f+++++++++ selinux-listing.log
2026-03-19 06:08:09.475139 | controller | cd+++++++++ ci-framework-data/
2026-03-19 06:08:09.475154 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-03-19 06:08:09.475170 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-03-19 06:08:09.475186 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-03-19 06:08:09.475201 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-03-19 06:08:09.475216 | controller | cd+++++++++ ci-framework-data/logs/
2026-03-19 06:08:09.475233 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-03-19 06:08:09.475252 | controller | cd+++++++++ registries.conf.d/
2026-03-19 06:08:09.475272 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-03-19 06:08:09.475288 | controller | cd+++++++++ system-config/
2026-03-19 06:08:09.475358 | controller | cd+++++++++ system-config/libvirt/
2026-03-19 06:08:09.475380 | controller | >f+++++++++ system-config/libvirt/auth.conf
2026-03-19 06:08:09.475398 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-03-19 06:08:09.475415 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-03-19 06:08:09.475430 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2026-03-19 06:08:09.475446 | controller | >f+++++++++ system-config/libvirt/network.conf
2026-03-19 06:08:09.475460 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2026-03-19 06:08:09.475475 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2026-03-19 06:08:09.475490 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2026-03-19 06:08:09.475506 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2026-03-19 06:08:09.475521 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2026-03-19 06:08:09.475536 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2026-03-19 06:08:09.475551 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2026-03-19 06:08:09.475565 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2026-03-19 06:08:09.475579 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2026-03-19 06:08:09.475593 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2026-03-19 06:08:09.475607 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2026-03-19 06:08:09.475620 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2026-03-19 06:08:09.845804 | controller | changed: .d..t...... ./
2026-03-19 06:08:10.200934 | controller | changed: .d..t...... ./
2026-03-19 06:08:10.217872 | 
2026-03-19 06:08:10.217944 | TASK [Return artifact to Zuul]
2026-03-19 06:08:10.247140 | controller | ok
2026-03-19 06:08:10.266355 | 
2026-03-19 06:08:10.266428 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-03-19 06:08:10.266587 | 
2026-03-19 06:08:10.266615 | PLAY RECAP
2026-03-19 06:08:10.266651 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-03-19 06:08:10.266671 | 
2026-03-19 06:08:10.339424 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-19 06:08:10.340197 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-19 06:08:10.848785 | 
2026-03-19 06:08:10.848887 | PLAY [all]
2026-03-19 06:08:10.867418 | 
2026-03-19 06:08:10.867509 | TASK [include_role : fetch-output]
2026-03-19 06:08:10.895638 | controller | ok
2026-03-19 06:08:10.912125 | 
2026-03-19 06:08:10.912209 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-19 06:08:10.956106 | controller | skipping: Conditional result was False
2026-03-19 06:08:10.961420 | 
2026-03-19 06:08:10.961487 | TASK [fetch-output : Set log path for single node]
2026-03-19 06:08:10.989558 | controller | ok
2026-03-19 06:08:10.994409 | 
2026-03-19 06:08:10.994477 | LOOP [fetch-output : Ensure local output dirs]
2026-03-19 06:08:11.317717 | controller -> localhost | ok: "/var/lib/zuul/builds/fcb564afd1274d53aac92c27178f19e3/work/logs"
2026-03-19 06:08:11.501139 | controller -> localhost | ok: "/var/lib/zuul/builds/fcb564afd1274d53aac92c27178f19e3/work/artifacts"
2026-03-19 06:08:11.680600 | controller -> localhost | ok: "/var/lib/zuul/builds/fcb564afd1274d53aac92c27178f19e3/work/docs"
2026-03-19 06:08:11.692938 | 
2026-03-19 06:08:11.693014 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-19 06:08:12.186130 | controller | ok
2026-03-19 06:08:12.186389 | controller | ok: All items complete
2026-03-19 06:08:12.186427 | 
2026-03-19 06:08:12.542455 | controller | ok
2026-03-19 06:08:12.896171 | controller | ok
2026-03-19 06:08:12.910730 | 
2026-03-19 06:08:12.910830 | TASK [include_role : fetch-output-openshift]
2026-03-19 06:08:12.924187 | controller | skipping: Conditional result was False
2026-03-19 06:08:12.929510 | 
2026-03-19 06:08:12.929576 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-19 06:08:13.254492 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.005833
2026-03-19 06:08:13.437068 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005981
2026-03-19 06:08:13.463267 | 
2026-03-19 06:08:13.463377 | PLAY [all]
2026-03-19 06:08:13.476831 | 
2026-03-19 06:08:13.476901 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-19 06:08:13.860563 | controller | changed
2026-03-19 06:08:13.881425 | 
2026-03-19 06:08:13.881480 | PLAY RECAP
2026-03-19 06:08:13.881521 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-03-19 06:08:13.881544 | 
2026-03-19 06:08:13.945425 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-19 06:08:13.946106 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-03-19 06:08:14.478549 | 
2026-03-19 06:08:14.478646 | PLAY [localhost]
2026-03-19 06:08:14.495544 | 
2026-03-19 06:08:14.495618 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-19 06:08:14.786436 | localhost | changed
2026-03-19 06:08:14.790901 | 
2026-03-19 06:08:14.790990 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-19 06:08:14.808818 | localhost | ok
2026-03-19 06:08:14.816630 | 
2026-03-19 06:08:14.816702 | TASK [add-fileserver : Create SSH private key tempfile]
2026-03-19 06:08:15.108543 | localhost | changed
2026-03-19 06:08:15.113956 | 
2026-03-19 06:08:15.114026 | TASK [add-fileserver : Create SSH private key from secret]
2026-03-19 06:08:15.638724 | localhost | changed
2026-03-19 06:08:15.643621 | 
2026-03-19 06:08:15.643692 | TASK [add-fileserver : Add fileserver ssh key]
2026-03-19 06:08:15.965508 | localhost | Identity added: /var/lib/zuul/builds/fcb564afd1274d53aac92c27178f19e3/work/tmp/ansible.xoclnpci (/var/lib/zuul/builds/fcb564afd1274d53aac92c27178f19e3/work/tmp/ansible.xoclnpci)
2026-03-19 06:08:15.965673 | localhost | ok: Runtime: 0:00:00.006054
2026-03-19 06:08:15.969802 | 
2026-03-19 06:08:15.969870 | TASK [add-fileserver : Remove SSH private key from disk]
2026-03-19 06:08:16.179579 | localhost | ok: Runtime: 0:00:00.003849
2026-03-19 06:08:16.184087 | 
2026-03-19 06:08:16.184155 | TASK [add-fileserver : Add fileserver to inventory]
2026-03-19 06:08:16.232239 | localhost | changed
2026-03-19 06:08:16.237035 | 
2026-03-19 06:08:16.237099 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-03-19 06:08:16.537653 | localhost | changed
2026-03-19 06:08:16.557350 | 
2026-03-19 06:08:16.557438 | PLAY [localhost]
2026-03-19 06:08:16.568653 | 
2026-03-19 06:08:16.568714 | TASK [Generate bulk log download script]
2026-03-19 06:08:16.586546 | localhost | ok
2026-03-19 06:08:16.597405 | 
2026-03-19 06:08:16.597473 | TASK [local-log-download : Check API endpoint is defined]
2026-03-19 06:08:16.624482 | localhost | ok: All assertions passed
2026-03-19 06:08:16.628734 | 
2026-03-19 06:08:16.628797 | TASK [local-log-download : Create download script]
2026-03-19 06:08:16.965712 | localhost -> localhost | changed
2026-03-19 06:08:16.974185 | 
2026-03-19 06:08:16.974262 | TASK [Register quick-download link]
2026-03-19 06:08:16.991378 | localhost | ok
2026-03-19 06:08:17.024800 | 
2026-03-19 06:08:17.024869 | PLAY [logserver.rdoproject.org]
2026-03-19 06:08:17.034096 | 
2026-03-19 06:08:17.034157 | TASK [Set zuul-log-path fact]
2026-03-19 06:08:17.049685 | logserver.rdoproject.org | ok
2026-03-19 06:08:17.059443 | 
2026-03-19 06:08:17.059511 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-19 06:08:17.085870 | logserver.rdoproject.org | ok
2026-03-19 06:08:17.091042 | 
2026-03-19 06:08:17.091105 | TASK [upload-logs : Create log directories]
2026-03-19 06:08:18.832173 | logserver.rdoproject.org | changed
2026-03-19 06:08:18.835247 | 
2026-03-19 06:08:18.835333 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-19 06:08:19.050999 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004425
2026-03-19 06:08:19.055803 | 
2026-03-19 06:08:19.055866 | TASK [upload-logs : Upload logs to log server]
2026-03-19 06:08:20.462981 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-03-19 06:08:20.465840 | 
2026-03-19 06:08:20.465904 | LOOP [upload-logs : Compress console log and json output]
2026-03-19 06:08:20.502008 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-19 06:08:20.509992 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-19 06:08:20.523267 | 
2026-03-19 06:08:20.523382 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-19 06:08:20.557822 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-19 06:08:20.558027 | 
2026-03-19 06:08:20.561011 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-19 06:08:20.572154 | 
2026-03-19 06:08:20.572235 | LOOP [upload-logs : Upload console log and json output]