2025-10-10 08:36:13.107660 | Job console starting...
2025-10-10 08:36:13.349694 | Updating repositories
2025-10-10 08:36:14.057692 | Preparing job workspace
2025-10-10 08:36:21.529356 | Running Ansible setup...
2025-10-10 08:36:24.578103 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-10 08:36:25.176921 | 
2025-10-10 08:36:25.177023 | PLAY [localhost]
2025-10-10 08:36:25.185599 | 
2025-10-10 08:36:25.185669 | TASK [Gathering Facts]
2025-10-10 08:36:26.002152 | localhost | ok
2025-10-10 08:36:26.017318 | 
2025-10-10 08:36:26.017414 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-10 08:36:26.356055 | localhost -> localhost | changed
2025-10-10 08:36:26.363510 | 
2025-10-10 08:36:26.363584 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-10 08:36:27.167373 | localhost -> localhost | changed
2025-10-10 08:36:27.179835 | 
2025-10-10 08:36:27.179960 | TASK [Setup log path fact]
2025-10-10 08:36:27.196862 | localhost | ok
2025-10-10 08:36:27.208885 | 
2025-10-10 08:36:27.208968 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-10 08:36:27.235960 | localhost | ok
2025-10-10 08:36:27.244022 | 
2025-10-10 08:36:27.244089 | TASK [emit-job-header : Print job information]
2025-10-10 08:36:27.281804 | # Job Information
2025-10-10 08:36:27.281973 | Ansible Version: 2.15.12
2025-10-10 08:36:27.282005 | Job: cifmw-molecule-hive
2025-10-10 08:36:27.282027 | Pipeline: github-check
2025-10-10 08:36:27.282045 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-10 08:36:27.282064 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3368
2025-10-10 08:36:27.282085 | Log URL (when completed): https://logserver.rdoproject.org/22b/rdoproject.org/22bbbd74dfd2486f998dc854dc1d5145/
2025-10-10 08:36:27.282103 | Event ID: dc051e10-a5b2-11f0-8a63-96c3b7c31c2e
2025-10-10 08:36:27.285547 | 
2025-10-10 08:36:27.285612 | LOOP [emit-job-header : Print node information]
2025-10-10 08:36:27.389182 | localhost | ok:
2025-10-10 08:36:27.389434 | localhost | # Node Information
2025-10-10 08:36:27.389471 | localhost | Inventory Hostname: controller
2025-10-10 08:36:27.389500 | localhost | Hostname: np0005479727
2025-10-10 08:36:27.389542 | localhost | Username: zuul
2025-10-10 08:36:27.389588 | localhost | Distro: CentOS 9
2025-10-10 08:36:27.389612 | localhost | Provider: ibm-bm4-nodepool
2025-10-10 08:36:27.389631 | localhost | Region: regionOne
2025-10-10 08:36:27.389669 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-10 08:36:27.389690 | localhost | Product Name: OpenStack Compute
2025-10-10 08:36:27.389708 | localhost | Interface IP: 192.168.26.122
2025-10-10 08:36:27.411046 | 
2025-10-10 08:36:27.411171 | PLAY [all]
2025-10-10 08:36:27.417685 | 
2025-10-10 08:36:27.417750 | TASK [Gather network facts]
2025-10-10 08:36:27.791784 | controller | ok
2025-10-10 08:36:27.806494 | 
2025-10-10 08:36:27.806580 | TASK [include_role : start-zuul-console]
2025-10-10 08:36:27.825228 | controller | ok
2025-10-10 08:36:27.836657 | 
2025-10-10 08:36:27.836720 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-10 08:36:28.142761 | controller | ok
2025-10-10 08:36:28.151288 | 
2025-10-10 08:36:28.151359 | TASK [include_role : add-build-sshkey]
2025-10-10 08:36:28.168802 | controller | ok
2025-10-10 08:36:28.181234 | 
2025-10-10 08:36:28.181301 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-10 08:36:28.376777 | controller -> localhost | ok
2025-10-10 08:36:28.382660 | 
2025-10-10 08:36:28.382723 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-10 08:36:28.400591 | controller | ok
2025-10-10 08:36:28.413709 | controller | included: /var/lib/zuul/builds/22bbbd74dfd2486f998dc854dc1d5145/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-10 08:36:28.419048 | 
2025-10-10 08:36:28.419108 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-10 08:36:28.929338 | controller -> localhost | Generating public/private rsa key pair.
2025-10-10 08:36:28.929537 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/22bbbd74dfd2486f998dc854dc1d5145/work/22bbbd74dfd2486f998dc854dc1d5145_id_rsa.
2025-10-10 08:36:28.929572 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/22bbbd74dfd2486f998dc854dc1d5145/work/22bbbd74dfd2486f998dc854dc1d5145_id_rsa.pub.
2025-10-10 08:36:28.929597 | controller -> localhost | The key fingerprint is:
2025-10-10 08:36:28.929619 | controller -> localhost | SHA256:N5w1gsJKhwkdQp+D7tWiDvIulA+L7R/E8ZCoCk6djsM zuul-build-sshkey
2025-10-10 08:36:28.929638 | controller -> localhost | The key's randomart image is:
2025-10-10 08:36:28.929656 | controller -> localhost | +---[RSA 3072]----+
2025-10-10 08:36:28.929674 | controller -> localhost | | .+...           |
2025-10-10 08:36:28.929692 | controller -> localhost | |   *.*   .       |
2025-10-10 08:36:28.929710 | controller -> localhost | |  o @ + . . o    |
2025-10-10 08:36:28.929729 | controller -> localhost | | o.o.O . . + .   |
2025-10-10 08:36:28.929748 | controller -> localhost | |o.oo* o S =      |
2025-10-10 08:36:28.929766 | controller -> localhost | |*=o+ .   . .     |
2025-10-10 08:36:28.929783 | controller -> localhost | |*E*..            |
2025-10-10 08:36:28.929801 | controller -> localhost | |+=o. .           |
2025-10-10 08:36:28.929837 | controller -> localhost | | +=..            |
2025-10-10 08:36:28.929857 | controller -> localhost | +----[SHA256]-----+
2025-10-10 08:36:28.929901 | controller -> localhost | ok: Runtime: 0:00:00.172973
2025-10-10 08:36:28.935405 | 
2025-10-10 08:36:28.935471 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-10 08:36:28.954087 | controller | ok
2025-10-10 08:36:28.964334 | controller | included: /var/lib/zuul/builds/22bbbd74dfd2486f998dc854dc1d5145/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-10 08:36:28.972415 | 
2025-10-10 08:36:28.972480 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-10 08:36:28.986300 | controller | skipping: Conditional result was False
2025-10-10 08:36:28.992374 | 
2025-10-10 08:36:28.992460 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-10 08:36:29.356382 | controller | changed
2025-10-10 08:36:29.361132 | 
2025-10-10 08:36:29.361194 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-10 08:36:29.553300 | controller | ok
2025-10-10 08:36:29.558291 | 
2025-10-10 08:36:29.558356 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-10 08:36:30.122585 | controller | changed
2025-10-10 08:36:30.127476 | 
2025-10-10 08:36:30.127544 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-10 08:36:30.696457 | controller | changed
2025-10-10 08:36:30.701593 | 
2025-10-10 08:36:30.701676 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-10 08:36:30.725468 | controller | skipping: Conditional result was False
2025-10-10 08:36:30.731749 | 
2025-10-10 08:36:30.731841 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-10 08:36:31.044827 | controller -> localhost | changed
2025-10-10 08:36:31.055119 | 
2025-10-10 08:36:31.055215 | TASK [add-build-sshkey : Add back temp key]
2025-10-10 08:36:31.281660 | controller -> localhost | Identity added: /var/lib/zuul/builds/22bbbd74dfd2486f998dc854dc1d5145/work/22bbbd74dfd2486f998dc854dc1d5145_id_rsa (zuul-build-sshkey)
2025-10-10 08:36:31.281864 | controller -> localhost | ok: Runtime: 0:00:00.007600
2025-10-10 08:36:31.287241 | 
2025-10-10 08:36:31.287309 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-10 08:36:31.599781 | controller | ok
2025-10-10 08:36:31.604558 | 
2025-10-10 08:36:31.604636 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-10 08:36:31.628939 | controller | skipping: Conditional result was False
2025-10-10 08:36:31.639334 | 
2025-10-10 08:36:31.639401 | TASK [include_role : validate-host]
2025-10-10 08:36:31.657870 | controller | ok
2025-10-10 08:36:31.678725 | 
2025-10-10 08:36:31.678793 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-10 08:36:31.705990 | controller | ok
2025-10-10 08:36:31.710658 | 
2025-10-10 08:36:31.710719 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-10 08:36:31.916899 | controller -> localhost | ok
2025-10-10 08:36:31.923069 | 
2025-10-10 08:36:31.923138 | TASK [validate-host : Collect information about the host]
2025-10-10 08:36:32.526767 | controller | ok
2025-10-10 08:36:32.535649 | 
2025-10-10 08:36:32.535731 | TASK [validate-host : Sanitize hostname]
2025-10-10 08:36:32.616426 | controller | ok
2025-10-10 08:36:32.669753 | 
2025-10-10 08:36:32.669872 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-10 08:36:33.089201 | controller -> localhost | changed
2025-10-10 08:36:33.095394 | 
2025-10-10 08:36:33.095467 | TASK [validate-host : Collect information about zuul worker]
2025-10-10 08:36:33.410946 | controller | ok
2025-10-10 08:36:33.416602 | 
2025-10-10 08:36:33.416688 | TASK [validate-host : Write out all zuul information for each host]
2025-10-10 08:36:33.774989 | controller -> localhost | changed
2025-10-10 08:36:33.784241 | 
2025-10-10 08:36:33.784305 | TASK [include_role : prepare-workspace-openshift]
2025-10-10 08:36:33.797984 | controller | skipping: Conditional result was False
2025-10-10 08:36:33.803137 | 
2025-10-10 08:36:33.803202 | TASK [include_role : remove-zuul-sshkey]
2025-10-10 08:36:33.816613 | controller | skipping: Conditional result was False
2025-10-10 08:36:33.822529 | 
2025-10-10 08:36:33.822609 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-10 08:36:34.011072 | controller | ok: "logs"
2025-10-10 08:36:34.011332 | controller | ok: All items complete
2025-10-10 08:36:34.011366 | 
2025-10-10 08:36:34.172940 | controller | ok: "artifacts"
2025-10-10 08:36:34.343713 | controller | ok: "docs"
2025-10-10 08:36:34.350512 | 
2025-10-10 08:36:34.350583 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-10 08:36:34.545924 | controller | changed: "logs"
2025-10-10 08:36:34.723839 | controller | changed: "artifacts"
2025-10-10 08:36:34.897180 | controller | changed: "docs"
2025-10-10 08:36:34.929700 | 
2025-10-10 08:36:34.929789 | PLAY RECAP
2025-10-10 08:36:34.929851 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-10 08:36:34.929882 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-10 08:36:34.929902 | 
2025-10-10 08:36:35.023163 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-10 08:36:35.023897 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-10 08:36:35.606127 | 
2025-10-10 08:36:35.606299 | PLAY [all]
2025-10-10 08:36:35.627125 | 
2025-10-10 08:36:35.627224 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-10 08:36:35.681671 | controller | ok
2025-10-10 08:36:35.686455 | 
2025-10-10 08:36:35.686536 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-10 08:36:36.049532 | controller | changed
2025-10-10 08:36:36.055657 | 
2025-10-10 08:36:36.055739 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-10 08:36:36.887983 | controller | changed
2025-10-10 08:36:36.898007 | 
2025-10-10 08:36:36.898195 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-10 08:36:37.247722 | controller | changed:
2025-10-10 08:36:37.247987 | controller | {
2025-10-10 08:36:37.248028 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-10 08:36:37.248087 | controller | }
2025-10-10 08:36:37.451593 | controller | changed:
2025-10-10 08:36:37.451750 | controller | {
2025-10-10 08:36:37.451785 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-10 08:36:37.451823 | controller | }
2025-10-10 08:36:37.652211 | controller | changed:
2025-10-10 08:36:37.652303 | controller | {
2025-10-10 08:36:37.652332 | 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-10 08:36:37.652363 | controller | }
2025-10-10 08:36:37.861464 | controller | changed:
2025-10-10 08:36:37.861608 | controller | {
2025-10-10 08:36:37.861637 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-10 08:36:37.861659 | controller | }
2025-10-10 08:36:38.058452 | controller | changed:
2025-10-10 08:36:38.058550 | controller | {
2025-10-10 08:36:38.058579 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-10 08:36:38.058600 | controller | }
2025-10-10 08:36:38.272687 | controller | changed:
2025-10-10 08:36:38.272892 | controller | {
2025-10-10 08:36:38.273001 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-10 08:36:38.273029 | controller | }
2025-10-10 08:36:38.474121 | controller | changed:
2025-10-10 08:36:38.474272 | controller | {
2025-10-10 08:36:38.474299 | 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-10 08:36:38.474320 | controller | }
2025-10-10 08:36:38.674541 | controller | changed:
2025-10-10 08:36:38.674633 | controller | {
2025-10-10 08:36:38.674660 | 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-10 08:36:38.674681 | controller | }
2025-10-10 08:36:38.881125 | controller | changed:
2025-10-10 08:36:38.881684 | controller | {
2025-10-10 08:36:38.881732 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-10 08:36:38.881757 | controller | }
2025-10-10 08:36:39.082417 | controller | changed:
2025-10-10 08:36:39.082516 | controller | {
2025-10-10 08:36:39.082543 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-10 08:36:39.082563 | controller | }
2025-10-10 08:36:39.282653 | controller | changed:
2025-10-10 08:36:39.282749 | controller | {
2025-10-10 08:36:39.282776 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-10 08:36:39.282797 | controller | }
2025-10-10 08:36:39.504057 | controller | changed:
2025-10-10 08:36:39.504143 | controller | {
2025-10-10 08:36:39.504171 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-10 08:36:39.504193 | controller | }
2025-10-10 08:36:39.693436 | controller | changed:
2025-10-10 08:36:39.693531 | controller | {
2025-10-10 08:36:39.693558 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-10 08:36:39.693580 | controller | }
2025-10-10 08:36:39.900356 | controller | changed:
2025-10-10 08:36:39.900541 | controller | {
2025-10-10 08:36:39.900573 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-10 08:36:39.900595 | controller | }
2025-10-10 08:36:40.111875 | controller | changed:
2025-10-10 08:36:40.111976 | controller | {
2025-10-10 08:36:40.112003 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-10 08:36:40.112024 | controller | }
2025-10-10 08:36:40.318690 | controller | changed:
2025-10-10 08:36:40.318782 | controller | {
2025-10-10 08:36:40.318821 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-10 08:36:40.318843 | controller | }
2025-10-10 08:36:40.523253 | controller | changed:
2025-10-10 08:36:40.523353 | controller | {
2025-10-10 08:36:40.523380 | 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-10 08:36:40.523402 | controller | }
2025-10-10 08:36:40.727842 | controller | changed:
2025-10-10 08:36:40.727994 | controller | {
2025-10-10 08:36:40.728025 | 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-10 08:36:40.728047 | controller | }
2025-10-10 08:36:40.935930 | controller | changed:
2025-10-10 08:36:40.936023 | controller | {
2025-10-10 08:36:40.936051 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-10 08:36:40.936071 | controller | }
2025-10-10 08:36:41.165433 | controller | changed:
2025-10-10 08:36:41.165526 | controller | {
2025-10-10 08:36:41.165552 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-10 08:36:41.165573 | controller | }
2025-10-10 08:36:41.377719 | controller | changed:
2025-10-10 08:36:41.377824 | controller | {
2025-10-10 08:36:41.377851 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-10 08:36:41.377870 | controller | }
2025-10-10 08:36:41.602531 | controller | changed:
2025-10-10 08:36:41.602677 | controller | {
2025-10-10 08:36:41.602703 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-10 08:36:41.602723 | controller | }
2025-10-10 08:36:41.822875 | controller | changed:
2025-10-10 08:36:41.822995 | controller | {
2025-10-10 08:36:41.823025 | 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-10 08:36:41.823047 | controller | }
2025-10-10 08:36:42.043024 | controller | changed:
2025-10-10 08:36:42.043245 | controller | {
2025-10-10 08:36:42.043285 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-10 08:36:42.043309 | controller | }
2025-10-10 08:36:42.252446 | controller | changed:
2025-10-10 08:36:42.252542 | controller | {
2025-10-10 08:36:42.252568 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-10 08:36:42.252589 | controller | }
2025-10-10 08:36:42.454434 | controller | changed:
2025-10-10 08:36:42.454526 | controller | {
2025-10-10 08:36:42.454552 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-10 08:36:42.454573 | controller | }
2025-10-10 08:36:42.477532 | 
2025-10-10 08:36:42.477643 | TASK [Set timezone to UTC]
2025-10-10 08:36:42.871946 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-10 08:36:42.876990 | 
2025-10-10 08:36:42.877063 | TASK [Create nodepool directory]
2025-10-10 08:36:43.078083 | controller | changed
2025-10-10 08:36:43.083761 | 
2025-10-10 08:36:43.083852 | TASK [Create nodepool sub_nodes file]
2025-10-10 08:36:43.590238 | controller | changed
2025-10-10 08:36:43.595584 | 
2025-10-10 08:36:43.595659 | TASK [Create nodepool sub_nodes_private file]
2025-10-10 08:36:44.121652 | controller | changed
2025-10-10 08:36:44.127379 | 
2025-10-10 08:36:44.127450 | LOOP [Populate nodepool sub_nodes file]
2025-10-10 08:36:44.157013 | 
2025-10-10 08:36:44.157164 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-10 08:36:44.186511 | 
2025-10-10 08:36:44.186670 | TASK [Create nodepool primary file]
2025-10-10 08:36:44.210880 | controller | skipping: Conditional result was False
2025-10-10 08:36:44.216835 | 
2025-10-10 08:36:44.216959 | TASK [Create nodepool node_private for this node]
2025-10-10 08:36:44.783350 | controller | changed
2025-10-10 08:36:44.789312 | 
2025-10-10 08:36:44.789395 | LOOP [Copy ssh keys to nodepool directory]
2025-10-10 08:36:45.113984 | controller | ok: Item: id_rsa Runtime: 0:00:00.006395
2025-10-10 08:36:45.114151 | 
2025-10-10 08:36:45.275369 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005199
2025-10-10 08:36:45.287569 | 
2025-10-10 08:36:45.287699 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-10 08:36:45.814616 | controller | changed
2025-10-10 08:36:45.820163 | 
2025-10-10 08:36:45.820378 | TASK [Validate sudoers config after edits]
2025-10-10 08:36:46.066217 | controller | /etc/sudoers: parsed OK
2025-10-10 08:36:46.066298 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-10 08:36:46.066309 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-10 08:36:46.066315 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-10 08:36:46.352259 | controller | ok: Runtime: 0:00:00.005590
2025-10-10 08:36:46.358495 | 
2025-10-10 08:36:46.358564 | TASK [Show the environment passed in to job shell scripts]
2025-10-10 08:36:46.619339 | controller | SHELL=/bin/bash
2025-10-10 08:36:46.619455 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-10 08:36:46.619469 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-10 08:36:46.619476 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/68/3368/c2b06211d634b2d96a02011714110041916012e4
2025-10-10 08:36:46.619483 | controller | PWD=/home/zuul
2025-10-10 08:36:46.619488 | controller | ZUUL_PIPELINE=github-check
2025-10-10 08:36:46.619494 | controller | LOGNAME=zuul
2025-10-10 08:36:46.619500 | controller | XDG_SESSION_TYPE=tty
2025-10-10 08:36:46.619505 | controller | _=/usr/bin/env
2025-10-10 08:36:46.619511 | controller | MOTD_SHOWN=pam
2025-10-10 08:36:46.619516 | controller | HOME=/home/zuul
2025-10-10 08:36:46.619522 | controller | LANG=en_US.UTF-8
2025-10-10 08:36:46.619527 | controller | SSH_CONNECTION=192.168.26.12 46946 192.168.26.122 22
2025-10-10 08:36:46.619533 | 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-10 08:36:46.619542 | controller | ZUUL_CHANGE_IDS=3368,c2b06211d634b2d96a02011714110041916012e4
2025-10-10 08:36:46.619547 | controller | WORKSPACE=/home/zuul/workspace
2025-10-10 08:36:46.619553 | controller | XDG_SESSION_CLASS=user
2025-10-10 08:36:46.619559 | controller | SELINUX_ROLE_REQUESTED=
2025-10-10 08:36:46.619564 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-10 08:36:46.619570 | controller | USER=zuul
2025-10-10 08:36:46.619575 | controller | ZUUL_VOTING=True
2025-10-10 08:36:46.619581 | controller | BUILD_TIMEOUT=1800000
2025-10-10 08:36:46.619586 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-10 08:36:46.619592 | controller | SHLVL=1
2025-10-10 08:36:46.619597 | controller | ZUUL_PATCHSET=c2b06211d634b2d96a02011714110041916012e4
2025-10-10 08:36:46.619603 | controller | XDG_SESSION_ID=1
2025-10-10 08:36:46.619608 | controller | ZUUL_BRANCH=main
2025-10-10 08:36:46.619614 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-10 08:36:46.619619 | controller | SSH_CLIENT=192.168.26.12 46946 22
2025-10-10 08:36:46.619625 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-10 08:36:46.619631 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-10 08:36:46.619638 | controller | which_declare=declare -f
2025-10-10 08:36:46.619644 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-10 08:36:46.619649 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-10 08:36:46.619656 | controller | ZUUL_CHANGE=3368
2025-10-10 08:36:46.619661 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-10 08:36:46.619666 | controller | ZUUL_UUID=22bbbd74dfd2486f998dc854dc1d5145
2025-10-10 08:36:46.619672 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-10 08:36:46.619678 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-10 08:36:46.619684 | controller | }
2025-10-10 08:36:46.893530 | controller | ok: Runtime: 0:00:00.005291
2025-10-10 08:36:46.898955 | 
2025-10-10 08:36:46.899019 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-10 08:36:46.912473 | controller | skipping: Conditional result was False
2025-10-10 08:36:46.918010 | 
2025-10-10 08:36:46.918082 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-10 08:36:47.441620 | controller | skipping: Conditional result was False
2025-10-10 08:36:47.447311 | 
2025-10-10 08:36:47.447383 | TASK [Ensure legacy workspace directory]
2025-10-10 08:36:47.640983 | controller | changed
2025-10-10 08:36:47.660294 | 
2025-10-10 08:36:47.660369 | PLAY RECAP
2025-10-10 08:36:47.660414 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-10 08:36:47.660437 | 
2025-10-10 08:36:47.742446 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-10 08:36:47.743243 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-10 08:36:48.277467 | 
2025-10-10 08:36:48.277567 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-10 08:36:48.298224 | 
2025-10-10 08:36:48.298301 | TASK [Create zuul-output directory]
2025-10-10 08:36:48.603934 | controller | changed
2025-10-10 08:36:48.609058 | 
2025-10-10 08:36:48.609142 | TASK [Slurp Zuul inventory test]
2025-10-10 08:36:48.886044 | controller -> localhost | ok
2025-10-10 08:36:48.892617 | 
2025-10-10 08:36:48.892713 | TASK [Save zuul inventory]
2025-10-10 08:36:49.619848 | controller | changed
2025-10-10 08:36:49.626962 | 
2025-10-10 08:36:49.627040 | TASK [Save zuul vars without the change_message]
2025-10-10 08:36:50.204876 | controller | changed
2025-10-10 08:36:50.225309 | 
2025-10-10 08:36:50.225375 | PLAY RECAP
2025-10-10 08:36:50.225450 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-10 08:36:50.225483 | 
2025-10-10 08:36:50.296090 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-10 08:36:50.296856 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-10 08:36:50.882054 | 
2025-10-10 08:36:50.882159 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-10 08:36:50.903136 | 
2025-10-10 08:36:50.903213 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-10 08:36:50.921071 | controller | ok
2025-10-10 08:36:50.938335 | 
2025-10-10 08:36:50.938422 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-10 08:36:50.961904 | controller | skipping: Conditional result was False
2025-10-10 08:36:50.967633 | 
2025-10-10 08:36:50.967702 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-10 08:36:51.296986 | controller | ok
2025-10-10 08:36:51.302769 | 
2025-10-10 08:36:51.302860 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-10 08:36:51.965017 | controller | ok
2025-10-10 08:36:51.977383 | 
2025-10-10 08:36:51.977476 | TASK [Prepare workspace]
2025-10-10 08:36:51.996496 | controller | ok
2025-10-10 08:36:52.014237 | 
2025-10-10 08:36:52.014328 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-10 08:36:52.317346 | controller | ok
2025-10-10 08:36:52.324173 | 
2025-10-10 08:36:52.324247 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-10 08:36:54.688413 | controller | Output suppressed because no_log was given
2025-10-10 08:36:54.697706 | 
2025-10-10 08:36:54.697773 | LOOP [Create zuul-output directory]
2025-10-10 08:36:54.880780 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-10 08:36:55.044858 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-10 08:36:55.054264 | 
2025-10-10 08:36:55.054373 | TASK [Install required packages]
2025-10-10 08:37:48.116406 | controller | changed
2025-10-10 08:37:48.122033 | 
2025-10-10 08:37:48.122106 | TASK [Install venv]
2025-10-10 08:38:49.446258 | controller | changed
2025-10-10 08:38:49.478125 | 
2025-10-10 08:38:49.478206 | PLAY RECAP
2025-10-10 08:38:49.478248 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-10 08:38:49.478271 | 
2025-10-10 08:38:49.561323 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-10 08:38:49.562143 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-10 08:38:50.302710 | 
2025-10-10 08:38:50.302817 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-10 08:38:50.323322 | 
2025-10-10 08:38:50.323395 | TASK [Gather required facts]
2025-10-10 08:38:50.850530 | controller | ok
2025-10-10 08:38:50.856325 | 
2025-10-10 08:38:50.856407 | TASK [Load environment var if instructed to]
2025-10-10 08:38:50.880305 | controller | skipping: Conditional result was False
2025-10-10 08:38:50.886235 | 
2025-10-10 08:38:50.886304 | TASK [Ensure group_vars dir exists]
2025-10-10 08:38:51.210850 | controller | ok
2025-10-10 08:38:51.217515 | 
2025-10-10 08:38:51.217581 | TASK [Run molecule]
2025-10-10 08:38:52.195820 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-10 08:38:52.267095 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-10 08:39:09.082257 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/7640da/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:39:09.082396 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/7640da/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:39:09.082857 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/7640da/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:39:09.083329 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/7640da/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:39:09.083815 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+1103f848 was found installed in /home/zuul/.cache/ansible-compat/7640da/collections/ansible_collections, only the first one will be used, 1.0.0+1103f848 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:39:09.084272 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/7640da/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:39:09.084703 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/7640da/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:39:09.085178 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/7640da/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:39:09.085608 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/7640da/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:39:09.086094 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/7640da/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:39:09.086538 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/7640da/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:39:09.087000 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/7640da/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:39:09.087430 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/7640da/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:39:09.087873 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/7640da/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:39:09.088329 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/7640da/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:39:09.098096 | controller | INFO     Running default > prepare
2025-10-10 08:39:09.778222 | controller |
2025-10-10 08:39:09.778325 | controller | PLAY [Prepare] *****************************************************************
2025-10-10 08:39:09.778422 | controller |
2025-10-10 08:39:09.778524 | controller | TASK [Gathering Facts] *********************************************************
2025-10-10 08:39:09.778629 | controller | Friday 10 October 2025  08:39:09 +0000 (0:00:00.019)       0:00:00.019 ********
2025-10-10 08:39:10.605394 | controller | ok: [instance]
2025-10-10 08:39:10.605451 | controller |
2025-10-10 08:39:10.605596 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-10 08:39:10.605704 | controller | Friday 10 October 2025  08:39:10 +0000 (0:00:00.827)       0:00:00.847 ********
2025-10-10 08:39:10.621744 | controller | skipping: [instance]
2025-10-10 08:39:10.621868 | controller |
2025-10-10 08:39:10.622028 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-10 08:39:10.622146 | controller | Friday 10 October 2025  08:39:10 +0000 (0:00:00.016)       0:00:00.863 ********
2025-10-10 08:39:10.660849 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-10 08:39:10.660974 | controller |
2025-10-10 08:39:10.661105 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-10 08:39:10.661232 | controller | Friday 10 October 2025  08:39:10 +0000 (0:00:00.038)       0:00:00.902 ********
2025-10-10 08:39:10.934126 | controller | ok: [instance]
2025-10-10 08:39:10.934214 | controller |
2025-10-10 08:39:10.934335 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-10 08:39:10.934445 | controller | Friday 10 October 2025  08:39:10 +0000 (0:00:00.273)       0:00:01.175 ********
2025-10-10 08:39:11.248592 | controller | ok: [instance]
2025-10-10 08:39:11.249300 | controller |
2025-10-10 08:39:11.253355 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-10 08:39:11.254126 | controller | Friday 10 October 2025  08:39:11 +0000 (0:00:00.312)       0:00:01.488 ********
2025-10-10 08:39:11.937408 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-10 08:39:11.937459 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-10 08:39:11.937600 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-10 08:39:11.937719 | controller |
2025-10-10 08:39:11.937867 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-10 08:39:11.938032 | controller | Friday 10 October 2025  08:39:11 +0000 (0:00:00.690)       0:00:02.179 ********
2025-10-10 08:39:11.995488 | controller |
2025-10-10 08:39:11.995616 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-10 08:39:11.995745 | controller | Friday 10 October 2025  08:39:11 +0000 (0:00:00.058)       0:00:02.237 ********
2025-10-10 08:39:12.542212 | controller | changed: [instance] => (item=tmp)
2025-10-10 08:39:12.542276 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-10 08:39:12.542394 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-10 08:39:12.542494 | controller |
2025-10-10 08:39:12.542610 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-10 08:39:12.542714 | controller | Friday 10 October 2025  08:39:12 +0000 (0:00:00.546)       0:00:02.783 ********
2025-10-10 08:39:13.307694 | controller | ok: [instance]
2025-10-10 08:39:13.307754 | controller |
2025-10-10 08:39:13.307881 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-10 08:39:13.308013 | controller | Friday 10 October 2025  08:39:13 +0000 (0:00:00.765)       0:00:03.549 ********
2025-10-10 08:39:14.514020 | controller | changed: [instance]
2025-10-10 08:39:14.514360 | controller |
2025-10-10 08:39:22.538410 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-10 08:39:22.538446 | controller | Friday 10 October 2025  08:39:14 +0000 (0:00:01.206)       0:00:04.755 ********
2025-10-10 08:39:22.538458 | controller | changed: [instance]
2025-10-10 08:39:23.290790 | controller |
2025-10-10 08:39:23.290829 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-10 08:39:23.290838 | controller | Friday 10 October 2025  08:39:22 +0000 (0:00:08.023)       0:00:12.779 ********
2025-10-10 08:39:23.290848 | controller | changed: [instance]
2025-10-10 08:39:23.311753 | controller |
2025-10-10 08:39:23.311774 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-10 08:39:23.311782 | controller | Friday 10 October 2025  08:39:23 +0000 (0:00:00.752)       0:00:13.532 ********
2025-10-10 08:39:23.311791 | controller | skipping: [instance]
2025-10-10 08:39:24.152719 | controller |
2025-10-10 08:39:24.152760 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-10 08:39:24.152768 | controller | Friday 10 October 2025  08:39:23 +0000 (0:00:00.021)       0:00:13.553 ********
2025-10-10 08:39:24.152777 | controller | changed: [instance]
2025-10-10 08:39:24.205544 | controller |
2025-10-10 08:39:24.205567 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-10 08:39:24.205575 | controller | Friday 10 October 2025  08:39:24 +0000 (0:00:00.840)       0:00:14.394 ********
2025-10-10 08:39:24.205585 | controller | skipping: [instance]
2025-10-10 08:39:24.242946 | controller |
2025-10-10 08:39:24.242965 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-10 08:39:24.242972 | controller | Friday 10 October 2025  08:39:24 +0000 (0:00:00.052)       0:00:14.447 ********
2025-10-10 08:39:24.242981 | controller | skipping: [instance]
2025-10-10 08:39:24.243002 | controller |
2025-10-10 08:39:24.243009 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-10 08:39:24.243521 | controller | Friday 10 October 2025  08:39:24 +0000 (0:00:00.037)       0:00:14.484 ********
2025-10-10 08:39:24.281355 | controller | skipping: [instance]
2025-10-10 08:39:24.839032 | controller |
2025-10-10 08:39:24.839064 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-10 08:39:24.839072 | controller | Friday 10 October 2025  08:39:24 +0000 (0:00:00.038)       0:00:14.522 ********
2025-10-10 08:39:24.839082 | controller | changed: [instance]
2025-10-10 08:39:25.287436 | controller |
2025-10-10 08:39:25.287467 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-10 08:39:25.287475 | controller | Friday 10 October 2025  08:39:24 +0000 (0:00:00.557)       0:00:15.080 ********
2025-10-10 08:39:25.287485 | controller | changed: [instance]
2025-10-10 08:39:25.315459 | controller |
2025-10-10 08:39:25.315487 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-10 08:39:25.315494 | controller | Friday 10 October 2025  08:39:25 +0000 (0:00:00.448)       0:00:15.529 ********
2025-10-10 08:39:25.315503 | controller | skipping: [instance]
2025-10-10 08:39:25.343589 | controller |
2025-10-10 08:39:25.343622 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-10 08:39:25.343630 | controller | Friday 10 October 2025  08:39:25 +0000 (0:00:00.027)       0:00:15.556 ********
2025-10-10 08:39:25.343640 | controller | skipping: [instance]
2025-10-10 08:39:25.371131 | controller |
2025-10-10 08:39:25.371160 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-10 08:39:25.371168 | controller | Friday 10 October 2025  08:39:25 +0000 (0:00:00.028)       0:00:15.585 ********
2025-10-10 08:39:25.371181 | controller | skipping: [instance]
2025-10-10 08:39:25.408228 | controller |
2025-10-10 08:39:25.408252 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-10 08:39:25.408259 | controller | Friday 10 October 2025  08:39:25 +0000 (0:00:00.027)       0:00:15.612 ********
2025-10-10 08:39:25.408269 | controller | ok: [instance]
2025-10-10 08:39:25.408424 | controller |
2025-10-10 08:39:25.408577 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-10 08:39:25.408720 | controller | Friday 10 October 2025  08:39:25 +0000 (0:00:00.037)       0:00:15.650 ********
2025-10-10 08:39:25.434493 | controller | skipping: [instance]
2025-10-10 08:39:25.434677 | controller |
2025-10-10 08:39:25.434840 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-10 08:39:25.435008 | controller | Friday 10 October 2025  08:39:25 +0000 (0:00:00.026)       0:00:15.676 ********
2025-10-10 08:39:25.460081 | controller | skipping: [instance]
2025-10-10 08:39:25.460274 | controller |
2025-10-10 08:39:25.460447 | controller | TASK [Download the RPM] ********************************************************
2025-10-10 08:39:25.460619 | controller | Friday 10 October 2025  08:39:25 +0000 (0:00:00.025)       0:00:15.702 ********
2025-10-10 08:39:25.486073 | controller | skipping: [instance]
2025-10-10 08:39:25.486248 | controller |
2025-10-10 08:39:25.486401 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-10 08:39:25.486546 | controller | Friday 10 October 2025  08:39:25 +0000 (0:00:00.025)       0:00:15.728 ********
2025-10-10 08:39:25.514660 | controller | skipping: [instance]
2025-10-10 08:39:25.514871 | controller |
2025-10-10 08:39:25.515056 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-10 08:39:25.515205 | controller | Friday 10 October 2025  08:39:25 +0000 (0:00:00.028)       0:00:15.756 ********
2025-10-10 08:39:25.541468 | controller | skipping: [instance]
2025-10-10 08:39:25.541669 | controller |
2025-10-10 08:39:25.541860 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-10 08:39:25.542037 | controller | Friday 10 October 2025  08:39:25 +0000 (0:00:00.026)       0:00:15.783 ********
2025-10-10 08:39:25.569814 | controller | skipping: [instance]
2025-10-10 08:39:25.570033 | controller |
2025-10-10 08:39:25.570213 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-10 08:39:25.570367 | controller | Friday 10 October 2025  08:39:25 +0000 (0:00:00.028)       0:00:15.811 ********
2025-10-10 08:39:25.595551 | controller | skipping: [instance]
2025-10-10 08:39:25.595778 | controller |
2025-10-10 08:39:25.595989 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-10 08:39:25.596145 | controller | Friday 10 October 2025  08:39:25 +0000 (0:00:00.025)       0:00:15.837 ********
2025-10-10 08:39:25.782986 | controller | ok: [instance]
2025-10-10 08:39:25.989535 | controller |
2025-10-10 08:39:25.989566 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-10 08:39:25.989574 | controller | Friday 10 October 2025  08:39:25 +0000 (0:00:00.187)       0:00:16.024 ********
2025-10-10 08:39:25.989584 | controller | changed: [instance]
2025-10-10 08:39:26.219843 | controller |
2025-10-10 08:39:26.219873 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-10 08:39:26.219881 | controller | Friday 10 October 2025  08:39:25 +0000 (0:00:00.206)       0:00:16.231 ********
2025-10-10 08:39:26.219915 | controller | changed: [instance]
2025-10-10 08:39:26.240260 | controller |
2025-10-10 08:39:26.240280 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-10 08:39:26.240287 | controller | Friday 10 October 2025  08:39:26 +0000 (0:00:00.230)       0:00:16.461 ********
2025-10-10 08:39:26.240297 | controller | skipping: [instance]
2025-10-10 08:39:26.263047 | controller |
2025-10-10 08:39:26.263068 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-10 08:39:26.263075 | controller | Friday 10 October 2025  08:39:26 +0000 (0:00:00.020)       0:00:16.481 ********
2025-10-10 08:39:26.263084 | controller | skipping: [instance]
2025-10-10 08:39:26.285977 | controller |
2025-10-10 08:39:26.286010 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-10 08:39:26.286018 | controller | Friday 10 October 2025  08:39:26 +0000 (0:00:00.022)       0:00:16.504 ********
2025-10-10 08:39:26.286029 | controller | skipping: [instance]
2025-10-10 08:39:26.286053 | controller |
2025-10-10 08:39:26.286061 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-10 08:39:26.286151 | controller | Friday 10 October 2025  08:39:26 +0000 (0:00:00.022)       0:00:16.527 ********
2025-10-10 08:39:26.309397 | controller | skipping: [instance]
2025-10-10 08:39:26.332140 | controller |
2025-10-10 08:39:26.332161 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-10 08:39:26.332174 | controller | Friday 10 October 2025  08:39:26 +0000 (0:00:00.023)       0:00:16.551 ********
2025-10-10 08:39:26.332184 | controller | skipping: [instance]
2025-10-10 08:39:26.332331 | controller |
2025-10-10 08:39:26.332498 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-10 08:39:26.332653 | controller | Friday 10 October 2025  08:39:26 +0000 (0:00:00.023)       0:00:16.574 ********
2025-10-10 08:39:26.355623 | controller | skipping: [instance]
2025-10-10 08:39:26.355817 | controller |
2025-10-10 08:39:26.356021 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-10 08:39:26.356199 | controller | Friday 10 October 2025  08:39:26 +0000 (0:00:00.023)       0:00:16.597 ********
2025-10-10 08:39:26.374332 | controller | skipping: [instance]
2025-10-10 08:39:26.374485 | controller |
2025-10-10 08:39:26.374627 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-10 08:39:26.374781 | controller | Friday 10 October 2025  08:39:26 +0000 (0:00:00.018)       0:00:16.616 ********
2025-10-10 08:39:26.406702 | controller | skipping: [instance]
2025-10-10 08:39:26.406947 | controller |
2025-10-10 08:39:26.407130 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-10 08:39:26.407302 | controller | Friday 10 October 2025  08:39:26 +0000 (0:00:00.032)       0:00:16.648 ********
2025-10-10 08:39:26.428637 | controller | skipping: [instance]
2025-10-10 08:39:26.428856 | controller |
2025-10-10 08:39:26.429058 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-10 08:39:26.429239 | controller | Friday 10 October 2025  08:39:26 +0000 (0:00:00.021)       0:00:16.670 ********
2025-10-10 08:39:26.451492 | controller | skipping: [instance]
2025-10-10 08:39:26.451673 | controller |
2025-10-10 08:39:26.451868 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-10 08:39:26.452069 | controller | Friday 10 October 2025  08:39:26 +0000 (0:00:00.022)       0:00:16.693 ********
2025-10-10 08:39:26.481467 | controller | skipping: [instance]
2025-10-10 08:39:26.481644 | controller |
2025-10-10 08:39:26.481805 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-10 08:39:26.481968 | controller | Friday 10 October 2025  08:39:26 +0000 (0:00:00.029)       0:00:16.723 ********
2025-10-10 08:39:26.510189 | controller | skipping: [instance]
2025-10-10 08:39:26.510375 | controller |
2025-10-10 08:39:26.510561 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-10 08:39:26.510735 | controller | Friday 10 October 2025  08:39:26 +0000 (0:00:00.028)       0:00:16.752 ********
2025-10-10 08:39:26.539585 | controller | skipping: [instance]
2025-10-10 08:39:26.539772 | controller |
2025-10-10 08:39:26.539939 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-10 08:39:26.540085 | controller | Friday 10 October 2025  08:39:26 +0000 (0:00:00.029)       0:00:16.781 ********
2025-10-10 08:39:26.568167 | controller | skipping: [instance]
2025-10-10 08:39:26.568324 | controller |
2025-10-10 08:39:26.568471 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-10 08:39:26.568610 | controller | Friday 10 October 2025  08:39:26 +0000 (0:00:00.028)       0:00:16.810 ********
2025-10-10 08:40:11.591258 | controller | ok: [instance]
2025-10-10 08:40:12.759867 | controller |
2025-10-10 08:40:12.759912 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-10 08:40:12.759920 | controller | Friday 10 October 2025  08:40:11 +0000 (0:00:45.022)       0:01:01.832 ********
2025-10-10 08:40:12.759931 | controller | ok: [instance]
2025-10-10 08:40:12.792363 | controller |
2025-10-10 08:40:12.792401 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-10 08:40:12.792409 | controller | Friday 10 October 2025  08:40:12 +0000 (0:00:01.168)       0:01:03.001 ********
2025-10-10 08:40:12.792419 | controller | skipping: [instance]
2025-10-10 08:40:12.834987 | controller |
2025-10-10 08:40:12.835025 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-10-10 08:40:12.835034 | controller | Friday 10 October 2025  08:40:12 +0000 (0:00:00.032)       0:01:03.034 ********
2025-10-10 08:40:12.835045 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-10-10 08:40:12.835063 | controller |
2025-10-10 08:40:12.835152 | controller | TASK [ci_setup : List packages to install] *************************************
2025-10-10 08:40:12.835285 | controller | Friday 10 October 2025  08:40:12 +0000 (0:00:00.042)       0:01:03.076 ********
2025-10-10 08:40:12.855963 | controller | ok: [instance] =>
2025-10-10 08:40:12.856002 | controller |   cifmw_ci_setup_packages:
2025-10-10 08:40:12.856011 | controller |   - bash-completion
2025-10-10 08:40:12.856017 | controller |   - ca-certificates
2025-10-10 08:40:12.856022 | controller |   - git-core
2025-10-10 08:40:12.856028 | controller |   - make
2025-10-10 08:40:12.856034 | controller |   - tar
2025-10-10 08:40:12.856039 | controller |   - tmux
2025-10-10 08:40:12.856048 | controller |   - python3-pip
2025-10-10 08:40:12.856055 | controller |
2025-10-10 08:40:12.856233 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-10-10 08:40:17.258434 | controller | Friday 10 October 2025  08:40:12 +0000 (0:00:00.020)       0:01:03.097 ********
2025-10-10 08:40:17.258470 | controller | changed: [instance]
2025-10-10 08:40:17.438914 | controller |
2025-10-10 08:40:17.438945 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-10-10 08:40:17.438958 | controller | Friday 10 October 2025  08:40:17 +0000 (0:00:04.402)       0:01:07.499 ********
2025-10-10 08:40:17.438969 | controller | ok: [instance]
2025-10-10 08:40:17.629968 | controller |
2025-10-10 08:40:17.630006 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-10-10 08:40:17.630014 | controller | Friday 10 October 2025  08:40:17 +0000 (0:00:00.180)       0:01:07.680 ********
2025-10-10 08:40:17.630025 | controller | changed: [instance]
2025-10-10 08:40:17.630043 | controller |
2025-10-10 08:40:17.630229 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-10-10 08:40:23.711996 | controller | Friday 10 October 2025  08:40:17 +0000 (0:00:00.191)       0:01:07.871 ********
2025-10-10 08:40:23.712041 | controller | changed: [instance]
2025-10-10 08:40:23.712068 | controller |
2025-10-10 08:40:23.712282 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-10-10 08:40:23.747450 | controller | Friday 10 October 2025  08:40:23 +0000 (0:00:06.082)       0:01:13.953 ********
2025-10-10 08:40:23.747487 | controller | skipping: [instance]
2025-10-10 08:40:24.011130 | controller |
2025-10-10 08:40:24.011164 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-10-10 08:40:24.011173 | controller | Friday 10 October 2025  08:40:23 +0000 (0:00:00.035)       0:01:13.989 ********
2025-10-10 08:40:24.011183 | controller | changed: [instance]
2025-10-10 08:40:24.291877 | controller |
2025-10-10 08:40:24.291936 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-10-10 08:40:24.291945 | controller | Friday 10 October 2025  08:40:24 +0000 (0:00:00.263)       0:01:14.252 ********
2025-10-10 08:40:24.291955 | controller | changed: [instance]
2025-10-10 08:40:24.309000 | controller |
2025-10-10 08:40:24.309039 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-10-10 08:40:24.309049 | controller | Friday 10 October 2025  08:40:24 +0000 (0:00:00.280)       0:01:14.533 ********
2025-10-10 08:40:24.309061 | controller | skipping: [instance]
2025-10-10 08:40:24.309089 | controller |
2025-10-10 08:40:24.309099 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-10-10 08:40:24.309226 | controller | Friday 10 October 2025  08:40:24 +0000 (0:00:00.017)       0:01:14.550 ********
2025-10-10 08:40:24.325712 | controller | skipping: [instance]
2025-10-10 08:40:24.342732 | controller |
2025-10-10 08:40:24.342754 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-10-10 08:40:24.342762 | controller | Friday 10 October 2025  08:40:24 +0000 (0:00:00.016)       0:01:14.567 ********
2025-10-10 08:40:24.342772 | controller | skipping: [instance]
2025-10-10 08:40:24.359416 | controller |
2025-10-10 08:40:24.359441 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-10-10 08:40:24.359449 | controller | Friday 10 October 2025  08:40:24 +0000 (0:00:00.017)       0:01:14.584 ********
2025-10-10 08:40:24.359459 | controller | skipping: [instance]
2025-10-10 08:40:24.375808 | controller |
2025-10-10 08:40:24.375831 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-10-10 08:40:24.375839 | controller | Friday 10 October 2025  08:40:24 +0000 (0:00:00.016)       0:01:14.601 ********
2025-10-10 08:40:24.375848 | controller | skipping: [instance]
2025-10-10 08:40:24.396968 | controller |
2025-10-10 08:40:24.397002 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-10-10 08:40:24.397018 | controller | Friday 10 October 2025  08:40:24 +0000 (0:00:00.016)       0:01:14.617 ********
2025-10-10 08:40:24.397030 | controller | skipping: [instance]
2025-10-10 08:40:24.397053 | controller |
2025-10-10 08:40:24.397061 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-10-10 08:40:24.397187 | controller | Friday 10 October 2025  08:40:24 +0000 (0:00:00.021)       0:01:14.638 ********
2025-10-10 08:40:25.405997 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-10-10 08:40:25.406938 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-10-10 08:40:25.406953 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-10-10 08:40:25.406960 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-10-10 08:40:25.406966 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-10-10 08:40:25.406972 | controller |
2025-10-10 08:40:25.406977 | controller | PLAY RECAP *********************************************************************
2025-10-10 08:40:25.406983 | controller | instance                   : ok=28   changed=15   unreachable=0    failed=0    skipped=37   rescued=0    ignored=0
2025-10-10 08:40:25.406988 | controller |
2025-10-10 08:40:25.406994 | controller | Friday 10 October 2025  08:40:25 +0000 (0:00:01.007)       0:01:15.646 ********
2025-10-10 08:40:25.406999 | controller | ===============================================================================
2025-10-10 08:40:25.407008 | controller | test_deps : Install selinux python libs -------------------------------- 45.02s
2025-10-10 08:40:25.407882 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.02s
2025-10-10 08:40:25.407935 | controller | ci_setup : Install openshift client ------------------------------------- 6.08s
2025-10-10 08:40:25.407943 | controller | ci_setup : Install needed packages -------------------------------------- 4.40s
2025-10-10 08:40:25.407949 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.21s
2025-10-10 08:40:25.407954 | controller | test_deps : Install python yaml libs ------------------------------------ 1.17s
2025-10-10 08:40:25.407960 | controller | ci_setup : Manage directories ------------------------------------------- 1.01s
2025-10-10 08:40:25.407965 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.84s
2025-10-10 08:40:25.407970 | controller | Gathering Facts --------------------------------------------------------- 0.83s
2025-10-10 08:40:25.407975 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.77s
2025-10-10 08:40:25.407985 | controller | repo_setup : Install repo-setup package --------------------------------- 0.75s
2025-10-10 08:40:25.464071 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.69s
2025-10-10 08:40:25.464102 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.56s
2025-10-10 08:40:25.464110 | controller | repo_setup : Ensure directories are present ----------------------------- 0.55s
2025-10-10 08:40:25.464115 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.45s
2025-10-10 08:40:25.464121 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.31s
2025-10-10 08:40:25.464126 | controller | ci_setup : Source completion from within .bashrc ------------------------ 0.28s
2025-10-10 08:40:25.464132 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.27s
2025-10-10 08:40:25.464137 | controller | ci_setup : Create completion file --------------------------------------- 0.26s
2025-10-10 08:40:25.464142 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.23s
2025-10-10 08:40:25.464152 | controller | INFO     Running default > converge
2025-10-10 08:40:25.891925 | controller |
2025-10-10 08:40:25.892143 | controller | PLAY [Converge] ****************************************************************
2025-10-10 08:40:25.892310 | controller |
2025-10-10 08:40:25.892454 | controller | TASK [Gathering Facts] *********************************************************
2025-10-10 08:40:25.892606 | controller | Friday 10 October 2025  08:40:25 +0000 (0:00:00.013)       0:00:00.013 ********
2025-10-10 08:40:26.675638 | controller | ok: [instance]
2025-10-10 08:40:26.699709 | controller |
2025-10-10 08:40:26.699734 | controller | TASK [Including the hive role] *************************************************
2025-10-10 08:40:26.699745 | controller | Friday 10 October 2025  08:40:26 +0000 (0:00:00.783)       0:00:00.797 ********
2025-10-10 08:40:26.699755 | controller |
2025-10-10 08:40:26.719977 | controller | TASK [hive : Check for supported flows] ****************************************
2025-10-10 08:40:26.720003 | controller | Friday 10 October 2025  08:40:26 +0000 (0:00:00.024)       0:00:00.821 ********
2025-10-10 08:40:26.720017 | controller | ok: [instance] => changed=false
2025-10-10 08:40:26.720171 | controller |   msg: All assertions passed
2025-10-10 08:40:26.720186 | controller |
2025-10-10 08:40:26.720195 | controller | TASK [hive : Check for required resources] *************************************
2025-10-10 08:40:26.743947 | controller | Friday 10 October 2025  08:40:26 +0000 (0:00:00.020)       0:00:00.842 ********
2025-10-10 08:40:26.743970 | controller | ok: [instance] => changed=false
2025-10-10 08:40:26.744340 | controller |   msg: All assertions passed
2025-10-10 08:40:27.029350 | controller |
2025-10-10 08:40:27.029380 | controller | TASK [hive : Ensure hive output directory exists] ******************************
2025-10-10 08:40:27.029389 | controller | Friday 10 October 2025  08:40:26 +0000 (0:00:00.024)       0:00:00.866 ********
2025-10-10 08:40:27.029399 | controller | ok: [instance]
2025-10-10 08:40:27.058987 | controller |
2025-10-10 08:40:27.059026 | controller | TASK [hive : Performing openstack claim_cluster] *******************************
2025-10-10 08:40:27.059036 | controller | Friday 10 October 2025  08:40:27 +0000 (0:00:00.284)       0:00:01.151 ********
2025-10-10 08:40:27.059048 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/hive/tasks/openstack_claim_cluster.yml for instance
2025-10-10 08:40:27.059070 | controller |
2025-10-10 08:40:27.059267 | controller | TASK [hive : Check if ClusterPool exists] **************************************
2025-10-10 08:40:27.086689 | controller | Friday 10 October 2025  08:40:27 +0000 (0:00:00.029)       0:00:01.181 ********
2025-10-10 08:40:27.086720 | controller | skipping: [instance]
2025-10-10 08:40:27.646522 | controller |
2025-10-10 08:40:27.646552 | controller | TASK [hive : Rendering the cluster_claim] **************************************
2025-10-10 08:40:27.646560 | controller | Friday 10 October 2025  08:40:27 +0000 (0:00:00.027)       0:00:01.208 ********
2025-10-10 08:40:27.646570 | controller | changed: [instance]
2025-10-10 08:40:27.671392 | controller |
2025-10-10 08:40:27.671422 | controller | TASK [hive : Create / modify the openshift resource] ***************************
2025-10-10 08:40:27.671430 | controller | Friday 10 October 2025  08:40:27 +0000 (0:00:00.559)       0:00:01.768 ********
2025-10-10 08:40:27.671440 | controller | skipping: [instance]
2025-10-10 08:40:27.697413 | controller |
2025-10-10 08:40:27.697438 | controller | TASK [hive : Wait for the claim to be successful] ******************************
2025-10-10 08:40:27.697445 | controller | Friday 10 October 2025  08:40:27 +0000 (0:00:00.024)       0:00:01.793 ********
2025-10-10 08:40:27.697454 | controller | skipping: [instance]
2025-10-10 08:40:27.722979 | controller |
2025-10-10 08:40:27.723003 | controller | TASK [hive : Gathering the namespace of created cluster claim] *****************
2025-10-10 08:40:27.723012 | controller | Friday 10 October 2025  08:40:27 +0000 (0:00:00.026)       0:00:01.819 ********
2025-10-10 08:40:27.723023 | controller | skipping: [instance]
2025-10-10 08:40:27.752968 | controller |
2025-10-10 08:40:27.753002 | controller | TASK [hive : Gather the kube config reference] *********************************
2025-10-10 08:40:27.753010 | controller | Friday 10 October 2025  08:40:27 +0000 (0:00:00.025)       0:00:01.845 ********
2025-10-10 08:40:27.753021 | controller | skipping: [instance]
2025-10-10 08:40:27.753220 | controller |
2025-10-10 08:40:27.753248 | controller | TASK [hive : Extracting the kubeconfig to /home/zuul/ci-framework-data] ********
2025-10-10 08:40:27.779671 | controller | Friday 10 October 2025  08:40:27 +0000 (0:00:00.030)       0:00:01.875 ********
2025-10-10 08:40:27.779701 | controller | skipping: [instance]
2025-10-10 08:40:27.779873 | controller |
2025-10-10 08:40:27.780086 | controller | TASK [hive : Collect the reference name of adminPassword] **********************
2025-10-10 08:40:27.780282 | controller | Friday 10 October 2025  08:40:27 +0000 (0:00:00.026)       0:00:01.902 ********
2025-10-10 08:40:27.805545 | controller | skipping: [instance]
2025-10-10 08:40:27.805691 | controller |
2025-10-10 08:40:27.805841 | controller | TASK [hive : Gather the username to be used for authentication against the deployed cluster] ***
2025-10-10 08:40:27.806005 | controller | Friday 10 October 2025  08:40:27 +0000 (0:00:00.025)       0:00:01.928 ********
2025-10-10 08:40:27.831702 | controller | skipping: [instance]
2025-10-10 08:40:27.831912 | controller |
2025-10-10 08:40:27.832124 | controller | TASK [hive : Gather the credentials to be used for authentication against the deployed cluster] ***
2025-10-10 08:40:27.832318 | controller | Friday 10 October 2025  08:40:27 +0000 (0:00:00.026)       0:00:01.954 ********
2025-10-10 08:40:27.857677 | controller | skipping: [instance]
2025-10-10 08:40:27.857834 | controller |
2025-10-10 08:40:27.858008 | controller | TASK [hive : Retrieve the webconsole URI] **************************************
2025-10-10 08:40:27.858161 | controller | Friday 10 October 2025  08:40:27 +0000 (0:00:00.026)       0:00:01.980 ********
2025-10-10 08:40:27.883991 | controller | skipping: [instance]
2025-10-10 08:40:27.884192 | controller |
2025-10-10 08:40:27.884392 | controller | TASK [hive : Defining openshift variables] *************************************
2025-10-10 08:40:27.884565 | controller | Friday 10 October 2025  08:40:27 +0000 (0:00:00.026)       0:00:02.006 ********
2025-10-10 08:40:27.913905 | controller | skipping: [instance]
2025-10-10 08:40:27.914115 | controller |
2025-10-10 08:40:27.914312 | controller | TASK [Gather template file] ****************************************************
2025-10-10 08:40:27.914486 | controller | Friday 10 October 2025  08:40:27 +0000 (0:00:00.029)       0:00:02.036 ********
2025-10-10 08:40:28.104481 | controller | ok: [instance]
2025-10-10 08:40:28.123691 | controller |
2025-10-10 08:40:28.123719 | controller | TASK [Check template file exists] **********************************************
2025-10-10 08:40:28.123728 | controller | Friday 10 October 2025  08:40:28 +0000 (0:00:00.190)       0:00:02.226 ********
2025-10-10 08:40:28.123738 | controller | ok: [instance] => changed=false
2025-10-10 08:40:28.304403 | controller |   msg: All assertions passed
2025-10-10 08:40:28.304434 | controller |
2025-10-10 08:40:28.304442 | controller | TASK [Create an empty file for testing] ****************************************
2025-10-10 08:40:28.304448 | controller | Friday 10 October 2025  08:40:28 +0000 (0:00:00.019)       0:00:02.245 ********
2025-10-10 08:40:28.304458 | controller | changed: [instance]
2025-10-10 08:40:28.658981 | controller |
2025-10-10 08:40:28.659015 | controller | TASK [Testing requirements - root ecdsa ssh key] *******************************
2025-10-10 08:40:28.659023 | controller | Friday 10 October 2025  08:40:28 +0000 (0:00:00.180)       0:00:02.426 ********
2025-10-10 08:40:28.659034 | controller | changed: [instance]
2025-10-10 08:40:28.659053 | controller |
2025-10-10 08:40:28.659224 | controller | TASK [Including the role with right values] ************************************
2025-10-10 08:40:28.684290 | controller | Friday 10 October 2025  08:40:28 +0000 (0:00:00.354)       0:00:02.781 ********
2025-10-10 08:40:28.684321 | controller |
2025-10-10 08:40:28.705413 | controller | TASK [hive : Check for supported flows] ****************************************
2025-10-10 08:40:28.705436 | controller | Friday 10 October 2025  08:40:28 +0000 (0:00:00.025)       0:00:02.806 ********
2025-10-10 08:40:28.705447 | controller | ok: [instance] => changed=false
2025-10-10 08:40:28.730680 | controller |   msg: All assertions passed
2025-10-10 08:40:28.730706 | controller |
2025-10-10 08:40:28.730713 | controller | TASK [hive : Check for required resources] *************************************
2025-10-10 08:40:28.730719 | controller | Friday 10 October 2025  08:40:28 +0000 (0:00:00.021)       0:00:02.827 ********
2025-10-10 08:40:28.730729 | controller | ok: [instance] => changed=false
2025-10-10 08:40:28.910369 | controller |   msg: All assertions passed
2025-10-10 08:40:28.910399 | controller |
2025-10-10 08:40:28.910407 | controller | TASK [hive : Ensure hive output directory exists] ******************************
2025-10-10 08:40:28.910413 | controller | Friday 10 October 2025  08:40:28 +0000 (0:00:00.025)       0:00:02.852 ********
2025-10-10 08:40:28.910422 | controller | ok: [instance]
2025-10-10 08:40:28.936306 | controller |
2025-10-10 08:40:28.936332 | controller | TASK [hive : Performing baremetal deploy_cluster] ******************************
2025-10-10 08:40:28.936340 | controller | Friday 10 October 2025  08:40:28 +0000 (0:00:00.179)       0:00:03.032 ********
2025-10-10 08:40:28.936354 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/hive/tasks/baremetal_deploy_cluster.yml for instance
2025-10-10 08:40:28.969356 | controller |
2025-10-10 08:40:28.969384 | controller | TASK [hive : Get variables and set defaults only for unit testing.] ************
2025-10-10 08:40:28.969392 | controller | Friday 10 October 2025  08:40:28 +0000 (0:00:00.026)       0:00:03.058 ********
2025-10-10 08:40:28.969402 | controller | ok: [instance]
2025-10-10 08:40:28.992697 | controller |
2025-10-10 08:40:28.992721 | controller | TASK [hive : Create a secret resource for provisioner node access] *************
2025-10-10 08:40:28.992729 | controller | Friday 10 October 2025  08:40:28 +0000 (0:00:00.032)       0:00:03.091 ********
2025-10-10 08:40:28.992739 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/hive/tasks/oc_apply.yml for instance
2025-10-10 08:40:29.328002 | controller |
2025-10-10 08:40:29.328032 | controller | TASK [hive : Rendering the Provisioner SSH private key secret] *****************
2025-10-10 08:40:29.328040 | controller | Friday 10 October 2025  08:40:28 +0000 (0:00:00.023)       0:00:03.114 ********
2025-10-10 08:40:29.328051 | controller | changed: [instance]
2025-10-10 08:40:29.353493 | controller |
2025-10-10 08:40:29.353519 | controller | TASK [hive : Create / modify the openshift resource] ***************************
2025-10-10 08:40:29.353527 | controller | Friday 10 October 2025  08:40:29 +0000 (0:00:00.335)       0:00:03.450 ********
2025-10-10 08:40:29.353536 | controller | skipping: [instance]
2025-10-10 08:40:29.371978 | controller |
2025-10-10 08:40:29.372003 | controller | TASK [hive : Load baremetal install config] ************************************
2025-10-10 08:40:29.372011 | controller | Friday 10 October 2025  08:40:29 +0000 (0:00:00.025)       0:00:03.475 ********
2025-10-10 08:40:29.372020 | controller | ok: [instance]
2025-10-10 08:40:29.372207 | controller |
2025-10-10 08:40:29.372229 | controller | TASK [hive : Add dynamic values into the bm install config] ********************
2025-10-10 08:40:29.415782 | controller | Friday 10 October 2025  08:40:29 +0000 (0:00:00.018)       0:00:03.494 ********
2025-10-10 08:40:29.415812 | controller | ok: [instance] => (item={'metadata': {'name': 'unittest-01'}})
2025-10-10 08:40:29.451354 | controller | ok: [instance] => (item={'platform': {'baremetal': {'libvirtURI': 'qemu+ssh://kni@127.0.10.10/system'}}})
2025-10-10 08:40:29.451381 | controller | ok: [instance] => (item={'platform': {'baremetal': {'externalMACAddress': 'aa:ff:ee'}}})
2025-10-10 08:40:29.451389 | controller | ok: [instance] => (item={'platform': {'baremetal': {'externalBridge': 'baremetal'}}})
2025-10-10 08:40:29.451395 | controller |
2025-10-10 08:40:29.451400 | controller | TASK [hive : Add provisioning bridge name to install config] *******************
2025-10-10 08:40:29.451406 | controller | Friday 10 October 2025  08:40:29 +0000 (0:00:00.043)       0:00:03.537 ********
2025-10-10 08:40:29.451415 | controller | ok: [instance]
2025-10-10 08:40:29.468867 | controller |
2025-10-10 08:40:29.468917 | controller | TASK [hive : Gather the base domain of the OCP cluster] ************************
2025-10-10 08:40:29.468926 | controller | Friday 10 October 2025  08:40:29 +0000 (0:00:00.035)       0:00:03.573 ********
2025-10-10 08:40:29.468937 | controller | ok: [instance]
2025-10-10 08:40:29.494539 | controller |
2025-10-10 08:40:29.494569 | controller | TASK [hive : Create a secret resource holding baremetal install config] ********
2025-10-10 08:40:29.494577 | controller | Friday 10 October 2025  08:40:29 +0000 (0:00:00.017)       0:00:03.590 ********
2025-10-10 08:40:29.494587 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/hive/tasks/oc_apply.yml for instance
2025-10-10 08:40:29.826346 | controller |
2025-10-10 08:40:29.826376 | controller | TASK [hive : Rendering the install config secret] ******************************
2025-10-10 08:40:29.826385 | controller | Friday 10 October 2025  08:40:29 +0000 (0:00:00.025)       0:00:03.616 ********
2025-10-10 08:40:29.826395 | controller | changed: [instance]
2025-10-10 08:40:29.850671 | controller |
2025-10-10 08:40:29.850697 | controller | TASK [hive : Create / modify the openshift resource] ***************************
2025-10-10 08:40:29.850710 | controller | Friday 10 October 2025  08:40:29 +0000 (0:00:00.331)       0:00:03.948 ********
2025-10-10 08:40:29.850721 | controller | skipping: [instance]
2025-10-10 08:40:29.875437 | controller |
2025-10-10 08:40:29.875459 | controller | TASK [hive : Create a cluster image set resource] ******************************
2025-10-10 08:40:29.875473 | controller | Friday 10 October 2025  08:40:29 +0000 (0:00:00.024)       0:00:03.972 ********
2025-10-10 08:40:29.875483 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/hive/tasks/oc_apply.yml for instance
2025-10-10 08:40:30.199358 | controller |
2025-10-10 08:40:30.199387 | controller | TASK [hive : Rendering the ocp cluster image] **********************************
2025-10-10 08:40:30.199395 | controller | Friday 10 October 2025  08:40:29 +0000 (0:00:00.024)       0:00:03.997 ********
2025-10-10 08:40:30.199405 | controller | changed: [instance]
2025-10-10 08:40:30.224457 | controller |
2025-10-10 08:40:30.224484 | controller | TASK [hive : Create / modify the openshift resource] ***************************
2025-10-10 08:40:30.224491 | controller | Friday 10 October 2025  08:40:30 +0000 (0:00:00.323)       0:00:04.321 ********
2025-10-10 08:40:30.224501 | controller | skipping: [instance]
2025-10-10 08:40:30.250967 | controller |
2025-10-10 08:40:30.250994 | controller | TASK [hive : Initiate OCP deployment on baremetal] *****************************
2025-10-10 08:40:30.251003 | controller | Friday 10 October 2025  08:40:30 +0000 (0:00:00.025)       0:00:04.346 ********
2025-10-10 08:40:30.251013 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/hive/tasks/oc_apply.yml for instance
2025-10-10 08:40:30.251037 | controller |
2025-10-10 08:40:30.251272 | controller | TASK [hive : Rendering the OCP cluster deployment] *****************************
2025-10-10 08:40:30.591367 | controller | Friday 10 October 2025  08:40:30 +0000 (0:00:00.026)       0:00:04.373 ********
2025-10-10 08:40:30.591401 | controller | changed: [instance]
2025-10-10 08:40:30.617691 | controller |
2025-10-10 08:40:30.617725 | controller | TASK [hive : Create / modify the openshift resource] ***************************
2025-10-10 08:40:30.617733 | controller | Friday 10 October 2025  08:40:30 +0000 (0:00:00.340)       0:00:04.713 ********
2025-10-10 08:40:30.617743 | controller | skipping: [instance]
2025-10-10 08:40:30.643514 | controller |
2025-10-10 08:40:30.643546 | controller | TASK [hive : Wait until OCP cluster is deployed and configured] ****************
2025-10-10 08:40:30.643555 | controller | Friday 10 October 2025  08:40:30 +0000 (0:00:00.026)       0:00:04.739 ********
2025-10-10 08:40:30.643565 | controller | skipping: [instance]
2025-10-10 08:40:30.673330 | controller |
2025-10-10 08:40:30.673359 | controller | TASK [hive : Gather and set OCP access information] ****************************
2025-10-10 08:40:30.673368 | controller | Friday 10 October 2025  08:40:30 +0000 (0:00:00.025)       0:00:04.765 ********
2025-10-10 08:40:30.673378 | controller | skipping: [instance]
2025-10-10 08:40:31.390964 | controller |
2025-10-10 08:40:31.390994 | controller | TASK [Gathering the file informaiton that is expected to be generated] *********
2025-10-10 08:40:31.391007 | controller | Friday 10 October 2025  08:40:30 +0000 (0:00:00.029)       0:00:04.795 ********
2025-10-10 08:40:31.391018 | controller | ok: [instance] => (item=ocp_image.yml)
2025-10-10 08:40:31.436431 | controller | ok: [instance] => (item=ocp_ssh_private_key.yml)
2025-10-10 08:40:31.436461 | controller | ok: [instance] => (item=bm_install_config.yml)
2025-10-10 08:40:31.436469 | controller | ok: [instance] => (item=bm_deploy_config.yml)
2025-10-10 08:40:31.436475 | controller |
2025-10-10 08:40:31.436481 | controller | TASK [Verify the file stat] ****************************************************
2025-10-10 08:40:31.436487 | controller | Friday 10 October 2025  08:40:31 +0000 (0:00:00.717)       0:00:05.512 ********
2025-10-10 08:40:31.436498 | controller | ok: [instance] => (item={'changed': False, 'stat': {'exists': True, 'path': '/home/zuul/ci-framework-data/ocp_image.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 180, 'inode': 125847058, 'dev': 64513, 'nlink': 1, 'atime': 1760085630.1659844, 'mtime': 1760085630.033984, 'ctime': 1760085630.1679845, 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False, 'blocks': 8, 'block_size': 4096, 'device_type': 0, 'readable': True, 'writeable': True, 'executable': False, 'pw_name': 'zuul', 'gr_name': 'zuul', 'checksum': '167ace007f3ff7586cfd41baf22998bbec37802a', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '1620544295', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/zuul/ci-framework-data/ocp_image.yml', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': 'ocp_image.yml', 'ansible_loop_var': 'item'}) => changed=false
2025-10-10 08:40:31.437941 | controller |   ansible_loop_var: item
2025-10-10 08:40:31.437962 | controller |   item:
2025-10-10 08:40:31.437969 | controller |     ansible_loop_var: item
2025-10-10 08:40:31.437979 | controller |     changed: false
2025-10-10 08:40:31.438000 | controller |     failed: false
2025-10-10 08:40:31.438007 | controller |     invocation:
2025-10-10 08:40:31.438012 | controller |       module_args:
2025-10-10 08:40:31.438017 | controller |         checksum_algorithm: sha1
2025-10-10 08:40:31.438023 | controller |         follow: false
2025-10-10 08:40:31.438028 | controller |         get_attributes: true
2025-10-10 08:40:31.438033 | controller |         get_checksum: true
2025-10-10 08:40:31.438039 | controller |         get_md5: false
2025-10-10 08:40:31.438044 | controller |         get_mime: true
2025-10-10 08:40:31.438049 | controller |         path: /home/zuul/ci-framework-data/ocp_image.yml
2025-10-10 08:40:31.438054 | controller |     item: ocp_image.yml
2025-10-10 08:40:31.438060 | controller |     stat:
2025-10-10 08:40:31.438065 | controller |       atime: 1760085630.1659844
2025-10-10 08:40:31.438071 | controller |       attr_flags: ''
2025-10-10 08:40:31.438076 | controller |       attributes: []
2025-10-10 08:40:31.438082 | controller |       block_size: 4096
2025-10-10 08:40:31.438101 | controller |       blocks: 8
2025-10-10 08:40:31.438979 | controller |       charset: us-ascii
2025-10-10 08:40:31.439040 | controller |       checksum: 167ace007f3ff7586cfd41baf22998bbec37802a
2025-10-10 08:40:31.439050 | controller |       ctime: 1760085630.1679845
2025-10-10 08:40:31.439057 | controller |       dev: 64513
2025-10-10 08:40:31.439063 | controller |       device_type: 0
2025-10-10 08:40:31.439068 | controller |       executable: false
2025-10-10 08:40:31.439073 | controller |       exists: true
2025-10-10 08:40:31.439079 | controller |       gid: 1000
2025-10-10 08:40:31.439084 | controller |       gr_name: zuul
2025-10-10 08:40:31.439092 | controller |       inode: 125847058
2025-10-10 08:40:31.439164 | controller |       isblk: false
2025-10-10 08:40:31.439295 | controller |       ischr: false
2025-10-10 08:40:31.439410 | controller |       isdir: false
2025-10-10 08:40:31.439524 | controller |       isfifo: false
2025-10-10 08:40:31.439630 | controller |       isgid: false
2025-10-10 08:40:31.439744 | controller |       islnk: false
2025-10-10 08:40:31.439855 | controller |       isreg: true
2025-10-10 08:40:31.439987 | controller |       issock: false
2025-10-10 08:40:31.440105 | controller |       isuid: false
2025-10-10 08:40:31.440215 | controller |       mimetype: text/plain
2025-10-10 08:40:31.440338 | controller |       mode: '0644'
2025-10-10 08:40:31.440448 | controller |       mtime: 1760085630.033984
2025-10-10 08:40:31.440558 | controller |       nlink: 1
2025-10-10 08:40:31.440678 | controller |       path: /home/zuul/ci-framework-data/ocp_image.yml
2025-10-10 08:40:31.440788 | controller |       pw_name: zuul
2025-10-10 08:40:31.440912 | controller |       readable: true
2025-10-10 08:40:31.441037 | controller |       rgrp: true
2025-10-10 08:40:31.441148 | controller |       roth: true
2025-10-10 08:40:31.441267 | controller |       rusr: true
2025-10-10 08:40:31.441378 | controller |       size: 180
2025-10-10 08:40:31.441485 | controller |       uid: 1000
2025-10-10 08:40:31.441594 | controller |       version: '1620544295'
2025-10-10 08:40:31.441701 | controller |       wgrp: false
2025-10-10 08:40:31.441810 | controller |       woth: false
2025-10-10 08:40:31.441935 | controller |       writeable: true
2025-10-10 08:40:31.442050 | controller |       wusr: true
2025-10-10 08:40:31.442163 | controller |       xgrp: false
2025-10-10 08:40:31.442293 | controller |       xoth: false
2025-10-10 08:40:31.442403 | controller |       xusr: false
2025-10-10 08:40:31.442511 | controller |   msg: All assertions passed
2025-10-10 08:40:31.442656 | controller | ok: [instance] => (item={'changed': False, 'stat': {'exists': True, 'path': '/home/zuul/ci-framework-data/ocp_ssh_private_key.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 665, 'inode': 92289688, 'dev': 64513, 'nlink': 1, 'atime': 1760085629.294981, 'mtime': 1760085629.1619806, 'ctime': 1760085629.297981, 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False, 'blocks': 8, 'block_size': 4096, 'device_type': 0, 'readable': True, 'writeable': True, 'executable': False, 'pw_name': 'zuul', 'gr_name': 'zuul', 'checksum': 'c09a08ae2d3971c344358c7dd41a4c72f985b00f', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '3102559761', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/zuul/ci-framework-data/ocp_ssh_private_key.yml', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': 'ocp_ssh_private_key.yml', 'ansible_loop_var': 'item'}) => changed=false
2025-10-10 08:40:31.442780 | controller |   ansible_loop_var: item
2025-10-10 08:40:31.442913 | controller |   item:
2025-10-10 08:40:31.443028 | controller |     ansible_loop_var: item
2025-10-10 08:40:31.443139 | controller |     changed: false
2025-10-10 08:40:31.443259 | controller |     failed: false
2025-10-10 08:40:31.443375 | controller |     invocation:
2025-10-10 08:40:31.443484 | controller |       module_args:
2025-10-10 08:40:31.443604 | controller |         checksum_algorithm: sha1
2025-10-10 08:40:31.443711 | controller |         follow: false
2025-10-10 08:40:31.443818 | controller |         get_attributes: true
2025-10-10 08:40:31.443961 | controller |         get_checksum: true
2025-10-10 08:40:31.444078 | controller |         get_md5: false
2025-10-10 08:40:31.444186 | controller |         get_mime: true
2025-10-10 08:40:31.444312 | controller |         path: /home/zuul/ci-framework-data/ocp_ssh_private_key.yml
2025-10-10 08:40:31.444432 | controller |     item: ocp_ssh_private_key.yml
2025-10-10 08:40:31.444577 | controller |     stat:
2025-10-10 08:40:31.444712 | controller |       atime: 1760085629.294981
2025-10-10 08:40:31.444847 | controller |       attr_flags: ''
2025-10-10 08:40:31.445003 | controller |       attributes: []
2025-10-10 08:40:31.445148 | controller |       block_size: 4096
2025-10-10 08:40:31.445296 | controller |       blocks: 8
2025-10-10 08:40:31.445429 | controller |       charset: us-ascii
2025-10-10 08:40:31.445559 | controller |       checksum: c09a08ae2d3971c344358c7dd41a4c72f985b00f
2025-10-10 08:40:31.445690 | controller |       ctime: 1760085629.297981
2025-10-10 08:40:31.445819 | controller |       dev: 64513
2025-10-10 08:40:31.445979 | controller |       device_type: 0
2025-10-10 08:40:31.446119 | controller |       executable: false
2025-10-10 08:40:31.446261 | controller |       exists: true
2025-10-10 08:40:31.446398 | controller |       gid: 1000
2025-10-10 08:40:31.446527 | controller |       gr_name: zuul
2025-10-10 08:40:31.446656 | controller |       inode: 92289688
2025-10-10 08:40:31.446783 | controller |       isblk: false
2025-10-10 08:40:31.446934 | controller |       ischr: false
2025-10-10 08:40:31.447071 | controller |       isdir: false
2025-10-10 08:40:31.447202 | controller |       isfifo: false
2025-10-10 08:40:31.447344 | controller |       isgid: false
2025-10-10 08:40:31.447473 | controller |       islnk: false
2025-10-10 08:40:31.447602 | controller |       isreg: true
2025-10-10 08:40:31.447737 | controller |       issock: false
2025-10-10 08:40:31.447868 | controller |       isuid: false
2025-10-10 08:40:31.448024 | controller |       mimetype: text/plain
2025-10-10 08:40:31.448162 | controller |       mode: '0644'
2025-10-10 08:40:31.448316 | controller |       mtime: 1760085629.1619806
2025-10-10 08:40:31.448448 | controller |       nlink: 1
2025-10-10 08:40:31.448579 | controller |       path: /home/zuul/ci-framework-data/ocp_ssh_private_key.yml
2025-10-10 08:40:31.448708 | controller |       pw_name: zuul
2025-10-10 08:40:31.448837 | controller |       readable: true
2025-10-10 08:40:31.448991 | controller |       rgrp: true
2025-10-10 08:40:31.449127 | controller |       roth: true
2025-10-10 08:40:31.449266 | controller |       rusr: true
2025-10-10 08:40:31.449399 | controller |       size: 665
2025-10-10 08:40:31.449526 | controller |       uid: 1000
2025-10-10 08:40:31.449660 | controller |       version: '3102559761'
2025-10-10 08:40:31.449790 | controller |       wgrp: false
2025-10-10 08:40:31.449941 | controller |       woth: false
2025-10-10 08:40:31.450076 | controller |       writeable: true
2025-10-10 08:40:31.450205 | controller |       wusr: true
2025-10-10 08:40:31.450406 | controller |       xgrp: false
2025-10-10 08:40:31.450541 | controller |       xoth: false
2025-10-10 08:40:31.450671 | controller |       xusr: false
2025-10-10 08:40:31.450799 | controller |   msg: All assertions passed
2025-10-10 08:40:31.450988 | controller | ok: [instance] => (item={'changed': False, 'stat': {'exists': True, 'path': '/home/zuul/ci-framework-data/bm_install_config.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 1031, 'inode': 109086334, 'dev': 64513, 'nlink': 1, 'atime': 1760085629.794983, 'mtime': 1760085629.6649826, 'ctime': 1760085629.797983, 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False, 'blocks': 8, 'block_size': 4096, 'device_type': 0, 'readable': True, 'writeable': True, 'executable': False, 'pw_name': 'zuul', 'gr_name': 'zuul', 'checksum': 'f7baf1c62770001d784c6ac557b40ca055bbbbc9', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '1745183525', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/zuul/ci-framework-data/bm_install_config.yml', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': 'bm_install_config.yml', 'ansible_loop_var': 'item'}) => changed=false
2025-10-10 08:40:31.451143 | controller |   ansible_loop_var: item
2025-10-10 08:40:31.451296 | controller |   item:
2025-10-10 08:40:31.451431 | controller |     ansible_loop_var: item
2025-10-10 08:40:31.451562 | controller |     changed: false
2025-10-10 08:40:31.451712 | controller |     failed: false
2025-10-10 08:40:31.451841 | controller |     invocation:
2025-10-10 08:40:31.451995 | controller |       module_args:
2025-10-10 08:40:31.452133 | controller |         checksum_algorithm: sha1
2025-10-10 08:40:31.452283 | controller |         follow: false
2025-10-10 08:40:31.452416 | controller |         get_attributes: true
2025-10-10 08:40:31.452544 | controller |         get_checksum: true
2025-10-10 08:40:31.452672 | controller |         get_md5: false
2025-10-10 08:40:31.452798 | controller |         get_mime: true
2025-10-10 08:40:31.452952 | controller |         path: /home/zuul/ci-framework-data/bm_install_config.yml
2025-10-10 08:40:31.453090 | controller |     item: bm_install_config.yml
2025-10-10 08:40:31.453218 | controller |     stat:
2025-10-10 08:40:31.453363 | controller |       atime: 1760085629.794983
2025-10-10 08:40:31.453493 | controller |       attr_flags: ''
2025-10-10 08:40:31.453629 | controller |       attributes: []
2025-10-10 08:40:31.453775 | controller |       block_size: 4096
2025-10-10 08:40:31.453928 | controller |       blocks: 8
2025-10-10 08:40:31.454067 | controller |       charset: us-ascii
2025-10-10 08:40:31.454203 | controller |       checksum: f7baf1c62770001d784c6ac557b40ca055bbbbc9
2025-10-10 08:40:31.454349 | controller |       ctime: 1760085629.797983
2025-10-10 08:40:31.454476 | controller |       dev: 64513
2025-10-10 08:40:31.454607 | controller |       device_type: 0
2025-10-10 08:40:31.454745 | controller |       executable: false
2025-10-10 08:40:31.454875 | controller |       exists: true
2025-10-10 08:40:31.455040 | controller |       gid: 1000
2025-10-10 08:40:31.455180 | controller |       gr_name: zuul
2025-10-10 08:40:31.455327 | controller |       inode: 109086334
2025-10-10 08:40:31.455460 | controller |       isblk: false
2025-10-10 08:40:31.455588 | controller |       ischr: false
2025-10-10 08:40:31.455718 | controller |       isdir: false
2025-10-10 08:40:31.455860 | controller |       isfifo: false
2025-10-10 08:40:31.456012 | controller |       isgid: false
2025-10-10 08:40:31.456147 | controller |       islnk: false
2025-10-10 08:40:31.456288 | controller |       isreg: true
2025-10-10 08:40:31.456419 | controller |       issock: false
2025-10-10 08:40:31.456546 | controller |       isuid: false
2025-10-10 08:40:31.456682 | controller |       mimetype: text/plain
2025-10-10 08:40:31.456809 | controller |       mode: '0644'
2025-10-10 08:40:31.456968 | controller |       mtime: 1760085629.6649826
2025-10-10 08:40:31.457104 | controller |       nlink: 1
2025-10-10 08:40:31.457299 | controller |       path: /home/zuul/ci-framework-data/bm_install_config.yml
2025-10-10 08:40:31.457434 | controller |       pw_name: zuul
2025-10-10 08:40:31.457579 | controller |       readable: true
2025-10-10 08:40:31.457708 | controller |       rgrp: true
2025-10-10 08:40:31.457835 | controller |       roth: true
2025-10-10 08:40:31.457985 | controller |       rusr: true
2025-10-10 08:40:31.458119 | controller |       size: 1031
2025-10-10 08:40:31.458263 | controller |       uid: 1000
2025-10-10 08:40:31.458410 | controller |       version: '1745183525'
2025-10-10 08:40:31.458551 | controller |       wgrp: false
2025-10-10 08:40:31.458684 | controller |       woth: false
2025-10-10 08:40:31.458817 | controller |       writeable: true
2025-10-10 08:40:31.458969 | controller |       wusr: true
2025-10-10 08:40:31.459107 | controller |       xgrp: false
2025-10-10 08:40:31.459256 | controller |       xoth: false
2025-10-10 08:40:31.459397 | controller |       xusr: false
2025-10-10 08:40:31.459528 | controller |   msg: All assertions passed
2025-10-10 08:40:31.459771 | controller | ok: [instance] => (item={'changed': False, 'stat': {'exists': True, 'path': '/home/zuul/ci-framework-data/bm_deploy_config.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 638, 'inode': 142645523, 'dev': 64513, 'nlink': 1, 'atime': 1760085630.557986, 'mtime': 1760085630.4229856, 'ctime': 1760085630.560986, 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False, 'blocks': 8, 'block_size': 4096, 'device_type': 0, 'readable': True, 'writeable': True, 'executable': False, 'pw_name': 'zuul', 'gr_name': 'zuul', 'checksum': '8df1ae1447a9970a781cdc66545a8f7b66ba228b', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '3274661339', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/zuul/ci-framework-data/bm_deploy_config.yml', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': 'bm_deploy_config.yml', 'ansible_loop_var': 'item'}) => changed=false
2025-10-10 08:40:31.459934 | controller |   ansible_loop_var: item
2025-10-10 08:40:31.460076 | controller |   item:
2025-10-10 08:40:31.460207 | controller |     ansible_loop_var: item
2025-10-10 08:40:31.460350 | controller |     changed: false
2025-10-10 08:40:31.460481 | controller |     failed: false
2025-10-10 08:40:31.460609 | controller |     invocation:
2025-10-10 08:40:31.460738 | controller |       module_args:
2025-10-10 08:40:31.460868 | controller |         checksum_algorithm: sha1
2025-10-10 08:40:31.461022 | controller |         follow: false
2025-10-10 08:40:31.461159 | controller |         get_attributes: true
2025-10-10 08:40:31.461305 | controller |         get_checksum: true
2025-10-10 08:40:31.461441 | controller |         get_md5: false
2025-10-10 08:40:31.461570 | controller |         get_mime: true
2025-10-10 08:40:31.461700 | controller |         path: /home/zuul/ci-framework-data/bm_deploy_config.yml
2025-10-10 08:40:31.461829 | controller |     item: bm_deploy_config.yml
2025-10-10 08:40:31.461981 | controller |     stat:
2025-10-10 08:40:31.462117 | controller |       atime: 1760085630.557986
2025-10-10 08:40:31.462257 | controller |       attr_flags: ''
2025-10-10 08:40:31.462393 | controller |       attributes: []
2025-10-10 08:40:31.462523 | controller |       block_size: 4096
2025-10-10 08:40:31.462654 | controller |       blocks: 8
2025-10-10 08:40:31.462784 | controller |       charset: us-ascii
2025-10-10 08:40:31.462934 | controller |       checksum: 8df1ae1447a9970a781cdc66545a8f7b66ba228b
2025-10-10 08:40:31.463071 | controller |       ctime: 1760085630.560986
2025-10-10 08:40:31.463199 | controller |       dev: 64513
2025-10-10 08:40:31.463339 | controller |       device_type: 0
2025-10-10 08:40:31.463475 | controller |       executable: false
2025-10-10 08:40:31.463602 | controller |       exists: true
2025-10-10 08:40:31.463728 | controller |       gid: 1000
2025-10-10 08:40:31.463865 | controller |       gr_name: zuul
2025-10-10 08:40:31.464015 | controller |       inode: 142645523
2025-10-10 08:40:31.464151 | controller |       isblk: false
2025-10-10 08:40:31.464294 | controller |       ischr: false
2025-10-10 08:40:31.464423 | controller |       isdir: false
2025-10-10 08:40:31.464550 | controller |       isfifo: false
2025-10-10 08:40:31.464678 | controller |       isgid: false
2025-10-10 08:40:31.464814 | controller |       islnk: false
2025-10-10 08:40:31.464975 | controller |       isreg: true
2025-10-10 08:40:31.465115 | controller |       issock: false
2025-10-10 08:40:31.465266 | controller |       isuid: false
2025-10-10 08:40:31.465403 | controller |       mimetype: text/plain
2025-10-10 08:40:31.465532 | controller |       mode: '0644'
2025-10-10 08:40:31.465660 | controller |       mtime: 1760085630.4229856
2025-10-10 08:40:31.465788 | controller |       nlink: 1
2025-10-10 08:40:31.465941 | controller |       path: /home/zuul/ci-framework-data/bm_deploy_config.yml
2025-10-10 08:40:31.466077 | controller |       pw_name: zuul
2025-10-10 08:40:31.466204 | controller |       readable: true
2025-10-10 08:40:31.466352 | controller |       rgrp: true
2025-10-10 08:40:31.466480 | controller |       roth: true
2025-10-10 08:40:31.466605 | controller |       rusr: true
2025-10-10 08:40:31.466732 | controller |       size: 638
2025-10-10 08:40:31.466882 | controller |       uid: 1000
2025-10-10 08:40:31.467051 | controller |       version: '3274661339'
2025-10-10 08:40:31.467191 | controller |       wgrp: false
2025-10-10 08:40:31.467337 | controller |       woth: false
2025-10-10 08:40:31.467465 | controller |       writeable: true
2025-10-10 08:40:31.467593 | controller |       wusr: true
2025-10-10 08:40:31.467718 | controller |       xgrp: false
2025-10-10 08:40:31.467845 | controller |       xoth: false
2025-10-10 08:40:31.467997 | controller |       xusr: false
2025-10-10 08:40:31.468132 | controller |   msg: All assertions passed
2025-10-10 08:40:31.468267 | controller |
2025-10-10 08:40:31.468400 | controller | TASK [Verify the host access secret] *******************************************
2025-10-10 08:40:31.468541 | controller | Friday 10 October 2025  08:40:31 +0000 (0:00:00.053)       0:00:05.566 ********
2025-10-10 08:40:31.476641 | controller | ok: [instance] => changed=false
2025-10-10 08:40:31.476814 | controller |   msg: All assertions passed
2025-10-10 08:40:31.477003 | controller |
2025-10-10 08:40:31.477173 | controller | TASK [Verify the install config] ***********************************************
2025-10-10 08:40:31.477353 | controller | Friday 10 October 2025  08:40:31 +0000 (0:00:00.033)       0:00:05.599 ********
2025-10-10 08:40:31.516932 | controller | ok: [instance] => changed=false
2025-10-10 08:40:31.517084 | controller |   msg: All assertions passed
2025-10-10 08:40:31.517214 | controller |
2025-10-10 08:40:31.517368 | controller | TASK [Verify the deploy definition] ********************************************
2025-10-10 08:40:31.517504 | controller | Friday 10 October 2025  08:40:31 +0000 (0:00:00.040)       0:00:05.639 ********
2025-10-10 08:40:31.574694 | controller | ok: [instance] => changed=false
2025-10-10 08:40:31.574878 | controller |   msg: All assertions passed
2025-10-10 08:40:31.575084 | controller |
2025-10-10 08:40:31.575275 | controller | PLAY RECAP *********************************************************************
2025-10-10 08:40:31.575454 | controller | instance                   : ok=32   changed=7    unreachable=0    failed=0    skipped=17   rescued=0    ignored=0
2025-10-10 08:40:31.575594 | controller |
2025-10-10 08:40:31.575727 | controller | Friday 10 October 2025  08:40:31 +0000 (0:00:00.057)       0:00:05.697 ********
2025-10-10 08:40:31.575856 | controller | ===============================================================================
2025-10-10 08:40:31.576031 | controller | Gathering Facts --------------------------------------------------------- 0.78s
2025-10-10 08:40:31.576179 | controller | Gathering the file informaiton that is expected to be generated --------- 0.72s
2025-10-10 08:40:31.576330 | controller | hive : Rendering the cluster_claim -------------------------------------- 0.56s
2025-10-10 08:40:31.576469 | controller | Testing requirements - root ecdsa ssh key ------------------------------- 0.35s
2025-10-10 08:40:31.576604 | controller | hive : Rendering the OCP cluster deployment ----------------------------- 0.34s
2025-10-10 08:40:31.576735 | controller | hive : Rendering the Provisioner SSH private key secret ----------------- 0.34s
2025-10-10 08:40:31.576864 | controller | hive : Rendering the install config secret ------------------------------ 0.33s
2025-10-10 08:40:31.577022 | controller | hive : Rendering the ocp cluster image ---------------------------------- 0.32s
2025-10-10 08:40:31.577162 | controller | hive : Ensure hive output directory exists ------------------------------ 0.28s
2025-10-10 08:40:31.577322 | controller | Gather template file ---------------------------------------------------- 0.19s
2025-10-10 08:40:31.577458 | controller | Create an empty file for testing ---------------------------------------- 0.18s
2025-10-10 08:40:31.577597 | controller | hive : Ensure hive output directory exists ------------------------------ 0.18s
2025-10-10 08:40:31.577729 | controller | Verify the deploy definition -------------------------------------------- 0.06s
2025-10-10 08:40:31.577857 | controller | Verify the file stat ---------------------------------------------------- 0.05s
2025-10-10 08:40:31.578010 | controller | hive : Add dynamic values into the bm install config -------------------- 0.04s
2025-10-10 08:40:31.578147 | controller | Verify the install config ----------------------------------------------- 0.04s
2025-10-10 08:40:31.578296 | controller | hive : Add provisioning bridge name to install config ------------------- 0.04s
2025-10-10 08:40:31.578429 | controller | Verify the host access secret ------------------------------------------- 0.03s
2025-10-10 08:40:31.578560 | controller | hive : Get variables and set defaults only for unit testing. ------------ 0.03s
2025-10-10 08:40:31.578701 | controller | hive : Gather the kube config reference --------------------------------- 0.03s
2025-10-10 08:40:31.635743 | controller | INFO     Running default > cleanup
2025-10-10 08:40:31.636303 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-10 08:40:31.636990 | controller | INFO     Writing /tmp/report.html report.
2025-10-10 08:40:31.880899 | controller | changed
2025-10-10 08:40:31.932094 | 
2025-10-10 08:40:31.932177 | PLAY RECAP
2025-10-10 08:40:31.932220 | controller | ok: 3 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-10 08:40:31.932245 | 
2025-10-10 08:40:32.061409 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-10 08:40:32.062234 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-10 08:40:32.805999 | 
2025-10-10 08:40:32.806129 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-10 08:40:32.840218 | 
2025-10-10 08:40:32.840348 | TASK [Filter out host if needed]
2025-10-10 08:40:32.863422 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-10 08:40:32.878935 | 
2025-10-10 08:40:32.879008 | TASK [Ensure file is present]
2025-10-10 08:40:33.367551 | controller | ok
2025-10-10 08:40:33.381237 | 
2025-10-10 08:40:33.381309 | TASK [Manage molecule report file]
2025-10-10 08:40:33.919249 | controller | changed
2025-10-10 08:40:33.924730 | 
2025-10-10 08:40:33.924798 | TASK [Check if we get ci-framework-data basedir]
2025-10-10 08:40:34.155643 | controller | ok
2025-10-10 08:40:34.160959 | 
2025-10-10 08:40:34.161024 | TASK [Create ci-framework-data log directory for zuul]
2025-10-10 08:40:34.551386 | controller | changed
2025-10-10 08:40:34.561124 | 
2025-10-10 08:40:34.561201 | TASK [Copy ci-framework interesting files]
2025-10-10 08:40:34.843399 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-10 08:40:35.128937 | controller | changed
2025-10-10 08:40:35.134084 | 
2025-10-10 08:40:35.134157 | TASK [Get SELinux listing]
2025-10-10 08:40:35.679363 | controller | changed
2025-10-10 08:40:35.684881 | 
2025-10-10 08:40:35.684965 | TASK [Generate log index]
2025-10-10 08:40:36.336153 | controller | changed
2025-10-10 08:40:36.349512 | 
2025-10-10 08:40:36.349619 | TASK [Get some env related data]
2025-10-10 08:40:36.893013 | controller | /home/zuul/.local/bin/ansible
2025-10-10 08:40:37.888433 | controller | changed
2025-10-10 08:40:37.894776 | 
2025-10-10 08:40:37.894872 | TASK [Generate list of logs to collect in home directory]
2025-10-10 08:40:38.189286 | controller | ok: All paths examined
2025-10-10 08:40:38.194959 | 
2025-10-10 08:40:38.195029 | LOOP [Copy logs from home directory]
2025-10-10 08:40:38.569198 | controller | changed:
2025-10-10 08:40:38.569320 | controller | {
2025-10-10 08:40:38.569352 | controller |   "atime": 1760085512.936288,
2025-10-10 08:40:38.569373 | controller |   "ctime": 1760085529.009461,
2025-10-10 08:40:38.569391 | controller |   "dev": 64513,
2025-10-10 08:40:38.569408 | controller |   "gid": 1000,
2025-10-10 08:40:38.569424 | controller |   "gr_name": "zuul",
2025-10-10 08:40:38.569441 | controller |   "inode": 4481571,
2025-10-10 08:40:38.569457 | controller |   "isblk": false,
2025-10-10 08:40:38.569473 | controller |   "ischr": false,
2025-10-10 08:40:38.569488 | controller |   "isdir": false,
2025-10-10 08:40:38.569503 | controller |   "isfifo": false,
2025-10-10 08:40:38.569519 | controller |   "isgid": false,
2025-10-10 08:40:38.569534 | controller |   "islnk": false,
2025-10-10 08:40:38.569548 | controller |   "isreg": true,
2025-10-10 08:40:38.569563 | controller |   "issock": false,
2025-10-10 08:40:38.569577 | controller |   "isuid": false,
2025-10-10 08:40:38.569591 | controller |   "mode": "0644",
2025-10-10 08:40:38.569605 | controller |   "mtime": 1760085529.009461,
2025-10-10 08:40:38.569620 | controller |   "nlink": 1,
2025-10-10 08:40:38.569634 | controller |   "path": "/home/zuul/ansible.log",
2025-10-10 08:40:38.569650 | controller |   "pw_name": "zuul",
2025-10-10 08:40:38.569680 | controller |   "rgrp": true,
2025-10-10 08:40:38.569696 | controller |   "roth": true,
2025-10-10 08:40:38.569716 | controller |   "rusr": true,
2025-10-10 08:40:38.569733 | controller |   "size": 6749,
2025-10-10 08:40:38.569748 | controller |   "uid": 1000,
2025-10-10 08:40:38.569762 | controller |   "wgrp": false,
2025-10-10 08:40:38.569777 | controller |   "woth": false,
2025-10-10 08:40:38.569793 | controller |   "wusr": true,
2025-10-10 08:40:38.569809 | controller |   "xgrp": false,
2025-10-10 08:40:38.569824 | controller |   "xoth": false,
2025-10-10 08:40:38.569838 | controller |   "xusr": false
2025-10-10 08:40:38.569852 | controller | }
2025-10-10 08:40:38.582457 | 
2025-10-10 08:40:38.582564 | TASK [Copy crio stats log file]
2025-10-10 08:40:38.596479 | controller | skipping: Conditional result was False
2025-10-10 08:40:38.602120 | 
2025-10-10 08:40:38.602188 | TASK [Get SELinux related data]
2025-10-10 08:40:38.830442 | controller | <no matches>
2025-10-10 08:40:39.138648 | controller | ERROR
2025-10-10 08:40:39.138777 | controller | {
2025-10-10 08:40:39.138809 | controller |   "delta": "0:00:00.008284",
2025-10-10 08:40:39.138830 | controller |   "end": "2025-10-10 08:40:38.831039",
2025-10-10 08:40:39.138848 | controller |   "msg": "non-zero return code",
2025-10-10 08:40:39.138865 | controller |   "rc": 1,
2025-10-10 08:40:39.138882 | controller |   "start": "2025-10-10 08:40:38.822755"
2025-10-10 08:40:39.138899 | controller | }
2025-10-10 08:40:39.138948 | controller | ERROR: Ignoring Errors
2025-10-10 08:40:39.143954 | 
2025-10-10 08:40:39.144016 | TASK [Create system configuration directory]
2025-10-10 08:40:39.335126 | controller | changed
2025-10-10 08:40:39.340938 | 
2025-10-10 08:40:39.341014 | TASK [Get some of the system configurations]
2025-10-10 08:40:39.867645 | controller | changed
2025-10-10 08:40:39.872724 | 
2025-10-10 08:40:39.872791 | TASK [Copy generated documentation if available]
2025-10-10 08:40:39.886163 | controller | skipping: Conditional result was False
2025-10-10 08:40:39.891825 | 
2025-10-10 08:40:39.891894 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-10 08:40:39.905169 | controller | skipping: Conditional result was False
2025-10-10 08:40:39.912804 | 
2025-10-10 08:40:39.912895 | TASK [Compress logs bigger than 2MB]
2025-10-10 08:40:40.438779 | controller | changed
2025-10-10 08:40:40.444263 | 
2025-10-10 08:40:40.444335 | TASK [Copy files from workspace on node]
2025-10-10 08:40:40.462378 | controller | ok
2025-10-10 08:40:40.486861 | 
2025-10-10 08:40:40.486976 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-10 08:40:40.516279 | controller | skipping: Conditional result was False
2025-10-10 08:40:40.521889 | 
2025-10-10 08:40:40.521972 | TASK [fetch-output : Set log path for single node]
2025-10-10 08:40:40.550682 | controller | ok
2025-10-10 08:40:40.555548 | 
2025-10-10 08:40:40.555609 | LOOP [fetch-output : Ensure local output dirs]
2025-10-10 08:40:40.776878 | controller -> localhost | ok: "/var/lib/zuul/builds/22bbbd74dfd2486f998dc854dc1d5145/work/logs"
2025-10-10 08:40:40.777130 | controller -> localhost | changed: All items complete
2025-10-10 08:40:40.777162 | 
2025-10-10 08:40:41.032102 | controller -> localhost | changed: "/var/lib/zuul/builds/22bbbd74dfd2486f998dc854dc1d5145/work/artifacts"
2025-10-10 08:40:41.317059 | controller -> localhost | changed: "/var/lib/zuul/builds/22bbbd74dfd2486f998dc854dc1d5145/work/docs"
2025-10-10 08:40:41.330418 | 
2025-10-10 08:40:41.330489 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-10 08:40:42.061395 | controller | changed:
2025-10-10 08:40:42.061561 | controller | .d..t...... ./
2025-10-10 08:40:42.061594 | controller | >f+++++++++ README.html
2025-10-10 08:40:42.061617 | controller | >f+++++++++ ansible-execution.log
2025-10-10 08:40:42.061637 | controller | >f+++++++++ ansible.log
2025-10-10 08:40:42.061670 | controller | >f+++++++++ dmesg.log
2025-10-10 08:40:42.061692 | controller | >f+++++++++ installed-pkgs.log
2025-10-10 08:40:42.061714 | controller | >f+++++++++ python.log
2025-10-10 08:40:42.061735 | controller | >f+++++++++ registries.conf
2025-10-10 08:40:42.061760 | controller | >f+++++++++ report.html
2025-10-10 08:40:42.061784 | controller | >f+++++++++ selinux-denials.log
2025-10-10 08:40:42.061803 | controller | >f+++++++++ selinux-listing.log
2025-10-10 08:40:42.061821 | controller | cd+++++++++ ci-framework-data/
2025-10-10 08:40:42.061838 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-10 08:40:42.061855 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-10 08:40:42.061872 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-10-10 08:40:42.061889 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-10-10 08:40:42.061905 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-10-10 08:40:42.061948 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-10 08:40:42.061968 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-10 08:40:42.061985 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-10 08:40:42.062002 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-10 08:40:42.062019 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-10 08:40:42.062035 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-10 08:40:42.062053 | controller | cd+++++++++ registries.conf.d/
2025-10-10 08:40:42.062069 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-10 08:40:42.062086 | controller | cd+++++++++ system-config/
2025-10-10 08:40:42.062102 | controller | cd+++++++++ system-config/libvirt/
2025-10-10 08:40:42.062118 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-10 08:40:42.062134 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-10 08:40:42.543059 | controller | changed: .d..t...... ./
2025-10-10 08:40:42.942754 | controller | changed: .d..t...... ./
2025-10-10 08:40:42.967290 | 
2025-10-10 08:40:42.967383 | TASK [Return artifact to Zuul]
2025-10-10 08:40:42.996794 | controller | ok
2025-10-10 08:40:43.022205 | 
2025-10-10 08:40:43.022289 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-10 08:40:43.022434 | 
2025-10-10 08:40:43.022466 | PLAY RECAP
2025-10-10 08:40:43.022503 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-10 08:40:43.022525 | 
2025-10-10 08:40:43.111971 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-10 08:40:43.112751 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-10 08:40:43.653821 | 
2025-10-10 08:40:43.653934 | PLAY [all]
2025-10-10 08:40:43.676585 | 
2025-10-10 08:40:43.676678 | TASK [include_role : fetch-output]
2025-10-10 08:40:43.725518 | controller | ok
2025-10-10 08:40:43.746566 | 
2025-10-10 08:40:43.746673 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-10 08:40:43.790426 | controller | skipping: Conditional result was False
2025-10-10 08:40:43.796001 | 
2025-10-10 08:40:43.796071 | TASK [fetch-output : Set log path for single node]
2025-10-10 08:40:43.824979 | controller | ok
2025-10-10 08:40:43.830024 | 
2025-10-10 08:40:43.830096 | LOOP [fetch-output : Ensure local output dirs]
2025-10-10 08:40:44.190113 | controller -> localhost | ok: "/var/lib/zuul/builds/22bbbd74dfd2486f998dc854dc1d5145/work/logs"
2025-10-10 08:40:44.373535 | controller -> localhost | ok: "/var/lib/zuul/builds/22bbbd74dfd2486f998dc854dc1d5145/work/artifacts"
2025-10-10 08:40:44.570221 | controller -> localhost | ok: "/var/lib/zuul/builds/22bbbd74dfd2486f998dc854dc1d5145/work/docs"
2025-10-10 08:40:44.581563 | 
2025-10-10 08:40:44.581696 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-10 08:40:45.124402 | controller | ok
2025-10-10 08:40:45.124586 | controller | ok: All items complete
2025-10-10 08:40:45.124616 | 
2025-10-10 08:40:45.505335 | controller | ok
2025-10-10 08:40:45.909112 | controller | ok
2025-10-10 08:40:45.927638 | 
2025-10-10 08:40:45.927732 | TASK [include_role : fetch-output-openshift]
2025-10-10 08:40:45.941104 | controller | skipping: Conditional result was False
2025-10-10 08:40:45.947113 | 
2025-10-10 08:40:45.947189 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-10 08:40:46.334579 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.018126
2025-10-10 08:40:46.538061 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006808
2025-10-10 08:40:46.570398 | 
2025-10-10 08:40:46.570536 | PLAY [all]
2025-10-10 08:40:46.584746 | 
2025-10-10 08:40:46.584818 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-10 08:40:46.990094 | controller | changed
2025-10-10 08:40:47.012332 | 
2025-10-10 08:40:47.012407 | PLAY RECAP
2025-10-10 08:40:47.012455 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-10 08:40:47.012479 | 
2025-10-10 08:40:47.091023 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-10 08:40:47.091761 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-10 08:40:47.658947 | 
2025-10-10 08:40:47.659051 | PLAY [localhost]
2025-10-10 08:40:47.676482 | 
2025-10-10 08:40:47.676559 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-10 08:40:48.001518 | localhost | changed
2025-10-10 08:40:48.006332 | 
2025-10-10 08:40:48.006428 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-10 08:40:48.033438 | localhost | ok
2025-10-10 08:40:48.041203 | 
2025-10-10 08:40:48.041292 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-10 08:40:48.342102 | localhost | changed
2025-10-10 08:40:48.353774 | 
2025-10-10 08:40:48.353867 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-10 08:40:48.932724 | localhost | changed
2025-10-10 08:40:48.937767 | 
2025-10-10 08:40:48.937846 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-10 08:40:49.317429 | localhost | Identity added: /var/lib/zuul/builds/22bbbd74dfd2486f998dc854dc1d5145/work/tmp/ansible.odku75ol (/var/lib/zuul/builds/22bbbd74dfd2486f998dc854dc1d5145/work/tmp/ansible.odku75ol)
2025-10-10 08:40:49.317606 | localhost | ok: Runtime: 0:00:00.008044
2025-10-10 08:40:49.321998 | 
2025-10-10 08:40:49.322082 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-10 08:40:49.552532 | localhost | ok: Runtime: 0:00:00.004993
2025-10-10 08:40:49.557334 | 
2025-10-10 08:40:49.557410 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-10 08:40:49.605738 | localhost | changed
2025-10-10 08:40:49.610385 | 
2025-10-10 08:40:49.610456 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-10 08:40:50.062814 | localhost | changed
2025-10-10 08:40:50.082707 | 
2025-10-10 08:40:50.082774 | PLAY [localhost]
2025-10-10 08:40:50.095038 | 
2025-10-10 08:40:50.095098 | TASK [Generate bulk log download script]
2025-10-10 08:40:50.112554 | localhost | ok
2025-10-10 08:40:50.124564 | 
2025-10-10 08:40:50.124670 | TASK [local-log-download : Check API endpoint is defined]
2025-10-10 08:40:50.152332 | localhost | ok: All assertions passed
2025-10-10 08:40:50.158043 | 
2025-10-10 08:40:50.158130 | TASK [local-log-download : Create download script]
2025-10-10 08:40:50.577603 | localhost -> localhost | changed
2025-10-10 08:40:50.586734 | 
2025-10-10 08:40:50.586807 | TASK [Register quick-download link]
2025-10-10 08:40:50.605017 | localhost | ok
2025-10-10 08:40:50.641727 | 
2025-10-10 08:40:50.641812 | PLAY [logserver.rdoproject.org]
2025-10-10 08:40:50.651640 | 
2025-10-10 08:40:50.651716 | TASK [Set zuul-log-path fact]
2025-10-10 08:40:50.667863 | logserver.rdoproject.org | ok
2025-10-10 08:40:50.677527 | 
2025-10-10 08:40:50.677595 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-10 08:40:50.703707 | logserver.rdoproject.org | ok
2025-10-10 08:40:50.709957 | 
2025-10-10 08:40:50.710031 | TASK [upload-logs : Create log directories]
2025-10-10 08:40:52.333947 | logserver.rdoproject.org | changed
2025-10-10 08:40:52.337341 | 
2025-10-10 08:40:52.337411 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-10 08:40:52.589357 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.022250
2025-10-10 08:40:52.595159 | 
2025-10-10 08:40:52.595238 | TASK [upload-logs : Upload logs to log server]
2025-10-10 08:40:54.179320 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-10 08:40:54.184073 | 
2025-10-10 08:40:54.184139 | LOOP [upload-logs : Compress console log and json output]
2025-10-10 08:40:54.234309 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-10 08:40:54.235721 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-10 08:40:54.242198 | 
2025-10-10 08:40:54.242292 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-10 08:40:54.289954 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-10 08:40:54.290198 | 
2025-10-10 08:40:54.295256 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-10 08:40:54.305391 | 
2025-10-10 08:40:54.305466 | LOOP [upload-logs : Upload console log and json output]