2025-10-13 11:58:45.535754 | Job console starting...
2025-10-13 11:58:45.776697 | Updating repositories
2025-10-13 11:58:46.560789 | Preparing job workspace
2025-10-13 11:59:03.151116 | Running Ansible setup...
2025-10-13 11:59:10.562295 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 11:59:13.519950 | 
2025-10-13 11:59:13.520083 | PLAY [localhost]
2025-10-13 11:59:13.544796 | 
2025-10-13 11:59:13.544870 | TASK [Gathering Facts]
2025-10-13 11:59:15.824851 | localhost | ok
2025-10-13 11:59:15.845475 | 
2025-10-13 11:59:15.845580 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-13 11:59:17.006236 | localhost -> localhost | changed
2025-10-13 11:59:17.011877 | 
2025-10-13 11:59:17.011987 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-13 11:59:19.667784 | localhost -> localhost | changed
2025-10-13 11:59:19.676342 | 
2025-10-13 11:59:19.676432 | TASK [Setup log path fact]
2025-10-13 11:59:19.750175 | localhost | ok
2025-10-13 11:59:19.769677 | 
2025-10-13 11:59:19.769770 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 11:59:19.818571 | localhost | ok
2025-10-13 11:59:19.826501 | 
2025-10-13 11:59:19.826589 | TASK [emit-job-header : Print job information]
2025-10-13 11:59:19.889505 | # Job Information
2025-10-13 11:59:19.889647 | Ansible Version: 2.15.12
2025-10-13 11:59:19.889692 | Job: cifmw-molecule-artifacts
2025-10-13 11:59:19.889717 | Pipeline: github-check
2025-10-13 11:59:19.889738 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-13 11:59:19.889759 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3402
2025-10-13 11:59:19.889779 | Log URL (when completed): https://logserver.rdoproject.org/913/rdoproject.org/913908b88f6d47c7a7376c53829031b2/
2025-10-13 11:59:19.889807 | Event ID: 3d0bd0d0-a82b-11f0-9a9b-cd26fae28367
2025-10-13 11:59:19.898136 | 
2025-10-13 11:59:19.898208 | LOOP [emit-job-header : Print node information]
2025-10-13 11:59:20.347530 | localhost | ok:
2025-10-13 11:59:20.347895 | localhost | # Node Information
2025-10-13 11:59:20.347958 | localhost | Inventory Hostname: controller
2025-10-13 11:59:20.347988 | localhost | Hostname: np0005484189
2025-10-13 11:59:20.348011 | localhost | Username: zuul
2025-10-13 11:59:20.348034 | localhost | Distro: CentOS 9
2025-10-13 11:59:20.348053 | localhost | Provider: ibm-bm4-nodepool
2025-10-13 11:59:20.348071 | localhost | Region: regionOne
2025-10-13 11:59:20.348088 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-13 11:59:20.348106 | localhost | Product Name: OpenStack Compute
2025-10-13 11:59:20.348124 | localhost | Interface IP: 192.168.26.37
2025-10-13 11:59:20.438279 | 
2025-10-13 11:59:20.438371 | PLAY [all]
2025-10-13 11:59:20.460350 | 
2025-10-13 11:59:20.460425 | TASK [Gather network facts]
2025-10-13 11:59:20.904694 | controller | ok
2025-10-13 11:59:20.972743 | 
2025-10-13 11:59:20.972840 | TASK [include_role : start-zuul-console]
2025-10-13 11:59:21.041202 | controller | ok
2025-10-13 11:59:21.081947 | 
2025-10-13 11:59:21.082052 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-13 11:59:22.061714 | controller | ok
2025-10-13 11:59:22.082743 | 
2025-10-13 11:59:22.082831 | TASK [include_role : add-build-sshkey]
2025-10-13 11:59:22.209368 | controller | ok
2025-10-13 11:59:22.259439 | 
2025-10-13 11:59:22.259543 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-13 11:59:23.222569 | controller -> localhost | ok
2025-10-13 11:59:23.228521 | 
2025-10-13 11:59:23.228605 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-13 11:59:23.288088 | controller | ok
2025-10-13 11:59:23.363950 | controller | included: /var/lib/zuul/builds/913908b88f6d47c7a7376c53829031b2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-13 11:59:23.388786 | 
2025-10-13 11:59:23.388865 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-13 11:59:25.412230 | controller -> localhost | Generating public/private rsa key pair.
2025-10-13 11:59:25.412401 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/913908b88f6d47c7a7376c53829031b2/work/913908b88f6d47c7a7376c53829031b2_id_rsa.
2025-10-13 11:59:25.412438 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/913908b88f6d47c7a7376c53829031b2/work/913908b88f6d47c7a7376c53829031b2_id_rsa.pub.
2025-10-13 11:59:25.412463 | controller -> localhost | The key fingerprint is:
2025-10-13 11:59:25.412485 | controller -> localhost | SHA256:t/lBcXxhaPsa25IEfiTndGp4HTZ+luDV8tDPjWJVggw zuul-build-sshkey
2025-10-13 11:59:25.412505 | controller -> localhost | The key's randomart image is:
2025-10-13 11:59:25.412524 | controller -> localhost | +---[RSA 3072]----+
2025-10-13 11:59:25.412543 | controller -> localhost | |          Eo ..o |
2025-10-13 11:59:25.412562 | controller -> localhost | |            o+o o|
2025-10-13 11:59:25.412580 | controller -> localhost | |            o ++o|
2025-10-13 11:59:25.412600 | controller -> localhost | |           o O+Bo|
2025-10-13 11:59:25.412619 | controller -> localhost | |        S o @.X**|
2025-10-13 11:59:25.412639 | controller -> localhost | |         . *oX.=B|
2025-10-13 11:59:25.412658 | controller -> localhost | |          o.*.*..|
2025-10-13 11:59:25.412694 | controller -> localhost | |           . * . |
2025-10-13 11:59:25.412714 | controller -> localhost | |            . .  |
2025-10-13 11:59:25.412732 | controller -> localhost | +----[SHA256]-----+
2025-10-13 11:59:25.412777 | controller -> localhost | ok: Runtime: 0:00:00.300910
2025-10-13 11:59:25.418719 | 
2025-10-13 11:59:25.418785 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-13 11:59:25.520429 | controller | ok
2025-10-13 11:59:25.544092 | controller | included: /var/lib/zuul/builds/913908b88f6d47c7a7376c53829031b2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-13 11:59:25.606004 | 
2025-10-13 11:59:25.606105 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-13 11:59:25.700523 | controller | skipping: Conditional result was False
2025-10-13 11:59:25.707003 | 
2025-10-13 11:59:25.707090 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-13 11:59:26.882265 | controller | changed
2025-10-13 11:59:26.897163 | 
2025-10-13 11:59:26.897248 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-13 11:59:27.234198 | controller | ok
2025-10-13 11:59:27.239475 | 
2025-10-13 11:59:27.243578 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-13 11:59:28.192788 | controller | changed
2025-10-13 11:59:28.339206 | 
2025-10-13 11:59:28.339301 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-13 11:59:29.311435 | controller | changed
2025-10-13 11:59:29.316789 | 
2025-10-13 11:59:29.316874 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-13 11:59:29.445595 | controller | skipping: Conditional result was False
2025-10-13 11:59:29.451975 | 
2025-10-13 11:59:29.452062 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-13 11:59:31.222077 | controller -> localhost | changed
2025-10-13 11:59:31.242406 | 
2025-10-13 11:59:31.242482 | TASK [add-build-sshkey : Add back temp key]
2025-10-13 11:59:32.589212 | controller -> localhost | Identity added: /var/lib/zuul/builds/913908b88f6d47c7a7376c53829031b2/work/913908b88f6d47c7a7376c53829031b2_id_rsa (zuul-build-sshkey)
2025-10-13 11:59:32.589478 | controller -> localhost | ok: Runtime: 0:00:00.059973
2025-10-13 11:59:32.596071 | 
2025-10-13 11:59:32.596146 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-13 11:59:33.276610 | controller | ok
2025-10-13 11:59:33.281279 | 
2025-10-13 11:59:33.281349 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-13 11:59:33.351302 | controller | skipping: Conditional result was False
2025-10-13 11:59:33.364769 | 
2025-10-13 11:59:33.365052 | TASK [include_role : validate-host]
2025-10-13 11:59:33.457670 | controller | ok
2025-10-13 11:59:33.533071 | 
2025-10-13 11:59:33.533160 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-13 11:59:33.588805 | controller | ok
2025-10-13 11:59:33.594879 | 
2025-10-13 11:59:33.594988 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-13 11:59:34.725466 | controller -> localhost | ok
2025-10-13 11:59:34.732789 | 
2025-10-13 11:59:34.732877 | TASK [validate-host : Collect information about the host]
2025-10-13 11:59:35.416904 | controller | ok
2025-10-13 11:59:35.447240 | 
2025-10-13 11:59:35.447325 | TASK [validate-host : Sanitize hostname]
2025-10-13 11:59:35.711359 | controller | ok
2025-10-13 11:59:35.716274 | 
2025-10-13 11:59:35.716348 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-13 11:59:38.013967 | controller -> localhost | changed
2025-10-13 11:59:38.019539 | 
2025-10-13 11:59:38.019616 | TASK [validate-host : Collect information about zuul worker]
2025-10-13 11:59:39.520624 | controller | ok
2025-10-13 11:59:39.525881 | 
2025-10-13 11:59:39.525966 | TASK [validate-host : Write out all zuul information for each host]
2025-10-13 11:59:42.192341 | controller -> localhost | changed
2025-10-13 11:59:42.203901 | 
2025-10-13 11:59:42.204011 | TASK [include_role : prepare-workspace-openshift]
2025-10-13 11:59:42.325064 | controller | skipping: Conditional result was False
2025-10-13 11:59:42.331770 | 
2025-10-13 11:59:42.331902 | TASK [include_role : remove-zuul-sshkey]
2025-10-13 11:59:42.427840 | controller | skipping: Conditional result was False
2025-10-13 11:59:42.434609 | 
2025-10-13 11:59:42.434689 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-13 11:59:42.804413 | controller | ok: "logs"
2025-10-13 11:59:42.804643 | controller | ok: All items complete
2025-10-13 11:59:42.804746 | 
2025-10-13 11:59:43.028009 | controller | ok: "artifacts"
2025-10-13 11:59:43.230051 | controller | ok: "docs"
2025-10-13 11:59:43.236516 | 
2025-10-13 11:59:43.236623 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-13 11:59:43.551960 | controller | changed: "logs"
2025-10-13 11:59:43.796954 | controller | changed: "artifacts"
2025-10-13 11:59:44.018372 | controller | changed: "docs"
2025-10-13 11:59:44.116056 | 
2025-10-13 11:59:44.116146 | PLAY RECAP
2025-10-13 11:59:44.116197 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 11:59:44.116227 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 11:59:44.116247 | 
2025-10-13 11:59:44.881411 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 11:59:44.882306 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 11:59:48.476131 | 
2025-10-13 11:59:48.476250 | PLAY [all]
2025-10-13 11:59:48.605954 | 
2025-10-13 11:59:48.606076 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-13 11:59:48.776046 | controller | ok
2025-10-13 11:59:48.783626 | 
2025-10-13 11:59:48.783765 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-13 11:59:49.723279 | controller | changed
2025-10-13 11:59:49.736585 | 
2025-10-13 11:59:49.736661 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-13 11:59:52.591151 | controller | changed
2025-10-13 11:59:52.627051 | 
2025-10-13 11:59:52.627142 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-13 11:59:53.822512 | controller | changed:
2025-10-13 11:59:53.822663 | controller | {
2025-10-13 11:59:53.822709 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-13 11:59:53.822740 | controller | }
2025-10-13 11:59:54.039951 | controller | changed:
2025-10-13 11:59:54.040073 | controller | {
2025-10-13 11:59:54.040107 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-13 11:59:54.040132 | controller | }
2025-10-13 11:59:54.299059 | controller | changed:
2025-10-13 11:59:54.299139 | controller | {
2025-10-13 11:59:54.299168 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-13 11:59:54.299190 | controller | }
2025-10-13 11:59:54.557166 | controller | changed:
2025-10-13 11:59:54.557237 | controller | {
2025-10-13 11:59:54.557264 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-13 11:59:54.557286 | controller | }
2025-10-13 11:59:54.795711 | controller | changed:
2025-10-13 11:59:54.795849 | controller | {
2025-10-13 11:59:54.795880 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-13 11:59:54.795902 | controller | }
2025-10-13 11:59:55.034631 | controller | changed:
2025-10-13 11:59:55.034725 | controller | {
2025-10-13 11:59:55.034757 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-13 11:59:55.034781 | controller | }
2025-10-13 11:59:55.310429 | controller | changed:
2025-10-13 11:59:55.310508 | controller | {
2025-10-13 11:59:55.310536 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-13 11:59:55.310560 | controller | }
2025-10-13 11:59:55.560626 | controller | changed:
2025-10-13 11:59:55.560712 | controller | {
2025-10-13 11:59:55.560741 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-13 11:59:55.560764 | controller | }
2025-10-13 11:59:55.786254 | controller | changed:
2025-10-13 11:59:55.786343 | controller | {
2025-10-13 11:59:55.786371 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-13 11:59:55.786394 | controller | }
2025-10-13 11:59:56.053279 | controller | changed:
2025-10-13 11:59:56.053354 | controller | {
2025-10-13 11:59:56.053383 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-13 11:59:56.053404 | controller | }
2025-10-13 11:59:56.291363 | controller | changed:
2025-10-13 11:59:56.291444 | controller | {
2025-10-13 11:59:56.291472 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-13 11:59:56.291492 | controller | }
2025-10-13 11:59:56.551415 | controller | changed:
2025-10-13 11:59:56.551571 | controller | {
2025-10-13 11:59:56.551605 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-13 11:59:56.551629 | controller | }
2025-10-13 11:59:56.816897 | controller | changed:
2025-10-13 11:59:56.817000 | controller | {
2025-10-13 11:59:56.817029 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-13 11:59:56.817051 | controller | }
2025-10-13 11:59:57.083950 | controller | changed:
2025-10-13 11:59:57.084034 | controller | {
2025-10-13 11:59:57.084062 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-13 11:59:57.084082 | controller | }
2025-10-13 11:59:57.347877 | controller | changed:
2025-10-13 11:59:57.348092 | controller | {
2025-10-13 11:59:57.348126 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-13 11:59:57.348149 | controller | }
2025-10-13 11:59:57.623977 | controller | changed:
2025-10-13 11:59:57.626117 | controller | {
2025-10-13 11:59:57.626176 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-13 11:59:57.626203 | controller | }
2025-10-13 11:59:57.886561 | controller | changed:
2025-10-13 11:59:57.886638 | controller | {
2025-10-13 11:59:57.886666 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-13 11:59:57.886700 | controller | }
2025-10-13 11:59:58.162678 | controller | changed:
2025-10-13 11:59:58.162774 | controller | {
2025-10-13 11:59:58.162802 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-13 11:59:58.162833 | controller | }
2025-10-13 11:59:58.449139 | controller | changed:
2025-10-13 11:59:58.449209 | controller | {
2025-10-13 11:59:58.449237 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-13 11:59:58.449259 | controller | }
2025-10-13 11:59:58.690358 | controller | changed:
2025-10-13 11:59:58.691131 | controller | {
2025-10-13 11:59:58.691212 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-13 11:59:58.691243 | controller | }
2025-10-13 11:59:58.930103 | controller | changed:
2025-10-13 11:59:58.930183 | controller | {
2025-10-13 11:59:58.930213 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-13 11:59:58.930236 | controller | }
2025-10-13 11:59:59.186147 | controller | changed:
2025-10-13 11:59:59.186236 | controller | {
2025-10-13 11:59:59.186266 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-13 11:59:59.186288 | controller | }
2025-10-13 11:59:59.442835 | controller | changed:
2025-10-13 11:59:59.442945 | controller | {
2025-10-13 11:59:59.442978 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-13 11:59:59.443001 | controller | }
2025-10-13 11:59:59.704618 | controller | changed:
2025-10-13 11:59:59.704720 | controller | {
2025-10-13 11:59:59.704749 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-13 11:59:59.704774 | controller | }
2025-10-13 11:59:59.965249 | controller | changed:
2025-10-13 11:59:59.965324 | controller | {
2025-10-13 11:59:59.965353 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-13 11:59:59.965375 | controller | }
2025-10-13 12:00:00.218006 | controller | changed:
2025-10-13 12:00:00.218091 | controller | {
2025-10-13 12:00:00.222312 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-13 12:00:00.222359 | controller | }
2025-10-13 12:00:00.308345 | 
2025-10-13 12:00:00.308458 | TASK [Set timezone to UTC]
2025-10-13 12:00:01.487071 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-13 12:00:01.497636 | 
2025-10-13 12:00:01.501270 | TASK [Create nodepool directory]
2025-10-13 12:00:01.800733 | controller | changed
2025-10-13 12:00:01.808581 | 
2025-10-13 12:00:01.808654 | TASK [Create nodepool sub_nodes file]
2025-10-13 12:00:02.681866 | controller | changed
2025-10-13 12:00:02.695288 | 
2025-10-13 12:00:02.695381 | TASK [Create nodepool sub_nodes_private file]
2025-10-13 12:00:03.755419 | controller | changed
2025-10-13 12:00:03.777134 | 
2025-10-13 12:00:03.777219 | LOOP [Populate nodepool sub_nodes file]
2025-10-13 12:00:03.988754 | 
2025-10-13 12:00:03.989017 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-13 12:00:04.083121 | 
2025-10-13 12:00:04.083276 | TASK [Create nodepool primary file]
2025-10-13 12:00:04.166880 | controller | skipping: Conditional result was False
2025-10-13 12:00:04.172776 | 
2025-10-13 12:00:04.172879 | TASK [Create nodepool node_private for this node]
2025-10-13 12:00:05.106026 | controller | changed
2025-10-13 12:00:05.126735 | 
2025-10-13 12:00:05.126948 | LOOP [Copy ssh keys to nodepool directory]
2025-10-13 12:00:06.433450 | controller | ok: Item: id_rsa Runtime: 0:00:00.004967
2025-10-13 12:00:06.433625 | 
2025-10-13 12:00:06.640989 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005032
2025-10-13 12:00:06.650416 | 
2025-10-13 12:00:06.650497 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-13 12:00:07.562542 | controller | changed
2025-10-13 12:00:07.576055 | 
2025-10-13 12:00:07.576141 | TASK [Validate sudoers config after edits]
2025-10-13 12:00:08.162559 | controller | /etc/sudoers: parsed OK
2025-10-13 12:00:08.162622 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-13 12:00:08.162632 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-13 12:00:08.162638 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-13 12:00:08.250660 | controller | ok: Runtime: 0:00:00.005368
2025-10-13 12:00:08.256591 | 
2025-10-13 12:00:08.256673 | TASK [Show the environment passed in to job shell scripts]
2025-10-13 12:00:08.982179 | controller | SHELL=/bin/bash
2025-10-13 12:00:08.982235 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-13 12:00:08.982245 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-13 12:00:08.982252 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/02/3402/eeae0ae9c1d57b4a0c12bc4381573dd0b9c62cf2
2025-10-13 12:00:08.982306 | controller | PWD=/home/zuul
2025-10-13 12:00:08.982315 | controller | ZUUL_PIPELINE=github-check
2025-10-13 12:00:08.982321 | controller | LOGNAME=zuul
2025-10-13 12:00:08.982327 | controller | XDG_SESSION_TYPE=tty
2025-10-13 12:00:08.982333 | controller | _=/usr/bin/env
2025-10-13 12:00:08.982339 | controller | MOTD_SHOWN=pam
2025-10-13 12:00:08.982361 | controller | HOME=/home/zuul
2025-10-13 12:00:08.982379 | controller | LANG=en_US.UTF-8
2025-10-13 12:00:08.982385 | controller | SSH_CONNECTION=192.168.26.12 35022 192.168.26.37 22
2025-10-13 12:00:08.982391 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-13 12:00:08.982400 | controller | ZUUL_CHANGE_IDS=3402,eeae0ae9c1d57b4a0c12bc4381573dd0b9c62cf2
2025-10-13 12:00:08.982406 | controller | WORKSPACE=/home/zuul/workspace
2025-10-13 12:00:08.982411 | controller | XDG_SESSION_CLASS=user
2025-10-13 12:00:08.982417 | controller | SELINUX_ROLE_REQUESTED=
2025-10-13 12:00:08.982422 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-13 12:00:08.982427 | controller | USER=zuul
2025-10-13 12:00:08.982433 | controller | ZUUL_VOTING=True
2025-10-13 12:00:08.982438 | controller | BUILD_TIMEOUT=1800000
2025-10-13 12:00:08.982443 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-13 12:00:08.982449 | controller | SHLVL=1
2025-10-13 12:00:08.982454 | controller | ZUUL_PATCHSET=eeae0ae9c1d57b4a0c12bc4381573dd0b9c62cf2
2025-10-13 12:00:08.982459 | controller | XDG_SESSION_ID=1
2025-10-13 12:00:08.982465 | controller | ZUUL_BRANCH=main
2025-10-13 12:00:08.982470 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-13 12:00:08.982475 | controller | SSH_CLIENT=192.168.26.12 35022 22
2025-10-13 12:00:08.982481 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-13 12:00:08.982486 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-13 12:00:08.982493 | controller | which_declare=declare -f
2025-10-13 12:00:08.982499 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-13 12:00:08.982505 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-13 12:00:08.982510 | controller | ZUUL_CHANGE=3402
2025-10-13 12:00:08.982516 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-13 12:00:08.982521 | controller | ZUUL_UUID=913908b88f6d47c7a7376c53829031b2
2025-10-13 12:00:08.982527 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-13 12:00:08.982532 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-13 12:00:08.982538 | controller | }
2025-10-13 12:00:09.379800 | controller | ok: Runtime: 0:00:00.005716
2025-10-13 12:00:09.385295 | 
2025-10-13 12:00:09.385359 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-13 12:00:09.439357 | controller | skipping: Conditional result was False
2025-10-13 12:00:09.446027 | 
2025-10-13 12:00:09.446109 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-13 12:00:10.112073 | controller | skipping: Conditional result was False
2025-10-13 12:00:10.117894 | 
2025-10-13 12:00:10.118657 | TASK [Ensure legacy workspace directory]
2025-10-13 12:00:10.456660 | controller | changed
2025-10-13 12:00:10.585893 | 
2025-10-13 12:00:10.586012 | PLAY RECAP
2025-10-13 12:00:10.586336 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 12:00:10.586370 | 
2025-10-13 12:00:11.340986 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 12:00:11.341730 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 12:00:14.326273 | 
2025-10-13 12:00:14.326386 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-13 12:00:14.499713 | 
2025-10-13 12:00:14.499844 | TASK [Create zuul-output directory]
2025-10-13 12:00:15.551280 | controller | changed
2025-10-13 12:00:15.556649 | 
2025-10-13 12:00:15.556747 | TASK [Slurp Zuul inventory test]
2025-10-13 12:00:17.257747 | controller -> localhost | ok
2025-10-13 12:00:17.264664 | 
2025-10-13 12:00:17.264749 | TASK [Save zuul inventory]
2025-10-13 12:00:19.615542 | controller | changed
2025-10-13 12:00:19.625704 | 
2025-10-13 12:00:19.625795 | TASK [Save zuul vars without the change_message]
2025-10-13 12:00:20.891688 | controller | changed
2025-10-13 12:00:21.013031 | 
2025-10-13 12:00:21.013110 | PLAY RECAP
2025-10-13 12:00:21.013158 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 12:00:21.013182 | 
2025-10-13 12:00:21.643998 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 12:00:21.644740 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-13 12:00:25.111656 | 
2025-10-13 12:00:25.111782 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-13 12:00:25.259167 | 
2025-10-13 12:00:25.259301 | TASK [Read group_vars]
2025-10-13 12:00:25.326644 | controller | ok
2025-10-13 12:00:25.402266 | 
2025-10-13 12:00:25.402387 | TASK [cifmw_helpers : Check directory is available /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars]
2025-10-13 12:00:26.512527 | controller | ok
2025-10-13 12:00:26.537534 | 
2025-10-13 12:00:26.537629 | TASK [cifmw_helpers : List available files]
2025-10-13 12:00:27.734079 | controller | /home/zuul
2025-10-13 12:00:27.735241 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory
2025-10-13 12:00:27.735498 | controller | /bin/sh: -c: line 3: syntax error near unexpected token `||'
2025-10-13 12:00:27.735640 | controller | /bin/sh: -c: line 3: ` || true;'
2025-10-13 12:00:28.191931 | controller | ERROR
2025-10-13 12:00:28.192057 | controller | {
2025-10-13 12:00:28.192089 | controller |   "delta": "0:00:00.005388",
2025-10-13 12:00:28.192111 | controller |   "end": "2025-10-13 12:00:27.735817",
2025-10-13 12:00:28.192132 | controller |   "msg": "non-zero return code",
2025-10-13 12:00:28.192153 | controller |   "rc": 2,
2025-10-13 12:00:28.192176 | controller |   "start": "2025-10-13 12:00:27.730429"
2025-10-13 12:00:28.192196 | controller | }
failure
2025-10-13 12:00:28.208227 | 
2025-10-13 12:00:28.208289 | PLAY RECAP
2025-10-13 12:00:28.208341 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2025-10-13 12:00:28.208378 | 
2025-10-13 12:00:28.642642 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-13 12:00:28.643945 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 12:00:31.846649 | 
2025-10-13 12:00:31.846788 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-13 12:00:31.997133 | 
2025-10-13 12:00:31.997263 | TASK [Filter out host if needed]
2025-10-13 12:00:32.047065 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-13 12:00:32.076680 | 
2025-10-13 12:00:32.076788 | TASK [Ensure file is present]
2025-10-13 12:00:33.053594 | controller | ok
2025-10-13 12:00:33.065950 | 
2025-10-13 12:00:33.066036 | TASK [Manage molecule report file]
2025-10-13 12:00:33.701386 | controller | skipping: Conditional result was False
2025-10-13 12:00:33.707206 | 
2025-10-13 12:00:33.707284 | TASK [Check if we get ci-framework-data basedir]
2025-10-13 12:00:33.985755 | controller | ok
2025-10-13 12:00:33.992422 | 
2025-10-13 12:00:33.992500 | TASK [Create ci-framework-data log directory for zuul]
2025-10-13 12:00:35.014741 | controller | changed
2025-10-13 12:00:35.024591 | 
2025-10-13 12:00:35.024721 | TASK [Copy ci-framework interesting files]
2025-10-13 12:00:36.222731 | controller | cp: cannot stat '/home/zuul/ci-framework-data/logs': No such file or directory
2025-10-13 12:00:36.227324 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-13 12:00:36.694708 | controller | changed
2025-10-13 12:00:36.700311 | 
2025-10-13 12:00:36.700378 | TASK [Get SELinux listing]
2025-10-13 12:00:37.331455 | controller | changed
2025-10-13 12:00:37.337681 | 
2025-10-13 12:00:37.337777 | TASK [Generate log index]
2025-10-13 12:00:39.153288 | controller | changed
2025-10-13 12:00:39.158675 | 
2025-10-13 12:00:39.158766 | TASK [Get some env related data]
2025-10-13 12:00:40.818290 | controller | changed
2025-10-13 12:00:40.912477 | 
2025-10-13 12:00:40.912574 | TASK [Generate list of logs to collect in home directory]
2025-10-13 12:00:41.821029 | controller | ok: All paths examined
2025-10-13 12:00:41.827678 | 
2025-10-13 12:00:41.827750 | LOOP [Copy logs from home directory]
2025-10-13 12:00:41.912861 | 
2025-10-13 12:00:41.913020 | TASK [Copy crio stats log file]
2025-10-13 12:00:41.990710 | controller | skipping: Conditional result was False
2025-10-13 12:00:41.996426 | 
2025-10-13 12:00:41.996511 | TASK [Get SELinux related data]
2025-10-13 12:00:42.408019 | controller | <no matches>
2025-10-13 12:00:42.609859 | controller | ERROR
2025-10-13 12:00:42.610020 | controller | {
2025-10-13 12:00:42.610056 | controller |   "delta": "0:00:00.009440",
2025-10-13 12:00:42.610080 | controller |   "end": "2025-10-13 12:00:42.408966",
2025-10-13 12:00:42.610099 | controller |   "msg": "non-zero return code",
2025-10-13 12:00:42.610117 | controller |   "rc": 1,
2025-10-13 12:00:42.610135 | controller |   "start": "2025-10-13 12:00:42.399526"
2025-10-13 12:00:42.610153 | controller | }
2025-10-13 12:00:42.610177 | controller | ERROR: Ignoring Errors
2025-10-13 12:00:42.615565 | 
2025-10-13 12:00:42.615630 | TASK [Create system configuration directory]
2025-10-13 12:00:42.929028 | controller | changed
2025-10-13 12:00:42.942030 | 
2025-10-13 12:00:42.942113 | TASK [Get some of the system configurations]
2025-10-13 12:00:43.335528 | controller | cp: cannot stat '/etc/libvirt/*.conf': No such file or directory
2025-10-13 12:00:43.342098 | controller | cp: cannot stat '/etc/containers/registries.conf': No such file or directory
2025-10-13 12:00:43.343806 | controller | cp: cannot stat '/etc/containers/registries.conf.d': No such file or directory
2025-10-13 12:00:43.345229 | controller | chown: cannot access '/home/zuul/zuul-output/logs/registries.conf.d': No such file or directory
2025-10-13 12:00:43.543815 | controller | ERROR
2025-10-13 12:00:43.543941 | controller | {
2025-10-13 12:00:43.543979 | controller |   "delta": "0:00:00.015778",
2025-10-13 12:00:43.544002 | controller |   "end": "2025-10-13 12:00:43.345749",
2025-10-13 12:00:43.544022 | controller |   "msg": "non-zero return code",
2025-10-13 12:00:43.544043 | controller |   "rc": 1,
2025-10-13 12:00:43.544062 | controller |   "start": "2025-10-13 12:00:43.329971"
2025-10-13 12:00:43.544080 | controller | }
2025-10-13 12:00:43.544104 | controller | ERROR: Ignoring Errors
2025-10-13 12:00:43.550186 | 
2025-10-13 12:00:43.550251 | TASK [Copy generated documentation if available]
2025-10-13 12:00:43.645260 | controller | skipping: Conditional result was False
2025-10-13 12:00:43.650593 | 
2025-10-13 12:00:43.650668 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-13 12:00:43.737770 | controller | skipping: Conditional result was False
2025-10-13 12:00:43.743531 | 
2025-10-13 12:00:43.743625 | TASK [Compress logs bigger than 2MB]
2025-10-13 12:00:44.403897 | controller | changed
2025-10-13 12:00:44.410277 | 
2025-10-13 12:00:44.410363 | TASK [Copy files from workspace on node]
2025-10-13 12:00:44.470200 | controller | ok
2025-10-13 12:00:44.609631 | 
2025-10-13 12:00:44.609736 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 12:00:44.715298 | controller | skipping: Conditional result was False
2025-10-13 12:00:44.721571 | 
2025-10-13 12:00:44.721666 | TASK [fetch-output : Set log path for single node]
2025-10-13 12:00:44.900423 | controller | ok
2025-10-13 12:00:44.906869 | 
2025-10-13 12:00:44.907061 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 12:00:45.687324 | controller -> localhost | ok: "/var/lib/zuul/builds/913908b88f6d47c7a7376c53829031b2/work/logs"
2025-10-13 12:00:45.687549 | 
2025-10-13 12:00:46.406388 | controller -> localhost | changed: "/var/lib/zuul/builds/913908b88f6d47c7a7376c53829031b2/work/artifacts"
2025-10-13 12:00:47.434209 | controller -> localhost | changed: "/var/lib/zuul/builds/913908b88f6d47c7a7376c53829031b2/work/docs"
2025-10-13 12:00:47.445460 | 
2025-10-13 12:00:47.445597 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 12:00:50.173327 | controller | changed:
2025-10-13 12:00:50.173503 | controller | .d..t...... ./
2025-10-13 12:00:50.173535 | controller | >f+++++++++ README.html
2025-10-13 12:00:50.173559 | controller | >f+++++++++ dmesg.log
2025-10-13 12:00:50.173583 | controller | >f+++++++++ installed-pkgs.log
2025-10-13 12:00:50.173605 | controller | >f+++++++++ python.log
2025-10-13 12:00:50.173624 | controller | >f+++++++++ selinux-denials.log
2025-10-13 12:00:50.173644 | controller | >f+++++++++ selinux-listing.log
2025-10-13 12:00:50.173663 | controller | cd+++++++++ ci-framework-data/
2025-10-13 12:00:50.173681 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-13 12:00:50.173700 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-13 12:00:50.173730 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-13 12:00:50.173749 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-13 12:00:50.173768 | controller | cd+++++++++ system-config/
2025-10-13 12:00:50.173786 | controller | cd+++++++++ system-config/libvirt/
2025-10-13 12:00:51.138529 | controller | changed: .d..t...... ./
2025-10-13 12:00:52.490094 | controller | changed: .d..t...... ./
2025-10-13 12:00:52.515671 | 
2025-10-13 12:00:52.515774 | TASK [Return artifact to Zuul]
2025-10-13 12:00:52.596209 | controller | skipping: Conditional result was False
2025-10-13 12:00:52.657339 | 
2025-10-13 12:00:52.657413 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-13 12:00:52.657522 | 
2025-10-13 12:00:52.657550 | PLAY RECAP
2025-10-13 12:00:52.657589 | controller | ok: 15 changed: 11 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 2
2025-10-13 12:00:52.657610 | 
2025-10-13 12:00:53.024831 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 12:00:53.025581 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 12:00:55.066372 | 
2025-10-13 12:00:55.066484 | PLAY [all]
2025-10-13 12:00:55.113215 | 
2025-10-13 12:00:55.113327 | TASK [include_role : fetch-output]
2025-10-13 12:00:55.198835 | controller | ok
2025-10-13 12:00:55.252304 | 
2025-10-13 12:00:55.252432 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 12:00:55.395069 | controller | skipping: Conditional result was False
2025-10-13 12:00:55.401196 | 
2025-10-13 12:00:55.401292 | TASK [fetch-output : Set log path for single node]
2025-10-13 12:00:55.517791 | controller | ok
2025-10-13 12:00:55.523277 | 
2025-10-13 12:00:55.523369 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 12:00:56.964957 | controller -> localhost | ok: "/var/lib/zuul/builds/913908b88f6d47c7a7376c53829031b2/work/logs"
2025-10-13 12:00:58.127690 | controller -> localhost | ok: "/var/lib/zuul/builds/913908b88f6d47c7a7376c53829031b2/work/artifacts"
2025-10-13 12:00:59.108145 | controller -> localhost | ok: "/var/lib/zuul/builds/913908b88f6d47c7a7376c53829031b2/work/docs"
2025-10-13 12:00:59.123583 | 
2025-10-13 12:00:59.123676 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 12:01:00.569521 | controller | ok
2025-10-13 12:01:00.569721 | controller | ok: All items complete
2025-10-13 12:01:00.569757 | 
2025-10-13 12:01:01.509224 | controller | ok
2025-10-13 12:01:02.105622 | controller | ok
2025-10-13 12:01:02.128160 | 
2025-10-13 12:01:02.128255 | TASK [include_role : fetch-output-openshift]
2025-10-13 12:01:02.176592 | controller | skipping: Conditional result was False
2025-10-13 12:01:02.182606 | 
2025-10-13 12:01:02.182782 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-13 12:01:03.554302 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.074963
2025-10-13 12:01:04.463156 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.049963
2025-10-13 12:01:04.567217 | 
2025-10-13 12:01:04.567343 | PLAY [all]
2025-10-13 12:01:04.584175 | 
2025-10-13 12:01:04.584249 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-13 12:01:05.649730 | controller | changed
2025-10-13 12:01:05.762387 | 
2025-10-13 12:01:05.762477 | PLAY RECAP
2025-10-13 12:01:05.762523 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-13 12:01:05.762547 | 
2025-10-13 12:01:06.313581 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 12:01:06.314760 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-13 12:01:08.531400 | 
2025-10-13 12:01:08.531555 | PLAY [localhost]
2025-10-13 12:01:08.607661 | 
2025-10-13 12:01:08.607802 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-13 12:01:10.336737 | localhost | changed
2025-10-13 12:01:10.341434 | 
2025-10-13 12:01:10.341532 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-13 12:01:10.379422 | localhost | ok
2025-10-13 12:01:10.417904 | 
2025-10-13 12:01:10.418013 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-13 12:01:11.791852 | localhost | changed
2025-10-13 12:01:11.796684 | 
2025-10-13 12:01:11.796792 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-13 12:01:14.457250 | localhost | changed
2025-10-13 12:01:14.462188 | 
2025-10-13 12:01:14.462273 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-13 12:01:16.137433 | localhost | Identity added: /var/lib/zuul/builds/913908b88f6d47c7a7376c53829031b2/work/tmp/ansible.9i_u301n (/var/lib/zuul/builds/913908b88f6d47c7a7376c53829031b2/work/tmp/ansible.9i_u301n)
2025-10-13 12:01:16.137625 | localhost | ok: Runtime: 0:00:00.042272
2025-10-13 12:01:16.142456 | 
2025-10-13 12:01:16.142706 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-13 12:01:16.832406 | localhost | ok: Runtime: 0:00:00.017549
2025-10-13 12:01:16.837124 | 
2025-10-13 12:01:16.837198 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-13 12:01:17.023526 | localhost | changed
2025-10-13 12:01:17.028145 | 
2025-10-13 12:01:17.028223 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-13 12:01:18.585385 | localhost | changed
2025-10-13 12:01:18.683079 | 
2025-10-13 12:01:18.683157 | PLAY [localhost]
2025-10-13 12:01:18.726749 | 
2025-10-13 12:01:18.726821 | TASK [Generate bulk log download script]
2025-10-13 12:01:18.792522 | localhost | ok
2025-10-13 12:01:18.843970 | 
2025-10-13 12:01:18.844065 | TASK [local-log-download : Check API endpoint is defined]
2025-10-13 12:01:18.967254 | localhost | ok: All assertions passed
2025-10-13 12:01:18.971727 | 
2025-10-13 12:01:18.971800 | TASK [local-log-download : Create download script]
2025-10-13 12:01:20.599339 | localhost -> localhost | changed
2025-10-13 12:01:20.611494 | 
2025-10-13 12:01:20.611584 | TASK [Register quick-download link]
2025-10-13 12:01:20.685985 | localhost | ok
2025-10-13 12:01:20.709638 | 
2025-10-13 12:01:20.709729 | PLAY [logserver.rdoproject.org]
2025-10-13 12:01:20.742850 | 
2025-10-13 12:01:20.742958 | TASK [Set zuul-log-path fact]
2025-10-13 12:01:20.766819 | logserver.rdoproject.org | ok
2025-10-13 12:01:20.814145 | 
2025-10-13 12:01:20.814227 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 12:01:20.879846 | logserver.rdoproject.org | ok
2025-10-13 12:01:20.897899 | 
2025-10-13 12:01:20.906020 | TASK [upload-logs : Create log directories]
2025-10-13 12:01:23.585448 | logserver.rdoproject.org | changed
2025-10-13 12:01:23.588653 | 
2025-10-13 12:01:23.588749 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-13 12:01:24.439192 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.028641
2025-10-13 12:01:24.443955 | 
2025-10-13 12:01:24.444036 | TASK [upload-logs : Upload logs to log server]
2025-10-13 12:01:26.925113 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-13 12:01:26.928765 | 
2025-10-13 12:01:26.928835 | LOOP [upload-logs : Compress console log and json output]
2025-10-13 12:01:27.153052 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 12:01:27.159466 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 12:01:27.170128 | 
2025-10-13 12:01:27.170207 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-13 12:01:27.338388 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 12:01:27.338593 | 
2025-10-13 12:01:27.345424 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 12:01:27.350226 | 
2025-10-13 12:01:27.350301 | LOOP [upload-logs : Upload console log and json output]