2025-10-13 19:20:33.251045 | Job console starting...
2025-10-13 19:20:33.490073 | Updating repositories
2025-10-13 19:20:34.204702 | Preparing job workspace
2025-10-13 19:20:44.728638 | Running Ansible setup...
2025-10-13 19:20:48.543558 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 19:20:49.716005 | 
2025-10-13 19:20:49.716120 | PLAY [localhost]
2025-10-13 19:20:49.724381 | 
2025-10-13 19:20:49.724447 | TASK [Gathering Facts]
2025-10-13 19:20:51.069115 | localhost | ok
2025-10-13 19:20:51.117494 | 
2025-10-13 19:20:51.117619 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-13 19:20:52.324617 | localhost -> localhost | changed
2025-10-13 19:20:52.329550 | 
2025-10-13 19:20:52.329618 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-13 19:20:54.173360 | localhost -> localhost | changed
2025-10-13 19:20:54.180983 | 
2025-10-13 19:20:54.181047 | TASK [Setup log path fact]
2025-10-13 19:20:54.244868 | localhost | ok
2025-10-13 19:20:54.283958 | 
2025-10-13 19:20:54.284068 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 19:20:54.379215 | localhost | ok
2025-10-13 19:20:54.387054 | 
2025-10-13 19:20:54.387125 | TASK [emit-job-header : Print job information]
2025-10-13 19:20:54.461831 | # Job Information
2025-10-13 19:20:54.462232 | Ansible Version: 2.15.12
2025-10-13 19:20:54.462265 | Job: cifmw-molecule-build_openstack_packages
2025-10-13 19:20:54.462301 | Pipeline: github-check
2025-10-13 19:20:54.462323 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-13 19:20:54.462342 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3402
2025-10-13 19:20:54.462360 | Log URL (when completed): https://logserver.rdoproject.org/532/rdoproject.org/5327fd7006164d02857ab3016cbc3002/
2025-10-13 19:20:54.462378 | Event ID: 8207d830-a869-11f0-8ae9-c38a08a1225e
2025-10-13 19:20:54.465643 | 
2025-10-13 19:20:54.465704 | LOOP [emit-job-header : Print node information]
2025-10-13 19:20:54.708683 | localhost | ok:
2025-10-13 19:20:54.708861 | localhost | # Node Information
2025-10-13 19:20:54.708891 | localhost | Inventory Hostname: controller
2025-10-13 19:20:54.708935 | localhost | Hostname: np0005486097
2025-10-13 19:20:54.708959 | localhost | Username: zuul
2025-10-13 19:20:54.708980 | localhost | Distro: CentOS 9
2025-10-13 19:20:54.708998 | localhost | Provider: ibm-bm4-nodepool
2025-10-13 19:20:54.709015 | localhost | Region: regionOne
2025-10-13 19:20:54.709032 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-13 19:20:54.709048 | localhost | Product Name: OpenStack Compute
2025-10-13 19:20:54.709065 | localhost | Interface IP: 192.168.26.17
2025-10-13 19:20:54.767229 | 
2025-10-13 19:20:54.767326 | PLAY [all]
2025-10-13 19:20:54.785167 | 
2025-10-13 19:20:54.785230 | TASK [Gather network facts]
2025-10-13 19:20:55.225390 | controller | ok
2025-10-13 19:20:55.260469 | 
2025-10-13 19:20:55.260536 | TASK [include_role : start-zuul-console]
2025-10-13 19:20:55.304545 | controller | ok
2025-10-13 19:20:55.319812 | 
2025-10-13 19:20:55.319874 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-13 19:20:55.892617 | controller | ok
2025-10-13 19:20:55.935057 | 
2025-10-13 19:20:55.935131 | TASK [include_role : add-build-sshkey]
2025-10-13 19:20:55.962511 | controller | ok
2025-10-13 19:20:55.979114 | 
2025-10-13 19:20:55.979209 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-13 19:20:56.401920 | controller -> localhost | ok
2025-10-13 19:20:56.407500 | 
2025-10-13 19:20:56.407563 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-13 19:20:56.486618 | controller | ok
2025-10-13 19:20:56.509229 | controller | included: /var/lib/zuul/builds/5327fd7006164d02857ab3016cbc3002/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-13 19:20:56.530818 | 
2025-10-13 19:20:56.530884 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-13 19:20:57.661004 | controller -> localhost | Generating public/private rsa key pair.
2025-10-13 19:20:57.661180 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5327fd7006164d02857ab3016cbc3002/work/5327fd7006164d02857ab3016cbc3002_id_rsa.
2025-10-13 19:20:57.661212 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5327fd7006164d02857ab3016cbc3002/work/5327fd7006164d02857ab3016cbc3002_id_rsa.pub.
2025-10-13 19:20:57.661236 | controller -> localhost | The key fingerprint is:
2025-10-13 19:20:57.661257 | controller -> localhost | SHA256:iQcF6ZZL4Npt0EF8pqMM2HvMEQCv+oOSprS/Zwt7uAs zuul-build-sshkey
2025-10-13 19:20:57.661277 | controller -> localhost | The key's randomart image is:
2025-10-13 19:20:57.661324 | controller -> localhost | +---[RSA 3072]----+
2025-10-13 19:20:57.661344 | controller -> localhost | |.... ooo.        |
2025-10-13 19:20:57.661364 | controller -> localhost | | .  o +.o        |
2025-10-13 19:20:57.661384 | controller -> localhost | | o.. =.*         |
2025-10-13 19:20:57.661402 | controller -> localhost | |..o + Oo .       |
2025-10-13 19:20:57.661420 | controller -> localhost | |.  O B.oS        |
2025-10-13 19:20:57.661437 | controller -> localhost | |. o B +.         |
2025-10-13 19:20:57.661454 | controller -> localhost | |.E o..           |
2025-10-13 19:20:57.661473 | controller -> localhost | |=o+.o+           |
2025-10-13 19:20:57.661492 | controller -> localhost | |=.oBB..          |
2025-10-13 19:20:57.661511 | controller -> localhost | +----[SHA256]-----+
2025-10-13 19:20:57.661552 | controller -> localhost | ok: Runtime: 0:00:00.342048
2025-10-13 19:20:57.666620 | 
2025-10-13 19:20:57.666679 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-13 19:20:57.704403 | controller | ok
2025-10-13 19:20:57.719223 | controller | included: /var/lib/zuul/builds/5327fd7006164d02857ab3016cbc3002/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-13 19:20:57.733845 | 
2025-10-13 19:20:57.734960 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-13 19:20:57.761332 | controller | skipping: Conditional result was False
2025-10-13 19:20:57.766175 | 
2025-10-13 19:20:57.766233 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-13 19:20:58.361310 | controller | changed
2025-10-13 19:20:58.366027 | 
2025-10-13 19:20:58.366100 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-13 19:20:58.651161 | controller | ok
2025-10-13 19:20:58.660162 | 
2025-10-13 19:20:58.660224 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-13 19:20:59.452682 | controller | changed
2025-10-13 19:20:59.457623 | 
2025-10-13 19:20:59.457690 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-13 19:21:00.161743 | controller | changed
2025-10-13 19:21:00.166592 | 
2025-10-13 19:21:00.166659 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-13 19:21:00.234173 | controller | skipping: Conditional result was False
2025-10-13 19:21:00.239511 | 
2025-10-13 19:21:00.239571 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-13 19:21:01.165526 | controller -> localhost | changed
2025-10-13 19:21:01.182844 | 
2025-10-13 19:21:01.182928 | TASK [add-build-sshkey : Add back temp key]
2025-10-13 19:21:02.110200 | controller -> localhost | Identity added: /var/lib/zuul/builds/5327fd7006164d02857ab3016cbc3002/work/5327fd7006164d02857ab3016cbc3002_id_rsa (zuul-build-sshkey)
2025-10-13 19:21:02.110444 | controller -> localhost | ok: Runtime: 0:00:00.032653
2025-10-13 19:21:02.116368 | 
2025-10-13 19:21:02.116429 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-13 19:21:02.824953 | controller | ok
2025-10-13 19:21:02.829114 | 
2025-10-13 19:21:02.829173 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-13 19:21:02.915943 | controller | skipping: Conditional result was False
2025-10-13 19:21:02.924564 | 
2025-10-13 19:21:02.924632 | TASK [include_role : validate-host]
2025-10-13 19:21:03.004398 | controller | ok
2025-10-13 19:21:03.070605 | 
2025-10-13 19:21:03.070702 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-13 19:21:03.150604 | controller | ok
2025-10-13 19:21:03.154821 | 
2025-10-13 19:21:03.154884 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-13 19:21:04.021627 | controller -> localhost | ok
2025-10-13 19:21:04.027741 | 
2025-10-13 19:21:04.027806 | TASK [validate-host : Collect information about the host]
2025-10-13 19:21:04.727075 | controller | ok
2025-10-13 19:21:04.750559 | 
2025-10-13 19:21:04.750642 | TASK [validate-host : Sanitize hostname]
2025-10-13 19:21:04.973224 | controller | ok
2025-10-13 19:21:04.977875 | 
2025-10-13 19:21:04.977960 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-13 19:21:06.452195 | controller -> localhost | changed
2025-10-13 19:21:06.457991 | 
2025-10-13 19:21:06.458072 | TASK [validate-host : Collect information about zuul worker]
2025-10-13 19:21:07.113124 | controller | ok
2025-10-13 19:21:07.117785 | 
2025-10-13 19:21:07.117863 | TASK [validate-host : Write out all zuul information for each host]
2025-10-13 19:21:08.790066 | controller -> localhost | changed
2025-10-13 19:21:08.798882 | 
2025-10-13 19:21:08.798977 | TASK [include_role : prepare-workspace-openshift]
2025-10-13 19:21:08.859595 | controller | skipping: Conditional result was False
2025-10-13 19:21:08.870438 | 
2025-10-13 19:21:08.870515 | TASK [include_role : remove-zuul-sshkey]
2025-10-13 19:21:08.924397 | controller | skipping: Conditional result was False
2025-10-13 19:21:08.929655 | 
2025-10-13 19:21:08.929719 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-13 19:21:09.202049 | controller | ok: "logs"
2025-10-13 19:21:09.202308 | controller | ok: All items complete
2025-10-13 19:21:09.202349 | 
2025-10-13 19:21:09.406873 | controller | ok: "artifacts"
2025-10-13 19:21:09.603503 | controller | ok: "docs"
2025-10-13 19:21:09.615113 | 
2025-10-13 19:21:09.615210 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-13 19:21:09.929866 | controller | changed: "logs"
2025-10-13 19:21:10.125520 | controller | changed: "artifacts"
2025-10-13 19:21:10.329454 | controller | changed: "docs"
2025-10-13 19:21:10.386065 | 
2025-10-13 19:21:10.386143 | PLAY RECAP
2025-10-13 19:21:10.386191 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 19:21:10.386220 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 19:21:10.386240 | 
2025-10-13 19:21:10.749767 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 19:21:10.750557 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 19:21:13.053185 | 
2025-10-13 19:21:13.053318 | PLAY [all]
2025-10-13 19:21:13.170811 | 
2025-10-13 19:21:13.170945 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-13 19:21:13.385118 | controller | ok
2025-10-13 19:21:13.391530 | 
2025-10-13 19:21:13.391611 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-13 19:21:14.194305 | controller | changed
2025-10-13 19:21:14.206076 | 
2025-10-13 19:21:14.206145 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-13 19:21:15.821236 | controller | changed
2025-10-13 19:21:15.859204 | 
2025-10-13 19:21:15.864956 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-13 19:21:17.303212 | controller | changed:
2025-10-13 19:21:17.303398 | controller | {
2025-10-13 19:21:17.303432 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-13 19:21:17.303465 | controller | }
2025-10-13 19:21:17.505086 | controller | changed:
2025-10-13 19:21:17.505174 | controller | {
2025-10-13 19:21:17.505205 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-13 19:21:17.505239 | controller | }
2025-10-13 19:21:17.721868 | controller | changed:
2025-10-13 19:21:17.721973 | controller | {
2025-10-13 19:21:17.722002 | 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-13 19:21:17.722025 | controller | }
2025-10-13 19:21:17.958932 | controller | changed:
2025-10-13 19:21:17.959004 | controller | {
2025-10-13 19:21:17.959029 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-13 19:21:17.959049 | controller | }
2025-10-13 19:21:18.189713 | controller | changed:
2025-10-13 19:21:18.189799 | controller | {
2025-10-13 19:21:18.189826 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-13 19:21:18.189848 | controller | }
2025-10-13 19:21:18.417554 | controller | changed:
2025-10-13 19:21:18.417645 | controller | {
2025-10-13 19:21:18.417676 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-13 19:21:18.417699 | controller | }
2025-10-13 19:21:18.653735 | controller | changed:
2025-10-13 19:21:18.653813 | controller | {
2025-10-13 19:21:18.653840 | 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-13 19:21:18.653863 | controller | }
2025-10-13 19:21:18.883765 | controller | changed:
2025-10-13 19:21:18.883850 | controller | {
2025-10-13 19:21:18.883878 | 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-13 19:21:18.883901 | controller | }
2025-10-13 19:21:19.107555 | controller | changed:
2025-10-13 19:21:19.107635 | controller | {
2025-10-13 19:21:19.107687 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-13 19:21:19.107710 | controller | }
2025-10-13 19:21:19.340360 | controller | changed:
2025-10-13 19:21:19.340438 | controller | {
2025-10-13 19:21:19.340466 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-13 19:21:19.340489 | controller | }
2025-10-13 19:21:19.574105 | controller | changed:
2025-10-13 19:21:19.574191 | controller | {
2025-10-13 19:21:19.574220 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-13 19:21:19.574241 | controller | }
2025-10-13 19:21:19.800837 | controller | changed:
2025-10-13 19:21:19.801029 | controller | {
2025-10-13 19:21:19.801071 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-13 19:21:19.801096 | controller | }
2025-10-13 19:21:20.064848 | controller | changed:
2025-10-13 19:21:20.064938 | controller | {
2025-10-13 19:21:20.064967 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-13 19:21:20.064990 | controller | }
2025-10-13 19:21:20.299921 | controller | changed:
2025-10-13 19:21:20.299998 | controller | {
2025-10-13 19:21:20.300025 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-13 19:21:20.300047 | controller | }
2025-10-13 19:21:20.554703 | controller | changed:
2025-10-13 19:21:20.554784 | controller | {
2025-10-13 19:21:20.554810 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-13 19:21:20.554831 | controller | }
2025-10-13 19:21:20.811454 | controller | changed:
2025-10-13 19:21:20.811528 | controller | {
2025-10-13 19:21:20.811555 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-13 19:21:20.811576 | controller | }
2025-10-13 19:21:21.063169 | controller | changed:
2025-10-13 19:21:21.063238 | controller | {
2025-10-13 19:21:21.063273 | 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-13 19:21:21.063295 | controller | }
2025-10-13 19:21:21.295736 | controller | changed:
2025-10-13 19:21:21.295811 | controller | {
2025-10-13 19:21:21.295838 | 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-13 19:21:21.295867 | controller | }
2025-10-13 19:21:21.530097 | controller | changed:
2025-10-13 19:21:21.530167 | controller | {
2025-10-13 19:21:21.530193 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-13 19:21:21.530213 | controller | }
2025-10-13 19:21:21.783169 | controller | changed:
2025-10-13 19:21:21.783241 | controller | {
2025-10-13 19:21:21.783276 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-13 19:21:21.783298 | controller | }
2025-10-13 19:21:22.021603 | controller | changed:
2025-10-13 19:21:22.021680 | controller | {
2025-10-13 19:21:22.021707 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-13 19:21:22.021729 | controller | }
2025-10-13 19:21:22.267635 | controller | changed:
2025-10-13 19:21:22.267711 | controller | {
2025-10-13 19:21:22.267738 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-13 19:21:22.267758 | controller | }
2025-10-13 19:21:22.509679 | controller | changed:
2025-10-13 19:21:22.509755 | controller | {
2025-10-13 19:21:22.509783 | 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-13 19:21:22.509805 | controller | }
2025-10-13 19:21:22.740844 | controller | changed:
2025-10-13 19:21:22.740955 | controller | {
2025-10-13 19:21:22.740987 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-13 19:21:22.741009 | controller | }
2025-10-13 19:21:22.968141 | controller | changed:
2025-10-13 19:21:22.968210 | controller | {
2025-10-13 19:21:22.968234 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-13 19:21:22.968255 | controller | }
2025-10-13 19:21:23.231224 | controller | changed:
2025-10-13 19:21:23.231308 | controller | {
2025-10-13 19:21:23.231337 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-13 19:21:23.231359 | controller | }
2025-10-13 19:21:23.266347 | 
2025-10-13 19:21:23.266440 | TASK [Set timezone to UTC]
2025-10-13 19:21:24.409113 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-13 19:21:24.438961 | 
2025-10-13 19:21:24.439074 | TASK [Create nodepool directory]
2025-10-13 19:21:24.719013 | controller | changed
2025-10-13 19:21:24.724718 | 
2025-10-13 19:21:24.724806 | TASK [Create nodepool sub_nodes file]
2025-10-13 19:21:25.497522 | controller | changed
2025-10-13 19:21:25.502818 | 
2025-10-13 19:21:25.513940 | TASK [Create nodepool sub_nodes_private file]
2025-10-13 19:21:26.339443 | controller | changed
2025-10-13 19:21:26.345052 | 
2025-10-13 19:21:26.345119 | LOOP [Populate nodepool sub_nodes file]
2025-10-13 19:21:26.456962 | 
2025-10-13 19:21:26.457324 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-13 19:21:26.568029 | 
2025-10-13 19:21:26.568179 | TASK [Create nodepool primary file]
2025-10-13 19:21:26.675407 | controller | skipping: Conditional result was False
2025-10-13 19:21:26.680600 | 
2025-10-13 19:21:26.680676 | TASK [Create nodepool node_private for this node]
2025-10-13 19:21:27.526390 | controller | changed
2025-10-13 19:21:27.531701 | 
2025-10-13 19:21:27.531778 | LOOP [Copy ssh keys to nodepool directory]
2025-10-13 19:21:28.429175 | controller | ok: Item: id_rsa Runtime: 0:00:00.006118
2025-10-13 19:21:28.429342 | 
2025-10-13 19:21:28.615198 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005107
2025-10-13 19:21:28.625091 | 
2025-10-13 19:21:28.625188 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-13 19:21:29.350235 | controller | changed
2025-10-13 19:21:29.358339 | 
2025-10-13 19:21:29.358426 | TASK [Validate sudoers config after edits]
2025-10-13 19:21:29.782779 | controller | /etc/sudoers: parsed OK
2025-10-13 19:21:29.782854 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-13 19:21:29.782862 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-13 19:21:29.782868 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-13 19:21:29.944158 | controller | ok: Runtime: 0:00:00.007518
2025-10-13 19:21:29.949769 | 
2025-10-13 19:21:29.949833 | TASK [Show the environment passed in to job shell scripts]
2025-10-13 19:21:30.305905 | controller | SHELL=/bin/bash
2025-10-13 19:21:30.306068 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-13 19:21:30.306086 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-13 19:21:30.306094 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/04/3404/9f142a3066b32ef704ae4f0d5fc5f7189e41a1f8^openstack-k8s-operators/ci-framework:main:refs/changes/06/3406/3b7e5d87cb08f8ceea89c6e58502f20dfffec667^openstack-k8s-operators/ci-framework:main:refs/changes/02/3402/a301e7c15ccc9d964f35eb738c51dc015730d4ed
2025-10-13 19:21:30.306102 | controller | PWD=/home/zuul
2025-10-13 19:21:30.306107 | controller | ZUUL_PIPELINE=github-check
2025-10-13 19:21:30.306113 | controller | LOGNAME=zuul
2025-10-13 19:21:30.306118 | controller | XDG_SESSION_TYPE=tty
2025-10-13 19:21:30.306124 | controller | _=/usr/bin/env
2025-10-13 19:21:30.306129 | controller | MOTD_SHOWN=pam
2025-10-13 19:21:30.306135 | controller | HOME=/home/zuul
2025-10-13 19:21:30.306140 | controller | LANG=en_US.UTF-8
2025-10-13 19:21:30.306145 | controller | SSH_CONNECTION=192.168.26.12 36452 192.168.26.17 22
2025-10-13 19:21:30.306151 | 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-13 19:21:30.306161 | controller | ZUUL_CHANGE_IDS=3404,9f142a3066b32ef704ae4f0d5fc5f7189e41a1f8 3406,3b7e5d87cb08f8ceea89c6e58502f20dfffec667 3402,a301e7c15ccc9d964f35eb738c51dc015730d4ed
2025-10-13 19:21:30.306166 | controller | WORKSPACE=/home/zuul/workspace
2025-10-13 19:21:30.306172 | controller | XDG_SESSION_CLASS=user
2025-10-13 19:21:30.306177 | controller | SELINUX_ROLE_REQUESTED=
2025-10-13 19:21:30.306182 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-13 19:21:30.306188 | controller | USER=zuul
2025-10-13 19:21:30.306193 | controller | ZUUL_VOTING=True
2025-10-13 19:21:30.306199 | controller | BUILD_TIMEOUT=1800000
2025-10-13 19:21:30.306204 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-13 19:21:30.306209 | controller | SHLVL=1
2025-10-13 19:21:30.306215 | controller | ZUUL_PATCHSET=a301e7c15ccc9d964f35eb738c51dc015730d4ed
2025-10-13 19:21:30.306220 | controller | XDG_SESSION_ID=1
2025-10-13 19:21:30.306225 | controller | ZUUL_BRANCH=main
2025-10-13 19:21:30.306231 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-13 19:21:30.306236 | controller | SSH_CLIENT=192.168.26.12 36452 22
2025-10-13 19:21:30.306242 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-13 19:21:30.306247 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-13 19:21:30.306253 | controller | which_declare=declare -f
2025-10-13 19:21:30.306259 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-13 19:21:30.306266 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-13 19:21:30.306271 | controller | ZUUL_CHANGE=3402
2025-10-13 19:21:30.306277 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-13 19:21:30.306282 | controller | ZUUL_UUID=5327fd7006164d02857ab3016cbc3002
2025-10-13 19:21:30.306287 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-13 19:21:30.306293 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-13 19:21:30.306299 | controller | }
2025-10-13 19:21:30.525052 | controller | ok: Runtime: 0:00:00.006877
2025-10-13 19:21:30.530472 | 
2025-10-13 19:21:30.530539 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-13 19:21:30.564427 | controller | skipping: Conditional result was False
2025-10-13 19:21:30.572618 | 
2025-10-13 19:21:30.572692 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-13 19:21:31.249779 | controller | skipping: Conditional result was False
2025-10-13 19:21:31.255438 | 
2025-10-13 19:21:31.255505 | TASK [Ensure legacy workspace directory]
2025-10-13 19:21:31.545400 | controller | changed
2025-10-13 19:21:31.621812 | 
2025-10-13 19:21:31.621882 | PLAY RECAP
2025-10-13 19:21:31.621958 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 19:21:31.621987 | 
2025-10-13 19:21:32.101387 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 19:21:32.102127 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 19:21:34.653234 | 
2025-10-13 19:21:34.661038 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-13 19:21:34.762054 | 
2025-10-13 19:21:34.762151 | TASK [Create zuul-output directory]
2025-10-13 19:21:35.721791 | controller | changed
2025-10-13 19:21:35.727574 | 
2025-10-13 19:21:35.727651 | TASK [Slurp Zuul inventory test]
2025-10-13 19:21:37.098420 | controller -> localhost | ok
2025-10-13 19:21:37.104641 | 
2025-10-13 19:21:37.105338 | TASK [Save zuul inventory]
2025-10-13 19:21:39.005815 | controller | changed
2025-10-13 19:21:39.010723 | 
2025-10-13 19:21:39.010787 | TASK [Save zuul vars without the change_message]
2025-10-13 19:21:40.107422 | controller | changed
2025-10-13 19:21:40.233052 | 
2025-10-13 19:21:40.233142 | PLAY RECAP
2025-10-13 19:21:40.233191 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 19:21:40.233215 | 
2025-10-13 19:21:40.750922 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 19:21:40.751661 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-13 19:21:43.812542 | 
2025-10-13 19:21:43.812650 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-13 19:21:43.892124 | 
2025-10-13 19:21:43.892229 | TASK [Read group_vars]
2025-10-13 19:21:43.953223 | controller | ok
2025-10-13 19:21:44.027467 | 
2025-10-13 19:21:44.027577 | TASK [cifmw_helpers : Check directory is available]
2025-10-13 19:21:44.948567 | controller | ok
2025-10-13 19:21:44.961460 | 
2025-10-13 19:21:44.961548 | TASK [cifmw_helpers : Find yaml files]
2025-10-13 19:21:45.035973 | controller | skipping: Conditional result was False
2025-10-13 19:21:45.042751 | 
2025-10-13 19:21:45.042897 | TASK [cifmw_helpers : Print available yaml files]
2025-10-13 19:21:45.117903 | controller | skipping: Conditional result was False
2025-10-13 19:21:45.127939 | 
2025-10-13 19:21:45.128017 | LOOP [cifmw_helpers : Create files on localhost and use include_vars]
2025-10-13 19:21:45.246170 | 
2025-10-13 19:21:45.246317 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-13 19:21:45.320388 | controller | ok
2025-10-13 19:21:45.419607 | 
2025-10-13 19:21:45.419710 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-13 19:21:45.566852 | controller | skipping: Conditional result was False
2025-10-13 19:21:45.572677 | 
2025-10-13 19:21:45.572751 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-13 19:21:46.430062 | controller | ok
2025-10-13 19:21:46.446653 | 
2025-10-13 19:21:46.446727 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-13 19:21:47.209215 | controller | ok
2025-10-13 19:21:47.265949 | 
2025-10-13 19:21:47.266054 | TASK [Prepare workspace]
2025-10-13 19:21:47.542712 | controller | ok
2025-10-13 19:21:47.751355 | 
2025-10-13 19:21:47.751470 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-13 19:21:48.485874 | controller | ok
2025-10-13 19:21:48.493897 | 
2025-10-13 19:21:48.493997 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-13 19:21:57.164282 | controller | Output suppressed because no_log was given
2025-10-13 19:21:57.185478 | 
2025-10-13 19:21:57.185562 | LOOP [Create zuul-output directory]
2025-10-13 19:21:57.528996 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-13 19:21:57.529193 | 
2025-10-13 19:21:57.701638 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-13 19:21:57.716665 | 
2025-10-13 19:21:57.716742 | TASK [Install required packages]
2025-10-13 19:22:46.788332 | controller | changed
2025-10-13 19:22:46.795011 | 
2025-10-13 19:22:46.795092 | TASK [Install venv]
2025-10-13 19:23:50.441931 | controller | changed
2025-10-13 19:23:50.498782 | 
2025-10-13 19:23:50.498901 | PLAY RECAP
2025-10-13 19:23:50.498968 | controller | ok: 8 changed: 4 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0
2025-10-13 19:23:50.498994 | 
2025-10-13 19:23:50.596999 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-13 19:23:50.597850 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-13 19:23:51.135630 | 
2025-10-13 19:23:51.135732 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-13 19:23:51.156721 | 
2025-10-13 19:23:51.156818 | TASK [Gather required facts]
2025-10-13 19:23:51.672752 | controller | ok
2025-10-13 19:23:51.678313 | 
2025-10-13 19:23:51.678399 | TASK [Load environment var if instructed to]
2025-10-13 19:23:51.703310 | controller | skipping: Conditional result was False
2025-10-13 19:23:51.709015 | 
2025-10-13 19:23:51.709096 | TASK [Ensure group_vars dir exists]
2025-10-13 19:23:52.021982 | controller | ok
2025-10-13 19:23:52.028565 | 
2025-10-13 19:23:52.028671 | TASK [Run molecule]
2025-10-13 19:23:52.970903 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-13 19:23:53.042057 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-13 19:24:12.660641 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 19:24:12.661126 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 19:24:12.661573 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 19:24:12.662035 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 19:24:12.662505 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+c48116c3 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 1.0.0+c48116c3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 19:24:12.662940 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 19:24:12.663410 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 19:24:12.663873 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 19:24:12.664332 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 19:24:12.664760 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 19:24:12.665242 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 19:24:12.665683 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 19:24:12.666165 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 19:24:12.666592 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 19:24:12.667065 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 19:24:12.676587 | controller | INFO     Running default > prepare
2025-10-13 19:24:13.397065 | controller |
2025-10-13 19:24:13.397155 | controller | PLAY [Prepare] *****************************************************************
2025-10-13 19:24:13.397258 | controller |
2025-10-13 19:24:13.397361 | controller | TASK [Gathering Facts] *********************************************************
2025-10-13 19:24:13.397464 | controller | Monday 13 October 2025  19:24:13 +0000 (0:00:00.022)       0:00:00.022 ********
2025-10-13 19:24:14.242375 | controller | ok: [instance]
2025-10-13 19:24:14.242430 | controller |
2025-10-13 19:24:14.242541 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-13 19:24:14.242649 | controller | Monday 13 October 2025  19:24:14 +0000 (0:00:00.845)       0:00:00.868 ********
2025-10-13 19:24:14.259749 | controller | skipping: [instance]
2025-10-13 19:24:14.259835 | controller |
2025-10-13 19:24:14.259944 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-13 19:24:14.260101 | controller | Monday 13 October 2025  19:24:14 +0000 (0:00:00.017)       0:00:00.886 ********
2025-10-13 19:24:14.301082 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-13 19:24:14.301163 | controller |
2025-10-13 19:24:14.301273 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-13 19:24:14.301381 | controller | Monday 13 October 2025  19:24:14 +0000 (0:00:00.041)       0:00:00.927 ********
2025-10-13 19:24:14.580811 | controller | ok: [instance]
2025-10-13 19:24:14.580875 | controller |
2025-10-13 19:24:14.581038 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-13 19:24:14.581176 | controller | Monday 13 October 2025  19:24:14 +0000 (0:00:00.279)       0:00:01.207 ********
2025-10-13 19:24:14.890237 | controller | ok: [instance]
2025-10-13 19:24:14.890294 | controller |
2025-10-13 19:24:14.890414 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-13 19:24:14.890522 | controller | Monday 13 October 2025  19:24:14 +0000 (0:00:00.309)       0:00:01.516 ********
2025-10-13 19:24:15.572742 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-13 19:24:15.572818 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-13 19:24:15.572924 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-13 19:24:15.573059 | controller |
2025-10-13 19:24:15.573157 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-13 19:24:15.573262 | controller | Monday 13 October 2025  19:24:15 +0000 (0:00:00.682)       0:00:02.199 ********
2025-10-13 19:24:15.627136 | controller |
2025-10-13 19:24:15.627244 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-13 19:24:15.627371 | controller | Monday 13 October 2025  19:24:15 +0000 (0:00:00.054)       0:00:02.253 ********
2025-10-13 19:24:16.197490 | controller | changed: [instance] => (item=tmp)
2025-10-13 19:24:16.956086 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-13 19:24:16.956117 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-13 19:24:16.956125 | controller |
2025-10-13 19:24:16.956131 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-13 19:24:16.956137 | controller | Monday 13 October 2025  19:24:16 +0000 (0:00:00.563)       0:00:02.817 ********
2025-10-13 19:24:16.956147 | controller | ok: [instance]
2025-10-13 19:24:16.956209 | controller |
2025-10-13 19:24:16.956218 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-13 19:24:16.956225 | controller | Monday 13 October 2025  19:24:16 +0000 (0:00:00.764)       0:00:03.581 ********
2025-10-13 19:24:18.151087 | controller | changed: [instance]
2025-10-13 19:24:18.151127 | controller |
2025-10-13 19:24:18.151352 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-13 19:24:27.408159 | controller | Monday 13 October 2025  19:24:18 +0000 (0:00:01.195)       0:00:04.777 ********
2025-10-13 19:24:27.408195 | controller | changed: [instance]
2025-10-13 19:24:28.121419 | controller |
2025-10-13 19:24:28.121458 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-13 19:24:28.121466 | controller | Monday 13 October 2025  19:24:27 +0000 (0:00:09.256)       0:00:14.033 ********
2025-10-13 19:24:28.121476 | controller | changed: [instance]
2025-10-13 19:24:28.141294 | controller |
2025-10-13 19:24:28.141317 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-13 19:24:28.141326 | controller | Monday 13 October 2025  19:24:28 +0000 (0:00:00.712)       0:00:14.746 ********
2025-10-13 19:24:28.141337 | controller | skipping: [instance]
2025-10-13 19:24:29.118573 | controller |
2025-10-13 19:24:29.118602 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-13 19:24:29.118610 | controller | Monday 13 October 2025  19:24:28 +0000 (0:00:00.020)       0:00:14.767 ********
2025-10-13 19:24:29.118619 | controller | changed: [instance]
2025-10-13 19:24:29.156058 | controller |
2025-10-13 19:24:29.156087 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-13 19:24:29.156096 | controller | Monday 13 October 2025  19:24:29 +0000 (0:00:00.977)       0:00:15.744 ********
2025-10-13 19:24:29.156107 | controller | skipping: [instance]
2025-10-13 19:24:29.156419 | controller |
2025-10-13 19:24:29.156433 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-13 19:24:29.194516 | controller | Monday 13 October 2025  19:24:29 +0000 (0:00:00.037)       0:00:15.782 ********
2025-10-13 19:24:29.194550 | controller | skipping: [instance]
2025-10-13 19:24:29.231344 | controller |
2025-10-13 19:24:29.231376 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-13 19:24:29.231385 | controller | Monday 13 October 2025  19:24:29 +0000 (0:00:00.037)       0:00:15.819 ********
2025-10-13 19:24:29.231396 | controller | skipping: [instance]
2025-10-13 19:24:29.231560 | controller |
2025-10-13 19:24:29.231705 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-13 19:24:29.231848 | controller | Monday 13 October 2025  19:24:29 +0000 (0:00:00.037)       0:00:15.857 ********
2025-10-13 19:24:29.798591 | controller | changed: [instance]
2025-10-13 19:24:30.230557 | controller |
2025-10-13 19:24:30.230586 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-13 19:24:30.230593 | controller | Monday 13 October 2025  19:24:29 +0000 (0:00:00.566)       0:00:16.424 ********
2025-10-13 19:24:30.230603 | controller | changed: [instance]
2025-10-13 19:24:30.258214 | controller |
2025-10-13 19:24:30.258236 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-13 19:24:30.258245 | controller | Monday 13 October 2025  19:24:30 +0000 (0:00:00.432)       0:00:16.856 ********
2025-10-13 19:24:30.258256 | controller | skipping: [instance]
2025-10-13 19:24:30.284622 | controller |
2025-10-13 19:24:30.284640 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-13 19:24:30.284647 | controller | Monday 13 October 2025  19:24:30 +0000 (0:00:00.027)       0:00:16.884 ********
2025-10-13 19:24:30.284658 | controller | skipping: [instance]
2025-10-13 19:24:30.311667 | controller |
2025-10-13 19:24:30.311686 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-13 19:24:30.311693 | controller | Monday 13 October 2025  19:24:30 +0000 (0:00:00.026)       0:00:16.910 ********
2025-10-13 19:24:30.311702 | controller | skipping: [instance]
2025-10-13 19:24:30.348031 | controller |
2025-10-13 19:24:30.348049 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-13 19:24:30.348057 | controller | Monday 13 October 2025  19:24:30 +0000 (0:00:00.026)       0:00:16.937 ********
2025-10-13 19:24:30.348065 | controller | ok: [instance]
2025-10-13 19:24:30.348092 | controller |
2025-10-13 19:24:30.348315 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-13 19:24:30.373424 | controller | Monday 13 October 2025  19:24:30 +0000 (0:00:00.036)       0:00:16.974 ********
2025-10-13 19:24:30.373445 | controller | skipping: [instance]
2025-10-13 19:24:30.398907 | controller |
2025-10-13 19:24:30.398943 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-13 19:24:30.398952 | controller | Monday 13 October 2025  19:24:30 +0000 (0:00:00.025)       0:00:16.999 ********
2025-10-13 19:24:30.398967 | controller | skipping: [instance]
2025-10-13 19:24:30.425707 | controller |
2025-10-13 19:24:30.425725 | controller | TASK [Download the RPM] ********************************************************
2025-10-13 19:24:30.425732 | controller | Monday 13 October 2025  19:24:30 +0000 (0:00:00.025)       0:00:17.024 ********
2025-10-13 19:24:30.425741 | controller | skipping: [instance]
2025-10-13 19:24:30.452441 | controller |
2025-10-13 19:24:30.452459 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-13 19:24:30.452466 | controller | Monday 13 October 2025  19:24:30 +0000 (0:00:00.026)       0:00:17.051 ********
2025-10-13 19:24:30.452475 | controller | skipping: [instance]
2025-10-13 19:24:30.478248 | controller |
2025-10-13 19:24:30.478269 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-13 19:24:30.478277 | controller | Monday 13 October 2025  19:24:30 +0000 (0:00:00.026)       0:00:17.078 ********
2025-10-13 19:24:30.478287 | controller | skipping: [instance]
2025-10-13 19:24:30.507999 | controller |
2025-10-13 19:24:30.508018 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-13 19:24:30.508025 | controller | Monday 13 October 2025  19:24:30 +0000 (0:00:00.025)       0:00:17.104 ********
2025-10-13 19:24:30.508034 | controller | skipping: [instance]
2025-10-13 19:24:30.533456 | controller |
2025-10-13 19:24:30.533478 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-13 19:24:30.533486 | controller | Monday 13 October 2025  19:24:30 +0000 (0:00:00.029)       0:00:17.134 ********
2025-10-13 19:24:30.533494 | controller | skipping: [instance]
2025-10-13 19:24:30.713758 | controller |
2025-10-13 19:24:30.713788 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-13 19:24:30.713796 | controller | Monday 13 October 2025  19:24:30 +0000 (0:00:00.025)       0:00:17.159 ********
2025-10-13 19:24:30.713806 | controller | ok: [instance]
2025-10-13 19:24:30.920251 | controller |
2025-10-13 19:24:30.920279 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-13 19:24:30.920287 | controller | Monday 13 October 2025  19:24:30 +0000 (0:00:00.179)       0:00:17.339 ********
2025-10-13 19:24:30.920297 | controller | changed: [instance]
2025-10-13 19:24:31.150222 | controller |
2025-10-13 19:24:31.150250 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-13 19:24:31.150258 | controller | Monday 13 October 2025  19:24:30 +0000 (0:00:00.206)       0:00:17.546 ********
2025-10-13 19:24:31.150268 | controller | changed: [instance]
2025-10-13 19:24:31.170623 | controller |
2025-10-13 19:24:31.170650 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-13 19:24:31.170658 | controller | Monday 13 October 2025  19:24:31 +0000 (0:00:00.229)       0:00:17.776 ********
2025-10-13 19:24:31.170667 | controller | skipping: [instance]
2025-10-13 19:24:31.192854 | controller |
2025-10-13 19:24:31.192877 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-13 19:24:31.192885 | controller | Monday 13 October 2025  19:24:31 +0000 (0:00:00.020)       0:00:17.796 ********
2025-10-13 19:24:31.192905 | controller | skipping: [instance]
2025-10-13 19:24:31.215542 | controller |
2025-10-13 19:24:31.215571 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-13 19:24:31.215579 | controller | Monday 13 October 2025  19:24:31 +0000 (0:00:00.022)       0:00:17.819 ********
2025-10-13 19:24:31.215589 | controller | skipping: [instance]
2025-10-13 19:24:31.238101 | controller |
2025-10-13 19:24:31.238127 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-13 19:24:31.238135 | controller | Monday 13 October 2025  19:24:31 +0000 (0:00:00.022)       0:00:17.841 ********
2025-10-13 19:24:31.238144 | controller | skipping: [instance]
2025-10-13 19:24:31.280036 | controller |
2025-10-13 19:24:31.280064 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-13 19:24:31.280077 | controller | Monday 13 October 2025  19:24:31 +0000 (0:00:00.022)       0:00:17.864 ********
2025-10-13 19:24:31.280087 | controller | skipping: [instance]
2025-10-13 19:24:31.307377 | controller |
2025-10-13 19:24:31.307401 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-13 19:24:31.307415 | controller | Monday 13 October 2025  19:24:31 +0000 (0:00:00.039)       0:00:17.903 ********
2025-10-13 19:24:31.307425 | controller | skipping: [instance]
2025-10-13 19:24:31.326063 | controller |
2025-10-13 19:24:31.326091 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-13 19:24:31.326100 | controller | Monday 13 October 2025  19:24:31 +0000 (0:00:00.029)       0:00:17.933 ********
2025-10-13 19:24:31.326111 | controller | skipping: [instance]
2025-10-13 19:24:31.326175 | controller |
2025-10-13 19:24:31.326191 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-13 19:24:31.326202 | controller | Monday 13 October 2025  19:24:31 +0000 (0:00:00.018)       0:00:17.952 ********
2025-10-13 19:24:31.356355 | controller | skipping: [instance]
2025-10-13 19:24:31.375242 | controller |
2025-10-13 19:24:31.375268 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-13 19:24:31.375276 | controller | Monday 13 October 2025  19:24:31 +0000 (0:00:00.030)       0:00:17.982 ********
2025-10-13 19:24:31.375285 | controller | skipping: [instance]
2025-10-13 19:24:31.397618 | controller |
2025-10-13 19:24:31.397644 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-13 19:24:31.397652 | controller | Monday 13 October 2025  19:24:31 +0000 (0:00:00.019)       0:00:18.001 ********
2025-10-13 19:24:31.397661 | controller | skipping: [instance]
2025-10-13 19:24:31.430272 | controller |
2025-10-13 19:24:31.430305 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-13 19:24:31.430313 | controller | Monday 13 October 2025  19:24:31 +0000 (0:00:00.022)       0:00:18.023 ********
2025-10-13 19:24:31.430325 | controller | skipping: [instance]
2025-10-13 19:24:31.430516 | controller |
2025-10-13 19:24:31.430694 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-13 19:24:31.430838 | controller | Monday 13 October 2025  19:24:31 +0000 (0:00:00.032)       0:00:18.056 ********
2025-10-13 19:24:31.461747 | controller | skipping: [instance]
2025-10-13 19:24:31.461965 | controller |
2025-10-13 19:24:31.462174 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-13 19:24:31.462323 | controller | Monday 13 October 2025  19:24:31 +0000 (0:00:00.031)       0:00:18.088 ********
2025-10-13 19:24:31.491749 | controller | skipping: [instance]
2025-10-13 19:24:31.491936 | controller |
2025-10-13 19:24:31.492126 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-13 19:24:31.492275 | controller | Monday 13 October 2025  19:24:31 +0000 (0:00:00.029)       0:00:18.118 ********
2025-10-13 19:24:31.521141 | controller | skipping: [instance]
2025-10-13 19:24:31.521306 | controller |
2025-10-13 19:24:31.521455 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-13 19:24:31.521596 | controller | Monday 13 October 2025  19:24:31 +0000 (0:00:00.029)       0:00:18.147 ********
2025-10-13 19:25:29.164062 | controller | ok: [instance]
2025-10-13 19:25:29.164281 | controller |
2025-10-13 19:25:29.164312 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-13 19:25:30.299928 | controller | Monday 13 October 2025  19:25:29 +0000 (0:00:57.642)       0:01:15.790 ********
2025-10-13 19:25:30.299965 | controller | ok: [instance]
2025-10-13 19:25:30.331048 | controller |
2025-10-13 19:25:30.331067 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-13 19:25:30.331074 | controller | Monday 13 October 2025  19:25:30 +0000 (0:00:01.135)       0:01:16.925 ********
2025-10-13 19:25:30.331083 | controller | skipping: [instance]
2025-10-13 19:25:30.331105 | controller |
2025-10-13 19:25:30.331111 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-13 19:25:30.331118 | controller | Monday 13 October 2025  19:25:30 +0000 (0:00:00.031)       0:01:16.957 ********
2025-10-13 19:25:30.872052 | controller | ok: [instance] => (item=tmp)
2025-10-13 19:25:31.986060 | controller | ok: [instance] => (item=artifacts/repositories)
2025-10-13 19:25:31.986088 | controller | ok: [instance] => (item=venv/repo_setup)
2025-10-13 19:25:31.986100 | controller |
2025-10-13 19:25:31.986107 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-13 19:25:31.986118 | controller | Monday 13 October 2025  19:25:30 +0000 (0:00:00.540)       0:01:17.497 ********
2025-10-13 19:25:31.986129 | controller | ok: [instance]
2025-10-13 19:25:32.440841 | controller |
2025-10-13 19:25:32.440870 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-13 19:25:32.440878 | controller | Monday 13 October 2025  19:25:31 +0000 (0:00:01.114)       0:01:18.612 ********
2025-10-13 19:25:32.440889 | controller | ok: [instance]
2025-10-13 19:25:33.626427 | controller |
2025-10-13 19:25:33.626458 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-13 19:25:33.626466 | controller | Monday 13 October 2025  19:25:32 +0000 (0:00:00.454)       0:01:19.066 ********
2025-10-13 19:25:33.626482 | controller | ok: [instance]
2025-10-13 19:25:33.829303 | controller |
2025-10-13 19:25:33.829333 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-13 19:25:33.829340 | controller | Monday 13 October 2025  19:25:33 +0000 (0:00:01.185)       0:01:20.252 ********
2025-10-13 19:25:33.829350 | controller | ok: [instance]
2025-10-13 19:25:33.850694 | controller |
2025-10-13 19:25:33.850713 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-13 19:25:33.850720 | controller | Monday 13 October 2025  19:25:33 +0000 (0:00:00.202)       0:01:20.455 ********
2025-10-13 19:25:33.850729 | controller | skipping: [instance]
2025-10-13 19:25:34.708097 | controller |
2025-10-13 19:25:34.708129 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-13 19:25:34.708138 | controller | Monday 13 October 2025  19:25:33 +0000 (0:00:00.021)       0:01:20.476 ********
2025-10-13 19:25:34.708148 | controller | changed: [instance]
2025-10-13 19:25:34.708369 | controller |
2025-10-13 19:25:34.708420 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-13 19:25:34.749409 | controller | Monday 13 October 2025  19:25:34 +0000 (0:00:00.857)       0:01:21.334 ********
2025-10-13 19:25:34.749463 | controller | skipping: [instance]
2025-10-13 19:25:34.790580 | controller |
2025-10-13 19:25:34.790634 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-13 19:25:34.790645 | controller | Monday 13 October 2025  19:25:34 +0000 (0:00:00.041)       0:01:21.375 ********
2025-10-13 19:25:34.790659 | controller | skipping: [instance]
2025-10-13 19:25:34.832295 | controller |
2025-10-13 19:25:34.832372 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-13 19:25:34.832382 | controller | Monday 13 October 2025  19:25:34 +0000 (0:00:00.040)       0:01:21.416 ********
2025-10-13 19:25:34.832397 | controller | skipping: [instance]
2025-10-13 19:25:34.832563 | controller |
2025-10-13 19:25:34.832716 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-13 19:25:34.832888 | controller | Monday 13 October 2025  19:25:34 +0000 (0:00:00.041)       0:01:21.457 ********
2025-10-13 19:25:35.439712 | controller | changed: [instance]
2025-10-13 19:25:35.849287 | controller |
2025-10-13 19:25:35.849346 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-13 19:25:35.849356 | controller | Monday 13 October 2025  19:25:35 +0000 (0:00:00.607)       0:01:22.065 ********
2025-10-13 19:25:35.849369 | controller | changed: [instance]
2025-10-13 19:25:35.889733 | controller |
2025-10-13 19:25:35.889806 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-13 19:25:35.889815 | controller | Monday 13 October 2025  19:25:35 +0000 (0:00:00.409)       0:01:22.474 ********
2025-10-13 19:25:35.889829 | controller | skipping: [instance]
2025-10-13 19:25:35.931867 | controller |
2025-10-13 19:25:35.931910 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-13 19:25:35.931918 | controller | Monday 13 October 2025  19:25:35 +0000 (0:00:00.040)       0:01:22.515 ********
2025-10-13 19:25:35.931929 | controller | skipping: [instance]
2025-10-13 19:25:35.963574 | controller |
2025-10-13 19:25:35.963605 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-13 19:25:35.963613 | controller | Monday 13 October 2025  19:25:35 +0000 (0:00:00.042)       0:01:22.557 ********
2025-10-13 19:25:35.963624 | controller | skipping: [instance]
2025-10-13 19:25:35.963785 | controller |
2025-10-13 19:25:35.963939 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-13 19:25:35.964114 | controller | Monday 13 October 2025  19:25:35 +0000 (0:00:00.032)       0:01:22.589 ********
2025-10-13 19:25:36.005352 | controller | ok: [instance]
2025-10-13 19:25:36.005591 | controller |
2025-10-13 19:25:36.005799 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-13 19:25:36.006001 | controller | Monday 13 October 2025  19:25:36 +0000 (0:00:00.041)       0:01:22.631 ********
2025-10-13 19:25:36.048244 | controller | skipping: [instance]
2025-10-13 19:25:36.048426 | controller |
2025-10-13 19:25:36.048587 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-13 19:25:36.048735 | controller | Monday 13 October 2025  19:25:36 +0000 (0:00:00.042)       0:01:22.673 ********
2025-10-13 19:25:36.079644 | controller | skipping: [instance]
2025-10-13 19:25:36.108558 | controller |
2025-10-13 19:25:36.108580 | controller | TASK [Download the RPM] ********************************************************
2025-10-13 19:25:36.108589 | controller | Monday 13 October 2025  19:25:36 +0000 (0:00:00.031)       0:01:22.705 ********
2025-10-13 19:25:36.108600 | controller | skipping: [instance]
2025-10-13 19:25:36.137460 | controller |
2025-10-13 19:25:36.137476 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-13 19:25:36.137488 | controller | Monday 13 October 2025  19:25:36 +0000 (0:00:00.029)       0:01:22.734 ********
2025-10-13 19:25:36.137497 | controller | skipping: [instance]
2025-10-13 19:25:36.172030 | controller |
2025-10-13 19:25:36.172052 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-13 19:25:36.172061 | controller | Monday 13 October 2025  19:25:36 +0000 (0:00:00.028)       0:01:22.763 ********
2025-10-13 19:25:36.172072 | controller | skipping: [instance]
2025-10-13 19:25:36.172418 | controller |
2025-10-13 19:25:36.206890 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-13 19:25:36.206908 | controller | Monday 13 October 2025  19:25:36 +0000 (0:00:00.033)       0:01:22.797 ********
2025-10-13 19:25:36.206918 | controller | skipping: [instance]
2025-10-13 19:25:36.239107 | controller |
2025-10-13 19:25:36.239142 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-13 19:25:36.239151 | controller | Monday 13 October 2025  19:25:36 +0000 (0:00:00.035)       0:01:22.832 ********
2025-10-13 19:25:36.239161 | controller | skipping: [instance]
2025-10-13 19:25:36.239179 | controller |
2025-10-13 19:25:36.239185 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-13 19:25:36.239192 | controller | Monday 13 October 2025  19:25:36 +0000 (0:00:00.032)       0:01:22.865 ********
2025-10-13 19:25:36.433023 | controller | ok: [instance]
2025-10-13 19:25:36.678539 | controller |
2025-10-13 19:25:36.678577 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-13 19:25:36.678585 | controller | Monday 13 October 2025  19:25:36 +0000 (0:00:00.193)       0:01:23.058 ********
2025-10-13 19:25:36.678596 | controller | changed: [instance]
2025-10-13 19:25:36.900911 | controller |
2025-10-13 19:25:36.900941 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-13 19:25:36.900949 | controller | Monday 13 October 2025  19:25:36 +0000 (0:00:00.245)       0:01:23.304 ********
2025-10-13 19:25:36.900959 | controller | changed: [instance]
2025-10-13 19:25:36.923716 | controller |
2025-10-13 19:25:36.923739 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-13 19:25:36.923746 | controller | Monday 13 October 2025  19:25:36 +0000 (0:00:00.222)       0:01:23.526 ********
2025-10-13 19:25:36.923756 | controller | skipping: [instance]
2025-10-13 19:25:36.961528 | controller |
2025-10-13 19:25:36.961551 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-13 19:25:36.961559 | controller | Monday 13 October 2025  19:25:36 +0000 (0:00:00.023)       0:01:23.549 ********
2025-10-13 19:25:36.961570 | controller | skipping: [instance]
2025-10-13 19:25:36.999077 | controller |
2025-10-13 19:25:36.999101 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-13 19:25:36.999109 | controller | Monday 13 October 2025  19:25:36 +0000 (0:00:00.037)       0:01:23.587 ********
2025-10-13 19:25:36.999119 | controller | skipping: [instance]
2025-10-13 19:25:36.999131 | controller |
2025-10-13 19:25:36.999137 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-13 19:25:36.999145 | controller | Monday 13 October 2025  19:25:36 +0000 (0:00:00.037)       0:01:23.625 ********
2025-10-13 19:25:37.036948 | controller | skipping: [instance]
2025-10-13 19:25:37.074262 | controller |
2025-10-13 19:25:37.074280 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-13 19:25:37.074287 | controller | Monday 13 October 2025  19:25:37 +0000 (0:00:00.037)       0:01:23.662 ********
2025-10-13 19:25:37.074296 | controller | skipping: [instance]
2025-10-13 19:25:37.111017 | controller |
2025-10-13 19:25:37.111036 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-13 19:25:37.111043 | controller | Monday 13 October 2025  19:25:37 +0000 (0:00:00.037)       0:01:23.700 ********
2025-10-13 19:25:37.111053 | controller | skipping: [instance]
2025-10-13 19:25:37.111172 | controller |
2025-10-13 19:25:37.111183 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-13 19:25:37.111191 | controller | Monday 13 October 2025  19:25:37 +0000 (0:00:00.036)       0:01:23.737 ********
2025-10-13 19:25:37.407999 | controller | ok: [instance]
2025-10-13 19:25:38.887620 | controller |
2025-10-13 19:25:38.887651 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-13 19:25:38.887659 | controller | Monday 13 October 2025  19:25:37 +0000 (0:00:00.296)       0:01:24.033 ********
2025-10-13 19:25:38.887669 | controller | changed: [instance] => (item=/etc/yum.repos.d/centos-addons.repo)
2025-10-13 19:25:39.337129 | controller | changed: [instance] => (item=/etc/yum.repos.d/centos.repo)
2025-10-13 19:25:39.337158 | controller | changed: [instance] => (item=/etc/yum.repos.d/delorean.repo)
2025-10-13 19:25:39.337166 | controller | changed: [instance] => (item=/etc/yum.repos.d/delorean-master-testing.repo)
2025-10-13 19:25:39.337172 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-highavailability.repo)
2025-10-13 19:25:39.337178 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-powertools.repo)
2025-10-13 19:25:39.337183 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-appstream.repo)
2025-10-13 19:25:39.337188 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-baseos.repo)
2025-10-13 19:25:39.337194 | controller |
2025-10-13 19:25:39.337199 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-13 19:25:39.337205 | controller | Monday 13 October 2025  19:25:38 +0000 (0:00:01.478)       0:01:25.512 ********
2025-10-13 19:25:39.337214 | controller | changed: [instance]
2025-10-13 19:25:39.573089 | controller |
2025-10-13 19:25:39.573124 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-13 19:25:39.573141 | controller | Monday 13 October 2025  19:25:39 +0000 (0:00:00.450)       0:01:25.963 ********
2025-10-13 19:25:39.573155 | controller | changed: [instance]
2025-10-13 19:25:39.573186 | controller |
2025-10-13 19:25:39.573195 | controller | PLAY RECAP *********************************************************************
2025-10-13 19:25:39.573202 | controller | instance                   : ok=35   changed=17   unreachable=0    failed=0    skipped=50   rescued=0    ignored=0
2025-10-13 19:25:39.573210 | controller |
2025-10-13 19:25:39.573220 | controller | Monday 13 October 2025  19:25:39 +0000 (0:00:00.235)       0:01:26.198 ********
2025-10-13 19:25:39.575048 | controller | ===============================================================================
2025-10-13 19:25:39.575280 | controller | test_deps : Install selinux python libs -------------------------------- 57.64s
2025-10-13 19:25:39.575296 | controller | repo_setup : Initialize python venv and install requirements ------------ 9.26s
2025-10-13 19:25:39.575303 | controller | repo_setup : Remove existing repos from /etc/yum.repos.d directory ------ 1.48s
2025-10-13 19:25:39.575308 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.20s
2025-10-13 19:25:39.575314 | controller | repo_setup : Initialize python venv and install requirements ------------ 1.19s
2025-10-13 19:25:39.575319 | controller | test_deps : Install python yaml libs ------------------------------------ 1.14s
2025-10-13 19:25:39.575329 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.11s
2025-10-13 19:25:39.575335 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.98s
2025-10-13 19:25:39.575340 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.86s
2025-10-13 19:25:39.575345 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-10-13 19:25:39.575351 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.76s
2025-10-13 19:25:39.575356 | controller | repo_setup : Install repo-setup package --------------------------------- 0.71s
2025-10-13 19:25:39.575361 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.68s
2025-10-13 19:25:39.575367 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.61s
2025-10-13 19:25:39.575372 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s
2025-10-13 19:25:39.575377 | controller | repo_setup : Ensure directories are present ----------------------------- 0.56s
2025-10-13 19:25:39.575382 | controller | repo_setup : Ensure directories are present ----------------------------- 0.54s
2025-10-13 19:25:39.575391 | controller | repo_setup : Get repo-setup repository ---------------------------------- 0.45s
2025-10-13 19:25:39.633560 | controller | repo_setup : Cleanup existing metadata ---------------------------------- 0.45s
2025-10-13 19:25:39.633592 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.43s
2025-10-13 19:25:39.633605 | controller | INFO     Running default > converge
2025-10-13 19:25:40.112690 | controller |
2025-10-13 19:25:40.867636 | controller | PLAY [Converge] ****************************************************************
2025-10-13 19:25:40.867667 | controller |
2025-10-13 19:25:40.867675 | controller | TASK [Gathering Facts] *********************************************************
2025-10-13 19:25:40.867681 | controller | Monday 13 October 2025  19:25:40 +0000 (0:00:00.032)       0:00:00.032 ********
2025-10-13 19:25:40.867691 | controller | ok: [instance]
2025-10-13 19:25:42.326705 | controller |
2025-10-13 19:25:42.326733 | controller | TASK [Clone neutron-tempest-plugin] ********************************************
2025-10-13 19:25:42.326741 | controller | Monday 13 October 2025  19:25:40 +0000 (0:00:00.755)       0:00:00.787 ********
2025-10-13 19:25:42.326750 | controller | changed: [instance]
2025-10-13 19:25:42.351632 | controller |
2025-10-13 19:25:42.351653 | controller | TASK [Set cifmw_bop_openstack_project_path] ************************************
2025-10-13 19:25:42.351665 | controller | Monday 13 October 2025  19:25:42 +0000 (0:00:01.459)       0:00:02.247 ********
2025-10-13 19:25:42.351675 | controller | ok: [instance]
2025-10-13 19:26:09.912053 | controller |
2025-10-13 19:26:09.912084 | controller | TASK [build_openstack_packages : Install Mock from DLRN repos] *****************
2025-10-13 19:26:09.912092 | controller | Monday 13 October 2025  19:25:42 +0000 (0:00:00.025)       0:00:02.272 ********
2025-10-13 19:26:09.912102 | controller | changed: [instance]
2025-10-13 19:26:09.912125 | controller |
2025-10-13 19:26:09.912133 | controller | TASK [build_openstack_packages : Ensure DLRN package dependencies] *************
2025-10-13 19:26:09.912259 | controller | Monday 13 October 2025  19:26:09 +0000 (0:00:27.560)       0:00:29.832 ********
2025-10-13 19:26:11.303515 | controller | changed: [instance]
2025-10-13 19:26:11.303560 | controller |
2025-10-13 19:26:11.303570 | controller | TASK [build_openstack_packages : Create mock group] ****************************
2025-10-13 19:26:11.303690 | controller | Monday 13 October 2025  19:26:11 +0000 (0:00:01.391)       0:00:31.224 ********
2025-10-13 19:26:11.729108 | controller | ok: [instance]
2025-10-13 19:26:12.109265 | controller |
2025-10-13 19:26:12.109294 | controller | TASK [build_openstack_packages : Add user to mock group] ***********************
2025-10-13 19:26:12.109302 | controller | Monday 13 October 2025  19:26:11 +0000 (0:00:00.425)       0:00:31.649 ********
2025-10-13 19:26:12.109312 | controller | changed: [instance]
2025-10-13 19:26:12.411018 | controller |
2025-10-13 19:26:12.411047 | controller | TASK [build_openstack_packages : Ensure DLRN dir is present] *******************
2025-10-13 19:26:12.411055 | controller | Monday 13 October 2025  19:26:12 +0000 (0:00:00.380)       0:00:32.029 ********
2025-10-13 19:26:12.411065 | controller | changed: [instance]
2025-10-13 19:26:12.705678 | controller |
2025-10-13 19:26:12.705707 | controller | TASK [build_openstack_packages : Remove data files from previous runs] *********
2025-10-13 19:26:12.705716 | controller | Monday 13 October 2025  19:26:12 +0000 (0:00:00.301)       0:00:32.331 ********
2025-10-13 19:26:12.705725 | controller | ok: [instance]
2025-10-13 19:26:16.518593 | controller |
2025-10-13 19:26:16.518623 | controller | TASK [build_openstack_packages : Create dlrn-venv] *****************************
2025-10-13 19:26:16.518630 | controller | Monday 13 October 2025  19:26:12 +0000 (0:00:00.294)       0:00:32.626 ********
2025-10-13 19:26:16.518640 | controller | changed: [instance]
2025-10-13 19:26:16.544967 | controller |
2025-10-13 19:26:16.545007 | controller | TASK [build_openstack_packages : Fetch DLRN from source] ***********************
2025-10-13 19:26:16.545015 | controller | Monday 13 October 2025  19:26:16 +0000 (0:00:03.812)       0:00:36.439 ********
2025-10-13 19:26:16.545025 | controller | skipping: [instance]
2025-10-13 19:26:16.570695 | controller |
2025-10-13 19:26:16.570716 | controller | TASK [build_openstack_packages : Install DLRN requirements] ********************
2025-10-13 19:26:16.570723 | controller | Monday 13 October 2025  19:26:16 +0000 (0:00:00.026)       0:00:36.465 ********
2025-10-13 19:26:16.570732 | controller | skipping: [instance]
2025-10-13 19:26:16.597634 | controller |
2025-10-13 19:26:16.597655 | controller | TASK [build_openstack_packages : Install DLRN from source] *********************
2025-10-13 19:26:16.597662 | controller | Monday 13 October 2025  19:26:16 +0000 (0:00:00.025)       0:00:36.491 ********
2025-10-13 19:26:16.597671 | controller | skipping: [instance]
2025-10-13 19:26:32.034544 | controller |
2025-10-13 19:26:32.034574 | controller | TASK [build_openstack_packages : Install DLRN and rdopkg from pip] *************
2025-10-13 19:26:32.034582 | controller | Monday 13 October 2025  19:26:16 +0000 (0:00:00.026)       0:00:36.518 ********
2025-10-13 19:26:32.034592 | controller | changed: [instance]
2025-10-13 19:26:32.604818 | controller |
2025-10-13 19:26:32.604846 | controller | TASK [build_openstack_packages : Drop in the templated version of projects.ini] ***
2025-10-13 19:26:32.604854 | controller | Monday 13 October 2025  19:26:32 +0000 (0:00:15.436)       0:00:51.955 ********
2025-10-13 19:26:32.604864 | controller | changed: [instance]
2025-10-13 19:26:32.969956 | controller |
2025-10-13 19:26:32.969999 | controller | TASK [build_openstack_packages : Copy the DLRN scripts in the virtualenv to the scripts dir] ***
2025-10-13 19:26:32.970008 | controller | Monday 13 October 2025  19:26:32 +0000 (0:00:00.570)       0:00:52.525 ********
2025-10-13 19:26:32.970018 | controller | changed: [instance]
2025-10-13 19:26:35.896700 | controller |
2025-10-13 19:26:35.896729 | controller | TASK [build_openstack_packages : Fetch local rdoinfo copy] *********************
2025-10-13 19:26:35.896737 | controller | Monday 13 October 2025  19:26:32 +0000 (0:00:00.365)       0:00:52.890 ********
2025-10-13 19:26:35.896747 | controller | changed: [instance]
2025-10-13 19:26:35.917780 | controller |
2025-10-13 19:26:35.917801 | controller | TASK [build_openstack_packages : Downstream related tasks] *********************
2025-10-13 19:26:35.917809 | controller | Monday 13 October 2025  19:26:35 +0000 (0:00:02.926)       0:00:55.817 ********
2025-10-13 19:26:35.917819 | controller | skipping: [instance]
2025-10-13 19:26:36.190059 | controller |
2025-10-13 19:26:36.190088 | controller | TASK [build_openstack_packages : Find all the repos files] *********************
2025-10-13 19:26:36.190103 | controller | Monday 13 October 2025  19:26:35 +0000 (0:00:00.021)       0:00:55.838 ********
2025-10-13 19:26:36.190113 | controller | ok: [instance]
2025-10-13 19:26:36.190307 | controller |
2025-10-13 19:26:36.190341 | controller | TASK [build_openstack_packages : Create local mock config] *********************
2025-10-13 19:26:36.389046 | controller | Monday 13 October 2025  19:26:36 +0000 (0:00:00.272)       0:00:56.110 ********
2025-10-13 19:26:36.389079 | controller | changed: [instance]
2025-10-13 19:26:36.389104 | controller |
2025-10-13 19:26:36.389112 | controller | TASK [build_openstack_packages : Remove last """ from local mock config] *******
2025-10-13 19:26:36.389229 | controller | Monday 13 October 2025  19:26:36 +0000 (0:00:00.199)       0:00:56.309 ********
2025-10-13 19:26:36.584069 | controller | changed: [instance]
2025-10-13 19:26:36.584199 | controller |
2025-10-13 19:26:36.584225 | controller | TASK [build_openstack_packages : Append repos into mock local config] **********
2025-10-13 19:26:36.584236 | controller | Monday 13 October 2025  19:26:36 +0000 (0:00:00.194)       0:00:56.504 ********
2025-10-13 19:26:36.638118 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/append_repos.yml for instance => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/artifacts/repositories/delorean.repo)
2025-10-13 19:26:36.638183 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/append_repos.yml for instance => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/artifacts/repositories/delorean-antelope-testing.repo)
2025-10-13 19:26:36.638197 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/append_repos.yml for instance => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/artifacts/repositories/repo-setup-centos-highavailability.repo)
2025-10-13 19:26:36.638207 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/append_repos.yml for instance => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/artifacts/repositories/repo-setup-centos-powertools.repo)
2025-10-13 19:26:36.638213 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/append_repos.yml for instance => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/artifacts/repositories/repo-setup-centos-appstream.repo)
2025-10-13 19:26:36.638220 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/append_repos.yml for instance => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/artifacts/repositories/repo-setup-centos-baseos.repo)
2025-10-13 19:26:36.638227 | controller |
2025-10-13 19:26:36.638234 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-10-13 19:26:36.638244 | controller | Monday 13 October 2025  19:26:36 +0000 (0:00:00.053)       0:00:56.558 ********
2025-10-13 19:26:36.884265 | controller | ok: [instance]
2025-10-13 19:26:36.915007 | controller |
2025-10-13 19:26:36.915028 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-10-13 19:26:36.915037 | controller | Monday 13 October 2025  19:26:36 +0000 (0:00:00.246)       0:00:56.804 ********
2025-10-13 19:26:36.915049 | controller | ok: [instance]
2025-10-13 19:26:37.082909 | controller |
2025-10-13 19:26:37.082938 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-10-13 19:26:37.082946 | controller | Monday 13 October 2025  19:26:36 +0000 (0:00:00.030)       0:00:56.835 ********
2025-10-13 19:26:37.082956 | controller | ok: [instance]
2025-10-13 19:26:37.114144 | controller |
2025-10-13 19:26:37.114172 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-10-13 19:26:37.114182 | controller | Monday 13 October 2025  19:26:37 +0000 (0:00:00.167)       0:00:57.003 ********
2025-10-13 19:26:37.114193 | controller | ok: [instance]
2025-10-13 19:26:37.283153 | controller |
2025-10-13 19:26:37.283181 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-10-13 19:26:37.283189 | controller | Monday 13 October 2025  19:26:37 +0000 (0:00:00.031)       0:00:57.034 ********
2025-10-13 19:26:37.283199 | controller | ok: [instance]
2025-10-13 19:26:37.312642 | controller |
2025-10-13 19:26:37.312665 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-10-13 19:26:37.312687 | controller | Monday 13 October 2025  19:26:37 +0000 (0:00:00.169)       0:00:57.203 ********
2025-10-13 19:26:37.312698 | controller | ok: [instance]
2025-10-13 19:26:37.482904 | controller |
2025-10-13 19:26:37.482935 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-10-13 19:26:37.482943 | controller | Monday 13 October 2025  19:26:37 +0000 (0:00:00.029)       0:00:57.233 ********
2025-10-13 19:26:37.482953 | controller | ok: [instance]
2025-10-13 19:26:37.512948 | controller |
2025-10-13 19:26:37.512997 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-10-13 19:26:37.513009 | controller | Monday 13 October 2025  19:26:37 +0000 (0:00:00.170)       0:00:57.403 ********
2025-10-13 19:26:37.513032 | controller | ok: [instance]
2025-10-13 19:26:37.682051 | controller |
2025-10-13 19:26:37.682081 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-10-13 19:26:37.682089 | controller | Monday 13 October 2025  19:26:37 +0000 (0:00:00.030)       0:00:57.433 ********
2025-10-13 19:26:37.682099 | controller | ok: [instance]
2025-10-13 19:26:37.682281 | controller |
2025-10-13 19:26:37.682314 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-10-13 19:26:37.713786 | controller | Monday 13 October 2025  19:26:37 +0000 (0:00:00.169)       0:00:57.602 ********
2025-10-13 19:26:37.713810 | controller | ok: [instance]
2025-10-13 19:26:37.892468 | controller |
2025-10-13 19:26:37.892499 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-10-13 19:26:37.892507 | controller | Monday 13 October 2025  19:26:37 +0000 (0:00:00.031)       0:00:57.634 ********
2025-10-13 19:26:37.892517 | controller | ok: [instance]
2025-10-13 19:26:37.922824 | controller |
2025-10-13 19:26:37.922846 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-10-13 19:26:37.922855 | controller | Monday 13 October 2025  19:26:37 +0000 (0:00:00.178)       0:00:57.813 ********
2025-10-13 19:26:37.922865 | controller | ok: [instance]
2025-10-13 19:26:38.222922 | controller |
2025-10-13 19:26:38.222953 | controller | TASK [build_openstack_packages : Append repo_content ending with """ in mock local config at last] ***
2025-10-13 19:26:38.222966 | controller | Monday 13 October 2025  19:26:37 +0000 (0:00:00.030)       0:00:57.843 ********
2025-10-13 19:26:38.222992 | controller | changed: [instance]
2025-10-13 19:26:40.155495 | controller |
2025-10-13 19:26:40.155526 | controller | TASK [build_openstack_packages : Check loop devices stat] **********************
2025-10-13 19:26:40.155534 | controller | Monday 13 October 2025  19:26:38 +0000 (0:00:00.299)       0:00:58.143 ********
2025-10-13 19:26:40.155545 | controller | ok: [instance] => (item=0)
2025-10-13 19:26:40.192400 | controller | ok: [instance] => (item=1)
2025-10-13 19:26:40.192423 | controller | ok: [instance] => (item=2)
2025-10-13 19:26:40.192430 | controller | ok: [instance] => (item=3)
2025-10-13 19:26:40.192436 | controller | ok: [instance] => (item=4)
2025-10-13 19:26:40.192441 | controller | ok: [instance] => (item=5)
2025-10-13 19:26:40.192446 | controller | ok: [instance] => (item=6)
2025-10-13 19:26:40.192452 | controller | ok: [instance] => (item=7)
2025-10-13 19:26:40.192457 | controller | ok: [instance] => (item=8)
2025-10-13 19:26:40.192462 | controller | ok: [instance] => (item=9)
2025-10-13 19:26:40.192467 | controller | ok: [instance] => (item=10)
2025-10-13 19:26:40.192472 | controller | ok: [instance] => (item=11)
2025-10-13 19:26:40.192478 | controller |
2025-10-13 19:26:40.192484 | controller | TASK [build_openstack_packages : Set loop_devices_absent fact] *****************
2025-10-13 19:26:40.192490 | controller | Monday 13 October 2025  19:26:40 +0000 (0:00:01.931)       0:01:00.075 ********
2025-10-13 19:26:40.192499 | controller | ok: [instance]
2025-10-13 19:26:40.226059 | controller |
2025-10-13 19:26:40.226083 | controller | TASK [build_openstack_packages : Parse Zuul changes] ***************************
2025-10-13 19:26:40.226093 | controller | Monday 13 October 2025  19:26:40 +0000 (0:00:00.038)       0:01:00.113 ********
2025-10-13 19:26:40.226104 | controller | skipping: [instance]
2025-10-13 19:26:40.226139 | controller |
2025-10-13 19:26:40.226151 | controller | TASK [build_openstack_packages : Print Zuul change list] ***********************
2025-10-13 19:26:40.226268 | controller | Monday 13 October 2025  19:26:40 +0000 (0:00:00.033)       0:01:00.147 ********
2025-10-13 19:26:40.246081 | controller | ok: [instance] =>
2025-10-13 19:26:40.246112 | controller |   cifmw_bop_change_list: []
2025-10-13 19:26:40.246120 | controller |
2025-10-13 19:26:40.246126 | controller | TASK [build_openstack_packages : Build DLRN packages from zuul changes] ********
2025-10-13 19:26:40.246134 | controller | Monday 13 October 2025  19:26:40 +0000 (0:00:00.019)       0:01:00.166 ********
2025-10-13 19:26:40.260679 | controller | skipping: [instance]
2025-10-13 19:26:40.311411 | controller |
2025-10-13 19:26:40.311440 | controller | TASK [build_openstack_packages : Build DLRN packages from Local change] ********
2025-10-13 19:26:40.311448 | controller | Monday 13 October 2025  19:26:40 +0000 (0:00:00.014)       0:01:00.181 ********
2025-10-13 19:26:40.311458 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/run_dlrn.yml for instance
2025-10-13 19:26:40.339825 | controller |
2025-10-13 19:26:40.339853 | controller | TASK [build_openstack_packages : Set proper branch name for stable opendev branches] ***
2025-10-13 19:26:40.339860 | controller | Monday 13 October 2025  19:26:40 +0000 (0:00:00.050)       0:01:00.232 ********
2025-10-13 19:26:40.339870 | controller | skipping: [instance]
2025-10-13 19:26:40.370062 | controller |
2025-10-13 19:26:40.370087 | controller | TASK [build_openstack_packages : Set proper branch name for non-stable branches] ***
2025-10-13 19:26:40.370095 | controller | Monday 13 October 2025  19:26:40 +0000 (0:00:00.028)       0:01:00.260 ********
2025-10-13 19:26:40.370105 | controller | ok: [instance]
2025-10-13 19:26:40.370251 | controller |
2025-10-13 19:26:40.370418 | controller | TASK [build_openstack_packages : Set RDO release for master openstack release] ***
2025-10-13 19:26:40.370560 | controller | Monday 13 October 2025  19:26:40 +0000 (0:00:00.030)       0:01:00.291 ********
2025-10-13 19:26:40.389651 | controller | ok: [instance]
2025-10-13 19:26:40.389839 | controller |
2025-10-13 19:26:40.390015 | controller | TASK [build_openstack_packages : Set RDO release for non-master openstack release] ***
2025-10-13 19:26:40.390165 | controller | Monday 13 October 2025  19:26:40 +0000 (0:00:00.019)       0:01:00.310 ********
2025-10-13 19:26:40.405825 | controller | skipping: [instance]
2025-10-13 19:26:40.406035 | controller |
2025-10-13 19:26:40.406228 | controller | TASK [build_openstack_packages : Make sure /usr/share/rhn/RHN-ORG-TRUSTED-SSL-CERT exists (RHEL)] ***
2025-10-13 19:26:40.406398 | controller | Monday 13 October 2025  19:26:40 +0000 (0:00:00.016)       0:01:00.326 ********
2025-10-13 19:26:40.422056 | controller | skipping: [instance]
2025-10-13 19:26:40.422104 | controller |
2025-10-13 19:26:40.422114 | controller | TASK [build_openstack_packages : Override projects.ini settings] ***************
2025-10-13 19:26:40.422235 | controller | Monday 13 October 2025  19:26:40 +0000 (0:00:00.016)       0:01:00.343 ********
2025-10-13 19:26:40.951831 | controller | ok: [instance] => (item={'regexp': 'baseurl=.*', 'line': 'baseurl=https://trunk.rdoproject.org/centos9-master'})
2025-10-13 19:26:44.463676 | controller | ok: [instance] => (item={'regexp': 'distro=.*', 'line': 'distro=rpm-master'})
2025-10-13 19:26:44.463704 | controller | ok: [instance] => (item={'regexp': 'source=.*', 'line': 'source=master'})
2025-10-13 19:26:44.463712 | controller |
2025-10-13 19:26:44.463718 | controller | TASK [build_openstack_packages : Map project name to DLRN project name] ********
2025-10-13 19:26:44.463723 | controller | Monday 13 October 2025  19:26:40 +0000 (0:00:00.528)       0:01:00.871 ********
2025-10-13 19:26:44.463733 | controller | ok: [instance]
2025-10-13 19:26:44.665754 | controller |
2025-10-13 19:26:44.665800 | controller | TASK [build_openstack_packages : Create data directory if doesn't exist yet] ***
2025-10-13 19:26:44.665808 | controller | Monday 13 October 2025  19:26:44 +0000 (0:00:03.512)       0:01:04.384 ********
2025-10-13 19:26:44.665818 | controller | changed: [instance]
2025-10-13 19:26:44.856639 | controller |
2025-10-13 19:26:44.856668 | controller | TASK [build_openstack_packages : Check for existing python-neutron-tests-tempest] ***
2025-10-13 19:26:44.856676 | controller | Monday 13 October 2025  19:26:44 +0000 (0:00:00.202)       0:01:04.586 ********
2025-10-13 19:26:44.856686 | controller | ok: [instance]
2025-10-13 19:26:45.397390 | controller |
2025-10-13 19:26:45.397419 | controller | TASK [build_openstack_packages : Sync python-neutron-tests-tempest from local repo] ***
2025-10-13 19:26:45.397431 | controller | Monday 13 October 2025  19:26:44 +0000 (0:00:00.190)       0:01:04.777 ********
2025-10-13 19:26:45.397442 | controller | changed: [instance]
2025-10-13 19:26:45.428409 | controller |
2025-10-13 19:26:45.428435 | controller | TASK [build_openstack_packages : Symlink python-neutron-tests-tempest from Zuul clonned repos for upstream] ***
2025-10-13 19:26:45.428443 | controller | Monday 13 October 2025  19:26:45 +0000 (0:00:00.540)       0:01:05.318 ********
2025-10-13 19:26:45.428454 | controller | skipping: [instance]
2025-10-13 19:26:45.458242 | controller |
2025-10-13 19:26:45.458271 | controller | TASK [build_openstack_packages : Update packages.yml to use zuul repo for python-neutron-tests-tempest] ***
2025-10-13 19:26:45.458280 | controller | Monday 13 October 2025  19:26:45 +0000 (0:00:00.030)       0:01:05.349 ********
2025-10-13 19:26:45.458292 | controller | skipping: [instance]
2025-10-13 19:26:45.679024 | controller |
2025-10-13 19:26:45.679052 | controller | TASK [build_openstack_packages : Find the last commit from the clonned repo] ***
2025-10-13 19:26:45.679066 | controller | Monday 13 October 2025  19:26:45 +0000 (0:00:00.029)       0:01:05.378 ********
2025-10-13 19:26:45.679076 | controller | changed: [instance]
2025-10-13 19:26:45.701070 | controller |
2025-10-13 19:26:45.701099 | controller | TASK [build_openstack_packages : Print the last commit of the git repo] ********
2025-10-13 19:26:45.701107 | controller | Monday 13 October 2025  19:26:45 +0000 (0:00:00.220)       0:01:05.599 ********
2025-10-13 19:26:45.701116 | controller | ok: [instance] =>
2025-10-13 19:26:45.701137 | controller |   _commit.stdout: 27c0c5ba5abc21aa340e27a7d5e8981ab3249d33
2025-10-13 19:26:45.701144 | controller |
2025-10-13 19:26:45.701150 | controller | TASK [build_openstack_packages : Ensure distgit repo is absent, DLRN takes care of cloning based on config] ***
2025-10-13 19:26:45.701157 | controller | Monday 13 October 2025  19:26:45 +0000 (0:00:00.022)       0:01:05.621 ********
2025-10-13 19:26:45.889061 | controller | ok: [instance]
2025-10-13 19:26:45.889338 | controller |
2025-10-13 19:26:46.234204 | controller | TASK [build_openstack_packages : Generate DLRN Script] *************************
2025-10-13 19:26:46.234235 | controller | Monday 13 October 2025  19:26:45 +0000 (0:00:00.188)       0:01:05.810 ********
2025-10-13 19:26:46.234246 | controller | changed: [instance]
2025-10-13 19:26:46.266052 | controller |
2025-10-13 19:26:46.266081 | controller | TASK [build_openstack_packages : Print info about building] ********************
2025-10-13 19:26:46.266090 | controller | Monday 13 October 2025  19:26:46 +0000 (0:00:00.344)       0:01:06.154 ********
2025-10-13 19:26:46.266102 | controller | ok: [instance] =>
2025-10-13 19:26:46.266128 | controller |   msg: Building change for python-neutron-tests-tempest
2025-10-13 19:26:46.266136 | controller |
2025-10-13 19:26:46.266145 | controller | TASK [build_openstack_packages : Ensure logs dir exists] ***********************
2025-10-13 19:26:46.266233 | controller | Monday 13 October 2025  19:26:46 +0000 (0:00:00.032)       0:01:06.186 ********
2025-10-13 19:26:46.447551 | controller | ok: [instance]
2025-10-13 19:33:38.312960 | controller |
2025-10-13 19:33:38.313009 | controller | TASK [build_openstack_packages : Run DLRN] *************************************
2025-10-13 19:33:38.313018 | controller | Monday 13 October 2025  19:26:46 +0000 (0:00:00.181)       0:01:06.368 ********
2025-10-13 19:33:38.313029 | controller | changed: [instance]
2025-10-13 19:33:38.339919 | controller |
2025-10-13 19:33:38.339944 | controller | TASK [build_openstack_packages : Create a temproary directory to hold the rpms] ***
2025-10-13 19:33:38.339951 | controller | Monday 13 October 2025  19:33:38 +0000 (0:06:51.865)       0:07:58.233 ********
2025-10-13 19:33:38.339961 | controller | skipping: [instance]
2025-10-13 19:33:38.366813 | controller |
2025-10-13 19:33:38.366841 | controller | TASK [build_openstack_packages : Copy the generated rpms] **********************
2025-10-13 19:33:38.366849 | controller | Monday 13 October 2025  19:33:38 +0000 (0:00:00.027)       0:07:58.260 ********
2025-10-13 19:33:38.366860 | controller | skipping: [instance]
2025-10-13 19:33:38.393049 | controller |
2025-10-13 19:33:38.393077 | controller | TASK [build_openstack_packages : Run createrepo on generated rpms] *************
2025-10-13 19:33:38.393085 | controller | Monday 13 October 2025  19:33:38 +0000 (0:00:00.026)       0:07:58.287 ********
2025-10-13 19:33:38.393094 | controller | skipping: [instance]
2025-10-13 19:33:38.393246 | controller |
2025-10-13 19:33:38.393265 | controller | TASK [build_openstack_packages : Copy the gating content to destination] *******
2025-10-13 19:33:38.419397 | controller | Monday 13 October 2025  19:33:38 +0000 (0:00:00.026)       0:07:58.313 ********
2025-10-13 19:33:38.419426 | controller | skipping: [instance]
2025-10-13 19:33:38.445706 | controller |
2025-10-13 19:33:38.445732 | controller | TASK [build_openstack_packages : Add gating.repo file to install the required built packages] ***
2025-10-13 19:33:38.445740 | controller | Monday 13 October 2025  19:33:38 +0000 (0:00:00.026)       0:07:58.340 ********
2025-10-13 19:33:38.445749 | controller | skipping: [instance]
2025-10-13 19:33:38.473997 | controller |
2025-10-13 19:33:38.474033 | controller | TASK [build_openstack_packages : Install python-psutil] ************************
2025-10-13 19:33:38.474043 | controller | Monday 13 October 2025  19:33:38 +0000 (0:00:00.026)       0:07:58.366 ********
2025-10-13 19:33:38.474055 | controller | skipping: [instance]
2025-10-13 19:33:38.505014 | controller |
2025-10-13 19:33:38.505044 | controller | TASK [build_openstack_packages : Getting process IDs of the python http server] ***
2025-10-13 19:33:38.505067 | controller | Monday 13 October 2025  19:33:38 +0000 (0:00:00.027)       0:07:58.394 ********
2025-10-13 19:33:38.505079 | controller | skipping: [instance]
2025-10-13 19:33:38.533237 | controller |
2025-10-13 19:33:38.533272 | controller | TASK [build_openstack_packages : Printing the process IDs obtained] ************
2025-10-13 19:33:38.533280 | controller | Monday 13 October 2025  19:33:38 +0000 (0:00:00.031)       0:07:58.425 ********
2025-10-13 19:33:38.533290 | controller | skipping: [instance]
2025-10-13 19:33:38.533518 | controller |
2025-10-13 19:33:38.533723 | controller | TASK [build_openstack_packages : Force kill the running process] ***************
2025-10-13 19:33:38.533890 | controller | Monday 13 October 2025  19:33:38 +0000 (0:00:00.028)       0:07:58.454 ********
2025-10-13 19:33:38.561871 | controller | skipping: [instance]
2025-10-13 19:33:38.562090 | controller |
2025-10-13 19:33:38.562274 | controller | TASK [build_openstack_packages : Open port 8766 to serve repos] ****************
2025-10-13 19:33:38.562436 | controller | Monday 13 October 2025  19:33:38 +0000 (0:00:00.028)       0:07:58.482 ********
2025-10-13 19:33:38.600380 | controller | skipping: [instance] => (item=nft add table ip filter)
2025-10-13 19:33:38.600565 | controller | skipping: [instance] => (item=nft add chain ip filter INPUT { type filter hook input priority 0 \; })
2025-10-13 19:33:38.600719 | controller | skipping: [instance] => (item=nft insert rule ip filter INPUT tcp dport 8766 counter accept)
2025-10-13 19:33:38.600859 | controller | skipping: [instance]
2025-10-13 19:33:38.601012 | controller |
2025-10-13 19:33:38.601159 | controller | TASK [build_openstack_packages : Serve gating repos] ***************************
2025-10-13 19:33:38.601307 | controller | Monday 13 October 2025  19:33:38 +0000 (0:00:00.038)       0:07:58.521 ********
2025-10-13 19:33:38.628481 | controller | skipping: [instance]
2025-10-13 19:33:38.628639 | controller |
2025-10-13 19:33:38.628826 | controller | TASK [build_openstack_packages : Check for DLRN repo at the destination] *******
2025-10-13 19:33:38.628990 | controller | Monday 13 October 2025  19:33:38 +0000 (0:00:00.028)       0:07:58.549 ********
2025-10-13 19:33:38.656801 | controller | skipping: [instance]
2025-10-13 19:33:38.656997 | controller |
2025-10-13 19:33:38.657194 | controller | TASK [build_openstack_packages : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-13 19:33:38.657390 | controller | Monday 13 October 2025  19:33:38 +0000 (0:00:00.028)       0:07:58.577 ********
2025-10-13 19:33:38.685544 | controller | skipping: [instance]
2025-10-13 19:33:38.685742 | controller |
2025-10-13 19:33:38.685902 | controller | TASK [build_openstack_packages : Clean up] *************************************
2025-10-13 19:33:38.686095 | controller | Monday 13 October 2025  19:33:38 +0000 (0:00:00.028)       0:07:58.606 ********
2025-10-13 19:33:38.722497 | controller | skipping: [instance] => (item=/home/zuul/dlrn_venv)
2025-10-13 19:33:38.722700 | controller | skipping: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/logs/gating_repo)
2025-10-13 19:33:38.722857 | controller | skipping: [instance]
2025-10-13 19:33:38.723057 | controller |
2025-10-13 19:33:38.723222 | controller | TASK [build_openstack_packages : Clean up DLRN if it was cloned by the playbook] ***
2025-10-13 19:33:38.723381 | controller | Monday 13 October 2025  19:33:38 +0000 (0:00:00.037)       0:07:58.643 ********
2025-10-13 19:33:38.770178 | controller | skipping: [instance]
2025-10-13 19:33:38.770365 | controller |
2025-10-13 19:33:38.770518 | controller | PLAY RECAP *********************************************************************
2025-10-13 19:33:38.770670 | controller | instance                   : ok=54   changed=18   unreachable=0    failed=0    skipped=26   rescued=0    ignored=0
2025-10-13 19:33:38.770802 | controller |
2025-10-13 19:33:38.770945 | controller | Monday 13 October 2025  19:33:38 +0000 (0:00:00.047)       0:07:58.691 ********
2025-10-13 19:33:38.771105 | controller | ===============================================================================
2025-10-13 19:33:38.771246 | controller | build_openstack_packages : Run DLRN ----------------------------------- 411.87s
2025-10-13 19:33:38.771424 | controller | build_openstack_packages : Install Mock from DLRN repos ---------------- 27.56s
2025-10-13 19:33:38.771565 | controller | build_openstack_packages : Install DLRN and rdopkg from pip ------------ 15.44s
2025-10-13 19:33:38.771701 | controller | build_openstack_packages : Create dlrn-venv ----------------------------- 3.81s
2025-10-13 19:33:38.771847 | controller | build_openstack_packages : Map project name to DLRN project name -------- 3.51s
2025-10-13 19:33:38.772005 | controller | build_openstack_packages : Fetch local rdoinfo copy --------------------- 2.93s
2025-10-13 19:33:38.772155 | controller | build_openstack_packages : Check loop devices stat ---------------------- 1.93s
2025-10-13 19:33:38.772289 | controller | Clone neutron-tempest-plugin -------------------------------------------- 1.46s
2025-10-13 19:33:38.772445 | controller | build_openstack_packages : Ensure DLRN package dependencies ------------- 1.39s
2025-10-13 19:33:38.772584 | controller | Gathering Facts --------------------------------------------------------- 0.76s
2025-10-13 19:33:38.772723 | controller | build_openstack_packages : Drop in the templated version of projects.ini --- 0.57s
2025-10-13 19:33:38.772860 | controller | build_openstack_packages : Sync python-neutron-tests-tempest from local repo --- 0.54s
2025-10-13 19:33:38.773022 | controller | build_openstack_packages : Override projects.ini settings --------------- 0.53s
2025-10-13 19:33:38.773174 | controller | build_openstack_packages : Create mock group ---------------------------- 0.43s
2025-10-13 19:33:38.773315 | controller | build_openstack_packages : Add user to mock group ----------------------- 0.38s
2025-10-13 19:33:38.773470 | controller | build_openstack_packages : Copy the DLRN scripts in the virtualenv to the scripts dir --- 0.37s
2025-10-13 19:33:38.773606 | controller | build_openstack_packages : Generate DLRN Script ------------------------- 0.34s
2025-10-13 19:33:38.773739 | controller | build_openstack_packages : Ensure DLRN dir is present ------------------- 0.30s
2025-10-13 19:33:38.773883 | controller | build_openstack_packages : Append repo_content ending with """ in mock local config at last --- 0.30s
2025-10-13 19:33:38.774038 | controller | build_openstack_packages : Remove data files from previous runs --------- 0.29s
2025-10-13 19:33:38.835692 | controller | INFO     Running default > cleanup
2025-10-13 19:33:38.836188 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-13 19:33:38.836757 | controller | INFO     Writing /tmp/report.html report.
2025-10-13 19:33:39.221005 | controller | changed
2025-10-13 19:33:39.266877 | 
2025-10-13 19:33:39.267005 | PLAY RECAP
2025-10-13 19:33:39.267055 | controller | ok: 3 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-13 19:33:39.267079 | 
2025-10-13 19:33:39.358036 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-13 19:33:39.358857 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 19:33:39.984657 | 
2025-10-13 19:33:39.984797 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-13 19:33:40.023655 | 
2025-10-13 19:33:40.023777 | TASK [Filter out host if needed]
2025-10-13 19:33:40.032996 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-13 19:33:40.038246 | 
2025-10-13 19:33:40.038314 | TASK [Ensure file is present]
2025-10-13 19:33:40.570151 | controller | ok
2025-10-13 19:33:40.576577 | 
2025-10-13 19:33:40.576651 | TASK [Manage molecule report file]
2025-10-13 19:33:41.111343 | controller | changed
2025-10-13 19:33:41.116967 | 
2025-10-13 19:33:41.117038 | TASK [Check if we get ci-framework-data basedir]
2025-10-13 19:33:41.348318 | controller | ok
2025-10-13 19:33:41.355394 | 
2025-10-13 19:33:41.355470 | TASK [Create ci-framework-data log directory for zuul]
2025-10-13 19:33:41.729681 | controller | changed
2025-10-13 19:33:41.744295 | 
2025-10-13 19:33:41.744396 | TASK [Copy ci-framework interesting files]
2025-10-13 19:33:41.985827 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-13 19:33:42.299881 | controller | changed
2025-10-13 19:33:42.306936 | 
2025-10-13 19:33:42.307066 | TASK [Get SELinux listing]
2025-10-13 19:33:42.873978 | controller | changed
2025-10-13 19:33:42.880028 | 
2025-10-13 19:33:42.880104 | TASK [Generate log index]
2025-10-13 19:33:43.527523 | controller | changed
2025-10-13 19:33:43.534167 | 
2025-10-13 19:33:43.534241 | TASK [Get some env related data]
2025-10-13 19:33:44.080054 | controller | /home/zuul/.local/bin/ansible
2025-10-13 19:33:45.079540 | controller | changed
2025-10-13 19:33:45.085533 | 
2025-10-13 19:33:45.085603 | TASK [Generate list of logs to collect in home directory]
2025-10-13 19:33:45.388103 | controller | ok: All paths examined
2025-10-13 19:33:45.393679 | 
2025-10-13 19:33:45.393762 | LOOP [Copy logs from home directory]
2025-10-13 19:33:45.796395 | controller | changed:
2025-10-13 19:33:45.796524 | controller | {
2025-10-13 19:33:45.796559 | controller |   "atime": 1760383411.855454,
2025-10-13 19:33:45.796585 | controller |   "ctime": 1760383430.0124946,
2025-10-13 19:33:45.796608 | controller |   "dev": 64513,
2025-10-13 19:33:45.796630 | controller |   "gid": 1000,
2025-10-13 19:33:45.796651 | controller |   "gr_name": "zuul",
2025-10-13 19:33:45.796673 | controller |   "inode": 4481140,
2025-10-13 19:33:45.796708 | controller |   "isblk": false,
2025-10-13 19:33:45.796732 | controller |   "ischr": false,
2025-10-13 19:33:45.796753 | controller |   "isdir": false,
2025-10-13 19:33:45.796774 | controller |   "isfifo": false,
2025-10-13 19:33:45.796795 | controller |   "isgid": false,
2025-10-13 19:33:45.796815 | controller |   "islnk": false,
2025-10-13 19:33:45.796835 | controller |   "isreg": true,
2025-10-13 19:33:45.796855 | controller |   "issock": false,
2025-10-13 19:33:45.796875 | controller |   "isuid": false,
2025-10-13 19:33:45.796894 | controller |   "mode": "0644",
2025-10-13 19:33:45.797008 | controller |   "mtime": 1760383430.0124946,
2025-10-13 19:33:45.797041 | controller |   "nlink": 1,
2025-10-13 19:33:45.797063 | controller |   "path": "/home/zuul/ansible.log",
2025-10-13 19:33:45.797082 | controller |   "pw_name": "zuul",
2025-10-13 19:33:45.797099 | controller |   "rgrp": true,
2025-10-13 19:33:45.797115 | controller |   "roth": true,
2025-10-13 19:33:45.797131 | controller |   "rusr": true,
2025-10-13 19:33:45.797147 | controller |   "size": 6749,
2025-10-13 19:33:45.797163 | controller |   "uid": 1000,
2025-10-13 19:33:45.797179 | controller |   "wgrp": false,
2025-10-13 19:33:45.797194 | controller |   "woth": false,
2025-10-13 19:33:45.797212 | controller |   "wusr": true,
2025-10-13 19:33:45.797228 | controller |   "xgrp": false,
2025-10-13 19:33:45.797243 | controller |   "xoth": false,
2025-10-13 19:33:45.797258 | controller |   "xusr": false
2025-10-13 19:33:45.797272 | controller | }
2025-10-13 19:33:45.812666 | 
2025-10-13 19:33:45.812776 | TASK [Copy crio stats log file]
2025-10-13 19:33:45.867136 | controller | skipping: Conditional result was False
2025-10-13 19:33:45.872681 | 
2025-10-13 19:33:45.872767 | TASK [Get SELinux related data]
2025-10-13 19:33:46.091653 | controller | <no matches>
2025-10-13 19:33:46.406250 | controller | ERROR
2025-10-13 19:33:46.406367 | controller | {
2025-10-13 19:33:46.406399 | controller |   "delta": "0:00:00.009108",
2025-10-13 19:33:46.406752 | controller |   "end": "2025-10-13 19:33:46.092607",
2025-10-13 19:33:46.406787 | controller |   "msg": "non-zero return code",
2025-10-13 19:33:46.406811 | controller |   "rc": 1,
2025-10-13 19:33:46.406829 | controller |   "start": "2025-10-13 19:33:46.083499"
2025-10-13 19:33:46.406847 | controller | }
2025-10-13 19:33:46.406874 | controller | ERROR: Ignoring Errors
2025-10-13 19:33:46.425604 | 
2025-10-13 19:33:46.425726 | TASK [Create system configuration directory]
2025-10-13 19:33:46.654044 | controller | changed
2025-10-13 19:33:46.659737 | 
2025-10-13 19:33:46.659819 | TASK [Get some of the system configurations]
2025-10-13 19:33:47.228372 | controller | changed
2025-10-13 19:33:47.234010 | 
2025-10-13 19:33:47.234095 | TASK [Copy generated documentation if available]
2025-10-13 19:33:47.299663 | controller | skipping: Conditional result was False
2025-10-13 19:33:47.306256 | 
2025-10-13 19:33:47.306327 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-13 19:33:47.349536 | controller | skipping: Conditional result was False
2025-10-13 19:33:47.355372 | 
2025-10-13 19:33:47.355443 | TASK [Compress logs bigger than 2MB]
2025-10-13 19:33:47.904049 | controller | changed
2025-10-13 19:33:47.909361 | 
2025-10-13 19:33:47.909426 | TASK [Copy files from workspace on node]
2025-10-13 19:33:47.937929 | controller | ok
2025-10-13 19:33:47.970346 | 
2025-10-13 19:33:47.970423 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 19:33:48.003971 | controller | skipping: Conditional result was False
2025-10-13 19:33:48.028326 | 
2025-10-13 19:33:48.028421 | TASK [fetch-output : Set log path for single node]
2025-10-13 19:33:48.082303 | controller | ok
2025-10-13 19:33:48.088503 | 
2025-10-13 19:33:48.088592 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 19:33:48.326438 | controller -> localhost | ok: "/var/lib/zuul/builds/5327fd7006164d02857ab3016cbc3002/work/logs"
2025-10-13 19:33:48.326666 | controller -> localhost | changed: All items complete
2025-10-13 19:33:48.326709 | 
2025-10-13 19:33:48.536508 | controller -> localhost | changed: "/var/lib/zuul/builds/5327fd7006164d02857ab3016cbc3002/work/artifacts"
2025-10-13 19:33:48.724409 | controller -> localhost | changed: "/var/lib/zuul/builds/5327fd7006164d02857ab3016cbc3002/work/docs"
2025-10-13 19:33:48.738556 | 
2025-10-13 19:33:48.738744 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 19:33:49.332082 | controller | changed:
2025-10-13 19:33:49.332282 | controller | .d..t...... ./
2025-10-13 19:33:49.332313 | controller | >f+++++++++ README.html
2025-10-13 19:33:49.332337 | controller | >f+++++++++ ansible-execution.log
2025-10-13 19:33:49.332360 | controller | >f+++++++++ ansible.log
2025-10-13 19:33:49.332379 | controller | >f+++++++++ dmesg.log
2025-10-13 19:33:49.332398 | controller | >f+++++++++ installed-pkgs.log
2025-10-13 19:33:49.332419 | controller | >f+++++++++ python.log
2025-10-13 19:33:49.332440 | controller | >f+++++++++ registries.conf
2025-10-13 19:33:49.332458 | controller | >f+++++++++ report.html
2025-10-13 19:33:49.332478 | controller | >f+++++++++ selinux-denials.log
2025-10-13 19:33:49.332496 | controller | >f+++++++++ selinux-listing.log
2025-10-13 19:33:49.332514 | controller | cd+++++++++ ci-framework-data/
2025-10-13 19:33:49.332531 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-13 19:33:49.332547 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-13 19:33:49.332564 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-13 19:33:49.332580 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-13 19:33:49.332595 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-13 19:33:49.332611 | controller | >f+++++++++ ci-framework-data/logs/dlrn.log
2025-10-13 19:33:49.332626 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-13 19:33:49.332641 | controller | cd+++++++++ registries.conf.d/
2025-10-13 19:33:49.332656 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-13 19:33:49.332672 | controller | cd+++++++++ system-config/
2025-10-13 19:33:49.332702 | controller | cd+++++++++ system-config/libvirt/
2025-10-13 19:33:49.332720 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-13 19:33:49.332737 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-13 19:33:49.724731 | controller | changed: .d..t...... ./
2025-10-13 19:33:50.370296 | controller | changed: .d..t...... ./
2025-10-13 19:33:50.384318 | 
2025-10-13 19:33:50.384412 | TASK [Return artifact to Zuul]
2025-10-13 19:33:50.443668 | controller | ok
2025-10-13 19:33:50.482670 | 
2025-10-13 19:33:50.482781 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-13 19:33:50.482882 | 
2025-10-13 19:33:50.482925 | PLAY RECAP
2025-10-13 19:33:50.482970 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-13 19:33:50.482994 | 
2025-10-13 19:33:50.637625 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 19:33:50.638409 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 19:33:51.399267 | 
2025-10-13 19:33:51.399387 | PLAY [all]
2025-10-13 19:33:51.418720 | 
2025-10-13 19:33:51.418838 | TASK [include_role : fetch-output]
2025-10-13 19:33:51.449889 | controller | ok
2025-10-13 19:33:51.469414 | 
2025-10-13 19:33:51.469532 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 19:33:51.513499 | controller | skipping: Conditional result was False
2025-10-13 19:33:51.519811 | 
2025-10-13 19:33:51.519892 | TASK [fetch-output : Set log path for single node]
2025-10-13 19:33:51.553994 | controller | ok
2025-10-13 19:33:51.559501 | 
2025-10-13 19:33:51.559577 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 19:33:52.197196 | controller -> localhost | ok: "/var/lib/zuul/builds/5327fd7006164d02857ab3016cbc3002/work/logs"
2025-10-13 19:33:52.671249 | controller -> localhost | ok: "/var/lib/zuul/builds/5327fd7006164d02857ab3016cbc3002/work/artifacts"
2025-10-13 19:33:53.067279 | controller -> localhost | ok: "/var/lib/zuul/builds/5327fd7006164d02857ab3016cbc3002/work/docs"
2025-10-13 19:33:53.076186 | 
2025-10-13 19:33:53.076365 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 19:33:53.960508 | controller | ok
2025-10-13 19:33:53.960710 | controller | ok: All items complete
2025-10-13 19:33:53.960746 | 
2025-10-13 19:33:54.561577 | controller | ok
2025-10-13 19:33:55.213310 | controller | ok
2025-10-13 19:33:55.229774 | 
2025-10-13 19:33:55.229885 | TASK [include_role : fetch-output-openshift]
2025-10-13 19:33:55.279712 | controller | skipping: Conditional result was False
2025-10-13 19:33:55.287514 | 
2025-10-13 19:33:55.287600 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-13 19:33:55.727808 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007378
2025-10-13 19:33:55.942724 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006362
2025-10-13 19:33:55.980034 | 
2025-10-13 19:33:55.980129 | PLAY [all]
2025-10-13 19:33:56.001273 | 
2025-10-13 19:33:56.001353 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-13 19:33:56.420060 | controller | changed
2025-10-13 19:33:56.444252 | 
2025-10-13 19:33:56.444810 | PLAY RECAP
2025-10-13 19:33:56.444866 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-13 19:33:56.444893 | 
2025-10-13 19:33:56.527462 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 19:33:56.533523 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-13 19:33:57.217168 | 
2025-10-13 19:33:57.217305 | PLAY [localhost]
2025-10-13 19:33:57.238316 | 
2025-10-13 19:33:57.238401 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-13 19:33:57.601602 | localhost | changed
2025-10-13 19:33:57.606616 | 
2025-10-13 19:33:57.606714 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-13 19:33:57.634428 | localhost | ok
2025-10-13 19:33:57.642818 | 
2025-10-13 19:33:57.642892 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-13 19:33:58.061882 | localhost | changed
2025-10-13 19:33:58.067349 | 
2025-10-13 19:33:58.067422 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-13 19:33:58.856529 | localhost | changed
2025-10-13 19:33:58.861557 | 
2025-10-13 19:33:58.861624 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-13 19:33:59.334646 | localhost | Identity added: /var/lib/zuul/builds/5327fd7006164d02857ab3016cbc3002/work/tmp/ansible.5stioijj (/var/lib/zuul/builds/5327fd7006164d02857ab3016cbc3002/work/tmp/ansible.5stioijj)
2025-10-13 19:33:59.334855 | localhost | ok: Runtime: 0:00:00.006720
2025-10-13 19:33:59.340829 | 
2025-10-13 19:33:59.340901 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-13 19:33:59.706859 | localhost | ok: Runtime: 0:00:00.007360
2025-10-13 19:33:59.712227 | 
2025-10-13 19:33:59.712298 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-13 19:33:59.760645 | localhost | changed
2025-10-13 19:33:59.765054 | 
2025-10-13 19:33:59.765124 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-13 19:34:00.171616 | localhost | changed
2025-10-13 19:34:00.218725 | 
2025-10-13 19:34:00.218807 | PLAY [localhost]
2025-10-13 19:34:00.231630 | 
2025-10-13 19:34:00.231708 | TASK [Generate bulk log download script]
2025-10-13 19:34:00.249385 | localhost | ok
2025-10-13 19:34:00.264569 | 
2025-10-13 19:34:00.264643 | TASK [local-log-download : Check API endpoint is defined]
2025-10-13 19:34:00.302426 | localhost | ok: All assertions passed
2025-10-13 19:34:00.308203 | 
2025-10-13 19:34:00.308278 | TASK [local-log-download : Create download script]
2025-10-13 19:34:00.945985 | localhost -> localhost | changed
2025-10-13 19:34:00.957462 | 
2025-10-13 19:34:00.957536 | TASK [Register quick-download link]
2025-10-13 19:34:01.019542 | localhost | ok
2025-10-13 19:34:01.046318 | 
2025-10-13 19:34:01.046400 | PLAY [logserver.rdoproject.org]
2025-10-13 19:34:01.070695 | 
2025-10-13 19:34:01.070788 | TASK [Set zuul-log-path fact]
2025-10-13 19:34:01.087809 | logserver.rdoproject.org | ok
2025-10-13 19:34:01.103091 | 
2025-10-13 19:34:01.103197 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 19:34:01.162269 | logserver.rdoproject.org | ok
2025-10-13 19:34:01.168339 | 
2025-10-13 19:34:01.168421 | TASK [upload-logs : Create log directories]
2025-10-13 19:34:03.052937 | logserver.rdoproject.org | changed
2025-10-13 19:34:03.056238 | 
2025-10-13 19:34:03.056319 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-13 19:34:03.326483 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.008453
2025-10-13 19:34:03.331484 | 
2025-10-13 19:34:03.331554 | TASK [upload-logs : Upload logs to log server]
2025-10-13 19:34:04.960885 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-13 19:34:04.966394 | 
2025-10-13 19:34:04.966470 | LOOP [upload-logs : Compress console log and json output]
2025-10-13 19:34:05.057053 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 19:34:05.060190 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 19:34:05.068873 | 
2025-10-13 19:34:05.068999 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-13 19:34:05.124778 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 19:34:05.125100 | 
2025-10-13 19:34:05.125625 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 19:34:05.139184 | 
2025-10-13 19:34:05.139291 | LOOP [upload-logs : Upload console log and json output]