2025-10-02 15:28:20.736123 | Job console starting...
2025-10-02 15:28:20.987523 | Updating repositories
2025-10-02 15:28:22.033823 | Preparing job workspace
2025-10-02 15:28:39.884062 | Running Ansible setup...
2025-10-02 15:28:51.706680 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-02 15:28:54.396162 | 
2025-10-02 15:28:54.396268 | PLAY [localhost]
2025-10-02 15:28:54.451219 | 
2025-10-02 15:28:54.451317 | TASK [Gathering Facts]
2025-10-02 15:28:57.769184 | localhost | ok
2025-10-02 15:28:57.821352 | 
2025-10-02 15:28:57.821497 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-02 15:28:59.950037 | localhost -> localhost | changed
2025-10-02 15:28:59.955828 | 
2025-10-02 15:28:59.955906 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-02 15:29:03.452951 | localhost -> localhost | changed
2025-10-02 15:29:03.462025 | 
2025-10-02 15:29:03.462097 | TASK [Setup log path fact]
2025-10-02 15:29:03.542485 | localhost | ok
2025-10-02 15:29:03.604489 | 
2025-10-02 15:29:03.604601 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-02 15:29:03.738060 | localhost | ok
2025-10-02 15:29:03.763364 | 
2025-10-02 15:29:03.763473 | TASK [emit-job-header : Print job information]
2025-10-02 15:29:03.920427 | # Job Information
2025-10-02 15:29:03.920570 | Ansible Version: 2.15.12
2025-10-02 15:29:03.920601 | Job: cifmw-molecule-openshift_provisioner_node
2025-10-02 15:29:03.920625 | Pipeline: github-check
2025-10-02 15:29:03.920667 | Executor: ibm-bm3-ze.softwarefactory-project.io
2025-10-02 15:29:03.920694 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3362
2025-10-02 15:29:03.920716 | Log URL (when completed): https://logserver.rdoproject.org/204/rdoproject.org/2044b1b706ee4a9bb7d0b759f8326766/
2025-10-02 15:29:03.920738 | Event ID: 31e612e0-9fa4-11f0-8bb8-66cf2469bdc9
2025-10-02 15:29:03.925015 | 
2025-10-02 15:29:03.925127 | LOOP [emit-job-header : Print node information]
2025-10-02 15:29:04.358226 | localhost | ok:
2025-10-02 15:29:04.358380 | localhost | # Node Information
2025-10-02 15:29:04.358429 | localhost | Inventory Hostname: controller
2025-10-02 15:29:04.358456 | localhost | Hostname: np0005466675
2025-10-02 15:29:04.358476 | localhost | Username: zuul
2025-10-02 15:29:04.358497 | localhost | Distro: CentOS 9
2025-10-02 15:29:04.358515 | localhost | Provider: ibm-bm3-nodepool
2025-10-02 15:29:04.358532 | localhost | Region: regionOne
2025-10-02 15:29:04.358549 | localhost | Label: centos-9-stream-crc-2-48-0-xl-ibm
2025-10-02 15:29:04.358565 | localhost | Product Name: OpenStack Compute
2025-10-02 15:29:04.358581 | localhost | Interface IP: 192.168.25.161
2025-10-02 15:29:04.428823 | 
2025-10-02 15:29:04.428917 | PLAY [all]
2025-10-02 15:29:04.486841 | 
2025-10-02 15:29:04.486933 | TASK [Gather network facts]
2025-10-02 15:29:05.051023 | controller | ok
2025-10-02 15:29:05.109728 | 
2025-10-02 15:29:05.109826 | TASK [include_role : start-zuul-console]
2025-10-02 15:29:05.157561 | controller | ok
2025-10-02 15:29:05.204080 | 
2025-10-02 15:29:05.204556 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-02 15:29:06.153061 | controller | ok
2025-10-02 15:29:06.170093 | 
2025-10-02 15:29:06.170168 | TASK [include_role : add-build-sshkey]
2025-10-02 15:29:06.238549 | controller | ok
2025-10-02 15:29:06.295159 | 
2025-10-02 15:29:06.295247 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-02 15:29:07.354177 | controller -> localhost | ok
2025-10-02 15:29:07.360059 | 
2025-10-02 15:29:07.360130 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-02 15:29:07.429378 | controller | ok
2025-10-02 15:29:07.455499 | controller | included: /var/lib/zuul/builds/2044b1b706ee4a9bb7d0b759f8326766/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-02 15:29:07.469935 | 
2025-10-02 15:29:07.470012 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-02 15:29:09.318452 | controller -> localhost | Generating public/private rsa key pair.
2025-10-02 15:29:09.318639 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/2044b1b706ee4a9bb7d0b759f8326766/work/2044b1b706ee4a9bb7d0b759f8326766_id_rsa.
2025-10-02 15:29:09.318674 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/2044b1b706ee4a9bb7d0b759f8326766/work/2044b1b706ee4a9bb7d0b759f8326766_id_rsa.pub.
2025-10-02 15:29:09.318699 | controller -> localhost | The key fingerprint is:
2025-10-02 15:29:09.318722 | controller -> localhost | SHA256:MmFiDPIQtfDMnRJ2gPdqt8KFi8vW+8mUL3rl2QrfFMA zuul-build-sshkey
2025-10-02 15:29:09.318744 | controller -> localhost | The key's randomart image is:
2025-10-02 15:29:09.318766 | controller -> localhost | +---[RSA 3072]----+
2025-10-02 15:29:09.318801 | controller -> localhost | |=+*..            |
2025-10-02 15:29:09.318824 | controller -> localhost | |.X.B ..          |
2025-10-02 15:29:09.318845 | controller -> localhost | | .B.* oE         |
2025-10-02 15:29:09.318865 | controller -> localhost | |   o.o ..        |
2025-10-02 15:29:09.318885 | controller -> localhost | |   o  o S.       |
2025-10-02 15:29:09.318904 | controller -> localhost | |  + o .+  .      |
2025-10-02 15:29:09.318923 | controller -> localhost | | +.+ =o o.       |
2025-10-02 15:29:09.318942 | controller -> localhost | |o.+.+o=oo.       |
2025-10-02 15:29:09.318962 | controller -> localhost | |oo +=+.+..       |
2025-10-02 15:29:09.318982 | controller -> localhost | +----[SHA256]-----+
2025-10-02 15:29:09.319027 | controller -> localhost | ok: Runtime: 0:00:00.611026
2025-10-02 15:29:09.324957 | 
2025-10-02 15:29:09.325024 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-02 15:29:09.376262 | controller | ok
2025-10-02 15:29:09.405185 | controller | included: /var/lib/zuul/builds/2044b1b706ee4a9bb7d0b759f8326766/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-02 15:29:09.436863 | 
2025-10-02 15:29:09.436940 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-02 15:29:09.494489 | controller | skipping: Conditional result was False
2025-10-02 15:29:09.501502 | 
2025-10-02 15:29:09.501581 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-02 15:29:10.597921 | controller | changed
2025-10-02 15:29:10.704118 | 
2025-10-02 15:29:10.704673 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-02 15:29:11.033366 | controller | ok
2025-10-02 15:29:11.038261 | 
2025-10-02 15:29:11.038327 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-02 15:29:12.356101 | controller | changed
2025-10-02 15:29:12.368560 | 
2025-10-02 15:29:12.368631 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-02 15:29:13.657108 | controller | changed
2025-10-02 15:29:13.675047 | 
2025-10-02 15:29:13.675135 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-02 15:29:13.743600 | controller | skipping: Conditional result was False
2025-10-02 15:29:13.749376 | 
2025-10-02 15:29:13.749521 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-02 15:29:14.882131 | controller -> localhost | changed
2025-10-02 15:29:14.897036 | 
2025-10-02 15:29:14.897117 | TASK [add-build-sshkey : Add back temp key]
2025-10-02 15:29:15.683200 | controller -> localhost | Identity added: /var/lib/zuul/builds/2044b1b706ee4a9bb7d0b759f8326766/work/2044b1b706ee4a9bb7d0b759f8326766_id_rsa (zuul-build-sshkey)
2025-10-02 15:29:15.683379 | controller -> localhost | ok: Runtime: 0:00:00.025082
2025-10-02 15:29:15.688799 | 
2025-10-02 15:29:15.688860 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-02 15:29:16.289450 | controller | ok
2025-10-02 15:29:16.294182 | 
2025-10-02 15:29:16.294245 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-02 15:29:16.380606 | controller | skipping: Conditional result was False
2025-10-02 15:29:16.390803 | 
2025-10-02 15:29:16.390877 | TASK [include_role : validate-host]
2025-10-02 15:29:16.503203 | controller | ok
2025-10-02 15:29:16.584045 | 
2025-10-02 15:29:16.584155 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-02 15:29:16.676690 | controller | ok
2025-10-02 15:29:16.684465 | 
2025-10-02 15:29:16.687341 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-02 15:29:17.307516 | controller -> localhost | ok
2025-10-02 15:29:17.313313 | 
2025-10-02 15:29:17.313410 | TASK [validate-host : Collect information about the host]
2025-10-02 15:29:18.107194 | controller | ok
2025-10-02 15:29:18.132602 | 
2025-10-02 15:29:18.132688 | TASK [validate-host : Sanitize hostname]
2025-10-02 15:29:18.289955 | controller | ok
2025-10-02 15:29:18.295419 | 
2025-10-02 15:29:18.295487 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-02 15:29:19.730665 | controller -> localhost | changed
2025-10-02 15:29:19.737524 | 
2025-10-02 15:29:19.737605 | TASK [validate-host : Collect information about zuul worker]
2025-10-02 15:29:20.795577 | controller | ok
2025-10-02 15:29:20.800664 | 
2025-10-02 15:29:20.802457 | TASK [validate-host : Write out all zuul information for each host]
2025-10-02 15:29:22.588322 | controller -> localhost | changed
2025-10-02 15:29:22.601056 | 
2025-10-02 15:29:22.601134 | TASK [include_role : prepare-workspace-openshift]
2025-10-02 15:29:22.658190 | controller | skipping: Conditional result was False
2025-10-02 15:29:22.671247 | 
2025-10-02 15:29:22.671331 | TASK [include_role : remove-zuul-sshkey]
2025-10-02 15:29:22.740966 | controller | skipping: Conditional result was False
2025-10-02 15:29:22.746798 | 
2025-10-02 15:29:22.746868 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-02 15:29:23.120599 | controller | ok: "logs"
2025-10-02 15:29:23.120855 | controller | ok: All items complete
2025-10-02 15:29:23.120887 | 
2025-10-02 15:29:23.383634 | controller | ok: "artifacts"
2025-10-02 15:29:23.645762 | controller | ok: "docs"
2025-10-02 15:29:23.657233 | 
2025-10-02 15:29:23.657373 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-02 15:29:23.993603 | controller | changed: "logs"
2025-10-02 15:29:24.266895 | controller | changed: "artifacts"
2025-10-02 15:29:24.523925 | controller | changed: "docs"
2025-10-02 15:29:24.581509 | 
2025-10-02 15:29:24.581594 | PLAY RECAP
2025-10-02 15:29:24.581640 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-02 15:29:24.581669 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-02 15:29:24.581690 | 
2025-10-02 15:29:24.721633 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-02 15:29:24.722316 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-02 15:29:26.629854 | 
2025-10-02 15:29:26.629994 | PLAY [all]
2025-10-02 15:29:26.675606 | 
2025-10-02 15:29:26.675718 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-02 15:29:26.779940 | controller | ok
2025-10-02 15:29:26.784977 | 
2025-10-02 15:29:26.785056 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-02 15:29:27.286955 | controller | changed
2025-10-02 15:29:27.296042 | 
2025-10-02 15:29:27.296128 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-02 15:29:29.210557 | controller | changed
2025-10-02 15:29:29.230033 | 
2025-10-02 15:29:29.230128 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-02 15:29:29.747031 | controller | changed:
2025-10-02 15:29:29.747201 | controller | {
2025-10-02 15:29:29.747234 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-02 15:29:29.747267 | controller | }
2025-10-02 15:29:30.039656 | controller | changed:
2025-10-02 15:29:30.039855 | controller | {
2025-10-02 15:29:30.039894 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-02 15:29:30.039918 | controller | }
2025-10-02 15:29:30.319957 | controller | changed:
2025-10-02 15:29:30.320044 | controller | {
2025-10-02 15:29:30.320071 | 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:29:30.320748 | controller | }
2025-10-02 15:29:30.609692 | controller | changed:
2025-10-02 15:29:30.609793 | controller | {
2025-10-02 15:29:30.609823 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-02 15:29:30.609845 | controller | }
2025-10-02 15:29:30.934203 | controller | changed:
2025-10-02 15:29:30.934288 | controller | {
2025-10-02 15:29:30.934315 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-02 15:29:30.934336 | controller | }
2025-10-02 15:29:31.234547 | controller | changed:
2025-10-02 15:29:31.234631 | controller | {
2025-10-02 15:29:31.234660 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-02 15:29:31.234683 | controller | }
2025-10-02 15:29:31.530317 | controller | changed:
2025-10-02 15:29:31.530558 | controller | {
2025-10-02 15:29:31.530594 | 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:29:31.530619 | controller | }
2025-10-02 15:29:31.835135 | controller | changed:
2025-10-02 15:29:31.835297 | controller | {
2025-10-02 15:29:31.835335 | 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:29:31.835370 | controller | }
2025-10-02 15:29:32.118814 | controller | changed:
2025-10-02 15:29:32.118912 | controller | {
2025-10-02 15:29:32.118940 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-02 15:29:32.118963 | controller | }
2025-10-02 15:29:32.417174 | controller | changed:
2025-10-02 15:29:32.417258 | controller | {
2025-10-02 15:29:32.417286 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-02 15:29:32.417308 | controller | }
2025-10-02 15:29:32.723659 | controller | changed:
2025-10-02 15:29:32.723759 | controller | {
2025-10-02 15:29:32.723799 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-02 15:29:32.723822 | controller | }
2025-10-02 15:29:33.019426 | controller | changed:
2025-10-02 15:29:33.019500 | controller | {
2025-10-02 15:29:33.019528 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-02 15:29:33.019550 | controller | }
2025-10-02 15:29:33.319398 | controller | changed:
2025-10-02 15:29:33.319489 | controller | {
2025-10-02 15:29:33.319518 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-02 15:29:33.319540 | controller | }
2025-10-02 15:29:33.636695 | controller | changed:
2025-10-02 15:29:33.636783 | controller | {
2025-10-02 15:29:33.636814 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-02 15:29:33.636838 | controller | }
2025-10-02 15:29:33.933338 | controller | changed:
2025-10-02 15:29:33.933455 | controller | {
2025-10-02 15:29:33.933486 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-02 15:29:33.933509 | controller | }
2025-10-02 15:29:34.258665 | controller | changed:
2025-10-02 15:29:34.258748 | controller | {
2025-10-02 15:29:34.258786 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-02 15:29:34.258809 | controller | }
2025-10-02 15:29:34.565167 | controller | changed:
2025-10-02 15:29:34.565242 | controller | {
2025-10-02 15:29:34.565270 | 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:29:34.565292 | controller | }
2025-10-02 15:29:34.872231 | controller | changed:
2025-10-02 15:29:34.872405 | controller | {
2025-10-02 15:29:34.872443 | 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:29:34.872475 | controller | }
2025-10-02 15:29:35.195530 | controller | changed:
2025-10-02 15:29:35.195625 | controller | {
2025-10-02 15:29:35.195652 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-02 15:29:35.195674 | controller | }
2025-10-02 15:29:35.487673 | controller | changed:
2025-10-02 15:29:35.487765 | controller | {
2025-10-02 15:29:35.487806 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-02 15:29:35.487830 | controller | }
2025-10-02 15:29:35.800481 | controller | changed:
2025-10-02 15:29:35.800563 | controller | {
2025-10-02 15:29:35.800832 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-02 15:29:35.800859 | controller | }
2025-10-02 15:29:36.095481 | controller | changed:
2025-10-02 15:29:36.095555 | controller | {
2025-10-02 15:29:36.095583 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-02 15:29:36.095604 | controller | }
2025-10-02 15:29:36.399079 | controller | changed:
2025-10-02 15:29:36.399166 | controller | {
2025-10-02 15:29:36.399193 | 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:29:36.399214 | controller | }
2025-10-02 15:29:36.686008 | controller | changed:
2025-10-02 15:29:36.686086 | controller | {
2025-10-02 15:29:36.686114 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-02 15:29:36.686135 | controller | }
2025-10-02 15:29:36.966436 | controller | changed:
2025-10-02 15:29:36.966714 | controller | {
2025-10-02 15:29:36.966871 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-02 15:29:36.966903 | controller | }
2025-10-02 15:29:37.246003 | controller | changed:
2025-10-02 15:29:37.247474 | controller | {
2025-10-02 15:29:37.247524 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-02 15:29:37.247552 | controller | }
2025-10-02 15:29:37.272076 | 
2025-10-02 15:29:37.272175 | TASK [Set timezone to UTC]
2025-10-02 15:29:37.768057 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-02 15:29:37.773940 | 
2025-10-02 15:29:37.774030 | TASK [Create nodepool directory]
2025-10-02 15:29:38.057234 | controller | changed
2025-10-02 15:29:38.064145 | 
2025-10-02 15:29:38.064246 | TASK [Create nodepool sub_nodes file]
2025-10-02 15:29:39.058713 | controller | changed
2025-10-02 15:29:39.064486 | 
2025-10-02 15:29:39.064555 | TASK [Create nodepool sub_nodes_private file]
2025-10-02 15:29:40.040243 | controller | changed
2025-10-02 15:29:40.045503 | 
2025-10-02 15:29:40.045565 | LOOP [Populate nodepool sub_nodes file]
2025-10-02 15:29:40.098526 | 
2025-10-02 15:29:40.098673 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-02 15:29:40.127754 | 
2025-10-02 15:29:40.127908 | TASK [Create nodepool primary file]
2025-10-02 15:29:40.151362 | controller | skipping: Conditional result was False
2025-10-02 15:29:40.157856 | 
2025-10-02 15:29:40.157932 | TASK [Create nodepool node_private for this node]
2025-10-02 15:29:41.147856 | controller | changed
2025-10-02 15:29:41.154854 | 
2025-10-02 15:29:41.154922 | LOOP [Copy ssh keys to nodepool directory]
2025-10-02 15:29:41.563188 | controller | ok: Item: id_rsa Runtime: 0:00:00.004331
2025-10-02 15:29:41.563365 | 
2025-10-02 15:29:41.786765 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004300
2025-10-02 15:29:41.796749 | 
2025-10-02 15:29:41.796885 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-02 15:29:42.725565 | controller | changed
2025-10-02 15:29:42.732022 | 
2025-10-02 15:29:42.732126 | TASK [Validate sudoers config after edits]
2025-10-02 15:29:43.006858 | controller | /etc/sudoers: parsed OK
2025-10-02 15:29:43.006971 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-02 15:29:43.006986 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-02 15:29:43.006993 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-02 15:29:43.260753 | controller | ok: Runtime: 0:00:00.005094
2025-10-02 15:29:43.266246 | 
2025-10-02 15:29:43.266312 | TASK [Show the environment passed in to job shell scripts]
2025-10-02 15:29:43.528270 | controller | SHELL=/bin/bash
2025-10-02 15:29:43.528327 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-02 15:29:43.528338 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-02 15:29:43.528389 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/62/3362/41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 15:29:43.528398 | controller | PWD=/home/zuul
2025-10-02 15:29:43.528404 | controller | ZUUL_PIPELINE=github-check
2025-10-02 15:29:43.528410 | controller | LOGNAME=zuul
2025-10-02 15:29:43.528416 | controller | XDG_SESSION_TYPE=tty
2025-10-02 15:29:43.528421 | controller | _=/usr/bin/env
2025-10-02 15:29:43.528427 | controller | MOTD_SHOWN=pam
2025-10-02 15:29:43.528432 | controller | HOME=/home/zuul
2025-10-02 15:29:43.528437 | controller | LANG=en_US.UTF-8
2025-10-02 15:29:43.528443 | controller | SSH_CONNECTION=192.168.25.12 47774 192.168.25.161 22
2025-10-02 15:29:43.528449 | 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:29:43.528457 | controller | ZUUL_CHANGE_IDS=3362,41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 15:29:43.528463 | controller | WORKSPACE=/home/zuul/workspace
2025-10-02 15:29:43.528469 | controller | XDG_SESSION_CLASS=user
2025-10-02 15:29:43.528474 | controller | SELINUX_ROLE_REQUESTED=
2025-10-02 15:29:43.528480 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-02 15:29:43.528485 | controller | USER=zuul
2025-10-02 15:29:43.528491 | controller | ZUUL_VOTING=True
2025-10-02 15:29:43.528496 | controller | BUILD_TIMEOUT=1800000
2025-10-02 15:29:43.528502 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-02 15:29:43.528507 | controller | SHLVL=1
2025-10-02 15:29:43.528513 | controller | ZUUL_PATCHSET=41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 15:29:43.528518 | controller | XDG_SESSION_ID=1
2025-10-02 15:29:43.528524 | controller | ZUUL_BRANCH=main
2025-10-02 15:29:43.528529 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-02 15:29:43.528535 | controller | SSH_CLIENT=192.168.25.12 47774 22
2025-10-02 15:29:43.528540 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-02 15:29:43.528546 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-02 15:29:43.528551 | controller | which_declare=declare -f
2025-10-02 15:29:43.528557 | 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:29:43.528563 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-02 15:29:43.528568 | controller | ZUUL_CHANGE=3362
2025-10-02 15:29:43.528574 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-02 15:29:43.528579 | controller | ZUUL_UUID=2044b1b706ee4a9bb7d0b759f8326766
2025-10-02 15:29:43.528585 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-02 15:29:43.528590 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-02 15:29:43.528596 | controller | }
2025-10-02 15:29:43.792811 | controller | ok: Runtime: 0:00:00.005784
2025-10-02 15:29:43.798009 | 
2025-10-02 15:29:43.798073 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-02 15:29:43.811338 | controller | skipping: Conditional result was False
2025-10-02 15:29:43.816594 | 
2025-10-02 15:29:43.816684 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-02 15:29:44.369895 | controller | skipping: Conditional result was False
2025-10-02 15:29:44.375366 | 
2025-10-02 15:29:44.375449 | TASK [Ensure legacy workspace directory]
2025-10-02 15:29:44.616224 | controller | changed
2025-10-02 15:29:44.634959 | 
2025-10-02 15:29:44.635031 | PLAY RECAP
2025-10-02 15:29:44.635077 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-02 15:29:44.635101 | 
2025-10-02 15:29:44.728764 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-02 15:29:44.729544 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-02 15:29:45.297436 | 
2025-10-02 15:29:45.297541 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-02 15:29:45.330515 | 
2025-10-02 15:29:45.330615 | TASK [Create zuul-output directory]
2025-10-02 15:29:45.725961 | controller | changed
2025-10-02 15:29:45.730926 | 
2025-10-02 15:29:45.731001 | TASK [Slurp Zuul inventory test]
2025-10-02 15:29:46.081050 | controller -> localhost | ok
2025-10-02 15:29:46.089023 | 
2025-10-02 15:29:46.089095 | TASK [Save zuul inventory]
2025-10-02 15:29:47.266049 | controller | changed
2025-10-02 15:29:47.271471 | 
2025-10-02 15:29:47.271542 | TASK [Save zuul vars without the change_message]
2025-10-02 15:29:48.241273 | controller | changed
2025-10-02 15:29:48.260112 | 
2025-10-02 15:29:48.260236 | PLAY RECAP
2025-10-02 15:29:48.260283 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-02 15:29:48.260306 | 
2025-10-02 15:29:48.336269 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-02 15:29:48.337053 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-02 15:29:48.905575 | 
2025-10-02 15:29:48.905684 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-02 15:29:48.927713 | 
2025-10-02 15:29:48.927809 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-02 15:29:48.956357 | controller | ok
2025-10-02 15:29:48.974049 | 
2025-10-02 15:29:48.974147 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-02 15:29:48.997559 | controller | skipping: Conditional result was False
2025-10-02 15:29:49.003529 | 
2025-10-02 15:29:49.003603 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-02 15:29:49.376722 | controller | ok
2025-10-02 15:29:49.387958 | 
2025-10-02 15:29:49.388054 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-02 15:29:50.288617 | controller | ok
2025-10-02 15:29:50.298454 | 
2025-10-02 15:29:50.298531 | TASK [Prepare workspace]
2025-10-02 15:29:50.316447 | controller | ok
2025-10-02 15:29:50.333682 | 
2025-10-02 15:29:50.333756 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-02 15:29:50.691317 | controller | ok
2025-10-02 15:29:50.698163 | 
2025-10-02 15:29:50.698230 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-02 15:29:52.699933 | controller | Output suppressed because no_log was given
2025-10-02 15:29:52.709164 | 
2025-10-02 15:29:52.709241 | LOOP [Create zuul-output directory]
2025-10-02 15:29:52.978345 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-02 15:29:53.213148 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-02 15:29:53.228190 | 
2025-10-02 15:29:53.228298 | TASK [Install required packages]
2025-10-02 15:30:10.000097 | controller | ok: Nothing to do
2025-10-02 15:30:10.005175 | 
2025-10-02 15:30:10.005309 | TASK [Install venv]
2025-10-02 15:31:10.568923 | controller | changed
2025-10-02 15:31:10.595195 | 
2025-10-02 15:31:10.595279 | PLAY RECAP
2025-10-02 15:31:10.595323 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-02 15:31:10.595346 | 
2025-10-02 15:31:10.688827 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-02 15:31:10.689811 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-02 15:31:11.246780 | 
2025-10-02 15:31:11.246887 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-02 15:31:11.267824 | 
2025-10-02 15:31:11.267908 | TASK [Gather required facts]
2025-10-02 15:31:11.857055 | controller | ok
2025-10-02 15:31:11.862965 | 
2025-10-02 15:31:11.863063 | TASK [Load environment var if instructed to]
2025-10-02 15:31:11.887634 | controller | skipping: Conditional result was False
2025-10-02 15:31:11.893701 | 
2025-10-02 15:31:11.893784 | TASK [Run molecule]
2025-10-02 15:31:12.886260 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-02 15:31:12.956525 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-02 15:31:31.179256 | 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:31:31.179955 | 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:31:31.180508 | 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:31:31.181020 | 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:31:31.181538 | 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:31:31.182024 | 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:31:31.182499 | 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:31:31.182955 | 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:31:31.183468 | 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:31:31.183929 | 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:31:31.184400 | 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:31:31.184836 | 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:31:31.185306 | 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:31:31.185734 | 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:31:31.186219 | 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:31:31.196739 | controller | INFO     Running default > prepare
2025-10-02 15:31:31.917438 | controller |
2025-10-02 15:31:31.917496 | controller | PLAY [Prepare] *****************************************************************
2025-10-02 15:31:31.917589 | controller |
2025-10-02 15:31:31.917723 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 15:31:31.917805 | controller | Thursday 02 October 2025  15:31:31 +0000 (0:00:00.028)       0:00:00.028 ******
2025-10-02 15:31:32.781983 | controller | ok: [instance]
2025-10-02 15:31:32.782073 | controller |
2025-10-02 15:31:32.782206 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-02 15:31:32.782326 | controller | Thursday 02 October 2025  15:31:32 +0000 (0:00:00.865)       0:00:00.894 ******
2025-10-02 15:31:32.798565 | controller | skipping: [instance]
2025-10-02 15:31:32.798669 | controller |
2025-10-02 15:31:32.798796 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-02 15:31:32.798948 | controller | Thursday 02 October 2025  15:31:32 +0000 (0:00:00.016)       0:00:00.910 ******
2025-10-02 15:31:32.836979 | 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:31:32.837098 | controller |
2025-10-02 15:31:32.837227 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-02 15:31:32.837334 | controller | Thursday 02 October 2025  15:31:32 +0000 (0:00:00.038)       0:00:00.949 ******
2025-10-02 15:31:33.112016 | controller | ok: [instance]
2025-10-02 15:31:33.112096 | controller |
2025-10-02 15:31:33.112240 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-02 15:31:33.112350 | controller | Thursday 02 October 2025  15:31:33 +0000 (0:00:00.274)       0:00:01.224 ******
2025-10-02 15:31:33.419160 | controller | ok: [instance]
2025-10-02 15:31:33.419248 | controller |
2025-10-02 15:31:33.419356 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-02 15:31:33.419460 | controller | Thursday 02 October 2025  15:31:33 +0000 (0:00:00.307)       0:00:01.531 ******
2025-10-02 15:31:34.099782 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-02 15:31:34.099864 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-02 15:31:34.099980 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-02 15:31:34.100084 | controller |
2025-10-02 15:31:34.100196 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-02 15:31:34.100291 | controller | Thursday 02 October 2025  15:31:34 +0000 (0:00:00.680)       0:00:02.212 ******
2025-10-02 15:31:34.160073 | controller |
2025-10-02 15:31:34.160244 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-02 15:31:34.160345 | controller | Thursday 02 October 2025  15:31:34 +0000 (0:00:00.060)       0:00:02.272 ******
2025-10-02 15:31:34.699330 | controller | changed: [instance] => (item=tmp)
2025-10-02 15:31:34.699417 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-02 15:31:34.699527 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-02 15:31:34.699623 | controller |
2025-10-02 15:31:34.699727 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-02 15:31:34.699831 | controller | Thursday 02 October 2025  15:31:34 +0000 (0:00:00.539)       0:00:02.811 ******
2025-10-02 15:31:35.519006 | controller | ok: [instance]
2025-10-02 15:31:35.519139 | controller |
2025-10-02 15:31:35.519334 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-02 15:31:36.711963 | controller | Thursday 02 October 2025  15:31:35 +0000 (0:00:00.819)       0:00:03.631 ******
2025-10-02 15:31:36.712003 | controller | changed: [instance]
2025-10-02 15:31:44.620063 | controller |
2025-10-02 15:31:44.620109 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-02 15:31:44.620132 | controller | Thursday 02 October 2025  15:31:36 +0000 (0:00:01.192)       0:00:04.823 ******
2025-10-02 15:31:44.620142 | controller | changed: [instance]
2025-10-02 15:31:44.620165 | controller |
2025-10-02 15:31:44.620180 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-02 15:31:44.620201 | controller | Thursday 02 October 2025  15:31:44 +0000 (0:00:07.908)       0:00:12.732 ******
2025-10-02 15:31:45.332405 | controller | changed: [instance]
2025-10-02 15:31:45.353582 | controller |
2025-10-02 15:31:45.353610 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-02 15:31:45.353619 | controller | Thursday 02 October 2025  15:31:45 +0000 (0:00:00.712)       0:00:13.444 ******
2025-10-02 15:31:45.353631 | controller | skipping: [instance]
2025-10-02 15:31:46.171701 | controller |
2025-10-02 15:31:46.171731 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-02 15:31:46.171739 | controller | Thursday 02 October 2025  15:31:45 +0000 (0:00:00.021)       0:00:13.465 ******
2025-10-02 15:31:46.171749 | controller | changed: [instance]
2025-10-02 15:31:46.224136 | controller |
2025-10-02 15:31:46.224185 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-02 15:31:46.224196 | controller | Thursday 02 October 2025  15:31:46 +0000 (0:00:00.817)       0:00:14.283 ******
2025-10-02 15:31:46.224208 | controller | skipping: [instance]
2025-10-02 15:31:46.261904 | controller |
2025-10-02 15:31:46.261962 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-02 15:31:46.261974 | controller | Thursday 02 October 2025  15:31:46 +0000 (0:00:00.052)       0:00:14.336 ******
2025-10-02 15:31:46.261984 | controller | skipping: [instance]
2025-10-02 15:31:46.297912 | controller |
2025-10-02 15:31:46.297961 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-02 15:31:46.297974 | controller | Thursday 02 October 2025  15:31:46 +0000 (0:00:00.037)       0:00:14.373 ******
2025-10-02 15:31:46.297986 | controller | skipping: [instance]
2025-10-02 15:31:46.846384 | controller |
2025-10-02 15:31:46.846417 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-02 15:31:46.846426 | controller | Thursday 02 October 2025  15:31:46 +0000 (0:00:00.036)       0:00:14.410 ******
2025-10-02 15:31:46.846436 | controller | changed: [instance]
2025-10-02 15:31:47.285002 | controller |
2025-10-02 15:31:47.285031 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-02 15:31:47.285039 | controller | Thursday 02 October 2025  15:31:46 +0000 (0:00:00.548)       0:00:14.958 ******
2025-10-02 15:31:47.285049 | controller | changed: [instance]
2025-10-02 15:31:47.313615 | controller |
2025-10-02 15:31:47.313647 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-02 15:31:47.313655 | controller | Thursday 02 October 2025  15:31:47 +0000 (0:00:00.438)       0:00:15.397 ******
2025-10-02 15:31:47.313664 | controller | skipping: [instance]
2025-10-02 15:31:47.343061 | controller |
2025-10-02 15:31:47.343095 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-02 15:31:47.343105 | controller | Thursday 02 October 2025  15:31:47 +0000 (0:00:00.028)       0:00:15.425 ******
2025-10-02 15:31:47.343135 | controller | skipping: [instance]
2025-10-02 15:31:47.371056 | controller |
2025-10-02 15:31:47.371092 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-02 15:31:47.371102 | controller | Thursday 02 October 2025  15:31:47 +0000 (0:00:00.029)       0:00:15.455 ******
2025-10-02 15:31:47.371135 | controller | skipping: [instance]
2025-10-02 15:31:47.371166 | controller |
2025-10-02 15:31:47.371177 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-02 15:31:47.371294 | controller | Thursday 02 October 2025  15:31:47 +0000 (0:00:00.028)       0:00:15.483 ******
2025-10-02 15:31:47.408609 | controller | ok: [instance]
2025-10-02 15:31:47.435032 | controller |
2025-10-02 15:31:47.435061 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-02 15:31:47.435072 | controller | Thursday 02 October 2025  15:31:47 +0000 (0:00:00.037)       0:00:15.520 ******
2025-10-02 15:31:47.435084 | controller | skipping: [instance]
2025-10-02 15:31:47.435136 | controller |
2025-10-02 15:31:47.435145 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-02 15:31:47.435155 | controller | Thursday 02 October 2025  15:31:47 +0000 (0:00:00.026)       0:00:15.547 ******
2025-10-02 15:31:47.461508 | controller | skipping: [instance]
2025-10-02 15:31:47.487037 | controller |
2025-10-02 15:31:47.487061 | controller | TASK [Download the RPM] ********************************************************
2025-10-02 15:31:47.487070 | controller | Thursday 02 October 2025  15:31:47 +0000 (0:00:00.026)       0:00:15.573 ******
2025-10-02 15:31:47.487081 | controller | skipping: [instance]
2025-10-02 15:31:47.487274 | controller |
2025-10-02 15:31:47.487449 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-02 15:31:47.487619 | controller | Thursday 02 October 2025  15:31:47 +0000 (0:00:00.025)       0:00:15.599 ******
2025-10-02 15:31:47.516393 | controller | skipping: [instance]
2025-10-02 15:31:47.516579 | controller |
2025-10-02 15:31:47.516745 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-02 15:31:47.516883 | controller | Thursday 02 October 2025  15:31:47 +0000 (0:00:00.029)       0:00:15.629 ******
2025-10-02 15:31:47.543051 | controller | skipping: [instance]
2025-10-02 15:31:47.543262 | controller |
2025-10-02 15:31:47.543439 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-02 15:31:47.543607 | controller | Thursday 02 October 2025  15:31:47 +0000 (0:00:00.026)       0:00:15.655 ******
2025-10-02 15:31:47.571353 | controller | skipping: [instance]
2025-10-02 15:31:47.571509 | controller |
2025-10-02 15:31:47.571660 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-02 15:31:47.571799 | controller | Thursday 02 October 2025  15:31:47 +0000 (0:00:00.028)       0:00:15.684 ******
2025-10-02 15:31:47.596467 | controller | skipping: [instance]
2025-10-02 15:31:47.596619 | controller |
2025-10-02 15:31:47.596762 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-02 15:31:47.596902 | controller | Thursday 02 October 2025  15:31:47 +0000 (0:00:00.025)       0:00:15.709 ******
2025-10-02 15:31:47.779073 | controller | ok: [instance]
2025-10-02 15:31:47.779216 | controller |
2025-10-02 15:31:47.779232 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-02 15:31:47.779242 | controller | Thursday 02 October 2025  15:31:47 +0000 (0:00:00.181)       0:00:15.891 ******
2025-10-02 15:31:47.989016 | controller | changed: [instance]
2025-10-02 15:31:47.989276 | controller |
2025-10-02 15:31:47.989309 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-02 15:31:48.212718 | controller | Thursday 02 October 2025  15:31:47 +0000 (0:00:00.210)       0:00:16.101 ******
2025-10-02 15:31:48.212755 | controller | changed: [instance]
2025-10-02 15:31:48.232404 | controller |
2025-10-02 15:31:48.232426 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-02 15:31:48.232434 | controller | Thursday 02 October 2025  15:31:48 +0000 (0:00:00.223)       0:00:16.324 ******
2025-10-02 15:31:48.232444 | controller | skipping: [instance]
2025-10-02 15:31:48.254610 | controller |
2025-10-02 15:31:48.254638 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-02 15:31:48.254648 | controller | Thursday 02 October 2025  15:31:48 +0000 (0:00:00.020)       0:00:16.344 ******
2025-10-02 15:31:48.254659 | controller | skipping: [instance]
2025-10-02 15:31:48.277608 | controller |
2025-10-02 15:31:48.277640 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-02 15:31:48.277649 | controller | Thursday 02 October 2025  15:31:48 +0000 (0:00:00.022)       0:00:16.366 ******
2025-10-02 15:31:48.277658 | controller | skipping: [instance]
2025-10-02 15:31:48.300612 | controller |
2025-10-02 15:31:48.300638 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-02 15:31:48.300647 | controller | Thursday 02 October 2025  15:31:48 +0000 (0:00:00.022)       0:00:16.389 ******
2025-10-02 15:31:48.300668 | controller | skipping: [instance]
2025-10-02 15:31:48.323557 | controller |
2025-10-02 15:31:48.323584 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-02 15:31:48.323592 | controller | Thursday 02 October 2025  15:31:48 +0000 (0:00:00.023)       0:00:16.412 ******
2025-10-02 15:31:48.323601 | controller | skipping: [instance]
2025-10-02 15:31:48.346605 | controller |
2025-10-02 15:31:48.346638 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-02 15:31:48.346653 | controller | Thursday 02 October 2025  15:31:48 +0000 (0:00:00.022)       0:00:16.435 ******
2025-10-02 15:31:48.346664 | controller | skipping: [instance]
2025-10-02 15:31:48.346812 | controller |
2025-10-02 15:31:48.346984 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-02 15:31:48.347148 | controller | Thursday 02 October 2025  15:31:48 +0000 (0:00:00.023)       0:00:16.458 ******
2025-10-02 15:31:48.365076 | controller | skipping: [instance]
2025-10-02 15:31:48.365254 | controller |
2025-10-02 15:31:48.365401 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-02 15:31:48.365541 | controller | Thursday 02 October 2025  15:31:48 +0000 (0:00:00.018)       0:00:16.477 ******
2025-10-02 15:31:48.397311 | controller | skipping: [instance]
2025-10-02 15:31:48.397506 | controller |
2025-10-02 15:31:48.397653 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-02 15:31:48.397826 | controller | Thursday 02 October 2025  15:31:48 +0000 (0:00:00.031)       0:00:16.509 ******
2025-10-02 15:31:48.420272 | controller | skipping: [instance]
2025-10-02 15:31:48.420438 | controller |
2025-10-02 15:31:48.420586 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-02 15:31:48.420736 | controller | Thursday 02 October 2025  15:31:48 +0000 (0:00:00.023)       0:00:16.532 ******
2025-10-02 15:31:48.442334 | controller | skipping: [instance]
2025-10-02 15:31:48.442489 | controller |
2025-10-02 15:31:48.442634 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-02 15:31:48.442789 | controller | Thursday 02 October 2025  15:31:48 +0000 (0:00:00.022)       0:00:16.555 ******
2025-10-02 15:31:48.471921 | controller | skipping: [instance]
2025-10-02 15:31:48.472150 | controller |
2025-10-02 15:31:48.472330 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-02 15:31:48.472500 | controller | Thursday 02 October 2025  15:31:48 +0000 (0:00:00.029)       0:00:16.584 ******
2025-10-02 15:31:48.501542 | controller | skipping: [instance]
2025-10-02 15:31:48.501691 | controller |
2025-10-02 15:31:48.501837 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-02 15:31:48.502006 | controller | Thursday 02 October 2025  15:31:48 +0000 (0:00:00.029)       0:00:16.614 ******
2025-10-02 15:31:48.530035 | controller | skipping: [instance]
2025-10-02 15:31:48.530237 | controller |
2025-10-02 15:31:48.530410 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-02 15:31:48.530576 | controller | Thursday 02 October 2025  15:31:48 +0000 (0:00:00.028)       0:00:16.642 ******
2025-10-02 15:31:48.559716 | controller | skipping: [instance]
2025-10-02 15:31:48.559862 | controller |
2025-10-02 15:31:48.560030 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-02 15:31:48.560195 | controller | Thursday 02 October 2025  15:31:48 +0000 (0:00:00.029)       0:00:16.672 ******
2025-10-02 15:32:43.849814 | controller | ok: [instance]
2025-10-02 15:32:45.032011 | controller |
2025-10-02 15:32:45.032051 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-02 15:32:45.032060 | controller | Thursday 02 October 2025  15:32:43 +0000 (0:00:55.289)       0:01:11.961 ******
2025-10-02 15:32:45.032072 | controller | ok: [instance]
2025-10-02 15:32:45.062871 | controller |
2025-10-02 15:32:45.062901 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-02 15:32:45.062910 | controller | Thursday 02 October 2025  15:32:45 +0000 (0:00:01.182)       0:01:13.144 ******
2025-10-02 15:32:45.062920 | controller | skipping: [instance]
2025-10-02 15:32:45.102999 | controller |
2025-10-02 15:32:45.103028 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-10-02 15:32:45.103037 | controller | Thursday 02 October 2025  15:32:45 +0000 (0:00:00.031)       0:01:13.175 ******
2025-10-02 15:32:45.103049 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-10-02 15:32:45.120094 | controller |
2025-10-02 15:32:45.120135 | controller | TASK [ci_setup : List packages to install] *************************************
2025-10-02 15:32:45.120144 | controller | Thursday 02 October 2025  15:32:45 +0000 (0:00:00.039)       0:01:13.215 ******
2025-10-02 15:32:45.120166 | controller | ok: [instance] =>
2025-10-02 15:32:45.120197 | controller |   cifmw_ci_setup_packages:
2025-10-02 15:32:45.120205 | controller |   - bash-completion
2025-10-02 15:32:45.120214 | controller |   - ca-certificates
2025-10-02 15:32:45.121039 | controller |   - git-core
2025-10-02 15:32:50.525497 | controller |   - make
2025-10-02 15:32:50.525528 | controller |   - tar
2025-10-02 15:32:50.525536 | controller |   - tmux
2025-10-02 15:32:50.525541 | controller |   - python3-pip
2025-10-02 15:32:50.525547 | controller |
2025-10-02 15:32:50.525553 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-10-02 15:32:50.525559 | controller | Thursday 02 October 2025  15:32:45 +0000 (0:00:00.017)       0:01:13.232 ******
2025-10-02 15:32:50.525574 | controller | changed: [instance]
2025-10-02 15:32:51.101362 | controller |
2025-10-02 15:32:51.101392 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-10-02 15:32:51.101400 | controller | Thursday 02 October 2025  15:32:50 +0000 (0:00:05.405)       0:01:18.637 ******
2025-10-02 15:32:51.101410 | controller | ok: [instance]
2025-10-02 15:32:51.101433 | controller |
2025-10-02 15:32:51.101636 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-10-02 15:32:51.296144 | controller | Thursday 02 October 2025  15:32:51 +0000 (0:00:00.576)       0:01:19.213 ******
2025-10-02 15:32:51.296181 | controller | changed: [instance]
2025-10-02 15:32:51.296329 | controller |
2025-10-02 15:32:51.296367 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-10-02 15:32:51.296444 | controller | Thursday 02 October 2025  15:32:51 +0000 (0:00:00.194)       0:01:19.408 ******
2025-10-02 15:32:57.094656 | controller | changed: [instance]
2025-10-02 15:32:57.130037 | controller |
2025-10-02 15:32:57.130066 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-10-02 15:32:57.130076 | controller | Thursday 02 October 2025  15:32:57 +0000 (0:00:05.798)       0:01:25.206 ******
2025-10-02 15:32:57.130088 | controller | skipping: [instance]
2025-10-02 15:32:57.384779 | controller |
2025-10-02 15:32:57.384805 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-10-02 15:32:57.384813 | controller | Thursday 02 October 2025  15:32:57 +0000 (0:00:00.035)       0:01:25.242 ******
2025-10-02 15:32:57.384822 | controller | changed: [instance]
2025-10-02 15:32:57.653052 | controller |
2025-10-02 15:32:57.653087 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-10-02 15:32:57.653110 | controller | Thursday 02 October 2025  15:32:57 +0000 (0:00:00.254)       0:01:25.496 ******
2025-10-02 15:32:57.653121 | controller | changed: [instance]
2025-10-02 15:32:57.653177 | controller |
2025-10-02 15:32:57.653194 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-10-02 15:32:57.653205 | controller | Thursday 02 October 2025  15:32:57 +0000 (0:00:00.268)       0:01:25.765 ******
2025-10-02 15:32:57.669649 | controller | skipping: [instance]
2025-10-02 15:32:57.685642 | controller |
2025-10-02 15:32:57.685662 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-10-02 15:32:57.685671 | controller | Thursday 02 October 2025  15:32:57 +0000 (0:00:00.016)       0:01:25.781 ******
2025-10-02 15:32:57.685682 | controller | skipping: [instance]
2025-10-02 15:32:57.702047 | controller |
2025-10-02 15:32:57.702066 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-10-02 15:32:57.702074 | controller | Thursday 02 October 2025  15:32:57 +0000 (0:00:00.015)       0:01:25.797 ******
2025-10-02 15:32:57.702083 | controller | skipping: [instance]
2025-10-02 15:32:57.702268 | controller |
2025-10-02 15:32:57.702285 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-10-02 15:32:57.718983 | controller | Thursday 02 October 2025  15:32:57 +0000 (0:00:00.016)       0:01:25.814 ******
2025-10-02 15:32:57.719006 | controller | skipping: [instance]
2025-10-02 15:32:57.735452 | controller |
2025-10-02 15:32:57.735468 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-10-02 15:32:57.735475 | controller | Thursday 02 October 2025  15:32:57 +0000 (0:00:00.016)       0:01:25.831 ******
2025-10-02 15:32:57.735483 | controller | skipping: [instance]
2025-10-02 15:32:57.755894 | controller |
2025-10-02 15:32:57.755918 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-10-02 15:32:57.755952 | controller | Thursday 02 October 2025  15:32:57 +0000 (0:00:00.016)       0:01:25.847 ******
2025-10-02 15:32:57.755968 | controller | skipping: [instance]
2025-10-02 15:32:58.751267 | controller |
2025-10-02 15:32:58.751360 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-10-02 15:32:58.751372 | controller | Thursday 02 October 2025  15:32:57 +0000 (0:00:00.020)       0:01:25.868 ******
2025-10-02 15:32:58.751392 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-10-02 15:32:58.953045 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-10-02 15:32:58.953082 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-10-02 15:32:58.953090 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-10-02 15:32:58.953115 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-10-02 15:32:58.953121 | controller |
2025-10-02 15:32:58.953127 | controller | TASK [libvirt_manager : Ensure output directory exists] ************************
2025-10-02 15:32:58.953132 | controller | Thursday 02 October 2025  15:32:58 +0000 (0:00:00.994)       0:01:26.862 ******
2025-10-02 15:32:58.953143 | controller | ok: [instance]
2025-10-02 15:32:59.139878 | controller |
2025-10-02 15:32:59.139917 | controller | TASK [libvirt_manager : Get vmx/svm extensions from /proc/cpuinfo] *************
2025-10-02 15:32:59.139927 | controller | Thursday 02 October 2025  15:32:58 +0000 (0:00:00.202)       0:01:27.065 ******
2025-10-02 15:32:59.139976 | controller | changed: [instance]
2025-10-02 15:32:59.321450 | controller |
2025-10-02 15:32:59.321481 | controller | TASK [libvirt_manager : Check if KVM is enabled] *******************************
2025-10-02 15:32:59.321490 | controller | Thursday 02 October 2025  15:32:59 +0000 (0:00:00.186)       0:01:27.251 ******
2025-10-02 15:32:59.321500 | controller | ok: [instance]
2025-10-02 15:32:59.348051 | controller |
2025-10-02 15:32:59.348127 | controller | TASK [libvirt_manager : Fail if KVM not enabled] *******************************
2025-10-02 15:32:59.348139 | controller | Thursday 02 October 2025  15:32:59 +0000 (0:00:00.181)       0:01:27.433 ******
2025-10-02 15:32:59.348156 | controller | skipping: [instance]
2025-10-02 15:32:59.348205 | controller |
2025-10-02 15:32:59.348216 | controller | TASK [libvirt_manager : Check if VMX virtualization] ***************************
2025-10-02 15:32:59.348247 | controller | Thursday 02 October 2025  15:32:59 +0000 (0:00:00.026)       0:01:27.460 ******
2025-10-02 15:32:59.363060 | controller | skipping: [instance]
2025-10-02 15:32:59.363123 | controller |
2025-10-02 15:32:59.363135 | controller | TASK [libvirt_manager : Check if SVM virtualization] ***************************
2025-10-02 15:32:59.363260 | controller | Thursday 02 October 2025  15:32:59 +0000 (0:00:00.014)       0:01:27.475 ******
2025-10-02 15:32:59.382562 | controller | ok: [instance]
2025-10-02 15:32:59.407804 | controller |
2025-10-02 15:32:59.407832 | controller | TASK [libvirt_manager : Enable VMX virtualization] *****************************
2025-10-02 15:32:59.407840 | controller | Thursday 02 October 2025  15:32:59 +0000 (0:00:00.019)       0:01:27.494 ******
2025-10-02 15:32:59.407850 | controller | skipping: [instance]
2025-10-02 15:33:10.607337 | controller |
2025-10-02 15:33:10.607369 | controller | TASK [libvirt_manager : Install packages required for using KVM] ***************
2025-10-02 15:33:10.607378 | controller | Thursday 02 October 2025  15:32:59 +0000 (0:00:00.025)       0:01:27.519 ******
2025-10-02 15:33:10.607388 | controller | changed: [instance]
2025-10-02 15:33:10.607418 | controller |
2025-10-02 15:33:10.607929 | controller | TASK [libvirt_manager : Create rules.d polkit folder] **************************
2025-10-02 15:33:10.828206 | controller | Thursday 02 October 2025  15:33:10 +0000 (0:00:11.199)       0:01:38.719 ******
2025-10-02 15:33:10.828246 | controller | changed: [instance]
2025-10-02 15:33:10.828278 | controller |
2025-10-02 15:33:10.828602 | controller | TASK [libvirt_manager : Enable UNIX socket PolicyKit auth] *********************
2025-10-02 15:33:11.235011 | controller | Thursday 02 October 2025  15:33:10 +0000 (0:00:00.220)       0:01:38.939 ******
2025-10-02 15:33:11.235047 | controller | changed: [instance]
2025-10-02 15:33:11.829770 | controller |
2025-10-02 15:33:11.829800 | controller | TASK [libvirt_manager : Restart service polkit service] ************************
2025-10-02 15:33:11.829808 | controller | Thursday 02 October 2025  15:33:11 +0000 (0:00:00.406)       0:01:39.346 ******
2025-10-02 15:33:11.829826 | controller | changed: [instance]
2025-10-02 15:33:12.027840 | controller |
2025-10-02 15:33:12.027873 | controller | TASK [libvirt_manager : Remove directory for service override] *****************
2025-10-02 15:33:12.027881 | controller | Thursday 02 October 2025  15:33:11 +0000 (0:00:00.594)       0:01:39.941 ******
2025-10-02 15:33:12.027891 | controller | ok: [instance]
2025-10-02 15:33:12.691608 | controller |
2025-10-02 15:33:12.691639 | controller | TASK [libvirt_manager : Reload systemctl and start/enable virtproxyd.service] ***
2025-10-02 15:33:12.691647 | controller | Thursday 02 October 2025  15:33:12 +0000 (0:00:00.198)       0:01:40.139 ******
2025-10-02 15:33:12.691657 | controller | ok: [instance]
2025-10-02 15:33:13.602221 | controller |
2025-10-02 15:33:13.602256 | controller | TASK [libvirt_manager : Ensure firewalld service is up] ************************
2025-10-02 15:33:13.602264 | controller | Thursday 02 October 2025  15:33:12 +0000 (0:00:00.663)       0:01:40.803 ******
2025-10-02 15:33:13.602274 | controller | changed: [instance]
2025-10-02 15:33:13.679136 | controller |
2025-10-02 15:33:13.679175 | controller | TASK [libvirt_manager : Ensure libvirt modular driver daemons are started.] ****
2025-10-02 15:33:13.679185 | controller | Thursday 02 October 2025  15:33:13 +0000 (0:00:00.910)       0:01:41.714 ******
2025-10-02 15:33:13.679200 | 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:33:13.679233 | 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:33:13.679241 | 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:33:13.679247 | 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:33:13.679254 | 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:33:13.679260 | 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:33:13.679267 | 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:33:13.679275 | 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:33:13.679471 | controller |
2025-10-02 15:33:13.679507 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for qemu.] *********
2025-10-02 15:33:14.002020 | controller | Thursday 02 October 2025  15:33:13 +0000 (0:00:00.076)       0:01:41.790 ******
2025-10-02 15:33:14.002060 | controller | ok: [instance]
2025-10-02 15:33:14.002132 | controller |
2025-10-02 15:33:14.002162 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for qemu.] **********
2025-10-02 15:33:14.002175 | controller | Thursday 02 October 2025  15:33:13 +0000 (0:00:00.323)       0:01:42.114 ******
2025-10-02 15:33:15.324456 | controller | ok: [instance]
2025-10-02 15:33:16.643018 | controller |
2025-10-02 15:33:16.643047 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for qemu.] **************
2025-10-02 15:33:16.643061 | controller | Thursday 02 October 2025  15:33:15 +0000 (0:00:01.322)       0:01:43.436 ******
2025-10-02 15:33:16.643072 | controller | ok: [instance]
2025-10-02 15:33:16.643114 | controller |
2025-10-02 15:33:16.643363 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for interface.] ****
2025-10-02 15:33:16.960446 | controller | Thursday 02 October 2025  15:33:16 +0000 (0:00:01.318)       0:01:44.755 ******
2025-10-02 15:33:16.960478 | controller | ok: [instance]
2025-10-02 15:33:17.277490 | controller |
2025-10-02 15:33:17.277519 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for interface.] *****
2025-10-02 15:33:17.277527 | controller | Thursday 02 October 2025  15:33:16 +0000 (0:00:00.317)       0:01:45.072 ******
2025-10-02 15:33:17.277537 | controller | ok: [instance]
2025-10-02 15:33:17.614060 | controller |
2025-10-02 15:33:17.614111 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for interface.] *********
2025-10-02 15:33:17.614120 | controller | Thursday 02 October 2025  15:33:17 +0000 (0:00:00.316)       0:01:45.389 ******
2025-10-02 15:33:17.614131 | controller | ok: [instance]
2025-10-02 15:33:18.933691 | controller |
2025-10-02 15:33:18.933722 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for network.] ******
2025-10-02 15:33:18.933730 | controller | Thursday 02 October 2025  15:33:17 +0000 (0:00:00.336)       0:01:45.726 ******
2025-10-02 15:33:18.933740 | controller | ok: [instance]
2025-10-02 15:33:19.249081 | controller |
2025-10-02 15:33:19.249129 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for network.] *******
2025-10-02 15:33:19.249139 | controller | Thursday 02 October 2025  15:33:18 +0000 (0:00:01.319)       0:01:47.045 ******
2025-10-02 15:33:19.249151 | controller | ok: [instance]
2025-10-02 15:33:19.249313 | controller |
2025-10-02 15:33:19.249333 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for network.] ***********
2025-10-02 15:33:19.573406 | controller | Thursday 02 October 2025  15:33:19 +0000 (0:00:00.315)       0:01:47.361 ******
2025-10-02 15:33:19.573439 | controller | ok: [instance]
2025-10-02 15:33:19.890742 | controller |
2025-10-02 15:33:19.890772 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nodedev.] ******
2025-10-02 15:33:19.890780 | controller | Thursday 02 October 2025  15:33:19 +0000 (0:00:00.324)       0:01:47.685 ******
2025-10-02 15:33:19.890790 | controller | ok: [instance]
2025-10-02 15:33:20.214043 | controller |
2025-10-02 15:33:20.214077 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nodedev.] *******
2025-10-02 15:33:20.214085 | controller | Thursday 02 October 2025  15:33:19 +0000 (0:00:00.317)       0:01:48.002 ******
2025-10-02 15:33:20.214110 | controller | ok: [instance]
2025-10-02 15:33:20.214136 | controller |
2025-10-02 15:33:20.214341 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nodedev.] ***********
2025-10-02 15:33:20.538812 | controller | Thursday 02 October 2025  15:33:20 +0000 (0:00:00.323)       0:01:48.326 ******
2025-10-02 15:33:20.538844 | controller | ok: [instance]
2025-10-02 15:33:20.856823 | controller |
2025-10-02 15:33:20.856853 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nwfilter.] *****
2025-10-02 15:33:20.856861 | controller | Thursday 02 October 2025  15:33:20 +0000 (0:00:00.324)       0:01:48.650 ******
2025-10-02 15:33:20.856871 | controller | ok: [instance]
2025-10-02 15:33:21.177041 | controller |
2025-10-02 15:33:21.177074 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nwfilter.] ******
2025-10-02 15:33:21.177084 | controller | Thursday 02 October 2025  15:33:20 +0000 (0:00:00.318)       0:01:48.968 ******
2025-10-02 15:33:21.177112 | controller | ok: [instance]
2025-10-02 15:33:21.177120 | controller |
2025-10-02 15:33:21.177127 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nwfilter.] **********
2025-10-02 15:33:21.177137 | controller | Thursday 02 October 2025  15:33:21 +0000 (0:00:00.320)       0:01:49.289 ******
2025-10-02 15:33:21.499075 | controller | ok: [instance]
2025-10-02 15:33:21.499146 | controller |
2025-10-02 15:33:21.499159 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for secret.] *******
2025-10-02 15:33:21.499292 | controller | Thursday 02 October 2025  15:33:21 +0000 (0:00:00.322)       0:01:49.611 ******
2025-10-02 15:33:21.821061 | controller | ok: [instance]
2025-10-02 15:33:21.821132 | controller |
2025-10-02 15:33:21.821145 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for secret.] ********
2025-10-02 15:33:21.821215 | controller | Thursday 02 October 2025  15:33:21 +0000 (0:00:00.321)       0:01:49.933 ******
2025-10-02 15:33:22.148795 | controller | ok: [instance]
2025-10-02 15:33:22.468436 | controller |
2025-10-02 15:33:22.468465 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for secret.] ************
2025-10-02 15:33:22.468473 | controller | Thursday 02 October 2025  15:33:22 +0000 (0:00:00.327)       0:01:50.260 ******
2025-10-02 15:33:22.468489 | controller | ok: [instance]
2025-10-02 15:33:22.784791 | controller |
2025-10-02 15:33:22.784822 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for storage.] ******
2025-10-02 15:33:22.784830 | controller | Thursday 02 October 2025  15:33:22 +0000 (0:00:00.319)       0:01:50.580 ******
2025-10-02 15:33:22.784851 | controller | ok: [instance]
2025-10-02 15:33:23.096444 | controller |
2025-10-02 15:33:23.096472 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for storage.] *******
2025-10-02 15:33:23.096480 | controller | Thursday 02 October 2025  15:33:22 +0000 (0:00:00.316)       0:01:50.896 ******
2025-10-02 15:33:23.096490 | controller | ok: [instance]
2025-10-02 15:33:23.415517 | controller |
2025-10-02 15:33:23.415545 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for storage.] ***********
2025-10-02 15:33:23.415554 | controller | Thursday 02 October 2025  15:33:23 +0000 (0:00:00.311)       0:01:51.208 ******
2025-10-02 15:33:23.415564 | controller | ok: [instance]
2025-10-02 15:33:23.730900 | controller |
2025-10-02 15:33:23.730929 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for proxy.] ********
2025-10-02 15:33:23.730954 | controller | Thursday 02 October 2025  15:33:23 +0000 (0:00:00.319)       0:01:51.527 ******
2025-10-02 15:33:23.730967 | controller | ok: [instance]
2025-10-02 15:33:24.044801 | controller |
2025-10-02 15:33:24.044830 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for proxy.] *********
2025-10-02 15:33:24.044839 | controller | Thursday 02 October 2025  15:33:23 +0000 (0:00:00.315)       0:01:51.843 ******
2025-10-02 15:33:24.044849 | controller | ok: [instance]
2025-10-02 15:33:24.349900 | controller |
2025-10-02 15:33:24.349928 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for proxy.] *************
2025-10-02 15:33:24.349953 | controller | Thursday 02 October 2025  15:33:24 +0000 (0:00:00.313)       0:01:52.156 ******
2025-10-02 15:33:24.349966 | controller | ok: [instance]
2025-10-02 15:33:24.367104 | controller |
2025-10-02 15:33:24.367149 | controller | TASK [libvirt_manager : Manage service for older releases] *********************
2025-10-02 15:33:24.367160 | controller | Thursday 02 October 2025  15:33:24 +0000 (0:00:00.305)       0:01:52.462 ******
2025-10-02 15:33:24.367173 | controller | skipping: [instance]
2025-10-02 15:33:24.624022 | controller |
2025-10-02 15:33:24.624073 | controller | TASK [libvirt_manager : Get libvirt group users] *******************************
2025-10-02 15:33:24.624084 | controller | Thursday 02 October 2025  15:33:24 +0000 (0:00:00.017)       0:01:52.479 ******
2025-10-02 15:33:24.624111 | controller | ok: [instance]
2025-10-02 15:33:24.624154 | controller |
2025-10-02 15:33:24.624164 | controller | TASK [libvirt_manager : Add user if not exists in libvirt group] ***************
2025-10-02 15:33:24.624310 | controller | Thursday 02 October 2025  15:33:24 +0000 (0:00:00.256)       0:01:52.736 ******
2025-10-02 15:33:24.988370 | controller | ok: [instance]
2025-10-02 15:33:25.287014 | controller |
2025-10-02 15:33:25.287044 | controller | TASK [libvirt_manager : Allow QEMU on home directory for the storage access] ***
2025-10-02 15:33:25.287052 | controller | Thursday 02 October 2025  15:33:24 +0000 (0:00:00.364)       0:01:53.100 ******
2025-10-02 15:33:25.287062 | controller | changed: [instance]
2025-10-02 15:33:25.468247 | controller |
2025-10-02 15:33:25.468276 | controller | TASK [libvirt_manager : Inject system connection parameters in bashrc] *********
2025-10-02 15:33:25.468284 | controller | Thursday 02 October 2025  15:33:25 +0000 (0:00:00.298)       0:01:53.399 ******
2025-10-02 15:33:25.468294 | controller | [WARNING]: Reset is not implemented for this connection
2025-10-02 15:33:25.489109 | controller | changed: [instance]
2025-10-02 15:33:25.489168 | controller |
2025-10-02 15:33:25.489180 | controller | TASK [libvirt_manager : Ensure we reload user env] *****************************
2025-10-02 15:33:25.489203 | controller | Thursday 02 October 2025  15:33:25 +0000 (0:00:00.176)       0:01:53.575 ******
2025-10-02 15:33:25.490040 | controller |
2025-10-02 15:33:25.490105 | controller | PLAY RECAP *********************************************************************
2025-10-02 15:33:25.490118 | controller | instance                   : ok=75   changed=23   unreachable=0    failed=0    skipped=41   rescued=0    ignored=0
2025-10-02 15:33:25.490126 | controller |
2025-10-02 15:33:25.490133 | controller | Thursday 02 October 2025  15:33:25 +0000 (0:00:00.025)       0:01:53.601 ******
2025-10-02 15:33:25.490140 | controller | ===============================================================================
2025-10-02 15:33:25.490147 | controller | test_deps : Install selinux python libs -------------------------------- 55.29s
2025-10-02 15:33:25.490153 | controller | libvirt_manager : Install packages required for using KVM -------------- 11.20s
2025-10-02 15:33:25.490162 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.91s
2025-10-02 15:33:25.490196 | controller | ci_setup : Install openshift client ------------------------------------- 5.80s
2025-10-02 15:33:25.491643 | controller | ci_setup : Install needed packages -------------------------------------- 5.41s
2025-10-02 15:33:25.545129 | controller | libvirt_manager : Ensure read-only socket is enabled for qemu. ---------- 1.32s
2025-10-02 15:33:25.545161 | controller | libvirt_manager : Ensure read-write socket is enabled for network. ------ 1.32s
2025-10-02 15:33:25.545169 | controller | libvirt_manager : Ensure admin socket is enabled for qemu. -------------- 1.32s
2025-10-02 15:33:25.545174 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.19s
2025-10-02 15:33:25.545180 | controller | test_deps : Install python yaml libs ------------------------------------ 1.18s
2025-10-02 15:33:25.545185 | controller | ci_setup : Manage directories ------------------------------------------- 0.99s
2025-10-02 15:33:25.545190 | controller | libvirt_manager : Ensure firewalld service is up ------------------------ 0.91s
2025-10-02 15:33:25.545195 | controller | Gathering Facts --------------------------------------------------------- 0.87s
2025-10-02 15:33:25.545200 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.82s
2025-10-02 15:33:25.545205 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.82s
2025-10-02 15:33:25.545210 | controller | repo_setup : Install repo-setup package --------------------------------- 0.71s
2025-10-02 15:33:25.545216 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.68s
2025-10-02 15:33:25.545221 | controller | libvirt_manager : Reload systemctl and start/enable virtproxyd.service --- 0.66s
2025-10-02 15:33:25.545226 | controller | libvirt_manager : Restart service polkit service ------------------------ 0.59s
2025-10-02 15:33:25.545231 | controller | ci_setup : Gather version of openshift client --------------------------- 0.58s
2025-10-02 15:33:25.545241 | controller | INFO     Running default > converge
2025-10-02 15:33:25.950864 | controller |
2025-10-02 15:33:26.745054 | controller | PLAY [Converge] ****************************************************************
2025-10-02 15:33:26.745100 | controller |
2025-10-02 15:33:26.745110 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 15:33:26.745118 | controller | Thursday 02 October 2025  15:33:25 +0000 (0:00:00.012)       0:00:00.012 ******
2025-10-02 15:33:26.745129 | controller | ok: [instance]
2025-10-02 15:33:26.745207 | controller |
2025-10-02 15:33:26.745232 | controller | TASK [Including the openshift provisioner node role.] **************************
2025-10-02 15:33:26.745243 | controller | Thursday 02 October 2025  15:33:26 +0000 (0:00:00.794)       0:00:00.807 ******
2025-10-02 15:33:26.784652 | controller |
2025-10-02 15:33:27.078037 | controller | TASK [openshift_provisioner_node : Create the artifacts directory] *************
2025-10-02 15:33:27.078073 | controller | Thursday 02 October 2025  15:33:26 +0000 (0:00:00.039)       0:00:00.847 ******
2025-10-02 15:33:27.078086 | controller | ok: [instance -> localhost]
2025-10-02 15:33:27.078315 | controller |
2025-10-02 15:33:27.471065 | controller | TASK [openshift_provisioner_node : Adding user kni] ****************************
2025-10-02 15:33:27.471111 | controller | Thursday 02 October 2025  15:33:27 +0000 (0:00:00.292)       0:00:01.139 ******
2025-10-02 15:33:27.471124 | controller | changed: [instance]
2025-10-02 15:33:27.471154 | controller |
2025-10-02 15:33:27.471369 | controller | TASK [openshift_provisioner_node : Generate SSH keys] **************************
2025-10-02 15:33:27.837714 | controller | Thursday 02 October 2025  15:33:27 +0000 (0:00:00.393)       0:00:01.533 ******
2025-10-02 15:33:27.837747 | controller | changed: [instance -> localhost]
2025-10-02 15:33:28.208099 | controller |
2025-10-02 15:33:28.208134 | controller | TASK [openshift_provisioner_node : Enable passwordless access] *****************
2025-10-02 15:33:28.208143 | controller | Thursday 02 October 2025  15:33:27 +0000 (0:00:00.366)       0:00:01.899 ******
2025-10-02 15:33:28.208153 | controller | changed: [instance]
2025-10-02 15:33:28.208180 | controller |
2025-10-02 15:33:28.208392 | controller | TASK [openshift_provisioner_node : Install required packages need to execute openshift installer] ***
2025-10-02 15:34:09.311075 | controller | Thursday 02 October 2025  15:33:28 +0000 (0:00:00.370)       0:00:02.270 ******
2025-10-02 15:34:09.311153 | controller | changed: [instance]
2025-10-02 15:34:09.716809 | controller |
2025-10-02 15:34:09.716838 | controller | TASK [openshift_provisioner_node : Create the external network connection] *****
2025-10-02 15:34:09.716846 | controller | Thursday 02 October 2025  15:34:09 +0000 (0:00:41.102)       0:00:43.373 ******
2025-10-02 15:34:09.716856 | controller | changed: [instance]
2025-10-02 15:34:09.749024 | controller |
2025-10-02 15:34:09.749056 | controller | TASK [openshift_provisioner_node : Verify external network interface is defined] ***
2025-10-02 15:34:09.749064 | controller | Thursday 02 October 2025  15:34:09 +0000 (0:00:00.405)       0:00:43.779 ******
2025-10-02 15:34:09.749075 | controller | skipping: [instance]
2025-10-02 15:34:09.749209 | controller |
2025-10-02 15:34:09.749328 | controller | TASK [openshift_provisioner_node : Add iface to the external network connection] ***
2025-10-02 15:34:09.749346 | controller | Thursday 02 October 2025  15:34:09 +0000 (0:00:00.032)       0:00:43.811 ******
2025-10-02 15:34:09.775117 | controller | skipping: [instance]
2025-10-02 15:34:09.775149 | controller |
2025-10-02 15:34:09.775157 | controller | TASK [openshift_provisioner_node : Create the provisioning network connection] ***
2025-10-02 15:34:09.775166 | controller | Thursday 02 October 2025  15:34:09 +0000 (0:00:00.026)       0:00:43.837 ******
2025-10-02 15:34:09.805860 | controller | skipping: [instance]
2025-10-02 15:34:09.805918 | controller |
2025-10-02 15:34:09.806227 | controller | TASK [openshift_provisioner_node : Add iface to the provisioning network connection] ***
2025-10-02 15:34:09.838156 | controller | Thursday 02 October 2025  15:34:09 +0000 (0:00:00.030)       0:00:43.868 ******
2025-10-02 15:34:09.838186 | controller | skipping: [instance]
2025-10-02 15:34:09.838350 | controller |
2025-10-02 15:34:09.838376 | controller | TASK [openshift_provisioner_node : Check required virtualization networks] *****
2025-10-02 15:34:09.875836 | controller | Thursday 02 October 2025  15:34:09 +0000 (0:00:00.032)       0:00:43.900 ******
2025-10-02 15:34:09.875867 | 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:34:10.734744 | 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:34:10.734773 | controller |
2025-10-02 15:34:10.734782 | controller | TASK [openshift_provisioner_node : Define virtual network baremetal] ***********
2025-10-02 15:34:10.734788 | controller | Thursday 02 October 2025  15:34:09 +0000 (0:00:00.037)       0:00:43.938 ******
2025-10-02 15:34:10.734804 | controller | changed: [instance]
2025-10-02 15:34:10.734844 | controller |
2025-10-02 15:34:10.734854 | controller | TASK [openshift_provisioner_node : Start the network - baremetal] **************
2025-10-02 15:34:10.734928 | controller | Thursday 02 October 2025  15:34:10 +0000 (0:00:00.859)       0:00:44.797 ******
2025-10-02 15:34:10.965861 | controller | changed: [instance]
2025-10-02 15:34:11.258793 | controller |
2025-10-02 15:34:11.258822 | controller | TASK [openshift_provisioner_node : Enable network autostart for baremetal] *****
2025-10-02 15:34:11.258830 | controller | Thursday 02 October 2025  15:34:10 +0000 (0:00:00.230)       0:00:45.028 ******
2025-10-02 15:34:11.258840 | controller | changed: [instance]
2025-10-02 15:34:11.490823 | controller |
2025-10-02 15:34:11.490852 | controller | TASK [openshift_provisioner_node : Define virtual network provisioning] ********
2025-10-02 15:34:11.490861 | controller | Thursday 02 October 2025  15:34:11 +0000 (0:00:00.291)       0:00:45.319 ******
2025-10-02 15:34:11.490871 | controller | changed: [instance]
2025-10-02 15:34:11.712964 | controller |
2025-10-02 15:34:11.712999 | controller | TASK [openshift_provisioner_node : Start the network - provisioning] ***********
2025-10-02 15:34:11.713009 | controller | Thursday 02 October 2025  15:34:11 +0000 (0:00:00.233)       0:00:45.553 ******
2025-10-02 15:34:11.713022 | controller | changed: [instance]
2025-10-02 15:34:11.933712 | controller |
2025-10-02 15:34:11.933742 | controller | TASK [openshift_provisioner_node : Enable network autostart for provisioning] ***
2025-10-02 15:34:11.933750 | controller | Thursday 02 October 2025  15:34:11 +0000 (0:00:00.221)       0:00:45.775 ******
2025-10-02 15:34:11.933761 | controller | changed: [instance]
2025-10-02 15:34:11.980803 | controller |
2025-10-02 15:34:11.980833 | controller | TASK [openshift_provisioner_node : Cache the MAC addresses] ********************
2025-10-02 15:34:11.980842 | controller | Thursday 02 October 2025  15:34:11 +0000 (0:00:00.220)       0:00:45.995 ******
2025-10-02 15:34:11.980852 | controller | ok: [instance]
2025-10-02 15:34:12.250830 | controller |
2025-10-02 15:34:12.250858 | controller | TASK [openshift_provisioner_node : Read host SSH fingerprint] ******************
2025-10-02 15:34:12.250866 | controller | Thursday 02 October 2025  15:34:11 +0000 (0:00:00.047)       0:00:46.043 ******
2025-10-02 15:34:12.250876 | controller | ok: [instance]
2025-10-02 15:34:12.281597 | controller |
2025-10-02 15:34:12.281619 | controller | TASK [openshift_provisioner_node : Set the role output parameters] *************
2025-10-02 15:34:12.281626 | controller | Thursday 02 October 2025  15:34:12 +0000 (0:00:00.270)       0:00:46.313 ******
2025-10-02 15:34:12.281636 | controller | ok: [instance]
2025-10-02 15:34:12.316394 | controller |
2025-10-02 15:34:12.316416 | controller | TASK [openshift_provisioner_node : Cache provisioning bridge name] *************
2025-10-02 15:34:12.316432 | controller | Thursday 02 October 2025  15:34:12 +0000 (0:00:00.030)       0:00:46.344 ******
2025-10-02 15:34:12.316441 | controller | skipping: [instance]
2025-10-02 15:34:12.591094 | controller |
2025-10-02 15:34:12.591204 | controller | TASK [Verify user is created] **************************************************
2025-10-02 15:34:12.591214 | controller | Thursday 02 October 2025  15:34:12 +0000 (0:00:00.034)       0:00:46.378 ******
2025-10-02 15:34:12.591229 | controller | ok: [instance]
2025-10-02 15:34:12.714963 | controller |
2025-10-02 15:34:12.715021 | controller | TASK [Verify external network bridge exists] ***********************************
2025-10-02 15:34:12.715030 | controller | Thursday 02 October 2025  15:34:12 +0000 (0:00:00.273)       0:00:46.652 ******
2025-10-02 15:34:12.715043 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_000_verify_external_network.log
2025-10-02 15:34:12.756141 | controller | changed: [instance]
2025-10-02 15:34:12.756199 | controller |
2025-10-02 15:34:12.756211 | controller | TASK [Perform cleanup] *********************************************************
2025-10-02 15:34:12.756221 | controller | Thursday 02 October 2025  15:34:12 +0000 (0:00:00.123)       0:00:46.776 ******
2025-10-02 15:34:12.756238 | controller |
2025-10-02 15:34:12.788749 | controller | TASK [openshift_provisioner_node : Cleaning up the virtual networks] ***********
2025-10-02 15:34:12.788795 | controller | Thursday 02 October 2025  15:34:12 +0000 (0:00:00.041)       0:00:46.818 ******
2025-10-02 15:34:12.788812 | 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:34:13.051894 | 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:34:13.051972 | controller |
2025-10-02 15:34:13.051983 | controller | TASK [openshift_provisioner_node : Stopping virtual network baremetal] *********
2025-10-02 15:34:13.051991 | controller | Thursday 02 October 2025  15:34:12 +0000 (0:00:00.032)       0:00:46.850 ******
2025-10-02 15:34:13.052005 | controller | ok: [instance]
2025-10-02 15:34:13.298101 | controller |
2025-10-02 15:34:13.298161 | controller | TASK [openshift_provisioner_node : Remove virtual network baremetal] ***********
2025-10-02 15:34:13.298172 | controller | Thursday 02 October 2025  15:34:13 +0000 (0:00:00.262)       0:00:47.113 ******
2025-10-02 15:34:13.298187 | controller | ok: [instance]
2025-10-02 15:34:13.298193 | controller |
2025-10-02 15:34:13.298201 | controller | TASK [openshift_provisioner_node : Stopping virtual network provisioning] ******
2025-10-02 15:34:13.526669 | controller | Thursday 02 October 2025  15:34:13 +0000 (0:00:00.246)       0:00:47.359 ******
2025-10-02 15:34:13.526704 | controller | ok: [instance]
2025-10-02 15:34:13.758185 | controller |
2025-10-02 15:34:13.758288 | controller | TASK [openshift_provisioner_node : Remove virtual network provisioning] ********
2025-10-02 15:34:13.758299 | controller | Thursday 02 October 2025  15:34:13 +0000 (0:00:00.228)       0:00:47.588 ******
2025-10-02 15:34:13.758321 | controller | ok: [instance]
2025-10-02 15:34:13.987574 | controller |
2025-10-02 15:34:13.987607 | controller | TASK [openshift_provisioner_node : Remove provisioning network] ****************
2025-10-02 15:34:13.987626 | controller | Thursday 02 October 2025  15:34:13 +0000 (0:00:00.231)       0:00:47.820 ******
2025-10-02 15:34:13.987637 | controller | ok: [instance]
2025-10-02 15:34:14.337069 | controller |
2025-10-02 15:34:14.337113 | controller | TASK [openshift_provisioner_node : Remove the user] ****************************
2025-10-02 15:34:14.337122 | controller | Thursday 02 October 2025  15:34:13 +0000 (0:00:00.229)       0:00:48.049 ******
2025-10-02 15:34:14.337133 | controller | changed: [instance]
2025-10-02 15:34:14.337159 | controller |
2025-10-02 15:34:14.337166 | controller | PLAY RECAP *********************************************************************
2025-10-02 15:34:14.337173 | controller | instance                   : ok=28   changed=13   unreachable=0    failed=0    skipped=5    rescued=0    ignored=0
2025-10-02 15:34:14.337771 | controller |
2025-10-02 15:34:14.337812 | controller | Thursday 02 October 2025  15:34:14 +0000 (0:00:00.349)       0:00:48.399 ******
2025-10-02 15:34:14.337823 | controller | ===============================================================================
2025-10-02 15:34:14.337831 | controller | openshift_provisioner_node : Install required packages need to execute openshift installer -- 41.10s
2025-10-02 15:34:14.337838 | controller | openshift_provisioner_node : Define virtual network baremetal ----------- 0.86s
2025-10-02 15:34:14.337845 | controller | Gathering Facts --------------------------------------------------------- 0.79s
2025-10-02 15:34:14.337854 | controller | openshift_provisioner_node : Create the external network connection ----- 0.41s
2025-10-02 15:34:14.337882 | controller | openshift_provisioner_node : Adding user kni ---------------------------- 0.39s
2025-10-02 15:34:14.339298 | controller | openshift_provisioner_node : Enable passwordless access ----------------- 0.37s
2025-10-02 15:34:14.386111 | controller | openshift_provisioner_node : Generate SSH keys -------------------------- 0.37s
2025-10-02 15:34:14.386145 | controller | openshift_provisioner_node : Remove the user ---------------------------- 0.35s
2025-10-02 15:34:14.386158 | controller | openshift_provisioner_node : Create the artifacts directory ------------- 0.29s
2025-10-02 15:34:14.386164 | controller | openshift_provisioner_node : Enable network autostart for baremetal ----- 0.29s
2025-10-02 15:34:14.386170 | controller | Verify user is created -------------------------------------------------- 0.27s
2025-10-02 15:34:14.386175 | controller | openshift_provisioner_node : Read host SSH fingerprint ------------------ 0.27s
2025-10-02 15:34:14.386180 | controller | openshift_provisioner_node : Stopping virtual network baremetal --------- 0.26s
2025-10-02 15:34:14.386186 | controller | openshift_provisioner_node : Remove virtual network baremetal ----------- 0.25s
2025-10-02 15:34:14.386191 | controller | openshift_provisioner_node : Define virtual network provisioning -------- 0.23s
2025-10-02 15:34:14.386196 | controller | openshift_provisioner_node : Remove virtual network provisioning -------- 0.23s
2025-10-02 15:34:14.386201 | controller | openshift_provisioner_node : Start the network - baremetal -------------- 0.23s
2025-10-02 15:34:14.386206 | controller | openshift_provisioner_node : Remove provisioning network ---------------- 0.23s
2025-10-02 15:34:14.386212 | controller | openshift_provisioner_node : Stopping virtual network provisioning ------ 0.23s
2025-10-02 15:34:14.386218 | controller | openshift_provisioner_node : Start the network - provisioning ----------- 0.22s
2025-10-02 15:34:14.386228 | controller | INFO     Running default > cleanup
2025-10-02 15:34:14.386668 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-02 15:34:14.387236 | controller | INFO     Writing /tmp/report.html report.
2025-10-02 15:34:14.641449 | [controller] Waiting on logger
2025-10-02 15:34:19.808885 | [controller] Waiting on logger
2025-10-02 15:34:30.240855 | [controller] Waiting on logger
2025-10-02 15:34:40.672953 | [controller] Waiting on logger
2025-10-02 15:34:44.456038 | [Zuul] Log Stream did not terminate
2025-10-02 15:34:44.456228 | controller | changed
2025-10-02 15:34:44.477516 | 
2025-10-02 15:34:44.477576 | PLAY RECAP
2025-10-02 15:34:44.477616 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-02 15:34:44.477639 | 
2025-10-02 15:34:44.545980 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-02 15:34:44.546783 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-02 15:34:45.115396 | 
2025-10-02 15:34:45.115502 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-02 15:34:45.136253 | 
2025-10-02 15:34:45.136330 | TASK [Filter out host if needed]
2025-10-02 15:34:45.145301 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-02 15:34:45.150499 | 
2025-10-02 15:34:45.150568 | TASK [Ensure file is present]
2025-10-02 15:34:45.554053 | controller | ok
2025-10-02 15:34:45.560808 | 
2025-10-02 15:34:45.560880 | TASK [Manage molecule report file]
2025-10-02 15:34:45.582527 | [controller] Waiting on logger
2025-10-02 15:34:54.945155 | [controller] Waiting on logger
2025-10-02 15:35:05.376951 | [controller] Waiting on logger
2025-10-02 15:35:14.757655 | [controller] Waiting on logger
2025-10-02 15:35:15.964814 | [Zuul] Log Stream did not terminate
2025-10-02 15:35:15.964981 | controller | changed
2025-10-02 15:35:15.970304 | 
2025-10-02 15:35:15.970370 | TASK [Check if we get ci-framework-data basedir]
2025-10-02 15:35:16.293532 | controller | ok
2025-10-02 15:35:16.298826 | 
2025-10-02 15:35:16.298891 | TASK [Create ci-framework-data log directory for zuul]
2025-10-02 15:35:16.722345 | controller | changed
2025-10-02 15:35:16.728457 | 
2025-10-02 15:35:16.728536 | TASK [Copy ci-framework interesting files]
2025-10-02 15:35:16.749296 | [controller] Waiting on logger
2025-10-02 15:35:27.136948 | [controller] Waiting on logger
2025-10-02 15:35:37.568910 | [controller] Waiting on logger
2025-10-02 15:35:46.912862 | [controller] Waiting on logger
2025-10-02 15:35:47.051554 | [Zuul] Log Stream did not terminate
2025-10-02 15:35:47.051719 | controller | changed
2025-10-02 15:35:47.057316 | 
2025-10-02 15:35:47.057398 | TASK [Get SELinux listing]
2025-10-02 15:35:50.176898 | [controller] Waiting on logger
2025-10-02 15:35:56.434205 | controller | changed
2025-10-02 15:35:56.440092 | 
2025-10-02 15:35:56.440161 | TASK [Generate log index]
2025-10-02 15:35:57.589956 | controller | changed
2025-10-02 15:35:57.595507 | 
2025-10-02 15:35:57.595582 | TASK [Get some env related data]
2025-10-02 15:35:59.392958 | [controller] Waiting on logger
2025-10-02 15:36:02.621610 | controller | changed
2025-10-02 15:36:02.627025 | 
2025-10-02 15:36:02.627100 | TASK [Generate list of logs to collect in home directory]
2025-10-02 15:36:03.009434 | controller | ok: All paths examined
2025-10-02 15:36:03.014824 | 
2025-10-02 15:36:03.014898 | LOOP [Copy logs from home directory]
2025-10-02 15:36:03.605069 | controller | changed:
2025-10-02 15:36:03.605366 | controller | {
2025-10-02 15:36:03.605437 | controller |   "atime": 1743544925.4788878,
2025-10-02 15:36:03.605461 | controller |   "ctime": 1743545329.1409318,
2025-10-02 15:36:03.605479 | controller |   "dev": 64513,
2025-10-02 15:36:03.605496 | controller |   "gid": 1000,
2025-10-02 15:36:03.605511 | controller |   "gr_name": "zuul",
2025-10-02 15:36:03.605525 | controller |   "inode": 4518807,
2025-10-02 15:36:03.605540 | controller |   "isblk": false,
2025-10-02 15:36:03.605555 | controller |   "ischr": false,
2025-10-02 15:36:03.605570 | controller |   "isdir": false,
2025-10-02 15:36:03.605585 | controller |   "isfifo": false,
2025-10-02 15:36:03.605600 | controller |   "isgid": false,
2025-10-02 15:36:03.605616 | controller |   "islnk": false,
2025-10-02 15:36:03.605630 | controller |   "isreg": true,
2025-10-02 15:36:03.605645 | controller |   "issock": false,
2025-10-02 15:36:03.605659 | controller |   "isuid": false,
2025-10-02 15:36:03.605674 | controller |   "mode": "0644",
2025-10-02 15:36:03.605688 | controller |   "mtime": 1743545329.1409318,
2025-10-02 15:36:03.605705 | controller |   "nlink": 1,
2025-10-02 15:36:03.605722 | controller |   "path": "/home/zuul/crc-setup.log",
2025-10-02 15:36:03.605739 | controller |   "pw_name": "zuul",
2025-10-02 15:36:03.605755 | controller |   "rgrp": true,
2025-10-02 15:36:03.605785 | controller |   "roth": true,
2025-10-02 15:36:03.605891 | controller |   "rusr": true,
2025-10-02 15:36:03.605912 | controller |   "size": 4108,
2025-10-02 15:36:03.606008 | controller |   "uid": 1000,
2025-10-02 15:36:03.606036 | controller |   "wgrp": false,
2025-10-02 15:36:03.606056 | controller |   "woth": false,
2025-10-02 15:36:03.606073 | controller |   "wusr": true,
2025-10-02 15:36:03.606088 | controller |   "xgrp": false,
2025-10-02 15:36:03.606103 | controller |   "xoth": false,
2025-10-02 15:36:03.606118 | controller |   "xusr": false
2025-10-02 15:36:03.606132 | controller | }
2025-10-02 15:36:04.180109 | controller | changed:
2025-10-02 15:36:04.180313 | controller | {
2025-10-02 15:36:04.180373 | controller |   "atime": 1743545331.0429637,
2025-10-02 15:36:04.180419 | controller |   "ctime": 1743545842.0111232,
2025-10-02 15:36:04.180440 | controller |   "dev": 64513,
2025-10-02 15:36:04.180457 | controller |   "gid": 1000,
2025-10-02 15:36:04.180474 | controller |   "gr_name": "zuul",
2025-10-02 15:36:04.180490 | controller |   "inode": 4194437,
2025-10-02 15:36:04.180505 | controller |   "isblk": false,
2025-10-02 15:36:04.180519 | controller |   "ischr": false,
2025-10-02 15:36:04.180533 | controller |   "isdir": false,
2025-10-02 15:36:04.180547 | controller |   "isfifo": false,
2025-10-02 15:36:04.180562 | controller |   "isgid": false,
2025-10-02 15:36:04.180576 | controller |   "islnk": false,
2025-10-02 15:36:04.180591 | controller |   "isreg": true,
2025-10-02 15:36:04.180604 | controller |   "issock": false,
2025-10-02 15:36:04.180618 | controller |   "isuid": false,
2025-10-02 15:36:04.180632 | controller |   "mode": "0644",
2025-10-02 15:36:04.180646 | controller |   "mtime": 1743545842.0111232,
2025-10-02 15:36:04.180662 | controller |   "nlink": 1,
2025-10-02 15:36:04.180676 | controller |   "path": "/home/zuul/crc-start.log",
2025-10-02 15:36:04.180690 | controller |   "pw_name": "zuul",
2025-10-02 15:36:04.180704 | controller |   "rgrp": true,
2025-10-02 15:36:04.180718 | controller |   "roth": true,
2025-10-02 15:36:04.180731 | controller |   "rusr": true,
2025-10-02 15:36:04.180745 | controller |   "size": 4023,
2025-10-02 15:36:04.180772 | controller |   "uid": 1000,
2025-10-02 15:36:04.180788 | controller |   "wgrp": false,
2025-10-02 15:36:04.180813 | controller |   "woth": false,
2025-10-02 15:36:04.180828 | controller |   "wusr": true,
2025-10-02 15:36:04.180842 | controller |   "xgrp": false,
2025-10-02 15:36:04.180856 | controller |   "xoth": false,
2025-10-02 15:36:04.180871 | controller |   "xusr": false
2025-10-02 15:36:04.180885 | controller | }
2025-10-02 15:36:04.729778 | controller | changed:
2025-10-02 15:36:04.729893 | controller | {
2025-10-02 15:36:04.729920 | controller |   "atime": 1759419052.3453157,
2025-10-02 15:36:04.729945 | controller |   "ctime": 1759419070.1644044,
2025-10-02 15:36:04.729963 | controller |   "dev": 64513,
2025-10-02 15:36:04.729980 | controller |   "gid": 1000,
2025-10-02 15:36:04.729997 | controller |   "gr_name": "zuul",
2025-10-02 15:36:04.730012 | controller |   "inode": 4686292,
2025-10-02 15:36:04.730028 | controller |   "isblk": false,
2025-10-02 15:36:04.730044 | controller |   "ischr": false,
2025-10-02 15:36:04.730060 | controller |   "isdir": false,
2025-10-02 15:36:04.730075 | controller |   "isfifo": false,
2025-10-02 15:36:04.730091 | controller |   "isgid": false,
2025-10-02 15:36:04.730107 | controller |   "islnk": false,
2025-10-02 15:36:04.730125 | controller |   "isreg": true,
2025-10-02 15:36:04.730141 | controller |   "issock": false,
2025-10-02 15:36:04.730157 | controller |   "isuid": false,
2025-10-02 15:36:04.730172 | controller |   "mode": "0644",
2025-10-02 15:36:04.730188 | controller |   "mtime": 1759419070.1644044,
2025-10-02 15:36:04.730204 | controller |   "nlink": 1,
2025-10-02 15:36:04.730219 | controller |   "path": "/home/zuul/ansible.log",
2025-10-02 15:36:04.730235 | controller |   "pw_name": "zuul",
2025-10-02 15:36:04.730249 | controller |   "rgrp": true,
2025-10-02 15:36:04.730264 | controller |   "roth": true,
2025-10-02 15:36:04.730278 | controller |   "rusr": true,
2025-10-02 15:36:04.730291 | controller |   "size": 6749,
2025-10-02 15:36:04.730305 | controller |   "uid": 1000,
2025-10-02 15:36:04.730320 | controller |   "wgrp": false,
2025-10-02 15:36:04.730336 | controller |   "woth": false,
2025-10-02 15:36:04.730352 | controller |   "wusr": true,
2025-10-02 15:36:04.730368 | controller |   "xgrp": false,
2025-10-02 15:36:04.730439 | controller |   "xoth": false,
2025-10-02 15:36:04.730463 | controller |   "xusr": false
2025-10-02 15:36:04.730481 | controller | }
2025-10-02 15:36:04.744206 | 
2025-10-02 15:36:04.744430 | TASK [Copy crio stats log file]
2025-10-02 15:36:04.769819 | controller | skipping: Conditional result was False
2025-10-02 15:36:04.776182 | 
2025-10-02 15:36:04.776253 | TASK [Get SELinux related data]
2025-10-02 15:36:04.803866 | [controller] Waiting on logger
2025-10-02 15:36:15.201000 | [controller] Waiting on logger
2025-10-02 15:36:25.632890 | [controller] Waiting on logger
2025-10-02 15:36:34.976750 | [controller] Waiting on logger
2025-10-02 15:36:35.083217 | [Zuul] Log Stream did not terminate
2025-10-02 15:36:35.083429 | controller | ERROR
2025-10-02 15:36:35.083522 | controller | {
2025-10-02 15:36:35.083553 | controller |   "delta": "0:00:00.008856",
2025-10-02 15:36:35.083572 | controller |   "end": "2025-10-02 15:36:05.055655",
2025-10-02 15:36:35.083590 | controller |   "msg": "non-zero return code",
2025-10-02 15:36:35.083605 | controller |   "rc": 1,
2025-10-02 15:36:35.083622 | controller |   "start": "2025-10-02 15:36:05.046799"
2025-10-02 15:36:35.083638 | controller | }
2025-10-02 15:36:35.083662 | controller | ERROR: Ignoring Errors
2025-10-02 15:36:35.088919 | 
2025-10-02 15:36:35.088984 | TASK [Create system configuration directory]
2025-10-02 15:36:35.351115 | controller | changed
2025-10-02 15:36:35.356036 | 
2025-10-02 15:36:35.356103 | TASK [Get some of the system configurations]
2025-10-02 15:36:40.382903 | controller | changed
2025-10-02 15:36:40.388651 | 
2025-10-02 15:36:40.388719 | TASK [Copy generated documentation if available]
2025-10-02 15:36:40.401973 | controller | skipping: Conditional result was False
2025-10-02 15:36:40.407700 | 
2025-10-02 15:36:40.407778 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-02 15:36:40.421089 | controller | skipping: Conditional result was False
2025-10-02 15:36:40.426566 | 
2025-10-02 15:36:40.426630 | TASK [Compress logs bigger than 2MB]
2025-10-02 15:36:45.408914 | [controller] Waiting on logger
2025-10-02 15:36:45.454209 | controller | changed
2025-10-02 15:36:45.459524 | 
2025-10-02 15:36:45.459600 | TASK [Copy files from workspace on node]
2025-10-02 15:36:45.477536 | controller | ok
2025-10-02 15:36:45.499973 | 
2025-10-02 15:36:45.500047 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-02 15:36:45.523617 | controller | skipping: Conditional result was False
2025-10-02 15:36:45.528925 | 
2025-10-02 15:36:45.528992 | TASK [fetch-output : Set log path for single node]
2025-10-02 15:36:45.556806 | controller | ok
2025-10-02 15:36:45.561776 | 
2025-10-02 15:36:45.561843 | LOOP [fetch-output : Ensure local output dirs]
2025-10-02 15:36:45.773946 | controller -> localhost | ok: "/var/lib/zuul/builds/2044b1b706ee4a9bb7d0b759f8326766/work/logs"
2025-10-02 15:36:45.774189 | controller -> localhost | changed: All items complete
2025-10-02 15:36:45.774223 | 
2025-10-02 15:36:45.961575 | controller -> localhost | changed: "/var/lib/zuul/builds/2044b1b706ee4a9bb7d0b759f8326766/work/artifacts"
2025-10-02 15:36:46.152003 | controller -> localhost | changed: "/var/lib/zuul/builds/2044b1b706ee4a9bb7d0b759f8326766/work/docs"
2025-10-02 15:36:46.160321 | 
2025-10-02 15:36:46.160443 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-02 15:36:46.803495 | controller | changed:
2025-10-02 15:36:46.803662 | controller | .d..t...... ./
2025-10-02 15:36:46.803694 | controller | >f+++++++++ README.html
2025-10-02 15:36:46.803717 | controller | >f+++++++++ ansible-execution.log
2025-10-02 15:36:46.803738 | controller | >f+++++++++ ansible.log
2025-10-02 15:36:46.803768 | controller | >f+++++++++ crc-setup.log
2025-10-02 15:36:46.803790 | controller | >f+++++++++ crc-start.log
2025-10-02 15:36:46.803809 | controller | >f+++++++++ dmesg.log
2025-10-02 15:36:46.803827 | controller | >f+++++++++ installed-pkgs.log
2025-10-02 15:36:46.803845 | controller | >f+++++++++ python.log
2025-10-02 15:36:46.803863 | controller | >f+++++++++ registries.conf
2025-10-02 15:36:46.803880 | controller | >f+++++++++ report.html
2025-10-02 15:36:46.803896 | controller | >f+++++++++ selinux-denials.log
2025-10-02 15:36:46.803915 | controller | >f+++++++++ selinux-listing.log
2025-10-02 15:36:46.803933 | controller | cd+++++++++ ci-framework-data/
2025-10-02 15:36:46.803950 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-02 15:36:46.803968 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_verify_external_network.sh
2025-10-02 15:36:46.803986 | controller | >f+++++++++ ci-framework-data/artifacts/kni_id_cifw
2025-10-02 15:36:46.804003 | controller | >f+++++++++ ci-framework-data/artifacts/kni_id_cifw.pub
2025-10-02 15:36:46.804019 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-02 15:36:46.804035 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-10-02 15:36:46.804052 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-10-02 15:36:46.804068 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-10-02 15:36:46.804084 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-02 15:36:46.804100 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-02 15:36:46.804116 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-02 15:36:46.804132 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-02 15:36:46.804158 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-02 15:36:46.804176 | controller | >f+++++++++ ci-framework-data/logs/ci_script_000_verify_external_network.log
2025-10-02 15:36:46.804193 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-02 15:36:46.804210 | controller | cd+++++++++ registries.conf.d/
2025-10-02 15:36:46.804232 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-02 15:36:46.804251 | controller | cd+++++++++ system-config/
2025-10-02 15:36:46.804268 | controller | cd+++++++++ system-config/libvirt/
2025-10-02 15:36:46.804285 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-02 15:36:46.804302 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-02 15:36:46.804319 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-10-02 15:36:46.804335 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-10-02 15:36:46.804352 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-10-02 15:36:46.804368 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-10-02 15:36:46.804412 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-10-02 15:36:46.804439 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-10-02 15:36:46.804459 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-10-02 15:36:46.804477 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-10-02 15:36:46.804493 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-10-02 15:36:46.804510 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-10-02 15:36:46.804525 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-10-02 15:36:46.804541 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-10-02 15:36:46.804557 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-10-02 15:36:46.804680 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-10-02 15:36:47.265212 | controller | changed: .d..t...... ./
2025-10-02 15:36:47.719008 | controller | changed: .d..t...... ./
2025-10-02 15:36:47.738899 | 
2025-10-02 15:36:47.739004 | TASK [Return artifact to Zuul]
2025-10-02 15:36:47.772266 | controller | ok
2025-10-02 15:36:47.795056 | 
2025-10-02 15:36:47.795134 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-02 15:36:47.795238 | 
2025-10-02 15:36:47.795266 | PLAY RECAP
2025-10-02 15:36:47.795307 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-02 15:36:47.795329 | 
2025-10-02 15:36:47.888616 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-02 15:36:47.889411 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-02 15:36:48.428515 | 
2025-10-02 15:36:48.428604 | PLAY [all]
2025-10-02 15:36:48.447099 | 
2025-10-02 15:36:48.447174 | TASK [include_role : fetch-output]
2025-10-02 15:36:48.475312 | controller | ok
2025-10-02 15:36:48.492186 | 
2025-10-02 15:36:48.492269 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-02 15:36:48.536109 | controller | skipping: Conditional result was False
2025-10-02 15:36:48.541621 | 
2025-10-02 15:36:48.541695 | TASK [fetch-output : Set log path for single node]
2025-10-02 15:36:48.570517 | controller | ok
2025-10-02 15:36:48.575667 | 
2025-10-02 15:36:48.575740 | LOOP [fetch-output : Ensure local output dirs]
2025-10-02 15:36:48.916083 | controller -> localhost | ok: "/var/lib/zuul/builds/2044b1b706ee4a9bb7d0b759f8326766/work/logs"
2025-10-02 15:36:49.104474 | controller -> localhost | ok: "/var/lib/zuul/builds/2044b1b706ee4a9bb7d0b759f8326766/work/artifacts"
2025-10-02 15:36:49.296523 | controller -> localhost | ok: "/var/lib/zuul/builds/2044b1b706ee4a9bb7d0b759f8326766/work/docs"
2025-10-02 15:36:49.307535 | 
2025-10-02 15:36:49.307639 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-02 15:36:49.905659 | controller | ok
2025-10-02 15:36:49.905851 | controller | ok: All items complete
2025-10-02 15:36:49.905881 | 
2025-10-02 15:36:50.378187 | controller | ok
2025-10-02 15:36:50.857730 | controller | ok
2025-10-02 15:36:50.873680 | 
2025-10-02 15:36:50.873751 | TASK [include_role : fetch-output-openshift]
2025-10-02 15:36:50.886660 | controller | skipping: Conditional result was False
2025-10-02 15:36:50.892462 | 
2025-10-02 15:36:50.892572 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-02 15:36:51.255329 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006541
2025-10-02 15:36:51.445360 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006943
2025-10-02 15:36:51.474275 | 
2025-10-02 15:36:51.474368 | PLAY [all]
2025-10-02 15:36:51.488768 | 
2025-10-02 15:36:51.488838 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-02 15:36:51.952896 | controller | changed
2025-10-02 15:36:51.974370 | 
2025-10-02 15:36:51.974642 | PLAY RECAP
2025-10-02 15:36:51.974698 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-02 15:36:51.974723 | 
2025-10-02 15:36:52.044070 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-02 15:36:52.044799 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-02 15:36:52.625081 | 
2025-10-02 15:36:52.625214 | PLAY [localhost]
2025-10-02 15:36:52.653136 | 
2025-10-02 15:36:52.653211 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-02 15:36:52.994845 | localhost | changed
2025-10-02 15:36:52.999504 | 
2025-10-02 15:36:52.999582 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-02 15:36:53.040205 | localhost | ok
2025-10-02 15:36:53.048508 | 
2025-10-02 15:36:53.048578 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-02 15:36:53.410733 | localhost | changed
2025-10-02 15:36:53.416582 | 
2025-10-02 15:36:53.416656 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-02 15:36:54.050871 | localhost | changed
2025-10-02 15:36:54.055899 | 
2025-10-02 15:36:54.055981 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-02 15:36:54.435674 | localhost | Identity added: /var/lib/zuul/builds/2044b1b706ee4a9bb7d0b759f8326766/work/tmp/ansible.52_v5z15 (/var/lib/zuul/builds/2044b1b706ee4a9bb7d0b759f8326766/work/tmp/ansible.52_v5z15)
2025-10-02 15:36:54.435864 | localhost | ok: Runtime: 0:00:00.006310
2025-10-02 15:36:54.440261 | 
2025-10-02 15:36:54.440331 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-02 15:36:54.669210 | localhost | ok: Runtime: 0:00:00.004057
2025-10-02 15:36:54.687880 | 
2025-10-02 15:36:54.688016 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-02 15:36:54.741211 | localhost | changed
2025-10-02 15:36:54.745537 | 
2025-10-02 15:36:54.745606 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-02 15:36:55.107179 | localhost | changed
2025-10-02 15:36:55.130266 | 
2025-10-02 15:36:55.130357 | PLAY [localhost]
2025-10-02 15:36:55.145161 | 
2025-10-02 15:36:55.145441 | TASK [Generate bulk log download script]
2025-10-02 15:36:55.164310 | localhost | ok
2025-10-02 15:36:55.177902 | 
2025-10-02 15:36:55.177992 | TASK [local-log-download : Check API endpoint is defined]
2025-10-02 15:36:55.206552 | localhost | ok: All assertions passed
2025-10-02 15:36:55.211457 | 
2025-10-02 15:36:55.211527 | TASK [local-log-download : Create download script]
2025-10-02 15:36:55.599555 | localhost -> localhost | changed
2025-10-02 15:36:55.609697 | 
2025-10-02 15:36:55.609802 | TASK [Register quick-download link]
2025-10-02 15:36:55.627818 | localhost | ok
2025-10-02 15:36:55.665714 | 
2025-10-02 15:36:55.665817 | PLAY [logserver.rdoproject.org]
2025-10-02 15:36:55.676514 | 
2025-10-02 15:36:55.676579 | TASK [Set zuul-log-path fact]
2025-10-02 15:36:55.693165 | logserver.rdoproject.org | ok
2025-10-02 15:36:55.702903 | 
2025-10-02 15:36:55.702976 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-02 15:36:55.720271 | logserver.rdoproject.org | ok
2025-10-02 15:36:55.726040 | 
2025-10-02 15:36:55.726105 | TASK [upload-logs : Create log directories]
2025-10-02 15:36:57.539625 | logserver.rdoproject.org | changed
2025-10-02 15:36:57.542999 | 
2025-10-02 15:36:57.543066 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-02 15:36:57.785080 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005443
2025-10-02 15:36:57.789920 | 
2025-10-02 15:36:57.789986 | TASK [upload-logs : Upload logs to log server]
2025-10-02 15:36:59.341130 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-02 15:36:59.344060 | 
2025-10-02 15:36:59.344134 | LOOP [upload-logs : Compress console log and json output]
2025-10-02 15:36:59.391084 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 15:36:59.391811 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 15:36:59.401921 | 
2025-10-02 15:36:59.402025 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-02 15:36:59.440180 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 15:36:59.440371 | 
2025-10-02 15:36:59.440896 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 15:36:59.450283 | 
2025-10-02 15:36:59.450416 | LOOP [upload-logs : Upload console log and json output]