2025-10-01 10:25:45.905999 | Job console starting...
2025-10-01 10:25:46.281811 | Updating repositories
2025-10-01 10:25:47.020376 | Preparing job workspace
2025-10-01 10:26:02.698706 | Running Ansible setup...
2025-10-01 10:26:10.403649 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-01 10:26:12.759544 | 
2025-10-01 10:26:12.759664 | PLAY [localhost]
2025-10-01 10:26:12.798718 | 
2025-10-01 10:26:12.798795 | TASK [Gathering Facts]
2025-10-01 10:26:15.577433 | localhost | ok
2025-10-01 10:26:15.605776 | 
2025-10-01 10:26:15.606871 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-01 10:26:17.145326 | localhost -> localhost | changed
2025-10-01 10:26:17.151659 | 
2025-10-01 10:26:17.151740 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-01 10:26:19.662511 | localhost -> localhost | changed
2025-10-01 10:26:19.671181 | 
2025-10-01 10:26:19.671260 | TASK [Setup log path fact]
2025-10-01 10:26:19.725893 | localhost | ok
2025-10-01 10:26:19.802171 | 
2025-10-01 10:26:19.802299 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-01 10:26:19.917689 | localhost | ok
2025-10-01 10:26:19.939411 | 
2025-10-01 10:26:19.939498 | TASK [emit-job-header : Print job information]
2025-10-01 10:26:20.073305 | # Job Information
2025-10-01 10:26:20.073503 | Ansible Version: 2.15.12
2025-10-01 10:26:20.073535 | Job: cifmw-molecule-ci_local_storage
2025-10-01 10:26:20.073561 | Pipeline: github-check
2025-10-01 10:26:20.073582 | Executor: ibm-bm3-ze.softwarefactory-project.io
2025-10-01 10:26:20.073602 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3237
2025-10-01 10:26:20.073621 | Log URL (when completed): https://logserver.rdoproject.org/917/rdoproject.org/917c4b02d9ba4071a90f9fd18dbd775d/
2025-10-01 10:26:20.073640 | Event ID: ca1127c0-9eb0-11f0-9508-c017e27814eb
2025-10-01 10:26:20.077527 | 
2025-10-01 10:26:20.077599 | LOOP [emit-job-header : Print node information]
2025-10-01 10:26:20.606798 | localhost | ok:
2025-10-01 10:26:20.606993 | localhost | # Node Information
2025-10-01 10:26:20.607026 | localhost | Inventory Hostname: controller
2025-10-01 10:26:20.607055 | localhost | Hostname: np0005464235
2025-10-01 10:26:20.607078 | localhost | Username: zuul
2025-10-01 10:26:20.607100 | localhost | Distro: CentOS 9
2025-10-01 10:26:20.607119 | localhost | Provider: ibm-bm3-nodepool
2025-10-01 10:26:20.607138 | localhost | Region: regionOne
2025-10-01 10:26:20.607155 | localhost | Label: centos-9-stream-crc-2-48-0-xl-ibm
2025-10-01 10:26:20.607173 | localhost | Product Name: OpenStack Compute
2025-10-01 10:26:20.607190 | localhost | Interface IP: 192.168.25.236
2025-10-01 10:26:20.677493 | 
2025-10-01 10:26:20.677596 | PLAY [all]
2025-10-01 10:26:20.698521 | 
2025-10-01 10:26:20.698592 | TASK [Gather network facts]
2025-10-01 10:26:21.220544 | controller | ok
2025-10-01 10:26:21.266521 | 
2025-10-01 10:26:21.266623 | TASK [include_role : start-zuul-console]
2025-10-01 10:26:21.327345 | controller | ok
2025-10-01 10:26:21.386509 | 
2025-10-01 10:26:21.386664 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-01 10:26:22.322777 | controller | ok
2025-10-01 10:26:22.346279 | 
2025-10-01 10:26:22.346355 | TASK [include_role : add-build-sshkey]
2025-10-01 10:26:22.430419 | controller | ok
2025-10-01 10:26:22.494968 | 
2025-10-01 10:26:22.495070 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-01 10:26:23.272200 | controller -> localhost | ok
2025-10-01 10:26:23.294200 | 
2025-10-01 10:26:23.294335 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-01 10:26:23.378970 | controller | ok
2025-10-01 10:26:23.443754 | controller | included: /var/lib/zuul/builds/917c4b02d9ba4071a90f9fd18dbd775d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-01 10:26:23.506354 | 
2025-10-01 10:26:23.506498 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-01 10:26:26.444758 | controller -> localhost | Generating public/private rsa key pair.
2025-10-01 10:26:26.444967 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/917c4b02d9ba4071a90f9fd18dbd775d/work/917c4b02d9ba4071a90f9fd18dbd775d_id_rsa.
2025-10-01 10:26:26.445011 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/917c4b02d9ba4071a90f9fd18dbd775d/work/917c4b02d9ba4071a90f9fd18dbd775d_id_rsa.pub.
2025-10-01 10:26:26.445036 | controller -> localhost | The key fingerprint is:
2025-10-01 10:26:26.445057 | controller -> localhost | SHA256:LtXf117I/7tcPhImioH6vsq/WWGE+baD+BqhKe75U5M zuul-build-sshkey
2025-10-01 10:26:26.445077 | controller -> localhost | The key's randomart image is:
2025-10-01 10:26:26.445096 | controller -> localhost | +---[RSA 3072]----+
2025-10-01 10:26:26.445115 | controller -> localhost | |                 |
2025-10-01 10:26:26.445135 | controller -> localhost | |      o          |
2025-10-01 10:26:26.445156 | controller -> localhost | |     o .         |
2025-10-01 10:26:26.445176 | controller -> localhost | |      o  .       |
2025-10-01 10:26:26.445196 | controller -> localhost | |   .  o=S .      |
2025-10-01 10:26:26.445215 | controller -> localhost | |  o oE++o  o = ..|
2025-10-01 10:26:26.445233 | controller -> localhost | |.o oo.o++ . + = =|
2025-10-01 10:26:26.445252 | controller -> localhost | |o ooo oo..   ..*o|
2025-10-01 10:26:26.445270 | controller -> localhost | |.+.=BOo       .+X|
2025-10-01 10:26:26.445289 | controller -> localhost | +----[SHA256]-----+
2025-10-01 10:26:26.445342 | controller -> localhost | ok: Runtime: 0:00:01.256326
2025-10-01 10:26:26.451850 | 
2025-10-01 10:26:26.451917 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-01 10:26:26.521071 | controller | ok
2025-10-01 10:26:26.564445 | controller | included: /var/lib/zuul/builds/917c4b02d9ba4071a90f9fd18dbd775d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-01 10:26:26.597882 | 
2025-10-01 10:26:26.597982 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-01 10:26:26.694073 | controller | skipping: Conditional result was False
2025-10-01 10:26:26.701607 | 
2025-10-01 10:26:26.701696 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-01 10:26:27.860135 | controller | changed
2025-10-01 10:26:27.870532 | 
2025-10-01 10:26:27.870599 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-01 10:26:28.264039 | controller | ok
2025-10-01 10:26:28.280196 | 
2025-10-01 10:26:28.280274 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-01 10:26:30.032879 | controller | changed
2025-10-01 10:26:30.037953 | 
2025-10-01 10:26:30.038015 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-01 10:26:31.420199 | controller | changed
2025-10-01 10:26:31.429858 | 
2025-10-01 10:26:31.429929 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-01 10:26:31.501641 | controller | skipping: Conditional result was False
2025-10-01 10:26:31.511629 | 
2025-10-01 10:26:31.511721 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-01 10:26:32.872042 | controller -> localhost | changed
2025-10-01 10:26:32.892523 | 
2025-10-01 10:26:32.892639 | TASK [add-build-sshkey : Add back temp key]
2025-10-01 10:26:33.892336 | controller -> localhost | Identity added: /var/lib/zuul/builds/917c4b02d9ba4071a90f9fd18dbd775d/work/917c4b02d9ba4071a90f9fd18dbd775d_id_rsa (zuul-build-sshkey)
2025-10-01 10:26:33.892577 | controller -> localhost | ok: Runtime: 0:00:00.042168
2025-10-01 10:26:33.900204 | 
2025-10-01 10:26:33.900271 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-01 10:26:34.566884 | controller | ok
2025-10-01 10:26:34.571929 | 
2025-10-01 10:26:34.571994 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-01 10:26:34.648433 | controller | skipping: Conditional result was False
2025-10-01 10:26:34.658630 | 
2025-10-01 10:26:34.658697 | TASK [include_role : validate-host]
2025-10-01 10:26:34.740463 | controller | ok
2025-10-01 10:26:34.818367 | 
2025-10-01 10:26:34.818589 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-01 10:26:34.948055 | controller | ok
2025-10-01 10:26:34.962436 | 
2025-10-01 10:26:34.962503 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-01 10:26:35.799215 | controller -> localhost | ok
2025-10-01 10:26:35.804966 | 
2025-10-01 10:26:35.805030 | TASK [validate-host : Collect information about the host]
2025-10-01 10:26:36.548164 | controller | ok
2025-10-01 10:26:36.587436 | 
2025-10-01 10:26:36.587543 | TASK [validate-host : Sanitize hostname]
2025-10-01 10:26:36.875629 | controller | ok
2025-10-01 10:26:36.880718 | 
2025-10-01 10:26:36.880786 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-01 10:26:38.621973 | controller -> localhost | changed
2025-10-01 10:26:38.628494 | 
2025-10-01 10:26:38.628592 | TASK [validate-host : Collect information about zuul worker]
2025-10-01 10:26:39.958225 | controller | ok
2025-10-01 10:26:39.973581 | 
2025-10-01 10:26:39.973672 | TASK [validate-host : Write out all zuul information for each host]
2025-10-01 10:26:42.249582 | controller -> localhost | changed
2025-10-01 10:26:42.261690 | 
2025-10-01 10:26:42.261766 | TASK [include_role : prepare-workspace-openshift]
2025-10-01 10:26:42.355508 | controller | skipping: Conditional result was False
2025-10-01 10:26:42.361933 | 
2025-10-01 10:26:42.362007 | TASK [include_role : remove-zuul-sshkey]
2025-10-01 10:26:42.440347 | controller | skipping: Conditional result was False
2025-10-01 10:26:42.447575 | 
2025-10-01 10:26:42.447685 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-01 10:26:42.831710 | controller | ok: "logs"
2025-10-01 10:26:42.831930 | controller | ok: All items complete
2025-10-01 10:26:42.831960 | 
2025-10-01 10:26:43.069295 | controller | ok: "artifacts"
2025-10-01 10:26:43.326088 | controller | ok: "docs"
2025-10-01 10:26:43.340358 | 
2025-10-01 10:26:43.340507 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-01 10:26:43.744147 | controller | changed: "logs"
2025-10-01 10:26:44.013714 | controller | changed: "artifacts"
2025-10-01 10:26:44.281666 | controller | changed: "docs"
2025-10-01 10:26:44.377882 | 
2025-10-01 10:26:44.377967 | PLAY RECAP
2025-10-01 10:26:44.378016 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-01 10:26:44.378047 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-01 10:26:44.378068 | 
2025-10-01 10:26:44.922879 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-01 10:26:44.923581 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-01 10:26:48.285642 | 
2025-10-01 10:26:48.285760 | PLAY [all]
2025-10-01 10:26:48.389503 | 
2025-10-01 10:26:48.389654 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-01 10:26:48.589527 | controller | ok
2025-10-01 10:26:48.596965 | 
2025-10-01 10:26:48.597085 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-01 10:26:49.931947 | controller | changed
2025-10-01 10:26:49.961240 | 
2025-10-01 10:26:49.961354 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-01 10:26:52.915079 | controller | changed
2025-10-01 10:26:52.964608 | 
2025-10-01 10:26:52.964721 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-01 10:26:54.254820 | controller | changed:
2025-10-01 10:26:54.254996 | controller | {
2025-10-01 10:26:54.255039 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-01 10:26:54.255428 | controller | }
2025-10-01 10:26:54.582770 | controller | changed:
2025-10-01 10:26:54.585181 | controller | {
2025-10-01 10:26:54.585230 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-01 10:26:54.585259 | controller | }
2025-10-01 10:26:54.904114 | controller | changed:
2025-10-01 10:26:54.904199 | controller | {
2025-10-01 10:26:54.904228 | 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-01 10:26:54.904250 | controller | }
2025-10-01 10:26:55.225470 | controller | changed:
2025-10-01 10:26:55.225559 | controller | {
2025-10-01 10:26:55.225587 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-01 10:26:55.225610 | controller | }
2025-10-01 10:26:55.745817 | controller | changed:
2025-10-01 10:26:55.745903 | controller | {
2025-10-01 10:26:55.745932 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-01 10:26:55.745955 | controller | }
2025-10-01 10:26:55.936665 | controller | changed:
2025-10-01 10:26:55.936750 | controller | {
2025-10-01 10:26:55.936777 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-01 10:26:55.936799 | controller | }
2025-10-01 10:26:56.281816 | controller | changed:
2025-10-01 10:26:56.281913 | controller | {
2025-10-01 10:26:56.281941 | 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-01 10:26:56.281964 | controller | }
2025-10-01 10:26:56.599675 | controller | changed:
2025-10-01 10:26:56.599860 | controller | {
2025-10-01 10:26:56.599896 | 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-01 10:26:56.599920 | controller | }
2025-10-01 10:26:56.899080 | controller | changed:
2025-10-01 10:26:56.899218 | controller | {
2025-10-01 10:26:56.899258 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-01 10:26:56.899287 | controller | }
2025-10-01 10:26:57.230209 | controller | changed:
2025-10-01 10:26:57.230314 | controller | {
2025-10-01 10:26:57.230343 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-01 10:26:57.230365 | controller | }
2025-10-01 10:26:57.561451 | controller | changed:
2025-10-01 10:26:57.561660 | controller | {
2025-10-01 10:26:57.561701 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-01 10:26:57.561728 | controller | }
2025-10-01 10:26:57.900265 | controller | changed:
2025-10-01 10:26:57.900347 | controller | {
2025-10-01 10:26:57.900375 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-01 10:26:57.900428 | controller | }
2025-10-01 10:26:58.209214 | controller | changed:
2025-10-01 10:26:58.209293 | controller | {
2025-10-01 10:26:58.209320 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-01 10:26:58.209340 | controller | }
2025-10-01 10:26:58.540488 | controller | changed:
2025-10-01 10:26:58.540579 | controller | {
2025-10-01 10:26:58.540606 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-01 10:26:58.540626 | controller | }
2025-10-01 10:26:58.875185 | controller | changed:
2025-10-01 10:26:58.875283 | controller | {
2025-10-01 10:26:58.875311 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-01 10:26:58.875332 | controller | }
2025-10-01 10:26:59.196641 | controller | changed:
2025-10-01 10:26:59.196813 | controller | {
2025-10-01 10:26:59.196847 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-01 10:26:59.196872 | controller | }
2025-10-01 10:26:59.530753 | controller | changed:
2025-10-01 10:26:59.530851 | controller | {
2025-10-01 10:26:59.530879 | 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-01 10:26:59.530903 | controller | }
2025-10-01 10:26:59.862516 | controller | changed:
2025-10-01 10:26:59.862680 | controller | {
2025-10-01 10:26:59.862715 | 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-01 10:26:59.862750 | controller | }
2025-10-01 10:27:00.192774 | controller | changed:
2025-10-01 10:27:00.192860 | controller | {
2025-10-01 10:27:00.192889 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-01 10:27:00.192911 | controller | }
2025-10-01 10:27:00.535074 | controller | changed:
2025-10-01 10:27:00.535161 | controller | {
2025-10-01 10:27:00.535189 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-01 10:27:00.535211 | controller | }
2025-10-01 10:27:00.851479 | controller | changed:
2025-10-01 10:27:00.851563 | controller | {
2025-10-01 10:27:00.851591 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-01 10:27:00.851613 | controller | }
2025-10-01 10:27:01.206164 | controller | changed:
2025-10-01 10:27:01.206315 | controller | {
2025-10-01 10:27:01.206349 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-01 10:27:01.206372 | controller | }
2025-10-01 10:27:01.526975 | controller | changed:
2025-10-01 10:27:01.527065 | controller | {
2025-10-01 10:27:01.527096 | 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-01 10:27:01.527119 | controller | }
2025-10-01 10:27:01.866041 | controller | changed:
2025-10-01 10:27:01.866122 | controller | {
2025-10-01 10:27:01.866150 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-01 10:27:01.866171 | controller | }
2025-10-01 10:27:02.158656 | controller | changed:
2025-10-01 10:27:02.158865 | controller | {
2025-10-01 10:27:02.158907 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-01 10:27:02.158933 | controller | }
2025-10-01 10:27:02.475305 | controller | changed:
2025-10-01 10:27:02.475479 | controller | {
2025-10-01 10:27:02.475516 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-01 10:27:02.475540 | controller | }
2025-10-01 10:27:02.511255 | 
2025-10-01 10:27:02.511366 | TASK [Set timezone to UTC]
2025-10-01 10:27:03.663633 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-01 10:27:03.674067 | 
2025-10-01 10:27:03.674137 | TASK [Create nodepool directory]
2025-10-01 10:27:04.063985 | controller | changed
2025-10-01 10:27:04.069810 | 
2025-10-01 10:27:04.069893 | TASK [Create nodepool sub_nodes file]
2025-10-01 10:27:05.375975 | controller | changed
2025-10-01 10:27:05.394727 | 
2025-10-01 10:27:05.394824 | TASK [Create nodepool sub_nodes_private file]
2025-10-01 10:27:06.605307 | controller | changed
2025-10-01 10:27:06.621940 | 
2025-10-01 10:27:06.622032 | LOOP [Populate nodepool sub_nodes file]
2025-10-01 10:27:06.857317 | 
2025-10-01 10:27:06.857552 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-01 10:27:06.971638 | 
2025-10-01 10:27:06.971888 | TASK [Create nodepool primary file]
2025-10-01 10:27:07.089872 | controller | skipping: Conditional result was False
2025-10-01 10:27:07.095883 | 
2025-10-01 10:27:07.095960 | TASK [Create nodepool node_private for this node]
2025-10-01 10:27:08.391049 | controller | changed
2025-10-01 10:27:08.396757 | 
2025-10-01 10:27:08.396821 | LOOP [Copy ssh keys to nodepool directory]
2025-10-01 10:27:09.655519 | controller | ok: Item: id_rsa Runtime: 0:00:00.005861
2025-10-01 10:27:09.655686 | 
2025-10-01 10:27:09.901751 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005357
2025-10-01 10:27:09.910303 | 
2025-10-01 10:27:09.910440 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-01 10:27:11.191267 | controller | changed
2025-10-01 10:27:11.197487 | 
2025-10-01 10:27:11.197560 | TASK [Validate sudoers config after edits]
2025-10-01 10:27:11.767571 | controller | /etc/sudoers: parsed OK
2025-10-01 10:27:11.767673 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-01 10:27:11.767688 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-01 10:27:11.767694 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-01 10:27:11.849663 | controller | ok: Runtime: 0:00:00.004689
2025-10-01 10:27:11.858780 | 
2025-10-01 10:27:11.858875 | TASK [Show the environment passed in to job shell scripts]
2025-10-01 10:27:12.644951 | controller | SHELL=/bin/bash
2025-10-01 10:27:12.645010 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-01 10:27:12.645020 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-01 10:27:12.645026 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/37/3237/6c8a9fa1410698edce0fcb9619a01150f49dbf44
2025-10-01 10:27:12.645031 | controller | PWD=/home/zuul
2025-10-01 10:27:12.645037 | controller | ZUUL_PIPELINE=github-check
2025-10-01 10:27:12.645043 | controller | LOGNAME=zuul
2025-10-01 10:27:12.645048 | controller | XDG_SESSION_TYPE=tty
2025-10-01 10:27:12.645053 | controller | _=/usr/bin/env
2025-10-01 10:27:12.645059 | controller | MOTD_SHOWN=pam
2025-10-01 10:27:12.645064 | controller | HOME=/home/zuul
2025-10-01 10:27:12.645114 | controller | LANG=en_US.UTF-8
2025-10-01 10:27:12.645122 | controller | SSH_CONNECTION=192.168.25.12 36830 192.168.25.236 22
2025-10-01 10:27:12.645128 | 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-01 10:27:12.645137 | controller | ZUUL_CHANGE_IDS=3237,6c8a9fa1410698edce0fcb9619a01150f49dbf44
2025-10-01 10:27:12.645142 | controller | WORKSPACE=/home/zuul/workspace
2025-10-01 10:27:12.645148 | controller | XDG_SESSION_CLASS=user
2025-10-01 10:27:12.645153 | controller | SELINUX_ROLE_REQUESTED=
2025-10-01 10:27:12.645158 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-01 10:27:12.645164 | controller | USER=zuul
2025-10-01 10:27:12.645169 | controller | ZUUL_VOTING=True
2025-10-01 10:27:12.645174 | controller | BUILD_TIMEOUT=1800000
2025-10-01 10:27:12.645179 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-01 10:27:12.645184 | controller | SHLVL=1
2025-10-01 10:27:12.645189 | controller | ZUUL_PATCHSET=6c8a9fa1410698edce0fcb9619a01150f49dbf44
2025-10-01 10:27:12.645194 | controller | XDG_SESSION_ID=1
2025-10-01 10:27:12.645200 | controller | ZUUL_BRANCH=main
2025-10-01 10:27:12.645205 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-01 10:27:12.645210 | controller | SSH_CLIENT=192.168.25.12 36830 22
2025-10-01 10:27:12.645215 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-01 10:27:12.645221 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-01 10:27:12.645227 | controller | which_declare=declare -f
2025-10-01 10:27:12.645232 | 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-01 10:27:12.645238 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-01 10:27:12.645243 | controller | ZUUL_CHANGE=3237
2025-10-01 10:27:12.645248 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-01 10:27:12.645253 | controller | ZUUL_UUID=917c4b02d9ba4071a90f9fd18dbd775d
2025-10-01 10:27:12.645258 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-01 10:27:12.645263 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-01 10:27:12.645269 | controller | }
2025-10-01 10:27:12.699599 | controller | ok: Runtime: 0:00:00.009035
2025-10-01 10:27:12.711377 | 
2025-10-01 10:27:12.711476 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-01 10:27:12.793906 | controller | skipping: Conditional result was False
2025-10-01 10:27:12.799741 | 
2025-10-01 10:27:12.799811 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-01 10:27:13.424029 | controller | skipping: Conditional result was False
2025-10-01 10:27:13.430605 | 
2025-10-01 10:27:13.430705 | TASK [Ensure legacy workspace directory]
2025-10-01 10:27:13.806788 | controller | changed
2025-10-01 10:27:13.923956 | 
2025-10-01 10:27:13.924061 | PLAY RECAP
2025-10-01 10:27:13.924114 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-01 10:27:13.924139 | 
2025-10-01 10:27:14.535902 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-01 10:27:14.536627 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-01 10:27:16.728234 | 
2025-10-01 10:27:16.728404 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-01 10:27:16.832982 | 
2025-10-01 10:27:16.833102 | TASK [Create zuul-output directory]
2025-10-01 10:27:17.844313 | controller | changed
2025-10-01 10:27:17.854244 | 
2025-10-01 10:27:17.854365 | TASK [Slurp Zuul inventory test]
2025-10-01 10:27:19.106207 | controller -> localhost | ok
2025-10-01 10:27:19.114038 | 
2025-10-01 10:27:19.114107 | TASK [Save zuul inventory]
2025-10-01 10:27:21.177551 | controller | changed
2025-10-01 10:27:21.183586 | 
2025-10-01 10:27:21.183655 | TASK [Save zuul vars without the change_message]
2025-10-01 10:27:22.519686 | controller | changed
2025-10-01 10:27:22.563183 | 
2025-10-01 10:27:22.563258 | PLAY RECAP
2025-10-01 10:27:22.563306 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-01 10:27:22.563330 | 
2025-10-01 10:27:22.765029 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-01 10:27:22.765734 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-01 10:27:24.466274 | 
2025-10-01 10:27:24.466370 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-01 10:27:24.532845 | 
2025-10-01 10:27:24.532973 | TASK [Read group_vars]
2025-10-01 10:27:24.614959 | controller | ok
2025-10-01 10:27:24.686333 | 
2025-10-01 10:27:24.686502 | TASK [cifmw_helpers : Check directory is available]
2025-10-01 10:27:25.204448 | controller | ok
2025-10-01 10:27:25.212328 | 
2025-10-01 10:27:25.212430 | TASK [cifmw_helpers : List dir in ansible user home]
2025-10-01 10:27:25.822095 | controller | var one
2025-10-01 10:27:25.823641 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars'$'\n': No such file or directory
2025-10-01 10:27:25.825303 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory
2025-10-01 10:27:25.826764 | controller | ls: cannot access '/root/src/github.com/openstack-k8s-operators/ci-framework/group_vars': Permission denied
2025-10-01 10:27:25.828133 | controller | ls: cannot access '/workspace/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory
2025-10-01 10:27:26.304759 | controller | ERROR
2025-10-01 10:27:26.304950 | controller | {
2025-10-01 10:27:26.304983 | controller |   "delta": "0:00:00.010245",
2025-10-01 10:27:26.305006 | controller |   "end": "2025-10-01 10:27:25.828464",
2025-10-01 10:27:26.305028 | controller |   "msg": "non-zero return code",
2025-10-01 10:27:26.305054 | controller |   "rc": 2,
2025-10-01 10:27:26.305077 | controller |   "start": "2025-10-01 10:27:25.818219"
2025-10-01 10:27:26.305096 | controller | }
2025-10-01 10:27:26.305123 | controller | ERROR: Ignoring Errors
2025-10-01 10:27:26.313918 | 
2025-10-01 10:27:26.313987 | TASK [cifmw_helpers : List available files]
2025-10-01 10:27:26.967127 | controller | skipping: Conditional result was False
2025-10-01 10:27:26.973321 | 
2025-10-01 10:27:26.973447 | LOOP [cifmw_helpers : Read vars]
2025-10-01 10:27:27.051722 | 
2025-10-01 10:27:27.051889 | LOOP [cifmw_helpers : Call task to parse all files as fact]
2025-10-01 10:27:27.090102 | 
2025-10-01 10:27:27.090243 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-01 10:27:27.134221 | controller | ok
2025-10-01 10:27:27.218875 | 
2025-10-01 10:27:27.218974 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-01 10:27:27.304965 | controller | skipping: Conditional result was False
2025-10-01 10:27:27.310914 | 
2025-10-01 10:27:27.310989 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-01 10:27:27.739872 | controller | ok
2025-10-01 10:27:27.746104 | 
2025-10-01 10:27:27.746192 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-01 10:27:28.560985 | controller | ok
2025-10-01 10:27:28.582913 | 
2025-10-01 10:27:28.583030 | TASK [Prepare workspace]
2025-10-01 10:27:28.617928 | controller | ok
2025-10-01 10:27:28.657419 | 
2025-10-01 10:27:28.657548 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-01 10:27:29.027757 | controller | ok
2025-10-01 10:27:29.037170 | 
2025-10-01 10:27:29.037263 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-01 10:27:31.783236 | controller | Output suppressed because no_log was given
2025-10-01 10:27:31.803775 | 
2025-10-01 10:27:31.803868 | LOOP [Create zuul-output directory]
2025-10-01 10:27:32.079866 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-01 10:27:32.080078 | 
2025-10-01 10:27:32.289241 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-01 10:27:32.305134 | 
2025-10-01 10:27:32.305250 | TASK [Install required packages]
2025-10-01 10:27:54.733980 | controller | ok: Nothing to do
2025-10-01 10:27:54.739508 | 
2025-10-01 10:27:54.739872 | TASK [Install venv]
2025-10-01 10:28:53.791351 | controller | changed
2025-10-01 10:28:53.816231 | 
2025-10-01 10:28:53.816306 | PLAY RECAP
2025-10-01 10:28:53.816352 | controller | ok: 9 changed: 4 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-01 10:28:53.816376 | 
2025-10-01 10:28:53.917501 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-01 10:28:53.918245 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-01 10:28:54.466595 | 
2025-10-01 10:28:54.466698 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-01 10:28:54.487297 | 
2025-10-01 10:28:54.487406 | TASK [Gather required facts]
2025-10-01 10:28:55.043743 | controller | ok
2025-10-01 10:28:55.049283 | 
2025-10-01 10:28:55.049367 | TASK [Load environment var if instructed to]
2025-10-01 10:28:55.073313 | controller | skipping: Conditional result was False
2025-10-01 10:28:55.079372 | 
2025-10-01 10:28:55.079474 | TASK [Run molecule]
2025-10-01 10:28:56.094938 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-01 10:28:56.164263 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-01 10:29:13.877198 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-01 10:29:13.877608 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-01 10:29:13.878070 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-01 10:29:13.878515 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-01 10:29:13.878984 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+f161d0d2 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 1.0.0+f161d0d2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-01 10:29:13.879443 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-01 10:29:13.879888 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-01 10:29:13.880332 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-01 10:29:13.880851 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-01 10:29:13.881290 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-01 10:29:13.881758 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-01 10:29:13.882218 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-01 10:29:13.882660 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-01 10:29:13.883104 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-01 10:29:13.883533 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/fc7f8d/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-01 10:29:13.892740 | controller | INFO     Running default > prepare
2025-10-01 10:29:14.580547 | controller |
2025-10-01 10:29:14.580617 | controller | PLAY [Prepare] *****************************************************************
2025-10-01 10:29:14.580751 | controller |
2025-10-01 10:29:14.580846 | controller | TASK [Gathering Facts] *********************************************************
2025-10-01 10:29:14.580950 | controller | Wednesday 01 October 2025  10:29:14 +0000 (0:00:00.013)       0:00:00.013 *****
2025-10-01 10:29:15.428583 | controller | ok: [instance]
2025-10-01 10:29:15.428670 | controller |
2025-10-01 10:29:15.428858 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-01 10:29:15.428903 | controller | Wednesday 01 October 2025  10:29:15 +0000 (0:00:00.848)       0:00:00.861 *****
2025-10-01 10:29:15.446121 | controller | skipping: [instance]
2025-10-01 10:29:15.446238 | controller |
2025-10-01 10:29:15.446362 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-01 10:29:15.446485 | controller | Wednesday 01 October 2025  10:29:15 +0000 (0:00:00.017)       0:00:00.879 *****
2025-10-01 10:29:15.514860 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-01 10:29:15.514940 | controller |
2025-10-01 10:29:15.515074 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-01 10:29:15.515183 | controller | Wednesday 01 October 2025  10:29:15 +0000 (0:00:00.068)       0:00:00.948 *****
2025-10-01 10:29:15.796610 | controller | ok: [instance]
2025-10-01 10:29:15.796726 | controller |
2025-10-01 10:29:15.796846 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-01 10:29:15.796952 | controller | Wednesday 01 October 2025  10:29:15 +0000 (0:00:00.281)       0:00:01.229 *****
2025-10-01 10:29:16.107807 | controller | ok: [instance]
2025-10-01 10:29:16.107891 | controller |
2025-10-01 10:29:16.108009 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-01 10:29:16.108120 | controller | Wednesday 01 October 2025  10:29:16 +0000 (0:00:00.311)       0:00:01.540 *****
2025-10-01 10:29:16.793029 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-01 10:29:16.793133 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-01 10:29:16.793305 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-01 10:29:16.793388 | controller |
2025-10-01 10:29:16.793493 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-01 10:29:16.793601 | controller | Wednesday 01 October 2025  10:29:16 +0000 (0:00:00.685)       0:00:02.226 *****
2025-10-01 10:29:16.851691 | controller |
2025-10-01 10:29:16.851804 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-01 10:29:16.851934 | controller | Wednesday 01 October 2025  10:29:16 +0000 (0:00:00.058)       0:00:02.285 *****
2025-10-01 10:29:17.403860 | controller | changed: [instance] => (item=tmp)
2025-10-01 10:29:17.403953 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-01 10:29:17.404114 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-01 10:29:17.404138 | controller |
2025-10-01 10:29:17.404196 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-01 10:29:17.404300 | controller | Wednesday 01 October 2025  10:29:17 +0000 (0:00:00.551)       0:00:02.836 *****
2025-10-01 10:29:18.221224 | controller | ok: [instance]
2025-10-01 10:29:18.221630 | controller |
2025-10-01 10:29:19.506718 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-01 10:29:19.506759 | controller | Wednesday 01 October 2025  10:29:18 +0000 (0:00:00.817)       0:00:03.654 *****
2025-10-01 10:29:19.506774 | controller | changed: [instance]
2025-10-01 10:29:19.506805 | controller |
2025-10-01 10:29:19.506823 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-01 10:29:19.507068 | controller | Wednesday 01 October 2025  10:29:19 +0000 (0:00:01.285)       0:00:04.939 *****
2025-10-01 10:29:27.608368 | controller | changed: [instance]
2025-10-01 10:29:28.326491 | controller |
2025-10-01 10:29:28.326531 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-01 10:29:28.326539 | controller | Wednesday 01 October 2025  10:29:27 +0000 (0:00:08.101)       0:00:13.041 *****
2025-10-01 10:29:28.326549 | controller | changed: [instance]
2025-10-01 10:29:28.347512 | controller |
2025-10-01 10:29:28.347536 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-01 10:29:28.347546 | controller | Wednesday 01 October 2025  10:29:28 +0000 (0:00:00.718)       0:00:13.759 *****
2025-10-01 10:29:28.347556 | controller | skipping: [instance]
2025-10-01 10:29:29.182756 | controller |
2025-10-01 10:29:29.182794 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-01 10:29:29.182803 | controller | Wednesday 01 October 2025  10:29:28 +0000 (0:00:00.021)       0:00:13.780 *****
2025-10-01 10:29:29.182813 | controller | changed: [instance]
2025-10-01 10:29:29.219621 | controller |
2025-10-01 10:29:29.219647 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-01 10:29:29.219656 | controller | Wednesday 01 October 2025  10:29:29 +0000 (0:00:00.834)       0:00:14.615 *****
2025-10-01 10:29:29.219668 | controller | skipping: [instance]
2025-10-01 10:29:29.255730 | controller |
2025-10-01 10:29:29.255770 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-01 10:29:29.255780 | controller | Wednesday 01 October 2025  10:29:29 +0000 (0:00:00.037)       0:00:14.652 *****
2025-10-01 10:29:29.255792 | controller | skipping: [instance]
2025-10-01 10:29:29.255824 | controller |
2025-10-01 10:29:29.255834 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-01 10:29:29.256336 | controller | Wednesday 01 October 2025  10:29:29 +0000 (0:00:00.036)       0:00:14.688 *****
2025-10-01 10:29:29.292086 | controller | skipping: [instance]
2025-10-01 10:29:29.846699 | controller |
2025-10-01 10:29:29.846734 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-01 10:29:29.846743 | controller | Wednesday 01 October 2025  10:29:29 +0000 (0:00:00.036)       0:00:14.725 *****
2025-10-01 10:29:29.846753 | controller | changed: [instance]
2025-10-01 10:29:29.846777 | controller |
2025-10-01 10:29:29.846789 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-01 10:29:29.846935 | controller | Wednesday 01 October 2025  10:29:29 +0000 (0:00:00.554)       0:00:15.279 *****
2025-10-01 10:29:30.285735 | controller | changed: [instance]
2025-10-01 10:29:30.285929 | controller |
2025-10-01 10:29:30.286078 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-01 10:29:30.286216 | controller | Wednesday 01 October 2025  10:29:30 +0000 (0:00:00.439)       0:00:15.718 *****
2025-10-01 10:29:30.314711 | controller | skipping: [instance]
2025-10-01 10:29:30.314876 | controller |
2025-10-01 10:29:30.315041 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-01 10:29:30.315195 | controller | Wednesday 01 October 2025  10:29:30 +0000 (0:00:00.029)       0:00:15.748 *****
2025-10-01 10:29:30.342891 | controller | skipping: [instance]
2025-10-01 10:29:30.343107 | controller |
2025-10-01 10:29:30.343293 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-01 10:29:30.343465 | controller | Wednesday 01 October 2025  10:29:30 +0000 (0:00:00.028)       0:00:15.776 *****
2025-10-01 10:29:30.371986 | controller | skipping: [instance]
2025-10-01 10:29:30.372179 | controller |
2025-10-01 10:29:30.372357 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-01 10:29:30.372529 | controller | Wednesday 01 October 2025  10:29:30 +0000 (0:00:00.029)       0:00:15.805 *****
2025-10-01 10:29:30.410013 | controller | ok: [instance]
2025-10-01 10:29:30.410169 | controller |
2025-10-01 10:29:30.410315 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-01 10:29:30.410458 | controller | Wednesday 01 October 2025  10:29:30 +0000 (0:00:00.038)       0:00:15.843 *****
2025-10-01 10:29:30.437380 | controller | skipping: [instance]
2025-10-01 10:29:30.437532 | controller |
2025-10-01 10:29:30.437701 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-01 10:29:30.437845 | controller | Wednesday 01 October 2025  10:29:30 +0000 (0:00:00.027)       0:00:15.870 *****
2025-10-01 10:29:30.495635 | controller | skipping: [instance]
2025-10-01 10:29:30.495854 | controller |
2025-10-01 10:29:30.496055 | controller | TASK [Download the RPM] ********************************************************
2025-10-01 10:29:30.496282 | controller | Wednesday 01 October 2025  10:29:30 +0000 (0:00:00.057)       0:00:15.928 *****
2025-10-01 10:29:30.523814 | controller | skipping: [instance]
2025-10-01 10:29:30.524023 | controller |
2025-10-01 10:29:30.524194 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-01 10:29:30.524335 | controller | Wednesday 01 October 2025  10:29:30 +0000 (0:00:00.028)       0:00:15.957 *****
2025-10-01 10:29:30.551500 | controller | skipping: [instance]
2025-10-01 10:29:30.551677 | controller |
2025-10-01 10:29:30.551827 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-01 10:29:30.551994 | controller | Wednesday 01 October 2025  10:29:30 +0000 (0:00:00.027)       0:00:15.985 *****
2025-10-01 10:29:30.578505 | controller | skipping: [instance]
2025-10-01 10:29:30.578690 | controller |
2025-10-01 10:29:30.578843 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-01 10:29:30.579008 | controller | Wednesday 01 October 2025  10:29:30 +0000 (0:00:00.026)       0:00:16.011 *****
2025-10-01 10:29:30.606938 | controller | skipping: [instance]
2025-10-01 10:29:30.607130 | controller |
2025-10-01 10:29:30.607316 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-01 10:29:30.607468 | controller | Wednesday 01 October 2025  10:29:30 +0000 (0:00:00.028)       0:00:16.040 *****
2025-10-01 10:29:30.631513 | controller | skipping: [instance]
2025-10-01 10:29:30.631733 | controller |
2025-10-01 10:29:30.631934 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-01 10:29:30.632110 | controller | Wednesday 01 October 2025  10:29:30 +0000 (0:00:00.024)       0:00:16.065 *****
2025-10-01 10:29:30.813788 | controller | ok: [instance]
2025-10-01 10:29:30.813865 | controller |
2025-10-01 10:29:30.813879 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-01 10:29:31.030741 | controller | Wednesday 01 October 2025  10:29:30 +0000 (0:00:00.181)       0:00:16.246 *****
2025-10-01 10:29:31.030802 | controller | changed: [instance]
2025-10-01 10:29:31.295612 | controller |
2025-10-01 10:29:31.295651 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-01 10:29:31.295659 | controller | Wednesday 01 October 2025  10:29:31 +0000 (0:00:00.216)       0:00:16.463 *****
2025-10-01 10:29:31.295670 | controller | changed: [instance]
2025-10-01 10:29:31.318339 | controller |
2025-10-01 10:29:31.318367 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-01 10:29:31.318377 | controller | Wednesday 01 October 2025  10:29:31 +0000 (0:00:00.264)       0:00:16.728 *****
2025-10-01 10:29:31.318388 | controller | skipping: [instance]
2025-10-01 10:29:31.341363 | controller |
2025-10-01 10:29:31.341391 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-01 10:29:31.341400 | controller | Wednesday 01 October 2025  10:29:31 +0000 (0:00:00.023)       0:00:16.751 *****
2025-10-01 10:29:31.341411 | controller | skipping: [instance]
2025-10-01 10:29:31.365207 | controller |
2025-10-01 10:29:31.365235 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-01 10:29:31.365244 | controller | Wednesday 01 October 2025  10:29:31 +0000 (0:00:00.022)       0:00:16.774 *****
2025-10-01 10:29:31.365262 | controller | skipping: [instance]
2025-10-01 10:29:31.388279 | controller |
2025-10-01 10:29:31.388308 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-01 10:29:31.388317 | controller | Wednesday 01 October 2025  10:29:31 +0000 (0:00:00.023)       0:00:16.798 *****
2025-10-01 10:29:31.388334 | controller | skipping: [instance]
2025-10-01 10:29:31.413760 | controller |
2025-10-01 10:29:31.413805 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-01 10:29:31.413815 | controller | Wednesday 01 October 2025  10:29:31 +0000 (0:00:00.022)       0:00:16.821 *****
2025-10-01 10:29:31.413828 | controller | skipping: [instance]
2025-10-01 10:29:31.439449 | controller |
2025-10-01 10:29:31.439482 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-01 10:29:31.439500 | controller | Wednesday 01 October 2025  10:29:31 +0000 (0:00:00.025)       0:00:16.846 *****
2025-10-01 10:29:31.439512 | controller | skipping: [instance]
2025-10-01 10:29:31.457555 | controller |
2025-10-01 10:29:31.457578 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-01 10:29:31.457586 | controller | Wednesday 01 October 2025  10:29:31 +0000 (0:00:00.025)       0:00:16.872 *****
2025-10-01 10:29:31.457617 | controller | skipping: [instance]
2025-10-01 10:29:31.487759 | controller |
2025-10-01 10:29:31.487800 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-01 10:29:31.487808 | controller | Wednesday 01 October 2025  10:29:31 +0000 (0:00:00.018)       0:00:16.890 *****
2025-10-01 10:29:31.487819 | controller | skipping: [instance]
2025-10-01 10:29:31.487844 | controller |
2025-10-01 10:29:31.487852 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-01 10:29:31.487874 | controller | Wednesday 01 October 2025  10:29:31 +0000 (0:00:00.030)       0:00:16.920 *****
2025-10-01 10:29:31.504719 | controller | skipping: [instance]
2025-10-01 10:29:31.504813 | controller |
2025-10-01 10:29:31.505083 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-01 10:29:31.525664 | controller | Wednesday 01 October 2025  10:29:31 +0000 (0:00:00.017)       0:00:16.938 *****
2025-10-01 10:29:31.525694 | controller | skipping: [instance]
2025-10-01 10:29:31.525704 | controller |
2025-10-01 10:29:31.525712 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-01 10:29:31.525721 | controller | Wednesday 01 October 2025  10:29:31 +0000 (0:00:00.020)       0:00:16.958 *****
2025-10-01 10:29:31.554730 | controller | skipping: [instance]
2025-10-01 10:29:31.554774 | controller |
2025-10-01 10:29:31.554919 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-01 10:29:31.555080 | controller | Wednesday 01 October 2025  10:29:31 +0000 (0:00:00.029)       0:00:16.988 *****
2025-10-01 10:29:31.585454 | controller | skipping: [instance]
2025-10-01 10:29:31.585631 | controller |
2025-10-01 10:29:31.585817 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-01 10:29:31.585983 | controller | Wednesday 01 October 2025  10:29:31 +0000 (0:00:00.030)       0:00:17.018 *****
2025-10-01 10:29:31.617310 | controller | skipping: [instance]
2025-10-01 10:29:31.617407 | controller |
2025-10-01 10:29:31.617531 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-01 10:29:31.617675 | controller | Wednesday 01 October 2025  10:29:31 +0000 (0:00:00.031)       0:00:17.050 *****
2025-10-01 10:29:31.648958 | controller | skipping: [instance]
2025-10-01 10:29:31.649047 | controller |
2025-10-01 10:29:31.649171 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-01 10:29:31.649288 | controller | Wednesday 01 October 2025  10:29:31 +0000 (0:00:00.031)       0:00:17.082 *****
2025-10-01 10:30:14.318503 | controller | ok: [instance]
2025-10-01 10:30:15.526861 | controller |
2025-10-01 10:30:15.526897 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-01 10:30:15.526907 | controller | Wednesday 01 October 2025  10:30:14 +0000 (0:00:42.669)       0:00:59.751 *****
2025-10-01 10:30:15.526920 | controller | ok: [instance]
2025-10-01 10:30:15.526948 | controller |
2025-10-01 10:30:15.526958 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-01 10:30:15.527099 | controller | Wednesday 01 October 2025  10:30:15 +0000 (0:00:01.208)       0:01:00.959 *****
2025-10-01 10:30:15.558397 | controller | skipping: [instance]
2025-10-01 10:33:02.741413 | controller |
2025-10-01 10:33:02.741461 | controller | TASK [Ensure CRC is started] ***************************************************
2025-10-01 10:33:02.741470 | controller | Wednesday 01 October 2025  10:30:15 +0000 (0:00:00.031)       0:01:00.991 *****
2025-10-01 10:33:02.741480 | controller | changed: [instance]
2025-10-01 10:33:03.057691 | controller |
2025-10-01 10:33:03.057724 | controller | TASK [Inject crc hostname/IP in hosts] *****************************************
2025-10-01 10:33:03.057732 | controller | Wednesday 01 October 2025  10:33:02 +0000 (0:02:47.182)       0:03:48.173 *****
2025-10-01 10:33:03.057742 | controller | changed: [instance]
2025-10-01 10:33:03.059611 | controller |
2025-10-01 10:33:03.059645 | controller | PLAY RECAP *********************************************************************
2025-10-01 10:33:03.059653 | controller | instance                   : ok=21   changed=11   unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-10-01 10:33:03.059672 | controller |
2025-10-01 10:33:03.059705 | controller | Wednesday 01 October 2025  10:33:03 +0000 (0:00:00.316)       0:03:48.490 *****
2025-10-01 10:33:03.059712 | controller | ===============================================================================
2025-10-01 10:33:03.059717 | controller | Ensure CRC is started ------------------------------------------------- 167.18s
2025-10-01 10:33:03.059723 | controller | test_deps : Install selinux python libs -------------------------------- 42.67s
2025-10-01 10:33:03.059728 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.10s
2025-10-01 10:33:03.059733 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.29s
2025-10-01 10:33:03.059738 | controller | test_deps : Install python yaml libs ------------------------------------ 1.21s
2025-10-01 10:33:03.059744 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-10-01 10:33:03.059749 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.83s
2025-10-01 10:33:03.059754 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.82s
2025-10-01 10:33:03.059759 | controller | repo_setup : Install repo-setup package --------------------------------- 0.72s
2025-10-01 10:33:03.059764 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.69s
2025-10-01 10:33:03.059769 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.55s
2025-10-01 10:33:03.059774 | controller | repo_setup : Ensure directories are present ----------------------------- 0.55s
2025-10-01 10:33:03.059785 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.44s
2025-10-01 10:33:03.059791 | controller | Inject crc hostname/IP in hosts ----------------------------------------- 0.32s
2025-10-01 10:33:03.059796 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.31s
2025-10-01 10:33:03.059801 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s
2025-10-01 10:33:03.059808 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.26s
2025-10-01 10:33:03.059826 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.22s
2025-10-01 10:33:03.059903 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.18s
2025-10-01 10:33:03.060023 | controller | test_deps : Gather variables for each operating system ------------------ 0.07s
2025-10-01 10:33:03.118875 | controller | INFO     Running default > converge
2025-10-01 10:33:03.556042 | controller |
2025-10-01 10:33:04.414561 | controller | PLAY [Converge] ****************************************************************
2025-10-01 10:33:04.414615 | controller |
2025-10-01 10:33:04.414632 | controller | TASK [Gathering Facts] *********************************************************
2025-10-01 10:33:04.414638 | controller | Wednesday 01 October 2025  10:33:03 +0000 (0:00:00.013)       0:00:00.013 *****
2025-10-01 10:33:04.414648 | controller | ok: [instance]
2025-10-01 10:33:04.414678 | controller |
2025-10-01 10:33:04.414685 | controller | TASK [Check if new ssh keypair exists] *****************************************
2025-10-01 10:33:04.414692 | controller | Wednesday 01 October 2025  10:33:04 +0000 (0:00:00.859)       0:00:00.872 *****
2025-10-01 10:33:04.443772 | controller |
2025-10-01 10:33:04.443892 | controller | TASK [recognize_ssh_keypair : Check if id_ed25519 key exists] ******************
2025-10-01 10:33:04.444038 | controller | Wednesday 01 October 2025  10:33:04 +0000 (0:00:00.029)       0:00:00.902 *****
2025-10-01 10:33:04.734883 | controller | ok: [instance]
2025-10-01 10:33:04.734933 | controller |
2025-10-01 10:33:04.735061 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair exists] ******************
2025-10-01 10:33:04.735170 | controller | Wednesday 01 October 2025  10:33:04 +0000 (0:00:00.290)       0:00:01.193 *****
2025-10-01 10:33:04.754778 | controller | ok: [instance]
2025-10-01 10:33:04.754893 | controller |
2025-10-01 10:33:04.755022 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair does not exists] *********
2025-10-01 10:33:04.755155 | controller | Wednesday 01 October 2025  10:33:04 +0000 (0:00:00.020)       0:00:01.213 *****
2025-10-01 10:33:04.776683 | controller | skipping: [instance]
2025-10-01 10:33:04.776736 | controller |
2025-10-01 10:33:04.776844 | controller | TASK [Add the crc host dynamically] ********************************************
2025-10-01 10:33:04.776936 | controller | Wednesday 01 October 2025  10:33:04 +0000 (0:00:00.021)       0:00:01.235 *****
2025-10-01 10:33:04.795810 | controller | changed: [instance]
2025-10-01 10:33:04.795913 | controller |
2025-10-01 10:33:04.796031 | controller | TASK [Run ci_local_storage role] ***********************************************
2025-10-01 10:33:04.796169 | controller | Wednesday 01 October 2025  10:33:04 +0000 (0:00:00.019)       0:00:01.254 *****
2025-10-01 10:33:04.829081 | controller |
2025-10-01 10:33:04.829204 | controller | TASK [ci_local_storage : Create role needed directories] ***********************
2025-10-01 10:33:04.829321 | controller | Wednesday 01 October 2025  10:33:04 +0000 (0:00:00.033)       0:00:01.287 *****
2025-10-01 10:33:05.147453 | controller | changed: [instance]
2025-10-01 10:33:05.147546 | controller |
2025-10-01 10:33:05.147682 | controller | TASK [ci_local_storage : Create the cifmw_cls_namespace namespace"] ************
2025-10-01 10:33:05.147794 | controller | Wednesday 01 October 2025  10:33:05 +0000 (0:00:00.318)       0:00:01.605 *****
2025-10-01 10:33:05.959829 | controller | changed: [instance]
2025-10-01 10:33:05.959894 | controller |
2025-10-01 10:33:05.960008 | controller | TASK [ci_local_storage : Save storage manifests as artifacts] ******************
2025-10-01 10:33:05.960111 | controller | Wednesday 01 October 2025  10:33:05 +0000 (0:00:00.812)       0:00:02.417 *****
2025-10-01 10:33:06.415013 | controller | changed: [instance]
2025-10-01 10:33:06.415102 | controller |
2025-10-01 10:33:06.415214 | controller | TASK [ci_local_storage : Get k8s nodes] ****************************************
2025-10-01 10:33:06.415341 | controller | Wednesday 01 October 2025  10:33:06 +0000 (0:00:00.455)       0:00:02.873 *****
2025-10-01 10:33:07.185970 | controller | ok: [instance]
2025-10-01 10:33:07.186019 | controller |
2025-10-01 10:33:07.186130 | controller | TASK [ci_local_storage : Fetch hostnames for all hosts] ************************
2025-10-01 10:33:07.186228 | controller | Wednesday 01 October 2025  10:33:07 +0000 (0:00:00.770)       0:00:03.644 *****
2025-10-01 10:33:08.584936 | controller | changed: [instance] => (item=instance)
2025-10-01 10:33:08.585021 | controller | changed: [instance -> crc] => (item=crc)
2025-10-01 10:33:08.585119 | controller |
2025-10-01 10:33:08.585227 | controller | TASK [ci_local_storage : Set the hosts k8s ansible hosts] **********************
2025-10-01 10:33:08.585317 | controller | Wednesday 01 October 2025  10:33:08 +0000 (0:00:01.398)       0:00:05.043 *****
2025-10-01 10:33:08.628528 | controller | ok: [instance]
2025-10-01 10:33:08.628647 | controller |
2025-10-01 10:33:08.628781 | controller | TASK [ci_local_storage : Apply the storage class manifests] ********************
2025-10-01 10:33:08.628929 | controller | Wednesday 01 October 2025  10:33:08 +0000 (0:00:00.043)       0:00:05.086 *****
2025-10-01 10:33:09.266451 | controller | changed: [instance]
2025-10-01 10:33:09.266511 | controller |
2025-10-01 10:33:09.266672 | controller | TASK [ci_local_storage : Create directories on worker node] ********************
2025-10-01 10:33:09.266777 | controller | Wednesday 01 October 2025  10:33:09 +0000 (0:00:00.637)       0:00:05.724 *****
2025-10-01 10:33:09.293819 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_local_storage/tasks/worker_node_dirs.yml for instance => (item=crc)
2025-10-01 10:33:09.293935 | controller |
2025-10-01 10:33:09.294066 | controller | TASK [ci_local_storage : Perform action in the PV directory] *******************
2025-10-01 10:33:09.294188 | controller | Wednesday 01 October 2025  10:33:09 +0000 (0:00:00.027)       0:00:05.752 *****
2025-10-01 10:33:16.471038 | controller | changed: [instance -> crc] => (item=1)
2025-10-01 10:33:16.471106 | controller | changed: [instance -> crc] => (item=2)
2025-10-01 10:33:16.471117 | controller | changed: [instance -> crc] => (item=3)
2025-10-01 10:33:16.471171 | controller | changed: [instance -> crc] => (item=4)
2025-10-01 10:33:16.471262 | controller | changed: [instance -> crc] => (item=5)
2025-10-01 10:33:16.471359 | controller | changed: [instance -> crc] => (item=6)
2025-10-01 10:33:16.471465 | controller | changed: [instance -> crc] => (item=7)
2025-10-01 10:33:16.471555 | controller | changed: [instance -> crc] => (item=8)
2025-10-01 10:33:16.471689 | controller | changed: [instance -> crc] => (item=9)
2025-10-01 10:33:16.471781 | controller | changed: [instance -> crc] => (item=10)
2025-10-01 10:33:16.471857 | controller |
2025-10-01 10:33:16.471955 | controller | TASK [ci_local_storage : Generate pv related storage manifest file] ************
2025-10-01 10:33:16.472053 | controller | Wednesday 01 October 2025  10:33:16 +0000 (0:00:07.176)       0:00:12.928 *****
2025-10-01 10:33:16.834711 | controller | changed: [instance]
2025-10-01 10:33:16.834757 | controller |
2025-10-01 10:33:16.834870 | controller | TASK [ci_local_storage : Apply pv related storage manifest file] ***************
2025-10-01 10:33:16.834981 | controller | Wednesday 01 October 2025  10:33:16 +0000 (0:00:00.363)       0:00:13.292 *****
2025-10-01 10:33:17.582733 | controller | changed: [instance]
2025-10-01 10:33:17.582928 | controller |
2025-10-01 10:33:17.582972 | controller | TASK [Not create directories on crc] *******************************************
2025-10-01 10:33:17.582986 | controller | Wednesday 01 October 2025  10:33:17 +0000 (0:00:00.747)       0:00:14.040 *****
2025-10-01 10:33:25.209406 | controller | ok: [instance -> crc] => (item=1)
2025-10-01 10:33:25.235192 | controller | ok: [instance -> crc] => (item=2)
2025-10-01 10:33:25.235290 | controller | ok: [instance -> crc] => (item=3)
2025-10-01 10:33:25.235298 | controller | ok: [instance -> crc] => (item=4)
2025-10-01 10:33:25.235304 | controller | ok: [instance -> crc] => (item=5)
2025-10-01 10:33:25.235309 | controller | ok: [instance -> crc] => (item=6)
2025-10-01 10:33:25.235314 | controller | ok: [instance -> crc] => (item=7)
2025-10-01 10:33:25.235319 | controller | ok: [instance -> crc] => (item=8)
2025-10-01 10:33:25.235324 | controller | ok: [instance -> crc] => (item=9)
2025-10-01 10:33:25.235331 | controller | ok: [instance -> crc] => (item=10)
2025-10-01 10:33:25.235338 | controller |
2025-10-01 10:33:25.235344 | controller | TASK [Fail if we have a change] ************************************************
2025-10-01 10:33:25.235350 | controller | Wednesday 01 October 2025  10:33:25 +0000 (0:00:07.624)       0:00:21.665 *****
2025-10-01 10:33:25.235397 | controller | skipping: [instance]
2025-10-01 10:33:25.974837 | controller |
2025-10-01 10:33:25.974889 | controller | TASK [Get all pvs] *************************************************************
2025-10-01 10:33:25.974897 | controller | Wednesday 01 October 2025  10:33:25 +0000 (0:00:00.027)       0:00:21.693 *****
2025-10-01 10:33:25.974915 | controller | ok: [instance]
2025-10-01 10:33:26.016945 | controller |
2025-10-01 10:33:26.016991 | controller | TASK [Assert that we created the wanted number of pvs] *************************
2025-10-01 10:33:26.017001 | controller | Wednesday 01 October 2025  10:33:25 +0000 (0:00:00.738)       0:00:22.432 *****
2025-10-01 10:33:26.017013 | controller | ok: [instance] => changed=false
2025-10-01 10:33:26.060674 | controller |   msg: All assertions passed
2025-10-01 10:33:26.060716 | controller |
2025-10-01 10:33:26.060726 | controller | TASK [Gather names from pvs] ***************************************************
2025-10-01 10:33:26.060737 | controller | Wednesday 01 October 2025  10:33:26 +0000 (0:00:00.041)       0:00:22.474 *****
2025-10-01 10:33:26.060753 | controller | ok: [instance]
2025-10-01 10:33:26.060920 | controller |
2025-10-01 10:33:26.060941 | controller | TASK [Gather computed PV names] ************************************************
2025-10-01 10:33:26.162347 | controller | Wednesday 01 October 2025  10:33:26 +0000 (0:00:00.044)       0:00:22.518 *****
2025-10-01 10:33:26.162389 | controller | ok: [instance] => (item=1)
2025-10-01 10:33:26.243747 | controller | ok: [instance] => (item=2)
2025-10-01 10:33:26.243794 | controller | ok: [instance] => (item=3)
2025-10-01 10:33:26.243805 | controller | ok: [instance] => (item=4)
2025-10-01 10:33:26.243818 | controller | ok: [instance] => (item=5)
2025-10-01 10:33:26.243824 | controller | ok: [instance] => (item=6)
2025-10-01 10:33:26.243831 | controller | ok: [instance] => (item=7)
2025-10-01 10:33:26.243837 | controller | ok: [instance] => (item=8)
2025-10-01 10:33:26.243848 | controller | ok: [instance] => (item=9)
2025-10-01 10:33:26.243855 | controller | ok: [instance] => (item=10)
2025-10-01 10:33:26.243862 | controller |
2025-10-01 10:33:26.243873 | controller | TASK [Assert that the pvs names are correct] ***********************************
2025-10-01 10:33:26.243881 | controller | Wednesday 01 October 2025  10:33:26 +0000 (0:00:00.100)       0:00:22.618 *****
2025-10-01 10:33:26.243896 | controller | ok: [instance] => (item=['molecule-storage01-crc', 'molecule-storage01-crc']) => changed=false
2025-10-01 10:33:26.248656 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.248680 | controller |   item:
2025-10-01 10:33:26.248689 | controller |   - molecule-storage01-crc
2025-10-01 10:33:26.248700 | controller |   - molecule-storage01-crc
2025-10-01 10:33:26.248708 | controller |   msg: All assertions passed
2025-10-01 10:33:26.248714 | controller | ok: [instance] => (item=['molecule-storage02-crc', 'molecule-storage02-crc']) => changed=false
2025-10-01 10:33:26.248721 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.248732 | controller |   item:
2025-10-01 10:33:26.248894 | controller |   - molecule-storage02-crc
2025-10-01 10:33:26.248917 | controller |   - molecule-storage02-crc
2025-10-01 10:33:26.248924 | controller |   msg: All assertions passed
2025-10-01 10:33:26.248931 | controller | ok: [instance] => (item=['molecule-storage03-crc', 'molecule-storage03-crc']) => changed=false
2025-10-01 10:33:26.248937 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.248949 | controller |   item:
2025-10-01 10:33:26.248960 | controller |   - molecule-storage03-crc
2025-10-01 10:33:26.248966 | controller |   - molecule-storage03-crc
2025-10-01 10:33:26.248977 | controller |   msg: All assertions passed
2025-10-01 10:33:26.248984 | controller | ok: [instance] => (item=['molecule-storage04-crc', 'molecule-storage04-crc']) => changed=false
2025-10-01 10:33:26.248991 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.248997 | controller |   item:
2025-10-01 10:33:26.249003 | controller |   - molecule-storage04-crc
2025-10-01 10:33:26.249010 | controller |   - molecule-storage04-crc
2025-10-01 10:33:26.249020 | controller |   msg: All assertions passed
2025-10-01 10:33:26.249028 | controller | ok: [instance] => (item=['molecule-storage05-crc', 'molecule-storage05-crc']) => changed=false
2025-10-01 10:33:26.249034 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.249045 | controller |   item:
2025-10-01 10:33:26.249052 | controller |   - molecule-storage05-crc
2025-10-01 10:33:26.249058 | controller |   - molecule-storage05-crc
2025-10-01 10:33:26.249065 | controller |   msg: All assertions passed
2025-10-01 10:33:26.249071 | controller | ok: [instance] => (item=['molecule-storage06-crc', 'molecule-storage06-crc']) => changed=false
2025-10-01 10:33:26.249078 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.249084 | controller |   item:
2025-10-01 10:33:26.249095 | controller |   - molecule-storage06-crc
2025-10-01 10:33:26.249102 | controller |   - molecule-storage06-crc
2025-10-01 10:33:26.249112 | controller |   msg: All assertions passed
2025-10-01 10:33:26.249120 | controller | ok: [instance] => (item=['molecule-storage07-crc', 'molecule-storage07-crc']) => changed=false
2025-10-01 10:33:26.249126 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.249132 | controller |   item:
2025-10-01 10:33:26.249139 | controller |   - molecule-storage07-crc
2025-10-01 10:33:26.249145 | controller |   - molecule-storage07-crc
2025-10-01 10:33:26.249151 | controller |   msg: All assertions passed
2025-10-01 10:33:26.249164 | controller | ok: [instance] => (item=['molecule-storage08-crc', 'molecule-storage08-crc']) => changed=false
2025-10-01 10:33:26.249171 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.249178 | controller |   item:
2025-10-01 10:33:26.249189 | controller |   - molecule-storage08-crc
2025-10-01 10:33:26.249195 | controller |   - molecule-storage08-crc
2025-10-01 10:33:26.249202 | controller |   msg: All assertions passed
2025-10-01 10:33:26.249212 | controller | ok: [instance] => (item=['molecule-storage09-crc', 'molecule-storage09-crc']) => changed=false
2025-10-01 10:33:26.249334 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.249344 | controller |   item:
2025-10-01 10:33:26.249353 | controller |   - molecule-storage09-crc
2025-10-01 10:33:26.249494 | controller |   - molecule-storage09-crc
2025-10-01 10:33:26.249687 | controller |   msg: All assertions passed
2025-10-01 10:33:26.249868 | controller | ok: [instance] => (item=['molecule-storage10-crc', 'molecule-storage10-crc']) => changed=false
2025-10-01 10:33:26.250011 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.250150 | controller |   item:
2025-10-01 10:33:26.250290 | controller |   - molecule-storage10-crc
2025-10-01 10:33:26.250460 | controller |   - molecule-storage10-crc
2025-10-01 10:33:26.250627 | controller |   msg: All assertions passed
2025-10-01 10:33:26.250777 | controller |
2025-10-01 10:33:26.250929 | controller | TASK [Gather mountpoints from pvs] *********************************************
2025-10-01 10:33:26.251084 | controller | Wednesday 01 October 2025  10:33:26 +0000 (0:00:00.082)       0:00:22.700 *****
2025-10-01 10:33:26.295373 | controller | ok: [instance]
2025-10-01 10:33:26.395330 | controller |
2025-10-01 10:33:26.395381 | controller | TASK [Gather computed mountpoints] *********************************************
2025-10-01 10:33:26.395398 | controller | Wednesday 01 October 2025  10:33:26 +0000 (0:00:00.052)       0:00:22.753 *****
2025-10-01 10:33:26.395412 | controller | ok: [instance] => (item=1)
2025-10-01 10:33:26.492686 | controller | ok: [instance] => (item=2)
2025-10-01 10:33:26.492730 | controller | ok: [instance] => (item=3)
2025-10-01 10:33:26.492743 | controller | ok: [instance] => (item=4)
2025-10-01 10:33:26.492753 | controller | ok: [instance] => (item=5)
2025-10-01 10:33:26.492758 | controller | ok: [instance] => (item=6)
2025-10-01 10:33:26.492764 | controller | ok: [instance] => (item=7)
2025-10-01 10:33:26.492772 | controller | ok: [instance] => (item=8)
2025-10-01 10:33:26.492782 | controller | ok: [instance] => (item=9)
2025-10-01 10:33:26.492787 | controller | ok: [instance] => (item=10)
2025-10-01 10:33:26.492793 | controller |
2025-10-01 10:33:26.492802 | controller | TASK [Assert that the mountpoints in the pv are correct] ***********************
2025-10-01 10:33:26.492809 | controller | Wednesday 01 October 2025  10:33:26 +0000 (0:00:00.098)       0:00:22.852 *****
2025-10-01 10:33:26.492824 | controller | ok: [instance] => (item=['/mnt/openstack/pv01', '/mnt/openstack/pv01']) => changed=false
2025-10-01 10:33:26.497131 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.497155 | controller |   item:
2025-10-01 10:33:26.497167 | controller |   - /mnt/openstack/pv01
2025-10-01 10:33:26.497173 | controller |   - /mnt/openstack/pv01
2025-10-01 10:33:26.497178 | controller |   msg: All assertions passed
2025-10-01 10:33:26.497188 | controller | ok: [instance] => (item=['/mnt/openstack/pv02', '/mnt/openstack/pv02']) => changed=false
2025-10-01 10:33:26.497193 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.497198 | controller |   item:
2025-10-01 10:33:26.497210 | controller |   - /mnt/openstack/pv02
2025-10-01 10:33:26.497215 | controller |   - /mnt/openstack/pv02
2025-10-01 10:33:26.497220 | controller |   msg: All assertions passed
2025-10-01 10:33:26.497226 | controller | ok: [instance] => (item=['/mnt/openstack/pv03', '/mnt/openstack/pv03']) => changed=false
2025-10-01 10:33:26.497234 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.497240 | controller |   item:
2025-10-01 10:33:26.497245 | controller |   - /mnt/openstack/pv03
2025-10-01 10:33:26.497250 | controller |   - /mnt/openstack/pv03
2025-10-01 10:33:26.497260 | controller |   msg: All assertions passed
2025-10-01 10:33:26.497265 | controller | ok: [instance] => (item=['/mnt/openstack/pv04', '/mnt/openstack/pv04']) => changed=false
2025-10-01 10:33:26.497271 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.497288 | controller |   item:
2025-10-01 10:33:26.497294 | controller |   - /mnt/openstack/pv04
2025-10-01 10:33:26.497299 | controller |   - /mnt/openstack/pv04
2025-10-01 10:33:26.497308 | controller |   msg: All assertions passed
2025-10-01 10:33:26.497314 | controller | ok: [instance] => (item=['/mnt/openstack/pv05', '/mnt/openstack/pv05']) => changed=false
2025-10-01 10:33:26.497319 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.497327 | controller |   item:
2025-10-01 10:33:26.497334 | controller |   - /mnt/openstack/pv05
2025-10-01 10:33:26.497339 | controller |   - /mnt/openstack/pv05
2025-10-01 10:33:26.497344 | controller |   msg: All assertions passed
2025-10-01 10:33:26.497365 | controller | ok: [instance] => (item=['/mnt/openstack/pv06', '/mnt/openstack/pv06']) => changed=false
2025-10-01 10:33:26.585668 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.585710 | controller |   item:
2025-10-01 10:33:26.585724 | controller |   - /mnt/openstack/pv06
2025-10-01 10:33:26.585731 | controller |   - /mnt/openstack/pv06
2025-10-01 10:33:26.585736 | controller |   msg: All assertions passed
2025-10-01 10:33:26.585746 | controller | ok: [instance] => (item=['/mnt/openstack/pv07', '/mnt/openstack/pv07']) => changed=false
2025-10-01 10:33:26.585752 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.585757 | controller |   item:
2025-10-01 10:33:26.585762 | controller |   - /mnt/openstack/pv07
2025-10-01 10:33:26.585767 | controller |   - /mnt/openstack/pv07
2025-10-01 10:33:26.585773 | controller |   msg: All assertions passed
2025-10-01 10:33:26.585778 | controller | ok: [instance] => (item=['/mnt/openstack/pv08', '/mnt/openstack/pv08']) => changed=false
2025-10-01 10:33:26.585787 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.585793 | controller |   item:
2025-10-01 10:33:26.585798 | controller |   - /mnt/openstack/pv08
2025-10-01 10:33:26.585807 | controller |   - /mnt/openstack/pv08
2025-10-01 10:33:26.585813 | controller |   msg: All assertions passed
2025-10-01 10:33:26.585818 | controller | ok: [instance] => (item=['/mnt/openstack/pv09', '/mnt/openstack/pv09']) => changed=false
2025-10-01 10:33:26.585829 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.585834 | controller |   item:
2025-10-01 10:33:26.585840 | controller |   - /mnt/openstack/pv09
2025-10-01 10:33:26.585849 | controller |   - /mnt/openstack/pv09
2025-10-01 10:33:26.585855 | controller |   msg: All assertions passed
2025-10-01 10:33:26.585866 | controller | ok: [instance] => (item=['/mnt/openstack/pv10', '/mnt/openstack/pv10']) => changed=false
2025-10-01 10:33:26.585876 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.585882 | controller |   item:
2025-10-01 10:33:26.585887 | controller |   - /mnt/openstack/pv10
2025-10-01 10:33:26.585892 | controller |   - /mnt/openstack/pv10
2025-10-01 10:33:26.585897 | controller |   msg: All assertions passed
2025-10-01 10:33:26.585902 | controller |
2025-10-01 10:33:26.585908 | controller | TASK [Assert that the storage class is correct] ********************************
2025-10-01 10:33:26.585917 | controller | Wednesday 01 October 2025  10:33:26 +0000 (0:00:00.095)       0:00:22.947 *****
2025-10-01 10:33:26.585930 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-01 10:33:26.585940 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.585946 | controller |   item: molecule-storage
2025-10-01 10:33:26.585951 | controller |   msg: All assertions passed
2025-10-01 10:33:26.585956 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-01 10:33:26.585961 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.585966 | controller |   item: molecule-storage
2025-10-01 10:33:26.585971 | controller |   msg: All assertions passed
2025-10-01 10:33:26.585980 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-01 10:33:26.585986 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.585991 | controller |   item: molecule-storage
2025-10-01 10:33:26.586000 | controller |   msg: All assertions passed
2025-10-01 10:33:26.586006 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-01 10:33:26.586011 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.586016 | controller |   item: molecule-storage
2025-10-01 10:33:26.586021 | controller |   msg: All assertions passed
2025-10-01 10:33:26.586026 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-01 10:33:26.586032 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.586037 | controller |   item: molecule-storage
2025-10-01 10:33:26.586046 | controller |   msg: All assertions passed
2025-10-01 10:33:26.586051 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-01 10:33:26.586057 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.586065 | controller |   item: molecule-storage
2025-10-01 10:33:26.586071 | controller |   msg: All assertions passed
2025-10-01 10:33:26.586076 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-01 10:33:26.586081 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.586087 | controller |   item: molecule-storage
2025-10-01 10:33:26.586092 | controller |   msg: All assertions passed
2025-10-01 10:33:26.586097 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-01 10:33:26.586102 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.586111 | controller |   item: molecule-storage
2025-10-01 10:33:26.586117 | controller |   msg: All assertions passed
2025-10-01 10:33:26.586122 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-01 10:33:26.586131 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.586139 | controller |   item: molecule-storage
2025-10-01 10:33:26.586262 | controller |   msg: All assertions passed
2025-10-01 10:33:26.586274 | controller | ok: [instance] => (item=molecule-storage) => changed=false
2025-10-01 10:33:26.586417 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.586584 | controller |   item: molecule-storage
2025-10-01 10:33:26.586758 | controller |   msg: All assertions passed
2025-10-01 10:33:26.586913 | controller |
2025-10-01 10:33:26.587077 | controller | TASK [Assert that the storage capacity is correct] *****************************
2025-10-01 10:33:26.587231 | controller | Wednesday 01 October 2025  10:33:26 +0000 (0:00:00.092)       0:00:23.039 *****
2025-10-01 10:33:26.665623 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-01 10:33:26.665675 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.665686 | controller |   item: 100Mi
2025-10-01 10:33:26.669584 | controller |   msg: All assertions passed
2025-10-01 10:33:26.705206 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-01 10:33:26.705243 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.705252 | controller |   item: 100Mi
2025-10-01 10:33:26.705264 | controller |   msg: All assertions passed
2025-10-01 10:33:26.705270 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-01 10:33:26.705275 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.705280 | controller |   item: 100Mi
2025-10-01 10:33:26.705286 | controller |   msg: All assertions passed
2025-10-01 10:33:26.705297 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-01 10:33:26.705303 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.705308 | controller |   item: 100Mi
2025-10-01 10:33:26.705318 | controller |   msg: All assertions passed
2025-10-01 10:33:26.705324 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-01 10:33:26.705329 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.705338 | controller |   item: 100Mi
2025-10-01 10:33:26.705344 | controller |   msg: All assertions passed
2025-10-01 10:33:26.705350 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-01 10:33:26.705355 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.705360 | controller |   item: 100Mi
2025-10-01 10:33:26.705365 | controller |   msg: All assertions passed
2025-10-01 10:33:26.705371 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-01 10:33:26.705376 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.705381 | controller |   item: 100Mi
2025-10-01 10:33:26.705386 | controller |   msg: All assertions passed
2025-10-01 10:33:26.705391 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-01 10:33:26.705396 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.705401 | controller |   item: 100Mi
2025-10-01 10:33:26.705407 | controller |   msg: All assertions passed
2025-10-01 10:33:26.705412 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-01 10:33:26.705431 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.705437 | controller |   item: 100Mi
2025-10-01 10:33:26.705446 | controller |   msg: All assertions passed
2025-10-01 10:33:26.705452 | controller | ok: [instance] => (item=100Mi) => changed=false
2025-10-01 10:33:26.705457 | controller |   ansible_loop_var: item
2025-10-01 10:33:26.705463 | controller |   item: 100Mi
2025-10-01 10:33:26.705468 | controller |   msg: All assertions passed
2025-10-01 10:33:26.705473 | controller |
2025-10-01 10:33:26.705478 | controller | TASK [Delete the created pvs] **************************************************
2025-10-01 10:33:26.705484 | controller | Wednesday 01 October 2025  10:33:26 +0000 (0:00:00.084)       0:00:23.124 *****
2025-10-01 10:33:26.705494 | controller |
2025-10-01 10:33:27.459143 | controller | TASK [ci_local_storage : Get all pvs] ******************************************
2025-10-01 10:33:27.459183 | controller | Wednesday 01 October 2025  10:33:26 +0000 (0:00:00.039)       0:00:23.163 *****
2025-10-01 10:33:27.459200 | controller | ok: [instance]
2025-10-01 10:33:27.498790 | controller |
2025-10-01 10:33:27.498832 | controller | TASK [ci_local_storage : Export PVs as facts] **********************************
2025-10-01 10:33:27.498841 | controller | Wednesday 01 October 2025  10:33:27 +0000 (0:00:00.753)       0:00:23.916 *****
2025-10-01 10:33:27.498851 | controller | ok: [instance]
2025-10-01 10:33:34.140388 | controller |
2025-10-01 10:33:34.140445 | controller | TASK [ci_local_storage : Delete all pvs] ***************************************
2025-10-01 10:33:34.140459 | controller | Wednesday 01 October 2025  10:33:27 +0000 (0:00:00.039)       0:00:23.956 *****
2025-10-01 10:33:34.140471 | controller | changed: [instance] => (item=molecule-storage01-crc)
2025-10-01 10:33:34.829795 | controller | changed: [instance] => (item=molecule-storage02-crc)
2025-10-01 10:33:34.829839 | controller | changed: [instance] => (item=molecule-storage03-crc)
2025-10-01 10:33:34.829852 | controller | changed: [instance] => (item=molecule-storage04-crc)
2025-10-01 10:33:34.829858 | controller | changed: [instance] => (item=molecule-storage05-crc)
2025-10-01 10:33:34.829863 | controller | changed: [instance] => (item=molecule-storage06-crc)
2025-10-01 10:33:34.829874 | controller | changed: [instance] => (item=molecule-storage07-crc)
2025-10-01 10:33:34.829880 | controller | changed: [instance] => (item=molecule-storage08-crc)
2025-10-01 10:33:34.829886 | controller | changed: [instance] => (item=molecule-storage09-crc)
2025-10-01 10:33:34.829891 | controller | changed: [instance] => (item=molecule-storage10-crc)
2025-10-01 10:33:34.829896 | controller |
2025-10-01 10:33:34.829902 | controller | TASK [ci_local_storage : Get k8s nodes] ****************************************
2025-10-01 10:33:34.829907 | controller | Wednesday 01 October 2025  10:33:34 +0000 (0:00:06.639)       0:00:30.596 *****
2025-10-01 10:33:34.829925 | controller | ok: [instance]
2025-10-01 10:33:36.056070 | controller |
2025-10-01 10:33:36.056101 | controller | TASK [ci_local_storage : Fetch hostnames for all hosts] ************************
2025-10-01 10:33:36.056109 | controller | Wednesday 01 October 2025  10:33:34 +0000 (0:00:00.691)       0:00:31.287 *****
2025-10-01 10:33:36.056119 | controller | changed: [instance] => (item=instance)
2025-10-01 10:33:36.056494 | controller | changed: [instance -> crc] => (item=crc)
2025-10-01 10:33:36.103796 | controller |
2025-10-01 10:33:36.103822 | controller | TASK [ci_local_storage : Set the hosts k8s ansible hosts] **********************
2025-10-01 10:33:36.103835 | controller | Wednesday 01 October 2025  10:33:36 +0000 (0:00:01.226)       0:00:32.514 *****
2025-10-01 10:33:36.103843 | controller | ok: [instance]
2025-10-01 10:33:36.132083 | controller |
2025-10-01 10:33:36.132108 | controller | TASK [ci_local_storage : Delete directories on worker node] ********************
2025-10-01 10:33:36.132116 | controller | Wednesday 01 October 2025  10:33:36 +0000 (0:00:00.047)       0:00:32.562 *****
2025-10-01 10:33:36.132125 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_local_storage/tasks/worker_node_dirs.yml for instance => (item=crc)
2025-10-01 10:33:48.070177 | controller |
2025-10-01 10:33:48.070285 | controller | TASK [ci_local_storage : Perform action in the PV directory] *******************
2025-10-01 10:33:48.070296 | controller | Wednesday 01 October 2025  10:33:36 +0000 (0:00:00.028)       0:00:32.590 *****
2025-10-01 10:33:48.070312 | controller | changed: [instance -> crc] => (item=1)
2025-10-01 10:35:08.088890 | controller | changed: [instance -> crc] => (item=2)
2025-10-01 10:35:08.088931 | controller | changed: [instance -> crc] => (item=3)
2025-10-01 10:35:08.088939 | controller | changed: [instance -> crc] => (item=4)
2025-10-01 10:35:08.088945 | controller | changed: [instance -> crc] => (item=5)
2025-10-01 10:35:08.088950 | controller | changed: [instance -> crc] => (item=6)
2025-10-01 10:35:08.088956 | controller | changed: [instance -> crc] => (item=7)
2025-10-01 10:35:08.088961 | controller | changed: [instance -> crc] => (item=8)
2025-10-01 10:35:08.088966 | controller | changed: [instance -> crc] => (item=9)
2025-10-01 10:35:08.088971 | controller | changed: [instance -> crc] => (item=10)
2025-10-01 10:35:08.088977 | controller |
2025-10-01 10:35:08.088983 | controller | TASK [ci_local_storage : Remove the cifmw_cls_namespace namespace] *************
2025-10-01 10:35:08.088988 | controller | Wednesday 01 October 2025  10:33:48 +0000 (0:00:11.932)       0:00:44.523 *****
2025-10-01 10:35:08.088998 | controller | changed: [instance]
2025-10-01 10:35:08.928162 | controller |
2025-10-01 10:35:08.928209 | controller | TASK [Get all namespaces] ******************************************************
2025-10-01 10:35:08.928220 | controller | Wednesday 01 October 2025  10:35:08 +0000 (0:01:20.023)       0:02:04.546 *****
2025-10-01 10:35:08.928242 | controller | ok: [instance]
2025-10-01 10:35:08.986504 | controller |
2025-10-01 10:35:08.986537 | controller | TASK [Assert that the cifmw_cls_namespace ns is deleted] ***********************
2025-10-01 10:35:08.986545 | controller | Wednesday 01 October 2025  10:35:08 +0000 (0:00:00.839)       0:02:05.385 *****
2025-10-01 10:35:08.986554 | controller | ok: [instance] => changed=false
2025-10-01 10:35:09.055972 | controller |   msg: All assertions passed
2025-10-01 10:35:09.056003 | controller |
2025-10-01 10:35:09.056011 | controller | PLAY RECAP *********************************************************************
2025-10-01 10:35:09.056018 | controller | instance                   : ok=37   changed=13   unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-10-01 10:35:09.056024 | controller |
2025-10-01 10:35:09.056029 | controller | Wednesday 01 October 2025  10:35:08 +0000 (0:00:00.056)       0:02:05.442 *****
2025-10-01 10:35:09.056034 | controller | ===============================================================================
2025-10-01 10:35:09.056039 | controller | ci_local_storage : Remove the cifmw_cls_namespace namespace ------------ 80.02s
2025-10-01 10:35:09.056044 | controller | ci_local_storage : Perform action in the PV directory ------------------ 11.93s
2025-10-01 10:35:09.056050 | controller | Not create directories on crc ------------------------------------------- 7.62s
2025-10-01 10:35:09.056055 | controller | ci_local_storage : Perform action in the PV directory ------------------- 7.18s
2025-10-01 10:35:09.056060 | controller | ci_local_storage : Delete all pvs --------------------------------------- 6.64s
2025-10-01 10:35:09.056071 | controller | ci_local_storage : Fetch hostnames for all hosts ------------------------ 1.40s
2025-10-01 10:35:09.056076 | controller | ci_local_storage : Fetch hostnames for all hosts ------------------------ 1.23s
2025-10-01 10:35:09.056082 | controller | Gathering Facts --------------------------------------------------------- 0.86s
2025-10-01 10:35:09.056087 | controller | Get all namespaces ------------------------------------------------------ 0.84s
2025-10-01 10:35:09.056092 | controller | ci_local_storage : Create the cifmw_cls_namespace namespace" ------------ 0.81s
2025-10-01 10:35:09.056097 | controller | ci_local_storage : Get k8s nodes ---------------------------------------- 0.77s
2025-10-01 10:35:09.056102 | controller | ci_local_storage : Get all pvs ------------------------------------------ 0.75s
2025-10-01 10:35:09.056107 | controller | ci_local_storage : Apply pv related storage manifest file --------------- 0.75s
2025-10-01 10:35:09.056112 | controller | Get all pvs ------------------------------------------------------------- 0.74s
2025-10-01 10:35:09.056117 | controller | ci_local_storage : Get k8s nodes ---------------------------------------- 0.69s
2025-10-01 10:35:09.056123 | controller | ci_local_storage : Apply the storage class manifests -------------------- 0.64s
2025-10-01 10:35:09.056128 | controller | ci_local_storage : Save storage manifests as artifacts ------------------ 0.46s
2025-10-01 10:35:09.056133 | controller | ci_local_storage : Generate pv related storage manifest file ------------ 0.36s
2025-10-01 10:35:09.056138 | controller | ci_local_storage : Create role needed directories ----------------------- 0.32s
2025-10-01 10:35:09.056143 | controller | recognize_ssh_keypair : Check if id_ed25519 key exists ------------------ 0.29s
2025-10-01 10:35:09.056153 | controller | INFO     Running default > cleanup
2025-10-01 10:35:09.056667 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-01 10:35:09.057256 | controller | INFO     Writing /tmp/report.html report.
2025-10-01 10:35:09.545193 | controller | changed
2025-10-01 10:35:09.569232 | 
2025-10-01 10:35:09.569324 | PLAY RECAP
2025-10-01 10:35:09.569410 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-01 10:35:09.569442 | 
2025-10-01 10:35:09.708941 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-01 10:35:09.709732 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-01 10:35:10.267435 | 
2025-10-01 10:35:10.267576 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-01 10:35:10.287879 | 
2025-10-01 10:35:10.287950 | TASK [Filter out host if needed]
2025-10-01 10:35:10.296431 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-01 10:35:10.300942 | 
2025-10-01 10:35:10.301010 | TASK [Ensure file is present]
2025-10-01 10:35:10.694020 | controller | ok
2025-10-01 10:35:10.700775 | 
2025-10-01 10:35:10.700859 | TASK [Manage molecule report file]
2025-10-01 10:35:11.226447 | controller | changed
2025-10-01 10:35:11.232013 | 
2025-10-01 10:35:11.232084 | TASK [Check if we get ci-framework-data basedir]
2025-10-01 10:35:11.494752 | controller | ok
2025-10-01 10:35:11.500435 | 
2025-10-01 10:35:11.500530 | TASK [Create ci-framework-data log directory for zuul]
2025-10-01 10:35:11.914041 | controller | changed
2025-10-01 10:35:11.920184 | 
2025-10-01 10:35:11.920264 | TASK [Copy ci-framework interesting files]
2025-10-01 10:35:12.207070 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-01 10:35:12.446775 | controller | changed
2025-10-01 10:35:12.452993 | 
2025-10-01 10:35:12.453114 | TASK [Get SELinux listing]
2025-10-01 10:35:12.980679 | controller | changed
2025-10-01 10:35:12.987126 | 
2025-10-01 10:35:12.987224 | TASK [Generate log index]
2025-10-01 10:35:14.625788 | controller | changed
2025-10-01 10:35:14.632644 | 
2025-10-01 10:35:14.632726 | TASK [Get some env related data]
2025-10-01 10:35:15.458059 | controller | /home/zuul/.local/bin/ansible
2025-10-01 10:35:16.669837 | controller | changed
2025-10-01 10:35:16.694795 | 
2025-10-01 10:35:16.694893 | TASK [Generate list of logs to collect in home directory]
2025-10-01 10:35:17.139575 | controller | ok: All paths examined
2025-10-01 10:35:17.145287 | 
2025-10-01 10:35:17.145456 | LOOP [Copy logs from home directory]
2025-10-01 10:35:17.973211 | controller | changed:
2025-10-01 10:35:17.973464 | controller | {
2025-10-01 10:35:17.973499 | controller |   "atime": 1743544925.4788878,
2025-10-01 10:35:17.973520 | controller |   "ctime": 1743545329.1409318,
2025-10-01 10:35:17.973538 | controller |   "dev": 64513,
2025-10-01 10:35:17.973555 | controller |   "gid": 1000,
2025-10-01 10:35:17.973570 | controller |   "gr_name": "zuul",
2025-10-01 10:35:17.973587 | controller |   "inode": 4518807,
2025-10-01 10:35:17.973603 | controller |   "isblk": false,
2025-10-01 10:35:17.973618 | controller |   "ischr": false,
2025-10-01 10:35:17.973632 | controller |   "isdir": false,
2025-10-01 10:35:17.973646 | controller |   "isfifo": false,
2025-10-01 10:35:17.973660 | controller |   "isgid": false,
2025-10-01 10:35:17.973674 | controller |   "islnk": false,
2025-10-01 10:35:17.973688 | controller |   "isreg": true,
2025-10-01 10:35:17.973701 | controller |   "issock": false,
2025-10-01 10:35:17.973715 | controller |   "isuid": false,
2025-10-01 10:35:17.973729 | controller |   "mode": "0644",
2025-10-01 10:35:17.973742 | controller |   "mtime": 1743545329.1409318,
2025-10-01 10:35:17.973756 | controller |   "nlink": 1,
2025-10-01 10:35:17.973771 | controller |   "path": "/home/zuul/crc-setup.log",
2025-10-01 10:35:17.973786 | controller |   "pw_name": "zuul",
2025-10-01 10:35:17.973801 | controller |   "rgrp": true,
2025-10-01 10:35:17.973816 | controller |   "roth": true,
2025-10-01 10:35:17.973830 | controller |   "rusr": true,
2025-10-01 10:35:17.973843 | controller |   "size": 4108,
2025-10-01 10:35:17.973857 | controller |   "uid": 1000,
2025-10-01 10:35:17.973871 | controller |   "wgrp": false,
2025-10-01 10:35:17.973884 | controller |   "woth": false,
2025-10-01 10:35:17.973901 | controller |   "wusr": true,
2025-10-01 10:35:17.973916 | controller |   "xgrp": false,
2025-10-01 10:35:17.973931 | controller |   "xoth": false,
2025-10-01 10:35:17.973946 | controller |   "xusr": false
2025-10-01 10:35:17.973960 | controller | }
2025-10-01 10:35:18.777958 | controller | changed:
2025-10-01 10:35:18.778063 | controller | {
2025-10-01 10:35:18.778090 | controller |   "atime": 1743545331.0429637,
2025-10-01 10:35:18.778111 | controller |   "ctime": 1743545842.0111232,
2025-10-01 10:35:18.778130 | controller |   "dev": 64513,
2025-10-01 10:35:18.778147 | controller |   "gid": 1000,
2025-10-01 10:35:18.778162 | controller |   "gr_name": "zuul",
2025-10-01 10:35:18.778178 | controller |   "inode": 4194437,
2025-10-01 10:35:18.778193 | controller |   "isblk": false,
2025-10-01 10:35:18.778209 | controller |   "ischr": false,
2025-10-01 10:35:18.778224 | controller |   "isdir": false,
2025-10-01 10:35:18.778239 | controller |   "isfifo": false,
2025-10-01 10:35:18.778253 | controller |   "isgid": false,
2025-10-01 10:35:18.778266 | controller |   "islnk": false,
2025-10-01 10:35:18.778279 | controller |   "isreg": true,
2025-10-01 10:35:18.778293 | controller |   "issock": false,
2025-10-01 10:35:18.778306 | controller |   "isuid": false,
2025-10-01 10:35:18.778320 | controller |   "mode": "0644",
2025-10-01 10:35:18.778333 | controller |   "mtime": 1743545842.0111232,
2025-10-01 10:35:18.778347 | controller |   "nlink": 1,
2025-10-01 10:35:18.778362 | controller |   "path": "/home/zuul/crc-start.log",
2025-10-01 10:35:18.778423 | controller |   "pw_name": "zuul",
2025-10-01 10:35:18.778444 | controller |   "rgrp": true,
2025-10-01 10:35:18.778461 | controller |   "roth": true,
2025-10-01 10:35:18.778477 | controller |   "rusr": true,
2025-10-01 10:35:18.778499 | controller |   "size": 4023,
2025-10-01 10:35:18.778516 | controller |   "uid": 1000,
2025-10-01 10:35:18.778531 | controller |   "wgrp": false,
2025-10-01 10:35:18.778545 | controller |   "woth": false,
2025-10-01 10:35:18.778559 | controller |   "wusr": true,
2025-10-01 10:35:18.778573 | controller |   "xgrp": false,
2025-10-01 10:35:18.778587 | controller |   "xoth": false,
2025-10-01 10:35:18.778601 | controller |   "xusr": false
2025-10-01 10:35:18.778615 | controller | }
2025-10-01 10:35:19.514103 | controller | changed:
2025-10-01 10:35:19.514198 | controller | {
2025-10-01 10:35:19.514233 | controller |   "atime": 1759314516.996948,
2025-10-01 10:35:19.514260 | controller |   "ctime": 1759314533.35793,
2025-10-01 10:35:19.514283 | controller |   "dev": 64513,
2025-10-01 10:35:19.514305 | controller |   "gid": 1000,
2025-10-01 10:35:19.514326 | controller |   "gr_name": "zuul",
2025-10-01 10:35:19.514347 | controller |   "inode": 4685785,
2025-10-01 10:35:19.514367 | controller |   "isblk": false,
2025-10-01 10:35:19.514463 | controller |   "ischr": false,
2025-10-01 10:35:19.514491 | controller |   "isdir": false,
2025-10-01 10:35:19.514515 | controller |   "isfifo": false,
2025-10-01 10:35:19.514536 | controller |   "isgid": false,
2025-10-01 10:35:19.514557 | controller |   "islnk": false,
2025-10-01 10:35:19.514579 | controller |   "isreg": true,
2025-10-01 10:35:19.514599 | controller |   "issock": false,
2025-10-01 10:35:19.514620 | controller |   "isuid": false,
2025-10-01 10:35:19.514640 | controller |   "mode": "0644",
2025-10-01 10:35:19.514659 | controller |   "mtime": 1759314533.35793,
2025-10-01 10:35:19.514680 | controller |   "nlink": 1,
2025-10-01 10:35:19.514700 | controller |   "path": "/home/zuul/ansible.log",
2025-10-01 10:35:19.514721 | controller |   "pw_name": "zuul",
2025-10-01 10:35:19.514742 | controller |   "rgrp": true,
2025-10-01 10:35:19.514762 | controller |   "roth": true,
2025-10-01 10:35:19.514786 | controller |   "rusr": true,
2025-10-01 10:35:19.514807 | controller |   "size": 6749,
2025-10-01 10:35:19.514828 | controller |   "uid": 1000,
2025-10-01 10:35:19.514848 | controller |   "wgrp": false,
2025-10-01 10:35:19.514868 | controller |   "woth": false,
2025-10-01 10:35:19.514883 | controller |   "wusr": true,
2025-10-01 10:35:19.514898 | controller |   "xgrp": false,
2025-10-01 10:35:19.514912 | controller |   "xoth": false,
2025-10-01 10:35:19.514926 | controller |   "xusr": false
2025-10-01 10:35:19.514939 | controller | }
2025-10-01 10:35:19.522787 | 
2025-10-01 10:35:19.522906 | TASK [Copy crio stats log file]
2025-10-01 10:35:19.537185 | controller | skipping: Conditional result was False
2025-10-01 10:35:19.542994 | 
2025-10-01 10:35:19.543074 | TASK [Get SELinux related data]
2025-10-01 10:35:20.126655 | controller | <no matches>
2025-10-01 10:35:20.570986 | controller | ERROR
2025-10-01 10:35:20.571364 | controller | {
2025-10-01 10:35:20.571440 | controller |   "delta": "0:00:00.014765",
2025-10-01 10:35:20.571465 | controller |   "end": "2025-10-01 10:35:20.131748",
2025-10-01 10:35:20.571485 | controller |   "msg": "non-zero return code",
2025-10-01 10:35:20.571501 | controller |   "rc": 1,
2025-10-01 10:35:20.571519 | controller |   "start": "2025-10-01 10:35:20.116983"
2025-10-01 10:35:20.571536 | controller | }
2025-10-01 10:35:20.571561 | controller | ERROR: Ignoring Errors
2025-10-01 10:35:20.577805 | 
2025-10-01 10:35:20.577873 | TASK [Create system configuration directory]
2025-10-01 10:35:20.950356 | controller | changed
2025-10-01 10:35:20.955606 | 
2025-10-01 10:35:20.955674 | TASK [Get some of the system configurations]
2025-10-01 10:35:21.479162 | controller | changed
2025-10-01 10:35:21.484900 | 
2025-10-01 10:35:21.484998 | TASK [Copy generated documentation if available]
2025-10-01 10:35:21.508799 | controller | skipping: Conditional result was False
2025-10-01 10:35:21.515539 | 
2025-10-01 10:35:21.515616 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-01 10:35:21.528983 | controller | skipping: Conditional result was False
2025-10-01 10:35:21.535156 | 
2025-10-01 10:35:21.535229 | TASK [Compress logs bigger than 2MB]
2025-10-01 10:35:22.061868 | controller | changed
2025-10-01 10:35:22.067080 | 
2025-10-01 10:35:22.067147 | TASK [Copy files from workspace on node]
2025-10-01 10:35:22.085934 | controller | ok
2025-10-01 10:35:22.121796 | 
2025-10-01 10:35:22.121879 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-01 10:35:22.140782 | controller | skipping: Conditional result was False
2025-10-01 10:35:22.147451 | 
2025-10-01 10:35:22.147550 | TASK [fetch-output : Set log path for single node]
2025-10-01 10:35:22.177058 | controller | ok
2025-10-01 10:35:22.183293 | 
2025-10-01 10:35:22.183358 | LOOP [fetch-output : Ensure local output dirs]
2025-10-01 10:35:22.383211 | controller -> localhost | ok: "/var/lib/zuul/builds/917c4b02d9ba4071a90f9fd18dbd775d/work/logs"
2025-10-01 10:35:22.383780 | controller -> localhost | changed: All items complete
2025-10-01 10:35:22.383818 | 
2025-10-01 10:35:22.561476 | controller -> localhost | changed: "/var/lib/zuul/builds/917c4b02d9ba4071a90f9fd18dbd775d/work/artifacts"
2025-10-01 10:35:22.757062 | controller -> localhost | changed: "/var/lib/zuul/builds/917c4b02d9ba4071a90f9fd18dbd775d/work/docs"
2025-10-01 10:35:22.776047 | 
2025-10-01 10:35:22.776200 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-01 10:35:23.520343 | controller | changed:
2025-10-01 10:35:23.520568 | controller | .d..t...... ./
2025-10-01 10:35:23.520607 | controller | >f+++++++++ README.html
2025-10-01 10:35:23.520635 | controller | >f+++++++++ ansible-execution.log
2025-10-01 10:35:23.520665 | controller | >f+++++++++ ansible.log
2025-10-01 10:35:23.520690 | controller | >f+++++++++ crc-setup.log
2025-10-01 10:35:23.520713 | controller | >f+++++++++ crc-start.log
2025-10-01 10:35:23.520735 | controller | >f+++++++++ dmesg.log
2025-10-01 10:35:23.520759 | controller | >f+++++++++ installed-pkgs.log
2025-10-01 10:35:23.520782 | controller | >f+++++++++ python.log
2025-10-01 10:35:23.520805 | controller | >f+++++++++ registries.conf
2025-10-01 10:35:23.520827 | controller | >f+++++++++ report.html
2025-10-01 10:35:23.520848 | controller | >f+++++++++ selinux-denials.log
2025-10-01 10:35:23.520870 | controller | >f+++++++++ selinux-listing.log
2025-10-01 10:35:23.521024 | controller | cd+++++++++ ci-framework-data/
2025-10-01 10:35:23.521056 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-01 10:35:23.521083 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-01 10:35:23.521107 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-10-01 10:35:23.521129 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/storage/
2025-10-01 10:35:23.521152 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/storage/storage-class.yaml
2025-10-01 10:35:23.521174 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/storage/storage.yaml
2025-10-01 10:35:23.521197 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-01 10:35:23.521219 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-01 10:35:23.521241 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-01 10:35:23.521264 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-01 10:35:23.521287 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-01 10:35:23.521310 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-01 10:35:23.521332 | controller | cd+++++++++ registries.conf.d/
2025-10-01 10:35:23.521355 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-01 10:35:23.522239 | controller | cd+++++++++ system-config/
2025-10-01 10:35:23.522289 | controller | cd+++++++++ system-config/libvirt/
2025-10-01 10:35:23.522316 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-01 10:35:23.522336 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-01 10:35:23.522360 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-10-01 10:35:23.522425 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-10-01 10:35:23.522450 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-10-01 10:35:23.522469 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-10-01 10:35:23.522486 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-10-01 10:35:23.522504 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-10-01 10:35:23.522522 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-10-01 10:35:23.522539 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-10-01 10:35:23.522556 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-10-01 10:35:23.522572 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-10-01 10:35:23.522588 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-10-01 10:35:23.522604 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-10-01 10:35:23.522620 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-10-01 10:35:23.522636 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-10-01 10:35:24.043979 | controller | changed: .d..t...... ./
2025-10-01 10:35:24.550949 | controller | changed: .d..t...... ./
2025-10-01 10:35:24.563899 | 
2025-10-01 10:35:24.564000 | TASK [Return artifact to Zuul]
2025-10-01 10:35:24.593068 | controller | ok
2025-10-01 10:35:24.612453 | 
2025-10-01 10:35:24.612510 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-01 10:35:24.612605 | 
2025-10-01 10:35:24.612633 | PLAY RECAP
2025-10-01 10:35:24.612668 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-01 10:35:24.612689 | 
2025-10-01 10:35:24.693453 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-01 10:35:24.694610 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-01 10:35:25.214170 | 
2025-10-01 10:35:25.214253 | PLAY [all]
2025-10-01 10:35:25.232211 | 
2025-10-01 10:35:25.232283 | TASK [include_role : fetch-output]
2025-10-01 10:35:25.260105 | controller | ok
2025-10-01 10:35:25.276302 | 
2025-10-01 10:35:25.276414 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-01 10:35:25.320276 | controller | skipping: Conditional result was False
2025-10-01 10:35:25.325656 | 
2025-10-01 10:35:25.325725 | TASK [fetch-output : Set log path for single node]
2025-10-01 10:35:25.353812 | controller | ok
2025-10-01 10:35:25.359785 | 
2025-10-01 10:35:25.359901 | LOOP [fetch-output : Ensure local output dirs]
2025-10-01 10:35:25.697140 | controller -> localhost | ok: "/var/lib/zuul/builds/917c4b02d9ba4071a90f9fd18dbd775d/work/logs"
2025-10-01 10:35:25.878743 | controller -> localhost | ok: "/var/lib/zuul/builds/917c4b02d9ba4071a90f9fd18dbd775d/work/artifacts"
2025-10-01 10:35:26.071951 | controller -> localhost | ok: "/var/lib/zuul/builds/917c4b02d9ba4071a90f9fd18dbd775d/work/docs"
2025-10-01 10:35:26.080550 | 
2025-10-01 10:35:26.080635 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-01 10:35:26.791106 | controller | ok
2025-10-01 10:35:26.791298 | controller | ok: All items complete
2025-10-01 10:35:26.791327 | 
2025-10-01 10:35:27.286164 | controller | ok
2025-10-01 10:35:27.759146 | controller | ok
2025-10-01 10:35:27.772722 | 
2025-10-01 10:35:27.772800 | TASK [include_role : fetch-output-openshift]
2025-10-01 10:35:27.786242 | controller | skipping: Conditional result was False
2025-10-01 10:35:27.792194 | 
2025-10-01 10:35:27.792262 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-01 10:35:28.125144 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006509
2025-10-01 10:35:28.311471 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006236
2025-10-01 10:35:28.348717 | 
2025-10-01 10:35:28.348841 | PLAY [all]
2025-10-01 10:35:28.365238 | 
2025-10-01 10:35:28.365307 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-01 10:35:28.882063 | controller | changed
2025-10-01 10:35:28.907100 | 
2025-10-01 10:35:28.907176 | PLAY RECAP
2025-10-01 10:35:28.907223 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-01 10:35:28.907245 | 
2025-10-01 10:35:28.978853 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-01 10:35:28.979598 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-01 10:35:29.538951 | 
2025-10-01 10:35:29.539061 | PLAY [localhost]
2025-10-01 10:35:29.556344 | 
2025-10-01 10:35:29.556449 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-01 10:35:29.857345 | localhost | changed
2025-10-01 10:35:29.861923 | 
2025-10-01 10:35:29.862018 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-01 10:35:29.879313 | localhost | ok
2025-10-01 10:35:29.887263 | 
2025-10-01 10:35:29.887341 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-01 10:35:30.188566 | localhost | changed
2025-10-01 10:35:30.194142 | 
2025-10-01 10:35:30.194216 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-01 10:35:30.742415 | localhost | changed
2025-10-01 10:35:30.747944 | 
2025-10-01 10:35:30.748028 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-01 10:35:31.134190 | localhost | Identity added: /var/lib/zuul/builds/917c4b02d9ba4071a90f9fd18dbd775d/work/tmp/ansible.v7sby6e1 (/var/lib/zuul/builds/917c4b02d9ba4071a90f9fd18dbd775d/work/tmp/ansible.v7sby6e1)
2025-10-01 10:35:31.134355 | localhost | ok: Runtime: 0:00:00.006644
2025-10-01 10:35:31.138770 | 
2025-10-01 10:35:31.138842 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-01 10:35:31.380233 | localhost | ok: Runtime: 0:00:00.016500
2025-10-01 10:35:31.385318 | 
2025-10-01 10:35:31.385425 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-01 10:35:31.444640 | localhost | changed
2025-10-01 10:35:31.448979 | 
2025-10-01 10:35:31.449046 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-01 10:35:31.779730 | localhost | changed
2025-10-01 10:35:31.800722 | 
2025-10-01 10:35:31.800800 | PLAY [localhost]
2025-10-01 10:35:31.812790 | 
2025-10-01 10:35:31.812859 | TASK [Generate bulk log download script]
2025-10-01 10:35:31.830989 | localhost | ok
2025-10-01 10:35:31.842754 | 
2025-10-01 10:35:31.842830 | TASK [local-log-download : Check API endpoint is defined]
2025-10-01 10:35:31.869919 | localhost | ok: All assertions passed
2025-10-01 10:35:31.875199 | 
2025-10-01 10:35:31.875294 | TASK [local-log-download : Create download script]
2025-10-01 10:35:32.235202 | localhost -> localhost | changed
2025-10-01 10:35:32.243978 | 
2025-10-01 10:35:32.244058 | TASK [Register quick-download link]
2025-10-01 10:35:32.260924 | localhost | ok
2025-10-01 10:35:32.297997 | 
2025-10-01 10:35:32.298082 | PLAY [logserver.rdoproject.org]
2025-10-01 10:35:32.307926 | 
2025-10-01 10:35:32.307997 | TASK [Set zuul-log-path fact]
2025-10-01 10:35:32.323735 | logserver.rdoproject.org | ok
2025-10-01 10:35:32.332946 | 
2025-10-01 10:35:32.333026 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-01 10:35:32.359661 | logserver.rdoproject.org | ok
2025-10-01 10:35:32.365061 | 
2025-10-01 10:35:32.365146 | TASK [upload-logs : Create log directories]
2025-10-01 10:35:34.077236 | logserver.rdoproject.org | changed
2025-10-01 10:35:34.081362 | 
2025-10-01 10:35:34.081479 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-01 10:35:34.353127 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.008757
2025-10-01 10:35:34.358343 | 
2025-10-01 10:35:34.358448 | TASK [upload-logs : Upload logs to log server]
2025-10-01 10:35:35.885003 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-01 10:35:35.888033 | 
2025-10-01 10:35:35.888105 | LOOP [upload-logs : Compress console log and json output]
2025-10-01 10:35:35.926031 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-01 10:35:35.935523 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-01 10:35:35.945858 | 
2025-10-01 10:35:35.945964 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-01 10:35:35.981083 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-01 10:35:35.981318 | 
2025-10-01 10:35:35.986572 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-01 10:35:35.994002 | 
2025-10-01 10:35:35.994099 | LOOP [upload-logs : Upload console log and json output]