2025-11-26 22:30:43.082474 | Job console starting...
2025-11-26 22:30:43.289720 | Updating repositories
2025-11-26 22:30:44.038586 | Preparing job workspace
2025-11-26 22:30:51.731033 | Running Ansible setup...
2025-11-26 22:30:54.576747 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-26 22:30:55.060556 | 
2025-11-26 22:30:55.060653 | PLAY [localhost]
2025-11-26 22:30:55.068915 | 
2025-11-26 22:30:55.068981 | TASK [Gathering Facts]
2025-11-26 22:30:55.886421 | localhost | ok
2025-11-26 22:30:55.898841 | 
2025-11-26 22:30:55.898918 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-26 22:30:56.223209 | localhost -> localhost | changed
2025-11-26 22:30:56.229630 | 
2025-11-26 22:30:56.229704 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-26 22:30:56.891172 | localhost -> localhost | changed
2025-11-26 22:30:56.903654 | 
2025-11-26 22:30:56.903724 | TASK [Setup log path fact]
2025-11-26 22:30:56.920227 | localhost | ok
2025-11-26 22:30:56.930507 | 
2025-11-26 22:30:56.930568 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-26 22:30:56.947914 | localhost | ok
2025-11-26 22:30:56.954786 | 
2025-11-26 22:30:56.954848 | TASK [emit-job-header : Print job information]
2025-11-26 22:30:56.981743 | # Job Information
2025-11-26 22:30:56.981852 | Ansible Version: 2.15.12
2025-11-26 22:30:56.981879 | Job: cifmw-molecule-ci_gen_kustomize_values
2025-11-26 22:30:56.981900 | Pipeline: github-check
2025-11-26 22:30:56.981918 | Executor: ibm-bm3-ze.softwarefactory-project.io
2025-11-26 22:30:56.981935 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3476
2025-11-26 22:30:56.981951 | Log URL (when completed): https://logserver.rdoproject.org/cc1/rdoproject.org/cc11b9144c8e4896be58e75cd128f17a/
2025-11-26 22:30:56.981968 | Event ID: 5f9e94c0-cb17-11f0-8a21-aa2834a2f9a7
2025-11-26 22:30:56.985331 | 
2025-11-26 22:30:56.985405 | LOOP [emit-job-header : Print node information]
2025-11-26 22:30:57.076523 | localhost | ok:
2025-11-26 22:30:57.076693 | localhost | # Node Information
2025-11-26 22:30:57.076723 | localhost | Inventory Hostname: controller
2025-11-26 22:30:57.076748 | localhost | Hostname: np0005537215
2025-11-26 22:30:57.076770 | localhost | Username: zuul
2025-11-26 22:30:57.076792 | localhost | Distro: CentOS 9
2025-11-26 22:30:57.076811 | localhost | Provider: ibm-bm3-nodepool
2025-11-26 22:30:57.076830 | localhost | Region: regionOne
2025-11-26 22:30:57.076847 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-11-26 22:30:57.076864 | localhost | Product Name: OpenStack Compute
2025-11-26 22:30:57.076882 | localhost | Interface IP: 192.168.25.96
2025-11-26 22:30:57.101459 | 
2025-11-26 22:30:57.101537 | PLAY [all]
2025-11-26 22:30:57.107244 | 
2025-11-26 22:30:57.107301 | TASK [Gather network facts]
2025-11-26 22:30:57.465494 | controller | ok
2025-11-26 22:30:57.479228 | 
2025-11-26 22:30:57.479292 | TASK [include_role : start-zuul-console]
2025-11-26 22:30:57.497409 | controller | ok
2025-11-26 22:30:57.509332 | 
2025-11-26 22:30:57.509410 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-26 22:30:57.791533 | controller | ok
2025-11-26 22:30:57.799735 | 
2025-11-26 22:30:57.799799 | TASK [include_role : add-build-sshkey]
2025-11-26 22:30:57.819757 | controller | ok
2025-11-26 22:30:57.833283 | 
2025-11-26 22:30:57.833369 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-26 22:30:58.030588 | controller -> localhost | ok
2025-11-26 22:30:58.035698 | 
2025-11-26 22:30:58.035758 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-26 22:30:58.053257 | controller | ok
2025-11-26 22:30:58.065643 | controller | included: /var/lib/zuul/builds/cc11b9144c8e4896be58e75cd128f17a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-26 22:30:58.070647 | 
2025-11-26 22:30:58.070706 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-26 22:30:58.459947 | controller -> localhost | Generating public/private rsa key pair.
2025-11-26 22:30:58.460122 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/cc11b9144c8e4896be58e75cd128f17a/work/cc11b9144c8e4896be58e75cd128f17a_id_rsa.
2025-11-26 22:30:58.460161 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/cc11b9144c8e4896be58e75cd128f17a/work/cc11b9144c8e4896be58e75cd128f17a_id_rsa.pub.
2025-11-26 22:30:58.460185 | controller -> localhost | The key fingerprint is:
2025-11-26 22:30:58.460206 | controller -> localhost | SHA256:XW1RFeWLQG6Y5DkUQVLnGVG3brZaqH0FYeG1iZweDaI zuul-build-sshkey
2025-11-26 22:30:58.460225 | controller -> localhost | The key's randomart image is:
2025-11-26 22:30:58.460243 | controller -> localhost | +---[RSA 3072]----+
2025-11-26 22:30:58.460262 | controller -> localhost | |       .oBo*ooo=O|
2025-11-26 22:30:58.460281 | controller -> localhost | |        = X =oO++|
2025-11-26 22:30:58.460298 | controller -> localhost | |         E *.*+*.|
2025-11-26 22:30:58.460316 | controller -> localhost | |         .o.o.= .|
2025-11-26 22:30:58.460333 | controller -> localhost | |        S .  o * |
2025-11-26 22:30:58.460349 | controller -> localhost | |              + o|
2025-11-26 22:30:58.460367 | controller -> localhost | |             . o.|
2025-11-26 22:30:58.460408 | controller -> localhost | |            o o. |
2025-11-26 22:30:58.460435 | controller -> localhost | |           . o.  |
2025-11-26 22:30:58.460454 | controller -> localhost | +----[SHA256]-----+
2025-11-26 22:30:58.460495 | controller -> localhost | ok: Runtime: 0:00:00.059597
2025-11-26 22:30:58.465632 | 
2025-11-26 22:30:58.465691 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-26 22:30:58.483197 | controller | ok
2025-11-26 22:30:58.492861 | controller | included: /var/lib/zuul/builds/cc11b9144c8e4896be58e75cd128f17a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-26 22:30:58.499809 | 
2025-11-26 22:30:58.499867 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-26 22:30:58.514491 | controller | skipping: Conditional result was False
2025-11-26 22:30:58.519329 | 
2025-11-26 22:30:58.519413 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-26 22:30:58.871711 | controller | changed
2025-11-26 22:30:58.877628 | 
2025-11-26 22:30:58.877690 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-26 22:30:59.075071 | controller | ok
2025-11-26 22:30:59.080026 | 
2025-11-26 22:30:59.080099 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-26 22:30:59.615265 | controller | changed
2025-11-26 22:30:59.619946 | 
2025-11-26 22:30:59.620011 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-26 22:31:00.188719 | controller | changed
2025-11-26 22:31:00.193519 | 
2025-11-26 22:31:00.193582 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-26 22:31:00.207439 | controller | skipping: Conditional result was False
2025-11-26 22:31:00.212518 | 
2025-11-26 22:31:00.212584 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-26 22:31:00.500592 | controller -> localhost | changed
2025-11-26 22:31:00.509016 | 
2025-11-26 22:31:00.509083 | TASK [add-build-sshkey : Add back temp key]
2025-11-26 22:31:00.736282 | controller -> localhost | Identity added: /var/lib/zuul/builds/cc11b9144c8e4896be58e75cd128f17a/work/cc11b9144c8e4896be58e75cd128f17a_id_rsa (zuul-build-sshkey)
2025-11-26 22:31:00.736467 | controller -> localhost | ok: Runtime: 0:00:00.007083
2025-11-26 22:31:00.741533 | 
2025-11-26 22:31:00.741598 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-26 22:31:01.035973 | controller | ok
2025-11-26 22:31:01.040471 | 
2025-11-26 22:31:01.040551 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-26 22:31:01.065086 | controller | skipping: Conditional result was False
2025-11-26 22:31:01.073711 | 
2025-11-26 22:31:01.073772 | TASK [include_role : validate-host]
2025-11-26 22:31:01.091133 | controller | ok
2025-11-26 22:31:01.115506 | 
2025-11-26 22:31:01.115577 | TASK [validate-host : Define zuul_info_dir fact]
2025-11-26 22:31:01.143826 | controller | ok
2025-11-26 22:31:01.148679 | 
2025-11-26 22:31:01.148744 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-11-26 22:31:01.360715 | controller -> localhost | ok
2025-11-26 22:31:01.365899 | 
2025-11-26 22:31:01.365962 | TASK [validate-host : Collect information about the host]
2025-11-26 22:31:01.972617 | controller | ok
2025-11-26 22:31:01.982021 | 
2025-11-26 22:31:01.982136 | TASK [validate-host : Sanitize hostname]
2025-11-26 22:31:02.039885 | controller | ok
2025-11-26 22:31:02.044661 | 
2025-11-26 22:31:02.044725 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-11-26 22:31:02.426947 | controller -> localhost | changed
2025-11-26 22:31:02.449735 | 
2025-11-26 22:31:02.449815 | TASK [validate-host : Collect information about zuul worker]
2025-11-26 22:31:02.774272 | controller | ok
2025-11-26 22:31:02.778695 | 
2025-11-26 22:31:02.778759 | TASK [validate-host : Write out all zuul information for each host]
2025-11-26 22:31:03.126023 | controller -> localhost | changed
2025-11-26 22:31:03.134480 | 
2025-11-26 22:31:03.134545 | TASK [include_role : prepare-workspace-openshift]
2025-11-26 22:31:03.147775 | controller | skipping: Conditional result was False
2025-11-26 22:31:03.152659 | 
2025-11-26 22:31:03.152723 | TASK [include_role : remove-zuul-sshkey]
2025-11-26 22:31:03.166313 | controller | skipping: Conditional result was False
2025-11-26 22:31:03.171144 | 
2025-11-26 22:31:03.171211 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-26 22:31:03.363207 | controller | ok: "logs"
2025-11-26 22:31:03.363438 | controller | ok: All items complete
2025-11-26 22:31:03.363470 | 
2025-11-26 22:31:03.527762 | controller | ok: "artifacts"
2025-11-26 22:31:03.705278 | controller | ok: "docs"
2025-11-26 22:31:03.717261 | 
2025-11-26 22:31:03.717355 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-26 22:31:03.904367 | controller | changed: "logs"
2025-11-26 22:31:04.082816 | controller | changed: "artifacts"
2025-11-26 22:31:04.252758 | controller | changed: "docs"
2025-11-26 22:31:04.282747 | 
2025-11-26 22:31:04.282845 | PLAY RECAP
2025-11-26 22:31:04.282894 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-26 22:31:04.282924 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-26 22:31:04.282945 | 
2025-11-26 22:31:04.365907 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-26 22:31:04.367039 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-26 22:31:04.894223 | 
2025-11-26 22:31:04.894318 | PLAY [all]
2025-11-26 22:31:04.912222 | 
2025-11-26 22:31:04.912297 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-26 22:31:04.960671 | controller | ok
2025-11-26 22:31:04.965330 | 
2025-11-26 22:31:04.965425 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-26 22:31:05.287530 | controller | changed
2025-11-26 22:31:05.292781 | 
2025-11-26 22:31:05.292853 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-26 22:31:06.079703 | controller | changed
2025-11-26 22:31:06.089067 | 
2025-11-26 22:31:06.089138 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-11-26 22:31:06.458326 | controller | changed:
2025-11-26 22:31:06.459298 | controller | {
2025-11-26 22:31:06.459356 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-11-26 22:31:06.459423 | controller | }
2025-11-26 22:31:06.666626 | controller | changed:
2025-11-26 22:31:06.666721 | controller | {
2025-11-26 22:31:06.666752 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-11-26 22:31:06.666776 | controller | }
2025-11-26 22:31:06.872272 | controller | changed:
2025-11-26 22:31:06.872354 | controller | {
2025-11-26 22:31:06.872397 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-11-26 22:31:06.872443 | controller | }
2025-11-26 22:31:07.084181 | controller | changed:
2025-11-26 22:31:07.084246 | controller | {
2025-11-26 22:31:07.084275 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-11-26 22:31:07.084297 | controller | }
2025-11-26 22:31:07.296745 | controller | changed:
2025-11-26 22:31:07.296805 | controller | {
2025-11-26 22:31:07.296833 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-11-26 22:31:07.296855 | controller | }
2025-11-26 22:31:07.501486 | controller | changed:
2025-11-26 22:31:07.501692 | controller | {
2025-11-26 22:31:07.501798 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-11-26 22:31:07.501828 | controller | }
2025-11-26 22:31:07.702526 | controller | changed:
2025-11-26 22:31:07.702656 | controller | {
2025-11-26 22:31:07.702685 | 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-11-26 22:31:07.702707 | controller | }
2025-11-26 22:31:07.914824 | controller | changed:
2025-11-26 22:31:07.914941 | controller | {
2025-11-26 22:31:07.914971 | 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-11-26 22:31:07.914994 | controller | }
2025-11-26 22:31:08.114784 | controller | changed:
2025-11-26 22:31:08.114886 | controller | {
2025-11-26 22:31:08.114913 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-11-26 22:31:08.114934 | controller | }
2025-11-26 22:31:08.319795 | controller | changed:
2025-11-26 22:31:08.319898 | controller | {
2025-11-26 22:31:08.319926 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-11-26 22:31:08.319946 | controller | }
2025-11-26 22:31:08.518494 | controller | changed:
2025-11-26 22:31:08.518629 | controller | {
2025-11-26 22:31:08.518658 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-11-26 22:31:08.518680 | controller | }
2025-11-26 22:31:08.736245 | controller | changed:
2025-11-26 22:31:08.736343 | controller | {
2025-11-26 22:31:08.736371 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-11-26 22:31:08.736415 | controller | }
2025-11-26 22:31:08.910918 | controller | changed:
2025-11-26 22:31:08.911082 | controller | {
2025-11-26 22:31:08.911111 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-11-26 22:31:08.911134 | controller | }
2025-11-26 22:31:09.106675 | controller | changed:
2025-11-26 22:31:09.106817 | controller | {
2025-11-26 22:31:09.106859 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-11-26 22:31:09.106883 | controller | }
2025-11-26 22:31:09.306126 | controller | changed:
2025-11-26 22:31:09.306248 | controller | {
2025-11-26 22:31:09.306275 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-11-26 22:31:09.306296 | controller | }
2025-11-26 22:31:09.508287 | controller | changed:
2025-11-26 22:31:09.508356 | controller | {
2025-11-26 22:31:09.508403 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-11-26 22:31:09.508433 | controller | }
2025-11-26 22:31:09.707718 | controller | changed:
2025-11-26 22:31:09.707812 | controller | {
2025-11-26 22:31:09.707837 | 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-11-26 22:31:09.707857 | controller | }
2025-11-26 22:31:09.908967 | controller | changed:
2025-11-26 22:31:09.909153 | controller | {
2025-11-26 22:31:09.909185 | 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-11-26 22:31:09.909214 | controller | }
2025-11-26 22:31:10.111675 | controller | changed:
2025-11-26 22:31:10.111850 | controller | {
2025-11-26 22:31:10.111881 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-11-26 22:31:10.111904 | controller | }
2025-11-26 22:31:10.307950 | controller | changed:
2025-11-26 22:31:10.308133 | controller | {
2025-11-26 22:31:10.308165 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-11-26 22:31:10.308188 | controller | }
2025-11-26 22:31:10.522599 | controller | changed:
2025-11-26 22:31:10.522723 | controller | {
2025-11-26 22:31:10.522752 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-11-26 22:31:10.522774 | controller | }
2025-11-26 22:31:10.722932 | controller | changed:
2025-11-26 22:31:10.723004 | controller | {
2025-11-26 22:31:10.723032 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-11-26 22:31:10.723065 | controller | }
2025-11-26 22:31:10.922127 | controller | changed:
2025-11-26 22:31:10.922234 | controller | {
2025-11-26 22:31:10.922262 | 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-11-26 22:31:10.922284 | controller | }
2025-11-26 22:31:11.128627 | controller | changed:
2025-11-26 22:31:11.128723 | controller | {
2025-11-26 22:31:11.128750 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-11-26 22:31:11.128771 | controller | }
2025-11-26 22:31:11.328010 | controller | changed:
2025-11-26 22:31:11.328114 | controller | {
2025-11-26 22:31:11.328143 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-11-26 22:31:11.328164 | controller | }
2025-11-26 22:31:11.530334 | controller | changed:
2025-11-26 22:31:11.530442 | controller | {
2025-11-26 22:31:11.530472 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-11-26 22:31:11.530494 | controller | }
2025-11-26 22:31:11.547225 | 
2025-11-26 22:31:11.547328 | TASK [Set timezone to UTC]
2025-11-26 22:31:11.930985 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-11-26 22:31:11.938028 | 
2025-11-26 22:31:11.938152 | TASK [Create nodepool directory]
2025-11-26 22:31:12.141540 | controller | changed
2025-11-26 22:31:12.147521 | 
2025-11-26 22:31:12.147609 | TASK [Create nodepool sub_nodes file]
2025-11-26 22:31:12.613072 | controller | changed
2025-11-26 22:31:12.627460 | 
2025-11-26 22:31:12.627545 | TASK [Create nodepool sub_nodes_private file]
2025-11-26 22:31:13.083910 | controller | changed
2025-11-26 22:31:13.089969 | 
2025-11-26 22:31:13.090076 | LOOP [Populate nodepool sub_nodes file]
2025-11-26 22:31:13.119260 | 
2025-11-26 22:31:13.119403 | LOOP [Populate nodepool sub_nodes_private file]
2025-11-26 22:31:13.147838 | 
2025-11-26 22:31:13.147957 | TASK [Create nodepool primary file]
2025-11-26 22:31:13.171130 | controller | skipping: Conditional result was False
2025-11-26 22:31:13.176303 | 
2025-11-26 22:31:13.176398 | TASK [Create nodepool node_private for this node]
2025-11-26 22:31:13.664807 | controller | changed
2025-11-26 22:31:13.670197 | 
2025-11-26 22:31:13.670260 | LOOP [Copy ssh keys to nodepool directory]
2025-11-26 22:31:14.035732 | controller | ok: Item: id_rsa Runtime: 0:00:00.004012
2025-11-26 22:31:14.035943 | 
2025-11-26 22:31:14.201926 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005648
2025-11-26 22:31:14.208558 | 
2025-11-26 22:31:14.208650 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-11-26 22:31:14.755218 | controller | changed
2025-11-26 22:31:14.760236 | 
2025-11-26 22:31:14.760298 | TASK [Validate sudoers config after edits]
2025-11-26 22:31:14.980990 | controller | /etc/sudoers: parsed OK
2025-11-26 22:31:14.981048 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-11-26 22:31:14.981057 | controller | /etc/sudoers.d/zuul: parsed OK
2025-11-26 22:31:14.981063 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-11-26 22:31:15.290213 | controller | ok: Runtime: 0:00:00.005729
2025-11-26 22:31:15.295360 | 
2025-11-26 22:31:15.295439 | TASK [Show the environment passed in to job shell scripts]
2025-11-26 22:31:15.501953 | controller | SHELL=/bin/bash
2025-11-26 22:31:15.502039 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-11-26 22:31:15.502109 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-11-26 22:31:15.502121 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/76/3476/cf640b3d11a4ae737e2ca7867f2016ea9f776dd7
2025-11-26 22:31:15.502127 | controller | PWD=/home/zuul
2025-11-26 22:31:15.502133 | controller | ZUUL_PIPELINE=github-check
2025-11-26 22:31:15.502138 | controller | LOGNAME=zuul
2025-11-26 22:31:15.502144 | controller | XDG_SESSION_TYPE=tty
2025-11-26 22:31:15.502149 | controller | _=/usr/bin/env
2025-11-26 22:31:15.502154 | controller | MOTD_SHOWN=pam
2025-11-26 22:31:15.502159 | controller | HOME=/home/zuul
2025-11-26 22:31:15.502164 | controller | LANG=en_US.UTF-8
2025-11-26 22:31:15.502169 | controller | SSH_CONNECTION=192.168.25.12 43144 192.168.25.96 22
2025-11-26 22:31:15.502175 | 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-11-26 22:31:15.502184 | controller | ZUUL_CHANGE_IDS=3476,cf640b3d11a4ae737e2ca7867f2016ea9f776dd7
2025-11-26 22:31:15.502190 | controller | WORKSPACE=/home/zuul/workspace
2025-11-26 22:31:15.502195 | controller | XDG_SESSION_CLASS=user
2025-11-26 22:31:15.502200 | controller | SELINUX_ROLE_REQUESTED=
2025-11-26 22:31:15.502206 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-11-26 22:31:15.502211 | controller | USER=zuul
2025-11-26 22:31:15.502216 | controller | ZUUL_VOTING=True
2025-11-26 22:31:15.502221 | controller | BUILD_TIMEOUT=1800000
2025-11-26 22:31:15.502226 | controller | SELINUX_USE_CURRENT_RANGE=
2025-11-26 22:31:15.502231 | controller | SHLVL=1
2025-11-26 22:31:15.502236 | controller | ZUUL_PATCHSET=cf640b3d11a4ae737e2ca7867f2016ea9f776dd7
2025-11-26 22:31:15.502242 | controller | XDG_SESSION_ID=1
2025-11-26 22:31:15.502247 | controller | ZUUL_BRANCH=main
2025-11-26 22:31:15.502252 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-11-26 22:31:15.502257 | controller | SSH_CLIENT=192.168.25.12 43144 22
2025-11-26 22:31:15.502263 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-11-26 22:31:15.502268 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-11-26 22:31:15.502273 | controller | which_declare=declare -f
2025-11-26 22:31:15.502279 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-11-26 22:31:15.502285 | controller | SELINUX_LEVEL_REQUESTED=
2025-11-26 22:31:15.502290 | controller | ZUUL_CHANGE=3476
2025-11-26 22:31:15.502295 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-11-26 22:31:15.502300 | controller | ZUUL_UUID=cc11b9144c8e4896be58e75cd128f17a
2025-11-26 22:31:15.502308 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-11-26 22:31:15.502313 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-11-26 22:31:15.502318 | controller | }
2025-11-26 22:31:15.820835 | controller | ok: Runtime: 0:00:00.006183
2025-11-26 22:31:15.825770 | 
2025-11-26 22:31:15.825835 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-11-26 22:31:15.839542 | controller | skipping: Conditional result was False
2025-11-26 22:31:15.845256 | 
2025-11-26 22:31:15.845321 | TASK [Symlink /home/zuul-worker/workspace]
2025-11-26 22:31:16.369737 | controller | skipping: Conditional result was False
2025-11-26 22:31:16.375243 | 
2025-11-26 22:31:16.375307 | TASK [Ensure legacy workspace directory]
2025-11-26 22:31:16.576229 | controller | changed
2025-11-26 22:31:16.593150 | 
2025-11-26 22:31:16.593202 | PLAY RECAP
2025-11-26 22:31:16.593247 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-26 22:31:16.593271 | 
2025-11-26 22:31:16.663271 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-26 22:31:16.664029 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-26 22:31:17.148042 | 
2025-11-26 22:31:17.148151 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-11-26 22:31:17.168694 | 
2025-11-26 22:31:17.168769 | TASK [Create zuul-output directory]
2025-11-26 22:31:17.471618 | controller | changed
2025-11-26 22:31:17.476157 | 
2025-11-26 22:31:17.476229 | TASK [Slurp Zuul inventory test]
2025-11-26 22:31:17.732428 | controller -> localhost | ok
2025-11-26 22:31:17.738639 | 
2025-11-26 22:31:17.738720 | TASK [Save zuul inventory]
2025-11-26 22:31:18.437766 | controller | changed
2025-11-26 22:31:18.442844 | 
2025-11-26 22:31:18.442914 | TASK [Save zuul vars without the change_message]
2025-11-26 22:31:18.988142 | controller | changed
2025-11-26 22:31:19.006338 | 
2025-11-26 22:31:19.006435 | PLAY RECAP
2025-11-26 22:31:19.006484 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-26 22:31:19.006506 | 
2025-11-26 22:31:19.071691 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-26 22:31:19.072525 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-26 22:31:19.616432 | 
2025-11-26 22:31:19.616529 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-11-26 22:31:19.636576 | 
2025-11-26 22:31:19.636649 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-11-26 22:31:19.654747 | controller | ok
2025-11-26 22:31:19.671394 | 
2025-11-26 22:31:19.671487 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-26 22:31:19.695036 | controller | skipping: Conditional result was False
2025-11-26 22:31:19.700667 | 
2025-11-26 22:31:19.700743 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-26 22:31:19.991798 | controller | ok
2025-11-26 22:31:19.997177 | 
2025-11-26 22:31:19.997246 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-26 22:31:20.611989 | controller | ok
2025-11-26 22:31:20.620963 | 
2025-11-26 22:31:20.621022 | TASK [Prepare workspace]
2025-11-26 22:31:20.637748 | controller | ok
2025-11-26 22:31:20.652340 | 
2025-11-26 22:31:20.652417 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-26 22:31:20.932268 | controller | ok
2025-11-26 22:31:20.937891 | 
2025-11-26 22:31:20.937982 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-26 22:31:23.854875 | controller | Output suppressed because no_log was given
2025-11-26 22:31:23.863446 | 
2025-11-26 22:31:23.863509 | LOOP [Create zuul-output directory]
2025-11-26 22:31:24.048507 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-11-26 22:31:24.209884 | controller | ok: "/home/zuul/zuul-output/logs"
2025-11-26 22:31:24.216922 | 
2025-11-26 22:31:24.216983 | TASK [Install required packages]
2025-11-26 22:32:16.568689 | controller | changed
2025-11-26 22:32:16.573621 | 
2025-11-26 22:32:16.573680 | TASK [Install venv]
2025-11-26 22:33:21.311877 | controller | changed
2025-11-26 22:33:21.335714 | 
2025-11-26 22:33:21.335808 | PLAY RECAP
2025-11-26 22:33:21.335853 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-26 22:33:21.335876 | 
2025-11-26 22:33:21.400036 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-26 22:33:21.400837 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-26 22:33:21.876737 | 
2025-11-26 22:33:21.876867 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-11-26 22:33:21.896769 | 
2025-11-26 22:33:21.896840 | TASK [Gather required facts]
2025-11-26 22:33:22.342757 | controller | ok
2025-11-26 22:33:22.347794 | 
2025-11-26 22:33:22.347864 | TASK [Load environment var if instructed to]
2025-11-26 22:33:22.371767 | controller | skipping: Conditional result was False
2025-11-26 22:33:22.376738 | 
2025-11-26 22:33:22.376802 | TASK [Ensure group_vars dir exists]
2025-11-26 22:33:22.689460 | controller | ok
2025-11-26 22:33:22.695064 | 
2025-11-26 22:33:22.695130 | TASK [Print related variables]
2025-11-26 22:33:22.723361 | controller | ok:
2025-11-26 22:33:22.723487 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-11-26 22:33:22.723517 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values
2025-11-26 22:33:22.728101 | 
2025-11-26 22:33:22.728166 | TASK [Run molecule]
2025-11-26 22:33:23.658143 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-11-26 22:33:23.728554 | controller | INFO     Performing prerun with role_name_check=0...
2025-11-26 22:33:40.245007 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 22:33:40.245481 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 22:33:40.245944 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 22:33:40.246415 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 22:33:40.246874 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+cf640b3d was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 1.0.0+cf640b3d (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 22:33:40.247323 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 22:33:40.247776 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 22:33:40.248216 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 22:33:40.248657 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 22:33:40.249098 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 22:33:40.249574 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 22:33:40.250012 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 22:33:40.250671 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 22:33:40.251106 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 22:33:40.251567 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 22:33:40.261072 | controller | INFO     Running default > prepare
2025-11-26 22:33:40.948577 | controller |
2025-11-26 22:33:40.948653 | controller | PLAY [Prepare] *****************************************************************
2025-11-26 22:33:40.948738 | controller |
2025-11-26 22:33:40.948829 | controller | TASK [Gathering Facts] *********************************************************
2025-11-26 22:33:40.948921 | controller | Wednesday 26 November 2025  22:33:40 +0000 (0:00:00.017)       0:00:00.017 ****
2025-11-26 22:33:41.819042 | controller | ok: [instance]
2025-11-26 22:33:41.819115 | controller |
2025-11-26 22:33:41.819222 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-11-26 22:33:41.819316 | controller | Wednesday 26 November 2025  22:33:41 +0000 (0:00:00.871)       0:00:00.888 ****
2025-11-26 22:33:41.837971 | controller | skipping: [instance]
2025-11-26 22:33:41.838045 | controller |
2025-11-26 22:33:41.838140 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-11-26 22:33:41.838244 | controller | Wednesday 26 November 2025  22:33:41 +0000 (0:00:00.019)       0:00:00.907 ****
2025-11-26 22:33:41.874219 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-11-26 22:33:41.874298 | controller |
2025-11-26 22:33:41.874438 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-11-26 22:33:41.874542 | controller | Wednesday 26 November 2025  22:33:41 +0000 (0:00:00.036)       0:00:00.944 ****
2025-11-26 22:33:42.160071 | controller | ok: [instance]
2025-11-26 22:33:42.160153 | controller |
2025-11-26 22:33:42.160258 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-11-26 22:33:42.160373 | controller | Wednesday 26 November 2025  22:33:42 +0000 (0:00:00.285)       0:00:01.229 ****
2025-11-26 22:33:42.468994 | controller | ok: [instance]
2025-11-26 22:33:42.469044 | controller |
2025-11-26 22:33:42.469151 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-11-26 22:33:42.469254 | controller | Wednesday 26 November 2025  22:33:42 +0000 (0:00:00.308)       0:00:01.538 ****
2025-11-26 22:33:43.160644 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-11-26 22:33:43.160688 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-11-26 22:33:43.160750 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-11-26 22:33:43.160848 | controller |
2025-11-26 22:33:43.160952 | controller | TASK [Deploy repo-setup] *******************************************************
2025-11-26 22:33:43.161054 | controller | Wednesday 26 November 2025  22:33:43 +0000 (0:00:00.691)       0:00:02.229 ****
2025-11-26 22:33:43.222932 | controller |
2025-11-26 22:33:43.223567 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-11-26 22:33:43.753261 | controller | Wednesday 26 November 2025  22:33:43 +0000 (0:00:00.062)       0:00:02.292 ****
2025-11-26 22:33:43.753302 | controller | changed: [instance] => (item=tmp)
2025-11-26 22:33:43.753343 | controller | changed: [instance] => (item=artifacts/repositories)
2025-11-26 22:33:43.753464 | controller | changed: [instance] => (item=venv/repo_setup)
2025-11-26 22:33:43.753551 | controller |
2025-11-26 22:33:43.753648 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-11-26 22:33:43.753743 | controller | Wednesday 26 November 2025  22:33:43 +0000 (0:00:00.530)       0:00:02.822 ****
2025-11-26 22:33:44.601377 | controller | ok: [instance]
2025-11-26 22:33:44.601430 | controller |
2025-11-26 22:33:44.601531 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-11-26 22:33:44.601622 | controller | Wednesday 26 November 2025  22:33:44 +0000 (0:00:00.848)       0:00:03.670 ****
2025-11-26 22:33:45.703745 | controller | changed: [instance]
2025-11-26 22:33:45.703809 | controller |
2025-11-26 22:33:45.703845 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-11-26 22:33:45.703997 | controller | Wednesday 26 November 2025  22:33:45 +0000 (0:00:01.102)       0:00:04.772 ****
2025-11-26 22:33:53.379168 | controller | changed: [instance]
2025-11-26 22:33:53.379593 | controller |
2025-11-26 22:33:53.379761 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-11-26 22:33:53.379904 | controller | Wednesday 26 November 2025  22:33:53 +0000 (0:00:07.671)       0:00:12.444 ****
2025-11-26 22:33:54.093460 | controller | changed: [instance]
2025-11-26 22:33:54.093718 | controller |
2025-11-26 22:33:54.093733 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-11-26 22:33:54.118048 | controller | Wednesday 26 November 2025  22:33:54 +0000 (0:00:00.718)       0:00:13.162 ****
2025-11-26 22:33:54.118071 | controller | skipping: [instance]
2025-11-26 22:33:54.914201 | controller |
2025-11-26 22:33:54.914234 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-11-26 22:33:54.914243 | controller | Wednesday 26 November 2025  22:33:54 +0000 (0:00:00.024)       0:00:13.187 ****
2025-11-26 22:33:54.914253 | controller | changed: [instance]
2025-11-26 22:33:54.944288 | controller |
2025-11-26 22:33:54.944316 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-11-26 22:33:54.944324 | controller | Wednesday 26 November 2025  22:33:54 +0000 (0:00:00.795)       0:00:13.983 ****
2025-11-26 22:33:54.944351 | controller | skipping: [instance]
2025-11-26 22:33:54.971440 | controller |
2025-11-26 22:33:54.971460 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-11-26 22:33:54.971468 | controller | Wednesday 26 November 2025  22:33:54 +0000 (0:00:00.030)       0:00:14.013 ****
2025-11-26 22:33:54.971479 | controller | skipping: [instance]
2025-11-26 22:33:54.971495 | controller |
2025-11-26 22:33:54.971501 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-11-26 22:33:54.971507 | controller | Wednesday 26 November 2025  22:33:54 +0000 (0:00:00.026)       0:00:14.040 ****
2025-11-26 22:33:54.999663 | controller | skipping: [instance]
2025-11-26 22:33:55.521961 | controller |
2025-11-26 22:33:55.521994 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-11-26 22:33:55.522003 | controller | Wednesday 26 November 2025  22:33:54 +0000 (0:00:00.028)       0:00:14.069 ****
2025-11-26 22:33:55.522013 | controller | changed: [instance]
2025-11-26 22:33:55.984669 | controller |
2025-11-26 22:33:55.984711 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-11-26 22:33:55.984720 | controller | Wednesday 26 November 2025  22:33:55 +0000 (0:00:00.522)       0:00:14.591 ****
2025-11-26 22:33:55.984731 | controller | changed: [instance]
2025-11-26 22:33:56.013320 | controller |
2025-11-26 22:33:56.013373 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-11-26 22:33:56.013382 | controller | Wednesday 26 November 2025  22:33:55 +0000 (0:00:00.462)       0:00:15.053 ****
2025-11-26 22:33:56.013393 | controller | skipping: [instance]
2025-11-26 22:33:56.039035 | controller |
2025-11-26 22:33:56.039057 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-11-26 22:33:56.039066 | controller | Wednesday 26 November 2025  22:33:56 +0000 (0:00:00.029)       0:00:15.082 ****
2025-11-26 22:33:56.039083 | controller | skipping: [instance]
2025-11-26 22:33:56.063512 | controller |
2025-11-26 22:33:56.063534 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-11-26 22:33:56.063542 | controller | Wednesday 26 November 2025  22:33:56 +0000 (0:00:00.025)       0:00:15.108 ****
2025-11-26 22:33:56.063552 | controller | skipping: [instance]
2025-11-26 22:33:56.097827 | controller |
2025-11-26 22:33:56.097846 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-11-26 22:33:56.097854 | controller | Wednesday 26 November 2025  22:33:56 +0000 (0:00:00.024)       0:00:15.132 ****
2025-11-26 22:33:56.097863 | controller | ok: [instance]
2025-11-26 22:33:56.119974 | controller |
2025-11-26 22:33:56.119996 | controller | TASK [repo_setup : Create download directory] **********************************
2025-11-26 22:33:56.120005 | controller | Wednesday 26 November 2025  22:33:56 +0000 (0:00:00.034)       0:00:15.167 ****
2025-11-26 22:33:56.120016 | controller | skipping: [instance]
2025-11-26 22:33:56.141435 | controller |
2025-11-26 22:33:56.141457 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-11-26 22:33:56.141465 | controller | Wednesday 26 November 2025  22:33:56 +0000 (0:00:00.021)       0:00:15.189 ****
2025-11-26 22:33:56.141484 | controller | skipping: [instance]
2025-11-26 22:33:56.162519 | controller |
2025-11-26 22:33:56.162557 | controller | TASK [Download the RPM] ********************************************************
2025-11-26 22:33:56.162566 | controller | Wednesday 26 November 2025  22:33:56 +0000 (0:00:00.021)       0:00:15.210 ****
2025-11-26 22:33:56.162578 | controller | skipping: [instance]
2025-11-26 22:33:56.162603 | controller |
2025-11-26 22:33:56.162797 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-11-26 22:33:56.184228 | controller | Wednesday 26 November 2025  22:33:56 +0000 (0:00:00.021)       0:00:15.232 ****
2025-11-26 22:33:56.184250 | controller | skipping: [instance]
2025-11-26 22:33:56.206248 | controller |
2025-11-26 22:33:56.206265 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-11-26 22:33:56.206273 | controller | Wednesday 26 November 2025  22:33:56 +0000 (0:00:00.021)       0:00:15.253 ****
2025-11-26 22:33:56.206281 | controller | skipping: [instance]
2025-11-26 22:33:56.228393 | controller |
2025-11-26 22:33:56.228428 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-11-26 22:33:56.228437 | controller | Wednesday 26 November 2025  22:33:56 +0000 (0:00:00.022)       0:00:15.275 ****
2025-11-26 22:33:56.228448 | controller | skipping: [instance]
2025-11-26 22:33:56.250564 | controller |
2025-11-26 22:33:56.250584 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-11-26 22:33:56.250593 | controller | Wednesday 26 November 2025  22:33:56 +0000 (0:00:00.021)       0:00:15.297 ****
2025-11-26 22:33:56.250604 | controller | skipping: [instance]
2025-11-26 22:33:56.456802 | controller |
2025-11-26 22:33:56.456836 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-11-26 22:33:56.456844 | controller | Wednesday 26 November 2025  22:33:56 +0000 (0:00:00.022)       0:00:15.320 ****
2025-11-26 22:33:56.456854 | controller | ok: [instance]
2025-11-26 22:33:56.688120 | controller |
2025-11-26 22:33:56.688151 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-11-26 22:33:56.688159 | controller | Wednesday 26 November 2025  22:33:56 +0000 (0:00:00.206)       0:00:15.526 ****
2025-11-26 22:33:56.688170 | controller | changed: [instance]
2025-11-26 22:33:56.936046 | controller |
2025-11-26 22:33:56.936071 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-11-26 22:33:56.936079 | controller | Wednesday 26 November 2025  22:33:56 +0000 (0:00:00.231)       0:00:15.757 ****
2025-11-26 22:33:56.936089 | controller | changed: [instance]
2025-11-26 22:33:56.962292 | controller |
2025-11-26 22:33:56.962312 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-11-26 22:33:56.962321 | controller | Wednesday 26 November 2025  22:33:56 +0000 (0:00:00.238)       0:00:15.995 ****
2025-11-26 22:33:56.962358 | controller | skipping: [instance]
2025-11-26 22:33:56.985521 | controller |
2025-11-26 22:33:56.985539 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-11-26 22:33:56.985547 | controller | Wednesday 26 November 2025  22:33:56 +0000 (0:00:00.036)       0:00:16.031 ****
2025-11-26 22:33:56.985557 | controller | skipping: [instance]
2025-11-26 22:33:57.007712 | controller |
2025-11-26 22:33:57.007734 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-11-26 22:33:57.007741 | controller | Wednesday 26 November 2025  22:33:56 +0000 (0:00:00.023)       0:00:16.055 ****
2025-11-26 22:33:57.007750 | controller | skipping: [instance]
2025-11-26 22:33:57.030058 | controller |
2025-11-26 22:33:57.030077 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-11-26 22:33:57.030085 | controller | Wednesday 26 November 2025  22:33:57 +0000 (0:00:00.022)       0:00:16.077 ****
2025-11-26 22:33:57.030094 | controller | skipping: [instance]
2025-11-26 22:33:57.052100 | controller |
2025-11-26 22:33:57.052119 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-11-26 22:33:57.052132 | controller | Wednesday 26 November 2025  22:33:57 +0000 (0:00:00.022)       0:00:16.099 ****
2025-11-26 22:33:57.052141 | controller | skipping: [instance]
2025-11-26 22:33:57.073183 | controller |
2025-11-26 22:33:57.073203 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-11-26 22:33:57.073217 | controller | Wednesday 26 November 2025  22:33:57 +0000 (0:00:00.022)       0:00:16.121 ****
2025-11-26 22:33:57.073226 | controller | skipping: [instance]
2025-11-26 22:33:57.088728 | controller |
2025-11-26 22:33:57.088747 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-11-26 22:33:57.088754 | controller | Wednesday 26 November 2025  22:33:57 +0000 (0:00:00.021)       0:00:16.142 ****
2025-11-26 22:33:57.088763 | controller | skipping: [instance]
2025-11-26 22:33:57.108286 | controller |
2025-11-26 22:33:57.108305 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-11-26 22:33:57.108312 | controller | Wednesday 26 November 2025  22:33:57 +0000 (0:00:00.015)       0:00:16.158 ****
2025-11-26 22:33:57.108321 | controller | skipping: [instance]
2025-11-26 22:33:57.126453 | controller |
2025-11-26 22:33:57.126499 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-11-26 22:33:57.126511 | controller | Wednesday 26 November 2025  22:33:57 +0000 (0:00:00.019)       0:00:16.177 ****
2025-11-26 22:33:57.126525 | controller | skipping: [instance]
2025-11-26 22:33:57.126549 | controller |
2025-11-26 22:33:57.126560 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-11-26 22:33:57.126637 | controller | Wednesday 26 November 2025  22:33:57 +0000 (0:00:00.018)       0:00:16.196 ****
2025-11-26 22:33:57.147412 | controller | skipping: [instance]
2025-11-26 22:33:57.168986 | controller |
2025-11-26 22:33:57.169013 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-11-26 22:33:57.169022 | controller | Wednesday 26 November 2025  22:33:57 +0000 (0:00:00.020)       0:00:16.217 ****
2025-11-26 22:33:57.169034 | controller | skipping: [instance]
2025-11-26 22:33:57.193445 | controller |
2025-11-26 22:33:57.193476 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-11-26 22:33:57.193487 | controller | Wednesday 26 November 2025  22:33:57 +0000 (0:00:00.021)       0:00:16.238 ****
2025-11-26 22:33:57.193500 | controller | skipping: [instance]
2025-11-26 22:33:57.193570 | controller |
2025-11-26 22:33:57.193593 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-11-26 22:33:57.193605 | controller | Wednesday 26 November 2025  22:33:57 +0000 (0:00:00.024)       0:00:16.262 ****
2025-11-26 22:33:57.215843 | controller | skipping: [instance]
2025-11-26 22:33:57.237189 | controller |
2025-11-26 22:33:57.237214 | controller | TASK [test_deps : Install additional packages] *********************************
2025-11-26 22:33:57.237221 | controller | Wednesday 26 November 2025  22:33:57 +0000 (0:00:00.022)       0:00:16.285 ****
2025-11-26 22:33:57.237230 | controller | skipping: [instance]
2025-11-26 22:34:39.194023 | controller |
2025-11-26 22:34:39.194057 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-11-26 22:34:39.194065 | controller | Wednesday 26 November 2025  22:33:57 +0000 (0:00:00.021)       0:00:16.306 ****
2025-11-26 22:34:39.194075 | controller | ok: [instance]
2025-11-26 22:34:40.390024 | controller |
2025-11-26 22:34:40.390057 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-11-26 22:34:40.390065 | controller | Wednesday 26 November 2025  22:34:39 +0000 (0:00:41.956)       0:00:58.263 ****
2025-11-26 22:34:40.390075 | controller | ok: [instance]
2025-11-26 22:34:40.414466 | controller |
2025-11-26 22:34:40.414499 | controller | TASK [test_deps : Install extra packages] **************************************
2025-11-26 22:34:40.414507 | controller | Wednesday 26 November 2025  22:34:40 +0000 (0:00:01.196)       0:00:59.459 ****
2025-11-26 22:34:40.414517 | controller | skipping: [instance]
2025-11-26 22:34:40.414623 | controller |
2025-11-26 22:34:40.414646 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-11-26 22:34:40.414660 | controller | Wednesday 26 November 2025  22:34:40 +0000 (0:00:00.024)       0:00:59.484 ****
2025-11-26 22:34:40.447849 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-11-26 22:34:40.467617 | controller |
2025-11-26 22:34:40.467634 | controller | TASK [ci_setup : List packages to install] *************************************
2025-11-26 22:34:40.467642 | controller | Wednesday 26 November 2025  22:34:40 +0000 (0:00:00.033)       0:00:59.517 ****
2025-11-26 22:34:40.467658 | controller | ok: [instance] =>
2025-11-26 22:34:44.808608 | controller |   cifmw_ci_setup_packages:
2025-11-26 22:34:44.808642 | controller |   - bash-completion
2025-11-26 22:34:44.808650 | controller |   - ca-certificates
2025-11-26 22:34:44.808656 | controller |   - git-core
2025-11-26 22:34:44.808662 | controller |   - make
2025-11-26 22:34:44.808668 | controller |   - tar
2025-11-26 22:34:44.808673 | controller |   - tmux
2025-11-26 22:34:44.808678 | controller |   - python3-pip
2025-11-26 22:34:44.808684 | controller |
2025-11-26 22:34:44.808690 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-11-26 22:34:44.808696 | controller | Wednesday 26 November 2025  22:34:40 +0000 (0:00:00.019)       0:00:59.536 ****
2025-11-26 22:34:44.808706 | controller | changed: [instance]
2025-11-26 22:34:44.987311 | controller |
2025-11-26 22:34:44.987361 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-11-26 22:34:44.987375 | controller | Wednesday 26 November 2025  22:34:44 +0000 (0:00:04.341)       0:01:03.877 ****
2025-11-26 22:34:44.987387 | controller | ok: [instance]
2025-11-26 22:34:45.169104 | controller |
2025-11-26 22:34:45.169137 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-11-26 22:34:45.169145 | controller | Wednesday 26 November 2025  22:34:44 +0000 (0:00:00.178)       0:01:04.056 ****
2025-11-26 22:34:45.169155 | controller | changed: [instance]
2025-11-26 22:34:51.119622 | controller |
2025-11-26 22:34:51.119655 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-11-26 22:34:51.119662 | controller | Wednesday 26 November 2025  22:34:45 +0000 (0:00:00.181)       0:01:04.238 ****
2025-11-26 22:34:51.119672 | controller | changed: [instance]
2025-11-26 22:34:51.144886 | controller |
2025-11-26 22:34:51.144904 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-11-26 22:34:51.144910 | controller | Wednesday 26 November 2025  22:34:51 +0000 (0:00:05.950)       0:01:10.188 ****
2025-11-26 22:34:51.144919 | controller | ok: [instance]
2025-11-26 22:34:51.394414 | controller |
2025-11-26 22:34:51.394448 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-11-26 22:34:51.394456 | controller | Wednesday 26 November 2025  22:34:51 +0000 (0:00:00.025)       0:01:10.214 ****
2025-11-26 22:34:51.394466 | controller | changed: [instance]
2025-11-26 22:34:51.394490 | controller |
2025-11-26 22:34:51.394761 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-11-26 22:34:51.670913 | controller | Wednesday 26 November 2025  22:34:51 +0000 (0:00:00.249)       0:01:10.463 ****
2025-11-26 22:34:51.670950 | controller | changed: [instance]
2025-11-26 22:34:51.686938 | controller |
2025-11-26 22:34:51.686960 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-11-26 22:34:51.686969 | controller | Wednesday 26 November 2025  22:34:51 +0000 (0:00:00.276)       0:01:10.740 ****
2025-11-26 22:34:51.686980 | controller | skipping: [instance]
2025-11-26 22:34:51.702394 | controller |
2025-11-26 22:34:51.702414 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-11-26 22:34:51.702422 | controller | Wednesday 26 November 2025  22:34:51 +0000 (0:00:00.016)       0:01:10.756 ****
2025-11-26 22:34:51.702432 | controller | skipping: [instance]
2025-11-26 22:34:51.702820 | controller |
2025-11-26 22:34:51.718451 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-11-26 22:34:51.718477 | controller | Wednesday 26 November 2025  22:34:51 +0000 (0:00:00.015)       0:01:10.772 ****
2025-11-26 22:34:51.718486 | controller | skipping: [instance]
2025-11-26 22:34:51.733591 | controller |
2025-11-26 22:34:51.733606 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-11-26 22:34:51.733612 | controller | Wednesday 26 November 2025  22:34:51 +0000 (0:00:00.015)       0:01:10.788 ****
2025-11-26 22:34:51.733620 | controller | skipping: [instance]
2025-11-26 22:34:51.748384 | controller |
2025-11-26 22:34:51.748403 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-11-26 22:34:51.748411 | controller | Wednesday 26 November 2025  22:34:51 +0000 (0:00:00.015)       0:01:10.803 ****
2025-11-26 22:34:51.748420 | controller | skipping: [instance]
2025-11-26 22:34:51.748564 | controller |
2025-11-26 22:34:51.748695 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-11-26 22:34:51.748833 | controller | Wednesday 26 November 2025  22:34:51 +0000 (0:00:00.015)       0:01:10.818 ****
2025-11-26 22:34:51.767284 | controller | skipping: [instance]
2025-11-26 22:34:51.767481 | controller |
2025-11-26 22:34:51.767622 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-11-26 22:34:51.767748 | controller | Wednesday 26 November 2025  22:34:51 +0000 (0:00:00.018)       0:01:10.837 ****
2025-11-26 22:34:52.683183 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-11-26 22:34:52.877849 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-11-26 22:34:52.877875 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-11-26 22:34:52.877883 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-11-26 22:34:52.877889 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-11-26 22:34:52.877894 | controller |
2025-11-26 22:34:52.877899 | controller | TASK [Ensure directory exists] *************************************************
2025-11-26 22:34:52.877905 | controller | Wednesday 26 November 2025  22:34:52 +0000 (0:00:00.914)       0:01:11.752 ****
2025-11-26 22:34:52.877914 | controller | changed: [instance]
2025-11-26 22:34:53.210488 | controller |
2025-11-26 22:34:53.210526 | controller | TASK [Create nova migration keypair] *******************************************
2025-11-26 22:34:53.210537 | controller | Wednesday 26 November 2025  22:34:52 +0000 (0:00:00.195)       0:01:11.947 ****
2025-11-26 22:34:53.210550 | controller | changed: [instance]
2025-11-26 22:34:53.210587 | controller |
2025-11-26 22:34:53.210596 | controller | PLAY [Download tools] **********************************************************
2025-11-26 22:34:53.210604 | controller |
2025-11-26 22:34:53.210620 | controller | TASK [Gathering Facts] *********************************************************
2025-11-26 22:34:53.790405 | controller | Wednesday 26 November 2025  22:34:53 +0000 (0:00:00.332)       0:01:12.279 ****
2025-11-26 22:34:53.790443 | controller | ok: [localhost]
2025-11-26 22:34:59.390187 | controller |
2025-11-26 22:34:59.390219 | controller | TASK [download_tools : Install build dependencies] *****************************
2025-11-26 22:34:59.390228 | controller | Wednesday 26 November 2025  22:34:53 +0000 (0:00:00.579)       0:01:12.859 ****
2025-11-26 22:34:59.390238 | controller | changed: [localhost]
2025-11-26 22:34:59.411556 | controller |
2025-11-26 22:34:59.411579 | controller | TASK [download_tools : Set opm download url suffix] ****************************
2025-11-26 22:34:59.411587 | controller | Wednesday 26 November 2025  22:34:59 +0000 (0:00:05.599)       0:01:18.459 ****
2025-11-26 22:34:59.411597 | controller | skipping: [localhost]
2025-11-26 22:34:59.436263 | controller |
2025-11-26 22:34:59.436284 | controller | TASK [download_tools : Set opm download url suffix] ****************************
2025-11-26 22:34:59.436291 | controller | Wednesday 26 November 2025  22:34:59 +0000 (0:00:00.021)       0:01:18.481 ****
2025-11-26 22:34:59.436300 | controller | ok: [localhost]
2025-11-26 22:34:59.621283 | controller |
2025-11-26 22:34:59.621313 | controller | TASK [download_tools : Create $HOME/bin dir] ***********************************
2025-11-26 22:34:59.621321 | controller | Wednesday 26 November 2025  22:34:59 +0000 (0:00:00.024)       0:01:18.505 ****
2025-11-26 22:34:59.621346 | controller | ok: [localhost]
2025-11-26 22:35:00.878434 | controller |
2025-11-26 22:35:00.878467 | controller | TASK [download_tools : Download opm] *******************************************
2025-11-26 22:35:00.878476 | controller | Wednesday 26 November 2025  22:34:59 +0000 (0:00:00.184)       0:01:18.690 ****
2025-11-26 22:35:00.878485 | controller | changed: [localhost]
2025-11-26 22:35:00.878810 | controller |
2025-11-26 22:35:00.900280 | controller | TASK [download_tools : Get version from sdk_version] ***************************
2025-11-26 22:35:00.900300 | controller | Wednesday 26 November 2025  22:35:00 +0000 (0:00:01.257)       0:01:19.948 ****
2025-11-26 22:35:00.900312 | controller | ok: [localhost]
2025-11-26 22:35:00.920426 | controller |
2025-11-26 22:35:00.920460 | controller | TASK [download_tools : Set operator-sdk file for version < 1.3.0] **************
2025-11-26 22:35:00.920468 | controller | Wednesday 26 November 2025  22:35:00 +0000 (0:00:00.021)       0:01:19.969 ****
2025-11-26 22:35:00.920479 | controller | skipping: [localhost]
2025-11-26 22:35:00.920503 | controller |
2025-11-26 22:35:00.920519 | controller | TASK [download_tools : Set operator-sdk file for version >= 1.3.0] *************
2025-11-26 22:35:00.920640 | controller | Wednesday 26 November 2025  22:35:00 +0000 (0:00:00.020)       0:01:19.990 ****
2025-11-26 22:35:00.943300 | controller | ok: [localhost]
2025-11-26 22:35:03.029202 | controller |
2025-11-26 22:35:03.029236 | controller | TASK [download_tools : Download operator-sdk] **********************************
2025-11-26 22:35:03.029244 | controller | Wednesday 26 November 2025  22:35:00 +0000 (0:00:00.022)       0:01:20.012 ****
2025-11-26 22:35:03.029254 | controller | changed: [localhost]
2025-11-26 22:35:04.070164 | controller |
2025-11-26 22:35:04.070196 | controller | TASK [download_tools : Download and extract kustomize] *************************
2025-11-26 22:35:04.070204 | controller | Wednesday 26 November 2025  22:35:03 +0000 (0:00:02.085)       0:01:22.098 ****
2025-11-26 22:35:04.070214 | controller | changed: [localhost]
2025-11-26 22:35:04.451473 | controller |
2025-11-26 22:35:04.451502 | controller | TASK [download_tools : Download kubectl] ***************************************
2025-11-26 22:35:04.451510 | controller | Wednesday 26 November 2025  22:35:04 +0000 (0:00:01.041)       0:01:23.139 ****
2025-11-26 22:35:04.451520 | controller | ok: [localhost]
2025-11-26 22:35:05.543761 | controller |
2025-11-26 22:35:05.543790 | controller | TASK [download_tools : Download kuttl] *****************************************
2025-11-26 22:35:05.543798 | controller | Wednesday 26 November 2025  22:35:04 +0000 (0:00:00.381)       0:01:23.520 ****
2025-11-26 22:35:05.543807 | controller | changed: [localhost]
2025-11-26 22:35:08.473908 | controller |
2025-11-26 22:35:08.473941 | controller | TASK [download_tools : Download chainsaw] **************************************
2025-11-26 22:35:08.473949 | controller | Wednesday 26 November 2025  22:35:05 +0000 (0:00:01.092)       0:01:24.613 ****
2025-11-26 22:35:08.473959 | controller | changed: [localhost]
2025-11-26 22:35:09.575417 | controller |
2025-11-26 22:35:09.575448 | controller | TASK [download_tools : Download and extract yq] ********************************
2025-11-26 22:35:09.575456 | controller | Wednesday 26 November 2025  22:35:08 +0000 (0:00:02.930)       0:01:27.543 ****
2025-11-26 22:35:09.575466 | controller | changed: [localhost]
2025-11-26 22:35:09.575637 | controller |
2025-11-26 22:35:09.575649 | controller | TASK [download_tools : Link yq_linux_amd64 as yq] ******************************
2025-11-26 22:35:09.575662 | controller | Wednesday 26 November 2025  22:35:09 +0000 (0:00:01.101)       0:01:28.644 ****
2025-11-26 22:35:09.752233 | controller | changed: [localhost]
2025-11-26 22:35:11.214904 | controller |
2025-11-26 22:35:11.214934 | controller | TASK [download_tools : Deinstall golang] ***************************************
2025-11-26 22:35:11.214942 | controller | Wednesday 26 November 2025  22:35:09 +0000 (0:00:00.176)       0:01:28.821 ****
2025-11-26 22:35:11.214951 | controller | ok: [localhost]
2025-11-26 22:35:12.119970 | controller |
2025-11-26 22:35:12.120003 | controller | TASK [download_tools : Delete old go version installed from upstream] **********
2025-11-26 22:35:12.120011 | controller | Wednesday 26 November 2025  22:35:11 +0000 (0:00:01.462)       0:01:30.284 ****
2025-11-26 22:35:12.120021 | controller | ok: [localhost] => (item=/usr/local/go)
2025-11-26 22:35:24.311312 | controller | ok: [localhost] => (item=/home/zuul/bin/go)
2025-11-26 22:35:24.311363 | controller | ok: [localhost] => (item=/home/zuul/bin/gofmt)
2025-11-26 22:35:24.311370 | controller | ok: [localhost] => (item=/usr/local/bin/go)
2025-11-26 22:35:24.311375 | controller | ok: [localhost] => (item=/usr/local/bin/gofmt)
2025-11-26 22:35:24.311380 | controller |
2025-11-26 22:35:24.311386 | controller | TASK [download_tools : Download and extract golang] ****************************
2025-11-26 22:35:24.311391 | controller | Wednesday 26 November 2025  22:35:12 +0000 (0:00:00.904)       0:01:31.188 ****
2025-11-26 22:35:24.311401 | controller | changed: [localhost]
2025-11-26 22:35:24.693393 | controller |
2025-11-26 22:35:24.693420 | controller | TASK [download_tools : Set alternatives link to installed go version] **********
2025-11-26 22:35:24.693429 | controller | Wednesday 26 November 2025  22:35:24 +0000 (0:00:12.191)       0:01:43.380 ****
2025-11-26 22:35:24.693439 | controller | changed: [localhost] => (item=go)
2025-11-26 22:35:24.734470 | controller | changed: [localhost] => (item=gofmt)
2025-11-26 22:35:24.734504 | controller |
2025-11-26 22:35:24.734513 | controller | TASK [download_tools : Clean bash cache] ***************************************
2025-11-26 22:35:24.734527 | controller | Wednesday 26 November 2025  22:35:24 +0000 (0:00:00.381)       0:01:43.762 ****
2025-11-26 22:35:24.734538 | controller | ok: [localhost] =>
2025-11-26 22:35:24.734672 | controller |   msg: When move from rpm to upstream version, make sure to clean bash cache using `hash -d go`
2025-11-26 22:35:24.734683 | controller |
2025-11-26 22:35:24.734690 | controller | PLAY RECAP *********************************************************************
2025-11-26 22:35:24.734695 | controller | instance                   : ok=31   changed=17   unreachable=0    failed=0    skipped=36   rescued=0    ignored=0
2025-11-26 22:35:24.734701 | controller | localhost                  : ok=19   changed=10   unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-11-26 22:35:24.734707 | controller |
2025-11-26 22:35:24.734712 | controller | Wednesday 26 November 2025  22:35:24 +0000 (0:00:00.039)       0:01:43.802 ****
2025-11-26 22:35:24.734718 | controller | ===============================================================================
2025-11-26 22:35:24.734723 | controller | test_deps : Install selinux python libs -------------------------------- 41.96s
2025-11-26 22:35:24.734728 | controller | download_tools : Download and extract golang --------------------------- 12.19s
2025-11-26 22:35:24.734734 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.67s
2025-11-26 22:35:24.734739 | controller | ci_setup : Install openshift client ------------------------------------- 5.95s
2025-11-26 22:35:24.734744 | controller | download_tools : Install build dependencies ----------------------------- 5.60s
2025-11-26 22:35:24.734750 | controller | ci_setup : Install needed packages -------------------------------------- 4.34s
2025-11-26 22:35:24.734755 | controller | download_tools : Download chainsaw -------------------------------------- 2.93s
2025-11-26 22:35:24.734760 | controller | download_tools : Download operator-sdk ---------------------------------- 2.09s
2025-11-26 22:35:24.734765 | controller | download_tools : Deinstall golang --------------------------------------- 1.46s
2025-11-26 22:35:24.734771 | controller | download_tools : Download opm ------------------------------------------- 1.26s
2025-11-26 22:35:24.734776 | controller | test_deps : Install python yaml libs ------------------------------------ 1.20s
2025-11-26 22:35:24.734782 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.10s
2025-11-26 22:35:24.734787 | controller | download_tools : Download and extract yq -------------------------------- 1.10s
2025-11-26 22:35:24.734793 | controller | download_tools : Download kuttl ----------------------------------------- 1.09s
2025-11-26 22:35:24.734801 | controller | download_tools : Download and extract kustomize ------------------------- 1.04s
2025-11-26 22:35:24.734835 | controller | ci_setup : Manage directories ------------------------------------------- 0.91s
2025-11-26 22:35:24.734964 | controller | download_tools : Delete old go version installed from upstream ---------- 0.90s
2025-11-26 22:35:24.735087 | controller | Gathering Facts --------------------------------------------------------- 0.87s
2025-11-26 22:35:24.735195 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.85s
2025-11-26 22:35:24.735322 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.80s
2025-11-26 22:35:24.803082 | controller | INFO     Running default > converge
2025-11-26 22:35:25.228381 | controller |
2025-11-26 22:35:26.055467 | controller | PLAY [Converge] ****************************************************************
2025-11-26 22:35:26.055502 | controller |
2025-11-26 22:35:26.055510 | controller | TASK [Gathering Facts] *********************************************************
2025-11-26 22:35:26.055516 | controller | Wednesday 26 November 2025  22:35:25 +0000 (0:00:00.014)       0:00:00.014 ****
2025-11-26 22:35:26.055527 | controller | ok: [instance]
2025-11-26 22:35:26.055552 | controller |
2025-11-26 22:35:26.055560 | controller | TASK [Ensure architecture repository is building] ******************************
2025-11-26 22:35:26.055729 | controller | Wednesday 26 November 2025  22:35:26 +0000 (0:00:00.827)       0:00:00.841 ****
2025-11-26 22:35:26.791485 | controller | changed: [instance] => (item={'name': 'ctlplane', 'path': 'examples/va/hci/control-plane/networking/nncp'})
2025-11-26 22:35:26.791773 | controller | changed: [instance] => (item={'name': 'dataplane', 'path': 'examples/va/hci/edpm-pre-ceph/nodeset'})
2025-11-26 22:35:26.791797 | controller |
2025-11-26 22:35:26.791808 | controller | TASK [Create nova migration keypair] *******************************************
2025-11-26 22:35:27.218270 | controller | Wednesday 26 November 2025  22:35:26 +0000 (0:00:00.735)       0:00:01.577 ****
2025-11-26 22:35:27.218322 | controller | changed: [instance]
2025-11-26 22:35:27.285161 | controller |
2025-11-26 22:35:27.285182 | controller | TASK [Generate needed facts out of local files] ********************************
2025-11-26 22:35:27.285190 | controller | Wednesday 26 November 2025  22:35:27 +0000 (0:00:00.426)       0:00:02.004 ****
2025-11-26 22:35:27.285200 | controller | ok: [instance]
2025-11-26 22:35:27.314472 | controller |
2025-11-26 22:35:27.314507 | controller | TASK [Generate network-values] *************************************************
2025-11-26 22:35:27.314517 | controller | Wednesday 26 November 2025  22:35:27 +0000 (0:00:00.067)       0:00:02.071 ****
2025-11-26 22:35:27.314529 | controller |
2025-11-26 22:35:27.346377 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-11-26 22:35:27.346399 | controller | Wednesday 26 November 2025  22:35:27 +0000 (0:00:00.029)       0:00:02.100 ****
2025-11-26 22:35:27.346410 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_snippets.yml for instance
2025-11-26 22:35:27.380896 | controller |
2025-11-26 22:35:27.380918 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-11-26 22:35:27.380926 | controller | Wednesday 26 November 2025  22:35:27 +0000 (0:00:00.031)       0:00:02.132 ****
2025-11-26 22:35:27.380936 | controller | ok: [instance] => changed=false
2025-11-26 22:35:27.686396 | controller |   msg: All assertions passed
2025-11-26 22:35:27.686419 | controller |
2025-11-26 22:35:27.686426 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-11-26 22:35:27.686432 | controller | Wednesday 26 November 2025  22:35:27 +0000 (0:00:00.034)       0:00:02.167 ****
2025-11-26 22:35:27.686440 | controller | ok: [instance]
2025-11-26 22:35:27.686601 | controller |
2025-11-26 22:35:27.686613 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-11-26 22:35:27.727443 | controller | Wednesday 26 November 2025  22:35:27 +0000 (0:00:00.305)       0:00:02.473 ****
2025-11-26 22:35:27.727484 | controller | ok: [instance] => changed=false
2025-11-26 22:35:27.727515 | controller |   msg: All assertions passed
2025-11-26 22:35:27.727523 | controller |
2025-11-26 22:35:27.727532 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-11-26 22:35:27.727614 | controller | Wednesday 26 November 2025  22:35:27 +0000 (0:00:00.041)       0:00:02.514 ****
2025-11-26 22:35:28.029457 | controller | ok: [instance]
2025-11-26 22:35:28.314835 | controller |
2025-11-26 22:35:28.314861 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-11-26 22:35:28.314868 | controller | Wednesday 26 November 2025  22:35:28 +0000 (0:00:00.301)       0:00:02.815 ****
2025-11-26 22:35:28.314878 | controller | ok: [instance]
2025-11-26 22:35:28.365989 | controller |
2025-11-26 22:35:28.366012 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-11-26 22:35:28.366022 | controller | Wednesday 26 November 2025  22:35:28 +0000 (0:00:00.285)       0:00:03.101 ****
2025-11-26 22:35:28.366033 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/network_values.yml for instance
2025-11-26 22:35:28.399997 | controller |
2025-11-26 22:35:28.400020 | controller | TASK [ci_gen_kustomize_values : Assert we have needed parameters] **************
2025-11-26 22:35:28.400030 | controller | Wednesday 26 November 2025  22:35:28 +0000 (0:00:00.051)       0:00:03.152 ****
2025-11-26 22:35:28.400041 | controller | ok: [instance] => changed=false
2025-11-26 22:35:28.725418 | controller |   msg: All assertions passed
2025-11-26 22:35:28.725452 | controller |
2025-11-26 22:35:28.725460 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-11-26 22:35:28.725466 | controller | Wednesday 26 November 2025  22:35:28 +0000 (0:00:00.033)       0:00:03.186 ****
2025-11-26 22:35:28.725482 | controller | changed: [instance]
2025-11-26 22:35:28.725508 | controller |
2025-11-26 22:35:28.725709 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-11-26 22:35:29.296730 | controller | Wednesday 26 November 2025  22:35:28 +0000 (0:00:00.325)       0:00:03.511 ****
2025-11-26 22:35:29.296764 | controller | changed: [instance]
2025-11-26 22:35:29.338991 | controller |
2025-11-26 22:35:29.339014 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-11-26 22:35:29.339022 | controller | Wednesday 26 November 2025  22:35:29 +0000 (0:00:00.571)       0:00:04.083 ****
2025-11-26 22:35:29.339033 | controller | ok: [instance]
2025-11-26 22:35:29.721474 | controller |
2025-11-26 22:35:29.721507 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-11-26 22:35:29.721515 | controller | Wednesday 26 November 2025  22:35:29 +0000 (0:00:00.042)       0:00:04.125 ****
2025-11-26 22:35:29.721524 | controller | changed: [instance]
2025-11-26 22:35:29.721894 | controller |
2025-11-26 22:35:30.100507 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-11-26 22:35:30.100569 | controller | Wednesday 26 November 2025  22:35:29 +0000 (0:00:00.382)       0:00:04.508 ****
2025-11-26 22:35:30.100584 | controller | changed: [instance]
2025-11-26 22:35:30.457491 | controller |
2025-11-26 22:35:30.457531 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-11-26 22:35:30.457539 | controller | Wednesday 26 November 2025  22:35:30 +0000 (0:00:00.378)       0:00:04.886 ****
2025-11-26 22:35:30.457548 | controller | changed: [instance]
2025-11-26 22:35:30.490477 | controller |
2025-11-26 22:35:30.490514 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-11-26 22:35:30.490523 | controller | Wednesday 26 November 2025  22:35:30 +0000 (0:00:00.356)       0:00:05.243 ****
2025-11-26 22:35:30.490537 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_values.yml for instance
2025-11-26 22:35:30.524042 | controller |
2025-11-26 22:35:30.524070 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-11-26 22:35:30.524078 | controller | Wednesday 26 November 2025  22:35:30 +0000 (0:00:00.033)       0:00:05.277 ****
2025-11-26 22:35:30.524086 | controller | ok: [instance] => changed=false
2025-11-26 22:35:30.816419 | controller |   msg: All assertions passed
2025-11-26 22:35:30.816455 | controller |
2025-11-26 22:35:30.816463 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-11-26 22:35:30.816470 | controller | Wednesday 26 November 2025  22:35:30 +0000 (0:00:00.033)       0:00:05.310 ****
2025-11-26 22:35:30.816488 | controller | ok: [instance]
2025-11-26 22:35:30.816515 | controller |
2025-11-26 22:35:30.816522 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-11-26 22:35:30.816553 | controller | Wednesday 26 November 2025  22:35:30 +0000 (0:00:00.292)       0:00:05.602 ****
2025-11-26 22:35:30.840413 | controller | ok: [instance]
2025-11-26 22:35:30.840454 | controller |
2025-11-26 22:35:30.840464 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-11-26 22:35:30.840473 | controller | Wednesday 26 November 2025  22:35:30 +0000 (0:00:00.024)       0:00:05.627 ****
2025-11-26 22:35:31.349459 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/02_ci_data.yaml)
2025-11-26 22:35:31.421887 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/03_user_data_b64.yaml)
2025-11-26 22:35:31.421905 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/04_user_data.yaml)
2025-11-26 22:35:31.421912 | controller |
2025-11-26 22:35:31.421918 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-11-26 22:35:31.421923 | controller | Wednesday 26 November 2025  22:35:31 +0000 (0:00:00.508)       0:00:06.135 ****
2025-11-26 22:35:31.421931 | controller | ok: [instance] => (item=02_ci_data.yaml)
2025-11-26 22:35:31.630163 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-11-26 22:35:31.630188 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-11-26 22:35:31.630195 | controller |
2025-11-26 22:35:31.630201 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-11-26 22:35:31.630206 | controller | Wednesday 26 November 2025  22:35:31 +0000 (0:00:00.072)       0:00:06.208 ****
2025-11-26 22:35:31.630224 | controller | changed: [instance]
2025-11-26 22:35:31.998040 | controller |
2025-11-26 22:35:31.998077 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-11-26 22:35:31.998085 | controller | Wednesday 26 November 2025  22:35:31 +0000 (0:00:00.208)       0:00:06.416 ****
2025-11-26 22:35:31.998094 | controller | changed: [instance]
2025-11-26 22:35:32.027325 | controller |
2025-11-26 22:35:32.027367 | controller | TASK [Generate edpm-nodeset-values] ********************************************
2025-11-26 22:35:32.027375 | controller | Wednesday 26 November 2025  22:35:31 +0000 (0:00:00.367)       0:00:06.784 ****
2025-11-26 22:35:32.027386 | controller |
2025-11-26 22:35:32.052570 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-11-26 22:35:32.052631 | controller | Wednesday 26 November 2025  22:35:32 +0000 (0:00:00.029)       0:00:06.813 ****
2025-11-26 22:35:32.052649 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_snippets.yml for instance
2025-11-26 22:35:32.088069 | controller |
2025-11-26 22:35:32.088112 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-11-26 22:35:32.088133 | controller | Wednesday 26 November 2025  22:35:32 +0000 (0:00:00.025)       0:00:06.839 ****
2025-11-26 22:35:32.088146 | controller | ok: [instance] => changed=false
2025-11-26 22:35:32.303150 | controller |   msg: All assertions passed
2025-11-26 22:35:32.303183 | controller |
2025-11-26 22:35:32.303192 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-11-26 22:35:32.303198 | controller | Wednesday 26 November 2025  22:35:32 +0000 (0:00:00.035)       0:00:06.874 ****
2025-11-26 22:35:32.303208 | controller | ok: [instance]
2025-11-26 22:35:32.348080 | controller |
2025-11-26 22:35:32.348108 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-11-26 22:35:32.348116 | controller | Wednesday 26 November 2025  22:35:32 +0000 (0:00:00.214)       0:00:07.089 ****
2025-11-26 22:35:32.348127 | controller | ok: [instance] => changed=false
2025-11-26 22:35:32.559534 | controller |   msg: All assertions passed
2025-11-26 22:35:32.559561 | controller |
2025-11-26 22:35:32.559569 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-11-26 22:35:32.559575 | controller | Wednesday 26 November 2025  22:35:32 +0000 (0:00:00.045)       0:00:07.134 ****
2025-11-26 22:35:32.559585 | controller | ok: [instance]
2025-11-26 22:35:32.816589 | controller |
2025-11-26 22:35:32.816638 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-11-26 22:35:32.816652 | controller | Wednesday 26 November 2025  22:35:32 +0000 (0:00:00.211)       0:00:07.345 ****
2025-11-26 22:35:32.816670 | controller | ok: [instance]
2025-11-26 22:35:32.868897 | controller |
2025-11-26 22:35:32.868933 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-11-26 22:35:32.868942 | controller | Wednesday 26 November 2025  22:35:32 +0000 (0:00:00.256)       0:00:07.602 ****
2025-11-26 22:35:32.868954 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/edpm_nodeset_values.yml for instance
2025-11-26 22:35:32.909148 | controller |
2025-11-26 22:35:32.909170 | controller | TASK [ci_gen_kustomize_values : Check if all required variables for accessing the node is provided.] ***
2025-11-26 22:35:32.909179 | controller | Wednesday 26 November 2025  22:35:32 +0000 (0:00:00.052)       0:00:07.655 ****
2025-11-26 22:35:32.909189 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/edpm_core_asserts.yml for instance
2025-11-26 22:35:32.946981 | controller |
2025-11-26 22:35:32.946998 | controller | TASK [ci_gen_kustomize_values : Ensure the required parameters are defined and configured.] ***
2025-11-26 22:35:32.947005 | controller | Wednesday 26 November 2025  22:35:32 +0000 (0:00:00.040)       0:00:07.695 ****
2025-11-26 22:35:32.947013 | controller | ok: [instance] => changed=false
2025-11-26 22:35:32.978510 | controller |   msg: All assertions passed
2025-11-26 22:35:32.978546 | controller |
2025-11-26 22:35:32.978559 | controller | TASK [ci_gen_kustomize_values : Get common data for dataplane templating] ******
2025-11-26 22:35:32.978582 | controller | Wednesday 26 November 2025  22:35:32 +0000 (0:00:00.037)       0:00:07.733 ****
2025-11-26 22:35:32.978598 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/edpm_core_facts.yml for instance
2025-11-26 22:35:32.978656 | controller |
2025-11-26 22:35:32.978672 | controller | TASK [ci_gen_kustomize_values : Fetch the already deployed services for further usage] ***
2025-11-26 22:35:32.978702 | controller | Wednesday 26 November 2025  22:35:32 +0000 (0:00:00.031)       0:00:07.765 ****
2025-11-26 22:35:33.000850 | controller | skipping: [instance]
2025-11-26 22:35:33.026950 | controller |
2025-11-26 22:35:33.026966 | controller | TASK [ci_gen_kustomize_values : Expose the deployed services as a fact] ********
2025-11-26 22:35:33.026973 | controller | Wednesday 26 November 2025  22:35:32 +0000 (0:00:00.022)       0:00:07.787 ****
2025-11-26 22:35:33.026982 | controller | ok: [instance]
2025-11-26 22:35:33.058285 | controller |
2025-11-26 22:35:33.058302 | controller | TASK [ci_gen_kustomize_values : Check if required variables for nova migration are provided.] ***
2025-11-26 22:35:33.058309 | controller | Wednesday 26 November 2025  22:35:33 +0000 (0:00:00.026)       0:00:07.813 ****
2025-11-26 22:35:33.058317 | controller | ok: [instance] => changed=false
2025-11-26 22:35:33.256567 | controller |   msg: All assertions passed
2025-11-26 22:35:33.256602 | controller |
2025-11-26 22:35:33.256610 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-11-26 22:35:33.256617 | controller | Wednesday 26 November 2025  22:35:33 +0000 (0:00:00.031)       0:00:07.844 ****
2025-11-26 22:35:33.256628 | controller | changed: [instance]
2025-11-26 22:35:33.679890 | controller |
2025-11-26 22:35:33.679926 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-11-26 22:35:33.679934 | controller | Wednesday 26 November 2025  22:35:33 +0000 (0:00:00.197)       0:00:08.042 ****
2025-11-26 22:35:33.679944 | controller | changed: [instance]
2025-11-26 22:35:33.728318 | controller |
2025-11-26 22:35:33.728360 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-11-26 22:35:33.728373 | controller | Wednesday 26 November 2025  22:35:33 +0000 (0:00:00.418)       0:00:08.461 ****
2025-11-26 22:35:33.728384 | controller | ok: [instance]
2025-11-26 22:35:34.086928 | controller |
2025-11-26 22:35:34.086963 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-11-26 22:35:34.086972 | controller | Wednesday 26 November 2025  22:35:33 +0000 (0:00:00.053)       0:00:08.514 ****
2025-11-26 22:35:34.086983 | controller | changed: [instance]
2025-11-26 22:35:34.433890 | controller |
2025-11-26 22:35:34.433925 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-11-26 22:35:34.433933 | controller | Wednesday 26 November 2025  22:35:34 +0000 (0:00:00.358)       0:00:08.872 ****
2025-11-26 22:35:34.433943 | controller | changed: [instance]
2025-11-26 22:35:34.778492 | controller |
2025-11-26 22:35:34.778527 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-11-26 22:35:34.778534 | controller | Wednesday 26 November 2025  22:35:34 +0000 (0:00:00.347)       0:00:09.220 ****
2025-11-26 22:35:34.778551 | controller | changed: [instance]
2025-11-26 22:35:34.810003 | controller |
2025-11-26 22:35:34.810020 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-11-26 22:35:34.810026 | controller | Wednesday 26 November 2025  22:35:34 +0000 (0:00:00.344)       0:00:09.564 ****
2025-11-26 22:35:34.810034 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_values.yml for instance
2025-11-26 22:35:34.842188 | controller |
2025-11-26 22:35:34.842202 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-11-26 22:35:34.842208 | controller | Wednesday 26 November 2025  22:35:34 +0000 (0:00:00.031)       0:00:09.596 ****
2025-11-26 22:35:34.842216 | controller | ok: [instance] => changed=false
2025-11-26 22:35:35.042893 | controller |   msg: All assertions passed
2025-11-26 22:35:35.042917 | controller |
2025-11-26 22:35:35.042926 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-11-26 22:35:35.042933 | controller | Wednesday 26 November 2025  22:35:34 +0000 (0:00:00.032)       0:00:09.628 ****
2025-11-26 22:35:35.042951 | controller | ok: [instance]
2025-11-26 22:35:35.065093 | controller |
2025-11-26 22:35:35.065115 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-11-26 22:35:35.065123 | controller | Wednesday 26 November 2025  22:35:35 +0000 (0:00:00.200)       0:00:09.828 ****
2025-11-26 22:35:35.065132 | controller | ok: [instance]
2025-11-26 22:35:35.607434 | controller |
2025-11-26 22:35:35.607470 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-11-26 22:35:35.607478 | controller | Wednesday 26 November 2025  22:35:35 +0000 (0:00:00.022)       0:00:09.851 ****
2025-11-26 22:35:35.607489 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/02_ci_data.yaml)
2025-11-26 22:35:35.607513 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/03_user_data_b64.yaml)
2025-11-26 22:35:35.607521 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/04_user_data.yaml)
2025-11-26 22:35:35.607543 | controller |
2025-11-26 22:35:35.607875 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-11-26 22:35:35.662929 | controller | Wednesday 26 November 2025  22:35:35 +0000 (0:00:00.541)       0:00:10.393 ****
2025-11-26 22:35:35.662955 | controller | ok: [instance] => (item=02_ci_data.yaml)
2025-11-26 22:35:35.870491 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-11-26 22:35:35.870544 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-11-26 22:35:35.870552 | controller |
2025-11-26 22:35:35.870559 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-11-26 22:35:35.870566 | controller | Wednesday 26 November 2025  22:35:35 +0000 (0:00:00.055)       0:00:10.449 ****
2025-11-26 22:35:35.870580 | controller | changed: [instance]
2025-11-26 22:35:36.227749 | controller |
2025-11-26 22:35:36.227785 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-11-26 22:35:36.227793 | controller | Wednesday 26 November 2025  22:35:35 +0000 (0:00:00.207)       0:00:10.656 ****
2025-11-26 22:35:36.227805 | controller | changed: [instance]
2025-11-26 22:35:36.591497 | controller |
2025-11-26 22:35:36.591522 | controller | TASK [Get generated files status] **********************************************
2025-11-26 22:35:36.591530 | controller | Wednesday 26 November 2025  22:35:36 +0000 (0:00:00.357)       0:00:11.013 ****
2025-11-26 22:35:36.591541 | controller | ok: [instance] => (item=network-values)
2025-11-26 22:35:36.624496 | controller | ok: [instance] => (item=edpm-nodeset-values)
2025-11-26 22:35:36.624560 | controller |
2025-11-26 22:35:36.624572 | controller | TASK [Assert generated values.yaml exists] *************************************
2025-11-26 22:35:36.624583 | controller | Wednesday 26 November 2025  22:35:36 +0000 (0:00:00.363)       0:00:11.377 ****
2025-11-26 22:35:36.624600 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/values.yaml) => changed=false
2025-11-26 22:35:36.624636 | controller |   ansible_loop_var: item
2025-11-26 22:35:36.624646 | controller |   item:
2025-11-26 22:35:36.624655 | controller |     ansible_loop_var: item
2025-11-26 22:35:36.624662 | controller |     changed: false
2025-11-26 22:35:36.624670 | controller |     failed: false
2025-11-26 22:35:36.624678 | controller |     invocation:
2025-11-26 22:35:36.624686 | controller |       module_args:
2025-11-26 22:35:36.624693 | controller |         checksum_algorithm: sha1
2025-11-26 22:35:36.624702 | controller |         follow: false
2025-11-26 22:35:36.624710 | controller |         get_attributes: false
2025-11-26 22:35:36.624717 | controller |         get_checksum: false
2025-11-26 22:35:36.624725 | controller |         get_md5: false
2025-11-26 22:35:36.624732 | controller |         get_mime: false
2025-11-26 22:35:36.624740 | controller |         path: /home/zuul/ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/values.yaml
2025-11-26 22:35:36.624750 | controller |     item: network-values
2025-11-26 22:35:36.624779 | controller |     stat:
2025-11-26 22:35:36.624789 | controller |       atime: 1764196531.954384
2025-11-26 22:35:36.625485 | controller |       block_size: 4096
2025-11-26 22:35:36.625526 | controller |       blocks: 16
2025-11-26 22:35:36.625549 | controller |       ctime: 1764196531.957384
2025-11-26 22:35:36.625555 | controller |       dev: 64513
2025-11-26 22:35:36.625560 | controller |       device_type: 0
2025-11-26 22:35:36.625574 | controller |       executable: false
2025-11-26 22:35:36.625579 | controller |       exists: true
2025-11-26 22:35:36.625585 | controller |       gid: 1000
2025-11-26 22:35:36.625590 | controller |       gr_name: zuul
2025-11-26 22:35:36.625597 | controller |       inode: 29422115
2025-11-26 22:35:36.627516 | controller |       isblk: false
2025-11-26 22:35:36.627588 | controller |       ischr: false
2025-11-26 22:35:36.627596 | controller |       isdir: false
2025-11-26 22:35:36.627601 | controller |       isfifo: false
2025-11-26 22:35:36.627606 | controller |       isgid: false
2025-11-26 22:35:36.627611 | controller |       islnk: false
2025-11-26 22:35:36.627616 | controller |       isreg: true
2025-11-26 22:35:36.627621 | controller |       issock: false
2025-11-26 22:35:36.627626 | controller |       isuid: false
2025-11-26 22:35:36.627631 | controller |       mode: '0644'
2025-11-26 22:35:36.627636 | controller |       mtime: 1764196531.8173838
2025-11-26 22:35:36.627642 | controller |       nlink: 1
2025-11-26 22:35:36.627649 | controller |       path: /home/zuul/ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/values.yaml
2025-11-26 22:35:36.627655 | controller |       pw_name: zuul
2025-11-26 22:35:36.627660 | controller |       readable: true
2025-11-26 22:35:36.627665 | controller |       rgrp: true
2025-11-26 22:35:36.627670 | controller |       roth: true
2025-11-26 22:35:36.627674 | controller |       rusr: true
2025-11-26 22:35:36.627679 | controller |       size: 5726
2025-11-26 22:35:36.627685 | controller |       uid: 1000
2025-11-26 22:35:36.627689 | controller |       wgrp: false
2025-11-26 22:35:36.627697 | controller |       woth: false
2025-11-26 22:35:36.627703 | controller |       writeable: true
2025-11-26 22:35:36.627817 | controller |       wusr: true
2025-11-26 22:35:36.627925 | controller |       xgrp: false
2025-11-26 22:35:36.628029 | controller |       xoth: false
2025-11-26 22:35:36.628129 | controller |       xusr: false
2025-11-26 22:35:36.628239 | controller |   msg: All assertions passed
2025-11-26 22:35:36.628387 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-nodeset-values/values.yaml) => changed=false
2025-11-26 22:35:36.628501 | controller |   ansible_loop_var: item
2025-11-26 22:35:36.628797 | controller |   item:
2025-11-26 22:35:36.628903 | controller |     ansible_loop_var: item
2025-11-26 22:35:36.629010 | controller |     changed: false
2025-11-26 22:35:36.629110 | controller |     failed: false
2025-11-26 22:35:36.629214 | controller |     invocation:
2025-11-26 22:35:36.629329 | controller |       module_args:
2025-11-26 22:35:36.629447 | controller |         checksum_algorithm: sha1
2025-11-26 22:35:36.629553 | controller |         follow: false
2025-11-26 22:35:36.629659 | controller |         get_attributes: false
2025-11-26 22:35:36.629768 | controller |         get_checksum: false
2025-11-26 22:35:36.629883 | controller |         get_md5: false
2025-11-26 22:35:36.629993 | controller |         get_mime: false
2025-11-26 22:35:36.630112 | controller |         path: /home/zuul/ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-nodeset-values/values.yaml
2025-11-26 22:35:36.630229 | controller |     item: edpm-nodeset-values
2025-11-26 22:35:36.630365 | controller |     stat:
2025-11-26 22:35:36.630484 | controller |       atime: 1764196536.1823945
2025-11-26 22:35:36.630594 | controller |       block_size: 4096
2025-11-26 22:35:36.630702 | controller |       blocks: 40
2025-11-26 22:35:36.630812 | controller |       ctime: 1764196536.1853943
2025-11-26 22:35:36.630919 | controller |       dev: 64513
2025-11-26 22:35:36.631027 | controller |       device_type: 0
2025-11-26 22:35:36.631135 | controller |       executable: false
2025-11-26 22:35:36.631243 | controller |       exists: true
2025-11-26 22:35:36.631376 | controller |       gid: 1000
2025-11-26 22:35:36.631483 | controller |       gr_name: zuul
2025-11-26 22:35:36.631584 | controller |       inode: 1087733
2025-11-26 22:35:36.631693 | controller |       isblk: false
2025-11-26 22:35:36.631795 | controller |       ischr: false
2025-11-26 22:35:36.631900 | controller |       isdir: false
2025-11-26 22:35:36.632001 | controller |       isfifo: false
2025-11-26 22:35:36.632102 | controller |       isgid: false
2025-11-26 22:35:36.632207 | controller |       islnk: false
2025-11-26 22:35:36.632343 | controller |       isreg: true
2025-11-26 22:35:36.632468 | controller |       issock: false
2025-11-26 22:35:36.632583 | controller |       isuid: false
2025-11-26 22:35:36.632695 | controller |       mode: '0644'
2025-11-26 22:35:36.632805 | controller |       mtime: 1764196536.043394
2025-11-26 22:35:36.632914 | controller |       nlink: 1
2025-11-26 22:35:36.633030 | controller |       path: /home/zuul/ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-nodeset-values/values.yaml
2025-11-26 22:35:36.633145 | controller |       pw_name: zuul
2025-11-26 22:35:36.633266 | controller |       readable: true
2025-11-26 22:35:36.633402 | controller |       rgrp: true
2025-11-26 22:35:36.633521 | controller |       roth: true
2025-11-26 22:35:36.633637 | controller |       rusr: true
2025-11-26 22:35:36.633746 | controller |       size: 16482
2025-11-26 22:35:36.633856 | controller |       uid: 1000
2025-11-26 22:35:36.633964 | controller |       wgrp: false
2025-11-26 22:35:36.634072 | controller |       woth: false
2025-11-26 22:35:36.634182 | controller |       writeable: true
2025-11-26 22:35:36.634303 | controller |       wusr: true
2025-11-26 22:35:36.634432 | controller |       xgrp: false
2025-11-26 22:35:36.634549 | controller |       xoth: false
2025-11-26 22:35:36.634666 | controller |       xusr: false
2025-11-26 22:35:36.634777 | controller |   msg: All assertions passed
2025-11-26 22:35:36.634882 | controller |
2025-11-26 22:35:36.634994 | controller | TASK [Ensure we have correct MAC] **********************************************
2025-11-26 22:35:36.635106 | controller | Wednesday 26 November 2025  22:35:36 +0000 (0:00:00.032)       0:00:11.409 ****
2025-11-26 22:35:36.662746 | controller | ok: [instance] => changed=false
2025-11-26 22:35:36.662899 | controller |   msg: All assertions passed
2025-11-26 22:35:36.663033 | controller |
2025-11-26 22:35:36.663175 | controller | TASK [Ensure we don't have node_1 nor node_2] **********************************
2025-11-26 22:35:36.663325 | controller | Wednesday 26 November 2025  22:35:36 +0000 (0:00:00.039)       0:00:11.449 ****
2025-11-26 22:35:36.704512 | controller | ok: [instance] => changed=false
2025-11-26 22:35:36.704705 | controller |   msg: All assertions passed
2025-11-26 22:35:36.704865 | controller |
2025-11-26 22:35:36.705030 | controller | TASK [Update cifmw_networking_env_definition] **********************************
2025-11-26 22:35:36.705194 | controller | Wednesday 26 November 2025  22:35:36 +0000 (0:00:00.041)       0:00:11.491 ****
2025-11-26 22:35:36.735505 | controller | ok: [instance]
2025-11-26 22:35:36.735658 | controller |
2025-11-26 22:35:36.735800 | controller | TASK [Regenerate network-values] ***********************************************
2025-11-26 22:35:36.735938 | controller | Wednesday 26 November 2025  22:35:36 +0000 (0:00:00.031)       0:00:11.522 ****
2025-11-26 22:35:36.763011 | controller |
2025-11-26 22:35:36.763197 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-11-26 22:35:36.763425 | controller | Wednesday 26 November 2025  22:35:36 +0000 (0:00:00.027)       0:00:11.549 ****
2025-11-26 22:35:36.790973 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_snippets.yml for instance
2025-11-26 22:35:36.818911 | controller |
2025-11-26 22:35:36.818932 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-11-26 22:35:36.818949 | controller | Wednesday 26 November 2025  22:35:36 +0000 (0:00:00.027)       0:00:11.577 ****
2025-11-26 22:35:36.818961 | controller | ok: [instance] => changed=false
2025-11-26 22:35:37.019460 | controller |   msg: All assertions passed
2025-11-26 22:35:37.019515 | controller |
2025-11-26 22:35:37.019524 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-11-26 22:35:37.019532 | controller | Wednesday 26 November 2025  22:35:36 +0000 (0:00:00.027)       0:00:11.605 ****
2025-11-26 22:35:37.019545 | controller | ok: [instance]
2025-11-26 22:35:37.019577 | controller |
2025-11-26 22:35:37.019585 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-11-26 22:35:37.046320 | controller | Wednesday 26 November 2025  22:35:37 +0000 (0:00:00.200)       0:00:11.805 ****
2025-11-26 22:35:37.046388 | controller | ok: [instance] => changed=false
2025-11-26 22:35:37.241858 | controller |   msg: All assertions passed
2025-11-26 22:35:37.241910 | controller |
2025-11-26 22:35:37.241920 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-11-26 22:35:37.241928 | controller | Wednesday 26 November 2025  22:35:37 +0000 (0:00:00.026)       0:00:11.832 ****
2025-11-26 22:35:37.241942 | controller | ok: [instance]
2025-11-26 22:35:37.485271 | controller |
2025-11-26 22:35:37.485316 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-11-26 22:35:37.485325 | controller | Wednesday 26 November 2025  22:35:37 +0000 (0:00:00.194)       0:00:12.027 ****
2025-11-26 22:35:37.485361 | controller | ok: [instance]
2025-11-26 22:35:37.522404 | controller |
2025-11-26 22:35:37.522429 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-11-26 22:35:37.522454 | controller | Wednesday 26 November 2025  22:35:37 +0000 (0:00:00.243)       0:00:12.271 ****
2025-11-26 22:35:37.522467 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/network_values.yml for instance
2025-11-26 22:35:37.551016 | controller |
2025-11-26 22:35:37.551034 | controller | TASK [ci_gen_kustomize_values : Assert we have needed parameters] **************
2025-11-26 22:35:37.551042 | controller | Wednesday 26 November 2025  22:35:37 +0000 (0:00:00.037)       0:00:12.308 ****
2025-11-26 22:35:37.551051 | controller | ok: [instance] => changed=false
2025-11-26 22:35:37.763275 | controller |   msg: All assertions passed
2025-11-26 22:35:37.763312 | controller |
2025-11-26 22:35:37.763319 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-11-26 22:35:37.763326 | controller | Wednesday 26 November 2025  22:35:37 +0000 (0:00:00.028)       0:00:12.337 ****
2025-11-26 22:35:37.763355 | controller | ok: [instance]
2025-11-26 22:35:38.253428 | controller |
2025-11-26 22:35:38.253463 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-11-26 22:35:38.253472 | controller | Wednesday 26 November 2025  22:35:37 +0000 (0:00:00.211)       0:00:12.549 ****
2025-11-26 22:35:38.253483 | controller | changed: [instance]
2025-11-26 22:35:38.253509 | controller |
2025-11-26 22:35:38.253517 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-11-26 22:35:38.253618 | controller | Wednesday 26 November 2025  22:35:38 +0000 (0:00:00.490)       0:00:13.039 ****
2025-11-26 22:35:38.281436 | controller | ok: [instance]
2025-11-26 22:35:38.281494 | controller |
2025-11-26 22:35:38.281512 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-11-26 22:35:38.281523 | controller | Wednesday 26 November 2025  22:35:38 +0000 (0:00:00.028)       0:00:13.067 ****
2025-11-26 22:35:38.657873 | controller | ok: [instance]
2025-11-26 22:35:39.014472 | controller |
2025-11-26 22:35:39.014508 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-11-26 22:35:39.014516 | controller | Wednesday 26 November 2025  22:35:38 +0000 (0:00:00.375)       0:00:13.443 ****
2025-11-26 22:35:39.014527 | controller | ok: [instance]
2025-11-26 22:35:39.014580 | controller |
2025-11-26 22:35:39.014597 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-11-26 22:35:39.014607 | controller | Wednesday 26 November 2025  22:35:39 +0000 (0:00:00.356)       0:00:13.800 ****
2025-11-26 22:35:39.393181 | controller | ok: [instance]
2025-11-26 22:35:39.424443 | controller |
2025-11-26 22:35:39.424467 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-11-26 22:35:39.424476 | controller | Wednesday 26 November 2025  22:35:39 +0000 (0:00:00.378)       0:00:14.179 ****
2025-11-26 22:35:39.424487 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_values.yml for instance
2025-11-26 22:35:39.453381 | controller |
2025-11-26 22:35:39.453409 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-11-26 22:35:39.453418 | controller | Wednesday 26 November 2025  22:35:39 +0000 (0:00:00.031)       0:00:14.210 ****
2025-11-26 22:35:39.453429 | controller | ok: [instance] => changed=false
2025-11-26 22:35:39.637450 | controller |   msg: All assertions passed
2025-11-26 22:35:39.637472 | controller |
2025-11-26 22:35:39.637479 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-11-26 22:35:39.637485 | controller | Wednesday 26 November 2025  22:35:39 +0000 (0:00:00.028)       0:00:14.239 ****
2025-11-26 22:35:39.637493 | controller | ok: [instance]
2025-11-26 22:35:39.657138 | controller |
2025-11-26 22:35:39.657160 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-11-26 22:35:39.657174 | controller | Wednesday 26 November 2025  22:35:39 +0000 (0:00:00.184)       0:00:14.424 ****
2025-11-26 22:35:39.657183 | controller | ok: [instance]
2025-11-26 22:35:40.209075 | controller |
2025-11-26 22:35:40.209107 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-11-26 22:35:40.209114 | controller | Wednesday 26 November 2025  22:35:39 +0000 (0:00:00.019)       0:00:14.443 ****
2025-11-26 22:35:40.209130 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/02_ci_data.yaml)
2025-11-26 22:35:40.268501 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/03_user_data_b64.yaml)
2025-11-26 22:35:40.268562 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/04_user_data.yaml)
2025-11-26 22:35:40.268572 | controller |
2025-11-26 22:35:40.268581 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-11-26 22:35:40.268589 | controller | Wednesday 26 November 2025  22:35:40 +0000 (0:00:00.551)       0:00:14.995 ****
2025-11-26 22:35:40.268606 | controller | ok: [instance] => (item=02_ci_data.yaml)
2025-11-26 22:35:40.469607 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-11-26 22:35:40.469650 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-11-26 22:35:40.469659 | controller |
2025-11-26 22:35:40.469667 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-11-26 22:35:40.469673 | controller | Wednesday 26 November 2025  22:35:40 +0000 (0:00:00.059)       0:00:15.054 ****
2025-11-26 22:35:40.469686 | controller | ok: [instance]
2025-11-26 22:35:40.873088 | controller |
2025-11-26 22:35:40.873123 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-11-26 22:35:40.873131 | controller | Wednesday 26 November 2025  22:35:40 +0000 (0:00:00.201)       0:00:15.255 ****
2025-11-26 22:35:40.873141 | controller | changed: [instance]
2025-11-26 22:35:40.901037 | controller |
2025-11-26 22:35:40.901060 | controller | TASK [Regenerate edpm-nodeset-values] ******************************************
2025-11-26 22:35:40.901068 | controller | Wednesday 26 November 2025  22:35:40 +0000 (0:00:00.403)       0:00:15.659 ****
2025-11-26 22:35:40.901078 | controller |
2025-11-26 22:35:40.929218 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-11-26 22:35:40.929239 | controller | Wednesday 26 November 2025  22:35:40 +0000 (0:00:00.028)       0:00:15.687 ****
2025-11-26 22:35:40.929263 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_snippets.yml for instance
2025-11-26 22:35:40.958506 | controller |
2025-11-26 22:35:40.958572 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-11-26 22:35:40.958586 | controller | Wednesday 26 November 2025  22:35:40 +0000 (0:00:00.028)       0:00:15.715 ****
2025-11-26 22:35:40.958603 | controller | ok: [instance] => changed=false
2025-11-26 22:35:40.958645 | controller |   msg: All assertions passed
2025-11-26 22:35:40.958935 | controller |
2025-11-26 22:35:40.958969 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-11-26 22:35:41.140806 | controller | Wednesday 26 November 2025  22:35:40 +0000 (0:00:00.029)       0:00:15.745 ****
2025-11-26 22:35:41.140836 | controller | ok: [instance]
2025-11-26 22:35:41.164536 | controller |
2025-11-26 22:35:41.164588 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-11-26 22:35:41.164602 | controller | Wednesday 26 November 2025  22:35:41 +0000 (0:00:00.182)       0:00:15.927 ****
2025-11-26 22:35:41.164617 | controller | ok: [instance] => changed=false
2025-11-26 22:35:41.164656 | controller |   msg: All assertions passed
2025-11-26 22:35:41.164667 | controller |
2025-11-26 22:35:41.164765 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-11-26 22:35:41.164796 | controller | Wednesday 26 November 2025  22:35:41 +0000 (0:00:00.023)       0:00:15.951 ****
2025-11-26 22:35:41.340453 | controller | ok: [instance]
2025-11-26 22:35:41.340489 | controller |
2025-11-26 22:35:41.340496 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-11-26 22:35:41.340506 | controller | Wednesday 26 November 2025  22:35:41 +0000 (0:00:00.175)       0:00:16.126 ****
2025-11-26 22:35:41.541594 | controller | ok: [instance]
2025-11-26 22:35:41.577989 | controller |
2025-11-26 22:35:41.578015 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-11-26 22:35:41.578024 | controller | Wednesday 26 November 2025  22:35:41 +0000 (0:00:00.201)       0:00:16.328 ****
2025-11-26 22:35:41.578037 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/edpm_nodeset_values.yml for instance
2025-11-26 22:35:41.604967 | controller |
2025-11-26 22:35:41.604986 | controller | TASK [ci_gen_kustomize_values : Check if all required variables for accessing the node is provided.] ***
2025-11-26 22:35:41.604995 | controller | Wednesday 26 November 2025  22:35:41 +0000 (0:00:00.036)       0:00:16.364 ****
2025-11-26 22:35:41.605005 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/edpm_core_asserts.yml for instance
2025-11-26 22:35:41.639913 | controller |
2025-11-26 22:35:41.639930 | controller | TASK [ci_gen_kustomize_values : Ensure the required parameters are defined and configured.] ***
2025-11-26 22:35:41.639937 | controller | Wednesday 26 November 2025  22:35:41 +0000 (0:00:00.027)       0:00:16.391 ****
2025-11-26 22:35:41.639948 | controller | ok: [instance] => changed=false
2025-11-26 22:35:41.667827 | controller |   msg: All assertions passed
2025-11-26 22:35:41.667844 | controller |
2025-11-26 22:35:41.667851 | controller | TASK [ci_gen_kustomize_values : Get common data for dataplane templating] ******
2025-11-26 22:35:41.667857 | controller | Wednesday 26 November 2025  22:35:41 +0000 (0:00:00.034)       0:00:16.426 ****
2025-11-26 22:35:41.667866 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/edpm_core_facts.yml for instance
2025-11-26 22:35:41.686492 | controller |
2025-11-26 22:35:41.686541 | controller | TASK [ci_gen_kustomize_values : Fetch the already deployed services for further usage] ***
2025-11-26 22:35:41.686555 | controller | Wednesday 26 November 2025  22:35:41 +0000 (0:00:00.028)       0:00:16.454 ****
2025-11-26 22:35:41.686574 | controller | skipping: [instance]
2025-11-26 22:35:41.686619 | controller |
2025-11-26 22:35:41.686634 | controller | TASK [ci_gen_kustomize_values : Expose the deployed services as a fact] ********
2025-11-26 22:35:41.686720 | controller | Wednesday 26 November 2025  22:35:41 +0000 (0:00:00.018)       0:00:16.473 ****
2025-11-26 22:35:41.708740 | controller | ok: [instance]
2025-11-26 22:35:41.737464 | controller |
2025-11-26 22:35:41.737515 | controller | TASK [ci_gen_kustomize_values : Check if required variables for nova migration are provided.] ***
2025-11-26 22:35:41.737530 | controller | Wednesday 26 November 2025  22:35:41 +0000 (0:00:00.022)       0:00:16.495 ****
2025-11-26 22:35:41.737551 | controller | ok: [instance] => changed=false
2025-11-26 22:35:41.937473 | controller |   msg: All assertions passed
2025-11-26 22:35:41.937537 | controller |
2025-11-26 22:35:41.937554 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-11-26 22:35:41.937562 | controller | Wednesday 26 November 2025  22:35:41 +0000 (0:00:00.028)       0:00:16.523 ****
2025-11-26 22:35:41.937575 | controller | ok: [instance]
2025-11-26 22:35:42.349493 | controller |
2025-11-26 22:35:42.349561 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-11-26 22:35:42.349571 | controller | Wednesday 26 November 2025  22:35:41 +0000 (0:00:00.199)       0:00:16.723 ****
2025-11-26 22:35:42.349586 | controller | ok: [instance]
2025-11-26 22:35:42.375421 | controller |
2025-11-26 22:35:42.375443 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-11-26 22:35:42.375452 | controller | Wednesday 26 November 2025  22:35:42 +0000 (0:00:00.411)       0:00:17.135 ****
2025-11-26 22:35:42.375464 | controller | ok: [instance]
2025-11-26 22:35:42.375499 | controller |
2025-11-26 22:35:42.375791 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-11-26 22:35:42.733604 | controller | Wednesday 26 November 2025  22:35:42 +0000 (0:00:00.026)       0:00:17.162 ****
2025-11-26 22:35:42.733670 | controller | ok: [instance]
2025-11-26 22:35:43.111054 | controller |
2025-11-26 22:35:43.111105 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-11-26 22:35:43.111114 | controller | Wednesday 26 November 2025  22:35:42 +0000 (0:00:00.357)       0:00:17.519 ****
2025-11-26 22:35:43.111126 | controller | ok: [instance]
2025-11-26 22:35:43.478436 | controller |
2025-11-26 22:35:43.478488 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-11-26 22:35:43.478497 | controller | Wednesday 26 November 2025  22:35:43 +0000 (0:00:00.377)       0:00:17.897 ****
2025-11-26 22:35:43.478510 | controller | ok: [instance]
2025-11-26 22:35:43.478534 | controller |
2025-11-26 22:35:43.478540 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-11-26 22:35:43.478547 | controller | Wednesday 26 November 2025  22:35:43 +0000 (0:00:00.367)       0:00:18.264 ****
2025-11-26 22:35:43.511086 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_values.yml for instance
2025-11-26 22:35:43.511162 | controller |
2025-11-26 22:35:43.511285 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-11-26 22:35:43.511440 | controller | Wednesday 26 November 2025  22:35:43 +0000 (0:00:00.033)       0:00:18.298 ****
2025-11-26 22:35:43.545832 | controller | ok: [instance] => changed=false
2025-11-26 22:35:43.751884 | controller |   msg: All assertions passed
2025-11-26 22:35:43.751902 | controller |
2025-11-26 22:35:43.751910 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-11-26 22:35:43.751916 | controller | Wednesday 26 November 2025  22:35:43 +0000 (0:00:00.034)       0:00:18.332 ****
2025-11-26 22:35:43.751925 | controller | ok: [instance]
2025-11-26 22:35:43.775820 | controller |
2025-11-26 22:35:43.775838 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-11-26 22:35:43.775846 | controller | Wednesday 26 November 2025  22:35:43 +0000 (0:00:00.206)       0:00:18.538 ****
2025-11-26 22:35:43.775855 | controller | ok: [instance]
2025-11-26 22:35:44.305645 | controller |
2025-11-26 22:35:44.305677 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-11-26 22:35:44.305685 | controller | Wednesday 26 November 2025  22:35:43 +0000 (0:00:00.023)       0:00:18.562 ****
2025-11-26 22:35:44.305697 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/02_ci_data.yaml)
2025-11-26 22:35:44.364170 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/03_user_data_b64.yaml)
2025-11-26 22:35:44.364194 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/04_user_data.yaml)
2025-11-26 22:35:44.364202 | controller |
2025-11-26 22:35:44.364209 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-11-26 22:35:44.364215 | controller | Wednesday 26 November 2025  22:35:44 +0000 (0:00:00.529)       0:00:19.091 ****
2025-11-26 22:35:44.364226 | controller | ok: [instance] => (item=02_ci_data.yaml)
2025-11-26 22:35:44.560874 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-11-26 22:35:44.560911 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-11-26 22:35:44.560919 | controller |
2025-11-26 22:35:44.560925 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-11-26 22:35:44.560932 | controller | Wednesday 26 November 2025  22:35:44 +0000 (0:00:00.058)       0:00:19.150 ****
2025-11-26 22:35:44.560943 | controller | ok: [instance]
2025-11-26 22:35:44.922491 | controller |
2025-11-26 22:35:44.922553 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-11-26 22:35:44.922562 | controller | Wednesday 26 November 2025  22:35:44 +0000 (0:00:00.197)       0:00:19.347 ****
2025-11-26 22:35:44.922575 | controller | ok: [instance]
2025-11-26 22:35:44.922604 | controller |
2025-11-26 22:35:44.922792 | controller | TASK [Copy generated values to correct location] *******************************
2025-11-26 22:35:44.922839 | controller | Wednesday 26 November 2025  22:35:44 +0000 (0:00:00.361)       0:00:19.709 ****
2025-11-26 22:35:45.309487 | controller | changed: [instance] => (item={'key': 'network-values', 'value': 'examples/va/hci/control-plane/networking/nncp/values.yaml'})
2025-11-26 22:35:45.309541 | controller | changed: [instance] => (item={'key': 'edpm-nodeset-values', 'value': 'examples/va/hci/edpm-pre-ceph/nodeset/values.yaml'})
2025-11-26 22:35:45.309550 | controller |
2025-11-26 22:35:45.309556 | controller | TASK [Ensure kustomize is able to build] ***************************************
2025-11-26 22:35:45.309565 | controller | Wednesday 26 November 2025  22:35:45 +0000 (0:00:00.386)       0:00:20.095 ****
2025-11-26 22:35:45.937492 | controller | changed: [instance] => (item={'name': 'ctlplane', 'path': 'examples/va/hci/control-plane/networking/nncp'})
2025-11-26 22:35:45.937571 | controller | changed: [instance] => (item={'name': 'dataplane', 'path': 'examples/va/hci/edpm-pre-ceph/nodeset'})
2025-11-26 22:35:45.937587 | controller |
2025-11-26 22:35:45.937593 | controller | PLAY RECAP *********************************************************************
2025-11-26 22:35:45.937600 | controller | instance                   : ok=107  changed=20   unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-11-26 22:35:45.937605 | controller |
2025-11-26 22:35:45.937610 | controller | Wednesday 26 November 2025  22:35:45 +0000 (0:00:00.627)       0:00:20.722 ****
2025-11-26 22:35:45.937616 | controller | ===============================================================================
2025-11-26 22:35:45.937621 | controller | Gathering Facts --------------------------------------------------------- 0.83s
2025-11-26 22:35:45.937626 | controller | Ensure architecture repository is building ------------------------------ 0.74s
2025-11-26 22:35:45.937633 | controller | Ensure kustomize is able to build --------------------------------------- 0.63s
2025-11-26 22:35:45.937656 | controller | ci_gen_kustomize_values : Generate CI snippet --------------------------- 0.57s
2025-11-26 22:35:45.937663 | controller | ci_gen_kustomize_values : Load various snippet files -------------------- 0.55s
2025-11-26 22:35:45.938382 | controller | ci_gen_kustomize_values : Load various snippet files -------------------- 0.54s
2025-11-26 22:35:45.938413 | controller | ci_gen_kustomize_values : Load various snippet files -------------------- 0.53s
2025-11-26 22:35:45.938420 | controller | ci_gen_kustomize_values : Load various snippet files -------------------- 0.51s
2025-11-26 22:35:45.938425 | controller | ci_gen_kustomize_values : Generate CI snippet --------------------------- 0.49s
2025-11-26 22:35:45.938430 | controller | Create nova migration keypair ------------------------------------------- 0.43s
2025-11-26 22:35:45.938435 | controller | ci_gen_kustomize_values : Generate CI snippet --------------------------- 0.42s
2025-11-26 22:35:45.938441 | controller | ci_gen_kustomize_values : Generate CI snippet --------------------------- 0.41s
2025-11-26 22:35:45.938447 | controller | ci_gen_kustomize_values : Output values file ---------------------------- 0.40s
2025-11-26 22:35:45.939165 | controller | Copy generated values to correct location ------------------------------- 0.39s
2025-11-26 22:35:46.029052 | controller | ci_gen_kustomize_values : Push base64 CI patches ------------------------ 0.38s
2025-11-26 22:35:46.029076 | controller | ci_gen_kustomize_values : Push user provided dataset -------------------- 0.38s
2025-11-26 22:35:46.029084 | controller | ci_gen_kustomize_values : Copy the base values.yaml --------------------- 0.38s
2025-11-26 22:35:46.029090 | controller | ci_gen_kustomize_values : Push user provided dataset -------------------- 0.38s
2025-11-26 22:35:46.029096 | controller | ci_gen_kustomize_values : Push base64 CI patches ------------------------ 0.38s
2025-11-26 22:35:46.029102 | controller | ci_gen_kustomize_values : Copy the base values.yaml --------------------- 0.37s
2025-11-26 22:35:46.029112 | controller | INFO     Running default > cleanup
2025-11-26 22:35:46.029623 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-11-26 22:35:46.030293 | controller | INFO     Writing /tmp/report.html report.
2025-11-26 22:35:46.431502 | controller | changed
2025-11-26 22:35:46.455207 | 
2025-11-26 22:35:46.455265 | PLAY RECAP
2025-11-26 22:35:46.455305 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-26 22:35:46.455326 | 
2025-11-26 22:35:46.514547 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-26 22:35:46.515278 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-26 22:35:47.012553 | 
2025-11-26 22:35:47.012659 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-11-26 22:35:47.033058 | 
2025-11-26 22:35:47.033216 | TASK [Filter out host if needed]
2025-11-26 22:35:47.041901 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-26 22:35:47.046309 | 
2025-11-26 22:35:47.046375 | TASK [Ensure file is present]
2025-11-26 22:35:47.359453 | controller | ok
2025-11-26 22:35:47.366100 | 
2025-11-26 22:35:47.366169 | TASK [Manage molecule report file]
2025-11-26 22:35:47.891535 | controller | changed
2025-11-26 22:35:47.896787 | 
2025-11-26 22:35:47.896855 | TASK [Check if we get ci-framework-data basedir]
2025-11-26 22:35:48.098459 | controller | ok
2025-11-26 22:35:48.103220 | 
2025-11-26 22:35:48.103286 | TASK [Create ci-framework-data log directory for zuul]
2025-11-26 22:35:48.406679 | controller | changed
2025-11-26 22:35:48.411968 | 
2025-11-26 22:35:48.412067 | TASK [Copy ci-framework interesting files]
2025-11-26 22:35:48.615190 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-11-26 22:35:48.937881 | controller | changed
2025-11-26 22:35:48.942629 | 
2025-11-26 22:35:48.942692 | TASK [Get SELinux listing]
2025-11-26 22:35:49.468561 | controller | changed
2025-11-26 22:35:49.473643 | 
2025-11-26 22:35:49.473709 | TASK [Generate log index]
2025-11-26 22:35:50.114843 | controller | changed
2025-11-26 22:35:50.119489 | 
2025-11-26 22:35:50.119552 | TASK [Get some env related data]
2025-11-26 22:35:50.735198 | controller | /home/zuul/.local/bin/ansible
2025-11-26 22:35:51.646953 | controller | changed
2025-11-26 22:35:51.651779 | 
2025-11-26 22:35:51.651839 | TASK [Generate list of logs to collect in home directory]
2025-11-26 22:35:51.953838 | controller | ok: All paths examined
2025-11-26 22:35:51.958706 | 
2025-11-26 22:35:51.958768 | LOOP [Copy logs from home directory]
2025-11-26 22:35:52.291975 | controller | changed:
2025-11-26 22:35:52.292091 | controller | {
2025-11-26 22:35:52.292117 | controller |   "atime": 1764196384.938686,
2025-11-26 22:35:52.292135 | controller |   "ctime": 1764196400.8757052,
2025-11-26 22:35:52.292151 | controller |   "dev": 64513,
2025-11-26 22:35:52.292166 | controller |   "gid": 1000,
2025-11-26 22:35:52.292180 | controller |   "gr_name": "zuul",
2025-11-26 22:35:52.292194 | controller |   "inode": 4329175,
2025-11-26 22:35:52.292208 | controller |   "isblk": false,
2025-11-26 22:35:52.292222 | controller |   "ischr": false,
2025-11-26 22:35:52.292235 | controller |   "isdir": false,
2025-11-26 22:35:52.292248 | controller |   "isfifo": false,
2025-11-26 22:35:52.292261 | controller |   "isgid": false,
2025-11-26 22:35:52.292274 | controller |   "islnk": false,
2025-11-26 22:35:52.292286 | controller |   "isreg": true,
2025-11-26 22:35:52.292298 | controller |   "issock": false,
2025-11-26 22:35:52.292311 | controller |   "isuid": false,
2025-11-26 22:35:52.292323 | controller |   "mode": "0644",
2025-11-26 22:35:52.292336 | controller |   "mtime": 1764196400.8757052,
2025-11-26 22:35:52.292348 | controller |   "nlink": 1,
2025-11-26 22:35:52.292361 | controller |   "path": "/home/zuul/ansible.log",
2025-11-26 22:35:52.292375 | controller |   "pw_name": "zuul",
2025-11-26 22:35:52.292408 | controller |   "rgrp": true,
2025-11-26 22:35:52.292425 | controller |   "roth": true,
2025-11-26 22:35:52.292439 | controller |   "rusr": true,
2025-11-26 22:35:52.292453 | controller |   "size": 6749,
2025-11-26 22:35:52.292466 | controller |   "uid": 1000,
2025-11-26 22:35:52.292479 | controller |   "wgrp": false,
2025-11-26 22:35:52.292492 | controller |   "woth": false,
2025-11-26 22:35:52.292508 | controller |   "wusr": true,
2025-11-26 22:35:52.292522 | controller |   "xgrp": false,
2025-11-26 22:35:52.292536 | controller |   "xoth": false,
2025-11-26 22:35:52.292548 | controller |   "xusr": false
2025-11-26 22:35:52.292561 | controller | }
2025-11-26 22:35:52.304805 | 
2025-11-26 22:35:52.304882 | TASK [Copy crio stats log file]
2025-11-26 22:35:52.318269 | controller | skipping: Conditional result was False
2025-11-26 22:35:52.323436 | 
2025-11-26 22:35:52.323500 | TASK [Get SELinux related data]
2025-11-26 22:35:52.533768 | controller | <no matches>
2025-11-26 22:35:52.849777 | controller | ERROR
2025-11-26 22:35:52.849923 | controller | {
2025-11-26 22:35:52.849956 | controller |   "delta": "0:00:00.011691",
2025-11-26 22:35:52.849977 | controller |   "end": "2025-11-26 22:35:52.534750",
2025-11-26 22:35:52.849995 | controller |   "msg": "non-zero return code",
2025-11-26 22:35:52.850010 | controller |   "rc": 1,
2025-11-26 22:35:52.850026 | controller |   "start": "2025-11-26 22:35:52.523059"
2025-11-26 22:35:52.850040 | controller | }
2025-11-26 22:35:52.850073 | controller | ERROR: Ignoring Errors
2025-11-26 22:35:52.854747 | 
2025-11-26 22:35:52.854812 | TASK [Create system configuration directory]
2025-11-26 22:35:53.045798 | controller | changed
2025-11-26 22:35:53.050518 | 
2025-11-26 22:35:53.050583 | TASK [Get some of the system configurations]
2025-11-26 22:35:53.577490 | controller | changed
2025-11-26 22:35:53.582206 | 
2025-11-26 22:35:53.582274 | TASK [Copy generated documentation if available]
2025-11-26 22:35:53.595522 | controller | skipping: Conditional result was False
2025-11-26 22:35:53.600577 | 
2025-11-26 22:35:53.600637 | TASK [Copy generated AsciiDoc documentation if available]
2025-11-26 22:35:53.614164 | controller | skipping: Conditional result was False
2025-11-26 22:35:53.619801 | 
2025-11-26 22:35:53.619863 | TASK [Compress logs bigger than 2MB]
2025-11-26 22:35:54.145285 | controller | changed
2025-11-26 22:35:54.150259 | 
2025-11-26 22:35:54.150314 | TASK [Copy files from workspace on node]
2025-11-26 22:35:54.168062 | controller | ok
2025-11-26 22:35:54.189312 | 
2025-11-26 22:35:54.189378 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-26 22:35:54.202587 | controller | skipping: Conditional result was False
2025-11-26 22:35:54.208000 | 
2025-11-26 22:35:54.208077 | TASK [fetch-output : Set log path for single node]
2025-11-26 22:35:54.236796 | controller | ok
2025-11-26 22:35:54.241425 | 
2025-11-26 22:35:54.241487 | LOOP [fetch-output : Ensure local output dirs]
2025-11-26 22:35:54.431762 | controller -> localhost | ok: "/var/lib/zuul/builds/cc11b9144c8e4896be58e75cd128f17a/work/logs"
2025-11-26 22:35:54.432003 | controller -> localhost | changed: All items complete
2025-11-26 22:35:54.432032 | 
2025-11-26 22:35:54.600901 | controller -> localhost | changed: "/var/lib/zuul/builds/cc11b9144c8e4896be58e75cd128f17a/work/artifacts"
2025-11-26 22:35:54.767840 | controller -> localhost | changed: "/var/lib/zuul/builds/cc11b9144c8e4896be58e75cd128f17a/work/docs"
2025-11-26 22:35:54.777528 | 
2025-11-26 22:35:54.777616 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-26 22:35:55.322618 | controller | changed:
2025-11-26 22:35:55.322803 | controller | .d..t...... ./
2025-11-26 22:35:55.322835 | controller | >f+++++++++ README.html
2025-11-26 22:35:55.322859 | controller | >f+++++++++ ansible-execution.log
2025-11-26 22:35:55.322883 | controller | >f+++++++++ ansible.log
2025-11-26 22:35:55.322904 | controller | >f+++++++++ dmesg.log
2025-11-26 22:35:55.322924 | controller | >f+++++++++ installed-pkgs.log
2025-11-26 22:35:55.322946 | controller | >f+++++++++ python.log
2025-11-26 22:35:55.322966 | controller | >f+++++++++ registries.conf
2025-11-26 22:35:55.322985 | controller | >f+++++++++ report.html
2025-11-26 22:35:55.323005 | controller | >f+++++++++ selinux-denials.log
2025-11-26 22:35:55.323023 | controller | >f+++++++++ selinux-listing.log
2025-11-26 22:35:55.323041 | controller | cd+++++++++ ci-framework-data/
2025-11-26 22:35:55.323074 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-11-26 22:35:55.323093 | controller | >f+++++++++ ci-framework-data/artifacts/ctlplane.yaml
2025-11-26 22:35:55.323111 | controller | >f+++++++++ ci-framework-data/artifacts/dataplane.yaml
2025-11-26 22:35:55.323129 | controller | >f+++++++++ ci-framework-data/artifacts/nova_migration_key
2025-11-26 22:35:55.323146 | controller | >f+++++++++ ci-framework-data/artifacts/nova_migration_key.pub
2025-11-26 22:35:55.323167 | controller | >f+++++++++ ci-framework-data/artifacts/vanilla-ctlplane.yaml
2025-11-26 22:35:55.323184 | controller | >f+++++++++ ci-framework-data/artifacts/vanilla-dataplane.yaml
2025-11-26 22:35:55.323201 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-11-26 22:35:55.323217 | controller | cd+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/
2025-11-26 22:35:55.323234 | controller | cd+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-nodeset-values/
2025-11-26 22:35:55.323252 | controller | >f+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-nodeset-values/values.yaml
2025-11-26 22:35:55.323270 | controller | cd+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/
2025-11-26 22:35:55.323288 | controller | >f+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/values.yaml
2025-11-26 22:35:55.323305 | controller | >f+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/values.yaml.32586.2025-11-26@22:35:40~
2025-11-26 22:35:55.323322 | controller | cd+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/
2025-11-26 22:35:55.323339 | controller | cd+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/
2025-11-26 22:35:55.323356 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/01_original.yaml
2025-11-26 22:35:55.323377 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/02_ci_data.yaml
2025-11-26 22:35:55.323420 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/03_user_data_b64.yaml
2025-11-26 22:35:55.323442 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/04_user_data.yaml
2025-11-26 22:35:55.323460 | controller | cd+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/
2025-11-26 22:35:55.323477 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/01_original.yaml
2025-11-26 22:35:55.323494 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/02_ci_data.yaml
2025-11-26 22:35:55.323510 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/02_ci_data.yaml.32417.2025-11-26@22:35:38~
2025-11-26 22:35:55.323527 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/03_user_data_b64.yaml
2025-11-26 22:35:55.323543 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/04_user_data.yaml
2025-11-26 22:35:55.323560 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-11-26 22:35:55.323575 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-11-26 22:35:55.323592 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-11-26 22:35:55.323609 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-11-26 22:35:55.323626 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-11-26 22:35:55.323643 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-11-26 22:35:55.323659 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-11-26 22:35:55.323675 | controller | cd+++++++++ ci-framework-data/logs/
2025-11-26 22:35:55.323691 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-11-26 22:35:55.323707 | controller | cd+++++++++ registries.conf.d/
2025-11-26 22:35:55.323723 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-11-26 22:35:55.323739 | controller | cd+++++++++ system-config/
2025-11-26 22:35:55.323755 | controller | cd+++++++++ system-config/libvirt/
2025-11-26 22:35:55.323771 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-11-26 22:35:55.323787 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-11-26 22:35:55.695545 | controller | changed: .d..t...... ./
2025-11-26 22:35:56.058721 | controller | changed: .d..t...... ./
2025-11-26 22:35:56.079024 | 
2025-11-26 22:35:56.079105 | TASK [Return artifact to Zuul]
2025-11-26 22:35:56.106864 | controller | ok
2025-11-26 22:35:56.125318 | 
2025-11-26 22:35:56.125372 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-11-26 22:35:56.125490 | 
2025-11-26 22:35:56.125519 | PLAY RECAP
2025-11-26 22:35:56.125559 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-11-26 22:35:56.125579 | 
2025-11-26 22:35:56.194567 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-26 22:35:56.195273 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-26 22:35:56.689745 | 
2025-11-26 22:35:56.689839 | PLAY [all]
2025-11-26 22:35:56.707482 | 
2025-11-26 22:35:56.707558 | TASK [include_role : fetch-output]
2025-11-26 22:35:56.735053 | controller | ok
2025-11-26 22:35:56.750739 | 
2025-11-26 22:35:56.750819 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-26 22:35:56.794621 | controller | skipping: Conditional result was False
2025-11-26 22:35:56.799913 | 
2025-11-26 22:35:56.799979 | TASK [fetch-output : Set log path for single node]
2025-11-26 22:35:56.828732 | controller | ok
2025-11-26 22:35:56.833279 | 
2025-11-26 22:35:56.833341 | LOOP [fetch-output : Ensure local output dirs]
2025-11-26 22:35:57.141958 | controller -> localhost | ok: "/var/lib/zuul/builds/cc11b9144c8e4896be58e75cd128f17a/work/logs"
2025-11-26 22:35:57.321230 | controller -> localhost | ok: "/var/lib/zuul/builds/cc11b9144c8e4896be58e75cd128f17a/work/artifacts"
2025-11-26 22:35:57.496499 | controller -> localhost | ok: "/var/lib/zuul/builds/cc11b9144c8e4896be58e75cd128f17a/work/docs"
2025-11-26 22:35:57.510900 | 
2025-11-26 22:35:57.510994 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-26 22:35:58.012808 | controller | ok
2025-11-26 22:35:58.012992 | controller | ok: All items complete
2025-11-26 22:35:58.013023 | 
2025-11-26 22:35:58.389968 | controller | ok
2025-11-26 22:35:58.760685 | controller | ok
2025-11-26 22:35:58.778799 | 
2025-11-26 22:35:58.778903 | TASK [include_role : fetch-output-openshift]
2025-11-26 22:35:58.791993 | controller | skipping: Conditional result was False
2025-11-26 22:35:58.797806 | 
2025-11-26 22:35:58.797882 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-26 22:35:59.119061 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006137
2025-11-26 22:35:59.301734 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005824
2025-11-26 22:35:59.331712 | 
2025-11-26 22:35:59.331793 | PLAY [all]
2025-11-26 22:35:59.345428 | 
2025-11-26 22:35:59.345497 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-11-26 22:35:59.750185 | controller | changed
2025-11-26 22:35:59.771098 | 
2025-11-26 22:35:59.771149 | PLAY RECAP
2025-11-26 22:35:59.771191 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-26 22:35:59.771215 | 
2025-11-26 22:35:59.836379 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-26 22:35:59.837136 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-11-26 22:36:00.339112 | 
2025-11-26 22:36:00.339199 | PLAY [localhost]
2025-11-26 22:36:00.355637 | 
2025-11-26 22:36:00.355705 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-26 22:36:00.635744 | localhost | changed
2025-11-26 22:36:00.639922 | 
2025-11-26 22:36:00.639992 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-26 22:36:00.656711 | localhost | ok
2025-11-26 22:36:00.664205 | 
2025-11-26 22:36:00.664271 | TASK [add-fileserver : Create SSH private key tempfile]
2025-11-26 22:36:00.944338 | localhost | changed
2025-11-26 22:36:00.950031 | 
2025-11-26 22:36:00.950128 | TASK [add-fileserver : Create SSH private key from secret]
2025-11-26 22:36:01.462560 | localhost | changed
2025-11-26 22:36:01.467470 | 
2025-11-26 22:36:01.467540 | TASK [add-fileserver : Add fileserver ssh key]
2025-11-26 22:36:01.779073 | localhost | Identity added: /var/lib/zuul/builds/cc11b9144c8e4896be58e75cd128f17a/work/tmp/ansible.zkk0d21_ (/var/lib/zuul/builds/cc11b9144c8e4896be58e75cd128f17a/work/tmp/ansible.zkk0d21_)
2025-11-26 22:36:01.779232 | localhost | ok: Runtime: 0:00:00.005677
2025-11-26 22:36:01.783393 | 
2025-11-26 22:36:01.783464 | TASK [add-fileserver : Remove SSH private key from disk]
2025-11-26 22:36:01.993634 | localhost | ok: Runtime: 0:00:00.003999
2025-11-26 22:36:01.998130 | 
2025-11-26 22:36:01.998195 | TASK [add-fileserver : Add fileserver to inventory]
2025-11-26 22:36:02.045506 | localhost | changed
2025-11-26 22:36:02.050123 | 
2025-11-26 22:36:02.050190 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-11-26 22:36:02.350409 | localhost | changed
2025-11-26 22:36:02.368765 | 
2025-11-26 22:36:02.368820 | PLAY [localhost]
2025-11-26 22:36:02.379584 | 
2025-11-26 22:36:02.379644 | TASK [Generate bulk log download script]
2025-11-26 22:36:02.397157 | localhost | ok
2025-11-26 22:36:02.407852 | 
2025-11-26 22:36:02.407920 | TASK [local-log-download : Check API endpoint is defined]
2025-11-26 22:36:02.434872 | localhost | ok: All assertions passed
2025-11-26 22:36:02.439099 | 
2025-11-26 22:36:02.439163 | TASK [local-log-download : Create download script]
2025-11-26 22:36:02.776285 | localhost -> localhost | changed
2025-11-26 22:36:02.784545 | 
2025-11-26 22:36:02.784618 | TASK [Register quick-download link]
2025-11-26 22:36:02.801127 | localhost | ok
2025-11-26 22:36:02.833425 | 
2025-11-26 22:36:02.833486 | PLAY [logserver.rdoproject.org]
2025-11-26 22:36:02.842568 | 
2025-11-26 22:36:02.842627 | TASK [Set zuul-log-path fact]
2025-11-26 22:36:02.857770 | logserver.rdoproject.org | ok
2025-11-26 22:36:02.866283 | 
2025-11-26 22:36:02.866343 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-26 22:36:02.882016 | logserver.rdoproject.org | ok
2025-11-26 22:36:02.887496 | 
2025-11-26 22:36:02.887561 | TASK [upload-logs : Create log directories]
2025-11-26 22:36:05.006729 | logserver.rdoproject.org | changed
2025-11-26 22:36:05.009930 | 
2025-11-26 22:36:05.009993 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-11-26 22:36:05.215585 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004182
2025-11-26 22:36:05.219654 | 
2025-11-26 22:36:05.219715 | TASK [upload-logs : Upload logs to log server]
2025-11-26 22:36:06.659261 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-11-26 22:36:06.661857 | 
2025-11-26 22:36:06.661918 | LOOP [upload-logs : Compress console log and json output]
2025-11-26 22:36:06.696164 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-26 22:36:06.703366 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-26 22:36:06.710483 | 
2025-11-26 22:36:06.710567 | LOOP [upload-logs : Upload compressed console log and json output]
2025-11-26 22:36:06.741940 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-26 22:36:06.742136 | 
2025-11-26 22:36:06.745202 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-26 22:36:06.757624 | 
2025-11-26 22:36:06.757709 | LOOP [upload-logs : Upload console log and json output]