2025-12-05 15:40:39.877696 | Job console starting...
2025-12-05 15:40:40.087486 | Updating repositories
2025-12-05 15:40:40.939327 | Preparing job workspace
2025-12-05 15:41:00.011100 | Running Ansible setup...
2025-12-05 15:41:06.545073 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 15:41:08.955693 | 
2025-12-05 15:41:08.955801 | PLAY [localhost]
2025-12-05 15:41:08.995324 | 
2025-12-05 15:41:08.995429 | TASK [Gathering Facts]
2025-12-05 15:41:11.579214 | localhost | ok
2025-12-05 15:41:11.592145 | 
2025-12-05 15:41:11.592249 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-05 15:41:13.208495 | localhost -> localhost | changed
2025-12-05 15:41:13.220117 | 
2025-12-05 15:41:13.220438 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-05 15:41:16.254264 | localhost -> localhost | changed
2025-12-05 15:41:16.263814 | 
2025-12-05 15:41:16.263885 | TASK [Setup log path fact]
2025-12-05 15:41:16.350439 | localhost | ok
2025-12-05 15:41:16.370217 | 
2025-12-05 15:41:16.370330 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 15:41:16.421143 | localhost | ok
2025-12-05 15:41:16.430633 | 
2025-12-05 15:41:16.430720 | TASK [emit-job-header : Print job information]
2025-12-05 15:41:16.476428 | # Job Information
2025-12-05 15:41:16.476575 | Ansible Version: 2.15.12
2025-12-05 15:41:16.476607 | Job: cifmw-molecule-artifacts
2025-12-05 15:41:16.476630 | Pipeline: github-check
2025-12-05 15:41:16.476651 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-12-05 15:41:16.476670 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3539
2025-12-05 15:41:16.476691 | Log URL (when completed): https://logserver.rdoproject.org/1bf/rdoproject.org/1bf164037bef4236b4131e8014cd9963/
2025-12-05 15:41:16.476711 | Event ID: 757b9e30-d1ef-11f0-97eb-f505e6ea5a00
2025-12-05 15:41:16.486215 | 
2025-12-05 15:41:16.486305 | LOOP [emit-job-header : Print node information]
2025-12-05 15:41:16.743966 | localhost | ok:
2025-12-05 15:41:16.744109 | localhost | # Node Information
2025-12-05 15:41:16.744140 | localhost | Inventory Hostname: controller
2025-12-05 15:41:16.744166 | localhost | Hostname: np0005547550
2025-12-05 15:41:16.744189 | localhost | Username: zuul
2025-12-05 15:41:16.744212 | localhost | Distro: CentOS 9
2025-12-05 15:41:16.744231 | localhost | Provider: ibm-bm4-nodepool
2025-12-05 15:41:16.744251 | localhost | Region: regionOne
2025-12-05 15:41:16.744269 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-12-05 15:41:16.744305 | localhost | Product Name: OpenStack Compute
2025-12-05 15:41:16.744324 | localhost | Interface IP: 192.168.26.58
2025-12-05 15:41:16.805892 | 
2025-12-05 15:41:16.806008 | PLAY [all]
2025-12-05 15:41:16.827551 | 
2025-12-05 15:41:16.827651 | TASK [Gather network facts]
2025-12-05 15:41:17.257203 | controller | ok
2025-12-05 15:41:17.300382 | 
2025-12-05 15:41:17.300469 | TASK [include_role : start-zuul-console]
2025-12-05 15:41:17.326578 | controller | ok
2025-12-05 15:41:17.369242 | 
2025-12-05 15:41:17.369344 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-05 15:41:18.157207 | controller | ok
2025-12-05 15:41:18.172934 | 
2025-12-05 15:41:18.173001 | TASK [include_role : add-build-sshkey]
2025-12-05 15:41:18.221592 | controller | ok
2025-12-05 15:41:18.251413 | 
2025-12-05 15:41:18.251493 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-05 15:41:18.841875 | controller -> localhost | ok
2025-12-05 15:41:18.847517 | 
2025-12-05 15:41:18.847582 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-05 15:41:18.931209 | controller | ok
2025-12-05 15:41:18.970253 | controller | included: /var/lib/zuul/builds/1bf164037bef4236b4131e8014cd9963/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-05 15:41:19.005504 | 
2025-12-05 15:41:19.005596 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-05 15:41:21.735466 | controller -> localhost | Generating public/private rsa key pair.
2025-12-05 15:41:21.735658 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/1bf164037bef4236b4131e8014cd9963/work/1bf164037bef4236b4131e8014cd9963_id_rsa.
2025-12-05 15:41:21.735696 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/1bf164037bef4236b4131e8014cd9963/work/1bf164037bef4236b4131e8014cd9963_id_rsa.pub.
2025-12-05 15:41:21.735722 | controller -> localhost | The key fingerprint is:
2025-12-05 15:41:21.735744 | controller -> localhost | SHA256:EmbfBWPcw70Eu/ACp9kPiibdKVrBY9DjQnLhDxIDpQE zuul-build-sshkey
2025-12-05 15:41:21.735765 | controller -> localhost | The key's randomart image is:
2025-12-05 15:41:21.735785 | controller -> localhost | +---[RSA 3072]----+
2025-12-05 15:41:21.735804 | controller -> localhost | |Eo+ .    .+o.o   |
2025-12-05 15:41:21.735824 | controller -> localhost | | o + o   ..o+.o  |
2025-12-05 15:41:21.735843 | controller -> localhost | |. o * * . o oo . |
2025-12-05 15:41:21.735861 | controller -> localhost | |   = O + B + ..  |
2025-12-05 15:41:21.735879 | controller -> localhost | |    . O S = o    |
2025-12-05 15:41:21.735896 | controller -> localhost | |     + * o +     |
2025-12-05 15:41:21.735935 | controller -> localhost | |    . * +   .    |
2025-12-05 15:41:21.735958 | controller -> localhost | |     = .         |
2025-12-05 15:41:21.735977 | controller -> localhost | |    .            |
2025-12-05 15:41:21.735995 | controller -> localhost | +----[SHA256]-----+
2025-12-05 15:41:21.736039 | controller -> localhost | ok: Runtime: 0:00:01.239242
2025-12-05 15:41:21.742270 | 
2025-12-05 15:41:21.742357 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-05 15:41:21.825405 | controller | ok
2025-12-05 15:41:21.851795 | controller | included: /var/lib/zuul/builds/1bf164037bef4236b4131e8014cd9963/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-05 15:41:21.884070 | 
2025-12-05 15:41:21.884152 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-05 15:41:21.966389 | controller | skipping: Conditional result was False
2025-12-05 15:41:21.972516 | 
2025-12-05 15:41:21.972594 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-05 15:41:22.935302 | controller | changed
2025-12-05 15:41:22.945436 | 
2025-12-05 15:41:22.945502 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-05 15:41:23.192984 | controller | ok
2025-12-05 15:41:23.224992 | 
2025-12-05 15:41:23.225076 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-05 15:41:24.177184 | controller | changed
2025-12-05 15:41:24.226211 | 
2025-12-05 15:41:24.226319 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-05 15:41:25.041518 | controller | changed
2025-12-05 15:41:25.046389 | 
2025-12-05 15:41:25.046454 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-05 15:41:25.129692 | controller | skipping: Conditional result was False
2025-12-05 15:41:25.135118 | 
2025-12-05 15:41:25.135189 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-05 15:41:26.045000 | controller -> localhost | changed
2025-12-05 15:41:26.061228 | 
2025-12-05 15:41:26.061315 | TASK [add-build-sshkey : Add back temp key]
2025-12-05 15:41:26.777678 | controller -> localhost | Identity added: /var/lib/zuul/builds/1bf164037bef4236b4131e8014cd9963/work/1bf164037bef4236b4131e8014cd9963_id_rsa (zuul-build-sshkey)
2025-12-05 15:41:26.777875 | controller -> localhost | ok: Runtime: 0:00:00.024946
2025-12-05 15:41:26.791615 | 
2025-12-05 15:41:26.791689 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-05 15:41:27.265042 | controller | ok
2025-12-05 15:41:27.269816 | 
2025-12-05 15:41:27.269882 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-05 15:41:27.380821 | controller | skipping: Conditional result was False
2025-12-05 15:41:27.390411 | 
2025-12-05 15:41:27.390482 | TASK [include_role : validate-host]
2025-12-05 15:41:27.453362 | controller | ok
2025-12-05 15:41:27.482795 | 
2025-12-05 15:41:27.482890 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-05 15:41:27.632150 | controller | ok
2025-12-05 15:41:27.636679 | 
2025-12-05 15:41:27.636745 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-05 15:41:28.007055 | controller -> localhost | ok
2025-12-05 15:41:28.013660 | 
2025-12-05 15:41:28.013740 | TASK [validate-host : Collect information about the host]
2025-12-05 15:41:28.694155 | controller | ok
2025-12-05 15:41:28.729046 | 
2025-12-05 15:41:28.729152 | TASK [validate-host : Sanitize hostname]
2025-12-05 15:41:28.849607 | controller | ok
2025-12-05 15:41:28.854465 | 
2025-12-05 15:41:28.854537 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-05 15:41:30.052772 | controller -> localhost | changed
2025-12-05 15:41:30.058509 | 
2025-12-05 15:41:30.058619 | TASK [validate-host : Collect information about zuul worker]
2025-12-05 15:41:30.695764 | controller | ok
2025-12-05 15:41:30.700474 | 
2025-12-05 15:41:30.700552 | TASK [validate-host : Write out all zuul information for each host]
2025-12-05 15:41:32.094800 | controller -> localhost | changed
2025-12-05 15:41:32.104979 | 
2025-12-05 15:41:32.105045 | TASK [include_role : prepare-workspace-openshift]
2025-12-05 15:41:32.160555 | controller | skipping: Conditional result was False
2025-12-05 15:41:32.165730 | 
2025-12-05 15:41:32.165797 | TASK [include_role : remove-zuul-sshkey]
2025-12-05 15:41:32.221620 | controller | skipping: Conditional result was False
2025-12-05 15:41:32.233849 | 
2025-12-05 15:41:32.233936 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 15:41:32.507873 | controller | ok: "logs"
2025-12-05 15:41:32.508098 | controller | ok: All items complete
2025-12-05 15:41:32.508130 | 
2025-12-05 15:41:32.700513 | controller | ok: "artifacts"
2025-12-05 15:41:32.906152 | controller | ok: "docs"
2025-12-05 15:41:32.932695 | 
2025-12-05 15:41:32.932802 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 15:41:33.222203 | controller | changed: "logs"
2025-12-05 15:41:33.406887 | controller | changed: "artifacts"
2025-12-05 15:41:33.616605 | controller | changed: "docs"
2025-12-05 15:41:33.660537 | 
2025-12-05 15:41:33.660622 | PLAY RECAP
2025-12-05 15:41:33.660668 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 15:41:33.660696 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 15:41:33.660716 | 
2025-12-05 15:41:34.536572 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 15:41:34.537630 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-05 15:41:36.520355 | 
2025-12-05 15:41:36.520474 | PLAY [all]
2025-12-05 15:41:36.655287 | 
2025-12-05 15:41:36.655421 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-05 15:41:36.921693 | controller | ok
2025-12-05 15:41:36.931787 | 
2025-12-05 15:41:36.931884 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-05 15:41:37.698614 | controller | changed
2025-12-05 15:41:37.704260 | 
2025-12-05 15:41:37.704342 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-05 15:41:39.242165 | controller | changed
2025-12-05 15:41:39.269319 | 
2025-12-05 15:41:39.269418 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-05 15:41:40.033395 | controller | changed:
2025-12-05 15:41:40.033737 | controller | {
2025-12-05 15:41:40.033785 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-05 15:41:40.033820 | controller | }
2025-12-05 15:41:40.261116 | controller | changed:
2025-12-05 15:41:40.261380 | controller | {
2025-12-05 15:41:40.261425 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-05 15:41:40.261451 | controller | }
2025-12-05 15:41:40.480092 | controller | changed:
2025-12-05 15:41:40.480175 | controller | {
2025-12-05 15:41:40.480203 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-12-05 15:41:40.480226 | controller | }
2025-12-05 15:41:40.710701 | controller | changed:
2025-12-05 15:41:40.710787 | controller | {
2025-12-05 15:41:40.710816 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-05 15:41:40.710838 | controller | }
2025-12-05 15:41:40.951080 | controller | changed:
2025-12-05 15:41:40.951154 | controller | {
2025-12-05 15:41:40.951183 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-05 15:41:40.951204 | controller | }
2025-12-05 15:41:41.176223 | controller | changed:
2025-12-05 15:41:41.176304 | controller | {
2025-12-05 15:41:41.176350 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-05 15:41:41.176374 | controller | }
2025-12-05 15:41:41.393755 | controller | changed:
2025-12-05 15:41:41.393834 | controller | {
2025-12-05 15:41:41.393862 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-12-05 15:41:41.393885 | controller | }
2025-12-05 15:41:41.612432 | controller | changed:
2025-12-05 15:41:41.612512 | controller | {
2025-12-05 15:41:41.612541 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-12-05 15:41:41.612563 | controller | }
2025-12-05 15:41:41.885200 | controller | changed:
2025-12-05 15:41:41.885328 | controller | {
2025-12-05 15:41:41.885359 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-05 15:41:41.885382 | controller | }
2025-12-05 15:41:42.060529 | controller | changed:
2025-12-05 15:41:42.060605 | controller | {
2025-12-05 15:41:42.060633 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-05 15:41:42.060656 | controller | }
2025-12-05 15:41:42.265242 | controller | changed:
2025-12-05 15:41:42.265332 | controller | {
2025-12-05 15:41:42.265361 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-05 15:41:42.265383 | controller | }
2025-12-05 15:41:42.499771 | controller | changed:
2025-12-05 15:41:42.499850 | controller | {
2025-12-05 15:41:42.499880 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-05 15:41:42.499902 | controller | }
2025-12-05 15:41:42.725690 | controller | changed:
2025-12-05 15:41:42.725770 | controller | {
2025-12-05 15:41:42.725797 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-05 15:41:42.725819 | controller | }
2025-12-05 15:41:42.938685 | controller | changed:
2025-12-05 15:41:42.938763 | controller | {
2025-12-05 15:41:42.938790 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-05 15:41:42.938812 | controller | }
2025-12-05 15:41:43.168828 | controller | changed:
2025-12-05 15:41:43.168925 | controller | {
2025-12-05 15:41:43.168960 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-05 15:41:43.168983 | controller | }
2025-12-05 15:41:43.426287 | controller | changed:
2025-12-05 15:41:43.426393 | controller | {
2025-12-05 15:41:43.426420 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-05 15:41:43.426441 | controller | }
2025-12-05 15:41:43.653391 | controller | changed:
2025-12-05 15:41:43.653477 | controller | {
2025-12-05 15:41:43.653505 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-12-05 15:41:43.653528 | controller | }
2025-12-05 15:41:43.878029 | controller | changed:
2025-12-05 15:41:43.878107 | controller | {
2025-12-05 15:41:43.878135 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-12-05 15:41:43.878164 | controller | }
2025-12-05 15:41:44.118118 | controller | changed:
2025-12-05 15:41:44.118260 | controller | {
2025-12-05 15:41:44.118293 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-05 15:41:44.118326 | controller | }
2025-12-05 15:41:44.342226 | controller | changed:
2025-12-05 15:41:44.342303 | controller | {
2025-12-05 15:41:44.342341 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-05 15:41:44.342365 | controller | }
2025-12-05 15:41:44.587998 | controller | changed:
2025-12-05 15:41:44.588081 | controller | {
2025-12-05 15:41:44.588108 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-05 15:41:44.588129 | controller | }
2025-12-05 15:41:44.813936 | controller | changed:
2025-12-05 15:41:44.814021 | controller | {
2025-12-05 15:41:44.814049 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-05 15:41:44.814077 | controller | }
2025-12-05 15:41:45.046217 | controller | changed:
2025-12-05 15:41:45.046374 | controller | {
2025-12-05 15:41:45.046404 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-12-05 15:41:45.046426 | controller | }
2025-12-05 15:41:45.288939 | controller | changed:
2025-12-05 15:41:45.289024 | controller | {
2025-12-05 15:41:45.289052 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-05 15:41:45.289073 | controller | }
2025-12-05 15:41:45.528634 | controller | changed:
2025-12-05 15:41:45.528713 | controller | {
2025-12-05 15:41:45.528738 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-05 15:41:45.528759 | controller | }
2025-12-05 15:41:45.757832 | controller | changed:
2025-12-05 15:41:45.758013 | controller | {
2025-12-05 15:41:45.758082 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-05 15:41:45.758111 | controller | }
2025-12-05 15:41:45.803378 | 
2025-12-05 15:41:45.803482 | TASK [Set timezone to UTC]
2025-12-05 15:41:46.922443 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-05 15:41:46.927639 | 
2025-12-05 15:41:46.927718 | TASK [Create nodepool directory]
2025-12-05 15:41:47.275732 | controller | changed
2025-12-05 15:41:47.285300 | 
2025-12-05 15:41:47.285388 | TASK [Create nodepool sub_nodes file]
2025-12-05 15:41:48.052116 | controller | changed
2025-12-05 15:41:48.059399 | 
2025-12-05 15:41:48.059469 | TASK [Create nodepool sub_nodes_private file]
2025-12-05 15:41:48.751627 | controller | changed
2025-12-05 15:41:48.766767 | 
2025-12-05 15:41:48.766855 | LOOP [Populate nodepool sub_nodes file]
2025-12-05 15:41:48.811245 | 
2025-12-05 15:41:48.811620 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-05 15:41:48.882803 | 
2025-12-05 15:41:48.883003 | TASK [Create nodepool primary file]
2025-12-05 15:41:49.004500 | controller | skipping: Conditional result was False
2025-12-05 15:41:49.020835 | 
2025-12-05 15:41:49.020933 | TASK [Create nodepool node_private for this node]
2025-12-05 15:41:49.781356 | controller | changed
2025-12-05 15:41:49.794384 | 
2025-12-05 15:41:49.794451 | LOOP [Copy ssh keys to nodepool directory]
2025-12-05 15:41:50.792298 | controller | ok: Item: id_rsa Runtime: 0:00:00.004495
2025-12-05 15:41:50.792477 | 
2025-12-05 15:41:50.978236 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004094
2025-12-05 15:41:50.984794 | 
2025-12-05 15:41:50.984891 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-05 15:41:51.700310 | controller | changed
2025-12-05 15:41:51.710154 | 
2025-12-05 15:41:51.710225 | TASK [Validate sudoers config after edits]
2025-12-05 15:41:52.093377 | controller | /etc/sudoers: parsed OK
2025-12-05 15:41:52.093452 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-05 15:41:52.093465 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-05 15:41:52.093474 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-05 15:41:52.317146 | controller | ok: Runtime: 0:00:00.005313
2025-12-05 15:41:52.322953 | 
2025-12-05 15:41:52.323023 | TASK [Show the environment passed in to job shell scripts]
2025-12-05 15:41:52.701315 | controller | SHELL=/bin/bash
2025-12-05 15:41:52.701388 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-05 15:41:52.701455 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-05 15:41:52.701467 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/39/3539/d69732b2a1625f2c30dfa731f6f764b0a55cbde9
2025-12-05 15:41:52.701473 | controller | PWD=/home/zuul
2025-12-05 15:41:52.701480 | controller | ZUUL_PIPELINE=github-check
2025-12-05 15:41:52.701485 | controller | LOGNAME=zuul
2025-12-05 15:41:52.701491 | controller | XDG_SESSION_TYPE=tty
2025-12-05 15:41:52.701497 | controller | _=/usr/bin/env
2025-12-05 15:41:52.701502 | controller | MOTD_SHOWN=pam
2025-12-05 15:41:52.701507 | controller | HOME=/home/zuul
2025-12-05 15:41:52.701512 | controller | LANG=en_US.UTF-8
2025-12-05 15:41:52.701518 | controller | SSH_CONNECTION=192.168.26.12 32940 192.168.26.58 22
2025-12-05 15:41:52.701524 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-12-05 15:41:52.701534 | controller | ZUUL_CHANGE_IDS=3539,d69732b2a1625f2c30dfa731f6f764b0a55cbde9
2025-12-05 15:41:52.701539 | controller | WORKSPACE=/home/zuul/workspace
2025-12-05 15:41:52.701545 | controller | XDG_SESSION_CLASS=user
2025-12-05 15:41:52.701551 | controller | SELINUX_ROLE_REQUESTED=
2025-12-05 15:41:52.701556 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-05 15:41:52.701564 | controller | USER=zuul
2025-12-05 15:41:52.701569 | controller | ZUUL_VOTING=True
2025-12-05 15:41:52.701575 | controller | BUILD_TIMEOUT=1800000
2025-12-05 15:41:52.701580 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-05 15:41:52.701586 | controller | SHLVL=1
2025-12-05 15:41:52.701591 | controller | ZUUL_PATCHSET=d69732b2a1625f2c30dfa731f6f764b0a55cbde9
2025-12-05 15:41:52.701597 | controller | XDG_SESSION_ID=1
2025-12-05 15:41:52.701602 | controller | ZUUL_BRANCH=main
2025-12-05 15:41:52.701607 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-05 15:41:52.701613 | controller | SSH_CLIENT=192.168.26.12 32940 22
2025-12-05 15:41:52.701619 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-05 15:41:52.701625 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-05 15:41:52.701630 | controller | which_declare=declare -f
2025-12-05 15:41:52.701637 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-05 15:41:52.701643 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-05 15:41:52.701648 | controller | ZUUL_CHANGE=3539
2025-12-05 15:41:52.701653 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-05 15:41:52.701659 | controller | ZUUL_UUID=1bf164037bef4236b4131e8014cd9963
2025-12-05 15:41:52.701676 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-05 15:41:52.701682 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-05 15:41:52.701687 | controller | }
2025-12-05 15:41:52.907204 | controller | ok: Runtime: 0:00:00.005734
2025-12-05 15:41:52.912557 | 
2025-12-05 15:41:52.912621 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-05 15:41:52.946863 | controller | skipping: Conditional result was False
2025-12-05 15:41:52.953050 | 
2025-12-05 15:41:52.953127 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-05 15:41:53.501650 | controller | skipping: Conditional result was False
2025-12-05 15:41:53.589409 | 
2025-12-05 15:41:53.589493 | TASK [Ensure legacy workspace directory]
2025-12-05 15:41:53.876724 | controller | changed
2025-12-05 15:41:53.964027 | 
2025-12-05 15:41:53.964101 | PLAY RECAP
2025-12-05 15:41:53.964147 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 15:41:53.964172 | 
2025-12-05 15:41:54.441452 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-05 15:41:54.442216 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-05 15:41:56.524130 | 
2025-12-05 15:41:56.524221 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-05 15:41:56.646275 | 
2025-12-05 15:41:56.646439 | TASK [Create zuul-output directory]
2025-12-05 15:41:57.437998 | controller | changed
2025-12-05 15:41:57.443513 | 
2025-12-05 15:41:57.443600 | TASK [Slurp Zuul inventory test]
2025-12-05 15:41:58.321277 | controller -> localhost | ok
2025-12-05 15:41:58.331345 | 
2025-12-05 15:41:58.331427 | TASK [Save zuul inventory]
2025-12-05 15:42:00.060521 | controller | changed
2025-12-05 15:42:00.069408 | 
2025-12-05 15:42:00.069472 | TASK [Save zuul vars without the change_message]
2025-12-05 15:42:01.147255 | controller | changed
2025-12-05 15:42:01.219162 | 
2025-12-05 15:42:01.219258 | PLAY RECAP
2025-12-05 15:42:01.219310 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 15:42:01.219357 | 
2025-12-05 15:42:01.606002 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-05 15:42:01.607003 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-05 15:42:04.139702 | 
2025-12-05 15:42:04.139796 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-05 15:42:04.170547 | 
2025-12-05 15:42:04.170623 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-05 15:42:04.214461 | controller | ok
2025-12-05 15:42:04.248630 | 
2025-12-05 15:42:04.248721 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-05 15:42:04.316842 | controller | skipping: Conditional result was False
2025-12-05 15:42:04.323374 | 
2025-12-05 15:42:04.323445 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-05 15:42:05.395035 | controller | ok
2025-12-05 15:42:05.400798 | 
2025-12-05 15:42:05.400871 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-05 15:42:06.801714 | controller | ok
2025-12-05 15:42:06.836570 | 
2025-12-05 15:42:06.836656 | TASK [Prepare workspace]
2025-12-05 15:42:06.919313 | controller | ok
2025-12-05 15:42:07.032264 | 
2025-12-05 15:42:07.032371 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-05 15:42:07.574937 | controller | ok
2025-12-05 15:42:07.607196 | 
2025-12-05 15:42:07.607282 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-05 15:42:16.325193 | controller | Output suppressed because no_log was given
2025-12-05 15:42:16.355879 | 
2025-12-05 15:42:16.361246 | LOOP [Create zuul-output directory]
2025-12-05 15:42:16.662477 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-05 15:42:16.806572 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-05 15:42:16.819836 | 
2025-12-05 15:42:16.820023 | TASK [Install required packages]
2025-12-05 15:43:06.489073 | controller | changed
2025-12-05 15:43:06.495853 | 
2025-12-05 15:43:06.497964 | TASK [Install venv]
2025-12-05 15:46:38.309962 | controller | ERROR
2025-12-05 15:46:38.310146 | controller | {
2025-12-05 15:46:38.310180 | controller |   "msg": "gmake: *** [Makefile:60: setup_molecule] Error 1",
2025-12-05 15:46:38.310203 | controller |   "rc": 2
2025-12-05 15:46:38.310222 | controller | }
failure
2025-12-05 15:46:38.314666 | 
2025-12-05 15:46:38.314720 | PLAY RECAP
2025-12-05 15:46:38.314776 | controller | ok: 6 changed: 3 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-12-05 15:46:38.314802 | 
2025-12-05 15:46:38.579755 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-05 15:46:38.580655 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-05 15:46:39.971361 | 
2025-12-05 15:46:39.971459 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-05 15:46:40.011493 | 
2025-12-05 15:46:40.011591 | TASK [Filter out host if needed]
2025-12-05 15:46:40.035990 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-05 15:46:40.047221 | 
2025-12-05 15:46:40.047291 | TASK [Ensure file is present]
2025-12-05 15:46:40.720139 | controller | ok
2025-12-05 15:46:40.730405 | 
2025-12-05 15:46:40.732984 | TASK [Manage molecule report file]
2025-12-05 15:46:41.335545 | controller | skipping: Conditional result was False
2025-12-05 15:46:41.341299 | 
2025-12-05 15:46:41.341368 | TASK [Check if we get ci-framework-data basedir]
2025-12-05 15:46:41.584535 | controller | ok
2025-12-05 15:46:41.591819 | 
2025-12-05 15:46:41.591896 | TASK [Create ci-framework-data log directory for zuul]
2025-12-05 15:46:42.201611 | controller | changed
2025-12-05 15:46:42.209444 | 
2025-12-05 15:46:42.209514 | TASK [Copy ci-framework interesting files]
2025-12-05 15:46:42.835754 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-12-05 15:46:43.301426 | controller | changed
2025-12-05 15:46:43.306595 | 
2025-12-05 15:46:43.306663 | TASK [Get SELinux listing]
2025-12-05 15:46:43.881884 | controller | changed
2025-12-05 15:46:43.887881 | 
2025-12-05 15:46:43.887968 | TASK [Generate log index]
2025-12-05 15:46:44.883344 | controller | changed
2025-12-05 15:46:44.895874 | 
2025-12-05 15:46:44.896016 | TASK [Get some env related data]
2025-12-05 15:46:45.535779 | controller | /home/zuul/.local/bin/ansible
2025-12-05 15:46:46.494532 | controller | changed
2025-12-05 15:46:46.501587 | 
2025-12-05 15:46:46.501666 | TASK [Generate list of logs to collect in home directory]
2025-12-05 15:46:47.078551 | controller | ok: All paths examined
2025-12-05 15:46:47.129402 | 
2025-12-05 15:46:47.129484 | LOOP [Copy logs from home directory]
2025-12-05 15:46:47.152121 | 
2025-12-05 15:46:47.152691 | TASK [Copy crio stats log file]
2025-12-05 15:46:47.175664 | controller | skipping: Conditional result was False
2025-12-05 15:46:47.181517 | 
2025-12-05 15:46:47.181590 | TASK [Get SELinux related data]
2025-12-05 15:46:47.418807 | controller | <no matches>
2025-12-05 15:46:47.715538 | controller | ERROR
2025-12-05 15:46:47.715658 | controller | {
2025-12-05 15:46:47.715690 | controller |   "delta": "0:00:00.007702",
2025-12-05 15:46:47.715712 | controller |   "end": "2025-12-05 15:46:47.419325",
2025-12-05 15:46:47.715732 | controller |   "msg": "non-zero return code",
2025-12-05 15:46:47.715775 | controller |   "rc": 1,
2025-12-05 15:46:47.715798 | controller |   "start": "2025-12-05 15:46:47.411623"
2025-12-05 15:46:47.715816 | controller | }
2025-12-05 15:46:47.715839 | controller | ERROR: Ignoring Errors
2025-12-05 15:46:47.721136 | 
2025-12-05 15:46:47.721202 | TASK [Create system configuration directory]
2025-12-05 15:46:47.934871 | controller | changed
2025-12-05 15:46:47.944359 | 
2025-12-05 15:46:47.944519 | TASK [Get some of the system configurations]
2025-12-05 15:46:48.489099 | controller | changed
2025-12-05 15:46:48.495199 | 
2025-12-05 15:46:48.495282 | TASK [Copy generated documentation if available]
2025-12-05 15:46:48.509700 | controller | skipping: Conditional result was False
2025-12-05 15:46:48.516300 | 
2025-12-05 15:46:48.516376 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-05 15:46:48.543091 | controller | skipping: Conditional result was False
2025-12-05 15:46:48.548678 | 
2025-12-05 15:46:48.548742 | TASK [Compress logs bigger than 2MB]
2025-12-05 15:46:49.117169 | controller | changed
2025-12-05 15:46:49.122513 | 
2025-12-05 15:46:49.122594 | TASK [Copy files from workspace on node]
2025-12-05 15:46:49.155428 | controller | ok
2025-12-05 15:46:49.189939 | 
2025-12-05 15:46:49.190023 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 15:46:49.233516 | controller | skipping: Conditional result was False
2025-12-05 15:46:49.240510 | 
2025-12-05 15:46:49.240592 | TASK [fetch-output : Set log path for single node]
2025-12-05 15:46:49.302553 | controller | ok
2025-12-05 15:46:49.307663 | 
2025-12-05 15:46:49.308402 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 15:46:50.029672 | controller -> localhost | ok: "/var/lib/zuul/builds/1bf164037bef4236b4131e8014cd9963/work/logs"
2025-12-05 15:46:50.029973 | 
2025-12-05 15:46:50.737357 | controller -> localhost | changed: "/var/lib/zuul/builds/1bf164037bef4236b4131e8014cd9963/work/artifacts"
2025-12-05 15:46:51.352957 | controller -> localhost | changed: "/var/lib/zuul/builds/1bf164037bef4236b4131e8014cd9963/work/docs"
2025-12-05 15:46:51.366660 | 
2025-12-05 15:46:51.366783 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 15:46:52.615436 | controller | changed:
2025-12-05 15:46:52.616959 | controller | .d..t...... ./
2025-12-05 15:46:52.617009 | controller | >f+++++++++ README.html
2025-12-05 15:46:52.617037 | controller | >f+++++++++ dmesg.log
2025-12-05 15:46:52.617061 | controller | >f+++++++++ installed-pkgs.log
2025-12-05 15:46:52.617081 | controller | >f+++++++++ python.log
2025-12-05 15:46:52.617099 | controller | >f+++++++++ registries.conf
2025-12-05 15:46:52.617116 | controller | >f+++++++++ selinux-denials.log
2025-12-05 15:46:52.617133 | controller | >f+++++++++ selinux-listing.log
2025-12-05 15:46:52.617151 | controller | cd+++++++++ ci-framework-data/
2025-12-05 15:46:52.617170 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-05 15:46:52.617188 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-05 15:46:52.617206 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-05 15:46:52.617223 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-05 15:46:52.617239 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-05 15:46:52.617256 | controller | cd+++++++++ registries.conf.d/
2025-12-05 15:46:52.617272 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-05 15:46:52.617288 | controller | cd+++++++++ system-config/
2025-12-05 15:46:52.617303 | controller | cd+++++++++ system-config/libvirt/
2025-12-05 15:46:52.617319 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-05 15:46:52.617335 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-05 15:46:53.045085 | controller | changed: .d..t...... ./
2025-12-05 15:46:53.758104 | controller | changed: .d..t...... ./
2025-12-05 15:46:53.779453 | 
2025-12-05 15:46:53.779521 | TASK [Return artifact to Zuul]
2025-12-05 15:46:53.833741 | controller | skipping: Conditional result was False
2025-12-05 15:46:53.883066 | 
2025-12-05 15:46:53.883138 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-05 15:46:53.883239 | 
2025-12-05 15:46:53.883267 | PLAY RECAP
2025-12-05 15:46:53.883306 | controller | ok: 15 changed: 11 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 1
2025-12-05 15:46:53.883327 | 
2025-12-05 15:46:54.071290 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-05 15:46:54.072285 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 15:46:55.000120 | 
2025-12-05 15:46:55.000238 | PLAY [all]
2025-12-05 15:46:55.035980 | 
2025-12-05 15:46:55.036066 | TASK [include_role : fetch-output]
2025-12-05 15:46:55.075999 | controller | ok
2025-12-05 15:46:55.094014 | 
2025-12-05 15:46:55.094123 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 15:46:55.138656 | controller | skipping: Conditional result was False
2025-12-05 15:46:55.144470 | 
2025-12-05 15:46:55.144546 | TASK [fetch-output : Set log path for single node]
2025-12-05 15:46:55.173793 | controller | ok
2025-12-05 15:46:55.182555 | 
2025-12-05 15:46:55.182674 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 15:46:55.545541 | controller -> localhost | ok: "/var/lib/zuul/builds/1bf164037bef4236b4131e8014cd9963/work/logs"
2025-12-05 15:46:55.768715 | controller -> localhost | ok: "/var/lib/zuul/builds/1bf164037bef4236b4131e8014cd9963/work/artifacts"
2025-12-05 15:46:55.958046 | controller -> localhost | ok: "/var/lib/zuul/builds/1bf164037bef4236b4131e8014cd9963/work/docs"
2025-12-05 15:46:55.975335 | 
2025-12-05 15:46:55.975431 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 15:46:56.710391 | controller | ok
2025-12-05 15:46:56.710616 | controller | ok: All items complete
2025-12-05 15:46:56.710649 | 
2025-12-05 15:46:57.236753 | controller | ok
2025-12-05 15:46:57.709016 | controller | ok
2025-12-05 15:46:57.729872 | 
2025-12-05 15:46:57.730009 | TASK [include_role : fetch-output-openshift]
2025-12-05 15:46:57.753640 | controller | skipping: Conditional result was False
2025-12-05 15:46:57.760341 | 
2025-12-05 15:46:57.760425 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-05 15:46:58.381347 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007195
2025-12-05 15:46:58.629629 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.025012
2025-12-05 15:46:58.689694 | 
2025-12-05 15:46:58.689789 | PLAY [all]
2025-12-05 15:46:58.708839 | 
2025-12-05 15:46:58.708922 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-05 15:46:59.154258 | controller | changed
2025-12-05 15:46:59.200711 | 
2025-12-05 15:46:59.200811 | PLAY RECAP
2025-12-05 15:46:59.200859 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 15:46:59.200884 | 
2025-12-05 15:46:59.370498 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 15:46:59.371277 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-05 15:47:00.301117 | 
2025-12-05 15:47:00.301222 | PLAY [localhost]
2025-12-05 15:47:00.325013 | 
2025-12-05 15:47:00.325136 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-05 15:47:00.781261 | localhost | changed
2025-12-05 15:47:00.785756 | 
2025-12-05 15:47:00.785866 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-05 15:47:00.817797 | localhost | ok
2025-12-05 15:47:00.826585 | 
2025-12-05 15:47:00.826682 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-05 15:47:01.200665 | localhost | changed
2025-12-05 15:47:01.210580 | 
2025-12-05 15:47:01.210693 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-05 15:47:01.830304 | localhost | changed
2025-12-05 15:47:01.836163 | 
2025-12-05 15:47:01.836252 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-05 15:47:02.239984 | localhost | Identity added: /var/lib/zuul/builds/1bf164037bef4236b4131e8014cd9963/work/tmp/ansible.4bxbjquv (/var/lib/zuul/builds/1bf164037bef4236b4131e8014cd9963/work/tmp/ansible.4bxbjquv)
2025-12-05 15:47:02.240144 | localhost | ok: Runtime: 0:00:00.010094
2025-12-05 15:47:02.244980 | 
2025-12-05 15:47:02.245063 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-05 15:47:02.699415 | localhost | ok: Runtime: 0:00:00.046302
2025-12-05 15:47:02.704641 | 
2025-12-05 15:47:02.704721 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-05 15:47:02.809229 | localhost | changed
2025-12-05 15:47:02.813856 | 
2025-12-05 15:47:02.816968 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-05 15:47:03.364135 | localhost | changed
2025-12-05 15:47:03.413157 | 
2025-12-05 15:47:03.413249 | PLAY [localhost]
2025-12-05 15:47:03.435650 | 
2025-12-05 15:47:03.435745 | TASK [Generate bulk log download script]
2025-12-05 15:47:03.476618 | localhost | ok
2025-12-05 15:47:03.517476 | 
2025-12-05 15:47:03.517589 | TASK [local-log-download : Check API endpoint is defined]
2025-12-05 15:47:03.589868 | localhost | ok: All assertions passed
2025-12-05 15:47:03.594839 | 
2025-12-05 15:47:03.594933 | TASK [local-log-download : Create download script]
2025-12-05 15:47:04.403270 | localhost -> localhost | changed
2025-12-05 15:47:04.456770 | 
2025-12-05 15:47:04.456860 | TASK [Register quick-download link]
2025-12-05 15:47:04.501584 | localhost | ok
2025-12-05 15:47:04.530587 | 
2025-12-05 15:47:04.530650 | PLAY [logserver.rdoproject.org]
2025-12-05 15:47:04.570673 | 
2025-12-05 15:47:04.570744 | TASK [Set zuul-log-path fact]
2025-12-05 15:47:04.607052 | logserver.rdoproject.org | ok
2025-12-05 15:47:04.627844 | 
2025-12-05 15:47:04.627944 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 15:47:04.689393 | logserver.rdoproject.org | ok
2025-12-05 15:47:04.695345 | 
2025-12-05 15:47:04.695420 | TASK [upload-logs : Create log directories]
2025-12-05 15:47:06.843519 | logserver.rdoproject.org | changed
2025-12-05 15:47:06.847241 | 
2025-12-05 15:47:06.847304 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-05 15:47:07.167721 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.009434
2025-12-05 15:47:07.173736 | 
2025-12-05 15:47:07.173830 | TASK [upload-logs : Upload logs to log server]
2025-12-05 15:47:08.996579 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-05 15:47:08.999527 | 
2025-12-05 15:47:08.999593 | LOOP [upload-logs : Compress console log and json output]
2025-12-05 15:47:09.092402 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:47:09.103182 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:47:09.111219 | 
2025-12-05 15:47:09.111318 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-05 15:47:09.189539 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:47:09.189792 | 
2025-12-05 15:47:09.192834 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:47:09.200841 | 
2025-12-05 15:47:09.200972 | LOOP [upload-logs : Upload console log and json output]