2026-03-19 05:35:54.797940 | Job console starting...
2026-03-19 05:35:55.006064 | Updating repositories
2026-03-19 05:35:55.681792 | Preparing job workspace
2026-03-19 05:36:02.398610 | Running Ansible setup...
2026-03-19 05:36:05.159742 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-19 05:36:05.636584 | 
2026-03-19 05:36:05.636691 | PLAY [localhost]
2026-03-19 05:36:05.644541 | 
2026-03-19 05:36:05.644606 | TASK [Gathering Facts]
2026-03-19 05:36:06.420119 | localhost | ok
2026-03-19 05:36:06.432750 | 
2026-03-19 05:36:06.432853 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-19 05:36:06.730247 | localhost -> localhost | changed
2026-03-19 05:36:06.735192 | 
2026-03-19 05:36:06.735258 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-19 05:36:07.368421 | localhost -> localhost | changed
2026-03-19 05:36:07.376134 | 
2026-03-19 05:36:07.376211 | TASK [Setup log path fact]
2026-03-19 05:36:07.393676 | localhost | ok
2026-03-19 05:36:07.403902 | 
2026-03-19 05:36:07.403966 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-19 05:36:07.431666 | localhost | ok
2026-03-19 05:36:07.438329 | 
2026-03-19 05:36:07.438393 | TASK [emit-job-header : Print job information]
2026-03-19 05:36:07.465878 | # Job Information
2026-03-19 05:36:07.465985 | Ansible Version: 2.15.12
2026-03-19 05:36:07.466013 | Job: edpm-ansible-molecule-edpm_libvirt
2026-03-19 05:36:07.466057 | Pipeline: github-check
2026-03-19 05:36:07.466079 | Executor: ibm-bm3-ze.softwarefactory-project.io
2026-03-19 05:36:07.466097 | Triggered by: https://github.com/openstack-k8s-operators/edpm-ansible/pull/1114
2026-03-19 05:36:07.466115 | Log URL (when completed): https://logserver.rdoproject.org/b30/rdoproject.org/b3050f50a1664d0cbe168fc853832b15/
2026-03-19 05:36:07.466132 | Event ID: 5dc01de0-2355-11f1-834c-cc9cc762b8c2
2026-03-19 05:36:07.469530 | 
2026-03-19 05:36:07.469590 | LOOP [emit-job-header : Print node information]
2026-03-19 05:36:07.549397 | localhost | ok:
2026-03-19 05:36:07.549615 | localhost | # Node Information
2026-03-19 05:36:07.549643 | localhost | Inventory Hostname: controller
2026-03-19 05:36:07.549664 | localhost | Hostname: np0005651444
2026-03-19 05:36:07.549682 | localhost | Username: zuul
2026-03-19 05:36:07.549701 | localhost | Distro: CentOS 9
2026-03-19 05:36:07.549716 | localhost | Provider: ibm-bm3-nodepool
2026-03-19 05:36:07.549731 | localhost | Region: regionOne
2026-03-19 05:36:07.549746 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2026-03-19 05:36:07.549760 | localhost | Product Name: OpenStack Compute
2026-03-19 05:36:07.549773 | localhost | Interface IP: 192.168.25.38
2026-03-19 05:36:07.570938 | 
2026-03-19 05:36:07.571005 | PLAY [all]
2026-03-19 05:36:07.576632 | 
2026-03-19 05:36:07.576693 | TASK [Gather network facts]
2026-03-19 05:36:07.930296 | controller | ok
2026-03-19 05:36:07.942519 | 
2026-03-19 05:36:07.942577 | TASK [include_role : start-zuul-console]
2026-03-19 05:36:07.959864 | controller | ok
2026-03-19 05:36:07.970835 | 
2026-03-19 05:36:07.970893 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-19 05:36:08.262186 | controller | ok
2026-03-19 05:36:08.269691 | 
2026-03-19 05:36:08.269748 | TASK [include_role : add-build-sshkey]
2026-03-19 05:36:08.287294 | controller | ok
2026-03-19 05:36:08.298534 | 
2026-03-19 05:36:08.298597 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-19 05:36:08.484046 | controller -> localhost | ok
2026-03-19 05:36:08.489151 | 
2026-03-19 05:36:08.489237 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-19 05:36:08.506682 | controller | ok
2026-03-19 05:36:08.519008 | controller | included: /var/lib/zuul/builds/b3050f50a1664d0cbe168fc853832b15/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-19 05:36:08.523988 | 
2026-03-19 05:36:08.524045 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-19 05:36:08.962409 | controller -> localhost | Generating public/private rsa key pair.
2026-03-19 05:36:08.962617 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b3050f50a1664d0cbe168fc853832b15/work/b3050f50a1664d0cbe168fc853832b15_id_rsa.
2026-03-19 05:36:08.962652 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b3050f50a1664d0cbe168fc853832b15/work/b3050f50a1664d0cbe168fc853832b15_id_rsa.pub.
2026-03-19 05:36:08.962677 | controller -> localhost | The key fingerprint is:
2026-03-19 05:36:08.962699 | controller -> localhost | SHA256:KpCOb5qSrsvOQVWtdqZhW3j0+OnAHP93zzTi64/ZSi4 zuul-build-sshkey
2026-03-19 05:36:08.962721 | controller -> localhost | The key's randomart image is:
2026-03-19 05:36:08.962741 | controller -> localhost | +---[RSA 3072]----+
2026-03-19 05:36:08.962761 | controller -> localhost | |     ..          |
2026-03-19 05:36:08.962781 | controller -> localhost | |    .  o         |
2026-03-19 05:36:08.962801 | controller -> localhost | |   .  + o        |
2026-03-19 05:36:08.962820 | controller -> localhost | |  .. * B .       |
2026-03-19 05:36:08.962840 | controller -> localhost | | .o o @ S .      |
2026-03-19 05:36:08.962859 | controller -> localhost | |.o . o = +       |
2026-03-19 05:36:08.962878 | controller -> localhost | |.o. . . o .  o ..|
2026-03-19 05:36:08.962900 | controller -> localhost | |=oo  .   . E+.=+.|
2026-03-19 05:36:08.962921 | controller -> localhost | |XO.         +O=o+|
2026-03-19 05:36:08.962941 | controller -> localhost | +----[SHA256]-----+
2026-03-19 05:36:08.962990 | controller -> localhost | ok: Runtime: 0:00:00.128342
2026-03-19 05:36:08.968736 | 
2026-03-19 05:36:08.968805 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-19 05:36:08.987476 | controller | ok
2026-03-19 05:36:08.996208 | controller | included: /var/lib/zuul/builds/b3050f50a1664d0cbe168fc853832b15/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-19 05:36:09.003187 | 
2026-03-19 05:36:09.003244 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-19 05:36:09.016656 | controller | skipping: Conditional result was False
2026-03-19 05:36:09.021425 | 
2026-03-19 05:36:09.021488 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-19 05:36:09.373442 | controller | changed
2026-03-19 05:36:09.378631 | 
2026-03-19 05:36:09.378687 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-19 05:36:09.559716 | controller | ok
2026-03-19 05:36:09.563948 | 
2026-03-19 05:36:09.564009 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-19 05:36:10.130669 | controller | changed
2026-03-19 05:36:10.134956 | 
2026-03-19 05:36:10.135013 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-19 05:36:10.701820 | controller | changed
2026-03-19 05:36:10.706095 | 
2026-03-19 05:36:10.706159 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-19 05:36:10.719607 | controller | skipping: Conditional result was False
2026-03-19 05:36:10.724805 | 
2026-03-19 05:36:10.724865 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-19 05:36:11.002403 | controller -> localhost | changed
2026-03-19 05:36:11.010673 | 
2026-03-19 05:36:11.010735 | TASK [add-build-sshkey : Add back temp key]
2026-03-19 05:36:11.216978 | controller -> localhost | Identity added: /var/lib/zuul/builds/b3050f50a1664d0cbe168fc853832b15/work/b3050f50a1664d0cbe168fc853832b15_id_rsa (zuul-build-sshkey)
2026-03-19 05:36:11.217204 | controller -> localhost | ok: Runtime: 0:00:00.006694
2026-03-19 05:36:11.222453 | 
2026-03-19 05:36:11.222512 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-19 05:36:11.495308 | controller | ok
2026-03-19 05:36:11.499379 | 
2026-03-19 05:36:11.499439 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-19 05:36:11.523560 | controller | skipping: Conditional result was False
2026-03-19 05:36:11.532086 | 
2026-03-19 05:36:11.532157 | TASK [include_role : validate-host]
2026-03-19 05:36:11.549958 | controller | ok
2026-03-19 05:36:11.567980 | 
2026-03-19 05:36:11.568044 | TASK [validate-host : Define zuul_info_dir fact]
2026-03-19 05:36:11.595889 | controller | ok
2026-03-19 05:36:11.599906 | 
2026-03-19 05:36:11.599963 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-03-19 05:36:11.786460 | controller -> localhost | ok
2026-03-19 05:36:11.791471 | 
2026-03-19 05:36:11.791533 | TASK [validate-host : Collect information about the host]
2026-03-19 05:36:12.411129 | controller | ok
2026-03-19 05:36:12.418615 | 
2026-03-19 05:36:12.418678 | TASK [validate-host : Sanitize hostname]
2026-03-19 05:36:12.486324 | controller | ok
2026-03-19 05:36:12.490785 | 
2026-03-19 05:36:12.490847 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-03-19 05:36:12.850729 | controller -> localhost | changed
2026-03-19 05:36:12.879092 | 
2026-03-19 05:36:12.879198 | TASK [validate-host : Collect information about zuul worker]
2026-03-19 05:36:13.191243 | controller | ok
2026-03-19 05:36:13.195461 | 
2026-03-19 05:36:13.195522 | TASK [validate-host : Write out all zuul information for each host]
2026-03-19 05:36:13.525016 | controller -> localhost | changed
2026-03-19 05:36:13.533444 | 
2026-03-19 05:36:13.533508 | TASK [include_role : prepare-workspace-openshift]
2026-03-19 05:36:13.547045 | controller | skipping: Conditional result was False
2026-03-19 05:36:13.551476 | 
2026-03-19 05:36:13.551538 | TASK [include_role : remove-zuul-sshkey]
2026-03-19 05:36:13.564851 | controller | skipping: Conditional result was False
2026-03-19 05:36:13.569758 | 
2026-03-19 05:36:13.569817 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-19 05:36:13.757031 | controller | ok: "logs"
2026-03-19 05:36:13.757357 | controller | ok: All items complete
2026-03-19 05:36:13.757393 | 
2026-03-19 05:36:13.919233 | controller | ok: "artifacts"
2026-03-19 05:36:14.082892 | controller | ok: "docs"
2026-03-19 05:36:14.095251 | 
2026-03-19 05:36:14.095364 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-19 05:36:14.288814 | controller | changed: "logs"
2026-03-19 05:36:14.451050 | controller | changed: "artifacts"
2026-03-19 05:36:14.616006 | controller | changed: "docs"
2026-03-19 05:36:14.644470 | 
2026-03-19 05:36:14.644521 | PLAY RECAP
2026-03-19 05:36:14.644567 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-19 05:36:14.644593 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-19 05:36:14.644611 | 
2026-03-19 05:36:14.719864 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-19 05:36:14.720672 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-19 05:36:15.229903 | 
2026-03-19 05:36:15.230019 | PLAY [all]
2026-03-19 05:36:15.248923 | 
2026-03-19 05:36:15.248993 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-19 05:36:15.297728 | controller | ok
2026-03-19 05:36:15.302210 | 
2026-03-19 05:36:15.302318 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-19 05:36:15.614328 | controller | changed
2026-03-19 05:36:15.619320 | 
2026-03-19 05:36:15.619389 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-19 05:36:16.388705 | controller | changed
2026-03-19 05:36:16.397292 | 
2026-03-19 05:36:16.397359 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-03-19 05:36:16.757638 | controller | changed:
2026-03-19 05:36:16.757849 | controller | {
2026-03-19 05:36:16.757878 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-03-19 05:36:16.757911 | controller | }
2026-03-19 05:36:16.957919 | controller | changed:
2026-03-19 05:36:16.958216 | controller | {
2026-03-19 05:36:16.958256 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-03-19 05:36:16.958299 | controller | }
2026-03-19 05:36:17.192510 | controller | changed:
2026-03-19 05:36:17.192604 | controller | {
2026-03-19 05:36:17.192629 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-03-19 05:36:17.192648 | controller | }
2026-03-19 05:36:17.395381 | controller | changed:
2026-03-19 05:36:17.395435 | controller | {
2026-03-19 05:36:17.395460 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-03-19 05:36:17.395478 | controller | }
2026-03-19 05:36:17.589241 | controller | changed:
2026-03-19 05:36:17.589309 | controller | {
2026-03-19 05:36:17.589333 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-03-19 05:36:17.589352 | controller | }
2026-03-19 05:36:17.788110 | controller | changed:
2026-03-19 05:36:17.788168 | controller | {
2026-03-19 05:36:17.788195 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-03-19 05:36:17.788216 | controller | }
2026-03-19 05:36:17.999195 | controller | changed:
2026-03-19 05:36:17.999301 | controller | {
2026-03-19 05:36:17.999418 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2026-03-19 05:36:17.999452 | controller | }
2026-03-19 05:36:18.194476 | controller | changed:
2026-03-19 05:36:18.194529 | controller | {
2026-03-19 05:36:18.194555 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2026-03-19 05:36:18.194576 | controller | }
2026-03-19 05:36:18.394922 | controller | changed:
2026-03-19 05:36:18.394984 | controller | {
2026-03-19 05:36:18.395009 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-03-19 05:36:18.395027 | controller | }
2026-03-19 05:36:18.601888 | controller | changed:
2026-03-19 05:36:18.601939 | controller | {
2026-03-19 05:36:18.601963 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-03-19 05:36:18.601982 | controller | }
2026-03-19 05:36:18.819356 | controller | changed:
2026-03-19 05:36:18.819491 | controller | {
2026-03-19 05:36:18.819527 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-03-19 05:36:18.819548 | controller | }
2026-03-19 05:36:19.040891 | controller | changed:
2026-03-19 05:36:19.040966 | controller | {
2026-03-19 05:36:19.040989 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-03-19 05:36:19.041007 | controller | }
2026-03-19 05:36:19.224055 | controller | changed:
2026-03-19 05:36:19.224123 | controller | {
2026-03-19 05:36:19.224162 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-03-19 05:36:19.224183 | controller | }
2026-03-19 05:36:19.431969 | controller | changed:
2026-03-19 05:36:19.432027 | controller | {
2026-03-19 05:36:19.432052 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-03-19 05:36:19.432071 | controller | }
2026-03-19 05:36:19.663774 | controller | changed:
2026-03-19 05:36:19.663822 | controller | {
2026-03-19 05:36:19.663846 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-03-19 05:36:19.663863 | controller | }
2026-03-19 05:36:19.870255 | controller | changed:
2026-03-19 05:36:19.870377 | controller | {
2026-03-19 05:36:19.870403 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-03-19 05:36:19.870422 | controller | }
2026-03-19 05:36:20.068913 | controller | changed:
2026-03-19 05:36:20.069106 | controller | {
2026-03-19 05:36:20.069149 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2026-03-19 05:36:20.069172 | controller | }
2026-03-19 05:36:20.276131 | controller | changed:
2026-03-19 05:36:20.276241 | controller | {
2026-03-19 05:36:20.276281 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2026-03-19 05:36:20.276319 | controller | }
2026-03-19 05:36:20.476384 | controller | changed:
2026-03-19 05:36:20.476461 | controller | {
2026-03-19 05:36:20.476484 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-03-19 05:36:20.476501 | controller | }
2026-03-19 05:36:20.716929 | controller | changed:
2026-03-19 05:36:20.716980 | controller | {
2026-03-19 05:36:20.717003 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-03-19 05:36:20.717021 | controller | }
2026-03-19 05:36:20.916320 | controller | changed:
2026-03-19 05:36:20.916369 | controller | {
2026-03-19 05:36:20.916393 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-03-19 05:36:20.916411 | controller | }
2026-03-19 05:36:21.113422 | controller | changed:
2026-03-19 05:36:21.113477 | controller | {
2026-03-19 05:36:21.113502 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-03-19 05:36:21.113519 | controller | }
2026-03-19 05:36:21.312719 | controller | changed:
2026-03-19 05:36:21.312768 | controller | {
2026-03-19 05:36:21.312790 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2026-03-19 05:36:21.312808 | controller | }
2026-03-19 05:36:21.511163 | controller | changed:
2026-03-19 05:36:21.511242 | controller | {
2026-03-19 05:36:21.511280 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-03-19 05:36:21.511306 | controller | }
2026-03-19 05:36:21.714939 | controller | changed:
2026-03-19 05:36:21.714992 | controller | {
2026-03-19 05:36:21.715016 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-03-19 05:36:21.715034 | controller | }
2026-03-19 05:36:21.920245 | controller | changed:
2026-03-19 05:36:21.920313 | controller | {
2026-03-19 05:36:21.920337 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-03-19 05:36:21.920354 | controller | }
2026-03-19 05:36:21.937944 | 
2026-03-19 05:36:21.938047 | TASK [Set timezone to UTC]
2026-03-19 05:36:22.311489 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-03-19 05:36:22.315927 | 
2026-03-19 05:36:22.315993 | TASK [Create nodepool directory]
2026-03-19 05:36:22.506715 | controller | changed
2026-03-19 05:36:22.511651 | 
2026-03-19 05:36:22.511712 | TASK [Create nodepool sub_nodes file]
2026-03-19 05:36:23.006014 | controller | changed
2026-03-19 05:36:23.010499 | 
2026-03-19 05:36:23.010560 | TASK [Create nodepool sub_nodes_private file]
2026-03-19 05:36:23.504625 | controller | changed
2026-03-19 05:36:23.509710 | 
2026-03-19 05:36:23.509772 | LOOP [Populate nodepool sub_nodes file]
2026-03-19 05:36:23.538058 | 
2026-03-19 05:36:23.538182 | LOOP [Populate nodepool sub_nodes_private file]
2026-03-19 05:36:23.566407 | 
2026-03-19 05:36:23.566526 | TASK [Create nodepool primary file]
2026-03-19 05:36:23.589300 | controller | skipping: Conditional result was False
2026-03-19 05:36:23.594227 | 
2026-03-19 05:36:23.594312 | TASK [Create nodepool node_private for this node]
2026-03-19 05:36:24.139401 | controller | changed
2026-03-19 05:36:24.144340 | 
2026-03-19 05:36:24.144396 | LOOP [Copy ssh keys to nodepool directory]
2026-03-19 05:36:24.458497 | controller | ok: Item: id_rsa Runtime: 0:00:00.005301
2026-03-19 05:36:24.458688 | 
2026-03-19 05:36:24.623034 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005200
2026-03-19 05:36:24.630593 | 
2026-03-19 05:36:24.630663 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-03-19 05:36:25.205642 | controller | changed
2026-03-19 05:36:25.210048 | 
2026-03-19 05:36:25.210103 | TASK [Validate sudoers config after edits]
2026-03-19 05:36:25.409170 | controller | /etc/sudoers: parsed OK
2026-03-19 05:36:25.409222 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-03-19 05:36:25.409230 | controller | /etc/sudoers.d/zuul: parsed OK
2026-03-19 05:36:25.409236 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-03-19 05:36:25.733569 | controller | ok: Runtime: 0:00:00.006933
2026-03-19 05:36:25.738508 | 
2026-03-19 05:36:25.738565 | TASK [Show the environment passed in to job shell scripts]
2026-03-19 05:36:25.963900 | controller | SHELL=/bin/bash
2026-03-19 05:36:25.963948 | controller | ZUUL_PROJECT=openstack-k8s-operators/edpm-ansible
2026-03-19 05:36:25.963957 | controller | ZUUL_SHORT_PROJECT_NAME=edpm-ansible
2026-03-19 05:36:25.963980 | controller | ZUUL_CHANGES=openstack-k8s-operators/edpm-ansible:main:refs/changes/14/1114/2e1be7179d11cf9a1af84970f52b70dde49b472c
2026-03-19 05:36:25.964028 | controller | PWD=/home/zuul
2026-03-19 05:36:25.964036 | controller | ZUUL_PIPELINE=github-check
2026-03-19 05:36:25.964042 | controller | LOGNAME=zuul
2026-03-19 05:36:25.964047 | controller | XDG_SESSION_TYPE=tty
2026-03-19 05:36:25.964052 | controller | _=/usr/bin/env
2026-03-19 05:36:25.964057 | controller | MOTD_SHOWN=pam
2026-03-19 05:36:25.964062 | controller | HOME=/home/zuul
2026-03-19 05:36:25.964067 | controller | LANG=en_US.UTF-8
2026-03-19 05:36:25.964072 | controller | SSH_CONNECTION=192.168.25.12 37968 192.168.25.38 22
2026-03-19 05:36:25.964077 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2026-03-19 05:36:25.964086 | controller | ZUUL_CHANGE_IDS=1114,2e1be7179d11cf9a1af84970f52b70dde49b472c
2026-03-19 05:36:25.964091 | controller | WORKSPACE=/home/zuul/workspace
2026-03-19 05:36:25.964096 | controller | XDG_SESSION_CLASS=user
2026-03-19 05:36:25.964102 | controller | SELINUX_ROLE_REQUESTED=
2026-03-19 05:36:25.964107 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-03-19 05:36:25.964112 | controller | USER=zuul
2026-03-19 05:36:25.964117 | controller | ZUUL_VOTING=True
2026-03-19 05:36:25.964122 | controller | BUILD_TIMEOUT=1800000
2026-03-19 05:36:25.964127 | controller | SELINUX_USE_CURRENT_RANGE=
2026-03-19 05:36:25.964132 | controller | SHLVL=1
2026-03-19 05:36:25.964137 | controller | ZUUL_PATCHSET=2e1be7179d11cf9a1af84970f52b70dde49b472c
2026-03-19 05:36:25.964142 | controller | XDG_SESSION_ID=1
2026-03-19 05:36:25.964147 | controller | ZUUL_BRANCH=main
2026-03-19 05:36:25.964152 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-03-19 05:36:25.964157 | controller | SSH_CLIENT=192.168.25.12 37968 22
2026-03-19 05:36:25.964162 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-03-19 05:36:25.964167 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-03-19 05:36:25.964172 | controller | which_declare=declare -f
2026-03-19 05:36:25.964178 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-03-19 05:36:25.964183 | controller | SELINUX_LEVEL_REQUESTED=
2026-03-19 05:36:25.964190 | controller | ZUUL_CHANGE=1114
2026-03-19 05:36:25.964195 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-03-19 05:36:25.964200 | controller | ZUUL_UUID=b3050f50a1664d0cbe168fc853832b15
2026-03-19 05:36:25.964205 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-03-19 05:36:25.964211 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-03-19 05:36:25.964216 | controller | }
2026-03-19 05:36:26.262447 | controller | ok: Runtime: 0:00:00.005178
2026-03-19 05:36:26.267791 | 
2026-03-19 05:36:26.267852 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-03-19 05:36:26.281405 | controller | skipping: Conditional result was False
2026-03-19 05:36:26.286330 | 
2026-03-19 05:36:26.286388 | TASK [Symlink /home/zuul-worker/workspace]
2026-03-19 05:36:26.809116 | controller | skipping: Conditional result was False
2026-03-19 05:36:26.814329 | 
2026-03-19 05:36:26.814391 | TASK [Ensure legacy workspace directory]
2026-03-19 05:36:26.984682 | controller | changed
2026-03-19 05:36:27.000586 | 
2026-03-19 05:36:27.000639 | PLAY RECAP
2026-03-19 05:36:27.000679 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-19 05:36:27.000699 | 
2026-03-19 05:36:27.071741 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-19 05:36:27.072518 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-19 05:36:27.527458 | 
2026-03-19 05:36:27.527578 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-03-19 05:36:27.545678 | 
2026-03-19 05:36:27.545743 | TASK [Create zuul-output directory]
2026-03-19 05:36:27.837839 | controller | changed
2026-03-19 05:36:27.842317 | 
2026-03-19 05:36:27.842387 | TASK [Slurp Zuul inventory test]
2026-03-19 05:36:28.089223 | controller -> localhost | ok
2026-03-19 05:36:28.095059 | 
2026-03-19 05:36:28.095120 | TASK [Save zuul inventory]
2026-03-19 05:36:28.772469 | controller | changed
2026-03-19 05:36:28.776908 | 
2026-03-19 05:36:28.776968 | TASK [Save zuul vars without the change_message]
2026-03-19 05:36:29.385246 | controller | changed
2026-03-19 05:36:29.401416 | 
2026-03-19 05:36:29.401470 | PLAY RECAP
2026-03-19 05:36:29.401508 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-19 05:36:29.401528 | 
2026-03-19 05:36:29.468347 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-19 05:36:29.469092 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-19 05:36:30.007131 | 
2026-03-19 05:36:30.007350 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-03-19 05:36:30.026304 | 
2026-03-19 05:36:30.026369 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-03-19 05:36:30.044512 | controller | ok
2026-03-19 05:36:30.060213 | 
2026-03-19 05:36:30.060306 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-19 05:36:30.083768 | controller | skipping: Conditional result was False
2026-03-19 05:36:30.088872 | 
2026-03-19 05:36:30.088938 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-19 05:36:30.411729 | controller | ok
2026-03-19 05:36:30.417327 | 
2026-03-19 05:36:30.417398 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-19 05:36:31.034436 | controller | ok
2026-03-19 05:36:31.043780 | 
2026-03-19 05:36:31.043843 | TASK [Prepare workspace]
2026-03-19 05:36:31.061862 | controller | ok
2026-03-19 05:36:31.078277 | 
2026-03-19 05:36:31.078346 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-19 05:36:31.369283 | controller | ok
2026-03-19 05:36:31.374557 | 
2026-03-19 05:36:31.374619 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-19 05:36:33.519629 | controller | Output suppressed because no_log was given
2026-03-19 05:36:33.527536 | 
2026-03-19 05:36:33.527594 | LOOP [Create zuul-output directory]
2026-03-19 05:36:33.702873 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-03-19 05:36:33.847310 | controller | ok: "/home/zuul/zuul-output/logs"
2026-03-19 05:36:33.861421 | 
2026-03-19 05:36:33.861481 | TASK [Install required packages]
2026-03-19 05:37:10.238966 | controller | changed
2026-03-19 05:37:10.243651 | 
2026-03-19 05:37:10.243710 | TASK [Install venv]
2026-03-19 05:38:08.262190 | controller | changed
2026-03-19 05:38:08.285481 | 
2026-03-19 05:38:08.285587 | PLAY RECAP
2026-03-19 05:38:08.285639 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-19 05:38:08.285661 | 
2026-03-19 05:38:08.357513 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-19 05:38:08.358366 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-03-19 05:38:08.839241 | 
2026-03-19 05:38:08.839369 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-03-19 05:38:08.858221 | 
2026-03-19 05:38:08.858324 | TASK [Gather required facts]
2026-03-19 05:38:09.313385 | controller | ok
2026-03-19 05:38:09.318310 | 
2026-03-19 05:38:09.318405 | TASK [Load environment var if instructed to]
2026-03-19 05:38:09.342070 | controller | skipping: Conditional result was False
2026-03-19 05:38:09.346920 | 
2026-03-19 05:38:09.346988 | TASK [Ensure group_vars dir exists]
2026-03-19 05:38:09.638289 | controller | changed
2026-03-19 05:38:09.643910 | 
2026-03-19 05:38:09.643972 | TASK [Print related variables]
2026-03-19 05:38:09.672028 | controller | ok:
2026-03-19 05:38:09.672142 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_edpm_ansible.yml
2026-03-19 05:38:09.672171 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/roles/edpm_libvirt
2026-03-19 05:38:09.676459 | 
2026-03-19 05:38:09.676515 | TASK [Run molecule]
2026-03-19 05:38:10.747940 | controller | WARNING  Driver vagrant does not provide a schema.
2026-03-19 05:38:10.750314 | controller | INFO     Performing prerun with role_name_check=0...
2026-03-19 05:38:11.518024 | controller | INFO     Running default > destroy
2026-03-19 05:38:11.518270 | controller | WARNING  Skipping, instances are delegated.
2026-03-19 05:38:11.524849 | controller | INFO     Running default > create
2026-03-19 05:38:11.525656 | controller | WARNING  Skipping, instances are delegated.
2026-03-19 05:38:11.532207 | controller | INFO     Running default > prepare
2026-03-19 05:38:12.154243 | controller |
2026-03-19 05:38:12.154317 | controller | PLAY [Setup DUT] ***************************************************************
2026-03-19 05:38:12.154335 | controller |
2026-03-19 05:38:12.154386 | controller | TASK [set basic user fact] *****************************************************
2026-03-19 05:38:12.154481 | controller | Thursday 19 March 2026  05:38:12 +0000 (0:00:00.008)       0:00:00.008 ********
2026-03-19 05:38:12.176316 | controller | ok: [compute-1]
2026-03-19 05:38:12.176490 | controller |
2026-03-19 05:38:12.176705 | controller | TASK [set basic home fact] *****************************************************
2026-03-19 05:38:12.176882 | controller | Thursday 19 March 2026  05:38:12 +0000 (0:00:00.022)       0:00:00.031 ********
2026-03-19 05:38:12.199362 | controller | ok: [compute-1]
2026-03-19 05:38:12.199465 | controller |
2026-03-19 05:38:12.199580 | controller | TASK [set bootc fact] **********************************************************
2026-03-19 05:38:12.199730 | controller | Thursday 19 March 2026  05:38:12 +0000 (0:00:00.023)       0:00:00.054 ********
2026-03-19 05:38:12.218265 | controller | ok: [compute-1]
2026-03-19 05:38:12.218438 | controller |
2026-03-19 05:38:12.218628 | controller | TASK [Enable package repos] ****************************************************
2026-03-19 05:38:12.218835 | controller | Thursday 19 March 2026  05:38:12 +0000 (0:00:00.018)       0:00:00.073 ********
2026-03-19 05:38:20.335582 | controller | changed: [compute-1]
2026-03-19 05:38:20.335638 | controller |
2026-03-19 05:38:20.335797 | controller | TASK [Install host packages] ***************************************************
2026-03-19 05:38:20.335859 | controller | Thursday 19 March 2026  05:38:20 +0000 (0:00:08.114)       0:00:08.187 ********
2026-03-19 05:40:17.588167 | controller | changed: [compute-1] => (item=openstack-selinux)
2026-03-19 05:40:17.588220 | controller | changed: [compute-1] => (item=python3-cryptography)
2026-03-19 05:40:17.588315 | controller |
2026-03-19 05:40:17.588399 | controller | TASK [set /etc/localtime] ******************************************************
2026-03-19 05:40:17.588489 | controller | Thursday 19 March 2026  05:40:17 +0000 (0:01:57.255)       0:02:05.442 ********
2026-03-19 05:40:17.867714 | controller | changed: [compute-1]
2026-03-19 05:40:17.867851 | controller |
2026-03-19 05:40:17.867990 | controller | TASK [osp.edpm.edpm_timezone : Validating arguments against arg spec 'main' - The main entry point for the edpm_timezone role.] ***
2026-03-19 05:40:17.868118 | controller | Thursday 19 March 2026  05:40:17 +0000 (0:00:00.279)       0:02:05.722 ********
2026-03-19 05:40:17.881923 | controller | ok: [compute-1]
2026-03-19 05:40:17.882003 | controller |
2026-03-19 05:40:17.882094 | controller | TASK [osp.edpm.edpm_timezone : Gather ansible_local facts] *********************
2026-03-19 05:40:17.882182 | controller | Thursday 19 March 2026  05:40:17 +0000 (0:00:00.014)       0:02:05.737 ********
2026-03-19 05:40:17.899803 | controller | skipping: [compute-1]
2026-03-19 05:40:17.899925 | controller |
2026-03-19 05:40:17.900052 | controller | TASK [osp.edpm.edpm_timezone : Check for /etc/localtime] ***********************
2026-03-19 05:40:17.900176 | controller | Thursday 19 March 2026  05:40:17 +0000 (0:00:00.017)       0:02:05.754 ********
2026-03-19 05:40:17.913864 | controller | skipping: [compute-1]
2026-03-19 05:40:17.913978 | controller |
2026-03-19 05:40:17.914103 | controller | TASK [osp.edpm.edpm_timezone : Symlink /etc/localtime to timezone file if it doesn't exist] ***
2026-03-19 05:40:17.914228 | controller | Thursday 19 March 2026  05:40:17 +0000 (0:00:00.014)       0:02:05.768 ********
2026-03-19 05:40:17.927454 | controller | skipping: [compute-1]
2026-03-19 05:40:17.927570 | controller |
2026-03-19 05:40:17.927711 | controller | TASK [osp.edpm.edpm_timezone : Set timezone] ***********************************
2026-03-19 05:40:17.927831 | controller | Thursday 19 March 2026  05:40:17 +0000 (0:00:00.013)       0:02:05.782 ********
2026-03-19 05:40:18.285207 | controller | ok: [compute-1]
2026-03-19 05:40:18.285269 | controller |
2026-03-19 05:40:18.285365 | controller | TASK [osp.edpm.edpm_timezone : Set fact for timezone result] *******************
2026-03-19 05:40:18.285452 | controller | Thursday 19 March 2026  05:40:18 +0000 (0:00:00.357)       0:02:06.139 ********
2026-03-19 05:40:18.300510 | controller | ok: [compute-1]
2026-03-19 05:40:18.300623 | controller |
2026-03-19 05:40:18.300745 | controller | TASK [osp.edpm.edpm_timezone : Restart time services] **************************
2026-03-19 05:40:18.300836 | controller | Thursday 19 March 2026  05:40:18 +0000 (0:00:00.015)       0:02:06.155 ********
2026-03-19 05:40:19.175901 | controller | changed: [compute-1] => (item=rsyslog)
2026-03-19 05:40:19.176015 | controller | changed: [compute-1] => (item=crond)
2026-03-19 05:40:19.176094 | controller |
2026-03-19 05:40:19.176192 | controller | TASK [remove vagrant image packages] *******************************************
2026-03-19 05:40:19.176274 | controller | Thursday 19 March 2026  05:40:19 +0000 (0:00:00.875)       0:02:07.030 ********
2026-03-19 05:40:20.315198 | controller | ok: [compute-1] => (item=firewalld)
2026-03-19 05:40:20.315473 | controller |
2026-03-19 05:40:20.499571 | controller | TASK [Create firewall directory] ***********************************************
2026-03-19 05:40:20.499629 | controller | Thursday 19 March 2026  05:40:20 +0000 (0:00:01.139)       0:02:08.170 ********
2026-03-19 05:40:20.499647 | controller | changed: [compute-1]
2026-03-19 05:40:21.057974 | controller |
2026-03-19 05:40:21.058004 | controller | TASK [open port 22 (edpm_nftables will active this later)] *********************
2026-03-19 05:40:21.058012 | controller | Thursday 19 March 2026  05:40:20 +0000 (0:00:00.184)       0:02:08.354 ********
2026-03-19 05:40:21.058022 | controller | changed: [compute-1]
2026-03-19 05:40:21.249073 | controller |
2026-03-19 05:40:21.249106 | controller | TASK [Create Ceph client file directory] ***************************************
2026-03-19 05:40:21.249114 | controller | Thursday 19 March 2026  05:40:21 +0000 (0:00:00.558)       0:02:08.912 ********
2026-03-19 05:40:21.249125 | controller | changed: [compute-1]
2026-03-19 05:40:21.419959 | controller |
2026-03-19 05:40:21.419989 | controller | TASK [Create random mock Ceph FSID] ********************************************
2026-03-19 05:40:21.419997 | controller | Thursday 19 March 2026  05:40:21 +0000 (0:00:00.191)       0:02:09.103 ********
2026-03-19 05:40:21.420007 | controller | changed: [compute-1]
2026-03-19 05:40:21.608744 | controller |
2026-03-19 05:40:21.608773 | controller | TASK [Create random mock Cephx key] ********************************************
2026-03-19 05:40:21.608781 | controller | Thursday 19 March 2026  05:40:21 +0000 (0:00:00.170)       0:02:09.274 ********
2026-03-19 05:40:21.608791 | controller | changed: [compute-1]
2026-03-19 05:40:21.958055 | controller |
2026-03-19 05:40:21.958083 | controller | TASK [Create a mock Ceph conf file] ********************************************
2026-03-19 05:40:21.958091 | controller | Thursday 19 March 2026  05:40:21 +0000 (0:00:00.188)       0:02:09.463 ********
2026-03-19 05:40:21.958101 | controller | changed: [compute-1]
2026-03-19 05:40:22.323297 | controller |
2026-03-19 05:40:22.323321 | controller | TASK [Create a mock Cephx keyring file] ****************************************
2026-03-19 05:40:22.323329 | controller | Thursday 19 March 2026  05:40:21 +0000 (0:00:00.349)       0:02:09.812 ********
2026-03-19 05:40:22.323338 | controller | changed: [compute-1]
2026-03-19 05:40:22.501653 | controller |
2026-03-19 05:40:22.501680 | controller | TASK [create pki temp directory] ***********************************************
2026-03-19 05:40:22.501688 | controller | Thursday 19 March 2026  05:40:22 +0000 (0:00:00.365)       0:02:10.178 ********
2026-03-19 05:40:22.501700 | controller | changed: [compute-1]
2026-03-19 05:40:23.765391 | controller |
2026-03-19 05:40:23.765420 | controller | TASK [Create private key (RSA, 4096 bits)] *************************************
2026-03-19 05:40:23.765427 | controller | Thursday 19 March 2026  05:40:22 +0000 (0:00:00.178)       0:02:10.356 ********
2026-03-19 05:40:23.765437 | controller | changed: [compute-1]
2026-03-19 05:40:24.420277 | controller |
2026-03-19 05:40:24.420307 | controller | TASK [Create a certificate signing request for ca] *****************************
2026-03-19 05:40:24.420315 | controller | Thursday 19 March 2026  05:40:23 +0000 (0:00:01.263)       0:02:11.619 ********
2026-03-19 05:40:24.420326 | controller | changed: [compute-1]
2026-03-19 05:40:25.185679 | controller |
2026-03-19 05:40:25.185712 | controller | TASK [Create a certificate athority] *******************************************
2026-03-19 05:40:25.185726 | controller | Thursday 19 March 2026  05:40:24 +0000 (0:00:00.654)       0:02:12.274 ********
2026-03-19 05:40:25.185736 | controller | changed: [compute-1]
2026-03-19 05:40:25.185761 | controller |
2026-03-19 05:40:25.185954 | controller | TASK [Create private key (RSA, 4096 bits)] *************************************
2026-03-19 05:40:26.452739 | controller | Thursday 19 March 2026  05:40:25 +0000 (0:00:00.765)       0:02:13.040 ********
2026-03-19 05:40:26.452775 | controller | changed: [compute-1]
2026-03-19 05:40:26.991517 | controller |
2026-03-19 05:40:26.991556 | controller | TASK [Create a certificate signing request for libvirt server and client cert] ***
2026-03-19 05:40:26.991564 | controller | Thursday 19 March 2026  05:40:26 +0000 (0:00:01.266)       0:02:14.307 ********
2026-03-19 05:40:26.991575 | controller | changed: [compute-1]
2026-03-19 05:40:27.606646 | controller |
2026-03-19 05:40:27.606674 | controller | TASK [Sign the certificate signing request] ************************************
2026-03-19 05:40:27.606681 | controller | Thursday 19 March 2026  05:40:26 +0000 (0:00:00.538)       0:02:14.846 ********
2026-03-19 05:40:27.606691 | controller | changed: [compute-1]
2026-03-19 05:40:27.897422 | controller |
2026-03-19 05:40:27.897448 | controller | TASK [Create password secret] **************************************************
2026-03-19 05:40:27.897456 | controller | Thursday 19 March 2026  05:40:27 +0000 (0:00:00.615)       0:02:15.461 ********
2026-03-19 05:40:27.897465 | controller | changed: [compute-1 -> localhost]
2026-03-19 05:40:28.218002 | controller |
2026-03-19 05:40:28.218033 | controller | TASK [Gather required facts] ***************************************************
2026-03-19 05:40:28.218040 | controller | Thursday 19 March 2026  05:40:27 +0000 (0:00:00.290)       0:02:15.752 ********
2026-03-19 05:40:28.218050 | controller | ok: [compute-1]
2026-03-19 05:40:28.506706 | controller |
2026-03-19 05:40:28.506738 | controller | TASK [Ensure hostname is resolvable in /etc/hosts] *****************************
2026-03-19 05:40:28.506747 | controller | Thursday 19 March 2026  05:40:28 +0000 (0:00:00.320)       0:02:16.072 ********
2026-03-19 05:40:28.506761 | controller | changed: [compute-1]
2026-03-19 05:40:28.506783 | controller |
2026-03-19 05:40:28.506790 | controller | PLAY RECAP *********************************************************************
2026-03-19 05:40:28.506795 | controller | compute-1                  : ok=28   changed=20   unreachable=0    failed=0    skipped=3    rescued=0    ignored=0
2026-03-19 05:40:28.506801 | controller |
2026-03-19 05:40:28.506806 | controller | Thursday 19 March 2026  05:40:28 +0000 (0:00:00.287)       0:02:16.360 ********
2026-03-19 05:40:28.506811 | controller | ===============================================================================
2026-03-19 05:40:28.506816 | controller | Install host packages ------------------------------------------------- 117.26s
2026-03-19 05:40:28.506821 | controller | Enable package repos ---------------------------------------------------- 8.11s
2026-03-19 05:40:28.506826 | controller | Create private key (RSA, 4096 bits) ------------------------------------- 1.27s
2026-03-19 05:40:28.506832 | controller | Create private key (RSA, 4096 bits) ------------------------------------- 1.26s
2026-03-19 05:40:28.506837 | controller | remove vagrant image packages ------------------------------------------- 1.14s
2026-03-19 05:40:28.506842 | controller | osp.edpm.edpm_timezone : Restart time services -------------------------- 0.88s
2026-03-19 05:40:28.506847 | controller | Create a certificate athority ------------------------------------------- 0.77s
2026-03-19 05:40:28.506853 | controller | Create a certificate signing request for ca ----------------------------- 0.65s
2026-03-19 05:40:28.506858 | controller | Sign the certificate signing request ------------------------------------ 0.62s
2026-03-19 05:40:28.506863 | controller | open port 22 (edpm_nftables will active this later) --------------------- 0.56s
2026-03-19 05:40:28.506868 | controller | Create a certificate signing request for libvirt server and client cert --- 0.54s
2026-03-19 05:40:28.506873 | controller | Create a mock Cephx keyring file ---------------------------------------- 0.37s
2026-03-19 05:40:28.506879 | controller | osp.edpm.edpm_timezone : Set timezone ----------------------------------- 0.36s
2026-03-19 05:40:28.506886 | controller | Create a mock Ceph conf file -------------------------------------------- 0.35s
2026-03-19 05:40:28.506905 | controller | Gather required facts --------------------------------------------------- 0.32s
2026-03-19 05:40:28.506917 | controller | Create password secret -------------------------------------------------- 0.29s
2026-03-19 05:40:28.507228 | controller | Ensure hostname is resolvable in /etc/hosts ----------------------------- 0.29s
2026-03-19 05:40:28.564952 | controller | set /etc/localtime ------------------------------------------------------ 0.28s
2026-03-19 05:40:28.564973 | controller | Create Ceph client file directory --------------------------------------- 0.19s
2026-03-19 05:40:28.564981 | controller | Create random mock Cephx key -------------------------------------------- 0.19s
2026-03-19 05:40:28.564990 | controller | INFO     Running default > converge
2026-03-19 05:40:28.953309 | controller |
2026-03-19 05:40:29.689406 | controller | PLAY [Converge] ****************************************************************
2026-03-19 05:40:29.689440 | controller |
2026-03-19 05:40:29.689447 | controller | TASK [Gathering Facts] *********************************************************
2026-03-19 05:40:29.689454 | controller | Thursday 19 March 2026  05:40:28 +0000 (0:00:00.010)       0:00:00.010 ********
2026-03-19 05:40:29.689464 | controller | ok: [compute-1]
2026-03-19 05:40:29.714976 | controller |
2026-03-19 05:40:29.714996 | controller | TASK [set basic user fact] *****************************************************
2026-03-19 05:40:29.715003 | controller | Thursday 19 March 2026  05:40:29 +0000 (0:00:00.736)       0:00:00.746 ********
2026-03-19 05:40:29.715012 | controller | ok: [compute-1]
2026-03-19 05:40:29.734832 | controller |
2026-03-19 05:40:29.734852 | controller | TASK [set basic home fact] *****************************************************
2026-03-19 05:40:29.734860 | controller | Thursday 19 March 2026  05:40:29 +0000 (0:00:00.025)       0:00:00.772 ********
2026-03-19 05:40:29.734871 | controller | skipping: [compute-1]
2026-03-19 05:40:29.758836 | controller |
2026-03-19 05:40:29.758852 | controller | TASK [set bootc fact] **********************************************************
2026-03-19 05:40:29.758859 | controller | Thursday 19 March 2026  05:40:29 +0000 (0:00:00.019)       0:00:00.792 ********
2026-03-19 05:40:29.758868 | controller | ok: [compute-1]
2026-03-19 05:40:29.798109 | controller |
2026-03-19 05:40:29.798125 | controller | TASK [osp.edpm.edpm_libvirt : Validating arguments against arg spec 'main' - The main entry point for the edpm_libvirt role.] ***
2026-03-19 05:40:29.798133 | controller | Thursday 19 March 2026  05:40:29 +0000 (0:00:00.023)       0:00:00.816 ********
2026-03-19 05:40:29.798141 | controller | ok: [compute-1]
2026-03-19 05:40:29.818181 | controller |
2026-03-19 05:40:29.818199 | controller | TASK [osp.edpm.edpm_libvirt : Adopt libvirt from tripleo] **********************
2026-03-19 05:40:29.818207 | controller | Thursday 19 March 2026  05:40:29 +0000 (0:00:00.039)       0:00:00.855 ********
2026-03-19 05:40:29.818216 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/collections/ansible_collections/osp/edpm/roles/edpm_libvirt/tasks/adoption.yml for compute-1
2026-03-19 05:40:30.160047 | controller |
2026-03-19 05:40:30.160079 | controller | TASK [osp.edpm.edpm_libvirt : Check if nova_virtlogd container exists] *********
2026-03-19 05:40:30.160088 | controller | Thursday 19 March 2026  05:40:29 +0000 (0:00:00.019)       0:00:00.875 ********
2026-03-19 05:40:30.160099 | controller | ok: [compute-1]
2026-03-19 05:40:30.186690 | controller |
2026-03-19 05:40:30.186714 | controller | TASK [osp.edpm.edpm_libvirt : Stop nova_virtlogd containers not managed by service units] ***
2026-03-19 05:40:30.186721 | controller | Thursday 19 March 2026  05:40:30 +0000 (0:00:00.341)       0:00:01.217 ********
2026-03-19 05:40:30.186731 | controller | skipping: [compute-1]
2026-03-19 05:40:30.232130 | controller |
2026-03-19 05:40:30.232154 | controller | TASK [Stop and disable libvirt services] ***************************************
2026-03-19 05:40:30.232163 | controller | Thursday 19 March 2026  05:40:30 +0000 (0:00:00.026)       0:00:01.244 ********
2026-03-19 05:40:30.232175 | controller |
2026-03-19 05:40:30.250068 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Validating arguments against arg spec 'main' - The main entry point for the edpm_tripleo_cleanup role.] ***
2026-03-19 05:40:30.250088 | controller | Thursday 19 March 2026  05:40:30 +0000 (0:00:00.045)       0:00:01.289 ********
2026-03-19 05:40:30.250105 | controller | ok: [compute-1]
2026-03-19 05:40:30.862264 | controller |
2026-03-19 05:40:30.862297 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reload units to check for new changes] ***
2026-03-19 05:40:30.862311 | controller | Thursday 19 March 2026  05:40:30 +0000 (0:00:00.017)       0:00:01.307 ********
2026-03-19 05:40:30.862321 | controller | ok: [compute-1]
2026-03-19 05:40:32.530060 | controller |
2026-03-19 05:40:32.530092 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Gather service facts] ********************
2026-03-19 05:40:32.530101 | controller | Thursday 19 March 2026  05:40:30 +0000 (0:00:00.611)       0:00:01.919 ********
2026-03-19 05:40:32.530111 | controller | ok: [compute-1]
2026-03-19 05:40:32.551026 | controller |
2026-03-19 05:40:32.551047 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Set list of tripleo services] ************
2026-03-19 05:40:32.551055 | controller | Thursday 19 March 2026  05:40:32 +0000 (0:00:01.667)       0:00:03.587 ********
2026-03-19 05:40:32.551065 | controller | ok: [compute-1]
2026-03-19 05:40:32.580978 | controller |
2026-03-19 05:40:32.581000 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Get all services] ************************
2026-03-19 05:40:32.581010 | controller | Thursday 19 March 2026  05:40:32 +0000 (0:00:00.021)       0:00:03.608 ********
2026-03-19 05:40:32.581021 | controller | skipping: [compute-1]
2026-03-19 05:40:32.610435 | controller |
2026-03-19 05:40:32.610451 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Filter for tripleo services and skip the ones in skip list] ***
2026-03-19 05:40:32.610458 | controller | Thursday 19 March 2026  05:40:32 +0000 (0:00:00.029)       0:00:03.638 ********
2026-03-19 05:40:32.610466 | controller | skipping: [compute-1]
2026-03-19 05:40:34.480660 | controller |
2026-03-19 05:40:34.480689 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Stop and disable tripleo services] *******
2026-03-19 05:40:34.480697 | controller | Thursday 19 March 2026  05:40:32 +0000 (0:00:00.029)       0:00:03.667 ********
2026-03-19 05:40:34.480707 | controller | ok: [compute-1] => (item=tripleo_nova_libvirt.target)
2026-03-19 05:40:34.481027 | controller | ok: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2026-03-19 05:40:34.481039 | controller | ok: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2026-03-19 05:40:34.481045 | controller | ok: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2026-03-19 05:40:34.481050 | controller | ok: [compute-1] => (item=tripleo_nova_virtqemud.service)
2026-03-19 05:40:34.481055 | controller | ok: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2026-03-19 05:40:34.481063 | controller | ok: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2026-03-19 05:40:34.521356 | controller |
2026-03-19 05:40:34.521375 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Check for errors] ************************
2026-03-19 05:40:34.521382 | controller | Thursday 19 March 2026  05:40:34 +0000 (0:00:01.869)       0:00:05.537 ********
2026-03-19 05:40:34.521396 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_libvirt.target: host', 'invocation': {'module_args': {'name': 'tripleo_nova_libvirt.target', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_libvirt.target', 'ansible_loop_var': 'item'})
2026-03-19 05:40:34.553700 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_virtlogd_wrapper.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_virtlogd_wrapper.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_virtlogd_wrapper.service', 'ansible_loop_var': 'item'})
2026-03-19 05:40:34.553722 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_virtnodedevd.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_virtnodedevd.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_virtnodedevd.service', 'ansible_loop_var': 'item'})
2026-03-19 05:40:34.553737 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_virtproxyd.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_virtproxyd.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_virtproxyd.service', 'ansible_loop_var': 'item'})
2026-03-19 05:40:34.553749 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_virtqemud.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_virtqemud.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_virtqemud.service', 'ansible_loop_var': 'item'})
2026-03-19 05:40:34.553792 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_virtsecretd.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_virtsecretd.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_virtsecretd.service', 'ansible_loop_var': 'item'})
2026-03-19 05:40:34.553799 | controller | skipping: [compute-1] => (item={'failed': False, 'msg': 'Could not find the requested service tripleo_nova_virtstoraged.service: host', 'invocation': {'module_args': {'name': 'tripleo_nova_virtstoraged.service', 'state': 'stopped', 'enabled': False, 'daemon_reload': False, 'daemon_reexec': False, 'scope': 'system', 'no_block': False, 'force': None, 'masked': None}}, 'changed': False, 'failed_when_result': False, 'item': 'tripleo_nova_virtstoraged.service', 'ansible_loop_var': 'item'})
2026-03-19 05:40:34.553806 | controller | skipping: [compute-1]
2026-03-19 05:40:34.553812 | controller |
2026-03-19 05:40:34.553819 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files] ***********************
2026-03-19 05:40:34.553825 | controller | Thursday 19 March 2026  05:40:34 +0000 (0:00:00.040)       0:00:05.578 ********
2026-03-19 05:40:34.553836 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/collections/ansible_collections/osp/edpm/roles/edpm_tripleo_cleanup/tasks/remove_unit_files.yml for compute-1 => (item=/usr/lib/systemd/system)
2026-03-19 05:40:35.852685 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/collections/ansible_collections/osp/edpm/roles/edpm_tripleo_cleanup/tasks/remove_unit_files.yml for compute-1 => (item=/etc/systemd/system)
2026-03-19 05:40:35.852719 | controller |
2026-03-19 05:40:35.852727 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /usr/lib/systemd/system] ***
2026-03-19 05:40:35.852733 | controller | Thursday 19 March 2026  05:40:34 +0000 (0:00:00.033)       0:00:05.611 ********
2026-03-19 05:40:35.852744 | controller | ok: [compute-1] => (item=tripleo_nova_libvirt.target)
2026-03-19 05:40:37.087683 | controller | ok: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2026-03-19 05:40:37.087715 | controller | ok: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2026-03-19 05:40:37.087723 | controller | ok: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2026-03-19 05:40:37.087729 | controller | ok: [compute-1] => (item=tripleo_nova_virtqemud.service)
2026-03-19 05:40:37.087735 | controller | ok: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2026-03-19 05:40:37.087740 | controller | ok: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2026-03-19 05:40:37.087746 | controller |
2026-03-19 05:40:37.087752 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /etc/systemd/system] ***
2026-03-19 05:40:37.087758 | controller | Thursday 19 March 2026  05:40:35 +0000 (0:00:01.298)       0:00:06.909 ********
2026-03-19 05:40:37.087769 | controller | ok: [compute-1] => (item=tripleo_nova_libvirt.target)
2026-03-19 05:40:37.123066 | controller | ok: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2026-03-19 05:40:37.123098 | controller | ok: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2026-03-19 05:40:37.123111 | controller | ok: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2026-03-19 05:40:37.123117 | controller | ok: [compute-1] => (item=tripleo_nova_virtqemud.service)
2026-03-19 05:40:37.123122 | controller | ok: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2026-03-19 05:40:37.123132 | controller | ok: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2026-03-19 05:40:37.123138 | controller |
2026-03-19 05:40:37.123144 | controller | TASK [Adopt (stop tracking) certs from tripleo] ********************************
2026-03-19 05:40:37.123150 | controller | Thursday 19 March 2026  05:40:37 +0000 (0:00:01.234)       0:00:08.144 ********
2026-03-19 05:40:37.123159 | controller |
2026-03-19 05:40:37.321466 | controller | TASK [osp.edpm.edpm_install_certs : Stop and disable certmonger] ***************
2026-03-19 05:40:37.321497 | controller | Thursday 19 March 2026  05:40:37 +0000 (0:00:00.036)       0:00:08.180 ********
2026-03-19 05:40:37.321508 | controller | ok: [compute-1]
2026-03-19 05:40:37.551658 | controller |
2026-03-19 05:40:37.551687 | controller | TASK [osp.edpm.edpm_install_certs : Find certmonger requests] ******************
2026-03-19 05:40:37.551700 | controller | Thursday 19 March 2026  05:40:37 +0000 (0:00:00.198)       0:00:08.378 ********
2026-03-19 05:40:37.551712 | controller | [WARNING]: Skipped '/var/lib/certmonger/requests' path due to this access
2026-03-19 05:40:37.551730 | controller | issue: '/var/lib/certmonger/requests' is not a directory
2026-03-19 05:40:37.560517 | controller | ok: [compute-1]
2026-03-19 05:40:37.579528 | controller |
2026-03-19 05:40:37.579549 | controller | TASK [osp.edpm.edpm_install_certs : Backup certificate requests] ***************
2026-03-19 05:40:37.579570 | controller | Thursday 19 March 2026  05:40:37 +0000 (0:00:00.239)       0:00:08.617 ********
2026-03-19 05:40:37.579583 | controller | skipping: [compute-1]
2026-03-19 05:40:37.602739 | controller |
2026-03-19 05:40:37.602780 | controller | TASK [osp.edpm.edpm_install_certs : Remove certificate requests] ***************
2026-03-19 05:40:37.602791 | controller | Thursday 19 March 2026  05:40:37 +0000 (0:00:00.018)       0:00:08.636 ********
2026-03-19 05:40:37.602805 | controller | skipping: [compute-1]
2026-03-19 05:40:37.602830 | controller |
2026-03-19 05:40:37.602970 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reload units] ****************************
2026-03-19 05:40:37.602995 | controller | Thursday 19 March 2026  05:40:37 +0000 (0:00:00.023)       0:00:08.660 ********
2026-03-19 05:40:38.021677 | controller | ok: [compute-1]
2026-03-19 05:40:39.326374 | controller |
2026-03-19 05:40:39.326407 | controller | TASK [osp.edpm.edpm_tripleo_cleanup : Reset state of failed systemd units] *****
2026-03-19 05:40:39.326421 | controller | Thursday 19 March 2026  05:40:38 +0000 (0:00:00.418)       0:00:09.078 ********
2026-03-19 05:40:39.326437 | controller | changed: [compute-1] => (item=tripleo_nova_libvirt.target)
2026-03-19 05:40:39.326456 | controller | changed: [compute-1] => (item=tripleo_nova_virtlogd_wrapper.service)
2026-03-19 05:40:39.326736 | controller | changed: [compute-1] => (item=tripleo_nova_virtnodedevd.service)
2026-03-19 05:40:39.326782 | controller | changed: [compute-1] => (item=tripleo_nova_virtproxyd.service)
2026-03-19 05:40:39.326793 | controller | changed: [compute-1] => (item=tripleo_nova_virtqemud.service)
2026-03-19 05:40:39.327239 | controller | changed: [compute-1] => (item=tripleo_nova_virtsecretd.service)
2026-03-19 05:40:39.360008 | controller | changed: [compute-1] => (item=tripleo_nova_virtstoraged.service)
2026-03-19 05:40:39.360025 | controller |
2026-03-19 05:40:39.360032 | controller | TASK [osp.edpm.edpm_libvirt : Ensure libvirt user] *****************************
2026-03-19 05:40:39.360039 | controller | Thursday 19 March 2026  05:40:39 +0000 (0:00:01.305)       0:00:10.383 ********
2026-03-19 05:40:39.360047 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/collections/ansible_collections/osp/edpm/roles/edpm_libvirt/tasks/user.yml for compute-1
2026-03-19 05:40:39.378660 | controller |
2026-03-19 05:40:39.378691 | controller | TASK [osp.edpm.edpm_users : Validating arguments against arg spec 'main' - The main entry point for the edpm_users role.] ***
2026-03-19 05:40:39.378699 | controller | Thursday 19 March 2026  05:40:39 +0000 (0:00:00.033)       0:00:10.417 ********
2026-03-19 05:40:39.378709 | controller | ok: [compute-1]
2026-03-19 05:40:39.378731 | controller |
2026-03-19 05:40:39.378739 | controller | TASK [osp.edpm.edpm_users : Gather user fact] **********************************
2026-03-19 05:40:39.378846 | controller | Thursday 19 March 2026  05:40:39 +0000 (0:00:00.018)       0:00:10.436 ********
2026-03-19 05:40:39.400547 | controller | skipping: [compute-1]
2026-03-19 05:40:39.432833 | controller |
2026-03-19 05:40:39.432856 | controller | TASK [osp.edpm.edpm_users : Create host users and groups] **********************
2026-03-19 05:40:39.432867 | controller | Thursday 19 March 2026  05:40:39 +0000 (0:00:00.021)       0:00:10.457 ********
2026-03-19 05:40:39.432879 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/collections/ansible_collections/osp/edpm/roles/edpm_users/tasks/create_users_and_groups.yml for compute-1 => (item={'name': 'libvirt', 'uid': '42473', 'gid': '42473', 'shell': '/sbin/nologin', 'comment': 'libvirt user'})
2026-03-19 05:40:39.726102 | controller |
2026-03-19 05:40:39.726135 | controller | TASK [osp.edpm.edpm_users : Detect when user exists and cannot be created] *****
2026-03-19 05:40:39.726144 | controller | Thursday 19 March 2026  05:40:39 +0000 (0:00:00.032)       0:00:10.490 ********
2026-03-19 05:40:39.726154 | controller | fatal: [compute-1]: FAILED! => changed=false
2026-03-19 05:40:39.736281 | controller |   msg: One or more supplied key could not be found in the database.
2026-03-19 05:40:39.736299 | controller | ...ignoring
2026-03-19 05:40:40.050674 | controller |
2026-03-19 05:40:40.050700 | controller | TASK [osp.edpm.edpm_users : Ensure group is present on the host [ libvirt ]] ***
2026-03-19 05:40:40.050708 | controller | Thursday 19 March 2026  05:40:39 +0000 (0:00:00.303)       0:00:10.793 ********
2026-03-19 05:40:40.050718 | controller | changed: [compute-1]
2026-03-19 05:40:40.448385 | controller |
2026-03-19 05:40:40.448414 | controller | TASK [osp.edpm.edpm_users : Ensure user is present on the host [ libvirt ]] ****
2026-03-19 05:40:40.448422 | controller | Thursday 19 March 2026  05:40:40 +0000 (0:00:00.314)       0:00:11.107 ********
2026-03-19 05:40:40.448432 | controller | changed: [compute-1]
2026-03-19 05:40:40.470316 | controller |
2026-03-19 05:40:40.470332 | controller | TASK [osp.edpm.edpm_users : Create host directories] ***************************
2026-03-19 05:40:40.470339 | controller | Thursday 19 March 2026  05:40:40 +0000 (0:00:00.397)       0:00:11.505 ********
2026-03-19 05:40:40.470347 | controller | skipping: [compute-1]
2026-03-19 05:40:40.501119 | controller |
2026-03-19 05:40:40.501137 | controller | TASK [osp.edpm.edpm_libvirt : Install libvirt] *********************************
2026-03-19 05:40:40.501146 | controller | Thursday 19 March 2026  05:40:40 +0000 (0:00:00.022)       0:00:11.527 ********
2026-03-19 05:40:40.501156 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/collections/ansible_collections/osp/edpm/roles/edpm_libvirt/tasks/install.yml for compute-1
2026-03-19 05:40:40.524685 | controller |
2026-03-19 05:40:40.524721 | controller | TASK [osp.edpm.edpm_libvirt : Gather ansible_local facts] **********************
2026-03-19 05:40:40.524729 | controller | Thursday 19 March 2026  05:40:40 +0000 (0:00:00.030)       0:00:11.558 ********
2026-03-19 05:40:40.524740 | controller | skipping: [compute-1]
2026-03-19 05:41:51.494852 | controller |
2026-03-19 05:41:51.494889 | controller | TASK [osp.edpm.edpm_libvirt : Install libvirt packages] ************************
2026-03-19 05:41:51.494898 | controller | Thursday 19 March 2026  05:40:40 +0000 (0:00:00.023)       0:00:11.581 ********
2026-03-19 05:41:51.494908 | controller | changed: [compute-1]
2026-03-19 05:41:53.678725 | controller |
2026-03-19 05:41:53.678754 | controller | TASK [osp.edpm.edpm_libvirt : Ensure monolithic libvirt and tcp socket activation is not enabled or running] ***
2026-03-19 05:41:53.678771 | controller | Thursday 19 March 2026  05:41:51 +0000 (0:01:10.969)       0:01:22.551 ********
2026-03-19 05:41:53.678782 | controller | changed: [compute-1] => (item=libvirtd)
2026-03-19 05:41:53.679254 | controller | changed: [compute-1] => (item=libvirtd-tcp.socket)
2026-03-19 05:41:56.001439 | controller | changed: [compute-1] => (item=libvirtd-tls.socket)
2026-03-19 05:41:56.001472 | controller | changed: [compute-1] => (item=virtproxyd-tcp.socket)
2026-03-19 05:41:56.001480 | controller |
2026-03-19 05:41:56.001486 | controller | TASK [osp.edpm.edpm_libvirt : Ensure libvirt services are enabled and running] ***
2026-03-19 05:41:56.001492 | controller | Thursday 19 March 2026  05:41:53 +0000 (0:00:02.184)       0:01:24.736 ********
2026-03-19 05:41:56.001503 | controller | changed: [compute-1] => (item=virtlogd)
2026-03-19 05:41:56.001527 | controller | changed: [compute-1] => (item=virtnodedevd)
2026-03-19 05:41:56.001542 | controller | changed: [compute-1] => (item=virtproxyd)
2026-03-19 05:41:56.001550 | controller | ok: [compute-1] => (item=virtqemud)
2026-03-19 05:41:56.001661 | controller | changed: [compute-1] => (item=virtsecretd)
2026-03-19 05:41:56.002086 | controller |
2026-03-19 05:41:56.532213 | controller | TASK [osp.edpm.edpm_libvirt : Configure virtproxyd-tls.socket] *****************
2026-03-19 05:41:56.532250 | controller | Thursday 19 March 2026  05:41:55 +0000 (0:00:02.322)       0:01:27.058 ********
2026-03-19 05:41:56.532262 | controller | changed: [compute-1]
2026-03-19 05:42:00.729723 | controller |
2026-03-19 05:42:00.729761 | controller | TASK [osp.edpm.edpm_libvirt : Configure socket activation for libvirt services] ***
2026-03-19 05:42:00.729772 | controller | Thursday 19 March 2026  05:41:56 +0000 (0:00:00.530)       0:01:27.589 ********
2026-03-19 05:42:00.729786 | controller | ok: [compute-1] => (item=['virtlogd', ''])
2026-03-19 05:42:00.729819 | controller | skipping: [compute-1] => (item=['virtlogd', '-ro'])
2026-03-19 05:42:00.729829 | controller | ok: [compute-1] => (item=['virtlogd', '-admin'])
2026-03-19 05:42:00.729836 | controller | ok: [compute-1] => (item=['virtnodedevd', ''])
2026-03-19 05:42:00.729844 | controller | ok: [compute-1] => (item=['virtnodedevd', '-ro'])
2026-03-19 05:42:00.729851 | controller | ok: [compute-1] => (item=['virtnodedevd', '-admin'])
2026-03-19 05:42:00.729861 | controller | ok: [compute-1] => (item=['virtproxyd', ''])
2026-03-19 05:42:00.730894 | controller | ok: [compute-1] => (item=['virtproxyd', '-ro'])
2026-03-19 05:42:00.771464 | controller | ok: [compute-1] => (item=['virtproxyd', '-admin'])
2026-03-19 05:42:00.771492 | controller | ok: [compute-1] => (item=['virtqemud', ''])
2026-03-19 05:42:00.771499 | controller | ok: [compute-1] => (item=['virtqemud', '-ro'])
2026-03-19 05:42:00.771505 | controller | ok: [compute-1] => (item=['virtqemud', '-admin'])
2026-03-19 05:42:00.771510 | controller | ok: [compute-1] => (item=['virtsecretd', ''])
2026-03-19 05:42:00.771516 | controller | ok: [compute-1] => (item=['virtsecretd', '-ro'])
2026-03-19 05:42:00.771521 | controller | ok: [compute-1] => (item=['virtsecretd', '-admin'])
2026-03-19 05:42:00.771526 | controller |
2026-03-19 05:42:00.771531 | controller | TASK [osp.edpm.edpm_libvirt : Configure libvirt] *******************************
2026-03-19 05:42:00.771537 | controller | Thursday 19 March 2026  05:42:00 +0000 (0:00:04.197)       0:01:31.786 ********
2026-03-19 05:42:00.771547 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/collections/ansible_collections/osp/edpm/roles/edpm_libvirt/tasks/configure.yml for compute-1
2026-03-19 05:42:00.807188 | controller |
2026-03-19 05:42:00.807212 | controller | TASK [osp.edpm.edpm_libvirt : Get libvirt password] ****************************
2026-03-19 05:42:00.807226 | controller | Thursday 19 March 2026  05:42:00 +0000 (0:00:00.042)       0:01:31.828 ********
2026-03-19 05:42:00.807236 | controller | ok: [compute-1]
2026-03-19 05:42:00.830805 | controller |
2026-03-19 05:42:00.830850 | controller | TASK [osp.edpm.edpm_libvirt : Gather user fact] ********************************
2026-03-19 05:42:00.830866 | controller | Thursday 19 March 2026  05:42:00 +0000 (0:00:00.035)       0:01:31.864 ********
2026-03-19 05:42:00.830883 | controller | skipping: [compute-1]
2026-03-19 05:42:01.939957 | controller |
2026-03-19 05:42:01.939988 | controller | TASK [osp.edpm.edpm_libvirt : Create libvirt config dir] ***********************
2026-03-19 05:42:01.939997 | controller | Thursday 19 March 2026  05:42:00 +0000 (0:00:00.023)       0:01:31.888 ********
2026-03-19 05:42:01.940007 | controller | changed: [compute-1] => (item={'path': '/etc/tmpfiles.d/', 'owner': 'root', 'group': 'root'})
2026-03-19 05:42:01.960411 | controller | changed: [compute-1] => (item={'path': '/var/lib/edpm-config/firewall', 'owner': 'root', 'group': 'root'})
2026-03-19 05:42:01.960427 | controller | changed: [compute-1] => (item={'path': '/etc/pki/libvirt', 'owner': 'root', 'group': 'root', 'mode': '0755'})
2026-03-19 05:42:01.960434 | controller | changed: [compute-1] => (item={'path': '/etc/pki/libvirt/private', 'owner': 'root', 'group': 'root', 'mode': '0755'})
2026-03-19 05:42:01.960440 | controller | changed: [compute-1] => (item={'path': '/etc/pki/CA', 'owner': 'root', 'group': 'root', 'mode': '0755'})
2026-03-19 05:42:01.960445 | controller | changed: [compute-1] => (item={'path': '/etc/pki/qemu', 'owner': 'root', 'group': 'qemu'})
2026-03-19 05:42:01.960450 | controller |
2026-03-19 05:42:01.960456 | controller | TASK [osp.edpm.edpm_libvirt : Gather SELinux fact if needed] *******************
2026-03-19 05:42:01.960466 | controller | Thursday 19 March 2026  05:42:01 +0000 (0:00:01.108)       0:01:32.996 ********
2026-03-19 05:42:01.960474 | controller | skipping: [compute-1]
2026-03-19 05:42:04.988681 | controller |
2026-03-19 05:42:04.988719 | controller | TASK [osp.edpm.edpm_libvirt : Render libvirt config files] *********************
2026-03-19 05:42:04.988727 | controller | Thursday 19 March 2026  05:42:01 +0000 (0:00:00.020)       0:01:33.017 ********
2026-03-19 05:42:04.988737 | controller | changed: [compute-1] => (item={'src': 'virtlogd.conf', 'dest': 'libvirt/virtlogd.conf'})
2026-03-19 05:42:04.988760 | controller | changed: [compute-1] => (item={'src': 'virtnodedevd.conf', 'dest': 'libvirt/virtnodedevd.conf'})
2026-03-19 05:42:04.988766 | controller | changed: [compute-1] => (item={'src': 'virtproxyd.conf', 'dest': 'libvirt/virtproxyd.conf'})
2026-03-19 05:42:04.988772 | controller | changed: [compute-1] => (item={'src': 'virtqemud.conf', 'dest': 'libvirt/virtqemud.conf'})
2026-03-19 05:42:04.988777 | controller | changed: [compute-1] => (item={'src': 'qemu.conf.j2', 'dest': 'libvirt/qemu.conf'})
2026-03-19 05:42:04.988782 | controller | changed: [compute-1] => (item={'src': 'virtsecretd.conf', 'dest': 'libvirt/virtsecretd.conf'})
2026-03-19 05:42:04.988789 | controller | changed: [compute-1] => (item={'src': 'auth.conf', 'dest': 'libvirt/auth.conf', 'mode': '0600'})
2026-03-19 05:42:04.988809 | controller | changed: [compute-1] => (item={'src': 'sasl_libvirt.conf', 'dest': 'sasl2/libvirt.conf'})
2026-03-19 05:42:04.989077 | controller |
2026-03-19 05:42:05.196113 | controller | TASK [osp.edpm.edpm_libvirt : Add libvirt password to sasl db] *****************
2026-03-19 05:42:05.196140 | controller | Thursday 19 March 2026  05:42:04 +0000 (0:00:03.028)       0:01:36.045 ********
2026-03-19 05:42:05.196152 | controller | changed: [compute-1]
2026-03-19 05:42:05.224718 | controller |
2026-03-19 05:42:05.224741 | controller | TASK [osp.edpm.edpm_libvirt : Remove libvirt sasl db] **************************
2026-03-19 05:42:05.224748 | controller | Thursday 19 March 2026  05:42:05 +0000 (0:00:00.207)       0:01:36.253 ********
2026-03-19 05:42:05.224758 | controller | skipping: [compute-1]
2026-03-19 05:42:07.807727 | controller |
2026-03-19 05:42:07.807754 | controller | TASK [osp.edpm.edpm_libvirt : Create libvirt socket activation drop-in directories] ***
2026-03-19 05:42:07.807762 | controller | Thursday 19 March 2026  05:42:05 +0000 (0:00:00.028)       0:01:36.282 ********
2026-03-19 05:42:07.807772 | controller | changed: [compute-1] => (item=['virtlogd', ''])
2026-03-19 05:42:07.807791 | controller | skipping: [compute-1] => (item=['virtlogd', '-ro'])
2026-03-19 05:42:07.807797 | controller | changed: [compute-1] => (item=['virtlogd', '-admin'])
2026-03-19 05:42:07.807804 | controller | changed: [compute-1] => (item=['virtnodedevd', ''])
2026-03-19 05:42:07.808881 | controller | changed: [compute-1] => (item=['virtnodedevd', '-ro'])
2026-03-19 05:42:12.527720 | controller | changed: [compute-1] => (item=['virtnodedevd', '-admin'])
2026-03-19 05:42:12.527752 | controller | changed: [compute-1] => (item=['virtproxyd', ''])
2026-03-19 05:42:12.527760 | controller | changed: [compute-1] => (item=['virtproxyd', '-ro'])
2026-03-19 05:42:12.527766 | controller | changed: [compute-1] => (item=['virtproxyd', '-admin'])
2026-03-19 05:42:12.527772 | controller | changed: [compute-1] => (item=['virtqemud', ''])
2026-03-19 05:42:12.527778 | controller | changed: [compute-1] => (item=['virtqemud', '-ro'])
2026-03-19 05:42:12.527783 | controller | changed: [compute-1] => (item=['virtqemud', '-admin'])
2026-03-19 05:42:12.527789 | controller | changed: [compute-1] => (item=['virtsecretd', ''])
2026-03-19 05:42:12.527794 | controller | changed: [compute-1] => (item=['virtsecretd', '-ro'])
2026-03-19 05:42:12.527800 | controller | changed: [compute-1] => (item=['virtsecretd', '-admin'])
2026-03-19 05:42:12.527806 | controller |
2026-03-19 05:42:12.527811 | controller | TASK [osp.edpm.edpm_libvirt : Render libvirt socket activation drop-in files] ***
2026-03-19 05:42:12.527817 | controller | Thursday 19 March 2026  05:42:07 +0000 (0:00:02.582)       0:01:38.865 ********
2026-03-19 05:42:12.527831 | controller | changed: [compute-1] => (item=['virtlogd', ''])
2026-03-19 05:42:12.527855 | controller | skipping: [compute-1] => (item=['virtlogd', '-ro'])
2026-03-19 05:42:12.527862 | controller | changed: [compute-1] => (item=['virtlogd', '-admin'])
2026-03-19 05:42:12.527868 | controller | changed: [compute-1] => (item=['virtnodedevd', ''])
2026-03-19 05:42:12.527874 | controller | changed: [compute-1] => (item=['virtnodedevd', '-ro'])
2026-03-19 05:42:12.527886 | controller | changed: [compute-1] => (item=['virtnodedevd', '-admin'])
2026-03-19 05:42:12.527892 | controller | changed: [compute-1] => (item=['virtproxyd', ''])
2026-03-19 05:42:12.527897 | controller | changed: [compute-1] => (item=['virtproxyd', '-ro'])
2026-03-19 05:42:12.527904 | controller | changed: [compute-1] => (item=['virtproxyd', '-admin'])
2026-03-19 05:42:12.527926 | controller | changed: [compute-1] => (item=['virtqemud', ''])
2026-03-19 05:42:12.528586 | controller | changed: [compute-1] => (item=['virtqemud', '-ro'])
2026-03-19 05:42:12.712965 | controller | changed: [compute-1] => (item=['virtqemud', '-admin'])
2026-03-19 05:42:12.712984 | controller | changed: [compute-1] => (item=['virtsecretd', ''])
2026-03-19 05:42:12.712991 | controller | changed: [compute-1] => (item=['virtsecretd', '-ro'])
2026-03-19 05:42:12.712996 | controller | changed: [compute-1] => (item=['virtsecretd', '-admin'])
2026-03-19 05:42:12.713001 | controller |
2026-03-19 05:42:12.713007 | controller | TASK [osp.edpm.edpm_libvirt : Check if /run/libvirt has container_file_t selinux label] ***
2026-03-19 05:42:12.713013 | controller | Thursday 19 March 2026  05:42:12 +0000 (0:00:04.719)       0:01:43.584 ********
2026-03-19 05:42:12.713021 | controller | ok: [compute-1]
2026-03-19 05:42:12.742459 | controller |
2026-03-19 05:42:12.742477 | controller | TASK [osp.edpm.edpm_libvirt : Restore selinux label for /run/libvirt to virt_var_run_t] ***
2026-03-19 05:42:12.742484 | controller | Thursday 19 March 2026  05:42:12 +0000 (0:00:00.185)       0:01:43.770 ********
2026-03-19 05:42:12.742493 | controller | skipping: [compute-1]
2026-03-19 05:42:13.757343 | controller |
2026-03-19 05:42:13.757374 | controller | TASK [osp.edpm.edpm_libvirt : Enable os_enable_vtpm SELinux boolean for vTPM] ***
2026-03-19 05:42:13.757383 | controller | Thursday 19 March 2026  05:42:12 +0000 (0:00:00.029)       0:01:43.799 ********
2026-03-19 05:42:13.757400 | controller | changed: [compute-1]
2026-03-19 05:42:14.701117 | controller |
2026-03-19 05:42:14.701144 | controller | TASK [osp.edpm.edpm_libvirt : Move libvirt TLS files to the right location on the compute node] ***
2026-03-19 05:42:14.701152 | controller | Thursday 19 March 2026  05:42:13 +0000 (0:00:01.014)       0:01:44.814 ********
2026-03-19 05:42:14.701161 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.crt', 'dest': '/etc/pki/libvirt/servercert.pem'})
2026-03-19 05:42:15.643736 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.key', 'dest': '/etc/pki/libvirt/private/serverkey.pem', 'mode': '0600'})
2026-03-19 05:42:15.643770 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.crt', 'dest': '/etc/pki/libvirt/clientcert.pem'})
2026-03-19 05:42:15.643779 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.key', 'dest': '/etc/pki/libvirt/private/clientkey.pem'})
2026-03-19 05:42:15.643786 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/ca.crt', 'dest': '/etc/pki/CA/cacert.pem'})
2026-03-19 05:42:15.643793 | controller |
2026-03-19 05:42:15.643800 | controller | TASK [osp.edpm.edpm_libvirt : Move qemu TLS files to the right location on the compute node] ***
2026-03-19 05:42:15.643806 | controller | Thursday 19 March 2026  05:42:14 +0000 (0:00:00.943)       0:01:45.758 ********
2026-03-19 05:42:15.643818 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.crt', 'dest': '/etc/pki/qemu/server-cert.pem'})
2026-03-19 05:42:15.643845 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.key', 'dest': '/etc/pki/qemu/server-key.pem'})
2026-03-19 05:42:15.643852 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.crt', 'dest': '/etc/pki/qemu/client-cert.pem'})
2026-03-19 05:42:15.643858 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/tls.key', 'dest': '/etc/pki/qemu/client-key.pem'})
2026-03-19 05:42:15.643867 | controller | changed: [compute-1] => (item={'src': '/tmp/pki/ca.crt', 'dest': '/etc/pki/qemu/ca-cert.pem'})
2026-03-19 05:42:15.643890 | controller |
2026-03-19 05:42:15.644314 | controller | TASK [osp.edpm.edpm_libvirt : Flush handlers] **********************************
2026-03-19 05:42:18.148037 | controller | Thursday 19 March 2026  05:42:15 +0000 (0:00:00.913)       0:01:46.671 ********
2026-03-19 05:42:18.148066 | controller |
2026-03-19 05:42:18.148074 | controller | RUNNING HANDLER [osp.edpm.edpm_libvirt : Restart libvirt services] *************
2026-03-19 05:42:18.148080 | controller | Thursday 19 March 2026  05:42:15 +0000 (0:00:00.029)       0:01:46.700 ********
2026-03-19 05:42:18.148097 | controller | changed: [compute-1] => (item=virtlogd)
2026-03-19 05:42:18.190475 | controller | changed: [compute-1] => (item=virtnodedevd)
2026-03-19 05:42:18.190495 | controller | changed: [compute-1] => (item=virtproxyd)
2026-03-19 05:42:18.190502 | controller | changed: [compute-1] => (item=virtqemud)
2026-03-19 05:42:18.190508 | controller | changed: [compute-1] => (item=virtsecretd)
2026-03-19 05:42:18.190513 | controller |
2026-03-19 05:42:18.190519 | controller | TASK [osp.edpm.edpm_libvirt : Post-install libvirt] ****************************
2026-03-19 05:42:18.190525 | controller | Thursday 19 March 2026  05:42:18 +0000 (0:00:02.503)       0:01:49.204 ********
2026-03-19 05:42:18.190533 | controller | included: /home/zuul/.cache/ansible-compat/40c08c/collections/ansible_collections/osp/edpm/roles/edpm_libvirt/tasks/post-install.yml for compute-1
2026-03-19 05:42:18.405461 | controller |
2026-03-19 05:42:18.405486 | controller | TASK [osp.edpm.edpm_libvirt : Disable libvirt default network autostart] *******
2026-03-19 05:42:18.405494 | controller | Thursday 19 March 2026  05:42:18 +0000 (0:00:00.043)       0:01:49.248 ********
2026-03-19 05:42:18.405504 | controller | fatal: [compute-1]: FAILED! => changed=false
2026-03-19 05:42:18.407499 | controller |   cmd:
2026-03-19 05:42:18.407515 | controller |   - virsh
2026-03-19 05:42:18.407522 | controller |   - net-autostart
2026-03-19 05:42:18.407527 | controller |   - --network
2026-03-19 05:42:18.407533 | controller |   - default
2026-03-19 05:42:18.407538 | controller |   - --disable
2026-03-19 05:42:18.407544 | controller |   delta: '0:00:00.020672'
2026-03-19 05:42:18.407549 | controller |   end: '2026-03-19 05:42:18.371994'
2026-03-19 05:42:18.407554 | controller |   failed_when_result: true
2026-03-19 05:42:18.407560 | controller |   msg: non-zero return code
2026-03-19 05:42:18.407564 | controller |   rc: 1
2026-03-19 05:42:18.407569 | controller |   start: '2026-03-19 05:42:18.351322'
2026-03-19 05:42:18.407574 | controller |   stderr: |-
2026-03-19 05:42:18.407580 | controller |     error: failed to get network 'default'
2026-03-19 05:42:18.407606 | controller |     error: Failed to connect socket to '/var/run/libvirt/virtnetworkd-sock': No such file or directory
2026-03-19 05:42:18.407617 | controller |   stderr_lines: <omitted>
2026-03-19 05:42:18.407622 | controller |   stdout: ''
2026-03-19 05:42:18.407627 | controller |   stdout_lines: <omitted>
2026-03-19 05:42:18.407635 | controller |
2026-03-19 05:42:18.409686 | controller | PLAY RECAP *********************************************************************
2026-03-19 05:42:18.409720 | controller | compute-1                  : ok=44   changed=16   unreachable=0    failed=1    skipped=14   rescued=0    ignored=1
2026-03-19 05:42:18.409834 | controller |
2026-03-19 05:42:18.409843 | controller | Thursday 19 March 2026  05:42:18 +0000 (0:00:00.216)       0:01:49.465 ********
2026-03-19 05:42:18.409849 | controller | ===============================================================================
2026-03-19 05:42:18.409855 | controller | osp.edpm.edpm_libvirt : Install libvirt packages ----------------------- 70.97s
2026-03-19 05:42:18.409861 | controller | osp.edpm.edpm_libvirt : Render libvirt socket activation drop-in files --- 4.72s
2026-03-19 05:42:18.409867 | controller | osp.edpm.edpm_libvirt : Configure socket activation for libvirt services --- 4.20s
2026-03-19 05:42:18.409873 | controller | osp.edpm.edpm_libvirt : Render libvirt config files --------------------- 3.03s
2026-03-19 05:42:18.409878 | controller | osp.edpm.edpm_libvirt : Create libvirt socket activation drop-in directories --- 2.58s
2026-03-19 05:42:18.409884 | controller | osp.edpm.edpm_libvirt : Restart libvirt services ------------------------ 2.50s
2026-03-19 05:42:18.409889 | controller | osp.edpm.edpm_libvirt : Ensure libvirt services are enabled and running --- 2.32s
2026-03-19 05:42:18.409895 | controller | osp.edpm.edpm_libvirt : Ensure monolithic libvirt and tcp socket activation is not enabled or running --- 2.18s
2026-03-19 05:42:18.409901 | controller | osp.edpm.edpm_tripleo_cleanup : Stop and disable tripleo services ------- 1.87s
2026-03-19 05:42:18.409906 | controller | osp.edpm.edpm_tripleo_cleanup : Gather service facts -------------------- 1.67s
2026-03-19 05:42:18.409912 | controller | osp.edpm.edpm_tripleo_cleanup : Reset state of failed systemd units ----- 1.31s
2026-03-19 05:42:18.409917 | controller | osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /usr/lib/systemd/system --- 1.30s
2026-03-19 05:42:18.409923 | controller | osp.edpm.edpm_tripleo_cleanup : Remove unit files from the location: /etc/systemd/system --- 1.23s
2026-03-19 05:42:18.409933 | controller | osp.edpm.edpm_libvirt : Create libvirt config dir ----------------------- 1.11s
2026-03-19 05:42:18.409939 | controller | osp.edpm.edpm_libvirt : Enable os_enable_vtpm SELinux boolean for vTPM --- 1.01s
2026-03-19 05:42:18.409944 | controller | osp.edpm.edpm_libvirt : Move libvirt TLS files to the right location on the compute node --- 0.94s
2026-03-19 05:42:18.409953 | controller | osp.edpm.edpm_libvirt : Move qemu TLS files to the right location on the compute node --- 0.91s
2026-03-19 05:42:18.409959 | controller | Gathering Facts --------------------------------------------------------- 0.74s
2026-03-19 05:42:18.409967 | controller | osp.edpm.edpm_tripleo_cleanup : Reload units to check for new changes --- 0.61s
2026-03-19 05:42:18.465732 | controller | osp.edpm.edpm_libvirt : Configure virtproxyd-tls.socket ----------------- 0.53s
2026-03-19 05:42:18.465764 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/edpm_libvirt/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/roles/edpm_libvirt/molecule/default/converge.yml']
2026-03-19 05:42:18.466074 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2026-03-19 05:42:18.474149 | controller | INFO     Running default > cleanup
2026-03-19 05:42:18.474516 | controller | WARNING  Skipping, cleanup playbook not configured.
2026-03-19 05:42:18.482880 | controller | INFO     Running default > destroy
2026-03-19 05:42:18.483180 | controller | WARNING  Skipping, instances are delegated.
2026-03-19 05:42:18.483484 | controller | INFO     Pruning extra files from scenario ephemeral directory
2026-03-19 05:42:18.485069 | controller | INFO     Writing /tmp/report.html report.
2026-03-19 05:42:18.965161 | controller | ERROR
2026-03-19 05:42:18.965381 | controller | {
2026-03-19 05:42:18.965414 | controller |   "delta": "0:04:08.554525",
2026-03-19 05:42:18.965435 | controller |   "end": "2026-03-19 05:42:18.529533",
2026-03-19 05:42:18.965453 | controller |   "msg": "non-zero return code",
2026-03-19 05:42:18.965468 | controller |   "rc": 1,
2026-03-19 05:42:18.965484 | controller |   "start": "2026-03-19 05:38:09.975008"
2026-03-19 05:42:18.965498 | controller | }
failure
2026-03-19 05:42:19.012296 | 
2026-03-19 05:42:19.012346 | PLAY RECAP
2026-03-19 05:42:19.012386 | controller | ok: 3 changed: 1 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-03-19 05:42:19.012408 | 
2026-03-19 05:42:19.072703 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-03-19 05:42:19.073472 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-19 05:42:19.560116 | 
2026-03-19 05:42:19.560219 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-03-19 05:42:19.578877 | 
2026-03-19 05:42:19.578960 | TASK [Filter out host if needed]
2026-03-19 05:42:19.587321 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-03-19 05:42:19.591485 | 
2026-03-19 05:42:19.591552 | TASK [Ensure file is present]
2026-03-19 05:42:19.862669 | controller | ok
2026-03-19 05:42:19.868766 | 
2026-03-19 05:42:19.868828 | TASK [Manage molecule report file]
2026-03-19 05:42:20.389646 | controller | changed
2026-03-19 05:42:20.394342 | 
2026-03-19 05:42:20.394400 | TASK [Check if we get ci-framework-data basedir]
2026-03-19 05:42:20.564400 | controller | ok
2026-03-19 05:42:20.568859 | 
2026-03-19 05:42:20.568914 | TASK [Create ci-framework-data log directory for zuul]
2026-03-19 05:42:20.850767 | controller | changed
2026-03-19 05:42:20.855322 | 
2026-03-19 05:42:20.855382 | TASK [Copy ci-framework interesting files]
2026-03-19 05:42:21.029198 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-03-19 05:42:21.376716 | controller | changed
2026-03-19 05:42:21.381290 | 
2026-03-19 05:42:21.381349 | TASK [Get SELinux listing]
2026-03-19 05:42:21.904258 | controller | changed
2026-03-19 05:42:21.909140 | 
2026-03-19 05:42:21.909200 | TASK [Generate log index]
2026-03-19 05:42:22.485083 | controller | changed
2026-03-19 05:42:22.489385 | 
2026-03-19 05:42:22.489442 | TASK [Get some env related data]
2026-03-19 05:42:23.025768 | controller | /home/zuul/.local/bin/ansible
2026-03-19 05:42:24.012388 | controller | changed
2026-03-19 05:42:24.017153 | 
2026-03-19 05:42:24.017215 | TASK [Generate list of logs to collect in home directory]
2026-03-19 05:42:24.278398 | controller | ok: All paths examined
2026-03-19 05:42:24.282908 | 
2026-03-19 05:42:24.282964 | LOOP [Copy logs from home directory]
2026-03-19 05:42:24.576365 | controller | changed:
2026-03-19 05:42:24.576510 | controller | {
2026-03-19 05:42:24.576538 | controller |   "atime": 1773898672.575724,
2026-03-19 05:42:24.576559 | controller |   "ctime": 1773898687.8567564,
2026-03-19 05:42:24.576577 | controller |   "dev": 64513,
2026-03-19 05:42:24.576594 | controller |   "gid": 1000,
2026-03-19 05:42:24.576611 | controller |   "gr_name": "zuul",
2026-03-19 05:42:24.576628 | controller |   "inode": 559874,
2026-03-19 05:42:24.576645 | controller |   "isblk": false,
2026-03-19 05:42:24.576661 | controller |   "ischr": false,
2026-03-19 05:42:24.576676 | controller |   "isdir": false,
2026-03-19 05:42:24.576693 | controller |   "isfifo": false,
2026-03-19 05:42:24.576709 | controller |   "isgid": false,
2026-03-19 05:42:24.576724 | controller |   "islnk": false,
2026-03-19 05:42:24.576740 | controller |   "isreg": true,
2026-03-19 05:42:24.576756 | controller |   "issock": false,
2026-03-19 05:42:24.576770 | controller |   "isuid": false,
2026-03-19 05:42:24.576785 | controller |   "mode": "0644",
2026-03-19 05:42:24.576799 | controller |   "mtime": 1773898687.8567564,
2026-03-19 05:42:24.576813 | controller |   "nlink": 1,
2026-03-19 05:42:24.576827 | controller |   "path": "/home/zuul/ansible.log",
2026-03-19 05:42:24.576844 | controller |   "pw_name": "zuul",
2026-03-19 05:42:24.576859 | controller |   "rgrp": true,
2026-03-19 05:42:24.576874 | controller |   "roth": true,
2026-03-19 05:42:24.576889 | controller |   "rusr": true,
2026-03-19 05:42:24.576903 | controller |   "size": 6749,
2026-03-19 05:42:24.576917 | controller |   "uid": 1000,
2026-03-19 05:42:24.576931 | controller |   "wgrp": false,
2026-03-19 05:42:24.576945 | controller |   "woth": false,
2026-03-19 05:42:24.576962 | controller |   "wusr": true,
2026-03-19 05:42:24.576977 | controller |   "xgrp": false,
2026-03-19 05:42:24.576991 | controller |   "xoth": false,
2026-03-19 05:42:24.577004 | controller |   "xusr": false
2026-03-19 05:42:24.577018 | controller | }
2026-03-19 05:42:24.602129 | 
2026-03-19 05:42:24.602204 | TASK [Copy crio stats log file]
2026-03-19 05:42:24.615747 | controller | skipping: Conditional result was False
2026-03-19 05:42:24.620925 | 
2026-03-19 05:42:24.620987 | TASK [Get SELinux related data]
2026-03-19 05:42:24.824048 | controller | <no matches>
2026-03-19 05:42:25.143212 | controller | ERROR
2026-03-19 05:42:25.143482 | controller | {
2026-03-19 05:42:25.143523 | controller |   "delta": "0:00:00.011141",
2026-03-19 05:42:25.143559 | controller |   "end": "2026-03-19 05:42:24.825021",
2026-03-19 05:42:25.143584 | controller |   "msg": "non-zero return code",
2026-03-19 05:42:25.143605 | controller |   "rc": 1,
2026-03-19 05:42:25.143625 | controller |   "start": "2026-03-19 05:42:24.813880"
2026-03-19 05:42:25.143644 | controller | }
2026-03-19 05:42:25.143671 | controller | ERROR: Ignoring Errors
2026-03-19 05:42:25.148709 | 
2026-03-19 05:42:25.148773 | TASK [Create system configuration directory]
2026-03-19 05:42:25.340011 | controller | changed
2026-03-19 05:42:25.344944 | 
2026-03-19 05:42:25.345009 | TASK [Get some of the system configurations]
2026-03-19 05:42:25.876322 | controller | changed
2026-03-19 05:42:25.881570 | 
2026-03-19 05:42:25.881632 | TASK [Copy generated documentation if available]
2026-03-19 05:42:25.896292 | controller | skipping: Conditional result was False
2026-03-19 05:42:25.901933 | 
2026-03-19 05:42:25.902000 | TASK [Copy generated AsciiDoc documentation if available]
2026-03-19 05:42:25.914995 | controller | skipping: Conditional result was False
2026-03-19 05:42:25.920605 | 
2026-03-19 05:42:25.920666 | TASK [Compress logs bigger than 2MB]
2026-03-19 05:42:26.445695 | controller | changed
2026-03-19 05:42:26.450607 | 
2026-03-19 05:42:26.450663 | TASK [Copy files from workspace on node]
2026-03-19 05:42:26.467962 | controller | ok
2026-03-19 05:42:26.487652 | 
2026-03-19 05:42:26.487712 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-19 05:42:26.501024 | controller | skipping: Conditional result was False
2026-03-19 05:42:26.506305 | 
2026-03-19 05:42:26.506365 | TASK [fetch-output : Set log path for single node]
2026-03-19 05:42:26.534026 | controller | ok
2026-03-19 05:42:26.538437 | 
2026-03-19 05:42:26.538496 | LOOP [fetch-output : Ensure local output dirs]
2026-03-19 05:42:26.718740 | controller -> localhost | ok: "/var/lib/zuul/builds/b3050f50a1664d0cbe168fc853832b15/work/logs"
2026-03-19 05:42:26.719179 | controller -> localhost | changed: All items complete
2026-03-19 05:42:26.719209 | 
2026-03-19 05:42:26.876666 | controller -> localhost | changed: "/var/lib/zuul/builds/b3050f50a1664d0cbe168fc853832b15/work/artifacts"
2026-03-19 05:42:27.039099 | controller -> localhost | changed: "/var/lib/zuul/builds/b3050f50a1664d0cbe168fc853832b15/work/docs"
2026-03-19 05:42:27.056207 | 
2026-03-19 05:42:27.056287 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-19 05:42:27.568398 | controller | changed:
2026-03-19 05:42:27.568557 | controller | .d..t...... ./
2026-03-19 05:42:27.568590 | controller | >f+++++++++ README.html
2026-03-19 05:42:27.568615 | controller | >f+++++++++ ansible-execution.log
2026-03-19 05:42:27.568637 | controller | >f+++++++++ ansible.log
2026-03-19 05:42:27.568658 | controller | >f+++++++++ dmesg.log
2026-03-19 05:42:27.568680 | controller | >f+++++++++ installed-pkgs.log
2026-03-19 05:42:27.568705 | controller | >f+++++++++ python.log
2026-03-19 05:42:27.568725 | controller | >f+++++++++ registries.conf
2026-03-19 05:42:27.568745 | controller | >f+++++++++ report.html
2026-03-19 05:42:27.568767 | controller | >f+++++++++ selinux-denials.log
2026-03-19 05:42:27.568786 | controller | >f+++++++++ selinux-listing.log
2026-03-19 05:42:27.568806 | controller | cd+++++++++ ci-framework-data/
2026-03-19 05:42:27.568824 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-03-19 05:42:27.568843 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-03-19 05:42:27.568862 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-03-19 05:42:27.568881 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-03-19 05:42:27.568899 | controller | cd+++++++++ ci-framework-data/logs/
2026-03-19 05:42:27.568917 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-03-19 05:42:27.568934 | controller | cd+++++++++ registries.conf.d/
2026-03-19 05:42:27.568951 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-03-19 05:42:27.568968 | controller | cd+++++++++ system-config/
2026-03-19 05:42:27.569037 | controller | cd+++++++++ system-config/libvirt/
2026-03-19 05:42:27.569076 | controller | >f+++++++++ system-config/libvirt/auth.conf
2026-03-19 05:42:27.569097 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-03-19 05:42:27.569113 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-03-19 05:42:27.569129 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2026-03-19 05:42:27.569143 | controller | >f+++++++++ system-config/libvirt/network.conf
2026-03-19 05:42:27.569157 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2026-03-19 05:42:27.569171 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2026-03-19 05:42:27.569184 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2026-03-19 05:42:27.569198 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2026-03-19 05:42:27.569212 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2026-03-19 05:42:27.569226 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2026-03-19 05:42:27.569239 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2026-03-19 05:42:27.569254 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2026-03-19 05:42:27.569284 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2026-03-19 05:42:27.569302 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2026-03-19 05:42:27.569317 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2026-03-19 05:42:27.569330 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2026-03-19 05:42:27.922596 | controller | changed: .d..t...... ./
2026-03-19 05:42:28.275884 | controller | changed: .d..t...... ./
2026-03-19 05:42:28.287030 | 
2026-03-19 05:42:28.287134 | TASK [Return artifact to Zuul]
2026-03-19 05:42:28.315626 | controller | ok
2026-03-19 05:42:28.333675 | 
2026-03-19 05:42:28.333727 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-03-19 05:42:28.333824 | 
2026-03-19 05:42:28.333852 | PLAY RECAP
2026-03-19 05:42:28.333890 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-03-19 05:42:28.333912 | 
2026-03-19 05:42:28.403806 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-19 05:42:28.404519 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-19 05:42:28.865682 | 
2026-03-19 05:42:28.865772 | PLAY [all]
2026-03-19 05:42:28.882382 | 
2026-03-19 05:42:28.882448 | TASK [include_role : fetch-output]
2026-03-19 05:42:28.910652 | controller | ok
2026-03-19 05:42:28.925835 | 
2026-03-19 05:42:28.925907 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-19 05:42:28.960083 | controller | skipping: Conditional result was False
2026-03-19 05:42:28.965326 | 
2026-03-19 05:42:28.965390 | TASK [fetch-output : Set log path for single node]
2026-03-19 05:42:28.992761 | controller | ok
2026-03-19 05:42:28.997177 | 
2026-03-19 05:42:28.997240 | LOOP [fetch-output : Ensure local output dirs]
2026-03-19 05:42:29.288485 | controller -> localhost | ok: "/var/lib/zuul/builds/b3050f50a1664d0cbe168fc853832b15/work/logs"
2026-03-19 05:42:29.478443 | controller -> localhost | ok: "/var/lib/zuul/builds/b3050f50a1664d0cbe168fc853832b15/work/artifacts"
2026-03-19 05:42:29.642231 | controller -> localhost | ok: "/var/lib/zuul/builds/b3050f50a1664d0cbe168fc853832b15/work/docs"
2026-03-19 05:42:29.656679 | 
2026-03-19 05:42:29.656740 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-19 05:42:30.150057 | controller | ok
2026-03-19 05:42:30.150247 | controller | ok: All items complete
2026-03-19 05:42:30.150297 | 
2026-03-19 05:42:30.519617 | controller | ok
2026-03-19 05:42:30.879455 | controller | ok
2026-03-19 05:42:30.895310 | 
2026-03-19 05:42:30.895447 | TASK [include_role : fetch-output-openshift]
2026-03-19 05:42:30.908690 | controller | skipping: Conditional result was False
2026-03-19 05:42:30.914160 | 
2026-03-19 05:42:30.914228 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-19 05:42:31.234630 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006043
2026-03-19 05:42:31.416873 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006145
2026-03-19 05:42:31.447508 | 
2026-03-19 05:42:31.447600 | PLAY [all]
2026-03-19 05:42:31.461171 | 
2026-03-19 05:42:31.461242 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-19 05:42:31.844817 | controller | changed
2026-03-19 05:42:31.863802 | 
2026-03-19 05:42:31.863850 | PLAY RECAP
2026-03-19 05:42:31.863890 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-03-19 05:42:31.863909 | 
2026-03-19 05:42:31.924022 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-19 05:42:31.924677 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-03-19 05:42:32.413252 | 
2026-03-19 05:42:32.413397 | PLAY [localhost]
2026-03-19 05:42:32.428942 | 
2026-03-19 05:42:32.429010 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-19 05:42:32.698504 | localhost | changed
2026-03-19 05:42:32.702472 | 
2026-03-19 05:42:32.702546 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-19 05:42:32.719222 | localhost | ok
2026-03-19 05:42:32.726216 | 
2026-03-19 05:42:32.726292 | TASK [add-fileserver : Create SSH private key tempfile]
2026-03-19 05:42:32.996008 | localhost | changed
2026-03-19 05:42:33.000907 | 
2026-03-19 05:42:33.000973 | TASK [add-fileserver : Create SSH private key from secret]
2026-03-19 05:42:33.493331 | localhost | changed
2026-03-19 05:42:33.497805 | 
2026-03-19 05:42:33.497868 | TASK [add-fileserver : Add fileserver ssh key]
2026-03-19 05:42:33.788656 | localhost | Identity added: /var/lib/zuul/builds/b3050f50a1664d0cbe168fc853832b15/work/tmp/ansible.9mhand8i (/var/lib/zuul/builds/b3050f50a1664d0cbe168fc853832b15/work/tmp/ansible.9mhand8i)
2026-03-19 05:42:33.788831 | localhost | ok: Runtime: 0:00:00.005425
2026-03-19 05:42:33.792792 | 
2026-03-19 05:42:33.792856 | TASK [add-fileserver : Remove SSH private key from disk]
2026-03-19 05:42:33.980747 | localhost | ok: Runtime: 0:00:00.003377
2026-03-19 05:42:33.984880 | 
2026-03-19 05:42:33.984942 | TASK [add-fileserver : Add fileserver to inventory]
2026-03-19 05:42:34.031788 | localhost | changed
2026-03-19 05:42:34.036318 | 
2026-03-19 05:42:34.036383 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-03-19 05:42:34.317026 | localhost | changed
2026-03-19 05:42:34.333857 | 
2026-03-19 05:42:34.333914 | PLAY [localhost]
2026-03-19 05:42:34.344673 | 
2026-03-19 05:42:34.344735 | TASK [Generate bulk log download script]
2026-03-19 05:42:34.361427 | localhost | ok
2026-03-19 05:42:34.373213 | 
2026-03-19 05:42:34.373294 | TASK [local-log-download : Check API endpoint is defined]
2026-03-19 05:42:34.399529 | localhost | ok: All assertions passed
2026-03-19 05:42:34.403437 | 
2026-03-19 05:42:34.403494 | TASK [local-log-download : Create download script]
2026-03-19 05:42:34.709462 | localhost -> localhost | changed
2026-03-19 05:42:34.716933 | 
2026-03-19 05:42:34.716990 | TASK [Register quick-download link]
2026-03-19 05:42:34.732888 | localhost | ok
2026-03-19 05:42:34.763178 | 
2026-03-19 05:42:34.763242 | PLAY [logserver.rdoproject.org]
2026-03-19 05:42:34.771918 | 
2026-03-19 05:42:34.771972 | TASK [Set zuul-log-path fact]
2026-03-19 05:42:34.786982 | logserver.rdoproject.org | ok
2026-03-19 05:42:34.794740 | 
2026-03-19 05:42:34.794799 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-19 05:42:34.810301 | logserver.rdoproject.org | ok
2026-03-19 05:42:34.814995 | 
2026-03-19 05:42:34.815055 | TASK [upload-logs : Create log directories]
2026-03-19 05:42:36.597495 | logserver.rdoproject.org | changed
2026-03-19 05:42:36.600350 | 
2026-03-19 05:42:36.600409 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-19 05:42:36.796103 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.003810
2026-03-19 05:42:36.800923 | 
2026-03-19 05:42:36.800986 | TASK [upload-logs : Upload logs to log server]
2026-03-19 05:42:38.276891 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-03-19 05:42:38.279496 | 
2026-03-19 05:42:38.279557 | LOOP [upload-logs : Compress console log and json output]
2026-03-19 05:42:38.314239 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-19 05:42:38.321507 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-19 05:42:38.326532 | 
2026-03-19 05:42:38.326611 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-19 05:42:38.358373 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-19 05:42:38.358566 | 
2026-03-19 05:42:38.361511 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-19 05:42:38.372966 | 
2026-03-19 05:42:38.373044 | LOOP [upload-logs : Upload console log and json output]