2025-12-03 11:05:38.672685 | Job console starting...
2025-12-03 11:05:38.685377 | Updating repositories
2025-12-03 11:05:38.765147 | Preparing job workspace
2025-12-03 11:05:47.710169 | Running Ansible setup...
2025-12-03 11:05:54.203225 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-03 11:05:54.840556 | 
2025-12-03 11:05:54.840667 | PLAY [localhost]
2025-12-03 11:05:54.850105 | 
2025-12-03 11:05:54.850258 | TASK [Gathering Facts]
2025-12-03 11:05:55.831166 | localhost | ok
2025-12-03 11:05:55.851696 | 
2025-12-03 11:05:55.851897 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-03 11:05:56.315910 | localhost -> localhost | changed
2025-12-03 11:05:56.322767 | 
2025-12-03 11:05:56.322840 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-03 11:05:57.298892 | localhost -> localhost | changed
2025-12-03 11:05:57.326041 | 
2025-12-03 11:05:57.326269 | TASK [Setup log path fact]
2025-12-03 11:05:57.349819 | localhost | ok
2025-12-03 11:05:57.368826 | 
2025-12-03 11:05:57.368948 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-03 11:05:57.410556 | localhost | ok
2025-12-03 11:05:57.422808 | 
2025-12-03 11:05:57.423098 | TASK [emit-job-header : Print job information]
2025-12-03 11:05:57.477281 | # Job Information
2025-12-03 11:05:57.477516 | Ansible Version: 2.15.12
2025-12-03 11:05:57.477555 | Job: cifmw-crc-podified-edpm-baremetal
2025-12-03 11:05:57.477586 | Pipeline: github-check
2025-12-03 11:05:57.477613 | Executor: ze03.softwarefactory-project.io
2025-12-03 11:05:57.477639 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3500
2025-12-03 11:05:57.477667 | Log URL (when completed): https://logserver.rdoproject.org/1ef/rdoproject.org/1ef2272f6752415189f0ab4b3dd34fdf/
2025-12-03 11:05:57.477711 | Event ID: 533965c0-d035-11f0-800d-decda92a8968
2025-12-03 11:05:57.483467 | 
2025-12-03 11:05:57.483553 | LOOP [emit-job-header : Print node information]
2025-12-03 11:05:57.595783 | localhost | ok:
2025-12-03 11:05:57.596089 | localhost | # Node Information
2025-12-03 11:05:57.596154 | localhost | Inventory Hostname: controller
2025-12-03 11:05:57.596206 | localhost | Hostname: np0005543682
2025-12-03 11:05:57.596250 | localhost | Username: zuul
2025-12-03 11:05:57.596295 | localhost | Distro: CentOS 9
2025-12-03 11:05:57.596336 | localhost | Provider: vexxhost-nodepool-tripleo
2025-12-03 11:05:57.596375 | localhost | Region: RegionOne
2025-12-03 11:05:57.596413 | localhost | Label: centos-9-stream-crc-2-48-0-6xlarge
2025-12-03 11:05:57.596450 | localhost | Product Name: OpenStack Nova
2025-12-03 11:05:57.596486 | localhost | Interface IP: 38.102.83.243
2025-12-03 11:05:57.619372 | 
2025-12-03 11:05:57.619476 | PLAY [all]
2025-12-03 11:05:57.626036 | 
2025-12-03 11:05:57.626100 | TASK [Gather network facts]
2025-12-03 11:05:58.388153 | controller | ok
2025-12-03 11:05:58.402718 | 
2025-12-03 11:05:58.402828 | TASK [include_role : start-zuul-console]
2025-12-03 11:05:58.421312 | controller | ok
2025-12-03 11:05:58.433083 | 
2025-12-03 11:05:58.433159 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-03 11:05:59.384327 | controller | ok
2025-12-03 11:05:59.398788 | 
2025-12-03 11:05:59.398949 | TASK [include_role : add-build-sshkey]
2025-12-03 11:05:59.432463 | controller | ok
2025-12-03 11:05:59.451529 | 
2025-12-03 11:05:59.451657 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-03 11:05:59.740936 | controller -> localhost | ok
2025-12-03 11:05:59.746921 | 
2025-12-03 11:05:59.747001 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-03 11:05:59.776679 | controller | ok
2025-12-03 11:05:59.790227 | controller | included: /var/lib/zuul/builds/1ef2272f6752415189f0ab4b3dd34fdf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-03 11:05:59.796921 | 
2025-12-03 11:05:59.796997 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-03 11:06:00.269633 | controller -> localhost | Generating public/private rsa key pair.
2025-12-03 11:06:00.269883 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/1ef2272f6752415189f0ab4b3dd34fdf/work/1ef2272f6752415189f0ab4b3dd34fdf_id_rsa.
2025-12-03 11:06:00.269941 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/1ef2272f6752415189f0ab4b3dd34fdf/work/1ef2272f6752415189f0ab4b3dd34fdf_id_rsa.pub.
2025-12-03 11:06:00.269974 | controller -> localhost | The key fingerprint is:
2025-12-03 11:06:00.270003 | controller -> localhost | SHA256:4W4+S+JwxIFNaeCYlcIkL93AFSlbRTdlxeHeKbRd8bE zuul-build-sshkey
2025-12-03 11:06:00.270029 | controller -> localhost | The key's randomart image is:
2025-12-03 11:06:00.270055 | controller -> localhost | +---[RSA 3072]----+
2025-12-03 11:06:00.270081 | controller -> localhost | |.+o.=B+.o.ooo. o |
2025-12-03 11:06:00.270109 | controller -> localhost | | +=B++o. o ..   =|
2025-12-03 11:06:00.270131 | controller -> localhost | |. ==+.o .   o  Eo|
2025-12-03 11:06:00.270150 | controller -> localhost | | ..  . o . o + o |
2025-12-03 11:06:00.270168 | controller -> localhost | |      o S   + +  |
2025-12-03 11:06:00.270186 | controller -> localhost | |     . .     .   |
2025-12-03 11:06:00.270204 | controller -> localhost | |    . o +        |
2025-12-03 11:06:00.270222 | controller -> localhost | |     + =.        |
2025-12-03 11:06:00.270240 | controller -> localhost | |      . oo       |
2025-12-03 11:06:00.270257 | controller -> localhost | +----[SHA256]-----+
2025-12-03 11:06:00.270303 | controller -> localhost | ok: Runtime: 0:00:00.054526
2025-12-03 11:06:00.276373 | 
2025-12-03 11:06:00.276456 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-03 11:06:00.310050 | controller | ok
2025-12-03 11:06:00.320120 | controller | included: /var/lib/zuul/builds/1ef2272f6752415189f0ab4b3dd34fdf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-03 11:06:00.328610 | 
2025-12-03 11:06:00.328684 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-03 11:06:00.343570 | controller | skipping: Conditional result was False
2025-12-03 11:06:00.349815 | 
2025-12-03 11:06:00.349878 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-03 11:06:01.323633 | controller | changed
2025-12-03 11:06:01.336694 | 
2025-12-03 11:06:01.336869 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-03 11:06:01.753321 | controller | ok
2025-12-03 11:06:01.765595 | 
2025-12-03 11:06:01.765779 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-03 11:06:03.693120 | controller | changed
2025-12-03 11:06:03.698881 | 
2025-12-03 11:06:03.698947 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-03 11:06:05.483919 | controller | changed
2025-12-03 11:06:05.493545 | 
2025-12-03 11:06:05.493665 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-03 11:06:05.520242 | controller | skipping: Conditional result was False
2025-12-03 11:06:05.530593 | 
2025-12-03 11:06:05.530758 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-03 11:06:05.995149 | controller -> localhost | changed
2025-12-03 11:06:06.023691 | 
2025-12-03 11:06:06.023942 | TASK [add-build-sshkey : Add back temp key]
2025-12-03 11:06:06.416803 | controller -> localhost | Identity added: /var/lib/zuul/builds/1ef2272f6752415189f0ab4b3dd34fdf/work/1ef2272f6752415189f0ab4b3dd34fdf_id_rsa (zuul-build-sshkey)
2025-12-03 11:06:06.417040 | controller -> localhost | ok: Runtime: 0:00:00.017496
2025-12-03 11:06:06.425608 | 
2025-12-03 11:06:06.425716 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-03 11:06:06.915633 | controller | ok
2025-12-03 11:06:06.930739 | 
2025-12-03 11:06:06.930959 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-03 11:06:06.958336 | controller | skipping: Conditional result was False
2025-12-03 11:06:06.977404 | 
2025-12-03 11:06:06.977576 | TASK [include_role : validate-host]
2025-12-03 11:06:07.013607 | controller | ok
2025-12-03 11:06:07.045387 | 
2025-12-03 11:06:07.045529 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-03 11:06:07.079441 | controller | ok
2025-12-03 11:06:07.086037 | 
2025-12-03 11:06:07.086151 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-03 11:06:07.368791 | controller -> localhost | ok
2025-12-03 11:06:07.377748 | 
2025-12-03 11:06:07.378709 | TASK [validate-host : Collect information about the host]
2025-12-03 11:06:08.251554 | controller | ok
2025-12-03 11:06:08.278057 | 
2025-12-03 11:06:08.278213 | TASK [validate-host : Sanitize hostname]
2025-12-03 11:06:08.354313 | controller | ok
2025-12-03 11:06:08.363299 | 
2025-12-03 11:06:08.363423 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-03 11:06:08.910263 | controller -> localhost | changed
2025-12-03 11:06:08.916300 | 
2025-12-03 11:06:08.916392 | TASK [validate-host : Collect information about zuul worker]
2025-12-03 11:06:09.436806 | controller | ok
2025-12-03 11:06:09.449812 | 
2025-12-03 11:06:09.450013 | TASK [validate-host : Write out all zuul information for each host]
2025-12-03 11:06:09.984128 | controller -> localhost | changed
2025-12-03 11:06:10.006180 | 
2025-12-03 11:06:10.006311 | TASK [include_role : prepare-workspace-openshift]
2025-12-03 11:06:10.032615 | controller | skipping: Conditional result was False
2025-12-03 11:06:10.044972 | 
2025-12-03 11:06:10.045122 | TASK [include_role : remove-zuul-sshkey]
2025-12-03 11:06:10.072112 | controller | skipping: Conditional result was False
2025-12-03 11:06:10.087486 | 
2025-12-03 11:06:10.087802 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-03 11:06:10.492456 | controller | ok: "logs"
2025-12-03 11:06:10.492917 | controller | ok: All items complete
2025-12-03 11:06:10.492986 | 
2025-12-03 11:06:10.830990 | controller | ok: "artifacts"
2025-12-03 11:06:11.247907 | controller | ok: "docs"
2025-12-03 11:06:11.260965 | 
2025-12-03 11:06:11.261103 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-03 11:06:11.611323 | controller | changed: "logs"
2025-12-03 11:06:11.950925 | controller | changed: "artifacts"
2025-12-03 11:06:12.372929 | controller | changed: "docs"
2025-12-03 11:06:12.401597 | 
2025-12-03 11:06:12.401696 | PLAY RECAP
2025-12-03 11:06:12.401756 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-03 11:06:12.401787 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-03 11:06:12.401806 | 
2025-12-03 11:06:12.507998 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-03 11:06:12.509528 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-03 11:06:13.122015 | 
2025-12-03 11:06:13.122136 | PLAY [all]
2025-12-03 11:06:13.143353 | 
2025-12-03 11:06:13.143448 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-03 11:06:13.226082 | controller | ok
2025-12-03 11:06:13.234907 | 
2025-12-03 11:06:13.235017 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-03 11:06:13.786469 | controller | changed
2025-12-03 11:06:13.801863 | 
2025-12-03 11:06:13.802050 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-03 11:06:15.792852 | controller | changed
2025-12-03 11:06:15.806536 | 
2025-12-03 11:06:15.806647 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-03 11:06:16.455448 | controller | changed:
2025-12-03 11:06:16.455931 | controller | {
2025-12-03 11:06:16.456025 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-03 11:06:16.456102 | controller | }
2025-12-03 11:06:16.848195 | controller | changed:
2025-12-03 11:06:16.848371 | controller | {
2025-12-03 11:06:16.848426 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-03 11:06:16.848633 | controller | }
2025-12-03 11:06:17.277085 | controller | changed:
2025-12-03 11:06:17.277238 | controller | {
2025-12-03 11:06:17.277305 | 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-03 11:06:17.277357 | controller | }
2025-12-03 11:06:17.739249 | controller | changed:
2025-12-03 11:06:17.739358 | controller | {
2025-12-03 11:06:17.739384 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-03 11:06:17.739403 | controller | }
2025-12-03 11:06:18.088245 | controller | changed:
2025-12-03 11:06:18.088350 | controller | {
2025-12-03 11:06:18.088373 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-03 11:06:18.088397 | controller | }
2025-12-03 11:06:18.511146 | controller | changed:
2025-12-03 11:06:18.511270 | controller | {
2025-12-03 11:06:18.511305 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-03 11:06:18.511433 | controller | }
2025-12-03 11:06:18.965490 | controller | changed:
2025-12-03 11:06:18.965759 | controller | {
2025-12-03 11:06:18.965825 | 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-03 11:06:18.965869 | controller | }
2025-12-03 11:06:19.345819 | controller | changed:
2025-12-03 11:06:19.346031 | controller | {
2025-12-03 11:06:19.346250 | 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-03 11:06:19.346275 | controller | }
2025-12-03 11:06:19.793115 | controller | changed:
2025-12-03 11:06:19.793235 | controller | {
2025-12-03 11:06:19.793259 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-03 11:06:19.793277 | controller | }
2025-12-03 11:06:20.212292 | controller | changed:
2025-12-03 11:06:20.212395 | controller | {
2025-12-03 11:06:20.212419 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-03 11:06:20.212437 | controller | }
2025-12-03 11:06:20.627359 | controller | changed:
2025-12-03 11:06:20.627507 | controller | {
2025-12-03 11:06:20.627556 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-03 11:06:20.627595 | controller | }
2025-12-03 11:06:21.141998 | controller | changed:
2025-12-03 11:06:21.142146 | controller | {
2025-12-03 11:06:21.142195 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-03 11:06:21.142234 | controller | }
2025-12-03 11:06:21.589581 | controller | changed:
2025-12-03 11:06:21.589772 | controller | {
2025-12-03 11:06:21.589835 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-03 11:06:21.589880 | controller | }
2025-12-03 11:06:21.941040 | controller | changed:
2025-12-03 11:06:21.941287 | controller | {
2025-12-03 11:06:21.941346 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-03 11:06:21.941387 | controller | }
2025-12-03 11:06:22.354070 | controller | changed:
2025-12-03 11:06:22.354230 | controller | {
2025-12-03 11:06:22.354282 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-03 11:06:22.354323 | controller | }
2025-12-03 11:06:22.729852 | controller | changed:
2025-12-03 11:06:22.729957 | controller | {
2025-12-03 11:06:22.729979 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-03 11:06:22.729996 | controller | }
2025-12-03 11:06:23.183517 | controller | changed:
2025-12-03 11:06:23.183671 | controller | {
2025-12-03 11:06:23.183760 | 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-03 11:06:23.183817 | controller | }
2025-12-03 11:06:23.683837 | controller | changed:
2025-12-03 11:06:23.683986 | controller | {
2025-12-03 11:06:23.684047 | 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-03 11:06:23.684314 | controller | }
2025-12-03 11:06:24.192252 | controller | changed:
2025-12-03 11:06:24.192394 | controller | {
2025-12-03 11:06:24.192445 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-03 11:06:24.192485 | controller | }
2025-12-03 11:06:24.672474 | controller | changed:
2025-12-03 11:06:24.672613 | controller | {
2025-12-03 11:06:24.672666 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-03 11:06:24.672710 | controller | }
2025-12-03 11:06:25.113195 | controller | changed:
2025-12-03 11:06:25.113473 | controller | {
2025-12-03 11:06:25.113535 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-03 11:06:25.113592 | controller | }
2025-12-03 11:06:25.537820 | controller | changed:
2025-12-03 11:06:25.537916 | controller | {
2025-12-03 11:06:25.537944 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-03 11:06:25.537966 | controller | }
2025-12-03 11:06:25.998425 | controller | changed:
2025-12-03 11:06:25.998567 | controller | {
2025-12-03 11:06:25.998614 | 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-03 11:06:25.998655 | controller | }
2025-12-03 11:06:26.388886 | controller | changed:
2025-12-03 11:06:26.389053 | controller | {
2025-12-03 11:06:26.389120 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-03 11:06:26.389175 | controller | }
2025-12-03 11:06:27.775523 | controller | changed:
2025-12-03 11:06:27.775660 | controller | {
2025-12-03 11:06:27.775710 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-03 11:06:27.775783 | controller | }
2025-12-03 11:06:28.372149 | controller | changed:
2025-12-03 11:06:28.372287 | controller | {
2025-12-03 11:06:28.372335 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-03 11:06:28.372373 | controller | }
2025-12-03 11:06:28.417009 | 
2025-12-03 11:06:28.417226 | TASK [Set timezone to UTC]
2025-12-03 11:06:29.171032 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-03 11:06:29.182883 | 
2025-12-03 11:06:29.183144 | TASK [Create nodepool directory]
2025-12-03 11:06:29.601469 | controller | changed
2025-12-03 11:06:29.616301 | 
2025-12-03 11:06:29.616486 | TASK [Create nodepool sub_nodes file]
2025-12-03 11:06:31.507840 | controller | changed
2025-12-03 11:06:31.515053 | 
2025-12-03 11:06:31.515185 | TASK [Create nodepool sub_nodes_private file]
2025-12-03 11:06:33.226847 | controller | changed
2025-12-03 11:06:33.244184 | 
2025-12-03 11:06:33.244409 | LOOP [Populate nodepool sub_nodes file]
2025-12-03 11:06:33.289668 | 
2025-12-03 11:06:33.289883 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-03 11:06:33.336515 | 
2025-12-03 11:06:33.336720 | TASK [Create nodepool primary file]
2025-12-03 11:06:33.372233 | controller | skipping: Conditional result was False
2025-12-03 11:06:33.397509 | 
2025-12-03 11:06:33.397849 | TASK [Create nodepool node_private for this node]
2025-12-03 11:06:35.256083 | controller | changed
2025-12-03 11:06:35.276360 | 
2025-12-03 11:06:35.276516 | LOOP [Copy ssh keys to nodepool directory]
2025-12-03 11:06:35.865033 | controller | ok: Item: id_rsa Runtime: 0:00:00.009285
2025-12-03 11:06:35.865371 | 
2025-12-03 11:06:36.295125 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005531
2025-12-03 11:06:36.305223 | 
2025-12-03 11:06:36.305351 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-03 11:06:38.438020 | controller | changed
2025-12-03 11:06:38.444760 | 
2025-12-03 11:06:38.444853 | TASK [Validate sudoers config after edits]
2025-12-03 11:06:38.965806 | controller | /etc/sudoers: parsed OK
2025-12-03 11:06:38.965928 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-03 11:06:38.965938 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-03 11:06:38.965944 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-03 11:06:39.008656 | controller | ok: Runtime: 0:00:00.009748
2025-12-03 11:06:39.014642 | 
2025-12-03 11:06:39.014714 | TASK [Show the environment passed in to job shell scripts]
2025-12-03 11:06:39.413761 | controller | SHELL=/bin/bash
2025-12-03 11:06:39.413886 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-03 11:06:39.413902 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-03 11:06:39.413912 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/00/3500/5eecc235ed5b8144169e3521f012be466a7f779f
2025-12-03 11:06:39.413921 | controller | PWD=/home/zuul
2025-12-03 11:06:39.413930 | controller | ZUUL_PIPELINE=github-check
2025-12-03 11:06:39.413939 | controller | LOGNAME=zuul
2025-12-03 11:06:39.413948 | controller | XDG_SESSION_TYPE=tty
2025-12-03 11:06:39.413957 | controller | _=/usr/bin/env
2025-12-03 11:06:39.413966 | controller | MOTD_SHOWN=pam
2025-12-03 11:06:39.413975 | controller | HOME=/home/zuul
2025-12-03 11:06:39.413983 | controller | LANG=en_US.UTF-8
2025-12-03 11:06:39.413992 | controller | SSH_CONNECTION=38.102.83.114 42384 38.102.83.243 22
2025-12-03 11:06:39.414001 | 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-03 11:06:39.414015 | controller | ZUUL_CHANGE_IDS=3500,5eecc235ed5b8144169e3521f012be466a7f779f
2025-12-03 11:06:39.414024 | controller | WORKSPACE=/home/zuul/workspace
2025-12-03 11:06:39.414034 | controller | XDG_SESSION_CLASS=user
2025-12-03 11:06:39.414043 | controller | SELINUX_ROLE_REQUESTED=
2025-12-03 11:06:39.414051 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-03 11:06:39.414063 | controller | USER=zuul
2025-12-03 11:06:39.414072 | controller | ZUUL_VOTING=True
2025-12-03 11:06:39.414081 | controller | BUILD_TIMEOUT=10800000
2025-12-03 11:06:39.414089 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-03 11:06:39.414098 | controller | SHLVL=1
2025-12-03 11:06:39.414122 | controller | ZUUL_PATCHSET=5eecc235ed5b8144169e3521f012be466a7f779f
2025-12-03 11:06:39.414138 | controller | XDG_SESSION_ID=1
2025-12-03 11:06:39.414200 | controller | ZUUL_BRANCH=main
2025-12-03 11:06:39.414212 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-03 11:06:39.414222 | controller | SSH_CLIENT=38.102.83.114 42384 22
2025-12-03 11:06:39.414231 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-03 11:06:39.414240 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-03 11:06:39.414249 | controller | which_declare=declare -f
2025-12-03 11:06:39.414259 | 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-03 11:06:39.414268 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-03 11:06:39.414277 | controller | ZUUL_CHANGE=3500
2025-12-03 11:06:39.414285 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-03 11:06:39.414294 | controller | ZUUL_UUID=1ef2272f6752415189f0ab4b3dd34fdf
2025-12-03 11:06:39.414303 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-03 11:06:39.414312 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-03 11:06:39.414321 | controller | }
2025-12-03 11:06:39.550123 | controller | ok: Runtime: 0:00:00.012542
2025-12-03 11:06:39.557508 | 
2025-12-03 11:06:39.557625 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-03 11:06:39.572903 | controller | skipping: Conditional result was False
2025-12-03 11:06:39.587814 | 
2025-12-03 11:06:39.588005 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-03 11:06:40.146556 | controller | skipping: Conditional result was False
2025-12-03 11:06:40.158896 | 
2025-12-03 11:06:40.159031 | TASK [Ensure legacy workspace directory]
2025-12-03 11:06:40.742698 | controller | changed
2025-12-03 11:06:40.784304 | 
2025-12-03 11:06:40.784458 | PLAY RECAP
2025-12-03 11:06:40.784522 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-03 11:06:40.784551 | 
2025-12-03 11:06:40.892170 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-03 11:06:40.895455 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-12-03 11:06:41.515059 | 
2025-12-03 11:06:41.515241 | PLAY [all,!compute]
2025-12-03 11:06:41.536149 | 
2025-12-03 11:06:41.536232 | TASK [Enable container-tools rhel-modules for c8/crc node]
2025-12-03 11:06:42.066874 | controller | skipping: Conditional result was False
2025-12-03 11:06:42.080794 | 
2025-12-03 11:06:42.080967 | TASK [Install podman]
2025-12-03 11:07:01.801000 | controller | changed
2025-12-03 11:07:01.814823 | 
2025-12-03 11:07:01.815226 | TASK [Set var name for quay login zuul secret]
2025-12-03 11:07:01.885265 | controller | skipping: Conditional result was False
2025-12-03 11:07:01.897855 | 
2025-12-03 11:07:01.898032 | TASK [Print the username]
2025-12-03 11:07:02.551056 | controller | openstack-k8s-operators+cirobot
2025-12-03 11:07:02.979496 | controller | ok: Runtime: 0:00:00.006900
2025-12-03 11:07:02.987899 | 
2025-12-03 11:07:02.987986 | TASK [Fail when user or password is not set]
2025-12-03 11:07:03.045349 | controller | skipping: Conditional result was False
2025-12-03 11:07:03.059367 | 
2025-12-03 11:07:03.059573 | TASK [Set vars for quay login]
2025-12-03 11:07:03.128864 | controller | Output suppressed because no_log was given
2025-12-03 11:07:03.140779 | 
2025-12-03 11:07:03.140932 | LOOP [Perform container registry login(s) with podman]
2025-12-03 11:07:04.298333 | controller | Output suppressed because no_log was given
2025-12-03 11:07:04.312148 | 
2025-12-03 11:07:04.312335 | LOOP [Perform container registry login(s) with buildah]
2025-12-03 11:07:04.391696 | controller | Output suppressed because no_log was given
2025-12-03 11:07:04.415385 | 
2025-12-03 11:07:04.415632 | TASK [Set Insecure registry for content provider]
2025-12-03 11:07:05.010368 | controller | changed: Block inserted
2025-12-03 11:07:05.051011 | 
2025-12-03 11:07:05.051129 | PLAY RECAP
2025-12-03 11:07:05.051182 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0
2025-12-03 11:07:05.051212 | 
2025-12-03 11:07:05.154616 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-12-03 11:07:05.157539 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-12-03 11:07:05.761434 | 
2025-12-03 11:07:05.761549 | PLAY [Prefer ipv4 over ipv6]
2025-12-03 11:07:05.781701 | 
2025-12-03 11:07:05.781822 | TASK [Configure /etc/gai.conf to prever ipv4 over ipv6]
2025-12-03 11:07:05.817224 | controller | skipping: Conditional result was False
2025-12-03 11:07:05.838100 | 
2025-12-03 11:07:05.838207 | PLAY [controller]
2025-12-03 11:07:05.853897 | 
2025-12-03 11:07:05.854008 | TASK [Fail when openshift_pull_sec not provided]
2025-12-03 11:07:05.877772 | controller | skipping: Conditional result was False
2025-12-03 11:07:05.883747 | 
2025-12-03 11:07:05.883820 | TASK [Clone repos in the job workspace]
2025-12-03 11:07:05.902843 | controller | ok
2025-12-03 11:07:05.919244 | 
2025-12-03 11:07:05.919313 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-03 11:07:06.436072 | controller | ok
2025-12-03 11:07:06.449130 | 
2025-12-03 11:07:06.449263 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-03 11:07:12.037343 | controller | Output suppressed because no_log was given
2025-12-03 11:07:12.052667 | 
2025-12-03 11:07:12.052797 | TASK [Create pull-secret.txt file]
2025-12-03 11:07:14.319982 | controller | Output suppressed because no_log was given
2025-12-03 11:07:14.331528 | 
2025-12-03 11:07:14.331661 | TASK [Apply CRC features and print image build date]
2025-12-03 11:07:14.360568 | controller | ok
2025-12-03 11:07:14.375773 | controller | included: /var/lib/zuul/builds/1ef2272f6752415189f0ab4b3dd34fdf/trusted/project_0/review.rdoproject.org/config/playbooks/crc/features.yaml
2025-12-03 11:07:14.387333 | 
2025-12-03 11:07:14.387426 | TASK [Set additional parameters for crc - telemetry]
2025-12-03 11:07:14.928353 | controller | skipping: Conditional result was False
2025-12-03 11:07:14.943511 | 
2025-12-03 11:07:14.943665 | TASK [Set additional parameters for crc - monitoring]
2025-12-03 11:07:15.493277 | controller | skipping: Conditional result was False
2025-12-03 11:07:15.501910 | 
2025-12-03 11:07:15.501995 | TASK [Set minimum required memory to run the CRC when monitoring enabled]
2025-12-03 11:07:16.037814 | controller | skipping: Conditional result was False
2025-12-03 11:07:16.043767 | 
2025-12-03 11:07:16.043844 | TASK [Set additional parameters for crc - enable microshift preset]
2025-12-03 11:07:16.576380 | controller | skipping: Conditional result was False
2025-12-03 11:07:16.589373 | 
2025-12-03 11:07:16.589528 | TASK [Set additional parameters for crc - enable okd preset]
2025-12-03 11:07:17.143600 | controller | skipping: Conditional result was False
2025-12-03 11:07:17.151598 | 
2025-12-03 11:07:17.151694 | TASK [Delete the cluster if there's a non-default bundle URL]
2025-12-03 11:07:17.692306 | controller | skipping: Conditional result was False
2025-12-03 11:07:17.699851 | 
2025-12-03 11:07:17.699938 | TASK [Execute crc start command]
2025-12-03 11:18:32.050865 | controller | ok: Runtime: 0:11:13.755539
2025-12-03 11:18:32.065234 | 
2025-12-03 11:18:32.065411 | TASK [Verify that OpenShift is up and running]
2025-12-03 11:18:32.097315 | controller | ok
2025-12-03 11:18:32.115921 | controller | included: /var/lib/zuul/builds/1ef2272f6752415189f0ab4b3dd34fdf/trusted/project_0/review.rdoproject.org/config/playbooks/crc/verify_openshift_start.yaml
2025-12-03 11:18:32.127428 | 
2025-12-03 11:18:32.127572 | TASK [Create script to login and verify services]
2025-12-03 11:18:34.149916 | controller | changed
2025-12-03 11:18:34.165504 | 
2025-12-03 11:18:34.165751 | TASK [Ensure that user is logged]
2025-12-03 11:18:34.601193 | controller | Checking login to the cluster 1...
2025-12-03 11:18:35.163656 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-03 11:18:35.468750 | controller |
2025-12-03 11:18:35.468948 | controller | Login successful.
2025-12-03 11:18:35.496458 | controller |
2025-12-03 11:18:35.496624 | controller | You have access to 65 projects, the list has been suppressed. You can list all projects with 'oc projects'
2025-12-03 11:18:35.530755 | controller |
2025-12-03 11:18:35.531007 | controller | Using project "default".
2025-12-03 11:18:35.729238 | controller | changed
2025-12-03 11:18:35.735281 | 
2025-12-03 11:18:35.735354 | TASK [Delete all openshift-marketplace pods]
2025-12-03 11:18:42.272980 | controller | pod "certified-operators-mz9qw" deleted
2025-12-03 11:18:53.520049 | controller | pod "community-operators-rl9mj" deleted
2025-12-03 11:18:54.400847 | controller | pod "marketplace-operator-79b997595-bv56r" deleted
2025-12-03 11:18:56.789235 | controller | pod "redhat-marketplace-ww44x" deleted
2025-12-03 11:18:56.813765 | controller | pod "redhat-operators-x9gsj" deleted
2025-12-03 11:18:58.331701 | controller | ok
2025-12-03 11:18:58.337946 | 
2025-12-03 11:18:58.338015 | TASK [Copy script ensure_services_up.sh]
2025-12-03 11:18:58.402907 | controller | skipping: Conditional result was False
2025-12-03 11:18:58.409963 | 
2025-12-03 11:18:58.410042 | TASK [Ensure all pods are up and running]
2025-12-03 11:18:58.943259 | controller | skipping: Conditional result was False
2025-12-03 11:18:58.951144 | 
2025-12-03 11:18:58.951260 | TASK [Check if there is a image build date file]
2025-12-03 11:18:59.443837 | controller | ok
2025-12-03 11:18:59.456154 | 
2025-12-03 11:18:59.456310 | TASK [Print image date if available]
2025-12-03 11:18:59.934669 | controller | Mon Nov 24 08:30:59 AM EST 2025
2025-12-03 11:19:00.002398 | controller | changed
2025-12-03 11:19:00.016033 | 
2025-12-03 11:19:00.016432 | TASK [Fix machineconfigpool]
2025-12-03 11:19:00.085579 | controller | skipping: Conditional result was False
2025-12-03 11:19:00.099490 | 
2025-12-03 11:19:00.099679 | TASK [Show available nodes]
2025-12-03 11:19:01.231451 | controller | NAME   STATUS   ROLES                         AGE    VERSION
2025-12-03 11:19:01.234647 | controller | crc    Ready    control-plane,master,worker   283d   v1.31.5
2025-12-03 11:19:01.694287 | controller | ok: Runtime: 0:00:00.499953
2025-12-03 11:19:01.707373 | 
2025-12-03 11:19:01.707516 | TASK [Configure insecure registry inside crc vm]
2025-12-03 11:19:01.759392 | controller | ok
2025-12-03 11:19:01.780165 | controller | included: /var/lib/zuul/builds/1ef2272f6752415189f0ab4b3dd34fdf/trusted/project_0/review.rdoproject.org/config/playbooks/crc/crc_insecure_registry.yaml
2025-12-03 11:19:01.799205 | 
2025-12-03 11:19:01.799315 | TASK [Catch CRC IP]
2025-12-03 11:19:02.508872 | controller | 192.168.130.11
2025-12-03 11:19:02.844823 | controller | changed
2025-12-03 11:19:02.851120 | 
2025-12-03 11:19:02.851248 | TASK [Patch the image.config.openshift.io resource to include insecure registry]
2025-12-03 11:19:03.481665 | controller | image.config.openshift.io/cluster patched
2025-12-03 11:19:03.887376 | controller | changed
2025-12-03 11:19:03.899198 | 
2025-12-03 11:19:03.899342 | TASK [Check if id_ed25519 key exists]
2025-12-03 11:19:04.279767 | controller | ok
2025-12-03 11:19:04.291866 | 
2025-12-03 11:19:04.292004 | TASK [Set fact if new keypair exists]
2025-12-03 11:19:04.331903 | controller | ok
2025-12-03 11:19:04.342655 | 
2025-12-03 11:19:04.342809 | TASK [update the /etc/containers/registries.conf in crc vm]
2025-12-03 11:19:05.949892 | controller |
2025-12-03 11:19:14.488280 | controller | PLAY [Set insecure registry with in crc vm] ************************************
2025-12-03 11:19:14.488436 | controller |
2025-12-03 11:19:14.488448 | controller | TASK [Gathering Facts] *********************************************************
2025-12-03 11:19:14.488471 | controller | [WARNING]: Platform linux on host 192.168.130.11 is using the discovered Python
2025-12-03 11:19:14.554408 | controller | interpreter at /usr/bin/python3.9, but future installation of another Python
2025-12-03 11:19:14.554519 | controller | interpreter could change the meaning of that path. See
2025-12-03 11:19:14.554534 | controller | https://docs.ansible.com/ansible-
2025-12-03 11:19:14.554543 | controller | core/2.14/reference_appendices/interpreter_discovery.html for more information.
2025-12-03 11:19:14.554567 | controller | ok: [192.168.130.11]
2025-12-03 11:19:15.773294 | controller |
2025-12-03 11:19:15.773388 | controller | TASK [Set Insecure registry for content provider] ******************************
2025-12-03 11:19:15.773411 | controller | changed: [192.168.130.11]
2025-12-03 11:19:24.815896 | controller |
2025-12-03 11:19:24.815982 | controller | TASK [Restart crio] ************************************************************
2025-12-03 11:19:24.816002 | controller | changed: [192.168.130.11]
2025-12-03 11:19:27.815722 | controller |
2025-12-03 11:19:27.816188 | controller | TASK [Restart kubelet] *********************************************************
2025-12-03 11:19:27.816233 | controller | changed: [192.168.130.11]
2025-12-03 11:19:27.880915 | controller |
2025-12-03 11:19:27.881004 | controller | PLAY RECAP *********************************************************************
2025-12-03 11:19:27.881014 | controller | 192.168.130.11             : ok=4    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-12-03 11:19:27.881022 | controller |
2025-12-03 11:19:27.995667 | controller | changed
2025-12-03 11:19:28.008776 | 
2025-12-03 11:19:28.008922 | TASK [Catch CRC IP]
2025-12-03 11:19:28.626851 | controller | 192.168.130.11
2025-12-03 11:19:29.063718 | controller | changed
2025-12-03 11:19:29.078159 | 
2025-12-03 11:19:29.078299 | TASK [Check if id_ed25519 key exists]
2025-12-03 11:19:29.454405 | controller | ok
2025-12-03 11:19:29.468343 | 
2025-12-03 11:19:29.468488 | TASK [Set fact if new keypair exists]
2025-12-03 11:19:29.519571 | controller | ok
2025-12-03 11:19:29.531806 | 
2025-12-03 11:19:29.531939 | TASK [Run crc]
2025-12-03 11:19:30.505872 | controller |
2025-12-03 11:19:34.973698 | controller | PLAY [OpenShift secret copy and CRC securitization] ****************************
2025-12-03 11:19:34.973853 | controller |
2025-12-03 11:19:34.973870 | controller | TASK [Gathering Facts] *********************************************************
2025-12-03 11:19:34.973897 | controller | [WARNING]: Platform linux on host 192.168.130.11 is using the discovered Python
2025-12-03 11:19:35.034791 | controller | interpreter at /usr/bin/python3.9, but future installation of another Python
2025-12-03 11:19:35.034853 | controller | interpreter could change the meaning of that path. See
2025-12-03 11:19:35.034864 | controller | https://docs.ansible.com/ansible-
2025-12-03 11:19:35.034873 | controller | core/2.14/reference_appendices/interpreter_discovery.html for more information.
2025-12-03 11:19:35.034889 | controller | ok: [192.168.130.11]
2025-12-03 11:19:37.193064 | controller |
2025-12-03 11:19:37.193172 | controller | TASK [Copy pull-secret] ********************************************************
2025-12-03 11:19:37.193191 | controller | changed: [192.168.130.11]
2025-12-03 11:19:39.022082 | controller |
2025-12-03 11:19:39.022606 | controller | TASK [Add crio config] *********************************************************
2025-12-03 11:19:39.022639 | controller | changed: [192.168.130.11]
2025-12-03 11:19:42.934876 | controller |
2025-12-03 11:19:42.934978 | controller | TASK [Restart crio to pickup new configuration] ********************************
2025-12-03 11:19:42.935016 | controller | changed: [192.168.130.11]
2025-12-03 11:19:43.006511 | controller |
2025-12-03 11:19:43.006676 | controller | PLAY RECAP *********************************************************************
2025-12-03 11:19:43.006689 | controller | 192.168.130.11             : ok=4    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-12-03 11:19:43.006697 | controller |
2025-12-03 11:19:43.100256 | controller | changed
2025-12-03 11:19:43.108489 | 
2025-12-03 11:19:43.108572 | TASK [Set slower etcd profile]
2025-12-03 11:19:43.218587 | controller | ok
2025-12-03 11:19:43.274056 | 
2025-12-03 11:19:43.274169 | TASK [prepare-crc-cloud : Set slower profile]
2025-12-03 11:19:44.057367 | controller | etcd.operator.openshift.io/cluster patched
2025-12-03 11:19:44.323065 | controller | ok
2025-12-03 11:19:44.337909 | 
2025-12-03 11:19:44.338050 | TASK [prepare-crc-cloud : Wait 30 seconds to apply]
2025-12-03 11:20:14.385785 | controller | ok
2025-12-03 11:20:14.398799 | 
2025-12-03 11:20:14.398987 | TASK [prepare-crc-cloud : Get pod phase status]
2025-12-03 11:26:50.499522 | controller | ok
2025-12-03 11:26:50.522389 | 
2025-12-03 11:26:50.522506 | TASK [Set limit for disk usage - IOPS and Read/Write]
2025-12-03 11:26:50.597178 | controller | ok
2025-12-03 11:26:50.607234 | controller | included: /var/lib/zuul/builds/1ef2272f6752415189f0ab4b3dd34fdf/trusted/project_0/review.rdoproject.org/config/playbooks/crc/limit-disk-usage.yaml
2025-12-03 11:26:50.623585 | 
2025-12-03 11:26:50.623713 | TASK [Set facts for disk limitation]
2025-12-03 11:26:50.656928 | controller | ok
2025-12-03 11:26:50.665111 | 
2025-12-03 11:26:50.665207 | TASK [Print limits for the disk]
2025-12-03 11:26:50.698966 | controller | ok: Set limit to crc VM to the vda disk: iops 18000 read/write 262144000
2025-12-03 11:26:50.707560 | 
2025-12-03 11:26:50.707684 | TASK [Set limit to crc VM to the vda disk]
2025-12-03 11:26:51.320406 | controller |
2025-12-03 11:26:51.775374 | controller | changed
2025-12-03 11:26:51.787828 | 
2025-12-03 11:26:51.787963 | TASK [Pause machineconfigpool]
2025-12-03 11:26:51.878630 | controller | ok
2025-12-03 11:26:51.927822 | 
2025-12-03 11:26:51.928059 | LOOP [prepare-crc-cloud : Pause the machineconfig configuration if mcp is not needed]
2025-12-03 11:26:52.752023 | controller | ok
2025-12-03 11:26:53.269566 | controller | ok
2025-12-03 11:26:53.284956 | 
2025-12-03 11:26:53.285059 | TASK [prepare-crc-cloud : Print current machineconfigpool]
2025-12-03 11:26:53.967457 | controller | NAME     CONFIG                                             UPDATED   UPDATING   DEGRADED   MACHINECOUNT   READYMACHINECOUNT   UPDATEDMACHINECOUNT   DEGRADEDMACHINECOUNT   AGE
2025-12-03 11:26:53.973244 | controller | master   rendered-master-63af5c6b4fa17c607a1f2008d8a3ac33   False     True       True       1              0                   0                     1                      283d
2025-12-03 11:26:53.973378 | controller | worker   rendered-worker-a5ca299799c681e19160925763acea14   True      False      False      0              0                   0                     0                      283d
2025-12-03 11:26:54.353380 | controller | ok
2025-12-03 11:26:54.410606 | 
2025-12-03 11:26:54.410711 | PLAY [Set IOPS and Read/Write disk limitation excluding controller]
2025-12-03 11:26:54.410837 | 
2025-12-03 11:26:54.410865 | PLAY RECAP
2025-12-03 11:26:54.410902 | controller | ok: 31 changed: 13 unreachable: 0 failed: 0 skipped: 11 rescued: 0 ignored: 0
2025-12-03 11:26:54.410922 | 
2025-12-03 11:26:54.568535 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-12-03 11:26:54.569844 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-prepare.yml@main]
2025-12-03 11:26:55.241837 | 
2025-12-03 11:26:55.241955 | PLAY [Run ci/playbooks/e2e-prepare.yml]
2025-12-03 11:26:55.272122 | 
2025-12-03 11:26:55.272273 | TASK [Clone repos in the job workspace]
2025-12-03 11:26:55.292123 | controller | ok
2025-12-03 11:26:55.310017 | 
2025-12-03 11:26:55.310148 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-03 11:26:55.919643 | controller | ok
2025-12-03 11:26:55.928555 | 
2025-12-03 11:26:55.928668 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-03 11:26:57.354539 | controller | Output suppressed because no_log was given
2025-12-03 11:26:57.375370 | 
2025-12-03 11:26:57.375485 | TASK [Create zuul-output directory]
2025-12-03 11:26:57.952087 | controller | ok
2025-12-03 11:26:57.963304 | 
2025-12-03 11:26:57.963437 | TASK [Install required packages]
2025-12-03 11:26:59.571847 | controller | ok: Nothing to do
2025-12-03 11:26:59.586267 | 
2025-12-03 11:26:59.586415 | TASK [Output pip related things]
2025-12-03 11:27:01.188367 | controller | pip 21.3.1 from /usr/lib/python3.9/site-packages/pip (python 3.9)
2025-12-03 11:27:01.646316 | controller | changed
2025-12-03 11:27:01.652231 | 
2025-12-03 11:27:01.652299 | TASK [Install requirements]
2025-12-03 11:29:58.204838 | controller | changed
2025-12-03 11:29:58.230980 | 
2025-12-03 11:29:58.231133 | TASK [Add cronjob to trigger job stats]
2025-12-03 11:29:58.277472 | controller | skipping: Conditional result was False
2025-12-03 11:29:58.288259 | 
2025-12-03 11:29:58.288411 | LOOP [Construct project change list]
2025-12-03 11:29:58.362330 | controller | ok:
2025-12-03 11:29:58.362537 | controller | {
2025-12-03 11:29:58.362574 | controller |   "branch": "main",
2025-12-03 11:29:58.362601 | controller |   "change": "3500",
2025-12-03 11:29:58.362627 | controller |   "change_message": "[hooks] Add install-openstack-lightspeed hook\n\n## Summary\r\n\r\nThis PR adds a hook to install OpenStack Lightspeed operators on CRC-based CI Framework deployments.\r\n\r\n## Background\r\n\r\nOpenStack Lightspeed operators are not deployed by default in CRC-based deployments. This hook enables teams to add OpenStack Lightspeed capabilities to their CI Framework deployments.\r\n\r\nThe hook deploys two operators sequentially:\r\n1. OpenShift Lightspeed (foundation operator from Red Hat catalog)\r\n2. OpenStack Lightspeed (custom catalog source)\r\n\r\nFor each operator, the hook:\r\n\r\n- Creates the required namespace\r\n- Configures OperatorGroup with target namespace\r\n- Creates Subscription resource\r\n- Waits for operator deployment to be ready (30 retries x 10s)\r\n\r\nAdditionally, for OpenShift Lightspeed, it configures the service-ca bundle required for metrics collection.\r\n\r\nThe hook is configurable via cifmw_* variables for namespace names, catalog images, and kubeconfig path, defaulting to standard CRC paths.\r\n\r\nTested with successful deployments on CRC clusters.\r\n\r\n## Intent\r\n\r\n  This is primarily for our internal team use. We're submitting to:\r\n  - Confirm we're following CI Framework hook standards\r\n  - Share with the community in case others find it useful\r\n  - Get feedback on our approach\r\n",
2025-12-03 11:29:58.362659 | controller |   "change_url": "https://github.com/openstack-k8s-operators/ci-framework/pull/3500",
2025-12-03 11:29:58.362686 | controller |   "commit_id": "5eecc235ed5b8144169e3521f012be466a7f779f",
2025-12-03 11:29:58.362710 | controller |   "patchset": "5eecc235ed5b8144169e3521f012be466a7f779f",
2025-12-03 11:29:58.362782 | controller |   "project": {
2025-12-03 11:29:58.362808 | controller |     "canonical_hostname": "github.com",
2025-12-03 11:29:58.362832 | controller |     "canonical_name": "github.com/openstack-k8s-operators/ci-framework",
2025-12-03 11:29:58.362856 | controller |     "name": "openstack-k8s-operators/ci-framework",
2025-12-03 11:29:58.362879 | controller |     "short_name": "ci-framework",
2025-12-03 11:29:58.362902 | controller |     "src_dir": "src/github.com/openstack-k8s-operators/ci-framework"
2025-12-03 11:29:58.362926 | controller |   },
2025-12-03 11:29:58.362950 | controller |   "topic": null
2025-12-03 11:29:58.362974 | controller | }
2025-12-03 11:29:58.427863 | 
2025-12-03 11:29:58.427976 | PLAY [Run ci/playbooks/build_runner_image.yml]
2025-12-03 11:29:58.444710 | 
2025-12-03 11:29:58.444843 | TASK [Filter out host if needed]
2025-12-03 11:29:58.455897 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-03 11:29:58.461041 | 
2025-12-03 11:29:58.461143 | TASK [Get git tag for image tagging]
2025-12-03 11:29:58.532203 | controller | skipping: Conditional result was False
2025-12-03 11:29:58.538930 | 
2025-12-03 11:29:58.539002 | TASK [Set openstack-runner image]
2025-12-03 11:29:58.563352 | controller | skipping: Conditional result was False
2025-12-03 11:29:58.569611 | 
2025-12-03 11:29:58.569693 | TASK [Build openstack-runner image]
2025-12-03 11:29:58.594215 | controller | skipping: Conditional result was False
2025-12-03 11:29:58.601396 | 
2025-12-03 11:29:58.601486 | TASK [Push openstack-runner image]
2025-12-03 11:29:58.625753 | controller | skipping: Conditional result was False
2025-12-03 11:29:58.632099 | 
2025-12-03 11:29:58.632192 | TASK [Make sure ci-framework directory exists]
2025-12-03 11:29:58.657478 | controller | skipping: Conditional result was False
2025-12-03 11:29:58.670423 | 
2025-12-03 11:29:58.670558 | TASK [Create EDPM ansible var file]
2025-12-03 11:29:58.706413 | controller | skipping: Conditional result was False
2025-12-03 11:29:58.737403 | 
2025-12-03 11:29:58.737524 | PLAY RECAP
2025-12-03 11:29:58.737582 | controller | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0
2025-12-03 11:29:58.737622 | 
2025-12-03 11:29:58.858247 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-prepare.yml@main]
2025-12-03 11:29:58.859502 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-03 11:29:59.464314 | 
2025-12-03 11:29:59.464434 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-03 11:29:59.488850 | 
2025-12-03 11:29:59.488982 | TASK [Create zuul-output directory]
2025-12-03 11:30:01.060656 | controller | changed
2025-12-03 11:30:01.070284 | 
2025-12-03 11:30:01.070415 | TASK [Slurp Zuul inventory test]
2025-12-03 11:30:01.494793 | controller -> localhost | ok
2025-12-03 11:30:01.502127 | 
2025-12-03 11:30:01.502223 | TASK [Save zuul inventory]
2025-12-03 11:30:05.311123 | controller | changed
2025-12-03 11:30:05.316398 | 
2025-12-03 11:30:05.316463 | TASK [Save zuul vars without the change_message]
2025-12-03 11:30:08.658651 | controller | changed
2025-12-03 11:30:08.703754 | 
2025-12-03 11:30:08.703882 | PLAY RECAP
2025-12-03 11:30:08.703945 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-03 11:30:08.703978 | 
2025-12-03 11:30:08.820714 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-03 11:30:08.823798 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/edpm_baremetal_deployment/run.yml@main]
2025-12-03 11:30:09.545962 | 
2025-12-03 11:30:09.546081 | PLAY [Run ci/playbooks/edpm_baremetal_deployment/run.yml]
2025-12-03 11:30:09.568667 | 
2025-12-03 11:30:09.568807 | TASK [Filter out host if needed]
2025-12-03 11:30:09.578345 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-03 11:30:09.583190 | 
2025-12-03 11:30:09.583357 | TASK [Check for edpm-ansible.yml file]
2025-12-03 11:30:10.897294 | controller | ok
2025-12-03 11:30:10.912592 | 
2025-12-03 11:30:10.912805 | TASK [Check if new ssh keypair exists]
2025-12-03 11:30:10.945517 | controller | ok
2025-12-03 11:30:10.976458 | 
2025-12-03 11:30:10.976587 | TASK [recognize_ssh_keypair : Check if id_ed25519 key exists]
2025-12-03 11:30:12.366370 | controller | ok
2025-12-03 11:30:12.379776 | 
2025-12-03 11:30:12.379915 | TASK [recognize_ssh_keypair : Set fact if new keypair exists]
2025-12-03 11:30:12.427676 | controller | ok
2025-12-03 11:30:12.439720 | 
2025-12-03 11:30:12.439927 | TASK [recognize_ssh_keypair : Set fact if new keypair does not exists]
2025-12-03 11:30:12.466633 | controller | skipping: Conditional result was False
2025-12-03 11:30:12.491517 | 
2025-12-03 11:30:12.491665 | TASK [Add crc node in local inventory]
2025-12-03 11:30:12.535938 | controller | changed
2025-12-03 11:30:12.544147 | 
2025-12-03 11:30:12.544259 | LOOP [Ensure we know ssh hosts]
2025-12-03 11:30:13.694178 | controller | changed
2025-12-03 11:30:21.179216 | controller | ERROR: Item: crc
2025-12-03 11:30:21.179365 | controller | {
2025-12-03 11:30:21.179393 | controller |   "ansible_loop_var": "item",
2025-12-03 11:30:21.179413 | controller |   "delta": "0:00:05.071261",
2025-12-03 11:30:21.179430 | controller |   "end": "2025-12-03 11:30:20.451861",
2025-12-03 11:30:21.179447 | controller |   "item": "crc",
2025-12-03 11:30:21.179464 | controller |   "msg": "non-zero return code",
2025-12-03 11:30:21.179480 | controller |   "rc": 1,
2025-12-03 11:30:21.179499 | controller |   "start": "2025-12-03 11:30:15.380600"
2025-12-03 11:30:21.179516 | controller | }
failure
2025-12-03 11:30:21.198793 | 
2025-12-03 11:30:21.198903 | PLAY RECAP
2025-12-03 11:30:21.198983 | controller | ok: 4 changed: 1 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-12-03 11:30:21.199027 | 
2025-12-03 11:30:21.318836 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/edpm_baremetal_deployment/run.yml@main]
2025-12-03 11:30:21.320185 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-collect-logs.yml@main]
2025-12-03 11:30:21.984699 | 
2025-12-03 11:30:21.984832 | PLAY [Run ci/playbooks/e2e-collect-logs.yml]
2025-12-03 11:30:22.014067 | 
2025-12-03 11:30:22.014198 | TASK [Filter out host if needed]
2025-12-03 11:30:22.024440 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-03 11:30:22.029314 | 
2025-12-03 11:30:22.029386 | TASK [Ensure we have the ci-framework on host]
2025-12-03 11:30:23.938477 | controller | ok
2025-12-03 11:30:23.953272 | 
2025-12-03 11:30:23.953437 | TASK [End host if no basedir]
2025-12-03 11:30:23.969955 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-03 11:30:23.982666 | 
2025-12-03 11:30:23.982926 | TASK [Read base centos-9 scenarios]
2025-12-03 11:30:24.015460 | controller | ok
2025-12-03 11:30:24.126357 | 
2025-12-03 11:30:24.126472 | TASK [cifmw_helpers : Fail if file is not yaml or yml extension]
2025-12-03 11:30:24.160802 | controller | skipping: Conditional result was False
2025-12-03 11:30:24.166703 | 
2025-12-03 11:30:24.166785 | TASK [cifmw_helpers : Check if file is available]
2025-12-03 11:30:25.089528 | controller | ok
2025-12-03 11:30:25.102462 | 
2025-12-03 11:30:25.102646 | TASK [cifmw_helpers : Create temporary directory]
2025-12-03 11:30:25.146587 | controller | skipping: Conditional result was False
2025-12-03 11:30:25.164116 | 
2025-12-03 11:30:25.164315 | TASK [cifmw_helpers : Read vars]
2025-12-03 11:30:25.207608 | 
2025-12-03 11:30:25.207929 | TASK [cifmw_helpers : Create new variable files with content]
2025-12-03 11:30:25.261310 | 
2025-12-03 11:30:25.261606 | TASK [cifmw_helpers : Include vars]
2025-12-03 11:30:25.302384 | 
2025-12-03 11:30:25.302650 | TASK [cifmw_helpers : Remove temporary directory]
2025-12-03 11:30:25.320318 | controller | skipping: Conditional result was False
2025-12-03 11:30:25.344624 | 
2025-12-03 11:30:25.344739 | TASK [run_hook : Assert parameters are valid]
2025-12-03 11:30:25.435630 | controller | ok: All assertions passed
2025-12-03 11:30:25.447616 | 
2025-12-03 11:30:25.447790 | TASK [run_hook : Assert single hooks are all mappings]
2025-12-03 11:30:25.518959 | controller | ok: All assertions passed
2025-12-03 11:30:25.534373 | 
2025-12-03 11:30:25.534516 | LOOP [run_hook : Loop on hooks for pre_logs]
2025-12-03 11:30:25.647429 | 
2025-12-03 11:30:25.647593 | TASK [cifmw_setup : Ensure cifmw_basedir param is set]
2025-12-03 11:30:25.678576 | controller | ok
2025-12-03 11:30:25.714955 | 
2025-12-03 11:30:25.715067 | TASK [Read artifacts parameters dir and set as facts]
2025-12-03 11:30:25.738965 | controller | ok
2025-12-03 11:30:25.767219 | 
2025-12-03 11:30:25.767357 | TASK [cifmw_helpers : Check directory is available]
2025-12-03 11:30:27.470324 | controller | ok
2025-12-03 11:30:27.484651 | 
2025-12-03 11:30:27.484836 | TASK [cifmw_helpers : Find yaml files]
2025-12-03 11:30:29.027876 | controller | ok: All paths examined
2025-12-03 11:30:29.044059 | 
2025-12-03 11:30:29.044183 | TASK [cifmw_helpers : Print available yaml files]
2025-12-03 11:30:29.109415 | controller | ok: Found yaml files to parse: ['/home/zuul/ci-framework-data/artifacts/parameters/zuul-params.yml']
2025-12-03 11:30:29.118501 | 
2025-12-03 11:30:29.118616 | LOOP [cifmw_helpers : Create files on localhost and use include_vars]
2025-12-03 11:30:29.145394 | controller | Output suppressed because no_log was given
2025-12-03 11:30:29.145560 | 
2025-12-03 11:30:29.171935 | controller | included: /var/lib/zuul/builds/1ef2272f6752415189f0ab4b3dd34fdf/untrusted/project_0/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_helpers/tasks/include_file.yml
2025-12-03 11:30:29.185842 | 
2025-12-03 11:30:29.185970 | TASK [cifmw_helpers : Fail if file is not yaml or yml extension]
2025-12-03 11:30:29.221535 | controller | skipping: Conditional result was False
2025-12-03 11:30:29.232770 | 
2025-12-03 11:30:29.232912 | TASK [cifmw_helpers : Check if file is available]
2025-12-03 11:30:30.193823 | controller | ok
2025-12-03 11:30:30.210274 | 
2025-12-03 11:30:30.210437 | TASK [cifmw_helpers : Create temporary directory]
2025-12-03 11:30:30.633015 | controller -> localhost | changed
2025-12-03 11:30:30.641792 | 
2025-12-03 11:30:30.641913 | TASK [cifmw_helpers : Read vars]
2025-12-03 11:30:32.122229 | controller | Output suppressed because no_log was given
2025-12-03 11:30:32.134418 | 
2025-12-03 11:30:32.134567 | TASK [cifmw_helpers : Create new variable files with content]
2025-12-03 11:30:32.852917 | controller -> localhost | Output suppressed because no_log was given
2025-12-03 11:30:32.860307 | 
2025-12-03 11:30:32.860419 | TASK [cifmw_helpers : Include vars]
2025-12-03 11:30:32.895912 | controller -> localhost | Output suppressed because no_log was given
2025-12-03 11:30:32.902981 | 
2025-12-03 11:30:32.903082 | TASK [cifmw_helpers : Remove temporary directory]
2025-12-03 11:30:33.945927 | controller | ok
2025-12-03 11:30:33.974913 | 
2025-12-03 11:30:33.975047 | TASK [cifmw_setup : Set custom cifmw PATH reusable fact]
2025-12-03 11:30:34.042247 | controller | ok
2025-12-03 11:30:34.054799 | 
2025-12-03 11:30:34.054905 | TASK [cifmw_setup : Set destination folder for the logs]
2025-12-03 11:30:34.103157 | controller | ok
2025-12-03 11:30:34.115801 | 
2025-12-03 11:30:34.115970 | TASK [artifacts : Verify if "success" flag exists after successful tests execution]
2025-12-03 11:30:34.860631 | controller | ok
2025-12-03 11:30:34.877644 | 
2025-12-03 11:30:34.877877 | TASK [artifacts : Ensure we have at least minimal ansible facts]
2025-12-03 11:30:36.013082 | controller | ok
2025-12-03 11:30:36.028874 | 
2025-12-03 11:30:36.029014 | LOOP [artifacts : Ensure base directory exists]
2025-12-03 11:30:37.665697 | controller | ok: "artifacts"
2025-12-03 11:30:38.144257 | controller | changed: "logs"
2025-12-03 11:30:38.161564 | 
2025-12-03 11:30:38.161721 | TASK [artifacts : Load generated hook environment for further usage]
2025-12-03 11:30:38.212902 | controller | ERROR
2025-12-03 11:30:38.213258 | controller | {
2025-12-03 11:30:38.213321 | controller |   "ansible_facts": {},
2025-12-03 11:30:38.213363 | controller |   "ansible_included_var_files": [],
2025-12-03 11:30:38.213399 | controller |   "message": "/home/zuul/ci-framework-data/artifacts directory does not exist"
2025-12-03 11:30:38.213433 | controller | }
2025-12-03 11:30:38.213476 | controller | ERROR: Ignoring Errors
2025-12-03 11:30:38.226621 | 
2025-12-03 11:30:38.226855 | TASK [artifacts : Gather parameter passed to the playbook]
2025-12-03 11:30:40.365780 | controller | changed
2025-12-03 11:30:40.373601 | 
2025-12-03 11:30:40.373694 | TASK [artifacts : Gather facts]
2025-12-03 11:30:42.940395 | controller | changed
2025-12-03 11:30:42.949582 | 
2025-12-03 11:30:42.949690 | TASK [artifacts : Copy some network configuration files]
2025-12-03 11:30:44.147160 | controller | changed
2025-12-03 11:30:44.166459 | 
2025-12-03 11:30:44.166614 | TASK [artifacts : Get installed packages list]
2025-12-03 11:30:47.798785 | controller | ok
2025-12-03 11:30:47.873951 | 
2025-12-03 11:30:47.874076 | TASK [artifacts : Output package list]
2025-12-03 11:30:52.938713 | controller | changed
2025-12-03 11:30:52.982262 | 
2025-12-03 11:30:52.982459 | LOOP [artifacts : Cleanup unnecesary directories]
2025-12-03 11:30:54.167501 | controller | ok: "/home/zuul/ci-framework-data/artifacts/manifests/operator"
2025-12-03 11:30:54.167664 | controller | changed: All items complete
2025-12-03 11:30:54.167702 | 
2025-12-03 11:30:54.224866 | 
2025-12-03 11:30:54.225159 | TASK [os_must_gather : Ensure directories are present]
2025-12-03 11:30:55.324648 | controller | changed
2025-12-03 11:30:55.348981 | 
2025-12-03 11:30:55.349172 | LOOP [os_must_gather : Construct project change list]
2025-12-03 11:30:55.408069 | controller | ok:
2025-12-03 11:30:55.408292 | controller | {
2025-12-03 11:30:55.408321 | controller |   "branch": "main",
2025-12-03 11:30:55.408340 | controller |   "change": "3500",
2025-12-03 11:30:55.408372 | controller |   "change_message": "[hooks] Add install-openstack-lightspeed hook\n\n## Summary\r\n\r\nThis PR adds a hook to install OpenStack Lightspeed operators on CRC-based CI Framework deployments.\r\n\r\n## Background\r\n\r\nOpenStack Lightspeed operators are not deployed by default in CRC-based deployments. This hook enables teams to add OpenStack Lightspeed capabilities to their CI Framework deployments.\r\n\r\nThe hook deploys two operators sequentially:\r\n1. OpenShift Lightspeed (foundation operator from Red Hat catalog)\r\n2. OpenStack Lightspeed (custom catalog source)\r\n\r\nFor each operator, the hook:\r\n\r\n- Creates the required namespace\r\n- Configures OperatorGroup with target namespace\r\n- Creates Subscription resource\r\n- Waits for operator deployment to be ready (30 retries x 10s)\r\n\r\nAdditionally, for OpenShift Lightspeed, it configures the service-ca bundle required for metrics collection.\r\n\r\nThe hook is configurable via cifmw_* variables for namespace names, catalog images, and kubeconfig path, defaulting to standard CRC paths.\r\n\r\nTested with successful deployments on CRC clusters.\r\n\r\n## Intent\r\n\r\n  This is primarily for our internal team use. We're submitting to:\r\n  - Confirm we're following CI Framework hook standards\r\n  - Share with the community in case others find it useful\r\n  - Get feedback on our approach\r\n",
2025-12-03 11:30:55.408393 | controller |   "change_url": "https://github.com/openstack-k8s-operators/ci-framework/pull/3500",
2025-12-03 11:30:55.408410 | controller |   "commit_id": "5eecc235ed5b8144169e3521f012be466a7f779f",
2025-12-03 11:30:55.408427 | controller |   "patchset": "5eecc235ed5b8144169e3521f012be466a7f779f",
2025-12-03 11:30:55.408442 | controller |   "project": {
2025-12-03 11:30:55.408458 | controller |     "canonical_hostname": "github.com",
2025-12-03 11:30:55.408475 | controller |     "canonical_name": "github.com/openstack-k8s-operators/ci-framework",
2025-12-03 11:30:55.408491 | controller |     "name": "openstack-k8s-operators/ci-framework",
2025-12-03 11:30:55.408506 | controller |     "short_name": "ci-framework",
2025-12-03 11:30:55.408522 | controller |     "src_dir": "src/github.com/openstack-k8s-operators/ci-framework"
2025-12-03 11:30:55.408538 | controller |   },
2025-12-03 11:30:55.408553 | controller |   "topic": null
2025-12-03 11:30:55.408568 | controller | }
2025-12-03 11:30:55.453636 | 
2025-12-03 11:30:55.453801 | TASK [os_must_gather : Get git tag for image tagging]
2025-12-03 11:30:56.072064 | controller | skipping: Conditional result was False
2025-12-03 11:30:56.124758 | 
2025-12-03 11:30:56.124925 | TASK [os_must_gather : Debug git show-ref]
2025-12-03 11:30:56.165871 | controller | skipping: Conditional result was False
2025-12-03 11:30:56.253929 | 
2025-12-03 11:30:56.254054 | TASK [os_must_gather : Build openstack-must-gather container]
2025-12-03 11:30:56.298957 | controller | skipping: Conditional result was False
2025-12-03 11:30:56.324755 | 
2025-12-03 11:30:56.324887 | TASK [os_must_gather : Push openstack-must-gather container]
2025-12-03 11:30:56.349695 | controller | skipping: Conditional result was False
2025-12-03 11:30:56.376132 | 
2025-12-03 11:30:56.376286 | TASK [os_must_gather : Set cifmw_os_must_gather_image to new image tag]
2025-12-03 11:30:56.401677 | controller | skipping: Conditional result was False
2025-12-03 11:30:56.427305 | 
2025-12-03 11:30:56.427441 | TASK [os_must_gather : Check for oc command]
2025-12-03 11:31:00.536453 | controller | changed
2025-12-03 11:31:00.565473 | 
2025-12-03 11:31:00.565612 | TASK [os_must_gather : Check if kubeconfig exists]
2025-12-03 11:31:03.049791 | controller | ok
2025-12-03 11:31:03.069334 | 
2025-12-03 11:31:03.069406 | TASK [os_must_gather : Run openstack-must-gather command]
2025-12-03 11:31:29.507476 | controller | changed
2025-12-03 11:31:29.539596 | 
2025-12-03 11:31:29.539717 | TASK [os_must_gather : Find existing os-must-gather directories]
2025-12-03 11:31:30.126439 | controller | ok: All paths examined
2025-12-03 11:31:30.148182 | 
2025-12-03 11:31:30.148308 | TASK [os_must_gather : Create a symlink to newest os-must-gather directory]
2025-12-03 11:31:30.216123 | controller | ERROR
2025-12-03 11:31:30.216509 | controller | {
2025-12-03 11:31:30.216585 | controller |   "msg": "The task includes an option with an undefined variable. The error was: Unable to look up a name or access an attribute in template string ({{ (_os_gather_latest_dir.files | sort(attribute='mtime', reverse=True) | first).path | basename }}).\nMake sure your variable name does not contain invalid characters like '-': expected str, bytes or os.PathLike object, not AnsibleUndefined. expected str, bytes or os.PathLike object, not AnsibleUndefined. Unable to look up a name or access an attribute in template string ({{ (_os_gather_latest_dir.files | sort(attribute='mtime', reverse=True) | first).path | basename }}).\nMake sure your variable name does not contain invalid characters like '-': expected str, bytes or os.PathLike object, not AnsibleUndefined. expected str, bytes or os.PathLike object, not AnsibleUndefined\n\nThe error appears to be in '/var/lib/zuul/builds/1ef2272f6752415189f0ab4b3dd34fdf/untrusted/project_0/github.com/openstack-k8s-operators/ci-framework/roles/os_must_gather/tasks/main.yml': line 95, column 7, but may\nbe elsewhere in the file depending on the exact syntax problem.\n\nThe offending line appears to be:\n\n\n    - name: Create a symlink to newest os-must-gather directory\n      ^ here\n"
2025-12-03 11:31:30.216664 | controller | }
2025-12-03 11:31:30.216745 | controller | ERROR: Ignoring Errors
2025-12-03 11:31:30.258036 | 
2025-12-03 11:31:30.258163 | TASK [artifacts : Create crc logs directory]
2025-12-03 11:31:31.005923 | controller | changed
2025-12-03 11:31:31.078675 | 
2025-12-03 11:31:31.078831 | TASK [artifacts : Ensure controller knows CRC ssh keys]
2025-12-03 11:31:36.748609 | controller | ERROR
2025-12-03 11:31:36.749011 | controller | {
2025-12-03 11:31:36.749075 | controller |   "delta": "0:00:05.024851",
2025-12-03 11:31:36.749118 | controller |   "end": "2025-12-03 11:31:36.666664",
2025-12-03 11:31:36.749157 | controller |   "msg": "non-zero return code",
2025-12-03 11:31:36.749193 | controller |   "rc": 1,
2025-12-03 11:31:36.749228 | controller |   "start": "2025-12-03 11:31:31.641813"
2025-12-03 11:31:36.749275 | controller | }
2025-12-03 11:31:36.749327 | controller | ERROR: Ignoring Errors
2025-12-03 11:31:36.780396 | 
2025-12-03 11:31:36.780685 | TASK [artifacts : Recognize new keypair]
2025-12-03 11:31:36.817115 | controller | skipping: Conditional result was False
2025-12-03 11:31:36.843182 | 
2025-12-03 11:31:36.843324 | TASK [artifacts : Set fact if new keypair exists]
2025-12-03 11:31:36.868618 | controller | skipping: Conditional result was False
2025-12-03 11:31:36.892899 | 
2025-12-03 11:31:36.893080 | TASK [artifacts : Prepare root ssh accesses]
2025-12-03 11:31:36.917878 | controller | skipping: Conditional result was False
2025-12-03 11:31:36.941927 | 
2025-12-03 11:31:36.942038 | TASK [artifacts : Copy logs from CRC VM]
2025-12-03 11:31:36.966782 | controller | skipping: Conditional result was False
2025-12-03 11:31:37.042104 | 
2025-12-03 11:31:37.042261 | TASK [artifacts : List all of the existing virtual machines]
2025-12-03 11:31:37.953686 | controller | ok
2025-12-03 11:31:37.989824 | 
2025-12-03 11:31:37.990001 | TASK [artifacts : Filter out edpm vm]
2025-12-03 11:31:38.071494 | controller | ok
2025-12-03 11:31:38.096259 | 
2025-12-03 11:31:38.096417 | TASK [artifacts : Slurp zuul inventory file]
2025-12-03 11:31:38.121520 | controller | skipping: Conditional result was False
2025-12-03 11:31:38.146125 | 
2025-12-03 11:31:38.146254 | LOOP [artifacts : Extract Compute and Networker from zuul mapping if any]
2025-12-03 11:31:38.198884 | 
2025-12-03 11:31:38.199068 | LOOP [artifacts : Generate logs on edpm vm {{ item.split('/')[0] }}]
2025-12-03 11:31:38.248954 | controller | ERROR
2025-12-03 11:31:38.249188 | controller | {
2025-12-03 11:31:38.249216 | controller |   "msg": "Invalid data passed to 'loop', it requires a list, got this instead: . Hint: If you passed a list/dict of just one element, try adding wantlist=True to your lookup invocation or use q/query instead of lookup."
2025-12-03 11:31:38.249248 | controller | }
2025-12-03 11:31:38.249270 | controller | ERROR: Ignoring Errors
2025-12-03 11:31:38.271759 | 
2025-12-03 11:31:38.271939 | LOOP [artifacts : Copy logs to host machine from {{ item.split('/')[0] }}]
2025-12-03 11:31:38.321949 | controller | ERROR
2025-12-03 11:31:38.322158 | controller | {
2025-12-03 11:31:38.322186 | controller |   "msg": "Invalid data passed to 'loop', it requires a list, got this instead: . Hint: If you passed a list/dict of just one element, try adding wantlist=True to your lookup invocation or use q/query instead of lookup."
2025-12-03 11:31:38.322207 | controller | }
2025-12-03 11:31:38.322231 | controller | ERROR: Ignoring Errors
2025-12-03 11:31:38.343146 | 
2025-12-03 11:31:38.343259 | TASK [artifacts : Generate list of ansible logs to collect in home directory]
2025-12-03 11:31:38.930244 | controller | ok: All paths examined
2025-12-03 11:31:38.959953 | 
2025-12-03 11:31:38.960262 | LOOP [artifacts : Copy ansible logs to logs directory]
2025-12-03 11:31:40.327621 | controller | changed:
2025-12-03 11:31:40.327804 | controller | {
2025-12-03 11:31:40.327831 | controller |   "atime": 1764761356.7259083,
2025-12-03 11:31:40.327848 | controller |   "ctime": 1764761396.9789104,
2025-12-03 11:31:40.327864 | controller |   "dev": 64513,
2025-12-03 11:31:40.327880 | controller |   "gid": 1000,
2025-12-03 11:31:40.327895 | controller |   "gr_name": "zuul",
2025-12-03 11:31:40.327911 | controller |   "inode": 5167386,
2025-12-03 11:31:40.327926 | controller |   "isblk": false,
2025-12-03 11:31:40.327940 | controller |   "ischr": false,
2025-12-03 11:31:40.327955 | controller |   "isdir": false,
2025-12-03 11:31:40.327969 | controller |   "isfifo": false,
2025-12-03 11:31:40.327983 | controller |   "isgid": false,
2025-12-03 11:31:40.327997 | controller |   "islnk": false,
2025-12-03 11:31:40.328010 | controller |   "isreg": true,
2025-12-03 11:31:40.328024 | controller |   "issock": false,
2025-12-03 11:31:40.328038 | controller |   "isuid": false,
2025-12-03 11:31:40.328055 | controller |   "mode": "0644",
2025-12-03 11:31:40.328071 | controller |   "mtime": 1764761396.9789104,
2025-12-03 11:31:40.328086 | controller |   "nlink": 1,
2025-12-03 11:31:40.328101 | controller |   "path": "/home/zuul/ansible.log",
2025-12-03 11:31:40.328117 | controller |   "pw_name": "zuul",
2025-12-03 11:31:40.328131 | controller |   "rgrp": true,
2025-12-03 11:31:40.328146 | controller |   "roth": true,
2025-12-03 11:31:40.328165 | controller |   "rusr": true,
2025-12-03 11:31:40.328181 | controller |   "size": 6749,
2025-12-03 11:31:40.328195 | controller |   "uid": 1000,
2025-12-03 11:31:40.328227 | controller |   "wgrp": false,
2025-12-03 11:31:40.328242 | controller |   "woth": false,
2025-12-03 11:31:40.328261 | controller |   "wusr": true,
2025-12-03 11:31:40.328277 | controller |   "xgrp": false,
2025-12-03 11:31:40.328296 | controller |   "xoth": false,
2025-12-03 11:31:40.328313 | controller |   "xusr": false
2025-12-03 11:31:40.328331 | controller | }
2025-12-03 11:31:40.328365 | 
2025-12-03 11:31:40.374180 | 
2025-12-03 11:31:40.374340 | TASK [artifacts : Ensure we have proper rights on the gathered content]
2025-12-03 11:31:41.481422 | controller | changed
2025-12-03 11:31:41.526669 | 
2025-12-03 11:31:41.526860 | LOOP [artifacts : Mask secrets in yaml log files]
2025-12-03 11:31:42.452149 | controller | ok: "/home/zuul/ci-framework-data/logs"
2025-12-03 11:31:43.058062 | controller | ok: "/home/zuul/ci-framework-data/artifacts"
2025-12-03 11:31:43.177503 | 
2025-12-03 11:31:43.177627 | LOOP [env_op_images : Ensure directory is present]
2025-12-03 11:31:43.692339 | controller | ok: "artifacts"
2025-12-03 11:31:43.692761 | controller | ok: All items complete
2025-12-03 11:31:43.692812 | 
2025-12-03 11:31:44.099399 | controller | ok: "logs"
2025-12-03 11:31:44.136251 | 
2025-12-03 11:31:44.136405 | TASK [env_op_images : Check if OpenStackControlPlane is setup]
2025-12-03 11:31:44.746390 | controller | skipping: Conditional result was False
2025-12-03 11:31:44.795216 | 
2025-12-03 11:31:44.795446 | TASK [env_op_images : Get images from the CSV]
2025-12-03 11:31:45.453764 | controller | skipping: Conditional result was False
2025-12-03 11:31:45.499752 | 
2025-12-03 11:31:45.499911 | TASK [env_op_images : Get the images name]
2025-12-03 11:31:46.119749 | controller | skipping: Conditional result was False
2025-12-03 11:31:46.189433 | 
2025-12-03 11:31:46.189620 | TASK [env_op_images : Extract env variable name and images]
2025-12-03 11:31:46.236147 | controller | skipping: Conditional result was False
2025-12-03 11:31:46.280263 | 
2025-12-03 11:31:46.280444 | TASK [env_op_images : Get all pods from all namespaces to find openstack-operator-index]
2025-12-03 11:31:46.316213 | controller | skipping: Conditional result was False
2025-12-03 11:31:46.354047 | 
2025-12-03 11:31:46.354196 | TASK [env_op_images : Retrieve openstack-operator-index pod]
2025-12-03 11:31:46.389811 | controller | skipping: Conditional result was False
2025-12-03 11:31:46.417492 | 
2025-12-03 11:31:46.417779 | TASK [env_op_images : Get all the pods in openstack-operator namespace]
2025-12-03 11:31:46.443018 | controller | skipping: Conditional result was False
2025-12-03 11:31:46.470291 | 
2025-12-03 11:31:46.470431 | TASK [env_op_images : Get operator images and pods]
2025-12-03 11:31:46.494887 | controller | skipping: Conditional result was False
2025-12-03 11:31:46.519507 | 
2025-12-03 11:31:46.519626 | LOOP [env_op_images : Add operator images to the dictionary]
2025-12-03 11:31:46.635462 | 
2025-12-03 11:31:46.635615 | TASK [env_op_images : Write images to file]
2025-12-03 11:31:46.670017 | controller | skipping: Conditional result was False
2025-12-03 11:31:46.716479 | 
2025-12-03 11:31:46.716607 | TASK [cifmw_setup : Create a versioned log folder]
2025-12-03 11:31:47.231973 | controller | changed
2025-12-03 11:31:47.265129 | 
2025-12-03 11:31:47.265268 | TASK [cifmw_setup : Return a list of log files in home directory]
2025-12-03 11:31:47.744635 | controller | ok: All paths examined
2025-12-03 11:31:47.769582 | 
2025-12-03 11:31:47.769721 | TASK [cifmw_setup : Ensure ansible facts cache exists]
2025-12-03 11:31:48.309841 | controller | ok
2025-12-03 11:31:48.356178 | 
2025-12-03 11:31:48.356345 | LOOP [cifmw_setup : Copy logs to proper location]
2025-12-03 11:31:50.356909 | controller | changed:
2025-12-03 11:31:50.357348 | controller | {
2025-12-03 11:31:50.357394 | controller |   "atime": 1763989167.9348345,
2025-12-03 11:31:50.357424 | controller |   "ctime": 1763990251.6129293,
2025-12-03 11:31:50.357450 | controller |   "dev": 64513,
2025-12-03 11:31:50.357481 | controller |   "gid": 1000,
2025-12-03 11:31:50.357507 | controller |   "gr_name": "zuul",
2025-12-03 11:31:50.357531 | controller |   "inode": 5131423,
2025-12-03 11:31:50.357555 | controller |   "isblk": false,
2025-12-03 11:31:50.357577 | controller |   "ischr": false,
2025-12-03 11:31:50.357600 | controller |   "isdir": false,
2025-12-03 11:31:50.357622 | controller |   "isfifo": false,
2025-12-03 11:31:50.357644 | controller |   "isgid": false,
2025-12-03 11:31:50.357666 | controller |   "islnk": false,
2025-12-03 11:31:50.357687 | controller |   "isreg": true,
2025-12-03 11:31:50.357709 | controller |   "issock": false,
2025-12-03 11:31:50.357760 | controller |   "isuid": false,
2025-12-03 11:31:50.357786 | controller |   "mode": "0644",
2025-12-03 11:31:50.357808 | controller |   "mtime": 1763990251.6129293,
2025-12-03 11:31:50.357831 | controller |   "nlink": 1,
2025-12-03 11:31:50.357853 | controller |   "path": "/home/zuul/crc-setup.log",
2025-12-03 11:31:50.357876 | controller |   "pw_name": "zuul",
2025-12-03 11:31:50.357900 | controller |   "rgrp": true,
2025-12-03 11:31:50.357922 | controller |   "roth": true,
2025-12-03 11:31:50.357944 | controller |   "rusr": true,
2025-12-03 11:31:50.357966 | controller |   "size": 4108,
2025-12-03 11:31:50.357997 | controller |   "uid": 1000,
2025-12-03 11:31:50.358019 | controller |   "wgrp": false,
2025-12-03 11:31:50.358041 | controller |   "woth": false,
2025-12-03 11:31:50.358063 | controller |   "wusr": true,
2025-12-03 11:31:50.358084 | controller |   "xgrp": false,
2025-12-03 11:31:50.358106 | controller |   "xoth": false,
2025-12-03 11:31:50.358127 | controller |   "xusr": false
2025-12-03 11:31:50.358147 | controller | }
2025-12-03 11:31:50.358192 | 
2025-12-03 11:31:51.453266 | controller | changed:
2025-12-03 11:31:51.453494 | controller | {
2025-12-03 11:31:51.453570 | controller |   "atime": 1764760714.5966563,
2025-12-03 11:31:51.453613 | controller |   "ctime": 1764760711.985591,
2025-12-03 11:31:51.453649 | controller |   "dev": 64513,
2025-12-03 11:31:51.453684 | controller |   "gid": 1000,
2025-12-03 11:31:51.453719 | controller |   "gr_name": "zuul",
2025-12-03 11:31:51.453793 | controller |   "inode": 5131425,
2025-12-03 11:31:51.453843 | controller |   "isblk": false,
2025-12-03 11:31:51.453880 | controller |   "ischr": false,
2025-12-03 11:31:51.453919 | controller |   "isdir": false,
2025-12-03 11:31:51.453958 | controller |   "isfifo": false,
2025-12-03 11:31:51.453997 | controller |   "isgid": false,
2025-12-03 11:31:51.454035 | controller |   "islnk": false,
2025-12-03 11:31:51.454074 | controller |   "isreg": true,
2025-12-03 11:31:51.454112 | controller |   "issock": false,
2025-12-03 11:31:51.454151 | controller |   "isuid": false,
2025-12-03 11:31:51.454189 | controller |   "mode": "0644",
2025-12-03 11:31:51.454229 | controller |   "mtime": 1764760711.985591,
2025-12-03 11:31:51.454269 | controller |   "nlink": 1,
2025-12-03 11:31:51.454309 | controller |   "path": "/home/zuul/crc-start.log",
2025-12-03 11:31:51.454351 | controller |   "pw_name": "zuul",
2025-12-03 11:31:51.454392 | controller |   "rgrp": true,
2025-12-03 11:31:51.454432 | controller |   "roth": true,
2025-12-03 11:31:51.454471 | controller |   "rusr": true,
2025-12-03 11:31:51.454511 | controller |   "size": 4120,
2025-12-03 11:31:51.454551 | controller |   "uid": 1000,
2025-12-03 11:31:51.454591 | controller |   "wgrp": false,
2025-12-03 11:31:51.454630 | controller |   "woth": false,
2025-12-03 11:31:51.454670 | controller |   "wusr": true,
2025-12-03 11:31:51.454709 | controller |   "xgrp": false,
2025-12-03 11:31:51.454781 | controller |   "xoth": false,
2025-12-03 11:31:51.454824 | controller |   "xusr": false
2025-12-03 11:31:51.454865 | controller | }
2025-12-03 11:31:52.483113 | controller | changed:
2025-12-03 11:31:52.483247 | controller | {
2025-12-03 11:31:52.483290 | controller |   "atime": 1764761499.9334843,
2025-12-03 11:31:52.483326 | controller |   "ctime": 1764761396.9789104,
2025-12-03 11:31:52.483359 | controller |   "dev": 64513,
2025-12-03 11:31:52.483391 | controller |   "gid": 1000,
2025-12-03 11:31:52.483420 | controller |   "gr_name": "zuul",
2025-12-03 11:31:52.483450 | controller |   "inode": 5167386,
2025-12-03 11:31:52.483481 | controller |   "isblk": false,
2025-12-03 11:31:52.483511 | controller |   "ischr": false,
2025-12-03 11:31:52.483542 | controller |   "isdir": false,
2025-12-03 11:31:52.483571 | controller |   "isfifo": false,
2025-12-03 11:31:52.483601 | controller |   "isgid": false,
2025-12-03 11:31:52.483633 | controller |   "islnk": false,
2025-12-03 11:31:52.483664 | controller |   "isreg": true,
2025-12-03 11:31:52.483692 | controller |   "issock": false,
2025-12-03 11:31:52.483719 | controller |   "isuid": false,
2025-12-03 11:31:52.483773 | controller |   "mode": "0644",
2025-12-03 11:31:52.483799 | controller |   "mtime": 1764761396.9789104,
2025-12-03 11:31:52.483825 | controller |   "nlink": 1,
2025-12-03 11:31:52.483883 | controller |   "path": "/home/zuul/ansible.log",
2025-12-03 11:31:52.483911 | controller |   "pw_name": "zuul",
2025-12-03 11:31:52.483936 | controller |   "rgrp": true,
2025-12-03 11:31:52.483960 | controller |   "roth": true,
2025-12-03 11:31:52.483984 | controller |   "rusr": true,
2025-12-03 11:31:52.484007 | controller |   "size": 6749,
2025-12-03 11:31:52.484041 | controller |   "uid": 1000,
2025-12-03 11:31:52.484064 | controller |   "wgrp": false,
2025-12-03 11:31:52.484087 | controller |   "woth": false,
2025-12-03 11:31:52.484112 | controller |   "wusr": true,
2025-12-03 11:31:52.484138 | controller |   "xgrp": false,
2025-12-03 11:31:52.484165 | controller |   "xoth": false,
2025-12-03 11:31:52.484197 | controller |   "xusr": false
2025-12-03 11:31:52.484228 | controller | }
2025-12-03 11:31:52.519705 | 
2025-12-03 11:31:52.519885 | LOOP [cifmw_setup : Remove original log from home directory]
2025-12-03 11:31:53.032109 | controller | changed:
2025-12-03 11:31:53.032414 | controller | {
2025-12-03 11:31:53.032450 | controller |   "atime": 1763989167.9348345,
2025-12-03 11:31:53.032475 | controller |   "ctime": 1763990251.6129293,
2025-12-03 11:31:53.032498 | controller |   "dev": 64513,
2025-12-03 11:31:53.032520 | controller |   "gid": 1000,
2025-12-03 11:31:53.032542 | controller |   "gr_name": "zuul",
2025-12-03 11:31:53.032564 | controller |   "inode": 5131423,
2025-12-03 11:31:53.032585 | controller |   "isblk": false,
2025-12-03 11:31:53.032606 | controller |   "ischr": false,
2025-12-03 11:31:53.032627 | controller |   "isdir": false,
2025-12-03 11:31:53.032647 | controller |   "isfifo": false,
2025-12-03 11:31:53.032668 | controller |   "isgid": false,
2025-12-03 11:31:53.032688 | controller |   "islnk": false,
2025-12-03 11:31:53.032708 | controller |   "isreg": true,
2025-12-03 11:31:53.032761 | controller |   "issock": false,
2025-12-03 11:31:53.032785 | controller |   "isuid": false,
2025-12-03 11:31:53.032805 | controller |   "mode": "0644",
2025-12-03 11:31:53.032825 | controller |   "mtime": 1763990251.6129293,
2025-12-03 11:31:53.032845 | controller |   "nlink": 1,
2025-12-03 11:31:53.032866 | controller |   "path": "/home/zuul/crc-setup.log",
2025-12-03 11:31:53.032887 | controller |   "pw_name": "zuul",
2025-12-03 11:31:53.032907 | controller |   "rgrp": true,
2025-12-03 11:31:53.032927 | controller |   "roth": true,
2025-12-03 11:31:53.032947 | controller |   "rusr": true,
2025-12-03 11:31:53.032967 | controller |   "size": 4108,
2025-12-03 11:31:53.032987 | controller |   "uid": 1000,
2025-12-03 11:31:53.033006 | controller |   "wgrp": false,
2025-12-03 11:31:53.033026 | controller |   "woth": false,
2025-12-03 11:31:53.033045 | controller |   "wusr": true,
2025-12-03 11:31:53.033065 | controller |   "xgrp": false,
2025-12-03 11:31:53.033084 | controller |   "xoth": false,
2025-12-03 11:31:53.033104 | controller |   "xusr": false
2025-12-03 11:31:53.033124 | controller | }
2025-12-03 11:31:53.697452 | controller | changed:
2025-12-03 11:31:53.697585 | controller | {
2025-12-03 11:31:53.697614 | controller |   "atime": 1764760714.5966563,
2025-12-03 11:31:53.697794 | controller |   "ctime": 1764760711.985591,
2025-12-03 11:31:53.697825 | controller |   "dev": 64513,
2025-12-03 11:31:53.697842 | controller |   "gid": 1000,
2025-12-03 11:31:53.697857 | controller |   "gr_name": "zuul",
2025-12-03 11:31:53.697872 | controller |   "inode": 5131425,
2025-12-03 11:31:53.697887 | controller |   "isblk": false,
2025-12-03 11:31:53.697902 | controller |   "ischr": false,
2025-12-03 11:31:53.697916 | controller |   "isdir": false,
2025-12-03 11:31:53.697929 | controller |   "isfifo": false,
2025-12-03 11:31:53.697943 | controller |   "isgid": false,
2025-12-03 11:31:53.697957 | controller |   "islnk": false,
2025-12-03 11:31:53.697971 | controller |   "isreg": true,
2025-12-03 11:31:53.697985 | controller |   "issock": false,
2025-12-03 11:31:53.697999 | controller |   "isuid": false,
2025-12-03 11:31:53.698014 | controller |   "mode": "0644",
2025-12-03 11:31:53.698028 | controller |   "mtime": 1764760711.985591,
2025-12-03 11:31:53.698042 | controller |   "nlink": 1,
2025-12-03 11:31:53.698057 | controller |   "path": "/home/zuul/crc-start.log",
2025-12-03 11:31:53.698072 | controller |   "pw_name": "zuul",
2025-12-03 11:31:53.698087 | controller |   "rgrp": true,
2025-12-03 11:31:53.698101 | controller |   "roth": true,
2025-12-03 11:31:53.698122 | controller |   "rusr": true,
2025-12-03 11:31:53.698138 | controller |   "size": 4120,
2025-12-03 11:31:53.698152 | controller |   "uid": 1000,
2025-12-03 11:31:53.698166 | controller |   "wgrp": false,
2025-12-03 11:31:53.698180 | controller |   "woth": false,
2025-12-03 11:31:53.698194 | controller |   "wusr": true,
2025-12-03 11:31:53.698208 | controller |   "xgrp": false,
2025-12-03 11:31:53.698222 | controller |   "xoth": false,
2025-12-03 11:31:53.698236 | controller |   "xusr": false
2025-12-03 11:31:53.698250 | controller | }
2025-12-03 11:31:54.340261 | controller | changed:
2025-12-03 11:31:54.340384 | controller | {
2025-12-03 11:31:54.340416 | controller |   "atime": 1764761499.9334843,
2025-12-03 11:31:54.340443 | controller |   "ctime": 1764761396.9789104,
2025-12-03 11:31:54.340467 | controller |   "dev": 64513,
2025-12-03 11:31:54.340491 | controller |   "gid": 1000,
2025-12-03 11:31:54.340553 | controller |   "gr_name": "zuul",
2025-12-03 11:31:54.340580 | controller |   "inode": 5167386,
2025-12-03 11:31:54.340603 | controller |   "isblk": false,
2025-12-03 11:31:54.340625 | controller |   "ischr": false,
2025-12-03 11:31:54.340647 | controller |   "isdir": false,
2025-12-03 11:31:54.340673 | controller |   "isfifo": false,
2025-12-03 11:31:54.340696 | controller |   "isgid": false,
2025-12-03 11:31:54.340719 | controller |   "islnk": false,
2025-12-03 11:31:54.340769 | controller |   "isreg": true,
2025-12-03 11:31:54.340791 | controller |   "issock": false,
2025-12-03 11:31:54.340811 | controller |   "isuid": false,
2025-12-03 11:31:54.340831 | controller |   "mode": "0644",
2025-12-03 11:31:54.340850 | controller |   "mtime": 1764761396.9789104,
2025-12-03 11:31:54.340870 | controller |   "nlink": 1,
2025-12-03 11:31:54.340889 | controller |   "path": "/home/zuul/ansible.log",
2025-12-03 11:31:54.340909 | controller |   "pw_name": "zuul",
2025-12-03 11:31:54.340929 | controller |   "rgrp": true,
2025-12-03 11:31:54.340948 | controller |   "roth": true,
2025-12-03 11:31:54.340968 | controller |   "rusr": true,
2025-12-03 11:31:54.340988 | controller |   "size": 6749,
2025-12-03 11:31:54.341007 | controller |   "uid": 1000,
2025-12-03 11:31:54.341026 | controller |   "wgrp": false,
2025-12-03 11:31:54.341044 | controller |   "woth": false,
2025-12-03 11:31:54.341063 | controller |   "wusr": true,
2025-12-03 11:31:54.341082 | controller |   "xgrp": false,
2025-12-03 11:31:54.341101 | controller |   "xoth": false,
2025-12-03 11:31:54.341120 | controller |   "xusr": false
2025-12-03 11:31:54.341139 | controller | }
2025-12-03 11:31:54.390202 | 
2025-12-03 11:31:54.390370 | TASK [cifmw_setup : Copy facts to dated directory]
2025-12-03 11:31:54.428014 | controller | skipping: Conditional result was False
2025-12-03 11:31:54.470275 | 
2025-12-03 11:31:54.470410 | TASK [run_hook : Assert parameters are valid]
2025-12-03 11:31:54.630020 | controller | ok: All assertions passed
2025-12-03 11:31:54.660178 | 
2025-12-03 11:31:54.660290 | TASK [run_hook : Assert single hooks are all mappings]
2025-12-03 11:31:54.816069 | controller | ok: All assertions passed
2025-12-03 11:31:54.857006 | 
2025-12-03 11:31:54.857139 | LOOP [run_hook : Loop on hooks for post_logs]
2025-12-03 11:31:55.241550 | 
2025-12-03 11:31:55.241683 | PLAY [Run ci/playbooks/e2e-collect-logs.yml on CRC host]
2025-12-03 11:31:55.241793 | 
2025-12-03 11:31:55.241823 | PLAY RECAP
2025-12-03 11:31:55.241860 | controller | ok: 52 changed: 17 unreachable: 0 failed: 0 skipped: 31 rescued: 0 ignored: 5
2025-12-03 11:31:55.241879 | 
2025-12-03 11:31:55.405910 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-collect-logs.yml@main]
2025-12-03 11:31:55.409106 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-03 11:31:56.000659 | 
2025-12-03 11:31:56.000782 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-03 11:31:56.088932 | 
2025-12-03 11:31:56.089067 | TASK [Filter out host if needed]
2025-12-03 11:31:56.142823 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-03 11:31:56.168805 | 
2025-12-03 11:31:56.168887 | TASK [Ensure file is present]
2025-12-03 11:31:56.833590 | controller | ok
2025-12-03 11:31:56.866472 | 
2025-12-03 11:31:56.866563 | TASK [Manage molecule report file]
2025-12-03 11:31:57.613605 | controller | skipping: Conditional result was False
2025-12-03 11:31:57.662158 | 
2025-12-03 11:31:57.662313 | TASK [Check if we get ci-framework-data basedir]
2025-12-03 11:31:58.546411 | controller | ok
2025-12-03 11:31:58.608990 | 
2025-12-03 11:31:58.609112 | TASK [Create ci-framework-data log directory for zuul]
2025-12-03 11:31:59.339597 | controller | changed
2025-12-03 11:31:59.373327 | 
2025-12-03 11:31:59.373442 | TASK [Copy ci-framework interesting files]
2025-12-03 11:32:00.875773 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-12-03 11:32:01.057681 | controller | changed
2025-12-03 11:32:01.111248 | 
2025-12-03 11:32:01.111362 | TASK [Get SELinux listing]
2025-12-03 11:32:02.286161 | controller | changed
2025-12-03 11:32:02.354462 | 
2025-12-03 11:32:02.354576 | TASK [Generate log index]
2025-12-03 11:32:04.652260 | controller | changed
2025-12-03 11:32:04.683521 | 
2025-12-03 11:32:04.683665 | TASK [Get some env related data]
2025-12-03 11:32:05.936357 | controller | /home/zuul/.local/bin/ansible
2025-12-03 11:32:06.937057 | controller | changed
2025-12-03 11:32:06.974622 | 
2025-12-03 11:32:06.974751 | TASK [Generate list of logs to collect in home directory]
2025-12-03 11:32:07.649019 | controller | ok: All paths examined
2025-12-03 11:32:07.713926 | 
2025-12-03 11:32:07.714046 | LOOP [Copy logs from home directory]
2025-12-03 11:32:07.773034 | 
2025-12-03 11:32:07.773238 | TASK [Copy crio stats log file]
2025-12-03 11:32:07.807510 | controller | skipping: Conditional result was False
2025-12-03 11:32:07.838563 | 
2025-12-03 11:32:07.838829 | TASK [Get SELinux related data]
2025-12-03 11:32:08.441932 | controller | <no matches>
2025-12-03 11:32:08.535814 | controller | ERROR
2025-12-03 11:32:08.536025 | controller | {
2025-12-03 11:32:08.536069 | controller |   "delta": "0:00:00.019556",
2025-12-03 11:32:08.536097 | controller |   "end": "2025-12-03 11:32:08.444069",
2025-12-03 11:32:08.536122 | controller |   "msg": "non-zero return code",
2025-12-03 11:32:08.536145 | controller |   "rc": 1,
2025-12-03 11:32:08.536169 | controller |   "start": "2025-12-03 11:32:08.424513"
2025-12-03 11:32:08.536192 | controller | }
2025-12-03 11:32:08.536222 | controller | ERROR: Ignoring Errors
2025-12-03 11:32:08.578927 | 
2025-12-03 11:32:08.579087 | TASK [Create system configuration directory]
2025-12-03 11:32:09.079312 | controller | changed
2025-12-03 11:32:09.175635 | 
2025-12-03 11:32:09.175818 | TASK [Get some of the system configurations]
2025-12-03 11:32:09.966501 | controller | changed
2025-12-03 11:32:09.999639 | 
2025-12-03 11:32:09.999812 | TASK [Copy generated documentation if available]
2025-12-03 11:32:10.033875 | controller | skipping: Conditional result was False
2025-12-03 11:32:10.062862 | 
2025-12-03 11:32:10.062994 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-03 11:32:10.097114 | controller | skipping: Conditional result was False
2025-12-03 11:32:10.126410 | 
2025-12-03 11:32:10.126503 | TASK [Compress logs bigger than 2MB]
2025-12-03 11:32:10.858600 | controller | changed
2025-12-03 11:32:10.935911 | 
2025-12-03 11:32:10.936034 | TASK [Copy files from workspace on node]
2025-12-03 11:32:10.980467 | controller | ok
2025-12-03 11:32:11.048210 | 
2025-12-03 11:32:11.048319 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-03 11:32:11.082764 | controller | skipping: Conditional result was False
2025-12-03 11:32:11.112305 | 
2025-12-03 11:32:11.112429 | TASK [fetch-output : Set log path for single node]
2025-12-03 11:32:11.185497 | controller | ok
2025-12-03 11:32:11.251416 | 
2025-12-03 11:32:11.251537 | LOOP [fetch-output : Ensure local output dirs]
2025-12-03 11:32:11.538058 | controller -> localhost | ok: "/var/lib/zuul/builds/1ef2272f6752415189f0ab4b3dd34fdf/work/logs"
2025-12-03 11:32:11.538291 | 
2025-12-03 11:32:11.768700 | controller -> localhost | changed: "/var/lib/zuul/builds/1ef2272f6752415189f0ab4b3dd34fdf/work/artifacts"
2025-12-03 11:32:11.996623 | controller -> localhost | changed: "/var/lib/zuul/builds/1ef2272f6752415189f0ab4b3dd34fdf/work/docs"
2025-12-03 11:32:12.059053 | 
2025-12-03 11:32:12.059206 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-03 11:32:13.028346 | controller | changed:
2025-12-03 11:32:13.028761 | controller | .d..t...... ./
2025-12-03 11:32:13.028831 | controller | >f+++++++++ README.html
2025-12-03 11:32:13.028878 | controller | >f+++++++++ dmesg.log
2025-12-03 11:32:13.028933 | controller | >f+++++++++ installed-pkgs.log
2025-12-03 11:32:13.028982 | controller | >f+++++++++ python.log
2025-12-03 11:32:13.029028 | controller | >f+++++++++ registries.conf
2025-12-03 11:32:13.029074 | controller | >f+++++++++ selinux-denials.log
2025-12-03 11:32:13.029119 | controller | >f+++++++++ selinux-listing.log
2025-12-03 11:32:13.029164 | controller | cd+++++++++ ci-framework-data/
2025-12-03 11:32:13.029207 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-03 11:32:13.029252 | controller | >f+++++++++ ci-framework-data/artifacts/ansible-facts.yml
2025-12-03 11:32:13.029296 | controller | >f+++++++++ ci-framework-data/artifacts/ansible-vars.yml
2025-12-03 11:32:13.029341 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_check_for_oc.sh
2025-12-03 11:32:13.029385 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_run_openstack_must_gather.sh
2025-12-03 11:32:13.029432 | controller | >f+++++++++ ci-framework-data/artifacts/hosts
2025-12-03 11:32:13.029478 | controller | >f+++++++++ ci-framework-data/artifacts/installed-packages.yml
2025-12-03 11:32:13.029523 | controller | >f+++++++++ ci-framework-data/artifacts/ip-network.txt
2025-12-03 11:32:13.029567 | controller | >f+++++++++ ci-framework-data/artifacts/resolv.conf
2025-12-03 11:32:13.029611 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-03 11:32:13.029654 | controller | cd+++++++++ ci-framework-data/artifacts/NetworkManager/
2025-12-03 11:32:13.029697 | controller | >f+++++++++ ci-framework-data/artifacts/NetworkManager/ens3.nmconnection
2025-12-03 11:32:13.029773 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-03 11:32:13.029934 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-03 11:32:13.029984 | controller | cd+++++++++ ci-framework-data/artifacts/yum_repos/
2025-12-03 11:32:13.030024 | controller | >f+++++++++ ci-framework-data/artifacts/yum_repos/centos-addons.repo
2025-12-03 11:32:13.030063 | controller | >f+++++++++ ci-framework-data/artifacts/yum_repos/centos.repo
2025-12-03 11:32:13.030099 | controller | >f+++++++++ ci-framework-data/artifacts/yum_repos/kubernetes.repo
2025-12-03 11:32:13.030136 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-03 11:32:13.030173 | controller | >f+++++++++ ci-framework-data/logs/ansible.log
2025-12-03 11:32:13.030211 | controller | >f+++++++++ ci-framework-data/logs/ci_script_000_check_for_oc.log
2025-12-03 11:32:13.030248 | controller | >f+++++++++ ci-framework-data/logs/ci_script_000_run_openstack_must_gather.log
2025-12-03 11:32:13.030284 | controller | cd+++++++++ ci-framework-data/logs/2025-12-03_11-30/
2025-12-03 11:32:13.030321 | controller | >f+++++++++ ci-framework-data/logs/2025-12-03_11-30/ansible.log
2025-12-03 11:32:13.030356 | controller | >f+++++++++ ci-framework-data/logs/2025-12-03_11-30/crc-setup.log
2025-12-03 11:32:13.030392 | controller | >f+++++++++ ci-framework-data/logs/2025-12-03_11-30/crc-start.log
2025-12-03 11:32:13.030428 | controller | cd+++++++++ ci-framework-data/logs/crc/
2025-12-03 11:32:13.030463 | controller | cd+++++++++ ci-framework-data/logs/openstack-must-gather/
2025-12-03 11:32:13.030498 | controller | >f+++++++++ ci-framework-data/logs/openstack-must-gather/event-filter.html
2025-12-03 11:32:13.030546 | controller | >f+++++++++ ci-framework-data/logs/openstack-must-gather/must-gather.logs
2025-12-03 11:32:13.030583 | controller | >f+++++++++ ci-framework-data/logs/openstack-must-gather/timestamp
2025-12-03 11:32:13.030619 | controller | cd+++++++++ registries.conf.d/
2025-12-03 11:32:13.030664 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-03 11:32:13.030703 | controller | cd+++++++++ system-config/
2025-12-03 11:32:13.030780 | controller | cd+++++++++ system-config/libvirt/
2025-12-03 11:32:13.030828 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-03 11:32:13.030868 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-03 11:32:13.030908 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-12-03 11:32:13.030946 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-12-03 11:32:13.030983 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-12-03 11:32:13.031020 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-12-03 11:32:13.031056 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-12-03 11:32:13.031092 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-12-03 11:32:13.031128 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-12-03 11:32:13.031164 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-12-03 11:32:13.031199 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-12-03 11:32:13.031235 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-12-03 11:32:13.031271 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-12-03 11:32:13.031308 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-12-03 11:32:13.031369 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-12-03 11:32:13.031412 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-12-03 11:32:13.674902 | controller | changed: .d..t...... ./
2025-12-03 11:32:14.328029 | controller | changed: .d..t...... ./
2025-12-03 11:32:14.467587 | 
2025-12-03 11:32:14.467713 | TASK [Return artifact to Zuul]
2025-12-03 11:32:14.503107 | controller | skipping: Conditional result was False
2025-12-03 11:32:14.691199 | 
2025-12-03 11:32:14.691300 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-03 11:32:14.691392 | 
2025-12-03 11:32:14.691418 | PLAY RECAP
2025-12-03 11:32:14.691454 | controller | ok: 15 changed: 11 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 1
2025-12-03 11:32:14.691474 | 
2025-12-03 11:32:14.831837 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-03 11:32:14.839503 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-12-03 11:32:15.444339 | 
2025-12-03 11:32:15.444476 | PLAY [Report DLRN results after job run]
2025-12-03 11:32:15.541215 | 
2025-12-03 11:32:15.541350 | TASK [Report job status to DLRN]
2025-12-03 11:32:15.609817 | controller | ok
2025-12-03 11:32:15.677650 | 
2025-12-03 11:32:15.677828 | TASK [dlrn_report : Warning if DLRN is not running]
2025-12-03 11:32:15.722186 | controller | skipping: Conditional result was False
2025-12-03 11:32:15.751045 | 
2025-12-03 11:32:15.751172 | TASK [dlrn_report : Install dlrnapi-client shyaml package]
2025-12-03 11:32:15.795900 | controller | skipping: Conditional result was False
2025-12-03 11:32:15.844496 | 
2025-12-03 11:32:15.844653 | TASK [dlrn_report : Install kinit related package]
2025-12-03 11:32:15.889961 | controller | skipping: Conditional result was False
2025-12-03 11:32:15.922075 | 
2025-12-03 11:32:15.922207 | TASK [dlrn_report : Install dlrn kerberos related packages]
2025-12-03 11:32:16.017686 | controller | skipping: Conditional result was False
2025-12-03 11:32:16.094615 | 
2025-12-03 11:32:16.094765 | TASK [Set zuul-log-path fact]
2025-12-03 11:32:16.139421 | controller | skipping: Conditional result was False
2025-12-03 11:32:16.170238 | 
2025-12-03 11:32:16.170371 | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used]
2025-12-03 11:32:16.215199 | controller | skipping: Conditional result was False
2025-12-03 11:32:16.245685 | 
2025-12-03 11:32:16.245833 | TASK [Get hash related data from repo_setup role]
2025-12-03 11:32:16.290587 | controller | skipping: Conditional result was False
2025-12-03 11:32:16.321532 | 
2025-12-03 11:32:16.321664 | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication]
2025-12-03 11:32:17.012138 | controller | skipping: Conditional result was False
2025-12-03 11:32:17.076390 | 
2025-12-03 11:32:17.076549 | TASK [dlrn_report : Set empty value for dlrnapi password]
2025-12-03 11:32:17.122029 | controller | skipping: Conditional result was False
2025-12-03 11:32:17.165356 | 
2025-12-03 11:32:17.165541 | TASK [dlrn_report : Report results to dlrn for the tested hash]
2025-12-03 11:32:17.851943 | controller | skipping: Conditional result was False
2025-12-03 11:32:18.047368 | 
2025-12-03 11:32:18.047478 | PLAY RECAP
2025-12-03 11:32:18.047517 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 0
2025-12-03 11:32:18.047536 | 
2025-12-03 11:32:18.150184 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-12-03 11:32:18.154153 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-03 11:32:18.818226 | 
2025-12-03 11:32:18.818354 | PLAY [all]
2025-12-03 11:32:18.910901 | 
2025-12-03 11:32:18.911051 | TASK [include_role : fetch-output]
2025-12-03 11:32:19.012784 | controller | ok
2025-12-03 11:32:19.080265 | 
2025-12-03 11:32:19.080421 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-03 11:32:19.175871 | controller | skipping: Conditional result was False
2025-12-03 11:32:19.228637 | 
2025-12-03 11:32:19.228815 | TASK [fetch-output : Set log path for single node]
2025-12-03 11:32:19.316669 | controller | ok
2025-12-03 11:32:19.350035 | 
2025-12-03 11:32:19.350153 | LOOP [fetch-output : Ensure local output dirs]
2025-12-03 11:32:19.877565 | controller -> localhost | ok: "/var/lib/zuul/builds/1ef2272f6752415189f0ab4b3dd34fdf/work/logs"
2025-12-03 11:32:20.154751 | controller -> localhost | ok: "/var/lib/zuul/builds/1ef2272f6752415189f0ab4b3dd34fdf/work/artifacts"
2025-12-03 11:32:20.406881 | controller -> localhost | ok: "/var/lib/zuul/builds/1ef2272f6752415189f0ab4b3dd34fdf/work/docs"
2025-12-03 11:32:20.474216 | 
2025-12-03 11:32:20.474338 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-03 11:32:21.315654 | controller | ok
2025-12-03 11:32:21.315937 | controller | ok: All items complete
2025-12-03 11:32:21.315975 | 
2025-12-03 11:32:22.028982 | controller | ok
2025-12-03 11:32:22.624423 | controller | ok
2025-12-03 11:32:22.749045 | 
2025-12-03 11:32:22.749179 | TASK [include_role : fetch-output-openshift]
2025-12-03 11:32:22.774369 | controller | skipping: Conditional result was False
2025-12-03 11:32:22.806574 | 
2025-12-03 11:32:22.806700 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-03 11:32:23.290307 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007447
2025-12-03 11:32:23.513163 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008640
2025-12-03 11:32:23.696999 | 
2025-12-03 11:32:23.697102 | PLAY [all]
2025-12-03 11:32:23.783084 | 
2025-12-03 11:32:23.783216 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-03 11:32:24.554750 | controller | changed
2025-12-03 11:32:24.732329 | 
2025-12-03 11:32:24.732441 | PLAY RECAP
2025-12-03 11:32:24.732495 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-03 11:32:24.732519 | 
2025-12-03 11:32:24.844604 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-03 11:32:24.847407 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-03 11:32:25.519511 | 
2025-12-03 11:32:25.519710 | PLAY [localhost]
2025-12-03 11:32:25.538595 | 
2025-12-03 11:32:25.538713 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-03 11:32:25.934037 | localhost | changed
2025-12-03 11:32:25.938929 | 
2025-12-03 11:32:25.939010 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-03 11:32:25.977167 | localhost | ok
2025-12-03 11:32:25.985359 | 
2025-12-03 11:32:25.985426 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-03 11:32:26.417219 | localhost | changed
2025-12-03 11:32:26.428134 | 
2025-12-03 11:32:26.428275 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-03 11:32:27.200354 | localhost | changed
2025-12-03 11:32:27.205473 | 
2025-12-03 11:32:27.205539 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-03 11:32:27.636288 | localhost | Identity added: /var/lib/zuul/builds/1ef2272f6752415189f0ab4b3dd34fdf/work/tmp/ansible.g8vz7ioy (/var/lib/zuul/builds/1ef2272f6752415189f0ab4b3dd34fdf/work/tmp/ansible.g8vz7ioy)
2025-12-03 11:32:27.636792 | localhost | ok: Runtime: 0:00:00.010520
2025-12-03 11:32:27.644766 | 
2025-12-03 11:32:27.644912 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-03 11:32:27.910972 | localhost | ok: Runtime: 0:00:00.006961
2025-12-03 11:32:27.916795 | 
2025-12-03 11:32:27.916919 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-03 11:32:27.966026 | localhost | changed
2025-12-03 11:32:27.970213 | 
2025-12-03 11:32:27.970277 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-03 11:32:28.376493 | localhost | changed
2025-12-03 11:32:28.412111 | 
2025-12-03 11:32:28.412240 | PLAY [localhost]
2025-12-03 11:32:28.430469 | 
2025-12-03 11:32:28.430572 | TASK [Generate bulk log download script]
2025-12-03 11:32:28.450945 | localhost | ok
2025-12-03 11:32:28.469265 | 
2025-12-03 11:32:28.470350 | TASK [local-log-download : Check API endpoint is defined]
2025-12-03 11:32:28.501935 | localhost | ok: All assertions passed
2025-12-03 11:32:28.508482 | 
2025-12-03 11:32:28.508571 | TASK [local-log-download : Create download script]
2025-12-03 11:32:28.998000 | localhost -> localhost | changed
2025-12-03 11:32:29.045340 | 
2025-12-03 11:32:29.045534 | TASK [Register quick-download link]
2025-12-03 11:32:29.075243 | localhost | ok
2025-12-03 11:32:29.090988 | 
2025-12-03 11:32:29.091070 | PLAY [logserver.rdoproject.org]
2025-12-03 11:32:29.101166 | 
2025-12-03 11:32:29.101235 | TASK [Set zuul-log-path fact]
2025-12-03 11:32:29.118616 | logserver.rdoproject.org | ok
2025-12-03 11:32:29.128175 | 
2025-12-03 11:32:29.128243 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-03 11:32:29.154367 | logserver.rdoproject.org | ok
2025-12-03 11:32:29.159997 | 
2025-12-03 11:32:29.160063 | TASK [upload-logs : Create log directories]
2025-12-03 11:32:30.052250 | logserver.rdoproject.org | changed
2025-12-03 11:32:30.060103 | 
2025-12-03 11:32:30.060282 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-03 11:32:30.449658 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.007228
2025-12-03 11:32:30.455512 | 
2025-12-03 11:32:30.455589 | TASK [upload-logs : Upload logs to log server]
2025-12-03 11:32:31.203164 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-03 11:32:31.206252 | 
2025-12-03 11:32:31.206326 | LOOP [upload-logs : Compress console log and json output]
2025-12-03 11:32:31.273818 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 11:32:31.274540 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 11:32:31.286281 | 
2025-12-03 11:32:31.286410 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-03 11:32:31.325058 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 11:32:31.325308 | 
2025-12-03 11:32:31.329465 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 11:32:31.334642 | 
2025-12-03 11:32:31.334914 | LOOP [upload-logs : Upload console log and json output]