2025-10-03 04:06:01.941025 | Job console starting...
2025-10-03 04:06:02.225159 | Updating repositories
2025-10-03 04:06:02.997393 | Preparing job workspace
2025-10-03 04:06:16.584687 | Running Ansible setup...
2025-10-03 04:06:22.002391 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-03 04:06:22.847983 | 
2025-10-03 04:06:22.848095 | PLAY [localhost]
2025-10-03 04:06:22.875178 | 
2025-10-03 04:06:22.875273 | TASK [Gathering Facts]
2025-10-03 04:06:24.518821 | localhost | ok
2025-10-03 04:06:24.544020 | 
2025-10-03 04:06:24.544168 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-03 04:06:25.283223 | localhost -> localhost | changed
2025-10-03 04:06:25.289743 | 
2025-10-03 04:06:25.289854 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-03 04:06:26.348827 | localhost -> localhost | changed
2025-10-03 04:06:26.358654 | 
2025-10-03 04:06:26.358739 | TASK [Setup log path fact]
2025-10-03 04:06:26.406023 | localhost | ok
2025-10-03 04:06:26.433093 | 
2025-10-03 04:06:26.433180 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-03 04:06:26.471148 | localhost | ok
2025-10-03 04:06:26.479288 | 
2025-10-03 04:06:26.479633 | TASK [emit-job-header : Print job information]
2025-10-03 04:06:26.531428 | # Job Information
2025-10-03 04:06:26.531557 | Ansible Version: 2.15.12
2025-10-03 04:06:26.531589 | Job: cifmw-molecule-hci_prepare
2025-10-03 04:06:26.531612 | Pipeline: github-check
2025-10-03 04:06:26.531633 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-03 04:06:26.531653 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3362
2025-10-03 04:06:26.531672 | Log URL (when completed): https://logserver.rdoproject.org/e8c/rdoproject.org/e8c22baad0e04cf1b8feb2842fa1b10d/
2025-10-03 04:06:26.531693 | Event ID: 3daecbd0-a00d-11f0-93f4-31ce34c57105
2025-10-03 04:06:26.535498 | 
2025-10-03 04:06:26.535569 | LOOP [emit-job-header : Print node information]
2025-10-03 04:06:26.725142 | localhost | ok:
2025-10-03 04:06:26.725506 | localhost | # Node Information
2025-10-03 04:06:26.725558 | localhost | Inventory Hostname: controller
2025-10-03 04:06:26.725587 | localhost | Hostname: np0005467778
2025-10-03 04:06:26.725611 | localhost | Username: zuul
2025-10-03 04:06:26.725633 | localhost | Distro: CentOS 9
2025-10-03 04:06:26.725653 | localhost | Provider: ibm-bm4-nodepool
2025-10-03 04:06:26.725739 | localhost | Region: regionOne
2025-10-03 04:06:26.725793 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-03 04:06:26.725816 | localhost | Product Name: OpenStack Compute
2025-10-03 04:06:26.725836 | localhost | Interface IP: 192.168.26.72
2025-10-03 04:06:26.790873 | 
2025-10-03 04:06:26.790981 | PLAY [all]
2025-10-03 04:06:26.802884 | 
2025-10-03 04:06:26.802983 | TASK [Gather network facts]
2025-10-03 04:06:27.187787 | controller | ok
2025-10-03 04:06:27.215357 | 
2025-10-03 04:06:27.215451 | TASK [include_role : start-zuul-console]
2025-10-03 04:06:27.249572 | controller | ok
2025-10-03 04:06:27.270813 | 
2025-10-03 04:06:27.270953 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-03 04:06:27.828099 | controller | ok
2025-10-03 04:06:27.856173 | 
2025-10-03 04:06:27.856258 | TASK [include_role : add-build-sshkey]
2025-10-03 04:06:27.946400 | controller | ok
2025-10-03 04:06:27.966470 | 
2025-10-03 04:06:27.966540 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-03 04:06:28.517608 | controller -> localhost | ok
2025-10-03 04:06:28.523179 | 
2025-10-03 04:06:28.523258 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-03 04:06:28.582255 | controller | ok
2025-10-03 04:06:28.613584 | controller | included: /var/lib/zuul/builds/e8c22baad0e04cf1b8feb2842fa1b10d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-03 04:06:28.632540 | 
2025-10-03 04:06:28.632615 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-03 04:06:29.745010 | controller -> localhost | Generating public/private rsa key pair.
2025-10-03 04:06:29.745183 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e8c22baad0e04cf1b8feb2842fa1b10d/work/e8c22baad0e04cf1b8feb2842fa1b10d_id_rsa.
2025-10-03 04:06:29.745220 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e8c22baad0e04cf1b8feb2842fa1b10d/work/e8c22baad0e04cf1b8feb2842fa1b10d_id_rsa.pub.
2025-10-03 04:06:29.745246 | controller -> localhost | The key fingerprint is:
2025-10-03 04:06:29.745268 | controller -> localhost | SHA256:tS7yJ1GlTFYI20ofm5LLHFKyxRJdFEtipNbFum1M93c zuul-build-sshkey
2025-10-03 04:06:29.745290 | controller -> localhost | The key's randomart image is:
2025-10-03 04:06:29.745310 | controller -> localhost | +---[RSA 3072]----+
2025-10-03 04:06:29.745329 | controller -> localhost | |      .o==*+.    |
2025-10-03 04:06:29.745349 | controller -> localhost | |       *.*=..    |
2025-10-03 04:06:29.745367 | controller -> localhost | |      = B==o     |
2025-10-03 04:06:29.745386 | controller -> localhost | |     . B.===.    |
2025-10-03 04:06:29.745406 | controller -> localhost | |      o S*=. .   |
2025-10-03 04:06:29.745425 | controller -> localhost | |       +o=+   . E|
2025-10-03 04:06:29.745444 | controller -> localhost | |      . =o.    ..|
2025-10-03 04:06:29.745463 | controller -> localhost | |       o...      |
2025-10-03 04:06:29.745481 | controller -> localhost | |        .o       |
2025-10-03 04:06:29.745499 | controller -> localhost | +----[SHA256]-----+
2025-10-03 04:06:29.745541 | controller -> localhost | ok: Runtime: 0:00:00.249479
2025-10-03 04:06:29.751294 | 
2025-10-03 04:06:29.751364 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-03 04:06:29.813967 | controller | ok
2025-10-03 04:06:29.841396 | controller | included: /var/lib/zuul/builds/e8c22baad0e04cf1b8feb2842fa1b10d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-03 04:06:29.875211 | 
2025-10-03 04:06:29.875296 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-03 04:06:29.925528 | controller | skipping: Conditional result was False
2025-10-03 04:06:29.930809 | 
2025-10-03 04:06:29.930879 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-03 04:06:30.635022 | controller | changed
2025-10-03 04:06:30.641419 | 
2025-10-03 04:06:30.641502 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-03 04:06:30.900581 | controller | ok
2025-10-03 04:06:30.909894 | 
2025-10-03 04:06:30.910012 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-03 04:06:31.719801 | controller | changed
2025-10-03 04:06:31.725139 | 
2025-10-03 04:06:31.725213 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-03 04:06:32.519844 | controller | changed
2025-10-03 04:06:32.525980 | 
2025-10-03 04:06:32.526067 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-03 04:06:32.593324 | controller | skipping: Conditional result was False
2025-10-03 04:06:32.599368 | 
2025-10-03 04:06:32.599457 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-03 04:06:33.367078 | controller -> localhost | changed
2025-10-03 04:06:33.379271 | 
2025-10-03 04:06:33.379369 | TASK [add-build-sshkey : Add back temp key]
2025-10-03 04:06:34.049399 | controller -> localhost | Identity added: /var/lib/zuul/builds/e8c22baad0e04cf1b8feb2842fa1b10d/work/e8c22baad0e04cf1b8feb2842fa1b10d_id_rsa (zuul-build-sshkey)
2025-10-03 04:06:34.049590 | controller -> localhost | ok: Runtime: 0:00:00.023317
2025-10-03 04:06:34.055570 | 
2025-10-03 04:06:34.055643 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-03 04:06:34.497420 | controller | ok
2025-10-03 04:06:34.502263 | 
2025-10-03 04:06:34.502342 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-03 04:06:34.551747 | controller | skipping: Conditional result was False
2025-10-03 04:06:34.592524 | 
2025-10-03 04:06:34.592616 | TASK [include_role : validate-host]
2025-10-03 04:06:34.637489 | controller | ok
2025-10-03 04:06:34.656688 | 
2025-10-03 04:06:34.656772 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-03 04:06:34.716701 | controller | ok
2025-10-03 04:06:34.723374 | 
2025-10-03 04:06:34.723448 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-03 04:06:35.353431 | controller -> localhost | ok
2025-10-03 04:06:35.359186 | 
2025-10-03 04:06:35.359277 | TASK [validate-host : Collect information about the host]
2025-10-03 04:06:36.025958 | controller | ok
2025-10-03 04:06:36.052046 | 
2025-10-03 04:06:36.052150 | TASK [validate-host : Sanitize hostname]
2025-10-03 04:06:36.153577 | controller | ok
2025-10-03 04:06:36.158902 | 
2025-10-03 04:06:36.158988 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-03 04:06:37.082734 | controller -> localhost | changed
2025-10-03 04:06:37.090481 | 
2025-10-03 04:06:37.090561 | TASK [validate-host : Collect information about zuul worker]
2025-10-03 04:06:37.676059 | controller | ok
2025-10-03 04:06:37.680679 | 
2025-10-03 04:06:37.680747 | TASK [validate-host : Write out all zuul information for each host]
2025-10-03 04:06:38.764051 | controller -> localhost | changed
2025-10-03 04:06:38.775454 | 
2025-10-03 04:06:38.775559 | TASK [include_role : prepare-workspace-openshift]
2025-10-03 04:06:38.825520 | controller | skipping: Conditional result was False
2025-10-03 04:06:38.831692 | 
2025-10-03 04:06:38.831798 | TASK [include_role : remove-zuul-sshkey]
2025-10-03 04:06:38.905322 | controller | skipping: Conditional result was False
2025-10-03 04:06:38.914822 | 
2025-10-03 04:06:38.914905 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-03 04:06:39.158975 | controller | ok: "logs"
2025-10-03 04:06:39.159353 | controller | ok: All items complete
2025-10-03 04:06:39.159396 | 
2025-10-03 04:06:39.342287 | controller | ok: "artifacts"
2025-10-03 04:06:39.512299 | controller | ok: "docs"
2025-10-03 04:06:39.520387 | 
2025-10-03 04:06:39.520496 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-03 04:06:39.829890 | controller | changed: "logs"
2025-10-03 04:06:40.019714 | controller | changed: "artifacts"
2025-10-03 04:06:40.194989 | controller | changed: "docs"
2025-10-03 04:06:40.220732 | 
2025-10-03 04:06:40.221207 | PLAY RECAP
2025-10-03 04:06:40.221273 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-03 04:06:40.221308 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-03 04:06:40.221332 | 
2025-10-03 04:06:40.327423 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-03 04:06:40.328171 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-03 04:06:41.023440 | 
2025-10-03 04:06:41.023549 | PLAY [all]
2025-10-03 04:06:41.067946 | 
2025-10-03 04:06:41.068057 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-03 04:06:41.179528 | controller | ok
2025-10-03 04:06:41.186718 | 
2025-10-03 04:06:41.186817 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-03 04:06:41.652954 | controller | changed
2025-10-03 04:06:41.662370 | 
2025-10-03 04:06:41.662479 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-03 04:06:42.750313 | controller | changed
2025-10-03 04:06:42.761855 | 
2025-10-03 04:06:42.761965 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-03 04:06:43.352571 | controller | changed:
2025-10-03 04:06:43.355940 | controller | {
2025-10-03 04:06:43.355995 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-03 04:06:43.356029 | controller | }
2025-10-03 04:06:43.597249 | controller | changed:
2025-10-03 04:06:43.599014 | controller | {
2025-10-03 04:06:43.599073 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-03 04:06:43.599101 | controller | }
2025-10-03 04:06:43.808277 | controller | changed:
2025-10-03 04:06:43.808351 | controller | {
2025-10-03 04:06:43.808379 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-03 04:06:43.808402 | controller | }
2025-10-03 04:06:44.033822 | controller | changed:
2025-10-03 04:06:44.033953 | controller | {
2025-10-03 04:06:44.034005 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-03 04:06:44.034031 | controller | }
2025-10-03 04:06:44.269729 | controller | changed:
2025-10-03 04:06:44.269920 | controller | {
2025-10-03 04:06:44.269963 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-03 04:06:44.269989 | controller | }
2025-10-03 04:06:44.510934 | controller | changed:
2025-10-03 04:06:44.511026 | controller | {
2025-10-03 04:06:44.511056 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-03 04:06:44.511078 | controller | }
2025-10-03 04:06:44.723263 | controller | changed:
2025-10-03 04:06:44.723355 | controller | {
2025-10-03 04:06:44.723384 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-03 04:06:44.723406 | controller | }
2025-10-03 04:06:44.943344 | controller | changed:
2025-10-03 04:06:44.943419 | controller | {
2025-10-03 04:06:44.943449 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-03 04:06:44.943473 | controller | }
2025-10-03 04:06:45.190146 | controller | changed:
2025-10-03 04:06:45.190265 | controller | {
2025-10-03 04:06:45.190297 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-03 04:06:45.190333 | controller | }
2025-10-03 04:06:45.414226 | controller | changed:
2025-10-03 04:06:45.414324 | controller | {
2025-10-03 04:06:45.414359 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-03 04:06:45.414383 | controller | }
2025-10-03 04:06:45.680699 | controller | changed:
2025-10-03 04:06:45.680790 | controller | {
2025-10-03 04:06:45.680819 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-03 04:06:45.680840 | controller | }
2025-10-03 04:06:45.851586 | controller | changed:
2025-10-03 04:06:45.853643 | controller | {
2025-10-03 04:06:45.853694 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-03 04:06:45.853722 | controller | }
2025-10-03 04:06:46.084024 | controller | changed:
2025-10-03 04:06:46.084104 | controller | {
2025-10-03 04:06:46.084133 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-03 04:06:46.084155 | controller | }
2025-10-03 04:06:46.301248 | controller | changed:
2025-10-03 04:06:46.301450 | controller | {
2025-10-03 04:06:46.301491 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-03 04:06:46.301514 | controller | }
2025-10-03 04:06:46.521899 | controller | changed:
2025-10-03 04:06:46.522011 | controller | {
2025-10-03 04:06:46.522040 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-03 04:06:46.522062 | controller | }
2025-10-03 04:06:46.747183 | controller | changed:
2025-10-03 04:06:46.747271 | controller | {
2025-10-03 04:06:46.747299 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-03 04:06:46.747319 | controller | }
2025-10-03 04:06:46.992698 | controller | changed:
2025-10-03 04:06:46.992799 | controller | {
2025-10-03 04:06:46.992833 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-03 04:06:46.992857 | controller | }
2025-10-03 04:06:47.200265 | controller | changed:
2025-10-03 04:06:47.200357 | controller | {
2025-10-03 04:06:47.200394 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-03 04:06:47.200513 | controller | }
2025-10-03 04:06:47.430970 | controller | changed:
2025-10-03 04:06:47.431102 | controller | {
2025-10-03 04:06:47.431141 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-03 04:06:47.431164 | controller | }
2025-10-03 04:06:47.649675 | controller | changed:
2025-10-03 04:06:47.649772 | controller | {
2025-10-03 04:06:47.649801 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-03 04:06:47.649823 | controller | }
2025-10-03 04:06:47.876877 | controller | changed:
2025-10-03 04:06:47.877111 | controller | {
2025-10-03 04:06:47.877146 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-03 04:06:47.877170 | controller | }
2025-10-03 04:06:48.092680 | controller | changed:
2025-10-03 04:06:48.092770 | controller | {
2025-10-03 04:06:48.092798 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-03 04:06:48.092821 | controller | }
2025-10-03 04:06:48.341363 | controller | changed:
2025-10-03 04:06:48.341455 | controller | {
2025-10-03 04:06:48.341483 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-03 04:06:48.341510 | controller | }
2025-10-03 04:06:48.556873 | controller | changed:
2025-10-03 04:06:48.556983 | controller | {
2025-10-03 04:06:48.557013 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-03 04:06:48.557035 | controller | }
2025-10-03 04:06:48.784502 | controller | changed:
2025-10-03 04:06:48.784595 | controller | {
2025-10-03 04:06:48.784631 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-03 04:06:48.784659 | controller | }
2025-10-03 04:06:49.026876 | controller | changed:
2025-10-03 04:06:49.026989 | controller | {
2025-10-03 04:06:49.027019 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-03 04:06:49.027041 | controller | }
2025-10-03 04:06:49.056980 | 
2025-10-03 04:06:49.057064 | TASK [Set timezone to UTC]
2025-10-03 04:06:49.591390 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-03 04:06:49.596453 | 
2025-10-03 04:06:49.596547 | TASK [Create nodepool directory]
2025-10-03 04:06:49.858089 | controller | changed
2025-10-03 04:06:49.866563 | 
2025-10-03 04:06:49.866656 | TASK [Create nodepool sub_nodes file]
2025-10-03 04:06:50.540293 | controller | changed
2025-10-03 04:06:50.549175 | 
2025-10-03 04:06:50.549263 | TASK [Create nodepool sub_nodes_private file]
2025-10-03 04:06:51.241068 | controller | changed
2025-10-03 04:06:51.246780 | 
2025-10-03 04:06:51.246866 | LOOP [Populate nodepool sub_nodes file]
2025-10-03 04:06:51.298840 | 
2025-10-03 04:06:51.299330 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-03 04:06:51.339685 | 
2025-10-03 04:06:51.339841 | TASK [Create nodepool primary file]
2025-10-03 04:06:51.368570 | controller | skipping: Conditional result was False
2025-10-03 04:06:51.375184 | 
2025-10-03 04:06:51.375278 | TASK [Create nodepool node_private for this node]
2025-10-03 04:06:51.996833 | controller | changed
2025-10-03 04:06:52.038085 | 
2025-10-03 04:06:52.038179 | LOOP [Copy ssh keys to nodepool directory]
2025-10-03 04:06:52.588643 | controller | ok: Item: id_rsa Runtime: 0:00:00.006602
2025-10-03 04:06:52.588837 | 
2025-10-03 04:06:52.756775 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005332
2025-10-03 04:06:52.773218 | 
2025-10-03 04:06:52.773320 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-03 04:06:53.438173 | controller | changed
2025-10-03 04:06:53.447116 | 
2025-10-03 04:06:53.447204 | TASK [Validate sudoers config after edits]
2025-10-03 04:06:53.728433 | controller | /etc/sudoers: parsed OK
2025-10-03 04:06:53.728483 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-03 04:06:53.728492 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-03 04:06:53.728499 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-03 04:06:54.012252 | controller | ok: Runtime: 0:00:00.005215
2025-10-03 04:06:54.018176 | 
2025-10-03 04:06:54.018244 | TASK [Show the environment passed in to job shell scripts]
2025-10-03 04:06:54.378929 | controller | SHELL=/bin/bash
2025-10-03 04:06:54.378998 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-03 04:06:54.379010 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-03 04:06:54.379017 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/62/3362/41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-03 04:06:54.379022 | controller | PWD=/home/zuul
2025-10-03 04:06:54.379039 | controller | ZUUL_PIPELINE=github-check
2025-10-03 04:06:54.379081 | controller | LOGNAME=zuul
2025-10-03 04:06:54.379088 | controller | XDG_SESSION_TYPE=tty
2025-10-03 04:06:54.379094 | controller | _=/usr/bin/env
2025-10-03 04:06:54.379099 | controller | MOTD_SHOWN=pam
2025-10-03 04:06:54.379105 | controller | HOME=/home/zuul
2025-10-03 04:06:54.379110 | controller | LANG=en_US.UTF-8
2025-10-03 04:06:54.379116 | controller | SSH_CONNECTION=192.168.26.12 51140 192.168.26.72 22
2025-10-03 04:06:54.379121 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-03 04:06:54.379130 | controller | ZUUL_CHANGE_IDS=3362,41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-03 04:06:54.379136 | controller | WORKSPACE=/home/zuul/workspace
2025-10-03 04:06:54.379141 | controller | XDG_SESSION_CLASS=user
2025-10-03 04:06:54.379147 | controller | SELINUX_ROLE_REQUESTED=
2025-10-03 04:06:54.379152 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-03 04:06:54.379157 | controller | USER=zuul
2025-10-03 04:06:54.379163 | controller | ZUUL_VOTING=True
2025-10-03 04:06:54.379168 | controller | BUILD_TIMEOUT=1800000
2025-10-03 04:06:54.379173 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-03 04:06:54.379178 | controller | SHLVL=1
2025-10-03 04:06:54.379184 | controller | ZUUL_PATCHSET=41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-03 04:06:54.379189 | controller | XDG_SESSION_ID=1
2025-10-03 04:06:54.379195 | controller | ZUUL_BRANCH=main
2025-10-03 04:06:54.379200 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-03 04:06:54.379205 | controller | SSH_CLIENT=192.168.26.12 51140 22
2025-10-03 04:06:54.379212 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-03 04:06:54.379218 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-03 04:06:54.379223 | controller | which_declare=declare -f
2025-10-03 04:06:54.379229 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-03 04:06:54.379235 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-03 04:06:54.379240 | controller | ZUUL_CHANGE=3362
2025-10-03 04:06:54.379246 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-03 04:06:54.379251 | controller | ZUUL_UUID=e8c22baad0e04cf1b8feb2842fa1b10d
2025-10-03 04:06:54.379257 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-03 04:06:54.379262 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-03 04:06:54.379267 | controller | }
2025-10-03 04:06:54.594233 | controller | ok: Runtime: 0:00:00.006171
2025-10-03 04:06:54.599589 | 
2025-10-03 04:06:54.599656 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-03 04:06:54.613693 | controller | skipping: Conditional result was False
2025-10-03 04:06:54.620558 | 
2025-10-03 04:06:54.620666 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-03 04:06:55.193370 | controller | skipping: Conditional result was False
2025-10-03 04:06:55.199217 | 
2025-10-03 04:06:55.199283 | TASK [Ensure legacy workspace directory]
2025-10-03 04:06:55.453003 | controller | changed
2025-10-03 04:06:55.542375 | 
2025-10-03 04:06:55.542456 | PLAY RECAP
2025-10-03 04:06:55.542499 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-03 04:06:55.542522 | 
2025-10-03 04:06:55.691293 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-03 04:06:55.692563 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-03 04:06:56.615377 | 
2025-10-03 04:06:56.615479 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-03 04:06:56.661923 | 
2025-10-03 04:06:56.662022 | TASK [Create zuul-output directory]
2025-10-03 04:06:57.179469 | controller | changed
2025-10-03 04:06:57.188406 | 
2025-10-03 04:06:57.188508 | TASK [Slurp Zuul inventory test]
2025-10-03 04:06:58.228600 | controller -> localhost | ok
2025-10-03 04:06:58.234840 | 
2025-10-03 04:06:58.234939 | TASK [Save zuul inventory]
2025-10-03 04:06:59.629992 | controller | changed
2025-10-03 04:06:59.638059 | 
2025-10-03 04:06:59.638145 | TASK [Save zuul vars without the change_message]
2025-10-03 04:07:00.529825 | controller | changed
2025-10-03 04:07:00.604458 | 
2025-10-03 04:07:00.604538 | PLAY RECAP
2025-10-03 04:07:00.604584 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-03 04:07:00.604608 | 
2025-10-03 04:07:00.869887 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-03 04:07:00.870652 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-03 04:07:02.077271 | 
2025-10-03 04:07:02.077372 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-03 04:07:02.165432 | 
2025-10-03 04:07:02.165552 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-03 04:07:02.216202 | controller | ok
2025-10-03 04:07:02.275034 | 
2025-10-03 04:07:02.275160 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-03 04:07:02.355460 | controller | skipping: Conditional result was False
2025-10-03 04:07:02.362267 | 
2025-10-03 04:07:02.362363 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-03 04:07:02.928075 | controller | ok
2025-10-03 04:07:02.933761 | 
2025-10-03 04:07:02.933853 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-03 04:07:03.990273 | controller | ok
2025-10-03 04:07:04.011679 | 
2025-10-03 04:07:04.011770 | TASK [Prepare workspace]
2025-10-03 04:07:04.096736 | controller | ok
2025-10-03 04:07:04.169384 | 
2025-10-03 04:07:04.169479 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-03 04:07:04.693317 | controller | ok
2025-10-03 04:07:04.706839 | 
2025-10-03 04:07:04.707093 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-03 04:07:10.620003 | controller | Output suppressed because no_log was given
2025-10-03 04:07:10.639516 | 
2025-10-03 04:07:10.639594 | LOOP [Create zuul-output directory]
2025-10-03 04:07:10.927719 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-03 04:07:11.117138 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-03 04:07:11.128376 | 
2025-10-03 04:07:11.128473 | TASK [Install required packages]
2025-10-03 04:08:04.606878 | controller | changed
2025-10-03 04:08:04.613604 | 
2025-10-03 04:08:04.613679 | TASK [Install venv]
2025-10-03 04:09:07.399676 | controller | changed
2025-10-03 04:09:07.427800 | 
2025-10-03 04:09:07.427869 | PLAY RECAP
2025-10-03 04:09:07.427932 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-03 04:09:07.427962 | 
2025-10-03 04:09:07.536170 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-03 04:09:07.537423 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-03 04:09:08.134927 | 
2025-10-03 04:09:08.135040 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-03 04:09:08.157704 | 
2025-10-03 04:09:08.157825 | TASK [Gather required facts]
2025-10-03 04:09:08.673750 | controller | ok
2025-10-03 04:09:08.679286 | 
2025-10-03 04:09:08.679369 | TASK [Load environment var if instructed to]
2025-10-03 04:09:08.703046 | controller | skipping: Conditional result was False
2025-10-03 04:09:08.708532 | 
2025-10-03 04:09:08.708605 | TASK [Run molecule]
2025-10-03 04:09:09.666259 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-03 04:09:09.735768 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-03 04:09:26.853821 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:09:26.854312 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:09:26.854767 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:09:26.855222 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:09:26.855700 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+41f2edaa was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 1.0.0+41f2edaa (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:09:26.856153 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:09:26.856601 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:09:26.857043 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:09:26.857496 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:09:26.857921 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:09:26.858381 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:09:26.858819 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:09:26.859298 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:09:26.859738 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:09:26.860201 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/9f00e3/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:09:26.869452 | controller | INFO     Running default > prepare
2025-10-03 04:09:27.525250 | controller |
2025-10-03 04:09:27.525357 | controller | PLAY [Prepare] *****************************************************************
2025-10-03 04:09:27.525454 | controller |
2025-10-03 04:09:27.525551 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:09:27.525651 | controller | Friday 03 October 2025  04:09:27 +0000 (0:00:00.012)       0:00:00.012 ********
2025-10-03 04:09:28.339447 | controller | ok: [instance]
2025-10-03 04:09:28.339546 | controller |
2025-10-03 04:09:28.339677 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-03 04:09:28.339805 | controller | Friday 03 October 2025  04:09:28 +0000 (0:00:00.814)       0:00:00.827 ********
2025-10-03 04:09:28.355582 | controller | skipping: [instance]
2025-10-03 04:09:28.355669 | controller |
2025-10-03 04:09:28.355773 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-03 04:09:28.355874 | controller | Friday 03 October 2025  04:09:28 +0000 (0:00:00.016)       0:00:00.844 ********
2025-10-03 04:09:28.393849 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-03 04:09:28.393937 | controller |
2025-10-03 04:09:28.394066 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-03 04:09:28.394168 | controller | Friday 03 October 2025  04:09:28 +0000 (0:00:00.038)       0:00:00.882 ********
2025-10-03 04:09:28.668467 | controller | ok: [instance]
2025-10-03 04:09:28.668537 | controller |
2025-10-03 04:09:28.668650 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-03 04:09:28.668756 | controller | Friday 03 October 2025  04:09:28 +0000 (0:00:00.274)       0:00:01.156 ********
2025-10-03 04:09:28.982111 | controller | ok: [instance]
2025-10-03 04:09:28.982160 | controller |
2025-10-03 04:09:28.982273 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-03 04:09:28.982378 | controller | Friday 03 October 2025  04:09:28 +0000 (0:00:00.313)       0:00:01.470 ********
2025-10-03 04:09:29.662585 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-03 04:09:29.662679 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-03 04:09:29.662791 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-03 04:09:29.662890 | controller |
2025-10-03 04:09:29.662993 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-03 04:09:29.663121 | controller | Friday 03 October 2025  04:09:29 +0000 (0:00:00.680)       0:00:02.150 ********
2025-10-03 04:09:29.720005 | controller |
2025-10-03 04:09:29.720156 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-03 04:09:29.720302 | controller | Friday 03 October 2025  04:09:29 +0000 (0:00:00.057)       0:00:02.208 ********
2025-10-03 04:09:30.247616 | controller | changed: [instance] => (item=tmp)
2025-10-03 04:09:30.247708 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-03 04:09:30.247822 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-03 04:09:30.247923 | controller |
2025-10-03 04:09:30.248046 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-03 04:09:30.248165 | controller | Friday 03 October 2025  04:09:30 +0000 (0:00:00.527)       0:00:02.735 ********
2025-10-03 04:09:31.041806 | controller | ok: [instance]
2025-10-03 04:09:31.041881 | controller |
2025-10-03 04:09:31.042120 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-03 04:09:31.042155 | controller | Friday 03 October 2025  04:09:31 +0000 (0:00:00.794)       0:00:03.530 ********
2025-10-03 04:09:32.260868 | controller | changed: [instance]
2025-10-03 04:09:40.194568 | controller |
2025-10-03 04:09:40.194606 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-03 04:09:40.194617 | controller | Friday 03 October 2025  04:09:32 +0000 (0:00:01.218)       0:00:04.748 ********
2025-10-03 04:09:40.194627 | controller | changed: [instance]
2025-10-03 04:09:40.917399 | controller |
2025-10-03 04:09:40.917435 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-03 04:09:40.917444 | controller | Friday 03 October 2025  04:09:40 +0000 (0:00:07.933)       0:00:12.682 ********
2025-10-03 04:09:40.917454 | controller | changed: [instance]
2025-10-03 04:09:40.936600 | controller |
2025-10-03 04:09:40.936624 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-03 04:09:40.936632 | controller | Friday 03 October 2025  04:09:40 +0000 (0:00:00.722)       0:00:13.405 ********
2025-10-03 04:09:40.936642 | controller | skipping: [instance]
2025-10-03 04:09:41.756671 | controller |
2025-10-03 04:09:41.756702 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-03 04:09:41.756711 | controller | Friday 03 October 2025  04:09:40 +0000 (0:00:00.019)       0:00:13.424 ********
2025-10-03 04:09:41.756721 | controller | changed: [instance]
2025-10-03 04:09:41.792742 | controller |
2025-10-03 04:09:41.792774 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-03 04:09:41.792782 | controller | Friday 03 October 2025  04:09:41 +0000 (0:00:00.819)       0:00:14.244 ********
2025-10-03 04:09:41.792792 | controller | skipping: [instance]
2025-10-03 04:09:41.829071 | controller |
2025-10-03 04:09:41.829102 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-03 04:09:41.829110 | controller | Friday 03 October 2025  04:09:41 +0000 (0:00:00.036)       0:00:14.280 ********
2025-10-03 04:09:41.829120 | controller | skipping: [instance]
2025-10-03 04:09:41.864814 | controller |
2025-10-03 04:09:41.864839 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-03 04:09:41.864847 | controller | Friday 03 October 2025  04:09:41 +0000 (0:00:00.035)       0:00:14.316 ********
2025-10-03 04:09:41.864856 | controller | skipping: [instance]
2025-10-03 04:09:42.425586 | controller |
2025-10-03 04:09:42.425615 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-03 04:09:42.425623 | controller | Friday 03 October 2025  04:09:41 +0000 (0:00:00.036)       0:00:14.352 ********
2025-10-03 04:09:42.425633 | controller | changed: [instance]
2025-10-03 04:09:42.863560 | controller |
2025-10-03 04:09:42.863591 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-03 04:09:42.863599 | controller | Friday 03 October 2025  04:09:42 +0000 (0:00:00.560)       0:00:14.913 ********
2025-10-03 04:09:42.863609 | controller | changed: [instance]
2025-10-03 04:09:42.891541 | controller |
2025-10-03 04:09:42.891560 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-03 04:09:42.891567 | controller | Friday 03 October 2025  04:09:42 +0000 (0:00:00.438)       0:00:15.351 ********
2025-10-03 04:09:42.891576 | controller | skipping: [instance]
2025-10-03 04:09:42.919490 | controller |
2025-10-03 04:09:42.919506 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-03 04:09:42.919513 | controller | Friday 03 October 2025  04:09:42 +0000 (0:00:00.028)       0:00:15.379 ********
2025-10-03 04:09:42.919525 | controller | skipping: [instance]
2025-10-03 04:09:42.948115 | controller |
2025-10-03 04:09:42.948134 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-03 04:09:42.948143 | controller | Friday 03 October 2025  04:09:42 +0000 (0:00:00.028)       0:00:15.407 ********
2025-10-03 04:09:42.948153 | controller | skipping: [instance]
2025-10-03 04:09:42.985583 | controller |
2025-10-03 04:09:42.985609 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-03 04:09:42.985616 | controller | Friday 03 October 2025  04:09:42 +0000 (0:00:00.028)       0:00:15.436 ********
2025-10-03 04:09:42.985626 | controller | ok: [instance]
2025-10-03 04:09:43.012107 | controller |
2025-10-03 04:09:43.012139 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-03 04:09:43.012147 | controller | Friday 03 October 2025  04:09:42 +0000 (0:00:00.037)       0:00:15.473 ********
2025-10-03 04:09:43.012158 | controller | skipping: [instance]
2025-10-03 04:09:43.012218 | controller |
2025-10-03 04:09:43.012237 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-03 04:09:43.012248 | controller | Friday 03 October 2025  04:09:43 +0000 (0:00:00.026)       0:00:15.500 ********
2025-10-03 04:09:43.040784 | controller | skipping: [instance]
2025-10-03 04:09:43.067868 | controller |
2025-10-03 04:09:43.067895 | controller | TASK [Download the RPM] ********************************************************
2025-10-03 04:09:43.067902 | controller | Friday 03 October 2025  04:09:43 +0000 (0:00:00.028)       0:00:15.528 ********
2025-10-03 04:09:43.067912 | controller | skipping: [instance]
2025-10-03 04:09:43.094758 | controller |
2025-10-03 04:09:43.094780 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-03 04:09:43.094788 | controller | Friday 03 October 2025  04:09:43 +0000 (0:00:00.027)       0:00:15.555 ********
2025-10-03 04:09:43.094797 | controller | skipping: [instance]
2025-10-03 04:09:43.121103 | controller |
2025-10-03 04:09:43.121123 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-03 04:09:43.121130 | controller | Friday 03 October 2025  04:09:43 +0000 (0:00:00.027)       0:00:15.582 ********
2025-10-03 04:09:43.121140 | controller | skipping: [instance]
2025-10-03 04:09:43.150127 | controller |
2025-10-03 04:09:43.150147 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-03 04:09:43.150155 | controller | Friday 03 October 2025  04:09:43 +0000 (0:00:00.026)       0:00:15.609 ********
2025-10-03 04:09:43.150164 | controller | skipping: [instance]
2025-10-03 04:09:43.175788 | controller |
2025-10-03 04:09:43.175807 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-03 04:09:43.175815 | controller | Friday 03 October 2025  04:09:43 +0000 (0:00:00.028)       0:00:15.638 ********
2025-10-03 04:09:43.175824 | controller | skipping: [instance]
2025-10-03 04:09:43.356565 | controller |
2025-10-03 04:09:43.356595 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-03 04:09:43.356603 | controller | Friday 03 October 2025  04:09:43 +0000 (0:00:00.025)       0:00:15.664 ********
2025-10-03 04:09:43.356613 | controller | ok: [instance]
2025-10-03 04:09:43.595350 | controller |
2025-10-03 04:09:43.595379 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-03 04:09:43.595387 | controller | Friday 03 October 2025  04:09:43 +0000 (0:00:00.180)       0:00:15.844 ********
2025-10-03 04:09:43.595398 | controller | changed: [instance]
2025-10-03 04:09:43.822889 | controller |
2025-10-03 04:09:43.822923 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-03 04:09:43.822933 | controller | Friday 03 October 2025  04:09:43 +0000 (0:00:00.238)       0:00:16.083 ********
2025-10-03 04:09:43.822945 | controller | changed: [instance]
2025-10-03 04:09:43.844081 | controller |
2025-10-03 04:09:43.844117 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-03 04:09:43.844125 | controller | Friday 03 October 2025  04:09:43 +0000 (0:00:00.227)       0:00:16.310 ********
2025-10-03 04:09:43.844136 | controller | skipping: [instance]
2025-10-03 04:09:43.866473 | controller |
2025-10-03 04:09:43.866511 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-03 04:09:43.866520 | controller | Friday 03 October 2025  04:09:43 +0000 (0:00:00.021)       0:00:16.332 ********
2025-10-03 04:09:43.866531 | controller | skipping: [instance]
2025-10-03 04:09:43.888958 | controller |
2025-10-03 04:09:43.888991 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-03 04:09:43.889000 | controller | Friday 03 October 2025  04:09:43 +0000 (0:00:00.022)       0:00:16.354 ********
2025-10-03 04:09:43.889010 | controller | skipping: [instance]
2025-10-03 04:09:43.911794 | controller |
2025-10-03 04:09:43.911830 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-03 04:09:43.911839 | controller | Friday 03 October 2025  04:09:43 +0000 (0:00:00.022)       0:00:16.376 ********
2025-10-03 04:09:43.911850 | controller | skipping: [instance]
2025-10-03 04:09:43.935637 | controller |
2025-10-03 04:09:43.935672 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-03 04:09:43.935680 | controller | Friday 03 October 2025  04:09:43 +0000 (0:00:00.022)       0:00:16.400 ********
2025-10-03 04:09:43.935696 | controller | skipping: [instance]
2025-10-03 04:09:43.957792 | controller |
2025-10-03 04:09:43.957819 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-03 04:09:43.957833 | controller | Friday 03 October 2025  04:09:43 +0000 (0:00:00.023)       0:00:16.423 ********
2025-10-03 04:09:43.957844 | controller | skipping: [instance]
2025-10-03 04:09:43.974123 | controller |
2025-10-03 04:09:43.974150 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-03 04:09:43.974158 | controller | Friday 03 October 2025  04:09:43 +0000 (0:00:00.022)       0:00:16.445 ********
2025-10-03 04:09:43.974169 | controller | skipping: [instance]
2025-10-03 04:09:43.974400 | controller |
2025-10-03 04:09:43.974411 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-03 04:09:44.003969 | controller | Friday 03 October 2025  04:09:43 +0000 (0:00:00.016)       0:00:16.462 ********
2025-10-03 04:09:44.004015 | controller | skipping: [instance]
2025-10-03 04:09:44.021079 | controller |
2025-10-03 04:09:44.021104 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-03 04:09:44.021111 | controller | Friday 03 October 2025  04:09:44 +0000 (0:00:00.029)       0:00:16.492 ********
2025-10-03 04:09:44.021121 | controller | skipping: [instance]
2025-10-03 04:09:44.042965 | controller |
2025-10-03 04:09:44.042993 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-03 04:09:44.043002 | controller | Friday 03 October 2025  04:09:44 +0000 (0:00:00.017)       0:00:16.509 ********
2025-10-03 04:09:44.043014 | controller | skipping: [instance]
2025-10-03 04:09:44.072178 | controller |
2025-10-03 04:09:44.072202 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-03 04:09:44.072211 | controller | Friday 03 October 2025  04:09:44 +0000 (0:00:00.021)       0:00:16.531 ********
2025-10-03 04:09:44.072222 | controller | skipping: [instance]
2025-10-03 04:09:44.072251 | controller |
2025-10-03 04:09:44.072448 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-03 04:09:44.101618 | controller | Friday 03 October 2025  04:09:44 +0000 (0:00:00.029)       0:00:16.560 ********
2025-10-03 04:09:44.101648 | controller | skipping: [instance]
2025-10-03 04:09:44.131208 | controller |
2025-10-03 04:09:44.131240 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-03 04:09:44.131249 | controller | Friday 03 October 2025  04:09:44 +0000 (0:00:00.029)       0:00:16.589 ********
2025-10-03 04:09:44.131261 | controller | skipping: [instance]
2025-10-03 04:09:44.160162 | controller |
2025-10-03 04:09:44.160196 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-03 04:09:44.160205 | controller | Friday 03 October 2025  04:09:44 +0000 (0:00:00.029)       0:00:16.619 ********
2025-10-03 04:09:44.160218 | controller | skipping: [instance]
2025-10-03 04:10:22.313282 | controller |
2025-10-03 04:10:22.313327 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-03 04:10:22.313336 | controller | Friday 03 October 2025  04:09:44 +0000 (0:00:00.028)       0:00:16.648 ********
2025-10-03 04:10:22.313347 | controller | ok: [instance]
2025-10-03 04:10:23.490077 | controller |
2025-10-03 04:10:23.490117 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-03 04:10:23.490126 | controller | Friday 03 October 2025  04:10:22 +0000 (0:00:38.153)       0:00:54.801 ********
2025-10-03 04:10:23.490137 | controller | ok: [instance]
2025-10-03 04:10:23.547144 | controller |
2025-10-03 04:10:23.547183 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-03 04:10:23.547193 | controller | Friday 03 October 2025  04:10:23 +0000 (0:00:01.176)       0:00:55.977 ********
2025-10-03 04:10:23.547206 | controller | skipping: [instance]
2025-10-03 04:10:23.547234 | controller |
2025-10-03 04:10:23.547242 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:10:23.547249 | controller | instance                   : ok=19   changed=9    unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-10-03 04:10:23.547256 | controller |
2025-10-03 04:10:23.547262 | controller | Friday 03 October 2025  04:10:23 +0000 (0:00:00.055)       0:00:56.033 ********
2025-10-03 04:10:23.547269 | controller | ===============================================================================
2025-10-03 04:10:23.547276 | controller | test_deps : Install selinux python libs -------------------------------- 38.15s
2025-10-03 04:10:23.547293 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.93s
2025-10-03 04:10:23.547300 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.22s
2025-10-03 04:10:23.547307 | controller | test_deps : Install python yaml libs ------------------------------------ 1.18s
2025-10-03 04:10:23.547313 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.82s
2025-10-03 04:10:23.547327 | controller | Gathering Facts --------------------------------------------------------- 0.81s
2025-10-03 04:10:23.547333 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.79s
2025-10-03 04:10:23.547340 | controller | repo_setup : Install repo-setup package --------------------------------- 0.72s
2025-10-03 04:10:23.547346 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.68s
2025-10-03 04:10:23.547353 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.56s
2025-10-03 04:10:23.547360 | controller | repo_setup : Ensure directories are present ----------------------------- 0.53s
2025-10-03 04:10:23.547366 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.44s
2025-10-03 04:10:23.547373 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.31s
2025-10-03 04:10:23.547395 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.27s
2025-10-03 04:10:23.547423 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.24s
2025-10-03 04:10:23.547845 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.23s
2025-10-03 04:10:23.547880 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.18s
2025-10-03 04:10:23.610510 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-10-03 04:10:23.610543 | controller | test_deps : Install extra packages -------------------------------------- 0.06s
2025-10-03 04:10:23.610551 | controller | test_deps : Gather variables for each operating system ------------------ 0.04s
2025-10-03 04:10:23.610562 | controller | INFO     Running default > converge
2025-10-03 04:10:24.046493 | controller |
2025-10-03 04:10:24.046709 | controller | PLAY [Converge] ****************************************************************
2025-10-03 04:10:24.046845 | controller |
2025-10-03 04:10:24.046983 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:10:24.047146 | controller | Friday 03 October 2025  04:10:24 +0000 (0:00:00.013)       0:00:00.013 ********
2025-10-03 04:10:24.829562 | controller | ok: [instance]
2025-10-03 04:10:24.846063 | controller |
2025-10-03 04:10:24.846082 | controller | TASK [Add compute-0 to inventory] **********************************************
2025-10-03 04:10:24.846090 | controller | Friday 03 October 2025  04:10:24 +0000 (0:00:00.783)       0:00:00.796 ********
2025-10-03 04:10:24.846099 | controller | changed: [instance]
2025-10-03 04:10:25.338982 | controller |
2025-10-03 04:10:25.339012 | controller | TASK [Ensure minimal dirs exists] **********************************************
2025-10-03 04:10:25.339020 | controller | Friday 03 October 2025  04:10:24 +0000 (0:00:00.016)       0:00:00.813 ********
2025-10-03 04:10:25.339054 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-10-03 04:10:25.884599 | controller | changed: [instance] => (item=/etc/ci/env)
2025-10-03 04:10:25.884629 | controller |
2025-10-03 04:10:25.884637 | controller | TASK [Expected network info] ***************************************************
2025-10-03 04:10:25.884643 | controller | Friday 03 October 2025  04:10:25 +0000 (0:00:00.492)       0:00:01.305 ********
2025-10-03 04:10:25.884653 | controller | changed: [instance]
2025-10-03 04:10:25.914654 | controller |
2025-10-03 04:10:25.914680 | controller | TASK [hci_prepare : Set common facts] ******************************************
2025-10-03 04:10:25.914689 | controller | Friday 03 October 2025  04:10:25 +0000 (0:00:00.545)       0:00:01.851 ********
2025-10-03 04:10:25.914700 | controller | ok: [instance]
2025-10-03 04:10:25.952101 | controller |
2025-10-03 04:10:25.952132 | controller | TASK [hci_prepare : Load parameters] *******************************************
2025-10-03 04:10:25.952149 | controller | Friday 03 October 2025  04:10:25 +0000 (0:00:00.030)       0:00:01.881 ********
2025-10-03 04:10:25.952159 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/hci_prepare/tasks/load_parameters.yml for instance
2025-10-03 04:10:25.952338 | controller |
2025-10-03 04:10:25.952352 | controller | TASK [hci_prepare : Load parameters] *******************************************
2025-10-03 04:10:25.985105 | controller | Friday 03 October 2025  04:10:25 +0000 (0:00:00.037)       0:00:01.919 ********
2025-10-03 04:10:25.985133 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-10-03 04:10:26.018546 | controller | ok: [instance] => (item=/etc/ci/env)
2025-10-03 04:10:26.018570 | controller |
2025-10-03 04:10:26.018578 | controller | TASK [hci_prepare : Extract first compute from inventory] **********************
2025-10-03 04:10:26.018584 | controller | Friday 03 October 2025  04:10:25 +0000 (0:00:00.032)       0:00:01.952 ********
2025-10-03 04:10:26.018593 | controller | ok: [instance]
2025-10-03 04:10:26.055108 | controller |
2025-10-03 04:10:26.055149 | controller | TASK [hci_prepare : Ensure we have needed bits for compute when needed] ********
2025-10-03 04:10:26.055160 | controller | Friday 03 October 2025  04:10:26 +0000 (0:00:00.033)       0:00:01.985 ********
2025-10-03 04:10:26.055174 | controller | ok: [instance] => changed=false
2025-10-03 04:10:26.055214 | controller |   msg: All assertions passed
2025-10-03 04:10:26.055222 | controller |
2025-10-03 04:10:26.055231 | controller | TASK [hci_prepare : Set mtu value from crc_ci_bootstrap_networks_out] **********
2025-10-03 04:10:26.055313 | controller | Friday 03 October 2025  04:10:26 +0000 (0:00:00.036)       0:00:02.022 ********
2025-10-03 04:10:26.090748 | controller | ok: [instance]
2025-10-03 04:10:26.126111 | controller |
2025-10-03 04:10:26.126137 | controller | TASK [hci_prepare : Set vlan value from crc_ci_bootstrap_networks_out] *********
2025-10-03 04:10:26.126146 | controller | Friday 03 October 2025  04:10:26 +0000 (0:00:00.035)       0:00:02.057 ********
2025-10-03 04:10:26.126157 | controller | ok: [instance]
2025-10-03 04:10:26.126226 | controller |
2025-10-03 04:10:26.126251 | controller | TASK [hci_prepare : Ensure the kustomizations dirs exists] *********************
2025-10-03 04:10:26.126262 | controller | Friday 03 October 2025  04:10:26 +0000 (0:00:00.035)       0:00:02.093 ********
2025-10-03 04:10:26.310361 | controller | changed: [instance]
2025-10-03 04:10:26.648462 | controller |
2025-10-03 04:10:26.648492 | controller | TASK [hci_prepare : Prepare EDPM network for HCI deployment] *******************
2025-10-03 04:10:26.648500 | controller | Friday 03 October 2025  04:10:26 +0000 (0:00:00.183)       0:00:02.277 ********
2025-10-03 04:10:26.648510 | controller | changed: [instance]
2025-10-03 04:10:26.988123 | controller |
2025-10-03 04:10:26.988151 | controller | TASK [hci_prepare : Enable services needed to deploy Ceph] *********************
2025-10-03 04:10:26.988159 | controller | Friday 03 October 2025  04:10:26 +0000 (0:00:00.338)       0:00:02.615 ********
2025-10-03 04:10:26.988169 | controller | changed: [instance]
2025-10-03 04:10:26.988317 | controller |
2025-10-03 04:10:26.988329 | controller | TASK [hci_prepare : Disable discover_hosts when deploying hci on phase1] *******
2025-10-03 04:10:26.988337 | controller | Friday 03 October 2025  04:10:26 +0000 (0:00:00.339)       0:00:02.955 ********
2025-10-03 04:10:27.007091 | controller | ok: [instance]
2025-10-03 04:10:27.007358 | controller |
2025-10-03 04:10:27.007379 | controller | TASK [Assert mtu and vlan values were set] *************************************
2025-10-03 04:10:27.036616 | controller | Friday 03 October 2025  04:10:27 +0000 (0:00:00.019)       0:00:02.974 ********
2025-10-03 04:10:27.036635 | controller | ok: [instance] => changed=false
2025-10-03 04:10:27.421099 | controller |   msg: All assertions passed
2025-10-03 04:10:27.421129 | controller |
2025-10-03 04:10:27.421137 | controller | TASK [Check expected kustomizations] *******************************************
2025-10-03 04:10:27.421144 | controller | Friday 03 October 2025  04:10:27 +0000 (0:00:00.029)       0:00:03.003 ********
2025-10-03 04:10:27.421154 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml)
2025-10-03 04:10:27.421175 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml)
2025-10-03 04:10:27.421183 | controller |
2025-10-03 04:10:27.421371 | controller | TASK [Check if expected files where created] ***********************************
2025-10-03 04:10:27.452151 | controller | Friday 03 October 2025  04:10:27 +0000 (0:00:00.384)       0:00:03.388 ********
2025-10-03 04:10:27.452195 | controller | ok: [instance] => (item={'changed': False, 'stat': {'exists': True, 'path': '/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 294, 'inode': 21013286, 'dev': 64513, 'nlink': 1, 'atime': 1759464626.615045, 'mtime': 1759464626.4810436, 'ctime': 1759464626.618045, 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False, 'blocks': 8, 'block_size': 4096, 'device_type': 0, 'readable': True, 'writeable': True, 'executable': False, 'pw_name': 'zuul', 'gr_name': 'zuul', 'checksum': 'eb1b0d7f73292ae259724656eabf9af0d6b574b2', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '1655790501', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': '/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml', 'ansible_loop_var': 'item'}) => changed=false
2025-10-03 04:10:27.452236 | controller |   ansible_loop_var: item
2025-10-03 04:10:27.452245 | controller |   item:
2025-10-03 04:10:27.452252 | controller |     ansible_loop_var: item
2025-10-03 04:10:27.452262 | controller |     changed: false
2025-10-03 04:10:27.453960 | controller |     failed: false
2025-10-03 04:10:27.454068 | controller |     invocation:
2025-10-03 04:10:27.454083 | controller |       module_args:
2025-10-03 04:10:27.454089 | controller |         checksum_algorithm: sha1
2025-10-03 04:10:27.454095 | controller |         follow: false
2025-10-03 04:10:27.454101 | controller |         get_attributes: true
2025-10-03 04:10:27.454106 | controller |         get_checksum: true
2025-10-03 04:10:27.454111 | controller |         get_md5: false
2025-10-03 04:10:27.454116 | controller |         get_mime: true
2025-10-03 04:10:27.454122 | controller |         path: /home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml
2025-10-03 04:10:27.454128 | controller |     item: /home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml
2025-10-03 04:10:27.454133 | controller |     stat:
2025-10-03 04:10:27.454139 | controller |       atime: 1759464626.615045
2025-10-03 04:10:27.454144 | controller |       attr_flags: ''
2025-10-03 04:10:27.454150 | controller |       attributes: []
2025-10-03 04:10:27.454155 | controller |       block_size: 4096
2025-10-03 04:10:27.454160 | controller |       blocks: 8
2025-10-03 04:10:27.454165 | controller |       charset: us-ascii
2025-10-03 04:10:27.454174 | controller |       checksum: eb1b0d7f73292ae259724656eabf9af0d6b574b2
2025-10-03 04:10:27.454195 | controller |       ctime: 1759464626.618045
2025-10-03 04:10:27.455089 | controller |       dev: 64513
2025-10-03 04:10:27.455442 | controller |       device_type: 0
2025-10-03 04:10:27.455452 | controller |       executable: false
2025-10-03 04:10:27.455458 | controller |       exists: true
2025-10-03 04:10:27.455463 | controller |       gid: 1000
2025-10-03 04:10:27.455468 | controller |       gr_name: zuul
2025-10-03 04:10:27.455474 | controller |       inode: 21013286
2025-10-03 04:10:27.455479 | controller |       isblk: false
2025-10-03 04:10:27.455484 | controller |       ischr: false
2025-10-03 04:10:27.455489 | controller |       isdir: false
2025-10-03 04:10:27.455497 | controller |       isfifo: false
2025-10-03 04:10:27.455568 | controller |       isgid: false
2025-10-03 04:10:27.455684 | controller |       islnk: false
2025-10-03 04:10:27.455794 | controller |       isreg: true
2025-10-03 04:10:27.455908 | controller |       issock: false
2025-10-03 04:10:27.456019 | controller |       isuid: false
2025-10-03 04:10:27.456180 | controller |       mimetype: text/plain
2025-10-03 04:10:27.456293 | controller |       mode: '0644'
2025-10-03 04:10:27.456426 | controller |       mtime: 1759464626.4810436
2025-10-03 04:10:27.456536 | controller |       nlink: 1
2025-10-03 04:10:27.456655 | controller |       path: /home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml
2025-10-03 04:10:27.456764 | controller |       pw_name: zuul
2025-10-03 04:10:27.456871 | controller |       readable: true
2025-10-03 04:10:27.456979 | controller |       rgrp: true
2025-10-03 04:10:27.457106 | controller |       roth: true
2025-10-03 04:10:27.457227 | controller |       rusr: true
2025-10-03 04:10:27.457336 | controller |       size: 294
2025-10-03 04:10:27.457452 | controller |       uid: 1000
2025-10-03 04:10:27.457559 | controller |       version: '1655790501'
2025-10-03 04:10:27.457667 | controller |       wgrp: false
2025-10-03 04:10:27.457774 | controller |       woth: false
2025-10-03 04:10:27.457906 | controller |       writeable: true
2025-10-03 04:10:27.458043 | controller |       wusr: true
2025-10-03 04:10:27.458171 | controller |       xgrp: false
2025-10-03 04:10:27.458280 | controller |       xoth: false
2025-10-03 04:10:27.458420 | controller |       xusr: false
2025-10-03 04:10:27.458574 | controller |   msg: All assertions passed
2025-10-03 04:10:27.458750 | controller | ok: [instance] => (item={'changed': False, 'stat': {'exists': True, 'path': '/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 446, 'inode': 33570981, 'dev': 64513, 'nlink': 1, 'atime': 1759464626.9530487, 'mtime': 1759464626.8160472, 'ctime': 1759464626.9560487, 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False, 'blocks': 8, 'block_size': 4096, 'device_type': 0, 'readable': True, 'writeable': True, 'executable': False, 'pw_name': 'zuul', 'gr_name': 'zuul', 'checksum': '3bb4e07b60ede01bd9d3d77cd53d3ab90828582d', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '1012605713', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': '/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml', 'ansible_loop_var': 'item'}) => changed=false
2025-10-03 04:10:27.458903 | controller |   ansible_loop_var: item
2025-10-03 04:10:27.459063 | controller |   item:
2025-10-03 04:10:27.459210 | controller |     ansible_loop_var: item
2025-10-03 04:10:27.459342 | controller |     changed: false
2025-10-03 04:10:27.459493 | controller |     failed: false
2025-10-03 04:10:27.459626 | controller |     invocation:
2025-10-03 04:10:27.459755 | controller |       module_args:
2025-10-03 04:10:27.459892 | controller |         checksum_algorithm: sha1
2025-10-03 04:10:27.460043 | controller |         follow: false
2025-10-03 04:10:27.460196 | controller |         get_attributes: true
2025-10-03 04:10:27.460330 | controller |         get_checksum: true
2025-10-03 04:10:27.460479 | controller |         get_md5: false
2025-10-03 04:10:27.460609 | controller |         get_mime: true
2025-10-03 04:10:27.460745 | controller |         path: /home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml
2025-10-03 04:10:27.460880 | controller |     item: /home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml
2025-10-03 04:10:27.461011 | controller |     stat:
2025-10-03 04:10:27.461167 | controller |       atime: 1759464626.9530487
2025-10-03 04:10:27.461308 | controller |       attr_flags: ''
2025-10-03 04:10:27.461458 | controller |       attributes: []
2025-10-03 04:10:27.461591 | controller |       block_size: 4096
2025-10-03 04:10:27.461727 | controller |       blocks: 8
2025-10-03 04:10:27.461857 | controller |       charset: us-ascii
2025-10-03 04:10:27.461987 | controller |       checksum: 3bb4e07b60ede01bd9d3d77cd53d3ab90828582d
2025-10-03 04:10:27.462146 | controller |       ctime: 1759464626.9560487
2025-10-03 04:10:27.462280 | controller |       dev: 64513
2025-10-03 04:10:27.462424 | controller |       device_type: 0
2025-10-03 04:10:27.462558 | controller |       executable: false
2025-10-03 04:10:27.462686 | controller |       exists: true
2025-10-03 04:10:27.462813 | controller |       gid: 1000
2025-10-03 04:10:27.462941 | controller |       gr_name: zuul
2025-10-03 04:10:27.463103 | controller |       inode: 33570981
2025-10-03 04:10:27.463242 | controller |       isblk: false
2025-10-03 04:10:27.463369 | controller |       ischr: false
2025-10-03 04:10:27.463513 | controller |       isdir: false
2025-10-03 04:10:27.463640 | controller |       isfifo: false
2025-10-03 04:10:27.463772 | controller |       isgid: false
2025-10-03 04:10:27.463900 | controller |       islnk: false
2025-10-03 04:10:27.464051 | controller |       isreg: true
2025-10-03 04:10:27.464206 | controller |       issock: false
2025-10-03 04:10:27.464341 | controller |       isuid: false
2025-10-03 04:10:27.464486 | controller |       mimetype: text/plain
2025-10-03 04:10:27.464615 | controller |       mode: '0644'
2025-10-03 04:10:27.464744 | controller |       mtime: 1759464626.8160472
2025-10-03 04:10:27.464881 | controller |       nlink: 1
2025-10-03 04:10:27.465042 | controller |       path: /home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml
2025-10-03 04:10:27.465193 | controller |       pw_name: zuul
2025-10-03 04:10:27.465325 | controller |       readable: true
2025-10-03 04:10:27.465471 | controller |       rgrp: true
2025-10-03 04:10:27.465600 | controller |       roth: true
2025-10-03 04:10:27.465738 | controller |       rusr: true
2025-10-03 04:10:27.465868 | controller |       size: 446
2025-10-03 04:10:27.465996 | controller |       uid: 1000
2025-10-03 04:10:27.466157 | controller |       version: '1012605713'
2025-10-03 04:10:27.466293 | controller |       wgrp: false
2025-10-03 04:10:27.466434 | controller |       woth: false
2025-10-03 04:10:27.466565 | controller |       writeable: true
2025-10-03 04:10:27.466692 | controller |       wusr: true
2025-10-03 04:10:27.466822 | controller |       xgrp: false
2025-10-03 04:10:27.466949 | controller |       xoth: false
2025-10-03 04:10:27.467108 | controller |       xusr: false
2025-10-03 04:10:27.467243 | controller |   msg: All assertions passed
2025-10-03 04:10:27.467367 | controller |
2025-10-03 04:10:27.467513 | controller | TASK [hci_prepare : Set common facts] ******************************************
2025-10-03 04:10:27.467646 | controller | Friday 03 October 2025  04:10:27 +0000 (0:00:00.030)       0:00:03.419 ********
2025-10-03 04:10:27.483256 | controller | ok: [instance]
2025-10-03 04:10:27.483453 | controller |
2025-10-03 04:10:27.483626 | controller | TASK [hci_prepare : Ensure directories] ****************************************
2025-10-03 04:10:27.483792 | controller | Friday 03 October 2025  04:10:27 +0000 (0:00:00.031)       0:00:03.450 ********
2025-10-03 04:10:27.857710 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts)
2025-10-03 04:10:27.874931 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/kustomizations/dataplane)
2025-10-03 04:10:27.874959 | controller |
2025-10-03 04:10:27.874967 | controller | TASK [hci_prepare : Create ceph config secret] *********************************
2025-10-03 04:10:27.874973 | controller | Friday 03 October 2025  04:10:27 +0000 (0:00:00.373)       0:00:03.824 ********
2025-10-03 04:10:27.874982 | controller | skipping: [instance]
2025-10-03 04:10:27.892098 | controller |
2025-10-03 04:10:27.892126 | controller | TASK [hci_prepare : Set Ceph FSID fact] ****************************************
2025-10-03 04:10:27.892134 | controller | Friday 03 October 2025  04:10:27 +0000 (0:00:00.017)       0:00:03.841 ********
2025-10-03 04:10:27.892143 | controller | skipping: [instance]
2025-10-03 04:10:27.892164 | controller |
2025-10-03 04:10:27.892172 | controller | TASK [hci_prepare : Generate nova config map] **********************************
2025-10-03 04:10:27.892296 | controller | Friday 03 October 2025  04:10:27 +0000 (0:00:00.017)       0:00:03.859 ********
2025-10-03 04:10:27.909149 | controller | skipping: [instance]
2025-10-03 04:10:27.909183 | controller |
2025-10-03 04:10:27.909193 | controller | TASK [hci_prepare : Create nova config map] ************************************
2025-10-03 04:10:27.909203 | controller | Friday 03 October 2025  04:10:27 +0000 (0:00:00.017)       0:00:03.876 ********
2025-10-03 04:10:27.925872 | controller | skipping: [instance]
2025-10-03 04:10:27.943093 | controller |
2025-10-03 04:10:27.943124 | controller | TASK [hci_prepare : Generate Ceph-Nova Dataplane Service] **********************
2025-10-03 04:10:27.943132 | controller | Friday 03 October 2025  04:10:27 +0000 (0:00:00.016)       0:00:03.893 ********
2025-10-03 04:10:27.943142 | controller | skipping: [instance]
2025-10-03 04:10:27.960847 | controller |
2025-10-03 04:10:27.960879 | controller | TASK [hci_prepare : Create Ceph-Nova Dataplane Service] ************************
2025-10-03 04:10:27.960887 | controller | Friday 03 October 2025  04:10:27 +0000 (0:00:00.017)       0:00:03.910 ********
2025-10-03 04:10:27.960906 | controller | skipping: [instance]
2025-10-03 04:10:27.977983 | controller |
2025-10-03 04:10:27.978008 | controller | TASK [hci_prepare : Delete OpenStackDataPlaneDeployment] ***********************
2025-10-03 04:10:27.978014 | controller | Friday 03 October 2025  04:10:27 +0000 (0:00:00.017)       0:00:03.927 ********
2025-10-03 04:10:27.978040 | controller | skipping: [instance]
2025-10-03 04:10:28.325600 | controller |
2025-10-03 04:10:28.325630 | controller | TASK [hci_prepare : Create configuration to finish HCI deployment] *************
2025-10-03 04:10:28.325638 | controller | Friday 03 October 2025  04:10:27 +0000 (0:00:00.017)       0:00:03.945 ********
2025-10-03 04:10:28.325647 | controller | changed: [instance]
2025-10-03 04:10:28.342948 | controller |
2025-10-03 04:10:28.342976 | controller | TASK [hci_prepare : Enabled nova discover_hosts after deployment] **************
2025-10-03 04:10:28.342984 | controller | Friday 03 October 2025  04:10:28 +0000 (0:00:00.347)       0:00:04.292 ********
2025-10-03 04:10:28.342996 | controller | ok: [instance]
2025-10-03 04:10:28.364129 | controller |
2025-10-03 04:10:28.364159 | controller | TASK [hci_prepare : Save HCI info] *********************************************
2025-10-03 04:10:28.364169 | controller | Friday 03 October 2025  04:10:28 +0000 (0:00:00.017)       0:00:04.310 ********
2025-10-03 04:10:28.364180 | controller | skipping: [instance]
2025-10-03 04:10:28.364352 | controller |
2025-10-03 04:10:28.364371 | controller | TASK [Check expected kustomizations - phase 2] *********************************
2025-10-03 04:10:28.558078 | controller | Friday 03 October 2025  04:10:28 +0000 (0:00:00.021)       0:00:04.331 ********
2025-10-03 04:10:28.558120 | controller | ok: [instance]
2025-10-03 04:10:28.590100 | controller |
2025-10-03 04:10:28.590132 | controller | TASK [Check if expected files where created - phase 2] *************************
2025-10-03 04:10:28.590140 | controller | Friday 03 October 2025  04:10:28 +0000 (0:00:00.193)       0:00:04.525 ********
2025-10-03 04:10:28.590151 | controller | ok: [instance] => changed=false
2025-10-03 04:10:28.592128 | controller |   msg: All assertions passed
2025-10-03 04:10:28.592148 | controller |
2025-10-03 04:10:28.592156 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:10:28.592162 | controller | instance                   : ok=24   changed=7    unreachable=0    failed=0    skipped=8    rescued=0    ignored=0
2025-10-03 04:10:28.592167 | controller |
2025-10-03 04:10:28.592173 | controller | Friday 03 October 2025  04:10:28 +0000 (0:00:00.031)       0:00:04.556 ********
2025-10-03 04:10:28.592182 | controller | ===============================================================================
2025-10-03 04:10:28.651685 | controller | Gathering Facts --------------------------------------------------------- 0.78s
2025-10-03 04:10:28.651714 | controller | Expected network info --------------------------------------------------- 0.55s
2025-10-03 04:10:28.651721 | controller | Ensure minimal dirs exists ---------------------------------------------- 0.49s
2025-10-03 04:10:28.651727 | controller | Check expected kustomizations ------------------------------------------- 0.38s
2025-10-03 04:10:28.651733 | controller | hci_prepare : Ensure directories ---------------------------------------- 0.37s
2025-10-03 04:10:28.651738 | controller | hci_prepare : Create configuration to finish HCI deployment ------------- 0.35s
2025-10-03 04:10:28.651743 | controller | hci_prepare : Enable services needed to deploy Ceph --------------------- 0.34s
2025-10-03 04:10:28.651748 | controller | hci_prepare : Prepare EDPM network for HCI deployment ------------------- 0.34s
2025-10-03 04:10:28.651754 | controller | Check expected kustomizations - phase 2 --------------------------------- 0.19s
2025-10-03 04:10:28.651763 | controller | hci_prepare : Ensure the kustomizations dirs exists --------------------- 0.18s
2025-10-03 04:10:28.651769 | controller | hci_prepare : Load parameters ------------------------------------------- 0.04s
2025-10-03 04:10:28.651774 | controller | hci_prepare : Ensure we have needed bits for compute when needed -------- 0.04s
2025-10-03 04:10:28.651779 | controller | hci_prepare : Set mtu value from crc_ci_bootstrap_networks_out ---------- 0.04s
2025-10-03 04:10:28.651784 | controller | hci_prepare : Set vlan value from crc_ci_bootstrap_networks_out --------- 0.04s
2025-10-03 04:10:28.651789 | controller | hci_prepare : Extract first compute from inventory ---------------------- 0.03s
2025-10-03 04:10:28.651801 | controller | hci_prepare : Load parameters ------------------------------------------- 0.03s
2025-10-03 04:10:28.651806 | controller | hci_prepare : Set common facts ------------------------------------------ 0.03s
2025-10-03 04:10:28.651812 | controller | Check if expected files where created - phase 2 ------------------------- 0.03s
2025-10-03 04:10:28.651817 | controller | Check if expected files where created ----------------------------------- 0.03s
2025-10-03 04:10:28.651822 | controller | hci_prepare : Set common facts ------------------------------------------ 0.03s
2025-10-03 04:10:28.651832 | controller | INFO     Running default > cleanup
2025-10-03 04:10:28.652296 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-03 04:10:28.652879 | controller | INFO     Writing /tmp/report.html report.
2025-10-03 04:10:28.828176 | controller | changed
2025-10-03 04:10:28.849709 | 
2025-10-03 04:10:28.849776 | PLAY RECAP
2025-10-03 04:10:28.849817 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-03 04:10:28.849840 | 
2025-10-03 04:10:28.920892 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-03 04:10:28.921805 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-03 04:10:29.472552 | 
2025-10-03 04:10:29.472646 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-03 04:10:29.492873 | 
2025-10-03 04:10:29.492986 | TASK [Filter out host if needed]
2025-10-03 04:10:29.501486 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-03 04:10:29.505853 | 
2025-10-03 04:10:29.505934 | TASK [Ensure file is present]
2025-10-03 04:10:29.817999 | controller | ok
2025-10-03 04:10:29.824854 | 
2025-10-03 04:10:29.824950 | TASK [Manage molecule report file]
2025-10-03 04:10:30.352559 | controller | changed
2025-10-03 04:10:30.358358 | 
2025-10-03 04:10:30.358442 | TASK [Check if we get ci-framework-data basedir]
2025-10-03 04:10:30.550058 | controller | ok
2025-10-03 04:10:30.559925 | 
2025-10-03 04:10:30.560014 | TASK [Create ci-framework-data log directory for zuul]
2025-10-03 04:10:30.881969 | controller | changed
2025-10-03 04:10:30.887362 | 
2025-10-03 04:10:30.887428 | TASK [Copy ci-framework interesting files]
2025-10-03 04:10:31.083012 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-03 04:10:31.411392 | controller | changed
2025-10-03 04:10:31.416636 | 
2025-10-03 04:10:31.416703 | TASK [Get SELinux listing]
2025-10-03 04:10:31.942555 | controller | changed
2025-10-03 04:10:31.948341 | 
2025-10-03 04:10:31.948424 | TASK [Generate log index]
2025-10-03 04:10:32.582621 | controller | changed
2025-10-03 04:10:32.587740 | 
2025-10-03 04:10:32.587813 | TASK [Get some env related data]
2025-10-03 04:10:33.119111 | controller | /home/zuul/.local/bin/ansible
2025-10-03 04:10:34.117481 | controller | changed
2025-10-03 04:10:34.122793 | 
2025-10-03 04:10:34.122874 | TASK [Generate list of logs to collect in home directory]
2025-10-03 04:10:34.456956 | controller | ok: All paths examined
2025-10-03 04:10:34.464751 | 
2025-10-03 04:10:34.464835 | LOOP [Copy logs from home directory]
2025-10-03 04:10:34.879773 | controller | changed:
2025-10-03 04:10:34.879886 | controller | {
2025-10-03 04:10:34.879935 | controller |   "atime": 1759464531.2179933,
2025-10-03 04:10:34.879959 | controller |   "ctime": 1759464546.9821692,
2025-10-03 04:10:34.879978 | controller |   "dev": 64513,
2025-10-03 04:10:34.879996 | controller |   "gid": 1000,
2025-10-03 04:10:34.880012 | controller |   "gr_name": "zuul",
2025-10-03 04:10:34.880030 | controller |   "inode": 4452303,
2025-10-03 04:10:34.880047 | controller |   "isblk": false,
2025-10-03 04:10:34.880064 | controller |   "ischr": false,
2025-10-03 04:10:34.880080 | controller |   "isdir": false,
2025-10-03 04:10:34.880096 | controller |   "isfifo": false,
2025-10-03 04:10:34.880112 | controller |   "isgid": false,
2025-10-03 04:10:34.880133 | controller |   "islnk": false,
2025-10-03 04:10:34.880149 | controller |   "isreg": true,
2025-10-03 04:10:34.880165 | controller |   "issock": false,
2025-10-03 04:10:34.880179 | controller |   "isuid": false,
2025-10-03 04:10:34.880194 | controller |   "mode": "0644",
2025-10-03 04:10:34.880208 | controller |   "mtime": 1759464546.9821692,
2025-10-03 04:10:34.880223 | controller |   "nlink": 1,
2025-10-03 04:10:34.880239 | controller |   "path": "/home/zuul/ansible.log",
2025-10-03 04:10:34.880255 | controller |   "pw_name": "zuul",
2025-10-03 04:10:34.880270 | controller |   "rgrp": true,
2025-10-03 04:10:34.880285 | controller |   "roth": true,
2025-10-03 04:10:34.880300 | controller |   "rusr": true,
2025-10-03 04:10:34.880315 | controller |   "size": 6749,
2025-10-03 04:10:34.880329 | controller |   "uid": 1000,
2025-10-03 04:10:34.880344 | controller |   "wgrp": false,
2025-10-03 04:10:34.880358 | controller |   "woth": false,
2025-10-03 04:10:34.880376 | controller |   "wusr": true,
2025-10-03 04:10:34.880392 | controller |   "xgrp": false,
2025-10-03 04:10:34.880409 | controller |   "xoth": false,
2025-10-03 04:10:34.880424 | controller |   "xusr": false
2025-10-03 04:10:34.880440 | controller | }
2025-10-03 04:10:34.891886 | 
2025-10-03 04:10:34.892010 | TASK [Copy crio stats log file]
2025-10-03 04:10:34.935796 | controller | skipping: Conditional result was False
2025-10-03 04:10:34.941591 | 
2025-10-03 04:10:34.941672 | TASK [Get SELinux related data]
2025-10-03 04:10:35.167910 | controller | <no matches>
2025-10-03 04:10:35.471715 | controller | ERROR
2025-10-03 04:10:35.471840 | controller | {
2025-10-03 04:10:35.471872 | controller |   "delta": "0:00:00.009962",
2025-10-03 04:10:35.471894 | controller |   "end": "2025-10-03 04:10:35.169596",
2025-10-03 04:10:35.471941 | controller |   "msg": "non-zero return code",
2025-10-03 04:10:35.471965 | controller |   "rc": 1,
2025-10-03 04:10:35.471983 | controller |   "start": "2025-10-03 04:10:35.159634"
2025-10-03 04:10:35.471999 | controller | }
2025-10-03 04:10:35.472022 | controller | ERROR: Ignoring Errors
2025-10-03 04:10:35.477219 | 
2025-10-03 04:10:35.477286 | TASK [Create system configuration directory]
2025-10-03 04:10:35.671866 | controller | changed
2025-10-03 04:10:35.677042 | 
2025-10-03 04:10:35.677120 | TASK [Get some of the system configurations]
2025-10-03 04:10:36.204827 | controller | changed
2025-10-03 04:10:36.209963 | 
2025-10-03 04:10:36.210035 | TASK [Copy generated documentation if available]
2025-10-03 04:10:36.224141 | controller | skipping: Conditional result was False
2025-10-03 04:10:36.229938 | 
2025-10-03 04:10:36.230008 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-03 04:10:36.253815 | controller | skipping: Conditional result was False
2025-10-03 04:10:36.259570 | 
2025-10-03 04:10:36.259638 | TASK [Compress logs bigger than 2MB]
2025-10-03 04:10:36.787262 | controller | changed
2025-10-03 04:10:36.792753 | 
2025-10-03 04:10:36.792816 | TASK [Copy files from workspace on node]
2025-10-03 04:10:36.811326 | controller | ok
2025-10-03 04:10:36.834704 | 
2025-10-03 04:10:36.834807 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-03 04:10:36.848686 | controller | skipping: Conditional result was False
2025-10-03 04:10:36.855212 | 
2025-10-03 04:10:36.855286 | TASK [fetch-output : Set log path for single node]
2025-10-03 04:10:36.883615 | controller | ok
2025-10-03 04:10:36.889865 | 
2025-10-03 04:10:36.889967 | LOOP [fetch-output : Ensure local output dirs]
2025-10-03 04:10:37.109564 | controller -> localhost | ok: "/var/lib/zuul/builds/e8c22baad0e04cf1b8feb2842fa1b10d/work/logs"
2025-10-03 04:10:37.109838 | controller -> localhost | changed: All items complete
2025-10-03 04:10:37.109870 | 
2025-10-03 04:10:37.315580 | controller -> localhost | changed: "/var/lib/zuul/builds/e8c22baad0e04cf1b8feb2842fa1b10d/work/artifacts"
2025-10-03 04:10:37.508065 | controller -> localhost | changed: "/var/lib/zuul/builds/e8c22baad0e04cf1b8feb2842fa1b10d/work/docs"
2025-10-03 04:10:37.525223 | 
2025-10-03 04:10:37.525323 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-03 04:10:38.121998 | controller | changed:
2025-10-03 04:10:38.122207 | controller | .d..t...... ./
2025-10-03 04:10:38.122239 | controller | >f+++++++++ README.html
2025-10-03 04:10:38.122263 | controller | >f+++++++++ ansible-execution.log
2025-10-03 04:10:38.122285 | controller | >f+++++++++ ansible.log
2025-10-03 04:10:38.122304 | controller | >f+++++++++ dmesg.log
2025-10-03 04:10:38.122322 | controller | >f+++++++++ installed-pkgs.log
2025-10-03 04:10:38.122342 | controller | >f+++++++++ python.log
2025-10-03 04:10:38.122364 | controller | >f+++++++++ registries.conf
2025-10-03 04:10:38.122381 | controller | >f+++++++++ report.html
2025-10-03 04:10:38.122401 | controller | >f+++++++++ selinux-denials.log
2025-10-03 04:10:38.122419 | controller | >f+++++++++ selinux-listing.log
2025-10-03 04:10:38.122438 | controller | cd+++++++++ ci-framework-data/
2025-10-03 04:10:38.122454 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-03 04:10:38.122472 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-03 04:10:38.122489 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-10-03 04:10:38.122506 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/kustomizations/
2025-10-03 04:10:38.122523 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/kustomizations/dataplane/
2025-10-03 04:10:38.122540 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/kustomizations/dataplane/87-hci-post-kustomization.yaml
2025-10-03 04:10:38.122557 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/kustomizations/dataplane/88-hci-pre-kustomization.yaml
2025-10-03 04:10:38.122574 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/kustomizations/dataplane/89-storage-mgmt-kustomization.yaml
2025-10-03 04:10:38.122592 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-03 04:10:38.122609 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/network_info.yml
2025-10-03 04:10:38.122626 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-03 04:10:38.122644 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-03 04:10:38.122661 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-03 04:10:38.122678 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-03 04:10:38.122695 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-03 04:10:38.122712 | controller | cd+++++++++ registries.conf.d/
2025-10-03 04:10:38.122756 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-03 04:10:38.122785 | controller | cd+++++++++ system-config/
2025-10-03 04:10:38.122804 | controller | cd+++++++++ system-config/libvirt/
2025-10-03 04:10:38.122821 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-03 04:10:38.122838 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-03 04:10:38.523640 | controller | changed: .d..t...... ./
2025-10-03 04:10:38.931187 | controller | changed: .d..t...... ./
2025-10-03 04:10:38.950494 | 
2025-10-03 04:10:38.950606 | TASK [Return artifact to Zuul]
2025-10-03 04:10:38.981972 | controller | ok
2025-10-03 04:10:39.009167 | 
2025-10-03 04:10:39.009252 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-03 04:10:39.009364 | 
2025-10-03 04:10:39.009392 | PLAY RECAP
2025-10-03 04:10:39.009431 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-03 04:10:39.009451 | 
2025-10-03 04:10:39.114613 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-03 04:10:39.115652 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-03 04:10:39.679867 | 
2025-10-03 04:10:39.679987 | PLAY [all]
2025-10-03 04:10:39.698470 | 
2025-10-03 04:10:39.698547 | TASK [include_role : fetch-output]
2025-10-03 04:10:39.747289 | controller | ok
2025-10-03 04:10:39.763672 | 
2025-10-03 04:10:39.763771 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-03 04:10:39.817381 | controller | skipping: Conditional result was False
2025-10-03 04:10:39.822846 | 
2025-10-03 04:10:39.822941 | TASK [fetch-output : Set log path for single node]
2025-10-03 04:10:39.852030 | controller | ok
2025-10-03 04:10:39.859060 | 
2025-10-03 04:10:39.859146 | LOOP [fetch-output : Ensure local output dirs]
2025-10-03 04:10:40.235677 | controller -> localhost | ok: "/var/lib/zuul/builds/e8c22baad0e04cf1b8feb2842fa1b10d/work/logs"
2025-10-03 04:10:40.437749 | controller -> localhost | ok: "/var/lib/zuul/builds/e8c22baad0e04cf1b8feb2842fa1b10d/work/artifacts"
2025-10-03 04:10:40.633306 | controller -> localhost | ok: "/var/lib/zuul/builds/e8c22baad0e04cf1b8feb2842fa1b10d/work/docs"
2025-10-03 04:10:40.643650 | 
2025-10-03 04:10:40.643775 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-03 04:10:41.180619 | controller | ok
2025-10-03 04:10:41.180818 | controller | ok: All items complete
2025-10-03 04:10:41.180850 | 
2025-10-03 04:10:41.563879 | controller | ok
2025-10-03 04:10:41.953266 | controller | ok
2025-10-03 04:10:41.973265 | 
2025-10-03 04:10:41.973358 | TASK [include_role : fetch-output-openshift]
2025-10-03 04:10:41.987287 | controller | skipping: Conditional result was False
2025-10-03 04:10:42.001168 | 
2025-10-03 04:10:42.001255 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-03 04:10:42.386553 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007125
2025-10-03 04:10:42.578858 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006345
2025-10-03 04:10:42.617420 | 
2025-10-03 04:10:42.617491 | PLAY [all]
2025-10-03 04:10:42.631608 | 
2025-10-03 04:10:42.631677 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-03 04:10:43.030257 | controller | changed
2025-10-03 04:10:43.055451 | 
2025-10-03 04:10:43.055522 | PLAY RECAP
2025-10-03 04:10:43.055566 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-03 04:10:43.055590 | 
2025-10-03 04:10:43.134309 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-03 04:10:43.135094 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-03 04:10:43.741966 | 
2025-10-03 04:10:43.742075 | PLAY [localhost]
2025-10-03 04:10:43.763206 | 
2025-10-03 04:10:43.763290 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-03 04:10:44.107298 | localhost | changed
2025-10-03 04:10:44.112316 | 
2025-10-03 04:10:44.112411 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-03 04:10:44.141820 | localhost | ok
2025-10-03 04:10:44.151642 | 
2025-10-03 04:10:44.151751 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-03 04:10:44.465810 | localhost | changed
2025-10-03 04:10:44.471876 | 
2025-10-03 04:10:44.471967 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-03 04:10:45.066245 | localhost | changed
2025-10-03 04:10:45.071224 | 
2025-10-03 04:10:45.071293 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-03 04:10:45.438686 | localhost | Identity added: /var/lib/zuul/builds/e8c22baad0e04cf1b8feb2842fa1b10d/work/tmp/ansible.et3zea7k (/var/lib/zuul/builds/e8c22baad0e04cf1b8feb2842fa1b10d/work/tmp/ansible.et3zea7k)
2025-10-03 04:10:45.438874 | localhost | ok: Runtime: 0:00:00.008681
2025-10-03 04:10:45.443280 | 
2025-10-03 04:10:45.443354 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-03 04:10:45.673606 | localhost | ok: Runtime: 0:00:00.005430
2025-10-03 04:10:45.678341 | 
2025-10-03 04:10:45.678420 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-03 04:10:45.737774 | localhost | changed
2025-10-03 04:10:45.742589 | 
2025-10-03 04:10:45.742681 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-03 04:10:46.131166 | localhost | changed
2025-10-03 04:10:46.152318 | 
2025-10-03 04:10:46.152392 | PLAY [localhost]
2025-10-03 04:10:46.165270 | 
2025-10-03 04:10:46.165337 | TASK [Generate bulk log download script]
2025-10-03 04:10:46.183056 | localhost | ok
2025-10-03 04:10:46.195562 | 
2025-10-03 04:10:46.195635 | TASK [local-log-download : Check API endpoint is defined]
2025-10-03 04:10:46.222833 | localhost | ok: All assertions passed
2025-10-03 04:10:46.227512 | 
2025-10-03 04:10:46.227581 | TASK [local-log-download : Create download script]
2025-10-03 04:10:46.626846 | localhost -> localhost | changed
2025-10-03 04:10:46.636350 | 
2025-10-03 04:10:46.636533 | TASK [Register quick-download link]
2025-10-03 04:10:46.654275 | localhost | ok
2025-10-03 04:10:46.691409 | 
2025-10-03 04:10:46.691499 | PLAY [logserver.rdoproject.org]
2025-10-03 04:10:46.701719 | 
2025-10-03 04:10:46.701793 | TASK [Set zuul-log-path fact]
2025-10-03 04:10:46.717550 | logserver.rdoproject.org | ok
2025-10-03 04:10:46.726934 | 
2025-10-03 04:10:46.727001 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-03 04:10:46.750107 | logserver.rdoproject.org | ok
2025-10-03 04:10:46.756484 | 
2025-10-03 04:10:46.756583 | TASK [upload-logs : Create log directories]
2025-10-03 04:10:48.664251 | logserver.rdoproject.org | changed
2025-10-03 04:10:48.667504 | 
2025-10-03 04:10:48.667575 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-03 04:10:48.924243 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004971
2025-10-03 04:10:48.928876 | 
2025-10-03 04:10:48.928960 | TASK [upload-logs : Upload logs to log server]
2025-10-03 04:10:50.510503 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-03 04:10:50.514056 | 
2025-10-03 04:10:50.514130 | LOOP [upload-logs : Compress console log and json output]
2025-10-03 04:10:50.562357 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 04:10:50.569199 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 04:10:50.583001 | 
2025-10-03 04:10:50.583107 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-03 04:10:50.619947 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 04:10:50.620171 | 
2025-10-03 04:10:50.629332 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 04:10:50.642709 | 
2025-10-03 04:10:50.642829 | LOOP [upload-logs : Upload console log and json output]