2026-03-18 11:51:09.494492 | Job console starting...
2026-03-18 11:51:09.708484 | Updating repositories
2026-03-18 11:51:10.423896 | Preparing job workspace
2026-03-18 11:51:17.663904 | Running Ansible setup...
2026-03-18 11:51:20.356594 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-18 11:51:20.827421 | 
2026-03-18 11:51:20.827520 | PLAY [localhost]
2026-03-18 11:51:20.835275 | 
2026-03-18 11:51:20.835369 | TASK [Gathering Facts]
2026-03-18 11:51:21.599585 | localhost | ok
2026-03-18 11:51:21.612055 | 
2026-03-18 11:51:21.612150 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-18 11:51:21.909103 | localhost -> localhost | changed
2026-03-18 11:51:21.914184 | 
2026-03-18 11:51:21.914258 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-18 11:51:22.534695 | localhost -> localhost | changed
2026-03-18 11:51:22.542006 | 
2026-03-18 11:51:22.542069 | TASK [Setup log path fact]
2026-03-18 11:51:22.558486 | localhost | ok
2026-03-18 11:51:22.568488 | 
2026-03-18 11:51:22.568556 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-18 11:51:22.595633 | localhost | ok
2026-03-18 11:51:22.602280 | 
2026-03-18 11:51:22.602370 | TASK [emit-job-header : Print job information]
2026-03-18 11:51:22.628700 | # Job Information
2026-03-18 11:51:22.628807 | Ansible Version: 2.15.12
2026-03-18 11:51:22.628835 | Job: edpm-ansible-molecule-edpm_libvirt
2026-03-18 11:51:22.628856 | Pipeline: github-check
2026-03-18 11:51:22.628875 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-03-18 11:51:22.628892 | Triggered by: https://github.com/openstack-k8s-operators/edpm-ansible/pull/1114
2026-03-18 11:51:22.628910 | Log URL (when completed): https://logserver.rdoproject.org/5ec/rdoproject.org/5ecbda745dce42cebfca4964d3a29418/
2026-03-18 11:51:22.628927 | Event ID: 9b833ad0-22c0-11f1-82f7-a36673af5101
2026-03-18 11:51:22.632279 | 
2026-03-18 11:51:22.632358 | LOOP [emit-job-header : Print node information]
2026-03-18 11:51:22.711708 | localhost | ok:
2026-03-18 11:51:22.711863 | localhost | # Node Information
2026-03-18 11:51:22.711891 | localhost | Inventory Hostname: controller
2026-03-18 11:51:22.711919 | localhost | Hostname: np0005650644
2026-03-18 11:51:22.711940 | localhost | Username: zuul
2026-03-18 11:51:22.711959 | localhost | Distro: CentOS 9
2026-03-18 11:51:22.711976 | localhost | Provider: ibm-bm4-nodepool
2026-03-18 11:51:22.711993 | localhost | Region: regionOne
2026-03-18 11:51:22.712008 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2026-03-18 11:51:22.712023 | localhost | Product Name: OpenStack Compute
2026-03-18 11:51:22.712038 | localhost | Interface IP: 192.168.26.48
2026-03-18 11:51:22.733450 | 
2026-03-18 11:51:22.733514 | PLAY [all]
2026-03-18 11:51:22.739120 | 
2026-03-18 11:51:22.739178 | TASK [Gather network facts]
2026-03-18 11:51:23.083753 | controller | ok
2026-03-18 11:51:23.096837 | 
2026-03-18 11:51:23.096895 | TASK [include_role : start-zuul-console]
2026-03-18 11:51:23.113758 | controller | ok
2026-03-18 11:51:23.124502 | 
2026-03-18 11:51:23.124561 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-18 11:51:23.405732 | controller | ok
2026-03-18 11:51:23.413165 | 
2026-03-18 11:51:23.413227 | TASK [include_role : add-build-sshkey]
2026-03-18 11:51:23.430007 | controller | ok
2026-03-18 11:51:23.441354 | 
2026-03-18 11:51:23.441428 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-18 11:51:23.616943 | controller -> localhost | ok
2026-03-18 11:51:23.622510 | 
2026-03-18 11:51:23.622586 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-18 11:51:23.639863 | controller | ok
2026-03-18 11:51:23.652890 | controller | included: /var/lib/zuul/builds/5ecbda745dce42cebfca4964d3a29418/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-18 11:51:23.658279 | 
2026-03-18 11:51:23.658359 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-18 11:51:24.005968 | controller -> localhost | Generating public/private rsa key pair.
2026-03-18 11:51:24.006135 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5ecbda745dce42cebfca4964d3a29418/work/5ecbda745dce42cebfca4964d3a29418_id_rsa.
2026-03-18 11:51:24.006168 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5ecbda745dce42cebfca4964d3a29418/work/5ecbda745dce42cebfca4964d3a29418_id_rsa.pub.
2026-03-18 11:51:24.006192 | controller -> localhost | The key fingerprint is:
2026-03-18 11:51:24.006213 | controller -> localhost | SHA256:VBFPULVcmNdT672hjo16D4qy1xdQizLHotAD6POmrQg zuul-build-sshkey
2026-03-18 11:51:24.006232 | controller -> localhost | The key's randomart image is:
2026-03-18 11:51:24.006251 | controller -> localhost | +---[RSA 3072]----+
2026-03-18 11:51:24.006270 | controller -> localhost | |  .       ==o..o=|
2026-03-18 11:51:24.006288 | controller -> localhost | | . .     . + .o++|
2026-03-18 11:51:24.006385 | controller -> localhost | |.   o   o o o oo.|
2026-03-18 11:51:24.006410 | controller -> localhost | | o . o = = .  . .|
2026-03-18 11:51:24.006429 | controller -> localhost | |  o . o S .    o.|
2026-03-18 11:51:24.006447 | controller -> localhost | |   o .     .  . o|
2026-03-18 11:51:24.006464 | controller -> localhost | |E +     .  ... . |
2026-03-18 11:51:24.006485 | controller -> localhost | |o. . . ....o*    |
2026-03-18 11:51:24.006504 | controller -> localhost | |...  .+. o+o.+   |
2026-03-18 11:51:24.006521 | controller -> localhost | +----[SHA256]-----+
2026-03-18 11:51:24.006563 | controller -> localhost | ok: Runtime: 0:00:00.052104
2026-03-18 11:51:24.011552 | 
2026-03-18 11:51:24.011613 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-18 11:51:24.029017 | controller | ok
2026-03-18 11:51:24.037605 | controller | included: /var/lib/zuul/builds/5ecbda745dce42cebfca4964d3a29418/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-18 11:51:24.044691 | 
2026-03-18 11:51:24.044748 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-18 11:51:24.057980 | controller | skipping: Conditional result was False
2026-03-18 11:51:24.062995 | 
2026-03-18 11:51:24.063060 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-18 11:51:24.405593 | controller | changed
2026-03-18 11:51:24.410797 | 
2026-03-18 11:51:24.410859 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-18 11:51:24.590972 | controller | ok
2026-03-18 11:51:24.595272 | 
2026-03-18 11:51:24.595346 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-18 11:51:25.150278 | controller | changed
2026-03-18 11:51:25.154836 | 
2026-03-18 11:51:25.154907 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-18 11:51:25.699653 | controller | changed
2026-03-18 11:51:25.704017 | 
2026-03-18 11:51:25.704076 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-18 11:51:25.716989 | controller | skipping: Conditional result was False
2026-03-18 11:51:25.721620 | 
2026-03-18 11:51:25.721680 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-18 11:51:25.998411 | controller -> localhost | changed
2026-03-18 11:51:26.008766 | 
2026-03-18 11:51:26.008839 | TASK [add-build-sshkey : Add back temp key]
2026-03-18 11:51:26.224480 | controller -> localhost | Identity added: /var/lib/zuul/builds/5ecbda745dce42cebfca4964d3a29418/work/5ecbda745dce42cebfca4964d3a29418_id_rsa (zuul-build-sshkey)
2026-03-18 11:51:26.224676 | controller -> localhost | ok: Runtime: 0:00:00.006940
2026-03-18 11:51:26.229720 | 
2026-03-18 11:51:26.229787 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-18 11:51:26.500949 | controller | ok
2026-03-18 11:51:26.505126 | 
2026-03-18 11:51:26.505186 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-18 11:51:26.528848 | controller | skipping: Conditional result was False
2026-03-18 11:51:26.537214 | 
2026-03-18 11:51:26.537274 | TASK [include_role : validate-host]
2026-03-18 11:51:26.554706 | controller | ok
2026-03-18 11:51:26.571256 | 
2026-03-18 11:51:26.571339 | TASK [validate-host : Define zuul_info_dir fact]
2026-03-18 11:51:26.599798 | controller | ok
2026-03-18 11:51:26.603681 | 
2026-03-18 11:51:26.603740 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-03-18 11:51:26.778806 | controller -> localhost | ok
2026-03-18 11:51:26.783858 | 
2026-03-18 11:51:26.783922 | TASK [validate-host : Collect information about the host]
2026-03-18 11:51:27.412083 | controller | ok
2026-03-18 11:51:27.419131 | 
2026-03-18 11:51:27.419192 | TASK [validate-host : Sanitize hostname]
2026-03-18 11:51:27.456711 | controller | ok
2026-03-18 11:51:27.461105 | 
2026-03-18 11:51:27.461171 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-03-18 11:51:27.818484 | controller -> localhost | changed
2026-03-18 11:51:27.824807 | 
2026-03-18 11:51:27.824892 | TASK [validate-host : Collect information about zuul worker]
2026-03-18 11:51:28.175529 | controller | ok
2026-03-18 11:51:28.179784 | 
2026-03-18 11:51:28.179848 | TASK [validate-host : Write out all zuul information for each host]
2026-03-18 11:51:28.507959 | controller -> localhost | changed
2026-03-18 11:51:28.516962 | 
2026-03-18 11:51:28.517027 | TASK [include_role : prepare-workspace-openshift]
2026-03-18 11:51:28.530208 | controller | skipping: Conditional result was False
2026-03-18 11:51:28.535147 | 
2026-03-18 11:51:28.535211 | TASK [include_role : remove-zuul-sshkey]
2026-03-18 11:51:28.548254 | controller | skipping: Conditional result was False
2026-03-18 11:51:28.552995 | 
2026-03-18 11:51:28.553063 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-18 11:51:28.743734 | controller | ok: "logs"
2026-03-18 11:51:28.743990 | controller | ok: All items complete
2026-03-18 11:51:28.744031 | 
2026-03-18 11:51:28.907254 | controller | ok: "artifacts"
2026-03-18 11:51:29.060725 | controller | ok: "docs"
2026-03-18 11:51:29.069499 | 
2026-03-18 11:51:29.069599 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-18 11:51:29.271754 | controller | changed: "logs"
2026-03-18 11:51:29.435681 | controller | changed: "artifacts"
2026-03-18 11:51:29.596811 | controller | changed: "docs"
2026-03-18 11:51:29.622137 | 
2026-03-18 11:51:29.622215 | PLAY RECAP
2026-03-18 11:51:29.622260 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-18 11:51:29.622288 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-18 11:51:29.622340 | 
2026-03-18 11:51:29.690854 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-18 11:51:29.691567 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-18 11:51:30.204483 | 
2026-03-18 11:51:30.204588 | PLAY [all]
2026-03-18 11:51:30.222270 | 
2026-03-18 11:51:30.222372 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-18 11:51:30.271197 | controller | ok
2026-03-18 11:51:30.275692 | 
2026-03-18 11:51:30.275774 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-18 11:51:30.599055 | controller | changed
2026-03-18 11:51:30.603933 | 
2026-03-18 11:51:30.604000 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-18 11:51:31.372260 | controller | changed
2026-03-18 11:51:31.380947 | 
2026-03-18 11:51:31.381017 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-03-18 11:51:31.730569 | controller | changed:
2026-03-18 11:51:31.731265 | controller | {
2026-03-18 11:51:31.731338 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-03-18 11:51:31.731404 | controller | }
2026-03-18 11:51:31.937092 | controller | changed:
2026-03-18 11:51:31.937182 | controller | {
2026-03-18 11:51:31.937210 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-03-18 11:51:31.937231 | controller | }
2026-03-18 11:51:32.135757 | controller | changed:
2026-03-18 11:51:32.135808 | controller | {
2026-03-18 11:51:32.135832 | 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-18 11:51:32.135850 | controller | }
2026-03-18 11:51:32.333485 | controller | changed:
2026-03-18 11:51:32.333580 | controller | {
2026-03-18 11:51:32.333613 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-03-18 11:51:32.333635 | controller | }
2026-03-18 11:51:32.526765 | controller | changed:
2026-03-18 11:51:32.526855 | controller | {
2026-03-18 11:51:32.526879 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-03-18 11:51:32.526898 | controller | }
2026-03-18 11:51:32.719201 | controller | changed:
2026-03-18 11:51:32.719258 | controller | {
2026-03-18 11:51:32.719286 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-03-18 11:51:32.719333 | controller | }
2026-03-18 11:51:32.916849 | controller | changed:
2026-03-18 11:51:32.916921 | controller | {
2026-03-18 11:51:32.916947 | 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-18 11:51:32.916968 | controller | }
2026-03-18 11:51:33.124704 | controller | changed:
2026-03-18 11:51:33.124794 | controller | {
2026-03-18 11:51:33.124822 | 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-18 11:51:33.124843 | controller | }
2026-03-18 11:51:33.327743 | controller | changed:
2026-03-18 11:51:33.327882 | controller | {
2026-03-18 11:51:33.327916 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-03-18 11:51:33.327937 | controller | }
2026-03-18 11:51:33.545977 | controller | changed:
2026-03-18 11:51:33.546072 | controller | {
2026-03-18 11:51:33.546099 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-03-18 11:51:33.546118 | controller | }
2026-03-18 11:51:33.748644 | controller | changed:
2026-03-18 11:51:33.748701 | controller | {
2026-03-18 11:51:33.748726 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-03-18 11:51:33.748745 | controller | }
2026-03-18 11:51:33.958346 | controller | changed:
2026-03-18 11:51:33.958438 | controller | {
2026-03-18 11:51:33.958466 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-03-18 11:51:33.958486 | controller | }
2026-03-18 11:51:34.134973 | controller | changed:
2026-03-18 11:51:34.135029 | controller | {
2026-03-18 11:51:34.135055 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-03-18 11:51:34.135074 | controller | }
2026-03-18 11:51:34.336041 | controller | changed:
2026-03-18 11:51:34.336124 | controller | {
2026-03-18 11:51:34.336151 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-03-18 11:51:34.336171 | controller | }
2026-03-18 11:51:34.526417 | controller | changed:
2026-03-18 11:51:34.526506 | controller | {
2026-03-18 11:51:34.526534 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-03-18 11:51:34.526554 | controller | }
2026-03-18 11:51:34.747327 | controller | changed:
2026-03-18 11:51:34.747411 | controller | {
2026-03-18 11:51:34.747436 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-03-18 11:51:34.747455 | controller | }
2026-03-18 11:51:34.951898 | controller | changed:
2026-03-18 11:51:34.951963 | controller | {
2026-03-18 11:51:34.951987 | 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-18 11:51:34.952007 | controller | }
2026-03-18 11:51:35.142403 | controller | changed:
2026-03-18 11:51:35.142449 | controller | {
2026-03-18 11:51:35.142473 | 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-18 11:51:35.142501 | controller | }
2026-03-18 11:51:35.332424 | controller | changed:
2026-03-18 11:51:35.332475 | controller | {
2026-03-18 11:51:35.332501 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-03-18 11:51:35.332520 | controller | }
2026-03-18 11:51:35.521773 | controller | changed:
2026-03-18 11:51:35.521822 | controller | {
2026-03-18 11:51:35.521848 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-03-18 11:51:35.521868 | controller | }
2026-03-18 11:51:35.736920 | controller | changed:
2026-03-18 11:51:35.736967 | controller | {
2026-03-18 11:51:35.736990 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-03-18 11:51:35.737007 | controller | }
2026-03-18 11:51:35.945907 | controller | changed:
2026-03-18 11:51:35.945988 | controller | {
2026-03-18 11:51:35.946013 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-03-18 11:51:35.946031 | controller | }
2026-03-18 11:51:36.169200 | controller | changed:
2026-03-18 11:51:36.169286 | controller | {
2026-03-18 11:51:36.169340 | 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-18 11:51:36.169375 | controller | }
2026-03-18 11:51:36.395372 | controller | changed:
2026-03-18 11:51:36.395462 | controller | {
2026-03-18 11:51:36.395545 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-03-18 11:51:36.395572 | controller | }
2026-03-18 11:51:36.599143 | controller | changed:
2026-03-18 11:51:36.599189 | controller | {
2026-03-18 11:51:36.599213 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-03-18 11:51:36.599231 | controller | }
2026-03-18 11:51:36.795983 | controller | changed:
2026-03-18 11:51:36.796028 | controller | {
2026-03-18 11:51:36.796051 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-03-18 11:51:36.796068 | controller | }
2026-03-18 11:51:36.814931 | 
2026-03-18 11:51:36.815017 | TASK [Set timezone to UTC]
2026-03-18 11:51:37.178848 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-03-18 11:51:37.183282 | 
2026-03-18 11:51:37.183370 | TASK [Create nodepool directory]
2026-03-18 11:51:37.375140 | controller | changed
2026-03-18 11:51:37.379917 | 
2026-03-18 11:51:37.379979 | TASK [Create nodepool sub_nodes file]
2026-03-18 11:51:37.864446 | controller | changed
2026-03-18 11:51:37.868851 | 
2026-03-18 11:51:37.868913 | TASK [Create nodepool sub_nodes_private file]
2026-03-18 11:51:38.343691 | controller | changed
2026-03-18 11:51:38.348693 | 
2026-03-18 11:51:38.348750 | LOOP [Populate nodepool sub_nodes file]
2026-03-18 11:51:38.376992 | 
2026-03-18 11:51:38.377105 | LOOP [Populate nodepool sub_nodes_private file]
2026-03-18 11:51:38.405189 | 
2026-03-18 11:51:38.405323 | TASK [Create nodepool primary file]
2026-03-18 11:51:38.428399 | controller | skipping: Conditional result was False
2026-03-18 11:51:38.433225 | 
2026-03-18 11:51:38.433292 | TASK [Create nodepool node_private for this node]
2026-03-18 11:51:38.937678 | controller | changed
2026-03-18 11:51:38.942656 | 
2026-03-18 11:51:38.942715 | LOOP [Copy ssh keys to nodepool directory]
2026-03-18 11:51:39.238988 | controller | ok: Item: id_rsa Runtime: 0:00:00.004272
2026-03-18 11:51:39.239197 | 
2026-03-18 11:51:39.383257 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.003703
2026-03-18 11:51:39.397310 | 
2026-03-18 11:51:39.397429 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-03-18 11:51:39.881043 | controller | changed
2026-03-18 11:51:39.885461 | 
2026-03-18 11:51:39.885523 | TASK [Validate sudoers config after edits]
2026-03-18 11:51:40.080873 | controller | /etc/sudoers: parsed OK
2026-03-18 11:51:40.080960 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-03-18 11:51:40.080970 | controller | /etc/sudoers.d/zuul: parsed OK
2026-03-18 11:51:40.080975 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-03-18 11:51:40.409580 | controller | ok: Runtime: 0:00:00.004576
2026-03-18 11:51:40.414226 | 
2026-03-18 11:51:40.414283 | TASK [Show the environment passed in to job shell scripts]
2026-03-18 11:51:40.599644 | controller | SHELL=/bin/bash
2026-03-18 11:51:40.599689 | controller | ZUUL_PROJECT=openstack-k8s-operators/edpm-ansible
2026-03-18 11:51:40.599699 | controller | ZUUL_SHORT_PROJECT_NAME=edpm-ansible
2026-03-18 11:51:40.599707 | controller | ZUUL_CHANGES=openstack-k8s-operators/edpm-ansible:main:refs/changes/14/1114/2e1be7179d11cf9a1af84970f52b70dde49b472c
2026-03-18 11:51:40.599730 | controller | PWD=/home/zuul
2026-03-18 11:51:40.599739 | controller | ZUUL_PIPELINE=github-check
2026-03-18 11:51:40.599745 | controller | LOGNAME=zuul
2026-03-18 11:51:40.599751 | controller | XDG_SESSION_TYPE=tty
2026-03-18 11:51:40.599756 | controller | _=/usr/bin/env
2026-03-18 11:51:40.599762 | controller | MOTD_SHOWN=pam
2026-03-18 11:51:40.599820 | controller | HOME=/home/zuul
2026-03-18 11:51:40.599828 | controller | LANG=en_US.UTF-8
2026-03-18 11:51:40.599835 | controller | SSH_CONNECTION=192.168.26.12 43788 192.168.26.48 22
2026-03-18 11:51:40.599842 | 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-18 11:51:40.599857 | controller | ZUUL_CHANGE_IDS=1114,2e1be7179d11cf9a1af84970f52b70dde49b472c
2026-03-18 11:51:40.599862 | controller | WORKSPACE=/home/zuul/workspace
2026-03-18 11:51:40.599868 | controller | XDG_SESSION_CLASS=user
2026-03-18 11:51:40.599874 | controller | SELINUX_ROLE_REQUESTED=
2026-03-18 11:51:40.599879 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-03-18 11:51:40.599885 | controller | USER=zuul
2026-03-18 11:51:40.599891 | controller | ZUUL_VOTING=True
2026-03-18 11:51:40.599896 | controller | BUILD_TIMEOUT=1800000
2026-03-18 11:51:40.599901 | controller | SELINUX_USE_CURRENT_RANGE=
2026-03-18 11:51:40.599906 | controller | SHLVL=1
2026-03-18 11:51:40.599912 | controller | ZUUL_PATCHSET=2e1be7179d11cf9a1af84970f52b70dde49b472c
2026-03-18 11:51:40.599918 | controller | XDG_SESSION_ID=1
2026-03-18 11:51:40.599923 | controller | ZUUL_BRANCH=main
2026-03-18 11:51:40.599928 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-03-18 11:51:40.599934 | controller | SSH_CLIENT=192.168.26.12 43788 22
2026-03-18 11:51:40.599939 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-03-18 11:51:40.599945 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-03-18 11:51:40.599950 | controller | which_declare=declare -f
2026-03-18 11:51:40.599957 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-03-18 11:51:40.599962 | controller | SELINUX_LEVEL_REQUESTED=
2026-03-18 11:51:40.599968 | controller | ZUUL_CHANGE=1114
2026-03-18 11:51:40.599973 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-03-18 11:51:40.599978 | controller | ZUUL_UUID=5ecbda745dce42cebfca4964d3a29418
2026-03-18 11:51:40.599984 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-03-18 11:51:40.599989 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-03-18 11:51:40.600004 | controller | }
2026-03-18 11:51:40.938753 | controller | ok: Runtime: 0:00:00.005976
2026-03-18 11:51:40.943905 | 
2026-03-18 11:51:40.943970 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-03-18 11:51:40.957138 | controller | skipping: Conditional result was False
2026-03-18 11:51:40.962462 | 
2026-03-18 11:51:40.962528 | TASK [Symlink /home/zuul-worker/workspace]
2026-03-18 11:51:41.487621 | controller | skipping: Conditional result was False
2026-03-18 11:51:41.493215 | 
2026-03-18 11:51:41.493280 | TASK [Ensure legacy workspace directory]
2026-03-18 11:51:41.663899 | controller | changed
2026-03-18 11:51:41.681434 | 
2026-03-18 11:51:41.681488 | PLAY RECAP
2026-03-18 11:51:41.681534 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-18 11:51:41.681557 | 
2026-03-18 11:51:41.753793 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-18 11:51:41.754544 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-18 11:51:42.232833 | 
2026-03-18 11:51:42.232930 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-03-18 11:51:42.253198 | 
2026-03-18 11:51:42.253318 | TASK [Create zuul-output directory]
2026-03-18 11:51:42.601762 | controller | changed
2026-03-18 11:51:42.606519 | 
2026-03-18 11:51:42.606592 | TASK [Slurp Zuul inventory test]
2026-03-18 11:51:42.852865 | controller -> localhost | ok
2026-03-18 11:51:42.858815 | 
2026-03-18 11:51:42.858876 | TASK [Save zuul inventory]
2026-03-18 11:51:43.537217 | controller | changed
2026-03-18 11:51:43.542012 | 
2026-03-18 11:51:43.542077 | TASK [Save zuul vars without the change_message]
2026-03-18 11:51:44.098160 | controller | changed
2026-03-18 11:51:44.114584 | 
2026-03-18 11:51:44.114646 | PLAY RECAP
2026-03-18 11:51:44.114695 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-18 11:51:44.114725 | 
2026-03-18 11:51:44.173831 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-18 11:51:44.174568 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-18 11:51:44.687198 | 
2026-03-18 11:51:44.687358 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-03-18 11:51:44.705786 | 
2026-03-18 11:51:44.705853 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-03-18 11:51:44.723654 | controller | ok
2026-03-18 11:51:44.738844 | 
2026-03-18 11:51:44.738919 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-18 11:51:44.762434 | controller | skipping: Conditional result was False
2026-03-18 11:51:44.767626 | 
2026-03-18 11:51:44.767688 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-18 11:51:45.059593 | controller | ok
2026-03-18 11:51:45.064576 | 
2026-03-18 11:51:45.064640 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-18 11:51:45.670845 | controller | ok
2026-03-18 11:51:45.680127 | 
2026-03-18 11:51:45.680199 | TASK [Prepare workspace]
2026-03-18 11:51:45.698088 | controller | ok
2026-03-18 11:51:45.713321 | 
2026-03-18 11:51:45.713399 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-18 11:51:46.004181 | controller | ok
2026-03-18 11:51:46.009381 | 
2026-03-18 11:51:46.009443 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-18 11:51:48.229718 | controller | Output suppressed because no_log was given
2026-03-18 11:51:48.237718 | 
2026-03-18 11:51:48.237781 | LOOP [Create zuul-output directory]
2026-03-18 11:51:48.424929 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-03-18 11:51:48.571176 | controller | ok: "/home/zuul/zuul-output/logs"
2026-03-18 11:51:48.581860 | 
2026-03-18 11:51:48.581933 | TASK [Install required packages]
2026-03-18 11:52:26.066246 | controller | changed
2026-03-18 11:52:26.071263 | 
2026-03-18 11:52:26.071358 | TASK [Install venv]
2026-03-18 11:53:25.740841 | controller | changed
2026-03-18 11:53:25.764825 | 
2026-03-18 11:53:25.764925 | PLAY RECAP
2026-03-18 11:53:25.764967 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-18 11:53:25.764989 | 
2026-03-18 11:53:25.832085 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-18 11:53:25.832828 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-03-18 11:53:26.310915 | 
2026-03-18 11:53:26.311048 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-03-18 11:53:26.329433 | 
2026-03-18 11:53:26.329500 | TASK [Gather required facts]
2026-03-18 11:53:26.763564 | controller | ok
2026-03-18 11:53:26.768337 | 
2026-03-18 11:53:26.768419 | TASK [Load environment var if instructed to]
2026-03-18 11:53:26.792357 | controller | skipping: Conditional result was False
2026-03-18 11:53:26.797312 | 
2026-03-18 11:53:26.797374 | TASK [Ensure group_vars dir exists]
2026-03-18 11:53:27.079799 | controller | changed
2026-03-18 11:53:27.085111 | 
2026-03-18 11:53:27.085171 | TASK [Print related variables]
2026-03-18 11:53:27.113110 | controller | ok:
2026-03-18 11:53:27.113216 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_edpm_ansible.yml
2026-03-18 11:53:27.113243 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/roles/edpm_libvirt
2026-03-18 11:53:27.117542 | 
2026-03-18 11:53:27.117603 | TASK [Run molecule]
2026-03-18 11:53:28.148416 | controller | WARNING  Driver vagrant does not provide a schema.
2026-03-18 11:53:28.150588 | controller | INFO     Performing prerun with role_name_check=0...
2026-03-18 11:53:28.911143 | controller | INFO     Running default > destroy
2026-03-18 11:53:28.911335 | controller | WARNING  Skipping, instances are delegated.
2026-03-18 11:53:28.917898 | controller | INFO     Running default > create
2026-03-18 11:53:28.918700 | controller | WARNING  Skipping, instances are delegated.
2026-03-18 11:53:28.925289 | controller | INFO     Running default > prepare
2026-03-18 11:53:29.547456 | controller |
2026-03-18 11:53:29.547499 | controller | PLAY [Setup DUT] ***************************************************************
2026-03-18 11:53:29.547556 | controller |
2026-03-18 11:53:29.547641 | controller | TASK [set basic user fact] *****************************************************
2026-03-18 11:53:29.547735 | controller | Wednesday 18 March 2026  11:53:29 +0000 (0:00:00.008)       0:00:00.008 *******
2026-03-18 11:53:29.573369 | controller | ok: [compute-1]
2026-03-18 11:53:29.573553 | controller |
2026-03-18 11:53:29.573643 | controller | TASK [set basic home fact] *****************************************************
2026-03-18 11:53:29.573746 | controller | Wednesday 18 March 2026  11:53:29 +0000 (0:00:00.026)       0:00:00.035 *******
2026-03-18 11:53:29.596796 | controller | ok: [compute-1]
2026-03-18 11:53:29.596899 | controller |
2026-03-18 11:53:29.597011 | controller | TASK [set bootc fact] **********************************************************
2026-03-18 11:53:29.597116 | controller | Wednesday 18 March 2026  11:53:29 +0000 (0:00:00.023)       0:00:00.058 *******
2026-03-18 11:53:29.615146 | controller | ok: [compute-1]
2026-03-18 11:53:29.615252 | controller |
2026-03-18 11:53:29.615375 | controller | TASK [Enable package repos] ****************************************************
2026-03-18 11:53:29.615485 | controller | Wednesday 18 March 2026  11:53:29 +0000 (0:00:00.018)       0:00:00.076 *******
2026-03-18 11:53:37.818914 | controller | changed: [compute-1]
2026-03-18 11:53:37.818961 | controller |
2026-03-18 11:53:37.819051 | controller | TASK [Install host packages] ***************************************************
2026-03-18 11:53:37.819148 | controller | Wednesday 18 March 2026  11:53:37 +0000 (0:00:08.203)       0:00:08.280 *******
2026-03-18 11:55:27.837694 | controller | changed: [compute-1] => (item=openstack-selinux)
2026-03-18 11:55:27.837756 | controller | changed: [compute-1] => (item=python3-cryptography)
2026-03-18 11:55:27.837831 | controller |
2026-03-18 11:55:27.837923 | controller | TASK [set /etc/localtime] ******************************************************
2026-03-18 11:55:27.838018 | controller | Wednesday 18 March 2026  11:55:27 +0000 (0:01:50.018)       0:01:58.299 *******
2026-03-18 11:55:28.112645 | controller | changed: [compute-1]
2026-03-18 11:55:28.112694 | controller |
2026-03-18 11:55:28.112812 | controller | TASK [osp.edpm.edpm_timezone : Validating arguments against arg spec 'main' - The main entry point for the edpm_timezone role.] ***
2026-03-18 11:55:28.112908 | controller | Wednesday 18 March 2026  11:55:28 +0000 (0:00:00.275)       0:01:58.574 *******
2026-03-18 11:55:28.127138 | controller | ok: [compute-1]
2026-03-18 11:55:28.127207 | controller |
2026-03-18 11:55:28.127296 | controller | TASK [osp.edpm.edpm_timezone : Gather ansible_local facts] *********************
2026-03-18 11:55:28.127382 | controller | Wednesday 18 March 2026  11:55:28 +0000 (0:00:00.014)       0:01:58.589 *******
2026-03-18 11:55:28.145891 | controller | skipping: [compute-1]
2026-03-18 11:55:28.145972 | controller |
2026-03-18 11:55:28.146067 | controller | TASK [osp.edpm.edpm_timezone : Check for /etc/localtime] ***********************
2026-03-18 11:55:28.146160 | controller | Wednesday 18 March 2026  11:55:28 +0000 (0:00:00.018)       0:01:58.607 *******
2026-03-18 11:55:28.159971 | controller | skipping: [compute-1]
2026-03-18 11:55:28.160081 | controller |
2026-03-18 11:55:28.160208 | controller | TASK [osp.edpm.edpm_timezone : Symlink /etc/localtime to timezone file if it doesn't exist] ***
2026-03-18 11:55:28.160331 | controller | Wednesday 18 March 2026  11:55:28 +0000 (0:00:00.014)       0:01:58.621 *******
2026-03-18 11:55:28.173789 | controller | skipping: [compute-1]
2026-03-18 11:55:28.173921 | controller |
2026-03-18 11:55:28.174047 | controller | TASK [osp.edpm.edpm_timezone : Set timezone] ***********************************
2026-03-18 11:55:28.174170 | controller | Wednesday 18 March 2026  11:55:28 +0000 (0:00:00.013)       0:01:58.635 *******
2026-03-18 11:55:28.527874 | controller | ok: [compute-1]
2026-03-18 11:55:28.527947 | controller |
2026-03-18 11:55:28.528041 | controller | TASK [osp.edpm.edpm_timezone : Set fact for timezone result] *******************
2026-03-18 11:55:28.528129 | controller | Wednesday 18 March 2026  11:55:28 +0000 (0:00:00.353)       0:01:58.989 *******
2026-03-18 11:55:28.542033 | controller | ok: [compute-1]
2026-03-18 11:55:28.542107 | controller |
2026-03-18 11:55:28.542201 | controller | TASK [osp.edpm.edpm_timezone : Restart time services] **************************
2026-03-18 11:55:28.542287 | controller | Wednesday 18 March 2026  11:55:28 +0000 (0:00:00.014)       0:01:59.003 *******
2026-03-18 11:55:29.462487 | controller | changed: [compute-1] => (item=rsyslog)
2026-03-18 11:55:29.462873 | controller | changed: [compute-1] => (item=crond)
2026-03-18 11:55:30.602320 | controller |
2026-03-18 11:55:30.602356 | controller | TASK [remove vagrant image packages] *******************************************
2026-03-18 11:55:30.602365 | controller | Wednesday 18 March 2026  11:55:29 +0000 (0:00:00.920)       0:01:59.924 *******
2026-03-18 11:55:30.602374 | controller | ok: [compute-1] => (item=firewalld)
2026-03-18 11:55:30.790240 | controller |
2026-03-18 11:55:30.790272 | controller | TASK [Create firewall directory] ***********************************************
2026-03-18 11:55:30.790279 | controller | Wednesday 18 March 2026  11:55:30 +0000 (0:00:01.139)       0:02:01.063 *******
2026-03-18 11:55:30.790289 | controller | changed: [compute-1]
2026-03-18 11:55:31.376279 | controller |
2026-03-18 11:55:31.376309 | controller | TASK [open port 22 (edpm_nftables will active this later)] *********************
2026-03-18 11:55:31.376317 | controller | Wednesday 18 March 2026  11:55:30 +0000 (0:00:00.187)       0:02:01.251 *******
2026-03-18 11:55:31.376327 | controller | changed: [compute-1]
2026-03-18 11:55:31.591833 | controller |
2026-03-18 11:55:31.591873 | controller | TASK [Create Ceph client file directory] ***************************************
2026-03-18 11:55:31.591881 | controller | Wednesday 18 March 2026  11:55:31 +0000 (0:00:00.584)       0:02:01.836 *******
2026-03-18 11:55:31.591893 | controller | changed: [compute-1]
2026-03-18 11:55:31.591919 | controller |
2026-03-18 11:55:31.592132 | controller | TASK [Create random mock Ceph FSID] ********************************************
2026-03-18 11:55:31.762304 | controller | Wednesday 18 March 2026  11:55:31 +0000 (0:00:00.217)       0:02:02.053 *******
2026-03-18 11:55:31.762333 | controller | changed: [compute-1]
2026-03-18 11:55:31.951201 | controller |
2026-03-18 11:55:31.951224 | controller | TASK [Create random mock Cephx key] ********************************************
2026-03-18 11:55:31.951233 | controller | Wednesday 18 March 2026  11:55:31 +0000 (0:00:00.170)       0:02:02.223 *******
2026-03-18 11:55:31.951242 | controller | changed: [compute-1]
2026-03-18 11:55:32.308987 | controller |
2026-03-18 11:55:32.309017 | controller | TASK [Create a mock Ceph conf file] ********************************************
2026-03-18 11:55:32.309025 | controller | Wednesday 18 March 2026  11:55:31 +0000 (0:00:00.188)       0:02:02.412 *******
2026-03-18 11:55:32.309035 | controller | changed: [compute-1]
2026-03-18 11:55:32.677750 | controller |
2026-03-18 11:55:32.677782 | controller | TASK [Create a mock Cephx keyring file] ****************************************
2026-03-18 11:55:32.677790 | controller | Wednesday 18 March 2026  11:55:32 +0000 (0:00:00.357)       0:02:02.770 *******
2026-03-18 11:55:32.677799 | controller | changed: [compute-1]
2026-03-18 11:55:32.867477 | controller |
2026-03-18 11:55:32.867504 | controller | TASK [create pki temp directory] ***********************************************
2026-03-18 11:55:32.867512 | controller | Wednesday 18 March 2026  11:55:32 +0000 (0:00:00.368)       0:02:03.139 *******
2026-03-18 11:55:32.867525 | controller | changed: [compute-1]
2026-03-18 11:55:34.348138 | controller |
2026-03-18 11:55:34.348167 | controller | TASK [Create private key (RSA, 4096 bits)] *************************************
2026-03-18 11:55:34.348175 | controller | Wednesday 18 March 2026  11:55:32 +0000 (0:00:00.189)       0:02:03.328 *******
2026-03-18 11:55:34.348186 | controller | changed: [compute-1]
2026-03-18 11:55:35.000766 | controller |
2026-03-18 11:55:35.000794 | controller | TASK [Create a certificate signing request for ca] *****************************
2026-03-18 11:55:35.000802 | controller | Wednesday 18 March 2026  11:55:34 +0000 (0:00:01.480)       0:02:04.809 *******
2026-03-18 11:55:35.000812 | controller | changed: [compute-1]
2026-03-18 11:55:35.001154 | controller |
2026-03-18 11:55:35.750919 | controller | TASK [Create a certificate athority] *******************************************
2026-03-18 11:55:35.750949 | controller | Wednesday 18 March 2026  11:55:34 +0000 (0:00:00.652)       0:02:05.462 *******
2026-03-18 11:55:35.750960 | controller | changed: [compute-1]
2026-03-18 11:55:37.719176 | controller |
2026-03-18 11:55:37.719202 | controller | TASK [Create private key (RSA, 4096 bits)] *************************************
2026-03-18 11:55:37.719210 | controller | Wednesday 18 March 2026  11:55:35 +0000 (0:00:00.749)       0:02:06.212 *******
2026-03-18 11:55:37.719218 | controller | changed: [compute-1]
2026-03-18 11:55:38.268378 | controller |
2026-03-18 11:55:38.268411 | controller | TASK [Create a certificate signing request for libvirt server and client cert] ***
2026-03-18 11:55:38.268419 | controller | Wednesday 18 March 2026  11:55:37 +0000 (0:00:01.968)       0:02:08.180 *******
2026-03-18 11:55:38.268430 | controller | changed: [compute-1]
2026-03-18 11:55:38.848737 | controller |
2026-03-18 11:55:38.848774 | controller | TASK [Sign the certificate signing request] ************************************
2026-03-18 11:55:38.848783 | controller | Wednesday 18 March 2026  11:55:38 +0000 (0:00:00.549)       0:02:08.729 *******
2026-03-18 11:55:38.848793 | controller | changed: [compute-1]
2026-03-18 11:55:39.147684 | controller |
2026-03-18 11:55:39.147724 | controller | TASK [Create password secret] **************************************************
2026-03-18 11:55:39.147735 | controller | Wednesday 18 March 2026  11:55:38 +0000 (0:00:00.580)       0:02:09.310 *******
2026-03-18 11:55:39.147745 | controller | changed: [compute-1 -> localhost]
2026-03-18 11:55:39.464318 | controller |
2026-03-18 11:55:39.464354 | controller | TASK [Gather required facts] ***************************************************
2026-03-18 11:55:39.464365 | controller | Wednesday 18 March 2026  11:55:39 +0000 (0:00:00.299)       0:02:09.609 *******
2026-03-18 11:55:39.464378 | controller | ok: [compute-1]
2026-03-18 11:55:39.777061 | controller |
2026-03-18 11:55:39.777100 | controller | TASK [Ensure hostname is resolvable in /etc/hosts] *****************************
2026-03-18 11:55:39.777109 | controller | Wednesday 18 March 2026  11:55:39 +0000 (0:00:00.316)       0:02:09.925 *******
2026-03-18 11:55:39.777119 | controller | changed: [compute-1]
2026-03-18 11:55:39.777139 | controller |
2026-03-18 11:55:39.777803 | controller | PLAY RECAP *********************************************************************
2026-03-18 11:55:39.777864 | controller | compute-1                  : ok=28   changed=20   unreachable=0    failed=0    skipped=3    rescued=0    ignored=0
2026-03-18 11:55:39.777873 | controller |
2026-03-18 11:55:39.777880 | controller | Wednesday 18 March 2026  11:55:39 +0000 (0:00:00.313)       0:02:10.238 *******
2026-03-18 11:55:39.777886 | controller | ===============================================================================
2026-03-18 11:55:39.777891 | controller | Install host packages ------------------------------------------------- 110.02s
2026-03-18 11:55:39.777897 | controller | Enable package repos ---------------------------------------------------- 8.20s
2026-03-18 11:55:39.777905 | controller | Create private key (RSA, 4096 bits) ------------------------------------- 1.97s
2026-03-18 11:55:39.779394 | controller | Create private key (RSA, 4096 bits) ------------------------------------- 1.48s
2026-03-18 11:55:39.838438 | controller | remove vagrant image packages ------------------------------------------- 1.14s
2026-03-18 11:55:39.838465 | controller | osp.edpm.edpm_timezone : Restart time services -------------------------- 0.92s
2026-03-18 11:55:39.838473 | controller | Create a certificate athority ------------------------------------------- 0.75s
2026-03-18 11:55:39.838479 | controller | Create a certificate signing request for ca ----------------------------- 0.65s
2026-03-18 11:55:39.838485 | controller | open port 22 (edpm_nftables will active this later) --------------------- 0.58s
2026-03-18 11:55:39.838491 | controller | Sign the certificate signing request ------------------------------------ 0.58s
2026-03-18 11:55:39.838496 | controller | Create a certificate signing request for libvirt server and client cert --- 0.55s
2026-03-18 11:55:39.838502 | controller | Create a mock Cephx keyring file ---------------------------------------- 0.37s
2026-03-18 11:55:39.838508 | controller | Create a mock Ceph conf file -------------------------------------------- 0.36s
2026-03-18 11:55:39.838513 | controller | osp.edpm.edpm_timezone : Set timezone ----------------------------------- 0.35s
2026-03-18 11:55:39.838518 | controller | Gather required facts --------------------------------------------------- 0.32s
2026-03-18 11:55:39.838530 | controller | Ensure hostname is resolvable in /etc/hosts ----------------------------- 0.31s
2026-03-18 11:55:39.838536 | controller | Create password secret -------------------------------------------------- 0.30s
2026-03-18 11:55:39.838541 | controller | set /etc/localtime ------------------------------------------------------ 0.28s
2026-03-18 11:55:39.838547 | controller | Create Ceph client file directory --------------------------------------- 0.22s
2026-03-18 11:55:39.838552 | controller | create pki temp directory ----------------------------------------------- 0.19s
2026-03-18 11:55:39.838562 | controller | INFO     Running default > converge
2026-03-18 11:55:40.258561 | controller |
2026-03-18 11:55:40.258774 | controller | PLAY [Converge] ****************************************************************
2026-03-18 11:55:40.258942 | controller |
2026-03-18 11:55:40.259075 | controller | TASK [Gathering Facts] *********************************************************
2026-03-18 11:55:40.259203 | controller | Wednesday 18 March 2026  11:55:40 +0000 (0:00:00.011)       0:00:00.011 *******
2026-03-18 11:55:41.024406 | controller | ok: [compute-1]
2026-03-18 11:55:41.049709 | controller |
2026-03-18 11:55:41.049741 | controller | TASK [set basic user fact] *****************************************************
2026-03-18 11:55:41.049750 | controller | Wednesday 18 March 2026  11:55:41 +0000 (0:00:00.765)       0:00:00.777 *******
2026-03-18 11:55:41.049758 | controller | ok: [compute-1]
2026-03-18 11:55:41.068043 | controller |
2026-03-18 11:55:41.068059 | controller | TASK [set basic home fact] *****************************************************
2026-03-18 11:55:41.068066 | controller | Wednesday 18 March 2026  11:55:41 +0000 (0:00:00.025)       0:00:00.803 *******
2026-03-18 11:55:41.068074 | controller | skipping: [compute-1]
2026-03-18 11:55:41.090845 | controller |
2026-03-18 11:55:41.090861 | controller | TASK [set bootc fact] **********************************************************
2026-03-18 11:55:41.090867 | controller | Wednesday 18 March 2026  11:55:41 +0000 (0:00:00.018)       0:00:00.821 *******
2026-03-18 11:55:41.090875 | controller | ok: [compute-1]
2026-03-18 11:55:41.130376 | controller |
2026-03-18 11:55:41.130394 | controller | TASK [osp.edpm.edpm_libvirt : Validating arguments against arg spec 'main' - The main entry point for the edpm_libvirt role.] ***
2026-03-18 11:55:41.130402 | controller | Wednesday 18 March 2026  11:55:41 +0000 (0:00:00.022)       0:00:00.844 *******
2026-03-18 11:55:41.130411 | controller | ok: [compute-1]
2026-03-18 11:55:41.151840 | controller |
2026-03-18 11:55:41.151876 | controller | TASK [osp.edpm.edpm_libvirt : Adopt libvirt from tripleo] **********************
2026-03-18 11:55:41.151887 | controller | Wednesday 18 March 2026  11:55:41 +0000 (0:00:00.039)       0:00:00.883 *******
2026-03-18 11:55:41.151900 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/collections/ansible_collections/osp/edpm/roles/edpm_libvirt/tasks/adoption.yml for compute-1
2026-03-18 11:55:41.151929 | controller |
2026-03-18 11:55:41.151940 | controller | TASK [osp.edpm.edpm_libvirt : Check if nova_virtlogd container exists] *********
2026-03-18 11:55:41.151998 | controller | Wednesday 18 March 2026  11:55:41 +0000 (0:00:00.021)       0:00:00.905 *******
2026-03-18 11:55:41.475551 | controller | ok: [compute-1]
2026-03-18 11:55:41.501639 | controller |
2026-03-18 11:55:41.501660 | controller | TASK [osp.edpm.edpm_libvirt : Stop nova_virtlogd containers not managed by service units] ***
2026-03-18 11:55:41.501668 | controller | Wednesday 18 March 2026  11:55:41 +0000 (0:00:00.323)       0:00:01.228 *******
2026-03-18 11:55:41.501678 | controller | skipping: [compute-1]
2026-03-18 11:55:41.545819 | controller |
2026-03-18 11:55:41.545879 | controller | TASK [Stop and disable libvirt services] ***************************************
2026-03-18 11:55:41.545891 | controller | Wednesday 18 March 2026  11:55:41 +0000 (0:00:00.026)       0:00:01.255 *******
2026-03-18 11:55:41.545907 | controller |
2026-03-18 11:55:41.546018 | 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-18 11:55:41.563766 | controller | Wednesday 18 March 2026  11:55:41 +0000 (0:00:00.044)       0:00:01.299 *******
2026-03-18 11:55:41.563796 | controller | ok: [compute-1]
2026-03-18 11:55:41.563880 | controller |
2026-03-18 11:55:41.563889 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reload units to check for new changes] ***
2026-03-18 11:55:41.563904 | controller | Wednesday 18 March 2026  11:55:41 +0000 (0:00:00.017)       0:00:01.317 *******
2026-03-18 11:55:42.175451 | controller | ok: [compute-1]
2026-03-18 11:55:43.834973 | controller |
2026-03-18 11:55:43.835002 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Gather service facts] ********************
2026-03-18 11:55:43.835010 | controller | Wednesday 18 March 2026  11:55:42 +0000 (0:00:00.611)       0:00:01.928 *******
2026-03-18 11:55:43.835019 | controller | ok: [compute-1]
2026-03-18 11:55:43.857621 | controller |
2026-03-18 11:55:43.857646 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Set list of tripleo services] ************
2026-03-18 11:55:43.857654 | controller | Wednesday 18 March 2026  11:55:43 +0000 (0:00:01.659)       0:00:03.588 *******
2026-03-18 11:55:43.857663 | controller | ok: [compute-1]
2026-03-18 11:55:43.889170 | controller |
2026-03-18 11:55:43.889191 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Get all services] ************************
2026-03-18 11:55:43.889199 | controller | Wednesday 18 March 2026  11:55:43 +0000 (0:00:00.022)       0:00:03.611 *******
2026-03-18 11:55:43.889208 | controller | skipping: [compute-1]
2026-03-18 11:55:43.917666 | controller |
2026-03-18 11:55:43.917687 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Filter for tripleo services and skip the ones in skip list] ***
2026-03-18 11:55:43.917695 | controller | Wednesday 18 March 2026  11:55:43 +0000 (0:00:00.031)       0:00:03.642 *******
2026-03-18 11:55:43.917705 | controller | skipping: [compute-1]
2026-03-18 11:55:45.748791 | controller |
2026-03-18 11:55:45.748821 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Stop and disable tripleo services] *******
2026-03-18 11:55:45.748835 | controller | Wednesday 18 March 2026  11:55:43 +0000 (0:00:00.028)       0:00:03.671 *******
2026-03-18 11:55:45.748846 | controller | ok: [compute-1] => (item=tripleo_nova_libvirt.target)
2026-03-18 11:55:45.748869 | controller | ok: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2026-03-18 11:55:45.748876 | controller | ok: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2026-03-18 11:55:45.748881 | controller | ok: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2026-03-18 11:55:45.748886 | controller | ok: [compute-1] => (item=tripleo_nova_virtqemud.service)
2026-03-18 11:55:45.748893 | controller | ok: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2026-03-18 11:55:45.749207 | controller | ok: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2026-03-18 11:55:45.793815 | controller |
2026-03-18 11:55:45.793852 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Check for errors] ************************
2026-03-18 11:55:45.793863 | controller | Wednesday 18 March 2026  11:55:45 +0000 (0:00:01.830)       0:00:05.501 *******
2026-03-18 11:55:45.793882 | 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-18 11:55:45.793915 | 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-18 11:55:45.793924 | 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-18 11:55:45.793935 | 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-18 11:55:45.793949 | 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-18 11:55:45.793955 | 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-18 11:55:45.793962 | 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-18 11:55:45.793968 | controller | skipping: [compute-1]
2026-03-18 11:55:45.793975 | controller |
2026-03-18 11:55:45.793984 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files] ***********************
2026-03-18 11:55:45.828816 | controller | Wednesday 18 March 2026  11:55:45 +0000 (0:00:00.044)       0:00:05.546 *******
2026-03-18 11:55:45.828863 | 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-18 11:55:47.121794 | 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-18 11:55:47.121822 | controller |
2026-03-18 11:55:47.121839 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /usr/lib/systemd/system] ***
2026-03-18 11:55:47.121846 | controller | Wednesday 18 March 2026  11:55:45 +0000 (0:00:00.035)       0:00:05.581 *******
2026-03-18 11:55:47.121856 | controller | ok: [compute-1] => (item=tripleo_nova_libvirt.target)
2026-03-18 11:55:47.122365 | controller | ok: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2026-03-18 11:55:47.122392 | controller | ok: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2026-03-18 11:55:47.122399 | controller | ok: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2026-03-18 11:55:47.122404 | controller | ok: [compute-1] => (item=tripleo_nova_virtqemud.service)
2026-03-18 11:55:47.122414 | controller | ok: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2026-03-18 11:55:48.340817 | controller | ok: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2026-03-18 11:55:48.340861 | controller |
2026-03-18 11:55:48.340870 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /etc/systemd/system] ***
2026-03-18 11:55:48.340876 | controller | Wednesday 18 March 2026  11:55:47 +0000 (0:00:01.292)       0:00:06.874 *******
2026-03-18 11:55:48.340887 | controller | ok: [compute-1] => (item=tripleo_nova_libvirt.target)
2026-03-18 11:55:48.373829 | controller | ok: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2026-03-18 11:55:48.373874 | controller | ok: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2026-03-18 11:55:48.373890 | controller | ok: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2026-03-18 11:55:48.373897 | controller | ok: [compute-1] => (item=tripleo_nova_virtqemud.service)
2026-03-18 11:55:48.373904 | controller | ok: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2026-03-18 11:55:48.373916 | controller | ok: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2026-03-18 11:55:48.373923 | controller |
2026-03-18 11:55:48.373930 | controller | TASK [Adopt (stop tracking) certs from tripleo] ********************************
2026-03-18 11:55:48.373937 | controller | Wednesday 18 March 2026  11:55:48 +0000 (0:00:01.218)       0:00:08.093 *******
2026-03-18 11:55:48.373949 | controller |
2026-03-18 11:55:48.561103 | controller | TASK [osp.edpm.edpm_install_certs : Stop and disable certmonger] ***************
2026-03-18 11:55:48.561128 | controller | Wednesday 18 March 2026  11:55:48 +0000 (0:00:00.033)       0:00:08.127 *******
2026-03-18 11:55:48.561140 | controller | ok: [compute-1]
2026-03-18 11:55:48.561164 | controller |
2026-03-18 11:55:48.561265 | controller | TASK [osp.edpm.edpm_install_certs : Find certmonger requests] ******************
2026-03-18 11:55:48.561356 | controller | Wednesday 18 March 2026  11:55:48 +0000 (0:00:00.187)       0:00:08.314 *******
2026-03-18 11:55:48.785389 | controller | [WARNING]: Skipped '/var/lib/certmonger/requests' path due to this access
2026-03-18 11:55:48.794785 | controller | issue: '/var/lib/certmonger/requests' is not a directory
2026-03-18 11:55:48.794821 | controller | ok: [compute-1]
2026-03-18 11:55:48.812664 | controller |
2026-03-18 11:55:48.812685 | controller | TASK [osp.edpm.edpm_install_certs : Backup certificate requests] ***************
2026-03-18 11:55:48.812692 | controller | Wednesday 18 March 2026  11:55:48 +0000 (0:00:00.233)       0:00:08.548 *******
2026-03-18 11:55:48.812702 | controller | skipping: [compute-1]
2026-03-18 11:55:48.834642 | controller |
2026-03-18 11:55:48.834658 | controller | TASK [osp.edpm.edpm_install_certs : Remove certificate requests] ***************
2026-03-18 11:55:48.834665 | controller | Wednesday 18 March 2026  11:55:48 +0000 (0:00:00.018)       0:00:08.566 *******
2026-03-18 11:55:48.834673 | controller | skipping: [compute-1]
2026-03-18 11:55:49.249103 | controller |
2026-03-18 11:55:49.249136 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reload units] ****************************
2026-03-18 11:55:49.249144 | controller | Wednesday 18 March 2026  11:55:48 +0000 (0:00:00.021)       0:00:08.588 *******
2026-03-18 11:55:49.249154 | controller | ok: [compute-1]
2026-03-18 11:55:50.529379 | controller |
2026-03-18 11:55:50.529410 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reset state of failed systemd units] *****
2026-03-18 11:55:50.529418 | controller | Wednesday 18 March 2026  11:55:49 +0000 (0:00:00.413)       0:00:09.002 *******
2026-03-18 11:55:50.529434 | controller | changed: [compute-1] => (item=tripleo_nova_libvirt.target)
2026-03-18 11:55:50.562376 | controller | changed: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2026-03-18 11:55:50.562391 | controller | changed: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2026-03-18 11:55:50.562398 | controller | changed: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2026-03-18 11:55:50.562403 | controller | changed: [compute-1] => (item=tripleo_nova_virtqemud.service)
2026-03-18 11:55:50.562408 | controller | changed: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2026-03-18 11:55:50.562413 | controller | changed: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2026-03-18 11:55:50.562418 | controller |
2026-03-18 11:55:50.562424 | controller | TASK [osp.edpm.edpm_libvirt : Ensure libvirt user] *****************************
2026-03-18 11:55:50.562429 | controller | Wednesday 18 March 2026  11:55:50 +0000 (0:00:01.279)       0:00:10.281 *******
2026-03-18 11:55:50.562437 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/collections/ansible_collections/osp/edpm/roles/edpm_libvirt/tasks/user.yml for compute-1
2026-03-18 11:55:50.580812 | controller |
2026-03-18 11:55:50.580858 | controller | TASK [osp.edpm.edpm_users : Validating arguments against arg spec 'main' - The main entry point for the edpm_users role.] ***
2026-03-18 11:55:50.580868 | controller | Wednesday 18 March 2026  11:55:50 +0000 (0:00:00.033)       0:00:10.315 *******
2026-03-18 11:55:50.580881 | controller | ok: [compute-1]
2026-03-18 11:55:50.580910 | controller |
2026-03-18 11:55:50.581007 | controller | TASK [osp.edpm.edpm_users : Gather user fact] **********************************
2026-03-18 11:55:50.581033 | controller | Wednesday 18 March 2026  11:55:50 +0000 (0:00:00.018)       0:00:10.334 *******
2026-03-18 11:55:50.602486 | controller | skipping: [compute-1]
2026-03-18 11:55:50.632222 | controller |
2026-03-18 11:55:50.632243 | controller | TASK [osp.edpm.edpm_users : Create host users and groups] **********************
2026-03-18 11:55:50.632251 | controller | Wednesday 18 March 2026  11:55:50 +0000 (0:00:00.021)       0:00:10.355 *******
2026-03-18 11:55:50.632262 | 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-18 11:55:50.906790 | controller |
2026-03-18 11:55:50.906816 | controller | TASK [osp.edpm.edpm_users : Detect when user exists and cannot be created] *****
2026-03-18 11:55:50.906824 | controller | Wednesday 18 March 2026  11:55:50 +0000 (0:00:00.029)       0:00:10.385 *******
2026-03-18 11:55:50.906842 | controller | fatal: [compute-1]: FAILED! => changed=false
2026-03-18 11:55:50.915754 | controller |   msg: One or more supplied key could not be found in the database.
2026-03-18 11:55:50.915771 | controller | ...ignoring
2026-03-18 11:55:50.915793 | controller |
2026-03-18 11:55:50.915801 | controller | TASK [osp.edpm.edpm_users : Ensure group is present on the host [ libvirt ]] ***
2026-03-18 11:55:50.915921 | controller | Wednesday 18 March 2026  11:55:50 +0000 (0:00:00.283)       0:00:10.669 *******
2026-03-18 11:55:51.239780 | controller | changed: [compute-1]
2026-03-18 11:55:51.634485 | controller |
2026-03-18 11:55:51.634513 | controller | TASK [osp.edpm.edpm_users : Ensure user is present on the host [ libvirt ]] ****
2026-03-18 11:55:51.634521 | controller | Wednesday 18 March 2026  11:55:51 +0000 (0:00:00.323)       0:00:10.993 *******
2026-03-18 11:55:51.634536 | controller | changed: [compute-1]
2026-03-18 11:55:51.634553 | controller |
2026-03-18 11:55:51.634650 | controller | TASK [osp.edpm.edpm_users : Create host directories] ***************************
2026-03-18 11:55:51.634772 | controller | Wednesday 18 March 2026  11:55:51 +0000 (0:00:00.394)       0:00:11.388 *******
2026-03-18 11:55:51.657539 | controller | skipping: [compute-1]
2026-03-18 11:55:51.687849 | controller |
2026-03-18 11:55:51.687884 | controller | TASK [osp.edpm.edpm_libvirt : Install libvirt] *********************************
2026-03-18 11:55:51.687894 | controller | Wednesday 18 March 2026  11:55:51 +0000 (0:00:00.022)       0:00:11.411 *******
2026-03-18 11:55:51.687906 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/collections/ansible_collections/osp/edpm/roles/edpm_libvirt/tasks/install.yml for compute-1
2026-03-18 11:55:51.687938 | controller |
2026-03-18 11:55:51.687946 | controller | TASK [osp.edpm.edpm_libvirt : Gather ansible_local facts] **********************
2026-03-18 11:55:51.687955 | controller | Wednesday 18 March 2026  11:55:51 +0000 (0:00:00.030)       0:00:11.441 *******
2026-03-18 11:55:51.711955 | controller | skipping: [compute-1]
2026-03-18 11:57:02.438267 | controller |
2026-03-18 11:57:02.438297 | controller | TASK [osp.edpm.edpm_libvirt : Install libvirt packages] ************************
2026-03-18 11:57:02.438305 | controller | Wednesday 18 March 2026  11:55:51 +0000 (0:00:00.023)       0:00:11.465 *******
2026-03-18 11:57:02.438314 | controller | changed: [compute-1]
2026-03-18 11:57:02.438534 | controller |
2026-03-18 11:57:04.583333 | controller | TASK [osp.edpm.edpm_libvirt : Ensure monolithic libvirt and tcp socket activation is not enabled or running] ***
2026-03-18 11:57:04.583362 | controller | Wednesday 18 March 2026  11:57:02 +0000 (0:01:10.726)       0:01:22.191 *******
2026-03-18 11:57:04.583373 | controller | changed: [compute-1] => (item=libvirtd)
2026-03-18 11:57:06.883035 | controller | changed: [compute-1] => (item=libvirtd-tcp.socket)
2026-03-18 11:57:06.883065 | controller | changed: [compute-1] => (item=libvirtd-tls.socket)
2026-03-18 11:57:06.883072 | controller | changed: [compute-1] => (item=virtproxyd-tcp.socket)
2026-03-18 11:57:06.883078 | controller |
2026-03-18 11:57:06.883083 | controller | TASK [osp.edpm.edpm_libvirt : Ensure libvirt services are enabled and running] ***
2026-03-18 11:57:06.883089 | controller | Wednesday 18 March 2026  11:57:04 +0000 (0:00:02.144)       0:01:24.335 *******
2026-03-18 11:57:06.883099 | controller | changed: [compute-1] => (item=virtlogd)
2026-03-18 11:57:07.421783 | controller | changed: [compute-1] => (item=virtnodedevd)
2026-03-18 11:57:07.421824 | controller | changed: [compute-1] => (item=virtproxyd)
2026-03-18 11:57:07.421844 | controller | ok: [compute-1] => (item=virtqemud)
2026-03-18 11:57:07.421851 | controller | changed: [compute-1] => (item=virtsecretd)
2026-03-18 11:57:07.421857 | controller |
2026-03-18 11:57:07.421862 | controller | TASK [osp.edpm.edpm_libvirt : Configure virtproxyd-tls.socket] *****************
2026-03-18 11:57:07.421868 | controller | Wednesday 18 March 2026  11:57:06 +0000 (0:00:02.299)       0:01:26.635 *******
2026-03-18 11:57:07.421878 | controller | changed: [compute-1]
2026-03-18 11:57:07.421902 | controller |
2026-03-18 11:57:07.421967 | controller | TASK [osp.edpm.edpm_libvirt : Configure socket activation for libvirt services] ***
2026-03-18 11:57:07.422059 | controller | Wednesday 18 March 2026  11:57:07 +0000 (0:00:00.539)       0:01:27.175 *******
2026-03-18 11:57:11.577856 | controller | ok: [compute-1] => (item=['virtlogd', ''])
2026-03-18 11:57:11.579321 | controller | skipping: [compute-1] => (item=['virtlogd', '-ro'])
2026-03-18 11:57:11.579342 | controller | ok: [compute-1] => (item=['virtlogd', '-admin'])
2026-03-18 11:57:11.579548 | controller | ok: [compute-1] => (item=['virtnodedevd', ''])
2026-03-18 11:57:11.579579 | controller | ok: [compute-1] => (item=['virtnodedevd', '-ro'])
2026-03-18 11:57:11.579586 | controller | ok: [compute-1] => (item=['virtnodedevd', '-admin'])
2026-03-18 11:57:11.579592 | controller | ok: [compute-1] => (item=['virtproxyd', ''])
2026-03-18 11:57:11.579598 | controller | ok: [compute-1] => (item=['virtproxyd', '-ro'])
2026-03-18 11:57:11.579604 | controller | ok: [compute-1] => (item=['virtproxyd', '-admin'])
2026-03-18 11:57:11.579609 | controller | ok: [compute-1] => (item=['virtqemud', ''])
2026-03-18 11:57:11.579615 | controller | ok: [compute-1] => (item=['virtqemud', '-ro'])
2026-03-18 11:57:11.579620 | controller | ok: [compute-1] => (item=['virtqemud', '-admin'])
2026-03-18 11:57:11.579626 | controller | ok: [compute-1] => (item=['virtsecretd', ''])
2026-03-18 11:57:11.579631 | controller | ok: [compute-1] => (item=['virtsecretd', '-ro'])
2026-03-18 11:57:11.579637 | controller | ok: [compute-1] => (item=['virtsecretd', '-admin'])
2026-03-18 11:57:11.579643 | controller |
2026-03-18 11:57:11.579652 | controller | TASK [osp.edpm.edpm_libvirt : Configure libvirt] *******************************
2026-03-18 11:57:11.621314 | controller | Wednesday 18 March 2026  11:57:11 +0000 (0:00:04.155)       0:01:31.330 *******
2026-03-18 11:57:11.621354 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/collections/ansible_collections/osp/edpm/roles/edpm_libvirt/tasks/configure.yml for compute-1
2026-03-18 11:57:11.654514 | controller |
2026-03-18 11:57:11.654531 | controller | TASK [osp.edpm.edpm_libvirt : Get libvirt password] ****************************
2026-03-18 11:57:11.654542 | controller | Wednesday 18 March 2026  11:57:11 +0000 (0:00:00.043)       0:01:31.374 *******
2026-03-18 11:57:11.654551 | controller | ok: [compute-1]
2026-03-18 11:57:11.677368 | controller |
2026-03-18 11:57:11.677398 | controller | TASK [osp.edpm.edpm_libvirt : Gather user fact] ********************************
2026-03-18 11:57:11.677412 | controller | Wednesday 18 March 2026  11:57:11 +0000 (0:00:00.033)       0:01:31.407 *******
2026-03-18 11:57:11.677427 | controller | skipping: [compute-1]
2026-03-18 11:57:12.819205 | controller |
2026-03-18 11:57:12.819237 | controller | TASK [osp.edpm.edpm_libvirt : Create libvirt config dir] ***********************
2026-03-18 11:57:12.819246 | controller | Wednesday 18 March 2026  11:57:11 +0000 (0:00:00.022)       0:01:31.430 *******
2026-03-18 11:57:12.819257 | controller | changed: [compute-1] => (item={'path': '/etc/tmpfiles.d/', 'owner': 'root', 'group': 'root'})
2026-03-18 11:57:12.837817 | controller | changed: [compute-1] => (item={'path': '/var/lib/edpm-config/firewall', 'owner': 'root', 'group': 'root'})
2026-03-18 11:57:12.837858 | controller | changed: [compute-1] => (item={'path': '/etc/pki/libvirt', 'owner': 'root', 'group': 'root', 'mode': '0755'})
2026-03-18 11:57:12.837866 | controller | changed: [compute-1] => (item={'path': '/etc/pki/libvirt/private', 'owner': 'root', 'group': 'root', 'mode': '0755'})
2026-03-18 11:57:12.837872 | controller | changed: [compute-1] => (item={'path': '/etc/pki/CA', 'owner': 'root', 'group': 'root', 'mode': '0755'})
2026-03-18 11:57:12.837877 | controller | changed: [compute-1] => (item={'path': '/etc/pki/qemu', 'owner': 'root', 'group': 'qemu'})
2026-03-18 11:57:12.837883 | controller |
2026-03-18 11:57:12.837888 | controller | TASK [osp.edpm.edpm_libvirt : Gather SELinux fact if needed] *******************
2026-03-18 11:57:12.837901 | controller | Wednesday 18 March 2026  11:57:12 +0000 (0:00:01.141)       0:01:32.571 *******
2026-03-18 11:57:12.837911 | controller | skipping: [compute-1]
2026-03-18 11:57:15.717776 | controller |
2026-03-18 11:57:15.717810 | controller | TASK [osp.edpm.edpm_libvirt : Render libvirt config files] *********************
2026-03-18 11:57:15.717818 | controller | Wednesday 18 March 2026  11:57:12 +0000 (0:00:00.019)       0:01:32.591 *******
2026-03-18 11:57:15.717835 | controller | changed: [compute-1] => (item={'src': 'virtlogd.conf', 'dest': 'libvirt/virtlogd.conf'})
2026-03-18 11:57:15.718148 | controller | changed: [compute-1] => (item={'src': 'virtnodedevd.conf', 'dest': 'libvirt/virtnodedevd.conf'})
2026-03-18 11:57:15.718162 | controller | changed: [compute-1] => (item={'src': 'virtproxyd.conf', 'dest': 'libvirt/virtproxyd.conf'})
2026-03-18 11:57:15.718169 | controller | changed: [compute-1] => (item={'src': 'virtqemud.conf', 'dest': 'libvirt/virtqemud.conf'})
2026-03-18 11:57:15.718174 | controller | changed: [compute-1] => (item={'src': 'qemu.conf.j2', 'dest': 'libvirt/qemu.conf'})
2026-03-18 11:57:15.718180 | controller | changed: [compute-1] => (item={'src': 'virtsecretd.conf', 'dest': 'libvirt/virtsecretd.conf'})
2026-03-18 11:57:15.718187 | controller | changed: [compute-1] => (item={'src': 'auth.conf', 'dest': 'libvirt/auth.conf', 'mode': '0600'})
2026-03-18 11:57:15.932522 | controller | changed: [compute-1] => (item={'src': 'sasl_libvirt.conf', 'dest': 'sasl2/libvirt.conf'})
2026-03-18 11:57:15.932548 | controller |
2026-03-18 11:57:15.932556 | controller | TASK [osp.edpm.edpm_libvirt : Add libvirt password to sasl db] *****************
2026-03-18 11:57:15.932562 | controller | Wednesday 18 March 2026  11:57:15 +0000 (0:00:02.879)       0:01:35.470 *******
2026-03-18 11:57:15.932571 | controller | changed: [compute-1]
2026-03-18 11:57:15.959192 | controller |
2026-03-18 11:57:15.959209 | controller | TASK [osp.edpm.edpm_libvirt : Remove libvirt sasl db] **************************
2026-03-18 11:57:15.959215 | controller | Wednesday 18 March 2026  11:57:15 +0000 (0:00:00.214)       0:01:35.685 *******
2026-03-18 11:57:15.959224 | controller | skipping: [compute-1]
2026-03-18 11:57:18.467778 | controller |
2026-03-18 11:57:18.467807 | controller | TASK [osp.edpm.edpm_libvirt : Create libvirt socket activation drop-in directories] ***
2026-03-18 11:57:18.467815 | controller | Wednesday 18 March 2026  11:57:15 +0000 (0:00:00.026)       0:01:35.712 *******
2026-03-18 11:57:18.467826 | controller | changed: [compute-1] => (item=['virtlogd', ''])
2026-03-18 11:57:18.467857 | controller | skipping: [compute-1] => (item=['virtlogd', '-ro'])
2026-03-18 11:57:18.467865 | controller | changed: [compute-1] => (item=['virtlogd', '-admin'])
2026-03-18 11:57:18.467885 | controller | changed: [compute-1] => (item=['virtnodedevd', ''])
2026-03-18 11:57:18.468761 | controller | changed: [compute-1] => (item=['virtnodedevd', '-ro'])
2026-03-18 11:57:18.469197 | controller | changed: [compute-1] => (item=['virtnodedevd', '-admin'])
2026-03-18 11:57:18.469213 | controller | changed: [compute-1] => (item=['virtproxyd', ''])
2026-03-18 11:57:18.469220 | controller | changed: [compute-1] => (item=['virtproxyd', '-ro'])
2026-03-18 11:57:18.469226 | controller | changed: [compute-1] => (item=['virtproxyd', '-admin'])
2026-03-18 11:57:18.469232 | controller | changed: [compute-1] => (item=['virtqemud', ''])
2026-03-18 11:57:18.469237 | controller | changed: [compute-1] => (item=['virtqemud', '-ro'])
2026-03-18 11:57:18.469243 | controller | changed: [compute-1] => (item=['virtqemud', '-admin'])
2026-03-18 11:57:18.469299 | controller | changed: [compute-1] => (item=['virtsecretd', ''])
2026-03-18 11:57:18.469306 | controller | changed: [compute-1] => (item=['virtsecretd', '-ro'])
2026-03-18 11:57:18.469315 | controller | changed: [compute-1] => (item=['virtsecretd', '-admin'])
2026-03-18 11:57:22.996775 | controller |
2026-03-18 11:57:22.996807 | controller | TASK [osp.edpm.edpm_libvirt : Render libvirt socket activation drop-in files] ***
2026-03-18 11:57:22.996815 | controller | Wednesday 18 March 2026  11:57:18 +0000 (0:00:02.508)       0:01:38.221 *******
2026-03-18 11:57:22.996836 | controller | changed: [compute-1] => (item=['virtlogd', ''])
2026-03-18 11:57:23.174914 | controller | skipping: [compute-1] => (item=['virtlogd', '-ro'])
2026-03-18 11:57:23.174943 | controller | changed: [compute-1] => (item=['virtlogd', '-admin'])
2026-03-18 11:57:23.174950 | controller | changed: [compute-1] => (item=['virtnodedevd', ''])
2026-03-18 11:57:23.174956 | controller | changed: [compute-1] => (item=['virtnodedevd', '-ro'])
2026-03-18 11:57:23.174967 | controller | changed: [compute-1] => (item=['virtnodedevd', '-admin'])
2026-03-18 11:57:23.174972 | controller | changed: [compute-1] => (item=['virtproxyd', ''])
2026-03-18 11:57:23.174978 | controller | changed: [compute-1] => (item=['virtproxyd', '-ro'])
2026-03-18 11:57:23.174983 | controller | changed: [compute-1] => (item=['virtproxyd', '-admin'])
2026-03-18 11:57:23.174988 | controller | changed: [compute-1] => (item=['virtqemud', ''])
2026-03-18 11:57:23.174993 | controller | changed: [compute-1] => (item=['virtqemud', '-ro'])
2026-03-18 11:57:23.174998 | controller | changed: [compute-1] => (item=['virtqemud', '-admin'])
2026-03-18 11:57:23.175003 | controller | changed: [compute-1] => (item=['virtsecretd', ''])
2026-03-18 11:57:23.175008 | controller | changed: [compute-1] => (item=['virtsecretd', '-ro'])
2026-03-18 11:57:23.175013 | controller | changed: [compute-1] => (item=['virtsecretd', '-admin'])
2026-03-18 11:57:23.175018 | controller |
2026-03-18 11:57:23.175024 | controller | TASK [osp.edpm.edpm_libvirt : Check if /run/libvirt has container_file_t selinux label] ***
2026-03-18 11:57:23.175029 | controller | Wednesday 18 March 2026  11:57:22 +0000 (0:00:04.527)       0:01:42.748 *******
2026-03-18 11:57:23.175039 | controller | ok: [compute-1]
2026-03-18 11:57:23.200188 | controller |
2026-03-18 11:57:23.200212 | controller | TASK [osp.edpm.edpm_libvirt : Restore selinux label for /run/libvirt to virt_var_run_t] ***
2026-03-18 11:57:23.200220 | controller | Wednesday 18 March 2026  11:57:23 +0000 (0:00:00.179)       0:01:42.928 *******
2026-03-18 11:57:23.200231 | controller | skipping: [compute-1]
2026-03-18 11:57:24.197460 | controller |
2026-03-18 11:57:24.197490 | controller | TASK [osp.edpm.edpm_libvirt : Enable os_enable_vtpm SELinux boolean for vTPM] ***
2026-03-18 11:57:24.197499 | controller | Wednesday 18 March 2026  11:57:23 +0000 (0:00:00.025)       0:01:42.953 *******
2026-03-18 11:57:24.197513 | controller | changed: [compute-1]
2026-03-18 11:57:25.085638 | controller |
2026-03-18 11:57:25.085670 | controller | TASK [osp.edpm.edpm_libvirt : Move libvirt TLS files to the right location on the compute node] ***
2026-03-18 11:57:25.085678 | controller | Wednesday 18 March 2026  11:57:24 +0000 (0:00:00.997)       0:01:43.950 *******
2026-03-18 11:57:25.085689 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.crt', 'dest': '/etc/pki/libvirt/servercert.pem'})
2026-03-18 11:57:25.985480 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.key', 'dest': '/etc/pki/libvirt/private/serverkey.pem', 'mode': '0600'})
2026-03-18 11:57:25.985513 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.crt', 'dest': '/etc/pki/libvirt/clientcert.pem'})
2026-03-18 11:57:25.985521 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.key', 'dest': '/etc/pki/libvirt/private/clientkey.pem'})
2026-03-18 11:57:25.985527 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/ca.crt', 'dest': '/etc/pki/CA/cacert.pem'})
2026-03-18 11:57:25.985533 | controller |
2026-03-18 11:57:25.985540 | controller | TASK [osp.edpm.edpm_libvirt : Move qemu TLS files to the right location on the compute node] ***
2026-03-18 11:57:25.985545 | controller | Wednesday 18 March 2026  11:57:25 +0000 (0:00:00.887)       0:01:44.838 *******
2026-03-18 11:57:25.985556 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.crt', 'dest': '/etc/pki/qemu/server-cert.pem'})
2026-03-18 11:57:28.471735 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.key', 'dest': '/etc/pki/qemu/server-key.pem'})
2026-03-18 11:57:28.471767 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.crt', 'dest': '/etc/pki/qemu/client-cert.pem'})
2026-03-18 11:57:28.471774 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.key', 'dest': '/etc/pki/qemu/client-key.pem'})
2026-03-18 11:57:28.471780 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/ca.crt', 'dest': '/etc/pki/qemu/ca-cert.pem'})
2026-03-18 11:57:28.471786 | controller |
2026-03-18 11:57:28.471791 | controller | TASK [osp.edpm.edpm_libvirt : Flush handlers] **********************************
2026-03-18 11:57:28.471797 | controller | Wednesday 18 March 2026  11:57:25 +0000 (0:00:00.888)       0:01:45.727 *******
2026-03-18 11:57:28.471802 | controller |
2026-03-18 11:57:28.471807 | controller | RUNNING HANDLER [osp.edpm.edpm_libvirt : Restart libvirt services] *************
2026-03-18 11:57:28.471812 | controller | Wednesday 18 March 2026  11:57:25 +0000 (0:00:00.010)       0:01:45.738 *******
2026-03-18 11:57:28.471836 | controller | changed: [compute-1] => (item=virtlogd)
2026-03-18 11:57:28.511745 | controller | changed: [compute-1] => (item=virtnodedevd)
2026-03-18 11:57:28.511765 | controller | changed: [compute-1] => (item=virtproxyd)
2026-03-18 11:57:28.511773 | controller | changed: [compute-1] => (item=virtqemud)
2026-03-18 11:57:28.511778 | controller | changed: [compute-1] => (item=virtsecretd)
2026-03-18 11:57:28.511784 | controller |
2026-03-18 11:57:28.511789 | controller | TASK [osp.edpm.edpm_libvirt : Post-install libvirt] ****************************
2026-03-18 11:57:28.511794 | controller | Wednesday 18 March 2026  11:57:28 +0000 (0:00:02.486)       0:01:48.224 *******
2026-03-18 11:57:28.511803 | 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-18 11:57:28.718670 | controller |
2026-03-18 11:57:28.718700 | controller | TASK [osp.edpm.edpm_libvirt : Disable libvirt default network autostart] *******
2026-03-18 11:57:28.718723 | controller | Wednesday 18 March 2026  11:57:28 +0000 (0:00:00.040)       0:01:48.265 *******
2026-03-18 11:57:28.718737 | controller | fatal: [compute-1]: FAILED! => changed=false
2026-03-18 11:57:28.722776 | controller |   cmd:
2026-03-18 11:57:28.722797 | controller |   - virsh
2026-03-18 11:57:28.722804 | controller |   - net-autostart
2026-03-18 11:57:28.722810 | controller |   - --network
2026-03-18 11:57:28.722815 | controller |   - default
2026-03-18 11:57:28.722820 | controller |   - --disable
2026-03-18 11:57:28.722834 | controller |   delta: '0:00:00.022340'
2026-03-18 11:57:28.722840 | controller |   end: '2026-03-18 11:57:28.688422'
2026-03-18 11:57:28.722845 | controller |   failed_when_result: true
2026-03-18 11:57:28.722850 | controller |   msg: non-zero return code
2026-03-18 11:57:28.722855 | controller |   rc: 1
2026-03-18 11:57:28.722860 | controller |   start: '2026-03-18 11:57:28.666082'
2026-03-18 11:57:28.722865 | controller |   stderr: |-
2026-03-18 11:57:28.722871 | controller |     error: failed to get network 'default'
2026-03-18 11:57:28.722876 | controller |     error: Failed to connect socket to '/var/run/libvirt/virtnetworkd-sock': No such file or directory
2026-03-18 11:57:28.722881 | controller |   stderr_lines: <omitted>
2026-03-18 11:57:28.722886 | controller |   stdout: ''
2026-03-18 11:57:28.722891 | controller |   stdout_lines: <omitted>
2026-03-18 11:57:28.722900 | controller |
2026-03-18 11:57:28.724888 | controller | PLAY RECAP *********************************************************************
2026-03-18 11:57:28.724908 | controller | compute-1                  : ok=44   changed=16   unreachable=0    failed=1    skipped=14   rescued=0    ignored=1
2026-03-18 11:57:28.724916 | controller |
2026-03-18 11:57:28.724922 | controller | Wednesday 18 March 2026  11:57:28 +0000 (0:00:00.209)       0:01:48.474 *******
2026-03-18 11:57:28.724927 | controller | ===============================================================================
2026-03-18 11:57:28.724932 | controller | osp.edpm.edpm_libvirt : Install libvirt packages ----------------------- 70.73s
2026-03-18 11:57:28.724937 | controller | osp.edpm.edpm_libvirt : Render libvirt socket activation drop-in files --- 4.53s
2026-03-18 11:57:28.724942 | controller | osp.edpm.edpm_libvirt : Configure socket activation for libvirt services --- 4.16s
2026-03-18 11:57:28.724947 | controller | osp.edpm.edpm_libvirt : Render libvirt config files --------------------- 2.88s
2026-03-18 11:57:28.724952 | controller | osp.edpm.edpm_libvirt : Create libvirt socket activation drop-in directories --- 2.51s
2026-03-18 11:57:28.724958 | controller | osp.edpm.edpm_libvirt : Restart libvirt services ------------------------ 2.49s
2026-03-18 11:57:28.724963 | controller | osp.edpm.edpm_libvirt : Ensure libvirt services are enabled and running --- 2.30s
2026-03-18 11:57:28.724968 | controller | osp.edpm.edpm_libvirt : Ensure monolithic libvirt and tcp socket activation is not enabled or running --- 2.14s
2026-03-18 11:57:28.724973 | controller | osp.edpm.edpm_tripleo_cleanup : Stop and disable tripleo services ------- 1.83s
2026-03-18 11:57:28.724978 | controller | osp.edpm.edpm_tripleo_cleanup : Gather service facts -------------------- 1.66s
2026-03-18 11:57:28.724983 | controller | osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /usr/lib/systemd/system --- 1.29s
2026-03-18 11:57:28.724988 | controller | osp.edpm.edpm_tripleo_cleanup : Reset state of failed systemd units ----- 1.28s
2026-03-18 11:57:28.724993 | controller | osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /etc/systemd/system --- 1.22s
2026-03-18 11:57:28.725011 | controller | osp.edpm.edpm_libvirt : Create libvirt config dir ----------------------- 1.14s
2026-03-18 11:57:28.725103 | controller | osp.edpm.edpm_libvirt : Enable os_enable_vtpm SELinux boolean for vTPM --- 1.00s
2026-03-18 11:57:28.725117 | controller | osp.edpm.edpm_libvirt : Move qemu TLS files to the right location on the compute node --- 0.89s
2026-03-18 11:57:28.725219 | controller | osp.edpm.edpm_libvirt : Move libvirt TLS files to the right location on the compute node --- 0.89s
2026-03-18 11:57:28.725314 | controller | Gathering Facts --------------------------------------------------------- 0.77s
2026-03-18 11:57:28.725410 | controller | osp.edpm.edpm_tripleo_cleanup : Reload units to check for new changes --- 0.61s
2026-03-18 11:57:28.725514 | controller | osp.edpm.edpm_libvirt : Configure virtproxyd-tls.socket ----------------- 0.54s
2026-03-18 11:57:28.774384 | 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-18 11:57:28.774780 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2026-03-18 11:57:28.783252 | controller | INFO     Running default > cleanup
2026-03-18 11:57:28.783626 | controller | WARNING  Skipping, cleanup playbook not configured.
2026-03-18 11:57:28.790445 | controller | INFO     Running default > destroy
2026-03-18 11:57:28.790735 | controller | WARNING  Skipping, instances are delegated.
2026-03-18 11:57:28.791028 | controller | INFO     Pruning extra files from scenario ephemeral directory
2026-03-18 11:57:28.792488 | controller | INFO     Writing /tmp/report.html report.
2026-03-18 11:57:28.906509 | controller | ERROR
2026-03-18 11:57:28.906606 | controller | {
2026-03-18 11:57:28.906636 | controller |   "delta": "0:04:01.435763",
2026-03-18 11:57:28.906657 | controller |   "end": "2026-03-18 11:57:28.837647",
2026-03-18 11:57:28.906673 | controller |   "msg": "non-zero return code",
2026-03-18 11:57:28.906688 | controller |   "rc": 1,
2026-03-18 11:57:28.906702 | controller |   "start": "2026-03-18 11:53:27.401884"
2026-03-18 11:57:28.906716 | controller | }
failure
2026-03-18 11:57:28.953354 | 
2026-03-18 11:57:28.953403 | PLAY RECAP
2026-03-18 11:57:28.953454 | controller | ok: 3 changed: 1 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-03-18 11:57:28.953476 | 
2026-03-18 11:57:29.012140 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-03-18 11:57:29.012895 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-18 11:57:29.515527 | 
2026-03-18 11:57:29.515656 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-03-18 11:57:29.534748 | 
2026-03-18 11:57:29.534819 | TASK [Filter out host if needed]
2026-03-18 11:57:29.542496 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-03-18 11:57:29.546685 | 
2026-03-18 11:57:29.546751 | TASK [Ensure file is present]
2026-03-18 11:57:29.827669 | controller | ok
2026-03-18 11:57:29.833888 | 
2026-03-18 11:57:29.833952 | TASK [Manage molecule report file]
2026-03-18 11:57:30.357220 | controller | changed
2026-03-18 11:57:30.362210 | 
2026-03-18 11:57:30.362279 | TASK [Check if we get ci-framework-data basedir]
2026-03-18 11:57:30.532227 | controller | ok
2026-03-18 11:57:30.537255 | 
2026-03-18 11:57:30.537344 | TASK [Create ci-framework-data log directory for zuul]
2026-03-18 11:57:30.819864 | controller | changed
2026-03-18 11:57:30.824648 | 
2026-03-18 11:57:30.824713 | TASK [Copy ci-framework interesting files]
2026-03-18 11:57:31.002928 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-03-18 11:57:31.348934 | controller | changed
2026-03-18 11:57:31.353530 | 
2026-03-18 11:57:31.353592 | TASK [Get SELinux listing]
2026-03-18 11:57:31.877549 | controller | changed
2026-03-18 11:57:31.882697 | 
2026-03-18 11:57:31.882762 | TASK [Generate log index]
2026-03-18 11:57:32.459287 | controller | changed
2026-03-18 11:57:32.463763 | 
2026-03-18 11:57:32.463826 | TASK [Get some env related data]
2026-03-18 11:57:32.995832 | controller | /home/zuul/.local/bin/ansible
2026-03-18 11:57:33.989458 | controller | changed
2026-03-18 11:57:33.994257 | 
2026-03-18 11:57:33.994330 | TASK [Generate list of logs to collect in home directory]
2026-03-18 11:57:34.266238 | controller | ok: All paths examined
2026-03-18 11:57:34.270737 | 
2026-03-18 11:57:34.270796 | LOOP [Copy logs from home directory]
2026-03-18 11:57:34.555197 | controller | changed:
2026-03-18 11:57:34.555447 | controller | {
2026-03-18 11:57:34.555498 | controller |   "atime": 1773834788.9647994,
2026-03-18 11:57:34.555522 | controller |   "ctime": 1773834805.327038,
2026-03-18 11:57:34.555541 | controller |   "dev": 64513,
2026-03-18 11:57:34.555558 | controller |   "gid": 1000,
2026-03-18 11:57:34.555573 | controller |   "gr_name": "zuul",
2026-03-18 11:57:34.555589 | controller |   "inode": 1190556,
2026-03-18 11:57:34.555604 | controller |   "isblk": false,
2026-03-18 11:57:34.555619 | controller |   "ischr": false,
2026-03-18 11:57:34.555634 | controller |   "isdir": false,
2026-03-18 11:57:34.555648 | controller |   "isfifo": false,
2026-03-18 11:57:34.555664 | controller |   "isgid": false,
2026-03-18 11:57:34.555678 | controller |   "islnk": false,
2026-03-18 11:57:34.555692 | controller |   "isreg": true,
2026-03-18 11:57:34.555707 | controller |   "issock": false,
2026-03-18 11:57:34.555721 | controller |   "isuid": false,
2026-03-18 11:57:34.555735 | controller |   "mode": "0644",
2026-03-18 11:57:34.555749 | controller |   "mtime": 1773834805.327038,
2026-03-18 11:57:34.555763 | controller |   "nlink": 1,
2026-03-18 11:57:34.555777 | controller |   "path": "/home/zuul/ansible.log",
2026-03-18 11:57:34.555792 | controller |   "pw_name": "zuul",
2026-03-18 11:57:34.555807 | controller |   "rgrp": true,
2026-03-18 11:57:34.555820 | controller |   "roth": true,
2026-03-18 11:57:34.555834 | controller |   "rusr": true,
2026-03-18 11:57:34.555848 | controller |   "size": 6749,
2026-03-18 11:57:34.555861 | controller |   "uid": 1000,
2026-03-18 11:57:34.555874 | controller |   "wgrp": false,
2026-03-18 11:57:34.555887 | controller |   "woth": false,
2026-03-18 11:57:34.555903 | controller |   "wusr": true,
2026-03-18 11:57:34.555917 | controller |   "xgrp": false,
2026-03-18 11:57:34.555931 | controller |   "xoth": false,
2026-03-18 11:57:34.555944 | controller |   "xusr": false
2026-03-18 11:57:34.555960 | controller | }
2026-03-18 11:57:34.568954 | 
2026-03-18 11:57:34.569028 | TASK [Copy crio stats log file]
2026-03-18 11:57:34.582458 | controller | skipping: Conditional result was False
2026-03-18 11:57:34.587366 | 
2026-03-18 11:57:34.587446 | TASK [Get SELinux related data]
2026-03-18 11:57:34.778423 | controller | <no matches>
2026-03-18 11:57:35.112280 | controller | ERROR
2026-03-18 11:57:35.112498 | controller | {
2026-03-18 11:57:35.112532 | controller |   "delta": "0:00:00.009007",
2026-03-18 11:57:35.112561 | controller |   "end": "2026-03-18 11:57:34.779010",
2026-03-18 11:57:35.112581 | controller |   "msg": "non-zero return code",
2026-03-18 11:57:35.112597 | controller |   "rc": 1,
2026-03-18 11:57:35.112612 | controller |   "start": "2026-03-18 11:57:34.770003"
2026-03-18 11:57:35.112626 | controller | }
2026-03-18 11:57:35.112646 | controller | ERROR: Ignoring Errors
2026-03-18 11:57:35.117336 | 
2026-03-18 11:57:35.117402 | TASK [Create system configuration directory]
2026-03-18 11:57:35.287379 | controller | changed
2026-03-18 11:57:35.292020 | 
2026-03-18 11:57:35.292080 | TASK [Get some of the system configurations]
2026-03-18 11:57:35.816887 | controller | changed
2026-03-18 11:57:35.821180 | 
2026-03-18 11:57:35.821234 | TASK [Copy generated documentation if available]
2026-03-18 11:57:35.834657 | controller | skipping: Conditional result was False
2026-03-18 11:57:35.839624 | 
2026-03-18 11:57:35.839685 | TASK [Copy generated AsciiDoc documentation if available]
2026-03-18 11:57:35.852876 | controller | skipping: Conditional result was False
2026-03-18 11:57:35.857620 | 
2026-03-18 11:57:35.857676 | TASK [Compress logs bigger than 2MB]
2026-03-18 11:57:36.381397 | controller | changed
2026-03-18 11:57:36.386377 | 
2026-03-18 11:57:36.386443 | TASK [Copy files from workspace on node]
2026-03-18 11:57:36.403457 | controller | ok
2026-03-18 11:57:36.423001 | 
2026-03-18 11:57:36.423062 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-18 11:57:36.435567 | controller | skipping: Conditional result was False
2026-03-18 11:57:36.440390 | 
2026-03-18 11:57:36.440454 | TASK [fetch-output : Set log path for single node]
2026-03-18 11:57:36.467464 | controller | ok
2026-03-18 11:57:36.472168 | 
2026-03-18 11:57:36.472228 | LOOP [fetch-output : Ensure local output dirs]
2026-03-18 11:57:36.658114 | controller -> localhost | ok: "/var/lib/zuul/builds/5ecbda745dce42cebfca4964d3a29418/work/logs"
2026-03-18 11:57:36.658356 | controller -> localhost | changed: All items complete
2026-03-18 11:57:36.658390 | 
2026-03-18 11:57:36.822795 | controller -> localhost | changed: "/var/lib/zuul/builds/5ecbda745dce42cebfca4964d3a29418/work/artifacts"
2026-03-18 11:57:36.987685 | controller -> localhost | changed: "/var/lib/zuul/builds/5ecbda745dce42cebfca4964d3a29418/work/docs"
2026-03-18 11:57:37.001474 | 
2026-03-18 11:57:37.001587 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-18 11:57:37.517757 | controller | changed:
2026-03-18 11:57:37.517919 | controller | .d..t...... ./
2026-03-18 11:57:37.517949 | controller | >f+++++++++ README.html
2026-03-18 11:57:37.517970 | controller | >f+++++++++ ansible-execution.log
2026-03-18 11:57:37.517990 | controller | >f+++++++++ ansible.log
2026-03-18 11:57:37.518008 | controller | >f+++++++++ dmesg.log
2026-03-18 11:57:37.518024 | controller | >f+++++++++ installed-pkgs.log
2026-03-18 11:57:37.518043 | controller | >f+++++++++ python.log
2026-03-18 11:57:37.518063 | controller | >f+++++++++ registries.conf
2026-03-18 11:57:37.518079 | controller | >f+++++++++ report.html
2026-03-18 11:57:37.518097 | controller | >f+++++++++ selinux-denials.log
2026-03-18 11:57:37.518114 | controller | >f+++++++++ selinux-listing.log
2026-03-18 11:57:37.518130 | controller | cd+++++++++ ci-framework-data/
2026-03-18 11:57:37.518145 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-03-18 11:57:37.518160 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-03-18 11:57:37.518176 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-03-18 11:57:37.518191 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-03-18 11:57:37.518205 | controller | cd+++++++++ ci-framework-data/logs/
2026-03-18 11:57:37.518220 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-03-18 11:57:37.518234 | controller | cd+++++++++ registries.conf.d/
2026-03-18 11:57:37.518248 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-03-18 11:57:37.518263 | controller | cd+++++++++ system-config/
2026-03-18 11:57:37.518281 | controller | cd+++++++++ system-config/libvirt/
2026-03-18 11:57:37.518313 | controller | >f+++++++++ system-config/libvirt/auth.conf
2026-03-18 11:57:37.518339 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-03-18 11:57:37.518356 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-03-18 11:57:37.518372 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2026-03-18 11:57:37.518387 | controller | >f+++++++++ system-config/libvirt/network.conf
2026-03-18 11:57:37.518402 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2026-03-18 11:57:37.518417 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2026-03-18 11:57:37.518431 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2026-03-18 11:57:37.518446 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2026-03-18 11:57:37.518460 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2026-03-18 11:57:37.518475 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2026-03-18 11:57:37.518498 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2026-03-18 11:57:37.518514 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2026-03-18 11:57:37.518529 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2026-03-18 11:57:37.518544 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2026-03-18 11:57:37.518558 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2026-03-18 11:57:37.518572 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2026-03-18 11:57:37.887860 | controller | changed: .d..t...... ./
2026-03-18 11:57:38.248846 | controller | changed: .d..t...... ./
2026-03-18 11:57:38.262589 | 
2026-03-18 11:57:38.262689 | TASK [Return artifact to Zuul]
2026-03-18 11:57:38.290741 | controller | ok
2026-03-18 11:57:38.308659 | 
2026-03-18 11:57:38.308715 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-03-18 11:57:38.308809 | 
2026-03-18 11:57:38.308834 | PLAY RECAP
2026-03-18 11:57:38.308871 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-03-18 11:57:38.308890 | 
2026-03-18 11:57:38.377322 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-18 11:57:38.377979 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-18 11:57:38.843897 | 
2026-03-18 11:57:38.843986 | PLAY [all]
2026-03-18 11:57:38.860929 | 
2026-03-18 11:57:38.860999 | TASK [include_role : fetch-output]
2026-03-18 11:57:38.888988 | controller | ok
2026-03-18 11:57:38.903857 | 
2026-03-18 11:57:38.903931 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-18 11:57:38.938208 | controller | skipping: Conditional result was False
2026-03-18 11:57:38.943737 | 
2026-03-18 11:57:38.943807 | TASK [fetch-output : Set log path for single node]
2026-03-18 11:57:38.971793 | controller | ok
2026-03-18 11:57:38.976496 | 
2026-03-18 11:57:38.976561 | LOOP [fetch-output : Ensure local output dirs]
2026-03-18 11:57:39.281894 | controller -> localhost | ok: "/var/lib/zuul/builds/5ecbda745dce42cebfca4964d3a29418/work/logs"
2026-03-18 11:57:39.451969 | controller -> localhost | ok: "/var/lib/zuul/builds/5ecbda745dce42cebfca4964d3a29418/work/artifacts"
2026-03-18 11:57:39.617391 | controller -> localhost | ok: "/var/lib/zuul/builds/5ecbda745dce42cebfca4964d3a29418/work/docs"
2026-03-18 11:57:39.633971 | 
2026-03-18 11:57:39.634063 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-18 11:57:40.116105 | controller | ok
2026-03-18 11:57:40.116271 | controller | ok: All items complete
2026-03-18 11:57:40.116322 | 
2026-03-18 11:57:40.490680 | controller | ok
2026-03-18 11:57:40.864641 | controller | ok
2026-03-18 11:57:40.883802 | 
2026-03-18 11:57:40.883907 | TASK [include_role : fetch-output-openshift]
2026-03-18 11:57:40.897102 | controller | skipping: Conditional result was False
2026-03-18 11:57:40.902454 | 
2026-03-18 11:57:40.902538 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-18 11:57:41.209626 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.005670
2026-03-18 11:57:41.388204 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005819
2026-03-18 11:57:41.413784 | 
2026-03-18 11:57:41.413872 | PLAY [all]
2026-03-18 11:57:41.426585 | 
2026-03-18 11:57:41.426649 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-18 11:57:41.820559 | controller | changed
2026-03-18 11:57:41.841234 | 
2026-03-18 11:57:41.841288 | PLAY RECAP
2026-03-18 11:57:41.841370 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-03-18 11:57:41.841394 | 
2026-03-18 11:57:41.908034 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-18 11:57:41.908765 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-03-18 11:57:42.432545 | 
2026-03-18 11:57:42.432682 | PLAY [localhost]
2026-03-18 11:57:42.449909 | 
2026-03-18 11:57:42.449984 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-18 11:57:42.751631 | localhost | changed
2026-03-18 11:57:42.756142 | 
2026-03-18 11:57:42.756254 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-18 11:57:42.775802 | localhost | ok
2026-03-18 11:57:42.783494 | 
2026-03-18 11:57:42.783563 | TASK [add-fileserver : Create SSH private key tempfile]
2026-03-18 11:57:43.136550 | localhost | changed
2026-03-18 11:57:43.142198 | 
2026-03-18 11:57:43.142289 | TASK [add-fileserver : Create SSH private key from secret]
2026-03-18 11:57:43.665777 | localhost | changed
2026-03-18 11:57:43.670714 | 
2026-03-18 11:57:43.670787 | TASK [add-fileserver : Add fileserver ssh key]
2026-03-18 11:57:43.981035 | localhost | Identity added: /var/lib/zuul/builds/5ecbda745dce42cebfca4964d3a29418/work/tmp/ansible.req5ergc (/var/lib/zuul/builds/5ecbda745dce42cebfca4964d3a29418/work/tmp/ansible.req5ergc)
2026-03-18 11:57:43.981184 | localhost | ok: Runtime: 0:00:00.005837
2026-03-18 11:57:43.985077 | 
2026-03-18 11:57:43.985147 | TASK [add-fileserver : Remove SSH private key from disk]
2026-03-18 11:57:44.184368 | localhost | ok: Runtime: 0:00:00.004005
2026-03-18 11:57:44.188884 | 
2026-03-18 11:57:44.188958 | TASK [add-fileserver : Add fileserver to inventory]
2026-03-18 11:57:44.236559 | localhost | changed
2026-03-18 11:57:44.241462 | 
2026-03-18 11:57:44.241544 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-03-18 11:57:44.541772 | localhost | changed
2026-03-18 11:57:44.560974 | 
2026-03-18 11:57:44.561036 | PLAY [localhost]
2026-03-18 11:57:44.571554 | 
2026-03-18 11:57:44.571612 | TASK [Generate bulk log download script]
2026-03-18 11:57:44.589277 | localhost | ok
2026-03-18 11:57:44.599563 | 
2026-03-18 11:57:44.599627 | TASK [local-log-download : Check API endpoint is defined]
2026-03-18 11:57:44.626263 | localhost | ok: All assertions passed
2026-03-18 11:57:44.630573 | 
2026-03-18 11:57:44.630638 | TASK [local-log-download : Create download script]
2026-03-18 11:57:44.957468 | localhost -> localhost | changed
2026-03-18 11:57:44.965270 | 
2026-03-18 11:57:44.965348 | TASK [Register quick-download link]
2026-03-18 11:57:44.982070 | localhost | ok
2026-03-18 11:57:45.013816 | 
2026-03-18 11:57:45.013881 | PLAY [logserver.rdoproject.org]
2026-03-18 11:57:45.022209 | 
2026-03-18 11:57:45.022263 | TASK [Set zuul-log-path fact]
2026-03-18 11:57:45.037670 | logserver.rdoproject.org | ok
2026-03-18 11:57:45.045680 | 
2026-03-18 11:57:45.045740 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-18 11:57:45.070946 | logserver.rdoproject.org | ok
2026-03-18 11:57:45.076038 | 
2026-03-18 11:57:45.076105 | TASK [upload-logs : Create log directories]
2026-03-18 11:57:46.809810 | logserver.rdoproject.org | changed
2026-03-18 11:57:46.812674 | 
2026-03-18 11:57:46.812733 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-18 11:57:47.018128 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004146
2026-03-18 11:57:47.022613 | 
2026-03-18 11:57:47.022685 | TASK [upload-logs : Upload logs to log server]
2026-03-18 11:57:48.480270 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-03-18 11:57:48.482869 | 
2026-03-18 11:57:48.482926 | LOOP [upload-logs : Compress console log and json output]
2026-03-18 11:57:48.517981 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-18 11:57:48.525249 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-18 11:57:48.529502 | 
2026-03-18 11:57:48.529565 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-18 11:57:48.562024 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-18 11:57:48.562191 | 
2026-03-18 11:57:48.565121 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-18 11:57:48.575734 | 
2026-03-18 11:57:48.575796 | LOOP [upload-logs : Upload console log and json output]