2025-09-30 11:17:28.403677 | Job console starting...
2025-09-30 11:17:28.646411 | Updating repositories
2025-09-30 11:17:29.545597 | Preparing job workspace
2025-09-30 11:17:51.384777 | Running Ansible setup...
2025-09-30 11:18:01.597653 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-30 11:18:03.843494 | 
2025-09-30 11:18:03.843626 | PLAY [localhost]
2025-09-30 11:18:03.893636 | 
2025-09-30 11:18:03.893759 | TASK [Gathering Facts]
2025-09-30 11:18:06.843513 | localhost | ok
2025-09-30 11:18:06.878463 | 
2025-09-30 11:18:06.888791 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-09-30 11:18:08.787788 | localhost -> localhost | changed
2025-09-30 11:18:08.794215 | 
2025-09-30 11:18:08.794316 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-09-30 11:18:12.822504 | localhost -> localhost | changed
2025-09-30 11:18:12.831318 | 
2025-09-30 11:18:12.831407 | TASK [Setup log path fact]
2025-09-30 11:18:12.925645 | localhost | ok
2025-09-30 11:18:12.979418 | 
2025-09-30 11:18:12.979537 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-30 11:18:13.105667 | localhost | ok
2025-09-30 11:18:13.127492 | 
2025-09-30 11:18:13.127599 | TASK [emit-job-header : Print job information]
2025-09-30 11:18:13.282704 | # Job Information
2025-09-30 11:18:13.282954 | Ansible Version: 2.15.12
2025-09-30 11:18:13.283010 | Job: cifmw-molecule-kustomize_deploy
2025-09-30 11:18:13.283038 | Pipeline: github-check
2025-09-30 11:18:13.283072 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-09-30 11:18:13.283094 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3333
2025-09-30 11:18:13.283115 | Log URL (when completed): https://logserver.rdoproject.org/3e5/rdoproject.org/3e5695ac00b84683a1e576f306dfc53f/
2025-09-30 11:18:13.283139 | Event ID: c7820690-9dee-11f0-870e-caa834dfb11c
2025-09-30 11:18:13.286870 | 
2025-09-30 11:18:13.287036 | LOOP [emit-job-header : Print node information]
2025-09-30 11:18:13.692709 | localhost | ok:
2025-09-30 11:18:13.692876 | localhost | # Node Information
2025-09-30 11:18:13.692924 | localhost | Inventory Hostname: controller
2025-09-30 11:18:13.692958 | localhost | Hostname: np0005462684
2025-09-30 11:18:13.692983 | localhost | Username: zuul
2025-09-30 11:18:13.693006 | localhost | Distro: CentOS 9
2025-09-30 11:18:13.693025 | localhost | Provider: ibm-bm4-nodepool
2025-09-30 11:18:13.693045 | localhost | Region: regionOne
2025-09-30 11:18:13.693063 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-09-30 11:18:13.693081 | localhost | Product Name: OpenStack Compute
2025-09-30 11:18:13.693099 | localhost | Interface IP: 192.168.26.157
2025-09-30 11:18:13.762310 | 
2025-09-30 11:18:13.762410 | PLAY [all]
2025-09-30 11:18:13.786818 | 
2025-09-30 11:18:13.786931 | TASK [Gather network facts]
2025-09-30 11:18:14.219197 | controller | ok
2025-09-30 11:18:14.328051 | 
2025-09-30 11:18:14.328167 | TASK [include_role : start-zuul-console]
2025-09-30 11:18:14.410733 | controller | ok
2025-09-30 11:18:14.493186 | 
2025-09-30 11:18:14.493326 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-09-30 11:18:15.648620 | controller | ok
2025-09-30 11:18:15.683935 | 
2025-09-30 11:18:15.689967 | TASK [include_role : add-build-sshkey]
2025-09-30 11:18:15.763950 | controller | ok
2025-09-30 11:18:15.812346 | 
2025-09-30 11:18:15.812451 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-09-30 11:18:16.792559 | controller -> localhost | ok
2025-09-30 11:18:16.799213 | 
2025-09-30 11:18:16.799301 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-09-30 11:18:16.884629 | controller | ok
2025-09-30 11:18:16.954059 | controller | included: /var/lib/zuul/builds/3e5695ac00b84683a1e576f306dfc53f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-09-30 11:18:16.980487 | 
2025-09-30 11:18:16.980585 | TASK [add-build-sshkey : Create Temp SSH key]
2025-09-30 11:18:20.750610 | controller -> localhost | Generating public/private rsa key pair.
2025-09-30 11:18:20.750828 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3e5695ac00b84683a1e576f306dfc53f/work/3e5695ac00b84683a1e576f306dfc53f_id_rsa.
2025-09-30 11:18:20.750875 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3e5695ac00b84683a1e576f306dfc53f/work/3e5695ac00b84683a1e576f306dfc53f_id_rsa.pub.
2025-09-30 11:18:20.750904 | controller -> localhost | The key fingerprint is:
2025-09-30 11:18:20.750951 | controller -> localhost | SHA256:YvpwMThsfaLznHGaA0oc5F19M/YfpaV7/XlKvrsH9xY zuul-build-sshkey
2025-09-30 11:18:20.750975 | controller -> localhost | The key's randomart image is:
2025-09-30 11:18:20.750997 | controller -> localhost | +---[RSA 3072]----+
2025-09-30 11:18:20.751017 | controller -> localhost | |       .         |
2025-09-30 11:18:20.751038 | controller -> localhost | |  .   . . =     o|
2025-09-30 11:18:20.751058 | controller -> localhost | | o . .   o +   = |
2025-09-30 11:18:20.751077 | controller -> localhost | |  o..o      . +  |
2025-09-30 11:18:20.751097 | controller -> localhost | | . .= B S    . o.|
2025-09-30 11:18:20.751116 | controller -> localhost | |  o..= *      +E+|
2025-09-30 11:18:20.751136 | controller -> localhost | | . .=.o .     .+=|
2025-09-30 11:18:20.751158 | controller -> localhost | |  .  B.*     o .*|
2025-09-30 11:18:20.751177 | controller -> localhost | |      B.      *B.|
2025-09-30 11:18:20.751196 | controller -> localhost | +----[SHA256]-----+
2025-09-30 11:18:20.751249 | controller -> localhost | ok: Runtime: 0:00:01.567981
2025-09-30 11:18:20.758140 | 
2025-09-30 11:18:20.758301 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-09-30 11:18:20.900335 | controller | ok
2025-09-30 11:18:20.953840 | controller | included: /var/lib/zuul/builds/3e5695ac00b84683a1e576f306dfc53f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-09-30 11:18:21.229379 | 
2025-09-30 11:18:21.229491 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-09-30 11:18:21.342277 | controller | skipping: Conditional result was False
2025-09-30 11:18:21.348414 | 
2025-09-30 11:18:21.348504 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-09-30 11:18:22.400513 | controller | changed
2025-09-30 11:18:22.423219 | 
2025-09-30 11:18:22.423365 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-09-30 11:18:22.673843 | controller | ok
2025-09-30 11:18:22.698858 | 
2025-09-30 11:18:22.698984 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-09-30 11:18:23.699396 | controller | changed
2025-09-30 11:18:23.704664 | 
2025-09-30 11:18:23.704754 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-09-30 11:18:24.716459 | controller | changed
2025-09-30 11:18:24.721678 | 
2025-09-30 11:18:24.721759 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-09-30 11:18:24.819558 | controller | skipping: Conditional result was False
2025-09-30 11:18:24.832438 | 
2025-09-30 11:18:24.832530 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-09-30 11:18:26.529168 | controller -> localhost | changed
2025-09-30 11:18:26.542163 | 
2025-09-30 11:18:26.542294 | TASK [add-build-sshkey : Add back temp key]
2025-09-30 11:18:28.042106 | controller -> localhost | Identity added: /var/lib/zuul/builds/3e5695ac00b84683a1e576f306dfc53f/work/3e5695ac00b84683a1e576f306dfc53f_id_rsa (zuul-build-sshkey)
2025-09-30 11:18:28.042297 | controller -> localhost | ok: Runtime: 0:00:00.077159
2025-09-30 11:18:28.048257 | 
2025-09-30 11:18:28.048329 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-09-30 11:18:28.586639 | controller | ok
2025-09-30 11:18:28.628620 | 
2025-09-30 11:18:28.628707 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-09-30 11:18:28.769610 | controller | skipping: Conditional result was False
2025-09-30 11:18:28.786311 | 
2025-09-30 11:18:28.786395 | TASK [include_role : validate-host]
2025-09-30 11:18:28.918766 | controller | ok
2025-09-30 11:18:29.037590 | 
2025-09-30 11:18:29.037705 | TASK [validate-host : Define zuul_info_dir fact]
2025-09-30 11:18:29.167852 | controller | ok
2025-09-30 11:18:29.172690 | 
2025-09-30 11:18:29.172769 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-09-30 11:18:30.436121 | controller -> localhost | ok
2025-09-30 11:18:30.443221 | 
2025-09-30 11:18:30.443412 | TASK [validate-host : Collect information about the host]
2025-09-30 11:18:31.223438 | controller | ok
2025-09-30 11:18:31.248400 | 
2025-09-30 11:18:31.248500 | TASK [validate-host : Sanitize hostname]
2025-09-30 11:18:31.613313 | controller | ok
2025-09-30 11:18:31.621804 | 
2025-09-30 11:18:31.621927 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-09-30 11:18:34.388353 | controller -> localhost | changed
2025-09-30 11:18:34.394474 | 
2025-09-30 11:18:34.394542 | TASK [validate-host : Collect information about zuul worker]
2025-09-30 11:18:35.733999 | controller | ok
2025-09-30 11:18:35.740569 | 
2025-09-30 11:18:35.740638 | TASK [validate-host : Write out all zuul information for each host]
2025-09-30 11:18:37.940369 | controller -> localhost | changed
2025-09-30 11:18:37.952778 | 
2025-09-30 11:18:37.952845 | TASK [include_role : prepare-workspace-openshift]
2025-09-30 11:18:38.010397 | controller | skipping: Conditional result was False
2025-09-30 11:18:38.016121 | 
2025-09-30 11:18:38.016203 | TASK [include_role : remove-zuul-sshkey]
2025-09-30 11:18:38.131063 | controller | skipping: Conditional result was False
2025-09-30 11:18:38.139010 | 
2025-09-30 11:18:38.139110 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-09-30 11:18:38.496251 | controller | ok: "logs"
2025-09-30 11:18:38.496487 | controller | ok: All items complete
2025-09-30 11:18:38.496524 | 
2025-09-30 11:18:38.674967 | controller | ok: "artifacts"
2025-09-30 11:18:38.862705 | controller | ok: "docs"
2025-09-30 11:18:38.876774 | 
2025-09-30 11:18:38.876897 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-09-30 11:18:39.174345 | controller | changed: "logs"
2025-09-30 11:18:39.367599 | controller | changed: "artifacts"
2025-09-30 11:18:39.586061 | controller | changed: "docs"
2025-09-30 11:18:39.686259 | 
2025-09-30 11:18:39.686379 | PLAY RECAP
2025-09-30 11:18:39.686430 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-30 11:18:39.686461 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-30 11:18:39.686481 | 
2025-09-30 11:18:40.524007 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-30 11:18:40.524797 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-30 11:18:43.527843 | 
2025-09-30 11:18:43.549851 | PLAY [all]
2025-09-30 11:18:43.669795 | 
2025-09-30 11:18:43.669983 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-30 11:18:43.843742 | controller | ok
2025-09-30 11:18:43.857084 | 
2025-09-30 11:18:43.857209 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-30 11:18:44.494784 | controller | changed
2025-09-30 11:18:44.507659 | 
2025-09-30 11:18:44.507824 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-30 11:18:46.203458 | controller | changed
2025-09-30 11:18:46.243448 | 
2025-09-30 11:18:46.243591 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-09-30 11:18:47.004481 | controller | changed:
2025-09-30 11:18:47.004674 | controller | {
2025-09-30 11:18:47.004706 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-09-30 11:18:47.004746 | controller | }
2025-09-30 11:18:47.260573 | controller | changed:
2025-09-30 11:18:47.260679 | controller | {
2025-09-30 11:18:47.260710 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-09-30 11:18:47.260736 | controller | }
2025-09-30 11:18:47.497128 | controller | changed:
2025-09-30 11:18:47.497235 | controller | {
2025-09-30 11:18:47.497263 | 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-30 11:18:47.497285 | controller | }
2025-09-30 11:18:47.726339 | controller | changed:
2025-09-30 11:18:47.726437 | controller | {
2025-09-30 11:18:47.726465 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-09-30 11:18:47.726488 | controller | }
2025-09-30 11:18:47.957628 | controller | changed:
2025-09-30 11:18:47.957724 | controller | {
2025-09-30 11:18:47.957752 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-09-30 11:18:47.957774 | controller | }
2025-09-30 11:18:48.181342 | controller | changed:
2025-09-30 11:18:48.181476 | controller | {
2025-09-30 11:18:48.181509 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-09-30 11:18:48.181533 | controller | }
2025-09-30 11:18:48.414724 | controller | changed:
2025-09-30 11:18:48.414866 | controller | {
2025-09-30 11:18:48.414928 | 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-30 11:18:48.414960 | controller | }
2025-09-30 11:18:48.657628 | controller | changed:
2025-09-30 11:18:48.657740 | controller | {
2025-09-30 11:18:48.657768 | 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-30 11:18:48.657791 | controller | }
2025-09-30 11:18:48.874240 | controller | changed:
2025-09-30 11:18:48.874335 | controller | {
2025-09-30 11:18:48.874365 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-09-30 11:18:48.874386 | controller | }
2025-09-30 11:18:49.108888 | controller | changed:
2025-09-30 11:18:49.109015 | controller | {
2025-09-30 11:18:49.109043 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-09-30 11:18:49.109066 | controller | }
2025-09-30 11:18:49.340118 | controller | changed:
2025-09-30 11:18:49.340207 | controller | {
2025-09-30 11:18:49.340236 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-09-30 11:18:49.340258 | controller | }
2025-09-30 11:18:49.558373 | controller | changed:
2025-09-30 11:18:49.558469 | controller | {
2025-09-30 11:18:49.558497 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-09-30 11:18:49.558519 | controller | }
2025-09-30 11:18:49.781792 | controller | changed:
2025-09-30 11:18:49.781886 | controller | {
2025-09-30 11:18:49.781950 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-09-30 11:18:49.781976 | controller | }
2025-09-30 11:18:50.010213 | controller | changed:
2025-09-30 11:18:50.010349 | controller | {
2025-09-30 11:18:50.010381 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-09-30 11:18:50.010404 | controller | }
2025-09-30 11:18:50.222232 | controller | changed:
2025-09-30 11:18:50.222320 | controller | {
2025-09-30 11:18:50.222348 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-09-30 11:18:50.222371 | controller | }
2025-09-30 11:18:50.434056 | controller | changed:
2025-09-30 11:18:50.434152 | controller | {
2025-09-30 11:18:50.434180 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-09-30 11:18:50.434201 | controller | }
2025-09-30 11:18:50.666275 | controller | changed:
2025-09-30 11:18:50.666368 | controller | {
2025-09-30 11:18:50.666398 | 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-30 11:18:50.666421 | controller | }
2025-09-30 11:18:50.899095 | controller | changed:
2025-09-30 11:18:50.899182 | controller | {
2025-09-30 11:18:50.899213 | 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-30 11:18:50.899243 | controller | }
2025-09-30 11:18:51.150793 | controller | changed:
2025-09-30 11:18:51.150885 | controller | {
2025-09-30 11:18:51.150962 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-09-30 11:18:51.150988 | controller | }
2025-09-30 11:18:51.385601 | controller | changed:
2025-09-30 11:18:51.385692 | controller | {
2025-09-30 11:18:51.385719 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-09-30 11:18:51.385741 | controller | }
2025-09-30 11:18:51.622508 | controller | changed:
2025-09-30 11:18:51.622602 | controller | {
2025-09-30 11:18:51.622631 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-09-30 11:18:51.622653 | controller | }
2025-09-30 11:18:51.841466 | controller | changed:
2025-09-30 11:18:51.841697 | controller | {
2025-09-30 11:18:51.841732 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-09-30 11:18:51.841756 | controller | }
2025-09-30 11:18:52.054558 | controller | changed:
2025-09-30 11:18:52.054651 | controller | {
2025-09-30 11:18:52.054678 | 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-30 11:18:52.054701 | controller | }
2025-09-30 11:18:52.285420 | controller | changed:
2025-09-30 11:18:52.285556 | controller | {
2025-09-30 11:18:52.285588 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-09-30 11:18:52.285611 | controller | }
2025-09-30 11:18:52.547578 | controller | changed:
2025-09-30 11:18:52.547677 | controller | {
2025-09-30 11:18:52.547707 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-09-30 11:18:52.547730 | controller | }
2025-09-30 11:18:52.784748 | controller | changed:
2025-09-30 11:18:52.784836 | controller | {
2025-09-30 11:18:52.785019 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-09-30 11:18:52.785082 | controller | }
2025-09-30 11:18:52.820216 | 
2025-09-30 11:18:52.820506 | TASK [Set timezone to UTC]
2025-09-30 11:18:53.532859 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-09-30 11:18:53.541489 | 
2025-09-30 11:18:53.541576 | TASK [Create nodepool directory]
2025-09-30 11:18:53.815926 | controller | changed
2025-09-30 11:18:53.833637 | 
2025-09-30 11:18:53.833728 | TASK [Create nodepool sub_nodes file]
2025-09-30 11:18:54.532622 | controller | changed
2025-09-30 11:18:54.537630 | 
2025-09-30 11:18:54.537721 | TASK [Create nodepool sub_nodes_private file]
2025-09-30 11:18:55.151134 | controller | changed
2025-09-30 11:18:55.156618 | 
2025-09-30 11:18:55.156715 | LOOP [Populate nodepool sub_nodes file]
2025-09-30 11:18:55.196722 | 
2025-09-30 11:18:55.196899 | LOOP [Populate nodepool sub_nodes_private file]
2025-09-30 11:18:55.229034 | 
2025-09-30 11:18:55.229283 | TASK [Create nodepool primary file]
2025-09-30 11:18:55.255394 | controller | skipping: Conditional result was False
2025-09-30 11:18:55.261841 | 
2025-09-30 11:18:55.261988 | TASK [Create nodepool node_private for this node]
2025-09-30 11:18:55.936981 | controller | changed
2025-09-30 11:18:55.947054 | 
2025-09-30 11:18:55.947140 | LOOP [Copy ssh keys to nodepool directory]
2025-09-30 11:18:56.360989 | controller | ok: Item: id_rsa Runtime: 0:00:00.007045
2025-09-30 11:18:56.361183 | 
2025-09-30 11:18:56.542854 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005714
2025-09-30 11:18:56.560144 | 
2025-09-30 11:18:56.560265 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-09-30 11:18:57.193673 | controller | changed
2025-09-30 11:18:57.200872 | 
2025-09-30 11:18:57.201002 | TASK [Validate sudoers config after edits]
2025-09-30 11:18:57.459993 | controller | /etc/sudoers: parsed OK
2025-09-30 11:18:57.460074 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-09-30 11:18:57.460086 | controller | /etc/sudoers.d/zuul: parsed OK
2025-09-30 11:18:57.460092 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-09-30 11:18:57.750068 | controller | ok: Runtime: 0:00:00.007228
2025-09-30 11:18:57.756314 | 
2025-09-30 11:18:57.756386 | TASK [Show the environment passed in to job shell scripts]
2025-09-30 11:18:58.032111 | controller | SHELL=/bin/bash
2025-09-30 11:18:58.032166 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-09-30 11:18:58.032176 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-09-30 11:18:58.032183 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/53/3353/70225949d764f12a7e698f7b0c4331d3defbb889^openstack-k8s-operators/ci-framework:main:refs/changes/33/3333/b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-30 11:18:58.032192 | controller | PWD=/home/zuul
2025-09-30 11:18:58.032198 | controller | ZUUL_PIPELINE=github-check
2025-09-30 11:18:58.032203 | controller | LOGNAME=zuul
2025-09-30 11:18:58.032209 | controller | XDG_SESSION_TYPE=tty
2025-09-30 11:18:58.032215 | controller | _=/usr/bin/env
2025-09-30 11:18:58.032228 | controller | MOTD_SHOWN=pam
2025-09-30 11:18:58.032241 | controller | HOME=/home/zuul
2025-09-30 11:18:58.032246 | controller | LANG=en_US.UTF-8
2025-09-30 11:18:58.032309 | controller | SSH_CONNECTION=192.168.26.12 59452 192.168.26.157 22
2025-09-30 11:18:58.032318 | 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-30 11:18:58.032324 | controller | ZUUL_CHANGE_IDS=3353,70225949d764f12a7e698f7b0c4331d3defbb889 3333,b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-30 11:18:58.032330 | controller | WORKSPACE=/home/zuul/workspace
2025-09-30 11:18:58.032336 | controller | XDG_SESSION_CLASS=user
2025-09-30 11:18:58.032342 | controller | SELINUX_ROLE_REQUESTED=
2025-09-30 11:18:58.032347 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-09-30 11:18:58.032352 | controller | USER=zuul
2025-09-30 11:18:58.032358 | controller | ZUUL_VOTING=True
2025-09-30 11:18:58.032363 | controller | BUILD_TIMEOUT=1800000
2025-09-30 11:18:58.032368 | controller | SELINUX_USE_CURRENT_RANGE=
2025-09-30 11:18:58.032373 | controller | SHLVL=1
2025-09-30 11:18:58.032379 | controller | ZUUL_PATCHSET=b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-30 11:18:58.032384 | controller | XDG_SESSION_ID=1
2025-09-30 11:18:58.032389 | controller | ZUUL_BRANCH=main
2025-09-30 11:18:58.032394 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-09-30 11:18:58.032400 | controller | SSH_CLIENT=192.168.26.12 59452 22
2025-09-30 11:18:58.032405 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-09-30 11:18:58.032411 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-09-30 11:18:58.032416 | controller | which_declare=declare -f
2025-09-30 11:18:58.032422 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-09-30 11:18:58.032429 | controller | SELINUX_LEVEL_REQUESTED=
2025-09-30 11:18:58.032434 | controller | ZUUL_CHANGE=3333
2025-09-30 11:18:58.032440 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-09-30 11:18:58.032445 | controller | ZUUL_UUID=3e5695ac00b84683a1e576f306dfc53f
2025-09-30 11:18:58.032453 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-09-30 11:18:58.032459 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-09-30 11:18:58.032464 | controller | }
2025-09-30 11:18:58.306943 | controller | ok: Runtime: 0:00:00.006906
2025-09-30 11:18:58.313703 | 
2025-09-30 11:18:58.313796 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-09-30 11:18:58.327606 | controller | skipping: Conditional result was False
2025-09-30 11:18:58.334542 | 
2025-09-30 11:18:58.334643 | TASK [Symlink /home/zuul-worker/workspace]
2025-09-30 11:18:58.869365 | controller | skipping: Conditional result was False
2025-09-30 11:18:58.875476 | 
2025-09-30 11:18:58.875640 | TASK [Ensure legacy workspace directory]
2025-09-30 11:18:59.113266 | controller | changed
2025-09-30 11:18:59.217044 | 
2025-09-30 11:18:59.217137 | PLAY RECAP
2025-09-30 11:18:59.217183 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-30 11:18:59.217208 | 
2025-09-30 11:18:59.414899 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-30 11:18:59.415717 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-30 11:19:00.539264 | 
2025-09-30 11:19:00.539388 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-09-30 11:19:00.584619 | 
2025-09-30 11:19:00.584740 | TASK [Create zuul-output directory]
2025-09-30 11:19:01.323017 | controller | changed
2025-09-30 11:19:01.328873 | 
2025-09-30 11:19:01.329018 | TASK [Slurp Zuul inventory test]
2025-09-30 11:19:02.121653 | controller -> localhost | ok
2025-09-30 11:19:02.128704 | 
2025-09-30 11:19:02.128794 | TASK [Save zuul inventory]
2025-09-30 11:19:03.267258 | controller | changed
2025-09-30 11:19:03.284148 | 
2025-09-30 11:19:03.284262 | TASK [Save zuul vars without the change_message]
2025-09-30 11:19:04.037302 | controller | changed
2025-09-30 11:19:04.060240 | 
2025-09-30 11:19:04.060309 | PLAY RECAP
2025-09-30 11:19:04.060355 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-30 11:19:04.060378 | 
2025-09-30 11:19:04.164467 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-30 11:19:04.165259 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-30 11:19:05.019495 | 
2025-09-30 11:19:05.019605 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-09-30 11:19:05.075978 | 
2025-09-30 11:19:05.076117 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-09-30 11:19:05.116054 | controller | ok
2025-09-30 11:19:05.135568 | 
2025-09-30 11:19:05.135723 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-30 11:19:05.166229 | controller | skipping: Conditional result was False
2025-09-30 11:19:05.173201 | 
2025-09-30 11:19:05.173311 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-30 11:19:05.618149 | controller | ok
2025-09-30 11:19:05.624256 | 
2025-09-30 11:19:05.624385 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-30 11:19:06.333355 | controller | ok
2025-09-30 11:19:06.344319 | 
2025-09-30 11:19:06.344416 | TASK [Prepare workspace]
2025-09-30 11:19:06.364301 | controller | ok
2025-09-30 11:19:06.381749 | 
2025-09-30 11:19:06.381816 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-09-30 11:19:06.708442 | controller | ok
2025-09-30 11:19:06.715651 | 
2025-09-30 11:19:06.715718 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-09-30 11:19:09.904864 | controller | Output suppressed because no_log was given
2025-09-30 11:19:09.919113 | 
2025-09-30 11:19:09.919241 | LOOP [Create zuul-output directory]
2025-09-30 11:19:10.168414 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-09-30 11:19:10.347007 | controller | ok: "/home/zuul/zuul-output/logs"
2025-09-30 11:19:10.362119 | 
2025-09-30 11:19:10.362219 | TASK [Install required packages]
2025-09-30 11:20:01.850010 | controller | changed
2025-09-30 11:20:01.855599 | 
2025-09-30 11:20:01.855664 | TASK [Install venv]
2025-09-30 11:21:04.544342 | controller | changed
2025-09-30 11:21:04.594066 | 
2025-09-30 11:21:04.594168 | PLAY RECAP
2025-09-30 11:21:04.594212 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-30 11:21:04.594236 | 
2025-09-30 11:21:04.668592 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-30 11:21:04.669647 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-30 11:21:05.260782 | 
2025-09-30 11:21:05.260935 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-09-30 11:21:05.282373 | 
2025-09-30 11:21:05.282470 | TASK [Gather required facts]
2025-09-30 11:21:05.852940 | controller | ok
2025-09-30 11:21:05.858284 | 
2025-09-30 11:21:05.858368 | TASK [Load environment var if instructed to]
2025-09-30 11:21:05.882209 | controller | skipping: Conditional result was False
2025-09-30 11:21:05.888110 | 
2025-09-30 11:21:05.888203 | TASK [Run molecule]
2025-09-30 11:21:06.882998 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-09-30 11:21:06.952379 | controller | INFO     Performing prerun with role_name_check=0...
2025-09-30 11:21:24.743955 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/addcb0/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 11:21:24.744437 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/addcb0/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 11:21:24.744882 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/addcb0/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 11:21:24.745353 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/addcb0/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 11:21:24.745833 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+f3b7a729 was found installed in /home/zuul/.cache/ansible-compat/addcb0/collections/ansible_collections, only the first one will be used, 1.0.0+f3b7a729 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 11:21:24.746305 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/addcb0/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 11:21:24.746739 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/addcb0/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 11:21:24.747203 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/addcb0/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 11:21:24.747641 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/addcb0/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 11:21:24.748122 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/addcb0/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 11:21:24.748565 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/addcb0/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 11:21:24.749027 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/addcb0/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 11:21:24.749480 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/addcb0/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 11:21:24.749904 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/addcb0/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 11:21:24.750380 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/addcb0/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 11:21:24.759950 | controller | INFO     Running flexible_loop > prepare
2025-09-30 11:21:25.416800 | controller |
2025-09-30 11:21:25.416891 | controller | PLAY [Prepare] *****************************************************************
2025-09-30 11:21:25.417008 | controller |
2025-09-30 11:21:25.417128 | controller | TASK [Gathering Facts] *********************************************************
2025-09-30 11:21:25.417223 | controller | Tuesday 30 September 2025  11:21:25 +0000 (0:00:00.013)       0:00:00.013 *****
2025-09-30 11:21:26.267734 | controller | ok: [instance]
2025-09-30 11:21:26.267823 | controller |
2025-09-30 11:21:26.268005 | controller | TASK [Include ci_setup role] ***************************************************
2025-09-30 11:21:26.268141 | controller | Tuesday 30 September 2025  11:21:26 +0000 (0:00:00.851)       0:00:00.865 *****
2025-09-30 11:21:26.323899 | controller |
2025-09-30 11:21:26.324061 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-09-30 11:21:26.324184 | controller | Tuesday 30 September 2025  11:21:26 +0000 (0:00:00.056)       0:00:00.921 *****
2025-09-30 11:21:26.368949 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-09-30 11:21:26.369030 | controller |
2025-09-30 11:21:26.369151 | controller | TASK [ci_setup : List packages to install] *************************************
2025-09-30 11:21:26.369258 | controller | Tuesday 30 September 2025  11:21:26 +0000 (0:00:00.045)       0:00:00.966 *****
2025-09-30 11:21:26.389778 | controller | ok: [instance] =>
2025-09-30 11:21:26.389893 | controller |   cifmw_ci_setup_packages:
2025-09-30 11:21:26.390061 | controller |   - bash-completion
2025-09-30 11:21:26.390188 | controller |   - ca-certificates
2025-09-30 11:21:26.390305 | controller |   - git-core
2025-09-30 11:21:26.390402 | controller |   - make
2025-09-30 11:21:26.390500 | controller |   - tar
2025-09-30 11:21:26.390599 | controller |   - tmux
2025-09-30 11:21:26.390698 | controller |   - python3-pip
2025-09-30 11:21:26.390794 | controller |
2025-09-30 11:21:26.390893 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-09-30 11:21:26.391019 | controller | Tuesday 30 September 2025  11:21:26 +0000 (0:00:00.020)       0:00:00.987 *****
2025-09-30 11:21:29.855774 | controller | changed: [instance]
2025-09-30 11:21:29.855862 | controller |
2025-09-30 11:21:29.855998 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-09-30 11:21:29.856120 | controller | Tuesday 30 September 2025  11:21:29 +0000 (0:00:03.465)       0:00:04.453 *****
2025-09-30 11:21:30.133852 | controller | ok: [instance]
2025-09-30 11:21:30.133942 | controller |
2025-09-30 11:21:30.134081 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-09-30 11:21:30.134196 | controller | Tuesday 30 September 2025  11:21:30 +0000 (0:00:00.278)       0:00:04.731 *****
2025-09-30 11:21:30.431545 | controller | changed: [instance]
2025-09-30 11:21:30.431594 | controller |
2025-09-30 11:21:30.431702 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-09-30 11:21:30.431802 | controller | Tuesday 30 September 2025  11:21:30 +0000 (0:00:00.297)       0:00:05.029 *****
2025-09-30 11:21:36.322031 | controller | changed: [instance]
2025-09-30 11:21:36.322335 | controller |
2025-09-30 11:21:36.356751 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-09-30 11:21:36.356773 | controller | Tuesday 30 September 2025  11:21:36 +0000 (0:00:05.890)       0:00:10.919 *****
2025-09-30 11:21:36.356784 | controller | skipping: [instance]
2025-09-30 11:21:36.609266 | controller |
2025-09-30 11:21:36.609302 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-09-30 11:21:36.609310 | controller | Tuesday 30 September 2025  11:21:36 +0000 (0:00:00.034)       0:00:10.954 *****
2025-09-30 11:21:36.609320 | controller | changed: [instance]
2025-09-30 11:21:36.880268 | controller |
2025-09-30 11:21:36.880296 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-09-30 11:21:36.880307 | controller | Tuesday 30 September 2025  11:21:36 +0000 (0:00:00.252)       0:00:11.206 *****
2025-09-30 11:21:36.880317 | controller | changed: [instance]
2025-09-30 11:21:36.898066 | controller |
2025-09-30 11:21:36.898092 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-09-30 11:21:36.898100 | controller | Tuesday 30 September 2025  11:21:36 +0000 (0:00:00.271)       0:00:11.477 *****
2025-09-30 11:21:36.898110 | controller | skipping: [instance]
2025-09-30 11:21:36.915158 | controller |
2025-09-30 11:21:36.915183 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-09-30 11:21:36.915194 | controller | Tuesday 30 September 2025  11:21:36 +0000 (0:00:00.018)       0:00:11.495 *****
2025-09-30 11:21:36.915205 | controller | skipping: [instance]
2025-09-30 11:21:36.932032 | controller |
2025-09-30 11:21:36.932081 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-09-30 11:21:36.932091 | controller | Tuesday 30 September 2025  11:21:36 +0000 (0:00:00.016)       0:00:11.512 *****
2025-09-30 11:21:36.932103 | controller | skipping: [instance]
2025-09-30 11:21:36.932133 | controller |
2025-09-30 11:21:36.932149 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-09-30 11:21:36.932269 | controller | Tuesday 30 September 2025  11:21:36 +0000 (0:00:00.017)       0:00:11.529 *****
2025-09-30 11:21:36.948875 | controller | skipping: [instance]
2025-09-30 11:21:36.964989 | controller |
2025-09-30 11:21:36.965009 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-09-30 11:21:36.965017 | controller | Tuesday 30 September 2025  11:21:36 +0000 (0:00:00.016)       0:00:11.546 *****
2025-09-30 11:21:36.965026 | controller | skipping: [instance]
2025-09-30 11:21:36.965067 | controller |
2025-09-30 11:21:36.965076 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-09-30 11:21:36.965215 | controller | Tuesday 30 September 2025  11:21:36 +0000 (0:00:00.016)       0:00:11.562 *****
2025-09-30 11:21:36.986137 | controller | skipping: [instance]
2025-09-30 11:21:36.986301 | controller |
2025-09-30 11:21:36.986449 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-09-30 11:21:36.986595 | controller | Tuesday 30 September 2025  11:21:36 +0000 (0:00:00.021)       0:00:11.583 *****
2025-09-30 11:21:37.947025 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-09-30 11:21:37.947072 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-09-30 11:21:37.947082 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-09-30 11:21:37.947089 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-09-30 11:21:37.947097 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-09-30 11:21:37.947103 | controller |
2025-09-30 11:21:37.947110 | controller | TASK [Create nova migration keypair] *******************************************
2025-09-30 11:21:37.947119 | controller | Tuesday 30 September 2025  11:21:37 +0000 (0:00:00.959)       0:00:12.543 *****
2025-09-30 11:21:38.310879 | controller | changed: [instance]
2025-09-30 11:21:38.560767 | controller |
2025-09-30 11:21:38.560796 | controller | TASK [Create user keypair] *****************************************************
2025-09-30 11:21:38.560804 | controller | Tuesday 30 September 2025  11:21:38 +0000 (0:00:00.364)       0:00:12.908 *****
2025-09-30 11:21:38.560815 | controller | changed: [instance]
2025-09-30 11:21:38.983034 | controller |
2025-09-30 11:21:38.983080 | controller | TASK [Create fake kubeconfig] **************************************************
2025-09-30 11:21:38.983092 | controller | Tuesday 30 September 2025  11:21:38 +0000 (0:00:00.249)       0:00:13.158 *****
2025-09-30 11:21:38.983102 | controller | changed: [instance]
2025-09-30 11:21:38.983123 | controller |
2025-09-30 11:21:38.983130 | controller | PLAY RECAP *********************************************************************
2025-09-30 11:21:38.983136 | controller | instance                   : ok=13   changed=9    unreachable=0    failed=0    skipped=7    rescued=0    ignored=0
2025-09-30 11:21:38.983141 | controller |
2025-09-30 11:21:38.983148 | controller | Tuesday 30 September 2025  11:21:38 +0000 (0:00:00.422)       0:00:13.580 *****
2025-09-30 11:21:38.983996 | controller | ===============================================================================
2025-09-30 11:21:38.984025 | controller | ci_setup : Install openshift client ------------------------------------- 5.89s
2025-09-30 11:21:38.984074 | controller | ci_setup : Install needed packages -------------------------------------- 3.47s
2025-09-30 11:21:38.984083 | controller | ci_setup : Manage directories ------------------------------------------- 0.96s
2025-09-30 11:21:38.984090 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-09-30 11:21:38.984097 | controller | Create fake kubeconfig -------------------------------------------------- 0.42s
2025-09-30 11:21:38.984104 | controller | Create nova migration keypair ------------------------------------------- 0.36s
2025-09-30 11:21:38.984110 | controller | ci_setup : Ensure openshift client install path is present -------------- 0.30s
2025-09-30 11:21:38.984115 | controller | ci_setup : Gather version of openshift client --------------------------- 0.28s
2025-09-30 11:21:38.984121 | controller | ci_setup : Source completion from within .bashrc ------------------------ 0.27s
2025-09-30 11:21:38.984128 | controller | ci_setup : Create completion file --------------------------------------- 0.25s
2025-09-30 11:21:38.984978 | controller | Create user keypair ----------------------------------------------------- 0.25s
2025-09-30 11:21:39.037531 | controller | Include ci_setup role --------------------------------------------------- 0.06s
2025-09-30 11:21:39.037559 | controller | ci_setup : Gather variables for each operating system ------------------- 0.05s
2025-09-30 11:21:39.037567 | controller | ci_setup : Add the OC path to cifmw_path if needed ---------------------- 0.03s
2025-09-30 11:21:39.037572 | controller | ci_setup : Ensure the repos are enabled in the system using yum --------- 0.02s
2025-09-30 11:21:39.037578 | controller | ci_setup : List packages to install ------------------------------------- 0.02s
2025-09-30 11:21:39.037583 | controller | ci_setup : Check rhsm status -------------------------------------------- 0.02s
2025-09-30 11:21:39.037588 | controller | ci_setup : Enabling the required repositories. -------------------------- 0.02s
2025-09-30 11:21:39.037594 | controller | ci_setup : Gather the repos to be enabled ------------------------------- 0.02s
2025-09-30 11:21:39.037599 | controller | ci_setup : Print current /etc/redhat-release ---------------------------- 0.02s
2025-09-30 11:21:39.037609 | controller | INFO     Running flexible_loop > converge
2025-09-30 11:21:39.476330 | controller |
2025-09-30 11:21:39.476431 | controller | PLAY [Converge] ****************************************************************
2025-09-30 11:21:39.476584 | controller |
2025-09-30 11:21:39.476681 | controller | TASK [Gathering Facts] *********************************************************
2025-09-30 11:21:39.476796 | controller | Tuesday 30 September 2025  11:21:39 +0000 (0:00:00.017)       0:00:00.017 *****
2025-09-30 11:21:40.258694 | controller | ok: [instance]
2025-09-30 11:21:40.302591 | controller |
2025-09-30 11:21:40.302623 | controller | TASK [Load networking environment definition] **********************************
2025-09-30 11:21:40.302631 | controller | Tuesday 30 September 2025  11:21:40 +0000 (0:00:00.782)       0:00:00.799 *****
2025-09-30 11:21:40.302641 | controller | ok: [instance]
2025-09-30 11:21:40.621405 | controller |
2025-09-30 11:21:40.621434 | controller | TASK [kustomize_deploy : Ensure that architecture repo exists] *****************
2025-09-30 11:21:40.621449 | controller | Tuesday 30 September 2025  11:21:40 +0000 (0:00:00.043)       0:00:00.843 *****
2025-09-30 11:21:40.621459 | controller | ok: [instance]
2025-09-30 11:21:40.962625 | controller |
2025-09-30 11:21:40.962660 | controller | TASK [kustomize_deploy : Gather the list of scenario folders] ******************
2025-09-30 11:21:40.962669 | controller | Tuesday 30 September 2025  11:21:40 +0000 (0:00:00.318)       0:00:01.162 *****
2025-09-30 11:21:40.962681 | controller | ok: [instance]
2025-09-30 11:21:40.985792 | controller |
2025-09-30 11:21:40.985821 | controller | TASK [kustomize_deploy : Check if scenario is in the list] *********************
2025-09-30 11:21:40.985830 | controller | Tuesday 30 September 2025  11:21:40 +0000 (0:00:00.341)       0:00:01.503 *****
2025-09-30 11:21:40.985841 | controller | skipping: [instance]
2025-09-30 11:21:41.296792 | controller |
2025-09-30 11:21:41.296823 | controller | TASK [kustomize_deploy : Ensure that destination directory exists] *************
2025-09-30 11:21:41.296831 | controller | Tuesday 30 September 2025  11:21:40 +0000 (0:00:00.023)       0:00:01.526 *****
2025-09-30 11:21:41.296841 | controller | changed: [instance]
2025-09-30 11:21:41.362700 | controller |
2025-09-30 11:21:41.362731 | controller | TASK [kustomize_deploy : Set the final cifmw_architecture_user_kustomize based on its patches] ***
2025-09-30 11:21:41.362745 | controller | Tuesday 30 September 2025  11:21:41 +0000 (0:00:00.310)       0:00:01.837 *****
2025-09-30 11:21:41.362756 | controller | ok: [instance] => (item=cifmw_architecture_user_kustomize)
2025-09-30 11:21:41.432621 | controller |
2025-09-30 11:21:41.432655 | controller | TASK [kustomize_deploy : Set the final cifmw_architecture_user_kustomize_base64 based on its patches] ***
2025-09-30 11:21:41.432665 | controller | Tuesday 30 September 2025  11:21:41 +0000 (0:00:00.065)       0:00:01.903 *****
2025-09-30 11:21:41.432676 | controller | ok: [instance]
2025-09-30 11:21:41.477313 | controller |
2025-09-30 11:21:41.477343 | controller | TASK [Load architecture automation] ********************************************
2025-09-30 11:21:41.477351 | controller | Tuesday 30 September 2025  11:21:41 +0000 (0:00:00.069)       0:00:01.973 *****
2025-09-30 11:21:41.477361 | controller | ok: [instance]
2025-09-30 11:21:41.692813 | controller |
2025-09-30 11:21:41.692843 | controller | TASK [Loop the deploy] *********************************************************
2025-09-30 11:21:41.692852 | controller | Tuesday 30 September 2025  11:21:41 +0000 (0:00:00.044)       0:00:02.018 *****
2025-09-30 11:21:41.692862 | controller |
2025-09-30 11:21:41.737873 | controller | TASK [kustomize_deploy : Assert mandatory bits are defined] ********************
2025-09-30 11:21:41.737899 | controller | Tuesday 30 September 2025  11:21:41 +0000 (0:00:00.215)       0:00:02.233 *****
2025-09-30 11:21:41.737910 | controller | ok: [instance] => changed=false
2025-09-30 11:21:41.769476 | controller |   msg: All assertions passed
2025-09-30 11:21:41.769500 | controller |
2025-09-30 11:21:41.769507 | controller | TASK [kustomize_deploy : Check custom wait conditions] *************************
2025-09-30 11:21:41.769513 | controller | Tuesday 30 September 2025  11:21:41 +0000 (0:00:00.045)       0:00:02.278 *****
2025-09-30 11:21:41.769523 | controller | skipping: [instance]
2025-09-30 11:21:41.795563 | controller |
2025-09-30 11:21:41.795587 | controller | TASK [kustomize_deploy : Check builtin wait conditions] ************************
2025-09-30 11:21:41.795595 | controller | Tuesday 30 September 2025  11:21:41 +0000 (0:00:00.031)       0:00:02.310 *****
2025-09-30 11:21:41.795605 | controller | ok: [instance] => (item=oc -n openstack wait nncp -l osp/nncm-config-type=standard --for jsonpath='{.status.conditions[0].reason}'=SuccessfullyConfigured --timeout=5m)
2025-09-30 11:21:41.818623 | controller |
2025-09-30 11:21:41.818651 | controller | TASK [kustomize_deploy : Check wait_conditions validation result] **************
2025-09-30 11:21:41.818659 | controller | Tuesday 30 September 2025  11:21:41 +0000 (0:00:00.026)       0:00:02.336 *****
2025-09-30 11:21:41.818668 | controller | skipping: [instance]
2025-09-30 11:21:41.856320 | controller |
2025-09-30 11:21:41.856345 | controller | TASK [kustomize_deploy : Set stage_wait_conditions fact] ***********************
2025-09-30 11:21:41.856352 | controller | Tuesday 30 September 2025  11:21:41 +0000 (0:00:00.022)       0:00:02.359 *****
2025-09-30 11:21:41.856362 | controller | ok: [instance]
2025-09-30 11:21:42.157239 | controller |
2025-09-30 11:21:42.157267 | controller | TASK [kustomize_deploy : Ensure source files exists] ***************************
2025-09-30 11:21:42.157275 | controller | Tuesday 30 September 2025  11:21:41 +0000 (0:00:00.037)       0:00:02.397 *****
2025-09-30 11:21:42.157285 | controller | ok: [instance] => (item={'name': 'network-values', 'src_file': 'values.yaml'})
2025-09-30 11:21:42.221086 | controller |
2025-09-30 11:21:42.221123 | controller | TASK [kustomize_deploy : Assert source files exist] ****************************
2025-09-30 11:21:42.221133 | controller | Tuesday 30 September 2025  11:21:42 +0000 (0:00:00.300)       0:00:02.698 *****
2025-09-30 11:21:42.221153 | controller | ok: [instance] => (item=values.yaml)
2025-09-30 11:21:42.221180 | controller |
2025-09-30 11:21:42.221188 | controller | TASK [Executing pre_stage hooks for examples/va/hci/control-plane/networking/nncp] ***
2025-09-30 11:21:42.221197 | controller | Tuesday 30 September 2025  11:21:42 +0000 (0:00:00.064)       0:00:02.762 *****
2025-09-30 11:21:42.266772 | controller | skipping: [instance]
2025-09-30 11:21:42.266862 | controller |
2025-09-30 11:21:42.267169 | controller | TASK [Generate values.yaml for examples/va/hci/control-plane/networking/nncp] ***
2025-09-30 11:21:42.349060 | controller | Tuesday 30 September 2025  11:21:42 +0000 (0:00:00.045)       0:00:02.808 *****
2025-09-30 11:21:42.349104 | controller |
2025-09-30 11:21:42.413133 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-09-30 11:21:42.413170 | controller | Tuesday 30 September 2025  11:21:42 +0000 (0:00:00.081)       0:00:02.890 *****
2025-09-30 11:21:42.413185 | 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-30 11:21:42.413214 | controller |
2025-09-30 11:21:42.413464 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-09-30 11:21:42.465511 | controller | Tuesday 30 September 2025  11:21:42 +0000 (0:00:00.064)       0:00:02.954 *****
2025-09-30 11:21:42.465550 | controller | ok: [instance] => changed=false
2025-09-30 11:21:42.675100 | controller |   msg: All assertions passed
2025-09-30 11:21:42.675129 | controller |
2025-09-30 11:21:42.675137 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-09-30 11:21:42.675143 | controller | Tuesday 30 September 2025  11:21:42 +0000 (0:00:00.051)       0:00:03.006 *****
2025-09-30 11:21:42.675153 | controller | ok: [instance]
2025-09-30 11:21:42.726993 | controller |
2025-09-30 11:21:42.727024 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-09-30 11:21:42.727032 | controller | Tuesday 30 September 2025  11:21:42 +0000 (0:00:00.209)       0:00:03.216 *****
2025-09-30 11:21:42.727042 | controller | ok: [instance] => changed=false
2025-09-30 11:21:42.727373 | controller |   msg: All assertions passed
2025-09-30 11:21:42.727402 | controller |
2025-09-30 11:21:43.035040 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-09-30 11:21:43.035078 | controller | Tuesday 30 September 2025  11:21:42 +0000 (0:00:00.052)       0:00:03.268 *****
2025-09-30 11:21:43.035090 | controller | ok: [instance]
2025-09-30 11:21:43.324843 | controller |
2025-09-30 11:21:43.324875 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-09-30 11:21:43.324883 | controller | Tuesday 30 September 2025  11:21:43 +0000 (0:00:00.307)       0:00:03.576 *****
2025-09-30 11:21:43.324893 | controller | ok: [instance]
2025-09-30 11:21:43.386781 | controller |
2025-09-30 11:21:43.386809 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-09-30 11:21:43.386817 | controller | Tuesday 30 September 2025  11:21:43 +0000 (0:00:00.289)       0:00:03.865 *****
2025-09-30 11:21:43.386827 | 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-30 11:21:43.436022 | controller |
2025-09-30 11:21:43.436066 | controller | TASK [ci_gen_kustomize_values : Assert we have needed parameters] **************
2025-09-30 11:21:43.436074 | controller | Tuesday 30 September 2025  11:21:43 +0000 (0:00:00.061)       0:00:03.927 *****
2025-09-30 11:21:43.436085 | controller | ok: [instance] => changed=false
2025-09-30 11:21:43.436109 | controller |   msg: All assertions passed
2025-09-30 11:21:43.436116 | controller |
2025-09-30 11:21:43.436123 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-09-30 11:21:43.436239 | controller | Tuesday 30 September 2025  11:21:43 +0000 (0:00:00.049)       0:00:03.977 *****
2025-09-30 11:21:43.650016 | controller | changed: [instance]
2025-09-30 11:21:44.201938 | controller |
2025-09-30 11:21:44.201968 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-09-30 11:21:44.201976 | controller | Tuesday 30 September 2025  11:21:43 +0000 (0:00:00.213)       0:00:04.190 *****
2025-09-30 11:21:44.201986 | controller | changed: [instance]
2025-09-30 11:21:44.265661 | controller |
2025-09-30 11:21:44.265702 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-09-30 11:21:44.265709 | controller | Tuesday 30 September 2025  11:21:44 +0000 (0:00:00.551)       0:00:04.742 *****
2025-09-30 11:21:44.265718 | controller | ok: [instance]
2025-09-30 11:21:44.639320 | controller |
2025-09-30 11:21:44.639350 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-09-30 11:21:44.639358 | controller | Tuesday 30 September 2025  11:21:44 +0000 (0:00:00.063)       0:00:04.806 *****
2025-09-30 11:21:44.639368 | controller | changed: [instance]
2025-09-30 11:21:45.014861 | controller |
2025-09-30 11:21:45.014891 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-09-30 11:21:45.014905 | controller | Tuesday 30 September 2025  11:21:44 +0000 (0:00:00.373)       0:00:05.180 *****
2025-09-30 11:21:45.014916 | controller | changed: [instance]
2025-09-30 11:21:45.368004 | controller |
2025-09-30 11:21:45.368035 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-09-30 11:21:45.368043 | controller | Tuesday 30 September 2025  11:21:45 +0000 (0:00:00.375)       0:00:05.555 *****
2025-09-30 11:21:45.368068 | controller | changed: [instance]
2025-09-30 11:21:45.368092 | controller |
2025-09-30 11:21:45.368291 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-09-30 11:21:45.431035 | controller | Tuesday 30 September 2025  11:21:45 +0000 (0:00:00.353)       0:00:05.909 *****
2025-09-30 11:21:45.431094 | 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-30 11:21:45.431119 | controller |
2025-09-30 11:21:45.431129 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-09-30 11:21:45.431209 | controller | Tuesday 30 September 2025  11:21:45 +0000 (0:00:00.062)       0:00:05.972 *****
2025-09-30 11:21:45.480027 | controller | ok: [instance] => changed=false
2025-09-30 11:21:45.480310 | controller |   msg: All assertions passed
2025-09-30 11:21:45.480329 | controller |
2025-09-30 11:21:45.480341 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-09-30 11:21:45.691443 | controller | Tuesday 30 September 2025  11:21:45 +0000 (0:00:00.048)       0:00:06.021 *****
2025-09-30 11:21:45.691477 | controller | ok: [instance]
2025-09-30 11:21:45.738003 | controller |
2025-09-30 11:21:45.738034 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-09-30 11:21:45.738042 | controller | Tuesday 30 September 2025  11:21:45 +0000 (0:00:00.211)       0:00:06.232 *****
2025-09-30 11:21:45.738061 | controller | ok: [instance]
2025-09-30 11:21:45.738409 | controller |
2025-09-30 11:21:46.335496 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-09-30 11:21:46.335529 | controller | Tuesday 30 September 2025  11:21:45 +0000 (0:00:00.046)       0:00:06.279 *****
2025-09-30 11:21:46.335541 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/02_ci_data.yaml)
2025-09-30 11:21:46.471750 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/03_user_data_b64.yaml)
2025-09-30 11:21:46.471783 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/04_user_data.yaml)
2025-09-30 11:21:46.471791 | controller |
2025-09-30 11:21:46.471797 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-09-30 11:21:46.471803 | controller | Tuesday 30 September 2025  11:21:46 +0000 (0:00:00.596)       0:00:06.876 *****
2025-09-30 11:21:46.471813 | controller | ok: [instance] => (item=02_ci_data.yaml)
2025-09-30 11:21:46.686169 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-09-30 11:21:46.686199 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-09-30 11:21:46.686207 | controller |
2025-09-30 11:21:46.686214 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-09-30 11:21:46.686219 | controller | Tuesday 30 September 2025  11:21:46 +0000 (0:00:00.136)       0:00:07.012 *****
2025-09-30 11:21:46.686229 | controller | changed: [instance]
2025-09-30 11:21:47.059196 | controller |
2025-09-30 11:21:47.059227 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-09-30 11:21:47.059235 | controller | Tuesday 30 September 2025  11:21:46 +0000 (0:00:00.214)       0:00:07.227 *****
2025-09-30 11:21:47.059245 | controller | changed: [instance]
2025-09-30 11:21:47.320039 | controller |
2025-09-30 11:21:47.320102 | controller | TASK [kustomize_deploy : Copy generated values for examples/va/hci/control-plane/networking/nncp] ***
2025-09-30 11:21:47.320112 | controller | Tuesday 30 September 2025  11:21:47 +0000 (0:00:00.372)       0:00:07.600 *****
2025-09-30 11:21:47.320125 | controller | changed: [instance] => (item=network-values)
2025-09-30 11:21:47.320149 | controller |
2025-09-30 11:21:47.320383 | controller | TASK [kustomize_deploy : Stop before building kustomization if requested] ******
2025-09-30 11:21:47.369386 | controller | Tuesday 30 September 2025  11:21:47 +0000 (0:00:00.261)       0:00:07.861 *****
2025-09-30 11:21:47.369424 | controller | skipping: [instance]
2025-09-30 11:21:47.855816 | controller |
2025-09-30 11:21:47.855846 | controller | TASK [kustomize_deploy : Build kustomized content for examples/va/hci/control-plane/networking/nncp] ***
2025-09-30 11:21:47.855855 | controller | Tuesday 30 September 2025  11:21:47 +0000 (0:00:00.048)       0:00:07.910 *****
2025-09-30 11:21:47.855865 | controller | changed: [instance]
2025-09-30 11:21:48.218027 | controller |
2025-09-30 11:21:48.218074 | controller | TASK [kustomize_deploy : Output kustomize build in final file] *****************
2025-09-30 11:21:48.218085 | controller | Tuesday 30 September 2025  11:21:47 +0000 (0:00:00.486)       0:00:08.396 *****
2025-09-30 11:21:48.218097 | controller | changed: [instance]
2025-09-30 11:21:48.218125 | controller |
2025-09-30 11:21:48.218134 | controller | TASK [kustomize_deploy : Store kustomized content in artifacts for examples/va/hci/control-plane/networking/nncp] ***
2025-09-30 11:21:48.218159 | controller | Tuesday 30 September 2025  11:21:48 +0000 (0:00:00.362)       0:00:08.759 *****
2025-09-30 11:21:48.448626 | controller | changed: [instance]
2025-09-30 11:21:48.495763 | controller |
2025-09-30 11:21:48.495789 | controller | TASK [kustomize_deploy : Stop after building kustomization if requested] *******
2025-09-30 11:21:48.495797 | controller | Tuesday 30 September 2025  11:21:48 +0000 (0:00:00.230)       0:00:08.989 *****
2025-09-30 11:21:48.495807 | controller | skipping: [instance]
2025-09-30 11:21:48.542688 | controller |
2025-09-30 11:21:48.542718 | controller | TASK [kustomize_deploy : Apply generated content for examples/va/hci/control-plane/networking/nncp] ***
2025-09-30 11:21:48.542727 | controller | Tuesday 30 September 2025  11:21:48 +0000 (0:00:00.047)       0:00:09.036 *****
2025-09-30 11:21:48.542739 | controller | skipping: [instance]
2025-09-30 11:21:48.590105 | controller |
2025-09-30 11:21:48.590141 | controller | TASK [kustomize_deploy : Run Wait Conditions for examples/va/hci/control-plane/networking/nncp] ***
2025-09-30 11:21:48.590151 | controller | Tuesday 30 September 2025  11:21:48 +0000 (0:00:00.046)       0:00:09.083 *****
2025-09-30 11:21:48.590163 | controller | skipping: [instance] => (item=oc -n openstack wait nncp -l osp/nncm-config-type=standard --for jsonpath='{.status.conditions[0].reason}'=SuccessfullyConfigured --timeout=5m)
2025-09-30 11:21:48.590190 | controller | skipping: [instance]
2025-09-30 11:21:48.590197 | controller |
2025-09-30 11:21:48.590206 | controller | TASK [kustomize_deploy : Stop after applying CRs if requested] *****************
2025-09-30 11:21:48.590289 | controller | Tuesday 30 September 2025  11:21:48 +0000 (0:00:00.047)       0:00:09.131 *****
2025-09-30 11:21:48.636626 | controller | skipping: [instance]
2025-09-30 11:21:48.636792 | controller |
2025-09-30 11:21:48.636963 | controller | TASK [Executing post_stage hooks for examples/va/hci/control-plane/networking/nncp] ***
2025-09-30 11:21:48.637141 | controller | Tuesday 30 September 2025  11:21:48 +0000 (0:00:00.046)       0:00:09.177 *****
2025-09-30 11:21:48.690167 | controller | skipping: [instance]
2025-09-30 11:21:48.690335 | controller |
2025-09-30 11:21:48.690686 | controller | TASK [kustomize_deploy : Assert mandatory bits are defined] ********************
2025-09-30 11:21:48.690833 | controller | Tuesday 30 September 2025  11:21:48 +0000 (0:00:00.053)       0:00:09.231 *****
2025-09-30 11:21:48.736576 | controller | ok: [instance] => changed=false
2025-09-30 11:21:48.771695 | controller |   msg: All assertions passed
2025-09-30 11:21:48.771722 | controller |
2025-09-30 11:21:48.771730 | controller | TASK [kustomize_deploy : Check custom wait conditions] *************************
2025-09-30 11:21:48.771736 | controller | Tuesday 30 September 2025  11:21:48 +0000 (0:00:00.046)       0:00:09.277 *****
2025-09-30 11:21:48.771751 | controller | skipping: [instance]
2025-09-30 11:21:48.799430 | controller |
2025-09-30 11:21:48.799452 | controller | TASK [kustomize_deploy : Check builtin wait conditions] ************************
2025-09-30 11:21:48.799461 | controller | Tuesday 30 September 2025  11:21:48 +0000 (0:00:00.035)       0:00:09.312 *****
2025-09-30 11:21:48.799471 | controller | ok: [instance] => (item=oc -n metallb-system wait pod -l app=metallb -l component=speaker --for condition=Ready --timeout=5m)
2025-09-30 11:21:48.822044 | controller |
2025-09-30 11:21:48.822094 | controller | TASK [kustomize_deploy : Check wait_conditions validation result] **************
2025-09-30 11:21:48.822125 | controller | Tuesday 30 September 2025  11:21:48 +0000 (0:00:00.027)       0:00:09.340 *****
2025-09-30 11:21:48.822138 | controller | skipping: [instance]
2025-09-30 11:21:48.822162 | controller |
2025-09-30 11:21:48.822266 | controller | TASK [kustomize_deploy : Set stage_wait_conditions fact] ***********************
2025-09-30 11:21:48.822395 | controller | Tuesday 30 September 2025  11:21:48 +0000 (0:00:00.022)       0:00:09.363 *****
2025-09-30 11:21:48.859897 | controller | ok: [instance]
2025-09-30 11:21:49.078004 | controller |
2025-09-30 11:21:49.078033 | controller | TASK [kustomize_deploy : Ensure source files exists] ***************************
2025-09-30 11:21:49.078041 | controller | Tuesday 30 September 2025  11:21:48 +0000 (0:00:00.037)       0:00:09.401 *****
2025-09-30 11:21:49.078061 | controller | ok: [instance] => (item={'name': 'network-values', 'src_file': 'nncp/values.yaml'})
2025-09-30 11:21:49.078068 | controller |
2025-09-30 11:21:49.078074 | controller | TASK [kustomize_deploy : Assert source files exist] ****************************
2025-09-30 11:21:49.078081 | controller | Tuesday 30 September 2025  11:21:49 +0000 (0:00:00.218)       0:00:09.619 *****
2025-09-30 11:21:49.141408 | controller | ok: [instance] => (item=values.yaml)
2025-09-30 11:21:49.188988 | controller |
2025-09-30 11:21:49.189020 | controller | TASK [Executing pre_stage hooks for examples/va/hci/control-plane/networking] ***
2025-09-30 11:21:49.189030 | controller | Tuesday 30 September 2025  11:21:49 +0000 (0:00:00.063)       0:00:09.682 *****
2025-09-30 11:21:49.189042 | controller | skipping: [instance]
2025-09-30 11:21:49.189226 | controller |
2025-09-30 11:21:49.189244 | controller | TASK [Generate values.yaml for examples/va/hci/control-plane/networking] *******
2025-09-30 11:21:49.272087 | controller | Tuesday 30 September 2025  11:21:49 +0000 (0:00:00.047)       0:00:09.730 *****
2025-09-30 11:21:49.272128 | controller |
2025-09-30 11:21:49.335482 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-09-30 11:21:49.335515 | controller | Tuesday 30 September 2025  11:21:49 +0000 (0:00:00.082)       0:00:09.813 *****
2025-09-30 11:21:49.335529 | 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-30 11:21:49.408811 | controller |
2025-09-30 11:21:49.408843 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-09-30 11:21:49.408851 | controller | Tuesday 30 September 2025  11:21:49 +0000 (0:00:00.063)       0:00:09.876 *****
2025-09-30 11:21:49.408861 | controller | ok: [instance] => changed=false
2025-09-30 11:21:49.615365 | controller |   msg: All assertions passed
2025-09-30 11:21:49.615394 | controller |
2025-09-30 11:21:49.615402 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-09-30 11:21:49.615408 | controller | Tuesday 30 September 2025  11:21:49 +0000 (0:00:00.073)       0:00:09.949 *****
2025-09-30 11:21:49.615418 | controller | ok: [instance]
2025-09-30 11:21:49.669558 | controller |
2025-09-30 11:21:49.669585 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-09-30 11:21:49.669593 | controller | Tuesday 30 September 2025  11:21:49 +0000 (0:00:00.206)       0:00:10.156 *****
2025-09-30 11:21:49.669603 | controller | ok: [instance] => changed=false
2025-09-30 11:21:49.880868 | controller |   msg: All assertions passed
2025-09-30 11:21:49.880900 | controller |
2025-09-30 11:21:49.880907 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-09-30 11:21:49.880914 | controller | Tuesday 30 September 2025  11:21:49 +0000 (0:00:00.054)       0:00:10.210 *****
2025-09-30 11:21:49.880940 | controller | ok: [instance]
2025-09-30 11:21:50.134818 | controller |
2025-09-30 11:21:50.134850 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-09-30 11:21:50.134858 | controller | Tuesday 30 September 2025  11:21:49 +0000 (0:00:00.211)       0:00:10.421 *****
2025-09-30 11:21:50.134868 | controller | ok: [instance]
2025-09-30 11:21:50.198016 | controller |
2025-09-30 11:21:50.198071 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-09-30 11:21:50.198081 | controller | Tuesday 30 September 2025  11:21:50 +0000 (0:00:00.253)       0:00:10.675 *****
2025-09-30 11:21:50.198092 | 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-30 11:21:50.269659 | controller |
2025-09-30 11:21:50.269682 | controller | TASK [ci_gen_kustomize_values : Assert we have needed parameters] **************
2025-09-30 11:21:50.269690 | controller | Tuesday 30 September 2025  11:21:50 +0000 (0:00:00.063)       0:00:10.738 *****
2025-09-30 11:21:50.269699 | controller | ok: [instance] => changed=false
2025-09-30 11:21:50.483727 | controller |   msg: All assertions passed
2025-09-30 11:21:50.483757 | controller |
2025-09-30 11:21:50.483765 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-09-30 11:21:50.483776 | controller | Tuesday 30 September 2025  11:21:50 +0000 (0:00:00.067)       0:00:10.806 *****
2025-09-30 11:21:50.483786 | controller | ok: [instance]
2025-09-30 11:21:50.947650 | controller |
2025-09-30 11:21:50.947679 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-09-30 11:21:50.947687 | controller | Tuesday 30 September 2025  11:21:50 +0000 (0:00:00.218)       0:00:11.024 *****
2025-09-30 11:21:50.947697 | controller | ok: [instance]
2025-09-30 11:21:51.009460 | controller |
2025-09-30 11:21:51.009488 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-09-30 11:21:51.009497 | controller | Tuesday 30 September 2025  11:21:50 +0000 (0:00:00.463)       0:00:11.488 *****
2025-09-30 11:21:51.009508 | controller | ok: [instance]
2025-09-30 11:21:51.377620 | controller |
2025-09-30 11:21:51.377649 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-09-30 11:21:51.377657 | controller | Tuesday 30 September 2025  11:21:51 +0000 (0:00:00.061)       0:00:11.550 *****
2025-09-30 11:21:51.377667 | controller | ok: [instance]
2025-09-30 11:21:51.737005 | controller |
2025-09-30 11:21:51.737036 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-09-30 11:21:51.737043 | controller | Tuesday 30 September 2025  11:21:51 +0000 (0:00:00.368)       0:00:11.918 *****
2025-09-30 11:21:51.737065 | controller | ok: [instance]
2025-09-30 11:21:51.737156 | controller |
2025-09-30 11:21:51.737186 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-09-30 11:21:51.737198 | controller | Tuesday 30 September 2025  11:21:51 +0000 (0:00:00.359)       0:00:12.278 *****
2025-09-30 11:21:52.114739 | controller | changed: [instance]
2025-09-30 11:21:52.179456 | controller |
2025-09-30 11:21:52.179476 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-09-30 11:21:52.179483 | controller | Tuesday 30 September 2025  11:21:52 +0000 (0:00:00.377)       0:00:12.655 *****
2025-09-30 11:21:52.179493 | 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-30 11:21:52.229756 | controller |
2025-09-30 11:21:52.229777 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-09-30 11:21:52.229785 | controller | Tuesday 30 September 2025  11:21:52 +0000 (0:00:00.064)       0:00:12.720 *****
2025-09-30 11:21:52.229794 | controller | ok: [instance] => changed=false
2025-09-30 11:21:52.439987 | controller |   msg: All assertions passed
2025-09-30 11:21:52.440028 | controller |
2025-09-30 11:21:52.440037 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-09-30 11:21:52.440043 | controller | Tuesday 30 September 2025  11:21:52 +0000 (0:00:00.050)       0:00:12.770 *****
2025-09-30 11:21:52.440062 | controller | ok: [instance]
2025-09-30 11:21:52.487025 | controller |
2025-09-30 11:21:52.487077 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-09-30 11:21:52.487086 | controller | Tuesday 30 September 2025  11:21:52 +0000 (0:00:00.210)       0:00:12.980 *****
2025-09-30 11:21:52.487096 | controller | ok: [instance]
2025-09-30 11:21:53.048103 | controller |
2025-09-30 11:21:53.048134 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-09-30 11:21:53.048142 | controller | Tuesday 30 September 2025  11:21:52 +0000 (0:00:00.047)       0:00:13.028 *****
2025-09-30 11:21:53.048152 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/02_ci_data.yaml)
2025-09-30 11:21:53.180889 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/03_user_data_b64.yaml)
2025-09-30 11:21:53.180920 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/04_user_data.yaml)
2025-09-30 11:21:53.180944 | controller |
2025-09-30 11:21:53.180953 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-09-30 11:21:53.180959 | controller | Tuesday 30 September 2025  11:21:53 +0000 (0:00:00.560)       0:00:13.588 *****
2025-09-30 11:21:53.180969 | controller | ok: [instance] => (item=02_ci_data.yaml)
2025-09-30 11:21:53.397321 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-09-30 11:21:53.397350 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-09-30 11:21:53.397358 | controller |
2025-09-30 11:21:53.397364 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-09-30 11:21:53.397370 | controller | Tuesday 30 September 2025  11:21:53 +0000 (0:00:00.132)       0:00:13.721 *****
2025-09-30 11:21:53.397380 | controller | ok: [instance]
2025-09-30 11:21:53.774859 | controller |
2025-09-30 11:21:53.774888 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-09-30 11:21:53.774896 | controller | Tuesday 30 September 2025  11:21:53 +0000 (0:00:00.216)       0:00:13.938 *****
2025-09-30 11:21:53.774906 | controller | ok: [instance]
2025-09-30 11:21:54.007654 | controller |
2025-09-30 11:21:54.007683 | controller | TASK [kustomize_deploy : Copy generated values for examples/va/hci/control-plane/networking] ***
2025-09-30 11:21:54.007691 | controller | Tuesday 30 September 2025  11:21:53 +0000 (0:00:00.377)       0:00:14.315 *****
2025-09-30 11:21:54.007701 | controller | ok: [instance] => (item=network-values)
2025-09-30 11:21:54.055388 | controller |
2025-09-30 11:21:54.055413 | controller | TASK [kustomize_deploy : Stop before building kustomization if requested] ******
2025-09-30 11:21:54.055421 | controller | Tuesday 30 September 2025  11:21:54 +0000 (0:00:00.232)       0:00:14.548 *****
2025-09-30 11:21:54.055431 | controller | skipping: [instance]
2025-09-30 11:21:54.439010 | controller |
2025-09-30 11:21:54.439044 | controller | TASK [kustomize_deploy : Build kustomized content for examples/va/hci/control-plane/networking] ***
2025-09-30 11:21:54.439062 | controller | Tuesday 30 September 2025  11:21:54 +0000 (0:00:00.047)       0:00:14.596 *****
2025-09-30 11:21:54.439078 | controller | changed: [instance]
2025-09-30 11:21:54.439103 | controller |
2025-09-30 11:21:54.439354 | controller | TASK [kustomize_deploy : Output kustomize build in final file] *****************
2025-09-30 11:21:54.798685 | controller | Tuesday 30 September 2025  11:21:54 +0000 (0:00:00.383)       0:00:14.980 *****
2025-09-30 11:21:54.798719 | controller | changed: [instance]
2025-09-30 11:21:55.036063 | controller |
2025-09-30 11:21:55.036097 | controller | TASK [kustomize_deploy : Store kustomized content in artifacts for examples/va/hci/control-plane/networking] ***
2025-09-30 11:21:55.036106 | controller | Tuesday 30 September 2025  11:21:54 +0000 (0:00:00.359)       0:00:15.339 *****
2025-09-30 11:21:55.036116 | controller | changed: [instance]
2025-09-30 11:21:55.084085 | controller |
2025-09-30 11:21:55.084130 | controller | TASK [kustomize_deploy : Stop after building kustomization if requested] *******
2025-09-30 11:21:55.084140 | controller | Tuesday 30 September 2025  11:21:55 +0000 (0:00:00.237)       0:00:15.577 *****
2025-09-30 11:21:55.084152 | controller | skipping: [instance]
2025-09-30 11:21:55.084173 | controller |
2025-09-30 11:21:55.084183 | controller | TASK [kustomize_deploy : Apply generated content for examples/va/hci/control-plane/networking] ***
2025-09-30 11:21:55.084270 | controller | Tuesday 30 September 2025  11:21:55 +0000 (0:00:00.048)       0:00:15.625 *****
2025-09-30 11:21:55.132346 | controller | skipping: [instance]
2025-09-30 11:21:55.180002 | controller |
2025-09-30 11:21:55.180040 | controller | TASK [kustomize_deploy : Run Wait Conditions for examples/va/hci/control-plane/networking] ***
2025-09-30 11:21:55.180062 | controller | Tuesday 30 September 2025  11:21:55 +0000 (0:00:00.047)       0:00:15.673 *****
2025-09-30 11:21:55.180073 | controller | skipping: [instance] => (item=oc -n metallb-system wait pod -l app=metallb -l component=speaker --for condition=Ready --timeout=5m)
2025-09-30 11:21:55.180107 | controller | skipping: [instance]
2025-09-30 11:21:55.180451 | controller |
2025-09-30 11:21:55.227844 | controller | TASK [kustomize_deploy : Stop after applying CRs if requested] *****************
2025-09-30 11:21:55.227872 | controller | Tuesday 30 September 2025  11:21:55 +0000 (0:00:00.047)       0:00:15.721 *****
2025-09-30 11:21:55.227893 | controller | skipping: [instance]
2025-09-30 11:21:55.277460 | controller |
2025-09-30 11:21:55.277482 | controller | TASK [Executing post_stage hooks for examples/va/hci/control-plane/networking] ***
2025-09-30 11:21:55.277491 | controller | Tuesday 30 September 2025  11:21:55 +0000 (0:00:00.047)       0:00:15.768 *****
2025-09-30 11:21:55.277502 | controller | skipping: [instance]
2025-09-30 11:21:55.321622 | controller |
2025-09-30 11:21:55.321640 | controller | TASK [kustomize_deploy : Assert mandatory bits are defined] ********************
2025-09-30 11:21:55.321647 | controller | Tuesday 30 September 2025  11:21:55 +0000 (0:00:00.049)       0:00:15.818 *****
2025-09-30 11:21:55.321656 | controller | ok: [instance] => changed=false
2025-09-30 11:21:55.353999 | controller |   msg: All assertions passed
2025-09-30 11:21:55.354030 | controller |
2025-09-30 11:21:55.354038 | controller | TASK [kustomize_deploy : Check custom wait conditions] *************************
2025-09-30 11:21:55.354044 | controller | Tuesday 30 September 2025  11:21:55 +0000 (0:00:00.044)       0:00:15.862 *****
2025-09-30 11:21:55.354064 | controller | skipping: [instance]
2025-09-30 11:21:55.379915 | controller |
2025-09-30 11:21:55.379952 | controller | TASK [kustomize_deploy : Check builtin wait conditions] ************************
2025-09-30 11:21:55.379960 | controller | Tuesday 30 September 2025  11:21:55 +0000 (0:00:00.032)       0:00:15.895 *****
2025-09-30 11:21:55.379969 | controller | ok: [instance] => (item=oc -n openstack wait osctlplane controlplane --for condition=Ready --timeout=60m)
2025-09-30 11:21:55.400028 | controller |
2025-09-30 11:21:55.400079 | controller | TASK [kustomize_deploy : Check wait_conditions validation result] **************
2025-09-30 11:21:55.400089 | controller | Tuesday 30 September 2025  11:21:55 +0000 (0:00:00.025)       0:00:15.921 *****
2025-09-30 11:21:55.400101 | controller | skipping: [instance]
2025-09-30 11:21:55.400130 | controller |
2025-09-30 11:21:55.400140 | controller | TASK [kustomize_deploy : Set stage_wait_conditions fact] ***********************
2025-09-30 11:21:55.400202 | controller | Tuesday 30 September 2025  11:21:55 +0000 (0:00:00.020)       0:00:15.941 *****
2025-09-30 11:21:55.437840 | controller | ok: [instance]
2025-09-30 11:21:55.841733 | controller |
2025-09-30 11:21:55.841764 | controller | TASK [kustomize_deploy : Ensure source files exists] ***************************
2025-09-30 11:21:55.841772 | controller | Tuesday 30 September 2025  11:21:55 +0000 (0:00:00.037)       0:00:15.979 *****
2025-09-30 11:21:55.841782 | controller | ok: [instance] => (item={'name': 'service-values', 'src_file': 'service-values.yaml'})
2025-09-30 11:21:55.935024 | controller | ok: [instance] => (item={'name': 'network-values', 'src_file': 'networking/nncp/values.yaml'})
2025-09-30 11:21:55.935069 | controller |
2025-09-30 11:21:55.935078 | controller | TASK [kustomize_deploy : Assert source files exist] ****************************
2025-09-30 11:21:55.935084 | controller | Tuesday 30 September 2025  11:21:55 +0000 (0:00:00.403)       0:00:16.382 *****
2025-09-30 11:21:55.935094 | controller | ok: [instance] => (item=service-values.yaml)
2025-09-30 11:21:55.982263 | controller | ok: [instance] => (item=values.yaml)
2025-09-30 11:21:55.982296 | controller |
2025-09-30 11:21:55.982304 | controller | TASK [Executing pre_stage hooks for examples/va/hci/control-plane] *************
2025-09-30 11:21:55.982310 | controller | Tuesday 30 September 2025  11:21:55 +0000 (0:00:00.093)       0:00:16.475 *****
2025-09-30 11:21:55.982322 | controller | skipping: [instance]
2025-09-30 11:21:56.100910 | controller |
2025-09-30 11:21:56.100946 | controller | TASK [Generate values.yaml for examples/va/hci/control-plane] ******************
2025-09-30 11:21:56.100961 | controller | Tuesday 30 September 2025  11:21:55 +0000 (0:00:00.047)       0:00:16.523 *****
2025-09-30 11:21:56.100971 | controller |
2025-09-30 11:21:56.163873 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-09-30 11:21:56.163905 | controller | Tuesday 30 September 2025  11:21:56 +0000 (0:00:00.118)       0:00:16.642 *****
2025-09-30 11:21:56.163916 | 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-30 11:21:56.215542 | controller |
2025-09-30 11:21:56.215575 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-09-30 11:21:56.215584 | controller | Tuesday 30 September 2025  11:21:56 +0000 (0:00:00.062)       0:00:16.705 *****
2025-09-30 11:21:56.215601 | controller | ok: [instance] => changed=false
2025-09-30 11:21:56.430963 | controller |   msg: All assertions passed
2025-09-30 11:21:56.430994 | controller |
2025-09-30 11:21:56.431002 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-09-30 11:21:56.431008 | controller | Tuesday 30 September 2025  11:21:56 +0000 (0:00:00.051)       0:00:16.756 *****
2025-09-30 11:21:56.431018 | controller | ok: [instance]
2025-09-30 11:21:56.482180 | controller |
2025-09-30 11:21:56.482211 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-09-30 11:21:56.482219 | controller | Tuesday 30 September 2025  11:21:56 +0000 (0:00:00.215)       0:00:16.971 *****
2025-09-30 11:21:56.482230 | controller | ok: [instance] => changed=false
2025-09-30 11:21:56.698597 | controller |   msg: All assertions passed
2025-09-30 11:21:56.698626 | controller |
2025-09-30 11:21:56.698634 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-09-30 11:21:56.698640 | controller | Tuesday 30 September 2025  11:21:56 +0000 (0:00:00.051)       0:00:17.023 *****
2025-09-30 11:21:56.698650 | controller | ok: [instance]
2025-09-30 11:21:56.872647 | controller |
2025-09-30 11:21:56.872682 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-09-30 11:21:56.872692 | controller | Tuesday 30 September 2025  11:21:56 +0000 (0:00:00.216)       0:00:17.239 *****
2025-09-30 11:21:56.872705 | controller | ok: [instance]
2025-09-30 11:21:56.921478 | controller |
2025-09-30 11:21:56.921503 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-09-30 11:21:56.921511 | controller | Tuesday 30 September 2025  11:21:56 +0000 (0:00:00.173)       0:00:17.413 *****
2025-09-30 11:21:56.921521 | controller | skipping: [instance]
2025-09-30 11:21:57.136999 | controller |
2025-09-30 11:21:57.137029 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-09-30 11:21:57.137037 | controller | Tuesday 30 September 2025  11:21:56 +0000 (0:00:00.049)       0:00:17.462 *****
2025-09-30 11:21:57.137046 | controller | changed: [instance]
2025-09-30 11:21:57.137080 | controller |
2025-09-30 11:21:57.137088 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-09-30 11:21:57.137161 | controller | Tuesday 30 September 2025  11:21:57 +0000 (0:00:00.215)       0:00:17.678 *****
2025-09-30 11:21:57.193754 | controller | skipping: [instance]
2025-09-30 11:21:57.256865 | controller |
2025-09-30 11:21:57.256893 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-09-30 11:21:57.256901 | controller | Tuesday 30 September 2025  11:21:57 +0000 (0:00:00.056)       0:00:17.734 *****
2025-09-30 11:21:57.256911 | controller | ok: [instance]
2025-09-30 11:21:57.611543 | controller |
2025-09-30 11:21:57.611573 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-09-30 11:21:57.611581 | controller | Tuesday 30 September 2025  11:21:57 +0000 (0:00:00.062)       0:00:17.797 *****
2025-09-30 11:21:57.611591 | controller | changed: [instance]
2025-09-30 11:21:57.978445 | controller |
2025-09-30 11:21:57.978475 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-09-30 11:21:57.978483 | controller | Tuesday 30 September 2025  11:21:57 +0000 (0:00:00.354)       0:00:18.152 *****
2025-09-30 11:21:57.978494 | controller | changed: [instance]
2025-09-30 11:21:58.332631 | controller |
2025-09-30 11:21:58.332661 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-09-30 11:21:58.332669 | controller | Tuesday 30 September 2025  11:21:57 +0000 (0:00:00.366)       0:00:18.519 *****
2025-09-30 11:21:58.332679 | controller | changed: [instance]
2025-09-30 11:21:58.397013 | controller |
2025-09-30 11:21:58.397044 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-09-30 11:21:58.397065 | controller | Tuesday 30 September 2025  11:21:58 +0000 (0:00:00.354)       0:00:18.873 *****
2025-09-30 11:21:58.397076 | 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-30 11:21:58.447639 | controller |
2025-09-30 11:21:58.447675 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-09-30 11:21:58.447682 | controller | Tuesday 30 September 2025  11:21:58 +0000 (0:00:00.064)       0:00:18.938 *****
2025-09-30 11:21:58.447700 | controller | ok: [instance] => changed=false
2025-09-30 11:21:58.657982 | controller |   msg: All assertions passed
2025-09-30 11:21:58.658011 | controller |
2025-09-30 11:21:58.658019 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-09-30 11:21:58.658031 | controller | Tuesday 30 September 2025  11:21:58 +0000 (0:00:00.050)       0:00:18.988 *****
2025-09-30 11:21:58.658041 | controller | ok: [instance]
2025-09-30 11:21:58.705007 | controller |
2025-09-30 11:21:58.705035 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-09-30 11:21:58.705045 | controller | Tuesday 30 September 2025  11:21:58 +0000 (0:00:00.210)       0:00:19.198 *****
2025-09-30 11:21:58.705071 | controller | ok: [instance]
2025-09-30 11:21:58.705098 | controller |
2025-09-30 11:21:58.705108 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-09-30 11:21:58.705184 | controller | Tuesday 30 September 2025  11:21:58 +0000 (0:00:00.047)       0:00:19.246 *****
2025-09-30 11:21:59.092896 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/service-values/03_user_data_b64.yaml)
2025-09-30 11:21:59.181572 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/service-values/04_user_data.yaml)
2025-09-30 11:21:59.181599 | controller |
2025-09-30 11:21:59.181607 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-09-30 11:21:59.181613 | controller | Tuesday 30 September 2025  11:21:59 +0000 (0:00:00.387)       0:00:19.633 *****
2025-09-30 11:21:59.181623 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-09-30 11:21:59.391440 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-09-30 11:21:59.391469 | controller |
2025-09-30 11:21:59.391477 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-09-30 11:21:59.391483 | controller | Tuesday 30 September 2025  11:21:59 +0000 (0:00:00.088)       0:00:19.722 *****
2025-09-30 11:21:59.391493 | controller | changed: [instance]
2025-09-30 11:21:59.754784 | controller |
2025-09-30 11:21:59.754813 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-09-30 11:21:59.754821 | controller | Tuesday 30 September 2025  11:21:59 +0000 (0:00:00.210)       0:00:19.932 *****
2025-09-30 11:21:59.754830 | controller | changed: [instance]
2025-09-30 11:21:59.817447 | controller |
2025-09-30 11:21:59.817476 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-09-30 11:21:59.817484 | controller | Tuesday 30 September 2025  11:21:59 +0000 (0:00:00.363)       0:00:20.295 *****
2025-09-30 11:21:59.817494 | 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-30 11:21:59.867488 | controller |
2025-09-30 11:21:59.867515 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-09-30 11:21:59.867524 | controller | Tuesday 30 September 2025  11:21:59 +0000 (0:00:00.062)       0:00:20.358 *****
2025-09-30 11:21:59.867533 | controller | ok: [instance] => changed=false
2025-09-30 11:22:00.072543 | controller |   msg: All assertions passed
2025-09-30 11:22:00.072572 | controller |
2025-09-30 11:22:00.072580 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-09-30 11:22:00.072586 | controller | Tuesday 30 September 2025  11:21:59 +0000 (0:00:00.049)       0:00:20.408 *****
2025-09-30 11:22:00.072595 | controller | ok: [instance]
2025-09-30 11:22:00.126508 | controller |
2025-09-30 11:22:00.126536 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-09-30 11:22:00.126544 | controller | Tuesday 30 September 2025  11:22:00 +0000 (0:00:00.205)       0:00:20.613 *****
2025-09-30 11:22:00.126555 | controller | ok: [instance] => changed=false
2025-09-30 11:22:00.332393 | controller |   msg: All assertions passed
2025-09-30 11:22:00.332423 | controller |
2025-09-30 11:22:00.332431 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-09-30 11:22:00.332437 | controller | Tuesday 30 September 2025  11:22:00 +0000 (0:00:00.053)       0:00:20.667 *****
2025-09-30 11:22:00.332447 | controller | ok: [instance]
2025-09-30 11:22:00.584556 | controller |
2025-09-30 11:22:00.584587 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-09-30 11:22:00.584601 | controller | Tuesday 30 September 2025  11:22:00 +0000 (0:00:00.205)       0:00:20.873 *****
2025-09-30 11:22:00.584611 | controller | ok: [instance]
2025-09-30 11:22:00.645854 | controller |
2025-09-30 11:22:00.645886 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-09-30 11:22:00.645894 | controller | Tuesday 30 September 2025  11:22:00 +0000 (0:00:00.252)       0:00:21.125 *****
2025-09-30 11:22:00.645904 | 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-30 11:22:00.696960 | controller |
2025-09-30 11:22:00.696995 | controller | TASK [ci_gen_kustomize_values : Assert we have needed parameters] **************
2025-09-30 11:22:00.697005 | controller | Tuesday 30 September 2025  11:22:00 +0000 (0:00:00.061)       0:00:21.186 *****
2025-09-30 11:22:00.697017 | controller | ok: [instance] => changed=false
2025-09-30 11:22:00.911040 | controller |   msg: All assertions passed
2025-09-30 11:22:00.911085 | controller |
2025-09-30 11:22:00.911094 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-09-30 11:22:00.911100 | controller | Tuesday 30 September 2025  11:22:00 +0000 (0:00:00.050)       0:00:21.237 *****
2025-09-30 11:22:00.911110 | controller | ok: [instance]
2025-09-30 11:22:01.388603 | controller |
2025-09-30 11:22:01.388633 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-09-30 11:22:01.388642 | controller | Tuesday 30 September 2025  11:22:00 +0000 (0:00:00.214)       0:00:21.452 *****
2025-09-30 11:22:01.388652 | controller | ok: [instance]
2025-09-30 11:22:01.453025 | controller |
2025-09-30 11:22:01.453075 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-09-30 11:22:01.453090 | controller | Tuesday 30 September 2025  11:22:01 +0000 (0:00:00.477)       0:00:21.929 *****
2025-09-30 11:22:01.453101 | controller | ok: [instance]
2025-09-30 11:22:01.812173 | controller |
2025-09-30 11:22:01.812203 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-09-30 11:22:01.812211 | controller | Tuesday 30 September 2025  11:22:01 +0000 (0:00:00.064)       0:00:21.993 *****
2025-09-30 11:22:01.812221 | controller | ok: [instance]
2025-09-30 11:22:02.184997 | controller |
2025-09-30 11:22:02.185026 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-09-30 11:22:02.185034 | controller | Tuesday 30 September 2025  11:22:01 +0000 (0:00:00.359)       0:00:22.353 *****
2025-09-30 11:22:02.185044 | controller | ok: [instance]
2025-09-30 11:22:02.580916 | controller |
2025-09-30 11:22:02.580962 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-09-30 11:22:02.580970 | controller | Tuesday 30 September 2025  11:22:02 +0000 (0:00:00.372)       0:00:22.725 *****
2025-09-30 11:22:02.580980 | controller | ok: [instance]
2025-09-30 11:22:02.643612 | controller |
2025-09-30 11:22:02.643642 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-09-30 11:22:02.643651 | controller | Tuesday 30 September 2025  11:22:02 +0000 (0:00:00.395)       0:00:23.121 *****
2025-09-30 11:22:02.643663 | 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-30 11:22:02.692157 | controller |
2025-09-30 11:22:02.692179 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-09-30 11:22:02.692186 | controller | Tuesday 30 September 2025  11:22:02 +0000 (0:00:00.062)       0:00:23.184 *****
2025-09-30 11:22:02.692196 | controller | ok: [instance] => changed=false
2025-09-30 11:22:02.900524 | controller |   msg: All assertions passed
2025-09-30 11:22:02.900552 | controller |
2025-09-30 11:22:02.900559 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-09-30 11:22:02.900566 | controller | Tuesday 30 September 2025  11:22:02 +0000 (0:00:00.048)       0:00:23.233 *****
2025-09-30 11:22:02.900575 | controller | ok: [instance]
2025-09-30 11:22:02.947011 | controller |
2025-09-30 11:22:02.947089 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-09-30 11:22:02.947100 | controller | Tuesday 30 September 2025  11:22:02 +0000 (0:00:00.208)       0:00:23.441 *****
2025-09-30 11:22:02.947123 | controller | ok: [instance]
2025-09-30 11:22:02.947197 | controller |
2025-09-30 11:22:02.947211 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-09-30 11:22:03.509374 | controller | Tuesday 30 September 2025  11:22:02 +0000 (0:00:00.046)       0:00:23.488 *****
2025-09-30 11:22:03.509418 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/02_ci_data.yaml)
2025-09-30 11:22:03.648806 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/03_user_data_b64.yaml)
2025-09-30 11:22:03.648847 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/04_user_data.yaml)
2025-09-30 11:22:03.648857 | controller |
2025-09-30 11:22:03.648865 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-09-30 11:22:03.648872 | controller | Tuesday 30 September 2025  11:22:03 +0000 (0:00:00.561)       0:00:24.050 *****
2025-09-30 11:22:03.648885 | controller | ok: [instance] => (item=02_ci_data.yaml)
2025-09-30 11:22:03.864309 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-09-30 11:22:03.864343 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-09-30 11:22:03.864351 | controller |
2025-09-30 11:22:03.864357 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-09-30 11:22:03.864363 | controller | Tuesday 30 September 2025  11:22:03 +0000 (0:00:00.139)       0:00:24.189 *****
2025-09-30 11:22:03.864374 | controller | ok: [instance]
2025-09-30 11:22:04.259675 | controller |
2025-09-30 11:22:04.259703 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-09-30 11:22:04.259711 | controller | Tuesday 30 September 2025  11:22:03 +0000 (0:00:00.215)       0:00:24.405 *****
2025-09-30 11:22:04.259721 | controller | ok: [instance]
2025-09-30 11:22:04.705648 | controller |
2025-09-30 11:22:04.705678 | controller | TASK [kustomize_deploy : Copy generated values for examples/va/hci/control-plane] ***
2025-09-30 11:22:04.705685 | controller | Tuesday 30 September 2025  11:22:04 +0000 (0:00:00.395)       0:00:24.800 *****
2025-09-30 11:22:04.705696 | controller | changed: [instance] => (item=service-values)
2025-09-30 11:22:04.753008 | controller | ok: [instance] => (item=network-values)
2025-09-30 11:22:04.753039 | controller |
2025-09-30 11:22:04.753046 | controller | TASK [kustomize_deploy : Stop before building kustomization if requested] ******
2025-09-30 11:22:04.753065 | controller | Tuesday 30 September 2025  11:22:04 +0000 (0:00:00.445)       0:00:25.246 *****
2025-09-30 11:22:04.753075 | controller | skipping: [instance]
2025-09-30 11:22:04.753081 | controller |
2025-09-30 11:22:04.753087 | controller | TASK [kustomize_deploy : Build kustomized content for examples/va/hci/control-plane] ***
2025-09-30 11:22:04.753094 | controller | Tuesday 30 September 2025  11:22:04 +0000 (0:00:00.047)       0:00:25.294 *****
2025-09-30 11:22:05.068678 | controller | changed: [instance]
2025-09-30 11:22:05.444682 | controller |
2025-09-30 11:22:05.444712 | controller | TASK [kustomize_deploy : Output kustomize build in final file] *****************
2025-09-30 11:22:05.444720 | controller | Tuesday 30 September 2025  11:22:05 +0000 (0:00:00.315)       0:00:25.609 *****
2025-09-30 11:22:05.444738 | controller | changed: [instance]
2025-09-30 11:22:05.678537 | controller |
2025-09-30 11:22:05.678571 | controller | TASK [kustomize_deploy : Store kustomized content in artifacts for examples/va/hci/control-plane] ***
2025-09-30 11:22:05.678580 | controller | Tuesday 30 September 2025  11:22:05 +0000 (0:00:00.376)       0:00:25.985 *****
2025-09-30 11:22:05.678590 | controller | changed: [instance]
2025-09-30 11:22:05.678615 | controller |
2025-09-30 11:22:05.678729 | controller | TASK [kustomize_deploy : Stop after building kustomization if requested] *******
2025-09-30 11:22:05.678878 | controller | Tuesday 30 September 2025  11:22:05 +0000 (0:00:00.234)       0:00:26.219 *****
2025-09-30 11:22:05.727143 | controller | skipping: [instance]
2025-09-30 11:22:05.774469 | controller |
2025-09-30 11:22:05.774495 | controller | TASK [kustomize_deploy : Apply generated content for examples/va/hci/control-plane] ***
2025-09-30 11:22:05.774504 | controller | Tuesday 30 September 2025  11:22:05 +0000 (0:00:00.048)       0:00:26.268 *****
2025-09-30 11:22:05.774514 | controller | skipping: [instance]
2025-09-30 11:22:05.823642 | controller |
2025-09-30 11:22:05.823669 | controller | TASK [kustomize_deploy : Run Wait Conditions for examples/va/hci/control-plane] ***
2025-09-30 11:22:05.823686 | controller | Tuesday 30 September 2025  11:22:05 +0000 (0:00:00.047)       0:00:26.315 *****
2025-09-30 11:22:05.823697 | controller | skipping: [instance] => (item=oc -n openstack wait osctlplane controlplane --for condition=Ready --timeout=60m)
2025-09-30 11:22:05.920082 | controller | skipping: [instance]
2025-09-30 11:22:05.920114 | controller |
2025-09-30 11:22:05.920122 | controller | TASK [kustomize_deploy : Stop after applying CRs if requested] *****************
2025-09-30 11:22:05.920129 | controller | Tuesday 30 September 2025  11:22:05 +0000 (0:00:00.048)       0:00:26.364 *****
2025-09-30 11:22:05.920138 | controller | skipping: [instance]
2025-09-30 11:22:05.972520 | controller |
2025-09-30 11:22:05.972550 | controller | TASK [Executing post_stage hooks for examples/va/hci/control-plane] ************
2025-09-30 11:22:05.972557 | controller | Tuesday 30 September 2025  11:22:05 +0000 (0:00:00.096)       0:00:26.461 *****
2025-09-30 11:22:05.972567 | controller | skipping: [instance]
2025-09-30 11:22:06.017542 | controller |
2025-09-30 11:22:06.017565 | controller | TASK [kustomize_deploy : Assert mandatory bits are defined] ********************
2025-09-30 11:22:06.017572 | controller | Tuesday 30 September 2025  11:22:05 +0000 (0:00:00.052)       0:00:26.513 *****
2025-09-30 11:22:06.017582 | controller | ok: [instance] => changed=false
2025-09-30 11:22:06.050994 | controller |   msg: All assertions passed
2025-09-30 11:22:06.051011 | controller |
2025-09-30 11:22:06.051018 | controller | TASK [kustomize_deploy : Check custom wait conditions] *************************
2025-09-30 11:22:06.051023 | controller | Tuesday 30 September 2025  11:22:06 +0000 (0:00:00.045)       0:00:26.558 *****
2025-09-30 11:22:06.051032 | controller | skipping: [instance]
2025-09-30 11:22:06.076991 | controller |
2025-09-30 11:22:06.077015 | controller | TASK [kustomize_deploy : Check builtin wait conditions] ************************
2025-09-30 11:22:06.077023 | controller | Tuesday 30 September 2025  11:22:06 +0000 (0:00:00.033)       0:00:26.592 *****
2025-09-30 11:22:06.077034 | controller | ok: [instance] => (item=oc -n openstack wait osdpns openstack-edpm --for condition=SetupReady --timeout=10m)
2025-09-30 11:22:06.097365 | controller |
2025-09-30 11:22:06.097384 | controller | TASK [kustomize_deploy : Check wait_conditions validation result] **************
2025-09-30 11:22:06.097394 | controller | Tuesday 30 September 2025  11:22:06 +0000 (0:00:00.025)       0:00:26.618 *****
2025-09-30 11:22:06.097408 | controller | skipping: [instance]
2025-09-30 11:22:06.097573 | controller |
2025-09-30 11:22:06.097741 | controller | TASK [kustomize_deploy : Set stage_wait_conditions fact] ***********************
2025-09-30 11:22:06.097907 | controller | Tuesday 30 September 2025  11:22:06 +0000 (0:00:00.020)       0:00:26.638 *****
2025-09-30 11:22:06.136571 | controller | ok: [instance]
2025-09-30 11:22:06.136723 | controller |
2025-09-30 11:22:06.136873 | controller | TASK [kustomize_deploy : Ensure source files exists] ***************************
2025-09-30 11:22:06.137071 | controller | Tuesday 30 September 2025  11:22:06 +0000 (0:00:00.039)       0:00:26.678 *****
2025-09-30 11:22:06.356669 | controller | ok: [instance] => (item={'name': 'edpm-nodeset-values', 'src_file': 'values.yaml'})
2025-09-30 11:22:06.424028 | controller |
2025-09-30 11:22:06.424081 | controller | TASK [kustomize_deploy : Assert source files exist] ****************************
2025-09-30 11:22:06.424091 | controller | Tuesday 30 September 2025  11:22:06 +0000 (0:00:00.219)       0:00:26.897 *****
2025-09-30 11:22:06.424103 | controller | ok: [instance] => (item=values.yaml)
2025-09-30 11:22:06.472443 | controller |
2025-09-30 11:22:06.472478 | controller | TASK [Executing pre_stage hooks for examples/va/hci/edpm-pre-ceph/nodeset] *****
2025-09-30 11:22:06.472486 | controller | Tuesday 30 September 2025  11:22:06 +0000 (0:00:00.067)       0:00:26.965 *****
2025-09-30 11:22:06.472497 | controller | skipping: [instance]
2025-09-30 11:22:06.554494 | controller |
2025-09-30 11:22:06.554527 | controller | TASK [Generate values.yaml for examples/va/hci/edpm-pre-ceph/nodeset] **********
2025-09-30 11:22:06.554535 | controller | Tuesday 30 September 2025  11:22:06 +0000 (0:00:00.048)       0:00:27.013 *****
2025-09-30 11:22:06.554545 | controller |
2025-09-30 11:22:06.619071 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-09-30 11:22:06.619109 | controller | Tuesday 30 September 2025  11:22:06 +0000 (0:00:00.082)       0:00:27.095 *****
2025-09-30 11:22:06.619121 | 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-30 11:22:06.619151 | controller |
2025-09-30 11:22:06.619159 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-09-30 11:22:06.619286 | controller | Tuesday 30 September 2025  11:22:06 +0000 (0:00:00.064)       0:00:27.160 *****
2025-09-30 11:22:06.671548 | controller | ok: [instance] => changed=false
2025-09-30 11:22:06.885868 | controller |   msg: All assertions passed
2025-09-30 11:22:06.885900 | controller |
2025-09-30 11:22:06.885908 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-09-30 11:22:06.885915 | controller | Tuesday 30 September 2025  11:22:06 +0000 (0:00:00.051)       0:00:27.212 *****
2025-09-30 11:22:06.885942 | controller | ok: [instance]
2025-09-30 11:22:06.939024 | controller |
2025-09-30 11:22:06.939070 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-09-30 11:22:06.939080 | controller | Tuesday 30 September 2025  11:22:06 +0000 (0:00:00.214)       0:00:27.426 *****
2025-09-30 11:22:06.939092 | controller | ok: [instance] => changed=false
2025-09-30 11:22:07.139374 | controller |   msg: All assertions passed
2025-09-30 11:22:07.139403 | controller |
2025-09-30 11:22:07.139411 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-09-30 11:22:07.139418 | controller | Tuesday 30 September 2025  11:22:06 +0000 (0:00:00.053)       0:00:27.479 *****
2025-09-30 11:22:07.139427 | controller | ok: [instance]
2025-09-30 11:22:07.375104 | controller |
2025-09-30 11:22:07.375139 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-09-30 11:22:07.375148 | controller | Tuesday 30 September 2025  11:22:07 +0000 (0:00:00.200)       0:00:27.680 *****
2025-09-30 11:22:07.375160 | controller | ok: [instance]
2025-09-30 11:22:07.375188 | controller |
2025-09-30 11:22:07.375195 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-09-30 11:22:07.375205 | controller | Tuesday 30 September 2025  11:22:07 +0000 (0:00:00.235)       0:00:27.916 *****
2025-09-30 11:22:07.441075 | 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-30 11:22:07.441211 | controller |
2025-09-30 11:22:07.441242 | controller | TASK [ci_gen_kustomize_values : Check if all required variables for accessing the node is provided.] ***
2025-09-30 11:22:07.441254 | controller | Tuesday 30 September 2025  11:22:07 +0000 (0:00:00.066)       0:00:27.982 *****
2025-09-30 11:22:07.508913 | 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-30 11:22:07.555533 | controller |
2025-09-30 11:22:07.555562 | controller | TASK [ci_gen_kustomize_values : Ensure the required parameters are defined and configured.] ***
2025-09-30 11:22:07.555570 | controller | Tuesday 30 September 2025  11:22:07 +0000 (0:00:00.067)       0:00:28.049 *****
2025-09-30 11:22:07.555586 | controller | ok: [instance] => changed=false
2025-09-30 11:22:07.607501 | controller |   msg: All assertions passed
2025-09-30 11:22:07.607530 | controller |
2025-09-30 11:22:07.607537 | controller | TASK [ci_gen_kustomize_values : Get common data for dataplane templating] ******
2025-09-30 11:22:07.607544 | controller | Tuesday 30 September 2025  11:22:07 +0000 (0:00:00.046)       0:00:28.096 *****
2025-09-30 11:22:07.607553 | 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-30 11:22:07.607702 | controller |
2025-09-30 11:22:07.607871 | controller | TASK [ci_gen_kustomize_values : Fetch the already deployed services for further usage] ***
2025-09-30 11:22:07.608036 | controller | Tuesday 30 September 2025  11:22:07 +0000 (0:00:00.052)       0:00:28.148 *****
2025-09-30 11:22:07.646270 | controller | skipping: [instance]
2025-09-30 11:22:07.646453 | controller |
2025-09-30 11:22:07.646601 | controller | TASK [ci_gen_kustomize_values : Expose the deployed services as a fact] ********
2025-09-30 11:22:07.646739 | controller | Tuesday 30 September 2025  11:22:07 +0000 (0:00:00.038)       0:00:28.187 *****
2025-09-30 11:22:07.687521 | controller | ok: [instance]
2025-09-30 11:22:07.687704 | controller |
2025-09-30 11:22:07.687890 | controller | TASK [ci_gen_kustomize_values : Check if required variables for nova migration are provided.] ***
2025-09-30 11:22:07.688102 | controller | Tuesday 30 September 2025  11:22:07 +0000 (0:00:00.041)       0:00:28.228 *****
2025-09-30 11:22:07.731885 | controller | ok: [instance] => changed=false
2025-09-30 11:22:07.732177 | controller |   msg: All assertions passed
2025-09-30 11:22:07.732355 | controller |
2025-09-30 11:22:07.732526 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-09-30 11:22:07.732666 | controller | Tuesday 30 September 2025  11:22:07 +0000 (0:00:00.044)       0:00:28.273 *****
2025-09-30 11:22:07.939086 | controller | changed: [instance]
2025-09-30 11:22:08.364013 | controller |
2025-09-30 11:22:08.364047 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-09-30 11:22:08.364065 | controller | Tuesday 30 September 2025  11:22:07 +0000 (0:00:00.206)       0:00:28.479 *****
2025-09-30 11:22:08.364076 | controller | changed: [instance]
2025-09-30 11:22:08.418773 | controller |
2025-09-30 11:22:08.418811 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-09-30 11:22:08.418821 | controller | Tuesday 30 September 2025  11:22:08 +0000 (0:00:00.424)       0:00:28.904 *****
2025-09-30 11:22:08.418831 | controller | ok: [instance]
2025-09-30 11:22:08.778983 | controller |
2025-09-30 11:22:08.779028 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-09-30 11:22:08.779036 | controller | Tuesday 30 September 2025  11:22:08 +0000 (0:00:00.054)       0:00:28.959 *****
2025-09-30 11:22:08.779063 | controller | changed: [instance]
2025-09-30 11:22:09.135045 | controller |
2025-09-30 11:22:09.135091 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-09-30 11:22:09.135099 | controller | Tuesday 30 September 2025  11:22:08 +0000 (0:00:00.360)       0:00:29.319 *****
2025-09-30 11:22:09.135116 | controller | changed: [instance]
2025-09-30 11:22:09.492453 | controller |
2025-09-30 11:22:09.492486 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-09-30 11:22:09.492494 | controller | Tuesday 30 September 2025  11:22:09 +0000 (0:00:00.356)       0:00:29.675 *****
2025-09-30 11:22:09.492504 | controller | changed: [instance]
2025-09-30 11:22:09.548086 | controller |
2025-09-30 11:22:09.548123 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-09-30 11:22:09.548133 | controller | Tuesday 30 September 2025  11:22:09 +0000 (0:00:00.357)       0:00:30.033 *****
2025-09-30 11:22:09.548145 | 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-30 11:22:09.548174 | controller |
2025-09-30 11:22:09.548185 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-09-30 11:22:09.548266 | controller | Tuesday 30 September 2025  11:22:09 +0000 (0:00:00.056)       0:00:30.089 *****
2025-09-30 11:22:09.588983 | controller | ok: [instance] => changed=false
2025-09-30 11:22:09.805258 | controller |   msg: All assertions passed
2025-09-30 11:22:09.805289 | controller |
2025-09-30 11:22:09.805297 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-09-30 11:22:09.805304 | controller | Tuesday 30 September 2025  11:22:09 +0000 (0:00:00.040)       0:00:30.130 *****
2025-09-30 11:22:09.805313 | controller | ok: [instance]
2025-09-30 11:22:09.845839 | controller |
2025-09-30 11:22:09.845864 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-09-30 11:22:09.845871 | controller | Tuesday 30 September 2025  11:22:09 +0000 (0:00:00.216)       0:00:30.346 *****
2025-09-30 11:22:09.845880 | controller | ok: [instance]
2025-09-30 11:22:10.435021 | controller |
2025-09-30 11:22:10.435070 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-09-30 11:22:10.435080 | controller | Tuesday 30 September 2025  11:22:09 +0000 (0:00:00.040)       0:00:30.386 *****
2025-09-30 11:22:10.435092 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/02_ci_data.yaml)
2025-09-30 11:22:10.435120 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/03_user_data_b64.yaml)
2025-09-30 11:22:10.435128 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/04_user_data.yaml)
2025-09-30 11:22:10.435139 | controller |
2025-09-30 11:22:10.435149 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-09-30 11:22:10.591043 | controller | Tuesday 30 September 2025  11:22:10 +0000 (0:00:00.589)       0:00:30.975 *****
2025-09-30 11:22:10.591093 | controller | ok: [instance] => (item=02_ci_data.yaml)
2025-09-30 11:22:10.802012 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-09-30 11:22:10.802044 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-09-30 11:22:10.802065 | controller |
2025-09-30 11:22:10.802072 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-09-30 11:22:10.802078 | controller | Tuesday 30 September 2025  11:22:10 +0000 (0:00:00.155)       0:00:31.131 *****
2025-09-30 11:22:10.802087 | controller | changed: [instance]
2025-09-30 11:22:11.162546 | controller |
2025-09-30 11:22:11.162577 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-09-30 11:22:11.162585 | controller | Tuesday 30 September 2025  11:22:10 +0000 (0:00:00.211)       0:00:31.343 *****
2025-09-30 11:22:11.162595 | controller | changed: [instance]
2025-09-30 11:22:11.401088 | controller |
2025-09-30 11:22:11.401117 | controller | TASK [kustomize_deploy : Copy generated values for examples/va/hci/edpm-pre-ceph/nodeset] ***
2025-09-30 11:22:11.401125 | controller | Tuesday 30 September 2025  11:22:11 +0000 (0:00:00.360)       0:00:31.703 *****
2025-09-30 11:22:11.401135 | controller | changed: [instance] => (item=edpm-nodeset-values)
2025-09-30 11:22:11.439824 | controller |
2025-09-30 11:22:11.439846 | controller | TASK [kustomize_deploy : Stop before building kustomization if requested] ******
2025-09-30 11:22:11.439853 | controller | Tuesday 30 September 2025  11:22:11 +0000 (0:00:00.238)       0:00:31.942 *****
2025-09-30 11:22:11.439863 | controller | skipping: [instance]
2025-09-30 11:22:11.737770 | controller |
2025-09-30 11:22:11.737801 | controller | TASK [kustomize_deploy : Build kustomized content for examples/va/hci/edpm-pre-ceph/nodeset] ***
2025-09-30 11:22:11.737810 | controller | Tuesday 30 September 2025  11:22:11 +0000 (0:00:00.038)       0:00:31.981 *****
2025-09-30 11:22:11.737819 | controller | changed: [instance]
2025-09-30 11:22:12.096618 | controller |
2025-09-30 11:22:12.096649 | controller | TASK [kustomize_deploy : Output kustomize build in final file] *****************
2025-09-30 11:22:12.096657 | controller | Tuesday 30 September 2025  11:22:11 +0000 (0:00:00.297)       0:00:32.278 *****
2025-09-30 11:22:12.096667 | controller | changed: [instance]
2025-09-30 11:22:12.316030 | controller |
2025-09-30 11:22:12.316077 | controller | TASK [kustomize_deploy : Store kustomized content in artifacts for examples/va/hci/edpm-pre-ceph/nodeset] ***
2025-09-30 11:22:12.316086 | controller | Tuesday 30 September 2025  11:22:12 +0000 (0:00:00.358)       0:00:32.637 *****
2025-09-30 11:22:12.316096 | controller | changed: [instance]
2025-09-30 11:22:12.316978 | controller |
2025-09-30 11:22:12.317017 | controller | TASK [kustomize_deploy : Stop after building kustomization if requested] *******
2025-09-30 11:22:12.352969 | controller | Tuesday 30 September 2025  11:22:12 +0000 (0:00:00.217)       0:00:32.855 *****
2025-09-30 11:22:12.353009 | controller | skipping: [instance]
2025-09-30 11:22:12.390012 | controller |
2025-09-30 11:22:12.390063 | controller | TASK [kustomize_deploy : Apply generated content for examples/va/hci/edpm-pre-ceph/nodeset] ***
2025-09-30 11:22:12.390074 | controller | Tuesday 30 September 2025  11:22:12 +0000 (0:00:00.038)       0:00:32.894 *****
2025-09-30 11:22:12.390086 | controller | skipping: [instance]
2025-09-30 11:22:12.390209 | controller |
2025-09-30 11:22:12.390220 | controller | TASK [kustomize_deploy : Run Wait Conditions for examples/va/hci/edpm-pre-ceph/nodeset] ***
2025-09-30 11:22:12.427728 | controller | Tuesday 30 September 2025  11:22:12 +0000 (0:00:00.037)       0:00:32.931 *****
2025-09-30 11:22:12.427770 | controller | skipping: [instance] => (item=oc -n openstack wait osdpns openstack-edpm --for condition=SetupReady --timeout=10m)
2025-09-30 11:22:12.427871 | controller | skipping: [instance]
2025-09-30 11:22:12.428027 | controller |
2025-09-30 11:22:12.428199 | controller | TASK [kustomize_deploy : Stop after applying CRs if requested] *****************
2025-09-30 11:22:12.428338 | controller | Tuesday 30 September 2025  11:22:12 +0000 (0:00:00.037)       0:00:32.969 *****
2025-09-30 11:22:12.465027 | controller | skipping: [instance]
2025-09-30 11:22:12.465155 | controller |
2025-09-30 11:22:12.465285 | controller | TASK [Executing post_stage hooks for examples/va/hci/edpm-pre-ceph/nodeset] ****
2025-09-30 11:22:12.465403 | controller | Tuesday 30 September 2025  11:22:12 +0000 (0:00:00.037)       0:00:33.006 *****
2025-09-30 11:22:12.509190 | controller | skipping: [instance]
2025-09-30 11:22:12.509314 | controller |
2025-09-30 11:22:12.509442 | controller | TASK [kustomize_deploy : Assert mandatory bits are defined] ********************
2025-09-30 11:22:12.509564 | controller | Tuesday 30 September 2025  11:22:12 +0000 (0:00:00.044)       0:00:33.050 *****
2025-09-30 11:22:12.546591 | controller | ok: [instance] => changed=false
2025-09-30 11:22:12.570031 | controller |   msg: All assertions passed
2025-09-30 11:22:12.570081 | controller |
2025-09-30 11:22:12.570089 | controller | TASK [kustomize_deploy : Check custom wait conditions] *************************
2025-09-30 11:22:12.570095 | controller | Tuesday 30 September 2025  11:22:12 +0000 (0:00:00.037)       0:00:33.087 *****
2025-09-30 11:22:12.570106 | controller | skipping: [instance]
2025-09-30 11:22:12.598618 | controller |
2025-09-30 11:22:12.598650 | controller | TASK [kustomize_deploy : Check builtin wait conditions] ************************
2025-09-30 11:22:12.598658 | controller | Tuesday 30 September 2025  11:22:12 +0000 (0:00:00.023)       0:00:33.111 *****
2025-09-30 11:22:12.598669 | controller | ok: [instance] => (item=oc -n openstack wait osdpns openstack-edpm --for condition=Ready --timeout=30m)
2025-09-30 11:22:12.598692 | controller |
2025-09-30 11:22:12.598807 | controller | TASK [kustomize_deploy : Check wait_conditions validation result] **************
2025-09-30 11:22:12.598908 | controller | Tuesday 30 September 2025  11:22:12 +0000 (0:00:00.028)       0:00:33.140 *****
2025-09-30 11:22:12.620648 | controller | skipping: [instance]
2025-09-30 11:22:12.620724 | controller |
2025-09-30 11:22:12.620829 | controller | TASK [kustomize_deploy : Set stage_wait_conditions fact] ***********************
2025-09-30 11:22:12.620950 | controller | Tuesday 30 September 2025  11:22:12 +0000 (0:00:00.022)       0:00:33.162 *****
2025-09-30 11:22:12.647947 | controller | ok: [instance]
2025-09-30 11:22:12.648016 | controller |
2025-09-30 11:22:12.648137 | controller | TASK [kustomize_deploy : Ensure source files exists] ***************************
2025-09-30 11:22:12.648234 | controller | Tuesday 30 September 2025  11:22:12 +0000 (0:00:00.027)       0:00:33.189 *****
2025-09-30 11:22:12.866173 | controller | ok: [instance] => (item={'name': 'edpm-deployment-values', 'src_file': 'values.yaml'})
2025-09-30 11:22:12.866267 | controller |
2025-09-30 11:22:12.866383 | controller | TASK [kustomize_deploy : Assert source files exist] ****************************
2025-09-30 11:22:12.866486 | controller | Tuesday 30 September 2025  11:22:12 +0000 (0:00:00.217)       0:00:33.407 *****
2025-09-30 11:22:12.922632 | controller | ok: [instance] => (item=values.yaml)
2025-09-30 11:22:12.922739 | controller |
2025-09-30 11:22:12.922869 | controller | TASK [Executing pre_stage hooks for examples/va/hci/edpm-pre-ceph/deployment] ***
2025-09-30 11:22:12.923011 | controller | Tuesday 30 September 2025  11:22:12 +0000 (0:00:00.056)       0:00:33.463 *****
2025-09-30 11:22:12.961386 | controller | skipping: [instance]
2025-09-30 11:22:12.961479 | controller |
2025-09-30 11:22:12.961589 | controller | TASK [Generate values.yaml for examples/va/hci/edpm-pre-ceph/deployment] *******
2025-09-30 11:22:12.961774 | controller | Tuesday 30 September 2025  11:22:12 +0000 (0:00:00.038)       0:00:33.502 *****
2025-09-30 11:22:13.036110 | controller |
2025-09-30 11:22:13.036174 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-09-30 11:22:13.036276 | controller | Tuesday 30 September 2025  11:22:13 +0000 (0:00:00.074)       0:00:33.577 *****
2025-09-30 11:22:13.092118 | 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-30 11:22:13.092225 | controller |
2025-09-30 11:22:13.092341 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-09-30 11:22:13.092445 | controller | Tuesday 30 September 2025  11:22:13 +0000 (0:00:00.055)       0:00:33.633 *****
2025-09-30 11:22:13.136417 | controller | ok: [instance] => changed=false
2025-09-30 11:22:13.136516 | controller |   msg: All assertions passed
2025-09-30 11:22:13.136612 | controller |
2025-09-30 11:22:13.136731 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-09-30 11:22:13.136828 | controller | Tuesday 30 September 2025  11:22:13 +0000 (0:00:00.044)       0:00:33.677 *****
2025-09-30 11:22:13.343223 | controller | ok: [instance]
2025-09-30 11:22:13.343312 | controller |
2025-09-30 11:22:13.343421 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-09-30 11:22:13.343525 | controller | Tuesday 30 September 2025  11:22:13 +0000 (0:00:00.206)       0:00:33.884 *****
2025-09-30 11:22:13.388580 | controller | ok: [instance] => changed=false
2025-09-30 11:22:13.388677 | controller |   msg: All assertions passed
2025-09-30 11:22:13.388777 | controller |
2025-09-30 11:22:13.388890 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-09-30 11:22:13.389016 | controller | Tuesday 30 September 2025  11:22:13 +0000 (0:00:00.045)       0:00:33.929 *****
2025-09-30 11:22:13.590642 | controller | ok: [instance]
2025-09-30 11:22:13.590727 | controller |
2025-09-30 11:22:13.590835 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-09-30 11:22:13.590961 | controller | Tuesday 30 September 2025  11:22:13 +0000 (0:00:00.202)       0:00:34.131 *****
2025-09-30 11:22:13.758096 | controller | ok: [instance]
2025-09-30 11:22:13.758177 | controller |
2025-09-30 11:22:13.758285 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-09-30 11:22:13.758385 | controller | Tuesday 30 September 2025  11:22:13 +0000 (0:00:00.167)       0:00:34.299 *****
2025-09-30 11:22:13.798083 | controller | skipping: [instance]
2025-09-30 11:22:13.798177 | controller |
2025-09-30 11:22:13.798274 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-09-30 11:22:13.798368 | controller | Tuesday 30 September 2025  11:22:13 +0000 (0:00:00.040)       0:00:34.339 *****
2025-09-30 11:22:14.005764 | controller | changed: [instance]
2025-09-30 11:22:14.005837 | controller |
2025-09-30 11:22:14.005946 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-09-30 11:22:14.006058 | controller | Tuesday 30 September 2025  11:22:14 +0000 (0:00:00.207)       0:00:34.547 *****
2025-09-30 11:22:14.054317 | controller | skipping: [instance]
2025-09-30 11:22:14.054407 | controller |
2025-09-30 11:22:14.054509 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-09-30 11:22:14.054605 | controller | Tuesday 30 September 2025  11:22:14 +0000 (0:00:00.048)       0:00:34.595 *****
2025-09-30 11:22:14.108375 | controller | ok: [instance]
2025-09-30 11:22:14.108460 | controller |
2025-09-30 11:22:14.108560 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-09-30 11:22:14.108658 | controller | Tuesday 30 September 2025  11:22:14 +0000 (0:00:00.054)       0:00:34.649 *****
2025-09-30 11:22:14.472988 | controller | changed: [instance]
2025-09-30 11:22:14.473099 | controller |
2025-09-30 11:22:14.473216 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-09-30 11:22:14.473321 | controller | Tuesday 30 September 2025  11:22:14 +0000 (0:00:00.364)       0:00:35.014 *****
2025-09-30 11:22:14.829499 | controller | changed: [instance]
2025-09-30 11:22:14.829567 | controller |
2025-09-30 11:22:14.829685 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-09-30 11:22:14.829784 | controller | Tuesday 30 September 2025  11:22:14 +0000 (0:00:00.356)       0:00:35.370 *****
2025-09-30 11:22:15.180086 | controller | changed: [instance]
2025-09-30 11:22:15.180162 | controller |
2025-09-30 11:22:15.180265 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-09-30 11:22:15.180361 | controller | Tuesday 30 September 2025  11:22:15 +0000 (0:00:00.350)       0:00:35.721 *****
2025-09-30 11:22:15.237060 | 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-30 11:22:15.237168 | controller |
2025-09-30 11:22:15.237290 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-09-30 11:22:15.237416 | controller | Tuesday 30 September 2025  11:22:15 +0000 (0:00:00.057)       0:00:35.778 *****
2025-09-30 11:22:15.278642 | controller | ok: [instance] => changed=false
2025-09-30 11:22:15.278754 | controller |   msg: All assertions passed
2025-09-30 11:22:15.278842 | controller |
2025-09-30 11:22:15.278959 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-09-30 11:22:15.279087 | controller | Tuesday 30 September 2025  11:22:15 +0000 (0:00:00.041)       0:00:35.820 *****
2025-09-30 11:22:15.485091 | controller | ok: [instance]
2025-09-30 11:22:15.485421 | controller |
2025-09-30 11:22:15.524907 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-09-30 11:22:15.524942 | controller | Tuesday 30 September 2025  11:22:15 +0000 (0:00:00.206)       0:00:36.026 *****
2025-09-30 11:22:15.524956 | controller | ok: [instance]
2025-09-30 11:22:15.917028 | controller |
2025-09-30 11:22:15.917059 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-09-30 11:22:15.917067 | controller | Tuesday 30 September 2025  11:22:15 +0000 (0:00:00.039)       0:00:36.066 *****
2025-09-30 11:22:15.917077 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-deployment-values/03_user_data_b64.yaml)
2025-09-30 11:22:15.917508 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-deployment-values/04_user_data.yaml)
2025-09-30 11:22:15.997753 | controller |
2025-09-30 11:22:15.997779 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-09-30 11:22:15.997786 | controller | Tuesday 30 September 2025  11:22:15 +0000 (0:00:00.392)       0:00:36.458 *****
2025-09-30 11:22:15.997795 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-09-30 11:22:16.206739 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-09-30 11:22:16.206770 | controller |
2025-09-30 11:22:16.206778 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-09-30 11:22:16.206784 | controller | Tuesday 30 September 2025  11:22:15 +0000 (0:00:00.080)       0:00:36.538 *****
2025-09-30 11:22:16.206799 | controller | changed: [instance]
2025-09-30 11:22:16.571281 | controller |
2025-09-30 11:22:16.571310 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-09-30 11:22:16.571318 | controller | Tuesday 30 September 2025  11:22:16 +0000 (0:00:00.208)       0:00:36.747 *****
2025-09-30 11:22:16.571328 | controller | changed: [instance]
2025-09-30 11:22:16.832068 | controller |
2025-09-30 11:22:16.832104 | controller | TASK [kustomize_deploy : Copy generated values for examples/va/hci/edpm-pre-ceph/deployment] ***
2025-09-30 11:22:16.832118 | controller | Tuesday 30 September 2025  11:22:16 +0000 (0:00:00.364)       0:00:37.112 *****
2025-09-30 11:22:16.832131 | controller | changed: [instance] => (item=edpm-deployment-values)
2025-09-30 11:22:16.832159 | controller |
2025-09-30 11:22:16.832169 | controller | TASK [kustomize_deploy : Stop before building kustomization if requested] ******
2025-09-30 11:22:16.832325 | controller | Tuesday 30 September 2025  11:22:16 +0000 (0:00:00.260)       0:00:37.373 *****
2025-09-30 11:22:16.870820 | controller | skipping: [instance]
2025-09-30 11:22:17.160979 | controller |
2025-09-30 11:22:17.161011 | controller | TASK [kustomize_deploy : Build kustomized content for examples/va/hci/edpm-pre-ceph/deployment] ***
2025-09-30 11:22:17.161019 | controller | Tuesday 30 September 2025  11:22:16 +0000 (0:00:00.038)       0:00:37.411 *****
2025-09-30 11:22:17.161029 | controller | changed: [instance]
2025-09-30 11:22:17.523589 | controller |
2025-09-30 11:22:17.523620 | controller | TASK [kustomize_deploy : Output kustomize build in final file] *****************
2025-09-30 11:22:17.523628 | controller | Tuesday 30 September 2025  11:22:17 +0000 (0:00:00.290)       0:00:37.701 *****
2025-09-30 11:22:17.523638 | controller | changed: [instance]
2025-09-30 11:22:17.737626 | controller |
2025-09-30 11:22:17.737657 | controller | TASK [kustomize_deploy : Store kustomized content in artifacts for examples/va/hci/edpm-pre-ceph/deployment] ***
2025-09-30 11:22:17.737665 | controller | Tuesday 30 September 2025  11:22:17 +0000 (0:00:00.362)       0:00:38.064 *****
2025-09-30 11:22:17.737675 | controller | changed: [instance]
2025-09-30 11:22:17.781890 | controller |
2025-09-30 11:22:17.781955 | controller | TASK [kustomize_deploy : Stop after building kustomization if requested] *******
2025-09-30 11:22:17.781971 | controller | Tuesday 30 September 2025  11:22:17 +0000 (0:00:00.213)       0:00:38.278 *****
2025-09-30 11:22:17.781982 | controller | skipping: [instance]
2025-09-30 11:22:17.825808 | controller |
2025-09-30 11:22:17.825839 | controller | TASK [kustomize_deploy : Apply generated content for examples/va/hci/edpm-pre-ceph/deployment] ***
2025-09-30 11:22:17.825852 | controller | Tuesday 30 September 2025  11:22:17 +0000 (0:00:00.044)       0:00:38.322 *****
2025-09-30 11:22:17.825862 | controller | skipping: [instance]
2025-09-30 11:22:17.870898 | controller |
2025-09-30 11:22:17.870960 | controller | TASK [kustomize_deploy : Run Wait Conditions for examples/va/hci/edpm-pre-ceph/deployment] ***
2025-09-30 11:22:17.870971 | controller | Tuesday 30 September 2025  11:22:17 +0000 (0:00:00.043)       0:00:38.366 *****
2025-09-30 11:22:17.870982 | controller | skipping: [instance] => (item=oc -n openstack wait osdpns openstack-edpm --for condition=Ready --timeout=30m)
2025-09-30 11:22:17.913854 | controller | skipping: [instance]
2025-09-30 11:22:17.913886 | controller |
2025-09-30 11:22:17.913893 | controller | TASK [kustomize_deploy : Stop after applying CRs if requested] *****************
2025-09-30 11:22:17.913899 | controller | Tuesday 30 September 2025  11:22:17 +0000 (0:00:00.044)       0:00:38.411 *****
2025-09-30 11:22:17.913908 | controller | skipping: [instance]
2025-09-30 11:22:17.961525 | controller |
2025-09-30 11:22:17.961558 | controller | TASK [Executing post_stage hooks for examples/va/hci/edpm-pre-ceph/deployment] ***
2025-09-30 11:22:17.961566 | controller | Tuesday 30 September 2025  11:22:17 +0000 (0:00:00.042)       0:00:38.454 *****
2025-09-30 11:22:17.961576 | controller | skipping: [instance]
2025-09-30 11:22:18.001850 | controller |
2025-09-30 11:22:18.001882 | controller | TASK [kustomize_deploy : Assert mandatory bits are defined] ********************
2025-09-30 11:22:18.001889 | controller | Tuesday 30 September 2025  11:22:17 +0000 (0:00:00.048)       0:00:38.502 *****
2025-09-30 11:22:18.001899 | controller | ok: [instance] => changed=false
2025-09-30 11:22:18.030542 | controller |   msg: All assertions passed
2025-09-30 11:22:18.030578 | controller |
2025-09-30 11:22:18.030587 | controller | TASK [kustomize_deploy : Check custom wait conditions] *************************
2025-09-30 11:22:18.030595 | controller | Tuesday 30 September 2025  11:22:17 +0000 (0:00:00.040)       0:00:38.542 *****
2025-09-30 11:22:18.030607 | controller | skipping: [instance]
2025-09-30 11:22:18.062559 | controller |
2025-09-30 11:22:18.062591 | controller | TASK [kustomize_deploy : Check builtin wait conditions] ************************
2025-09-30 11:22:18.062599 | controller | Tuesday 30 September 2025  11:22:18 +0000 (0:00:00.028)       0:00:38.571 *****
2025-09-30 11:22:18.062609 | controller | ok: [instance] => (item=oc -n openstack wait osdpns openstack-edpm --for condition=SetupReady --timeout=10m)
2025-09-30 11:22:18.088346 | controller |
2025-09-30 11:22:18.088376 | controller | TASK [kustomize_deploy : Check wait_conditions validation result] **************
2025-09-30 11:22:18.088383 | controller | Tuesday 30 September 2025  11:22:18 +0000 (0:00:00.031)       0:00:38.603 *****
2025-09-30 11:22:18.088393 | controller | skipping: [instance]
2025-09-30 11:22:18.120648 | controller |
2025-09-30 11:22:18.120691 | controller | TASK [kustomize_deploy : Set stage_wait_conditions fact] ***********************
2025-09-30 11:22:18.120701 | controller | Tuesday 30 September 2025  11:22:18 +0000 (0:00:00.025)       0:00:38.629 *****
2025-09-30 11:22:18.120712 | controller | ok: [instance]
2025-09-30 11:22:18.519442 | controller |
2025-09-30 11:22:18.519472 | controller | TASK [kustomize_deploy : Ensure source files exists] ***************************
2025-09-30 11:22:18.519479 | controller | Tuesday 30 September 2025  11:22:18 +0000 (0:00:00.032)       0:00:38.661 *****
2025-09-30 11:22:18.519489 | controller | ok: [instance] => (item={'name': 'service-values', 'src_file': 'service-values.yaml'})
2025-09-30 11:22:18.603077 | controller | ok: [instance] => (item={'name': 'edpm-nodeset-values-post-ceph', 'src_file': 'values.yaml'})
2025-09-30 11:22:18.603103 | controller |
2025-09-30 11:22:18.603117 | controller | TASK [kustomize_deploy : Assert source files exist] ****************************
2025-09-30 11:22:18.603124 | controller | Tuesday 30 September 2025  11:22:18 +0000 (0:00:00.398)       0:00:39.060 *****
2025-09-30 11:22:18.603133 | controller | ok: [instance] => (item=service-values.yaml)
2025-09-30 11:22:18.643583 | controller | ok: [instance] => (item=values.yaml)
2025-09-30 11:22:18.643604 | controller |
2025-09-30 11:22:18.643612 | controller | TASK [Executing pre_stage hooks for examples/va/hci] ***************************
2025-09-30 11:22:18.643618 | controller | Tuesday 30 September 2025  11:22:18 +0000 (0:00:00.083)       0:00:39.144 *****
2025-09-30 11:22:18.643628 | controller | skipping: [instance]
2025-09-30 11:22:18.755845 | controller |
2025-09-30 11:22:18.755867 | controller | TASK [Generate values.yaml for examples/va/hci] ********************************
2025-09-30 11:22:18.755879 | controller | Tuesday 30 September 2025  11:22:18 +0000 (0:00:00.040)       0:00:39.184 *****
2025-09-30 11:22:18.755888 | controller |
2025-09-30 11:22:18.813548 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-09-30 11:22:18.813568 | controller | Tuesday 30 September 2025  11:22:18 +0000 (0:00:00.112)       0:00:39.297 *****
2025-09-30 11:22:18.813579 | 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-30 11:22:18.857302 | controller |
2025-09-30 11:22:18.857321 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-09-30 11:22:18.857328 | controller | Tuesday 30 September 2025  11:22:18 +0000 (0:00:00.057)       0:00:39.354 *****
2025-09-30 11:22:18.857338 | controller | ok: [instance] => changed=false
2025-09-30 11:22:19.063658 | controller |   msg: All assertions passed
2025-09-30 11:22:19.063682 | controller |
2025-09-30 11:22:19.063690 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-09-30 11:22:19.063696 | controller | Tuesday 30 September 2025  11:22:18 +0000 (0:00:00.043)       0:00:39.398 *****
2025-09-30 11:22:19.063705 | controller | ok: [instance]
2025-09-30 11:22:19.106983 | controller |
2025-09-30 11:22:19.107005 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-09-30 11:22:19.107013 | controller | Tuesday 30 September 2025  11:22:19 +0000 (0:00:00.206)       0:00:39.604 *****
2025-09-30 11:22:19.107022 | controller | ok: [instance] => changed=false
2025-09-30 11:22:19.107466 | controller |   msg: All assertions passed
2025-09-30 11:22:19.301997 | controller |
2025-09-30 11:22:19.302024 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-09-30 11:22:19.302032 | controller | Tuesday 30 September 2025  11:22:19 +0000 (0:00:00.043)       0:00:39.648 *****
2025-09-30 11:22:19.302042 | controller | ok: [instance]
2025-09-30 11:22:19.302362 | controller |
2025-09-30 11:22:19.497393 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-09-30 11:22:19.497423 | controller | Tuesday 30 September 2025  11:22:19 +0000 (0:00:00.194)       0:00:39.843 *****
2025-09-30 11:22:19.497435 | controller | ok: [instance]
2025-09-30 11:22:19.537405 | controller |
2025-09-30 11:22:19.537431 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-09-30 11:22:19.537438 | controller | Tuesday 30 September 2025  11:22:19 +0000 (0:00:00.195)       0:00:40.038 *****
2025-09-30 11:22:19.537448 | controller | skipping: [instance]
2025-09-30 11:22:19.743091 | controller |
2025-09-30 11:22:19.743118 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-09-30 11:22:19.743126 | controller | Tuesday 30 September 2025  11:22:19 +0000 (0:00:00.040)       0:00:40.078 *****
2025-09-30 11:22:19.743135 | controller | ok: [instance]
2025-09-30 11:22:19.791679 | controller |
2025-09-30 11:22:19.791704 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-09-30 11:22:19.791712 | controller | Tuesday 30 September 2025  11:22:19 +0000 (0:00:00.205)       0:00:40.284 *****
2025-09-30 11:22:19.791722 | controller | skipping: [instance]
2025-09-30 11:22:19.845537 | controller |
2025-09-30 11:22:19.845560 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-09-30 11:22:19.845567 | controller | Tuesday 30 September 2025  11:22:19 +0000 (0:00:00.048)       0:00:40.332 *****
2025-09-30 11:22:19.845576 | controller | ok: [instance]
2025-09-30 11:22:20.210071 | controller |
2025-09-30 11:22:20.210100 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-09-30 11:22:20.210108 | controller | Tuesday 30 September 2025  11:22:19 +0000 (0:00:00.053)       0:00:40.386 *****
2025-09-30 11:22:20.210118 | controller | ok: [instance]
2025-09-30 11:22:20.560369 | controller |
2025-09-30 11:22:20.560398 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-09-30 11:22:20.560407 | controller | Tuesday 30 September 2025  11:22:20 +0000 (0:00:00.364)       0:00:40.751 *****
2025-09-30 11:22:20.560417 | controller | ok: [instance]
2025-09-30 11:22:20.929774 | controller |
2025-09-30 11:22:20.929803 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-09-30 11:22:20.929821 | controller | Tuesday 30 September 2025  11:22:20 +0000 (0:00:00.350)       0:00:41.101 *****
2025-09-30 11:22:20.929832 | controller | changed: [instance]
2025-09-30 11:22:20.984063 | controller |
2025-09-30 11:22:20.984103 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-09-30 11:22:20.984113 | controller | Tuesday 30 September 2025  11:22:20 +0000 (0:00:00.369)       0:00:41.470 *****
2025-09-30 11:22:20.984124 | 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-30 11:22:20.984153 | controller |
2025-09-30 11:22:20.984163 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-09-30 11:22:20.984195 | controller | Tuesday 30 September 2025  11:22:20 +0000 (0:00:00.054)       0:00:41.525 *****
2025-09-30 11:22:21.025010 | controller | ok: [instance] => changed=false
2025-09-30 11:22:21.025054 | controller |   msg: All assertions passed
2025-09-30 11:22:21.025066 | controller |
2025-09-30 11:22:21.025288 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-09-30 11:22:21.217747 | controller | Tuesday 30 September 2025  11:22:21 +0000 (0:00:00.040)       0:00:41.566 *****
2025-09-30 11:22:21.217781 | controller | ok: [instance]
2025-09-30 11:22:21.256144 | controller |
2025-09-30 11:22:21.256165 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-09-30 11:22:21.256172 | controller | Tuesday 30 September 2025  11:22:21 +0000 (0:00:00.192)       0:00:41.758 *****
2025-09-30 11:22:21.256182 | controller | ok: [instance]
2025-09-30 11:22:21.635168 | controller |
2025-09-30 11:22:21.635198 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-09-30 11:22:21.635206 | controller | Tuesday 30 September 2025  11:22:21 +0000 (0:00:00.038)       0:00:41.797 *****
2025-09-30 11:22:21.635217 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/service-values/03_user_data_b64.yaml)
2025-09-30 11:22:21.718027 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/service-values/04_user_data.yaml)
2025-09-30 11:22:21.718069 | controller |
2025-09-30 11:22:21.718078 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-09-30 11:22:21.718086 | controller | Tuesday 30 September 2025  11:22:21 +0000 (0:00:00.378)       0:00:42.176 *****
2025-09-30 11:22:21.718098 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-09-30 11:22:21.718127 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-09-30 11:22:21.718519 | controller |
2025-09-30 11:22:21.920586 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-09-30 11:22:21.920615 | controller | Tuesday 30 September 2025  11:22:21 +0000 (0:00:00.083)       0:00:42.259 *****
2025-09-30 11:22:21.920627 | controller | ok: [instance]
2025-09-30 11:22:22.313378 | controller |
2025-09-30 11:22:22.313408 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-09-30 11:22:22.313416 | controller | Tuesday 30 September 2025  11:22:21 +0000 (0:00:00.202)       0:00:42.461 *****
2025-09-30 11:22:22.313426 | controller | changed: [instance]
2025-09-30 11:22:22.394057 | controller |
2025-09-30 11:22:22.394088 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-09-30 11:22:22.394096 | controller | Tuesday 30 September 2025  11:22:22 +0000 (0:00:00.392)       0:00:42.854 *****
2025-09-30 11:22:22.394106 | 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-30 11:22:22.394131 | controller |
2025-09-30 11:22:22.394287 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-09-30 11:22:22.436210 | controller | Tuesday 30 September 2025  11:22:22 +0000 (0:00:00.080)       0:00:42.935 *****
2025-09-30 11:22:22.436241 | controller | ok: [instance] => changed=false
2025-09-30 11:22:22.624630 | controller |   msg: All assertions passed
2025-09-30 11:22:22.624658 | controller |
2025-09-30 11:22:22.624666 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-09-30 11:22:22.624672 | controller | Tuesday 30 September 2025  11:22:22 +0000 (0:00:00.041)       0:00:42.977 *****
2025-09-30 11:22:22.624686 | controller | ok: [instance]
2025-09-30 11:22:22.665844 | controller |
2025-09-30 11:22:22.665868 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-09-30 11:22:22.665875 | controller | Tuesday 30 September 2025  11:22:22 +0000 (0:00:00.188)       0:00:43.165 *****
2025-09-30 11:22:22.665885 | controller | ok: [instance] => changed=false
2025-09-30 11:22:22.851007 | controller |   msg: All assertions passed
2025-09-30 11:22:22.851035 | controller |
2025-09-30 11:22:22.851042 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-09-30 11:22:22.851048 | controller | Tuesday 30 September 2025  11:22:22 +0000 (0:00:00.041)       0:00:43.206 *****
2025-09-30 11:22:22.851058 | controller | ok: [instance]
2025-09-30 11:22:22.851126 | controller |
2025-09-30 11:22:22.851142 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-09-30 11:22:22.851151 | controller | Tuesday 30 September 2025  11:22:22 +0000 (0:00:00.185)       0:00:43.392 *****
2025-09-30 11:22:23.023591 | controller | ok: [instance]
2025-09-30 11:22:23.061299 | controller |
2025-09-30 11:22:23.061321 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-09-30 11:22:23.061329 | controller | Tuesday 30 September 2025  11:22:23 +0000 (0:00:00.172)       0:00:43.564 *****
2025-09-30 11:22:23.061339 | controller | skipping: [instance]
2025-09-30 11:22:23.258992 | controller |
2025-09-30 11:22:23.259020 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-09-30 11:22:23.259032 | controller | Tuesday 30 September 2025  11:22:23 +0000 (0:00:00.037)       0:00:43.602 *****
2025-09-30 11:22:23.259042 | controller | changed: [instance]
2025-09-30 11:22:23.259065 | controller |
2025-09-30 11:22:23.259279 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-09-30 11:22:23.304998 | controller | Tuesday 30 September 2025  11:22:23 +0000 (0:00:00.197)       0:00:43.800 *****
2025-09-30 11:22:23.305022 | controller | skipping: [instance]
2025-09-30 11:22:23.305264 | controller |
2025-09-30 11:22:23.305286 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-09-30 11:22:23.356912 | controller | Tuesday 30 September 2025  11:22:23 +0000 (0:00:00.046)       0:00:43.846 *****
2025-09-30 11:22:23.356956 | controller | ok: [instance]
2025-09-30 11:22:23.696453 | controller |
2025-09-30 11:22:23.696483 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-09-30 11:22:23.696491 | controller | Tuesday 30 September 2025  11:22:23 +0000 (0:00:00.051)       0:00:43.897 *****
2025-09-30 11:22:23.696501 | controller | changed: [instance]
2025-09-30 11:22:24.039582 | controller |
2025-09-30 11:22:24.039612 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-09-30 11:22:24.039620 | controller | Tuesday 30 September 2025  11:22:23 +0000 (0:00:00.339)       0:00:44.237 *****
2025-09-30 11:22:24.039630 | controller | changed: [instance]
2025-09-30 11:22:24.377045 | controller |
2025-09-30 11:22:24.377075 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-09-30 11:22:24.377083 | controller | Tuesday 30 September 2025  11:22:24 +0000 (0:00:00.343)       0:00:44.580 *****
2025-09-30 11:22:24.377092 | controller | changed: [instance]
2025-09-30 11:22:24.432616 | controller |
2025-09-30 11:22:24.432647 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-09-30 11:22:24.432656 | controller | Tuesday 30 September 2025  11:22:24 +0000 (0:00:00.337)       0:00:44.918 *****
2025-09-30 11:22:24.432668 | 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-30 11:22:24.472727 | controller |
2025-09-30 11:22:24.472768 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-09-30 11:22:24.472777 | controller | Tuesday 30 September 2025  11:22:24 +0000 (0:00:00.055)       0:00:44.973 *****
2025-09-30 11:22:24.472787 | controller | ok: [instance] => changed=false
2025-09-30 11:22:24.669447 | controller |   msg: All assertions passed
2025-09-30 11:22:24.669477 | controller |
2025-09-30 11:22:24.669485 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-09-30 11:22:24.669491 | controller | Tuesday 30 September 2025  11:22:24 +0000 (0:00:00.040)       0:00:45.013 *****
2025-09-30 11:22:24.669505 | controller | ok: [instance]
2025-09-30 11:22:24.707720 | controller |
2025-09-30 11:22:24.707759 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-09-30 11:22:24.707767 | controller | Tuesday 30 September 2025  11:22:24 +0000 (0:00:00.196)       0:00:45.210 *****
2025-09-30 11:22:24.707777 | controller | ok: [instance]
2025-09-30 11:22:25.094996 | controller |
2025-09-30 11:22:25.095027 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-09-30 11:22:25.095035 | controller | Tuesday 30 September 2025  11:22:24 +0000 (0:00:00.038)       0:00:45.248 *****
2025-09-30 11:22:25.095045 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values-post-ceph/03_user_data_b64.yaml)
2025-09-30 11:22:25.095068 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values-post-ceph/04_user_data.yaml)
2025-09-30 11:22:25.095331 | controller |
2025-09-30 11:22:25.177421 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-09-30 11:22:25.177451 | controller | Tuesday 30 September 2025  11:22:25 +0000 (0:00:00.387)       0:00:45.636 *****
2025-09-30 11:22:25.177462 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-09-30 11:22:25.378979 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-09-30 11:22:25.379009 | controller |
2025-09-30 11:22:25.379016 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-09-30 11:22:25.379022 | controller | Tuesday 30 September 2025  11:22:25 +0000 (0:00:00.081)       0:00:45.718 *****
2025-09-30 11:22:25.379032 | controller | changed: [instance]
2025-09-30 11:22:25.742540 | controller |
2025-09-30 11:22:25.742568 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-09-30 11:22:25.742576 | controller | Tuesday 30 September 2025  11:22:25 +0000 (0:00:00.201)       0:00:45.919 *****
2025-09-30 11:22:25.742586 | controller | changed: [instance]
2025-09-30 11:22:26.212836 | controller |
2025-09-30 11:22:26.212879 | controller | TASK [kustomize_deploy : Copy generated values for examples/va/hci] ************
2025-09-30 11:22:26.212887 | controller | Tuesday 30 September 2025  11:22:25 +0000 (0:00:00.363)       0:00:46.283 *****
2025-09-30 11:22:26.212899 | controller | changed: [instance] => (item=service-values)
2025-09-30 11:22:26.255068 | controller | changed: [instance] => (item=edpm-nodeset-values-post-ceph)
2025-09-30 11:22:26.255110 | controller |
2025-09-30 11:22:26.255121 | controller | TASK [kustomize_deploy : Stop before building kustomization if requested] ******
2025-09-30 11:22:26.255129 | controller | Tuesday 30 September 2025  11:22:26 +0000 (0:00:00.465)       0:00:46.749 *****
2025-09-30 11:22:26.255146 | controller | skipping: [instance]
2025-09-30 11:22:26.255154 | controller |
2025-09-30 11:22:26.255161 | controller | TASK [kustomize_deploy : Build kustomized content for examples/va/hci] *********
2025-09-30 11:22:26.255170 | controller | Tuesday 30 September 2025  11:22:26 +0000 (0:00:00.046)       0:00:46.796 *****
2025-09-30 11:22:26.668674 | controller | changed: [instance]
2025-09-30 11:22:27.019522 | controller |
2025-09-30 11:22:27.019556 | controller | TASK [kustomize_deploy : Output kustomize build in final file] *****************
2025-09-30 11:22:27.019564 | controller | Tuesday 30 September 2025  11:22:26 +0000 (0:00:00.413)       0:00:47.209 *****
2025-09-30 11:22:27.019575 | controller | changed: [instance]
2025-09-30 11:22:27.230893 | controller |
2025-09-30 11:22:27.230940 | controller | TASK [kustomize_deploy : Store kustomized content in artifacts for examples/va/hci] ***
2025-09-30 11:22:27.230952 | controller | Tuesday 30 September 2025  11:22:27 +0000 (0:00:00.350)       0:00:47.560 *****
2025-09-30 11:22:27.230963 | controller | changed: [instance]
2025-09-30 11:22:27.267655 | controller |
2025-09-30 11:22:27.267692 | controller | TASK [kustomize_deploy : Stop after building kustomization if requested] *******
2025-09-30 11:22:27.267701 | controller | Tuesday 30 September 2025  11:22:27 +0000 (0:00:00.211)       0:00:47.771 *****
2025-09-30 11:22:27.267711 | controller | skipping: [instance]
2025-09-30 11:22:27.304858 | controller |
2025-09-30 11:22:27.304876 | controller | TASK [kustomize_deploy : Apply generated content for examples/va/hci] **********
2025-09-30 11:22:27.304883 | controller | Tuesday 30 September 2025  11:22:27 +0000 (0:00:00.036)       0:00:47.808 *****
2025-09-30 11:22:27.304897 | controller | skipping: [instance]
2025-09-30 11:22:27.343519 | controller |
2025-09-30 11:22:27.343541 | controller | TASK [kustomize_deploy : Run Wait Conditions for examples/va/hci] **************
2025-09-30 11:22:27.343548 | controller | Tuesday 30 September 2025  11:22:27 +0000 (0:00:00.037)       0:00:47.846 *****
2025-09-30 11:22:27.343557 | controller | skipping: [instance] => (item=oc -n openstack wait osdpns openstack-edpm --for condition=SetupReady --timeout=10m)
2025-09-30 11:22:27.380465 | controller | skipping: [instance]
2025-09-30 11:22:27.380493 | controller |
2025-09-30 11:22:27.380500 | controller | TASK [kustomize_deploy : Stop after applying CRs if requested] *****************
2025-09-30 11:22:27.380506 | controller | Tuesday 30 September 2025  11:22:27 +0000 (0:00:00.038)       0:00:47.884 *****
2025-09-30 11:22:27.380516 | controller | skipping: [instance]
2025-09-30 11:22:27.422888 | controller |
2025-09-30 11:22:27.422949 | controller | TASK [Executing post_stage hooks for examples/va/hci] **************************
2025-09-30 11:22:27.422960 | controller | Tuesday 30 September 2025  11:22:27 +0000 (0:00:00.036)       0:00:47.921 *****
2025-09-30 11:22:27.422970 | controller | skipping: [instance]
2025-09-30 11:22:27.459160 | controller |
2025-09-30 11:22:27.459191 | controller | TASK [kustomize_deploy : Assert mandatory bits are defined] ********************
2025-09-30 11:22:27.459201 | controller | Tuesday 30 September 2025  11:22:27 +0000 (0:00:00.042)       0:00:47.963 *****
2025-09-30 11:22:27.459212 | controller | ok: [instance] => changed=false
2025-09-30 11:22:27.481073 | controller |   msg: All assertions passed
2025-09-30 11:22:27.481100 | controller |
2025-09-30 11:22:27.481108 | controller | TASK [kustomize_deploy : Check custom wait conditions] *************************
2025-09-30 11:22:27.481116 | controller | Tuesday 30 September 2025  11:22:27 +0000 (0:00:00.036)       0:00:48.000 *****
2025-09-30 11:22:27.481126 | controller | skipping: [instance]
2025-09-30 11:22:27.481293 | controller |
2025-09-30 11:22:27.481461 | controller | TASK [kustomize_deploy : Check builtin wait conditions] ************************
2025-09-30 11:22:27.481613 | controller | Tuesday 30 September 2025  11:22:27 +0000 (0:00:00.022)       0:00:48.022 *****
2025-09-30 11:22:27.508488 | controller | ok: [instance] => (item=oc -n openstack wait osdpns openstack-edpm --for condition=Ready --timeout=80m)
2025-09-30 11:22:27.508662 | controller |
2025-09-30 11:22:27.508821 | controller | TASK [kustomize_deploy : Check wait_conditions validation result] **************
2025-09-30 11:22:27.508982 | controller | Tuesday 30 September 2025  11:22:27 +0000 (0:00:00.027)       0:00:48.049 *****
2025-09-30 11:22:27.529790 | controller | skipping: [instance]
2025-09-30 11:22:27.530003 | controller |
2025-09-30 11:22:27.530182 | controller | TASK [kustomize_deploy : Set stage_wait_conditions fact] ***********************
2025-09-30 11:22:27.530357 | controller | Tuesday 30 September 2025  11:22:27 +0000 (0:00:00.021)       0:00:48.071 *****
2025-09-30 11:22:27.556329 | controller | ok: [instance]
2025-09-30 11:22:27.556508 | controller |
2025-09-30 11:22:27.556692 | controller | TASK [kustomize_deploy : Ensure source files exists] ***************************
2025-09-30 11:22:27.556833 | controller | Tuesday 30 September 2025  11:22:27 +0000 (0:00:00.026)       0:00:48.097 *****
2025-09-30 11:22:27.765784 | controller | ok: [instance] => (item={'name': 'edpm-deployment-values-post-ceph', 'src_file': 'values.yaml'})
2025-09-30 11:22:27.821004 | controller |
2025-09-30 11:22:27.821035 | controller | TASK [kustomize_deploy : Assert source files exist] ****************************
2025-09-30 11:22:27.821043 | controller | Tuesday 30 September 2025  11:22:27 +0000 (0:00:00.208)       0:00:48.306 *****
2025-09-30 11:22:27.821053 | controller | ok: [instance] => (item=values.yaml)
2025-09-30 11:22:27.858847 | controller |
2025-09-30 11:22:27.858885 | controller | TASK [Executing pre_stage hooks for examples/va/hci/deployment] ****************
2025-09-30 11:22:27.858900 | controller | Tuesday 30 September 2025  11:22:27 +0000 (0:00:00.055)       0:00:48.362 *****
2025-09-30 11:22:27.858912 | controller | skipping: [instance]
2025-09-30 11:22:27.931514 | controller |
2025-09-30 11:22:27.931543 | controller | TASK [Generate values.yaml for examples/va/hci/deployment] *********************
2025-09-30 11:22:27.931551 | controller | Tuesday 30 September 2025  11:22:27 +0000 (0:00:00.037)       0:00:48.399 *****
2025-09-30 11:22:27.931561 | controller |
2025-09-30 11:22:27.984877 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-09-30 11:22:27.984912 | controller | Tuesday 30 September 2025  11:22:27 +0000 (0:00:00.072)       0:00:48.472 *****
2025-09-30 11:22:27.984941 | 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-30 11:22:28.027073 | controller |
2025-09-30 11:22:28.027100 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-09-30 11:22:28.027110 | controller | Tuesday 30 September 2025  11:22:27 +0000 (0:00:00.053)       0:00:48.525 *****
2025-09-30 11:22:28.027121 | controller | ok: [instance] => changed=false
2025-09-30 11:22:28.027305 | controller |   msg: All assertions passed
2025-09-30 11:22:28.027463 | controller |
2025-09-30 11:22:28.027613 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-09-30 11:22:28.027766 | controller | Tuesday 30 September 2025  11:22:28 +0000 (0:00:00.042)       0:00:48.568 *****
2025-09-30 11:22:28.250705 | controller | ok: [instance]
2025-09-30 11:22:28.294041 | controller |
2025-09-30 11:22:28.294072 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-09-30 11:22:28.294080 | controller | Tuesday 30 September 2025  11:22:28 +0000 (0:00:00.223)       0:00:48.791 *****
2025-09-30 11:22:28.294091 | controller | ok: [instance] => changed=false
2025-09-30 11:22:28.294120 | controller |   msg: All assertions passed
2025-09-30 11:22:28.294142 | controller |
2025-09-30 11:22:28.294374 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-09-30 11:22:28.491897 | controller | Tuesday 30 September 2025  11:22:28 +0000 (0:00:00.043)       0:00:48.835 *****
2025-09-30 11:22:28.491946 | controller | ok: [instance]
2025-09-30 11:22:28.669122 | controller |
2025-09-30 11:22:28.669165 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-09-30 11:22:28.669175 | controller | Tuesday 30 September 2025  11:22:28 +0000 (0:00:00.197)       0:00:49.032 *****
2025-09-30 11:22:28.669188 | controller | ok: [instance]
2025-09-30 11:22:28.709467 | controller |
2025-09-30 11:22:28.709500 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-09-30 11:22:28.709508 | controller | Tuesday 30 September 2025  11:22:28 +0000 (0:00:00.177)       0:00:49.209 *****
2025-09-30 11:22:28.709519 | controller | skipping: [instance]
2025-09-30 11:22:28.917016 | controller |
2025-09-30 11:22:28.917051 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-09-30 11:22:28.917059 | controller | Tuesday 30 September 2025  11:22:28 +0000 (0:00:00.040)       0:00:49.250 *****
2025-09-30 11:22:28.917070 | controller | changed: [instance]
2025-09-30 11:22:28.917094 | controller |
2025-09-30 11:22:28.917310 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-09-30 11:22:28.965550 | controller | Tuesday 30 September 2025  11:22:28 +0000 (0:00:00.207)       0:00:49.458 *****
2025-09-30 11:22:28.965582 | controller | skipping: [instance]
2025-09-30 11:22:29.019245 | controller |
2025-09-30 11:22:29.019275 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-09-30 11:22:29.019285 | controller | Tuesday 30 September 2025  11:22:28 +0000 (0:00:00.048)       0:00:49.506 *****
2025-09-30 11:22:29.019296 | controller | ok: [instance]
2025-09-30 11:22:29.378215 | controller |
2025-09-30 11:22:29.378244 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-09-30 11:22:29.378252 | controller | Tuesday 30 September 2025  11:22:29 +0000 (0:00:00.053)       0:00:49.560 *****
2025-09-30 11:22:29.378262 | controller | changed: [instance]
2025-09-30 11:22:29.731699 | controller |
2025-09-30 11:22:29.731729 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-09-30 11:22:29.731737 | controller | Tuesday 30 September 2025  11:22:29 +0000 (0:00:00.359)       0:00:49.919 *****
2025-09-30 11:22:29.731746 | controller | changed: [instance]
2025-09-30 11:22:30.086874 | controller |
2025-09-30 11:22:30.086905 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-09-30 11:22:30.086913 | controller | Tuesday 30 September 2025  11:22:29 +0000 (0:00:00.353)       0:00:50.272 *****
2025-09-30 11:22:30.086939 | controller | changed: [instance]
2025-09-30 11:22:30.142073 | controller |
2025-09-30 11:22:30.142119 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-09-30 11:22:30.142129 | controller | Tuesday 30 September 2025  11:22:30 +0000 (0:00:00.355)       0:00:50.627 *****
2025-09-30 11:22:30.142142 | 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-30 11:22:30.184733 | controller |
2025-09-30 11:22:30.184766 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-09-30 11:22:30.184778 | controller | Tuesday 30 September 2025  11:22:30 +0000 (0:00:00.055)       0:00:50.683 *****
2025-09-30 11:22:30.184789 | controller | ok: [instance] => changed=false
2025-09-30 11:22:30.392819 | controller |   msg: All assertions passed
2025-09-30 11:22:30.392849 | controller |
2025-09-30 11:22:30.392857 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-09-30 11:22:30.392863 | controller | Tuesday 30 September 2025  11:22:30 +0000 (0:00:00.042)       0:00:50.725 *****
2025-09-30 11:22:30.392873 | controller | ok: [instance]
2025-09-30 11:22:30.434379 | controller |
2025-09-30 11:22:30.434412 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-09-30 11:22:30.434420 | controller | Tuesday 30 September 2025  11:22:30 +0000 (0:00:00.207)       0:00:50.933 *****
2025-09-30 11:22:30.434431 | controller | ok: [instance]
2025-09-30 11:22:30.829054 | controller |
2025-09-30 11:22:30.829084 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-09-30 11:22:30.829092 | controller | Tuesday 30 September 2025  11:22:30 +0000 (0:00:00.041)       0:00:50.975 *****
2025-09-30 11:22:30.829102 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-deployment-values-post-ceph/03_user_data_b64.yaml)
2025-09-30 11:22:30.913391 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-deployment-values-post-ceph/04_user_data.yaml)
2025-09-30 11:22:30.913421 | controller |
2025-09-30 11:22:30.913428 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-09-30 11:22:30.913434 | controller | Tuesday 30 September 2025  11:22:30 +0000 (0:00:00.394)       0:00:51.369 *****
2025-09-30 11:22:30.913444 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-09-30 11:22:31.122194 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-09-30 11:22:31.122224 | controller |
2025-09-30 11:22:31.122231 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-09-30 11:22:31.122238 | controller | Tuesday 30 September 2025  11:22:30 +0000 (0:00:00.084)       0:00:51.454 *****
2025-09-30 11:22:31.122247 | controller | changed: [instance]
2025-09-30 11:22:31.486627 | controller |
2025-09-30 11:22:31.486658 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-09-30 11:22:31.486666 | controller | Tuesday 30 September 2025  11:22:31 +0000 (0:00:00.208)       0:00:51.663 *****
2025-09-30 11:22:31.486676 | controller | changed: [instance]
2025-09-30 11:22:31.721028 | controller |
2025-09-30 11:22:31.721061 | controller | TASK [kustomize_deploy : Copy generated values for examples/va/hci/deployment] ***
2025-09-30 11:22:31.721071 | controller | Tuesday 30 September 2025  11:22:31 +0000 (0:00:00.364)       0:00:52.027 *****
2025-09-30 11:22:31.721083 | controller | changed: [instance] => (item=edpm-deployment-values-post-ceph)
2025-09-30 11:22:31.758714 | controller |
2025-09-30 11:22:31.758740 | controller | TASK [kustomize_deploy : Stop before building kustomization if requested] ******
2025-09-30 11:22:31.758748 | controller | Tuesday 30 September 2025  11:22:31 +0000 (0:00:00.234)       0:00:52.262 *****
2025-09-30 11:22:31.758757 | controller | skipping: [instance]
2025-09-30 11:22:32.043819 | controller |
2025-09-30 11:22:32.043848 | controller | TASK [kustomize_deploy : Build kustomized content for examples/va/hci/deployment] ***
2025-09-30 11:22:32.043856 | controller | Tuesday 30 September 2025  11:22:31 +0000 (0:00:00.037)       0:00:52.299 *****
2025-09-30 11:22:32.043866 | controller | changed: [instance]
2025-09-30 11:22:32.410206 | controller |
2025-09-30 11:22:32.410235 | controller | TASK [kustomize_deploy : Output kustomize build in final file] *****************
2025-09-30 11:22:32.410243 | controller | Tuesday 30 September 2025  11:22:32 +0000 (0:00:00.284)       0:00:52.584 *****
2025-09-30 11:22:32.410258 | controller | changed: [instance]
2025-09-30 11:22:32.623991 | controller |
2025-09-30 11:22:32.624020 | controller | TASK [kustomize_deploy : Store kustomized content in artifacts for examples/va/hci/deployment] ***
2025-09-30 11:22:32.624028 | controller | Tuesday 30 September 2025  11:22:32 +0000 (0:00:00.366)       0:00:52.951 *****
2025-09-30 11:22:32.624038 | controller | changed: [instance]
2025-09-30 11:22:32.662969 | controller |
2025-09-30 11:22:32.662998 | controller | TASK [kustomize_deploy : Stop after building kustomization if requested] *******
2025-09-30 11:22:32.663007 | controller | Tuesday 30 September 2025  11:22:32 +0000 (0:00:00.213)       0:00:53.164 *****
2025-09-30 11:22:32.663018 | controller | skipping: [instance]
2025-09-30 11:22:32.701694 | controller |
2025-09-30 11:22:32.701723 | controller | TASK [kustomize_deploy : Apply generated content for examples/va/hci/deployment] ***
2025-09-30 11:22:32.701732 | controller | Tuesday 30 September 2025  11:22:32 +0000 (0:00:00.038)       0:00:53.203 *****
2025-09-30 11:22:32.701744 | controller | skipping: [instance]
2025-09-30 11:22:32.741530 | controller |
2025-09-30 11:22:32.741558 | controller | TASK [kustomize_deploy : Run Wait Conditions for examples/va/hci/deployment] ***
2025-09-30 11:22:32.741566 | controller | Tuesday 30 September 2025  11:22:32 +0000 (0:00:00.038)       0:00:53.242 *****
2025-09-30 11:22:32.741576 | controller | skipping: [instance] => (item=oc -n openstack wait osdpns openstack-edpm --for condition=Ready --timeout=80m)
2025-09-30 11:22:32.779907 | controller | skipping: [instance]
2025-09-30 11:22:32.779950 | controller |
2025-09-30 11:22:32.779959 | controller | TASK [kustomize_deploy : Stop after applying CRs if requested] *****************
2025-09-30 11:22:32.779965 | controller | Tuesday 30 September 2025  11:22:32 +0000 (0:00:00.039)       0:00:53.282 *****
2025-09-30 11:22:32.779974 | controller | skipping: [instance]
2025-09-30 11:22:32.822780 | controller |
2025-09-30 11:22:32.822807 | controller | TASK [Executing post_stage hooks for examples/va/hci/deployment] ***************
2025-09-30 11:22:32.822819 | controller | Tuesday 30 September 2025  11:22:32 +0000 (0:00:00.038)       0:00:53.320 *****
2025-09-30 11:22:32.822829 | controller | skipping: [instance]
2025-09-30 11:22:33.030444 | controller |
2025-09-30 11:22:33.030475 | controller | TASK [Run loop deployment again] ***********************************************
2025-09-30 11:22:33.030483 | controller | Tuesday 30 September 2025  11:22:32 +0000 (0:00:00.042)       0:00:53.363 *****
2025-09-30 11:22:33.030492 | controller |
2025-09-30 11:22:33.069533 | controller | TASK [kustomize_deploy : Assert mandatory bits are defined] ********************
2025-09-30 11:22:33.069568 | controller | Tuesday 30 September 2025  11:22:33 +0000 (0:00:00.207)       0:00:53.571 *****
2025-09-30 11:22:33.069589 | controller | ok: [instance] => changed=false
2025-09-30 11:22:33.097700 | controller |   msg: All assertions passed
2025-09-30 11:22:33.097727 | controller |
2025-09-30 11:22:33.097735 | controller | TASK [kustomize_deploy : Check custom wait conditions] *************************
2025-09-30 11:22:33.097741 | controller | Tuesday 30 September 2025  11:22:33 +0000 (0:00:00.038)       0:00:53.610 *****
2025-09-30 11:22:33.097751 | controller | failed: [instance] (item=sudo cat /etc/passwd) => changed=false
2025-09-30 11:22:33.097773 | controller |   ansible_loop_var: item
2025-09-30 11:22:33.098147 | controller |   assertion: item is match(_pattern)
2025-09-30 11:22:33.100814 | controller |   evaluated_to: false
2025-09-30 11:22:33.100832 | controller |   item: sudo cat /etc/passwd
2025-09-30 11:22:33.100840 | controller |   msg: 'The following custom condition is invalid: sudo cat /etc/passwd'
2025-09-30 11:22:33.100848 | controller | failed: [instance] (item=oc rsh exec blah) => changed=false
2025-09-30 11:22:33.104322 | controller |   ansible_loop_var: item
2025-09-30 11:22:33.104337 | controller |   assertion: item is match(_pattern)
2025-09-30 11:22:33.104344 | controller |   evaluated_to: false
2025-09-30 11:22:33.104349 | controller |   item: oc rsh exec blah
2025-09-30 11:22:33.104355 | controller |   msg: 'The following custom condition is invalid: oc rsh exec blah'
2025-09-30 11:22:33.104363 | controller | failed: [instance] (item=kubectl rsh exec bar) => changed=false
2025-09-30 11:22:33.104499 | controller |   ansible_loop_var: item
2025-09-30 11:22:33.104654 | controller |   assertion: item is match(_pattern)
2025-09-30 11:22:33.104784 | controller |   evaluated_to: false
2025-09-30 11:22:33.104908 | controller |   item: kubectl rsh exec bar
2025-09-30 11:22:33.105064 | controller |   msg: 'The following custom condition is invalid: kubectl rsh exec bar'
2025-09-30 11:22:33.113904 | controller | ...ignoring
2025-09-30 11:22:33.114093 | controller |
2025-09-30 11:22:33.114228 | controller | TASK [kustomize_deploy : Check builtin wait conditions] ************************
2025-09-30 11:22:33.114356 | controller | Tuesday 30 September 2025  11:22:33 +0000 (0:00:00.045)       0:00:53.655 *****
2025-09-30 11:22:33.144093 | controller | ok: [instance] => (item=oc -n openstack wait nncp -l osp/nncm-config-type=standard --for jsonpath='{.status.conditions[0].reason}'=SuccessfullyConfigured --timeout=5m)
2025-09-30 11:22:33.161748 | controller |
2025-09-30 11:22:33.161769 | controller | TASK [kustomize_deploy : Check wait_conditions validation result] **************
2025-09-30 11:22:33.161776 | controller | Tuesday 30 September 2025  11:22:33 +0000 (0:00:00.029)       0:00:53.685 *****
2025-09-30 11:22:33.161785 | controller | fatal: [instance]: FAILED! => changed=false
2025-09-30 11:22:33.188292 | controller |   msg: Review and correct the faulty wait_conditions listed above.
2025-09-30 11:22:33.188344 | controller |
2025-09-30 11:22:33.188381 | controller | TASK [Clear expected errors] ***************************************************
2025-09-30 11:22:33.189050 | controller | Tuesday 30 September 2025  11:22:33 +0000 (0:00:00.026)       0:00:53.711 *****
2025-09-30 11:22:33.189977 | controller |
2025-09-30 11:22:33.189996 | controller | TASK [Ensure a successful end] *************************************************
2025-09-30 11:22:33.190003 | controller | Tuesday 30 September 2025  11:22:33 +0000 (0:00:00.010)       0:00:53.721 *****
2025-09-30 11:22:33.190008 | controller |
2025-09-30 11:22:33.190014 | controller | PLAY RECAP *********************************************************************
2025-09-30 11:22:33.190020 | controller | instance                   : ok=261  changed=69   unreachable=0    failed=0    skipped=75   rescued=1    ignored=1
2025-09-30 11:22:33.190028 | controller |
2025-09-30 11:22:33.191097 | controller | Tuesday 30 September 2025  11:22:33 +0000 (0:00:00.007)       0:00:53.729 *****
2025-09-30 11:22:33.191117 | controller | ===============================================================================
2025-09-30 11:22:33.191125 | controller | Gathering Facts --------------------------------------------------------- 0.78s
2025-09-30 11:22:33.191131 | controller | ci_gen_kustomize_values : Load various snippet files -------------------- 0.60s
2025-09-30 11:22:33.191136 | controller | ci_gen_kustomize_values : Load various snippet files -------------------- 0.59s
2025-09-30 11:22:33.191141 | controller | ci_gen_kustomize_values : Load various snippet files -------------------- 0.56s
2025-09-30 11:22:33.191147 | controller | ci_gen_kustomize_values : Load various snippet files -------------------- 0.56s
2025-09-30 11:22:33.191152 | controller | ci_gen_kustomize_values : Generate CI snippet --------------------------- 0.55s
2025-09-30 11:22:33.191157 | controller | kustomize_deploy : Build kustomized content for examples/va/hci/control-plane/networking/nncp --- 0.49s
2025-09-30 11:22:33.191163 | controller | ci_gen_kustomize_values : Generate CI snippet --------------------------- 0.48s
2025-09-30 11:22:33.191172 | controller | kustomize_deploy : Copy generated values for examples/va/hci ------------ 0.47s
2025-09-30 11:22:33.263117 | controller | ci_gen_kustomize_values : Generate CI snippet --------------------------- 0.46s
2025-09-30 11:22:33.263144 | controller | kustomize_deploy : Copy generated values for examples/va/hci/control-plane --- 0.45s
2025-09-30 11:22:33.263152 | controller | ci_gen_kustomize_values : Generate CI snippet --------------------------- 0.42s
2025-09-30 11:22:33.263158 | controller | kustomize_deploy : Build kustomized content for examples/va/hci --------- 0.41s
2025-09-30 11:22:33.263163 | controller | kustomize_deploy : Ensure source files exists --------------------------- 0.40s
2025-09-30 11:22:33.263171 | controller | kustomize_deploy : Ensure source files exists --------------------------- 0.40s
2025-09-30 11:22:33.263177 | controller | ci_gen_kustomize_values : Copy the base values.yaml --------------------- 0.40s
2025-09-30 11:22:33.263182 | controller | ci_gen_kustomize_values : Output values file ---------------------------- 0.40s
2025-09-30 11:22:33.263187 | controller | ci_gen_kustomize_values : Load various snippet files -------------------- 0.39s
2025-09-30 11:22:33.263192 | controller | ci_gen_kustomize_values : Output values file ---------------------------- 0.39s
2025-09-30 11:22:33.263197 | controller | ci_gen_kustomize_values : Load various snippet files -------------------- 0.39s
2025-09-30 11:22:33.263211 | controller | INFO     Running flexible_loop > cleanup
2025-09-30 11:22:33.263654 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-09-30 11:22:33.264196 | controller | INFO     Writing /tmp/report.html report.
2025-09-30 11:22:33.516068 | controller | changed
2025-09-30 11:22:33.611897 | 
2025-09-30 11:22:33.612030 | PLAY RECAP
2025-09-30 11:22:33.612077 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-30 11:22:33.612102 | 
2025-09-30 11:22:34.098333 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-30 11:22:34.100230 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-30 11:22:36.355783 | 
2025-09-30 11:22:36.355940 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-09-30 11:22:36.440233 | 
2025-09-30 11:22:36.440374 | TASK [Filter out host if needed]
2025-09-30 11:22:36.479613 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-09-30 11:22:36.488934 | 
2025-09-30 11:22:36.489058 | TASK [Ensure file is present]
2025-09-30 11:22:37.195823 | controller | ok
2025-09-30 11:22:37.205875 | 
2025-09-30 11:22:37.206325 | TASK [Manage molecule report file]
2025-09-30 11:22:38.246106 | controller | changed
2025-09-30 11:22:38.251723 | 
2025-09-30 11:22:38.251814 | TASK [Check if we get ci-framework-data basedir]
2025-09-30 11:22:38.474341 | controller | ok
2025-09-30 11:22:38.482138 | 
2025-09-30 11:22:38.482225 | TASK [Create ci-framework-data log directory for zuul]
2025-09-30 11:22:38.973080 | controller | changed
2025-09-30 11:22:38.979051 | 
2025-09-30 11:22:38.979134 | TASK [Copy ci-framework interesting files]
2025-09-30 11:22:39.219682 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-09-30 11:22:39.535816 | controller | changed
2025-09-30 11:22:39.542064 | 
2025-09-30 11:22:39.542133 | TASK [Get SELinux listing]
2025-09-30 11:22:40.176047 | controller | changed
2025-09-30 11:22:40.195745 | 
2025-09-30 11:22:40.195846 | TASK [Generate log index]
2025-09-30 11:22:41.163746 | controller | changed
2025-09-30 11:22:41.177236 | 
2025-09-30 11:22:41.177333 | TASK [Get some env related data]
2025-09-30 11:22:41.803085 | controller | /home/zuul/.local/bin/ansible
2025-09-30 11:22:42.740438 | controller | changed
2025-09-30 11:22:42.746127 | 
2025-09-30 11:22:42.746215 | TASK [Generate list of logs to collect in home directory]
2025-09-30 11:22:43.506632 | controller | ok: All paths examined
2025-09-30 11:22:43.529824 | 
2025-09-30 11:22:43.529937 | LOOP [Copy logs from home directory]
2025-09-30 11:22:43.960127 | controller | changed:
2025-09-30 11:22:43.960284 | controller | {
2025-09-30 11:22:43.960313 | controller |   "atime": 1759231247.864263,
2025-09-30 11:22:43.960333 | controller |   "ctime": 1759231264.1255176,
2025-09-30 11:22:43.960351 | controller |   "dev": 64513,
2025-09-30 11:22:43.960368 | controller |   "gid": 1000,
2025-09-30 11:22:43.960386 | controller |   "gr_name": "zuul",
2025-09-30 11:22:43.960404 | controller |   "inode": 4330587,
2025-09-30 11:22:43.960420 | controller |   "isblk": false,
2025-09-30 11:22:43.960436 | controller |   "ischr": false,
2025-09-30 11:22:43.960452 | controller |   "isdir": false,
2025-09-30 11:22:43.960467 | controller |   "isfifo": false,
2025-09-30 11:22:43.960483 | controller |   "isgid": false,
2025-09-30 11:22:43.960498 | controller |   "islnk": false,
2025-09-30 11:22:43.960513 | controller |   "isreg": true,
2025-09-30 11:22:43.960528 | controller |   "issock": false,
2025-09-30 11:22:43.960542 | controller |   "isuid": false,
2025-09-30 11:22:43.960557 | controller |   "mode": "0644",
2025-09-30 11:22:43.960573 | controller |   "mtime": 1759231264.1255176,
2025-09-30 11:22:43.960588 | controller |   "nlink": 1,
2025-09-30 11:22:43.960604 | controller |   "path": "/home/zuul/ansible.log",
2025-09-30 11:22:43.960620 | controller |   "pw_name": "zuul",
2025-09-30 11:22:43.960636 | controller |   "rgrp": true,
2025-09-30 11:22:43.960653 | controller |   "roth": true,
2025-09-30 11:22:43.960670 | controller |   "rusr": true,
2025-09-30 11:22:43.960686 | controller |   "size": 6749,
2025-09-30 11:22:43.960701 | controller |   "uid": 1000,
2025-09-30 11:22:43.960717 | controller |   "wgrp": false,
2025-09-30 11:22:43.961140 | controller |   "woth": false,
2025-09-30 11:22:43.961190 | controller |   "wusr": true,
2025-09-30 11:22:43.961216 | controller |   "xgrp": false,
2025-09-30 11:22:43.961236 | controller |   "xoth": false,
2025-09-30 11:22:43.961254 | controller |   "xusr": false
2025-09-30 11:22:43.961270 | controller | }
2025-09-30 11:22:43.967941 | 
2025-09-30 11:22:43.970410 | TASK [Copy crio stats log file]
2025-09-30 11:22:44.025950 | controller | skipping: Conditional result was False
2025-09-30 11:22:44.032035 | 
2025-09-30 11:22:44.032128 | TASK [Get SELinux related data]
2025-09-30 11:22:44.290914 | controller | <no matches>
2025-09-30 11:22:44.606791 | controller | ERROR
2025-09-30 11:22:44.606988 | controller | {
2025-09-30 11:22:44.607057 | controller |   "delta": "0:00:00.007411",
2025-09-30 11:22:44.607085 | controller |   "end": "2025-09-30 11:22:44.291435",
2025-09-30 11:22:44.607106 | controller |   "msg": "non-zero return code",
2025-09-30 11:22:44.607125 | controller |   "rc": 1,
2025-09-30 11:22:44.607144 | controller |   "start": "2025-09-30 11:22:44.284024"
2025-09-30 11:22:44.607161 | controller | }
2025-09-30 11:22:44.607188 | controller | ERROR: Ignoring Errors
2025-09-30 11:22:44.613638 | 
2025-09-30 11:22:44.613711 | TASK [Create system configuration directory]
2025-09-30 11:22:44.813073 | controller | changed
2025-09-30 11:22:44.818102 | 
2025-09-30 11:22:44.818191 | TASK [Get some of the system configurations]
2025-09-30 11:22:45.428353 | controller | changed
2025-09-30 11:22:45.435095 | 
2025-09-30 11:22:45.435185 | TASK [Copy generated documentation if available]
2025-09-30 11:22:45.485870 | controller | skipping: Conditional result was False
2025-09-30 11:22:45.492347 | 
2025-09-30 11:22:45.492987 | TASK [Copy generated AsciiDoc documentation if available]
2025-09-30 11:22:45.554222 | controller | skipping: Conditional result was False
2025-09-30 11:22:45.561145 | 
2025-09-30 11:22:45.561241 | TASK [Compress logs bigger than 2MB]
2025-09-30 11:22:46.220993 | controller | changed
2025-09-30 11:22:46.227856 | 
2025-09-30 11:22:46.227967 | TASK [Copy files from workspace on node]
2025-09-30 11:22:46.281583 | controller | ok
2025-09-30 11:22:46.468884 | 
2025-09-30 11:22:46.483621 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-30 11:22:46.605383 | controller | skipping: Conditional result was False
2025-09-30 11:22:46.612263 | 
2025-09-30 11:22:46.612357 | TASK [fetch-output : Set log path for single node]
2025-09-30 11:22:46.702276 | controller | ok
2025-09-30 11:22:46.721965 | 
2025-09-30 11:22:46.722074 | LOOP [fetch-output : Ensure local output dirs]
2025-09-30 11:22:47.430523 | controller -> localhost | ok: "/var/lib/zuul/builds/3e5695ac00b84683a1e576f306dfc53f/work/logs"
2025-09-30 11:22:47.430768 | controller -> localhost | changed: All items complete
2025-09-30 11:22:47.430804 | 
2025-09-30 11:22:48.030994 | controller -> localhost | changed: "/var/lib/zuul/builds/3e5695ac00b84683a1e576f306dfc53f/work/artifacts"
2025-09-30 11:22:48.423822 | controller -> localhost | changed: "/var/lib/zuul/builds/3e5695ac00b84683a1e576f306dfc53f/work/docs"
2025-09-30 11:22:48.437504 | 
2025-09-30 11:22:48.437613 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-30 11:22:49.894594 | controller | changed:
2025-09-30 11:22:49.894783 | controller | .d..t...... ./
2025-09-30 11:22:49.894817 | controller | >f+++++++++ README.html
2025-09-30 11:22:49.894842 | controller | >f+++++++++ ansible-execution.log
2025-09-30 11:22:49.894866 | controller | >f+++++++++ ansible.log
2025-09-30 11:22:49.894886 | controller | >f+++++++++ dmesg.log
2025-09-30 11:22:49.894936 | controller | >f+++++++++ installed-pkgs.log
2025-09-30 11:22:49.894971 | controller | >f+++++++++ python.log
2025-09-30 11:22:49.894995 | controller | >f+++++++++ registries.conf
2025-09-30 11:22:49.895030 | controller | >f+++++++++ report.html
2025-09-30 11:22:49.895052 | controller | >f+++++++++ selinux-denials.log
2025-09-30 11:22:49.895071 | controller | >f+++++++++ selinux-listing.log
2025-09-30 11:22:49.895091 | controller | cd+++++++++ ci-framework-data/
2025-09-30 11:22:49.895109 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-09-30 11:22:49.895127 | controller | >f+++++++++ ci-framework-data/artifacts/nova_migration_key
2025-09-30 11:22:49.895145 | controller | >f+++++++++ ci-framework-data/artifacts/nova_migration_key.pub
2025-09-30 11:22:49.895162 | controller | >f+++++++++ ci-framework-data/artifacts/user_key
2025-09-30 11:22:49.895180 | controller | >f+++++++++ ci-framework-data/artifacts/user_key.pub
2025-09-30 11:22:49.895197 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-09-30 11:22:49.895215 | controller | cd+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/
2025-09-30 11:22:49.895232 | controller | cd+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-deployment-values-post-ceph/
2025-09-30 11:22:49.895250 | controller | >f+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-deployment-values-post-ceph/values.yaml
2025-09-30 11:22:49.895267 | controller | cd+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-deployment-values/
2025-09-30 11:22:49.895284 | controller | >f+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-deployment-values/values.yaml
2025-09-30 11:22:49.895300 | controller | cd+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-nodeset-values-post-ceph/
2025-09-30 11:22:49.895317 | controller | >f+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-nodeset-values-post-ceph/values.yaml
2025-09-30 11:22:49.895339 | controller | cd+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-nodeset-values/
2025-09-30 11:22:49.895357 | controller | >f+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-nodeset-values/values.yaml
2025-09-30 11:22:49.895373 | controller | cd+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/
2025-09-30 11:22:49.895390 | controller | >f+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/values.yaml
2025-09-30 11:22:49.895407 | controller | cd+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/service-values/
2025-09-30 11:22:49.895424 | controller | >f+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/service-values/values.yaml
2025-09-30 11:22:49.895441 | controller | >f+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/service-values/values.yaml.28930.2025-09-30@11:22:22~
2025-09-30 11:22:49.895460 | controller | cd+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/
2025-09-30 11:22:49.895478 | controller | cd+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-deployment-values-post-ceph/
2025-09-30 11:22:49.895495 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-deployment-values-post-ceph/01_original.yaml
2025-09-30 11:22:49.895512 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-deployment-values-post-ceph/03_user_data_b64.yaml
2025-09-30 11:22:49.895528 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-deployment-values-post-ceph/04_user_data.yaml
2025-09-30 11:22:49.895544 | controller | cd+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-deployment-values/
2025-09-30 11:22:49.895560 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-deployment-values/01_original.yaml
2025-09-30 11:22:49.895576 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-deployment-values/03_user_data_b64.yaml
2025-09-30 11:22:49.895592 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-deployment-values/04_user_data.yaml
2025-09-30 11:22:49.895608 | controller | cd+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values-post-ceph/
2025-09-30 11:22:49.895624 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values-post-ceph/01_original.yaml
2025-09-30 11:22:49.895640 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values-post-ceph/03_user_data_b64.yaml
2025-09-30 11:22:49.895656 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values-post-ceph/04_user_data.yaml
2025-09-30 11:22:49.895672 | controller | cd+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/
2025-09-30 11:22:49.895689 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/01_original.yaml
2025-09-30 11:22:49.895704 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/02_ci_data.yaml
2025-09-30 11:22:49.895720 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/03_user_data_b64.yaml
2025-09-30 11:22:49.895736 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/04_user_data.yaml
2025-09-30 11:22:49.895752 | controller | cd+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/
2025-09-30 11:22:49.895768 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/01_original.yaml
2025-09-30 11:22:49.895784 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/01_original.yaml.27187.2025-09-30@11:21:52~
2025-09-30 11:22:49.895800 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/02_ci_data.yaml
2025-09-30 11:22:49.895815 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/03_user_data_b64.yaml
2025-09-30 11:22:49.895831 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/04_user_data.yaml
2025-09-30 11:22:49.895851 | controller | cd+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/service-values/
2025-09-30 11:22:49.895869 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/service-values/01_original.yaml
2025-09-30 11:22:49.895885 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/service-values/01_original.yaml.28839.2025-09-30@11:22:20~
2025-09-30 11:22:49.895901 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/service-values/03_user_data_b64.yaml
2025-09-30 11:22:49.895943 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/service-values/04_user_data.yaml
2025-09-30 11:22:49.895964 | controller | cd+++++++++ ci-framework-data/artifacts/kustomize_deploy/
2025-09-30 11:22:49.895982 | controller | >f+++++++++ ci-framework-data/artifacts/kustomize_deploy/control-plane.yaml
2025-09-30 11:22:49.896009 | controller | >f+++++++++ ci-framework-data/artifacts/kustomize_deploy/deployment-post-ceph.yaml
2025-09-30 11:22:49.896028 | controller | >f+++++++++ ci-framework-data/artifacts/kustomize_deploy/deployment-pre-ceph.yaml
2025-09-30 11:22:49.896952 | controller | >f+++++++++ ci-framework-data/artifacts/kustomize_deploy/network.yaml
2025-09-30 11:22:49.897016 | controller | >f+++++++++ ci-framework-data/artifacts/kustomize_deploy/nncp.yaml
2025-09-30 11:22:49.897045 | controller | >f+++++++++ ci-framework-data/artifacts/kustomize_deploy/nodeset-post-ceph.yaml
2025-09-30 11:22:49.897068 | controller | >f+++++++++ ci-framework-data/artifacts/kustomize_deploy/nodeset-pre-ceph.yaml
2025-09-30 11:22:49.897088 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-09-30 11:22:49.897107 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-09-30 11:22:49.897128 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-09-30 11:22:49.897146 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-09-30 11:22:49.897163 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-09-30 11:22:49.897179 | controller | cd+++++++++ ci-framework-data/logs/
2025-09-30 11:22:49.897196 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-09-30 11:22:49.897213 | controller | cd+++++++++ registries.conf.d/
2025-09-30 11:22:49.897230 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-09-30 11:22:49.897248 | controller | cd+++++++++ system-config/
2025-09-30 11:22:49.897266 | controller | cd+++++++++ system-config/libvirt/
2025-09-30 11:22:49.897284 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-09-30 11:22:49.897300 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-09-30 11:22:51.142837 | controller | changed: .d..t...... ./
2025-09-30 11:22:52.283949 | controller | changed: .d..t...... ./
2025-09-30 11:22:52.313074 | 
2025-09-30 11:22:52.313179 | TASK [Return artifact to Zuul]
2025-09-30 11:22:52.416469 | controller | ok
2025-09-30 11:22:52.475647 | 
2025-09-30 11:22:52.475726 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-09-30 11:22:52.475837 | 
2025-09-30 11:22:52.475872 | PLAY RECAP
2025-09-30 11:22:52.476123 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-09-30 11:22:52.476156 | 
2025-09-30 11:22:52.877737 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-30 11:22:52.878725 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-30 11:22:54.158097 | 
2025-09-30 11:22:54.158251 | PLAY [all]
2025-09-30 11:22:54.220182 | 
2025-09-30 11:22:54.220308 | TASK [include_role : fetch-output]
2025-09-30 11:22:54.290088 | controller | ok
2025-09-30 11:22:54.319670 | 
2025-09-30 11:22:54.319791 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-30 11:22:54.452377 | controller | skipping: Conditional result was False
2025-09-30 11:22:54.458528 | 
2025-09-30 11:22:54.458621 | TASK [fetch-output : Set log path for single node]
2025-09-30 11:22:54.527824 | controller | ok
2025-09-30 11:22:54.533186 | 
2025-09-30 11:22:54.533275 | LOOP [fetch-output : Ensure local output dirs]
2025-09-30 11:22:55.506381 | controller -> localhost | ok: "/var/lib/zuul/builds/3e5695ac00b84683a1e576f306dfc53f/work/logs"
2025-09-30 11:22:55.930081 | controller -> localhost | ok: "/var/lib/zuul/builds/3e5695ac00b84683a1e576f306dfc53f/work/artifacts"
2025-09-30 11:22:56.278152 | controller -> localhost | ok: "/var/lib/zuul/builds/3e5695ac00b84683a1e576f306dfc53f/work/docs"
2025-09-30 11:22:56.289498 | 
2025-09-30 11:22:56.289624 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-30 11:22:57.316980 | controller | ok
2025-09-30 11:22:57.319116 | controller | ok: All items complete
2025-09-30 11:22:57.319165 | 
2025-09-30 11:22:57.932426 | controller | ok
2025-09-30 11:22:58.956460 | controller | ok
2025-09-30 11:22:58.977393 | 
2025-09-30 11:22:58.977508 | TASK [include_role : fetch-output-openshift]
2025-09-30 11:22:59.034786 | controller | skipping: Conditional result was False
2025-09-30 11:22:59.042019 | 
2025-09-30 11:22:59.042114 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-09-30 11:23:00.234719 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.049922
2025-09-30 11:23:00.595028 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.045766
2025-09-30 11:23:00.632332 | 
2025-09-30 11:23:00.632413 | PLAY [all]
2025-09-30 11:23:00.646357 | 
2025-09-30 11:23:00.646428 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-09-30 11:23:01.183514 | controller | changed
2025-09-30 11:23:01.271261 | 
2025-09-30 11:23:01.271351 | PLAY RECAP
2025-09-30 11:23:01.271399 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-30 11:23:01.271424 | 
2025-09-30 11:23:01.489560 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-30 11:23:01.490360 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-09-30 11:23:02.920975 | 
2025-09-30 11:23:02.921108 | PLAY [localhost]
2025-09-30 11:23:02.965454 | 
2025-09-30 11:23:02.965561 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-09-30 11:23:03.846665 | localhost | changed
2025-09-30 11:23:03.853396 | 
2025-09-30 11:23:03.853498 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-09-30 11:23:03.901428 | localhost | ok
2025-09-30 11:23:03.914281 | 
2025-09-30 11:23:03.914365 | TASK [add-fileserver : Create SSH private key tempfile]
2025-09-30 11:23:04.724160 | localhost | changed
2025-09-30 11:23:04.730154 | 
2025-09-30 11:23:04.730288 | TASK [add-fileserver : Create SSH private key from secret]
2025-09-30 11:23:05.950467 | localhost | changed
2025-09-30 11:23:05.955725 | 
2025-09-30 11:23:05.955801 | TASK [add-fileserver : Add fileserver ssh key]
2025-09-30 11:23:06.795699 | localhost | Identity added: /var/lib/zuul/builds/3e5695ac00b84683a1e576f306dfc53f/work/tmp/ansible.xjyye8m5 (/var/lib/zuul/builds/3e5695ac00b84683a1e576f306dfc53f/work/tmp/ansible.xjyye8m5)
2025-09-30 11:23:06.795874 | localhost | ok: Runtime: 0:00:00.008261
2025-09-30 11:23:06.802165 | 
2025-09-30 11:23:06.802232 | TASK [add-fileserver : Remove SSH private key from disk]
2025-09-30 11:23:07.457937 | localhost | ok: Runtime: 0:00:00.020420
2025-09-30 11:23:07.463497 | 
2025-09-30 11:23:07.463579 | TASK [add-fileserver : Add fileserver to inventory]
2025-09-30 11:23:07.625418 | localhost | changed
2025-09-30 11:23:07.630794 | 
2025-09-30 11:23:07.630885 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-09-30 11:23:08.268859 | localhost | changed
2025-09-30 11:23:08.303869 | 
2025-09-30 11:23:08.303972 | PLAY [localhost]
2025-09-30 11:23:08.332890 | 
2025-09-30 11:23:08.334697 | TASK [Generate bulk log download script]
2025-09-30 11:23:08.363271 | localhost | ok
2025-09-30 11:23:08.424537 | 
2025-09-30 11:23:08.424706 | TASK [local-log-download : Check API endpoint is defined]
2025-09-30 11:23:08.487189 | localhost | ok: All assertions passed
2025-09-30 11:23:08.492133 | 
2025-09-30 11:23:08.492224 | TASK [local-log-download : Create download script]
2025-09-30 11:23:09.337974 | localhost -> localhost | changed
2025-09-30 11:23:09.346781 | 
2025-09-30 11:23:09.346855 | TASK [Register quick-download link]
2025-09-30 11:23:09.387865 | localhost | ok
2025-09-30 11:23:09.420900 | 
2025-09-30 11:23:09.421002 | PLAY [logserver.rdoproject.org]
2025-09-30 11:23:09.454811 | 
2025-09-30 11:23:09.454895 | TASK [Set zuul-log-path fact]
2025-09-30 11:23:09.481285 | logserver.rdoproject.org | ok
2025-09-30 11:23:09.491462 | 
2025-09-30 11:23:09.491540 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-30 11:23:09.538424 | logserver.rdoproject.org | ok
2025-09-30 11:23:09.544400 | 
2025-09-30 11:23:09.544483 | TASK [upload-logs : Create log directories]
2025-09-30 11:23:11.246442 | logserver.rdoproject.org | changed
2025-09-30 11:23:11.251743 | 
2025-09-30 11:23:11.251809 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-09-30 11:23:11.602278 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.006109
2025-09-30 11:23:11.607402 | 
2025-09-30 11:23:11.607470 | TASK [upload-logs : Upload logs to log server]
2025-09-30 11:23:13.582397 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-09-30 11:23:13.585358 | 
2025-09-30 11:23:13.585440 | LOOP [upload-logs : Compress console log and json output]
2025-09-30 11:23:13.721480 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 11:23:13.722301 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 11:23:13.736410 | 
2025-09-30 11:23:13.736499 | LOOP [upload-logs : Upload compressed console log and json output]
2025-09-30 11:23:13.824849 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 11:23:13.825116 | 
2025-09-30 11:23:13.825729 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 11:23:13.837958 | 
2025-09-30 11:23:13.838059 | LOOP [upload-logs : Upload console log and json output]