2025-11-26 07:30:41.216472 | Job console starting...
2025-11-26 07:30:41.434022 | Updating repositories
2025-11-26 07:30:42.452378 | Preparing job workspace
2025-11-26 07:31:00.677624 | Running Ansible setup...
2025-11-26 07:31:08.675588 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-26 07:31:10.014834 | 
2025-11-26 07:31:10.014959 | PLAY [localhost]
2025-11-26 07:31:10.064528 | 
2025-11-26 07:31:10.064634 | TASK [Gathering Facts]
2025-11-26 07:31:12.257518 | localhost | ok
2025-11-26 07:31:12.308263 | 
2025-11-26 07:31:12.308379 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-26 07:31:13.774956 | localhost -> localhost | changed
2025-11-26 07:31:13.786672 | 
2025-11-26 07:31:13.786749 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-26 07:31:17.497745 | localhost -> localhost | changed
2025-11-26 07:31:17.506004 | 
2025-11-26 07:31:17.506084 | TASK [Setup log path fact]
2025-11-26 07:31:17.585785 | localhost | ok
2025-11-26 07:31:17.613009 | 
2025-11-26 07:31:17.613088 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-26 07:31:17.733748 | localhost | ok
2025-11-26 07:31:17.774345 | 
2025-11-26 07:31:17.774478 | TASK [emit-job-header : Print job information]
2025-11-26 07:31:17.898880 | # Job Information
2025-11-26 07:31:17.899094 | Ansible Version: 2.15.12
2025-11-26 07:31:17.899129 | Job: cifmw-molecule-hci_prepare
2025-11-26 07:31:17.899152 | Pipeline: github-check
2025-11-26 07:31:17.899173 | Executor: ibm-bm3-ze.softwarefactory-project.io
2025-11-26 07:31:17.899193 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3505
2025-11-26 07:31:17.899212 | Log URL (when completed): https://logserver.rdoproject.org/7a9/rdoproject.org/7a9d4413f516458185f754d3e350f18e/
2025-11-26 07:31:17.899231 | Event ID: 8adaaf80-ca99-11f0-898f-2010a17eda0e
2025-11-26 07:31:17.906841 | 
2025-11-26 07:31:17.906930 | LOOP [emit-job-header : Print node information]
2025-11-26 07:31:18.115990 | localhost | ok:
2025-11-26 07:31:18.116184 | localhost | # Node Information
2025-11-26 07:31:18.116217 | localhost | Inventory Hostname: controller
2025-11-26 07:31:18.116246 | localhost | Hostname: np0005536228
2025-11-26 07:31:18.116269 | localhost | Username: zuul
2025-11-26 07:31:18.116290 | localhost | Distro: CentOS 9
2025-11-26 07:31:18.116310 | localhost | Provider: ibm-bm3-nodepool
2025-11-26 07:31:18.116462 | localhost | Region: regionOne
2025-11-26 07:31:18.116510 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-11-26 07:31:18.116536 | localhost | Product Name: OpenStack Compute
2025-11-26 07:31:18.116557 | localhost | Interface IP: 192.168.25.197
2025-11-26 07:31:18.189745 | 
2025-11-26 07:31:18.189821 | PLAY [all]
2025-11-26 07:31:18.213079 | 
2025-11-26 07:31:18.213150 | TASK [Gather network facts]
2025-11-26 07:31:18.612134 | controller | ok
2025-11-26 07:31:18.663845 | 
2025-11-26 07:31:18.663943 | TASK [include_role : start-zuul-console]
2025-11-26 07:31:18.703932 | controller | ok
2025-11-26 07:31:18.743584 | 
2025-11-26 07:31:18.743660 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-26 07:31:19.464171 | controller | ok
2025-11-26 07:31:19.487972 | 
2025-11-26 07:31:19.488073 | TASK [include_role : add-build-sshkey]
2025-11-26 07:31:19.565865 | controller | ok
2025-11-26 07:31:19.592161 | 
2025-11-26 07:31:19.592248 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-26 07:31:20.443298 | controller -> localhost | ok
2025-11-26 07:31:20.449985 | 
2025-11-26 07:31:20.450070 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-26 07:31:20.522090 | controller | ok
2025-11-26 07:31:20.581565 | controller | included: /var/lib/zuul/builds/7a9d4413f516458185f754d3e350f18e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-26 07:31:20.596448 | 
2025-11-26 07:31:20.596531 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-26 07:31:22.156725 | controller -> localhost | Generating public/private rsa key pair.
2025-11-26 07:31:22.156947 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7a9d4413f516458185f754d3e350f18e/work/7a9d4413f516458185f754d3e350f18e_id_rsa.
2025-11-26 07:31:22.156988 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7a9d4413f516458185f754d3e350f18e/work/7a9d4413f516458185f754d3e350f18e_id_rsa.pub.
2025-11-26 07:31:22.157014 | controller -> localhost | The key fingerprint is:
2025-11-26 07:31:22.157037 | controller -> localhost | SHA256:ynUZnYyQAILk3GhMUSTrjSkC8p62GnKM8XIFwTxpVQw zuul-build-sshkey
2025-11-26 07:31:22.157057 | controller -> localhost | The key's randomart image is:
2025-11-26 07:31:22.157076 | controller -> localhost | +---[RSA 3072]----+
2025-11-26 07:31:22.157095 | controller -> localhost | |.O*=E=o...       |
2025-11-26 07:31:22.157115 | controller -> localhost | |* @o  . .. + .   |
2025-11-26 07:31:22.157133 | controller -> localhost | |oO.o      o +    |
2025-11-26 07:31:22.157153 | controller -> localhost | |=.+.       o     |
2025-11-26 07:31:22.157178 | controller -> localhost | |++...   S o      |
2025-11-26 07:31:22.157196 | controller -> localhost | |o* o . o .       |
2025-11-26 07:31:22.157214 | controller -> localhost | |= O   o          |
2025-11-26 07:31:22.157232 | controller -> localhost | |.* .             |
2025-11-26 07:31:22.157250 | controller -> localhost | |...              |
2025-11-26 07:31:22.157269 | controller -> localhost | +----[SHA256]-----+
2025-11-26 07:31:22.157313 | controller -> localhost | ok: Runtime: 0:00:00.378698
2025-11-26 07:31:22.163268 | 
2025-11-26 07:31:22.163340 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-26 07:31:22.215871 | controller | ok
2025-11-26 07:31:22.231013 | controller | included: /var/lib/zuul/builds/7a9d4413f516458185f754d3e350f18e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-26 07:31:22.251785 | 
2025-11-26 07:31:22.251870 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-26 07:31:22.300901 | controller | skipping: Conditional result was False
2025-11-26 07:31:22.316090 | 
2025-11-26 07:31:22.316184 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-26 07:31:22.915408 | controller | changed
2025-11-26 07:31:22.921351 | 
2025-11-26 07:31:22.921449 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-26 07:31:23.170627 | controller | ok
2025-11-26 07:31:23.178064 | 
2025-11-26 07:31:23.178151 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-26 07:31:23.945791 | controller | changed
2025-11-26 07:31:23.952233 | 
2025-11-26 07:31:23.952311 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-26 07:31:24.818536 | controller | changed
2025-11-26 07:31:24.838921 | 
2025-11-26 07:31:24.839012 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-26 07:31:24.906290 | controller | skipping: Conditional result was False
2025-11-26 07:31:24.912748 | 
2025-11-26 07:31:24.912828 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-26 07:31:26.071769 | controller -> localhost | changed
2025-11-26 07:31:26.086731 | 
2025-11-26 07:31:26.086819 | TASK [add-build-sshkey : Add back temp key]
2025-11-26 07:31:26.843704 | controller -> localhost | Identity added: /var/lib/zuul/builds/7a9d4413f516458185f754d3e350f18e/work/7a9d4413f516458185f754d3e350f18e_id_rsa (zuul-build-sshkey)
2025-11-26 07:31:26.843890 | controller -> localhost | ok: Runtime: 0:00:00.039170
2025-11-26 07:31:26.849501 | 
2025-11-26 07:31:26.849573 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-26 07:31:27.389221 | controller | ok
2025-11-26 07:31:27.394105 | 
2025-11-26 07:31:27.394180 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-26 07:31:27.481825 | controller | skipping: Conditional result was False
2025-11-26 07:31:27.495668 | 
2025-11-26 07:31:27.495749 | TASK [include_role : validate-host]
2025-11-26 07:31:27.560862 | controller | ok
2025-11-26 07:31:27.600279 | 
2025-11-26 07:31:27.600397 | TASK [validate-host : Define zuul_info_dir fact]
2025-11-26 07:31:27.662510 | controller | ok
2025-11-26 07:31:27.670334 | 
2025-11-26 07:31:27.670434 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-11-26 07:31:28.393109 | controller -> localhost | ok
2025-11-26 07:31:28.398972 | 
2025-11-26 07:31:28.399061 | TASK [validate-host : Collect information about the host]
2025-11-26 07:31:29.078411 | controller | ok
2025-11-26 07:31:29.101240 | 
2025-11-26 07:31:29.101322 | TASK [validate-host : Sanitize hostname]
2025-11-26 07:31:29.165298 | controller | ok
2025-11-26 07:31:29.172589 | 
2025-11-26 07:31:29.172668 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-11-26 07:31:30.583856 | controller -> localhost | changed
2025-11-26 07:31:30.590122 | 
2025-11-26 07:31:30.590208 | TASK [validate-host : Collect information about zuul worker]
2025-11-26 07:31:31.182613 | controller | ok
2025-11-26 07:31:31.187764 | 
2025-11-26 07:31:31.187834 | TASK [validate-host : Write out all zuul information for each host]
2025-11-26 07:31:32.071474 | controller -> localhost | changed
2025-11-26 07:31:32.096939 | 
2025-11-26 07:31:32.097027 | TASK [include_role : prepare-workspace-openshift]
2025-11-26 07:31:32.161517 | controller | skipping: Conditional result was False
2025-11-26 07:31:32.171356 | 
2025-11-26 07:31:32.171472 | TASK [include_role : remove-zuul-sshkey]
2025-11-26 07:31:32.254894 | controller | skipping: Conditional result was False
2025-11-26 07:31:32.261227 | 
2025-11-26 07:31:32.261309 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-26 07:31:32.506425 | controller | ok: "logs"
2025-11-26 07:31:32.506688 | controller | ok: All items complete
2025-11-26 07:31:32.506834 | 
2025-11-26 07:31:32.689174 | controller | ok: "artifacts"
2025-11-26 07:31:32.905562 | controller | ok: "docs"
2025-11-26 07:31:32.917754 | 
2025-11-26 07:31:32.917838 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-26 07:31:33.181443 | controller | changed: "logs"
2025-11-26 07:31:33.379986 | controller | changed: "artifacts"
2025-11-26 07:31:33.592035 | controller | changed: "docs"
2025-11-26 07:31:33.666748 | 
2025-11-26 07:31:33.666854 | PLAY RECAP
2025-11-26 07:31:33.667214 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-26 07:31:33.667268 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-26 07:31:33.667294 | 
2025-11-26 07:31:33.978870 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-26 07:31:33.979755 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-26 07:31:34.751701 | 
2025-11-26 07:31:34.751808 | PLAY [all]
2025-11-26 07:31:34.784563 | 
2025-11-26 07:31:34.784640 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-26 07:31:34.867490 | controller | ok
2025-11-26 07:31:34.873006 | 
2025-11-26 07:31:34.873097 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-26 07:31:35.367027 | controller | changed
2025-11-26 07:31:35.373420 | 
2025-11-26 07:31:35.373506 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-26 07:31:36.755140 | controller | changed
2025-11-26 07:31:36.786144 | 
2025-11-26 07:31:36.786233 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-11-26 07:31:37.384187 | controller | changed:
2025-11-26 07:31:37.384354 | controller | {
2025-11-26 07:31:37.384410 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-11-26 07:31:37.384448 | controller | }
2025-11-26 07:31:37.598427 | controller | changed:
2025-11-26 07:31:37.598517 | controller | {
2025-11-26 07:31:37.598547 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-11-26 07:31:37.598570 | controller | }
2025-11-26 07:31:37.813248 | controller | changed:
2025-11-26 07:31:37.813336 | controller | {
2025-11-26 07:31:37.813365 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-11-26 07:31:37.813408 | controller | }
2025-11-26 07:31:38.029486 | controller | changed:
2025-11-26 07:31:38.029567 | controller | {
2025-11-26 07:31:38.029598 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-11-26 07:31:38.029621 | controller | }
2025-11-26 07:31:38.255637 | controller | changed:
2025-11-26 07:31:38.255735 | controller | {
2025-11-26 07:31:38.255774 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-11-26 07:31:38.255797 | controller | }
2025-11-26 07:31:38.471027 | controller | changed:
2025-11-26 07:31:38.471105 | controller | {
2025-11-26 07:31:38.471133 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-11-26 07:31:38.471155 | controller | }
2025-11-26 07:31:38.680645 | controller | changed:
2025-11-26 07:31:38.680721 | controller | {
2025-11-26 07:31:38.680748 | 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-11-26 07:31:38.680768 | controller | }
2025-11-26 07:31:38.883248 | controller | changed:
2025-11-26 07:31:38.883342 | controller | {
2025-11-26 07:31:38.883370 | 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-11-26 07:31:38.883413 | controller | }
2025-11-26 07:31:39.100115 | controller | changed:
2025-11-26 07:31:39.100200 | controller | {
2025-11-26 07:31:39.100230 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-11-26 07:31:39.100253 | controller | }
2025-11-26 07:31:39.318337 | controller | changed:
2025-11-26 07:31:39.318447 | controller | {
2025-11-26 07:31:39.318478 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-11-26 07:31:39.318500 | controller | }
2025-11-26 07:31:39.528054 | controller | changed:
2025-11-26 07:31:39.528139 | controller | {
2025-11-26 07:31:39.528170 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-11-26 07:31:39.528193 | controller | }
2025-11-26 07:31:39.742983 | controller | changed:
2025-11-26 07:31:39.743071 | controller | {
2025-11-26 07:31:39.743100 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-11-26 07:31:39.743122 | controller | }
2025-11-26 07:31:39.959417 | controller | changed:
2025-11-26 07:31:39.959510 | controller | {
2025-11-26 07:31:39.959538 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-11-26 07:31:39.959559 | controller | }
2025-11-26 07:31:40.189166 | controller | changed:
2025-11-26 07:31:40.189292 | controller | {
2025-11-26 07:31:40.189323 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-11-26 07:31:40.189344 | controller | }
2025-11-26 07:31:40.407112 | controller | changed:
2025-11-26 07:31:40.407324 | controller | {
2025-11-26 07:31:40.407362 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-11-26 07:31:40.407407 | controller | }
2025-11-26 07:31:40.616119 | controller | changed:
2025-11-26 07:31:40.616206 | controller | {
2025-11-26 07:31:40.616235 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-11-26 07:31:40.616256 | controller | }
2025-11-26 07:31:40.829642 | controller | changed:
2025-11-26 07:31:40.829724 | controller | {
2025-11-26 07:31:40.829755 | 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-11-26 07:31:40.829779 | controller | }
2025-11-26 07:31:41.039242 | controller | changed:
2025-11-26 07:31:41.039333 | controller | {
2025-11-26 07:31:41.039363 | 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-11-26 07:31:41.039506 | controller | }
2025-11-26 07:31:41.250100 | controller | changed:
2025-11-26 07:31:41.250187 | controller | {
2025-11-26 07:31:41.250215 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-11-26 07:31:41.250237 | controller | }
2025-11-26 07:31:41.463320 | controller | changed:
2025-11-26 07:31:41.463418 | controller | {
2025-11-26 07:31:41.463449 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-11-26 07:31:41.463470 | controller | }
2025-11-26 07:31:41.683685 | controller | changed:
2025-11-26 07:31:41.683768 | controller | {
2025-11-26 07:31:41.683797 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-11-26 07:31:41.683819 | controller | }
2025-11-26 07:31:41.896113 | controller | changed:
2025-11-26 07:31:41.896191 | controller | {
2025-11-26 07:31:41.896219 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-11-26 07:31:41.896241 | controller | }
2025-11-26 07:31:42.112559 | controller | changed:
2025-11-26 07:31:42.112644 | controller | {
2025-11-26 07:31:42.112675 | 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-11-26 07:31:42.112697 | controller | }
2025-11-26 07:31:42.330359 | controller | changed:
2025-11-26 07:31:42.330548 | controller | {
2025-11-26 07:31:42.330580 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-11-26 07:31:42.330603 | controller | }
2025-11-26 07:31:42.557364 | controller | changed:
2025-11-26 07:31:42.557484 | controller | {
2025-11-26 07:31:42.557519 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-11-26 07:31:42.557541 | controller | }
2025-11-26 07:31:42.785655 | controller | changed:
2025-11-26 07:31:42.785789 | controller | {
2025-11-26 07:31:42.785822 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-11-26 07:31:42.785845 | controller | }
2025-11-26 07:31:42.810270 | 
2025-11-26 07:31:42.810340 | TASK [Set timezone to UTC]
2025-11-26 07:31:43.511310 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-11-26 07:31:43.516886 | 
2025-11-26 07:31:43.516969 | TASK [Create nodepool directory]
2025-11-26 07:31:43.749153 | controller | changed
2025-11-26 07:31:43.757860 | 
2025-11-26 07:31:43.757954 | TASK [Create nodepool sub_nodes file]
2025-11-26 07:31:44.355152 | controller | changed
2025-11-26 07:31:44.360255 | 
2025-11-26 07:31:44.360339 | TASK [Create nodepool sub_nodes_private file]
2025-11-26 07:31:44.889604 | controller | changed
2025-11-26 07:31:44.894584 | 
2025-11-26 07:31:44.894664 | LOOP [Populate nodepool sub_nodes file]
2025-11-26 07:31:44.934187 | 
2025-11-26 07:31:44.934336 | LOOP [Populate nodepool sub_nodes_private file]
2025-11-26 07:31:44.963263 | 
2025-11-26 07:31:44.963419 | TASK [Create nodepool primary file]
2025-11-26 07:31:44.986425 | controller | skipping: Conditional result was False
2025-11-26 07:31:44.991783 | 
2025-11-26 07:31:44.991867 | TASK [Create nodepool node_private for this node]
2025-11-26 07:31:45.560134 | controller | changed
2025-11-26 07:31:45.565630 | 
2025-11-26 07:31:45.565694 | LOOP [Copy ssh keys to nodepool directory]
2025-11-26 07:31:45.926753 | controller | ok: Item: id_rsa Runtime: 0:00:00.004711
2025-11-26 07:31:45.926950 | 
2025-11-26 07:31:46.096993 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004569
2025-11-26 07:31:46.108876 | 
2025-11-26 07:31:46.108982 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-11-26 07:31:46.686767 | controller | changed
2025-11-26 07:31:46.691818 | 
2025-11-26 07:31:46.691894 | TASK [Validate sudoers config after edits]
2025-11-26 07:31:46.911348 | controller | /etc/sudoers: parsed OK
2025-11-26 07:31:46.911438 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-11-26 07:31:46.911449 | controller | /etc/sudoers.d/zuul: parsed OK
2025-11-26 07:31:46.911456 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-11-26 07:31:47.219719 | controller | ok: Runtime: 0:00:00.005144
2025-11-26 07:31:47.225090 | 
2025-11-26 07:31:47.225152 | TASK [Show the environment passed in to job shell scripts]
2025-11-26 07:31:47.454297 | controller | SHELL=/bin/bash
2025-11-26 07:31:47.454346 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-11-26 07:31:47.454358 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-11-26 07:31:47.454365 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/05/3505/48e1843efd45f40e0afe5b5c680514c004968226
2025-11-26 07:31:47.454371 | controller | PWD=/home/zuul
2025-11-26 07:31:47.454377 | controller | ZUUL_PIPELINE=github-check
2025-11-26 07:31:47.454427 | controller | LOGNAME=zuul
2025-11-26 07:31:47.454437 | controller | XDG_SESSION_TYPE=tty
2025-11-26 07:31:47.454443 | controller | _=/usr/bin/env
2025-11-26 07:31:47.454448 | controller | MOTD_SHOWN=pam
2025-11-26 07:31:47.454454 | controller | HOME=/home/zuul
2025-11-26 07:31:47.454459 | controller | LANG=en_US.UTF-8
2025-11-26 07:31:47.454464 | controller | SSH_CONNECTION=192.168.25.12 37458 192.168.25.197 22
2025-11-26 07:31:47.454470 | 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-11-26 07:31:47.454479 | controller | ZUUL_CHANGE_IDS=3505,48e1843efd45f40e0afe5b5c680514c004968226
2025-11-26 07:31:47.454485 | controller | WORKSPACE=/home/zuul/workspace
2025-11-26 07:31:47.454490 | controller | XDG_SESSION_CLASS=user
2025-11-26 07:31:47.454496 | controller | SELINUX_ROLE_REQUESTED=
2025-11-26 07:31:47.454501 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-11-26 07:31:47.454506 | controller | USER=zuul
2025-11-26 07:31:47.454512 | controller | ZUUL_VOTING=True
2025-11-26 07:31:47.454517 | controller | BUILD_TIMEOUT=1800000
2025-11-26 07:31:47.454522 | controller | SELINUX_USE_CURRENT_RANGE=
2025-11-26 07:31:47.454528 | controller | SHLVL=1
2025-11-26 07:31:47.454533 | controller | ZUUL_PATCHSET=48e1843efd45f40e0afe5b5c680514c004968226
2025-11-26 07:31:47.454538 | controller | XDG_SESSION_ID=1
2025-11-26 07:31:47.454544 | controller | ZUUL_BRANCH=main
2025-11-26 07:31:47.454549 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-11-26 07:31:47.454554 | controller | SSH_CLIENT=192.168.25.12 37458 22
2025-11-26 07:31:47.454559 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-11-26 07:31:47.454565 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-11-26 07:31:47.454570 | controller | which_declare=declare -f
2025-11-26 07:31:47.454576 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-11-26 07:31:47.454581 | controller | SELINUX_LEVEL_REQUESTED=
2025-11-26 07:31:47.454587 | controller | ZUUL_CHANGE=3505
2025-11-26 07:31:47.454592 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-11-26 07:31:47.454597 | controller | ZUUL_UUID=7a9d4413f516458185f754d3e350f18e
2025-11-26 07:31:47.454602 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-11-26 07:31:47.454609 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-11-26 07:31:47.454615 | controller | }
2025-11-26 07:31:47.769364 | controller | ok: Runtime: 0:00:00.006517
2025-11-26 07:31:47.775295 | 
2025-11-26 07:31:47.775366 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-11-26 07:31:47.799144 | controller | skipping: Conditional result was False
2025-11-26 07:31:47.805297 | 
2025-11-26 07:31:47.805421 | TASK [Symlink /home/zuul-worker/workspace]
2025-11-26 07:31:48.358224 | controller | skipping: Conditional result was False
2025-11-26 07:31:48.366239 | 
2025-11-26 07:31:48.366327 | TASK [Ensure legacy workspace directory]
2025-11-26 07:31:48.619580 | controller | changed
2025-11-26 07:31:48.719717 | 
2025-11-26 07:31:48.719810 | PLAY RECAP
2025-11-26 07:31:48.719855 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-26 07:31:48.719880 | 
2025-11-26 07:31:48.845998 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-26 07:31:48.846768 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-26 07:31:50.009857 | 
2025-11-26 07:31:50.009974 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-11-26 07:31:50.053238 | 
2025-11-26 07:31:50.053329 | TASK [Create zuul-output directory]
2025-11-26 07:31:50.605798 | controller | changed
2025-11-26 07:31:50.611067 | 
2025-11-26 07:31:50.611144 | TASK [Slurp Zuul inventory test]
2025-11-26 07:31:50.982456 | controller -> localhost | ok
2025-11-26 07:31:50.990436 | 
2025-11-26 07:31:50.990549 | TASK [Save zuul inventory]
2025-11-26 07:31:51.793189 | controller | changed
2025-11-26 07:31:51.798955 | 
2025-11-26 07:31:51.799082 | TASK [Save zuul vars without the change_message]
2025-11-26 07:31:52.398958 | controller | changed
2025-11-26 07:31:52.434186 | 
2025-11-26 07:31:52.434308 | PLAY RECAP
2025-11-26 07:31:52.434354 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-26 07:31:52.434378 | 
2025-11-26 07:31:52.506318 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-26 07:31:52.507092 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-26 07:31:53.075313 | 
2025-11-26 07:31:53.075435 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-11-26 07:31:53.098243 | 
2025-11-26 07:31:53.098319 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-11-26 07:31:53.129768 | controller | ok
2025-11-26 07:31:53.146634 | 
2025-11-26 07:31:53.146730 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-26 07:31:53.169978 | controller | skipping: Conditional result was False
2025-11-26 07:31:53.175853 | 
2025-11-26 07:31:53.175941 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-26 07:31:53.507929 | controller | ok
2025-11-26 07:31:53.513806 | 
2025-11-26 07:31:53.513901 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-26 07:31:54.185639 | controller | ok
2025-11-26 07:31:54.200550 | 
2025-11-26 07:31:54.200637 | TASK [Prepare workspace]
2025-11-26 07:31:54.223998 | controller | ok
2025-11-26 07:31:54.252206 | 
2025-11-26 07:31:54.252315 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-26 07:31:54.565154 | controller | ok
2025-11-26 07:31:54.572071 | 
2025-11-26 07:31:54.572160 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-26 07:31:57.052625 | controller | Output suppressed because no_log was given
2025-11-26 07:31:57.061417 | 
2025-11-26 07:31:57.061482 | LOOP [Create zuul-output directory]
2025-11-26 07:31:57.246060 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-11-26 07:31:57.403949 | controller | ok: "/home/zuul/zuul-output/logs"
2025-11-26 07:31:57.414772 | 
2025-11-26 07:31:57.414858 | TASK [Install required packages]
2025-11-26 07:32:48.450422 | controller | changed
2025-11-26 07:32:48.455562 | 
2025-11-26 07:32:48.455628 | TASK [Install venv]
2025-11-26 07:33:50.467133 | controller | changed
2025-11-26 07:33:50.491841 | 
2025-11-26 07:33:50.491909 | PLAY RECAP
2025-11-26 07:33:50.491973 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-26 07:33:50.491998 | 
2025-11-26 07:33:50.570799 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-26 07:33:50.571621 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-26 07:33:51.104931 | 
2025-11-26 07:33:51.105037 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-11-26 07:33:51.125634 | 
2025-11-26 07:33:51.125715 | TASK [Gather required facts]
2025-11-26 07:33:51.622110 | controller | ok
2025-11-26 07:33:51.627351 | 
2025-11-26 07:33:51.627443 | TASK [Load environment var if instructed to]
2025-11-26 07:33:51.651811 | controller | skipping: Conditional result was False
2025-11-26 07:33:51.657338 | 
2025-11-26 07:33:51.657438 | TASK [Ensure group_vars dir exists]
2025-11-26 07:33:51.969937 | controller | ok
2025-11-26 07:33:51.976175 | 
2025-11-26 07:33:51.976249 | TASK [Print related variables]
2025-11-26 07:33:52.005592 | controller | ok:
2025-11-26 07:33:52.005742 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-11-26 07:33:52.005774 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/hci_prepare
2025-11-26 07:33:52.010610 | 
2025-11-26 07:33:52.010681 | TASK [Run molecule]
2025-11-26 07:33:52.984456 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-11-26 07:33:53.055530 | controller | INFO     Performing prerun with role_name_check=0...
2025-11-26 07:34:09.974832 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:09.975329 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:09.975822 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:09.976279 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:09.976764 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+69a40365 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 1.0.0+69a40365 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:09.977228 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:09.977676 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:09.978154 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:09.978590 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:09.979083 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:09.979526 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:09.980003 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:09.980442 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:09.980915 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:09.981363 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:09.990878 | controller | INFO     Running default > prepare
2025-11-26 07:34:10.648125 | controller |
2025-11-26 07:34:10.648181 | controller | PLAY [Prepare] *****************************************************************
2025-11-26 07:34:10.648216 | controller |
2025-11-26 07:34:10.648338 | controller | TASK [Gathering Facts] *********************************************************
2025-11-26 07:34:10.648435 | controller | Wednesday 26 November 2025  07:34:10 +0000 (0:00:00.012)       0:00:00.012 ****
2025-11-26 07:34:11.467068 | controller | ok: [instance]
2025-11-26 07:34:11.467142 | controller |
2025-11-26 07:34:11.467251 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-11-26 07:34:11.467355 | controller | Wednesday 26 November 2025  07:34:11 +0000 (0:00:00.819)       0:00:00.831 ****
2025-11-26 07:34:11.483526 | controller | skipping: [instance]
2025-11-26 07:34:11.483630 | controller |
2025-11-26 07:34:11.483771 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-11-26 07:34:11.483922 | controller | Wednesday 26 November 2025  07:34:11 +0000 (0:00:00.016)       0:00:00.848 ****
2025-11-26 07:34:11.522570 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-11-26 07:34:11.522661 | controller |
2025-11-26 07:34:11.522803 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-11-26 07:34:11.522958 | controller | Wednesday 26 November 2025  07:34:11 +0000 (0:00:00.038)       0:00:00.887 ****
2025-11-26 07:34:11.801154 | controller | ok: [instance]
2025-11-26 07:34:11.801232 | controller |
2025-11-26 07:34:11.801341 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-11-26 07:34:11.801443 | controller | Wednesday 26 November 2025  07:34:11 +0000 (0:00:00.278)       0:00:01.165 ****
2025-11-26 07:34:12.107457 | controller | ok: [instance]
2025-11-26 07:34:12.107538 | controller |
2025-11-26 07:34:12.107650 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-11-26 07:34:12.107762 | controller | Wednesday 26 November 2025  07:34:12 +0000 (0:00:00.306)       0:00:01.472 ****
2025-11-26 07:34:12.781541 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-11-26 07:34:12.781624 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-11-26 07:34:12.781738 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-11-26 07:34:12.781846 | controller |
2025-11-26 07:34:12.781967 | controller | TASK [Deploy repo-setup] *******************************************************
2025-11-26 07:34:12.782065 | controller | Wednesday 26 November 2025  07:34:12 +0000 (0:00:00.673)       0:00:02.146 ****
2025-11-26 07:34:12.840303 | controller |
2025-11-26 07:34:12.840430 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-11-26 07:34:12.840559 | controller | Wednesday 26 November 2025  07:34:12 +0000 (0:00:00.058)       0:00:02.205 ****
2025-11-26 07:34:13.375246 | controller | changed: [instance] => (item=tmp)
2025-11-26 07:34:13.375297 | controller | changed: [instance] => (item=artifacts/repositories)
2025-11-26 07:34:13.375412 | controller | changed: [instance] => (item=venv/repo_setup)
2025-11-26 07:34:13.375498 | controller |
2025-11-26 07:34:13.375600 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-11-26 07:34:13.375706 | controller | Wednesday 26 November 2025  07:34:13 +0000 (0:00:00.534)       0:00:02.739 ****
2025-11-26 07:34:14.147283 | controller | ok: [instance]
2025-11-26 07:34:14.147591 | controller |
2025-11-26 07:34:15.184003 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-11-26 07:34:15.184042 | controller | Wednesday 26 November 2025  07:34:14 +0000 (0:00:00.772)       0:00:03.512 ****
2025-11-26 07:34:15.184055 | controller | changed: [instance]
2025-11-26 07:34:15.184079 | controller |
2025-11-26 07:34:15.184100 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-11-26 07:34:15.184249 | controller | Wednesday 26 November 2025  07:34:15 +0000 (0:00:01.036)       0:00:04.548 ****
2025-11-26 07:34:23.101689 | controller | changed: [instance]
2025-11-26 07:34:23.805405 | controller |
2025-11-26 07:34:23.805443 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-11-26 07:34:23.805452 | controller | Wednesday 26 November 2025  07:34:23 +0000 (0:00:07.917)       0:00:12.466 ****
2025-11-26 07:34:23.805463 | controller | changed: [instance]
2025-11-26 07:34:23.826930 | controller |
2025-11-26 07:34:23.826962 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-11-26 07:34:23.826971 | controller | Wednesday 26 November 2025  07:34:23 +0000 (0:00:00.703)       0:00:13.169 ****
2025-11-26 07:34:23.826982 | controller | skipping: [instance]
2025-11-26 07:34:24.626925 | controller |
2025-11-26 07:34:24.626959 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-11-26 07:34:24.626967 | controller | Wednesday 26 November 2025  07:34:23 +0000 (0:00:00.021)       0:00:13.191 ****
2025-11-26 07:34:24.626977 | controller | changed: [instance]
2025-11-26 07:34:24.627005 | controller |
2025-11-26 07:34:24.627013 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-11-26 07:34:24.627162 | controller | Wednesday 26 November 2025  07:34:24 +0000 (0:00:00.800)       0:00:13.991 ****
2025-11-26 07:34:24.664060 | controller | skipping: [instance]
2025-11-26 07:34:24.698929 | controller |
2025-11-26 07:34:24.698960 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-11-26 07:34:24.698968 | controller | Wednesday 26 November 2025  07:34:24 +0000 (0:00:00.036)       0:00:14.028 ****
2025-11-26 07:34:24.698979 | controller | skipping: [instance]
2025-11-26 07:34:24.699024 | controller |
2025-11-26 07:34:24.699034 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-11-26 07:34:24.699478 | controller | Wednesday 26 November 2025  07:34:24 +0000 (0:00:00.035)       0:00:14.063 ****
2025-11-26 07:34:24.736975 | controller | skipping: [instance]
2025-11-26 07:34:24.737094 | controller |
2025-11-26 07:34:24.737116 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-11-26 07:34:24.737129 | controller | Wednesday 26 November 2025  07:34:24 +0000 (0:00:00.037)       0:00:14.101 ****
2025-11-26 07:34:25.293320 | controller | changed: [instance]
2025-11-26 07:34:25.758213 | controller |
2025-11-26 07:34:25.758243 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-11-26 07:34:25.758251 | controller | Wednesday 26 November 2025  07:34:25 +0000 (0:00:00.556)       0:00:14.657 ****
2025-11-26 07:34:25.758262 | controller | changed: [instance]
2025-11-26 07:34:25.788231 | controller |
2025-11-26 07:34:25.788253 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-11-26 07:34:25.788261 | controller | Wednesday 26 November 2025  07:34:25 +0000 (0:00:00.464)       0:00:15.122 ****
2025-11-26 07:34:25.788270 | controller | skipping: [instance]
2025-11-26 07:34:25.816347 | controller |
2025-11-26 07:34:25.816365 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-11-26 07:34:25.816372 | controller | Wednesday 26 November 2025  07:34:25 +0000 (0:00:00.030)       0:00:15.152 ****
2025-11-26 07:34:25.816384 | controller | skipping: [instance]
2025-11-26 07:34:25.844940 | controller |
2025-11-26 07:34:25.844960 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-11-26 07:34:25.844968 | controller | Wednesday 26 November 2025  07:34:25 +0000 (0:00:00.028)       0:00:15.181 ****
2025-11-26 07:34:25.844977 | controller | skipping: [instance]
2025-11-26 07:34:25.883335 | controller |
2025-11-26 07:34:25.883359 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-11-26 07:34:25.883367 | controller | Wednesday 26 November 2025  07:34:25 +0000 (0:00:00.028)       0:00:15.209 ****
2025-11-26 07:34:25.883375 | controller | ok: [instance]
2025-11-26 07:34:25.911481 | controller |
2025-11-26 07:34:25.911501 | controller | TASK [repo_setup : Create download directory] **********************************
2025-11-26 07:34:25.911508 | controller | Wednesday 26 November 2025  07:34:25 +0000 (0:00:00.038)       0:00:15.248 ****
2025-11-26 07:34:25.911517 | controller | skipping: [instance]
2025-11-26 07:34:25.939251 | controller |
2025-11-26 07:34:25.939275 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-11-26 07:34:25.939283 | controller | Wednesday 26 November 2025  07:34:25 +0000 (0:00:00.028)       0:00:15.276 ****
2025-11-26 07:34:25.939300 | controller | skipping: [instance]
2025-11-26 07:34:25.966571 | controller |
2025-11-26 07:34:25.966594 | controller | TASK [Download the RPM] ********************************************************
2025-11-26 07:34:25.966602 | controller | Wednesday 26 November 2025  07:34:25 +0000 (0:00:00.027)       0:00:15.303 ****
2025-11-26 07:34:25.966612 | controller | skipping: [instance]
2025-11-26 07:34:25.993945 | controller |
2025-11-26 07:34:25.993988 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-11-26 07:34:25.993997 | controller | Wednesday 26 November 2025  07:34:25 +0000 (0:00:00.027)       0:00:15.331 ****
2025-11-26 07:34:25.994008 | controller | skipping: [instance]
2025-11-26 07:34:25.994025 | controller |
2025-11-26 07:34:25.994033 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-11-26 07:34:25.994135 | controller | Wednesday 26 November 2025  07:34:25 +0000 (0:00:00.027)       0:00:15.358 ****
2025-11-26 07:34:26.021809 | controller | skipping: [instance]
2025-11-26 07:34:26.049867 | controller |
2025-11-26 07:34:26.049889 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-11-26 07:34:26.049899 | controller | Wednesday 26 November 2025  07:34:26 +0000 (0:00:00.027)       0:00:15.386 ****
2025-11-26 07:34:26.049908 | controller | skipping: [instance]
2025-11-26 07:34:26.075029 | controller |
2025-11-26 07:34:26.075046 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-11-26 07:34:26.075054 | controller | Wednesday 26 November 2025  07:34:26 +0000 (0:00:00.028)       0:00:15.414 ****
2025-11-26 07:34:26.075062 | controller | skipping: [instance]
2025-11-26 07:34:26.266388 | controller |
2025-11-26 07:34:26.266417 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-11-26 07:34:26.266424 | controller | Wednesday 26 November 2025  07:34:26 +0000 (0:00:00.025)       0:00:15.439 ****
2025-11-26 07:34:26.266434 | controller | ok: [instance]
2025-11-26 07:34:26.473361 | controller |
2025-11-26 07:34:26.473391 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-11-26 07:34:26.473399 | controller | Wednesday 26 November 2025  07:34:26 +0000 (0:00:00.190)       0:00:15.630 ****
2025-11-26 07:34:26.473409 | controller | changed: [instance]
2025-11-26 07:34:26.694241 | controller |
2025-11-26 07:34:26.694271 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-11-26 07:34:26.694279 | controller | Wednesday 26 November 2025  07:34:26 +0000 (0:00:00.207)       0:00:15.837 ****
2025-11-26 07:34:26.694290 | controller | changed: [instance]
2025-11-26 07:34:26.715000 | controller |
2025-11-26 07:34:26.715038 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-11-26 07:34:26.715048 | controller | Wednesday 26 November 2025  07:34:26 +0000 (0:00:00.220)       0:00:16.058 ****
2025-11-26 07:34:26.715061 | controller | skipping: [instance]
2025-11-26 07:34:26.736944 | controller |
2025-11-26 07:34:26.736978 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-11-26 07:34:26.736986 | controller | Wednesday 26 November 2025  07:34:26 +0000 (0:00:00.020)       0:00:16.079 ****
2025-11-26 07:34:26.736997 | controller | skipping: [instance]
2025-11-26 07:34:26.737016 | controller |
2025-11-26 07:34:26.737040 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-11-26 07:34:26.737173 | controller | Wednesday 26 November 2025  07:34:26 +0000 (0:00:00.022)       0:00:16.101 ****
2025-11-26 07:34:26.759124 | controller | skipping: [instance]
2025-11-26 07:34:26.796986 | controller |
2025-11-26 07:34:26.797030 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-11-26 07:34:26.797039 | controller | Wednesday 26 November 2025  07:34:26 +0000 (0:00:00.021)       0:00:16.123 ****
2025-11-26 07:34:26.797053 | controller | skipping: [instance]
2025-11-26 07:34:26.819015 | controller |
2025-11-26 07:34:26.819043 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-11-26 07:34:26.819060 | controller | Wednesday 26 November 2025  07:34:26 +0000 (0:00:00.035)       0:00:16.159 ****
2025-11-26 07:34:26.819072 | controller | skipping: [instance]
2025-11-26 07:34:26.840976 | controller |
2025-11-26 07:34:26.840994 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-11-26 07:34:26.841008 | controller | Wednesday 26 November 2025  07:34:26 +0000 (0:00:00.024)       0:00:16.183 ****
2025-11-26 07:34:26.841017 | controller | skipping: [instance]
2025-11-26 07:34:26.857247 | controller |
2025-11-26 07:34:26.857270 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-11-26 07:34:26.857279 | controller | Wednesday 26 November 2025  07:34:26 +0000 (0:00:00.021)       0:00:16.205 ****
2025-11-26 07:34:26.857290 | controller | skipping: [instance]
2025-11-26 07:34:26.857469 | controller |
2025-11-26 07:34:26.857613 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-11-26 07:34:26.857769 | controller | Wednesday 26 November 2025  07:34:26 +0000 (0:00:00.016)       0:00:16.222 ****
2025-11-26 07:34:26.886707 | controller | skipping: [instance]
2025-11-26 07:34:26.886936 | controller |
2025-11-26 07:34:26.887125 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-11-26 07:34:26.887307 | controller | Wednesday 26 November 2025  07:34:26 +0000 (0:00:00.029)       0:00:16.251 ****
2025-11-26 07:34:26.903035 | controller | skipping: [instance]
2025-11-26 07:34:26.903224 | controller |
2025-11-26 07:34:26.903417 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-11-26 07:34:26.903581 | controller | Wednesday 26 November 2025  07:34:26 +0000 (0:00:00.016)       0:00:16.268 ****
2025-11-26 07:34:26.923601 | controller | skipping: [instance]
2025-11-26 07:34:26.923805 | controller |
2025-11-26 07:34:26.924014 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-11-26 07:34:26.924194 | controller | Wednesday 26 November 2025  07:34:26 +0000 (0:00:00.020)       0:00:16.288 ****
2025-11-26 07:34:26.953362 | controller | skipping: [instance]
2025-11-26 07:34:26.953547 | controller |
2025-11-26 07:34:26.953708 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-11-26 07:34:26.953889 | controller | Wednesday 26 November 2025  07:34:26 +0000 (0:00:00.029)       0:00:16.318 ****
2025-11-26 07:34:26.982668 | controller | skipping: [instance]
2025-11-26 07:34:26.982894 | controller |
2025-11-26 07:34:26.983083 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-11-26 07:34:26.983256 | controller | Wednesday 26 November 2025  07:34:26 +0000 (0:00:00.029)       0:00:16.347 ****
2025-11-26 07:34:27.012487 | controller | skipping: [instance]
2025-11-26 07:34:27.012674 | controller |
2025-11-26 07:34:27.012866 | controller | TASK [test_deps : Install additional packages] *********************************
2025-11-26 07:34:27.013038 | controller | Wednesday 26 November 2025  07:34:27 +0000 (0:00:00.029)       0:00:16.377 ****
2025-11-26 07:34:27.041550 | controller | skipping: [instance]
2025-11-26 07:34:27.041703 | controller |
2025-11-26 07:34:27.041885 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-11-26 07:34:27.042036 | controller | Wednesday 26 November 2025  07:34:27 +0000 (0:00:00.029)       0:00:16.406 ****
2025-11-26 07:35:10.164723 | controller | ok: [instance]
2025-11-26 07:35:11.356932 | controller |
2025-11-26 07:35:11.356963 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-11-26 07:35:11.356971 | controller | Wednesday 26 November 2025  07:35:10 +0000 (0:00:43.122)       0:00:59.529 ****
2025-11-26 07:35:11.356980 | controller | ok: [instance]
2025-11-26 07:35:11.357174 | controller |
2025-11-26 07:35:11.357188 | controller | TASK [test_deps : Install extra packages] **************************************
2025-11-26 07:35:11.401907 | controller | Wednesday 26 November 2025  07:35:11 +0000 (0:00:01.192)       0:01:00.721 ****
2025-11-26 07:35:11.401930 | controller | skipping: [instance]
2025-11-26 07:35:11.403162 | controller |
2025-11-26 07:35:11.403179 | controller | PLAY RECAP *********************************************************************
2025-11-26 07:35:11.403186 | controller | instance                   : ok=19   changed=9    unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-11-26 07:35:11.403192 | controller |
2025-11-26 07:35:11.403198 | controller | Wednesday 26 November 2025  07:35:11 +0000 (0:00:00.043)       0:01:00.765 ****
2025-11-26 07:35:11.403203 | controller | ===============================================================================
2025-11-26 07:35:11.403208 | controller | test_deps : Install selinux python libs -------------------------------- 43.12s
2025-11-26 07:35:11.403220 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.92s
2025-11-26 07:35:11.403225 | controller | test_deps : Install python yaml libs ------------------------------------ 1.19s
2025-11-26 07:35:11.403231 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.04s
2025-11-26 07:35:11.403240 | controller | Gathering Facts --------------------------------------------------------- 0.82s
2025-11-26 07:35:11.403246 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.80s
2025-11-26 07:35:11.403251 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.77s
2025-11-26 07:35:11.403259 | controller | repo_setup : Install repo-setup package --------------------------------- 0.70s
2025-11-26 07:35:11.460049 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.67s
2025-11-26 07:35:11.460080 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.56s
2025-11-26 07:35:11.460088 | controller | repo_setup : Ensure directories are present ----------------------------- 0.53s
2025-11-26 07:35:11.460094 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.46s
2025-11-26 07:35:11.460099 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.31s
2025-11-26 07:35:11.460105 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s
2025-11-26 07:35:11.460110 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.22s
2025-11-26 07:35:11.460116 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.21s
2025-11-26 07:35:11.460121 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.19s
2025-11-26 07:35:11.460126 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-11-26 07:35:11.460131 | controller | test_deps : Install extra packages -------------------------------------- 0.04s
2025-11-26 07:35:11.460137 | controller | test_deps : Gather variables for each operating system ------------------ 0.04s
2025-11-26 07:35:11.460147 | controller | INFO     Running default > converge
2025-11-26 07:35:11.901025 | controller |
2025-11-26 07:35:11.901223 | controller | PLAY [Converge] ****************************************************************
2025-11-26 07:35:11.901368 | controller |
2025-11-26 07:35:11.901510 | controller | TASK [Gathering Facts] *********************************************************
2025-11-26 07:35:11.901655 | controller | Wednesday 26 November 2025  07:35:11 +0000 (0:00:00.013)       0:00:00.013 ****
2025-11-26 07:35:12.691951 | controller | ok: [instance]
2025-11-26 07:35:12.692259 | controller |
2025-11-26 07:35:12.692276 | controller | TASK [Add compute-0 to inventory] **********************************************
2025-11-26 07:35:12.708287 | controller | Wednesday 26 November 2025  07:35:12 +0000 (0:00:00.790)       0:00:00.804 ****
2025-11-26 07:35:12.708308 | controller | changed: [instance]
2025-11-26 07:35:13.193441 | controller |
2025-11-26 07:35:13.193471 | controller | TASK [Ensure minimal dirs exists] **********************************************
2025-11-26 07:35:13.193480 | controller | Wednesday 26 November 2025  07:35:12 +0000 (0:00:00.016)       0:00:00.820 ****
2025-11-26 07:35:13.193490 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-11-26 07:35:13.762371 | controller | changed: [instance] => (item=/etc/ci/env)
2025-11-26 07:35:13.762402 | controller |
2025-11-26 07:35:13.762411 | controller | TASK [Expected network info] ***************************************************
2025-11-26 07:35:13.762417 | controller | Wednesday 26 November 2025  07:35:13 +0000 (0:00:00.484)       0:00:01.305 ****
2025-11-26 07:35:13.762427 | controller | changed: [instance]
2025-11-26 07:35:13.793895 | controller |
2025-11-26 07:35:13.793925 | controller | TASK [hci_prepare : Set common facts] ******************************************
2025-11-26 07:35:13.793934 | controller | Wednesday 26 November 2025  07:35:13 +0000 (0:00:00.568)       0:00:01.874 ****
2025-11-26 07:35:13.793944 | controller | ok: [instance]
2025-11-26 07:35:13.831993 | controller |
2025-11-26 07:35:13.832030 | controller | TASK [hci_prepare : Load parameters] *******************************************
2025-11-26 07:35:13.832047 | controller | Wednesday 26 November 2025  07:35:13 +0000 (0:00:00.031)       0:00:01.906 ****
2025-11-26 07:35:13.832059 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/hci_prepare/tasks/load_parameters.yml for instance
2025-11-26 07:35:13.832087 | controller |
2025-11-26 07:35:13.832304 | controller | TASK [hci_prepare : Load parameters] *******************************************
2025-11-26 07:35:13.861961 | controller | Wednesday 26 November 2025  07:35:13 +0000 (0:00:00.038)       0:00:01.944 ****
2025-11-26 07:35:13.862005 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-11-26 07:35:13.862037 | controller | ok: [instance] => (item=/etc/ci/env)
2025-11-26 07:35:13.862046 | controller |
2025-11-26 07:35:13.862053 | controller | TASK [hci_prepare : Extract first compute from inventory] **********************
2025-11-26 07:35:13.862061 | controller | Wednesday 26 November 2025  07:35:13 +0000 (0:00:00.029)       0:00:01.974 ****
2025-11-26 07:35:13.895979 | controller | ok: [instance]
2025-11-26 07:35:13.896134 | controller |
2025-11-26 07:35:13.896163 | controller | TASK [hci_prepare : Ensure we have needed bits for compute when needed] ********
2025-11-26 07:35:13.896174 | controller | Wednesday 26 November 2025  07:35:13 +0000 (0:00:00.033)       0:00:02.008 ****
2025-11-26 07:35:13.931944 | controller | ok: [instance] => changed=false
2025-11-26 07:35:13.932214 | controller |   msg: All assertions passed
2025-11-26 07:35:13.932226 | controller |
2025-11-26 07:35:13.932235 | controller | TASK [hci_prepare : Set mtu value from crc_ci_bootstrap_networks_out] **********
2025-11-26 07:35:13.966948 | controller | Wednesday 26 November 2025  07:35:13 +0000 (0:00:00.036)       0:00:02.044 ****
2025-11-26 07:35:13.966984 | controller | ok: [instance]
2025-11-26 07:35:13.967174 | controller |
2025-11-26 07:35:13.967197 | controller | TASK [hci_prepare : Set vlan value from crc_ci_bootstrap_networks_out] *********
2025-11-26 07:35:14.002567 | controller | Wednesday 26 November 2025  07:35:13 +0000 (0:00:00.035)       0:00:02.079 ****
2025-11-26 07:35:14.002595 | controller | ok: [instance]
2025-11-26 07:35:14.185907 | controller |
2025-11-26 07:35:14.185937 | controller | TASK [hci_prepare : Ensure the kustomizations dirs exists] *********************
2025-11-26 07:35:14.185945 | controller | Wednesday 26 November 2025  07:35:14 +0000 (0:00:00.035)       0:00:02.115 ****
2025-11-26 07:35:14.185955 | controller | changed: [instance]
2025-11-26 07:35:14.511085 | controller |
2025-11-26 07:35:14.511116 | controller | TASK [hci_prepare : Prepare EDPM network for HCI deployment] *******************
2025-11-26 07:35:14.511124 | controller | Wednesday 26 November 2025  07:35:14 +0000 (0:00:00.183)       0:00:02.298 ****
2025-11-26 07:35:14.511134 | controller | changed: [instance]
2025-11-26 07:35:14.839610 | controller |
2025-11-26 07:35:14.839643 | controller | TASK [hci_prepare : Enable services needed to deploy Ceph] *********************
2025-11-26 07:35:14.839651 | controller | Wednesday 26 November 2025  07:35:14 +0000 (0:00:00.325)       0:00:02.623 ****
2025-11-26 07:35:14.839661 | controller | changed: [instance]
2025-11-26 07:35:14.859959 | controller |
2025-11-26 07:35:14.859995 | controller | TASK [hci_prepare : Disable discover_hosts when deploying hci on phase1] *******
2025-11-26 07:35:14.860005 | controller | Wednesday 26 November 2025  07:35:14 +0000 (0:00:00.328)       0:00:02.951 ****
2025-11-26 07:35:14.860018 | controller | ok: [instance]
2025-11-26 07:35:14.890832 | controller |
2025-11-26 07:35:14.890906 | controller | TASK [Assert mtu and vlan values were set] *************************************
2025-11-26 07:35:14.890915 | controller | Wednesday 26 November 2025  07:35:14 +0000 (0:00:00.020)       0:00:02.972 ****
2025-11-26 07:35:14.890926 | controller | ok: [instance] => changed=false
2025-11-26 07:35:15.252098 | controller |   msg: All assertions passed
2025-11-26 07:35:15.252129 | controller |
2025-11-26 07:35:15.252137 | controller | TASK [Check expected kustomizations] *******************************************
2025-11-26 07:35:15.252143 | controller | Wednesday 26 November 2025  07:35:14 +0000 (0:00:00.030)       0:00:03.003 ****
2025-11-26 07:35:15.252153 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml)
2025-11-26 07:35:15.281967 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml)
2025-11-26 07:35:15.282001 | controller |
2025-11-26 07:35:15.282016 | controller | TASK [Check if expected files where created] ***********************************
2025-11-26 07:35:15.282024 | controller | Wednesday 26 November 2025  07:35:15 +0000 (0:00:00.361)       0:00:03.364 ****
2025-11-26 07:35:15.282037 | controller | ok: [instance] => (item={'changed': False, 'stat': {'exists': True, 'path': '/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 294, 'inode': 125836036, 'dev': 64513, 'nlink': 1, 'atime': 1764142514.4780629, 'mtime': 1764142514.3440619, 'ctime': 1764142514.481063, 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False, 'blocks': 8, 'block_size': 4096, 'device_type': 0, 'readable': True, 'writeable': True, 'executable': False, 'pw_name': 'zuul', 'gr_name': 'zuul', 'checksum': 'eb1b0d7f73292ae259724656eabf9af0d6b574b2', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '2618298037', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': '/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml', 'ansible_loop_var': 'item'}) => changed=false
2025-11-26 07:35:15.282073 | controller |   ansible_loop_var: item
2025-11-26 07:35:15.282081 | controller |   item:
2025-11-26 07:35:15.282087 | controller |     ansible_loop_var: item
2025-11-26 07:35:15.282092 | controller |     changed: false
2025-11-26 07:35:15.282097 | controller |     failed: false
2025-11-26 07:35:15.282103 | controller |     invocation:
2025-11-26 07:35:15.282108 | controller |       module_args:
2025-11-26 07:35:15.282113 | controller |         checksum_algorithm: sha1
2025-11-26 07:35:15.282119 | controller |         follow: false
2025-11-26 07:35:15.282126 | controller |         get_attributes: true
2025-11-26 07:35:15.283081 | controller |         get_checksum: true
2025-11-26 07:35:15.283115 | controller |         get_md5: false
2025-11-26 07:35:15.283139 | controller |         get_mime: true
2025-11-26 07:35:15.283146 | controller |         path: /home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml
2025-11-26 07:35:15.283151 | controller |     item: /home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml
2025-11-26 07:35:15.283157 | controller |     stat:
2025-11-26 07:35:15.283163 | controller |       atime: 1764142514.4780629
2025-11-26 07:35:15.283168 | controller |       attr_flags: ''
2025-11-26 07:35:15.283173 | controller |       attributes: []
2025-11-26 07:35:15.283179 | controller |       block_size: 4096
2025-11-26 07:35:15.283184 | controller |       blocks: 8
2025-11-26 07:35:15.283191 | controller |       charset: us-ascii
2025-11-26 07:35:15.283210 | controller |       checksum: eb1b0d7f73292ae259724656eabf9af0d6b574b2
2025-11-26 07:35:15.283896 | controller |       ctime: 1764142514.481063
2025-11-26 07:35:15.283942 | controller |       dev: 64513
2025-11-26 07:35:15.283951 | controller |       device_type: 0
2025-11-26 07:35:15.283956 | controller |       executable: false
2025-11-26 07:35:15.283961 | controller |       exists: true
2025-11-26 07:35:15.283967 | controller |       gid: 1000
2025-11-26 07:35:15.283972 | controller |       gr_name: zuul
2025-11-26 07:35:15.283979 | controller |       inode: 125836036
2025-11-26 07:35:15.284064 | controller |       isblk: false
2025-11-26 07:35:15.284186 | controller |       ischr: false
2025-11-26 07:35:15.284302 | controller |       isdir: false
2025-11-26 07:35:15.284410 | controller |       isfifo: false
2025-11-26 07:35:15.284518 | controller |       isgid: false
2025-11-26 07:35:15.284874 | controller |       islnk: false
2025-11-26 07:35:15.284994 | controller |       isreg: true
2025-11-26 07:35:15.285112 | controller |       issock: false
2025-11-26 07:35:15.285230 | controller |       isuid: false
2025-11-26 07:35:15.285371 | controller |       mimetype: text/plain
2025-11-26 07:35:15.285503 | controller |       mode: '0644'
2025-11-26 07:35:15.285632 | controller |       mtime: 1764142514.3440619
2025-11-26 07:35:15.285761 | controller |       nlink: 1
2025-11-26 07:35:15.285947 | controller |       path: /home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml
2025-11-26 07:35:15.286087 | controller |       pw_name: zuul
2025-11-26 07:35:15.286217 | controller |       readable: true
2025-11-26 07:35:15.286345 | controller |       rgrp: true
2025-11-26 07:35:15.286477 | controller |       roth: true
2025-11-26 07:35:15.286607 | controller |       rusr: true
2025-11-26 07:35:15.286735 | controller |       size: 294
2025-11-26 07:35:15.286894 | controller |       uid: 1000
2025-11-26 07:35:15.287045 | controller |       version: '2618298037'
2025-11-26 07:35:15.287177 | controller |       wgrp: false
2025-11-26 07:35:15.287309 | controller |       woth: false
2025-11-26 07:35:15.287436 | controller |       writeable: true
2025-11-26 07:35:15.287565 | controller |       wusr: true
2025-11-26 07:35:15.287693 | controller |       xgrp: false
2025-11-26 07:35:15.287821 | controller |       xoth: false
2025-11-26 07:35:15.287990 | controller |       xusr: false
2025-11-26 07:35:15.288127 | controller |   msg: All assertions passed
2025-11-26 07:35:15.288293 | controller | ok: [instance] => (item={'changed': False, 'stat': {'exists': True, 'path': '/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 446, 'inode': 138434545, 'dev': 64513, 'nlink': 1, 'atime': 1764142514.8050652, 'mtime': 1764142514.6740642, 'ctime': 1764142514.8080652, 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False, 'blocks': 8, 'block_size': 4096, 'device_type': 0, 'readable': True, 'writeable': True, 'executable': False, 'pw_name': 'zuul', 'gr_name': 'zuul', 'checksum': '3bb4e07b60ede01bd9d3d77cd53d3ab90828582d', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '633882820', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': '/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml', 'ansible_loop_var': 'item'}) => changed=false
2025-11-26 07:35:15.288443 | controller |   ansible_loop_var: item
2025-11-26 07:35:15.288576 | controller |   item:
2025-11-26 07:35:15.288704 | controller |     ansible_loop_var: item
2025-11-26 07:35:15.288830 | controller |     changed: false
2025-11-26 07:35:15.289001 | controller |     failed: false
2025-11-26 07:35:15.289137 | controller |     invocation:
2025-11-26 07:35:15.289263 | controller |       module_args:
2025-11-26 07:35:15.289390 | controller |         checksum_algorithm: sha1
2025-11-26 07:35:15.289519 | controller |         follow: false
2025-11-26 07:35:15.289647 | controller |         get_attributes: true
2025-11-26 07:35:15.289775 | controller |         get_checksum: true
2025-11-26 07:35:15.289953 | controller |         get_md5: false
2025-11-26 07:35:15.290089 | controller |         get_mime: true
2025-11-26 07:35:15.290223 | controller |         path: /home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml
2025-11-26 07:35:15.290361 | controller |     item: /home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml
2025-11-26 07:35:15.290489 | controller |     stat:
2025-11-26 07:35:15.290630 | controller |       atime: 1764142514.8050652
2025-11-26 07:35:15.290763 | controller |       attr_flags: ''
2025-11-26 07:35:15.290930 | controller |       attributes: []
2025-11-26 07:35:15.291067 | controller |       block_size: 4096
2025-11-26 07:35:15.291196 | controller |       blocks: 8
2025-11-26 07:35:15.291324 | controller |       charset: us-ascii
2025-11-26 07:35:15.291454 | controller |       checksum: 3bb4e07b60ede01bd9d3d77cd53d3ab90828582d
2025-11-26 07:35:15.291583 | controller |       ctime: 1764142514.8080652
2025-11-26 07:35:15.291709 | controller |       dev: 64513
2025-11-26 07:35:15.291836 | controller |       device_type: 0
2025-11-26 07:35:15.292012 | controller |       executable: false
2025-11-26 07:35:15.292146 | controller |       exists: true
2025-11-26 07:35:15.292275 | controller |       gid: 1000
2025-11-26 07:35:15.292403 | controller |       gr_name: zuul
2025-11-26 07:35:15.292549 | controller |       inode: 138434545
2025-11-26 07:35:15.292678 | controller |       isblk: false
2025-11-26 07:35:15.292805 | controller |       ischr: false
2025-11-26 07:35:15.292966 | controller |       isdir: false
2025-11-26 07:35:15.293100 | controller |       isfifo: false
2025-11-26 07:35:15.293228 | controller |       isgid: false
2025-11-26 07:35:15.293354 | controller |       islnk: false
2025-11-26 07:35:15.293479 | controller |       isreg: true
2025-11-26 07:35:15.293606 | controller |       issock: false
2025-11-26 07:35:15.293732 | controller |       isuid: false
2025-11-26 07:35:15.293895 | controller |       mimetype: text/plain
2025-11-26 07:35:15.294038 | controller |       mode: '0644'
2025-11-26 07:35:15.294175 | controller |       mtime: 1764142514.6740642
2025-11-26 07:35:15.294303 | controller |       nlink: 1
2025-11-26 07:35:15.294440 | controller |       path: /home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml
2025-11-26 07:35:15.294569 | controller |       pw_name: zuul
2025-11-26 07:35:15.294694 | controller |       readable: true
2025-11-26 07:35:15.294819 | controller |       rgrp: true
2025-11-26 07:35:15.294980 | controller |       roth: true
2025-11-26 07:35:15.295118 | controller |       rusr: true
2025-11-26 07:35:15.295245 | controller |       size: 446
2025-11-26 07:35:15.295370 | controller |       uid: 1000
2025-11-26 07:35:15.295495 | controller |       version: '633882820'
2025-11-26 07:35:15.295621 | controller |       wgrp: false
2025-11-26 07:35:15.295746 | controller |       woth: false
2025-11-26 07:35:15.295901 | controller |       writeable: true
2025-11-26 07:35:15.296036 | controller |       wusr: true
2025-11-26 07:35:15.296169 | controller |       xgrp: false
2025-11-26 07:35:15.296295 | controller |       xoth: false
2025-11-26 07:35:15.296421 | controller |       xusr: false
2025-11-26 07:35:15.296559 | controller |   msg: All assertions passed
2025-11-26 07:35:15.296681 | controller |
2025-11-26 07:35:15.296809 | controller | TASK [hci_prepare : Set common facts] ******************************************
2025-11-26 07:35:15.296972 | controller | Wednesday 26 November 2025  07:35:15 +0000 (0:00:00.029)       0:00:03.393 ****
2025-11-26 07:35:15.310575 | controller | ok: [instance]
2025-11-26 07:35:15.310755 | controller |
2025-11-26 07:35:15.310967 | controller | TASK [hci_prepare : Ensure directories] ****************************************
2025-11-26 07:35:15.311139 | controller | Wednesday 26 November 2025  07:35:15 +0000 (0:00:00.029)       0:00:03.423 ****
2025-11-26 07:35:15.663361 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts)
2025-11-26 07:35:15.679932 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane)
2025-11-26 07:35:15.679963 | controller |
2025-11-26 07:35:15.679971 | controller | TASK [hci_prepare : Create ceph config secret] *********************************
2025-11-26 07:35:15.679977 | controller | Wednesday 26 November 2025  07:35:15 +0000 (0:00:00.351)       0:00:03.775 ****
2025-11-26 07:35:15.679987 | controller | skipping: [instance]
2025-11-26 07:35:15.680550 | controller |
2025-11-26 07:35:15.680896 | controller | TASK [hci_prepare : Set Ceph FSID fact] ****************************************
2025-11-26 07:35:15.680912 | controller | Wednesday 26 November 2025  07:35:15 +0000 (0:00:00.016)       0:00:03.791 ****
2025-11-26 07:35:15.695101 | controller | skipping: [instance]
2025-11-26 07:35:15.695597 | controller |
2025-11-26 07:35:15.696255 | controller | TASK [hci_prepare : Generate nova config map] **********************************
2025-11-26 07:35:15.696543 | controller | Wednesday 26 November 2025  07:35:15 +0000 (0:00:00.015)       0:00:03.807 ****
2025-11-26 07:35:15.709553 | controller | skipping: [instance]
2025-11-26 07:35:15.709690 | controller |
2025-11-26 07:35:15.709826 | controller | TASK [hci_prepare : Create nova config map] ************************************
2025-11-26 07:35:15.709999 | controller | Wednesday 26 November 2025  07:35:15 +0000 (0:00:00.014)       0:00:03.822 ****
2025-11-26 07:35:15.724520 | controller | skipping: [instance]
2025-11-26 07:35:15.724690 | controller |
2025-11-26 07:35:15.724883 | controller | TASK [hci_prepare : Generate Ceph-Nova Dataplane Service] **********************
2025-11-26 07:35:15.725041 | controller | Wednesday 26 November 2025  07:35:15 +0000 (0:00:00.014)       0:00:03.837 ****
2025-11-26 07:35:15.739610 | controller | skipping: [instance]
2025-11-26 07:35:15.739749 | controller |
2025-11-26 07:35:15.739920 | controller | TASK [hci_prepare : Create Ceph-Nova Dataplane Service] ************************
2025-11-26 07:35:15.740070 | controller | Wednesday 26 November 2025  07:35:15 +0000 (0:00:00.015)       0:00:03.852 ****
2025-11-26 07:35:15.754684 | controller | skipping: [instance]
2025-11-26 07:35:15.754825 | controller |
2025-11-26 07:35:15.755000 | controller | TASK [hci_prepare : Delete OpenStackDataPlaneDeployment] ***********************
2025-11-26 07:35:15.755143 | controller | Wednesday 26 November 2025  07:35:15 +0000 (0:00:00.015)       0:00:03.867 ****
2025-11-26 07:35:15.769903 | controller | skipping: [instance]
2025-11-26 07:35:15.770068 | controller |
2025-11-26 07:35:15.770236 | controller | TASK [hci_prepare : Create configuration to finish HCI deployment] *************
2025-11-26 07:35:15.770398 | controller | Wednesday 26 November 2025  07:35:15 +0000 (0:00:00.015)       0:00:03.882 ****
2025-11-26 07:35:16.099174 | controller | changed: [instance]
2025-11-26 07:35:16.115562 | controller |
2025-11-26 07:35:16.115585 | controller | TASK [hci_prepare : Enabled nova discover_hosts after deployment] **************
2025-11-26 07:35:16.115595 | controller | Wednesday 26 November 2025  07:35:16 +0000 (0:00:00.328)       0:00:04.211 ****
2025-11-26 07:35:16.115606 | controller | ok: [instance]
2025-11-26 07:35:16.135964 | controller |
2025-11-26 07:35:16.135999 | controller | TASK [hci_prepare : Save HCI info] *********************************************
2025-11-26 07:35:16.136008 | controller | Wednesday 26 November 2025  07:35:16 +0000 (0:00:00.016)       0:00:04.228 ****
2025-11-26 07:35:16.136020 | controller | skipping: [instance]
2025-11-26 07:35:16.324739 | controller |
2025-11-26 07:35:16.324771 | controller | TASK [Check expected kustomizations - phase 2] *********************************
2025-11-26 07:35:16.324779 | controller | Wednesday 26 November 2025  07:35:16 +0000 (0:00:00.020)       0:00:04.248 ****
2025-11-26 07:35:16.324788 | controller | ok: [instance]
2025-11-26 07:35:16.356933 | controller |
2025-11-26 07:35:16.356960 | controller | TASK [Check if expected files where created - phase 2] *************************
2025-11-26 07:35:16.356969 | controller | Wednesday 26 November 2025  07:35:16 +0000 (0:00:00.188)       0:00:04.437 ****
2025-11-26 07:35:16.356980 | controller | ok: [instance] => changed=false
2025-11-26 07:35:16.359509 | controller |   msg: All assertions passed
2025-11-26 07:35:16.414063 | controller |
2025-11-26 07:35:16.414095 | controller | PLAY RECAP *********************************************************************
2025-11-26 07:35:16.414104 | controller | instance                   : ok=24   changed=7    unreachable=0    failed=0    skipped=8    rescued=0    ignored=0
2025-11-26 07:35:16.414110 | controller |
2025-11-26 07:35:16.414115 | controller | Wednesday 26 November 2025  07:35:16 +0000 (0:00:00.032)       0:00:04.469 ****
2025-11-26 07:35:16.414120 | controller | ===============================================================================
2025-11-26 07:35:16.414126 | controller | Gathering Facts --------------------------------------------------------- 0.79s
2025-11-26 07:35:16.414131 | controller | Expected network info --------------------------------------------------- 0.57s
2025-11-26 07:35:16.414136 | controller | Ensure minimal dirs exists ---------------------------------------------- 0.48s
2025-11-26 07:35:16.414141 | controller | Check expected kustomizations ------------------------------------------- 0.36s
2025-11-26 07:35:16.414147 | controller | hci_prepare : Ensure directories ---------------------------------------- 0.35s
2025-11-26 07:35:16.414152 | controller | hci_prepare : Create configuration to finish HCI deployment ------------- 0.33s
2025-11-26 07:35:16.414158 | controller | hci_prepare : Enable services needed to deploy Ceph --------------------- 0.33s
2025-11-26 07:35:16.414163 | controller | hci_prepare : Prepare EDPM network for HCI deployment ------------------- 0.33s
2025-11-26 07:35:16.414168 | controller | Check expected kustomizations - phase 2 --------------------------------- 0.19s
2025-11-26 07:35:16.414178 | controller | hci_prepare : Ensure the kustomizations dirs exists --------------------- 0.18s
2025-11-26 07:35:16.414183 | controller | hci_prepare : Load parameters ------------------------------------------- 0.04s
2025-11-26 07:35:16.414189 | controller | hci_prepare : Ensure we have needed bits for compute when needed -------- 0.04s
2025-11-26 07:35:16.414194 | controller | hci_prepare : Set vlan value from crc_ci_bootstrap_networks_out --------- 0.04s
2025-11-26 07:35:16.414199 | controller | hci_prepare : Set mtu value from crc_ci_bootstrap_networks_out ---------- 0.04s
2025-11-26 07:35:16.414204 | controller | hci_prepare : Extract first compute from inventory ---------------------- 0.03s
2025-11-26 07:35:16.414214 | controller | Check if expected files where created - phase 2 ------------------------- 0.03s
2025-11-26 07:35:16.414219 | controller | hci_prepare : Set common facts ------------------------------------------ 0.03s
2025-11-26 07:35:16.414224 | controller | Assert mtu and vlan values were set ------------------------------------- 0.03s
2025-11-26 07:35:16.414230 | controller | hci_prepare : Load parameters ------------------------------------------- 0.03s
2025-11-26 07:35:16.414235 | controller | hci_prepare : Set common facts ------------------------------------------ 0.03s
2025-11-26 07:35:16.414245 | controller | INFO     Running default > cleanup
2025-11-26 07:35:16.414702 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-11-26 07:35:16.415300 | controller | INFO     Writing /tmp/report.html report.
2025-11-26 07:35:16.636917 | controller | changed
2025-11-26 07:35:16.659360 | 
2025-11-26 07:35:16.659440 | PLAY RECAP
2025-11-26 07:35:16.659486 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-26 07:35:16.659588 | 
2025-11-26 07:35:16.744049 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-26 07:35:16.744860 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-26 07:35:17.302501 | 
2025-11-26 07:35:17.302615 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-11-26 07:35:17.323512 | 
2025-11-26 07:35:17.323627 | TASK [Filter out host if needed]
2025-11-26 07:35:17.332448 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-26 07:35:17.337174 | 
2025-11-26 07:35:17.337250 | TASK [Ensure file is present]
2025-11-26 07:35:17.671656 | controller | ok
2025-11-26 07:35:17.683404 | 
2025-11-26 07:35:17.683545 | TASK [Manage molecule report file]
2025-11-26 07:35:18.210550 | controller | changed
2025-11-26 07:35:18.219179 | 
2025-11-26 07:35:18.219318 | TASK [Check if we get ci-framework-data basedir]
2025-11-26 07:35:18.421150 | controller | ok
2025-11-26 07:35:18.426459 | 
2025-11-26 07:35:18.426529 | TASK [Create ci-framework-data log directory for zuul]
2025-11-26 07:35:18.729487 | controller | changed
2025-11-26 07:35:18.735196 | 
2025-11-26 07:35:18.735274 | TASK [Copy ci-framework interesting files]
2025-11-26 07:35:18.928148 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-11-26 07:35:19.263960 | controller | changed
2025-11-26 07:35:19.269678 | 
2025-11-26 07:35:19.269753 | TASK [Get SELinux listing]
2025-11-26 07:35:19.795001 | controller | changed
2025-11-26 07:35:19.802070 | 
2025-11-26 07:35:19.802183 | TASK [Generate log index]
2025-11-26 07:35:20.450818 | controller | changed
2025-11-26 07:35:20.455720 | 
2025-11-26 07:35:20.455788 | TASK [Get some env related data]
2025-11-26 07:35:20.975743 | controller | /home/zuul/.local/bin/ansible
2025-11-26 07:35:22.013709 | controller | changed
2025-11-26 07:35:22.019106 | 
2025-11-26 07:35:22.019191 | TASK [Generate list of logs to collect in home directory]
2025-11-26 07:35:22.333327 | controller | ok: All paths examined
2025-11-26 07:35:22.338401 | 
2025-11-26 07:35:22.338488 | LOOP [Copy logs from home directory]
2025-11-26 07:35:22.655325 | controller | changed:
2025-11-26 07:35:22.655459 | controller | {
2025-11-26 07:35:22.655489 | controller |   "atime": 1764142413.9052973,
2025-11-26 07:35:22.655515 | controller |   "ctime": 1764142430.015437,
2025-11-26 07:35:22.655534 | controller |   "dev": 64513,
2025-11-26 07:35:22.655552 | controller |   "gid": 1000,
2025-11-26 07:35:22.655568 | controller |   "gr_name": "zuul",
2025-11-26 07:35:22.655586 | controller |   "inode": 4333703,
2025-11-26 07:35:22.655602 | controller |   "isblk": false,
2025-11-26 07:35:22.655618 | controller |   "ischr": false,
2025-11-26 07:35:22.655633 | controller |   "isdir": false,
2025-11-26 07:35:22.655648 | controller |   "isfifo": false,
2025-11-26 07:35:22.655663 | controller |   "isgid": false,
2025-11-26 07:35:22.655677 | controller |   "islnk": false,
2025-11-26 07:35:22.655692 | controller |   "isreg": true,
2025-11-26 07:35:22.655706 | controller |   "issock": false,
2025-11-26 07:35:22.655720 | controller |   "isuid": false,
2025-11-26 07:35:22.655734 | controller |   "mode": "0644",
2025-11-26 07:35:22.655748 | controller |   "mtime": 1764142430.015437,
2025-11-26 07:35:22.655762 | controller |   "nlink": 1,
2025-11-26 07:35:22.655776 | controller |   "path": "/home/zuul/ansible.log",
2025-11-26 07:35:22.655793 | controller |   "pw_name": "zuul",
2025-11-26 07:35:22.655808 | controller |   "rgrp": true,
2025-11-26 07:35:22.655823 | controller |   "roth": true,
2025-11-26 07:35:22.655838 | controller |   "rusr": true,
2025-11-26 07:35:22.655853 | controller |   "size": 6749,
2025-11-26 07:35:22.655868 | controller |   "uid": 1000,
2025-11-26 07:35:22.655882 | controller |   "wgrp": false,
2025-11-26 07:35:22.655896 | controller |   "woth": false,
2025-11-26 07:35:22.655913 | controller |   "wusr": true,
2025-11-26 07:35:22.655930 | controller |   "xgrp": false,
2025-11-26 07:35:22.655958 | controller |   "xoth": false,
2025-11-26 07:35:22.655974 | controller |   "xusr": false
2025-11-26 07:35:22.655989 | controller | }
2025-11-26 07:35:22.668815 | 
2025-11-26 07:35:22.668926 | TASK [Copy crio stats log file]
2025-11-26 07:35:22.682606 | controller | skipping: Conditional result was False
2025-11-26 07:35:22.688751 | 
2025-11-26 07:35:22.688838 | TASK [Get SELinux related data]
2025-11-26 07:35:22.893947 | controller | <no matches>
2025-11-26 07:35:23.216339 | controller | ERROR
2025-11-26 07:35:23.216475 | controller | {
2025-11-26 07:35:23.216508 | controller |   "delta": "0:00:00.009787",
2025-11-26 07:35:23.216530 | controller |   "end": "2025-11-26 07:35:22.894755",
2025-11-26 07:35:23.216548 | controller |   "msg": "non-zero return code",
2025-11-26 07:35:23.216564 | controller |   "rc": 1,
2025-11-26 07:35:23.216579 | controller |   "start": "2025-11-26 07:35:22.884968"
2025-11-26 07:35:23.216594 | controller | }
2025-11-26 07:35:23.216614 | controller | ERROR: Ignoring Errors
2025-11-26 07:35:23.221662 | 
2025-11-26 07:35:23.221725 | TASK [Create system configuration directory]
2025-11-26 07:35:23.405762 | controller | changed
2025-11-26 07:35:23.410733 | 
2025-11-26 07:35:23.410797 | TASK [Get some of the system configurations]
2025-11-26 07:35:23.934266 | controller | changed
2025-11-26 07:35:23.939349 | 
2025-11-26 07:35:23.939430 | TASK [Copy generated documentation if available]
2025-11-26 07:35:23.952595 | controller | skipping: Conditional result was False
2025-11-26 07:35:23.958039 | 
2025-11-26 07:35:23.958104 | TASK [Copy generated AsciiDoc documentation if available]
2025-11-26 07:35:23.971196 | controller | skipping: Conditional result was False
2025-11-26 07:35:23.976915 | 
2025-11-26 07:35:23.976993 | TASK [Compress logs bigger than 2MB]
2025-11-26 07:35:24.502119 | controller | changed
2025-11-26 07:35:24.507815 | 
2025-11-26 07:35:24.507895 | TASK [Copy files from workspace on node]
2025-11-26 07:35:24.526031 | controller | ok
2025-11-26 07:35:24.551257 | 
2025-11-26 07:35:24.551346 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-26 07:35:24.564714 | controller | skipping: Conditional result was False
2025-11-26 07:35:24.570962 | 
2025-11-26 07:35:24.571041 | TASK [fetch-output : Set log path for single node]
2025-11-26 07:35:24.598918 | controller | ok
2025-11-26 07:35:24.605397 | 
2025-11-26 07:35:24.605473 | LOOP [fetch-output : Ensure local output dirs]
2025-11-26 07:35:24.839756 | controller -> localhost | ok: "/var/lib/zuul/builds/7a9d4413f516458185f754d3e350f18e/work/logs"
2025-11-26 07:35:24.839987 | controller -> localhost | changed: All items complete
2025-11-26 07:35:24.840023 | 
2025-11-26 07:35:25.034185 | controller -> localhost | changed: "/var/lib/zuul/builds/7a9d4413f516458185f754d3e350f18e/work/artifacts"
2025-11-26 07:35:25.245222 | controller -> localhost | changed: "/var/lib/zuul/builds/7a9d4413f516458185f754d3e350f18e/work/docs"
2025-11-26 07:35:25.258559 | 
2025-11-26 07:35:25.258665 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-26 07:35:25.891960 | controller | changed:
2025-11-26 07:35:25.892170 | controller | .d..t...... ./
2025-11-26 07:35:25.892202 | controller | >f+++++++++ README.html
2025-11-26 07:35:25.892226 | controller | >f+++++++++ ansible-execution.log
2025-11-26 07:35:25.892247 | controller | >f+++++++++ ansible.log
2025-11-26 07:35:25.892267 | controller | >f+++++++++ dmesg.log
2025-11-26 07:35:25.892285 | controller | >f+++++++++ installed-pkgs.log
2025-11-26 07:35:25.892307 | controller | >f+++++++++ python.log
2025-11-26 07:35:25.892327 | controller | >f+++++++++ registries.conf
2025-11-26 07:35:25.892345 | controller | >f+++++++++ report.html
2025-11-26 07:35:25.892366 | controller | >f+++++++++ selinux-denials.log
2025-11-26 07:35:25.892436 | controller | >f+++++++++ selinux-listing.log
2025-11-26 07:35:25.892465 | controller | cd+++++++++ ci-framework-data/
2025-11-26 07:35:25.892486 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-11-26 07:35:25.892505 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-11-26 07:35:25.892525 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-11-26 07:35:25.892543 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/kustomizations/
2025-11-26 07:35:25.892561 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/kustomizations/dataplane/
2025-11-26 07:35:25.892579 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/kustomizations/dataplane/87-hci-post-kustomization.yaml
2025-11-26 07:35:25.892597 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml
2025-11-26 07:35:25.892615 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml
2025-11-26 07:35:25.892633 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-11-26 07:35:25.892651 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/network_info.yml
2025-11-26 07:35:25.892669 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-11-26 07:35:25.892687 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-11-26 07:35:25.892705 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-11-26 07:35:25.892723 | controller | cd+++++++++ ci-framework-data/logs/
2025-11-26 07:35:25.892740 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-11-26 07:35:25.892757 | controller | cd+++++++++ registries.conf.d/
2025-11-26 07:35:25.892774 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-11-26 07:35:25.892791 | controller | cd+++++++++ system-config/
2025-11-26 07:35:25.892808 | controller | cd+++++++++ system-config/libvirt/
2025-11-26 07:35:25.892825 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-11-26 07:35:25.892841 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-11-26 07:35:26.326013 | controller | changed: .d..t...... ./
2025-11-26 07:35:26.742865 | controller | changed: .d..t...... ./
2025-11-26 07:35:26.756910 | 
2025-11-26 07:35:26.757024 | TASK [Return artifact to Zuul]
2025-11-26 07:35:26.786065 | controller | ok
2025-11-26 07:35:26.806917 | 
2025-11-26 07:35:26.806992 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-11-26 07:35:26.807097 | 
2025-11-26 07:35:26.807127 | PLAY RECAP
2025-11-26 07:35:26.807164 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-11-26 07:35:26.807185 | 
2025-11-26 07:35:26.900094 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-26 07:35:26.900853 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-26 07:35:27.445758 | 
2025-11-26 07:35:27.445877 | PLAY [all]
2025-11-26 07:35:27.465207 | 
2025-11-26 07:35:27.465329 | TASK [include_role : fetch-output]
2025-11-26 07:35:27.494432 | controller | ok
2025-11-26 07:35:27.514193 | 
2025-11-26 07:35:27.514305 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-26 07:35:27.569342 | controller | skipping: Conditional result was False
2025-11-26 07:35:27.575366 | 
2025-11-26 07:35:27.575479 | TASK [fetch-output : Set log path for single node]
2025-11-26 07:35:27.614692 | controller | ok
2025-11-26 07:35:27.620786 | 
2025-11-26 07:35:27.620878 | LOOP [fetch-output : Ensure local output dirs]
2025-11-26 07:35:28.089201 | controller -> localhost | ok: "/var/lib/zuul/builds/7a9d4413f516458185f754d3e350f18e/work/logs"
2025-11-26 07:35:28.305845 | controller -> localhost | ok: "/var/lib/zuul/builds/7a9d4413f516458185f754d3e350f18e/work/artifacts"
2025-11-26 07:35:28.511726 | controller -> localhost | ok: "/var/lib/zuul/builds/7a9d4413f516458185f754d3e350f18e/work/docs"
2025-11-26 07:35:28.524110 | 
2025-11-26 07:35:28.524246 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-26 07:35:29.173205 | controller | ok
2025-11-26 07:35:29.173454 | controller | ok: All items complete
2025-11-26 07:35:29.173493 | 
2025-11-26 07:35:29.760979 | controller | ok
2025-11-26 07:35:30.251113 | controller | ok
2025-11-26 07:35:30.264173 | 
2025-11-26 07:35:30.264283 | TASK [include_role : fetch-output-openshift]
2025-11-26 07:35:30.277964 | controller | skipping: Conditional result was False
2025-11-26 07:35:30.284305 | 
2025-11-26 07:35:30.284414 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-26 07:35:30.740977 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006861
2025-11-26 07:35:30.975272 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.030564
2025-11-26 07:35:31.005061 | 
2025-11-26 07:35:31.005148 | PLAY [all]
2025-11-26 07:35:31.019305 | 
2025-11-26 07:35:31.019376 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-11-26 07:35:31.431614 | controller | changed
2025-11-26 07:35:31.454028 | 
2025-11-26 07:35:31.454098 | PLAY RECAP
2025-11-26 07:35:31.454144 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-26 07:35:31.454167 | 
2025-11-26 07:35:31.534837 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-26 07:35:31.535584 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-11-26 07:35:32.141605 | 
2025-11-26 07:35:32.141755 | PLAY [localhost]
2025-11-26 07:35:32.160644 | 
2025-11-26 07:35:32.160758 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-26 07:35:32.513665 | localhost | changed
2025-11-26 07:35:32.518492 | 
2025-11-26 07:35:32.518591 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-26 07:35:32.547458 | localhost | ok
2025-11-26 07:35:32.555807 | 
2025-11-26 07:35:32.555914 | TASK [add-fileserver : Create SSH private key tempfile]
2025-11-26 07:35:32.999888 | localhost | changed
2025-11-26 07:35:33.004866 | 
2025-11-26 07:35:33.004967 | TASK [add-fileserver : Create SSH private key from secret]
2025-11-26 07:35:33.807216 | localhost | changed
2025-11-26 07:35:33.817431 | 
2025-11-26 07:35:33.817510 | TASK [add-fileserver : Add fileserver ssh key]
2025-11-26 07:35:34.417636 | localhost | Identity added: /var/lib/zuul/builds/7a9d4413f516458185f754d3e350f18e/work/tmp/ansible.cdiemd9s (/var/lib/zuul/builds/7a9d4413f516458185f754d3e350f18e/work/tmp/ansible.cdiemd9s)
2025-11-26 07:35:34.417827 | localhost | ok: Runtime: 0:00:00.014675
2025-11-26 07:35:34.422207 | 
2025-11-26 07:35:34.422277 | TASK [add-fileserver : Remove SSH private key from disk]
2025-11-26 07:35:34.767377 | localhost | ok: Runtime: 0:00:00.035063
2025-11-26 07:35:34.777797 | 
2025-11-26 07:35:34.777877 | TASK [add-fileserver : Add fileserver to inventory]
2025-11-26 07:35:34.826820 | localhost | changed
2025-11-26 07:35:34.832086 | 
2025-11-26 07:35:34.832210 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-11-26 07:35:35.384615 | localhost | changed
2025-11-26 07:35:35.409060 | 
2025-11-26 07:35:35.409200 | PLAY [localhost]
2025-11-26 07:35:35.421428 | 
2025-11-26 07:35:35.421492 | TASK [Generate bulk log download script]
2025-11-26 07:35:35.438561 | localhost | ok
2025-11-26 07:35:35.450257 | 
2025-11-26 07:35:35.450325 | TASK [local-log-download : Check API endpoint is defined]
2025-11-26 07:35:35.477857 | localhost | ok: All assertions passed
2025-11-26 07:35:35.482284 | 
2025-11-26 07:35:35.482348 | TASK [local-log-download : Create download script]
2025-11-26 07:35:35.882772 | localhost -> localhost | changed
2025-11-26 07:35:35.892062 | 
2025-11-26 07:35:35.892126 | TASK [Register quick-download link]
2025-11-26 07:35:35.927609 | localhost | ok
2025-11-26 07:35:35.942322 | 
2025-11-26 07:35:35.942377 | PLAY [logserver.rdoproject.org]
2025-11-26 07:35:35.952019 | 
2025-11-26 07:35:35.952081 | TASK [Set zuul-log-path fact]
2025-11-26 07:35:35.967651 | logserver.rdoproject.org | ok
2025-11-26 07:35:35.976591 | 
2025-11-26 07:35:35.976659 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-26 07:35:36.008480 | logserver.rdoproject.org | ok
2025-11-26 07:35:36.013924 | 
2025-11-26 07:35:36.014001 | TASK [upload-logs : Create log directories]
2025-11-26 07:35:37.589648 | logserver.rdoproject.org | changed
2025-11-26 07:35:37.593078 | 
2025-11-26 07:35:37.593143 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-11-26 07:35:37.831824 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.011226
2025-11-26 07:35:37.836297 | 
2025-11-26 07:35:37.836373 | TASK [upload-logs : Upload logs to log server]
2025-11-26 07:35:39.430308 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-11-26 07:35:39.434132 | 
2025-11-26 07:35:39.434208 | LOOP [upload-logs : Compress console log and json output]
2025-11-26 07:35:39.480080 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-26 07:35:39.491820 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-26 07:35:39.503231 | 
2025-11-26 07:35:39.503344 | LOOP [upload-logs : Upload compressed console log and json output]
2025-11-26 07:35:39.549620 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-26 07:35:39.549953 | 
2025-11-26 07:35:39.550604 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-26 07:35:39.566870 | 
2025-11-26 07:35:39.566994 | LOOP [upload-logs : Upload console log and json output]