2026-01-21 07:37:12.010870 | Job console starting...
2026-01-21 07:37:12.226426 | Updating repositories
2026-01-21 07:37:12.948847 | Preparing job workspace
2026-01-21 07:37:20.131377 | Running Ansible setup...
2026-01-21 07:37:23.062903 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-21 07:37:23.580829 | 
2026-01-21 07:37:23.580933 | PLAY [localhost]
2026-01-21 07:37:23.589433 | 
2026-01-21 07:37:23.589508 | TASK [Gathering Facts]
2026-01-21 07:37:24.383390 | localhost | ok
2026-01-21 07:37:24.396595 | 
2026-01-21 07:37:24.396700 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-21 07:37:24.714273 | localhost -> localhost | changed
2026-01-21 07:37:24.719482 | 
2026-01-21 07:37:24.719559 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-21 07:37:25.401793 | localhost -> localhost | changed
2026-01-21 07:37:25.409730 | 
2026-01-21 07:37:25.409801 | TASK [Setup log path fact]
2026-01-21 07:37:25.426780 | localhost | ok
2026-01-21 07:37:25.437647 | 
2026-01-21 07:37:25.437723 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-21 07:37:25.454777 | localhost | ok
2026-01-21 07:37:25.462176 | 
2026-01-21 07:37:25.462262 | TASK [emit-job-header : Print job information]
2026-01-21 07:37:25.490004 | # Job Information
2026-01-21 07:37:25.490145 | Ansible Version: 2.15.12
2026-01-21 07:37:25.490175 | Job: cifmw-molecule-test_operator
2026-01-21 07:37:25.490199 | Pipeline: github-check
2026-01-21 07:37:25.490218 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-01-21 07:37:25.490236 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3589
2026-01-21 07:37:25.490332 | Log URL (when completed): https://logserver.rdoproject.org/484/rdoproject.org/4845f8698d0646048768235bcb1248d4/
2026-01-21 07:37:25.490359 | Event ID: 7fed7e80-f69b-11f0-8be3-0224bdcce5ed
2026-01-21 07:37:25.494082 | 
2026-01-21 07:37:25.494149 | LOOP [emit-job-header : Print node information]
2026-01-21 07:37:25.577332 | localhost | ok:
2026-01-21 07:37:25.577536 | localhost | # Node Information
2026-01-21 07:37:25.577570 | localhost | Inventory Hostname: controller
2026-01-21 07:37:25.577599 | localhost | Hostname: np0005589948
2026-01-21 07:37:25.577624 | localhost | Username: zuul
2026-01-21 07:37:25.577645 | localhost | Distro: CentOS 9
2026-01-21 07:37:25.577665 | localhost | Provider: ibm-bm4-nodepool
2026-01-21 07:37:25.577682 | localhost | Region: regionOne
2026-01-21 07:37:25.577700 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2026-01-21 07:37:25.577716 | localhost | Product Name: OpenStack Compute
2026-01-21 07:37:25.577733 | localhost | Interface IP: 192.168.26.45
2026-01-21 07:37:25.600896 | 
2026-01-21 07:37:25.600992 | PLAY [all]
2026-01-21 07:37:25.607078 | 
2026-01-21 07:37:25.607146 | TASK [Gather network facts]
2026-01-21 07:37:25.981733 | controller | ok
2026-01-21 07:37:25.995141 | 
2026-01-21 07:37:25.995223 | TASK [include_role : start-zuul-console]
2026-01-21 07:37:26.012520 | controller | ok
2026-01-21 07:37:26.023957 | 
2026-01-21 07:37:26.024027 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-21 07:37:26.325201 | controller | ok
2026-01-21 07:37:26.333285 | 
2026-01-21 07:37:26.333368 | TASK [include_role : add-build-sshkey]
2026-01-21 07:37:26.350567 | controller | ok
2026-01-21 07:37:26.362683 | 
2026-01-21 07:37:26.362753 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-21 07:37:26.548256 | controller -> localhost | ok
2026-01-21 07:37:26.553663 | 
2026-01-21 07:37:26.553728 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-21 07:37:26.571680 | controller | ok
2026-01-21 07:37:26.585575 | controller | included: /var/lib/zuul/builds/4845f8698d0646048768235bcb1248d4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-21 07:37:26.591561 | 
2026-01-21 07:37:26.591630 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-21 07:37:27.040285 | controller -> localhost | Generating public/private rsa key pair.
2026-01-21 07:37:27.040480 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4845f8698d0646048768235bcb1248d4/work/4845f8698d0646048768235bcb1248d4_id_rsa.
2026-01-21 07:37:27.040518 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4845f8698d0646048768235bcb1248d4/work/4845f8698d0646048768235bcb1248d4_id_rsa.pub.
2026-01-21 07:37:27.040544 | controller -> localhost | The key fingerprint is:
2026-01-21 07:37:27.040566 | controller -> localhost | SHA256:KAXZoH3Oxaue5CRTtifYqKuPjbSLIUsmOyArU7gf6FM zuul-build-sshkey
2026-01-21 07:37:27.040587 | controller -> localhost | The key's randomart image is:
2026-01-21 07:37:27.040606 | controller -> localhost | +---[RSA 3072]----+
2026-01-21 07:37:27.040625 | controller -> localhost | |    o+           |
2026-01-21 07:37:27.040645 | controller -> localhost | |   o....         |
2026-01-21 07:37:27.040664 | controller -> localhost | |  . . o o        |
2026-01-21 07:37:27.040683 | controller -> localhost | |     = o .       |
2026-01-21 07:37:27.040701 | controller -> localhost | | .  . * S        |
2026-01-21 07:37:27.040718 | controller -> localhost | |+..E B o         |
2026-01-21 07:37:27.040735 | controller -> localhost | |BBo = B .        |
2026-01-21 07:37:27.040755 | controller -> localhost | |#O.o B +         |
2026-01-21 07:37:27.040774 | controller -> localhost | |OXO.  +          |
2026-01-21 07:37:27.040794 | controller -> localhost | +----[SHA256]-----+
2026-01-21 07:37:27.040842 | controller -> localhost | ok: Runtime: 0:00:00.115905
2026-01-21 07:37:27.046524 | 
2026-01-21 07:37:27.046596 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-21 07:37:27.064372 | controller | ok
2026-01-21 07:37:27.073496 | controller | included: /var/lib/zuul/builds/4845f8698d0646048768235bcb1248d4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-21 07:37:27.081083 | 
2026-01-21 07:37:27.081157 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-21 07:37:27.094612 | controller | skipping: Conditional result was False
2026-01-21 07:37:27.100114 | 
2026-01-21 07:37:27.100186 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-21 07:37:27.483490 | controller | changed
2026-01-21 07:37:27.489153 | 
2026-01-21 07:37:27.489220 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-21 07:37:27.679734 | controller | ok
2026-01-21 07:37:27.684430 | 
2026-01-21 07:37:27.684509 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-21 07:37:28.239305 | controller | changed
2026-01-21 07:37:28.243878 | 
2026-01-21 07:37:28.243944 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-21 07:37:28.849541 | controller | changed
2026-01-21 07:37:28.854307 | 
2026-01-21 07:37:28.854374 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-21 07:37:28.867657 | controller | skipping: Conditional result was False
2026-01-21 07:37:28.872843 | 
2026-01-21 07:37:28.872914 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-21 07:37:29.180768 | controller -> localhost | changed
2026-01-21 07:37:29.190352 | 
2026-01-21 07:37:29.190438 | TASK [add-build-sshkey : Add back temp key]
2026-01-21 07:37:29.406511 | controller -> localhost | Identity added: /var/lib/zuul/builds/4845f8698d0646048768235bcb1248d4/work/4845f8698d0646048768235bcb1248d4_id_rsa (zuul-build-sshkey)
2026-01-21 07:37:29.406755 | controller -> localhost | ok: Runtime: 0:00:00.006889
2026-01-21 07:37:29.412214 | 
2026-01-21 07:37:29.412314 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-21 07:37:29.693371 | controller | ok
2026-01-21 07:37:29.697755 | 
2026-01-21 07:37:29.697837 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-21 07:37:29.721950 | controller | skipping: Conditional result was False
2026-01-21 07:37:29.730659 | 
2026-01-21 07:37:29.730732 | TASK [include_role : validate-host]
2026-01-21 07:37:29.748262 | controller | ok
2026-01-21 07:37:29.767885 | 
2026-01-21 07:37:29.767974 | TASK [validate-host : Define zuul_info_dir fact]
2026-01-21 07:37:29.796135 | controller | ok
2026-01-21 07:37:29.800703 | 
2026-01-21 07:37:29.800798 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-01-21 07:37:29.996311 | controller -> localhost | ok
2026-01-21 07:37:30.001992 | 
2026-01-21 07:37:30.002074 | TASK [validate-host : Collect information about the host]
2026-01-21 07:37:30.608584 | controller | ok
2026-01-21 07:37:30.616277 | 
2026-01-21 07:37:30.616361 | TASK [validate-host : Sanitize hostname]
2026-01-21 07:37:30.674799 | controller | ok
2026-01-21 07:37:30.680527 | 
2026-01-21 07:37:30.680600 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-01-21 07:37:31.070113 | controller -> localhost | changed
2026-01-21 07:37:31.075166 | 
2026-01-21 07:37:31.075267 | TASK [validate-host : Collect information about zuul worker]
2026-01-21 07:37:31.397131 | controller | ok
2026-01-21 07:37:31.401560 | 
2026-01-21 07:37:31.401632 | TASK [validate-host : Write out all zuul information for each host]
2026-01-21 07:37:31.759602 | controller -> localhost | changed
2026-01-21 07:37:31.768536 | 
2026-01-21 07:37:31.768606 | TASK [include_role : prepare-workspace-openshift]
2026-01-21 07:37:31.781969 | controller | skipping: Conditional result was False
2026-01-21 07:37:31.787058 | 
2026-01-21 07:37:31.787134 | TASK [include_role : remove-zuul-sshkey]
2026-01-21 07:37:31.800291 | controller | skipping: Conditional result was False
2026-01-21 07:37:31.805452 | 
2026-01-21 07:37:31.805526 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-21 07:37:32.006140 | controller | ok: "logs"
2026-01-21 07:37:32.006422 | controller | ok: All items complete
2026-01-21 07:37:32.006454 | 
2026-01-21 07:37:32.165673 | controller | ok: "artifacts"
2026-01-21 07:37:32.323113 | controller | ok: "docs"
2026-01-21 07:37:32.331977 | 
2026-01-21 07:37:32.332091 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-21 07:37:32.527383 | controller | changed: "logs"
2026-01-21 07:37:32.727405 | controller | changed: "artifacts"
2026-01-21 07:37:32.897006 | controller | changed: "docs"
2026-01-21 07:37:32.923127 | 
2026-01-21 07:37:32.923198 | PLAY RECAP
2026-01-21 07:37:32.923243 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-21 07:37:32.923323 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-21 07:37:32.923354 | 
2026-01-21 07:37:32.998665 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-21 07:37:32.999424 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-21 07:37:33.538655 | 
2026-01-21 07:37:33.538761 | PLAY [all]
2026-01-21 07:37:33.557671 | 
2026-01-21 07:37:33.557746 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-01-21 07:37:33.606069 | controller | ok
2026-01-21 07:37:33.610891 | 
2026-01-21 07:37:33.610969 | TASK [mirror-info-fork : Create /etc/ci]
2026-01-21 07:37:33.954099 | controller | changed
2026-01-21 07:37:33.959556 | 
2026-01-21 07:37:33.959632 | TASK [mirror-info-fork : Install ci_mirror script]
2026-01-21 07:37:34.789766 | controller | changed
2026-01-21 07:37:34.799056 | 
2026-01-21 07:37:34.799127 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-01-21 07:37:35.173912 | controller | changed:
2026-01-21 07:37:35.174089 | controller | {
2026-01-21 07:37:35.174119 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-01-21 07:37:35.174149 | controller | }
2026-01-21 07:37:35.386980 | controller | changed:
2026-01-21 07:37:35.387070 | controller | {
2026-01-21 07:37:35.387098 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-01-21 07:37:35.387120 | controller | }
2026-01-21 07:37:35.591931 | controller | changed:
2026-01-21 07:37:35.592024 | controller | {
2026-01-21 07:37:35.592055 | 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 07:37:35.592077 | controller | }
2026-01-21 07:37:35.802222 | controller | changed:
2026-01-21 07:37:35.802339 | controller | {
2026-01-21 07:37:35.802373 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-01-21 07:37:35.802395 | controller | }
2026-01-21 07:37:36.008040 | controller | changed:
2026-01-21 07:37:36.008129 | controller | {
2026-01-21 07:37:36.008158 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-01-21 07:37:36.008179 | controller | }
2026-01-21 07:37:36.219093 | controller | changed:
2026-01-21 07:37:36.219184 | controller | {
2026-01-21 07:37:36.219215 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-01-21 07:37:36.219238 | controller | }
2026-01-21 07:37:36.448333 | controller | changed:
2026-01-21 07:37:36.448423 | controller | {
2026-01-21 07:37:36.448452 | 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 07:37:36.448474 | controller | }
2026-01-21 07:37:36.669793 | controller | changed:
2026-01-21 07:37:36.669974 | controller | {
2026-01-21 07:37:36.670014 | 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 07:37:36.670038 | controller | }
2026-01-21 07:37:36.892502 | controller | changed:
2026-01-21 07:37:36.892598 | controller | {
2026-01-21 07:37:36.892625 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-01-21 07:37:36.892644 | controller | }
2026-01-21 07:37:37.103824 | controller | changed:
2026-01-21 07:37:37.103911 | controller | {
2026-01-21 07:37:37.103939 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-01-21 07:37:37.103959 | controller | }
2026-01-21 07:37:37.314041 | controller | changed:
2026-01-21 07:37:37.314130 | controller | {
2026-01-21 07:37:37.314157 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-01-21 07:37:37.314177 | controller | }
2026-01-21 07:37:37.524640 | controller | changed:
2026-01-21 07:37:37.524727 | controller | {
2026-01-21 07:37:37.524754 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-01-21 07:37:37.524775 | controller | }
2026-01-21 07:37:37.735870 | controller | changed:
2026-01-21 07:37:37.735974 | controller | {
2026-01-21 07:37:37.736002 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2026-01-21 07:37:37.736021 | controller | }
2026-01-21 07:37:37.959405 | controller | changed:
2026-01-21 07:37:37.959497 | controller | {
2026-01-21 07:37:37.959524 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-01-21 07:37:37.959545 | controller | }
2026-01-21 07:37:38.169714 | controller | changed:
2026-01-21 07:37:38.169814 | controller | {
2026-01-21 07:37:38.169845 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-01-21 07:37:38.169867 | controller | }
2026-01-21 07:37:38.415285 | controller | changed:
2026-01-21 07:37:38.415847 | controller | {
2026-01-21 07:37:38.415887 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-01-21 07:37:38.415910 | controller | }
2026-01-21 07:37:38.616083 | controller | changed:
2026-01-21 07:37:38.616168 | controller | {
2026-01-21 07:37:38.616197 | 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 07:37:38.616218 | controller | }
2026-01-21 07:37:38.829152 | controller | changed:
2026-01-21 07:37:38.829247 | controller | {
2026-01-21 07:37:38.829291 | 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 07:37:38.829340 | controller | }
2026-01-21 07:37:39.036346 | controller | changed:
2026-01-21 07:37:39.036507 | controller | {
2026-01-21 07:37:39.036539 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-01-21 07:37:39.036560 | controller | }
2026-01-21 07:37:39.247673 | controller | changed:
2026-01-21 07:37:39.247778 | controller | {
2026-01-21 07:37:39.247804 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-01-21 07:37:39.247824 | controller | }
2026-01-21 07:37:39.476244 | controller | changed:
2026-01-21 07:37:39.476342 | controller | {
2026-01-21 07:37:39.476370 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-01-21 07:37:39.476389 | controller | }
2026-01-21 07:37:39.695968 | controller | changed:
2026-01-21 07:37:39.696093 | controller | {
2026-01-21 07:37:39.696123 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-01-21 07:37:39.696143 | controller | }
2026-01-21 07:37:39.910096 | controller | changed:
2026-01-21 07:37:39.910177 | controller | {
2026-01-21 07:37:39.910203 | 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 07:37:39.910223 | controller | }
2026-01-21 07:37:40.128332 | controller | changed:
2026-01-21 07:37:40.128423 | controller | {
2026-01-21 07:37:40.128450 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-01-21 07:37:40.128469 | controller | }
2026-01-21 07:37:40.346331 | controller | changed:
2026-01-21 07:37:40.346417 | controller | {
2026-01-21 07:37:40.346443 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-01-21 07:37:40.346463 | controller | }
2026-01-21 07:37:40.562150 | controller | changed:
2026-01-21 07:37:40.562237 | controller | {
2026-01-21 07:37:40.562276 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-01-21 07:37:40.562336 | controller | }
2026-01-21 07:37:40.582383 | 
2026-01-21 07:37:40.582451 | TASK [Set timezone to UTC]
2026-01-21 07:37:41.006562 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-01-21 07:37:41.011759 | 
2026-01-21 07:37:41.011823 | TASK [Create nodepool directory]
2026-01-21 07:37:41.233723 | controller | changed
2026-01-21 07:37:41.238960 | 
2026-01-21 07:37:41.239028 | TASK [Create nodepool sub_nodes file]
2026-01-21 07:37:41.784444 | controller | changed
2026-01-21 07:37:41.789123 | 
2026-01-21 07:37:41.789198 | TASK [Create nodepool sub_nodes_private file]
2026-01-21 07:37:42.293159 | controller | changed
2026-01-21 07:37:42.297957 | 
2026-01-21 07:37:42.298018 | LOOP [Populate nodepool sub_nodes file]
2026-01-21 07:37:42.326323 | 
2026-01-21 07:37:42.326450 | LOOP [Populate nodepool sub_nodes_private file]
2026-01-21 07:37:42.354242 | 
2026-01-21 07:37:42.354396 | TASK [Create nodepool primary file]
2026-01-21 07:37:42.377352 | controller | skipping: Conditional result was False
2026-01-21 07:37:42.382817 | 
2026-01-21 07:37:42.382891 | TASK [Create nodepool node_private for this node]
2026-01-21 07:37:42.969653 | controller | changed
2026-01-21 07:37:42.976154 | 
2026-01-21 07:37:42.976226 | LOOP [Copy ssh keys to nodepool directory]
2026-01-21 07:37:43.309188 | controller | ok: Item: id_rsa Runtime: 0:00:00.007561
2026-01-21 07:37:43.309429 | 
2026-01-21 07:37:43.484432 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.006346
2026-01-21 07:37:43.492593 | 
2026-01-21 07:37:43.492701 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-01-21 07:37:44.100290 | controller | changed
2026-01-21 07:37:44.105054 | 
2026-01-21 07:37:44.105133 | TASK [Validate sudoers config after edits]
2026-01-21 07:37:44.339324 | controller | /etc/sudoers: parsed OK
2026-01-21 07:37:44.339430 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-01-21 07:37:44.339442 | controller | /etc/sudoers.d/zuul: parsed OK
2026-01-21 07:37:44.339449 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-01-21 07:37:44.630212 | controller | ok: Runtime: 0:00:00.006367
2026-01-21 07:37:44.635532 | 
2026-01-21 07:37:44.635600 | TASK [Show the environment passed in to job shell scripts]
2026-01-21 07:37:44.874073 | controller | SHELL=/bin/bash
2026-01-21 07:37:44.874149 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-01-21 07:37:44.874160 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-01-21 07:37:44.874168 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/89/3589/bd304d0d935277b59eac13a07a8407ea49cf60b6
2026-01-21 07:37:44.874174 | controller | PWD=/home/zuul
2026-01-21 07:37:44.874258 | controller | ZUUL_PIPELINE=github-check
2026-01-21 07:37:44.874268 | controller | LOGNAME=zuul
2026-01-21 07:37:44.874275 | controller | XDG_SESSION_TYPE=tty
2026-01-21 07:37:44.874281 | controller | _=/usr/bin/env
2026-01-21 07:37:44.874287 | controller | MOTD_SHOWN=pam
2026-01-21 07:37:44.874292 | controller | HOME=/home/zuul
2026-01-21 07:37:44.874297 | controller | LANG=en_US.UTF-8
2026-01-21 07:37:44.874303 | controller | SSH_CONNECTION=192.168.26.12 41502 192.168.26.45 22
2026-01-21 07:37:44.874310 | 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 07:37:44.874320 | controller | ZUUL_CHANGE_IDS=3589,bd304d0d935277b59eac13a07a8407ea49cf60b6
2026-01-21 07:37:44.874326 | controller | WORKSPACE=/home/zuul/workspace
2026-01-21 07:37:44.874332 | controller | XDG_SESSION_CLASS=user
2026-01-21 07:37:44.874338 | controller | SELINUX_ROLE_REQUESTED=
2026-01-21 07:37:44.874357 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-01-21 07:37:44.874363 | controller | USER=zuul
2026-01-21 07:37:44.874369 | controller | ZUUL_VOTING=True
2026-01-21 07:37:44.874375 | controller | BUILD_TIMEOUT=1800000
2026-01-21 07:37:44.874381 | controller | SELINUX_USE_CURRENT_RANGE=
2026-01-21 07:37:44.874386 | controller | SHLVL=1
2026-01-21 07:37:44.874392 | controller | ZUUL_PATCHSET=bd304d0d935277b59eac13a07a8407ea49cf60b6
2026-01-21 07:37:44.874398 | controller | XDG_SESSION_ID=1
2026-01-21 07:37:44.874403 | controller | ZUUL_BRANCH=main
2026-01-21 07:37:44.874408 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-01-21 07:37:44.874414 | controller | SSH_CLIENT=192.168.26.12 41502 22
2026-01-21 07:37:44.874421 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-01-21 07:37:44.874426 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-01-21 07:37:44.874435 | controller | which_declare=declare -f
2026-01-21 07:37:44.874443 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-01-21 07:37:44.874449 | controller | SELINUX_LEVEL_REQUESTED=
2026-01-21 07:37:44.874455 | controller | ZUUL_CHANGE=3589
2026-01-21 07:37:44.874460 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-01-21 07:37:44.874466 | controller | ZUUL_UUID=4845f8698d0646048768235bcb1248d4
2026-01-21 07:37:44.874472 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-01-21 07:37:44.874479 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-01-21 07:37:44.874485 | controller | }
2026-01-21 07:37:45.160645 | controller | ok: Runtime: 0:00:00.008598
2026-01-21 07:37:45.166120 | 
2026-01-21 07:37:45.166193 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-01-21 07:37:45.179600 | controller | skipping: Conditional result was False
2026-01-21 07:37:45.184736 | 
2026-01-21 07:37:45.184802 | TASK [Symlink /home/zuul-worker/workspace]
2026-01-21 07:37:45.710032 | controller | skipping: Conditional result was False
2026-01-21 07:37:45.715982 | 
2026-01-21 07:37:45.716057 | TASK [Ensure legacy workspace directory]
2026-01-21 07:37:45.906530 | controller | changed
2026-01-21 07:37:45.924129 | 
2026-01-21 07:37:45.924210 | PLAY RECAP
2026-01-21 07:37:45.924283 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-21 07:37:45.924336 | 
2026-01-21 07:37:45.999399 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-21 07:37:46.000128 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-21 07:37:46.502223 | 
2026-01-21 07:37:46.502352 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-01-21 07:37:46.521960 | 
2026-01-21 07:37:46.522034 | TASK [Create zuul-output directory]
2026-01-21 07:37:46.834333 | controller | changed
2026-01-21 07:37:46.839093 | 
2026-01-21 07:37:46.839169 | TASK [Slurp Zuul inventory test]
2026-01-21 07:37:47.105803 | controller -> localhost | ok
2026-01-21 07:37:47.111990 | 
2026-01-21 07:37:47.112067 | TASK [Save zuul inventory]
2026-01-21 07:37:47.850131 | controller | changed
2026-01-21 07:37:47.854901 | 
2026-01-21 07:37:47.854967 | TASK [Save zuul vars without the change_message]
2026-01-21 07:37:48.471289 | controller | changed
2026-01-21 07:37:48.488488 | 
2026-01-21 07:37:48.488544 | PLAY RECAP
2026-01-21 07:37:48.488584 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-21 07:37:48.488608 | 
2026-01-21 07:37:48.551133 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-21 07:37:48.551891 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-01-21 07:37:49.091283 | 
2026-01-21 07:37:49.091405 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-01-21 07:37:49.111743 | 
2026-01-21 07:37:49.111819 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-01-21 07:37:49.129757 | controller | ok
2026-01-21 07:37:49.146137 | 
2026-01-21 07:37:49.146221 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-01-21 07:37:49.169817 | controller | skipping: Conditional result was False
2026-01-21 07:37:49.175482 | 
2026-01-21 07:37:49.175559 | TASK [mirror-info-fork : Create /etc/ci]
2026-01-21 07:37:49.507378 | controller | ok
2026-01-21 07:37:49.512770 | 
2026-01-21 07:37:49.512849 | TASK [mirror-info-fork : Install ci_mirror script]
2026-01-21 07:37:50.159748 | controller | ok
2026-01-21 07:37:50.170011 | 
2026-01-21 07:37:50.170078 | TASK [Prepare workspace]
2026-01-21 07:37:50.188074 | controller | ok
2026-01-21 07:37:50.203934 | 
2026-01-21 07:37:50.204006 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-21 07:37:50.495492 | controller | ok
2026-01-21 07:37:50.501934 | 
2026-01-21 07:37:50.502005 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-21 07:37:52.902796 | controller | Output suppressed because no_log was given
2026-01-21 07:37:52.911979 | 
2026-01-21 07:37:52.912073 | LOOP [Create zuul-output directory]
2026-01-21 07:37:53.099373 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-01-21 07:37:53.266286 | controller | ok: "/home/zuul/zuul-output/logs"
2026-01-21 07:37:53.277165 | 
2026-01-21 07:37:53.277286 | TASK [Install required packages]
2026-01-21 07:38:34.837573 | controller | changed
2026-01-21 07:38:34.850217 | 
2026-01-21 07:38:34.850378 | TASK [Install venv]
2026-01-21 07:39:04.127585 | controller | ERROR
2026-01-21 07:39:04.127796 | controller | {
2026-01-21 07:39:04.127829 | controller |   "msg": "gmake: *** [Makefile:60: setup_molecule] Error 2",
2026-01-21 07:39:04.127850 | controller |   "rc": 2
2026-01-21 07:39:04.127868 | controller | }
failure
2026-01-21 07:39:04.131573 | 
2026-01-21 07:39:04.131631 | PLAY RECAP
2026-01-21 07:39:04.131673 | controller | ok: 6 changed: 3 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-01-21 07:39:04.131696 | 
2026-01-21 07:39:04.203184 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-01-21 07:39:04.204085 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-21 07:39:04.728836 | 
2026-01-21 07:39:04.728933 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-01-21 07:39:04.748799 | 
2026-01-21 07:39:04.748874 | TASK [Filter out host if needed]
2026-01-21 07:39:04.757233 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-01-21 07:39:04.761640 | 
2026-01-21 07:39:04.761708 | TASK [Ensure file is present]
2026-01-21 07:39:05.053110 | controller | ok
2026-01-21 07:39:05.059643 | 
2026-01-21 07:39:05.059715 | TASK [Manage molecule report file]
2026-01-21 07:39:05.584699 | controller | skipping: Conditional result was False
2026-01-21 07:39:05.590026 | 
2026-01-21 07:39:05.590113 | TASK [Check if we get ci-framework-data basedir]
2026-01-21 07:39:05.780907 | controller | ok
2026-01-21 07:39:05.785844 | 
2026-01-21 07:39:05.785908 | TASK [Create ci-framework-data log directory for zuul]
2026-01-21 07:39:06.098027 | controller | changed
2026-01-21 07:39:06.103120 | 
2026-01-21 07:39:06.103190 | TASK [Copy ci-framework interesting files]
2026-01-21 07:39:06.417604 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-01-21 07:39:06.629580 | controller | changed
2026-01-21 07:39:06.634683 | 
2026-01-21 07:39:06.634750 | TASK [Get SELinux listing]
2026-01-21 07:39:07.160020 | controller | changed
2026-01-21 07:39:07.165782 | 
2026-01-21 07:39:07.165870 | TASK [Generate log index]
2026-01-21 07:39:07.812282 | controller | changed
2026-01-21 07:39:07.817459 | 
2026-01-21 07:39:07.817534 | TASK [Get some env related data]
2026-01-21 07:39:08.375248 | controller | /home/zuul/.local/bin/ansible
2026-01-21 07:39:09.343703 | controller | changed
2026-01-21 07:39:09.348858 | 
2026-01-21 07:39:09.348923 | TASK [Generate list of logs to collect in home directory]
2026-01-21 07:39:09.631035 | controller | ok: All paths examined
2026-01-21 07:39:09.635745 | 
2026-01-21 07:39:09.635810 | LOOP [Copy logs from home directory]
2026-01-21 07:39:09.654808 | 
2026-01-21 07:39:09.654931 | TASK [Copy crio stats log file]
2026-01-21 07:39:09.669028 | controller | skipping: Conditional result was False
2026-01-21 07:39:09.674605 | 
2026-01-21 07:39:09.674670 | TASK [Get SELinux related data]
2026-01-21 07:39:09.909059 | controller | <no matches>
2026-01-21 07:39:10.222155 | controller | ERROR
2026-01-21 07:39:10.222335 | controller | {
2026-01-21 07:39:10.222374 | controller |   "delta": "0:00:00.008699",
2026-01-21 07:39:10.222397 | controller |   "end": "2026-01-21 07:39:09.909697",
2026-01-21 07:39:10.222415 | controller |   "msg": "non-zero return code",
2026-01-21 07:39:10.222432 | controller |   "rc": 1,
2026-01-21 07:39:10.222448 | controller |   "start": "2026-01-21 07:39:09.900998"
2026-01-21 07:39:10.222464 | controller | }
2026-01-21 07:39:10.222486 | controller | ERROR: Ignoring Errors
2026-01-21 07:39:10.227585 | 
2026-01-21 07:39:10.227650 | TASK [Create system configuration directory]
2026-01-21 07:39:10.408070 | controller | changed
2026-01-21 07:39:10.412784 | 
2026-01-21 07:39:10.412848 | TASK [Get some of the system configurations]
2026-01-21 07:39:10.938714 | controller | changed
2026-01-21 07:39:10.943908 | 
2026-01-21 07:39:10.943972 | TASK [Copy generated documentation if available]
2026-01-21 07:39:10.957202 | controller | skipping: Conditional result was False
2026-01-21 07:39:10.962323 | 
2026-01-21 07:39:10.962388 | TASK [Copy generated AsciiDoc documentation if available]
2026-01-21 07:39:10.975882 | controller | skipping: Conditional result was False
2026-01-21 07:39:10.980804 | 
2026-01-21 07:39:10.980867 | TASK [Compress logs bigger than 2MB]
2026-01-21 07:39:11.506454 | controller | changed
2026-01-21 07:39:11.512162 | 
2026-01-21 07:39:11.512244 | TASK [Copy files from workspace on node]
2026-01-21 07:39:11.530234 | controller | ok
2026-01-21 07:39:11.552787 | 
2026-01-21 07:39:11.552867 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-21 07:39:11.565727 | controller | skipping: Conditional result was False
2026-01-21 07:39:11.571129 | 
2026-01-21 07:39:11.571206 | TASK [fetch-output : Set log path for single node]
2026-01-21 07:39:11.598597 | controller | ok
2026-01-21 07:39:11.603603 | 
2026-01-21 07:39:11.603676 | LOOP [fetch-output : Ensure local output dirs]
2026-01-21 07:39:11.795190 | controller -> localhost | ok: "/var/lib/zuul/builds/4845f8698d0646048768235bcb1248d4/work/logs"
2026-01-21 07:39:11.795441 | 
2026-01-21 07:39:11.969731 | controller -> localhost | changed: "/var/lib/zuul/builds/4845f8698d0646048768235bcb1248d4/work/artifacts"
2026-01-21 07:39:12.146630 | controller -> localhost | changed: "/var/lib/zuul/builds/4845f8698d0646048768235bcb1248d4/work/docs"
2026-01-21 07:39:12.159962 | 
2026-01-21 07:39:12.160039 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-21 07:39:12.680378 | controller | changed:
2026-01-21 07:39:12.680723 | controller | .d..t...... ./
2026-01-21 07:39:12.680754 | controller | >f+++++++++ README.html
2026-01-21 07:39:12.680778 | controller | >f+++++++++ dmesg.log
2026-01-21 07:39:12.680798 | controller | >f+++++++++ installed-pkgs.log
2026-01-21 07:39:12.680817 | controller | >f+++++++++ python.log
2026-01-21 07:39:12.680835 | controller | >f+++++++++ registries.conf
2026-01-21 07:39:12.680853 | controller | >f+++++++++ selinux-denials.log
2026-01-21 07:39:12.680869 | controller | >f+++++++++ selinux-listing.log
2026-01-21 07:39:12.680887 | controller | cd+++++++++ ci-framework-data/
2026-01-21 07:39:12.680903 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-01-21 07:39:12.680920 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-01-21 07:39:12.680938 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-01-21 07:39:12.680955 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-01-21 07:39:12.680972 | controller | cd+++++++++ ci-framework-data/logs/
2026-01-21 07:39:12.680989 | controller | cd+++++++++ registries.conf.d/
2026-01-21 07:39:12.681005 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-01-21 07:39:12.681021 | controller | cd+++++++++ system-config/
2026-01-21 07:39:12.681037 | controller | cd+++++++++ system-config/libvirt/
2026-01-21 07:39:12.681052 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-01-21 07:39:12.681068 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-01-21 07:39:13.041269 | controller | changed: .d..t...... ./
2026-01-21 07:39:13.400916 | controller | changed: .d..t...... ./
2026-01-21 07:39:13.413065 | 
2026-01-21 07:39:13.413175 | TASK [Return artifact to Zuul]
2026-01-21 07:39:13.427189 | controller | skipping: Conditional result was False
2026-01-21 07:39:13.447290 | 
2026-01-21 07:39:13.447387 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-01-21 07:39:13.447491 | 
2026-01-21 07:39:13.447519 | PLAY RECAP
2026-01-21 07:39:13.447559 | controller | ok: 15 changed: 11 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 1
2026-01-21 07:39:13.447581 | 
2026-01-21 07:39:13.527212 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-21 07:39:13.528016 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-21 07:39:14.044026 | 
2026-01-21 07:39:14.044159 | PLAY [all]
2026-01-21 07:39:14.062654 | 
2026-01-21 07:39:14.062736 | TASK [include_role : fetch-output]
2026-01-21 07:39:14.091356 | controller | ok
2026-01-21 07:39:14.108381 | 
2026-01-21 07:39:14.108485 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-21 07:39:14.153063 | controller | skipping: Conditional result was False
2026-01-21 07:39:14.158974 | 
2026-01-21 07:39:14.159071 | TASK [fetch-output : Set log path for single node]
2026-01-21 07:39:14.188607 | controller | ok
2026-01-21 07:39:14.193666 | 
2026-01-21 07:39:14.193743 | LOOP [fetch-output : Ensure local output dirs]
2026-01-21 07:39:14.532415 | controller -> localhost | ok: "/var/lib/zuul/builds/4845f8698d0646048768235bcb1248d4/work/logs"
2026-01-21 07:39:14.722948 | controller -> localhost | ok: "/var/lib/zuul/builds/4845f8698d0646048768235bcb1248d4/work/artifacts"
2026-01-21 07:39:14.915966 | controller -> localhost | ok: "/var/lib/zuul/builds/4845f8698d0646048768235bcb1248d4/work/docs"
2026-01-21 07:39:14.925838 | 
2026-01-21 07:39:14.925951 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-21 07:39:15.443466 | controller | ok
2026-01-21 07:39:15.443674 | controller | ok: All items complete
2026-01-21 07:39:15.443713 | 
2026-01-21 07:39:15.808591 | controller | ok
2026-01-21 07:39:16.182500 | controller | ok
2026-01-21 07:39:16.197992 | 
2026-01-21 07:39:16.198148 | TASK [include_role : fetch-output-openshift]
2026-01-21 07:39:16.212081 | controller | skipping: Conditional result was False
2026-01-21 07:39:16.219034 | 
2026-01-21 07:39:16.219194 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-21 07:39:16.577033 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006437
2026-01-21 07:39:16.771601 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006884
2026-01-21 07:39:16.805131 | 
2026-01-21 07:39:16.805214 | PLAY [all]
2026-01-21 07:39:16.818744 | 
2026-01-21 07:39:16.818820 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-01-21 07:39:17.233618 | controller | changed
2026-01-21 07:39:17.255616 | 
2026-01-21 07:39:17.255699 | PLAY RECAP
2026-01-21 07:39:17.255749 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-01-21 07:39:17.255773 | 
2026-01-21 07:39:17.328594 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-21 07:39:17.329373 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-01-21 07:39:17.882163 | 
2026-01-21 07:39:17.882274 | PLAY [localhost]
2026-01-21 07:39:17.899918 | 
2026-01-21 07:39:17.899996 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-21 07:39:18.201063 | localhost | changed
2026-01-21 07:39:18.205658 | 
2026-01-21 07:39:18.205761 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-21 07:39:18.233770 | localhost | ok
2026-01-21 07:39:18.241822 | 
2026-01-21 07:39:18.241910 | TASK [add-fileserver : Create SSH private key tempfile]
2026-01-21 07:39:18.554641 | localhost | changed
2026-01-21 07:39:18.560269 | 
2026-01-21 07:39:18.560388 | TASK [add-fileserver : Create SSH private key from secret]
2026-01-21 07:39:19.125755 | localhost | changed
2026-01-21 07:39:19.130684 | 
2026-01-21 07:39:19.130760 | TASK [add-fileserver : Add fileserver ssh key]
2026-01-21 07:39:19.474083 | localhost | Identity added: /var/lib/zuul/builds/4845f8698d0646048768235bcb1248d4/work/tmp/ansible.kf18glq5 (/var/lib/zuul/builds/4845f8698d0646048768235bcb1248d4/work/tmp/ansible.kf18glq5)
2026-01-21 07:39:19.474317 | localhost | ok: Runtime: 0:00:00.006304
2026-01-21 07:39:19.478732 | 
2026-01-21 07:39:19.478803 | TASK [add-fileserver : Remove SSH private key from disk]
2026-01-21 07:39:19.711462 | localhost | ok: Runtime: 0:00:00.005717
2026-01-21 07:39:19.716615 | 
2026-01-21 07:39:19.716737 | TASK [add-fileserver : Add fileserver to inventory]
2026-01-21 07:39:19.778673 | localhost | changed
2026-01-21 07:39:19.783455 | 
2026-01-21 07:39:19.783568 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-01-21 07:39:20.137490 | localhost | changed
2026-01-21 07:39:20.157709 | 
2026-01-21 07:39:20.157801 | PLAY [localhost]
2026-01-21 07:39:20.169099 | 
2026-01-21 07:39:20.169172 | TASK [Generate bulk log download script]
2026-01-21 07:39:20.189759 | localhost | ok
2026-01-21 07:39:20.201865 | 
2026-01-21 07:39:20.201959 | TASK [local-log-download : Check API endpoint is defined]
2026-01-21 07:39:20.231644 | localhost | ok: All assertions passed
2026-01-21 07:39:20.236261 | 
2026-01-21 07:39:20.236368 | TASK [local-log-download : Create download script]
2026-01-21 07:39:20.615577 | localhost -> localhost | changed
2026-01-21 07:39:20.624971 | 
2026-01-21 07:39:20.625067 | TASK [Register quick-download link]
2026-01-21 07:39:20.644399 | localhost | ok
2026-01-21 07:39:20.686655 | 
2026-01-21 07:39:20.686766 | PLAY [logserver.rdoproject.org]
2026-01-21 07:39:20.696258 | 
2026-01-21 07:39:20.696350 | TASK [Set zuul-log-path fact]
2026-01-21 07:39:20.712959 | logserver.rdoproject.org | ok
2026-01-21 07:39:20.721849 | 
2026-01-21 07:39:20.721915 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-21 07:39:20.748277 | logserver.rdoproject.org | ok
2026-01-21 07:39:20.753508 | 
2026-01-21 07:39:20.753571 | TASK [upload-logs : Create log directories]
2026-01-21 07:39:22.483594 | logserver.rdoproject.org | changed
2026-01-21 07:39:22.488347 | 
2026-01-21 07:39:22.488430 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-01-21 07:39:22.719723 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004933
2026-01-21 07:39:22.725667 | 
2026-01-21 07:39:22.725748 | TASK [upload-logs : Upload logs to log server]
2026-01-21 07:39:24.218853 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-01-21 07:39:24.222490 | 
2026-01-21 07:39:24.222566 | LOOP [upload-logs : Compress console log and json output]
2026-01-21 07:39:24.268048 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-21 07:39:24.276108 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-21 07:39:24.284127 | 
2026-01-21 07:39:24.284261 | LOOP [upload-logs : Upload compressed console log and json output]
2026-01-21 07:39:24.323883 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-21 07:39:24.324205 | 
2026-01-21 07:39:24.326819 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-21 07:39:24.334845 | 
2026-01-21 07:39:24.334981 | LOOP [upload-logs : Upload console log and json output]