2026-01-28 12:10:15.314991 | Job console starting...
2026-01-28 12:10:15.533915 | Updating repositories
2026-01-28 12:10:17.060762 | Preparing job workspace
2026-01-28 12:10:23.682156 | Running Ansible setup...
2026-01-28 12:10:26.451955 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-28 12:10:26.925182 | 
2026-01-28 12:10:26.925309 | PLAY [localhost]
2026-01-28 12:10:26.933393 | 
2026-01-28 12:10:26.933458 | TASK [Gathering Facts]
2026-01-28 12:10:27.690738 | localhost | ok
2026-01-28 12:10:27.702336 | 
2026-01-28 12:10:27.702410 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-28 12:10:27.990789 | localhost -> localhost | changed
2026-01-28 12:10:27.995507 | 
2026-01-28 12:10:27.995571 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-28 12:10:28.627287 | localhost -> localhost | changed
2026-01-28 12:10:28.634532 | 
2026-01-28 12:10:28.634595 | TASK [Setup log path fact]
2026-01-28 12:10:28.651374 | localhost | ok
2026-01-28 12:10:28.661107 | 
2026-01-28 12:10:28.661168 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-28 12:10:28.678529 | localhost | ok
2026-01-28 12:10:28.685024 | 
2026-01-28 12:10:28.685093 | TASK [emit-job-header : Print job information]
2026-01-28 12:10:28.712161 | # Job Information
2026-01-28 12:10:28.712269 | Ansible Version: 2.15.12
2026-01-28 12:10:28.712310 | Job: cifmw-architecture-validate-hci
2026-01-28 12:10:28.712339 | Pipeline: github-check
2026-01-28 12:10:28.712359 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-01-28 12:10:28.712377 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3634
2026-01-28 12:10:28.712395 | Log URL (when completed): https://logserver.rdoproject.org/096/rdoproject.org/096787b971c84df3902424a35d2eea36/
2026-01-28 12:10:28.712412 | Event ID: 27d22550-fc42-11f0-83b9-5d0a512851a4
2026-01-28 12:10:28.715720 | 
2026-01-28 12:10:28.715781 | LOOP [emit-job-header : Print node information]
2026-01-28 12:10:28.794083 | localhost | ok:
2026-01-28 12:10:28.794239 | localhost | # Node Information
2026-01-28 12:10:28.794269 | localhost | Inventory Hostname: controller
2026-01-28 12:10:28.794309 | localhost | Hostname: np0005598880
2026-01-28 12:10:28.794336 | localhost | Username: zuul
2026-01-28 12:10:28.794359 | localhost | Distro: CentOS 9
2026-01-28 12:10:28.794377 | localhost | Provider: ibm-bm4-nodepool
2026-01-28 12:10:28.794394 | localhost | Region: regionOne
2026-01-28 12:10:28.794410 | localhost | Label: cloud-centos-9-stream-tripleo
2026-01-28 12:10:28.794425 | localhost | Product Name: OpenStack Compute
2026-01-28 12:10:28.794439 | localhost | Interface IP: 192.168.26.130
2026-01-28 12:10:28.817019 | 
2026-01-28 12:10:28.817095 | PLAY [all]
2026-01-28 12:10:28.822629 | 
2026-01-28 12:10:28.822689 | TASK [Gather network facts]
2026-01-28 12:10:29.178673 | controller | ok
2026-01-28 12:10:29.190838 | 
2026-01-28 12:10:29.190894 | TASK [include_role : start-zuul-console]
2026-01-28 12:10:29.207694 | controller | ok
2026-01-28 12:10:29.218210 | 
2026-01-28 12:10:29.218268 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-28 12:10:29.510745 | controller | ok
2026-01-28 12:10:29.518597 | 
2026-01-28 12:10:29.518654 | TASK [include_role : add-build-sshkey]
2026-01-28 12:10:29.535957 | controller | ok
2026-01-28 12:10:29.547409 | 
2026-01-28 12:10:29.547466 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-28 12:10:29.722479 | controller -> localhost | ok
2026-01-28 12:10:29.727388 | 
2026-01-28 12:10:29.727456 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-28 12:10:29.744752 | controller | ok
2026-01-28 12:10:29.756924 | controller | included: /var/lib/zuul/builds/096787b971c84df3902424a35d2eea36/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-28 12:10:29.761906 | 
2026-01-28 12:10:29.761967 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-28 12:10:30.180093 | controller -> localhost | Generating public/private rsa key pair.
2026-01-28 12:10:30.180238 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/096787b971c84df3902424a35d2eea36/work/096787b971c84df3902424a35d2eea36_id_rsa.
2026-01-28 12:10:30.180269 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/096787b971c84df3902424a35d2eea36/work/096787b971c84df3902424a35d2eea36_id_rsa.pub.
2026-01-28 12:10:30.180291 | controller -> localhost | The key fingerprint is:
2026-01-28 12:10:30.180337 | controller -> localhost | SHA256:PdPzUS67ACGWvRruuhkOfnSvvi2MmrTnU0SJPf4bGLI zuul-build-sshkey
2026-01-28 12:10:30.180359 | controller -> localhost | The key's randomart image is:
2026-01-28 12:10:30.180376 | controller -> localhost | +---[RSA 3072]----+
2026-01-28 12:10:30.180393 | controller -> localhost | |       o .       |
2026-01-28 12:10:30.180409 | controller -> localhost | |      . =o       |
2026-01-28 12:10:30.180424 | controller -> localhost | |       o+.o     .|
2026-01-28 12:10:30.180440 | controller -> localhost | |      ..+o +   o |
2026-01-28 12:10:30.180455 | controller -> localhost | |       +S+* o o .|
2026-01-28 12:10:30.180470 | controller -> localhost | |      E.+oo+ o + |
2026-01-28 12:10:30.180485 | controller -> localhost | |    o..=o. o. o  |
2026-01-28 12:10:30.180500 | controller -> localhost | |   o ==+o.o  . . |
2026-01-28 12:10:30.180515 | controller -> localhost | |    ==*==+.   .  |
2026-01-28 12:10:30.180529 | controller -> localhost | +----[SHA256]-----+
2026-01-28 12:10:30.180567 | controller -> localhost | ok: Runtime: 0:00:00.113799
2026-01-28 12:10:30.185654 | 
2026-01-28 12:10:30.185710 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-28 12:10:30.203359 | controller | ok
2026-01-28 12:10:30.211672 | controller | included: /var/lib/zuul/builds/096787b971c84df3902424a35d2eea36/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-28 12:10:30.218430 | 
2026-01-28 12:10:30.218487 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-28 12:10:30.232103 | controller | skipping: Conditional result was False
2026-01-28 12:10:30.237073 | 
2026-01-28 12:10:30.237135 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-28 12:10:30.579787 | controller | changed
2026-01-28 12:10:30.584849 | 
2026-01-28 12:10:30.584905 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-28 12:10:30.806114 | controller | ok
2026-01-28 12:10:30.810446 | 
2026-01-28 12:10:30.810507 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-28 12:10:31.366033 | controller | changed
2026-01-28 12:10:31.370153 | 
2026-01-28 12:10:31.370210 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-28 12:10:31.915786 | controller | changed
2026-01-28 12:10:31.920237 | 
2026-01-28 12:10:31.920313 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-28 12:10:31.933621 | controller | skipping: Conditional result was False
2026-01-28 12:10:31.938234 | 
2026-01-28 12:10:31.938307 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-28 12:10:32.205584 | controller -> localhost | changed
2026-01-28 12:10:32.214089 | 
2026-01-28 12:10:32.214159 | TASK [add-build-sshkey : Add back temp key]
2026-01-28 12:10:32.419881 | controller -> localhost | Identity added: /var/lib/zuul/builds/096787b971c84df3902424a35d2eea36/work/096787b971c84df3902424a35d2eea36_id_rsa (zuul-build-sshkey)
2026-01-28 12:10:32.420029 | controller -> localhost | ok: Runtime: 0:00:00.006979
2026-01-28 12:10:32.425309 | 
2026-01-28 12:10:32.425367 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-28 12:10:32.696333 | controller | ok
2026-01-28 12:10:32.700464 | 
2026-01-28 12:10:32.700522 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-28 12:10:32.724189 | controller | skipping: Conditional result was False
2026-01-28 12:10:32.732859 | 
2026-01-28 12:10:32.732919 | TASK [include_role : validate-host]
2026-01-28 12:10:32.750466 | controller | ok
2026-01-28 12:10:32.768152 | 
2026-01-28 12:10:32.768217 | TASK [validate-host : Define zuul_info_dir fact]
2026-01-28 12:10:32.795893 | controller | ok
2026-01-28 12:10:32.799906 | 
2026-01-28 12:10:32.799964 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-01-28 12:10:32.975342 | controller -> localhost | ok
2026-01-28 12:10:32.980491 | 
2026-01-28 12:10:32.980550 | TASK [validate-host : Collect information about the host]
2026-01-28 12:10:33.577089 | controller | ok
2026-01-28 12:10:33.584912 | 
2026-01-28 12:10:33.584976 | TASK [validate-host : Sanitize hostname]
2026-01-28 12:10:33.653466 | controller | ok
2026-01-28 12:10:33.658120 | 
2026-01-28 12:10:33.658182 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-01-28 12:10:34.018517 | controller -> localhost | changed
2026-01-28 12:10:34.042540 | 
2026-01-28 12:10:34.042619 | TASK [validate-host : Collect information about zuul worker]
2026-01-28 12:10:34.345755 | controller | ok
2026-01-28 12:10:34.349888 | 
2026-01-28 12:10:34.349946 | TASK [validate-host : Write out all zuul information for each host]
2026-01-28 12:10:34.677162 | controller -> localhost | changed
2026-01-28 12:10:34.685751 | 
2026-01-28 12:10:34.685820 | TASK [include_role : prepare-workspace-openshift]
2026-01-28 12:10:34.699488 | controller | skipping: Conditional result was False
2026-01-28 12:10:34.704093 | 
2026-01-28 12:10:34.704154 | TASK [include_role : remove-zuul-sshkey]
2026-01-28 12:10:34.717448 | controller | skipping: Conditional result was False
2026-01-28 12:10:34.722292 | 
2026-01-28 12:10:34.722370 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-28 12:10:34.926627 | controller | ok: "logs"
2026-01-28 12:10:34.926873 | controller | ok: All items complete
2026-01-28 12:10:34.926908 | 
2026-01-28 12:10:35.079993 | controller | ok: "artifacts"
2026-01-28 12:10:35.239035 | controller | ok: "docs"
2026-01-28 12:10:35.247645 | 
2026-01-28 12:10:35.247753 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-28 12:10:35.445922 | controller | changed: "logs"
2026-01-28 12:10:35.604459 | controller | changed: "artifacts"
2026-01-28 12:10:35.769954 | controller | changed: "docs"
2026-01-28 12:10:35.789401 | 
2026-01-28 12:10:35.789495 | PLAY RECAP
2026-01-28 12:10:35.789536 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-28 12:10:35.789560 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-28 12:10:35.789577 | 
2026-01-28 12:10:35.864569 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-28 12:10:35.865314 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-28 12:10:36.356584 | 
2026-01-28 12:10:36.356689 | PLAY [all]
2026-01-28 12:10:36.374350 | 
2026-01-28 12:10:36.374422 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-01-28 12:10:36.422710 | controller | ok
2026-01-28 12:10:36.427100 | 
2026-01-28 12:10:36.427173 | TASK [mirror-info-fork : Create /etc/ci]
2026-01-28 12:10:36.740241 | controller | changed
2026-01-28 12:10:36.745624 | 
2026-01-28 12:10:36.745697 | TASK [mirror-info-fork : Install ci_mirror script]
2026-01-28 12:10:37.563203 | controller | changed
2026-01-28 12:10:37.571817 | 
2026-01-28 12:10:37.571880 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-01-28 12:10:37.904730 | controller | changed:
2026-01-28 12:10:37.904930 | controller | {
2026-01-28 12:10:37.904958 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-01-28 12:10:37.904990 | controller | }
2026-01-28 12:10:38.092372 | controller | changed:
2026-01-28 12:10:38.092420 | controller | {
2026-01-28 12:10:38.092445 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-01-28 12:10:38.092464 | controller | }
2026-01-28 12:10:38.285813 | controller | changed:
2026-01-28 12:10:38.285860 | controller | {
2026-01-28 12:10:38.285885 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-01-28 12:10:38.285903 | controller | }
2026-01-28 12:10:38.476542 | controller | changed:
2026-01-28 12:10:38.476588 | controller | {
2026-01-28 12:10:38.476612 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-01-28 12:10:38.476630 | controller | }
2026-01-28 12:10:38.664415 | controller | changed:
2026-01-28 12:10:38.664470 | controller | {
2026-01-28 12:10:38.664499 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-01-28 12:10:38.664522 | controller | }
2026-01-28 12:10:38.853564 | controller | changed:
2026-01-28 12:10:38.853628 | controller | {
2026-01-28 12:10:38.853653 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-01-28 12:10:38.853672 | controller | }
2026-01-28 12:10:39.044396 | controller | changed:
2026-01-28 12:10:39.044471 | controller | {
2026-01-28 12:10:39.044495 | 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"
2026-01-28 12:10:39.044514 | controller | }
2026-01-28 12:10:39.235942 | controller | changed:
2026-01-28 12:10:39.236007 | controller | {
2026-01-28 12:10:39.236033 | 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"
2026-01-28 12:10:39.236052 | controller | }
2026-01-28 12:10:39.427463 | controller | changed:
2026-01-28 12:10:39.427523 | controller | {
2026-01-28 12:10:39.427547 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-01-28 12:10:39.427564 | controller | }
2026-01-28 12:10:39.617750 | controller | changed:
2026-01-28 12:10:39.617801 | controller | {
2026-01-28 12:10:39.617827 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-01-28 12:10:39.617846 | controller | }
2026-01-28 12:10:39.816064 | controller | changed:
2026-01-28 12:10:39.816110 | controller | {
2026-01-28 12:10:39.816133 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-01-28 12:10:39.816150 | controller | }
2026-01-28 12:10:40.043785 | controller | changed:
2026-01-28 12:10:40.043846 | controller | {
2026-01-28 12:10:40.043870 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-01-28 12:10:40.043888 | controller | }
2026-01-28 12:10:40.225679 | controller | changed:
2026-01-28 12:10:40.225736 | controller | {
2026-01-28 12:10:40.225765 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2026-01-28 12:10:40.225785 | controller | }
2026-01-28 12:10:40.414065 | controller | changed:
2026-01-28 12:10:40.414116 | controller | {
2026-01-28 12:10:40.414141 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-01-28 12:10:40.414161 | controller | }
2026-01-28 12:10:40.607456 | controller | changed:
2026-01-28 12:10:40.607543 | controller | {
2026-01-28 12:10:40.607570 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-01-28 12:10:40.607590 | controller | }
2026-01-28 12:10:40.813001 | controller | changed:
2026-01-28 12:10:40.813095 | controller | {
2026-01-28 12:10:40.813125 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-01-28 12:10:40.813148 | controller | }
2026-01-28 12:10:41.006079 | controller | changed:
2026-01-28 12:10:41.006146 | controller | {
2026-01-28 12:10:41.006177 | 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"
2026-01-28 12:10:41.006203 | controller | }
2026-01-28 12:10:41.199385 | controller | changed:
2026-01-28 12:10:41.199435 | controller | {
2026-01-28 12:10:41.199458 | 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"
2026-01-28 12:10:41.199484 | controller | }
2026-01-28 12:10:41.397641 | controller | changed:
2026-01-28 12:10:41.397688 | controller | {
2026-01-28 12:10:41.397711 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-01-28 12:10:41.397728 | controller | }
2026-01-28 12:10:41.590598 | controller | changed:
2026-01-28 12:10:41.590644 | controller | {
2026-01-28 12:10:41.590668 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-01-28 12:10:41.590686 | controller | }
2026-01-28 12:10:41.796599 | controller | changed:
2026-01-28 12:10:41.796645 | controller | {
2026-01-28 12:10:41.796668 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-01-28 12:10:41.796685 | controller | }
2026-01-28 12:10:41.983724 | controller | changed:
2026-01-28 12:10:41.983864 | controller | {
2026-01-28 12:10:41.983897 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-01-28 12:10:41.983918 | controller | }
2026-01-28 12:10:42.178142 | controller | changed:
2026-01-28 12:10:42.178213 | controller | {
2026-01-28 12:10:42.178240 | 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"
2026-01-28 12:10:42.178261 | controller | }
2026-01-28 12:10:42.364265 | controller | changed:
2026-01-28 12:10:42.364351 | controller | {
2026-01-28 12:10:42.364376 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-01-28 12:10:42.364393 | controller | }
2026-01-28 12:10:42.551976 | controller | changed:
2026-01-28 12:10:42.552077 | controller | {
2026-01-28 12:10:42.552103 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-01-28 12:10:42.552121 | controller | }
2026-01-28 12:10:42.736143 | controller | changed:
2026-01-28 12:10:42.736216 | controller | {
2026-01-28 12:10:42.736242 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-01-28 12:10:42.736261 | controller | }
2026-01-28 12:10:42.750782 | 
2026-01-28 12:10:42.750890 | TASK [Set timezone to UTC]
2026-01-28 12:10:43.105137 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-01-28 12:10:43.109335 | 
2026-01-28 12:10:43.109400 | TASK [Create nodepool directory]
2026-01-28 12:10:43.300942 | controller | changed
2026-01-28 12:10:43.305868 | 
2026-01-28 12:10:43.305927 | TASK [Create nodepool sub_nodes file]
2026-01-28 12:10:43.769109 | controller | changed
2026-01-28 12:10:43.773684 | 
2026-01-28 12:10:43.773760 | TASK [Create nodepool sub_nodes_private file]
2026-01-28 12:10:44.227767 | controller | changed
2026-01-28 12:10:44.232680 | 
2026-01-28 12:10:44.232740 | LOOP [Populate nodepool sub_nodes file]
2026-01-28 12:10:44.261338 | 
2026-01-28 12:10:44.261451 | LOOP [Populate nodepool sub_nodes_private file]
2026-01-28 12:10:44.290357 | 
2026-01-28 12:10:44.290473 | TASK [Create nodepool primary file]
2026-01-28 12:10:44.313702 | controller | skipping: Conditional result was False
2026-01-28 12:10:44.318810 | 
2026-01-28 12:10:44.318881 | TASK [Create nodepool node_private for this node]
2026-01-28 12:10:44.814852 | controller | changed
2026-01-28 12:10:44.819667 | 
2026-01-28 12:10:44.819723 | LOOP [Copy ssh keys to nodepool directory]
2026-01-28 12:10:45.113864 | controller | ok: Item: id_rsa Runtime: 0:00:00.004468
2026-01-28 12:10:45.114019 | 
2026-01-28 12:10:45.261111 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.003655
2026-01-28 12:10:45.273729 | 
2026-01-28 12:10:45.273839 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-01-28 12:10:45.768972 | controller | changed
2026-01-28 12:10:45.773422 | 
2026-01-28 12:10:45.773481 | TASK [Validate sudoers config after edits]
2026-01-28 12:10:45.965249 | controller | /etc/sudoers: parsed OK
2026-01-28 12:10:45.965342 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-01-28 12:10:45.965352 | controller | /etc/sudoers.d/zuul: parsed OK
2026-01-28 12:10:45.965359 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-01-28 12:10:46.297475 | controller | ok: Runtime: 0:00:00.004949
2026-01-28 12:10:46.302504 | 
2026-01-28 12:10:46.302562 | TASK [Show the environment passed in to job shell scripts]
2026-01-28 12:10:46.487056 | controller | SHELL=/bin/bash
2026-01-28 12:10:46.487104 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-01-28 12:10:46.487113 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-01-28 12:10:46.487121 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/34/3634/cbb5049f33fa8eaa6cbff0636ad689936e000505
2026-01-28 12:10:46.487127 | controller | PWD=/home/zuul
2026-01-28 12:10:46.487132 | controller | ZUUL_PIPELINE=github-check
2026-01-28 12:10:46.487138 | controller | LOGNAME=zuul
2026-01-28 12:10:46.487143 | controller | XDG_SESSION_TYPE=tty
2026-01-28 12:10:46.487180 | controller | _=/usr/bin/env
2026-01-28 12:10:46.487188 | controller | MOTD_SHOWN=pam
2026-01-28 12:10:46.487194 | controller | HOME=/home/zuul
2026-01-28 12:10:46.487199 | controller | LANG=en_US.UTF-8
2026-01-28 12:10:46.487204 | controller | SSH_CONNECTION=192.168.26.12 53478 192.168.26.130 22
2026-01-28 12:10:46.487210 | 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
2026-01-28 12:10:46.487218 | controller | ZUUL_CHANGE_IDS=3634,cbb5049f33fa8eaa6cbff0636ad689936e000505
2026-01-28 12:10:46.487223 | controller | WORKSPACE=/home/zuul/workspace
2026-01-28 12:10:46.487229 | controller | XDG_SESSION_CLASS=user
2026-01-28 12:10:46.487234 | controller | SELINUX_ROLE_REQUESTED=
2026-01-28 12:10:46.487239 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-01-28 12:10:46.487245 | controller | USER=zuul
2026-01-28 12:10:46.487250 | controller | ZUUL_VOTING=True
2026-01-28 12:10:46.487255 | controller | BUILD_TIMEOUT=1800000
2026-01-28 12:10:46.487261 | controller | SELINUX_USE_CURRENT_RANGE=
2026-01-28 12:10:46.487266 | controller | SHLVL=1
2026-01-28 12:10:46.487271 | controller | ZUUL_PATCHSET=cbb5049f33fa8eaa6cbff0636ad689936e000505
2026-01-28 12:10:46.487277 | controller | XDG_SESSION_ID=1
2026-01-28 12:10:46.487282 | controller | ZUUL_BRANCH=main
2026-01-28 12:10:46.487287 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-01-28 12:10:46.487292 | controller | SSH_CLIENT=192.168.26.12 53478 22
2026-01-28 12:10:46.487298 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-01-28 12:10:46.487303 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-01-28 12:10:46.487309 | controller | which_declare=declare -f
2026-01-28 12:10:46.487314 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-01-28 12:10:46.487319 | controller | SELINUX_LEVEL_REQUESTED=
2026-01-28 12:10:46.487325 | controller | ZUUL_CHANGE=3634
2026-01-28 12:10:46.487330 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-01-28 12:10:46.487335 | controller | ZUUL_UUID=096787b971c84df3902424a35d2eea36
2026-01-28 12:10:46.487341 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-01-28 12:10:46.487346 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-01-28 12:10:46.487351 | controller | }
2026-01-28 12:10:46.827283 | controller | ok: Runtime: 0:00:00.005551
2026-01-28 12:10:46.832330 | 
2026-01-28 12:10:46.832389 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-01-28 12:10:46.845468 | controller | skipping: Conditional result was False
2026-01-28 12:10:46.850074 | 
2026-01-28 12:10:46.850131 | TASK [Symlink /home/zuul-worker/workspace]
2026-01-28 12:10:47.373360 | controller | skipping: Conditional result was False
2026-01-28 12:10:47.378875 | 
2026-01-28 12:10:47.378938 | TASK [Ensure legacy workspace directory]
2026-01-28 12:10:47.559210 | controller | changed
2026-01-28 12:10:47.575241 | 
2026-01-28 12:10:47.575289 | PLAY RECAP
2026-01-28 12:10:47.575362 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-28 12:10:47.575384 | 
2026-01-28 12:10:47.640088 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-28 12:10:47.640792 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-prepare.yml@main]
2026-01-28 12:10:48.129541 | 
2026-01-28 12:10:48.129639 | PLAY [Run ci/playbooks/e2e-prepare.yml]
2026-01-28 12:10:48.148609 | 
2026-01-28 12:10:48.148677 | TASK [Clone repos in the job workspace]
2026-01-28 12:10:48.166951 | controller | ok
2026-01-28 12:10:48.180942 | 
2026-01-28 12:10:48.181021 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-28 12:10:48.461968 | controller | ok
2026-01-28 12:10:48.467863 | 
2026-01-28 12:10:48.467924 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-28 12:10:50.823615 | controller | Output suppressed because no_log was given
2026-01-28 12:10:50.847895 | 
2026-01-28 12:10:50.847972 | TASK [Create zuul-output directory]
2026-01-28 12:10:51.110900 | controller | ok
2026-01-28 12:10:51.115642 | 
2026-01-28 12:10:51.115702 | TASK [Install required packages]
2026-01-28 12:11:23.808443 | controller | changed
2026-01-28 12:11:23.813341 | 
2026-01-28 12:11:23.813404 | TASK [Output pip related things]
2026-01-28 12:11:24.336748 | controller | pip 21.3.1 from /usr/lib/python3.9/site-packages/pip (python 3.9)
2026-01-28 12:11:24.836892 | controller | changed
2026-01-28 12:11:24.841926 | 
2026-01-28 12:11:24.841994 | TASK [Install requirements]
2026-01-28 12:12:23.791676 | controller | changed
2026-01-28 12:12:23.802926 | 
2026-01-28 12:12:23.802992 | TASK [Add cronjob to trigger job stats]
2026-01-28 12:12:23.836783 | controller | skipping: Conditional result was False
2026-01-28 12:12:23.842225 | 
2026-01-28 12:12:23.842291 | LOOP [Construct project change list]
2026-01-28 12:12:23.885440 | controller | ok:
2026-01-28 12:12:23.885610 | controller | {
2026-01-28 12:12:23.885638 | controller |   "branch": "main",
2026-01-28 12:12:23.885682 | controller |   "change": "3634",
2026-01-28 12:12:23.885708 | controller |   "change_message": "[kustomize_deploy] Add retry for wait conditions\n\nResources may not be immediately available in the API after `oc apply` completes, causing wait commands to fail with NotFound errors. This adds retry logic with 5 attempts and 3-second delays to handle transient errors during resource registration.\r\n\r\nAssisted-By: Claude Code/claude-4.5-sonnet",
2026-01-28 12:12:23.885732 | controller |   "change_url": "https://github.com/openstack-k8s-operators/ci-framework/pull/3634",
2026-01-28 12:12:23.885771 | controller |   "commit_id": "cbb5049f33fa8eaa6cbff0636ad689936e000505",
2026-01-28 12:12:23.885791 | controller |   "patchset": "cbb5049f33fa8eaa6cbff0636ad689936e000505",
2026-01-28 12:12:23.885806 | controller |   "project": {
2026-01-28 12:12:23.885821 | controller |     "canonical_hostname": "github.com",
2026-01-28 12:12:23.885868 | controller |     "canonical_name": "github.com/openstack-k8s-operators/ci-framework",
2026-01-28 12:12:23.885980 | controller |     "name": "openstack-k8s-operators/ci-framework",
2026-01-28 12:12:23.886032 | controller |     "short_name": "ci-framework",
2026-01-28 12:12:23.886061 | controller |     "src_dir": "src/github.com/openstack-k8s-operators/ci-framework"
2026-01-28 12:12:23.886102 | controller |   },
2026-01-28 12:12:23.886119 | controller |   "topic": null
2026-01-28 12:12:23.886133 | controller | }
2026-01-28 12:12:23.931282 | 
2026-01-28 12:12:23.931371 | PLAY [Run ci/playbooks/build_runner_image.yml]
2026-01-28 12:12:23.943546 | 
2026-01-28 12:12:23.943598 | TASK [Filter out host if needed]
2026-01-28 12:12:23.952204 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-01-28 12:12:23.956238 | 
2026-01-28 12:12:23.956290 | TASK [Get git tag for image tagging]
2026-01-28 12:12:24.480664 | controller | skipping: Conditional result was False
2026-01-28 12:12:24.485765 | 
2026-01-28 12:12:24.485832 | TASK [Set openstack-runner image]
2026-01-28 12:12:24.499241 | controller | skipping: Conditional result was False
2026-01-28 12:12:24.504651 | 
2026-01-28 12:12:24.504707 | TASK [Build openstack-runner image]
2026-01-28 12:12:24.517984 | controller | skipping: Conditional result was False
2026-01-28 12:12:24.523228 | 
2026-01-28 12:12:24.523321 | TASK [Push openstack-runner image]
2026-01-28 12:12:24.536500 | controller | skipping: Conditional result was False
2026-01-28 12:12:24.542164 | 
2026-01-28 12:12:24.542231 | TASK [Make sure ci-framework directory exists]
2026-01-28 12:12:24.556038 | controller | skipping: Conditional result was False
2026-01-28 12:12:24.561395 | 
2026-01-28 12:12:24.561459 | TASK [Create EDPM ansible var file]
2026-01-28 12:12:24.575320 | controller | skipping: Conditional result was False
2026-01-28 12:12:24.591654 | 
2026-01-28 12:12:24.591702 | PLAY RECAP
2026-01-28 12:12:24.591743 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0
2026-01-28 12:12:24.591764 | 
2026-01-28 12:12:24.662525 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-prepare.yml@main]
2026-01-28 12:12:24.663314 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-28 12:12:25.129755 | 
2026-01-28 12:12:25.129842 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-01-28 12:12:25.149475 | 
2026-01-28 12:12:25.149546 | TASK [Create zuul-output directory]
2026-01-28 12:12:25.433156 | controller | changed
2026-01-28 12:12:25.437913 | 
2026-01-28 12:12:25.437983 | TASK [Slurp Zuul inventory test]
2026-01-28 12:12:25.694528 | controller -> localhost | ok
2026-01-28 12:12:25.699845 | 
2026-01-28 12:12:25.699905 | TASK [Save zuul inventory]
2026-01-28 12:12:26.387503 | controller | changed
2026-01-28 12:12:26.392255 | 
2026-01-28 12:12:26.392337 | TASK [Save zuul vars without the change_message]
2026-01-28 12:12:26.926162 | controller | changed
2026-01-28 12:12:26.944107 | 
2026-01-28 12:12:26.944159 | PLAY RECAP
2026-01-28 12:12:26.944200 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-28 12:12:26.944222 | 
2026-01-28 12:12:27.005170 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-28 12:12:27.005984 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/architecture/run.yml@main]
2026-01-28 12:12:27.466802 | 
2026-01-28 12:12:27.466891 | PLAY [Run ci/playbooks/architecture/validate-architecture.yml]
2026-01-28 12:12:27.484768 | 
2026-01-28 12:12:27.484834 | TASK [Filter out host if needed]
2026-01-28 12:12:27.492502 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-01-28 12:12:27.496917 | 
2026-01-28 12:12:27.496991 | TASK [Run playbook]
2026-01-28 12:12:28.201526 | controller |
2026-01-28 12:12:28.940851 | controller | PLAY [Test architecture automations] *******************************************
2026-01-28 12:12:28.940886 | controller |
2026-01-28 12:12:28.940894 | controller | TASK [Gathering Facts ] ********************************************************
2026-01-28 12:12:28.940900 | controller | Wednesday 28 January 2026  12:12:28 +0000 (0:00:00.024)       0:00:00.024 *****
2026-01-28 12:12:28.940905 | controller | Wednesday 28 January 2026  12:12:28 +0000 (0:00:00.023)       0:00:00.023 *****
2026-01-28 12:12:28.940915 | controller | ok: [localhost]
2026-01-28 12:12:28.971671 | controller |
2026-01-28 12:12:28.971694 | controller | TASK [Assert we have the bare minimum to run quiet=True, that=['cifmw_architecture_repo is defined', 'cifmw_architecture_scenario is defined', 'cifmw_networking_mapper_networking_env_def_path is defined']] ***
2026-01-28 12:12:28.971707 | controller | Wednesday 28 January 2026  12:12:28 +0000 (0:00:00.739)       0:00:00.764 *****
2026-01-28 12:12:28.971713 | controller | Wednesday 28 January 2026  12:12:28 +0000 (0:00:00.739)       0:00:00.762 *****
2026-01-28 12:12:28.971723 | controller | ok: [localhost]
2026-01-28 12:12:29.001864 | controller |
2026-01-28 12:12:29.001884 | controller | TASK [ci_setup : Gather variables for each operating system _raw_params={{ item }}] ***
2026-01-28 12:12:29.001891 | controller | Wednesday 28 January 2026  12:12:28 +0000 (0:00:00.030)       0:00:00.794 *****
2026-01-28 12:12:29.001897 | controller | Wednesday 28 January 2026  12:12:28 +0000 (0:00:00.030)       0:00:00.793 *****
2026-01-28 12:12:29.001906 | controller | ok: [localhost] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2026-01-28 12:12:29.020023 | controller |
2026-01-28 12:12:29.020045 | controller | TASK [ci_setup : List packages to install var=cifmw_ci_setup_packages] *********
2026-01-28 12:12:29.020053 | controller | Wednesday 28 January 2026  12:12:28 +0000 (0:00:00.030)       0:00:00.825 *****
2026-01-28 12:12:29.020058 | controller | Wednesday 28 January 2026  12:12:28 +0000 (0:00:00.030)       0:00:00.823 *****
2026-01-28 12:12:29.020068 | controller | ok: [localhost] =>
2026-01-28 12:12:32.655057 | controller |     cifmw_ci_setup_packages:
2026-01-28 12:12:32.655095 | controller |     - bash-completion
2026-01-28 12:12:32.655103 | controller |     - ca-certificates
2026-01-28 12:12:32.655109 | controller |     - git-core
2026-01-28 12:12:32.655115 | controller |     - make
2026-01-28 12:12:32.655120 | controller |     - tar
2026-01-28 12:12:32.655125 | controller |     - tmux
2026-01-28 12:12:32.655130 | controller |     - python3-pip
2026-01-28 12:12:32.655135 | controller |
2026-01-28 12:12:32.655140 | controller | TASK [ci_setup : Install needed packages name={{ cifmw_ci_setup_packages }}, state=latest] ***
2026-01-28 12:12:32.655152 | controller | Wednesday 28 January 2026  12:12:29 +0000 (0:00:00.018)       0:00:00.843 *****
2026-01-28 12:12:32.655158 | controller | Wednesday 28 January 2026  12:12:29 +0000 (0:00:00.018)       0:00:00.842 *****
2026-01-28 12:12:32.655169 | controller | changed: [localhost]
2026-01-28 12:12:32.891995 | controller |
2026-01-28 12:12:32.892021 | controller | TASK [ci_setup : Gather version of openshift client _raw_params=oc version --client -o yaml] ***
2026-01-28 12:12:32.892029 | controller | Wednesday 28 January 2026  12:12:32 +0000 (0:00:03.634)       0:00:04.478 *****
2026-01-28 12:12:32.892034 | controller | Wednesday 28 January 2026  12:12:32 +0000 (0:00:03.634)       0:00:04.477 *****
2026-01-28 12:12:32.892044 | controller | ok: [localhost]
2026-01-28 12:12:33.137132 | controller |
2026-01-28 12:12:33.137155 | controller | TASK [ci_setup : Ensure openshift client install path is present path={{ cifmw_ci_setup_oc_install_path }}, state=directory, mode=0755] ***
2026-01-28 12:12:33.137163 | controller | Wednesday 28 January 2026  12:12:32 +0000 (0:00:00.236)       0:00:04.715 *****
2026-01-28 12:12:33.137169 | controller | Wednesday 28 January 2026  12:12:32 +0000 (0:00:00.236)       0:00:04.714 *****
2026-01-28 12:12:33.137181 | controller | changed: [localhost]
2026-01-28 12:12:39.134308 | controller |
2026-01-28 12:12:39.134353 | controller | TASK [ci_setup : Install openshift client src={{ cifmw_ci_setup_openshift_client_download_uri }}/{{ cifmw_ci_setup_openshift_client_version }}/openshift-client-linux.tar.gz, dest={{ cifmw_ci_setup_oc_install_path }}, remote_src=True, mode=0755, creates={{ cifmw_ci_setup_oc_install_path }}/oc] ***
2026-01-28 12:12:39.134364 | controller | Wednesday 28 January 2026  12:12:33 +0000 (0:00:00.245)       0:00:04.960 *****
2026-01-28 12:12:39.134378 | controller | Wednesday 28 January 2026  12:12:33 +0000 (0:00:00.245)       0:00:04.959 *****
2026-01-28 12:12:39.134389 | controller | changed: [localhost]
2026-01-28 12:12:39.157928 | controller |
2026-01-28 12:12:39.157952 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed cifmw_path={{ cifmw_ci_setup_oc_install_path }}:{{ ansible_env.PATH }}, cacheable=True] ***
2026-01-28 12:12:39.157967 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:05.997)       0:00:10.957 *****
2026-01-28 12:12:39.157974 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:05.997)       0:00:10.956 *****
2026-01-28 12:12:39.157986 | controller | skipping: [localhost]
2026-01-28 12:12:39.388008 | controller |
2026-01-28 12:12:39.388040 | controller | TASK [ci_setup : Create completion file] ***************************************
2026-01-28 12:12:39.388048 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:00.023)       0:00:10.981 *****
2026-01-28 12:12:39.388053 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:00.023)       0:00:10.979 *****
2026-01-28 12:12:39.388063 | controller | changed: [localhost]
2026-01-28 12:12:39.606663 | controller |
2026-01-28 12:12:39.606695 | controller | TASK [ci_setup : Source completion from within .bashrc create=True, mode=0644, path={{ ansible_user_dir }}/.bashrc, block=if [ -f ~/.oc_completion ]; then
2026-01-28 12:12:39.606704 | controller |   source ~/.oc_completion
2026-01-28 12:12:39.606711 | controller | fi] ***
2026-01-28 12:12:39.606727 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:00.230)       0:00:11.211 *****
2026-01-28 12:12:39.606733 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:00.230)       0:00:11.210 *****
2026-01-28 12:12:39.606744 | controller | changed: [localhost]
2026-01-28 12:12:39.624075 | controller |
2026-01-28 12:12:39.624104 | controller | TASK [ci_setup : Check rhsm status _raw_params=subscription-manager status] ****
2026-01-28 12:12:39.624115 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:00.218)       0:00:11.429 *****
2026-01-28 12:12:39.624122 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:00.218)       0:00:11.428 *****
2026-01-28 12:12:39.624135 | controller | skipping: [localhost]
2026-01-28 12:12:39.639587 | controller |
2026-01-28 12:12:39.639614 | controller | TASK [ci_setup : Gather the repos to be enabled _repos={{
2026-01-28 12:12:39.639623 | controller |   cifmw_ci_setup_rhel_rhsm_default_repos +
2026-01-28 12:12:39.639631 | controller |   (cifmw_ci_setup_rhel_rhsm_extra_repos | default([]))
2026-01-28 12:12:39.639639 | controller | }}] ***
2026-01-28 12:12:39.639647 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:00.017)       0:00:11.447 *****
2026-01-28 12:12:39.639654 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:00.017)       0:00:11.446 *****
2026-01-28 12:12:39.639666 | controller | skipping: [localhost]
2026-01-28 12:12:39.654977 | controller |
2026-01-28 12:12:39.655003 | controller | TASK [ci_setup : Enabling the required repositories. name={{ item }}, state={{ rhsm_repo_state | default('enabled') }}] ***
2026-01-28 12:12:39.655013 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:00.015)       0:00:11.462 *****
2026-01-28 12:12:39.655021 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:00.015)       0:00:11.461 *****
2026-01-28 12:12:39.655032 | controller | skipping: [localhost]
2026-01-28 12:12:39.669839 | controller |
2026-01-28 12:12:39.669866 | controller | TASK [ci_setup : Get current /etc/redhat-release _raw_params=cat /etc/redhat-release] ***
2026-01-28 12:12:39.669875 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:00.015)       0:00:11.478 *****
2026-01-28 12:12:39.669883 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:00.015)       0:00:11.477 *****
2026-01-28 12:12:39.669895 | controller | skipping: [localhost]
2026-01-28 12:12:39.684814 | controller |
2026-01-28 12:12:39.684839 | controller | TASK [ci_setup : Print current /etc/redhat-release msg={{ _current_rh_release.stdout }}] ***
2026-01-28 12:12:39.684849 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:00.014)       0:00:11.493 *****
2026-01-28 12:12:39.684856 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:00.014)       0:00:11.492 *****
2026-01-28 12:12:39.684868 | controller | skipping: [localhost]
2026-01-28 12:12:39.704014 | controller |
2026-01-28 12:12:39.704037 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum name={{ item.name }}, baseurl={{ item.baseurl }}, description={{ item.description | default(item.name) }}, gpgcheck={{ item.gpgcheck | default(false) }}, enabled=True, state={{ yum_repo_state | default('present') }}] ***
2026-01-28 12:12:39.704052 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:00.014)       0:00:11.508 *****
2026-01-28 12:12:39.704062 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:00.014)       0:00:11.506 *****
2026-01-28 12:12:39.704073 | controller | skipping: [localhost]
2026-01-28 12:12:40.529135 | controller |
2026-01-28 12:12:40.529167 | controller | TASK [ci_setup : Manage directories path={{ item }}, state={{ directory_state }}, mode=0755, owner={{ ansible_user_id }}, group={{ ansible_user_id }}] ***
2026-01-28 12:12:40.529176 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:00.019)       0:00:11.527 *****
2026-01-28 12:12:40.529183 | controller | Wednesday 28 January 2026  12:12:39 +0000 (0:00:00.019)       0:00:11.526 *****
2026-01-28 12:12:40.529193 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2026-01-28 12:12:40.813984 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/logs)
2026-01-28 12:12:40.814016 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/tmp)
2026-01-28 12:12:40.814024 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/volumes)
2026-01-28 12:12:40.814030 | controller | ok: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2026-01-28 12:12:40.814036 | controller |
2026-01-28 12:12:40.814041 | controller | TASK [Create needed directories path={{ item }}, state=directory, mode=0755] ***
2026-01-28 12:12:40.814046 | controller | Wednesday 28 January 2026  12:12:40 +0000 (0:00:00.825)       0:00:12.352 *****
2026-01-28 12:12:40.814051 | controller | Wednesday 28 January 2026  12:12:40 +0000 (0:00:00.825)       0:00:12.351 *****
2026-01-28 12:12:40.814060 | controller | ok: [localhost] => (item=/home/zuul/ci-framework-data/logs)
2026-01-28 12:12:40.945833 | controller | ok: [localhost] => (item=/home/zuul/ci-framework-data/artifacts)
2026-01-28 12:12:40.945865 | controller |
2026-01-28 12:12:40.945873 | controller | TASK [Check if we have a mock file path={{ _mock_file }}] **********************
2026-01-28 12:12:40.945879 | controller | Wednesday 28 January 2026  12:12:40 +0000 (0:00:00.284)       0:00:12.637 *****
2026-01-28 12:12:40.945884 | controller | Wednesday 28 January 2026  12:12:40 +0000 (0:00:00.284)       0:00:12.636 *****
2026-01-28 12:12:40.945894 | controller | ok: [localhost]
2026-01-28 12:12:40.958443 | controller |
2026-01-28 12:12:40.958467 | controller | TASK [Slurp file from remote src={{ _mock_file }}] *****************************
2026-01-28 12:12:40.958475 | controller | Wednesday 28 January 2026  12:12:40 +0000 (0:00:00.131)       0:00:12.769 *****
2026-01-28 12:12:40.958481 | controller | Wednesday 28 January 2026  12:12:40 +0000 (0:00:00.131)       0:00:12.767 *****
2026-01-28 12:12:40.958491 | controller | skipping: [localhost]
2026-01-28 12:12:40.973834 | controller |
2026-01-28 12:12:40.973860 | controller | TASK [Copy file on localhost dest={{ lookup('env', 'HOME') ~ '/' ~ cifmw_architecture_scenario ~ '.yml' }}, mode=0644, content={{ _mock_content.content | b64decode }}] ***
2026-01-28 12:12:40.973873 | controller | Wednesday 28 January 2026  12:12:40 +0000 (0:00:00.012)       0:00:12.781 *****
2026-01-28 12:12:40.973880 | controller | Wednesday 28 January 2026  12:12:40 +0000 (0:00:00.012)       0:00:12.780 *****
2026-01-28 12:12:40.973891 | controller | skipping: [localhost]
2026-01-28 12:12:40.986139 | controller |
2026-01-28 12:12:40.986160 | controller | TASK [Include var file file={{ lookup('env', 'HOME') ~ '/' ~ cifmw_architecture_scenario ~ '.yml' }}] ***
2026-01-28 12:12:40.986167 | controller | Wednesday 28 January 2026  12:12:40 +0000 (0:00:00.015)       0:00:12.797 *****
2026-01-28 12:12:40.986173 | controller | Wednesday 28 January 2026  12:12:40 +0000 (0:00:00.015)       0:00:12.796 *****
2026-01-28 12:12:40.986183 | controller | skipping: [localhost]
2026-01-28 12:12:41.234387 | controller |
2026-01-28 12:12:41.234439 | controller | TASK [kustomize_deploy : Ensure that architecture repo exists repo={{ cifmw_kustomize_deploy_architecture_repo_url }}, dest={{ cifmw_kustomize_deploy_architecture_repo_dest_dir }}, update=False, version={{ cifmw_kustomize_deploy_architecture_repo_version }}] ***
2026-01-28 12:12:41.234448 | controller | Wednesday 28 January 2026  12:12:40 +0000 (0:00:00.012)       0:00:12.809 *****
2026-01-28 12:12:41.234460 | controller | Wednesday 28 January 2026  12:12:40 +0000 (0:00:00.012)       0:00:12.808 *****
2026-01-28 12:12:41.234470 | controller | ok: [localhost]
2026-01-28 12:12:41.486238 | controller |
2026-01-28 12:12:41.486275 | controller | TASK [kustomize_deploy : Gather the list of scenario folders paths={{ _va_scenario_dir }}, patterns=kustomization.y*ml, recurse=True] ***
2026-01-28 12:12:41.486285 | controller | Wednesday 28 January 2026  12:12:41 +0000 (0:00:00.248)       0:00:13.057 *****
2026-01-28 12:12:41.486293 | controller | Wednesday 28 January 2026  12:12:41 +0000 (0:00:00.248)       0:00:13.056 *****
2026-01-28 12:12:41.486305 | controller | ok: [localhost]
2026-01-28 12:12:41.503376 | controller |
2026-01-28 12:12:41.503401 | controller | TASK [kustomize_deploy : Check if scenario is in the list msg=You need to properly set the `cifmw_architecture_scenario` variable in order to select the architecture-based scenario to deploy. You can take a list of scenario in the `examples/va` folder in the architecture repo.
2026-01-28 12:12:41.503412 | controller | ] ***
2026-01-28 12:12:41.503419 | controller | Wednesday 28 January 2026  12:12:41 +0000 (0:00:00.251)       0:00:13.309 *****
2026-01-28 12:12:41.503425 | controller | Wednesday 28 January 2026  12:12:41 +0000 (0:00:00.251)       0:00:13.308 *****
2026-01-28 12:12:41.503434 | controller | skipping: [localhost]
2026-01-28 12:12:41.646949 | controller |
2026-01-28 12:12:41.646983 | controller | TASK [kustomize_deploy : Ensure that destination directory exists path={{ cifmw_kustomize_deploy_kustomizations_dest_dir }}, mode=0755, state=directory] ***
2026-01-28 12:12:41.646992 | controller | Wednesday 28 January 2026  12:12:41 +0000 (0:00:00.017)       0:00:13.326 *****
2026-01-28 12:12:41.646998 | controller | Wednesday 28 January 2026  12:12:41 +0000 (0:00:00.017)       0:00:13.325 *****
2026-01-28 12:12:41.647010 | controller | changed: [localhost]
2026-01-28 12:12:41.700571 | controller |
2026-01-28 12:12:41.700602 | controller | TASK [kustomize_deploy : Set the final cifmw_architecture_user_kustomize based on its patches _cifmw_kustomize_deploy_user_kustomize={{
2026-01-28 12:12:41.700612 | controller |   _cifmw_kustomize_deploy_user_kustomize |
2026-01-28 12:12:41.700623 | controller |   default({}) |
2026-01-28 12:12:41.700629 | controller |   combine(item.value, recursive=True)
2026-01-28 12:12:41.700636 | controller | }}] ***
2026-01-28 12:12:41.700643 | controller | Wednesday 28 January 2026  12:12:41 +0000 (0:00:00.143)       0:00:13.470 *****
2026-01-28 12:12:41.700651 | controller | Wednesday 28 January 2026  12:12:41 +0000 (0:00:00.143)       0:00:13.468 *****
2026-01-28 12:12:41.700662 | controller | ok: [localhost] => (item=cifmw_architecture_user_kustomize)
2026-01-28 12:12:41.760516 | controller |
2026-01-28 12:12:41.760559 | controller | TASK [kustomize_deploy : Set the final cifmw_architecture_user_kustomize_base64 based on its patches _cifmw_kustomize_deploy_user_base64_kustomize={{
2026-01-28 12:12:41.760571 | controller |   _b64_kustomize_user_patches |
2026-01-28 12:12:41.760578 | controller |   ci_kustomize_deploy_combine_base64_patch_dict
2026-01-28 12:12:41.760584 | controller | }}] ***
2026-01-28 12:12:41.760590 | controller | Wednesday 28 January 2026  12:12:41 +0000 (0:00:00.053)       0:00:13.523 *****
2026-01-28 12:12:41.760596 | controller | Wednesday 28 January 2026  12:12:41 +0000 (0:00:00.053)       0:00:13.522 *****
2026-01-28 12:12:41.760606 | controller | ok: [localhost]
2026-01-28 12:12:41.925970 | controller |
2026-01-28 12:12:41.926003 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence path={{ cifmw_networking_mapper_networking_env_def_path }}] ***
2026-01-28 12:12:41.926011 | controller | Wednesday 28 January 2026  12:12:41 +0000 (0:00:00.059)       0:00:13.583 *****
2026-01-28 12:12:41.926017 | controller | Wednesday 28 January 2026  12:12:41 +0000 (0:00:00.059)       0:00:13.582 *****
2026-01-28 12:12:41.926027 | controller | ok: [localhost]
2026-01-28 12:12:41.954427 | controller |
2026-01-28 12:12:41.954460 | controller | TASK [networking_mapper : Check for Networking Definition file existance that=['_net_env_def_stat.stat.exists'], msg=Ensure that the Networking Environment Definition file exists in {{ cifmw_networking_mapper_networking_env_def_path }}, quiet=True] ***
2026-01-28 12:12:41.954470 | controller | Wednesday 28 January 2026  12:12:41 +0000 (0:00:00.165)       0:00:13.749 *****
2026-01-28 12:12:41.954478 | controller | Wednesday 28 January 2026  12:12:41 +0000 (0:00:00.165)       0:00:13.748 *****
2026-01-28 12:12:41.954496 | controller | ok: [localhost]
2026-01-28 12:12:42.162312 | controller |
2026-01-28 12:12:42.162346 | controller | TASK [networking_mapper : Load the Networking Definition from file path={{ cifmw_networking_mapper_networking_env_def_path }}] ***
2026-01-28 12:12:42.162355 | controller | Wednesday 28 January 2026  12:12:41 +0000 (0:00:00.028)       0:00:13.777 *****
2026-01-28 12:12:42.162362 | controller | Wednesday 28 January 2026  12:12:41 +0000 (0:00:00.028)       0:00:13.776 *****
2026-01-28 12:12:42.162372 | controller | ok: [localhost]
2026-01-28 12:12:42.201514 | controller |
2026-01-28 12:12:42.201556 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present cifmw_networking_env_definition={{
2026-01-28 12:12:42.201570 | controller |   _net_env_def_slurp['content'] |
2026-01-28 12:12:42.201577 | controller |   b64decode |
2026-01-28 12:12:42.201584 | controller |   from_yaml
2026-01-28 12:12:42.201590 | controller | }}, cacheable=True] ***
2026-01-28 12:12:42.201597 | controller | Wednesday 28 January 2026  12:12:42 +0000 (0:00:00.207)       0:00:13.985 *****
2026-01-28 12:12:42.201603 | controller | Wednesday 28 January 2026  12:12:42 +0000 (0:00:00.207)       0:00:13.984 *****
2026-01-28 12:12:42.201614 | controller | ok: [localhost]
2026-01-28 12:12:42.376452 | controller |
2026-01-28 12:12:42.376486 | controller | TASK [Discover automation files paths={{
2026-01-28 12:12:42.376496 | controller |   [cifmw_architecture_repo, _automation_relative] |
2026-01-28 12:12:42.376504 | controller |   path_join
2026-01-28 12:12:42.376511 | controller | }}, patterns=*.yaml] ***
2026-01-28 12:12:42.376518 | controller | Wednesday 28 January 2026  12:12:42 +0000 (0:00:00.039)       0:00:14.024 *****
2026-01-28 12:12:42.376525 | controller | Wednesday 28 January 2026  12:12:42 +0000 (0:00:00.039)       0:00:14.023 *****
2026-01-28 12:12:42.376536 | controller | ok: [localhost]
2026-01-28 12:12:47.728725 | controller |
2026-01-28 12:12:47.728757 | controller | TASK [Get automation contents path={{ item.path }}] ****************************
2026-01-28 12:12:47.728766 | controller | Wednesday 28 January 2026  12:12:42 +0000 (0:00:00.174)       0:00:14.199 *****
2026-01-28 12:12:47.728771 | controller | Wednesday 28 January 2026  12:12:42 +0000 (0:00:00.174)       0:00:14.198 *****
2026-01-28 12:12:47.728782 | controller | ok: [localhost] => (item=bgp-l3-xl-adoption.yaml)
2026-01-28 12:12:48.834753 | controller | ok: [localhost] => (item=bgp-l3-xl.yaml)
2026-01-28 12:12:48.834793 | controller | ok: [localhost] => (item=bgp_dt01.yaml)
2026-01-28 12:12:48.834803 | controller | ok: [localhost] => (item=bgp_dt04_ipv6.yaml)
2026-01-28 12:12:48.834810 | controller | ok: [localhost] => (item=bmo01.yaml)
2026-01-28 12:12:48.834817 | controller | ok: [localhost] => (item=dcn.yaml)
2026-01-28 12:12:48.834823 | controller | ok: [localhost] => (item=dz-storage.yaml)
2026-01-28 12:12:48.834830 | controller | ok: [localhost] => (item=hci-adoption.yaml)
2026-01-28 12:12:48.834837 | controller | ok: [localhost] => (item=hci.yaml)
2026-01-28 12:12:48.834843 | controller | ok: [localhost] => (item=multi-namespace.yaml)
2026-01-28 12:12:48.834850 | controller | ok: [localhost] => (item=nfv-ovs-dpdk-sriov-adoption.yaml)
2026-01-28 12:12:48.834857 | controller | ok: [localhost] => (item=nfv-ovs-dpdk-sriov-hci.yaml)
2026-01-28 12:12:48.834864 | controller | ok: [localhost] => (item=nova-three-cells.yaml)
2026-01-28 12:12:48.834871 | controller | ok: [localhost] => (item=nova01alpha.yaml)
2026-01-28 12:12:48.834877 | controller | ok: [localhost] => (item=nova02beta.yaml)
2026-01-28 12:12:48.834884 | controller | ok: [localhost] => (item=nova04delta-adoption.yaml)
2026-01-28 12:12:48.834891 | controller | ok: [localhost] => (item=nova04delta.yaml)
2026-01-28 12:12:48.834897 | controller | ok: [localhost] => (item=nvidia-mdev.yaml)
2026-01-28 12:12:48.834904 | controller | ok: [localhost] => (item=nvidia-vfio-passthrough-adoption.yaml)
2026-01-28 12:12:48.834910 | controller | ok: [localhost] => (item=nvidia-vfio-passthrough.yaml)
2026-01-28 12:12:48.834917 | controller | ok: [localhost] => (item=osasinfra-ipv6.yaml)
2026-01-28 12:12:48.834924 | controller | ok: [localhost] => (item=osasinfra.yaml)
2026-01-28 12:12:48.834930 | controller | ok: [localhost] => (item=ovs-dpdk-sriov-2nodesets.yaml)
2026-01-28 12:12:48.834937 | controller | ok: [localhost] => (item=ovs-dpdk-sriov-ipv6-2nodesets.yaml)
2026-01-28 12:12:48.834944 | controller | ok: [localhost] => (item=ovs-dpdk-sriov-ipv6.yaml)
2026-01-28 12:12:48.834951 | controller | ok: [localhost] => (item=ovs-dpdk-sriov-networker.yaml)
2026-01-28 12:12:48.834965 | controller | ok: [localhost] => (item=ovs-dpdk-sriov.yaml)
2026-01-28 12:12:48.834972 | controller | ok: [localhost] => (item=ovs-dpdk.yaml)
2026-01-28 12:12:48.834979 | controller | ok: [localhost] => (item=pidone.yaml)
2026-01-28 12:12:48.834985 | controller | ok: [localhost] => (item=sriov.yaml)
2026-01-28 12:12:48.834992 | controller | ok: [localhost] => (item=uni01alpha-adoption.yaml)
2026-01-28 12:12:48.834999 | controller | ok: [localhost] => (item=uni01alpha.yaml)
2026-01-28 12:12:48.835005 | controller | ok: [localhost] => (item=uni02beta-adoption.yaml)
2026-01-28 12:12:48.835012 | controller | ok: [localhost] => (item=uni02beta.yaml)
2026-01-28 12:12:48.835018 | controller | ok: [localhost] => (item=uni04delta-adoption.yaml)
2026-01-28 12:12:48.835025 | controller | ok: [localhost] => (item=uni04delta-ipv6-adoption.yaml)
2026-01-28 12:12:48.835031 | controller | ok: [localhost] => (item=uni04delta-ipv6.yaml)
2026-01-28 12:12:48.835038 | controller | ok: [localhost] => (item=uni04delta.yaml)
2026-01-28 12:12:48.835045 | controller | ok: [localhost] => (item=uni05epsilon-adoption.yaml)
2026-01-28 12:12:48.835051 | controller | ok: [localhost] => (item=uni05epsilon.yaml)
2026-01-28 12:12:48.835058 | controller | ok: [localhost] => (item=uni06zeta-adoption.yaml)
2026-01-28 12:12:48.835065 | controller | ok: [localhost] => (item=uni06zeta.yaml)
2026-01-28 12:12:48.835071 | controller | ok: [localhost] => (item=uni07eta-adoption.yaml)
2026-01-28 12:12:48.835078 | controller | ok: [localhost] => (item=uni07eta.yaml)
2026-01-28 12:12:48.835085 | controller | ok: [localhost] => (item=uni09iota-adoption.yaml)
2026-01-28 12:12:48.835091 | controller | ok: [localhost] => (item=uni09iota.yaml)
2026-01-28 12:12:48.835098 | controller |
2026-01-28 12:12:48.835105 | controller | TASK [Load automation files as fact vas={{
2026-01-28 12:12:48.835111 | controller |   vas | default({}) |
2026-01-28 12:12:48.835118 | controller |   combine(item.content | b64decode | from_yaml, recursive=true)
2026-01-28 12:12:48.835125 | controller | }}] ***
2026-01-28 12:12:48.835132 | controller | Wednesday 28 January 2026  12:12:47 +0000 (0:00:05.352)       0:00:19.552 *****
2026-01-28 12:12:48.835139 | controller | Wednesday 28 January 2026  12:12:47 +0000 (0:00:05.352)       0:00:19.550 *****
2026-01-28 12:12:48.835152 | controller | ok: [localhost] => (item=bgp-l3-xl-adoption.yaml)
2026-01-28 12:12:48.863035 | controller | ok: [localhost] => (item=bgp-l3-xl.yaml)
2026-01-28 12:12:48.863069 | controller | ok: [localhost] => (item=bgp_dt01.yaml)
2026-01-28 12:12:48.863078 | controller | ok: [localhost] => (item=bgp_dt04_ipv6.yaml)
2026-01-28 12:12:48.863083 | controller | ok: [localhost] => (item=bmo01.yaml)
2026-01-28 12:12:48.863089 | controller | ok: [localhost] => (item=dcn.yaml)
2026-01-28 12:12:48.863094 | controller | ok: [localhost] => (item=dz-storage.yaml)
2026-01-28 12:12:48.863100 | controller | ok: [localhost] => (item=hci-adoption.yaml)
2026-01-28 12:12:48.863105 | controller | ok: [localhost] => (item=hci.yaml)
2026-01-28 12:12:48.863110 | controller | ok: [localhost] => (item=multi-namespace.yaml)
2026-01-28 12:12:48.863119 | controller | ok: [localhost] => (item=nfv-ovs-dpdk-sriov-adoption.yaml)
2026-01-28 12:12:48.863125 | controller | ok: [localhost] => (item=nfv-ovs-dpdk-sriov-hci.yaml)
2026-01-28 12:12:48.863131 | controller | ok: [localhost] => (item=nova-three-cells.yaml)
2026-01-28 12:12:48.863136 | controller | ok: [localhost] => (item=nova01alpha.yaml)
2026-01-28 12:12:48.863141 | controller | ok: [localhost] => (item=nova02beta.yaml)
2026-01-28 12:12:48.863147 | controller | ok: [localhost] => (item=nova04delta-adoption.yaml)
2026-01-28 12:12:48.863152 | controller | ok: [localhost] => (item=nova04delta.yaml)
2026-01-28 12:12:48.863157 | controller | ok: [localhost] => (item=nvidia-mdev.yaml)
2026-01-28 12:12:48.863163 | controller | ok: [localhost] => (item=nvidia-vfio-passthrough-adoption.yaml)
2026-01-28 12:12:48.863168 | controller | ok: [localhost] => (item=nvidia-vfio-passthrough.yaml)
2026-01-28 12:12:48.863173 | controller | ok: [localhost] => (item=osasinfra-ipv6.yaml)
2026-01-28 12:12:48.863178 | controller | ok: [localhost] => (item=osasinfra.yaml)
2026-01-28 12:12:48.863184 | controller | ok: [localhost] => (item=ovs-dpdk-sriov-2nodesets.yaml)
2026-01-28 12:12:48.863189 | controller | ok: [localhost] => (item=ovs-dpdk-sriov-ipv6-2nodesets.yaml)
2026-01-28 12:12:48.863194 | controller | ok: [localhost] => (item=ovs-dpdk-sriov-ipv6.yaml)
2026-01-28 12:12:48.863210 | controller | ok: [localhost] => (item=ovs-dpdk-sriov-networker.yaml)
2026-01-28 12:12:48.863216 | controller | ok: [localhost] => (item=ovs-dpdk-sriov.yaml)
2026-01-28 12:12:48.863226 | controller | ok: [localhost] => (item=ovs-dpdk.yaml)
2026-01-28 12:12:48.863236 | controller | ok: [localhost] => (item=pidone.yaml)
2026-01-28 12:12:48.863242 | controller | ok: [localhost] => (item=sriov.yaml)
2026-01-28 12:12:48.863247 | controller | ok: [localhost] => (item=uni01alpha-adoption.yaml)
2026-01-28 12:12:48.863252 | controller | ok: [localhost] => (item=uni01alpha.yaml)
2026-01-28 12:12:48.863257 | controller | ok: [localhost] => (item=uni02beta-adoption.yaml)
2026-01-28 12:12:48.863263 | controller | ok: [localhost] => (item=uni02beta.yaml)
2026-01-28 12:12:48.863268 | controller | ok: [localhost] => (item=uni04delta-adoption.yaml)
2026-01-28 12:12:48.863273 | controller | ok: [localhost] => (item=uni04delta-ipv6-adoption.yaml)
2026-01-28 12:12:48.863278 | controller | ok: [localhost] => (item=uni04delta-ipv6.yaml)
2026-01-28 12:12:48.863284 | controller | ok: [localhost] => (item=uni04delta.yaml)
2026-01-28 12:12:48.863289 | controller | ok: [localhost] => (item=uni05epsilon-adoption.yaml)
2026-01-28 12:12:48.863294 | controller | ok: [localhost] => (item=uni05epsilon.yaml)
2026-01-28 12:12:48.863299 | controller | ok: [localhost] => (item=uni06zeta-adoption.yaml)
2026-01-28 12:12:48.863305 | controller | ok: [localhost] => (item=uni06zeta.yaml)
2026-01-28 12:12:48.863310 | controller | ok: [localhost] => (item=uni07eta-adoption.yaml)
2026-01-28 12:12:48.863315 | controller | ok: [localhost] => (item=uni07eta.yaml)
2026-01-28 12:12:48.863320 | controller | ok: [localhost] => (item=uni09iota-adoption.yaml)
2026-01-28 12:12:48.863326 | controller | ok: [localhost] => (item=uni09iota.yaml)
2026-01-28 12:12:48.863331 | controller |
2026-01-28 12:12:48.863337 | controller | TASK [Prepare automation data cifmw_deploy_architecture_steps={{ vas['vas'][cifmw_architecture_scenario] }}] ***
2026-01-28 12:12:48.863343 | controller | Wednesday 28 January 2026  12:12:48 +0000 (0:00:01.105)       0:00:20.658 *****
2026-01-28 12:12:48.863348 | controller | Wednesday 28 January 2026  12:12:48 +0000 (0:00:01.105)       0:00:20.656 *****
2026-01-28 12:12:48.863358 | controller | ok: [localhost]
2026-01-28 12:12:49.382137 | controller |
2026-01-28 12:12:49.382170 | controller | TASK [Create needed SSH keypairs comment={{ item.comment }}, path={{ item.path }}, type=ecdsa, size=521] ***
2026-01-28 12:12:49.382178 | controller | Wednesday 28 January 2026  12:12:48 +0000 (0:00:00.028)       0:00:20.686 *****
2026-01-28 12:12:49.382183 | controller | Wednesday 28 January 2026  12:12:48 +0000 (0:00:00.028)       0:00:20.685 *****
2026-01-28 12:12:49.382193 | controller | changed: [localhost] => (item={'comment': 'Nova migration', 'path': '/home/zuul/ci-framework-data/artifacts/ecdsa_nova_migration'})
2026-01-28 12:12:49.659910 | controller | changed: [localhost] => (item={'comment': 'EDPM deploy key', 'path': '/home/zuul/ci-framework-data/artifacts/ecdsa_deploy'})
2026-01-28 12:12:49.659945 | controller |
2026-01-28 12:12:49.659953 | controller | TASK [Load public SSH keys path={{ item }}] ************************************
2026-01-28 12:12:49.659959 | controller | Wednesday 28 January 2026  12:12:49 +0000 (0:00:00.519)       0:00:21.205 *****
2026-01-28 12:12:49.659965 | controller | Wednesday 28 January 2026  12:12:49 +0000 (0:00:00.519)       0:00:21.204 *****
2026-01-28 12:12:49.659975 | controller | ok: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/ecdsa_nova_migration.pub)
2026-01-28 12:12:49.938633 | controller | ok: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/ecdsa_deploy.pub)
2026-01-28 12:12:49.938667 | controller |
2026-01-28 12:12:49.938676 | controller | TASK [Load private SSH keys path={{ item }}] ***********************************
2026-01-28 12:12:49.938682 | controller | Wednesday 28 January 2026  12:12:49 +0000 (0:00:00.277)       0:00:21.483 *****
2026-01-28 12:12:49.938687 | controller | Wednesday 28 January 2026  12:12:49 +0000 (0:00:00.277)       0:00:21.481 *****
2026-01-28 12:12:49.938698 | controller | ok: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/ecdsa_nova_migration)
2026-01-28 12:12:49.965784 | controller | ok: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/ecdsa_deploy)
2026-01-28 12:12:49.965812 | controller |
2026-01-28 12:12:49.965820 | controller | TASK [Generate needed facts out of local files cifmw_ci_gen_kustomize_values_ssh_authorizedkeys={{
2026-01-28 12:12:49.965827 | controller |   _pub_keys.results[1].content | b64decode
2026-01-28 12:12:49.965833 | controller | }}, cifmw_ci_gen_kustomize_values_ssh_private_key={{
2026-01-28 12:12:49.965839 | controller |   _priv_keys.results[1].content | b64decode
2026-01-28 12:12:49.965844 | controller | }}, cifmw_ci_gen_kustomize_values_ssh_public_key={{
2026-01-28 12:12:49.965856 | controller |   _pub_keys.results[1].content | b64decode
2026-01-28 12:12:49.965862 | controller | }}, cifmw_ci_gen_kustomize_values_migration_pub_key={{
2026-01-28 12:12:49.965867 | controller |   _pub_keys.results[0].content | b64decode
2026-01-28 12:12:49.965873 | controller | }}, cifmw_ci_gen_kustomize_values_migration_priv_key={{
2026-01-28 12:12:49.965878 | controller |   _priv_keys.results[0].content | b64decode
2026-01-28 12:12:49.965883 | controller | }}, cifmw_ci_gen_kustomize_values_sshd_ranges={{
2026-01-28 12:12:49.965889 | controller |   [
2026-01-28 12:12:49.965894 | controller |     cifmw_networking_env_definition.networks.ctlplane.network_v4 | default (none),
2026-01-28 12:12:49.965899 | controller |     cifmw_networking_env_definition.networks.ctlplane.network_v6 | default (none)
2026-01-28 12:12:49.965905 | controller |   ] | select()
2026-01-28 12:12:49.965910 | controller | }}] ***
2026-01-28 12:12:49.965920 | controller | Wednesday 28 January 2026  12:12:49 +0000 (0:00:00.278)       0:00:21.761 *****
2026-01-28 12:12:49.965926 | controller | Wednesday 28 January 2026  12:12:49 +0000 (0:00:00.278)       0:00:21.760 *****
2026-01-28 12:12:49.965936 | controller | ok: [localhost]
2026-01-28 12:12:50.013285 | controller |
2026-01-28 12:12:50.013314 | controller | TASK [Execute deployment steps name=kustomize_deploy, tasks_from=execute_step.yml, apply={'tags': ['edpm_deploy']}] ***
2026-01-28 12:12:50.013324 | controller | Wednesday 28 January 2026  12:12:49 +0000 (0:00:00.027)       0:00:21.789 *****
2026-01-28 12:12:50.013332 | controller | Wednesday 28 January 2026  12:12:49 +0000 (0:00:00.027)       0:00:21.787 *****
2026-01-28 12:12:50.013344 | controller | ERROR! We were unable to read either as JSON nor YAML, these are the errors we got from each:
2026-01-28 12:12:50.069292 | controller | JSON: Expecting value: line 1 column 1 (char 0)
2026-01-28 12:12:50.069323 | controller |
2026-01-28 12:12:50.069331 | controller | Syntax Error while loading YAML.
2026-01-28 12:12:50.069338 | controller |   mapping values are not allowed in this context
2026-01-28 12:12:50.069345 | controller |
2026-01-28 12:12:50.069352 | controller | The error appears to be in '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/kustomize_deploy/tasks/execute_step.yml': line 315, column 127, but may
2026-01-28 12:12:50.069360 | controller | be elsewhere in the file depending on the exact syntax problem.
2026-01-28 12:12:50.069367 | controller |
2026-01-28 12:12:50.069373 | controller | The offending line appears to be:
2026-01-28 12:12:50.069380 | controller |
2026-01-28 12:12:50.069388 | controller |                    _wait_result.stderr is search('timed out.*condition.*clusterserviceversions/openstack-operator', ignorecase=True) or
2026-01-28 12:12:50.069395 | controller |                                                                                                                               ^ here
2026-01-28 12:12:50.069402 | controller |
2026-01-28 12:12:50.069408 | controller | There appears to be both 'k=v' shorthand syntax and YAML in this task. Only one syntax may be used.
2026-01-28 12:12:50.547392 | controller | ERROR
2026-01-28 12:12:50.547509 | controller | {
2026-01-28 12:12:50.547537 | controller |   "delta": "0:00:22.309601",
2026-01-28 12:12:50.547554 | controller |   "end": "2026-01-28 12:12:50.069802",
2026-01-28 12:12:50.547570 | controller |   "msg": "non-zero return code",
2026-01-28 12:12:50.547586 | controller |   "rc": 4,
2026-01-28 12:12:50.547600 | controller |   "start": "2026-01-28 12:12:27.760201"
2026-01-28 12:12:50.547615 | controller | }
failure
2026-01-28 12:12:50.550831 | 
2026-01-28 12:12:50.550878 | PLAY RECAP
2026-01-28 12:12:50.550917 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-01-28 12:12:50.550936 | 
2026-01-28 12:12:50.611105 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/architecture/run.yml@main]
2026-01-28 12:12:50.611857 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-28 12:12:51.102464 | 
2026-01-28 12:12:51.102562 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-01-28 12:12:51.122342 | 
2026-01-28 12:12:51.122417 | TASK [Filter out host if needed]
2026-01-28 12:12:51.130667 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-01-28 12:12:51.135073 | 
2026-01-28 12:12:51.135140 | TASK [Ensure file is present]
2026-01-28 12:12:51.418038 | controller | ok
2026-01-28 12:12:51.424018 | 
2026-01-28 12:12:51.424092 | TASK [Manage molecule report file]
2026-01-28 12:12:51.948153 | controller | skipping: Conditional result was False
2026-01-28 12:12:51.953270 | 
2026-01-28 12:12:51.953349 | TASK [Check if we get ci-framework-data basedir]
2026-01-28 12:12:52.124457 | controller | ok
2026-01-28 12:12:52.129061 | 
2026-01-28 12:12:52.129123 | TASK [Create ci-framework-data log directory for zuul]
2026-01-28 12:12:52.400953 | controller | changed
2026-01-28 12:12:52.405618 | 
2026-01-28 12:12:52.405679 | TASK [Copy ci-framework interesting files]
2026-01-28 12:12:52.678953 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-01-28 12:12:52.927253 | controller | changed
2026-01-28 12:12:52.931729 | 
2026-01-28 12:12:52.931791 | TASK [Get SELinux listing]
2026-01-28 12:12:53.453727 | controller | changed
2026-01-28 12:12:53.458658 | 
2026-01-28 12:12:53.458717 | TASK [Generate log index]
2026-01-28 12:12:54.035229 | controller | changed
2026-01-28 12:12:54.039846 | 
2026-01-28 12:12:54.039905 | TASK [Get some env related data]
2026-01-28 12:12:54.539378 | controller | /home/zuul/.local/bin/ansible
2026-01-28 12:12:55.079341 | controller | changed
2026-01-28 12:12:55.083814 | 
2026-01-28 12:12:55.083870 | TASK [Generate list of logs to collect in home directory]
2026-01-28 12:12:55.354786 | controller | ok: All paths examined
2026-01-28 12:12:55.359357 | 
2026-01-28 12:12:55.359422 | LOOP [Copy logs from home directory]
2026-01-28 12:12:55.678762 | controller | changed:
2026-01-28 12:12:55.678906 | controller | {
2026-01-28 12:12:55.678932 | controller |   "atime": 1769602327.5697021,
2026-01-28 12:12:55.678950 | controller |   "ctime": 1769602370.0120792,
2026-01-28 12:12:55.678966 | controller |   "dev": 64513,
2026-01-28 12:12:55.678981 | controller |   "gid": 1000,
2026-01-28 12:12:55.678994 | controller |   "gr_name": "zuul",
2026-01-28 12:12:55.679008 | controller |   "inode": 363055,
2026-01-28 12:12:55.679022 | controller |   "isblk": false,
2026-01-28 12:12:55.679035 | controller |   "ischr": false,
2026-01-28 12:12:55.679058 | controller |   "isdir": false,
2026-01-28 12:12:55.679073 | controller |   "isfifo": false,
2026-01-28 12:12:55.679086 | controller |   "isgid": false,
2026-01-28 12:12:55.679098 | controller |   "islnk": false,
2026-01-28 12:12:55.679110 | controller |   "isreg": true,
2026-01-28 12:12:55.679121 | controller |   "issock": false,
2026-01-28 12:12:55.679133 | controller |   "isuid": false,
2026-01-28 12:12:55.679144 | controller |   "mode": "0644",
2026-01-28 12:12:55.679156 | controller |   "mtime": 1769602370.0120792,
2026-01-28 12:12:55.679167 | controller |   "nlink": 1,
2026-01-28 12:12:55.679180 | controller |   "path": "/home/zuul/ansible.log",
2026-01-28 12:12:55.679197 | controller |   "pw_name": "zuul",
2026-01-28 12:12:55.679210 | controller |   "rgrp": true,
2026-01-28 12:12:55.679222 | controller |   "roth": true,
2026-01-28 12:12:55.679234 | controller |   "rusr": true,
2026-01-28 12:12:55.679247 | controller |   "size": 41109,
2026-01-28 12:12:55.679259 | controller |   "uid": 1000,
2026-01-28 12:12:55.679271 | controller |   "wgrp": false,
2026-01-28 12:12:55.679284 | controller |   "woth": false,
2026-01-28 12:12:55.679318 | controller |   "wusr": true,
2026-01-28 12:12:55.679337 | controller |   "xgrp": false,
2026-01-28 12:12:55.679355 | controller |   "xoth": false,
2026-01-28 12:12:55.679369 | controller |   "xusr": false
2026-01-28 12:12:55.679382 | controller | }
2026-01-28 12:12:55.693083 | 
2026-01-28 12:12:55.693145 | TASK [Copy crio stats log file]
2026-01-28 12:12:55.726718 | controller | skipping: Conditional result was False
2026-01-28 12:12:55.749713 | 
2026-01-28 12:12:55.749788 | TASK [Get SELinux related data]
2026-01-28 12:12:55.951677 | controller | <no matches>
2026-01-28 12:12:56.273610 | controller | ERROR
2026-01-28 12:12:56.273757 | controller | {
2026-01-28 12:12:56.273785 | controller |   "delta": "0:00:00.012299",
2026-01-28 12:12:56.273805 | controller |   "end": "2026-01-28 12:12:55.952610",
2026-01-28 12:12:56.273820 | controller |   "msg": "non-zero return code",
2026-01-28 12:12:56.273834 | controller |   "rc": 1,
2026-01-28 12:12:56.273848 | controller |   "start": "2026-01-28 12:12:55.940311"
2026-01-28 12:12:56.273863 | controller | }
2026-01-28 12:12:56.273883 | controller | ERROR: Ignoring Errors
2026-01-28 12:12:56.278461 | 
2026-01-28 12:12:56.278524 | TASK [Create system configuration directory]
2026-01-28 12:12:56.459153 | controller | changed
2026-01-28 12:12:56.463645 | 
2026-01-28 12:12:56.463701 | TASK [Get some of the system configurations]
2026-01-28 12:12:56.986174 | controller | changed
2026-01-28 12:12:56.990727 | 
2026-01-28 12:12:56.990784 | TASK [Copy generated documentation if available]
2026-01-28 12:12:57.003562 | controller | skipping: Conditional result was False
2026-01-28 12:12:57.008340 | 
2026-01-28 12:12:57.008399 | TASK [Copy generated AsciiDoc documentation if available]
2026-01-28 12:12:57.020953 | controller | skipping: Conditional result was False
2026-01-28 12:12:57.025654 | 
2026-01-28 12:12:57.025711 | TASK [Compress logs bigger than 2MB]
2026-01-28 12:12:57.551016 | controller | changed
2026-01-28 12:12:57.555771 | 
2026-01-28 12:12:57.555830 | TASK [Copy files from workspace on node]
2026-01-28 12:12:57.573105 | controller | ok
2026-01-28 12:12:57.592884 | 
2026-01-28 12:12:57.592946 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-28 12:12:57.606350 | controller | skipping: Conditional result was False
2026-01-28 12:12:57.611776 | 
2026-01-28 12:12:57.611836 | TASK [fetch-output : Set log path for single node]
2026-01-28 12:12:57.639628 | controller | ok
2026-01-28 12:12:57.643992 | 
2026-01-28 12:12:57.644060 | LOOP [fetch-output : Ensure local output dirs]
2026-01-28 12:12:57.818704 | controller -> localhost | ok: "/var/lib/zuul/builds/096787b971c84df3902424a35d2eea36/work/logs"
2026-01-28 12:12:57.818911 | controller -> localhost | changed: All items complete
2026-01-28 12:12:57.818938 | 
2026-01-28 12:12:57.979194 | controller -> localhost | changed: "/var/lib/zuul/builds/096787b971c84df3902424a35d2eea36/work/artifacts"
2026-01-28 12:12:58.139385 | controller -> localhost | changed: "/var/lib/zuul/builds/096787b971c84df3902424a35d2eea36/work/docs"
2026-01-28 12:12:58.150438 | 
2026-01-28 12:12:58.150505 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-28 12:12:58.633426 | controller | changed:
2026-01-28 12:12:58.633593 | controller | .d..t...... ./
2026-01-28 12:12:58.633622 | controller | >f+++++++++ README.html
2026-01-28 12:12:58.633641 | controller | >f+++++++++ ansible.log
2026-01-28 12:12:58.633660 | controller | >f+++++++++ dmesg.log
2026-01-28 12:12:58.633679 | controller | >f+++++++++ installed-pkgs.log
2026-01-28 12:12:58.633696 | controller | >f+++++++++ python.log
2026-01-28 12:12:58.633715 | controller | >f+++++++++ registries.conf
2026-01-28 12:12:58.633733 | controller | >f+++++++++ selinux-denials.log
2026-01-28 12:12:58.633750 | controller | >f+++++++++ selinux-listing.log
2026-01-28 12:12:58.633767 | controller | cd+++++++++ ci-framework-data/
2026-01-28 12:12:58.633783 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-01-28 12:12:58.633799 | controller | >f+++++++++ ci-framework-data/artifacts/ecdsa_deploy
2026-01-28 12:12:58.633814 | controller | >f+++++++++ ci-framework-data/artifacts/ecdsa_deploy.pub
2026-01-28 12:12:58.633832 | controller | >f+++++++++ ci-framework-data/artifacts/ecdsa_nova_migration
2026-01-28 12:12:58.633848 | controller | >f+++++++++ ci-framework-data/artifacts/ecdsa_nova_migration.pub
2026-01-28 12:12:58.633862 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-01-28 12:12:58.633876 | controller | cd+++++++++ ci-framework-data/artifacts/kustomize_deploy/
2026-01-28 12:12:58.633890 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2026-01-28 12:12:58.633903 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2026-01-28 12:12:58.633916 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2026-01-28 12:12:58.633929 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-01-28 12:12:58.633942 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-01-28 12:12:58.633956 | controller | cd+++++++++ ci-framework-data/logs/
2026-01-28 12:12:58.633970 | controller | cd+++++++++ registries.conf.d/
2026-01-28 12:12:58.633983 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-01-28 12:12:58.633996 | controller | cd+++++++++ system-config/
2026-01-28 12:12:58.634009 | controller | cd+++++++++ system-config/libvirt/
2026-01-28 12:12:58.634023 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-01-28 12:12:58.634036 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-01-28 12:12:58.990069 | controller | changed: .d..t...... ./
2026-01-28 12:12:59.333650 | controller | changed: .d..t...... ./
2026-01-28 12:12:59.349847 | 
2026-01-28 12:12:59.349943 | TASK [Return artifact to Zuul]
2026-01-28 12:12:59.363468 | controller | skipping: Conditional result was False
2026-01-28 12:12:59.381869 | 
2026-01-28 12:12:59.381924 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-01-28 12:12:59.382016 | 
2026-01-28 12:12:59.382053 | PLAY RECAP
2026-01-28 12:12:59.382093 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1
2026-01-28 12:12:59.382115 | 
2026-01-28 12:12:59.449851 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-28 12:12:59.450576 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-28 12:12:59.914600 | 
2026-01-28 12:12:59.914722 | PLAY [all]
2026-01-28 12:12:59.931631 | 
2026-01-28 12:12:59.931702 | TASK [include_role : fetch-output]
2026-01-28 12:12:59.959511 | controller | ok
2026-01-28 12:12:59.974471 | 
2026-01-28 12:12:59.974546 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-28 12:13:00.008065 | controller | skipping: Conditional result was False
2026-01-28 12:13:00.013013 | 
2026-01-28 12:13:00.013090 | TASK [fetch-output : Set log path for single node]
2026-01-28 12:13:00.040530 | controller | ok
2026-01-28 12:13:00.044885 | 
2026-01-28 12:13:00.044949 | LOOP [fetch-output : Ensure local output dirs]
2026-01-28 12:13:00.339577 | controller -> localhost | ok: "/var/lib/zuul/builds/096787b971c84df3902424a35d2eea36/work/logs"
2026-01-28 12:13:00.507118 | controller -> localhost | ok: "/var/lib/zuul/builds/096787b971c84df3902424a35d2eea36/work/artifacts"
2026-01-28 12:13:00.670749 | controller -> localhost | ok: "/var/lib/zuul/builds/096787b971c84df3902424a35d2eea36/work/docs"
2026-01-28 12:13:00.681086 | 
2026-01-28 12:13:00.681182 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-28 12:13:01.162345 | controller | ok
2026-01-28 12:13:01.162513 | controller | ok: All items complete
2026-01-28 12:13:01.162543 | 
2026-01-28 12:13:01.507476 | controller | ok
2026-01-28 12:13:01.849924 | controller | ok
2026-01-28 12:13:01.870087 | 
2026-01-28 12:13:01.870189 | TASK [include_role : fetch-output-openshift]
2026-01-28 12:13:01.883478 | controller | skipping: Conditional result was False
2026-01-28 12:13:01.889157 | 
2026-01-28 12:13:01.889230 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-28 12:13:02.194621 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.005362
2026-01-28 12:13:02.366081 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005866
2026-01-28 12:13:02.391908 | 
2026-01-28 12:13:02.391987 | PLAY [all]
2026-01-28 12:13:02.405179 | 
2026-01-28 12:13:02.405254 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-01-28 12:13:02.788267 | controller | changed
2026-01-28 12:13:02.809378 | 
2026-01-28 12:13:02.809454 | PLAY RECAP
2026-01-28 12:13:02.809500 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-01-28 12:13:02.809521 | 
2026-01-28 12:13:02.879270 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-28 12:13:02.879989 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-01-28 12:13:03.395553 | 
2026-01-28 12:13:03.395689 | PLAY [localhost]
2026-01-28 12:13:03.412636 | 
2026-01-28 12:13:03.412714 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-28 12:13:03.703238 | localhost | changed
2026-01-28 12:13:03.707268 | 
2026-01-28 12:13:03.707355 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-28 12:13:03.724532 | localhost | ok
2026-01-28 12:13:03.731989 | 
2026-01-28 12:13:03.732069 | TASK [add-fileserver : Create SSH private key tempfile]
2026-01-28 12:13:04.002014 | localhost | changed
2026-01-28 12:13:04.007590 | 
2026-01-28 12:13:04.007669 | TASK [add-fileserver : Create SSH private key from secret]
2026-01-28 12:13:04.510232 | localhost | changed
2026-01-28 12:13:04.514632 | 
2026-01-28 12:13:04.514697 | TASK [add-fileserver : Add fileserver ssh key]
2026-01-28 12:13:04.835228 | localhost | Identity added: /var/lib/zuul/builds/096787b971c84df3902424a35d2eea36/work/tmp/ansible.l_iv64u_ (/var/lib/zuul/builds/096787b971c84df3902424a35d2eea36/work/tmp/ansible.l_iv64u_)
2026-01-28 12:13:04.835399 | localhost | ok: Runtime: 0:00:00.005921
2026-01-28 12:13:04.839577 | 
2026-01-28 12:13:04.839646 | TASK [add-fileserver : Remove SSH private key from disk]
2026-01-28 12:13:05.049010 | localhost | ok: Runtime: 0:00:00.004006
2026-01-28 12:13:05.053675 | 
2026-01-28 12:13:05.053747 | TASK [add-fileserver : Add fileserver to inventory]
2026-01-28 12:13:05.101256 | localhost | changed
2026-01-28 12:13:05.106093 | 
2026-01-28 12:13:05.106161 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-01-28 12:13:05.406450 | localhost | changed
2026-01-28 12:13:05.424542 | 
2026-01-28 12:13:05.424602 | PLAY [localhost]
2026-01-28 12:13:05.434716 | 
2026-01-28 12:13:05.434777 | TASK [Generate bulk log download script]
2026-01-28 12:13:05.452183 | localhost | ok
2026-01-28 12:13:05.462219 | 
2026-01-28 12:13:05.462282 | TASK [local-log-download : Check API endpoint is defined]
2026-01-28 12:13:05.489221 | localhost | ok: All assertions passed
2026-01-28 12:13:05.493146 | 
2026-01-28 12:13:05.493205 | TASK [local-log-download : Create download script]
2026-01-28 12:13:05.809743 | localhost -> localhost | changed
2026-01-28 12:13:05.817429 | 
2026-01-28 12:13:05.817493 | TASK [Register quick-download link]
2026-01-28 12:13:05.834212 | localhost | ok
2026-01-28 12:13:05.865852 | 
2026-01-28 12:13:05.865913 | PLAY [logserver.rdoproject.org]
2026-01-28 12:13:05.874382 | 
2026-01-28 12:13:05.874441 | TASK [Set zuul-log-path fact]
2026-01-28 12:13:05.889593 | logserver.rdoproject.org | ok
2026-01-28 12:13:05.898033 | 
2026-01-28 12:13:05.898110 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-28 12:13:05.923663 | logserver.rdoproject.org | ok
2026-01-28 12:13:05.928689 | 
2026-01-28 12:13:05.928746 | TASK [upload-logs : Create log directories]
2026-01-28 12:13:07.604017 | logserver.rdoproject.org | changed
2026-01-28 12:13:07.606957 | 
2026-01-28 12:13:07.607015 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-01-28 12:13:07.813538 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004908
2026-01-28 12:13:07.818020 | 
2026-01-28 12:13:07.818092 | TASK [upload-logs : Upload logs to log server]
2026-01-28 12:13:09.340997 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-01-28 12:13:09.343643 | 
2026-01-28 12:13:09.343700 | LOOP [upload-logs : Compress console log and json output]
2026-01-28 12:13:09.381651 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-28 12:13:09.389335 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-28 12:13:09.401252 | 
2026-01-28 12:13:09.401352 | LOOP [upload-logs : Upload compressed console log and json output]
2026-01-28 12:13:09.435041 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-28 12:13:09.435262 | 
2026-01-28 12:13:09.438201 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-28 12:13:09.448803 | 
2026-01-28 12:13:09.448863 | LOOP [upload-logs : Upload console log and json output]