2025-10-02 15:08:17.661958 | Job console starting...
2025-10-02 15:08:17.890916 | Updating repositories
2025-10-02 15:08:18.710194 | Preparing job workspace
2025-10-02 15:08:26.007068 | Running Ansible setup...
2025-10-02 15:08:30.180331 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-02 15:08:31.007000 | 
2025-10-02 15:08:31.007092 | PLAY [localhost]
2025-10-02 15:08:31.033975 | 
2025-10-02 15:08:31.034048 | TASK [Gathering Facts]
2025-10-02 15:08:32.564558 | localhost | ok
2025-10-02 15:08:32.582466 | 
2025-10-02 15:08:32.582559 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-02 15:08:33.082908 | localhost -> localhost | changed
2025-10-02 15:08:33.088686 | 
2025-10-02 15:08:33.088761 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-02 15:08:34.290914 | localhost -> localhost | changed
2025-10-02 15:08:34.304578 | 
2025-10-02 15:08:34.304668 | TASK [Setup log path fact]
2025-10-02 15:08:34.335073 | localhost | ok
2025-10-02 15:08:34.356919 | 
2025-10-02 15:08:34.357024 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-02 15:08:34.419371 | localhost | ok
2025-10-02 15:08:34.426657 | 
2025-10-02 15:08:34.426731 | TASK [emit-job-header : Print job information]
2025-10-02 15:08:34.497716 | # Job Information
2025-10-02 15:08:34.497852 | Ansible Version: 2.15.12
2025-10-02 15:08:34.497884 | Job: cifmw-molecule-ci_local_storage
2025-10-02 15:08:34.497908 | Pipeline: github-check
2025-10-02 15:08:34.497928 | Executor: ibm-bm3-ze.softwarefactory-project.io
2025-10-02 15:08:34.497947 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3362
2025-10-02 15:08:34.497968 | Log URL (when completed): https://logserver.rdoproject.org/1ff/rdoproject.org/1ff118f029314cad8eb4891845c2cc76/
2025-10-02 15:08:34.497988 | Event ID: 854a5e80-9fa1-11f0-891b-738b4b5da685
2025-10-02 15:08:34.501633 | 
2025-10-02 15:08:34.501700 | LOOP [emit-job-header : Print node information]
2025-10-02 15:08:34.634225 | localhost | ok:
2025-10-02 15:08:34.634368 | localhost | # Node Information
2025-10-02 15:08:34.634459 | localhost | Inventory Hostname: controller
2025-10-02 15:08:34.634489 | localhost | Hostname: np0005466520
2025-10-02 15:08:34.634511 | localhost | Username: zuul
2025-10-02 15:08:34.634533 | localhost | Distro: CentOS 9
2025-10-02 15:08:34.634554 | localhost | Provider: ibm-bm3-nodepool
2025-10-02 15:08:34.634573 | localhost | Region: regionOne
2025-10-02 15:08:34.634592 | localhost | Label: centos-9-stream-crc-2-48-0-xl-ibm
2025-10-02 15:08:34.634611 | localhost | Product Name: OpenStack Compute
2025-10-02 15:08:34.634629 | localhost | Interface IP: 192.168.25.107
2025-10-02 15:08:34.666274 | 
2025-10-02 15:08:34.666367 | PLAY [all]
2025-10-02 15:08:34.681368 | 
2025-10-02 15:08:34.681456 | TASK [Gather network facts]
2025-10-02 15:08:35.179246 | controller | ok
2025-10-02 15:08:35.226806 | 
2025-10-02 15:08:35.226888 | TASK [include_role : start-zuul-console]
2025-10-02 15:08:35.305363 | controller | ok
2025-10-02 15:08:35.337840 | 
2025-10-02 15:08:35.337924 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-02 15:08:35.838277 | controller | ok
2025-10-02 15:08:35.851747 | 
2025-10-02 15:08:35.851821 | TASK [include_role : add-build-sshkey]
2025-10-02 15:08:35.906738 | controller | ok
2025-10-02 15:08:35.939750 | 
2025-10-02 15:08:35.939861 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-02 15:08:36.450871 | controller -> localhost | ok
2025-10-02 15:08:36.456221 | 
2025-10-02 15:08:36.456287 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-02 15:08:36.509108 | controller | ok
2025-10-02 15:08:36.534118 | controller | included: /var/lib/zuul/builds/1ff118f029314cad8eb4891845c2cc76/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-02 15:08:36.545736 | 
2025-10-02 15:08:36.545810 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-02 15:08:37.582890 | controller -> localhost | Generating public/private rsa key pair.
2025-10-02 15:08:37.583052 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/1ff118f029314cad8eb4891845c2cc76/work/1ff118f029314cad8eb4891845c2cc76_id_rsa.
2025-10-02 15:08:37.583085 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/1ff118f029314cad8eb4891845c2cc76/work/1ff118f029314cad8eb4891845c2cc76_id_rsa.pub.
2025-10-02 15:08:37.583111 | controller -> localhost | The key fingerprint is:
2025-10-02 15:08:37.583132 | controller -> localhost | SHA256:IPi/qK1EU+PAnekPnuOpRvf+Ott8m8rt8UwXq9ghAn4 zuul-build-sshkey
2025-10-02 15:08:37.583153 | controller -> localhost | The key's randomart image is:
2025-10-02 15:08:37.583174 | controller -> localhost | +---[RSA 3072]----+
2025-10-02 15:08:37.583193 | controller -> localhost | |                 |
2025-10-02 15:08:37.583213 | controller -> localhost | | . o o           |
2025-10-02 15:08:37.583234 | controller -> localhost | |  + B .          |
2025-10-02 15:08:37.583253 | controller -> localhost | |   * o .         |
2025-10-02 15:08:37.583272 | controller -> localhost | |  o = . S     .  |
2025-10-02 15:08:37.583291 | controller -> localhost | | ..o.* .       o |
2025-10-02 15:08:37.583309 | controller -> localhost | | ...+.+ E o o o  |
2025-10-02 15:08:37.583328 | controller -> localhost | | ..o +o* o.X +   |
2025-10-02 15:08:37.583346 | controller -> localhost | | .++=.==*+*.=    |
2025-10-02 15:08:37.583364 | controller -> localhost | +----[SHA256]-----+
2025-10-02 15:08:37.583438 | controller -> localhost | ok: Runtime: 0:00:00.351293
2025-10-02 15:08:37.596642 | 
2025-10-02 15:08:37.596707 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-02 15:08:37.676308 | controller | ok
2025-10-02 15:08:37.695120 | controller | included: /var/lib/zuul/builds/1ff118f029314cad8eb4891845c2cc76/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-02 15:08:37.729732 | 
2025-10-02 15:08:37.729808 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-02 15:08:37.787428 | controller | skipping: Conditional result was False
2025-10-02 15:08:37.793538 | 
2025-10-02 15:08:37.793614 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-02 15:08:38.521738 | controller | changed
2025-10-02 15:08:38.527974 | 
2025-10-02 15:08:38.528039 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-02 15:08:38.899122 | controller | ok
2025-10-02 15:08:38.904106 | 
2025-10-02 15:08:38.904174 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-02 15:08:40.296204 | controller | changed
2025-10-02 15:08:40.305160 | 
2025-10-02 15:08:40.305226 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-02 15:08:41.777296 | controller | changed
2025-10-02 15:08:41.785337 | 
2025-10-02 15:08:41.785433 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-02 15:08:41.951083 | controller | skipping: Conditional result was False
2025-10-02 15:08:41.957757 | 
2025-10-02 15:08:41.957847 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-02 15:08:43.692956 | controller -> localhost | changed
2025-10-02 15:08:43.707722 | 
2025-10-02 15:08:43.707803 | TASK [add-build-sshkey : Add back temp key]
2025-10-02 15:08:44.422620 | controller -> localhost | Identity added: /var/lib/zuul/builds/1ff118f029314cad8eb4891845c2cc76/work/1ff118f029314cad8eb4891845c2cc76_id_rsa (zuul-build-sshkey)
2025-10-02 15:08:44.422799 | controller -> localhost | ok: Runtime: 0:00:00.043289
2025-10-02 15:08:44.428639 | 
2025-10-02 15:08:44.428700 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-02 15:08:45.009264 | controller | ok
2025-10-02 15:08:45.033848 | 
2025-10-02 15:08:45.033924 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-02 15:08:45.144449 | controller | skipping: Conditional result was False
2025-10-02 15:08:45.154017 | 
2025-10-02 15:08:45.160468 | TASK [include_role : validate-host]
2025-10-02 15:08:45.240254 | controller | ok
2025-10-02 15:08:45.330711 | 
2025-10-02 15:08:45.330823 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-02 15:08:45.398766 | controller | ok
2025-10-02 15:08:45.419432 | 
2025-10-02 15:08:45.419528 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-02 15:08:46.064806 | controller -> localhost | ok
2025-10-02 15:08:46.071321 | 
2025-10-02 15:08:46.071399 | TASK [validate-host : Collect information about the host]
2025-10-02 15:08:46.843835 | controller | ok
2025-10-02 15:08:46.879041 | 
2025-10-02 15:08:46.879133 | TASK [validate-host : Sanitize hostname]
2025-10-02 15:08:47.043406 | controller | ok
2025-10-02 15:08:47.048314 | 
2025-10-02 15:08:47.048404 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-02 15:08:48.327248 | controller -> localhost | changed
2025-10-02 15:08:48.342763 | 
2025-10-02 15:08:48.342860 | TASK [validate-host : Collect information about zuul worker]
2025-10-02 15:08:48.884803 | controller | ok
2025-10-02 15:08:48.891840 | 
2025-10-02 15:08:48.895473 | TASK [validate-host : Write out all zuul information for each host]
2025-10-02 15:08:50.179201 | controller -> localhost | changed
2025-10-02 15:08:50.195437 | 
2025-10-02 15:08:50.195528 | TASK [include_role : prepare-workspace-openshift]
2025-10-02 15:08:50.250569 | controller | skipping: Conditional result was False
2025-10-02 15:08:50.261447 | 
2025-10-02 15:08:50.261536 | TASK [include_role : remove-zuul-sshkey]
2025-10-02 15:08:50.372283 | controller | skipping: Conditional result was False
2025-10-02 15:08:50.378197 | 
2025-10-02 15:08:50.378270 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-02 15:08:50.736113 | controller | ok: "logs"
2025-10-02 15:08:50.736483 | controller | ok: All items complete
2025-10-02 15:08:50.736521 | 
2025-10-02 15:08:51.006611 | controller | ok: "artifacts"
2025-10-02 15:08:51.288839 | controller | ok: "docs"
2025-10-02 15:08:51.298901 | 
2025-10-02 15:08:51.299047 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-02 15:08:51.716125 | controller | changed: "logs"
2025-10-02 15:08:51.989198 | controller | changed: "artifacts"
2025-10-02 15:08:52.261461 | controller | changed: "docs"
2025-10-02 15:08:52.331556 | 
2025-10-02 15:08:52.331686 | PLAY RECAP
2025-10-02 15:08:52.331740 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-02 15:08:52.331770 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-02 15:08:52.331809 | 
2025-10-02 15:08:52.619683 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-02 15:08:52.621472 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-02 15:08:55.078973 | 
2025-10-02 15:08:55.079092 | PLAY [all]
2025-10-02 15:08:55.116079 | 
2025-10-02 15:08:55.116168 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-02 15:08:55.349778 | controller | ok
2025-10-02 15:08:55.355526 | 
2025-10-02 15:08:55.355622 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-02 15:08:56.166311 | controller | changed
2025-10-02 15:08:56.198941 | 
2025-10-02 15:08:56.199032 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-02 15:08:58.668988 | controller | changed
2025-10-02 15:08:58.692997 | 
2025-10-02 15:08:58.693090 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-02 15:08:59.549063 | controller | changed:
2025-10-02 15:08:59.549226 | controller | {
2025-10-02 15:08:59.549259 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-02 15:08:59.549294 | controller | }
2025-10-02 15:08:59.874033 | controller | changed:
2025-10-02 15:08:59.874113 | controller | {
2025-10-02 15:08:59.874142 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-02 15:08:59.874165 | controller | }
2025-10-02 15:09:00.210664 | controller | changed:
2025-10-02 15:09:00.210878 | controller | {
2025-10-02 15:09:00.210912 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-02 15:09:00.210936 | controller | }
2025-10-02 15:09:00.529628 | controller | changed:
2025-10-02 15:09:00.529711 | controller | {
2025-10-02 15:09:00.529740 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-02 15:09:00.529763 | controller | }
2025-10-02 15:09:00.861875 | controller | changed:
2025-10-02 15:09:00.861997 | controller | {
2025-10-02 15:09:00.862029 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-02 15:09:00.862052 | controller | }
2025-10-02 15:09:01.198259 | controller | changed:
2025-10-02 15:09:01.198419 | controller | {
2025-10-02 15:09:01.198453 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-02 15:09:01.198478 | controller | }
2025-10-02 15:09:01.612593 | controller | changed:
2025-10-02 15:09:01.612676 | controller | {
2025-10-02 15:09:01.612705 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-02 15:09:01.612729 | controller | }
2025-10-02 15:09:01.846665 | controller | changed:
2025-10-02 15:09:01.846749 | controller | {
2025-10-02 15:09:01.846778 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-02 15:09:01.846811 | controller | }
2025-10-02 15:09:02.172310 | controller | changed:
2025-10-02 15:09:02.172418 | controller | {
2025-10-02 15:09:02.172451 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-02 15:09:02.172475 | controller | }
2025-10-02 15:09:02.530415 | controller | changed:
2025-10-02 15:09:02.530504 | controller | {
2025-10-02 15:09:02.530532 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-02 15:09:02.530554 | controller | }
2025-10-02 15:09:02.850006 | controller | changed:
2025-10-02 15:09:02.850135 | controller | {
2025-10-02 15:09:02.850166 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-02 15:09:02.850189 | controller | }
2025-10-02 15:09:03.199126 | controller | changed:
2025-10-02 15:09:03.199211 | controller | {
2025-10-02 15:09:03.199239 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-02 15:09:03.199261 | controller | }
2025-10-02 15:09:03.541819 | controller | changed:
2025-10-02 15:09:03.541897 | controller | {
2025-10-02 15:09:03.541926 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-02 15:09:03.541948 | controller | }
2025-10-02 15:09:03.885328 | controller | changed:
2025-10-02 15:09:03.885470 | controller | {
2025-10-02 15:09:03.885502 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-02 15:09:03.885526 | controller | }
2025-10-02 15:09:04.208190 | controller | changed:
2025-10-02 15:09:04.208264 | controller | {
2025-10-02 15:09:04.208292 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-02 15:09:04.208314 | controller | }
2025-10-02 15:09:04.545948 | controller | changed:
2025-10-02 15:09:04.546045 | controller | {
2025-10-02 15:09:04.546073 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-02 15:09:04.546096 | controller | }
2025-10-02 15:09:04.908938 | controller | changed:
2025-10-02 15:09:04.909017 | controller | {
2025-10-02 15:09:04.909045 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-02 15:09:04.909068 | controller | }
2025-10-02 15:09:05.241245 | controller | changed:
2025-10-02 15:09:05.241365 | controller | {
2025-10-02 15:09:05.241434 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-02 15:09:05.241465 | controller | }
2025-10-02 15:09:05.570957 | controller | changed:
2025-10-02 15:09:05.571043 | controller | {
2025-10-02 15:09:05.571073 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-02 15:09:05.571095 | controller | }
2025-10-02 15:09:05.919269 | controller | changed:
2025-10-02 15:09:05.919356 | controller | {
2025-10-02 15:09:05.919399 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-02 15:09:05.919429 | controller | }
2025-10-02 15:09:06.274039 | controller | changed:
2025-10-02 15:09:06.274116 | controller | {
2025-10-02 15:09:06.274145 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-02 15:09:06.274167 | controller | }
2025-10-02 15:09:06.642862 | controller | changed:
2025-10-02 15:09:06.642947 | controller | {
2025-10-02 15:09:06.642974 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-02 15:09:06.642996 | controller | }
2025-10-02 15:09:06.997460 | controller | changed:
2025-10-02 15:09:06.997539 | controller | {
2025-10-02 15:09:06.997566 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-02 15:09:06.997589 | controller | }
2025-10-02 15:09:07.386097 | controller | changed:
2025-10-02 15:09:07.386170 | controller | {
2025-10-02 15:09:07.386198 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-02 15:09:07.386219 | controller | }
2025-10-02 15:09:07.717044 | controller | changed:
2025-10-02 15:09:07.717127 | controller | {
2025-10-02 15:09:07.717155 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-02 15:09:07.717176 | controller | }
2025-10-02 15:09:08.051740 | controller | changed:
2025-10-02 15:09:08.051836 | controller | {
2025-10-02 15:09:08.051865 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-02 15:09:08.051888 | controller | }
2025-10-02 15:09:08.085234 | 
2025-10-02 15:09:08.085334 | TASK [Set timezone to UTC]
2025-10-02 15:09:11.210445 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-02 15:09:11.219995 | 
2025-10-02 15:09:11.220063 | TASK [Create nodepool directory]
2025-10-02 15:09:11.643522 | controller | changed
2025-10-02 15:09:11.651378 | 
2025-10-02 15:09:11.651476 | TASK [Create nodepool sub_nodes file]
2025-10-02 15:09:13.042868 | controller | changed
2025-10-02 15:09:13.051751 | 
2025-10-02 15:09:13.051832 | TASK [Create nodepool sub_nodes_private file]
2025-10-02 15:09:14.423163 | controller | changed
2025-10-02 15:09:14.432012 | 
2025-10-02 15:09:14.432091 | LOOP [Populate nodepool sub_nodes file]
2025-10-02 15:09:14.604336 | 
2025-10-02 15:09:14.604524 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-02 15:09:14.698269 | 
2025-10-02 15:09:14.698447 | TASK [Create nodepool primary file]
2025-10-02 15:09:14.753272 | controller | skipping: Conditional result was False
2025-10-02 15:09:14.759053 | 
2025-10-02 15:09:14.759124 | TASK [Create nodepool node_private for this node]
2025-10-02 15:09:16.169423 | controller | changed
2025-10-02 15:09:16.183192 | 
2025-10-02 15:09:16.183970 | LOOP [Copy ssh keys to nodepool directory]
2025-10-02 15:09:16.846880 | controller | ok: Item: id_rsa Runtime: 0:00:00.004644
2025-10-02 15:09:16.847054 | 
2025-10-02 15:09:17.119293 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004482
2025-10-02 15:09:17.128950 | 
2025-10-02 15:09:17.129023 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-02 15:09:18.524122 | controller | changed
2025-10-02 15:09:18.536415 | 
2025-10-02 15:09:18.536492 | TASK [Validate sudoers config after edits]
2025-10-02 15:09:19.391551 | controller | /etc/sudoers: parsed OK
2025-10-02 15:09:19.391646 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-02 15:09:19.391655 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-02 15:09:19.391661 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-02 15:09:19.434195 | controller | ok: Runtime: 0:00:00.007524
2025-10-02 15:09:19.440420 | 
2025-10-02 15:09:19.440484 | TASK [Show the environment passed in to job shell scripts]
2025-10-02 15:09:20.001370 | controller | SHELL=/bin/bash
2025-10-02 15:09:20.001432 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-02 15:09:20.001442 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-02 15:09:20.001449 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/62/3362/41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 15:09:20.001455 | controller | PWD=/home/zuul
2025-10-02 15:09:20.001461 | controller | ZUUL_PIPELINE=github-check
2025-10-02 15:09:20.001533 | controller | LOGNAME=zuul
2025-10-02 15:09:20.001542 | controller | XDG_SESSION_TYPE=tty
2025-10-02 15:09:20.001548 | controller | _=/usr/bin/env
2025-10-02 15:09:20.001553 | controller | MOTD_SHOWN=pam
2025-10-02 15:09:20.001558 | controller | HOME=/home/zuul
2025-10-02 15:09:20.001563 | controller | LANG=en_US.UTF-8
2025-10-02 15:09:20.001569 | controller | SSH_CONNECTION=192.168.25.12 43380 192.168.25.107 22
2025-10-02 15:09:20.001575 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-02 15:09:20.001585 | controller | ZUUL_CHANGE_IDS=3362,41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 15:09:20.001591 | controller | WORKSPACE=/home/zuul/workspace
2025-10-02 15:09:20.001608 | controller | XDG_SESSION_CLASS=user
2025-10-02 15:09:20.001614 | controller | SELINUX_ROLE_REQUESTED=
2025-10-02 15:09:20.001619 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-02 15:09:20.001625 | controller | USER=zuul
2025-10-02 15:09:20.001630 | controller | ZUUL_VOTING=True
2025-10-02 15:09:20.001635 | controller | BUILD_TIMEOUT=1800000
2025-10-02 15:09:20.001641 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-02 15:09:20.001646 | controller | SHLVL=1
2025-10-02 15:09:20.001651 | controller | ZUUL_PATCHSET=41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 15:09:20.001656 | controller | XDG_SESSION_ID=1
2025-10-02 15:09:20.001662 | controller | ZUUL_BRANCH=main
2025-10-02 15:09:20.001667 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-02 15:09:20.001673 | controller | SSH_CLIENT=192.168.25.12 43380 22
2025-10-02 15:09:20.001678 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-02 15:09:20.001684 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-02 15:09:20.001691 | controller | which_declare=declare -f
2025-10-02 15:09:20.001698 | 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-10-02 15:09:20.001704 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-02 15:09:20.001710 | controller | ZUUL_CHANGE=3362
2025-10-02 15:09:20.001715 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-02 15:09:20.001720 | controller | ZUUL_UUID=1ff118f029314cad8eb4891845c2cc76
2025-10-02 15:09:20.001725 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-02 15:09:20.001731 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-02 15:09:20.001737 | controller | }
2025-10-02 15:09:20.086936 | controller | ok: Runtime: 0:00:00.008896
2025-10-02 15:09:20.092517 | 
2025-10-02 15:09:20.092580 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-02 15:09:20.167289 | controller | skipping: Conditional result was False
2025-10-02 15:09:20.173117 | 
2025-10-02 15:09:20.173189 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-02 15:09:20.793973 | controller | skipping: Conditional result was False
2025-10-02 15:09:20.799944 | 
2025-10-02 15:09:20.800012 | TASK [Ensure legacy workspace directory]
2025-10-02 15:09:21.206991 | controller | changed
2025-10-02 15:09:21.328554 | 
2025-10-02 15:09:21.328644 | PLAY RECAP
2025-10-02 15:09:21.328691 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-02 15:09:21.328715 | 
2025-10-02 15:09:21.925754 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-02 15:09:21.926489 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-02 15:09:24.947268 | 
2025-10-02 15:09:24.947378 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-02 15:09:25.062069 | 
2025-10-02 15:09:25.062201 | TASK [Create zuul-output directory]
2025-10-02 15:09:26.229770 | controller | changed
2025-10-02 15:09:26.237770 | 
2025-10-02 15:09:26.237861 | TASK [Slurp Zuul inventory test]
2025-10-02 15:09:28.386679 | controller -> localhost | ok
2025-10-02 15:09:28.393488 | 
2025-10-02 15:09:28.393568 | TASK [Save zuul inventory]
2025-10-02 15:09:31.036611 | controller | changed
2025-10-02 15:09:31.041942 | 
2025-10-02 15:09:31.042007 | TASK [Save zuul vars without the change_message]
2025-10-02 15:09:32.910367 | controller | changed
2025-10-02 15:09:33.014468 | 
2025-10-02 15:09:33.014549 | PLAY RECAP
2025-10-02 15:09:33.014596 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-02 15:09:33.014620 | 
2025-10-02 15:09:33.520843 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-02 15:09:33.521716 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-02 15:09:36.669678 | 
2025-10-02 15:09:36.669776 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-02 15:09:36.754032 | 
2025-10-02 15:09:36.754139 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-02 15:09:36.841769 | controller | ok
2025-10-02 15:09:36.903348 | 
2025-10-02 15:09:36.903484 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-02 15:09:36.983332 | controller | skipping: Conditional result was False
2025-10-02 15:09:36.989314 | 
2025-10-02 15:09:36.989415 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-02 15:09:38.026759 | controller | ok
2025-10-02 15:09:38.039525 | 
2025-10-02 15:09:38.039624 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-02 15:09:39.894051 | controller | ok
2025-10-02 15:09:39.988483 | 
2025-10-02 15:09:39.988583 | TASK [Prepare workspace]
2025-10-02 15:09:40.055933 | controller | ok
2025-10-02 15:09:40.146909 | 
2025-10-02 15:09:40.147017 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-02 15:09:41.284429 | controller | ok
2025-10-02 15:09:41.294801 | 
2025-10-02 15:09:41.294879 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-02 15:09:49.553825 | controller | Output suppressed because no_log was given
2025-10-02 15:09:49.592890 | 
2025-10-02 15:09:49.592992 | LOOP [Create zuul-output directory]
2025-10-02 15:09:49.963210 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-02 15:09:50.224275 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-02 15:09:50.231668 | 
2025-10-02 15:09:50.231772 | TASK [Install required packages]
2025-10-02 15:10:13.689738 | controller | ok: Nothing to do
2025-10-02 15:10:13.696267 | 
2025-10-02 15:10:13.696331 | TASK [Install venv]
2025-10-02 15:11:13.376065 | controller | changed
2025-10-02 15:11:13.402709 | 
2025-10-02 15:11:13.402813 | PLAY RECAP
2025-10-02 15:11:13.402862 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-02 15:11:13.402886 | 
2025-10-02 15:11:13.487008 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-02 15:11:13.487817 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-02 15:11:14.001677 | 
2025-10-02 15:11:14.001774 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-02 15:11:14.021692 | 
2025-10-02 15:11:14.021766 | TASK [Gather required facts]
2025-10-02 15:11:14.568054 | controller | ok
2025-10-02 15:11:14.573949 | 
2025-10-02 15:11:14.574031 | TASK [Load environment var if instructed to]
2025-10-02 15:11:14.597940 | controller | skipping: Conditional result was False
2025-10-02 15:11:14.603767 | 
2025-10-02 15:11:14.603850 | TASK [Run molecule]
2025-10-02 15:11:15.625010 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-02 15:11:15.695285 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-02 15:11:32.637476 | 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-10-02 15:11:32.637764 | 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-10-02 15:11:32.638220 | 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-10-02 15:11:32.638685 | 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-10-02 15:11:32.639159 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+41f2edaa was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 1.0.0+41f2edaa (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 15:11:32.639620 | 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-10-02 15:11:32.640059 | 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-10-02 15:11:32.640514 | 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-10-02 15:11:32.640966 | 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-10-02 15:11:32.641422 | 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-10-02 15:11:32.641870 | 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-10-02 15:11:32.642336 | 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-10-02 15:11:32.642796 | 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-10-02 15:11:32.643231 | 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-10-02 15:11:32.643682 | 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-10-02 15:11:32.653313 | controller | INFO     Running default > prepare
2025-10-02 15:11:33.316544 | controller |
2025-10-02 15:11:33.316585 | controller | PLAY [Prepare] *****************************************************************
2025-10-02 15:11:33.316696 | controller |
2025-10-02 15:11:33.316804 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 15:11:33.316913 | controller | Thursday 02 October 2025  15:11:33 +0000 (0:00:00.012)       0:00:00.012 ******
2025-10-02 15:11:34.195185 | controller | ok: [instance]
2025-10-02 15:11:34.195226 | controller |
2025-10-02 15:11:34.195353 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-02 15:11:34.195456 | controller | Thursday 02 October 2025  15:11:34 +0000 (0:00:00.879)       0:00:00.891 ******
2025-10-02 15:11:34.212410 | controller | skipping: [instance]
2025-10-02 15:11:34.212490 | controller |
2025-10-02 15:11:34.212613 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-02 15:11:34.212758 | controller | Thursday 02 October 2025  15:11:34 +0000 (0:00:00.017)       0:00:00.909 ******
2025-10-02 15:11:34.255481 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-02 15:11:34.255559 | controller |
2025-10-02 15:11:34.255710 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-02 15:11:34.255839 | controller | Thursday 02 October 2025  15:11:34 +0000 (0:00:00.042)       0:00:00.952 ******
2025-10-02 15:11:34.553979 | controller | ok: [instance]
2025-10-02 15:11:34.554081 | controller |
2025-10-02 15:11:34.554196 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-02 15:11:34.554299 | controller | Thursday 02 October 2025  15:11:34 +0000 (0:00:00.298)       0:00:01.250 ******
2025-10-02 15:11:34.861734 | controller | ok: [instance]
2025-10-02 15:11:34.861823 | controller |
2025-10-02 15:11:34.861939 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-02 15:11:34.862066 | controller | Thursday 02 October 2025  15:11:34 +0000 (0:00:00.307)       0:00:01.558 ******
2025-10-02 15:11:35.541155 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-02 15:11:35.541229 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-02 15:11:35.541340 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-02 15:11:35.541434 | controller |
2025-10-02 15:11:35.541546 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-02 15:11:35.541667 | controller | Thursday 02 October 2025  15:11:35 +0000 (0:00:00.679)       0:00:02.237 ******
2025-10-02 15:11:35.605169 | controller |
2025-10-02 15:11:35.605265 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-02 15:11:35.605419 | controller | Thursday 02 October 2025  15:11:35 +0000 (0:00:00.063)       0:00:02.301 ******
2025-10-02 15:11:36.146928 | controller | changed: [instance] => (item=tmp)
2025-10-02 15:11:36.147015 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-02 15:11:36.147143 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-02 15:11:36.147241 | controller |
2025-10-02 15:11:36.147343 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-02 15:11:36.147437 | controller | Thursday 02 October 2025  15:11:36 +0000 (0:00:00.541)       0:00:02.843 ******
2025-10-02 15:11:36.985777 | controller | ok: [instance]
2025-10-02 15:11:38.198189 | controller |
2025-10-02 15:11:38.198222 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-02 15:11:38.198235 | controller | Thursday 02 October 2025  15:11:36 +0000 (0:00:00.838)       0:00:03.682 ******
2025-10-02 15:11:38.198246 | controller | changed: [instance]
2025-10-02 15:11:38.198302 | controller |
2025-10-02 15:11:38.198319 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-02 15:11:38.198328 | controller | Thursday 02 October 2025  15:11:38 +0000 (0:00:01.212)       0:00:04.894 ******
2025-10-02 15:11:46.407146 | controller | changed: [instance]
2025-10-02 15:11:46.407518 | controller |
2025-10-02 15:11:47.137820 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-02 15:11:47.137851 | controller | Thursday 02 October 2025  15:11:46 +0000 (0:00:08.209)       0:00:13.103 ******
2025-10-02 15:11:47.137862 | controller | changed: [instance]
2025-10-02 15:11:47.158481 | controller |
2025-10-02 15:11:47.158503 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-02 15:11:47.158510 | controller | Thursday 02 October 2025  15:11:47 +0000 (0:00:00.730)       0:00:13.834 ******
2025-10-02 15:11:47.158519 | controller | skipping: [instance]
2025-10-02 15:11:47.998191 | controller |
2025-10-02 15:11:47.998225 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-02 15:11:47.998233 | controller | Thursday 02 October 2025  15:11:47 +0000 (0:00:00.021)       0:00:13.855 ******
2025-10-02 15:11:47.998243 | controller | changed: [instance]
2025-10-02 15:11:47.998266 | controller |
2025-10-02 15:11:47.998275 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-02 15:11:47.998376 | controller | Thursday 02 October 2025  15:11:47 +0000 (0:00:00.839)       0:00:14.694 ******
2025-10-02 15:11:48.033200 | controller | skipping: [instance]
2025-10-02 15:11:48.033283 | controller |
2025-10-02 15:11:48.033312 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-02 15:11:48.033324 | controller | Thursday 02 October 2025  15:11:48 +0000 (0:00:00.035)       0:00:14.729 ******
2025-10-02 15:11:48.069183 | controller | skipping: [instance]
2025-10-02 15:11:48.104178 | controller |
2025-10-02 15:11:48.104210 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-02 15:11:48.104218 | controller | Thursday 02 October 2025  15:11:48 +0000 (0:00:00.035)       0:00:14.765 ******
2025-10-02 15:11:48.104229 | controller | skipping: [instance]
2025-10-02 15:11:48.661044 | controller |
2025-10-02 15:11:48.661094 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-02 15:11:48.661102 | controller | Thursday 02 October 2025  15:11:48 +0000 (0:00:00.035)       0:00:14.800 ******
2025-10-02 15:11:48.661112 | controller | changed: [instance]
2025-10-02 15:11:49.106080 | controller |
2025-10-02 15:11:49.106111 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-02 15:11:49.106119 | controller | Thursday 02 October 2025  15:11:48 +0000 (0:00:00.556)       0:00:15.357 ******
2025-10-02 15:11:49.106129 | controller | changed: [instance]
2025-10-02 15:11:49.134569 | controller |
2025-10-02 15:11:49.134594 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-02 15:11:49.134602 | controller | Thursday 02 October 2025  15:11:49 +0000 (0:00:00.445)       0:00:15.802 ******
2025-10-02 15:11:49.134612 | controller | skipping: [instance]
2025-10-02 15:11:49.163095 | controller |
2025-10-02 15:11:49.163115 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-02 15:11:49.163124 | controller | Thursday 02 October 2025  15:11:49 +0000 (0:00:00.028)       0:00:15.831 ******
2025-10-02 15:11:49.163139 | controller | skipping: [instance]
2025-10-02 15:11:49.192011 | controller |
2025-10-02 15:11:49.192065 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-02 15:11:49.192079 | controller | Thursday 02 October 2025  15:11:49 +0000 (0:00:00.028)       0:00:15.859 ******
2025-10-02 15:11:49.192092 | controller | skipping: [instance]
2025-10-02 15:11:49.230840 | controller |
2025-10-02 15:11:49.230864 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-02 15:11:49.230871 | controller | Thursday 02 October 2025  15:11:49 +0000 (0:00:00.028)       0:00:15.888 ******
2025-10-02 15:11:49.230881 | controller | ok: [instance]
2025-10-02 15:11:49.272513 | controller |
2025-10-02 15:11:49.272535 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-02 15:11:49.272542 | controller | Thursday 02 October 2025  15:11:49 +0000 (0:00:00.039)       0:00:15.927 ******
2025-10-02 15:11:49.272552 | controller | skipping: [instance]
2025-10-02 15:11:49.300166 | controller |
2025-10-02 15:11:49.300191 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-02 15:11:49.300201 | controller | Thursday 02 October 2025  15:11:49 +0000 (0:00:00.041)       0:00:15.969 ******
2025-10-02 15:11:49.300221 | controller | skipping: [instance]
2025-10-02 15:11:49.300404 | controller |
2025-10-02 15:11:49.300557 | controller | TASK [Download the RPM] ********************************************************
2025-10-02 15:11:49.300720 | controller | Thursday 02 October 2025  15:11:49 +0000 (0:00:00.027)       0:00:15.997 ******
2025-10-02 15:11:49.327236 | controller | skipping: [instance]
2025-10-02 15:11:49.327385 | controller |
2025-10-02 15:11:49.327535 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-02 15:11:49.327697 | controller | Thursday 02 October 2025  15:11:49 +0000 (0:00:00.027)       0:00:16.024 ******
2025-10-02 15:11:49.354406 | controller | skipping: [instance]
2025-10-02 15:11:49.354567 | controller |
2025-10-02 15:11:49.354735 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-02 15:11:49.354909 | controller | Thursday 02 October 2025  15:11:49 +0000 (0:00:00.027)       0:00:16.051 ******
2025-10-02 15:11:49.382697 | controller | skipping: [instance]
2025-10-02 15:11:49.382848 | controller |
2025-10-02 15:11:49.382991 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-02 15:11:49.383159 | controller | Thursday 02 October 2025  15:11:49 +0000 (0:00:00.028)       0:00:16.079 ******
2025-10-02 15:11:49.412998 | controller | skipping: [instance]
2025-10-02 15:11:49.413198 | controller |
2025-10-02 15:11:49.413371 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-02 15:11:49.413523 | controller | Thursday 02 October 2025  15:11:49 +0000 (0:00:00.030)       0:00:16.109 ******
2025-10-02 15:11:49.439321 | controller | skipping: [instance]
2025-10-02 15:11:49.439489 | controller |
2025-10-02 15:11:49.439636 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-02 15:11:49.439793 | controller | Thursday 02 October 2025  15:11:49 +0000 (0:00:00.026)       0:00:16.136 ******
2025-10-02 15:11:49.624172 | controller | ok: [instance]
2025-10-02 15:11:49.624234 | controller |
2025-10-02 15:11:49.624471 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-02 15:11:49.840215 | controller | Thursday 02 October 2025  15:11:49 +0000 (0:00:00.184)       0:00:16.320 ******
2025-10-02 15:11:49.840263 | controller | changed: [instance]
2025-10-02 15:11:49.840298 | controller |
2025-10-02 15:11:49.840308 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-02 15:11:49.840445 | controller | Thursday 02 October 2025  15:11:49 +0000 (0:00:00.216)       0:00:16.537 ******
2025-10-02 15:11:50.066718 | controller | changed: [instance]
2025-10-02 15:11:50.087888 | controller |
2025-10-02 15:11:50.087918 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-02 15:11:50.087928 | controller | Thursday 02 October 2025  15:11:50 +0000 (0:00:00.226)       0:00:16.763 ******
2025-10-02 15:11:50.087940 | controller | skipping: [instance]
2025-10-02 15:11:50.109640 | controller |
2025-10-02 15:11:50.109678 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-02 15:11:50.109687 | controller | Thursday 02 October 2025  15:11:50 +0000 (0:00:00.021)       0:00:16.784 ******
2025-10-02 15:11:50.109699 | controller | skipping: [instance]
2025-10-02 15:11:50.130760 | controller |
2025-10-02 15:11:50.130783 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-02 15:11:50.130791 | controller | Thursday 02 October 2025  15:11:50 +0000 (0:00:00.021)       0:00:16.806 ******
2025-10-02 15:11:50.130811 | controller | skipping: [instance]
2025-10-02 15:11:50.150493 | controller |
2025-10-02 15:11:50.150517 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-02 15:11:50.150526 | controller | Thursday 02 October 2025  15:11:50 +0000 (0:00:00.021)       0:00:16.827 ******
2025-10-02 15:11:50.150542 | controller | skipping: [instance]
2025-10-02 15:11:50.171805 | controller |
2025-10-02 15:11:50.171832 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-02 15:11:50.171840 | controller | Thursday 02 October 2025  15:11:50 +0000 (0:00:00.019)       0:00:16.847 ******
2025-10-02 15:11:50.171849 | controller | skipping: [instance]
2025-10-02 15:11:50.191262 | controller |
2025-10-02 15:11:50.191281 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-02 15:11:50.191294 | controller | Thursday 02 October 2025  15:11:50 +0000 (0:00:00.021)       0:00:16.868 ******
2025-10-02 15:11:50.191303 | controller | skipping: [instance]
2025-10-02 15:11:50.191451 | controller |
2025-10-02 15:11:50.191594 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-02 15:11:50.191748 | controller | Thursday 02 October 2025  15:11:50 +0000 (0:00:00.020)       0:00:16.888 ******
2025-10-02 15:11:50.206206 | controller | skipping: [instance]
2025-10-02 15:11:50.206378 | controller |
2025-10-02 15:11:50.206527 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-02 15:11:50.206686 | controller | Thursday 02 October 2025  15:11:50 +0000 (0:00:00.014)       0:00:16.903 ******
2025-10-02 15:11:50.234639 | controller | skipping: [instance]
2025-10-02 15:11:50.234838 | controller |
2025-10-02 15:11:50.235013 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-02 15:11:50.235214 | controller | Thursday 02 October 2025  15:11:50 +0000 (0:00:00.028)       0:00:16.931 ******
2025-10-02 15:11:50.251219 | controller | skipping: [instance]
2025-10-02 15:11:50.251380 | controller |
2025-10-02 15:11:50.251525 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-02 15:11:50.251702 | controller | Thursday 02 October 2025  15:11:50 +0000 (0:00:00.016)       0:00:16.948 ******
2025-10-02 15:11:50.269771 | controller | skipping: [instance]
2025-10-02 15:11:50.269954 | controller |
2025-10-02 15:11:50.270158 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-02 15:11:50.270324 | controller | Thursday 02 October 2025  15:11:50 +0000 (0:00:00.018)       0:00:16.966 ******
2025-10-02 15:11:50.298252 | controller | skipping: [instance]
2025-10-02 15:11:50.298416 | controller |
2025-10-02 15:11:50.298554 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-02 15:11:50.298711 | controller | Thursday 02 October 2025  15:11:50 +0000 (0:00:00.028)       0:00:16.995 ******
2025-10-02 15:11:50.327244 | controller | skipping: [instance]
2025-10-02 15:11:50.327393 | controller |
2025-10-02 15:11:50.327535 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-02 15:11:50.327688 | controller | Thursday 02 October 2025  15:11:50 +0000 (0:00:00.029)       0:00:17.024 ******
2025-10-02 15:11:50.356245 | controller | skipping: [instance]
2025-10-02 15:11:50.356420 | controller |
2025-10-02 15:11:50.356561 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-02 15:11:50.356712 | controller | Thursday 02 October 2025  15:11:50 +0000 (0:00:00.028)       0:00:17.053 ******
2025-10-02 15:11:50.385935 | controller | skipping: [instance]
2025-10-02 15:11:50.386102 | controller |
2025-10-02 15:11:50.386248 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-02 15:11:50.386383 | controller | Thursday 02 October 2025  15:11:50 +0000 (0:00:00.029)       0:00:17.083 ******
2025-10-02 15:12:41.769322 | controller | ok: [instance]
2025-10-02 15:12:41.769392 | controller |
2025-10-02 15:12:41.769403 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-02 15:12:43.019591 | controller | Thursday 02 October 2025  15:12:41 +0000 (0:00:51.382)       0:01:08.465 ******
2025-10-02 15:12:43.019628 | controller | ok: [instance]
2025-10-02 15:12:43.053991 | controller |
2025-10-02 15:12:43.054021 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-02 15:12:43.054030 | controller | Thursday 02 October 2025  15:12:43 +0000 (0:00:01.250)       0:01:09.715 ******
2025-10-02 15:12:43.054040 | controller | skipping: [instance]
2025-10-02 15:16:28.419247 | controller |
2025-10-02 15:16:28.419288 | controller | TASK [Ensure CRC is started] ***************************************************
2025-10-02 15:16:28.419297 | controller | Thursday 02 October 2025  15:12:43 +0000 (0:00:00.034)       0:01:09.750 ******
2025-10-02 15:16:28.419307 | controller | changed: [instance]
2025-10-02 15:16:28.419338 | controller |
2025-10-02 15:16:28.419450 | controller | TASK [Inject crc hostname/IP in hosts] *****************************************
2025-10-02 15:16:28.419465 | controller | Thursday 02 October 2025  15:16:28 +0000 (0:03:45.362)       0:04:55.112 ******
2025-10-02 15:16:28.737879 | controller | changed: [instance]
2025-10-02 15:16:28.737988 | controller |
2025-10-02 15:16:28.738013 | controller | PLAY RECAP *********************************************************************
2025-10-02 15:16:28.738202 | controller | instance                   : ok=21   changed=11   unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-10-02 15:16:28.738256 | controller |
2025-10-02 15:16:28.738404 | controller | Thursday 02 October 2025  15:16:28 +0000 (0:00:00.321)       0:04:55.434 ******
2025-10-02 15:16:28.738533 | controller | ===============================================================================
2025-10-02 15:16:28.738573 | controller | Ensure CRC is started ------------------------------------------------- 225.36s
2025-10-02 15:16:28.738854 | controller | test_deps : Install selinux python libs -------------------------------- 51.38s
2025-10-02 15:16:28.738985 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.21s
2025-10-02 15:16:28.738998 | controller | test_deps : Install python yaml libs ------------------------------------ 1.25s
2025-10-02 15:16:28.739007 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.21s
2025-10-02 15:16:28.739318 | controller | Gathering Facts --------------------------------------------------------- 0.88s
2025-10-02 15:16:28.739343 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.84s
2025-10-02 15:16:28.739391 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.84s
2025-10-02 15:16:28.739400 | controller | repo_setup : Install repo-setup package --------------------------------- 0.73s
2025-10-02 15:16:28.739408 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.68s
2025-10-02 15:16:28.739724 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.56s
2025-10-02 15:16:28.739827 | controller | repo_setup : Ensure directories are present ----------------------------- 0.54s
2025-10-02 15:16:28.739840 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.45s
2025-10-02 15:16:28.739849 | controller | Inject crc hostname/IP in hosts ----------------------------------------- 0.32s
2025-10-02 15:16:28.740085 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.31s
2025-10-02 15:16:28.740106 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.30s
2025-10-02 15:16:28.740133 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.23s
2025-10-02 15:16:28.740141 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.22s
2025-10-02 15:16:28.740350 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.18s
2025-10-02 15:16:28.798975 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-10-02 15:16:28.799028 | controller | INFO     Running default > converge
2025-10-02 15:16:29.225562 | controller |
2025-10-02 15:16:30.101959 | controller | PLAY [Converge] ****************************************************************
2025-10-02 15:16:30.101992 | controller |
2025-10-02 15:16:30.102001 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 15:16:30.102007 | controller | Thursday 02 October 2025  15:16:29 +0000 (0:00:00.013)       0:00:00.013 ******
2025-10-02 15:16:30.102018 | controller | ok: [instance]
2025-10-02 15:16:30.124106 | controller |
2025-10-02 15:16:30.124135 | controller | TASK [Check if new ssh keypair exists] *****************************************
2025-10-02 15:16:30.124144 | controller | Thursday 02 October 2025  15:16:30 +0000 (0:00:00.877)       0:00:00.890 ******
2025-10-02 15:16:30.124155 | controller |
2025-10-02 15:16:30.408967 | controller | TASK [recognize_ssh_keypair : Check if id_ed25519 key exists] ******************
2025-10-02 15:16:30.409001 | controller | Thursday 02 October 2025  15:16:30 +0000 (0:00:00.022)       0:00:00.913 ******
2025-10-02 15:16:30.409015 | controller | ok: [instance]
2025-10-02 15:16:30.427390 | controller |
2025-10-02 15:16:30.427413 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair exists] ******************
2025-10-02 15:16:30.427427 | controller | Thursday 02 October 2025  15:16:30 +0000 (0:00:00.284)       0:00:01.197 ******
2025-10-02 15:16:30.427440 | controller | ok: [instance]
2025-10-02 15:16:30.427505 | controller |
2025-10-02 15:16:30.427523 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair does not exists] *********
2025-10-02 15:16:30.427540 | controller | Thursday 02 October 2025  15:16:30 +0000 (0:00:00.018)       0:00:01.216 ******
2025-10-02 15:16:30.448828 | controller | skipping: [instance]
2025-10-02 15:16:30.448947 | controller |
2025-10-02 15:16:30.449100 | controller | TASK [Add the crc host dynamically] ********************************************
2025-10-02 15:16:30.449238 | controller | Thursday 02 October 2025  15:16:30 +0000 (0:00:00.021)       0:00:01.238 ******
2025-10-02 15:16:30.467085 | controller | changed: [instance]
2025-10-02 15:16:30.467187 | controller |
2025-10-02 15:16:30.467284 | controller | TASK [Run ci_local_storage role] ***********************************************
2025-10-02 15:16:30.468118 | controller | Thursday 02 October 2025  15:16:30 +0000 (0:00:00.018)       0:00:01.256 ******
2025-10-02 15:16:30.499755 | controller |
2025-10-02 15:16:30.499870 | controller | TASK [ci_local_storage : Create role needed directories] ***********************
2025-10-02 15:16:30.500200 | controller | Thursday 02 October 2025  15:16:30 +0000 (0:00:00.032)       0:00:01.288 ******
2025-10-02 15:16:30.803153 | controller | changed: [instance]
2025-10-02 15:16:30.803199 | controller |
2025-10-02 15:16:30.803311 | controller | TASK [ci_local_storage : Create the cifmw_cls_namespace namespace"] ************
2025-10-02 15:16:30.803429 | controller | Thursday 02 October 2025  15:16:30 +0000 (0:00:00.303)       0:00:01.591 ******
2025-10-02 15:16:31.619350 | controller | changed: [instance]
2025-10-02 15:16:31.619424 | controller |
2025-10-02 15:16:31.619532 | controller | TASK [ci_local_storage : Save storage manifests as artifacts] ******************
2025-10-02 15:16:31.619639 | controller | Thursday 02 October 2025  15:16:31 +0000 (0:00:00.816)       0:00:02.408 ******
2025-10-02 15:16:32.078127 | controller | changed: [instance]
2025-10-02 15:16:32.078195 | controller |
2025-10-02 15:16:32.078335 | controller | TASK [ci_local_storage : Get k8s nodes] ****************************************
2025-10-02 15:16:32.078469 | controller | Thursday 02 October 2025  15:16:32 +0000 (0:00:00.458)       0:00:02.866 ******
2025-10-02 15:16:32.825895 | controller | ok: [instance]
2025-10-02 15:16:32.825963 | controller |
2025-10-02 15:16:32.826101 | controller | TASK [ci_local_storage : Fetch hostnames for all hosts] ************************
2025-10-02 15:16:32.826210 | controller | Thursday 02 October 2025  15:16:32 +0000 (0:00:00.747)       0:00:03.614 ******
2025-10-02 15:16:34.210678 | controller | changed: [instance] => (item=instance)
2025-10-02 15:16:34.210905 | controller | changed: [instance -> crc] => (item=crc)
2025-10-02 15:16:34.211025 | controller |
2025-10-02 15:16:34.211042 | controller | TASK [ci_local_storage : Set the hosts k8s ansible hosts] **********************
2025-10-02 15:16:34.211110 | controller | Thursday 02 October 2025  15:16:34 +0000 (0:00:01.384)       0:00:04.999 ******
2025-10-02 15:16:34.254570 | controller | ok: [instance]
2025-10-02 15:16:34.254661 | controller |
2025-10-02 15:16:34.254796 | controller | TASK [ci_local_storage : Apply the storage class manifests] ********************
2025-10-02 15:16:34.254882 | controller | Thursday 02 October 2025  15:16:34 +0000 (0:00:00.043)       0:00:05.043 ******
2025-10-02 15:16:34.867176 | controller | changed: [instance]
2025-10-02 15:16:34.867230 | controller |
2025-10-02 15:16:34.867369 | controller | TASK [ci_local_storage : Create directories on worker node] ********************
2025-10-02 15:16:34.867478 | controller | Thursday 02 October 2025  15:16:34 +0000 (0:00:00.612)       0:00:05.656 ******
2025-10-02 15:16:34.895970 | 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-10-02 15:16:34.896178 | controller |
2025-10-02 15:16:34.896305 | controller | TASK [ci_local_storage : Perform action in the PV directory] *******************
2025-10-02 15:16:34.896327 | controller | Thursday 02 October 2025  15:16:34 +0000 (0:00:00.028)       0:00:05.685 ******
2025-10-02 15:16:42.097243 | controller | changed: [instance -> crc] => (item=1)
2025-10-02 15:16:42.098630 | controller | changed: [instance -> crc] => (item=2)
2025-10-02 15:16:42.098809 | controller | changed: [instance -> crc] => (item=3)
2025-10-02 15:16:42.098821 | controller | changed: [instance -> crc] => (item=4)
2025-10-02 15:16:42.098827 | controller | changed: [instance -> crc] => (item=5)
2025-10-02 15:16:42.098847 | controller | changed: [instance -> crc] => (item=6)
2025-10-02 15:16:42.098853 | controller | changed: [instance -> crc] => (item=7)
2025-10-02 15:16:42.098870 | controller | changed: [instance -> crc] => (item=8)
2025-10-02 15:16:42.098876 | controller | changed: [instance -> crc] => (item=9)
2025-10-02 15:16:42.098882 | controller | changed: [instance -> crc] => (item=10)
2025-10-02 15:16:42.098887 | controller |
2025-10-02 15:16:42.098900 | controller | TASK [ci_local_storage : Generate pv related storage manifest file] ************
2025-10-02 15:16:42.098908 | controller | Thursday 02 October 2025  15:16:42 +0000 (0:00:07.200)       0:00:12.886 ******
2025-10-02 15:16:42.460913 | controller | changed: [instance]
2025-10-02 15:16:42.460997 | controller |
2025-10-02 15:16:42.461130 | controller | TASK [ci_local_storage : Apply pv related storage manifest file] ***************
2025-10-02 15:16:42.461234 | controller | Thursday 02 October 2025  15:16:42 +0000 (0:00:00.363)       0:00:13.249 ******
2025-10-02 15:16:43.296652 | controller | changed: [instance]
2025-10-02 15:16:43.296711 | controller |
2025-10-02 15:16:43.296817 | controller | TASK [Not create directories on crc] *******************************************
2025-10-02 15:16:43.296935 | controller | Thursday 02 October 2025  15:16:43 +0000 (0:00:00.832)       0:00:14.082 ******
2025-10-02 15:16:50.322323 | controller | ok: [instance -> crc] => (item=1)
2025-10-02 15:16:50.323170 | controller | ok: [instance -> crc] => (item=2)
2025-10-02 15:16:50.323206 | controller | ok: [instance -> crc] => (item=3)
2025-10-02 15:16:50.323215 | controller | ok: [instance -> crc] => (item=4)
2025-10-02 15:16:50.323239 | controller | ok: [instance -> crc] => (item=5)
2025-10-02 15:16:50.323244 | controller | ok: [instance -> crc] => (item=6)
2025-10-02 15:16:50.323250 | controller | ok: [instance -> crc] => (item=7)
2025-10-02 15:16:50.323255 | controller | ok: [instance -> crc] => (item=8)
2025-10-02 15:16:50.323260 | controller | ok: [instance -> crc] => (item=9)
2025-10-02 15:16:50.323266 | controller | ok: [instance -> crc] => (item=10)
2025-10-02 15:16:50.323273 | controller |
2025-10-02 15:16:50.323421 | controller | TASK [Fail if we have a change] ************************************************
2025-10-02 15:16:50.345074 | controller | Thursday 02 October 2025  15:16:50 +0000 (0:00:07.028)       0:00:21.111 ******
2025-10-02 15:16:50.345106 | controller | skipping: [instance]
2025-10-02 15:16:50.954165 | controller |
2025-10-02 15:16:50.954208 | controller | TASK [Get all pvs] *************************************************************
2025-10-02 15:16:50.954217 | controller | Thursday 02 October 2025  15:16:50 +0000 (0:00:00.022)       0:00:21.133 ******
2025-10-02 15:16:50.954229 | controller | ok: [instance]
2025-10-02 15:16:50.954429 | controller |
2025-10-02 15:16:50.954448 | controller | TASK [Assert that we created the wanted number of pvs] *************************
2025-10-02 15:16:50.954459 | controller | Thursday 02 October 2025  15:16:50 +0000 (0:00:00.608)       0:00:21.742 ******
2025-10-02 15:16:50.987629 | controller | ok: [instance] => changed=false
2025-10-02 15:16:50.987725 | controller |   msg: All assertions passed
2025-10-02 15:16:50.987821 | controller |
2025-10-02 15:16:50.987959 | controller | TASK [Gather names from pvs] ***************************************************
2025-10-02 15:16:50.988114 | controller | Thursday 02 October 2025  15:16:50 +0000 (0:00:00.033)       0:00:21.776 ******
2025-10-02 15:16:51.026741 | controller | ok: [instance]
2025-10-02 15:16:51.026811 | controller |
2025-10-02 15:16:51.026902 | controller | TASK [Gather computed PV names] ************************************************
2025-10-02 15:16:51.027066 | controller | Thursday 02 October 2025  15:16:51 +0000 (0:00:00.039)       0:00:21.815 ******
2025-10-02 15:16:51.116175 | controller | ok: [instance] => (item=1)
2025-10-02 15:16:51.116214 | controller | ok: [instance] => (item=2)
2025-10-02 15:16:51.116337 | controller | ok: [instance] => (item=3)
2025-10-02 15:16:51.116434 | controller | ok: [instance] => (item=4)
2025-10-02 15:16:51.116534 | controller | ok: [instance] => (item=5)
2025-10-02 15:16:51.116627 | controller | ok: [instance] => (item=6)
2025-10-02 15:16:51.116714 | controller | ok: [instance] => (item=7)
2025-10-02 15:16:51.116805 | controller | ok: [instance] => (item=8)
2025-10-02 15:16:51.116898 | controller | ok: [instance] => (item=9)
2025-10-02 15:16:51.117253 | controller | ok: [instance] => (item=10)
2025-10-02 15:16:51.117312 | controller |
2025-10-02 15:16:51.117322 | controller | TASK [Assert that the pvs names are correct] ***********************************
2025-10-02 15:16:51.117331 | controller | Thursday 02 October 2025  15:16:51 +0000 (0:00:00.089)       0:00:21.905 ******
2025-10-02 15:16:51.180860 | controller | ok: [instance] => (item=['molecule-storage01-crc', 'molecule-storage01-crc']) => changed=false
2025-10-02 15:16:51.180947 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.181066 | controller |   item:
2025-10-02 15:16:51.181172 | controller |   - molecule-storage01-crc
2025-10-02 15:16:51.181269 | controller |   - molecule-storage01-crc
2025-10-02 15:16:51.181357 | controller |   msg: All assertions passed
2025-10-02 15:16:51.181454 | controller | ok: [instance] => (item=['molecule-storage02-crc', 'molecule-storage02-crc']) => changed=false
2025-10-02 15:16:51.181548 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.181642 | controller |   item:
2025-10-02 15:16:51.181742 | controller |   - molecule-storage02-crc
2025-10-02 15:16:51.181838 | controller |   - molecule-storage02-crc
2025-10-02 15:16:51.181943 | controller |   msg: All assertions passed
2025-10-02 15:16:51.182060 | controller | ok: [instance] => (item=['molecule-storage03-crc', 'molecule-storage03-crc']) => changed=false
2025-10-02 15:16:51.182155 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.182243 | controller |   item:
2025-10-02 15:16:51.182334 | controller |   - molecule-storage03-crc
2025-10-02 15:16:51.182435 | controller |   - molecule-storage03-crc
2025-10-02 15:16:51.182526 | controller |   msg: All assertions passed
2025-10-02 15:16:51.182622 | controller | ok: [instance] => (item=['molecule-storage04-crc', 'molecule-storage04-crc']) => changed=false
2025-10-02 15:16:51.182716 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.182809 | controller |   item:
2025-10-02 15:16:51.182901 | controller |   - molecule-storage04-crc
2025-10-02 15:16:51.183021 | controller |   - molecule-storage04-crc
2025-10-02 15:16:51.183121 | controller |   msg: All assertions passed
2025-10-02 15:16:51.183220 | controller | ok: [instance] => (item=['molecule-storage05-crc', 'molecule-storage05-crc']) => changed=false
2025-10-02 15:16:51.183315 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.183404 | controller |   item:
2025-10-02 15:16:51.183495 | controller |   - molecule-storage05-crc
2025-10-02 15:16:51.183586 | controller |   - molecule-storage05-crc
2025-10-02 15:16:51.183676 | controller |   msg: All assertions passed
2025-10-02 15:16:51.183773 | controller | ok: [instance] => (item=['molecule-storage06-crc', 'molecule-storage06-crc']) => changed=false
2025-10-02 15:16:51.183874 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.183974 | controller |   item:
2025-10-02 15:16:51.184082 | controller |   - molecule-storage06-crc
2025-10-02 15:16:51.184179 | controller |   - molecule-storage06-crc
2025-10-02 15:16:51.184273 | controller |   msg: All assertions passed
2025-10-02 15:16:51.184370 | controller | ok: [instance] => (item=['molecule-storage07-crc', 'molecule-storage07-crc']) => changed=false
2025-10-02 15:16:51.184458 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.184549 | controller |   item:
2025-10-02 15:16:51.184639 | controller |   - molecule-storage07-crc
2025-10-02 15:16:51.184728 | controller |   - molecule-storage07-crc
2025-10-02 15:16:51.184818 | controller |   msg: All assertions passed
2025-10-02 15:16:51.184915 | controller | ok: [instance] => (item=['molecule-storage08-crc', 'molecule-storage08-crc']) => changed=false
2025-10-02 15:16:51.185027 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.185135 | controller |   item:
2025-10-02 15:16:51.185226 | controller |   - molecule-storage08-crc
2025-10-02 15:16:51.185321 | controller |   - molecule-storage08-crc
2025-10-02 15:16:51.185413 | controller |   msg: All assertions passed
2025-10-02 15:16:51.185513 | controller | ok: [instance] => (item=['molecule-storage09-crc', 'molecule-storage09-crc']) => changed=false
2025-10-02 15:16:51.185603 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.185691 | controller |   item:
2025-10-02 15:16:51.185781 | controller |   - molecule-storage09-crc
2025-10-02 15:16:51.185871 | controller |   - molecule-storage09-crc
2025-10-02 15:16:51.185973 | controller |   msg: All assertions passed
2025-10-02 15:16:51.186091 | controller | ok: [instance] => (item=['molecule-storage10-crc', 'molecule-storage10-crc']) => changed=false
2025-10-02 15:16:51.186188 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.186278 | controller |   item:
2025-10-02 15:16:51.186369 | controller |   - molecule-storage10-crc
2025-10-02 15:16:51.186457 | controller |   - molecule-storage10-crc
2025-10-02 15:16:51.186548 | controller |   msg: All assertions passed
2025-10-02 15:16:51.186634 | controller |
2025-10-02 15:16:51.186727 | controller | TASK [Gather mountpoints from pvs] *********************************************
2025-10-02 15:16:51.186828 | controller | Thursday 02 October 2025  15:16:51 +0000 (0:00:00.064)       0:00:21.970 ******
2025-10-02 15:16:51.216842 | controller | ok: [instance]
2025-10-02 15:16:51.216949 | controller |
2025-10-02 15:16:51.217100 | controller | TASK [Gather computed mountpoints] *********************************************
2025-10-02 15:16:51.217237 | controller | Thursday 02 October 2025  15:16:51 +0000 (0:00:00.035)       0:00:22.005 ******
2025-10-02 15:16:51.299880 | controller | ok: [instance] => (item=1)
2025-10-02 15:16:51.299940 | controller | ok: [instance] => (item=2)
2025-10-02 15:16:51.300068 | controller | ok: [instance] => (item=3)
2025-10-02 15:16:51.300190 | controller | ok: [instance] => (item=4)
2025-10-02 15:16:51.300289 | controller | ok: [instance] => (item=5)
2025-10-02 15:16:51.300381 | controller | ok: [instance] => (item=6)
2025-10-02 15:16:51.300475 | controller | ok: [instance] => (item=7)
2025-10-02 15:16:51.300560 | controller | ok: [instance] => (item=8)
2025-10-02 15:16:51.300652 | controller | ok: [instance] => (item=9)
2025-10-02 15:16:51.300753 | controller | ok: [instance] => (item=10)
2025-10-02 15:16:51.300846 | controller |
2025-10-02 15:16:51.300976 | controller | TASK [Assert that the mountpoints in the pv are correct] ***********************
2025-10-02 15:16:51.301165 | controller | Thursday 02 October 2025  15:16:51 +0000 (0:00:00.083)       0:00:22.088 ******
2025-10-02 15:16:51.366836 | controller | ok: [instance] => (item=['/mnt/openstack/pv01', '/mnt/openstack/pv01']) => changed=false
2025-10-02 15:16:51.366896 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.367016 | controller |   item:
2025-10-02 15:16:51.367147 | controller |   - /mnt/openstack/pv01
2025-10-02 15:16:51.367244 | controller |   - /mnt/openstack/pv01
2025-10-02 15:16:51.367341 | controller |   msg: All assertions passed
2025-10-02 15:16:51.367437 | controller | ok: [instance] => (item=['/mnt/openstack/pv02', '/mnt/openstack/pv02']) => changed=false
2025-10-02 15:16:51.367523 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.367628 | controller |   item:
2025-10-02 15:16:51.367718 | controller |   - /mnt/openstack/pv02
2025-10-02 15:16:51.367815 | controller |   - /mnt/openstack/pv02
2025-10-02 15:16:51.367904 | controller |   msg: All assertions passed
2025-10-02 15:16:51.368034 | controller | ok: [instance] => (item=['/mnt/openstack/pv03', '/mnt/openstack/pv03']) => changed=false
2025-10-02 15:16:51.368151 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.368258 | controller |   item:
2025-10-02 15:16:51.368346 | controller |   - /mnt/openstack/pv03
2025-10-02 15:16:51.368441 | controller |   - /mnt/openstack/pv03
2025-10-02 15:16:51.368538 | controller |   msg: All assertions passed
2025-10-02 15:16:51.368634 | controller | ok: [instance] => (item=['/mnt/openstack/pv04', '/mnt/openstack/pv04']) => changed=false
2025-10-02 15:16:51.368725 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.368820 | controller |   item:
2025-10-02 15:16:51.368911 | controller |   - /mnt/openstack/pv04
2025-10-02 15:16:51.369021 | controller |   - /mnt/openstack/pv04
2025-10-02 15:16:51.369126 | controller |   msg: All assertions passed
2025-10-02 15:16:51.369228 | controller | ok: [instance] => (item=['/mnt/openstack/pv05', '/mnt/openstack/pv05']) => changed=false
2025-10-02 15:16:51.369331 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.369412 | controller |   item:
2025-10-02 15:16:51.369503 | controller |   - /mnt/openstack/pv05
2025-10-02 15:16:51.369599 | controller |   - /mnt/openstack/pv05
2025-10-02 15:16:51.369691 | controller |   msg: All assertions passed
2025-10-02 15:16:51.369803 | controller | ok: [instance] => (item=['/mnt/openstack/pv06', '/mnt/openstack/pv06']) => changed=false
2025-10-02 15:16:51.369891 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.369997 | controller |   item:
2025-10-02 15:16:51.370102 | controller |   - /mnt/openstack/pv06
2025-10-02 15:16:51.370201 | controller |   - /mnt/openstack/pv06
2025-10-02 15:16:51.370299 | controller |   msg: All assertions passed
2025-10-02 15:16:51.370397 | controller | ok: [instance] => (item=['/mnt/openstack/pv07', '/mnt/openstack/pv07']) => changed=false
2025-10-02 15:16:51.370485 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.370581 | controller |   item:
2025-10-02 15:16:51.370674 | controller |   - /mnt/openstack/pv07
2025-10-02 15:16:51.370763 | controller |   - /mnt/openstack/pv07
2025-10-02 15:16:51.370858 | controller |   msg: All assertions passed
2025-10-02 15:16:51.370972 | controller | ok: [instance] => (item=['/mnt/openstack/pv08', '/mnt/openstack/pv08']) => changed=false
2025-10-02 15:16:51.371084 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.371181 | controller |   item:
2025-10-02 15:16:51.371278 | controller |   - /mnt/openstack/pv08
2025-10-02 15:16:51.371373 | controller |   - /mnt/openstack/pv08
2025-10-02 15:16:51.371463 | controller |   msg: All assertions passed
2025-10-02 15:16:51.371558 | controller | ok: [instance] => (item=['/mnt/openstack/pv09', '/mnt/openstack/pv09']) => changed=false
2025-10-02 15:16:51.371647 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.371736 | controller |   item:
2025-10-02 15:16:51.371832 | controller |   - /mnt/openstack/pv09
2025-10-02 15:16:51.371930 | controller |   - /mnt/openstack/pv09
2025-10-02 15:16:51.372021 | controller |   msg: All assertions passed
2025-10-02 15:16:51.372141 | controller | ok: [instance] => (item=['/mnt/openstack/pv10', '/mnt/openstack/pv10']) => changed=false
2025-10-02 15:16:51.372244 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.372324 | controller |   item:
2025-10-02 15:16:51.372410 | controller |   - /mnt/openstack/pv10
2025-10-02 15:16:51.372506 | controller |   - /mnt/openstack/pv10
2025-10-02 15:16:51.372597 | controller |   msg: All assertions passed
2025-10-02 15:16:51.372682 | controller |
2025-10-02 15:16:51.372780 | controller | TASK [Assert that the storage class is correct] ********************************
2025-10-02 15:16:51.372874 | controller | Thursday 02 October 2025  15:16:51 +0000 (0:00:00.066)       0:00:22.155 ******
2025-10-02 15:16:51.438354 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-02 15:16:51.438453 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.438569 | controller |   item: molecule-storage
2025-10-02 15:16:51.438695 | controller |   msg: All assertions passed
2025-10-02 15:16:51.438815 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-02 15:16:51.438896 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.439018 | controller |   item: molecule-storage
2025-10-02 15:16:51.439128 | controller |   msg: All assertions passed
2025-10-02 15:16:51.439228 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-02 15:16:51.439322 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.439420 | controller |   item: molecule-storage
2025-10-02 15:16:51.439511 | controller |   msg: All assertions passed
2025-10-02 15:16:51.439617 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-02 15:16:51.439755 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.439837 | controller |   item: molecule-storage
2025-10-02 15:16:51.439934 | controller |   msg: All assertions passed
2025-10-02 15:16:51.440167 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-02 15:16:51.440271 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.440365 | controller |   item: molecule-storage
2025-10-02 15:16:51.440459 | controller |   msg: All assertions passed
2025-10-02 15:16:51.440633 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-02 15:16:51.440671 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.440777 | controller |   item: molecule-storage
2025-10-02 15:16:51.440856 | controller |   msg: All assertions passed
2025-10-02 15:16:51.440958 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-02 15:16:51.441062 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.441186 | controller |   item: molecule-storage
2025-10-02 15:16:51.441281 | controller |   msg: All assertions passed
2025-10-02 15:16:51.441373 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-02 15:16:51.441451 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.441545 | controller |   item: molecule-storage
2025-10-02 15:16:51.441643 | controller |   msg: All assertions passed
2025-10-02 15:16:51.441750 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-02 15:16:51.441845 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.441945 | controller |   item: molecule-storage
2025-10-02 15:16:51.442087 | controller |   msg: All assertions passed
2025-10-02 15:16:51.442166 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-02 15:16:51.442268 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.442349 | controller |   item: molecule-storage
2025-10-02 15:16:51.442446 | controller |   msg: All assertions passed
2025-10-02 15:16:51.442515 | controller |
2025-10-02 15:16:51.442620 | controller | TASK [Assert that the storage capacity is correct] *****************************
2025-10-02 15:16:51.442713 | controller | Thursday 02 October 2025  15:16:51 +0000 (0:00:00.071)       0:00:22.227 ******
2025-10-02 15:16:51.517621 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-02 15:16:51.517720 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.517816 | controller |   item: 100Mi
2025-10-02 15:16:51.517913 | controller |   msg: All assertions passed
2025-10-02 15:16:51.518024 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-02 15:16:51.518157 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.518219 | controller |   item: 100Mi
2025-10-02 15:16:51.518316 | controller |   msg: All assertions passed
2025-10-02 15:16:51.518412 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-02 15:16:51.518504 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.518597 | controller |   item: 100Mi
2025-10-02 15:16:51.518687 | controller |   msg: All assertions passed
2025-10-02 15:16:51.518784 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-02 15:16:51.518892 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.518994 | controller |   item: 100Mi
2025-10-02 15:16:51.519112 | controller |   msg: All assertions passed
2025-10-02 15:16:51.519208 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-02 15:16:51.519301 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.519391 | controller |   item: 100Mi
2025-10-02 15:16:51.519481 | controller |   msg: All assertions passed
2025-10-02 15:16:51.519576 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-02 15:16:51.519668 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.519758 | controller |   item: 100Mi
2025-10-02 15:16:51.519849 | controller |   msg: All assertions passed
2025-10-02 15:16:51.519949 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-02 15:16:51.520060 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.520160 | controller |   item: 100Mi
2025-10-02 15:16:51.520253 | controller |   msg: All assertions passed
2025-10-02 15:16:51.520346 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-02 15:16:51.520440 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.520531 | controller |   item: 100Mi
2025-10-02 15:16:51.520629 | controller |   msg: All assertions passed
2025-10-02 15:16:51.520721 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-02 15:16:51.520813 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.520901 | controller |   item: 100Mi
2025-10-02 15:16:51.521005 | controller |   msg: All assertions passed
2025-10-02 15:16:51.521115 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-02 15:16:51.521207 | controller |   ansible_loop_var: item
2025-10-02 15:16:51.521297 | controller |   item: 100Mi
2025-10-02 15:16:51.521388 | controller |   msg: All assertions passed
2025-10-02 15:16:51.521475 | controller |
2025-10-02 15:16:51.521567 | controller | TASK [Delete the created pvs] **************************************************
2025-10-02 15:16:51.521665 | controller | Thursday 02 October 2025  15:16:51 +0000 (0:00:00.079)       0:00:22.306 ******
2025-10-02 15:16:51.548164 | controller |
2025-10-02 15:16:51.548935 | controller | TASK [ci_local_storage : Get all pvs] ******************************************
2025-10-02 15:16:52.184568 | controller | Thursday 02 October 2025  15:16:51 +0000 (0:00:00.030)       0:00:22.337 ******
2025-10-02 15:16:52.184625 | controller | ok: [instance]
2025-10-02 15:16:52.184703 | controller |
2025-10-02 15:16:52.184715 | controller | TASK [ci_local_storage : Export PVs as facts] **********************************
2025-10-02 15:16:52.184803 | controller | Thursday 02 October 2025  15:16:52 +0000 (0:00:00.636)       0:00:22.973 ******
2025-10-02 15:16:52.230264 | controller | ok: [instance]
2025-10-02 15:16:52.230556 | controller |
2025-10-02 15:16:52.230584 | controller | TASK [ci_local_storage : Delete all pvs] ***************************************
2025-10-02 15:16:52.230818 | controller | Thursday 02 October 2025  15:16:52 +0000 (0:00:00.045)       0:00:23.019 ******
2025-10-02 15:16:58.215383 | controller | changed: [instance] => (item=molecule-storage01-crc)
2025-10-02 15:16:58.215424 | controller | changed: [instance] => (item=molecule-storage02-crc)
2025-10-02 15:16:58.215561 | controller | changed: [instance] => (item=molecule-storage03-crc)
2025-10-02 15:16:58.215670 | controller | changed: [instance] => (item=molecule-storage04-crc)
2025-10-02 15:16:58.215752 | controller | changed: [instance] => (item=molecule-storage05-crc)
2025-10-02 15:16:58.215848 | controller | changed: [instance] => (item=molecule-storage06-crc)
2025-10-02 15:16:58.215966 | controller | changed: [instance] => (item=molecule-storage07-crc)
2025-10-02 15:16:58.216070 | controller | changed: [instance] => (item=molecule-storage08-crc)
2025-10-02 15:16:58.216178 | controller | changed: [instance] => (item=molecule-storage09-crc)
2025-10-02 15:16:58.216283 | controller | changed: [instance] => (item=molecule-storage10-crc)
2025-10-02 15:16:58.216367 | controller |
2025-10-02 15:16:58.216471 | controller | TASK [ci_local_storage : Get k8s nodes] ****************************************
2025-10-02 15:16:58.216567 | controller | Thursday 02 October 2025  15:16:58 +0000 (0:00:05.985)       0:00:29.004 ******
2025-10-02 15:16:58.831400 | controller | ok: [instance]
2025-10-02 15:16:58.831443 | controller |
2025-10-02 15:16:58.831553 | controller | TASK [ci_local_storage : Fetch hostnames for all hosts] ************************
2025-10-02 15:16:58.831655 | controller | Thursday 02 October 2025  15:16:58 +0000 (0:00:00.616)       0:00:29.620 ******
2025-10-02 15:16:59.881509 | controller | changed: [instance] => (item=instance)
2025-10-02 15:16:59.881585 | controller | changed: [instance -> crc] => (item=crc)
2025-10-02 15:16:59.881681 | controller |
2025-10-02 15:16:59.881802 | controller | TASK [ci_local_storage : Set the hosts k8s ansible hosts] **********************
2025-10-02 15:16:59.881934 | controller | Thursday 02 October 2025  15:16:59 +0000 (0:00:01.050)       0:00:30.670 ******
2025-10-02 15:16:59.932714 | controller | ok: [instance]
2025-10-02 15:16:59.932825 | controller |
2025-10-02 15:16:59.932961 | controller | TASK [ci_local_storage : Delete directories on worker node] ********************
2025-10-02 15:16:59.933108 | controller | Thursday 02 October 2025  15:16:59 +0000 (0:00:00.051)       0:00:30.721 ******
2025-10-02 15:16:59.964085 | 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-10-02 15:16:59.964231 | controller |
2025-10-02 15:16:59.964389 | controller | TASK [ci_local_storage : Perform action in the PV directory] *******************
2025-10-02 15:16:59.964536 | controller | Thursday 02 October 2025  15:16:59 +0000 (0:00:00.031)       0:00:30.753 ******
2025-10-02 15:17:09.103856 | controller | changed: [instance -> crc] => (item=1)
2025-10-02 15:18:15.044488 | controller | changed: [instance -> crc] => (item=2)
2025-10-02 15:18:15.044542 | controller | changed: [instance -> crc] => (item=3)
2025-10-02 15:18:15.044552 | controller | changed: [instance -> crc] => (item=4)
2025-10-02 15:18:15.044559 | controller | changed: [instance -> crc] => (item=5)
2025-10-02 15:18:15.044565 | controller | changed: [instance -> crc] => (item=6)
2025-10-02 15:18:15.044572 | controller | changed: [instance -> crc] => (item=7)
2025-10-02 15:18:15.044578 | controller | changed: [instance -> crc] => (item=8)
2025-10-02 15:18:15.044590 | controller | changed: [instance -> crc] => (item=9)
2025-10-02 15:18:15.044597 | controller | changed: [instance -> crc] => (item=10)
2025-10-02 15:18:15.044604 | controller |
2025-10-02 15:18:15.044614 | controller | TASK [ci_local_storage : Remove the cifmw_cls_namespace namespace] *************
2025-10-02 15:18:15.044623 | controller | Thursday 02 October 2025  15:17:09 +0000 (0:00:09.137)       0:00:39.891 ******
2025-10-02 15:18:15.044635 | controller | changed: [instance]
2025-10-02 15:18:15.927895 | controller |
2025-10-02 15:18:15.927978 | controller | TASK [Get all namespaces] ******************************************************
2025-10-02 15:18:15.927993 | controller | Thursday 02 October 2025  15:18:15 +0000 (0:01:05.938)       0:01:45.829 ******
2025-10-02 15:18:15.928038 | controller | ok: [instance]
2025-10-02 15:18:15.987279 | controller |
2025-10-02 15:18:15.987328 | controller | TASK [Assert that the cifmw_cls_namespace ns is deleted] ***********************
2025-10-02 15:18:15.987338 | controller | Thursday 02 October 2025  15:18:15 +0000 (0:00:00.886)       0:01:46.716 ******
2025-10-02 15:18:15.987353 | controller | ok: [instance] => changed=false
2025-10-02 15:18:16.051474 | controller |   msg: All assertions passed
2025-10-02 15:18:16.051507 | controller |
2025-10-02 15:18:16.051515 | controller | PLAY RECAP *********************************************************************
2025-10-02 15:18:16.051521 | controller | instance                   : ok=37   changed=13   unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-10-02 15:18:16.051526 | controller |
2025-10-02 15:18:16.051532 | controller | Thursday 02 October 2025  15:18:15 +0000 (0:00:00.057)       0:01:46.773 ******
2025-10-02 15:18:16.051537 | controller | ===============================================================================
2025-10-02 15:18:16.051542 | controller | ci_local_storage : Remove the cifmw_cls_namespace namespace ------------ 65.94s
2025-10-02 15:18:16.051548 | controller | ci_local_storage : Perform action in the PV directory ------------------- 9.14s
2025-10-02 15:18:16.051553 | controller | ci_local_storage : Perform action in the PV directory ------------------- 7.20s
2025-10-02 15:18:16.051558 | controller | Not create directories on crc ------------------------------------------- 7.03s
2025-10-02 15:18:16.051563 | controller | ci_local_storage : Delete all pvs --------------------------------------- 5.99s
2025-10-02 15:18:16.051578 | controller | ci_local_storage : Fetch hostnames for all hosts ------------------------ 1.38s
2025-10-02 15:18:16.051583 | controller | ci_local_storage : Fetch hostnames for all hosts ------------------------ 1.05s
2025-10-02 15:18:16.051589 | controller | Get all namespaces ------------------------------------------------------ 0.89s
2025-10-02 15:18:16.051594 | controller | Gathering Facts --------------------------------------------------------- 0.88s
2025-10-02 15:18:16.051599 | controller | ci_local_storage : Apply pv related storage manifest file --------------- 0.83s
2025-10-02 15:18:16.051604 | controller | ci_local_storage : Create the cifmw_cls_namespace namespace" ------------ 0.82s
2025-10-02 15:18:16.051610 | controller | ci_local_storage : Get k8s nodes ---------------------------------------- 0.75s
2025-10-02 15:18:16.051615 | controller | ci_local_storage : Get all pvs ------------------------------------------ 0.64s
2025-10-02 15:18:16.051620 | controller | ci_local_storage : Get k8s nodes ---------------------------------------- 0.62s
2025-10-02 15:18:16.051625 | controller | ci_local_storage : Apply the storage class manifests -------------------- 0.61s
2025-10-02 15:18:16.051630 | controller | Get all pvs ------------------------------------------------------------- 0.61s
2025-10-02 15:18:16.051635 | controller | ci_local_storage : Save storage manifests as artifacts ------------------ 0.46s
2025-10-02 15:18:16.051640 | controller | ci_local_storage : Generate pv related storage manifest file ------------ 0.36s
2025-10-02 15:18:16.051646 | controller | ci_local_storage : Create role needed directories ----------------------- 0.30s
2025-10-02 15:18:16.051651 | controller | recognize_ssh_keypair : Check if id_ed25519 key exists ------------------ 0.28s
2025-10-02 15:18:16.051665 | controller | INFO     Running default > cleanup
2025-10-02 15:18:16.051929 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-02 15:18:16.052515 | controller | INFO     Writing /tmp/report.html report.
2025-10-02 15:18:16.130186 | controller | changed
2025-10-02 15:18:16.151764 | 
2025-10-02 15:18:16.151833 | PLAY RECAP
2025-10-02 15:18:16.151875 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-02 15:18:16.151898 | 
2025-10-02 15:18:16.216369 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-02 15:18:16.217437 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-02 15:18:16.753187 | 
2025-10-02 15:18:16.753287 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-02 15:18:16.774265 | 
2025-10-02 15:18:16.774365 | TASK [Filter out host if needed]
2025-10-02 15:18:16.783977 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-02 15:18:16.789085 | 
2025-10-02 15:18:16.789229 | TASK [Ensure file is present]
2025-10-02 15:18:17.200323 | controller | ok
2025-10-02 15:18:17.207937 | 
2025-10-02 15:18:17.208020 | TASK [Manage molecule report file]
2025-10-02 15:18:17.733960 | controller | changed
2025-10-02 15:18:17.740021 | 
2025-10-02 15:18:17.740114 | TASK [Check if we get ci-framework-data basedir]
2025-10-02 15:18:18.002439 | controller | ok
2025-10-02 15:18:18.007770 | 
2025-10-02 15:18:18.007847 | TASK [Create ci-framework-data log directory for zuul]
2025-10-02 15:18:18.401184 | controller | changed
2025-10-02 15:18:18.406956 | 
2025-10-02 15:18:18.407027 | TASK [Copy ci-framework interesting files]
2025-10-02 15:18:18.675303 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-02 15:18:18.932435 | controller | changed
2025-10-02 15:18:18.937911 | 
2025-10-02 15:18:18.937988 | TASK [Get SELinux listing]
2025-10-02 15:18:19.464695 | controller | changed
2025-10-02 15:18:19.470615 | 
2025-10-02 15:18:19.470689 | TASK [Generate log index]
2025-10-02 15:18:21.225803 | controller | changed
2025-10-02 15:18:21.230550 | 
2025-10-02 15:18:21.230613 | TASK [Get some env related data]
2025-10-02 15:18:22.173439 | controller | /home/zuul/.local/bin/ansible
2025-10-02 15:18:23.262945 | controller | changed
2025-10-02 15:18:23.268242 | 
2025-10-02 15:18:23.268306 | TASK [Generate list of logs to collect in home directory]
2025-10-02 15:18:23.661023 | controller | ok: All paths examined
2025-10-02 15:18:23.666515 | 
2025-10-02 15:18:23.666584 | LOOP [Copy logs from home directory]
2025-10-02 15:18:24.409397 | controller | changed:
2025-10-02 15:18:24.409590 | controller | {
2025-10-02 15:18:24.409619 | controller |   "atime": 1743544925.4788878,
2025-10-02 15:18:24.409639 | controller |   "ctime": 1743545329.1409318,
2025-10-02 15:18:24.409657 | controller |   "dev": 64513,
2025-10-02 15:18:24.409673 | controller |   "gid": 1000,
2025-10-02 15:18:24.409689 | controller |   "gr_name": "zuul",
2025-10-02 15:18:24.409705 | controller |   "inode": 4518807,
2025-10-02 15:18:24.409719 | controller |   "isblk": false,
2025-10-02 15:18:24.409734 | controller |   "ischr": false,
2025-10-02 15:18:24.409748 | controller |   "isdir": false,
2025-10-02 15:18:24.409762 | controller |   "isfifo": false,
2025-10-02 15:18:24.409775 | controller |   "isgid": false,
2025-10-02 15:18:24.409803 | controller |   "islnk": false,
2025-10-02 15:18:24.409817 | controller |   "isreg": true,
2025-10-02 15:18:24.409830 | controller |   "issock": false,
2025-10-02 15:18:24.409843 | controller |   "isuid": false,
2025-10-02 15:18:24.409856 | controller |   "mode": "0644",
2025-10-02 15:18:24.409869 | controller |   "mtime": 1743545329.1409318,
2025-10-02 15:18:24.409882 | controller |   "nlink": 1,
2025-10-02 15:18:24.409896 | controller |   "path": "/home/zuul/crc-setup.log",
2025-10-02 15:18:24.409910 | controller |   "pw_name": "zuul",
2025-10-02 15:18:24.409924 | controller |   "rgrp": true,
2025-10-02 15:18:24.409938 | controller |   "roth": true,
2025-10-02 15:18:24.409952 | controller |   "rusr": true,
2025-10-02 15:18:24.409971 | controller |   "size": 4108,
2025-10-02 15:18:24.409986 | controller |   "uid": 1000,
2025-10-02 15:18:24.410000 | controller |   "wgrp": false,
2025-10-02 15:18:24.410014 | controller |   "woth": false,
2025-10-02 15:18:24.410030 | controller |   "wusr": true,
2025-10-02 15:18:24.410044 | controller |   "xgrp": false,
2025-10-02 15:18:24.410058 | controller |   "xoth": false,
2025-10-02 15:18:24.410071 | controller |   "xusr": false
2025-10-02 15:18:24.410086 | controller | }
2025-10-02 15:18:25.221290 | controller | changed:
2025-10-02 15:18:25.221404 | controller | {
2025-10-02 15:18:25.221437 | controller |   "atime": 1743545331.0429637,
2025-10-02 15:18:25.221460 | controller |   "ctime": 1743545842.0111232,
2025-10-02 15:18:25.221479 | controller |   "dev": 64513,
2025-10-02 15:18:25.221496 | controller |   "gid": 1000,
2025-10-02 15:18:25.221512 | controller |   "gr_name": "zuul",
2025-10-02 15:18:25.221528 | controller |   "inode": 4194437,
2025-10-02 15:18:25.221542 | controller |   "isblk": false,
2025-10-02 15:18:25.221556 | controller |   "ischr": false,
2025-10-02 15:18:25.221570 | controller |   "isdir": false,
2025-10-02 15:18:25.221584 | controller |   "isfifo": false,
2025-10-02 15:18:25.221598 | controller |   "isgid": false,
2025-10-02 15:18:25.221611 | controller |   "islnk": false,
2025-10-02 15:18:25.221625 | controller |   "isreg": true,
2025-10-02 15:18:25.221639 | controller |   "issock": false,
2025-10-02 15:18:25.221653 | controller |   "isuid": false,
2025-10-02 15:18:25.221666 | controller |   "mode": "0644",
2025-10-02 15:18:25.221680 | controller |   "mtime": 1743545842.0111232,
2025-10-02 15:18:25.221694 | controller |   "nlink": 1,
2025-10-02 15:18:25.221707 | controller |   "path": "/home/zuul/crc-start.log",
2025-10-02 15:18:25.221721 | controller |   "pw_name": "zuul",
2025-10-02 15:18:25.221734 | controller |   "rgrp": true,
2025-10-02 15:18:25.221748 | controller |   "roth": true,
2025-10-02 15:18:25.221761 | controller |   "rusr": true,
2025-10-02 15:18:25.221774 | controller |   "size": 4023,
2025-10-02 15:18:25.221799 | controller |   "uid": 1000,
2025-10-02 15:18:25.221814 | controller |   "wgrp": false,
2025-10-02 15:18:25.221828 | controller |   "woth": false,
2025-10-02 15:18:25.221841 | controller |   "wusr": true,
2025-10-02 15:18:25.221854 | controller |   "xgrp": false,
2025-10-02 15:18:25.221867 | controller |   "xoth": false,
2025-10-02 15:18:25.221880 | controller |   "xusr": false
2025-10-02 15:18:25.221893 | controller | }
2025-10-02 15:18:25.992002 | controller | changed:
2025-10-02 15:18:25.992091 | controller | {
2025-10-02 15:18:25.992117 | controller |   "atime": 1759417856.973905,
2025-10-02 15:18:25.992136 | controller |   "ctime": 1759417872.9511604,
2025-10-02 15:18:25.992152 | controller |   "dev": 64513,
2025-10-02 15:18:25.992168 | controller |   "gid": 1000,
2025-10-02 15:18:25.992183 | controller |   "gr_name": "zuul",
2025-10-02 15:18:25.992198 | controller |   "inode": 4686287,
2025-10-02 15:18:25.992212 | controller |   "isblk": false,
2025-10-02 15:18:25.992227 | controller |   "ischr": false,
2025-10-02 15:18:25.992241 | controller |   "isdir": false,
2025-10-02 15:18:25.992255 | controller |   "isfifo": false,
2025-10-02 15:18:25.992268 | controller |   "isgid": false,
2025-10-02 15:18:25.992282 | controller |   "islnk": false,
2025-10-02 15:18:25.992296 | controller |   "isreg": true,
2025-10-02 15:18:25.992309 | controller |   "issock": false,
2025-10-02 15:18:25.992322 | controller |   "isuid": false,
2025-10-02 15:18:25.992335 | controller |   "mode": "0644",
2025-10-02 15:18:25.992348 | controller |   "mtime": 1759417872.9511604,
2025-10-02 15:18:25.992361 | controller |   "nlink": 1,
2025-10-02 15:18:25.992374 | controller |   "path": "/home/zuul/ansible.log",
2025-10-02 15:18:25.992453 | controller |   "pw_name": "zuul",
2025-10-02 15:18:25.992478 | controller |   "rgrp": true,
2025-10-02 15:18:25.992495 | controller |   "roth": true,
2025-10-02 15:18:25.992513 | controller |   "rusr": true,
2025-10-02 15:18:25.992528 | controller |   "size": 6749,
2025-10-02 15:18:25.992548 | controller |   "uid": 1000,
2025-10-02 15:18:25.992563 | controller |   "wgrp": false,
2025-10-02 15:18:25.992577 | controller |   "woth": false,
2025-10-02 15:18:25.992590 | controller |   "wusr": true,
2025-10-02 15:18:25.992603 | controller |   "xgrp": false,
2025-10-02 15:18:25.992616 | controller |   "xoth": false,
2025-10-02 15:18:25.992629 | controller |   "xusr": false
2025-10-02 15:18:25.992642 | controller | }
2025-10-02 15:18:26.004109 | 
2025-10-02 15:18:26.004222 | TASK [Copy crio stats log file]
2025-10-02 15:18:26.018260 | controller | skipping: Conditional result was False
2025-10-02 15:18:26.024338 | 
2025-10-02 15:18:26.024425 | TASK [Get SELinux related data]
2025-10-02 15:18:26.478000 | controller | <no matches>
2025-10-02 15:18:26.555866 | controller | ERROR
2025-10-02 15:18:26.556055 | controller | {
2025-10-02 15:18:26.556093 | controller |   "delta": "0:00:00.013499",
2025-10-02 15:18:26.556118 | controller |   "end": "2025-10-02 15:18:26.480350",
2025-10-02 15:18:26.556139 | controller |   "msg": "non-zero return code",
2025-10-02 15:18:26.556157 | controller |   "rc": 1,
2025-10-02 15:18:26.556177 | controller |   "start": "2025-10-02 15:18:26.466851"
2025-10-02 15:18:26.556196 | controller | }
2025-10-02 15:18:26.556220 | controller | ERROR: Ignoring Errors
2025-10-02 15:18:26.562127 | 
2025-10-02 15:18:26.562215 | TASK [Create system configuration directory]
2025-10-02 15:18:27.036795 | controller | changed
2025-10-02 15:18:27.042516 | 
2025-10-02 15:18:27.042605 | TASK [Get some of the system configurations]
2025-10-02 15:18:27.570920 | controller | changed
2025-10-02 15:18:27.575960 | 
2025-10-02 15:18:27.576026 | TASK [Copy generated documentation if available]
2025-10-02 15:18:27.589489 | controller | skipping: Conditional result was False
2025-10-02 15:18:27.594714 | 
2025-10-02 15:18:27.594791 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-02 15:18:27.608194 | controller | skipping: Conditional result was False
2025-10-02 15:18:27.614866 | 
2025-10-02 15:18:27.614934 | TASK [Compress logs bigger than 2MB]
2025-10-02 15:18:28.144512 | controller | changed
2025-10-02 15:18:28.150303 | 
2025-10-02 15:18:28.150373 | TASK [Copy files from workspace on node]
2025-10-02 15:18:28.180243 | controller | ok
2025-10-02 15:18:28.204034 | 
2025-10-02 15:18:28.204106 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-02 15:18:28.218084 | controller | skipping: Conditional result was False
2025-10-02 15:18:28.223753 | 
2025-10-02 15:18:28.223834 | TASK [fetch-output : Set log path for single node]
2025-10-02 15:18:28.253003 | controller | ok
2025-10-02 15:18:28.258237 | 
2025-10-02 15:18:28.258301 | LOOP [fetch-output : Ensure local output dirs]
2025-10-02 15:18:28.459661 | controller -> localhost | ok: "/var/lib/zuul/builds/1ff118f029314cad8eb4891845c2cc76/work/logs"
2025-10-02 15:18:28.459892 | controller -> localhost | changed: All items complete
2025-10-02 15:18:28.459922 | 
2025-10-02 15:18:28.643325 | controller -> localhost | changed: "/var/lib/zuul/builds/1ff118f029314cad8eb4891845c2cc76/work/artifacts"
2025-10-02 15:18:28.824250 | controller -> localhost | changed: "/var/lib/zuul/builds/1ff118f029314cad8eb4891845c2cc76/work/docs"
2025-10-02 15:18:28.836676 | 
2025-10-02 15:18:28.836757 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-02 15:18:29.528120 | controller | changed:
2025-10-02 15:18:29.528285 | controller | .d..t...... ./
2025-10-02 15:18:29.528316 | controller | >f+++++++++ README.html
2025-10-02 15:18:29.528339 | controller | >f+++++++++ ansible-execution.log
2025-10-02 15:18:29.528359 | controller | >f+++++++++ ansible.log
2025-10-02 15:18:29.528379 | controller | >f+++++++++ crc-setup.log
2025-10-02 15:18:29.528436 | controller | >f+++++++++ crc-start.log
2025-10-02 15:18:29.528458 | controller | >f+++++++++ dmesg.log
2025-10-02 15:18:29.528484 | controller | >f+++++++++ installed-pkgs.log
2025-10-02 15:18:29.528509 | controller | >f+++++++++ python.log
2025-10-02 15:18:29.528526 | controller | >f+++++++++ registries.conf
2025-10-02 15:18:29.528548 | controller | >f+++++++++ report.html
2025-10-02 15:18:29.528566 | controller | >f+++++++++ selinux-denials.log
2025-10-02 15:18:29.528582 | controller | >f+++++++++ selinux-listing.log
2025-10-02 15:18:29.528598 | controller | cd+++++++++ ci-framework-data/
2025-10-02 15:18:29.528613 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-02 15:18:29.528629 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-02 15:18:29.528644 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-10-02 15:18:29.528659 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/storage/
2025-10-02 15:18:29.528680 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/storage/storage-class.yaml
2025-10-02 15:18:29.528697 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/storage/storage.yaml
2025-10-02 15:18:29.528712 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-02 15:18:29.528727 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-02 15:18:29.528742 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-02 15:18:29.528758 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-02 15:18:29.528774 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-02 15:18:29.528801 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-02 15:18:29.528817 | controller | cd+++++++++ registries.conf.d/
2025-10-02 15:18:29.528833 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-02 15:18:29.528849 | controller | cd+++++++++ system-config/
2025-10-02 15:18:29.528864 | controller | cd+++++++++ system-config/libvirt/
2025-10-02 15:18:29.528879 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-02 15:18:29.528894 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-02 15:18:29.528909 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-10-02 15:18:29.528923 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-10-02 15:18:29.528938 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-10-02 15:18:29.528952 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-10-02 15:18:29.528967 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-10-02 15:18:29.528981 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-10-02 15:18:29.528998 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-10-02 15:18:29.529014 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-10-02 15:18:29.529031 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-10-02 15:18:29.529046 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-10-02 15:18:29.529062 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-10-02 15:18:29.529077 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-10-02 15:18:29.529092 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-10-02 15:18:29.529107 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-10-02 15:18:30.062087 | controller | changed: .d..t...... ./
2025-10-02 15:18:30.651250 | controller | changed: .d..t...... ./
2025-10-02 15:18:30.670904 | 
2025-10-02 15:18:30.670994 | TASK [Return artifact to Zuul]
2025-10-02 15:18:30.699055 | controller | ok
2025-10-02 15:18:30.718195 | 
2025-10-02 15:18:30.718264 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-02 15:18:30.718359 | 
2025-10-02 15:18:30.718406 | PLAY RECAP
2025-10-02 15:18:30.718448 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-02 15:18:30.718471 | 
2025-10-02 15:18:30.800961 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-02 15:18:30.801695 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-02 15:18:31.311849 | 
2025-10-02 15:18:31.311955 | PLAY [all]
2025-10-02 15:18:31.330515 | 
2025-10-02 15:18:31.330591 | TASK [include_role : fetch-output]
2025-10-02 15:18:31.358770 | controller | ok
2025-10-02 15:18:31.375554 | 
2025-10-02 15:18:31.375644 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-02 15:18:31.420705 | controller | skipping: Conditional result was False
2025-10-02 15:18:31.426633 | 
2025-10-02 15:18:31.426719 | TASK [fetch-output : Set log path for single node]
2025-10-02 15:18:31.454924 | controller | ok
2025-10-02 15:18:31.459899 | 
2025-10-02 15:18:31.459971 | LOOP [fetch-output : Ensure local output dirs]
2025-10-02 15:18:31.800875 | controller -> localhost | ok: "/var/lib/zuul/builds/1ff118f029314cad8eb4891845c2cc76/work/logs"
2025-10-02 15:18:31.985728 | controller -> localhost | ok: "/var/lib/zuul/builds/1ff118f029314cad8eb4891845c2cc76/work/artifacts"
2025-10-02 15:18:32.169131 | controller -> localhost | ok: "/var/lib/zuul/builds/1ff118f029314cad8eb4891845c2cc76/work/docs"
2025-10-02 15:18:32.181082 | 
2025-10-02 15:18:32.181173 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-02 15:18:32.827798 | controller | ok
2025-10-02 15:18:32.827983 | controller | ok: All items complete
2025-10-02 15:18:32.828014 | 
2025-10-02 15:18:33.359562 | controller | ok
2025-10-02 15:18:33.852019 | controller | ok
2025-10-02 15:18:33.867298 | 
2025-10-02 15:18:33.867404 | TASK [include_role : fetch-output-openshift]
2025-10-02 15:18:33.880709 | controller | skipping: Conditional result was False
2025-10-02 15:18:33.886475 | 
2025-10-02 15:18:33.886542 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-02 15:18:34.247573 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006375
2025-10-02 15:18:34.443836 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006690
2025-10-02 15:18:34.473516 | 
2025-10-02 15:18:34.473619 | PLAY [all]
2025-10-02 15:18:34.489485 | 
2025-10-02 15:18:34.489554 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-02 15:18:34.988032 | controller | changed
2025-10-02 15:18:35.012217 | 
2025-10-02 15:18:35.012296 | PLAY RECAP
2025-10-02 15:18:35.012341 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-02 15:18:35.012365 | 
2025-10-02 15:18:35.090666 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-02 15:18:35.091353 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-02 15:18:35.655995 | 
2025-10-02 15:18:35.656105 | PLAY [localhost]
2025-10-02 15:18:35.673732 | 
2025-10-02 15:18:35.673834 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-02 15:18:35.985565 | localhost | changed
2025-10-02 15:18:35.990090 | 
2025-10-02 15:18:35.990166 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-02 15:18:36.007799 | localhost | ok
2025-10-02 15:18:36.015451 | 
2025-10-02 15:18:36.015518 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-02 15:18:36.316351 | localhost | changed
2025-10-02 15:18:36.321942 | 
2025-10-02 15:18:36.322013 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-02 15:18:36.919437 | localhost | changed
2025-10-02 15:18:36.925164 | 
2025-10-02 15:18:36.925246 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-02 15:18:37.300912 | localhost | Identity added: /var/lib/zuul/builds/1ff118f029314cad8eb4891845c2cc76/work/tmp/ansible.6owzw_cq (/var/lib/zuul/builds/1ff118f029314cad8eb4891845c2cc76/work/tmp/ansible.6owzw_cq)
2025-10-02 15:18:37.301090 | localhost | ok: Runtime: 0:00:00.006813
2025-10-02 15:18:37.305639 | 
2025-10-02 15:18:37.305708 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-02 15:18:37.526828 | localhost | ok: Runtime: 0:00:00.005901
2025-10-02 15:18:37.531543 | 
2025-10-02 15:18:37.531610 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-02 15:18:37.581056 | localhost | changed
2025-10-02 15:18:37.585876 | 
2025-10-02 15:18:37.585956 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-02 15:18:37.918260 | localhost | changed
2025-10-02 15:18:37.938576 | 
2025-10-02 15:18:37.938648 | PLAY [localhost]
2025-10-02 15:18:37.950503 | 
2025-10-02 15:18:37.950564 | TASK [Generate bulk log download script]
2025-10-02 15:18:37.969023 | localhost | ok
2025-10-02 15:18:37.980577 | 
2025-10-02 15:18:37.980642 | TASK [local-log-download : Check API endpoint is defined]
2025-10-02 15:18:38.007971 | localhost | ok: All assertions passed
2025-10-02 15:18:38.012452 | 
2025-10-02 15:18:38.012520 | TASK [local-log-download : Create download script]
2025-10-02 15:18:38.380823 | localhost -> localhost | changed
2025-10-02 15:18:38.389703 | 
2025-10-02 15:18:38.389770 | TASK [Register quick-download link]
2025-10-02 15:18:38.407948 | localhost | ok
2025-10-02 15:18:38.443495 | 
2025-10-02 15:18:38.443579 | PLAY [logserver.rdoproject.org]
2025-10-02 15:18:38.453579 | 
2025-10-02 15:18:38.453640 | TASK [Set zuul-log-path fact]
2025-10-02 15:18:38.469695 | logserver.rdoproject.org | ok
2025-10-02 15:18:38.478753 | 
2025-10-02 15:18:38.478829 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-02 15:18:38.504701 | logserver.rdoproject.org | ok
2025-10-02 15:18:38.510278 | 
2025-10-02 15:18:38.510342 | TASK [upload-logs : Create log directories]
2025-10-02 15:18:40.142259 | logserver.rdoproject.org | changed
2025-10-02 15:18:40.145600 | 
2025-10-02 15:18:40.145671 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-02 15:18:40.392872 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005533
2025-10-02 15:18:40.397319 | 
2025-10-02 15:18:40.397418 | TASK [upload-logs : Upload logs to log server]
2025-10-02 15:18:42.504399 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-02 15:18:42.507801 | 
2025-10-02 15:18:42.507868 | LOOP [upload-logs : Compress console log and json output]
2025-10-02 15:18:42.548489 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 15:18:42.557906 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 15:18:42.565338 | 
2025-10-02 15:18:42.565426 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-02 15:18:42.600179 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 15:18:42.600436 | 
2025-10-02 15:18:42.604756 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 15:18:42.619881 | 
2025-10-02 15:18:42.619977 | LOOP [upload-logs : Upload console log and json output]