2025-11-25 14:01:56.035063 | Job console starting...
2025-11-25 14:01:56.374788 | Updating repositories
2025-11-25 14:01:57.222466 | Preparing job workspace
2025-11-25 14:02:14.342993 | Running Ansible setup...
2025-11-25 14:02:22.300295 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-25 14:02:24.093693 | 
2025-11-25 14:02:24.094033 | PLAY [localhost]
2025-11-25 14:02:24.110050 | 
2025-11-25 14:02:24.110124 | TASK [Gathering Facts]
2025-11-25 14:02:27.003033 | localhost | ok
2025-11-25 14:02:27.041699 | 
2025-11-25 14:02:27.041803 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-25 14:02:28.889667 | localhost -> localhost | changed
2025-11-25 14:02:28.895139 | 
2025-11-25 14:02:28.895223 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-25 14:02:31.831425 | localhost -> localhost | changed
2025-11-25 14:02:31.846240 | 
2025-11-25 14:02:31.846329 | TASK [Setup log path fact]
2025-11-25 14:02:31.917820 | localhost | ok
2025-11-25 14:02:31.956848 | 
2025-11-25 14:02:31.956937 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-25 14:02:32.020971 | localhost | ok
2025-11-25 14:02:32.043572 | 
2025-11-25 14:02:32.043658 | TASK [emit-job-header : Print job information]
2025-11-25 14:02:32.195781 | # Job Information
2025-11-25 14:02:32.195922 | Ansible Version: 2.15.12
2025-11-25 14:02:32.195952 | Job: cifmw-molecule-shiftstack
2025-11-25 14:02:32.195973 | Pipeline: github-check
2025-11-25 14:02:32.195993 | Executor: ibm-bm3-ze.softwarefactory-project.io
2025-11-25 14:02:32.196012 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3505
2025-11-25 14:02:32.196190 | Log URL (when completed): https://logserver.rdoproject.org/2ec/rdoproject.org/2ec4b0ff8ec74929a01ca0aae98f7787/
2025-11-25 14:02:32.196213 | Event ID: 1ddbeee0-ca07-11f0-9bf4-54479d946bd8
2025-11-25 14:02:32.200085 | 
2025-11-25 14:02:32.200153 | LOOP [emit-job-header : Print node information]
2025-11-25 14:02:32.521960 | localhost | ok:
2025-11-25 14:02:32.522108 | localhost | # Node Information
2025-11-25 14:02:32.522139 | localhost | Inventory Hostname: controller
2025-11-25 14:02:32.522164 | localhost | Hostname: np0005535287
2025-11-25 14:02:32.522185 | localhost | Username: zuul
2025-11-25 14:02:32.522206 | localhost | Distro: CentOS 9
2025-11-25 14:02:32.522226 | localhost | Provider: ibm-bm3-nodepool
2025-11-25 14:02:32.522244 | localhost | Region: regionOne
2025-11-25 14:02:32.522362 | localhost | Label: centos-9-stream-crc-2-48-0-xl-ibm
2025-11-25 14:02:32.522407 | localhost | Product Name: OpenStack Compute
2025-11-25 14:02:32.522432 | localhost | Interface IP: 192.168.25.137
2025-11-25 14:02:32.575149 | 
2025-11-25 14:02:32.575211 | PLAY [all]
2025-11-25 14:02:32.599235 | 
2025-11-25 14:02:32.599302 | TASK [Gather network facts]
2025-11-25 14:02:33.172786 | controller | ok
2025-11-25 14:02:33.223763 | 
2025-11-25 14:02:33.223857 | TASK [include_role : start-zuul-console]
2025-11-25 14:02:33.315204 | controller | ok
2025-11-25 14:02:33.338496 | 
2025-11-25 14:02:33.338571 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-25 14:02:34.116197 | controller | ok
2025-11-25 14:02:34.129499 | 
2025-11-25 14:02:34.129581 | TASK [include_role : add-build-sshkey]
2025-11-25 14:02:34.185259 | controller | ok
2025-11-25 14:02:34.217739 | 
2025-11-25 14:02:34.217830 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-25 14:02:35.140523 | controller -> localhost | ok
2025-11-25 14:02:35.146516 | 
2025-11-25 14:02:35.146581 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-25 14:02:35.206903 | controller | ok
2025-11-25 14:02:35.283450 | controller | included: /var/lib/zuul/builds/2ec4b0ff8ec74929a01ca0aae98f7787/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-25 14:02:35.314923 | 
2025-11-25 14:02:35.315012 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-25 14:02:37.258067 | controller -> localhost | Generating public/private rsa key pair.
2025-11-25 14:02:37.258247 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/2ec4b0ff8ec74929a01ca0aae98f7787/work/2ec4b0ff8ec74929a01ca0aae98f7787_id_rsa.
2025-11-25 14:02:37.258282 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/2ec4b0ff8ec74929a01ca0aae98f7787/work/2ec4b0ff8ec74929a01ca0aae98f7787_id_rsa.pub.
2025-11-25 14:02:37.258306 | controller -> localhost | The key fingerprint is:
2025-11-25 14:02:37.258327 | controller -> localhost | SHA256:c1N1vkYrfzazwXzUyWDK+ddg/We5FpjTJEsMFOXxZqY zuul-build-sshkey
2025-11-25 14:02:37.258348 | controller -> localhost | The key's randomart image is:
2025-11-25 14:02:37.258367 | controller -> localhost | +---[RSA 3072]----+
2025-11-25 14:02:37.258419 | controller -> localhost | |          .ooo. .|
2025-11-25 14:02:37.258444 | controller -> localhost | |           ...oo |
2025-11-25 14:02:37.258464 | controller -> localhost | |            ++ *.|
2025-11-25 14:02:37.258484 | controller -> localhost | |          ..++Oo=|
2025-11-25 14:02:37.258503 | controller -> localhost | |        S o+.EX*+|
2025-11-25 14:02:37.258522 | controller -> localhost | |         o ..**=+|
2025-11-25 14:02:37.258541 | controller -> localhost | |             ..XX|
2025-11-25 14:02:37.258560 | controller -> localhost | |              .+X|
2025-11-25 14:02:37.258579 | controller -> localhost | |              .o |
2025-11-25 14:02:37.258596 | controller -> localhost | +----[SHA256]-----+
2025-11-25 14:02:37.258638 | controller -> localhost | ok: Runtime: 0:00:00.581380
2025-11-25 14:02:37.264239 | 
2025-11-25 14:02:37.264306 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-25 14:02:37.316223 | controller | ok
2025-11-25 14:02:37.353803 | controller | included: /var/lib/zuul/builds/2ec4b0ff8ec74929a01ca0aae98f7787/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-25 14:02:37.386996 | 
2025-11-25 14:02:37.387087 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-25 14:02:37.456561 | controller | skipping: Conditional result was False
2025-11-25 14:02:37.463092 | 
2025-11-25 14:02:37.463175 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-25 14:02:38.412366 | controller | changed
2025-11-25 14:02:38.417329 | 
2025-11-25 14:02:38.417425 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-25 14:02:38.818167 | controller | ok
2025-11-25 14:02:38.824305 | 
2025-11-25 14:02:38.824406 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-25 14:02:40.298599 | controller | changed
2025-11-25 14:02:40.303462 | 
2025-11-25 14:02:40.303542 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-25 14:02:41.862560 | controller | changed
2025-11-25 14:02:41.877025 | 
2025-11-25 14:02:41.877126 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-25 14:02:41.954731 | controller | skipping: Conditional result was False
2025-11-25 14:02:41.960714 | 
2025-11-25 14:02:41.960786 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-25 14:02:43.102922 | controller -> localhost | changed
2025-11-25 14:02:43.113937 | 
2025-11-25 14:02:43.114020 | TASK [add-build-sshkey : Add back temp key]
2025-11-25 14:02:44.235805 | controller -> localhost | Identity added: /var/lib/zuul/builds/2ec4b0ff8ec74929a01ca0aae98f7787/work/2ec4b0ff8ec74929a01ca0aae98f7787_id_rsa (zuul-build-sshkey)
2025-11-25 14:02:44.236007 | controller -> localhost | ok: Runtime: 0:00:00.042731
2025-11-25 14:02:44.242972 | 
2025-11-25 14:02:44.243038 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-25 14:02:44.750663 | controller | ok
2025-11-25 14:02:44.755623 | 
2025-11-25 14:02:44.755688 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-25 14:02:44.826507 | controller | skipping: Conditional result was False
2025-11-25 14:02:44.836614 | 
2025-11-25 14:02:44.836691 | TASK [include_role : validate-host]
2025-11-25 14:02:44.887854 | controller | ok
2025-11-25 14:02:44.942317 | 
2025-11-25 14:02:44.942412 | TASK [validate-host : Define zuul_info_dir fact]
2025-11-25 14:02:45.024108 | controller | ok
2025-11-25 14:02:45.028922 | 
2025-11-25 14:02:45.028995 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-11-25 14:02:46.040875 | controller -> localhost | ok
2025-11-25 14:02:46.047103 | 
2025-11-25 14:02:46.047176 | TASK [validate-host : Collect information about the host]
2025-11-25 14:02:46.910934 | controller | ok
2025-11-25 14:02:46.947842 | 
2025-11-25 14:02:46.947930 | TASK [validate-host : Sanitize hostname]
2025-11-25 14:02:47.161653 | controller | ok
2025-11-25 14:02:47.170559 | 
2025-11-25 14:02:47.170651 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-11-25 14:02:49.394487 | controller -> localhost | changed
2025-11-25 14:02:49.400739 | 
2025-11-25 14:02:49.400822 | TASK [validate-host : Collect information about zuul worker]
2025-11-25 14:02:50.136898 | controller | ok
2025-11-25 14:02:50.141852 | 
2025-11-25 14:02:50.141934 | TASK [validate-host : Write out all zuul information for each host]
2025-11-25 14:02:51.443953 | controller -> localhost | changed
2025-11-25 14:02:51.454051 | 
2025-11-25 14:02:51.454143 | TASK [include_role : prepare-workspace-openshift]
2025-11-25 14:02:51.522839 | controller | skipping: Conditional result was False
2025-11-25 14:02:51.529048 | 
2025-11-25 14:02:51.529149 | TASK [include_role : remove-zuul-sshkey]
2025-11-25 14:02:51.627716 | controller | skipping: Conditional result was False
2025-11-25 14:02:51.632779 | 
2025-11-25 14:02:51.632848 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-25 14:02:51.994552 | controller | ok: "logs"
2025-11-25 14:02:51.994802 | controller | ok: All items complete
2025-11-25 14:02:51.994840 | 
2025-11-25 14:02:52.247056 | controller | ok: "artifacts"
2025-11-25 14:02:52.510747 | controller | ok: "docs"
2025-11-25 14:02:52.519572 | 
2025-11-25 14:02:52.519682 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-25 14:02:52.895635 | controller | changed: "logs"
2025-11-25 14:02:53.154500 | controller | changed: "artifacts"
2025-11-25 14:02:53.433323 | controller | changed: "docs"
2025-11-25 14:02:53.463164 | 
2025-11-25 14:02:53.463265 | PLAY RECAP
2025-11-25 14:02:53.463313 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-25 14:02:53.463342 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-25 14:02:53.463361 | 
2025-11-25 14:02:53.619353 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-25 14:02:53.620027 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-25 14:02:54.681905 | 
2025-11-25 14:02:54.682014 | PLAY [all]
2025-11-25 14:02:54.719314 | 
2025-11-25 14:02:54.719430 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-25 14:02:54.849546 | controller | ok
2025-11-25 14:02:54.854554 | 
2025-11-25 14:02:54.854644 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-25 14:02:55.503609 | controller | changed
2025-11-25 14:02:55.509362 | 
2025-11-25 14:02:55.509782 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-25 14:02:57.459253 | controller | changed
2025-11-25 14:02:57.475553 | 
2025-11-25 14:02:57.475628 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-11-25 14:02:58.318509 | controller | changed:
2025-11-25 14:02:58.318684 | controller | {
2025-11-25 14:02:58.318718 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-11-25 14:02:58.318749 | controller | }
2025-11-25 14:02:58.649229 | controller | changed:
2025-11-25 14:02:58.649304 | controller | {
2025-11-25 14:02:58.649333 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-11-25 14:02:58.649354 | controller | }
2025-11-25 14:02:58.977874 | controller | changed:
2025-11-25 14:02:58.977957 | controller | {
2025-11-25 14:02:58.977986 | 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-25 14:02:58.978008 | controller | }
2025-11-25 14:02:59.319781 | controller | changed:
2025-11-25 14:02:59.319872 | controller | {
2025-11-25 14:02:59.319905 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-11-25 14:02:59.319929 | controller | }
2025-11-25 14:02:59.651603 | controller | changed:
2025-11-25 14:02:59.651683 | controller | {
2025-11-25 14:02:59.651710 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-11-25 14:02:59.651730 | controller | }
2025-11-25 14:02:59.978817 | controller | changed:
2025-11-25 14:02:59.978901 | controller | {
2025-11-25 14:02:59.978930 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-11-25 14:02:59.978951 | controller | }
2025-11-25 14:03:00.292021 | controller | changed:
2025-11-25 14:03:00.292111 | controller | {
2025-11-25 14:03:00.292160 | 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-25 14:03:00.292205 | controller | }
2025-11-25 14:03:00.646049 | controller | changed:
2025-11-25 14:03:00.646157 | controller | {
2025-11-25 14:03:00.646190 | 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-25 14:03:00.646214 | controller | }
2025-11-25 14:03:00.946184 | controller | changed:
2025-11-25 14:03:00.946279 | controller | {
2025-11-25 14:03:00.946316 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-11-25 14:03:00.946345 | controller | }
2025-11-25 14:03:01.253213 | controller | changed:
2025-11-25 14:03:01.253296 | controller | {
2025-11-25 14:03:01.253322 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-11-25 14:03:01.253343 | controller | }
2025-11-25 14:03:01.577466 | controller | changed:
2025-11-25 14:03:01.577541 | controller | {
2025-11-25 14:03:01.577570 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-11-25 14:03:01.577594 | controller | }
2025-11-25 14:03:01.883132 | controller | changed:
2025-11-25 14:03:01.883220 | controller | {
2025-11-25 14:03:01.883249 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-11-25 14:03:01.883271 | controller | }
2025-11-25 14:03:02.235452 | controller | changed:
2025-11-25 14:03:02.235545 | controller | {
2025-11-25 14:03:02.235574 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-11-25 14:03:02.235595 | controller | }
2025-11-25 14:03:02.574936 | controller | changed:
2025-11-25 14:03:02.575019 | controller | {
2025-11-25 14:03:02.575046 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-11-25 14:03:02.575068 | controller | }
2025-11-25 14:03:02.906060 | controller | changed:
2025-11-25 14:03:02.906155 | controller | {
2025-11-25 14:03:02.906183 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-11-25 14:03:02.906204 | controller | }
2025-11-25 14:03:03.223614 | controller | changed:
2025-11-25 14:03:03.223689 | controller | {
2025-11-25 14:03:03.223716 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-11-25 14:03:03.223736 | controller | }
2025-11-25 14:03:03.540585 | controller | changed:
2025-11-25 14:03:03.540662 | controller | {
2025-11-25 14:03:03.540690 | 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-25 14:03:03.540712 | controller | }
2025-11-25 14:03:03.844070 | controller | changed:
2025-11-25 14:03:03.844205 | controller | {
2025-11-25 14:03:03.844234 | 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-25 14:03:03.844264 | controller | }
2025-11-25 14:03:04.179019 | controller | changed:
2025-11-25 14:03:04.179094 | controller | {
2025-11-25 14:03:04.179132 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-11-25 14:03:04.179154 | controller | }
2025-11-25 14:03:04.511619 | controller | changed:
2025-11-25 14:03:04.513475 | controller | {
2025-11-25 14:03:04.513511 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-11-25 14:03:04.513535 | controller | }
2025-11-25 14:03:04.814675 | controller | changed:
2025-11-25 14:03:04.814760 | controller | {
2025-11-25 14:03:04.814788 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-11-25 14:03:04.814810 | controller | }
2025-11-25 14:03:05.139984 | controller | changed:
2025-11-25 14:03:05.140065 | controller | {
2025-11-25 14:03:05.140094 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-11-25 14:03:05.140127 | controller | }
2025-11-25 14:03:05.433443 | controller | changed:
2025-11-25 14:03:05.433518 | controller | {
2025-11-25 14:03:05.433545 | 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-25 14:03:05.433567 | controller | }
2025-11-25 14:03:05.746708 | controller | changed:
2025-11-25 14:03:05.746797 | controller | {
2025-11-25 14:03:05.746823 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-11-25 14:03:05.746843 | controller | }
2025-11-25 14:03:06.059029 | controller | changed:
2025-11-25 14:03:06.059128 | controller | {
2025-11-25 14:03:06.059159 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-11-25 14:03:06.059181 | controller | }
2025-11-25 14:03:06.379027 | controller | changed:
2025-11-25 14:03:06.379101 | controller | {
2025-11-25 14:03:06.379140 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-11-25 14:03:06.379161 | controller | }
2025-11-25 14:03:06.424302 | 
2025-11-25 14:03:06.424443 | TASK [Set timezone to UTC]
2025-11-25 14:03:08.272666 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-11-25 14:03:08.288091 | 
2025-11-25 14:03:08.288201 | TASK [Create nodepool directory]
2025-11-25 14:03:08.615785 | controller | changed
2025-11-25 14:03:08.624438 | 
2025-11-25 14:03:08.624545 | TASK [Create nodepool sub_nodes file]
2025-11-25 14:03:09.923879 | controller | changed
2025-11-25 14:03:09.929011 | 
2025-11-25 14:03:09.929085 | TASK [Create nodepool sub_nodes_private file]
2025-11-25 14:03:11.179145 | controller | changed
2025-11-25 14:03:11.184973 | 
2025-11-25 14:03:11.185055 | LOOP [Populate nodepool sub_nodes file]
2025-11-25 14:03:11.216184 | 
2025-11-25 14:03:11.216321 | LOOP [Populate nodepool sub_nodes_private file]
2025-11-25 14:03:11.247560 | 
2025-11-25 14:03:11.247692 | TASK [Create nodepool primary file]
2025-11-25 14:03:11.291582 | controller | skipping: Conditional result was False
2025-11-25 14:03:11.301233 | 
2025-11-25 14:03:11.301338 | TASK [Create nodepool node_private for this node]
2025-11-25 14:03:12.521552 | controller | changed
2025-11-25 14:03:12.533307 | 
2025-11-25 14:03:12.533377 | LOOP [Copy ssh keys to nodepool directory]
2025-11-25 14:03:13.052440 | controller | ok: Item: id_rsa Runtime: 0:00:00.005752
2025-11-25 14:03:13.052598 | 
2025-11-25 14:03:13.302865 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005330
2025-11-25 14:03:13.319230 | 
2025-11-25 14:03:13.319330 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-11-25 14:03:14.653737 | controller | changed
2025-11-25 14:03:14.659632 | 
2025-11-25 14:03:14.659715 | TASK [Validate sudoers config after edits]
2025-11-25 14:03:15.050669 | controller | /etc/sudoers: parsed OK
2025-11-25 14:03:15.050733 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-11-25 14:03:15.050743 | controller | /etc/sudoers.d/zuul: parsed OK
2025-11-25 14:03:15.050749 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-11-25 14:03:15.211543 | controller | ok: Runtime: 0:00:00.007145
2025-11-25 14:03:15.218286 | 
2025-11-25 14:03:15.218368 | TASK [Show the environment passed in to job shell scripts]
2025-11-25 14:03:15.730389 | controller | SHELL=/bin/bash
2025-11-25 14:03:15.730445 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-11-25 14:03:15.730455 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-11-25 14:03:15.730461 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/05/3505/aecb71877114ccffda079e1e34f306d7ec6e9421
2025-11-25 14:03:15.730467 | controller | PWD=/home/zuul
2025-11-25 14:03:15.730528 | controller | ZUUL_PIPELINE=github-check
2025-11-25 14:03:15.730538 | controller | LOGNAME=zuul
2025-11-25 14:03:15.730544 | controller | XDG_SESSION_TYPE=tty
2025-11-25 14:03:15.730549 | controller | _=/usr/bin/env
2025-11-25 14:03:15.730554 | controller | MOTD_SHOWN=pam
2025-11-25 14:03:15.730560 | controller | HOME=/home/zuul
2025-11-25 14:03:15.730565 | controller | LANG=en_US.UTF-8
2025-11-25 14:03:15.730570 | controller | SSH_CONNECTION=192.168.25.12 50864 192.168.25.137 22
2025-11-25 14:03:15.730576 | 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-25 14:03:15.730585 | controller | ZUUL_CHANGE_IDS=3505,aecb71877114ccffda079e1e34f306d7ec6e9421
2025-11-25 14:03:15.730590 | controller | WORKSPACE=/home/zuul/workspace
2025-11-25 14:03:15.730596 | controller | XDG_SESSION_CLASS=user
2025-11-25 14:03:15.730601 | controller | SELINUX_ROLE_REQUESTED=
2025-11-25 14:03:15.730606 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-11-25 14:03:15.730611 | controller | USER=zuul
2025-11-25 14:03:15.730617 | controller | ZUUL_VOTING=True
2025-11-25 14:03:15.730622 | controller | BUILD_TIMEOUT=1800000
2025-11-25 14:03:15.730627 | controller | SELINUX_USE_CURRENT_RANGE=
2025-11-25 14:03:15.730632 | controller | SHLVL=1
2025-11-25 14:03:15.730663 | controller | ZUUL_PATCHSET=aecb71877114ccffda079e1e34f306d7ec6e9421
2025-11-25 14:03:15.730670 | controller | XDG_SESSION_ID=1
2025-11-25 14:03:15.730675 | controller | ZUUL_BRANCH=main
2025-11-25 14:03:15.730681 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-11-25 14:03:15.730686 | controller | SSH_CLIENT=192.168.25.12 50864 22
2025-11-25 14:03:15.730691 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-11-25 14:03:15.730697 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-11-25 14:03:15.730702 | controller | which_declare=declare -f
2025-11-25 14:03:15.730708 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-11-25 14:03:15.730713 | controller | SELINUX_LEVEL_REQUESTED=
2025-11-25 14:03:15.730718 | controller | ZUUL_CHANGE=3505
2025-11-25 14:03:15.730724 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-11-25 14:03:15.730729 | controller | ZUUL_UUID=2ec4b0ff8ec74929a01ca0aae98f7787
2025-11-25 14:03:15.730734 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-11-25 14:03:15.730739 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-11-25 14:03:15.730745 | controller | }
2025-11-25 14:03:15.833753 | controller | ok: Runtime: 0:00:00.006988
2025-11-25 14:03:15.839613 | 
2025-11-25 14:03:15.839678 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-11-25 14:03:15.893944 | controller | skipping: Conditional result was False
2025-11-25 14:03:15.899463 | 
2025-11-25 14:03:15.899545 | TASK [Symlink /home/zuul-worker/workspace]
2025-11-25 14:03:16.464229 | controller | skipping: Conditional result was False
2025-11-25 14:03:16.470123 | 
2025-11-25 14:03:16.470206 | TASK [Ensure legacy workspace directory]
2025-11-25 14:03:16.803449 | controller | changed
2025-11-25 14:03:16.888968 | 
2025-11-25 14:03:16.889044 | PLAY RECAP
2025-11-25 14:03:16.889090 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-25 14:03:16.889114 | 
2025-11-25 14:03:17.062775 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-25 14:03:17.063573 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-25 14:03:18.287423 | 
2025-11-25 14:03:18.287533 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-11-25 14:03:18.338199 | 
2025-11-25 14:03:18.338327 | TASK [Create zuul-output directory]
2025-11-25 14:03:18.927901 | controller | changed
2025-11-25 14:03:18.933247 | 
2025-11-25 14:03:18.933355 | TASK [Slurp Zuul inventory test]
2025-11-25 14:03:19.395888 | controller -> localhost | ok
2025-11-25 14:03:19.402673 | 
2025-11-25 14:03:19.402740 | TASK [Save zuul inventory]
2025-11-25 14:03:20.884704 | controller | changed
2025-11-25 14:03:20.891250 | 
2025-11-25 14:03:20.891333 | TASK [Save zuul vars without the change_message]
2025-11-25 14:03:22.108760 | controller | changed
2025-11-25 14:03:22.128143 | 
2025-11-25 14:03:22.128213 | PLAY RECAP
2025-11-25 14:03:22.128263 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-25 14:03:22.128294 | 
2025-11-25 14:03:22.242092 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-25 14:03:22.242820 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-25 14:03:22.914478 | 
2025-11-25 14:03:22.914575 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-11-25 14:03:22.937477 | 
2025-11-25 14:03:22.937550 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-11-25 14:03:22.956853 | controller | ok
2025-11-25 14:03:22.976302 | 
2025-11-25 14:03:22.976406 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-25 14:03:23.049434 | controller | skipping: Conditional result was False
2025-11-25 14:03:23.055190 | 
2025-11-25 14:03:23.055266 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-25 14:03:23.519837 | controller | ok
2025-11-25 14:03:23.531812 | 
2025-11-25 14:03:23.535420 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-25 14:03:24.700941 | controller | ok
2025-11-25 14:03:24.710754 | 
2025-11-25 14:03:24.710837 | TASK [Prepare workspace]
2025-11-25 14:03:24.729999 | controller | ok
2025-11-25 14:03:24.747286 | 
2025-11-25 14:03:24.747361 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-25 14:03:25.153881 | controller | ok
2025-11-25 14:03:25.159779 | 
2025-11-25 14:03:25.159852 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-25 14:03:27.724309 | controller | Output suppressed because no_log was given
2025-11-25 14:03:27.734099 | 
2025-11-25 14:03:27.734185 | LOOP [Create zuul-output directory]
2025-11-25 14:03:28.003905 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-11-25 14:03:28.233233 | controller | ok: "/home/zuul/zuul-output/logs"
2025-11-25 14:03:28.246732 | 
2025-11-25 14:03:28.246835 | TASK [Install required packages]
2025-11-25 14:03:51.316264 | controller | ok: Nothing to do
2025-11-25 14:03:51.321578 | 
2025-11-25 14:03:51.321639 | TASK [Install venv]
2025-11-25 14:04:52.160319 | controller | changed
2025-11-25 14:04:52.185572 | 
2025-11-25 14:04:52.185663 | PLAY RECAP
2025-11-25 14:04:52.185709 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-25 14:04:52.185733 | 
2025-11-25 14:04:52.281855 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-25 14:04:52.282648 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-25 14:04:52.820520 | 
2025-11-25 14:04:52.820623 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-11-25 14:04:52.841641 | 
2025-11-25 14:04:52.841716 | TASK [Gather required facts]
2025-11-25 14:04:53.409121 | controller | ok
2025-11-25 14:04:53.414373 | 
2025-11-25 14:04:53.414476 | TASK [Load environment var if instructed to]
2025-11-25 14:04:53.438923 | controller | skipping: Conditional result was False
2025-11-25 14:04:53.444155 | 
2025-11-25 14:04:53.444221 | TASK [Ensure group_vars dir exists]
2025-11-25 14:04:53.838292 | controller | ok
2025-11-25 14:04:53.843749 | 
2025-11-25 14:04:53.843832 | TASK [Print related variables]
2025-11-25 14:04:53.872657 | controller | ok:
2025-11-25 14:04:53.872773 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-11-25 14:04:53.872802 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack
2025-11-25 14:04:53.877609 | 
2025-11-25 14:04:53.877676 | TASK [Run molecule]
2025-11-25 14:04:54.956031 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-11-25 14:04:55.028455 | controller | INFO     Performing prerun with role_name_check=0...
2025-11-25 14:05:17.405984 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:05:17.406115 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:05:17.406131 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:05:17.406490 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:05:17.406990 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+0b589e62 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.0.0+0b589e62 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:05:17.407445 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:05:17.407889 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:05:17.408355 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:05:17.408829 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:05:17.409299 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:05:17.409729 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:05:17.410188 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:05:17.410615 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:05:17.411082 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:05:17.411537 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:05:17.421493 | controller | INFO     Running default > prepare
2025-11-25 14:05:18.133070 | controller |
2025-11-25 14:05:18.133151 | controller | PLAY [Prepare] *****************************************************************
2025-11-25 14:05:18.133163 | controller |
2025-11-25 14:05:18.133259 | controller | TASK [Gathering Facts] *********************************************************
2025-11-25 14:05:18.133361 | controller | Tuesday 25 November 2025  14:05:18 +0000 (0:00:00.021)       0:00:00.021 ******
2025-11-25 14:05:19.062364 | controller | ok: [instance]
2025-11-25 14:05:19.062481 | controller |
2025-11-25 14:05:19.062493 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-11-25 14:05:19.062605 | controller | Tuesday 25 November 2025  14:05:19 +0000 (0:00:00.929)       0:00:00.951 ******
2025-11-25 14:05:19.086694 | controller | skipping: [instance]
2025-11-25 14:05:19.086798 | controller |
2025-11-25 14:05:19.086973 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-11-25 14:05:19.087104 | controller | Tuesday 25 November 2025  14:05:19 +0000 (0:00:00.024)       0:00:00.976 ******
2025-11-25 14:05:19.138579 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-11-25 14:05:19.138664 | controller |
2025-11-25 14:05:19.138795 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-11-25 14:05:19.138898 | controller | Tuesday 25 November 2025  14:05:19 +0000 (0:00:00.051)       0:00:01.028 ******
2025-11-25 14:05:19.459761 | controller | ok: [instance]
2025-11-25 14:05:19.459821 | controller |
2025-11-25 14:05:19.459894 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-11-25 14:05:19.460007 | controller | Tuesday 25 November 2025  14:05:19 +0000 (0:00:00.320)       0:00:01.348 ******
2025-11-25 14:05:19.823530 | controller | ok: [instance]
2025-11-25 14:05:19.823593 | controller |
2025-11-25 14:05:19.823675 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-11-25 14:05:19.823776 | controller | Tuesday 25 November 2025  14:05:19 +0000 (0:00:00.363)       0:00:01.712 ******
2025-11-25 14:05:20.595036 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-11-25 14:05:20.595104 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-11-25 14:05:20.595247 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-11-25 14:05:20.595371 | controller |
2025-11-25 14:05:20.595484 | controller | TASK [Deploy repo-setup] *******************************************************
2025-11-25 14:05:20.595593 | controller | Tuesday 25 November 2025  14:05:20 +0000 (0:00:00.771)       0:00:02.484 ******
2025-11-25 14:05:20.662309 | controller |
2025-11-25 14:05:20.662372 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-11-25 14:05:20.662453 | controller | Tuesday 25 November 2025  14:05:20 +0000 (0:00:00.067)       0:00:02.551 ******
2025-11-25 14:05:21.289880 | controller | changed: [instance] => (item=tmp)
2025-11-25 14:05:21.289968 | controller | changed: [instance] => (item=artifacts/repositories)
2025-11-25 14:05:21.289999 | controller | changed: [instance] => (item=venv/repo_setup)
2025-11-25 14:05:21.290102 | controller |
2025-11-25 14:05:21.290204 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-11-25 14:05:21.290308 | controller | Tuesday 25 November 2025  14:05:21 +0000 (0:00:00.627)       0:00:03.178 ******
2025-11-25 14:05:22.229309 | controller | ok: [instance]
2025-11-25 14:05:22.229396 | controller |
2025-11-25 14:05:22.229578 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-11-25 14:05:22.229661 | controller | Tuesday 25 November 2025  14:05:22 +0000 (0:00:00.939)       0:00:04.118 ******
2025-11-25 14:05:23.378079 | controller | changed: [instance]
2025-11-25 14:05:31.658342 | controller |
2025-11-25 14:05:31.658408 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-11-25 14:05:31.658418 | controller | Tuesday 25 November 2025  14:05:23 +0000 (0:00:01.148)       0:00:05.267 ******
2025-11-25 14:05:31.658430 | controller | changed: [instance]
2025-11-25 14:05:32.455722 | controller |
2025-11-25 14:05:32.455784 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-11-25 14:05:32.455792 | controller | Tuesday 25 November 2025  14:05:31 +0000 (0:00:08.279)       0:00:13.546 ******
2025-11-25 14:05:32.455805 | controller | changed: [instance]
2025-11-25 14:05:32.484813 | controller |
2025-11-25 14:05:32.484866 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-11-25 14:05:32.484876 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.797)       0:00:14.344 ******
2025-11-25 14:05:32.484890 | controller | skipping: [instance]
2025-11-25 14:05:32.484913 | controller |
2025-11-25 14:05:32.484922 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-11-25 14:05:32.484931 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.029)       0:00:14.374 ******
2025-11-25 14:05:33.326416 | controller | changed: [instance]
2025-11-25 14:05:33.368038 | controller |
2025-11-25 14:05:33.368080 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-11-25 14:05:33.368088 | controller | Tuesday 25 November 2025  14:05:33 +0000 (0:00:00.841)       0:00:15.215 ******
2025-11-25 14:05:33.368099 | controller | skipping: [instance]
2025-11-25 14:05:33.409984 | controller |
2025-11-25 14:05:33.410021 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-11-25 14:05:33.410029 | controller | Tuesday 25 November 2025  14:05:33 +0000 (0:00:00.040)       0:00:15.255 ******
2025-11-25 14:05:33.410040 | controller | skipping: [instance]
2025-11-25 14:05:33.448382 | controller |
2025-11-25 14:05:33.448441 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-11-25 14:05:33.448451 | controller | Tuesday 25 November 2025  14:05:33 +0000 (0:00:00.042)       0:00:15.298 ******
2025-11-25 14:05:33.448468 | controller | skipping: [instance]
2025-11-25 14:05:33.448518 | controller |
2025-11-25 14:05:33.448688 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-11-25 14:05:33.448813 | controller | Tuesday 25 November 2025  14:05:33 +0000 (0:00:00.039)       0:00:15.337 ******
2025-11-25 14:05:34.015725 | controller | changed: [instance]
2025-11-25 14:05:34.497457 | controller |
2025-11-25 14:05:34.497506 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-11-25 14:05:34.497515 | controller | Tuesday 25 November 2025  14:05:34 +0000 (0:00:00.566)       0:00:15.904 ******
2025-11-25 14:05:34.497526 | controller | changed: [instance]
2025-11-25 14:05:34.532472 | controller |
2025-11-25 14:05:34.532508 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-11-25 14:05:34.532516 | controller | Tuesday 25 November 2025  14:05:34 +0000 (0:00:00.481)       0:00:16.385 ******
2025-11-25 14:05:34.532527 | controller | skipping: [instance]
2025-11-25 14:05:34.566599 | controller |
2025-11-25 14:05:34.566664 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-11-25 14:05:34.566675 | controller | Tuesday 25 November 2025  14:05:34 +0000 (0:00:00.036)       0:00:16.421 ******
2025-11-25 14:05:34.566687 | controller | skipping: [instance]
2025-11-25 14:05:34.602105 | controller |
2025-11-25 14:05:34.602125 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-11-25 14:05:34.602132 | controller | Tuesday 25 November 2025  14:05:34 +0000 (0:00:00.033)       0:00:16.455 ******
2025-11-25 14:05:34.602148 | controller | skipping: [instance]
2025-11-25 14:05:34.645462 | controller |
2025-11-25 14:05:34.645486 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-11-25 14:05:34.645495 | controller | Tuesday 25 November 2025  14:05:34 +0000 (0:00:00.035)       0:00:16.491 ******
2025-11-25 14:05:34.645506 | controller | ok: [instance]
2025-11-25 14:05:34.678329 | controller |
2025-11-25 14:05:34.678362 | controller | TASK [repo_setup : Create download directory] **********************************
2025-11-25 14:05:34.678368 | controller | Tuesday 25 November 2025  14:05:34 +0000 (0:00:00.043)       0:00:16.534 ******
2025-11-25 14:05:34.678379 | controller | skipping: [instance]
2025-11-25 14:05:34.709923 | controller |
2025-11-25 14:05:34.709965 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-11-25 14:05:34.709972 | controller | Tuesday 25 November 2025  14:05:34 +0000 (0:00:00.032)       0:00:16.567 ******
2025-11-25 14:05:34.709992 | controller | skipping: [instance]
2025-11-25 14:05:34.710139 | controller |
2025-11-25 14:05:34.710282 | controller | TASK [Download the RPM] ********************************************************
2025-11-25 14:05:34.710423 | controller | Tuesday 25 November 2025  14:05:34 +0000 (0:00:00.031)       0:00:16.599 ******
2025-11-25 14:05:34.741796 | controller | skipping: [instance]
2025-11-25 14:05:34.742024 | controller |
2025-11-25 14:05:34.742192 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-11-25 14:05:34.742342 | controller | Tuesday 25 November 2025  14:05:34 +0000 (0:00:00.031)       0:00:16.631 ******
2025-11-25 14:05:34.772455 | controller | skipping: [instance]
2025-11-25 14:05:34.772671 | controller |
2025-11-25 14:05:34.772865 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-11-25 14:05:34.773059 | controller | Tuesday 25 November 2025  14:05:34 +0000 (0:00:00.030)       0:00:16.662 ******
2025-11-25 14:05:34.803494 | controller | skipping: [instance]
2025-11-25 14:05:34.803711 | controller |
2025-11-25 14:05:34.803892 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-11-25 14:05:34.804075 | controller | Tuesday 25 November 2025  14:05:34 +0000 (0:00:00.030)       0:00:16.693 ******
2025-11-25 14:05:34.839608 | controller | skipping: [instance]
2025-11-25 14:05:34.839827 | controller |
2025-11-25 14:05:34.839997 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-11-25 14:05:34.840137 | controller | Tuesday 25 November 2025  14:05:34 +0000 (0:00:00.035)       0:00:16.728 ******
2025-11-25 14:05:34.870011 | controller | skipping: [instance]
2025-11-25 14:05:34.870216 | controller |
2025-11-25 14:05:34.870390 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-11-25 14:05:34.870534 | controller | Tuesday 25 November 2025  14:05:34 +0000 (0:00:00.030)       0:00:16.759 ******
2025-11-25 14:05:35.069107 | controller | ok: [instance]
2025-11-25 14:05:35.314242 | controller |
2025-11-25 14:05:35.314286 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-11-25 14:05:35.314295 | controller | Tuesday 25 November 2025  14:05:35 +0000 (0:00:00.198)       0:00:16.958 ******
2025-11-25 14:05:35.314307 | controller | changed: [instance]
2025-11-25 14:05:35.590205 | controller |
2025-11-25 14:05:35.590253 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-11-25 14:05:35.590264 | controller | Tuesday 25 November 2025  14:05:35 +0000 (0:00:00.245)       0:00:17.203 ******
2025-11-25 14:05:35.590279 | controller | changed: [instance]
2025-11-25 14:05:35.620471 | controller |
2025-11-25 14:05:35.620498 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-11-25 14:05:35.620508 | controller | Tuesday 25 November 2025  14:05:35 +0000 (0:00:00.275)       0:00:17.478 ******
2025-11-25 14:05:35.620520 | controller | skipping: [instance]
2025-11-25 14:05:35.645452 | controller |
2025-11-25 14:05:35.645474 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-11-25 14:05:35.645483 | controller | Tuesday 25 November 2025  14:05:35 +0000 (0:00:00.030)       0:00:17.509 ******
2025-11-25 14:05:35.645494 | controller | skipping: [instance]
2025-11-25 14:05:35.670842 | controller |
2025-11-25 14:05:35.670892 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-11-25 14:05:35.670902 | controller | Tuesday 25 November 2025  14:05:35 +0000 (0:00:00.024)       0:00:17.534 ******
2025-11-25 14:05:35.670928 | controller | skipping: [instance]
2025-11-25 14:05:35.670979 | controller |
2025-11-25 14:05:35.670990 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-11-25 14:05:35.671061 | controller | Tuesday 25 November 2025  14:05:35 +0000 (0:00:00.025)       0:00:17.560 ******
2025-11-25 14:05:35.696187 | controller | skipping: [instance]
2025-11-25 14:05:35.722852 | controller |
2025-11-25 14:05:35.722903 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-11-25 14:05:35.722911 | controller | Tuesday 25 November 2025  14:05:35 +0000 (0:00:00.025)       0:00:17.585 ******
2025-11-25 14:05:35.722923 | controller | skipping: [instance]
2025-11-25 14:05:35.749197 | controller |
2025-11-25 14:05:35.749220 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-11-25 14:05:35.749241 | controller | Tuesday 25 November 2025  14:05:35 +0000 (0:00:00.026)       0:00:17.611 ******
2025-11-25 14:05:35.749260 | controller | skipping: [instance]
2025-11-25 14:05:35.749363 | controller |
2025-11-25 14:05:35.749515 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-11-25 14:05:35.749665 | controller | Tuesday 25 November 2025  14:05:35 +0000 (0:00:00.026)       0:00:17.638 ******
2025-11-25 14:05:35.770211 | controller | skipping: [instance]
2025-11-25 14:05:35.770363 | controller |
2025-11-25 14:05:35.770511 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-11-25 14:05:35.770665 | controller | Tuesday 25 November 2025  14:05:35 +0000 (0:00:00.020)       0:00:17.659 ******
2025-11-25 14:05:35.811953 | controller | skipping: [instance]
2025-11-25 14:05:35.812044 | controller |
2025-11-25 14:05:35.812195 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-11-25 14:05:35.812310 | controller | Tuesday 25 November 2025  14:05:35 +0000 (0:00:00.041)       0:00:17.701 ******
2025-11-25 14:05:35.833863 | controller | skipping: [instance]
2025-11-25 14:05:35.833968 | controller |
2025-11-25 14:05:35.834123 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-11-25 14:05:35.834255 | controller | Tuesday 25 November 2025  14:05:35 +0000 (0:00:00.021)       0:00:17.723 ******
2025-11-25 14:05:35.860675 | controller | skipping: [instance]
2025-11-25 14:05:35.860808 | controller |
2025-11-25 14:05:35.861017 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-11-25 14:05:35.861125 | controller | Tuesday 25 November 2025  14:05:35 +0000 (0:00:00.026)       0:00:17.750 ******
2025-11-25 14:05:35.902627 | controller | skipping: [instance]
2025-11-25 14:05:35.902783 | controller |
2025-11-25 14:05:35.902929 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-11-25 14:05:35.903070 | controller | Tuesday 25 November 2025  14:05:35 +0000 (0:00:00.041)       0:00:17.792 ******
2025-11-25 14:05:35.937807 | controller | skipping: [instance]
2025-11-25 14:05:35.937954 | controller |
2025-11-25 14:05:35.938111 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-11-25 14:05:35.938253 | controller | Tuesday 25 November 2025  14:05:35 +0000 (0:00:00.035)       0:00:17.827 ******
2025-11-25 14:05:35.969859 | controller | skipping: [instance]
2025-11-25 14:05:35.970014 | controller |
2025-11-25 14:05:35.970162 | controller | TASK [test_deps : Install additional packages] *********************************
2025-11-25 14:05:35.970304 | controller | Tuesday 25 November 2025  14:05:35 +0000 (0:00:00.032)       0:00:17.859 ******
2025-11-25 14:05:36.001694 | controller | skipping: [instance]
2025-11-25 14:05:36.001860 | controller |
2025-11-25 14:05:36.002033 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-11-25 14:05:36.002179 | controller | Tuesday 25 November 2025  14:05:35 +0000 (0:00:00.031)       0:00:17.891 ******
2025-11-25 14:06:36.373912 | controller | ok: [instance]
2025-11-25 14:06:37.757714 | controller |
2025-11-25 14:06:37.757750 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-11-25 14:06:37.757758 | controller | Tuesday 25 November 2025  14:06:36 +0000 (0:01:00.370)       0:01:18.261 ******
2025-11-25 14:06:37.757769 | controller | ok: [instance]
2025-11-25 14:06:37.791409 | controller |
2025-11-25 14:06:37.791440 | controller | TASK [test_deps : Install extra packages] **************************************
2025-11-25 14:06:37.791448 | controller | Tuesday 25 November 2025  14:06:37 +0000 (0:00:01.385)       0:01:19.646 ******
2025-11-25 14:06:37.791459 | controller | skipping: [instance]
2025-11-25 14:06:37.846880 | controller |
2025-11-25 14:06:37.846939 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-11-25 14:06:37.846950 | controller | Tuesday 25 November 2025  14:06:37 +0000 (0:00:00.033)       0:01:19.680 ******
2025-11-25 14:06:37.846964 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-11-25 14:06:37.875024 | controller |
2025-11-25 14:06:37.875151 | controller | TASK [ci_setup : List packages to install] *************************************
2025-11-25 14:06:37.875164 | controller | Tuesday 25 November 2025  14:06:37 +0000 (0:00:00.055)       0:01:19.736 ******
2025-11-25 14:06:37.875213 | controller | ok: [instance] =>
2025-11-25 14:06:46.386579 | controller |   cifmw_ci_setup_packages:
2025-11-25 14:06:46.386634 | controller |   - bash-completion
2025-11-25 14:06:46.386663 | controller |   - ca-certificates
2025-11-25 14:06:46.386669 | controller |   - git-core
2025-11-25 14:06:46.386675 | controller |   - make
2025-11-25 14:06:46.386681 | controller |   - tar
2025-11-25 14:06:46.386686 | controller |   - tmux
2025-11-25 14:06:46.386691 | controller |   - python3-pip
2025-11-25 14:06:46.386697 | controller |
2025-11-25 14:06:46.386703 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-11-25 14:06:46.386716 | controller | Tuesday 25 November 2025  14:06:37 +0000 (0:00:00.027)       0:01:19.763 ******
2025-11-25 14:06:46.386727 | controller | changed: [instance]
2025-11-25 14:06:46.999980 | controller |
2025-11-25 14:06:47.000020 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-11-25 14:06:47.000028 | controller | Tuesday 25 November 2025  14:06:46 +0000 (0:00:08.510)       0:01:28.274 ******
2025-11-25 14:06:47.000039 | controller | ok: [instance]
2025-11-25 14:06:47.000368 | controller |
2025-11-25 14:06:47.000403 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-11-25 14:06:47.000435 | controller | Tuesday 25 November 2025  14:06:46 +0000 (0:00:00.614)       0:01:28.888 ******
2025-11-25 14:06:47.249214 | controller | changed: [instance]
2025-11-25 14:06:47.249264 | controller |
2025-11-25 14:06:47.249275 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-11-25 14:06:47.249431 | controller | Tuesday 25 November 2025  14:06:47 +0000 (0:00:00.248)       0:01:29.137 ******
2025-11-25 14:06:54.139759 | controller | changed: [instance]
2025-11-25 14:06:54.139796 | controller |
2025-11-25 14:06:54.139804 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-11-25 14:06:54.139813 | controller | Tuesday 25 November 2025  14:06:54 +0000 (0:00:06.890)       0:01:36.028 ******
2025-11-25 14:06:54.187019 | controller | skipping: [instance]
2025-11-25 14:06:54.497166 | controller |
2025-11-25 14:06:54.497213 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-11-25 14:06:54.497222 | controller | Tuesday 25 November 2025  14:06:54 +0000 (0:00:00.047)       0:01:36.075 ******
2025-11-25 14:06:54.497234 | controller | changed: [instance]
2025-11-25 14:06:54.807462 | controller |
2025-11-25 14:06:54.807527 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-11-25 14:06:54.807536 | controller | Tuesday 25 November 2025  14:06:54 +0000 (0:00:00.310)       0:01:36.385 ******
2025-11-25 14:06:54.807550 | controller | changed: [instance]
2025-11-25 14:06:54.831760 | controller |
2025-11-25 14:06:54.831810 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-11-25 14:06:54.831819 | controller | Tuesday 25 November 2025  14:06:54 +0000 (0:00:00.310)       0:01:36.696 ******
2025-11-25 14:06:54.831832 | controller | skipping: [instance]
2025-11-25 14:06:54.855799 | controller |
2025-11-25 14:06:54.855866 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-11-25 14:06:54.855877 | controller | Tuesday 25 November 2025  14:06:54 +0000 (0:00:00.024)       0:01:36.720 ******
2025-11-25 14:06:54.855894 | controller | skipping: [instance]
2025-11-25 14:06:54.878787 | controller |
2025-11-25 14:06:54.878825 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-11-25 14:06:54.878835 | controller | Tuesday 25 November 2025  14:06:54 +0000 (0:00:00.023)       0:01:36.744 ******
2025-11-25 14:06:54.878847 | controller | skipping: [instance]
2025-11-25 14:06:54.878884 | controller |
2025-11-25 14:06:54.878895 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-11-25 14:06:54.879044 | controller | Tuesday 25 November 2025  14:06:54 +0000 (0:00:00.023)       0:01:36.768 ******
2025-11-25 14:06:54.901607 | controller | skipping: [instance]
2025-11-25 14:06:54.923418 | controller |
2025-11-25 14:06:54.923446 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-11-25 14:06:54.923457 | controller | Tuesday 25 November 2025  14:06:54 +0000 (0:00:00.022)       0:01:36.790 ******
2025-11-25 14:06:54.923469 | controller | skipping: [instance]
2025-11-25 14:06:54.953036 | controller |
2025-11-25 14:06:54.953106 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-11-25 14:06:54.953130 | controller | Tuesday 25 November 2025  14:06:54 +0000 (0:00:00.021)       0:01:36.812 ******
2025-11-25 14:06:54.953142 | controller | skipping: [instance]
2025-11-25 14:06:54.953291 | controller |
2025-11-25 14:06:54.953435 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-11-25 14:06:54.953573 | controller | Tuesday 25 November 2025  14:06:54 +0000 (0:00:00.029)       0:01:36.842 ******
2025-11-25 14:06:56.016819 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-11-25 14:09:52.461511 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-11-25 14:09:52.461553 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-11-25 14:09:52.461562 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-11-25 14:09:52.461568 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-11-25 14:09:52.461574 | controller |
2025-11-25 14:09:52.461579 | controller | TASK [Ensure CRC is started] ***************************************************
2025-11-25 14:09:52.461584 | controller | Tuesday 25 November 2025  14:06:56 +0000 (0:00:01.063)       0:01:37.905 ******
2025-11-25 14:09:52.461594 | controller | changed: [instance]
2025-11-25 14:09:52.461615 | controller |
2025-11-25 14:09:53.296053 | controller | TASK [Create the openstack namespace] ******************************************
2025-11-25 14:09:53.296112 | controller | Tuesday 25 November 2025  14:09:52 +0000 (0:02:56.443)       0:04:34.349 ******
2025-11-25 14:09:53.296127 | controller | changed: [instance]
2025-11-25 14:09:53.296147 | controller |
2025-11-25 14:09:53.296226 | controller | TASK [Create a dummy configmap] ************************************************
2025-11-25 14:09:53.296329 | controller | Tuesday 25 November 2025  14:09:53 +0000 (0:00:00.835)       0:04:35.185 ******
2025-11-25 14:09:53.910623 | controller | changed: [instance]
2025-11-25 14:09:53.910862 | controller |
2025-11-25 14:09:53.910883 | controller | TASK [Create a dummy openstack config secret] **********************************
2025-11-25 14:09:54.535494 | controller | Tuesday 25 November 2025  14:09:53 +0000 (0:00:00.614)       0:04:35.799 ******
2025-11-25 14:09:54.535547 | controller | changed: [instance]
2025-11-25 14:09:54.535608 | controller |
2025-11-25 14:09:54.535627 | controller | TASK [Create dummy openstack root ca secret] ***********************************
2025-11-25 14:09:55.159223 | controller | Tuesday 25 November 2025  14:09:54 +0000 (0:00:00.624)       0:04:36.424 ******
2025-11-25 14:09:55.159317 | controller | changed: [instance]
2025-11-25 14:09:55.159366 | controller |
2025-11-25 14:09:55.159374 | controller | PLAY RECAP *********************************************************************
2025-11-25 14:09:55.159760 | controller | instance                   : ok=33   changed=20   unreachable=0    failed=0    skipped=37   rescued=0    ignored=0
2025-11-25 14:09:55.159818 | controller |
2025-11-25 14:09:55.159827 | controller | Tuesday 25 November 2025  14:09:55 +0000 (0:00:00.623)       0:04:37.048 ******
2025-11-25 14:09:55.159833 | controller | ===============================================================================
2025-11-25 14:09:55.159841 | controller | Ensure CRC is started ------------------------------------------------- 176.44s
2025-11-25 14:09:55.160005 | controller | test_deps : Install selinux python libs -------------------------------- 60.37s
2025-11-25 14:09:55.160689 | controller | ci_setup : Install needed packages -------------------------------------- 8.51s
2025-11-25 14:09:55.160706 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.28s
2025-11-25 14:09:55.161663 | controller | ci_setup : Install openshift client ------------------------------------- 6.89s
2025-11-25 14:09:55.161688 | controller | test_deps : Install python yaml libs ------------------------------------ 1.39s
2025-11-25 14:09:55.161696 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.15s
2025-11-25 14:09:55.161702 | controller | ci_setup : Manage directories ------------------------------------------- 1.06s
2025-11-25 14:09:55.161707 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.94s
2025-11-25 14:09:55.161712 | controller | Gathering Facts --------------------------------------------------------- 0.93s
2025-11-25 14:09:55.161733 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.84s
2025-11-25 14:09:55.228388 | controller | Create the openstack namespace ------------------------------------------ 0.84s
2025-11-25 14:09:55.228431 | controller | repo_setup : Install repo-setup package --------------------------------- 0.80s
2025-11-25 14:09:55.228439 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.77s
2025-11-25 14:09:55.228445 | controller | repo_setup : Ensure directories are present ----------------------------- 0.63s
2025-11-25 14:09:55.228450 | controller | Create a dummy openstack config secret ---------------------------------- 0.62s
2025-11-25 14:09:55.228456 | controller | Create dummy openstack root ca secret ----------------------------------- 0.62s
2025-11-25 14:09:55.228461 | controller | Create a dummy configmap ------------------------------------------------ 0.61s
2025-11-25 14:09:55.228466 | controller | ci_setup : Gather version of openshift client --------------------------- 0.61s
2025-11-25 14:09:55.228471 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s
2025-11-25 14:09:55.228484 | controller | INFO     Running default > converge
2025-11-25 14:09:55.656086 | controller |
2025-11-25 14:09:55.656334 | controller | PLAY [Converge] ****************************************************************
2025-11-25 14:09:55.656481 | controller |
2025-11-25 14:09:55.656624 | controller | TASK [Gathering Facts] *********************************************************
2025-11-25 14:09:55.656793 | controller | Tuesday 25 November 2025  14:09:55 +0000 (0:00:00.035)       0:00:00.035 ******
2025-11-25 14:09:56.623300 | controller | ok: [instance]
2025-11-25 14:09:56.650466 | controller |
2025-11-25 14:09:56.650531 | controller | TASK [Include the shiftstack role] *********************************************
2025-11-25 14:09:56.650542 | controller | Tuesday 25 November 2025  14:09:56 +0000 (0:00:00.966)       0:00:01.002 ******
2025-11-25 14:09:56.650554 | controller |
2025-11-25 14:09:56.650586 | controller | TASK [shiftstack : Include pre test shiftstack tasks] **************************
2025-11-25 14:09:56.650625 | controller | Tuesday 25 November 2025  14:09:56 +0000 (0:00:00.027)       0:00:01.030 ******
2025-11-25 14:09:56.677754 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/pre_test_shiftstack.yml for instance
2025-11-25 14:09:56.677814 | controller |
2025-11-25 14:09:56.677923 | controller | TASK [shiftstack : Fail if the testconfig is not passed] ***********************
2025-11-25 14:09:56.678048 | controller | Tuesday 25 November 2025  14:09:56 +0000 (0:00:00.027)       0:00:01.058 ******
2025-11-25 14:09:56.704222 | controller | ok: [instance] => changed=false
2025-11-25 14:09:56.704320 | controller |   msg: All assertions passed
2025-11-25 14:09:56.704413 | controller |
2025-11-25 14:09:56.704528 | controller | TASK [shiftstack : Remove the shiftstackclient pod if exists] ******************
2025-11-25 14:09:56.704627 | controller | Tuesday 25 November 2025  14:09:56 +0000 (0:00:00.026)       0:00:01.084 ******
2025-11-25 14:09:57.507414 | controller | ok: [instance]
2025-11-25 14:09:57.507479 | controller |
2025-11-25 14:09:57.507530 | controller | TASK [shiftstack : Remove the shiftstack role data directory (if exists)] ******
2025-11-25 14:09:57.507659 | controller | Tuesday 25 November 2025  14:09:57 +0000 (0:00:00.802)       0:00:01.887 ******
2025-11-25 14:09:57.818144 | controller | ok: [instance]
2025-11-25 14:09:57.818219 | controller |
2025-11-25 14:09:57.819021 | controller | TASK [shiftstack : Create the directory '{{ item }}'] **************************
2025-11-25 14:09:58.581519 | controller | Tuesday 25 November 2025  14:09:57 +0000 (0:00:00.310)       0:00:02.198 ******
2025-11-25 14:09:58.581572 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack)
2025-11-25 14:09:58.581601 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs)
2025-11-25 14:09:58.582176 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack/manifests)
2025-11-25 14:09:58.582189 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack/artifacts)
2025-11-25 14:09:58.582195 | controller |
2025-11-25 14:09:58.582200 | controller | TASK [shiftstack : Deploy the pod 'shiftstackclient-shiftstack'] ***************
2025-11-25 14:09:58.582208 | controller | Tuesday 25 November 2025  14:09:58 +0000 (0:00:00.763)       0:00:02.961 ******
2025-11-25 14:09:58.610012 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/deploy_shiftstackclient_pod.yml for instance
2025-11-25 14:09:58.610119 | controller |
2025-11-25 14:09:58.610235 | controller | TASK [shiftstack : Switch context to admin on /home/zuul/.crc/machines/crc/kubeconfig] ***
2025-11-25 14:09:58.610332 | controller | Tuesday 25 November 2025  14:09:58 +0000 (0:00:00.028)       0:00:02.990 ******
2025-11-25 14:09:59.000474 | controller | changed: [instance]
2025-11-25 14:09:59.000625 | controller |
2025-11-25 14:09:59.000704 | controller | TASK [shiftstack : Encode kubeconfig file to Base64] ***************************
2025-11-25 14:09:59.000821 | controller | Tuesday 25 November 2025  14:09:58 +0000 (0:00:00.390)       0:00:03.380 ******
2025-11-25 14:09:59.034985 | controller | ok: [instance]
2025-11-25 14:09:59.035058 | controller |
2025-11-25 14:09:59.035151 | controller | TASK [shiftstack : Render the incluster kubeconfig secret manifest] ************
2025-11-25 14:09:59.035256 | controller | Tuesday 25 November 2025  14:09:59 +0000 (0:00:00.034)       0:00:03.415 ******
2025-11-25 14:09:59.623686 | controller | changed: [instance]
2025-11-25 14:09:59.623731 | controller |
2025-11-25 14:09:59.623843 | controller | TASK [shiftstack : Apply the manifest for the incluster kubeconfig secret] *****
2025-11-25 14:09:59.623975 | controller | Tuesday 25 November 2025  14:09:59 +0000 (0:00:00.588)       0:00:04.003 ******
2025-11-25 14:10:00.269699 | controller | changed: [instance]
2025-11-25 14:10:00.269787 | controller |
2025-11-25 14:10:00.269887 | controller | TASK [shiftstack : Render the pvc manifest] ************************************
2025-11-25 14:10:00.269924 | controller | Tuesday 25 November 2025  14:10:00 +0000 (0:00:00.646)       0:00:04.649 ******
2025-11-25 14:10:00.621895 | controller | changed: [instance]
2025-11-25 14:10:00.621953 | controller |
2025-11-25 14:10:00.621997 | controller | TASK [shiftstack : Apply the manifest for the PVC creation] ********************
2025-11-25 14:10:00.622177 | controller | Tuesday 25 November 2025  14:10:00 +0000 (0:00:00.352)       0:00:05.002 ******
2025-11-25 14:10:01.242215 | controller | changed: [instance]
2025-11-25 14:10:01.604916 | controller |
2025-11-25 14:10:01.604957 | controller | TASK [shiftstack : Render the pod manifest from a template] ********************
2025-11-25 14:10:01.604983 | controller | Tuesday 25 November 2025  14:10:01 +0000 (0:00:00.620)       0:00:05.622 ******
2025-11-25 14:10:01.604995 | controller | changed: [instance]
2025-11-25 14:14:44.666476 | controller |
2025-11-25 14:14:44.666518 | controller | TASK [shiftstack : Apply the manifest for the pod creation] ********************
2025-11-25 14:14:44.666527 | controller | Tuesday 25 November 2025  14:10:01 +0000 (0:00:00.359)       0:00:05.981 ******
2025-11-25 14:14:44.666537 | controller | changed: [instance]
2025-11-25 14:14:44.700758 | controller |
2025-11-25 14:14:44.700803 | controller | TASK [shiftstack : Test Openshift on Openstack] ********************************
2025-11-25 14:14:44.700815 | controller | Tuesday 25 November 2025  14:14:44 +0000 (0:04:43.065)       0:04:49.046 ******
2025-11-25 14:14:44.700831 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/test_shiftstack.yml for instance
2025-11-25 14:14:44.700865 | controller |
2025-11-25 14:14:44.700875 | controller | TASK [shiftstack : Clone the repository 'https://review.gerrithub.io/shiftstack/shiftstack-qa'] ***
2025-11-25 14:14:44.700960 | controller | Tuesday 25 November 2025  14:14:44 +0000 (0:00:00.034)       0:04:49.080 ******
2025-11-25 14:14:44.732919 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/exec_command_in_pod.yml for instance
2025-11-25 14:14:44.732968 | controller |
2025-11-25 14:14:44.732979 | controller | TASK [shiftstack : Execute in the pod the command 'git clone https://review.gerrithub.io/shiftstack/shiftstack-qa'] ***
2025-11-25 14:14:44.733105 | controller | Tuesday 25 November 2025  14:14:44 +0000 (0:00:00.032)       0:04:49.113 ******
2025-11-25 14:14:52.621277 | controller | changed: [instance]
2025-11-25 14:14:52.860579 | controller |
2025-11-25 14:14:52.860622 | controller | TASK [shiftstack : Get current date and time] **********************************
2025-11-25 14:14:52.860631 | controller | Tuesday 25 November 2025  14:14:52 +0000 (0:00:07.887)       0:04:57.001 ******
2025-11-25 14:14:52.860658 | controller | changed: [instance]
2025-11-25 14:14:52.860693 | controller |
2025-11-25 14:14:52.860700 | controller | TASK [shiftstack : Save the command execution information in the log file in '/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs'] ***
2025-11-25 14:14:52.860708 | controller | Tuesday 25 November 2025  14:14:52 +0000 (0:00:00.239)       0:04:57.240 ******
2025-11-25 14:14:53.231612 | controller | changed: [instance]
2025-11-25 14:14:53.231690 | controller |
2025-11-25 14:14:53.231965 | controller | TASK [shiftstack : Fetch the gerrithub change ''] ******************************
2025-11-25 14:14:53.260721 | controller | Tuesday 25 November 2025  14:14:53 +0000 (0:00:00.371)       0:04:57.611 ******
2025-11-25 14:14:53.260752 | controller | skipping: [instance]
2025-11-25 14:14:53.260793 | controller |
2025-11-25 14:14:53.261009 | controller | TASK [shiftstack : Install the ansible collections] ****************************
2025-11-25 14:14:53.290108 | controller | Tuesday 25 November 2025  14:14:53 +0000 (0:00:00.029)       0:04:57.641 ******
2025-11-25 14:14:53.290140 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/exec_command_in_pod.yml for instance
2025-11-25 14:14:53.290353 | controller |
2025-11-25 14:14:53.290364 | controller | TASK [shiftstack : Execute in the pod the command 'cd shiftstack-qa && ansible-galaxy collection install -f -r requirements.yaml'] ***
2025-11-25 14:15:10.552249 | controller | Tuesday 25 November 2025  14:14:53 +0000 (0:00:00.029)       0:04:57.670 ******
2025-11-25 14:15:10.552326 | controller | changed: [instance]
2025-11-25 14:15:10.750265 | controller |
2025-11-25 14:15:10.750304 | controller | TASK [shiftstack : Get current date and time] **********************************
2025-11-25 14:15:10.750313 | controller | Tuesday 25 November 2025  14:15:10 +0000 (0:00:17.260)       0:05:14.931 ******
2025-11-25 14:15:10.750326 | controller | changed: [instance]
2025-11-25 14:15:10.750507 | controller |
2025-11-25 14:15:10.750698 | controller | TASK [shiftstack : Save the command execution information in the log file in '/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs'] ***
2025-11-25 14:15:11.102295 | controller | Tuesday 25 November 2025  14:15:10 +0000 (0:00:00.199)       0:05:15.130 ******
2025-11-25 14:15:11.102340 | controller | changed: [instance]
2025-11-25 14:15:11.102957 | controller |
2025-11-25 14:15:11.102972 | controller | TASK [shiftstack : Initialize a dictionary to store the result of the test configs] ***
2025-11-25 14:15:11.102981 | controller | Tuesday 25 November 2025  14:15:11 +0000 (0:00:00.352)       0:05:15.482 ******
2025-11-25 14:15:11.125620 | controller | ok: [instance]
2025-11-25 14:15:11.125737 | controller |
2025-11-25 14:15:11.125872 | controller | TASK [shiftstack : Include test config tasks and loop over '['cifmw-gate.yaml', 'cifmw-gate.yaml']'] ***
2025-11-25 14:15:11.125974 | controller | Tuesday 25 November 2025  14:15:11 +0000 (0:00:00.023)       0:05:15.506 ******
2025-11-25 14:15:11.192094 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/test_config.yml for instance => (item=cifmw-gate.yaml)
2025-11-25 14:15:11.192287 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/test_config.yml for instance => (item=cifmw-gate.yaml)
2025-11-25 14:15:11.192445 | controller |
2025-11-25 14:15:11.192618 | controller | TASK [shiftstack : Initialize the testconfig_result_dict fact for the testconfig 'cifmw-gate.yaml'] ***
2025-11-25 14:15:11.192855 | controller | Tuesday 25 November 2025  14:15:11 +0000 (0:00:00.066)       0:05:15.572 ******
2025-11-25 14:15:11.217273 | controller | ok: [instance]
2025-11-25 14:15:11.217389 | controller |
2025-11-25 14:15:11.217521 | controller | TASK [shiftstack : Check the test configuration file exists in the repository] ***
2025-11-25 14:15:11.217651 | controller | Tuesday 25 November 2025  14:15:11 +0000 (0:00:00.025)       0:05:15.597 ******
2025-11-25 14:15:11.249600 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/exec_command_in_pod.yml for instance
2025-11-25 14:15:11.249743 | controller |
2025-11-25 14:15:11.249896 | controller | TASK [shiftstack : Execute in the pod the command 'test -f shiftstack-qa/jobs_definitions/cifmw-gate.yaml'] ***
2025-11-25 14:15:11.250006 | controller | Tuesday 25 November 2025  14:15:11 +0000 (0:00:00.032)       0:05:15.630 ******
2025-11-25 14:15:11.667532 | controller | changed: [instance]
2025-11-25 14:15:11.667583 | controller |
2025-11-25 14:15:11.667700 | controller | TASK [shiftstack : Get current date and time] **********************************
2025-11-25 14:15:11.667825 | controller | Tuesday 25 November 2025  14:15:11 +0000 (0:00:00.417)       0:05:16.047 ******
2025-11-25 14:15:11.872359 | controller | changed: [instance]
2025-11-25 14:15:11.872450 | controller |
2025-11-25 14:15:11.872601 | controller | TASK [shiftstack : Save the command execution information in the log file in '/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs'] ***
2025-11-25 14:15:11.872770 | controller | Tuesday 25 November 2025  14:15:11 +0000 (0:00:00.204)       0:05:16.252 ******
2025-11-25 14:15:12.248904 | controller | changed: [instance]
2025-11-25 14:15:12.248980 | controller |
2025-11-25 14:15:12.249098 | controller | TASK [shiftstack : Create the directory for the test config artifacts '/home/zuul/ci-framework-data/tests/shiftstack/artifacts/0-cifmw-gate'] ***
2025-11-25 14:15:12.249213 | controller | Tuesday 25 November 2025  14:15:12 +0000 (0:00:00.376)       0:05:16.629 ******
2025-11-25 14:15:12.458450 | controller | changed: [instance]
2025-11-25 14:15:12.458530 | controller |
2025-11-25 14:15:12.458653 | controller | TASK [shiftstack : Test Openshift on Openstack with the test configuration 'cifmw-gate.yaml'] ***
2025-11-25 14:15:12.458790 | controller | Tuesday 25 November 2025  14:15:12 +0000 (0:00:00.209)       0:05:16.838 ******
2025-11-25 14:15:12.496391 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/exec_command_in_pod.yml for instance
2025-11-25 14:15:12.496489 | controller |
2025-11-25 14:15:12.496654 | controller | TASK [shiftstack : Execute in the pod the command 'source .bashrc && cd shiftstack-qa && ansible-navigator run playbooks/cifmw-gate.yaml -e @jobs_definitions/cifmw-gate.yaml -e ocp_cluster_name=ostest -e user_cloud=shiftstack -e hypervisor= -e rhoso_kubeconfig=/home/cloud-admin/incluster-kubeconfig/kubeconfig'] ***
2025-11-25 14:15:12.496794 | controller | Tuesday 25 November 2025  14:15:12 +0000 (0:00:00.038)       0:05:16.877 ******
2025-11-25 14:15:15.049198 | controller | changed: [instance]
2025-11-25 14:15:15.049368 | controller |
2025-11-25 14:15:15.049486 | controller | TASK [shiftstack : Get current date and time] **********************************
2025-11-25 14:15:15.049525 | controller | Tuesday 25 November 2025  14:15:15 +0000 (0:00:02.552)       0:05:19.429 ******
2025-11-25 14:15:15.074688 | controller | skipping: [instance]
2025-11-25 14:15:15.095478 | controller |
2025-11-25 14:15:15.095522 | controller | TASK [shiftstack : Save the command execution information in the log file in '/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs'] ***
2025-11-25 14:15:15.095536 | controller | Tuesday 25 November 2025  14:15:15 +0000 (0:00:00.025)       0:05:19.454 ******
2025-11-25 14:15:15.095548 | controller | skipping: [instance]
2025-11-25 14:15:15.095593 | controller |
2025-11-25 14:15:15.095603 | controller | TASK [shiftstack : Copy the artifacts from the pod 'shiftstackclient-shiftstack'] ***
2025-11-25 14:15:15.696418 | controller | Tuesday 25 November 2025  14:15:15 +0000 (0:00:00.021)       0:05:19.476 ******
2025-11-25 14:15:15.696474 | controller | changed: [instance]
2025-11-25 14:15:15.696499 | controller |
2025-11-25 14:15:15.696589 | controller | TASK [shiftstack : Initialize the testconfig_result_dict fact for the testconfig 'cifmw-gate.yaml'] ***
2025-11-25 14:15:15.696740 | controller | Tuesday 25 November 2025  14:15:15 +0000 (0:00:00.600)       0:05:20.076 ******
2025-11-25 14:15:15.723346 | controller | ok: [instance]
2025-11-25 14:15:15.723444 | controller |
2025-11-25 14:15:15.723564 | controller | TASK [shiftstack : Check the test configuration file exists in the repository] ***
2025-11-25 14:15:15.723702 | controller | Tuesday 25 November 2025  14:15:15 +0000 (0:00:00.027)       0:05:20.104 ******
2025-11-25 14:15:15.754675 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/exec_command_in_pod.yml for instance
2025-11-25 14:15:15.754837 | controller |
2025-11-25 14:15:15.754850 | controller | TASK [shiftstack : Execute in the pod the command 'test -f shiftstack-qa/jobs_definitions/cifmw-gate.yaml'] ***
2025-11-25 14:15:15.754967 | controller | Tuesday 25 November 2025  14:15:15 +0000 (0:00:00.031)       0:05:20.135 ******
2025-11-25 14:15:16.153890 | controller | changed: [instance]
2025-11-25 14:15:16.153953 | controller |
2025-11-25 14:15:16.154067 | controller | TASK [shiftstack : Get current date and time] **********************************
2025-11-25 14:15:16.154183 | controller | Tuesday 25 November 2025  14:15:16 +0000 (0:00:00.398)       0:05:20.533 ******
2025-11-25 14:15:16.361393 | controller | changed: [instance]
2025-11-25 14:15:16.361503 | controller |
2025-11-25 14:15:16.361614 | controller | TASK [shiftstack : Save the command execution information in the log file in '/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs'] ***
2025-11-25 14:15:16.361754 | controller | Tuesday 25 November 2025  14:15:16 +0000 (0:00:00.207)       0:05:20.741 ******
2025-11-25 14:15:16.731218 | controller | changed: [instance]
2025-11-25 14:15:16.731301 | controller |
2025-11-25 14:15:16.731419 | controller | TASK [shiftstack : Create the directory for the test config artifacts '/home/zuul/ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate'] ***
2025-11-25 14:15:16.731543 | controller | Tuesday 25 November 2025  14:15:16 +0000 (0:00:00.369)       0:05:21.111 ******
2025-11-25 14:15:16.946113 | controller | changed: [instance]
2025-11-25 14:15:16.946176 | controller |
2025-11-25 14:15:16.946280 | controller | TASK [shiftstack : Test Openshift on Openstack with the test configuration 'cifmw-gate.yaml'] ***
2025-11-25 14:15:16.946385 | controller | Tuesday 25 November 2025  14:15:16 +0000 (0:00:00.214)       0:05:21.326 ******
2025-11-25 14:15:16.980931 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/exec_command_in_pod.yml for instance
2025-11-25 14:15:16.981042 | controller |
2025-11-25 14:15:16.981195 | controller | TASK [shiftstack : Execute in the pod the command 'source .bashrc && cd shiftstack-qa && ansible-navigator run playbooks/cifmw-gate.yaml -e @jobs_definitions/cifmw-gate.yaml -e ocp_cluster_name=ostest -e user_cloud=shiftstack -e hypervisor= -e rhoso_kubeconfig=/home/cloud-admin/incluster-kubeconfig/kubeconfig'] ***
2025-11-25 14:15:16.981319 | controller | Tuesday 25 November 2025  14:15:16 +0000 (0:00:00.035)       0:05:21.361 ******
2025-11-25 14:15:19.278782 | controller | changed: [instance]
2025-11-25 14:15:19.278878 | controller |
2025-11-25 14:15:19.278991 | controller | TASK [shiftstack : Get current date and time] **********************************
2025-11-25 14:15:19.279096 | controller | Tuesday 25 November 2025  14:15:19 +0000 (0:00:02.297)       0:05:23.658 ******
2025-11-25 14:15:19.306530 | controller | skipping: [instance]
2025-11-25 14:15:19.306619 | controller |
2025-11-25 14:15:19.306762 | controller | TASK [shiftstack : Save the command execution information in the log file in '/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs'] ***
2025-11-25 14:15:19.306888 | controller | Tuesday 25 November 2025  14:15:19 +0000 (0:00:00.028)       0:05:23.687 ******
2025-11-25 14:15:19.330409 | controller | skipping: [instance]
2025-11-25 14:15:19.330509 | controller |
2025-11-25 14:15:19.330660 | controller | TASK [shiftstack : Copy the artifacts from the pod 'shiftstackclient-shiftstack'] ***
2025-11-25 14:15:19.330745 | controller | Tuesday 25 November 2025  14:15:19 +0000 (0:00:00.023)       0:05:23.711 ******
2025-11-25 14:15:19.885700 | controller | changed: [instance]
2025-11-25 14:15:19.885757 | controller |
2025-11-25 14:15:19.885894 | controller | TASK [shiftstack : Initialize a list to store failed test configs] *************
2025-11-25 14:15:19.885997 | controller | Tuesday 25 November 2025  14:15:19 +0000 (0:00:00.554)       0:05:24.265 ******
2025-11-25 14:15:19.905540 | controller | ok: [instance]
2025-11-25 14:15:19.905654 | controller |
2025-11-25 14:15:19.905831 | controller | TASK [shiftstack : Find failed test configurations] ****************************
2025-11-25 14:15:19.905974 | controller | Tuesday 25 November 2025  14:15:19 +0000 (0:00:00.020)       0:05:24.286 ******
2025-11-25 14:15:19.936436 | controller | skipping: [instance] => (item={'key': 0, 'value': {'cifmw-gate.yaml': 'PASS'}})
2025-11-25 14:15:19.936568 | controller | skipping: [instance] => (item={'key': 1, 'value': {'cifmw-gate.yaml': 'PASS'}})
2025-11-25 14:15:19.936735 | controller | skipping: [instance]
2025-11-25 14:15:19.936883 | controller |
2025-11-25 14:15:19.937024 | controller | TASK [shiftstack : Print test configs result] **********************************
2025-11-25 14:15:19.937153 | controller | Tuesday 25 November 2025  14:15:19 +0000 (0:00:00.030)       0:05:24.316 ******
2025-11-25 14:15:19.958398 | controller | ok: [instance] =>
2025-11-25 14:15:19.958541 | controller |   msg: |-
2025-11-25 14:15:19.959017 | controller |     {
2025-11-25 14:15:19.959156 | controller |         "0": {
2025-11-25 14:15:19.959309 | controller |             "cifmw-gate.yaml": "PASS"
2025-11-25 14:15:19.959454 | controller |         },
2025-11-25 14:15:19.959548 | controller |         "1": {
2025-11-25 14:15:19.959663 | controller |             "cifmw-gate.yaml": "PASS"
2025-11-25 14:15:19.959797 | controller |         }
2025-11-25 14:15:19.959918 | controller |     }
2025-11-25 14:15:19.959984 | controller |
2025-11-25 14:15:19.960082 | controller | TASK [shiftstack : Fail if there is any failed test config] ********************
2025-11-25 14:15:19.960202 | controller | Tuesday 25 November 2025  14:15:19 +0000 (0:00:00.021)       0:05:24.338 ******
2025-11-25 14:15:19.997323 | controller | skipping: [instance]
2025-11-25 14:15:19.997445 | controller |
2025-11-25 14:15:19.997507 | controller | PLAY RECAP *********************************************************************
2025-11-25 14:15:19.997613 | controller | instance                   : ok=47   changed=26   unreachable=0    failed=0    skipped=7    rescued=0    ignored=0
2025-11-25 14:15:19.997723 | controller |
2025-11-25 14:15:19.997832 | controller | Tuesday 25 November 2025  14:15:19 +0000 (0:00:00.038)       0:05:24.377 ******
2025-11-25 14:15:19.997924 | controller | ===============================================================================
2025-11-25 14:15:19.998041 | controller | shiftstack : Apply the manifest for the pod creation ------------------ 283.07s
2025-11-25 14:15:19.998135 | controller | shiftstack : Execute in the pod the command 'cd shiftstack-qa && ansible-galaxy collection install -f -r requirements.yaml' -- 17.26s
2025-11-25 14:15:19.998240 | controller | shiftstack : Execute in the pod the command 'git clone https://review.gerrithub.io/shiftstack/shiftstack-qa' --- 7.89s
2025-11-25 14:15:19.998377 | controller | shiftstack : Execute in the pod the command 'source .bashrc && cd shiftstack-qa && ansible-navigator run playbooks/cifmw-gate.yaml -e @jobs_definitions/cifmw-gate.yaml -e ocp_cluster_name=ostest -e user_cloud=shiftstack -e hypervisor= -e rhoso_kubeconfig=/home/cloud-admin/incluster-kubeconfig/kubeconfig' --- 2.55s
2025-11-25 14:15:19.998466 | controller | shiftstack : Execute in the pod the command 'source .bashrc && cd shiftstack-qa && ansible-navigator run playbooks/cifmw-gate.yaml -e @jobs_definitions/cifmw-gate.yaml -e ocp_cluster_name=ostest -e user_cloud=shiftstack -e hypervisor= -e rhoso_kubeconfig=/home/cloud-admin/incluster-kubeconfig/kubeconfig' --- 2.30s
2025-11-25 14:15:19.998566 | controller | Gathering Facts --------------------------------------------------------- 0.97s
2025-11-25 14:15:19.998699 | controller | shiftstack : Remove the shiftstackclient pod if exists ------------------ 0.80s
2025-11-25 14:15:19.998810 | controller | shiftstack : Create the directory '{{ item }}' -------------------------- 0.76s
2025-11-25 14:15:19.998979 | controller | shiftstack : Apply the manifest for the incluster kubeconfig secret ----- 0.65s
2025-11-25 14:15:19.999087 | controller | shiftstack : Apply the manifest for the PVC creation -------------------- 0.62s
2025-11-25 14:15:19.999123 | controller | shiftstack : Copy the artifacts from the pod 'shiftstackclient-shiftstack' --- 0.60s
2025-11-25 14:15:19.999224 | controller | shiftstack : Render the incluster kubeconfig secret manifest ------------ 0.59s
2025-11-25 14:15:19.999400 | controller | shiftstack : Copy the artifacts from the pod 'shiftstackclient-shiftstack' --- 0.55s
2025-11-25 14:15:19.999439 | controller | shiftstack : Execute in the pod the command 'test -f shiftstack-qa/jobs_definitions/cifmw-gate.yaml' --- 0.42s
2025-11-25 14:15:19.999543 | controller | shiftstack : Execute in the pod the command 'test -f shiftstack-qa/jobs_definitions/cifmw-gate.yaml' --- 0.40s
2025-11-25 14:15:19.999653 | controller | shiftstack : Switch context to admin on /home/zuul/.crc/machines/crc/kubeconfig --- 0.39s
2025-11-25 14:15:19.999977 | controller | shiftstack : Save the command execution information in the log file in '/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs' --- 0.38s
2025-11-25 14:15:20.000078 | controller | shiftstack : Save the command execution information in the log file in '/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs' --- 0.37s
2025-11-25 14:15:20.000243 | controller | shiftstack : Save the command execution information in the log file in '/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs' --- 0.37s
2025-11-25 14:15:20.075338 | controller | shiftstack : Render the pod manifest from a template -------------------- 0.36s
2025-11-25 14:15:20.075376 | controller | INFO     Running default > cleanup
2025-11-25 14:15:20.552029 | controller |
2025-11-25 14:15:20.552072 | controller | PLAY [Cleanup] *****************************************************************
2025-11-25 14:15:20.552164 | controller |
2025-11-25 14:15:20.552262 | controller | TASK [Gathering Facts] *********************************************************
2025-11-25 14:15:20.552363 | controller | Tuesday 25 November 2025  14:15:20 +0000 (0:00:00.049)       0:00:00.049 ******
2025-11-25 14:15:21.451319 | controller | ok: [instance]
2025-11-25 14:15:21.451395 | controller |
2025-11-25 14:15:21.451511 | controller | TASK [Include the shiftstack role and run the cleanup] *************************
2025-11-25 14:15:21.451629 | controller | Tuesday 25 November 2025  14:15:21 +0000 (0:00:00.899)       0:00:00.948 ******
2025-11-25 14:15:21.480089 | controller |
2025-11-25 14:15:21.480219 | controller | TASK [shiftstack : Destroy the Openshift cluster] ******************************
2025-11-25 14:15:21.480363 | controller | Tuesday 25 November 2025  14:15:21 +0000 (0:00:00.029)       0:00:00.978 ******
2025-11-25 14:15:21.503763 | controller | ok: [instance] =>
2025-11-25 14:15:21.503910 | controller |   msg: 'TODO: to be added once it''s supported in shiftstack-qa automation'
2025-11-25 14:15:21.504011 | controller |
2025-11-25 14:15:21.504111 | controller | TASK [shiftstack : Delete the secret 'incluster-kubeconfig'] *******************
2025-11-25 14:15:21.504204 | controller | Tuesday 25 November 2025  14:15:21 +0000 (0:00:00.023)       0:00:01.001 ******
2025-11-25 14:15:22.326704 | controller | changed: [instance]
2025-11-25 14:15:22.326756 | controller |
2025-11-25 14:15:22.326920 | controller | TASK [shiftstack : Delete the pod 'shiftstackclient-shiftstack'] ***************
2025-11-25 14:15:22.327016 | controller | Tuesday 25 November 2025  14:15:22 +0000 (0:00:00.822)       0:00:01.824 ******
2025-11-25 14:15:23.024091 | controller | changed: [instance]
2025-11-25 14:15:23.024184 | controller |
2025-11-25 14:15:23.024343 | controller | TASK [Delete the openstack namespace] ******************************************
2025-11-25 14:15:23.024444 | controller | Tuesday 25 November 2025  14:15:23 +0000 (0:00:00.697)       0:00:02.521 ******
2025-11-25 14:15:23.678049 | controller | changed: [instance]
2025-11-25 14:15:23.678105 | controller |
2025-11-25 14:15:23.678191 | controller | PLAY RECAP *********************************************************************
2025-11-25 14:15:23.678331 | controller | instance                   : ok=5    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-11-25 14:15:23.678420 | controller |
2025-11-25 14:15:23.678525 | controller | Tuesday 25 November 2025  14:15:23 +0000 (0:00:00.654)       0:00:03.176 ******
2025-11-25 14:15:23.678619 | controller | ===============================================================================
2025-11-25 14:15:23.678844 | controller | Gathering Facts --------------------------------------------------------- 0.90s
2025-11-25 14:15:23.678872 | controller | shiftstack : Delete the secret 'incluster-kubeconfig' ------------------- 0.82s
2025-11-25 14:15:23.679211 | controller | shiftstack : Delete the pod 'shiftstackclient-shiftstack' --------------- 0.70s
2025-11-25 14:15:23.679284 | controller | Delete the openstack namespace ------------------------------------------ 0.65s
2025-11-25 14:15:23.679315 | controller | Include the shiftstack role and run the cleanup ------------------------- 0.03s
2025-11-25 14:15:23.679325 | controller | shiftstack : Destroy the Openshift cluster ------------------------------ 0.02s
2025-11-25 14:15:23.735037 | controller | INFO     Writing /tmp/report.html report.
2025-11-25 14:15:24.232759 | controller | changed
2025-11-25 14:15:24.253082 | 
2025-11-25 14:15:24.253151 | PLAY RECAP
2025-11-25 14:15:24.253198 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-25 14:15:24.253226 | 
2025-11-25 14:15:24.313090 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-25 14:15:24.313952 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-25 14:15:24.816727 | 
2025-11-25 14:15:24.816818 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-11-25 14:15:24.836812 | 
2025-11-25 14:15:24.836883 | TASK [Filter out host if needed]
2025-11-25 14:15:24.845150 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-25 14:15:24.849540 | 
2025-11-25 14:15:24.849605 | TASK [Ensure file is present]
2025-11-25 14:15:25.223494 | controller | ok
2025-11-25 14:15:25.230157 | 
2025-11-25 14:15:25.230227 | TASK [Manage molecule report file]
2025-11-25 14:15:25.755250 | controller | changed
2025-11-25 14:15:25.760199 | 
2025-11-25 14:15:25.760272 | TASK [Check if we get ci-framework-data basedir]
2025-11-25 14:15:26.011916 | controller | ok
2025-11-25 14:15:26.016868 | 
2025-11-25 14:15:26.016932 | TASK [Create ci-framework-data log directory for zuul]
2025-11-25 14:15:26.378996 | controller | changed
2025-11-25 14:15:26.384357 | 
2025-11-25 14:15:26.384451 | TASK [Copy ci-framework interesting files]
2025-11-25 14:15:26.910946 | controller | changed
2025-11-25 14:15:26.916170 | 
2025-11-25 14:15:26.916236 | TASK [Get SELinux listing]
2025-11-25 14:15:27.441959 | controller | changed
2025-11-25 14:15:27.447500 | 
2025-11-25 14:15:27.447568 | TASK [Generate log index]
2025-11-25 14:15:28.873614 | controller | changed
2025-11-25 14:15:28.878511 | 
2025-11-25 14:15:28.878586 | TASK [Get some env related data]
2025-11-25 14:15:29.649139 | controller | /home/zuul/.local/bin/ansible
2025-11-25 14:15:30.406340 | controller | changed
2025-11-25 14:15:30.411267 | 
2025-11-25 14:15:30.411328 | TASK [Generate list of logs to collect in home directory]
2025-11-25 14:15:30.781627 | controller | ok: All paths examined
2025-11-25 14:15:30.786699 | 
2025-11-25 14:15:30.786760 | LOOP [Copy logs from home directory]
2025-11-25 14:15:31.494889 | controller | changed:
2025-11-25 14:15:31.495086 | controller | {
2025-11-25 14:15:31.495115 | controller |   "atime": 1743544925.4788878,
2025-11-25 14:15:31.495139 | controller |   "ctime": 1743545329.1409318,
2025-11-25 14:15:31.495157 | controller |   "dev": 64513,
2025-11-25 14:15:31.495174 | controller |   "gid": 1000,
2025-11-25 14:15:31.495191 | controller |   "gr_name": "zuul",
2025-11-25 14:15:31.495205 | controller |   "inode": 4518807,
2025-11-25 14:15:31.495230 | controller |   "isblk": false,
2025-11-25 14:15:31.495247 | controller |   "ischr": false,
2025-11-25 14:15:31.495263 | controller |   "isdir": false,
2025-11-25 14:15:31.495277 | controller |   "isfifo": false,
2025-11-25 14:15:31.495291 | controller |   "isgid": false,
2025-11-25 14:15:31.495306 | controller |   "islnk": false,
2025-11-25 14:15:31.495320 | controller |   "isreg": true,
2025-11-25 14:15:31.495333 | controller |   "issock": false,
2025-11-25 14:15:31.495346 | controller |   "isuid": false,
2025-11-25 14:15:31.495360 | controller |   "mode": "0644",
2025-11-25 14:15:31.495373 | controller |   "mtime": 1743545329.1409318,
2025-11-25 14:15:31.495422 | controller |   "nlink": 1,
2025-11-25 14:15:31.495441 | controller |   "path": "/home/zuul/crc-setup.log",
2025-11-25 14:15:31.495456 | controller |   "pw_name": "zuul",
2025-11-25 14:15:31.495472 | controller |   "rgrp": true,
2025-11-25 14:15:31.495486 | controller |   "roth": true,
2025-11-25 14:15:31.495500 | controller |   "rusr": true,
2025-11-25 14:15:31.495514 | controller |   "size": 4108,
2025-11-25 14:15:31.495528 | controller |   "uid": 1000,
2025-11-25 14:15:31.495542 | controller |   "wgrp": false,
2025-11-25 14:15:31.495557 | controller |   "woth": false,
2025-11-25 14:15:31.495571 | controller |   "wusr": true,
2025-11-25 14:15:31.495588 | controller |   "xgrp": false,
2025-11-25 14:15:31.495602 | controller |   "xoth": false,
2025-11-25 14:15:31.495616 | controller |   "xusr": false
2025-11-25 14:15:31.495629 | controller | }
2025-11-25 14:15:32.177693 | controller | changed:
2025-11-25 14:15:32.177784 | controller | {
2025-11-25 14:15:32.177810 | controller |   "atime": 1743545331.0429637,
2025-11-25 14:15:32.177829 | controller |   "ctime": 1743545842.0111232,
2025-11-25 14:15:32.177845 | controller |   "dev": 64513,
2025-11-25 14:15:32.177862 | controller |   "gid": 1000,
2025-11-25 14:15:32.177878 | controller |   "gr_name": "zuul",
2025-11-25 14:15:32.177894 | controller |   "inode": 4194437,
2025-11-25 14:15:32.177909 | controller |   "isblk": false,
2025-11-25 14:15:32.177923 | controller |   "ischr": false,
2025-11-25 14:15:32.177936 | controller |   "isdir": false,
2025-11-25 14:15:32.177950 | controller |   "isfifo": false,
2025-11-25 14:15:32.177963 | controller |   "isgid": false,
2025-11-25 14:15:32.177976 | controller |   "islnk": false,
2025-11-25 14:15:32.177989 | controller |   "isreg": true,
2025-11-25 14:15:32.178002 | controller |   "issock": false,
2025-11-25 14:15:32.178015 | controller |   "isuid": false,
2025-11-25 14:15:32.178028 | controller |   "mode": "0644",
2025-11-25 14:15:32.178041 | controller |   "mtime": 1743545842.0111232,
2025-11-25 14:15:32.178055 | controller |   "nlink": 1,
2025-11-25 14:15:32.178071 | controller |   "path": "/home/zuul/crc-start.log",
2025-11-25 14:15:32.178086 | controller |   "pw_name": "zuul",
2025-11-25 14:15:32.178100 | controller |   "rgrp": true,
2025-11-25 14:15:32.178114 | controller |   "roth": true,
2025-11-25 14:15:32.178128 | controller |   "rusr": true,
2025-11-25 14:15:32.178141 | controller |   "size": 4023,
2025-11-25 14:15:32.178154 | controller |   "uid": 1000,
2025-11-25 14:15:32.178168 | controller |   "wgrp": false,
2025-11-25 14:15:32.178180 | controller |   "woth": false,
2025-11-25 14:15:32.178193 | controller |   "wusr": true,
2025-11-25 14:15:32.178206 | controller |   "xgrp": false,
2025-11-25 14:15:32.178219 | controller |   "xoth": false,
2025-11-25 14:15:32.178231 | controller |   "xusr": false
2025-11-25 14:15:32.178245 | controller | }
2025-11-25 14:15:32.922969 | controller | changed:
2025-11-25 14:15:32.923064 | controller | {
2025-11-25 14:15:32.923090 | controller |   "atime": 1764079475.1949353,
2025-11-25 14:15:32.923109 | controller |   "ctime": 1764079491.735032,
2025-11-25 14:15:32.923125 | controller |   "dev": 64513,
2025-11-25 14:15:32.923140 | controller |   "gid": 1000,
2025-11-25 14:15:32.923155 | controller |   "gr_name": "zuul",
2025-11-25 14:15:32.923175 | controller |   "inode": 4686308,
2025-11-25 14:15:32.923195 | controller |   "isblk": false,
2025-11-25 14:15:32.923209 | controller |   "ischr": false,
2025-11-25 14:15:32.923223 | controller |   "isdir": false,
2025-11-25 14:15:32.923237 | controller |   "isfifo": false,
2025-11-25 14:15:32.923250 | controller |   "isgid": false,
2025-11-25 14:15:32.923263 | controller |   "islnk": false,
2025-11-25 14:15:32.923281 | controller |   "isreg": true,
2025-11-25 14:15:32.923293 | controller |   "issock": false,
2025-11-25 14:15:32.923307 | controller |   "isuid": false,
2025-11-25 14:15:32.923321 | controller |   "mode": "0644",
2025-11-25 14:15:32.923336 | controller |   "mtime": 1764079491.735032,
2025-11-25 14:15:32.923351 | controller |   "nlink": 1,
2025-11-25 14:15:32.923365 | controller |   "path": "/home/zuul/ansible.log",
2025-11-25 14:15:32.923403 | controller |   "pw_name": "zuul",
2025-11-25 14:15:32.923429 | controller |   "rgrp": true,
2025-11-25 14:15:32.923446 | controller |   "roth": true,
2025-11-25 14:15:32.923461 | controller |   "rusr": true,
2025-11-25 14:15:32.923478 | controller |   "size": 6749,
2025-11-25 14:15:32.923492 | controller |   "uid": 1000,
2025-11-25 14:15:32.923506 | controller |   "wgrp": false,
2025-11-25 14:15:32.923519 | controller |   "woth": false,
2025-11-25 14:15:32.923533 | controller |   "wusr": true,
2025-11-25 14:15:32.923548 | controller |   "xgrp": false,
2025-11-25 14:15:32.923563 | controller |   "xoth": false,
2025-11-25 14:15:32.923577 | controller |   "xusr": false
2025-11-25 14:15:32.923595 | controller | }
2025-11-25 14:15:32.938611 | 
2025-11-25 14:15:32.938714 | TASK [Copy crio stats log file]
2025-11-25 14:15:32.952081 | controller | skipping: Conditional result was False
2025-11-25 14:15:32.957288 | 
2025-11-25 14:15:32.957353 | TASK [Get SELinux related data]
2025-11-25 14:15:33.305333 | controller | <no matches>
2025-11-25 14:15:33.483417 | controller | ERROR
2025-11-25 14:15:33.483571 | controller | {
2025-11-25 14:15:33.483608 | controller |   "delta": "0:00:00.010638",
2025-11-25 14:15:33.483633 | controller |   "end": "2025-11-25 14:15:33.306424",
2025-11-25 14:15:33.483654 | controller |   "msg": "non-zero return code",
2025-11-25 14:15:33.483676 | controller |   "rc": 1,
2025-11-25 14:15:33.483698 | controller |   "start": "2025-11-25 14:15:33.295786"
2025-11-25 14:15:33.483718 | controller | }
2025-11-25 14:15:33.483744 | controller | ERROR: Ignoring Errors
2025-11-25 14:15:33.488461 | 
2025-11-25 14:15:33.488523 | TASK [Create system configuration directory]
2025-11-25 14:15:33.821643 | controller | changed
2025-11-25 14:15:33.826570 | 
2025-11-25 14:15:33.826639 | TASK [Get some of the system configurations]
2025-11-25 14:15:34.352703 | controller | changed
2025-11-25 14:15:34.357541 | 
2025-11-25 14:15:34.357603 | TASK [Copy generated documentation if available]
2025-11-25 14:15:34.370570 | controller | skipping: Conditional result was False
2025-11-25 14:15:34.375899 | 
2025-11-25 14:15:34.375964 | TASK [Copy generated AsciiDoc documentation if available]
2025-11-25 14:15:34.388934 | controller | skipping: Conditional result was False
2025-11-25 14:15:34.394235 | 
2025-11-25 14:15:34.394318 | TASK [Compress logs bigger than 2MB]
2025-11-25 14:15:34.920268 | controller | changed
2025-11-25 14:15:34.925121 | 
2025-11-25 14:15:34.925180 | TASK [Copy files from workspace on node]
2025-11-25 14:15:34.942873 | controller | ok
2025-11-25 14:15:34.964251 | 
2025-11-25 14:15:34.964320 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-25 14:15:34.976961 | controller | skipping: Conditional result was False
2025-11-25 14:15:34.982339 | 
2025-11-25 14:15:34.982431 | TASK [fetch-output : Set log path for single node]
2025-11-25 14:15:35.009756 | controller | ok
2025-11-25 14:15:35.014589 | 
2025-11-25 14:15:35.014653 | LOOP [fetch-output : Ensure local output dirs]
2025-11-25 14:15:35.198544 | controller -> localhost | ok: "/var/lib/zuul/builds/2ec4b0ff8ec74929a01ca0aae98f7787/work/logs"
2025-11-25 14:15:35.198774 | controller -> localhost | changed: All items complete
2025-11-25 14:15:35.198803 | 
2025-11-25 14:15:35.366512 | controller -> localhost | changed: "/var/lib/zuul/builds/2ec4b0ff8ec74929a01ca0aae98f7787/work/artifacts"
2025-11-25 14:15:35.535755 | controller -> localhost | changed: "/var/lib/zuul/builds/2ec4b0ff8ec74929a01ca0aae98f7787/work/docs"
2025-11-25 14:15:35.551465 | 
2025-11-25 14:15:35.551552 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-25 14:15:36.205094 | controller | changed:
2025-11-25 14:15:36.205265 | controller | .d..t...... ./
2025-11-25 14:15:36.205295 | controller | >f+++++++++ README.html
2025-11-25 14:15:36.205317 | controller | >f+++++++++ ansible-execution.log
2025-11-25 14:15:36.205336 | controller | >f+++++++++ ansible.log
2025-11-25 14:15:36.205353 | controller | >f+++++++++ crc-setup.log
2025-11-25 14:15:36.205369 | controller | >f+++++++++ crc-start.log
2025-11-25 14:15:36.205414 | controller | >f+++++++++ dmesg.log
2025-11-25 14:15:36.205436 | controller | >f+++++++++ installed-pkgs.log
2025-11-25 14:15:36.205454 | controller | >f+++++++++ python.log
2025-11-25 14:15:36.205470 | controller | >f+++++++++ registries.conf
2025-11-25 14:15:36.205486 | controller | >f+++++++++ report.html
2025-11-25 14:15:36.205501 | controller | >f+++++++++ selinux-denials.log
2025-11-25 14:15:36.205517 | controller | >f+++++++++ selinux-listing.log
2025-11-25 14:15:36.205531 | controller | cd+++++++++ ci-framework-data/
2025-11-25 14:15:36.205546 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-11-25 14:15:36.205561 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-11-25 14:15:36.205576 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-11-25 14:15:36.205591 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-11-25 14:15:36.205605 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-11-25 14:15:36.205620 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-11-25 14:15:36.205634 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-11-25 14:15:36.205649 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-11-25 14:15:36.205663 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-11-25 14:15:36.205678 | controller | cd+++++++++ ci-framework-data/logs/
2025-11-25 14:15:36.205694 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-11-25 14:15:36.205709 | controller | cd+++++++++ ci-framework-data/tests/
2025-11-25 14:15:36.205724 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/
2025-11-25 14:15:36.205739 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/ansible_command_logs/
2025-11-25 14:15:36.205754 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/ansible_command_logs/2025_11_25-14_14_52.clone_shiftstack_qa_repo.log
2025-11-25 14:15:36.205770 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/ansible_command_logs/2025_11_25-14_15_10.install_collections.log
2025-11-25 14:15:36.205785 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/ansible_command_logs/2025_11_25-14_15_11.cifmw-gate.yaml-find_test_config.log
2025-11-25 14:15:36.205799 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/ansible_command_logs/2025_11_25-14_15_16.cifmw-gate.yaml-find_test_config.log
2025-11-25 14:15:36.205814 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/artifacts/
2025-11-25 14:15:36.205829 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/artifacts/0-cifmw-gate/
2025-11-25 14:15:36.205843 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/artifacts/0-cifmw-gate/ansible_logs/
2025-11-25 14:15:36.205858 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/0-cifmw-gate/ansible_logs/ansible-log.txt
2025-11-25 14:15:36.205872 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/0-cifmw-gate/ansible_logs/ansible-navigator.log
2025-11-25 14:15:36.205886 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/0-cifmw-gate/ansible_logs/cifmw-gate-1764080114.767385.xml
2025-11-25 14:15:36.205904 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/0-cifmw-gate/ansible_logs/cifmw-gate-artifact-2025-11-25T14:15:14.946523+00:00.json
2025-11-25 14:15:36.205919 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/artifacts/0-cifmw-gate/installation/
2025-11-25 14:15:36.205933 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate/
2025-11-25 14:15:36.205946 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate/ansible_logs/
2025-11-25 14:15:36.205960 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate/ansible_logs/ansible-log.txt
2025-11-25 14:15:36.205976 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate/ansible_logs/ansible-navigator.log
2025-11-25 14:15:36.205992 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate/ansible_logs/cifmw-gate-1764080114.767385.xml
2025-11-25 14:15:36.206007 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate/ansible_logs/cifmw-gate-1764080119.0062366.xml
2025-11-25 14:15:36.206021 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate/ansible_logs/cifmw-gate-artifact-2025-11-25T14:15:14.946523+00:00.json
2025-11-25 14:15:36.206036 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate/ansible_logs/cifmw-gate-artifact-2025-11-25T14:15:19.175895+00:00.json
2025-11-25 14:15:36.206050 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate/installation/
2025-11-25 14:15:36.206065 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/manifests/
2025-11-25 14:15:36.206080 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/manifests/shiftstackclient-shiftstack_incluster_secret.yml
2025-11-25 14:15:36.206094 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/manifests/shiftstackclient-shiftstack_pod.yml
2025-11-25 14:15:36.206108 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/manifests/shiftstackclient-shiftstack_pvc.yml
2025-11-25 14:15:36.206122 | controller | cd+++++++++ registries.conf.d/
2025-11-25 14:15:36.206136 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-11-25 14:15:36.206151 | controller | cd+++++++++ system-config/
2025-11-25 14:15:36.206166 | controller | cd+++++++++ system-config/libvirt/
2025-11-25 14:15:36.206180 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-11-25 14:15:36.206196 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-11-25 14:15:36.206211 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-11-25 14:15:36.206226 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-11-25 14:15:36.206241 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-11-25 14:15:36.206255 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-11-25 14:15:36.206270 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-11-25 14:15:36.206284 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-11-25 14:15:36.206299 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-11-25 14:15:36.206313 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-11-25 14:15:36.206327 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-11-25 14:15:36.206341 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-11-25 14:15:36.206358 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-11-25 14:15:36.206373 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-11-25 14:15:36.206416 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-11-25 14:15:36.206436 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-11-25 14:15:36.736115 | controller | changed: .d..t...... ./
2025-11-25 14:15:37.199971 | controller | changed: .d..t...... ./
2025-11-25 14:15:37.213905 | 
2025-11-25 14:15:37.213990 | TASK [Return artifact to Zuul]
2025-11-25 14:15:37.242450 | controller | ok
2025-11-25 14:15:37.261462 | 
2025-11-25 14:15:37.261519 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-11-25 14:15:37.261610 | 
2025-11-25 14:15:37.261638 | PLAY RECAP
2025-11-25 14:15:37.261673 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-11-25 14:15:37.261694 | 
2025-11-25 14:15:37.336115 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-25 14:15:37.336784 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-25 14:15:37.832555 | 
2025-11-25 14:15:37.832651 | PLAY [all]
2025-11-25 14:15:37.850648 | 
2025-11-25 14:15:37.850723 | TASK [include_role : fetch-output]
2025-11-25 14:15:37.879262 | controller | ok
2025-11-25 14:15:37.895355 | 
2025-11-25 14:15:37.895469 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-25 14:15:37.929765 | controller | skipping: Conditional result was False
2025-11-25 14:15:37.935263 | 
2025-11-25 14:15:37.935335 | TASK [fetch-output : Set log path for single node]
2025-11-25 14:15:37.963262 | controller | ok
2025-11-25 14:15:37.968099 | 
2025-11-25 14:15:37.968166 | LOOP [fetch-output : Ensure local output dirs]
2025-11-25 14:15:38.273768 | controller -> localhost | ok: "/var/lib/zuul/builds/2ec4b0ff8ec74929a01ca0aae98f7787/work/logs"
2025-11-25 14:15:38.452962 | controller -> localhost | ok: "/var/lib/zuul/builds/2ec4b0ff8ec74929a01ca0aae98f7787/work/artifacts"
2025-11-25 14:15:38.626475 | controller -> localhost | ok: "/var/lib/zuul/builds/2ec4b0ff8ec74929a01ca0aae98f7787/work/docs"
2025-11-25 14:15:38.636896 | 
2025-11-25 14:15:38.636993 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-25 14:15:39.246731 | controller | ok
2025-11-25 14:15:39.246902 | controller | ok: All items complete
2025-11-25 14:15:39.246932 | 
2025-11-25 14:15:39.740471 | controller | ok
2025-11-25 14:15:40.202036 | controller | ok
2025-11-25 14:15:40.216316 | 
2025-11-25 14:15:40.216429 | TASK [include_role : fetch-output-openshift]
2025-11-25 14:15:40.229847 | controller | skipping: Conditional result was False
2025-11-25 14:15:40.235365 | 
2025-11-25 14:15:40.235456 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-25 14:15:40.566001 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.010441
2025-11-25 14:15:40.752092 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009302
2025-11-25 14:15:40.778839 | 
2025-11-25 14:15:40.778926 | PLAY [all]
2025-11-25 14:15:40.792465 | 
2025-11-25 14:15:40.792538 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-11-25 14:15:41.287285 | controller | changed
2025-11-25 14:15:41.308052 | 
2025-11-25 14:15:41.308102 | PLAY RECAP
2025-11-25 14:15:41.308141 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-25 14:15:41.308162 | 
2025-11-25 14:15:41.371856 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-25 14:15:41.372542 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-11-25 14:15:41.896592 | 
2025-11-25 14:15:41.896699 | PLAY [localhost]
2025-11-25 14:15:41.913563 | 
2025-11-25 14:15:41.913640 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-25 14:15:42.204496 | localhost | changed
2025-11-25 14:15:42.208941 | 
2025-11-25 14:15:42.209021 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-25 14:15:42.236334 | localhost | ok
2025-11-25 14:15:42.243906 | 
2025-11-25 14:15:42.243976 | TASK [add-fileserver : Create SSH private key tempfile]
2025-11-25 14:15:42.524896 | localhost | changed
2025-11-25 14:15:42.530350 | 
2025-11-25 14:15:42.530449 | TASK [add-fileserver : Create SSH private key from secret]
2025-11-25 14:15:43.074251 | localhost | changed
2025-11-25 14:15:43.078857 | 
2025-11-25 14:15:43.078923 | TASK [add-fileserver : Add fileserver ssh key]
2025-11-25 14:15:43.399695 | localhost | Identity added: /var/lib/zuul/builds/2ec4b0ff8ec74929a01ca0aae98f7787/work/tmp/ansible.cdrcnrbf (/var/lib/zuul/builds/2ec4b0ff8ec74929a01ca0aae98f7787/work/tmp/ansible.cdrcnrbf)
2025-11-25 14:15:43.399865 | localhost | ok: Runtime: 0:00:00.006198
2025-11-25 14:15:43.403990 | 
2025-11-25 14:15:43.404060 | TASK [add-fileserver : Remove SSH private key from disk]
2025-11-25 14:15:43.613206 | localhost | ok: Runtime: 0:00:00.003774
2025-11-25 14:15:43.617729 | 
2025-11-25 14:15:43.617796 | TASK [add-fileserver : Add fileserver to inventory]
2025-11-25 14:15:43.665193 | localhost | changed
2025-11-25 14:15:43.669976 | 
2025-11-25 14:15:43.670044 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-11-25 14:15:43.970630 | localhost | changed
2025-11-25 14:15:43.989451 | 
2025-11-25 14:15:43.989528 | PLAY [localhost]
2025-11-25 14:15:44.000503 | 
2025-11-25 14:15:44.000571 | TASK [Generate bulk log download script]
2025-11-25 14:15:44.017920 | localhost | ok
2025-11-25 14:15:44.028818 | 
2025-11-25 14:15:44.028887 | TASK [local-log-download : Check API endpoint is defined]
2025-11-25 14:15:44.055302 | localhost | ok: All assertions passed
2025-11-25 14:15:44.059600 | 
2025-11-25 14:15:44.059671 | TASK [local-log-download : Create download script]
2025-11-25 14:15:44.386119 | localhost -> localhost | changed
2025-11-25 14:15:44.394306 | 
2025-11-25 14:15:44.394371 | TASK [Register quick-download link]
2025-11-25 14:15:44.410972 | localhost | ok
2025-11-25 14:15:44.443040 | 
2025-11-25 14:15:44.443118 | PLAY [logserver.rdoproject.org]
2025-11-25 14:15:44.451951 | 
2025-11-25 14:15:44.452011 | TASK [Set zuul-log-path fact]
2025-11-25 14:15:44.467332 | logserver.rdoproject.org | ok
2025-11-25 14:15:44.476078 | 
2025-11-25 14:15:44.476141 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-25 14:15:44.502457 | logserver.rdoproject.org | ok
2025-11-25 14:15:44.507617 | 
2025-11-25 14:15:44.507684 | TASK [upload-logs : Create log directories]
2025-11-25 14:15:46.269009 | logserver.rdoproject.org | changed
2025-11-25 14:15:46.272146 | 
2025-11-25 14:15:46.272212 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-11-25 14:15:46.487737 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004790
2025-11-25 14:15:46.492575 | 
2025-11-25 14:15:46.492652 | TASK [upload-logs : Upload logs to log server]
2025-11-25 14:15:48.204584 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-11-25 14:15:48.207509 | 
2025-11-25 14:15:48.207580 | LOOP [upload-logs : Compress console log and json output]
2025-11-25 14:15:48.245161 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 14:15:48.253433 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 14:15:48.264881 | 
2025-11-25 14:15:48.264964 | LOOP [upload-logs : Upload compressed console log and json output]
2025-11-25 14:15:48.297877 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 14:15:48.298077 | 
2025-11-25 14:15:48.301256 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 14:15:48.311798 | 
2025-11-25 14:15:48.311880 | LOOP [upload-logs : Upload console log and json output]