2025-09-29 19:07:47.708886 | Job console starting...
2025-09-29 19:07:47.960761 | Updating repositories
2025-09-29 19:07:48.755939 | Preparing job workspace
2025-09-29 19:07:57.036810 | Running Ansible setup...
2025-09-29 19:08:00.818079 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-29 19:08:01.352873 | 
2025-09-29 19:08:01.352999 | PLAY [localhost]
2025-09-29 19:08:01.361578 | 
2025-09-29 19:08:01.361651 | TASK [Gathering Facts]
2025-09-29 19:08:02.368418 | localhost | ok
2025-09-29 19:08:02.387009 | 
2025-09-29 19:08:02.387188 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-09-29 19:08:02.789991 | localhost -> localhost | changed
2025-09-29 19:08:02.795807 | 
2025-09-29 19:08:02.795880 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-09-29 19:08:03.647802 | localhost -> localhost | changed
2025-09-29 19:08:03.657302 | 
2025-09-29 19:08:03.657710 | TASK [Setup log path fact]
2025-09-29 19:08:03.686081 | localhost | ok
2025-09-29 19:08:03.698855 | 
2025-09-29 19:08:03.698971 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-29 19:08:03.726370 | localhost | ok
2025-09-29 19:08:03.736312 | 
2025-09-29 19:08:03.736401 | TASK [emit-job-header : Print job information]
2025-09-29 19:08:03.775444 | # Job Information
2025-09-29 19:08:03.775580 | Ansible Version: 2.15.12
2025-09-29 19:08:03.775612 | Job: cifmw-molecule-rhol_crc
2025-09-29 19:08:03.775636 | Pipeline: github-check
2025-09-29 19:08:03.775658 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-09-29 19:08:03.775677 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3332
2025-09-29 19:08:03.775699 | Log URL (when completed): https://logserver.rdoproject.org/f53/rdoproject.org/f53484b48fb141879ba1a3d52732b233/
2025-09-29 19:08:03.775720 | Event ID: 01c598b0-9d65-11f0-8b9c-9f217b126b73
2025-09-29 19:08:03.779429 | 
2025-09-29 19:08:03.779499 | LOOP [emit-job-header : Print node information]
2025-09-29 19:08:03.882384 | localhost | ok:
2025-09-29 19:08:03.882609 | localhost | # Node Information
2025-09-29 19:08:03.882642 | localhost | Inventory Hostname: controller
2025-09-29 19:08:03.882668 | localhost | Hostname: np0005460760
2025-09-29 19:08:03.882691 | localhost | Username: zuul
2025-09-29 19:08:03.882713 | localhost | Distro: CentOS 9
2025-09-29 19:08:03.882734 | localhost | Provider: ibm-bm4-nodepool
2025-09-29 19:08:03.882754 | localhost | Region: regionOne
2025-09-29 19:08:03.882773 | localhost | Label: centos-9-stream-crc-2-48-0-xxl-ibm
2025-09-29 19:08:03.882791 | localhost | Product Name: OpenStack Compute
2025-09-29 19:08:03.882809 | localhost | Interface IP: 192.168.26.241
2025-09-29 19:08:03.907688 | 
2025-09-29 19:08:03.907776 | PLAY [all]
2025-09-29 19:08:03.926145 | 
2025-09-29 19:08:03.926241 | TASK [Gather network facts]
2025-09-29 19:08:04.388383 | controller | ok
2025-09-29 19:08:04.405781 | 
2025-09-29 19:08:04.405881 | TASK [include_role : start-zuul-console]
2025-09-29 19:08:04.444816 | controller | ok
2025-09-29 19:08:04.464751 | 
2025-09-29 19:08:04.464856 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-09-29 19:08:04.902178 | controller | ok
2025-09-29 19:08:04.914981 | 
2025-09-29 19:08:04.915101 | TASK [include_role : add-build-sshkey]
2025-09-29 19:08:04.943706 | controller | ok
2025-09-29 19:08:04.966352 | 
2025-09-29 19:08:04.966456 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-09-29 19:08:05.191575 | controller -> localhost | ok
2025-09-29 19:08:05.198749 | 
2025-09-29 19:08:05.198833 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-09-29 19:08:05.218141 | controller | ok
2025-09-29 19:08:05.232922 | controller | included: /var/lib/zuul/builds/f53484b48fb141879ba1a3d52732b233/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-09-29 19:08:05.239330 | 
2025-09-29 19:08:05.239399 | TASK [add-build-sshkey : Create Temp SSH key]
2025-09-29 19:08:05.731966 | controller -> localhost | Generating public/private rsa key pair.
2025-09-29 19:08:05.732186 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f53484b48fb141879ba1a3d52732b233/work/f53484b48fb141879ba1a3d52732b233_id_rsa.
2025-09-29 19:08:05.732230 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f53484b48fb141879ba1a3d52732b233/work/f53484b48fb141879ba1a3d52732b233_id_rsa.pub.
2025-09-29 19:08:05.732257 | controller -> localhost | The key fingerprint is:
2025-09-29 19:08:05.732280 | controller -> localhost | SHA256:KYWTnF6FZXiN35oI1g20U7dYxyQzJA1SnfPybY41Ccs zuul-build-sshkey
2025-09-29 19:08:05.732302 | controller -> localhost | The key's randomart image is:
2025-09-29 19:08:05.732323 | controller -> localhost | +---[RSA 3072]----+
2025-09-29 19:08:05.732343 | controller -> localhost | |         =*+==B+o|
2025-09-29 19:08:05.732363 | controller -> localhost | |     . +oo++.==*.|
2025-09-29 19:08:05.732384 | controller -> localhost | |      * ooo+...o |
2025-09-29 19:08:05.732403 | controller -> localhost | |     . +o...o.o .|
2025-09-29 19:08:05.732422 | controller -> localhost | |      o.S. ..oooo|
2025-09-29 19:08:05.732442 | controller -> localhost | |       .  . oE o=|
2025-09-29 19:08:05.732461 | controller -> localhost | |               =.|
2025-09-29 19:08:05.732481 | controller -> localhost | |              . .|
2025-09-29 19:08:05.732501 | controller -> localhost | |                 |
2025-09-29 19:08:05.732520 | controller -> localhost | +----[SHA256]-----+
2025-09-29 19:08:05.732561 | controller -> localhost | ok: Runtime: 0:00:00.080218
2025-09-29 19:08:05.749276 | 
2025-09-29 19:08:05.749350 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-09-29 19:08:05.777976 | controller | ok
2025-09-29 19:08:05.800857 | controller | included: /var/lib/zuul/builds/f53484b48fb141879ba1a3d52732b233/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-09-29 19:08:05.809010 | 
2025-09-29 19:08:05.809093 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-09-29 19:08:05.832574 | controller | skipping: Conditional result was False
2025-09-29 19:08:05.838931 | 
2025-09-29 19:08:05.839003 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-09-29 19:08:06.390286 | controller | changed
2025-09-29 19:08:06.395676 | 
2025-09-29 19:08:06.395760 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-09-29 19:08:06.667516 | controller | ok
2025-09-29 19:08:06.672592 | 
2025-09-29 19:08:06.672691 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-09-29 19:08:07.819338 | controller | changed
2025-09-29 19:08:07.824791 | 
2025-09-29 19:08:07.824887 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-09-29 19:08:09.044537 | controller | changed
2025-09-29 19:08:09.053179 | 
2025-09-29 19:08:09.053260 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-09-29 19:08:09.077376 | controller | skipping: Conditional result was False
2025-09-29 19:08:09.084361 | 
2025-09-29 19:08:09.084426 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-09-29 19:08:09.486200 | controller -> localhost | changed
2025-09-29 19:08:09.497292 | 
2025-09-29 19:08:09.497418 | TASK [add-build-sshkey : Add back temp key]
2025-09-29 19:08:09.767609 | controller -> localhost | Identity added: /var/lib/zuul/builds/f53484b48fb141879ba1a3d52732b233/work/f53484b48fb141879ba1a3d52732b233_id_rsa (zuul-build-sshkey)
2025-09-29 19:08:09.767816 | controller -> localhost | ok: Runtime: 0:00:00.007629
2025-09-29 19:08:09.774326 | 
2025-09-29 19:08:09.774398 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-09-29 19:08:10.153868 | controller | ok
2025-09-29 19:08:10.159237 | 
2025-09-29 19:08:10.159308 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-09-29 19:08:10.183404 | controller | skipping: Conditional result was False
2025-09-29 19:08:10.194009 | 
2025-09-29 19:08:10.194094 | TASK [include_role : validate-host]
2025-09-29 19:08:10.212057 | controller | ok
2025-09-29 19:08:10.231546 | 
2025-09-29 19:08:10.231695 | TASK [validate-host : Define zuul_info_dir fact]
2025-09-29 19:08:10.254083 | controller | ok
2025-09-29 19:08:10.258893 | 
2025-09-29 19:08:10.258980 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-09-29 19:08:10.561118 | controller -> localhost | ok
2025-09-29 19:08:10.597051 | 
2025-09-29 19:08:10.597154 | TASK [validate-host : Collect information about the host]
2025-09-29 19:08:11.372436 | controller | ok
2025-09-29 19:08:11.389160 | 
2025-09-29 19:08:11.389253 | TASK [validate-host : Sanitize hostname]
2025-09-29 19:08:11.437617 | controller | ok
2025-09-29 19:08:11.442899 | 
2025-09-29 19:08:11.443002 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-09-29 19:08:11.866821 | controller -> localhost | changed
2025-09-29 19:08:11.872807 | 
2025-09-29 19:08:11.872879 | TASK [validate-host : Collect information about zuul worker]
2025-09-29 19:08:12.305622 | controller | ok
2025-09-29 19:08:12.311591 | 
2025-09-29 19:08:12.311681 | TASK [validate-host : Write out all zuul information for each host]
2025-09-29 19:08:12.799725 | controller -> localhost | changed
2025-09-29 19:08:12.809552 | 
2025-09-29 19:08:12.809628 | TASK [include_role : prepare-workspace-openshift]
2025-09-29 19:08:12.825615 | controller | skipping: Conditional result was False
2025-09-29 19:08:12.830725 | 
2025-09-29 19:08:12.830797 | TASK [include_role : remove-zuul-sshkey]
2025-09-29 19:08:12.844696 | controller | skipping: Conditional result was False
2025-09-29 19:08:12.849816 | 
2025-09-29 19:08:12.849891 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-09-29 19:08:13.116473 | controller | ok: "logs"
2025-09-29 19:08:13.116708 | controller | ok: All items complete
2025-09-29 19:08:13.116745 | 
2025-09-29 19:08:13.360247 | controller | ok: "artifacts"
2025-09-29 19:08:13.626145 | controller | ok: "docs"
2025-09-29 19:08:13.641442 | 
2025-09-29 19:08:13.641551 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-09-29 19:08:13.927499 | controller | changed: "logs"
2025-09-29 19:08:14.186084 | controller | changed: "artifacts"
2025-09-29 19:08:14.425467 | controller | changed: "docs"
2025-09-29 19:08:14.450974 | 
2025-09-29 19:08:14.451080 | PLAY RECAP
2025-09-29 19:08:14.451125 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-29 19:08:14.451157 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-29 19:08:14.451178 | 
2025-09-29 19:08:14.540269 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-29 19:08:14.540959 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-29 19:08:15.189996 | 
2025-09-29 19:08:15.190123 | PLAY [all]
2025-09-29 19:08:15.211716 | 
2025-09-29 19:08:15.211826 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-29 19:08:15.271816 | controller | ok
2025-09-29 19:08:15.277087 | 
2025-09-29 19:08:15.277189 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-29 19:08:15.692968 | controller | changed
2025-09-29 19:08:15.703095 | 
2025-09-29 19:08:15.703175 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-29 19:08:17.051512 | controller | changed
2025-09-29 19:08:17.061983 | 
2025-09-29 19:08:17.062085 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-09-29 19:08:17.539039 | controller | changed:
2025-09-29 19:08:17.539217 | controller | {
2025-09-29 19:08:17.539254 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-09-29 19:08:17.539286 | controller | }
2025-09-29 19:08:17.847233 | controller | changed:
2025-09-29 19:08:17.847317 | controller | {
2025-09-29 19:08:17.847348 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-09-29 19:08:17.847381 | controller | }
2025-09-29 19:08:18.150765 | controller | changed:
2025-09-29 19:08:18.150845 | controller | {
2025-09-29 19:08:18.150875 | 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:08:18.150898 | controller | }
2025-09-29 19:08:18.474651 | controller | changed:
2025-09-29 19:08:18.474733 | controller | {
2025-09-29 19:08:18.474762 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-09-29 19:08:18.474786 | controller | }
2025-09-29 19:08:18.788784 | controller | changed:
2025-09-29 19:08:18.788869 | controller | {
2025-09-29 19:08:18.788897 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-09-29 19:08:18.788957 | controller | }
2025-09-29 19:08:19.082933 | controller | changed:
2025-09-29 19:08:19.083021 | controller | {
2025-09-29 19:08:19.083061 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-09-29 19:08:19.083084 | controller | }
2025-09-29 19:08:19.376575 | controller | changed:
2025-09-29 19:08:19.376665 | controller | {
2025-09-29 19:08:19.376695 | 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:08:19.376717 | controller | }
2025-09-29 19:08:19.676904 | controller | changed:
2025-09-29 19:08:19.677081 | controller | {
2025-09-29 19:08:19.677113 | 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:08:19.677136 | controller | }
2025-09-29 19:08:19.984011 | controller | changed:
2025-09-29 19:08:19.984132 | controller | {
2025-09-29 19:08:19.984160 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-09-29 19:08:19.984181 | controller | }
2025-09-29 19:08:20.290636 | controller | changed:
2025-09-29 19:08:20.290732 | controller | {
2025-09-29 19:08:20.290761 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-09-29 19:08:20.290783 | controller | }
2025-09-29 19:08:20.601972 | controller | changed:
2025-09-29 19:08:20.602141 | controller | {
2025-09-29 19:08:20.602177 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-09-29 19:08:20.602201 | controller | }
2025-09-29 19:08:20.904660 | controller | changed:
2025-09-29 19:08:20.904765 | controller | {
2025-09-29 19:08:20.904795 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-09-29 19:08:20.904817 | controller | }
2025-09-29 19:08:21.218890 | controller | changed:
2025-09-29 19:08:21.219127 | controller | {
2025-09-29 19:08:21.219156 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-09-29 19:08:21.219179 | controller | }
2025-09-29 19:08:21.514151 | controller | changed:
2025-09-29 19:08:21.514249 | controller | {
2025-09-29 19:08:21.514277 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-09-29 19:08:21.514298 | controller | }
2025-09-29 19:08:21.828681 | controller | changed:
2025-09-29 19:08:21.828793 | controller | {
2025-09-29 19:08:21.828822 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-09-29 19:08:21.828843 | controller | }
2025-09-29 19:08:22.140523 | controller | changed:
2025-09-29 19:08:22.140677 | controller | {
2025-09-29 19:08:22.140714 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-09-29 19:08:22.140738 | controller | }
2025-09-29 19:08:22.457929 | controller | changed:
2025-09-29 19:08:22.458017 | controller | {
2025-09-29 19:08:22.458057 | 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:08:22.458080 | controller | }
2025-09-29 19:08:22.773658 | controller | changed:
2025-09-29 19:08:22.773741 | controller | {
2025-09-29 19:08:22.773769 | 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:08:22.773798 | controller | }
2025-09-29 19:08:23.105480 | controller | changed:
2025-09-29 19:08:23.105618 | controller | {
2025-09-29 19:08:23.105650 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-09-29 19:08:23.105672 | controller | }
2025-09-29 19:08:23.424351 | controller | changed:
2025-09-29 19:08:23.424448 | controller | {
2025-09-29 19:08:23.424475 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-09-29 19:08:23.424496 | controller | }
2025-09-29 19:08:23.734552 | controller | changed:
2025-09-29 19:08:23.734657 | controller | {
2025-09-29 19:08:23.734686 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-09-29 19:08:23.734708 | controller | }
2025-09-29 19:08:24.036902 | controller | changed:
2025-09-29 19:08:24.037133 | controller | {
2025-09-29 19:08:24.037162 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-09-29 19:08:24.037183 | controller | }
2025-09-29 19:08:24.335343 | controller | changed:
2025-09-29 19:08:24.335437 | controller | {
2025-09-29 19:08:24.335467 | 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:08:24.335489 | controller | }
2025-09-29 19:08:24.640362 | controller | changed:
2025-09-29 19:08:24.640457 | controller | {
2025-09-29 19:08:24.640484 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-09-29 19:08:24.640504 | controller | }
2025-09-29 19:08:24.952818 | controller | changed:
2025-09-29 19:08:24.952932 | controller | {
2025-09-29 19:08:24.952970 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-09-29 19:08:24.952992 | controller | }
2025-09-29 19:08:25.247388 | controller | changed:
2025-09-29 19:08:25.247471 | controller | {
2025-09-29 19:08:25.247499 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-09-29 19:08:25.247518 | controller | }
2025-09-29 19:08:25.265864 | 
2025-09-29 19:08:25.265978 | TASK [Set timezone to UTC]
2025-09-29 19:08:25.801463 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-09-29 19:08:25.806452 | 
2025-09-29 19:08:25.806519 | TASK [Create nodepool directory]
2025-09-29 19:08:26.089554 | controller | changed
2025-09-29 19:08:26.095041 | 
2025-09-29 19:08:26.095122 | TASK [Create nodepool sub_nodes file]
2025-09-29 19:08:27.076881 | controller | changed
2025-09-29 19:08:27.082068 | 
2025-09-29 19:08:27.082275 | TASK [Create nodepool sub_nodes_private file]
2025-09-29 19:08:28.076108 | controller | changed
2025-09-29 19:08:28.082844 | 
2025-09-29 19:08:28.082996 | LOOP [Populate nodepool sub_nodes file]
2025-09-29 19:08:28.114283 | 
2025-09-29 19:08:28.114436 | LOOP [Populate nodepool sub_nodes_private file]
2025-09-29 19:08:28.144580 | 
2025-09-29 19:08:28.144760 | TASK [Create nodepool primary file]
2025-09-29 19:08:28.168827 | controller | skipping: Conditional result was False
2025-09-29 19:08:28.175264 | 
2025-09-29 19:08:28.175438 | TASK [Create nodepool node_private for this node]
2025-09-29 19:08:29.223948 | controller | changed
2025-09-29 19:08:29.230177 | 
2025-09-29 19:08:29.230264 | LOOP [Copy ssh keys to nodepool directory]
2025-09-29 19:08:29.650576 | controller | ok: Item: id_rsa Runtime: 0:00:00.005317
2025-09-29 19:08:29.650812 | 
2025-09-29 19:08:29.896338 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.007242
2025-09-29 19:08:29.909700 | 
2025-09-29 19:08:29.909823 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-09-29 19:08:30.976410 | controller | changed
2025-09-29 19:08:30.982095 | 
2025-09-29 19:08:30.982307 | TASK [Validate sudoers config after edits]
2025-09-29 19:08:31.268480 | controller | /etc/sudoers: parsed OK
2025-09-29 19:08:31.268546 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-09-29 19:08:31.268555 | controller | /etc/sudoers.d/zuul: parsed OK
2025-09-29 19:08:31.268562 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-09-29 19:08:31.526286 | controller | ok: Runtime: 0:00:00.006351
2025-09-29 19:08:31.533641 | 
2025-09-29 19:08:31.533716 | TASK [Show the environment passed in to job shell scripts]
2025-09-29 19:08:31.830773 | controller | SHELL=/bin/bash
2025-09-29 19:08:31.830838 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-09-29 19:08:31.830856 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-09-29 19:08:31.830863 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/46/3346/eb5b53fb17db92a72d33d052a56c8edd0d10ea78^openstack-k8s-operators/ci-framework:main:refs/changes/32/3332/2b7f44a769d14bbef534eeafa4b4e5c8b67650d8
2025-09-29 19:08:31.830923 | controller | PWD=/home/zuul
2025-09-29 19:08:31.830934 | controller | ZUUL_PIPELINE=github-check
2025-09-29 19:08:31.830939 | controller | LOGNAME=zuul
2025-09-29 19:08:31.830945 | controller | XDG_SESSION_TYPE=tty
2025-09-29 19:08:31.830950 | controller | _=/usr/bin/env
2025-09-29 19:08:31.830955 | controller | MOTD_SHOWN=pam
2025-09-29 19:08:31.830961 | controller | HOME=/home/zuul
2025-09-29 19:08:31.830966 | controller | LANG=en_US.UTF-8
2025-09-29 19:08:31.830971 | controller | SSH_CONNECTION=192.168.26.12 54226 192.168.26.241 22
2025-09-29 19:08:31.830977 | 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:08:31.830997 | controller | ZUUL_CHANGE_IDS=3346,eb5b53fb17db92a72d33d052a56c8edd0d10ea78 3332,2b7f44a769d14bbef534eeafa4b4e5c8b67650d8
2025-09-29 19:08:31.831003 | controller | WORKSPACE=/home/zuul/workspace
2025-09-29 19:08:31.831008 | controller | XDG_SESSION_CLASS=user
2025-09-29 19:08:31.831014 | controller | SELINUX_ROLE_REQUESTED=
2025-09-29 19:08:31.831019 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-09-29 19:08:31.831024 | controller | USER=zuul
2025-09-29 19:08:31.831030 | controller | ZUUL_VOTING=True
2025-09-29 19:08:31.831035 | controller | BUILD_TIMEOUT=5400000
2025-09-29 19:08:31.831040 | controller | SELINUX_USE_CURRENT_RANGE=
2025-09-29 19:08:31.831046 | controller | SHLVL=1
2025-09-29 19:08:31.831051 | controller | ZUUL_PATCHSET=2b7f44a769d14bbef534eeafa4b4e5c8b67650d8
2025-09-29 19:08:31.831057 | controller | XDG_SESSION_ID=1
2025-09-29 19:08:31.831062 | controller | ZUUL_BRANCH=main
2025-09-29 19:08:31.831068 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-09-29 19:08:31.831073 | controller | SSH_CLIENT=192.168.26.12 54226 22
2025-09-29 19:08:31.831079 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-09-29 19:08:31.831084 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-09-29 19:08:31.831090 | controller | which_declare=declare -f
2025-09-29 19:08:31.831096 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-09-29 19:08:31.831101 | controller | SELINUX_LEVEL_REQUESTED=
2025-09-29 19:08:31.831106 | controller | ZUUL_CHANGE=3332
2025-09-29 19:08:31.831112 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-09-29 19:08:31.831117 | controller | ZUUL_UUID=f53484b48fb141879ba1a3d52732b233
2025-09-29 19:08:31.831122 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-09-29 19:08:31.831130 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-09-29 19:08:31.831135 | controller | }
2025-09-29 19:08:32.066639 | controller | ok: Runtime: 0:00:00.008732
2025-09-29 19:08:32.072149 | 
2025-09-29 19:08:32.072214 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-09-29 19:08:32.106368 | controller | skipping: Conditional result was False
2025-09-29 19:08:32.112335 | 
2025-09-29 19:08:32.112412 | TASK [Symlink /home/zuul-worker/workspace]
2025-09-29 19:08:32.675232 | controller | skipping: Conditional result was False
2025-09-29 19:08:32.681195 | 
2025-09-29 19:08:32.681291 | TASK [Ensure legacy workspace directory]
2025-09-29 19:08:32.943772 | controller | changed
2025-09-29 19:08:32.988579 | 
2025-09-29 19:08:32.988702 | PLAY RECAP
2025-09-29 19:08:32.988752 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-29 19:08:32.988775 | 
2025-09-29 19:08:33.078643 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-29 19:08:33.079352 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-29 19:08:33.650745 | 
2025-09-29 19:08:33.650832 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-09-29 19:08:33.672498 | 
2025-09-29 19:08:33.672586 | TASK [Create zuul-output directory]
2025-09-29 19:08:34.088348 | controller | changed
2025-09-29 19:08:34.094664 | 
2025-09-29 19:08:34.094755 | TASK [Slurp Zuul inventory test]
2025-09-29 19:08:34.412506 | controller -> localhost | ok
2025-09-29 19:08:34.418819 | 
2025-09-29 19:08:34.418888 | TASK [Save zuul inventory]
2025-09-29 19:08:35.700924 | controller | changed
2025-09-29 19:08:35.706369 | 
2025-09-29 19:08:35.706515 | TASK [Save zuul vars without the change_message]
2025-09-29 19:08:36.761354 | controller | changed
2025-09-29 19:08:36.780579 | 
2025-09-29 19:08:36.780647 | PLAY RECAP
2025-09-29 19:08:36.780693 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-29 19:08:36.780715 | 
2025-09-29 19:08:36.853879 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-29 19:08:36.854804 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-29 19:08:37.448937 | 
2025-09-29 19:08:37.449072 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-09-29 19:08:37.471308 | 
2025-09-29 19:08:37.471533 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-09-29 19:08:37.491324 | controller | ok
2025-09-29 19:08:37.510768 | 
2025-09-29 19:08:37.510957 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-29 19:08:37.546609 | controller | skipping: Conditional result was False
2025-09-29 19:08:37.553343 | 
2025-09-29 19:08:37.553453 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-29 19:08:37.961922 | controller | ok
2025-09-29 19:08:37.968650 | 
2025-09-29 19:08:37.968769 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-29 19:08:38.946362 | controller | ok
2025-09-29 19:08:38.959214 | 
2025-09-29 19:08:38.959506 | TASK [Prepare workspace]
2025-09-29 19:08:38.981172 | controller | ok
2025-09-29 19:08:38.999160 | 
2025-09-29 19:08:38.999272 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-09-29 19:08:39.367148 | controller | ok
2025-09-29 19:08:39.373081 | 
2025-09-29 19:08:39.373167 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-09-29 19:08:41.400250 | controller | Output suppressed because no_log was given
2025-09-29 19:08:41.409535 | 
2025-09-29 19:08:41.409603 | LOOP [Create zuul-output directory]
2025-09-29 19:08:41.670697 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-09-29 19:08:41.911122 | controller | ok: "/home/zuul/zuul-output/logs"
2025-09-29 19:08:41.918402 | 
2025-09-29 19:08:41.918483 | TASK [Install required packages]
2025-09-29 19:09:04.400083 | controller | ok: Nothing to do
2025-09-29 19:09:04.405501 | 
2025-09-29 19:09:04.405587 | TASK [Install venv]
2025-09-29 19:10:04.015016 | controller | changed
2025-09-29 19:10:04.042082 | 
2025-09-29 19:10:04.042155 | PLAY RECAP
2025-09-29 19:10:04.042200 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-29 19:10:04.042223 | 
2025-09-29 19:10:04.128775 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-29 19:10:04.129626 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-29 19:10:04.668905 | 
2025-09-29 19:10:04.669072 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-09-29 19:10:04.689953 | 
2025-09-29 19:10:04.690033 | TASK [Gather required facts]
2025-09-29 19:10:05.276752 | controller | ok
2025-09-29 19:10:05.295533 | 
2025-09-29 19:10:05.295668 | TASK [Load environment var if instructed to]
2025-09-29 19:10:05.319585 | controller | skipping: Conditional result was False
2025-09-29 19:10:05.325864 | 
2025-09-29 19:10:05.325979 | TASK [Run molecule]
2025-09-29 19:10:06.376546 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-09-29 19:10:06.731115 | controller | INFO     Performing prerun with role_name_check=0...
2025-09-29 19:10:24.061619 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:24.061744 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:24.062223 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:24.062771 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:24.063205 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+24673bce was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0+24673bce (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:24.063672 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:24.064127 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:24.064555 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:24.064998 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:24.065453 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:24.065897 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:24.066326 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:24.066773 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:24.067212 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:24.067657 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:24.078382 | controller | INFO     Running add_crc_creds > prepare
2025-09-29 19:10:24.825402 | controller |
2025-09-29 19:10:24.825530 | controller | PLAY [Prepare] *****************************************************************
2025-09-29 19:10:24.825625 | controller |
2025-09-29 19:10:24.825727 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 19:10:24.825829 | controller | Monday 29 September 2025  19:10:24 +0000 (0:00:00.015)       0:00:00.015 ******
2025-09-29 19:10:25.717989 | controller | ok: [instance]
2025-09-29 19:10:25.718048 | controller |
2025-09-29 19:10:25.718204 | controller | TASK [Setup libvirt] ***********************************************************
2025-09-29 19:10:25.718329 | controller | Monday 29 September 2025  19:10:25 +0000 (0:00:00.893)       0:00:00.908 ******
2025-09-29 19:10:25.742995 | controller | skipping: [instance]
2025-09-29 19:10:25.743077 | controller |
2025-09-29 19:10:25.743221 | controller | TASK [Setup CRC] ***************************************************************
2025-09-29 19:10:25.743329 | controller | Monday 29 September 2025  19:10:25 +0000 (0:00:00.025)       0:00:00.933 ******
2025-09-29 19:10:25.767308 | controller | skipping: [instance]
2025-09-29 19:10:25.767433 | controller |
2025-09-29 19:10:25.767557 | controller | TASK [Start RHOL/CRC] **********************************************************
2025-09-29 19:10:25.767681 | controller | Monday 29 September 2025  19:10:25 +0000 (0:00:00.024)       0:00:00.957 ******
2025-09-29 19:10:26.223604 | controller | changed: [instance]
2025-09-29 19:10:26.223661 | controller |
2025-09-29 19:10:26.223822 | controller | TASK [Inject our own NetworkManager configuration] *****************************
2025-09-29 19:10:26.223946 | controller | Monday 29 September 2025  19:10:26 +0000 (0:00:00.455)       0:00:01.413 ******
2025-09-29 19:10:26.860594 | controller | changed: [instance]
2025-09-29 19:10:26.860883 | controller |
2025-09-29 19:10:26.860971 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-09-29 19:10:26.861078 | controller | Monday 29 September 2025  19:10:26 +0000 (0:00:00.635)       0:00:02.049 ******
2025-09-29 19:10:26.882937 | controller | skipping: [instance]
2025-09-29 19:10:26.883026 | controller |
2025-09-29 19:10:26.883142 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-09-29 19:10:26.883247 | controller | Monday 29 September 2025  19:10:26 +0000 (0:00:00.023)       0:00:02.073 ******
2025-09-29 19:10:26.931902 | 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:10:26.931958 | controller |
2025-09-29 19:10:26.932072 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-09-29 19:10:26.932195 | controller | Monday 29 September 2025  19:10:26 +0000 (0:00:00.048)       0:00:02.122 ******
2025-09-29 19:10:27.140003 | controller | ok: [instance]
2025-09-29 19:10:27.140050 | controller |
2025-09-29 19:10:27.140174 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-09-29 19:10:27.140272 | controller | Monday 29 September 2025  19:10:27 +0000 (0:00:00.208)       0:00:02.330 ******
2025-09-29 19:10:27.508401 | controller | ok: [instance]
2025-09-29 19:10:27.508506 | controller |
2025-09-29 19:10:27.508520 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-09-29 19:10:27.508654 | controller | Monday 29 September 2025  19:10:27 +0000 (0:00:00.368)       0:00:02.698 ******
2025-09-29 19:10:28.302940 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-09-29 19:10:28.303025 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-09-29 19:10:28.303134 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-09-29 19:10:28.303240 | controller |
2025-09-29 19:10:28.303433 | controller | TASK [Deploy repo-setup] *******************************************************
2025-09-29 19:10:28.303595 | controller | Monday 29 September 2025  19:10:28 +0000 (0:00:00.794)       0:00:03.493 ******
2025-09-29 19:10:28.392239 | controller |
2025-09-29 19:10:28.392306 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-09-29 19:10:28.392490 | controller | Monday 29 September 2025  19:10:28 +0000 (0:00:00.089)       0:00:03.582 ******
2025-09-29 19:10:29.066632 | controller | changed: [instance] => (item=tmp)
2025-09-29 19:10:29.066726 | controller | changed: [instance] => (item=artifacts/repositories)
2025-09-29 19:10:29.066740 | controller | changed: [instance] => (item=venv/repo_setup)
2025-09-29 19:10:29.066750 | controller |
2025-09-29 19:10:29.066792 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-09-29 19:10:29.066804 | controller | Monday 29 September 2025  19:10:29 +0000 (0:00:00.673)       0:00:04.256 ******
2025-09-29 19:10:30.015687 | controller | ok: [instance]
2025-09-29 19:10:30.015743 | controller |
2025-09-29 19:10:30.015805 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-09-29 19:10:30.015926 | controller | Monday 29 September 2025  19:10:30 +0000 (0:00:00.949)       0:00:05.205 ******
2025-09-29 19:10:31.268872 | controller | changed: [instance]
2025-09-29 19:10:40.364097 | controller |
2025-09-29 19:10:40.364139 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-09-29 19:10:40.364148 | controller | Monday 29 September 2025  19:10:31 +0000 (0:00:01.252)       0:00:06.458 ******
2025-09-29 19:10:40.364163 | controller | changed: [instance]
2025-09-29 19:10:41.161202 | controller |
2025-09-29 19:10:41.161286 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-09-29 19:10:41.161295 | controller | Monday 29 September 2025  19:10:40 +0000 (0:00:09.093)       0:00:15.552 ******
2025-09-29 19:10:41.161305 | controller | changed: [instance]
2025-09-29 19:10:41.186693 | controller |
2025-09-29 19:10:41.186741 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-09-29 19:10:41.186752 | controller | Monday 29 September 2025  19:10:41 +0000 (0:00:00.796)       0:00:16.348 ******
2025-09-29 19:10:41.186762 | controller | skipping: [instance]
2025-09-29 19:10:42.192869 | controller |
2025-09-29 19:10:42.192920 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-09-29 19:10:42.192928 | controller | Monday 29 September 2025  19:10:41 +0000 (0:00:00.027)       0:00:16.376 ******
2025-09-29 19:10:42.192938 | controller | changed: [instance]
2025-09-29 19:10:42.233537 | controller |
2025-09-29 19:10:42.233591 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-09-29 19:10:42.233599 | controller | Monday 29 September 2025  19:10:42 +0000 (0:00:01.005)       0:00:17.382 ******
2025-09-29 19:10:42.233612 | controller | skipping: [instance]
2025-09-29 19:10:42.233651 | controller |
2025-09-29 19:10:42.233659 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-09-29 19:10:42.233682 | controller | Monday 29 September 2025  19:10:42 +0000 (0:00:00.041)       0:00:17.424 ******
2025-09-29 19:10:42.277465 | controller | skipping: [instance]
2025-09-29 19:10:42.277524 | controller |
2025-09-29 19:10:42.277536 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-09-29 19:10:42.277672 | controller | Monday 29 September 2025  19:10:42 +0000 (0:00:00.043)       0:00:17.468 ******
2025-09-29 19:10:42.315845 | controller | skipping: [instance]
2025-09-29 19:10:42.315920 | controller |
2025-09-29 19:10:42.315930 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-09-29 19:10:42.315940 | controller | Monday 29 September 2025  19:10:42 +0000 (0:00:00.038)       0:00:17.506 ******
2025-09-29 19:10:42.925870 | controller | changed: [instance]
2025-09-29 19:10:43.347497 | controller |
2025-09-29 19:10:43.347551 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-09-29 19:10:43.347559 | controller | Monday 29 September 2025  19:10:42 +0000 (0:00:00.609)       0:00:18.115 ******
2025-09-29 19:10:43.347574 | controller | changed: [instance]
2025-09-29 19:10:43.385106 | controller |
2025-09-29 19:10:43.385149 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-09-29 19:10:43.385158 | controller | Monday 29 September 2025  19:10:43 +0000 (0:00:00.421)       0:00:18.536 ******
2025-09-29 19:10:43.385168 | controller | skipping: [instance]
2025-09-29 19:10:43.423834 | controller |
2025-09-29 19:10:43.423868 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-09-29 19:10:43.423876 | controller | Monday 29 September 2025  19:10:43 +0000 (0:00:00.037)       0:00:18.574 ******
2025-09-29 19:10:43.423896 | controller | skipping: [instance]
2025-09-29 19:10:43.458925 | controller |
2025-09-29 19:10:43.458967 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-09-29 19:10:43.458976 | controller | Monday 29 September 2025  19:10:43 +0000 (0:00:00.038)       0:00:18.613 ******
2025-09-29 19:10:43.458990 | controller | skipping: [instance]
2025-09-29 19:10:43.512293 | controller |
2025-09-29 19:10:43.512360 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-09-29 19:10:43.512370 | controller | Monday 29 September 2025  19:10:43 +0000 (0:00:00.035)       0:00:18.648 ******
2025-09-29 19:10:43.512384 | controller | ok: [instance]
2025-09-29 19:10:43.512605 | controller |
2025-09-29 19:10:43.512763 | controller | TASK [repo_setup : Create download directory] **********************************
2025-09-29 19:10:43.512910 | controller | Monday 29 September 2025  19:10:43 +0000 (0:00:00.053)       0:00:18.702 ******
2025-09-29 19:10:43.544267 | controller | skipping: [instance]
2025-09-29 19:10:43.544525 | controller |
2025-09-29 19:10:43.544719 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-09-29 19:10:43.544887 | controller | Monday 29 September 2025  19:10:43 +0000 (0:00:00.032)       0:00:18.734 ******
2025-09-29 19:10:43.576041 | controller | skipping: [instance]
2025-09-29 19:10:43.576326 | controller |
2025-09-29 19:10:43.576576 | controller | TASK [Download the RPM] ********************************************************
2025-09-29 19:10:43.576781 | controller | Monday 29 September 2025  19:10:43 +0000 (0:00:00.031)       0:00:18.766 ******
2025-09-29 19:10:43.608761 | controller | skipping: [instance]
2025-09-29 19:10:43.609003 | controller |
2025-09-29 19:10:43.609217 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-09-29 19:10:43.609416 | controller | Monday 29 September 2025  19:10:43 +0000 (0:00:00.033)       0:00:18.799 ******
2025-09-29 19:10:43.643495 | controller | skipping: [instance]
2025-09-29 19:10:43.643730 | controller |
2025-09-29 19:10:43.643919 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-09-29 19:10:43.644110 | controller | Monday 29 September 2025  19:10:43 +0000 (0:00:00.034)       0:00:18.833 ******
2025-09-29 19:10:43.675305 | controller | skipping: [instance]
2025-09-29 19:10:43.675588 | controller |
2025-09-29 19:10:43.675778 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-09-29 19:10:43.675956 | controller | Monday 29 September 2025  19:10:43 +0000 (0:00:00.031)       0:00:18.865 ******
2025-09-29 19:10:43.709766 | controller | skipping: [instance]
2025-09-29 19:10:43.710016 | controller |
2025-09-29 19:10:43.710210 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-09-29 19:10:43.710443 | controller | Monday 29 September 2025  19:10:43 +0000 (0:00:00.034)       0:00:18.900 ******
2025-09-29 19:10:43.741817 | controller | skipping: [instance]
2025-09-29 19:10:43.742074 | controller |
2025-09-29 19:10:43.742300 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-09-29 19:10:43.742541 | controller | Monday 29 September 2025  19:10:43 +0000 (0:00:00.032)       0:00:18.932 ******
2025-09-29 19:10:43.962505 | controller | ok: [instance]
2025-09-29 19:10:44.239337 | controller |
2025-09-29 19:10:44.239387 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-09-29 19:10:44.239404 | controller | Monday 29 September 2025  19:10:43 +0000 (0:00:00.220)       0:00:19.152 ******
2025-09-29 19:10:44.239416 | controller | changed: [instance]
2025-09-29 19:10:44.515712 | controller |
2025-09-29 19:10:44.515756 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-09-29 19:10:44.515765 | controller | Monday 29 September 2025  19:10:44 +0000 (0:00:00.276)       0:00:19.429 ******
2025-09-29 19:10:44.515776 | controller | changed: [instance]
2025-09-29 19:10:44.542211 | controller |
2025-09-29 19:10:44.542235 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-09-29 19:10:44.542251 | controller | Monday 29 September 2025  19:10:44 +0000 (0:00:00.276)       0:00:19.705 ******
2025-09-29 19:10:44.542267 | controller | skipping: [instance]
2025-09-29 19:10:44.571160 | controller |
2025-09-29 19:10:44.571205 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-09-29 19:10:44.571223 | controller | Monday 29 September 2025  19:10:44 +0000 (0:00:00.026)       0:00:19.732 ******
2025-09-29 19:10:44.571237 | controller | skipping: [instance]
2025-09-29 19:10:44.604336 | controller |
2025-09-29 19:10:44.604378 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-09-29 19:10:44.604386 | controller | Monday 29 September 2025  19:10:44 +0000 (0:00:00.028)       0:00:19.761 ******
2025-09-29 19:10:44.604397 | controller | skipping: [instance]
2025-09-29 19:10:44.633620 | controller |
2025-09-29 19:10:44.633654 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-09-29 19:10:44.633662 | controller | Monday 29 September 2025  19:10:44 +0000 (0:00:00.032)       0:00:19.793 ******
2025-09-29 19:10:44.633672 | controller | skipping: [instance]
2025-09-29 19:10:44.664826 | controller |
2025-09-29 19:10:44.664850 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-09-29 19:10:44.664863 | controller | Monday 29 September 2025  19:10:44 +0000 (0:00:00.030)       0:00:19.823 ******
2025-09-29 19:10:44.664873 | controller | skipping: [instance]
2025-09-29 19:10:44.665039 | controller |
2025-09-29 19:10:44.665237 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-09-29 19:10:44.665462 | controller | Monday 29 September 2025  19:10:44 +0000 (0:00:00.031)       0:00:19.855 ******
2025-09-29 19:10:44.695884 | controller | skipping: [instance]
2025-09-29 19:10:44.696105 | controller |
2025-09-29 19:10:44.696337 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-09-29 19:10:44.696538 | controller | Monday 29 September 2025  19:10:44 +0000 (0:00:00.030)       0:00:19.886 ******
2025-09-29 19:10:44.719956 | controller | skipping: [instance]
2025-09-29 19:10:44.720207 | controller |
2025-09-29 19:10:44.720430 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-09-29 19:10:44.720616 | controller | Monday 29 September 2025  19:10:44 +0000 (0:00:00.024)       0:00:19.910 ******
2025-09-29 19:10:44.757496 | controller | skipping: [instance]
2025-09-29 19:10:44.757716 | controller |
2025-09-29 19:10:44.757912 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-09-29 19:10:44.758098 | controller | Monday 29 September 2025  19:10:44 +0000 (0:00:00.037)       0:00:19.947 ******
2025-09-29 19:10:44.779929 | controller | skipping: [instance]
2025-09-29 19:10:44.780129 | controller |
2025-09-29 19:10:44.780340 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-09-29 19:10:44.780538 | controller | Monday 29 September 2025  19:10:44 +0000 (0:00:00.022)       0:00:19.970 ******
2025-09-29 19:10:44.808687 | controller | skipping: [instance]
2025-09-29 19:10:44.808900 | controller |
2025-09-29 19:10:44.809085 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-09-29 19:10:44.809270 | controller | Monday 29 September 2025  19:10:44 +0000 (0:00:00.028)       0:00:19.999 ******
2025-09-29 19:10:44.846582 | controller | skipping: [instance]
2025-09-29 19:10:44.846785 | controller |
2025-09-29 19:10:44.846966 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-09-29 19:10:44.847203 | controller | Monday 29 September 2025  19:10:44 +0000 (0:00:00.037)       0:00:20.037 ******
2025-09-29 19:10:44.884455 | controller | skipping: [instance]
2025-09-29 19:10:44.884665 | controller |
2025-09-29 19:10:44.884845 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-09-29 19:10:44.885021 | controller | Monday 29 September 2025  19:10:44 +0000 (0:00:00.037)       0:00:20.074 ******
2025-09-29 19:10:44.922383 | controller | skipping: [instance]
2025-09-29 19:10:44.922577 | controller |
2025-09-29 19:10:44.922755 | controller | TASK [test_deps : Install additional packages] *********************************
2025-09-29 19:10:44.922935 | controller | Monday 29 September 2025  19:10:44 +0000 (0:00:00.038)       0:00:20.112 ******
2025-09-29 19:10:44.960663 | controller | skipping: [instance]
2025-09-29 19:10:44.960903 | controller |
2025-09-29 19:10:44.961146 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-09-29 19:10:44.961394 | controller | Monday 29 September 2025  19:10:44 +0000 (0:00:00.038)       0:00:20.151 ******
2025-09-29 19:11:35.505792 | controller | ok: [instance]
2025-09-29 19:11:37.032083 | controller |
2025-09-29 19:11:37.032162 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-09-29 19:11:37.032264 | controller | Monday 29 September 2025  19:11:35 +0000 (0:00:50.542)       0:01:10.693 ******
2025-09-29 19:11:37.032282 | controller | ok: [instance]
2025-09-29 19:11:37.075614 | controller |
2025-09-29 19:11:37.075643 | controller | TASK [test_deps : Install extra packages] **************************************
2025-09-29 19:11:37.075652 | controller | Monday 29 September 2025  19:11:37 +0000 (0:00:01.527)       0:01:12.221 ******
2025-09-29 19:11:37.075662 | controller | skipping: [instance]
2025-09-29 19:11:37.131477 | controller |
2025-09-29 19:11:37.131535 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-09-29 19:11:37.131544 | controller | Monday 29 September 2025  19:11:37 +0000 (0:00:00.044)       0:01:12.265 ******
2025-09-29 19:11:37.131561 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-09-29 19:11:37.158094 | controller |
2025-09-29 19:11:37.158135 | controller | TASK [ci_setup : List packages to install] *************************************
2025-09-29 19:11:37.158144 | controller | Monday 29 September 2025  19:11:37 +0000 (0:00:00.055)       0:01:12.321 ******
2025-09-29 19:11:37.158156 | controller | ok: [instance] =>
2025-09-29 19:11:37.158195 | controller |   cifmw_ci_setup_packages:
2025-09-29 19:11:37.158203 | controller |   - bash-completion
2025-09-29 19:11:37.158209 | controller |   - ca-certificates
2025-09-29 19:11:37.158216 | controller |   - git-core
2025-09-29 19:11:37.158241 | controller |   - make
2025-09-29 19:11:37.158648 | controller |   - tar
2025-09-29 19:11:37.158718 | controller |   - tmux
2025-09-29 19:11:37.158743 | controller |   - python3-pip
2025-09-29 19:11:37.158757 | controller |
2025-09-29 19:11:37.158935 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-09-29 19:11:37.158982 | controller | Monday 29 September 2025  19:11:37 +0000 (0:00:00.026)       0:01:12.348 ******
2025-09-29 19:11:46.737555 | controller | changed: [instance]
2025-09-29 19:11:47.332260 | controller |
2025-09-29 19:11:47.332302 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-09-29 19:11:47.332326 | controller | Monday 29 September 2025  19:11:46 +0000 (0:00:09.578)       0:01:21.926 ******
2025-09-29 19:11:47.332342 | controller | ok: [instance]
2025-09-29 19:11:47.584373 | controller |
2025-09-29 19:11:47.584423 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-09-29 19:11:47.584432 | controller | Monday 29 September 2025  19:11:47 +0000 (0:00:00.596)       0:01:22.522 ******
2025-09-29 19:11:47.584449 | controller | changed: [instance]
2025-09-29 19:11:54.119197 | controller |
2025-09-29 19:11:54.119298 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-09-29 19:11:54.119333 | controller | Monday 29 September 2025  19:11:47 +0000 (0:00:00.251)       0:01:22.773 ******
2025-09-29 19:11:54.119358 | controller | changed: [instance]
2025-09-29 19:11:54.173605 | controller |
2025-09-29 19:11:54.173666 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-09-29 19:11:54.173674 | controller | Monday 29 September 2025  19:11:54 +0000 (0:00:06.534)       0:01:29.308 ******
2025-09-29 19:11:54.173687 | controller | ok: [instance]
2025-09-29 19:11:54.513731 | controller |
2025-09-29 19:11:54.513793 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-09-29 19:11:54.513803 | controller | Monday 29 September 2025  19:11:54 +0000 (0:00:00.055)       0:01:29.363 ******
2025-09-29 19:11:54.513816 | controller | changed: [instance]
2025-09-29 19:11:54.513838 | controller |
2025-09-29 19:11:54.513960 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-09-29 19:11:54.513970 | controller | Monday 29 September 2025  19:11:54 +0000 (0:00:00.339)       0:01:29.703 ******
2025-09-29 19:11:54.865036 | controller | changed: [instance]
2025-09-29 19:11:54.865104 | controller |
2025-09-29 19:11:54.865772 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-09-29 19:11:54.888431 | controller | Monday 29 September 2025  19:11:54 +0000 (0:00:00.351)       0:01:30.055 ******
2025-09-29 19:11:54.888510 | controller | skipping: [instance]
2025-09-29 19:11:54.888547 | controller |
2025-09-29 19:11:54.888646 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-09-29 19:11:54.888761 | controller | Monday 29 September 2025  19:11:54 +0000 (0:00:00.023)       0:01:30.078 ******
2025-09-29 19:11:54.912229 | controller | skipping: [instance]
2025-09-29 19:11:54.912306 | controller |
2025-09-29 19:11:54.912403 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-09-29 19:11:54.912519 | controller | Monday 29 September 2025  19:11:54 +0000 (0:00:00.023)       0:01:30.102 ******
2025-09-29 19:11:54.935797 | controller | skipping: [instance]
2025-09-29 19:11:54.935880 | controller |
2025-09-29 19:11:54.936013 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-09-29 19:11:54.936112 | controller | Monday 29 September 2025  19:11:54 +0000 (0:00:00.023)       0:01:30.125 ******
2025-09-29 19:11:54.959434 | controller | skipping: [instance]
2025-09-29 19:11:54.959544 | controller |
2025-09-29 19:11:54.959666 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-09-29 19:11:54.959765 | controller | Monday 29 September 2025  19:11:54 +0000 (0:00:00.023)       0:01:30.149 ******
2025-09-29 19:11:54.982636 | controller | skipping: [instance]
2025-09-29 19:11:54.982709 | controller |
2025-09-29 19:11:54.982720 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-09-29 19:11:55.013953 | controller | Monday 29 September 2025  19:11:54 +0000 (0:00:00.023)       0:01:30.172 ******
2025-09-29 19:11:55.013988 | controller | skipping: [instance]
2025-09-29 19:11:55.014047 | controller |
2025-09-29 19:11:55.014181 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-09-29 19:11:55.014291 | controller | Monday 29 September 2025  19:11:55 +0000 (0:00:00.031)       0:01:30.204 ******
2025-09-29 19:11:56.248600 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-09-29 19:11:56.248662 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-09-29 19:11:56.248742 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-09-29 19:11:56.248852 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-09-29 19:11:56.248957 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-09-29 19:11:56.249055 | controller |
2025-09-29 19:11:56.249175 | controller | TASK [Check for CRC startup] ***************************************************
2025-09-29 19:11:56.249333 | controller | Monday 29 September 2025  19:11:56 +0000 (0:00:01.234)       0:01:31.438 ******
2025-09-29 19:13:37.065212 | controller | FAILED - RETRYING: [instance]: Check for CRC startup (120 retries left).
2025-09-29 19:13:37.065269 | controller | FAILED - RETRYING: [instance]: Check for CRC startup (119 retries left).
2025-09-29 19:13:37.065281 | controller | changed: [instance]
2025-09-29 19:13:37.065289 | controller |
2025-09-29 19:13:37.065427 | controller | RUNNING HANDLER [Restart NetworkManager] ***************************************
2025-09-29 19:13:37.065509 | controller | Monday 29 September 2025  19:13:37 +0000 (0:01:40.816)       0:03:12.254 ******
2025-09-29 19:13:37.821779 | controller | changed: [instance]
2025-09-29 19:13:37.822244 | controller |
2025-09-29 19:13:37.822378 | controller | PLAY RECAP *********************************************************************
2025-09-29 19:13:37.822395 | controller | instance                   : ok=33   changed=19   unreachable=0    failed=0    skipped=38   rescued=0    ignored=0
2025-09-29 19:13:37.822402 | controller |
2025-09-29 19:13:37.822408 | controller | Monday 29 September 2025  19:13:37 +0000 (0:00:00.757)       0:03:13.012 ******
2025-09-29 19:13:37.822417 | controller | ===============================================================================
2025-09-29 19:13:37.822502 | controller | Check for CRC startup ------------------------------------------------- 100.82s
2025-09-29 19:13:37.822614 | controller | test_deps : Install selinux python libs -------------------------------- 50.54s
2025-09-29 19:13:37.822719 | controller | ci_setup : Install needed packages -------------------------------------- 9.58s
2025-09-29 19:13:37.822825 | controller | repo_setup : Initialize python venv and install requirements ------------ 9.09s
2025-09-29 19:13:37.822912 | controller | ci_setup : Install openshift client ------------------------------------- 6.53s
2025-09-29 19:13:37.823004 | controller | test_deps : Install python yaml libs ------------------------------------ 1.53s
2025-09-29 19:13:37.823096 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.25s
2025-09-29 19:13:37.823202 | controller | ci_setup : Manage directories ------------------------------------------- 1.23s
2025-09-29 19:13:37.823301 | controller | repo_setup : Run repo-setup --------------------------------------------- 1.01s
2025-09-29 19:13:37.823430 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.95s
2025-09-29 19:13:37.823520 | controller | Gathering Facts --------------------------------------------------------- 0.89s
2025-09-29 19:13:37.823634 | controller | repo_setup : Install repo-setup package --------------------------------- 0.80s
2025-09-29 19:13:37.823739 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.79s
2025-09-29 19:13:37.823829 | controller | Restart NetworkManager -------------------------------------------------- 0.76s
2025-09-29 19:13:37.823921 | controller | repo_setup : Ensure directories are present ----------------------------- 0.67s
2025-09-29 19:13:37.824012 | controller | Inject our own NetworkManager configuration ----------------------------- 0.64s
2025-09-29 19:13:37.824102 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.61s
2025-09-29 19:13:37.824204 | controller | ci_setup : Gather version of openshift client --------------------------- 0.60s
2025-09-29 19:13:37.824300 | controller | Start RHOL/CRC ---------------------------------------------------------- 0.46s
2025-09-29 19:13:37.824417 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.42s
2025-09-29 19:13:37.894580 | controller | INFO     Running add_crc_creds > converge
2025-09-29 19:13:38.370021 | controller |
2025-09-29 19:13:38.370232 | controller | PLAY [Converge add_crc_creds] **************************************************
2025-09-29 19:13:38.370325 | controller |
2025-09-29 19:13:38.370345 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 19:13:38.370437 | controller | Monday 29 September 2025  19:13:38 +0000 (0:00:00.038)       0:00:00.038 ******
2025-09-29 19:13:39.338075 | controller | ok: [instance]
2025-09-29 19:13:39.338147 | controller |
2025-09-29 19:13:39.338266 | controller | TASK [Verify crc creds playbook] ***********************************************
2025-09-29 19:13:39.338397 | controller | Monday 29 September 2025  19:13:39 +0000 (0:00:00.968)       0:00:01.006 ******
2025-09-29 19:13:39.368652 | controller |
2025-09-29 19:13:39.368741 | controller | TASK [rhol_crc : Make sure kubeconfig file exists] *****************************
2025-09-29 19:13:39.368840 | controller | Monday 29 September 2025  19:13:39 +0000 (0:00:00.030)       0:00:01.037 ******
2025-09-29 19:13:39.692799 | controller | ok: [instance]
2025-09-29 19:13:39.693062 | controller |
2025-09-29 19:13:39.724603 | controller | TASK [rhol_crc : Warn the user that the kubeconfig files doesn't exist] ********
2025-09-29 19:13:39.724654 | controller | Monday 29 September 2025  19:13:39 +0000 (0:00:00.323)       0:00:01.361 ******
2025-09-29 19:13:39.724670 | controller | skipping: [instance]
2025-09-29 19:13:39.724714 | controller |
2025-09-29 19:13:39.724816 | controller | TASK [rhol_crc : Add crc creds in the user bashrc file] ************************
2025-09-29 19:13:39.724929 | controller | Monday 29 September 2025  19:13:39 +0000 (0:00:00.031)       0:00:01.393 ******
2025-09-29 19:13:40.070161 | controller | changed: [instance]
2025-09-29 19:13:40.070702 | controller |
2025-09-29 19:13:40.754780 | controller | TASK [rhol_crc : Source bashrc and confirm crc login] **************************
2025-09-29 19:13:40.754821 | controller | Monday 29 September 2025  19:13:40 +0000 (0:00:00.340)       0:00:01.733 ******
2025-09-29 19:13:40.754834 | controller | ok: [instance]
2025-09-29 19:13:40.803343 | controller |
2025-09-29 19:13:40.803370 | controller | TASK [rhol_crc : Check bashrc results] *****************************************
2025-09-29 19:13:40.803377 | controller | Monday 29 September 2025  19:13:40 +0000 (0:00:00.689)       0:00:02.423 ******
2025-09-29 19:13:40.803391 | controller | ok: [instance] =>
2025-09-29 19:13:40.803489 | controller |   bashrc_result.stdout: |-
2025-09-29 19:13:40.803627 | controller |     /usr/local/bin/crc
2025-09-29 19:13:40.803785 | controller |     system:admin
2025-09-29 19:13:40.803913 | controller |
2025-09-29 19:13:40.804057 | controller | PLAY RECAP *********************************************************************
2025-09-29 19:13:40.804186 | controller | instance                   : ok=5    changed=1    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2025-09-29 19:13:40.804307 | controller |
2025-09-29 19:13:40.804474 | controller | Monday 29 September 2025  19:13:40 +0000 (0:00:00.049)       0:00:02.472 ******
2025-09-29 19:13:40.804593 | controller | ===============================================================================
2025-09-29 19:13:40.804739 | controller | Gathering Facts --------------------------------------------------------- 0.97s
2025-09-29 19:13:40.804862 | controller | rhol_crc : Source bashrc and confirm crc login -------------------------- 0.69s
2025-09-29 19:13:40.804997 | controller | rhol_crc : Add crc creds in the user bashrc file ------------------------ 0.34s
2025-09-29 19:13:40.805121 | controller | rhol_crc : Make sure kubeconfig file exists ----------------------------- 0.32s
2025-09-29 19:13:40.805249 | controller | rhol_crc : Check bashrc results ----------------------------------------- 0.05s
2025-09-29 19:13:40.805413 | controller | rhol_crc : Warn the user that the kubeconfig files doesn't exist -------- 0.03s
2025-09-29 19:13:40.805536 | controller | Verify crc creds playbook ----------------------------------------------- 0.03s
2025-09-29 19:13:40.893630 | controller | INFO     Running add_crc_creds > cleanup
2025-09-29 19:13:41.434205 | controller |
2025-09-29 19:13:41.434286 | controller | PLAY [Prepare] *****************************************************************
2025-09-29 19:13:41.434355 | controller |
2025-09-29 19:13:41.434488 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 19:13:41.434607 | controller | Monday 29 September 2025  19:13:41 +0000 (0:00:00.018)       0:00:00.018 ******
2025-09-29 19:13:42.385769 | controller | ok: [instance]
2025-09-29 19:13:42.385938 | controller |
2025-09-29 19:13:42.386030 | controller | TASK [rhol_crc : Check RHOL/CRC binary exists] *********************************
2025-09-29 19:13:42.386047 | controller | Monday 29 September 2025  19:13:42 +0000 (0:00:00.952)       0:00:00.970 ******
2025-09-29 19:13:42.690661 | controller | ok: [instance]
2025-09-29 19:13:42.690749 | controller |
2025-09-29 19:13:42.690771 | controller | TASK [rhol_crc : Delete RHOL/CRC instance and associated configuration] ********
2025-09-29 19:13:42.690900 | controller | Monday 29 September 2025  19:13:42 +0000 (0:00:00.304)       0:00:01.275 ******
2025-09-29 19:13:46.557628 | controller | changed: [instance]
2025-09-29 19:13:46.557695 | controller |
2025-09-29 19:13:46.558166 | controller | PLAY RECAP *********************************************************************
2025-09-29 19:13:46.558414 | controller | instance                   : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-09-29 19:13:46.558429 | controller |
2025-09-29 19:13:46.558436 | controller | Monday 29 September 2025  19:13:46 +0000 (0:00:03.867)       0:00:05.143 ******
2025-09-29 19:13:46.558443 | controller | ===============================================================================
2025-09-29 19:13:46.558453 | controller | rhol_crc : Delete RHOL/CRC instance and associated configuration -------- 3.87s
2025-09-29 19:13:46.558478 | controller | Gathering Facts --------------------------------------------------------- 0.95s
2025-09-29 19:13:46.558486 | controller | rhol_crc : Check RHOL/CRC binary exists --------------------------------- 0.30s
2025-09-29 19:13:46.637969 | controller | INFO     Performing prerun with role_name_check=0...
2025-09-29 19:14:04.493219 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:14:04.493633 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:14:04.494100 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:14:04.494562 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:14:04.495062 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+24673bce was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0+24673bce (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:14:04.495519 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:14:04.495981 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:14:04.496429 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:14:04.496894 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:14:04.497325 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:14:04.497812 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:14:04.498255 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:14:04.498714 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:14:04.499156 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:14:04.499603 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:14:04.508617 | controller | INFO     Running binary > prepare
2025-09-29 19:14:04.975395 | controller |
2025-09-29 19:14:04.975477 | controller | PLAY [Prepare] *****************************************************************
2025-09-29 19:14:04.975585 | controller |
2025-09-29 19:14:04.975711 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 19:14:04.975827 | controller | Monday 29 September 2025  19:14:04 +0000 (0:00:00.017)       0:00:00.017 ******
2025-09-29 19:14:05.830155 | controller | ok: [instance]
2025-09-29 19:14:05.854452 | controller |
2025-09-29 19:14:05.854478 | controller | TASK [Setup libvirt] ***********************************************************
2025-09-29 19:14:05.854486 | controller | Monday 29 September 2025  19:14:05 +0000 (0:00:00.854)       0:00:00.872 ******
2025-09-29 19:14:05.854496 | controller | skipping: [instance]
2025-09-29 19:14:05.878441 | controller |
2025-09-29 19:14:05.878468 | controller | TASK [Setup CRC] ***************************************************************
2025-09-29 19:14:05.878487 | controller | Monday 29 September 2025  19:14:05 +0000 (0:00:00.025)       0:00:00.897 ******
2025-09-29 19:14:05.878499 | controller | skipping: [instance]
2025-09-29 19:14:05.900465 | controller |
2025-09-29 19:14:05.900514 | controller | TASK [Start RHOL/CRC] **********************************************************
2025-09-29 19:14:05.900525 | controller | Monday 29 September 2025  19:14:05 +0000 (0:00:00.023)       0:00:00.921 ******
2025-09-29 19:14:05.900538 | controller | skipping: [instance]
2025-09-29 19:14:05.900579 | controller |
2025-09-29 19:14:05.900589 | controller | TASK [Inject our own NetworkManager configuration] *****************************
2025-09-29 19:14:06.509944 | controller | Monday 29 September 2025  19:14:05 +0000 (0:00:00.022)       0:00:00.943 ******
2025-09-29 19:14:06.510000 | controller | ok: [instance]
2025-09-29 19:14:06.510030 | controller |
2025-09-29 19:14:06.510041 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-09-29 19:14:06.510233 | controller | Monday 29 September 2025  19:14:06 +0000 (0:00:00.609)       0:00:01.552 ******
2025-09-29 19:14:06.530275 | controller | skipping: [instance]
2025-09-29 19:14:06.577443 | controller |
2025-09-29 19:14:06.577527 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-09-29 19:14:06.577537 | controller | Monday 29 September 2025  19:14:06 +0000 (0:00:00.020)       0:00:01.572 ******
2025-09-29 19:14:06.577557 | 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:14:06.577688 | controller |
2025-09-29 19:14:06.577736 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-09-29 19:14:06.776482 | controller | Monday 29 September 2025  19:14:06 +0000 (0:00:00.047)       0:00:01.620 ******
2025-09-29 19:14:06.776534 | controller | ok: [instance]
2025-09-29 19:14:06.776569 | controller |
2025-09-29 19:14:06.776577 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-09-29 19:14:06.776585 | controller | Monday 29 September 2025  19:14:06 +0000 (0:00:00.199)       0:00:01.819 ******
2025-09-29 19:14:06.998447 | controller | ok: [instance]
2025-09-29 19:14:06.998677 | controller |
2025-09-29 19:14:07.701275 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-09-29 19:14:07.701378 | controller | Monday 29 September 2025  19:14:06 +0000 (0:00:00.221)       0:00:02.040 ******
2025-09-29 19:14:07.701395 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-09-29 19:14:07.764818 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-09-29 19:14:07.764878 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-09-29 19:14:07.764886 | controller |
2025-09-29 19:14:07.764892 | controller | TASK [Deploy repo-setup] *******************************************************
2025-09-29 19:14:07.764898 | controller | Monday 29 September 2025  19:14:07 +0000 (0:00:00.702)       0:00:02.743 ******
2025-09-29 19:14:07.764917 | controller |
2025-09-29 19:14:08.369445 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-09-29 19:14:08.369488 | controller | Monday 29 September 2025  19:14:07 +0000 (0:00:00.063)       0:00:02.807 ******
2025-09-29 19:14:08.369503 | controller | ok: [instance] => (item=tmp)
2025-09-29 19:14:08.369538 | controller | ok: [instance] => (item=artifacts/repositories)
2025-09-29 19:14:08.369977 | controller | ok: [instance] => (item=venv/repo_setup)
2025-09-29 19:14:09.805434 | controller |
2025-09-29 19:14:09.805502 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-09-29 19:14:09.805511 | controller | Monday 29 September 2025  19:14:08 +0000 (0:00:00.604)       0:00:03.411 ******
2025-09-29 19:14:09.805525 | controller | ok: [instance]
2025-09-29 19:14:10.426160 | controller |
2025-09-29 19:14:10.426213 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-09-29 19:14:10.426222 | controller | Monday 29 September 2025  19:14:09 +0000 (0:00:01.436)       0:00:04.847 ******
2025-09-29 19:14:10.426233 | controller | ok: [instance]
2025-09-29 19:14:11.764946 | controller |
2025-09-29 19:14:11.764987 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-09-29 19:14:11.764995 | controller | Monday 29 September 2025  19:14:10 +0000 (0:00:00.620)       0:00:05.468 ******
2025-09-29 19:14:11.765014 | controller | ok: [instance]
2025-09-29 19:14:12.073456 | controller |
2025-09-29 19:14:12.073497 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-09-29 19:14:12.073505 | controller | Monday 29 September 2025  19:14:11 +0000 (0:00:01.338)       0:00:06.807 ******
2025-09-29 19:14:12.073516 | controller | ok: [instance]
2025-09-29 19:14:12.073540 | controller |
2025-09-29 19:14:12.073561 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-09-29 19:14:12.073703 | controller | Monday 29 September 2025  19:14:12 +0000 (0:00:00.308)       0:00:07.116 ******
2025-09-29 19:14:12.098422 | controller | skipping: [instance]
2025-09-29 19:14:12.098471 | controller |
2025-09-29 19:14:12.098481 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-09-29 19:14:12.098604 | controller | Monday 29 September 2025  19:14:12 +0000 (0:00:00.025)       0:00:07.141 ******
2025-09-29 19:14:13.009136 | controller | changed: [instance]
2025-09-29 19:14:13.046958 | controller |
2025-09-29 19:14:13.046985 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-09-29 19:14:13.046994 | controller | Monday 29 September 2025  19:14:13 +0000 (0:00:00.910)       0:00:08.051 ******
2025-09-29 19:14:13.047006 | controller | skipping: [instance]
2025-09-29 19:14:13.090383 | controller |
2025-09-29 19:14:13.090430 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-09-29 19:14:13.090438 | controller | Monday 29 September 2025  19:14:13 +0000 (0:00:00.037)       0:00:08.089 ******
2025-09-29 19:14:13.090449 | controller | skipping: [instance]
2025-09-29 19:14:13.131906 | controller |
2025-09-29 19:14:13.131941 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-09-29 19:14:13.131951 | controller | Monday 29 September 2025  19:14:13 +0000 (0:00:00.042)       0:00:08.132 ******
2025-09-29 19:14:13.131963 | controller | skipping: [instance]
2025-09-29 19:14:13.710989 | controller |
2025-09-29 19:14:13.711027 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-09-29 19:14:13.711035 | controller | Monday 29 September 2025  19:14:13 +0000 (0:00:00.041)       0:00:08.174 ******
2025-09-29 19:14:13.711046 | controller | changed: [instance]
2025-09-29 19:14:14.096708 | controller |
2025-09-29 19:14:14.096749 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-09-29 19:14:14.096757 | controller | Monday 29 September 2025  19:14:13 +0000 (0:00:00.578)       0:00:08.753 ******
2025-09-29 19:14:14.096768 | controller | ok: [instance]
2025-09-29 19:14:14.130208 | controller |
2025-09-29 19:14:14.130258 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-09-29 19:14:14.130266 | controller | Monday 29 September 2025  19:14:14 +0000 (0:00:00.385)       0:00:09.139 ******
2025-09-29 19:14:14.130277 | controller | skipping: [instance]
2025-09-29 19:14:14.162481 | controller |
2025-09-29 19:14:14.162517 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-09-29 19:14:14.162525 | controller | Monday 29 September 2025  19:14:14 +0000 (0:00:00.033)       0:00:09.172 ******
2025-09-29 19:14:14.162536 | controller | skipping: [instance]
2025-09-29 19:14:14.195735 | controller |
2025-09-29 19:14:14.195770 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-09-29 19:14:14.195777 | controller | Monday 29 September 2025  19:14:14 +0000 (0:00:00.032)       0:00:09.205 ******
2025-09-29 19:14:14.195788 | controller | skipping: [instance]
2025-09-29 19:14:14.238924 | controller |
2025-09-29 19:14:14.238953 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-09-29 19:14:14.238969 | controller | Monday 29 September 2025  19:14:14 +0000 (0:00:00.033)       0:00:09.238 ******
2025-09-29 19:14:14.238980 | controller | ok: [instance]
2025-09-29 19:14:14.270810 | controller |
2025-09-29 19:14:14.270840 | controller | TASK [repo_setup : Create download directory] **********************************
2025-09-29 19:14:14.270847 | controller | Monday 29 September 2025  19:14:14 +0000 (0:00:00.043)       0:00:09.281 ******
2025-09-29 19:14:14.270858 | controller | skipping: [instance]
2025-09-29 19:14:14.303483 | controller |
2025-09-29 19:14:14.303525 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-09-29 19:14:14.303533 | controller | Monday 29 September 2025  19:14:14 +0000 (0:00:00.031)       0:00:09.313 ******
2025-09-29 19:14:14.303556 | controller | skipping: [instance]
2025-09-29 19:14:14.303587 | controller |
2025-09-29 19:14:14.303595 | controller | TASK [Download the RPM] ********************************************************
2025-09-29 19:14:14.303700 | controller | Monday 29 September 2025  19:14:14 +0000 (0:00:00.032)       0:00:09.346 ******
2025-09-29 19:14:14.343086 | controller | skipping: [instance]
2025-09-29 19:14:14.373629 | controller |
2025-09-29 19:14:14.373665 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-09-29 19:14:14.373673 | controller | Monday 29 September 2025  19:14:14 +0000 (0:00:00.039)       0:00:09.385 ******
2025-09-29 19:14:14.373684 | controller | skipping: [instance]
2025-09-29 19:14:14.373833 | controller |
2025-09-29 19:14:14.373979 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-09-29 19:14:14.374119 | controller | Monday 29 September 2025  19:14:14 +0000 (0:00:00.030)       0:00:09.416 ******
2025-09-29 19:14:14.404115 | controller | skipping: [instance]
2025-09-29 19:14:14.404395 | controller |
2025-09-29 19:14:14.404576 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-09-29 19:14:14.404724 | controller | Monday 29 September 2025  19:14:14 +0000 (0:00:00.030)       0:00:09.447 ******
2025-09-29 19:14:14.437593 | controller | skipping: [instance]
2025-09-29 19:14:14.437787 | controller |
2025-09-29 19:14:14.437971 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-09-29 19:14:14.438139 | controller | Monday 29 September 2025  19:14:14 +0000 (0:00:00.033)       0:00:09.480 ******
2025-09-29 19:14:14.467373 | controller | skipping: [instance]
2025-09-29 19:14:14.467557 | controller |
2025-09-29 19:14:14.467711 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-09-29 19:14:14.467846 | controller | Monday 29 September 2025  19:14:14 +0000 (0:00:00.029)       0:00:09.510 ******
2025-09-29 19:14:14.665265 | controller | ok: [instance]
2025-09-29 19:14:14.665501 | controller |
2025-09-29 19:14:14.665682 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-09-29 19:14:14.665852 | controller | Monday 29 September 2025  19:14:14 +0000 (0:00:00.197)       0:00:09.708 ******
2025-09-29 19:14:14.891828 | controller | changed: [instance]
2025-09-29 19:14:15.133989 | controller |
2025-09-29 19:14:15.134033 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-09-29 19:14:15.134041 | controller | Monday 29 September 2025  19:14:14 +0000 (0:00:00.226)       0:00:09.934 ******
2025-09-29 19:14:15.134052 | controller | changed: [instance]
2025-09-29 19:14:15.158843 | controller |
2025-09-29 19:14:15.158884 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-09-29 19:14:15.158892 | controller | Monday 29 September 2025  19:14:15 +0000 (0:00:00.242)       0:00:10.176 ******
2025-09-29 19:14:15.158902 | controller | skipping: [instance]
2025-09-29 19:14:15.185488 | controller |
2025-09-29 19:14:15.185526 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-09-29 19:14:15.185535 | controller | Monday 29 September 2025  19:14:15 +0000 (0:00:00.024)       0:00:10.201 ******
2025-09-29 19:14:15.185546 | controller | skipping: [instance]
2025-09-29 19:14:15.185620 | controller |
2025-09-29 19:14:15.185660 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-09-29 19:14:15.185674 | controller | Monday 29 September 2025  19:14:15 +0000 (0:00:00.026)       0:00:10.228 ******
2025-09-29 19:14:15.212905 | controller | skipping: [instance]
2025-09-29 19:14:15.239483 | controller |
2025-09-29 19:14:15.239528 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-09-29 19:14:15.239537 | controller | Monday 29 September 2025  19:14:15 +0000 (0:00:00.027)       0:00:10.255 ******
2025-09-29 19:14:15.239549 | controller | skipping: [instance]
2025-09-29 19:14:15.266036 | controller |
2025-09-29 19:14:15.266092 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-09-29 19:14:15.266101 | controller | Monday 29 September 2025  19:14:15 +0000 (0:00:00.026)       0:00:10.281 ******
2025-09-29 19:14:15.266118 | controller | skipping: [instance]
2025-09-29 19:14:15.266170 | controller |
2025-09-29 19:14:15.266306 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-09-29 19:14:15.266457 | controller | Monday 29 September 2025  19:14:15 +0000 (0:00:00.026)       0:00:10.308 ******
2025-09-29 19:14:15.294005 | controller | skipping: [instance]
2025-09-29 19:14:15.294111 | controller |
2025-09-29 19:14:15.294231 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-09-29 19:14:15.294432 | controller | Monday 29 September 2025  19:14:15 +0000 (0:00:00.028)       0:00:10.337 ******
2025-09-29 19:14:15.315898 | controller | skipping: [instance]
2025-09-29 19:14:15.316009 | controller |
2025-09-29 19:14:15.316133 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-09-29 19:14:15.316245 | controller | Monday 29 September 2025  19:14:15 +0000 (0:00:00.021)       0:00:10.358 ******
2025-09-29 19:14:15.349566 | controller | skipping: [instance]
2025-09-29 19:14:15.349673 | controller |
2025-09-29 19:14:15.349788 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-09-29 19:14:15.349901 | controller | Monday 29 September 2025  19:14:15 +0000 (0:00:00.033)       0:00:10.392 ******
2025-09-29 19:14:15.370186 | controller | skipping: [instance]
2025-09-29 19:14:15.370373 | controller |
2025-09-29 19:14:15.370530 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-09-29 19:14:15.370677 | controller | Monday 29 September 2025  19:14:15 +0000 (0:00:00.020)       0:00:10.413 ******
2025-09-29 19:14:15.398920 | controller | skipping: [instance]
2025-09-29 19:14:15.399066 | controller |
2025-09-29 19:14:15.399177 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-09-29 19:14:15.399293 | controller | Monday 29 September 2025  19:14:15 +0000 (0:00:00.028)       0:00:10.441 ******
2025-09-29 19:14:15.433875 | controller | skipping: [instance]
2025-09-29 19:14:15.434032 | controller |
2025-09-29 19:14:15.434152 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-09-29 19:14:15.434273 | controller | Monday 29 September 2025  19:14:15 +0000 (0:00:00.035)       0:00:10.476 ******
2025-09-29 19:14:15.471978 | controller | skipping: [instance]
2025-09-29 19:14:15.472112 | controller |
2025-09-29 19:14:15.472270 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-09-29 19:14:15.472391 | controller | Monday 29 September 2025  19:14:15 +0000 (0:00:00.038)       0:00:10.514 ******
2025-09-29 19:14:15.506757 | controller | skipping: [instance]
2025-09-29 19:14:15.506940 | controller |
2025-09-29 19:14:15.507100 | controller | TASK [test_deps : Install additional packages] *********************************
2025-09-29 19:14:15.507247 | controller | Monday 29 September 2025  19:14:15 +0000 (0:00:00.034)       0:00:10.549 ******
2025-09-29 19:14:15.540383 | controller | skipping: [instance]
2025-09-29 19:14:15.540515 | controller |
2025-09-29 19:14:15.540640 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-09-29 19:14:15.540757 | controller | Monday 29 September 2025  19:14:15 +0000 (0:00:00.033)       0:00:10.583 ******
2025-09-29 19:15:18.049656 | controller | ok: [instance]
2025-09-29 19:15:19.326045 | controller |
2025-09-29 19:15:19.326102 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-09-29 19:15:19.326111 | controller | Monday 29 September 2025  19:15:18 +0000 (0:01:02.508)       0:01:13.091 ******
2025-09-29 19:15:19.326123 | controller | ok: [instance]
2025-09-29 19:15:19.362486 | controller |
2025-09-29 19:15:19.362527 | controller | TASK [test_deps : Install extra packages] **************************************
2025-09-29 19:15:19.362534 | controller | Monday 29 September 2025  19:15:19 +0000 (0:00:01.276)       0:01:14.368 ******
2025-09-29 19:15:19.362546 | controller | skipping: [instance]
2025-09-29 19:15:19.412115 | controller |
2025-09-29 19:15:19.412158 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-09-29 19:15:19.412166 | controller | Monday 29 September 2025  19:15:19 +0000 (0:00:00.036)       0:01:14.404 ******
2025-09-29 19:15:19.412178 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-09-29 19:15:19.435489 | controller |
2025-09-29 19:15:19.435534 | controller | TASK [ci_setup : List packages to install] *************************************
2025-09-29 19:15:19.435543 | controller | Monday 29 September 2025  19:15:19 +0000 (0:00:00.050)       0:01:14.454 ******
2025-09-29 19:15:19.435565 | controller | ok: [instance] =>
2025-09-29 19:15:19.435592 | controller |   cifmw_ci_setup_packages:
2025-09-29 19:15:19.435599 | controller |   - bash-completion
2025-09-29 19:15:19.435605 | controller |   - ca-certificates
2025-09-29 19:15:19.435610 | controller |   - git-core
2025-09-29 19:15:19.435616 | controller |   - make
2025-09-29 19:15:19.435622 | controller |   - tar
2025-09-29 19:15:19.435627 | controller |   - tmux
2025-09-29 19:15:19.435634 | controller |   - python3-pip
2025-09-29 19:15:19.435656 | controller |
2025-09-29 19:15:19.435886 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-09-29 19:15:20.709099 | controller | Monday 29 September 2025  19:15:19 +0000 (0:00:00.022)       0:01:14.477 ******
2025-09-29 19:15:20.709142 | controller | ok: [instance]
2025-09-29 19:15:20.987460 | controller |
2025-09-29 19:15:20.987511 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-09-29 19:15:20.987522 | controller | Monday 29 September 2025  19:15:20 +0000 (0:00:01.273)       0:01:15.751 ******
2025-09-29 19:15:20.987536 | controller | ok: [instance]
2025-09-29 19:15:21.197445 | controller |
2025-09-29 19:15:21.197492 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-09-29 19:15:21.197502 | controller | Monday 29 September 2025  19:15:20 +0000 (0:00:00.278)       0:01:16.030 ******
2025-09-29 19:15:21.197517 | controller | ok: [instance]
2025-09-29 19:15:21.267019 | controller |
2025-09-29 19:15:21.267072 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-09-29 19:15:21.267089 | controller | Monday 29 September 2025  19:15:21 +0000 (0:00:00.209)       0:01:16.239 ******
2025-09-29 19:15:21.267101 | controller | skipping: [instance]
2025-09-29 19:15:21.301934 | controller |
2025-09-29 19:15:21.301953 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-09-29 19:15:21.301961 | controller | Monday 29 September 2025  19:15:21 +0000 (0:00:00.069)       0:01:16.309 ******
2025-09-29 19:15:21.301970 | controller | skipping: [instance]
2025-09-29 19:15:21.492231 | controller |
2025-09-29 19:15:21.492270 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-09-29 19:15:21.492279 | controller | Monday 29 September 2025  19:15:21 +0000 (0:00:00.035)       0:01:16.344 ******
2025-09-29 19:15:21.492289 | controller | ok: [instance]
2025-09-29 19:15:21.779137 | controller |
2025-09-29 19:15:21.779178 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-09-29 19:15:21.779186 | controller | Monday 29 September 2025  19:15:21 +0000 (0:00:00.189)       0:01:16.534 ******
2025-09-29 19:15:21.779196 | controller | changed: [instance]
2025-09-29 19:15:21.797454 | controller |
2025-09-29 19:15:21.797498 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-09-29 19:15:21.797508 | controller | Monday 29 September 2025  19:15:21 +0000 (0:00:00.287)       0:01:16.821 ******
2025-09-29 19:15:21.797522 | controller | skipping: [instance]
2025-09-29 19:15:21.797560 | controller |
2025-09-29 19:15:21.797741 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-09-29 19:15:21.797776 | controller | Monday 29 September 2025  19:15:21 +0000 (0:00:00.018)       0:01:16.840 ******
2025-09-29 19:15:21.815869 | controller | skipping: [instance]
2025-09-29 19:15:21.834737 | controller |
2025-09-29 19:15:21.834761 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-09-29 19:15:21.834770 | controller | Monday 29 September 2025  19:15:21 +0000 (0:00:00.018)       0:01:16.858 ******
2025-09-29 19:15:21.834780 | controller | skipping: [instance]
2025-09-29 19:15:21.853222 | controller |
2025-09-29 19:15:21.853238 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-09-29 19:15:21.853245 | controller | Monday 29 September 2025  19:15:21 +0000 (0:00:00.019)       0:01:16.877 ******
2025-09-29 19:15:21.853253 | controller | skipping: [instance]
2025-09-29 19:15:21.872479 | controller |
2025-09-29 19:15:21.872534 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-09-29 19:15:21.872543 | controller | Monday 29 September 2025  19:15:21 +0000 (0:00:00.018)       0:01:16.896 ******
2025-09-29 19:15:21.872556 | controller | skipping: [instance]
2025-09-29 19:15:21.900436 | controller |
2025-09-29 19:15:21.900479 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-09-29 19:15:21.900488 | controller | Monday 29 September 2025  19:15:21 +0000 (0:00:00.019)       0:01:16.915 ******
2025-09-29 19:15:21.900500 | controller | skipping: [instance]
2025-09-29 19:15:22.933432 | controller |
2025-09-29 19:15:22.933472 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-09-29 19:15:22.933481 | controller | Monday 29 September 2025  19:15:21 +0000 (0:00:00.027)       0:01:16.943 ******
2025-09-29 19:15:22.933492 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-09-29 19:15:22.933522 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-09-29 19:15:22.933529 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-09-29 19:15:22.933536 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-09-29 19:15:22.933964 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-09-29 19:15:22.934032 | controller |
2025-09-29 19:15:22.971726 | controller | TASK [Check for CRC startup] ***************************************************
2025-09-29 19:15:22.971832 | controller | Monday 29 September 2025  19:15:22 +0000 (0:00:01.032)       0:01:17.975 ******
2025-09-29 19:15:22.971854 | controller | skipping: [instance]
2025-09-29 19:15:22.971906 | controller |
2025-09-29 19:15:22.971914 | controller | PLAY RECAP *********************************************************************
2025-09-29 19:15:22.971921 | controller | instance                   : ok=28   changed=5    unreachable=0    failed=0    skipped=42   rescued=0    ignored=0
2025-09-29 19:15:22.971929 | controller |
2025-09-29 19:15:22.971935 | controller | Monday 29 September 2025  19:15:22 +0000 (0:00:00.037)       0:01:18.013 ******
2025-09-29 19:15:22.971942 | controller | ===============================================================================
2025-09-29 19:15:22.971948 | controller | test_deps : Install selinux python libs -------------------------------- 62.51s
2025-09-29 19:15:22.971954 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.44s
2025-09-29 19:15:22.971961 | controller | repo_setup : Initialize python venv and install requirements ------------ 1.34s
2025-09-29 19:15:22.971967 | controller | test_deps : Install python yaml libs ------------------------------------ 1.28s
2025-09-29 19:15:22.972002 | controller | ci_setup : Install needed packages -------------------------------------- 1.27s
2025-09-29 19:15:22.972012 | controller | ci_setup : Manage directories ------------------------------------------- 1.03s
2025-09-29 19:15:22.972958 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.91s
2025-09-29 19:15:22.972988 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-09-29 19:15:22.973185 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.70s
2025-09-29 19:15:22.973292 | controller | repo_setup : Get repo-setup repository ---------------------------------- 0.62s
2025-09-29 19:15:22.973442 | controller | Inject our own NetworkManager configuration ----------------------------- 0.61s
2025-09-29 19:15:22.973570 | controller | repo_setup : Ensure directories are present ----------------------------- 0.60s
2025-09-29 19:15:22.973679 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.58s
2025-09-29 19:15:22.973840 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.39s
2025-09-29 19:15:22.973956 | controller | repo_setup : Install repo-setup package --------------------------------- 0.31s
2025-09-29 19:15:22.974075 | controller | ci_setup : Source completion from within .bashrc ------------------------ 0.29s
2025-09-29 19:15:22.974185 | controller | ci_setup : Gather version of openshift client --------------------------- 0.28s
2025-09-29 19:15:22.974347 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.24s
2025-09-29 19:15:22.974474 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.23s
2025-09-29 19:15:22.974590 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.22s
2025-09-29 19:15:23.038364 | controller | INFO     Running binary > converge
2025-09-29 19:15:23.485597 | controller |
2025-09-29 19:15:23.485709 | controller | PLAY [Converge binary] *********************************************************
2025-09-29 19:15:23.485812 | controller |
2025-09-29 19:15:23.486003 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 19:15:23.486049 | controller | Monday 29 September 2025  19:15:23 +0000 (0:00:00.015)       0:00:00.015 ******
2025-09-29 19:15:24.319475 | controller | ok: [instance]
2025-09-29 19:15:24.319535 | controller |
2025-09-29 19:15:24.319544 | controller | TASK [rhol_crc : Create bin directory if it does not exist] ********************
2025-09-29 19:15:24.319553 | controller | Monday 29 September 2025  19:15:24 +0000 (0:00:00.834)       0:00:00.849 ******
2025-09-29 19:15:24.633473 | controller | ok: [instance]
2025-09-29 19:15:24.633756 | controller |
2025-09-29 19:15:24.633800 | controller | TASK [rhol_crc : Create temporary directory for downloading RHOL/CRC] **********
2025-09-29 19:15:24.911889 | controller | Monday 29 September 2025  19:15:24 +0000 (0:00:00.314)       0:00:01.163 ******
2025-09-29 19:15:24.911933 | controller | changed: [instance]
2025-09-29 19:15:31.198813 | controller |
2025-09-29 19:15:31.198851 | controller | TASK [rhol_crc : Download RHOL/CRC with sha256 checksum] ***********************
2025-09-29 19:15:31.198859 | controller | Monday 29 September 2025  19:15:24 +0000 (0:00:00.278)       0:00:01.441 ******
2025-09-29 19:15:31.198870 | controller | changed: [instance]
2025-09-29 19:15:36.973866 | controller |
2025-09-29 19:15:36.973909 | controller | TASK [rhol_crc : Unarchive RHOL/CRC binary] ************************************
2025-09-29 19:15:36.973917 | controller | Monday 29 September 2025  19:15:31 +0000 (0:00:06.287)       0:00:07.728 ******
2025-09-29 19:15:36.973928 | controller | changed: [instance]
2025-09-29 19:15:37.160831 | controller |
2025-09-29 19:15:37.160869 | controller | TASK [rhol_crc : Delete temporary directory for downloading RHOL/CRC] **********
2025-09-29 19:15:37.160877 | controller | Monday 29 September 2025  19:15:36 +0000 (0:00:05.775)       0:00:13.503 ******
2025-09-29 19:15:37.160887 | controller | ok: [instance]
2025-09-29 19:15:38.047522 | controller |
2025-09-29 19:15:38.047559 | controller | TASK [rhol_crc : Get CRC binary version] ***************************************
2025-09-29 19:15:38.047567 | controller | Monday 29 September 2025  19:15:37 +0000 (0:00:00.186)       0:00:13.690 ******
2025-09-29 19:15:38.047578 | controller | changed: [instance]
2025-09-29 19:15:38.047605 | controller |
2025-09-29 19:15:38.047611 | controller | TASK [rhol_crc : Extract versions] *********************************************
2025-09-29 19:15:38.047625 | controller | Monday 29 September 2025  19:15:38 +0000 (0:00:00.886)       0:00:14.577 ******
2025-09-29 19:15:38.085857 | controller | ok: [instance]
2025-09-29 19:15:38.134487 | controller |
2025-09-29 19:15:38.134521 | controller | TASK [rhol_crc : Output found binary location and versions] ********************
2025-09-29 19:15:38.134530 | controller | Monday 29 September 2025  19:15:38 +0000 (0:00:00.038)       0:00:14.615 ******
2025-09-29 19:15:38.134541 | controller | ok: [instance] =>
2025-09-29 19:15:38.778907 | controller |   msg: 'Found crc at /usr/local/bin/crc, with version 2.39.0 (wants: 2.39.0) and serving OpenShift version 4.16.0.'
2025-09-29 19:15:38.778950 | controller |
2025-09-29 19:15:38.778960 | controller | PLAY [Verify get_version] ******************************************************
2025-09-29 19:15:38.778967 | controller |
2025-09-29 19:15:38.778974 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 19:15:38.778980 | controller | Monday 29 September 2025  19:15:38 +0000 (0:00:00.048)       0:00:14.664 ******
2025-09-29 19:15:38.778992 | controller | ok: [instance]
2025-09-29 19:15:38.836458 | controller |
2025-09-29 19:15:38.836500 | controller | TASK [Ensure facts are set and look correct] ***********************************
2025-09-29 19:15:38.836508 | controller | Monday 29 September 2025  19:15:38 +0000 (0:00:00.643)       0:00:15.308 ******
2025-09-29 19:15:38.836519 | controller | ok: [instance] => changed=false
2025-09-29 19:15:38.836546 | controller |   msg: All assertions passed
2025-09-29 19:15:38.836552 | controller |
2025-09-29 19:15:38.836558 | controller | PLAY RECAP *********************************************************************
2025-09-29 19:15:38.836563 | controller | instance                   : ok=11   changed=4    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-09-29 19:15:38.836569 | controller |
2025-09-29 19:15:38.836574 | controller | Monday 29 September 2025  19:15:38 +0000 (0:00:00.057)       0:00:15.366 ******
2025-09-29 19:15:38.836587 | controller | ===============================================================================
2025-09-29 19:15:38.836609 | controller | rhol_crc : Download RHOL/CRC with sha256 checksum ----------------------- 6.29s
2025-09-29 19:15:38.837598 | controller | rhol_crc : Unarchive RHOL/CRC binary ------------------------------------ 5.78s
2025-09-29 19:15:38.885416 | controller | rhol_crc : Get CRC binary version --------------------------------------- 0.89s
2025-09-29 19:15:38.885452 | controller | Gathering Facts --------------------------------------------------------- 0.83s
2025-09-29 19:15:38.885459 | controller | Gathering Facts --------------------------------------------------------- 0.64s
2025-09-29 19:15:38.885465 | controller | rhol_crc : Create bin directory if it does not exist -------------------- 0.31s
2025-09-29 19:15:38.885470 | controller | rhol_crc : Create temporary directory for downloading RHOL/CRC ---------- 0.28s
2025-09-29 19:15:38.885476 | controller | rhol_crc : Delete temporary directory for downloading RHOL/CRC ---------- 0.19s
2025-09-29 19:15:38.885481 | controller | Ensure facts are set and look correct ----------------------------------- 0.06s
2025-09-29 19:15:38.885486 | controller | rhol_crc : Output found binary location and versions -------------------- 0.05s
2025-09-29 19:15:38.885491 | controller | rhol_crc : Extract versions --------------------------------------------- 0.04s
2025-09-29 19:15:38.885501 | controller | INFO     Running binary > cleanup
2025-09-29 19:15:39.315375 | controller |
2025-09-29 19:15:39.315591 | controller | PLAY [Prepare] *****************************************************************
2025-09-29 19:15:39.315730 | controller |
2025-09-29 19:15:39.315883 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 19:15:39.316088 | controller | Monday 29 September 2025  19:15:39 +0000 (0:00:00.015)       0:00:00.015 ******
2025-09-29 19:15:40.168477 | controller | ok: [instance]
2025-09-29 19:15:40.614698 | controller |
2025-09-29 19:15:40.614740 | controller | TASK [Cleanup RHOL/CRC after tests] ********************************************
2025-09-29 19:15:40.614749 | controller | Monday 29 September 2025  19:15:40 +0000 (0:00:00.853)       0:00:00.868 ******
2025-09-29 19:15:40.614759 | controller | changed: [instance]
2025-09-29 19:15:40.614778 | controller |
2025-09-29 19:15:40.614888 | controller | PLAY RECAP *********************************************************************
2025-09-29 19:15:40.615485 | controller | instance                   : ok=2    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-09-29 19:15:40.662736 | controller |
2025-09-29 19:15:40.662789 | controller | Monday 29 September 2025  19:15:40 +0000 (0:00:00.447)       0:00:01.315 ******
2025-09-29 19:15:40.662798 | controller | ===============================================================================
2025-09-29 19:15:40.662804 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-09-29 19:15:40.662822 | controller | Cleanup RHOL/CRC after tests -------------------------------------------- 0.45s
2025-09-29 19:15:40.662839 | controller | INFO     Performing prerun with role_name_check=0...
2025-09-29 19:15:58.209611 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:15:58.210041 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:15:58.210525 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:15:58.210964 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:15:58.211460 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+24673bce was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0+24673bce (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:15:58.211910 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:15:58.212378 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:15:58.212824 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:15:58.213271 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:15:58.213739 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:15:58.214220 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:15:58.214661 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:15:58.215328 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:15:58.215791 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:15:58.216257 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:15:58.224827 | controller | INFO     Running default > prepare
2025-09-29 19:15:58.741503 | controller |
2025-09-29 19:15:58.741584 | controller | PLAY [Prepare] *****************************************************************
2025-09-29 19:15:58.741709 | controller |
2025-09-29 19:15:58.741833 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 19:15:58.741954 | controller | Monday 29 September 2025  19:15:58 +0000 (0:00:00.018)       0:00:00.018 ******
2025-09-29 19:16:00.632492 | controller | ok: [instance]
2025-09-29 19:16:00.657331 | controller |
2025-09-29 19:16:00.657388 | controller | TASK [Setup libvirt] ***********************************************************
2025-09-29 19:16:00.657398 | controller | Monday 29 September 2025  19:16:00 +0000 (0:00:01.890)       0:00:01.909 ******
2025-09-29 19:16:00.657413 | controller | skipping: [instance]
2025-09-29 19:16:00.684995 | controller |
2025-09-29 19:16:00.685013 | controller | TASK [Setup CRC] ***************************************************************
2025-09-29 19:16:00.685020 | controller | Monday 29 September 2025  19:16:00 +0000 (0:00:00.025)       0:00:01.934 ******
2025-09-29 19:16:00.685028 | controller | skipping: [instance]
2025-09-29 19:16:00.710993 | controller |
2025-09-29 19:16:00.711018 | controller | TASK [Start RHOL/CRC] **********************************************************
2025-09-29 19:16:00.711025 | controller | Monday 29 September 2025  19:16:00 +0000 (0:00:00.027)       0:00:01.962 ******
2025-09-29 19:16:00.711034 | controller | skipping: [instance]
2025-09-29 19:16:01.337135 | controller |
2025-09-29 19:16:01.337177 | controller | TASK [Inject our own NetworkManager configuration] *****************************
2025-09-29 19:16:01.337186 | controller | Monday 29 September 2025  19:16:00 +0000 (0:00:00.025)       0:00:01.988 ******
2025-09-29 19:16:01.337197 | controller | ok: [instance]
2025-09-29 19:16:01.356445 | controller |
2025-09-29 19:16:01.356506 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-09-29 19:16:01.356517 | controller | Monday 29 September 2025  19:16:01 +0000 (0:00:00.625)       0:00:02.614 ******
2025-09-29 19:16:01.356533 | controller | skipping: [instance]
2025-09-29 19:16:01.356846 | controller |
2025-09-29 19:16:01.401494 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-09-29 19:16:01.401538 | controller | Monday 29 September 2025  19:16:01 +0000 (0:00:00.020)       0:00:02.634 ******
2025-09-29 19:16:01.401554 | 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:16:01.601807 | controller |
2025-09-29 19:16:01.601850 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-09-29 19:16:01.601858 | controller | Monday 29 September 2025  19:16:01 +0000 (0:00:00.044)       0:00:02.678 ******
2025-09-29 19:16:01.601869 | controller | ok: [instance]
2025-09-29 19:16:01.820413 | controller |
2025-09-29 19:16:01.820452 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-09-29 19:16:01.820460 | controller | Monday 29 September 2025  19:16:01 +0000 (0:00:00.200)       0:00:02.879 ******
2025-09-29 19:16:01.820471 | controller | ok: [instance]
2025-09-29 19:16:01.820477 | controller |
2025-09-29 19:16:01.820483 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-09-29 19:16:01.820490 | controller | Monday 29 September 2025  19:16:01 +0000 (0:00:00.218)       0:00:03.097 ******
2025-09-29 19:16:02.537073 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-09-29 19:16:02.605961 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-09-29 19:16:02.606074 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-09-29 19:16:02.606083 | controller |
2025-09-29 19:16:02.606091 | controller | TASK [Deploy repo-setup] *******************************************************
2025-09-29 19:16:02.606097 | controller | Monday 29 September 2025  19:16:02 +0000 (0:00:00.716)       0:00:03.813 ******
2025-09-29 19:16:02.606109 | controller |
2025-09-29 19:16:03.210520 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-09-29 19:16:03.210584 | controller | Monday 29 September 2025  19:16:02 +0000 (0:00:00.069)       0:00:03.883 ******
2025-09-29 19:16:03.210601 | controller | ok: [instance] => (item=tmp)
2025-09-29 19:16:03.210631 | controller | ok: [instance] => (item=artifacts/repositories)
2025-09-29 19:16:03.210638 | controller | ok: [instance] => (item=venv/repo_setup)
2025-09-29 19:16:03.210646 | controller |
2025-09-29 19:16:03.210669 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-09-29 19:16:03.210874 | controller | Monday 29 September 2025  19:16:03 +0000 (0:00:00.604)       0:00:04.487 ******
2025-09-29 19:16:04.652343 | controller | ok: [instance]
2025-09-29 19:16:05.281496 | controller |
2025-09-29 19:16:05.281541 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-09-29 19:16:05.281551 | controller | Monday 29 September 2025  19:16:04 +0000 (0:00:01.441)       0:00:05.929 ******
2025-09-29 19:16:05.281564 | controller | ok: [instance]
2025-09-29 19:16:06.631222 | controller |
2025-09-29 19:16:06.631263 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-09-29 19:16:06.631278 | controller | Monday 29 September 2025  19:16:05 +0000 (0:00:00.629)       0:00:06.558 ******
2025-09-29 19:16:06.631290 | controller | ok: [instance]
2025-09-29 19:16:06.938855 | controller |
2025-09-29 19:16:06.938927 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-09-29 19:16:06.938936 | controller | Monday 29 September 2025  19:16:06 +0000 (0:00:01.349)       0:00:07.908 ******
2025-09-29 19:16:06.938958 | controller | ok: [instance]
2025-09-29 19:16:06.964393 | controller |
2025-09-29 19:16:06.964421 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-09-29 19:16:06.964429 | controller | Monday 29 September 2025  19:16:06 +0000 (0:00:00.307)       0:00:08.215 ******
2025-09-29 19:16:06.964437 | controller | skipping: [instance]
2025-09-29 19:16:06.964699 | controller |
2025-09-29 19:16:06.964713 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-09-29 19:16:07.904467 | controller | Monday 29 September 2025  19:16:06 +0000 (0:00:00.025)       0:00:08.241 ******
2025-09-29 19:16:07.904515 | controller | changed: [instance]
2025-09-29 19:16:07.904543 | controller |
2025-09-29 19:16:07.904552 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-09-29 19:16:07.904659 | controller | Monday 29 September 2025  19:16:07 +0000 (0:00:00.940)       0:00:09.182 ******
2025-09-29 19:16:07.946484 | controller | skipping: [instance]
2025-09-29 19:16:07.989811 | controller |
2025-09-29 19:16:07.989864 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-09-29 19:16:07.989873 | controller | Monday 29 September 2025  19:16:07 +0000 (0:00:00.041)       0:00:09.223 ******
2025-09-29 19:16:07.989883 | controller | skipping: [instance]
2025-09-29 19:16:08.032205 | controller |
2025-09-29 19:16:08.032241 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-09-29 19:16:08.032249 | controller | Monday 29 September 2025  19:16:07 +0000 (0:00:00.043)       0:00:09.267 ******
2025-09-29 19:16:08.032259 | controller | skipping: [instance]
2025-09-29 19:16:08.600747 | controller |
2025-09-29 19:16:08.600791 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-09-29 19:16:08.600816 | controller | Monday 29 September 2025  19:16:08 +0000 (0:00:00.042)       0:00:09.309 ******
2025-09-29 19:16:08.600828 | controller | changed: [instance]
2025-09-29 19:16:08.976414 | controller |
2025-09-29 19:16:08.976493 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-09-29 19:16:08.976504 | controller | Monday 29 September 2025  19:16:08 +0000 (0:00:00.568)       0:00:09.878 ******
2025-09-29 19:16:08.976522 | controller | ok: [instance]
2025-09-29 19:16:08.976564 | controller |
2025-09-29 19:16:08.976572 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-09-29 19:16:09.007813 | controller | Monday 29 September 2025  19:16:08 +0000 (0:00:00.375)       0:00:10.254 ******
2025-09-29 19:16:09.007860 | controller | skipping: [instance]
2025-09-29 19:16:09.040109 | controller |
2025-09-29 19:16:09.040154 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-09-29 19:16:09.040174 | controller | Monday 29 September 2025  19:16:09 +0000 (0:00:00.031)       0:00:10.285 ******
2025-09-29 19:16:09.040187 | controller | skipping: [instance]
2025-09-29 19:16:09.073011 | controller |
2025-09-29 19:16:09.073031 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-09-29 19:16:09.073039 | controller | Monday 29 September 2025  19:16:09 +0000 (0:00:00.032)       0:00:10.317 ******
2025-09-29 19:16:09.073047 | controller | skipping: [instance]
2025-09-29 19:16:09.115513 | controller |
2025-09-29 19:16:09.115573 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-09-29 19:16:09.115582 | controller | Monday 29 September 2025  19:16:09 +0000 (0:00:00.033)       0:00:10.350 ******
2025-09-29 19:16:09.115595 | controller | ok: [instance]
2025-09-29 19:16:09.115625 | controller |
2025-09-29 19:16:09.115640 | controller | TASK [repo_setup : Create download directory] **********************************
2025-09-29 19:16:09.115660 | controller | Monday 29 September 2025  19:16:09 +0000 (0:00:00.042)       0:00:10.393 ******
2025-09-29 19:16:09.147920 | controller | skipping: [instance]
2025-09-29 19:16:09.178028 | controller |
2025-09-29 19:16:09.178072 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-09-29 19:16:09.178080 | controller | Monday 29 September 2025  19:16:09 +0000 (0:00:00.032)       0:00:10.425 ******
2025-09-29 19:16:09.178090 | controller | skipping: [instance]
2025-09-29 19:16:09.178235 | controller |
2025-09-29 19:16:09.178405 | controller | TASK [Download the RPM] ********************************************************
2025-09-29 19:16:09.178558 | controller | Monday 29 September 2025  19:16:09 +0000 (0:00:00.030)       0:00:10.455 ******
2025-09-29 19:16:09.210956 | controller | skipping: [instance]
2025-09-29 19:16:09.211120 | controller |
2025-09-29 19:16:09.211262 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-09-29 19:16:09.211435 | controller | Monday 29 September 2025  19:16:09 +0000 (0:00:00.032)       0:00:10.488 ******
2025-09-29 19:16:09.240221 | controller | skipping: [instance]
2025-09-29 19:16:09.240449 | controller |
2025-09-29 19:16:09.240627 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-09-29 19:16:09.240809 | controller | Monday 29 September 2025  19:16:09 +0000 (0:00:00.029)       0:00:10.518 ******
2025-09-29 19:16:09.269291 | controller | skipping: [instance]
2025-09-29 19:16:09.269534 | controller |
2025-09-29 19:16:09.269717 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-09-29 19:16:09.269898 | controller | Monday 29 September 2025  19:16:09 +0000 (0:00:00.028)       0:00:10.547 ******
2025-09-29 19:16:09.300052 | controller | skipping: [instance]
2025-09-29 19:16:09.300213 | controller |
2025-09-29 19:16:09.300402 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-09-29 19:16:09.300549 | controller | Monday 29 September 2025  19:16:09 +0000 (0:00:00.030)       0:00:10.578 ******
2025-09-29 19:16:09.333066 | controller | skipping: [instance]
2025-09-29 19:16:09.333223 | controller |
2025-09-29 19:16:09.333389 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-09-29 19:16:09.333534 | controller | Monday 29 September 2025  19:16:09 +0000 (0:00:00.033)       0:00:10.611 ******
2025-09-29 19:16:09.529878 | controller | ok: [instance]
2025-09-29 19:16:09.758375 | controller |
2025-09-29 19:16:09.758412 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-09-29 19:16:09.758421 | controller | Monday 29 September 2025  19:16:09 +0000 (0:00:00.196)       0:00:10.807 ******
2025-09-29 19:16:09.758431 | controller | changed: [instance]
2025-09-29 19:16:10.005829 | controller |
2025-09-29 19:16:10.005872 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-09-29 19:16:10.005881 | controller | Monday 29 September 2025  19:16:09 +0000 (0:00:00.228)       0:00:11.035 ******
2025-09-29 19:16:10.005892 | controller | changed: [instance]
2025-09-29 19:16:10.029409 | controller |
2025-09-29 19:16:10.029454 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-09-29 19:16:10.029463 | controller | Monday 29 September 2025  19:16:10 +0000 (0:00:00.247)       0:00:11.283 ******
2025-09-29 19:16:10.029474 | controller | skipping: [instance]
2025-09-29 19:16:10.053002 | controller |
2025-09-29 19:16:10.053037 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-09-29 19:16:10.053046 | controller | Monday 29 September 2025  19:16:10 +0000 (0:00:00.023)       0:00:11.306 ******
2025-09-29 19:16:10.053059 | controller | skipping: [instance]
2025-09-29 19:16:10.077959 | controller |
2025-09-29 19:16:10.077995 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-09-29 19:16:10.078003 | controller | Monday 29 September 2025  19:16:10 +0000 (0:00:00.023)       0:00:11.330 ******
2025-09-29 19:16:10.078013 | controller | skipping: [instance]
2025-09-29 19:16:10.103685 | controller |
2025-09-29 19:16:10.103778 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-09-29 19:16:10.103789 | controller | Monday 29 September 2025  19:16:10 +0000 (0:00:00.025)       0:00:11.355 ******
2025-09-29 19:16:10.103807 | controller | skipping: [instance]
2025-09-29 19:16:10.133486 | controller |
2025-09-29 19:16:10.133548 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-09-29 19:16:10.133559 | controller | Monday 29 September 2025  19:16:10 +0000 (0:00:00.025)       0:00:11.381 ******
2025-09-29 19:16:10.133576 | controller | skipping: [instance]
2025-09-29 19:16:10.133740 | controller |
2025-09-29 19:16:10.133772 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-09-29 19:16:10.133881 | controller | Monday 29 September 2025  19:16:10 +0000 (0:00:00.029)       0:00:11.411 ******
2025-09-29 19:16:10.160496 | controller | skipping: [instance]
2025-09-29 19:16:10.160576 | controller |
2025-09-29 19:16:10.160668 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-09-29 19:16:10.160800 | controller | Monday 29 September 2025  19:16:10 +0000 (0:00:00.027)       0:00:11.438 ******
2025-09-29 19:16:10.180707 | controller | skipping: [instance]
2025-09-29 19:16:10.180834 | controller |
2025-09-29 19:16:10.180953 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-09-29 19:16:10.181071 | controller | Monday 29 September 2025  19:16:10 +0000 (0:00:00.020)       0:00:11.458 ******
2025-09-29 19:16:10.213775 | controller | skipping: [instance]
2025-09-29 19:16:10.213902 | controller |
2025-09-29 19:16:10.214022 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-09-29 19:16:10.214141 | controller | Monday 29 September 2025  19:16:10 +0000 (0:00:00.032)       0:00:11.491 ******
2025-09-29 19:16:10.233510 | controller | skipping: [instance]
2025-09-29 19:16:10.233643 | controller |
2025-09-29 19:16:10.233771 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-09-29 19:16:10.233900 | controller | Monday 29 September 2025  19:16:10 +0000 (0:00:00.020)       0:00:11.511 ******
2025-09-29 19:16:10.258665 | controller | skipping: [instance]
2025-09-29 19:16:10.258768 | controller |
2025-09-29 19:16:10.258901 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-09-29 19:16:10.259032 | controller | Monday 29 September 2025  19:16:10 +0000 (0:00:00.025)       0:00:11.536 ******
2025-09-29 19:16:10.294551 | controller | skipping: [instance]
2025-09-29 19:16:10.294694 | controller |
2025-09-29 19:16:10.294859 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-09-29 19:16:10.294977 | controller | Monday 29 September 2025  19:16:10 +0000 (0:00:00.035)       0:00:11.572 ******
2025-09-29 19:16:10.326530 | controller | skipping: [instance]
2025-09-29 19:16:10.326589 | controller |
2025-09-29 19:16:10.326737 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-09-29 19:16:10.326917 | controller | Monday 29 September 2025  19:16:10 +0000 (0:00:00.031)       0:00:11.604 ******
2025-09-29 19:16:10.359455 | controller | skipping: [instance]
2025-09-29 19:16:10.359529 | controller |
2025-09-29 19:16:10.359701 | controller | TASK [test_deps : Install additional packages] *********************************
2025-09-29 19:16:10.359864 | controller | Monday 29 September 2025  19:16:10 +0000 (0:00:00.032)       0:00:11.637 ******
2025-09-29 19:16:10.393069 | controller | skipping: [instance]
2025-09-29 19:16:10.393146 | controller |
2025-09-29 19:16:10.393286 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-09-29 19:16:10.393436 | controller | Monday 29 September 2025  19:16:10 +0000 (0:00:00.033)       0:00:11.671 ******
2025-09-29 19:16:55.841424 | controller | ok: [instance]
2025-09-29 19:16:55.841473 | controller |
2025-09-29 19:16:55.841484 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-09-29 19:16:55.841562 | controller | Monday 29 September 2025  19:16:55 +0000 (0:00:45.448)       0:00:57.119 ******
2025-09-29 19:16:57.115213 | controller | ok: [instance]
2025-09-29 19:16:57.150057 | controller |
2025-09-29 19:16:57.150093 | controller | TASK [test_deps : Install extra packages] **************************************
2025-09-29 19:16:57.150102 | controller | Monday 29 September 2025  19:16:57 +0000 (0:00:01.273)       0:00:58.392 ******
2025-09-29 19:16:57.150113 | controller | skipping: [instance]
2025-09-29 19:16:57.197431 | controller |
2025-09-29 19:16:57.197490 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-09-29 19:16:57.197500 | controller | Monday 29 September 2025  19:16:57 +0000 (0:00:00.034)       0:00:58.427 ******
2025-09-29 19:16:57.197511 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-09-29 19:16:57.218399 | controller |
2025-09-29 19:16:57.218431 | controller | TASK [ci_setup : List packages to install] *************************************
2025-09-29 19:16:57.218440 | controller | Monday 29 September 2025  19:16:57 +0000 (0:00:00.047)       0:00:58.474 ******
2025-09-29 19:16:57.218450 | controller | ok: [instance] =>
2025-09-29 19:16:57.218536 | controller |   cifmw_ci_setup_packages:
2025-09-29 19:16:57.218554 | controller |   - bash-completion
2025-09-29 19:16:57.218560 | controller |   - ca-certificates
2025-09-29 19:16:57.218566 | controller |   - git-core
2025-09-29 19:16:57.218571 | controller |   - make
2025-09-29 19:16:57.218577 | controller |   - tar
2025-09-29 19:16:57.218582 | controller |   - tmux
2025-09-29 19:16:57.218587 | controller |   - python3-pip
2025-09-29 19:16:57.218592 | controller |
2025-09-29 19:16:57.218600 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-09-29 19:16:57.218656 | controller | Monday 29 September 2025  19:16:57 +0000 (0:00:00.020)       0:00:58.495 ******
2025-09-29 19:16:58.494881 | controller | ok: [instance]
2025-09-29 19:16:58.781255 | controller |
2025-09-29 19:16:58.781299 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-09-29 19:16:58.781308 | controller | Monday 29 September 2025  19:16:58 +0000 (0:00:01.276)       0:00:59.772 ******
2025-09-29 19:16:58.781340 | controller | ok: [instance]
2025-09-29 19:16:58.994916 | controller |
2025-09-29 19:16:58.994951 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-09-29 19:16:58.994959 | controller | Monday 29 September 2025  19:16:58 +0000 (0:00:00.286)       0:01:00.058 ******
2025-09-29 19:16:58.994969 | controller | ok: [instance]
2025-09-29 19:16:59.065052 | controller |
2025-09-29 19:16:59.065120 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-09-29 19:16:59.065129 | controller | Monday 29 September 2025  19:16:58 +0000 (0:00:00.213)       0:01:00.272 ******
2025-09-29 19:16:59.065140 | controller | skipping: [instance]
2025-09-29 19:16:59.104478 | controller |
2025-09-29 19:16:59.104534 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-09-29 19:16:59.104543 | controller | Monday 29 September 2025  19:16:59 +0000 (0:00:00.070)       0:01:00.342 ******
2025-09-29 19:16:59.104553 | controller | skipping: [instance]
2025-09-29 19:16:59.297123 | controller |
2025-09-29 19:16:59.297160 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-09-29 19:16:59.297169 | controller | Monday 29 September 2025  19:16:59 +0000 (0:00:00.039)       0:01:00.381 ******
2025-09-29 19:16:59.297179 | controller | ok: [instance]
2025-09-29 19:16:59.586205 | controller |
2025-09-29 19:16:59.586240 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-09-29 19:16:59.586248 | controller | Monday 29 September 2025  19:16:59 +0000 (0:00:00.192)       0:01:00.574 ******
2025-09-29 19:16:59.586265 | controller | ok: [instance]
2025-09-29 19:16:59.605436 | controller |
2025-09-29 19:16:59.605459 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-09-29 19:16:59.605468 | controller | Monday 29 September 2025  19:16:59 +0000 (0:00:00.289)       0:01:00.863 ******
2025-09-29 19:16:59.605479 | controller | skipping: [instance]
2025-09-29 19:16:59.605689 | controller |
2025-09-29 19:16:59.605705 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-09-29 19:16:59.624186 | controller | Monday 29 September 2025  19:16:59 +0000 (0:00:00.019)       0:01:00.883 ******
2025-09-29 19:16:59.624207 | controller | skipping: [instance]
2025-09-29 19:16:59.643913 | controller |
2025-09-29 19:16:59.643937 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-09-29 19:16:59.643945 | controller | Monday 29 September 2025  19:16:59 +0000 (0:00:00.018)       0:01:00.902 ******
2025-09-29 19:16:59.643956 | controller | skipping: [instance]
2025-09-29 19:16:59.662956 | controller |
2025-09-29 19:16:59.662978 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-09-29 19:16:59.662986 | controller | Monday 29 September 2025  19:16:59 +0000 (0:00:00.019)       0:01:00.921 ******
2025-09-29 19:16:59.662997 | controller | skipping: [instance]
2025-09-29 19:16:59.681475 | controller |
2025-09-29 19:16:59.681519 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-09-29 19:16:59.681527 | controller | Monday 29 September 2025  19:16:59 +0000 (0:00:00.019)       0:01:00.940 ******
2025-09-29 19:16:59.681539 | controller | skipping: [instance]
2025-09-29 19:16:59.681566 | controller |
2025-09-29 19:16:59.681574 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-09-29 19:16:59.681704 | controller | Monday 29 September 2025  19:16:59 +0000 (0:00:00.018)       0:01:00.959 ******
2025-09-29 19:16:59.705966 | controller | skipping: [instance]
2025-09-29 19:17:00.718206 | controller |
2025-09-29 19:17:00.718244 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-09-29 19:17:00.718253 | controller | Monday 29 September 2025  19:16:59 +0000 (0:00:00.024)       0:01:00.983 ******
2025-09-29 19:17:00.718263 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-09-29 19:17:00.756484 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-09-29 19:17:00.756523 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-09-29 19:17:00.756532 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-09-29 19:17:00.756537 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-09-29 19:17:00.756544 | controller |
2025-09-29 19:17:00.756550 | controller | TASK [Check for CRC startup] ***************************************************
2025-09-29 19:17:00.756555 | controller | Monday 29 September 2025  19:17:00 +0000 (0:00:01.011)       0:01:01.995 ******
2025-09-29 19:17:00.756565 | controller | skipping: [instance]
2025-09-29 19:17:00.756592 | controller |
2025-09-29 19:17:00.756606 | controller | PLAY RECAP *********************************************************************
2025-09-29 19:17:00.756612 | controller | instance                   : ok=28   changed=4    unreachable=0    failed=0    skipped=42   rescued=0    ignored=0
2025-09-29 19:17:00.756618 | controller |
2025-09-29 19:17:00.756623 | controller | Monday 29 September 2025  19:17:00 +0000 (0:00:00.038)       0:01:02.033 ******
2025-09-29 19:17:00.756630 | controller | ===============================================================================
2025-09-29 19:17:00.756652 | controller | test_deps : Install selinux python libs -------------------------------- 45.45s
2025-09-29 19:17:00.757385 | controller | Gathering Facts --------------------------------------------------------- 1.89s
2025-09-29 19:17:00.758460 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.44s
2025-09-29 19:17:00.758501 | controller | repo_setup : Initialize python venv and install requirements ------------ 1.35s
2025-09-29 19:17:00.758508 | controller | ci_setup : Install needed packages -------------------------------------- 1.28s
2025-09-29 19:17:00.758514 | controller | test_deps : Install python yaml libs ------------------------------------ 1.27s
2025-09-29 19:17:00.758519 | controller | ci_setup : Manage directories ------------------------------------------- 1.01s
2025-09-29 19:17:00.758524 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.94s
2025-09-29 19:17:00.758529 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.72s
2025-09-29 19:17:00.758539 | controller | repo_setup : Get repo-setup repository ---------------------------------- 0.63s
2025-09-29 19:17:00.823270 | controller | Inject our own NetworkManager configuration ----------------------------- 0.63s
2025-09-29 19:17:00.823329 | controller | repo_setup : Ensure directories are present ----------------------------- 0.60s
2025-09-29 19:17:00.823341 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s
2025-09-29 19:17:00.823348 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.38s
2025-09-29 19:17:00.823353 | controller | repo_setup : Install repo-setup package --------------------------------- 0.31s
2025-09-29 19:17:00.823359 | controller | ci_setup : Source completion from within .bashrc ------------------------ 0.29s
2025-09-29 19:17:00.823364 | controller | ci_setup : Gather version of openshift client --------------------------- 0.29s
2025-09-29 19:17:00.823369 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.25s
2025-09-29 19:17:00.823375 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.23s
2025-09-29 19:17:00.823380 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.22s
2025-09-29 19:17:00.823391 | controller | INFO     Running default > converge
2025-09-29 19:17:01.326913 | controller |
2025-09-29 19:17:01.327148 | controller | PLAY [Converge default] ********************************************************
2025-09-29 19:17:01.327291 | controller |
2025-09-29 19:17:01.327459 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 19:17:01.327619 | controller | Monday 29 September 2025  19:17:01 +0000 (0:00:00.030)       0:00:00.030 ******
2025-09-29 19:17:02.181531 | controller | ok: [instance]
2025-09-29 19:17:02.869395 | controller |
2025-09-29 19:17:02.869436 | controller | TASK [rhol_crc : Ensure directories exist] *************************************
2025-09-29 19:17:02.869444 | controller | Monday 29 September 2025  19:17:02 +0000 (0:00:00.854)       0:00:00.885 ******
2025-09-29 19:17:02.869455 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts)
2025-09-29 19:17:03.100177 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-09-29 19:17:03.100219 | controller | ok: [instance] => (item=/usr/local/bin)
2025-09-29 19:17:03.100227 | controller |
2025-09-29 19:17:03.100234 | controller | TASK [manage_secrets : Ensure directory tree exist] ****************************
2025-09-29 19:17:03.100240 | controller | Monday 29 September 2025  19:17:02 +0000 (0:00:00.687)       0:00:01.573 ******
2025-09-29 19:17:03.100250 | controller | changed: [instance]
2025-09-29 19:17:03.377328 | controller |
2025-09-29 19:17:03.377370 | controller | TASK [rhol_crc : Check for binary] *********************************************
2025-09-29 19:17:03.377379 | controller | Monday 29 September 2025  19:17:03 +0000 (0:00:00.230)       0:00:01.803 ******
2025-09-29 19:17:03.377389 | controller | ok: [instance]
2025-09-29 19:17:03.417102 | controller |
2025-09-29 19:17:03.417132 | controller | TASK [rhol_crc : Get versions if binary is available] **************************
2025-09-29 19:17:03.417142 | controller | Monday 29 September 2025  19:17:03 +0000 (0:00:00.276)       0:00:02.080 ******
2025-09-29 19:17:03.417154 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/rhol_crc/tasks/get_versions.yml for instance
2025-09-29 19:17:04.172487 | controller |
2025-09-29 19:17:04.172530 | controller | TASK [rhol_crc : Get CRC binary version] ***************************************
2025-09-29 19:17:04.172539 | controller | Monday 29 September 2025  19:17:03 +0000 (0:00:00.040)       0:00:02.121 ******
2025-09-29 19:17:04.172552 | controller | changed: [instance]
2025-09-29 19:17:04.214111 | controller |
2025-09-29 19:17:04.214156 | controller | TASK [rhol_crc : Extract versions] *********************************************
2025-09-29 19:17:04.214164 | controller | Monday 29 September 2025  19:17:04 +0000 (0:00:00.755)       0:00:02.876 ******
2025-09-29 19:17:04.214175 | controller | ok: [instance]
2025-09-29 19:17:04.236347 | controller |
2025-09-29 19:17:04.236386 | controller | TASK [rhol_crc : Output found binary location and versions] ********************
2025-09-29 19:17:04.236394 | controller | Monday 29 September 2025  19:17:04 +0000 (0:00:00.041)       0:00:02.917 ******
2025-09-29 19:17:04.236406 | controller | ok: [instance] =>
2025-09-29 19:17:04.613444 | controller |   msg: 'Found crc at /usr/local/bin/crc, with version 2.39.0 (wants: 2.39.0) and serving OpenShift version 4.16.0.'
2025-09-29 19:17:04.613490 | controller |
2025-09-29 19:17:04.613499 | controller | TASK [rhol_crc : Get CRC VM availability] **************************************
2025-09-29 19:17:04.613505 | controller | Monday 29 September 2025  19:17:04 +0000 (0:00:00.022)       0:00:02.940 ******
2025-09-29 19:17:04.613518 | controller | ok: [instance]
2025-09-29 19:17:04.613549 | controller |
2025-09-29 19:17:04.613724 | controller | TASK [rhol_crc : Set CRC status] ***********************************************
2025-09-29 19:17:04.613764 | controller | Monday 29 September 2025  19:17:04 +0000 (0:00:00.377)       0:00:03.317 ******
2025-09-29 19:17:04.641413 | controller | ok: [instance]
2025-09-29 19:17:04.641506 | controller |
2025-09-29 19:17:04.641539 | controller | TASK [rhol_crc : Fail if crc domain is already defined] ************************
2025-09-29 19:17:04.641555 | controller | Monday 29 September 2025  19:17:04 +0000 (0:00:00.027)       0:00:03.345 ******
2025-09-29 19:17:04.669473 | controller | skipping: [instance]
2025-09-29 19:17:04.669539 | controller |
2025-09-29 19:17:04.669550 | controller | TASK [Ensure pull-secret is in place if we need to configure CRC] **************
2025-09-29 19:17:04.669672 | controller | Monday 29 September 2025  19:17:04 +0000 (0:00:00.028)       0:00:03.373 ******
2025-09-29 19:17:04.713696 | controller |
2025-09-29 19:17:04.737462 | controller | TASK [manage_secrets : Assert we get needed data] ******************************
2025-09-29 19:17:04.737500 | controller | Monday 29 September 2025  19:17:04 +0000 (0:00:00.043)       0:00:03.417 ******
2025-09-29 19:17:04.737520 | controller | ok: [instance]
2025-09-29 19:17:04.764848 | controller |
2025-09-29 19:17:04.764902 | controller | TASK [manage_secrets : Manage secret] ******************************************
2025-09-29 19:17:04.764912 | controller | Monday 29 September 2025  19:17:04 +0000 (0:00:00.023)       0:00:03.441 ******
2025-09-29 19:17:04.764924 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/manage_secrets/tasks/_push_secret.yml for instance
2025-09-29 19:17:04.789930 | controller |
2025-09-29 19:17:04.789954 | controller | TASK [manage_secrets : Debug _secret_dest] *************************************
2025-09-29 19:17:04.789962 | controller | Monday 29 September 2025  19:17:04 +0000 (0:00:00.027)       0:00:03.468 ******
2025-09-29 19:17:04.789974 | controller | ok: [instance] =>
2025-09-29 19:17:04.790160 | controller |   _secret_dest: /home/zuul/pull-secret.json
2025-09-29 19:17:04.790290 | controller |
2025-09-29 19:17:04.790456 | controller | TASK [manage_secrets : Debug _secret_file] *************************************
2025-09-29 19:17:04.790601 | controller | Monday 29 September 2025  19:17:04 +0000 (0:00:00.025)       0:00:03.494 ******
2025-09-29 19:17:04.810989 | controller | ok: [instance] =>
2025-09-29 19:17:04.811154 | controller |   _secret_file: ''
2025-09-29 19:17:04.811286 | controller |
2025-09-29 19:17:04.811452 | controller | TASK [manage_secrets : Debug _secret_content] **********************************
2025-09-29 19:17:04.811592 | controller | Monday 29 September 2025  19:17:04 +0000 (0:00:00.021)       0:00:03.515 ******
2025-09-29 19:17:04.833551 | controller | ok: [instance] =>
2025-09-29 19:17:04.833729 | controller |   _secret_content:
2025-09-29 19:17:04.833902 | controller |     auths:
2025-09-29 19:17:04.834054 | controller |       fake:
2025-09-29 19:17:04.834190 | controller |         auth: Zm9vOmJhcgo=
2025-09-29 19:17:04.834333 | controller |
2025-09-29 19:17:04.834493 | controller | TASK [manage_secrets : Ensure parameter is an absolute path] *******************
2025-09-29 19:17:04.834629 | controller | Monday 29 September 2025  19:17:04 +0000 (0:00:00.022)       0:00:03.537 ******
2025-09-29 19:17:04.852133 | controller | skipping: [instance]
2025-09-29 19:17:04.852307 | controller |
2025-09-29 19:17:04.852489 | controller | TASK [manage_secrets : Check if pull secret src file exists] *******************
2025-09-29 19:17:04.852633 | controller | Monday 29 September 2025  19:17:04 +0000 (0:00:00.018)       0:00:03.556 ******
2025-09-29 19:17:04.872184 | controller | skipping: [instance]
2025-09-29 19:17:04.872376 | controller |
2025-09-29 19:17:04.872533 | controller | TASK [manage_secrets : Copy file to location] **********************************
2025-09-29 19:17:04.872676 | controller | Monday 29 September 2025  19:17:04 +0000 (0:00:00.019)       0:00:03.576 ******
2025-09-29 19:17:04.892951 | controller | skipping: [instance]
2025-09-29 19:17:04.893119 | controller |
2025-09-29 19:17:04.893271 | controller | TASK [manage_secrets : Create file from content] *******************************
2025-09-29 19:17:04.893440 | controller | Monday 29 September 2025  19:17:04 +0000 (0:00:00.020)       0:00:03.597 ******
2025-09-29 19:17:05.374504 | controller | changed: [instance]
2025-09-29 19:17:05.374573 | controller |
2025-09-29 19:17:05.374583 | controller | TASK [rhol_crc : Get wanted RHOL/CRC version binary if needed] *****************
2025-09-29 19:17:05.374714 | controller | Monday 29 September 2025  19:17:05 +0000 (0:00:00.481)       0:00:04.078 ******
2025-09-29 19:17:05.410469 | controller | skipping: [instance]
2025-09-29 19:17:05.449460 | controller |
2025-09-29 19:17:05.449502 | controller | TASK [rhol_crc : Setup sudoers file for sudo commands in RHOL/CRC setup] *******
2025-09-29 19:17:05.449510 | controller | Monday 29 September 2025  19:17:05 +0000 (0:00:00.035)       0:00:04.114 ******
2025-09-29 19:17:05.449521 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/rhol_crc/tasks/sudoers_grant.yml for instance
2025-09-29 19:17:05.449539 | controller |
2025-09-29 19:17:05.449545 | controller | TASK [rhol_crc : Add passwordless sudo access for CRC commands] ****************
2025-09-29 19:17:05.449553 | controller | Monday 29 September 2025  19:17:05 +0000 (0:00:00.038)       0:00:04.153 ******
2025-09-29 19:17:05.789413 | controller | changed: [instance]
2025-09-29 19:17:05.821161 | controller |
2025-09-29 19:17:05.821199 | controller | TASK [rhol_crc : Clean RHOL/CRC if wanted] *************************************
2025-09-29 19:17:05.821208 | controller | Monday 29 September 2025  19:17:05 +0000 (0:00:00.339)       0:00:04.492 ******
2025-09-29 19:17:05.821225 | controller | skipping: [instance]
2025-09-29 19:17:05.861332 | controller |
2025-09-29 19:17:05.861373 | controller | TASK [rhol_crc : Set RHOL/CRC configuration options] ***************************
2025-09-29 19:17:05.861382 | controller | Monday 29 September 2025  19:17:05 +0000 (0:00:00.032)       0:00:04.525 ******
2025-09-29 19:17:05.861393 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/rhol_crc/tasks/configuration.yml for instance
2025-09-29 19:17:07.858454 | controller |
2025-09-29 19:17:07.858493 | controller | TASK [rhol_crc : Set RHOL/CRC configuration options] ***************************
2025-09-29 19:17:07.858509 | controller | Monday 29 September 2025  19:17:05 +0000 (0:00:00.039)       0:00:04.565 ******
2025-09-29 19:17:07.858521 | controller | changed: [instance] => (item={'key': 'consent-telemetry', 'value': 'no'})
2025-09-29 19:17:07.858549 | controller | changed: [instance] => (item={'key': 'disk-size', 'value': 32})
2025-09-29 19:17:07.858556 | controller | changed: [instance] => (item={'key': 'memory', 'value': 10752})
2025-09-29 19:17:07.858563 | controller | changed: [instance] => (item={'key': 'cpus', 'value': 4})
2025-09-29 19:17:07.859401 | controller | changed: [instance] => (item={'key': 'preset', 'value': 'openshift'})
2025-09-29 19:22:29.476155 | controller | changed: [instance] => (item={'key': 'pull-secret-file', 'value': '/home/zuul/pull-secret.json'})
2025-09-29 19:22:29.476380 | controller | changed: [instance] => (item={'key': 'skip-check-daemon-systemd-unit', 'value': True})
2025-09-29 19:22:29.476396 | controller | changed: [instance] => (item={'key': 'skip-check-daemon-systemd-sockets', 'value': True})
2025-09-29 19:22:29.476403 | controller | changed: [instance] => (item={'key': 'kubeadmin-password', 'value': 12345678})
2025-09-29 19:22:29.476409 | controller |
2025-09-29 19:22:29.476414 | controller | TASK [rhol_crc : Setup RHOL/CRC] ***********************************************
2025-09-29 19:22:29.476419 | controller | Monday 29 September 2025  19:17:07 +0000 (0:00:01.997)       0:00:06.562 ******
2025-09-29 19:22:29.476429 | controller | changed: [instance]
2025-09-29 19:39:33.869680 | controller |
2025-09-29 19:39:33.869759 | controller | TASK [rhol_crc : Start RHOL/CRC] ***********************************************
2025-09-29 19:39:33.869769 | controller | Monday 29 September 2025  19:22:29 +0000 (0:05:21.616)       0:05:28.179 ******
2025-09-29 19:39:33.869780 | controller | fatal: [instance]: FAILED! => changed=true
2025-09-29 19:39:33.869804 | controller |   cmd: set -o pipefail; /usr/local/bin/crc start 2>&1 | tee /home/zuul/ci-framework-data/logs/crc-start.log
2025-09-29 19:39:33.869811 | controller |   delta: '0:17:04.128364'
2025-09-29 19:39:33.869816 | controller |   end: '2025-09-29 19:39:33.830186'
2025-09-29 19:39:33.869821 | controller |   msg: non-zero return code
2025-09-29 19:39:33.869827 | controller |   rc: 1
2025-09-29 19:39:33.869832 | controller |   start: '2025-09-29 19:22:29.701822'
2025-09-29 19:39:33.869837 | controller |   stderr: ''
2025-09-29 19:39:33.869843 | controller |   stderr_lines: <omitted>
2025-09-29 19:39:33.869850 | controller |   stdout: |-
2025-09-29 19:39:33.871271 | controller |     level=warning msg="A new version (2.54.0) has been published on https://developers.redhat.com/content-gateway/file/pub/openshift-v4/clients/crc/2.54.0/crc-linux-amd64.tar.xz"
2025-09-29 19:39:33.871295 | controller |     level=info msg="Using bundle path /home/zuul/.crc/cache/crc_libvirt_4.16.0_amd64.crcbundle"
2025-09-29 19:39:33.871307 | controller |     level=info msg="Checking if running as non-root"
2025-09-29 19:39:33.871480 | controller |     level=info msg="Checking if running inside WSL2"
2025-09-29 19:39:33.871498 | controller |     level=info msg="Checking if crc-admin-helper executable is cached"
2025-09-29 19:39:33.871504 | controller |     level=info msg="Checking if running on a supported CPU architecture"
2025-09-29 19:39:33.871510 | controller |     level=info msg="Checking if crc executable symlink exists"
2025-09-29 19:39:33.871515 | controller |     level=info msg="Checking minimum RAM requirements"
2025-09-29 19:39:33.871521 | controller |     level=info msg="Check if Podman binary exists in: /home/zuul/.crc/bin/oc"
2025-09-29 19:39:33.871526 | controller |     level=info msg="Checking if Virtualization is enabled"
2025-09-29 19:39:33.871531 | controller |     level=info msg="Checking if KVM is enabled"
2025-09-29 19:39:33.871536 | controller |     level=info msg="Checking if libvirt is installed"
2025-09-29 19:39:33.871577 | controller |     level=info msg="Checking if user is part of libvirt group"
2025-09-29 19:39:33.871584 | controller |     level=info msg="Checking if active user/process is currently part of the libvirt group"
2025-09-29 19:39:33.871589 | controller |     level=info msg="Checking if libvirt daemon is running"
2025-09-29 19:39:33.871594 | controller |     level=info msg="Checking if a supported libvirt version is installed"
2025-09-29 19:39:33.871600 | controller |     level=info msg="Checking if crc-driver-libvirt is installed"
2025-09-29 19:39:33.871605 | controller |     level=info msg="Checking crc daemon systemd socket units"
2025-09-29 19:39:33.871614 | controller |     level=warning msg="Skipping above check..."
2025-09-29 19:39:33.872204 | controller |     level=info msg="Checking if systemd-networkd is running"
2025-09-29 19:39:33.872237 | controller |     level=info msg="Checking if NetworkManager is installed"
2025-09-29 19:39:33.872376 | controller |     level=info msg="Checking if NetworkManager service is running"
2025-09-29 19:39:33.872399 | controller |     level=info msg="Checking if /etc/NetworkManager/conf.d/crc-nm-dnsmasq.conf exists"
2025-09-29 19:39:33.872405 | controller |     level=info msg="Checking if /etc/NetworkManager/dnsmasq.d/crc.conf exists"
2025-09-29 19:39:33.872410 | controller |     level=info msg="Checking if libvirt 'crc' network is available"
2025-09-29 19:39:33.872416 | controller |     level=info msg="Checking if libvirt 'crc' network is active"
2025-09-29 19:39:33.872421 | controller |     level=info msg="Loading bundle: crc_libvirt_4.16.0_amd64..."
2025-09-29 19:39:33.872426 | controller |     level=info msg="Creating CRC VM for OpenShift 4.16.0..."
2025-09-29 19:39:33.872446 | controller |     level=info msg="Generating new SSH key pair..."
2025-09-29 19:39:33.872505 | controller |     level=info msg="Generating new password for the kubeadmin user"
2025-09-29 19:39:33.872526 | controller |     level=info msg="Starting CRC VM for openshift 4.16.0..."
2025-09-29 19:39:33.872628 | controller |     level=info msg="CRC instance is running with IP 192.168.130.11"
2025-09-29 19:39:33.872749 | controller |     level=info msg="CRC VM is running"
2025-09-29 19:39:33.872857 | controller |     level=info msg="Updating authorized keys..."
2025-09-29 19:39:33.872990 | controller |     level=info msg="Resizing /dev/vda4 filesystem"
2025-09-29 19:39:33.873100 | controller |     level=info msg="Configuring shared directories"
2025-09-29 19:39:33.873210 | controller |     level=info msg="Check internal and public DNS query..."
2025-09-29 19:39:33.873360 | controller |     level=info msg="Check DNS query from host..."
2025-09-29 19:39:33.873455 | controller |     level=info msg="Verifying validity of the kubelet certificates..."
2025-09-29 19:39:33.873629 | controller |     level=info msg="Starting kubelet service"
2025-09-29 19:39:33.873733 | controller |     level=info msg="Kubelet client certificate has expired, renewing it... [will take up to 10 minutes]"
2025-09-29 19:39:33.873856 | controller |     Failed to renew TLS certificates: please check if a newer CRC release is available: Temporary error: No Pending CSR with signerName kubernetes.io/kubelet-serving (x111)
2025-09-29 19:39:33.873972 | controller |   stdout_lines: <omitted>
2025-09-29 19:39:33.879155 | controller |
2025-09-29 19:39:33.879287 | controller | TASK [rhol_crc : Revoke permissions granted in sudoers file for CRC/RHOL setup] ***
2025-09-29 19:39:33.879476 | controller | Monday 29 September 2025  19:39:33 +0000 (0:17:04.403)       0:22:32.583 ******
2025-09-29 19:39:34.117592 | controller | changed: [instance]
2025-09-29 19:39:34.117650 | controller |
2025-09-29 19:39:34.117659 | controller | PLAY RECAP *********************************************************************
2025-09-29 19:39:34.117666 | controller | instance                   : ok=22   changed=7    unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2025-09-29 19:39:34.117672 | controller |
2025-09-29 19:39:34.117680 | controller | Monday 29 September 2025  19:39:34 +0000 (0:00:00.237)       0:22:32.820 ******
2025-09-29 19:39:34.117703 | controller | ===============================================================================
2025-09-29 19:39:34.118018 | controller | rhol_crc : Start RHOL/CRC -------------------------------------------- 1024.40s
2025-09-29 19:39:34.118506 | controller | rhol_crc : Setup RHOL/CRC --------------------------------------------- 321.62s
2025-09-29 19:39:34.118529 | controller | rhol_crc : Set RHOL/CRC configuration options --------------------------- 2.00s
2025-09-29 19:39:34.118549 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-09-29 19:39:34.118602 | controller | rhol_crc : Get CRC binary version --------------------------------------- 0.76s
2025-09-29 19:39:34.118736 | controller | rhol_crc : Ensure directories exist ------------------------------------- 0.69s
2025-09-29 19:39:34.119210 | controller | manage_secrets : Create file from content ------------------------------- 0.48s
2025-09-29 19:39:34.119229 | controller | rhol_crc : Get CRC VM availability -------------------------------------- 0.38s
2025-09-29 19:39:34.119493 | controller | rhol_crc : Add passwordless sudo access for CRC commands ---------------- 0.34s
2025-09-29 19:39:34.119515 | controller | rhol_crc : Check for binary --------------------------------------------- 0.28s
2025-09-29 19:39:34.119522 | controller | rhol_crc : Revoke permissions granted in sudoers file for CRC/RHOL setup --- 0.24s
2025-09-29 19:39:34.119528 | controller | manage_secrets : Ensure directory tree exist ---------------------------- 0.23s
2025-09-29 19:39:34.119538 | controller | Ensure pull-secret is in place if we need to configure CRC -------------- 0.04s
2025-09-29 19:39:34.119566 | controller | rhol_crc : Extract versions --------------------------------------------- 0.04s
2025-09-29 19:39:34.119574 | controller | rhol_crc : Get versions if binary is available -------------------------- 0.04s
2025-09-29 19:39:34.119977 | controller | rhol_crc : Set RHOL/CRC configuration options --------------------------- 0.04s
2025-09-29 19:39:34.119989 | controller | rhol_crc : Setup sudoers file for sudo commands in RHOL/CRC setup ------- 0.04s
2025-09-29 19:39:34.230245 | controller | rhol_crc : Get wanted RHOL/CRC version binary if needed ----------------- 0.04s
2025-09-29 19:39:34.230291 | controller | rhol_crc : Clean RHOL/CRC if wanted ------------------------------------- 0.03s
2025-09-29 19:39:34.230300 | controller | rhol_crc : Fail if crc domain is already defined ------------------------ 0.03s
2025-09-29 19:39:34.230328 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/rhol_crc/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/rhol_crc/molecule/default/converge.yml']
2025-09-29 19:39:34.230644 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-09-29 19:39:34.239977 | controller | INFO     Running default > cleanup
2025-09-29 19:39:34.721468 | controller |
2025-09-29 19:39:34.721656 | controller | PLAY [Prepare] *****************************************************************
2025-09-29 19:39:34.721778 | controller |
2025-09-29 19:39:34.721898 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 19:39:34.722023 | controller | Monday 29 September 2025  19:39:34 +0000 (0:00:00.015)       0:00:00.015 ******
2025-09-29 19:39:35.677040 | controller | ok: [instance]
2025-09-29 19:39:35.677443 | controller |
2025-09-29 19:39:35.677476 | controller | TASK [rhol_crc : Check RHOL/CRC binary exists] *********************************
2025-09-29 19:39:35.677487 | controller | Monday 29 September 2025  19:39:35 +0000 (0:00:00.955)       0:00:00.971 ******
2025-09-29 19:39:35.997421 | controller | ok: [instance]
2025-09-29 19:39:35.997509 | controller |
2025-09-29 19:39:35.997636 | controller | TASK [rhol_crc : Delete RHOL/CRC instance and associated configuration] ********
2025-09-29 19:39:35.997736 | controller | Monday 29 September 2025  19:39:35 +0000 (0:00:00.319)       0:00:01.291 ******
2025-09-29 19:39:38.955594 | controller | changed: [instance]
2025-09-29 19:39:39.018770 | controller |
2025-09-29 19:39:39.018797 | controller | PLAY RECAP *********************************************************************
2025-09-29 19:39:39.018809 | controller | instance                   : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-09-29 19:39:39.018816 | controller |
2025-09-29 19:39:39.018823 | controller | Monday 29 September 2025  19:39:38 +0000 (0:00:02.958)       0:00:04.249 ******
2025-09-29 19:39:39.018830 | controller | ===============================================================================
2025-09-29 19:39:39.018837 | controller | rhol_crc : Delete RHOL/CRC instance and associated configuration -------- 2.96s
2025-09-29 19:39:39.018844 | controller | Gathering Facts --------------------------------------------------------- 0.96s
2025-09-29 19:39:39.018859 | controller | rhol_crc : Check RHOL/CRC binary exists --------------------------------- 0.32s
2025-09-29 19:39:39.018872 | controller | INFO     Running default > destroy
2025-09-29 19:39:39.019134 | controller | WARNING  Skipping, instances are delegated.
2025-09-29 19:39:39.019713 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-09-29 19:39:39.021543 | controller | INFO     Writing /tmp/report.html report.
2025-09-29 19:39:39.151537 | controller | ERROR
2025-09-29 19:39:39.151780 | controller | {
2025-09-29 19:39:39.151828 | controller |   "delta": "0:29:33.389851",
2025-09-29 19:39:39.151852 | controller |   "end": "2025-09-29 19:39:39.122511",
2025-09-29 19:39:39.151871 | controller |   "msg": "non-zero return code",
2025-09-29 19:39:39.151890 | controller |   "rc": 1,
2025-09-29 19:39:39.151931 | controller |   "start": "2025-09-29 19:10:05.732660"
2025-09-29 19:39:39.151970 | controller | }
failure
2025-09-29 19:39:39.204279 | 
2025-09-29 19:39:39.204338 | PLAY RECAP
2025-09-29 19:39:39.204383 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-09-29 19:39:39.204407 | 
2025-09-29 19:39:39.308012 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-29 19:39:39.308825 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-29 19:39:39.852994 | 
2025-09-29 19:39:39.853102 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-09-29 19:39:39.873171 | 
2025-09-29 19:39:39.873243 | TASK [Filter out host if needed]
2025-09-29 19:39:39.881667 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-09-29 19:39:39.886231 | 
2025-09-29 19:39:39.886297 | TASK [Ensure file is present]
2025-09-29 19:39:40.269766 | controller | ok
2025-09-29 19:39:40.276752 | 
2025-09-29 19:39:40.276827 | TASK [Manage molecule report file]
2025-09-29 19:39:40.806397 | controller | changed
2025-09-29 19:39:40.812629 | 
2025-09-29 19:39:40.812694 | TASK [Check if we get ci-framework-data basedir]
2025-09-29 19:39:41.054176 | controller | ok
2025-09-29 19:39:41.060261 | 
2025-09-29 19:39:41.060353 | TASK [Create ci-framework-data log directory for zuul]
2025-09-29 19:39:41.468524 | controller | changed
2025-09-29 19:39:41.474170 | 
2025-09-29 19:39:41.474268 | TASK [Copy ci-framework interesting files]
2025-09-29 19:39:41.719076 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-09-29 19:39:41.999464 | controller | changed
2025-09-29 19:39:42.004552 | 
2025-09-29 19:39:42.004622 | TASK [Get SELinux listing]
2025-09-29 19:39:42.529049 | controller | changed
2025-09-29 19:39:42.535127 | 
2025-09-29 19:39:42.535196 | TASK [Generate log index]
2025-09-29 19:39:43.385608 | controller | changed
2025-09-29 19:39:43.390435 | 
2025-09-29 19:39:43.390498 | TASK [Get some env related data]
2025-09-29 19:39:44.046158 | controller | /home/zuul/.local/bin/ansible
2025-09-29 19:39:44.918527 | controller | changed
2025-09-29 19:39:44.923515 | 
2025-09-29 19:39:44.923578 | TASK [Generate list of logs to collect in home directory]
2025-09-29 19:39:45.274736 | controller | ok: All paths examined
2025-09-29 19:39:45.280037 | 
2025-09-29 19:39:45.280097 | LOOP [Copy logs from home directory]
2025-09-29 19:39:45.714665 | controller | changed:
2025-09-29 19:39:45.714873 | controller | {
2025-09-29 19:39:45.714903 | controller |   "atime": 1743544925.4788878,
2025-09-29 19:39:45.714972 | controller |   "ctime": 1743545329.1409318,
2025-09-29 19:39:45.714993 | controller |   "dev": 64513,
2025-09-29 19:39:45.715010 | controller |   "gid": 1000,
2025-09-29 19:39:45.715025 | controller |   "gr_name": "zuul",
2025-09-29 19:39:45.715041 | controller |   "inode": 4518807,
2025-09-29 19:39:45.715056 | controller |   "isblk": false,
2025-09-29 19:39:45.715071 | controller |   "ischr": false,
2025-09-29 19:39:45.715086 | controller |   "isdir": false,
2025-09-29 19:39:45.715101 | controller |   "isfifo": false,
2025-09-29 19:39:45.715116 | controller |   "isgid": false,
2025-09-29 19:39:45.715131 | controller |   "islnk": false,
2025-09-29 19:39:45.715146 | controller |   "isreg": true,
2025-09-29 19:39:45.715160 | controller |   "issock": false,
2025-09-29 19:39:45.715175 | controller |   "isuid": false,
2025-09-29 19:39:45.715188 | controller |   "mode": "0644",
2025-09-29 19:39:45.715202 | controller |   "mtime": 1743545329.1409318,
2025-09-29 19:39:45.715217 | controller |   "nlink": 1,
2025-09-29 19:39:45.715238 | controller |   "path": "/home/zuul/crc-setup.log",
2025-09-29 19:39:45.715258 | controller |   "pw_name": "zuul",
2025-09-29 19:39:45.715274 | controller |   "rgrp": true,
2025-09-29 19:39:45.715289 | controller |   "roth": true,
2025-09-29 19:39:45.715304 | controller |   "rusr": true,
2025-09-29 19:39:45.715318 | controller |   "size": 4108,
2025-09-29 19:39:45.715332 | controller |   "uid": 1000,
2025-09-29 19:39:45.715346 | controller |   "wgrp": false,
2025-09-29 19:39:45.715360 | controller |   "woth": false,
2025-09-29 19:39:45.715376 | controller |   "wusr": true,
2025-09-29 19:39:45.715392 | controller |   "xgrp": false,
2025-09-29 19:39:45.715406 | controller |   "xoth": false,
2025-09-29 19:39:45.715421 | controller |   "xusr": false
2025-09-29 19:39:45.715435 | controller | }
2025-09-29 19:39:46.104285 | controller | changed:
2025-09-29 19:39:46.104379 | controller | {
2025-09-29 19:39:46.104404 | controller |   "atime": 1743545331.0429637,
2025-09-29 19:39:46.104423 | controller |   "ctime": 1743545842.0111232,
2025-09-29 19:39:46.104440 | controller |   "dev": 64513,
2025-09-29 19:39:46.104456 | controller |   "gid": 1000,
2025-09-29 19:39:46.104471 | controller |   "gr_name": "zuul",
2025-09-29 19:39:46.104486 | controller |   "inode": 4194437,
2025-09-29 19:39:46.104500 | controller |   "isblk": false,
2025-09-29 19:39:46.104513 | controller |   "ischr": false,
2025-09-29 19:39:46.104526 | controller |   "isdir": false,
2025-09-29 19:39:46.104539 | controller |   "isfifo": false,
2025-09-29 19:39:46.104552 | controller |   "isgid": false,
2025-09-29 19:39:46.104564 | controller |   "islnk": false,
2025-09-29 19:39:46.104576 | controller |   "isreg": true,
2025-09-29 19:39:46.104589 | controller |   "issock": false,
2025-09-29 19:39:46.104602 | controller |   "isuid": false,
2025-09-29 19:39:46.104616 | controller |   "mode": "0644",
2025-09-29 19:39:46.104630 | controller |   "mtime": 1743545842.0111232,
2025-09-29 19:39:46.104644 | controller |   "nlink": 1,
2025-09-29 19:39:46.104657 | controller |   "path": "/home/zuul/crc-start.log",
2025-09-29 19:39:46.104670 | controller |   "pw_name": "zuul",
2025-09-29 19:39:46.104684 | controller |   "rgrp": true,
2025-09-29 19:39:46.104697 | controller |   "roth": true,
2025-09-29 19:39:46.104711 | controller |   "rusr": true,
2025-09-29 19:39:46.104725 | controller |   "size": 4023,
2025-09-29 19:39:46.104738 | controller |   "uid": 1000,
2025-09-29 19:39:46.104752 | controller |   "wgrp": false,
2025-09-29 19:39:46.104765 | controller |   "woth": false,
2025-09-29 19:39:46.104778 | controller |   "wusr": true,
2025-09-29 19:39:46.104791 | controller |   "xgrp": false,
2025-09-29 19:39:46.104804 | controller |   "xoth": false,
2025-09-29 19:39:46.104817 | controller |   "xusr": false
2025-09-29 19:39:46.104830 | controller | }
2025-09-29 19:39:46.508517 | controller | changed:
2025-09-29 19:39:46.508619 | controller | {
2025-09-29 19:39:46.508645 | controller |   "atime": 1759172988.0687373,
2025-09-29 19:39:46.508666 | controller |   "ctime": 1759173003.5936286,
2025-09-29 19:39:46.508683 | controller |   "dev": 64513,
2025-09-29 19:39:46.508700 | controller |   "gid": 1000,
2025-09-29 19:39:46.508716 | controller |   "gr_name": "zuul",
2025-09-29 19:39:46.508730 | controller |   "inode": 4233905,
2025-09-29 19:39:46.508744 | controller |   "isblk": false,
2025-09-29 19:39:46.508758 | controller |   "ischr": false,
2025-09-29 19:39:46.508773 | controller |   "isdir": false,
2025-09-29 19:39:46.508788 | controller |   "isfifo": false,
2025-09-29 19:39:46.508802 | controller |   "isgid": false,
2025-09-29 19:39:46.508816 | controller |   "islnk": false,
2025-09-29 19:39:46.508829 | controller |   "isreg": true,
2025-09-29 19:39:46.508843 | controller |   "issock": false,
2025-09-29 19:39:46.508856 | controller |   "isuid": false,
2025-09-29 19:39:46.508870 | controller |   "mode": "0644",
2025-09-29 19:39:46.508882 | controller |   "mtime": 1759173003.5936286,
2025-09-29 19:39:46.508896 | controller |   "nlink": 1,
2025-09-29 19:39:46.508982 | controller |   "path": "/home/zuul/ansible.log",
2025-09-29 19:39:46.509018 | controller |   "pw_name": "zuul",
2025-09-29 19:39:46.509038 | controller |   "rgrp": true,
2025-09-29 19:39:46.509059 | controller |   "roth": true,
2025-09-29 19:39:46.509081 | controller |   "rusr": true,
2025-09-29 19:39:46.509097 | controller |   "size": 6749,
2025-09-29 19:39:46.509117 | controller |   "uid": 1000,
2025-09-29 19:39:46.509135 | controller |   "wgrp": false,
2025-09-29 19:39:46.509149 | controller |   "woth": false,
2025-09-29 19:39:46.509163 | controller |   "wusr": true,
2025-09-29 19:39:46.509183 | controller |   "xgrp": false,
2025-09-29 19:39:46.509197 | controller |   "xoth": false,
2025-09-29 19:39:46.509210 | controller |   "xusr": false
2025-09-29 19:39:46.509224 | controller | }
2025-09-29 19:39:46.518282 | 
2025-09-29 19:39:46.518378 | TASK [Copy crio stats log file]
2025-09-29 19:39:46.533254 | controller | skipping: Conditional result was False
2025-09-29 19:39:46.538744 | 
2025-09-29 19:39:46.538815 | TASK [Get SELinux related data]
2025-09-29 19:39:46.794070 | controller | <no matches>
2025-09-29 19:39:47.066482 | controller | ERROR
2025-09-29 19:39:47.066608 | controller | {
2025-09-29 19:39:47.066641 | controller |   "delta": "0:00:00.012997",
2025-09-29 19:39:47.066662 | controller |   "end": "2025-09-29 19:39:46.795174",
2025-09-29 19:39:47.066679 | controller |   "msg": "non-zero return code",
2025-09-29 19:39:47.066695 | controller |   "rc": 1,
2025-09-29 19:39:47.066711 | controller |   "start": "2025-09-29 19:39:46.782177"
2025-09-29 19:39:47.066726 | controller | }
2025-09-29 19:39:47.066748 | controller | ERROR: Ignoring Errors
2025-09-29 19:39:47.072040 | 
2025-09-29 19:39:47.072103 | TASK [Create system configuration directory]
2025-09-29 19:39:47.293246 | controller | changed
2025-09-29 19:39:47.298197 | 
2025-09-29 19:39:47.298272 | TASK [Get some of the system configurations]
2025-09-29 19:39:47.825999 | controller | changed
2025-09-29 19:39:47.830874 | 
2025-09-29 19:39:47.830965 | TASK [Copy generated documentation if available]
2025-09-29 19:39:47.854230 | controller | skipping: Conditional result was False
2025-09-29 19:39:47.859955 | 
2025-09-29 19:39:47.860019 | TASK [Copy generated AsciiDoc documentation if available]
2025-09-29 19:39:47.872717 | controller | skipping: Conditional result was False
2025-09-29 19:39:47.878775 | 
2025-09-29 19:39:47.878836 | TASK [Compress logs bigger than 2MB]
2025-09-29 19:39:48.407247 | controller | changed
2025-09-29 19:39:48.413160 | 
2025-09-29 19:39:48.413340 | TASK [Copy files from workspace on node]
2025-09-29 19:39:48.431752 | controller | ok
2025-09-29 19:39:48.453762 | 
2025-09-29 19:39:48.453837 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-29 19:39:48.466842 | controller | skipping: Conditional result was False
2025-09-29 19:39:48.472372 | 
2025-09-29 19:39:48.472442 | TASK [fetch-output : Set log path for single node]
2025-09-29 19:39:48.501029 | controller | ok
2025-09-29 19:39:48.506401 | 
2025-09-29 19:39:48.506487 | LOOP [fetch-output : Ensure local output dirs]
2025-09-29 19:39:48.703619 | controller -> localhost | ok: "/var/lib/zuul/builds/f53484b48fb141879ba1a3d52732b233/work/logs"
2025-09-29 19:39:48.703869 | controller -> localhost | changed: All items complete
2025-09-29 19:39:48.703938 | 
2025-09-29 19:39:48.894602 | controller -> localhost | changed: "/var/lib/zuul/builds/f53484b48fb141879ba1a3d52732b233/work/artifacts"
2025-09-29 19:39:49.074220 | controller -> localhost | changed: "/var/lib/zuul/builds/f53484b48fb141879ba1a3d52732b233/work/docs"
2025-09-29 19:39:49.083352 | 
2025-09-29 19:39:49.083454 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-29 19:39:49.673301 | controller | changed:
2025-09-29 19:39:49.673503 | controller | .d..t...... ./
2025-09-29 19:39:49.673533 | controller | >f+++++++++ README.html
2025-09-29 19:39:49.673554 | controller | >f+++++++++ ansible-execution.log
2025-09-29 19:39:49.673573 | controller | >f+++++++++ ansible.log
2025-09-29 19:39:49.673591 | controller | >f+++++++++ crc-setup.log
2025-09-29 19:39:49.673613 | controller | >f+++++++++ crc-start.log
2025-09-29 19:39:49.673630 | controller | >f+++++++++ dmesg.log
2025-09-29 19:39:49.673651 | controller | >f+++++++++ installed-pkgs.log
2025-09-29 19:39:49.673669 | controller | >f+++++++++ python.log
2025-09-29 19:39:49.673686 | controller | >f+++++++++ registries.conf
2025-09-29 19:39:49.673703 | controller | >f+++++++++ report.html
2025-09-29 19:39:49.673719 | controller | >f+++++++++ selinux-denials.log
2025-09-29 19:39:49.673736 | controller | >f+++++++++ selinux-listing.log
2025-09-29 19:39:49.673753 | controller | cd+++++++++ ci-framework-data/
2025-09-29 19:39:49.673769 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-09-29 19:39:49.673784 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-09-29 19:39:49.673800 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-09-29 19:39:49.673815 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-09-29 19:39:49.673829 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-09-29 19:39:49.673843 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-09-29 19:39:49.673857 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-09-29 19:39:49.673871 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-09-29 19:39:49.673886 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-09-29 19:39:49.673901 | controller | cd+++++++++ ci-framework-data/logs/
2025-09-29 19:39:49.673953 | controller | >f+++++++++ ci-framework-data/logs/crc-config.log
2025-09-29 19:39:49.673972 | controller | >f+++++++++ ci-framework-data/logs/crc-setup.log
2025-09-29 19:39:49.673989 | controller | >f+++++++++ ci-framework-data/logs/crc-start-add_crc_creds
2025-09-29 19:39:49.674004 | controller | >f+++++++++ ci-framework-data/logs/crc-start.log
2025-09-29 19:39:49.674019 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-09-29 19:39:49.674034 | controller | cd+++++++++ registries.conf.d/
2025-09-29 19:39:49.674049 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-09-29 19:39:49.674064 | controller | cd+++++++++ system-config/
2025-09-29 19:39:49.674079 | controller | cd+++++++++ system-config/libvirt/
2025-09-29 19:39:49.674093 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-09-29 19:39:49.674107 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-09-29 19:39:49.674122 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-09-29 19:39:49.674136 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-09-29 19:39:49.674150 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-09-29 19:39:49.674164 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-09-29 19:39:49.674177 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-09-29 19:39:49.674191 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-09-29 19:39:49.674204 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-09-29 19:39:49.674218 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-09-29 19:39:49.674231 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-09-29 19:39:49.674245 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-09-29 19:39:49.674258 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-09-29 19:39:49.674272 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-09-29 19:39:49.674285 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-09-29 19:39:49.674298 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-09-29 19:39:50.093963 | controller | changed: .d..t...... ./
2025-09-29 19:39:50.510272 | controller | changed: .d..t...... ./
2025-09-29 19:39:50.528831 | 
2025-09-29 19:39:50.528980 | TASK [Return artifact to Zuul]
2025-09-29 19:39:50.557966 | controller | ok
2025-09-29 19:39:50.577141 | 
2025-09-29 19:39:50.577204 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-09-29 19:39:50.577304 | 
2025-09-29 19:39:50.577332 | PLAY RECAP
2025-09-29 19:39:50.577369 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-09-29 19:39:50.577391 | 
2025-09-29 19:39:50.657031 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-29 19:39:50.657754 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-29 19:39:51.175255 | 
2025-09-29 19:39:51.175358 | PLAY [all]
2025-09-29 19:39:51.193714 | 
2025-09-29 19:39:51.193789 | TASK [include_role : fetch-output]
2025-09-29 19:39:51.221965 | controller | ok
2025-09-29 19:39:51.238472 | 
2025-09-29 19:39:51.238554 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-29 19:39:51.288680 | controller | skipping: Conditional result was False
2025-09-29 19:39:51.300301 | 
2025-09-29 19:39:51.300377 | TASK [fetch-output : Set log path for single node]
2025-09-29 19:39:51.328692 | controller | ok
2025-09-29 19:39:51.333697 | 
2025-09-29 19:39:51.333767 | LOOP [fetch-output : Ensure local output dirs]
2025-09-29 19:39:51.653982 | controller -> localhost | ok: "/var/lib/zuul/builds/f53484b48fb141879ba1a3d52732b233/work/logs"
2025-09-29 19:39:51.835478 | controller -> localhost | ok: "/var/lib/zuul/builds/f53484b48fb141879ba1a3d52732b233/work/artifacts"
2025-09-29 19:39:52.016031 | controller -> localhost | ok: "/var/lib/zuul/builds/f53484b48fb141879ba1a3d52732b233/work/docs"
2025-09-29 19:39:52.024079 | 
2025-09-29 19:39:52.024177 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-29 19:39:52.584809 | controller | ok
2025-09-29 19:39:52.585005 | controller | ok: All items complete
2025-09-29 19:39:52.585046 | 
2025-09-29 19:39:53.020505 | controller | ok
2025-09-29 19:39:53.439608 | controller | ok
2025-09-29 19:39:53.456117 | 
2025-09-29 19:39:53.456186 | TASK [include_role : fetch-output-openshift]
2025-09-29 19:39:53.469515 | controller | skipping: Conditional result was False
2025-09-29 19:39:53.475122 | 
2025-09-29 19:39:53.475190 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-09-29 19:39:53.813371 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006483
2025-09-29 19:39:54.000480 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005799
2025-09-29 19:39:54.028169 | 
2025-09-29 19:39:54.028235 | PLAY [all]
2025-09-29 19:39:54.042037 | 
2025-09-29 19:39:54.042104 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-09-29 19:39:54.527284 | controller | changed
2025-09-29 19:39:54.548397 | 
2025-09-29 19:39:54.548451 | PLAY RECAP
2025-09-29 19:39:54.548494 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-29 19:39:54.548516 | 
2025-09-29 19:39:54.618762 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-29 19:39:54.619485 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-09-29 19:39:55.160020 | 
2025-09-29 19:39:55.160127 | PLAY [localhost]
2025-09-29 19:39:55.177243 | 
2025-09-29 19:39:55.177318 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-09-29 19:39:55.477893 | localhost | changed
2025-09-29 19:39:55.482411 | 
2025-09-29 19:39:55.482499 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-09-29 19:39:55.499804 | localhost | ok
2025-09-29 19:39:55.507638 | 
2025-09-29 19:39:55.507709 | TASK [add-fileserver : Create SSH private key tempfile]
2025-09-29 19:39:55.798980 | localhost | changed
2025-09-29 19:39:55.804361 | 
2025-09-29 19:39:55.804431 | TASK [add-fileserver : Create SSH private key from secret]
2025-09-29 19:39:56.338341 | localhost | changed
2025-09-29 19:39:56.343175 | 
2025-09-29 19:39:56.343251 | TASK [add-fileserver : Add fileserver ssh key]
2025-09-29 19:39:56.663818 | localhost | Identity added: /var/lib/zuul/builds/f53484b48fb141879ba1a3d52732b233/work/tmp/ansible.n5gwpstt (/var/lib/zuul/builds/f53484b48fb141879ba1a3d52732b233/work/tmp/ansible.n5gwpstt)
2025-09-29 19:39:56.664009 | localhost | ok: Runtime: 0:00:00.006769
2025-09-29 19:39:56.668112 | 
2025-09-29 19:39:56.668178 | TASK [add-fileserver : Remove SSH private key from disk]
2025-09-29 19:39:56.878362 | localhost | ok: Runtime: 0:00:00.004440
2025-09-29 19:39:56.883465 | 
2025-09-29 19:39:56.883572 | TASK [add-fileserver : Add fileserver to inventory]
2025-09-29 19:39:56.931207 | localhost | changed
2025-09-29 19:39:56.936222 | 
2025-09-29 19:39:56.936291 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-09-29 19:39:57.236093 | localhost | changed
2025-09-29 19:39:57.255615 | 
2025-09-29 19:39:57.255673 | PLAY [localhost]
2025-09-29 19:39:57.267295 | 
2025-09-29 19:39:57.267357 | TASK [Generate bulk log download script]
2025-09-29 19:39:57.285023 | localhost | ok
2025-09-29 19:39:57.296240 | 
2025-09-29 19:39:57.296304 | TASK [local-log-download : Check API endpoint is defined]
2025-09-29 19:39:57.322942 | localhost | ok: All assertions passed
2025-09-29 19:39:57.327201 | 
2025-09-29 19:39:57.327326 | TASK [local-log-download : Create download script]
2025-09-29 19:39:57.664070 | localhost -> localhost | changed
2025-09-29 19:39:57.672869 | 
2025-09-29 19:39:57.672976 | TASK [Register quick-download link]
2025-09-29 19:39:57.690081 | localhost | ok
2025-09-29 19:39:57.724705 | 
2025-09-29 19:39:57.724787 | PLAY [logserver.rdoproject.org]
2025-09-29 19:39:57.734270 | 
2025-09-29 19:39:57.734331 | TASK [Set zuul-log-path fact]
2025-09-29 19:39:57.749882 | logserver.rdoproject.org | ok
2025-09-29 19:39:57.758721 | 
2025-09-29 19:39:57.758788 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-29 19:39:57.784783 | logserver.rdoproject.org | ok
2025-09-29 19:39:57.790043 | 
2025-09-29 19:39:57.790114 | TASK [upload-logs : Create log directories]
2025-09-29 19:39:59.962572 | logserver.rdoproject.org | changed
2025-09-29 19:39:59.965585 | 
2025-09-29 19:39:59.965650 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-09-29 19:40:00.192553 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004809
2025-09-29 19:40:00.197192 | 
2025-09-29 19:40:00.197253 | TASK [upload-logs : Upload logs to log server]
2025-09-29 19:40:01.721536 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-09-29 19:40:01.724543 | 
2025-09-29 19:40:01.724617 | LOOP [upload-logs : Compress console log and json output]
2025-09-29 19:40:01.761829 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 19:40:01.769449 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 19:40:01.781780 | 
2025-09-29 19:40:01.781862 | LOOP [upload-logs : Upload compressed console log and json output]
2025-09-29 19:40:01.816024 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 19:40:01.816242 | 
2025-09-29 19:40:01.819519 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 19:40:01.828888 | 
2025-09-29 19:40:01.829001 | LOOP [upload-logs : Upload console log and json output]