2025-12-01 11:38:37.079516 | Job console starting...
2025-12-01 11:38:37.290414 | Updating repositories
2025-12-01 11:38:38.019498 | Preparing job workspace
2025-12-01 11:38:45.916550 | Running Ansible setup...
2025-12-01 11:38:48.768217 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-01 11:38:49.311626 | 
2025-12-01 11:38:49.311862 | PLAY [localhost]
2025-12-01 11:38:49.322133 | 
2025-12-01 11:38:49.322218 | TASK [Gathering Facts]
2025-12-01 11:38:50.113471 | localhost | ok
2025-12-01 11:38:50.129628 | 
2025-12-01 11:38:50.129797 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-01 11:38:50.437335 | localhost -> localhost | changed
2025-12-01 11:38:50.442803 | 
2025-12-01 11:38:50.442888 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-01 11:38:51.154829 | localhost -> localhost | changed
2025-12-01 11:38:51.163738 | 
2025-12-01 11:38:51.163825 | TASK [Setup log path fact]
2025-12-01 11:38:51.182610 | localhost | ok
2025-12-01 11:38:51.193937 | 
2025-12-01 11:38:51.194015 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-01 11:38:51.221967 | localhost | ok
2025-12-01 11:38:51.229165 | 
2025-12-01 11:38:51.229239 | TASK [emit-job-header : Print job information]
2025-12-01 11:38:51.266464 | # Job Information
2025-12-01 11:38:51.266584 | Ansible Version: 2.15.12
2025-12-01 11:38:51.266611 | Job: cifmw-molecule-dnsmasq
2025-12-01 11:38:51.266629 | Pipeline: github-check
2025-12-01 11:38:51.266646 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-12-01 11:38:51.266661 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3505
2025-12-01 11:38:51.266676 | Log URL (when completed): https://logserver.rdoproject.org/318/rdoproject.org/3184df9b0acf4e5daaea05dd22299350/
2025-12-01 11:38:51.266692 | Event ID: 2081e030-ceaa-11f0-87de-439844a058a6
2025-12-01 11:38:51.269936 | 
2025-12-01 11:38:51.269998 | LOOP [emit-job-header : Print node information]
2025-12-01 11:38:51.352114 | localhost | ok:
2025-12-01 11:38:51.352326 | localhost | # Node Information
2025-12-01 11:38:51.352354 | localhost | Inventory Hostname: controller
2025-12-01 11:38:51.352376 | localhost | Hostname: np0005541035
2025-12-01 11:38:51.352394 | localhost | Username: zuul
2025-12-01 11:38:51.352413 | localhost | Distro: CentOS 9
2025-12-01 11:38:51.352430 | localhost | Provider: ibm-bm4-nodepool
2025-12-01 11:38:51.352446 | localhost | Region: regionOne
2025-12-01 11:38:51.352461 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-12-01 11:38:51.352478 | localhost | Product Name: OpenStack Compute
2025-12-01 11:38:51.352508 | localhost | Interface IP: 192.168.26.95
2025-12-01 11:38:51.371281 | 
2025-12-01 11:38:51.371402 | PLAY [all]
2025-12-01 11:38:51.377241 | 
2025-12-01 11:38:51.377307 | TASK [Gather network facts]
2025-12-01 11:38:51.716164 | controller | ok
2025-12-01 11:38:51.739924 | 
2025-12-01 11:38:51.740038 | TASK [include_role : start-zuul-console]
2025-12-01 11:38:51.761174 | controller | ok
2025-12-01 11:38:51.780621 | 
2025-12-01 11:38:51.780712 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-01 11:38:52.125202 | controller | ok
2025-12-01 11:38:52.132844 | 
2025-12-01 11:38:52.132902 | TASK [include_role : add-build-sshkey]
2025-12-01 11:38:52.162481 | controller | ok
2025-12-01 11:38:52.174296 | 
2025-12-01 11:38:52.174361 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-01 11:38:52.391627 | controller -> localhost | ok
2025-12-01 11:38:52.397115 | 
2025-12-01 11:38:52.397187 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-01 11:38:52.426006 | controller | ok
2025-12-01 11:38:52.439204 | controller | included: /var/lib/zuul/builds/3184df9b0acf4e5daaea05dd22299350/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-01 11:38:52.444323 | 
2025-12-01 11:38:52.444388 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-01 11:38:52.965950 | controller -> localhost | Generating public/private rsa key pair.
2025-12-01 11:38:52.966173 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3184df9b0acf4e5daaea05dd22299350/work/3184df9b0acf4e5daaea05dd22299350_id_rsa.
2025-12-01 11:38:52.966206 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3184df9b0acf4e5daaea05dd22299350/work/3184df9b0acf4e5daaea05dd22299350_id_rsa.pub.
2025-12-01 11:38:52.966228 | controller -> localhost | The key fingerprint is:
2025-12-01 11:38:52.966247 | controller -> localhost | SHA256:RQKDXZaM0Uaj/8PE5wosps89+eY14vRz8Y15h7qyKgQ zuul-build-sshkey
2025-12-01 11:38:52.966268 | controller -> localhost | The key's randomart image is:
2025-12-01 11:38:52.966286 | controller -> localhost | +---[RSA 3072]----+
2025-12-01 11:38:52.966304 | controller -> localhost | |     o+O*..      |
2025-12-01 11:38:52.966322 | controller -> localhost | |    . o+=+       |
2025-12-01 11:38:52.966339 | controller -> localhost | |      ..  .      |
2025-12-01 11:38:52.966355 | controller -> localhost | |    E  . o       |
2025-12-01 11:38:52.966371 | controller -> localhost | |     .  S o .    |
2025-12-01 11:38:52.966387 | controller -> localhost | |      .. + o  .  |
2025-12-01 11:38:52.966404 | controller -> localhost | |     .o o.* +  *.|
2025-12-01 11:38:52.966423 | controller -> localhost | |     +.oo+o*..= =|
2025-12-01 11:38:52.966439 | controller -> localhost | |    ..o.o**o+= ..|
2025-12-01 11:38:52.966455 | controller -> localhost | +----[SHA256]-----+
2025-12-01 11:38:52.966503 | controller -> localhost | ok: Runtime: 0:00:00.183748
2025-12-01 11:38:52.972050 | 
2025-12-01 11:38:52.972126 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-01 11:38:52.990687 | controller | ok
2025-12-01 11:38:52.999442 | controller | included: /var/lib/zuul/builds/3184df9b0acf4e5daaea05dd22299350/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-01 11:38:53.006458 | 
2025-12-01 11:38:53.006515 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-01 11:38:53.019894 | controller | skipping: Conditional result was False
2025-12-01 11:38:53.025137 | 
2025-12-01 11:38:53.025198 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-01 11:38:53.366728 | controller | changed
2025-12-01 11:38:53.372366 | 
2025-12-01 11:38:53.372425 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-01 11:38:53.563299 | controller | ok
2025-12-01 11:38:53.567821 | 
2025-12-01 11:38:53.567883 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-01 11:38:54.115181 | controller | changed
2025-12-01 11:38:54.119797 | 
2025-12-01 11:38:54.119863 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-01 11:38:54.646432 | controller | changed
2025-12-01 11:38:54.651232 | 
2025-12-01 11:38:54.651298 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-01 11:38:54.666023 | controller | skipping: Conditional result was False
2025-12-01 11:38:54.671426 | 
2025-12-01 11:38:54.671486 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-01 11:38:55.001165 | controller -> localhost | changed
2025-12-01 11:38:55.010647 | 
2025-12-01 11:38:55.010712 | TASK [add-build-sshkey : Add back temp key]
2025-12-01 11:38:55.247220 | controller -> localhost | Identity added: /var/lib/zuul/builds/3184df9b0acf4e5daaea05dd22299350/work/3184df9b0acf4e5daaea05dd22299350_id_rsa (zuul-build-sshkey)
2025-12-01 11:38:55.247406 | controller -> localhost | ok: Runtime: 0:00:00.027152
2025-12-01 11:38:55.252716 | 
2025-12-01 11:38:55.252778 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-01 11:38:55.537860 | controller | ok
2025-12-01 11:38:55.542763 | 
2025-12-01 11:38:55.542836 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-01 11:38:55.567434 | controller | skipping: Conditional result was False
2025-12-01 11:38:55.576694 | 
2025-12-01 11:38:55.576753 | TASK [include_role : validate-host]
2025-12-01 11:38:55.594733 | controller | ok
2025-12-01 11:38:55.613574 | 
2025-12-01 11:38:55.613637 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-01 11:38:55.641748 | controller | ok
2025-12-01 11:38:55.646444 | 
2025-12-01 11:38:55.646508 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-01 11:38:55.862790 | controller -> localhost | ok
2025-12-01 11:38:55.870288 | 
2025-12-01 11:38:55.870375 | TASK [validate-host : Collect information about the host]
2025-12-01 11:38:56.446510 | controller | ok
2025-12-01 11:38:56.454012 | 
2025-12-01 11:38:56.454085 | TASK [validate-host : Sanitize hostname]
2025-12-01 11:38:56.522824 | controller | ok
2025-12-01 11:38:56.527218 | 
2025-12-01 11:38:56.527279 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-01 11:38:56.967861 | controller -> localhost | changed
2025-12-01 11:38:57.042225 | 
2025-12-01 11:38:57.042387 | TASK [validate-host : Collect information about zuul worker]
2025-12-01 11:38:57.345029 | controller | ok
2025-12-01 11:38:57.349730 | 
2025-12-01 11:38:57.349804 | TASK [validate-host : Write out all zuul information for each host]
2025-12-01 11:38:57.730137 | controller -> localhost | changed
2025-12-01 11:38:57.739425 | 
2025-12-01 11:38:57.739486 | TASK [include_role : prepare-workspace-openshift]
2025-12-01 11:38:57.764129 | controller | skipping: Conditional result was False
2025-12-01 11:38:57.769538 | 
2025-12-01 11:38:57.769602 | TASK [include_role : remove-zuul-sshkey]
2025-12-01 11:38:57.784314 | controller | skipping: Conditional result was False
2025-12-01 11:38:57.790209 | 
2025-12-01 11:38:57.790271 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-01 11:38:57.982692 | controller | ok: "logs"
2025-12-01 11:38:57.982951 | controller | ok: All items complete
2025-12-01 11:38:57.982985 | 
2025-12-01 11:38:58.139555 | controller | ok: "artifacts"
2025-12-01 11:38:58.299176 | controller | ok: "docs"
2025-12-01 11:38:58.315568 | 
2025-12-01 11:38:58.315689 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-01 11:38:58.509416 | controller | changed: "logs"
2025-12-01 11:38:58.667604 | controller | changed: "artifacts"
2025-12-01 11:38:58.829235 | controller | changed: "docs"
2025-12-01 11:38:58.850024 | 
2025-12-01 11:38:58.850144 | PLAY RECAP
2025-12-01 11:38:58.850192 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-01 11:38:58.850220 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-01 11:38:58.850239 | 
2025-12-01 11:38:58.936166 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-01 11:38:58.936906 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-01 11:38:59.457439 | 
2025-12-01 11:38:59.457550 | PLAY [all]
2025-12-01 11:38:59.475604 | 
2025-12-01 11:38:59.475675 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-01 11:38:59.535792 | controller | ok
2025-12-01 11:38:59.540744 | 
2025-12-01 11:38:59.540842 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-01 11:38:59.880071 | controller | changed
2025-12-01 11:38:59.890575 | 
2025-12-01 11:38:59.890647 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-01 11:39:00.760107 | controller | changed
2025-12-01 11:39:00.768996 | 
2025-12-01 11:39:00.769063 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-01 11:39:01.191241 | controller | changed:
2025-12-01 11:39:01.191605 | controller | {
2025-12-01 11:39:01.191635 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-01 11:39:01.191672 | controller | }
2025-12-01 11:39:01.382697 | controller | changed:
2025-12-01 11:39:01.382759 | controller | {
2025-12-01 11:39:01.382787 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-01 11:39:01.382810 | controller | }
2025-12-01 11:39:01.576565 | controller | changed:
2025-12-01 11:39:01.576623 | controller | {
2025-12-01 11:39:01.576648 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-12-01 11:39:01.576670 | controller | }
2025-12-01 11:39:01.772166 | controller | changed:
2025-12-01 11:39:01.772251 | controller | {
2025-12-01 11:39:01.772280 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-01 11:39:01.772303 | controller | }
2025-12-01 11:39:01.967692 | controller | changed:
2025-12-01 11:39:01.967902 | controller | {
2025-12-01 11:39:01.967965 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-01 11:39:01.967990 | controller | }
2025-12-01 11:39:02.162164 | controller | changed:
2025-12-01 11:39:02.162292 | controller | {
2025-12-01 11:39:02.162321 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-01 11:39:02.162342 | controller | }
2025-12-01 11:39:02.357382 | controller | changed:
2025-12-01 11:39:02.357577 | controller | {
2025-12-01 11:39:02.357608 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-12-01 11:39:02.357630 | controller | }
2025-12-01 11:39:02.552992 | controller | changed:
2025-12-01 11:39:02.553083 | controller | {
2025-12-01 11:39:02.553114 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-12-01 11:39:02.553136 | controller | }
2025-12-01 11:39:02.743345 | controller | changed:
2025-12-01 11:39:02.743489 | controller | {
2025-12-01 11:39:02.743525 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-01 11:39:02.743546 | controller | }
2025-12-01 11:39:02.940168 | controller | changed:
2025-12-01 11:39:02.940219 | controller | {
2025-12-01 11:39:02.940243 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-01 11:39:02.940263 | controller | }
2025-12-01 11:39:03.138218 | controller | changed:
2025-12-01 11:39:03.138299 | controller | {
2025-12-01 11:39:03.138325 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-01 11:39:03.138344 | controller | }
2025-12-01 11:39:03.348335 | controller | changed:
2025-12-01 11:39:03.348412 | controller | {
2025-12-01 11:39:03.348438 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-01 11:39:03.348458 | controller | }
2025-12-01 11:39:03.521006 | controller | changed:
2025-12-01 11:39:03.521058 | controller | {
2025-12-01 11:39:03.521091 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-01 11:39:03.521112 | controller | }
2025-12-01 11:39:03.715444 | controller | changed:
2025-12-01 11:39:03.715494 | controller | {
2025-12-01 11:39:03.715521 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-01 11:39:03.715541 | controller | }
2025-12-01 11:39:03.915484 | controller | changed:
2025-12-01 11:39:03.915532 | controller | {
2025-12-01 11:39:03.915556 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-01 11:39:03.915573 | controller | }
2025-12-01 11:39:04.112689 | controller | changed:
2025-12-01 11:39:04.112737 | controller | {
2025-12-01 11:39:04.112760 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-01 11:39:04.112778 | controller | }
2025-12-01 11:39:04.313420 | controller | changed:
2025-12-01 11:39:04.313471 | controller | {
2025-12-01 11:39:04.313494 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-12-01 11:39:04.313514 | controller | }
2025-12-01 11:39:04.507059 | controller | changed:
2025-12-01 11:39:04.507115 | controller | {
2025-12-01 11:39:04.507139 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-12-01 11:39:04.507159 | controller | }
2025-12-01 11:39:04.704668 | controller | changed:
2025-12-01 11:39:04.704715 | controller | {
2025-12-01 11:39:04.704738 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-01 11:39:04.704755 | controller | }
2025-12-01 11:39:04.904482 | controller | changed:
2025-12-01 11:39:04.904526 | controller | {
2025-12-01 11:39:04.904550 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-01 11:39:04.904568 | controller | }
2025-12-01 11:39:05.099451 | controller | changed:
2025-12-01 11:39:05.099499 | controller | {
2025-12-01 11:39:05.099523 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-01 11:39:05.099541 | controller | }
2025-12-01 11:39:05.299185 | controller | changed:
2025-12-01 11:39:05.299288 | controller | {
2025-12-01 11:39:05.299320 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-01 11:39:05.299340 | controller | }
2025-12-01 11:39:05.507377 | controller | changed:
2025-12-01 11:39:05.507463 | controller | {
2025-12-01 11:39:05.507488 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-12-01 11:39:05.507508 | controller | }
2025-12-01 11:39:05.705944 | controller | changed:
2025-12-01 11:39:05.705990 | controller | {
2025-12-01 11:39:05.706014 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-01 11:39:05.706032 | controller | }
2025-12-01 11:39:05.907083 | controller | changed:
2025-12-01 11:39:05.907131 | controller | {
2025-12-01 11:39:05.907156 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-01 11:39:05.907175 | controller | }
2025-12-01 11:39:06.107324 | controller | changed:
2025-12-01 11:39:06.107369 | controller | {
2025-12-01 11:39:06.107393 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-01 11:39:06.107412 | controller | }
2025-12-01 11:39:06.129584 | 
2025-12-01 11:39:06.129644 | TASK [Set timezone to UTC]
2025-12-01 11:39:06.494458 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-01 11:39:06.498640 | 
2025-12-01 11:39:06.498701 | TASK [Create nodepool directory]
2025-12-01 11:39:06.691772 | controller | changed
2025-12-01 11:39:06.696715 | 
2025-12-01 11:39:06.696775 | TASK [Create nodepool sub_nodes file]
2025-12-01 11:39:07.171485 | controller | changed
2025-12-01 11:39:07.175963 | 
2025-12-01 11:39:07.176025 | TASK [Create nodepool sub_nodes_private file]
2025-12-01 11:39:07.659930 | controller | changed
2025-12-01 11:39:07.665297 | 
2025-12-01 11:39:07.665358 | LOOP [Populate nodepool sub_nodes file]
2025-12-01 11:39:07.693355 | 
2025-12-01 11:39:07.693473 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-01 11:39:07.721565 | 
2025-12-01 11:39:07.721681 | TASK [Create nodepool primary file]
2025-12-01 11:39:07.744790 | controller | skipping: Conditional result was False
2025-12-01 11:39:07.749596 | 
2025-12-01 11:39:07.749663 | TASK [Create nodepool node_private for this node]
2025-12-01 11:39:08.254186 | controller | changed
2025-12-01 11:39:08.259063 | 
2025-12-01 11:39:08.259133 | LOOP [Copy ssh keys to nodepool directory]
2025-12-01 11:39:08.565154 | controller | ok: Item: id_rsa Runtime: 0:00:00.004245
2025-12-01 11:39:08.565317 | 
2025-12-01 11:39:08.715373 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004355
2025-12-01 11:39:08.724062 | 
2025-12-01 11:39:08.724153 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-01 11:39:09.237990 | controller | changed
2025-12-01 11:39:09.242370 | 
2025-12-01 11:39:09.242430 | TASK [Validate sudoers config after edits]
2025-12-01 11:39:09.448695 | controller | /etc/sudoers: parsed OK
2025-12-01 11:39:09.448739 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-01 11:39:09.448748 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-01 11:39:09.448754 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-01 11:39:09.767168 | controller | ok: Runtime: 0:00:00.005581
2025-12-01 11:39:09.771880 | 
2025-12-01 11:39:09.771968 | TASK [Show the environment passed in to job shell scripts]
2025-12-01 11:39:09.964414 | controller | SHELL=/bin/bash
2025-12-01 11:39:09.964450 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-01 11:39:09.964460 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-01 11:39:09.964466 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/23/3523/9dca7030e1689ab350d0cc525f1944d26503b3cc^openstack-k8s-operators/ci-framework:main:refs/changes/05/3505/15653cd6e36d23c3e933a923c0993de79217b550
2025-12-01 11:39:09.964474 | controller | PWD=/home/zuul
2025-12-01 11:39:09.964479 | controller | ZUUL_PIPELINE=github-check
2025-12-01 11:39:09.964484 | controller | LOGNAME=zuul
2025-12-01 11:39:09.964489 | controller | XDG_SESSION_TYPE=tty
2025-12-01 11:39:09.964495 | controller | _=/usr/bin/env
2025-12-01 11:39:09.964500 | controller | MOTD_SHOWN=pam
2025-12-01 11:39:09.964549 | controller | HOME=/home/zuul
2025-12-01 11:39:09.964557 | controller | LANG=en_US.UTF-8
2025-12-01 11:39:09.964563 | controller | SSH_CONNECTION=192.168.26.12 59440 192.168.26.95 22
2025-12-01 11:39:09.964568 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-12-01 11:39:09.964573 | controller | ZUUL_CHANGE_IDS=3523,9dca7030e1689ab350d0cc525f1944d26503b3cc 3505,15653cd6e36d23c3e933a923c0993de79217b550
2025-12-01 11:39:09.964579 | controller | WORKSPACE=/home/zuul/workspace
2025-12-01 11:39:09.964584 | controller | XDG_SESSION_CLASS=user
2025-12-01 11:39:09.964589 | controller | SELINUX_ROLE_REQUESTED=
2025-12-01 11:39:09.964594 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-01 11:39:09.964600 | controller | USER=zuul
2025-12-01 11:39:09.964605 | controller | ZUUL_VOTING=True
2025-12-01 11:39:09.964610 | controller | BUILD_TIMEOUT=1800000
2025-12-01 11:39:09.964615 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-01 11:39:09.964621 | controller | SHLVL=1
2025-12-01 11:39:09.964626 | controller | ZUUL_PATCHSET=15653cd6e36d23c3e933a923c0993de79217b550
2025-12-01 11:39:09.964631 | controller | XDG_SESSION_ID=1
2025-12-01 11:39:09.964636 | controller | ZUUL_BRANCH=main
2025-12-01 11:39:09.964642 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-01 11:39:09.964647 | controller | SSH_CLIENT=192.168.26.12 59440 22
2025-12-01 11:39:09.964652 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-01 11:39:09.964657 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-01 11:39:09.964671 | controller | which_declare=declare -f
2025-12-01 11:39:09.964677 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-01 11:39:09.964682 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-01 11:39:09.964688 | controller | ZUUL_CHANGE=3505
2025-12-01 11:39:09.964693 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-01 11:39:09.964698 | controller | ZUUL_UUID=3184df9b0acf4e5daaea05dd22299350
2025-12-01 11:39:09.964703 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-01 11:39:09.964708 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-01 11:39:09.964714 | controller | }
2025-12-01 11:39:10.295835 | controller | ok: Runtime: 0:00:00.005089
2025-12-01 11:39:10.300463 | 
2025-12-01 11:39:10.300526 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-01 11:39:10.313820 | controller | skipping: Conditional result was False
2025-12-01 11:39:10.318497 | 
2025-12-01 11:39:10.318556 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-01 11:39:10.842135 | controller | skipping: Conditional result was False
2025-12-01 11:39:10.848031 | 
2025-12-01 11:39:10.848130 | TASK [Ensure legacy workspace directory]
2025-12-01 11:39:11.031805 | controller | changed
2025-12-01 11:39:11.051606 | 
2025-12-01 11:39:11.051686 | PLAY RECAP
2025-12-01 11:39:11.051747 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-01 11:39:11.051779 | 
2025-12-01 11:39:11.188435 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-01 11:39:11.189196 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-01 11:39:11.699444 | 
2025-12-01 11:39:11.699561 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-01 11:39:11.719274 | 
2025-12-01 11:39:11.719348 | TASK [Create zuul-output directory]
2025-12-01 11:39:12.022062 | controller | changed
2025-12-01 11:39:12.026938 | 
2025-12-01 11:39:12.027029 | TASK [Slurp Zuul inventory test]
2025-12-01 11:39:12.293957 | controller -> localhost | ok
2025-12-01 11:39:12.299860 | 
2025-12-01 11:39:12.299946 | TASK [Save zuul inventory]
2025-12-01 11:39:13.015857 | controller | changed
2025-12-01 11:39:13.020990 | 
2025-12-01 11:39:13.021083 | TASK [Save zuul vars without the change_message]
2025-12-01 11:39:13.589435 | controller | changed
2025-12-01 11:39:13.607138 | 
2025-12-01 11:39:13.607193 | PLAY RECAP
2025-12-01 11:39:13.607240 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-01 11:39:13.607261 | 
2025-12-01 11:39:13.733081 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-01 11:39:13.734242 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-01 11:39:14.284350 | 
2025-12-01 11:39:14.284472 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-01 11:39:14.304644 | 
2025-12-01 11:39:14.304715 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-01 11:39:14.333737 | controller | ok
2025-12-01 11:39:14.350293 | 
2025-12-01 11:39:14.350375 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-01 11:39:14.374382 | controller | skipping: Conditional result was False
2025-12-01 11:39:14.379840 | 
2025-12-01 11:39:14.379927 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-01 11:39:14.692466 | controller | ok
2025-12-01 11:39:14.697449 | 
2025-12-01 11:39:14.697513 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-01 11:39:15.314592 | controller | ok
2025-12-01 11:39:15.323965 | 
2025-12-01 11:39:15.324030 | TASK [Prepare workspace]
2025-12-01 11:39:15.341428 | controller | ok
2025-12-01 11:39:15.356836 | 
2025-12-01 11:39:15.356901 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-01 11:39:15.647681 | controller | ok
2025-12-01 11:39:15.652839 | 
2025-12-01 11:39:15.652899 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-01 11:39:18.001528 | controller | Output suppressed because no_log was given
2025-12-01 11:39:18.010184 | 
2025-12-01 11:39:18.010249 | LOOP [Create zuul-output directory]
2025-12-01 11:39:18.193196 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-01 11:39:18.349320 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-01 11:39:18.362470 | 
2025-12-01 11:39:18.362525 | TASK [Install required packages]
2025-12-01 11:40:18.819260 | controller | changed
2025-12-01 11:40:18.823865 | 
2025-12-01 11:40:18.823949 | TASK [Install venv]
2025-12-01 11:41:20.032555 | controller | changed
2025-12-01 11:41:20.071826 | 
2025-12-01 11:41:20.071898 | PLAY RECAP
2025-12-01 11:41:20.071980 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-01 11:41:20.072005 | 
2025-12-01 11:41:20.128142 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-01 11:41:20.128895 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-01 11:41:20.595487 | 
2025-12-01 11:41:20.595582 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-12-01 11:41:20.614123 | 
2025-12-01 11:41:20.614193 | TASK [Gather required facts]
2025-12-01 11:41:21.068895 | controller | ok
2025-12-01 11:41:21.073821 | 
2025-12-01 11:41:21.073890 | TASK [Load environment var if instructed to]
2025-12-01 11:41:21.098265 | controller | skipping: Conditional result was False
2025-12-01 11:41:21.103124 | 
2025-12-01 11:41:21.103189 | TASK [Ensure group_vars dir exists]
2025-12-01 11:41:21.395809 | controller | ok
2025-12-01 11:41:21.401170 | 
2025-12-01 11:41:21.401231 | TASK [Print related variables]
2025-12-01 11:41:21.429621 | controller | ok:
2025-12-01 11:41:21.429730 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-12-01 11:41:21.429758 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq
2025-12-01 11:41:21.434056 | 
2025-12-01 11:41:21.434116 | TASK [Run molecule]
2025-12-01 11:41:22.338389 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-12-01 11:41:22.405082 | controller | INFO     Performing prerun with role_name_check=0...
2025-12-01 11:41:38.703563 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:41:38.703952 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:41:38.704389 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:41:38.704804 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:41:38.705251 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+57ff4392 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 1.0.0+57ff4392 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:41:38.705672 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:41:38.706075 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:41:38.706506 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:41:38.706902 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:41:38.707311 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:41:38.707722 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:41:38.708144 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:41:38.708563 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:41:38.708958 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:41:38.709390 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:41:38.718663 | controller | INFO     Running default > prepare
2025-12-01 11:41:39.337803 | controller |
2025-12-01 11:41:39.337870 | controller | PLAY [Prepare] *****************************************************************
2025-12-01 11:41:39.338012 | controller |
2025-12-01 11:41:39.338032 | controller | TASK [Gathering Facts] *********************************************************
2025-12-01 11:41:39.338144 | controller | Monday 01 December 2025  11:41:39 +0000 (0:00:00.011)       0:00:00.011 *******
2025-12-01 11:41:40.136147 | controller | ok: [instance]
2025-12-01 11:41:40.136282 | controller |
2025-12-01 11:41:40.136439 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-12-01 11:41:40.136611 | controller | Monday 01 December 2025  11:41:40 +0000 (0:00:00.799)       0:00:00.810 *******
2025-12-01 11:41:40.152530 | controller | skipping: [instance]
2025-12-01 11:41:40.152612 | controller |
2025-12-01 11:41:40.152722 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-12-01 11:41:40.152832 | controller | Monday 01 December 2025  11:41:40 +0000 (0:00:00.016)       0:00:00.827 *******
2025-12-01 11:41:40.190614 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-12-01 11:41:40.190714 | controller |
2025-12-01 11:41:40.190832 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-12-01 11:41:40.190944 | controller | Monday 01 December 2025  11:41:40 +0000 (0:00:00.038)       0:00:00.865 *******
2025-12-01 11:41:40.450924 | controller | ok: [instance]
2025-12-01 11:41:40.450969 | controller |
2025-12-01 11:41:40.451098 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-12-01 11:41:40.451225 | controller | Monday 01 December 2025  11:41:40 +0000 (0:00:00.260)       0:00:01.125 *******
2025-12-01 11:41:40.740459 | controller | ok: [instance]
2025-12-01 11:41:40.740542 | controller |
2025-12-01 11:41:40.740648 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-12-01 11:41:40.740752 | controller | Monday 01 December 2025  11:41:40 +0000 (0:00:00.289)       0:00:01.415 *******
2025-12-01 11:41:41.394160 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-12-01 11:41:41.394230 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-12-01 11:41:41.394334 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-12-01 11:41:41.394427 | controller |
2025-12-01 11:41:41.394533 | controller | TASK [Deploy repo-setup] *******************************************************
2025-12-01 11:41:41.394624 | controller | Monday 01 December 2025  11:41:41 +0000 (0:00:00.653)       0:00:02.068 *******
2025-12-01 11:41:41.451116 | controller |
2025-12-01 11:41:41.451266 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-12-01 11:41:41.451386 | controller | Monday 01 December 2025  11:41:41 +0000 (0:00:00.056)       0:00:02.125 *******
2025-12-01 11:41:41.972958 | controller | changed: [instance] => (item=tmp)
2025-12-01 11:41:41.973064 | controller | changed: [instance] => (item=artifacts/repositories)
2025-12-01 11:41:41.973205 | controller | changed: [instance] => (item=venv/repo_setup)
2025-12-01 11:41:41.973310 | controller |
2025-12-01 11:41:41.973419 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-12-01 11:41:41.973537 | controller | Monday 01 December 2025  11:41:41 +0000 (0:00:00.521)       0:00:02.647 *******
2025-12-01 11:41:42.693298 | controller | ok: [instance]
2025-12-01 11:41:42.693366 | controller |
2025-12-01 11:41:42.693469 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-12-01 11:41:42.693586 | controller | Monday 01 December 2025  11:41:42 +0000 (0:00:00.720)       0:00:03.367 *******
2025-12-01 11:41:43.704253 | controller | changed: [instance]
2025-12-01 11:41:43.704301 | controller |
2025-12-01 11:41:43.704576 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-12-01 11:41:51.366575 | controller | Monday 01 December 2025  11:41:43 +0000 (0:00:01.011)       0:00:04.378 *******
2025-12-01 11:41:51.366612 | controller | changed: [instance]
2025-12-01 11:41:52.062921 | controller |
2025-12-01 11:41:52.062953 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-12-01 11:41:52.062961 | controller | Monday 01 December 2025  11:41:51 +0000 (0:00:07.661)       0:00:12.040 *******
2025-12-01 11:41:52.062971 | controller | changed: [instance]
2025-12-01 11:41:52.084188 | controller |
2025-12-01 11:41:52.084223 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-12-01 11:41:52.084232 | controller | Monday 01 December 2025  11:41:52 +0000 (0:00:00.696)       0:00:12.737 *******
2025-12-01 11:41:52.084242 | controller | skipping: [instance]
2025-12-01 11:41:52.084262 | controller |
2025-12-01 11:41:52.084331 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-12-01 11:41:52.084446 | controller | Monday 01 December 2025  11:41:52 +0000 (0:00:00.021)       0:00:12.758 *******
2025-12-01 11:41:52.850121 | controller | changed: [instance]
2025-12-01 11:41:52.850168 | controller |
2025-12-01 11:41:52.850275 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-12-01 11:41:52.850422 | controller | Monday 01 December 2025  11:41:52 +0000 (0:00:00.765)       0:00:13.524 *******
2025-12-01 11:41:52.887183 | controller | skipping: [instance]
2025-12-01 11:41:52.887622 | controller |
2025-12-01 11:41:52.887658 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-12-01 11:41:52.922169 | controller | Monday 01 December 2025  11:41:52 +0000 (0:00:00.036)       0:00:13.561 *******
2025-12-01 11:41:52.922193 | controller | skipping: [instance]
2025-12-01 11:41:52.954740 | controller |
2025-12-01 11:41:52.954772 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-12-01 11:41:52.954785 | controller | Monday 01 December 2025  11:41:52 +0000 (0:00:00.034)       0:00:13.595 *******
2025-12-01 11:41:52.954801 | controller | skipping: [instance]
2025-12-01 11:41:53.481504 | controller |
2025-12-01 11:41:53.481530 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-12-01 11:41:53.481537 | controller | Monday 01 December 2025  11:41:52 +0000 (0:00:00.033)       0:00:13.628 *******
2025-12-01 11:41:53.481547 | controller | changed: [instance]
2025-12-01 11:41:53.919514 | controller |
2025-12-01 11:41:53.919539 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-12-01 11:41:53.919547 | controller | Monday 01 December 2025  11:41:53 +0000 (0:00:00.526)       0:00:14.155 *******
2025-12-01 11:41:53.919557 | controller | changed: [instance]
2025-12-01 11:41:53.948214 | controller |
2025-12-01 11:41:53.948253 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-12-01 11:41:53.948264 | controller | Monday 01 December 2025  11:41:53 +0000 (0:00:00.437)       0:00:14.593 *******
2025-12-01 11:41:53.948277 | controller | skipping: [instance]
2025-12-01 11:41:53.948482 | controller |
2025-12-01 11:41:53.948508 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-12-01 11:41:53.976192 | controller | Monday 01 December 2025  11:41:53 +0000 (0:00:00.029)       0:00:14.622 *******
2025-12-01 11:41:53.976220 | controller | skipping: [instance]
2025-12-01 11:41:53.976250 | controller |
2025-12-01 11:41:53.976260 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-12-01 11:41:53.976381 | controller | Monday 01 December 2025  11:41:53 +0000 (0:00:00.028)       0:00:14.650 *******
2025-12-01 11:41:54.004000 | controller | skipping: [instance]
2025-12-01 11:41:54.039763 | controller |
2025-12-01 11:41:54.039788 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-12-01 11:41:54.039798 | controller | Monday 01 December 2025  11:41:54 +0000 (0:00:00.027)       0:00:14.678 *******
2025-12-01 11:41:54.039808 | controller | ok: [instance]
2025-12-01 11:41:54.065641 | controller |
2025-12-01 11:41:54.065663 | controller | TASK [repo_setup : Create download directory] **********************************
2025-12-01 11:41:54.065673 | controller | Monday 01 December 2025  11:41:54 +0000 (0:00:00.035)       0:00:14.714 *******
2025-12-01 11:41:54.065683 | controller | skipping: [instance]
2025-12-01 11:41:54.091160 | controller |
2025-12-01 11:41:54.091178 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-12-01 11:41:54.091185 | controller | Monday 01 December 2025  11:41:54 +0000 (0:00:00.025)       0:00:14.739 *******
2025-12-01 11:41:54.091203 | controller | skipping: [instance]
2025-12-01 11:41:54.117987 | controller |
2025-12-01 11:41:54.118010 | controller | TASK [Download the RPM] ********************************************************
2025-12-01 11:41:54.118020 | controller | Monday 01 December 2025  11:41:54 +0000 (0:00:00.025)       0:00:14.765 *******
2025-12-01 11:41:54.118030 | controller | skipping: [instance]
2025-12-01 11:41:54.146745 | controller |
2025-12-01 11:41:54.146767 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-12-01 11:41:54.146776 | controller | Monday 01 December 2025  11:41:54 +0000 (0:00:00.026)       0:00:14.792 *******
2025-12-01 11:41:54.146786 | controller | skipping: [instance]
2025-12-01 11:41:54.146960 | controller |
2025-12-01 11:41:54.147165 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-12-01 11:41:54.147322 | controller | Monday 01 December 2025  11:41:54 +0000 (0:00:00.029)       0:00:14.821 *******
2025-12-01 11:41:54.172443 | controller | skipping: [instance]
2025-12-01 11:41:54.172630 | controller |
2025-12-01 11:41:54.172806 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-12-01 11:41:54.172974 | controller | Monday 01 December 2025  11:41:54 +0000 (0:00:00.025)       0:00:14.847 *******
2025-12-01 11:41:54.199926 | controller | skipping: [instance]
2025-12-01 11:41:54.200136 | controller |
2025-12-01 11:41:54.200329 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-12-01 11:41:54.200495 | controller | Monday 01 December 2025  11:41:54 +0000 (0:00:00.027)       0:00:14.874 *******
2025-12-01 11:41:54.225614 | controller | skipping: [instance]
2025-12-01 11:41:54.225783 | controller |
2025-12-01 11:41:54.225955 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-12-01 11:41:54.226148 | controller | Monday 01 December 2025  11:41:54 +0000 (0:00:00.025)       0:00:14.900 *******
2025-12-01 11:41:54.404876 | controller | ok: [instance]
2025-12-01 11:41:54.620395 | controller |
2025-12-01 11:41:54.620435 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-12-01 11:41:54.620443 | controller | Monday 01 December 2025  11:41:54 +0000 (0:00:00.178)       0:00:15.079 *******
2025-12-01 11:41:54.620454 | controller | changed: [instance]
2025-12-01 11:41:54.849824 | controller |
2025-12-01 11:41:54.849855 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-12-01 11:41:54.849863 | controller | Monday 01 December 2025  11:41:54 +0000 (0:00:00.215)       0:00:15.294 *******
2025-12-01 11:41:54.849873 | controller | changed: [instance]
2025-12-01 11:41:54.870137 | controller |
2025-12-01 11:41:54.870158 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-12-01 11:41:54.870166 | controller | Monday 01 December 2025  11:41:54 +0000 (0:00:00.229)       0:00:15.524 *******
2025-12-01 11:41:54.870175 | controller | skipping: [instance]
2025-12-01 11:41:54.870196 | controller |
2025-12-01 11:41:54.870473 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-12-01 11:41:54.891019 | controller | Monday 01 December 2025  11:41:54 +0000 (0:00:00.020)       0:00:15.544 *******
2025-12-01 11:41:54.891043 | controller | skipping: [instance]
2025-12-01 11:41:54.912135 | controller |
2025-12-01 11:41:54.912152 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-12-01 11:41:54.912159 | controller | Monday 01 December 2025  11:41:54 +0000 (0:00:00.020)       0:00:15.565 *******
2025-12-01 11:41:54.912167 | controller | skipping: [instance]
2025-12-01 11:41:54.912409 | controller |
2025-12-01 11:41:54.912431 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-12-01 11:41:54.931914 | controller | Monday 01 December 2025  11:41:54 +0000 (0:00:00.021)       0:00:15.586 *******
2025-12-01 11:41:54.931932 | controller | skipping: [instance]
2025-12-01 11:41:54.951384 | controller |
2025-12-01 11:41:54.951415 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-12-01 11:41:54.951429 | controller | Monday 01 December 2025  11:41:54 +0000 (0:00:00.019)       0:00:15.606 *******
2025-12-01 11:41:54.951439 | controller | skipping: [instance]
2025-12-01 11:41:54.971055 | controller |
2025-12-01 11:41:54.971075 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-12-01 11:41:54.971113 | controller | Monday 01 December 2025  11:41:54 +0000 (0:00:00.019)       0:00:15.625 *******
2025-12-01 11:41:54.971127 | controller | skipping: [instance]
2025-12-01 11:41:54.986493 | controller |
2025-12-01 11:41:54.986512 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-12-01 11:41:54.986520 | controller | Monday 01 December 2025  11:41:54 +0000 (0:00:00.019)       0:00:15.645 *******
2025-12-01 11:41:54.986529 | controller | skipping: [instance]
2025-12-01 11:41:55.013535 | controller |
2025-12-01 11:41:55.013555 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-12-01 11:41:55.013563 | controller | Monday 01 December 2025  11:41:54 +0000 (0:00:00.015)       0:00:15.660 *******
2025-12-01 11:41:55.013573 | controller | skipping: [instance]
2025-12-01 11:41:55.028395 | controller |
2025-12-01 11:41:55.028429 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-12-01 11:41:55.028437 | controller | Monday 01 December 2025  11:41:55 +0000 (0:00:00.027)       0:00:15.687 *******
2025-12-01 11:41:55.028446 | controller | skipping: [instance]
2025-12-01 11:41:55.028613 | controller |
2025-12-01 11:41:55.028776 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-12-01 11:41:55.028940 | controller | Monday 01 December 2025  11:41:55 +0000 (0:00:00.015)       0:00:15.703 *******
2025-12-01 11:41:55.048364 | controller | skipping: [instance]
2025-12-01 11:41:55.048547 | controller |
2025-12-01 11:41:55.048708 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-12-01 11:41:55.048866 | controller | Monday 01 December 2025  11:41:55 +0000 (0:00:00.019)       0:00:15.723 *******
2025-12-01 11:41:55.075682 | controller | skipping: [instance]
2025-12-01 11:41:55.075853 | controller |
2025-12-01 11:41:55.076012 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-12-01 11:41:55.076176 | controller | Monday 01 December 2025  11:41:55 +0000 (0:00:00.027)       0:00:15.750 *******
2025-12-01 11:41:55.105266 | controller | skipping: [instance]
2025-12-01 11:41:55.105431 | controller |
2025-12-01 11:41:55.105574 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-12-01 11:41:55.105703 | controller | Monday 01 December 2025  11:41:55 +0000 (0:00:00.029)       0:00:15.779 *******
2025-12-01 11:41:55.133244 | controller | skipping: [instance]
2025-12-01 11:41:55.133448 | controller |
2025-12-01 11:41:55.133612 | controller | TASK [test_deps : Install additional packages] *********************************
2025-12-01 11:41:55.133775 | controller | Monday 01 December 2025  11:41:55 +0000 (0:00:00.028)       0:00:15.808 *******
2025-12-01 11:41:55.160191 | controller | skipping: [instance]
2025-12-01 11:41:55.160345 | controller |
2025-12-01 11:41:55.160503 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-12-01 11:41:55.160641 | controller | Monday 01 December 2025  11:41:55 +0000 (0:00:00.026)       0:00:15.834 *******
2025-12-01 11:42:34.143757 | controller | ok: [instance]
2025-12-01 11:42:35.340398 | controller |
2025-12-01 11:42:35.340429 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-12-01 11:42:35.340438 | controller | Monday 01 December 2025  11:42:34 +0000 (0:00:38.982)       0:00:54.817 *******
2025-12-01 11:42:35.340448 | controller | ok: [instance]
2025-12-01 11:42:35.372157 | controller |
2025-12-01 11:42:35.372176 | controller | TASK [test_deps : Install extra packages] **************************************
2025-12-01 11:42:35.372185 | controller | Monday 01 December 2025  11:42:35 +0000 (0:00:01.196)       0:00:56.014 *******
2025-12-01 11:42:35.372196 | controller | skipping: [instance]
2025-12-01 11:42:35.372549 | controller |
2025-12-01 11:42:35.760827 | controller | TASK [Create 1st dummy interface with needed IPs] ******************************
2025-12-01 11:42:35.760859 | controller | Monday 01 December 2025  11:42:35 +0000 (0:00:00.032)       0:00:56.046 *******
2025-12-01 11:42:35.760871 | controller | changed: [instance]
2025-12-01 11:42:36.024968 | controller |
2025-12-01 11:42:36.025000 | controller | TASK [Create 2nd dummy interface with needed IPs] ******************************
2025-12-01 11:42:36.025008 | controller | Monday 01 December 2025  11:42:35 +0000 (0:00:00.384)       0:00:56.431 *******
2025-12-01 11:42:36.025018 | controller | changed: [instance]
2025-12-01 11:42:36.293180 | controller |
2025-12-01 11:42:36.293217 | controller | TASK [Create 3nd dummy interface with needed IPs] ******************************
2025-12-01 11:42:36.293232 | controller | Monday 01 December 2025  11:42:36 +0000 (0:00:00.268)       0:00:56.699 *******
2025-12-01 11:42:36.293243 | controller | changed: [instance]
2025-12-01 11:42:36.293303 | controller |
2025-12-01 11:42:36.293331 | controller | PLAY RECAP *********************************************************************
2025-12-01 11:42:36.293339 | controller | instance                   : ok=22   changed=12   unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-12-01 11:42:36.293345 | controller |
2025-12-01 11:42:36.293357 | controller | Monday 01 December 2025  11:42:36 +0000 (0:00:00.267)       0:00:56.966 *******
2025-12-01 11:42:36.293363 | controller | ===============================================================================
2025-12-01 11:42:36.293372 | controller | test_deps : Install selinux python libs -------------------------------- 38.98s
2025-12-01 11:42:36.293397 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.66s
2025-12-01 11:42:36.293419 | controller | test_deps : Install python yaml libs ------------------------------------ 1.20s
2025-12-01 11:42:36.295176 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.01s
2025-12-01 11:42:36.350275 | controller | Gathering Facts --------------------------------------------------------- 0.80s
2025-12-01 11:42:36.350309 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.77s
2025-12-01 11:42:36.350317 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.72s
2025-12-01 11:42:36.350322 | controller | repo_setup : Install repo-setup package --------------------------------- 0.70s
2025-12-01 11:42:36.350328 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.65s
2025-12-01 11:42:36.350333 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.53s
2025-12-01 11:42:36.350339 | controller | repo_setup : Ensure directories are present ----------------------------- 0.52s
2025-12-01 11:42:36.350344 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.44s
2025-12-01 11:42:36.350349 | controller | Create 1st dummy interface with needed IPs ------------------------------ 0.38s
2025-12-01 11:42:36.350355 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.29s
2025-12-01 11:42:36.350360 | controller | Create 2nd dummy interface with needed IPs ------------------------------ 0.27s
2025-12-01 11:42:36.350365 | controller | Create 3nd dummy interface with needed IPs ------------------------------ 0.27s
2025-12-01 11:42:36.350370 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.26s
2025-12-01 11:42:36.350376 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.23s
2025-12-01 11:42:36.350381 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.22s
2025-12-01 11:42:36.350387 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.18s
2025-12-01 11:42:36.350397 | controller | INFO     Running default > converge
2025-12-01 11:42:36.786868 | controller |
2025-12-01 11:42:36.787052 | controller | PLAY [Converge] ****************************************************************
2025-12-01 11:42:36.787221 | controller |
2025-12-01 11:42:36.787365 | controller | TASK [Gathering Facts] *********************************************************
2025-12-01 11:42:36.787513 | controller | Monday 01 December 2025  11:42:36 +0000 (0:00:00.017)       0:00:00.017 *******
2025-12-01 11:42:37.640055 | controller | ok: [instance]
2025-12-01 11:42:41.744177 | controller |
2025-12-01 11:42:41.744208 | controller | TASK [dnsmasq : Install needed packages] ***************************************
2025-12-01 11:42:41.744218 | controller | Monday 01 December 2025  11:42:37 +0000 (0:00:00.852)       0:00:00.870 *******
2025-12-01 11:42:41.744228 | controller | changed: [instance]
2025-12-01 11:42:41.744603 | controller |
2025-12-01 11:42:45.146208 | controller | TASK [dnsmasq : Ensure SELinux knows about the new files and directories] ******
2025-12-01 11:42:45.146239 | controller | Monday 01 December 2025  11:42:41 +0000 (0:00:04.104)       0:00:04.975 *******
2025-12-01 11:42:45.146251 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.conf', 'setype': 'dnsmasq_etc_t'})
2025-12-01 11:42:45.146280 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.d(/.*)?', 'setype': 'dnsmasq_etc_t'})
2025-12-01 11:42:45.146287 | controller | changed: [instance] => (item={'target': '/var/run/cifmw-dnsmasq.pid', 'setype': 'dnsmasq_var_run_t'})
2025-12-01 11:42:45.146293 | controller |
2025-12-01 11:42:45.146301 | controller | TASK [dnsmasq : Manage configuration directory] ********************************
2025-12-01 11:42:45.146441 | controller | Monday 01 December 2025  11:42:45 +0000 (0:00:03.401)       0:00:08.376 *******
2025-12-01 11:42:45.454797 | controller | changed: [instance]
2025-12-01 11:42:46.015188 | controller |
2025-12-01 11:42:46.015220 | controller | TASK [dnsmasq : Manage systemd unit file] **************************************
2025-12-01 11:42:46.015234 | controller | Monday 01 December 2025  11:42:45 +0000 (0:00:00.308)       0:00:08.685 *******
2025-12-01 11:42:46.015244 | controller | changed: [instance]
2025-12-01 11:42:46.379981 | controller |
2025-12-01 11:42:46.380009 | controller | TASK [dnsmasq : Manage base configuration file] ********************************
2025-12-01 11:42:46.380018 | controller | Monday 01 December 2025  11:42:46 +0000 (0:00:00.560)       0:00:09.245 *******
2025-12-01 11:42:46.380027 | controller | changed: [instance]
2025-12-01 11:42:46.380058 | controller |
2025-12-01 11:42:46.380340 | controller | TASK [dnsmasq : Render listener configuration] *********************************
2025-12-01 11:42:46.402328 | controller | Monday 01 December 2025  11:42:46 +0000 (0:00:00.365)       0:00:09.611 *******
2025-12-01 11:42:46.402349 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/listener.yml for instance
2025-12-01 11:42:46.783834 | controller |
2025-12-01 11:42:46.783864 | controller | TASK [dnsmasq : Create listener configuration file] ****************************
2025-12-01 11:42:46.783872 | controller | Monday 01 December 2025  11:42:46 +0000 (0:00:00.022)       0:00:09.633 *******
2025-12-01 11:42:46.783883 | controller | changed: [instance]
2025-12-01 11:42:46.800593 | controller |
2025-12-01 11:42:46.800616 | controller | TASK [dnsmasq : Remove listener configuration file] ****************************
2025-12-01 11:42:46.800625 | controller | Monday 01 December 2025  11:42:46 +0000 (0:00:00.381)       0:00:10.014 *******
2025-12-01 11:42:46.800636 | controller | skipping: [instance]
2025-12-01 11:42:46.823199 | controller |
2025-12-01 11:42:46.823239 | controller | TASK [dnsmasq : Render dns configuration] **************************************
2025-12-01 11:42:46.823251 | controller | Monday 01 December 2025  11:42:46 +0000 (0:00:00.016)       0:00:10.031 *******
2025-12-01 11:42:46.823264 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/dns.yml for instance
2025-12-01 11:42:46.823297 | controller |
2025-12-01 11:42:46.823308 | controller | TASK [dnsmasq : Create dns configuration file] *********************************
2025-12-01 11:42:46.823424 | controller | Monday 01 December 2025  11:42:46 +0000 (0:00:00.022)       0:00:10.054 *******
2025-12-01 11:42:47.193592 | controller | changed: [instance]
2025-12-01 11:42:47.210630 | controller |
2025-12-01 11:42:47.210652 | controller | TASK [dnsmasq : Remove dns configuration file] *********************************
2025-12-01 11:42:47.210660 | controller | Monday 01 December 2025  11:42:47 +0000 (0:00:00.370)       0:00:10.424 *******
2025-12-01 11:42:47.210670 | controller | skipping: [instance]
2025-12-01 11:42:47.235537 | controller |
2025-12-01 11:42:47.235560 | controller | TASK [dnsmasq : Add localhost addresses from defined dnsmasq listen addresses to loopback interface] ***
2025-12-01 11:42:47.235569 | controller | Monday 01 December 2025  11:42:47 +0000 (0:00:00.017)       0:00:10.441 *******
2025-12-01 11:42:47.235581 | controller | skipping: [instance] => (item=192.168.253.9)
2025-12-01 11:42:48.061381 | controller | skipping: [instance] => (item=192.168.254.9)
2025-12-01 11:42:48.061411 | controller | skipping: [instance]
2025-12-01 11:42:48.061419 | controller |
2025-12-01 11:42:48.061426 | controller | TASK [dnsmasq : Enable and start service] **************************************
2025-12-01 11:42:48.061432 | controller | Monday 01 December 2025  11:42:47 +0000 (0:00:00.024)       0:00:10.466 *******
2025-12-01 11:42:48.061441 | controller | changed: [instance]
2025-12-01 11:42:48.078270 | controller |
2025-12-01 11:42:48.078289 | controller | TASK [dnsmasq : Stop service] **************************************************
2025-12-01 11:42:48.078303 | controller | Monday 01 December 2025  11:42:48 +0000 (0:00:00.825)       0:00:11.292 *******
2025-12-01 11:42:48.078313 | controller | skipping: [instance]
2025-12-01 11:42:48.095082 | controller |
2025-12-01 11:42:48.095122 | controller | TASK [dnsmasq : Remove unit file] **********************************************
2025-12-01 11:42:48.095132 | controller | Monday 01 December 2025  11:42:48 +0000 (0:00:00.016)       0:00:11.309 *******
2025-12-01 11:42:48.095143 | controller | skipping: [instance]
2025-12-01 11:42:48.121191 | controller |
2025-12-01 11:42:48.121226 | controller | TASK [dnsmasq : Remove main configuration files] *******************************
2025-12-01 11:42:48.121235 | controller | Monday 01 December 2025  11:42:48 +0000 (0:00:00.016)       0:00:11.326 *******
2025-12-01 11:42:48.121246 | controller | skipping: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2025-12-01 11:42:48.121270 | controller | skipping: [instance] => (item=/etc/cifmw-dnsmasq.d)
2025-12-01 11:42:48.121279 | controller | skipping: [instance]
2025-12-01 11:42:48.121286 | controller |
2025-12-01 11:42:48.121295 | controller | TASK [Create network] **********************************************************
2025-12-01 11:42:48.121398 | controller | Monday 01 December 2025  11:42:48 +0000 (0:00:00.026)       0:00:11.352 *******
2025-12-01 11:42:48.148839 | controller |
2025-12-01 11:42:48.174733 | controller | TASK [dnsmasq : Assert mandatory parameter] ************************************
2025-12-01 11:42:48.174757 | controller | Monday 01 December 2025  11:42:48 +0000 (0:00:00.027)       0:00:11.379 *******
2025-12-01 11:42:48.174771 | controller | ok: [instance] => changed=false
2025-12-01 11:42:48.174974 | controller |   msg: All assertions passed
2025-12-01 11:42:48.175208 | controller |
2025-12-01 11:42:48.175415 | controller | TASK [dnsmasq : Assert mandatory parameters for new network] *******************
2025-12-01 11:42:48.175558 | controller | Monday 01 December 2025  11:42:48 +0000 (0:00:00.026)       0:00:11.406 *******
2025-12-01 11:42:48.206555 | controller | ok: [instance] => changed=false
2025-12-01 11:42:48.206754 | controller |   msg: All assertions passed
2025-12-01 11:42:48.206917 | controller |
2025-12-01 11:42:48.207129 | controller | TASK [dnsmasq : Create network] ************************************************
2025-12-01 11:42:48.207323 | controller | Monday 01 December 2025  11:42:48 +0000 (0:00:00.031)       0:00:11.437 *******
2025-12-01 11:42:48.658107 | controller | changed: [instance]
2025-12-01 11:42:48.675901 | controller |
2025-12-01 11:42:48.675921 | controller | TASK [dnsmasq : Remove network] ************************************************
2025-12-01 11:42:48.675929 | controller | Monday 01 December 2025  11:42:48 +0000 (0:00:00.450)       0:00:11.888 *******
2025-12-01 11:42:48.675938 | controller | skipping: [instance]
2025-12-01 11:42:48.698013 | controller |
2025-12-01 11:42:48.698032 | controller | TASK [dnsmasq : Remove dhcp host files] ****************************************
2025-12-01 11:42:48.698040 | controller | Monday 01 December 2025  11:42:48 +0000 (0:00:00.018)       0:00:11.906 *******
2025-12-01 11:42:48.698048 | controller | skipping: [instance]
2025-12-01 11:42:48.727249 | controller |
2025-12-01 11:42:48.727288 | controller | TASK [Create second network] ***************************************************
2025-12-01 11:42:48.727308 | controller | Monday 01 December 2025  11:42:48 +0000 (0:00:00.021)       0:00:11.928 *******
2025-12-01 11:42:48.727323 | controller |
2025-12-01 11:42:48.727346 | controller | TASK [dnsmasq : Assert mandatory parameter] ************************************
2025-12-01 11:42:48.727357 | controller | Monday 01 December 2025  11:42:48 +0000 (0:00:00.029)       0:00:11.958 *******
2025-12-01 11:42:48.754161 | controller | ok: [instance] => changed=false
2025-12-01 11:42:48.754268 | controller |   msg: All assertions passed
2025-12-01 11:42:48.754372 | controller |
2025-12-01 11:42:48.754605 | controller | TASK [dnsmasq : Assert mandatory parameters for new network] *******************
2025-12-01 11:42:48.789181 | controller | Monday 01 December 2025  11:42:48 +0000 (0:00:00.027)       0:00:11.985 *******
2025-12-01 11:42:48.789219 | controller | ok: [instance] => changed=false
2025-12-01 11:42:48.789592 | controller |   msg: All assertions passed
2025-12-01 11:42:48.789613 | controller |
2025-12-01 11:42:49.226170 | controller | TASK [dnsmasq : Create network] ************************************************
2025-12-01 11:42:49.226206 | controller | Monday 01 December 2025  11:42:48 +0000 (0:00:00.034)       0:00:12.020 *******
2025-12-01 11:42:49.226227 | controller | changed: [instance]
2025-12-01 11:42:49.226251 | controller |
2025-12-01 11:42:49.226259 | controller | TASK [dnsmasq : Remove network] ************************************************
2025-12-01 11:42:49.226345 | controller | Monday 01 December 2025  11:42:49 +0000 (0:00:00.436)       0:00:12.457 *******
2025-12-01 11:42:49.243354 | controller | skipping: [instance]
2025-12-01 11:42:49.264680 | controller |
2025-12-01 11:42:49.264700 | controller | TASK [dnsmasq : Remove dhcp host files] ****************************************
2025-12-01 11:42:49.264709 | controller | Monday 01 December 2025  11:42:49 +0000 (0:00:00.017)       0:00:12.474 *******
2025-12-01 11:42:49.264718 | controller | skipping: [instance]
2025-12-01 11:42:49.548140 | controller |
2025-12-01 11:42:49.548161 | controller | TASK [Inject enable-ra in startrek] ********************************************
2025-12-01 11:42:49.548169 | controller | Monday 01 December 2025  11:42:49 +0000 (0:00:00.021)       0:00:12.495 *******
2025-12-01 11:42:49.548178 | controller | ok: [instance]
2025-12-01 11:42:49.548362 | controller |
2025-12-01 11:42:49.548375 | controller | TASK [Assert no change was done in startrek] ***********************************
2025-12-01 11:42:49.572007 | controller | Monday 01 December 2025  11:42:49 +0000 (0:00:00.283)       0:00:12.779 *******
2025-12-01 11:42:49.572031 | controller | ok: [instance] => changed=false
2025-12-01 11:42:49.597863 | controller |   msg: All assertions passed
2025-12-01 11:42:49.597884 | controller |
2025-12-01 11:42:49.597893 | controller | TASK [Create IPv4-only network] ************************************************
2025-12-01 11:42:49.597900 | controller | Monday 01 December 2025  11:42:49 +0000 (0:00:00.023)       0:00:12.802 *******
2025-12-01 11:42:49.597911 | controller |
2025-12-01 11:42:49.626019 | controller | TASK [dnsmasq : Assert mandatory parameter] ************************************
2025-12-01 11:42:49.626041 | controller | Monday 01 December 2025  11:42:49 +0000 (0:00:00.026)       0:00:12.828 *******
2025-12-01 11:42:49.626055 | controller | ok: [instance] => changed=false
2025-12-01 11:42:49.658378 | controller |   msg: All assertions passed
2025-12-01 11:42:49.658399 | controller |
2025-12-01 11:42:49.658408 | controller | TASK [dnsmasq : Assert mandatory parameters for new network] *******************
2025-12-01 11:42:49.658415 | controller | Monday 01 December 2025  11:42:49 +0000 (0:00:00.027)       0:00:12.856 *******
2025-12-01 11:42:49.658426 | controller | ok: [instance] => changed=false
2025-12-01 11:42:50.082981 | controller |   msg: All assertions passed
2025-12-01 11:42:50.083006 | controller |
2025-12-01 11:42:50.083014 | controller | TASK [dnsmasq : Create network] ************************************************
2025-12-01 11:42:50.083021 | controller | Monday 01 December 2025  11:42:49 +0000 (0:00:00.032)       0:00:12.889 *******
2025-12-01 11:42:50.083030 | controller | changed: [instance]
2025-12-01 11:42:50.100639 | controller |
2025-12-01 11:42:50.100660 | controller | TASK [dnsmasq : Remove network] ************************************************
2025-12-01 11:42:50.100673 | controller | Monday 01 December 2025  11:42:50 +0000 (0:00:00.424)       0:00:13.313 *******
2025-12-01 11:42:50.100683 | controller | skipping: [instance]
2025-12-01 11:42:50.122738 | controller |
2025-12-01 11:42:50.122758 | controller | TASK [dnsmasq : Remove dhcp host files] ****************************************
2025-12-01 11:42:50.122766 | controller | Monday 01 December 2025  11:42:50 +0000 (0:00:00.017)       0:00:13.331 *******
2025-12-01 11:42:50.122775 | controller | skipping: [instance]
2025-12-01 11:42:50.313540 | controller |
2025-12-01 11:42:50.313569 | controller | TASK [Inject enable-ra in oldies] **********************************************
2025-12-01 11:42:50.313577 | controller | Monday 01 December 2025  11:42:50 +0000 (0:00:00.022)       0:00:13.353 *******
2025-12-01 11:42:50.313587 | controller | changed: [instance]
2025-12-01 11:42:50.334197 | controller |
2025-12-01 11:42:50.334231 | controller | TASK [Debug] *******************************************************************
2025-12-01 11:42:50.334239 | controller | Monday 01 December 2025  11:42:50 +0000 (0:00:00.190)       0:00:13.544 *******
2025-12-01 11:42:50.334251 | controller | ok: [instance] =>
2025-12-01 11:42:50.334275 | controller |   _set_ra:
2025-12-01 11:42:50.334281 | controller |     backup: ''
2025-12-01 11:42:50.334287 | controller |     changed: true
2025-12-01 11:42:50.334293 | controller |     diff:
2025-12-01 11:42:50.334298 | controller |     - after: ''
2025-12-01 11:42:50.334304 | controller |       after_header: /etc/cifmw-dnsmasq.d/oldies.conf (content)
2025-12-01 11:42:50.334309 | controller |       before: ''
2025-12-01 11:42:50.334322 | controller |       before_header: /etc/cifmw-dnsmasq.d/oldies.conf (content)
2025-12-01 11:42:50.334344 | controller |     - after_header: /etc/cifmw-dnsmasq.d/oldies.conf (file attributes)
2025-12-01 11:42:50.334905 | controller |       before_header: /etc/cifmw-dnsmasq.d/oldies.conf (file attributes)
2025-12-01 11:42:50.356707 | controller |     failed: false
2025-12-01 11:42:50.356725 | controller |     msg: line added
2025-12-01 11:42:50.356733 | controller |
2025-12-01 11:42:50.356739 | controller | TASK [Assert oldies was changed] ***********************************************
2025-12-01 11:42:50.356745 | controller | Monday 01 December 2025  11:42:50 +0000 (0:00:00.020)       0:00:13.564 *******
2025-12-01 11:42:50.356754 | controller | ok: [instance] => changed=false
2025-12-01 11:42:50.384563 | controller |   msg: All assertions passed
2025-12-01 11:42:50.384582 | controller |
2025-12-01 11:42:50.384590 | controller | TASK [Inject some node in starwars network] ************************************
2025-12-01 11:42:50.384596 | controller | Monday 01 December 2025  11:42:50 +0000 (0:00:00.022)       0:00:13.587 *******
2025-12-01 11:42:50.384604 | controller |
2025-12-01 11:42:50.418976 | controller | TASK [dnsmasq : Ensure we have the right data and type] ************************
2025-12-01 11:42:50.418995 | controller | Monday 01 December 2025  11:42:50 +0000 (0:00:00.028)       0:00:13.615 *******
2025-12-01 11:42:50.419006 | controller | ok: [instance]
2025-12-01 11:42:50.481947 | controller |
2025-12-01 11:42:50.481965 | controller | TASK [dnsmasq : Assert we have needed host data] *******************************
2025-12-01 11:42:50.481972 | controller | Monday 01 December 2025  11:42:50 +0000 (0:00:00.034)       0:00:13.649 *******
2025-12-01 11:42:50.481980 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:a7', 'ips': ['2345:0425:2CA1::0567:5673:cafe', '192.168.254.11']})
2025-12-01 11:42:50.522237 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:a8', 'ips': ['2345:0425:2CA1::0567:5673:babe', '192.168.254.12'], 'name': 'solo'})
2025-12-01 11:42:50.522277 | controller | ok: [instance] => (item={'network': 'startrek', 'state': 'present', 'mac': '0a:19:02:f8:4c:a8', 'ips': ['2345:0426:2CA1::0567:5673:babe', '192.168.253.12'], 'name': 'spock'})
2025-12-01 11:42:50.522287 | controller |
2025-12-01 11:42:50.522296 | controller | TASK [dnsmasq : Ensure networks exists] ****************************************
2025-12-01 11:42:50.522304 | controller | Monday 01 December 2025  11:42:50 +0000 (0:00:00.062)       0:00:13.712 *******
2025-12-01 11:42:50.522317 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/_check_net_status.yml for instance => (item=starwars)
2025-12-01 11:42:50.522540 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/_check_net_status.yml for instance => (item=startrek)
2025-12-01 11:42:50.522563 | controller |
2025-12-01 11:42:50.522577 | controller | TASK [dnsmasq : Check network file status] *************************************
2025-12-01 11:42:50.691489 | controller | Monday 01 December 2025  11:42:50 +0000 (0:00:00.040)       0:00:13.753 *******
2025-12-01 11:42:50.691513 | controller | ok: [instance]
2025-12-01 11:42:50.713487 | controller |
2025-12-01 11:42:50.713504 | controller | TASK [dnsmasq : Assert network exists] *****************************************
2025-12-01 11:42:50.713512 | controller | Monday 01 December 2025  11:42:50 +0000 (0:00:00.169)       0:00:13.922 *******
2025-12-01 11:42:50.713520 | controller | ok: [instance]
2025-12-01 11:42:50.881873 | controller |
2025-12-01 11:42:50.881902 | controller | TASK [dnsmasq : Check network file status] *************************************
2025-12-01 11:42:50.881911 | controller | Monday 01 December 2025  11:42:50 +0000 (0:00:00.022)       0:00:13.944 *******
2025-12-01 11:42:50.881921 | controller | ok: [instance]
2025-12-01 11:42:50.902154 | controller |
2025-12-01 11:42:50.902180 | controller | TASK [dnsmasq : Assert network exists] *****************************************
2025-12-01 11:42:50.902190 | controller | Monday 01 December 2025  11:42:50 +0000 (0:00:00.168)       0:00:14.112 *******
2025-12-01 11:42:50.902202 | controller | ok: [instance]
2025-12-01 11:42:50.902450 | controller |
2025-12-01 11:42:50.902468 | controller | TASK [dnsmasq : Initialize empty dhcp_host_entries] ****************************
2025-12-01 11:42:50.917991 | controller | Monday 01 December 2025  11:42:50 +0000 (0:00:00.020)       0:00:14.133 *******
2025-12-01 11:42:50.918013 | controller | ok: [instance]
2025-12-01 11:42:51.028179 | controller |
2025-12-01 11:42:51.028215 | controller | TASK [dnsmasq : Compute entry] *************************************************
2025-12-01 11:42:51.028224 | controller | Monday 01 December 2025  11:42:50 +0000 (0:00:00.015)       0:00:14.148 *******
2025-12-01 11:42:51.028235 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:a7', 'ips': ['2345:0425:2CA1::0567:5673:cafe', '192.168.254.11']})
2025-12-01 11:42:51.028260 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:a8', 'ips': ['2345:0425:2CA1::0567:5673:babe', '192.168.254.12'], 'name': 'solo'})
2025-12-01 11:42:51.028269 | controller | ok: [instance] => (item={'network': 'startrek', 'state': 'present', 'mac': '0a:19:02:f8:4c:a8', 'ips': ['2345:0426:2CA1::0567:5673:babe', '192.168.253.12'], 'name': 'spock'})
2025-12-01 11:42:51.028579 | controller |
2025-12-01 11:42:51.062762 | controller | TASK [dnsmasq : Create add/remove sets] ****************************************
2025-12-01 11:42:51.062786 | controller | Monday 01 December 2025  11:42:51 +0000 (0:00:00.110)       0:00:14.259 *******
2025-12-01 11:42:51.062808 | controller | ok: [instance]
2025-12-01 11:42:52.108084 | controller |
2025-12-01 11:42:52.108127 | controller | TASK [dnsmasq : Add DHCP entries] **********************************************
2025-12-01 11:42:52.108136 | controller | Monday 01 December 2025  11:42:51 +0000 (0:00:00.034)       0:00:14.293 *******
2025-12-01 11:42:52.108146 | controller | changed: [instance] => (item={'file': 'starwars_0a:19:02:f8:4c:a7', 'entry': '0a:19:02:f8:4c:a7,[2345:425:2ca1::567:5673:cafe],192.168.254.11', 'state': 'present'})
2025-12-01 11:42:52.127601 | controller | changed: [instance] => (item={'file': 'starwars_solo_0a:19:02:f8:4c:a8', 'entry': '0a:19:02:f8:4c:a8,[2345:425:2ca1::567:5673:babe],192.168.254.12,solo', 'state': 'present'})
2025-12-01 11:42:52.127625 | controller | changed: [instance] => (item={'file': 'startrek_spock_0a:19:02:f8:4c:a8', 'entry': '0a:19:02:f8:4c:a8,[2345:426:2ca1::567:5673:babe],192.168.253.12,spock', 'state': 'present'})
2025-12-01 11:42:52.127635 | controller |
2025-12-01 11:42:52.127643 | controller | TASK [dnsmasq : Remove DHCP entries] *******************************************
2025-12-01 11:42:52.127651 | controller | Monday 01 December 2025  11:42:52 +0000 (0:00:01.044)       0:00:15.338 *******
2025-12-01 11:42:52.127662 | controller | skipping: [instance]
2025-12-01 11:42:52.159975 | controller |
2025-12-01 11:42:52.159999 | controller | TASK [Inject nodes with tags for DHCP options] *********************************
2025-12-01 11:42:52.160006 | controller | Monday 01 December 2025  11:42:52 +0000 (0:00:00.019)       0:00:15.358 *******
2025-12-01 11:42:52.160015 | controller |
2025-12-01 11:42:52.196186 | controller | TASK [dnsmasq : Ensure we have the right data and type] ************************
2025-12-01 11:42:52.196222 | controller | Monday 01 December 2025  11:42:52 +0000 (0:00:00.032)       0:00:15.390 *******
2025-12-01 11:42:52.196234 | controller | ok: [instance]
2025-12-01 11:42:52.196254 | controller |
2025-12-01 11:42:52.196260 | controller | TASK [dnsmasq : Assert we have needed host data] *******************************
2025-12-01 11:42:52.196268 | controller | Monday 01 December 2025  11:42:52 +0000 (0:00:00.036)       0:00:15.427 *******
2025-12-01 11:42:52.258083 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:b1', 'ips': ['192.168.254.21', '2345:0425:2CA1::0567:5673:0021'], 'name': 'r2d2', 'tag': 'droid'})
2025-12-01 11:42:52.258321 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:b2', 'ips': ['192.168.254.22'], 'name': 'c3po', 'tag': 'droid'})
2025-12-01 11:42:52.258512 | controller | ok: [instance] => (item={'network': 'startrek', 'state': 'present', 'mac': '0a:19:02:f8:4c:b3', 'ips': ['192.168.253.31'], 'name': 'data', 'tag': 'android'})
2025-12-01 11:42:52.258673 | controller |
2025-12-01 11:42:52.258844 | controller | TASK [dnsmasq : Ensure networks exists] ****************************************
2025-12-01 11:42:52.259016 | controller | Monday 01 December 2025  11:42:52 +0000 (0:00:00.062)       0:00:15.489 *******
2025-12-01 11:42:52.298609 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/_check_net_status.yml for instance => (item=starwars)
2025-12-01 11:42:52.298790 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/_check_net_status.yml for instance => (item=startrek)
2025-12-01 11:42:52.298934 | controller |
2025-12-01 11:42:52.299103 | controller | TASK [dnsmasq : Check network file status] *************************************
2025-12-01 11:42:52.299268 | controller | Monday 01 December 2025  11:42:52 +0000 (0:00:00.040)       0:00:15.529 *******
2025-12-01 11:42:52.471725 | controller | ok: [instance]
2025-12-01 11:42:52.494787 | controller |
2025-12-01 11:42:52.494804 | controller | TASK [dnsmasq : Assert network exists] *****************************************
2025-12-01 11:42:52.494813 | controller | Monday 01 December 2025  11:42:52 +0000 (0:00:00.172)       0:00:15.702 *******
2025-12-01 11:42:52.494822 | controller | ok: [instance]
2025-12-01 11:42:52.668743 | controller |
2025-12-01 11:42:52.668774 | controller | TASK [dnsmasq : Check network file status] *************************************
2025-12-01 11:42:52.668782 | controller | Monday 01 December 2025  11:42:52 +0000 (0:00:00.023)       0:00:15.725 *******
2025-12-01 11:42:52.668792 | controller | ok: [instance]
2025-12-01 11:42:52.689280 | controller |
2025-12-01 11:42:52.689299 | controller | TASK [dnsmasq : Assert network exists] *****************************************
2025-12-01 11:42:52.689306 | controller | Monday 01 December 2025  11:42:52 +0000 (0:00:00.173)       0:00:15.899 *******
2025-12-01 11:42:52.689315 | controller | ok: [instance]
2025-12-01 11:42:52.705903 | controller |
2025-12-01 11:42:52.705925 | controller | TASK [dnsmasq : Initialize empty dhcp_host_entries] ****************************
2025-12-01 11:42:52.705934 | controller | Monday 01 December 2025  11:42:52 +0000 (0:00:00.020)       0:00:15.920 *******
2025-12-01 11:42:52.705945 | controller | ok: [instance]
2025-12-01 11:42:52.821162 | controller |
2025-12-01 11:42:52.821188 | controller | TASK [dnsmasq : Compute entry] *************************************************
2025-12-01 11:42:52.821196 | controller | Monday 01 December 2025  11:42:52 +0000 (0:00:00.016)       0:00:15.936 *******
2025-12-01 11:42:52.821205 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:b1', 'ips': ['192.168.254.21', '2345:0425:2CA1::0567:5673:0021'], 'name': 'r2d2', 'tag': 'droid'})
2025-12-01 11:42:52.821283 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:b2', 'ips': ['192.168.254.22'], 'name': 'c3po', 'tag': 'droid'})
2025-12-01 11:42:52.821292 | controller | ok: [instance] => (item={'network': 'startrek', 'state': 'present', 'mac': '0a:19:02:f8:4c:b3', 'ips': ['192.168.253.31'], 'name': 'data', 'tag': 'android'})
2025-12-01 11:42:52.821298 | controller |
2025-12-01 11:42:52.821305 | controller | TASK [dnsmasq : Create add/remove sets] ****************************************
2025-12-01 11:42:52.821446 | controller | Monday 01 December 2025  11:42:52 +0000 (0:00:00.114)       0:00:16.051 *******
2025-12-01 11:42:52.854970 | controller | ok: [instance]
2025-12-01 11:42:53.896899 | controller |
2025-12-01 11:42:53.896928 | controller | TASK [dnsmasq : Add DHCP entries] **********************************************
2025-12-01 11:42:53.896937 | controller | Monday 01 December 2025  11:42:52 +0000 (0:00:00.034)       0:00:16.085 *******
2025-12-01 11:42:53.896946 | controller | changed: [instance] => (item={'file': 'starwars_r2d2_0a:19:02:f8:4c:b1', 'entry': 'set:droid,0a:19:02:f8:4c:b1,192.168.254.21,[2345:425:2ca1::567:5673:21],r2d2', 'state': 'present'})
2025-12-01 11:42:53.916792 | controller | changed: [instance] => (item={'file': 'starwars_c3po_0a:19:02:f8:4c:b2', 'entry': 'set:droid,0a:19:02:f8:4c:b2,192.168.254.22,c3po', 'state': 'present'})
2025-12-01 11:42:53.916811 | controller | changed: [instance] => (item={'file': 'startrek_data_0a:19:02:f8:4c:b3', 'entry': 'set:android,0a:19:02:f8:4c:b3,192.168.253.31,data', 'state': 'present'})
2025-12-01 11:42:53.916818 | controller |
2025-12-01 11:42:53.916824 | controller | TASK [dnsmasq : Remove DHCP entries] *******************************************
2025-12-01 11:42:53.916830 | controller | Monday 01 December 2025  11:42:53 +0000 (0:00:01.041)       0:00:17.127 *******
2025-12-01 11:42:53.916838 | controller | skipping: [instance]
2025-12-01 11:42:54.179522 | controller |
2025-12-01 11:42:54.179551 | controller | TASK [Read r2d2 DHCP host entry] ***********************************************
2025-12-01 11:42:54.179565 | controller | Monday 01 December 2025  11:42:53 +0000 (0:00:00.020)       0:00:17.147 *******
2025-12-01 11:42:54.179579 | controller | ok: [instance]
2025-12-01 11:42:54.361179 | controller |
2025-12-01 11:42:54.361207 | controller | TASK [Read c3po DHCP host entry] ***********************************************
2025-12-01 11:42:54.361216 | controller | Monday 01 December 2025  11:42:54 +0000 (0:00:00.262)       0:00:17.410 *******
2025-12-01 11:42:54.361225 | controller | ok: [instance]
2025-12-01 11:42:54.361355 | controller |
2025-12-01 11:42:54.361366 | controller | TASK [Read data DHCP host entry] ***********************************************
2025-12-01 11:42:54.361374 | controller | Monday 01 December 2025  11:42:54 +0000 (0:00:00.181)       0:00:17.592 *******
2025-12-01 11:42:54.547888 | controller | ok: [instance]
2025-12-01 11:42:54.579209 | controller |
2025-12-01 11:42:54.579247 | controller | TASK [Decode entries] **********************************************************
2025-12-01 11:42:54.579256 | controller | Monday 01 December 2025  11:42:54 +0000 (0:00:00.186)       0:00:17.778 *******
2025-12-01 11:42:54.579267 | controller | ok: [instance]
2025-12-01 11:42:54.579292 | controller |
2025-12-01 11:42:54.579301 | controller | TASK [Assert r2d2 entry has droid tag] *****************************************
2025-12-01 11:42:54.579486 | controller | Monday 01 December 2025  11:42:54 +0000 (0:00:00.031)       0:00:17.809 *******
2025-12-01 11:42:54.602822 | controller | ok: [instance] => changed=false
2025-12-01 11:42:54.625510 | controller |   msg: All assertions passed
2025-12-01 11:42:54.625532 | controller |
2025-12-01 11:42:54.625541 | controller | TASK [Assert c3po entry has droid tag] *****************************************
2025-12-01 11:42:54.625549 | controller | Monday 01 December 2025  11:42:54 +0000 (0:00:00.023)       0:00:17.833 *******
2025-12-01 11:42:54.625559 | controller | ok: [instance] => changed=false
2025-12-01 11:42:54.648138 | controller |   msg: All assertions passed
2025-12-01 11:42:54.648170 | controller |
2025-12-01 11:42:54.648183 | controller | TASK [Assert data entry has android tag] ***************************************
2025-12-01 11:42:54.648194 | controller | Monday 01 December 2025  11:42:54 +0000 (0:00:00.022)       0:00:17.856 *******
2025-12-01 11:42:54.648210 | controller | ok: [instance] => changed=false
2025-12-01 11:42:54.648483 | controller |   msg: All assertions passed
2025-12-01 11:42:54.648695 | controller |
2025-12-01 11:42:54.648835 | controller | TASK [Verify entry without tag has no set: prefix] *****************************
2025-12-01 11:42:54.648990 | controller | Monday 01 December 2025  11:42:54 +0000 (0:00:00.022)       0:00:17.879 *******
2025-12-01 11:42:54.838956 | controller | ok: [instance]
2025-12-01 11:42:54.875171 | controller |
2025-12-01 11:42:54.875203 | controller | TASK [Assert solo entry does not have a tag] ***********************************
2025-12-01 11:42:54.875211 | controller | Monday 01 December 2025  11:42:54 +0000 (0:00:00.190)       0:00:18.069 *******
2025-12-01 11:42:54.875221 | controller | ok: [instance] => changed=false
2025-12-01 11:42:54.875628 | controller |   msg: All assertions passed
2025-12-01 11:42:55.241926 | controller |
2025-12-01 11:42:55.241953 | controller | TASK [Create DHCP options configuration for tagged hosts] **********************
2025-12-01 11:42:55.241962 | controller | Monday 01 December 2025  11:42:54 +0000 (0:00:00.036)       0:00:18.106 *******
2025-12-01 11:42:55.241971 | controller | changed: [instance]
2025-12-01 11:42:55.530038 | controller |
2025-12-01 11:42:55.530069 | controller | TASK [Verify dnsmasq configuration is valid] ***********************************
2025-12-01 11:42:55.530079 | controller | Monday 01 December 2025  11:42:55 +0000 (0:00:00.366)       0:00:18.472 *******
2025-12-01 11:42:55.530111 | controller | ok: [instance]
2025-12-01 11:42:55.556620 | controller |
2025-12-01 11:42:55.556638 | controller | TASK [Add a domain specific forwarder] *****************************************
2025-12-01 11:42:55.556646 | controller | Monday 01 December 2025  11:42:55 +0000 (0:00:00.288)       0:00:18.760 *******
2025-12-01 11:42:55.556655 | controller |
2025-12-01 11:42:55.625163 | controller | TASK [dnsmasq : Assert we have needed forwarder data] **************************
2025-12-01 11:42:55.625187 | controller | Monday 01 December 2025  11:42:55 +0000 (0:00:00.026)       0:00:18.787 *******
2025-12-01 11:42:55.625199 | controller | ok: [instance]
2025-12-01 11:42:55.667179 | controller |
2025-12-01 11:42:55.667209 | controller | TASK [dnsmasq : Assert each forwarder element have needed data] ****************
2025-12-01 11:42:55.667224 | controller | Monday 01 December 2025  11:42:55 +0000 (0:00:00.068)       0:00:18.856 *******
2025-12-01 11:42:55.667235 | controller | ok: [instance] => (item={'state': 'present', 'server': '192.168.254.100', 'domains': ['battlestar.galactica.lan']})
2025-12-01 11:42:55.667260 | controller |
2025-12-01 11:42:55.667267 | controller | TASK [dnsmasq : Add/Remove forwarder] ******************************************
2025-12-01 11:42:55.667274 | controller | Monday 01 December 2025  11:42:55 +0000 (0:00:00.042)       0:00:18.898 *******
2025-12-01 11:42:55.892495 | controller | changed: [instance] => (item={'state': 'present', 'server': '192.168.254.100', 'domains': ['battlestar.galactica.lan']})
2025-12-01 11:42:55.892583 | controller |
2025-12-01 11:42:55.892693 | controller | TASK [Add some host records] ***************************************************
2025-12-01 11:42:55.892798 | controller | Monday 01 December 2025  11:42:55 +0000 (0:00:00.225)       0:00:19.123 *******
2025-12-01 11:42:55.922744 | controller |
2025-12-01 11:42:55.922809 | controller | TASK [dnsmasq : Assert we have needed host record data] ************************
2025-12-01 11:42:55.922950 | controller | Monday 01 December 2025  11:42:55 +0000 (0:00:00.030)       0:00:19.154 *******
2025-12-01 11:42:55.957195 | controller | ok: [instance]
2025-12-01 11:42:55.957254 | controller |
2025-12-01 11:42:55.957379 | controller | TASK [dnsmasq : Assert each address element have needed data] ******************
2025-12-01 11:42:55.957502 | controller | Monday 01 December 2025  11:42:55 +0000 (0:00:00.034)       0:00:19.188 *******
2025-12-01 11:42:56.008168 | controller | ok: [instance] => (item={'state': 'present', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']})
2025-12-01 11:42:56.008618 | controller | ok: [instance] => (item={'state': 'present', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']})
2025-12-01 11:42:56.413159 | controller |
2025-12-01 11:42:56.413185 | controller | TASK [dnsmasq : Add/Remove address] ********************************************
2025-12-01 11:42:56.413193 | controller | Monday 01 December 2025  11:42:56 +0000 (0:00:00.050)       0:00:19.239 *******
2025-12-01 11:42:56.413203 | controller | changed: [instance] => (item={'state': 'present', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']})
2025-12-01 11:42:56.443855 | controller | changed: [instance] => (item={'state': 'present', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']})
2025-12-01 11:42:56.443878 | controller |
2025-12-01 11:42:56.443887 | controller | TASK [Add some wildcard addresses] *********************************************
2025-12-01 11:42:56.443894 | controller | Monday 01 December 2025  11:42:56 +0000 (0:00:00.404)       0:00:19.643 *******
2025-12-01 11:42:56.443905 | controller |
2025-12-01 11:42:56.479109 | controller | TASK [dnsmasq : Assert we have needed address data] ****************************
2025-12-01 11:42:56.479128 | controller | Monday 01 December 2025  11:42:56 +0000 (0:00:00.030)       0:00:19.674 *******
2025-12-01 11:42:56.479139 | controller | ok: [instance]
2025-12-01 11:42:56.564434 | controller |
2025-12-01 11:42:56.564456 | controller | TASK [dnsmasq : Assert each address element have needed data] ******************
2025-12-01 11:42:56.564463 | controller | Monday 01 December 2025  11:42:56 +0000 (0:00:00.035)       0:00:19.709 *******
2025-12-01 11:42:56.564472 | controller | ok: [instance] => (item={'state': 'present', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']})
2025-12-01 11:42:56.981049 | controller | ok: [instance] => (item={'state': 'present', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']})
2025-12-01 11:42:56.981104 | controller |
2025-12-01 11:42:56.981116 | controller | TASK [dnsmasq : Add/Remove address] ********************************************
2025-12-01 11:42:56.981123 | controller | Monday 01 December 2025  11:42:56 +0000 (0:00:00.085)       0:00:19.795 *******
2025-12-01 11:42:56.981133 | controller | changed: [instance] => (item={'state': 'present', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']})
2025-12-01 11:42:57.321222 | controller | changed: [instance] => (item={'state': 'present', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']})
2025-12-01 11:42:57.321254 | controller |
2025-12-01 11:42:57.321264 | controller | TASK [Force reload dnsmasq] ****************************************************
2025-12-01 11:42:57.321279 | controller | Monday 01 December 2025  11:42:56 +0000 (0:00:00.406)       0:00:20.202 *******
2025-12-01 11:42:57.321286 | controller |
2025-12-01 11:42:57.321293 | controller | RUNNING HANDLER [dnsmasq : Restart dnsmasq] ************************************
2025-12-01 11:42:57.321300 | controller | Monday 01 December 2025  11:42:56 +0000 (0:00:00.009)       0:00:20.211 *******
2025-12-01 11:42:57.321311 | controller | changed: [instance]
2025-12-01 11:42:57.670449 | controller |
2025-12-01 11:42:57.670476 | controller | RUNNING HANDLER [dnsmasq : Reload dnsmasq] *************************************
2025-12-01 11:42:57.670484 | controller | Monday 01 December 2025  11:42:57 +0000 (0:00:00.340)       0:00:20.551 *******
2025-12-01 11:42:57.670493 | controller | changed: [instance]
2025-12-01 11:42:57.725027 | controller |
2025-12-01 11:42:57.725052 | controller | PLAY RECAP *********************************************************************
2025-12-01 11:42:57.725073 | controller | instance                   : ok=71   changed=20   unreachable=0    failed=0    skipped=14   rescued=0    ignored=0
2025-12-01 11:42:57.725080 | controller |
2025-12-01 11:42:57.725107 | controller | Monday 01 December 2025  11:42:57 +0000 (0:00:00.347)       0:00:20.898 *******
2025-12-01 11:42:57.725116 | controller | ===============================================================================
2025-12-01 11:42:57.725122 | controller | dnsmasq : Install needed packages --------------------------------------- 4.10s
2025-12-01 11:42:57.725127 | controller | dnsmasq : Ensure SELinux knows about the new files and directories ------ 3.40s
2025-12-01 11:42:57.725133 | controller | dnsmasq : Add DHCP entries ---------------------------------------------- 1.04s
2025-12-01 11:42:57.725138 | controller | dnsmasq : Add DHCP entries ---------------------------------------------- 1.04s
2025-12-01 11:42:57.725144 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-12-01 11:42:57.725149 | controller | dnsmasq : Enable and start service -------------------------------------- 0.83s
2025-12-01 11:42:57.725155 | controller | dnsmasq : Manage systemd unit file -------------------------------------- 0.56s
2025-12-01 11:42:57.725160 | controller | dnsmasq : Create network ------------------------------------------------ 0.45s
2025-12-01 11:42:57.725171 | controller | dnsmasq : Create network ------------------------------------------------ 0.44s
2025-12-01 11:42:57.725176 | controller | dnsmasq : Create network ------------------------------------------------ 0.42s
2025-12-01 11:42:57.725182 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.41s
2025-12-01 11:42:57.725187 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.40s
2025-12-01 11:42:57.725193 | controller | dnsmasq : Create listener configuration file ---------------------------- 0.38s
2025-12-01 11:42:57.725198 | controller | dnsmasq : Create dns configuration file --------------------------------- 0.37s
2025-12-01 11:42:57.725203 | controller | Create DHCP options configuration for tagged hosts ---------------------- 0.37s
2025-12-01 11:42:57.725209 | controller | dnsmasq : Manage base configuration file -------------------------------- 0.37s
2025-12-01 11:42:57.725214 | controller | dnsmasq : Reload dnsmasq ------------------------------------------------ 0.35s
2025-12-01 11:42:57.725219 | controller | dnsmasq : Restart dnsmasq ----------------------------------------------- 0.34s
2025-12-01 11:42:57.725225 | controller | dnsmasq : Manage configuration directory -------------------------------- 0.31s
2025-12-01 11:42:57.725230 | controller | Verify dnsmasq configuration is valid ----------------------------------- 0.29s
2025-12-01 11:42:57.725239 | controller | INFO     Running default > cleanup
2025-12-01 11:42:58.115356 | controller |
2025-12-01 11:42:58.115533 | controller | PLAY [Converge] ****************************************************************
2025-12-01 11:42:58.115669 | controller |
2025-12-01 11:42:58.115814 | controller | TASK [Gathering Facts] *********************************************************
2025-12-01 11:42:58.115952 | controller | Monday 01 December 2025  11:42:58 +0000 (0:00:00.012)       0:00:00.012 *******
2025-12-01 11:42:58.940908 | controller | ok: [instance]
2025-12-01 11:42:59.499149 | controller |
2025-12-01 11:42:59.499173 | controller | TASK [Copy generated content in ci-framework-data/artifacts] *******************
2025-12-01 11:42:59.499187 | controller | Monday 01 December 2025  11:42:58 +0000 (0:00:00.825)       0:00:00.838 *******
2025-12-01 11:42:59.499196 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2025-12-01 11:42:59.499569 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.d/)
2025-12-01 11:42:59.527401 | controller |
2025-12-01 11:42:59.527418 | controller | TASK [Remove a domain specific forwarder] **************************************
2025-12-01 11:42:59.527425 | controller | Monday 01 December 2025  11:42:59 +0000 (0:00:00.558)       0:00:01.396 *******
2025-12-01 11:42:59.527433 | controller |
2025-12-01 11:42:59.559649 | controller | TASK [dnsmasq : Assert we have needed forwarder data] **************************
2025-12-01 11:42:59.559669 | controller | Monday 01 December 2025  11:42:59 +0000 (0:00:00.028)       0:00:01.425 *******
2025-12-01 11:42:59.559679 | controller | ok: [instance]
2025-12-01 11:42:59.595902 | controller |
2025-12-01 11:42:59.595923 | controller | TASK [dnsmasq : Assert each forwarder element have needed data] ****************
2025-12-01 11:42:59.595931 | controller | Monday 01 December 2025  11:42:59 +0000 (0:00:00.032)       0:00:01.457 *******
2025-12-01 11:42:59.595941 | controller | ok: [instance] => (item={'state': 'absent', 'server': '192.168.100.100', 'domains': ['battlestar.galactica.lan']})
2025-12-01 11:42:59.886195 | controller |
2025-12-01 11:42:59.886230 | controller | TASK [dnsmasq : Add/Remove forwarder] ******************************************
2025-12-01 11:42:59.886239 | controller | Monday 01 December 2025  11:42:59 +0000 (0:00:00.036)       0:00:01.493 *******
2025-12-01 11:42:59.886250 | controller | ok: [instance] => (item={'state': 'absent', 'server': '192.168.100.100', 'domains': ['battlestar.galactica.lan']})
2025-12-01 11:42:59.915563 | controller |
2025-12-01 11:42:59.915584 | controller | TASK [Remove some host records] ************************************************
2025-12-01 11:42:59.915592 | controller | Monday 01 December 2025  11:42:59 +0000 (0:00:00.290)       0:00:01.783 *******
2025-12-01 11:42:59.915603 | controller |
2025-12-01 11:42:59.949140 | controller | TASK [dnsmasq : Assert we have needed host record data] ************************
2025-12-01 11:42:59.949162 | controller | Monday 01 December 2025  11:42:59 +0000 (0:00:00.029)       0:00:01.813 *******
2025-12-01 11:42:59.949173 | controller | ok: [instance]
2025-12-01 11:42:59.949377 | controller |
2025-12-01 11:42:59.949395 | controller | TASK [dnsmasq : Assert each address element have needed data] ******************
2025-12-01 11:42:59.997005 | controller | Monday 01 December 2025  11:42:59 +0000 (0:00:00.033)       0:00:01.846 *******
2025-12-01 11:42:59.997036 | controller | ok: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']})
2025-12-01 11:43:00.404321 | controller | ok: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']})
2025-12-01 11:43:00.404350 | controller |
2025-12-01 11:43:00.404358 | controller | TASK [dnsmasq : Add/Remove address] ********************************************
2025-12-01 11:43:00.404364 | controller | Monday 01 December 2025  11:42:59 +0000 (0:00:00.047)       0:00:01.894 *******
2025-12-01 11:43:00.404374 | controller | changed: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']})
2025-12-01 11:43:00.434217 | controller | changed: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']})
2025-12-01 11:43:00.434242 | controller |
2025-12-01 11:43:00.434251 | controller | TASK [Remove some wildcard addresses] ******************************************
2025-12-01 11:43:00.434258 | controller | Monday 01 December 2025  11:43:00 +0000 (0:00:00.407)       0:00:02.301 *******
2025-12-01 11:43:00.434269 | controller |
2025-12-01 11:43:00.465264 | controller | TASK [dnsmasq : Assert we have needed address data] ****************************
2025-12-01 11:43:00.465287 | controller | Monday 01 December 2025  11:43:00 +0000 (0:00:00.030)       0:00:02.331 *******
2025-12-01 11:43:00.465298 | controller | ok: [instance]
2025-12-01 11:43:00.465350 | controller |
2025-12-01 11:43:00.465452 | controller | TASK [dnsmasq : Assert each address element have needed data] ******************
2025-12-01 11:43:00.465571 | controller | Monday 01 December 2025  11:43:00 +0000 (0:00:00.031)       0:00:02.363 *******
2025-12-01 11:43:00.544308 | controller | ok: [instance] => (item={'state': 'absent', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']})
2025-12-01 11:43:00.544415 | controller | ok: [instance] => (item={'state': 'absent', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']})
2025-12-01 11:43:00.544510 | controller |
2025-12-01 11:43:00.544612 | controller | TASK [dnsmasq : Add/Remove address] ********************************************
2025-12-01 11:43:00.544728 | controller | Monday 01 December 2025  11:43:00 +0000 (0:00:00.079)       0:00:02.442 *******
2025-12-01 11:43:00.933002 | controller | changed: [instance] => (item={'state': 'absent', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']})
2025-12-01 11:43:00.963189 | controller | changed: [instance] => (item={'state': 'absent', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']})
2025-12-01 11:43:00.963238 | controller |
2025-12-01 11:43:00.963249 | controller | TASK [dnsmasq : Cleaning dnsmasq] **********************************************
2025-12-01 11:43:00.963258 | controller | Monday 01 December 2025  11:43:00 +0000 (0:00:00.388)       0:00:02.830 *******
2025-12-01 11:43:00.963273 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/configure.yml for instance
2025-12-01 11:43:02.215250 | controller |
2025-12-01 11:43:02.215277 | controller | TASK [dnsmasq : Install needed packages] ***************************************
2025-12-01 11:43:02.215285 | controller | Monday 01 December 2025  11:43:00 +0000 (0:00:00.030)       0:00:02.860 *******
2025-12-01 11:43:02.215294 | controller | ok: [instance]
2025-12-01 11:43:05.474156 | controller |
2025-12-01 11:43:05.474186 | controller | TASK [dnsmasq : Ensure SELinux knows about the new files and directories] ******
2025-12-01 11:43:05.474195 | controller | Monday 01 December 2025  11:43:02 +0000 (0:00:01.251)       0:00:04.112 *******
2025-12-01 11:43:05.474205 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.conf', 'setype': 'dnsmasq_etc_t'})
2025-12-01 11:43:05.474651 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.d(/.*)?', 'setype': 'dnsmasq_etc_t'})
2025-12-01 11:43:05.474666 | controller | changed: [instance] => (item={'target': '/var/run/cifmw-dnsmasq.pid', 'setype': 'dnsmasq_var_run_t'})
2025-12-01 11:43:05.774925 | controller |
2025-12-01 11:43:05.774953 | controller | TASK [dnsmasq : Manage configuration directory] ********************************
2025-12-01 11:43:05.774961 | controller | Monday 01 December 2025  11:43:05 +0000 (0:00:03.258)       0:00:07.371 *******
2025-12-01 11:43:05.774971 | controller | changed: [instance]
2025-12-01 11:43:05.793134 | controller |
2025-12-01 11:43:05.793154 | controller | TASK [dnsmasq : Manage systemd unit file] **************************************
2025-12-01 11:43:05.793162 | controller | Monday 01 December 2025  11:43:05 +0000 (0:00:00.300)       0:00:07.672 *******
2025-12-01 11:43:05.793172 | controller | skipping: [instance]
2025-12-01 11:43:05.793566 | controller |
2025-12-01 11:43:05.811004 | controller | TASK [dnsmasq : Manage base configuration file] ********************************
2025-12-01 11:43:05.811023 | controller | Monday 01 December 2025  11:43:05 +0000 (0:00:00.018)       0:00:07.690 *******
2025-12-01 11:43:05.811035 | controller | skipping: [instance]
2025-12-01 11:43:05.835122 | controller |
2025-12-01 11:43:05.835147 | controller | TASK [dnsmasq : Render listener configuration] *********************************
2025-12-01 11:43:05.835156 | controller | Monday 01 December 2025  11:43:05 +0000 (0:00:00.017)       0:00:07.708 *******
2025-12-01 11:43:05.835167 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/listener.yml for instance
2025-12-01 11:43:05.851957 | controller |
2025-12-01 11:43:05.851974 | controller | TASK [dnsmasq : Create listener configuration file] ****************************
2025-12-01 11:43:05.851981 | controller | Monday 01 December 2025  11:43:05 +0000 (0:00:00.024)       0:00:07.732 *******
2025-12-01 11:43:05.851990 | controller | skipping: [instance]
2025-12-01 11:43:06.044908 | controller |
2025-12-01 11:43:06.044927 | controller | TASK [dnsmasq : Remove listener configuration file] ****************************
2025-12-01 11:43:06.044935 | controller | Monday 01 December 2025  11:43:05 +0000 (0:00:00.016)       0:00:07.749 *******
2025-12-01 11:43:06.044943 | controller | changed: [instance]
2025-12-01 11:43:06.072885 | controller |
2025-12-01 11:43:06.072909 | controller | TASK [dnsmasq : Render dns configuration] **************************************
2025-12-01 11:43:06.072917 | controller | Monday 01 December 2025  11:43:06 +0000 (0:00:00.192)       0:00:07.942 *******
2025-12-01 11:43:06.072931 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/dns.yml for instance
2025-12-01 11:43:06.090511 | controller |
2025-12-01 11:43:06.090530 | controller | TASK [dnsmasq : Create dns configuration file] *********************************
2025-12-01 11:43:06.090537 | controller | Monday 01 December 2025  11:43:06 +0000 (0:00:00.027)       0:00:07.970 *******
2025-12-01 11:43:06.090546 | controller | skipping: [instance]
2025-12-01 11:43:06.287668 | controller |
2025-12-01 11:43:06.287697 | controller | TASK [dnsmasq : Remove dns configuration file] *********************************
2025-12-01 11:43:06.287706 | controller | Monday 01 December 2025  11:43:06 +0000 (0:00:00.017)       0:00:07.988 *******
2025-12-01 11:43:06.287715 | controller | changed: [instance]
2025-12-01 11:43:06.303199 | controller |
2025-12-01 11:43:06.303237 | controller | TASK [dnsmasq : Add localhost addresses from defined dnsmasq listen addresses to loopback interface] ***
2025-12-01 11:43:06.303248 | controller | Monday 01 December 2025  11:43:06 +0000 (0:00:00.196)       0:00:08.184 *******
2025-12-01 11:43:06.303261 | controller | skipping: [instance]
2025-12-01 11:43:06.322507 | controller |
2025-12-01 11:43:06.322534 | controller | TASK [dnsmasq : Enable and start service] **************************************
2025-12-01 11:43:06.322545 | controller | Monday 01 December 2025  11:43:06 +0000 (0:00:00.015)       0:00:08.200 *******
2025-12-01 11:43:06.322556 | controller | skipping: [instance]
2025-12-01 11:43:06.988563 | controller |
2025-12-01 11:43:06.988594 | controller | TASK [dnsmasq : Stop service] **************************************************
2025-12-01 11:43:06.988603 | controller | Monday 01 December 2025  11:43:06 +0000 (0:00:00.019)       0:00:08.219 *******
2025-12-01 11:43:06.988613 | controller | changed: [instance]
2025-12-01 11:43:07.175483 | controller |
2025-12-01 11:43:07.175506 | controller | TASK [dnsmasq : Remove unit file] **********************************************
2025-12-01 11:43:07.175514 | controller | Monday 01 December 2025  11:43:06 +0000 (0:00:00.665)       0:00:08.885 *******
2025-12-01 11:43:07.175524 | controller | changed: [instance]
2025-12-01 11:43:07.561029 | controller |
2025-12-01 11:43:07.561070 | controller | TASK [dnsmasq : Remove main configuration files] *******************************
2025-12-01 11:43:07.561080 | controller | Monday 01 December 2025  11:43:07 +0000 (0:00:00.187)       0:00:09.072 *******
2025-12-01 11:43:07.561109 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2025-12-01 11:43:07.765167 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.d)
2025-12-01 11:43:07.765195 | controller |
2025-12-01 11:43:07.765203 | controller | TASK [dnsmasq : Remove lease file] *********************************************
2025-12-01 11:43:07.765210 | controller | Monday 01 December 2025  11:43:07 +0000 (0:00:00.385)       0:00:09.458 *******
2025-12-01 11:43:07.765220 | controller | changed: [instance]
2025-12-01 11:43:07.765331 | controller |
2025-12-01 11:43:07.765341 | controller | RUNNING HANDLER [dnsmasq : Restart dnsmasq] ************************************
2025-12-01 11:43:07.765348 | controller | Monday 01 December 2025  11:43:07 +0000 (0:00:00.204)       0:00:09.662 *******
2025-12-01 11:43:08.070164 | controller | ok: [instance]
2025-12-01 11:43:08.072227 | controller |
2025-12-01 11:43:08.072245 | controller | PLAY RECAP *********************************************************************
2025-12-01 11:43:08.072253 | controller | instance                   : ok=24   changed=11   unreachable=0    failed=0    skipped=6    rescued=0    ignored=0
2025-12-01 11:43:08.072259 | controller |
2025-12-01 11:43:08.072265 | controller | Monday 01 December 2025  11:43:08 +0000 (0:00:00.304)       0:00:09.966 *******
2025-12-01 11:43:08.072271 | controller | ===============================================================================
2025-12-01 11:43:08.072280 | controller | dnsmasq : Ensure SELinux knows about the new files and directories ------ 3.26s
2025-12-01 11:43:08.120529 | controller | dnsmasq : Install needed packages --------------------------------------- 1.25s
2025-12-01 11:43:08.120550 | controller | Gathering Facts --------------------------------------------------------- 0.83s
2025-12-01 11:43:08.120558 | controller | dnsmasq : Stop service -------------------------------------------------- 0.67s
2025-12-01 11:43:08.120568 | controller | Copy generated content in ci-framework-data/artifacts ------------------- 0.56s
2025-12-01 11:43:08.120574 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.41s
2025-12-01 11:43:08.120579 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.39s
2025-12-01 11:43:08.120585 | controller | dnsmasq : Remove main configuration files ------------------------------- 0.39s
2025-12-01 11:43:08.120590 | controller | dnsmasq : Restart dnsmasq ----------------------------------------------- 0.30s
2025-12-01 11:43:08.120596 | controller | dnsmasq : Manage configuration directory -------------------------------- 0.30s
2025-12-01 11:43:08.120601 | controller | dnsmasq : Add/Remove forwarder ------------------------------------------ 0.29s
2025-12-01 11:43:08.120611 | controller | dnsmasq : Remove lease file --------------------------------------------- 0.20s
2025-12-01 11:43:08.120616 | controller | dnsmasq : Remove dns configuration file --------------------------------- 0.20s
2025-12-01 11:43:08.120622 | controller | dnsmasq : Remove listener configuration file ---------------------------- 0.19s
2025-12-01 11:43:08.120627 | controller | dnsmasq : Remove unit file ---------------------------------------------- 0.19s
2025-12-01 11:43:08.120632 | controller | dnsmasq : Assert each address element have needed data ------------------ 0.08s
2025-12-01 11:43:08.120638 | controller | dnsmasq : Assert each address element have needed data ------------------ 0.05s
2025-12-01 11:43:08.120644 | controller | dnsmasq : Assert each forwarder element have needed data ---------------- 0.04s
2025-12-01 11:43:08.120649 | controller | dnsmasq : Assert we have needed host record data ------------------------ 0.03s
2025-12-01 11:43:08.120654 | controller | dnsmasq : Assert we have needed forwarder data -------------------------- 0.03s
2025-12-01 11:43:08.120663 | controller | INFO     Writing /tmp/report.html report.
2025-12-01 11:43:08.574946 | controller | changed
2025-12-01 11:43:08.597457 | 
2025-12-01 11:43:08.597520 | PLAY RECAP
2025-12-01 11:43:08.597568 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-01 11:43:08.597597 | 
2025-12-01 11:43:08.659505 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-01 11:43:08.660246 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-01 11:43:09.158209 | 
2025-12-01 11:43:09.158331 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-01 11:43:09.176481 | 
2025-12-01 11:43:09.176550 | TASK [Filter out host if needed]
2025-12-01 11:43:09.184106 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-01 11:43:09.188098 | 
2025-12-01 11:43:09.188160 | TASK [Ensure file is present]
2025-12-01 11:43:09.469820 | controller | ok
2025-12-01 11:43:09.476449 | 
2025-12-01 11:43:09.476516 | TASK [Manage molecule report file]
2025-12-01 11:43:09.998742 | controller | changed
2025-12-01 11:43:10.003501 | 
2025-12-01 11:43:10.003562 | TASK [Check if we get ci-framework-data basedir]
2025-12-01 11:43:10.173423 | controller | ok
2025-12-01 11:43:10.177939 | 
2025-12-01 11:43:10.177999 | TASK [Create ci-framework-data log directory for zuul]
2025-12-01 11:43:10.459891 | controller | changed
2025-12-01 11:43:10.464623 | 
2025-12-01 11:43:10.464691 | TASK [Copy ci-framework interesting files]
2025-12-01 11:43:10.646366 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-12-01 11:43:10.986091 | controller | changed
2025-12-01 11:43:10.990785 | 
2025-12-01 11:43:10.990844 | TASK [Get SELinux listing]
2025-12-01 11:43:11.513848 | controller | changed
2025-12-01 11:43:11.518677 | 
2025-12-01 11:43:11.518748 | TASK [Generate log index]
2025-12-01 11:43:12.145297 | controller | changed
2025-12-01 11:43:12.149639 | 
2025-12-01 11:43:12.149712 | TASK [Get some env related data]
2025-12-01 11:43:12.729954 | controller | /home/zuul/.local/bin/ansible
2025-12-01 11:43:13.672783 | controller | changed
2025-12-01 11:43:13.677546 | 
2025-12-01 11:43:13.677604 | TASK [Generate list of logs to collect in home directory]
2025-12-01 11:43:13.959573 | controller | ok: All paths examined
2025-12-01 11:43:13.964323 | 
2025-12-01 11:43:13.964379 | LOOP [Copy logs from home directory]
2025-12-01 11:43:14.310019 | controller | changed:
2025-12-01 11:43:14.310132 | controller | {
2025-12-01 11:43:14.310160 | controller |   "atime": 1764589263.334265,
2025-12-01 11:43:14.310179 | controller |   "ctime": 1764589279.6034276,
2025-12-01 11:43:14.310197 | controller |   "dev": 64513,
2025-12-01 11:43:14.310214 | controller |   "gid": 1000,
2025-12-01 11:43:14.310230 | controller |   "gr_name": "zuul",
2025-12-01 11:43:14.310246 | controller |   "inode": 4336065,
2025-12-01 11:43:14.310261 | controller |   "isblk": false,
2025-12-01 11:43:14.310275 | controller |   "ischr": false,
2025-12-01 11:43:14.310289 | controller |   "isdir": false,
2025-12-01 11:43:14.310305 | controller |   "isfifo": false,
2025-12-01 11:43:14.310320 | controller |   "isgid": false,
2025-12-01 11:43:14.310335 | controller |   "islnk": false,
2025-12-01 11:43:14.310349 | controller |   "isreg": true,
2025-12-01 11:43:14.310362 | controller |   "issock": false,
2025-12-01 11:43:14.310376 | controller |   "isuid": false,
2025-12-01 11:43:14.310389 | controller |   "mode": "0644",
2025-12-01 11:43:14.310403 | controller |   "mtime": 1764589279.6034276,
2025-12-01 11:43:14.310417 | controller |   "nlink": 1,
2025-12-01 11:43:14.310432 | controller |   "path": "/home/zuul/ansible.log",
2025-12-01 11:43:14.310448 | controller |   "pw_name": "zuul",
2025-12-01 11:43:14.310463 | controller |   "rgrp": true,
2025-12-01 11:43:14.310477 | controller |   "roth": true,
2025-12-01 11:43:14.310491 | controller |   "rusr": true,
2025-12-01 11:43:14.310504 | controller |   "size": 6749,
2025-12-01 11:43:14.310518 | controller |   "uid": 1000,
2025-12-01 11:43:14.310532 | controller |   "wgrp": false,
2025-12-01 11:43:14.310546 | controller |   "woth": false,
2025-12-01 11:43:14.310562 | controller |   "wusr": true,
2025-12-01 11:43:14.310576 | controller |   "xgrp": false,
2025-12-01 11:43:14.310590 | controller |   "xoth": false,
2025-12-01 11:43:14.310603 | controller |   "xusr": false
2025-12-01 11:43:14.310617 | controller | }
2025-12-01 11:43:14.324467 | 
2025-12-01 11:43:14.324532 | TASK [Copy crio stats log file]
2025-12-01 11:43:14.337978 | controller | skipping: Conditional result was False
2025-12-01 11:43:14.343018 | 
2025-12-01 11:43:14.343078 | TASK [Get SELinux related data]
2025-12-01 11:43:14.555460 | controller | <no matches>
2025-12-01 11:43:14.869122 | controller | ERROR
2025-12-01 11:43:14.869258 | controller | {
2025-12-01 11:43:14.869292 | controller |   "delta": "0:00:00.011124",
2025-12-01 11:43:14.869316 | controller |   "end": "2025-12-01 11:43:14.556506",
2025-12-01 11:43:14.869336 | controller |   "msg": "non-zero return code",
2025-12-01 11:43:14.869355 | controller |   "rc": 1,
2025-12-01 11:43:14.869373 | controller |   "start": "2025-12-01 11:43:14.545382"
2025-12-01 11:43:14.869390 | controller | }
2025-12-01 11:43:14.869422 | controller | ERROR: Ignoring Errors
2025-12-01 11:43:14.874059 | 
2025-12-01 11:43:14.874116 | TASK [Create system configuration directory]
2025-12-01 11:43:15.064403 | controller | changed
2025-12-01 11:43:15.069019 | 
2025-12-01 11:43:15.069081 | TASK [Get some of the system configurations]
2025-12-01 11:43:15.594621 | controller | changed
2025-12-01 11:43:15.599094 | 
2025-12-01 11:43:15.599150 | TASK [Copy generated documentation if available]
2025-12-01 11:43:15.611897 | controller | skipping: Conditional result was False
2025-12-01 11:43:15.616529 | 
2025-12-01 11:43:15.616583 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-01 11:43:15.629038 | controller | skipping: Conditional result was False
2025-12-01 11:43:15.633767 | 
2025-12-01 11:43:15.633822 | TASK [Compress logs bigger than 2MB]
2025-12-01 11:43:16.156557 | controller | changed
2025-12-01 11:43:16.162120 | 
2025-12-01 11:43:16.162193 | TASK [Copy files from workspace on node]
2025-12-01 11:43:16.180770 | controller | ok
2025-12-01 11:43:16.202104 | 
2025-12-01 11:43:16.202189 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-01 11:43:16.215960 | controller | skipping: Conditional result was False
2025-12-01 11:43:16.221483 | 
2025-12-01 11:43:16.221548 | TASK [fetch-output : Set log path for single node]
2025-12-01 11:43:16.250106 | controller | ok
2025-12-01 11:43:16.255289 | 
2025-12-01 11:43:16.255381 | LOOP [fetch-output : Ensure local output dirs]
2025-12-01 11:43:16.450176 | controller -> localhost | ok: "/var/lib/zuul/builds/3184df9b0acf4e5daaea05dd22299350/work/logs"
2025-12-01 11:43:16.450436 | controller -> localhost | changed: All items complete
2025-12-01 11:43:16.450470 | 
2025-12-01 11:43:16.624050 | controller -> localhost | changed: "/var/lib/zuul/builds/3184df9b0acf4e5daaea05dd22299350/work/artifacts"
2025-12-01 11:43:16.796428 | controller -> localhost | changed: "/var/lib/zuul/builds/3184df9b0acf4e5daaea05dd22299350/work/docs"
2025-12-01 11:43:16.805617 | 
2025-12-01 11:43:16.805733 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-01 11:43:17.334844 | controller | changed:
2025-12-01 11:43:17.335036 | controller | .d..t...... ./
2025-12-01 11:43:17.335067 | controller | >f+++++++++ README.html
2025-12-01 11:43:17.335087 | controller | >f+++++++++ ansible-execution.log
2025-12-01 11:43:17.335109 | controller | >f+++++++++ ansible.log
2025-12-01 11:43:17.335129 | controller | >f+++++++++ dmesg.log
2025-12-01 11:43:17.335147 | controller | >f+++++++++ installed-pkgs.log
2025-12-01 11:43:17.335167 | controller | >f+++++++++ python.log
2025-12-01 11:43:17.335185 | controller | >f+++++++++ registries.conf
2025-12-01 11:43:17.335202 | controller | >f+++++++++ report.html
2025-12-01 11:43:17.335221 | controller | >f+++++++++ selinux-denials.log
2025-12-01 11:43:17.335238 | controller | >f+++++++++ selinux-listing.log
2025-12-01 11:43:17.335254 | controller | cd+++++++++ ci-framework-data/
2025-12-01 11:43:17.335269 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-01 11:43:17.335284 | controller | >f+++++++++ ci-framework-data/artifacts/addresses.conf
2025-12-01 11:43:17.335300 | controller | >f+++++++++ ci-framework-data/artifacts/cifmw-dnsmasq.conf
2025-12-01 11:43:17.335315 | controller | >f+++++++++ ci-framework-data/artifacts/dns.conf
2025-12-01 11:43:17.335329 | controller | >f+++++++++ ci-framework-data/artifacts/forwarders.conf
2025-12-01 11:43:17.335343 | controller | >f+++++++++ ci-framework-data/artifacts/host_records.conf
2025-12-01 11:43:17.335358 | controller | >f+++++++++ ci-framework-data/artifacts/listener.conf
2025-12-01 11:43:17.335372 | controller | >f+++++++++ ci-framework-data/artifacts/oldies.conf
2025-12-01 11:43:17.335386 | controller | >f+++++++++ ci-framework-data/artifacts/startrek.conf
2025-12-01 11:43:17.335400 | controller | >f+++++++++ ci-framework-data/artifacts/starwars.conf
2025-12-01 11:43:17.335414 | controller | >f+++++++++ ci-framework-data/artifacts/test-dhcp-options.conf
2025-12-01 11:43:17.335429 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-01 11:43:17.335443 | controller | cd+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/
2025-12-01 11:43:17.335457 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/startrek_data_0a:19:02:f8:4c:b3
2025-12-01 11:43:17.335481 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/startrek_spock_0a:19:02:f8:4c:a8
2025-12-01 11:43:17.335497 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/starwars_0a:19:02:f8:4c:a7
2025-12-01 11:43:17.335512 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/starwars_c3po_0a:19:02:f8:4c:b2
2025-12-01 11:43:17.335527 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/starwars_r2d2_0a:19:02:f8:4c:b1
2025-12-01 11:43:17.335541 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/starwars_solo_0a:19:02:f8:4c:a8
2025-12-01 11:43:17.335555 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-01 11:43:17.335569 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-01 11:43:17.335685 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-12-01 11:43:17.335716 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-12-01 11:43:17.335736 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-01 11:43:17.335753 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-12-01 11:43:17.335769 | controller | cd+++++++++ registries.conf.d/
2025-12-01 11:43:17.335784 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-01 11:43:17.335800 | controller | cd+++++++++ system-config/
2025-12-01 11:43:17.335815 | controller | cd+++++++++ system-config/libvirt/
2025-12-01 11:43:17.335829 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-01 11:43:17.335845 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-01 11:43:17.693116 | controller | changed: .d..t...... ./
2025-12-01 11:43:18.048726 | controller | changed: .d..t...... ./
2025-12-01 11:43:18.066145 | 
2025-12-01 11:43:18.066237 | TASK [Return artifact to Zuul]
2025-12-01 11:43:18.093766 | controller | ok
2025-12-01 11:43:18.111145 | 
2025-12-01 11:43:18.111199 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-01 11:43:18.111295 | 
2025-12-01 11:43:18.111322 | PLAY RECAP
2025-12-01 11:43:18.111357 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-12-01 11:43:18.111377 | 
2025-12-01 11:43:18.176999 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-01 11:43:18.177691 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-01 11:43:18.641857 | 
2025-12-01 11:43:18.641991 | PLAY [all]
2025-12-01 11:43:18.658572 | 
2025-12-01 11:43:18.658640 | TASK [include_role : fetch-output]
2025-12-01 11:43:18.686248 | controller | ok
2025-12-01 11:43:18.701265 | 
2025-12-01 11:43:18.701342 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-01 11:43:18.735051 | controller | skipping: Conditional result was False
2025-12-01 11:43:18.740158 | 
2025-12-01 11:43:18.740222 | TASK [fetch-output : Set log path for single node]
2025-12-01 11:43:18.767647 | controller | ok
2025-12-01 11:43:18.772131 | 
2025-12-01 11:43:18.772195 | LOOP [fetch-output : Ensure local output dirs]
2025-12-01 11:43:19.066812 | controller -> localhost | ok: "/var/lib/zuul/builds/3184df9b0acf4e5daaea05dd22299350/work/logs"
2025-12-01 11:43:19.242022 | controller -> localhost | ok: "/var/lib/zuul/builds/3184df9b0acf4e5daaea05dd22299350/work/artifacts"
2025-12-01 11:43:19.415298 | controller -> localhost | ok: "/var/lib/zuul/builds/3184df9b0acf4e5daaea05dd22299350/work/docs"
2025-12-01 11:43:19.429011 | 
2025-12-01 11:43:19.429100 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-01 11:43:19.968580 | controller | ok
2025-12-01 11:43:19.968894 | controller | ok: All items complete
2025-12-01 11:43:19.968961 | 
2025-12-01 11:43:20.341269 | controller | ok
2025-12-01 11:43:20.699413 | controller | ok
2025-12-01 11:43:20.718622 | 
2025-12-01 11:43:20.718702 | TASK [include_role : fetch-output-openshift]
2025-12-01 11:43:20.731988 | controller | skipping: Conditional result was False
2025-12-01 11:43:20.737536 | 
2025-12-01 11:43:20.737605 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-01 11:43:21.052841 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.005134
2025-12-01 11:43:21.226486 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005060
2025-12-01 11:43:21.261637 | 
2025-12-01 11:43:21.261715 | PLAY [all]
2025-12-01 11:43:21.275188 | 
2025-12-01 11:43:21.275254 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-01 11:43:21.668249 | controller | changed
2025-12-01 11:43:21.689034 | 
2025-12-01 11:43:21.689089 | PLAY RECAP
2025-12-01 11:43:21.689130 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-01 11:43:21.689153 | 
2025-12-01 11:43:21.753231 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-01 11:43:21.753990 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-01 11:43:22.271256 | 
2025-12-01 11:43:22.271358 | PLAY [localhost]
2025-12-01 11:43:22.287645 | 
2025-12-01 11:43:22.287725 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-01 11:43:22.568765 | localhost | changed
2025-12-01 11:43:22.573016 | 
2025-12-01 11:43:22.573096 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-01 11:43:22.590561 | localhost | ok
2025-12-01 11:43:22.597719 | 
2025-12-01 11:43:22.597786 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-01 11:43:22.868129 | localhost | changed
2025-12-01 11:43:22.873218 | 
2025-12-01 11:43:22.873286 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-01 11:43:23.365793 | localhost | changed
2025-12-01 11:43:23.370776 | 
2025-12-01 11:43:23.370844 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-01 11:43:23.671552 | localhost | Identity added: /var/lib/zuul/builds/3184df9b0acf4e5daaea05dd22299350/work/tmp/ansible.zyb7vkvx (/var/lib/zuul/builds/3184df9b0acf4e5daaea05dd22299350/work/tmp/ansible.zyb7vkvx)
2025-12-01 11:43:23.671706 | localhost | ok: Runtime: 0:00:00.005732
2025-12-01 11:43:23.675728 | 
2025-12-01 11:43:23.675793 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-01 11:43:23.874742 | localhost | ok: Runtime: 0:00:00.004574
2025-12-01 11:43:23.879098 | 
2025-12-01 11:43:23.879165 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-01 11:43:23.926593 | localhost | changed
2025-12-01 11:43:23.931126 | 
2025-12-01 11:43:23.931187 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-01 11:43:24.222487 | localhost | changed
2025-12-01 11:43:24.240267 | 
2025-12-01 11:43:24.240317 | PLAY [localhost]
2025-12-01 11:43:24.250382 | 
2025-12-01 11:43:24.250439 | TASK [Generate bulk log download script]
2025-12-01 11:43:24.267951 | localhost | ok
2025-12-01 11:43:24.278106 | 
2025-12-01 11:43:24.278171 | TASK [local-log-download : Check API endpoint is defined]
2025-12-01 11:43:24.304735 | localhost | ok: All assertions passed
2025-12-01 11:43:24.308617 | 
2025-12-01 11:43:24.308709 | TASK [local-log-download : Create download script]
2025-12-01 11:43:24.636167 | localhost -> localhost | changed
2025-12-01 11:43:24.643897 | 
2025-12-01 11:43:24.643975 | TASK [Register quick-download link]
2025-12-01 11:43:24.660471 | localhost | ok
2025-12-01 11:43:24.691155 | 
2025-12-01 11:43:24.691224 | PLAY [logserver.rdoproject.org]
2025-12-01 11:43:24.699781 | 
2025-12-01 11:43:24.699837 | TASK [Set zuul-log-path fact]
2025-12-01 11:43:24.715945 | logserver.rdoproject.org | ok
2025-12-01 11:43:24.724122 | 
2025-12-01 11:43:24.724183 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-01 11:43:24.740152 | logserver.rdoproject.org | ok
2025-12-01 11:43:24.744854 | 
2025-12-01 11:43:24.744932 | TASK [upload-logs : Create log directories]
2025-12-01 11:43:26.399722 | logserver.rdoproject.org | changed
2025-12-01 11:43:26.402655 | 
2025-12-01 11:43:26.402719 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-01 11:43:26.599022 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004138
2025-12-01 11:43:26.602988 | 
2025-12-01 11:43:26.603045 | TASK [upload-logs : Upload logs to log server]
2025-12-01 11:43:28.012368 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-01 11:43:28.015151 | 
2025-12-01 11:43:28.015212 | LOOP [upload-logs : Compress console log and json output]
2025-12-01 11:43:28.048939 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 11:43:28.056384 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 11:43:28.062759 | 
2025-12-01 11:43:28.062849 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-01 11:43:28.095012 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 11:43:28.095203 | 
2025-12-01 11:43:28.098144 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 11:43:28.109320 | 
2025-12-01 11:43:28.109400 | LOOP [upload-logs : Upload console log and json output]