2025-11-24 08:37:57.436023 | Job console starting...
2025-11-24 08:37:57.677353 | Updating repositories
2025-11-24 08:37:58.411499 | Preparing job workspace
2025-11-24 08:38:09.306444 | Running Ansible setup...
2025-11-24 08:38:14.004328 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-24 08:38:14.644904 | 
2025-11-24 08:38:14.645035 | PLAY [localhost]
2025-11-24 08:38:14.667255 | 
2025-11-24 08:38:14.667328 | TASK [Gathering Facts]
2025-11-24 08:38:15.647933 | localhost | ok
2025-11-24 08:38:15.662494 | 
2025-11-24 08:38:15.662592 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-24 08:38:16.426365 | localhost -> localhost | changed
2025-11-24 08:38:16.433466 | 
2025-11-24 08:38:16.433656 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-24 08:38:17.574514 | localhost -> localhost | changed
2025-11-24 08:38:17.583750 | 
2025-11-24 08:38:17.583820 | TASK [Setup log path fact]
2025-11-24 08:38:17.625504 | localhost | ok
2025-11-24 08:38:17.646487 | 
2025-11-24 08:38:17.646557 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-24 08:38:17.674122 | localhost | ok
2025-11-24 08:38:17.683139 | 
2025-11-24 08:38:17.683254 | TASK [emit-job-header : Print job information]
2025-11-24 08:38:17.731336 | # Job Information
2025-11-24 08:38:17.731524 | Ansible Version: 2.15.12
2025-11-24 08:38:17.731554 | Job: cifmw-molecule-ci_local_storage
2025-11-24 08:38:17.731578 | Pipeline: github-check
2025-11-24 08:38:17.731598 | Executor: ibm-bm3-ze.softwarefactory-project.io
2025-11-24 08:38:17.731618 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3451
2025-11-24 08:38:17.731639 | Log URL (when completed): https://logserver.rdoproject.org/aee/rdoproject.org/aee287eca05245c880a6241053f34642/
2025-11-24 08:38:17.731659 | Event ID: 2dae9040-c910-11f0-818a-433f9d14d6bc
2025-11-24 08:38:17.735281 | 
2025-11-24 08:38:17.735346 | LOOP [emit-job-header : Print node information]
2025-11-24 08:38:17.850199 | localhost | ok:
2025-11-24 08:38:17.850446 | localhost | # Node Information
2025-11-24 08:38:17.850585 | localhost | Inventory Hostname: controller
2025-11-24 08:38:17.850638 | localhost | Hostname: np0005533191
2025-11-24 08:38:17.850664 | localhost | Username: zuul
2025-11-24 08:38:17.850688 | localhost | Distro: CentOS 9
2025-11-24 08:38:17.850709 | localhost | Provider: ibm-bm3-nodepool
2025-11-24 08:38:17.850728 | localhost | Region: regionOne
2025-11-24 08:38:17.850746 | localhost | Label: centos-9-stream-crc-2-48-0-xl-ibm
2025-11-24 08:38:17.850764 | localhost | Product Name: OpenStack Compute
2025-11-24 08:38:17.850782 | localhost | Interface IP: 192.168.25.94
2025-11-24 08:38:17.894712 | 
2025-11-24 08:38:17.894803 | PLAY [all]
2025-11-24 08:38:17.901566 | 
2025-11-24 08:38:17.901640 | TASK [Gather network facts]
2025-11-24 08:38:18.362295 | controller | ok
2025-11-24 08:38:18.380165 | 
2025-11-24 08:38:18.380286 | TASK [include_role : start-zuul-console]
2025-11-24 08:38:18.398884 | controller | ok
2025-11-24 08:38:18.410874 | 
2025-11-24 08:38:18.410943 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-24 08:38:18.868559 | controller | ok
2025-11-24 08:38:18.878163 | 
2025-11-24 08:38:18.878238 | TASK [include_role : add-build-sshkey]
2025-11-24 08:38:18.922593 | controller | ok
2025-11-24 08:38:18.936755 | 
2025-11-24 08:38:18.936831 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-24 08:38:19.163717 | controller -> localhost | ok
2025-11-24 08:38:19.169875 | 
2025-11-24 08:38:19.169955 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-24 08:38:19.210703 | controller | ok
2025-11-24 08:38:19.224350 | controller | included: /var/lib/zuul/builds/aee287eca05245c880a6241053f34642/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-24 08:38:19.230242 | 
2025-11-24 08:38:19.230306 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-24 08:38:19.766674 | controller -> localhost | Generating public/private rsa key pair.
2025-11-24 08:38:19.766894 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/aee287eca05245c880a6241053f34642/work/aee287eca05245c880a6241053f34642_id_rsa.
2025-11-24 08:38:19.766930 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/aee287eca05245c880a6241053f34642/work/aee287eca05245c880a6241053f34642_id_rsa.pub.
2025-11-24 08:38:19.766955 | controller -> localhost | The key fingerprint is:
2025-11-24 08:38:19.766988 | controller -> localhost | SHA256:et7KyGe1gofLZkxHBS8By//WPJUeKQAE4CB2fFdltiA zuul-build-sshkey
2025-11-24 08:38:19.767011 | controller -> localhost | The key's randomart image is:
2025-11-24 08:38:19.767032 | controller -> localhost | +---[RSA 3072]----+
2025-11-24 08:38:19.767051 | controller -> localhost | |..o...oE*+.+     |
2025-11-24 08:38:19.767070 | controller -> localhost | |...+ o o.+= .    |
2025-11-24 08:38:19.767090 | controller -> localhost | |    o + ..o.     |
2025-11-24 08:38:19.767110 | controller -> localhost | |       ... .   o |
2025-11-24 08:38:19.767129 | controller -> localhost | |       .S   . =  |
2025-11-24 08:38:19.767148 | controller -> localhost | |      .....o + . |
2025-11-24 08:38:19.767166 | controller -> localhost | |     o.+..o.+ .  |
2025-11-24 08:38:19.767187 | controller -> localhost | |     o*==o.  .   |
2025-11-24 08:38:19.767206 | controller -> localhost | |     o==+o.      |
2025-11-24 08:38:19.767225 | controller -> localhost | +----[SHA256]-----+
2025-11-24 08:38:19.767270 | controller -> localhost | ok: Runtime: 0:00:00.107179
2025-11-24 08:38:19.772938 | 
2025-11-24 08:38:19.773018 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-24 08:38:19.802715 | controller | ok
2025-11-24 08:38:19.815240 | controller | included: /var/lib/zuul/builds/aee287eca05245c880a6241053f34642/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-24 08:38:19.831277 | 
2025-11-24 08:38:19.831422 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-24 08:38:19.857661 | controller | skipping: Conditional result was False
2025-11-24 08:38:19.864340 | 
2025-11-24 08:38:19.864436 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-24 08:38:20.349451 | controller | changed
2025-11-24 08:38:20.355116 | 
2025-11-24 08:38:20.355202 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-24 08:38:20.627436 | controller | ok
2025-11-24 08:38:20.633260 | 
2025-11-24 08:38:20.633327 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-24 08:38:21.839466 | controller | changed
2025-11-24 08:38:21.847070 | 
2025-11-24 08:38:21.847141 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-24 08:38:23.085729 | controller | changed
2025-11-24 08:38:23.094440 | 
2025-11-24 08:38:23.094505 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-24 08:38:23.141806 | controller | skipping: Conditional result was False
2025-11-24 08:38:23.148294 | 
2025-11-24 08:38:23.148364 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-24 08:38:23.520322 | controller -> localhost | changed
2025-11-24 08:38:23.536335 | 
2025-11-24 08:38:23.536435 | TASK [add-build-sshkey : Add back temp key]
2025-11-24 08:38:23.920747 | controller -> localhost | Identity added: /var/lib/zuul/builds/aee287eca05245c880a6241053f34642/work/aee287eca05245c880a6241053f34642_id_rsa (zuul-build-sshkey)
2025-11-24 08:38:23.920957 | controller -> localhost | ok: Runtime: 0:00:00.018598
2025-11-24 08:38:23.928537 | 
2025-11-24 08:38:23.928601 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-24 08:38:24.327441 | controller | ok
2025-11-24 08:38:24.332350 | 
2025-11-24 08:38:24.335185 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-24 08:38:24.379065 | controller | skipping: Conditional result was False
2025-11-24 08:38:24.390047 | 
2025-11-24 08:38:24.390125 | TASK [include_role : validate-host]
2025-11-24 08:38:24.419347 | controller | ok
2025-11-24 08:38:24.440669 | 
2025-11-24 08:38:24.440735 | TASK [validate-host : Define zuul_info_dir fact]
2025-11-24 08:38:24.523159 | controller | ok
2025-11-24 08:38:24.528291 | 
2025-11-24 08:38:24.528353 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-11-24 08:38:24.800058 | controller -> localhost | ok
2025-11-24 08:38:24.806323 | 
2025-11-24 08:38:24.806428 | TASK [validate-host : Collect information about the host]
2025-11-24 08:38:25.506873 | controller | ok
2025-11-24 08:38:25.517167 | 
2025-11-24 08:38:25.517237 | TASK [validate-host : Sanitize hostname]
2025-11-24 08:38:25.566174 | controller | ok
2025-11-24 08:38:25.571323 | 
2025-11-24 08:38:25.571404 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-11-24 08:38:25.997427 | controller -> localhost | changed
2025-11-24 08:38:26.003691 | 
2025-11-24 08:38:26.003757 | TASK [validate-host : Collect information about zuul worker]
2025-11-24 08:38:26.422136 | controller | ok
2025-11-24 08:38:26.426986 | 
2025-11-24 08:38:26.427058 | TASK [validate-host : Write out all zuul information for each host]
2025-11-24 08:38:26.813668 | controller -> localhost | changed
2025-11-24 08:38:26.823135 | 
2025-11-24 08:38:26.823218 | TASK [include_role : prepare-workspace-openshift]
2025-11-24 08:38:26.837276 | controller | skipping: Conditional result was False
2025-11-24 08:38:26.843407 | 
2025-11-24 08:38:26.843486 | TASK [include_role : remove-zuul-sshkey]
2025-11-24 08:38:26.859011 | controller | skipping: Conditional result was False
2025-11-24 08:38:26.865417 | 
2025-11-24 08:38:26.865495 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-24 08:38:27.136429 | controller | ok: "logs"
2025-11-24 08:38:27.136686 | controller | ok: All items complete
2025-11-24 08:38:27.136716 | 
2025-11-24 08:38:27.391104 | controller | ok: "artifacts"
2025-11-24 08:38:27.628128 | controller | ok: "docs"
2025-11-24 08:38:27.643731 | 
2025-11-24 08:38:27.643822 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-24 08:38:27.964710 | controller | changed: "logs"
2025-11-24 08:38:28.218926 | controller | changed: "artifacts"
2025-11-24 08:38:28.465244 | controller | changed: "docs"
2025-11-24 08:38:28.512696 | 
2025-11-24 08:38:28.512796 | PLAY RECAP
2025-11-24 08:38:28.512844 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-24 08:38:28.512874 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-24 08:38:28.512895 | 
2025-11-24 08:38:28.669919 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-24 08:38:28.670685 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-24 08:38:29.359962 | 
2025-11-24 08:38:29.360111 | PLAY [all]
2025-11-24 08:38:29.381614 | 
2025-11-24 08:38:29.381692 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-24 08:38:29.441805 | controller | ok
2025-11-24 08:38:29.447661 | 
2025-11-24 08:38:29.447740 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-24 08:38:29.874753 | controller | changed
2025-11-24 08:38:29.880799 | 
2025-11-24 08:38:29.880938 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-24 08:38:31.149654 | controller | changed
2025-11-24 08:38:31.160635 | 
2025-11-24 08:38:31.160722 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-11-24 08:38:31.608224 | controller | changed:
2025-11-24 08:38:31.608852 | controller | {
2025-11-24 08:38:31.608914 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-11-24 08:38:31.608953 | controller | }
2025-11-24 08:38:31.894293 | controller | changed:
2025-11-24 08:38:31.894408 | controller | {
2025-11-24 08:38:31.894451 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-11-24 08:38:31.894475 | controller | }
2025-11-24 08:38:32.172715 | controller | changed:
2025-11-24 08:38:32.172830 | controller | {
2025-11-24 08:38:32.172859 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-11-24 08:38:32.172881 | controller | }
2025-11-24 08:38:32.450960 | controller | changed:
2025-11-24 08:38:32.451061 | controller | {
2025-11-24 08:38:32.451089 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-11-24 08:38:32.451110 | controller | }
2025-11-24 08:38:32.732406 | controller | changed:
2025-11-24 08:38:32.732509 | controller | {
2025-11-24 08:38:32.732537 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-11-24 08:38:32.732560 | controller | }
2025-11-24 08:38:33.012202 | controller | changed:
2025-11-24 08:38:33.012492 | controller | {
2025-11-24 08:38:33.012533 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-11-24 08:38:33.012558 | controller | }
2025-11-24 08:38:33.305044 | controller | changed:
2025-11-24 08:38:33.305149 | controller | {
2025-11-24 08:38:33.305187 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-11-24 08:38:33.305210 | controller | }
2025-11-24 08:38:33.600990 | controller | changed:
2025-11-24 08:38:33.601086 | controller | {
2025-11-24 08:38:33.601113 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-11-24 08:38:33.601133 | controller | }
2025-11-24 08:38:33.891418 | controller | changed:
2025-11-24 08:38:33.891526 | controller | {
2025-11-24 08:38:33.891553 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-11-24 08:38:33.891574 | controller | }
2025-11-24 08:38:34.196962 | controller | changed:
2025-11-24 08:38:34.197069 | controller | {
2025-11-24 08:38:34.197096 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-11-24 08:38:34.197119 | controller | }
2025-11-24 08:38:34.461026 | controller | changed:
2025-11-24 08:38:34.461119 | controller | {
2025-11-24 08:38:34.461146 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-11-24 08:38:34.461167 | controller | }
2025-11-24 08:38:34.744960 | controller | changed:
2025-11-24 08:38:34.745056 | controller | {
2025-11-24 08:38:34.745084 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-11-24 08:38:34.745114 | controller | }
2025-11-24 08:38:35.053664 | controller | changed:
2025-11-24 08:38:35.053767 | controller | {
2025-11-24 08:38:35.053793 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-11-24 08:38:35.053813 | controller | }
2025-11-24 08:38:35.339652 | controller | changed:
2025-11-24 08:38:35.339746 | controller | {
2025-11-24 08:38:35.339772 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-11-24 08:38:35.339791 | controller | }
2025-11-24 08:38:35.618141 | controller | changed:
2025-11-24 08:38:35.618236 | controller | {
2025-11-24 08:38:35.618263 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-11-24 08:38:35.618284 | controller | }
2025-11-24 08:38:35.897055 | controller | changed:
2025-11-24 08:38:35.897156 | controller | {
2025-11-24 08:38:35.897183 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-11-24 08:38:35.897203 | controller | }
2025-11-24 08:38:36.178879 | controller | changed:
2025-11-24 08:38:36.178971 | controller | {
2025-11-24 08:38:36.179010 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-11-24 08:38:36.179033 | controller | }
2025-11-24 08:38:36.487481 | controller | changed:
2025-11-24 08:38:36.487586 | controller | {
2025-11-24 08:38:36.487613 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-11-24 08:38:36.487644 | controller | }
2025-11-24 08:38:36.774110 | controller | changed:
2025-11-24 08:38:36.774226 | controller | {
2025-11-24 08:38:36.774254 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-11-24 08:38:36.774276 | controller | }
2025-11-24 08:38:37.054058 | controller | changed:
2025-11-24 08:38:37.054141 | controller | {
2025-11-24 08:38:37.054169 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-11-24 08:38:37.054192 | controller | }
2025-11-24 08:38:37.336325 | controller | changed:
2025-11-24 08:38:37.336430 | controller | {
2025-11-24 08:38:37.336458 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-11-24 08:38:37.336478 | controller | }
2025-11-24 08:38:37.623892 | controller | changed:
2025-11-24 08:38:37.624000 | controller | {
2025-11-24 08:38:37.624028 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-11-24 08:38:37.624049 | controller | }
2025-11-24 08:38:37.907677 | controller | changed:
2025-11-24 08:38:37.907774 | controller | {
2025-11-24 08:38:37.907801 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-11-24 08:38:37.907823 | controller | }
2025-11-24 08:38:38.190045 | controller | changed:
2025-11-24 08:38:38.190144 | controller | {
2025-11-24 08:38:38.190170 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-11-24 08:38:38.190190 | controller | }
2025-11-24 08:38:38.466595 | controller | changed:
2025-11-24 08:38:38.466782 | controller | {
2025-11-24 08:38:38.466813 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-11-24 08:38:38.466834 | controller | }
2025-11-24 08:38:38.738949 | controller | changed:
2025-11-24 08:38:38.739127 | controller | {
2025-11-24 08:38:38.739153 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-11-24 08:38:38.739173 | controller | }
2025-11-24 08:38:38.762465 | 
2025-11-24 08:38:38.762575 | TASK [Set timezone to UTC]
2025-11-24 08:38:39.238148 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-11-24 08:38:39.243418 | 
2025-11-24 08:38:39.243505 | TASK [Create nodepool directory]
2025-11-24 08:38:39.517850 | controller | changed
2025-11-24 08:38:39.523665 | 
2025-11-24 08:38:39.523782 | TASK [Create nodepool sub_nodes file]
2025-11-24 08:38:40.449450 | controller | changed
2025-11-24 08:38:40.455601 | 
2025-11-24 08:38:40.455691 | TASK [Create nodepool sub_nodes_private file]
2025-11-24 08:38:41.410168 | controller | changed
2025-11-24 08:38:41.419848 | 
2025-11-24 08:38:41.419957 | LOOP [Populate nodepool sub_nodes file]
2025-11-24 08:38:41.451014 | 
2025-11-24 08:38:41.451182 | LOOP [Populate nodepool sub_nodes_private file]
2025-11-24 08:38:41.481662 | 
2025-11-24 08:38:41.481886 | TASK [Create nodepool primary file]
2025-11-24 08:38:41.507330 | controller | skipping: Conditional result was False
2025-11-24 08:38:41.515355 | 
2025-11-24 08:38:41.515513 | TASK [Create nodepool node_private for this node]
2025-11-24 08:38:42.478005 | controller | changed
2025-11-24 08:38:42.483865 | 
2025-11-24 08:38:42.483931 | LOOP [Copy ssh keys to nodepool directory]
2025-11-24 08:38:42.891584 | controller | ok: Item: id_rsa Runtime: 0:00:00.003430
2025-11-24 08:38:42.891805 | 
2025-11-24 08:38:43.113210 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004439
2025-11-24 08:38:43.128424 | 
2025-11-24 08:38:43.128588 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-11-24 08:38:44.054544 | controller | changed
2025-11-24 08:38:44.059923 | 
2025-11-24 08:38:44.060003 | TASK [Validate sudoers config after edits]
2025-11-24 08:38:44.333216 | controller | /etc/sudoers: parsed OK
2025-11-24 08:38:44.333306 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-11-24 08:38:44.333317 | controller | /etc/sudoers.d/zuul: parsed OK
2025-11-24 08:38:44.333323 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-11-24 08:38:44.590544 | controller | ok: Runtime: 0:00:00.004220
2025-11-24 08:38:44.596313 | 
2025-11-24 08:38:44.596377 | TASK [Show the environment passed in to job shell scripts]
2025-11-24 08:38:44.849529 | controller | SHELL=/bin/bash
2025-11-24 08:38:44.849560 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-11-24 08:38:44.849569 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-11-24 08:38:44.849575 | controller | ZUUL_CHANGES=rdo-jobs:master:refs/changes/88/58488/1^openstack-k8s-operators/ci-framework:main:refs/changes/51/3451/9e962b797235f287fc3e87d5e76ab3a88eb03cd8
2025-11-24 08:38:44.849581 | controller | PWD=/home/zuul
2025-11-24 08:38:44.849587 | controller | ZUUL_PIPELINE=github-check
2025-11-24 08:38:44.849592 | controller | LOGNAME=zuul
2025-11-24 08:38:44.849597 | controller | XDG_SESSION_TYPE=tty
2025-11-24 08:38:44.849603 | controller | _=/usr/bin/env
2025-11-24 08:38:44.849608 | controller | MOTD_SHOWN=pam
2025-11-24 08:38:44.849613 | controller | HOME=/home/zuul
2025-11-24 08:38:44.849618 | controller | LANG=en_US.UTF-8
2025-11-24 08:38:44.849623 | controller | SSH_CONNECTION=192.168.25.12 34292 192.168.25.94 22
2025-11-24 08:38:44.849649 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-11-24 08:38:44.849657 | controller | ZUUL_CHANGE_IDS=58488,1 3451,9e962b797235f287fc3e87d5e76ab3a88eb03cd8
2025-11-24 08:38:44.849663 | controller | WORKSPACE=/home/zuul/workspace
2025-11-24 08:38:44.849668 | controller | XDG_SESSION_CLASS=user
2025-11-24 08:38:44.849673 | controller | SELINUX_ROLE_REQUESTED=
2025-11-24 08:38:44.849678 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-11-24 08:38:44.849683 | controller | USER=zuul
2025-11-24 08:38:44.849688 | controller | ZUUL_VOTING=True
2025-11-24 08:38:44.849693 | controller | BUILD_TIMEOUT=1800000
2025-11-24 08:38:44.849698 | controller | SELINUX_USE_CURRENT_RANGE=
2025-11-24 08:38:44.849703 | controller | SHLVL=1
2025-11-24 08:38:44.849709 | controller | ZUUL_PATCHSET=9e962b797235f287fc3e87d5e76ab3a88eb03cd8
2025-11-24 08:38:44.849714 | controller | XDG_SESSION_ID=1
2025-11-24 08:38:44.849719 | controller | ZUUL_BRANCH=main
2025-11-24 08:38:44.849725 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-11-24 08:38:44.849730 | controller | SSH_CLIENT=192.168.25.12 34292 22
2025-11-24 08:38:44.849735 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-11-24 08:38:44.849740 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-11-24 08:38:44.849745 | controller | which_declare=declare -f
2025-11-24 08:38:44.849751 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-11-24 08:38:44.849756 | controller | SELINUX_LEVEL_REQUESTED=
2025-11-24 08:38:44.849761 | controller | ZUUL_CHANGE=3451
2025-11-24 08:38:44.849766 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-11-24 08:38:44.849771 | controller | ZUUL_UUID=aee287eca05245c880a6241053f34642
2025-11-24 08:38:44.849776 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-11-24 08:38:44.849782 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-11-24 08:38:44.849788 | controller | }
2025-11-24 08:38:45.120299 | controller | ok: Runtime: 0:00:00.005005
2025-11-24 08:38:45.126180 | 
2025-11-24 08:38:45.126249 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-11-24 08:38:45.140240 | controller | skipping: Conditional result was False
2025-11-24 08:38:45.146222 | 
2025-11-24 08:38:45.146296 | TASK [Symlink /home/zuul-worker/workspace]
2025-11-24 08:38:45.705862 | controller | skipping: Conditional result was False
2025-11-24 08:38:45.714727 | 
2025-11-24 08:38:45.714877 | TASK [Ensure legacy workspace directory]
2025-11-24 08:38:45.977659 | controller | changed
2025-11-24 08:38:45.996875 | 
2025-11-24 08:38:45.996938 | PLAY RECAP
2025-11-24 08:38:45.997010 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-24 08:38:45.997043 | 
2025-11-24 08:38:46.074625 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-24 08:38:46.075406 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-24 08:38:46.624941 | 
2025-11-24 08:38:46.625073 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-11-24 08:38:46.658090 | 
2025-11-24 08:38:46.658212 | TASK [Create zuul-output directory]
2025-11-24 08:38:47.023959 | controller | changed
2025-11-24 08:38:47.029543 | 
2025-11-24 08:38:47.029632 | TASK [Slurp Zuul inventory test]
2025-11-24 08:38:47.320173 | controller -> localhost | ok
2025-11-24 08:38:47.326516 | 
2025-11-24 08:38:47.326590 | TASK [Save zuul inventory]
2025-11-24 08:38:48.441239 | controller | changed
2025-11-24 08:38:48.448070 | 
2025-11-24 08:38:48.448189 | TASK [Save zuul vars without the change_message]
2025-11-24 08:38:49.403148 | controller | changed
2025-11-24 08:38:49.422912 | 
2025-11-24 08:38:49.423000 | PLAY RECAP
2025-11-24 08:38:49.423048 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-24 08:38:49.423073 | 
2025-11-24 08:38:49.609504 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-24 08:38:49.610225 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-24 08:38:50.361332 | 
2025-11-24 08:38:50.361463 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-11-24 08:38:50.382101 | 
2025-11-24 08:38:50.382173 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-11-24 08:38:50.413606 | controller | ok
2025-11-24 08:38:50.445843 | 
2025-11-24 08:38:50.445967 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-24 08:38:50.469707 | controller | skipping: Conditional result was False
2025-11-24 08:38:50.475334 | 
2025-11-24 08:38:50.475421 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-24 08:38:50.875753 | controller | ok
2025-11-24 08:38:50.881352 | 
2025-11-24 08:38:50.881440 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-24 08:38:51.758842 | controller | ok
2025-11-24 08:38:51.769726 | 
2025-11-24 08:38:51.769815 | TASK [Prepare workspace]
2025-11-24 08:38:51.798770 | controller | ok
2025-11-24 08:38:51.816072 | 
2025-11-24 08:38:51.816150 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-24 08:38:52.189319 | controller | ok
2025-11-24 08:38:52.195219 | 
2025-11-24 08:38:52.195286 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-24 08:38:56.065180 | controller | Output suppressed because no_log was given
2025-11-24 08:38:56.076303 | 
2025-11-24 08:38:56.076401 | LOOP [Create zuul-output directory]
2025-11-24 08:38:56.325184 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-11-24 08:38:56.530341 | controller | ok: "/home/zuul/zuul-output/logs"
2025-11-24 08:38:56.537897 | 
2025-11-24 08:38:56.538041 | TASK [Install required packages]
2025-11-24 08:39:30.548339 | controller | ok: Nothing to do
2025-11-24 08:39:30.553830 | 
2025-11-24 08:39:30.553896 | TASK [Install venv]
2025-11-24 08:40:29.941699 | controller | changed
2025-11-24 08:40:29.968094 | 
2025-11-24 08:40:29.968178 | PLAY RECAP
2025-11-24 08:40:29.968223 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-24 08:40:29.968246 | 
2025-11-24 08:40:30.049495 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-24 08:40:30.050335 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-24 08:40:30.631751 | 
2025-11-24 08:40:30.631889 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-11-24 08:40:30.653205 | 
2025-11-24 08:40:30.653298 | TASK [Gather required facts]
2025-11-24 08:40:31.242934 | controller | ok
2025-11-24 08:40:31.248826 | 
2025-11-24 08:40:31.248975 | TASK [Load environment var if instructed to]
2025-11-24 08:40:31.277698 | controller | skipping: Conditional result was False
2025-11-24 08:40:31.286287 | 
2025-11-24 08:40:31.286478 | TASK [Ensure group_vars dir exists]
2025-11-24 08:40:31.745084 | controller | ok
2025-11-24 08:40:31.754110 | 
2025-11-24 08:40:31.754711 | TASK [Print related variables]
2025-11-24 08:40:31.800216 | controller | ok:
2025-11-24 08:40:31.800431 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-11-24 08:40:31.800468 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_local_storage
2025-11-24 08:40:31.806745 | 
2025-11-24 08:40:31.806816 | TASK [Run molecule]
2025-11-24 08:40:32.886193 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-11-24 08:40:32.956525 | controller | INFO     Performing prerun with role_name_check=0...
2025-11-24 08:40:49.012906 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:40:49.013385 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:40:49.013882 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:40:49.014361 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:40:49.014859 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+9e962b79 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 1.0.0+9e962b79 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:40:49.015342 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:40:49.015810 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:40:49.016271 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:40:49.016732 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:40:49.017183 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:40:49.017659 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:40:49.018096 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:40:49.018566 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:40:49.019003 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:40:49.019487 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:40:49.029053 | controller | INFO     Running default > prepare
2025-11-24 08:40:49.686160 | controller |
2025-11-24 08:40:49.686297 | controller | PLAY [Prepare] *****************************************************************
2025-11-24 08:40:49.686412 | controller |
2025-11-24 08:40:49.686547 | controller | TASK [Gathering Facts] *********************************************************
2025-11-24 08:40:49.686655 | controller | Monday 24 November 2025  08:40:49 +0000 (0:00:00.013)       0:00:00.013 *******
2025-11-24 08:40:50.513342 | controller | ok: [instance]
2025-11-24 08:40:50.513393 | controller |
2025-11-24 08:40:50.513561 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-11-24 08:40:50.513680 | controller | Monday 24 November 2025  08:40:50 +0000 (0:00:00.827)       0:00:00.840 *******
2025-11-24 08:40:50.529875 | controller | skipping: [instance]
2025-11-24 08:40:50.529960 | controller |
2025-11-24 08:40:50.530084 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-11-24 08:40:50.530257 | controller | Monday 24 November 2025  08:40:50 +0000 (0:00:00.016)       0:00:00.857 *******
2025-11-24 08:40:50.570365 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-11-24 08:40:50.570479 | controller |
2025-11-24 08:40:50.570643 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-11-24 08:40:50.570745 | controller | Monday 24 November 2025  08:40:50 +0000 (0:00:00.040)       0:00:00.897 *******
2025-11-24 08:40:50.845941 | controller | ok: [instance]
2025-11-24 08:40:50.846012 | controller |
2025-11-24 08:40:50.846120 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-11-24 08:40:50.846232 | controller | Monday 24 November 2025  08:40:50 +0000 (0:00:00.275)       0:00:01.173 *******
2025-11-24 08:40:51.151306 | controller | ok: [instance]
2025-11-24 08:40:51.151393 | controller |
2025-11-24 08:40:51.151551 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-11-24 08:40:51.151678 | controller | Monday 24 November 2025  08:40:51 +0000 (0:00:00.305)       0:00:01.478 *******
2025-11-24 08:40:51.832166 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-11-24 08:40:51.832224 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-11-24 08:40:51.832345 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-11-24 08:40:51.832461 | controller |
2025-11-24 08:40:51.832569 | controller | TASK [Deploy repo-setup] *******************************************************
2025-11-24 08:40:51.832662 | controller | Monday 24 November 2025  08:40:51 +0000 (0:00:00.680)       0:00:02.159 *******
2025-11-24 08:40:51.891383 | controller |
2025-11-24 08:40:51.891662 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-11-24 08:40:52.428562 | controller | Monday 24 November 2025  08:40:51 +0000 (0:00:00.059)       0:00:02.218 *******
2025-11-24 08:40:52.428604 | controller | changed: [instance] => (item=tmp)
2025-11-24 08:40:53.265681 | controller | changed: [instance] => (item=artifacts/repositories)
2025-11-24 08:40:53.265715 | controller | changed: [instance] => (item=venv/repo_setup)
2025-11-24 08:40:53.265723 | controller |
2025-11-24 08:40:53.265729 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-11-24 08:40:53.265735 | controller | Monday 24 November 2025  08:40:52 +0000 (0:00:00.536)       0:00:02.755 *******
2025-11-24 08:40:53.265745 | controller | ok: [instance]
2025-11-24 08:40:54.319448 | controller |
2025-11-24 08:40:54.319480 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-11-24 08:40:54.319492 | controller | Monday 24 November 2025  08:40:53 +0000 (0:00:00.837)       0:00:03.592 *******
2025-11-24 08:40:54.319502 | controller | changed: [instance]
2025-11-24 08:41:02.179565 | controller |
2025-11-24 08:41:02.179610 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-11-24 08:41:02.179618 | controller | Monday 24 November 2025  08:40:54 +0000 (0:00:01.053)       0:00:04.646 *******
2025-11-24 08:41:02.179629 | controller | changed: [instance]
2025-11-24 08:41:02.873356 | controller |
2025-11-24 08:41:02.873400 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-11-24 08:41:02.873408 | controller | Monday 24 November 2025  08:41:02 +0000 (0:00:07.860)       0:00:12.506 *******
2025-11-24 08:41:02.873441 | controller | changed: [instance]
2025-11-24 08:41:02.894556 | controller |
2025-11-24 08:41:02.894588 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-11-24 08:41:02.894596 | controller | Monday 24 November 2025  08:41:02 +0000 (0:00:00.693)       0:00:13.200 *******
2025-11-24 08:41:02.894606 | controller | skipping: [instance]
2025-11-24 08:41:02.894805 | controller |
2025-11-24 08:41:02.894834 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-11-24 08:41:03.692216 | controller | Monday 24 November 2025  08:41:02 +0000 (0:00:00.021)       0:00:13.222 *******
2025-11-24 08:41:03.692249 | controller | changed: [instance]
2025-11-24 08:41:03.728813 | controller |
2025-11-24 08:41:03.728834 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-11-24 08:41:03.728841 | controller | Monday 24 November 2025  08:41:03 +0000 (0:00:00.797)       0:00:14.019 *******
2025-11-24 08:41:03.728851 | controller | skipping: [instance]
2025-11-24 08:41:03.765332 | controller |
2025-11-24 08:41:03.765355 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-11-24 08:41:03.765362 | controller | Monday 24 November 2025  08:41:03 +0000 (0:00:00.036)       0:00:14.056 *******
2025-11-24 08:41:03.765371 | controller | skipping: [instance]
2025-11-24 08:41:03.799564 | controller |
2025-11-24 08:41:03.799599 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-11-24 08:41:03.799609 | controller | Monday 24 November 2025  08:41:03 +0000 (0:00:00.036)       0:00:14.092 *******
2025-11-24 08:41:03.799621 | controller | skipping: [instance]
2025-11-24 08:41:04.337523 | controller |
2025-11-24 08:41:04.337557 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-11-24 08:41:04.337566 | controller | Monday 24 November 2025  08:41:03 +0000 (0:00:00.034)       0:00:14.126 *******
2025-11-24 08:41:04.337578 | controller | changed: [instance]
2025-11-24 08:41:04.337608 | controller |
2025-11-24 08:41:04.337881 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-11-24 08:41:04.767401 | controller | Monday 24 November 2025  08:41:04 +0000 (0:00:00.537)       0:00:14.664 *******
2025-11-24 08:41:04.767510 | controller | changed: [instance]
2025-11-24 08:41:04.795849 | controller |
2025-11-24 08:41:04.795871 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-11-24 08:41:04.795879 | controller | Monday 24 November 2025  08:41:04 +0000 (0:00:00.429)       0:00:15.094 *******
2025-11-24 08:41:04.795888 | controller | skipping: [instance]
2025-11-24 08:41:04.824533 | controller |
2025-11-24 08:41:04.824578 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-11-24 08:41:04.824588 | controller | Monday 24 November 2025  08:41:04 +0000 (0:00:00.028)       0:00:15.123 *******
2025-11-24 08:41:04.824606 | controller | skipping: [instance]
2025-11-24 08:41:04.824724 | controller |
2025-11-24 08:41:04.824748 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-11-24 08:41:04.852367 | controller | Monday 24 November 2025  08:41:04 +0000 (0:00:00.028)       0:00:15.151 *******
2025-11-24 08:41:04.852389 | controller | skipping: [instance]
2025-11-24 08:41:04.889219 | controller |
2025-11-24 08:41:04.889242 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-11-24 08:41:04.889252 | controller | Monday 24 November 2025  08:41:04 +0000 (0:00:00.027)       0:00:15.179 *******
2025-11-24 08:41:04.889263 | controller | ok: [instance]
2025-11-24 08:41:04.889496 | controller |
2025-11-24 08:41:04.889513 | controller | TASK [repo_setup : Create download directory] **********************************
2025-11-24 08:41:04.915864 | controller | Monday 24 November 2025  08:41:04 +0000 (0:00:00.037)       0:00:15.216 *******
2025-11-24 08:41:04.915885 | controller | skipping: [instance]
2025-11-24 08:41:04.916035 | controller |
2025-11-24 08:41:04.916199 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-11-24 08:41:04.916330 | controller | Monday 24 November 2025  08:41:04 +0000 (0:00:00.026)       0:00:15.243 *******
2025-11-24 08:41:04.941596 | controller | skipping: [instance]
2025-11-24 08:41:04.941751 | controller |
2025-11-24 08:41:04.941897 | controller | TASK [Download the RPM] ********************************************************
2025-11-24 08:41:04.942041 | controller | Monday 24 November 2025  08:41:04 +0000 (0:00:00.025)       0:00:15.269 *******
2025-11-24 08:41:04.967558 | controller | skipping: [instance]
2025-11-24 08:41:04.967714 | controller |
2025-11-24 08:41:04.967866 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-11-24 08:41:04.968012 | controller | Monday 24 November 2025  08:41:04 +0000 (0:00:00.025)       0:00:15.295 *******
2025-11-24 08:41:04.993889 | controller | skipping: [instance]
2025-11-24 08:41:04.994019 | controller |
2025-11-24 08:41:04.994191 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-11-24 08:41:04.994324 | controller | Monday 24 November 2025  08:41:04 +0000 (0:00:00.026)       0:00:15.321 *******
2025-11-24 08:41:05.020444 | controller | skipping: [instance]
2025-11-24 08:41:05.020555 | controller |
2025-11-24 08:41:05.020712 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-11-24 08:41:05.020799 | controller | Monday 24 November 2025  08:41:05 +0000 (0:00:00.026)       0:00:15.348 *******
2025-11-24 08:41:05.048007 | controller | skipping: [instance]
2025-11-24 08:41:05.048154 | controller |
2025-11-24 08:41:05.048344 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-11-24 08:41:05.048464 | controller | Monday 24 November 2025  08:41:05 +0000 (0:00:00.027)       0:00:15.375 *******
2025-11-24 08:41:05.073596 | controller | skipping: [instance]
2025-11-24 08:41:05.073727 | controller |
2025-11-24 08:41:05.073878 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-11-24 08:41:05.074017 | controller | Monday 24 November 2025  08:41:05 +0000 (0:00:00.025)       0:00:15.401 *******
2025-11-24 08:41:05.254773 | controller | ok: [instance]
2025-11-24 08:41:05.254900 | controller |
2025-11-24 08:41:05.255024 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-11-24 08:41:05.255157 | controller | Monday 24 November 2025  08:41:05 +0000 (0:00:00.181)       0:00:15.582 *******
2025-11-24 08:41:05.458525 | controller | changed: [instance]
2025-11-24 08:41:05.458577 | controller |
2025-11-24 08:41:05.458590 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-11-24 08:41:05.458708 | controller | Monday 24 November 2025  08:41:05 +0000 (0:00:00.203)       0:00:15.785 *******
2025-11-24 08:41:05.680233 | controller | changed: [instance]
2025-11-24 08:41:05.701826 | controller |
2025-11-24 08:41:05.701844 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-11-24 08:41:05.701851 | controller | Monday 24 November 2025  08:41:05 +0000 (0:00:00.221)       0:00:16.007 *******
2025-11-24 08:41:05.701860 | controller | skipping: [instance]
2025-11-24 08:41:05.723594 | controller |
2025-11-24 08:41:05.723635 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-11-24 08:41:05.723647 | controller | Monday 24 November 2025  08:41:05 +0000 (0:00:00.021)       0:00:16.029 *******
2025-11-24 08:41:05.723663 | controller | skipping: [instance]
2025-11-24 08:41:05.723705 | controller |
2025-11-24 08:41:05.723829 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-11-24 08:41:05.723853 | controller | Monday 24 November 2025  08:41:05 +0000 (0:00:00.021)       0:00:16.051 *******
2025-11-24 08:41:05.745590 | controller | skipping: [instance]
2025-11-24 08:41:05.745621 | controller |
2025-11-24 08:41:05.745630 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-11-24 08:41:05.745641 | controller | Monday 24 November 2025  08:41:05 +0000 (0:00:00.021)       0:00:16.072 *******
2025-11-24 08:41:05.769252 | controller | skipping: [instance]
2025-11-24 08:41:05.791534 | controller |
2025-11-24 08:41:05.791558 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-11-24 08:41:05.791566 | controller | Monday 24 November 2025  08:41:05 +0000 (0:00:00.023)       0:00:16.096 *******
2025-11-24 08:41:05.791576 | controller | skipping: [instance]
2025-11-24 08:41:05.791600 | controller |
2025-11-24 08:41:05.791608 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-11-24 08:41:05.791685 | controller | Monday 24 November 2025  08:41:05 +0000 (0:00:00.022)       0:00:16.119 *******
2025-11-24 08:41:05.813486 | controller | skipping: [instance]
2025-11-24 08:41:05.813656 | controller |
2025-11-24 08:41:05.813677 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-11-24 08:41:05.830111 | controller | Monday 24 November 2025  08:41:05 +0000 (0:00:00.021)       0:00:16.140 *******
2025-11-24 08:41:05.830156 | controller | skipping: [instance]
2025-11-24 08:41:05.830334 | controller |
2025-11-24 08:41:05.830553 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-11-24 08:41:05.830730 | controller | Monday 24 November 2025  08:41:05 +0000 (0:00:00.016)       0:00:16.157 *******
2025-11-24 08:41:05.859367 | controller | skipping: [instance]
2025-11-24 08:41:05.859543 | controller |
2025-11-24 08:41:05.859691 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-11-24 08:41:05.859838 | controller | Monday 24 November 2025  08:41:05 +0000 (0:00:00.029)       0:00:16.187 *******
2025-11-24 08:41:05.876689 | controller | skipping: [instance]
2025-11-24 08:41:05.876842 | controller |
2025-11-24 08:41:05.876984 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-11-24 08:41:05.877142 | controller | Monday 24 November 2025  08:41:05 +0000 (0:00:00.017)       0:00:16.204 *******
2025-11-24 08:41:05.897170 | controller | skipping: [instance]
2025-11-24 08:41:05.897465 | controller |
2025-11-24 08:41:05.897814 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-11-24 08:41:05.898010 | controller | Monday 24 November 2025  08:41:05 +0000 (0:00:00.020)       0:00:16.224 *******
2025-11-24 08:41:05.925664 | controller | skipping: [instance]
2025-11-24 08:41:05.925788 | controller |
2025-11-24 08:41:05.925977 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-11-24 08:41:05.926081 | controller | Monday 24 November 2025  08:41:05 +0000 (0:00:00.028)       0:00:16.253 *******
2025-11-24 08:41:05.954397 | controller | skipping: [instance]
2025-11-24 08:41:05.954576 | controller |
2025-11-24 08:41:05.954737 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-11-24 08:41:05.954853 | controller | Monday 24 November 2025  08:41:05 +0000 (0:00:00.028)       0:00:16.282 *******
2025-11-24 08:41:05.983535 | controller | skipping: [instance]
2025-11-24 08:41:05.983701 | controller |
2025-11-24 08:41:05.983857 | controller | TASK [test_deps : Install additional packages] *********************************
2025-11-24 08:41:05.984015 | controller | Monday 24 November 2025  08:41:05 +0000 (0:00:00.029)       0:00:16.311 *******
2025-11-24 08:41:06.013649 | controller | skipping: [instance]
2025-11-24 08:41:06.013818 | controller |
2025-11-24 08:41:06.013978 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-11-24 08:41:06.014142 | controller | Monday 24 November 2025  08:41:06 +0000 (0:00:00.030)       0:00:16.341 *******
2025-11-24 08:41:52.505819 | controller | ok: [instance]
2025-11-24 08:41:52.505902 | controller |
2025-11-24 08:41:52.505915 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-11-24 08:41:52.506070 | controller | Monday 24 November 2025  08:41:52 +0000 (0:00:46.486)       0:01:02.827 *******
2025-11-24 08:41:53.745524 | controller | ok: [instance]
2025-11-24 08:41:53.745642 | controller |
2025-11-24 08:41:53.745771 | controller | TASK [test_deps : Install extra packages] **************************************
2025-11-24 08:41:53.745894 | controller | Monday 24 November 2025  08:41:53 +0000 (0:00:01.244)       0:01:04.072 *******
2025-11-24 08:41:53.785673 | controller | skipping: [instance]
2025-11-24 08:41:53.785729 | controller |
2025-11-24 08:41:53.785836 | controller | TASK [Ensure CRC is started] ***************************************************
2025-11-24 08:41:53.785993 | controller | Monday 24 November 2025  08:41:53 +0000 (0:00:00.040)       0:01:04.112 *******
2025-11-24 08:44:37.223276 | controller | changed: [instance]
2025-11-24 08:44:37.539723 | controller |
2025-11-24 08:44:37.539755 | controller | TASK [Inject crc hostname/IP in hosts] *****************************************
2025-11-24 08:44:37.539763 | controller | Monday 24 November 2025  08:44:37 +0000 (0:02:43.437)       0:03:47.549 *******
2025-11-24 08:44:37.539773 | controller | changed: [instance]
2025-11-24 08:44:37.594061 | controller |
2025-11-24 08:44:37.594094 | controller | PLAY RECAP *********************************************************************
2025-11-24 08:44:37.594102 | controller | instance                   : ok=21   changed=11   unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-11-24 08:44:37.594108 | controller |
2025-11-24 08:44:37.594130 | controller | Monday 24 November 2025  08:44:37 +0000 (0:00:00.314)       0:03:47.864 *******
2025-11-24 08:44:37.594136 | controller | ===============================================================================
2025-11-24 08:44:37.594142 | controller | Ensure CRC is started ------------------------------------------------- 163.44s
2025-11-24 08:44:37.594147 | controller | test_deps : Install selinux python libs -------------------------------- 46.49s
2025-11-24 08:44:37.594152 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.86s
2025-11-24 08:44:37.594157 | controller | test_deps : Install python yaml libs ------------------------------------ 1.24s
2025-11-24 08:44:37.594162 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.05s
2025-11-24 08:44:37.594167 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.84s
2025-11-24 08:44:37.594172 | controller | Gathering Facts --------------------------------------------------------- 0.83s
2025-11-24 08:44:37.594177 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.80s
2025-11-24 08:44:37.594182 | controller | repo_setup : Install repo-setup package --------------------------------- 0.69s
2025-11-24 08:44:37.594188 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.68s
2025-11-24 08:44:37.594193 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.54s
2025-11-24 08:44:37.594198 | controller | repo_setup : Ensure directories are present ----------------------------- 0.54s
2025-11-24 08:44:37.594203 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.43s
2025-11-24 08:44:37.594208 | controller | Inject crc hostname/IP in hosts ----------------------------------------- 0.31s
2025-11-24 08:44:37.594213 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.31s
2025-11-24 08:44:37.594218 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s
2025-11-24 08:44:37.594223 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.22s
2025-11-24 08:44:37.594228 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.20s
2025-11-24 08:44:37.594233 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.18s
2025-11-24 08:44:37.594239 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-11-24 08:44:37.594249 | controller | INFO     Running default > converge
2025-11-24 08:44:38.015540 | controller |
2025-11-24 08:44:38.015969 | controller | PLAY [Converge] ****************************************************************
2025-11-24 08:44:38.016110 | controller |
2025-11-24 08:44:38.016254 | controller | TASK [Gathering Facts] *********************************************************
2025-11-24 08:44:38.016416 | controller | Monday 24 November 2025  08:44:38 +0000 (0:00:00.013)       0:00:00.013 *******
2025-11-24 08:44:38.895714 | controller | ok: [instance]
2025-11-24 08:44:38.920822 | controller |
2025-11-24 08:44:38.920846 | controller | TASK [Check if new ssh keypair exists] *****************************************
2025-11-24 08:44:38.920854 | controller | Monday 24 November 2025  08:44:38 +0000 (0:00:00.880)       0:00:00.893 *******
2025-11-24 08:44:38.920863 | controller |
2025-11-24 08:44:39.201571 | controller | TASK [recognize_ssh_keypair : Check if id_ed25519 key exists] ******************
2025-11-24 08:44:39.201789 | controller | Monday 24 November 2025  08:44:38 +0000 (0:00:00.025)       0:00:00.919 *******
2025-11-24 08:44:39.201802 | controller | ok: [instance]
2025-11-24 08:44:39.201808 | controller |
2025-11-24 08:44:39.201814 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair exists] ******************
2025-11-24 08:44:39.201821 | controller | Monday 24 November 2025  08:44:39 +0000 (0:00:00.280)       0:00:01.199 *******
2025-11-24 08:44:39.220438 | controller | ok: [instance]
2025-11-24 08:44:39.220760 | controller |
2025-11-24 08:44:39.241890 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair does not exists] *********
2025-11-24 08:44:39.241935 | controller | Monday 24 November 2025  08:44:39 +0000 (0:00:00.019)       0:00:01.219 *******
2025-11-24 08:44:39.241946 | controller | skipping: [instance]
2025-11-24 08:44:39.242025 | controller |
2025-11-24 08:44:39.242117 | controller | TASK [Add the crc host dynamically] ********************************************
2025-11-24 08:44:39.242246 | controller | Monday 24 November 2025  08:44:39 +0000 (0:00:00.021)       0:00:01.240 *******
2025-11-24 08:44:39.260939 | controller | changed: [instance]
2025-11-24 08:44:39.293956 | controller |
2025-11-24 08:44:39.293977 | controller | TASK [Run ci_local_storage role] ***********************************************
2025-11-24 08:44:39.293985 | controller | Monday 24 November 2025  08:44:39 +0000 (0:00:00.018)       0:00:01.259 *******
2025-11-24 08:44:39.293994 | controller |
2025-11-24 08:44:39.602145 | controller | TASK [ci_local_storage : Create role needed directories] ***********************
2025-11-24 08:44:39.602179 | controller | Monday 24 November 2025  08:44:39 +0000 (0:00:00.033)       0:00:01.292 *******
2025-11-24 08:44:39.602190 | controller | changed: [instance]
2025-11-24 08:44:39.602323 | controller |
2025-11-24 08:44:39.602443 | controller | TASK [ci_local_storage : Create the cifmw_cls_namespace namespace"] ************
2025-11-24 08:44:39.602468 | controller | Monday 24 November 2025  08:44:39 +0000 (0:00:00.307)       0:00:01.600 *******
2025-11-24 08:44:40.421347 | controller | changed: [instance]
2025-11-24 08:44:40.880209 | controller |
2025-11-24 08:44:40.880251 | controller | TASK [ci_local_storage : Save storage manifests as artifacts] ******************
2025-11-24 08:44:40.880259 | controller | Monday 24 November 2025  08:44:40 +0000 (0:00:00.816)       0:00:02.417 *******
2025-11-24 08:44:40.880275 | controller | changed: [instance]
2025-11-24 08:44:41.642522 | controller |
2025-11-24 08:44:41.642557 | controller | TASK [ci_local_storage : Get k8s nodes] ****************************************
2025-11-24 08:44:41.642566 | controller | Monday 24 November 2025  08:44:40 +0000 (0:00:00.458)       0:00:02.876 *******
2025-11-24 08:44:41.642579 | controller | ok: [instance]
2025-11-24 08:44:41.642687 | controller |
2025-11-24 08:44:41.642697 | controller | TASK [ci_local_storage : Fetch hostnames for all hosts] ************************
2025-11-24 08:44:41.642791 | controller | Monday 24 November 2025  08:44:41 +0000 (0:00:00.764)       0:00:03.641 *******
2025-11-24 08:44:43.277328 | controller | changed: [instance] => (item=instance)
2025-11-24 08:44:43.277703 | controller | changed: [instance -> crc] => (item=crc)
2025-11-24 08:44:43.333968 | controller |
2025-11-24 08:44:43.334009 | controller | TASK [ci_local_storage : Set the hosts k8s ansible hosts] **********************
2025-11-24 08:44:43.334019 | controller | Monday 24 November 2025  08:44:43 +0000 (0:00:01.634)       0:00:05.275 *******
2025-11-24 08:44:43.334031 | controller | ok: [instance]
2025-11-24 08:44:43.334061 | controller |
2025-11-24 08:44:43.334264 | controller | TASK [ci_local_storage : Apply the storage class manifests] ********************
2025-11-24 08:44:43.973373 | controller | Monday 24 November 2025  08:44:43 +0000 (0:00:00.056)       0:00:05.332 *******
2025-11-24 08:44:43.973414 | controller | changed: [instance]
2025-11-24 08:44:44.005918 | controller |
2025-11-24 08:44:44.005984 | controller | TASK [ci_local_storage : Create directories on worker node] ********************
2025-11-24 08:44:44.005994 | controller | Monday 24 November 2025  08:44:43 +0000 (0:00:00.639)       0:00:05.971 *******
2025-11-24 08:44:44.006009 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_local_storage/tasks/worker_node_dirs.yml for instance => (item=crc)
2025-11-24 08:44:44.006040 | controller |
2025-11-24 08:44:44.006215 | controller | TASK [ci_local_storage : Perform action in the PV directory] *******************
2025-11-24 08:44:44.006245 | controller | Monday 24 November 2025  08:44:44 +0000 (0:00:00.032)       0:00:06.004 *******
2025-11-24 08:44:51.663993 | controller | changed: [instance -> crc] => (item=1)
2025-11-24 08:44:52.040110 | controller | changed: [instance -> crc] => (item=2)
2025-11-24 08:44:52.040140 | controller | changed: [instance -> crc] => (item=3)
2025-11-24 08:44:52.040148 | controller | changed: [instance -> crc] => (item=4)
2025-11-24 08:44:52.040153 | controller | changed: [instance -> crc] => (item=5)
2025-11-24 08:44:52.040159 | controller | changed: [instance -> crc] => (item=6)
2025-11-24 08:44:52.040164 | controller | changed: [instance -> crc] => (item=7)
2025-11-24 08:44:52.040176 | controller | changed: [instance -> crc] => (item=8)
2025-11-24 08:44:52.040182 | controller | changed: [instance -> crc] => (item=9)
2025-11-24 08:44:52.040187 | controller | changed: [instance -> crc] => (item=10)
2025-11-24 08:44:52.040192 | controller |
2025-11-24 08:44:52.040198 | controller | TASK [ci_local_storage : Generate pv related storage manifest file] ************
2025-11-24 08:44:52.040203 | controller | Monday 24 November 2025  08:44:51 +0000 (0:00:07.656)       0:00:13.660 *******
2025-11-24 08:44:52.040213 | controller | changed: [instance]
2025-11-24 08:44:52.854948 | controller |
2025-11-24 08:44:52.854986 | controller | TASK [ci_local_storage : Apply pv related storage manifest file] ***************
2025-11-24 08:44:52.854994 | controller | Monday 24 November 2025  08:44:52 +0000 (0:00:00.377)       0:00:14.038 *******
2025-11-24 08:44:52.855005 | controller | changed: [instance]
2025-11-24 08:45:00.534414 | controller |
2025-11-24 08:45:00.534476 | controller | TASK [Not create directories on crc] *******************************************
2025-11-24 08:45:00.534485 | controller | Monday 24 November 2025  08:44:52 +0000 (0:00:00.814)       0:00:14.853 *******
2025-11-24 08:45:00.534496 | controller | ok: [instance -> crc] => (item=1)
2025-11-24 08:45:00.557482 | controller | ok: [instance -> crc] => (item=2)
2025-11-24 08:45:00.557510 | controller | ok: [instance -> crc] => (item=3)
2025-11-24 08:45:00.557517 | controller | ok: [instance -> crc] => (item=4)
2025-11-24 08:45:00.557523 | controller | ok: [instance -> crc] => (item=5)
2025-11-24 08:45:00.557528 | controller | ok: [instance -> crc] => (item=6)
2025-11-24 08:45:00.557533 | controller | ok: [instance -> crc] => (item=7)
2025-11-24 08:45:00.557539 | controller | ok: [instance -> crc] => (item=8)
2025-11-24 08:45:00.557544 | controller | ok: [instance -> crc] => (item=9)
2025-11-24 08:45:00.557550 | controller | ok: [instance -> crc] => (item=10)
2025-11-24 08:45:00.557556 | controller |
2025-11-24 08:45:00.557562 | controller | TASK [Fail if we have a change] ************************************************
2025-11-24 08:45:00.557568 | controller | Monday 24 November 2025  08:45:00 +0000 (0:00:07.677)       0:00:22.531 *******
2025-11-24 08:45:00.557592 | controller | skipping: [instance]
2025-11-24 08:45:01.199531 | controller |
2025-11-24 08:45:01.199598 | controller | TASK [Get all pvs] *************************************************************
2025-11-24 08:45:01.199609 | controller | Monday 24 November 2025  08:45:00 +0000 (0:00:00.024)       0:00:22.556 *******
2025-11-24 08:45:01.199625 | controller | ok: [instance]
2025-11-24 08:45:01.199717 | controller |
2025-11-24 08:45:01.199749 | controller | TASK [Assert that we created the wanted number of pvs] *************************
2025-11-24 08:45:01.199762 | controller | Monday 24 November 2025  08:45:01 +0000 (0:00:00.641)       0:00:23.197 *******
2025-11-24 08:45:01.234330 | controller | ok: [instance] => changed=false
2025-11-24 08:45:01.234394 | controller |   msg: All assertions passed
2025-11-24 08:45:01.234403 | controller |
2025-11-24 08:45:01.234412 | controller | TASK [Gather names from pvs] ***************************************************
2025-11-24 08:45:01.269782 | controller | Monday 24 November 2025  08:45:01 +0000 (0:00:00.035)       0:00:23.232 *******
2025-11-24 08:45:01.269855 | controller | ok: [instance]
2025-11-24 08:45:01.269947 | controller |
2025-11-24 08:45:01.269985 | controller | TASK [Gather computed PV names] ************************************************
2025-11-24 08:45:01.370235 | controller | Monday 24 November 2025  08:45:01 +0000 (0:00:00.035)       0:00:23.268 *******
2025-11-24 08:45:01.370300 | controller | ok: [instance] => (item=1)
2025-11-24 08:45:01.456385 | controller | ok: [instance] => (item=2)
2025-11-24 08:45:01.456450 | controller | ok: [instance] => (item=3)
2025-11-24 08:45:01.456459 | controller | ok: [instance] => (item=4)
2025-11-24 08:45:01.456464 | controller | ok: [instance] => (item=5)
2025-11-24 08:45:01.456470 | controller | ok: [instance] => (item=6)
2025-11-24 08:45:01.456475 | controller | ok: [instance] => (item=7)
2025-11-24 08:45:01.456480 | controller | ok: [instance] => (item=8)
2025-11-24 08:45:01.456485 | controller | ok: [instance] => (item=9)
2025-11-24 08:45:01.456491 | controller | ok: [instance] => (item=10)
2025-11-24 08:45:01.456496 | controller |
2025-11-24 08:45:01.456502 | controller | TASK [Assert that the pvs names are correct] ***********************************
2025-11-24 08:45:01.456509 | controller | Monday 24 November 2025  08:45:01 +0000 (0:00:00.098)       0:00:23.367 *******
2025-11-24 08:45:01.456522 | controller | ok: [instance] => (item=['molecule-storage01-crc', 'molecule-storage01-crc']) => changed=false
2025-11-24 08:45:01.488871 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.488910 | controller |   item:
2025-11-24 08:45:01.488919 | controller |   - molecule-storage01-crc
2025-11-24 08:45:01.488926 | controller |   - molecule-storage01-crc
2025-11-24 08:45:01.488942 | controller |   msg: All assertions passed
2025-11-24 08:45:01.488950 | controller | ok: [instance] => (item=['molecule-storage02-crc', 'molecule-storage02-crc']) => changed=false
2025-11-24 08:45:01.488957 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.488964 | controller |   item:
2025-11-24 08:45:01.488970 | controller |   - molecule-storage02-crc
2025-11-24 08:45:01.488976 | controller |   - molecule-storage02-crc
2025-11-24 08:45:01.488983 | controller |   msg: All assertions passed
2025-11-24 08:45:01.488989 | controller | ok: [instance] => (item=['molecule-storage03-crc', 'molecule-storage03-crc']) => changed=false
2025-11-24 08:45:01.488996 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.489003 | controller |   item:
2025-11-24 08:45:01.489009 | controller |   - molecule-storage03-crc
2025-11-24 08:45:01.489016 | controller |   - molecule-storage03-crc
2025-11-24 08:45:01.489022 | controller |   msg: All assertions passed
2025-11-24 08:45:01.489028 | controller | ok: [instance] => (item=['molecule-storage04-crc', 'molecule-storage04-crc']) => changed=false
2025-11-24 08:45:01.489035 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.489041 | controller |   item:
2025-11-24 08:45:01.489048 | controller |   - molecule-storage04-crc
2025-11-24 08:45:01.489054 | controller |   - molecule-storage04-crc
2025-11-24 08:45:01.489060 | controller |   msg: All assertions passed
2025-11-24 08:45:01.489067 | controller | ok: [instance] => (item=['molecule-storage05-crc', 'molecule-storage05-crc']) => changed=false
2025-11-24 08:45:01.489073 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.489079 | controller |   item:
2025-11-24 08:45:01.489086 | controller |   - molecule-storage05-crc
2025-11-24 08:45:01.489092 | controller |   - molecule-storage05-crc
2025-11-24 08:45:01.489098 | controller |   msg: All assertions passed
2025-11-24 08:45:01.489105 | controller | ok: [instance] => (item=['molecule-storage06-crc', 'molecule-storage06-crc']) => changed=false
2025-11-24 08:45:01.489111 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.489117 | controller |   item:
2025-11-24 08:45:01.489124 | controller |   - molecule-storage06-crc
2025-11-24 08:45:01.489130 | controller |   - molecule-storage06-crc
2025-11-24 08:45:01.489137 | controller |   msg: All assertions passed
2025-11-24 08:45:01.489143 | controller | ok: [instance] => (item=['molecule-storage07-crc', 'molecule-storage07-crc']) => changed=false
2025-11-24 08:45:01.489150 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.489156 | controller |   item:
2025-11-24 08:45:01.489162 | controller |   - molecule-storage07-crc
2025-11-24 08:45:01.489169 | controller |   - molecule-storage07-crc
2025-11-24 08:45:01.489175 | controller |   msg: All assertions passed
2025-11-24 08:45:01.489182 | controller | ok: [instance] => (item=['molecule-storage08-crc', 'molecule-storage08-crc']) => changed=false
2025-11-24 08:45:01.489188 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.489197 | controller |   item:
2025-11-24 08:45:01.489203 | controller |   - molecule-storage08-crc
2025-11-24 08:45:01.489210 | controller |   - molecule-storage08-crc
2025-11-24 08:45:01.489216 | controller |   msg: All assertions passed
2025-11-24 08:45:01.489222 | controller | ok: [instance] => (item=['molecule-storage09-crc', 'molecule-storage09-crc']) => changed=false
2025-11-24 08:45:01.489229 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.489235 | controller |   item:
2025-11-24 08:45:01.489242 | controller |   - molecule-storage09-crc
2025-11-24 08:45:01.489248 | controller |   - molecule-storage09-crc
2025-11-24 08:45:01.489254 | controller |   msg: All assertions passed
2025-11-24 08:45:01.489261 | controller | ok: [instance] => (item=['molecule-storage10-crc', 'molecule-storage10-crc']) => changed=false
2025-11-24 08:45:01.489267 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.489274 | controller |   item:
2025-11-24 08:45:01.489280 | controller |   - molecule-storage10-crc
2025-11-24 08:45:01.489286 | controller |   - molecule-storage10-crc
2025-11-24 08:45:01.489292 | controller |   msg: All assertions passed
2025-11-24 08:45:01.489299 | controller |
2025-11-24 08:45:01.489306 | controller | TASK [Gather mountpoints from pvs] *********************************************
2025-11-24 08:45:01.489331 | controller | Monday 24 November 2025  08:45:01 +0000 (0:00:00.081)       0:00:23.449 *******
2025-11-24 08:45:01.489346 | controller | ok: [instance]
2025-11-24 08:45:01.489531 | controller |
2025-11-24 08:45:01.489690 | controller | TASK [Gather computed mountpoints] *********************************************
2025-11-24 08:45:01.489828 | controller | Monday 24 November 2025  08:45:01 +0000 (0:00:00.038)       0:00:23.487 *******
2025-11-24 08:45:01.574143 | controller | ok: [instance] => (item=1)
2025-11-24 08:45:01.574332 | controller | ok: [instance] => (item=2)
2025-11-24 08:45:01.574507 | controller | ok: [instance] => (item=3)
2025-11-24 08:45:01.574654 | controller | ok: [instance] => (item=4)
2025-11-24 08:45:01.574801 | controller | ok: [instance] => (item=5)
2025-11-24 08:45:01.574937 | controller | ok: [instance] => (item=6)
2025-11-24 08:45:01.575068 | controller | ok: [instance] => (item=7)
2025-11-24 08:45:01.575200 | controller | ok: [instance] => (item=8)
2025-11-24 08:45:01.575338 | controller | ok: [instance] => (item=9)
2025-11-24 08:45:01.575496 | controller | ok: [instance] => (item=10)
2025-11-24 08:45:01.575629 | controller |
2025-11-24 08:45:01.575761 | controller | TASK [Assert that the mountpoints in the pv are correct] ***********************
2025-11-24 08:45:01.575900 | controller | Monday 24 November 2025  08:45:01 +0000 (0:00:00.085)       0:00:23.573 *******
2025-11-24 08:45:01.642450 | controller | ok: [instance] => (item=['/mnt/openstack/pv01', '/mnt/openstack/pv01']) => changed=false
2025-11-24 08:45:01.642518 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.642528 | controller |   item:
2025-11-24 08:45:01.642535 | controller |   - /mnt/openstack/pv01
2025-11-24 08:45:01.642543 | controller |   - /mnt/openstack/pv01
2025-11-24 08:45:01.642634 | controller |   msg: All assertions passed
2025-11-24 08:45:01.642741 | controller | ok: [instance] => (item=['/mnt/openstack/pv02', '/mnt/openstack/pv02']) => changed=false
2025-11-24 08:45:01.642841 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.642935 | controller |   item:
2025-11-24 08:45:01.643028 | controller |   - /mnt/openstack/pv02
2025-11-24 08:45:01.643123 | controller |   - /mnt/openstack/pv02
2025-11-24 08:45:01.643216 | controller |   msg: All assertions passed
2025-11-24 08:45:01.643325 | controller | ok: [instance] => (item=['/mnt/openstack/pv03', '/mnt/openstack/pv03']) => changed=false
2025-11-24 08:45:01.643445 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.643534 | controller |   item:
2025-11-24 08:45:01.643629 | controller |   - /mnt/openstack/pv03
2025-11-24 08:45:01.643722 | controller |   - /mnt/openstack/pv03
2025-11-24 08:45:01.643815 | controller |   msg: All assertions passed
2025-11-24 08:45:01.643917 | controller | ok: [instance] => (item=['/mnt/openstack/pv04', '/mnt/openstack/pv04']) => changed=false
2025-11-24 08:45:01.644011 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.644105 | controller |   item:
2025-11-24 08:45:01.644204 | controller |   - /mnt/openstack/pv04
2025-11-24 08:45:01.644331 | controller |   - /mnt/openstack/pv04
2025-11-24 08:45:01.644439 | controller |   msg: All assertions passed
2025-11-24 08:45:01.644528 | controller | ok: [instance] => (item=['/mnt/openstack/pv05', '/mnt/openstack/pv05']) => changed=false
2025-11-24 08:45:01.644624 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.644710 | controller |   item:
2025-11-24 08:45:01.644801 | controller |   - /mnt/openstack/pv05
2025-11-24 08:45:01.644894 | controller |   - /mnt/openstack/pv05
2025-11-24 08:45:01.644984 | controller |   msg: All assertions passed
2025-11-24 08:45:01.645090 | controller | ok: [instance] => (item=['/mnt/openstack/pv06', '/mnt/openstack/pv06']) => changed=false
2025-11-24 08:45:01.645190 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.645273 | controller |   item:
2025-11-24 08:45:01.645402 | controller |   - /mnt/openstack/pv06
2025-11-24 08:45:01.645506 | controller |   - /mnt/openstack/pv06
2025-11-24 08:45:01.645596 | controller |   msg: All assertions passed
2025-11-24 08:45:01.645715 | controller | ok: [instance] => (item=['/mnt/openstack/pv07', '/mnt/openstack/pv07']) => changed=false
2025-11-24 08:45:01.645803 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.645893 | controller |   item:
2025-11-24 08:45:01.645987 | controller |   - /mnt/openstack/pv07
2025-11-24 08:45:01.646085 | controller |   - /mnt/openstack/pv07
2025-11-24 08:45:01.646170 | controller |   msg: All assertions passed
2025-11-24 08:45:01.646273 | controller | ok: [instance] => (item=['/mnt/openstack/pv08', '/mnt/openstack/pv08']) => changed=false
2025-11-24 08:45:01.646367 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.646484 | controller |   item:
2025-11-24 08:45:01.646595 | controller |   - /mnt/openstack/pv08
2025-11-24 08:45:01.646689 | controller |   - /mnt/openstack/pv08
2025-11-24 08:45:01.646787 | controller |   msg: All assertions passed
2025-11-24 08:45:01.646881 | controller | ok: [instance] => (item=['/mnt/openstack/pv09', '/mnt/openstack/pv09']) => changed=false
2025-11-24 08:45:01.646968 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.647066 | controller |   item:
2025-11-24 08:45:01.647159 | controller |   - /mnt/openstack/pv09
2025-11-24 08:45:01.647270 | controller |   - /mnt/openstack/pv09
2025-11-24 08:45:01.647362 | controller |   msg: All assertions passed
2025-11-24 08:45:01.647497 | controller | ok: [instance] => (item=['/mnt/openstack/pv10', '/mnt/openstack/pv10']) => changed=false
2025-11-24 08:45:01.647587 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.647688 | controller |   item:
2025-11-24 08:45:01.647773 | controller |   - /mnt/openstack/pv10
2025-11-24 08:45:01.647859 | controller |   - /mnt/openstack/pv10
2025-11-24 08:45:01.647953 | controller |   msg: All assertions passed
2025-11-24 08:45:01.648037 | controller |
2025-11-24 08:45:01.648137 | controller | TASK [Assert that the storage class is correct] ********************************
2025-11-24 08:45:01.648227 | controller | Monday 24 November 2025  08:45:01 +0000 (0:00:00.067)       0:00:23.640 *******
2025-11-24 08:45:01.712846 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-11-24 08:45:01.712960 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.713053 | controller |   item: molecule-storage
2025-11-24 08:45:01.713149 | controller |   msg: All assertions passed
2025-11-24 08:45:01.713250 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-11-24 08:45:01.713354 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.713493 | controller |   item: molecule-storage
2025-11-24 08:45:01.713580 | controller |   msg: All assertions passed
2025-11-24 08:45:01.713674 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-11-24 08:45:01.713768 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.713857 | controller |   item: molecule-storage
2025-11-24 08:45:01.713953 | controller |   msg: All assertions passed
2025-11-24 08:45:01.714047 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-11-24 08:45:01.714140 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.714241 | controller |   item: molecule-storage
2025-11-24 08:45:01.714335 | controller |   msg: All assertions passed
2025-11-24 08:45:01.714456 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-11-24 08:45:01.714540 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.714639 | controller |   item: molecule-storage
2025-11-24 08:45:01.714737 | controller |   msg: All assertions passed
2025-11-24 08:45:01.714830 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-11-24 08:45:01.714939 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.715031 | controller |   item: molecule-storage
2025-11-24 08:45:01.715128 | controller |   msg: All assertions passed
2025-11-24 08:45:01.715221 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-11-24 08:45:01.715303 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.715409 | controller |   item: molecule-storage
2025-11-24 08:45:01.715540 | controller |   msg: All assertions passed
2025-11-24 08:45:01.715617 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-11-24 08:45:01.715713 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.715805 | controller |   item: molecule-storage
2025-11-24 08:45:01.715899 | controller |   msg: All assertions passed
2025-11-24 08:45:01.715995 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-11-24 08:45:01.716091 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.716187 | controller |   item: molecule-storage
2025-11-24 08:45:01.716275 | controller |   msg: All assertions passed
2025-11-24 08:45:01.716384 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-11-24 08:45:01.716500 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.716593 | controller |   item: molecule-storage
2025-11-24 08:45:01.716683 | controller |   msg: All assertions passed
2025-11-24 08:45:01.716771 | controller |
2025-11-24 08:45:01.716868 | controller | TASK [Assert that the storage capacity is correct] *****************************
2025-11-24 08:45:01.716967 | controller | Monday 24 November 2025  08:45:01 +0000 (0:00:00.070)       0:00:23.711 *******
2025-11-24 08:45:01.790928 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-11-24 08:45:01.791070 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.791189 | controller |   item: 100Mi
2025-11-24 08:45:01.791300 | controller |   msg: All assertions passed
2025-11-24 08:45:01.791431 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-11-24 08:45:01.791566 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.791674 | controller |   item: 100Mi
2025-11-24 08:45:01.791767 | controller |   msg: All assertions passed
2025-11-24 08:45:01.791869 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-11-24 08:45:01.791964 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.792057 | controller |   item: 100Mi
2025-11-24 08:45:01.792150 | controller |   msg: All assertions passed
2025-11-24 08:45:01.792259 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-11-24 08:45:01.792353 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.792466 | controller |   item: 100Mi
2025-11-24 08:45:01.792560 | controller |   msg: All assertions passed
2025-11-24 08:45:01.792658 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-11-24 08:45:01.792748 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.792842 | controller |   item: 100Mi
2025-11-24 08:45:01.792933 | controller |   msg: All assertions passed
2025-11-24 08:45:01.793030 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-11-24 08:45:01.793122 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.793212 | controller |   item: 100Mi
2025-11-24 08:45:01.793328 | controller |   msg: All assertions passed
2025-11-24 08:45:01.793453 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-11-24 08:45:01.793549 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.793641 | controller |   item: 100Mi
2025-11-24 08:45:01.793734 | controller |   msg: All assertions passed
2025-11-24 08:45:01.793830 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-11-24 08:45:01.793918 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.794011 | controller |   item: 100Mi
2025-11-24 08:45:01.794104 | controller |   msg: All assertions passed
2025-11-24 08:45:01.794199 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-11-24 08:45:01.794285 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.794389 | controller |   item: 100Mi
2025-11-24 08:45:01.794505 | controller |   msg: All assertions passed
2025-11-24 08:45:01.794600 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-11-24 08:45:01.794688 | controller |   ansible_loop_var: item
2025-11-24 08:45:01.794779 | controller |   item: 100Mi
2025-11-24 08:45:01.794878 | controller |   msg: All assertions passed
2025-11-24 08:45:01.794970 | controller |
2025-11-24 08:45:01.795074 | controller | TASK [Delete the created pvs] **************************************************
2025-11-24 08:45:01.795168 | controller | Monday 24 November 2025  08:45:01 +0000 (0:00:00.077)       0:00:23.789 *******
2025-11-24 08:45:01.825297 | controller |
2025-11-24 08:45:01.825436 | controller | TASK [ci_local_storage : Get all pvs] ******************************************
2025-11-24 08:45:01.825577 | controller | Monday 24 November 2025  08:45:01 +0000 (0:00:00.034)       0:00:23.823 *******
2025-11-24 08:45:02.494903 | controller | ok: [instance]
2025-11-24 08:45:02.494978 | controller |
2025-11-24 08:45:02.495094 | controller | TASK [ci_local_storage : Export PVs as facts] **********************************
2025-11-24 08:45:02.495109 | controller | Monday 24 November 2025  08:45:02 +0000 (0:00:00.669)       0:00:24.493 *******
2025-11-24 08:45:02.535230 | controller | ok: [instance]
2025-11-24 08:45:02.535324 | controller |
2025-11-24 08:45:02.535455 | controller | TASK [ci_local_storage : Delete all pvs] ***************************************
2025-11-24 08:45:02.535559 | controller | Monday 24 November 2025  08:45:02 +0000 (0:00:00.040)       0:00:24.533 *******
2025-11-24 08:45:08.821732 | controller | changed: [instance] => (item=molecule-storage01-crc)
2025-11-24 08:45:08.821865 | controller | changed: [instance] => (item=molecule-storage02-crc)
2025-11-24 08:45:08.821872 | controller | changed: [instance] => (item=molecule-storage03-crc)
2025-11-24 08:45:08.821895 | controller | changed: [instance] => (item=molecule-storage04-crc)
2025-11-24 08:45:08.821901 | controller | changed: [instance] => (item=molecule-storage05-crc)
2025-11-24 08:45:08.821908 | controller | changed: [instance] => (item=molecule-storage06-crc)
2025-11-24 08:45:08.821917 | controller | changed: [instance] => (item=molecule-storage07-crc)
2025-11-24 08:45:08.822210 | controller | changed: [instance] => (item=molecule-storage08-crc)
2025-11-24 08:45:08.822221 | controller | changed: [instance] => (item=molecule-storage09-crc)
2025-11-24 08:45:09.461036 | controller | changed: [instance] => (item=molecule-storage10-crc)
2025-11-24 08:45:09.461094 | controller |
2025-11-24 08:45:09.461102 | controller | TASK [ci_local_storage : Get k8s nodes] ****************************************
2025-11-24 08:45:09.461108 | controller | Monday 24 November 2025  08:45:08 +0000 (0:00:06.285)       0:00:30.819 *******
2025-11-24 08:45:09.461139 | controller | ok: [instance]
2025-11-24 08:45:10.397510 | controller |
2025-11-24 08:45:10.397577 | controller | TASK [ci_local_storage : Fetch hostnames for all hosts] ************************
2025-11-24 08:45:10.397586 | controller | Monday 24 November 2025  08:45:09 +0000 (0:00:00.639)       0:00:31.459 *******
2025-11-24 08:45:10.397604 | controller | changed: [instance] => (item=instance)
2025-11-24 08:45:10.442503 | controller | changed: [instance -> crc] => (item=crc)
2025-11-24 08:45:10.442545 | controller |
2025-11-24 08:45:10.442553 | controller | TASK [ci_local_storage : Set the hosts k8s ansible hosts] **********************
2025-11-24 08:45:10.442560 | controller | Monday 24 November 2025  08:45:10 +0000 (0:00:00.936)       0:00:32.395 *******
2025-11-24 08:45:10.442574 | controller | ok: [instance]
2025-11-24 08:45:10.442731 | controller |
2025-11-24 08:45:10.442747 | controller | TASK [ci_local_storage : Delete directories on worker node] ********************
2025-11-24 08:45:10.442829 | controller | Monday 24 November 2025  08:45:10 +0000 (0:00:00.045)       0:00:32.441 *******
2025-11-24 08:45:10.472846 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_local_storage/tasks/worker_node_dirs.yml for instance => (item=crc)
2025-11-24 08:45:10.472949 | controller |
2025-11-24 08:45:10.473077 | controller | TASK [ci_local_storage : Perform action in the PV directory] *******************
2025-11-24 08:45:10.473179 | controller | Monday 24 November 2025  08:45:10 +0000 (0:00:00.030)       0:00:32.471 *******
2025-11-24 08:45:19.154843 | controller | changed: [instance -> crc] => (item=1)
2025-11-24 08:46:46.835028 | controller | changed: [instance -> crc] => (item=2)
2025-11-24 08:46:46.835067 | controller | changed: [instance -> crc] => (item=3)
2025-11-24 08:46:46.835075 | controller | changed: [instance -> crc] => (item=4)
2025-11-24 08:46:46.835091 | controller | changed: [instance -> crc] => (item=5)
2025-11-24 08:46:46.835096 | controller | changed: [instance -> crc] => (item=6)
2025-11-24 08:46:46.835102 | controller | changed: [instance -> crc] => (item=7)
2025-11-24 08:46:46.835107 | controller | changed: [instance -> crc] => (item=8)
2025-11-24 08:46:46.835112 | controller | changed: [instance -> crc] => (item=9)
2025-11-24 08:46:46.835117 | controller | changed: [instance -> crc] => (item=10)
2025-11-24 08:46:46.835123 | controller |
2025-11-24 08:46:46.835128 | controller | TASK [ci_local_storage : Remove the cifmw_cls_namespace namespace] *************
2025-11-24 08:46:46.835134 | controller | Monday 24 November 2025  08:45:19 +0000 (0:00:08.678)       0:00:41.150 *******
2025-11-24 08:46:46.835145 | controller | changed: [instance]
2025-11-24 08:46:46.835234 | controller |
2025-11-24 08:46:47.635860 | controller | TASK [Get all namespaces] ******************************************************
2025-11-24 08:46:47.635891 | controller | Monday 24 November 2025  08:46:46 +0000 (0:01:27.683)       0:02:08.833 *******
2025-11-24 08:46:47.635918 | controller | ok: [instance]
2025-11-24 08:46:47.694973 | controller |
2025-11-24 08:46:47.694998 | controller | TASK [Assert that the cifmw_cls_namespace ns is deleted] ***********************
2025-11-24 08:46:47.695005 | controller | Monday 24 November 2025  08:46:47 +0000 (0:00:00.800)       0:02:09.634 *******
2025-11-24 08:46:47.695016 | controller | ok: [instance] => changed=false
2025-11-24 08:46:47.754205 | controller |   msg: All assertions passed
2025-11-24 08:46:47.754238 | controller |
2025-11-24 08:46:47.754246 | controller | PLAY RECAP *********************************************************************
2025-11-24 08:46:47.754253 | controller | instance                   : ok=37   changed=13   unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-11-24 08:46:47.754259 | controller |
2025-11-24 08:46:47.754264 | controller | Monday 24 November 2025  08:46:47 +0000 (0:00:00.056)       0:02:09.691 *******
2025-11-24 08:46:47.754269 | controller | ===============================================================================
2025-11-24 08:46:47.754275 | controller | ci_local_storage : Remove the cifmw_cls_namespace namespace ------------ 87.68s
2025-11-24 08:46:47.754280 | controller | ci_local_storage : Perform action in the PV directory ------------------- 8.68s
2025-11-24 08:46:47.754285 | controller | Not create directories on crc ------------------------------------------- 7.68s
2025-11-24 08:46:47.754290 | controller | ci_local_storage : Perform action in the PV directory ------------------- 7.66s
2025-11-24 08:46:47.754295 | controller | ci_local_storage : Delete all pvs --------------------------------------- 6.29s
2025-11-24 08:46:47.754307 | controller | ci_local_storage : Fetch hostnames for all hosts ------------------------ 1.63s
2025-11-24 08:46:47.754312 | controller | ci_local_storage : Fetch hostnames for all hosts ------------------------ 0.94s
2025-11-24 08:46:47.754317 | controller | Gathering Facts --------------------------------------------------------- 0.88s
2025-11-24 08:46:47.754323 | controller | ci_local_storage : Create the cifmw_cls_namespace namespace" ------------ 0.82s
2025-11-24 08:46:47.754328 | controller | ci_local_storage : Apply pv related storage manifest file --------------- 0.81s
2025-11-24 08:46:47.754333 | controller | Get all namespaces ------------------------------------------------------ 0.80s
2025-11-24 08:46:47.754338 | controller | ci_local_storage : Get k8s nodes ---------------------------------------- 0.76s
2025-11-24 08:46:47.754343 | controller | ci_local_storage : Get all pvs ------------------------------------------ 0.67s
2025-11-24 08:46:47.754348 | controller | Get all pvs ------------------------------------------------------------- 0.64s
2025-11-24 08:46:47.754366 | controller | ci_local_storage : Get k8s nodes ---------------------------------------- 0.64s
2025-11-24 08:46:47.754372 | controller | ci_local_storage : Apply the storage class manifests -------------------- 0.64s
2025-11-24 08:46:47.754377 | controller | ci_local_storage : Save storage manifests as artifacts ------------------ 0.46s
2025-11-24 08:46:47.754382 | controller | ci_local_storage : Generate pv related storage manifest file ------------ 0.38s
2025-11-24 08:46:47.754387 | controller | ci_local_storage : Create role needed directories ----------------------- 0.31s
2025-11-24 08:46:47.754392 | controller | recognize_ssh_keypair : Check if id_ed25519 key exists ------------------ 0.28s
2025-11-24 08:46:47.754403 | controller | INFO     Running default > cleanup
2025-11-24 08:46:47.754939 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-11-24 08:46:47.755578 | controller | INFO     Writing /tmp/report.html report.
2025-11-24 08:46:48.279875 | controller | changed
2025-11-24 08:46:48.301202 | 
2025-11-24 08:46:48.301279 | PLAY RECAP
2025-11-24 08:46:48.301323 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-24 08:46:48.301345 | 
2025-11-24 08:46:48.374867 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-24 08:46:48.375704 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-24 08:46:48.926877 | 
2025-11-24 08:46:48.926991 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-11-24 08:46:48.948139 | 
2025-11-24 08:46:48.948222 | TASK [Filter out host if needed]
2025-11-24 08:46:48.958229 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-24 08:46:48.962818 | 
2025-11-24 08:46:48.962885 | TASK [Ensure file is present]
2025-11-24 08:46:49.359226 | controller | ok
2025-11-24 08:46:49.365082 | 
2025-11-24 08:46:49.365155 | TASK [Manage molecule report file]
2025-11-24 08:46:49.890305 | controller | changed
2025-11-24 08:46:49.895757 | 
2025-11-24 08:46:49.895824 | TASK [Check if we get ci-framework-data basedir]
2025-11-24 08:46:50.147851 | controller | ok
2025-11-24 08:46:50.153004 | 
2025-11-24 08:46:50.153082 | TASK [Create ci-framework-data log directory for zuul]
2025-11-24 08:46:50.526077 | controller | changed
2025-11-24 08:46:50.531944 | 
2025-11-24 08:46:50.532024 | TASK [Copy ci-framework interesting files]
2025-11-24 08:46:50.788283 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-11-24 08:46:51.057799 | controller | changed
2025-11-24 08:46:51.063464 | 
2025-11-24 08:46:51.063537 | TASK [Get SELinux listing]
2025-11-24 08:46:51.589049 | controller | changed
2025-11-24 08:46:51.595230 | 
2025-11-24 08:46:51.595309 | TASK [Generate log index]
2025-11-24 08:46:53.106814 | controller | changed
2025-11-24 08:46:53.112304 | 
2025-11-24 08:46:53.112415 | TASK [Get some env related data]
2025-11-24 08:46:53.923023 | controller | /home/zuul/.local/bin/ansible
2025-11-24 08:46:54.655327 | controller | changed
2025-11-24 08:46:54.682366 | 
2025-11-24 08:46:54.682494 | TASK [Generate list of logs to collect in home directory]
2025-11-24 08:46:55.076434 | controller | ok: All paths examined
2025-11-24 08:46:55.082000 | 
2025-11-24 08:46:55.082077 | LOOP [Copy logs from home directory]
2025-11-24 08:46:55.939316 | controller | changed:
2025-11-24 08:46:55.939551 | controller | {
2025-11-24 08:46:55.939580 | controller |   "atime": 1743544925.4788878,
2025-11-24 08:46:55.939600 | controller |   "ctime": 1743545329.1409318,
2025-11-24 08:46:55.939616 | controller |   "dev": 64513,
2025-11-24 08:46:55.939632 | controller |   "gid": 1000,
2025-11-24 08:46:55.939647 | controller |   "gr_name": "zuul",
2025-11-24 08:46:55.939662 | controller |   "inode": 4518807,
2025-11-24 08:46:55.939677 | controller |   "isblk": false,
2025-11-24 08:46:55.939691 | controller |   "ischr": false,
2025-11-24 08:46:55.939704 | controller |   "isdir": false,
2025-11-24 08:46:55.939718 | controller |   "isfifo": false,
2025-11-24 08:46:55.939732 | controller |   "isgid": false,
2025-11-24 08:46:55.939745 | controller |   "islnk": false,
2025-11-24 08:46:55.939759 | controller |   "isreg": true,
2025-11-24 08:46:55.939772 | controller |   "issock": false,
2025-11-24 08:46:55.939790 | controller |   "isuid": false,
2025-11-24 08:46:55.939805 | controller |   "mode": "0644",
2025-11-24 08:46:55.939818 | controller |   "mtime": 1743545329.1409318,
2025-11-24 08:46:55.939831 | controller |   "nlink": 1,
2025-11-24 08:46:55.939845 | controller |   "path": "/home/zuul/crc-setup.log",
2025-11-24 08:46:55.939861 | controller |   "pw_name": "zuul",
2025-11-24 08:46:55.939875 | controller |   "rgrp": true,
2025-11-24 08:46:55.939889 | controller |   "roth": true,
2025-11-24 08:46:55.939902 | controller |   "rusr": true,
2025-11-24 08:46:55.939916 | controller |   "size": 4108,
2025-11-24 08:46:55.939931 | controller |   "uid": 1000,
2025-11-24 08:46:55.939946 | controller |   "wgrp": false,
2025-11-24 08:46:55.939960 | controller |   "woth": false,
2025-11-24 08:46:55.939976 | controller |   "wusr": true,
2025-11-24 08:46:55.939993 | controller |   "xgrp": false,
2025-11-24 08:46:55.940020 | controller |   "xoth": false,
2025-11-24 08:46:55.940036 | controller |   "xusr": false
2025-11-24 08:46:55.940050 | controller | }
2025-11-24 08:46:56.795558 | controller | changed:
2025-11-24 08:46:56.795664 | controller | {
2025-11-24 08:46:56.795691 | controller |   "atime": 1743545331.0429637,
2025-11-24 08:46:56.795711 | controller |   "ctime": 1743545842.0111232,
2025-11-24 08:46:56.795729 | controller |   "dev": 64513,
2025-11-24 08:46:56.795745 | controller |   "gid": 1000,
2025-11-24 08:46:56.795760 | controller |   "gr_name": "zuul",
2025-11-24 08:46:56.795775 | controller |   "inode": 4194437,
2025-11-24 08:46:56.795791 | controller |   "isblk": false,
2025-11-24 08:46:56.795806 | controller |   "ischr": false,
2025-11-24 08:46:56.795821 | controller |   "isdir": false,
2025-11-24 08:46:56.795836 | controller |   "isfifo": false,
2025-11-24 08:46:56.795852 | controller |   "isgid": false,
2025-11-24 08:46:56.795867 | controller |   "islnk": false,
2025-11-24 08:46:56.795882 | controller |   "isreg": true,
2025-11-24 08:46:56.795897 | controller |   "issock": false,
2025-11-24 08:46:56.795911 | controller |   "isuid": false,
2025-11-24 08:46:56.795926 | controller |   "mode": "0644",
2025-11-24 08:46:56.795940 | controller |   "mtime": 1743545842.0111232,
2025-11-24 08:46:56.795955 | controller |   "nlink": 1,
2025-11-24 08:46:56.795969 | controller |   "path": "/home/zuul/crc-start.log",
2025-11-24 08:46:56.795983 | controller |   "pw_name": "zuul",
2025-11-24 08:46:56.795998 | controller |   "rgrp": true,
2025-11-24 08:46:56.796025 | controller |   "roth": true,
2025-11-24 08:46:56.796042 | controller |   "rusr": true,
2025-11-24 08:46:56.796057 | controller |   "size": 4023,
2025-11-24 08:46:56.796071 | controller |   "uid": 1000,
2025-11-24 08:46:56.796085 | controller |   "wgrp": false,
2025-11-24 08:46:56.796098 | controller |   "woth": false,
2025-11-24 08:46:56.796112 | controller |   "wusr": true,
2025-11-24 08:46:56.796125 | controller |   "xgrp": false,
2025-11-24 08:46:56.796138 | controller |   "xoth": false,
2025-11-24 08:46:56.796151 | controller |   "xusr": false
2025-11-24 08:46:56.796165 | controller | }
2025-11-24 08:46:57.644197 | controller | changed:
2025-11-24 08:46:57.644303 | controller | {
2025-11-24 08:46:57.644329 | controller |   "atime": 1763973613.0252862,
2025-11-24 08:46:57.644348 | controller |   "ctime": 1763973629.5203679,
2025-11-24 08:46:57.644365 | controller |   "dev": 64513,
2025-11-24 08:46:57.644399 | controller |   "gid": 1000,
2025-11-24 08:46:57.644422 | controller |   "gr_name": "zuul",
2025-11-24 08:46:57.644439 | controller |   "inode": 4685813,
2025-11-24 08:46:57.644453 | controller |   "isblk": false,
2025-11-24 08:46:57.644468 | controller |   "ischr": false,
2025-11-24 08:46:57.644481 | controller |   "isdir": false,
2025-11-24 08:46:57.644495 | controller |   "isfifo": false,
2025-11-24 08:46:57.644508 | controller |   "isgid": false,
2025-11-24 08:46:57.644521 | controller |   "islnk": false,
2025-11-24 08:46:57.644535 | controller |   "isreg": true,
2025-11-24 08:46:57.644549 | controller |   "issock": false,
2025-11-24 08:46:57.644568 | controller |   "isuid": false,
2025-11-24 08:46:57.644582 | controller |   "mode": "0644",
2025-11-24 08:46:57.644596 | controller |   "mtime": 1763973629.5203679,
2025-11-24 08:46:57.644610 | controller |   "nlink": 1,
2025-11-24 08:46:57.644624 | controller |   "path": "/home/zuul/ansible.log",
2025-11-24 08:46:57.644637 | controller |   "pw_name": "zuul",
2025-11-24 08:46:57.644654 | controller |   "rgrp": true,
2025-11-24 08:46:57.644669 | controller |   "roth": true,
2025-11-24 08:46:57.644687 | controller |   "rusr": true,
2025-11-24 08:46:57.644702 | controller |   "size": 6749,
2025-11-24 08:46:57.644718 | controller |   "uid": 1000,
2025-11-24 08:46:57.644732 | controller |   "wgrp": false,
2025-11-24 08:46:57.644747 | controller |   "woth": false,
2025-11-24 08:46:57.644760 | controller |   "wusr": true,
2025-11-24 08:46:57.644773 | controller |   "xgrp": false,
2025-11-24 08:46:57.644786 | controller |   "xoth": false,
2025-11-24 08:46:57.644800 | controller |   "xusr": false
2025-11-24 08:46:57.644814 | controller | }
2025-11-24 08:46:57.656344 | 
2025-11-24 08:46:57.656470 | TASK [Copy crio stats log file]
2025-11-24 08:46:57.669981 | controller | skipping: Conditional result was False
2025-11-24 08:46:57.675637 | 
2025-11-24 08:46:57.675702 | TASK [Get SELinux related data]
2025-11-24 08:46:57.997824 | controller | <no matches>
2025-11-24 08:46:58.199338 | controller | ERROR
2025-11-24 08:46:58.199491 | controller | {
2025-11-24 08:46:58.199524 | controller |   "delta": "0:00:00.016120",
2025-11-24 08:46:58.199545 | controller |   "end": "2025-11-24 08:46:57.999700",
2025-11-24 08:46:58.199562 | controller |   "msg": "non-zero return code",
2025-11-24 08:46:58.199577 | controller |   "rc": 1,
2025-11-24 08:46:58.199592 | controller |   "start": "2025-11-24 08:46:57.983580"
2025-11-24 08:46:58.199607 | controller | }
2025-11-24 08:46:58.199628 | controller | ERROR: Ignoring Errors
2025-11-24 08:46:58.204736 | 
2025-11-24 08:46:58.204799 | TASK [Create system configuration directory]
2025-11-24 08:46:58.528471 | controller | changed
2025-11-24 08:46:58.533690 | 
2025-11-24 08:46:58.533788 | TASK [Get some of the system configurations]
2025-11-24 08:46:59.056731 | controller | changed
2025-11-24 08:46:59.061566 | 
2025-11-24 08:46:59.061628 | TASK [Copy generated documentation if available]
2025-11-24 08:46:59.075357 | controller | skipping: Conditional result was False
2025-11-24 08:46:59.080655 | 
2025-11-24 08:46:59.080718 | TASK [Copy generated AsciiDoc documentation if available]
2025-11-24 08:46:59.093952 | controller | skipping: Conditional result was False
2025-11-24 08:46:59.099754 | 
2025-11-24 08:46:59.099824 | TASK [Compress logs bigger than 2MB]
2025-11-24 08:46:59.623422 | controller | changed
2025-11-24 08:46:59.628756 | 
2025-11-24 08:46:59.628820 | TASK [Copy files from workspace on node]
2025-11-24 08:46:59.647504 | controller | ok
2025-11-24 08:46:59.669053 | 
2025-11-24 08:46:59.669124 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-24 08:46:59.682189 | controller | skipping: Conditional result was False
2025-11-24 08:46:59.687612 | 
2025-11-24 08:46:59.687678 | TASK [fetch-output : Set log path for single node]
2025-11-24 08:46:59.715819 | controller | ok
2025-11-24 08:46:59.720837 | 
2025-11-24 08:46:59.720908 | LOOP [fetch-output : Ensure local output dirs]
2025-11-24 08:46:59.922690 | controller -> localhost | ok: "/var/lib/zuul/builds/aee287eca05245c880a6241053f34642/work/logs"
2025-11-24 08:46:59.922944 | controller -> localhost | changed: All items complete
2025-11-24 08:46:59.922976 | 
2025-11-24 08:47:00.103313 | controller -> localhost | changed: "/var/lib/zuul/builds/aee287eca05245c880a6241053f34642/work/artifacts"
2025-11-24 08:47:00.282162 | controller -> localhost | changed: "/var/lib/zuul/builds/aee287eca05245c880a6241053f34642/work/docs"
2025-11-24 08:47:00.292148 | 
2025-11-24 08:47:00.292262 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-24 08:47:00.961679 | controller | changed:
2025-11-24 08:47:00.961878 | controller | .d..t...... ./
2025-11-24 08:47:00.961910 | controller | >f+++++++++ README.html
2025-11-24 08:47:00.961933 | controller | >f+++++++++ ansible-execution.log
2025-11-24 08:47:00.961954 | controller | >f+++++++++ ansible.log
2025-11-24 08:47:00.961973 | controller | >f+++++++++ crc-setup.log
2025-11-24 08:47:00.961991 | controller | >f+++++++++ crc-start.log
2025-11-24 08:47:00.962024 | controller | >f+++++++++ dmesg.log
2025-11-24 08:47:00.962050 | controller | >f+++++++++ installed-pkgs.log
2025-11-24 08:47:00.962069 | controller | >f+++++++++ python.log
2025-11-24 08:47:00.962087 | controller | >f+++++++++ registries.conf
2025-11-24 08:47:00.962105 | controller | >f+++++++++ report.html
2025-11-24 08:47:00.962122 | controller | >f+++++++++ selinux-denials.log
2025-11-24 08:47:00.962139 | controller | >f+++++++++ selinux-listing.log
2025-11-24 08:47:00.962155 | controller | cd+++++++++ ci-framework-data/
2025-11-24 08:47:00.962172 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-11-24 08:47:00.962188 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-11-24 08:47:00.962206 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-11-24 08:47:00.962222 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/storage/
2025-11-24 08:47:00.962239 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/storage/storage-class.yaml
2025-11-24 08:47:00.962255 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/storage/storage.yaml
2025-11-24 08:47:00.962271 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-11-24 08:47:00.962288 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-11-24 08:47:00.962304 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-11-24 08:47:00.962321 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-11-24 08:47:00.962337 | controller | cd+++++++++ ci-framework-data/logs/
2025-11-24 08:47:00.962353 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-11-24 08:47:00.962369 | controller | cd+++++++++ registries.conf.d/
2025-11-24 08:47:00.962423 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-11-24 08:47:00.962447 | controller | cd+++++++++ system-config/
2025-11-24 08:47:00.962467 | controller | cd+++++++++ system-config/libvirt/
2025-11-24 08:47:00.962485 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-11-24 08:47:00.962502 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-11-24 08:47:00.962519 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-11-24 08:47:00.962536 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-11-24 08:47:00.962553 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-11-24 08:47:00.962570 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-11-24 08:47:00.962586 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-11-24 08:47:00.962602 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-11-24 08:47:00.962618 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-11-24 08:47:00.962634 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-11-24 08:47:00.962649 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-11-24 08:47:00.962665 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-11-24 08:47:00.962681 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-11-24 08:47:00.962696 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-11-24 08:47:00.962711 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-11-24 08:47:00.962726 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-11-24 08:47:01.424422 | controller | changed: .d..t...... ./
2025-11-24 08:47:01.968701 | controller | changed: .d..t...... ./
2025-11-24 08:47:01.984441 | 
2025-11-24 08:47:01.984574 | TASK [Return artifact to Zuul]
2025-11-24 08:47:02.013541 | controller | ok
2025-11-24 08:47:02.032709 | 
2025-11-24 08:47:02.032765 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-11-24 08:47:02.032868 | 
2025-11-24 08:47:02.032894 | PLAY RECAP
2025-11-24 08:47:02.032932 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-11-24 08:47:02.032952 | 
2025-11-24 08:47:02.114454 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-24 08:47:02.115217 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-24 08:47:02.630708 | 
2025-11-24 08:47:02.630827 | PLAY [all]
2025-11-24 08:47:02.649941 | 
2025-11-24 08:47:02.650036 | TASK [include_role : fetch-output]
2025-11-24 08:47:02.684651 | controller | ok
2025-11-24 08:47:02.701953 | 
2025-11-24 08:47:02.702091 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-24 08:47:02.746315 | controller | skipping: Conditional result was False
2025-11-24 08:47:02.752243 | 
2025-11-24 08:47:02.752318 | TASK [fetch-output : Set log path for single node]
2025-11-24 08:47:02.790933 | controller | ok
2025-11-24 08:47:02.795981 | 
2025-11-24 08:47:02.796068 | LOOP [fetch-output : Ensure local output dirs]
2025-11-24 08:47:03.119462 | controller -> localhost | ok: "/var/lib/zuul/builds/aee287eca05245c880a6241053f34642/work/logs"
2025-11-24 08:47:03.300253 | controller -> localhost | ok: "/var/lib/zuul/builds/aee287eca05245c880a6241053f34642/work/artifacts"
2025-11-24 08:47:03.487678 | controller -> localhost | ok: "/var/lib/zuul/builds/aee287eca05245c880a6241053f34642/work/docs"
2025-11-24 08:47:03.497187 | 
2025-11-24 08:47:03.497303 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-24 08:47:04.158703 | controller | ok
2025-11-24 08:47:04.158914 | controller | ok: All items complete
2025-11-24 08:47:04.158943 | 
2025-11-24 08:47:04.675401 | controller | ok
2025-11-24 08:47:05.131681 | controller | ok
2025-11-24 08:47:05.143467 | 
2025-11-24 08:47:05.143586 | TASK [include_role : fetch-output-openshift]
2025-11-24 08:47:05.157193 | controller | skipping: Conditional result was False
2025-11-24 08:47:05.163040 | 
2025-11-24 08:47:05.163116 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-24 08:47:05.502853 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006925
2025-11-24 08:47:05.715620 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007781
2025-11-24 08:47:05.748122 | 
2025-11-24 08:47:05.748264 | PLAY [all]
2025-11-24 08:47:05.763252 | 
2025-11-24 08:47:05.763352 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-11-24 08:47:06.361531 | controller | changed
2025-11-24 08:47:06.383366 | 
2025-11-24 08:47:06.383439 | PLAY RECAP
2025-11-24 08:47:06.383483 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-24 08:47:06.383507 | 
2025-11-24 08:47:06.455582 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-24 08:47:06.456541 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-11-24 08:47:07.024288 | 
2025-11-24 08:47:07.024422 | PLAY [localhost]
2025-11-24 08:47:07.042343 | 
2025-11-24 08:47:07.042453 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-24 08:47:07.365723 | localhost | changed
2025-11-24 08:47:07.370574 | 
2025-11-24 08:47:07.370672 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-24 08:47:07.398757 | localhost | ok
2025-11-24 08:47:07.407153 | 
2025-11-24 08:47:07.407241 | TASK [add-fileserver : Create SSH private key tempfile]
2025-11-24 08:47:07.709143 | localhost | changed
2025-11-24 08:47:07.715002 | 
2025-11-24 08:47:07.715091 | TASK [add-fileserver : Create SSH private key from secret]
2025-11-24 08:47:08.258973 | localhost | changed
2025-11-24 08:47:08.264085 | 
2025-11-24 08:47:08.264157 | TASK [add-fileserver : Add fileserver ssh key]
2025-11-24 08:47:08.606674 | localhost | Identity added: /var/lib/zuul/builds/aee287eca05245c880a6241053f34642/work/tmp/ansible._cn3qjsy (/var/lib/zuul/builds/aee287eca05245c880a6241053f34642/work/tmp/ansible._cn3qjsy)
2025-11-24 08:47:08.606846 | localhost | ok: Runtime: 0:00:00.006151
2025-11-24 08:47:08.611198 | 
2025-11-24 08:47:08.611276 | TASK [add-fileserver : Remove SSH private key from disk]
2025-11-24 08:47:08.831327 | localhost | ok: Runtime: 0:00:00.004277
2025-11-24 08:47:08.836092 | 
2025-11-24 08:47:08.836162 | TASK [add-fileserver : Add fileserver to inventory]
2025-11-24 08:47:08.883521 | localhost | changed
2025-11-24 08:47:08.888572 | 
2025-11-24 08:47:08.888640 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-11-24 08:47:09.199423 | localhost | changed
2025-11-24 08:47:09.219375 | 
2025-11-24 08:47:09.219505 | PLAY [localhost]
2025-11-24 08:47:09.231144 | 
2025-11-24 08:47:09.231222 | TASK [Generate bulk log download script]
2025-11-24 08:47:09.249635 | localhost | ok
2025-11-24 08:47:09.261243 | 
2025-11-24 08:47:09.261320 | TASK [local-log-download : Check API endpoint is defined]
2025-11-24 08:47:09.288764 | localhost | ok: All assertions passed
2025-11-24 08:47:09.293408 | 
2025-11-24 08:47:09.293477 | TASK [local-log-download : Create download script]
2025-11-24 08:47:09.641302 | localhost -> localhost | changed
2025-11-24 08:47:09.649982 | 
2025-11-24 08:47:09.650073 | TASK [Register quick-download link]
2025-11-24 08:47:09.667145 | localhost | ok
2025-11-24 08:47:09.716416 | 
2025-11-24 08:47:09.716496 | PLAY [logserver.rdoproject.org]
2025-11-24 08:47:09.725844 | 
2025-11-24 08:47:09.725906 | TASK [Set zuul-log-path fact]
2025-11-24 08:47:09.741329 | logserver.rdoproject.org | ok
2025-11-24 08:47:09.750746 | 
2025-11-24 08:47:09.750817 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-24 08:47:09.786998 | logserver.rdoproject.org | ok
2025-11-24 08:47:09.792564 | 
2025-11-24 08:47:09.792635 | TASK [upload-logs : Create log directories]
2025-11-24 08:47:11.416486 | logserver.rdoproject.org | changed
2025-11-24 08:47:11.419592 | 
2025-11-24 08:47:11.419669 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-11-24 08:47:11.655318 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005016
2025-11-24 08:47:11.659811 | 
2025-11-24 08:47:11.659878 | TASK [upload-logs : Upload logs to log server]
2025-11-24 08:47:13.047865 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-11-24 08:47:13.050720 | 
2025-11-24 08:47:13.050798 | LOOP [upload-logs : Compress console log and json output]
2025-11-24 08:47:13.090545 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-24 08:47:13.098357 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-24 08:47:13.111347 | 
2025-11-24 08:47:13.111533 | LOOP [upload-logs : Upload compressed console log and json output]
2025-11-24 08:47:13.147092 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-24 08:47:13.147305 | 
2025-11-24 08:47:13.151411 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-24 08:47:13.159754 | 
2025-11-24 08:47:13.159867 | LOOP [upload-logs : Upload console log and json output]