2025-09-29 19:17:22.498490 | Job console starting...
2025-09-29 19:17:22.738995 | Updating repositories
2025-09-29 19:17:23.464582 | Preparing job workspace
2025-09-29 19:17:31.894387 | Running Ansible setup...
2025-09-29 19:17:34.903325 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-29 19:17:35.468001 | 
2025-09-29 19:17:35.468126 | PLAY [localhost]
2025-09-29 19:17:35.478406 | 
2025-09-29 19:17:35.478518 | TASK [Gathering Facts]
2025-09-29 19:17:36.397074 | localhost | ok
2025-09-29 19:17:36.413250 | 
2025-09-29 19:17:36.413466 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-09-29 19:17:36.857563 | localhost -> localhost | changed
2025-09-29 19:17:36.863047 | 
2025-09-29 19:17:36.863130 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-09-29 19:17:37.820114 | localhost -> localhost | changed
2025-09-29 19:17:37.828993 | 
2025-09-29 19:17:37.829090 | TASK [Setup log path fact]
2025-09-29 19:17:37.846538 | localhost | ok
2025-09-29 19:17:37.858101 | 
2025-09-29 19:17:37.858310 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-29 19:17:37.886266 | localhost | ok
2025-09-29 19:17:37.894671 | 
2025-09-29 19:17:37.894767 | TASK [emit-job-header : Print job information]
2025-09-29 19:17:37.932439 | # Job Information
2025-09-29 19:17:37.932588 | Ansible Version: 2.15.12
2025-09-29 19:17:37.932621 | Job: cifmw-molecule-repo_setup
2025-09-29 19:17:37.932644 | Pipeline: github-check
2025-09-29 19:17:37.932664 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-09-29 19:17:37.932683 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3333
2025-09-29 19:17:37.932701 | Log URL (when completed): https://logserver.rdoproject.org/9d9/rdoproject.org/9d9b6a076db54803b9c1f490b6de0015/
2025-09-29 19:17:37.932719 | Event ID: e4caed00-9d64-11f0-91c5-1ab1a6168f94
2025-09-29 19:17:37.937310 | 
2025-09-29 19:17:37.937386 | LOOP [emit-job-header : Print node information]
2025-09-29 19:17:38.036531 | localhost | ok:
2025-09-29 19:17:38.036694 | localhost | # Node Information
2025-09-29 19:17:38.036725 | localhost | Inventory Hostname: controller
2025-09-29 19:17:38.036752 | localhost | Hostname: np0005460785
2025-09-29 19:17:38.036774 | localhost | Username: zuul
2025-09-29 19:17:38.036796 | localhost | Distro: CentOS 9
2025-09-29 19:17:38.036816 | localhost | Provider: ibm-bm4-nodepool
2025-09-29 19:17:38.036835 | localhost | Region: regionOne
2025-09-29 19:17:38.036853 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-09-29 19:17:38.036872 | localhost | Product Name: OpenStack Compute
2025-09-29 19:17:38.036891 | localhost | Interface IP: 192.168.26.30
2025-09-29 19:17:38.071108 | 
2025-09-29 19:17:38.071196 | PLAY [all]
2025-09-29 19:17:38.077615 | 
2025-09-29 19:17:38.077683 | TASK [Gather network facts]
2025-09-29 19:17:38.474815 | controller | ok
2025-09-29 19:17:38.490189 | 
2025-09-29 19:17:38.490279 | TASK [include_role : start-zuul-console]
2025-09-29 19:17:38.508056 | controller | ok
2025-09-29 19:17:38.519745 | 
2025-09-29 19:17:38.519845 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-09-29 19:17:38.836947 | controller | ok
2025-09-29 19:17:38.845824 | 
2025-09-29 19:17:38.845926 | TASK [include_role : add-build-sshkey]
2025-09-29 19:17:38.864524 | controller | ok
2025-09-29 19:17:38.879097 | 
2025-09-29 19:17:38.879194 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-09-29 19:17:39.096085 | controller -> localhost | ok
2025-09-29 19:17:39.102950 | 
2025-09-29 19:17:39.103080 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-09-29 19:17:39.121188 | controller | ok
2025-09-29 19:17:39.133824 | controller | included: /var/lib/zuul/builds/9d9b6a076db54803b9c1f490b6de0015/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-09-29 19:17:39.139854 | 
2025-09-29 19:17:39.139971 | TASK [add-build-sshkey : Create Temp SSH key]
2025-09-29 19:17:39.838777 | controller -> localhost | Generating public/private rsa key pair.
2025-09-29 19:17:39.838975 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9d9b6a076db54803b9c1f490b6de0015/work/9d9b6a076db54803b9c1f490b6de0015_id_rsa.
2025-09-29 19:17:39.839015 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9d9b6a076db54803b9c1f490b6de0015/work/9d9b6a076db54803b9c1f490b6de0015_id_rsa.pub.
2025-09-29 19:17:39.839040 | controller -> localhost | The key fingerprint is:
2025-09-29 19:17:39.839061 | controller -> localhost | SHA256:Y/Qlswj0Y9O2h4LNSwbhnLO/0vqWrUzhghVPlw0RzaU zuul-build-sshkey
2025-09-29 19:17:39.839081 | controller -> localhost | The key's randomart image is:
2025-09-29 19:17:39.839099 | controller -> localhost | +---[RSA 3072]----+
2025-09-29 19:17:39.839117 | controller -> localhost | |      o   += ..  |
2025-09-29 19:17:39.839136 | controller -> localhost | |     + + . +o.   |
2025-09-29 19:17:39.839153 | controller -> localhost | |      O B B E    |
2025-09-29 19:17:39.839171 | controller -> localhost | |       # B B     |
2025-09-29 19:17:39.839188 | controller -> localhost | |      + S = .    |
2025-09-29 19:17:39.839204 | controller -> localhost | |     o * = .     |
2025-09-29 19:17:39.839221 | controller -> localhost | |    . ..=o       |
2025-09-29 19:17:39.839239 | controller -> localhost | |      .++..      |
2025-09-29 19:17:39.839256 | controller -> localhost | |      .==.       |
2025-09-29 19:17:39.839274 | controller -> localhost | +----[SHA256]-----+
2025-09-29 19:17:39.839332 | controller -> localhost | ok: Runtime: 0:00:00.309620
2025-09-29 19:17:39.845062 | 
2025-09-29 19:17:39.845131 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-09-29 19:17:39.863574 | controller | ok
2025-09-29 19:17:39.873866 | controller | included: /var/lib/zuul/builds/9d9b6a076db54803b9c1f490b6de0015/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-09-29 19:17:39.882341 | 
2025-09-29 19:17:39.882416 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-09-29 19:17:39.896111 | controller | skipping: Conditional result was False
2025-09-29 19:17:39.903455 | 
2025-09-29 19:17:39.903534 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-09-29 19:17:40.296866 | controller | changed
2025-09-29 19:17:40.305599 | 
2025-09-29 19:17:40.305690 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-09-29 19:17:40.508420 | controller | ok
2025-09-29 19:17:40.513530 | 
2025-09-29 19:17:40.513615 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-09-29 19:17:41.093851 | controller | changed
2025-09-29 19:17:41.099376 | 
2025-09-29 19:17:41.099456 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-09-29 19:17:41.667184 | controller | changed
2025-09-29 19:17:41.672378 | 
2025-09-29 19:17:41.672467 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-09-29 19:17:41.686845 | controller | skipping: Conditional result was False
2025-09-29 19:17:41.693314 | 
2025-09-29 19:17:41.693396 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-09-29 19:17:42.042051 | controller -> localhost | changed
2025-09-29 19:17:42.053184 | 
2025-09-29 19:17:42.053279 | TASK [add-build-sshkey : Add back temp key]
2025-09-29 19:17:42.294279 | controller -> localhost | Identity added: /var/lib/zuul/builds/9d9b6a076db54803b9c1f490b6de0015/work/9d9b6a076db54803b9c1f490b6de0015_id_rsa (zuul-build-sshkey)
2025-09-29 19:17:42.294502 | controller -> localhost | ok: Runtime: 0:00:00.008239
2025-09-29 19:17:42.300464 | 
2025-09-29 19:17:42.300533 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-09-29 19:17:42.612465 | controller | ok
2025-09-29 19:17:42.617163 | 
2025-09-29 19:17:42.617328 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-09-29 19:17:42.641227 | controller | skipping: Conditional result was False
2025-09-29 19:17:42.652053 | 
2025-09-29 19:17:42.652132 | TASK [include_role : validate-host]
2025-09-29 19:17:42.670160 | controller | ok
2025-09-29 19:17:42.689702 | 
2025-09-29 19:17:42.689800 | TASK [validate-host : Define zuul_info_dir fact]
2025-09-29 19:17:42.718507 | controller | ok
2025-09-29 19:17:42.723381 | 
2025-09-29 19:17:42.723481 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-09-29 19:17:42.941446 | controller -> localhost | ok
2025-09-29 19:17:42.947757 | 
2025-09-29 19:17:42.947852 | TASK [validate-host : Collect information about the host]
2025-09-29 19:17:43.573695 | controller | ok
2025-09-29 19:17:43.624697 | 
2025-09-29 19:17:43.624800 | TASK [validate-host : Sanitize hostname]
2025-09-29 19:17:43.673266 | controller | ok
2025-09-29 19:17:43.678261 | 
2025-09-29 19:17:43.678377 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-09-29 19:17:44.140025 | controller -> localhost | changed
2025-09-29 19:17:44.145689 | 
2025-09-29 19:17:44.145925 | TASK [validate-host : Collect information about zuul worker]
2025-09-29 19:17:44.488625 | controller | ok
2025-09-29 19:17:44.493721 | 
2025-09-29 19:17:44.493813 | TASK [validate-host : Write out all zuul information for each host]
2025-09-29 19:17:44.894576 | controller -> localhost | changed
2025-09-29 19:17:44.904105 | 
2025-09-29 19:17:44.904199 | TASK [include_role : prepare-workspace-openshift]
2025-09-29 19:17:44.918044 | controller | skipping: Conditional result was False
2025-09-29 19:17:44.924025 | 
2025-09-29 19:17:44.924152 | TASK [include_role : remove-zuul-sshkey]
2025-09-29 19:17:44.937944 | controller | skipping: Conditional result was False
2025-09-29 19:17:44.943658 | 
2025-09-29 19:17:44.943747 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-09-29 19:17:45.150611 | controller | ok: "logs"
2025-09-29 19:17:45.150883 | controller | ok: All items complete
2025-09-29 19:17:45.150937 | 
2025-09-29 19:17:45.319765 | controller | ok: "artifacts"
2025-09-29 19:17:45.497233 | controller | ok: "docs"
2025-09-29 19:17:45.503651 | 
2025-09-29 19:17:45.503746 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-09-29 19:17:45.715815 | controller | changed: "logs"
2025-09-29 19:17:45.882360 | controller | changed: "artifacts"
2025-09-29 19:17:46.059280 | controller | changed: "docs"
2025-09-29 19:17:46.084086 | 
2025-09-29 19:17:46.084185 | PLAY RECAP
2025-09-29 19:17:46.084232 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-29 19:17:46.084261 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-29 19:17:46.084280 | 
2025-09-29 19:17:46.172810 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-29 19:17:46.173654 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-29 19:17:46.764639 | 
2025-09-29 19:17:46.764756 | PLAY [all]
2025-09-29 19:17:46.785433 | 
2025-09-29 19:17:46.785535 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-29 19:17:46.845135 | controller | ok
2025-09-29 19:17:46.850804 | 
2025-09-29 19:17:46.850930 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-29 19:17:47.227331 | controller | changed
2025-09-29 19:17:47.233224 | 
2025-09-29 19:17:47.233349 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-29 19:17:48.182129 | controller | changed
2025-09-29 19:17:48.197282 | 
2025-09-29 19:17:48.197390 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-09-29 19:17:48.591074 | controller | changed:
2025-09-29 19:17:48.591377 | controller | {
2025-09-29 19:17:48.591413 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-09-29 19:17:48.591445 | controller | }
2025-09-29 19:17:48.801132 | controller | changed:
2025-09-29 19:17:48.801219 | controller | {
2025-09-29 19:17:48.801248 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-09-29 19:17:48.801270 | controller | }
2025-09-29 19:17:49.021727 | controller | changed:
2025-09-29 19:17:49.021814 | controller | {
2025-09-29 19:17:49.021841 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-09-29 19:17:49.021863 | controller | }
2025-09-29 19:17:49.245964 | controller | changed:
2025-09-29 19:17:49.246052 | controller | {
2025-09-29 19:17:49.246080 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-09-29 19:17:49.246102 | controller | }
2025-09-29 19:17:49.468850 | controller | changed:
2025-09-29 19:17:49.468969 | controller | {
2025-09-29 19:17:49.469001 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-09-29 19:17:49.469023 | controller | }
2025-09-29 19:17:49.694102 | controller | changed:
2025-09-29 19:17:49.694193 | controller | {
2025-09-29 19:17:49.694221 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-09-29 19:17:49.694242 | controller | }
2025-09-29 19:17:49.954768 | controller | changed:
2025-09-29 19:17:49.954867 | controller | {
2025-09-29 19:17:49.954897 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-09-29 19:17:49.955141 | controller | }
2025-09-29 19:17:50.224015 | controller | changed:
2025-09-29 19:17:50.224108 | controller | {
2025-09-29 19:17:50.224135 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-09-29 19:17:50.224155 | controller | }
2025-09-29 19:17:50.442784 | controller | changed:
2025-09-29 19:17:50.442882 | controller | {
2025-09-29 19:17:50.442933 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-09-29 19:17:50.442958 | controller | }
2025-09-29 19:17:50.666255 | controller | changed:
2025-09-29 19:17:50.666360 | controller | {
2025-09-29 19:17:50.666389 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-09-29 19:17:50.666410 | controller | }
2025-09-29 19:17:50.899005 | controller | changed:
2025-09-29 19:17:50.899098 | controller | {
2025-09-29 19:17:50.899126 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-09-29 19:17:50.899148 | controller | }
2025-09-29 19:17:51.095195 | controller | changed:
2025-09-29 19:17:51.095282 | controller | {
2025-09-29 19:17:51.095325 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-09-29 19:17:51.095348 | controller | }
2025-09-29 19:17:51.307435 | controller | changed:
2025-09-29 19:17:51.307516 | controller | {
2025-09-29 19:17:51.307546 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-09-29 19:17:51.307570 | controller | }
2025-09-29 19:17:51.516422 | controller | changed:
2025-09-29 19:17:51.516513 | controller | {
2025-09-29 19:17:51.516541 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-09-29 19:17:51.516563 | controller | }
2025-09-29 19:17:51.741526 | controller | changed:
2025-09-29 19:17:51.741611 | controller | {
2025-09-29 19:17:51.741640 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-09-29 19:17:51.741663 | controller | }
2025-09-29 19:17:51.962946 | controller | changed:
2025-09-29 19:17:51.963134 | controller | {
2025-09-29 19:17:51.963170 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-09-29 19:17:51.963193 | controller | }
2025-09-29 19:17:52.177325 | controller | changed:
2025-09-29 19:17:52.177433 | controller | {
2025-09-29 19:17:52.177471 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-09-29 19:17:52.177500 | controller | }
2025-09-29 19:17:52.390045 | controller | changed:
2025-09-29 19:17:52.390127 | controller | {
2025-09-29 19:17:52.390154 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-09-29 19:17:52.390180 | controller | }
2025-09-29 19:17:52.614515 | controller | changed:
2025-09-29 19:17:52.614602 | controller | {
2025-09-29 19:17:52.614628 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-09-29 19:17:52.614649 | controller | }
2025-09-29 19:17:52.825339 | controller | changed:
2025-09-29 19:17:52.830049 | controller | {
2025-09-29 19:17:52.830191 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-09-29 19:17:52.830219 | controller | }
2025-09-29 19:17:53.024117 | controller | changed:
2025-09-29 19:17:53.024209 | controller | {
2025-09-29 19:17:53.024234 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-09-29 19:17:53.024253 | controller | }
2025-09-29 19:17:53.234600 | controller | changed:
2025-09-29 19:17:53.234785 | controller | {
2025-09-29 19:17:53.234824 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-09-29 19:17:53.234847 | controller | }
2025-09-29 19:17:53.452410 | controller | changed:
2025-09-29 19:17:53.452502 | controller | {
2025-09-29 19:17:53.452532 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-09-29 19:17:53.452555 | controller | }
2025-09-29 19:17:53.669565 | controller | changed:
2025-09-29 19:17:53.669649 | controller | {
2025-09-29 19:17:53.669677 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-09-29 19:17:53.669699 | controller | }
2025-09-29 19:17:53.879645 | controller | changed:
2025-09-29 19:17:53.879736 | controller | {
2025-09-29 19:17:53.879764 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-09-29 19:17:53.879786 | controller | }
2025-09-29 19:17:54.087816 | controller | changed:
2025-09-29 19:17:54.088065 | controller | {
2025-09-29 19:17:54.088105 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-09-29 19:17:54.088129 | controller | }
2025-09-29 19:17:54.114452 | 
2025-09-29 19:17:54.114584 | TASK [Set timezone to UTC]
2025-09-29 19:17:54.519192 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-09-29 19:17:54.524595 | 
2025-09-29 19:17:54.524671 | TASK [Create nodepool directory]
2025-09-29 19:17:54.725326 | controller | changed
2025-09-29 19:17:54.730877 | 
2025-09-29 19:17:54.730985 | TASK [Create nodepool sub_nodes file]
2025-09-29 19:17:55.235527 | controller | changed
2025-09-29 19:17:55.242029 | 
2025-09-29 19:17:55.242119 | TASK [Create nodepool sub_nodes_private file]
2025-09-29 19:17:55.762608 | controller | changed
2025-09-29 19:17:55.768112 | 
2025-09-29 19:17:55.768210 | LOOP [Populate nodepool sub_nodes file]
2025-09-29 19:17:55.800343 | 
2025-09-29 19:17:55.800497 | LOOP [Populate nodepool sub_nodes_private file]
2025-09-29 19:17:55.831020 | 
2025-09-29 19:17:55.831176 | TASK [Create nodepool primary file]
2025-09-29 19:17:55.855219 | controller | skipping: Conditional result was False
2025-09-29 19:17:55.860926 | 
2025-09-29 19:17:55.861020 | TASK [Create nodepool node_private for this node]
2025-09-29 19:17:56.401305 | controller | changed
2025-09-29 19:17:56.408645 | 
2025-09-29 19:17:56.408735 | LOOP [Copy ssh keys to nodepool directory]
2025-09-29 19:17:56.765688 | controller | ok: Item: id_rsa Runtime: 0:00:00.005090
2025-09-29 19:17:56.765886 | 
2025-09-29 19:17:56.931969 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004589
2025-09-29 19:17:56.948393 | 
2025-09-29 19:17:56.948512 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-09-29 19:17:57.618303 | controller | changed
2025-09-29 19:17:57.623531 | 
2025-09-29 19:17:57.623616 | TASK [Validate sudoers config after edits]
2025-09-29 19:17:57.824331 | controller | /etc/sudoers: parsed OK
2025-09-29 19:17:57.824422 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-09-29 19:17:57.824434 | controller | /etc/sudoers.d/zuul: parsed OK
2025-09-29 19:17:57.824441 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-09-29 19:17:58.148902 | controller | ok: Runtime: 0:00:00.005343
2025-09-29 19:17:58.154367 | 
2025-09-29 19:17:58.154435 | TASK [Show the environment passed in to job shell scripts]
2025-09-29 19:17:58.343142 | controller | SHELL=/bin/bash
2025-09-29 19:17:58.343200 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-09-29 19:17:58.343210 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-09-29 19:17:58.343216 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/46/3346/eb5b53fb17db92a72d33d052a56c8edd0d10ea78^openstack-k8s-operators/ci-framework:main:refs/changes/33/3333/b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-29 19:17:58.343224 | controller | PWD=/home/zuul
2025-09-29 19:17:58.343239 | controller | ZUUL_PIPELINE=github-check
2025-09-29 19:17:58.343286 | controller | LOGNAME=zuul
2025-09-29 19:17:58.343294 | controller | XDG_SESSION_TYPE=tty
2025-09-29 19:17:58.343299 | controller | _=/usr/bin/env
2025-09-29 19:17:58.343305 | controller | MOTD_SHOWN=pam
2025-09-29 19:17:58.343310 | controller | HOME=/home/zuul
2025-09-29 19:17:58.343315 | controller | LANG=en_US.UTF-8
2025-09-29 19:17:58.343321 | controller | SSH_CONNECTION=192.168.26.12 42444 192.168.26.30 22
2025-09-29 19:17:58.343327 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-09-29 19:17:58.343332 | controller | ZUUL_CHANGE_IDS=3346,eb5b53fb17db92a72d33d052a56c8edd0d10ea78 3333,b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-29 19:17:58.343338 | controller | WORKSPACE=/home/zuul/workspace
2025-09-29 19:17:58.343344 | controller | XDG_SESSION_CLASS=user
2025-09-29 19:17:58.343349 | controller | SELINUX_ROLE_REQUESTED=
2025-09-29 19:17:58.343354 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-09-29 19:17:58.343359 | controller | USER=zuul
2025-09-29 19:17:58.343365 | controller | ZUUL_VOTING=True
2025-09-29 19:17:58.343370 | controller | BUILD_TIMEOUT=1800000
2025-09-29 19:17:58.343375 | controller | SELINUX_USE_CURRENT_RANGE=
2025-09-29 19:17:58.343381 | controller | SHLVL=1
2025-09-29 19:17:58.343386 | controller | ZUUL_PATCHSET=b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-29 19:17:58.343391 | controller | XDG_SESSION_ID=1
2025-09-29 19:17:58.343397 | controller | ZUUL_BRANCH=main
2025-09-29 19:17:58.343402 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-09-29 19:17:58.343407 | controller | SSH_CLIENT=192.168.26.12 42444 22
2025-09-29 19:17:58.343413 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-09-29 19:17:58.343418 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-09-29 19:17:58.343423 | controller | which_declare=declare -f
2025-09-29 19:17:58.343429 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-09-29 19:17:58.343435 | controller | SELINUX_LEVEL_REQUESTED=
2025-09-29 19:17:58.343440 | controller | ZUUL_CHANGE=3333
2025-09-29 19:17:58.343446 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-09-29 19:17:58.343451 | controller | ZUUL_UUID=9d9b6a076db54803b9c1f490b6de0015
2025-09-29 19:17:58.343456 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-09-29 19:17:58.343462 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-09-29 19:17:58.343467 | controller | }
2025-09-29 19:17:58.681266 | controller | ok: Runtime: 0:00:00.006019
2025-09-29 19:17:58.688187 | 
2025-09-29 19:17:58.688335 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-09-29 19:17:58.702761 | controller | skipping: Conditional result was False
2025-09-29 19:17:58.709095 | 
2025-09-29 19:17:58.709175 | TASK [Symlink /home/zuul-worker/workspace]
2025-09-29 19:17:59.235334 | controller | skipping: Conditional result was False
2025-09-29 19:17:59.241794 | 
2025-09-29 19:17:59.241891 | TASK [Ensure legacy workspace directory]
2025-09-29 19:17:59.432614 | controller | changed
2025-09-29 19:17:59.454671 | 
2025-09-29 19:17:59.454756 | PLAY RECAP
2025-09-29 19:17:59.454804 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-29 19:17:59.454828 | 
2025-09-29 19:17:59.545301 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-29 19:17:59.546106 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/read_global_vars.yml@main]
2025-09-29 19:18:00.148374 | 
2025-09-29 19:18:00.148486 | PLAY [Load global variables]
2025-09-29 19:18:00.190546 | 
2025-09-29 19:18:00.190658 | TASK [Read group_vars all file]
2025-09-29 19:18:00.220283 | controller | ok
2025-09-29 19:18:00.245889 | 
2025-09-29 19:18:00.246003 | TASK [cifmw_helpers : Check if file is available]
2025-09-29 19:18:00.529653 | controller | ok
2025-09-29 19:18:00.535087 | 
2025-09-29 19:18:00.535401 | TASK [cifmw_helpers : Read vars]
2025-09-29 19:18:00.554892 | 
2025-09-29 19:18:00.555095 | LOOP [cifmw_helpers : Set vars as fact]
2025-09-29 19:18:00.644345 | 
2025-09-29 19:18:00.644466 | PLAY RECAP
2025-09-29 19:18:00.644509 | controller | ok: 1 changed: 0 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-29 19:18:00.644532 | 
2025-09-29 19:18:00.735830 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/read_global_vars.yml@main]
2025-09-29 19:18:00.736731 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-29 19:18:01.333473 | 
2025-09-29 19:18:01.333572 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-09-29 19:18:01.354338 | 
2025-09-29 19:18:01.354556 | TASK [Create zuul-output directory]
2025-09-29 19:18:01.710499 | controller | changed
2025-09-29 19:18:01.716251 | 
2025-09-29 19:18:01.716384 | TASK [Slurp Zuul inventory test]
2025-09-29 19:18:02.058112 | controller -> localhost | ok
2025-09-29 19:18:02.064266 | 
2025-09-29 19:18:02.064360 | TASK [Save zuul inventory]
2025-09-29 19:18:02.852600 | controller | changed
2025-09-29 19:18:02.858172 | 
2025-09-29 19:18:02.858285 | TASK [Save zuul vars without the change_message]
2025-09-29 19:18:03.497764 | controller | changed
2025-09-29 19:18:03.533056 | 
2025-09-29 19:18:03.533141 | PLAY RECAP
2025-09-29 19:18:03.533184 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-29 19:18:03.533208 | 
2025-09-29 19:18:03.649994 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-29 19:18:03.650735 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-29 19:18:04.315148 | 
2025-09-29 19:18:04.315374 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-09-29 19:18:04.338593 | 
2025-09-29 19:18:04.338703 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-09-29 19:18:04.378754 | controller | ok
2025-09-29 19:18:04.398014 | 
2025-09-29 19:18:04.398140 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-29 19:18:04.421845 | controller | skipping: Conditional result was False
2025-09-29 19:18:04.429133 | 
2025-09-29 19:18:04.429232 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-29 19:18:04.752658 | controller | ok
2025-09-29 19:18:04.758485 | 
2025-09-29 19:18:04.758560 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-29 19:18:05.410385 | controller | ok
2025-09-29 19:18:05.422885 | 
2025-09-29 19:18:05.423013 | TASK [Prepare workspace]
2025-09-29 19:18:05.444334 | controller | ok
2025-09-29 19:18:05.466817 | 
2025-09-29 19:18:05.466945 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-09-29 19:18:05.813139 | controller | ok
2025-09-29 19:18:05.819980 | 
2025-09-29 19:18:05.820081 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-09-29 19:18:07.976673 | controller | Output suppressed because no_log was given
2025-09-29 19:18:07.986042 | 
2025-09-29 19:18:07.986138 | LOOP [Create zuul-output directory]
2025-09-29 19:18:08.173727 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-09-29 19:18:08.337743 | controller | ok: "/home/zuul/zuul-output/logs"
2025-09-29 19:18:08.349666 | 
2025-09-29 19:18:08.349755 | TASK [Install required packages]
2025-09-29 19:19:01.096973 | controller | changed
2025-09-29 19:19:01.103207 | 
2025-09-29 19:19:01.103311 | TASK [Install venv]
2025-09-29 19:20:04.068829 | controller | changed
2025-09-29 19:20:04.093014 | 
2025-09-29 19:20:04.093088 | PLAY RECAP
2025-09-29 19:20:04.093132 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-29 19:20:04.093154 | 
2025-09-29 19:20:04.173880 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-29 19:20:04.174783 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-29 19:20:04.758036 | 
2025-09-29 19:20:04.758148 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-09-29 19:20:04.779433 | 
2025-09-29 19:20:04.779552 | TASK [Gather required facts]
2025-09-29 19:20:05.295846 | controller | ok
2025-09-29 19:20:05.301307 | 
2025-09-29 19:20:05.301399 | TASK [Load environment var if instructed to]
2025-09-29 19:20:05.328136 | controller | skipping: Conditional result was False
2025-09-29 19:20:05.335604 | 
2025-09-29 19:20:05.335682 | TASK [Run molecule]
2025-09-29 19:20:06.297190 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-09-29 19:20:06.367872 | controller | INFO     Performing prerun with role_name_check=0...
2025-09-29 19:20:22.960636 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/a3195f/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:20:22.961220 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/a3195f/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:20:22.961724 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/a3195f/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:20:22.962234 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/a3195f/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:20:22.962748 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+4a832a0b was found installed in /home/zuul/.cache/ansible-compat/a3195f/collections/ansible_collections, only the first one will be used, 1.0.0+4a832a0b (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:20:22.963257 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/a3195f/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:20:22.963733 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/a3195f/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:20:22.964236 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/a3195f/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:20:22.964715 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/a3195f/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:20:22.965235 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/a3195f/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:20:22.965711 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/a3195f/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:20:22.966217 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/a3195f/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:20:22.966695 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/a3195f/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:20:22.967196 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/a3195f/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:20:22.967677 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/a3195f/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:20:22.977341 | controller | INFO     Running default > prepare
2025-09-29 19:20:23.660893 | controller |
2025-09-29 19:20:24.510919 | controller | PLAY [Prepare] *****************************************************************
2025-09-29 19:20:24.510966 | controller |
2025-09-29 19:20:24.510975 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 19:20:24.510983 | controller | Monday 29 September 2025  19:20:23 +0000 (0:00:00.013)       0:00:00.013 ******
2025-09-29 19:20:24.510995 | controller | ok: [instance]
2025-09-29 19:20:24.531775 | controller |
2025-09-29 19:20:24.531805 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-09-29 19:20:24.531814 | controller | Monday 29 September 2025  19:20:24 +0000 (0:00:00.850)       0:00:00.863 ******
2025-09-29 19:20:24.531825 | controller | skipping: [instance]
2025-09-29 19:20:24.573644 | controller |
2025-09-29 19:20:24.573671 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-09-29 19:20:24.573680 | controller | Monday 29 September 2025  19:20:24 +0000 (0:00:00.020)       0:00:00.884 ******
2025-09-29 19:20:24.573692 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-09-29 19:20:24.857993 | controller |
2025-09-29 19:20:24.858052 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-09-29 19:20:24.858062 | controller | Monday 29 September 2025  19:20:24 +0000 (0:00:00.041)       0:00:00.926 ******
2025-09-29 19:20:24.858073 | controller | ok: [instance]
2025-09-29 19:20:25.178504 | controller |
2025-09-29 19:20:25.178545 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-09-29 19:20:25.178554 | controller | Monday 29 September 2025  19:20:24 +0000 (0:00:00.284)       0:00:01.210 ******
2025-09-29 19:20:25.178565 | controller | ok: [instance]
2025-09-29 19:20:25.858655 | controller |
2025-09-29 19:20:25.858689 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-09-29 19:20:25.858697 | controller | Monday 29 September 2025  19:20:25 +0000 (0:00:00.320)       0:00:01.531 ******
2025-09-29 19:20:25.858709 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-09-29 19:20:25.915988 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-09-29 19:20:25.916032 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-09-29 19:20:25.916039 | controller |
2025-09-29 19:20:25.916046 | controller | TASK [Deploy repo-setup] *******************************************************
2025-09-29 19:20:25.916052 | controller | Monday 29 September 2025  19:20:25 +0000 (0:00:00.679)       0:00:02.211 ******
2025-09-29 19:20:25.916069 | controller |
2025-09-29 19:20:25.916088 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-09-29 19:20:25.916095 | controller | Monday 29 September 2025  19:20:25 +0000 (0:00:00.057)       0:00:02.269 ******
2025-09-29 19:20:26.453431 | controller | changed: [instance] => (item=tmp)
2025-09-29 19:20:26.453535 | controller | changed: [instance] => (item=artifacts/repositories)
2025-09-29 19:20:26.453649 | controller | changed: [instance] => (item=venv/repo_setup)
2025-09-29 19:20:26.453759 | controller |
2025-09-29 19:20:26.453898 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-09-29 19:20:26.454012 | controller | Monday 29 September 2025  19:20:26 +0000 (0:00:00.537)       0:00:02.806 ******
2025-09-29 19:20:27.239671 | controller | ok: [instance]
2025-09-29 19:20:27.239744 | controller |
2025-09-29 19:20:27.240016 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-09-29 19:20:28.425326 | controller | Monday 29 September 2025  19:20:27 +0000 (0:00:00.786)       0:00:03.593 ******
2025-09-29 19:20:28.425368 | controller | changed: [instance]
2025-09-29 19:20:36.571382 | controller |
2025-09-29 19:20:36.571421 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-09-29 19:20:36.571433 | controller | Monday 29 September 2025  19:20:28 +0000 (0:00:01.185)       0:00:04.778 ******
2025-09-29 19:20:36.571443 | controller | changed: [instance]
2025-09-29 19:20:37.284908 | controller |
2025-09-29 19:20:37.284947 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-09-29 19:20:37.284956 | controller | Monday 29 September 2025  19:20:36 +0000 (0:00:08.145)       0:00:12.924 ******
2025-09-29 19:20:37.284966 | controller | changed: [instance]
2025-09-29 19:20:37.284988 | controller |
2025-09-29 19:20:37.284996 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-09-29 19:20:37.285063 | controller | Monday 29 September 2025  19:20:37 +0000 (0:00:00.713)       0:00:13.638 ******
2025-09-29 19:20:37.307675 | controller | skipping: [instance]
2025-09-29 19:20:38.146892 | controller |
2025-09-29 19:20:38.146922 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-09-29 19:20:38.146930 | controller | Monday 29 September 2025  19:20:37 +0000 (0:00:00.022)       0:00:13.660 ******
2025-09-29 19:20:38.146940 | controller | changed: [instance]
2025-09-29 19:20:38.180740 | controller |
2025-09-29 19:20:38.180767 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-09-29 19:20:38.180775 | controller | Monday 29 September 2025  19:20:38 +0000 (0:00:00.839)       0:00:14.499 ******
2025-09-29 19:20:38.180785 | controller | skipping: [instance]
2025-09-29 19:20:38.215146 | controller |
2025-09-29 19:20:38.215175 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-09-29 19:20:38.215183 | controller | Monday 29 September 2025  19:20:38 +0000 (0:00:00.034)       0:00:14.534 ******
2025-09-29 19:20:38.215193 | controller | skipping: [instance]
2025-09-29 19:20:38.249699 | controller |
2025-09-29 19:20:38.249729 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-09-29 19:20:38.249737 | controller | Monday 29 September 2025  19:20:38 +0000 (0:00:00.033)       0:00:14.567 ******
2025-09-29 19:20:38.249749 | controller | skipping: [instance]
2025-09-29 19:20:38.790708 | controller |
2025-09-29 19:20:38.790738 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-09-29 19:20:38.790747 | controller | Monday 29 September 2025  19:20:38 +0000 (0:00:00.034)       0:00:14.602 ******
2025-09-29 19:20:38.790757 | controller | changed: [instance]
2025-09-29 19:20:39.215391 | controller |
2025-09-29 19:20:39.215420 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-09-29 19:20:39.215429 | controller | Monday 29 September 2025  19:20:38 +0000 (0:00:00.540)       0:00:15.143 ******
2025-09-29 19:20:39.215438 | controller | changed: [instance]
2025-09-29 19:20:39.242427 | controller |
2025-09-29 19:20:39.242482 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-09-29 19:20:39.242492 | controller | Monday 29 September 2025  19:20:39 +0000 (0:00:00.424)       0:00:15.568 ******
2025-09-29 19:20:39.242503 | controller | skipping: [instance]
2025-09-29 19:20:39.269525 | controller |
2025-09-29 19:20:39.269554 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-09-29 19:20:39.269562 | controller | Monday 29 September 2025  19:20:39 +0000 (0:00:00.027)       0:00:15.595 ******
2025-09-29 19:20:39.269574 | controller | skipping: [instance]
2025-09-29 19:20:39.296495 | controller |
2025-09-29 19:20:39.296523 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-09-29 19:20:39.296531 | controller | Monday 29 September 2025  19:20:39 +0000 (0:00:00.027)       0:00:15.622 ******
2025-09-29 19:20:39.296546 | controller | skipping: [instance]
2025-09-29 19:20:39.333034 | controller |
2025-09-29 19:20:39.333059 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-09-29 19:20:39.333067 | controller | Monday 29 September 2025  19:20:39 +0000 (0:00:00.026)       0:00:15.649 ******
2025-09-29 19:20:39.333076 | controller | ok: [instance]
2025-09-29 19:20:39.358176 | controller |
2025-09-29 19:20:39.358200 | controller | TASK [repo_setup : Create download directory] **********************************
2025-09-29 19:20:39.358206 | controller | Monday 29 September 2025  19:20:39 +0000 (0:00:00.036)       0:00:15.686 ******
2025-09-29 19:20:39.358216 | controller | skipping: [instance]
2025-09-29 19:20:39.382416 | controller |
2025-09-29 19:20:39.382442 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-09-29 19:20:39.382450 | controller | Monday 29 September 2025  19:20:39 +0000 (0:00:00.025)       0:00:15.711 ******
2025-09-29 19:20:39.382470 | controller | skipping: [instance]
2025-09-29 19:20:39.382650 | controller |
2025-09-29 19:20:39.382823 | controller | TASK [Download the RPM] ********************************************************
2025-09-29 19:20:39.383018 | controller | Monday 29 September 2025  19:20:39 +0000 (0:00:00.024)       0:00:15.736 ******
2025-09-29 19:20:39.407513 | controller | skipping: [instance]
2025-09-29 19:20:39.432548 | controller |
2025-09-29 19:20:39.432577 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-09-29 19:20:39.432586 | controller | Monday 29 September 2025  19:20:39 +0000 (0:00:00.024)       0:00:15.760 ******
2025-09-29 19:20:39.432595 | controller | skipping: [instance]
2025-09-29 19:20:39.457276 | controller |
2025-09-29 19:20:39.457301 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-09-29 19:20:39.457309 | controller | Monday 29 September 2025  19:20:39 +0000 (0:00:00.025)       0:00:15.785 ******
2025-09-29 19:20:39.457319 | controller | skipping: [instance]
2025-09-29 19:20:39.483677 | controller |
2025-09-29 19:20:39.483701 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-09-29 19:20:39.483709 | controller | Monday 29 September 2025  19:20:39 +0000 (0:00:00.024)       0:00:15.810 ******
2025-09-29 19:20:39.483719 | controller | skipping: [instance]
2025-09-29 19:20:39.506907 | controller |
2025-09-29 19:20:39.506931 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-09-29 19:20:39.506939 | controller | Monday 29 September 2025  19:20:39 +0000 (0:00:00.026)       0:00:15.836 ******
2025-09-29 19:20:39.506951 | controller | skipping: [instance]
2025-09-29 19:20:39.507287 | controller |
2025-09-29 19:20:39.681919 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-09-29 19:20:39.681950 | controller | Monday 29 September 2025  19:20:39 +0000 (0:00:00.023)       0:00:15.860 ******
2025-09-29 19:20:39.681962 | controller | ok: [instance]
2025-09-29 19:20:39.681969 | controller |
2025-09-29 19:20:39.681975 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-09-29 19:20:39.681983 | controller | Monday 29 September 2025  19:20:39 +0000 (0:00:00.174)       0:00:16.034 ******
2025-09-29 19:20:39.890804 | controller | changed: [instance]
2025-09-29 19:20:40.121926 | controller |
2025-09-29 19:20:40.121955 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-09-29 19:20:40.121964 | controller | Monday 29 September 2025  19:20:39 +0000 (0:00:00.208)       0:00:16.243 ******
2025-09-29 19:20:40.121974 | controller | changed: [instance]
2025-09-29 19:20:40.140993 | controller |
2025-09-29 19:20:40.141021 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-09-29 19:20:40.141028 | controller | Monday 29 September 2025  19:20:40 +0000 (0:00:00.231)       0:00:16.475 ******
2025-09-29 19:20:40.141038 | controller | skipping: [instance]
2025-09-29 19:20:40.161069 | controller |
2025-09-29 19:20:40.161094 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-09-29 19:20:40.161103 | controller | Monday 29 September 2025  19:20:40 +0000 (0:00:00.019)       0:00:16.494 ******
2025-09-29 19:20:40.161114 | controller | skipping: [instance]
2025-09-29 19:20:40.180588 | controller |
2025-09-29 19:20:40.180610 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-09-29 19:20:40.180617 | controller | Monday 29 September 2025  19:20:40 +0000 (0:00:00.019)       0:00:16.514 ******
2025-09-29 19:20:40.180626 | controller | skipping: [instance]
2025-09-29 19:20:40.199978 | controller |
2025-09-29 19:20:40.200000 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-09-29 19:20:40.200008 | controller | Monday 29 September 2025  19:20:40 +0000 (0:00:00.019)       0:00:16.533 ******
2025-09-29 19:20:40.200017 | controller | skipping: [instance]
2025-09-29 19:20:40.219910 | controller |
2025-09-29 19:20:40.219934 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-09-29 19:20:40.219946 | controller | Monday 29 September 2025  19:20:40 +0000 (0:00:00.019)       0:00:16.553 ******
2025-09-29 19:20:40.219956 | controller | skipping: [instance]
2025-09-29 19:20:40.220026 | controller |
2025-09-29 19:20:40.220041 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-09-29 19:20:40.220058 | controller | Monday 29 September 2025  19:20:40 +0000 (0:00:00.020)       0:00:16.573 ******
2025-09-29 19:20:40.240415 | controller | skipping: [instance]
2025-09-29 19:20:40.254902 | controller |
2025-09-29 19:20:40.254928 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-09-29 19:20:40.254937 | controller | Monday 29 September 2025  19:20:40 +0000 (0:00:00.020)       0:00:16.593 ******
2025-09-29 19:20:40.254948 | controller | skipping: [instance]
2025-09-29 19:20:40.255412 | controller |
2025-09-29 19:20:40.281733 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-09-29 19:20:40.281761 | controller | Monday 29 September 2025  19:20:40 +0000 (0:00:00.014)       0:00:16.608 ******
2025-09-29 19:20:40.281772 | controller | skipping: [instance]
2025-09-29 19:20:40.297371 | controller |
2025-09-29 19:20:40.297401 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-09-29 19:20:40.297409 | controller | Monday 29 September 2025  19:20:40 +0000 (0:00:00.026)       0:00:16.634 ******
2025-09-29 19:20:40.297421 | controller | skipping: [instance]
2025-09-29 19:20:40.315922 | controller |
2025-09-29 19:20:40.315945 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-09-29 19:20:40.315953 | controller | Monday 29 September 2025  19:20:40 +0000 (0:00:00.015)       0:00:16.650 ******
2025-09-29 19:20:40.315963 | controller | skipping: [instance]
2025-09-29 19:20:40.343786 | controller |
2025-09-29 19:20:40.343813 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-09-29 19:20:40.343821 | controller | Monday 29 September 2025  19:20:40 +0000 (0:00:00.018)       0:00:16.669 ******
2025-09-29 19:20:40.343830 | controller | skipping: [instance]
2025-09-29 19:20:40.371722 | controller |
2025-09-29 19:20:40.371747 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-09-29 19:20:40.371755 | controller | Monday 29 September 2025  19:20:40 +0000 (0:00:00.027)       0:00:16.697 ******
2025-09-29 19:20:40.371765 | controller | skipping: [instance]
2025-09-29 19:20:40.399931 | controller |
2025-09-29 19:20:40.399962 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-09-29 19:20:40.399971 | controller | Monday 29 September 2025  19:20:40 +0000 (0:00:00.027)       0:00:16.725 ******
2025-09-29 19:20:40.399983 | controller | skipping: [instance]
2025-09-29 19:20:40.400011 | controller |
2025-09-29 19:20:40.400218 | controller | TASK [test_deps : Install additional packages] *********************************
2025-09-29 19:20:40.427908 | controller | Monday 29 September 2025  19:20:40 +0000 (0:00:00.028)       0:00:16.753 ******
2025-09-29 19:20:40.427930 | controller | skipping: [instance]
2025-09-29 19:20:40.428129 | controller |
2025-09-29 19:20:40.428147 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-09-29 19:21:26.480100 | controller | Monday 29 September 2025  19:20:40 +0000 (0:00:00.028)       0:00:16.781 ******
2025-09-29 19:21:26.480141 | controller | ok: [instance]
2025-09-29 19:21:27.723212 | controller |
2025-09-29 19:21:27.723243 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-09-29 19:21:27.723251 | controller | Monday 29 September 2025  19:21:26 +0000 (0:00:46.051)       0:01:02.833 ******
2025-09-29 19:21:27.723262 | controller | ok: [instance]
2025-09-29 19:21:27.768971 | controller |
2025-09-29 19:21:27.769006 | controller | TASK [test_deps : Install extra packages] **************************************
2025-09-29 19:21:27.769020 | controller | Monday 29 September 2025  19:21:27 +0000 (0:00:01.243)       0:01:04.076 ******
2025-09-29 19:21:27.769031 | controller | skipping: [instance]
2025-09-29 19:21:27.769057 | controller |
2025-09-29 19:21:27.769066 | controller | PLAY RECAP *********************************************************************
2025-09-29 19:21:27.769940 | controller | instance                   : ok=19   changed=9    unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-09-29 19:21:27.769992 | controller |
2025-09-29 19:21:27.770001 | controller | Monday 29 September 2025  19:21:27 +0000 (0:00:00.045)       0:01:04.122 ******
2025-09-29 19:21:27.770008 | controller | ===============================================================================
2025-09-29 19:21:27.770013 | controller | test_deps : Install selinux python libs -------------------------------- 46.05s
2025-09-29 19:21:27.770028 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.15s
2025-09-29 19:21:27.770034 | controller | test_deps : Install python yaml libs ------------------------------------ 1.24s
2025-09-29 19:21:27.770039 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.19s
2025-09-29 19:21:27.770052 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-09-29 19:21:27.770074 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.84s
2025-09-29 19:21:27.770896 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.79s
2025-09-29 19:21:27.770932 | controller | repo_setup : Install repo-setup package --------------------------------- 0.71s
2025-09-29 19:21:27.770940 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.68s
2025-09-29 19:21:27.770946 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.54s
2025-09-29 19:21:27.770951 | controller | repo_setup : Ensure directories are present ----------------------------- 0.54s
2025-09-29 19:21:27.770957 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.42s
2025-09-29 19:21:27.770962 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.32s
2025-09-29 19:21:27.770968 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s
2025-09-29 19:21:27.770975 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.23s
2025-09-29 19:21:27.771411 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.21s
2025-09-29 19:21:27.830337 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.17s
2025-09-29 19:21:27.830369 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-09-29 19:21:27.830377 | controller | test_deps : Install extra packages -------------------------------------- 0.05s
2025-09-29 19:21:27.830383 | controller | test_deps : Gather variables for each operating system ------------------ 0.04s
2025-09-29 19:21:27.830393 | controller | INFO     Running default > converge
2025-09-29 19:21:28.305227 | controller |
2025-09-29 19:21:29.061957 | controller | PLAY [Converge] ****************************************************************
2025-09-29 19:21:29.061992 | controller |
2025-09-29 19:21:29.062001 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 19:21:29.062008 | controller | Monday 29 September 2025  19:21:28 +0000 (0:00:00.023)       0:00:00.023 ******
2025-09-29 19:21:29.062020 | controller | ok: [instance]
2025-09-29 19:21:29.062046 | controller |
2025-09-29 19:21:29.062054 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-09-29 19:21:29.062062 | controller | Monday 29 September 2025  19:21:29 +0000 (0:00:00.757)       0:00:00.780 ******
2025-09-29 19:21:29.699536 | controller | ok: [instance] => (item=tmp)
2025-09-29 19:21:30.989080 | controller | ok: [instance] => (item=artifacts/repositories)
2025-09-29 19:21:30.989113 | controller | ok: [instance] => (item=venv/repo_setup)
2025-09-29 19:21:30.989120 | controller |
2025-09-29 19:21:30.989127 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-09-29 19:21:30.989133 | controller | Monday 29 September 2025  19:21:29 +0000 (0:00:00.637)       0:00:01.418 ******
2025-09-29 19:21:30.989143 | controller | ok: [instance]
2025-09-29 19:21:31.578726 | controller |
2025-09-29 19:21:31.578755 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-09-29 19:21:31.578764 | controller | Monday 29 September 2025  19:21:30 +0000 (0:00:01.289)       0:00:02.707 ******
2025-09-29 19:21:31.578774 | controller | ok: [instance]
2025-09-29 19:21:38.503003 | controller |
2025-09-29 19:21:38.503037 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-09-29 19:21:38.503045 | controller | Monday 29 September 2025  19:21:31 +0000 (0:00:00.589)       0:00:03.297 ******
2025-09-29 19:21:38.503055 | controller | changed: [instance]
2025-09-29 19:21:39.259888 | controller |
2025-09-29 19:21:39.259922 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-09-29 19:21:39.259937 | controller | Monday 29 September 2025  19:21:38 +0000 (0:00:06.924)       0:00:10.221 ******
2025-09-29 19:21:39.259948 | controller | changed: [instance]
2025-09-29 19:21:39.281141 | controller |
2025-09-29 19:21:39.281164 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-09-29 19:21:39.281171 | controller | Monday 29 September 2025  19:21:39 +0000 (0:00:00.756)       0:00:10.978 ******
2025-09-29 19:21:39.281180 | controller | skipping: [instance]
2025-09-29 19:21:40.100038 | controller |
2025-09-29 19:21:40.100067 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-09-29 19:21:40.100080 | controller | Monday 29 September 2025  19:21:39 +0000 (0:00:00.021)       0:00:11.000 ******
2025-09-29 19:21:40.100091 | controller | changed: [instance]
2025-09-29 19:21:40.731927 | controller |
2025-09-29 19:21:40.731958 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-09-29 19:21:40.731966 | controller | Monday 29 September 2025  19:21:40 +0000 (0:00:00.818)       0:00:11.818 ******
2025-09-29 19:21:40.731976 | controller | changed: [instance]
2025-09-29 19:21:41.056439 | controller |
2025-09-29 19:21:41.056475 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-09-29 19:21:41.056484 | controller | Monday 29 September 2025  19:21:40 +0000 (0:00:00.632)       0:00:12.450 ******
2025-09-29 19:21:41.056495 | controller | changed: [instance]
2025-09-29 19:21:41.437946 | controller |
2025-09-29 19:21:41.437993 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-09-29 19:21:41.438002 | controller | Monday 29 September 2025  19:21:41 +0000 (0:00:00.323)       0:00:12.774 ******
2025-09-29 19:21:41.438015 | controller | changed: [instance]
2025-09-29 19:21:42.005640 | controller |
2025-09-29 19:21:42.005670 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-09-29 19:21:42.005678 | controller | Monday 29 September 2025  19:21:41 +0000 (0:00:00.382)       0:00:13.156 ******
2025-09-29 19:21:42.005688 | controller | changed: [instance]
2025-09-29 19:21:42.034578 | controller |
2025-09-29 19:21:42.034602 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-09-29 19:21:42.034609 | controller | Monday 29 September 2025  19:21:42 +0000 (0:00:00.567)       0:00:13.724 ******
2025-09-29 19:21:42.034618 | controller | skipping: [instance]
2025-09-29 19:21:42.507917 | controller |
2025-09-29 19:21:42.507947 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-09-29 19:21:42.507955 | controller | Monday 29 September 2025  19:21:42 +0000 (0:00:00.029)       0:00:13.753 ******
2025-09-29 19:21:42.507965 | controller | changed: [instance]
2025-09-29 19:21:42.507983 | controller |
2025-09-29 19:21:42.508152 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-09-29 19:21:42.803504 | controller | Monday 29 September 2025  19:21:42 +0000 (0:00:00.473)       0:00:14.227 ******
2025-09-29 19:21:42.803540 | controller | ok: [instance]
2025-09-29 19:21:42.843972 | controller |
2025-09-29 19:21:42.844010 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-09-29 19:21:42.844020 | controller | Monday 29 September 2025  19:21:42 +0000 (0:00:00.295)       0:00:14.522 ******
2025-09-29 19:21:42.844032 | controller | ok: [instance]
2025-09-29 19:21:42.844106 | controller |
2025-09-29 19:21:42.844130 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-09-29 19:21:42.844143 | controller | Monday 29 September 2025  19:21:42 +0000 (0:00:00.040)       0:00:14.563 ******
2025-09-29 19:21:42.870684 | controller | ok: [instance]
2025-09-29 19:21:42.901353 | controller |
2025-09-29 19:21:42.901375 | controller | TASK [repo_setup : Create download directory] **********************************
2025-09-29 19:21:42.901385 | controller | Monday 29 September 2025  19:21:42 +0000 (0:00:00.026)       0:00:14.589 ******
2025-09-29 19:21:42.901395 | controller | skipping: [instance]
2025-09-29 19:21:42.931831 | controller |
2025-09-29 19:21:42.931882 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-09-29 19:21:42.931890 | controller | Monday 29 September 2025  19:21:42 +0000 (0:00:00.030)       0:00:14.620 ******
2025-09-29 19:21:42.931899 | controller | skipping: [instance]
2025-09-29 19:21:42.962300 | controller |
2025-09-29 19:21:42.962319 | controller | TASK [Download the RPM] ********************************************************
2025-09-29 19:21:42.962336 | controller | Monday 29 September 2025  19:21:42 +0000 (0:00:00.030)       0:00:14.650 ******
2025-09-29 19:21:42.962347 | controller | skipping: [instance]
2025-09-29 19:21:42.991909 | controller |
2025-09-29 19:21:42.991940 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-09-29 19:21:42.991947 | controller | Monday 29 September 2025  19:21:42 +0000 (0:00:00.030)       0:00:14.681 ******
2025-09-29 19:21:42.991956 | controller | skipping: [instance]
2025-09-29 19:21:42.992054 | controller |
2025-09-29 19:21:42.992064 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-09-29 19:21:42.992072 | controller | Monday 29 September 2025  19:21:42 +0000 (0:00:00.029)       0:00:14.711 ******
2025-09-29 19:21:43.020181 | controller | skipping: [instance]
2025-09-29 19:21:43.052344 | controller |
2025-09-29 19:21:43.052376 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-09-29 19:21:43.052384 | controller | Monday 29 September 2025  19:21:43 +0000 (0:00:00.028)       0:00:14.739 ******
2025-09-29 19:21:43.052399 | controller | skipping: [instance]
2025-09-29 19:21:43.080292 | controller |
2025-09-29 19:21:43.080325 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-09-29 19:21:43.080333 | controller | Monday 29 September 2025  19:21:43 +0000 (0:00:00.031)       0:00:14.770 ******
2025-09-29 19:21:43.080343 | controller | skipping: [instance]
2025-09-29 19:21:43.363889 | controller |
2025-09-29 19:21:43.363920 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-09-29 19:21:43.363929 | controller | Monday 29 September 2025  19:21:43 +0000 (0:00:00.027)       0:00:14.798 ******
2025-09-29 19:21:43.363938 | controller | ok: [instance]
2025-09-29 19:21:43.593054 | controller |
2025-09-29 19:21:43.593086 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-09-29 19:21:43.593094 | controller | Monday 29 September 2025  19:21:43 +0000 (0:00:00.283)       0:00:15.082 ******
2025-09-29 19:21:43.593103 | controller | changed: [instance]
2025-09-29 19:21:43.807462 | controller |
2025-09-29 19:21:43.807492 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-09-29 19:21:43.807500 | controller | Monday 29 September 2025  19:21:43 +0000 (0:00:00.229)       0:00:15.311 ******
2025-09-29 19:21:43.807510 | controller | changed: [instance]
2025-09-29 19:21:43.827694 | controller |
2025-09-29 19:21:43.827731 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-09-29 19:21:43.827738 | controller | Monday 29 September 2025  19:21:43 +0000 (0:00:00.214)       0:00:15.526 ******
2025-09-29 19:21:43.827748 | controller | skipping: [instance]
2025-09-29 19:21:43.860926 | controller |
2025-09-29 19:21:43.860959 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-09-29 19:21:43.860967 | controller | Monday 29 September 2025  19:21:43 +0000 (0:00:00.020)       0:00:15.546 ******
2025-09-29 19:21:43.860977 | controller | skipping: [instance]
2025-09-29 19:21:43.861002 | controller |
2025-09-29 19:21:43.861258 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-09-29 19:21:43.893612 | controller | Monday 29 September 2025  19:21:43 +0000 (0:00:00.033)       0:00:15.580 ******
2025-09-29 19:21:43.893642 | controller | skipping: [instance]
2025-09-29 19:21:43.927651 | controller |
2025-09-29 19:21:43.927683 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-09-29 19:21:43.927691 | controller | Monday 29 September 2025  19:21:43 +0000 (0:00:00.032)       0:00:15.612 ******
2025-09-29 19:21:43.927701 | controller | skipping: [instance]
2025-09-29 19:21:43.927886 | controller |
2025-09-29 19:21:43.928040 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-09-29 19:21:43.928184 | controller | Monday 29 September 2025  19:21:43 +0000 (0:00:00.034)       0:00:15.646 ******
2025-09-29 19:21:43.962558 | controller | skipping: [instance]
2025-09-29 19:21:43.962769 | controller |
2025-09-29 19:21:43.962950 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-09-29 19:21:43.963106 | controller | Monday 29 September 2025  19:21:43 +0000 (0:00:00.034)       0:00:15.681 ******
2025-09-29 19:21:43.997213 | controller | skipping: [instance]
2025-09-29 19:21:43.997378 | controller |
2025-09-29 19:21:43.997529 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-09-29 19:21:43.997678 | controller | Monday 29 September 2025  19:21:43 +0000 (0:00:00.034)       0:00:15.716 ******
2025-09-29 19:21:44.310063 | controller | ok: [instance]
2025-09-29 19:21:44.310140 | controller |
2025-09-29 19:21:44.310151 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-09-29 19:21:44.310160 | controller | Monday 29 September 2025  19:21:44 +0000 (0:00:00.312)       0:00:16.028 ******
2025-09-29 19:21:45.854437 | controller | changed: [instance] => (item=/etc/yum.repos.d/centos-addons.repo)
2025-09-29 19:21:45.854525 | controller | changed: [instance] => (item=/etc/yum.repos.d/centos.repo)
2025-09-29 19:21:45.855525 | controller | changed: [instance] => (item=/etc/yum.repos.d/delorean.repo)
2025-09-29 19:21:46.294649 | controller | changed: [instance] => (item=/etc/yum.repos.d/delorean-master-testing.repo)
2025-09-29 19:21:46.294680 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-highavailability.repo)
2025-09-29 19:21:46.294687 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-powertools.repo)
2025-09-29 19:21:46.294693 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-appstream.repo)
2025-09-29 19:21:46.294699 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-baseos.repo)
2025-09-29 19:21:46.294705 | controller |
2025-09-29 19:21:46.294710 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-09-29 19:21:46.294716 | controller | Monday 29 September 2025  19:21:45 +0000 (0:00:01.544)       0:00:17.573 ******
2025-09-29 19:21:46.294738 | controller | changed: [instance]
2025-09-29 19:21:46.611069 | controller |
2025-09-29 19:21:46.611099 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-09-29 19:21:46.611106 | controller | Monday 29 September 2025  19:21:46 +0000 (0:00:00.439)       0:00:18.013 ******
2025-09-29 19:21:46.611116 | controller | changed: [instance]
2025-09-29 19:21:46.973908 | controller |
2025-09-29 19:21:46.973948 | controller | TASK [Stat some files] *********************************************************
2025-09-29 19:21:46.973955 | controller | Monday 29 September 2025  19:21:46 +0000 (0:00:00.316)       0:00:18.330 ******
2025-09-29 19:21:46.973965 | controller | ok: [instance] => (item=artifacts/repositories/delorean.repo.md5)
2025-09-29 19:21:46.973989 | controller | ok: [instance] => (item=artifacts/repositories/baremetal_consistent_delorean.repo)
2025-09-29 19:21:46.974271 | controller |
2025-09-29 19:21:47.009913 | controller | TASK [Assert file status] ******************************************************
2025-09-29 19:21:47.009946 | controller | Monday 29 September 2025  19:21:46 +0000 (0:00:00.362)       0:00:18.692 ******
2025-09-29 19:21:47.009962 | controller | ok: [instance] => (item={'changed': False, 'stat': {'exists': True, 'path': '/home/zuul/ci-framework-data/artifacts/repositories/delorean.repo.md5', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 32, 'inode': 21008461, 'dev': 64513, 'nlink': 1, 'atime': 1759173702.7745097, 'mtime': 1759173702.459505, 'ctime': 1759173702.462505, 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False, 'blocks': 8, 'block_size': 4096, 'device_type': 0, 'readable': True, 'writeable': True, 'executable': False, 'pw_name': 'zuul', 'gr_name': 'zuul', 'checksum': '68c51000e5c65b64b30d8eacfd9bfc52b05cc3d3', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '3036832834', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/zuul/ci-framework-data/artifacts/repositories/delorean.repo.md5', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': 'artifacts/repositories/delorean.repo.md5', 'ansible_loop_var': 'item'}) => changed=false
2025-09-29 19:21:47.011892 | controller |   ansible_loop_var: item
2025-09-29 19:21:47.011955 | controller |   item:
2025-09-29 19:21:47.011967 | controller |     ansible_loop_var: item
2025-09-29 19:21:47.011973 | controller |     changed: false
2025-09-29 19:21:47.011979 | controller |     failed: false
2025-09-29 19:21:47.011984 | controller |     invocation:
2025-09-29 19:21:47.011990 | controller |       module_args:
2025-09-29 19:21:47.011995 | controller |         checksum_algorithm: sha1
2025-09-29 19:21:47.012001 | controller |         follow: false
2025-09-29 19:21:47.012006 | controller |         get_attributes: true
2025-09-29 19:21:47.012011 | controller |         get_checksum: true
2025-09-29 19:21:47.012020 | controller |         get_md5: false
2025-09-29 19:21:47.012025 | controller |         get_mime: true
2025-09-29 19:21:47.012031 | controller |         path: /home/zuul/ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-09-29 19:21:47.012036 | controller |     item: artifacts/repositories/delorean.repo.md5
2025-09-29 19:21:47.012042 | controller |     stat:
2025-09-29 19:21:47.012047 | controller |       atime: 1759173702.7745097
2025-09-29 19:21:47.012053 | controller |       attr_flags: ''
2025-09-29 19:21:47.012058 | controller |       attributes: []
2025-09-29 19:21:47.012066 | controller |       block_size: 4096
2025-09-29 19:21:47.012186 | controller |       blocks: 8
2025-09-29 19:21:47.012198 | controller |       charset: us-ascii
2025-09-29 19:21:47.012303 | controller |       checksum: 68c51000e5c65b64b30d8eacfd9bfc52b05cc3d3
2025-09-29 19:21:47.012422 | controller |       ctime: 1759173702.462505
2025-09-29 19:21:47.012572 | controller |       dev: 64513
2025-09-29 19:21:47.012679 | controller |       device_type: 0
2025-09-29 19:21:47.012803 | controller |       executable: false
2025-09-29 19:21:47.012938 | controller |       exists: true
2025-09-29 19:21:47.013060 | controller |       gid: 1000
2025-09-29 19:21:47.013170 | controller |       gr_name: zuul
2025-09-29 19:21:47.013279 | controller |       inode: 21008461
2025-09-29 19:21:47.013388 | controller |       isblk: false
2025-09-29 19:21:47.013499 | controller |       ischr: false
2025-09-29 19:21:47.013610 | controller |       isdir: false
2025-09-29 19:21:47.013720 | controller |       isfifo: false
2025-09-29 19:21:47.013864 | controller |       isgid: false
2025-09-29 19:21:47.013996 | controller |       islnk: false
2025-09-29 19:21:47.014110 | controller |       isreg: true
2025-09-29 19:21:47.014226 | controller |       issock: false
2025-09-29 19:21:47.014331 | controller |       isuid: false
2025-09-29 19:21:47.014440 | controller |       mimetype: text/plain
2025-09-29 19:21:47.014549 | controller |       mode: '0644'
2025-09-29 19:21:47.014656 | controller |       mtime: 1759173702.459505
2025-09-29 19:21:47.014780 | controller |       nlink: 1
2025-09-29 19:21:47.014911 | controller |       path: /home/zuul/ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-09-29 19:21:47.015029 | controller |       pw_name: zuul
2025-09-29 19:21:47.015139 | controller |       readable: true
2025-09-29 19:21:47.015246 | controller |       rgrp: true
2025-09-29 19:21:47.015358 | controller |       roth: true
2025-09-29 19:21:47.015500 | controller |       rusr: true
2025-09-29 19:21:47.015635 | controller |       size: 32
2025-09-29 19:21:47.015800 | controller |       uid: 1000
2025-09-29 19:21:47.015957 | controller |       version: '3036832834'
2025-09-29 19:21:47.016097 | controller |       wgrp: false
2025-09-29 19:21:47.016231 | controller |       woth: false
2025-09-29 19:21:47.016363 | controller |       writeable: true
2025-09-29 19:21:47.016491 | controller |       wusr: true
2025-09-29 19:21:47.016623 | controller |       xgrp: false
2025-09-29 19:21:47.016767 | controller |       xoth: false
2025-09-29 19:21:47.016924 | controller |       xusr: false
2025-09-29 19:21:47.017060 | controller |   msg: All assertions passed
2025-09-29 19:21:47.017224 | controller | ok: [instance] => (item={'changed': False, 'stat': {'exists': True, 'path': '/home/zuul/ci-framework-data/artifacts/repositories/baremetal_consistent_delorean.repo', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 289, 'inode': 16806337, 'dev': 64513, 'nlink': 1, 'atime': 1759173706.570564, 'mtime': 1759173703.774524, 'ctime': 1759173703.774524, 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False, 'blocks': 8, 'block_size': 4096, 'device_type': 0, 'readable': True, 'writeable': True, 'executable': False, 'pw_name': 'zuul', 'gr_name': 'zuul', 'checksum': '18a23cf67fa7acd888116b0a7e47eb663f69ee2e', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '487799464', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/zuul/ci-framework-data/artifacts/repositories/baremetal_consistent_delorean.repo', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': 'artifacts/repositories/baremetal_consistent_delorean.repo', 'ansible_loop_var': 'item'}) => changed=false
2025-09-29 19:21:47.017367 | controller |   ansible_loop_var: item
2025-09-29 19:21:47.017495 | controller |   item:
2025-09-29 19:21:47.017631 | controller |     ansible_loop_var: item
2025-09-29 19:21:47.017778 | controller |     changed: false
2025-09-29 19:21:47.017930 | controller |     failed: false
2025-09-29 19:21:47.018068 | controller |     invocation:
2025-09-29 19:21:47.018199 | controller |       module_args:
2025-09-29 19:21:47.018328 | controller |         checksum_algorithm: sha1
2025-09-29 19:21:47.018457 | controller |         follow: false
2025-09-29 19:21:47.018586 | controller |         get_attributes: true
2025-09-29 19:21:47.018714 | controller |         get_checksum: true
2025-09-29 19:21:47.018882 | controller |         get_md5: false
2025-09-29 19:21:47.019021 | controller |         get_mime: true
2025-09-29 19:21:47.019157 | controller |         path: /home/zuul/ci-framework-data/artifacts/repositories/baremetal_consistent_delorean.repo
2025-09-29 19:21:47.019287 | controller |     item: artifacts/repositories/baremetal_consistent_delorean.repo
2025-09-29 19:21:47.019414 | controller |     stat:
2025-09-29 19:21:47.019541 | controller |       atime: 1759173706.570564
2025-09-29 19:21:47.019675 | controller |       attr_flags: ''
2025-09-29 19:21:47.019823 | controller |       attributes: []
2025-09-29 19:21:47.019976 | controller |       block_size: 4096
2025-09-29 19:21:47.020110 | controller |       blocks: 8
2025-09-29 19:21:47.020238 | controller |       charset: us-ascii
2025-09-29 19:21:47.020367 | controller |       checksum: 18a23cf67fa7acd888116b0a7e47eb663f69ee2e
2025-09-29 19:21:47.020494 | controller |       ctime: 1759173703.774524
2025-09-29 19:21:47.020620 | controller |       dev: 64513
2025-09-29 19:21:47.020759 | controller |       device_type: 0
2025-09-29 19:21:47.020907 | controller |       executable: false
2025-09-29 19:21:47.021050 | controller |       exists: true
2025-09-29 19:21:47.021182 | controller |       gid: 1000
2025-09-29 19:21:47.021313 | controller |       gr_name: zuul
2025-09-29 19:21:47.021441 | controller |       inode: 16806337
2025-09-29 19:21:47.021577 | controller |       isblk: false
2025-09-29 19:21:47.021712 | controller |       ischr: false
2025-09-29 19:21:47.021874 | controller |       isdir: false
2025-09-29 19:21:47.022012 | controller |       isfifo: false
2025-09-29 19:21:47.022141 | controller |       isgid: false
2025-09-29 19:21:47.022270 | controller |       islnk: false
2025-09-29 19:21:47.022395 | controller |       isreg: true
2025-09-29 19:21:47.022529 | controller |       issock: false
2025-09-29 19:21:47.022660 | controller |       isuid: false
2025-09-29 19:21:47.022806 | controller |       mimetype: text/plain
2025-09-29 19:21:47.022961 | controller |       mode: '0644'
2025-09-29 19:21:47.023097 | controller |       mtime: 1759173703.774524
2025-09-29 19:21:47.023224 | controller |       nlink: 1
2025-09-29 19:21:47.023362 | controller |       path: /home/zuul/ci-framework-data/artifacts/repositories/baremetal_consistent_delorean.repo
2025-09-29 19:21:47.023501 | controller |       pw_name: zuul
2025-09-29 19:21:47.023630 | controller |       readable: true
2025-09-29 19:21:47.023768 | controller |       rgrp: true
2025-09-29 19:21:47.023932 | controller |       roth: true
2025-09-29 19:21:47.024067 | controller |       rusr: true
2025-09-29 19:21:47.024196 | controller |       size: 289
2025-09-29 19:21:47.024324 | controller |       uid: 1000
2025-09-29 19:21:47.024451 | controller |       version: '487799464'
2025-09-29 19:21:47.024578 | controller |       wgrp: false
2025-09-29 19:21:47.024706 | controller |       woth: false
2025-09-29 19:21:47.024859 | controller |       writeable: true
2025-09-29 19:21:47.025003 | controller |       wusr: true
2025-09-29 19:21:47.025133 | controller |       xgrp: false
2025-09-29 19:21:47.025265 | controller |       xoth: false
2025-09-29 19:21:47.025396 | controller |       xusr: false
2025-09-29 19:21:47.025524 | controller |   msg: All assertions passed
2025-09-29 19:21:47.025648 | controller |
2025-09-29 19:21:47.025790 | controller | TASK [Cleanup env] *************************************************************
2025-09-29 19:21:47.025944 | controller | Monday 29 September 2025  19:21:47 +0000 (0:00:00.036)       0:00:18.729 ******
2025-09-29 19:21:47.034193 | controller |
2025-09-29 19:21:47.034366 | controller | TASK [repo_setup : Remove virtualenv] ******************************************
2025-09-29 19:21:47.034538 | controller | Monday 29 September 2025  19:21:47 +0000 (0:00:00.024)       0:00:18.753 ******
2025-09-29 19:21:47.291335 | controller | changed: [instance]
2025-09-29 19:21:47.493161 | controller |
2025-09-29 19:21:47.493189 | controller | TASK [repo_setup : Remove repositories] ****************************************
2025-09-29 19:21:47.493197 | controller | Monday 29 September 2025  19:21:47 +0000 (0:00:00.256)       0:00:19.010 ******
2025-09-29 19:21:47.493207 | controller | changed: [instance]
2025-09-29 19:21:47.996053 | controller |
2025-09-29 19:21:47.996096 | controller | TASK [Stat some files] *********************************************************
2025-09-29 19:21:47.996104 | controller | Monday 29 September 2025  19:21:47 +0000 (0:00:00.201)       0:00:19.212 ******
2025-09-29 19:21:47.996115 | controller | ok: [instance] => (item=artifacts/repositories/delorean.repo.md5)
2025-09-29 19:21:48.047955 | controller | ok: [instance] => (item=venv/repo_setup_test)
2025-09-29 19:21:48.047987 | controller | ok: [instance] => (item=artifacts/repositories)
2025-09-29 19:21:48.047994 | controller |
2025-09-29 19:21:48.048001 | controller | TASK [Assert file status] ******************************************************
2025-09-29 19:21:48.048007 | controller | Monday 29 September 2025  19:21:47 +0000 (0:00:00.502)       0:00:19.714 ******
2025-09-29 19:21:48.048017 | controller | ok: [instance] => (item={'changed': False, 'stat': {'exists': False}, 'invocation': {'module_args': {'path': '/home/zuul/ci-framework-data/artifacts/repositories/delorean.repo.md5', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': 'artifacts/repositories/delorean.repo.md5', 'ansible_loop_var': 'item'}) => changed=false
2025-09-29 19:21:48.048043 | controller |   ansible_loop_var: item
2025-09-29 19:21:48.048050 | controller |   item:
2025-09-29 19:21:48.048056 | controller |     ansible_loop_var: item
2025-09-29 19:21:48.048062 | controller |     changed: false
2025-09-29 19:21:48.048067 | controller |     failed: false
2025-09-29 19:21:48.048073 | controller |     invocation:
2025-09-29 19:21:48.048080 | controller |       module_args:
2025-09-29 19:21:48.048085 | controller |         checksum_algorithm: sha1
2025-09-29 19:21:48.048091 | controller |         follow: false
2025-09-29 19:21:48.048096 | controller |         get_attributes: true
2025-09-29 19:21:48.048102 | controller |         get_checksum: true
2025-09-29 19:21:48.048107 | controller |         get_md5: false
2025-09-29 19:21:48.048114 | controller |         get_mime: true
2025-09-29 19:21:48.048134 | controller |         path: /home/zuul/ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-09-29 19:21:48.048214 | controller |     item: artifacts/repositories/delorean.repo.md5
2025-09-29 19:21:48.048334 | controller |     stat:
2025-09-29 19:21:48.048449 | controller |       exists: false
2025-09-29 19:21:48.048564 | controller |   msg: All assertions passed
2025-09-29 19:21:48.048696 | controller | ok: [instance] => (item={'changed': False, 'stat': {'exists': False}, 'invocation': {'module_args': {'path': '/home/zuul/ci-framework-data/venv/repo_setup_test', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': 'venv/repo_setup_test', 'ansible_loop_var': 'item'}) => changed=false
2025-09-29 19:21:48.048830 | controller |   ansible_loop_var: item
2025-09-29 19:21:48.048971 | controller |   item:
2025-09-29 19:21:48.049186 | controller |     ansible_loop_var: item
2025-09-29 19:21:48.049303 | controller |     changed: false
2025-09-29 19:21:48.049422 | controller |     failed: false
2025-09-29 19:21:48.049567 | controller |     invocation:
2025-09-29 19:21:48.049749 | controller |       module_args:
2025-09-29 19:21:48.049900 | controller |         checksum_algorithm: sha1
2025-09-29 19:21:48.050033 | controller |         follow: false
2025-09-29 19:21:48.050155 | controller |         get_attributes: true
2025-09-29 19:21:48.050335 | controller |         get_checksum: true
2025-09-29 19:21:48.050467 | controller |         get_md5: false
2025-09-29 19:21:48.050584 | controller |         get_mime: true
2025-09-29 19:21:48.050708 | controller |         path: /home/zuul/ci-framework-data/venv/repo_setup_test
2025-09-29 19:21:48.050852 | controller |     item: venv/repo_setup_test
2025-09-29 19:21:48.051059 | controller |     stat:
2025-09-29 19:21:48.051214 | controller |       exists: false
2025-09-29 19:21:48.051369 | controller |   msg: All assertions passed
2025-09-29 19:21:48.051507 | controller | ok: [instance] => (item={'changed': False, 'stat': {'exists': False}, 'invocation': {'module_args': {'path': '/home/zuul/ci-framework-data/artifacts/repositories', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': 'artifacts/repositories', 'ansible_loop_var': 'item'}) => changed=false
2025-09-29 19:21:48.051634 | controller |   ansible_loop_var: item
2025-09-29 19:21:48.051768 | controller |   item:
2025-09-29 19:21:48.051974 | controller |     ansible_loop_var: item
2025-09-29 19:21:48.052139 | controller |     changed: false
2025-09-29 19:21:48.052327 | controller |     failed: false
2025-09-29 19:21:48.052473 | controller |     invocation:
2025-09-29 19:21:48.052593 | controller |       module_args:
2025-09-29 19:21:48.052715 | controller |         checksum_algorithm: sha1
2025-09-29 19:21:48.052861 | controller |         follow: false
2025-09-29 19:21:48.052989 | controller |         get_attributes: true
2025-09-29 19:21:48.053106 | controller |         get_checksum: true
2025-09-29 19:21:48.053224 | controller |         get_md5: false
2025-09-29 19:21:48.053336 | controller |         get_mime: true
2025-09-29 19:21:48.053487 | controller |         path: /home/zuul/ci-framework-data/artifacts/repositories
2025-09-29 19:21:48.053668 | controller |     item: artifacts/repositories
2025-09-29 19:21:48.053864 | controller |     stat:
2025-09-29 19:21:48.053999 | controller |       exists: false
2025-09-29 19:21:48.054127 | controller |   msg: All assertions passed
2025-09-29 19:21:48.054242 | controller |
2025-09-29 19:21:48.054362 | controller | PLAY RECAP *********************************************************************
2025-09-29 19:21:48.054541 | controller | instance                   : ok=28   changed=15   unreachable=0    failed=0    skipped=15   rescued=0    ignored=0
2025-09-29 19:21:48.054676 | controller |
2025-09-29 19:21:48.054907 | controller | Monday 29 September 2025  19:21:48 +0000 (0:00:00.051)       0:00:19.765 ******
2025-09-29 19:21:48.055041 | controller | ===============================================================================
2025-09-29 19:21:48.055169 | controller | repo_setup : Initialize python venv and install requirements ------------ 6.92s
2025-09-29 19:21:48.055288 | controller | repo_setup : Remove existing repos from /etc/yum.repos.d directory ------ 1.54s
2025-09-29 19:21:48.055405 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.29s
2025-09-29 19:21:48.055524 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.82s
2025-09-29 19:21:48.055658 | controller | Gathering Facts --------------------------------------------------------- 0.76s
2025-09-29 19:21:48.055788 | controller | repo_setup : Install repo-setup package --------------------------------- 0.76s
2025-09-29 19:21:48.055919 | controller | repo_setup : Ensure directories are present ----------------------------- 0.64s
2025-09-29 19:21:48.056031 | controller | repo_setup : Get component repo ----------------------------------------- 0.63s
2025-09-29 19:21:48.056139 | controller | repo_setup : Get repo-setup repository ---------------------------------- 0.59s
2025-09-29 19:21:48.056252 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s
2025-09-29 19:21:48.056360 | controller | Stat some files --------------------------------------------------------- 0.50s
2025-09-29 19:21:48.056469 | controller | repo_setup : Dump current-podified hash --------------------------------- 0.47s
2025-09-29 19:21:48.056579 | controller | repo_setup : Cleanup existing metadata ---------------------------------- 0.44s
2025-09-29 19:21:48.056719 | controller | repo_setup : Disable component repo in current-podified dlrn repo ------- 0.38s
2025-09-29 19:21:48.056890 | controller | Stat some files --------------------------------------------------------- 0.36s
2025-09-29 19:21:48.057092 | controller | repo_setup : Rename component repo -------------------------------------- 0.32s
2025-09-29 19:21:48.057212 | controller | repo_setup : Copy generated repos to /etc/yum.repos.d directory --------- 0.32s
2025-09-29 19:21:48.057336 | controller | repo_setup : Find existing repos from /etc/yum.repos.d directory -------- 0.31s
2025-09-29 19:21:48.057517 | controller | repo_setup : Slurp current podified hash -------------------------------- 0.30s
2025-09-29 19:21:48.057639 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.28s
2025-09-29 19:21:48.109024 | controller | INFO     Running default > cleanup
2025-09-29 19:21:48.109478 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-09-29 19:21:48.110227 | controller | INFO     Writing /tmp/report.html report.
2025-09-29 19:21:48.481555 | controller | changed
2025-09-29 19:21:48.508186 | 
2025-09-29 19:21:48.508260 | PLAY RECAP
2025-09-29 19:21:48.508317 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-29 19:21:48.508342 | 
2025-09-29 19:21:48.586163 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-29 19:21:48.587147 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-29 19:21:49.183378 | 
2025-09-29 19:21:49.183482 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-09-29 19:21:49.204268 | 
2025-09-29 19:21:49.204360 | TASK [Filter out host if needed]
2025-09-29 19:21:49.213208 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-09-29 19:21:49.217874 | 
2025-09-29 19:21:49.217960 | TASK [Ensure file is present]
2025-09-29 19:21:49.540745 | controller | ok
2025-09-29 19:21:49.547549 | 
2025-09-29 19:21:49.547643 | TASK [Manage molecule report file]
2025-09-29 19:21:50.074343 | controller | changed
2025-09-29 19:21:50.079771 | 
2025-09-29 19:21:50.079856 | TASK [Check if we get ci-framework-data basedir]
2025-09-29 19:21:50.264892 | controller | ok
2025-09-29 19:21:50.271902 | 
2025-09-29 19:21:50.272019 | TASK [Create ci-framework-data log directory for zuul]
2025-09-29 19:21:50.614384 | controller | changed
2025-09-29 19:21:50.619843 | 
2025-09-29 19:21:50.619927 | TASK [Copy ci-framework interesting files]
2025-09-29 19:21:50.818522 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-09-29 19:21:51.144855 | controller | changed
2025-09-29 19:21:51.150443 | 
2025-09-29 19:21:51.150519 | TASK [Get SELinux listing]
2025-09-29 19:21:51.675225 | controller | changed
2025-09-29 19:21:51.680994 | 
2025-09-29 19:21:51.681066 | TASK [Generate log index]
2025-09-29 19:21:52.328490 | controller | changed
2025-09-29 19:21:52.333447 | 
2025-09-29 19:21:52.333525 | TASK [Get some env related data]
2025-09-29 19:21:52.863772 | controller | /home/zuul/.local/bin/ansible
2025-09-29 19:21:53.861204 | controller | changed
2025-09-29 19:21:53.866602 | 
2025-09-29 19:21:53.866662 | TASK [Generate list of logs to collect in home directory]
2025-09-29 19:21:54.158181 | controller | ok: All paths examined
2025-09-29 19:21:54.163773 | 
2025-09-29 19:21:54.163839 | LOOP [Copy logs from home directory]
2025-09-29 19:21:54.484098 | controller | changed:
2025-09-29 19:21:54.484216 | controller | {
2025-09-29 19:21:54.484244 | controller |   "atime": 1759173587.4862561,
2025-09-29 19:21:54.484265 | controller |   "ctime": 1759173603.6225984,
2025-09-29 19:21:54.484296 | controller |   "dev": 64513,
2025-09-29 19:21:54.484316 | controller |   "gid": 1000,
2025-09-29 19:21:54.484333 | controller |   "gr_name": "zuul",
2025-09-29 19:21:54.484350 | controller |   "inode": 4401321,
2025-09-29 19:21:54.484366 | controller |   "isblk": false,
2025-09-29 19:21:54.484381 | controller |   "ischr": false,
2025-09-29 19:21:54.484395 | controller |   "isdir": false,
2025-09-29 19:21:54.484409 | controller |   "isfifo": false,
2025-09-29 19:21:54.484424 | controller |   "isgid": false,
2025-09-29 19:21:54.484438 | controller |   "islnk": false,
2025-09-29 19:21:54.484452 | controller |   "isreg": true,
2025-09-29 19:21:54.484468 | controller |   "issock": false,
2025-09-29 19:21:54.484483 | controller |   "isuid": false,
2025-09-29 19:21:54.484498 | controller |   "mode": "0644",
2025-09-29 19:21:54.484512 | controller |   "mtime": 1759173603.6225984,
2025-09-29 19:21:54.484526 | controller |   "nlink": 1,
2025-09-29 19:21:54.484540 | controller |   "path": "/home/zuul/ansible.log",
2025-09-29 19:21:54.484661 | controller |   "pw_name": "zuul",
2025-09-29 19:21:54.484686 | controller |   "rgrp": true,
2025-09-29 19:21:54.484704 | controller |   "roth": true,
2025-09-29 19:21:54.484720 | controller |   "rusr": true,
2025-09-29 19:21:54.484735 | controller |   "size": 6749,
2025-09-29 19:21:54.484750 | controller |   "uid": 1000,
2025-09-29 19:21:54.484763 | controller |   "wgrp": false,
2025-09-29 19:21:54.484777 | controller |   "woth": false,
2025-09-29 19:21:54.484794 | controller |   "wusr": true,
2025-09-29 19:21:54.484809 | controller |   "xgrp": false,
2025-09-29 19:21:54.484823 | controller |   "xoth": false,
2025-09-29 19:21:54.484838 | controller |   "xusr": false
2025-09-29 19:21:54.484851 | controller | }
2025-09-29 19:21:54.499050 | 
2025-09-29 19:21:54.499176 | TASK [Copy crio stats log file]
2025-09-29 19:21:54.523210 | controller | skipping: Conditional result was False
2025-09-29 19:21:54.529497 | 
2025-09-29 19:21:54.529576 | TASK [Get SELinux related data]
2025-09-29 19:21:54.734639 | controller | <no matches>
2025-09-29 19:21:55.057361 | controller | ERROR
2025-09-29 19:21:55.057555 | controller | {
2025-09-29 19:21:55.057596 | controller |   "delta": "0:00:00.009284",
2025-09-29 19:21:55.057620 | controller |   "end": "2025-09-29 19:21:54.735663",
2025-09-29 19:21:55.057641 | controller |   "msg": "non-zero return code",
2025-09-29 19:21:55.057659 | controller |   "rc": 1,
2025-09-29 19:21:55.057677 | controller |   "start": "2025-09-29 19:21:54.726379"
2025-09-29 19:21:55.057701 | controller | }
2025-09-29 19:21:55.057727 | controller | ERROR: Ignoring Errors
2025-09-29 19:21:55.063817 | 
2025-09-29 19:21:55.063885 | TASK [Create system configuration directory]
2025-09-29 19:21:55.255098 | controller | changed
2025-09-29 19:21:55.260076 | 
2025-09-29 19:21:55.260228 | TASK [Get some of the system configurations]
2025-09-29 19:21:55.786788 | controller | changed
2025-09-29 19:21:55.792948 | 
2025-09-29 19:21:55.793025 | TASK [Copy generated documentation if available]
2025-09-29 19:21:55.818138 | controller | skipping: Conditional result was False
2025-09-29 19:21:55.824483 | 
2025-09-29 19:21:55.824551 | TASK [Copy generated AsciiDoc documentation if available]
2025-09-29 19:21:55.838428 | controller | skipping: Conditional result was False
2025-09-29 19:21:55.844892 | 
2025-09-29 19:21:55.844986 | TASK [Compress logs bigger than 2MB]
2025-09-29 19:21:56.378117 | controller | changed
2025-09-29 19:21:56.383574 | 
2025-09-29 19:21:56.383640 | TASK [Copy files from workspace on node]
2025-09-29 19:21:56.422358 | controller | ok
2025-09-29 19:21:56.464847 | 
2025-09-29 19:21:56.464969 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-29 19:21:56.491730 | controller | skipping: Conditional result was False
2025-09-29 19:21:56.501967 | 
2025-09-29 19:21:56.502037 | TASK [fetch-output : Set log path for single node]
2025-09-29 19:21:56.581899 | controller | ok
2025-09-29 19:21:56.586623 | 
2025-09-29 19:21:56.586684 | LOOP [fetch-output : Ensure local output dirs]
2025-09-29 19:21:56.823226 | controller -> localhost | ok: "/var/lib/zuul/builds/9d9b6a076db54803b9c1f490b6de0015/work/logs"
2025-09-29 19:21:56.823469 | controller -> localhost | changed: All items complete
2025-09-29 19:21:56.823499 | 
2025-09-29 19:21:57.052137 | controller -> localhost | changed: "/var/lib/zuul/builds/9d9b6a076db54803b9c1f490b6de0015/work/artifacts"
2025-09-29 19:21:57.265421 | controller -> localhost | changed: "/var/lib/zuul/builds/9d9b6a076db54803b9c1f490b6de0015/work/docs"
2025-09-29 19:21:57.276018 | 
2025-09-29 19:21:57.276129 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-29 19:21:57.909041 | controller | changed:
2025-09-29 19:21:57.909340 | controller | .d..t...... ./
2025-09-29 19:21:57.909381 | controller | >f+++++++++ README.html
2025-09-29 19:21:57.909406 | controller | >f+++++++++ ansible-execution.log
2025-09-29 19:21:57.909428 | controller | >f+++++++++ ansible.log
2025-09-29 19:21:57.909448 | controller | >f+++++++++ dmesg.log
2025-09-29 19:21:57.909467 | controller | >f+++++++++ installed-pkgs.log
2025-09-29 19:21:57.909489 | controller | >f+++++++++ python.log
2025-09-29 19:21:57.909510 | controller | >f+++++++++ registries.conf
2025-09-29 19:21:57.909528 | controller | >f+++++++++ report.html
2025-09-29 19:21:57.909548 | controller | >f+++++++++ selinux-denials.log
2025-09-29 19:21:57.909566 | controller | >f+++++++++ selinux-listing.log
2025-09-29 19:21:57.909583 | controller | cd+++++++++ ci-framework-data/
2025-09-29 19:21:57.909600 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-09-29 19:21:57.909616 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-09-29 19:21:57.909634 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-09-29 19:21:57.909651 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-09-29 19:21:57.909667 | controller | cd+++++++++ ci-framework-data/logs/
2025-09-29 19:21:57.909684 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-09-29 19:21:57.909700 | controller | cd+++++++++ registries.conf.d/
2025-09-29 19:21:57.909716 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-09-29 19:21:57.909732 | controller | cd+++++++++ system-config/
2025-09-29 19:21:57.909748 | controller | cd+++++++++ system-config/libvirt/
2025-09-29 19:21:57.909764 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-09-29 19:21:57.909781 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-09-29 19:21:58.313423 | controller | changed: .d..t...... ./
2025-09-29 19:21:58.740590 | controller | changed: .d..t...... ./
2025-09-29 19:21:58.754888 | 
2025-09-29 19:21:58.757704 | TASK [Return artifact to Zuul]
2025-09-29 19:21:58.789531 | controller | ok
2025-09-29 19:21:58.810484 | 
2025-09-29 19:21:58.810562 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-09-29 19:21:58.810679 | 
2025-09-29 19:21:58.810712 | PLAY RECAP
2025-09-29 19:21:58.810753 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-09-29 19:21:58.810776 | 
2025-09-29 19:21:58.910178 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-29 19:21:58.911037 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-29 19:21:59.496797 | 
2025-09-29 19:21:59.496947 | PLAY [all]
2025-09-29 19:21:59.517726 | 
2025-09-29 19:21:59.517802 | TASK [include_role : fetch-output]
2025-09-29 19:21:59.558302 | controller | ok
2025-09-29 19:21:59.579152 | 
2025-09-29 19:21:59.579382 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-29 19:21:59.655773 | controller | skipping: Conditional result was False
2025-09-29 19:21:59.662484 | 
2025-09-29 19:21:59.662585 | TASK [fetch-output : Set log path for single node]
2025-09-29 19:21:59.704142 | controller | ok
2025-09-29 19:21:59.712065 | 
2025-09-29 19:21:59.712185 | LOOP [fetch-output : Ensure local output dirs]
2025-09-29 19:22:00.179326 | controller -> localhost | ok: "/var/lib/zuul/builds/9d9b6a076db54803b9c1f490b6de0015/work/logs"
2025-09-29 19:22:00.422835 | controller -> localhost | ok: "/var/lib/zuul/builds/9d9b6a076db54803b9c1f490b6de0015/work/artifacts"
2025-09-29 19:22:00.793100 | controller -> localhost | ok: "/var/lib/zuul/builds/9d9b6a076db54803b9c1f490b6de0015/work/docs"
2025-09-29 19:22:00.808806 | 
2025-09-29 19:22:00.808961 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-29 19:22:01.405782 | controller | ok
2025-09-29 19:22:01.406005 | controller | ok: All items complete
2025-09-29 19:22:01.406037 | 
2025-09-29 19:22:01.859564 | controller | ok
2025-09-29 19:22:02.268435 | controller | ok
2025-09-29 19:22:02.281704 | 
2025-09-29 19:22:02.281798 | TASK [include_role : fetch-output-openshift]
2025-09-29 19:22:02.295440 | controller | skipping: Conditional result was False
2025-09-29 19:22:02.301329 | 
2025-09-29 19:22:02.301399 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-09-29 19:22:02.715895 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007189
2025-09-29 19:22:02.940566 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.011976
2025-09-29 19:22:02.979198 | 
2025-09-29 19:22:02.979306 | PLAY [all]
2025-09-29 19:22:03.000527 | 
2025-09-29 19:22:03.000604 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-09-29 19:22:03.448034 | controller | changed
2025-09-29 19:22:03.476219 | 
2025-09-29 19:22:03.476328 | PLAY RECAP
2025-09-29 19:22:03.476373 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-29 19:22:03.476396 | 
2025-09-29 19:22:03.594294 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-29 19:22:03.595374 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-09-29 19:22:04.261675 | 
2025-09-29 19:22:04.261786 | PLAY [localhost]
2025-09-29 19:22:04.282324 | 
2025-09-29 19:22:04.282426 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-09-29 19:22:04.861193 | localhost | changed
2025-09-29 19:22:04.866678 | 
2025-09-29 19:22:04.866782 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-09-29 19:22:04.894258 | localhost | ok
2025-09-29 19:22:04.902302 | 
2025-09-29 19:22:04.902389 | TASK [add-fileserver : Create SSH private key tempfile]
2025-09-29 19:22:05.265715 | localhost | changed
2025-09-29 19:22:05.270544 | 
2025-09-29 19:22:05.270622 | TASK [add-fileserver : Create SSH private key from secret]
2025-09-29 19:22:06.244889 | localhost | changed
2025-09-29 19:22:06.250309 | 
2025-09-29 19:22:06.250399 | TASK [add-fileserver : Add fileserver ssh key]
2025-09-29 19:22:06.647234 | localhost | Identity added: /var/lib/zuul/builds/9d9b6a076db54803b9c1f490b6de0015/work/tmp/ansible.myegcrlw (/var/lib/zuul/builds/9d9b6a076db54803b9c1f490b6de0015/work/tmp/ansible.myegcrlw)
2025-09-29 19:22:06.647487 | localhost | ok: Runtime: 0:00:00.006921
2025-09-29 19:22:06.653365 | 
2025-09-29 19:22:06.653438 | TASK [add-fileserver : Remove SSH private key from disk]
2025-09-29 19:22:06.926177 | localhost | ok: Runtime: 0:00:00.005109
2025-09-29 19:22:06.930676 | 
2025-09-29 19:22:06.930748 | TASK [add-fileserver : Add fileserver to inventory]
2025-09-29 19:22:06.988870 | localhost | changed
2025-09-29 19:22:06.993186 | 
2025-09-29 19:22:06.993255 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-09-29 19:22:07.375669 | localhost | changed
2025-09-29 19:22:07.435098 | 
2025-09-29 19:22:07.435231 | PLAY [localhost]
2025-09-29 19:22:07.455319 | 
2025-09-29 19:22:07.455430 | TASK [Generate bulk log download script]
2025-09-29 19:22:07.475450 | localhost | ok
2025-09-29 19:22:07.488259 | 
2025-09-29 19:22:07.488378 | TASK [local-log-download : Check API endpoint is defined]
2025-09-29 19:22:07.528392 | localhost | ok: All assertions passed
2025-09-29 19:22:07.535629 | 
2025-09-29 19:22:07.536822 | TASK [local-log-download : Create download script]
2025-09-29 19:22:07.957084 | localhost -> localhost | changed
2025-09-29 19:22:07.967421 | 
2025-09-29 19:22:07.967539 | TASK [Register quick-download link]
2025-09-29 19:22:08.011240 | localhost | ok
2025-09-29 19:22:08.026722 | 
2025-09-29 19:22:08.026788 | PLAY [logserver.rdoproject.org]
2025-09-29 19:22:08.036561 | 
2025-09-29 19:22:08.036622 | TASK [Set zuul-log-path fact]
2025-09-29 19:22:08.053259 | logserver.rdoproject.org | ok
2025-09-29 19:22:08.063367 | 
2025-09-29 19:22:08.063448 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-29 19:22:08.090247 | logserver.rdoproject.org | ok
2025-09-29 19:22:08.097520 | 
2025-09-29 19:22:08.097617 | TASK [upload-logs : Create log directories]
2025-09-29 19:22:09.839222 | logserver.rdoproject.org | changed
2025-09-29 19:22:09.842970 | 
2025-09-29 19:22:09.843061 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-09-29 19:22:10.195649 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.030432
2025-09-29 19:22:10.201587 | 
2025-09-29 19:22:10.201678 | TASK [upload-logs : Upload logs to log server]
2025-09-29 19:22:11.956449 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-09-29 19:22:11.959548 | 
2025-09-29 19:22:11.959634 | LOOP [upload-logs : Compress console log and json output]
2025-09-29 19:22:12.002945 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 19:22:12.012896 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 19:22:12.018366 | 
2025-09-29 19:22:12.018477 | LOOP [upload-logs : Upload compressed console log and json output]
2025-09-29 19:22:12.061435 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 19:22:12.061664 | 
2025-09-29 19:22:12.065764 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 19:22:12.076873 | 
2025-09-29 19:22:12.076971 | LOOP [upload-logs : Upload console log and json output]