2025-10-13 14:52:47.531107 | Job console starting...
2025-10-13 14:52:47.543553 | Updating repositories
2025-10-13 14:52:47.608331 | Preparing job workspace
2025-10-13 14:52:55.521371 | Running Ansible setup...
2025-10-13 14:53:01.356340 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 14:53:02.158610 | 
2025-10-13 14:53:02.158755 | PLAY [localhost]
2025-10-13 14:53:02.167914 | 
2025-10-13 14:53:02.168003 | TASK [Gathering Facts]
2025-10-13 14:53:03.440995 | localhost | ok
2025-10-13 14:53:03.464373 | 
2025-10-13 14:53:03.464533 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-13 14:53:03.983233 | localhost -> localhost | changed
2025-10-13 14:53:03.992055 | 
2025-10-13 14:53:03.992194 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-13 14:53:05.893323 | localhost -> localhost | changed
2025-10-13 14:53:05.904970 | 
2025-10-13 14:53:05.905106 | TASK [Setup log path fact]
2025-10-13 14:53:05.932809 | localhost | ok
2025-10-13 14:53:05.952782 | 
2025-10-13 14:53:05.952907 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 14:53:06.011546 | localhost | ok
2025-10-13 14:53:06.019259 | 
2025-10-13 14:53:06.019328 | TASK [emit-job-header : Print job information]
2025-10-13 14:53:06.100459 | # Job Information
2025-10-13 14:53:06.100626 | Ansible Version: 2.15.12
2025-10-13 14:53:06.100655 | Job: cifmw-molecule-ci_nmstate
2025-10-13 14:53:06.100674 | Pipeline: github-check
2025-10-13 14:53:06.100691 | Executor: ze03.softwarefactory-project.io
2025-10-13 14:53:06.100708 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3402
2025-10-13 14:53:06.100750 | Log URL (when completed): https://logserver.rdoproject.org/fd7/rdoproject.org/fd72f1893f98443e9035efa49fd2a1be/
2025-10-13 14:53:06.100775 | Event ID: 03ccc7c0-a844-11f0-9fcd-d5792746e07c
2025-10-13 14:53:06.104878 | 
2025-10-13 14:53:06.104953 | LOOP [emit-job-header : Print node information]
2025-10-13 14:53:06.261283 | localhost | ok:
2025-10-13 14:53:06.261550 | localhost | # Node Information
2025-10-13 14:53:06.261589 | localhost | Inventory Hostname: controller
2025-10-13 14:53:06.261621 | localhost | Hostname: np0005485317
2025-10-13 14:53:06.261650 | localhost | Username: zuul
2025-10-13 14:53:06.261678 | localhost | Distro: CentOS 9
2025-10-13 14:53:06.261704 | localhost | Provider: vexxhost-nodepool-tripleo
2025-10-13 14:53:06.261745 | localhost | Region: RegionOne
2025-10-13 14:53:06.261776 | localhost | Label: centos-9-stream-crc-2-48-0-xxl
2025-10-13 14:53:06.261803 | localhost | Product Name: OpenStack Nova
2025-10-13 14:53:06.261828 | localhost | Interface IP: 38.129.56.11
2025-10-13 14:53:06.302149 | 
2025-10-13 14:53:06.302233 | PLAY [all]
2025-10-13 14:53:06.320100 | 
2025-10-13 14:53:06.320200 | TASK [Gather network facts]
2025-10-13 14:53:06.914755 | controller | ok
2025-10-13 14:53:06.938443 | 
2025-10-13 14:53:06.938551 | TASK [include_role : start-zuul-console]
2025-10-13 14:53:06.958104 | controller | ok
2025-10-13 14:53:06.981697 | 
2025-10-13 14:53:06.981841 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-13 14:53:07.526660 | controller | ok
2025-10-13 14:53:07.562033 | 
2025-10-13 14:53:07.562256 | TASK [include_role : add-build-sshkey]
2025-10-13 14:53:07.601988 | controller | ok
2025-10-13 14:53:07.618581 | 
2025-10-13 14:53:07.618708 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-13 14:53:08.050060 | controller -> localhost | ok
2025-10-13 14:53:08.058329 | 
2025-10-13 14:53:08.058455 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-13 14:53:08.078404 | controller | ok
2025-10-13 14:53:08.098869 | controller | included: /var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-13 14:53:08.105937 | 
2025-10-13 14:53:08.106040 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-13 14:53:09.276189 | controller -> localhost | Generating public/private rsa key pair.
2025-10-13 14:53:09.276416 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/work/fd72f1893f98443e9035efa49fd2a1be_id_rsa.
2025-10-13 14:53:09.276450 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/work/fd72f1893f98443e9035efa49fd2a1be_id_rsa.pub.
2025-10-13 14:53:09.276473 | controller -> localhost | The key fingerprint is:
2025-10-13 14:53:09.276495 | controller -> localhost | SHA256:fCuL2oG8p0l1Ij8tjczkQGiVcushGnlFktQPGbOTqvI zuul-build-sshkey
2025-10-13 14:53:09.276514 | controller -> localhost | The key's randomart image is:
2025-10-13 14:53:09.276533 | controller -> localhost | +---[RSA 3072]----+
2025-10-13 14:53:09.276552 | controller -> localhost | | .o+=+           |
2025-10-13 14:53:09.276570 | controller -> localhost | |  o+B+           |
2025-10-13 14:53:09.276588 | controller -> localhost | | .o+=+           |
2025-10-13 14:53:09.276607 | controller -> localhost | |o.ooo...         |
2025-10-13 14:53:09.276625 | controller -> localhost | | +.oo.+ S .      |
2025-10-13 14:53:09.276643 | controller -> localhost | |.. ..@ * . .     |
2025-10-13 14:53:09.276660 | controller -> localhost | |o   + X + .      |
2025-10-13 14:53:09.276678 | controller -> localhost | |.. . +.= o       |
2025-10-13 14:53:09.276696 | controller -> localhost | |  E =+o .        |
2025-10-13 14:53:09.276713 | controller -> localhost | +----[SHA256]-----+
2025-10-13 14:53:09.276782 | controller -> localhost | ok: Runtime: 0:00:00.316849
2025-10-13 14:53:09.283160 | 
2025-10-13 14:53:09.283262 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-13 14:53:09.337837 | controller | ok
2025-10-13 14:53:09.401657 | controller | included: /var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-13 14:53:09.452452 | 
2025-10-13 14:53:09.452570 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-13 14:53:09.533382 | controller | skipping: Conditional result was False
2025-10-13 14:53:09.539619 | 
2025-10-13 14:53:09.539783 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-13 14:53:10.449693 | controller | changed
2025-10-13 14:53:10.455493 | 
2025-10-13 14:53:10.455612 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-13 14:53:10.937412 | controller | ok
2025-10-13 14:53:10.965484 | 
2025-10-13 14:53:10.965631 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-13 14:53:12.663614 | controller | changed
2025-10-13 14:53:12.669919 | 
2025-10-13 14:53:12.670041 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-13 14:53:14.340548 | controller | changed
2025-10-13 14:53:14.359226 | 
2025-10-13 14:53:14.359356 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-13 14:53:14.414197 | controller | skipping: Conditional result was False
2025-10-13 14:53:14.419903 | 
2025-10-13 14:53:14.419979 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-13 14:53:15.098076 | controller -> localhost | changed
2025-10-13 14:53:15.112279 | 
2025-10-13 14:53:15.112410 | TASK [add-build-sshkey : Add back temp key]
2025-10-13 14:53:15.674623 | controller -> localhost | Identity added: /var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/work/fd72f1893f98443e9035efa49fd2a1be_id_rsa (zuul-build-sshkey)
2025-10-13 14:53:15.674836 | controller -> localhost | ok: Runtime: 0:00:00.009005
2025-10-13 14:53:15.680125 | 
2025-10-13 14:53:15.680189 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-13 14:53:16.181968 | controller | ok
2025-10-13 14:53:16.214710 | 
2025-10-13 14:53:16.214882 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-13 14:53:16.286231 | controller | skipping: Conditional result was False
2025-10-13 14:53:16.313135 | 
2025-10-13 14:53:16.313283 | TASK [include_role : validate-host]
2025-10-13 14:53:16.390246 | controller | ok
2025-10-13 14:53:16.444914 | 
2025-10-13 14:53:16.445045 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-13 14:53:16.521472 | controller | ok
2025-10-13 14:53:16.528581 | 
2025-10-13 14:53:16.528671 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-13 14:53:17.171467 | controller -> localhost | ok
2025-10-13 14:53:17.177679 | 
2025-10-13 14:53:17.177779 | TASK [validate-host : Collect information about the host]
2025-10-13 14:53:18.090918 | controller | ok
2025-10-13 14:53:18.111203 | 
2025-10-13 14:53:18.111330 | TASK [validate-host : Sanitize hostname]
2025-10-13 14:53:18.168947 | controller | ok
2025-10-13 14:53:18.175501 | 
2025-10-13 14:53:18.175628 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-13 14:53:18.868821 | controller -> localhost | changed
2025-10-13 14:53:18.874836 | 
2025-10-13 14:53:18.874906 | TASK [validate-host : Collect information about zuul worker]
2025-10-13 14:53:19.466362 | controller | ok
2025-10-13 14:53:19.482771 | 
2025-10-13 14:53:19.482901 | TASK [validate-host : Write out all zuul information for each host]
2025-10-13 14:53:20.400860 | controller -> localhost | changed
2025-10-13 14:53:20.419300 | 
2025-10-13 14:53:20.419441 | TASK [include_role : prepare-workspace-openshift]
2025-10-13 14:53:20.448992 | controller | skipping: Conditional result was False
2025-10-13 14:53:20.457477 | 
2025-10-13 14:53:20.457617 | TASK [include_role : remove-zuul-sshkey]
2025-10-13 14:53:20.483889 | controller | skipping: Conditional result was False
2025-10-13 14:53:20.491760 | 
2025-10-13 14:53:20.491888 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-13 14:53:20.863065 | controller | ok: "logs"
2025-10-13 14:53:20.863277 | controller | ok: All items complete
2025-10-13 14:53:20.863309 | 
2025-10-13 14:53:21.145692 | controller | ok: "artifacts"
2025-10-13 14:53:21.448530 | controller | ok: "docs"
2025-10-13 14:53:21.458804 | 
2025-10-13 14:53:21.458907 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-13 14:53:21.767921 | controller | changed: "logs"
2025-10-13 14:53:22.095919 | controller | changed: "artifacts"
2025-10-13 14:53:22.401227 | controller | changed: "docs"
2025-10-13 14:53:22.445475 | 
2025-10-13 14:53:22.445597 | PLAY RECAP
2025-10-13 14:53:22.445652 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 14:53:22.445689 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 14:53:22.445717 | 
2025-10-13 14:53:22.620370 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 14:53:22.621188 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 14:53:23.498175 | 
2025-10-13 14:53:23.498300 | PLAY [all]
2025-10-13 14:53:23.527003 | 
2025-10-13 14:53:23.527154 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-13 14:53:23.587109 | controller | ok
2025-10-13 14:53:23.592801 | 
2025-10-13 14:53:23.592884 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-13 14:53:24.118779 | controller | changed
2025-10-13 14:53:24.133790 | 
2025-10-13 14:53:24.133958 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-13 14:53:25.822838 | controller | changed
2025-10-13 14:53:25.843233 | 
2025-10-13 14:53:25.843359 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-13 14:53:26.436844 | controller | changed:
2025-10-13 14:53:26.437008 | controller | {
2025-10-13 14:53:26.437055 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-13 14:53:26.437087 | controller | }
2025-10-13 14:53:26.914780 | controller | changed:
2025-10-13 14:53:26.914881 | controller | {
2025-10-13 14:53:26.914906 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-13 14:53:26.914926 | controller | }
2025-10-13 14:53:27.284111 | controller | changed:
2025-10-13 14:53:27.284208 | controller | {
2025-10-13 14:53:27.284233 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-13 14:53:27.284255 | controller | }
2025-10-13 14:53:27.659466 | controller | changed:
2025-10-13 14:53:27.659622 | controller | {
2025-10-13 14:53:27.659652 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-13 14:53:27.659671 | controller | }
2025-10-13 14:53:28.035166 | controller | changed:
2025-10-13 14:53:28.035300 | controller | {
2025-10-13 14:53:28.035338 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-13 14:53:28.035366 | controller | }
2025-10-13 14:53:28.438142 | controller | changed:
2025-10-13 14:53:28.439796 | controller | {
2025-10-13 14:53:28.439879 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-13 14:53:28.439904 | controller | }
2025-10-13 14:53:28.830992 | controller | changed:
2025-10-13 14:53:28.831113 | controller | {
2025-10-13 14:53:28.831141 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-13 14:53:28.831163 | controller | }
2025-10-13 14:53:29.209081 | controller | changed:
2025-10-13 14:53:29.209181 | controller | {
2025-10-13 14:53:29.209205 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-13 14:53:29.209225 | controller | }
2025-10-13 14:53:29.580418 | controller | changed:
2025-10-13 14:53:29.580542 | controller | {
2025-10-13 14:53:29.580566 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-13 14:53:29.580583 | controller | }
2025-10-13 14:53:30.002274 | controller | changed:
2025-10-13 14:53:30.002373 | controller | {
2025-10-13 14:53:30.002401 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-13 14:53:30.002423 | controller | }
2025-10-13 14:53:30.370872 | controller | changed:
2025-10-13 14:53:30.370969 | controller | {
2025-10-13 14:53:30.370993 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-13 14:53:30.371012 | controller | }
2025-10-13 14:53:30.762332 | controller | changed:
2025-10-13 14:53:30.762418 | controller | {
2025-10-13 14:53:30.762442 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-13 14:53:30.762462 | controller | }
2025-10-13 14:53:31.189479 | controller | changed:
2025-10-13 14:53:31.193026 | controller | {
2025-10-13 14:53:31.193057 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-13 14:53:31.193077 | controller | }
2025-10-13 14:53:31.584805 | controller | changed:
2025-10-13 14:53:31.584904 | controller | {
2025-10-13 14:53:31.584928 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-13 14:53:31.584947 | controller | }
2025-10-13 14:53:31.990832 | controller | changed:
2025-10-13 14:53:31.990917 | controller | {
2025-10-13 14:53:31.990942 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-13 14:53:31.990962 | controller | }
2025-10-13 14:53:32.425279 | controller | changed:
2025-10-13 14:53:32.425544 | controller | {
2025-10-13 14:53:32.425571 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-13 14:53:32.425590 | controller | }
2025-10-13 14:53:32.829869 | controller | changed:
2025-10-13 14:53:32.829974 | controller | {
2025-10-13 14:53:32.829999 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-13 14:53:32.830019 | controller | }
2025-10-13 14:53:33.201956 | controller | changed:
2025-10-13 14:53:33.202135 | controller | {
2025-10-13 14:53:33.202169 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-13 14:53:33.202196 | controller | }
2025-10-13 14:53:33.577015 | controller | changed:
2025-10-13 14:53:33.577144 | controller | {
2025-10-13 14:53:33.577172 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-13 14:53:33.577194 | controller | }
2025-10-13 14:53:34.022705 | controller | changed:
2025-10-13 14:53:34.022926 | controller | {
2025-10-13 14:53:34.022960 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-13 14:53:34.022980 | controller | }
2025-10-13 14:53:34.412856 | controller | changed:
2025-10-13 14:53:34.412973 | controller | {
2025-10-13 14:53:34.413002 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-13 14:53:34.413020 | controller | }
2025-10-13 14:53:34.817985 | controller | changed:
2025-10-13 14:53:34.818093 | controller | {
2025-10-13 14:53:34.818117 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-13 14:53:34.818135 | controller | }
2025-10-13 14:53:35.229029 | controller | changed:
2025-10-13 14:53:35.229146 | controller | {
2025-10-13 14:53:35.229174 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-13 14:53:35.229196 | controller | }
2025-10-13 14:53:35.622652 | controller | changed:
2025-10-13 14:53:35.623049 | controller | {
2025-10-13 14:53:35.623085 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-13 14:53:35.623110 | controller | }
2025-10-13 14:53:36.039186 | controller | changed:
2025-10-13 14:53:36.039263 | controller | {
2025-10-13 14:53:36.039287 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-13 14:53:36.039306 | controller | }
2025-10-13 14:53:36.400600 | controller | changed:
2025-10-13 14:53:36.400829 | controller | {
2025-10-13 14:53:36.400860 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-13 14:53:36.400881 | controller | }
2025-10-13 14:53:36.436418 | 
2025-10-13 14:53:36.436539 | TASK [Set timezone to UTC]
2025-10-13 14:53:37.295995 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-13 14:53:37.302089 | 
2025-10-13 14:53:37.302173 | TASK [Create nodepool directory]
2025-10-13 14:53:37.740884 | controller | changed
2025-10-13 14:53:37.747017 | 
2025-10-13 14:53:37.747080 | TASK [Create nodepool sub_nodes file]
2025-10-13 14:53:39.263093 | controller | changed
2025-10-13 14:53:39.268804 | 
2025-10-13 14:53:39.268907 | TASK [Create nodepool sub_nodes_private file]
2025-10-13 14:53:40.656895 | controller | changed
2025-10-13 14:53:40.662552 | 
2025-10-13 14:53:40.662649 | LOOP [Populate nodepool sub_nodes file]
2025-10-13 14:53:40.738291 | 
2025-10-13 14:53:40.738438 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-13 14:53:40.799396 | 
2025-10-13 14:53:40.799561 | TASK [Create nodepool primary file]
2025-10-13 14:53:40.851804 | controller | skipping: Conditional result was False
2025-10-13 14:53:40.858587 | 
2025-10-13 14:53:40.858748 | TASK [Create nodepool node_private for this node]
2025-10-13 14:53:42.289705 | controller | changed
2025-10-13 14:53:42.307110 | 
2025-10-13 14:53:42.307543 | LOOP [Copy ssh keys to nodepool directory]
2025-10-13 14:53:42.844987 | controller | ok: Item: id_rsa Runtime: 0:00:00.008435
2025-10-13 14:53:42.845210 | 
2025-10-13 14:53:43.197197 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.007389
2025-10-13 14:53:43.204561 | 
2025-10-13 14:53:43.204691 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-13 14:53:44.743629 | controller | changed
2025-10-13 14:53:44.757485 | 
2025-10-13 14:53:44.757620 | TASK [Validate sudoers config after edits]
2025-10-13 14:53:45.121231 | controller | /etc/sudoers: parsed OK
2025-10-13 14:53:45.121378 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-13 14:53:45.121402 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-13 14:53:45.121444 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-13 14:53:45.329924 | controller | ok: Runtime: 0:00:00.008666
2025-10-13 14:53:45.336156 | 
2025-10-13 14:53:45.336323 | TASK [Show the environment passed in to job shell scripts]
2025-10-13 14:53:45.751587 | controller | SHELL=/bin/bash
2025-10-13 14:53:45.751674 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-13 14:53:45.751695 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-13 14:53:45.751711 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/04/3404/9f142a3066b32ef704ae4f0d5fc5f7189e41a1f8^openstack-k8s-operators/ci-framework:main:refs/changes/02/3402/d28093521bfef49de97fe37ed681e49283c4131e
2025-10-13 14:53:45.751803 | controller | PWD=/home/zuul
2025-10-13 14:53:45.751820 | controller | ZUUL_PIPELINE=github-check
2025-10-13 14:53:45.751834 | controller | LOGNAME=zuul
2025-10-13 14:53:45.751847 | controller | XDG_SESSION_TYPE=tty
2025-10-13 14:53:45.751860 | controller | _=/usr/bin/env
2025-10-13 14:53:45.751873 | controller | MOTD_SHOWN=pam
2025-10-13 14:53:45.751887 | controller | HOME=/home/zuul
2025-10-13 14:53:45.751899 | controller | LANG=en_US.UTF-8
2025-10-13 14:53:45.751913 | controller | SSH_CONNECTION=38.102.83.114 44434 38.129.56.11 22
2025-10-13 14:53:45.751926 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-13 14:53:45.751940 | controller | ZUUL_CHANGE_IDS=3404,9f142a3066b32ef704ae4f0d5fc5f7189e41a1f8 3402,d28093521bfef49de97fe37ed681e49283c4131e
2025-10-13 14:53:45.751953 | controller | WORKSPACE=/home/zuul/workspace
2025-10-13 14:53:45.751972 | controller | XDG_SESSION_CLASS=user
2025-10-13 14:53:45.751986 | controller | SELINUX_ROLE_REQUESTED=
2025-10-13 14:53:45.751999 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-13 14:53:45.752012 | controller | USER=zuul
2025-10-13 14:53:45.752025 | controller | ZUUL_VOTING=True
2025-10-13 14:53:45.752038 | controller | BUILD_TIMEOUT=1800000
2025-10-13 14:53:45.752050 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-13 14:53:45.752063 | controller | SHLVL=1
2025-10-13 14:53:45.752077 | controller | ZUUL_PATCHSET=d28093521bfef49de97fe37ed681e49283c4131e
2025-10-13 14:53:45.752090 | controller | XDG_SESSION_ID=1
2025-10-13 14:53:45.752103 | controller | ZUUL_BRANCH=main
2025-10-13 14:53:45.752116 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-13 14:53:45.752129 | controller | SSH_CLIENT=38.102.83.114 44434 22
2025-10-13 14:53:45.752143 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-13 14:53:45.752156 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-13 14:53:45.752169 | controller | which_declare=declare -f
2025-10-13 14:53:45.752183 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-13 14:53:45.752196 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-13 14:53:45.752209 | controller | ZUUL_CHANGE=3402
2025-10-13 14:53:45.752222 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-13 14:53:45.752235 | controller | ZUUL_UUID=fd72f1893f98443e9035efa49fd2a1be
2025-10-13 14:53:45.752248 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-13 14:53:45.752261 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-13 14:53:45.752274 | controller | }
2025-10-13 14:53:45.920640 | controller | ok: Runtime: 0:00:00.009262
2025-10-13 14:53:45.927334 | 
2025-10-13 14:53:45.927431 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-13 14:53:45.992595 | controller | skipping: Conditional result was False
2025-10-13 14:53:46.000364 | 
2025-10-13 14:53:46.000480 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-13 14:53:46.597778 | controller | skipping: Conditional result was False
2025-10-13 14:53:46.612906 | 
2025-10-13 14:53:46.613695 | TASK [Ensure legacy workspace directory]
2025-10-13 14:53:46.962847 | controller | changed
2025-10-13 14:53:46.984230 | 
2025-10-13 14:53:46.984353 | PLAY RECAP
2025-10-13 14:53:46.984399 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 14:53:46.984420 | 
2025-10-13 14:53:47.114460 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 14:53:47.115308 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-10-13 14:53:47.746392 | 
2025-10-13 14:53:47.746517 | PLAY [all,!compute]
2025-10-13 14:53:47.767242 | 
2025-10-13 14:53:47.767360 | TASK [Enable container-tools rhel-modules for c8/crc node]
2025-10-13 14:53:48.301213 | controller | skipping: Conditional result was False
2025-10-13 14:53:48.316105 | 
2025-10-13 14:53:48.316335 | TASK [Install podman]
2025-10-13 14:53:48.367273 | controller | skipping: Conditional result was False
2025-10-13 14:53:48.376918 | 
2025-10-13 14:53:48.377024 | TASK [Set var name for quay login zuul secret]
2025-10-13 14:53:48.430148 | controller | skipping: Conditional result was False
2025-10-13 14:53:48.438639 | 
2025-10-13 14:53:48.438754 | TASK [Print the username]
2025-10-13 14:53:48.986783 | controller | skipping: Conditional result was False
2025-10-13 14:53:48.996088 | 
2025-10-13 14:53:48.996550 | TASK [Fail when user or password is not set]
2025-10-13 14:53:49.064656 | controller | skipping: Conditional result was False
2025-10-13 14:53:49.074813 | 
2025-10-13 14:53:49.075100 | TASK [Set vars for quay login]
2025-10-13 14:53:49.147042 | 
2025-10-13 14:53:49.147342 | LOOP [Perform container registry login(s) with podman]
2025-10-13 14:53:49.214465 | 
2025-10-13 14:53:49.214695 | LOOP [Perform container registry login(s) with buildah]
2025-10-13 14:53:49.282899 | 
2025-10-13 14:53:49.283190 | TASK [Set Insecure registry for content provider]
2025-10-13 14:53:49.350867 | controller | skipping: Conditional result was False
2025-10-13 14:53:49.386115 | 
2025-10-13 14:53:49.386244 | PLAY RECAP
2025-10-13 14:53:49.386299 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 9 rescued: 0 ignored: 0
2025-10-13 14:53:49.386326 | 
2025-10-13 14:53:49.495823 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-10-13 14:53:49.496642 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-10-13 14:53:50.154464 | 
2025-10-13 14:53:50.154588 | PLAY [Prefer ipv4 over ipv6]
2025-10-13 14:53:50.176417 | 
2025-10-13 14:53:50.176562 | TASK [Configure /etc/gai.conf to prever ipv4 over ipv6]
2025-10-13 14:53:50.242473 | controller | skipping: Conditional result was False
2025-10-13 14:53:50.270427 | 
2025-10-13 14:53:50.270586 | PLAY [controller]
2025-10-13 14:53:50.286559 | 
2025-10-13 14:53:50.286697 | TASK [Fail when openshift_pull_sec not provided]
2025-10-13 14:53:50.311926 | controller | skipping: Conditional result was False
2025-10-13 14:53:50.318248 | 
2025-10-13 14:53:50.318314 | TASK [Clone repos in the job workspace]
2025-10-13 14:53:50.337454 | controller | ok
2025-10-13 14:53:50.354564 | 
2025-10-13 14:53:50.354677 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-13 14:53:50.830374 | controller | ok
2025-10-13 14:53:50.843571 | 
2025-10-13 14:53:50.843747 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-13 14:53:56.108256 | controller | Output suppressed because no_log was given
2025-10-13 14:53:56.121042 | 
2025-10-13 14:53:56.121176 | TASK [Create pull-secret.txt file]
2025-10-13 14:53:57.685101 | controller | Output suppressed because no_log was given
2025-10-13 14:53:57.690239 | 
2025-10-13 14:53:57.690350 | TASK [Apply CRC features and print image build date]
2025-10-13 14:53:57.715559 | controller | ok
2025-10-13 14:53:57.723467 | controller | included: /var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/trusted/project_0/review.rdoproject.org/config/playbooks/crc/features.yaml
2025-10-13 14:53:57.731365 | 
2025-10-13 14:53:57.731490 | TASK [Set additional parameters for crc - telemetry]
2025-10-13 14:53:58.262208 | controller | skipping: Conditional result was False
2025-10-13 14:53:58.268112 | 
2025-10-13 14:53:58.268191 | TASK [Set additional parameters for crc - monitoring]
2025-10-13 14:53:58.806559 | controller | skipping: Conditional result was False
2025-10-13 14:53:58.819706 | 
2025-10-13 14:53:58.820347 | TASK [Set minimum required memory to run the CRC when monitoring enabled]
2025-10-13 14:53:59.396387 | controller | skipping: Conditional result was False
2025-10-13 14:53:59.413841 | 
2025-10-13 14:53:59.414030 | TASK [Set additional parameters for crc - enable microshift preset]
2025-10-13 14:53:59.965843 | controller | skipping: Conditional result was False
2025-10-13 14:53:59.972839 | 
2025-10-13 14:53:59.972956 | TASK [Set additional parameters for crc - enable okd preset]
2025-10-13 14:54:00.544953 | controller | skipping: Conditional result was False
2025-10-13 14:54:00.557454 | 
2025-10-13 14:54:00.557629 | TASK [Delete the cluster if there's a non-default bundle URL]
2025-10-13 14:54:01.108256 | controller | skipping: Conditional result was False
2025-10-13 14:54:01.120836 | 
2025-10-13 14:54:01.121025 | TASK [Execute crc start command]
2025-10-13 14:57:12.909554 | controller | ok: Runtime: 0:03:11.082761
2025-10-13 14:57:12.916203 | 
2025-10-13 14:57:12.916277 | TASK [Verify that OpenShift is up and running]
2025-10-13 14:57:12.936902 | controller | ok
2025-10-13 14:57:12.958405 | controller | included: /var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/trusted/project_0/review.rdoproject.org/config/playbooks/crc/verify_openshift_start.yaml
2025-10-13 14:57:12.975292 | 
2025-10-13 14:57:12.975458 | TASK [Create script to login and verify services]
2025-10-13 14:57:14.262304 | controller | changed
2025-10-13 14:57:14.268571 | 
2025-10-13 14:57:14.268660 | TASK [Ensure that user is logged]
2025-10-13 14:57:14.621009 | controller | Checking login to the cluster 1...
2025-10-13 14:57:15.665289 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:57:15.742750 | controller |
2025-10-13 14:57:15.742837 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-13 14:57:15.748246 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:57:20.750525 | controller | Checking login to the cluster 2...
2025-10-13 14:57:20.875372 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:57:20.899088 | controller |
2025-10-13 14:57:20.899188 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-13 14:57:20.904614 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:57:25.906716 | controller | Checking login to the cluster 3...
2025-10-13 14:57:26.051462 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:57:26.071875 | controller |
2025-10-13 14:57:26.071956 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-13 14:57:26.076825 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:57:31.078932 | controller | Checking login to the cluster 4...
2025-10-13 14:57:31.199142 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:57:31.218517 | controller |
2025-10-13 14:57:31.218609 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-13 14:57:31.222997 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:57:36.228289 | controller | Checking login to the cluster 5...
2025-10-13 14:57:36.369055 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:57:36.390129 | controller |
2025-10-13 14:57:36.390232 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-13 14:57:36.395477 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:57:41.400052 | controller | Checking login to the cluster 6...
2025-10-13 14:57:41.525629 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:57:41.551490 | controller |
2025-10-13 14:57:41.551608 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-13 14:57:41.563732 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:57:46.566396 | controller | Checking login to the cluster 7...
2025-10-13 14:57:46.688733 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:57:46.713841 | controller |
2025-10-13 14:57:46.713944 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-13 14:57:46.721463 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:57:51.724086 | controller | Checking login to the cluster 8...
2025-10-13 14:57:51.835641 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:57:51.852287 | controller |
2025-10-13 14:57:51.852383 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-13 14:57:51.857290 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:57:56.859600 | controller | Checking login to the cluster 9...
2025-10-13 14:57:56.980188 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:57:57.009499 | controller |
2025-10-13 14:57:57.009581 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-13 14:57:57.016880 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:58:02.019594 | controller | Checking login to the cluster 10...
2025-10-13 14:58:02.165018 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:58:02.180832 | controller |
2025-10-13 14:58:02.180887 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-13 14:58:02.185219 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:58:07.188254 | controller | Checking login to the cluster 11...
2025-10-13 14:58:07.346324 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:58:07.448351 | controller |
2025-10-13 14:58:07.448438 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-13 14:58:07.454409 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:58:12.457633 | controller | Checking login to the cluster 12...
2025-10-13 14:58:12.682996 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:58:12.850083 | controller |
2025-10-13 14:58:12.850176 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-13 14:58:12.855981 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:58:17.859696 | controller | Checking login to the cluster 13...
2025-10-13 14:58:18.027942 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:58:18.209317 | controller |
2025-10-13 14:58:18.209414 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-13 14:58:18.214525 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:58:23.221005 | controller | Checking login to the cluster 14...
2025-10-13 14:58:23.392436 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:58:23.536921 | controller |
2025-10-13 14:58:23.537014 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-13 14:58:23.553173 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:58:28.558652 | controller | Checking login to the cluster 15...
2025-10-13 14:58:28.737392 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:58:28.871746 | controller |
2025-10-13 14:58:28.871851 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-13 14:58:28.877124 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:58:33.881957 | controller | Checking login to the cluster 16...
2025-10-13 14:58:34.050929 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:58:34.181467 | controller |
2025-10-13 14:58:34.181563 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-13 14:58:34.189208 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:58:39.193599 | controller | Checking login to the cluster 17...
2025-10-13 14:58:39.762127 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:58:40.270238 | controller |
2025-10-13 14:58:40.270387 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-13 14:58:40.279760 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:58:45.283509 | controller | Checking login to the cluster 18...
2025-10-13 14:58:45.448188 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:58:45.552422 | controller |
2025-10-13 14:58:45.552520 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-13 14:58:45.558210 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:58:50.562641 | controller | Checking login to the cluster 19...
2025-10-13 14:58:50.763550 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:58:51.038847 | controller |
2025-10-13 14:58:51.038977 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-13 14:58:51.049088 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:58:56.053728 | controller | Checking login to the cluster 20...
2025-10-13 14:58:56.594464 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:58:56.799119 | controller |
2025-10-13 14:58:56.799253 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-13 14:58:56.814508 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:59:01.818096 | controller | Checking login to the cluster 21...
2025-10-13 14:59:01.960953 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:59:01.998269 | controller |
2025-10-13 14:59:01.998395 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-13 14:59:02.005588 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:59:07.007945 | controller | Checking login to the cluster 22...
2025-10-13 14:59:07.123570 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:59:07.157472 | controller |
2025-10-13 14:59:07.157578 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-13 14:59:07.162235 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:59:12.164667 | controller | Checking login to the cluster 23...
2025-10-13 14:59:12.277432 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:59:12.441537 | controller |
2025-10-13 14:59:12.441613 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 400
2025-10-13 14:59:12.445798 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:59:17.449396 | controller | Checking login to the cluster 24...
2025-10-13 14:59:17.608402 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:59:17.663443 | controller |
2025-10-13 14:59:17.663535 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 400
2025-10-13 14:59:17.668727 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:59:22.671600 | controller | Checking login to the cluster 25...
2025-10-13 14:59:22.784856 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:59:22.849255 | controller |
2025-10-13 14:59:22.849343 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 400
2025-10-13 14:59:22.854256 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:59:27.857714 | controller | Checking login to the cluster 26...
2025-10-13 14:59:27.996347 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:59:28.102233 | controller |
2025-10-13 14:59:28.102332 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 400
2025-10-13 14:59:28.107645 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:59:33.110288 | controller | Checking login to the cluster 27...
2025-10-13 14:59:33.258969 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:59:33.331437 | controller |
2025-10-13 14:59:33.331506 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 400
2025-10-13 14:59:33.336527 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:59:38.339888 | controller | Checking login to the cluster 28...
2025-10-13 14:59:38.450222 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:59:38.494949 | controller |
2025-10-13 14:59:38.495039 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 400
2025-10-13 14:59:38.499832 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:59:43.502353 | controller | Checking login to the cluster 29...
2025-10-13 14:59:43.682755 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:59:43.878328 | controller |
2025-10-13 14:59:43.878432 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 400
2025-10-13 14:59:43.889043 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:59:48.892053 | controller | Checking login to the cluster 30...
2025-10-13 14:59:49.047884 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:59:49.262731 | controller |
2025-10-13 14:59:49.262822 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 400
2025-10-13 14:59:49.273035 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:59:54.277475 | controller | Checking login to the cluster 31...
2025-10-13 14:59:54.446386 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 14:59:54.568157 | controller |
2025-10-13 14:59:54.568243 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 400
2025-10-13 14:59:54.573714 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 14:59:59.577321 | controller | Checking login to the cluster 32...
2025-10-13 14:59:59.740403 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 15:00:06.071162 | controller |
2025-10-13 15:00:06.071256 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 500
2025-10-13 15:00:06.084527 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 15:00:11.088281 | controller | Checking login to the cluster 33...
2025-10-13 15:00:11.275544 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 15:00:17.204674 | controller |
2025-10-13 15:00:17.204746 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 500
2025-10-13 15:00:17.210716 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 15:00:22.213885 | controller | Checking login to the cluster 34...
2025-10-13 15:00:22.339894 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 15:00:27.603363 | controller |
2025-10-13 15:00:27.603449 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 500
2025-10-13 15:00:27.610172 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 15:00:32.612680 | controller | Checking login to the cluster 35...
2025-10-13 15:00:32.753649 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 15:00:37.954545 | controller |
2025-10-13 15:00:37.954718 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 500
2025-10-13 15:00:37.959856 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-13 15:00:42.962542 | controller | Checking login to the cluster 36...
2025-10-13 15:00:43.096828 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-13 15:00:48.798567 | controller |
2025-10-13 15:00:48.798653 | controller | Login successful.
2025-10-13 15:00:48.841614 | controller |
2025-10-13 15:00:48.841695 | controller | You have access to 65 projects, the list has been suppressed. You can list all projects with 'oc projects'
2025-10-13 15:00:48.868681 | controller |
2025-10-13 15:00:48.868740 | controller | Using project "default".
2025-10-13 15:00:49.062093 | controller | changed
2025-10-13 15:00:49.068937 | 
2025-10-13 15:00:49.069040 | TASK [Delete all openshift-marketplace pods]
2025-10-13 15:00:50.852408 | controller | pod "certified-operators-f8szs" deleted
2025-10-13 15:00:50.866205 | controller | pod "certified-operators-v4skg" deleted
2025-10-13 15:00:52.057869 | controller | pod "community-operators-5zsqg" deleted
2025-10-13 15:00:52.321056 | controller | pod "marketplace-operator-79b997595-d2lpl" deleted
2025-10-13 15:00:52.351934 | controller | pod "redhat-marketplace-ps6xk" deleted
2025-10-13 15:00:52.398159 | controller | pod "redhat-operators-qml94" deleted
2025-10-13 15:00:52.472419 | controller | pod "redhat-operators-tqxl9" deleted
2025-10-13 15:01:03.630982 | controller | ok
2025-10-13 15:01:03.643091 | 
2025-10-13 15:01:03.643294 | TASK [Copy script ensure_services_up.sh]
2025-10-13 15:01:03.691710 | controller | skipping: Conditional result was False
2025-10-13 15:01:03.700834 | 
2025-10-13 15:01:03.700987 | TASK [Ensure all pods are up and running]
2025-10-13 15:01:04.244519 | controller | skipping: Conditional result was False
2025-10-13 15:01:04.251877 | 
2025-10-13 15:01:04.251978 | TASK [Check if there is a image build date file]
2025-10-13 15:01:04.647559 | controller | ok
2025-10-13 15:01:04.660920 | 
2025-10-13 15:01:04.661072 | TASK [Print image date if available]
2025-10-13 15:01:05.009506 | controller | Tue Apr  1 06:17:46 PM EDT 2025
2025-10-13 15:01:05.211191 | controller | changed
2025-10-13 15:01:05.221273 | 
2025-10-13 15:01:05.221360 | TASK [Fix machineconfigpool]
2025-10-13 15:01:05.286194 | controller | skipping: Conditional result was False
2025-10-13 15:01:05.292226 | 
2025-10-13 15:01:05.292295 | TASK [Show available nodes]
2025-10-13 15:01:06.227060 | controller | NAME   STATUS   ROLES                         AGE    VERSION
2025-10-13 15:01:06.230380 | controller | crc    Ready    control-plane,master,worker   232d   v1.31.5
2025-10-13 15:01:06.325906 | controller | ok: Runtime: 0:00:00.611293
2025-10-13 15:01:06.331550 | 
2025-10-13 15:01:06.331612 | TASK [Configure insecure registry inside crc vm]
2025-10-13 15:01:06.356459 | controller | skipping: Conditional result was False
2025-10-13 15:01:06.365223 | 
2025-10-13 15:01:06.365354 | TASK [Catch CRC IP]
2025-10-13 15:01:07.005787 | controller | 192.168.130.11
2025-10-13 15:01:07.435225 | controller | changed
2025-10-13 15:01:07.453021 | 
2025-10-13 15:01:07.453276 | TASK [Check if id_ed25519 key exists]
2025-10-13 15:01:07.922959 | controller | ok
2025-10-13 15:01:07.945521 | 
2025-10-13 15:01:07.949098 | TASK [Set fact if new keypair exists]
2025-10-13 15:01:08.028986 | controller | ok
2025-10-13 15:01:08.037921 | 
2025-10-13 15:01:08.038038 | TASK [Run crc]
2025-10-13 15:01:09.729994 | controller |
2025-10-13 15:01:16.276229 | controller | PLAY [OpenShift secret copy and CRC securitization] ****************************
2025-10-13 15:01:16.276292 | controller |
2025-10-13 15:01:16.276316 | controller | TASK [Gathering Facts] *********************************************************
2025-10-13 15:01:16.276335 | controller | [WARNING]: Platform linux on host 192.168.130.11 is using the discovered Python
2025-10-13 15:01:16.317371 | controller | interpreter at /usr/bin/python3.9, but future installation of another Python
2025-10-13 15:01:16.317416 | controller | interpreter could change the meaning of that path. See
2025-10-13 15:01:16.317423 | controller | https://docs.ansible.com/ansible-
2025-10-13 15:01:16.317430 | controller | core/2.14/reference_appendices/interpreter_discovery.html for more information.
2025-10-13 15:01:16.317441 | controller | ok: [192.168.130.11]
2025-10-13 15:01:18.219019 | controller |
2025-10-13 15:01:18.219103 | controller | TASK [Copy pull-secret] ********************************************************
2025-10-13 15:01:18.219121 | controller | changed: [192.168.130.11]
2025-10-13 15:01:19.722458 | controller |
2025-10-13 15:01:19.722518 | controller | TASK [Add crio config] *********************************************************
2025-10-13 15:01:19.722532 | controller | changed: [192.168.130.11]
2025-10-13 15:01:23.286062 | controller |
2025-10-13 15:01:23.286126 | controller | TASK [Restart crio to pickup new configuration] ********************************
2025-10-13 15:01:23.286164 | controller | changed: [192.168.130.11]
2025-10-13 15:01:23.353674 | controller |
2025-10-13 15:01:23.353742 | controller | PLAY RECAP *********************************************************************
2025-10-13 15:01:23.353750 | controller | 192.168.130.11             : ok=4    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-13 15:01:23.353756 | controller |
2025-10-13 15:01:23.631328 | controller | changed
2025-10-13 15:01:23.641310 | 
2025-10-13 15:01:23.641476 | TASK [Set slower etcd profile]
2025-10-13 15:01:23.714020 | controller | ok
2025-10-13 15:01:23.751521 | 
2025-10-13 15:01:23.751650 | TASK [prepare-crc-cloud : Set slower profile]
2025-10-13 15:01:24.312608 | controller | etcd.operator.openshift.io/cluster patched
2025-10-13 15:01:24.787248 | controller | ok
2025-10-13 15:01:24.794743 | 
2025-10-13 15:01:24.794838 | TASK [prepare-crc-cloud : Wait 30 seconds to apply]
2025-10-13 15:01:54.836645 | controller | ok
2025-10-13 15:01:54.849794 | 
2025-10-13 15:01:54.849991 | TASK [prepare-crc-cloud : Get pod phase status]
2025-10-13 15:07:23.109183 | controller | ok
2025-10-13 15:07:23.135023 | 
2025-10-13 15:07:23.135228 | TASK [Set limit for disk usage - IOPS and Read/Write]
2025-10-13 15:07:23.191408 | controller | ok
2025-10-13 15:07:23.199107 | controller | included: /var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/trusted/project_0/review.rdoproject.org/config/playbooks/crc/limit-disk-usage.yaml
2025-10-13 15:07:23.210561 | 
2025-10-13 15:07:23.210694 | TASK [Set facts for disk limitation]
2025-10-13 15:07:23.272857 | controller | ok
2025-10-13 15:07:23.278208 | 
2025-10-13 15:07:23.278316 | TASK [Print limits for the disk]
2025-10-13 15:07:23.308805 | controller | ok: Set limit to crc VM to the vda disk: iops 18000 read/write 262144000
2025-10-13 15:07:23.314373 | 
2025-10-13 15:07:23.314488 | TASK [Set limit to crc VM to the vda disk]
2025-10-13 15:07:23.782350 | controller |
2025-10-13 15:07:23.849338 | controller | changed
2025-10-13 15:07:23.855425 | 
2025-10-13 15:07:23.855539 | TASK [Pause machineconfigpool]
2025-10-13 15:07:23.906552 | controller | ok
2025-10-13 15:07:23.932653 | 
2025-10-13 15:07:23.932795 | LOOP [prepare-crc-cloud : Pause the machineconfig configuration if mcp is not needed]
2025-10-13 15:07:24.563179 | controller | ok
2025-10-13 15:07:25.159484 | controller | ok
2025-10-13 15:07:25.178135 | 
2025-10-13 15:07:25.178343 | TASK [prepare-crc-cloud : Print current machineconfigpool]
2025-10-13 15:07:25.760769 | controller | NAME     CONFIG                                             UPDATED   UPDATING   DEGRADED   MACHINECOUNT   READYMACHINECOUNT   UPDATEDMACHINECOUNT   DEGRADEDMACHINECOUNT   AGE
2025-10-13 15:07:25.767715 | controller | master   rendered-master-95020e6e79c40b4dd095817bccfc5060   True      False      False      1              1                   1                     0                      232d
2025-10-13 15:07:25.767780 | controller | worker   rendered-worker-594cc07c9feefb5e37f2530ac457d36c   True      False      False      0              0                   0                     0                      232d
2025-10-13 15:07:26.229665 | controller | ok
2025-10-13 15:07:26.254980 | 
2025-10-13 15:07:26.255069 | PLAY [Set IOPS and Read/Write disk limitation excluding controller]
2025-10-13 15:07:26.255168 | 
2025-10-13 15:07:26.255197 | PLAY RECAP
2025-10-13 15:07:26.255235 | controller | ok: 25 changed: 10 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0
2025-10-13 15:07:26.255257 | 
2025-10-13 15:07:26.363204 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-10-13 15:07:26.364023 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 15:07:26.973788 | 
2025-10-13 15:07:26.973937 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-13 15:07:27.008313 | 
2025-10-13 15:07:27.008462 | TASK [Create zuul-output directory]
2025-10-13 15:07:27.715094 | controller | changed
2025-10-13 15:07:27.721505 | 
2025-10-13 15:07:27.721649 | TASK [Slurp Zuul inventory test]
2025-10-13 15:07:28.065579 | controller -> localhost | ok
2025-10-13 15:07:28.073098 | 
2025-10-13 15:07:28.073188 | TASK [Save zuul inventory]
2025-10-13 15:07:29.670497 | controller | changed
2025-10-13 15:07:29.681211 | 
2025-10-13 15:07:29.681388 | TASK [Save zuul vars without the change_message]
2025-10-13 15:07:31.055352 | controller | changed
2025-10-13 15:07:31.088603 | 
2025-10-13 15:07:31.088757 | PLAY RECAP
2025-10-13 15:07:31.088826 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 15:07:31.088857 | 
2025-10-13 15:07:31.206153 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 15:07:31.207105 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-13 15:07:31.841305 | 
2025-10-13 15:07:31.841432 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-13 15:07:31.864364 | 
2025-10-13 15:07:31.864476 | TASK [Read group_vars]
2025-10-13 15:07:31.883793 | controller | ok
2025-10-13 15:07:31.902972 | 
2025-10-13 15:07:31.903119 | TASK [cifmw_helpers : Check directory is available]
2025-10-13 15:07:32.409341 | controller | ok
2025-10-13 15:07:32.423150 | 
2025-10-13 15:07:32.423844 | TASK [cifmw_helpers : Find yaml files]
2025-10-13 15:07:32.957573 | controller | ok: All paths examined
2025-10-13 15:07:32.973172 | 
2025-10-13 15:07:32.973357 | TASK [cifmw_helpers : Print available yaml files]
2025-10-13 15:07:33.026002 | controller | ok: Found yaml files to parse: ['/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars/all.yml']
2025-10-13 15:07:33.035667 | 
2025-10-13 15:07:33.035792 | LOOP [cifmw_helpers : Read vars]
2025-10-13 15:07:33.508910 | controller | ok:
2025-10-13 15:07:33.509130 | controller | {
2025-10-13 15:07:33.509168 | controller |   "atime": 1760367232.2678869,
2025-10-13 15:07:33.509301 | controller |   "ctime": 1760367235.3549805,
2025-10-13 15:07:33.509352 | controller |   "dev": 64513,
2025-10-13 15:07:33.509381 | controller |   "gid": 1000,
2025-10-13 15:07:33.509407 | controller |   "gr_name": "zuul",
2025-10-13 15:07:33.509431 | controller |   "inode": 222298379,
2025-10-13 15:07:33.509454 | controller |   "isblk": false,
2025-10-13 15:07:33.509478 | controller |   "ischr": false,
2025-10-13 15:07:33.509500 | controller |   "isdir": false,
2025-10-13 15:07:33.509524 | controller |   "isfifo": false,
2025-10-13 15:07:33.509549 | controller |   "isgid": false,
2025-10-13 15:07:33.509572 | controller |   "islnk": false,
2025-10-13 15:07:33.509596 | controller |   "isreg": true,
2025-10-13 15:07:33.509619 | controller |   "issock": false,
2025-10-13 15:07:33.509642 | controller |   "isuid": false,
2025-10-13 15:07:33.509665 | controller |   "mode": "0644",
2025-10-13 15:07:33.509692 | controller |   "mtime": 1760367170.8469133,
2025-10-13 15:07:33.509716 | controller |   "nlink": 1,
2025-10-13 15:07:33.510175 | controller |   "path": "/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars/all.yml",
2025-10-13 15:07:33.510211 | controller |   "pw_name": "zuul",
2025-10-13 15:07:33.510238 | controller |   "rgrp": true,
2025-10-13 15:07:33.510262 | controller |   "roth": true,
2025-10-13 15:07:33.510286 | controller |   "rusr": true,
2025-10-13 15:07:33.510309 | controller |   "size": 355,
2025-10-13 15:07:33.510332 | controller |   "uid": 1000,
2025-10-13 15:07:33.510355 | controller |   "wgrp": false,
2025-10-13 15:07:33.510378 | controller |   "woth": false,
2025-10-13 15:07:33.510401 | controller |   "wusr": true,
2025-10-13 15:07:33.510423 | controller |   "xgrp": false,
2025-10-13 15:07:33.510445 | controller |   "xoth": false,
2025-10-13 15:07:33.510467 | controller |   "xusr": false
2025-10-13 15:07:33.510489 | controller | }
2025-10-13 15:07:33.531096 | 
2025-10-13 15:07:33.531239 | LOOP [cifmw_helpers : Call task to parse all files as fact]
2025-10-13 15:07:33.562438 | controller | Output suppressed because no_log was given
2025-10-13 15:07:33.562647 | controller | Output suppressed because no_log was given
2025-10-13 15:07:33.562690 | 
2025-10-13 15:07:33.579334 | controller | included: /var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/untrusted/project_0/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_helpers/tasks/set_dir_facts.yml
2025-10-13 15:07:33.586181 | 
2025-10-13 15:07:33.586291 | LOOP [cifmw_helpers : Set files as fact]
2025-10-13 15:07:33.628849 | controller | ok:
2025-10-13 15:07:33.629125 | controller | {
2025-10-13 15:07:33.629167 | controller |   "key": "ansible_user_dir",
2025-10-13 15:07:33.629194 | controller |   "value": "{{ lookup('env', 'HOME') }}"
2025-10-13 15:07:33.629220 | controller | }
2025-10-13 15:07:33.639295 | controller | ok:
2025-10-13 15:07:33.639424 | controller | {
2025-10-13 15:07:33.639458 | controller |   "key": "cifmw_project_dir",
2025-10-13 15:07:33.639485 | controller |   "value": "src/github.com/openstack-k8s-operators/ci-framework"
2025-10-13 15:07:33.639510 | controller | }
2025-10-13 15:07:33.646176 | controller | ok:
2025-10-13 15:07:33.646286 | controller | {
2025-10-13 15:07:33.646309 | controller |   "key": "cifmw_project_dir_absolute",
2025-10-13 15:07:33.646328 | controller |   "value": "{{ ansible_user_dir }}/{{ cifmw_project_dir }}"
2025-10-13 15:07:33.646346 | controller | }
2025-10-13 15:07:33.667773 | 
2025-10-13 15:07:33.667892 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-13 15:07:33.687604 | controller | ok
2025-10-13 15:07:33.749276 | 
2025-10-13 15:07:33.749370 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-13 15:07:33.773561 | controller | skipping: Conditional result was False
2025-10-13 15:07:33.779962 | 
2025-10-13 15:07:33.780039 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-13 15:07:34.241020 | controller | ok
2025-10-13 15:07:34.263060 | 
2025-10-13 15:07:34.263206 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-13 15:07:35.407915 | controller | ok
2025-10-13 15:07:35.429145 | 
2025-10-13 15:07:35.429318 | TASK [Prepare workspace]
2025-10-13 15:07:35.454056 | controller | ok
2025-10-13 15:07:35.491393 | 
2025-10-13 15:07:35.491598 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-13 15:07:35.915351 | controller | ok
2025-10-13 15:07:35.926130 | 
2025-10-13 15:07:35.926272 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-13 15:07:37.156901 | controller | Output suppressed because no_log was given
2025-10-13 15:07:37.166858 | 
2025-10-13 15:07:37.166946 | LOOP [Create zuul-output directory]
2025-10-13 15:07:37.492615 | controller | changed: "{{ lookup('env', 'HOME') }}/ci-framework-data/logs"
2025-10-13 15:07:37.492936 | controller | ok: All items complete
2025-10-13 15:07:37.492986 | 
2025-10-13 15:07:37.782485 | controller | changed: "{{ lookup('env', 'HOME') }}/zuul-output/logs"
2025-10-13 15:07:37.801554 | 
2025-10-13 15:07:37.801786 | TASK [Install required packages]
2025-10-13 15:07:53.376131 | controller | ok: Nothing to do
2025-10-13 15:07:53.381934 | 
2025-10-13 15:07:53.382004 | TASK [Install venv]
2025-10-13 15:07:53.820810 | controller | ERROR
2025-10-13 15:07:53.821104 | controller | {
2025-10-13 15:07:53.821144 | controller |   "msg": "gmake: *** No rule to make target 'setup_molecule'.  Stop.",
2025-10-13 15:07:53.821170 | controller |   "rc": 2
2025-10-13 15:07:53.821193 | controller | }
failure
2025-10-13 15:07:53.822991 | 
2025-10-13 15:07:53.823052 | PLAY RECAP
2025-10-13 15:07:53.823105 | controller | ok: 12 changed: 2 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-10-13 15:07:53.823133 | 
2025-10-13 15:07:53.960590 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-13 15:07:53.962086 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 15:07:54.686859 | 
2025-10-13 15:07:54.686984 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-13 15:07:54.712282 | 
2025-10-13 15:07:54.712449 | TASK [Filter out host if needed]
2025-10-13 15:07:54.722430 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-13 15:07:54.727446 | 
2025-10-13 15:07:54.727526 | TASK [Ensure file is present]
2025-10-13 15:07:55.230024 | controller | ok
2025-10-13 15:07:55.237393 | 
2025-10-13 15:07:55.237500 | TASK [Manage molecule report file]
2025-10-13 15:07:55.789275 | controller | skipping: Conditional result was False
2025-10-13 15:07:55.795162 | 
2025-10-13 15:07:55.795239 | TASK [Check if we get ci-framework-data basedir]
2025-10-13 15:07:56.122491 | controller | ok
2025-10-13 15:07:56.128395 | 
2025-10-13 15:07:56.128484 | TASK [Create ci-framework-data log directory for zuul]
2025-10-13 15:07:56.593843 | controller | changed
2025-10-13 15:07:56.612159 | 
2025-10-13 15:07:56.612389 | TASK [Copy ci-framework interesting files]
2025-10-13 15:07:57.112841 | controller | /bin/sh: -c: line 1: syntax error near unexpected token `('
2025-10-13 15:07:57.112994 | controller | /bin/sh: -c: line 1: `cp -ra {{ lookup('env', 'HOME') }}/ci-framework-data/logs . ;'
2025-10-13 15:07:57.166317 | controller | ERROR
2025-10-13 15:07:57.166650 | controller | {
2025-10-13 15:07:57.166715 | controller |   "delta": "0:00:00.005015",
2025-10-13 15:07:57.166809 | controller |   "end": "2025-10-13 15:07:57.113263",
2025-10-13 15:07:57.166851 | controller |   "msg": "non-zero return code",
2025-10-13 15:07:57.166889 | controller |   "rc": 2,
2025-10-13 15:07:57.166927 | controller |   "start": "2025-10-13 15:07:57.108248"
2025-10-13 15:07:57.166963 | controller | }
failure
2025-10-13 15:07:57.185103 | 
2025-10-13 15:07:57.185293 | TASK [Compress logs bigger than 2MB]
2025-10-13 15:07:57.732563 | controller | changed
2025-10-13 15:07:57.740505 | 
2025-10-13 15:07:57.740607 | TASK [Copy files from workspace on node]
2025-10-13 15:07:57.763443 | controller | ok
2025-10-13 15:07:57.801141 | 
2025-10-13 15:07:57.801290 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 15:07:57.826128 | controller | skipping: Conditional result was False
2025-10-13 15:07:57.834463 | 
2025-10-13 15:07:57.834572 | TASK [fetch-output : Set log path for single node]
2025-10-13 15:07:57.876082 | controller | ok
2025-10-13 15:07:57.883346 | 
2025-10-13 15:07:57.883434 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 15:07:58.154215 | controller -> localhost | ok: "/var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/work/logs"
2025-10-13 15:07:58.355112 | controller -> localhost | changed: "/var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/work/artifacts"
2025-10-13 15:07:58.595468 | controller -> localhost | changed: "/var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/work/docs"
2025-10-13 15:07:58.615843 | 
2025-10-13 15:07:58.616009 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 15:07:59.447562 | controller | changed:
2025-10-13 15:07:59.447923 | controller | .d..t...... ./
2025-10-13 15:07:59.447982 | controller | cd+++++++++ ci-framework-data/
2025-10-13 15:07:59.448046 | controller | changed: All items complete
2025-10-13 15:07:59.448086 | 
2025-10-13 15:08:00.160076 | controller | ERROR: Item: {'dest': '/var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/work/artifacts', 'src': 'artifacts'}
2025-10-13 15:08:00.160523 | controller | {
2025-10-13 15:08:00.160572 | controller |   "ansible_loop_var": "zj_output",
2025-10-13 15:08:00.160605 | controller |   "msg": "rsync: [sender] change_dir \"/home/zuul/{{ lookup('env', 'HOME') }}/zuul-output/artifacts\" failed: No such file or directory (2)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1670) [Receiver=3.1.3]\nrsync: [Receiver] write error: Broken pipe (32)\n",
2025-10-13 15:08:00.160629 | controller |   "rc": 23,
2025-10-13 15:08:00.160651 | controller |   "zj_output": {
2025-10-13 15:08:00.160672 | controller |     "dest": "/var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/work/artifacts",
2025-10-13 15:08:00.160693 | controller |     "src": "artifacts"
2025-10-13 15:08:00.160713 | controller |   }
2025-10-13 15:08:00.160796 | controller | }
2025-10-13 15:08:00.848540 | controller | ERROR: Item: {'dest': '/var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/work/docs', 'src': 'docs'}
2025-10-13 15:08:00.848924 | controller | {
2025-10-13 15:08:00.849017 | controller |   "ansible_loop_var": "zj_output",
2025-10-13 15:08:00.849513 | controller |   "msg": "rsync: [sender] change_dir \"/home/zuul/{{ lookup('env', 'HOME') }}/zuul-output/docs\" failed: No such file or directory (2)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1670) [Receiver=3.1.3]\nrsync: [Receiver] write error: Broken pipe (32)\n",
2025-10-13 15:08:00.849588 | controller |   "rc": 23,
2025-10-13 15:08:00.849642 | controller |   "zj_output": {
2025-10-13 15:08:00.849692 | controller |     "dest": "/var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/work/docs",
2025-10-13 15:08:00.849805 | controller |     "src": "docs"
2025-10-13 15:08:00.849861 | controller |   }
2025-10-13 15:08:00.849899 | controller | }
2025-10-13 15:08:00.858659 | 
2025-10-13 15:08:00.858766 | PLAY RECAP
2025-10-13 15:08:00.858809 | controller | ok: 6 changed: 3 unreachable: 0 failed: 2 skipped: 2 rescued: 0 ignored: 0
2025-10-13 15:08:00.858830 | 
2025-10-13 15:08:00.965892 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 15:08:00.966878 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-10-13 15:08:01.603130 | 
2025-10-13 15:08:01.603268 | PLAY [Report DLRN results after job run]
2025-10-13 15:08:01.627720 | 
2025-10-13 15:08:01.627941 | TASK [Report job status to DLRN]
2025-10-13 15:08:01.648821 | controller | ok
2025-10-13 15:08:01.678418 | 
2025-10-13 15:08:01.678572 | TASK [dlrn_report : Warning if DLRN is not running]
2025-10-13 15:08:01.737059 | controller | skipping: Conditional result was False
2025-10-13 15:08:01.744287 | 
2025-10-13 15:08:01.744404 | TASK [dlrn_report : Install dlrnapi-client shyaml package]
2025-10-13 15:08:01.794913 | controller | skipping: Conditional result was False
2025-10-13 15:08:01.802382 | 
2025-10-13 15:08:01.802497 | TASK [dlrn_report : Install kinit related package]
2025-10-13 15:08:01.852716 | controller | skipping: Conditional result was False
2025-10-13 15:08:01.860515 | 
2025-10-13 15:08:01.860677 | TASK [dlrn_report : Install dlrn kerberos related packages]
2025-10-13 15:08:01.911471 | controller | skipping: Conditional result was False
2025-10-13 15:08:01.921651 | 
2025-10-13 15:08:01.921837 | TASK [Set zuul-log-path fact]
2025-10-13 15:08:01.976553 | controller | skipping: Conditional result was False
2025-10-13 15:08:01.990769 | 
2025-10-13 15:08:01.990962 | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used]
2025-10-13 15:08:02.058514 | controller | skipping: Conditional result was False
2025-10-13 15:08:02.067230 | 
2025-10-13 15:08:02.067340 | TASK [Get hash related data from repo_setup role]
2025-10-13 15:08:02.132677 | controller | skipping: Conditional result was False
2025-10-13 15:08:02.139537 | 
2025-10-13 15:08:02.139629 | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication]
2025-10-13 15:08:02.671440 | controller | skipping: Conditional result was False
2025-10-13 15:08:02.680970 | 
2025-10-13 15:08:02.681174 | TASK [dlrn_report : Set empty value for dlrnapi password]
2025-10-13 15:08:02.737317 | controller | skipping: Conditional result was False
2025-10-13 15:08:02.748140 | 
2025-10-13 15:08:02.748312 | TASK [dlrn_report : Report results to dlrn for the tested hash]
2025-10-13 15:08:03.296881 | controller | skipping: Conditional result was False
2025-10-13 15:08:03.332856 | 
2025-10-13 15:08:03.332987 | PLAY RECAP
2025-10-13 15:08:03.333050 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 0
2025-10-13 15:08:03.333082 | 
2025-10-13 15:08:03.456793 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-10-13 15:08:03.458013 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 15:08:04.122088 | 
2025-10-13 15:08:04.122260 | PLAY [all]
2025-10-13 15:08:04.145031 | 
2025-10-13 15:08:04.145169 | TASK [include_role : fetch-output]
2025-10-13 15:08:04.173917 | controller | ok
2025-10-13 15:08:04.194544 | 
2025-10-13 15:08:04.194680 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 15:08:04.260186 | controller | skipping: Conditional result was False
2025-10-13 15:08:04.266236 | 
2025-10-13 15:08:04.266320 | TASK [fetch-output : Set log path for single node]
2025-10-13 15:08:04.297300 | controller | ok
2025-10-13 15:08:04.304086 | 
2025-10-13 15:08:04.304208 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 15:08:04.762863 | controller -> localhost | ok: "/var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/work/logs"
2025-10-13 15:08:05.002473 | controller -> localhost | ok: "/var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/work/artifacts"
2025-10-13 15:08:05.242711 | controller -> localhost | ok: "/var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/work/docs"
2025-10-13 15:08:05.263979 | 
2025-10-13 15:08:05.264098 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 15:08:05.985859 | controller | ok
2025-10-13 15:08:05.986234 | controller | ok: All items complete
2025-10-13 15:08:05.986285 | 
2025-10-13 15:08:06.671477 | controller | ERROR: Item: {'dest': '/var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/work/artifacts', 'src': 'artifacts'}
2025-10-13 15:08:06.671822 | controller | {
2025-10-13 15:08:06.672054 | controller |   "ansible_loop_var": "zj_output",
2025-10-13 15:08:06.672111 | controller |   "msg": "rsync: [sender] change_dir \"/home/zuul/{{ lookup('env', 'HOME') }}/zuul-output/artifacts\" failed: No such file or directory (2)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1670) [Receiver=3.1.3]\nrsync: [Receiver] write error: Broken pipe (32)\n",
2025-10-13 15:08:06.672161 | controller |   "rc": 23,
2025-10-13 15:08:06.672200 | controller |   "zj_output": {
2025-10-13 15:08:06.672237 | controller |     "dest": "/var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/work/artifacts",
2025-10-13 15:08:06.672272 | controller |     "src": "artifacts"
2025-10-13 15:08:06.672307 | controller |   }
2025-10-13 15:08:06.672342 | controller | }
2025-10-13 15:08:07.370266 | controller | ERROR: Item: {'dest': '/var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/work/docs', 'src': 'docs'}
2025-10-13 15:08:07.370503 | controller | {
2025-10-13 15:08:07.370582 | controller |   "ansible_loop_var": "zj_output",
2025-10-13 15:08:07.370768 | controller |   "msg": "rsync: [sender] change_dir \"/home/zuul/{{ lookup('env', 'HOME') }}/zuul-output/docs\" failed: No such file or directory (2)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1670) [Receiver=3.1.3]\nrsync: [Receiver] write error: Broken pipe (32)\n",
2025-10-13 15:08:07.370874 | controller |   "rc": 23,
2025-10-13 15:08:07.370925 | controller |   "zj_output": {
2025-10-13 15:08:07.370966 | controller |     "dest": "/var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/work/docs",
2025-10-13 15:08:07.371006 | controller |     "src": "docs"
2025-10-13 15:08:07.371046 | controller |   }
2025-10-13 15:08:07.371084 | controller | }
failure
2025-10-13 15:08:07.389413 | 
2025-10-13 15:08:07.389482 | PLAY RECAP
2025-10-13 15:08:07.389524 | controller | ok: 2 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-10-13 15:08:07.389546 | 
2025-10-13 15:08:07.516948 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 15:08:07.518045 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-13 15:08:08.175344 | 
2025-10-13 15:08:08.175475 | PLAY [localhost]
2025-10-13 15:08:08.195052 | 
2025-10-13 15:08:08.195192 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-13 15:08:08.626161 | localhost | changed
2025-10-13 15:08:08.631521 | 
2025-10-13 15:08:08.631703 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-13 15:08:08.664873 | localhost | ok
2025-10-13 15:08:08.677288 | 
2025-10-13 15:08:08.677556 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-13 15:08:09.083699 | localhost | changed
2025-10-13 15:08:09.091007 | 
2025-10-13 15:08:09.091109 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-13 15:08:09.814543 | localhost | changed
2025-10-13 15:08:09.820785 | 
2025-10-13 15:08:09.820864 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-13 15:08:10.275164 | localhost | Identity added: /var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/work/tmp/ansible.ly3lmbcn (/var/lib/zuul/builds/fd72f1893f98443e9035efa49fd2a1be/work/tmp/ansible.ly3lmbcn)
2025-10-13 15:08:10.275422 | localhost | ok: Runtime: 0:00:00.015611
2025-10-13 15:08:10.282055 | 
2025-10-13 15:08:10.282164 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-13 15:08:10.551042 | localhost | ok: Runtime: 0:00:00.006499
2025-10-13 15:08:10.563185 | 
2025-10-13 15:08:10.563358 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-13 15:08:10.624114 | localhost | changed
2025-10-13 15:08:10.637802 | 
2025-10-13 15:08:10.638002 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-13 15:08:11.035998 | localhost | changed
2025-10-13 15:08:11.056708 | 
2025-10-13 15:08:11.056826 | PLAY [localhost]
2025-10-13 15:08:11.069831 | 
2025-10-13 15:08:11.069915 | TASK [Generate bulk log download script]
2025-10-13 15:08:11.087987 | localhost | ok
2025-10-13 15:08:11.100592 | 
2025-10-13 15:08:11.100685 | TASK [local-log-download : Check API endpoint is defined]
2025-10-13 15:08:11.128894 | localhost | ok: All assertions passed
2025-10-13 15:08:11.133614 | 
2025-10-13 15:08:11.133685 | TASK [local-log-download : Create download script]
2025-10-13 15:08:11.537280 | localhost -> localhost | changed
2025-10-13 15:08:11.545887 | 
2025-10-13 15:08:11.545975 | TASK [Register quick-download link]
2025-10-13 15:08:11.563119 | localhost | ok
2025-10-13 15:08:11.601868 | 
2025-10-13 15:08:11.601970 | PLAY [logserver.rdoproject.org]
2025-10-13 15:08:11.612000 | 
2025-10-13 15:08:11.612116 | TASK [Set zuul-log-path fact]
2025-10-13 15:08:11.628628 | logserver.rdoproject.org | ok
2025-10-13 15:08:11.638525 | 
2025-10-13 15:08:11.638639 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 15:08:11.665066 | logserver.rdoproject.org | ok
2025-10-13 15:08:11.670210 | 
2025-10-13 15:08:11.670304 | TASK [upload-logs : Create log directories]
2025-10-13 15:08:12.440352 | logserver.rdoproject.org | changed
2025-10-13 15:08:12.449663 | 
2025-10-13 15:08:12.449890 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-13 15:08:12.791597 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004962
2025-10-13 15:08:12.807099 | 
2025-10-13 15:08:12.807286 | TASK [upload-logs : Upload logs to log server]
2025-10-13 15:08:13.592506 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-13 15:08:13.596449 | 
2025-10-13 15:08:13.596587 | LOOP [upload-logs : Compress console log and json output]
2025-10-13 15:08:13.652861 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 15:08:13.664061 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 15:08:13.676958 | 
2025-10-13 15:08:13.677104 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-13 15:08:13.725017 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 15:08:13.725257 | 
2025-10-13 15:08:13.728701 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 15:08:13.741745 | 
2025-10-13 15:08:13.741929 | LOOP [upload-logs : Upload console log and json output]