2025-09-29 19:26:14.399496 | Job console starting...
2025-09-29 19:26:14.637924 | Updating repositories
2025-09-29 19:26:15.381140 | Preparing job workspace
2025-09-29 19:26:24.121393 | Running Ansible setup...
2025-09-29 19:26:27.220326 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-29 19:26:27.769898 | 
2025-09-29 19:26:27.770005 | PLAY [localhost]
2025-09-29 19:26:27.778319 | 
2025-09-29 19:26:27.778387 | TASK [Gathering Facts]
2025-09-29 19:26:28.633995 | localhost | ok
2025-09-29 19:26:28.650646 | 
2025-09-29 19:26:28.650790 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-09-29 19:26:29.001129 | localhost -> localhost | changed
2025-09-29 19:26:29.007819 | 
2025-09-29 19:26:29.007930 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-09-29 19:26:29.773238 | localhost -> localhost | changed
2025-09-29 19:26:29.782741 | 
2025-09-29 19:26:29.782837 | TASK [Setup log path fact]
2025-09-29 19:26:29.801607 | localhost | ok
2025-09-29 19:26:29.814889 | 
2025-09-29 19:26:29.815022 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-29 19:26:29.833982 | localhost | ok
2025-09-29 19:26:29.843068 | 
2025-09-29 19:26:29.843165 | TASK [emit-job-header : Print job information]
2025-09-29 19:26:29.872233 | # Job Information
2025-09-29 19:26:29.872390 | Ansible Version: 2.15.12
2025-09-29 19:26:29.872424 | Job: cifmw-molecule-ci_gen_kustomize_values
2025-09-29 19:26:29.872448 | Pipeline: github-check
2025-09-29 19:26:29.872468 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-09-29 19:26:29.872487 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3332
2025-09-29 19:26:29.872505 | Log URL (when completed): https://logserver.rdoproject.org/9ed/rdoproject.org/9edf4aeb45c74c7aabd7434214abfd70/
2025-09-29 19:26:29.872523 | Event ID: 01c598b0-9d65-11f0-8b9c-9f217b126b73
2025-09-29 19:26:29.876308 | 
2025-09-29 19:26:29.876381 | LOOP [emit-job-header : Print node information]
2025-09-29 19:26:29.977869 | localhost | ok:
2025-09-29 19:26:29.978046 | localhost | # Node Information
2025-09-29 19:26:29.978078 | localhost | Inventory Hostname: controller
2025-09-29 19:26:29.978103 | localhost | Hostname: np0005460810
2025-09-29 19:26:29.978123 | localhost | Username: zuul
2025-09-29 19:26:29.978143 | localhost | Distro: CentOS 9
2025-09-29 19:26:29.978161 | localhost | Provider: ibm-bm4-nodepool
2025-09-29 19:26:29.978178 | localhost | Region: regionOne
2025-09-29 19:26:29.978194 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-09-29 19:26:29.978210 | localhost | Product Name: OpenStack Compute
2025-09-29 19:26:29.978225 | localhost | Interface IP: 192.168.26.148
2025-09-29 19:26:30.007559 | 
2025-09-29 19:26:30.007647 | PLAY [all]
2025-09-29 19:26:30.014223 | 
2025-09-29 19:26:30.014324 | TASK [Gather network facts]
2025-09-29 19:26:30.378877 | controller | ok
2025-09-29 19:26:30.393940 | 
2025-09-29 19:26:30.394093 | TASK [include_role : start-zuul-console]
2025-09-29 19:26:30.417125 | controller | ok
2025-09-29 19:26:30.430705 | 
2025-09-29 19:26:30.430806 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-09-29 19:26:30.768674 | controller | ok
2025-09-29 19:26:30.777298 | 
2025-09-29 19:26:30.777365 | TASK [include_role : add-build-sshkey]
2025-09-29 19:26:30.795337 | controller | ok
2025-09-29 19:26:30.809100 | 
2025-09-29 19:26:30.809180 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-09-29 19:26:31.015356 | controller -> localhost | ok
2025-09-29 19:26:31.021633 | 
2025-09-29 19:26:31.021721 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-09-29 19:26:31.040678 | controller | ok
2025-09-29 19:26:31.053602 | controller | included: /var/lib/zuul/builds/9edf4aeb45c74c7aabd7434214abfd70/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-09-29 19:26:31.059500 | 
2025-09-29 19:26:31.059567 | TASK [add-build-sshkey : Create Temp SSH key]
2025-09-29 19:26:31.788289 | controller -> localhost | Generating public/private rsa key pair.
2025-09-29 19:26:31.788498 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9edf4aeb45c74c7aabd7434214abfd70/work/9edf4aeb45c74c7aabd7434214abfd70_id_rsa.
2025-09-29 19:26:31.788539 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9edf4aeb45c74c7aabd7434214abfd70/work/9edf4aeb45c74c7aabd7434214abfd70_id_rsa.pub.
2025-09-29 19:26:31.788565 | controller -> localhost | The key fingerprint is:
2025-09-29 19:26:31.788587 | controller -> localhost | SHA256:wUXk4k2RDWzMka0cUSblcXEKLxSUHIKCxybJ9h13o6U zuul-build-sshkey
2025-09-29 19:26:31.788627 | controller -> localhost | The key's randomart image is:
2025-09-29 19:26:31.788650 | controller -> localhost | +---[RSA 3072]----+
2025-09-29 19:26:31.788669 | controller -> localhost | |   . +   B@&@+o..|
2025-09-29 19:26:31.788689 | controller -> localhost | |    * =.ooXBO= o |
2025-09-29 19:26:31.788708 | controller -> localhost | |   . = o+=oBo.o  |
2025-09-29 19:26:31.788727 | controller -> localhost | |      ...=E  .   |
2025-09-29 19:26:31.788745 | controller -> localhost | |        S .      |
2025-09-29 19:26:31.788763 | controller -> localhost | |                 |
2025-09-29 19:26:31.788781 | controller -> localhost | |                 |
2025-09-29 19:26:31.788799 | controller -> localhost | |                 |
2025-09-29 19:26:31.788817 | controller -> localhost | |                 |
2025-09-29 19:26:31.788835 | controller -> localhost | +----[SHA256]-----+
2025-09-29 19:26:31.788882 | controller -> localhost | ok: Runtime: 0:00:00.344622
2025-09-29 19:26:31.795950 | 
2025-09-29 19:26:31.796033 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-09-29 19:26:31.826721 | controller | ok
2025-09-29 19:26:31.837161 | controller | included: /var/lib/zuul/builds/9edf4aeb45c74c7aabd7434214abfd70/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-09-29 19:26:31.845547 | 
2025-09-29 19:26:31.845652 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-09-29 19:26:31.869826 | controller | skipping: Conditional result was False
2025-09-29 19:26:31.875401 | 
2025-09-29 19:26:31.875491 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-09-29 19:26:32.277953 | controller | changed
2025-09-29 19:26:32.282661 | 
2025-09-29 19:26:32.282726 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-09-29 19:26:32.474051 | controller | ok
2025-09-29 19:26:32.480160 | 
2025-09-29 19:26:32.480248 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-09-29 19:26:33.055349 | controller | changed
2025-09-29 19:26:33.060377 | 
2025-09-29 19:26:33.060465 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-09-29 19:26:33.713871 | controller | changed
2025-09-29 19:26:33.718790 | 
2025-09-29 19:26:33.718870 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-09-29 19:26:33.757394 | controller | skipping: Conditional result was False
2025-09-29 19:26:33.763105 | 
2025-09-29 19:26:33.763171 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-09-29 19:26:34.288162 | controller -> localhost | changed
2025-09-29 19:26:34.298306 | 
2025-09-29 19:26:34.298426 | TASK [add-build-sshkey : Add back temp key]
2025-09-29 19:26:34.575992 | controller -> localhost | Identity added: /var/lib/zuul/builds/9edf4aeb45c74c7aabd7434214abfd70/work/9edf4aeb45c74c7aabd7434214abfd70_id_rsa (zuul-build-sshkey)
2025-09-29 19:26:34.576189 | controller -> localhost | ok: Runtime: 0:00:00.006879
2025-09-29 19:26:34.583322 | 
2025-09-29 19:26:34.583392 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-09-29 19:26:34.905561 | controller | ok
2025-09-29 19:26:34.910268 | 
2025-09-29 19:26:34.910335 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-09-29 19:26:34.933362 | controller | skipping: Conditional result was False
2025-09-29 19:26:34.943527 | 
2025-09-29 19:26:34.943601 | TASK [include_role : validate-host]
2025-09-29 19:26:34.972613 | controller | ok
2025-09-29 19:26:34.992356 | 
2025-09-29 19:26:34.992435 | TASK [validate-host : Define zuul_info_dir fact]
2025-09-29 19:26:35.036094 | controller | ok
2025-09-29 19:26:35.040727 | 
2025-09-29 19:26:35.040794 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-09-29 19:26:35.277133 | controller -> localhost | ok
2025-09-29 19:26:35.283165 | 
2025-09-29 19:26:35.283272 | TASK [validate-host : Collect information about the host]
2025-09-29 19:26:35.912384 | controller | ok
2025-09-29 19:26:35.945385 | 
2025-09-29 19:26:35.945473 | TASK [validate-host : Sanitize hostname]
2025-09-29 19:26:36.006641 | controller | ok
2025-09-29 19:26:36.014701 | 
2025-09-29 19:26:36.014795 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-09-29 19:26:36.526652 | controller -> localhost | changed
2025-09-29 19:26:36.532971 | 
2025-09-29 19:26:36.533066 | TASK [validate-host : Collect information about zuul worker]
2025-09-29 19:26:36.876562 | controller | ok
2025-09-29 19:26:36.882531 | 
2025-09-29 19:26:36.882615 | TASK [validate-host : Write out all zuul information for each host]
2025-09-29 19:26:37.416662 | controller -> localhost | changed
2025-09-29 19:26:37.425944 | 
2025-09-29 19:26:37.426035 | TASK [include_role : prepare-workspace-openshift]
2025-09-29 19:26:37.465502 | controller | skipping: Conditional result was False
2025-09-29 19:26:37.471035 | 
2025-09-29 19:26:37.471134 | TASK [include_role : remove-zuul-sshkey]
2025-09-29 19:26:37.505142 | controller | skipping: Conditional result was False
2025-09-29 19:26:37.513654 | 
2025-09-29 19:26:37.513758 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-09-29 19:26:37.773732 | controller | ok: "logs"
2025-09-29 19:26:37.773963 | controller | ok: All items complete
2025-09-29 19:26:37.774004 | 
2025-09-29 19:26:37.965852 | controller | ok: "artifacts"
2025-09-29 19:26:38.155518 | controller | ok: "docs"
2025-09-29 19:26:38.161289 | 
2025-09-29 19:26:38.161364 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-09-29 19:26:38.448313 | controller | changed: "logs"
2025-09-29 19:26:38.630363 | controller | changed: "artifacts"
2025-09-29 19:26:38.811133 | controller | changed: "docs"
2025-09-29 19:26:38.884193 | 
2025-09-29 19:26:38.884287 | PLAY RECAP
2025-09-29 19:26:38.884334 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-29 19:26:38.884364 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-29 19:26:38.884384 | 
2025-09-29 19:26:39.059941 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-29 19:26:39.060687 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-29 19:26:39.700108 | 
2025-09-29 19:26:39.700233 | PLAY [all]
2025-09-29 19:26:39.724358 | 
2025-09-29 19:26:39.724463 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-29 19:26:39.829476 | controller | ok
2025-09-29 19:26:39.837609 | 
2025-09-29 19:26:39.837719 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-29 19:26:40.203290 | controller | changed
2025-09-29 19:26:40.208857 | 
2025-09-29 19:26:40.208962 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-29 19:26:41.151420 | controller | changed
2025-09-29 19:26:41.169691 | 
2025-09-29 19:26:41.169838 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-09-29 19:26:41.702986 | controller | changed:
2025-09-29 19:26:41.703152 | controller | {
2025-09-29 19:26:41.703185 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-09-29 19:26:41.703215 | controller | }
2025-09-29 19:26:41.919304 | controller | changed:
2025-09-29 19:26:41.919393 | controller | {
2025-09-29 19:26:41.919434 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-09-29 19:26:41.919459 | controller | }
2025-09-29 19:26:42.125276 | controller | changed:
2025-09-29 19:26:42.125427 | controller | {
2025-09-29 19:26:42.125466 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-09-29 19:26:42.125490 | controller | }
2025-09-29 19:26:42.361833 | controller | changed:
2025-09-29 19:26:42.361942 | controller | {
2025-09-29 19:26:42.361987 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-09-29 19:26:42.362011 | controller | }
2025-09-29 19:26:42.574433 | controller | changed:
2025-09-29 19:26:42.575028 | controller | {
2025-09-29 19:26:42.575090 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-09-29 19:26:42.575130 | controller | }
2025-09-29 19:26:42.787562 | controller | changed:
2025-09-29 19:26:42.787644 | controller | {
2025-09-29 19:26:42.787677 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-09-29 19:26:42.787699 | controller | }
2025-09-29 19:26:42.995356 | controller | changed:
2025-09-29 19:26:42.995448 | controller | {
2025-09-29 19:26:42.995478 | 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-09-29 19:26:42.995501 | controller | }
2025-09-29 19:26:43.211140 | controller | changed:
2025-09-29 19:26:43.211238 | controller | {
2025-09-29 19:26:43.211287 | 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-09-29 19:26:43.211312 | controller | }
2025-09-29 19:26:43.428871 | controller | changed:
2025-09-29 19:26:43.429054 | controller | {
2025-09-29 19:26:43.429087 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-09-29 19:26:43.429111 | controller | }
2025-09-29 19:26:43.639588 | controller | changed:
2025-09-29 19:26:43.639674 | controller | {
2025-09-29 19:26:43.639702 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-09-29 19:26:43.639723 | controller | }
2025-09-29 19:26:43.930632 | controller | changed:
2025-09-29 19:26:43.930711 | controller | {
2025-09-29 19:26:43.930740 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-09-29 19:26:43.930763 | controller | }
2025-09-29 19:26:44.062923 | controller | changed:
2025-09-29 19:26:44.063063 | controller | {
2025-09-29 19:26:44.063096 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-09-29 19:26:44.063119 | controller | }
2025-09-29 19:26:44.271460 | controller | changed:
2025-09-29 19:26:44.271536 | controller | {
2025-09-29 19:26:44.271565 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-09-29 19:26:44.271587 | controller | }
2025-09-29 19:26:44.499847 | controller | changed:
2025-09-29 19:26:44.499996 | controller | {
2025-09-29 19:26:44.500031 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-09-29 19:26:44.500054 | controller | }
2025-09-29 19:26:44.730583 | controller | changed:
2025-09-29 19:26:44.730671 | controller | {
2025-09-29 19:26:44.730701 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-09-29 19:26:44.730722 | controller | }
2025-09-29 19:26:44.936053 | controller | changed:
2025-09-29 19:26:44.937137 | controller | {
2025-09-29 19:26:44.937207 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-09-29 19:26:44.937232 | controller | }
2025-09-29 19:26:45.146635 | controller | changed:
2025-09-29 19:26:45.146737 | controller | {
2025-09-29 19:26:45.146767 | 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-09-29 19:26:45.146790 | controller | }
2025-09-29 19:26:45.361499 | controller | changed:
2025-09-29 19:26:45.361595 | controller | {
2025-09-29 19:26:45.361628 | 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-09-29 19:26:45.361660 | controller | }
2025-09-29 19:26:45.575757 | controller | changed:
2025-09-29 19:26:45.575853 | controller | {
2025-09-29 19:26:45.575884 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-09-29 19:26:45.575905 | controller | }
2025-09-29 19:26:45.778657 | controller | changed:
2025-09-29 19:26:45.778740 | controller | {
2025-09-29 19:26:45.778770 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-09-29 19:26:45.778796 | controller | }
2025-09-29 19:26:45.987129 | controller | changed:
2025-09-29 19:26:45.987211 | controller | {
2025-09-29 19:26:45.987240 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-09-29 19:26:45.987278 | controller | }
2025-09-29 19:26:46.221979 | controller | changed:
2025-09-29 19:26:46.224272 | controller | {
2025-09-29 19:26:46.224321 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-09-29 19:26:46.224348 | controller | }
2025-09-29 19:26:46.413227 | controller | changed:
2025-09-29 19:26:46.413330 | controller | {
2025-09-29 19:26:46.413361 | 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-09-29 19:26:46.413383 | controller | }
2025-09-29 19:26:46.634274 | controller | changed:
2025-09-29 19:26:46.634364 | controller | {
2025-09-29 19:26:46.634391 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-09-29 19:26:46.634411 | controller | }
2025-09-29 19:26:46.862368 | controller | changed:
2025-09-29 19:26:46.862465 | controller | {
2025-09-29 19:26:46.862492 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-09-29 19:26:46.862511 | controller | }
2025-09-29 19:26:47.075615 | controller | changed:
2025-09-29 19:26:47.075688 | controller | {
2025-09-29 19:26:47.075714 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-09-29 19:26:47.075734 | controller | }
2025-09-29 19:26:47.107470 | 
2025-09-29 19:26:47.107581 | TASK [Set timezone to UTC]
2025-09-29 19:26:47.531807 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-09-29 19:26:47.542295 | 
2025-09-29 19:26:47.542384 | TASK [Create nodepool directory]
2025-09-29 19:26:47.779010 | controller | changed
2025-09-29 19:26:47.787936 | 
2025-09-29 19:26:47.788027 | TASK [Create nodepool sub_nodes file]
2025-09-29 19:26:48.465809 | controller | changed
2025-09-29 19:26:48.474459 | 
2025-09-29 19:26:48.474543 | TASK [Create nodepool sub_nodes_private file]
2025-09-29 19:26:49.126879 | controller | changed
2025-09-29 19:26:49.132130 | 
2025-09-29 19:26:49.132211 | LOOP [Populate nodepool sub_nodes file]
2025-09-29 19:26:49.161724 | 
2025-09-29 19:26:49.161895 | LOOP [Populate nodepool sub_nodes_private file]
2025-09-29 19:26:49.191732 | 
2025-09-29 19:26:49.191958 | TASK [Create nodepool primary file]
2025-09-29 19:26:49.214846 | controller | skipping: Conditional result was False
2025-09-29 19:26:49.220885 | 
2025-09-29 19:26:49.220984 | TASK [Create nodepool node_private for this node]
2025-09-29 19:26:49.814553 | controller | changed
2025-09-29 19:26:49.825869 | 
2025-09-29 19:26:49.826047 | LOOP [Copy ssh keys to nodepool directory]
2025-09-29 19:26:50.403130 | controller | ok: Item: id_rsa Runtime: 0:00:00.005583
2025-09-29 19:26:50.403312 | 
2025-09-29 19:26:50.576226 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004544
2025-09-29 19:26:50.584697 | 
2025-09-29 19:26:50.584810 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-09-29 19:26:51.143772 | controller | changed
2025-09-29 19:26:51.149439 | 
2025-09-29 19:26:51.149521 | TASK [Validate sudoers config after edits]
2025-09-29 19:26:51.396958 | controller | /etc/sudoers: parsed OK
2025-09-29 19:26:51.397069 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-09-29 19:26:51.397080 | controller | /etc/sudoers.d/zuul: parsed OK
2025-09-29 19:26:51.397087 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-09-29 19:26:51.701973 | controller | ok: Runtime: 0:00:00.004722
2025-09-29 19:26:51.707875 | 
2025-09-29 19:26:51.707983 | TASK [Show the environment passed in to job shell scripts]
2025-09-29 19:26:51.911960 | controller | SHELL=/bin/bash
2025-09-29 19:26:51.912011 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-09-29 19:26:51.912020 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-09-29 19:26:51.912027 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/46/3346/eb5b53fb17db92a72d33d052a56c8edd0d10ea78^openstack-k8s-operators/ci-framework:main:refs/changes/32/3332/2b7f44a769d14bbef534eeafa4b4e5c8b67650d8
2025-09-29 19:26:51.912035 | controller | PWD=/home/zuul
2025-09-29 19:26:51.912040 | controller | ZUUL_PIPELINE=github-check
2025-09-29 19:26:51.912046 | controller | LOGNAME=zuul
2025-09-29 19:26:51.912051 | controller | XDG_SESSION_TYPE=tty
2025-09-29 19:26:51.912056 | controller | _=/usr/bin/env
2025-09-29 19:26:51.912062 | controller | MOTD_SHOWN=pam
2025-09-29 19:26:51.912067 | controller | HOME=/home/zuul
2025-09-29 19:26:51.912072 | controller | LANG=en_US.UTF-8
2025-09-29 19:26:51.912110 | controller | SSH_CONNECTION=192.168.26.12 37712 192.168.26.148 22
2025-09-29 19:26:51.912118 | 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-09-29 19:26:51.912124 | controller | ZUUL_CHANGE_IDS=3346,eb5b53fb17db92a72d33d052a56c8edd0d10ea78 3332,2b7f44a769d14bbef534eeafa4b4e5c8b67650d8
2025-09-29 19:26:51.912129 | controller | WORKSPACE=/home/zuul/workspace
2025-09-29 19:26:51.912135 | controller | XDG_SESSION_CLASS=user
2025-09-29 19:26:51.912140 | controller | SELINUX_ROLE_REQUESTED=
2025-09-29 19:26:51.912146 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-09-29 19:26:51.912151 | controller | USER=zuul
2025-09-29 19:26:51.912156 | controller | ZUUL_VOTING=True
2025-09-29 19:26:51.912161 | controller | BUILD_TIMEOUT=1800000
2025-09-29 19:26:51.912167 | controller | SELINUX_USE_CURRENT_RANGE=
2025-09-29 19:26:51.912172 | controller | SHLVL=1
2025-09-29 19:26:51.912177 | controller | ZUUL_PATCHSET=2b7f44a769d14bbef534eeafa4b4e5c8b67650d8
2025-09-29 19:26:51.912183 | controller | XDG_SESSION_ID=1
2025-09-29 19:26:51.912188 | controller | ZUUL_BRANCH=main
2025-09-29 19:26:51.912193 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-09-29 19:26:51.912198 | controller | SSH_CLIENT=192.168.26.12 37712 22
2025-09-29 19:26:51.912204 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-09-29 19:26:51.912209 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-09-29 19:26:51.912214 | controller | which_declare=declare -f
2025-09-29 19:26:51.912220 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-09-29 19:26:51.912225 | controller | SELINUX_LEVEL_REQUESTED=
2025-09-29 19:26:51.912230 | controller | ZUUL_CHANGE=3332
2025-09-29 19:26:51.912236 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-09-29 19:26:51.912241 | controller | ZUUL_UUID=9edf4aeb45c74c7aabd7434214abfd70
2025-09-29 19:26:51.912248 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-09-29 19:26:51.912254 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-09-29 19:26:51.912259 | controller | }
2025-09-29 19:26:52.243247 | controller | ok: Runtime: 0:00:00.005261
2025-09-29 19:26:52.249001 | 
2025-09-29 19:26:52.249094 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-09-29 19:26:52.263011 | controller | skipping: Conditional result was False
2025-09-29 19:26:52.268843 | 
2025-09-29 19:26:52.268956 | TASK [Symlink /home/zuul-worker/workspace]
2025-09-29 19:26:52.802379 | controller | skipping: Conditional result was False
2025-09-29 19:26:52.810244 | 
2025-09-29 19:26:52.810351 | TASK [Ensure legacy workspace directory]
2025-09-29 19:26:53.049094 | controller | changed
2025-09-29 19:26:53.076266 | 
2025-09-29 19:26:53.076332 | PLAY RECAP
2025-09-29 19:26:53.076378 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-29 19:26:53.076402 | 
2025-09-29 19:26:53.187098 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-29 19:26:53.187897 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-29 19:26:53.887172 | 
2025-09-29 19:26:53.887420 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-09-29 19:26:53.914133 | 
2025-09-29 19:26:53.914216 | TASK [Create zuul-output directory]
2025-09-29 19:26:54.285836 | controller | changed
2025-09-29 19:26:54.291974 | 
2025-09-29 19:26:54.292069 | TASK [Slurp Zuul inventory test]
2025-09-29 19:26:54.693888 | controller -> localhost | ok
2025-09-29 19:26:54.704881 | 
2025-09-29 19:26:54.705046 | TASK [Save zuul inventory]
2025-09-29 19:26:55.635056 | controller | changed
2025-09-29 19:26:55.644407 | 
2025-09-29 19:26:55.644502 | TASK [Save zuul vars without the change_message]
2025-09-29 19:26:56.417283 | controller | changed
2025-09-29 19:26:56.456177 | 
2025-09-29 19:26:56.456284 | PLAY RECAP
2025-09-29 19:26:56.456334 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-29 19:26:56.456359 | 
2025-09-29 19:26:56.615349 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-29 19:26:56.616103 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-29 19:26:57.655381 | 
2025-09-29 19:26:57.655502 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-09-29 19:26:57.691564 | 
2025-09-29 19:26:57.691692 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-09-29 19:26:57.711532 | controller | ok
2025-09-29 19:26:57.736551 | 
2025-09-29 19:26:57.736702 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-29 19:26:57.760546 | controller | skipping: Conditional result was False
2025-09-29 19:26:57.767210 | 
2025-09-29 19:26:57.767324 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-29 19:26:58.252053 | controller | ok
2025-09-29 19:26:58.265972 | 
2025-09-29 19:26:58.266070 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-29 19:26:59.062169 | controller | ok
2025-09-29 19:26:59.076495 | 
2025-09-29 19:26:59.076586 | TASK [Prepare workspace]
2025-09-29 19:26:59.133218 | controller | ok
2025-09-29 19:26:59.178605 | 
2025-09-29 19:26:59.178712 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-09-29 19:26:59.558889 | controller | ok
2025-09-29 19:26:59.566551 | 
2025-09-29 19:26:59.566664 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-09-29 19:27:03.009579 | controller | Output suppressed because no_log was given
2025-09-29 19:27:03.018697 | 
2025-09-29 19:27:03.018773 | LOOP [Create zuul-output directory]
2025-09-29 19:27:03.203588 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-09-29 19:27:03.367060 | controller | ok: "/home/zuul/zuul-output/logs"
2025-09-29 19:27:03.375096 | 
2025-09-29 19:27:03.375226 | TASK [Install required packages]
2025-09-29 19:27:57.942588 | controller | changed
2025-09-29 19:27:57.947860 | 
2025-09-29 19:27:57.947957 | TASK [Install venv]
2025-09-29 19:29:03.225417 | controller | changed
2025-09-29 19:29:03.251956 | 
2025-09-29 19:29:03.252035 | PLAY RECAP
2025-09-29 19:29:03.252082 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-29 19:29:03.252105 | 
2025-09-29 19:29:03.342795 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-29 19:29:03.343609 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-29 19:29:03.965035 | 
2025-09-29 19:29:03.965147 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-09-29 19:29:03.989143 | 
2025-09-29 19:29:03.989254 | TASK [Gather required facts]
2025-09-29 19:29:04.642795 | controller | ok
2025-09-29 19:29:04.648736 | 
2025-09-29 19:29:04.648842 | TASK [Load environment var if instructed to]
2025-09-29 19:29:04.680702 | controller | skipping: Conditional result was False
2025-09-29 19:29:04.694342 | 
2025-09-29 19:29:04.694478 | TASK [Run molecule]
2025-09-29 19:29:05.659586 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-09-29 19:29:05.728812 | controller | INFO     Performing prerun with role_name_check=0...
2025-09-29 19:29:22.416697 | 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-09-29 19:29:22.417157 | 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-09-29 19:29:22.417615 | 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-09-29 19:29:22.418072 | 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-09-29 19:29:22.418570 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+24673bce was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 1.0.0+24673bce (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:29:22.419028 | 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-09-29 19:29:22.419504 | 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-09-29 19:29:22.420159 | 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-09-29 19:29:22.420430 | 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-09-29 19:29:22.420842 | 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-09-29 19:29:22.421313 | 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-09-29 19:29:22.421751 | 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-09-29 19:29:22.422230 | 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-09-29 19:29:22.422729 | 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-09-29 19:29:22.423320 | 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-09-29 19:29:22.432692 | controller | INFO     Running default > prepare
2025-09-29 19:29:23.142646 | controller |
2025-09-29 19:29:23.142722 | controller | PLAY [Prepare] *****************************************************************
2025-09-29 19:29:23.142821 | controller |
2025-09-29 19:29:23.142929 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 19:29:23.143055 | controller | Monday 29 September 2025  19:29:23 +0000 (0:00:00.019)       0:00:00.019 ******
2025-09-29 19:29:23.982873 | controller | ok: [instance]
2025-09-29 19:29:23.982936 | controller |
2025-09-29 19:29:23.983109 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-09-29 19:29:23.983215 | controller | Monday 29 September 2025  19:29:23 +0000 (0:00:00.840)       0:00:00.859 ******
2025-09-29 19:29:23.999893 | controller | skipping: [instance]
2025-09-29 19:29:24.000001 | controller |
2025-09-29 19:29:24.000136 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-09-29 19:29:24.000248 | controller | Monday 29 September 2025  19:29:23 +0000 (0:00:00.017)       0:00:00.876 ******
2025-09-29 19:29:24.031183 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-09-29 19:29:24.031300 | controller |
2025-09-29 19:29:24.031432 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-09-29 19:29:24.031567 | controller | Monday 29 September 2025  19:29:24 +0000 (0:00:00.031)       0:00:00.908 ******
2025-09-29 19:29:24.316275 | controller | ok: [instance]
2025-09-29 19:29:24.316353 | controller |
2025-09-29 19:29:24.316463 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-09-29 19:29:24.316566 | controller | Monday 29 September 2025  19:29:24 +0000 (0:00:00.285)       0:00:01.193 ******
2025-09-29 19:29:24.628110 | controller | ok: [instance]
2025-09-29 19:29:24.628191 | controller |
2025-09-29 19:29:24.628308 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-09-29 19:29:24.628422 | controller | Monday 29 September 2025  19:29:24 +0000 (0:00:00.311)       0:00:01.505 ******
2025-09-29 19:29:25.312989 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-09-29 19:29:25.313097 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-09-29 19:29:25.313209 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-09-29 19:29:25.313309 | controller |
2025-09-29 19:29:25.313418 | controller | TASK [Deploy repo-setup] *******************************************************
2025-09-29 19:29:25.313521 | controller | Monday 29 September 2025  19:29:25 +0000 (0:00:00.684)       0:00:02.190 ******
2025-09-29 19:29:25.370377 | controller |
2025-09-29 19:29:25.370506 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-09-29 19:29:25.370636 | controller | Monday 29 September 2025  19:29:25 +0000 (0:00:00.057)       0:00:02.247 ******
2025-09-29 19:29:25.914101 | controller | changed: [instance] => (item=tmp)
2025-09-29 19:29:25.914181 | controller | changed: [instance] => (item=artifacts/repositories)
2025-09-29 19:29:25.914310 | controller | changed: [instance] => (item=venv/repo_setup)
2025-09-29 19:29:25.914418 | controller |
2025-09-29 19:29:25.914527 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-09-29 19:29:25.914626 | controller | Monday 29 September 2025  19:29:25 +0000 (0:00:00.543)       0:00:02.791 ******
2025-09-29 19:29:26.711772 | controller | ok: [instance]
2025-09-29 19:29:26.712024 | controller |
2025-09-29 19:29:26.712216 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-09-29 19:29:26.712252 | controller | Monday 29 September 2025  19:29:26 +0000 (0:00:00.797)       0:00:03.588 ******
2025-09-29 19:29:27.909996 | controller | changed: [instance]
2025-09-29 19:29:35.905114 | controller |
2025-09-29 19:29:35.905152 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-09-29 19:29:35.905163 | controller | Monday 29 September 2025  19:29:27 +0000 (0:00:01.197)       0:00:04.786 ******
2025-09-29 19:29:35.905174 | controller | changed: [instance]
2025-09-29 19:29:36.614179 | controller |
2025-09-29 19:29:36.614224 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-09-29 19:29:36.614233 | controller | Monday 29 September 2025  19:29:35 +0000 (0:00:07.995)       0:00:12.781 ******
2025-09-29 19:29:36.614243 | controller | changed: [instance]
2025-09-29 19:29:36.634350 | controller |
2025-09-29 19:29:36.634377 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-09-29 19:29:36.634386 | controller | Monday 29 September 2025  19:29:36 +0000 (0:00:00.709)       0:00:13.490 ******
2025-09-29 19:29:36.634398 | controller | skipping: [instance]
2025-09-29 19:29:37.451191 | controller |
2025-09-29 19:29:37.451223 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-09-29 19:29:37.451232 | controller | Monday 29 September 2025  19:29:36 +0000 (0:00:00.020)       0:00:13.511 ******
2025-09-29 19:29:37.451242 | controller | changed: [instance]
2025-09-29 19:29:37.478091 | controller |
2025-09-29 19:29:37.478122 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-09-29 19:29:37.478131 | controller | Monday 29 September 2025  19:29:37 +0000 (0:00:00.816)       0:00:14.327 ******
2025-09-29 19:29:37.478141 | controller | skipping: [instance]
2025-09-29 19:29:37.504818 | controller |
2025-09-29 19:29:37.504849 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-09-29 19:29:37.504857 | controller | Monday 29 September 2025  19:29:37 +0000 (0:00:00.027)       0:00:14.354 ******
2025-09-29 19:29:37.504867 | controller | skipping: [instance]
2025-09-29 19:29:37.530709 | controller |
2025-09-29 19:29:37.530737 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-09-29 19:29:37.530744 | controller | Monday 29 September 2025  19:29:37 +0000 (0:00:00.026)       0:00:14.381 ******
2025-09-29 19:29:37.530754 | controller | skipping: [instance]
2025-09-29 19:29:38.072191 | controller |
2025-09-29 19:29:38.072221 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-09-29 19:29:38.072229 | controller | Monday 29 September 2025  19:29:37 +0000 (0:00:00.026)       0:00:14.407 ******
2025-09-29 19:29:38.072239 | controller | changed: [instance]
2025-09-29 19:29:38.494481 | controller |
2025-09-29 19:29:38.494511 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-09-29 19:29:38.494519 | controller | Monday 29 September 2025  19:29:38 +0000 (0:00:00.541)       0:00:14.949 ******
2025-09-29 19:29:38.494529 | controller | changed: [instance]
2025-09-29 19:29:38.514044 | controller |
2025-09-29 19:29:38.514073 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-09-29 19:29:38.514080 | controller | Monday 29 September 2025  19:29:38 +0000 (0:00:00.422)       0:00:15.371 ******
2025-09-29 19:29:38.514090 | controller | skipping: [instance]
2025-09-29 19:29:38.533338 | controller |
2025-09-29 19:29:38.533361 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-09-29 19:29:38.533368 | controller | Monday 29 September 2025  19:29:38 +0000 (0:00:00.019)       0:00:15.391 ******
2025-09-29 19:29:38.533379 | controller | skipping: [instance]
2025-09-29 19:29:38.552363 | controller |
2025-09-29 19:29:38.552387 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-09-29 19:29:38.552395 | controller | Monday 29 September 2025  19:29:38 +0000 (0:00:00.019)       0:00:15.410 ******
2025-09-29 19:29:38.552405 | controller | skipping: [instance]
2025-09-29 19:29:38.580046 | controller |
2025-09-29 19:29:38.580071 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-09-29 19:29:38.580079 | controller | Monday 29 September 2025  19:29:38 +0000 (0:00:00.018)       0:00:15.429 ******
2025-09-29 19:29:38.580089 | controller | ok: [instance]
2025-09-29 19:29:38.580119 | controller |
2025-09-29 19:29:38.580136 | controller | TASK [repo_setup : Create download directory] **********************************
2025-09-29 19:29:38.580147 | controller | Monday 29 September 2025  19:29:38 +0000 (0:00:00.027)       0:00:15.457 ******
2025-09-29 19:29:38.597109 | controller | skipping: [instance]
2025-09-29 19:29:38.613414 | controller |
2025-09-29 19:29:38.613437 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-09-29 19:29:38.613445 | controller | Monday 29 September 2025  19:29:38 +0000 (0:00:00.016)       0:00:15.473 ******
2025-09-29 19:29:38.613463 | controller | skipping: [instance]
2025-09-29 19:29:38.629726 | controller |
2025-09-29 19:29:38.629751 | controller | TASK [Download the RPM] ********************************************************
2025-09-29 19:29:38.629758 | controller | Monday 29 September 2025  19:29:38 +0000 (0:00:00.016)       0:00:15.490 ******
2025-09-29 19:29:38.629767 | controller | skipping: [instance]
2025-09-29 19:29:38.645896 | controller |
2025-09-29 19:29:38.645918 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-09-29 19:29:38.645925 | controller | Monday 29 September 2025  19:29:38 +0000 (0:00:00.016)       0:00:15.506 ******
2025-09-29 19:29:38.646167 | controller | skipping: [instance]
2025-09-29 19:29:38.646334 | controller |
2025-09-29 19:29:38.646483 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-09-29 19:29:38.646627 | controller | Monday 29 September 2025  19:29:38 +0000 (0:00:00.016)       0:00:15.523 ******
2025-09-29 19:29:38.662341 | controller | skipping: [instance]
2025-09-29 19:29:38.662515 | controller |
2025-09-29 19:29:38.662668 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-09-29 19:29:38.662807 | controller | Monday 29 September 2025  19:29:38 +0000 (0:00:00.016)       0:00:15.539 ******
2025-09-29 19:29:38.679176 | controller | skipping: [instance]
2025-09-29 19:29:38.679325 | controller |
2025-09-29 19:29:38.679474 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-09-29 19:29:38.679613 | controller | Monday 29 September 2025  19:29:38 +0000 (0:00:00.016)       0:00:15.556 ******
2025-09-29 19:29:38.695355 | controller | skipping: [instance]
2025-09-29 19:29:38.695526 | controller |
2025-09-29 19:29:38.695668 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-09-29 19:29:38.695804 | controller | Monday 29 September 2025  19:29:38 +0000 (0:00:00.016)       0:00:15.572 ******
2025-09-29 19:29:38.880455 | controller | ok: [instance]
2025-09-29 19:29:39.090311 | controller |
2025-09-29 19:29:39.090340 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-09-29 19:29:39.090349 | controller | Monday 29 September 2025  19:29:38 +0000 (0:00:00.184)       0:00:15.757 ******
2025-09-29 19:29:39.090359 | controller | changed: [instance]
2025-09-29 19:29:39.300194 | controller |
2025-09-29 19:29:39.300223 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-09-29 19:29:39.300231 | controller | Monday 29 September 2025  19:29:39 +0000 (0:00:00.209)       0:00:15.967 ******
2025-09-29 19:29:39.300241 | controller | changed: [instance]
2025-09-29 19:29:39.319772 | controller |
2025-09-29 19:29:39.319798 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-09-29 19:29:39.319805 | controller | Monday 29 September 2025  19:29:39 +0000 (0:00:00.209)       0:00:16.177 ******
2025-09-29 19:29:39.319815 | controller | skipping: [instance]
2025-09-29 19:29:39.339785 | controller |
2025-09-29 19:29:39.339813 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-09-29 19:29:39.339821 | controller | Monday 29 September 2025  19:29:39 +0000 (0:00:00.019)       0:00:16.196 ******
2025-09-29 19:29:39.339831 | controller | skipping: [instance]
2025-09-29 19:29:39.359781 | controller |
2025-09-29 19:29:39.359806 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-09-29 19:29:39.359814 | controller | Monday 29 September 2025  19:29:39 +0000 (0:00:00.019)       0:00:16.216 ******
2025-09-29 19:29:39.359824 | controller | skipping: [instance]
2025-09-29 19:29:39.381065 | controller |
2025-09-29 19:29:39.381095 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-09-29 19:29:39.381104 | controller | Monday 29 September 2025  19:29:39 +0000 (0:00:00.020)       0:00:16.236 ******
2025-09-29 19:29:39.381116 | controller | skipping: [instance]
2025-09-29 19:29:39.401764 | controller |
2025-09-29 19:29:39.401791 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-09-29 19:29:39.401801 | controller | Monday 29 September 2025  19:29:39 +0000 (0:00:00.021)       0:00:16.258 ******
2025-09-29 19:29:39.401819 | controller | skipping: [instance]
2025-09-29 19:29:39.421889 | controller |
2025-09-29 19:29:39.421911 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-09-29 19:29:39.421926 | controller | Monday 29 September 2025  19:29:39 +0000 (0:00:00.020)       0:00:16.278 ******
2025-09-29 19:29:39.421936 | controller | skipping: [instance]
2025-09-29 19:29:39.436469 | controller |
2025-09-29 19:29:39.436491 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-09-29 19:29:39.436499 | controller | Monday 29 September 2025  19:29:39 +0000 (0:00:00.020)       0:00:16.298 ******
2025-09-29 19:29:39.436508 | controller | skipping: [instance]
2025-09-29 19:29:39.454006 | controller |
2025-09-29 19:29:39.454027 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-09-29 19:29:39.454054 | controller | Monday 29 September 2025  19:29:39 +0000 (0:00:00.014)       0:00:16.313 ******
2025-09-29 19:29:39.454063 | controller | skipping: [instance]
2025-09-29 19:29:39.469307 | controller |
2025-09-29 19:29:39.469328 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-09-29 19:29:39.469335 | controller | Monday 29 September 2025  19:29:39 +0000 (0:00:00.017)       0:00:16.331 ******
2025-09-29 19:29:39.469344 | controller | skipping: [instance]
2025-09-29 19:29:39.488160 | controller |
2025-09-29 19:29:39.488181 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-09-29 19:29:39.488188 | controller | Monday 29 September 2025  19:29:39 +0000 (0:00:00.015)       0:00:16.346 ******
2025-09-29 19:29:39.488197 | controller | skipping: [instance]
2025-09-29 19:29:39.507051 | controller |
2025-09-29 19:29:39.507077 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-09-29 19:29:39.507085 | controller | Monday 29 September 2025  19:29:39 +0000 (0:00:00.018)       0:00:16.365 ******
2025-09-29 19:29:39.507098 | controller | skipping: [instance]
2025-09-29 19:29:39.507126 | controller |
2025-09-29 19:29:39.507325 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-09-29 19:29:39.526062 | controller | Monday 29 September 2025  19:29:39 +0000 (0:00:00.018)       0:00:16.384 ******
2025-09-29 19:29:39.526091 | controller | skipping: [instance]
2025-09-29 19:29:39.526120 | controller |
2025-09-29 19:29:39.526443 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-09-29 19:29:39.544563 | controller | Monday 29 September 2025  19:29:39 +0000 (0:00:00.019)       0:00:16.403 ******
2025-09-29 19:29:39.544584 | controller | skipping: [instance]
2025-09-29 19:29:39.562810 | controller |
2025-09-29 19:29:39.562829 | controller | TASK [test_deps : Install additional packages] *********************************
2025-09-29 19:29:39.562836 | controller | Monday 29 September 2025  19:29:39 +0000 (0:00:00.018)       0:00:16.421 ******
2025-09-29 19:29:39.562845 | controller | skipping: [instance]
2025-09-29 19:30:20.292808 | controller |
2025-09-29 19:30:20.292840 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-09-29 19:30:20.292848 | controller | Monday 29 September 2025  19:29:39 +0000 (0:00:00.018)       0:00:16.440 ******
2025-09-29 19:30:20.292858 | controller | ok: [instance]
2025-09-29 19:30:21.479959 | controller |
2025-09-29 19:30:21.480016 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-09-29 19:30:21.480024 | controller | Monday 29 September 2025  19:30:20 +0000 (0:00:40.729)       0:00:57.169 ******
2025-09-29 19:30:21.480034 | controller | ok: [instance]
2025-09-29 19:30:21.502570 | controller |
2025-09-29 19:30:21.502598 | controller | TASK [test_deps : Install extra packages] **************************************
2025-09-29 19:30:21.502606 | controller | Monday 29 September 2025  19:30:21 +0000 (0:00:01.187)       0:00:58.356 ******
2025-09-29 19:30:21.502616 | controller | skipping: [instance]
2025-09-29 19:30:21.537075 | controller |
2025-09-29 19:30:21.537110 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-09-29 19:30:21.537121 | controller | Monday 29 September 2025  19:30:21 +0000 (0:00:00.022)       0:00:58.379 ******
2025-09-29 19:30:21.537133 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-09-29 19:30:21.537159 | controller |
2025-09-29 19:30:21.537170 | controller | TASK [ci_setup : List packages to install] *************************************
2025-09-29 19:30:21.537206 | controller | Monday 29 September 2025  19:30:21 +0000 (0:00:00.034)       0:00:58.413 ******
2025-09-29 19:30:21.557060 | controller | ok: [instance] =>
2025-09-29 19:30:21.557394 | controller |   cifmw_ci_setup_packages:
2025-09-29 19:30:21.557411 | controller |   - bash-completion
2025-09-29 19:30:21.557418 | controller |   - ca-certificates
2025-09-29 19:30:21.557424 | controller |   - git-core
2025-09-29 19:30:21.557430 | controller |   - make
2025-09-29 19:30:21.557436 | controller |   - tar
2025-09-29 19:30:21.557441 | controller |   - tmux
2025-09-29 19:30:21.557446 | controller |   - python3-pip
2025-09-29 19:30:21.557454 | controller |
2025-09-29 19:30:25.315736 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-09-29 19:30:25.315814 | controller | Monday 29 September 2025  19:30:21 +0000 (0:00:00.019)       0:00:58.433 ******
2025-09-29 19:30:25.315828 | controller | changed: [instance]
2025-09-29 19:30:25.503517 | controller |
2025-09-29 19:30:25.503557 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-09-29 19:30:25.503572 | controller | Monday 29 September 2025  19:30:25 +0000 (0:00:03.758)       0:01:02.192 ******
2025-09-29 19:30:25.503590 | controller | ok: [instance]
2025-09-29 19:30:25.503609 | controller |
2025-09-29 19:30:25.503828 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-09-29 19:30:25.693999 | controller | Monday 29 September 2025  19:30:25 +0000 (0:00:00.188)       0:01:02.380 ******
2025-09-29 19:30:25.694035 | controller | changed: [instance]
2025-09-29 19:30:31.725073 | controller |
2025-09-29 19:30:31.725105 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-09-29 19:30:31.725113 | controller | Monday 29 September 2025  19:30:25 +0000 (0:00:00.190)       0:01:02.570 ******
2025-09-29 19:30:31.725123 | controller | changed: [instance]
2025-09-29 19:30:31.725148 | controller |
2025-09-29 19:30:31.725423 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-09-29 19:30:31.753048 | controller | Monday 29 September 2025  19:30:31 +0000 (0:00:06.031)       0:01:08.602 ******
2025-09-29 19:30:31.753078 | controller | ok: [instance]
2025-09-29 19:30:31.753107 | controller |
2025-09-29 19:30:31.753117 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-09-29 19:30:31.753297 | controller | Monday 29 September 2025  19:30:31 +0000 (0:00:00.027)       0:01:08.630 ******
2025-09-29 19:30:32.019465 | controller | changed: [instance]
2025-09-29 19:30:32.305932 | controller |
2025-09-29 19:30:32.305961 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-09-29 19:30:32.305984 | controller | Monday 29 September 2025  19:30:32 +0000 (0:00:00.266)       0:01:08.896 ******
2025-09-29 19:30:32.305997 | controller | changed: [instance]
2025-09-29 19:30:32.323456 | controller |
2025-09-29 19:30:32.323478 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-09-29 19:30:32.323485 | controller | Monday 29 September 2025  19:30:32 +0000 (0:00:00.286)       0:01:09.182 ******
2025-09-29 19:30:32.323494 | controller | skipping: [instance]
2025-09-29 19:30:32.339450 | controller |
2025-09-29 19:30:32.339478 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-09-29 19:30:32.339487 | controller | Monday 29 September 2025  19:30:32 +0000 (0:00:00.017)       0:01:09.200 ******
2025-09-29 19:30:32.339499 | controller | skipping: [instance]
2025-09-29 19:30:32.355042 | controller |
2025-09-29 19:30:32.355074 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-09-29 19:30:32.355082 | controller | Monday 29 September 2025  19:30:32 +0000 (0:00:00.015)       0:01:09.216 ******
2025-09-29 19:30:32.355093 | controller | skipping: [instance]
2025-09-29 19:30:32.370079 | controller |
2025-09-29 19:30:32.370117 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-09-29 19:30:32.370127 | controller | Monday 29 September 2025  19:30:32 +0000 (0:00:00.015)       0:01:09.232 ******
2025-09-29 19:30:32.370139 | controller | skipping: [instance]
2025-09-29 19:30:32.370147 | controller |
2025-09-29 19:30:32.370157 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-09-29 19:30:32.370311 | controller | Monday 29 September 2025  19:30:32 +0000 (0:00:00.015)       0:01:09.247 ******
2025-09-29 19:30:32.385005 | controller | skipping: [instance]
2025-09-29 19:30:32.385161 | controller |
2025-09-29 19:30:32.385284 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-09-29 19:30:32.385422 | controller | Monday 29 September 2025  19:30:32 +0000 (0:00:00.015)       0:01:09.262 ******
2025-09-29 19:30:32.406770 | controller | skipping: [instance]
2025-09-29 19:30:32.406922 | controller |
2025-09-29 19:30:32.407094 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-09-29 19:30:32.407250 | controller | Monday 29 September 2025  19:30:32 +0000 (0:00:00.021)       0:01:09.284 ******
2025-09-29 19:30:33.386097 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-09-29 19:30:33.386144 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-09-29 19:30:33.386153 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-09-29 19:30:33.386160 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-09-29 19:30:33.386166 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-09-29 19:30:33.386171 | controller |
2025-09-29 19:30:33.386179 | controller | TASK [Ensure directory exists] *************************************************
2025-09-29 19:30:33.386201 | controller | Monday 29 September 2025  19:30:33 +0000 (0:00:00.978)       0:01:10.262 ******
2025-09-29 19:30:33.596564 | controller | changed: [instance]
2025-09-29 19:30:33.980626 | controller |
2025-09-29 19:30:33.980657 | controller | TASK [Create nova migration keypair] *******************************************
2025-09-29 19:30:33.980665 | controller | Monday 29 September 2025  19:30:33 +0000 (0:00:00.210)       0:01:10.473 ******
2025-09-29 19:30:33.980675 | controller | changed: [instance]
2025-09-29 19:30:34.581565 | controller |
2025-09-29 19:30:34.581602 | controller | PLAY [Download tools] **********************************************************
2025-09-29 19:30:34.581612 | controller |
2025-09-29 19:30:34.581619 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 19:30:34.581633 | controller | Monday 29 September 2025  19:30:33 +0000 (0:00:00.384)       0:01:10.857 ******
2025-09-29 19:30:34.581644 | controller | ok: [localhost]
2025-09-29 19:30:40.204601 | controller |
2025-09-29 19:30:40.204633 | controller | TASK [download_tools : Install build dependencies] *****************************
2025-09-29 19:30:40.204641 | controller | Monday 29 September 2025  19:30:34 +0000 (0:00:00.600)       0:01:11.458 ******
2025-09-29 19:30:40.204652 | controller | changed: [localhost]
2025-09-29 19:30:40.227594 | controller |
2025-09-29 19:30:40.227622 | controller | TASK [download_tools : Set opm download url suffix] ****************************
2025-09-29 19:30:40.227630 | controller | Monday 29 September 2025  19:30:40 +0000 (0:00:05.622)       0:01:17.081 ******
2025-09-29 19:30:40.227641 | controller | skipping: [localhost]
2025-09-29 19:30:40.251437 | controller |
2025-09-29 19:30:40.251466 | controller | TASK [download_tools : Set opm download url suffix] ****************************
2025-09-29 19:30:40.251474 | controller | Monday 29 September 2025  19:30:40 +0000 (0:00:00.023)       0:01:17.104 ******
2025-09-29 19:30:40.251484 | controller | ok: [localhost]
2025-09-29 19:30:40.468074 | controller |
2025-09-29 19:30:40.468104 | controller | TASK [download_tools : Create $HOME/bin dir] ***********************************
2025-09-29 19:30:40.468112 | controller | Monday 29 September 2025  19:30:40 +0000 (0:00:00.023)       0:01:17.128 ******
2025-09-29 19:30:40.468122 | controller | ok: [localhost]
2025-09-29 19:30:41.778097 | controller |
2025-09-29 19:30:41.778129 | controller | TASK [download_tools : Download opm] *******************************************
2025-09-29 19:30:41.778137 | controller | Monday 29 September 2025  19:30:40 +0000 (0:00:00.213)       0:01:17.341 ******
2025-09-29 19:30:41.778148 | controller | changed: [localhost]
2025-09-29 19:30:41.800066 | controller |
2025-09-29 19:30:41.800087 | controller | TASK [download_tools : Get version from sdk_version] ***************************
2025-09-29 19:30:41.800095 | controller | Monday 29 September 2025  19:30:41 +0000 (0:00:01.313)       0:01:18.654 ******
2025-09-29 19:30:41.800104 | controller | ok: [localhost]
2025-09-29 19:30:41.820943 | controller |
2025-09-29 19:30:41.820967 | controller | TASK [download_tools : Set operator-sdk file for version < 1.3.0] **************
2025-09-29 19:30:41.820999 | controller | Monday 29 September 2025  19:30:41 +0000 (0:00:00.022)       0:01:18.677 ******
2025-09-29 19:30:41.821011 | controller | skipping: [localhost]
2025-09-29 19:30:41.843796 | controller |
2025-09-29 19:30:41.843823 | controller | TASK [download_tools : Set operator-sdk file for version >= 1.3.0] *************
2025-09-29 19:30:41.843830 | controller | Monday 29 September 2025  19:30:41 +0000 (0:00:00.020)       0:01:18.697 ******
2025-09-29 19:30:41.843839 | controller | ok: [localhost]
2025-09-29 19:30:43.357040 | controller |
2025-09-29 19:30:43.357072 | controller | TASK [download_tools : Download operator-sdk] **********************************
2025-09-29 19:30:43.357080 | controller | Monday 29 September 2025  19:30:41 +0000 (0:00:00.022)       0:01:18.720 ******
2025-09-29 19:30:43.357089 | controller | changed: [localhost]
2025-09-29 19:30:43.357111 | controller |
2025-09-29 19:30:43.357117 | controller | TASK [download_tools : Download and extract kustomize] *************************
2025-09-29 19:30:43.357124 | controller | Monday 29 September 2025  19:30:43 +0000 (0:00:01.513)       0:01:20.234 ******
2025-09-29 19:30:44.451158 | controller | changed: [localhost]
2025-09-29 19:30:44.451261 | controller |
2025-09-29 19:30:44.451292 | controller | TASK [download_tools : Download kubectl] ***************************************
2025-09-29 19:30:44.451304 | controller | Monday 29 September 2025  19:30:44 +0000 (0:00:01.094)       0:01:21.328 ******
2025-09-29 19:30:44.997712 | controller | ok: [localhost]
2025-09-29 19:30:45.978842 | controller |
2025-09-29 19:30:45.978872 | controller | TASK [download_tools : Download kuttl] *****************************************
2025-09-29 19:30:45.978881 | controller | Monday 29 September 2025  19:30:44 +0000 (0:00:00.546)       0:01:21.874 ******
2025-09-29 19:30:45.978891 | controller | changed: [localhost]
2025-09-29 19:30:49.072394 | controller |
2025-09-29 19:30:49.072440 | controller | TASK [download_tools : Download chainsaw] **************************************
2025-09-29 19:30:49.072454 | controller | Monday 29 September 2025  19:30:45 +0000 (0:00:00.980)       0:01:22.855 ******
2025-09-29 19:30:49.072465 | controller | changed: [localhost]
2025-09-29 19:30:50.213023 | controller |
2025-09-29 19:30:50.213056 | controller | TASK [download_tools : Download and extract yq] ********************************
2025-09-29 19:30:50.213064 | controller | Monday 29 September 2025  19:30:49 +0000 (0:00:03.093)       0:01:25.949 ******
2025-09-29 19:30:50.213074 | controller | changed: [localhost]
2025-09-29 19:30:50.409154 | controller |
2025-09-29 19:30:50.409187 | controller | TASK [download_tools : Link yq_linux_amd64 as yq] ******************************
2025-09-29 19:30:50.409195 | controller | Monday 29 September 2025  19:30:50 +0000 (0:00:01.140)       0:01:27.089 ******
2025-09-29 19:30:50.409212 | controller | changed: [localhost]
2025-09-29 19:30:51.904537 | controller |
2025-09-29 19:30:51.904569 | controller | TASK [download_tools : Deinstall golang] ***************************************
2025-09-29 19:30:51.904577 | controller | Monday 29 September 2025  19:30:50 +0000 (0:00:00.196)       0:01:27.285 ******
2025-09-29 19:30:51.904587 | controller | ok: [localhost]
2025-09-29 19:30:52.817075 | controller |
2025-09-29 19:30:52.817107 | controller | TASK [download_tools : Delete old go version installed from upstream] **********
2025-09-29 19:30:52.817115 | controller | Monday 29 September 2025  19:30:51 +0000 (0:00:01.495)       0:01:28.781 ******
2025-09-29 19:30:52.817125 | controller | ok: [localhost] => (item=/usr/local/go)
2025-09-29 19:30:52.817446 | controller | ok: [localhost] => (item=/home/zuul/bin/go)
2025-09-29 19:30:52.817473 | controller | ok: [localhost] => (item=/home/zuul/bin/gofmt)
2025-09-29 19:30:52.817479 | controller | ok: [localhost] => (item=/usr/local/bin/go)
2025-09-29 19:30:52.817485 | controller | ok: [localhost] => (item=/usr/local/bin/gofmt)
2025-09-29 19:30:52.817493 | controller |
2025-09-29 19:31:05.212009 | controller | TASK [download_tools : Download and extract golang] ****************************
2025-09-29 19:31:05.212048 | controller | Monday 29 September 2025  19:30:52 +0000 (0:00:00.912)       0:01:29.693 ******
2025-09-29 19:31:05.212060 | controller | changed: [localhost]
2025-09-29 19:31:05.588060 | controller |
2025-09-29 19:31:05.588091 | controller | TASK [download_tools : Set alternatives link to installed go version] **********
2025-09-29 19:31:05.588099 | controller | Monday 29 September 2025  19:31:05 +0000 (0:00:12.395)       0:01:42.088 ******
2025-09-29 19:31:05.588109 | controller | changed: [localhost] => (item=go)
2025-09-29 19:31:05.621786 | controller | changed: [localhost] => (item=gofmt)
2025-09-29 19:31:05.621820 | controller |
2025-09-29 19:31:05.621828 | controller | TASK [download_tools : Clean bash cache] ***************************************
2025-09-29 19:31:05.621841 | controller | Monday 29 September 2025  19:31:05 +0000 (0:00:00.376)       0:01:42.464 ******
2025-09-29 19:31:05.621852 | controller | ok: [localhost] =>
2025-09-29 19:31:05.621878 | controller |   msg: When move from rpm to upstream version, make sure to clean bash cache using `hash -d go`
2025-09-29 19:31:05.624740 | controller |
2025-09-29 19:31:05.680259 | controller | PLAY RECAP *********************************************************************
2025-09-29 19:31:05.680280 | controller | instance                   : ok=31   changed=17   unreachable=0    failed=0    skipped=36   rescued=0    ignored=0
2025-09-29 19:31:05.680288 | controller | localhost                  : ok=19   changed=10   unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-09-29 19:31:05.680294 | controller |
2025-09-29 19:31:05.680300 | controller | Monday 29 September 2025  19:31:05 +0000 (0:00:00.033)       0:01:42.498 ******
2025-09-29 19:31:05.680305 | controller | ===============================================================================
2025-09-29 19:31:05.680311 | controller | test_deps : Install selinux python libs -------------------------------- 40.73s
2025-09-29 19:31:05.680316 | controller | download_tools : Download and extract golang --------------------------- 12.40s
2025-09-29 19:31:05.680321 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.00s
2025-09-29 19:31:05.680327 | controller | ci_setup : Install openshift client ------------------------------------- 6.03s
2025-09-29 19:31:05.680332 | controller | download_tools : Install build dependencies ----------------------------- 5.62s
2025-09-29 19:31:05.680337 | controller | ci_setup : Install needed packages -------------------------------------- 3.76s
2025-09-29 19:31:05.680343 | controller | download_tools : Download chainsaw -------------------------------------- 3.09s
2025-09-29 19:31:05.680348 | controller | download_tools : Download operator-sdk ---------------------------------- 1.51s
2025-09-29 19:31:05.680353 | controller | download_tools : Deinstall golang --------------------------------------- 1.50s
2025-09-29 19:31:05.680358 | controller | download_tools : Download opm ------------------------------------------- 1.31s
2025-09-29 19:31:05.680364 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.20s
2025-09-29 19:31:05.680369 | controller | test_deps : Install python yaml libs ------------------------------------ 1.19s
2025-09-29 19:31:05.680374 | controller | download_tools : Download and extract yq -------------------------------- 1.14s
2025-09-29 19:31:05.680379 | controller | download_tools : Download and extract kustomize ------------------------- 1.09s
2025-09-29 19:31:05.680384 | controller | download_tools : Download kuttl ----------------------------------------- 0.98s
2025-09-29 19:31:05.680390 | controller | ci_setup : Manage directories ------------------------------------------- 0.98s
2025-09-29 19:31:05.680395 | controller | download_tools : Delete old go version installed from upstream ---------- 0.91s
2025-09-29 19:31:05.680405 | controller | Gathering Facts --------------------------------------------------------- 0.84s
2025-09-29 19:31:05.680411 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.82s
2025-09-29 19:31:05.680416 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.80s
2025-09-29 19:31:05.680425 | controller | INFO     Running default > converge
2025-09-29 19:31:06.120601 | controller |
2025-09-29 19:31:06.120820 | controller | PLAY [Converge] ****************************************************************
2025-09-29 19:31:06.120961 | controller |
2025-09-29 19:31:06.121142 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 19:31:06.121297 | controller | Monday 29 September 2025  19:31:06 +0000 (0:00:00.013)       0:00:00.013 ******
2025-09-29 19:31:06.914509 | controller | ok: [instance]
2025-09-29 19:31:07.575685 | controller |
2025-09-29 19:31:07.575716 | controller | TASK [Ensure architecture repository is building] ******************************
2025-09-29 19:31:07.575724 | controller | Monday 29 September 2025  19:31:06 +0000 (0:00:00.794)       0:00:00.807 ******
2025-09-29 19:31:07.575735 | controller | changed: [instance] => (item={'name': 'ctlplane', 'path': 'examples/va/hci/control-plane/networking/nncp'})
2025-09-29 19:31:07.961198 | controller | changed: [instance] => (item={'name': 'dataplane', 'path': 'examples/va/hci/edpm-pre-ceph/nodeset'})
2025-09-29 19:31:07.961239 | controller |
2025-09-29 19:31:07.961247 | controller | TASK [Create nova migration keypair] *******************************************
2025-09-29 19:31:07.961253 | controller | Monday 29 September 2025  19:31:07 +0000 (0:00:00.660)       0:00:01.468 ******
2025-09-29 19:31:07.961264 | controller | changed: [instance]
2025-09-29 19:31:08.020438 | controller |
2025-09-29 19:31:08.020471 | controller | TASK [Generate needed facts out of local files] ********************************
2025-09-29 19:31:08.020479 | controller | Monday 29 September 2025  19:31:07 +0000 (0:00:00.385)       0:00:01.853 ******
2025-09-29 19:31:08.020489 | controller | ok: [instance]
2025-09-29 19:31:08.045866 | controller |
2025-09-29 19:31:08.045896 | controller | TASK [Generate network-values] *************************************************
2025-09-29 19:31:08.045911 | controller | Monday 29 September 2025  19:31:08 +0000 (0:00:00.059)       0:00:01.913 ******
2025-09-29 19:31:08.045922 | controller |
2025-09-29 19:31:08.074111 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-09-29 19:31:08.074147 | controller | Monday 29 September 2025  19:31:08 +0000 (0:00:00.025)       0:00:01.938 ******
2025-09-29 19:31:08.074160 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_snippets.yml for instance
2025-09-29 19:31:08.100229 | controller |
2025-09-29 19:31:08.100262 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-09-29 19:31:08.100271 | controller | Monday 29 September 2025  19:31:08 +0000 (0:00:00.028)       0:00:01.967 ******
2025-09-29 19:31:08.100281 | controller | ok: [instance] => changed=false
2025-09-29 19:31:08.388055 | controller |   msg: All assertions passed
2025-09-29 19:31:08.388095 | controller |
2025-09-29 19:31:08.388103 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-09-29 19:31:08.388110 | controller | Monday 29 September 2025  19:31:08 +0000 (0:00:00.025)       0:00:01.992 ******
2025-09-29 19:31:08.388120 | controller | ok: [instance]
2025-09-29 19:31:08.418649 | controller |
2025-09-29 19:31:08.418688 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-09-29 19:31:08.418697 | controller | Monday 29 September 2025  19:31:08 +0000 (0:00:00.287)       0:00:02.280 ******
2025-09-29 19:31:08.418708 | controller | ok: [instance] => changed=false
2025-09-29 19:31:08.696796 | controller |   msg: All assertions passed
2025-09-29 19:31:08.696832 | controller |
2025-09-29 19:31:08.696841 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-09-29 19:31:08.696848 | controller | Monday 29 September 2025  19:31:08 +0000 (0:00:00.030)       0:00:02.311 ******
2025-09-29 19:31:08.696857 | controller | ok: [instance]
2025-09-29 19:31:08.959148 | controller |
2025-09-29 19:31:08.959179 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-09-29 19:31:08.959188 | controller | Monday 29 September 2025  19:31:08 +0000 (0:00:00.278)       0:00:02.589 ******
2025-09-29 19:31:08.959198 | controller | ok: [instance]
2025-09-29 19:31:08.999732 | controller |
2025-09-29 19:31:08.999763 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-09-29 19:31:08.999773 | controller | Monday 29 September 2025  19:31:08 +0000 (0:00:00.262)       0:00:02.852 ******
2025-09-29 19:31:08.999785 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/network_values.yml for instance
2025-09-29 19:31:09.022507 | controller |
2025-09-29 19:31:09.022533 | controller | TASK [ci_gen_kustomize_values : Assert we have needed parameters] **************
2025-09-29 19:31:09.022540 | controller | Monday 29 September 2025  19:31:08 +0000 (0:00:00.040)       0:00:02.892 ******
2025-09-29 19:31:09.022550 | controller | ok: [instance] => changed=false
2025-09-29 19:31:09.298494 | controller |   msg: All assertions passed
2025-09-29 19:31:09.298524 | controller |
2025-09-29 19:31:09.298532 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-09-29 19:31:09.298539 | controller | Monday 29 September 2025  19:31:09 +0000 (0:00:00.022)       0:00:02.915 ******
2025-09-29 19:31:09.298554 | controller | changed: [instance]
2025-09-29 19:31:09.830055 | controller |
2025-09-29 19:31:09.830088 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-09-29 19:31:09.830102 | controller | Monday 29 September 2025  19:31:09 +0000 (0:00:00.275)       0:00:03.191 ******
2025-09-29 19:31:09.830113 | controller | changed: [instance]
2025-09-29 19:31:09.830132 | controller |
2025-09-29 19:31:09.830315 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-09-29 19:31:09.863041 | controller | Monday 29 September 2025  19:31:09 +0000 (0:00:00.531)       0:00:03.723 ******
2025-09-29 19:31:09.863076 | controller | ok: [instance]
2025-09-29 19:31:10.206156 | controller |
2025-09-29 19:31:10.206185 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-09-29 19:31:10.206193 | controller | Monday 29 September 2025  19:31:09 +0000 (0:00:00.032)       0:00:03.755 ******
2025-09-29 19:31:10.206203 | controller | changed: [instance]
2025-09-29 19:31:10.544793 | controller |
2025-09-29 19:31:10.544824 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-09-29 19:31:10.544832 | controller | Monday 29 September 2025  19:31:10 +0000 (0:00:00.343)       0:00:04.099 ******
2025-09-29 19:31:10.544842 | controller | changed: [instance]
2025-09-29 19:31:10.877460 | controller |
2025-09-29 19:31:10.877490 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-09-29 19:31:10.877498 | controller | Monday 29 September 2025  19:31:10 +0000 (0:00:00.338)       0:00:04.437 ******
2025-09-29 19:31:10.877508 | controller | changed: [instance]
2025-09-29 19:31:10.905088 | controller |
2025-09-29 19:31:10.905126 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-09-29 19:31:10.905136 | controller | Monday 29 September 2025  19:31:10 +0000 (0:00:00.332)       0:00:04.770 ******
2025-09-29 19:31:10.905149 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_values.yml for instance
2025-09-29 19:31:10.905182 | controller |
2025-09-29 19:31:10.905419 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-09-29 19:31:10.930754 | controller | Monday 29 September 2025  19:31:10 +0000 (0:00:00.027)       0:00:04.798 ******
2025-09-29 19:31:10.930793 | controller | ok: [instance] => changed=false
2025-09-29 19:31:11.201079 | controller |   msg: All assertions passed
2025-09-29 19:31:11.201111 | controller |
2025-09-29 19:31:11.201120 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-09-29 19:31:11.201127 | controller | Monday 29 September 2025  19:31:10 +0000 (0:00:00.025)       0:00:04.823 ******
2025-09-29 19:31:11.201142 | controller | ok: [instance]
2025-09-29 19:31:11.201336 | controller |
2025-09-29 19:31:11.201369 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-09-29 19:31:11.219343 | controller | Monday 29 September 2025  19:31:11 +0000 (0:00:00.270)       0:00:05.094 ******
2025-09-29 19:31:11.219369 | controller | ok: [instance]
2025-09-29 19:31:11.703757 | controller |
2025-09-29 19:31:11.703787 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-09-29 19:31:11.703795 | controller | Monday 29 September 2025  19:31:11 +0000 (0:00:00.018)       0:00:05.112 ******
2025-09-29 19:31:11.703805 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/02_ci_data.yaml)
2025-09-29 19:31:11.766687 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/03_user_data_b64.yaml)
2025-09-29 19:31:11.766718 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/04_user_data.yaml)
2025-09-29 19:31:11.766726 | controller |
2025-09-29 19:31:11.766733 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-09-29 19:31:11.766739 | controller | Monday 29 September 2025  19:31:11 +0000 (0:00:00.484)       0:00:05.596 ******
2025-09-29 19:31:11.766748 | controller | ok: [instance] => (item=02_ci_data.yaml)
2025-09-29 19:31:11.965687 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-09-29 19:31:11.965717 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-09-29 19:31:11.965724 | controller |
2025-09-29 19:31:11.965731 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-09-29 19:31:11.965737 | controller | Monday 29 September 2025  19:31:11 +0000 (0:00:00.062)       0:00:05.659 ******
2025-09-29 19:31:11.965753 | controller | changed: [instance]
2025-09-29 19:31:12.323809 | controller |
2025-09-29 19:31:12.323838 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-09-29 19:31:12.323847 | controller | Monday 29 September 2025  19:31:11 +0000 (0:00:00.199)       0:00:05.858 ******
2025-09-29 19:31:12.323857 | controller | changed: [instance]
2025-09-29 19:31:12.349398 | controller |
2025-09-29 19:31:12.349424 | controller | TASK [Generate edpm-nodeset-values] ********************************************
2025-09-29 19:31:12.349432 | controller | Monday 29 September 2025  19:31:12 +0000 (0:00:00.358)       0:00:06.216 ******
2025-09-29 19:31:12.349442 | controller |
2025-09-29 19:31:12.375067 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-09-29 19:31:12.375094 | controller | Monday 29 September 2025  19:31:12 +0000 (0:00:00.025)       0:00:06.242 ******
2025-09-29 19:31:12.375106 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_snippets.yml for instance
2025-09-29 19:31:12.402054 | controller |
2025-09-29 19:31:12.402085 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-09-29 19:31:12.402101 | controller | Monday 29 September 2025  19:31:12 +0000 (0:00:00.025)       0:00:06.268 ******
2025-09-29 19:31:12.402113 | controller | ok: [instance] => changed=false
2025-09-29 19:31:12.402140 | controller |   msg: All assertions passed
2025-09-29 19:31:12.402150 | controller |
2025-09-29 19:31:12.402332 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-09-29 19:31:12.592799 | controller | Monday 29 September 2025  19:31:12 +0000 (0:00:00.027)       0:00:06.295 ******
2025-09-29 19:31:12.592836 | controller | ok: [instance]
2025-09-29 19:31:12.625920 | controller |
2025-09-29 19:31:12.625950 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-09-29 19:31:12.625959 | controller | Monday 29 September 2025  19:31:12 +0000 (0:00:00.190)       0:00:06.485 ******
2025-09-29 19:31:12.625993 | controller | ok: [instance] => changed=false
2025-09-29 19:31:12.807847 | controller |   msg: All assertions passed
2025-09-29 19:31:12.807877 | controller |
2025-09-29 19:31:12.807885 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-09-29 19:31:12.807891 | controller | Monday 29 September 2025  19:31:12 +0000 (0:00:00.032)       0:00:06.518 ******
2025-09-29 19:31:12.807902 | controller | ok: [instance]
2025-09-29 19:31:13.037436 | controller |
2025-09-29 19:31:13.037469 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-09-29 19:31:13.037478 | controller | Monday 29 September 2025  19:31:12 +0000 (0:00:00.182)       0:00:06.700 ******
2025-09-29 19:31:13.037488 | controller | ok: [instance]
2025-09-29 19:31:13.080099 | controller |
2025-09-29 19:31:13.080136 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-09-29 19:31:13.080145 | controller | Monday 29 September 2025  19:31:13 +0000 (0:00:00.229)       0:00:06.930 ******
2025-09-29 19:31:13.080158 | 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-09-29 19:31:13.080181 | controller |
2025-09-29 19:31:13.080189 | controller | TASK [ci_gen_kustomize_values : Check if all required variables for accessing the node is provided.] ***
2025-09-29 19:31:13.080198 | controller | Monday 29 September 2025  19:31:13 +0000 (0:00:00.042)       0:00:06.973 ******
2025-09-29 19:31:13.114599 | 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-09-29 19:31:13.144057 | controller |
2025-09-29 19:31:13.144087 | controller | TASK [ci_gen_kustomize_values : Ensure the required parameters are defined and configured.] ***
2025-09-29 19:31:13.144097 | controller | Monday 29 September 2025  19:31:13 +0000 (0:00:00.034)       0:00:07.007 ******
2025-09-29 19:31:13.144109 | controller | ok: [instance] => changed=false
2025-09-29 19:31:13.187097 | controller |   msg: All assertions passed
2025-09-29 19:31:13.187134 | controller |
2025-09-29 19:31:13.187144 | controller | TASK [ci_gen_kustomize_values : Get common data for dataplane templating] ******
2025-09-29 19:31:13.187160 | controller | Monday 29 September 2025  19:31:13 +0000 (0:00:00.029)       0:00:07.036 ******
2025-09-29 19:31:13.187172 | 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-09-29 19:31:13.202755 | controller |
2025-09-29 19:31:13.202784 | controller | TASK [ci_gen_kustomize_values : Fetch the already deployed services for further usage] ***
2025-09-29 19:31:13.202793 | controller | Monday 29 September 2025  19:31:13 +0000 (0:00:00.043)       0:00:07.080 ******
2025-09-29 19:31:13.202805 | controller | skipping: [instance]
2025-09-29 19:31:13.202896 | controller |
2025-09-29 19:31:13.203074 | controller | TASK [ci_gen_kustomize_values : Expose the deployed services as a fact] ********
2025-09-29 19:31:13.203234 | controller | Monday 29 September 2025  19:31:13 +0000 (0:00:00.016)       0:00:07.096 ******
2025-09-29 19:31:13.222418 | controller | ok: [instance]
2025-09-29 19:31:13.222534 | controller |
2025-09-29 19:31:13.222681 | controller | TASK [ci_gen_kustomize_values : Check if required variables for nova migration are provided.] ***
2025-09-29 19:31:13.222804 | controller | Monday 29 September 2025  19:31:13 +0000 (0:00:00.019)       0:00:07.115 ******
2025-09-29 19:31:13.247956 | controller | ok: [instance] => changed=false
2025-09-29 19:31:13.248133 | controller |   msg: All assertions passed
2025-09-29 19:31:13.248293 | controller |
2025-09-29 19:31:13.248463 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-09-29 19:31:13.248634 | controller | Monday 29 September 2025  19:31:13 +0000 (0:00:00.025)       0:00:07.141 ******
2025-09-29 19:31:13.433328 | controller | changed: [instance]
2025-09-29 19:31:13.814064 | controller |
2025-09-29 19:31:13.814095 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-09-29 19:31:13.814103 | controller | Monday 29 September 2025  19:31:13 +0000 (0:00:00.184)       0:00:07.326 ******
2025-09-29 19:31:13.814113 | controller | changed: [instance]
2025-09-29 19:31:13.814138 | controller |
2025-09-29 19:31:13.814145 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-09-29 19:31:13.814304 | controller | Monday 29 September 2025  19:31:13 +0000 (0:00:00.380)       0:00:07.707 ******
2025-09-29 19:31:13.837680 | controller | ok: [instance]
2025-09-29 19:31:14.176370 | controller |
2025-09-29 19:31:14.176402 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-09-29 19:31:14.176410 | controller | Monday 29 September 2025  19:31:13 +0000 (0:00:00.023)       0:00:07.730 ******
2025-09-29 19:31:14.176420 | controller | changed: [instance]
2025-09-29 19:31:14.509092 | controller |
2025-09-29 19:31:14.509125 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-09-29 19:31:14.509134 | controller | Monday 29 September 2025  19:31:14 +0000 (0:00:00.338)       0:00:08.069 ******
2025-09-29 19:31:14.509144 | controller | changed: [instance]
2025-09-29 19:31:14.846451 | controller |
2025-09-29 19:31:14.846494 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-09-29 19:31:14.846502 | controller | Monday 29 September 2025  19:31:14 +0000 (0:00:00.332)       0:00:08.401 ******
2025-09-29 19:31:14.846521 | controller | changed: [instance]
2025-09-29 19:31:14.872044 | controller |
2025-09-29 19:31:14.872075 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-09-29 19:31:14.872083 | controller | Monday 29 September 2025  19:31:14 +0000 (0:00:00.337)       0:00:08.739 ******
2025-09-29 19:31:14.872093 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_values.yml for instance
2025-09-29 19:31:14.872116 | controller |
2025-09-29 19:31:14.872124 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-09-29 19:31:14.872285 | controller | Monday 29 September 2025  19:31:14 +0000 (0:00:00.025)       0:00:08.765 ******
2025-09-29 19:31:14.895380 | controller | ok: [instance] => changed=false
2025-09-29 19:31:15.076449 | controller |   msg: All assertions passed
2025-09-29 19:31:15.076481 | controller |
2025-09-29 19:31:15.076490 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-09-29 19:31:15.076496 | controller | Monday 29 September 2025  19:31:14 +0000 (0:00:00.023)       0:00:08.788 ******
2025-09-29 19:31:15.076512 | controller | ok: [instance]
2025-09-29 19:31:15.091583 | controller |
2025-09-29 19:31:15.091622 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-09-29 19:31:15.091631 | controller | Monday 29 September 2025  19:31:15 +0000 (0:00:00.180)       0:00:08.968 ******
2025-09-29 19:31:15.091642 | controller | ok: [instance]
2025-09-29 19:31:15.575853 | controller |
2025-09-29 19:31:15.575885 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-09-29 19:31:15.575893 | controller | Monday 29 September 2025  19:31:15 +0000 (0:00:00.015)       0:00:08.984 ******
2025-09-29 19:31:15.575904 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/02_ci_data.yaml)
2025-09-29 19:31:15.622041 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/03_user_data_b64.yaml)
2025-09-29 19:31:15.622065 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/04_user_data.yaml)
2025-09-29 19:31:15.622073 | controller |
2025-09-29 19:31:15.622080 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-09-29 19:31:15.622086 | controller | Monday 29 September 2025  19:31:15 +0000 (0:00:00.483)       0:00:09.468 ******
2025-09-29 19:31:15.622096 | controller | ok: [instance] => (item=02_ci_data.yaml)
2025-09-29 19:31:15.622390 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-09-29 19:31:15.622402 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-09-29 19:31:15.622411 | controller |
2025-09-29 19:31:15.809593 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-09-29 19:31:15.809639 | controller | Monday 29 September 2025  19:31:15 +0000 (0:00:00.046)       0:00:09.515 ******
2025-09-29 19:31:15.809652 | controller | changed: [instance]
2025-09-29 19:31:16.146759 | controller |
2025-09-29 19:31:16.146791 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-09-29 19:31:16.146799 | controller | Monday 29 September 2025  19:31:15 +0000 (0:00:00.187)       0:00:09.702 ******
2025-09-29 19:31:16.146809 | controller | changed: [instance]
2025-09-29 19:31:16.486294 | controller |
2025-09-29 19:31:16.486326 | controller | TASK [Get generated files status] **********************************************
2025-09-29 19:31:16.486334 | controller | Monday 29 September 2025  19:31:16 +0000 (0:00:00.337)       0:00:10.039 ******
2025-09-29 19:31:16.486344 | controller | ok: [instance] => (item=network-values)
2025-09-29 19:31:16.514111 | controller | ok: [instance] => (item=edpm-nodeset-values)
2025-09-29 19:31:16.514136 | controller |
2025-09-29 19:31:16.514144 | controller | TASK [Assert generated values.yaml exists] *************************************
2025-09-29 19:31:16.514151 | controller | Monday 29 September 2025  19:31:16 +0000 (0:00:00.339)       0:00:10.379 ******
2025-09-29 19:31:16.514161 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/values.yaml) => changed=false
2025-09-29 19:31:16.514184 | controller |   ansible_loop_var: item
2025-09-29 19:31:16.514191 | controller |   item:
2025-09-29 19:31:16.514198 | controller |     ansible_loop_var: item
2025-09-29 19:31:16.514205 | controller |     changed: false
2025-09-29 19:31:16.514210 | controller |     failed: false
2025-09-29 19:31:16.514216 | controller |     invocation:
2025-09-29 19:31:16.514221 | controller |       module_args:
2025-09-29 19:31:16.514226 | controller |         checksum_algorithm: sha1
2025-09-29 19:31:16.514232 | controller |         follow: false
2025-09-29 19:31:16.514237 | controller |         get_attributes: false
2025-09-29 19:31:16.514242 | controller |         get_checksum: false
2025-09-29 19:31:16.514248 | controller |         get_md5: false
2025-09-29 19:31:16.514253 | controller |         get_mime: false
2025-09-29 19:31:16.514258 | controller |         path: /home/zuul/ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/values.yaml
2025-09-29 19:31:16.514264 | controller |     item: network-values
2025-09-29 19:31:16.514269 | controller |     stat:
2025-09-29 19:31:16.514275 | controller |       atime: 1759174272.2847638
2025-09-29 19:31:16.514280 | controller |       block_size: 4096
2025-09-29 19:31:16.514285 | controller |       blocks: 16
2025-09-29 19:31:16.514292 | controller |       ctime: 1759174272.2877638
2025-09-29 19:31:16.514315 | controller |       dev: 64513
2025-09-29 19:31:16.515015 | controller |       device_type: 0
2025-09-29 19:31:16.515035 | controller |       executable: false
2025-09-29 19:31:16.515074 | controller |       exists: true
2025-09-29 19:31:16.515091 | controller |       gid: 1000
2025-09-29 19:31:16.515098 | controller |       gr_name: zuul
2025-09-29 19:31:16.515104 | controller |       inode: 75544056
2025-09-29 19:31:16.515109 | controller |       isblk: false
2025-09-29 19:31:16.515114 | controller |       ischr: false
2025-09-29 19:31:16.515122 | controller |       isdir: false
2025-09-29 19:31:16.515266 | controller |       isfifo: false
2025-09-29 19:31:16.515403 | controller |       isgid: false
2025-09-29 19:31:16.515538 | controller |       islnk: false
2025-09-29 19:31:16.515723 | controller |       isreg: true
2025-09-29 19:31:16.515888 | controller |       issock: false
2025-09-29 19:31:16.516051 | controller |       isuid: false
2025-09-29 19:31:16.516196 | controller |       mode: '0644'
2025-09-29 19:31:16.516330 | controller |       mtime: 1759174272.1447616
2025-09-29 19:31:16.516463 | controller |       nlink: 1
2025-09-29 19:31:16.516622 | controller |       path: /home/zuul/ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/values.yaml
2025-09-29 19:31:16.516760 | controller |       pw_name: zuul
2025-09-29 19:31:16.516890 | controller |       readable: true
2025-09-29 19:31:16.517052 | controller |       rgrp: true
2025-09-29 19:31:16.517198 | controller |       roth: true
2025-09-29 19:31:16.517333 | controller |       rusr: true
2025-09-29 19:31:16.517463 | controller |       size: 5726
2025-09-29 19:31:16.517618 | controller |       uid: 1000
2025-09-29 19:31:16.517752 | controller |       wgrp: false
2025-09-29 19:31:16.517879 | controller |       woth: false
2025-09-29 19:31:16.518039 | controller |       writeable: true
2025-09-29 19:31:16.518187 | controller |       wusr: true
2025-09-29 19:31:16.518316 | controller |       xgrp: false
2025-09-29 19:31:16.518445 | controller |       xoth: false
2025-09-29 19:31:16.518573 | controller |       xusr: false
2025-09-29 19:31:16.518718 | controller |   msg: All assertions passed
2025-09-29 19:31:16.518864 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-nodeset-values/values.yaml) => changed=false
2025-09-29 19:31:16.519018 | controller |   ansible_loop_var: item
2025-09-29 19:31:16.519156 | controller |   item:
2025-09-29 19:31:16.519285 | controller |     ansible_loop_var: item
2025-09-29 19:31:16.519412 | controller |     changed: false
2025-09-29 19:31:16.519540 | controller |     failed: false
2025-09-29 19:31:16.519684 | controller |     invocation:
2025-09-29 19:31:16.519812 | controller |       module_args:
2025-09-29 19:31:16.519941 | controller |         checksum_algorithm: sha1
2025-09-29 19:31:16.520099 | controller |         follow: false
2025-09-29 19:31:16.520236 | controller |         get_attributes: false
2025-09-29 19:31:16.520365 | controller |         get_checksum: false
2025-09-29 19:31:16.520493 | controller |         get_md5: false
2025-09-29 19:31:16.520631 | controller |         get_mime: false
2025-09-29 19:31:16.520777 | controller |         path: /home/zuul/ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-nodeset-values/values.yaml
2025-09-29 19:31:16.520908 | controller |     item: edpm-nodeset-values
2025-09-29 19:31:16.521086 | controller |     stat:
2025-09-29 19:31:16.521225 | controller |       atime: 1759174276.108824
2025-09-29 19:31:16.521354 | controller |       block_size: 4096
2025-09-29 19:31:16.521480 | controller |       blocks: 40
2025-09-29 19:31:16.521621 | controller |       ctime: 1759174276.1128242
2025-09-29 19:31:16.521756 | controller |       dev: 64513
2025-09-29 19:31:16.521891 | controller |       device_type: 0
2025-09-29 19:31:16.522048 | controller |       executable: false
2025-09-29 19:31:16.522185 | controller |       exists: true
2025-09-29 19:31:16.522313 | controller |       gid: 1000
2025-09-29 19:31:16.522441 | controller |       gr_name: zuul
2025-09-29 19:31:16.522570 | controller |       inode: 46274954
2025-09-29 19:31:16.522718 | controller |       isblk: false
2025-09-29 19:31:16.522846 | controller |       ischr: false
2025-09-29 19:31:16.523014 | controller |       isdir: false
2025-09-29 19:31:16.523151 | controller |       isfifo: false
2025-09-29 19:31:16.523280 | controller |       isgid: false
2025-09-29 19:31:16.523406 | controller |       islnk: false
2025-09-29 19:31:16.523533 | controller |       isreg: true
2025-09-29 19:31:16.523685 | controller |       issock: false
2025-09-29 19:31:16.523820 | controller |       isuid: false
2025-09-29 19:31:16.523948 | controller |       mode: '0644'
2025-09-29 19:31:16.524103 | controller |       mtime: 1759174275.9738219
2025-09-29 19:31:16.524237 | controller |       nlink: 1
2025-09-29 19:31:16.524375 | controller |       path: /home/zuul/ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-nodeset-values/values.yaml
2025-09-29 19:31:16.524507 | controller |       pw_name: zuul
2025-09-29 19:31:16.524661 | controller |       readable: true
2025-09-29 19:31:16.524790 | controller |       rgrp: true
2025-09-29 19:31:16.524917 | controller |       roth: true
2025-09-29 19:31:16.525072 | controller |       rusr: true
2025-09-29 19:31:16.525209 | controller |       size: 16482
2025-09-29 19:31:16.525337 | controller |       uid: 1000
2025-09-29 19:31:16.525466 | controller |       wgrp: false
2025-09-29 19:31:16.525600 | controller |       woth: false
2025-09-29 19:31:16.525749 | controller |       writeable: true
2025-09-29 19:31:16.525876 | controller |       wusr: true
2025-09-29 19:31:16.526028 | controller |       xgrp: false
2025-09-29 19:31:16.526164 | controller |       xoth: false
2025-09-29 19:31:16.526296 | controller |       xusr: false
2025-09-29 19:31:16.526423 | controller |   msg: All assertions passed
2025-09-29 19:31:16.526547 | controller |
2025-09-29 19:31:16.526703 | controller | TASK [Ensure we have correct MAC] **********************************************
2025-09-29 19:31:16.526840 | controller | Monday 29 September 2025  19:31:16 +0000 (0:00:00.026)       0:00:10.405 ******
2025-09-29 19:31:16.546151 | controller | ok: [instance] => changed=false
2025-09-29 19:31:16.546312 | controller |   msg: All assertions passed
2025-09-29 19:31:16.546443 | controller |
2025-09-29 19:31:16.546587 | controller | TASK [Ensure we don't have node_1 nor node_2] **********************************
2025-09-29 19:31:16.546750 | controller | Monday 29 September 2025  19:31:16 +0000 (0:00:00.033)       0:00:10.439 ******
2025-09-29 19:31:16.579259 | controller | ok: [instance] => changed=false
2025-09-29 19:31:16.579464 | controller |   msg: All assertions passed
2025-09-29 19:31:16.579637 | controller |
2025-09-29 19:31:16.579779 | controller | TASK [Update cifmw_networking_env_definition] **********************************
2025-09-29 19:31:16.579917 | controller | Monday 29 September 2025  19:31:16 +0000 (0:00:00.033)       0:00:10.472 ******
2025-09-29 19:31:16.605952 | controller | ok: [instance]
2025-09-29 19:31:16.606142 | controller |
2025-09-29 19:31:16.606293 | controller | TASK [Regenerate network-values] ***********************************************
2025-09-29 19:31:16.606433 | controller | Monday 29 September 2025  19:31:16 +0000 (0:00:00.026)       0:00:10.499 ******
2025-09-29 19:31:16.628134 | controller |
2025-09-29 19:31:16.628295 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-09-29 19:31:16.628436 | controller | Monday 29 September 2025  19:31:16 +0000 (0:00:00.022)       0:00:10.521 ******
2025-09-29 19:31:16.652835 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_snippets.yml for instance
2025-09-29 19:31:16.653008 | controller |
2025-09-29 19:31:16.653173 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-09-29 19:31:16.653323 | controller | Monday 29 September 2025  19:31:16 +0000 (0:00:00.024)       0:00:10.546 ******
2025-09-29 19:31:16.678411 | controller | ok: [instance] => changed=false
2025-09-29 19:31:16.678576 | controller |   msg: All assertions passed
2025-09-29 19:31:16.678729 | controller |
2025-09-29 19:31:16.678876 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-09-29 19:31:16.679044 | controller | Monday 29 September 2025  19:31:16 +0000 (0:00:00.025)       0:00:10.571 ******
2025-09-29 19:31:16.855072 | controller | ok: [instance]
2025-09-29 19:31:16.855119 | controller |
2025-09-29 19:31:16.855130 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-09-29 19:31:16.855251 | controller | Monday 29 September 2025  19:31:16 +0000 (0:00:00.176)       0:00:10.748 ******
2025-09-29 19:31:16.877921 | controller | ok: [instance] => changed=false
2025-09-29 19:31:17.048160 | controller |   msg: All assertions passed
2025-09-29 19:31:17.048189 | controller |
2025-09-29 19:31:17.048197 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-09-29 19:31:17.048204 | controller | Monday 29 September 2025  19:31:16 +0000 (0:00:00.022)       0:00:10.770 ******
2025-09-29 19:31:17.048214 | controller | ok: [instance]
2025-09-29 19:31:17.281067 | controller |
2025-09-29 19:31:17.281098 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-09-29 19:31:17.281106 | controller | Monday 29 September 2025  19:31:17 +0000 (0:00:00.170)       0:00:10.940 ******
2025-09-29 19:31:17.281116 | controller | ok: [instance]
2025-09-29 19:31:17.312226 | controller |
2025-09-29 19:31:17.312249 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-09-29 19:31:17.312268 | controller | Monday 29 September 2025  19:31:17 +0000 (0:00:00.232)       0:00:11.173 ******
2025-09-29 19:31:17.312279 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/network_values.yml for instance
2025-09-29 19:31:17.335717 | controller |
2025-09-29 19:31:17.335739 | controller | TASK [ci_gen_kustomize_values : Assert we have needed parameters] **************
2025-09-29 19:31:17.335746 | controller | Monday 29 September 2025  19:31:17 +0000 (0:00:00.031)       0:00:11.205 ******
2025-09-29 19:31:17.335756 | controller | ok: [instance] => changed=false
2025-09-29 19:31:17.519786 | controller |   msg: All assertions passed
2025-09-29 19:31:17.519816 | controller |
2025-09-29 19:31:17.519824 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-09-29 19:31:17.519831 | controller | Monday 29 September 2025  19:31:17 +0000 (0:00:00.023)       0:00:11.228 ******
2025-09-29 19:31:17.519841 | controller | ok: [instance]
2025-09-29 19:31:17.992049 | controller |
2025-09-29 19:31:17.992079 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-09-29 19:31:17.992088 | controller | Monday 29 September 2025  19:31:17 +0000 (0:00:00.183)       0:00:11.412 ******
2025-09-29 19:31:17.992098 | controller | changed: [instance]
2025-09-29 19:31:17.992263 | controller |
2025-09-29 19:31:17.992280 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-09-29 19:31:17.992290 | controller | Monday 29 September 2025  19:31:17 +0000 (0:00:00.472)       0:00:11.884 ******
2025-09-29 19:31:18.013968 | controller | ok: [instance]
2025-09-29 19:31:18.341206 | controller |
2025-09-29 19:31:18.341235 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-09-29 19:31:18.341243 | controller | Monday 29 September 2025  19:31:18 +0000 (0:00:00.022)       0:00:11.907 ******
2025-09-29 19:31:18.341253 | controller | ok: [instance]
2025-09-29 19:31:18.692404 | controller |
2025-09-29 19:31:18.692435 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-09-29 19:31:18.692443 | controller | Monday 29 September 2025  19:31:18 +0000 (0:00:00.326)       0:00:12.234 ******
2025-09-29 19:31:18.692453 | controller | ok: [instance]
2025-09-29 19:31:19.048274 | controller |
2025-09-29 19:31:19.048306 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-09-29 19:31:19.048314 | controller | Monday 29 September 2025  19:31:18 +0000 (0:00:00.351)       0:00:12.585 ******
2025-09-29 19:31:19.048324 | controller | ok: [instance]
2025-09-29 19:31:19.076063 | controller |
2025-09-29 19:31:19.076097 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-09-29 19:31:19.076106 | controller | Monday 29 September 2025  19:31:19 +0000 (0:00:00.355)       0:00:12.941 ******
2025-09-29 19:31:19.076116 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_values.yml for instance
2025-09-29 19:31:19.076122 | controller |
2025-09-29 19:31:19.076128 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-09-29 19:31:19.076136 | controller | Monday 29 September 2025  19:31:19 +0000 (0:00:00.027)       0:00:12.968 ******
2025-09-29 19:31:19.101059 | controller | ok: [instance] => changed=false
2025-09-29 19:31:19.101189 | controller |   msg: All assertions passed
2025-09-29 19:31:19.101220 | controller |
2025-09-29 19:31:19.101229 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-09-29 19:31:19.101242 | controller | Monday 29 September 2025  19:31:19 +0000 (0:00:00.025)       0:00:12.994 ******
2025-09-29 19:31:19.292665 | controller | ok: [instance]
2025-09-29 19:31:19.317763 | controller |
2025-09-29 19:31:19.317795 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-09-29 19:31:19.317806 | controller | Monday 29 September 2025  19:31:19 +0000 (0:00:00.191)       0:00:13.185 ******
2025-09-29 19:31:19.317823 | controller | ok: [instance]
2025-09-29 19:31:19.858613 | controller |
2025-09-29 19:31:19.858661 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-09-29 19:31:19.858669 | controller | Monday 29 September 2025  19:31:19 +0000 (0:00:00.025)       0:00:13.210 ******
2025-09-29 19:31:19.858686 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/02_ci_data.yaml)
2025-09-29 19:31:19.919481 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/03_user_data_b64.yaml)
2025-09-29 19:31:19.919514 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/04_user_data.yaml)
2025-09-29 19:31:19.919522 | controller |
2025-09-29 19:31:19.919529 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-09-29 19:31:19.919535 | controller | Monday 29 September 2025  19:31:19 +0000 (0:00:00.540)       0:00:13.751 ******
2025-09-29 19:31:19.919544 | controller | ok: [instance] => (item=02_ci_data.yaml)
2025-09-29 19:31:20.118406 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-09-29 19:31:20.118438 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-09-29 19:31:20.118446 | controller |
2025-09-29 19:31:20.118452 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-09-29 19:31:20.118458 | controller | Monday 29 September 2025  19:31:19 +0000 (0:00:00.060)       0:00:13.812 ******
2025-09-29 19:31:20.118468 | controller | ok: [instance]
2025-09-29 19:31:20.545235 | controller |
2025-09-29 19:31:20.545270 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-09-29 19:31:20.545279 | controller | Monday 29 September 2025  19:31:20 +0000 (0:00:00.199)       0:00:14.011 ******
2025-09-29 19:31:20.545289 | controller | changed: [instance]
2025-09-29 19:31:20.570740 | controller |
2025-09-29 19:31:20.570775 | controller | TASK [Regenerate edpm-nodeset-values] ******************************************
2025-09-29 19:31:20.570783 | controller | Monday 29 September 2025  19:31:20 +0000 (0:00:00.426)       0:00:14.437 ******
2025-09-29 19:31:20.570793 | controller |
2025-09-29 19:31:20.596089 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-09-29 19:31:20.596123 | controller | Monday 29 September 2025  19:31:20 +0000 (0:00:00.025)       0:00:14.463 ******
2025-09-29 19:31:20.596135 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_snippets.yml for instance
2025-09-29 19:31:20.624074 | controller |
2025-09-29 19:31:20.624110 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-09-29 19:31:20.624118 | controller | Monday 29 September 2025  19:31:20 +0000 (0:00:00.025)       0:00:14.489 ******
2025-09-29 19:31:20.624128 | controller | ok: [instance] => changed=false
2025-09-29 19:31:20.624151 | controller |   msg: All assertions passed
2025-09-29 19:31:20.624159 | controller |
2025-09-29 19:31:20.624362 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-09-29 19:31:20.804508 | controller | Monday 29 September 2025  19:31:20 +0000 (0:00:00.028)       0:00:14.517 ******
2025-09-29 19:31:20.804543 | controller | ok: [instance]
2025-09-29 19:31:20.827527 | controller |
2025-09-29 19:31:20.827558 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-09-29 19:31:20.827568 | controller | Monday 29 September 2025  19:31:20 +0000 (0:00:00.180)       0:00:14.697 ******
2025-09-29 19:31:20.827580 | controller | ok: [instance] => changed=false
2025-09-29 19:31:21.012556 | controller |   msg: All assertions passed
2025-09-29 19:31:21.012586 | controller |
2025-09-29 19:31:21.012594 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-09-29 19:31:21.012600 | controller | Monday 29 September 2025  19:31:20 +0000 (0:00:00.022)       0:00:14.720 ******
2025-09-29 19:31:21.012610 | controller | ok: [instance]
2025-09-29 19:31:21.237996 | controller |
2025-09-29 19:31:21.238030 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-09-29 19:31:21.238037 | controller | Monday 29 September 2025  19:31:21 +0000 (0:00:00.185)       0:00:14.905 ******
2025-09-29 19:31:21.238048 | controller | ok: [instance]
2025-09-29 19:31:21.272119 | controller |
2025-09-29 19:31:21.272161 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-09-29 19:31:21.272169 | controller | Monday 29 September 2025  19:31:21 +0000 (0:00:00.225)       0:00:15.130 ******
2025-09-29 19:31:21.272180 | 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-09-29 19:31:21.272213 | controller |
2025-09-29 19:31:21.272220 | controller | TASK [ci_gen_kustomize_values : Check if all required variables for accessing the node is provided.] ***
2025-09-29 19:31:21.272228 | controller | Monday 29 September 2025  19:31:21 +0000 (0:00:00.034)       0:00:15.164 ******
2025-09-29 19:31:21.297100 | 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-09-29 19:31:21.297302 | controller |
2025-09-29 19:31:21.297319 | controller | TASK [ci_gen_kustomize_values : Ensure the required parameters are defined and configured.] ***
2025-09-29 19:31:21.297458 | controller | Monday 29 September 2025  19:31:21 +0000 (0:00:00.025)       0:00:15.190 ******
2025-09-29 19:31:21.328393 | controller | ok: [instance] => changed=false
2025-09-29 19:31:21.328550 | controller |   msg: All assertions passed
2025-09-29 19:31:21.328701 | controller |
2025-09-29 19:31:21.328841 | controller | TASK [ci_gen_kustomize_values : Get common data for dataplane templating] ******
2025-09-29 19:31:21.328964 | controller | Monday 29 September 2025  19:31:21 +0000 (0:00:00.031)       0:00:15.221 ******
2025-09-29 19:31:21.353926 | 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-09-29 19:31:21.354096 | controller |
2025-09-29 19:31:21.354258 | controller | TASK [ci_gen_kustomize_values : Fetch the already deployed services for further usage] ***
2025-09-29 19:31:21.354403 | controller | Monday 29 September 2025  19:31:21 +0000 (0:00:00.025)       0:00:15.247 ******
2025-09-29 19:31:21.370749 | controller | skipping: [instance]
2025-09-29 19:31:21.370904 | controller |
2025-09-29 19:31:21.371083 | controller | TASK [ci_gen_kustomize_values : Expose the deployed services as a fact] ********
2025-09-29 19:31:21.371244 | controller | Monday 29 September 2025  19:31:21 +0000 (0:00:00.016)       0:00:15.264 ******
2025-09-29 19:31:21.391453 | controller | ok: [instance]
2025-09-29 19:31:21.391606 | controller |
2025-09-29 19:31:21.391780 | controller | TASK [ci_gen_kustomize_values : Check if required variables for nova migration are provided.] ***
2025-09-29 19:31:21.391903 | controller | Monday 29 September 2025  19:31:21 +0000 (0:00:00.020)       0:00:15.284 ******
2025-09-29 19:31:21.420016 | controller | ok: [instance] => changed=false
2025-09-29 19:31:21.420253 | controller |   msg: All assertions passed
2025-09-29 19:31:21.420423 | controller |
2025-09-29 19:31:21.420596 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-09-29 19:31:21.420792 | controller | Monday 29 September 2025  19:31:21 +0000 (0:00:00.028)       0:00:15.313 ******
2025-09-29 19:31:21.607053 | controller | ok: [instance]
2025-09-29 19:31:21.607142 | controller |
2025-09-29 19:31:21.607153 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-09-29 19:31:21.607161 | controller | Monday 29 September 2025  19:31:21 +0000 (0:00:00.186)       0:00:15.499 ******
2025-09-29 19:31:21.999034 | controller | ok: [instance]
2025-09-29 19:31:22.022681 | controller |
2025-09-29 19:31:22.022709 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-09-29 19:31:22.022719 | controller | Monday 29 September 2025  19:31:21 +0000 (0:00:00.391)       0:00:15.891 ******
2025-09-29 19:31:22.022731 | controller | ok: [instance]
2025-09-29 19:31:22.359443 | controller |
2025-09-29 19:31:22.359472 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-09-29 19:31:22.359480 | controller | Monday 29 September 2025  19:31:22 +0000 (0:00:00.023)       0:00:15.915 ******
2025-09-29 19:31:22.359495 | controller | ok: [instance]
2025-09-29 19:31:22.695172 | controller |
2025-09-29 19:31:22.695203 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-09-29 19:31:22.695211 | controller | Monday 29 September 2025  19:31:22 +0000 (0:00:00.336)       0:00:16.252 ******
2025-09-29 19:31:22.695221 | controller | ok: [instance]
2025-09-29 19:31:23.033507 | controller |
2025-09-29 19:31:23.033538 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-09-29 19:31:23.033546 | controller | Monday 29 September 2025  19:31:22 +0000 (0:00:00.335)       0:00:16.588 ******
2025-09-29 19:31:23.033556 | controller | ok: [instance]
2025-09-29 19:31:23.060081 | controller |
2025-09-29 19:31:23.060117 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-09-29 19:31:23.060127 | controller | Monday 29 September 2025  19:31:23 +0000 (0:00:00.338)       0:00:16.926 ******
2025-09-29 19:31:23.060140 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_values.yml for instance
2025-09-29 19:31:23.060172 | controller |
2025-09-29 19:31:23.060382 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-09-29 19:31:23.085176 | controller | Monday 29 September 2025  19:31:23 +0000 (0:00:00.026)       0:00:16.953 ******
2025-09-29 19:31:23.085210 | controller | ok: [instance] => changed=false
2025-09-29 19:31:23.273595 | controller |   msg: All assertions passed
2025-09-29 19:31:23.273626 | controller |
2025-09-29 19:31:23.273633 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-09-29 19:31:23.273640 | controller | Monday 29 September 2025  19:31:23 +0000 (0:00:00.024)       0:00:16.977 ******
2025-09-29 19:31:23.273663 | controller | ok: [instance]
2025-09-29 19:31:23.290520 | controller |
2025-09-29 19:31:23.290544 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-09-29 19:31:23.290551 | controller | Monday 29 September 2025  19:31:23 +0000 (0:00:00.188)       0:00:17.166 ******
2025-09-29 19:31:23.290561 | controller | ok: [instance]
2025-09-29 19:31:23.786826 | controller |
2025-09-29 19:31:23.786858 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-09-29 19:31:23.786866 | controller | Monday 29 September 2025  19:31:23 +0000 (0:00:00.016)       0:00:17.183 ******
2025-09-29 19:31:23.786877 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/02_ci_data.yaml)
2025-09-29 19:31:23.833067 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/03_user_data_b64.yaml)
2025-09-29 19:31:23.833101 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/04_user_data.yaml)
2025-09-29 19:31:23.833110 | controller |
2025-09-29 19:31:23.833118 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-09-29 19:31:23.833125 | controller | Monday 29 September 2025  19:31:23 +0000 (0:00:00.495)       0:00:17.679 ******
2025-09-29 19:31:23.833136 | controller | ok: [instance] => (item=02_ci_data.yaml)
2025-09-29 19:31:24.011814 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-09-29 19:31:24.011845 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-09-29 19:31:24.011853 | controller |
2025-09-29 19:31:24.011859 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-09-29 19:31:24.011865 | controller | Monday 29 September 2025  19:31:23 +0000 (0:00:00.046)       0:00:17.725 ******
2025-09-29 19:31:24.011875 | controller | ok: [instance]
2025-09-29 19:31:24.362566 | controller |
2025-09-29 19:31:24.362596 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-09-29 19:31:24.362604 | controller | Monday 29 September 2025  19:31:24 +0000 (0:00:00.178)       0:00:17.904 ******
2025-09-29 19:31:24.362614 | controller | ok: [instance]
2025-09-29 19:31:24.721058 | controller |
2025-09-29 19:31:24.721088 | controller | TASK [Copy generated values to correct location] *******************************
2025-09-29 19:31:24.721102 | controller | Monday 29 September 2025  19:31:24 +0000 (0:00:00.350)       0:00:18.255 ******
2025-09-29 19:31:24.721113 | controller | changed: [instance] => (item={'key': 'network-values', 'value': 'examples/va/hci/control-plane/networking/nncp/values.yaml'})
2025-09-29 19:31:24.721119 | controller | changed: [instance] => (item={'key': 'edpm-nodeset-values', 'value': 'examples/va/hci/edpm-pre-ceph/nodeset/values.yaml'})
2025-09-29 19:31:24.721125 | controller |
2025-09-29 19:31:24.721131 | controller | TASK [Ensure kustomize is able to build] ***************************************
2025-09-29 19:31:24.721138 | controller | Monday 29 September 2025  19:31:24 +0000 (0:00:00.358)       0:00:18.613 ******
2025-09-29 19:31:25.288907 | controller | changed: [instance] => (item={'name': 'ctlplane', 'path': 'examples/va/hci/control-plane/networking/nncp'})
2025-09-29 19:31:25.350167 | controller | changed: [instance] => (item={'name': 'dataplane', 'path': 'examples/va/hci/edpm-pre-ceph/nodeset'})
2025-09-29 19:31:25.350207 | controller |
2025-09-29 19:31:25.350215 | controller | PLAY RECAP *********************************************************************
2025-09-29 19:31:25.350221 | controller | instance                   : ok=107  changed=20   unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-09-29 19:31:25.350227 | controller |
2025-09-29 19:31:25.350232 | controller | Monday 29 September 2025  19:31:25 +0000 (0:00:00.565)       0:00:19.179 ******
2025-09-29 19:31:25.350237 | controller | ===============================================================================
2025-09-29 19:31:25.350243 | controller | Gathering Facts --------------------------------------------------------- 0.79s
2025-09-29 19:31:25.350248 | controller | Ensure architecture repository is building ------------------------------ 0.66s
2025-09-29 19:31:25.350253 | controller | Ensure kustomize is able to build --------------------------------------- 0.57s
2025-09-29 19:31:25.350258 | controller | ci_gen_kustomize_values : Load various snippet files -------------------- 0.54s
2025-09-29 19:31:25.350264 | controller | ci_gen_kustomize_values : Generate CI snippet --------------------------- 0.53s
2025-09-29 19:31:25.350269 | controller | ci_gen_kustomize_values : Load various snippet files -------------------- 0.50s
2025-09-29 19:31:25.350274 | controller | ci_gen_kustomize_values : Load various snippet files -------------------- 0.48s
2025-09-29 19:31:25.350280 | controller | ci_gen_kustomize_values : Load various snippet files -------------------- 0.48s
2025-09-29 19:31:25.350285 | controller | ci_gen_kustomize_values : Generate CI snippet --------------------------- 0.47s
2025-09-29 19:31:25.350290 | controller | ci_gen_kustomize_values : Output values file ---------------------------- 0.43s
2025-09-29 19:31:25.350295 | controller | ci_gen_kustomize_values : Generate CI snippet --------------------------- 0.39s
2025-09-29 19:31:25.350300 | controller | Create nova migration keypair ------------------------------------------- 0.39s
2025-09-29 19:31:25.350306 | controller | ci_gen_kustomize_values : Generate CI snippet --------------------------- 0.38s
2025-09-29 19:31:25.350311 | controller | Copy generated values to correct location ------------------------------- 0.36s
2025-09-29 19:31:25.350316 | controller | ci_gen_kustomize_values : Output values file ---------------------------- 0.36s
2025-09-29 19:31:25.350321 | controller | ci_gen_kustomize_values : Copy the base values.yaml --------------------- 0.36s
2025-09-29 19:31:25.350326 | controller | ci_gen_kustomize_values : Push user provided dataset -------------------- 0.35s
2025-09-29 19:31:25.350331 | controller | ci_gen_kustomize_values : Output values file ---------------------------- 0.35s
2025-09-29 19:31:25.350337 | controller | ci_gen_kustomize_values : Push base64 CI patches ------------------------ 0.34s
2025-09-29 19:31:25.350342 | controller | Get generated files status ---------------------------------------------- 0.34s
2025-09-29 19:31:25.350352 | controller | INFO     Running default > cleanup
2025-09-29 19:31:25.350899 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-09-29 19:31:25.351580 | controller | INFO     Writing /tmp/report.html report.
2025-09-29 19:31:25.886027 | controller | changed
2025-09-29 19:31:25.911469 | 
2025-09-29 19:31:25.911541 | PLAY RECAP
2025-09-29 19:31:25.911588 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-29 19:31:25.911612 | 
2025-09-29 19:31:26.011474 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-29 19:31:26.012292 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-29 19:31:26.601153 | 
2025-09-29 19:31:26.601268 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-09-29 19:31:26.623248 | 
2025-09-29 19:31:26.623385 | TASK [Filter out host if needed]
2025-09-29 19:31:26.632791 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-09-29 19:31:26.637736 | 
2025-09-29 19:31:26.637827 | TASK [Ensure file is present]
2025-09-29 19:31:26.981735 | controller | ok
2025-09-29 19:31:26.993651 | 
2025-09-29 19:31:26.993738 | TASK [Manage molecule report file]
2025-09-29 19:31:27.520023 | controller | changed
2025-09-29 19:31:27.526076 | 
2025-09-29 19:31:27.526189 | TASK [Check if we get ci-framework-data basedir]
2025-09-29 19:31:27.718038 | controller | ok
2025-09-29 19:31:27.724372 | 
2025-09-29 19:31:27.724457 | TASK [Create ci-framework-data log directory for zuul]
2025-09-29 19:31:28.067112 | controller | changed
2025-09-29 19:31:28.077252 | 
2025-09-29 19:31:28.077335 | TASK [Copy ci-framework interesting files]
2025-09-29 19:31:28.277539 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-09-29 19:31:28.602075 | controller | changed
2025-09-29 19:31:28.608040 | 
2025-09-29 19:31:28.608114 | TASK [Get SELinux listing]
2025-09-29 19:31:29.133101 | controller | changed
2025-09-29 19:31:29.139303 | 
2025-09-29 19:31:29.139380 | TASK [Generate log index]
2025-09-29 19:31:29.809327 | controller | changed
2025-09-29 19:31:29.815251 | 
2025-09-29 19:31:29.815327 | TASK [Get some env related data]
2025-09-29 19:31:30.368609 | controller | /home/zuul/.local/bin/ansible
2025-09-29 19:31:31.357442 | controller | changed
2025-09-29 19:31:31.364050 | 
2025-09-29 19:31:31.364136 | TASK [Generate list of logs to collect in home directory]
2025-09-29 19:31:31.689737 | controller | ok: All paths examined
2025-09-29 19:31:31.695289 | 
2025-09-29 19:31:31.695372 | LOOP [Copy logs from home directory]
2025-09-29 19:31:32.023806 | controller | changed:
2025-09-29 19:31:32.023932 | controller | {
2025-09-29 19:31:32.023965 | controller |   "atime": 1759174127.1782537,
2025-09-29 19:31:32.023987 | controller |   "ctime": 1759174142.7841182,
2025-09-29 19:31:32.024006 | controller |   "dev": 64513,
2025-09-29 19:31:32.024022 | controller |   "gid": 1000,
2025-09-29 19:31:32.024038 | controller |   "gr_name": "zuul",
2025-09-29 19:31:32.024055 | controller |   "inode": 4407146,
2025-09-29 19:31:32.024070 | controller |   "isblk": false,
2025-09-29 19:31:32.024087 | controller |   "ischr": false,
2025-09-29 19:31:32.024102 | controller |   "isdir": false,
2025-09-29 19:31:32.024117 | controller |   "isfifo": false,
2025-09-29 19:31:32.024133 | controller |   "isgid": false,
2025-09-29 19:31:32.024160 | controller |   "islnk": false,
2025-09-29 19:31:32.024178 | controller |   "isreg": true,
2025-09-29 19:31:32.024193 | controller |   "issock": false,
2025-09-29 19:31:32.024208 | controller |   "isuid": false,
2025-09-29 19:31:32.024223 | controller |   "mode": "0644",
2025-09-29 19:31:32.024238 | controller |   "mtime": 1759174142.7841182,
2025-09-29 19:31:32.024252 | controller |   "nlink": 1,
2025-09-29 19:31:32.024267 | controller |   "path": "/home/zuul/ansible.log",
2025-09-29 19:31:32.024285 | controller |   "pw_name": "zuul",
2025-09-29 19:31:32.024301 | controller |   "rgrp": true,
2025-09-29 19:31:32.024316 | controller |   "roth": true,
2025-09-29 19:31:32.024331 | controller |   "rusr": true,
2025-09-29 19:31:32.024346 | controller |   "size": 6749,
2025-09-29 19:31:32.024360 | controller |   "uid": 1000,
2025-09-29 19:31:32.024374 | controller |   "wgrp": false,
2025-09-29 19:31:32.024388 | controller |   "woth": false,
2025-09-29 19:31:32.024405 | controller |   "wusr": true,
2025-09-29 19:31:32.024420 | controller |   "xgrp": false,
2025-09-29 19:31:32.024436 | controller |   "xoth": false,
2025-09-29 19:31:32.024451 | controller |   "xusr": false
2025-09-29 19:31:32.024465 | controller | }
2025-09-29 19:31:32.037718 | 
2025-09-29 19:31:32.037815 | TASK [Copy crio stats log file]
2025-09-29 19:31:32.063448 | controller | skipping: Conditional result was False
2025-09-29 19:31:32.069603 | 
2025-09-29 19:31:32.069681 | TASK [Get SELinux related data]
2025-09-29 19:31:32.295880 | controller | <no matches>
2025-09-29 19:31:32.607759 | controller | ERROR
2025-09-29 19:31:32.607877 | controller | {
2025-09-29 19:31:32.607928 | controller |   "delta": "0:00:00.010331",
2025-09-29 19:31:32.607960 | controller |   "end": "2025-09-29 19:31:32.296868",
2025-09-29 19:31:32.607982 | controller |   "msg": "non-zero return code",
2025-09-29 19:31:32.608000 | controller |   "rc": 1,
2025-09-29 19:31:32.608017 | controller |   "start": "2025-09-29 19:31:32.286537"
2025-09-29 19:31:32.608034 | controller | }
2025-09-29 19:31:32.608060 | controller | ERROR: Ignoring Errors
2025-09-29 19:31:32.613522 | 
2025-09-29 19:31:32.613595 | TASK [Create system configuration directory]
2025-09-29 19:31:32.814555 | controller | changed
2025-09-29 19:31:32.819882 | 
2025-09-29 19:31:32.819992 | TASK [Get some of the system configurations]
2025-09-29 19:31:33.349660 | controller | changed
2025-09-29 19:31:33.355603 | 
2025-09-29 19:31:33.355685 | TASK [Copy generated documentation if available]
2025-09-29 19:31:33.369693 | controller | skipping: Conditional result was False
2025-09-29 19:31:33.375853 | 
2025-09-29 19:31:33.375970 | TASK [Copy generated AsciiDoc documentation if available]
2025-09-29 19:31:33.404239 | controller | skipping: Conditional result was False
2025-09-29 19:31:33.410312 | 
2025-09-29 19:31:33.410380 | TASK [Compress logs bigger than 2MB]
2025-09-29 19:31:33.937800 | controller | changed
2025-09-29 19:31:33.943846 | 
2025-09-29 19:31:33.943960 | TASK [Copy files from workspace on node]
2025-09-29 19:31:33.972866 | controller | ok
2025-09-29 19:31:34.020076 | 
2025-09-29 19:31:34.020202 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-29 19:31:34.056277 | controller | skipping: Conditional result was False
2025-09-29 19:31:34.062386 | 
2025-09-29 19:31:34.062475 | TASK [fetch-output : Set log path for single node]
2025-09-29 19:31:34.113249 | controller | ok
2025-09-29 19:31:34.118636 | 
2025-09-29 19:31:34.118721 | LOOP [fetch-output : Ensure local output dirs]
2025-09-29 19:31:34.364029 | controller -> localhost | ok: "/var/lib/zuul/builds/9edf4aeb45c74c7aabd7434214abfd70/work/logs"
2025-09-29 19:31:34.364273 | controller -> localhost | changed: All items complete
2025-09-29 19:31:34.364308 | 
2025-09-29 19:31:34.603374 | controller -> localhost | changed: "/var/lib/zuul/builds/9edf4aeb45c74c7aabd7434214abfd70/work/artifacts"
2025-09-29 19:31:34.859762 | controller -> localhost | changed: "/var/lib/zuul/builds/9edf4aeb45c74c7aabd7434214abfd70/work/docs"
2025-09-29 19:31:34.877964 | 
2025-09-29 19:31:34.878061 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-29 19:31:35.516824 | controller | changed:
2025-09-29 19:31:35.517061 | controller | .d..t...... ./
2025-09-29 19:31:35.517094 | controller | >f+++++++++ README.html
2025-09-29 19:31:35.517118 | controller | >f+++++++++ ansible-execution.log
2025-09-29 19:31:35.517141 | controller | >f+++++++++ ansible.log
2025-09-29 19:31:35.517177 | controller | >f+++++++++ dmesg.log
2025-09-29 19:31:35.517198 | controller | >f+++++++++ installed-pkgs.log
2025-09-29 19:31:35.517220 | controller | >f+++++++++ python.log
2025-09-29 19:31:35.517241 | controller | >f+++++++++ registries.conf
2025-09-29 19:31:35.517260 | controller | >f+++++++++ report.html
2025-09-29 19:31:35.517280 | controller | >f+++++++++ selinux-denials.log
2025-09-29 19:31:35.517299 | controller | >f+++++++++ selinux-listing.log
2025-09-29 19:31:35.517416 | controller | cd+++++++++ ci-framework-data/
2025-09-29 19:31:35.517444 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-09-29 19:31:35.517466 | controller | >f+++++++++ ci-framework-data/artifacts/ctlplane.yaml
2025-09-29 19:31:35.517486 | controller | >f+++++++++ ci-framework-data/artifacts/dataplane.yaml
2025-09-29 19:31:35.517505 | controller | >f+++++++++ ci-framework-data/artifacts/nova_migration_key
2025-09-29 19:31:35.517523 | controller | >f+++++++++ ci-framework-data/artifacts/nova_migration_key.pub
2025-09-29 19:31:35.517541 | controller | >f+++++++++ ci-framework-data/artifacts/vanilla-ctlplane.yaml
2025-09-29 19:31:35.517558 | controller | >f+++++++++ ci-framework-data/artifacts/vanilla-dataplane.yaml
2025-09-29 19:31:35.517575 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-09-29 19:31:35.517592 | controller | cd+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/
2025-09-29 19:31:35.517609 | controller | cd+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-nodeset-values/
2025-09-29 19:31:35.517627 | controller | >f+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-nodeset-values/values.yaml
2025-09-29 19:31:35.517645 | controller | cd+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/
2025-09-29 19:31:35.517663 | controller | >f+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/values.yaml
2025-09-29 19:31:35.517681 | controller | >f+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/values.yaml.28996.2025-09-29@19:31:20~
2025-09-29 19:31:35.517698 | controller | cd+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/
2025-09-29 19:31:35.517716 | controller | cd+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/
2025-09-29 19:31:35.517732 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/01_original.yaml
2025-09-29 19:31:35.517749 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/02_ci_data.yaml
2025-09-29 19:31:35.517766 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/03_user_data_b64.yaml
2025-09-29 19:31:35.517783 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/04_user_data.yaml
2025-09-29 19:31:35.517805 | controller | cd+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/
2025-09-29 19:31:35.517823 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/01_original.yaml
2025-09-29 19:31:35.517841 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/02_ci_data.yaml
2025-09-29 19:31:35.517858 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/02_ci_data.yaml.28827.2025-09-29@19:31:17~
2025-09-29 19:31:35.517875 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/03_user_data_b64.yaml
2025-09-29 19:31:35.517893 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/04_user_data.yaml
2025-09-29 19:31:35.518014 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-09-29 19:31:35.518308 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-09-29 19:31:35.518342 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-09-29 19:31:35.518364 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-09-29 19:31:35.518385 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-09-29 19:31:35.518406 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-09-29 19:31:35.518424 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-09-29 19:31:35.518442 | controller | cd+++++++++ ci-framework-data/logs/
2025-09-29 19:31:35.518460 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-09-29 19:31:35.518479 | controller | cd+++++++++ registries.conf.d/
2025-09-29 19:31:35.518497 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-09-29 19:31:35.518515 | controller | cd+++++++++ system-config/
2025-09-29 19:31:35.518532 | controller | cd+++++++++ system-config/libvirt/
2025-09-29 19:31:35.518548 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-09-29 19:31:35.518565 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-09-29 19:31:35.978395 | controller | changed: .d..t...... ./
2025-09-29 19:31:36.452040 | controller | changed: .d..t...... ./
2025-09-29 19:31:36.481035 | 
2025-09-29 19:31:36.481131 | TASK [Return artifact to Zuul]
2025-09-29 19:31:36.524462 | controller | ok
2025-09-29 19:31:36.544684 | 
2025-09-29 19:31:36.544788 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-09-29 19:31:36.544895 | 
2025-09-29 19:31:36.544951 | PLAY RECAP
2025-09-29 19:31:36.544995 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-09-29 19:31:36.545017 | 
2025-09-29 19:31:36.642723 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-29 19:31:36.643471 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-29 19:31:37.216176 | 
2025-09-29 19:31:37.216276 | PLAY [all]
2025-09-29 19:31:37.245712 | 
2025-09-29 19:31:37.245794 | TASK [include_role : fetch-output]
2025-09-29 19:31:37.294717 | controller | ok
2025-09-29 19:31:37.316730 | 
2025-09-29 19:31:37.316878 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-29 19:31:37.371277 | controller | skipping: Conditional result was False
2025-09-29 19:31:37.376931 | 
2025-09-29 19:31:37.377009 | TASK [fetch-output : Set log path for single node]
2025-09-29 19:31:37.404792 | controller | ok
2025-09-29 19:31:37.410078 | 
2025-09-29 19:31:37.410165 | LOOP [fetch-output : Ensure local output dirs]
2025-09-29 19:31:37.781485 | controller -> localhost | ok: "/var/lib/zuul/builds/9edf4aeb45c74c7aabd7434214abfd70/work/logs"
2025-09-29 19:31:37.963937 | controller -> localhost | ok: "/var/lib/zuul/builds/9edf4aeb45c74c7aabd7434214abfd70/work/artifacts"
2025-09-29 19:31:38.204134 | controller -> localhost | ok: "/var/lib/zuul/builds/9edf4aeb45c74c7aabd7434214abfd70/work/docs"
2025-09-29 19:31:38.213529 | 
2025-09-29 19:31:38.213600 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-29 19:31:38.808488 | controller | ok
2025-09-29 19:31:38.808660 | controller | ok: All items complete
2025-09-29 19:31:38.808691 | 
2025-09-29 19:31:39.373791 | controller | ok
2025-09-29 19:31:39.876730 | controller | ok
2025-09-29 19:31:39.894522 | 
2025-09-29 19:31:39.894772 | TASK [include_role : fetch-output-openshift]
2025-09-29 19:31:39.912960 | controller | skipping: Conditional result was False
2025-09-29 19:31:39.920641 | 
2025-09-29 19:31:39.920727 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-09-29 19:31:40.514099 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.028915
2025-09-29 19:31:40.738411 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.019488
2025-09-29 19:31:40.802261 | 
2025-09-29 19:31:40.802361 | PLAY [all]
2025-09-29 19:31:40.830719 | 
2025-09-29 19:31:40.830831 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-09-29 19:31:41.267744 | controller | changed
2025-09-29 19:31:41.291565 | 
2025-09-29 19:31:41.291652 | PLAY RECAP
2025-09-29 19:31:41.291698 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-29 19:31:41.291720 | 
2025-09-29 19:31:41.396289 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-29 19:31:41.397100 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-09-29 19:31:41.995508 | 
2025-09-29 19:31:41.995614 | PLAY [localhost]
2025-09-29 19:31:42.013085 | 
2025-09-29 19:31:42.013174 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-09-29 19:31:42.344786 | localhost | changed
2025-09-29 19:31:42.349446 | 
2025-09-29 19:31:42.349538 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-09-29 19:31:42.376855 | localhost | ok
2025-09-29 19:31:42.385192 | 
2025-09-29 19:31:42.385271 | TASK [add-fileserver : Create SSH private key tempfile]
2025-09-29 19:31:42.717723 | localhost | changed
2025-09-29 19:31:42.723632 | 
2025-09-29 19:31:42.723726 | TASK [add-fileserver : Create SSH private key from secret]
2025-09-29 19:31:43.309184 | localhost | changed
2025-09-29 19:31:43.316360 | 
2025-09-29 19:31:43.316450 | TASK [add-fileserver : Add fileserver ssh key]
2025-09-29 19:31:43.679904 | localhost | Identity added: /var/lib/zuul/builds/9edf4aeb45c74c7aabd7434214abfd70/work/tmp/ansible.gx5_fjd1 (/var/lib/zuul/builds/9edf4aeb45c74c7aabd7434214abfd70/work/tmp/ansible.gx5_fjd1)
2025-09-29 19:31:43.680138 | localhost | ok: Runtime: 0:00:00.008162
2025-09-29 19:31:43.686187 | 
2025-09-29 19:31:43.686273 | TASK [add-fileserver : Remove SSH private key from disk]
2025-09-29 19:31:43.912732 | localhost | ok: Runtime: 0:00:00.005095
2025-09-29 19:31:43.917507 | 
2025-09-29 19:31:43.917719 | TASK [add-fileserver : Add fileserver to inventory]
2025-09-29 19:31:43.966865 | localhost | changed
2025-09-29 19:31:43.971488 | 
2025-09-29 19:31:43.971560 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-09-29 19:31:44.305258 | localhost | changed
2025-09-29 19:31:44.328458 | 
2025-09-29 19:31:44.328565 | PLAY [localhost]
2025-09-29 19:31:44.340409 | 
2025-09-29 19:31:44.340507 | TASK [Generate bulk log download script]
2025-09-29 19:31:44.359330 | localhost | ok
2025-09-29 19:31:44.370841 | 
2025-09-29 19:31:44.370943 | TASK [local-log-download : Check API endpoint is defined]
2025-09-29 19:31:44.398019 | localhost | ok: All assertions passed
2025-09-29 19:31:44.402592 | 
2025-09-29 19:31:44.402657 | TASK [local-log-download : Create download script]
2025-09-29 19:31:44.795753 | localhost -> localhost | changed
2025-09-29 19:31:44.804999 | 
2025-09-29 19:31:44.805082 | TASK [Register quick-download link]
2025-09-29 19:31:44.823307 | localhost | ok
2025-09-29 19:31:44.859738 | 
2025-09-29 19:31:44.859830 | PLAY [logserver.rdoproject.org]
2025-09-29 19:31:44.869671 | 
2025-09-29 19:31:44.869738 | TASK [Set zuul-log-path fact]
2025-09-29 19:31:44.886109 | logserver.rdoproject.org | ok
2025-09-29 19:31:44.895964 | 
2025-09-29 19:31:44.896047 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-29 19:31:44.921788 | logserver.rdoproject.org | ok
2025-09-29 19:31:44.933017 | 
2025-09-29 19:31:44.933104 | TASK [upload-logs : Create log directories]
2025-09-29 19:31:46.549828 | logserver.rdoproject.org | changed
2025-09-29 19:31:46.552902 | 
2025-09-29 19:31:46.553001 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-09-29 19:31:46.780974 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005207
2025-09-29 19:31:46.787564 | 
2025-09-29 19:31:46.787642 | TASK [upload-logs : Upload logs to log server]
2025-09-29 19:31:48.349732 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-09-29 19:31:48.359059 | 
2025-09-29 19:31:48.359145 | LOOP [upload-logs : Compress console log and json output]
2025-09-29 19:31:48.414468 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 19:31:48.415226 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 19:31:48.423546 | 
2025-09-29 19:31:48.423625 | LOOP [upload-logs : Upload compressed console log and json output]
2025-09-29 19:31:48.467146 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 19:31:48.467408 | 
2025-09-29 19:31:48.468297 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 19:31:48.481642 | 
2025-09-29 19:31:48.481722 | LOOP [upload-logs : Upload console log and json output]