2025-10-13 10:33:24.609143 | Job console starting...
2025-10-13 10:33:24.849679 | Updating repositories
2025-10-13 10:33:25.620423 | Preparing job workspace
2025-10-13 10:33:34.627410 | Running Ansible setup...
2025-10-13 10:33:38.033279 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 10:33:38.906539 | 
2025-10-13 10:33:38.906644 | PLAY [localhost]
2025-10-13 10:33:38.928590 | 
2025-10-13 10:33:38.928673 | TASK [Gathering Facts]
2025-10-13 10:33:40.433058 | localhost | ok
2025-10-13 10:33:40.453851 | 
2025-10-13 10:33:40.453978 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-13 10:33:40.952515 | localhost -> localhost | changed
2025-10-13 10:33:40.957778 | 
2025-10-13 10:33:40.957847 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-13 10:33:42.366317 | localhost -> localhost | changed
2025-10-13 10:33:42.374474 | 
2025-10-13 10:33:42.374540 | TASK [Setup log path fact]
2025-10-13 10:33:42.424776 | localhost | ok
2025-10-13 10:33:42.450935 | 
2025-10-13 10:33:42.451007 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 10:33:42.509459 | localhost | ok
2025-10-13 10:33:42.527189 | 
2025-10-13 10:33:42.527260 | TASK [emit-job-header : Print job information]
2025-10-13 10:33:42.611094 | # Job Information
2025-10-13 10:33:42.611222 | Ansible Version: 2.15.12
2025-10-13 10:33:42.611252 | Job: cifmw-molecule-ci_gen_kustomize_values
2025-10-13 10:33:42.611276 | Pipeline: github-check
2025-10-13 10:33:42.611297 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-13 10:33:42.611317 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3402
2025-10-13 10:33:42.611337 | Log URL (when completed): https://logserver.rdoproject.org/da9/rdoproject.org/da9547f6dece43f1af0b598f2762776f/
2025-10-13 10:33:42.611357 | Event ID: c3280dc0-a81f-11f0-8d33-e350c072644d
2025-10-13 10:33:42.615695 | 
2025-10-13 10:33:42.615764 | LOOP [emit-job-header : Print node information]
2025-10-13 10:33:43.004083 | localhost | ok:
2025-10-13 10:33:43.004238 | localhost | # Node Information
2025-10-13 10:33:43.004269 | localhost | Inventory Hostname: controller
2025-10-13 10:33:43.004296 | localhost | Hostname: np0005483626
2025-10-13 10:33:43.004319 | localhost | Username: zuul
2025-10-13 10:33:43.004341 | localhost | Distro: CentOS 9
2025-10-13 10:33:43.004361 | localhost | Provider: ibm-bm4-nodepool
2025-10-13 10:33:43.004380 | localhost | Region: regionOne
2025-10-13 10:33:43.004397 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-13 10:33:43.004414 | localhost | Product Name: OpenStack Compute
2025-10-13 10:33:43.004433 | localhost | Interface IP: 192.168.26.155
2025-10-13 10:33:43.071590 | 
2025-10-13 10:33:43.071665 | PLAY [all]
2025-10-13 10:33:43.092691 | 
2025-10-13 10:33:43.092779 | TASK [Gather network facts]
2025-10-13 10:33:43.500335 | controller | ok
2025-10-13 10:33:43.564503 | 
2025-10-13 10:33:43.564612 | TASK [include_role : start-zuul-console]
2025-10-13 10:33:43.639483 | controller | ok
2025-10-13 10:33:43.699730 | 
2025-10-13 10:33:43.699812 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-13 10:33:44.043766 | controller | ok
2025-10-13 10:33:44.081629 | 
2025-10-13 10:33:44.081726 | TASK [include_role : add-build-sshkey]
2025-10-13 10:33:44.143900 | controller | ok
2025-10-13 10:33:44.183634 | 
2025-10-13 10:33:44.183710 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-13 10:33:44.984827 | controller -> localhost | ok
2025-10-13 10:33:44.989809 | 
2025-10-13 10:33:44.989870 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-13 10:33:45.006698 | controller | ok
2025-10-13 10:33:45.021265 | controller | included: /var/lib/zuul/builds/da9547f6dece43f1af0b598f2762776f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-13 10:33:45.027901 | 
2025-10-13 10:33:45.027980 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-13 10:33:46.561841 | controller -> localhost | Generating public/private rsa key pair.
2025-10-13 10:33:46.562021 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/da9547f6dece43f1af0b598f2762776f/work/da9547f6dece43f1af0b598f2762776f_id_rsa.
2025-10-13 10:33:46.562057 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/da9547f6dece43f1af0b598f2762776f/work/da9547f6dece43f1af0b598f2762776f_id_rsa.pub.
2025-10-13 10:33:46.562082 | controller -> localhost | The key fingerprint is:
2025-10-13 10:33:46.562103 | controller -> localhost | SHA256:oOIau0fBn+M+MKrCEjIVVAnRb4OHItYHQY7mmcvdp/c zuul-build-sshkey
2025-10-13 10:33:46.562124 | controller -> localhost | The key's randomart image is:
2025-10-13 10:33:46.562143 | controller -> localhost | +---[RSA 3072]----+
2025-10-13 10:33:46.562163 | controller -> localhost | | .=Bo.           |
2025-10-13 10:33:46.562184 | controller -> localhost | |  +.o            |
2025-10-13 10:33:46.562203 | controller -> localhost | | +.o.+.          |
2025-10-13 10:33:46.562222 | controller -> localhost | |+.B.oo=.         |
2025-10-13 10:33:46.562241 | controller -> localhost | |.*.+o+ .S        |
2025-10-13 10:33:46.562274 | controller -> localhost | |=.*.=            |
2025-10-13 10:33:46.562295 | controller -> localhost | |+B.= o .         |
2025-10-13 10:33:46.562313 | controller -> localhost | |+=. o o.         |
2025-10-13 10:33:46.562331 | controller -> localhost | |Oo ..o. .E       |
2025-10-13 10:33:46.562349 | controller -> localhost | +----[SHA256]-----+
2025-10-13 10:33:46.562390 | controller -> localhost | ok: Runtime: 0:00:00.230823
2025-10-13 10:33:46.567999 | 
2025-10-13 10:33:46.568063 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-13 10:33:46.626315 | controller | ok
2025-10-13 10:33:46.641664 | controller | included: /var/lib/zuul/builds/da9547f6dece43f1af0b598f2762776f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-13 10:33:46.657674 | 
2025-10-13 10:33:46.657740 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-13 10:33:46.705373 | controller | skipping: Conditional result was False
2025-10-13 10:33:46.710447 | 
2025-10-13 10:33:46.710512 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-13 10:33:47.378171 | controller | changed
2025-10-13 10:33:47.384876 | 
2025-10-13 10:33:47.384966 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-13 10:33:47.670050 | controller | ok
2025-10-13 10:33:47.675496 | 
2025-10-13 10:33:47.675565 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-13 10:33:48.464341 | controller | changed
2025-10-13 10:33:48.469365 | 
2025-10-13 10:33:48.469446 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-13 10:33:49.276789 | controller | changed
2025-10-13 10:33:49.281790 | 
2025-10-13 10:33:49.281865 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-13 10:33:49.337796 | controller | skipping: Conditional result was False
2025-10-13 10:33:49.343187 | 
2025-10-13 10:33:49.343254 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-13 10:33:50.325386 | controller -> localhost | changed
2025-10-13 10:33:50.334049 | 
2025-10-13 10:33:50.334109 | TASK [add-build-sshkey : Add back temp key]
2025-10-13 10:33:51.275201 | controller -> localhost | Identity added: /var/lib/zuul/builds/da9547f6dece43f1af0b598f2762776f/work/da9547f6dece43f1af0b598f2762776f_id_rsa (zuul-build-sshkey)
2025-10-13 10:33:51.275429 | controller -> localhost | ok: Runtime: 0:00:00.018325
2025-10-13 10:33:51.281039 | 
2025-10-13 10:33:51.281104 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-13 10:33:51.769867 | controller | ok
2025-10-13 10:33:51.777238 | 
2025-10-13 10:33:51.777299 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-13 10:33:51.882352 | controller | skipping: Conditional result was False
2025-10-13 10:33:51.896670 | 
2025-10-13 10:33:51.896745 | TASK [include_role : validate-host]
2025-10-13 10:33:51.971786 | controller | ok
2025-10-13 10:33:52.038154 | 
2025-10-13 10:33:52.038241 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-13 10:33:52.104900 | controller | ok
2025-10-13 10:33:52.110979 | 
2025-10-13 10:33:52.111048 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-13 10:33:52.927855 | controller -> localhost | ok
2025-10-13 10:33:52.933328 | 
2025-10-13 10:33:52.933390 | TASK [validate-host : Collect information about the host]
2025-10-13 10:33:53.646447 | controller | ok
2025-10-13 10:33:53.664951 | 
2025-10-13 10:33:53.665014 | TASK [validate-host : Sanitize hostname]
2025-10-13 10:33:53.809412 | controller | ok
2025-10-13 10:33:53.818816 | 
2025-10-13 10:33:53.818876 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-13 10:33:55.684917 | controller -> localhost | changed
2025-10-13 10:33:55.690225 | 
2025-10-13 10:33:55.690291 | TASK [validate-host : Collect information about zuul worker]
2025-10-13 10:33:56.238923 | controller | ok
2025-10-13 10:33:56.250694 | 
2025-10-13 10:33:56.250768 | TASK [validate-host : Write out all zuul information for each host]
2025-10-13 10:33:57.731064 | controller -> localhost | changed
2025-10-13 10:33:57.743660 | 
2025-10-13 10:33:57.743728 | TASK [include_role : prepare-workspace-openshift]
2025-10-13 10:33:57.823445 | controller | skipping: Conditional result was False
2025-10-13 10:33:57.828721 | 
2025-10-13 10:33:57.828784 | TASK [include_role : remove-zuul-sshkey]
2025-10-13 10:33:57.881883 | controller | skipping: Conditional result was False
2025-10-13 10:33:57.886930 | 
2025-10-13 10:33:57.886996 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-13 10:33:58.174898 | controller | ok: "logs"
2025-10-13 10:33:58.175371 | controller | ok: All items complete
2025-10-13 10:33:58.175411 | 
2025-10-13 10:33:58.363559 | controller | ok: "artifacts"
2025-10-13 10:33:58.541801 | controller | ok: "docs"
2025-10-13 10:33:58.554861 | 
2025-10-13 10:33:58.554973 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-13 10:33:58.874843 | controller | changed: "logs"
2025-10-13 10:33:59.049173 | controller | changed: "artifacts"
2025-10-13 10:33:59.241954 | controller | changed: "docs"
2025-10-13 10:33:59.308593 | 
2025-10-13 10:33:59.308679 | PLAY RECAP
2025-10-13 10:33:59.308724 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 10:33:59.308753 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 10:33:59.308772 | 
2025-10-13 10:33:59.635437 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 10:33:59.636201 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 10:34:02.757439 | 
2025-10-13 10:34:02.757543 | PLAY [all]
2025-10-13 10:34:02.799030 | 
2025-10-13 10:34:02.799109 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-13 10:34:03.002326 | controller | ok
2025-10-13 10:34:03.017905 | 
2025-10-13 10:34:03.018076 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-13 10:34:03.682264 | controller | changed
2025-10-13 10:34:03.691332 | 
2025-10-13 10:34:03.691696 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-13 10:34:04.979620 | controller | changed
2025-10-13 10:34:05.007895 | 
2025-10-13 10:34:05.008010 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-13 10:34:06.208145 | controller | changed:
2025-10-13 10:34:06.208315 | controller | {
2025-10-13 10:34:06.208349 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-13 10:34:06.208381 | controller | }
2025-10-13 10:34:06.426327 | controller | changed:
2025-10-13 10:34:06.426411 | controller | {
2025-10-13 10:34:06.426441 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-13 10:34:06.426464 | controller | }
2025-10-13 10:34:06.643261 | controller | changed:
2025-10-13 10:34:06.643357 | controller | {
2025-10-13 10:34:06.643386 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-13 10:34:06.643409 | controller | }
2025-10-13 10:34:06.863693 | controller | changed:
2025-10-13 10:34:06.863770 | controller | {
2025-10-13 10:34:06.863799 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-13 10:34:06.863821 | controller | }
2025-10-13 10:34:07.076270 | controller | changed:
2025-10-13 10:34:07.076348 | controller | {
2025-10-13 10:34:07.076377 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-13 10:34:07.076398 | controller | }
2025-10-13 10:34:07.296571 | controller | changed:
2025-10-13 10:34:07.296659 | controller | {
2025-10-13 10:34:07.296688 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-13 10:34:07.296710 | controller | }
2025-10-13 10:34:07.514889 | controller | changed:
2025-10-13 10:34:07.514987 | controller | {
2025-10-13 10:34:07.515016 | 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-10-13 10:34:07.515039 | controller | }
2025-10-13 10:34:07.748631 | controller | changed:
2025-10-13 10:34:07.748765 | controller | {
2025-10-13 10:34:07.748798 | 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-10-13 10:34:07.748822 | controller | }
2025-10-13 10:34:08.072098 | controller | changed:
2025-10-13 10:34:08.072191 | controller | {
2025-10-13 10:34:08.072221 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-13 10:34:08.072244 | controller | }
2025-10-13 10:34:08.185502 | controller | changed:
2025-10-13 10:34:08.185574 | controller | {
2025-10-13 10:34:08.185614 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-13 10:34:08.185638 | controller | }
2025-10-13 10:34:08.399021 | controller | changed:
2025-10-13 10:34:08.399109 | controller | {
2025-10-13 10:34:08.399137 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-13 10:34:08.399159 | controller | }
2025-10-13 10:34:08.617065 | controller | changed:
2025-10-13 10:34:08.617201 | controller | {
2025-10-13 10:34:08.617232 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-13 10:34:08.617254 | controller | }
2025-10-13 10:34:08.856010 | controller | changed:
2025-10-13 10:34:08.856087 | controller | {
2025-10-13 10:34:08.856115 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-13 10:34:08.856137 | controller | }
2025-10-13 10:34:09.071073 | controller | changed:
2025-10-13 10:34:09.071157 | controller | {
2025-10-13 10:34:09.071185 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-13 10:34:09.071207 | controller | }
2025-10-13 10:34:09.308378 | controller | changed:
2025-10-13 10:34:09.308463 | controller | {
2025-10-13 10:34:09.308491 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-13 10:34:09.308513 | controller | }
2025-10-13 10:34:09.537882 | controller | changed:
2025-10-13 10:34:09.537994 | controller | {
2025-10-13 10:34:09.538023 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-13 10:34:09.538044 | controller | }
2025-10-13 10:34:09.730732 | controller | changed:
2025-10-13 10:34:09.730815 | controller | {
2025-10-13 10:34:09.730843 | 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-10-13 10:34:09.730865 | controller | }
2025-10-13 10:34:09.945050 | controller | changed:
2025-10-13 10:34:09.945133 | controller | {
2025-10-13 10:34:09.945163 | 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-10-13 10:34:09.945193 | controller | }
2025-10-13 10:34:10.167189 | controller | changed:
2025-10-13 10:34:10.167348 | controller | {
2025-10-13 10:34:10.167379 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-13 10:34:10.167400 | controller | }
2025-10-13 10:34:10.384667 | controller | changed:
2025-10-13 10:34:10.384751 | controller | {
2025-10-13 10:34:10.384778 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-13 10:34:10.384800 | controller | }
2025-10-13 10:34:10.598756 | controller | changed:
2025-10-13 10:34:10.598834 | controller | {
2025-10-13 10:34:10.598863 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-13 10:34:10.598886 | controller | }
2025-10-13 10:34:10.841526 | controller | changed:
2025-10-13 10:34:10.841665 | controller | {
2025-10-13 10:34:10.841699 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-13 10:34:10.841722 | controller | }
2025-10-13 10:34:11.070005 | controller | changed:
2025-10-13 10:34:11.070077 | controller | {
2025-10-13 10:34:11.070106 | 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-10-13 10:34:11.070129 | controller | }
2025-10-13 10:34:11.303520 | controller | changed:
2025-10-13 10:34:11.303593 | controller | {
2025-10-13 10:34:11.303632 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-13 10:34:11.303654 | controller | }
2025-10-13 10:34:11.526992 | controller | changed:
2025-10-13 10:34:11.527096 | controller | {
2025-10-13 10:34:11.527125 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-13 10:34:11.527146 | controller | }
2025-10-13 10:34:11.741152 | controller | changed:
2025-10-13 10:34:11.741270 | controller | {
2025-10-13 10:34:11.741302 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-13 10:34:11.741324 | controller | }
2025-10-13 10:34:11.773085 | 
2025-10-13 10:34:11.773163 | TASK [Set timezone to UTC]
2025-10-13 10:34:12.638807 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-13 10:34:12.646212 | 
2025-10-13 10:34:12.646282 | TASK [Create nodepool directory]
2025-10-13 10:34:12.912962 | controller | changed
2025-10-13 10:34:12.922040 | 
2025-10-13 10:34:12.922126 | TASK [Create nodepool sub_nodes file]
2025-10-13 10:34:13.600138 | controller | changed
2025-10-13 10:34:13.605169 | 
2025-10-13 10:34:13.605239 | TASK [Create nodepool sub_nodes_private file]
2025-10-13 10:34:14.321031 | controller | changed
2025-10-13 10:34:14.326732 | 
2025-10-13 10:34:14.326855 | LOOP [Populate nodepool sub_nodes file]
2025-10-13 10:34:14.420259 | 
2025-10-13 10:34:14.420398 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-13 10:34:14.524294 | 
2025-10-13 10:34:14.524526 | TASK [Create nodepool primary file]
2025-10-13 10:34:14.615021 | controller | skipping: Conditional result was False
2025-10-13 10:34:14.620723 | 
2025-10-13 10:34:14.620820 | TASK [Create nodepool node_private for this node]
2025-10-13 10:34:15.474498 | controller | changed
2025-10-13 10:34:15.483753 | 
2025-10-13 10:34:15.483833 | LOOP [Copy ssh keys to nodepool directory]
2025-10-13 10:34:16.310955 | controller | ok: Item: id_rsa Runtime: 0:00:00.008253
2025-10-13 10:34:16.311123 | 
2025-10-13 10:34:16.496396 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005698
2025-10-13 10:34:16.509722 | 
2025-10-13 10:34:16.509807 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-13 10:34:17.253657 | controller | changed
2025-10-13 10:34:17.258960 | 
2025-10-13 10:34:17.259023 | TASK [Validate sudoers config after edits]
2025-10-13 10:34:17.685943 | controller | /etc/sudoers: parsed OK
2025-10-13 10:34:17.686048 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-13 10:34:17.686062 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-13 10:34:17.686068 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-13 10:34:17.855178 | controller | ok: Runtime: 0:00:00.006447
2025-10-13 10:34:17.860971 | 
2025-10-13 10:34:17.861034 | TASK [Show the environment passed in to job shell scripts]
2025-10-13 10:34:18.204493 | controller | SHELL=/bin/bash
2025-10-13 10:34:18.204534 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-13 10:34:18.204544 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-13 10:34:18.204550 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/02/3402/c2e3cdb106c3c91ed9e4c69bd61e0ffd9bd88749
2025-10-13 10:34:18.204556 | controller | PWD=/home/zuul
2025-10-13 10:34:18.204561 | controller | ZUUL_PIPELINE=github-check
2025-10-13 10:34:18.204566 | controller | LOGNAME=zuul
2025-10-13 10:34:18.204571 | controller | XDG_SESSION_TYPE=tty
2025-10-13 10:34:18.204577 | controller | _=/usr/bin/env
2025-10-13 10:34:18.204582 | controller | MOTD_SHOWN=pam
2025-10-13 10:34:18.204587 | controller | HOME=/home/zuul
2025-10-13 10:34:18.204592 | controller | LANG=en_US.UTF-8
2025-10-13 10:34:18.204629 | controller | SSH_CONNECTION=192.168.26.12 40648 192.168.26.155 22
2025-10-13 10:34:18.204637 | 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-10-13 10:34:18.204645 | controller | ZUUL_CHANGE_IDS=3402,c2e3cdb106c3c91ed9e4c69bd61e0ffd9bd88749
2025-10-13 10:34:18.204651 | controller | WORKSPACE=/home/zuul/workspace
2025-10-13 10:34:18.204656 | controller | XDG_SESSION_CLASS=user
2025-10-13 10:34:18.204661 | controller | SELINUX_ROLE_REQUESTED=
2025-10-13 10:34:18.204666 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-13 10:34:18.204671 | controller | USER=zuul
2025-10-13 10:34:18.204677 | controller | ZUUL_VOTING=True
2025-10-13 10:34:18.204682 | controller | BUILD_TIMEOUT=1800000
2025-10-13 10:34:18.204687 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-13 10:34:18.204692 | controller | SHLVL=1
2025-10-13 10:34:18.204697 | controller | ZUUL_PATCHSET=c2e3cdb106c3c91ed9e4c69bd61e0ffd9bd88749
2025-10-13 10:34:18.204703 | controller | XDG_SESSION_ID=1
2025-10-13 10:34:18.204708 | controller | ZUUL_BRANCH=main
2025-10-13 10:34:18.204713 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-13 10:34:18.204718 | controller | SSH_CLIENT=192.168.26.12 40648 22
2025-10-13 10:34:18.204723 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-13 10:34:18.204728 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-13 10:34:18.204733 | controller | which_declare=declare -f
2025-10-13 10:34:18.204739 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-13 10:34:18.204744 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-13 10:34:18.204749 | controller | ZUUL_CHANGE=3402
2025-10-13 10:34:18.204754 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-13 10:34:18.204759 | controller | ZUUL_UUID=da9547f6dece43f1af0b598f2762776f
2025-10-13 10:34:18.204766 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-13 10:34:18.204772 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-13 10:34:18.204777 | controller | }
2025-10-13 10:34:18.476026 | controller | ok: Runtime: 0:00:00.007564
2025-10-13 10:34:18.486330 | 
2025-10-13 10:34:18.486394 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-13 10:34:18.550148 | controller | skipping: Conditional result was False
2025-10-13 10:34:18.559922 | 
2025-10-13 10:34:18.562112 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-13 10:34:19.169511 | controller | skipping: Conditional result was False
2025-10-13 10:34:19.269772 | 
2025-10-13 10:34:19.269873 | TASK [Ensure legacy workspace directory]
2025-10-13 10:34:19.544726 | controller | changed
2025-10-13 10:34:19.633438 | 
2025-10-13 10:34:19.633519 | PLAY RECAP
2025-10-13 10:34:19.633568 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 10:34:19.633592 | 
2025-10-13 10:34:19.924496 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 10:34:19.925674 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 10:34:21.986256 | 
2025-10-13 10:34:21.986369 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-13 10:34:22.046852 | 
2025-10-13 10:34:22.050214 | TASK [Create zuul-output directory]
2025-10-13 10:34:22.867070 | controller | changed
2025-10-13 10:34:22.873051 | 
2025-10-13 10:34:22.873129 | TASK [Slurp Zuul inventory test]
2025-10-13 10:34:24.359224 | controller -> localhost | ok
2025-10-13 10:34:24.366039 | 
2025-10-13 10:34:24.366107 | TASK [Save zuul inventory]
2025-10-13 10:34:26.258591 | controller | changed
2025-10-13 10:34:26.264866 | 
2025-10-13 10:34:26.264972 | TASK [Save zuul vars without the change_message]
2025-10-13 10:34:27.417524 | controller | changed
2025-10-13 10:34:27.484284 | 
2025-10-13 10:34:27.484366 | PLAY RECAP
2025-10-13 10:34:27.484413 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 10:34:27.484437 | 
2025-10-13 10:34:27.839513 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 10:34:27.840278 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-13 10:34:30.349229 | 
2025-10-13 10:34:30.349332 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-13 10:34:30.440461 | 
2025-10-13 10:34:30.440573 | TASK [Read group_vars]
2025-10-13 10:34:30.519318 | controller | ok
2025-10-13 10:34:30.565831 | 
2025-10-13 10:34:30.565955 | TASK [cifmw_helpers : Check directory is available /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars]
2025-10-13 10:34:31.215810 | controller | ok
2025-10-13 10:34:31.224560 | 
2025-10-13 10:34:31.224644 | TASK [cifmw_helpers : List available files]
2025-10-13 10:34:31.876695 | controller | skipping: Conditional result was False
2025-10-13 10:34:31.889215 | 
2025-10-13 10:34:31.889320 | LOOP [cifmw_helpers : Read vars]
2025-10-13 10:34:31.989056 | 
2025-10-13 10:34:31.989291 | LOOP [cifmw_helpers : Call task to parse all files as fact]
2025-10-13 10:34:32.087540 | 
2025-10-13 10:34:32.087697 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-13 10:34:32.171235 | controller | ok
2025-10-13 10:34:32.289372 | 
2025-10-13 10:34:32.289494 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-13 10:34:32.387931 | controller | skipping: Conditional result was False
2025-10-13 10:34:32.399237 | 
2025-10-13 10:34:32.399337 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-13 10:34:33.255345 | controller | ok
2025-10-13 10:34:33.263059 | 
2025-10-13 10:34:33.263128 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-13 10:34:33.959806 | controller | ok
2025-10-13 10:34:34.049432 | 
2025-10-13 10:34:34.049543 | TASK [Prepare workspace]
2025-10-13 10:34:34.161492 | controller | ok
2025-10-13 10:34:34.262089 | 
2025-10-13 10:34:34.264330 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-13 10:34:35.155584 | controller | ok
2025-10-13 10:34:35.175315 | 
2025-10-13 10:34:35.175410 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-13 10:34:45.150642 | controller | Output suppressed because no_log was given
2025-10-13 10:34:45.178178 | 
2025-10-13 10:34:45.178271 | LOOP [Create zuul-output directory]
2025-10-13 10:34:45.488298 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-13 10:34:45.488541 | 
2025-10-13 10:34:45.651791 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-13 10:34:45.667685 | 
2025-10-13 10:34:45.667762 | TASK [Install required packages]
2025-10-13 10:35:46.968113 | controller | changed
2025-10-13 10:35:46.973616 | 
2025-10-13 10:35:46.973699 | TASK [Install venv]
2025-10-13 10:36:53.852172 | controller | changed
2025-10-13 10:36:53.878429 | 
2025-10-13 10:36:53.878517 | PLAY RECAP
2025-10-13 10:36:53.878563 | controller | ok: 8 changed: 4 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0
2025-10-13 10:36:53.878588 | 
2025-10-13 10:36:53.954622 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-13 10:36:53.955444 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-13 10:36:54.514948 | 
2025-10-13 10:36:54.515074 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-13 10:36:54.537284 | 
2025-10-13 10:36:54.537396 | TASK [Gather required facts]
2025-10-13 10:36:55.023420 | controller | ok
2025-10-13 10:36:55.028948 | 
2025-10-13 10:36:55.029044 | TASK [Load environment var if instructed to]
2025-10-13 10:36:55.053461 | controller | skipping: Conditional result was False
2025-10-13 10:36:55.059038 | 
2025-10-13 10:36:55.059112 | TASK [Ensure group_vars dir exists]
2025-10-13 10:36:55.362544 | controller | ok
2025-10-13 10:36:55.368835 | 
2025-10-13 10:36:55.368943 | TASK [Run molecule]
2025-10-13 10:36:56.307162 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-13 10:36:56.375976 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-13 10:37:20.511827 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:37:20.512301 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:37:20.512784 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:37:20.513243 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:37:20.513748 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+490278e0 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 1.0.0+490278e0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:37:20.514200 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:37:20.514634 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:37:20.515092 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:37:20.515543 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:37:20.515948 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:37:20.516395 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:37:20.516836 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:37:20.517299 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:37:20.517748 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:37:20.518216 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/9b0c27/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:37:20.527685 | controller | INFO     Running default > prepare
2025-10-13 10:37:21.226397 | controller |
2025-10-13 10:37:21.226480 | controller | PLAY [Prepare] *****************************************************************
2025-10-13 10:37:21.226575 | controller |
2025-10-13 10:37:21.226676 | controller | TASK [Gathering Facts] *********************************************************
2025-10-13 10:37:21.226769 | controller | Monday 13 October 2025  10:37:21 +0000 (0:00:00.021)       0:00:00.021 ********
2025-10-13 10:37:22.039161 | controller | ok: [instance]
2025-10-13 10:37:22.039218 | controller |
2025-10-13 10:37:22.039354 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-13 10:37:22.039551 | controller | Monday 13 October 2025  10:37:22 +0000 (0:00:00.813)       0:00:00.834 ********
2025-10-13 10:37:22.056414 | controller | skipping: [instance]
2025-10-13 10:37:22.056539 | controller |
2025-10-13 10:37:22.056662 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-13 10:37:22.056785 | controller | Monday 13 October 2025  10:37:22 +0000 (0:00:00.017)       0:00:00.852 ********
2025-10-13 10:37:22.087045 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-13 10:37:22.087111 | controller |
2025-10-13 10:37:22.087212 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-13 10:37:22.087316 | controller | Monday 13 October 2025  10:37:22 +0000 (0:00:00.030)       0:00:00.883 ********
2025-10-13 10:37:22.361425 | controller | ok: [instance]
2025-10-13 10:37:22.361488 | controller |
2025-10-13 10:37:22.361605 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-13 10:37:22.361706 | controller | Monday 13 October 2025  10:37:22 +0000 (0:00:00.274)       0:00:01.157 ********
2025-10-13 10:37:22.655132 | controller | ok: [instance]
2025-10-13 10:37:22.655203 | controller |
2025-10-13 10:37:22.655315 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-13 10:37:22.655430 | controller | Monday 13 October 2025  10:37:22 +0000 (0:00:00.293)       0:00:01.450 ********
2025-10-13 10:37:23.311232 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-13 10:37:23.311315 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-13 10:37:23.311431 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-13 10:37:23.311530 | controller |
2025-10-13 10:37:23.311631 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-13 10:37:23.311736 | controller | Monday 13 October 2025  10:37:23 +0000 (0:00:00.656)       0:00:02.107 ********
2025-10-13 10:37:23.370731 | controller |
2025-10-13 10:37:23.370804 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-13 10:37:23.370912 | controller | Monday 13 October 2025  10:37:23 +0000 (0:00:00.059)       0:00:02.166 ********
2025-10-13 10:37:23.903930 | controller | changed: [instance] => (item=tmp)
2025-10-13 10:37:23.904029 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-13 10:37:23.904133 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-13 10:37:23.904230 | controller |
2025-10-13 10:37:23.904335 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-13 10:37:23.904444 | controller | Monday 13 October 2025  10:37:23 +0000 (0:00:00.533)       0:00:02.699 ********
2025-10-13 10:37:24.660296 | controller | ok: [instance]
2025-10-13 10:37:24.660594 | controller |
2025-10-13 10:37:25.849176 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-13 10:37:25.849212 | controller | Monday 13 October 2025  10:37:24 +0000 (0:00:00.756)       0:00:03.456 ********
2025-10-13 10:37:25.849224 | controller | changed: [instance]
2025-10-13 10:37:33.745779 | controller |
2025-10-13 10:37:33.745819 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-13 10:37:33.745832 | controller | Monday 13 October 2025  10:37:25 +0000 (0:00:01.188)       0:00:04.644 ********
2025-10-13 10:37:33.745844 | controller | changed: [instance]
2025-10-13 10:37:34.463741 | controller |
2025-10-13 10:37:34.463781 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-13 10:37:34.463790 | controller | Monday 13 October 2025  10:37:33 +0000 (0:00:07.896)       0:00:12.541 ********
2025-10-13 10:37:34.463801 | controller | changed: [instance]
2025-10-13 10:37:34.485735 | controller |
2025-10-13 10:37:34.485761 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-13 10:37:34.485768 | controller | Monday 13 October 2025  10:37:34 +0000 (0:00:00.717)       0:00:13.259 ********
2025-10-13 10:37:34.485779 | controller | skipping: [instance]
2025-10-13 10:37:35.344592 | controller |
2025-10-13 10:37:35.344622 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-13 10:37:35.344630 | controller | Monday 13 October 2025  10:37:34 +0000 (0:00:00.022)       0:00:13.281 ********
2025-10-13 10:37:35.344640 | controller | changed: [instance]
2025-10-13 10:37:35.377640 | controller |
2025-10-13 10:37:35.377667 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-13 10:37:35.377675 | controller | Monday 13 October 2025  10:37:35 +0000 (0:00:00.858)       0:00:14.140 ********
2025-10-13 10:37:35.377685 | controller | skipping: [instance]
2025-10-13 10:37:35.405639 | controller |
2025-10-13 10:37:35.405659 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-13 10:37:35.405666 | controller | Monday 13 October 2025  10:37:35 +0000 (0:00:00.033)       0:00:14.173 ********
2025-10-13 10:37:35.405675 | controller | skipping: [instance]
2025-10-13 10:37:35.433067 | controller |
2025-10-13 10:37:35.433088 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-13 10:37:35.433097 | controller | Monday 13 October 2025  10:37:35 +0000 (0:00:00.027)       0:00:14.200 ********
2025-10-13 10:37:35.433108 | controller | skipping: [instance]
2025-10-13 10:37:35.981746 | controller |
2025-10-13 10:37:35.981778 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-13 10:37:35.981787 | controller | Monday 13 October 2025  10:37:35 +0000 (0:00:00.027)       0:00:14.228 ********
2025-10-13 10:37:35.981797 | controller | changed: [instance]
2025-10-13 10:37:36.422724 | controller |
2025-10-13 10:37:36.422756 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-13 10:37:36.422765 | controller | Monday 13 October 2025  10:37:35 +0000 (0:00:00.548)       0:00:14.777 ********
2025-10-13 10:37:36.422775 | controller | changed: [instance]
2025-10-13 10:37:36.441562 | controller |
2025-10-13 10:37:36.441588 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-13 10:37:36.441596 | controller | Monday 13 October 2025  10:37:36 +0000 (0:00:00.440)       0:00:15.218 ********
2025-10-13 10:37:36.441606 | controller | skipping: [instance]
2025-10-13 10:37:36.460074 | controller |
2025-10-13 10:37:36.460108 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-13 10:37:36.460117 | controller | Monday 13 October 2025  10:37:36 +0000 (0:00:00.018)       0:00:15.237 ********
2025-10-13 10:37:36.460130 | controller | skipping: [instance]
2025-10-13 10:37:36.460153 | controller |
2025-10-13 10:37:36.460335 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-13 10:37:36.477518 | controller | Monday 13 October 2025  10:37:36 +0000 (0:00:00.018)       0:00:15.255 ********
2025-10-13 10:37:36.477539 | controller | skipping: [instance]
2025-10-13 10:37:36.505741 | controller |
2025-10-13 10:37:36.505764 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-13 10:37:36.505771 | controller | Monday 13 October 2025  10:37:36 +0000 (0:00:00.017)       0:00:15.273 ********
2025-10-13 10:37:36.505780 | controller | ok: [instance]
2025-10-13 10:37:36.521927 | controller |
2025-10-13 10:37:36.521945 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-13 10:37:36.521952 | controller | Monday 13 October 2025  10:37:36 +0000 (0:00:00.028)       0:00:15.301 ********
2025-10-13 10:37:36.521960 | controller | skipping: [instance]
2025-10-13 10:37:36.537622 | controller |
2025-10-13 10:37:36.537639 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-13 10:37:36.537646 | controller | Monday 13 October 2025  10:37:36 +0000 (0:00:00.016)       0:00:15.317 ********
2025-10-13 10:37:36.537663 | controller | skipping: [instance]
2025-10-13 10:37:36.553528 | controller |
2025-10-13 10:37:36.553545 | controller | TASK [Download the RPM] ********************************************************
2025-10-13 10:37:36.553552 | controller | Monday 13 October 2025  10:37:36 +0000 (0:00:00.015)       0:00:15.333 ********
2025-10-13 10:37:36.553561 | controller | skipping: [instance]
2025-10-13 10:37:36.568806 | controller |
2025-10-13 10:37:36.568824 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-13 10:37:36.568831 | controller | Monday 13 October 2025  10:37:36 +0000 (0:00:00.015)       0:00:15.349 ********
2025-10-13 10:37:36.568839 | controller | skipping: [instance]
2025-10-13 10:37:36.584014 | controller |
2025-10-13 10:37:36.584031 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-13 10:37:36.584038 | controller | Monday 13 October 2025  10:37:36 +0000 (0:00:00.015)       0:00:15.364 ********
2025-10-13 10:37:36.584047 | controller | skipping: [instance]
2025-10-13 10:37:36.584253 | controller |
2025-10-13 10:37:36.584270 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-13 10:37:36.600826 | controller | Monday 13 October 2025  10:37:36 +0000 (0:00:00.015)       0:00:15.380 ********
2025-10-13 10:37:36.600846 | controller | skipping: [instance]
2025-10-13 10:37:36.616503 | controller |
2025-10-13 10:37:36.616520 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-13 10:37:36.616527 | controller | Monday 13 October 2025  10:37:36 +0000 (0:00:00.016)       0:00:15.396 ********
2025-10-13 10:37:36.616536 | controller | skipping: [instance]
2025-10-13 10:37:36.800717 | controller |
2025-10-13 10:37:36.800747 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-13 10:37:36.800755 | controller | Monday 13 October 2025  10:37:36 +0000 (0:00:00.015)       0:00:15.412 ********
2025-10-13 10:37:36.800765 | controller | ok: [instance]
2025-10-13 10:37:37.010057 | controller |
2025-10-13 10:37:37.010088 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-13 10:37:37.010096 | controller | Monday 13 October 2025  10:37:36 +0000 (0:00:00.183)       0:00:15.596 ********
2025-10-13 10:37:37.010107 | controller | changed: [instance]
2025-10-13 10:37:37.010133 | controller |
2025-10-13 10:37:37.010346 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-13 10:37:37.228142 | controller | Monday 13 October 2025  10:37:37 +0000 (0:00:00.209)       0:00:15.805 ********
2025-10-13 10:37:37.228175 | controller | changed: [instance]
2025-10-13 10:37:37.249026 | controller |
2025-10-13 10:37:37.249053 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-13 10:37:37.249061 | controller | Monday 13 October 2025  10:37:37 +0000 (0:00:00.217)       0:00:16.023 ********
2025-10-13 10:37:37.249071 | controller | skipping: [instance]
2025-10-13 10:37:37.270908 | controller |
2025-10-13 10:37:37.270926 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-13 10:37:37.270933 | controller | Monday 13 October 2025  10:37:37 +0000 (0:00:00.021)       0:00:16.044 ********
2025-10-13 10:37:37.270941 | controller | skipping: [instance]
2025-10-13 10:37:37.291837 | controller |
2025-10-13 10:37:37.291854 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-13 10:37:37.291860 | controller | Monday 13 October 2025  10:37:37 +0000 (0:00:00.021)       0:00:16.066 ********
2025-10-13 10:37:37.291869 | controller | skipping: [instance]
2025-10-13 10:37:37.313024 | controller |
2025-10-13 10:37:37.313040 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-13 10:37:37.313047 | controller | Monday 13 October 2025  10:37:37 +0000 (0:00:00.020)       0:00:16.087 ********
2025-10-13 10:37:37.313056 | controller | skipping: [instance]
2025-10-13 10:37:37.335114 | controller |
2025-10-13 10:37:37.335130 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-13 10:37:37.335142 | controller | Monday 13 October 2025  10:37:37 +0000 (0:00:00.021)       0:00:16.108 ********
2025-10-13 10:37:37.335151 | controller | skipping: [instance]
2025-10-13 10:37:37.356452 | controller |
2025-10-13 10:37:37.356484 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-13 10:37:37.356501 | controller | Monday 13 October 2025  10:37:37 +0000 (0:00:00.021)       0:00:16.130 ********
2025-10-13 10:37:37.356512 | controller | skipping: [instance]
2025-10-13 10:37:37.356689 | controller |
2025-10-13 10:37:37.356864 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-13 10:37:37.357035 | controller | Monday 13 October 2025  10:37:37 +0000 (0:00:00.021)       0:00:16.152 ********
2025-10-13 10:37:37.375635 | controller | skipping: [instance]
2025-10-13 10:37:37.395246 | controller |
2025-10-13 10:37:37.395263 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-13 10:37:37.395270 | controller | Monday 13 October 2025  10:37:37 +0000 (0:00:00.018)       0:00:16.171 ********
2025-10-13 10:37:37.395278 | controller | skipping: [instance]
2025-10-13 10:37:37.411556 | controller |
2025-10-13 10:37:37.411575 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-13 10:37:37.411590 | controller | Monday 13 October 2025  10:37:37 +0000 (0:00:00.019)       0:00:16.191 ********
2025-10-13 10:37:37.411601 | controller | skipping: [instance]
2025-10-13 10:37:37.431954 | controller |
2025-10-13 10:37:37.431992 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-13 10:37:37.432000 | controller | Monday 13 October 2025  10:37:37 +0000 (0:00:00.016)       0:00:16.207 ********
2025-10-13 10:37:37.432011 | controller | skipping: [instance]
2025-10-13 10:37:37.452290 | controller |
2025-10-13 10:37:37.452307 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-13 10:37:37.452313 | controller | Monday 13 October 2025  10:37:37 +0000 (0:00:00.020)       0:00:16.227 ********
2025-10-13 10:37:37.452322 | controller | skipping: [instance]
2025-10-13 10:37:37.472716 | controller |
2025-10-13 10:37:37.472736 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-13 10:37:37.472745 | controller | Monday 13 October 2025  10:37:37 +0000 (0:00:00.020)       0:00:16.248 ********
2025-10-13 10:37:37.472755 | controller | skipping: [instance]
2025-10-13 10:37:37.492595 | controller |
2025-10-13 10:37:37.492612 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-13 10:37:37.492619 | controller | Monday 13 October 2025  10:37:37 +0000 (0:00:00.020)       0:00:16.268 ********
2025-10-13 10:37:37.492627 | controller | skipping: [instance]
2025-10-13 10:37:37.513451 | controller |
2025-10-13 10:37:37.513484 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-13 10:37:37.513492 | controller | Monday 13 October 2025  10:37:37 +0000 (0:00:00.020)       0:00:16.288 ********
2025-10-13 10:37:37.513500 | controller | skipping: [instance]
2025-10-13 10:38:28.004826 | controller |
2025-10-13 10:38:28.004860 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-13 10:38:28.004868 | controller | Monday 13 October 2025  10:37:37 +0000 (0:00:00.020)       0:00:16.309 ********
2025-10-13 10:38:28.004878 | controller | ok: [instance]
2025-10-13 10:38:29.150559 | controller |
2025-10-13 10:38:29.150588 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-13 10:38:29.150597 | controller | Monday 13 October 2025  10:38:28 +0000 (0:00:50.491)       0:01:06.800 ********
2025-10-13 10:38:29.150607 | controller | ok: [instance]
2025-10-13 10:38:29.173102 | controller |
2025-10-13 10:38:29.173137 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-13 10:38:29.173145 | controller | Monday 13 October 2025  10:38:29 +0000 (0:00:01.145)       0:01:07.946 ********
2025-10-13 10:38:29.173156 | controller | skipping: [instance]
2025-10-13 10:38:29.207467 | controller |
2025-10-13 10:38:29.207490 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-10-13 10:38:29.207497 | controller | Monday 13 October 2025  10:38:29 +0000 (0:00:00.022)       0:01:07.968 ********
2025-10-13 10:38:29.207506 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-10-13 10:38:29.227047 | controller |
2025-10-13 10:38:29.227080 | controller | TASK [ci_setup : List packages to install] *************************************
2025-10-13 10:38:29.227088 | controller | Monday 13 October 2025  10:38:29 +0000 (0:00:00.034)       0:01:08.003 ********
2025-10-13 10:38:29.227106 | controller | ok: [instance] =>
2025-10-13 10:38:29.227130 | controller |   cifmw_ci_setup_packages:
2025-10-13 10:38:29.227138 | controller |   - bash-completion
2025-10-13 10:38:29.227144 | controller |   - ca-certificates
2025-10-13 10:38:29.227149 | controller |   - git-core
2025-10-13 10:38:29.227156 | controller |   - make
2025-10-13 10:38:29.227763 | controller |   - tar
2025-10-13 10:38:33.528838 | controller |   - tmux
2025-10-13 10:38:33.528867 | controller |   - python3-pip
2025-10-13 10:38:33.528875 | controller |
2025-10-13 10:38:33.528881 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-10-13 10:38:33.528887 | controller | Monday 13 October 2025  10:38:29 +0000 (0:00:00.019)       0:01:08.022 ********
2025-10-13 10:38:33.528919 | controller | changed: [instance]
2025-10-13 10:38:33.529115 | controller |
2025-10-13 10:38:33.529414 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-10-13 10:38:33.713568 | controller | Monday 13 October 2025  10:38:33 +0000 (0:00:04.301)       0:01:12.324 ********
2025-10-13 10:38:33.713601 | controller | ok: [instance]
2025-10-13 10:38:33.902007 | controller |
2025-10-13 10:38:33.902036 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-10-13 10:38:33.902044 | controller | Monday 13 October 2025  10:38:33 +0000 (0:00:00.184)       0:01:12.509 ********
2025-10-13 10:38:33.902054 | controller | changed: [instance]
2025-10-13 10:38:39.886356 | controller |
2025-10-13 10:38:39.886386 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-10-13 10:38:39.886394 | controller | Monday 13 October 2025  10:38:33 +0000 (0:00:00.188)       0:01:12.697 ********
2025-10-13 10:38:39.886404 | controller | changed: [instance]
2025-10-13 10:38:39.915822 | controller |
2025-10-13 10:38:39.915842 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-10-13 10:38:39.915850 | controller | Monday 13 October 2025  10:38:39 +0000 (0:00:05.984)       0:01:18.681 ********
2025-10-13 10:38:39.915859 | controller | ok: [instance]
2025-10-13 10:38:40.214043 | controller |
2025-10-13 10:38:40.214073 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-10-13 10:38:40.214081 | controller | Monday 13 October 2025  10:38:39 +0000 (0:00:00.029)       0:01:18.711 ********
2025-10-13 10:38:40.214091 | controller | changed: [instance]
2025-10-13 10:38:40.214107 | controller |
2025-10-13 10:38:40.214115 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-10-13 10:38:40.214167 | controller | Monday 13 October 2025  10:38:40 +0000 (0:00:00.298)       0:01:19.009 ********
2025-10-13 10:38:40.468058 | controller | changed: [instance]
2025-10-13 10:38:40.468109 | controller |
2025-10-13 10:38:40.468307 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-10-13 10:38:40.485619 | controller | Monday 13 October 2025  10:38:40 +0000 (0:00:00.254)       0:01:19.263 ********
2025-10-13 10:38:40.485639 | controller | skipping: [instance]
2025-10-13 10:38:40.501828 | controller |
2025-10-13 10:38:40.501846 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-10-13 10:38:40.501853 | controller | Monday 13 October 2025  10:38:40 +0000 (0:00:00.017)       0:01:19.281 ********
2025-10-13 10:38:40.501862 | controller | skipping: [instance]
2025-10-13 10:38:40.518400 | controller |
2025-10-13 10:38:40.518420 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-10-13 10:38:40.518429 | controller | Monday 13 October 2025  10:38:40 +0000 (0:00:00.016)       0:01:19.297 ********
2025-10-13 10:38:40.518439 | controller | skipping: [instance]
2025-10-13 10:38:40.534310 | controller |
2025-10-13 10:38:40.534327 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-10-13 10:38:40.534334 | controller | Monday 13 October 2025  10:38:40 +0000 (0:00:00.016)       0:01:19.314 ********
2025-10-13 10:38:40.534343 | controller | skipping: [instance]
2025-10-13 10:38:40.550070 | controller |
2025-10-13 10:38:40.550091 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-10-13 10:38:40.550099 | controller | Monday 13 October 2025  10:38:40 +0000 (0:00:00.016)       0:01:19.330 ********
2025-10-13 10:38:40.550110 | controller | skipping: [instance]
2025-10-13 10:38:40.550274 | controller |
2025-10-13 10:38:40.550442 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-10-13 10:38:40.550619 | controller | Monday 13 October 2025  10:38:40 +0000 (0:00:00.016)       0:01:19.346 ********
2025-10-13 10:38:40.573010 | controller | skipping: [instance]
2025-10-13 10:38:40.573200 | controller |
2025-10-13 10:38:40.573376 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-10-13 10:38:40.573547 | controller | Monday 13 October 2025  10:38:40 +0000 (0:00:00.022)       0:01:19.369 ********
2025-10-13 10:38:41.564012 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-10-13 10:38:41.768847 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-10-13 10:38:41.768878 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-10-13 10:38:41.768886 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-10-13 10:38:41.768893 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-10-13 10:38:41.768898 | controller |
2025-10-13 10:38:41.768904 | controller | TASK [Ensure directory exists] *************************************************
2025-10-13 10:38:41.768909 | controller | Monday 13 October 2025  10:38:41 +0000 (0:00:00.990)       0:01:20.359 ********
2025-10-13 10:38:41.768919 | controller | changed: [instance]
2025-10-13 10:38:42.157074 | controller |
2025-10-13 10:38:42.157110 | controller | TASK [Create nova migration keypair] *******************************************
2025-10-13 10:38:42.157120 | controller | Monday 13 October 2025  10:38:41 +0000 (0:00:00.205)       0:01:20.564 ********
2025-10-13 10:38:42.157132 | controller | changed: [instance]
2025-10-13 10:38:42.157160 | controller |
2025-10-13 10:38:42.157170 | controller | PLAY [Download tools] **********************************************************
2025-10-13 10:38:42.157202 | controller |
2025-10-13 10:38:42.157479 | controller | TASK [Gathering Facts] *********************************************************
2025-10-13 10:38:42.763027 | controller | Monday 13 October 2025  10:38:42 +0000 (0:00:00.388)       0:01:20.952 ********
2025-10-13 10:38:42.763073 | controller | ok: [localhost]
2025-10-13 10:38:54.816473 | controller |
2025-10-13 10:38:54.816505 | controller | TASK [download_tools : Install build dependencies] *****************************
2025-10-13 10:38:54.816513 | controller | Monday 13 October 2025  10:38:42 +0000 (0:00:00.605)       0:01:21.558 ********
2025-10-13 10:38:54.816529 | controller | changed: [localhost]
2025-10-13 10:38:54.816548 | controller |
2025-10-13 10:38:54.816770 | controller | TASK [download_tools : Set opm download url suffix] ****************************
2025-10-13 10:38:54.844178 | controller | Monday 13 October 2025  10:38:54 +0000 (0:00:12.053)       0:01:33.612 ********
2025-10-13 10:38:54.844240 | controller | skipping: [localhost]
2025-10-13 10:38:54.844263 | controller |
2025-10-13 10:38:54.844272 | controller | TASK [download_tools : Set opm download url suffix] ****************************
2025-10-13 10:38:54.844331 | controller | Monday 13 October 2025  10:38:54 +0000 (0:00:00.027)       0:01:33.640 ********
2025-10-13 10:38:54.868942 | controller | ok: [localhost]
2025-10-13 10:38:54.869079 | controller |
2025-10-13 10:38:54.869348 | controller | TASK [download_tools : Create $HOME/bin dir] ***********************************
2025-10-13 10:38:55.138510 | controller | Monday 13 October 2025  10:38:54 +0000 (0:00:00.024)       0:01:33.665 ********
2025-10-13 10:38:55.138615 | controller | ok: [localhost]
2025-10-13 10:38:55.138640 | controller |
2025-10-13 10:38:55.138649 | controller | TASK [download_tools : Download opm] *******************************************
2025-10-13 10:38:56.483360 | controller | Monday 13 October 2025  10:38:55 +0000 (0:00:00.264)       0:01:33.929 ********
2025-10-13 10:38:56.483398 | controller | changed: [localhost]
2025-10-13 10:38:56.504588 | controller |
2025-10-13 10:38:56.504612 | controller | TASK [download_tools : Get version from sdk_version] ***************************
2025-10-13 10:38:56.504621 | controller | Monday 13 October 2025  10:38:56 +0000 (0:00:01.349)       0:01:35.278 ********
2025-10-13 10:38:56.504631 | controller | ok: [localhost]
2025-10-13 10:38:56.529605 | controller |
2025-10-13 10:38:56.529631 | controller | TASK [download_tools : Set operator-sdk file for version < 1.3.0] **************
2025-10-13 10:38:56.529639 | controller | Monday 13 October 2025  10:38:56 +0000 (0:00:00.021)       0:01:35.300 ********
2025-10-13 10:38:56.529648 | controller | skipping: [localhost]
2025-10-13 10:38:56.562123 | controller |
2025-10-13 10:38:56.562189 | controller | TASK [download_tools : Set operator-sdk file for version >= 1.3.0] *************
2025-10-13 10:38:56.562200 | controller | Monday 13 October 2025  10:38:56 +0000 (0:00:00.024)       0:01:35.325 ********
2025-10-13 10:38:56.562215 | controller | ok: [localhost]
2025-10-13 10:38:58.077885 | controller |
2025-10-13 10:38:58.077920 | controller | TASK [download_tools : Download operator-sdk] **********************************
2025-10-13 10:38:58.077928 | controller | Monday 13 October 2025  10:38:56 +0000 (0:00:00.032)       0:01:35.357 ********
2025-10-13 10:38:58.077939 | controller | changed: [localhost]
2025-10-13 10:38:59.252689 | controller |
2025-10-13 10:38:59.252725 | controller | TASK [download_tools : Download and extract kustomize] *************************
2025-10-13 10:38:59.252734 | controller | Monday 13 October 2025  10:38:58 +0000 (0:00:01.515)       0:01:36.873 ********
2025-10-13 10:38:59.252745 | controller | changed: [localhost]
2025-10-13 10:38:59.793071 | controller |
2025-10-13 10:38:59.793111 | controller | TASK [download_tools : Download kubectl] ***************************************
2025-10-13 10:38:59.793119 | controller | Monday 13 October 2025  10:38:59 +0000 (0:00:01.174)       0:01:38.048 ********
2025-10-13 10:38:59.793131 | controller | ok: [localhost]
2025-10-13 10:38:59.793216 | controller |
2025-10-13 10:38:59.793259 | controller | TASK [download_tools : Download kuttl] *****************************************
2025-10-13 10:38:59.793274 | controller | Monday 13 October 2025  10:38:59 +0000 (0:00:00.540)       0:01:38.588 ********
2025-10-13 10:39:00.741543 | controller | changed: [localhost]
2025-10-13 10:39:03.902408 | controller |
2025-10-13 10:39:03.902450 | controller | TASK [download_tools : Download chainsaw] **************************************
2025-10-13 10:39:03.902459 | controller | Monday 13 October 2025  10:39:00 +0000 (0:00:00.948)       0:01:39.536 ********
2025-10-13 10:39:03.902470 | controller | changed: [localhost]
2025-10-13 10:39:05.106060 | controller |
2025-10-13 10:39:05.106103 | controller | TASK [download_tools : Download and extract yq] ********************************
2025-10-13 10:39:05.106112 | controller | Monday 13 October 2025  10:39:03 +0000 (0:00:03.160)       0:01:42.697 ********
2025-10-13 10:39:05.106127 | controller | changed: [localhost]
2025-10-13 10:39:05.312498 | controller |
2025-10-13 10:39:05.312548 | controller | TASK [download_tools : Link yq_linux_amd64 as yq] ******************************
2025-10-13 10:39:05.312564 | controller | Monday 13 October 2025  10:39:05 +0000 (0:00:01.204)       0:01:43.901 ********
2025-10-13 10:39:05.312576 | controller | changed: [localhost]
2025-10-13 10:39:06.876325 | controller |
2025-10-13 10:39:06.876369 | controller | TASK [download_tools : Deinstall golang] ***************************************
2025-10-13 10:39:06.876377 | controller | Monday 13 October 2025  10:39:05 +0000 (0:00:00.205)       0:01:44.106 ********
2025-10-13 10:39:06.876388 | controller | ok: [localhost]
2025-10-13 10:39:07.889060 | controller |
2025-10-13 10:39:07.889100 | controller | TASK [download_tools : Delete old go version installed from upstream] **********
2025-10-13 10:39:07.889108 | controller | Monday 13 October 2025  10:39:06 +0000 (0:00:01.564)       0:01:45.671 ********
2025-10-13 10:39:07.889119 | controller | ok: [localhost] => (item=/usr/local/go)
2025-10-13 10:39:20.148117 | controller | ok: [localhost] => (item=/home/zuul/bin/go)
2025-10-13 10:39:20.148152 | controller | ok: [localhost] => (item=/home/zuul/bin/gofmt)
2025-10-13 10:39:20.148163 | controller | ok: [localhost] => (item=/usr/local/bin/go)
2025-10-13 10:39:20.148169 | controller | ok: [localhost] => (item=/usr/local/bin/gofmt)
2025-10-13 10:39:20.148175 | controller |
2025-10-13 10:39:20.148181 | controller | TASK [download_tools : Download and extract golang] ****************************
2025-10-13 10:39:20.148187 | controller | Monday 13 October 2025  10:39:07 +0000 (0:00:01.012)       0:01:46.683 ********
2025-10-13 10:39:20.148532 | controller | changed: [localhost]
2025-10-13 10:39:20.585071 | controller |
2025-10-13 10:39:20.585111 | controller | TASK [download_tools : Set alternatives link to installed go version] **********
2025-10-13 10:39:20.585120 | controller | Monday 13 October 2025  10:39:20 +0000 (0:00:12.259)       0:01:58.943 ********
2025-10-13 10:39:20.585131 | controller | changed: [localhost] => (item=go)
2025-10-13 10:39:20.628143 | controller | changed: [localhost] => (item=gofmt)
2025-10-13 10:39:20.628202 | controller |
2025-10-13 10:39:20.628211 | controller | TASK [download_tools : Clean bash cache] ***************************************
2025-10-13 10:39:20.628229 | controller | Monday 13 October 2025  10:39:20 +0000 (0:00:00.436)       0:01:59.380 ********
2025-10-13 10:39:20.628243 | controller | ok: [localhost] =>
2025-10-13 10:39:20.628274 | controller |   msg: When move from rpm to upstream version, make sure to clean bash cache using `hash -d go`
2025-10-13 10:39:20.628281 | controller |
2025-10-13 10:39:20.628289 | controller | PLAY RECAP *********************************************************************
2025-10-13 10:39:20.628295 | controller | instance                   : ok=31   changed=17   unreachable=0    failed=0    skipped=36   rescued=0    ignored=0
2025-10-13 10:39:20.628303 | controller | localhost                  : ok=19   changed=10   unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-10-13 10:39:20.628329 | controller |
2025-10-13 10:39:20.629183 | controller | Monday 13 October 2025  10:39:20 +0000 (0:00:00.043)       0:01:59.423 ********
2025-10-13 10:39:20.629274 | controller | ===============================================================================
2025-10-13 10:39:20.629285 | controller | test_deps : Install selinux python libs -------------------------------- 50.49s
2025-10-13 10:39:20.629293 | controller | download_tools : Download and extract golang --------------------------- 12.26s
2025-10-13 10:39:20.629300 | controller | download_tools : Install build dependencies ---------------------------- 12.05s
2025-10-13 10:39:20.629308 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.90s
2025-10-13 10:39:20.629315 | controller | ci_setup : Install openshift client ------------------------------------- 5.98s
2025-10-13 10:39:20.629325 | controller | ci_setup : Install needed packages -------------------------------------- 4.30s
2025-10-13 10:39:20.629479 | controller | download_tools : Download chainsaw -------------------------------------- 3.16s
2025-10-13 10:39:20.629491 | controller | download_tools : Deinstall golang --------------------------------------- 1.56s
2025-10-13 10:39:20.629501 | controller | download_tools : Download operator-sdk ---------------------------------- 1.52s
2025-10-13 10:39:20.629602 | controller | download_tools : Download opm ------------------------------------------- 1.35s
2025-10-13 10:39:20.629721 | controller | download_tools : Download and extract yq -------------------------------- 1.20s
2025-10-13 10:39:20.629835 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.19s
2025-10-13 10:39:20.629981 | controller | download_tools : Download and extract kustomize ------------------------- 1.17s
2025-10-13 10:39:20.630097 | controller | test_deps : Install python yaml libs ------------------------------------ 1.15s
2025-10-13 10:39:20.630225 | controller | download_tools : Delete old go version installed from upstream ---------- 1.01s
2025-10-13 10:39:20.630435 | controller | ci_setup : Manage directories ------------------------------------------- 0.99s
2025-10-13 10:39:20.630546 | controller | download_tools : Download kuttl ----------------------------------------- 0.95s
2025-10-13 10:39:20.630670 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.86s
2025-10-13 10:39:20.630778 | controller | Gathering Facts --------------------------------------------------------- 0.81s
2025-10-13 10:39:20.630917 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.76s
2025-10-13 10:39:20.715288 | controller | INFO     Running default > converge
2025-10-13 10:39:21.176271 | controller |
2025-10-13 10:39:21.176421 | controller | PLAY [Converge] ****************************************************************
2025-10-13 10:39:21.176547 | controller |
2025-10-13 10:39:21.176682 | controller | TASK [Gathering Facts] *********************************************************
2025-10-13 10:39:21.176804 | controller | Monday 13 October 2025  10:39:21 +0000 (0:00:00.013)       0:00:00.013 ********
2025-10-13 10:39:22.034135 | controller | ok: [instance]
2025-10-13 10:39:22.034212 | controller |
2025-10-13 10:39:22.034429 | controller | TASK [Ensure architecture repository is building] ******************************
2025-10-13 10:39:22.034480 | controller | Monday 13 October 2025  10:39:22 +0000 (0:00:00.858)       0:00:00.871 ********
2025-10-13 10:39:22.754571 | controller | changed: [instance] => (item={'name': 'ctlplane', 'path': 'examples/va/hci/control-plane/networking/nncp'})
2025-10-13 10:39:23.143891 | controller | changed: [instance] => (item={'name': 'dataplane', 'path': 'examples/va/hci/edpm-pre-ceph/nodeset'})
2025-10-13 10:39:23.143942 | controller |
2025-10-13 10:39:23.143950 | controller | TASK [Create nova migration keypair] *******************************************
2025-10-13 10:39:23.143957 | controller | Monday 13 October 2025  10:39:22 +0000 (0:00:00.719)       0:00:01.591 ********
2025-10-13 10:39:23.143982 | controller | changed: [instance]
2025-10-13 10:39:23.204145 | controller |
2025-10-13 10:39:23.204201 | controller | TASK [Generate needed facts out of local files] ********************************
2025-10-13 10:39:23.204210 | controller | Monday 13 October 2025  10:39:23 +0000 (0:00:00.389)       0:00:01.981 ********
2025-10-13 10:39:23.204222 | controller | ok: [instance]
2025-10-13 10:39:23.204249 | controller |
2025-10-13 10:39:23.204257 | controller | TASK [Generate network-values] *************************************************
2025-10-13 10:39:23.204403 | controller | Monday 13 October 2025  10:39:23 +0000 (0:00:00.060)       0:00:02.042 ********
2025-10-13 10:39:23.232507 | controller |
2025-10-13 10:39:23.261725 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-10-13 10:39:23.261749 | controller | Monday 13 October 2025  10:39:23 +0000 (0:00:00.028)       0:00:02.070 ********
2025-10-13 10:39:23.261761 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_snippets.yml for instance
2025-10-13 10:39:23.290651 | controller |
2025-10-13 10:39:23.290673 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-10-13 10:39:23.290680 | controller | Monday 13 October 2025  10:39:23 +0000 (0:00:00.029)       0:00:02.099 ********
2025-10-13 10:39:23.290690 | controller | ok: [instance] => changed=false
2025-10-13 10:39:23.600048 | controller |   msg: All assertions passed
2025-10-13 10:39:23.600112 | controller |
2025-10-13 10:39:23.600121 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-10-13 10:39:23.600127 | controller | Monday 13 October 2025  10:39:23 +0000 (0:00:00.028)       0:00:02.128 ********
2025-10-13 10:39:23.600137 | controller | ok: [instance]
2025-10-13 10:39:23.635127 | controller |
2025-10-13 10:39:23.635152 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-10-13 10:39:23.635160 | controller | Monday 13 October 2025  10:39:23 +0000 (0:00:00.309)       0:00:02.437 ********
2025-10-13 10:39:23.635170 | controller | ok: [instance] => changed=false
2025-10-13 10:39:23.928186 | controller |   msg: All assertions passed
2025-10-13 10:39:23.928222 | controller |
2025-10-13 10:39:23.928230 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-10-13 10:39:23.928237 | controller | Monday 13 October 2025  10:39:23 +0000 (0:00:00.035)       0:00:02.472 ********
2025-10-13 10:39:23.928247 | controller | ok: [instance]
2025-10-13 10:39:24.198983 | controller |
2025-10-13 10:39:24.199024 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-10-13 10:39:24.199033 | controller | Monday 13 October 2025  10:39:23 +0000 (0:00:00.292)       0:00:02.765 ********
2025-10-13 10:39:24.199044 | controller | ok: [instance]
2025-10-13 10:39:24.243796 | controller |
2025-10-13 10:39:24.243828 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-10-13 10:39:24.243837 | controller | Monday 13 October 2025  10:39:24 +0000 (0:00:00.270)       0:00:03.036 ********
2025-10-13 10:39:24.243847 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/network_values.yml for instance
2025-10-13 10:39:24.270043 | controller |
2025-10-13 10:39:24.270068 | controller | TASK [ci_gen_kustomize_values : Assert we have needed parameters] **************
2025-10-13 10:39:24.270075 | controller | Monday 13 October 2025  10:39:24 +0000 (0:00:00.045)       0:00:03.081 ********
2025-10-13 10:39:24.270085 | controller | ok: [instance] => changed=false
2025-10-13 10:39:24.598143 | controller |   msg: All assertions passed
2025-10-13 10:39:24.598713 | controller |
2025-10-13 10:39:24.598725 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-10-13 10:39:24.598731 | controller | Monday 13 October 2025  10:39:24 +0000 (0:00:00.026)       0:00:03.107 ********
2025-10-13 10:39:24.598753 | controller | changed: [instance]
2025-10-13 10:39:25.182499 | controller |
2025-10-13 10:39:25.182533 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-10-13 10:39:25.182548 | controller | Monday 13 October 2025  10:39:24 +0000 (0:00:00.327)       0:00:03.435 ********
2025-10-13 10:39:25.182558 | controller | changed: [instance]
2025-10-13 10:39:25.218549 | controller |
2025-10-13 10:39:25.218580 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-10-13 10:39:25.218587 | controller | Monday 13 October 2025  10:39:25 +0000 (0:00:00.584)       0:00:04.020 ********
2025-10-13 10:39:25.218597 | controller | ok: [instance]
2025-10-13 10:39:25.567658 | controller |
2025-10-13 10:39:25.567706 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-10-13 10:39:25.567714 | controller | Monday 13 October 2025  10:39:25 +0000 (0:00:00.036)       0:00:04.056 ********
2025-10-13 10:39:25.567727 | controller | changed: [instance]
2025-10-13 10:39:25.955345 | controller |
2025-10-13 10:39:25.955383 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-10-13 10:39:25.955390 | controller | Monday 13 October 2025  10:39:25 +0000 (0:00:00.348)       0:00:04.405 ********
2025-10-13 10:39:25.955400 | controller | changed: [instance]
2025-10-13 10:39:26.342187 | controller |
2025-10-13 10:39:26.342232 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-10-13 10:39:26.342240 | controller | Monday 13 October 2025  10:39:25 +0000 (0:00:00.387)       0:00:04.792 ********
2025-10-13 10:39:26.342252 | controller | changed: [instance]
2025-10-13 10:39:26.376893 | controller |
2025-10-13 10:39:26.376920 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-10-13 10:39:26.376977 | controller | Monday 13 October 2025  10:39:26 +0000 (0:00:00.386)       0:00:05.179 ********
2025-10-13 10:39:26.376993 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_values.yml for instance
2025-10-13 10:39:26.403824 | controller |
2025-10-13 10:39:26.403847 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-10-13 10:39:26.403854 | controller | Monday 13 October 2025  10:39:26 +0000 (0:00:00.035)       0:00:05.214 ********
2025-10-13 10:39:26.403864 | controller | ok: [instance] => changed=false
2025-10-13 10:39:26.704738 | controller |   msg: All assertions passed
2025-10-13 10:39:26.704773 | controller |
2025-10-13 10:39:26.704781 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-10-13 10:39:26.704787 | controller | Monday 13 October 2025  10:39:26 +0000 (0:00:00.026)       0:00:05.241 ********
2025-10-13 10:39:26.704802 | controller | ok: [instance]
2025-10-13 10:39:26.724669 | controller |
2025-10-13 10:39:26.724690 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-10-13 10:39:26.724697 | controller | Monday 13 October 2025  10:39:26 +0000 (0:00:00.300)       0:00:05.542 ********
2025-10-13 10:39:26.724707 | controller | ok: [instance]
2025-10-13 10:39:27.289695 | controller |
2025-10-13 10:39:27.289741 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-10-13 10:39:27.289749 | controller | Monday 13 October 2025  10:39:26 +0000 (0:00:00.020)       0:00:05.562 ********
2025-10-13 10:39:27.289760 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/02_ci_data.yaml)
2025-10-13 10:39:27.361129 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/03_user_data_b64.yaml)
2025-10-13 10:39:27.361208 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/04_user_data.yaml)
2025-10-13 10:39:27.361217 | controller |
2025-10-13 10:39:27.361224 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-10-13 10:39:27.361230 | controller | Monday 13 October 2025  10:39:27 +0000 (0:00:00.564)       0:00:06.126 ********
2025-10-13 10:39:27.361242 | controller | ok: [instance] => (item=02_ci_data.yaml)
2025-10-13 10:39:27.361308 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-10-13 10:39:27.361318 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-10-13 10:39:27.361326 | controller |
2025-10-13 10:39:27.361417 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-10-13 10:39:27.361442 | controller | Monday 13 October 2025  10:39:27 +0000 (0:00:00.072)       0:00:06.199 ********
2025-10-13 10:39:27.583821 | controller | changed: [instance]
2025-10-13 10:39:27.970910 | controller |
2025-10-13 10:39:27.970986 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-10-13 10:39:27.970997 | controller | Monday 13 October 2025  10:39:27 +0000 (0:00:00.222)       0:00:06.421 ********
2025-10-13 10:39:27.971009 | controller | changed: [instance]
2025-10-13 10:39:28.001582 | controller |
2025-10-13 10:39:28.001607 | controller | TASK [Generate edpm-nodeset-values] ********************************************
2025-10-13 10:39:28.001614 | controller | Monday 13 October 2025  10:39:27 +0000 (0:00:00.386)       0:00:06.808 ********
2025-10-13 10:39:28.001624 | controller |
2025-10-13 10:39:28.029866 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-10-13 10:39:28.029889 | controller | Monday 13 October 2025  10:39:27 +0000 (0:00:00.031)       0:00:06.839 ********
2025-10-13 10:39:28.029900 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_snippets.yml for instance
2025-10-13 10:39:28.060979 | controller |
2025-10-13 10:39:28.061000 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-10-13 10:39:28.061013 | controller | Monday 13 October 2025  10:39:28 +0000 (0:00:00.028)       0:00:06.867 ********
2025-10-13 10:39:28.061024 | controller | ok: [instance] => changed=false
2025-10-13 10:39:28.271465 | controller |   msg: All assertions passed
2025-10-13 10:39:28.271502 | controller |
2025-10-13 10:39:28.271509 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-10-13 10:39:28.271516 | controller | Monday 13 October 2025  10:39:28 +0000 (0:00:00.031)       0:00:06.898 ********
2025-10-13 10:39:28.271526 | controller | ok: [instance]
2025-10-13 10:39:28.307103 | controller |
2025-10-13 10:39:28.307149 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-10-13 10:39:28.307157 | controller | Monday 13 October 2025  10:39:28 +0000 (0:00:00.210)       0:00:07.108 ********
2025-10-13 10:39:28.307168 | controller | ok: [instance] => changed=false
2025-10-13 10:39:28.511064 | controller |   msg: All assertions passed
2025-10-13 10:39:28.511101 | controller |
2025-10-13 10:39:28.511108 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-10-13 10:39:28.511115 | controller | Monday 13 October 2025  10:39:28 +0000 (0:00:00.035)       0:00:07.144 ********
2025-10-13 10:39:28.511125 | controller | ok: [instance]
2025-10-13 10:39:28.511149 | controller |
2025-10-13 10:39:28.511341 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-10-13 10:39:28.763848 | controller | Monday 13 October 2025  10:39:28 +0000 (0:00:00.204)       0:00:07.348 ********
2025-10-13 10:39:28.763907 | controller | ok: [instance]
2025-10-13 10:39:28.820147 | controller |
2025-10-13 10:39:28.820206 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-10-13 10:39:28.820216 | controller | Monday 13 October 2025  10:39:28 +0000 (0:00:00.251)       0:00:07.600 ********
2025-10-13 10:39:28.820230 | 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-10-13 10:39:28.820259 | controller |
2025-10-13 10:39:28.820268 | controller | TASK [ci_gen_kustomize_values : Check if all required variables for accessing the node is provided.] ***
2025-10-13 10:39:28.820390 | controller | Monday 13 October 2025  10:39:28 +0000 (0:00:00.056)       0:00:07.657 ********
2025-10-13 10:39:28.865661 | 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-10-13 10:39:28.905078 | controller |
2025-10-13 10:39:28.905116 | controller | TASK [ci_gen_kustomize_values : Ensure the required parameters are defined and configured.] ***
2025-10-13 10:39:28.905126 | controller | Monday 13 October 2025  10:39:28 +0000 (0:00:00.045)       0:00:07.702 ********
2025-10-13 10:39:28.905138 | controller | ok: [instance] => changed=false
2025-10-13 10:39:28.905166 | controller |   msg: All assertions passed
2025-10-13 10:39:28.905176 | controller |
2025-10-13 10:39:28.905200 | controller | TASK [ci_gen_kustomize_values : Get common data for dataplane templating] ******
2025-10-13 10:39:28.905318 | controller | Monday 13 October 2025  10:39:28 +0000 (0:00:00.039)       0:00:07.742 ********
2025-10-13 10:39:28.933537 | 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-10-13 10:39:28.953056 | controller |
2025-10-13 10:39:28.953089 | controller | TASK [ci_gen_kustomize_values : Fetch the already deployed services for further usage] ***
2025-10-13 10:39:28.953098 | controller | Monday 13 October 2025  10:39:28 +0000 (0:00:00.028)       0:00:07.771 ********
2025-10-13 10:39:28.953110 | controller | skipping: [instance]
2025-10-13 10:39:28.953139 | controller |
2025-10-13 10:39:28.953341 | controller | TASK [ci_gen_kustomize_values : Expose the deployed services as a fact] ********
2025-10-13 10:39:28.977048 | controller | Monday 13 October 2025  10:39:28 +0000 (0:00:00.019)       0:00:07.791 ********
2025-10-13 10:39:28.977078 | controller | ok: [instance]
2025-10-13 10:39:28.977102 | controller |
2025-10-13 10:39:28.977111 | controller | TASK [ci_gen_kustomize_values : Check if required variables for nova migration are provided.] ***
2025-10-13 10:39:28.977231 | controller | Monday 13 October 2025  10:39:28 +0000 (0:00:00.023)       0:00:07.814 ********
2025-10-13 10:39:29.008617 | controller | ok: [instance] => changed=false
2025-10-13 10:39:29.197757 | controller |   msg: All assertions passed
2025-10-13 10:39:29.197791 | controller |
2025-10-13 10:39:29.197800 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-10-13 10:39:29.197806 | controller | Monday 13 October 2025  10:39:29 +0000 (0:00:00.031)       0:00:07.845 ********
2025-10-13 10:39:29.197816 | controller | changed: [instance]
2025-10-13 10:39:29.625174 | controller |
2025-10-13 10:39:29.625223 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-10-13 10:39:29.625231 | controller | Monday 13 October 2025  10:39:29 +0000 (0:00:00.189)       0:00:08.035 ********
2025-10-13 10:39:29.625242 | controller | changed: [instance]
2025-10-13 10:39:29.656912 | controller |
2025-10-13 10:39:29.656994 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-10-13 10:39:29.657005 | controller | Monday 13 October 2025  10:39:29 +0000 (0:00:00.427)       0:00:08.462 ********
2025-10-13 10:39:29.657017 | controller | ok: [instance]
2025-10-13 10:39:30.013867 | controller |
2025-10-13 10:39:30.013912 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-10-13 10:39:30.013920 | controller | Monday 13 October 2025  10:39:29 +0000 (0:00:00.031)       0:00:08.494 ********
2025-10-13 10:39:30.013931 | controller | changed: [instance]
2025-10-13 10:39:30.389283 | controller |
2025-10-13 10:39:30.389327 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-10-13 10:39:30.389335 | controller | Monday 13 October 2025  10:39:30 +0000 (0:00:00.356)       0:00:08.850 ********
2025-10-13 10:39:30.389347 | controller | changed: [instance]
2025-10-13 10:39:30.764205 | controller |
2025-10-13 10:39:30.764242 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-10-13 10:39:30.764250 | controller | Monday 13 October 2025  10:39:30 +0000 (0:00:00.375)       0:00:09.226 ********
2025-10-13 10:39:30.764267 | controller | changed: [instance]
2025-10-13 10:39:30.796003 | controller |
2025-10-13 10:39:30.796041 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-10-13 10:39:30.796049 | controller | Monday 13 October 2025  10:39:30 +0000 (0:00:00.375)       0:00:09.601 ********
2025-10-13 10:39:30.796060 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_values.yml for instance
2025-10-13 10:39:30.826692 | controller |
2025-10-13 10:39:30.826729 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-10-13 10:39:30.826736 | controller | Monday 13 October 2025  10:39:30 +0000 (0:00:00.031)       0:00:09.633 ********
2025-10-13 10:39:30.826747 | controller | ok: [instance] => changed=false
2025-10-13 10:39:31.038927 | controller |   msg: All assertions passed
2025-10-13 10:39:31.039000 | controller |
2025-10-13 10:39:31.039010 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-10-13 10:39:31.039017 | controller | Monday 13 October 2025  10:39:30 +0000 (0:00:00.030)       0:00:09.664 ********
2025-10-13 10:39:31.039040 | controller | ok: [instance]
2025-10-13 10:39:31.062738 | controller |
2025-10-13 10:39:31.062761 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-10-13 10:39:31.062768 | controller | Monday 13 October 2025  10:39:31 +0000 (0:00:00.211)       0:00:09.875 ********
2025-10-13 10:39:31.062778 | controller | ok: [instance]
2025-10-13 10:39:31.635125 | controller |
2025-10-13 10:39:31.635159 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-10-13 10:39:31.635167 | controller | Monday 13 October 2025  10:39:31 +0000 (0:00:00.024)       0:00:09.900 ********
2025-10-13 10:39:31.635178 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/02_ci_data.yaml)
2025-10-13 10:39:31.688720 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/03_user_data_b64.yaml)
2025-10-13 10:39:31.688746 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/04_user_data.yaml)
2025-10-13 10:39:31.688753 | controller |
2025-10-13 10:39:31.688759 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-10-13 10:39:31.688765 | controller | Monday 13 October 2025  10:39:31 +0000 (0:00:00.571)       0:00:10.472 ********
2025-10-13 10:39:31.688775 | controller | ok: [instance] => (item=02_ci_data.yaml)
2025-10-13 10:39:31.890044 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-10-13 10:39:31.890080 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-10-13 10:39:31.890087 | controller |
2025-10-13 10:39:31.890094 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-10-13 10:39:31.890100 | controller | Monday 13 October 2025  10:39:31 +0000 (0:00:00.053)       0:00:10.526 ********
2025-10-13 10:39:31.890111 | controller | changed: [instance]
2025-10-13 10:39:31.890378 | controller |
2025-10-13 10:39:31.890392 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-10-13 10:39:32.275056 | controller | Monday 13 October 2025  10:39:31 +0000 (0:00:00.201)       0:00:10.727 ********
2025-10-13 10:39:32.275110 | controller | changed: [instance]
2025-10-13 10:39:32.667058 | controller |
2025-10-13 10:39:32.667099 | controller | TASK [Get generated files status] **********************************************
2025-10-13 10:39:32.667108 | controller | Monday 13 October 2025  10:39:32 +0000 (0:00:00.384)       0:00:11.112 ********
2025-10-13 10:39:32.667119 | controller | ok: [instance] => (item=network-values)
2025-10-13 10:39:32.667138 | controller | ok: [instance] => (item=edpm-nodeset-values)
2025-10-13 10:39:32.667146 | controller |
2025-10-13 10:39:32.667230 | controller | TASK [Assert generated values.yaml exists] *************************************
2025-10-13 10:39:32.667340 | controller | Monday 13 October 2025  10:39:32 +0000 (0:00:00.392)       0:00:11.504 ********
2025-10-13 10:39:32.696015 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/values.yaml) => changed=false
2025-10-13 10:39:32.696115 | controller |   ansible_loop_var: item
2025-10-13 10:39:32.696219 | controller |   item:
2025-10-13 10:39:32.696330 | controller |     ansible_loop_var: item
2025-10-13 10:39:32.696412 | controller |     changed: false
2025-10-13 10:39:32.696520 | controller |     failed: false
2025-10-13 10:39:32.696607 | controller |     invocation:
2025-10-13 10:39:32.696702 | controller |       module_args:
2025-10-13 10:39:32.696798 | controller |         checksum_algorithm: sha1
2025-10-13 10:39:32.696893 | controller |         follow: false
2025-10-13 10:39:32.697135 | controller |         get_attributes: false
2025-10-13 10:39:32.697239 | controller |         get_checksum: false
2025-10-13 10:39:32.697339 | controller |         get_md5: false
2025-10-13 10:39:32.697436 | controller |         get_mime: false
2025-10-13 10:39:32.697544 | controller |         path: /home/zuul/ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/values.yaml
2025-10-13 10:39:32.697648 | controller |     item: network-values
2025-10-13 10:39:32.697745 | controller |     stat:
2025-10-13 10:39:32.697841 | controller |       atime: 1760351967.9245024
2025-10-13 10:39:32.697935 | controller |       block_size: 4096
2025-10-13 10:39:32.698095 | controller |       blocks: 16
2025-10-13 10:39:32.698187 | controller |       ctime: 1760351967.9275024
2025-10-13 10:39:32.698281 | controller |       dev: 64513
2025-10-13 10:39:32.698375 | controller |       device_type: 0
2025-10-13 10:39:32.698471 | controller |       executable: false
2025-10-13 10:39:32.698562 | controller |       exists: true
2025-10-13 10:39:32.698654 | controller |       gid: 1000
2025-10-13 10:39:32.698747 | controller |       gr_name: zuul
2025-10-13 10:39:32.698840 | controller |       inode: 83953031
2025-10-13 10:39:32.698939 | controller |       isblk: false
2025-10-13 10:39:32.699069 | controller |       ischr: false
2025-10-13 10:39:32.699162 | controller |       isdir: false
2025-10-13 10:39:32.699253 | controller |       isfifo: false
2025-10-13 10:39:32.699345 | controller |       isgid: false
2025-10-13 10:39:32.699436 | controller |       islnk: false
2025-10-13 10:39:32.699525 | controller |       isreg: true
2025-10-13 10:39:32.699615 | controller |       issock: false
2025-10-13 10:39:32.699706 | controller |       isuid: false
2025-10-13 10:39:32.699797 | controller |       mode: '0644'
2025-10-13 10:39:32.699888 | controller |       mtime: 1760351967.7805004
2025-10-13 10:39:32.700009 | controller |       nlink: 1
2025-10-13 10:39:32.700110 | controller |       path: /home/zuul/ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/values.yaml
2025-10-13 10:39:32.700204 | controller |       pw_name: zuul
2025-10-13 10:39:32.700305 | controller |       readable: true
2025-10-13 10:39:32.700397 | controller |       rgrp: true
2025-10-13 10:39:32.700490 | controller |       roth: true
2025-10-13 10:39:32.700582 | controller |       rusr: true
2025-10-13 10:39:32.700680 | controller |       size: 5726
2025-10-13 10:39:32.700773 | controller |       uid: 1000
2025-10-13 10:39:32.700866 | controller |       wgrp: false
2025-10-13 10:39:32.701000 | controller |       woth: false
2025-10-13 10:39:32.701133 | controller |       writeable: true
2025-10-13 10:39:32.701232 | controller |       wusr: true
2025-10-13 10:39:32.701325 | controller |       xgrp: false
2025-10-13 10:39:32.701416 | controller |       xoth: false
2025-10-13 10:39:32.701507 | controller |       xusr: false
2025-10-13 10:39:32.701610 | controller |   msg: All assertions passed
2025-10-13 10:39:32.701717 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-nodeset-values/values.yaml) => changed=false
2025-10-13 10:39:32.701811 | controller |   ansible_loop_var: item
2025-10-13 10:39:32.701903 | controller |   item:
2025-10-13 10:39:32.702031 | controller |     ansible_loop_var: item
2025-10-13 10:39:32.702125 | controller |     changed: false
2025-10-13 10:39:32.702216 | controller |     failed: false
2025-10-13 10:39:32.702308 | controller |     invocation:
2025-10-13 10:39:32.702401 | controller |       module_args:
2025-10-13 10:39:32.702494 | controller |         checksum_algorithm: sha1
2025-10-13 10:39:32.702585 | controller |         follow: false
2025-10-13 10:39:32.702675 | controller |         get_attributes: false
2025-10-13 10:39:32.702767 | controller |         get_checksum: false
2025-10-13 10:39:32.702858 | controller |         get_md5: false
2025-10-13 10:39:32.702974 | controller |         get_mime: false
2025-10-13 10:39:32.703088 | controller |         path: /home/zuul/ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-nodeset-values/values.yaml
2025-10-13 10:39:32.703180 | controller |     item: edpm-nodeset-values
2025-10-13 10:39:32.703271 | controller |     stat:
2025-10-13 10:39:32.703363 | controller |       atime: 1760351972.2305608
2025-10-13 10:39:32.703454 | controller |       block_size: 4096
2025-10-13 10:39:32.703545 | controller |       blocks: 40
2025-10-13 10:39:32.703637 | controller |       ctime: 1760351972.2335608
2025-10-13 10:39:32.703727 | controller |       dev: 64513
2025-10-13 10:39:32.703817 | controller |       device_type: 0
2025-10-13 10:39:32.703908 | controller |       executable: false
2025-10-13 10:39:32.704033 | controller |       exists: true
2025-10-13 10:39:32.704123 | controller |       gid: 1000
2025-10-13 10:39:32.704213 | controller |       gr_name: zuul
2025-10-13 10:39:32.704310 | controller |       inode: 54541483
2025-10-13 10:39:32.704403 | controller |       isblk: false
2025-10-13 10:39:32.704494 | controller |       ischr: false
2025-10-13 10:39:32.704591 | controller |       isdir: false
2025-10-13 10:39:32.704682 | controller |       isfifo: false
2025-10-13 10:39:32.704773 | controller |       isgid: false
2025-10-13 10:39:32.704864 | controller |       islnk: false
2025-10-13 10:39:32.704978 | controller |       isreg: true
2025-10-13 10:39:32.705101 | controller |       issock: false
2025-10-13 10:39:32.705194 | controller |       isuid: false
2025-10-13 10:39:32.705285 | controller |       mode: '0644'
2025-10-13 10:39:32.705683 | controller |       mtime: 1760351972.0815587
2025-10-13 10:39:32.705996 | controller |       nlink: 1
2025-10-13 10:39:32.706145 | controller |       path: /home/zuul/ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-nodeset-values/values.yaml
2025-10-13 10:39:32.706269 | controller |       pw_name: zuul
2025-10-13 10:39:32.706376 | controller |       readable: true
2025-10-13 10:39:32.706474 | controller |       rgrp: true
2025-10-13 10:39:32.706563 | controller |       roth: true
2025-10-13 10:39:32.706665 | controller |       rusr: true
2025-10-13 10:39:32.706761 | controller |       size: 16482
2025-10-13 10:39:32.706863 | controller |       uid: 1000
2025-10-13 10:39:32.706990 | controller |       wgrp: false
2025-10-13 10:39:32.707124 | controller |       woth: false
2025-10-13 10:39:32.707216 | controller |       writeable: true
2025-10-13 10:39:32.707308 | controller |       wusr: true
2025-10-13 10:39:32.707400 | controller |       xgrp: false
2025-10-13 10:39:32.707493 | controller |       xoth: false
2025-10-13 10:39:32.707594 | controller |       xusr: false
2025-10-13 10:39:32.707691 | controller |   msg: All assertions passed
2025-10-13 10:39:32.707785 | controller |
2025-10-13 10:39:32.707889 | controller | TASK [Ensure we have correct MAC] **********************************************
2025-10-13 10:39:32.708032 | controller | Monday 13 October 2025  10:39:32 +0000 (0:00:00.029)       0:00:11.534 ********
2025-10-13 10:39:32.729528 | controller | ok: [instance] => changed=false
2025-10-13 10:39:32.729624 | controller |   msg: All assertions passed
2025-10-13 10:39:32.729720 | controller |
2025-10-13 10:39:32.729822 | controller | TASK [Ensure we don't have node_1 nor node_2] **********************************
2025-10-13 10:39:32.729923 | controller | Monday 13 October 2025  10:39:32 +0000 (0:00:00.033)       0:00:11.567 ********
2025-10-13 10:39:32.762868 | controller | ok: [instance] => changed=false
2025-10-13 10:39:32.762989 | controller |   msg: All assertions passed
2025-10-13 10:39:32.763104 | controller |
2025-10-13 10:39:32.763208 | controller | TASK [Update cifmw_networking_env_definition] **********************************
2025-10-13 10:39:32.763309 | controller | Monday 13 October 2025  10:39:32 +0000 (0:00:00.033)       0:00:11.600 ********
2025-10-13 10:39:32.790369 | controller | ok: [instance]
2025-10-13 10:39:32.790454 | controller |
2025-10-13 10:39:32.790557 | controller | TASK [Regenerate network-values] ***********************************************
2025-10-13 10:39:32.790660 | controller | Monday 13 October 2025  10:39:32 +0000 (0:00:00.027)       0:00:11.628 ********
2025-10-13 10:39:32.815395 | controller |
2025-10-13 10:39:32.815525 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-10-13 10:39:32.815652 | controller | Monday 13 October 2025  10:39:32 +0000 (0:00:00.025)       0:00:11.653 ********
2025-10-13 10:39:32.842837 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_snippets.yml for instance
2025-10-13 10:39:32.842943 | controller |
2025-10-13 10:39:32.843124 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-10-13 10:39:32.843247 | controller | Monday 13 October 2025  10:39:32 +0000 (0:00:00.027)       0:00:11.680 ********
2025-10-13 10:39:32.871244 | controller | ok: [instance] => changed=false
2025-10-13 10:39:32.871349 | controller |   msg: All assertions passed
2025-10-13 10:39:32.871454 | controller |
2025-10-13 10:39:32.871575 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-10-13 10:39:32.871663 | controller | Monday 13 October 2025  10:39:32 +0000 (0:00:00.027)       0:00:11.708 ********
2025-10-13 10:39:33.063748 | controller | ok: [instance]
2025-10-13 10:39:33.063811 | controller |
2025-10-13 10:39:33.063936 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-10-13 10:39:33.064103 | controller | Monday 13 October 2025  10:39:33 +0000 (0:00:00.192)       0:00:11.901 ********
2025-10-13 10:39:33.088808 | controller | ok: [instance] => changed=false
2025-10-13 10:39:33.088923 | controller |   msg: All assertions passed
2025-10-13 10:39:33.089088 | controller |
2025-10-13 10:39:33.089218 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-10-13 10:39:33.089330 | controller | Monday 13 October 2025  10:39:33 +0000 (0:00:00.025)       0:00:11.926 ********
2025-10-13 10:39:33.282599 | controller | ok: [instance]
2025-10-13 10:39:33.282663 | controller |
2025-10-13 10:39:33.282766 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-10-13 10:39:33.282870 | controller | Monday 13 October 2025  10:39:33 +0000 (0:00:00.192)       0:00:12.119 ********
2025-10-13 10:39:33.523199 | controller | ok: [instance]
2025-10-13 10:39:33.523260 | controller |
2025-10-13 10:39:33.523401 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-10-13 10:39:33.523517 | controller | Monday 13 October 2025  10:39:33 +0000 (0:00:00.241)       0:00:12.360 ********
2025-10-13 10:39:33.560071 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/network_values.yml for instance
2025-10-13 10:39:33.560145 | controller |
2025-10-13 10:39:33.560255 | controller | TASK [ci_gen_kustomize_values : Assert we have needed parameters] **************
2025-10-13 10:39:33.560361 | controller | Monday 13 October 2025  10:39:33 +0000 (0:00:00.036)       0:00:12.397 ********
2025-10-13 10:39:33.589536 | controller | ok: [instance] => changed=false
2025-10-13 10:39:33.589625 | controller |   msg: All assertions passed
2025-10-13 10:39:33.589730 | controller |
2025-10-13 10:39:33.589946 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-10-13 10:39:33.590037 | controller | Monday 13 October 2025  10:39:33 +0000 (0:00:00.028)       0:00:12.426 ********
2025-10-13 10:39:33.797323 | controller | ok: [instance]
2025-10-13 10:39:33.797380 | controller |
2025-10-13 10:39:33.797497 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-10-13 10:39:33.797604 | controller | Monday 13 October 2025  10:39:33 +0000 (0:00:00.208)       0:00:12.635 ********
2025-10-13 10:39:34.311498 | controller | changed: [instance]
2025-10-13 10:39:34.311557 | controller |
2025-10-13 10:39:34.311863 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-10-13 10:39:34.337062 | controller | Monday 13 October 2025  10:39:34 +0000 (0:00:00.514)       0:00:13.149 ********
2025-10-13 10:39:34.337094 | controller | ok: [instance]
2025-10-13 10:39:34.711106 | controller |
2025-10-13 10:39:34.711158 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-10-13 10:39:34.711167 | controller | Monday 13 October 2025  10:39:34 +0000 (0:00:00.025)       0:00:13.174 ********
2025-10-13 10:39:34.711179 | controller | ok: [instance]
2025-10-13 10:39:34.711301 | controller |
2025-10-13 10:39:34.711365 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-10-13 10:39:35.090363 | controller | Monday 13 October 2025  10:39:34 +0000 (0:00:00.373)       0:00:13.548 ********
2025-10-13 10:39:35.090406 | controller | ok: [instance]
2025-10-13 10:39:35.483076 | controller |
2025-10-13 10:39:35.483116 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-10-13 10:39:35.483125 | controller | Monday 13 October 2025  10:39:35 +0000 (0:00:00.379)       0:00:13.927 ********
2025-10-13 10:39:35.483137 | controller | ok: [instance]
2025-10-13 10:39:35.483190 | controller |
2025-10-13 10:39:35.483198 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-10-13 10:39:35.483205 | controller | Monday 13 October 2025  10:39:35 +0000 (0:00:00.392)       0:00:14.320 ********
2025-10-13 10:39:35.516464 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_values.yml for instance
2025-10-13 10:39:35.552900 | controller |
2025-10-13 10:39:35.552929 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-10-13 10:39:35.552939 | controller | Monday 13 October 2025  10:39:35 +0000 (0:00:00.033)       0:00:14.354 ********
2025-10-13 10:39:35.552951 | controller | ok: [instance] => changed=false
2025-10-13 10:39:35.762132 | controller |   msg: All assertions passed
2025-10-13 10:39:35.762174 | controller |
2025-10-13 10:39:35.762183 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-10-13 10:39:35.762190 | controller | Monday 13 October 2025  10:39:35 +0000 (0:00:00.036)       0:00:14.390 ********
2025-10-13 10:39:35.762202 | controller | ok: [instance]
2025-10-13 10:39:35.784149 | controller |
2025-10-13 10:39:35.784225 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-10-13 10:39:35.784251 | controller | Monday 13 October 2025  10:39:35 +0000 (0:00:00.209)       0:00:14.599 ********
2025-10-13 10:39:35.784269 | controller | ok: [instance]
2025-10-13 10:39:35.784323 | controller |
2025-10-13 10:39:36.373098 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-10-13 10:39:36.373141 | controller | Monday 13 October 2025  10:39:35 +0000 (0:00:00.022)       0:00:14.622 ********
2025-10-13 10:39:36.373164 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/02_ci_data.yaml)
2025-10-13 10:39:36.373197 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/03_user_data_b64.yaml)
2025-10-13 10:39:36.373658 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/network-values/04_user_data.yaml)
2025-10-13 10:39:36.439077 | controller |
2025-10-13 10:39:36.439108 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-10-13 10:39:36.439116 | controller | Monday 13 October 2025  10:39:36 +0000 (0:00:00.588)       0:00:15.210 ********
2025-10-13 10:39:36.439126 | controller | ok: [instance] => (item=02_ci_data.yaml)
2025-10-13 10:39:36.439152 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-10-13 10:39:36.439158 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-10-13 10:39:36.439165 | controller |
2025-10-13 10:39:36.439387 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-10-13 10:39:36.656628 | controller | Monday 13 October 2025  10:39:36 +0000 (0:00:00.065)       0:00:15.276 ********
2025-10-13 10:39:36.656663 | controller | ok: [instance]
2025-10-13 10:39:37.047107 | controller |
2025-10-13 10:39:37.047146 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-10-13 10:39:37.047154 | controller | Monday 13 October 2025  10:39:36 +0000 (0:00:00.217)       0:00:15.494 ********
2025-10-13 10:39:37.047165 | controller | changed: [instance]
2025-10-13 10:39:37.070059 | controller |
2025-10-13 10:39:37.070086 | controller | TASK [Regenerate edpm-nodeset-values] ******************************************
2025-10-13 10:39:37.070095 | controller | Monday 13 October 2025  10:39:37 +0000 (0:00:00.390)       0:00:15.884 ********
2025-10-13 10:39:37.070105 | controller |
2025-10-13 10:39:37.070129 | controller | TASK [ci_gen_kustomize_values : Generate snippets files] ***********************
2025-10-13 10:39:37.070219 | controller | Monday 13 October 2025  10:39:37 +0000 (0:00:00.023)       0:00:15.908 ********
2025-10-13 10:39:37.094353 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_snippets.yml for instance
2025-10-13 10:39:37.118550 | controller |
2025-10-13 10:39:37.118570 | controller | TASK [ci_gen_kustomize_values : Ensure needed parameter is properly set] *******
2025-10-13 10:39:37.118578 | controller | Monday 13 October 2025  10:39:37 +0000 (0:00:00.023)       0:00:15.932 ********
2025-10-13 10:39:37.118587 | controller | ok: [instance] => changed=false
2025-10-13 10:39:37.300927 | controller |   msg: All assertions passed
2025-10-13 10:39:37.300958 | controller |
2025-10-13 10:39:37.300996 | controller | TASK [ci_gen_kustomize_values : Stat original source file] *********************
2025-10-13 10:39:37.301008 | controller | Monday 13 October 2025  10:39:37 +0000 (0:00:00.024)       0:00:15.956 ********
2025-10-13 10:39:37.301019 | controller | ok: [instance]
2025-10-13 10:39:37.322078 | controller |
2025-10-13 10:39:37.322109 | controller | TASK [ci_gen_kustomize_values : Assert source file exists] *********************
2025-10-13 10:39:37.322118 | controller | Monday 13 October 2025  10:39:37 +0000 (0:00:00.182)       0:00:16.138 ********
2025-10-13 10:39:37.322130 | controller | ok: [instance] => changed=false
2025-10-13 10:39:37.322157 | controller |   msg: All assertions passed
2025-10-13 10:39:37.322167 | controller |
2025-10-13 10:39:37.322384 | controller | TASK [ci_gen_kustomize_values : Load original values file] *********************
2025-10-13 10:39:37.500741 | controller | Monday 13 October 2025  10:39:37 +0000 (0:00:00.021)       0:00:16.160 ********
2025-10-13 10:39:37.500779 | controller | ok: [instance]
2025-10-13 10:39:37.715972 | controller |
2025-10-13 10:39:37.716015 | controller | TASK [ci_gen_kustomize_values : Expose common data for future usage] ***********
2025-10-13 10:39:37.716023 | controller | Monday 13 October 2025  10:39:37 +0000 (0:00:00.178)       0:00:16.338 ********
2025-10-13 10:39:37.716034 | controller | ok: [instance]
2025-10-13 10:39:37.750114 | controller |
2025-10-13 10:39:37.750152 | controller | TASK [ci_gen_kustomize_values : Ensure we get the needed data depending on the values type] ***
2025-10-13 10:39:37.750162 | controller | Monday 13 October 2025  10:39:37 +0000 (0:00:00.211)       0:00:16.549 ********
2025-10-13 10:39:37.750175 | 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-10-13 10:39:37.750383 | controller |
2025-10-13 10:39:37.750406 | controller | TASK [ci_gen_kustomize_values : Check if all required variables for accessing the node is provided.] ***
2025-10-13 10:39:37.776090 | controller | Monday 13 October 2025  10:39:37 +0000 (0:00:00.038)       0:00:16.588 ********
2025-10-13 10:39:37.776119 | 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-10-13 10:39:37.808242 | controller |
2025-10-13 10:39:37.808281 | controller | TASK [ci_gen_kustomize_values : Ensure the required parameters are defined and configured.] ***
2025-10-13 10:39:37.808292 | controller | Monday 13 October 2025  10:39:37 +0000 (0:00:00.025)       0:00:16.613 ********
2025-10-13 10:39:37.808309 | controller | ok: [instance] => changed=false
2025-10-13 10:39:37.808397 | controller |   msg: All assertions passed
2025-10-13 10:39:37.808517 | controller |
2025-10-13 10:39:37.808639 | controller | TASK [ci_gen_kustomize_values : Get common data for dataplane templating] ******
2025-10-13 10:39:37.808767 | controller | Monday 13 October 2025  10:39:37 +0000 (0:00:00.032)       0:00:16.646 ********
2025-10-13 10:39:37.836083 | 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-10-13 10:39:37.836200 | controller |
2025-10-13 10:39:37.836229 | controller | TASK [ci_gen_kustomize_values : Fetch the already deployed services for further usage] ***
2025-10-13 10:39:37.836242 | controller | Monday 13 October 2025  10:39:37 +0000 (0:00:00.027)       0:00:16.674 ********
2025-10-13 10:39:37.856716 | controller | skipping: [instance]
2025-10-13 10:39:37.882395 | controller |
2025-10-13 10:39:37.882415 | controller | TASK [ci_gen_kustomize_values : Expose the deployed services as a fact] ********
2025-10-13 10:39:37.882431 | controller | Monday 13 October 2025  10:39:37 +0000 (0:00:00.020)       0:00:16.694 ********
2025-10-13 10:39:37.882442 | controller | ok: [instance]
2025-10-13 10:39:37.913890 | controller |
2025-10-13 10:39:37.913909 | controller | TASK [ci_gen_kustomize_values : Check if required variables for nova migration are provided.] ***
2025-10-13 10:39:37.913917 | controller | Monday 13 October 2025  10:39:37 +0000 (0:00:00.025)       0:00:16.720 ********
2025-10-13 10:39:37.913927 | controller | ok: [instance] => changed=false
2025-10-13 10:39:38.118016 | controller |   msg: All assertions passed
2025-10-13 10:39:38.118049 | controller |
2025-10-13 10:39:38.118057 | controller | TASK [ci_gen_kustomize_values : Ensure output directory exists] ****************
2025-10-13 10:39:38.118063 | controller | Monday 13 October 2025  10:39:37 +0000 (0:00:00.031)       0:00:16.751 ********
2025-10-13 10:39:38.118081 | controller | ok: [instance]
2025-10-13 10:39:38.550013 | controller |
2025-10-13 10:39:38.550046 | controller | TASK [ci_gen_kustomize_values : Generate CI snippet] ***************************
2025-10-13 10:39:38.550055 | controller | Monday 13 October 2025  10:39:38 +0000 (0:00:00.203)       0:00:16.955 ********
2025-10-13 10:39:38.550065 | controller | ok: [instance]
2025-10-13 10:39:38.576138 | controller |
2025-10-13 10:39:38.576177 | controller | TASK [ci_gen_kustomize_values : Generate the base64 CI ConfigMap patches] ******
2025-10-13 10:39:38.576185 | controller | Monday 13 October 2025  10:39:38 +0000 (0:00:00.431)       0:00:17.387 ********
2025-10-13 10:39:38.576195 | controller | ok: [instance]
2025-10-13 10:39:38.576279 | controller |
2025-10-13 10:39:38.576300 | controller | TASK [ci_gen_kustomize_values : Push base64 CI patches] ************************
2025-10-13 10:39:38.576313 | controller | Monday 13 October 2025  10:39:38 +0000 (0:00:00.026)       0:00:17.414 ********
2025-10-13 10:39:38.948798 | controller | ok: [instance]
2025-10-13 10:39:39.333571 | controller |
2025-10-13 10:39:39.333611 | controller | TASK [ci_gen_kustomize_values : Push user provided dataset] ********************
2025-10-13 10:39:39.333619 | controller | Monday 13 October 2025  10:39:38 +0000 (0:00:00.372)       0:00:17.786 ********
2025-10-13 10:39:39.333630 | controller | ok: [instance]
2025-10-13 10:39:39.702422 | controller |
2025-10-13 10:39:39.702467 | controller | TASK [ci_gen_kustomize_values : Copy the base values.yaml] *********************
2025-10-13 10:39:39.702476 | controller | Monday 13 October 2025  10:39:39 +0000 (0:00:00.384)       0:00:18.171 ********
2025-10-13 10:39:39.702488 | controller | ok: [instance]
2025-10-13 10:39:39.732612 | controller |
2025-10-13 10:39:39.732656 | controller | TASK [ci_gen_kustomize_values : Generate values file] **************************
2025-10-13 10:39:39.732664 | controller | Monday 13 October 2025  10:39:39 +0000 (0:00:00.368)       0:00:18.539 ********
2025-10-13 10:39:39.732676 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_gen_kustomize_values/tasks/generate_values.yml for instance
2025-10-13 10:39:39.761196 | controller |
2025-10-13 10:39:39.761236 | controller | TASK [ci_gen_kustomize_values : Ensure we have needed parameter] ***************
2025-10-13 10:39:39.761244 | controller | Monday 13 October 2025  10:39:39 +0000 (0:00:00.030)       0:00:18.570 ********
2025-10-13 10:39:39.761256 | controller | ok: [instance] => changed=false
2025-10-13 10:39:39.967347 | controller |   msg: All assertions passed
2025-10-13 10:39:39.967404 | controller |
2025-10-13 10:39:39.967412 | controller | TASK [ci_gen_kustomize_values : List snippets] *********************************
2025-10-13 10:39:39.967419 | controller | Monday 13 October 2025  10:39:39 +0000 (0:00:00.028)       0:00:18.598 ********
2025-10-13 10:39:39.967432 | controller | ok: [instance]
2025-10-13 10:39:40.021878 | controller |
2025-10-13 10:39:40.021938 | controller | TASK [ci_gen_kustomize_values : Ensure _content is empty] **********************
2025-10-13 10:39:40.021947 | controller | Monday 13 October 2025  10:39:39 +0000 (0:00:00.205)       0:00:18.804 ********
2025-10-13 10:39:40.021961 | controller | ok: [instance]
2025-10-13 10:39:40.571001 | controller |
2025-10-13 10:39:40.571043 | controller | TASK [ci_gen_kustomize_values : Load various snippet files] ********************
2025-10-13 10:39:40.571051 | controller | Monday 13 October 2025  10:39:40 +0000 (0:00:00.054)       0:00:18.858 ********
2025-10-13 10:39:40.571062 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/02_ci_data.yaml)
2025-10-13 10:39:40.629423 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/03_user_data_b64.yaml)
2025-10-13 10:39:40.629442 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/04_user_data.yaml)
2025-10-13 10:39:40.629449 | controller |
2025-10-13 10:39:40.629456 | controller | TASK [ci_gen_kustomize_values : Combine snippets] ******************************
2025-10-13 10:39:40.629461 | controller | Monday 13 October 2025  10:39:40 +0000 (0:00:00.548)       0:00:19.407 ********
2025-10-13 10:39:40.629470 | controller | ok: [instance] => (item=02_ci_data.yaml)
2025-10-13 10:39:40.843490 | controller | ok: [instance] => (item=03_user_data_b64.yaml)
2025-10-13 10:39:40.843524 | controller | ok: [instance] => (item=04_user_data.yaml)
2025-10-13 10:39:40.843531 | controller |
2025-10-13 10:39:40.843537 | controller | TASK [ci_gen_kustomize_values : Ensure directories exist] **********************
2025-10-13 10:39:40.843543 | controller | Monday 13 October 2025  10:39:40 +0000 (0:00:00.058)       0:00:19.466 ********
2025-10-13 10:39:40.843553 | controller | ok: [instance]
2025-10-13 10:39:41.225475 | controller |
2025-10-13 10:39:41.225518 | controller | TASK [ci_gen_kustomize_values : Output values file] ****************************
2025-10-13 10:39:41.225527 | controller | Monday 13 October 2025  10:39:40 +0000 (0:00:00.214)       0:00:19.681 ********
2025-10-13 10:39:41.225537 | controller | ok: [instance]
2025-10-13 10:39:41.630830 | controller |
2025-10-13 10:39:41.630863 | controller | TASK [Copy generated values to correct location] *******************************
2025-10-13 10:39:41.630878 | controller | Monday 13 October 2025  10:39:41 +0000 (0:00:00.381)       0:00:20.062 ********
2025-10-13 10:39:41.630890 | controller | changed: [instance] => (item={'key': 'network-values', 'value': 'examples/va/hci/control-plane/networking/nncp/values.yaml'})
2025-10-13 10:39:42.273261 | controller | changed: [instance] => (item={'key': 'edpm-nodeset-values', 'value': 'examples/va/hci/edpm-pre-ceph/nodeset/values.yaml'})
2025-10-13 10:39:42.273305 | controller |
2025-10-13 10:39:42.273314 | controller | TASK [Ensure kustomize is able to build] ***************************************
2025-10-13 10:39:42.273320 | controller | Monday 13 October 2025  10:39:41 +0000 (0:00:00.405)       0:00:20.468 ********
2025-10-13 10:39:42.273332 | controller | changed: [instance] => (item={'name': 'ctlplane', 'path': 'examples/va/hci/control-plane/networking/nncp'})
2025-10-13 10:39:42.344464 | controller | changed: [instance] => (item={'name': 'dataplane', 'path': 'examples/va/hci/edpm-pre-ceph/nodeset'})
2025-10-13 10:39:42.344512 | controller |
2025-10-13 10:39:42.344520 | controller | PLAY RECAP *********************************************************************
2025-10-13 10:39:42.344527 | controller | instance                   : ok=107  changed=20   unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-10-13 10:39:42.344532 | controller |
2025-10-13 10:39:42.344538 | controller | Monday 13 October 2025  10:39:42 +0000 (0:00:00.639)       0:00:21.107 ********
2025-10-13 10:39:42.344543 | controller | ===============================================================================
2025-10-13 10:39:42.344548 | controller | Gathering Facts --------------------------------------------------------- 0.86s
2025-10-13 10:39:42.344553 | controller | Ensure architecture repository is building ------------------------------ 0.72s
2025-10-13 10:39:42.344559 | controller | Ensure kustomize is able to build --------------------------------------- 0.64s
2025-10-13 10:39:42.344564 | controller | ci_gen_kustomize_values : Load various snippet files -------------------- 0.59s
2025-10-13 10:39:42.344569 | controller | ci_gen_kustomize_values : Generate CI snippet --------------------------- 0.58s
2025-10-13 10:39:42.344574 | controller | ci_gen_kustomize_values : Load various snippet files -------------------- 0.57s
2025-10-13 10:39:42.344579 | controller | ci_gen_kustomize_values : Load various snippet files -------------------- 0.56s
2025-10-13 10:39:42.344585 | controller | ci_gen_kustomize_values : Load various snippet files -------------------- 0.55s
2025-10-13 10:39:42.344590 | controller | ci_gen_kustomize_values : Generate CI snippet --------------------------- 0.51s
2025-10-13 10:39:42.344595 | controller | ci_gen_kustomize_values : Generate CI snippet --------------------------- 0.43s
2025-10-13 10:39:42.344600 | controller | ci_gen_kustomize_values : Generate CI snippet --------------------------- 0.43s
2025-10-13 10:39:42.344605 | controller | Copy generated values to correct location ------------------------------- 0.41s
2025-10-13 10:39:42.344610 | controller | ci_gen_kustomize_values : Copy the base values.yaml --------------------- 0.39s
2025-10-13 10:39:42.344615 | controller | Get generated files status ---------------------------------------------- 0.39s
2025-10-13 10:39:42.344620 | controller | ci_gen_kustomize_values : Output values file ---------------------------- 0.39s
2025-10-13 10:39:42.344625 | controller | Create nova migration keypair ------------------------------------------- 0.39s
2025-10-13 10:39:42.344631 | controller | ci_gen_kustomize_values : Push user provided dataset -------------------- 0.39s
2025-10-13 10:39:42.344636 | controller | ci_gen_kustomize_values : Output values file ---------------------------- 0.39s
2025-10-13 10:39:42.344641 | controller | ci_gen_kustomize_values : Copy the base values.yaml --------------------- 0.39s
2025-10-13 10:39:42.344646 | controller | ci_gen_kustomize_values : Push user provided dataset -------------------- 0.38s
2025-10-13 10:39:42.344658 | controller | INFO     Running default > cleanup
2025-10-13 10:39:42.345210 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-13 10:39:42.345884 | controller | INFO     Writing /tmp/report.html report.
2025-10-13 10:39:42.611023 | controller | changed
2025-10-13 10:39:42.658458 | 
2025-10-13 10:39:42.658545 | PLAY RECAP
2025-10-13 10:39:42.658603 | controller | ok: 3 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-13 10:39:42.658628 | 
2025-10-13 10:39:42.775248 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-13 10:39:42.776223 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 10:39:43.559225 | 
2025-10-13 10:39:43.559321 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-13 10:39:43.599149 | 
2025-10-13 10:39:43.599223 | TASK [Filter out host if needed]
2025-10-13 10:39:43.632606 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-13 10:39:43.648662 | 
2025-10-13 10:39:43.648751 | TASK [Ensure file is present]
2025-10-13 10:39:44.084617 | controller | ok
2025-10-13 10:39:44.102998 | 
2025-10-13 10:39:44.103071 | TASK [Manage molecule report file]
2025-10-13 10:39:44.676433 | controller | changed
2025-10-13 10:39:44.681730 | 
2025-10-13 10:39:44.681794 | TASK [Check if we get ci-framework-data basedir]
2025-10-13 10:39:44.903378 | controller | ok
2025-10-13 10:39:44.909095 | 
2025-10-13 10:39:44.909171 | TASK [Create ci-framework-data log directory for zuul]
2025-10-13 10:39:45.261641 | controller | changed
2025-10-13 10:39:45.267607 | 
2025-10-13 10:39:45.267689 | TASK [Copy ci-framework interesting files]
2025-10-13 10:39:45.515917 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-13 10:39:45.807866 | controller | changed
2025-10-13 10:39:45.813206 | 
2025-10-13 10:39:45.813290 | TASK [Get SELinux listing]
2025-10-13 10:39:46.369713 | controller | changed
2025-10-13 10:39:46.375390 | 
2025-10-13 10:39:46.375455 | TASK [Generate log index]
2025-10-13 10:39:47.449926 | controller | changed
2025-10-13 10:39:47.460270 | 
2025-10-13 10:39:47.460336 | TASK [Get some env related data]
2025-10-13 10:39:48.122691 | controller | /home/zuul/.local/bin/ansible
2025-10-13 10:39:49.044621 | controller | changed
2025-10-13 10:39:49.049987 | 
2025-10-13 10:39:49.050053 | TASK [Generate list of logs to collect in home directory]
2025-10-13 10:39:49.929614 | controller | ok: All paths examined
2025-10-13 10:39:49.944736 | 
2025-10-13 10:39:49.944803 | LOOP [Copy logs from home directory]
2025-10-13 10:39:50.487358 | controller | changed:
2025-10-13 10:39:50.487569 | controller | {
2025-10-13 10:39:50.487619 | controller |   "atime": 1760351791.7194765,
2025-10-13 10:39:50.487649 | controller |   "ctime": 1760351813.425463,
2025-10-13 10:39:50.487674 | controller |   "dev": 64513,
2025-10-13 10:39:50.487698 | controller |   "gid": 1000,
2025-10-13 10:39:50.487721 | controller |   "gr_name": "zuul",
2025-10-13 10:39:50.487745 | controller |   "inode": 4482346,
2025-10-13 10:39:50.487767 | controller |   "isblk": false,
2025-10-13 10:39:50.487790 | controller |   "ischr": false,
2025-10-13 10:39:50.487811 | controller |   "isdir": false,
2025-10-13 10:39:50.487833 | controller |   "isfifo": false,
2025-10-13 10:39:50.487854 | controller |   "isgid": false,
2025-10-13 10:39:50.487876 | controller |   "islnk": false,
2025-10-13 10:39:50.487897 | controller |   "isreg": true,
2025-10-13 10:39:50.487956 | controller |   "issock": false,
2025-10-13 10:39:50.487984 | controller |   "isuid": false,
2025-10-13 10:39:50.488008 | controller |   "mode": "0644",
2025-10-13 10:39:50.488030 | controller |   "mtime": 1760351813.425463,
2025-10-13 10:39:50.488052 | controller |   "nlink": 1,
2025-10-13 10:39:50.488074 | controller |   "path": "/home/zuul/ansible.log",
2025-10-13 10:39:50.488098 | controller |   "pw_name": "zuul",
2025-10-13 10:39:50.488121 | controller |   "rgrp": true,
2025-10-13 10:39:50.488142 | controller |   "roth": true,
2025-10-13 10:39:50.488163 | controller |   "rusr": true,
2025-10-13 10:39:50.488184 | controller |   "size": 6749,
2025-10-13 10:39:50.488205 | controller |   "uid": 1000,
2025-10-13 10:39:50.488227 | controller |   "wgrp": false,
2025-10-13 10:39:50.488247 | controller |   "woth": false,
2025-10-13 10:39:50.488270 | controller |   "wusr": true,
2025-10-13 10:39:50.488291 | controller |   "xgrp": false,
2025-10-13 10:39:50.488313 | controller |   "xoth": false,
2025-10-13 10:39:50.488334 | controller |   "xusr": false
2025-10-13 10:39:50.488355 | controller | }
2025-10-13 10:39:50.505767 | 
2025-10-13 10:39:50.505860 | TASK [Copy crio stats log file]
2025-10-13 10:39:50.582231 | controller | skipping: Conditional result was False
2025-10-13 10:39:50.594895 | 
2025-10-13 10:39:50.594982 | TASK [Get SELinux related data]
2025-10-13 10:39:50.898045 | controller | <no matches>
2025-10-13 10:39:51.165237 | controller | ERROR
2025-10-13 10:39:51.165400 | controller | {
2025-10-13 10:39:51.165433 | controller |   "delta": "0:00:00.012258",
2025-10-13 10:39:51.165455 | controller |   "end": "2025-10-13 10:39:50.899335",
2025-10-13 10:39:51.165475 | controller |   "msg": "non-zero return code",
2025-10-13 10:39:51.165494 | controller |   "rc": 1,
2025-10-13 10:39:51.165511 | controller |   "start": "2025-10-13 10:39:50.887077"
2025-10-13 10:39:51.165528 | controller | }
2025-10-13 10:39:51.165551 | controller | ERROR: Ignoring Errors
2025-10-13 10:39:51.170817 | 
2025-10-13 10:39:51.170882 | TASK [Create system configuration directory]
2025-10-13 10:39:51.418573 | controller | changed
2025-10-13 10:39:51.426780 | 
2025-10-13 10:39:51.426866 | TASK [Get some of the system configurations]
2025-10-13 10:39:51.973546 | controller | changed
2025-10-13 10:39:51.979093 | 
2025-10-13 10:39:51.979158 | TASK [Copy generated documentation if available]
2025-10-13 10:39:52.033060 | controller | skipping: Conditional result was False
2025-10-13 10:39:52.039164 | 
2025-10-13 10:39:52.039231 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-13 10:39:52.063354 | controller | skipping: Conditional result was False
2025-10-13 10:39:52.068938 | 
2025-10-13 10:39:52.069003 | TASK [Compress logs bigger than 2MB]
2025-10-13 10:39:52.643363 | controller | changed
2025-10-13 10:39:52.649331 | 
2025-10-13 10:39:52.649409 | TASK [Copy files from workspace on node]
2025-10-13 10:39:52.698001 | controller | ok
2025-10-13 10:39:52.736261 | 
2025-10-13 10:39:52.736358 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 10:39:52.760713 | controller | skipping: Conditional result was False
2025-10-13 10:39:52.766068 | 
2025-10-13 10:39:52.766138 | TASK [fetch-output : Set log path for single node]
2025-10-13 10:39:52.826326 | controller | ok
2025-10-13 10:39:52.837799 | 
2025-10-13 10:39:52.837868 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 10:39:53.298222 | controller -> localhost | ok: "/var/lib/zuul/builds/da9547f6dece43f1af0b598f2762776f/work/logs"
2025-10-13 10:39:53.298460 | controller -> localhost | changed: All items complete
2025-10-13 10:39:53.298496 | 
2025-10-13 10:39:53.805956 | controller -> localhost | changed: "/var/lib/zuul/builds/da9547f6dece43f1af0b598f2762776f/work/artifacts"
2025-10-13 10:39:54.339699 | controller -> localhost | changed: "/var/lib/zuul/builds/da9547f6dece43f1af0b598f2762776f/work/docs"
2025-10-13 10:39:54.351179 | 
2025-10-13 10:39:54.351278 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 10:39:55.420151 | controller | changed:
2025-10-13 10:39:55.420331 | controller | .d..t...... ./
2025-10-13 10:39:55.420363 | controller | >f+++++++++ README.html
2025-10-13 10:39:55.420386 | controller | >f+++++++++ ansible-execution.log
2025-10-13 10:39:55.420408 | controller | >f+++++++++ ansible.log
2025-10-13 10:39:55.420429 | controller | >f+++++++++ dmesg.log
2025-10-13 10:39:55.420448 | controller | >f+++++++++ installed-pkgs.log
2025-10-13 10:39:55.420470 | controller | >f+++++++++ python.log
2025-10-13 10:39:55.420490 | controller | >f+++++++++ registries.conf
2025-10-13 10:39:55.420508 | controller | >f+++++++++ report.html
2025-10-13 10:39:55.420527 | controller | >f+++++++++ selinux-denials.log
2025-10-13 10:39:55.420544 | controller | >f+++++++++ selinux-listing.log
2025-10-13 10:39:55.420562 | controller | cd+++++++++ ci-framework-data/
2025-10-13 10:39:55.420578 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-13 10:39:55.420612 | controller | >f+++++++++ ci-framework-data/artifacts/ctlplane.yaml
2025-10-13 10:39:55.420633 | controller | >f+++++++++ ci-framework-data/artifacts/dataplane.yaml
2025-10-13 10:39:55.420650 | controller | >f+++++++++ ci-framework-data/artifacts/nova_migration_key
2025-10-13 10:39:55.420667 | controller | >f+++++++++ ci-framework-data/artifacts/nova_migration_key.pub
2025-10-13 10:39:55.420683 | controller | >f+++++++++ ci-framework-data/artifacts/vanilla-ctlplane.yaml
2025-10-13 10:39:55.420701 | controller | >f+++++++++ ci-framework-data/artifacts/vanilla-dataplane.yaml
2025-10-13 10:39:55.420717 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-13 10:39:55.420733 | controller | cd+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/
2025-10-13 10:39:55.420749 | controller | cd+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-nodeset-values/
2025-10-13 10:39:55.420765 | controller | >f+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/edpm-nodeset-values/values.yaml
2025-10-13 10:39:55.420782 | controller | cd+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/
2025-10-13 10:39:55.420798 | controller | >f+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/values.yaml
2025-10-13 10:39:55.420814 | controller | >f+++++++++ ci-framework-data/artifacts/ci_gen_kustomize_values/network-values/values.yaml.29296.2025-10-13@10:39:36~
2025-10-13 10:39:55.420835 | controller | cd+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/
2025-10-13 10:39:55.420852 | controller | cd+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/
2025-10-13 10:39:55.420870 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/01_original.yaml
2025-10-13 10:39:55.420886 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/02_ci_data.yaml
2025-10-13 10:39:55.420902 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/03_user_data_b64.yaml
2025-10-13 10:39:55.420941 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/edpm-nodeset-values/04_user_data.yaml
2025-10-13 10:39:55.420961 | controller | cd+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/
2025-10-13 10:39:55.420978 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/01_original.yaml
2025-10-13 10:39:55.420996 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/02_ci_data.yaml
2025-10-13 10:39:55.421012 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/02_ci_data.yaml.29127.2025-10-13@10:39:34~
2025-10-13 10:39:55.421027 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/03_user_data_b64.yaml
2025-10-13 10:39:55.421042 | controller | >f+++++++++ ci-framework-data/artifacts/ci_k8s_snippets/network-values/04_user_data.yaml
2025-10-13 10:39:55.421057 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-10-13 10:39:55.421072 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-10-13 10:39:55.421087 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-10-13 10:39:55.421102 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-13 10:39:55.421117 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-13 10:39:55.421132 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-13 10:39:55.421147 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-13 10:39:55.421162 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-13 10:39:55.421177 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-13 10:39:55.421191 | controller | cd+++++++++ registries.conf.d/
2025-10-13 10:39:55.421206 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-13 10:39:55.421221 | controller | cd+++++++++ system-config/
2025-10-13 10:39:55.421236 | controller | cd+++++++++ system-config/libvirt/
2025-10-13 10:39:55.421250 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-13 10:39:55.421264 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-13 10:39:56.119258 | controller | changed: .d..t...... ./
2025-10-13 10:39:56.696889 | controller | changed: .d..t...... ./
2025-10-13 10:39:56.724970 | 
2025-10-13 10:39:56.725080 | TASK [Return artifact to Zuul]
2025-10-13 10:39:56.776869 | controller | ok
2025-10-13 10:39:56.796031 | 
2025-10-13 10:39:56.796102 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-13 10:39:56.796224 | 
2025-10-13 10:39:56.796258 | PLAY RECAP
2025-10-13 10:39:56.796302 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-13 10:39:56.796328 | 
2025-10-13 10:39:56.901242 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 10:39:56.902520 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 10:39:57.906273 | 
2025-10-13 10:39:57.906373 | PLAY [all]
2025-10-13 10:39:57.928959 | 
2025-10-13 10:39:57.929036 | TASK [include_role : fetch-output]
2025-10-13 10:39:57.977431 | controller | ok
2025-10-13 10:39:58.006243 | 
2025-10-13 10:39:58.006338 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 10:39:58.063508 | controller | skipping: Conditional result was False
2025-10-13 10:39:58.069774 | 
2025-10-13 10:39:58.069854 | TASK [fetch-output : Set log path for single node]
2025-10-13 10:39:58.108368 | controller | ok
2025-10-13 10:39:58.114650 | 
2025-10-13 10:39:58.114725 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 10:39:58.572274 | controller -> localhost | ok: "/var/lib/zuul/builds/da9547f6dece43f1af0b598f2762776f/work/logs"
2025-10-13 10:39:59.099035 | controller -> localhost | ok: "/var/lib/zuul/builds/da9547f6dece43f1af0b598f2762776f/work/artifacts"
2025-10-13 10:39:59.558049 | controller -> localhost | ok: "/var/lib/zuul/builds/da9547f6dece43f1af0b598f2762776f/work/docs"
2025-10-13 10:39:59.573785 | 
2025-10-13 10:39:59.573886 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 10:40:00.525113 | controller | ok
2025-10-13 10:40:00.525384 | controller | ok: All items complete
2025-10-13 10:40:00.525426 | 
2025-10-13 10:40:01.116396 | controller | ok
2025-10-13 10:40:01.745665 | controller | ok
2025-10-13 10:40:01.765078 | 
2025-10-13 10:40:01.765181 | TASK [include_role : fetch-output-openshift]
2025-10-13 10:40:01.816186 | controller | skipping: Conditional result was False
2025-10-13 10:40:01.822441 | 
2025-10-13 10:40:01.822519 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-13 10:40:02.342369 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.009906
2025-10-13 10:40:02.792057 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.021069
2025-10-13 10:40:02.877548 | 
2025-10-13 10:40:02.877640 | PLAY [all]
2025-10-13 10:40:02.914448 | 
2025-10-13 10:40:02.914551 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-13 10:40:03.612827 | controller | changed
2025-10-13 10:40:03.653520 | 
2025-10-13 10:40:03.653620 | PLAY RECAP
2025-10-13 10:40:03.653664 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-13 10:40:03.653688 | 
2025-10-13 10:40:03.787399 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 10:40:03.788144 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-13 10:40:04.993529 | 
2025-10-13 10:40:04.993634 | PLAY [localhost]
2025-10-13 10:40:05.011528 | 
2025-10-13 10:40:05.011621 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-13 10:40:05.694530 | localhost | changed
2025-10-13 10:40:05.699181 | 
2025-10-13 10:40:05.699261 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-13 10:40:05.734340 | localhost | ok
2025-10-13 10:40:05.750959 | 
2025-10-13 10:40:05.751047 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-13 10:40:06.093077 | localhost | changed
2025-10-13 10:40:06.098850 | 
2025-10-13 10:40:06.098942 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-13 10:40:07.310149 | localhost | changed
2025-10-13 10:40:07.315205 | 
2025-10-13 10:40:07.323613 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-13 10:40:08.066621 | localhost | Identity added: /var/lib/zuul/builds/da9547f6dece43f1af0b598f2762776f/work/tmp/ansible.ewfz9550 (/var/lib/zuul/builds/da9547f6dece43f1af0b598f2762776f/work/tmp/ansible.ewfz9550)
2025-10-13 10:40:08.066789 | localhost | ok: Runtime: 0:00:00.016635
2025-10-13 10:40:08.071778 | 
2025-10-13 10:40:08.071849 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-13 10:40:08.534194 | localhost | ok: Runtime: 0:00:00.021000
2025-10-13 10:40:08.540524 | 
2025-10-13 10:40:08.540605 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-13 10:40:08.642354 | localhost | changed
2025-10-13 10:40:08.646708 | 
2025-10-13 10:40:08.646777 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-13 10:40:09.256423 | localhost | changed
2025-10-13 10:40:09.281225 | 
2025-10-13 10:40:09.281312 | PLAY [localhost]
2025-10-13 10:40:09.297811 | 
2025-10-13 10:40:09.297938 | TASK [Generate bulk log download script]
2025-10-13 10:40:09.327748 | localhost | ok
2025-10-13 10:40:09.340621 | 
2025-10-13 10:40:09.340732 | TASK [local-log-download : Check API endpoint is defined]
2025-10-13 10:40:09.379428 | localhost | ok: All assertions passed
2025-10-13 10:40:09.385197 | 
2025-10-13 10:40:09.385278 | TASK [local-log-download : Create download script]
2025-10-13 10:40:09.836949 | localhost -> localhost | changed
2025-10-13 10:40:09.874687 | 
2025-10-13 10:40:09.874778 | TASK [Register quick-download link]
2025-10-13 10:40:09.902648 | localhost | ok
2025-10-13 10:40:09.919861 | 
2025-10-13 10:40:09.919937 | PLAY [logserver.rdoproject.org]
2025-10-13 10:40:09.930411 | 
2025-10-13 10:40:09.930473 | TASK [Set zuul-log-path fact]
2025-10-13 10:40:09.946483 | logserver.rdoproject.org | ok
2025-10-13 10:40:09.956731 | 
2025-10-13 10:40:09.956865 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 10:40:10.013382 | logserver.rdoproject.org | ok
2025-10-13 10:40:10.021829 | 
2025-10-13 10:40:10.021902 | TASK [upload-logs : Create log directories]
2025-10-13 10:40:11.759420 | logserver.rdoproject.org | changed
2025-10-13 10:40:11.762972 | 
2025-10-13 10:40:11.763041 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-13 10:40:12.154877 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.023407
2025-10-13 10:40:12.164671 | 
2025-10-13 10:40:12.164757 | TASK [upload-logs : Upload logs to log server]
2025-10-13 10:40:14.764439 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-13 10:40:14.767516 | 
2025-10-13 10:40:14.767581 | LOOP [upload-logs : Compress console log and json output]
2025-10-13 10:40:14.818277 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 10:40:14.819062 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 10:40:14.827025 | 
2025-10-13 10:40:14.827127 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-13 10:40:14.883528 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 10:40:14.883903 | 
2025-10-13 10:40:14.884492 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 10:40:14.899015 | 
2025-10-13 10:40:14.899119 | LOOP [upload-logs : Upload console log and json output]