2025-10-02 14:19:14.079786 | Job console starting...
2025-10-02 14:19:14.317699 | Updating repositories
2025-10-02 14:19:15.029630 | Preparing job workspace
2025-10-02 14:19:22.548152 | Running Ansible setup...
2025-10-02 14:19:25.501250 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-02 14:19:26.059098 | 
2025-10-02 14:19:26.059213 | PLAY [localhost]
2025-10-02 14:19:26.067780 | 
2025-10-02 14:19:26.067851 | TASK [Gathering Facts]
2025-10-02 14:19:26.977132 | localhost | ok
2025-10-02 14:19:26.990518 | 
2025-10-02 14:19:26.990613 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-02 14:19:27.318000 | localhost -> localhost | changed
2025-10-02 14:19:27.323389 | 
2025-10-02 14:19:27.323457 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-02 14:19:28.055271 | localhost -> localhost | changed
2025-10-02 14:19:28.063680 | 
2025-10-02 14:19:28.063745 | TASK [Setup log path fact]
2025-10-02 14:19:28.080973 | localhost | ok
2025-10-02 14:19:28.092434 | 
2025-10-02 14:19:28.092508 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-02 14:19:28.119398 | localhost | ok
2025-10-02 14:19:28.126841 | 
2025-10-02 14:19:28.126949 | TASK [emit-job-header : Print job information]
2025-10-02 14:19:28.153879 | # Job Information
2025-10-02 14:19:28.154014 | Ansible Version: 2.15.12
2025-10-02 14:19:28.154046 | Job: cifmw-molecule-test_deps
2025-10-02 14:19:28.154070 | Pipeline: github-check
2025-10-02 14:19:28.154090 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-02 14:19:28.154109 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3362
2025-10-02 14:19:28.154139 | Log URL (when completed): https://logserver.rdoproject.org/977/rdoproject.org/97761fdad209433e9eec58cfdb1fe100/
2025-10-02 14:19:28.154159 | Event ID: be9565c0-9f99-11f0-9e8d-1085adf9bc04
2025-10-02 14:19:28.158296 | 
2025-10-02 14:19:28.158379 | LOOP [emit-job-header : Print node information]
2025-10-02 14:19:28.244839 | localhost | ok:
2025-10-02 14:19:28.245011 | localhost | # Node Information
2025-10-02 14:19:28.245042 | localhost | Inventory Hostname: controller
2025-10-02 14:19:28.245068 | localhost | Hostname: np0005466423
2025-10-02 14:19:28.245090 | localhost | Username: zuul
2025-10-02 14:19:28.245111 | localhost | Distro: CentOS 9
2025-10-02 14:19:28.245144 | localhost | Provider: ibm-bm4-nodepool
2025-10-02 14:19:28.245164 | localhost | Region: regionOne
2025-10-02 14:19:28.245182 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-02 14:19:28.245200 | localhost | Product Name: OpenStack Compute
2025-10-02 14:19:28.245217 | localhost | Interface IP: 192.168.26.198
2025-10-02 14:19:28.272021 | 
2025-10-02 14:19:28.272096 | PLAY [all]
2025-10-02 14:19:28.279004 | 
2025-10-02 14:19:28.279074 | TASK [Gather network facts]
2025-10-02 14:19:28.631678 | controller | ok
2025-10-02 14:19:28.645621 | 
2025-10-02 14:19:28.645694 | TASK [include_role : start-zuul-console]
2025-10-02 14:19:28.664482 | controller | ok
2025-10-02 14:19:28.676963 | 
2025-10-02 14:19:28.677044 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-02 14:19:29.072182 | controller | ok
2025-10-02 14:19:29.088014 | 
2025-10-02 14:19:29.088102 | TASK [include_role : add-build-sshkey]
2025-10-02 14:19:29.127360 | controller | ok
2025-10-02 14:19:29.146946 | 
2025-10-02 14:19:29.147020 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-02 14:19:29.557379 | controller -> localhost | ok
2025-10-02 14:19:29.563520 | 
2025-10-02 14:19:29.563605 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-02 14:19:29.584456 | controller | ok
2025-10-02 14:19:29.597278 | controller | included: /var/lib/zuul/builds/97761fdad209433e9eec58cfdb1fe100/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-02 14:19:29.603389 | 
2025-10-02 14:19:29.603453 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-02 14:19:30.095518 | controller -> localhost | Generating public/private rsa key pair.
2025-10-02 14:19:30.095706 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/97761fdad209433e9eec58cfdb1fe100/work/97761fdad209433e9eec58cfdb1fe100_id_rsa.
2025-10-02 14:19:30.095740 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/97761fdad209433e9eec58cfdb1fe100/work/97761fdad209433e9eec58cfdb1fe100_id_rsa.pub.
2025-10-02 14:19:30.095766 | controller -> localhost | The key fingerprint is:
2025-10-02 14:19:30.095798 | controller -> localhost | SHA256:TzNR1lHkGd3gRDfD/rMPzLBcB5us+FM51RZTIWQoKEg zuul-build-sshkey
2025-10-02 14:19:30.095819 | controller -> localhost | The key's randomart image is:
2025-10-02 14:19:30.095839 | controller -> localhost | +---[RSA 3072]----+
2025-10-02 14:19:30.095858 | controller -> localhost | |  .E.   .   =**XX|
2025-10-02 14:19:30.095877 | controller -> localhost | |   . . . . +.oo*B|
2025-10-02 14:19:30.095895 | controller -> localhost | |      .   o   +.=|
2025-10-02 14:19:30.095936 | controller -> localhost | |           . . =+|
2025-10-02 14:19:30.095958 | controller -> localhost | |        S + . =+o|
2025-10-02 14:19:30.095977 | controller -> localhost | |         o = B+o.|
2025-10-02 14:19:30.095995 | controller -> localhost | |          o +.+.o|
2025-10-02 14:19:30.096012 | controller -> localhost | |           ..  o |
2025-10-02 14:19:30.096030 | controller -> localhost | |            ..  o|
2025-10-02 14:19:30.096047 | controller -> localhost | +----[SHA256]-----+
2025-10-02 14:19:30.096090 | controller -> localhost | ok: Runtime: 0:00:00.162662
2025-10-02 14:19:30.101990 | 
2025-10-02 14:19:30.102055 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-02 14:19:30.119845 | controller | ok
2025-10-02 14:19:30.129243 | controller | included: /var/lib/zuul/builds/97761fdad209433e9eec58cfdb1fe100/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-02 14:19:30.137771 | 
2025-10-02 14:19:30.137834 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-02 14:19:30.151363 | controller | skipping: Conditional result was False
2025-10-02 14:19:30.156846 | 
2025-10-02 14:19:30.156929 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-02 14:19:30.529802 | controller | changed
2025-10-02 14:19:30.534538 | 
2025-10-02 14:19:30.534601 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-02 14:19:30.749737 | controller | ok
2025-10-02 14:19:30.755067 | 
2025-10-02 14:19:30.755145 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-02 14:19:31.329584 | controller | changed
2025-10-02 14:19:31.334349 | 
2025-10-02 14:19:31.334422 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-02 14:19:31.912885 | controller | changed
2025-10-02 14:19:31.919223 | 
2025-10-02 14:19:31.919302 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-02 14:19:31.932806 | controller | skipping: Conditional result was False
2025-10-02 14:19:31.939067 | 
2025-10-02 14:19:31.939167 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-02 14:19:32.343377 | controller -> localhost | changed
2025-10-02 14:19:32.352860 | 
2025-10-02 14:19:32.352946 | TASK [add-build-sshkey : Add back temp key]
2025-10-02 14:19:32.613104 | controller -> localhost | Identity added: /var/lib/zuul/builds/97761fdad209433e9eec58cfdb1fe100/work/97761fdad209433e9eec58cfdb1fe100_id_rsa (zuul-build-sshkey)
2025-10-02 14:19:32.613280 | controller -> localhost | ok: Runtime: 0:00:00.010443
2025-10-02 14:19:32.618779 | 
2025-10-02 14:19:32.618841 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-02 14:19:33.020475 | controller | ok
2025-10-02 14:19:33.025110 | 
2025-10-02 14:19:33.025192 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-02 14:19:33.073036 | controller | skipping: Conditional result was False
2025-10-02 14:19:33.082132 | 
2025-10-02 14:19:33.082195 | TASK [include_role : validate-host]
2025-10-02 14:19:33.103440 | controller | ok
2025-10-02 14:19:33.136765 | 
2025-10-02 14:19:33.136836 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-02 14:19:33.198372 | controller | ok
2025-10-02 14:19:33.203478 | 
2025-10-02 14:19:33.203542 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-02 14:19:33.463847 | controller -> localhost | ok
2025-10-02 14:19:33.470408 | 
2025-10-02 14:19:33.470474 | TASK [validate-host : Collect information about the host]
2025-10-02 14:19:34.071668 | controller | ok
2025-10-02 14:19:34.080557 | 
2025-10-02 14:19:34.080619 | TASK [validate-host : Sanitize hostname]
2025-10-02 14:19:34.144601 | controller | ok
2025-10-02 14:19:34.149406 | 
2025-10-02 14:19:34.149468 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-02 14:19:34.579996 | controller -> localhost | changed
2025-10-02 14:19:34.587190 | 
2025-10-02 14:19:34.587255 | TASK [validate-host : Collect information about zuul worker]
2025-10-02 14:19:34.900348 | controller | ok
2025-10-02 14:19:34.905613 | 
2025-10-02 14:19:34.905683 | TASK [validate-host : Write out all zuul information for each host]
2025-10-02 14:19:35.357779 | controller -> localhost | changed
2025-10-02 14:19:35.367672 | 
2025-10-02 14:19:35.367753 | TASK [include_role : prepare-workspace-openshift]
2025-10-02 14:19:35.381309 | controller | skipping: Conditional result was False
2025-10-02 14:19:35.387271 | 
2025-10-02 14:19:35.387355 | TASK [include_role : remove-zuul-sshkey]
2025-10-02 14:19:35.402884 | controller | skipping: Conditional result was False
2025-10-02 14:19:35.407866 | 
2025-10-02 14:19:35.408010 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-02 14:19:35.599434 | controller | ok: "logs"
2025-10-02 14:19:35.599647 | controller | ok: All items complete
2025-10-02 14:19:35.599678 | 
2025-10-02 14:19:35.761810 | controller | ok: "artifacts"
2025-10-02 14:19:35.935496 | controller | ok: "docs"
2025-10-02 14:19:35.942626 | 
2025-10-02 14:19:35.942716 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-02 14:19:36.136962 | controller | changed: "logs"
2025-10-02 14:19:36.301351 | controller | changed: "artifacts"
2025-10-02 14:19:36.469995 | controller | changed: "docs"
2025-10-02 14:19:36.496060 | 
2025-10-02 14:19:36.496197 | PLAY RECAP
2025-10-02 14:19:36.496246 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-02 14:19:36.496274 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-02 14:19:36.496292 | 
2025-10-02 14:19:36.583497 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-02 14:19:36.584356 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-02 14:19:37.194098 | 
2025-10-02 14:19:37.194210 | PLAY [all]
2025-10-02 14:19:37.214373 | 
2025-10-02 14:19:37.214511 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-02 14:19:37.273382 | controller | ok
2025-10-02 14:19:37.278570 | 
2025-10-02 14:19:37.278647 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-02 14:19:37.616983 | controller | changed
2025-10-02 14:19:37.622463 | 
2025-10-02 14:19:37.622536 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-02 14:19:38.521322 | controller | changed
2025-10-02 14:19:38.537251 | 
2025-10-02 14:19:38.537343 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-02 14:19:38.969125 | controller | changed:
2025-10-02 14:19:38.969299 | controller | {
2025-10-02 14:19:38.969331 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-02 14:19:38.969362 | controller | }
2025-10-02 14:19:39.173099 | controller | changed:
2025-10-02 14:19:39.173188 | controller | {
2025-10-02 14:19:39.173219 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-02 14:19:39.173241 | controller | }
2025-10-02 14:19:39.386731 | controller | changed:
2025-10-02 14:19:39.386818 | controller | {
2025-10-02 14:19:39.386848 | 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-02 14:19:39.386871 | controller | }
2025-10-02 14:19:39.599550 | controller | changed:
2025-10-02 14:19:39.599634 | controller | {
2025-10-02 14:19:39.599662 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-02 14:19:39.599683 | controller | }
2025-10-02 14:19:39.810507 | controller | changed:
2025-10-02 14:19:39.810605 | controller | {
2025-10-02 14:19:39.810633 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-02 14:19:39.810654 | controller | }
2025-10-02 14:19:40.011769 | controller | changed:
2025-10-02 14:19:40.011850 | controller | {
2025-10-02 14:19:40.011879 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-02 14:19:40.011901 | controller | }
2025-10-02 14:19:40.210487 | controller | changed:
2025-10-02 14:19:40.210579 | controller | {
2025-10-02 14:19:40.210607 | 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-02 14:19:40.210630 | controller | }
2025-10-02 14:19:40.411156 | controller | changed:
2025-10-02 14:19:40.411252 | controller | {
2025-10-02 14:19:40.411282 | 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-02 14:19:40.411307 | controller | }
2025-10-02 14:19:40.626831 | controller | changed:
2025-10-02 14:19:40.626941 | controller | {
2025-10-02 14:19:40.626978 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-02 14:19:40.627002 | controller | }
2025-10-02 14:19:40.822679 | controller | changed:
2025-10-02 14:19:40.822755 | controller | {
2025-10-02 14:19:40.822782 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-02 14:19:40.822803 | controller | }
2025-10-02 14:19:41.022994 | controller | changed:
2025-10-02 14:19:41.023168 | controller | {
2025-10-02 14:19:41.023208 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-02 14:19:41.023232 | controller | }
2025-10-02 14:19:41.269180 | controller | changed:
2025-10-02 14:19:41.269258 | controller | {
2025-10-02 14:19:41.269288 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-02 14:19:41.269310 | controller | }
2025-10-02 14:19:41.448580 | controller | changed:
2025-10-02 14:19:41.448658 | controller | {
2025-10-02 14:19:41.450543 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-02 14:19:41.452408 | controller | }
2025-10-02 14:19:41.663009 | controller | changed:
2025-10-02 14:19:41.663086 | controller | {
2025-10-02 14:19:41.663125 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-02 14:19:41.663150 | controller | }
2025-10-02 14:19:41.876450 | controller | changed:
2025-10-02 14:19:41.876542 | controller | {
2025-10-02 14:19:41.876570 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-02 14:19:41.876593 | controller | }
2025-10-02 14:19:42.100208 | controller | changed:
2025-10-02 14:19:42.100284 | controller | {
2025-10-02 14:19:42.100313 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-02 14:19:42.100334 | controller | }
2025-10-02 14:19:42.319108 | controller | changed:
2025-10-02 14:19:42.319300 | controller | {
2025-10-02 14:19:42.319339 | 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-02 14:19:42.319365 | controller | }
2025-10-02 14:19:42.520074 | controller | changed:
2025-10-02 14:19:42.520162 | controller | {
2025-10-02 14:19:42.520193 | 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-02 14:19:42.520223 | controller | }
2025-10-02 14:19:42.741359 | controller | changed:
2025-10-02 14:19:42.741434 | controller | {
2025-10-02 14:19:42.741462 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-02 14:19:42.741483 | controller | }
2025-10-02 14:19:42.947355 | controller | changed:
2025-10-02 14:19:42.947431 | controller | {
2025-10-02 14:19:42.947459 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-02 14:19:42.947481 | controller | }
2025-10-02 14:19:43.156009 | controller | changed:
2025-10-02 14:19:43.156164 | controller | {
2025-10-02 14:19:43.156201 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-02 14:19:43.156225 | controller | }
2025-10-02 14:19:43.360142 | controller | changed:
2025-10-02 14:19:43.360218 | controller | {
2025-10-02 14:19:43.360245 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-02 14:19:43.360266 | controller | }
2025-10-02 14:19:43.571475 | controller | changed:
2025-10-02 14:19:43.571566 | controller | {
2025-10-02 14:19:43.571596 | 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-02 14:19:43.571619 | controller | }
2025-10-02 14:19:43.779850 | controller | changed:
2025-10-02 14:19:43.783424 | controller | {
2025-10-02 14:19:43.783499 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-02 14:19:43.783527 | controller | }
2025-10-02 14:19:43.986620 | controller | changed:
2025-10-02 14:19:43.986704 | controller | {
2025-10-02 14:19:43.986731 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-02 14:19:43.986752 | controller | }
2025-10-02 14:19:44.193016 | controller | changed:
2025-10-02 14:19:44.193096 | controller | {
2025-10-02 14:19:44.193138 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-02 14:19:44.193161 | controller | }
2025-10-02 14:19:44.210222 | 
2025-10-02 14:19:44.210306 | TASK [Set timezone to UTC]
2025-10-02 14:19:44.612239 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-02 14:19:44.617109 | 
2025-10-02 14:19:44.617187 | TASK [Create nodepool directory]
2025-10-02 14:19:44.820549 | controller | changed
2025-10-02 14:19:44.825997 | 
2025-10-02 14:19:44.826079 | TASK [Create nodepool sub_nodes file]
2025-10-02 14:19:45.358540 | controller | changed
2025-10-02 14:19:45.366402 | 
2025-10-02 14:19:45.366600 | TASK [Create nodepool sub_nodes_private file]
2025-10-02 14:19:45.933454 | controller | changed
2025-10-02 14:19:45.938624 | 
2025-10-02 14:19:45.938710 | LOOP [Populate nodepool sub_nodes file]
2025-10-02 14:19:45.994806 | 
2025-10-02 14:19:45.994969 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-02 14:19:46.055214 | 
2025-10-02 14:19:46.055376 | TASK [Create nodepool primary file]
2025-10-02 14:19:46.119423 | controller | skipping: Conditional result was False
2025-10-02 14:19:46.125392 | 
2025-10-02 14:19:46.125479 | TASK [Create nodepool node_private for this node]
2025-10-02 14:19:46.827334 | controller | changed
2025-10-02 14:19:46.835763 | 
2025-10-02 14:19:46.835837 | LOOP [Copy ssh keys to nodepool directory]
2025-10-02 14:19:47.215370 | controller | ok: Item: id_rsa Runtime: 0:00:00.006069
2025-10-02 14:19:47.215534 | 
2025-10-02 14:19:47.383345 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005107
2025-10-02 14:19:47.389451 | 
2025-10-02 14:19:47.389560 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-02 14:19:47.926680 | controller | changed
2025-10-02 14:19:47.934582 | 
2025-10-02 14:19:47.934688 | TASK [Validate sudoers config after edits]
2025-10-02 14:19:48.163729 | controller | /etc/sudoers: parsed OK
2025-10-02 14:19:48.163816 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-02 14:19:48.163827 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-02 14:19:48.163834 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-02 14:19:48.474469 | controller | ok: Runtime: 0:00:00.005265
2025-10-02 14:19:48.480361 | 
2025-10-02 14:19:48.480427 | TASK [Show the environment passed in to job shell scripts]
2025-10-02 14:19:48.702995 | controller | SHELL=/bin/bash
2025-10-02 14:19:48.703051 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-02 14:19:48.703061 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-02 14:19:48.703068 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/62/3362/41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 14:19:48.703136 | controller | PWD=/home/zuul
2025-10-02 14:19:48.703146 | controller | ZUUL_PIPELINE=github-check
2025-10-02 14:19:48.703151 | controller | LOGNAME=zuul
2025-10-02 14:19:48.703157 | controller | XDG_SESSION_TYPE=tty
2025-10-02 14:19:48.703162 | controller | _=/usr/bin/env
2025-10-02 14:19:48.703167 | controller | MOTD_SHOWN=pam
2025-10-02 14:19:48.703173 | controller | HOME=/home/zuul
2025-10-02 14:19:48.703178 | controller | LANG=en_US.UTF-8
2025-10-02 14:19:48.703183 | controller | SSH_CONNECTION=192.168.26.12 56808 192.168.26.198 22
2025-10-02 14:19:48.703189 | 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-02 14:19:48.703198 | controller | ZUUL_CHANGE_IDS=3362,41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 14:19:48.703203 | controller | WORKSPACE=/home/zuul/workspace
2025-10-02 14:19:48.703208 | controller | XDG_SESSION_CLASS=user
2025-10-02 14:19:48.703214 | controller | SELINUX_ROLE_REQUESTED=
2025-10-02 14:19:48.703219 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-02 14:19:48.703224 | controller | USER=zuul
2025-10-02 14:19:48.703229 | controller | ZUUL_VOTING=True
2025-10-02 14:19:48.703234 | controller | BUILD_TIMEOUT=1800000
2025-10-02 14:19:48.703240 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-02 14:19:48.703245 | controller | SHLVL=1
2025-10-02 14:19:48.703250 | controller | ZUUL_PATCHSET=41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 14:19:48.703255 | controller | XDG_SESSION_ID=1
2025-10-02 14:19:48.703261 | controller | ZUUL_BRANCH=main
2025-10-02 14:19:48.703266 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-02 14:19:48.703271 | controller | SSH_CLIENT=192.168.26.12 56808 22
2025-10-02 14:19:48.703276 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-02 14:19:48.703281 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-02 14:19:48.703287 | controller | which_declare=declare -f
2025-10-02 14:19:48.703293 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-02 14:19:48.703298 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-02 14:19:48.703303 | controller | ZUUL_CHANGE=3362
2025-10-02 14:19:48.703309 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-02 14:19:48.703314 | controller | ZUUL_UUID=97761fdad209433e9eec58cfdb1fe100
2025-10-02 14:19:48.703319 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-02 14:19:48.703324 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-02 14:19:48.703330 | controller | }
2025-10-02 14:19:49.023208 | controller | ok: Runtime: 0:00:00.006154
2025-10-02 14:19:49.028901 | 
2025-10-02 14:19:49.028978 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-02 14:19:49.056837 | controller | skipping: Conditional result was False
2025-10-02 14:19:49.063062 | 
2025-10-02 14:19:49.063146 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-02 14:19:49.591845 | controller | skipping: Conditional result was False
2025-10-02 14:19:49.598333 | 
2025-10-02 14:19:49.598413 | TASK [Ensure legacy workspace directory]
2025-10-02 14:19:49.782700 | controller | changed
2025-10-02 14:19:49.801108 | 
2025-10-02 14:19:49.801188 | PLAY RECAP
2025-10-02 14:19:49.801230 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-02 14:19:49.801253 | 
2025-10-02 14:19:49.875755 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-02 14:19:49.876516 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-02 14:19:50.395389 | 
2025-10-02 14:19:50.395492 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-02 14:19:50.422779 | 
2025-10-02 14:19:50.422855 | TASK [Create zuul-output directory]
2025-10-02 14:19:50.746014 | controller | changed
2025-10-02 14:19:50.753052 | 
2025-10-02 14:19:50.753205 | TASK [Slurp Zuul inventory test]
2025-10-02 14:19:51.072467 | controller -> localhost | ok
2025-10-02 14:19:51.078873 | 
2025-10-02 14:19:51.078980 | TASK [Save zuul inventory]
2025-10-02 14:19:51.879922 | controller | changed
2025-10-02 14:19:51.884996 | 
2025-10-02 14:19:51.885079 | TASK [Save zuul vars without the change_message]
2025-10-02 14:19:52.507484 | controller | changed
2025-10-02 14:19:52.533344 | 
2025-10-02 14:19:52.533430 | PLAY RECAP
2025-10-02 14:19:52.533473 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-02 14:19:52.533497 | 
2025-10-02 14:19:52.609416 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-02 14:19:52.610158 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-02 14:19:53.202505 | 
2025-10-02 14:19:53.202596 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-02 14:19:53.223211 | 
2025-10-02 14:19:53.223333 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-02 14:19:53.241630 | controller | ok
2025-10-02 14:19:53.258389 | 
2025-10-02 14:19:53.258480 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-02 14:19:53.292318 | controller | skipping: Conditional result was False
2025-10-02 14:19:53.298551 | 
2025-10-02 14:19:53.298658 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-02 14:19:53.630596 | controller | ok
2025-10-02 14:19:53.638071 | 
2025-10-02 14:19:53.638157 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-02 14:19:54.255222 | controller | ok
2025-10-02 14:19:54.265852 | 
2025-10-02 14:19:54.265942 | TASK [Prepare workspace]
2025-10-02 14:19:54.283649 | controller | ok
2025-10-02 14:19:54.300663 | 
2025-10-02 14:19:54.300735 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-02 14:19:54.604501 | controller | ok
2025-10-02 14:19:54.611233 | 
2025-10-02 14:19:54.611310 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-02 14:19:56.653456 | controller | Output suppressed because no_log was given
2025-10-02 14:19:56.662614 | 
2025-10-02 14:19:56.662686 | LOOP [Create zuul-output directory]
2025-10-02 14:19:56.847392 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-02 14:19:57.013457 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-02 14:19:57.028453 | 
2025-10-02 14:19:57.028554 | TASK [Install required packages]
2025-10-02 14:20:45.792821 | controller | changed
2025-10-02 14:20:45.798352 | 
2025-10-02 14:20:45.798424 | TASK [Install venv]
2025-10-02 14:21:46.965621 | controller | changed
2025-10-02 14:21:46.990018 | 
2025-10-02 14:21:46.990090 | PLAY RECAP
2025-10-02 14:21:46.990148 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-02 14:21:46.990173 | 
2025-10-02 14:21:47.063019 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-02 14:21:47.063822 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-02 14:21:47.585363 | 
2025-10-02 14:21:47.585460 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-02 14:21:47.605698 | 
2025-10-02 14:21:47.605768 | TASK [Gather required facts]
2025-10-02 14:21:48.100466 | controller | ok
2025-10-02 14:21:48.105752 | 
2025-10-02 14:21:48.105828 | TASK [Load environment var if instructed to]
2025-10-02 14:21:48.129839 | controller | skipping: Conditional result was False
2025-10-02 14:21:48.135237 | 
2025-10-02 14:21:48.135307 | TASK [Run molecule]
2025-10-02 14:21:49.074988 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-02 14:21:49.143461 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-02 14:22:05.967325 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/9d1eb3/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:22:05.967757 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/9d1eb3/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:22:05.968222 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/9d1eb3/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:22:05.968680 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/9d1eb3/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:22:05.969169 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+41f2edaa was found installed in /home/zuul/.cache/ansible-compat/9d1eb3/collections/ansible_collections, only the first one will be used, 1.0.0+41f2edaa (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:22:05.969629 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/9d1eb3/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:22:05.970062 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/9d1eb3/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:22:05.970513 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/9d1eb3/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:22:05.970928 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/9d1eb3/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:22:05.971373 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/9d1eb3/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:22:05.971823 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/9d1eb3/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:22:05.972271 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/9d1eb3/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:22:05.972713 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/9d1eb3/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:22:05.973144 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/9d1eb3/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:22:05.973614 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/9d1eb3/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:22:05.982693 | controller | INFO     Running default > prepare
2025-10-02 14:22:06.643584 | controller |
2025-10-02 14:22:06.643673 | controller | PLAY [Prepare] *****************************************************************
2025-10-02 14:22:06.643766 | controller |
2025-10-02 14:22:06.643869 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 14:22:06.643968 | controller | Thursday 02 October 2025  14:22:06 +0000 (0:00:00.011)       0:00:00.011 ******
2025-10-02 14:22:07.459855 | controller | ok: [instance]
2025-10-02 14:22:07.459928 | controller |
2025-10-02 14:22:07.460033 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-02 14:22:07.460142 | controller | Thursday 02 October 2025  14:22:07 +0000 (0:00:00.817)       0:00:00.829 ******
2025-10-02 14:22:07.477270 | controller | skipping: [instance]
2025-10-02 14:22:07.477384 | controller |
2025-10-02 14:22:07.477510 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-02 14:22:07.477672 | controller | Thursday 02 October 2025  14:22:07 +0000 (0:00:00.017)       0:00:00.846 ******
2025-10-02 14:22:07.518153 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-02 14:22:07.518245 | controller |
2025-10-02 14:22:07.518353 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-02 14:22:07.518449 | controller | Thursday 02 October 2025  14:22:07 +0000 (0:00:00.040)       0:00:00.887 ******
2025-10-02 14:22:07.797920 | controller | ok: [instance]
2025-10-02 14:22:07.798022 | controller |
2025-10-02 14:22:07.798175 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-02 14:22:07.798307 | controller | Thursday 02 October 2025  14:22:07 +0000 (0:00:00.279)       0:00:01.167 ******
2025-10-02 14:22:08.111651 | controller | ok: [instance]
2025-10-02 14:22:08.111724 | controller |
2025-10-02 14:22:08.111831 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-02 14:22:08.111931 | controller | Thursday 02 October 2025  14:22:08 +0000 (0:00:00.313)       0:00:01.480 ******
2025-10-02 14:22:08.789608 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-02 14:22:08.789638 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-02 14:22:08.789647 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-02 14:22:08.789653 | controller |
2025-10-02 14:22:08.789660 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-02 14:22:08.789751 | controller | Thursday 02 October 2025  14:22:08 +0000 (0:00:00.677)       0:00:02.158 ******
2025-10-02 14:22:08.847331 | controller |
2025-10-02 14:22:09.376291 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-02 14:22:09.376321 | controller | Thursday 02 October 2025  14:22:08 +0000 (0:00:00.057)       0:00:02.216 ******
2025-10-02 14:22:09.376333 | controller | changed: [instance] => (item=tmp)
2025-10-02 14:22:10.162887 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-02 14:22:10.162917 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-02 14:22:10.162926 | controller |
2025-10-02 14:22:10.162932 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-02 14:22:10.162938 | controller | Thursday 02 October 2025  14:22:09 +0000 (0:00:00.528)       0:00:02.745 ******
2025-10-02 14:22:10.162947 | controller | ok: [instance]
2025-10-02 14:22:11.310477 | controller |
2025-10-02 14:22:11.310507 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-02 14:22:11.310515 | controller | Thursday 02 October 2025  14:22:10 +0000 (0:00:00.786)       0:00:03.531 ******
2025-10-02 14:22:11.310539 | controller | changed: [instance]
2025-10-02 14:22:19.221089 | controller |
2025-10-02 14:22:19.221128 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-02 14:22:19.221139 | controller | Thursday 02 October 2025  14:22:11 +0000 (0:00:01.147)       0:00:04.679 ******
2025-10-02 14:22:19.221149 | controller | changed: [instance]
2025-10-02 14:22:19.941625 | controller |
2025-10-02 14:22:19.941661 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-02 14:22:19.941670 | controller | Thursday 02 October 2025  14:22:19 +0000 (0:00:07.910)       0:00:12.589 ******
2025-10-02 14:22:19.941681 | controller | changed: [instance]
2025-10-02 14:22:19.941804 | controller |
2025-10-02 14:22:19.941815 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-02 14:22:19.941823 | controller | Thursday 02 October 2025  14:22:19 +0000 (0:00:00.720)       0:00:13.310 ******
2025-10-02 14:22:19.962733 | controller | skipping: [instance]
2025-10-02 14:22:20.796401 | controller |
2025-10-02 14:22:20.796435 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-02 14:22:20.796443 | controller | Thursday 02 October 2025  14:22:19 +0000 (0:00:00.021)       0:00:13.331 ******
2025-10-02 14:22:20.796453 | controller | changed: [instance]
2025-10-02 14:22:20.832618 | controller |
2025-10-02 14:22:20.832649 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-02 14:22:20.832657 | controller | Thursday 02 October 2025  14:22:20 +0000 (0:00:00.833)       0:00:14.165 ******
2025-10-02 14:22:20.832667 | controller | skipping: [instance]
2025-10-02 14:22:20.832692 | controller |
2025-10-02 14:22:20.832940 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-02 14:22:20.868443 | controller | Thursday 02 October 2025  14:22:20 +0000 (0:00:00.036)       0:00:14.201 ******
2025-10-02 14:22:20.868477 | controller | skipping: [instance]
2025-10-02 14:22:20.903255 | controller |
2025-10-02 14:22:20.903272 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-02 14:22:20.903279 | controller | Thursday 02 October 2025  14:22:20 +0000 (0:00:00.035)       0:00:14.237 ******
2025-10-02 14:22:20.903287 | controller | skipping: [instance]
2025-10-02 14:22:21.471508 | controller |
2025-10-02 14:22:21.471610 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-02 14:22:21.471620 | controller | Thursday 02 October 2025  14:22:20 +0000 (0:00:00.035)       0:00:14.272 ******
2025-10-02 14:22:21.471630 | controller | changed: [instance]
2025-10-02 14:22:21.915251 | controller |
2025-10-02 14:22:21.915281 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-02 14:22:21.915289 | controller | Thursday 02 October 2025  14:22:21 +0000 (0:00:00.567)       0:00:14.840 ******
2025-10-02 14:22:21.915299 | controller | changed: [instance]
2025-10-02 14:22:21.943290 | controller |
2025-10-02 14:22:21.943313 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-02 14:22:21.943320 | controller | Thursday 02 October 2025  14:22:21 +0000 (0:00:00.443)       0:00:15.284 ******
2025-10-02 14:22:21.943330 | controller | skipping: [instance]
2025-10-02 14:22:21.971665 | controller |
2025-10-02 14:22:21.971692 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-02 14:22:21.971699 | controller | Thursday 02 October 2025  14:22:21 +0000 (0:00:00.028)       0:00:15.312 ******
2025-10-02 14:22:21.971709 | controller | skipping: [instance]
2025-10-02 14:22:22.000496 | controller |
2025-10-02 14:22:22.000523 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-02 14:22:22.000555 | controller | Thursday 02 October 2025  14:22:21 +0000 (0:00:00.028)       0:00:15.340 ******
2025-10-02 14:22:22.000571 | controller | skipping: [instance]
2025-10-02 14:22:22.037617 | controller |
2025-10-02 14:22:22.037653 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-02 14:22:22.037667 | controller | Thursday 02 October 2025  14:22:21 +0000 (0:00:00.028)       0:00:15.369 ******
2025-10-02 14:22:22.037677 | controller | ok: [instance]
2025-10-02 14:22:22.037703 | controller |
2025-10-02 14:22:22.037902 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-02 14:22:22.064634 | controller | Thursday 02 October 2025  14:22:22 +0000 (0:00:00.037)       0:00:15.406 ******
2025-10-02 14:22:22.064679 | controller | skipping: [instance]
2025-10-02 14:22:22.064710 | controller |
2025-10-02 14:22:22.064721 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-02 14:22:22.064804 | controller | Thursday 02 October 2025  14:22:22 +0000 (0:00:00.026)       0:00:15.433 ******
2025-10-02 14:22:22.090624 | controller | skipping: [instance]
2025-10-02 14:22:22.090834 | controller |
2025-10-02 14:22:22.090858 | controller | TASK [Download the RPM] ********************************************************
2025-10-02 14:22:22.118190 | controller | Thursday 02 October 2025  14:22:22 +0000 (0:00:00.026)       0:00:15.459 ******
2025-10-02 14:22:22.118225 | controller | skipping: [instance]
2025-10-02 14:22:22.163608 | controller |
2025-10-02 14:22:22.163640 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-02 14:22:22.163647 | controller | Thursday 02 October 2025  14:22:22 +0000 (0:00:00.027)       0:00:15.487 ******
2025-10-02 14:22:22.163657 | controller | skipping: [instance]
2025-10-02 14:22:22.189620 | controller |
2025-10-02 14:22:22.189653 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-02 14:22:22.189661 | controller | Thursday 02 October 2025  14:22:22 +0000 (0:00:00.045)       0:00:15.532 ******
2025-10-02 14:22:22.189671 | controller | skipping: [instance]
2025-10-02 14:22:22.189694 | controller |
2025-10-02 14:22:22.189702 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-02 14:22:22.189849 | controller | Thursday 02 October 2025  14:22:22 +0000 (0:00:00.026)       0:00:15.558 ******
2025-10-02 14:22:22.217951 | controller | skipping: [instance]
2025-10-02 14:22:22.243364 | controller |
2025-10-02 14:22:22.243398 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-02 14:22:22.243407 | controller | Thursday 02 October 2025  14:22:22 +0000 (0:00:00.027)       0:00:15.586 ******
2025-10-02 14:22:22.243423 | controller | skipping: [instance]
2025-10-02 14:22:22.427324 | controller |
2025-10-02 14:22:22.427358 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-02 14:22:22.427367 | controller | Thursday 02 October 2025  14:22:22 +0000 (0:00:00.025)       0:00:15.612 ******
2025-10-02 14:22:22.427376 | controller | ok: [instance]
2025-10-02 14:22:22.660583 | controller |
2025-10-02 14:22:22.660617 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-02 14:22:22.660625 | controller | Thursday 02 October 2025  14:22:22 +0000 (0:00:00.183)       0:00:15.796 ******
2025-10-02 14:22:22.660636 | controller | changed: [instance]
2025-10-02 14:22:22.886550 | controller |
2025-10-02 14:22:22.886583 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-02 14:22:22.886591 | controller | Thursday 02 October 2025  14:22:22 +0000 (0:00:00.233)       0:00:16.029 ******
2025-10-02 14:22:22.886602 | controller | changed: [instance]
2025-10-02 14:22:22.907670 | controller |
2025-10-02 14:22:22.907707 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-02 14:22:22.907716 | controller | Thursday 02 October 2025  14:22:22 +0000 (0:00:00.225)       0:00:16.255 ******
2025-10-02 14:22:22.907726 | controller | skipping: [instance]
2025-10-02 14:22:22.907751 | controller |
2025-10-02 14:22:22.907760 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-02 14:22:22.907855 | controller | Thursday 02 October 2025  14:22:22 +0000 (0:00:00.021)       0:00:16.277 ******
2025-10-02 14:22:22.929611 | controller | skipping: [instance]
2025-10-02 14:22:22.929800 | controller |
2025-10-02 14:22:22.929821 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-02 14:22:22.951624 | controller | Thursday 02 October 2025  14:22:22 +0000 (0:00:00.021)       0:00:16.299 ******
2025-10-02 14:22:22.951659 | controller | skipping: [instance]
2025-10-02 14:22:22.973151 | controller |
2025-10-02 14:22:22.973174 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-02 14:22:22.973182 | controller | Thursday 02 October 2025  14:22:22 +0000 (0:00:00.021)       0:00:16.320 ******
2025-10-02 14:22:22.973192 | controller | skipping: [instance]
2025-10-02 14:22:22.995124 | controller |
2025-10-02 14:22:22.995147 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-02 14:22:22.995159 | controller | Thursday 02 October 2025  14:22:22 +0000 (0:00:00.021)       0:00:16.342 ******
2025-10-02 14:22:22.995169 | controller | skipping: [instance]
2025-10-02 14:22:23.017104 | controller |
2025-10-02 14:22:23.017121 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-02 14:22:23.017135 | controller | Thursday 02 October 2025  14:22:22 +0000 (0:00:00.021)       0:00:16.364 ******
2025-10-02 14:22:23.017144 | controller | skipping: [instance]
2025-10-02 14:22:23.033061 | controller |
2025-10-02 14:22:23.033079 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-02 14:22:23.033086 | controller | Thursday 02 October 2025  14:22:23 +0000 (0:00:00.021)       0:00:16.386 ******
2025-10-02 14:22:23.033094 | controller | skipping: [instance]
2025-10-02 14:22:23.061500 | controller |
2025-10-02 14:22:23.061517 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-02 14:22:23.061524 | controller | Thursday 02 October 2025  14:22:23 +0000 (0:00:00.016)       0:00:16.402 ******
2025-10-02 14:22:23.061568 | controller | skipping: [instance]
2025-10-02 14:22:23.079991 | controller |
2025-10-02 14:22:23.080013 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-02 14:22:23.080020 | controller | Thursday 02 October 2025  14:22:23 +0000 (0:00:00.028)       0:00:16.430 ******
2025-10-02 14:22:23.080029 | controller | skipping: [instance]
2025-10-02 14:22:23.100182 | controller |
2025-10-02 14:22:23.100201 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-02 14:22:23.100210 | controller | Thursday 02 October 2025  14:22:23 +0000 (0:00:00.018)       0:00:16.449 ******
2025-10-02 14:22:23.100221 | controller | skipping: [instance]
2025-10-02 14:22:23.128077 | controller |
2025-10-02 14:22:23.128096 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-02 14:22:23.128102 | controller | Thursday 02 October 2025  14:22:23 +0000 (0:00:00.020)       0:00:16.469 ******
2025-10-02 14:22:23.128111 | controller | skipping: [instance]
2025-10-02 14:22:23.158158 | controller |
2025-10-02 14:22:23.158176 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-02 14:22:23.158183 | controller | Thursday 02 October 2025  14:22:23 +0000 (0:00:00.027)       0:00:16.497 ******
2025-10-02 14:22:23.158191 | controller | skipping: [instance]
2025-10-02 14:22:23.188413 | controller |
2025-10-02 14:22:23.188431 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-02 14:22:23.188438 | controller | Thursday 02 October 2025  14:22:23 +0000 (0:00:00.030)       0:00:16.527 ******
2025-10-02 14:22:23.188447 | controller | skipping: [instance]
2025-10-02 14:22:23.218663 | controller |
2025-10-02 14:22:23.218707 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-02 14:22:23.218717 | controller | Thursday 02 October 2025  14:22:23 +0000 (0:00:00.030)       0:00:16.557 ******
2025-10-02 14:22:23.218731 | controller | skipping: [instance]
2025-10-02 14:22:23.218753 | controller |
2025-10-02 14:22:23.218761 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-02 14:22:23.218770 | controller | Thursday 02 October 2025  14:22:23 +0000 (0:00:00.030)       0:00:16.587 ******
2025-10-02 14:23:09.983030 | controller | ok: [instance]
2025-10-02 14:23:09.983380 | controller |
2025-10-02 14:23:11.132337 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-02 14:23:11.132366 | controller | Thursday 02 October 2025  14:23:09 +0000 (0:00:46.764)       0:01:03.352 ******
2025-10-02 14:23:11.132379 | controller | ok: [instance]
2025-10-02 14:23:11.174603 | controller |
2025-10-02 14:23:11.174627 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-02 14:23:11.174648 | controller | Thursday 02 October 2025  14:23:11 +0000 (0:00:01.149)       0:01:04.501 ******
2025-10-02 14:23:11.174660 | controller | skipping: [instance]
2025-10-02 14:23:11.176606 | controller |
2025-10-02 14:23:11.176646 | controller | PLAY RECAP *********************************************************************
2025-10-02 14:23:11.176661 | controller | instance                   : ok=19   changed=9    unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-10-02 14:23:11.176791 | controller |
2025-10-02 14:23:11.176801 | controller | Thursday 02 October 2025  14:23:11 +0000 (0:00:00.042)       0:01:04.543 ******
2025-10-02 14:23:11.176807 | controller | ===============================================================================
2025-10-02 14:23:11.176812 | controller | test_deps : Install selinux python libs -------------------------------- 46.76s
2025-10-02 14:23:11.176824 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.91s
2025-10-02 14:23:11.176829 | controller | test_deps : Install python yaml libs ------------------------------------ 1.15s
2025-10-02 14:23:11.176835 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.15s
2025-10-02 14:23:11.176845 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.83s
2025-10-02 14:23:11.176851 | controller | Gathering Facts --------------------------------------------------------- 0.82s
2025-10-02 14:23:11.176857 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.79s
2025-10-02 14:23:11.176862 | controller | repo_setup : Install repo-setup package --------------------------------- 0.72s
2025-10-02 14:23:11.176867 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.68s
2025-10-02 14:23:11.176872 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s
2025-10-02 14:23:11.176877 | controller | repo_setup : Ensure directories are present ----------------------------- 0.53s
2025-10-02 14:23:11.176883 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.44s
2025-10-02 14:23:11.176888 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.31s
2025-10-02 14:23:11.176893 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s
2025-10-02 14:23:11.176899 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.23s
2025-10-02 14:23:11.176904 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.23s
2025-10-02 14:23:11.176910 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.18s
2025-10-02 14:23:11.176915 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-10-02 14:23:11.176923 | controller | repo_setup : Install RHOS Release tool ---------------------------------- 0.05s
2025-10-02 14:23:11.232130 | controller | test_deps : Install extra packages -------------------------------------- 0.04s
2025-10-02 14:23:11.232183 | controller | INFO     Running default > converge
2025-10-02 14:23:11.647132 | controller |
2025-10-02 14:23:12.437794 | controller | PLAY [Converge] ****************************************************************
2025-10-02 14:23:12.437823 | controller |
2025-10-02 14:23:12.437831 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 14:23:12.437837 | controller | Thursday 02 October 2025  14:23:11 +0000 (0:00:00.015)       0:00:00.015 ******
2025-10-02 14:23:12.437847 | controller | ok: [instance]
2025-10-02 14:23:12.454632 | controller |
2025-10-02 14:23:12.454681 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-02 14:23:12.454692 | controller | Thursday 02 October 2025  14:23:12 +0000 (0:00:00.791)       0:00:00.806 ******
2025-10-02 14:23:12.454704 | controller | skipping: [instance]
2025-10-02 14:23:12.454725 | controller |
2025-10-02 14:23:12.454734 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-02 14:23:12.454825 | controller | Thursday 02 October 2025  14:23:12 +0000 (0:00:00.017)       0:00:00.823 ******
2025-10-02 14:23:12.494086 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-02 14:23:12.776278 | controller |
2025-10-02 14:23:12.776313 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-02 14:23:12.776322 | controller | Thursday 02 October 2025  14:23:12 +0000 (0:00:00.039)       0:00:00.862 ******
2025-10-02 14:23:12.776332 | controller | ok: [instance]
2025-10-02 14:23:13.074218 | controller |
2025-10-02 14:23:13.074247 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-02 14:23:13.074255 | controller | Thursday 02 October 2025  14:23:12 +0000 (0:00:00.282)       0:00:01.144 ******
2025-10-02 14:23:13.074265 | controller | ok: [instance]
2025-10-02 14:23:13.746643 | controller |
2025-10-02 14:23:13.746672 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-02 14:23:13.746696 | controller | Thursday 02 October 2025  14:23:13 +0000 (0:00:00.297)       0:00:01.442 ******
2025-10-02 14:23:13.746714 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-02 14:23:13.805341 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-02 14:23:13.805368 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-02 14:23:13.805377 | controller |
2025-10-02 14:23:13.805385 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-02 14:23:13.805393 | controller | Thursday 02 October 2025  14:23:13 +0000 (0:00:00.672)       0:00:02.114 ******
2025-10-02 14:23:13.805406 | controller |
2025-10-02 14:23:14.342620 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-02 14:23:14.342659 | controller | Thursday 02 October 2025  14:23:13 +0000 (0:00:00.059)       0:00:02.173 ******
2025-10-02 14:23:14.342675 | controller | ok: [instance] => (item=tmp)
2025-10-02 14:23:14.342714 | controller | ok: [instance] => (item=artifacts/repositories)
2025-10-02 14:23:14.342723 | controller | ok: [instance] => (item=venv/repo_setup)
2025-10-02 14:23:14.342732 | controller |
2025-10-02 14:23:14.342929 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-02 14:23:15.592617 | controller | Thursday 02 October 2025  14:23:14 +0000 (0:00:00.537)       0:00:02.711 ******
2025-10-02 14:23:15.592652 | controller | ok: [instance]
2025-10-02 14:23:15.592948 | controller |
2025-10-02 14:23:15.592964 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-02 14:23:16.194131 | controller | Thursday 02 October 2025  14:23:15 +0000 (0:00:01.250)       0:00:03.961 ******
2025-10-02 14:23:16.194165 | controller | ok: [instance]
2025-10-02 14:23:17.473033 | controller |
2025-10-02 14:23:17.473066 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-02 14:23:17.473075 | controller | Thursday 02 October 2025  14:23:16 +0000 (0:00:00.601)       0:00:04.562 ******
2025-10-02 14:23:17.473085 | controller | ok: [instance]
2025-10-02 14:23:17.753122 | controller |
2025-10-02 14:23:17.753153 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-02 14:23:17.753161 | controller | Thursday 02 October 2025  14:23:17 +0000 (0:00:01.278)       0:00:05.841 ******
2025-10-02 14:23:17.753171 | controller | ok: [instance]
2025-10-02 14:23:17.774325 | controller |
2025-10-02 14:23:17.774347 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-02 14:23:17.774356 | controller | Thursday 02 October 2025  14:23:17 +0000 (0:00:00.280)       0:00:06.121 ******
2025-10-02 14:23:17.774367 | controller | skipping: [instance]
2025-10-02 14:23:18.646809 | controller |
2025-10-02 14:23:18.646838 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-02 14:23:18.646845 | controller | Thursday 02 October 2025  14:23:17 +0000 (0:00:00.021)       0:00:06.142 ******
2025-10-02 14:23:18.646855 | controller | changed: [instance]
2025-10-02 14:23:18.683311 | controller |
2025-10-02 14:23:18.683334 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-02 14:23:18.683344 | controller | Thursday 02 October 2025  14:23:18 +0000 (0:00:00.872)       0:00:07.015 ******
2025-10-02 14:23:18.683355 | controller | skipping: [instance]
2025-10-02 14:23:18.719207 | controller |
2025-10-02 14:23:18.719236 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-02 14:23:18.719249 | controller | Thursday 02 October 2025  14:23:18 +0000 (0:00:00.036)       0:00:07.051 ******
2025-10-02 14:23:18.719263 | controller | skipping: [instance]
2025-10-02 14:23:18.754544 | controller |
2025-10-02 14:23:18.754564 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-02 14:23:18.754575 | controller | Thursday 02 October 2025  14:23:18 +0000 (0:00:00.035)       0:00:07.087 ******
2025-10-02 14:23:18.754584 | controller | skipping: [instance]
2025-10-02 14:23:19.311126 | controller |
2025-10-02 14:23:19.311157 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-02 14:23:19.311165 | controller | Thursday 02 October 2025  14:23:18 +0000 (0:00:00.035)       0:00:07.123 ******
2025-10-02 14:23:19.311175 | controller | changed: [instance]
2025-10-02 14:23:19.660265 | controller |
2025-10-02 14:23:19.660298 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-02 14:23:19.660314 | controller | Thursday 02 October 2025  14:23:19 +0000 (0:00:00.556)       0:00:07.679 ******
2025-10-02 14:23:19.660325 | controller | ok: [instance]
2025-10-02 14:23:19.688317 | controller |
2025-10-02 14:23:19.688341 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-02 14:23:19.688349 | controller | Thursday 02 October 2025  14:23:19 +0000 (0:00:00.349)       0:00:08.028 ******
2025-10-02 14:23:19.688359 | controller | skipping: [instance]
2025-10-02 14:23:19.716782 | controller |
2025-10-02 14:23:19.716811 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-02 14:23:19.716820 | controller | Thursday 02 October 2025  14:23:19 +0000 (0:00:00.028)       0:00:08.056 ******
2025-10-02 14:23:19.716832 | controller | skipping: [instance]
2025-10-02 14:23:19.744915 | controller |
2025-10-02 14:23:19.744936 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-02 14:23:19.744943 | controller | Thursday 02 October 2025  14:23:19 +0000 (0:00:00.028)       0:00:08.085 ******
2025-10-02 14:23:19.744953 | controller | skipping: [instance]
2025-10-02 14:23:19.784226 | controller |
2025-10-02 14:23:19.784251 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-02 14:23:19.784260 | controller | Thursday 02 October 2025  14:23:19 +0000 (0:00:00.028)       0:00:08.113 ******
2025-10-02 14:23:19.784277 | controller | ok: [instance]
2025-10-02 14:23:19.811036 | controller |
2025-10-02 14:23:19.811058 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-02 14:23:19.811065 | controller | Thursday 02 October 2025  14:23:19 +0000 (0:00:00.039)       0:00:08.152 ******
2025-10-02 14:23:19.811075 | controller | skipping: [instance]
2025-10-02 14:23:19.838217 | controller |
2025-10-02 14:23:19.838237 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-02 14:23:19.838245 | controller | Thursday 02 October 2025  14:23:19 +0000 (0:00:00.026)       0:00:08.179 ******
2025-10-02 14:23:19.838254 | controller | skipping: [instance]
2025-10-02 14:23:19.864382 | controller |
2025-10-02 14:23:19.864407 | controller | TASK [Download the RPM] ********************************************************
2025-10-02 14:23:19.864417 | controller | Thursday 02 October 2025  14:23:19 +0000 (0:00:00.027)       0:00:08.206 ******
2025-10-02 14:23:19.864428 | controller | skipping: [instance]
2025-10-02 14:23:19.890517 | controller |
2025-10-02 14:23:19.890565 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-02 14:23:19.890574 | controller | Thursday 02 October 2025  14:23:19 +0000 (0:00:00.025)       0:00:08.232 ******
2025-10-02 14:23:19.890586 | controller | skipping: [instance]
2025-10-02 14:23:19.916748 | controller |
2025-10-02 14:23:19.916795 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-02 14:23:19.916803 | controller | Thursday 02 October 2025  14:23:19 +0000 (0:00:00.025)       0:00:08.258 ******
2025-10-02 14:23:19.916812 | controller | skipping: [instance]
2025-10-02 14:23:19.944372 | controller |
2025-10-02 14:23:19.944397 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-02 14:23:19.944406 | controller | Thursday 02 October 2025  14:23:19 +0000 (0:00:00.026)       0:00:08.285 ******
2025-10-02 14:23:19.944418 | controller | skipping: [instance]
2025-10-02 14:23:19.944621 | controller |
2025-10-02 14:23:19.944832 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-02 14:23:19.944979 | controller | Thursday 02 October 2025  14:23:19 +0000 (0:00:00.028)       0:00:08.313 ******
2025-10-02 14:23:19.970090 | controller | skipping: [instance]
2025-10-02 14:23:19.970280 | controller |
2025-10-02 14:23:19.970456 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-02 14:23:19.970659 | controller | Thursday 02 October 2025  14:23:19 +0000 (0:00:00.025)       0:00:08.338 ******
2025-10-02 14:23:20.152500 | controller | ok: [instance]
2025-10-02 14:23:20.370597 | controller |
2025-10-02 14:23:20.370627 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-02 14:23:20.370636 | controller | Thursday 02 October 2025  14:23:20 +0000 (0:00:00.181)       0:00:08.520 ******
2025-10-02 14:23:20.370646 | controller | changed: [instance]
2025-10-02 14:23:20.370669 | controller |
2025-10-02 14:23:20.370676 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-02 14:23:20.370689 | controller | Thursday 02 October 2025  14:23:20 +0000 (0:00:00.218)       0:00:08.739 ******
2025-10-02 14:23:20.597764 | controller | changed: [instance]
2025-10-02 14:23:20.618502 | controller |
2025-10-02 14:23:20.618554 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-02 14:23:20.618567 | controller | Thursday 02 October 2025  14:23:20 +0000 (0:00:00.226)       0:00:08.966 ******
2025-10-02 14:23:20.618578 | controller | skipping: [instance]
2025-10-02 14:23:20.639995 | controller |
2025-10-02 14:23:20.640013 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-02 14:23:20.640020 | controller | Thursday 02 October 2025  14:23:20 +0000 (0:00:00.021)       0:00:08.987 ******
2025-10-02 14:23:20.640029 | controller | skipping: [instance]
2025-10-02 14:23:20.662425 | controller |
2025-10-02 14:23:20.662442 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-02 14:23:20.662449 | controller | Thursday 02 October 2025  14:23:20 +0000 (0:00:00.021)       0:00:09.008 ******
2025-10-02 14:23:20.662458 | controller | skipping: [instance]
2025-10-02 14:23:20.684276 | controller |
2025-10-02 14:23:20.684294 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-02 14:23:20.684303 | controller | Thursday 02 October 2025  14:23:20 +0000 (0:00:00.022)       0:00:09.031 ******
2025-10-02 14:23:20.684311 | controller | skipping: [instance]
2025-10-02 14:23:20.705866 | controller |
2025-10-02 14:23:20.705887 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-02 14:23:20.705895 | controller | Thursday 02 October 2025  14:23:20 +0000 (0:00:00.021)       0:00:09.052 ******
2025-10-02 14:23:20.705906 | controller | skipping: [instance]
2025-10-02 14:23:20.727067 | controller |
2025-10-02 14:23:20.727086 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-02 14:23:20.727094 | controller | Thursday 02 October 2025  14:23:20 +0000 (0:00:00.021)       0:00:09.074 ******
2025-10-02 14:23:20.727104 | controller | skipping: [instance]
2025-10-02 14:23:20.743228 | controller |
2025-10-02 14:23:20.743247 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-02 14:23:20.743254 | controller | Thursday 02 October 2025  14:23:20 +0000 (0:00:00.021)       0:00:09.095 ******
2025-10-02 14:23:20.743262 | controller | skipping: [instance]
2025-10-02 14:23:20.771282 | controller |
2025-10-02 14:23:20.771302 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-02 14:23:20.771315 | controller | Thursday 02 October 2025  14:23:20 +0000 (0:00:00.016)       0:00:09.111 ******
2025-10-02 14:23:20.771324 | controller | skipping: [instance]
2025-10-02 14:23:20.787645 | controller |
2025-10-02 14:23:20.787680 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-02 14:23:20.787692 | controller | Thursday 02 October 2025  14:23:20 +0000 (0:00:00.028)       0:00:09.139 ******
2025-10-02 14:23:20.787704 | controller | skipping: [instance]
2025-10-02 14:23:20.787736 | controller |
2025-10-02 14:23:20.787746 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-02 14:23:20.787871 | controller | Thursday 02 October 2025  14:23:20 +0000 (0:00:00.016)       0:00:09.156 ******
2025-10-02 14:23:20.808613 | controller | skipping: [instance]
2025-10-02 14:23:20.837269 | controller |
2025-10-02 14:23:20.837293 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-02 14:23:20.837301 | controller | Thursday 02 October 2025  14:23:20 +0000 (0:00:00.020)       0:00:09.177 ******
2025-10-02 14:23:20.837310 | controller | skipping: [instance]
2025-10-02 14:23:20.869131 | controller |
2025-10-02 14:23:20.869151 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-02 14:23:20.869158 | controller | Thursday 02 October 2025  14:23:20 +0000 (0:00:00.028)       0:00:09.205 ******
2025-10-02 14:23:20.869167 | controller | skipping: [instance]
2025-10-02 14:23:20.898160 | controller |
2025-10-02 14:23:20.898183 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-02 14:23:20.898192 | controller | Thursday 02 October 2025  14:23:20 +0000 (0:00:00.031)       0:00:09.237 ******
2025-10-02 14:23:20.898207 | controller | skipping: [instance]
2025-10-02 14:23:20.927119 | controller |
2025-10-02 14:23:20.927141 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-02 14:23:20.927150 | controller | Thursday 02 October 2025  14:23:20 +0000 (0:00:00.028)       0:00:09.266 ******
2025-10-02 14:23:20.927161 | controller | skipping: [instance]
2025-10-02 14:24:06.648621 | controller |
2025-10-02 14:24:06.648654 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-02 14:24:06.648663 | controller | Thursday 02 October 2025  14:23:20 +0000 (0:00:00.028)       0:00:09.295 ******
2025-10-02 14:24:06.648672 | controller | ok: [instance]
2025-10-02 14:24:06.648699 | controller |
2025-10-02 14:24:06.648708 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-02 14:24:06.648865 | controller | Thursday 02 October 2025  14:24:06 +0000 (0:00:45.721)       0:00:55.017 ******
2025-10-02 14:24:07.787111 | controller | ok: [instance]
2025-10-02 14:24:07.829573 | controller |
2025-10-02 14:24:07.829597 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-02 14:24:07.829606 | controller | Thursday 02 October 2025  14:24:07 +0000 (0:00:01.138)       0:00:56.155 ******
2025-10-02 14:24:07.829615 | controller | skipping: [instance]
2025-10-02 14:24:07.831122 | controller |
2025-10-02 14:24:07.831138 | controller | PLAY RECAP *********************************************************************
2025-10-02 14:24:07.831146 | controller | instance                   : ok=19   changed=4    unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-10-02 14:24:07.831152 | controller |
2025-10-02 14:24:07.831157 | controller | Thursday 02 October 2025  14:24:07 +0000 (0:00:00.041)       0:00:56.197 ******
2025-10-02 14:24:07.831163 | controller | ===============================================================================
2025-10-02 14:24:07.831168 | controller | test_deps : Install selinux python libs -------------------------------- 45.72s
2025-10-02 14:24:07.831173 | controller | repo_setup : Initialize python venv and install requirements ------------ 1.28s
2025-10-02 14:24:07.831178 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.25s
2025-10-02 14:24:07.831184 | controller | test_deps : Install python yaml libs ------------------------------------ 1.14s
2025-10-02 14:24:07.831189 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.87s
2025-10-02 14:24:07.831197 | controller | Gathering Facts --------------------------------------------------------- 0.79s
2025-10-02 14:24:07.885165 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.67s
2025-10-02 14:24:07.885186 | controller | repo_setup : Get repo-setup repository ---------------------------------- 0.60s
2025-10-02 14:24:07.885193 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.56s
2025-10-02 14:24:07.885203 | controller | repo_setup : Ensure directories are present ----------------------------- 0.54s
2025-10-02 14:24:07.885208 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.35s
2025-10-02 14:24:07.885214 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.30s
2025-10-02 14:24:07.885219 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s
2025-10-02 14:24:07.885224 | controller | repo_setup : Install repo-setup package --------------------------------- 0.28s
2025-10-02 14:24:07.885229 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.23s
2025-10-02 14:24:07.885235 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.22s
2025-10-02 14:24:07.885240 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.18s
2025-10-02 14:24:07.885246 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-10-02 14:24:07.885251 | controller | test_deps : Install extra packages -------------------------------------- 0.04s
2025-10-02 14:24:07.885256 | controller | repo_setup : Export hashes facts for further use ------------------------ 0.04s
2025-10-02 14:24:07.885264 | controller | INFO     Running default > cleanup
2025-10-02 14:24:07.885732 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-02 14:24:07.886336 | controller | INFO     Writing /tmp/report.html report.
2025-10-02 14:24:08.321476 | controller | changed
2025-10-02 14:24:08.341833 | 
2025-10-02 14:24:08.341921 | PLAY RECAP
2025-10-02 14:24:08.341976 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-02 14:24:08.342006 | 
2025-10-02 14:24:08.411242 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-02 14:24:08.412138 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-02 14:24:08.938703 | 
2025-10-02 14:24:08.938814 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-02 14:24:08.959103 | 
2025-10-02 14:24:08.959185 | TASK [Filter out host if needed]
2025-10-02 14:24:08.967733 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-02 14:24:08.972201 | 
2025-10-02 14:24:08.972270 | TASK [Ensure file is present]
2025-10-02 14:24:09.284497 | controller | ok
2025-10-02 14:24:09.291477 | 
2025-10-02 14:24:09.291549 | TASK [Manage molecule report file]
2025-10-02 14:24:09.816763 | controller | changed
2025-10-02 14:24:09.821998 | 
2025-10-02 14:24:09.822064 | TASK [Check if we get ci-framework-data basedir]
2025-10-02 14:24:10.013431 | controller | ok
2025-10-02 14:24:10.018701 | 
2025-10-02 14:24:10.018848 | TASK [Create ci-framework-data log directory for zuul]
2025-10-02 14:24:10.332047 | controller | changed
2025-10-02 14:24:10.337530 | 
2025-10-02 14:24:10.337639 | TASK [Copy ci-framework interesting files]
2025-10-02 14:24:10.531128 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-02 14:24:10.861661 | controller | changed
2025-10-02 14:24:10.867558 | 
2025-10-02 14:24:10.867632 | TASK [Get SELinux listing]
2025-10-02 14:24:11.390869 | controller | changed
2025-10-02 14:24:11.397198 | 
2025-10-02 14:24:11.397291 | TASK [Generate log index]
2025-10-02 14:24:12.024305 | controller | changed
2025-10-02 14:24:12.029311 | 
2025-10-02 14:24:12.029378 | TASK [Get some env related data]
2025-10-02 14:24:12.563604 | controller | /home/zuul/.local/bin/ansible
2025-10-02 14:24:13.553834 | controller | changed
2025-10-02 14:24:13.559280 | 
2025-10-02 14:24:13.559348 | TASK [Generate list of logs to collect in home directory]
2025-10-02 14:24:13.851854 | controller | ok: All paths examined
2025-10-02 14:24:13.857019 | 
2025-10-02 14:24:13.857090 | LOOP [Copy logs from home directory]
2025-10-02 14:24:14.162819 | controller | changed:
2025-10-02 14:24:14.162953 | controller | {
2025-10-02 14:24:14.162984 | controller |   "atime": 1759414890.185967,
2025-10-02 14:24:14.163005 | controller |   "ctime": 1759414906.5499177,
2025-10-02 14:24:14.163022 | controller |   "dev": 64513,
2025-10-02 14:24:14.163039 | controller |   "gid": 1000,
2025-10-02 14:24:14.163055 | controller |   "gr_name": "zuul",
2025-10-02 14:24:14.163071 | controller |   "inode": 4452447,
2025-10-02 14:24:14.163087 | controller |   "isblk": false,
2025-10-02 14:24:14.163113 | controller |   "ischr": false,
2025-10-02 14:24:14.163129 | controller |   "isdir": false,
2025-10-02 14:24:14.163143 | controller |   "isfifo": false,
2025-10-02 14:24:14.163158 | controller |   "isgid": false,
2025-10-02 14:24:14.163172 | controller |   "islnk": false,
2025-10-02 14:24:14.163186 | controller |   "isreg": true,
2025-10-02 14:24:14.163199 | controller |   "issock": false,
2025-10-02 14:24:14.163213 | controller |   "isuid": false,
2025-10-02 14:24:14.163226 | controller |   "mode": "0644",
2025-10-02 14:24:14.163239 | controller |   "mtime": 1759414906.5499177,
2025-10-02 14:24:14.163252 | controller |   "nlink": 1,
2025-10-02 14:24:14.163265 | controller |   "path": "/home/zuul/ansible.log",
2025-10-02 14:24:14.163280 | controller |   "pw_name": "zuul",
2025-10-02 14:24:14.163294 | controller |   "rgrp": true,
2025-10-02 14:24:14.163308 | controller |   "roth": true,
2025-10-02 14:24:14.163322 | controller |   "rusr": true,
2025-10-02 14:24:14.163336 | controller |   "size": 6749,
2025-10-02 14:24:14.163350 | controller |   "uid": 1000,
2025-10-02 14:24:14.163364 | controller |   "wgrp": false,
2025-10-02 14:24:14.163378 | controller |   "woth": false,
2025-10-02 14:24:14.163394 | controller |   "wusr": true,
2025-10-02 14:24:14.163409 | controller |   "xgrp": false,
2025-10-02 14:24:14.163423 | controller |   "xoth": false,
2025-10-02 14:24:14.163436 | controller |   "xusr": false
2025-10-02 14:24:14.163450 | controller | }
2025-10-02 14:24:14.175949 | 
2025-10-02 14:24:14.176011 | TASK [Copy crio stats log file]
2025-10-02 14:24:14.200116 | controller | skipping: Conditional result was False
2025-10-02 14:24:14.205317 | 
2025-10-02 14:24:14.205380 | TASK [Get SELinux related data]
2025-10-02 14:24:14.409555 | controller | <no matches>
2025-10-02 14:24:14.729803 | controller | ERROR
2025-10-02 14:24:14.730077 | controller | {
2025-10-02 14:24:14.730128 | controller |   "delta": "0:00:00.008917",
2025-10-02 14:24:14.730152 | controller |   "end": "2025-10-02 14:24:14.410664",
2025-10-02 14:24:14.730170 | controller |   "msg": "non-zero return code",
2025-10-02 14:24:14.730187 | controller |   "rc": 1,
2025-10-02 14:24:14.730203 | controller |   "start": "2025-10-02 14:24:14.401747"
2025-10-02 14:24:14.730217 | controller | }
2025-10-02 14:24:14.730239 | controller | ERROR: Ignoring Errors
2025-10-02 14:24:14.735729 | 
2025-10-02 14:24:14.735794 | TASK [Create system configuration directory]
2025-10-02 14:24:14.927008 | controller | changed
2025-10-02 14:24:14.932009 | 
2025-10-02 14:24:14.932076 | TASK [Get some of the system configurations]
2025-10-02 14:24:15.456004 | controller | changed
2025-10-02 14:24:15.461766 | 
2025-10-02 14:24:15.461850 | TASK [Copy generated documentation if available]
2025-10-02 14:24:15.477257 | controller | skipping: Conditional result was False
2025-10-02 14:24:15.483130 | 
2025-10-02 14:24:15.483197 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-02 14:24:15.507080 | controller | skipping: Conditional result was False
2025-10-02 14:24:15.512934 | 
2025-10-02 14:24:15.513001 | TASK [Compress logs bigger than 2MB]
2025-10-02 14:24:16.039017 | controller | changed
2025-10-02 14:24:16.044686 | 
2025-10-02 14:24:16.044748 | TASK [Copy files from workspace on node]
2025-10-02 14:24:16.063380 | controller | ok
2025-10-02 14:24:16.085476 | 
2025-10-02 14:24:16.085549 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-02 14:24:16.099582 | controller | skipping: Conditional result was False
2025-10-02 14:24:16.105366 | 
2025-10-02 14:24:16.105434 | TASK [fetch-output : Set log path for single node]
2025-10-02 14:24:16.133734 | controller | ok
2025-10-02 14:24:16.139846 | 
2025-10-02 14:24:16.139928 | LOOP [fetch-output : Ensure local output dirs]
2025-10-02 14:24:16.352272 | controller -> localhost | ok: "/var/lib/zuul/builds/97761fdad209433e9eec58cfdb1fe100/work/logs"
2025-10-02 14:24:16.352608 | controller -> localhost | changed: All items complete
2025-10-02 14:24:16.352643 | 
2025-10-02 14:24:16.542667 | controller -> localhost | changed: "/var/lib/zuul/builds/97761fdad209433e9eec58cfdb1fe100/work/artifacts"
2025-10-02 14:24:16.727089 | controller -> localhost | changed: "/var/lib/zuul/builds/97761fdad209433e9eec58cfdb1fe100/work/docs"
2025-10-02 14:24:16.738747 | 
2025-10-02 14:24:16.738827 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-02 14:24:17.277366 | controller | changed:
2025-10-02 14:24:17.277555 | controller | .d..t...... ./
2025-10-02 14:24:17.277587 | controller | >f+++++++++ README.html
2025-10-02 14:24:17.277610 | controller | >f+++++++++ ansible-execution.log
2025-10-02 14:24:17.277632 | controller | >f+++++++++ ansible.log
2025-10-02 14:24:17.277653 | controller | >f+++++++++ dmesg.log
2025-10-02 14:24:17.277673 | controller | >f+++++++++ installed-pkgs.log
2025-10-02 14:24:17.277695 | controller | >f+++++++++ python.log
2025-10-02 14:24:17.277717 | controller | >f+++++++++ registries.conf
2025-10-02 14:24:17.277736 | controller | >f+++++++++ report.html
2025-10-02 14:24:17.277758 | controller | >f+++++++++ selinux-denials.log
2025-10-02 14:24:17.277777 | controller | >f+++++++++ selinux-listing.log
2025-10-02 14:24:17.277795 | controller | cd+++++++++ ci-framework-data/
2025-10-02 14:24:17.277813 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-02 14:24:17.277831 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-02 14:24:17.277849 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-02 14:24:17.277867 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-02 14:24:17.277979 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-02 14:24:17.278019 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-02 14:24:17.278043 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-02 14:24:17.278063 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-02 14:24:17.278081 | controller | cd+++++++++ registries.conf.d/
2025-10-02 14:24:17.278098 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-02 14:24:17.278131 | controller | cd+++++++++ system-config/
2025-10-02 14:24:17.278150 | controller | cd+++++++++ system-config/libvirt/
2025-10-02 14:24:17.278167 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-02 14:24:17.278184 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-02 14:24:17.639555 | controller | changed: .d..t...... ./
2025-10-02 14:24:17.996066 | controller | changed: .d..t...... ./
2025-10-02 14:24:18.011521 | 
2025-10-02 14:24:18.011656 | TASK [Return artifact to Zuul]
2025-10-02 14:24:18.040592 | controller | ok
2025-10-02 14:24:18.059675 | 
2025-10-02 14:24:18.059737 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-02 14:24:18.059835 | 
2025-10-02 14:24:18.059862 | PLAY RECAP
2025-10-02 14:24:18.059902 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-02 14:24:18.059948 | 
2025-10-02 14:24:18.148091 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-02 14:24:18.148942 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-02 14:24:18.678266 | 
2025-10-02 14:24:18.678391 | PLAY [all]
2025-10-02 14:24:18.697529 | 
2025-10-02 14:24:18.697603 | TASK [include_role : fetch-output]
2025-10-02 14:24:18.726055 | controller | ok
2025-10-02 14:24:18.742479 | 
2025-10-02 14:24:18.742562 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-02 14:24:18.786852 | controller | skipping: Conditional result was False
2025-10-02 14:24:18.792597 | 
2025-10-02 14:24:18.792668 | TASK [fetch-output : Set log path for single node]
2025-10-02 14:24:18.821538 | controller | ok
2025-10-02 14:24:18.826555 | 
2025-10-02 14:24:18.826624 | LOOP [fetch-output : Ensure local output dirs]
2025-10-02 14:24:19.176092 | controller -> localhost | ok: "/var/lib/zuul/builds/97761fdad209433e9eec58cfdb1fe100/work/logs"
2025-10-02 14:24:19.366450 | controller -> localhost | ok: "/var/lib/zuul/builds/97761fdad209433e9eec58cfdb1fe100/work/artifacts"
2025-10-02 14:24:19.555606 | controller -> localhost | ok: "/var/lib/zuul/builds/97761fdad209433e9eec58cfdb1fe100/work/docs"
2025-10-02 14:24:19.569231 | 
2025-10-02 14:24:19.569371 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-02 14:24:20.091484 | controller | ok
2025-10-02 14:24:20.091719 | controller | ok: All items complete
2025-10-02 14:24:20.091749 | 
2025-10-02 14:24:20.468339 | controller | ok
2025-10-02 14:24:20.843268 | controller | ok
2025-10-02 14:24:20.863480 | 
2025-10-02 14:24:20.863575 | TASK [include_role : fetch-output-openshift]
2025-10-02 14:24:20.877280 | controller | skipping: Conditional result was False
2025-10-02 14:24:20.882879 | 
2025-10-02 14:24:20.882964 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-02 14:24:21.242974 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007414
2025-10-02 14:24:21.438299 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006346
2025-10-02 14:24:21.468259 | 
2025-10-02 14:24:21.468329 | PLAY [all]
2025-10-02 14:24:21.481784 | 
2025-10-02 14:24:21.481852 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-02 14:24:21.886403 | controller | changed
2025-10-02 14:24:21.907929 | 
2025-10-02 14:24:21.907986 | PLAY RECAP
2025-10-02 14:24:21.908031 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-02 14:24:21.908060 | 
2025-10-02 14:24:21.976798 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-02 14:24:21.977836 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-02 14:24:22.532253 | 
2025-10-02 14:24:22.532394 | PLAY [localhost]
2025-10-02 14:24:22.549762 | 
2025-10-02 14:24:22.549836 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-02 14:24:22.871487 | localhost | changed
2025-10-02 14:24:22.876111 | 
2025-10-02 14:24:22.876194 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-02 14:24:22.903496 | localhost | ok
2025-10-02 14:24:22.911557 | 
2025-10-02 14:24:22.911630 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-02 14:24:23.211726 | localhost | changed
2025-10-02 14:24:23.217424 | 
2025-10-02 14:24:23.217495 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-02 14:24:23.771875 | localhost | changed
2025-10-02 14:24:23.777163 | 
2025-10-02 14:24:23.777258 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-02 14:24:24.108820 | localhost | Identity added: /var/lib/zuul/builds/97761fdad209433e9eec58cfdb1fe100/work/tmp/ansible.sa5m7w61 (/var/lib/zuul/builds/97761fdad209433e9eec58cfdb1fe100/work/tmp/ansible.sa5m7w61)
2025-10-02 14:24:24.109079 | localhost | ok: Runtime: 0:00:00.006272
2025-10-02 14:24:24.113340 | 
2025-10-02 14:24:24.113410 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-02 14:24:24.333773 | localhost | ok: Runtime: 0:00:00.004303
2025-10-02 14:24:24.338444 | 
2025-10-02 14:24:24.338524 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-02 14:24:24.386494 | localhost | changed
2025-10-02 14:24:24.391536 | 
2025-10-02 14:24:24.391610 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-02 14:24:24.712342 | localhost | changed
2025-10-02 14:24:24.731676 | 
2025-10-02 14:24:24.731735 | PLAY [localhost]
2025-10-02 14:24:24.742858 | 
2025-10-02 14:24:24.742938 | TASK [Generate bulk log download script]
2025-10-02 14:24:24.760861 | localhost | ok
2025-10-02 14:24:24.772086 | 
2025-10-02 14:24:24.772167 | TASK [local-log-download : Check API endpoint is defined]
2025-10-02 14:24:24.799326 | localhost | ok: All assertions passed
2025-10-02 14:24:24.803678 | 
2025-10-02 14:24:24.803744 | TASK [local-log-download : Create download script]
2025-10-02 14:24:25.150895 | localhost -> localhost | changed
2025-10-02 14:24:25.159556 | 
2025-10-02 14:24:25.159627 | TASK [Register quick-download link]
2025-10-02 14:24:25.176406 | localhost | ok
2025-10-02 14:24:25.210750 | 
2025-10-02 14:24:25.210822 | PLAY [logserver.rdoproject.org]
2025-10-02 14:24:25.219885 | 
2025-10-02 14:24:25.219974 | TASK [Set zuul-log-path fact]
2025-10-02 14:24:25.235508 | logserver.rdoproject.org | ok
2025-10-02 14:24:25.244312 | 
2025-10-02 14:24:25.244380 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-02 14:24:25.270284 | logserver.rdoproject.org | ok
2025-10-02 14:24:25.275691 | 
2025-10-02 14:24:25.275756 | TASK [upload-logs : Create log directories]
2025-10-02 14:24:27.351342 | logserver.rdoproject.org | changed
2025-10-02 14:24:27.354636 | 
2025-10-02 14:24:27.354717 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-02 14:24:27.571957 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004134
2025-10-02 14:24:27.576814 | 
2025-10-02 14:24:27.576885 | TASK [upload-logs : Upload logs to log server]
2025-10-02 14:24:29.512339 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-02 14:24:29.515273 | 
2025-10-02 14:24:29.515339 | LOOP [upload-logs : Compress console log and json output]
2025-10-02 14:24:29.551657 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 14:24:29.560316 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 14:24:29.571697 | 
2025-10-02 14:24:29.571768 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-02 14:24:29.606421 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 14:24:29.606614 | 
2025-10-02 14:24:29.610132 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 14:24:29.618037 | 
2025-10-02 14:24:29.618121 | LOOP [upload-logs : Upload console log and json output]