2025-10-08 08:28:26.811780 | Job console starting...
2025-10-08 08:28:27.051313 | Updating repositories
2025-10-08 08:28:27.767175 | Preparing job workspace
2025-10-08 08:28:34.350341 | Running Ansible setup...
2025-10-08 08:28:37.237668 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 08:28:37.741338 | 
2025-10-08 08:28:37.741474 | PLAY [localhost]
2025-10-08 08:28:37.750094 | 
2025-10-08 08:28:37.750167 | TASK [Gathering Facts]
2025-10-08 08:28:38.544003 | localhost | ok
2025-10-08 08:28:38.557012 | 
2025-10-08 08:28:38.557095 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-08 08:28:38.863874 | localhost -> localhost | changed
2025-10-08 08:28:38.869278 | 
2025-10-08 08:28:38.869355 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-08 08:28:39.529976 | localhost -> localhost | changed
2025-10-08 08:28:39.537988 | 
2025-10-08 08:28:39.538057 | TASK [Setup log path fact]
2025-10-08 08:28:39.554951 | localhost | ok
2025-10-08 08:28:39.565623 | 
2025-10-08 08:28:39.565688 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 08:28:39.582211 | localhost | ok
2025-10-08 08:28:39.589311 | 
2025-10-08 08:28:39.589375 | TASK [emit-job-header : Print job information]
2025-10-08 08:28:39.616055 | # Job Information
2025-10-08 08:28:39.616167 | Ansible Version: 2.15.12
2025-10-08 08:28:39.616196 | Job: cifmw-molecule-dlrn_report
2025-10-08 08:28:39.616218 | Pipeline: github-check
2025-10-08 08:28:39.616237 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-08 08:28:39.616255 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3237
2025-10-08 08:28:39.616273 | Log URL (when completed): https://logserver.rdoproject.org/e74/rdoproject.org/e742255b6d6246c88655423f2a77626c/
2025-10-08 08:28:39.616291 | Event ID: 920c7930-a408-11f0-96e0-244f77bacc6d
2025-10-08 08:28:39.619836 | 
2025-10-08 08:28:39.619903 | LOOP [emit-job-header : Print node information]
2025-10-08 08:28:39.702904 | localhost | ok:
2025-10-08 08:28:39.703035 | localhost | # Node Information
2025-10-08 08:28:39.703063 | localhost | Inventory Hostname: controller
2025-10-08 08:28:39.703086 | localhost | Hostname: np0005475422
2025-10-08 08:28:39.703106 | localhost | Username: zuul
2025-10-08 08:28:39.703127 | localhost | Distro: CentOS 9
2025-10-08 08:28:39.703144 | localhost | Provider: ibm-bm4-nodepool
2025-10-08 08:28:39.703161 | localhost | Region: regionOne
2025-10-08 08:28:39.703176 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-08 08:28:39.703191 | localhost | Product Name: OpenStack Compute
2025-10-08 08:28:39.703207 | localhost | Interface IP: 192.168.26.128
2025-10-08 08:28:39.721406 | 
2025-10-08 08:28:39.721460 | PLAY [all]
2025-10-08 08:28:39.727405 | 
2025-10-08 08:28:39.727470 | TASK [Gather network facts]
2025-10-08 08:28:40.091198 | controller | ok
2025-10-08 08:28:40.105180 | 
2025-10-08 08:28:40.105239 | TASK [include_role : start-zuul-console]
2025-10-08 08:28:40.123040 | controller | ok
2025-10-08 08:28:40.135089 | 
2025-10-08 08:28:40.135153 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-08 08:28:40.436874 | controller | ok
2025-10-08 08:28:40.445016 | 
2025-10-08 08:28:40.445077 | TASK [include_role : add-build-sshkey]
2025-10-08 08:28:40.462296 | controller | ok
2025-10-08 08:28:40.474230 | 
2025-10-08 08:28:40.474295 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-08 08:28:40.670305 | controller -> localhost | ok
2025-10-08 08:28:40.686723 | 
2025-10-08 08:28:40.686787 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-08 08:28:40.704342 | controller | ok
2025-10-08 08:28:40.718195 | controller | included: /var/lib/zuul/builds/e742255b6d6246c88655423f2a77626c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-08 08:28:40.723624 | 
2025-10-08 08:28:40.723685 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-08 08:28:41.101008 | controller -> localhost | Generating public/private rsa key pair.
2025-10-08 08:28:41.101157 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e742255b6d6246c88655423f2a77626c/work/e742255b6d6246c88655423f2a77626c_id_rsa.
2025-10-08 08:28:41.101191 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e742255b6d6246c88655423f2a77626c/work/e742255b6d6246c88655423f2a77626c_id_rsa.pub.
2025-10-08 08:28:41.101216 | controller -> localhost | The key fingerprint is:
2025-10-08 08:28:41.101238 | controller -> localhost | SHA256:DIPfrPE3zX+ZBXr6YLnMVkzC5zQ+IQ6es7FS7HttC2k zuul-build-sshkey
2025-10-08 08:28:41.101259 | controller -> localhost | The key's randomart image is:
2025-10-08 08:28:41.101279 | controller -> localhost | +---[RSA 3072]----+
2025-10-08 08:28:41.101298 | controller -> localhost | |                 |
2025-10-08 08:28:41.101317 | controller -> localhost | |     .           |
2025-10-08 08:28:41.101336 | controller -> localhost | |    . o    .     |
2025-10-08 08:28:41.101354 | controller -> localhost | |     . *  . + B  |
2025-10-08 08:28:41.101373 | controller -> localhost | |      o So + @ + |
2025-10-08 08:28:41.101391 | controller -> localhost | |       +  BoooB .|
2025-10-08 08:28:41.101408 | controller -> localhost | |      . .oo=E= .+|
2025-10-08 08:28:41.101426 | controller -> localhost | |        ..+*+=oo.|
2025-10-08 08:28:41.101443 | controller -> localhost | |         ..++o+o |
2025-10-08 08:28:41.101461 | controller -> localhost | +----[SHA256]-----+
2025-10-08 08:28:41.101503 | controller -> localhost | ok: Runtime: 0:00:00.060542
2025-10-08 08:28:41.107158 | 
2025-10-08 08:28:41.107222 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-08 08:28:41.125066 | controller | ok
2025-10-08 08:28:41.133968 | controller | included: /var/lib/zuul/builds/e742255b6d6246c88655423f2a77626c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-08 08:28:41.141427 | 
2025-10-08 08:28:41.141488 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-08 08:28:41.154844 | controller | skipping: Conditional result was False
2025-10-08 08:28:41.159790 | 
2025-10-08 08:28:41.159854 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-08 08:28:41.533415 | controller | changed
2025-10-08 08:28:41.539223 | 
2025-10-08 08:28:41.539288 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-08 08:28:41.730112 | controller | ok
2025-10-08 08:28:41.734722 | 
2025-10-08 08:28:41.734785 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-08 08:28:42.300945 | controller | changed
2025-10-08 08:28:42.305554 | 
2025-10-08 08:28:42.305617 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-08 08:28:42.902183 | controller | changed
2025-10-08 08:28:42.906762 | 
2025-10-08 08:28:42.906826 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-08 08:28:42.920199 | controller | skipping: Conditional result was False
2025-10-08 08:28:42.925276 | 
2025-10-08 08:28:42.925339 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-08 08:28:43.213677 | controller -> localhost | changed
2025-10-08 08:28:43.222752 | 
2025-10-08 08:28:43.222815 | TASK [add-build-sshkey : Add back temp key]
2025-10-08 08:28:43.439163 | controller -> localhost | Identity added: /var/lib/zuul/builds/e742255b6d6246c88655423f2a77626c/work/e742255b6d6246c88655423f2a77626c_id_rsa (zuul-build-sshkey)
2025-10-08 08:28:43.439399 | controller -> localhost | ok: Runtime: 0:00:00.007015
2025-10-08 08:28:43.444540 | 
2025-10-08 08:28:43.444602 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-08 08:28:43.725179 | controller | ok
2025-10-08 08:28:43.729435 | 
2025-10-08 08:28:43.729497 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-08 08:28:43.752967 | controller | skipping: Conditional result was False
2025-10-08 08:28:43.761422 | 
2025-10-08 08:28:43.761487 | TASK [include_role : validate-host]
2025-10-08 08:28:43.779599 | controller | ok
2025-10-08 08:28:43.799405 | 
2025-10-08 08:28:43.799477 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-08 08:28:43.827941 | controller | ok
2025-10-08 08:28:43.832372 | 
2025-10-08 08:28:43.832439 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-08 08:28:44.038181 | controller -> localhost | ok
2025-10-08 08:28:44.044199 | 
2025-10-08 08:28:44.044293 | TASK [validate-host : Collect information about the host]
2025-10-08 08:28:44.692523 | controller | ok
2025-10-08 08:28:44.700394 | 
2025-10-08 08:28:44.700472 | TASK [validate-host : Sanitize hostname]
2025-10-08 08:28:44.768897 | controller | ok
2025-10-08 08:28:44.774601 | 
2025-10-08 08:28:44.774668 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-08 08:28:45.152112 | controller -> localhost | changed
2025-10-08 08:28:45.157057 | 
2025-10-08 08:28:45.157120 | TASK [validate-host : Collect information about zuul worker]
2025-10-08 08:28:45.469388 | controller | ok
2025-10-08 08:28:45.473729 | 
2025-10-08 08:28:45.473794 | TASK [validate-host : Write out all zuul information for each host]
2025-10-08 08:28:45.821211 | controller -> localhost | changed
2025-10-08 08:28:45.830101 | 
2025-10-08 08:28:45.830164 | TASK [include_role : prepare-workspace-openshift]
2025-10-08 08:28:45.843652 | controller | skipping: Conditional result was False
2025-10-08 08:28:45.848576 | 
2025-10-08 08:28:45.848648 | TASK [include_role : remove-zuul-sshkey]
2025-10-08 08:28:45.861795 | controller | skipping: Conditional result was False
2025-10-08 08:28:45.866590 | 
2025-10-08 08:28:45.866656 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-08 08:28:46.060439 | controller | ok: "logs"
2025-10-08 08:28:46.060668 | controller | ok: All items complete
2025-10-08 08:28:46.060701 | 
2025-10-08 08:28:46.220256 | controller | ok: "artifacts"
2025-10-08 08:28:46.393945 | controller | ok: "docs"
2025-10-08 08:28:46.401821 | 
2025-10-08 08:28:46.401927 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-08 08:28:46.593966 | controller | changed: "logs"
2025-10-08 08:28:46.764414 | controller | changed: "artifacts"
2025-10-08 08:28:46.933206 | controller | changed: "docs"
2025-10-08 08:28:46.954153 | 
2025-10-08 08:28:46.954242 | PLAY RECAP
2025-10-08 08:28:46.954285 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 08:28:46.954313 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 08:28:46.954332 | 
2025-10-08 08:28:47.023868 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 08:28:47.024621 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 08:28:47.534924 | 
2025-10-08 08:28:47.535020 | PLAY [all]
2025-10-08 08:28:47.552632 | 
2025-10-08 08:28:47.552700 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 08:28:47.591701 | controller | ok
2025-10-08 08:28:47.596426 | 
2025-10-08 08:28:47.596505 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 08:28:47.929339 | controller | changed
2025-10-08 08:28:47.934529 | 
2025-10-08 08:28:47.934666 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 08:28:48.763303 | controller | changed
2025-10-08 08:28:48.772939 | 
2025-10-08 08:28:48.773033 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-08 08:28:49.127478 | controller | changed:
2025-10-08 08:28:49.127646 | controller | {
2025-10-08 08:28:49.127676 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-08 08:28:49.127717 | controller | }
2025-10-08 08:28:49.335250 | controller | changed:
2025-10-08 08:28:49.335335 | controller | {
2025-10-08 08:28:49.335364 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-08 08:28:49.335385 | controller | }
2025-10-08 08:28:49.547724 | controller | changed:
2025-10-08 08:28:49.547846 | controller | {
2025-10-08 08:28:49.547885 | 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-08 08:28:49.547906 | controller | }
2025-10-08 08:28:49.768179 | controller | changed:
2025-10-08 08:28:49.768248 | controller | {
2025-10-08 08:28:49.768283 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-08 08:28:49.768305 | controller | }
2025-10-08 08:28:49.971266 | controller | changed:
2025-10-08 08:28:49.971338 | controller | {
2025-10-08 08:28:49.971572 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-08 08:28:49.971599 | controller | }
2025-10-08 08:28:50.171084 | controller | changed:
2025-10-08 08:28:50.171155 | controller | {
2025-10-08 08:28:50.171183 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-08 08:28:50.171202 | controller | }
2025-10-08 08:28:50.363731 | controller | changed:
2025-10-08 08:28:50.363794 | controller | {
2025-10-08 08:28:50.363821 | 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-08 08:28:50.363842 | controller | }
2025-10-08 08:28:50.585171 | controller | changed:
2025-10-08 08:28:50.585261 | controller | {
2025-10-08 08:28:50.585289 | 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-08 08:28:50.585311 | controller | }
2025-10-08 08:28:50.789378 | controller | changed:
2025-10-08 08:28:50.789446 | controller | {
2025-10-08 08:28:50.789474 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-08 08:28:50.789495 | controller | }
2025-10-08 08:28:50.983590 | controller | changed:
2025-10-08 08:28:50.983648 | controller | {
2025-10-08 08:28:50.983675 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-08 08:28:50.983693 | controller | }
2025-10-08 08:28:51.192726 | controller | changed:
2025-10-08 08:28:51.192814 | controller | {
2025-10-08 08:28:51.192840 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-08 08:28:51.192860 | controller | }
2025-10-08 08:28:51.423781 | controller | changed:
2025-10-08 08:28:51.423859 | controller | {
2025-10-08 08:28:51.423887 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-08 08:28:51.423925 | controller | }
2025-10-08 08:28:51.611125 | controller | changed:
2025-10-08 08:28:51.611184 | controller | {
2025-10-08 08:28:51.611211 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-08 08:28:51.611233 | controller | }
2025-10-08 08:28:51.817831 | controller | changed:
2025-10-08 08:28:51.817896 | controller | {
2025-10-08 08:28:51.818030 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-08 08:28:51.818065 | controller | }
2025-10-08 08:28:52.038295 | controller | changed:
2025-10-08 08:28:52.038383 | controller | {
2025-10-08 08:28:52.038409 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-08 08:28:52.038429 | controller | }
2025-10-08 08:28:52.245632 | controller | changed:
2025-10-08 08:28:52.245725 | controller | {
2025-10-08 08:28:52.245753 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-08 08:28:52.245772 | controller | }
2025-10-08 08:28:52.453202 | controller | changed:
2025-10-08 08:28:52.453269 | controller | {
2025-10-08 08:28:52.453296 | 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-08 08:28:52.453317 | controller | }
2025-10-08 08:28:52.650263 | controller | changed:
2025-10-08 08:28:52.650321 | controller | {
2025-10-08 08:28:52.650348 | 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-08 08:28:52.650376 | controller | }
2025-10-08 08:28:52.866854 | controller | changed:
2025-10-08 08:28:52.866946 | controller | {
2025-10-08 08:28:52.866976 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-08 08:28:52.866996 | controller | }
2025-10-08 08:28:53.084775 | controller | changed:
2025-10-08 08:28:53.084834 | controller | {
2025-10-08 08:28:53.084860 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-08 08:28:53.084880 | controller | }
2025-10-08 08:28:53.296744 | controller | changed:
2025-10-08 08:28:53.296828 | controller | {
2025-10-08 08:28:53.296855 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-08 08:28:53.296875 | controller | }
2025-10-08 08:28:53.501208 | controller | changed:
2025-10-08 08:28:53.501295 | controller | {
2025-10-08 08:28:53.501321 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-08 08:28:53.501340 | controller | }
2025-10-08 08:28:53.715612 | controller | changed:
2025-10-08 08:28:53.715754 | controller | {
2025-10-08 08:28:53.715789 | 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-08 08:28:53.715814 | controller | }
2025-10-08 08:28:53.929025 | controller | changed:
2025-10-08 08:28:53.929150 | controller | {
2025-10-08 08:28:53.929184 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-08 08:28:53.929206 | controller | }
2025-10-08 08:28:54.143312 | controller | changed:
2025-10-08 08:28:54.143407 | controller | {
2025-10-08 08:28:54.143434 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-08 08:28:54.143453 | controller | }
2025-10-08 08:28:54.364218 | controller | changed:
2025-10-08 08:28:54.364296 | controller | {
2025-10-08 08:28:54.364323 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-08 08:28:54.364343 | controller | }
2025-10-08 08:28:54.379857 | 
2025-10-08 08:28:54.379984 | TASK [Set timezone to UTC]
2025-10-08 08:28:54.783809 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-08 08:28:54.789075 | 
2025-10-08 08:28:54.789140 | TASK [Create nodepool directory]
2025-10-08 08:28:55.000307 | controller | changed
2025-10-08 08:28:55.005414 | 
2025-10-08 08:28:55.005481 | TASK [Create nodepool sub_nodes file]
2025-10-08 08:28:55.541616 | controller | changed
2025-10-08 08:28:55.546252 | 
2025-10-08 08:28:55.546319 | TASK [Create nodepool sub_nodes_private file]
2025-10-08 08:28:56.080901 | controller | changed
2025-10-08 08:28:56.085580 | 
2025-10-08 08:28:56.085643 | LOOP [Populate nodepool sub_nodes file]
2025-10-08 08:28:56.114101 | 
2025-10-08 08:28:56.114219 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-08 08:28:56.142605 | 
2025-10-08 08:28:56.142734 | TASK [Create nodepool primary file]
2025-10-08 08:28:56.165832 | controller | skipping: Conditional result was False
2025-10-08 08:28:56.170844 | 
2025-10-08 08:28:56.170945 | TASK [Create nodepool node_private for this node]
2025-10-08 08:28:56.766016 | controller | changed
2025-10-08 08:28:56.772408 | 
2025-10-08 08:28:56.772477 | LOOP [Copy ssh keys to nodepool directory]
2025-10-08 08:28:57.097226 | controller | ok: Item: id_rsa Runtime: 0:00:00.006290
2025-10-08 08:28:57.097401 | 
2025-10-08 08:28:57.269022 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005550
2025-10-08 08:28:57.277174 | 
2025-10-08 08:28:57.277288 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-08 08:28:57.862616 | controller | changed
2025-10-08 08:28:57.867947 | 
2025-10-08 08:28:57.868013 | TASK [Validate sudoers config after edits]
2025-10-08 08:28:58.087620 | controller | /etc/sudoers: parsed OK
2025-10-08 08:28:58.087713 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-08 08:28:58.087723 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-08 08:28:58.087729 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-08 08:28:58.392961 | controller | ok: Runtime: 0:00:00.006500
2025-10-08 08:28:58.398368 | 
2025-10-08 08:28:58.398434 | TASK [Show the environment passed in to job shell scripts]
2025-10-08 08:28:58.598013 | controller | SHELL=/bin/bash
2025-10-08 08:28:58.598058 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-08 08:28:58.598068 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-08 08:28:58.598074 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/37/3237/f73256b19c90fb94359fc527fcfcb675474058ed
2025-10-08 08:28:58.598080 | controller | PWD=/home/zuul
2025-10-08 08:28:58.598085 | controller | ZUUL_PIPELINE=github-check
2025-10-08 08:28:58.598091 | controller | LOGNAME=zuul
2025-10-08 08:28:58.598149 | controller | XDG_SESSION_TYPE=tty
2025-10-08 08:28:58.598159 | controller | _=/usr/bin/env
2025-10-08 08:28:58.598165 | controller | MOTD_SHOWN=pam
2025-10-08 08:28:58.598170 | controller | HOME=/home/zuul
2025-10-08 08:28:58.598175 | controller | LANG=en_US.UTF-8
2025-10-08 08:28:58.598181 | controller | SSH_CONNECTION=192.168.26.12 43216 192.168.26.128 22
2025-10-08 08:28:58.598186 | 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-08 08:28:58.598195 | controller | ZUUL_CHANGE_IDS=3237,f73256b19c90fb94359fc527fcfcb675474058ed
2025-10-08 08:28:58.598201 | controller | WORKSPACE=/home/zuul/workspace
2025-10-08 08:28:58.598206 | controller | XDG_SESSION_CLASS=user
2025-10-08 08:28:58.598212 | controller | SELINUX_ROLE_REQUESTED=
2025-10-08 08:28:58.598217 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-08 08:28:58.598222 | controller | USER=zuul
2025-10-08 08:28:58.598228 | controller | ZUUL_VOTING=True
2025-10-08 08:28:58.598233 | controller | BUILD_TIMEOUT=1800000
2025-10-08 08:28:58.598238 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-08 08:28:58.598243 | controller | SHLVL=1
2025-10-08 08:28:58.598249 | controller | ZUUL_PATCHSET=f73256b19c90fb94359fc527fcfcb675474058ed
2025-10-08 08:28:58.598254 | controller | XDG_SESSION_ID=1
2025-10-08 08:28:58.598259 | controller | ZUUL_BRANCH=main
2025-10-08 08:28:58.598264 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-08 08:28:58.598269 | controller | SSH_CLIENT=192.168.26.12 43216 22
2025-10-08 08:28:58.598275 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-08 08:28:58.598280 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-08 08:28:58.598285 | controller | which_declare=declare -f
2025-10-08 08:28:58.598294 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-08 08:28:58.598299 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-08 08:28:58.598305 | controller | ZUUL_CHANGE=3237
2025-10-08 08:28:58.598310 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-08 08:28:58.598315 | controller | ZUUL_UUID=e742255b6d6246c88655423f2a77626c
2025-10-08 08:28:58.598321 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-08 08:28:58.598326 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-08 08:28:58.598332 | controller | }
2025-10-08 08:28:58.924546 | controller | ok: Runtime: 0:00:00.006217
2025-10-08 08:28:58.930055 | 
2025-10-08 08:28:58.930121 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-08 08:28:58.943475 | controller | skipping: Conditional result was False
2025-10-08 08:28:58.948743 | 
2025-10-08 08:28:58.948808 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-08 08:28:59.473171 | controller | skipping: Conditional result was False
2025-10-08 08:28:59.479188 | 
2025-10-08 08:28:59.479258 | TASK [Ensure legacy workspace directory]
2025-10-08 08:28:59.670113 | controller | changed
2025-10-08 08:28:59.687385 | 
2025-10-08 08:28:59.687438 | PLAY RECAP
2025-10-08 08:28:59.687482 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 08:28:59.687504 | 
2025-10-08 08:28:59.758025 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 08:28:59.758834 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 08:29:00.252397 | 
2025-10-08 08:29:00.252491 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-08 08:29:00.273380 | 
2025-10-08 08:29:00.273453 | TASK [Create zuul-output directory]
2025-10-08 08:29:00.598370 | controller | changed
2025-10-08 08:29:00.603223 | 
2025-10-08 08:29:00.603302 | TASK [Slurp Zuul inventory test]
2025-10-08 08:29:00.859849 | controller -> localhost | ok
2025-10-08 08:29:00.865979 | 
2025-10-08 08:29:00.866043 | TASK [Save zuul inventory]
2025-10-08 08:29:01.603600 | controller | changed
2025-10-08 08:29:01.608594 | 
2025-10-08 08:29:01.608664 | TASK [Save zuul vars without the change_message]
2025-10-08 08:29:02.215364 | controller | changed
2025-10-08 08:29:02.232376 | 
2025-10-08 08:29:02.232433 | PLAY RECAP
2025-10-08 08:29:02.232476 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 08:29:02.232499 | 
2025-10-08 08:29:02.293445 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 08:29:02.294175 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 08:29:02.829022 | 
2025-10-08 08:29:02.829118 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-08 08:29:02.849016 | 
2025-10-08 08:29:02.849093 | TASK [Read group_vars]
2025-10-08 08:29:02.867390 | controller | ok
2025-10-08 08:29:02.884033 | 
2025-10-08 08:29:02.884116 | TASK [cifmw_helpers : Check directory is available]
2025-10-08 08:29:03.175086 | controller | ok
2025-10-08 08:29:03.180290 | 
2025-10-08 08:29:03.180366 | TASK [cifmw_helpers : List dir in ansible user home]
2025-10-08 08:29:03.474581 | controller | var one
2025-10-08 08:29:03.475410 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars'$'\n': No such file or directory
2025-10-08 08:29:03.480969 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory
2025-10-08 08:29:03.482951 | controller | ls: cannot access '/root/src/github.com/openstack-k8s-operators/ci-framework/group_vars': Permission denied
2025-10-08 08:29:03.484600 | controller | ls: cannot access '/workspace/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory
2025-10-08 08:29:03.706590 | controller | ERROR
2025-10-08 08:29:03.706750 | controller | {
2025-10-08 08:29:03.706783 | controller |   "delta": "0:00:00.015588",
2025-10-08 08:29:03.706805 | controller |   "end": "2025-10-08 08:29:03.485026",
2025-10-08 08:29:03.706825 | controller |   "msg": "non-zero return code",
2025-10-08 08:29:03.706849 | controller |   "rc": 2,
2025-10-08 08:29:03.706870 | controller |   "start": "2025-10-08 08:29:03.469438"
2025-10-08 08:29:03.706886 | controller | }
2025-10-08 08:29:03.706931 | controller | ERROR: Ignoring Errors
2025-10-08 08:29:03.712249 | 
2025-10-08 08:29:03.712315 | TASK [cifmw_helpers : List available files]
2025-10-08 08:29:04.238268 | controller | skipping: Conditional result was False
2025-10-08 08:29:04.243864 | 
2025-10-08 08:29:04.243950 | LOOP [cifmw_helpers : Read vars]
2025-10-08 08:29:04.262369 | 
2025-10-08 08:29:04.262494 | LOOP [cifmw_helpers : Call task to parse all files as fact]
2025-10-08 08:29:04.284760 | 
2025-10-08 08:29:04.284871 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-08 08:29:04.301981 | controller | ok
2025-10-08 08:29:04.338107 | 
2025-10-08 08:29:04.338193 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 08:29:04.361520 | controller | skipping: Conditional result was False
2025-10-08 08:29:04.367046 | 
2025-10-08 08:29:04.367116 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 08:29:04.647593 | controller | ok
2025-10-08 08:29:04.653013 | 
2025-10-08 08:29:04.653080 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 08:29:05.177938 | controller | ok
2025-10-08 08:29:05.187894 | 
2025-10-08 08:29:05.187974 | TASK [Prepare workspace]
2025-10-08 08:29:05.205310 | controller | ok
2025-10-08 08:29:05.224098 | 
2025-10-08 08:29:05.224164 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-08 08:29:05.505282 | controller | ok
2025-10-08 08:29:05.511975 | 
2025-10-08 08:29:05.512042 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-08 08:29:07.415000 | controller | Output suppressed because no_log was given
2025-10-08 08:29:07.423784 | 
2025-10-08 08:29:07.423849 | LOOP [Create zuul-output directory]
2025-10-08 08:29:07.620310 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-08 08:29:07.620518 | 
2025-10-08 08:29:07.794289 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-08 08:29:07.800904 | 
2025-10-08 08:29:07.801006 | TASK [Install required packages]
2025-10-08 08:30:03.845262 | controller | changed
2025-10-08 08:30:03.850211 | 
2025-10-08 08:30:03.850270 | TASK [Install venv]
2025-10-08 08:31:04.647935 | controller | changed
2025-10-08 08:31:04.671661 | 
2025-10-08 08:31:04.671749 | PLAY RECAP
2025-10-08 08:31:04.671794 | controller | ok: 9 changed: 5 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-08 08:31:04.671819 | 
2025-10-08 08:31:04.741706 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 08:31:04.742585 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 08:31:05.262464 | 
2025-10-08 08:31:05.262615 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-08 08:31:05.282955 | 
2025-10-08 08:31:05.283032 | TASK [Gather required facts]
2025-10-08 08:31:05.757668 | controller | ok
2025-10-08 08:31:05.762842 | 
2025-10-08 08:31:05.762937 | TASK [Load environment var if instructed to]
2025-10-08 08:31:05.786436 | controller | skipping: Conditional result was False
2025-10-08 08:31:05.791730 | 
2025-10-08 08:31:05.791798 | TASK [Run molecule]
2025-10-08 08:31:06.695019 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-08 08:31:06.763954 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-08 08:31:23.559104 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 08:31:23.559564 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 08:31:23.560009 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 08:31:23.560470 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 08:31:23.560933 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+f73256b1 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 1.0.0+f73256b1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 08:31:23.561386 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 08:31:23.561843 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 08:31:23.562293 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 08:31:23.562738 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 08:31:23.563186 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 08:31:23.563630 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 08:31:23.564068 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 08:31:23.564536 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 08:31:23.564964 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 08:31:23.565433 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 08:31:23.575007 | controller | INFO     Running default > prepare
2025-10-08 08:31:24.213717 | controller |
2025-10-08 08:31:24.213805 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 08:31:24.213898 | controller |
2025-10-08 08:31:24.213996 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 08:31:24.214099 | controller | Wednesday 08 October 2025  08:31:24 +0000 (0:00:00.012)       0:00:00.012 *****
2025-10-08 08:31:25.007577 | controller | ok: [instance]
2025-10-08 08:31:25.007682 | controller |
2025-10-08 08:31:25.007816 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-08 08:31:25.007952 | controller | Wednesday 08 October 2025  08:31:25 +0000 (0:00:00.794)       0:00:00.806 *****
2025-10-08 08:31:25.023076 | controller | skipping: [instance]
2025-10-08 08:31:25.023178 | controller |
2025-10-08 08:31:25.023292 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-08 08:31:25.023421 | controller | Wednesday 08 October 2025  08:31:25 +0000 (0:00:00.015)       0:00:00.822 *****
2025-10-08 08:31:25.060649 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-08 08:31:25.060700 | controller |
2025-10-08 08:31:25.060867 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-08 08:31:25.060932 | controller | Wednesday 08 October 2025  08:31:25 +0000 (0:00:00.037)       0:00:00.859 *****
2025-10-08 08:31:25.325872 | controller | ok: [instance]
2025-10-08 08:31:25.325959 | controller |
2025-10-08 08:31:25.326077 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-08 08:31:25.326202 | controller | Wednesday 08 October 2025  08:31:25 +0000 (0:00:00.265)       0:00:01.124 *****
2025-10-08 08:31:25.620567 | controller | ok: [instance]
2025-10-08 08:31:25.620630 | controller |
2025-10-08 08:31:25.620746 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-08 08:31:25.620849 | controller | Wednesday 08 October 2025  08:31:25 +0000 (0:00:00.294)       0:00:01.419 *****
2025-10-08 08:31:26.266252 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-08 08:31:26.266369 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-08 08:31:26.266491 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-08 08:31:26.266589 | controller |
2025-10-08 08:31:26.266686 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-08 08:31:26.266777 | controller | Wednesday 08 October 2025  08:31:26 +0000 (0:00:00.645)       0:00:02.065 *****
2025-10-08 08:31:26.322877 | controller |
2025-10-08 08:31:26.323003 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-08 08:31:26.323130 | controller | Wednesday 08 October 2025  08:31:26 +0000 (0:00:00.056)       0:00:02.122 *****
2025-10-08 08:31:26.834759 | controller | changed: [instance] => (item=tmp)
2025-10-08 08:31:26.834845 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-08 08:31:26.834945 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-08 08:31:26.835037 | controller |
2025-10-08 08:31:26.835136 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-08 08:31:26.835256 | controller | Wednesday 08 October 2025  08:31:26 +0000 (0:00:00.511)       0:00:02.633 *****
2025-10-08 08:31:27.606271 | controller | ok: [instance]
2025-10-08 08:31:27.606318 | controller |
2025-10-08 08:31:27.606453 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-08 08:31:27.606550 | controller | Wednesday 08 October 2025  08:31:27 +0000 (0:00:00.771)       0:00:03.405 *****
2025-10-08 08:31:28.749415 | controller | changed: [instance]
2025-10-08 08:31:36.529086 | controller |
2025-10-08 08:31:36.529121 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-08 08:31:36.529131 | controller | Wednesday 08 October 2025  08:31:28 +0000 (0:00:01.142)       0:00:04.548 *****
2025-10-08 08:31:36.529142 | controller | changed: [instance]
2025-10-08 08:31:37.216209 | controller |
2025-10-08 08:31:37.216245 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-08 08:31:37.216254 | controller | Wednesday 08 October 2025  08:31:36 +0000 (0:00:07.779)       0:00:12.327 *****
2025-10-08 08:31:37.216264 | controller | changed: [instance]
2025-10-08 08:31:37.237303 | controller |
2025-10-08 08:31:37.237355 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-08 08:31:37.237368 | controller | Wednesday 08 October 2025  08:31:37 +0000 (0:00:00.687)       0:00:13.014 *****
2025-10-08 08:31:37.237378 | controller | skipping: [instance]
2025-10-08 08:31:38.068961 | controller |
2025-10-08 08:31:38.068989 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-08 08:31:38.068997 | controller | Wednesday 08 October 2025  08:31:37 +0000 (0:00:00.021)       0:00:13.036 *****
2025-10-08 08:31:38.069008 | controller | changed: [instance]
2025-10-08 08:31:38.104787 | controller |
2025-10-08 08:31:38.104805 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-08 08:31:38.104812 | controller | Wednesday 08 October 2025  08:31:38 +0000 (0:00:00.831)       0:00:13.867 *****
2025-10-08 08:31:38.104821 | controller | skipping: [instance]
2025-10-08 08:31:38.140418 | controller |
2025-10-08 08:31:38.140439 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-08 08:31:38.140447 | controller | Wednesday 08 October 2025  08:31:38 +0000 (0:00:00.035)       0:00:13.903 *****
2025-10-08 08:31:38.140456 | controller | skipping: [instance]
2025-10-08 08:31:38.175073 | controller |
2025-10-08 08:31:38.175095 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-08 08:31:38.175104 | controller | Wednesday 08 October 2025  08:31:38 +0000 (0:00:00.035)       0:00:13.938 *****
2025-10-08 08:31:38.175115 | controller | skipping: [instance]
2025-10-08 08:31:38.719891 | controller |
2025-10-08 08:31:38.719920 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-08 08:31:38.719929 | controller | Wednesday 08 October 2025  08:31:38 +0000 (0:00:00.035)       0:00:13.973 *****
2025-10-08 08:31:38.719939 | controller | changed: [instance]
2025-10-08 08:31:39.185654 | controller |
2025-10-08 08:31:39.185685 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-08 08:31:39.185694 | controller | Wednesday 08 October 2025  08:31:38 +0000 (0:00:00.544)       0:00:14.518 *****
2025-10-08 08:31:39.185705 | controller | changed: [instance]
2025-10-08 08:31:39.214287 | controller |
2025-10-08 08:31:39.214328 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-08 08:31:39.214358 | controller | Wednesday 08 October 2025  08:31:39 +0000 (0:00:00.465)       0:00:14.984 *****
2025-10-08 08:31:39.214375 | controller | skipping: [instance]
2025-10-08 08:31:39.241457 | controller |
2025-10-08 08:31:39.241492 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-08 08:31:39.241502 | controller | Wednesday 08 October 2025  08:31:39 +0000 (0:00:00.028)       0:00:15.013 *****
2025-10-08 08:31:39.241518 | controller | skipping: [instance]
2025-10-08 08:31:39.241549 | controller |
2025-10-08 08:31:39.241576 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-08 08:31:39.241700 | controller | Wednesday 08 October 2025  08:31:39 +0000 (0:00:00.027)       0:00:15.040 *****
2025-10-08 08:31:39.270146 | controller | skipping: [instance]
2025-10-08 08:31:39.308039 | controller |
2025-10-08 08:31:39.308057 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-08 08:31:39.308065 | controller | Wednesday 08 October 2025  08:31:39 +0000 (0:00:00.028)       0:00:15.068 *****
2025-10-08 08:31:39.308074 | controller | ok: [instance]
2025-10-08 08:31:39.334454 | controller |
2025-10-08 08:31:39.334489 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-08 08:31:39.334499 | controller | Wednesday 08 October 2025  08:31:39 +0000 (0:00:00.038)       0:00:15.107 *****
2025-10-08 08:31:39.334512 | controller | skipping: [instance]
2025-10-08 08:31:39.334534 | controller |
2025-10-08 08:31:39.334563 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-08 08:31:39.334698 | controller | Wednesday 08 October 2025  08:31:39 +0000 (0:00:00.026)       0:00:15.133 *****
2025-10-08 08:31:39.361478 | controller | skipping: [instance]
2025-10-08 08:31:39.390834 | controller |
2025-10-08 08:31:39.390851 | controller | TASK [Download the RPM] ********************************************************
2025-10-08 08:31:39.390858 | controller | Wednesday 08 October 2025  08:31:39 +0000 (0:00:00.026)       0:00:15.160 *****
2025-10-08 08:31:39.390867 | controller | skipping: [instance]
2025-10-08 08:31:39.417415 | controller |
2025-10-08 08:31:39.417439 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-08 08:31:39.417447 | controller | Wednesday 08 October 2025  08:31:39 +0000 (0:00:00.029)       0:00:15.189 *****
2025-10-08 08:31:39.417457 | controller | skipping: [instance]
2025-10-08 08:31:39.417560 | controller |
2025-10-08 08:31:39.417579 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-08 08:31:39.417591 | controller | Wednesday 08 October 2025  08:31:39 +0000 (0:00:00.026)       0:00:15.216 *****
2025-10-08 08:31:39.443415 | controller | skipping: [instance]
2025-10-08 08:31:39.472482 | controller |
2025-10-08 08:31:39.472517 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-08 08:31:39.472530 | controller | Wednesday 08 October 2025  08:31:39 +0000 (0:00:00.025)       0:00:15.242 *****
2025-10-08 08:31:39.472547 | controller | skipping: [instance]
2025-10-08 08:31:39.497858 | controller |
2025-10-08 08:31:39.497880 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-08 08:31:39.497889 | controller | Wednesday 08 October 2025  08:31:39 +0000 (0:00:00.029)       0:00:15.271 *****
2025-10-08 08:31:39.497898 | controller | skipping: [instance]
2025-10-08 08:31:39.682798 | controller |
2025-10-08 08:31:39.682829 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-08 08:31:39.682836 | controller | Wednesday 08 October 2025  08:31:39 +0000 (0:00:00.025)       0:00:15.296 *****
2025-10-08 08:31:39.682847 | controller | ok: [instance]
2025-10-08 08:31:39.888862 | controller |
2025-10-08 08:31:39.888887 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-08 08:31:39.888894 | controller | Wednesday 08 October 2025  08:31:39 +0000 (0:00:00.184)       0:00:15.481 *****
2025-10-08 08:31:39.888904 | controller | changed: [instance]
2025-10-08 08:31:40.108743 | controller |
2025-10-08 08:31:40.108770 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-08 08:31:40.108778 | controller | Wednesday 08 October 2025  08:31:39 +0000 (0:00:00.205)       0:00:15.687 *****
2025-10-08 08:31:40.108788 | controller | changed: [instance]
2025-10-08 08:31:40.130233 | controller |
2025-10-08 08:31:40.130256 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-08 08:31:40.130263 | controller | Wednesday 08 October 2025  08:31:40 +0000 (0:00:00.220)       0:00:15.907 *****
2025-10-08 08:31:40.130272 | controller | skipping: [instance]
2025-10-08 08:31:40.164432 | controller |
2025-10-08 08:31:40.164456 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-08 08:31:40.164466 | controller | Wednesday 08 October 2025  08:31:40 +0000 (0:00:00.021)       0:00:15.929 *****
2025-10-08 08:31:40.164477 | controller | skipping: [instance]
2025-10-08 08:31:40.164507 | controller |
2025-10-08 08:31:40.164760 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-08 08:31:40.188252 | controller | Wednesday 08 October 2025  08:31:40 +0000 (0:00:00.032)       0:00:15.962 *****
2025-10-08 08:31:40.188279 | controller | skipping: [instance]
2025-10-08 08:31:40.209458 | controller |
2025-10-08 08:31:40.209495 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-08 08:31:40.209505 | controller | Wednesday 08 October 2025  08:31:40 +0000 (0:00:00.024)       0:00:15.987 *****
2025-10-08 08:31:40.209518 | controller | skipping: [instance]
2025-10-08 08:31:40.230518 | controller |
2025-10-08 08:31:40.230547 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-08 08:31:40.230561 | controller | Wednesday 08 October 2025  08:31:40 +0000 (0:00:00.021)       0:00:16.008 *****
2025-10-08 08:31:40.230571 | controller | skipping: [instance]
2025-10-08 08:31:40.251994 | controller |
2025-10-08 08:31:40.252023 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-08 08:31:40.252040 | controller | Wednesday 08 October 2025  08:31:40 +0000 (0:00:00.020)       0:00:16.029 *****
2025-10-08 08:31:40.252051 | controller | skipping: [instance]
2025-10-08 08:31:40.267889 | controller |
2025-10-08 08:31:40.267909 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-08 08:31:40.267916 | controller | Wednesday 08 October 2025  08:31:40 +0000 (0:00:00.021)       0:00:16.050 *****
2025-10-08 08:31:40.267925 | controller | skipping: [instance]
2025-10-08 08:31:40.296918 | controller |
2025-10-08 08:31:40.296942 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-08 08:31:40.296951 | controller | Wednesday 08 October 2025  08:31:40 +0000 (0:00:00.016)       0:00:16.066 *****
2025-10-08 08:31:40.296962 | controller | skipping: [instance]
2025-10-08 08:31:40.315251 | controller |
2025-10-08 08:31:40.315274 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-08 08:31:40.315283 | controller | Wednesday 08 October 2025  08:31:40 +0000 (0:00:00.028)       0:00:16.095 *****
2025-10-08 08:31:40.315294 | controller | skipping: [instance]
2025-10-08 08:31:40.335255 | controller |
2025-10-08 08:31:40.335277 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-08 08:31:40.335286 | controller | Wednesday 08 October 2025  08:31:40 +0000 (0:00:00.018)       0:00:16.114 *****
2025-10-08 08:31:40.335297 | controller | skipping: [instance]
2025-10-08 08:31:40.364683 | controller |
2025-10-08 08:31:40.364703 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-08 08:31:40.364710 | controller | Wednesday 08 October 2025  08:31:40 +0000 (0:00:00.020)       0:00:16.134 *****
2025-10-08 08:31:40.364719 | controller | skipping: [instance]
2025-10-08 08:31:40.393173 | controller |
2025-10-08 08:31:40.393197 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-08 08:31:40.393205 | controller | Wednesday 08 October 2025  08:31:40 +0000 (0:00:00.029)       0:00:16.163 *****
2025-10-08 08:31:40.393214 | controller | skipping: [instance]
2025-10-08 08:31:40.421846 | controller |
2025-10-08 08:31:40.421867 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-08 08:31:40.421874 | controller | Wednesday 08 October 2025  08:31:40 +0000 (0:00:00.028)       0:00:16.192 *****
2025-10-08 08:31:40.421883 | controller | skipping: [instance]
2025-10-08 08:31:40.450483 | controller |
2025-10-08 08:31:40.450506 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-08 08:31:40.450514 | controller | Wednesday 08 October 2025  08:31:40 +0000 (0:00:00.028)       0:00:16.220 *****
2025-10-08 08:31:40.450523 | controller | skipping: [instance]
2025-10-08 08:32:44.388420 | controller |
2025-10-08 08:32:44.388450 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-08 08:32:44.388458 | controller | Wednesday 08 October 2025  08:31:40 +0000 (0:00:00.028)       0:00:16.249 *****
2025-10-08 08:32:44.388469 | controller | ok: [instance]
2025-10-08 08:32:45.508854 | controller |
2025-10-08 08:32:45.508883 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-08 08:32:45.508891 | controller | Wednesday 08 October 2025  08:32:44 +0000 (0:01:03.937)       0:01:20.187 *****
2025-10-08 08:32:45.508901 | controller | ok: [instance]
2025-10-08 08:32:45.540278 | controller |
2025-10-08 08:32:45.540304 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-08 08:32:45.540313 | controller | Wednesday 08 October 2025  08:32:45 +0000 (0:00:01.120)       0:01:21.307 *****
2025-10-08 08:32:45.540324 | controller | skipping: [instance]
2025-10-08 08:32:47.895454 | controller |
2025-10-08 08:32:47.895488 | controller | TASK [Get the zuul/zuul-jobs repo] *********************************************
2025-10-08 08:32:47.895496 | controller | Wednesday 08 October 2025  08:32:45 +0000 (0:00:00.031)       0:01:21.339 *****
2025-10-08 08:32:47.895507 | controller | changed: [instance]
2025-10-08 08:32:47.895531 | controller |
2025-10-08 08:32:47.895538 | controller | PLAY RECAP *********************************************************************
2025-10-08 08:32:47.895543 | controller | instance                   : ok=20   changed=10   unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-10-08 08:32:47.895551 | controller |
2025-10-08 08:32:47.896423 | controller | Wednesday 08 October 2025  08:32:47 +0000 (0:00:02.354)       0:01:23.693 *****
2025-10-08 08:32:47.896472 | controller | ===============================================================================
2025-10-08 08:32:47.896481 | controller | test_deps : Install selinux python libs -------------------------------- 63.94s
2025-10-08 08:32:47.896486 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.78s
2025-10-08 08:32:47.896497 | controller | Get the zuul/zuul-jobs repo --------------------------------------------- 2.35s
2025-10-08 08:32:47.896503 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.14s
2025-10-08 08:32:47.896508 | controller | test_deps : Install python yaml libs ------------------------------------ 1.12s
2025-10-08 08:32:47.896514 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.83s
2025-10-08 08:32:47.896521 | controller | Gathering Facts --------------------------------------------------------- 0.79s
2025-10-08 08:32:47.896542 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.77s
2025-10-08 08:32:47.897750 | controller | repo_setup : Install repo-setup package --------------------------------- 0.69s
2025-10-08 08:32:47.949756 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.65s
2025-10-08 08:32:47.949781 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.54s
2025-10-08 08:32:47.949789 | controller | repo_setup : Ensure directories are present ----------------------------- 0.51s
2025-10-08 08:32:47.949795 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.47s
2025-10-08 08:32:47.949800 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.29s
2025-10-08 08:32:47.949805 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.27s
2025-10-08 08:32:47.949811 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.22s
2025-10-08 08:32:47.949817 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.21s
2025-10-08 08:32:47.949822 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.18s
2025-10-08 08:32:47.949827 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-10-08 08:32:47.949832 | controller | repo_setup : Export hashes facts for further use ------------------------ 0.04s
2025-10-08 08:32:47.949842 | controller | INFO     Running default > converge
2025-10-08 08:32:48.369141 | controller |
2025-10-08 08:32:48.369333 | controller | PLAY [Converge] ****************************************************************
2025-10-08 08:32:48.369499 | controller |
2025-10-08 08:32:48.369642 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 08:32:48.369794 | controller | Wednesday 08 October 2025  08:32:48 +0000 (0:00:00.015)       0:00:00.015 *****
2025-10-08 08:32:49.153471 | controller | ok: [instance]
2025-10-08 08:32:49.153503 | controller |
2025-10-08 08:32:49.153513 | controller | TASK [dlrn_report : Warning if DLRN is not running] ****************************
2025-10-08 08:32:49.153523 | controller | Wednesday 08 October 2025  08:32:49 +0000 (0:00:00.784)       0:00:00.799 *****
2025-10-08 08:32:49.174989 | controller | skipping: [instance]
2025-10-08 08:32:49.196432 | controller |
2025-10-08 08:32:49.196470 | controller | TASK [dlrn_report : Install dlrnapi-client shyaml package] *********************
2025-10-08 08:32:49.196478 | controller | Wednesday 08 October 2025  08:32:49 +0000 (0:00:00.021)       0:00:00.821 *****
2025-10-08 08:32:49.196489 | controller | skipping: [instance]
2025-10-08 08:32:49.196507 | controller |
2025-10-08 08:32:49.196515 | controller | TASK [dlrn_report : Install kinit related package] *****************************
2025-10-08 08:32:49.196597 | controller | Wednesday 08 October 2025  08:32:49 +0000 (0:00:00.021)       0:00:00.843 *****
2025-10-08 08:32:49.216905 | controller | skipping: [instance]
2025-10-08 08:32:49.217003 | controller |
2025-10-08 08:32:49.217110 | controller | TASK [dlrn_report : Install dlrn kerberos related packages] ********************
2025-10-08 08:32:49.217214 | controller | Wednesday 08 October 2025  08:32:49 +0000 (0:00:00.020)       0:00:00.864 *****
2025-10-08 08:32:49.238200 | controller | skipping: [instance]
2025-10-08 08:32:49.238300 | controller |
2025-10-08 08:32:49.238435 | controller | TASK [Set zuul-log-path fact] **************************************************
2025-10-08 08:32:49.238541 | controller | Wednesday 08 October 2025  08:32:49 +0000 (0:00:00.021)       0:00:00.885 *****
2025-10-08 08:32:49.260411 | controller | skipping: [instance]
2025-10-08 08:32:49.260504 | controller |
2025-10-08 08:32:49.260615 | controller | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used] ***
2025-10-08 08:32:49.260757 | controller | Wednesday 08 October 2025  08:32:49 +0000 (0:00:00.022)       0:00:00.907 *****
2025-10-08 08:32:49.282382 | controller | skipping: [instance]
2025-10-08 08:32:49.303791 | controller |
2025-10-08 08:32:49.303809 | controller | TASK [Get hash related data from repo_setup role] ******************************
2025-10-08 08:32:49.303822 | controller | Wednesday 08 October 2025  08:32:49 +0000 (0:00:00.021)       0:00:00.929 *****
2025-10-08 08:32:49.303831 | controller | skipping: [instance]
2025-10-08 08:32:49.324194 | controller |
2025-10-08 08:32:49.324212 | controller | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication] ************
2025-10-08 08:32:49.324219 | controller | Wednesday 08 October 2025  08:32:49 +0000 (0:00:00.021)       0:00:00.950 *****
2025-10-08 08:32:49.324228 | controller | skipping: [instance]
2025-10-08 08:32:49.345001 | controller |
2025-10-08 08:32:49.345019 | controller | TASK [dlrn_report : Set empty value for dlrnapi password] **********************
2025-10-08 08:32:49.345026 | controller | Wednesday 08 October 2025  08:32:49 +0000 (0:00:00.020)       0:00:00.971 *****
2025-10-08 08:32:49.345035 | controller | skipping: [instance]
2025-10-08 08:32:49.383956 | controller |
2025-10-08 08:32:49.383975 | controller | TASK [dlrn_report : Report results to dlrn for the tested hash] ****************
2025-10-08 08:32:49.383983 | controller | Wednesday 08 October 2025  08:32:49 +0000 (0:00:00.020)       0:00:00.991 *****
2025-10-08 08:32:49.383993 | controller | skipping: [instance]
2025-10-08 08:32:49.439191 | controller |
2025-10-08 08:32:49.439211 | controller | PLAY RECAP *********************************************************************
2025-10-08 08:32:49.439218 | controller | instance                   : ok=1    changed=0    unreachable=0    failed=0    skipped=10   rescued=0    ignored=0
2025-10-08 08:32:49.439224 | controller |
2025-10-08 08:32:49.439230 | controller | Wednesday 08 October 2025  08:32:49 +0000 (0:00:00.037)       0:00:01.029 *****
2025-10-08 08:32:49.439235 | controller | ===============================================================================
2025-10-08 08:32:49.439241 | controller | Gathering Facts --------------------------------------------------------- 0.78s
2025-10-08 08:32:49.439246 | controller | dlrn_report : Report results to dlrn for the tested hash ---------------- 0.04s
2025-10-08 08:32:49.439252 | controller | Set zuul-log-path fact -------------------------------------------------- 0.02s
2025-10-08 08:32:49.439257 | controller | dlrn_report : Warning if DLRN is not running ---------------------------- 0.02s
2025-10-08 08:32:49.439262 | controller | dlrn_report : Install dlrnapi-client shyaml package --------------------- 0.02s
2025-10-08 08:32:49.439267 | controller | dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used --- 0.02s
2025-10-08 08:32:49.439273 | controller | Get hash related data from repo_setup role ------------------------------ 0.02s
2025-10-08 08:32:49.439278 | controller | dlrn_report : Install dlrn kerberos related packages -------------------- 0.02s
2025-10-08 08:32:49.439283 | controller | dlrn_report : Set empty value for dlrnapi password ---------------------- 0.02s
2025-10-08 08:32:49.439289 | controller | dlrn_report : Install kinit related package ----------------------------- 0.02s
2025-10-08 08:32:49.439294 | controller | dlrn_report : Perform kinit for DLRN kerberos authentication ------------ 0.02s
2025-10-08 08:32:49.439303 | controller | INFO     Running default > cleanup
2025-10-08 08:32:49.439806 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-08 08:32:49.440400 | controller | INFO     Writing /tmp/report.html report.
2025-10-08 08:32:49.933606 | controller | changed
2025-10-08 08:32:49.953757 | 
2025-10-08 08:32:49.953812 | PLAY RECAP
2025-10-08 08:32:49.953852 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 08:32:49.953874 | 
2025-10-08 08:32:50.012361 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 08:32:50.013255 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 08:32:50.535684 | 
2025-10-08 08:32:50.535792 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-08 08:32:50.556100 | 
2025-10-08 08:32:50.556179 | TASK [Filter out host if needed]
2025-10-08 08:32:50.565010 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-08 08:32:50.569472 | 
2025-10-08 08:32:50.569540 | TASK [Ensure file is present]
2025-10-08 08:32:50.861949 | controller | ok
2025-10-08 08:32:50.869079 | 
2025-10-08 08:32:50.869156 | TASK [Manage molecule report file]
2025-10-08 08:32:51.395411 | controller | changed
2025-10-08 08:32:51.400635 | 
2025-10-08 08:32:51.400700 | TASK [Check if we get ci-framework-data basedir]
2025-10-08 08:32:51.580979 | controller | ok
2025-10-08 08:32:51.586017 | 
2025-10-08 08:32:51.586080 | TASK [Create ci-framework-data log directory for zuul]
2025-10-08 08:32:51.877780 | controller | changed
2025-10-08 08:32:51.883014 | 
2025-10-08 08:32:51.883089 | TASK [Copy ci-framework interesting files]
2025-10-08 08:32:52.064274 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-08 08:32:52.406246 | controller | changed
2025-10-08 08:32:52.413659 | 
2025-10-08 08:32:52.413735 | TASK [Get SELinux listing]
2025-10-08 08:32:52.936654 | controller | changed
2025-10-08 08:32:52.942235 | 
2025-10-08 08:32:52.942301 | TASK [Generate log index]
2025-10-08 08:32:53.547969 | controller | changed
2025-10-08 08:32:53.552735 | 
2025-10-08 08:32:53.552798 | TASK [Get some env related data]
2025-10-08 08:32:54.065889 | controller | /home/zuul/.local/bin/ansible
2025-10-08 08:32:55.079161 | controller | changed
2025-10-08 08:32:55.084486 | 
2025-10-08 08:32:55.084552 | TASK [Generate list of logs to collect in home directory]
2025-10-08 08:32:55.417280 | controller | ok: All paths examined
2025-10-08 08:32:55.422374 | 
2025-10-08 08:32:55.422439 | LOOP [Copy logs from home directory]
2025-10-08 08:32:55.758923 | controller | changed:
2025-10-08 08:32:55.759051 | controller | {
2025-10-08 08:32:55.759079 | controller |   "atime": 1759912248.328384,
2025-10-08 08:32:55.759100 | controller |   "ctime": 1759912264.2258463,
2025-10-08 08:32:55.759117 | controller |   "dev": 64513,
2025-10-08 08:32:55.759134 | controller |   "gid": 1000,
2025-10-08 08:32:55.759150 | controller |   "gr_name": "zuul",
2025-10-08 08:32:55.759166 | controller |   "inode": 4448214,
2025-10-08 08:32:55.759182 | controller |   "isblk": false,
2025-10-08 08:32:55.759199 | controller |   "ischr": false,
2025-10-08 08:32:55.759214 | controller |   "isdir": false,
2025-10-08 08:32:55.759229 | controller |   "isfifo": false,
2025-10-08 08:32:55.759244 | controller |   "isgid": false,
2025-10-08 08:32:55.759258 | controller |   "islnk": false,
2025-10-08 08:32:55.759272 | controller |   "isreg": true,
2025-10-08 08:32:55.759287 | controller |   "issock": false,
2025-10-08 08:32:55.759300 | controller |   "isuid": false,
2025-10-08 08:32:55.759316 | controller |   "mode": "0644",
2025-10-08 08:32:55.759332 | controller |   "mtime": 1759912264.2258463,
2025-10-08 08:32:55.759347 | controller |   "nlink": 1,
2025-10-08 08:32:55.759362 | controller |   "path": "/home/zuul/ansible.log",
2025-10-08 08:32:55.759379 | controller |   "pw_name": "zuul",
2025-10-08 08:32:55.759395 | controller |   "rgrp": true,
2025-10-08 08:32:55.759410 | controller |   "roth": true,
2025-10-08 08:32:55.759425 | controller |   "rusr": true,
2025-10-08 08:32:55.759439 | controller |   "size": 6749,
2025-10-08 08:32:55.759454 | controller |   "uid": 1000,
2025-10-08 08:32:55.759468 | controller |   "wgrp": false,
2025-10-08 08:32:55.759482 | controller |   "woth": false,
2025-10-08 08:32:55.759500 | controller |   "wusr": true,
2025-10-08 08:32:55.759516 | controller |   "xgrp": false,
2025-10-08 08:32:55.759532 | controller |   "xoth": false,
2025-10-08 08:32:55.759546 | controller |   "xusr": false
2025-10-08 08:32:55.759560 | controller | }
2025-10-08 08:32:55.772904 | 
2025-10-08 08:32:55.772985 | TASK [Copy crio stats log file]
2025-10-08 08:32:55.787679 | controller | skipping: Conditional result was False
2025-10-08 08:32:55.794205 | 
2025-10-08 08:32:55.794302 | TASK [Get SELinux related data]
2025-10-08 08:32:56.009142 | controller | <no matches>
2025-10-08 08:32:56.323894 | controller | ERROR
2025-10-08 08:32:56.324169 | controller | {
2025-10-08 08:32:56.324205 | controller |   "delta": "0:00:00.009711",
2025-10-08 08:32:56.324227 | controller |   "end": "2025-10-08 08:32:56.009734",
2025-10-08 08:32:56.324252 | controller |   "msg": "non-zero return code",
2025-10-08 08:32:56.324270 | controller |   "rc": 1,
2025-10-08 08:32:56.324286 | controller |   "start": "2025-10-08 08:32:56.000023"
2025-10-08 08:32:56.324301 | controller | }
2025-10-08 08:32:56.324325 | controller | ERROR: Ignoring Errors
2025-10-08 08:32:56.329649 | 
2025-10-08 08:32:56.329714 | TASK [Create system configuration directory]
2025-10-08 08:32:56.521933 | controller | changed
2025-10-08 08:32:56.527084 | 
2025-10-08 08:32:56.527156 | TASK [Get some of the system configurations]
2025-10-08 08:32:57.054869 | controller | changed
2025-10-08 08:32:57.060083 | 
2025-10-08 08:32:57.060152 | TASK [Copy generated documentation if available]
2025-10-08 08:32:57.073805 | controller | skipping: Conditional result was False
2025-10-08 08:32:57.079329 | 
2025-10-08 08:32:57.079391 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-08 08:32:57.092616 | controller | skipping: Conditional result was False
2025-10-08 08:32:57.098395 | 
2025-10-08 08:32:57.098456 | TASK [Compress logs bigger than 2MB]
2025-10-08 08:32:57.624676 | controller | changed
2025-10-08 08:32:57.630430 | 
2025-10-08 08:32:57.630495 | TASK [Copy files from workspace on node]
2025-10-08 08:32:57.648860 | controller | ok
2025-10-08 08:32:57.672737 | 
2025-10-08 08:32:57.672811 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 08:32:57.696492 | controller | skipping: Conditional result was False
2025-10-08 08:32:57.702546 | 
2025-10-08 08:32:57.702622 | TASK [fetch-output : Set log path for single node]
2025-10-08 08:32:57.732730 | controller | ok
2025-10-08 08:32:57.738973 | 
2025-10-08 08:32:57.739044 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 08:32:57.949959 | controller -> localhost | ok: "/var/lib/zuul/builds/e742255b6d6246c88655423f2a77626c/work/logs"
2025-10-08 08:32:57.950375 | controller -> localhost | changed: All items complete
2025-10-08 08:32:57.950406 | 
2025-10-08 08:32:58.146400 | controller -> localhost | changed: "/var/lib/zuul/builds/e742255b6d6246c88655423f2a77626c/work/artifacts"
2025-10-08 08:32:58.333517 | controller -> localhost | changed: "/var/lib/zuul/builds/e742255b6d6246c88655423f2a77626c/work/docs"
2025-10-08 08:32:58.348934 | 
2025-10-08 08:32:58.349021 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 08:32:58.884575 | controller | changed:
2025-10-08 08:32:58.884764 | controller | .d..t...... ./
2025-10-08 08:32:58.884794 | controller | >f+++++++++ README.html
2025-10-08 08:32:58.884816 | controller | >f+++++++++ ansible-execution.log
2025-10-08 08:32:58.884838 | controller | >f+++++++++ ansible.log
2025-10-08 08:32:58.884857 | controller | >f+++++++++ dmesg.log
2025-10-08 08:32:58.884875 | controller | >f+++++++++ installed-pkgs.log
2025-10-08 08:32:58.884894 | controller | >f+++++++++ python.log
2025-10-08 08:32:58.884934 | controller | >f+++++++++ registries.conf
2025-10-08 08:32:58.884956 | controller | >f+++++++++ report.html
2025-10-08 08:32:58.884975 | controller | >f+++++++++ selinux-denials.log
2025-10-08 08:32:58.884992 | controller | >f+++++++++ selinux-listing.log
2025-10-08 08:32:58.885008 | controller | cd+++++++++ ci-framework-data/
2025-10-08 08:32:58.885024 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-08 08:32:58.885040 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-08 08:32:58.885056 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-08 08:32:58.885072 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-08 08:32:58.885088 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-08 08:32:58.885103 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-08 08:32:58.885118 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-08 08:32:58.885132 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-08 08:32:58.885147 | controller | cd+++++++++ registries.conf.d/
2025-10-08 08:32:58.885166 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-08 08:32:58.885182 | controller | cd+++++++++ system-config/
2025-10-08 08:32:58.885198 | controller | cd+++++++++ system-config/libvirt/
2025-10-08 08:32:58.885213 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-08 08:32:58.885228 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-08 08:32:59.244490 | controller | changed: .d..t...... ./
2025-10-08 08:32:59.605469 | controller | changed: .d..t...... ./
2025-10-08 08:32:59.630029 | 
2025-10-08 08:32:59.630203 | TASK [Return artifact to Zuul]
2025-10-08 08:32:59.660429 | controller | ok
2025-10-08 08:32:59.680928 | 
2025-10-08 08:32:59.680990 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-08 08:32:59.681096 | 
2025-10-08 08:32:59.681124 | PLAY RECAP
2025-10-08 08:32:59.681167 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-08 08:32:59.681190 | 
2025-10-08 08:32:59.761011 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 08:32:59.761852 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 08:33:00.279881 | 
2025-10-08 08:33:00.279993 | PLAY [all]
2025-10-08 08:33:00.298554 | 
2025-10-08 08:33:00.298630 | TASK [include_role : fetch-output]
2025-10-08 08:33:00.327365 | controller | ok
2025-10-08 08:33:00.343817 | 
2025-10-08 08:33:00.343980 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 08:33:00.398290 | controller | skipping: Conditional result was False
2025-10-08 08:33:00.404332 | 
2025-10-08 08:33:00.404404 | TASK [fetch-output : Set log path for single node]
2025-10-08 08:33:00.444209 | controller | ok
2025-10-08 08:33:00.449266 | 
2025-10-08 08:33:00.449335 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 08:33:00.806335 | controller -> localhost | ok: "/var/lib/zuul/builds/e742255b6d6246c88655423f2a77626c/work/logs"
2025-10-08 08:33:01.024888 | controller -> localhost | ok: "/var/lib/zuul/builds/e742255b6d6246c88655423f2a77626c/work/artifacts"
2025-10-08 08:33:01.222011 | controller -> localhost | ok: "/var/lib/zuul/builds/e742255b6d6246c88655423f2a77626c/work/docs"
2025-10-08 08:33:01.234662 | 
2025-10-08 08:33:01.234835 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 08:33:01.763456 | controller | ok
2025-10-08 08:33:01.763824 | controller | ok: All items complete
2025-10-08 08:33:01.763856 | 
2025-10-08 08:33:02.136305 | controller | ok
2025-10-08 08:33:02.528498 | controller | ok
2025-10-08 08:33:02.540854 | 
2025-10-08 08:33:02.541013 | TASK [include_role : fetch-output-openshift]
2025-10-08 08:33:02.556035 | controller | skipping: Conditional result was False
2025-10-08 08:33:02.563064 | 
2025-10-08 08:33:02.563145 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-08 08:33:02.963182 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007196
2025-10-08 08:33:03.176329 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006606
2025-10-08 08:33:03.208061 | 
2025-10-08 08:33:03.208250 | PLAY [all]
2025-10-08 08:33:03.222369 | 
2025-10-08 08:33:03.222442 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-08 08:33:03.614845 | controller | changed
2025-10-08 08:33:03.636221 | 
2025-10-08 08:33:03.636276 | PLAY RECAP
2025-10-08 08:33:03.636324 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-08 08:33:03.636348 | 
2025-10-08 08:33:03.710169 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 08:33:03.710992 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-08 08:33:04.282396 | 
2025-10-08 08:33:04.282600 | PLAY [localhost]
2025-10-08 08:33:04.302322 | 
2025-10-08 08:33:04.302413 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-08 08:33:04.639719 | localhost | changed
2025-10-08 08:33:04.644630 | 
2025-10-08 08:33:04.644713 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-08 08:33:04.673572 | localhost | ok
2025-10-08 08:33:04.682142 | 
2025-10-08 08:33:04.682215 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-08 08:33:05.037197 | localhost | changed
2025-10-08 08:33:05.043945 | 
2025-10-08 08:33:05.044021 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-08 08:33:05.610299 | localhost | changed
2025-10-08 08:33:05.615486 | 
2025-10-08 08:33:05.615558 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-08 08:33:05.979528 | localhost | Identity added: /var/lib/zuul/builds/e742255b6d6246c88655423f2a77626c/work/tmp/ansible.s7en18tl (/var/lib/zuul/builds/e742255b6d6246c88655423f2a77626c/work/tmp/ansible.s7en18tl)
2025-10-08 08:33:05.979706 | localhost | ok: Runtime: 0:00:00.025331
2025-10-08 08:33:05.984201 | 
2025-10-08 08:33:05.984272 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-08 08:33:06.244753 | localhost | ok: Runtime: 0:00:00.019232
2025-10-08 08:33:06.249589 | 
2025-10-08 08:33:06.249659 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-08 08:33:06.310140 | localhost | changed
2025-10-08 08:33:06.314378 | 
2025-10-08 08:33:06.314445 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-08 08:33:06.666949 | localhost | changed
2025-10-08 08:33:06.690471 | 
2025-10-08 08:33:06.690537 | PLAY [localhost]
2025-10-08 08:33:06.702514 | 
2025-10-08 08:33:06.702578 | TASK [Generate bulk log download script]
2025-10-08 08:33:06.720445 | localhost | ok
2025-10-08 08:33:06.731881 | 
2025-10-08 08:33:06.731965 | TASK [local-log-download : Check API endpoint is defined]
2025-10-08 08:33:06.759054 | localhost | ok: All assertions passed
2025-10-08 08:33:06.763354 | 
2025-10-08 08:33:06.763418 | TASK [local-log-download : Create download script]
2025-10-08 08:33:07.121421 | localhost -> localhost | changed
2025-10-08 08:33:07.130254 | 
2025-10-08 08:33:07.130318 | TASK [Register quick-download link]
2025-10-08 08:33:07.163701 | localhost | ok
2025-10-08 08:33:07.202273 | 
2025-10-08 08:33:07.202341 | PLAY [logserver.rdoproject.org]
2025-10-08 08:33:07.212094 | 
2025-10-08 08:33:07.212157 | TASK [Set zuul-log-path fact]
2025-10-08 08:33:07.229379 | logserver.rdoproject.org | ok
2025-10-08 08:33:07.238801 | 
2025-10-08 08:33:07.238867 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 08:33:07.265218 | logserver.rdoproject.org | ok
2025-10-08 08:33:07.270902 | 
2025-10-08 08:33:07.270980 | TASK [upload-logs : Create log directories]
2025-10-08 08:33:09.025749 | logserver.rdoproject.org | changed
2025-10-08 08:33:09.029422 | 
2025-10-08 08:33:09.029482 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-08 08:33:09.287123 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.021175
2025-10-08 08:33:09.292507 | 
2025-10-08 08:33:09.292575 | TASK [upload-logs : Upload logs to log server]
2025-10-08 08:33:10.853794 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-08 08:33:10.857090 | 
2025-10-08 08:33:10.857157 | LOOP [upload-logs : Compress console log and json output]
2025-10-08 08:33:10.914548 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 08:33:10.923173 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 08:33:10.930120 | 
2025-10-08 08:33:10.930225 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-08 08:33:10.971664 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 08:33:10.971954 | 
2025-10-08 08:33:10.976595 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 08:33:10.989297 | 
2025-10-08 08:33:10.989383 | LOOP [upload-logs : Upload console log and json output]