2025-10-02 15:09:40.030664 | Job console starting...
2025-10-02 15:09:40.256679 | Updating repositories
2025-10-02 15:09:41.159116 | Preparing job workspace
2025-10-02 15:09:58.717506 | Running Ansible setup...
2025-10-02 15:10:05.882764 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-02 15:10:08.146694 | 
2025-10-02 15:10:08.146814 | PLAY [localhost]
2025-10-02 15:10:08.179184 | 
2025-10-02 15:10:08.179268 | TASK [Gathering Facts]
2025-10-02 15:10:11.312503 | localhost | ok
2025-10-02 15:10:11.348826 | 
2025-10-02 15:10:11.348942 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-02 15:10:12.188756 | localhost -> localhost | changed
2025-10-02 15:10:12.220768 | 
2025-10-02 15:10:12.220902 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-02 15:10:13.652895 | localhost -> localhost | changed
2025-10-02 15:10:13.665613 | 
2025-10-02 15:10:13.665719 | TASK [Setup log path fact]
2025-10-02 15:10:13.706901 | localhost | ok
2025-10-02 15:10:13.722894 | 
2025-10-02 15:10:13.722978 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-02 15:10:13.774953 | localhost | ok
2025-10-02 15:10:13.784317 | 
2025-10-02 15:10:13.786469 | TASK [emit-job-header : Print job information]
2025-10-02 15:10:13.898294 | # Job Information
2025-10-02 15:10:13.898483 | Ansible Version: 2.15.12
2025-10-02 15:10:13.898516 | Job: cifmw-molecule-openshift_provisioner_node
2025-10-02 15:10:13.898540 | Pipeline: github-check
2025-10-02 15:10:13.898561 | Executor: ibm-bm3-ze.softwarefactory-project.io
2025-10-02 15:10:13.898580 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3362
2025-10-02 15:10:13.898599 | Log URL (when completed): https://logserver.rdoproject.org/b34/rdoproject.org/b34d8e63349847dc98b841f857d95291/
2025-10-02 15:10:13.898619 | Event ID: 854a5e80-9fa1-11f0-891b-738b4b5da685
2025-10-02 15:10:13.903944 | 
2025-10-02 15:10:13.904015 | LOOP [emit-job-header : Print node information]
2025-10-02 15:10:14.104850 | localhost | ok:
2025-10-02 15:10:14.105090 | localhost | # Node Information
2025-10-02 15:10:14.105128 | localhost | Inventory Hostname: controller
2025-10-02 15:10:14.105156 | localhost | Hostname: np0005466561
2025-10-02 15:10:14.105177 | localhost | Username: zuul
2025-10-02 15:10:14.105198 | localhost | Distro: CentOS 9
2025-10-02 15:10:14.105216 | localhost | Provider: ibm-bm3-nodepool
2025-10-02 15:10:14.105234 | localhost | Region: regionOne
2025-10-02 15:10:14.105252 | localhost | Label: centos-9-stream-crc-2-48-0-xl-ibm
2025-10-02 15:10:14.105270 | localhost | Product Name: OpenStack Compute
2025-10-02 15:10:14.105288 | localhost | Interface IP: 192.168.25.139
2025-10-02 15:10:14.159856 | 
2025-10-02 15:10:14.159946 | PLAY [all]
2025-10-02 15:10:14.174649 | 
2025-10-02 15:10:14.174730 | TASK [Gather network facts]
2025-10-02 15:10:14.685289 | controller | ok
2025-10-02 15:10:14.738203 | 
2025-10-02 15:10:14.738306 | TASK [include_role : start-zuul-console]
2025-10-02 15:10:14.778616 | controller | ok
2025-10-02 15:10:14.807839 | 
2025-10-02 15:10:14.807952 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-02 15:10:15.460610 | controller | ok
2025-10-02 15:10:15.470108 | 
2025-10-02 15:10:15.470171 | TASK [include_role : add-build-sshkey]
2025-10-02 15:10:15.533090 | controller | ok
2025-10-02 15:10:15.574415 | 
2025-10-02 15:10:15.574514 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-02 15:10:16.317883 | controller -> localhost | ok
2025-10-02 15:10:16.324413 | 
2025-10-02 15:10:16.324489 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-02 15:10:16.376030 | controller | ok
2025-10-02 15:10:16.388274 | controller | included: /var/lib/zuul/builds/b34d8e63349847dc98b841f857d95291/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-02 15:10:16.396339 | 
2025-10-02 15:10:16.396507 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-02 15:10:17.516396 | controller -> localhost | Generating public/private rsa key pair.
2025-10-02 15:10:17.516561 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b34d8e63349847dc98b841f857d95291/work/b34d8e63349847dc98b841f857d95291_id_rsa.
2025-10-02 15:10:17.516595 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b34d8e63349847dc98b841f857d95291/work/b34d8e63349847dc98b841f857d95291_id_rsa.pub.
2025-10-02 15:10:17.516620 | controller -> localhost | The key fingerprint is:
2025-10-02 15:10:17.516642 | controller -> localhost | SHA256:lv8olNn9rOHmHokFCUdiaSfR9hsLLoSsSq7x6UKU00Y zuul-build-sshkey
2025-10-02 15:10:17.516663 | controller -> localhost | The key's randomart image is:
2025-10-02 15:10:17.516684 | controller -> localhost | +---[RSA 3072]----+
2025-10-02 15:10:17.516705 | controller -> localhost | |        ==o      |
2025-10-02 15:10:17.516725 | controller -> localhost | |   E   .+++.     |
2025-10-02 15:10:17.516745 | controller -> localhost | |  +  . o +o.     |
2025-10-02 15:10:17.516765 | controller -> localhost | | + o  o ....o    |
2025-10-02 15:10:17.516794 | controller -> localhost | |. o  . .S= o.+   |
2025-10-02 15:10:17.516815 | controller -> localhost | | .. .  .=.oo+.   |
2025-10-02 15:10:17.516834 | controller -> localhost | |oo .   . .o +o   |
2025-10-02 15:10:17.516854 | controller -> localhost | |.oo.    .  +.oo  |
2025-10-02 15:10:17.516873 | controller -> localhost | |.++      ..+*.   |
2025-10-02 15:10:17.516891 | controller -> localhost | +----[SHA256]-----+
2025-10-02 15:10:17.516935 | controller -> localhost | ok: Runtime: 0:00:00.231210
2025-10-02 15:10:17.522528 | 
2025-10-02 15:10:17.522595 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-02 15:10:17.551579 | controller | ok
2025-10-02 15:10:17.565683 | controller | included: /var/lib/zuul/builds/b34d8e63349847dc98b841f857d95291/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-02 15:10:17.583552 | 
2025-10-02 15:10:17.583616 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-02 15:10:17.600644 | controller | skipping: Conditional result was False
2025-10-02 15:10:17.606248 | 
2025-10-02 15:10:17.606314 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-02 15:10:18.202534 | controller | changed
2025-10-02 15:10:18.207500 | 
2025-10-02 15:10:18.207566 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-02 15:10:18.516016 | controller | ok
2025-10-02 15:10:18.529576 | 
2025-10-02 15:10:18.529735 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-02 15:10:19.737868 | controller | changed
2025-10-02 15:10:19.742916 | 
2025-10-02 15:10:19.742982 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-02 15:10:20.922948 | controller | changed
2025-10-02 15:10:20.927882 | 
2025-10-02 15:10:20.927948 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-02 15:10:20.962971 | controller | skipping: Conditional result was False
2025-10-02 15:10:20.968440 | 
2025-10-02 15:10:20.968537 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-02 15:10:21.453486 | controller -> localhost | changed
2025-10-02 15:10:21.489334 | 
2025-10-02 15:10:21.489447 | TASK [add-build-sshkey : Add back temp key]
2025-10-02 15:10:21.787575 | controller -> localhost | Identity added: /var/lib/zuul/builds/b34d8e63349847dc98b841f857d95291/work/b34d8e63349847dc98b841f857d95291_id_rsa (zuul-build-sshkey)
2025-10-02 15:10:21.787750 | controller -> localhost | ok: Runtime: 0:00:00.007337
2025-10-02 15:10:21.800666 | 
2025-10-02 15:10:21.800734 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-02 15:10:22.218262 | controller | ok
2025-10-02 15:10:22.224348 | 
2025-10-02 15:10:22.224442 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-02 15:10:22.269235 | controller | skipping: Conditional result was False
2025-10-02 15:10:22.280168 | 
2025-10-02 15:10:22.280244 | TASK [include_role : validate-host]
2025-10-02 15:10:22.328771 | controller | ok
2025-10-02 15:10:22.350857 | 
2025-10-02 15:10:22.350940 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-02 15:10:22.384398 | controller | ok
2025-10-02 15:10:22.389631 | 
2025-10-02 15:10:22.389715 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-02 15:10:22.616928 | controller -> localhost | ok
2025-10-02 15:10:22.622433 | 
2025-10-02 15:10:22.622501 | TASK [validate-host : Collect information about the host]
2025-10-02 15:10:23.344523 | controller | ok
2025-10-02 15:10:23.361022 | 
2025-10-02 15:10:23.361128 | TASK [validate-host : Sanitize hostname]
2025-10-02 15:10:23.462042 | controller | ok
2025-10-02 15:10:23.469967 | 
2025-10-02 15:10:23.470046 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-02 15:10:23.964773 | controller -> localhost | changed
2025-10-02 15:10:23.970102 | 
2025-10-02 15:10:23.970172 | TASK [validate-host : Collect information about zuul worker]
2025-10-02 15:10:24.398999 | controller | ok
2025-10-02 15:10:24.404146 | 
2025-10-02 15:10:24.404216 | TASK [validate-host : Write out all zuul information for each host]
2025-10-02 15:10:24.813146 | controller -> localhost | changed
2025-10-02 15:10:24.822125 | 
2025-10-02 15:10:24.822196 | TASK [include_role : prepare-workspace-openshift]
2025-10-02 15:10:24.835377 | controller | skipping: Conditional result was False
2025-10-02 15:10:24.841122 | 
2025-10-02 15:10:24.841200 | TASK [include_role : remove-zuul-sshkey]
2025-10-02 15:10:24.854420 | controller | skipping: Conditional result was False
2025-10-02 15:10:24.859330 | 
2025-10-02 15:10:24.859430 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-02 15:10:25.123103 | controller | ok: "logs"
2025-10-02 15:10:25.123319 | controller | ok: All items complete
2025-10-02 15:10:25.123350 | 
2025-10-02 15:10:25.356879 | controller | ok: "artifacts"
2025-10-02 15:10:25.586053 | controller | ok: "docs"
2025-10-02 15:10:25.602527 | 
2025-10-02 15:10:25.602617 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-02 15:10:25.881839 | controller | changed: "logs"
2025-10-02 15:10:26.121561 | controller | changed: "artifacts"
2025-10-02 15:10:26.365622 | controller | changed: "docs"
2025-10-02 15:10:26.389573 | 
2025-10-02 15:10:26.389685 | PLAY RECAP
2025-10-02 15:10:26.389734 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-02 15:10:26.389800 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-02 15:10:26.389824 | 
2025-10-02 15:10:26.468077 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-02 15:10:26.468796 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-02 15:10:27.042725 | 
2025-10-02 15:10:27.042860 | PLAY [all]
2025-10-02 15:10:27.071812 | 
2025-10-02 15:10:27.071887 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-02 15:10:27.121075 | controller | ok
2025-10-02 15:10:27.126084 | 
2025-10-02 15:10:27.126162 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-02 15:10:27.571058 | controller | changed
2025-10-02 15:10:27.580615 | 
2025-10-02 15:10:27.580689 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-02 15:10:28.957170 | controller | changed
2025-10-02 15:10:28.975252 | 
2025-10-02 15:10:28.975342 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-02 15:10:29.513819 | controller | changed:
2025-10-02 15:10:29.514345 | controller | {
2025-10-02 15:10:29.514398 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-02 15:10:29.514452 | controller | }
2025-10-02 15:10:29.821262 | controller | changed:
2025-10-02 15:10:29.821346 | controller | {
2025-10-02 15:10:29.821375 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-02 15:10:29.821427 | controller | }
2025-10-02 15:10:30.158924 | controller | changed:
2025-10-02 15:10:30.159011 | controller | {
2025-10-02 15:10:30.159042 | 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 15:10:30.159066 | controller | }
2025-10-02 15:10:30.495011 | controller | changed:
2025-10-02 15:10:30.495102 | controller | {
2025-10-02 15:10:30.495131 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-02 15:10:30.495152 | controller | }
2025-10-02 15:10:30.839903 | controller | changed:
2025-10-02 15:10:30.839990 | controller | {
2025-10-02 15:10:30.840017 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-02 15:10:30.840038 | controller | }
2025-10-02 15:10:31.139676 | controller | changed:
2025-10-02 15:10:31.139763 | controller | {
2025-10-02 15:10:31.139801 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-02 15:10:31.139824 | controller | }
2025-10-02 15:10:31.446042 | controller | changed:
2025-10-02 15:10:31.446181 | controller | {
2025-10-02 15:10:31.446216 | 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 15:10:31.446241 | controller | }
2025-10-02 15:10:31.741521 | controller | changed:
2025-10-02 15:10:31.741607 | controller | {
2025-10-02 15:10:31.741637 | 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 15:10:31.741661 | controller | }
2025-10-02 15:10:32.030944 | controller | changed:
2025-10-02 15:10:32.031039 | controller | {
2025-10-02 15:10:32.031068 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-02 15:10:32.031091 | controller | }
2025-10-02 15:10:32.326729 | controller | changed:
2025-10-02 15:10:32.326818 | controller | {
2025-10-02 15:10:32.326848 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-02 15:10:32.326871 | controller | }
2025-10-02 15:10:32.649915 | controller | changed:
2025-10-02 15:10:32.650005 | controller | {
2025-10-02 15:10:32.650033 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-02 15:10:32.650054 | controller | }
2025-10-02 15:10:32.962834 | controller | changed:
2025-10-02 15:10:32.962928 | controller | {
2025-10-02 15:10:32.962961 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-02 15:10:32.962988 | controller | }
2025-10-02 15:10:33.262815 | controller | changed:
2025-10-02 15:10:33.262903 | controller | {
2025-10-02 15:10:33.262930 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-02 15:10:33.262950 | controller | }
2025-10-02 15:10:33.558324 | controller | changed:
2025-10-02 15:10:33.558435 | controller | {
2025-10-02 15:10:33.558464 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-02 15:10:33.558487 | controller | }
2025-10-02 15:10:33.862527 | controller | changed:
2025-10-02 15:10:33.862608 | controller | {
2025-10-02 15:10:33.862635 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-02 15:10:33.862656 | controller | }
2025-10-02 15:10:34.168192 | controller | changed:
2025-10-02 15:10:34.168264 | controller | {
2025-10-02 15:10:34.168359 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-02 15:10:34.168398 | controller | }
2025-10-02 15:10:34.483676 | controller | changed:
2025-10-02 15:10:34.483759 | controller | {
2025-10-02 15:10:34.483799 | 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 15:10:34.483824 | controller | }
2025-10-02 15:10:34.821118 | controller | changed:
2025-10-02 15:10:34.821204 | controller | {
2025-10-02 15:10:34.821233 | 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 15:10:34.821264 | controller | }
2025-10-02 15:10:35.149797 | controller | changed:
2025-10-02 15:10:35.149884 | controller | {
2025-10-02 15:10:35.149911 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-02 15:10:35.149932 | controller | }
2025-10-02 15:10:35.460234 | controller | changed:
2025-10-02 15:10:35.460308 | controller | {
2025-10-02 15:10:35.460335 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-02 15:10:35.460357 | controller | }
2025-10-02 15:10:35.781516 | controller | changed:
2025-10-02 15:10:35.781593 | controller | {
2025-10-02 15:10:35.781619 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-02 15:10:35.781639 | controller | }
2025-10-02 15:10:36.071732 | controller | changed:
2025-10-02 15:10:36.071800 | controller | {
2025-10-02 15:10:36.071834 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-02 15:10:36.071860 | controller | }
2025-10-02 15:10:36.370902 | controller | changed:
2025-10-02 15:10:36.370983 | controller | {
2025-10-02 15:10:36.371010 | 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 15:10:36.371032 | controller | }
2025-10-02 15:10:36.703370 | controller | changed:
2025-10-02 15:10:36.703493 | controller | {
2025-10-02 15:10:36.703529 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-02 15:10:36.703557 | controller | }
2025-10-02 15:10:37.009362 | controller | changed:
2025-10-02 15:10:37.009472 | controller | {
2025-10-02 15:10:37.009499 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-02 15:10:37.009519 | controller | }
2025-10-02 15:10:37.304555 | controller | changed:
2025-10-02 15:10:37.304637 | controller | {
2025-10-02 15:10:37.304663 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-02 15:10:37.304682 | controller | }
2025-10-02 15:10:37.332931 | 
2025-10-02 15:10:37.333042 | TASK [Set timezone to UTC]
2025-10-02 15:10:37.817432 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-02 15:10:37.822261 | 
2025-10-02 15:10:37.822325 | TASK [Create nodepool directory]
2025-10-02 15:10:38.096647 | controller | changed
2025-10-02 15:10:38.103421 | 
2025-10-02 15:10:38.103488 | TASK [Create nodepool sub_nodes file]
2025-10-02 15:10:39.110032 | controller | changed
2025-10-02 15:10:39.125342 | 
2025-10-02 15:10:39.125465 | TASK [Create nodepool sub_nodes_private file]
2025-10-02 15:10:40.097456 | controller | changed
2025-10-02 15:10:40.103004 | 
2025-10-02 15:10:40.103065 | LOOP [Populate nodepool sub_nodes file]
2025-10-02 15:10:40.132332 | 
2025-10-02 15:10:40.132573 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-02 15:10:40.161576 | 
2025-10-02 15:10:40.161717 | TASK [Create nodepool primary file]
2025-10-02 15:10:40.185581 | controller | skipping: Conditional result was False
2025-10-02 15:10:40.191068 | 
2025-10-02 15:10:40.191142 | TASK [Create nodepool node_private for this node]
2025-10-02 15:10:41.284699 | controller | changed
2025-10-02 15:10:41.290207 | 
2025-10-02 15:10:41.290270 | LOOP [Copy ssh keys to nodepool directory]
2025-10-02 15:10:41.686481 | controller | ok: Item: id_rsa Runtime: 0:00:00.005310
2025-10-02 15:10:41.686659 | 
2025-10-02 15:10:41.935203 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005468
2025-10-02 15:10:41.947692 | 
2025-10-02 15:10:41.947776 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-02 15:10:42.938859 | controller | changed
2025-10-02 15:10:42.944034 | 
2025-10-02 15:10:42.944098 | TASK [Validate sudoers config after edits]
2025-10-02 15:10:43.243246 | controller | /etc/sudoers: parsed OK
2025-10-02 15:10:43.243320 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-02 15:10:43.243362 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-02 15:10:43.243370 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-02 15:10:43.471654 | controller | ok: Runtime: 0:00:00.005590
2025-10-02 15:10:43.479401 | 
2025-10-02 15:10:43.479468 | TASK [Show the environment passed in to job shell scripts]
2025-10-02 15:10:43.750035 | controller | SHELL=/bin/bash
2025-10-02 15:10:43.750085 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-02 15:10:43.750094 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-02 15:10:43.750100 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/62/3362/41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 15:10:43.750106 | controller | PWD=/home/zuul
2025-10-02 15:10:43.750111 | controller | ZUUL_PIPELINE=github-check
2025-10-02 15:10:43.750117 | controller | LOGNAME=zuul
2025-10-02 15:10:43.750122 | controller | XDG_SESSION_TYPE=tty
2025-10-02 15:10:43.750127 | controller | _=/usr/bin/env
2025-10-02 15:10:43.750132 | controller | MOTD_SHOWN=pam
2025-10-02 15:10:43.750137 | controller | HOME=/home/zuul
2025-10-02 15:10:43.750143 | controller | LANG=en_US.UTF-8
2025-10-02 15:10:43.750188 | controller | SSH_CONNECTION=192.168.25.12 44522 192.168.25.139 22
2025-10-02 15:10:43.750196 | 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 15:10:43.750205 | controller | ZUUL_CHANGE_IDS=3362,41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 15:10:43.750210 | controller | WORKSPACE=/home/zuul/workspace
2025-10-02 15:10:43.750215 | controller | XDG_SESSION_CLASS=user
2025-10-02 15:10:43.750221 | controller | SELINUX_ROLE_REQUESTED=
2025-10-02 15:10:43.750226 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-02 15:10:43.750231 | controller | USER=zuul
2025-10-02 15:10:43.750236 | controller | ZUUL_VOTING=True
2025-10-02 15:10:43.750242 | controller | BUILD_TIMEOUT=1800000
2025-10-02 15:10:43.750247 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-02 15:10:43.750252 | controller | SHLVL=1
2025-10-02 15:10:43.750257 | controller | ZUUL_PATCHSET=41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 15:10:43.750262 | controller | XDG_SESSION_ID=1
2025-10-02 15:10:43.750268 | controller | ZUUL_BRANCH=main
2025-10-02 15:10:43.750273 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-02 15:10:43.750278 | controller | SSH_CLIENT=192.168.25.12 44522 22
2025-10-02 15:10:43.750284 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-02 15:10:43.750289 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-02 15:10:43.750294 | controller | which_declare=declare -f
2025-10-02 15:10:43.750300 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-02 15:10:43.750305 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-02 15:10:43.750310 | controller | ZUUL_CHANGE=3362
2025-10-02 15:10:43.750315 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-02 15:10:43.750321 | controller | ZUUL_UUID=b34d8e63349847dc98b841f857d95291
2025-10-02 15:10:43.750326 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-02 15:10:43.750333 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-02 15:10:43.750338 | controller | }
2025-10-02 15:10:44.005224 | controller | ok: Runtime: 0:00:00.006993
2025-10-02 15:10:44.010762 | 
2025-10-02 15:10:44.010841 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-02 15:10:44.024196 | controller | skipping: Conditional result was False
2025-10-02 15:10:44.029300 | 
2025-10-02 15:10:44.029365 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-02 15:10:44.572467 | controller | skipping: Conditional result was False
2025-10-02 15:10:44.578202 | 
2025-10-02 15:10:44.578275 | TASK [Ensure legacy workspace directory]
2025-10-02 15:10:44.819525 | controller | changed
2025-10-02 15:10:44.838886 | 
2025-10-02 15:10:44.838944 | PLAY RECAP
2025-10-02 15:10:44.838988 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-02 15:10:44.839011 | 
2025-10-02 15:10:44.917678 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-02 15:10:44.918417 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-02 15:10:45.431923 | 
2025-10-02 15:10:45.432021 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-02 15:10:45.452076 | 
2025-10-02 15:10:45.452147 | TASK [Create zuul-output directory]
2025-10-02 15:10:45.815814 | controller | changed
2025-10-02 15:10:45.820973 | 
2025-10-02 15:10:45.821047 | TASK [Slurp Zuul inventory test]
2025-10-02 15:10:46.129554 | controller -> localhost | ok
2025-10-02 15:10:46.135927 | 
2025-10-02 15:10:46.136007 | TASK [Save zuul inventory]
2025-10-02 15:10:47.350458 | controller | changed
2025-10-02 15:10:47.356808 | 
2025-10-02 15:10:47.356909 | TASK [Save zuul vars without the change_message]
2025-10-02 15:10:48.367157 | controller | changed
2025-10-02 15:10:48.385461 | 
2025-10-02 15:10:48.385513 | PLAY RECAP
2025-10-02 15:10:48.385555 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-02 15:10:48.385578 | 
2025-10-02 15:10:48.449423 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-02 15:10:48.450125 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-02 15:10:48.984994 | 
2025-10-02 15:10:48.985099 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-02 15:10:49.005353 | 
2025-10-02 15:10:49.005465 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-02 15:10:49.024297 | controller | ok
2025-10-02 15:10:49.041631 | 
2025-10-02 15:10:49.041721 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-02 15:10:49.065566 | controller | skipping: Conditional result was False
2025-10-02 15:10:49.071115 | 
2025-10-02 15:10:49.071191 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-02 15:10:49.454692 | controller | ok
2025-10-02 15:10:49.460268 | 
2025-10-02 15:10:49.460340 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-02 15:10:50.382443 | controller | ok
2025-10-02 15:10:50.393081 | 
2025-10-02 15:10:50.393191 | TASK [Prepare workspace]
2025-10-02 15:10:50.411533 | controller | ok
2025-10-02 15:10:50.427906 | 
2025-10-02 15:10:50.427976 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-02 15:10:50.781810 | controller | ok
2025-10-02 15:10:50.787514 | 
2025-10-02 15:10:50.787588 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-02 15:10:52.765891 | controller | Output suppressed because no_log was given
2025-10-02 15:10:52.775186 | 
2025-10-02 15:10:52.775250 | LOOP [Create zuul-output directory]
2025-10-02 15:10:53.016778 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-02 15:10:53.235454 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-02 15:10:53.242236 | 
2025-10-02 15:10:53.242455 | TASK [Install required packages]
2025-10-02 15:11:11.632890 | controller | ok: Nothing to do
2025-10-02 15:11:11.638203 | 
2025-10-02 15:11:11.638284 | TASK [Install venv]
2025-10-02 15:12:10.975445 | controller | changed
2025-10-02 15:12:11.003585 | 
2025-10-02 15:12:11.003771 | PLAY RECAP
2025-10-02 15:12:11.003838 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-02 15:12:11.003863 | 
2025-10-02 15:12:11.081943 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-02 15:12:11.082709 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-02 15:12:11.638550 | 
2025-10-02 15:12:11.638639 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-02 15:12:11.659216 | 
2025-10-02 15:12:11.659291 | TASK [Gather required facts]
2025-10-02 15:12:12.214818 | controller | ok
2025-10-02 15:12:12.220240 | 
2025-10-02 15:12:12.220315 | TASK [Load environment var if instructed to]
2025-10-02 15:12:12.244373 | controller | skipping: Conditional result was False
2025-10-02 15:12:12.250183 | 
2025-10-02 15:12:12.250253 | TASK [Run molecule]
2025-10-02 15:12:13.238308 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-02 15:12:13.309287 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-02 15:12:30.768339 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 15:12:30.768765 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 15:12:30.769235 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 15:12:30.769687 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 15:12:30.770163 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+41f2edaa was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 1.0.0+41f2edaa (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 15:12:30.770619 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 15:12:30.771037 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 15:12:30.771481 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 15:12:30.771896 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 15:12:30.772346 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 15:12:30.772783 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 15:12:30.773255 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 15:12:30.773706 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 15:12:30.774146 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 15:12:30.774593 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 15:12:30.783694 | controller | INFO     Running default > prepare
2025-10-02 15:12:31.500293 | controller |
2025-10-02 15:12:31.500341 | controller | PLAY [Prepare] *****************************************************************
2025-10-02 15:12:31.500461 | controller |
2025-10-02 15:12:31.500559 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 15:12:31.500655 | controller | Thursday 02 October 2025  15:12:31 +0000 (0:00:00.027)       0:00:00.027 ******
2025-10-02 15:12:32.334775 | controller | ok: [instance]
2025-10-02 15:12:32.334822 | controller |
2025-10-02 15:12:32.334978 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-02 15:12:32.335063 | controller | Thursday 02 October 2025  15:12:32 +0000 (0:00:00.834)       0:00:00.862 ******
2025-10-02 15:12:32.351863 | controller | skipping: [instance]
2025-10-02 15:12:32.351980 | controller |
2025-10-02 15:12:32.352143 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-02 15:12:32.352350 | controller | Thursday 02 October 2025  15:12:32 +0000 (0:00:00.017)       0:00:00.880 ******
2025-10-02 15:12:32.391990 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-02 15:12:32.392046 | controller |
2025-10-02 15:12:32.392217 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-02 15:12:32.392292 | controller | Thursday 02 October 2025  15:12:32 +0000 (0:00:00.039)       0:00:00.920 ******
2025-10-02 15:12:32.666747 | controller | ok: [instance]
2025-10-02 15:12:32.666787 | controller |
2025-10-02 15:12:32.666921 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-02 15:12:32.667033 | controller | Thursday 02 October 2025  15:12:32 +0000 (0:00:00.274)       0:00:01.194 ******
2025-10-02 15:12:32.978284 | controller | ok: [instance]
2025-10-02 15:12:32.978369 | controller |
2025-10-02 15:12:32.978446 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-02 15:12:32.978543 | controller | Thursday 02 October 2025  15:12:32 +0000 (0:00:00.311)       0:00:01.506 ******
2025-10-02 15:12:33.657174 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-02 15:12:33.657293 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-02 15:12:33.657372 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-02 15:12:33.657462 | controller |
2025-10-02 15:12:33.657554 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-02 15:12:33.657644 | controller | Thursday 02 October 2025  15:12:33 +0000 (0:00:00.678)       0:00:02.185 ******
2025-10-02 15:12:33.717548 | controller |
2025-10-02 15:12:33.717583 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-02 15:12:33.717724 | controller | Thursday 02 October 2025  15:12:33 +0000 (0:00:00.060)       0:00:02.245 ******
2025-10-02 15:12:34.259350 | controller | changed: [instance] => (item=tmp)
2025-10-02 15:12:34.259417 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-02 15:12:34.259517 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-02 15:12:34.259614 | controller |
2025-10-02 15:12:34.259715 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-02 15:12:34.259802 | controller | Thursday 02 October 2025  15:12:34 +0000 (0:00:00.541)       0:00:02.787 ******
2025-10-02 15:12:35.078444 | controller | ok: [instance]
2025-10-02 15:12:35.078820 | controller |
2025-10-02 15:12:36.256755 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-02 15:12:36.256789 | controller | Thursday 02 October 2025  15:12:35 +0000 (0:00:00.818)       0:00:03.606 ******
2025-10-02 15:12:36.256802 | controller | changed: [instance]
2025-10-02 15:12:44.218773 | controller |
2025-10-02 15:12:44.218810 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-02 15:12:44.218819 | controller | Thursday 02 October 2025  15:12:36 +0000 (0:00:01.178)       0:00:04.784 ******
2025-10-02 15:12:44.218828 | controller | changed: [instance]
2025-10-02 15:12:44.950120 | controller |
2025-10-02 15:12:44.950211 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-02 15:12:44.950223 | controller | Thursday 02 October 2025  15:12:44 +0000 (0:00:07.962)       0:00:12.746 ******
2025-10-02 15:12:44.950240 | controller | changed: [instance]
2025-10-02 15:12:44.972071 | controller |
2025-10-02 15:12:44.972110 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-02 15:12:44.972119 | controller | Thursday 02 October 2025  15:12:44 +0000 (0:00:00.731)       0:00:13.477 ******
2025-10-02 15:12:44.972130 | controller | skipping: [instance]
2025-10-02 15:12:44.972319 | controller |
2025-10-02 15:12:44.972338 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-02 15:12:45.830616 | controller | Thursday 02 October 2025  15:12:44 +0000 (0:00:00.022)       0:00:13.500 ******
2025-10-02 15:12:45.830651 | controller | changed: [instance]
2025-10-02 15:12:45.868915 | controller |
2025-10-02 15:12:45.868938 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-02 15:12:45.868945 | controller | Thursday 02 October 2025  15:12:45 +0000 (0:00:00.858)       0:00:14.358 ******
2025-10-02 15:12:45.868972 | controller | skipping: [instance]
2025-10-02 15:12:45.908097 | controller |
2025-10-02 15:12:45.908131 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-02 15:12:45.908139 | controller | Thursday 02 October 2025  15:12:45 +0000 (0:00:00.038)       0:00:14.396 ******
2025-10-02 15:12:45.908149 | controller | skipping: [instance]
2025-10-02 15:12:45.908413 | controller |
2025-10-02 15:12:45.908445 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-02 15:12:45.945668 | controller | Thursday 02 October 2025  15:12:45 +0000 (0:00:00.039)       0:00:14.435 ******
2025-10-02 15:12:45.945707 | controller | skipping: [instance]
2025-10-02 15:12:46.750788 | controller |
2025-10-02 15:12:46.750820 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-02 15:12:46.750829 | controller | Thursday 02 October 2025  15:12:45 +0000 (0:00:00.037)       0:00:14.473 ******
2025-10-02 15:12:46.750838 | controller | changed: [instance]
2025-10-02 15:12:47.182036 | controller |
2025-10-02 15:12:47.182068 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-02 15:12:47.182076 | controller | Thursday 02 October 2025  15:12:46 +0000 (0:00:00.805)       0:00:15.278 ******
2025-10-02 15:12:47.182086 | controller | changed: [instance]
2025-10-02 15:12:47.211095 | controller |
2025-10-02 15:12:47.211133 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-02 15:12:47.211143 | controller | Thursday 02 October 2025  15:12:47 +0000 (0:00:00.431)       0:00:15.709 ******
2025-10-02 15:12:47.211154 | controller | skipping: [instance]
2025-10-02 15:12:47.211411 | controller |
2025-10-02 15:12:47.211464 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-02 15:12:47.239686 | controller | Thursday 02 October 2025  15:12:47 +0000 (0:00:00.029)       0:00:15.739 ******
2025-10-02 15:12:47.239720 | controller | skipping: [instance]
2025-10-02 15:12:47.268868 | controller |
2025-10-02 15:12:47.268900 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-02 15:12:47.268908 | controller | Thursday 02 October 2025  15:12:47 +0000 (0:00:00.028)       0:00:15.767 ******
2025-10-02 15:12:47.268918 | controller | skipping: [instance]
2025-10-02 15:12:47.306688 | controller |
2025-10-02 15:12:47.306718 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-02 15:12:47.306726 | controller | Thursday 02 October 2025  15:12:47 +0000 (0:00:00.029)       0:00:15.796 ******
2025-10-02 15:12:47.306735 | controller | ok: [instance]
2025-10-02 15:12:47.334775 | controller |
2025-10-02 15:12:47.334803 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-02 15:12:47.334812 | controller | Thursday 02 October 2025  15:12:47 +0000 (0:00:00.037)       0:00:15.834 ******
2025-10-02 15:12:47.334824 | controller | skipping: [instance]
2025-10-02 15:12:47.335026 | controller |
2025-10-02 15:12:47.335229 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-02 15:12:47.335401 | controller | Thursday 02 October 2025  15:12:47 +0000 (0:00:00.028)       0:00:15.862 ******
2025-10-02 15:12:47.362088 | controller | skipping: [instance]
2025-10-02 15:12:47.362258 | controller |
2025-10-02 15:12:47.362402 | controller | TASK [Download the RPM] ********************************************************
2025-10-02 15:12:47.362537 | controller | Thursday 02 October 2025  15:12:47 +0000 (0:00:00.027)       0:00:15.890 ******
2025-10-02 15:12:47.390635 | controller | skipping: [instance]
2025-10-02 15:12:47.390812 | controller |
2025-10-02 15:12:47.390950 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-02 15:12:47.391130 | controller | Thursday 02 October 2025  15:12:47 +0000 (0:00:00.028)       0:00:15.918 ******
2025-10-02 15:12:47.419081 | controller | skipping: [instance]
2025-10-02 15:12:47.419303 | controller |
2025-10-02 15:12:47.419479 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-02 15:12:47.419646 | controller | Thursday 02 October 2025  15:12:47 +0000 (0:00:00.028)       0:00:15.947 ******
2025-10-02 15:12:47.448664 | controller | skipping: [instance]
2025-10-02 15:12:47.448835 | controller |
2025-10-02 15:12:47.449003 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-02 15:12:47.449149 | controller | Thursday 02 October 2025  15:12:47 +0000 (0:00:00.029)       0:00:15.976 ******
2025-10-02 15:12:47.482377 | controller | skipping: [instance]
2025-10-02 15:12:47.482536 | controller |
2025-10-02 15:12:47.482700 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-02 15:12:47.482840 | controller | Thursday 02 October 2025  15:12:47 +0000 (0:00:00.033)       0:00:16.010 ******
2025-10-02 15:12:47.509238 | controller | skipping: [instance]
2025-10-02 15:12:47.509392 | controller |
2025-10-02 15:12:47.509536 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-02 15:12:47.509676 | controller | Thursday 02 October 2025  15:12:47 +0000 (0:00:00.027)       0:00:16.037 ******
2025-10-02 15:12:47.693498 | controller | ok: [instance]
2025-10-02 15:12:47.693682 | controller |
2025-10-02 15:12:47.693835 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-02 15:12:47.694001 | controller | Thursday 02 October 2025  15:12:47 +0000 (0:00:00.183)       0:00:16.221 ******
2025-10-02 15:12:47.904577 | controller | changed: [instance]
2025-10-02 15:12:48.134873 | controller |
2025-10-02 15:12:48.134905 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-02 15:12:48.134913 | controller | Thursday 02 October 2025  15:12:47 +0000 (0:00:00.210)       0:00:16.432 ******
2025-10-02 15:12:48.134923 | controller | changed: [instance]
2025-10-02 15:12:48.155540 | controller |
2025-10-02 15:12:48.155562 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-02 15:12:48.155570 | controller | Thursday 02 October 2025  15:12:48 +0000 (0:00:00.230)       0:00:16.662 ******
2025-10-02 15:12:48.155579 | controller | skipping: [instance]
2025-10-02 15:12:48.178681 | controller |
2025-10-02 15:12:48.178703 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-02 15:12:48.178712 | controller | Thursday 02 October 2025  15:12:48 +0000 (0:00:00.020)       0:00:16.683 ******
2025-10-02 15:12:48.178722 | controller | skipping: [instance]
2025-10-02 15:12:48.202119 | controller |
2025-10-02 15:12:48.202158 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-02 15:12:48.202168 | controller | Thursday 02 October 2025  15:12:48 +0000 (0:00:00.023)       0:00:16.706 ******
2025-10-02 15:12:48.202205 | controller | skipping: [instance]
2025-10-02 15:12:48.227952 | controller |
2025-10-02 15:12:48.227999 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-02 15:12:48.228009 | controller | Thursday 02 October 2025  15:12:48 +0000 (0:00:00.023)       0:00:16.730 ******
2025-10-02 15:12:48.228028 | controller | skipping: [instance]
2025-10-02 15:12:48.251527 | controller |
2025-10-02 15:12:48.251547 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-02 15:12:48.251554 | controller | Thursday 02 October 2025  15:12:48 +0000 (0:00:00.025)       0:00:16.755 ******
2025-10-02 15:12:48.251563 | controller | skipping: [instance]
2025-10-02 15:12:48.276904 | controller |
2025-10-02 15:12:48.276922 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-02 15:12:48.276939 | controller | Thursday 02 October 2025  15:12:48 +0000 (0:00:00.023)       0:00:16.779 ******
2025-10-02 15:12:48.276948 | controller | skipping: [instance]
2025-10-02 15:12:48.296235 | controller |
2025-10-02 15:12:48.296258 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-02 15:12:48.296267 | controller | Thursday 02 October 2025  15:12:48 +0000 (0:00:00.025)       0:00:16.804 ******
2025-10-02 15:12:48.296277 | controller | skipping: [instance]
2025-10-02 15:12:48.296450 | controller |
2025-10-02 15:12:48.296595 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-02 15:12:48.296731 | controller | Thursday 02 October 2025  15:12:48 +0000 (0:00:00.019)       0:00:16.824 ******
2025-10-02 15:12:48.327502 | controller | skipping: [instance]
2025-10-02 15:12:48.327686 | controller |
2025-10-02 15:12:48.327858 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-02 15:12:48.328057 | controller | Thursday 02 October 2025  15:12:48 +0000 (0:00:00.031)       0:00:16.855 ******
2025-10-02 15:12:48.347368 | controller | skipping: [instance]
2025-10-02 15:12:48.347563 | controller |
2025-10-02 15:12:48.347704 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-02 15:12:48.347847 | controller | Thursday 02 October 2025  15:12:48 +0000 (0:00:00.019)       0:00:16.875 ******
2025-10-02 15:12:48.373777 | controller | skipping: [instance]
2025-10-02 15:12:48.373930 | controller |
2025-10-02 15:12:48.374103 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-02 15:12:48.374281 | controller | Thursday 02 October 2025  15:12:48 +0000 (0:00:00.026)       0:00:16.902 ******
2025-10-02 15:12:48.404984 | controller | skipping: [instance]
2025-10-02 15:12:48.405153 | controller |
2025-10-02 15:12:48.405326 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-02 15:12:48.405468 | controller | Thursday 02 October 2025  15:12:48 +0000 (0:00:00.031)       0:00:16.933 ******
2025-10-02 15:12:48.435528 | controller | skipping: [instance]
2025-10-02 15:12:48.435682 | controller |
2025-10-02 15:12:48.435829 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-02 15:12:48.436003 | controller | Thursday 02 October 2025  15:12:48 +0000 (0:00:00.030)       0:00:16.963 ******
2025-10-02 15:12:48.464750 | controller | skipping: [instance]
2025-10-02 15:12:48.464911 | controller |
2025-10-02 15:12:48.465078 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-02 15:12:48.465243 | controller | Thursday 02 October 2025  15:12:48 +0000 (0:00:00.029)       0:00:16.993 ******
2025-10-02 15:12:48.494401 | controller | skipping: [instance]
2025-10-02 15:12:48.494583 | controller |
2025-10-02 15:12:48.494756 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-02 15:12:48.494911 | controller | Thursday 02 October 2025  15:12:48 +0000 (0:00:00.029)       0:00:17.022 ******
2025-10-02 15:13:40.368113 | controller | ok: [instance]
2025-10-02 15:13:40.368156 | controller |
2025-10-02 15:13:40.368166 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-02 15:13:40.368176 | controller | Thursday 02 October 2025  15:13:40 +0000 (0:00:51.873)       0:01:08.895 ******
2025-10-02 15:13:41.578039 | controller | ok: [instance]
2025-10-02 15:13:41.578146 | controller |
2025-10-02 15:13:41.578402 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-02 15:13:41.610145 | controller | Thursday 02 October 2025  15:13:41 +0000 (0:00:01.210)       0:01:10.106 ******
2025-10-02 15:13:41.610194 | controller | skipping: [instance]
2025-10-02 15:13:41.653793 | controller |
2025-10-02 15:13:41.653831 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-10-02 15:13:41.653841 | controller | Thursday 02 October 2025  15:13:41 +0000 (0:00:00.031)       0:01:10.137 ******
2025-10-02 15:13:41.653853 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-10-02 15:13:41.672669 | controller |
2025-10-02 15:13:41.672717 | controller | TASK [ci_setup : List packages to install] *************************************
2025-10-02 15:13:41.672726 | controller | Thursday 02 October 2025  15:13:41 +0000 (0:00:00.043)       0:01:10.181 ******
2025-10-02 15:13:41.672760 | controller | ok: [instance] =>
2025-10-02 15:13:41.672790 | controller |   cifmw_ci_setup_packages:
2025-10-02 15:13:41.672801 | controller |   - bash-completion
2025-10-02 15:13:41.673071 | controller |   - ca-certificates
2025-10-02 15:13:41.673089 | controller |   - git-core
2025-10-02 15:13:41.673552 | controller |   - make
2025-10-02 15:13:41.673570 | controller |   - tar
2025-10-02 15:13:41.673581 | controller |   - tmux
2025-10-02 15:13:50.159845 | controller |   - python3-pip
2025-10-02 15:13:50.159893 | controller |
2025-10-02 15:13:50.159903 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-10-02 15:13:50.159917 | controller | Thursday 02 October 2025  15:13:41 +0000 (0:00:00.019)       0:01:10.200 ******
2025-10-02 15:13:50.159929 | controller | changed: [instance]
2025-10-02 15:13:50.744364 | controller |
2025-10-02 15:13:50.744393 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-10-02 15:13:50.744401 | controller | Thursday 02 October 2025  15:13:50 +0000 (0:00:08.486)       0:01:18.687 ******
2025-10-02 15:13:50.744410 | controller | ok: [instance]
2025-10-02 15:13:50.944718 | controller |
2025-10-02 15:13:50.944749 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-10-02 15:13:50.944757 | controller | Thursday 02 October 2025  15:13:50 +0000 (0:00:00.584)       0:01:19.272 ******
2025-10-02 15:13:50.944767 | controller | changed: [instance]
2025-10-02 15:13:50.944791 | controller |
2025-10-02 15:13:50.945006 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-10-02 15:13:56.615430 | controller | Thursday 02 October 2025  15:13:50 +0000 (0:00:00.200)       0:01:19.472 ******
2025-10-02 15:13:56.615467 | controller | changed: [instance]
2025-10-02 15:13:56.650770 | controller |
2025-10-02 15:13:56.650791 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-10-02 15:13:56.650800 | controller | Thursday 02 October 2025  15:13:56 +0000 (0:00:05.670)       0:01:25.143 ******
2025-10-02 15:13:56.650811 | controller | skipping: [instance]
2025-10-02 15:13:56.908557 | controller |
2025-10-02 15:13:56.908591 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-10-02 15:13:56.908601 | controller | Thursday 02 October 2025  15:13:56 +0000 (0:00:00.035)       0:01:25.178 ******
2025-10-02 15:13:56.908612 | controller | changed: [instance]
2025-10-02 15:13:57.193111 | controller |
2025-10-02 15:13:57.193140 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-10-02 15:13:57.193148 | controller | Thursday 02 October 2025  15:13:56 +0000 (0:00:00.257)       0:01:25.436 ******
2025-10-02 15:13:57.193158 | controller | changed: [instance]
2025-10-02 15:13:57.209142 | controller |
2025-10-02 15:13:57.209187 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-10-02 15:13:57.209197 | controller | Thursday 02 October 2025  15:13:57 +0000 (0:00:00.284)       0:01:25.720 ******
2025-10-02 15:13:57.209210 | controller | skipping: [instance]
2025-10-02 15:13:57.209237 | controller |
2025-10-02 15:13:57.209247 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-10-02 15:13:57.209387 | controller | Thursday 02 October 2025  15:13:57 +0000 (0:00:00.016)       0:01:25.737 ******
2025-10-02 15:13:57.224103 | controller | skipping: [instance]
2025-10-02 15:13:57.224145 | controller |
2025-10-02 15:13:57.224154 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-10-02 15:13:57.224163 | controller | Thursday 02 October 2025  15:13:57 +0000 (0:00:00.014)       0:01:25.752 ******
2025-10-02 15:13:57.239895 | controller | skipping: [instance]
2025-10-02 15:13:57.254838 | controller |
2025-10-02 15:13:57.254882 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-10-02 15:13:57.254892 | controller | Thursday 02 October 2025  15:13:57 +0000 (0:00:00.015)       0:01:25.767 ******
2025-10-02 15:13:57.254905 | controller | skipping: [instance]
2025-10-02 15:13:57.269520 | controller |
2025-10-02 15:13:57.269547 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-10-02 15:13:57.269557 | controller | Thursday 02 October 2025  15:13:57 +0000 (0:00:00.015)       0:01:25.782 ******
2025-10-02 15:13:57.269570 | controller | skipping: [instance]
2025-10-02 15:13:57.288946 | controller |
2025-10-02 15:13:57.288993 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-10-02 15:13:57.289015 | controller | Thursday 02 October 2025  15:13:57 +0000 (0:00:00.014)       0:01:25.797 ******
2025-10-02 15:13:57.289028 | controller | skipping: [instance]
2025-10-02 15:13:58.268087 | controller |
2025-10-02 15:13:58.268125 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-10-02 15:13:58.268133 | controller | Thursday 02 October 2025  15:13:57 +0000 (0:00:00.019)       0:01:25.817 ******
2025-10-02 15:13:58.268145 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-10-02 15:13:58.268176 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-10-02 15:13:58.268183 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-10-02 15:13:58.268189 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-10-02 15:13:58.268195 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-10-02 15:13:58.268200 | controller |
2025-10-02 15:13:58.268207 | controller | TASK [libvirt_manager : Ensure output directory exists] ************************
2025-10-02 15:13:58.268313 | controller | Thursday 02 October 2025  15:13:58 +0000 (0:00:00.978)       0:01:26.795 ******
2025-10-02 15:13:58.465485 | controller | ok: [instance]
2025-10-02 15:13:58.647699 | controller |
2025-10-02 15:13:58.647731 | controller | TASK [libvirt_manager : Get vmx/svm extensions from /proc/cpuinfo] *************
2025-10-02 15:13:58.647749 | controller | Thursday 02 October 2025  15:13:58 +0000 (0:00:00.197)       0:01:26.993 ******
2025-10-02 15:13:58.647762 | controller | changed: [instance]
2025-10-02 15:13:58.831477 | controller |
2025-10-02 15:13:58.831506 | controller | TASK [libvirt_manager : Check if KVM is enabled] *******************************
2025-10-02 15:13:58.831514 | controller | Thursday 02 October 2025  15:13:58 +0000 (0:00:00.182)       0:01:27.175 ******
2025-10-02 15:13:58.831525 | controller | ok: [instance]
2025-10-02 15:13:58.858110 | controller |
2025-10-02 15:13:58.858171 | controller | TASK [libvirt_manager : Fail if KVM not enabled] *******************************
2025-10-02 15:13:58.858181 | controller | Thursday 02 October 2025  15:13:58 +0000 (0:00:00.183)       0:01:27.359 ******
2025-10-02 15:13:58.858198 | controller | skipping: [instance]
2025-10-02 15:13:58.858246 | controller |
2025-10-02 15:13:58.858341 | controller | TASK [libvirt_manager : Check if VMX virtualization] ***************************
2025-10-02 15:13:58.858368 | controller | Thursday 02 October 2025  15:13:58 +0000 (0:00:00.027)       0:01:27.386 ******
2025-10-02 15:13:58.874002 | controller | skipping: [instance]
2025-10-02 15:13:58.874174 | controller |
2025-10-02 15:13:58.874324 | controller | TASK [libvirt_manager : Check if SVM virtualization] ***************************
2025-10-02 15:13:58.874470 | controller | Thursday 02 October 2025  15:13:58 +0000 (0:00:00.015)       0:01:27.402 ******
2025-10-02 15:13:58.893750 | controller | ok: [instance]
2025-10-02 15:13:58.893875 | controller |
2025-10-02 15:13:58.894039 | controller | TASK [libvirt_manager : Enable VMX virtualization] *****************************
2025-10-02 15:13:58.894154 | controller | Thursday 02 October 2025  15:13:58 +0000 (0:00:00.019)       0:01:27.422 ******
2025-10-02 15:13:58.918867 | controller | skipping: [instance]
2025-10-02 15:13:58.919046 | controller |
2025-10-02 15:13:58.919204 | controller | TASK [libvirt_manager : Install packages required for using KVM] ***************
2025-10-02 15:13:58.919348 | controller | Thursday 02 October 2025  15:13:58 +0000 (0:00:00.025)       0:01:27.447 ******
2025-10-02 15:14:13.037046 | controller | changed: [instance]
2025-10-02 15:14:13.037097 | controller |
2025-10-02 15:14:13.037288 | controller | TASK [libvirt_manager : Create rules.d polkit folder] **************************
2025-10-02 15:14:13.281704 | controller | Thursday 02 October 2025  15:14:13 +0000 (0:00:14.117)       0:01:41.564 ******
2025-10-02 15:14:13.281744 | controller | changed: [instance]
2025-10-02 15:14:13.281770 | controller |
2025-10-02 15:14:13.281999 | controller | TASK [libvirt_manager : Enable UNIX socket PolicyKit auth] *********************
2025-10-02 15:14:13.282077 | controller | Thursday 02 October 2025  15:14:13 +0000 (0:00:00.244)       0:01:41.809 ******
2025-10-02 15:14:13.682592 | controller | changed: [instance]
2025-10-02 15:14:13.682814 | controller |
2025-10-02 15:14:13.682865 | controller | TASK [libvirt_manager : Restart service polkit service] ************************
2025-10-02 15:14:14.292058 | controller | Thursday 02 October 2025  15:14:13 +0000 (0:00:00.400)       0:01:42.210 ******
2025-10-02 15:14:14.292146 | controller | changed: [instance]
2025-10-02 15:14:14.292482 | controller |
2025-10-02 15:14:14.500533 | controller | TASK [libvirt_manager : Remove directory for service override] *****************
2025-10-02 15:14:14.500565 | controller | Thursday 02 October 2025  15:14:14 +0000 (0:00:00.609)       0:01:42.819 ******
2025-10-02 15:14:14.500577 | controller | ok: [instance]
2025-10-02 15:14:15.180032 | controller |
2025-10-02 15:14:15.180067 | controller | TASK [libvirt_manager : Reload systemctl and start/enable virtproxyd.service] ***
2025-10-02 15:14:15.180074 | controller | Thursday 02 October 2025  15:14:14 +0000 (0:00:00.207)       0:01:43.027 ******
2025-10-02 15:14:15.180085 | controller | ok: [instance]
2025-10-02 15:14:16.112139 | controller |
2025-10-02 15:14:16.112198 | controller | TASK [libvirt_manager : Ensure firewalld service is up] ************************
2025-10-02 15:14:16.112207 | controller | Thursday 02 October 2025  15:14:15 +0000 (0:00:00.679)       0:01:43.707 ******
2025-10-02 15:14:16.112222 | controller | changed: [instance]
2025-10-02 15:14:16.112242 | controller |
2025-10-02 15:14:16.112250 | controller | TASK [libvirt_manager : Ensure libvirt modular driver daemons are started.] ****
2025-10-02 15:14:16.193112 | controller | Thursday 02 October 2025  15:14:16 +0000 (0:00:00.931)       0:01:44.638 ******
2025-10-02 15:14:16.193158 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=qemu)
2025-10-02 15:14:16.193194 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=interface)
2025-10-02 15:14:16.193202 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=network)
2025-10-02 15:14:16.193207 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nodedev)
2025-10-02 15:14:16.193213 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nwfilter)
2025-10-02 15:14:16.193219 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=secret)
2025-10-02 15:14:16.193224 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=storage)
2025-10-02 15:14:16.193230 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=proxy)
2025-10-02 15:14:16.193237 | controller |
2025-10-02 15:14:16.193263 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for qemu.] *********
2025-10-02 15:14:16.193451 | controller | Thursday 02 October 2025  15:14:16 +0000 (0:00:00.081)       0:01:44.720 ******
2025-10-02 15:14:16.542647 | controller | ok: [instance]
2025-10-02 15:14:16.875175 | controller |
2025-10-02 15:14:16.875206 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for qemu.] **********
2025-10-02 15:14:16.875214 | controller | Thursday 02 October 2025  15:14:16 +0000 (0:00:00.350)       0:01:45.070 ******
2025-10-02 15:14:16.875230 | controller | ok: [instance]
2025-10-02 15:14:17.212581 | controller |
2025-10-02 15:14:17.212613 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for qemu.] **************
2025-10-02 15:14:17.212621 | controller | Thursday 02 October 2025  15:14:16 +0000 (0:00:00.332)       0:01:45.402 ******
2025-10-02 15:14:17.212631 | controller | ok: [instance]
2025-10-02 15:14:17.536947 | controller |
2025-10-02 15:14:17.536992 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for interface.] ****
2025-10-02 15:14:17.537001 | controller | Thursday 02 October 2025  15:14:17 +0000 (0:00:00.337)       0:01:45.740 ******
2025-10-02 15:14:17.537011 | controller | ok: [instance]
2025-10-02 15:14:17.855040 | controller |
2025-10-02 15:14:17.855077 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for interface.] *****
2025-10-02 15:14:17.855085 | controller | Thursday 02 October 2025  15:14:17 +0000 (0:00:00.324)       0:01:46.064 ******
2025-10-02 15:14:17.855096 | controller | ok: [instance]
2025-10-02 15:14:19.176762 | controller |
2025-10-02 15:14:19.176799 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for interface.] *********
2025-10-02 15:14:19.176810 | controller | Thursday 02 October 2025  15:14:17 +0000 (0:00:00.318)       0:01:46.382 ******
2025-10-02 15:14:19.176822 | controller | ok: [instance]
2025-10-02 15:14:19.503081 | controller |
2025-10-02 15:14:19.503119 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for network.] ******
2025-10-02 15:14:19.503127 | controller | Thursday 02 October 2025  15:14:19 +0000 (0:00:01.321)       0:01:47.704 ******
2025-10-02 15:14:19.503138 | controller | ok: [instance]
2025-10-02 15:14:19.503165 | controller |
2025-10-02 15:14:19.503173 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for network.] *******
2025-10-02 15:14:19.503271 | controller | Thursday 02 October 2025  15:14:19 +0000 (0:00:00.326)       0:01:48.031 ******
2025-10-02 15:14:19.820597 | controller | ok: [instance]
2025-10-02 15:14:20.140047 | controller |
2025-10-02 15:14:20.140083 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for network.] ***********
2025-10-02 15:14:20.140093 | controller | Thursday 02 October 2025  15:14:19 +0000 (0:00:00.317)       0:01:48.348 ******
2025-10-02 15:14:20.140106 | controller | ok: [instance]
2025-10-02 15:14:20.470759 | controller |
2025-10-02 15:14:20.470790 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nodedev.] ******
2025-10-02 15:14:20.470798 | controller | Thursday 02 October 2025  15:14:20 +0000 (0:00:00.319)       0:01:48.667 ******
2025-10-02 15:14:20.470812 | controller | ok: [instance]
2025-10-02 15:14:20.793725 | controller |
2025-10-02 15:14:20.793755 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nodedev.] *******
2025-10-02 15:14:20.793763 | controller | Thursday 02 October 2025  15:14:20 +0000 (0:00:00.330)       0:01:48.998 ******
2025-10-02 15:14:20.793773 | controller | ok: [instance]
2025-10-02 15:14:21.113054 | controller |
2025-10-02 15:14:21.113089 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nodedev.] ***********
2025-10-02 15:14:21.113099 | controller | Thursday 02 October 2025  15:14:20 +0000 (0:00:00.322)       0:01:49.321 ******
2025-10-02 15:14:21.113111 | controller | ok: [instance]
2025-10-02 15:14:21.113141 | controller |
2025-10-02 15:14:21.113374 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nwfilter.] *****
2025-10-02 15:14:21.434075 | controller | Thursday 02 October 2025  15:14:21 +0000 (0:00:00.319)       0:01:49.641 ******
2025-10-02 15:14:21.434115 | controller | ok: [instance]
2025-10-02 15:14:21.434148 | controller |
2025-10-02 15:14:21.434360 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nwfilter.] ******
2025-10-02 15:14:21.757033 | controller | Thursday 02 October 2025  15:14:21 +0000 (0:00:00.320)       0:01:49.962 ******
2025-10-02 15:14:21.757072 | controller | ok: [instance]
2025-10-02 15:14:22.078972 | controller |
2025-10-02 15:14:22.079004 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nwfilter.] **********
2025-10-02 15:14:22.079012 | controller | Thursday 02 October 2025  15:14:21 +0000 (0:00:00.322)       0:01:50.284 ******
2025-10-02 15:14:22.079023 | controller | ok: [instance]
2025-10-02 15:14:22.399419 | controller |
2025-10-02 15:14:22.399450 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for secret.] *******
2025-10-02 15:14:22.399458 | controller | Thursday 02 October 2025  15:14:22 +0000 (0:00:00.321)       0:01:50.606 ******
2025-10-02 15:14:22.399468 | controller | ok: [instance]
2025-10-02 15:14:22.725875 | controller |
2025-10-02 15:14:22.725908 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for secret.] ********
2025-10-02 15:14:22.725916 | controller | Thursday 02 October 2025  15:14:22 +0000 (0:00:00.320)       0:01:50.927 ******
2025-10-02 15:14:22.725926 | controller | ok: [instance]
2025-10-02 15:14:24.046063 | controller |
2025-10-02 15:14:24.046096 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for secret.] ************
2025-10-02 15:14:24.046109 | controller | Thursday 02 October 2025  15:14:22 +0000 (0:00:00.326)       0:01:51.253 ******
2025-10-02 15:14:24.046119 | controller | ok: [instance]
2025-10-02 15:14:24.046409 | controller |
2025-10-02 15:14:25.367888 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for storage.] ******
2025-10-02 15:14:25.367920 | controller | Thursday 02 October 2025  15:14:24 +0000 (0:00:01.320)       0:01:52.574 ******
2025-10-02 15:14:25.367938 | controller | ok: [instance]
2025-10-02 15:14:25.692054 | controller |
2025-10-02 15:14:25.692091 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for storage.] *******
2025-10-02 15:14:25.692099 | controller | Thursday 02 October 2025  15:14:25 +0000 (0:00:01.321)       0:01:53.895 ******
2025-10-02 15:14:25.692110 | controller | ok: [instance]
2025-10-02 15:14:25.692134 | controller |
2025-10-02 15:14:25.692143 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for storage.] ***********
2025-10-02 15:14:25.692171 | controller | Thursday 02 October 2025  15:14:25 +0000 (0:00:00.323)       0:01:54.219 ******
2025-10-02 15:14:26.015912 | controller | ok: [instance]
2025-10-02 15:14:26.335091 | controller |
2025-10-02 15:14:26.335134 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for proxy.] ********
2025-10-02 15:14:26.335144 | controller | Thursday 02 October 2025  15:14:26 +0000 (0:00:00.323)       0:01:54.543 ******
2025-10-02 15:14:26.335157 | controller | ok: [instance]
2025-10-02 15:14:26.656949 | controller |
2025-10-02 15:14:26.656997 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for proxy.] *********
2025-10-02 15:14:26.657006 | controller | Thursday 02 October 2025  15:14:26 +0000 (0:00:00.319)       0:01:54.862 ******
2025-10-02 15:14:26.657015 | controller | ok: [instance]
2025-10-02 15:14:26.977705 | controller |
2025-10-02 15:14:26.977735 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for proxy.] *************
2025-10-02 15:14:26.977742 | controller | Thursday 02 October 2025  15:14:26 +0000 (0:00:00.321)       0:01:55.184 ******
2025-10-02 15:14:26.977752 | controller | ok: [instance]
2025-10-02 15:14:26.994787 | controller |
2025-10-02 15:14:26.994812 | controller | TASK [libvirt_manager : Manage service for older releases] *********************
2025-10-02 15:14:26.994820 | controller | Thursday 02 October 2025  15:14:26 +0000 (0:00:00.320)       0:01:55.505 ******
2025-10-02 15:14:26.994830 | controller | skipping: [instance]
2025-10-02 15:14:27.268612 | controller |
2025-10-02 15:14:27.268642 | controller | TASK [libvirt_manager : Get libvirt group users] *******************************
2025-10-02 15:14:27.268650 | controller | Thursday 02 October 2025  15:14:26 +0000 (0:00:00.017)       0:01:55.522 ******
2025-10-02 15:14:27.268660 | controller | ok: [instance]
2025-10-02 15:14:27.649702 | controller |
2025-10-02 15:14:27.649734 | controller | TASK [libvirt_manager : Add user if not exists in libvirt group] ***************
2025-10-02 15:14:27.649742 | controller | Thursday 02 October 2025  15:14:27 +0000 (0:00:00.273)       0:01:55.796 ******
2025-10-02 15:14:27.649752 | controller | ok: [instance]
2025-10-02 15:14:27.961072 | controller |
2025-10-02 15:14:27.961103 | controller | TASK [libvirt_manager : Allow QEMU on home directory for the storage access] ***
2025-10-02 15:14:27.961111 | controller | Thursday 02 October 2025  15:14:27 +0000 (0:00:00.380)       0:01:56.177 ******
2025-10-02 15:14:27.961121 | controller | changed: [instance]
2025-10-02 15:14:27.961146 | controller |
2025-10-02 15:14:27.961154 | controller | TASK [libvirt_manager : Inject system connection parameters in bashrc] *********
2025-10-02 15:14:27.961275 | controller | Thursday 02 October 2025  15:14:27 +0000 (0:00:00.311)       0:01:56.488 ******
2025-10-02 15:14:28.143568 | controller | [WARNING]: Reset is not implemented for this connection
2025-10-02 15:14:28.165082 | controller | changed: [instance]
2025-10-02 15:14:28.165139 | controller |
2025-10-02 15:14:28.165150 | controller | TASK [libvirt_manager : Ensure we reload user env] *****************************
2025-10-02 15:14:28.165158 | controller | Thursday 02 October 2025  15:14:28 +0000 (0:00:00.177)       0:01:56.666 ******
2025-10-02 15:14:28.165165 | controller |
2025-10-02 15:14:28.165172 | controller | PLAY RECAP *********************************************************************
2025-10-02 15:14:28.165179 | controller | instance                   : ok=75   changed=23   unreachable=0    failed=0    skipped=41   rescued=0    ignored=0
2025-10-02 15:14:28.165185 | controller |
2025-10-02 15:14:28.165192 | controller | Thursday 02 October 2025  15:14:28 +0000 (0:00:00.025)       0:01:56.692 ******
2025-10-02 15:14:28.165201 | controller | ===============================================================================
2025-10-02 15:14:28.165226 | controller | test_deps : Install selinux python libs -------------------------------- 51.87s
2025-10-02 15:14:28.166089 | controller | libvirt_manager : Install packages required for using KVM -------------- 14.12s
2025-10-02 15:14:28.167033 | controller | ci_setup : Install needed packages -------------------------------------- 8.49s
2025-10-02 15:14:28.167075 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.96s
2025-10-02 15:14:28.167083 | controller | ci_setup : Install openshift client ------------------------------------- 5.67s
2025-10-02 15:14:28.167089 | controller | libvirt_manager : Ensure read-write socket is enabled for storage. ------ 1.32s
2025-10-02 15:14:28.167094 | controller | libvirt_manager : Ensure admin socket is enabled for interface. --------- 1.32s
2025-10-02 15:14:28.167099 | controller | libvirt_manager : Ensure admin socket is enabled for secret. ------------ 1.32s
2025-10-02 15:14:28.167104 | controller | test_deps : Install python yaml libs ------------------------------------ 1.21s
2025-10-02 15:14:28.167109 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.18s
2025-10-02 15:14:28.167119 | controller | ci_setup : Manage directories ------------------------------------------- 0.98s
2025-10-02 15:14:28.226864 | controller | libvirt_manager : Ensure firewalld service is up ------------------------ 0.93s
2025-10-02 15:14:28.226897 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.86s
2025-10-02 15:14:28.226905 | controller | Gathering Facts --------------------------------------------------------- 0.83s
2025-10-02 15:14:28.226910 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.82s
2025-10-02 15:14:28.226915 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.81s
2025-10-02 15:14:28.226920 | controller | repo_setup : Install repo-setup package --------------------------------- 0.73s
2025-10-02 15:14:28.226926 | controller | libvirt_manager : Reload systemctl and start/enable virtproxyd.service --- 0.68s
2025-10-02 15:14:28.226931 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.68s
2025-10-02 15:14:28.226936 | controller | libvirt_manager : Restart service polkit service ------------------------ 0.61s
2025-10-02 15:14:28.226947 | controller | INFO     Running default > converge
2025-10-02 15:14:28.648401 | controller |
2025-10-02 15:14:28.648631 | controller | PLAY [Converge] ****************************************************************
2025-10-02 15:14:28.648802 | controller |
2025-10-02 15:14:28.648974 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 15:14:28.649137 | controller | Thursday 02 October 2025  15:14:28 +0000 (0:00:00.012)       0:00:00.012 ******
2025-10-02 15:14:29.448062 | controller | ok: [instance]
2025-10-02 15:14:29.488091 | controller |
2025-10-02 15:14:29.488139 | controller | TASK [Including the openshift provisioner node role.] **************************
2025-10-02 15:14:29.488150 | controller | Thursday 02 October 2025  15:14:29 +0000 (0:00:00.799)       0:00:00.812 ******
2025-10-02 15:14:29.488162 | controller |
2025-10-02 15:14:29.786061 | controller | TASK [openshift_provisioner_node : Create the artifacts directory] *************
2025-10-02 15:14:29.786099 | controller | Thursday 02 October 2025  15:14:29 +0000 (0:00:00.040)       0:00:00.852 ******
2025-10-02 15:14:29.786112 | controller | ok: [instance -> localhost]
2025-10-02 15:14:29.786145 | controller |
2025-10-02 15:14:29.786152 | controller | TASK [openshift_provisioner_node : Adding user kni] ****************************
2025-10-02 15:14:29.786160 | controller | Thursday 02 October 2025  15:14:29 +0000 (0:00:00.297)       0:00:01.150 ******
2025-10-02 15:14:30.171085 | controller | changed: [instance]
2025-10-02 15:14:30.533062 | controller |
2025-10-02 15:14:30.533096 | controller | TASK [openshift_provisioner_node : Generate SSH keys] **************************
2025-10-02 15:14:30.533106 | controller | Thursday 02 October 2025  15:14:30 +0000 (0:00:00.384)       0:00:01.535 ******
2025-10-02 15:14:30.533118 | controller | changed: [instance -> localhost]
2025-10-02 15:14:30.533149 | controller |
2025-10-02 15:14:30.533348 | controller | TASK [openshift_provisioner_node : Enable passwordless access] *****************
2025-10-02 15:14:30.903860 | controller | Thursday 02 October 2025  15:14:30 +0000 (0:00:00.362)       0:00:01.897 ******
2025-10-02 15:14:30.903896 | controller | changed: [instance]
2025-10-02 15:15:02.145938 | controller |
2025-10-02 15:15:02.145996 | controller | TASK [openshift_provisioner_node : Install required packages need to execute openshift installer] ***
2025-10-02 15:15:02.146006 | controller | Thursday 02 October 2025  15:14:30 +0000 (0:00:00.370)       0:00:02.268 ******
2025-10-02 15:15:02.146030 | controller | changed: [instance]
2025-10-02 15:15:02.597026 | controller |
2025-10-02 15:15:02.597091 | controller | TASK [openshift_provisioner_node : Create the external network connection] *****
2025-10-02 15:15:02.597101 | controller | Thursday 02 October 2025  15:15:02 +0000 (0:00:31.241)       0:00:33.510 ******
2025-10-02 15:15:02.597115 | controller | changed: [instance]
2025-10-02 15:15:02.641467 | controller |
2025-10-02 15:15:02.641509 | controller | TASK [openshift_provisioner_node : Verify external network interface is defined] ***
2025-10-02 15:15:02.641517 | controller | Thursday 02 October 2025  15:15:02 +0000 (0:00:00.450)       0:00:33.960 ******
2025-10-02 15:15:02.641530 | controller | skipping: [instance]
2025-10-02 15:15:02.700865 | controller |
2025-10-02 15:15:02.700900 | controller | TASK [openshift_provisioner_node : Add iface to the external network connection] ***
2025-10-02 15:15:02.700908 | controller | Thursday 02 October 2025  15:15:02 +0000 (0:00:00.044)       0:00:34.005 ******
2025-10-02 15:15:02.700918 | controller | skipping: [instance]
2025-10-02 15:15:02.737248 | controller |
2025-10-02 15:15:02.737276 | controller | TASK [openshift_provisioner_node : Create the provisioning network connection] ***
2025-10-02 15:15:02.737284 | controller | Thursday 02 October 2025  15:15:02 +0000 (0:00:00.059)       0:00:34.065 ******
2025-10-02 15:15:02.737295 | controller | skipping: [instance]
2025-10-02 15:15:02.775762 | controller |
2025-10-02 15:15:02.775795 | controller | TASK [openshift_provisioner_node : Add iface to the provisioning network connection] ***
2025-10-02 15:15:02.775803 | controller | Thursday 02 October 2025  15:15:02 +0000 (0:00:00.036)       0:00:34.101 ******
2025-10-02 15:15:02.775814 | controller | skipping: [instance]
2025-10-02 15:15:02.815581 | controller |
2025-10-02 15:15:02.815614 | controller | TASK [openshift_provisioner_node : Check required virtualization networks] *****
2025-10-02 15:15:02.815623 | controller | Thursday 02 October 2025  15:15:02 +0000 (0:00:00.038)       0:00:34.140 ******
2025-10-02 15:15:02.815634 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/openshift_provisioner_node/tasks/add_virtual_network.yml for instance => (item=baremetal)
2025-10-02 15:15:03.693868 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/openshift_provisioner_node/tasks/add_virtual_network.yml for instance => (item=provisioning)
2025-10-02 15:15:03.693908 | controller |
2025-10-02 15:15:03.693917 | controller | TASK [openshift_provisioner_node : Define virtual network baremetal] ***********
2025-10-02 15:15:03.693923 | controller | Thursday 02 October 2025  15:15:02 +0000 (0:00:00.039)       0:00:34.179 ******
2025-10-02 15:15:03.693935 | controller | changed: [instance]
2025-10-02 15:15:03.693986 | controller |
2025-10-02 15:15:03.694021 | controller | TASK [openshift_provisioner_node : Start the network - baremetal] **************
2025-10-02 15:15:03.694125 | controller | Thursday 02 October 2025  15:15:03 +0000 (0:00:00.878)       0:00:35.058 ******
2025-10-02 15:15:03.942526 | controller | changed: [instance]
2025-10-02 15:15:04.186206 | controller |
2025-10-02 15:15:04.186238 | controller | TASK [openshift_provisioner_node : Enable network autostart for baremetal] *****
2025-10-02 15:15:04.186247 | controller | Thursday 02 October 2025  15:15:03 +0000 (0:00:00.248)       0:00:35.307 ******
2025-10-02 15:15:04.186257 | controller | changed: [instance]
2025-10-02 15:15:04.422055 | controller |
2025-10-02 15:15:04.422091 | controller | TASK [openshift_provisioner_node : Define virtual network provisioning] ********
2025-10-02 15:15:04.422100 | controller | Thursday 02 October 2025  15:15:04 +0000 (0:00:00.243)       0:00:35.550 ******
2025-10-02 15:15:04.422111 | controller | changed: [instance]
2025-10-02 15:15:04.422138 | controller |
2025-10-02 15:15:04.422406 | controller | TASK [openshift_provisioner_node : Start the network - provisioning] ***********
2025-10-02 15:15:04.651053 | controller | Thursday 02 October 2025  15:15:04 +0000 (0:00:00.235)       0:00:35.786 ******
2025-10-02 15:15:04.651088 | controller | changed: [instance]
2025-10-02 15:15:04.651113 | controller |
2025-10-02 15:15:04.651121 | controller | TASK [openshift_provisioner_node : Enable network autostart for provisioning] ***
2025-10-02 15:15:04.651207 | controller | Thursday 02 October 2025  15:15:04 +0000 (0:00:00.229)       0:00:36.015 ******
2025-10-02 15:15:04.879053 | controller | changed: [instance]
2025-10-02 15:15:04.879104 | controller |
2025-10-02 15:15:04.879360 | controller | TASK [openshift_provisioner_node : Cache the MAC addresses] ********************
2025-10-02 15:15:04.927652 | controller | Thursday 02 October 2025  15:15:04 +0000 (0:00:00.227)       0:00:36.243 ******
2025-10-02 15:15:04.927688 | controller | ok: [instance]
2025-10-02 15:15:05.201443 | controller |
2025-10-02 15:15:05.201473 | controller | TASK [openshift_provisioner_node : Read host SSH fingerprint] ******************
2025-10-02 15:15:05.201481 | controller | Thursday 02 October 2025  15:15:04 +0000 (0:00:00.048)       0:00:36.292 ******
2025-10-02 15:15:05.201492 | controller | ok: [instance]
2025-10-02 15:15:05.233938 | controller |
2025-10-02 15:15:05.233985 | controller | TASK [openshift_provisioner_node : Set the role output parameters] *************
2025-10-02 15:15:05.233994 | controller | Thursday 02 October 2025  15:15:05 +0000 (0:00:00.273)       0:00:36.566 ******
2025-10-02 15:15:05.234003 | controller | ok: [instance]
2025-10-02 15:15:05.269755 | controller |
2025-10-02 15:15:05.269774 | controller | TASK [openshift_provisioner_node : Cache provisioning bridge name] *************
2025-10-02 15:15:05.269788 | controller | Thursday 02 October 2025  15:15:05 +0000 (0:00:00.032)       0:00:36.598 ******
2025-10-02 15:15:05.269797 | controller | skipping: [instance]
2025-10-02 15:15:05.556419 | controller |
2025-10-02 15:15:05.556450 | controller | TASK [Verify user is created] **************************************************
2025-10-02 15:15:05.556458 | controller | Thursday 02 October 2025  15:15:05 +0000 (0:00:00.035)       0:00:36.634 ******
2025-10-02 15:15:05.556468 | controller | ok: [instance]
2025-10-02 15:15:05.662057 | controller |
2025-10-02 15:15:05.662088 | controller | TASK [Verify external network bridge exists] ***********************************
2025-10-02 15:15:05.662096 | controller | Thursday 02 October 2025  15:15:05 +0000 (0:00:00.286)       0:00:36.921 ******
2025-10-02 15:15:05.662107 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_000_verify_external_network.log
2025-10-02 15:15:05.662129 | controller | changed: [instance]
2025-10-02 15:15:05.662402 | controller |
2025-10-02 15:15:05.697067 | controller | TASK [Perform cleanup] *********************************************************
2025-10-02 15:15:05.697090 | controller | Thursday 02 October 2025  15:15:05 +0000 (0:00:00.105)       0:00:37.026 ******
2025-10-02 15:15:05.697103 | controller |
2025-10-02 15:15:05.697300 | controller | TASK [openshift_provisioner_node : Cleaning up the virtual networks] ***********
2025-10-02 15:15:05.726040 | controller | Thursday 02 October 2025  15:15:05 +0000 (0:00:00.035)       0:00:37.062 ******
2025-10-02 15:15:05.726067 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/openshift_provisioner_node/tasks/cleanup_virtual_network.yml for instance => (item=baremetal)
2025-10-02 15:15:05.966509 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/openshift_provisioner_node/tasks/cleanup_virtual_network.yml for instance => (item=provisioning)
2025-10-02 15:15:05.966547 | controller |
2025-10-02 15:15:05.966555 | controller | TASK [openshift_provisioner_node : Stopping virtual network baremetal] *********
2025-10-02 15:15:05.966562 | controller | Thursday 02 October 2025  15:15:05 +0000 (0:00:00.028)       0:00:37.090 ******
2025-10-02 15:15:05.966572 | controller | ok: [instance]
2025-10-02 15:15:06.195074 | controller |
2025-10-02 15:15:06.195109 | controller | TASK [openshift_provisioner_node : Remove virtual network baremetal] ***********
2025-10-02 15:15:06.195118 | controller | Thursday 02 October 2025  15:15:05 +0000 (0:00:00.240)       0:00:37.331 ******
2025-10-02 15:15:06.195128 | controller | ok: [instance]
2025-10-02 15:15:06.195149 | controller |
2025-10-02 15:15:06.195157 | controller | TASK [openshift_provisioner_node : Stopping virtual network provisioning] ******
2025-10-02 15:15:06.195279 | controller | Thursday 02 October 2025  15:15:06 +0000 (0:00:00.228)       0:00:37.559 ******
2025-10-02 15:15:06.426837 | controller | ok: [instance]
2025-10-02 15:15:06.674869 | controller |
2025-10-02 15:15:06.674907 | controller | TASK [openshift_provisioner_node : Remove virtual network provisioning] ********
2025-10-02 15:15:06.674916 | controller | Thursday 02 October 2025  15:15:06 +0000 (0:00:00.231)       0:00:37.791 ******
2025-10-02 15:15:06.674926 | controller | ok: [instance]
2025-10-02 15:15:06.911075 | controller |
2025-10-02 15:15:06.911117 | controller | TASK [openshift_provisioner_node : Remove provisioning network] ****************
2025-10-02 15:15:06.911136 | controller | Thursday 02 October 2025  15:15:06 +0000 (0:00:00.247)       0:00:38.039 ******
2025-10-02 15:15:06.911150 | controller | ok: [instance]
2025-10-02 15:15:07.290123 | controller |
2025-10-02 15:15:07.290172 | controller | TASK [openshift_provisioner_node : Remove the user] ****************************
2025-10-02 15:15:07.290183 | controller | Thursday 02 October 2025  15:15:06 +0000 (0:00:00.236)       0:00:38.275 ******
2025-10-02 15:15:07.290196 | controller | changed: [instance]
2025-10-02 15:15:07.290226 | controller |
2025-10-02 15:15:07.290234 | controller | PLAY RECAP *********************************************************************
2025-10-02 15:15:07.290243 | controller | instance                   : ok=28   changed=13   unreachable=0    failed=0    skipped=5    rescued=0    ignored=0
2025-10-02 15:15:07.292372 | controller |
2025-10-02 15:15:07.352880 | controller | Thursday 02 October 2025  15:15:07 +0000 (0:00:00.378)       0:00:38.654 ******
2025-10-02 15:15:07.352917 | controller | ===============================================================================
2025-10-02 15:15:07.352926 | controller | openshift_provisioner_node : Install required packages need to execute openshift installer -- 31.24s
2025-10-02 15:15:07.352932 | controller | openshift_provisioner_node : Define virtual network baremetal ----------- 0.88s
2025-10-02 15:15:07.352938 | controller | Gathering Facts --------------------------------------------------------- 0.80s
2025-10-02 15:15:07.352943 | controller | openshift_provisioner_node : Create the external network connection ----- 0.45s
2025-10-02 15:15:07.352948 | controller | openshift_provisioner_node : Adding user kni ---------------------------- 0.38s
2025-10-02 15:15:07.352973 | controller | openshift_provisioner_node : Remove the user ---------------------------- 0.38s
2025-10-02 15:15:07.352982 | controller | openshift_provisioner_node : Enable passwordless access ----------------- 0.37s
2025-10-02 15:15:07.352988 | controller | openshift_provisioner_node : Generate SSH keys -------------------------- 0.36s
2025-10-02 15:15:07.353001 | controller | openshift_provisioner_node : Create the artifacts directory ------------- 0.30s
2025-10-02 15:15:07.353007 | controller | Verify user is created -------------------------------------------------- 0.29s
2025-10-02 15:15:07.353012 | controller | openshift_provisioner_node : Read host SSH fingerprint ------------------ 0.27s
2025-10-02 15:15:07.353018 | controller | openshift_provisioner_node : Start the network - baremetal -------------- 0.25s
2025-10-02 15:15:07.353023 | controller | openshift_provisioner_node : Remove virtual network provisioning -------- 0.25s
2025-10-02 15:15:07.353029 | controller | openshift_provisioner_node : Enable network autostart for baremetal ----- 0.24s
2025-10-02 15:15:07.353034 | controller | openshift_provisioner_node : Stopping virtual network baremetal --------- 0.24s
2025-10-02 15:15:07.353039 | controller | openshift_provisioner_node : Remove provisioning network ---------------- 0.24s
2025-10-02 15:15:07.353044 | controller | openshift_provisioner_node : Define virtual network provisioning -------- 0.24s
2025-10-02 15:15:07.353050 | controller | openshift_provisioner_node : Stopping virtual network provisioning ------ 0.23s
2025-10-02 15:15:07.353056 | controller | openshift_provisioner_node : Start the network - provisioning ----------- 0.23s
2025-10-02 15:15:07.353061 | controller | openshift_provisioner_node : Remove virtual network baremetal ----------- 0.23s
2025-10-02 15:15:07.353071 | controller | INFO     Running default > cleanup
2025-10-02 15:15:07.353566 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-02 15:15:07.354177 | controller | INFO     Writing /tmp/report.html report.
2025-10-02 15:15:07.495589 | [controller] Waiting on logger
2025-10-02 15:15:12.673133 | [controller] Waiting on logger
2025-10-02 15:15:23.104842 | [controller] Waiting on logger
2025-10-02 15:15:33.536810 | [controller] Waiting on logger
2025-10-02 15:15:37.429348 | [Zuul] Log Stream did not terminate
2025-10-02 15:15:37.429543 | controller | changed
2025-10-02 15:15:37.452237 | 
2025-10-02 15:15:37.452292 | PLAY RECAP
2025-10-02 15:15:37.452332 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-02 15:15:37.452354 | 
2025-10-02 15:15:37.520322 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-02 15:15:37.521074 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-02 15:15:38.088970 | 
2025-10-02 15:15:38.089078 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-02 15:15:38.110208 | 
2025-10-02 15:15:38.110301 | TASK [Filter out host if needed]
2025-10-02 15:15:38.119114 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-02 15:15:38.123617 | 
2025-10-02 15:15:38.123684 | TASK [Ensure file is present]
2025-10-02 15:15:38.570375 | controller | ok
2025-10-02 15:15:38.575991 | 
2025-10-02 15:15:38.576067 | TASK [Manage molecule report file]
2025-10-02 15:15:38.598116 | [controller] Waiting on logger
2025-10-02 15:15:47.936988 | [controller] Waiting on logger
2025-10-02 15:15:58.368754 | [controller] Waiting on logger
2025-10-02 15:16:07.712868 | [controller] Waiting on logger
2025-10-02 15:16:09.002321 | [Zuul] Log Stream did not terminate
2025-10-02 15:16:09.002531 | controller | changed
2025-10-02 15:16:09.008054 | 
2025-10-02 15:16:09.008115 | TASK [Check if we get ci-framework-data basedir]
2025-10-02 15:16:09.289614 | controller | ok
2025-10-02 15:16:09.295164 | 
2025-10-02 15:16:09.295240 | TASK [Create ci-framework-data log directory for zuul]
2025-10-02 15:16:09.688890 | controller | changed
2025-10-02 15:16:09.695455 | 
2025-10-02 15:16:09.695540 | TASK [Copy ci-framework interesting files]
2025-10-02 15:16:09.717125 | [controller] Waiting on logger
2025-10-02 15:16:20.128858 | [controller] Waiting on logger
2025-10-02 15:16:29.509482 | [controller] Waiting on logger
2025-10-02 15:16:39.904913 | [controller] Waiting on logger
2025-10-02 15:16:40.009188 | [Zuul] Log Stream did not terminate
2025-10-02 15:16:40.009352 | controller | changed
2025-10-02 15:16:40.014684 | 
2025-10-02 15:16:40.014755 | TASK [Get SELinux listing]
2025-10-02 15:16:43.104953 | [controller] Waiting on logger
2025-10-02 15:16:53.536887 | [controller] Waiting on logger
2025-10-02 15:17:02.880931 | [controller] Waiting on logger
2025-10-02 15:17:10.318644 | [Zuul] Log Stream did not terminate
2025-10-02 15:17:10.318834 | controller | changed
2025-10-02 15:17:10.324407 | 
2025-10-02 15:17:10.324478 | TASK [Generate log index]
2025-10-02 15:17:11.608898 | controller | changed
2025-10-02 15:17:11.613965 | 
2025-10-02 15:17:11.614029 | TASK [Get some env related data]
2025-10-02 15:17:11.635361 | [controller] Waiting on logger
2025-10-02 15:17:22.016866 | [controller] Waiting on logger
2025-10-02 15:17:32.449025 | [controller] Waiting on logger
2025-10-02 15:17:41.792853 | [controller] Waiting on logger
2025-10-02 15:17:42.916280 | [Zuul] Log Stream did not terminate
2025-10-02 15:17:42.916457 | controller | changed
2025-10-02 15:17:42.921574 | 
2025-10-02 15:17:42.921633 | TASK [Generate list of logs to collect in home directory]
2025-10-02 15:17:43.292277 | controller | ok: All paths examined
2025-10-02 15:17:43.297618 | 
2025-10-02 15:17:43.297678 | LOOP [Copy logs from home directory]
2025-10-02 15:17:43.899776 | controller | changed:
2025-10-02 15:17:43.901179 | controller | {
2025-10-02 15:17:43.901222 | controller |   "atime": 1743544925.4788878,
2025-10-02 15:17:43.901255 | controller |   "ctime": 1743545329.1409318,
2025-10-02 15:17:43.901280 | controller |   "dev": 64513,
2025-10-02 15:17:43.901300 | controller |   "gid": 1000,
2025-10-02 15:17:43.901317 | controller |   "gr_name": "zuul",
2025-10-02 15:17:43.901333 | controller |   "inode": 4518807,
2025-10-02 15:17:43.901349 | controller |   "isblk": false,
2025-10-02 15:17:43.901364 | controller |   "ischr": false,
2025-10-02 15:17:43.901397 | controller |   "isdir": false,
2025-10-02 15:17:43.901443 | controller |   "isfifo": false,
2025-10-02 15:17:43.901466 | controller |   "isgid": false,
2025-10-02 15:17:43.901485 | controller |   "islnk": false,
2025-10-02 15:17:43.901502 | controller |   "isreg": true,
2025-10-02 15:17:43.901517 | controller |   "issock": false,
2025-10-02 15:17:43.901533 | controller |   "isuid": false,
2025-10-02 15:17:43.901548 | controller |   "mode": "0644",
2025-10-02 15:17:43.901563 | controller |   "mtime": 1743545329.1409318,
2025-10-02 15:17:43.901577 | controller |   "nlink": 1,
2025-10-02 15:17:43.901593 | controller |   "path": "/home/zuul/crc-setup.log",
2025-10-02 15:17:43.901608 | controller |   "pw_name": "zuul",
2025-10-02 15:17:43.901624 | controller |   "rgrp": true,
2025-10-02 15:17:43.901639 | controller |   "roth": true,
2025-10-02 15:17:43.901653 | controller |   "rusr": true,
2025-10-02 15:17:43.901667 | controller |   "size": 4108,
2025-10-02 15:17:43.901681 | controller |   "uid": 1000,
2025-10-02 15:17:43.901695 | controller |   "wgrp": false,
2025-10-02 15:17:43.901709 | controller |   "woth": false,
2025-10-02 15:17:43.901723 | controller |   "wusr": true,
2025-10-02 15:17:43.901737 | controller |   "xgrp": false,
2025-10-02 15:17:43.901751 | controller |   "xoth": false,
2025-10-02 15:17:43.901766 | controller |   "xusr": false
2025-10-02 15:17:43.901794 | controller | }
2025-10-02 15:17:44.510304 | controller | changed:
2025-10-02 15:17:44.510417 | controller | {
2025-10-02 15:17:44.510451 | controller |   "atime": 1743545331.0429637,
2025-10-02 15:17:44.510473 | controller |   "ctime": 1743545842.0111232,
2025-10-02 15:17:44.510491 | controller |   "dev": 64513,
2025-10-02 15:17:44.510509 | controller |   "gid": 1000,
2025-10-02 15:17:44.510525 | controller |   "gr_name": "zuul",
2025-10-02 15:17:44.510541 | controller |   "inode": 4194437,
2025-10-02 15:17:44.510556 | controller |   "isblk": false,
2025-10-02 15:17:44.510571 | controller |   "ischr": false,
2025-10-02 15:17:44.510585 | controller |   "isdir": false,
2025-10-02 15:17:44.510599 | controller |   "isfifo": false,
2025-10-02 15:17:44.510613 | controller |   "isgid": false,
2025-10-02 15:17:44.510627 | controller |   "islnk": false,
2025-10-02 15:17:44.510641 | controller |   "isreg": true,
2025-10-02 15:17:44.510655 | controller |   "issock": false,
2025-10-02 15:17:44.510670 | controller |   "isuid": false,
2025-10-02 15:17:44.510684 | controller |   "mode": "0644",
2025-10-02 15:17:44.510698 | controller |   "mtime": 1743545842.0111232,
2025-10-02 15:17:44.510712 | controller |   "nlink": 1,
2025-10-02 15:17:44.510726 | controller |   "path": "/home/zuul/crc-start.log",
2025-10-02 15:17:44.510746 | controller |   "pw_name": "zuul",
2025-10-02 15:17:44.510763 | controller |   "rgrp": true,
2025-10-02 15:17:44.510791 | controller |   "roth": true,
2025-10-02 15:17:44.510809 | controller |   "rusr": true,
2025-10-02 15:17:44.510825 | controller |   "size": 4023,
2025-10-02 15:17:44.510839 | controller |   "uid": 1000,
2025-10-02 15:17:44.510853 | controller |   "wgrp": false,
2025-10-02 15:17:44.510869 | controller |   "woth": false,
2025-10-02 15:17:44.510884 | controller |   "wusr": true,
2025-10-02 15:17:44.510899 | controller |   "xgrp": false,
2025-10-02 15:17:44.510947 | controller |   "xoth": false,
2025-10-02 15:17:44.511033 | controller |   "xusr": false
2025-10-02 15:17:44.511156 | controller | }
2025-10-02 15:17:45.079527 | controller | changed:
2025-10-02 15:17:45.079610 | controller | {
2025-10-02 15:17:45.079637 | controller |   "atime": 1759417914.6517444,
2025-10-02 15:17:45.079657 | controller |   "ctime": 1759417930.5637302,
2025-10-02 15:17:45.079675 | controller |   "dev": 64513,
2025-10-02 15:17:45.079693 | controller |   "gid": 1000,
2025-10-02 15:17:45.079709 | controller |   "gr_name": "zuul",
2025-10-02 15:17:45.079727 | controller |   "inode": 4686284,
2025-10-02 15:17:45.079744 | controller |   "isblk": false,
2025-10-02 15:17:45.079759 | controller |   "ischr": false,
2025-10-02 15:17:45.079774 | controller |   "isdir": false,
2025-10-02 15:17:45.079801 | controller |   "isfifo": false,
2025-10-02 15:17:45.079817 | controller |   "isgid": false,
2025-10-02 15:17:45.079832 | controller |   "islnk": false,
2025-10-02 15:17:45.079847 | controller |   "isreg": true,
2025-10-02 15:17:45.079861 | controller |   "issock": false,
2025-10-02 15:17:45.079875 | controller |   "isuid": false,
2025-10-02 15:17:45.079889 | controller |   "mode": "0644",
2025-10-02 15:17:45.079903 | controller |   "mtime": 1759417930.5637302,
2025-10-02 15:17:45.079917 | controller |   "nlink": 1,
2025-10-02 15:17:45.079931 | controller |   "path": "/home/zuul/ansible.log",
2025-10-02 15:17:45.079945 | controller |   "pw_name": "zuul",
2025-10-02 15:17:45.079960 | controller |   "rgrp": true,
2025-10-02 15:17:45.079974 | controller |   "roth": true,
2025-10-02 15:17:45.079987 | controller |   "rusr": true,
2025-10-02 15:17:45.080001 | controller |   "size": 6749,
2025-10-02 15:17:45.080015 | controller |   "uid": 1000,
2025-10-02 15:17:45.080028 | controller |   "wgrp": false,
2025-10-02 15:17:45.080041 | controller |   "woth": false,
2025-10-02 15:17:45.080054 | controller |   "wusr": true,
2025-10-02 15:17:45.080067 | controller |   "xgrp": false,
2025-10-02 15:17:45.080080 | controller |   "xoth": false,
2025-10-02 15:17:45.080095 | controller |   "xusr": false
2025-10-02 15:17:45.080109 | controller | }
2025-10-02 15:17:45.092647 | 
2025-10-02 15:17:45.092713 | TASK [Copy crio stats log file]
2025-10-02 15:17:45.106056 | controller | skipping: Conditional result was False
2025-10-02 15:17:45.111239 | 
2025-10-02 15:17:45.111301 | TASK [Get SELinux related data]
2025-10-02 15:17:45.132284 | [controller] Waiting on logger
2025-10-02 15:17:51.379012 | controller | ERROR
2025-10-02 15:17:51.379240 | controller | {
2025-10-02 15:17:51.379292 | controller |   "delta": "0:00:00.008990",
2025-10-02 15:17:51.379316 | controller |   "end": "2025-10-02 15:17:45.390795",
2025-10-02 15:17:51.379338 | controller |   "msg": "non-zero return code",
2025-10-02 15:17:51.379353 | controller |   "rc": 1,
2025-10-02 15:17:51.379368 | controller |   "start": "2025-10-02 15:17:45.381805"
2025-10-02 15:17:51.379399 | controller | }
2025-10-02 15:17:51.379431 | controller | ERROR: Ignoring Errors
2025-10-02 15:17:51.384922 | 
2025-10-02 15:17:51.384985 | TASK [Create system configuration directory]
2025-10-02 15:17:51.658479 | controller | changed
2025-10-02 15:17:51.663872 | 
2025-10-02 15:17:51.663944 | TASK [Get some of the system configurations]
2025-10-02 15:17:51.688173 | [controller] Waiting on logger
2025-10-02 15:18:02.080888 | [controller] Waiting on logger
2025-10-02 15:18:12.512731 | [controller] Waiting on logger
2025-10-02 15:18:21.856920 | [controller] Waiting on logger
2025-10-02 15:18:21.987227 | [Zuul] Log Stream did not terminate
2025-10-02 15:18:21.987418 | controller | changed
2025-10-02 15:18:21.992606 | 
2025-10-02 15:18:21.992667 | TASK [Copy generated documentation if available]
2025-10-02 15:18:22.006449 | controller | skipping: Conditional result was False
2025-10-02 15:18:22.011713 | 
2025-10-02 15:18:22.011789 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-02 15:18:22.024918 | controller | skipping: Conditional result was False
2025-10-02 15:18:22.030013 | 
2025-10-02 15:18:22.030076 | TASK [Compress logs bigger than 2MB]
2025-10-02 15:18:25.120860 | [controller] Waiting on logger
2025-10-02 15:18:31.378690 | controller | changed
2025-10-02 15:18:31.383987 | 
2025-10-02 15:18:31.384052 | TASK [Copy files from workspace on node]
2025-10-02 15:18:31.402067 | controller | ok
2025-10-02 15:18:31.425333 | 
2025-10-02 15:18:31.425440 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-02 15:18:31.438885 | controller | skipping: Conditional result was False
2025-10-02 15:18:31.445322 | 
2025-10-02 15:18:31.445418 | TASK [fetch-output : Set log path for single node]
2025-10-02 15:18:31.476519 | controller | ok
2025-10-02 15:18:31.485053 | 
2025-10-02 15:18:31.485116 | LOOP [fetch-output : Ensure local output dirs]
2025-10-02 15:18:31.707284 | controller -> localhost | ok: "/var/lib/zuul/builds/b34d8e63349847dc98b841f857d95291/work/logs"
2025-10-02 15:18:31.707529 | controller -> localhost | changed: All items complete
2025-10-02 15:18:31.707559 | 
2025-10-02 15:18:31.895024 | controller -> localhost | changed: "/var/lib/zuul/builds/b34d8e63349847dc98b841f857d95291/work/artifacts"
2025-10-02 15:18:32.081365 | controller -> localhost | changed: "/var/lib/zuul/builds/b34d8e63349847dc98b841f857d95291/work/docs"
2025-10-02 15:18:32.096697 | 
2025-10-02 15:18:32.096820 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-02 15:18:32.746264 | controller | changed:
2025-10-02 15:18:32.746481 | controller | .d..t...... ./
2025-10-02 15:18:32.746517 | controller | >f+++++++++ README.html
2025-10-02 15:18:32.746541 | controller | >f+++++++++ ansible-execution.log
2025-10-02 15:18:32.746562 | controller | >f+++++++++ ansible.log
2025-10-02 15:18:32.746581 | controller | >f+++++++++ crc-setup.log
2025-10-02 15:18:32.746600 | controller | >f+++++++++ crc-start.log
2025-10-02 15:18:32.746618 | controller | >f+++++++++ dmesg.log
2025-10-02 15:18:32.746635 | controller | >f+++++++++ installed-pkgs.log
2025-10-02 15:18:32.746651 | controller | >f+++++++++ python.log
2025-10-02 15:18:32.746669 | controller | >f+++++++++ registries.conf
2025-10-02 15:18:32.746686 | controller | >f+++++++++ report.html
2025-10-02 15:18:32.746703 | controller | >f+++++++++ selinux-denials.log
2025-10-02 15:18:32.746721 | controller | >f+++++++++ selinux-listing.log
2025-10-02 15:18:32.746738 | controller | cd+++++++++ ci-framework-data/
2025-10-02 15:18:32.746754 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-02 15:18:32.746770 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_verify_external_network.sh
2025-10-02 15:18:32.746806 | controller | >f+++++++++ ci-framework-data/artifacts/kni_id_cifw
2025-10-02 15:18:32.746825 | controller | >f+++++++++ ci-framework-data/artifacts/kni_id_cifw.pub
2025-10-02 15:18:32.746840 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-02 15:18:32.746856 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-10-02 15:18:32.746871 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-10-02 15:18:32.746886 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-10-02 15:18:32.746901 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-02 15:18:32.746917 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-02 15:18:32.746933 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-02 15:18:32.746955 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-02 15:18:32.746971 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-02 15:18:32.746987 | controller | >f+++++++++ ci-framework-data/logs/ci_script_000_verify_external_network.log
2025-10-02 15:18:32.747002 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-02 15:18:32.747017 | controller | cd+++++++++ registries.conf.d/
2025-10-02 15:18:32.747034 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-02 15:18:32.747049 | controller | cd+++++++++ system-config/
2025-10-02 15:18:32.747064 | controller | cd+++++++++ system-config/libvirt/
2025-10-02 15:18:32.747079 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-02 15:18:32.747093 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-02 15:18:32.747108 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-10-02 15:18:32.747122 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-10-02 15:18:32.747136 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-10-02 15:18:32.747151 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-10-02 15:18:32.747165 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-10-02 15:18:32.747180 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-10-02 15:18:32.747202 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-10-02 15:18:32.747217 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-10-02 15:18:32.747232 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-10-02 15:18:32.747247 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-10-02 15:18:32.747263 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-10-02 15:18:32.747279 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-10-02 15:18:32.747295 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-10-02 15:18:32.747310 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-10-02 15:18:33.203767 | controller | changed: .d..t...... ./
2025-10-02 15:18:33.675542 | controller | changed: .d..t...... ./
2025-10-02 15:18:33.696892 | 
2025-10-02 15:18:33.697013 | TASK [Return artifact to Zuul]
2025-10-02 15:18:33.726554 | controller | ok
2025-10-02 15:18:33.745505 | 
2025-10-02 15:18:33.745566 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-02 15:18:33.745671 | 
2025-10-02 15:18:33.745699 | PLAY RECAP
2025-10-02 15:18:33.745736 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-02 15:18:33.745758 | 
2025-10-02 15:18:33.834064 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-02 15:18:33.834802 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-02 15:18:34.358826 | 
2025-10-02 15:18:34.358923 | PLAY [all]
2025-10-02 15:18:34.377015 | 
2025-10-02 15:18:34.377089 | TASK [include_role : fetch-output]
2025-10-02 15:18:34.407645 | controller | ok
2025-10-02 15:18:34.424324 | 
2025-10-02 15:18:34.424445 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-02 15:18:34.479068 | controller | skipping: Conditional result was False
2025-10-02 15:18:34.485652 | 
2025-10-02 15:18:34.485740 | TASK [fetch-output : Set log path for single node]
2025-10-02 15:18:34.516548 | controller | ok
2025-10-02 15:18:34.522001 | 
2025-10-02 15:18:34.522133 | LOOP [fetch-output : Ensure local output dirs]
2025-10-02 15:18:34.875763 | controller -> localhost | ok: "/var/lib/zuul/builds/b34d8e63349847dc98b841f857d95291/work/logs"
2025-10-02 15:18:35.087683 | controller -> localhost | ok: "/var/lib/zuul/builds/b34d8e63349847dc98b841f857d95291/work/artifacts"
2025-10-02 15:18:35.287883 | controller -> localhost | ok: "/var/lib/zuul/builds/b34d8e63349847dc98b841f857d95291/work/docs"
2025-10-02 15:18:35.298500 | 
2025-10-02 15:18:35.298599 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-02 15:18:35.909625 | controller | ok
2025-10-02 15:18:35.909817 | controller | ok: All items complete
2025-10-02 15:18:35.909847 | 
2025-10-02 15:18:36.368422 | controller | ok
2025-10-02 15:18:36.835299 | controller | ok
2025-10-02 15:18:36.847063 | 
2025-10-02 15:18:36.847144 | TASK [include_role : fetch-output-openshift]
2025-10-02 15:18:36.864433 | controller | skipping: Conditional result was False
2025-10-02 15:18:36.873548 | 
2025-10-02 15:18:36.873658 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-02 15:18:37.244699 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007245
2025-10-02 15:18:37.440376 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007240
2025-10-02 15:18:37.473604 | 
2025-10-02 15:18:37.473671 | PLAY [all]
2025-10-02 15:18:37.488156 | 
2025-10-02 15:18:37.488226 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-02 15:18:37.968185 | controller | changed
2025-10-02 15:18:37.990660 | 
2025-10-02 15:18:37.990736 | PLAY RECAP
2025-10-02 15:18:37.990792 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-02 15:18:37.990817 | 
2025-10-02 15:18:38.060996 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-02 15:18:38.061691 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-02 15:18:38.620239 | 
2025-10-02 15:18:38.620363 | PLAY [localhost]
2025-10-02 15:18:38.637703 | 
2025-10-02 15:18:38.637789 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-02 15:18:38.948757 | localhost | changed
2025-10-02 15:18:38.953567 | 
2025-10-02 15:18:38.953656 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-02 15:18:38.971279 | localhost | ok
2025-10-02 15:18:38.979139 | 
2025-10-02 15:18:38.979213 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-02 15:18:39.289942 | localhost | changed
2025-10-02 15:18:39.295479 | 
2025-10-02 15:18:39.295557 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-02 15:18:39.851201 | localhost | changed
2025-10-02 15:18:39.856124 | 
2025-10-02 15:18:39.856201 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-02 15:18:40.218901 | localhost | Identity added: /var/lib/zuul/builds/b34d8e63349847dc98b841f857d95291/work/tmp/ansible.4bsrasq1 (/var/lib/zuul/builds/b34d8e63349847dc98b841f857d95291/work/tmp/ansible.4bsrasq1)
2025-10-02 15:18:40.219060 | localhost | ok: Runtime: 0:00:00.006270
2025-10-02 15:18:40.223502 | 
2025-10-02 15:18:40.223574 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-02 15:18:40.464435 | localhost | ok: Runtime: 0:00:00.004221
2025-10-02 15:18:40.469351 | 
2025-10-02 15:18:40.469464 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-02 15:18:40.518818 | localhost | changed
2025-10-02 15:18:40.523053 | 
2025-10-02 15:18:40.523132 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-02 15:18:40.854524 | localhost | changed
2025-10-02 15:18:40.873753 | 
2025-10-02 15:18:40.873945 | PLAY [localhost]
2025-10-02 15:18:40.885561 | 
2025-10-02 15:18:40.885636 | TASK [Generate bulk log download script]
2025-10-02 15:18:40.903582 | localhost | ok
2025-10-02 15:18:40.915260 | 
2025-10-02 15:18:40.915343 | TASK [local-log-download : Check API endpoint is defined]
2025-10-02 15:18:40.942715 | localhost | ok: All assertions passed
2025-10-02 15:18:40.947349 | 
2025-10-02 15:18:40.947442 | TASK [local-log-download : Create download script]
2025-10-02 15:18:41.294568 | localhost -> localhost | changed
2025-10-02 15:18:41.303105 | 
2025-10-02 15:18:41.303172 | TASK [Register quick-download link]
2025-10-02 15:18:41.319737 | localhost | ok
2025-10-02 15:18:41.353660 | 
2025-10-02 15:18:41.353746 | PLAY [logserver.rdoproject.org]
2025-10-02 15:18:41.363233 | 
2025-10-02 15:18:41.363296 | TASK [Set zuul-log-path fact]
2025-10-02 15:18:41.378838 | logserver.rdoproject.org | ok
2025-10-02 15:18:41.387671 | 
2025-10-02 15:18:41.387741 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-02 15:18:41.413737 | logserver.rdoproject.org | ok
2025-10-02 15:18:41.418995 | 
2025-10-02 15:18:41.419059 | TASK [upload-logs : Create log directories]
2025-10-02 15:18:42.996753 | logserver.rdoproject.org | changed
2025-10-02 15:18:42.999736 | 
2025-10-02 15:18:42.999815 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-02 15:18:43.246404 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005626
2025-10-02 15:18:43.251843 | 
2025-10-02 15:18:43.251946 | TASK [upload-logs : Upload logs to log server]
2025-10-02 15:18:44.779183 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-02 15:18:44.782611 | 
2025-10-02 15:18:44.782734 | LOOP [upload-logs : Compress console log and json output]
2025-10-02 15:18:44.821080 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 15:18:44.831030 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 15:18:44.840482 | 
2025-10-02 15:18:44.840589 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-02 15:18:44.876561 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 15:18:44.876816 | 
2025-10-02 15:18:44.879898 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 15:18:44.887608 | 
2025-10-02 15:18:44.887702 | LOOP [upload-logs : Upload console log and json output]