2026-01-21 14:58:18.080940 | Job console starting...
2026-01-21 14:58:18.354558 | Updating repositories
2026-01-21 14:58:19.087516 | Preparing job workspace
2026-01-21 14:58:29.694707 | Running Ansible setup...
2026-01-21 14:58:35.173872 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-21 14:58:36.563962 | 
2026-01-21 14:58:36.564070 | PLAY [localhost]
2026-01-21 14:58:36.585485 | 
2026-01-21 14:58:36.585565 | TASK [Gathering Facts]
2026-01-21 14:58:38.720699 | localhost | ok
2026-01-21 14:58:38.751489 | 
2026-01-21 14:58:38.751608 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-21 14:58:40.122286 | localhost -> localhost | changed
2026-01-21 14:58:40.127804 | 
2026-01-21 14:58:40.127879 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-21 14:58:41.893543 | localhost -> localhost | changed
2026-01-21 14:58:41.901989 | 
2026-01-21 14:58:41.902061 | TASK [Setup log path fact]
2026-01-21 14:58:41.919270 | localhost | ok
2026-01-21 14:58:41.938007 | 
2026-01-21 14:58:41.938074 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-21 14:58:41.985236 | localhost | ok
2026-01-21 14:58:41.993164 | 
2026-01-21 14:58:41.999612 | TASK [emit-job-header : Print job information]
2026-01-21 14:58:42.157011 | # Job Information
2026-01-21 14:58:42.157173 | Ansible Version: 2.15.12
2026-01-21 14:58:42.157204 | Job: cifmw-molecule-ci_multus
2026-01-21 14:58:42.157228 | Pipeline: github-check
2026-01-21 14:58:42.157249 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-01-21 14:58:42.157269 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3612
2026-01-21 14:58:42.157288 | Log URL (when completed): https://logserver.rdoproject.org/95f/rdoproject.org/95faca4e6bd64736a8ab8ac4ea9229e4/
2026-01-21 14:58:42.157351 | Event ID: 74607520-f6d9-11f0-9dda-0587936d9c0f
2026-01-21 14:58:42.160928 | 
2026-01-21 14:58:42.160998 | LOOP [emit-job-header : Print node information]
2026-01-21 14:58:42.303897 | localhost | ok:
2026-01-21 14:58:42.304044 | localhost | # Node Information
2026-01-21 14:58:42.304075 | localhost | Inventory Hostname: controller
2026-01-21 14:58:42.304114 | localhost | Hostname: np0005590730
2026-01-21 14:58:42.304139 | localhost | Username: zuul
2026-01-21 14:58:42.304161 | localhost | Distro: CentOS 9
2026-01-21 14:58:42.304181 | localhost | Provider: ibm-bm4-nodepool
2026-01-21 14:58:42.304200 | localhost | Region: regionOne
2026-01-21 14:58:42.304218 | localhost | Label: centos-9-stream-crc-2-48-0-xxl
2026-01-21 14:58:42.304236 | localhost | Product Name: OpenStack Compute
2026-01-21 14:58:42.304255 | localhost | Interface IP: 192.168.26.17
2026-01-21 14:58:42.363577 | 
2026-01-21 14:58:42.363671 | PLAY [all]
2026-01-21 14:58:42.382635 | 
2026-01-21 14:58:42.382716 | TASK [Gather network facts]
2026-01-21 14:58:42.849629 | controller | ok
2026-01-21 14:58:42.884097 | 
2026-01-21 14:58:42.884195 | TASK [include_role : start-zuul-console]
2026-01-21 14:58:42.943077 | controller | ok
2026-01-21 14:58:42.971590 | 
2026-01-21 14:58:42.971678 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-21 14:58:43.668646 | controller | ok
2026-01-21 14:58:43.685767 | 
2026-01-21 14:58:43.685851 | TASK [include_role : add-build-sshkey]
2026-01-21 14:58:43.767416 | controller | ok
2026-01-21 14:58:43.811539 | 
2026-01-21 14:58:43.811629 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-21 14:58:44.491168 | controller -> localhost | ok
2026-01-21 14:58:44.496597 | 
2026-01-21 14:58:44.496676 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-21 14:58:44.549656 | controller | ok
2026-01-21 14:58:44.579491 | controller | included: /var/lib/zuul/builds/95faca4e6bd64736a8ab8ac4ea9229e4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-21 14:58:44.601343 | 
2026-01-21 14:58:44.601437 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-21 14:58:46.417178 | controller -> localhost | Generating public/private rsa key pair.
2026-01-21 14:58:46.417398 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/95faca4e6bd64736a8ab8ac4ea9229e4/work/95faca4e6bd64736a8ab8ac4ea9229e4_id_rsa.
2026-01-21 14:58:46.417436 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/95faca4e6bd64736a8ab8ac4ea9229e4/work/95faca4e6bd64736a8ab8ac4ea9229e4_id_rsa.pub.
2026-01-21 14:58:46.417462 | controller -> localhost | The key fingerprint is:
2026-01-21 14:58:46.417485 | controller -> localhost | SHA256:YTswJLEk5tlkDczMwIdAZEJPpa5Cgfi4BKOnt1t8WTk zuul-build-sshkey
2026-01-21 14:58:46.417505 | controller -> localhost | The key's randomart image is:
2026-01-21 14:58:46.417525 | controller -> localhost | +---[RSA 3072]----+
2026-01-21 14:58:46.417546 | controller -> localhost | |*B+OO=.          |
2026-01-21 14:58:46.417566 | controller -> localhost | |=o=BB+.          |
2026-01-21 14:58:46.417585 | controller -> localhost | |=.o+o o o        |
2026-01-21 14:58:46.417604 | controller -> localhost | |o+o    + +       |
2026-01-21 14:58:46.417622 | controller -> localhost | |ooo.    E        |
2026-01-21 14:58:46.417640 | controller -> localhost | |o+..   o o       |
2026-01-21 14:58:46.417657 | controller -> localhost | |+.. o o          |
2026-01-21 14:58:46.417675 | controller -> localhost | |.. o .           |
2026-01-21 14:58:46.417693 | controller -> localhost | |  o.             |
2026-01-21 14:58:46.417710 | controller -> localhost | +----[SHA256]-----+
2026-01-21 14:58:46.417756 | controller -> localhost | ok: Runtime: 0:00:00.582389
2026-01-21 14:58:46.423877 | 
2026-01-21 14:58:46.423949 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-21 14:58:46.516109 | controller | ok
2026-01-21 14:58:46.540120 | controller | included: /var/lib/zuul/builds/95faca4e6bd64736a8ab8ac4ea9229e4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-21 14:58:46.575757 | 
2026-01-21 14:58:46.575839 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-21 14:58:46.634442 | controller | skipping: Conditional result was False
2026-01-21 14:58:46.645541 | 
2026-01-21 14:58:46.645620 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-21 14:58:47.476185 | controller | changed
2026-01-21 14:58:47.481147 | 
2026-01-21 14:58:47.481224 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-21 14:58:47.777035 | controller | ok
2026-01-21 14:58:47.786682 | 
2026-01-21 14:58:47.786766 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-21 14:58:49.208756 | controller | changed
2026-01-21 14:58:49.213795 | 
2026-01-21 14:58:49.213863 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-21 14:58:50.517174 | controller | changed
2026-01-21 14:58:50.570987 | 
2026-01-21 14:58:50.571080 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-21 14:58:50.661399 | controller | skipping: Conditional result was False
2026-01-21 14:58:50.666989 | 
2026-01-21 14:58:50.667060 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-21 14:58:51.855579 | controller -> localhost | changed
2026-01-21 14:58:51.865839 | 
2026-01-21 14:58:51.865924 | TASK [add-build-sshkey : Add back temp key]
2026-01-21 14:58:52.543941 | controller -> localhost | Identity added: /var/lib/zuul/builds/95faca4e6bd64736a8ab8ac4ea9229e4/work/95faca4e6bd64736a8ab8ac4ea9229e4_id_rsa (zuul-build-sshkey)
2026-01-21 14:58:52.544150 | controller -> localhost | ok: Runtime: 0:00:00.016591
2026-01-21 14:58:52.550046 | 
2026-01-21 14:58:52.550126 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-21 14:58:53.318959 | controller | ok
2026-01-21 14:58:53.323879 | 
2026-01-21 14:58:53.323956 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-21 14:58:53.452664 | controller | skipping: Conditional result was False
2026-01-21 14:58:53.466160 | 
2026-01-21 14:58:53.466242 | TASK [include_role : validate-host]
2026-01-21 14:58:53.543732 | controller | ok
2026-01-21 14:58:53.636599 | 
2026-01-21 14:58:53.636696 | TASK [validate-host : Define zuul_info_dir fact]
2026-01-21 14:58:53.729233 | controller | ok
2026-01-21 14:58:53.737933 | 
2026-01-21 14:58:53.738002 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-01-21 14:58:54.687340 | controller -> localhost | ok
2026-01-21 14:58:54.693378 | 
2026-01-21 14:58:54.693467 | TASK [validate-host : Collect information about the host]
2026-01-21 14:58:55.489645 | controller | ok
2026-01-21 14:58:55.503223 | 
2026-01-21 14:58:55.503316 | TASK [validate-host : Sanitize hostname]
2026-01-21 14:58:55.797361 | controller | ok
2026-01-21 14:58:55.802348 | 
2026-01-21 14:58:55.802431 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-01-21 14:58:57.514410 | controller -> localhost | changed
2026-01-21 14:58:57.520873 | 
2026-01-21 14:58:57.520940 | TASK [validate-host : Collect information about zuul worker]
2026-01-21 14:58:58.418982 | controller | ok
2026-01-21 14:58:58.425213 | 
2026-01-21 14:58:58.425287 | TASK [validate-host : Write out all zuul information for each host]
2026-01-21 14:58:59.797245 | controller -> localhost | changed
2026-01-21 14:58:59.817257 | 
2026-01-21 14:58:59.817357 | TASK [include_role : prepare-workspace-openshift]
2026-01-21 14:58:59.887555 | controller | skipping: Conditional result was False
2026-01-21 14:58:59.893849 | 
2026-01-21 14:58:59.893914 | TASK [include_role : remove-zuul-sshkey]
2026-01-21 14:58:59.948193 | controller | skipping: Conditional result was False
2026-01-21 14:58:59.954444 | 
2026-01-21 14:58:59.954514 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-21 14:59:00.322307 | controller | ok: "logs"
2026-01-21 14:59:00.322601 | controller | ok: All items complete
2026-01-21 14:59:00.322635 | 
2026-01-21 14:59:00.559874 | controller | ok: "artifacts"
2026-01-21 14:59:00.811087 | controller | ok: "docs"
2026-01-21 14:59:00.819628 | 
2026-01-21 14:59:00.819697 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-21 14:59:01.204419 | controller | changed: "logs"
2026-01-21 14:59:01.441518 | controller | changed: "artifacts"
2026-01-21 14:59:01.699585 | controller | changed: "docs"
2026-01-21 14:59:01.752603 | 
2026-01-21 14:59:01.752676 | PLAY RECAP
2026-01-21 14:59:01.752726 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-21 14:59:01.752755 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-21 14:59:01.752775 | 
2026-01-21 14:59:02.011685 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-21 14:59:02.012455 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-21 14:59:04.174466 | 
2026-01-21 14:59:04.174592 | PLAY [all]
2026-01-21 14:59:04.262003 | 
2026-01-21 14:59:04.262120 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-01-21 14:59:04.474558 | controller | ok
2026-01-21 14:59:04.482523 | 
2026-01-21 14:59:04.482637 | TASK [mirror-info-fork : Create /etc/ci]
2026-01-21 14:59:05.164615 | controller | changed
2026-01-21 14:59:05.170786 | 
2026-01-21 14:59:05.170870 | TASK [mirror-info-fork : Install ci_mirror script]
2026-01-21 14:59:07.931191 | controller | changed
2026-01-21 14:59:07.974832 | 
2026-01-21 14:59:07.974955 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-01-21 14:59:08.588923 | controller | changed:
2026-01-21 14:59:08.589085 | controller | {
2026-01-21 14:59:08.589130 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-01-21 14:59:08.589160 | controller | }
2026-01-21 14:59:08.876915 | controller | changed:
2026-01-21 14:59:08.877211 | controller | {
2026-01-21 14:59:08.877256 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-01-21 14:59:08.877282 | controller | }
2026-01-21 14:59:09.170499 | controller | changed:
2026-01-21 14:59:09.170595 | controller | {
2026-01-21 14:59:09.170624 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-01-21 14:59:09.170647 | controller | }
2026-01-21 14:59:09.475707 | controller | changed:
2026-01-21 14:59:09.475805 | controller | {
2026-01-21 14:59:09.475842 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-01-21 14:59:09.475864 | controller | }
2026-01-21 14:59:09.790577 | controller | changed:
2026-01-21 14:59:09.790678 | controller | {
2026-01-21 14:59:09.790707 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-01-21 14:59:09.790730 | controller | }
2026-01-21 14:59:10.108942 | controller | changed:
2026-01-21 14:59:10.109032 | controller | {
2026-01-21 14:59:10.109061 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-01-21 14:59:10.109083 | controller | }
2026-01-21 14:59:10.591502 | controller | changed:
2026-01-21 14:59:10.591915 | controller | {
2026-01-21 14:59:10.591957 | 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"
2026-01-21 14:59:10.591983 | controller | }
2026-01-21 14:59:10.735541 | controller | changed:
2026-01-21 14:59:10.735626 | controller | {
2026-01-21 14:59:10.735655 | 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"
2026-01-21 14:59:10.735678 | controller | }
2026-01-21 14:59:11.035973 | controller | changed:
2026-01-21 14:59:11.036131 | controller | {
2026-01-21 14:59:11.036165 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-01-21 14:59:11.036191 | controller | }
2026-01-21 14:59:11.331898 | controller | changed:
2026-01-21 14:59:11.331989 | controller | {
2026-01-21 14:59:11.332018 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-01-21 14:59:11.332041 | controller | }
2026-01-21 14:59:11.653435 | controller | changed:
2026-01-21 14:59:11.653521 | controller | {
2026-01-21 14:59:11.653550 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-01-21 14:59:11.653572 | controller | }
2026-01-21 14:59:11.977274 | controller | changed:
2026-01-21 14:59:11.977382 | controller | {
2026-01-21 14:59:11.977412 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-01-21 14:59:11.977435 | controller | }
2026-01-21 14:59:12.291798 | controller | changed:
2026-01-21 14:59:12.291879 | controller | {
2026-01-21 14:59:12.291907 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2026-01-21 14:59:12.291929 | controller | }
2026-01-21 14:59:12.610238 | controller | changed:
2026-01-21 14:59:12.610337 | controller | {
2026-01-21 14:59:12.610367 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-01-21 14:59:12.610389 | controller | }
2026-01-21 14:59:12.937705 | controller | changed:
2026-01-21 14:59:12.937800 | controller | {
2026-01-21 14:59:12.937831 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-01-21 14:59:12.937854 | controller | }
2026-01-21 14:59:13.256634 | controller | changed:
2026-01-21 14:59:13.256725 | controller | {
2026-01-21 14:59:13.256751 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-01-21 14:59:13.256772 | controller | }
2026-01-21 14:59:13.569578 | controller | changed:
2026-01-21 14:59:13.569795 | controller | {
2026-01-21 14:59:13.569834 | 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"
2026-01-21 14:59:13.569860 | controller | }
2026-01-21 14:59:13.881662 | controller | changed:
2026-01-21 14:59:13.881752 | controller | {
2026-01-21 14:59:13.881780 | 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"
2026-01-21 14:59:13.881803 | controller | }
2026-01-21 14:59:14.207793 | controller | changed:
2026-01-21 14:59:14.207980 | controller | {
2026-01-21 14:59:14.208022 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-01-21 14:59:14.208046 | controller | }
2026-01-21 14:59:14.528020 | controller | changed:
2026-01-21 14:59:14.528127 | controller | {
2026-01-21 14:59:14.528157 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-01-21 14:59:14.528179 | controller | }
2026-01-21 14:59:14.853373 | controller | changed:
2026-01-21 14:59:14.853456 | controller | {
2026-01-21 14:59:14.853484 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-01-21 14:59:14.853507 | controller | }
2026-01-21 14:59:15.183115 | controller | changed:
2026-01-21 14:59:15.183204 | controller | {
2026-01-21 14:59:15.183232 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-01-21 14:59:15.183254 | controller | }
2026-01-21 14:59:15.529967 | controller | changed:
2026-01-21 14:59:15.530050 | controller | {
2026-01-21 14:59:15.530100 | 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"
2026-01-21 14:59:15.530125 | controller | }
2026-01-21 14:59:15.877602 | controller | changed:
2026-01-21 14:59:15.877694 | controller | {
2026-01-21 14:59:15.877721 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-01-21 14:59:15.877743 | controller | }
2026-01-21 14:59:16.195630 | controller | changed:
2026-01-21 14:59:16.195711 | controller | {
2026-01-21 14:59:16.195739 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-01-21 14:59:16.195759 | controller | }
2026-01-21 14:59:16.509752 | controller | changed:
2026-01-21 14:59:16.509835 | controller | {
2026-01-21 14:59:16.509862 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-01-21 14:59:16.509882 | controller | }
2026-01-21 14:59:16.580180 | 
2026-01-21 14:59:16.580287 | TASK [Set timezone to UTC]
2026-01-21 14:59:17.783562 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-01-21 14:59:17.788943 | 
2026-01-21 14:59:17.789012 | TASK [Create nodepool directory]
2026-01-21 14:59:18.177202 | controller | changed
2026-01-21 14:59:18.205220 | 
2026-01-21 14:59:18.205327 | TASK [Create nodepool sub_nodes file]
2026-01-21 14:59:19.590316 | controller | changed
2026-01-21 14:59:19.605011 | 
2026-01-21 14:59:19.605103 | TASK [Create nodepool sub_nodes_private file]
2026-01-21 14:59:20.941647 | controller | changed
2026-01-21 14:59:20.946764 | 
2026-01-21 14:59:20.946829 | LOOP [Populate nodepool sub_nodes file]
2026-01-21 14:59:21.098341 | 
2026-01-21 14:59:21.098511 | LOOP [Populate nodepool sub_nodes_private file]
2026-01-21 14:59:21.209055 | 
2026-01-21 14:59:21.209227 | TASK [Create nodepool primary file]
2026-01-21 14:59:21.296354 | controller | skipping: Conditional result was False
2026-01-21 14:59:21.303351 | 
2026-01-21 14:59:21.303436 | TASK [Create nodepool node_private for this node]
2026-01-21 14:59:22.703647 | controller | changed
2026-01-21 14:59:22.715138 | 
2026-01-21 14:59:22.715219 | LOOP [Copy ssh keys to nodepool directory]
2026-01-21 14:59:24.073444 | controller | ok: Item: id_rsa Runtime: 0:00:00.005572
2026-01-21 14:59:24.073640 | 
2026-01-21 14:59:24.347779 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005294
2026-01-21 14:59:24.365462 | 
2026-01-21 14:59:24.365562 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-01-21 14:59:25.712634 | controller | changed
2026-01-21 14:59:25.733788 | 
2026-01-21 14:59:25.733975 | TASK [Validate sudoers config after edits]
2026-01-21 14:59:26.463211 | controller | /etc/sudoers: parsed OK
2026-01-21 14:59:26.463266 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-01-21 14:59:26.463276 | controller | /etc/sudoers.d/zuul: parsed OK
2026-01-21 14:59:26.463282 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-01-21 14:59:26.932195 | controller | ok: Runtime: 0:00:00.006376
2026-01-21 14:59:26.937913 | 
2026-01-21 14:59:26.938070 | TASK [Show the environment passed in to job shell scripts]
2026-01-21 14:59:27.800092 | controller | SHELL=/bin/bash
2026-01-21 14:59:27.800147 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-01-21 14:59:27.800157 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-01-21 14:59:27.800164 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/12/3612/ad59be263468c9d65538fe1fed8944959129e83c
2026-01-21 14:59:27.800169 | controller | PWD=/home/zuul
2026-01-21 14:59:27.800175 | controller | ZUUL_PIPELINE=github-check
2026-01-21 14:59:27.800180 | controller | LOGNAME=zuul
2026-01-21 14:59:27.800186 | controller | XDG_SESSION_TYPE=tty
2026-01-21 14:59:27.800248 | controller | _=/usr/bin/env
2026-01-21 14:59:27.800256 | controller | MOTD_SHOWN=pam
2026-01-21 14:59:27.800262 | controller | HOME=/home/zuul
2026-01-21 14:59:27.800267 | controller | LANG=en_US.UTF-8
2026-01-21 14:59:27.800272 | controller | SSH_CONNECTION=192.168.26.12 38132 192.168.26.17 22
2026-01-21 14:59:27.800278 | 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
2026-01-21 14:59:27.800287 | controller | ZUUL_CHANGE_IDS=3612,ad59be263468c9d65538fe1fed8944959129e83c
2026-01-21 14:59:27.800292 | controller | WORKSPACE=/home/zuul/workspace
2026-01-21 14:59:27.800298 | controller | XDG_SESSION_CLASS=user
2026-01-21 14:59:27.800303 | controller | SELINUX_ROLE_REQUESTED=
2026-01-21 14:59:27.800308 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-01-21 14:59:27.800313 | controller | USER=zuul
2026-01-21 14:59:27.800319 | controller | ZUUL_VOTING=True
2026-01-21 14:59:27.800324 | controller | BUILD_TIMEOUT=1800000
2026-01-21 14:59:27.800329 | controller | SELINUX_USE_CURRENT_RANGE=
2026-01-21 14:59:27.800334 | controller | SHLVL=1
2026-01-21 14:59:27.800339 | controller | ZUUL_PATCHSET=ad59be263468c9d65538fe1fed8944959129e83c
2026-01-21 14:59:27.800344 | controller | XDG_SESSION_ID=1
2026-01-21 14:59:27.800350 | controller | ZUUL_BRANCH=main
2026-01-21 14:59:27.800355 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-01-21 14:59:27.800360 | controller | SSH_CLIENT=192.168.26.12 38132 22
2026-01-21 14:59:27.800371 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-01-21 14:59:27.800377 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-01-21 14:59:27.800382 | controller | which_declare=declare -f
2026-01-21 14:59:27.800388 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-01-21 14:59:27.800394 | controller | SELINUX_LEVEL_REQUESTED=
2026-01-21 14:59:27.800399 | controller | ZUUL_CHANGE=3612
2026-01-21 14:59:27.800404 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-01-21 14:59:27.800429 | controller | ZUUL_UUID=95faca4e6bd64736a8ab8ac4ea9229e4
2026-01-21 14:59:27.800439 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-01-21 14:59:27.800446 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-01-21 14:59:27.800452 | controller | }
2026-01-21 14:59:27.905553 | controller | ok: Runtime: 0:00:00.006622
2026-01-21 14:59:27.911421 | 
2026-01-21 14:59:27.911494 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-01-21 14:59:27.996035 | controller | skipping: Conditional result was False
2026-01-21 14:59:28.002263 | 
2026-01-21 14:59:28.002364 | TASK [Symlink /home/zuul-worker/workspace]
2026-01-21 14:59:28.635092 | controller | skipping: Conditional result was False
2026-01-21 14:59:28.641037 | 
2026-01-21 14:59:28.641138 | TASK [Ensure legacy workspace directory]
2026-01-21 14:59:28.991385 | controller | changed
2026-01-21 14:59:29.085707 | 
2026-01-21 14:59:29.085798 | PLAY RECAP
2026-01-21 14:59:29.085848 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-21 14:59:29.085871 | 
2026-01-21 14:59:29.957998 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-21 14:59:29.958729 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2026-01-21 14:59:32.546510 | 
2026-01-21 14:59:32.546612 | PLAY [all,!compute]
2026-01-21 14:59:32.620186 | 
2026-01-21 14:59:32.620316 | TASK [Enable container-tools rhel-modules for c8/crc node]
2026-01-21 14:59:33.311439 | controller | skipping: Conditional result was False
2026-01-21 14:59:33.319895 | 
2026-01-21 14:59:33.320041 | TASK [Install podman]
2026-01-21 14:59:33.491995 | controller | skipping: Conditional result was False
2026-01-21 14:59:33.498581 | 
2026-01-21 14:59:33.498699 | TASK [Set var name for quay login zuul secret]
2026-01-21 14:59:33.625836 | controller | skipping: Conditional result was False
2026-01-21 14:59:33.634162 | 
2026-01-21 14:59:33.634264 | TASK [Print the username]
2026-01-21 14:59:34.240342 | controller | skipping: Conditional result was False
2026-01-21 14:59:34.246823 | 
2026-01-21 14:59:34.246919 | TASK [Fail when user or password is not set]
2026-01-21 14:59:34.384225 | controller | skipping: Conditional result was False
2026-01-21 14:59:34.390517 | 
2026-01-21 14:59:34.390607 | TASK [Set vars for quay login]
2026-01-21 14:59:34.609425 | 
2026-01-21 14:59:34.609693 | LOOP [Perform container registry login(s) with podman]
2026-01-21 14:59:34.744822 | 
2026-01-21 14:59:34.744968 | LOOP [Perform container registry login(s) with buildah]
2026-01-21 14:59:34.890507 | 
2026-01-21 14:59:34.890651 | TASK [Set Insecure registry for content provider]
2026-01-21 14:59:35.127505 | controller | skipping: Conditional result was False
2026-01-21 14:59:35.212994 | 
2026-01-21 14:59:35.213091 | PLAY RECAP
2026-01-21 14:59:35.213149 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 9 rescued: 0 ignored: 0
2026-01-21 14:59:35.213174 | 
2026-01-21 14:59:35.895253 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2026-01-21 14:59:35.896017 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2026-01-21 14:59:38.515703 | 
2026-01-21 14:59:38.515827 | PLAY [Prefer ipv4 over ipv6]
2026-01-21 14:59:38.616546 | 
2026-01-21 14:59:38.616672 | TASK [Configure /etc/gai.conf to prever ipv4 over ipv6]
2026-01-21 14:59:38.873803 | controller | skipping: Conditional result was False
2026-01-21 14:59:38.996359 | 
2026-01-21 14:59:38.996463 | PLAY [controller]
2026-01-21 14:59:39.164935 | 
2026-01-21 14:59:39.165096 | TASK [Fail when openshift_pull_sec not provided]
2026-01-21 14:59:39.223920 | controller | skipping: Conditional result was False
2026-01-21 14:59:39.256117 | 
2026-01-21 14:59:39.256266 | TASK [Clone repos in the job workspace]
2026-01-21 14:59:39.312376 | controller | ok
2026-01-21 14:59:39.357307 | 
2026-01-21 14:59:39.357410 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-21 14:59:40.292307 | controller | ok
2026-01-21 14:59:40.336561 | 
2026-01-21 14:59:40.336671 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-21 14:59:52.214091 | controller | Output suppressed because no_log was given
2026-01-21 14:59:52.253148 | 
2026-01-21 14:59:52.253252 | TASK [Create pull-secret.txt file]
2026-01-21 14:59:54.994634 | controller | Output suppressed because no_log was given
2026-01-21 14:59:55.009671 | 
2026-01-21 14:59:55.009768 | TASK [Apply CRC features and print image build date]
2026-01-21 14:59:55.115356 | controller | ok
2026-01-21 14:59:55.152615 | controller | included: /var/lib/zuul/builds/95faca4e6bd64736a8ab8ac4ea9229e4/trusted/project_0/review.rdoproject.org/config/playbooks/crc/features.yaml
2026-01-21 14:59:55.180676 | 
2026-01-21 14:59:55.180787 | TASK [Set additional parameters for crc - telemetry]
2026-01-21 14:59:55.810619 | controller | skipping: Conditional result was False
2026-01-21 14:59:55.817031 | 
2026-01-21 14:59:55.817133 | TASK [Set additional parameters for crc - monitoring]
2026-01-21 14:59:56.412572 | controller | skipping: Conditional result was False
2026-01-21 14:59:56.418420 | 
2026-01-21 14:59:56.418496 | TASK [Set minimum required memory to run the CRC when monitoring enabled]
2026-01-21 14:59:57.008439 | controller | skipping: Conditional result was False
2026-01-21 14:59:57.014313 | 
2026-01-21 14:59:57.014405 | TASK [Set additional parameters for crc - enable microshift preset]
2026-01-21 14:59:57.645398 | controller | skipping: Conditional result was False
2026-01-21 14:59:57.651224 | 
2026-01-21 14:59:57.651311 | TASK [Set additional parameters for crc - enable okd preset]
2026-01-21 14:59:58.329370 | controller | skipping: Conditional result was False
2026-01-21 14:59:58.335169 | 
2026-01-21 14:59:58.335240 | TASK [Delete the cluster if there's a non-default bundle URL]
2026-01-21 14:59:59.021827 | controller | skipping: Conditional result was False
2026-01-21 14:59:59.027881 | 
2026-01-21 14:59:59.027976 | TASK [Execute crc start command]
2026-01-21 15:03:02.372194 | controller | ok: Runtime: 0:03:01.618541
2026-01-21 15:03:02.377689 | 
2026-01-21 15:03:02.377755 | TASK [Verify that OpenShift is up and running]
2026-01-21 15:03:02.396554 | controller | ok
2026-01-21 15:03:02.405804 | controller | included: /var/lib/zuul/builds/95faca4e6bd64736a8ab8ac4ea9229e4/trusted/project_0/review.rdoproject.org/config/playbooks/crc/verify_openshift_start.yaml
2026-01-21 15:03:02.412623 | 
2026-01-21 15:03:02.412689 | TASK [Create script to login and verify services]
2026-01-21 15:03:03.475005 | controller | changed
2026-01-21 15:03:03.480866 | 
2026-01-21 15:03:03.480952 | TASK [Ensure that user is logged]
2026-01-21 15:03:03.769195 | controller | Checking login to the cluster 1...
2026-01-21 15:03:04.212256 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:03:04.249850 | controller |
2026-01-21 15:03:04.249922 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2026-01-21 15:03:04.253465 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:03:09.254858 | controller | Checking login to the cluster 2...
2026-01-21 15:03:09.355898 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:03:09.371210 | controller |
2026-01-21 15:03:09.371270 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2026-01-21 15:03:09.375146 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:03:14.378925 | controller | Checking login to the cluster 3...
2026-01-21 15:03:14.521713 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:03:14.556689 | controller |
2026-01-21 15:03:14.556768 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2026-01-21 15:03:14.561971 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:03:19.565022 | controller | Checking login to the cluster 4...
2026-01-21 15:03:19.675383 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:03:19.700130 | controller |
2026-01-21 15:03:19.700192 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2026-01-21 15:03:19.703436 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:03:24.705100 | controller | Checking login to the cluster 5...
2026-01-21 15:03:24.815462 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:03:24.895123 | controller |
2026-01-21 15:03:24.895193 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2026-01-21 15:03:24.898937 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:03:29.901361 | controller | Checking login to the cluster 6...
2026-01-21 15:03:30.034834 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:03:30.087774 | controller |
2026-01-21 15:03:30.087835 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2026-01-21 15:03:30.092818 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:03:35.095363 | controller | Checking login to the cluster 7...
2026-01-21 15:03:35.244287 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:03:35.294357 | controller |
2026-01-21 15:03:35.294425 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2026-01-21 15:03:35.299184 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:03:40.301205 | controller | Checking login to the cluster 8...
2026-01-21 15:03:40.417695 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:03:40.707724 | controller |
2026-01-21 15:03:40.707797 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2026-01-21 15:03:40.711603 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:03:45.713628 | controller | Checking login to the cluster 9...
2026-01-21 15:03:46.009964 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:03:46.766040 | controller |
2026-01-21 15:03:46.766113 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2026-01-21 15:03:46.769790 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:03:51.771727 | controller | Checking login to the cluster 10...
2026-01-21 15:03:51.891013 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:03:52.143210 | controller |
2026-01-21 15:03:52.143274 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2026-01-21 15:03:52.147671 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:03:57.149545 | controller | Checking login to the cluster 11...
2026-01-21 15:03:57.800801 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:03:59.053537 | controller |
2026-01-21 15:03:59.053609 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2026-01-21 15:03:59.057322 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:04:04.059524 | controller | Checking login to the cluster 12...
2026-01-21 15:04:04.187246 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:04:04.513361 | controller |
2026-01-21 15:04:04.513462 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2026-01-21 15:04:04.517747 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:04:09.522395 | controller | Checking login to the cluster 13...
2026-01-21 15:04:10.453314 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:04:11.459974 | controller |
2026-01-21 15:04:11.460036 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2026-01-21 15:04:11.465435 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:04:16.469167 | controller | Checking login to the cluster 14...
2026-01-21 15:04:16.755492 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:04:16.941960 | controller |
2026-01-21 15:04:16.942023 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2026-01-21 15:04:16.946001 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:04:21.948475 | controller | Checking login to the cluster 15...
2026-01-21 15:04:22.065593 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:04:22.140351 | controller |
2026-01-21 15:04:22.140440 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2026-01-21 15:04:22.144277 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:04:27.146505 | controller | Checking login to the cluster 16...
2026-01-21 15:04:27.237386 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:04:27.273900 | controller |
2026-01-21 15:04:27.273965 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2026-01-21 15:04:27.277715 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:04:32.279445 | controller | Checking login to the cluster 17...
2026-01-21 15:04:32.388736 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:04:32.421512 | controller |
2026-01-21 15:04:32.421567 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2026-01-21 15:04:32.425394 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:04:37.427390 | controller | Checking login to the cluster 18...
2026-01-21 15:04:37.531085 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:04:37.547232 | controller |
2026-01-21 15:04:37.547267 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2026-01-21 15:04:37.551675 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:04:42.553546 | controller | Checking login to the cluster 19...
2026-01-21 15:04:42.651240 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:04:42.675338 | controller |
2026-01-21 15:04:42.675423 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2026-01-21 15:04:42.679340 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:04:47.681152 | controller | Checking login to the cluster 20...
2026-01-21 15:04:47.780800 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:04:47.797028 | controller |
2026-01-21 15:04:47.797076 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2026-01-21 15:04:47.800462 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:04:52.801988 | controller | Checking login to the cluster 21...
2026-01-21 15:04:52.883376 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:04:52.898893 | controller |
2026-01-21 15:04:52.898949 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2026-01-21 15:04:52.902876 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:04:57.904914 | controller | Checking login to the cluster 22...
2026-01-21 15:04:57.998830 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:04:58.018744 | controller |
2026-01-21 15:04:58.018785 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2026-01-21 15:04:58.022392 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:05:03.024196 | controller | Checking login to the cluster 23...
2026-01-21 15:05:03.123490 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:05:03.138464 | controller |
2026-01-21 15:05:03.138510 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2026-01-21 15:05:03.142620 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:05:08.144530 | controller | Checking login to the cluster 24...
2026-01-21 15:05:08.243018 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:05:08.258688 | controller |
2026-01-21 15:05:08.258747 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2026-01-21 15:05:08.262394 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:05:13.264133 | controller | Checking login to the cluster 25...
2026-01-21 15:05:13.346368 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:05:13.358103 | controller |
2026-01-21 15:05:13.358149 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2026-01-21 15:05:13.361686 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:05:18.363401 | controller | Checking login to the cluster 26...
2026-01-21 15:05:18.458802 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:05:18.471712 | controller |
2026-01-21 15:05:18.471765 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2026-01-21 15:05:18.476870 | controller | Can not login to the CRC cluster. Sleeping...
2026-01-21 15:05:23.478926 | controller | Checking login to the cluster 27...
2026-01-21 15:05:23.571224 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2026-01-21 15:05:24.525421 | controller |
2026-01-21 15:05:24.525489 | controller | Login successful.
2026-01-21 15:05:24.570953 | controller |
2026-01-21 15:05:24.571008 | controller | You have access to 65 projects, the list has been suppressed. You can list all projects with 'oc projects'
2026-01-21 15:05:24.584585 | controller |
2026-01-21 15:05:24.584633 | controller | Using project "default".
2026-01-21 15:05:24.678786 | controller | changed
2026-01-21 15:05:24.685133 | 
2026-01-21 15:05:24.685210 | TASK [Delete all openshift-marketplace pods]
2026-01-21 15:05:26.271203 | controller | pod "certified-operators-f8szs" deleted
2026-01-21 15:05:26.510407 | controller | pod "certified-operators-rqr58" deleted
2026-01-21 15:05:26.534830 | controller | pod "community-operators-5zsqg" deleted
2026-01-21 15:05:26.551187 | controller | pod "community-operators-kzrx2" deleted
2026-01-21 15:05:26.574574 | controller | pod "marketplace-operator-79b997595-d2lpl" deleted
2026-01-21 15:05:26.599777 | controller | pod "redhat-marketplace-ps6xk" deleted
2026-01-21 15:05:26.701577 | controller | pod "redhat-marketplace-thnww" deleted
2026-01-21 15:05:26.738115 | controller | pod "redhat-operators-n4krp" deleted
2026-01-21 15:05:26.764495 | controller | pod "redhat-operators-tqxl9" deleted
2026-01-21 15:05:29.720390 | controller | ok
2026-01-21 15:05:29.726581 | 
2026-01-21 15:05:29.726675 | TASK [Copy script ensure_services_up.sh]
2026-01-21 15:05:29.760836 | controller | skipping: Conditional result was False
2026-01-21 15:05:29.767517 | 
2026-01-21 15:05:29.767687 | TASK [Ensure all pods are up and running]
2026-01-21 15:05:30.303053 | controller | skipping: Conditional result was False
2026-01-21 15:05:30.309507 | 
2026-01-21 15:05:30.309597 | TASK [Check if there is a image build date file]
2026-01-21 15:05:30.633270 | controller | ok
2026-01-21 15:05:30.638732 | 
2026-01-21 15:05:30.638809 | TASK [Print image date if available]
2026-01-21 15:05:30.992588 | controller | Tue Apr  1 06:17:46 PM EDT 2025
2026-01-21 15:05:31.167496 | controller | changed
2026-01-21 15:05:31.172939 | 
2026-01-21 15:05:31.173023 | TASK [Fix machineconfigpool]
2026-01-21 15:05:31.207004 | controller | skipping: Conditional result was False
2026-01-21 15:05:31.213233 | 
2026-01-21 15:05:31.213328 | TASK [Show available nodes]
2026-01-21 15:05:31.861491 | controller | NAME   STATUS   ROLES                         AGE    VERSION
2026-01-21 15:05:31.863262 | controller | crc    Ready    control-plane,master,worker   332d   v1.31.5
2026-01-21 15:05:32.268474 | controller | ok: Runtime: 0:00:00.269435
2026-01-21 15:05:32.273726 | 
2026-01-21 15:05:32.273794 | TASK [Configure insecure registry inside crc vm]
2026-01-21 15:05:32.297792 | controller | skipping: Conditional result was False
2026-01-21 15:05:32.303162 | 
2026-01-21 15:05:32.303240 | TASK [Catch CRC IP]
2026-01-21 15:05:32.765035 | controller | 192.168.130.11
2026-01-21 15:05:32.832417 | controller | changed
2026-01-21 15:05:32.838072 | 
2026-01-21 15:05:32.838151 | TASK [Check if id_ed25519 key exists]
2026-01-21 15:05:33.211927 | controller | ok
2026-01-21 15:05:33.217837 | 
2026-01-21 15:05:33.217922 | TASK [Set fact if new keypair exists]
2026-01-21 15:05:33.246862 | controller | ok
2026-01-21 15:05:33.251563 | 
2026-01-21 15:05:33.251728 | TASK [Run crc]
2026-01-21 15:05:34.807486 | controller |
2026-01-21 15:05:39.923462 | controller | PLAY [OpenShift secret copy and CRC securitization] ****************************
2026-01-21 15:05:39.923502 | controller |
2026-01-21 15:05:39.923510 | controller | TASK [Gathering Facts] *********************************************************
2026-01-21 15:05:39.923522 | controller | [WARNING]: Platform linux on host 192.168.130.11 is using the discovered Python
2026-01-21 15:05:39.979873 | controller | interpreter at /usr/bin/python3.9, but future installation of another Python
2026-01-21 15:05:39.979914 | controller | interpreter could change the meaning of that path. See
2026-01-21 15:05:39.979930 | controller | https://docs.ansible.com/ansible-
2026-01-21 15:05:39.979937 | controller | core/2.14/reference_appendices/interpreter_discovery.html for more information.
2026-01-21 15:05:39.979950 | controller | ok: [192.168.130.11]
2026-01-21 15:05:41.641235 | controller |
2026-01-21 15:05:41.641289 | controller | TASK [Copy pull-secret] ********************************************************
2026-01-21 15:05:41.641303 | controller | changed: [192.168.130.11]
2026-01-21 15:05:42.788208 | controller |
2026-01-21 15:05:42.788263 | controller | TASK [Add crio config] *********************************************************
2026-01-21 15:05:42.788279 | controller | changed: [192.168.130.11]
2026-01-21 15:05:45.844447 | controller |
2026-01-21 15:05:45.844537 | controller | TASK [Restart crio to pickup new configuration] ********************************
2026-01-21 15:05:45.844568 | controller | changed: [192.168.130.11]
2026-01-21 15:05:45.882103 | controller |
2026-01-21 15:05:45.882164 | controller | PLAY RECAP *********************************************************************
2026-01-21 15:05:45.882173 | controller | 192.168.130.11             : ok=4    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-01-21 15:05:45.882179 | controller |
2026-01-21 15:05:46.316874 | controller | changed
2026-01-21 15:05:46.322177 | 
2026-01-21 15:05:46.322243 | TASK [Set slower etcd profile]
2026-01-21 15:05:46.360816 | controller | ok
2026-01-21 15:05:46.381847 | 
2026-01-21 15:05:46.381954 | TASK [prepare-crc-cloud : Set slower profile]
2026-01-21 15:05:46.808452 | controller | etcd.operator.openshift.io/cluster patched
2026-01-21 15:05:46.907720 | controller | ok
2026-01-21 15:05:46.913191 | 
2026-01-21 15:05:46.913263 | TASK [prepare-crc-cloud : Wait 30 seconds to apply]
2026-01-21 15:06:16.938090 | controller | ok
2026-01-21 15:06:16.943372 | 
2026-01-21 15:06:16.943450 | TASK [prepare-crc-cloud : Get pod phase status]
2026-01-21 15:06:32.604000 | controller | ok
2026-01-21 15:06:32.614378 | 
2026-01-21 15:06:32.614463 | TASK [Set limit for disk usage - IOPS and Read/Write]
2026-01-21 15:06:32.684642 | controller | ok
2026-01-21 15:06:32.714474 | controller | included: /var/lib/zuul/builds/95faca4e6bd64736a8ab8ac4ea9229e4/trusted/project_0/review.rdoproject.org/config/playbooks/crc/limit-disk-usage.yaml
2026-01-21 15:06:32.723210 | 
2026-01-21 15:06:32.723584 | TASK [Set facts for disk limitation]
2026-01-21 15:06:32.742529 | controller | ok
2026-01-21 15:06:32.748018 | 
2026-01-21 15:06:32.748085 | TASK [Print limits for the disk]
2026-01-21 15:06:32.777187 | controller | ok: Set limit to crc VM to the vda disk: iops 18000 read/write 262144000
2026-01-21 15:06:32.781860 | 
2026-01-21 15:06:32.781928 | TASK [Set limit to crc VM to the vda disk]
2026-01-21 15:06:33.110251 | controller |
2026-01-21 15:06:33.310108 | controller | changed
2026-01-21 15:06:33.316162 | 
2026-01-21 15:06:33.316255 | TASK [Pause machineconfigpool]
2026-01-21 15:06:33.356662 | controller | ok
2026-01-21 15:06:33.381724 | 
2026-01-21 15:06:33.381810 | LOOP [prepare-crc-cloud : Pause the machineconfig configuration if mcp is not needed]
2026-01-21 15:06:33.806777 | controller | ok
2026-01-21 15:06:34.141438 | controller | ok
2026-01-21 15:06:34.149067 | 
2026-01-21 15:06:34.149167 | TASK [prepare-crc-cloud : Print current machineconfigpool]
2026-01-21 15:06:34.547133 | controller | NAME     CONFIG                                             UPDATED   UPDATING   DEGRADED   MACHINECOUNT   READYMACHINECOUNT   UPDATEDMACHINECOUNT   DEGRADEDMACHINECOUNT   AGE
2026-01-21 15:06:34.547223 | controller | master   rendered-master-95020e6e79c40b4dd095817bccfc5060   True      False      False      1              1                   1                     0                      332d
2026-01-21 15:06:34.547237 | controller | worker   rendered-worker-594cc07c9feefb5e37f2530ac457d36c   True      False      False      0              0                   0                     0                      332d
2026-01-21 15:06:34.675679 | controller | ok
2026-01-21 15:06:34.685921 | 
2026-01-21 15:06:34.685985 | TASK [Create cluster certificate approver workaround]
2026-01-21 15:06:34.724877 | controller | ok
2026-01-21 15:06:34.751540 | 
2026-01-21 15:06:34.751642 | TASK [prepare-crc-cloud : Get OpenShift version]
2026-01-21 15:06:35.135786 | controller | 4.18.1
2026-01-21 15:06:35.277792 | controller | ok
2026-01-21 15:06:35.284484 | 
2026-01-21 15:06:35.284563 | TASK [prepare-crc-cloud : Create service account for - auto-csr-approver]
2026-01-21 15:06:35.308649 | controller | skipping: Conditional result was False
2026-01-21 15:06:35.314610 | 
2026-01-21 15:06:35.314702 | TASK [prepare-crc-cloud : Apply cluster cert approver]
2026-01-21 15:06:35.840505 | controller | skipping: Conditional result was False
2026-01-21 15:06:35.851707 | 
2026-01-21 15:06:35.851792 | TASK [Ensure that all cluster operators are stable]
2026-01-21 15:06:36.286449 | controller | True
2026-01-21 15:06:36.286528 | controller | True
2026-01-21 15:06:36.286538 | controller | False
2026-01-21 15:06:36.286543 | controller | True
2026-01-21 15:06:36.286549 | controller | False
2026-01-21 15:06:36.286554 | controller | True
2026-01-21 15:06:36.286559 | controller | False
2026-01-21 15:06:36.286564 | controller | True
2026-01-21 15:06:36.286570 | controller | True
2026-01-21 15:06:36.286575 | controller | True
2026-01-21 15:06:36.286589 | controller | True
2026-01-21 15:06:36.286599 | controller | True
2026-01-21 15:06:36.286742 | controller | True
2026-01-21 15:06:36.286771 | controller | True
2026-01-21 15:06:36.286777 | controller | True
2026-01-21 15:06:36.286783 | controller | True
2026-01-21 15:06:36.286788 | controller | True
2026-01-21 15:06:36.286793 | controller | True
2026-01-21 15:06:36.286798 | controller | False
2026-01-21 15:06:36.286803 | controller | True
2026-01-21 15:06:36.286809 | controller | True
2026-01-21 15:06:36.286814 | controller | True
2026-01-21 15:06:36.286819 | controller | True
2026-01-21 15:06:36.286824 | controller | True
2026-01-21 15:09:19.026902 | controller | changed
2026-01-21 15:09:19.044714 | 
2026-01-21 15:09:19.044784 | PLAY [Set IOPS and Read/Write disk limitation excluding controller]
2026-01-21 15:09:19.044869 | 
2026-01-21 15:09:19.044896 | PLAY RECAP
2026-01-21 15:09:19.044929 | controller | ok: 27 changed: 11 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0
2026-01-21 15:09:19.044950 | 
2026-01-21 15:09:19.127154 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2026-01-21 15:09:19.127879 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-21 15:09:19.637026 | 
2026-01-21 15:09:19.637126 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-01-21 15:09:19.658609 | 
2026-01-21 15:09:19.658683 | TASK [Create zuul-output directory]
2026-01-21 15:09:20.042659 | controller | changed
2026-01-21 15:09:20.048030 | 
2026-01-21 15:09:20.048131 | TASK [Slurp Zuul inventory test]
2026-01-21 15:09:20.325532 | controller -> localhost | ok
2026-01-21 15:09:20.331711 | 
2026-01-21 15:09:20.331778 | TASK [Save zuul inventory]
2026-01-21 15:09:21.517856 | controller | changed
2026-01-21 15:09:21.523290 | 
2026-01-21 15:09:21.523413 | TASK [Save zuul vars without the change_message]
2026-01-21 15:09:22.534777 | controller | changed
2026-01-21 15:09:22.552898 | 
2026-01-21 15:09:22.552950 | PLAY RECAP
2026-01-21 15:09:22.552995 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-21 15:09:22.553017 | 
2026-01-21 15:09:22.618997 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-21 15:09:22.619754 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-01-21 15:09:23.171533 | 
2026-01-21 15:09:23.171637 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-01-21 15:09:23.192551 | 
2026-01-21 15:09:23.192642 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-01-21 15:09:23.210991 | controller | ok
2026-01-21 15:09:23.228667 | 
2026-01-21 15:09:23.228766 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-01-21 15:09:23.252553 | controller | skipping: Conditional result was False
2026-01-21 15:09:23.258231 | 
2026-01-21 15:09:23.258334 | TASK [mirror-info-fork : Create /etc/ci]
2026-01-21 15:09:23.641063 | controller | ok
2026-01-21 15:09:23.646680 | 
2026-01-21 15:09:23.646751 | TASK [mirror-info-fork : Install ci_mirror script]
2026-01-21 15:09:24.578608 | controller | ok
2026-01-21 15:09:24.588378 | 
2026-01-21 15:09:24.588488 | TASK [Prepare workspace]
2026-01-21 15:09:24.606268 | controller | ok
2026-01-21 15:09:24.624752 | 
2026-01-21 15:09:24.624844 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-21 15:09:24.978434 | controller | ok
2026-01-21 15:09:24.984248 | 
2026-01-21 15:09:24.984347 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-21 15:09:25.893978 | controller | Output suppressed because no_log was given
2026-01-21 15:09:25.903103 | 
2026-01-21 15:09:25.903168 | LOOP [Create zuul-output directory]
2026-01-21 15:09:26.162787 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-01-21 15:09:26.399815 | controller | ok: "/home/zuul/zuul-output/logs"
2026-01-21 15:09:26.409419 | 
2026-01-21 15:09:26.409485 | TASK [Install required packages]
2026-01-21 15:09:43.507366 | controller | ok: Nothing to do
2026-01-21 15:09:43.512555 | 
2026-01-21 15:09:43.512624 | TASK [Install venv]
2026-01-21 15:10:44.256980 | controller | changed
2026-01-21 15:10:44.281514 | 
2026-01-21 15:10:44.281679 | PLAY RECAP
2026-01-21 15:10:44.281730 | controller | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-01-21 15:10:44.281754 | 
2026-01-21 15:10:44.357284 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-01-21 15:10:44.358099 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-01-21 15:10:44.897833 | 
2026-01-21 15:10:44.897939 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-01-21 15:10:44.918649 | 
2026-01-21 15:10:44.918735 | TASK [Gather required facts]
2026-01-21 15:10:45.505312 | controller | ok
2026-01-21 15:10:45.510920 | 
2026-01-21 15:10:45.511016 | TASK [Load environment var if instructed to]
2026-01-21 15:10:45.534870 | controller | skipping: Conditional result was False
2026-01-21 15:10:45.540442 | 
2026-01-21 15:10:45.540518 | TASK [Ensure group_vars dir exists]
2026-01-21 15:10:45.944211 | controller | ok
2026-01-21 15:10:45.949378 | 
2026-01-21 15:10:45.949447 | TASK [Print related variables]
2026-01-21 15:10:45.978420 | controller | ok:
2026-01-21 15:10:45.978550 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-01-21 15:10:45.978579 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_multus
2026-01-21 15:10:45.983240 | 
2026-01-21 15:10:45.983337 | TASK [Run molecule]
2026-01-21 15:10:47.020292 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-01-21 15:10:47.250150 | controller | INFO     Running default > destroy
2026-01-21 15:10:47.250420 | controller | WARNING  Skipping, instances are delegated.
2026-01-21 15:10:47.258913 | controller | INFO     Running default > create
2026-01-21 15:10:47.259839 | controller | WARNING  Skipping, instances are delegated.
2026-01-21 15:10:47.267725 | controller | INFO     Running default > converge
2026-01-21 15:10:47.930033 | controller |
2026-01-21 15:10:47.930148 | controller | PLAY [Converge] ****************************************************************
2026-01-21 15:10:47.930291 | controller |
2026-01-21 15:10:47.930340 | controller | TASK [Gathering Facts] *********************************************************
2026-01-21 15:10:47.930481 | controller | Wednesday 21 January 2026  15:10:47 +0000 (0:00:00.013)       0:00:00.013 *****
2026-01-21 15:10:48.844190 | controller | ok: [instance]
2026-01-21 15:10:48.844283 | controller |
2026-01-21 15:10:48.844425 | controller | TASK [Add crc hostname with it's IP to /etc/hosts] *****************************
2026-01-21 15:10:48.844584 | controller | Wednesday 21 January 2026  15:10:48 +0000 (0:00:00.915)       0:00:00.928 *****
2026-01-21 15:10:49.136945 | controller | changed: [instance]
2026-01-21 15:10:49.137073 | controller |
2026-01-21 15:10:49.137168 | controller | TASK [Check if new ssh keypair exists] *****************************************
2026-01-21 15:10:49.137279 | controller | Wednesday 21 January 2026  15:10:49 +0000 (0:00:00.292)       0:00:01.220 *****
2026-01-21 15:10:49.166311 | controller |
2026-01-21 15:10:49.166438 | controller | TASK [recognize_ssh_keypair : Check if id_ed25519 key exists] ******************
2026-01-21 15:10:49.166574 | controller | Wednesday 21 January 2026  15:10:49 +0000 (0:00:00.029)       0:00:01.250 *****
2026-01-21 15:10:49.461779 | controller | ok: [instance]
2026-01-21 15:10:49.461879 | controller |
2026-01-21 15:10:49.462005 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair exists] ******************
2026-01-21 15:10:49.462118 | controller | Wednesday 21 January 2026  15:10:49 +0000 (0:00:00.295)       0:00:01.545 *****
2026-01-21 15:10:49.481916 | controller | ok: [instance]
2026-01-21 15:10:49.482037 | controller |
2026-01-21 15:10:49.482171 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair does not exists] *********
2026-01-21 15:10:49.482265 | controller | Wednesday 21 January 2026  15:10:49 +0000 (0:00:00.020)       0:00:01.566 *****
2026-01-21 15:10:49.503706 | controller | skipping: [instance]
2026-01-21 15:10:49.503838 | controller |
2026-01-21 15:10:49.503990 | controller | TASK [Add the crc host dynamically] ********************************************
2026-01-21 15:10:49.504102 | controller | Wednesday 21 January 2026  15:10:49 +0000 (0:00:00.021)       0:00:01.587 *****
2026-01-21 15:10:49.522768 | controller | changed: [instance]
2026-01-21 15:10:49.522885 | controller |
2026-01-21 15:10:49.523009 | controller | TASK [Fetch crc network facts] *************************************************
2026-01-21 15:10:49.523127 | controller | Wednesday 21 January 2026  15:10:49 +0000 (0:00:00.019)       0:00:01.606 *****
2026-01-21 15:10:52.529882 | controller | ok: [instance -> crc]
2026-01-21 15:10:52.529997 | controller |
2026-01-21 15:10:52.530017 | controller | TASK [Load shared variables] ***************************************************
2026-01-21 15:10:52.530117 | controller | Wednesday 21 January 2026  15:10:52 +0000 (0:00:03.006)       0:00:04.613 *****
2026-01-21 15:10:52.584699 | controller | ok: [instance]
2026-01-21 15:10:52.584786 | controller |
2026-01-21 15:10:52.584938 | controller | TASK [Call ci_multus role] *****************************************************
2026-01-21 15:10:52.585078 | controller | Wednesday 21 January 2026  15:10:52 +0000 (0:00:00.055)       0:00:04.668 *****
2026-01-21 15:10:52.624748 | controller |
2026-01-21 15:10:52.624871 | controller | TASK [ci_multus : Create manifests directory] **********************************
2026-01-21 15:10:52.625017 | controller | Wednesday 21 January 2026  15:10:52 +0000 (0:00:00.040)       0:00:04.708 *****
2026-01-21 15:10:52.921179 | controller | changed: [instance]
2026-01-21 15:10:52.921249 | controller |
2026-01-21 15:10:52.921381 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2026-01-21 15:10:52.921519 | controller | Wednesday 21 January 2026  15:10:52 +0000 (0:00:00.296)       0:00:05.005 *****
2026-01-21 15:10:52.946470 | controller | skipping: [instance]
2026-01-21 15:10:52.946564 | controller |
2026-01-21 15:10:52.946675 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2026-01-21 15:10:52.946775 | controller | Wednesday 21 January 2026  15:10:52 +0000 (0:00:00.025)       0:00:05.030 *****
2026-01-21 15:10:52.970516 | controller | skipping: [instance]
2026-01-21 15:10:52.970595 | controller |
2026-01-21 15:10:52.970702 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2026-01-21 15:10:52.970958 | controller | Wednesday 21 January 2026  15:10:52 +0000 (0:00:00.022)       0:00:05.053 *****
2026-01-21 15:10:52.994738 | controller | skipping: [instance]
2026-01-21 15:10:52.994843 | controller |
2026-01-21 15:10:52.995060 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2026-01-21 15:10:52.995085 | controller | Wednesday 21 January 2026  15:10:52 +0000 (0:00:00.025)       0:00:05.078 *****
2026-01-21 15:10:53.022612 | controller | skipping: [instance]
2026-01-21 15:10:53.022670 | controller |
2026-01-21 15:10:53.022801 | controller | TASK [ci_multus : Gather network layout from OCP host or default networks] *****
2026-01-21 15:10:53.022930 | controller | Wednesday 21 January 2026  15:10:53 +0000 (0:00:00.028)       0:00:05.106 *****
2026-01-21 15:10:53.055488 | controller | ok: [instance]
2026-01-21 15:10:53.055560 | controller |
2026-01-21 15:10:53.055675 | controller | TASK [ci_multus : Merge any available multus net info patches] *****************
2026-01-21 15:10:53.055785 | controller | Wednesday 21 January 2026  15:10:53 +0000 (0:00:00.032)       0:00:05.139 *****
2026-01-21 15:10:53.256382 | controller | ok: [instance] => (item={'default': {'interface_name': 'eth0', 'network_name': 'default', 'gw_v4': '192.168.122.1', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'deny_network': {'gw_v4': '192.168.122.1', 'network_name': 'deny_network', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'not_allowed_network': {'gw_v4': '192.168.122.1', 'network_name': 'not_allowed_network', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'no_multus_network': {'gw_v4': '192.168.122.1', 'network_name': 'patchnetwork', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth2'}})
2026-01-21 15:10:53.256522 | controller | ok: [instance] => (item={'patchnetwork': {'gw_v4': '192.168.122.1', 'network_name': 'patchnetwork', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth2', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'macvlan'}}}})
2026-01-21 15:10:53.256661 | controller | ok: [instance] => (item={'bridge-to-linux-bridge': {'gw_v4': '192.168.122.1', 'network_name': 'bridge-to-linux-bridge', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth1', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge', 'multus_attach': 'linux-bridge'}}}})
2026-01-21 15:10:53.256761 | controller |
2026-01-21 15:10:53.256891 | controller | TASK [ci_multus : Remove any networks without Multus networking defined] *******
2026-01-21 15:10:53.256986 | controller | Wednesday 21 January 2026  15:10:53 +0000 (0:00:00.200)       0:00:05.340 *****
2026-01-21 15:10:53.339286 | controller | ok: [instance]
2026-01-21 15:10:53.339356 | controller |
2026-01-21 15:10:53.339503 | controller | TASK [ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined] ***
2026-01-21 15:10:53.339612 | controller | Wednesday 21 January 2026  15:10:53 +0000 (0:00:00.082)       0:00:05.423 *****
2026-01-21 15:10:53.408577 | controller | ok: [instance]
2026-01-21 15:10:53.408636 | controller |
2026-01-21 15:10:53.408757 | controller | TASK [ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined] ***
2026-01-21 15:10:53.408877 | controller | Wednesday 21 January 2026  15:10:53 +0000 (0:00:00.069)       0:00:05.492 *****
2026-01-21 15:10:53.474525 | controller | ok: [instance]
2026-01-21 15:10:53.474577 | controller |
2026-01-21 15:10:53.474692 | controller | TASK [ci_multus : Render NetworkAttachmenktDefinition manifests] ***************
2026-01-21 15:10:53.474795 | controller | Wednesday 21 January 2026  15:10:53 +0000 (0:00:00.065)       0:00:05.558 *****
2026-01-21 15:10:53.927122 | controller | changed: [instance]
2026-01-21 15:10:53.927188 | controller |
2026-01-21 15:10:53.927520 | controller | TASK [ci_multus : Create the multus namespace] *********************************
2026-01-21 15:11:54.738326 | controller | Wednesday 21 January 2026  15:10:53 +0000 (0:00:00.452)       0:00:06.010 *****
2026-01-21 15:11:54.738386 | controller | fatal: [instance]: FAILED! => changed=false
2026-01-21 15:11:54.740443 | controller |   msg: 'Failed to retrieve requested object: b''{"metadata":{},"status":"Failure","message":"Timeout: request did not complete within the allotted timeout","reason":"Timeout","details":{},"code":504}\n'''
2026-01-21 15:11:54.740480 | controller |   reason: Gateway Timeout
2026-01-21 15:11:54.740492 | controller |
2026-01-21 15:11:54.740548 | controller | PLAY RECAP *********************************************************************
2026-01-21 15:11:54.740674 | controller | instance                   : ok=14   changed=4    unreachable=0    failed=1    skipped=5    rescued=0    ignored=0
2026-01-21 15:11:54.741031 | controller |
2026-01-21 15:11:54.741198 | controller | Wednesday 21 January 2026  15:11:54 +0000 (0:01:00.813)       0:01:06.824 *****
2026-01-21 15:11:54.741211 | controller | ===============================================================================
2026-01-21 15:11:54.741219 | controller | ci_multus : Create the multus namespace -------------------------------- 60.81s
2026-01-21 15:11:54.741461 | controller | Fetch crc network facts ------------------------------------------------- 3.01s
2026-01-21 15:11:54.741483 | controller | Gathering Facts --------------------------------------------------------- 0.92s
2026-01-21 15:11:54.741507 | controller | ci_multus : Render NetworkAttachmenktDefinition manifests --------------- 0.45s
2026-01-21 15:11:54.741517 | controller | ci_multus : Create manifests directory ---------------------------------- 0.30s
2026-01-21 15:11:54.741612 | controller | recognize_ssh_keypair : Check if id_ed25519 key exists ------------------ 0.30s
2026-01-21 15:11:54.742013 | controller | Add crc hostname with it's IP to /etc/hosts ----------------------------- 0.29s
2026-01-21 15:11:54.742294 | controller | ci_multus : Merge any available multus net info patches ----------------- 0.20s
2026-01-21 15:11:54.742327 | controller | ci_multus : Remove any networks without Multus networking defined ------- 0.08s
2026-01-21 15:11:54.742335 | controller | ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined --- 0.07s
2026-01-21 15:11:54.742345 | controller | ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined --- 0.07s
2026-01-21 15:11:54.742370 | controller | Load shared variables --------------------------------------------------- 0.06s
2026-01-21 15:11:54.742377 | controller | Call ci_multus role ----------------------------------------------------- 0.04s
2026-01-21 15:11:54.742384 | controller | ci_multus : Gather network layout from OCP host or default networks ----- 0.03s
2026-01-21 15:11:54.742502 | controller | Check if new ssh keypair exists ----------------------------------------- 0.03s
2026-01-21 15:11:54.742747 | controller | networking_mapper : Set cifmw_networking_env_definition is present ------ 0.03s
2026-01-21 15:11:54.742783 | controller | networking_mapper : Check for Networking Environment Definition file existence --- 0.03s
2026-01-21 15:11:54.742793 | controller | networking_mapper : Load the Networking Definition from file ------------ 0.03s
2026-01-21 15:11:54.743003 | controller | networking_mapper : Check for Networking Definition file existance ------ 0.02s
2026-01-21 15:11:54.797715 | controller | recognize_ssh_keypair : Set fact if new keypair does not exists --------- 0.02s
2026-01-21 15:11:54.797754 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/ci_multus/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_multus/molecule/default/converge.yml']
2026-01-21 15:11:54.798283 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2026-01-21 15:11:54.807695 | controller | INFO     Running default > cleanup
2026-01-21 15:11:54.808194 | controller | WARNING  Skipping, cleanup playbook not configured.
2026-01-21 15:11:54.816014 | controller | INFO     Running default > destroy
2026-01-21 15:11:54.816382 | controller | WARNING  Skipping, instances are delegated.
2026-01-21 15:11:54.816747 | controller | INFO     Pruning extra files from scenario ephemeral directory
2026-01-21 15:11:54.818270 | controller | INFO     Writing /tmp/report.html report.
2026-01-21 15:11:55.084818 | controller | ERROR
2026-01-21 15:11:55.084968 | controller | {
2026-01-21 15:11:55.085005 | controller |   "delta": "0:01:08.494287",
2026-01-21 15:11:55.085031 | controller |   "end": "2026-01-21 15:11:54.865664",
2026-01-21 15:11:55.085052 | controller |   "msg": "non-zero return code",
2026-01-21 15:11:55.085070 | controller |   "rc": 1,
2026-01-21 15:11:55.085088 | controller |   "start": "2026-01-21 15:10:46.371377"
2026-01-21 15:11:55.085106 | controller | }
failure
2026-01-21 15:11:55.087032 | 
2026-01-21 15:11:55.087088 | PLAY RECAP
2026-01-21 15:11:55.087136 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-01-21 15:11:55.087160 | 
2026-01-21 15:11:55.164469 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-01-21 15:11:55.165971 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-21 15:11:55.698166 | 
2026-01-21 15:11:55.698351 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-01-21 15:11:55.719412 | 
2026-01-21 15:11:55.719507 | TASK [Filter out host if needed]
2026-01-21 15:11:55.728289 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-01-21 15:11:55.732898 | 
2026-01-21 15:11:55.732973 | TASK [Ensure file is present]
2026-01-21 15:11:56.127392 | controller | ok
2026-01-21 15:11:56.133039 | 
2026-01-21 15:11:56.133111 | TASK [Manage molecule report file]
2026-01-21 15:11:56.658420 | controller | changed
2026-01-21 15:11:56.663845 | 
2026-01-21 15:11:56.663927 | TASK [Check if we get ci-framework-data basedir]
2026-01-21 15:11:56.915495 | controller | ok
2026-01-21 15:11:56.920905 | 
2026-01-21 15:11:56.920971 | TASK [Create ci-framework-data log directory for zuul]
2026-01-21 15:11:57.285768 | controller | changed
2026-01-21 15:11:57.291585 | 
2026-01-21 15:11:57.291650 | TASK [Copy ci-framework interesting files]
2026-01-21 15:11:57.544556 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-01-21 15:11:57.817381 | controller | changed
2026-01-21 15:11:57.823066 | 
2026-01-21 15:11:57.823137 | TASK [Get SELinux listing]
2026-01-21 15:11:58.348520 | controller | changed
2026-01-21 15:11:58.354646 | 
2026-01-21 15:11:58.354742 | TASK [Generate log index]
2026-01-21 15:11:59.578130 | controller | changed
2026-01-21 15:11:59.583412 | 
2026-01-21 15:11:59.583511 | TASK [Get some env related data]
2026-01-21 15:12:00.225975 | controller | /home/zuul/.local/bin/ansible
2026-01-21 15:12:01.112028 | controller | changed
2026-01-21 15:12:01.117476 | 
2026-01-21 15:12:01.117544 | TASK [Generate list of logs to collect in home directory]
2026-01-21 15:12:01.482838 | controller | ok: All paths examined
2026-01-21 15:12:01.488207 | 
2026-01-21 15:12:01.488284 | LOOP [Copy logs from home directory]
2026-01-21 15:12:02.110836 | controller | changed:
2026-01-21 15:12:02.111183 | controller | {
2026-01-21 15:12:02.111229 | controller |   "atime": 1743544925.4788878,
2026-01-21 15:12:02.111253 | controller |   "ctime": 1743545329.1409318,
2026-01-21 15:12:02.111290 | controller |   "dev": 64513,
2026-01-21 15:12:02.111348 | controller |   "gid": 1000,
2026-01-21 15:12:02.111369 | controller |   "gr_name": "zuul",
2026-01-21 15:12:02.111386 | controller |   "inode": 4518807,
2026-01-21 15:12:02.111401 | controller |   "isblk": false,
2026-01-21 15:12:02.111416 | controller |   "ischr": false,
2026-01-21 15:12:02.111430 | controller |   "isdir": false,
2026-01-21 15:12:02.111443 | controller |   "isfifo": false,
2026-01-21 15:12:02.111456 | controller |   "isgid": false,
2026-01-21 15:12:02.111470 | controller |   "islnk": false,
2026-01-21 15:12:02.111483 | controller |   "isreg": true,
2026-01-21 15:12:02.111496 | controller |   "issock": false,
2026-01-21 15:12:02.111508 | controller |   "isuid": false,
2026-01-21 15:12:02.111521 | controller |   "mode": "0644",
2026-01-21 15:12:02.111546 | controller |   "mtime": 1743545329.1409318,
2026-01-21 15:12:02.111561 | controller |   "nlink": 1,
2026-01-21 15:12:02.111574 | controller |   "path": "/home/zuul/crc-setup.log",
2026-01-21 15:12:02.111589 | controller |   "pw_name": "zuul",
2026-01-21 15:12:02.111603 | controller |   "rgrp": true,
2026-01-21 15:12:02.111616 | controller |   "roth": true,
2026-01-21 15:12:02.111629 | controller |   "rusr": true,
2026-01-21 15:12:02.111642 | controller |   "size": 4108,
2026-01-21 15:12:02.111655 | controller |   "uid": 1000,
2026-01-21 15:12:02.111668 | controller |   "wgrp": false,
2026-01-21 15:12:02.111683 | controller |   "woth": false,
2026-01-21 15:12:02.111700 | controller |   "wusr": true,
2026-01-21 15:12:02.111716 | controller |   "xgrp": false,
2026-01-21 15:12:02.111730 | controller |   "xoth": false,
2026-01-21 15:12:02.111744 | controller |   "xusr": false
2026-01-21 15:12:02.111758 | controller | }
2026-01-21 15:12:02.735517 | controller | changed:
2026-01-21 15:12:02.735651 | controller | {
2026-01-21 15:12:02.735677 | controller |   "atime": 1769007783.7645404,
2026-01-21 15:12:02.735697 | controller |   "ctime": 1769007782.3325455,
2026-01-21 15:12:02.735713 | controller |   "dev": 64513,
2026-01-21 15:12:02.735729 | controller |   "gid": 1000,
2026-01-21 15:12:02.735743 | controller |   "gr_name": "zuul",
2026-01-21 15:12:02.735758 | controller |   "inode": 4194437,
2026-01-21 15:12:02.735773 | controller |   "isblk": false,
2026-01-21 15:12:02.735787 | controller |   "ischr": false,
2026-01-21 15:12:02.735800 | controller |   "isdir": false,
2026-01-21 15:12:02.735814 | controller |   "isfifo": false,
2026-01-21 15:12:02.735828 | controller |   "isgid": false,
2026-01-21 15:12:02.735842 | controller |   "islnk": false,
2026-01-21 15:12:02.735856 | controller |   "isreg": true,
2026-01-21 15:12:02.735869 | controller |   "issock": false,
2026-01-21 15:12:02.735882 | controller |   "isuid": false,
2026-01-21 15:12:02.735896 | controller |   "mode": "0644",
2026-01-21 15:12:02.735909 | controller |   "mtime": 1769007782.3325455,
2026-01-21 15:12:02.735922 | controller |   "nlink": 1,
2026-01-21 15:12:02.735935 | controller |   "path": "/home/zuul/crc-start.log",
2026-01-21 15:12:02.735949 | controller |   "pw_name": "zuul",
2026-01-21 15:12:02.735963 | controller |   "rgrp": true,
2026-01-21 15:12:02.735977 | controller |   "roth": true,
2026-01-21 15:12:02.735991 | controller |   "rusr": true,
2026-01-21 15:12:02.736004 | controller |   "size": 3404,
2026-01-21 15:12:02.736018 | controller |   "uid": 1000,
2026-01-21 15:12:02.736031 | controller |   "wgrp": false,
2026-01-21 15:12:02.736044 | controller |   "woth": false,
2026-01-21 15:12:02.736056 | controller |   "wusr": true,
2026-01-21 15:12:02.736069 | controller |   "xgrp": false,
2026-01-21 15:12:02.736082 | controller |   "xoth": false,
2026-01-21 15:12:02.736095 | controller |   "xusr": false
2026-01-21 15:12:02.736107 | controller | }
2026-01-21 15:12:03.352368 | controller | changed:
2026-01-21 15:12:03.352469 | controller | {
2026-01-21 15:12:03.352496 | controller |   "atime": 1769008227.6843698,
2026-01-21 15:12:03.352515 | controller |   "ctime": 1769008243.8285298,
2026-01-21 15:12:03.352532 | controller |   "dev": 64513,
2026-01-21 15:12:03.352548 | controller |   "gid": 1000,
2026-01-21 15:12:03.352564 | controller |   "gr_name": "zuul",
2026-01-21 15:12:03.352579 | controller |   "inode": 4685809,
2026-01-21 15:12:03.352593 | controller |   "isblk": false,
2026-01-21 15:12:03.352607 | controller |   "ischr": false,
2026-01-21 15:12:03.352620 | controller |   "isdir": false,
2026-01-21 15:12:03.352633 | controller |   "isfifo": false,
2026-01-21 15:12:03.352647 | controller |   "isgid": false,
2026-01-21 15:12:03.352660 | controller |   "islnk": false,
2026-01-21 15:12:03.352674 | controller |   "isreg": true,
2026-01-21 15:12:03.352688 | controller |   "issock": false,
2026-01-21 15:12:03.352701 | controller |   "isuid": false,
2026-01-21 15:12:03.352714 | controller |   "mode": "0644",
2026-01-21 15:12:03.352733 | controller |   "mtime": 1769008243.8285298,
2026-01-21 15:12:03.352751 | controller |   "nlink": 1,
2026-01-21 15:12:03.352767 | controller |   "path": "/home/zuul/ansible.log",
2026-01-21 15:12:03.352782 | controller |   "pw_name": "zuul",
2026-01-21 15:12:03.352797 | controller |   "rgrp": true,
2026-01-21 15:12:03.352812 | controller |   "roth": true,
2026-01-21 15:12:03.352828 | controller |   "rusr": true,
2026-01-21 15:12:03.352843 | controller |   "size": 6749,
2026-01-21 15:12:03.352857 | controller |   "uid": 1000,
2026-01-21 15:12:03.352870 | controller |   "wgrp": false,
2026-01-21 15:12:03.352883 | controller |   "woth": false,
2026-01-21 15:12:03.352896 | controller |   "wusr": true,
2026-01-21 15:12:03.352910 | controller |   "xgrp": false,
2026-01-21 15:12:03.352923 | controller |   "xoth": false,
2026-01-21 15:12:03.352936 | controller |   "xusr": false
2026-01-21 15:12:03.352948 | controller | }
2026-01-21 15:12:03.365613 | 
2026-01-21 15:12:03.365731 | TASK [Copy crio stats log file]
2026-01-21 15:12:03.379848 | controller | skipping: Conditional result was False
2026-01-21 15:12:03.386064 | 
2026-01-21 15:12:03.386159 | TASK [Get SELinux related data]
2026-01-21 15:12:03.678895 | controller | <no matches>
2026-01-21 15:12:03.916410 | controller | ERROR
2026-01-21 15:12:03.916629 | controller | {
2026-01-21 15:12:03.916661 | controller |   "delta": "0:00:00.009165",
2026-01-21 15:12:03.916682 | controller |   "end": "2026-01-21 15:12:03.679809",
2026-01-21 15:12:03.916700 | controller |   "msg": "non-zero return code",
2026-01-21 15:12:03.916717 | controller |   "rc": 1,
2026-01-21 15:12:03.916733 | controller |   "start": "2026-01-21 15:12:03.670644"
2026-01-21 15:12:03.916747 | controller | }
2026-01-21 15:12:03.916767 | controller | ERROR: Ignoring Errors
2026-01-21 15:12:03.921914 | 
2026-01-21 15:12:03.921977 | TASK [Create system configuration directory]
2026-01-21 15:12:04.214492 | controller | changed
2026-01-21 15:12:04.219279 | 
2026-01-21 15:12:04.219376 | TASK [Get some of the system configurations]
2026-01-21 15:12:04.743085 | controller | changed
2026-01-21 15:12:04.748144 | 
2026-01-21 15:12:04.748215 | TASK [Copy generated documentation if available]
2026-01-21 15:12:04.761703 | controller | skipping: Conditional result was False
2026-01-21 15:12:04.766937 | 
2026-01-21 15:12:04.767003 | TASK [Copy generated AsciiDoc documentation if available]
2026-01-21 15:12:04.779835 | controller | skipping: Conditional result was False
2026-01-21 15:12:04.785046 | 
2026-01-21 15:12:04.785113 | TASK [Compress logs bigger than 2MB]
2026-01-21 15:12:05.311960 | controller | changed
2026-01-21 15:12:05.317389 | 
2026-01-21 15:12:05.317455 | TASK [Copy files from workspace on node]
2026-01-21 15:12:05.335588 | controller | ok
2026-01-21 15:12:05.358857 | 
2026-01-21 15:12:05.358940 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-21 15:12:05.372472 | controller | skipping: Conditional result was False
2026-01-21 15:12:05.378325 | 
2026-01-21 15:12:05.378396 | TASK [fetch-output : Set log path for single node]
2026-01-21 15:12:05.406372 | controller | ok
2026-01-21 15:12:05.411454 | 
2026-01-21 15:12:05.411520 | LOOP [fetch-output : Ensure local output dirs]
2026-01-21 15:12:05.612389 | controller -> localhost | ok: "/var/lib/zuul/builds/95faca4e6bd64736a8ab8ac4ea9229e4/work/logs"
2026-01-21 15:12:05.612616 | controller -> localhost | changed: All items complete
2026-01-21 15:12:05.612647 | 
2026-01-21 15:12:05.796894 | controller -> localhost | changed: "/var/lib/zuul/builds/95faca4e6bd64736a8ab8ac4ea9229e4/work/artifacts"
2026-01-21 15:12:05.985565 | controller -> localhost | changed: "/var/lib/zuul/builds/95faca4e6bd64736a8ab8ac4ea9229e4/work/docs"
2026-01-21 15:12:05.999749 | 
2026-01-21 15:12:05.999856 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-21 15:12:06.661412 | controller | changed:
2026-01-21 15:12:06.661611 | controller | .d..t...... ./
2026-01-21 15:12:06.661642 | controller | >f+++++++++ README.html
2026-01-21 15:12:06.661665 | controller | >f+++++++++ ansible-execution.log
2026-01-21 15:12:06.661694 | controller | >f+++++++++ ansible.log
2026-01-21 15:12:06.661715 | controller | >f+++++++++ crc-setup.log
2026-01-21 15:12:06.661735 | controller | >f+++++++++ crc-start.log
2026-01-21 15:12:06.661753 | controller | >f+++++++++ dmesg.log
2026-01-21 15:12:06.661775 | controller | >f+++++++++ installed-pkgs.log
2026-01-21 15:12:06.661795 | controller | >f+++++++++ python.log
2026-01-21 15:12:06.661813 | controller | >f+++++++++ registries.conf
2026-01-21 15:12:06.661832 | controller | >f+++++++++ report.html
2026-01-21 15:12:06.661850 | controller | >f+++++++++ selinux-denials.log
2026-01-21 15:12:06.661867 | controller | >f+++++++++ selinux-listing.log
2026-01-21 15:12:06.661884 | controller | cd+++++++++ ci-framework-data/
2026-01-21 15:12:06.661902 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-01-21 15:12:06.661920 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-01-21 15:12:06.661938 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2026-01-21 15:12:06.661955 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/ci_multus/
2026-01-21 15:12:06.661972 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/ci_multus/ci_multus_nads.yml
2026-01-21 15:12:06.661990 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-01-21 15:12:06.662008 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-01-21 15:12:06.662027 | controller | cd+++++++++ ci-framework-data/logs/
2026-01-21 15:12:06.662046 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-01-21 15:12:06.662065 | controller | cd+++++++++ registries.conf.d/
2026-01-21 15:12:06.662084 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-01-21 15:12:06.662103 | controller | cd+++++++++ system-config/
2026-01-21 15:12:06.662120 | controller | cd+++++++++ system-config/libvirt/
2026-01-21 15:12:06.662137 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-01-21 15:12:06.662154 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-01-21 15:12:06.662171 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2026-01-21 15:12:06.662188 | controller | >f+++++++++ system-config/libvirt/network.conf
2026-01-21 15:12:06.662205 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2026-01-21 15:12:06.662222 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2026-01-21 15:12:06.662239 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2026-01-21 15:12:06.662267 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2026-01-21 15:12:06.662286 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2026-01-21 15:12:06.662324 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2026-01-21 15:12:06.662345 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2026-01-21 15:12:06.662363 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2026-01-21 15:12:06.662380 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2026-01-21 15:12:06.662397 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2026-01-21 15:12:06.662415 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2026-01-21 15:12:06.662432 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2026-01-21 15:12:07.129030 | controller | changed: .d..t...... ./
2026-01-21 15:12:07.585637 | controller | changed: .d..t...... ./
2026-01-21 15:12:07.599138 | 
2026-01-21 15:12:07.599265 | TASK [Return artifact to Zuul]
2026-01-21 15:12:07.629444 | controller | ok
2026-01-21 15:12:07.648974 | 
2026-01-21 15:12:07.649056 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-01-21 15:12:07.649177 | 
2026-01-21 15:12:07.649205 | PLAY RECAP
2026-01-21 15:12:07.649250 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-01-21 15:12:07.649289 | 
2026-01-21 15:12:07.782510 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-21 15:12:07.783271 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2026-01-21 15:12:08.294517 | 
2026-01-21 15:12:08.294624 | PLAY [Report DLRN results after job run]
2026-01-21 15:12:08.322759 | 
2026-01-21 15:12:08.322833 | TASK [Report job status to DLRN]
2026-01-21 15:12:08.341651 | controller | ok
2026-01-21 15:12:08.365719 | 
2026-01-21 15:12:08.365818 | TASK [dlrn_report : Warning if DLRN is not running]
2026-01-21 15:12:08.410570 | controller | skipping: Conditional result was False
2026-01-21 15:12:08.416278 | 
2026-01-21 15:12:08.416376 | TASK [dlrn_report : Install dlrnapi-client shyaml package]
2026-01-21 15:12:08.461817 | controller | skipping: Conditional result was False
2026-01-21 15:12:08.468213 | 
2026-01-21 15:12:08.468320 | TASK [dlrn_report : Install kinit related package]
2026-01-21 15:12:08.513747 | controller | skipping: Conditional result was False
2026-01-21 15:12:08.519745 | 
2026-01-21 15:12:08.519818 | TASK [dlrn_report : Install dlrn kerberos related packages]
2026-01-21 15:12:08.565328 | controller | skipping: Conditional result was False
2026-01-21 15:12:08.572631 | 
2026-01-21 15:12:08.572701 | TASK [Set zuul-log-path fact]
2026-01-21 15:12:08.617884 | controller | skipping: Conditional result was False
2026-01-21 15:12:08.624361 | 
2026-01-21 15:12:08.624440 | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used]
2026-01-21 15:12:08.669282 | controller | skipping: Conditional result was False
2026-01-21 15:12:08.675847 | 
2026-01-21 15:12:08.675945 | TASK [Get hash related data from repo_setup role]
2026-01-21 15:12:08.721245 | controller | skipping: Conditional result was False
2026-01-21 15:12:08.727750 | 
2026-01-21 15:12:08.727833 | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication]
2026-01-21 15:12:09.256445 | controller | skipping: Conditional result was False
2026-01-21 15:12:09.262853 | 
2026-01-21 15:12:09.262953 | TASK [dlrn_report : Set empty value for dlrnapi password]
2026-01-21 15:12:09.307742 | controller | skipping: Conditional result was False
2026-01-21 15:12:09.314923 | 
2026-01-21 15:12:09.315003 | TASK [dlrn_report : Report results to dlrn for the tested hash]
2026-01-21 15:12:09.841612 | controller | skipping: Conditional result was False
2026-01-21 15:12:09.864715 | 
2026-01-21 15:12:09.864775 | PLAY RECAP
2026-01-21 15:12:09.864822 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 0
2026-01-21 15:12:09.864848 | 
2026-01-21 15:12:09.931594 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2026-01-21 15:12:09.932347 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-21 15:12:10.450565 | 
2026-01-21 15:12:10.450687 | PLAY [all]
2026-01-21 15:12:10.470245 | 
2026-01-21 15:12:10.470407 | TASK [include_role : fetch-output]
2026-01-21 15:12:10.499483 | controller | ok
2026-01-21 15:12:10.517730 | 
2026-01-21 15:12:10.517846 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-21 15:12:10.562727 | controller | skipping: Conditional result was False
2026-01-21 15:12:10.568677 | 
2026-01-21 15:12:10.568768 | TASK [fetch-output : Set log path for single node]
2026-01-21 15:12:10.608205 | controller | ok
2026-01-21 15:12:10.613610 | 
2026-01-21 15:12:10.613716 | LOOP [fetch-output : Ensure local output dirs]
2026-01-21 15:12:10.961416 | controller -> localhost | ok: "/var/lib/zuul/builds/95faca4e6bd64736a8ab8ac4ea9229e4/work/logs"
2026-01-21 15:12:11.167122 | controller -> localhost | ok: "/var/lib/zuul/builds/95faca4e6bd64736a8ab8ac4ea9229e4/work/artifacts"
2026-01-21 15:12:11.360570 | controller -> localhost | ok: "/var/lib/zuul/builds/95faca4e6bd64736a8ab8ac4ea9229e4/work/docs"
2026-01-21 15:12:11.375123 | 
2026-01-21 15:12:11.375245 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-21 15:12:12.016117 | controller | ok
2026-01-21 15:12:12.016348 | controller | ok: All items complete
2026-01-21 15:12:12.016384 | 
2026-01-21 15:12:12.521688 | controller | ok
2026-01-21 15:12:12.992384 | controller | ok
2026-01-21 15:12:13.013017 | 
2026-01-21 15:12:13.013155 | TASK [include_role : fetch-output-openshift]
2026-01-21 15:12:13.026982 | controller | skipping: Conditional result was False
2026-01-21 15:12:13.033094 | 
2026-01-21 15:12:13.033180 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-21 15:12:13.415399 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007284
2026-01-21 15:12:13.610572 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007332
2026-01-21 15:12:13.643678 | 
2026-01-21 15:12:13.643780 | PLAY [all]
2026-01-21 15:12:13.659862 | 
2026-01-21 15:12:13.659952 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-01-21 15:12:14.150475 | controller | changed
2026-01-21 15:12:14.173384 | 
2026-01-21 15:12:14.173499 | PLAY RECAP
2026-01-21 15:12:14.173552 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-01-21 15:12:14.173575 | 
2026-01-21 15:12:14.273599 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-21 15:12:14.274360 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-01-21 15:12:14.827014 | 
2026-01-21 15:12:14.827132 | PLAY [localhost]
2026-01-21 15:12:14.844448 | 
2026-01-21 15:12:14.844527 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-21 15:12:15.165613 | localhost | changed
2026-01-21 15:12:15.170074 | 
2026-01-21 15:12:15.170153 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-21 15:12:15.198096 | localhost | ok
2026-01-21 15:12:15.205761 | 
2026-01-21 15:12:15.205835 | TASK [add-fileserver : Create SSH private key tempfile]
2026-01-21 15:12:15.537189 | localhost | changed
2026-01-21 15:12:15.542749 | 
2026-01-21 15:12:15.542825 | TASK [add-fileserver : Create SSH private key from secret]
2026-01-21 15:12:16.147472 | localhost | changed
2026-01-21 15:12:16.152536 | 
2026-01-21 15:12:16.152625 | TASK [add-fileserver : Add fileserver ssh key]
2026-01-21 15:12:16.525757 | localhost | Identity added: /var/lib/zuul/builds/95faca4e6bd64736a8ab8ac4ea9229e4/work/tmp/ansible.jyo0qvyh (/var/lib/zuul/builds/95faca4e6bd64736a8ab8ac4ea9229e4/work/tmp/ansible.jyo0qvyh)
2026-01-21 15:12:16.525957 | localhost | ok: Runtime: 0:00:00.007905
2026-01-21 15:12:16.530428 | 
2026-01-21 15:12:16.530504 | TASK [add-fileserver : Remove SSH private key from disk]
2026-01-21 15:12:16.793843 | localhost | ok: Runtime: 0:00:00.008110
2026-01-21 15:12:16.799202 | 
2026-01-21 15:12:16.799332 | TASK [add-fileserver : Add fileserver to inventory]
2026-01-21 15:12:16.850469 | localhost | changed
2026-01-21 15:12:16.854786 | 
2026-01-21 15:12:16.854946 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-01-21 15:12:17.219573 | localhost | changed
2026-01-21 15:12:17.240343 | 
2026-01-21 15:12:17.240422 | PLAY [localhost]
2026-01-21 15:12:17.252973 | 
2026-01-21 15:12:17.253047 | TASK [Generate bulk log download script]
2026-01-21 15:12:17.271694 | localhost | ok
2026-01-21 15:12:17.283987 | 
2026-01-21 15:12:17.284059 | TASK [local-log-download : Check API endpoint is defined]
2026-01-21 15:12:17.312369 | localhost | ok: All assertions passed
2026-01-21 15:12:17.316782 | 
2026-01-21 15:12:17.316850 | TASK [local-log-download : Create download script]
2026-01-21 15:12:17.685956 | localhost -> localhost | changed
2026-01-21 15:12:17.695021 | 
2026-01-21 15:12:17.695112 | TASK [Register quick-download link]
2026-01-21 15:12:17.715789 | localhost | ok
2026-01-21 15:12:17.753197 | 
2026-01-21 15:12:17.753319 | PLAY [logserver.rdoproject.org]
2026-01-21 15:12:17.762654 | 
2026-01-21 15:12:17.762715 | TASK [Set zuul-log-path fact]
2026-01-21 15:12:17.780324 | logserver.rdoproject.org | ok
2026-01-21 15:12:17.790285 | 
2026-01-21 15:12:17.790372 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-21 15:12:17.816948 | logserver.rdoproject.org | ok
2026-01-21 15:12:17.822641 | 
2026-01-21 15:12:17.822709 | TASK [upload-logs : Create log directories]
2026-01-21 15:12:19.563557 | logserver.rdoproject.org | changed
2026-01-21 15:12:19.567175 | 
2026-01-21 15:12:19.567309 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-01-21 15:12:19.826396 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.008237
2026-01-21 15:12:19.831723 | 
2026-01-21 15:12:19.831822 | TASK [upload-logs : Upload logs to log server]
2026-01-21 15:12:21.308858 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-01-21 15:12:21.311967 | 
2026-01-21 15:12:21.312033 | LOOP [upload-logs : Compress console log and json output]
2026-01-21 15:12:21.351088 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-21 15:12:21.361023 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-21 15:12:21.369679 | 
2026-01-21 15:12:21.369765 | LOOP [upload-logs : Upload compressed console log and json output]
2026-01-21 15:12:21.410924 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-21 15:12:21.411189 | 
2026-01-21 15:12:21.414683 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-21 15:12:21.427356 | 
2026-01-21 15:12:21.427444 | LOOP [upload-logs : Upload console log and json output]