2025-10-08 00:00:29.900005 | Job console starting...
2025-10-08 00:00:30.150614 | Updating repositories
2025-10-08 00:00:30.848402 | Preparing job workspace
2025-10-08 00:00:37.855532 | Running Ansible setup...
2025-10-08 00:00:41.335375 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 00:00:41.810975 | 
2025-10-08 00:00:41.811075 | PLAY [localhost]
2025-10-08 00:00:41.819030 | 
2025-10-08 00:00:41.819098 | TASK [Gathering Facts]
2025-10-08 00:00:42.574267 | localhost | ok
2025-10-08 00:00:42.587075 | 
2025-10-08 00:00:42.587171 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-08 00:00:42.883474 | localhost -> localhost | changed
2025-10-08 00:00:42.888252 | 
2025-10-08 00:00:42.888320 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-08 00:00:43.532111 | localhost -> localhost | changed
2025-10-08 00:00:43.540323 | 
2025-10-08 00:00:43.540414 | TASK [Setup log path fact]
2025-10-08 00:00:43.557085 | localhost | ok
2025-10-08 00:00:43.567546 | 
2025-10-08 00:00:43.567628 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 00:00:43.584699 | localhost | ok
2025-10-08 00:00:43.591791 | 
2025-10-08 00:00:43.591859 | TASK [emit-job-header : Print job information]
2025-10-08 00:00:43.618508 | # Job Information
2025-10-08 00:00:43.618625 | Ansible Version: 2.15.12
2025-10-08 00:00:43.618659 | Job: cifmw-molecule-rhol_crc
2025-10-08 00:00:43.618681 | Pipeline: github-check
2025-10-08 00:00:43.618701 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-08 00:00:43.618720 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3368
2025-10-08 00:00:43.618739 | Log URL (when completed): https://logserver.rdoproject.org/5dc/rdoproject.org/5dc25eb6609d4a449d9bf33269c5cd73/
2025-10-08 00:00:43.618758 | Event ID: baecefb0-a395-11f0-9567-2a348cffbde7
2025-10-08 00:00:43.622334 | 
2025-10-08 00:00:43.622398 | LOOP [emit-job-header : Print node information]
2025-10-08 00:00:43.705214 | localhost | ok:
2025-10-08 00:00:43.705387 | localhost | # Node Information
2025-10-08 00:00:43.705417 | localhost | Inventory Hostname: controller
2025-10-08 00:00:43.705445 | localhost | Hostname: np0005475050
2025-10-08 00:00:43.705466 | localhost | Username: zuul
2025-10-08 00:00:43.705485 | localhost | Distro: CentOS 9
2025-10-08 00:00:43.705503 | localhost | Provider: ibm-bm4-nodepool
2025-10-08 00:00:43.705520 | localhost | Region: regionOne
2025-10-08 00:00:43.705535 | localhost | Label: centos-9-stream-crc-2-48-0-xxl-ibm
2025-10-08 00:00:43.705551 | localhost | Product Name: OpenStack Compute
2025-10-08 00:00:43.705566 | localhost | Interface IP: 192.168.26.15
2025-10-08 00:00:43.723607 | 
2025-10-08 00:00:43.723662 | PLAY [all]
2025-10-08 00:00:43.729638 | 
2025-10-08 00:00:43.729700 | TASK [Gather network facts]
2025-10-08 00:00:44.174861 | controller | ok
2025-10-08 00:00:44.188570 | 
2025-10-08 00:00:44.188646 | TASK [include_role : start-zuul-console]
2025-10-08 00:00:44.206809 | controller | ok
2025-10-08 00:00:44.216974 | 
2025-10-08 00:00:44.217034 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-08 00:00:44.579138 | controller | ok
2025-10-08 00:00:44.587187 | 
2025-10-08 00:00:44.587248 | TASK [include_role : add-build-sshkey]
2025-10-08 00:00:44.604379 | controller | ok
2025-10-08 00:00:44.616453 | 
2025-10-08 00:00:44.616515 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-08 00:00:44.791564 | controller -> localhost | ok
2025-10-08 00:00:44.796532 | 
2025-10-08 00:00:44.796606 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-08 00:00:44.814414 | controller | ok
2025-10-08 00:00:44.825493 | controller | included: /var/lib/zuul/builds/5dc25eb6609d4a449d9bf33269c5cd73/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-08 00:00:44.830545 | 
2025-10-08 00:00:44.830611 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-08 00:00:45.188201 | controller -> localhost | Generating public/private rsa key pair.
2025-10-08 00:00:45.188410 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5dc25eb6609d4a449d9bf33269c5cd73/work/5dc25eb6609d4a449d9bf33269c5cd73_id_rsa.
2025-10-08 00:00:45.188443 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5dc25eb6609d4a449d9bf33269c5cd73/work/5dc25eb6609d4a449d9bf33269c5cd73_id_rsa.pub.
2025-10-08 00:00:45.188467 | controller -> localhost | The key fingerprint is:
2025-10-08 00:00:45.188488 | controller -> localhost | SHA256:h+BYbw0EUFpOQM5ielBuQFfEKnxCy7HlbiNUid1ApeI zuul-build-sshkey
2025-10-08 00:00:45.188508 | controller -> localhost | The key's randomart image is:
2025-10-08 00:00:45.188528 | controller -> localhost | +---[RSA 3072]----+
2025-10-08 00:00:45.188547 | controller -> localhost | |o.==%B=..        |
2025-10-08 00:00:45.188566 | controller -> localhost | | B.B.B .         |
2025-10-08 00:00:45.188585 | controller -> localhost | |=.%.= + .        |
2025-10-08 00:00:45.188615 | controller -> localhost | |.&.= + o +       |
2025-10-08 00:00:45.188636 | controller -> localhost | |oE* . . S o      |
2025-10-08 00:00:45.188654 | controller -> localhost | | o +   . .       |
2025-10-08 00:00:45.188672 | controller -> localhost | |  o .            |
2025-10-08 00:00:45.188694 | controller -> localhost | |                 |
2025-10-08 00:00:45.188714 | controller -> localhost | |                 |
2025-10-08 00:00:45.188733 | controller -> localhost | +----[SHA256]-----+
2025-10-08 00:00:45.188781 | controller -> localhost | ok: Runtime: 0:00:00.058659
2025-10-08 00:00:45.194470 | 
2025-10-08 00:00:45.194536 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-08 00:00:45.211702 | controller | ok
2025-10-08 00:00:45.220354 | controller | included: /var/lib/zuul/builds/5dc25eb6609d4a449d9bf33269c5cd73/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-08 00:00:45.227365 | 
2025-10-08 00:00:45.227429 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-08 00:00:45.240060 | controller | skipping: Conditional result was False
2025-10-08 00:00:45.244863 | 
2025-10-08 00:00:45.244943 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-08 00:00:45.718434 | controller | changed
2025-10-08 00:00:45.723001 | 
2025-10-08 00:00:45.723064 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-08 00:00:45.994175 | controller | ok
2025-10-08 00:00:45.998820 | 
2025-10-08 00:00:45.998883 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-08 00:00:47.170973 | controller | changed
2025-10-08 00:00:47.175687 | 
2025-10-08 00:00:47.175753 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-08 00:00:48.329432 | controller | changed
2025-10-08 00:00:48.334097 | 
2025-10-08 00:00:48.334159 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-08 00:00:48.347313 | controller | skipping: Conditional result was False
2025-10-08 00:00:48.352731 | 
2025-10-08 00:00:48.352797 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-08 00:00:48.639450 | controller -> localhost | changed
2025-10-08 00:00:48.648563 | 
2025-10-08 00:00:48.648635 | TASK [add-build-sshkey : Add back temp key]
2025-10-08 00:00:48.854044 | controller -> localhost | Identity added: /var/lib/zuul/builds/5dc25eb6609d4a449d9bf33269c5cd73/work/5dc25eb6609d4a449d9bf33269c5cd73_id_rsa (zuul-build-sshkey)
2025-10-08 00:00:48.854190 | controller -> localhost | ok: Runtime: 0:00:00.006214
2025-10-08 00:00:48.859279 | 
2025-10-08 00:00:48.859333 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-08 00:00:49.212244 | controller | ok
2025-10-08 00:00:49.216511 | 
2025-10-08 00:00:49.216572 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-08 00:00:49.240084 | controller | skipping: Conditional result was False
2025-10-08 00:00:49.248207 | 
2025-10-08 00:00:49.248264 | TASK [include_role : validate-host]
2025-10-08 00:00:49.265711 | controller | ok
2025-10-08 00:00:49.282357 | 
2025-10-08 00:00:49.282419 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-08 00:00:49.309991 | controller | ok
2025-10-08 00:00:49.314055 | 
2025-10-08 00:00:49.314112 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-08 00:00:49.489273 | controller -> localhost | ok
2025-10-08 00:00:49.494961 | 
2025-10-08 00:00:49.495044 | TASK [validate-host : Collect information about the host]
2025-10-08 00:00:50.217544 | controller | ok
2025-10-08 00:00:50.224884 | 
2025-10-08 00:00:50.224955 | TASK [validate-host : Sanitize hostname]
2025-10-08 00:00:50.262450 | controller | ok
2025-10-08 00:00:50.266863 | 
2025-10-08 00:00:50.266936 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-08 00:00:50.604738 | controller -> localhost | changed
2025-10-08 00:00:50.609657 | 
2025-10-08 00:00:50.609718 | TASK [validate-host : Collect information about zuul worker]
2025-10-08 00:00:50.995185 | controller | ok
2025-10-08 00:00:50.999390 | 
2025-10-08 00:00:50.999455 | TASK [validate-host : Write out all zuul information for each host]
2025-10-08 00:00:51.336722 | controller -> localhost | changed
2025-10-08 00:00:51.345405 | 
2025-10-08 00:00:51.345467 | TASK [include_role : prepare-workspace-openshift]
2025-10-08 00:00:51.358495 | controller | skipping: Conditional result was False
2025-10-08 00:00:51.363374 | 
2025-10-08 00:00:51.363437 | TASK [include_role : remove-zuul-sshkey]
2025-10-08 00:00:51.376423 | controller | skipping: Conditional result was False
2025-10-08 00:00:51.381080 | 
2025-10-08 00:00:51.381139 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-08 00:00:51.645832 | controller | ok: "logs"
2025-10-08 00:00:51.646031 | controller | ok: All items complete
2025-10-08 00:00:51.646061 | 
2025-10-08 00:00:51.876523 | controller | ok: "artifacts"
2025-10-08 00:00:52.105662 | controller | ok: "docs"
2025-10-08 00:00:52.111731 | 
2025-10-08 00:00:52.111846 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-08 00:00:52.371709 | controller | changed: "logs"
2025-10-08 00:00:52.597837 | controller | changed: "artifacts"
2025-10-08 00:00:52.840704 | controller | changed: "docs"
2025-10-08 00:00:52.867754 | 
2025-10-08 00:00:52.867852 | PLAY RECAP
2025-10-08 00:00:52.867896 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 00:00:52.867945 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 00:00:52.867966 | 
2025-10-08 00:00:52.939407 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 00:00:52.940193 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 00:00:53.438892 | 
2025-10-08 00:00:53.439002 | PLAY [all]
2025-10-08 00:00:53.456792 | 
2025-10-08 00:00:53.456862 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 00:00:53.505202 | controller | ok
2025-10-08 00:00:53.509743 | 
2025-10-08 00:00:53.509821 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 00:00:53.904928 | controller | changed
2025-10-08 00:00:53.910349 | 
2025-10-08 00:00:53.910420 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 00:00:55.168600 | controller | changed
2025-10-08 00:00:55.178182 | 
2025-10-08 00:00:55.178250 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-08 00:00:55.599434 | controller | changed:
2025-10-08 00:00:55.599613 | controller | {
2025-10-08 00:00:55.599647 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-08 00:00:55.599678 | controller | }
2025-10-08 00:00:55.881650 | controller | changed:
2025-10-08 00:00:55.881841 | controller | {
2025-10-08 00:00:55.881881 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-08 00:00:55.881921 | controller | }
2025-10-08 00:00:56.166367 | controller | changed:
2025-10-08 00:00:56.166555 | controller | {
2025-10-08 00:00:56.166603 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-08 00:00:56.166627 | controller | }
2025-10-08 00:00:56.450394 | controller | changed:
2025-10-08 00:00:56.450490 | controller | {
2025-10-08 00:00:56.450519 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-08 00:00:56.450549 | controller | }
2025-10-08 00:00:56.734673 | controller | changed:
2025-10-08 00:00:56.734756 | controller | {
2025-10-08 00:00:56.734782 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-08 00:00:56.734801 | controller | }
2025-10-08 00:00:57.014325 | controller | changed:
2025-10-08 00:00:57.014386 | controller | {
2025-10-08 00:00:57.014413 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-08 00:00:57.014434 | controller | }
2025-10-08 00:00:57.291872 | controller | changed:
2025-10-08 00:00:57.291943 | controller | {
2025-10-08 00:00:57.291973 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-08 00:00:57.291994 | controller | }
2025-10-08 00:00:57.573170 | controller | changed:
2025-10-08 00:00:57.573244 | controller | {
2025-10-08 00:00:57.573273 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-08 00:00:57.573294 | controller | }
2025-10-08 00:00:57.851149 | controller | changed:
2025-10-08 00:00:57.851215 | controller | {
2025-10-08 00:00:57.851243 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-08 00:00:57.851265 | controller | }
2025-10-08 00:00:58.146056 | controller | changed:
2025-10-08 00:00:58.146138 | controller | {
2025-10-08 00:00:58.146165 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-08 00:00:58.146187 | controller | }
2025-10-08 00:00:58.398440 | controller | changed:
2025-10-08 00:00:58.398498 | controller | {
2025-10-08 00:00:58.398525 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-08 00:00:58.398545 | controller | }
2025-10-08 00:00:58.677817 | controller | changed:
2025-10-08 00:00:58.677925 | controller | {
2025-10-08 00:00:58.677958 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-08 00:00:58.677981 | controller | }
2025-10-08 00:00:58.945846 | controller | changed:
2025-10-08 00:00:58.945963 | controller | {
2025-10-08 00:00:58.945993 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-08 00:00:58.946015 | controller | }
2025-10-08 00:00:59.229150 | controller | changed:
2025-10-08 00:00:59.229229 | controller | {
2025-10-08 00:00:59.229256 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-08 00:00:59.229277 | controller | }
2025-10-08 00:00:59.504069 | controller | changed:
2025-10-08 00:00:59.504165 | controller | {
2025-10-08 00:00:59.504191 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-08 00:00:59.504212 | controller | }
2025-10-08 00:00:59.782180 | controller | changed:
2025-10-08 00:00:59.782250 | controller | {
2025-10-08 00:00:59.782275 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-08 00:00:59.782295 | controller | }
2025-10-08 00:01:00.054934 | controller | changed:
2025-10-08 00:01:00.054995 | controller | {
2025-10-08 00:01:00.055022 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-08 00:01:00.055043 | controller | }
2025-10-08 00:01:00.335894 | controller | changed:
2025-10-08 00:01:00.335959 | controller | {
2025-10-08 00:01:00.335985 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-08 00:01:00.336010 | controller | }
2025-10-08 00:01:00.615776 | controller | changed:
2025-10-08 00:01:00.615924 | controller | {
2025-10-08 00:01:00.615962 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-08 00:01:00.615985 | controller | }
2025-10-08 00:01:00.914089 | controller | changed:
2025-10-08 00:01:00.914182 | controller | {
2025-10-08 00:01:00.914207 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-08 00:01:00.914227 | controller | }
2025-10-08 00:01:01.205211 | controller | changed:
2025-10-08 00:01:01.205308 | controller | {
2025-10-08 00:01:01.205338 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-08 00:01:01.205360 | controller | }
2025-10-08 00:01:01.484036 | controller | changed:
2025-10-08 00:01:01.484084 | controller | {
2025-10-08 00:01:01.484108 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-08 00:01:01.484127 | controller | }
2025-10-08 00:01:01.780080 | controller | changed:
2025-10-08 00:01:01.780139 | controller | {
2025-10-08 00:01:01.780167 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-08 00:01:01.780188 | controller | }
2025-10-08 00:01:02.073933 | controller | changed:
2025-10-08 00:01:02.073983 | controller | {
2025-10-08 00:01:02.074009 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-08 00:01:02.074028 | controller | }
2025-10-08 00:01:02.373798 | controller | changed:
2025-10-08 00:01:02.373849 | controller | {
2025-10-08 00:01:02.373875 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-08 00:01:02.373894 | controller | }
2025-10-08 00:01:02.664063 | controller | changed:
2025-10-08 00:01:02.664124 | controller | {
2025-10-08 00:01:02.664154 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-08 00:01:02.664173 | controller | }
2025-10-08 00:01:02.684140 | 
2025-10-08 00:01:02.684206 | TASK [Set timezone to UTC]
2025-10-08 00:01:03.160167 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-08 00:01:03.164704 | 
2025-10-08 00:01:03.164766 | TASK [Create nodepool directory]
2025-10-08 00:01:03.437894 | controller | changed
2025-10-08 00:01:03.443024 | 
2025-10-08 00:01:03.443089 | TASK [Create nodepool sub_nodes file]
2025-10-08 00:01:04.412725 | controller | changed
2025-10-08 00:01:04.417545 | 
2025-10-08 00:01:04.417622 | TASK [Create nodepool sub_nodes_private file]
2025-10-08 00:01:05.419807 | controller | changed
2025-10-08 00:01:05.424770 | 
2025-10-08 00:01:05.424834 | LOOP [Populate nodepool sub_nodes file]
2025-10-08 00:01:05.464154 | 
2025-10-08 00:01:05.464295 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-08 00:01:05.494660 | 
2025-10-08 00:01:05.494797 | TASK [Create nodepool primary file]
2025-10-08 00:01:05.519105 | controller | skipping: Conditional result was False
2025-10-08 00:01:05.524520 | 
2025-10-08 00:01:05.524599 | TASK [Create nodepool node_private for this node]
2025-10-08 00:01:06.577389 | controller | changed
2025-10-08 00:01:06.582670 | 
2025-10-08 00:01:06.582731 | LOOP [Copy ssh keys to nodepool directory]
2025-10-08 00:01:06.970871 | controller | ok: Item: id_rsa Runtime: 0:00:00.004029
2025-10-08 00:01:06.971096 | 
2025-10-08 00:01:07.215531 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004613
2025-10-08 00:01:07.229692 | 
2025-10-08 00:01:07.229760 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-08 00:01:08.274113 | controller | changed
2025-10-08 00:01:08.278807 | 
2025-10-08 00:01:08.278871 | TASK [Validate sudoers config after edits]
2025-10-08 00:01:08.575688 | controller | /etc/sudoers: parsed OK
2025-10-08 00:01:08.576014 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-08 00:01:08.576030 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-08 00:01:08.576041 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-08 00:01:08.804070 | controller | ok: Runtime: 0:00:00.007527
2025-10-08 00:01:08.809222 | 
2025-10-08 00:01:08.809292 | TASK [Show the environment passed in to job shell scripts]
2025-10-08 00:01:09.080861 | controller | SHELL=/bin/bash
2025-10-08 00:01:09.080913 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-08 00:01:09.080923 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-08 00:01:09.080930 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/68/3368/83ef6805b611cc3d0acd8d5bab85c9edc12f42a4
2025-10-08 00:01:09.080936 | controller | PWD=/home/zuul
2025-10-08 00:01:09.080942 | controller | ZUUL_PIPELINE=github-check
2025-10-08 00:01:09.080948 | controller | LOGNAME=zuul
2025-10-08 00:01:09.081003 | controller | XDG_SESSION_TYPE=tty
2025-10-08 00:01:09.081012 | controller | _=/usr/bin/env
2025-10-08 00:01:09.081018 | controller | MOTD_SHOWN=pam
2025-10-08 00:01:09.081023 | controller | HOME=/home/zuul
2025-10-08 00:01:09.081028 | controller | LANG=en_US.UTF-8
2025-10-08 00:01:09.081034 | controller | SSH_CONNECTION=192.168.26.12 44420 192.168.26.15 22
2025-10-08 00:01:09.081040 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-08 00:01:09.081049 | controller | ZUUL_CHANGE_IDS=3368,83ef6805b611cc3d0acd8d5bab85c9edc12f42a4
2025-10-08 00:01:09.081054 | controller | WORKSPACE=/home/zuul/workspace
2025-10-08 00:01:09.081059 | controller | XDG_SESSION_CLASS=user
2025-10-08 00:01:09.081065 | controller | SELINUX_ROLE_REQUESTED=
2025-10-08 00:01:09.081069 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-08 00:01:09.081074 | controller | USER=zuul
2025-10-08 00:01:09.081080 | controller | ZUUL_VOTING=True
2025-10-08 00:01:09.081084 | controller | BUILD_TIMEOUT=5400000
2025-10-08 00:01:09.081089 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-08 00:01:09.081094 | controller | SHLVL=1
2025-10-08 00:01:09.081099 | controller | ZUUL_PATCHSET=83ef6805b611cc3d0acd8d5bab85c9edc12f42a4
2025-10-08 00:01:09.081104 | controller | XDG_SESSION_ID=1
2025-10-08 00:01:09.081109 | controller | ZUUL_BRANCH=main
2025-10-08 00:01:09.081114 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-08 00:01:09.081119 | controller | SSH_CLIENT=192.168.26.12 44420 22
2025-10-08 00:01:09.081124 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-08 00:01:09.081129 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-08 00:01:09.081136 | controller | which_declare=declare -f
2025-10-08 00:01:09.081141 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-08 00:01:09.081147 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-08 00:01:09.081152 | controller | ZUUL_CHANGE=3368
2025-10-08 00:01:09.081156 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-08 00:01:09.081161 | controller | ZUUL_UUID=5dc25eb6609d4a449d9bf33269c5cd73
2025-10-08 00:01:09.081167 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-08 00:01:09.081171 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-08 00:01:09.081177 | controller | }
2025-10-08 00:01:09.334422 | controller | ok: Runtime: 0:00:00.006599
2025-10-08 00:01:09.339498 | 
2025-10-08 00:01:09.339557 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-08 00:01:09.353049 | controller | skipping: Conditional result was False
2025-10-08 00:01:09.358196 | 
2025-10-08 00:01:09.358257 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-08 00:01:09.905004 | controller | skipping: Conditional result was False
2025-10-08 00:01:09.911020 | 
2025-10-08 00:01:09.911086 | TASK [Ensure legacy workspace directory]
2025-10-08 00:01:10.173874 | controller | changed
2025-10-08 00:01:10.191830 | 
2025-10-08 00:01:10.191942 | PLAY RECAP
2025-10-08 00:01:10.191991 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 00:01:10.192016 | 
2025-10-08 00:01:10.263090 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 00:01:10.263804 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 00:01:10.744687 | 
2025-10-08 00:01:10.744786 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-08 00:01:10.766459 | 
2025-10-08 00:01:10.766592 | TASK [Create zuul-output directory]
2025-10-08 00:01:11.150466 | controller | changed
2025-10-08 00:01:11.155437 | 
2025-10-08 00:01:11.155511 | TASK [Slurp Zuul inventory test]
2025-10-08 00:01:11.411799 | controller -> localhost | ok
2025-10-08 00:01:11.417646 | 
2025-10-08 00:01:11.417712 | TASK [Save zuul inventory]
2025-10-08 00:01:12.643266 | controller | changed
2025-10-08 00:01:12.648537 | 
2025-10-08 00:01:12.648613 | TASK [Save zuul vars without the change_message]
2025-10-08 00:01:13.791196 | controller | changed
2025-10-08 00:01:13.809705 | 
2025-10-08 00:01:13.809759 | PLAY RECAP
2025-10-08 00:01:13.809802 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 00:01:13.809825 | 
2025-10-08 00:01:13.869437 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 00:01:13.870172 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 00:01:14.371259 | 
2025-10-08 00:01:14.371353 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-08 00:01:14.389813 | 
2025-10-08 00:01:14.389881 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-08 00:01:14.408087 | controller | ok
2025-10-08 00:01:14.423211 | 
2025-10-08 00:01:14.423288 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 00:01:14.446884 | controller | skipping: Conditional result was False
2025-10-08 00:01:14.452430 | 
2025-10-08 00:01:14.452499 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 00:01:14.837155 | controller | ok
2025-10-08 00:01:14.842587 | 
2025-10-08 00:01:14.842656 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 00:01:15.868621 | controller | ok
2025-10-08 00:01:15.877697 | 
2025-10-08 00:01:15.877760 | TASK [Prepare workspace]
2025-10-08 00:01:15.895902 | controller | ok
2025-10-08 00:01:15.912011 | 
2025-10-08 00:01:15.912076 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-08 00:01:16.296990 | controller | ok
2025-10-08 00:01:16.302436 | 
2025-10-08 00:01:16.302502 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-08 00:01:18.668382 | controller | Output suppressed because no_log was given
2025-10-08 00:01:18.677089 | 
2025-10-08 00:01:18.677154 | LOOP [Create zuul-output directory]
2025-10-08 00:01:18.946467 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-08 00:01:19.184223 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-08 00:01:19.194842 | 
2025-10-08 00:01:19.194964 | TASK [Install required packages]
2025-10-08 00:01:37.173711 | controller | ok: Nothing to do
2025-10-08 00:01:37.178756 | 
2025-10-08 00:01:37.178816 | TASK [Install venv]
2025-10-08 00:02:34.772814 | controller | changed
2025-10-08 00:02:34.815768 | 
2025-10-08 00:02:34.815841 | PLAY RECAP
2025-10-08 00:02:34.815880 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 00:02:34.815902 | 
2025-10-08 00:02:34.874074 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 00:02:34.874885 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 00:02:35.349386 | 
2025-10-08 00:02:35.349481 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-08 00:02:35.368115 | 
2025-10-08 00:02:35.368186 | TASK [Gather required facts]
2025-10-08 00:02:35.915573 | controller | ok
2025-10-08 00:02:35.920834 | 
2025-10-08 00:02:35.920934 | TASK [Load environment var if instructed to]
2025-10-08 00:02:35.944813 | controller | skipping: Conditional result was False
2025-10-08 00:02:35.950351 | 
2025-10-08 00:02:35.950421 | TASK [Run molecule]
2025-10-08 00:02:36.914870 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-08 00:02:37.248014 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-08 00:02:53.872149 | 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-10-08 00:02:53.872576 | 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-10-08 00:02:53.873053 | 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-10-08 00:02:53.873494 | 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-10-08 00:02:53.873985 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+6451eac5 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0+6451eac5 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 00:02:53.874429 | 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-10-08 00:02:53.874885 | 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-10-08 00:02:53.875317 | 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-10-08 00:02:53.875752 | 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-10-08 00:02:53.876228 | 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-10-08 00:02:53.876638 | 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-10-08 00:02:53.877096 | 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-10-08 00:02:53.877527 | 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-10-08 00:02:53.877970 | 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-10-08 00:02:53.878365 | 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-10-08 00:02:53.888078 | controller | INFO     Running add_crc_creds > prepare
2025-10-08 00:02:54.562215 | controller |
2025-10-08 00:02:54.562265 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 00:02:54.562387 | controller |
2025-10-08 00:02:54.562476 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 00:02:54.562565 | controller | Wednesday 08 October 2025  00:02:54 +0000 (0:00:00.014)       0:00:00.014 *****
2025-10-08 00:02:55.405006 | controller | ok: [instance]
2025-10-08 00:02:55.405049 | controller |
2025-10-08 00:02:55.405166 | controller | TASK [Setup libvirt] ***********************************************************
2025-10-08 00:02:55.405256 | controller | Wednesday 08 October 2025  00:02:55 +0000 (0:00:00.843)       0:00:00.858 *****
2025-10-08 00:02:55.432207 | controller | skipping: [instance]
2025-10-08 00:02:55.432317 | controller |
2025-10-08 00:02:55.432484 | controller | TASK [Setup CRC] ***************************************************************
2025-10-08 00:02:55.432594 | controller | Wednesday 08 October 2025  00:02:55 +0000 (0:00:00.027)       0:00:00.885 *****
2025-10-08 00:02:55.461248 | controller | skipping: [instance]
2025-10-08 00:02:55.461343 | controller |
2025-10-08 00:02:55.461519 | controller | TASK [Start RHOL/CRC] **********************************************************
2025-10-08 00:02:55.461617 | controller | Wednesday 08 October 2025  00:02:55 +0000 (0:00:00.029)       0:00:00.914 *****
2025-10-08 00:02:55.867091 | controller | changed: [instance]
2025-10-08 00:02:55.867253 | controller |
2025-10-08 00:02:55.867439 | controller | TASK [Inject our own NetworkManager configuration] *****************************
2025-10-08 00:02:55.867611 | controller | Wednesday 08 October 2025  00:02:55 +0000 (0:00:00.405)       0:00:01.320 *****
2025-10-08 00:02:56.467915 | controller | changed: [instance]
2025-10-08 00:02:56.467990 | controller |
2025-10-08 00:02:56.468097 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-08 00:02:56.468219 | controller | Wednesday 08 October 2025  00:02:56 +0000 (0:00:00.600)       0:00:01.921 *****
2025-10-08 00:02:56.488236 | controller | skipping: [instance]
2025-10-08 00:02:56.488309 | controller |
2025-10-08 00:02:56.488412 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-08 00:02:56.488517 | controller | Wednesday 08 October 2025  00:02:56 +0000 (0:00:00.020)       0:00:01.941 *****
2025-10-08 00:02:56.534908 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-08 00:02:56.535012 | controller |
2025-10-08 00:02:56.535199 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-08 00:02:56.535214 | controller | Wednesday 08 October 2025  00:02:56 +0000 (0:00:00.046)       0:00:01.988 *****
2025-10-08 00:02:56.747822 | controller | ok: [instance]
2025-10-08 00:02:56.747931 | controller |
2025-10-08 00:02:56.748033 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-08 00:02:56.748132 | controller | Wednesday 08 October 2025  00:02:56 +0000 (0:00:00.212)       0:00:02.200 *****
2025-10-08 00:02:57.096482 | controller | ok: [instance]
2025-10-08 00:02:57.096525 | controller |
2025-10-08 00:02:57.096647 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-08 00:02:57.096787 | controller | Wednesday 08 October 2025  00:02:57 +0000 (0:00:00.348)       0:00:02.549 *****
2025-10-08 00:02:57.875016 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-08 00:02:57.875076 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-08 00:02:57.875086 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-08 00:02:57.875092 | controller |
2025-10-08 00:02:57.875104 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-08 00:02:57.875226 | controller | Wednesday 08 October 2025  00:02:57 +0000 (0:00:00.777)       0:00:03.327 *****
2025-10-08 00:02:57.952146 | controller |
2025-10-08 00:02:57.952254 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-08 00:02:57.952376 | controller | Wednesday 08 October 2025  00:02:57 +0000 (0:00:00.078)       0:00:03.405 *****
2025-10-08 00:02:58.624994 | controller | changed: [instance] => (item=tmp)
2025-10-08 00:02:58.625248 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-08 00:02:58.625329 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-08 00:02:58.625365 | controller |
2025-10-08 00:02:58.625426 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-08 00:02:58.625578 | controller | Wednesday 08 October 2025  00:02:58 +0000 (0:00:00.672)       0:00:04.077 *****
2025-10-08 00:02:59.560079 | controller | ok: [instance]
2025-10-08 00:03:00.798884 | controller |
2025-10-08 00:03:00.798945 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-08 00:03:00.798955 | controller | Wednesday 08 October 2025  00:02:59 +0000 (0:00:00.934)       0:00:05.012 *****
2025-10-08 00:03:00.798967 | controller | changed: [instance]
2025-10-08 00:03:09.525039 | controller |
2025-10-08 00:03:09.525086 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-08 00:03:09.525095 | controller | Wednesday 08 October 2025  00:03:00 +0000 (0:00:01.238)       0:00:06.251 *****
2025-10-08 00:03:09.525109 | controller | changed: [instance]
2025-10-08 00:03:10.293736 | controller |
2025-10-08 00:03:10.293780 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-08 00:03:10.293789 | controller | Wednesday 08 October 2025  00:03:09 +0000 (0:00:08.725)       0:00:14.977 *****
2025-10-08 00:03:10.293799 | controller | changed: [instance]
2025-10-08 00:03:10.321083 | controller |
2025-10-08 00:03:10.321106 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-08 00:03:10.321113 | controller | Wednesday 08 October 2025  00:03:10 +0000 (0:00:00.768)       0:00:15.745 *****
2025-10-08 00:03:10.321122 | controller | skipping: [instance]
2025-10-08 00:03:11.337257 | controller |
2025-10-08 00:03:11.337300 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-08 00:03:11.337309 | controller | Wednesday 08 October 2025  00:03:10 +0000 (0:00:00.027)       0:00:15.773 *****
2025-10-08 00:03:11.337328 | controller | changed: [instance]
2025-10-08 00:03:11.384676 | controller |
2025-10-08 00:03:11.384701 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-08 00:03:11.384709 | controller | Wednesday 08 October 2025  00:03:11 +0000 (0:00:01.015)       0:00:16.789 *****
2025-10-08 00:03:11.384730 | controller | skipping: [instance]
2025-10-08 00:03:11.440111 | controller |
2025-10-08 00:03:11.440134 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-08 00:03:11.440141 | controller | Wednesday 08 October 2025  00:03:11 +0000 (0:00:00.048)       0:00:16.837 *****
2025-10-08 00:03:11.440150 | controller | skipping: [instance]
2025-10-08 00:03:11.489768 | controller |
2025-10-08 00:03:11.489790 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-08 00:03:11.489797 | controller | Wednesday 08 October 2025  00:03:11 +0000 (0:00:00.055)       0:00:16.893 *****
2025-10-08 00:03:11.489806 | controller | skipping: [instance]
2025-10-08 00:03:12.117390 | controller |
2025-10-08 00:03:12.117437 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-08 00:03:12.117446 | controller | Wednesday 08 October 2025  00:03:11 +0000 (0:00:00.049)       0:00:16.942 *****
2025-10-08 00:03:12.117463 | controller | changed: [instance]
2025-10-08 00:03:12.571985 | controller |
2025-10-08 00:03:12.572033 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-08 00:03:12.572044 | controller | Wednesday 08 October 2025  00:03:12 +0000 (0:00:00.627)       0:00:17.570 *****
2025-10-08 00:03:12.572057 | controller | changed: [instance]
2025-10-08 00:03:12.614635 | controller |
2025-10-08 00:03:12.614701 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-08 00:03:12.614748 | controller | Wednesday 08 October 2025  00:03:12 +0000 (0:00:00.454)       0:00:18.024 *****
2025-10-08 00:03:12.614781 | controller | skipping: [instance]
2025-10-08 00:03:12.614828 | controller |
2025-10-08 00:03:12.614844 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-08 00:03:12.614859 | controller | Wednesday 08 October 2025  00:03:12 +0000 (0:00:00.042)       0:00:18.067 *****
2025-10-08 00:03:12.660427 | controller | skipping: [instance]
2025-10-08 00:03:12.699686 | controller |
2025-10-08 00:03:12.699712 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-08 00:03:12.699739 | controller | Wednesday 08 October 2025  00:03:12 +0000 (0:00:00.045)       0:00:18.112 *****
2025-10-08 00:03:12.699750 | controller | skipping: [instance]
2025-10-08 00:03:12.753815 | controller |
2025-10-08 00:03:12.753842 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-08 00:03:12.753851 | controller | Wednesday 08 October 2025  00:03:12 +0000 (0:00:00.039)       0:00:18.151 *****
2025-10-08 00:03:12.753860 | controller | ok: [instance]
2025-10-08 00:03:12.794605 | controller |
2025-10-08 00:03:12.794688 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-08 00:03:12.794699 | controller | Wednesday 08 October 2025  00:03:12 +0000 (0:00:00.054)       0:00:18.206 *****
2025-10-08 00:03:12.794713 | controller | skipping: [instance]
2025-10-08 00:03:12.794970 | controller |
2025-10-08 00:03:12.795193 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-08 00:03:12.795396 | controller | Wednesday 08 October 2025  00:03:12 +0000 (0:00:00.040)       0:00:18.246 *****
2025-10-08 00:03:12.833623 | controller | skipping: [instance]
2025-10-08 00:03:12.833878 | controller |
2025-10-08 00:03:12.834108 | controller | TASK [Download the RPM] ********************************************************
2025-10-08 00:03:12.834322 | controller | Wednesday 08 October 2025  00:03:12 +0000 (0:00:00.040)       0:00:18.287 *****
2025-10-08 00:03:12.873774 | controller | skipping: [instance]
2025-10-08 00:03:12.873977 | controller |
2025-10-08 00:03:12.874169 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-08 00:03:12.874375 | controller | Wednesday 08 October 2025  00:03:12 +0000 (0:00:00.040)       0:00:18.327 *****
2025-10-08 00:03:12.911408 | controller | skipping: [instance]
2025-10-08 00:03:12.911588 | controller |
2025-10-08 00:03:12.911793 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-08 00:03:12.911991 | controller | Wednesday 08 October 2025  00:03:12 +0000 (0:00:00.037)       0:00:18.364 *****
2025-10-08 00:03:12.949678 | controller | skipping: [instance]
2025-10-08 00:03:12.949906 | controller |
2025-10-08 00:03:12.950101 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-08 00:03:12.950293 | controller | Wednesday 08 October 2025  00:03:12 +0000 (0:00:00.038)       0:00:18.403 *****
2025-10-08 00:03:12.990793 | controller | skipping: [instance]
2025-10-08 00:03:12.990996 | controller |
2025-10-08 00:03:12.991223 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-08 00:03:12.991442 | controller | Wednesday 08 October 2025  00:03:12 +0000 (0:00:00.041)       0:00:18.444 *****
2025-10-08 00:03:13.025048 | controller | skipping: [instance]
2025-10-08 00:03:13.025223 | controller |
2025-10-08 00:03:13.025415 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-08 00:03:13.025591 | controller | Wednesday 08 October 2025  00:03:13 +0000 (0:00:00.034)       0:00:18.478 *****
2025-10-08 00:03:13.274919 | controller | ok: [instance]
2025-10-08 00:03:13.552400 | controller |
2025-10-08 00:03:13.552426 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-08 00:03:13.552483 | controller | Wednesday 08 October 2025  00:03:13 +0000 (0:00:00.248)       0:00:18.727 *****
2025-10-08 00:03:13.552495 | controller | changed: [instance]
2025-10-08 00:03:13.838418 | controller |
2025-10-08 00:03:13.838446 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-08 00:03:13.838458 | controller | Wednesday 08 October 2025  00:03:13 +0000 (0:00:00.277)       0:00:19.005 *****
2025-10-08 00:03:13.838472 | controller | changed: [instance]
2025-10-08 00:03:13.868044 | controller |
2025-10-08 00:03:13.868069 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-08 00:03:13.868081 | controller | Wednesday 08 October 2025  00:03:13 +0000 (0:00:00.286)       0:00:19.291 *****
2025-10-08 00:03:13.868094 | controller | skipping: [instance]
2025-10-08 00:03:13.909909 | controller |
2025-10-08 00:03:13.909938 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-08 00:03:13.909960 | controller | Wednesday 08 October 2025  00:03:13 +0000 (0:00:00.029)       0:00:19.320 *****
2025-10-08 00:03:13.909979 | controller | skipping: [instance]
2025-10-08 00:03:13.942062 | controller |
2025-10-08 00:03:13.942083 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-08 00:03:13.942091 | controller | Wednesday 08 October 2025  00:03:13 +0000 (0:00:00.041)       0:00:19.362 *****
2025-10-08 00:03:13.942100 | controller | skipping: [instance]
2025-10-08 00:03:13.981104 | controller |
2025-10-08 00:03:13.981126 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-08 00:03:13.981134 | controller | Wednesday 08 October 2025  00:03:13 +0000 (0:00:00.032)       0:00:19.395 *****
2025-10-08 00:03:13.981147 | controller | skipping: [instance]
2025-10-08 00:03:14.013646 | controller |
2025-10-08 00:03:14.013673 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-08 00:03:14.013681 | controller | Wednesday 08 October 2025  00:03:13 +0000 (0:00:00.038)       0:00:19.434 *****
2025-10-08 00:03:14.013696 | controller | skipping: [instance]
2025-10-08 00:03:14.013948 | controller |
2025-10-08 00:03:14.014162 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-08 00:03:14.014344 | controller | Wednesday 08 October 2025  00:03:14 +0000 (0:00:00.033)       0:00:19.467 *****
2025-10-08 00:03:14.049104 | controller | skipping: [instance]
2025-10-08 00:03:14.049359 | controller |
2025-10-08 00:03:14.049540 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-08 00:03:14.049743 | controller | Wednesday 08 October 2025  00:03:14 +0000 (0:00:00.035)       0:00:19.502 *****
2025-10-08 00:03:14.074056 | controller | skipping: [instance]
2025-10-08 00:03:14.074376 | controller |
2025-10-08 00:03:14.074763 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-08 00:03:14.074944 | controller | Wednesday 08 October 2025  00:03:14 +0000 (0:00:00.024)       0:00:19.527 *****
2025-10-08 00:03:14.113423 | controller | skipping: [instance]
2025-10-08 00:03:14.113816 | controller |
2025-10-08 00:03:14.114168 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-08 00:03:14.114455 | controller | Wednesday 08 October 2025  00:03:14 +0000 (0:00:00.039)       0:00:19.566 *****
2025-10-08 00:03:14.139186 | controller | skipping: [instance]
2025-10-08 00:03:14.139479 | controller |
2025-10-08 00:03:14.139655 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-08 00:03:14.139842 | controller | Wednesday 08 October 2025  00:03:14 +0000 (0:00:00.025)       0:00:19.592 *****
2025-10-08 00:03:14.171536 | controller | skipping: [instance]
2025-10-08 00:03:14.171740 | controller |
2025-10-08 00:03:14.171958 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-08 00:03:14.172146 | controller | Wednesday 08 October 2025  00:03:14 +0000 (0:00:00.032)       0:00:19.624 *****
2025-10-08 00:03:14.207592 | controller | skipping: [instance]
2025-10-08 00:03:14.207823 | controller |
2025-10-08 00:03:14.208035 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-08 00:03:14.208234 | controller | Wednesday 08 October 2025  00:03:14 +0000 (0:00:00.036)       0:00:19.661 *****
2025-10-08 00:03:14.249580 | controller | skipping: [instance]
2025-10-08 00:03:14.249864 | controller |
2025-10-08 00:03:14.250130 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-08 00:03:14.250343 | controller | Wednesday 08 October 2025  00:03:14 +0000 (0:00:00.041)       0:00:19.703 *****
2025-10-08 00:03:14.287604 | controller | skipping: [instance]
2025-10-08 00:03:14.287849 | controller |
2025-10-08 00:03:14.288035 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-08 00:03:14.288193 | controller | Wednesday 08 October 2025  00:03:14 +0000 (0:00:00.037)       0:00:19.740 *****
2025-10-08 00:03:14.328600 | controller | skipping: [instance]
2025-10-08 00:03:14.328847 | controller |
2025-10-08 00:03:14.329037 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-08 00:03:14.329217 | controller | Wednesday 08 October 2025  00:03:14 +0000 (0:00:00.040)       0:00:19.781 *****
2025-10-08 00:04:06.809357 | controller | ok: [instance]
2025-10-08 00:04:06.809831 | controller |
2025-10-08 00:04:06.810060 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-08 00:04:06.810255 | controller | Wednesday 08 October 2025  00:04:06 +0000 (0:00:52.471)       0:01:12.253 *****
2025-10-08 00:04:08.155737 | controller | ok: [instance]
2025-10-08 00:04:08.197130 | controller |
2025-10-08 00:04:08.197153 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-08 00:04:08.197169 | controller | Wednesday 08 October 2025  00:04:08 +0000 (0:00:01.350)       0:01:13.603 *****
2025-10-08 00:04:08.197181 | controller | skipping: [instance]
2025-10-08 00:04:08.247916 | controller |
2025-10-08 00:04:08.248005 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-10-08 00:04:08.248014 | controller | Wednesday 08 October 2025  00:04:08 +0000 (0:00:00.046)       0:01:13.650 *****
2025-10-08 00:04:08.248034 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-10-08 00:04:08.275523 | controller |
2025-10-08 00:04:08.275614 | controller | TASK [ci_setup : List packages to install] *************************************
2025-10-08 00:04:08.275624 | controller | Wednesday 08 October 2025  00:04:08 +0000 (0:00:00.050)       0:01:13.700 *****
2025-10-08 00:04:08.275641 | controller | ok: [instance] =>
2025-10-08 00:04:08.275682 | controller |   cifmw_ci_setup_packages:
2025-10-08 00:04:08.275691 | controller |   - bash-completion
2025-10-08 00:04:08.275697 | controller |   - ca-certificates
2025-10-08 00:04:08.275702 | controller |   - git-core
2025-10-08 00:04:08.275711 | controller |   - make
2025-10-08 00:04:08.275766 | controller |   - tar
2025-10-08 00:04:08.275779 | controller |   - tmux
2025-10-08 00:04:08.276156 | controller |   - python3-pip
2025-10-08 00:04:08.276291 | controller |
2025-10-08 00:04:08.276303 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-10-08 00:04:08.276312 | controller | Wednesday 08 October 2025  00:04:08 +0000 (0:00:00.027)       0:01:13.728 *****
2025-10-08 00:04:16.723960 | controller | changed: [instance]
2025-10-08 00:04:16.724033 | controller |
2025-10-08 00:04:16.724190 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-10-08 00:04:16.724288 | controller | Wednesday 08 October 2025  00:04:16 +0000 (0:00:08.447)       0:01:22.176 *****
2025-10-08 00:04:17.382305 | controller | ok: [instance]
2025-10-08 00:04:17.382373 | controller |
2025-10-08 00:04:17.382451 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-10-08 00:04:17.382558 | controller | Wednesday 08 October 2025  00:04:17 +0000 (0:00:00.658)       0:01:22.835 *****
2025-10-08 00:04:17.632433 | controller | changed: [instance]
2025-10-08 00:04:17.632508 | controller |
2025-10-08 00:04:17.632620 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-10-08 00:04:17.632740 | controller | Wednesday 08 October 2025  00:04:17 +0000 (0:00:00.250)       0:01:23.085 *****
2025-10-08 00:04:24.052785 | controller | changed: [instance]
2025-10-08 00:04:24.053091 | controller |
2025-10-08 00:04:24.053158 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-10-08 00:04:24.109996 | controller | Wednesday 08 October 2025  00:04:24 +0000 (0:00:06.415)       0:01:29.500 *****
2025-10-08 00:04:24.110038 | controller | ok: [instance]
2025-10-08 00:04:24.442186 | controller |
2025-10-08 00:04:24.442232 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-10-08 00:04:24.442241 | controller | Wednesday 08 October 2025  00:04:24 +0000 (0:00:00.062)       0:01:29.562 *****
2025-10-08 00:04:24.442252 | controller | changed: [instance]
2025-10-08 00:04:24.832222 | controller |
2025-10-08 00:04:24.832283 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-10-08 00:04:24.832293 | controller | Wednesday 08 October 2025  00:04:24 +0000 (0:00:00.331)       0:01:29.894 *****
2025-10-08 00:04:24.832304 | controller | changed: [instance]
2025-10-08 00:04:24.860743 | controller |
2025-10-08 00:04:24.860783 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-10-08 00:04:24.860795 | controller | Wednesday 08 October 2025  00:04:24 +0000 (0:00:00.389)       0:01:30.284 *****
2025-10-08 00:04:24.860817 | controller | skipping: [instance]
2025-10-08 00:04:24.887271 | controller |
2025-10-08 00:04:24.887291 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-10-08 00:04:24.887312 | controller | Wednesday 08 October 2025  00:04:24 +0000 (0:00:00.029)       0:01:30.313 *****
2025-10-08 00:04:24.887323 | controller | skipping: [instance]
2025-10-08 00:04:24.914319 | controller |
2025-10-08 00:04:24.914337 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-10-08 00:04:24.914345 | controller | Wednesday 08 October 2025  00:04:24 +0000 (0:00:00.026)       0:01:30.340 *****
2025-10-08 00:04:24.914359 | controller | skipping: [instance]
2025-10-08 00:04:24.942893 | controller |
2025-10-08 00:04:24.942910 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-10-08 00:04:24.942922 | controller | Wednesday 08 October 2025  00:04:24 +0000 (0:00:00.027)       0:01:30.367 *****
2025-10-08 00:04:24.942932 | controller | skipping: [instance]
2025-10-08 00:04:24.969452 | controller |
2025-10-08 00:04:24.969479 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-10-08 00:04:24.969491 | controller | Wednesday 08 October 2025  00:04:24 +0000 (0:00:00.028)       0:01:30.396 *****
2025-10-08 00:04:24.969502 | controller | skipping: [instance]
2025-10-08 00:04:24.969739 | controller |
2025-10-08 00:04:24.970019 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-10-08 00:04:24.970216 | controller | Wednesday 08 October 2025  00:04:24 +0000 (0:00:00.026)       0:01:30.422 *****
2025-10-08 00:04:25.003019 | controller | skipping: [instance]
2025-10-08 00:04:25.003261 | controller |
2025-10-08 00:04:25.003454 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-10-08 00:04:25.003650 | controller | Wednesday 08 October 2025  00:04:24 +0000 (0:00:00.033)       0:01:30.456 *****
2025-10-08 00:04:26.270852 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-10-08 00:12:48.909147 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-10-08 00:12:48.909197 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-10-08 00:12:48.909204 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-10-08 00:12:48.909210 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-10-08 00:12:48.909216 | controller |
2025-10-08 00:12:48.909221 | controller | TASK [Check for CRC startup] ***************************************************
2025-10-08 00:12:48.909226 | controller | Wednesday 08 October 2025  00:04:26 +0000 (0:00:01.265)       0:01:31.722 *****
2025-10-08 00:12:48.909236 | controller | FAILED - RETRYING: [instance]: Check for CRC startup (120 retries left).
2025-10-08 00:12:49.645156 | controller | FAILED - RETRYING: [instance]: Check for CRC startup (119 retries left).
2025-10-08 00:12:49.645195 | controller | FAILED - RETRYING: [instance]: Check for CRC startup (118 retries left).
2025-10-08 00:12:49.645203 | controller | FAILED - RETRYING: [instance]: Check for CRC startup (117 retries left).
2025-10-08 00:12:49.645209 | controller | FAILED - RETRYING: [instance]: Check for CRC startup (116 retries left).
2025-10-08 00:12:49.645215 | controller | FAILED - RETRYING: [instance]: Check for CRC startup (115 retries left).
2025-10-08 00:12:49.645220 | controller | FAILED - RETRYING: [instance]: Check for CRC startup (114 retries left).
2025-10-08 00:12:49.645226 | controller | FAILED - RETRYING: [instance]: Check for CRC startup (113 retries left).
2025-10-08 00:12:49.645231 | controller | FAILED - RETRYING: [instance]: Check for CRC startup (112 retries left).
2025-10-08 00:12:49.645236 | controller | FAILED - RETRYING: [instance]: Check for CRC startup (111 retries left).
2025-10-08 00:12:49.645241 | controller | changed: [instance]
2025-10-08 00:12:49.645247 | controller |
2025-10-08 00:12:49.645258 | controller | RUNNING HANDLER [Restart NetworkManager] ***************************************
2025-10-08 00:12:49.645264 | controller | Wednesday 08 October 2025  00:12:48 +0000 (0:08:22.638)       0:09:54.360 *****
2025-10-08 00:12:49.645274 | controller | changed: [instance]
2025-10-08 00:12:49.645303 | controller |
2025-10-08 00:12:49.645310 | controller | PLAY RECAP *********************************************************************
2025-10-08 00:12:49.645315 | controller | instance                   : ok=33   changed=19   unreachable=0    failed=0    skipped=38   rescued=0    ignored=0
2025-10-08 00:12:49.645321 | controller |
2025-10-08 00:12:49.645335 | controller | Wednesday 08 October 2025  00:12:49 +0000 (0:00:00.736)       0:09:55.097 *****
2025-10-08 00:12:49.645342 | controller | ===============================================================================
2025-10-08 00:12:49.645347 | controller | Check for CRC startup ------------------------------------------------- 502.64s
2025-10-08 00:12:49.645352 | controller | test_deps : Install selinux python libs -------------------------------- 52.47s
2025-10-08 00:12:49.645359 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.73s
2025-10-08 00:12:49.645830 | controller | ci_setup : Install needed packages -------------------------------------- 8.45s
2025-10-08 00:12:49.645874 | controller | ci_setup : Install openshift client ------------------------------------- 6.42s
2025-10-08 00:12:49.645904 | controller | test_deps : Install python yaml libs ------------------------------------ 1.35s
2025-10-08 00:12:49.645920 | controller | ci_setup : Manage directories ------------------------------------------- 1.27s
2025-10-08 00:12:49.645927 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.24s
2025-10-08 00:12:49.645932 | controller | repo_setup : Run repo-setup --------------------------------------------- 1.02s
2025-10-08 00:12:49.645940 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.93s
2025-10-08 00:12:49.646189 | controller | Gathering Facts --------------------------------------------------------- 0.84s
2025-10-08 00:12:49.646650 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.78s
2025-10-08 00:12:49.646668 | controller | repo_setup : Install repo-setup package --------------------------------- 0.77s
2025-10-08 00:12:49.646677 | controller | Restart NetworkManager -------------------------------------------------- 0.74s
2025-10-08 00:12:49.646703 | controller | repo_setup : Ensure directories are present ----------------------------- 0.67s
2025-10-08 00:12:49.646710 | controller | ci_setup : Gather version of openshift client --------------------------- 0.66s
2025-10-08 00:12:49.646715 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.63s
2025-10-08 00:12:49.646752 | controller | Inject our own NetworkManager configuration ----------------------------- 0.60s
2025-10-08 00:12:49.646762 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.45s
2025-10-08 00:12:49.646911 | controller | Start RHOL/CRC ---------------------------------------------------------- 0.41s
2025-10-08 00:12:49.730034 | controller | INFO     Running add_crc_creds > converge
2025-10-08 00:12:50.160849 | controller |
2025-10-08 00:12:51.082665 | controller | PLAY [Converge add_crc_creds] **************************************************
2025-10-08 00:12:51.082695 | controller |
2025-10-08 00:12:51.082702 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 00:12:51.082707 | controller | Wednesday 08 October 2025  00:12:50 +0000 (0:00:00.034)       0:00:00.034 *****
2025-10-08 00:12:51.082741 | controller | ok: [instance]
2025-10-08 00:12:51.114154 | controller |
2025-10-08 00:12:51.114193 | controller | TASK [Verify crc creds playbook] ***********************************************
2025-10-08 00:12:51.114201 | controller | Wednesday 08 October 2025  00:12:51 +0000 (0:00:00.921)       0:00:00.956 *****
2025-10-08 00:12:51.114212 | controller |
2025-10-08 00:12:51.114229 | controller | TASK [rhol_crc : Make sure kubeconfig file exists] *****************************
2025-10-08 00:12:51.114236 | controller | Wednesday 08 October 2025  00:12:51 +0000 (0:00:00.031)       0:00:00.988 *****
2025-10-08 00:12:51.415044 | controller | ok: [instance]
2025-10-08 00:12:51.415196 | controller |
2025-10-08 00:12:51.415356 | controller | TASK [rhol_crc : Warn the user that the kubeconfig files doesn't exist] ********
2025-10-08 00:12:51.446858 | controller | Wednesday 08 October 2025  00:12:51 +0000 (0:00:00.296)       0:00:01.284 *****
2025-10-08 00:12:51.446910 | controller | skipping: [instance]
2025-10-08 00:12:51.724425 | controller |
2025-10-08 00:12:51.724468 | controller | TASK [rhol_crc : Add crc creds in the user bashrc file] ************************
2025-10-08 00:12:51.724476 | controller | Wednesday 08 October 2025  00:12:51 +0000 (0:00:00.035)       0:00:01.320 *****
2025-10-08 00:12:51.724488 | controller | changed: [instance]
2025-10-08 00:12:52.313311 | controller |
2025-10-08 00:12:52.313358 | controller | TASK [rhol_crc : Source bashrc and confirm crc login] **************************
2025-10-08 00:12:52.313375 | controller | Wednesday 08 October 2025  00:12:51 +0000 (0:00:00.277)       0:00:01.597 *****
2025-10-08 00:12:52.313386 | controller | ok: [instance]
2025-10-08 00:12:52.313559 | controller |
2025-10-08 00:12:52.313610 | controller | TASK [rhol_crc : Check bashrc results] *****************************************
2025-10-08 00:12:52.359801 | controller | Wednesday 08 October 2025  00:12:52 +0000 (0:00:00.588)       0:00:02.186 *****
2025-10-08 00:12:52.359845 | controller | ok: [instance] =>
2025-10-08 00:12:52.359875 | controller |   bashrc_result.stdout: |-
2025-10-08 00:12:52.359882 | controller |     /usr/local/bin/crc
2025-10-08 00:12:52.359890 | controller |     system:admin
2025-10-08 00:12:52.359924 | controller |
2025-10-08 00:12:52.360060 | controller | PLAY RECAP *********************************************************************
2025-10-08 00:12:52.360327 | controller | instance                   : ok=5    changed=1    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2025-10-08 00:12:52.360542 | controller |
2025-10-08 00:12:52.360565 | controller | Wednesday 08 October 2025  00:12:52 +0000 (0:00:00.047)       0:00:02.233 *****
2025-10-08 00:12:52.360760 | controller | ===============================================================================
2025-10-08 00:12:52.360792 | controller | Gathering Facts --------------------------------------------------------- 0.92s
2025-10-08 00:12:52.360804 | controller | rhol_crc : Source bashrc and confirm crc login -------------------------- 0.59s
2025-10-08 00:12:52.360843 | controller | rhol_crc : Make sure kubeconfig file exists ----------------------------- 0.30s
2025-10-08 00:12:52.360854 | controller | rhol_crc : Add crc creds in the user bashrc file ------------------------ 0.28s
2025-10-08 00:12:52.360941 | controller | rhol_crc : Check bashrc results ----------------------------------------- 0.05s
2025-10-08 00:12:52.361055 | controller | rhol_crc : Warn the user that the kubeconfig files doesn't exist -------- 0.04s
2025-10-08 00:12:52.361242 | controller | Verify crc creds playbook ----------------------------------------------- 0.03s
2025-10-08 00:12:52.430331 | controller | INFO     Running add_crc_creds > cleanup
2025-10-08 00:12:52.851945 | controller |
2025-10-08 00:12:52.851987 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 00:12:52.852085 | controller |
2025-10-08 00:12:52.852187 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 00:12:52.852307 | controller | Wednesday 08 October 2025  00:12:52 +0000 (0:00:00.014)       0:00:00.014 *****
2025-10-08 00:12:53.767761 | controller | ok: [instance]
2025-10-08 00:12:53.767808 | controller |
2025-10-08 00:12:53.768026 | controller | TASK [rhol_crc : Check RHOL/CRC binary exists] *********************************
2025-10-08 00:12:54.053080 | controller | Wednesday 08 October 2025  00:12:53 +0000 (0:00:00.915)       0:00:00.929 *****
2025-10-08 00:12:54.053126 | controller | ok: [instance]
2025-10-08 00:12:54.053239 | controller |
2025-10-08 00:12:54.053250 | controller | TASK [rhol_crc : Delete RHOL/CRC instance and associated configuration] ********
2025-10-08 00:12:54.053334 | controller | Wednesday 08 October 2025  00:12:54 +0000 (0:00:00.285)       0:00:01.215 *****
2025-10-08 00:12:59.200095 | controller | changed: [instance]
2025-10-08 00:12:59.200144 | controller |
2025-10-08 00:12:59.200157 | controller | PLAY RECAP *********************************************************************
2025-10-08 00:12:59.200309 | controller | instance                   : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 00:12:59.200366 | controller |
2025-10-08 00:12:59.200472 | controller | Wednesday 08 October 2025  00:12:59 +0000 (0:00:05.147)       0:00:06.362 *****
2025-10-08 00:12:59.200555 | controller | ===============================================================================
2025-10-08 00:12:59.200649 | controller | rhol_crc : Delete RHOL/CRC instance and associated configuration -------- 5.15s
2025-10-08 00:12:59.200738 | controller | Gathering Facts --------------------------------------------------------- 0.92s
2025-10-08 00:12:59.200849 | controller | rhol_crc : Check RHOL/CRC binary exists --------------------------------- 0.29s
2025-10-08 00:12:59.271406 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-08 00:13:16.293034 | 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-10-08 00:13:16.293478 | 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-10-08 00:13:16.293947 | 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-10-08 00:13:16.294490 | 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-10-08 00:13:16.294901 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+6451eac5 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0+6451eac5 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 00:13:16.295347 | 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-10-08 00:13:16.295796 | 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-10-08 00:13:16.296417 | 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-10-08 00:13:16.296849 | 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-10-08 00:13:16.297323 | 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-10-08 00:13:16.297732 | 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-10-08 00:13:16.298216 | 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-10-08 00:13:16.298642 | 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-10-08 00:13:16.299117 | 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-10-08 00:13:16.299547 | 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-10-08 00:13:16.308908 | controller | INFO     Running binary > prepare
2025-10-08 00:13:16.767756 | controller |
2025-10-08 00:13:16.767920 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 00:13:16.768085 | controller |
2025-10-08 00:13:16.768220 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 00:13:16.768340 | controller | Wednesday 08 October 2025  00:13:16 +0000 (0:00:00.018)       0:00:00.018 *****
2025-10-08 00:13:17.634672 | controller | ok: [instance]
2025-10-08 00:13:17.659482 | controller |
2025-10-08 00:13:17.659512 | controller | TASK [Setup libvirt] ***********************************************************
2025-10-08 00:13:17.659524 | controller | Wednesday 08 October 2025  00:13:17 +0000 (0:00:00.866)       0:00:00.885 *****
2025-10-08 00:13:17.659539 | controller | skipping: [instance]
2025-10-08 00:13:17.689477 | controller |
2025-10-08 00:13:17.689497 | controller | TASK [Setup CRC] ***************************************************************
2025-10-08 00:13:17.689506 | controller | Wednesday 08 October 2025  00:13:17 +0000 (0:00:00.024)       0:00:00.910 *****
2025-10-08 00:13:17.689516 | controller | skipping: [instance]
2025-10-08 00:13:17.713209 | controller |
2025-10-08 00:13:17.713229 | controller | TASK [Start RHOL/CRC] **********************************************************
2025-10-08 00:13:17.713238 | controller | Wednesday 08 October 2025  00:13:17 +0000 (0:00:00.030)       0:00:00.940 *****
2025-10-08 00:13:17.713247 | controller | skipping: [instance]
2025-10-08 00:13:18.359846 | controller |
2025-10-08 00:13:18.359885 | controller | TASK [Inject our own NetworkManager configuration] *****************************
2025-10-08 00:13:18.359894 | controller | Wednesday 08 October 2025  00:13:17 +0000 (0:00:00.023)       0:00:00.964 *****
2025-10-08 00:13:18.359905 | controller | ok: [instance]
2025-10-08 00:13:18.392553 | controller |
2025-10-08 00:13:18.392592 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-08 00:13:18.392602 | controller | Wednesday 08 October 2025  00:13:18 +0000 (0:00:00.646)       0:00:01.610 *****
2025-10-08 00:13:18.392614 | controller | skipping: [instance]
2025-10-08 00:13:18.439803 | controller |
2025-10-08 00:13:18.439825 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-08 00:13:18.439832 | controller | Wednesday 08 October 2025  00:13:18 +0000 (0:00:00.033)       0:00:01.643 *****
2025-10-08 00:13:18.439841 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-08 00:13:18.439868 | controller |
2025-10-08 00:13:18.439875 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-08 00:13:18.440049 | controller | Wednesday 08 October 2025  00:13:18 +0000 (0:00:00.047)       0:00:01.691 *****
2025-10-08 00:13:18.646142 | controller | ok: [instance]
2025-10-08 00:13:18.868519 | controller |
2025-10-08 00:13:18.868550 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-08 00:13:18.868559 | controller | Wednesday 08 October 2025  00:13:18 +0000 (0:00:00.205)       0:00:01.897 *****
2025-10-08 00:13:18.868569 | controller | ok: [instance]
2025-10-08 00:13:19.572920 | controller |
2025-10-08 00:13:19.572966 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-08 00:13:19.572974 | controller | Wednesday 08 October 2025  00:13:18 +0000 (0:00:00.222)       0:00:02.119 *****
2025-10-08 00:13:19.572985 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-08 00:13:19.634653 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-08 00:13:19.634675 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-08 00:13:19.634684 | controller |
2025-10-08 00:13:19.634690 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-08 00:13:19.634697 | controller | Wednesday 08 October 2025  00:13:19 +0000 (0:00:00.703)       0:00:02.823 *****
2025-10-08 00:13:19.634714 | controller |
2025-10-08 00:13:20.192957 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-08 00:13:20.192995 | controller | Wednesday 08 October 2025  00:13:19 +0000 (0:00:00.062)       0:00:02.885 *****
2025-10-08 00:13:20.193007 | controller | ok: [instance] => (item=tmp)
2025-10-08 00:13:21.509154 | controller | ok: [instance] => (item=artifacts/repositories)
2025-10-08 00:13:21.509188 | controller | ok: [instance] => (item=venv/repo_setup)
2025-10-08 00:13:21.509195 | controller |
2025-10-08 00:13:21.509209 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-08 00:13:21.509215 | controller | Wednesday 08 October 2025  00:13:20 +0000 (0:00:00.557)       0:00:03.443 *****
2025-10-08 00:13:21.509226 | controller | ok: [instance]
2025-10-08 00:13:22.108528 | controller |
2025-10-08 00:13:22.108563 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-08 00:13:22.108572 | controller | Wednesday 08 October 2025  00:13:21 +0000 (0:00:01.316)       0:00:04.760 *****
2025-10-08 00:13:22.108583 | controller | ok: [instance]
2025-10-08 00:13:23.348893 | controller |
2025-10-08 00:13:23.348947 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-08 00:13:23.348958 | controller | Wednesday 08 October 2025  00:13:22 +0000 (0:00:00.598)       0:00:05.358 *****
2025-10-08 00:13:23.348972 | controller | ok: [instance]
2025-10-08 00:13:23.349002 | controller |
2025-10-08 00:13:23.349011 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-08 00:13:23.349078 | controller | Wednesday 08 October 2025  00:13:23 +0000 (0:00:01.240)       0:00:06.599 *****
2025-10-08 00:13:23.640927 | controller | ok: [instance]
2025-10-08 00:13:23.640982 | controller |
2025-10-08 00:13:23.640992 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-08 00:13:23.641003 | controller | Wednesday 08 October 2025  00:13:23 +0000 (0:00:00.292)       0:00:06.892 *****
2025-10-08 00:13:23.667301 | controller | skipping: [instance]
2025-10-08 00:13:24.567831 | controller |
2025-10-08 00:13:24.567876 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-08 00:13:24.567885 | controller | Wednesday 08 October 2025  00:13:23 +0000 (0:00:00.026)       0:00:06.918 *****
2025-10-08 00:13:24.567896 | controller | changed: [instance]
2025-10-08 00:13:24.567919 | controller |
2025-10-08 00:13:24.568114 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-08 00:13:24.610237 | controller | Wednesday 08 October 2025  00:13:24 +0000 (0:00:00.900)       0:00:07.818 *****
2025-10-08 00:13:24.610259 | controller | skipping: [instance]
2025-10-08 00:13:24.650808 | controller |
2025-10-08 00:13:24.650825 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-08 00:13:24.650832 | controller | Wednesday 08 October 2025  00:13:24 +0000 (0:00:00.042)       0:00:07.861 *****
2025-10-08 00:13:24.650843 | controller | skipping: [instance]
2025-10-08 00:13:24.650869 | controller |
2025-10-08 00:13:24.650876 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-08 00:13:24.650884 | controller | Wednesday 08 October 2025  00:13:24 +0000 (0:00:00.040)       0:00:07.902 *****
2025-10-08 00:13:24.691235 | controller | skipping: [instance]
2025-10-08 00:13:25.261272 | controller |
2025-10-08 00:13:25.261307 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-08 00:13:25.261315 | controller | Wednesday 08 October 2025  00:13:24 +0000 (0:00:00.040)       0:00:07.942 *****
2025-10-08 00:13:25.261326 | controller | changed: [instance]
2025-10-08 00:13:25.624886 | controller |
2025-10-08 00:13:25.624924 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-08 00:13:25.624945 | controller | Wednesday 08 October 2025  00:13:25 +0000 (0:00:00.569)       0:00:08.512 *****
2025-10-08 00:13:25.624958 | controller | ok: [instance]
2025-10-08 00:13:25.624978 | controller |
2025-10-08 00:13:25.624985 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-08 00:13:25.624994 | controller | Wednesday 08 October 2025  00:13:25 +0000 (0:00:00.363)       0:00:08.876 *****
2025-10-08 00:13:25.654833 | controller | skipping: [instance]
2025-10-08 00:13:25.654880 | controller |
2025-10-08 00:13:25.655088 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-08 00:13:25.685767 | controller | Wednesday 08 October 2025  00:13:25 +0000 (0:00:00.030)       0:00:08.906 *****
2025-10-08 00:13:25.685792 | controller | skipping: [instance]
2025-10-08 00:13:25.717822 | controller |
2025-10-08 00:13:25.717847 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-08 00:13:25.717855 | controller | Wednesday 08 October 2025  00:13:25 +0000 (0:00:00.030)       0:00:08.937 *****
2025-10-08 00:13:25.717864 | controller | skipping: [instance]
2025-10-08 00:13:25.717882 | controller |
2025-10-08 00:13:25.717888 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-08 00:13:25.717904 | controller | Wednesday 08 October 2025  00:13:25 +0000 (0:00:00.032)       0:00:08.969 *****
2025-10-08 00:13:25.756161 | controller | ok: [instance]
2025-10-08 00:13:25.783385 | controller |
2025-10-08 00:13:25.783410 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-08 00:13:25.783418 | controller | Wednesday 08 October 2025  00:13:25 +0000 (0:00:00.038)       0:00:09.007 *****
2025-10-08 00:13:25.783429 | controller | skipping: [instance]
2025-10-08 00:13:25.813853 | controller |
2025-10-08 00:13:25.813878 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-08 00:13:25.813887 | controller | Wednesday 08 October 2025  00:13:25 +0000 (0:00:00.027)       0:00:09.034 *****
2025-10-08 00:13:25.813897 | controller | skipping: [instance]
2025-10-08 00:13:25.847546 | controller |
2025-10-08 00:13:25.847568 | controller | TASK [Download the RPM] ********************************************************
2025-10-08 00:13:25.847576 | controller | Wednesday 08 October 2025  00:13:25 +0000 (0:00:00.029)       0:00:09.064 *****
2025-10-08 00:13:25.847587 | controller | skipping: [instance]
2025-10-08 00:13:25.847793 | controller |
2025-10-08 00:13:25.848007 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-08 00:13:25.848164 | controller | Wednesday 08 October 2025  00:13:25 +0000 (0:00:00.034)       0:00:09.099 *****
2025-10-08 00:13:25.877942 | controller | skipping: [instance]
2025-10-08 00:13:25.878127 | controller |
2025-10-08 00:13:25.878270 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-08 00:13:25.878410 | controller | Wednesday 08 October 2025  00:13:25 +0000 (0:00:00.030)       0:00:09.129 *****
2025-10-08 00:13:25.914778 | controller | skipping: [instance]
2025-10-08 00:13:25.914948 | controller |
2025-10-08 00:13:25.915096 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-08 00:13:25.915234 | controller | Wednesday 08 October 2025  00:13:25 +0000 (0:00:00.036)       0:00:09.166 *****
2025-10-08 00:13:25.948038 | controller | skipping: [instance]
2025-10-08 00:13:25.948197 | controller |
2025-10-08 00:13:25.948356 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-08 00:13:25.948498 | controller | Wednesday 08 October 2025  00:13:25 +0000 (0:00:00.033)       0:00:09.199 *****
2025-10-08 00:13:25.979181 | controller | skipping: [instance]
2025-10-08 00:13:25.979354 | controller |
2025-10-08 00:13:25.979496 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-08 00:13:25.979635 | controller | Wednesday 08 October 2025  00:13:25 +0000 (0:00:00.030)       0:00:09.230 *****
2025-10-08 00:13:26.166446 | controller | ok: [instance]
2025-10-08 00:13:26.166648 | controller |
2025-10-08 00:13:26.166825 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-08 00:13:26.167003 | controller | Wednesday 08 October 2025  00:13:26 +0000 (0:00:00.187)       0:00:09.417 *****
2025-10-08 00:13:26.385380 | controller | changed: [instance]
2025-10-08 00:13:26.612424 | controller |
2025-10-08 00:13:26.612460 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-08 00:13:26.612468 | controller | Wednesday 08 October 2025  00:13:26 +0000 (0:00:00.218)       0:00:09.636 *****
2025-10-08 00:13:26.612478 | controller | changed: [instance]
2025-10-08 00:13:26.635672 | controller |
2025-10-08 00:13:26.635696 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-08 00:13:26.635704 | controller | Wednesday 08 October 2025  00:13:26 +0000 (0:00:00.227)       0:00:09.863 *****
2025-10-08 00:13:26.635714 | controller | skipping: [instance]
2025-10-08 00:13:26.660661 | controller |
2025-10-08 00:13:26.660682 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-08 00:13:26.660690 | controller | Wednesday 08 October 2025  00:13:26 +0000 (0:00:00.023)       0:00:09.886 *****
2025-10-08 00:13:26.660699 | controller | skipping: [instance]
2025-10-08 00:13:26.688346 | controller |
2025-10-08 00:13:26.688368 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-08 00:13:26.688376 | controller | Wednesday 08 October 2025  00:13:26 +0000 (0:00:00.024)       0:00:09.911 *****
2025-10-08 00:13:26.688394 | controller | skipping: [instance]
2025-10-08 00:13:26.716841 | controller |
2025-10-08 00:13:26.716866 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-08 00:13:26.716876 | controller | Wednesday 08 October 2025  00:13:26 +0000 (0:00:00.027)       0:00:09.939 *****
2025-10-08 00:13:26.716888 | controller | skipping: [instance]
2025-10-08 00:13:26.717123 | controller |
2025-10-08 00:13:26.717145 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-08 00:13:26.744508 | controller | Wednesday 08 October 2025  00:13:26 +0000 (0:00:00.028)       0:00:09.968 *****
2025-10-08 00:13:26.744534 | controller | skipping: [instance]
2025-10-08 00:13:26.771664 | controller |
2025-10-08 00:13:26.771685 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-08 00:13:26.771694 | controller | Wednesday 08 October 2025  00:13:26 +0000 (0:00:00.027)       0:00:09.995 *****
2025-10-08 00:13:26.771703 | controller | skipping: [instance]
2025-10-08 00:13:26.771882 | controller |
2025-10-08 00:13:26.772031 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-08 00:13:26.772166 | controller | Wednesday 08 October 2025  00:13:26 +0000 (0:00:00.027)       0:00:10.023 *****
2025-10-08 00:13:26.793675 | controller | skipping: [instance]
2025-10-08 00:13:26.793892 | controller |
2025-10-08 00:13:26.794047 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-08 00:13:26.794174 | controller | Wednesday 08 October 2025  00:13:26 +0000 (0:00:00.022)       0:00:10.045 *****
2025-10-08 00:13:26.826888 | controller | skipping: [instance]
2025-10-08 00:13:26.827078 | controller |
2025-10-08 00:13:26.827238 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-08 00:13:26.827390 | controller | Wednesday 08 October 2025  00:13:26 +0000 (0:00:00.033)       0:00:10.078 *****
2025-10-08 00:13:26.847420 | controller | skipping: [instance]
2025-10-08 00:13:26.847587 | controller |
2025-10-08 00:13:26.847783 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-08 00:13:26.847967 | controller | Wednesday 08 October 2025  00:13:26 +0000 (0:00:00.020)       0:00:10.099 *****
2025-10-08 00:13:26.875602 | controller | skipping: [instance]
2025-10-08 00:13:26.875889 | controller |
2025-10-08 00:13:26.876128 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-08 00:13:26.876266 | controller | Wednesday 08 October 2025  00:13:26 +0000 (0:00:00.027)       0:00:10.127 *****
2025-10-08 00:13:26.908354 | controller | skipping: [instance]
2025-10-08 00:13:26.908531 | controller |
2025-10-08 00:13:26.908705 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-08 00:13:26.908905 | controller | Wednesday 08 October 2025  00:13:26 +0000 (0:00:00.032)       0:00:10.160 *****
2025-10-08 00:13:26.940241 | controller | skipping: [instance]
2025-10-08 00:13:26.940411 | controller |
2025-10-08 00:13:26.940571 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-08 00:13:26.940749 | controller | Wednesday 08 October 2025  00:13:26 +0000 (0:00:00.031)       0:00:10.191 *****
2025-10-08 00:13:26.971776 | controller | skipping: [instance]
2025-10-08 00:13:26.971977 | controller |
2025-10-08 00:13:26.972140 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-08 00:13:26.972284 | controller | Wednesday 08 October 2025  00:13:26 +0000 (0:00:00.031)       0:00:10.223 *****
2025-10-08 00:13:27.003075 | controller | skipping: [instance]
2025-10-08 00:13:27.003246 | controller |
2025-10-08 00:13:27.003385 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-08 00:13:27.003520 | controller | Wednesday 08 October 2025  00:13:27 +0000 (0:00:00.031)       0:00:10.254 *****
2025-10-08 00:14:12.352652 | controller | ok: [instance]
2025-10-08 00:14:13.546489 | controller |
2025-10-08 00:14:13.546533 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-08 00:14:13.546542 | controller | Wednesday 08 October 2025  00:14:12 +0000 (0:00:45.348)       0:00:55.603 *****
2025-10-08 00:14:13.546551 | controller | ok: [instance]
2025-10-08 00:14:13.588925 | controller |
2025-10-08 00:14:13.588996 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-08 00:14:13.589007 | controller | Wednesday 08 October 2025  00:14:13 +0000 (0:00:01.193)       0:00:56.797 *****
2025-10-08 00:14:13.589020 | controller | skipping: [instance]
2025-10-08 00:14:13.638887 | controller |
2025-10-08 00:14:13.638914 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-10-08 00:14:13.638922 | controller | Wednesday 08 October 2025  00:14:13 +0000 (0:00:00.042)       0:00:56.839 *****
2025-10-08 00:14:13.638933 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-10-08 00:14:13.662920 | controller |
2025-10-08 00:14:13.662969 | controller | TASK [ci_setup : List packages to install] *************************************
2025-10-08 00:14:13.662979 | controller | Wednesday 08 October 2025  00:14:13 +0000 (0:00:00.050)       0:00:56.889 *****
2025-10-08 00:14:13.662992 | controller | ok: [instance] =>
2025-10-08 00:14:13.663025 | controller |   cifmw_ci_setup_packages:
2025-10-08 00:14:13.663033 | controller |   - bash-completion
2025-10-08 00:14:13.663039 | controller |   - ca-certificates
2025-10-08 00:14:13.663047 | controller |   - git-core
2025-10-08 00:14:13.663127 | controller |   - make
2025-10-08 00:14:13.663141 | controller |   - tar
2025-10-08 00:14:13.663239 | controller |   - tmux
2025-10-08 00:14:13.663345 | controller |   - python3-pip
2025-10-08 00:14:13.663441 | controller |
2025-10-08 00:14:13.663542 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-10-08 00:14:13.663665 | controller | Wednesday 08 October 2025  00:14:13 +0000 (0:00:00.023)       0:00:56.913 *****
2025-10-08 00:14:14.985147 | controller | ok: [instance]
2025-10-08 00:14:15.276661 | controller |
2025-10-08 00:14:15.276685 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-10-08 00:14:15.276694 | controller | Wednesday 08 October 2025  00:14:14 +0000 (0:00:01.322)       0:00:58.236 *****
2025-10-08 00:14:15.276703 | controller | ok: [instance]
2025-10-08 00:14:15.504185 | controller |
2025-10-08 00:14:15.504209 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-10-08 00:14:15.504217 | controller | Wednesday 08 October 2025  00:14:15 +0000 (0:00:00.291)       0:00:58.527 *****
2025-10-08 00:14:15.504225 | controller | ok: [instance]
2025-10-08 00:14:15.579400 | controller |
2025-10-08 00:14:15.579416 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-10-08 00:14:15.579433 | controller | Wednesday 08 October 2025  00:14:15 +0000 (0:00:00.227)       0:00:58.755 *****
2025-10-08 00:14:15.579442 | controller | skipping: [instance]
2025-10-08 00:14:15.617476 | controller |
2025-10-08 00:14:15.617493 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-10-08 00:14:15.617501 | controller | Wednesday 08 October 2025  00:14:15 +0000 (0:00:00.075)       0:00:58.830 *****
2025-10-08 00:14:15.617509 | controller | skipping: [instance]
2025-10-08 00:14:15.815653 | controller |
2025-10-08 00:14:15.815678 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-10-08 00:14:15.815688 | controller | Wednesday 08 October 2025  00:14:15 +0000 (0:00:00.037)       0:00:58.868 *****
2025-10-08 00:14:15.815701 | controller | ok: [instance]
2025-10-08 00:14:16.101990 | controller |
2025-10-08 00:14:16.102071 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-10-08 00:14:16.102083 | controller | Wednesday 08 October 2025  00:14:15 +0000 (0:00:00.198)       0:00:59.066 *****
2025-10-08 00:14:16.102101 | controller | changed: [instance]
2025-10-08 00:14:16.132698 | controller |
2025-10-08 00:14:16.132761 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-10-08 00:14:16.132772 | controller | Wednesday 08 October 2025  00:14:16 +0000 (0:00:00.285)       0:00:59.352 *****
2025-10-08 00:14:16.132785 | controller | skipping: [instance]
2025-10-08 00:14:16.155888 | controller |
2025-10-08 00:14:16.155956 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-10-08 00:14:16.155983 | controller | Wednesday 08 October 2025  00:14:16 +0000 (0:00:00.031)       0:00:59.384 *****
2025-10-08 00:14:16.155998 | controller | skipping: [instance]
2025-10-08 00:14:16.156055 | controller |
2025-10-08 00:14:16.156067 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-10-08 00:14:16.156266 | controller | Wednesday 08 October 2025  00:14:16 +0000 (0:00:00.023)       0:00:59.407 *****
2025-10-08 00:14:16.176528 | controller | skipping: [instance]
2025-10-08 00:14:16.200500 | controller |
2025-10-08 00:14:16.200544 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-10-08 00:14:16.200557 | controller | Wednesday 08 October 2025  00:14:16 +0000 (0:00:00.020)       0:00:59.427 *****
2025-10-08 00:14:16.200566 | controller | skipping: [instance]
2025-10-08 00:14:16.221841 | controller |
2025-10-08 00:14:16.221874 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-10-08 00:14:16.221887 | controller | Wednesday 08 October 2025  00:14:16 +0000 (0:00:00.023)       0:00:59.451 *****
2025-10-08 00:14:16.221902 | controller | skipping: [instance]
2025-10-08 00:14:16.222181 | controller |
2025-10-08 00:14:16.222444 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-10-08 00:14:16.222658 | controller | Wednesday 08 October 2025  00:14:16 +0000 (0:00:00.021)       0:00:59.473 *****
2025-10-08 00:14:16.252635 | controller | skipping: [instance]
2025-10-08 00:14:16.252819 | controller |
2025-10-08 00:14:16.252956 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-10-08 00:14:16.253098 | controller | Wednesday 08 October 2025  00:14:16 +0000 (0:00:00.030)       0:00:59.504 *****
2025-10-08 00:14:17.325515 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-10-08 00:14:17.366913 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-10-08 00:14:17.366953 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-10-08 00:14:17.366978 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-10-08 00:14:17.366985 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-10-08 00:14:17.366993 | controller |
2025-10-08 00:14:17.366999 | controller | TASK [Check for CRC startup] ***************************************************
2025-10-08 00:14:17.367006 | controller | Wednesday 08 October 2025  00:14:17 +0000 (0:00:01.071)       0:01:00.576 *****
2025-10-08 00:14:17.367018 | controller | skipping: [instance]
2025-10-08 00:14:17.367050 | controller |
2025-10-08 00:14:17.367058 | controller | PLAY RECAP *********************************************************************
2025-10-08 00:14:17.367067 | controller | instance                   : ok=28   changed=5    unreachable=0    failed=0    skipped=42   rescued=0    ignored=0
2025-10-08 00:14:17.369140 | controller |
2025-10-08 00:14:17.369182 | controller | Wednesday 08 October 2025  00:14:17 +0000 (0:00:00.041)       0:01:00.617 *****
2025-10-08 00:14:17.431995 | controller | ===============================================================================
2025-10-08 00:14:17.432028 | controller | test_deps : Install selinux python libs -------------------------------- 45.35s
2025-10-08 00:14:17.432036 | controller | ci_setup : Install needed packages -------------------------------------- 1.32s
2025-10-08 00:14:17.432041 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.32s
2025-10-08 00:14:17.432047 | controller | repo_setup : Initialize python venv and install requirements ------------ 1.24s
2025-10-08 00:14:17.432059 | controller | test_deps : Install python yaml libs ------------------------------------ 1.19s
2025-10-08 00:14:17.432065 | controller | ci_setup : Manage directories ------------------------------------------- 1.07s
2025-10-08 00:14:17.432070 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.90s
2025-10-08 00:14:17.432076 | controller | Gathering Facts --------------------------------------------------------- 0.87s
2025-10-08 00:14:17.432081 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.70s
2025-10-08 00:14:17.432087 | controller | Inject our own NetworkManager configuration ----------------------------- 0.65s
2025-10-08 00:14:17.432092 | controller | repo_setup : Get repo-setup repository ---------------------------------- 0.60s
2025-10-08 00:14:17.432097 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s
2025-10-08 00:14:17.432102 | controller | repo_setup : Ensure directories are present ----------------------------- 0.56s
2025-10-08 00:14:17.432107 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.36s
2025-10-08 00:14:17.432122 | controller | repo_setup : Install repo-setup package --------------------------------- 0.29s
2025-10-08 00:14:17.432127 | controller | ci_setup : Gather version of openshift client --------------------------- 0.29s
2025-10-08 00:14:17.432132 | controller | ci_setup : Source completion from within .bashrc ------------------------ 0.29s
2025-10-08 00:14:17.432138 | controller | ci_setup : Ensure openshift client install path is present -------------- 0.23s
2025-10-08 00:14:17.432143 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.23s
2025-10-08 00:14:17.432148 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.22s
2025-10-08 00:14:17.432158 | controller | INFO     Running binary > converge
2025-10-08 00:14:17.875819 | controller |
2025-10-08 00:14:17.876191 | controller | PLAY [Converge binary] *********************************************************
2025-10-08 00:14:17.876334 | controller |
2025-10-08 00:14:17.876476 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 00:14:17.876618 | controller | Wednesday 08 October 2025  00:14:17 +0000 (0:00:00.015)       0:00:00.015 *****
2025-10-08 00:14:18.762900 | controller | ok: [instance]
2025-10-08 00:14:18.762981 | controller |
2025-10-08 00:14:18.763320 | controller | TASK [rhol_crc : Create bin directory if it does not exist] ********************
2025-10-08 00:14:19.081378 | controller | Wednesday 08 October 2025  00:14:18 +0000 (0:00:00.887)       0:00:00.903 *****
2025-10-08 00:14:19.081408 | controller | ok: [instance]
2025-10-08 00:14:19.348443 | controller |
2025-10-08 00:14:19.348474 | controller | TASK [rhol_crc : Create temporary directory for downloading RHOL/CRC] **********
2025-10-08 00:14:19.348482 | controller | Wednesday 08 October 2025  00:14:19 +0000 (0:00:00.318)       0:00:01.221 *****
2025-10-08 00:14:19.348493 | controller | changed: [instance]
2025-10-08 00:14:24.287513 | controller |
2025-10-08 00:14:24.287551 | controller | TASK [rhol_crc : Download RHOL/CRC with sha256 checksum] ***********************
2025-10-08 00:14:24.287559 | controller | Wednesday 08 October 2025  00:14:19 +0000 (0:00:00.266)       0:00:01.488 *****
2025-10-08 00:14:24.287569 | controller | changed: [instance]
2025-10-08 00:14:29.207243 | controller |
2025-10-08 00:14:29.207276 | controller | TASK [rhol_crc : Unarchive RHOL/CRC binary] ************************************
2025-10-08 00:14:29.207284 | controller | Wednesday 08 October 2025  00:14:24 +0000 (0:00:04.938)       0:00:06.427 *****
2025-10-08 00:14:29.207294 | controller | changed: [instance]
2025-10-08 00:14:29.418835 | controller |
2025-10-08 00:14:29.418856 | controller | TASK [rhol_crc : Delete temporary directory for downloading RHOL/CRC] **********
2025-10-08 00:14:29.418864 | controller | Wednesday 08 October 2025  00:14:29 +0000 (0:00:04.919)       0:00:11.347 *****
2025-10-08 00:14:29.418873 | controller | ok: [instance]
2025-10-08 00:14:29.419071 | controller |
2025-10-08 00:14:29.419090 | controller | TASK [rhol_crc : Get CRC binary version] ***************************************
2025-10-08 00:14:30.181478 | controller | Wednesday 08 October 2025  00:14:29 +0000 (0:00:00.212)       0:00:11.559 *****
2025-10-08 00:14:30.181521 | controller | changed: [instance]
2025-10-08 00:14:30.226788 | controller |
2025-10-08 00:14:30.226822 | controller | TASK [rhol_crc : Extract versions] *********************************************
2025-10-08 00:14:30.226846 | controller | Wednesday 08 October 2025  00:14:30 +0000 (0:00:00.762)       0:00:12.321 *****
2025-10-08 00:14:30.226863 | controller | ok: [instance]
2025-10-08 00:14:30.281786 | controller |
2025-10-08 00:14:30.281803 | controller | TASK [rhol_crc : Output found binary location and versions] ********************
2025-10-08 00:14:30.281810 | controller | Wednesday 08 October 2025  00:14:30 +0000 (0:00:00.045)       0:00:12.366 *****
2025-10-08 00:14:30.281818 | controller | ok: [instance] =>
2025-10-08 00:14:30.281840 | 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-10-08 00:14:30.281846 | controller |
2025-10-08 00:14:30.281851 | controller | PLAY [Verify get_version] ******************************************************
2025-10-08 00:14:30.281857 | controller |
2025-10-08 00:14:30.282100 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 00:14:30.935916 | controller | Wednesday 08 October 2025  00:14:30 +0000 (0:00:00.055)       0:00:12.422 *****
2025-10-08 00:14:30.936008 | controller | ok: [instance]
2025-10-08 00:14:30.979492 | controller |
2025-10-08 00:14:30.979531 | controller | TASK [Ensure facts are set and look correct] ***********************************
2025-10-08 00:14:30.979539 | controller | Wednesday 08 October 2025  00:14:30 +0000 (0:00:00.653)       0:00:13.075 *****
2025-10-08 00:14:30.979549 | controller | ok: [instance] => changed=false
2025-10-08 00:14:30.979574 | controller |   msg: All assertions passed
2025-10-08 00:14:30.979886 | controller |
2025-10-08 00:14:30.979938 | controller | PLAY RECAP *********************************************************************
2025-10-08 00:14:30.979991 | controller | instance                   : ok=11   changed=4    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 00:14:30.980004 | controller |
2025-10-08 00:14:30.981268 | controller | Wednesday 08 October 2025  00:14:30 +0000 (0:00:00.044)       0:00:13.120 *****
2025-10-08 00:14:31.101262 | controller | ===============================================================================
2025-10-08 00:14:31.101281 | controller | rhol_crc : Download RHOL/CRC with sha256 checksum ----------------------- 4.94s
2025-10-08 00:14:31.101289 | controller | rhol_crc : Unarchive RHOL/CRC binary ------------------------------------ 4.92s
2025-10-08 00:14:31.101294 | controller | Gathering Facts --------------------------------------------------------- 0.89s
2025-10-08 00:14:31.101299 | controller | rhol_crc : Get CRC binary version --------------------------------------- 0.76s
2025-10-08 00:14:31.101304 | controller | Gathering Facts --------------------------------------------------------- 0.65s
2025-10-08 00:14:31.101309 | controller | rhol_crc : Create bin directory if it does not exist -------------------- 0.32s
2025-10-08 00:14:31.101314 | controller | rhol_crc : Create temporary directory for downloading RHOL/CRC ---------- 0.27s
2025-10-08 00:14:31.101319 | controller | rhol_crc : Delete temporary directory for downloading RHOL/CRC ---------- 0.21s
2025-10-08 00:14:31.101323 | controller | rhol_crc : Output found binary location and versions -------------------- 0.06s
2025-10-08 00:14:31.101328 | controller | rhol_crc : Extract versions --------------------------------------------- 0.05s
2025-10-08 00:14:31.101333 | controller | Ensure facts are set and look correct ----------------------------------- 0.04s
2025-10-08 00:14:31.101341 | controller | INFO     Running binary > cleanup
2025-10-08 00:14:31.498177 | controller |
2025-10-08 00:14:31.498496 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 00:14:31.498650 | controller |
2025-10-08 00:14:31.498798 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 00:14:31.498933 | controller | Wednesday 08 October 2025  00:14:31 +0000 (0:00:00.014)       0:00:00.014 *****
2025-10-08 00:14:32.348613 | controller | ok: [instance]
2025-10-08 00:14:32.799085 | controller |
2025-10-08 00:14:32.799123 | controller | TASK [Cleanup RHOL/CRC after tests] ********************************************
2025-10-08 00:14:32.799132 | controller | Wednesday 08 October 2025  00:14:32 +0000 (0:00:00.850)       0:00:00.864 *****
2025-10-08 00:14:32.799143 | controller | changed: [instance]
2025-10-08 00:14:32.799174 | controller |
2025-10-08 00:14:32.799181 | controller | PLAY RECAP *********************************************************************
2025-10-08 00:14:32.799189 | controller | instance                   : ok=2    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 00:14:32.799523 | controller |
2025-10-08 00:14:32.799541 | controller | Wednesday 08 October 2025  00:14:32 +0000 (0:00:00.450)       0:00:01.315 *****
2025-10-08 00:14:32.858793 | controller | ===============================================================================
2025-10-08 00:14:32.858806 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-10-08 00:14:32.858819 | controller | Cleanup RHOL/CRC after tests -------------------------------------------- 0.45s
2025-10-08 00:14:32.858829 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-08 00:14:49.857032 | 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-10-08 00:14:49.857387 | 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-10-08 00:14:49.857819 | 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-10-08 00:14:49.858243 | 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-10-08 00:14:49.858676 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+6451eac5 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0+6451eac5 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 00:14:49.859101 | 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-10-08 00:14:49.859503 | 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-10-08 00:14:49.859922 | 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-10-08 00:14:49.860335 | 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-10-08 00:14:49.860745 | 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-10-08 00:14:49.861151 | 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-10-08 00:14:49.861548 | 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-10-08 00:14:49.862201 | 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-10-08 00:14:49.862615 | 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-10-08 00:14:49.863048 | 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-10-08 00:14:49.871750 | controller | INFO     Running default > prepare
2025-10-08 00:14:50.298342 | controller |
2025-10-08 00:14:50.298427 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 00:14:50.298558 | controller |
2025-10-08 00:14:50.298667 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 00:14:50.298801 | controller | Wednesday 08 October 2025  00:14:50 +0000 (0:00:00.017)       0:00:00.017 *****
2025-10-08 00:14:51.088908 | controller | ok: [instance]
2025-10-08 00:14:51.088994 | controller |
2025-10-08 00:14:51.089371 | controller | TASK [Setup libvirt] ***********************************************************
2025-10-08 00:14:51.113123 | controller | Wednesday 08 October 2025  00:14:51 +0000 (0:00:00.790)       0:00:00.807 *****
2025-10-08 00:14:51.113144 | controller | skipping: [instance]
2025-10-08 00:14:51.137734 | controller |
2025-10-08 00:14:51.137754 | controller | TASK [Setup CRC] ***************************************************************
2025-10-08 00:14:51.137762 | controller | Wednesday 08 October 2025  00:14:51 +0000 (0:00:00.024)       0:00:00.831 *****
2025-10-08 00:14:51.137772 | controller | skipping: [instance]
2025-10-08 00:14:51.159682 | controller |
2025-10-08 00:14:51.159703 | controller | TASK [Start RHOL/CRC] **********************************************************
2025-10-08 00:14:51.159712 | controller | Wednesday 08 October 2025  00:14:51 +0000 (0:00:00.024)       0:00:00.856 *****
2025-10-08 00:14:51.159739 | controller | skipping: [instance]
2025-10-08 00:14:51.716841 | controller |
2025-10-08 00:14:51.716885 | controller | TASK [Inject our own NetworkManager configuration] *****************************
2025-10-08 00:14:51.716893 | controller | Wednesday 08 October 2025  00:14:51 +0000 (0:00:00.022)       0:00:00.878 *****
2025-10-08 00:14:51.716904 | controller | ok: [instance]
2025-10-08 00:14:51.736843 | controller |
2025-10-08 00:14:51.736868 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-08 00:14:51.736881 | controller | Wednesday 08 October 2025  00:14:51 +0000 (0:00:00.556)       0:00:01.435 *****
2025-10-08 00:14:51.736891 | controller | skipping: [instance]
2025-10-08 00:14:51.736920 | controller |
2025-10-08 00:14:51.737125 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-08 00:14:51.779703 | controller | Wednesday 08 October 2025  00:14:51 +0000 (0:00:00.020)       0:00:01.455 *****
2025-10-08 00:14:51.779777 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-08 00:14:51.954848 | controller |
2025-10-08 00:14:51.954885 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-08 00:14:51.954893 | controller | Wednesday 08 October 2025  00:14:51 +0000 (0:00:00.042)       0:00:01.498 *****
2025-10-08 00:14:51.954903 | controller | ok: [instance]
2025-10-08 00:14:51.954937 | controller |
2025-10-08 00:14:51.954955 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-08 00:14:51.955095 | controller | Wednesday 08 October 2025  00:14:51 +0000 (0:00:00.175)       0:00:01.673 *****
2025-10-08 00:14:52.157192 | controller | ok: [instance]
2025-10-08 00:14:52.820411 | controller |
2025-10-08 00:14:52.820444 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-08 00:14:52.820453 | controller | Wednesday 08 October 2025  00:14:52 +0000 (0:00:00.202)       0:00:01.875 *****
2025-10-08 00:14:52.820464 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-08 00:14:52.881244 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-08 00:14:52.881271 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-08 00:14:52.881278 | controller |
2025-10-08 00:14:52.881284 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-08 00:14:52.881289 | controller | Wednesday 08 October 2025  00:14:52 +0000 (0:00:00.662)       0:00:02.538 *****
2025-10-08 00:14:52.881298 | controller |
2025-10-08 00:14:53.418519 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-08 00:14:53.418581 | controller | Wednesday 08 October 2025  00:14:52 +0000 (0:00:00.061)       0:00:02.600 *****
2025-10-08 00:14:53.418595 | controller | ok: [instance] => (item=tmp)
2025-10-08 00:14:54.715510 | controller | ok: [instance] => (item=artifacts/repositories)
2025-10-08 00:14:54.715565 | controller | ok: [instance] => (item=venv/repo_setup)
2025-10-08 00:14:54.715573 | controller |
2025-10-08 00:14:54.715580 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-08 00:14:54.715587 | controller | Wednesday 08 October 2025  00:14:53 +0000 (0:00:00.535)       0:00:03.136 *****
2025-10-08 00:14:54.715611 | controller | ok: [instance]
2025-10-08 00:14:55.278614 | controller |
2025-10-08 00:14:55.278672 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-08 00:14:55.278681 | controller | Wednesday 08 October 2025  00:14:54 +0000 (0:00:01.297)       0:00:04.433 *****
2025-10-08 00:14:55.278694 | controller | ok: [instance]
2025-10-08 00:14:56.479330 | controller |
2025-10-08 00:14:56.479385 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-08 00:14:56.479402 | controller | Wednesday 08 October 2025  00:14:55 +0000 (0:00:00.562)       0:00:04.996 *****
2025-10-08 00:14:56.479416 | controller | ok: [instance]
2025-10-08 00:14:56.762694 | controller |
2025-10-08 00:14:56.762758 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-08 00:14:56.762768 | controller | Wednesday 08 October 2025  00:14:56 +0000 (0:00:01.200)       0:00:06.197 *****
2025-10-08 00:14:56.762781 | controller | ok: [instance]
2025-10-08 00:14:56.786836 | controller |
2025-10-08 00:14:56.786865 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-08 00:14:56.786875 | controller | Wednesday 08 October 2025  00:14:56 +0000 (0:00:00.283)       0:00:06.480 *****
2025-10-08 00:14:56.786887 | controller | skipping: [instance]
2025-10-08 00:14:57.640628 | controller |
2025-10-08 00:14:57.640671 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-08 00:14:57.640679 | controller | Wednesday 08 October 2025  00:14:56 +0000 (0:00:00.024)       0:00:06.505 *****
2025-10-08 00:14:57.640691 | controller | changed: [instance]
2025-10-08 00:14:57.680430 | controller |
2025-10-08 00:14:57.680463 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-08 00:14:57.680471 | controller | Wednesday 08 October 2025  00:14:57 +0000 (0:00:00.853)       0:00:07.358 *****
2025-10-08 00:14:57.680484 | controller | skipping: [instance]
2025-10-08 00:14:57.718248 | controller |
2025-10-08 00:14:57.718274 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-08 00:14:57.718282 | controller | Wednesday 08 October 2025  00:14:57 +0000 (0:00:00.040)       0:00:07.398 *****
2025-10-08 00:14:57.718292 | controller | skipping: [instance]
2025-10-08 00:14:57.756631 | controller |
2025-10-08 00:14:57.756663 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-08 00:14:57.756672 | controller | Wednesday 08 October 2025  00:14:57 +0000 (0:00:00.037)       0:00:07.436 *****
2025-10-08 00:14:57.756683 | controller | skipping: [instance]
2025-10-08 00:14:58.287294 | controller |
2025-10-08 00:14:58.287334 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-08 00:14:58.287342 | controller | Wednesday 08 October 2025  00:14:57 +0000 (0:00:00.038)       0:00:07.475 *****
2025-10-08 00:14:58.287353 | controller | changed: [instance]
2025-10-08 00:14:58.635593 | controller |
2025-10-08 00:14:58.635629 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-08 00:14:58.635644 | controller | Wednesday 08 October 2025  00:14:58 +0000 (0:00:00.530)       0:00:08.005 *****
2025-10-08 00:14:58.635655 | controller | ok: [instance]
2025-10-08 00:14:58.667714 | controller |
2025-10-08 00:14:58.667755 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-08 00:14:58.667764 | controller | Wednesday 08 October 2025  00:14:58 +0000 (0:00:00.348)       0:00:08.353 *****
2025-10-08 00:14:58.667775 | controller | skipping: [instance]
2025-10-08 00:14:58.700255 | controller |
2025-10-08 00:14:58.700277 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-08 00:14:58.700285 | controller | Wednesday 08 October 2025  00:14:58 +0000 (0:00:00.032)       0:00:08.386 *****
2025-10-08 00:14:58.700295 | controller | skipping: [instance]
2025-10-08 00:14:58.729838 | controller |
2025-10-08 00:14:58.729876 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-08 00:14:58.729884 | controller | Wednesday 08 October 2025  00:14:58 +0000 (0:00:00.032)       0:00:08.418 *****
2025-10-08 00:14:58.729894 | controller | skipping: [instance]
2025-10-08 00:14:58.729920 | controller |
2025-10-08 00:14:58.729927 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-08 00:14:58.730025 | controller | Wednesday 08 October 2025  00:14:58 +0000 (0:00:00.029)       0:00:08.448 *****
2025-10-08 00:14:58.769062 | controller | ok: [instance]
2025-10-08 00:14:58.769311 | controller |
2025-10-08 00:14:58.769330 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-08 00:14:58.798695 | controller | Wednesday 08 October 2025  00:14:58 +0000 (0:00:00.039)       0:00:08.487 *****
2025-10-08 00:14:58.798741 | controller | skipping: [instance]
2025-10-08 00:14:58.829672 | controller |
2025-10-08 00:14:58.829694 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-08 00:14:58.829703 | controller | Wednesday 08 October 2025  00:14:58 +0000 (0:00:00.029)       0:00:08.517 *****
2025-10-08 00:14:58.829713 | controller | skipping: [instance]
2025-10-08 00:14:58.829908 | controller |
2025-10-08 00:14:58.830092 | controller | TASK [Download the RPM] ********************************************************
2025-10-08 00:14:58.830247 | controller | Wednesday 08 October 2025  00:14:58 +0000 (0:00:00.031)       0:00:08.548 *****
2025-10-08 00:14:58.857305 | controller | skipping: [instance]
2025-10-08 00:14:58.857477 | controller |
2025-10-08 00:14:58.857635 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-08 00:14:58.857824 | controller | Wednesday 08 October 2025  00:14:58 +0000 (0:00:00.027)       0:00:08.576 *****
2025-10-08 00:14:58.885413 | controller | skipping: [instance]
2025-10-08 00:14:58.885584 | controller |
2025-10-08 00:14:58.885767 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-08 00:14:58.885940 | controller | Wednesday 08 October 2025  00:14:58 +0000 (0:00:00.028)       0:00:08.604 *****
2025-10-08 00:14:58.912304 | controller | skipping: [instance]
2025-10-08 00:14:58.912467 | controller |
2025-10-08 00:14:58.912630 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-08 00:14:58.912795 | controller | Wednesday 08 October 2025  00:14:58 +0000 (0:00:00.026)       0:00:08.631 *****
2025-10-08 00:14:58.942541 | controller | skipping: [instance]
2025-10-08 00:14:58.942701 | controller |
2025-10-08 00:14:58.942895 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-08 00:14:58.943068 | controller | Wednesday 08 October 2025  00:14:58 +0000 (0:00:00.030)       0:00:08.661 *****
2025-10-08 00:14:58.969744 | controller | skipping: [instance]
2025-10-08 00:14:58.969920 | controller |
2025-10-08 00:14:58.970086 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-08 00:14:58.970236 | controller | Wednesday 08 October 2025  00:14:58 +0000 (0:00:00.027)       0:00:08.688 *****
2025-10-08 00:14:59.148857 | controller | ok: [instance]
2025-10-08 00:14:59.149049 | controller |
2025-10-08 00:14:59.149067 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-08 00:14:59.353377 | controller | Wednesday 08 October 2025  00:14:59 +0000 (0:00:00.178)       0:00:08.867 *****
2025-10-08 00:14:59.353415 | controller | changed: [instance]
2025-10-08 00:14:59.568423 | controller |
2025-10-08 00:14:59.568460 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-08 00:14:59.568468 | controller | Wednesday 08 October 2025  00:14:59 +0000 (0:00:00.204)       0:00:09.071 *****
2025-10-08 00:14:59.568480 | controller | changed: [instance]
2025-10-08 00:14:59.590788 | controller |
2025-10-08 00:14:59.590834 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-08 00:14:59.590842 | controller | Wednesday 08 October 2025  00:14:59 +0000 (0:00:00.214)       0:00:09.286 *****
2025-10-08 00:14:59.590852 | controller | skipping: [instance]
2025-10-08 00:14:59.590876 | controller |
2025-10-08 00:14:59.591052 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-08 00:14:59.618331 | controller | Wednesday 08 October 2025  00:14:59 +0000 (0:00:00.023)       0:00:09.309 *****
2025-10-08 00:14:59.618354 | controller | skipping: [instance]
2025-10-08 00:14:59.644325 | controller |
2025-10-08 00:14:59.644343 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-08 00:14:59.644351 | controller | Wednesday 08 October 2025  00:14:59 +0000 (0:00:00.027)       0:00:09.337 *****
2025-10-08 00:14:59.644361 | controller | skipping: [instance]
2025-10-08 00:14:59.668524 | controller |
2025-10-08 00:14:59.668545 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-08 00:14:59.668561 | controller | Wednesday 08 October 2025  00:14:59 +0000 (0:00:00.026)       0:00:09.363 *****
2025-10-08 00:14:59.668571 | controller | skipping: [instance]
2025-10-08 00:14:59.692807 | controller |
2025-10-08 00:14:59.692836 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-08 00:14:59.692845 | controller | Wednesday 08 October 2025  00:14:59 +0000 (0:00:00.024)       0:00:09.387 *****
2025-10-08 00:14:59.692856 | controller | skipping: [instance]
2025-10-08 00:14:59.692885 | controller |
2025-10-08 00:14:59.693141 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-08 00:14:59.718879 | controller | Wednesday 08 October 2025  00:14:59 +0000 (0:00:00.024)       0:00:09.411 *****
2025-10-08 00:14:59.718928 | controller | skipping: [instance]
2025-10-08 00:14:59.738367 | controller |
2025-10-08 00:14:59.738391 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-08 00:14:59.738399 | controller | Wednesday 08 October 2025  00:14:59 +0000 (0:00:00.025)       0:00:09.437 *****
2025-10-08 00:14:59.738410 | controller | skipping: [instance]
2025-10-08 00:14:59.768853 | controller |
2025-10-08 00:14:59.768878 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-08 00:14:59.768886 | controller | Wednesday 08 October 2025  00:14:59 +0000 (0:00:00.019)       0:00:09.457 *****
2025-10-08 00:14:59.768896 | controller | skipping: [instance]
2025-10-08 00:14:59.769063 | controller |
2025-10-08 00:14:59.769195 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-08 00:14:59.769324 | controller | Wednesday 08 October 2025  00:14:59 +0000 (0:00:00.030)       0:00:09.487 *****
2025-10-08 00:14:59.789415 | controller | skipping: [instance]
2025-10-08 00:14:59.789571 | controller |
2025-10-08 00:14:59.789751 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-08 00:14:59.789929 | controller | Wednesday 08 October 2025  00:14:59 +0000 (0:00:00.020)       0:00:09.508 *****
2025-10-08 00:14:59.813579 | controller | skipping: [instance]
2025-10-08 00:14:59.813772 | controller |
2025-10-08 00:14:59.813950 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-08 00:14:59.814135 | controller | Wednesday 08 October 2025  00:14:59 +0000 (0:00:00.024)       0:00:09.532 *****
2025-10-08 00:14:59.843930 | controller | skipping: [instance]
2025-10-08 00:14:59.844115 | controller |
2025-10-08 00:14:59.844259 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-08 00:14:59.844384 | controller | Wednesday 08 October 2025  00:14:59 +0000 (0:00:00.030)       0:00:09.562 *****
2025-10-08 00:14:59.873421 | controller | skipping: [instance]
2025-10-08 00:14:59.873593 | controller |
2025-10-08 00:14:59.873795 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-08 00:14:59.873958 | controller | Wednesday 08 October 2025  00:14:59 +0000 (0:00:00.029)       0:00:09.592 *****
2025-10-08 00:14:59.901630 | controller | skipping: [instance]
2025-10-08 00:14:59.901836 | controller |
2025-10-08 00:14:59.902014 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-08 00:14:59.902170 | controller | Wednesday 08 October 2025  00:14:59 +0000 (0:00:00.028)       0:00:09.620 *****
2025-10-08 00:14:59.932815 | controller | skipping: [instance]
2025-10-08 00:14:59.933000 | controller |
2025-10-08 00:14:59.933163 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-08 00:14:59.933315 | controller | Wednesday 08 October 2025  00:14:59 +0000 (0:00:00.031)       0:00:09.651 *****
2025-10-08 00:15:42.124793 | controller | ok: [instance]
2025-10-08 00:15:43.330648 | controller |
2025-10-08 00:15:43.330683 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-08 00:15:43.330690 | controller | Wednesday 08 October 2025  00:15:42 +0000 (0:00:42.190)       0:00:51.842 *****
2025-10-08 00:15:43.330699 | controller | ok: [instance]
2025-10-08 00:15:43.366385 | controller |
2025-10-08 00:15:43.366401 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-08 00:15:43.366409 | controller | Wednesday 08 October 2025  00:15:43 +0000 (0:00:01.206)       0:00:53.049 *****
2025-10-08 00:15:43.366423 | controller | skipping: [instance]
2025-10-08 00:15:43.415865 | controller |
2025-10-08 00:15:43.415902 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-10-08 00:15:43.415910 | controller | Wednesday 08 October 2025  00:15:43 +0000 (0:00:00.035)       0:00:53.084 *****
2025-10-08 00:15:43.415922 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-10-08 00:15:43.439855 | controller |
2025-10-08 00:15:43.439879 | controller | TASK [ci_setup : List packages to install] *************************************
2025-10-08 00:15:43.439888 | controller | Wednesday 08 October 2025  00:15:43 +0000 (0:00:00.049)       0:00:53.134 *****
2025-10-08 00:15:43.439899 | controller | ok: [instance] =>
2025-10-08 00:15:43.439930 | controller |   cifmw_ci_setup_packages:
2025-10-08 00:15:43.439940 | controller |   - bash-completion
2025-10-08 00:15:43.440805 | controller |   - ca-certificates
2025-10-08 00:15:43.440949 | controller |   - git-core
2025-10-08 00:15:43.440967 | controller |   - make
2025-10-08 00:15:43.440985 | controller |   - tar
2025-10-08 00:15:43.440991 | controller |   - tmux
2025-10-08 00:15:43.440996 | controller |   - python3-pip
2025-10-08 00:15:43.441002 | controller |
2025-10-08 00:15:43.441011 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-10-08 00:15:44.645553 | controller | Wednesday 08 October 2025  00:15:43 +0000 (0:00:00.024)       0:00:53.158 *****
2025-10-08 00:15:44.645586 | controller | ok: [instance]
2025-10-08 00:15:44.927876 | controller |
2025-10-08 00:15:44.927913 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-10-08 00:15:44.927921 | controller | Wednesday 08 October 2025  00:15:44 +0000 (0:00:01.205)       0:00:54.363 *****
2025-10-08 00:15:44.927931 | controller | ok: [instance]
2025-10-08 00:15:45.130169 | controller |
2025-10-08 00:15:45.130206 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-10-08 00:15:45.130213 | controller | Wednesday 08 October 2025  00:15:44 +0000 (0:00:00.282)       0:00:54.646 *****
2025-10-08 00:15:45.130223 | controller | ok: [instance]
2025-10-08 00:15:45.202166 | controller |
2025-10-08 00:15:45.202184 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-10-08 00:15:45.202191 | controller | Wednesday 08 October 2025  00:15:45 +0000 (0:00:00.202)       0:00:54.848 *****
2025-10-08 00:15:45.202199 | controller | skipping: [instance]
2025-10-08 00:15:45.237210 | controller |
2025-10-08 00:15:45.237227 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-10-08 00:15:45.237234 | controller | Wednesday 08 October 2025  00:15:45 +0000 (0:00:00.072)       0:00:54.921 *****
2025-10-08 00:15:45.237242 | controller | skipping: [instance]
2025-10-08 00:15:45.418895 | controller |
2025-10-08 00:15:45.418939 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-10-08 00:15:45.418949 | controller | Wednesday 08 October 2025  00:15:45 +0000 (0:00:00.034)       0:00:54.955 *****
2025-10-08 00:15:45.418962 | controller | ok: [instance]
2025-10-08 00:15:45.698331 | controller |
2025-10-08 00:15:45.698369 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-10-08 00:15:45.698378 | controller | Wednesday 08 October 2025  00:15:45 +0000 (0:00:00.181)       0:00:55.137 *****
2025-10-08 00:15:45.698398 | controller | ok: [instance]
2025-10-08 00:15:45.717129 | controller |
2025-10-08 00:15:45.717146 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-10-08 00:15:45.717153 | controller | Wednesday 08 October 2025  00:15:45 +0000 (0:00:00.279)       0:00:55.417 *****
2025-10-08 00:15:45.717161 | controller | skipping: [instance]
2025-10-08 00:15:45.717289 | controller |
2025-10-08 00:15:45.717417 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-10-08 00:15:45.717542 | controller | Wednesday 08 October 2025  00:15:45 +0000 (0:00:00.019)       0:00:55.436 *****
2025-10-08 00:15:45.735112 | controller | skipping: [instance]
2025-10-08 00:15:45.735381 | controller |
2025-10-08 00:15:45.735637 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-10-08 00:15:45.735956 | controller | Wednesday 08 October 2025  00:15:45 +0000 (0:00:00.018)       0:00:55.454 *****
2025-10-08 00:15:45.756932 | controller | skipping: [instance]
2025-10-08 00:15:45.757131 | controller |
2025-10-08 00:15:45.757296 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-10-08 00:15:45.757422 | controller | Wednesday 08 October 2025  00:15:45 +0000 (0:00:00.019)       0:00:55.474 *****
2025-10-08 00:15:45.777249 | controller | skipping: [instance]
2025-10-08 00:15:45.777418 | controller |
2025-10-08 00:15:45.777573 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-10-08 00:15:45.777754 | controller | Wednesday 08 October 2025  00:15:45 +0000 (0:00:00.022)       0:00:55.496 *****
2025-10-08 00:15:45.794847 | controller | skipping: [instance]
2025-10-08 00:15:45.794955 | controller |
2025-10-08 00:15:45.795195 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-10-08 00:15:45.795314 | controller | Wednesday 08 October 2025  00:15:45 +0000 (0:00:00.017)       0:00:55.513 *****
2025-10-08 00:15:45.817224 | controller | skipping: [instance]
2025-10-08 00:15:45.817306 | controller |
2025-10-08 00:15:45.817473 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-10-08 00:15:45.817573 | controller | Wednesday 08 October 2025  00:15:45 +0000 (0:00:00.022)       0:00:55.536 *****
2025-10-08 00:15:46.811873 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-10-08 00:15:46.850421 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-10-08 00:15:46.850444 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-10-08 00:15:46.850451 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-10-08 00:15:46.850457 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-10-08 00:15:46.850462 | controller |
2025-10-08 00:15:46.850468 | controller | TASK [Check for CRC startup] ***************************************************
2025-10-08 00:15:46.850473 | controller | Wednesday 08 October 2025  00:15:46 +0000 (0:00:00.993)       0:00:56.529 *****
2025-10-08 00:15:46.850481 | controller | skipping: [instance]
2025-10-08 00:15:46.850502 | controller |
2025-10-08 00:15:46.850517 | controller | PLAY RECAP *********************************************************************
2025-10-08 00:15:46.850630 | controller | instance                   : ok=28   changed=4    unreachable=0    failed=0    skipped=42   rescued=0    ignored=0
2025-10-08 00:15:46.850712 | controller |
2025-10-08 00:15:46.850835 | controller | Wednesday 08 October 2025  00:15:46 +0000 (0:00:00.039)       0:00:56.569 *****
2025-10-08 00:15:46.850931 | controller | ===============================================================================
2025-10-08 00:15:46.851034 | controller | test_deps : Install selinux python libs -------------------------------- 42.19s
2025-10-08 00:15:46.851129 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.30s
2025-10-08 00:15:46.851217 | controller | test_deps : Install python yaml libs ------------------------------------ 1.21s
2025-10-08 00:15:46.851309 | controller | ci_setup : Install needed packages -------------------------------------- 1.21s
2025-10-08 00:15:46.851423 | controller | repo_setup : Initialize python venv and install requirements ------------ 1.20s
2025-10-08 00:15:46.851516 | controller | ci_setup : Manage directories ------------------------------------------- 0.99s
2025-10-08 00:15:46.851604 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.85s
2025-10-08 00:15:46.851695 | controller | Gathering Facts --------------------------------------------------------- 0.79s
2025-10-08 00:15:46.851807 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.66s
2025-10-08 00:15:46.851896 | controller | repo_setup : Get repo-setup repository ---------------------------------- 0.56s
2025-10-08 00:15:46.851991 | controller | Inject our own NetworkManager configuration ----------------------------- 0.56s
2025-10-08 00:15:46.852083 | controller | repo_setup : Ensure directories are present ----------------------------- 0.54s
2025-10-08 00:15:46.852172 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.53s
2025-10-08 00:15:46.852261 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.35s
2025-10-08 00:15:46.852351 | controller | repo_setup : Install repo-setup package --------------------------------- 0.28s
2025-10-08 00:15:46.852439 | controller | ci_setup : Gather version of openshift client --------------------------- 0.28s
2025-10-08 00:15:46.852526 | controller | ci_setup : Source completion from within .bashrc ------------------------ 0.28s
2025-10-08 00:15:46.852620 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.21s
2025-10-08 00:15:46.852708 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.20s
2025-10-08 00:15:46.852818 | controller | ci_setup : Ensure openshift client install path is present -------------- 0.20s
2025-10-08 00:15:46.919273 | controller | INFO     Running default > converge
2025-10-08 00:15:47.379278 | controller |
2025-10-08 00:15:47.379369 | controller | PLAY [Converge default] ********************************************************
2025-10-08 00:15:47.379469 | controller |
2025-10-08 00:15:47.379565 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 00:15:47.379656 | controller | Wednesday 08 October 2025  00:15:47 +0000 (0:00:00.028)       0:00:00.028 *****
2025-10-08 00:15:48.233490 | controller | ok: [instance]
2025-10-08 00:15:48.233546 | controller |
2025-10-08 00:15:48.233891 | controller | TASK [rhol_crc : Ensure directories exist] *************************************
2025-10-08 00:15:48.892900 | controller | Wednesday 08 October 2025  00:15:48 +0000 (0:00:00.854)       0:00:00.883 *****
2025-10-08 00:15:48.892965 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts)
2025-10-08 00:15:48.893012 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-10-08 00:15:48.893021 | controller | ok: [instance] => (item=/usr/local/bin)
2025-10-08 00:15:48.893028 | controller |
2025-10-08 00:15:48.893037 | controller | TASK [manage_secrets : Ensure directory tree exist] ****************************
2025-10-08 00:15:48.893151 | controller | Wednesday 08 October 2025  00:15:48 +0000 (0:00:00.659)       0:00:01.542 *****
2025-10-08 00:15:49.101743 | controller | changed: [instance]
2025-10-08 00:15:49.392931 | controller |
2025-10-08 00:15:49.393002 | controller | TASK [rhol_crc : Check for binary] *********************************************
2025-10-08 00:15:49.393019 | controller | Wednesday 08 October 2025  00:15:49 +0000 (0:00:00.208)       0:00:01.750 *****
2025-10-08 00:15:49.393038 | controller | ok: [instance]
2025-10-08 00:15:49.393087 | controller |
2025-10-08 00:15:49.393102 | controller | TASK [rhol_crc : Get versions if binary is available] **************************
2025-10-08 00:15:49.430891 | controller | Wednesday 08 October 2025  00:15:49 +0000 (0:00:00.292)       0:00:02.042 *****
2025-10-08 00:15:49.430968 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/rhol_crc/tasks/get_versions.yml for instance
2025-10-08 00:15:50.208400 | controller |
2025-10-08 00:15:50.208432 | controller | TASK [rhol_crc : Get CRC binary version] ***************************************
2025-10-08 00:15:50.208440 | controller | Wednesday 08 October 2025  00:15:49 +0000 (0:00:00.038)       0:00:02.080 *****
2025-10-08 00:15:50.208451 | controller | changed: [instance]
2025-10-08 00:15:50.246460 | controller |
2025-10-08 00:15:50.246488 | controller | TASK [rhol_crc : Extract versions] *********************************************
2025-10-08 00:15:50.246496 | controller | Wednesday 08 October 2025  00:15:50 +0000 (0:00:00.777)       0:00:02.858 *****
2025-10-08 00:15:50.246506 | controller | ok: [instance]
2025-10-08 00:15:50.268477 | controller |
2025-10-08 00:15:50.268507 | controller | TASK [rhol_crc : Output found binary location and versions] ********************
2025-10-08 00:15:50.268516 | controller | Wednesday 08 October 2025  00:15:50 +0000 (0:00:00.038)       0:00:02.896 *****
2025-10-08 00:15:50.268528 | controller | ok: [instance] =>
2025-10-08 00:15:50.599597 | 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-10-08 00:15:50.599632 | controller |
2025-10-08 00:15:50.599639 | controller | TASK [rhol_crc : Get CRC VM availability] **************************************
2025-10-08 00:15:50.599646 | controller | Wednesday 08 October 2025  00:15:50 +0000 (0:00:00.021)       0:00:02.918 *****
2025-10-08 00:15:50.599655 | controller | ok: [instance]
2025-10-08 00:15:50.624863 | controller |
2025-10-08 00:15:50.624895 | controller | TASK [rhol_crc : Set CRC status] ***********************************************
2025-10-08 00:15:50.624904 | controller | Wednesday 08 October 2025  00:15:50 +0000 (0:00:00.330)       0:00:03.249 *****
2025-10-08 00:15:50.624921 | controller | ok: [instance]
2025-10-08 00:15:50.651451 | controller |
2025-10-08 00:15:50.651475 | controller | TASK [rhol_crc : Fail if crc domain is already defined] ************************
2025-10-08 00:15:50.651483 | controller | Wednesday 08 October 2025  00:15:50 +0000 (0:00:00.025)       0:00:03.274 *****
2025-10-08 00:15:50.651500 | controller | skipping: [instance]
2025-10-08 00:15:50.690862 | controller |
2025-10-08 00:15:50.690899 | controller | TASK [Ensure pull-secret is in place if we need to configure CRC] **************
2025-10-08 00:15:50.690908 | controller | Wednesday 08 October 2025  00:15:50 +0000 (0:00:00.026)       0:00:03.301 *****
2025-10-08 00:15:50.690919 | controller |
2025-10-08 00:15:50.710864 | controller | TASK [manage_secrets : Assert we get needed data] ******************************
2025-10-08 00:15:50.710888 | controller | Wednesday 08 October 2025  00:15:50 +0000 (0:00:00.039)       0:00:03.340 *****
2025-10-08 00:15:50.710900 | controller | ok: [instance]
2025-10-08 00:15:50.711116 | controller |
2025-10-08 00:15:50.711136 | controller | TASK [manage_secrets : Manage secret] ******************************************
2025-10-08 00:15:50.735280 | controller | Wednesday 08 October 2025  00:15:50 +0000 (0:00:00.020)       0:00:03.361 *****
2025-10-08 00:15:50.735304 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/manage_secrets/tasks/_push_secret.yml for instance
2025-10-08 00:15:50.758535 | controller |
2025-10-08 00:15:50.758559 | controller | TASK [manage_secrets : Debug _secret_dest] *************************************
2025-10-08 00:15:50.758568 | controller | Wednesday 08 October 2025  00:15:50 +0000 (0:00:00.024)       0:00:03.385 *****
2025-10-08 00:15:50.758578 | controller | ok: [instance] =>
2025-10-08 00:15:50.777818 | controller |   _secret_dest: /home/zuul/pull-secret.json
2025-10-08 00:15:50.777849 | controller |
2025-10-08 00:15:50.777858 | controller | TASK [manage_secrets : Debug _secret_file] *************************************
2025-10-08 00:15:50.777865 | controller | Wednesday 08 October 2025  00:15:50 +0000 (0:00:00.023)       0:00:03.408 *****
2025-10-08 00:15:50.777876 | controller | ok: [instance] =>
2025-10-08 00:15:50.778059 | controller |   _secret_file: ''
2025-10-08 00:15:50.778210 | controller |
2025-10-08 00:15:50.778370 | controller | TASK [manage_secrets : Debug _secret_content] **********************************
2025-10-08 00:15:50.778531 | controller | Wednesday 08 October 2025  00:15:50 +0000 (0:00:00.019)       0:00:03.427 *****
2025-10-08 00:15:50.801643 | controller | ok: [instance] =>
2025-10-08 00:15:50.801838 | controller |   _secret_content:
2025-10-08 00:15:50.802010 | controller |     auths:
2025-10-08 00:15:50.802175 | controller |       fake:
2025-10-08 00:15:50.802324 | controller |         auth: Zm9vOmJhcgo=
2025-10-08 00:15:50.802469 | controller |
2025-10-08 00:15:50.802621 | controller | TASK [manage_secrets : Ensure parameter is an absolute path] *******************
2025-10-08 00:15:50.802787 | controller | Wednesday 08 October 2025  00:15:50 +0000 (0:00:00.023)       0:00:03.451 *****
2025-10-08 00:15:50.818100 | controller | skipping: [instance]
2025-10-08 00:15:50.818237 | controller |
2025-10-08 00:15:50.818371 | controller | TASK [manage_secrets : Check if pull secret src file exists] *******************
2025-10-08 00:15:50.818498 | controller | Wednesday 08 October 2025  00:15:50 +0000 (0:00:00.016)       0:00:03.468 *****
2025-10-08 00:15:50.838965 | controller | skipping: [instance]
2025-10-08 00:15:50.839147 | controller |
2025-10-08 00:15:50.839306 | controller | TASK [manage_secrets : Copy file to location] **********************************
2025-10-08 00:15:50.839456 | controller | Wednesday 08 October 2025  00:15:50 +0000 (0:00:00.020)       0:00:03.489 *****
2025-10-08 00:15:50.857099 | controller | skipping: [instance]
2025-10-08 00:15:50.857280 | controller |
2025-10-08 00:15:50.857453 | controller | TASK [manage_secrets : Create file from content] *******************************
2025-10-08 00:15:50.857623 | controller | Wednesday 08 October 2025  00:15:50 +0000 (0:00:00.018)       0:00:03.507 *****
2025-10-08 00:15:51.299588 | controller | changed: [instance]
2025-10-08 00:15:51.333352 | controller |
2025-10-08 00:15:51.333376 | controller | TASK [rhol_crc : Get wanted RHOL/CRC version binary if needed] *****************
2025-10-08 00:15:51.333386 | controller | Wednesday 08 October 2025  00:15:51 +0000 (0:00:00.441)       0:00:03.949 *****
2025-10-08 00:15:51.333396 | controller | skipping: [instance]
2025-10-08 00:15:51.368603 | controller |
2025-10-08 00:15:51.368624 | controller | TASK [rhol_crc : Setup sudoers file for sudo commands in RHOL/CRC setup] *******
2025-10-08 00:15:51.368636 | controller | Wednesday 08 October 2025  00:15:51 +0000 (0:00:00.034)       0:00:03.983 *****
2025-10-08 00:15:51.368647 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/rhol_crc/tasks/sudoers_grant.yml for instance
2025-10-08 00:15:51.674812 | controller |
2025-10-08 00:15:51.674844 | controller | TASK [rhol_crc : Add passwordless sudo access for CRC commands] ****************
2025-10-08 00:15:51.674851 | controller | Wednesday 08 October 2025  00:15:51 +0000 (0:00:00.035)       0:00:04.018 *****
2025-10-08 00:15:51.674861 | controller | changed: [instance]
2025-10-08 00:15:51.712841 | controller |
2025-10-08 00:15:51.712869 | controller | TASK [rhol_crc : Clean RHOL/CRC if wanted] *************************************
2025-10-08 00:15:51.712879 | controller | Wednesday 08 October 2025  00:15:51 +0000 (0:00:00.306)       0:00:04.324 *****
2025-10-08 00:15:51.712891 | controller | skipping: [instance]
2025-10-08 00:15:51.712921 | controller |
2025-10-08 00:15:51.713180 | controller | TASK [rhol_crc : Set RHOL/CRC configuration options] ***************************
2025-10-08 00:15:51.758539 | controller | Wednesday 08 October 2025  00:15:51 +0000 (0:00:00.038)       0:00:04.362 *****
2025-10-08 00:15:51.758561 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/rhol_crc/tasks/configuration.yml for instance
2025-10-08 00:15:53.563806 | controller |
2025-10-08 00:15:53.563840 | controller | TASK [rhol_crc : Set RHOL/CRC configuration options] ***************************
2025-10-08 00:15:53.563855 | controller | Wednesday 08 October 2025  00:15:51 +0000 (0:00:00.045)       0:00:04.408 *****
2025-10-08 00:15:53.563866 | controller | changed: [instance] => (item={'key': 'consent-telemetry', 'value': 'no'})
2025-10-08 00:15:53.564440 | controller | changed: [instance] => (item={'key': 'disk-size', 'value': 32})
2025-10-08 00:15:53.564457 | controller | changed: [instance] => (item={'key': 'memory', 'value': 10752})
2025-10-08 00:15:53.564464 | controller | changed: [instance] => (item={'key': 'cpus', 'value': 4})
2025-10-08 00:15:53.564470 | controller | changed: [instance] => (item={'key': 'preset', 'value': 'openshift'})
2025-10-08 00:15:53.564476 | controller | changed: [instance] => (item={'key': 'pull-secret-file', 'value': '/home/zuul/pull-secret.json'})
2025-10-08 00:15:53.564484 | controller | changed: [instance] => (item={'key': 'skip-check-daemon-systemd-unit', 'value': True})
2025-10-08 00:20:57.401058 | controller | changed: [instance] => (item={'key': 'skip-check-daemon-systemd-sockets', 'value': True})
2025-10-08 00:20:57.401283 | controller | changed: [instance] => (item={'key': 'kubeadmin-password', 'value': 12345678})
2025-10-08 00:20:57.401298 | controller |
2025-10-08 00:20:57.401307 | controller | TASK [rhol_crc : Setup RHOL/CRC] ***********************************************
2025-10-08 00:20:57.401314 | controller | Wednesday 08 October 2025  00:15:53 +0000 (0:00:01.804)       0:00:06.213 *****
2025-10-08 00:20:57.401330 | controller | changed: [instance]
2025-10-08 00:43:14.422771 | controller |
2025-10-08 00:43:14.422823 | controller | TASK [rhol_crc : Start RHOL/CRC] ***********************************************
2025-10-08 00:43:14.422832 | controller | Wednesday 08 October 2025  00:20:57 +0000 (0:05:03.837)       0:05:10.050 *****
2025-10-08 00:43:14.422843 | controller | fatal: [instance]: FAILED! => changed=true
2025-10-08 00:43:14.423111 | controller |   cmd: set -o pipefail; /usr/local/bin/crc start 2>&1 | tee /home/zuul/ci-framework-data/logs/crc-start.log
2025-10-08 00:43:14.423127 | controller |   delta: '0:22:16.810995'
2025-10-08 00:43:14.423134 | controller |   end: '2025-10-08 00:43:14.385929'
2025-10-08 00:43:14.423139 | controller |   msg: non-zero return code
2025-10-08 00:43:14.423144 | controller |   rc: 1
2025-10-08 00:43:14.423150 | controller |   start: '2025-10-08 00:20:57.574934'
2025-10-08 00:43:14.423155 | controller |   stderr: ''
2025-10-08 00:43:14.423161 | controller |   stderr_lines: <omitted>
2025-10-08 00:43:14.423166 | controller |   stdout: |-
2025-10-08 00:43:14.423171 | 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-10-08 00:43:14.423180 | controller |     level=info msg="Using bundle path /home/zuul/.crc/cache/crc_libvirt_4.16.0_amd64.crcbundle"
2025-10-08 00:43:14.423255 | controller |     level=info msg="Checking if running as non-root"
2025-10-08 00:43:14.423300 | controller |     level=info msg="Checking if running inside WSL2"
2025-10-08 00:43:14.423312 | controller |     level=info msg="Checking if crc-admin-helper executable is cached"
2025-10-08 00:43:14.423339 | controller |     level=info msg="Checking if running on a supported CPU architecture"
2025-10-08 00:43:14.423362 | controller |     level=info msg="Checking if crc executable symlink exists"
2025-10-08 00:43:14.423508 | controller |     level=info msg="Checking minimum RAM requirements"
2025-10-08 00:43:14.423690 | controller |     level=info msg="Check if Podman binary exists in: /home/zuul/.crc/bin/oc"
2025-10-08 00:43:14.423852 | controller |     level=info msg="Checking if Virtualization is enabled"
2025-10-08 00:43:14.423881 | controller |     level=info msg="Checking if KVM is enabled"
2025-10-08 00:43:14.424167 | controller |     level=info msg="Checking if libvirt is installed"
2025-10-08 00:43:14.424194 | controller |     level=info msg="Checking if user is part of libvirt group"
2025-10-08 00:43:14.424314 | controller |     level=info msg="Checking if active user/process is currently part of the libvirt group"
2025-10-08 00:43:14.424326 | controller |     level=info msg="Checking if libvirt daemon is running"
2025-10-08 00:43:14.424335 | controller |     level=info msg="Checking if a supported libvirt version is installed"
2025-10-08 00:43:14.424621 | controller |     level=info msg="Checking if crc-driver-libvirt is installed"
2025-10-08 00:43:14.425005 | controller |     level=info msg="Checking crc daemon systemd socket units"
2025-10-08 00:43:14.425025 | controller |     level=warning msg="Skipping above check..."
2025-10-08 00:43:14.425032 | controller |     level=info msg="Checking if systemd-networkd is running"
2025-10-08 00:43:14.425041 | controller |     level=info msg="Checking if NetworkManager is installed"
2025-10-08 00:43:14.425155 | controller |     level=info msg="Checking if NetworkManager service is running"
2025-10-08 00:43:14.425167 | controller |     level=info msg="Checking if /etc/NetworkManager/conf.d/crc-nm-dnsmasq.conf exists"
2025-10-08 00:43:14.425172 | controller |     level=info msg="Checking if /etc/NetworkManager/dnsmasq.d/crc.conf exists"
2025-10-08 00:43:14.425180 | controller |     level=info msg="Checking if libvirt 'crc' network is available"
2025-10-08 00:43:14.425471 | controller |     level=info msg="Checking if libvirt 'crc' network is active"
2025-10-08 00:43:14.425736 | controller |     level=info msg="Loading bundle: crc_libvirt_4.16.0_amd64..."
2025-10-08 00:43:14.425749 | controller |     level=info msg="Creating CRC VM for OpenShift 4.16.0..."
2025-10-08 00:43:14.425755 | controller |     level=info msg="Generating new SSH key pair..."
2025-10-08 00:43:14.425763 | controller |     level=info msg="Generating new password for the kubeadmin user"
2025-10-08 00:43:14.425846 | controller |     level=info msg="Starting CRC VM for openshift 4.16.0..."
2025-10-08 00:43:14.425854 | controller |     level=info msg="CRC instance is running with IP 192.168.130.11"
2025-10-08 00:43:14.425861 | controller |     level=info msg="CRC VM is running"
2025-10-08 00:43:14.425989 | controller |     level=info msg="Updating authorized keys..."
2025-10-08 00:43:14.426116 | controller |     level=info msg="Resizing /dev/vda4 filesystem"
2025-10-08 00:43:14.426229 | controller |     level=info msg="Configuring shared directories"
2025-10-08 00:43:14.426345 | controller |     level=info msg="Check internal and public DNS query..."
2025-10-08 00:43:14.426447 | controller |     level=info msg="Check DNS query from host..."
2025-10-08 00:43:14.426549 | controller |     level=info msg="Verifying validity of the kubelet certificates..."
2025-10-08 00:43:14.426664 | controller |     level=info msg="Starting kubelet service"
2025-10-08 00:43:14.426817 | controller |     level=info msg="Kubelet client certificate has expired, renewing it... [will take up to 10 minutes]"
2025-10-08 00:43:14.426955 | controller |     level=info msg="Kubelet serving certificate has expired, waiting for automatic renewal... [will take up to 5 minutes]"
2025-10-08 00:43:14.427080 | controller |     level=info msg="Waiting for kube-apiserver availability... [takes around 2min]"
2025-10-08 00:43:14.427206 | controller |     level=info msg="Adding user's pull secret to the cluster..."
2025-10-08 00:43:14.427321 | controller |     level=info msg="Updating SSH key to machine config resource..."
2025-10-08 00:43:14.427431 | controller |     level=info msg="Waiting until the user's pull secret is written to the instance disk..."
2025-10-08 00:43:14.427536 | controller |     Failed to update pull secret on the disk: Temporary error: pull secret not updated to disk (x205)
2025-10-08 00:43:14.427644 | controller |   stdout_lines: <omitted>
2025-10-08 00:43:14.429292 | controller |
2025-10-08 00:43:14.429417 | controller | TASK [rhol_crc : Revoke permissions granted in sudoers file for CRC/RHOL setup] ***
2025-10-08 00:43:14.429532 | controller | Wednesday 08 October 2025  00:43:14 +0000 (0:22:17.029)       0:27:27.079 *****
2025-10-08 00:43:14.625348 | controller | changed: [instance]
2025-10-08 00:43:14.625441 | controller |
2025-10-08 00:43:14.625452 | controller | PLAY RECAP *********************************************************************
2025-10-08 00:43:14.625459 | controller | instance                   : ok=22   changed=7    unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2025-10-08 00:43:14.625467 | controller |
2025-10-08 00:43:14.625798 | controller | Wednesday 08 October 2025  00:43:14 +0000 (0:00:00.195)       0:27:27.275 *****
2025-10-08 00:43:14.625920 | controller | ===============================================================================
2025-10-08 00:43:14.626020 | controller | rhol_crc : Start RHOL/CRC -------------------------------------------- 1337.03s
2025-10-08 00:43:14.626116 | controller | rhol_crc : Setup RHOL/CRC --------------------------------------------- 303.84s
2025-10-08 00:43:14.626290 | controller | rhol_crc : Set RHOL/CRC configuration options --------------------------- 1.80s
2025-10-08 00:43:14.626377 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-10-08 00:43:14.626384 | controller | rhol_crc : Get CRC binary version --------------------------------------- 0.78s
2025-10-08 00:43:14.626467 | controller | rhol_crc : Ensure directories exist ------------------------------------- 0.66s
2025-10-08 00:43:14.626552 | controller | manage_secrets : Create file from content ------------------------------- 0.44s
2025-10-08 00:43:14.626638 | controller | rhol_crc : Get CRC VM availability -------------------------------------- 0.33s
2025-10-08 00:43:14.626735 | controller | rhol_crc : Add passwordless sudo access for CRC commands ---------------- 0.31s
2025-10-08 00:43:14.626846 | controller | rhol_crc : Check for binary --------------------------------------------- 0.29s
2025-10-08 00:43:14.626937 | controller | manage_secrets : Ensure directory tree exist ---------------------------- 0.21s
2025-10-08 00:43:14.627028 | controller | rhol_crc : Revoke permissions granted in sudoers file for CRC/RHOL setup --- 0.20s
2025-10-08 00:43:14.627125 | controller | rhol_crc : Set RHOL/CRC configuration options --------------------------- 0.05s
2025-10-08 00:43:14.627223 | controller | Ensure pull-secret is in place if we need to configure CRC -------------- 0.04s
2025-10-08 00:43:14.627397 | controller | rhol_crc : Extract versions --------------------------------------------- 0.04s
2025-10-08 00:43:14.627410 | controller | rhol_crc : Clean RHOL/CRC if wanted ------------------------------------- 0.04s
2025-10-08 00:43:14.627497 | controller | rhol_crc : Get versions if binary is available -------------------------- 0.04s
2025-10-08 00:43:14.627591 | controller | rhol_crc : Setup sudoers file for sudo commands in RHOL/CRC setup ------- 0.04s
2025-10-08 00:43:14.627683 | controller | rhol_crc : Get wanted RHOL/CRC version binary if needed ----------------- 0.03s
2025-10-08 00:43:14.627802 | controller | rhol_crc : Fail if crc domain is already defined ------------------------ 0.03s
2025-10-08 00:43:14.689419 | 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-10-08 00:43:14.689792 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-10-08 00:43:14.699211 | controller | INFO     Running default > cleanup
2025-10-08 00:43:15.107087 | controller |
2025-10-08 00:43:15.107266 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 00:43:15.107350 | controller |
2025-10-08 00:43:15.107451 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 00:43:15.107586 | controller | Wednesday 08 October 2025  00:43:15 +0000 (0:00:00.014)       0:00:00.014 *****
2025-10-08 00:43:15.957265 | controller | ok: [instance]
2025-10-08 00:43:15.957330 | controller |
2025-10-08 00:43:15.957463 | controller | TASK [rhol_crc : Check RHOL/CRC binary exists] *********************************
2025-10-08 00:43:15.957579 | controller | Wednesday 08 October 2025  00:43:15 +0000 (0:00:00.850)       0:00:00.865 *****
2025-10-08 00:43:16.233620 | controller | ok: [instance]
2025-10-08 00:43:16.233662 | controller |
2025-10-08 00:43:16.233811 | controller | TASK [rhol_crc : Delete RHOL/CRC instance and associated configuration] ********
2025-10-08 00:43:16.233939 | controller | Wednesday 08 October 2025  00:43:16 +0000 (0:00:00.276)       0:00:01.141 *****
2025-10-08 00:43:19.749256 | controller | changed: [instance]
2025-10-08 00:43:19.749407 | controller |
2025-10-08 00:43:19.749604 | controller | PLAY RECAP *********************************************************************
2025-10-08 00:43:19.749851 | controller | instance                   : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 00:43:19.750019 | controller |
2025-10-08 00:43:19.750120 | controller | Wednesday 08 October 2025  00:43:19 +0000 (0:00:03.515)       0:00:04.657 *****
2025-10-08 00:43:19.750217 | controller | ===============================================================================
2025-10-08 00:43:19.750317 | controller | rhol_crc : Delete RHOL/CRC instance and associated configuration -------- 3.52s
2025-10-08 00:43:19.750415 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-10-08 00:43:19.750529 | controller | rhol_crc : Check RHOL/CRC binary exists --------------------------------- 0.28s
2025-10-08 00:43:19.800896 | controller | INFO     Running default > destroy
2025-10-08 00:43:19.801178 | controller | WARNING  Skipping, instances are delegated.
2025-10-08 00:43:19.801468 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-10-08 00:43:19.802891 | controller | INFO     Writing /tmp/report.html report.
2025-10-08 00:43:20.306206 | controller | ERROR
2025-10-08 00:43:20.306396 | controller | {
2025-10-08 00:43:20.306451 | controller |   "delta": "0:40:43.546270",
2025-10-08 00:43:20.306478 | controller |   "end": "2025-10-08 00:43:19.852594",
2025-10-08 00:43:20.306498 | controller |   "msg": "non-zero return code",
2025-10-08 00:43:20.306547 | controller |   "rc": 1,
2025-10-08 00:43:20.306571 | controller |   "start": "2025-10-08 00:02:36.306324"
2025-10-08 00:43:20.306588 | controller | }
failure
2025-10-08 00:43:20.358549 | 
2025-10-08 00:43:20.358619 | PLAY RECAP
2025-10-08 00:43:20.358665 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-10-08 00:43:20.358693 | 
2025-10-08 00:43:20.417139 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 00:43:20.417998 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 00:43:20.916470 | 
2025-10-08 00:43:20.916579 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-08 00:43:20.935119 | 
2025-10-08 00:43:20.935189 | TASK [Filter out host if needed]
2025-10-08 00:43:20.943212 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-08 00:43:20.947372 | 
2025-10-08 00:43:20.947440 | TASK [Ensure file is present]
2025-10-08 00:43:21.271448 | controller | ok
2025-10-08 00:43:21.278510 | 
2025-10-08 00:43:21.278591 | TASK [Manage molecule report file]
2025-10-08 00:43:21.804180 | controller | changed
2025-10-08 00:43:21.809569 | 
2025-10-08 00:43:21.809636 | TASK [Check if we get ci-framework-data basedir]
2025-10-08 00:43:22.010687 | controller | ok
2025-10-08 00:43:22.015686 | 
2025-10-08 00:43:22.015747 | TASK [Create ci-framework-data log directory for zuul]
2025-10-08 00:43:22.328497 | controller | changed
2025-10-08 00:43:22.333540 | 
2025-10-08 00:43:22.333609 | TASK [Copy ci-framework interesting files]
2025-10-08 00:43:22.547104 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-08 00:43:22.857437 | controller | changed
2025-10-08 00:43:22.861878 | 
2025-10-08 00:43:22.861955 | TASK [Get SELinux listing]
2025-10-08 00:43:23.386650 | controller | changed
2025-10-08 00:43:23.391669 | 
2025-10-08 00:43:23.391729 | TASK [Generate log index]
2025-10-08 00:43:24.132492 | controller | changed
2025-10-08 00:43:24.136854 | 
2025-10-08 00:43:24.136925 | TASK [Get some env related data]
2025-10-08 00:43:24.744637 | controller | /home/zuul/.local/bin/ansible
2025-10-08 00:43:25.662420 | controller | changed
2025-10-08 00:43:25.667041 | 
2025-10-08 00:43:25.667096 | TASK [Generate list of logs to collect in home directory]
2025-10-08 00:43:25.960052 | controller | ok: All paths examined
2025-10-08 00:43:25.984781 | 
2025-10-08 00:43:25.984859 | LOOP [Copy logs from home directory]
2025-10-08 00:43:26.370755 | controller | changed:
2025-10-08 00:43:26.371016 | controller | {
2025-10-08 00:43:26.371046 | controller |   "atime": 1743544925.4788878,
2025-10-08 00:43:26.371067 | controller |   "ctime": 1743545329.1409318,
2025-10-08 00:43:26.371084 | controller |   "dev": 64513,
2025-10-08 00:43:26.371100 | controller |   "gid": 1000,
2025-10-08 00:43:26.371115 | controller |   "gr_name": "zuul",
2025-10-08 00:43:26.371131 | controller |   "inode": 4518807,
2025-10-08 00:43:26.371145 | controller |   "isblk": false,
2025-10-08 00:43:26.371159 | controller |   "ischr": false,
2025-10-08 00:43:26.371172 | controller |   "isdir": false,
2025-10-08 00:43:26.371185 | controller |   "isfifo": false,
2025-10-08 00:43:26.371200 | controller |   "isgid": false,
2025-10-08 00:43:26.371214 | controller |   "islnk": false,
2025-10-08 00:43:26.371227 | controller |   "isreg": true,
2025-10-08 00:43:26.371240 | controller |   "issock": false,
2025-10-08 00:43:26.371254 | controller |   "isuid": false,
2025-10-08 00:43:26.371267 | controller |   "mode": "0644",
2025-10-08 00:43:26.371281 | controller |   "mtime": 1743545329.1409318,
2025-10-08 00:43:26.371294 | controller |   "nlink": 1,
2025-10-08 00:43:26.371308 | controller |   "path": "/home/zuul/crc-setup.log",
2025-10-08 00:43:26.371329 | controller |   "pw_name": "zuul",
2025-10-08 00:43:26.371345 | controller |   "rgrp": true,
2025-10-08 00:43:26.371360 | controller |   "roth": true,
2025-10-08 00:43:26.371374 | controller |   "rusr": true,
2025-10-08 00:43:26.371387 | controller |   "size": 4108,
2025-10-08 00:43:26.371401 | controller |   "uid": 1000,
2025-10-08 00:43:26.371414 | controller |   "wgrp": false,
2025-10-08 00:43:26.371427 | controller |   "woth": false,
2025-10-08 00:43:26.371442 | controller |   "wusr": true,
2025-10-08 00:43:26.371457 | controller |   "xgrp": false,
2025-10-08 00:43:26.371470 | controller |   "xoth": false,
2025-10-08 00:43:26.371483 | controller |   "xusr": false
2025-10-08 00:43:26.371497 | controller | }
2025-10-08 00:43:26.723074 | controller | changed:
2025-10-08 00:43:26.723129 | controller | {
2025-10-08 00:43:26.723155 | controller |   "atime": 1743545331.0429637,
2025-10-08 00:43:26.723174 | controller |   "ctime": 1743545842.0111232,
2025-10-08 00:43:26.723192 | controller |   "dev": 64513,
2025-10-08 00:43:26.723208 | controller |   "gid": 1000,
2025-10-08 00:43:26.723223 | controller |   "gr_name": "zuul",
2025-10-08 00:43:26.723238 | controller |   "inode": 4194437,
2025-10-08 00:43:26.723252 | controller |   "isblk": false,
2025-10-08 00:43:26.723266 | controller |   "ischr": false,
2025-10-08 00:43:26.723279 | controller |   "isdir": false,
2025-10-08 00:43:26.723293 | controller |   "isfifo": false,
2025-10-08 00:43:26.723306 | controller |   "isgid": false,
2025-10-08 00:43:26.723320 | controller |   "islnk": false,
2025-10-08 00:43:26.723333 | controller |   "isreg": true,
2025-10-08 00:43:26.723346 | controller |   "issock": false,
2025-10-08 00:43:26.723359 | controller |   "isuid": false,
2025-10-08 00:43:26.723373 | controller |   "mode": "0644",
2025-10-08 00:43:26.723386 | controller |   "mtime": 1743545842.0111232,
2025-10-08 00:43:26.723400 | controller |   "nlink": 1,
2025-10-08 00:43:26.723414 | controller |   "path": "/home/zuul/crc-start.log",
2025-10-08 00:43:26.723428 | controller |   "pw_name": "zuul",
2025-10-08 00:43:26.723441 | controller |   "rgrp": true,
2025-10-08 00:43:26.723455 | controller |   "roth": true,
2025-10-08 00:43:26.723469 | controller |   "rusr": true,
2025-10-08 00:43:26.723482 | controller |   "size": 4023,
2025-10-08 00:43:26.723495 | controller |   "uid": 1000,
2025-10-08 00:43:26.723508 | controller |   "wgrp": false,
2025-10-08 00:43:26.723534 | controller |   "woth": false,
2025-10-08 00:43:26.723613 | controller |   "wusr": true,
2025-10-08 00:43:26.723733 | controller |   "xgrp": false,
2025-10-08 00:43:26.723763 | controller |   "xoth": false,
2025-10-08 00:43:26.723788 | controller |   "xusr": false
2025-10-08 00:43:26.723805 | controller | }
2025-10-08 00:43:27.072961 | controller | changed:
2025-10-08 00:43:27.073019 | controller | {
2025-10-08 00:43:27.073044 | controller |   "atime": 1759881738.5324066,
2025-10-08 00:43:27.073063 | controller |   "ctime": 1759881754.3154066,
2025-10-08 00:43:27.073080 | controller |   "dev": 64513,
2025-10-08 00:43:27.073096 | controller |   "gid": 1000,
2025-10-08 00:43:27.073111 | controller |   "gr_name": "zuul",
2025-10-08 00:43:27.073125 | controller |   "inode": 4340271,
2025-10-08 00:43:27.073139 | controller |   "isblk": false,
2025-10-08 00:43:27.073152 | controller |   "ischr": false,
2025-10-08 00:43:27.073166 | controller |   "isdir": false,
2025-10-08 00:43:27.073179 | controller |   "isfifo": false,
2025-10-08 00:43:27.073192 | controller |   "isgid": false,
2025-10-08 00:43:27.073205 | controller |   "islnk": false,
2025-10-08 00:43:27.073219 | controller |   "isreg": true,
2025-10-08 00:43:27.073233 | controller |   "issock": false,
2025-10-08 00:43:27.073245 | controller |   "isuid": false,
2025-10-08 00:43:27.073258 | controller |   "mode": "0644",
2025-10-08 00:43:27.073271 | controller |   "mtime": 1759881754.3154066,
2025-10-08 00:43:27.073285 | controller |   "nlink": 1,
2025-10-08 00:43:27.073299 | controller |   "path": "/home/zuul/ansible.log",
2025-10-08 00:43:27.073313 | controller |   "pw_name": "zuul",
2025-10-08 00:43:27.073328 | controller |   "rgrp": true,
2025-10-08 00:43:27.073343 | controller |   "roth": true,
2025-10-08 00:43:27.073360 | controller |   "rusr": true,
2025-10-08 00:43:27.073375 | controller |   "size": 6749,
2025-10-08 00:43:27.073389 | controller |   "uid": 1000,
2025-10-08 00:43:27.073403 | controller |   "wgrp": false,
2025-10-08 00:43:27.073417 | controller |   "woth": false,
2025-10-08 00:43:27.073430 | controller |   "wusr": true,
2025-10-08 00:43:27.073444 | controller |   "xgrp": false,
2025-10-08 00:43:27.073457 | controller |   "xoth": false,
2025-10-08 00:43:27.073470 | controller |   "xusr": false
2025-10-08 00:43:27.073482 | controller | }
2025-10-08 00:43:27.084366 | 
2025-10-08 00:43:27.084469 | TASK [Copy crio stats log file]
2025-10-08 00:43:27.097951 | controller | skipping: Conditional result was False
2025-10-08 00:43:27.102852 | 
2025-10-08 00:43:27.102926 | TASK [Get SELinux related data]
2025-10-08 00:43:27.316068 | controller | <no matches>
2025-10-08 00:43:27.627499 | controller | ERROR
2025-10-08 00:43:27.627652 | controller | {
2025-10-08 00:43:27.627684 | controller |   "delta": "0:00:00.008740",
2025-10-08 00:43:27.627705 | controller |   "end": "2025-10-08 00:43:27.316669",
2025-10-08 00:43:27.627723 | controller |   "msg": "non-zero return code",
2025-10-08 00:43:27.627740 | controller |   "rc": 1,
2025-10-08 00:43:27.627758 | controller |   "start": "2025-10-08 00:43:27.307929"
2025-10-08 00:43:27.627775 | controller | }
2025-10-08 00:43:27.627798 | controller | ERROR: Ignoring Errors
2025-10-08 00:43:27.632572 | 
2025-10-08 00:43:27.632634 | TASK [Create system configuration directory]
2025-10-08 00:43:27.874732 | controller | changed
2025-10-08 00:43:27.879538 | 
2025-10-08 00:43:27.879598 | TASK [Get some of the system configurations]
2025-10-08 00:43:28.404067 | controller | changed
2025-10-08 00:43:28.408688 | 
2025-10-08 00:43:28.408746 | TASK [Copy generated documentation if available]
2025-10-08 00:43:28.422711 | controller | skipping: Conditional result was False
2025-10-08 00:43:28.427978 | 
2025-10-08 00:43:28.428036 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-08 00:43:28.441479 | controller | skipping: Conditional result was False
2025-10-08 00:43:28.446489 | 
2025-10-08 00:43:28.446557 | TASK [Compress logs bigger than 2MB]
2025-10-08 00:43:28.971977 | controller | changed
2025-10-08 00:43:28.976692 | 
2025-10-08 00:43:28.976754 | TASK [Copy files from workspace on node]
2025-10-08 00:43:28.994660 | controller | ok
2025-10-08 00:43:29.014529 | 
2025-10-08 00:43:29.014590 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 00:43:29.026981 | controller | skipping: Conditional result was False
2025-10-08 00:43:29.031805 | 
2025-10-08 00:43:29.031869 | TASK [fetch-output : Set log path for single node]
2025-10-08 00:43:29.048740 | controller | ok
2025-10-08 00:43:29.054037 | 
2025-10-08 00:43:29.054094 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 00:43:29.229547 | controller -> localhost | ok: "/var/lib/zuul/builds/5dc25eb6609d4a449d9bf33269c5cd73/work/logs"
2025-10-08 00:43:29.229770 | controller -> localhost | changed: All items complete
2025-10-08 00:43:29.229797 | 
2025-10-08 00:43:29.395830 | controller -> localhost | changed: "/var/lib/zuul/builds/5dc25eb6609d4a449d9bf33269c5cd73/work/artifacts"
2025-10-08 00:43:29.567984 | controller -> localhost | changed: "/var/lib/zuul/builds/5dc25eb6609d4a449d9bf33269c5cd73/work/docs"
2025-10-08 00:43:29.579847 | 
2025-10-08 00:43:29.579982 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 00:43:30.159045 | controller | changed:
2025-10-08 00:43:30.159204 | controller | .d..t...... ./
2025-10-08 00:43:30.159231 | controller | >f+++++++++ README.html
2025-10-08 00:43:30.159251 | controller | >f+++++++++ ansible-execution.log
2025-10-08 00:43:30.159268 | controller | >f+++++++++ ansible.log
2025-10-08 00:43:30.159284 | controller | >f+++++++++ crc-setup.log
2025-10-08 00:43:30.159299 | controller | >f+++++++++ crc-start.log
2025-10-08 00:43:30.159314 | controller | >f+++++++++ dmesg.log
2025-10-08 00:43:30.159332 | controller | >f+++++++++ installed-pkgs.log
2025-10-08 00:43:30.159348 | controller | >f+++++++++ python.log
2025-10-08 00:43:30.159363 | controller | >f+++++++++ registries.conf
2025-10-08 00:43:30.159377 | controller | >f+++++++++ report.html
2025-10-08 00:43:30.159391 | controller | >f+++++++++ selinux-denials.log
2025-10-08 00:43:30.159404 | controller | >f+++++++++ selinux-listing.log
2025-10-08 00:43:30.159418 | controller | cd+++++++++ ci-framework-data/
2025-10-08 00:43:30.159431 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-08 00:43:30.159444 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-08 00:43:30.159458 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-10-08 00:43:30.159471 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-10-08 00:43:30.159484 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-10-08 00:43:30.159497 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-08 00:43:30.159519 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-08 00:43:30.159534 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-08 00:43:30.159547 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-08 00:43:30.159561 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-08 00:43:30.159574 | controller | >f+++++++++ ci-framework-data/logs/crc-config.log
2025-10-08 00:43:30.159587 | controller | >f+++++++++ ci-framework-data/logs/crc-setup.log
2025-10-08 00:43:30.159599 | controller | >f+++++++++ ci-framework-data/logs/crc-start-add_crc_creds
2025-10-08 00:43:30.159612 | controller | >f+++++++++ ci-framework-data/logs/crc-start.log
2025-10-08 00:43:30.159625 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-08 00:43:30.159639 | controller | cd+++++++++ registries.conf.d/
2025-10-08 00:43:30.159652 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-08 00:43:30.159665 | controller | cd+++++++++ system-config/
2025-10-08 00:43:30.159678 | controller | cd+++++++++ system-config/libvirt/
2025-10-08 00:43:30.159690 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-08 00:43:30.159703 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-08 00:43:30.159715 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-10-08 00:43:30.159727 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-10-08 00:43:30.159745 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-10-08 00:43:30.159760 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-10-08 00:43:30.159775 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-10-08 00:43:30.159790 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-10-08 00:43:30.159804 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-10-08 00:43:30.159818 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-10-08 00:43:30.159832 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-10-08 00:43:30.159845 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-10-08 00:43:30.159858 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-10-08 00:43:30.159871 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-10-08 00:43:30.159884 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-10-08 00:43:30.159896 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-10-08 00:43:30.556075 | controller | changed: .d..t...... ./
2025-10-08 00:43:30.951711 | controller | changed: .d..t...... ./
2025-10-08 00:43:30.963304 | 
2025-10-08 00:43:30.963429 | TASK [Return artifact to Zuul]
2025-10-08 00:43:30.992349 | controller | ok
2025-10-08 00:43:31.011003 | 
2025-10-08 00:43:31.011060 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-08 00:43:31.011163 | 
2025-10-08 00:43:31.011189 | PLAY RECAP
2025-10-08 00:43:31.011226 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-08 00:43:31.011247 | 
2025-10-08 00:43:31.085593 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 00:43:31.086355 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 00:43:31.558780 | 
2025-10-08 00:43:31.558869 | PLAY [all]
2025-10-08 00:43:31.575550 | 
2025-10-08 00:43:31.575619 | TASK [include_role : fetch-output]
2025-10-08 00:43:31.604129 | controller | ok
2025-10-08 00:43:31.619359 | 
2025-10-08 00:43:31.619435 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 00:43:31.653829 | controller | skipping: Conditional result was False
2025-10-08 00:43:31.659306 | 
2025-10-08 00:43:31.659376 | TASK [fetch-output : Set log path for single node]
2025-10-08 00:43:31.687858 | controller | ok
2025-10-08 00:43:31.693092 | 
2025-10-08 00:43:31.693161 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 00:43:31.988534 | controller -> localhost | ok: "/var/lib/zuul/builds/5dc25eb6609d4a449d9bf33269c5cd73/work/logs"
2025-10-08 00:43:32.158220 | controller -> localhost | ok: "/var/lib/zuul/builds/5dc25eb6609d4a449d9bf33269c5cd73/work/artifacts"
2025-10-08 00:43:32.323796 | controller -> localhost | ok: "/var/lib/zuul/builds/5dc25eb6609d4a449d9bf33269c5cd73/work/docs"
2025-10-08 00:43:32.340964 | 
2025-10-08 00:43:32.341080 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 00:43:32.866246 | controller | ok
2025-10-08 00:43:32.866418 | controller | ok: All items complete
2025-10-08 00:43:32.866446 | 
2025-10-08 00:43:33.263843 | controller | ok
2025-10-08 00:43:33.675223 | controller | ok
2025-10-08 00:43:33.692035 | 
2025-10-08 00:43:33.692142 | TASK [include_role : fetch-output-openshift]
2025-10-08 00:43:33.705704 | controller | skipping: Conditional result was False
2025-10-08 00:43:33.711454 | 
2025-10-08 00:43:33.711535 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-08 00:43:34.033152 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006357
2025-10-08 00:43:34.216576 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006313
2025-10-08 00:43:34.245220 | 
2025-10-08 00:43:34.245302 | PLAY [all]
2025-10-08 00:43:34.258621 | 
2025-10-08 00:43:34.258690 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-08 00:43:34.654069 | controller | changed
2025-10-08 00:43:34.674869 | 
2025-10-08 00:43:34.674949 | PLAY RECAP
2025-10-08 00:43:34.674997 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-08 00:43:34.675021 | 
2025-10-08 00:43:34.739844 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 00:43:34.740602 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-08 00:43:35.260791 | 
2025-10-08 00:43:35.260932 | PLAY [localhost]
2025-10-08 00:43:35.277701 | 
2025-10-08 00:43:35.277772 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-08 00:43:35.558052 | localhost | changed
2025-10-08 00:43:35.562230 | 
2025-10-08 00:43:35.562310 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-08 00:43:35.578872 | localhost | ok
2025-10-08 00:43:35.586059 | 
2025-10-08 00:43:35.586124 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-08 00:43:35.855955 | localhost | changed
2025-10-08 00:43:35.861806 | 
2025-10-08 00:43:35.861885 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-08 00:43:36.344486 | localhost | changed
2025-10-08 00:43:36.348938 | 
2025-10-08 00:43:36.348999 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-08 00:43:36.659305 | localhost | Identity added: /var/lib/zuul/builds/5dc25eb6609d4a449d9bf33269c5cd73/work/tmp/ansible.0mnwhmax (/var/lib/zuul/builds/5dc25eb6609d4a449d9bf33269c5cd73/work/tmp/ansible.0mnwhmax)
2025-10-08 00:43:36.659472 | localhost | ok: Runtime: 0:00:00.005197
2025-10-08 00:43:36.663582 | 
2025-10-08 00:43:36.663643 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-08 00:43:36.872961 | localhost | ok: Runtime: 0:00:00.003565
2025-10-08 00:43:36.877582 | 
2025-10-08 00:43:36.877652 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-08 00:43:36.924945 | localhost | changed
2025-10-08 00:43:36.929668 | 
2025-10-08 00:43:36.929733 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-08 00:43:37.219247 | localhost | changed
2025-10-08 00:43:37.237665 | 
2025-10-08 00:43:37.237718 | PLAY [localhost]
2025-10-08 00:43:37.248797 | 
2025-10-08 00:43:37.248859 | TASK [Generate bulk log download script]
2025-10-08 00:43:37.266604 | localhost | ok
2025-10-08 00:43:37.277628 | 
2025-10-08 00:43:37.277691 | TASK [local-log-download : Check API endpoint is defined]
2025-10-08 00:43:37.304984 | localhost | ok: All assertions passed
2025-10-08 00:43:37.309295 | 
2025-10-08 00:43:37.309362 | TASK [local-log-download : Create download script]
2025-10-08 00:43:37.626371 | localhost -> localhost | changed
2025-10-08 00:43:37.634809 | 
2025-10-08 00:43:37.634873 | TASK [Register quick-download link]
2025-10-08 00:43:37.651027 | localhost | ok
2025-10-08 00:43:37.681439 | 
2025-10-08 00:43:37.681496 | PLAY [logserver.rdoproject.org]
2025-10-08 00:43:37.690105 | 
2025-10-08 00:43:37.690162 | TASK [Set zuul-log-path fact]
2025-10-08 00:43:37.705505 | logserver.rdoproject.org | ok
2025-10-08 00:43:37.713504 | 
2025-10-08 00:43:37.713575 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 00:43:37.728936 | logserver.rdoproject.org | ok
2025-10-08 00:43:37.733648 | 
2025-10-08 00:43:37.733708 | TASK [upload-logs : Create log directories]
2025-10-08 00:43:39.564941 | logserver.rdoproject.org | changed
2025-10-08 00:43:39.567699 | 
2025-10-08 00:43:39.567760 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-08 00:43:39.773726 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004844
2025-10-08 00:43:39.778246 | 
2025-10-08 00:43:39.778310 | TASK [upload-logs : Upload logs to log server]
2025-10-08 00:43:41.265816 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-08 00:43:41.268687 | 
2025-10-08 00:43:41.268752 | LOOP [upload-logs : Compress console log and json output]
2025-10-08 00:43:41.306337 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 00:43:41.313846 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 00:43:41.326097 | 
2025-10-08 00:43:41.326160 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-08 00:43:41.357480 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 00:43:41.357669 | 
2025-10-08 00:43:41.360792 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 00:43:41.373066 | 
2025-10-08 00:43:41.373128 | LOOP [upload-logs : Upload console log and json output]