2025-12-05 23:36:40.356521 | Job console starting...
2025-12-05 23:36:40.366056 | Updating repositories
2025-12-05 23:36:40.411121 | Preparing job workspace
2025-12-05 23:36:47.994739 | Running Ansible setup...
2025-12-05 23:36:53.360115 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 23:36:54.255389 | 
2025-12-05 23:36:54.255522 | PLAY [localhost]
2025-12-05 23:36:54.266000 | 
2025-12-05 23:36:54.266077 | TASK [Gathering Facts]
2025-12-05 23:36:55.534237 | localhost | ok
2025-12-05 23:36:55.554201 | 
2025-12-05 23:36:55.554303 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-05 23:36:56.080527 | localhost -> localhost | changed
2025-12-05 23:36:56.086906 | 
2025-12-05 23:36:56.086973 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-05 23:36:57.061370 | localhost -> localhost | changed
2025-12-05 23:36:57.070513 | 
2025-12-05 23:36:57.070580 | TASK [Setup log path fact]
2025-12-05 23:36:57.102884 | localhost | ok
2025-12-05 23:36:57.118376 | 
2025-12-05 23:36:57.118449 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 23:36:57.156635 | localhost | ok
2025-12-05 23:36:57.165176 | 
2025-12-05 23:36:57.165252 | TASK [emit-job-header : Print job information]
2025-12-05 23:36:57.214953 | # Job Information
2025-12-05 23:36:57.215141 | Ansible Version: 2.15.12
2025-12-05 23:36:57.215167 | Job: cifmw-molecule-set_openstack_containers
2025-12-05 23:36:57.215185 | Pipeline: github-check
2025-12-05 23:36:57.215203 | Executor: ze02.softwarefactory-project.io
2025-12-05 23:36:57.215219 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3539
2025-12-05 23:36:57.215237 | Log URL (when completed): https://logserver.rdoproject.org/e3c/rdoproject.org/e3c6c60ee7d64f279e1e5b4b09fc4e37/
2025-12-05 23:36:57.215254 | Event ID: 0eecf260-d233-11f0-9ab7-da45df476e85
2025-12-05 23:36:57.218972 | 
2025-12-05 23:36:57.219038 | LOOP [emit-job-header : Print node information]
2025-12-05 23:36:57.418319 | localhost | ok:
2025-12-05 23:36:57.418570 | localhost | # Node Information
2025-12-05 23:36:57.418597 | localhost | Inventory Hostname: controller
2025-12-05 23:36:57.418622 | localhost | Hostname: np0005548189
2025-12-05 23:36:57.418641 | localhost | Username: zuul
2025-12-05 23:36:57.418708 | localhost | Distro: CentOS 9
2025-12-05 23:36:57.418736 | localhost | Provider: vexxhost-nodepool-tripleo
2025-12-05 23:36:57.418754 | localhost | Region: RegionOne
2025-12-05 23:36:57.418770 | localhost | Label: centos-9-stream-crc-2-48-0-xxl
2025-12-05 23:36:57.418786 | localhost | Product Name: OpenStack Nova
2025-12-05 23:36:57.418802 | localhost | Interface IP: 38.102.83.143
2025-12-05 23:36:57.469800 | 
2025-12-05 23:36:57.469912 | PLAY [all]
2025-12-05 23:36:57.482857 | 
2025-12-05 23:36:57.482923 | TASK [Gather network facts]
2025-12-05 23:36:58.149237 | controller | ok
2025-12-05 23:36:58.180518 | 
2025-12-05 23:36:58.180588 | TASK [include_role : start-zuul-console]
2025-12-05 23:36:58.238058 | controller | ok
2025-12-05 23:36:58.263344 | 
2025-12-05 23:36:58.263452 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-05 23:36:59.169011 | controller | ok
2025-12-05 23:36:59.184703 | 
2025-12-05 23:36:59.184782 | TASK [include_role : add-build-sshkey]
2025-12-05 23:36:59.256502 | controller | ok
2025-12-05 23:36:59.273299 | 
2025-12-05 23:36:59.273377 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-05 23:36:59.621277 | controller -> localhost | ok
2025-12-05 23:36:59.628965 | 
2025-12-05 23:36:59.629030 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-05 23:36:59.660676 | controller | ok
2025-12-05 23:36:59.674388 | controller | included: /var/lib/zuul/builds/e3c6c60ee7d64f279e1e5b4b09fc4e37/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-05 23:36:59.680186 | 
2025-12-05 23:36:59.680251 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-05 23:37:00.216325 | controller -> localhost | Generating public/private rsa key pair.
2025-12-05 23:37:00.216655 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e3c6c60ee7d64f279e1e5b4b09fc4e37/work/e3c6c60ee7d64f279e1e5b4b09fc4e37_id_rsa.
2025-12-05 23:37:00.216719 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e3c6c60ee7d64f279e1e5b4b09fc4e37/work/e3c6c60ee7d64f279e1e5b4b09fc4e37_id_rsa.pub.
2025-12-05 23:37:00.216744 | controller -> localhost | The key fingerprint is:
2025-12-05 23:37:00.216764 | controller -> localhost | SHA256:ST60PAqy+llpz4SDwQyvrTmLy5gWL+PYhWlj7HXNSZ8 zuul-build-sshkey
2025-12-05 23:37:00.216786 | controller -> localhost | The key's randomart image is:
2025-12-05 23:37:00.216805 | controller -> localhost | +---[RSA 3072]----+
2025-12-05 23:37:00.216822 | controller -> localhost | |                 |
2025-12-05 23:37:00.216840 | controller -> localhost | |                 |
2025-12-05 23:37:00.216857 | controller -> localhost | |.       o        |
2025-12-05 23:37:00.216874 | controller -> localhost | | =     = o       |
2025-12-05 23:37:00.216892 | controller -> localhost | |  * .   S        |
2025-12-05 23:37:00.216909 | controller -> localhost | | = B + = = .     |
2025-12-05 23:37:00.216926 | controller -> localhost | |. & O + + E      |
2025-12-05 23:37:00.216945 | controller -> localhost | |*& O *           |
2025-12-05 23:37:00.216963 | controller -> localhost | |&BB   o          |
2025-12-05 23:37:00.216980 | controller -> localhost | +----[SHA256]-----+
2025-12-05 23:37:00.217039 | controller -> localhost | ok: Runtime: 0:00:00.054333
2025-12-05 23:37:00.226418 | 
2025-12-05 23:37:00.226486 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-05 23:37:00.266876 | controller | ok
2025-12-05 23:37:00.278034 | controller | included: /var/lib/zuul/builds/e3c6c60ee7d64f279e1e5b4b09fc4e37/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-05 23:37:00.288035 | 
2025-12-05 23:37:00.288098 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-05 23:37:00.312730 | controller | skipping: Conditional result was False
2025-12-05 23:37:00.318421 | 
2025-12-05 23:37:00.318488 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-05 23:37:00.976850 | controller | changed
2025-12-05 23:37:00.981825 | 
2025-12-05 23:37:00.981889 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-05 23:37:01.424121 | controller | ok
2025-12-05 23:37:01.439252 | 
2025-12-05 23:37:01.439322 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-05 23:37:03.474021 | controller | changed
2025-12-05 23:37:03.480739 | 
2025-12-05 23:37:03.480833 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-05 23:37:05.257420 | controller | changed
2025-12-05 23:37:05.263292 | 
2025-12-05 23:37:05.263364 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-05 23:37:05.297740 | controller | skipping: Conditional result was False
2025-12-05 23:37:05.309214 | 
2025-12-05 23:37:05.309302 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-05 23:37:06.135027 | controller -> localhost | changed
2025-12-05 23:37:06.154007 | 
2025-12-05 23:37:06.154085 | TASK [add-build-sshkey : Add back temp key]
2025-12-05 23:37:06.483574 | controller -> localhost | Identity added: /var/lib/zuul/builds/e3c6c60ee7d64f279e1e5b4b09fc4e37/work/e3c6c60ee7d64f279e1e5b4b09fc4e37_id_rsa (zuul-build-sshkey)
2025-12-05 23:37:06.483821 | controller -> localhost | ok: Runtime: 0:00:00.009948
2025-12-05 23:37:06.491023 | 
2025-12-05 23:37:06.491090 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-05 23:37:07.008964 | controller | ok
2025-12-05 23:37:07.016408 | 
2025-12-05 23:37:07.016480 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-05 23:37:07.045931 | controller | skipping: Conditional result was False
2025-12-05 23:37:07.056475 | 
2025-12-05 23:37:07.056574 | TASK [include_role : validate-host]
2025-12-05 23:37:07.078606 | controller | ok
2025-12-05 23:37:07.100173 | 
2025-12-05 23:37:07.100311 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-05 23:37:07.134750 | controller | ok
2025-12-05 23:37:07.139544 | 
2025-12-05 23:37:07.139608 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-05 23:37:07.383397 | controller -> localhost | ok
2025-12-05 23:37:07.389078 | 
2025-12-05 23:37:07.389140 | TASK [validate-host : Collect information about the host]
2025-12-05 23:37:08.288626 | controller | ok
2025-12-05 23:37:08.308404 | 
2025-12-05 23:37:08.308601 | TASK [validate-host : Sanitize hostname]
2025-12-05 23:37:08.370952 | controller | ok
2025-12-05 23:37:08.376109 | 
2025-12-05 23:37:08.376172 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-05 23:37:08.838911 | controller -> localhost | changed
2025-12-05 23:37:08.844606 | 
2025-12-05 23:37:08.844702 | TASK [validate-host : Collect information about zuul worker]
2025-12-05 23:37:09.520539 | controller | ok
2025-12-05 23:37:09.525746 | 
2025-12-05 23:37:09.525822 | TASK [validate-host : Write out all zuul information for each host]
2025-12-05 23:37:09.929162 | controller -> localhost | changed
2025-12-05 23:37:09.940742 | 
2025-12-05 23:37:09.940811 | TASK [include_role : prepare-workspace-openshift]
2025-12-05 23:37:09.976288 | controller | skipping: Conditional result was False
2025-12-05 23:37:09.983193 | 
2025-12-05 23:37:09.983256 | TASK [include_role : remove-zuul-sshkey]
2025-12-05 23:37:10.007586 | controller | skipping: Conditional result was False
2025-12-05 23:37:10.012875 | 
2025-12-05 23:37:10.012938 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 23:37:10.355252 | controller | ok: "logs"
2025-12-05 23:37:10.355502 | controller | ok: All items complete
2025-12-05 23:37:10.355529 | 
2025-12-05 23:37:10.698451 | controller | ok: "artifacts"
2025-12-05 23:37:11.011741 | controller | ok: "docs"
2025-12-05 23:37:11.021060 | 
2025-12-05 23:37:11.021236 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 23:37:11.447861 | controller | changed: "logs"
2025-12-05 23:37:11.816588 | controller | changed: "artifacts"
2025-12-05 23:37:12.235060 | controller | changed: "docs"
2025-12-05 23:37:12.285363 | 
2025-12-05 23:37:12.285490 | PLAY RECAP
2025-12-05 23:37:12.285551 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 23:37:12.285588 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 23:37:12.285614 | 
2025-12-05 23:37:12.499170 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 23:37:12.500225 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-05 23:37:13.178414 | 
2025-12-05 23:37:13.178719 | PLAY [all]
2025-12-05 23:37:13.200244 | 
2025-12-05 23:37:13.200320 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-05 23:37:13.266779 | controller | ok
2025-12-05 23:37:13.293252 | 
2025-12-05 23:37:13.293834 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-05 23:37:13.883070 | controller | changed
2025-12-05 23:37:13.889325 | 
2025-12-05 23:37:13.889406 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-05 23:37:16.002582 | controller | changed
2025-12-05 23:37:16.019039 | 
2025-12-05 23:37:16.019152 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-05 23:37:16.625525 | controller | changed:
2025-12-05 23:37:16.625909 | controller | {
2025-12-05 23:37:16.625978 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-05 23:37:16.626037 | controller | }
2025-12-05 23:37:17.017697 | controller | changed:
2025-12-05 23:37:17.017888 | controller | {
2025-12-05 23:37:17.017945 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-05 23:37:17.017990 | controller | }
2025-12-05 23:37:17.371415 | controller | changed:
2025-12-05 23:37:17.371580 | controller | {
2025-12-05 23:37:17.371630 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-12-05 23:37:17.371705 | controller | }
2025-12-05 23:37:17.762145 | controller | changed:
2025-12-05 23:37:17.762271 | controller | {
2025-12-05 23:37:17.762304 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-05 23:37:17.762330 | controller | }
2025-12-05 23:37:18.162389 | controller | changed:
2025-12-05 23:37:18.162510 | controller | {
2025-12-05 23:37:18.162542 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-05 23:37:18.162567 | controller | }
2025-12-05 23:37:18.543087 | controller | changed:
2025-12-05 23:37:18.543193 | controller | {
2025-12-05 23:37:18.543217 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-05 23:37:18.543237 | controller | }
2025-12-05 23:37:18.931889 | controller | changed:
2025-12-05 23:37:18.932001 | controller | {
2025-12-05 23:37:18.932024 | 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-12-05 23:37:18.932044 | controller | }
2025-12-05 23:37:19.366876 | controller | changed:
2025-12-05 23:37:19.367021 | controller | {
2025-12-05 23:37:19.367050 | 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-12-05 23:37:19.367070 | controller | }
2025-12-05 23:37:19.792422 | controller | changed:
2025-12-05 23:37:19.792539 | controller | {
2025-12-05 23:37:19.792563 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-05 23:37:19.792582 | controller | }
2025-12-05 23:37:20.194446 | controller | changed:
2025-12-05 23:37:20.194607 | controller | {
2025-12-05 23:37:20.194646 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-05 23:37:20.194707 | controller | }
2025-12-05 23:37:20.584383 | controller | changed:
2025-12-05 23:37:20.584486 | controller | {
2025-12-05 23:37:20.584509 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-05 23:37:20.584527 | controller | }
2025-12-05 23:37:21.002823 | controller | changed:
2025-12-05 23:37:21.002971 | controller | {
2025-12-05 23:37:21.003021 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-05 23:37:21.003062 | controller | }
2025-12-05 23:37:21.449370 | controller | changed:
2025-12-05 23:37:21.449506 | controller | {
2025-12-05 23:37:21.449531 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-05 23:37:21.449553 | controller | }
2025-12-05 23:37:21.845926 | controller | changed:
2025-12-05 23:37:21.846100 | controller | {
2025-12-05 23:37:21.846151 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-05 23:37:21.846191 | controller | }
2025-12-05 23:37:22.261028 | controller | changed:
2025-12-05 23:37:22.261147 | controller | {
2025-12-05 23:37:22.261170 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-05 23:37:22.261189 | controller | }
2025-12-05 23:37:22.681543 | controller | changed:
2025-12-05 23:37:22.681737 | controller | {
2025-12-05 23:37:22.681792 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-05 23:37:22.681833 | controller | }
2025-12-05 23:37:23.103959 | controller | changed:
2025-12-05 23:37:23.104147 | controller | {
2025-12-05 23:37:23.104200 | 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-12-05 23:37:23.104242 | controller | }
2025-12-05 23:37:23.523970 | controller | changed:
2025-12-05 23:37:23.524133 | controller | {
2025-12-05 23:37:23.524184 | 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-12-05 23:37:23.524243 | controller | }
2025-12-05 23:37:23.944989 | controller | changed:
2025-12-05 23:37:23.945164 | controller | {
2025-12-05 23:37:23.945218 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-05 23:37:23.945259 | controller | }
2025-12-05 23:37:24.343149 | controller | changed:
2025-12-05 23:37:24.343309 | controller | {
2025-12-05 23:37:24.343360 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-05 23:37:24.343401 | controller | }
2025-12-05 23:37:24.741393 | controller | changed:
2025-12-05 23:37:24.741580 | controller | {
2025-12-05 23:37:24.741631 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-05 23:37:24.741709 | controller | }
2025-12-05 23:37:25.117946 | controller | changed:
2025-12-05 23:37:25.118262 | controller | {
2025-12-05 23:37:25.118347 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-05 23:37:25.118407 | controller | }
2025-12-05 23:37:25.582969 | controller | changed:
2025-12-05 23:37:25.583113 | controller | {
2025-12-05 23:37:25.583165 | 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-12-05 23:37:25.583208 | controller | }
2025-12-05 23:37:25.945259 | controller | changed:
2025-12-05 23:37:25.945367 | controller | {
2025-12-05 23:37:25.945398 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-05 23:37:25.945424 | controller | }
2025-12-05 23:37:26.331432 | controller | changed:
2025-12-05 23:37:26.331517 | controller | {
2025-12-05 23:37:26.331540 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-05 23:37:26.331559 | controller | }
2025-12-05 23:37:26.732906 | controller | changed:
2025-12-05 23:37:26.733035 | controller | {
2025-12-05 23:37:26.733068 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-05 23:37:26.733094 | controller | }
2025-12-05 23:37:26.781798 | 
2025-12-05 23:37:26.781976 | TASK [Set timezone to UTC]
2025-12-05 23:37:27.511587 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-05 23:37:27.523955 | 
2025-12-05 23:37:27.524096 | TASK [Create nodepool directory]
2025-12-05 23:37:27.935857 | controller | changed
2025-12-05 23:37:27.941431 | 
2025-12-05 23:37:27.941496 | TASK [Create nodepool sub_nodes file]
2025-12-05 23:37:29.340759 | controller | changed
2025-12-05 23:37:29.354842 | 
2025-12-05 23:37:29.354983 | TASK [Create nodepool sub_nodes_private file]
2025-12-05 23:37:30.912313 | controller | changed
2025-12-05 23:37:30.920088 | 
2025-12-05 23:37:30.920226 | LOOP [Populate nodepool sub_nodes file]
2025-12-05 23:37:30.951758 | 
2025-12-05 23:37:30.952010 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-05 23:37:30.984823 | 
2025-12-05 23:37:30.984978 | TASK [Create nodepool primary file]
2025-12-05 23:37:31.028810 | controller | skipping: Conditional result was False
2025-12-05 23:37:31.036327 | 
2025-12-05 23:37:31.036408 | TASK [Create nodepool node_private for this node]
2025-12-05 23:37:32.576450 | controller | changed
2025-12-05 23:37:32.585050 | 
2025-12-05 23:37:32.585116 | LOOP [Copy ssh keys to nodepool directory]
2025-12-05 23:37:33.300783 | controller | ok: Item: id_rsa Runtime: 0:00:00.009182
2025-12-05 23:37:33.301227 | 
2025-12-05 23:37:33.692860 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005773
2025-12-05 23:37:33.709057 | 
2025-12-05 23:37:33.709167 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-05 23:37:35.330179 | controller | changed
2025-12-05 23:37:35.336024 | 
2025-12-05 23:37:35.336093 | TASK [Validate sudoers config after edits]
2025-12-05 23:37:35.726055 | controller | /etc/sudoers: parsed OK
2025-12-05 23:37:35.726156 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-05 23:37:35.726167 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-05 23:37:35.726173 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-05 23:37:35.870820 | controller | ok: Runtime: 0:00:00.010224
2025-12-05 23:37:35.879970 | 
2025-12-05 23:37:35.880065 | TASK [Show the environment passed in to job shell scripts]
2025-12-05 23:37:36.288661 | controller | SHELL=/bin/bash
2025-12-05 23:37:36.288814 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-05 23:37:36.288864 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-05 23:37:36.288989 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/39/3539/ef8821f44d461626a5ab46002cd514823bd56c63
2025-12-05 23:37:36.289010 | controller | PWD=/home/zuul
2025-12-05 23:37:36.289024 | controller | ZUUL_PIPELINE=github-check
2025-12-05 23:37:36.289037 | controller | LOGNAME=zuul
2025-12-05 23:37:36.289051 | controller | XDG_SESSION_TYPE=tty
2025-12-05 23:37:36.289065 | controller | _=/usr/bin/env
2025-12-05 23:37:36.289079 | controller | MOTD_SHOWN=pam
2025-12-05 23:37:36.289092 | controller | HOME=/home/zuul
2025-12-05 23:37:36.289105 | controller | LANG=en_US.UTF-8
2025-12-05 23:37:36.289118 | controller | SSH_CONNECTION=38.102.83.114 43048 38.102.83.143 22
2025-12-05 23:37:36.289133 | 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-12-05 23:37:36.289156 | controller | ZUUL_CHANGE_IDS=3539,ef8821f44d461626a5ab46002cd514823bd56c63
2025-12-05 23:37:36.289170 | controller | WORKSPACE=/home/zuul/workspace
2025-12-05 23:37:36.289185 | controller | XDG_SESSION_CLASS=user
2025-12-05 23:37:36.289200 | controller | SELINUX_ROLE_REQUESTED=
2025-12-05 23:37:36.289213 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-05 23:37:36.289226 | controller | USER=zuul
2025-12-05 23:37:36.289240 | controller | ZUUL_VOTING=True
2025-12-05 23:37:36.289253 | controller | BUILD_TIMEOUT=1800000
2025-12-05 23:37:36.289266 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-05 23:37:36.289280 | controller | SHLVL=1
2025-12-05 23:37:36.289293 | controller | ZUUL_PATCHSET=ef8821f44d461626a5ab46002cd514823bd56c63
2025-12-05 23:37:36.289307 | controller | XDG_SESSION_ID=1
2025-12-05 23:37:36.289320 | controller | ZUUL_BRANCH=main
2025-12-05 23:37:36.289334 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-05 23:37:36.289347 | controller | SSH_CLIENT=38.102.83.114 43048 22
2025-12-05 23:37:36.289361 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-05 23:37:36.289375 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-05 23:37:36.289388 | controller | which_declare=declare -f
2025-12-05 23:37:36.289403 | 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-12-05 23:37:36.289417 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-05 23:37:36.289431 | controller | ZUUL_CHANGE=3539
2025-12-05 23:37:36.289444 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-05 23:37:36.289457 | controller | ZUUL_UUID=e3c6c60ee7d64f279e1e5b4b09fc4e37
2025-12-05 23:37:36.289471 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-05 23:37:36.289485 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-05 23:37:36.289500 | controller | }
2025-12-05 23:37:36.421498 | controller | ok: Runtime: 0:00:00.009179
2025-12-05 23:37:36.434122 | 
2025-12-05 23:37:36.434256 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-05 23:37:36.471613 | controller | skipping: Conditional result was False
2025-12-05 23:37:36.485333 | 
2025-12-05 23:37:36.485485 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-05 23:37:37.033985 | controller | skipping: Conditional result was False
2025-12-05 23:37:37.040901 | 
2025-12-05 23:37:37.040969 | TASK [Ensure legacy workspace directory]
2025-12-05 23:37:37.454796 | controller | changed
2025-12-05 23:37:37.477430 | 
2025-12-05 23:37:37.477483 | PLAY RECAP
2025-12-05 23:37:37.477526 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 23:37:37.477547 | 
2025-12-05 23:37:37.630203 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-05 23:37:37.631140 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-12-05 23:37:38.347339 | 
2025-12-05 23:37:38.347461 | PLAY [all,!compute]
2025-12-05 23:37:38.388676 | 
2025-12-05 23:37:38.388778 | TASK [Enable container-tools rhel-modules for c8/crc node]
2025-12-05 23:37:38.919678 | controller | skipping: Conditional result was False
2025-12-05 23:37:38.925525 | 
2025-12-05 23:37:38.925598 | TASK [Install podman]
2025-12-05 23:37:38.981078 | controller | skipping: Conditional result was False
2025-12-05 23:37:38.988469 | 
2025-12-05 23:37:38.988545 | TASK [Set var name for quay login zuul secret]
2025-12-05 23:37:39.032899 | controller | skipping: Conditional result was False
2025-12-05 23:37:39.039813 | 
2025-12-05 23:37:39.039885 | TASK [Print the username]
2025-12-05 23:37:39.578907 | controller | skipping: Conditional result was False
2025-12-05 23:37:39.586525 | 
2025-12-05 23:37:39.586712 | TASK [Fail when user or password is not set]
2025-12-05 23:37:39.741495 | controller | skipping: Conditional result was False
2025-12-05 23:37:39.749098 | 
2025-12-05 23:37:39.749266 | TASK [Set vars for quay login]
2025-12-05 23:37:39.815418 | 
2025-12-05 23:37:39.815649 | LOOP [Perform container registry login(s) with podman]
2025-12-05 23:37:39.897688 | 
2025-12-05 23:37:39.897877 | LOOP [Perform container registry login(s) with buildah]
2025-12-05 23:37:39.954174 | 
2025-12-05 23:37:39.954347 | TASK [Set Insecure registry for content provider]
2025-12-05 23:37:40.000069 | controller | skipping: Conditional result was False
2025-12-05 23:37:40.019246 | 
2025-12-05 23:37:40.019365 | PLAY RECAP
2025-12-05 23:37:40.019415 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 9 rescued: 0 ignored: 0
2025-12-05 23:37:40.019436 | 
2025-12-05 23:37:40.125042 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-12-05 23:37:40.125868 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-12-05 23:37:40.713721 | 
2025-12-05 23:37:40.713901 | PLAY [Prefer ipv4 over ipv6]
2025-12-05 23:37:40.733791 | 
2025-12-05 23:37:40.733864 | TASK [Configure /etc/gai.conf to prever ipv4 over ipv6]
2025-12-05 23:37:40.799070 | controller | skipping: Conditional result was False
2025-12-05 23:37:40.854852 | 
2025-12-05 23:37:40.854985 | PLAY [controller]
2025-12-05 23:37:40.874841 | 
2025-12-05 23:37:40.874956 | TASK [Fail when openshift_pull_sec not provided]
2025-12-05 23:37:40.910566 | controller | skipping: Conditional result was False
2025-12-05 23:37:40.919239 | 
2025-12-05 23:37:40.919328 | TASK [Clone repos in the job workspace]
2025-12-05 23:37:40.939879 | controller | ok
2025-12-05 23:37:40.955434 | 
2025-12-05 23:37:40.955502 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-05 23:37:41.452246 | controller | ok
2025-12-05 23:37:41.465649 | 
2025-12-05 23:37:41.465855 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-05 23:37:45.317991 | controller | Output suppressed because no_log was given
2025-12-05 23:37:45.331785 | 
2025-12-05 23:37:45.331919 | TASK [Create pull-secret.txt file]
2025-12-05 23:37:46.974288 | controller | Output suppressed because no_log was given
2025-12-05 23:37:46.996215 | 
2025-12-05 23:37:46.996368 | TASK [Apply CRC features and print image build date]
2025-12-05 23:37:47.031416 | controller | ok
2025-12-05 23:37:47.041343 | controller | included: /var/lib/zuul/builds/e3c6c60ee7d64f279e1e5b4b09fc4e37/trusted/project_0/review.rdoproject.org/config/playbooks/crc/features.yaml
2025-12-05 23:37:47.051300 | 
2025-12-05 23:37:47.051392 | TASK [Set additional parameters for crc - telemetry]
2025-12-05 23:37:47.619518 | controller | skipping: Conditional result was False
2025-12-05 23:37:47.625517 | 
2025-12-05 23:37:47.625582 | TASK [Set additional parameters for crc - monitoring]
2025-12-05 23:37:48.155920 | controller | skipping: Conditional result was False
2025-12-05 23:37:48.169173 | 
2025-12-05 23:37:48.169317 | TASK [Set minimum required memory to run the CRC when monitoring enabled]
2025-12-05 23:37:48.713644 | controller | skipping: Conditional result was False
2025-12-05 23:37:48.722688 | 
2025-12-05 23:37:48.722787 | TASK [Set additional parameters for crc - enable microshift preset]
2025-12-05 23:37:49.262144 | controller | skipping: Conditional result was False
2025-12-05 23:37:49.275926 | 
2025-12-05 23:37:49.276066 | TASK [Set additional parameters for crc - enable okd preset]
2025-12-05 23:37:49.830439 | controller | skipping: Conditional result was False
2025-12-05 23:37:49.839645 | 
2025-12-05 23:37:49.839785 | TASK [Delete the cluster if there's a non-default bundle URL]
2025-12-05 23:37:50.382794 | controller | skipping: Conditional result was False
2025-12-05 23:37:50.396931 | 
2025-12-05 23:37:50.397096 | TASK [Execute crc start command]
2025-12-05 23:50:02.945914 | controller | ok: Runtime: 0:12:11.615051
2025-12-05 23:50:02.957391 | 
2025-12-05 23:50:02.957518 | TASK [Verify that OpenShift is up and running]
2025-12-05 23:50:02.987241 | controller | ok
2025-12-05 23:50:03.005813 | controller | included: /var/lib/zuul/builds/e3c6c60ee7d64f279e1e5b4b09fc4e37/trusted/project_0/review.rdoproject.org/config/playbooks/crc/verify_openshift_start.yaml
2025-12-05 23:50:03.017181 | 
2025-12-05 23:50:03.017295 | TASK [Create script to login and verify services]
2025-12-05 23:50:04.575953 | controller | changed
2025-12-05 23:50:04.588820 | 
2025-12-05 23:50:04.588946 | TASK [Ensure that user is logged]
2025-12-05 23:50:05.010545 | controller | Checking login to the cluster 1...
2025-12-05 23:50:05.440491 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-05 23:50:05.678066 | controller |
2025-12-05 23:50:05.678144 | controller | Login successful.
2025-12-05 23:50:05.711647 | controller |
2025-12-05 23:50:05.711718 | controller | You have access to 65 projects, the list has been suppressed. You can list all projects with 'oc projects'
2025-12-05 23:50:05.735860 | controller |
2025-12-05 23:50:05.735982 | controller | Using project "default".
2025-12-05 23:50:06.138492 | controller | changed
2025-12-05 23:50:06.150182 | 
2025-12-05 23:50:06.150307 | TASK [Delete all openshift-marketplace pods]
2025-12-05 23:50:09.509150 | controller | pod "certified-operators-mz9qw" deleted
2025-12-05 23:50:09.544219 | controller | pod "community-operators-rl9mj" deleted
2025-12-05 23:50:09.586019 | controller | pod "marketplace-operator-79b997595-bv56r" deleted
2025-12-05 23:50:09.617305 | controller | pod "redhat-marketplace-ww44x" deleted
2025-12-05 23:50:13.229268 | controller | pod "redhat-operators-x9gsj" deleted
2025-12-05 23:50:15.239973 | controller | ok
2025-12-05 23:50:15.251412 | 
2025-12-05 23:50:15.251742 | TASK [Copy script ensure_services_up.sh]
2025-12-05 23:50:15.321893 | controller | skipping: Conditional result was False
2025-12-05 23:50:15.334249 | 
2025-12-05 23:50:15.334390 | TASK [Ensure all pods are up and running]
2025-12-05 23:50:15.887882 | controller | skipping: Conditional result was False
2025-12-05 23:50:15.901337 | 
2025-12-05 23:50:15.901477 | TASK [Check if there is a image build date file]
2025-12-05 23:50:16.327398 | controller | ok
2025-12-05 23:50:16.339394 | 
2025-12-05 23:50:16.339521 | TASK [Print image date if available]
2025-12-05 23:50:16.789250 | controller | Mon Nov 24 08:30:59 AM EST 2025
2025-12-05 23:50:16.892448 | controller | changed
2025-12-05 23:50:16.903242 | 
2025-12-05 23:50:16.903374 | TASK [Fix machineconfigpool]
2025-12-05 23:50:16.971438 | controller | skipping: Conditional result was False
2025-12-05 23:50:16.983527 | 
2025-12-05 23:50:16.983654 | TASK [Show available nodes]
2025-12-05 23:50:17.676930 | controller | NAME   STATUS   ROLES                         AGE    VERSION
2025-12-05 23:50:17.680500 | controller | crc    Ready    control-plane,master,worker   285d   v1.31.5
2025-12-05 23:50:18.067422 | controller | ok: Runtime: 0:00:00.299414
2025-12-05 23:50:18.082769 | 
2025-12-05 23:50:18.082922 | TASK [Configure insecure registry inside crc vm]
2025-12-05 23:50:18.122489 | controller | skipping: Conditional result was False
2025-12-05 23:50:18.135093 | 
2025-12-05 23:50:18.135227 | TASK [Catch CRC IP]
2025-12-05 23:50:18.803504 | controller | 192.168.130.11
2025-12-05 23:50:19.185467 | controller | changed
2025-12-05 23:50:19.192826 | 
2025-12-05 23:50:19.192901 | TASK [Check if id_ed25519 key exists]
2025-12-05 23:50:19.750798 | controller | ok
2025-12-05 23:50:19.763233 | 
2025-12-05 23:50:19.763373 | TASK [Set fact if new keypair exists]
2025-12-05 23:50:19.812550 | controller | ok
2025-12-05 23:50:19.824136 | 
2025-12-05 23:50:19.824289 | TASK [Run crc]
2025-12-05 23:50:21.565716 | controller |
2025-12-05 23:50:34.338436 | controller | PLAY [OpenShift secret copy and CRC securitization] ****************************
2025-12-05 23:50:34.338524 | controller |
2025-12-05 23:50:34.338535 | controller | TASK [Gathering Facts] *********************************************************
2025-12-05 23:50:34.338576 | controller | [WARNING]: Platform linux on host 192.168.130.11 is using the discovered Python
2025-12-05 23:50:34.387214 | controller | interpreter at /usr/bin/python3.9, but future installation of another Python
2025-12-05 23:50:34.387293 | controller | interpreter could change the meaning of that path. See
2025-12-05 23:50:34.387304 | controller | https://docs.ansible.com/ansible-
2025-12-05 23:50:34.387312 | controller | core/2.14/reference_appendices/interpreter_discovery.html for more information.
2025-12-05 23:50:34.387332 | controller | ok: [192.168.130.11]
2025-12-05 23:50:36.343920 | controller |
2025-12-05 23:50:36.344142 | controller | TASK [Copy pull-secret] ********************************************************
2025-12-05 23:50:36.344169 | controller | changed: [192.168.130.11]
2025-12-05 23:50:37.771406 | controller |
2025-12-05 23:50:37.771463 | controller | TASK [Add crio config] *********************************************************
2025-12-05 23:50:37.771476 | controller | changed: [192.168.130.11]
2025-12-05 23:50:42.190702 | controller |
2025-12-05 23:50:42.190806 | controller | TASK [Restart crio to pickup new configuration] ********************************
2025-12-05 23:50:42.190847 | controller | changed: [192.168.130.11]
2025-12-05 23:50:42.254672 | controller |
2025-12-05 23:50:42.254770 | controller | PLAY RECAP *********************************************************************
2025-12-05 23:50:42.254784 | controller | 192.168.130.11             : ok=4    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-12-05 23:50:42.254791 | controller |
2025-12-05 23:50:42.418500 | controller | changed
2025-12-05 23:50:42.430437 | 
2025-12-05 23:50:42.430586 | TASK [Set slower etcd profile]
2025-12-05 23:50:42.508390 | controller | ok
2025-12-05 23:50:42.552258 | 
2025-12-05 23:50:42.552419 | TASK [prepare-crc-cloud : Set slower profile]
2025-12-05 23:50:43.062372 | controller | etcd.operator.openshift.io/cluster patched
2025-12-05 23:50:43.103431 | controller | ok
2025-12-05 23:50:43.111185 | 
2025-12-05 23:50:43.111270 | TASK [prepare-crc-cloud : Wait 30 seconds to apply]
2025-12-05 23:51:13.153288 | controller | ok
2025-12-05 23:51:13.165614 | 
2025-12-05 23:51:13.165882 | TASK [prepare-crc-cloud : Get pod phase status]
2025-12-05 23:56:37.314546 | controller | ok
2025-12-05 23:56:37.342333 | 
2025-12-05 23:56:37.342488 | TASK [Set limit for disk usage - IOPS and Read/Write]
2025-12-05 23:56:37.421837 | controller | ok
2025-12-05 23:56:37.433996 | controller | included: /var/lib/zuul/builds/e3c6c60ee7d64f279e1e5b4b09fc4e37/trusted/project_0/review.rdoproject.org/config/playbooks/crc/limit-disk-usage.yaml
2025-12-05 23:56:37.448155 | 
2025-12-05 23:56:37.448247 | TASK [Set facts for disk limitation]
2025-12-05 23:56:37.481421 | controller | ok
2025-12-05 23:56:37.487015 | 
2025-12-05 23:56:37.487083 | TASK [Print limits for the disk]
2025-12-05 23:56:37.531850 | controller | ok: Set limit to crc VM to the vda disk: iops 18000 read/write 262144000
2025-12-05 23:56:37.540462 | 
2025-12-05 23:56:37.540553 | TASK [Set limit to crc VM to the vda disk]
2025-12-05 23:56:37.990154 | controller |
2025-12-05 23:56:38.087831 | controller | changed
2025-12-05 23:56:38.099991 | 
2025-12-05 23:56:38.100197 | TASK [Pause machineconfigpool]
2025-12-05 23:56:38.183707 | controller | ok
2025-12-05 23:56:38.228302 | 
2025-12-05 23:56:38.228463 | LOOP [prepare-crc-cloud : Pause the machineconfig configuration if mcp is not needed]
2025-12-05 23:56:38.844209 | controller | ok
2025-12-05 23:56:39.347292 | controller | ok
2025-12-05 23:56:39.368584 | 
2025-12-05 23:56:39.368842 | TASK [prepare-crc-cloud : Print current machineconfigpool]
2025-12-05 23:56:39.889602 | controller | NAME     CONFIG                                             UPDATED   UPDATING   DEGRADED   MACHINECOUNT   READYMACHINECOUNT   UPDATEDMACHINECOUNT   DEGRADEDMACHINECOUNT   AGE
2025-12-05 23:56:39.889704 | controller | master   rendered-master-63af5c6b4fa17c607a1f2008d8a3ac33   True      False      False      1              1                   1                     0                      285d
2025-12-05 23:56:39.895423 | controller | worker   rendered-worker-594cc07c9feefb5e37f2530ac457d36c   True      False      False      0              0                   0                     0                      285d
2025-12-05 23:56:39.940550 | controller | ok
2025-12-05 23:56:39.987624 | 
2025-12-05 23:56:39.987752 | PLAY [Set IOPS and Read/Write disk limitation excluding controller]
2025-12-05 23:56:39.987887 | 
2025-12-05 23:56:39.987926 | PLAY RECAP
2025-12-05 23:56:39.987974 | controller | ok: 25 changed: 10 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0
2025-12-05 23:56:39.988002 | 
2025-12-05 23:56:40.102634 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-12-05 23:56:40.104291 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-05 23:56:40.681942 | 
2025-12-05 23:56:40.682076 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-05 23:56:40.702959 | 
2025-12-05 23:56:40.703038 | TASK [Create zuul-output directory]
2025-12-05 23:56:41.230281 | controller | changed
2025-12-05 23:56:41.241746 | 
2025-12-05 23:56:41.241905 | TASK [Slurp Zuul inventory test]
2025-12-05 23:56:41.647213 | controller -> localhost | ok
2025-12-05 23:56:41.661297 | 
2025-12-05 23:56:41.661437 | TASK [Save zuul inventory]
2025-12-05 23:56:43.438499 | controller | changed
2025-12-05 23:56:43.445342 | 
2025-12-05 23:56:43.445456 | TASK [Save zuul vars without the change_message]
2025-12-05 23:56:44.825924 | controller | changed
2025-12-05 23:56:44.870244 | 
2025-12-05 23:56:44.870336 | PLAY RECAP
2025-12-05 23:56:44.870395 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 23:56:44.870444 | 
2025-12-05 23:56:44.982545 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-05 23:56:44.983423 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-05 23:56:45.591548 | 
2025-12-05 23:56:45.591690 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-05 23:56:45.615151 | 
2025-12-05 23:56:45.615247 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-05 23:56:45.635175 | controller | ok
2025-12-05 23:56:45.653818 | 
2025-12-05 23:56:45.653903 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-05 23:56:45.678230 | controller | skipping: Conditional result was False
2025-12-05 23:56:45.684997 | 
2025-12-05 23:56:45.685072 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-05 23:56:46.210408 | controller | ok
2025-12-05 23:56:46.223659 | 
2025-12-05 23:56:46.223879 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-05 23:56:47.824802 | controller | ok
2025-12-05 23:56:47.846961 | 
2025-12-05 23:56:47.847132 | TASK [Prepare workspace]
2025-12-05 23:56:47.876399 | controller | ok
2025-12-05 23:56:47.907588 | 
2025-12-05 23:56:47.907720 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-05 23:56:48.404985 | controller | ok
2025-12-05 23:56:48.418962 | 
2025-12-05 23:56:48.419111 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-05 23:56:49.702315 | controller | Output suppressed because no_log was given
2025-12-05 23:56:49.765208 | 
2025-12-05 23:56:49.765404 | LOOP [Create zuul-output directory]
2025-12-05 23:56:50.149444 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-05 23:56:50.455330 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-05 23:56:50.472306 | 
2025-12-05 23:56:50.472547 | TASK [Install required packages]
2025-12-05 23:57:03.888382 | controller | ok: Nothing to do
2025-12-05 23:57:03.901029 | 
2025-12-05 23:57:03.901172 | TASK [Install venv]
2025-12-05 23:57:16.484310 | controller | ERROR
2025-12-05 23:57:16.484759 | controller | {
2025-12-05 23:57:16.484835 | controller |   "msg": "gmake: *** [Makefile:56: setup_tests] Error 1",
2025-12-05 23:57:16.484880 | controller |   "rc": 2
2025-12-05 23:57:16.484918 | controller | }
failure
2025-12-05 23:57:16.490552 | 
2025-12-05 23:57:16.490696 | PLAY RECAP
2025-12-05 23:57:16.490792 | controller | ok: 6 changed: 1 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-12-05 23:57:16.490838 | 
2025-12-05 23:57:16.621202 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-05 23:57:16.623101 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-05 23:57:17.239360 | 
2025-12-05 23:57:17.239492 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-05 23:57:17.260111 | 
2025-12-05 23:57:17.260192 | TASK [Filter out host if needed]
2025-12-05 23:57:17.268773 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-05 23:57:17.273413 | 
2025-12-05 23:57:17.273474 | TASK [Ensure file is present]
2025-12-05 23:57:17.808474 | controller | ok
2025-12-05 23:57:17.822400 | 
2025-12-05 23:57:17.822605 | TASK [Manage molecule report file]
2025-12-05 23:57:18.369185 | controller | skipping: Conditional result was False
2025-12-05 23:57:18.382300 | 
2025-12-05 23:57:18.382437 | TASK [Check if we get ci-framework-data basedir]
2025-12-05 23:57:18.817806 | controller | ok
2025-12-05 23:57:18.829420 | 
2025-12-05 23:57:18.829549 | TASK [Create ci-framework-data log directory for zuul]
2025-12-05 23:57:19.377144 | controller | changed
2025-12-05 23:57:19.388762 | 
2025-12-05 23:57:19.388899 | TASK [Copy ci-framework interesting files]
2025-12-05 23:57:19.971190 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-12-05 23:57:20.440317 | controller | changed
2025-12-05 23:57:20.451723 | 
2025-12-05 23:57:20.451854 | TASK [Get SELinux listing]
2025-12-05 23:57:21.004782 | controller | changed
2025-12-05 23:57:21.016771 | 
2025-12-05 23:57:21.016904 | TASK [Generate log index]
2025-12-05 23:57:22.691526 | controller | changed
2025-12-05 23:57:22.703199 | 
2025-12-05 23:57:22.703336 | TASK [Get some env related data]
2025-12-05 23:57:23.487727 | controller | /usr/bin/ansible
2025-12-05 23:57:24.758898 | controller | changed
2025-12-05 23:57:24.770909 | 
2025-12-05 23:57:24.771051 | TASK [Generate list of logs to collect in home directory]
2025-12-05 23:57:25.244798 | controller | ok: All paths examined
2025-12-05 23:57:25.256411 | 
2025-12-05 23:57:25.256567 | LOOP [Copy logs from home directory]
2025-12-05 23:57:26.070622 | controller | changed:
2025-12-05 23:57:26.070909 | controller | {
2025-12-05 23:57:26.070935 | controller |   "atime": 1763989167.9348345,
2025-12-05 23:57:26.070953 | controller |   "ctime": 1763990251.6129293,
2025-12-05 23:57:26.070969 | controller |   "dev": 64513,
2025-12-05 23:57:26.070985 | controller |   "gid": 1000,
2025-12-05 23:57:26.071000 | controller |   "gr_name": "zuul",
2025-12-05 23:57:26.071016 | controller |   "inode": 5131423,
2025-12-05 23:57:26.071031 | controller |   "isblk": false,
2025-12-05 23:57:26.071046 | controller |   "ischr": false,
2025-12-05 23:57:26.071060 | controller |   "isdir": false,
2025-12-05 23:57:26.071074 | controller |   "isfifo": false,
2025-12-05 23:57:26.071089 | controller |   "isgid": false,
2025-12-05 23:57:26.071103 | controller |   "islnk": false,
2025-12-05 23:57:26.071117 | controller |   "isreg": true,
2025-12-05 23:57:26.071131 | controller |   "issock": false,
2025-12-05 23:57:26.071145 | controller |   "isuid": false,
2025-12-05 23:57:26.071160 | controller |   "mode": "0644",
2025-12-05 23:57:26.071174 | controller |   "mtime": 1763990251.6129293,
2025-12-05 23:57:26.071188 | controller |   "nlink": 1,
2025-12-05 23:57:26.071202 | controller |   "path": "/home/zuul/crc-setup.log",
2025-12-05 23:57:26.071220 | controller |   "pw_name": "zuul",
2025-12-05 23:57:26.071236 | controller |   "rgrp": true,
2025-12-05 23:57:26.071250 | controller |   "roth": true,
2025-12-05 23:57:26.071265 | controller |   "rusr": true,
2025-12-05 23:57:26.071280 | controller |   "size": 4108,
2025-12-05 23:57:26.071294 | controller |   "uid": 1000,
2025-12-05 23:57:26.071308 | controller |   "wgrp": false,
2025-12-05 23:57:26.071323 | controller |   "woth": false,
2025-12-05 23:57:26.071339 | controller |   "wusr": true,
2025-12-05 23:57:26.071355 | controller |   "xgrp": false,
2025-12-05 23:57:26.071370 | controller |   "xoth": false,
2025-12-05 23:57:26.071384 | controller |   "xusr": false
2025-12-05 23:57:26.071398 | controller | }
2025-12-05 23:57:26.797084 | controller | changed:
2025-12-05 23:57:26.797220 | controller | {
2025-12-05 23:57:26.797243 | controller |   "atime": 1764978605.007126,
2025-12-05 23:57:26.797263 | controller |   "ctime": 1764978602.543061,
2025-12-05 23:57:26.797279 | controller |   "dev": 64513,
2025-12-05 23:57:26.797295 | controller |   "gid": 1000,
2025-12-05 23:57:26.797310 | controller |   "gr_name": "zuul",
2025-12-05 23:57:26.797325 | controller |   "inode": 5131425,
2025-12-05 23:57:26.797340 | controller |   "isblk": false,
2025-12-05 23:57:26.797355 | controller |   "ischr": false,
2025-12-05 23:57:26.797369 | controller |   "isdir": false,
2025-12-05 23:57:26.797384 | controller |   "isfifo": false,
2025-12-05 23:57:26.797398 | controller |   "isgid": false,
2025-12-05 23:57:26.797412 | controller |   "islnk": false,
2025-12-05 23:57:26.797426 | controller |   "isreg": true,
2025-12-05 23:57:26.797442 | controller |   "issock": false,
2025-12-05 23:57:26.797456 | controller |   "isuid": false,
2025-12-05 23:57:26.797470 | controller |   "mode": "0644",
2025-12-05 23:57:26.797484 | controller |   "mtime": 1764978602.543061,
2025-12-05 23:57:26.797499 | controller |   "nlink": 1,
2025-12-05 23:57:26.797514 | controller |   "path": "/home/zuul/crc-start.log",
2025-12-05 23:57:26.797540 | controller |   "pw_name": "zuul",
2025-12-05 23:57:26.797555 | controller |   "rgrp": true,
2025-12-05 23:57:26.797570 | controller |   "roth": true,
2025-12-05 23:57:26.797584 | controller |   "rusr": true,
2025-12-05 23:57:26.797599 | controller |   "size": 4572,
2025-12-05 23:57:26.797613 | controller |   "uid": 1000,
2025-12-05 23:57:26.797628 | controller |   "wgrp": false,
2025-12-05 23:57:26.797642 | controller |   "woth": false,
2025-12-05 23:57:26.797656 | controller |   "wusr": true,
2025-12-05 23:57:26.797694 | controller |   "xgrp": false,
2025-12-05 23:57:26.797711 | controller |   "xoth": false,
2025-12-05 23:57:26.797726 | controller |   "xusr": false
2025-12-05 23:57:26.797740 | controller | }
2025-12-05 23:57:26.817346 | 
2025-12-05 23:57:26.817551 | TASK [Copy crio stats log file]
2025-12-05 23:57:26.844528 | controller | skipping: Conditional result was False
2025-12-05 23:57:26.857492 | 
2025-12-05 23:57:26.857640 | TASK [Get SELinux related data]
2025-12-05 23:57:27.264977 | controller | <no matches>
2025-12-05 23:57:27.409299 | controller | ERROR
2025-12-05 23:57:27.409635 | controller | {
2025-12-05 23:57:27.409740 | controller |   "delta": "0:00:00.015417",
2025-12-05 23:57:27.409782 | controller |   "end": "2025-12-05 23:57:27.266248",
2025-12-05 23:57:27.409817 | controller |   "msg": "non-zero return code",
2025-12-05 23:57:27.409851 | controller |   "rc": 1,
2025-12-05 23:57:27.409886 | controller |   "start": "2025-12-05 23:57:27.250831"
2025-12-05 23:57:27.409920 | controller | }
2025-12-05 23:57:27.409965 | controller | ERROR: Ignoring Errors
2025-12-05 23:57:27.421076 | 
2025-12-05 23:57:27.421207 | TASK [Create system configuration directory]
2025-12-05 23:57:27.825790 | controller | changed
2025-12-05 23:57:27.836460 | 
2025-12-05 23:57:27.836591 | TASK [Get some of the system configurations]
2025-12-05 23:57:28.387190 | controller | changed
2025-12-05 23:57:28.398170 | 
2025-12-05 23:57:28.398297 | TASK [Copy generated documentation if available]
2025-12-05 23:57:28.425292 | controller | skipping: Conditional result was False
2025-12-05 23:57:28.438099 | 
2025-12-05 23:57:28.438229 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-05 23:57:28.464913 | controller | skipping: Conditional result was False
2025-12-05 23:57:28.477297 | 
2025-12-05 23:57:28.477430 | TASK [Compress logs bigger than 2MB]
2025-12-05 23:57:29.024872 | controller | changed
2025-12-05 23:57:29.036542 | 
2025-12-05 23:57:29.036704 | TASK [Copy files from workspace on node]
2025-12-05 23:57:29.066188 | controller | ok
2025-12-05 23:57:29.106385 | 
2025-12-05 23:57:29.106480 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 23:57:29.133376 | controller | skipping: Conditional result was False
2025-12-05 23:57:29.148465 | 
2025-12-05 23:57:29.148635 | TASK [fetch-output : Set log path for single node]
2025-12-05 23:57:29.196935 | controller | ok
2025-12-05 23:57:29.207981 | 
2025-12-05 23:57:29.208112 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 23:57:29.504247 | controller -> localhost | ok: "/var/lib/zuul/builds/e3c6c60ee7d64f279e1e5b4b09fc4e37/work/logs"
2025-12-05 23:57:29.504547 | controller -> localhost | changed: All items complete
2025-12-05 23:57:29.504576 | 
2025-12-05 23:57:29.764533 | controller -> localhost | changed: "/var/lib/zuul/builds/e3c6c60ee7d64f279e1e5b4b09fc4e37/work/artifacts"
2025-12-05 23:57:29.981929 | controller -> localhost | changed: "/var/lib/zuul/builds/e3c6c60ee7d64f279e1e5b4b09fc4e37/work/docs"
2025-12-05 23:57:30.005569 | 
2025-12-05 23:57:30.005798 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 23:57:30.861020 | controller | changed:
2025-12-05 23:57:30.861594 | controller | .d..t...... ./
2025-12-05 23:57:30.861698 | controller | >f+++++++++ README.html
2025-12-05 23:57:30.861753 | controller | >f+++++++++ crc-setup.log
2025-12-05 23:57:30.861799 | controller | >f+++++++++ crc-start.log
2025-12-05 23:57:30.861838 | controller | >f+++++++++ dmesg.log
2025-12-05 23:57:30.861892 | controller | >f+++++++++ installed-pkgs.log
2025-12-05 23:57:30.861930 | controller | >f+++++++++ python.log
2025-12-05 23:57:30.861966 | controller | >f+++++++++ registries.conf
2025-12-05 23:57:30.862001 | controller | >f+++++++++ selinux-denials.log
2025-12-05 23:57:30.862036 | controller | >f+++++++++ selinux-listing.log
2025-12-05 23:57:30.862070 | controller | cd+++++++++ ci-framework-data/
2025-12-05 23:57:30.862103 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-05 23:57:30.862137 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-05 23:57:30.862173 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-05 23:57:30.862208 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-05 23:57:30.862242 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-05 23:57:30.862276 | controller | cd+++++++++ registries.conf.d/
2025-12-05 23:57:30.862311 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-05 23:57:30.862346 | controller | cd+++++++++ system-config/
2025-12-05 23:57:30.862380 | controller | cd+++++++++ system-config/libvirt/
2025-12-05 23:57:30.862414 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-05 23:57:30.862448 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-05 23:57:30.862483 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-12-05 23:57:30.862518 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-12-05 23:57:30.862552 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-12-05 23:57:30.862586 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-12-05 23:57:30.862620 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-12-05 23:57:30.862654 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-12-05 23:57:30.862731 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-12-05 23:57:30.862769 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-12-05 23:57:30.862803 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-12-05 23:57:30.862837 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-12-05 23:57:30.862872 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-12-05 23:57:30.862905 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-12-05 23:57:30.862938 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-12-05 23:57:30.862972 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-12-05 23:57:31.514555 | controller | changed: .d..t...... ./
2025-12-05 23:57:32.127033 | controller | changed: .d..t...... ./
2025-12-05 23:57:32.161434 | 
2025-12-05 23:57:32.161742 | TASK [Return artifact to Zuul]
2025-12-05 23:57:32.202743 | controller | skipping: Conditional result was False
2025-12-05 23:57:32.244495 | 
2025-12-05 23:57:32.244575 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-05 23:57:32.244744 | 
2025-12-05 23:57:32.244789 | PLAY RECAP
2025-12-05 23:57:32.244842 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1
2025-12-05 23:57:32.244868 | 
2025-12-05 23:57:32.418402 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-05 23:57:32.420200 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-12-05 23:57:32.993837 | 
2025-12-05 23:57:32.993966 | PLAY [Report DLRN results after job run]
2025-12-05 23:57:33.014382 | 
2025-12-05 23:57:33.014457 | TASK [Report job status to DLRN]
2025-12-05 23:57:33.036286 | controller | ok
2025-12-05 23:57:33.061981 | 
2025-12-05 23:57:33.062187 | TASK [dlrn_report : Warning if DLRN is not running]
2025-12-05 23:57:33.139090 | controller | skipping: Conditional result was False
2025-12-05 23:57:33.147455 | 
2025-12-05 23:57:33.147566 | TASK [dlrn_report : Install dlrnapi-client shyaml package]
2025-12-05 23:57:33.204944 | controller | skipping: Conditional result was False
2025-12-05 23:57:33.218388 | 
2025-12-05 23:57:33.218535 | TASK [dlrn_report : Install kinit related package]
2025-12-05 23:57:33.278296 | controller | skipping: Conditional result was False
2025-12-05 23:57:33.290903 | 
2025-12-05 23:57:33.291047 | TASK [dlrn_report : Install dlrn kerberos related packages]
2025-12-05 23:57:33.350253 | controller | skipping: Conditional result was False
2025-12-05 23:57:33.363266 | 
2025-12-05 23:57:33.363405 | TASK [Set zuul-log-path fact]
2025-12-05 23:57:33.422063 | controller | skipping: Conditional result was False
2025-12-05 23:57:33.437964 | 
2025-12-05 23:57:33.438109 | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used]
2025-12-05 23:57:33.496835 | controller | skipping: Conditional result was False
2025-12-05 23:57:33.509723 | 
2025-12-05 23:57:33.509869 | TASK [Get hash related data from repo_setup role]
2025-12-05 23:57:33.570053 | controller | skipping: Conditional result was False
2025-12-05 23:57:33.584120 | 
2025-12-05 23:57:33.584268 | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication]
2025-12-05 23:57:34.146933 | controller | skipping: Conditional result was False
2025-12-05 23:57:34.155597 | 
2025-12-05 23:57:34.155717 | TASK [dlrn_report : Set empty value for dlrnapi password]
2025-12-05 23:57:34.232307 | controller | skipping: Conditional result was False
2025-12-05 23:57:34.246068 | 
2025-12-05 23:57:34.246208 | TASK [dlrn_report : Report results to dlrn for the tested hash]
2025-12-05 23:57:34.798303 | controller | skipping: Conditional result was False
2025-12-05 23:57:34.850777 | 
2025-12-05 23:57:34.850864 | PLAY RECAP
2025-12-05 23:57:34.850920 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 0
2025-12-05 23:57:34.850948 | 
2025-12-05 23:57:34.974629 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-12-05 23:57:34.975520 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 23:57:35.596129 | 
2025-12-05 23:57:35.596289 | PLAY [all]
2025-12-05 23:57:35.616095 | 
2025-12-05 23:57:35.616190 | TASK [include_role : fetch-output]
2025-12-05 23:57:35.667521 | controller | ok
2025-12-05 23:57:35.687630 | 
2025-12-05 23:57:35.687758 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 23:57:35.764410 | controller | skipping: Conditional result was False
2025-12-05 23:57:35.777524 | 
2025-12-05 23:57:35.777703 | TASK [fetch-output : Set log path for single node]
2025-12-05 23:57:35.827634 | controller | ok
2025-12-05 23:57:35.839105 | 
2025-12-05 23:57:35.839244 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 23:57:36.286267 | controller -> localhost | ok: "/var/lib/zuul/builds/e3c6c60ee7d64f279e1e5b4b09fc4e37/work/logs"
2025-12-05 23:57:36.504659 | controller -> localhost | ok: "/var/lib/zuul/builds/e3c6c60ee7d64f279e1e5b4b09fc4e37/work/artifacts"
2025-12-05 23:57:36.746487 | controller -> localhost | ok: "/var/lib/zuul/builds/e3c6c60ee7d64f279e1e5b4b09fc4e37/work/docs"
2025-12-05 23:57:36.760611 | 
2025-12-05 23:57:36.760766 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 23:57:37.627646 | controller | ok
2025-12-05 23:57:37.628133 | controller | ok: All items complete
2025-12-05 23:57:37.628197 | 
2025-12-05 23:57:38.283012 | controller | ok
2025-12-05 23:57:38.947221 | controller | ok
2025-12-05 23:57:38.974057 | 
2025-12-05 23:57:38.974284 | TASK [include_role : fetch-output-openshift]
2025-12-05 23:57:39.002184 | controller | skipping: Conditional result was False
2025-12-05 23:57:39.014951 | 
2025-12-05 23:57:39.015098 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-05 23:57:39.600707 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.014345
2025-12-05 23:57:39.891274 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.010524
2025-12-05 23:57:39.930298 | 
2025-12-05 23:57:39.930443 | PLAY [all]
2025-12-05 23:57:39.945737 | 
2025-12-05 23:57:39.945805 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-05 23:57:40.563338 | controller | changed
2025-12-05 23:57:40.609594 | 
2025-12-05 23:57:40.609693 | PLAY RECAP
2025-12-05 23:57:40.609759 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 23:57:40.609788 | 
2025-12-05 23:57:40.735809 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 23:57:40.737450 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-05 23:57:41.418333 | 
2025-12-05 23:57:41.418491 | PLAY [localhost]
2025-12-05 23:57:41.440280 | 
2025-12-05 23:57:41.440477 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-05 23:57:41.905847 | localhost | changed
2025-12-05 23:57:41.918073 | 
2025-12-05 23:57:41.918254 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-05 23:57:41.957482 | localhost | ok
2025-12-05 23:57:41.975875 | 
2025-12-05 23:57:41.976021 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-05 23:57:42.384402 | localhost | changed
2025-12-05 23:57:42.395552 | 
2025-12-05 23:57:42.395733 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-05 23:57:43.047829 | localhost | changed
2025-12-05 23:57:43.059815 | 
2025-12-05 23:57:43.060039 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-05 23:57:43.488123 | localhost | Identity added: /var/lib/zuul/builds/e3c6c60ee7d64f279e1e5b4b09fc4e37/work/tmp/ansible.v_p81qdg (/var/lib/zuul/builds/e3c6c60ee7d64f279e1e5b4b09fc4e37/work/tmp/ansible.v_p81qdg)
2025-12-05 23:57:43.488575 | localhost | ok: Runtime: 0:00:00.013018
2025-12-05 23:57:43.498088 | 
2025-12-05 23:57:43.498230 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-05 23:57:43.825806 | localhost | ok: Runtime: 0:00:00.009717
2025-12-05 23:57:43.832027 | 
2025-12-05 23:57:43.832099 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-05 23:57:43.917921 | localhost | changed
2025-12-05 23:57:43.927199 | 
2025-12-05 23:57:43.927328 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-05 23:57:44.394194 | localhost | changed
2025-12-05 23:57:44.432150 | 
2025-12-05 23:57:44.432238 | PLAY [localhost]
2025-12-05 23:57:44.450101 | 
2025-12-05 23:57:44.450187 | TASK [Generate bulk log download script]
2025-12-05 23:57:44.473951 | localhost | ok
2025-12-05 23:57:44.491047 | 
2025-12-05 23:57:44.491136 | TASK [local-log-download : Check API endpoint is defined]
2025-12-05 23:57:44.545769 | localhost | ok: All assertions passed
2025-12-05 23:57:44.555399 | 
2025-12-05 23:57:44.555525 | TASK [local-log-download : Create download script]
2025-12-05 23:57:45.081914 | localhost -> localhost | changed
2025-12-05 23:57:45.100832 | 
2025-12-05 23:57:45.100968 | TASK [Register quick-download link]
2025-12-05 23:57:45.139649 | localhost | ok
2025-12-05 23:57:45.204241 | 
2025-12-05 23:57:45.204393 | PLAY [logserver.rdoproject.org]
2025-12-05 23:57:45.213886 | 
2025-12-05 23:57:45.213947 | TASK [Set zuul-log-path fact]
2025-12-05 23:57:45.233785 | logserver.rdoproject.org | ok
2025-12-05 23:57:45.242637 | 
2025-12-05 23:57:45.242730 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 23:57:45.281788 | logserver.rdoproject.org | ok
2025-12-05 23:57:45.289198 | 
2025-12-05 23:57:45.289282 | TASK [upload-logs : Create log directories]
2025-12-05 23:57:46.012093 | logserver.rdoproject.org | changed
2025-12-05 23:57:46.015420 | 
2025-12-05 23:57:46.015484 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-05 23:57:46.356913 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.012283
2025-12-05 23:57:46.366609 | 
2025-12-05 23:57:46.366774 | TASK [upload-logs : Upload logs to log server]
2025-12-05 23:57:47.159345 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-05 23:57:47.166120 | 
2025-12-05 23:57:47.166256 | LOOP [upload-logs : Compress console log and json output]
2025-12-05 23:57:47.238970 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 23:57:47.260350 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 23:57:47.275058 | 
2025-12-05 23:57:47.275262 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-05 23:57:47.336780 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 23:57:47.337186 | 
2025-12-05 23:57:47.348314 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 23:57:47.362309 | 
2025-12-05 23:57:47.362509 | LOOP [upload-logs : Upload console log and json output]