2025-10-08 11:45:40.521793 | Job console starting...
2025-10-08 11:45:40.534311 | Updating repositories
2025-10-08 11:45:40.589209 | Preparing job workspace
2025-10-08 11:45:45.906065 | Running Ansible setup...
2025-10-08 11:45:51.145205 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 11:45:51.780507 | 
2025-10-08 11:45:51.780636 | PLAY [localhost]
2025-10-08 11:45:51.792855 | 
2025-10-08 11:45:51.793008 | TASK [Gathering Facts]
2025-10-08 11:45:52.719316 | localhost | ok
2025-10-08 11:45:52.748173 | 
2025-10-08 11:45:52.748394 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-08 11:45:53.162243 | localhost -> localhost | changed
2025-10-08 11:45:53.167778 | 
2025-10-08 11:45:53.167852 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-08 11:45:54.008853 | localhost -> localhost | changed
2025-10-08 11:45:54.018137 | 
2025-10-08 11:45:54.018259 | TASK [Setup log path fact]
2025-10-08 11:45:54.037523 | localhost | ok
2025-10-08 11:45:54.050334 | 
2025-10-08 11:45:54.050464 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 11:45:54.094520 | localhost | ok
2025-10-08 11:45:54.107083 | 
2025-10-08 11:45:54.107234 | TASK [emit-job-header : Print job information]
2025-10-08 11:45:54.160813 | # Job Information
2025-10-08 11:45:54.161092 | Ansible Version: 2.15.12
2025-10-08 11:45:54.161146 | Job: cifmw-molecule-tofu
2025-10-08 11:45:54.161187 | Pipeline: github-check
2025-10-08 11:45:54.161226 | Executor: ze02.softwarefactory-project.io
2025-10-08 11:45:54.161266 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3237
2025-10-08 11:45:54.161308 | Log URL (when completed): https://logserver.rdoproject.org/ebc/rdoproject.org/ebc0cc789e484eaaa2c1ed28d287b182/
2025-10-08 11:45:54.161350 | Event ID: 920c7930-a408-11f0-96e0-244f77bacc6d
2025-10-08 11:45:54.167900 | 
2025-10-08 11:45:54.168015 | LOOP [emit-job-header : Print node information]
2025-10-08 11:45:54.294364 | localhost | ok:
2025-10-08 11:45:54.294721 | localhost | # Node Information
2025-10-08 11:45:54.294766 | localhost | Inventory Hostname: controller
2025-10-08 11:45:54.294799 | localhost | Hostname: np0005475850
2025-10-08 11:45:54.294828 | localhost | Username: zuul
2025-10-08 11:45:54.294856 | localhost | Distro: CentOS 9
2025-10-08 11:45:54.294882 | localhost | Provider: vexxhost-nodepool-tripleo
2025-10-08 11:45:54.294906 | localhost | Region: RegionOne
2025-10-08 11:45:54.294929 | localhost | Label: centos-9-stream-crc-2-48-0-xl
2025-10-08 11:45:54.294953 | localhost | Product Name: OpenStack Nova
2025-10-08 11:45:54.294976 | localhost | Interface IP: 38.102.83.227
2025-10-08 11:45:54.326735 | 
2025-10-08 11:45:54.326875 | PLAY [all]
2025-10-08 11:45:54.337546 | 
2025-10-08 11:45:54.337759 | TASK [Gather network facts]
2025-10-08 11:45:55.042464 | controller | ok
2025-10-08 11:45:55.071514 | 
2025-10-08 11:45:55.071686 | TASK [include_role : start-zuul-console]
2025-10-08 11:45:55.097694 | controller | ok
2025-10-08 11:45:55.118156 | 
2025-10-08 11:45:55.118301 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-08 11:45:55.695589 | controller | ok
2025-10-08 11:45:55.717308 | 
2025-10-08 11:45:55.717518 | TASK [include_role : add-build-sshkey]
2025-10-08 11:45:55.759710 | controller | ok
2025-10-08 11:45:55.792201 | 
2025-10-08 11:45:55.792398 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-08 11:45:56.125830 | controller -> localhost | ok
2025-10-08 11:45:56.137571 | 
2025-10-08 11:45:56.137742 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-08 11:45:56.169130 | controller | ok
2025-10-08 11:45:56.200981 | controller | included: /var/lib/zuul/builds/ebc0cc789e484eaaa2c1ed28d287b182/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-08 11:45:56.215179 | 
2025-10-08 11:45:56.215315 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-08 11:45:56.969787 | controller -> localhost | Generating public/private rsa key pair.
2025-10-08 11:45:56.970463 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ebc0cc789e484eaaa2c1ed28d287b182/work/ebc0cc789e484eaaa2c1ed28d287b182_id_rsa.
2025-10-08 11:45:56.970569 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ebc0cc789e484eaaa2c1ed28d287b182/work/ebc0cc789e484eaaa2c1ed28d287b182_id_rsa.pub.
2025-10-08 11:45:56.970646 | controller -> localhost | The key fingerprint is:
2025-10-08 11:45:56.970766 | controller -> localhost | SHA256:fcXm9lShs0kn/v3xOAtCrF45mzO8p2z/pfjtGBdW0vo zuul-build-sshkey
2025-10-08 11:45:56.970878 | controller -> localhost | The key's randomart image is:
2025-10-08 11:45:56.970953 | controller -> localhost | +---[RSA 3072]----+
2025-10-08 11:45:56.971021 | controller -> localhost | |               . |
2025-10-08 11:45:56.971087 | controller -> localhost | |             ....|
2025-10-08 11:45:56.971152 | controller -> localhost | |             ==.+|
2025-10-08 11:45:56.971215 | controller -> localhost | |         o  o+*oo|
2025-10-08 11:45:56.971279 | controller -> localhost | |        S + .++o.|
2025-10-08 11:45:56.971342 | controller -> localhost | |         o o .o+o|
2025-10-08 11:45:56.971404 | controller -> localhost | |        ..= . .oE|
2025-10-08 11:45:56.971475 | controller -> localhost | |       . o==.o.O+|
2025-10-08 11:45:56.971543 | controller -> localhost | |        ..*BooB+=|
2025-10-08 11:45:56.971609 | controller -> localhost | +----[SHA256]-----+
2025-10-08 11:45:56.971780 | controller -> localhost | ok: Runtime: 0:00:00.262962
2025-10-08 11:45:56.992348 | 
2025-10-08 11:45:56.992625 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-08 11:45:57.023015 | controller | ok
2025-10-08 11:45:57.045867 | controller | included: /var/lib/zuul/builds/ebc0cc789e484eaaa2c1ed28d287b182/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-08 11:45:57.057695 | 
2025-10-08 11:45:57.057796 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-08 11:45:57.073997 | controller | skipping: Conditional result was False
2025-10-08 11:45:57.082235 | 
2025-10-08 11:45:57.082332 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-08 11:45:57.822707 | controller | changed
2025-10-08 11:45:57.834570 | 
2025-10-08 11:45:57.834750 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-08 11:45:58.251525 | controller | ok
2025-10-08 11:45:58.262859 | 
2025-10-08 11:45:58.262997 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-08 11:46:00.018839 | controller | changed
2025-10-08 11:46:00.024718 | 
2025-10-08 11:46:00.024856 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-08 11:46:01.960917 | controller | changed
2025-10-08 11:46:01.973424 | 
2025-10-08 11:46:01.973564 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-08 11:46:02.001223 | controller | skipping: Conditional result was False
2025-10-08 11:46:02.014088 | 
2025-10-08 11:46:02.014231 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-08 11:46:02.451325 | controller -> localhost | changed
2025-10-08 11:46:02.462497 | 
2025-10-08 11:46:02.462613 | TASK [add-build-sshkey : Add back temp key]
2025-10-08 11:46:02.762535 | controller -> localhost | Identity added: /var/lib/zuul/builds/ebc0cc789e484eaaa2c1ed28d287b182/work/ebc0cc789e484eaaa2c1ed28d287b182_id_rsa (zuul-build-sshkey)
2025-10-08 11:46:02.762762 | controller -> localhost | ok: Runtime: 0:00:00.013313
2025-10-08 11:46:02.768972 | 
2025-10-08 11:46:02.769054 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-08 11:46:03.316165 | controller | ok
2025-10-08 11:46:03.329068 | 
2025-10-08 11:46:03.329212 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-08 11:46:03.366318 | controller | skipping: Conditional result was False
2025-10-08 11:46:03.418707 | 
2025-10-08 11:46:03.418854 | TASK [include_role : validate-host]
2025-10-08 11:46:03.450355 | controller | ok
2025-10-08 11:46:03.479864 | 
2025-10-08 11:46:03.479990 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-08 11:46:03.512116 | controller | ok
2025-10-08 11:46:03.518441 | 
2025-10-08 11:46:03.518524 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-08 11:46:03.787605 | controller -> localhost | ok
2025-10-08 11:46:03.801282 | 
2025-10-08 11:46:03.801419 | TASK [validate-host : Collect information about the host]
2025-10-08 11:46:04.797778 | controller | ok
2025-10-08 11:46:04.811275 | 
2025-10-08 11:46:04.811394 | TASK [validate-host : Sanitize hostname]
2025-10-08 11:46:04.886633 | controller | ok
2025-10-08 11:46:04.894333 | 
2025-10-08 11:46:04.894424 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-08 11:46:05.336687 | controller -> localhost | changed
2025-10-08 11:46:05.344963 | 
2025-10-08 11:46:05.345067 | TASK [validate-host : Collect information about zuul worker]
2025-10-08 11:46:05.917832 | controller | ok
2025-10-08 11:46:05.924446 | 
2025-10-08 11:46:05.924539 | TASK [validate-host : Write out all zuul information for each host]
2025-10-08 11:46:06.376788 | controller -> localhost | changed
2025-10-08 11:46:06.386694 | 
2025-10-08 11:46:06.386764 | TASK [include_role : prepare-workspace-openshift]
2025-10-08 11:46:06.411793 | controller | skipping: Conditional result was False
2025-10-08 11:46:06.417503 | 
2025-10-08 11:46:06.417565 | TASK [include_role : remove-zuul-sshkey]
2025-10-08 11:46:06.442110 | controller | skipping: Conditional result was False
2025-10-08 11:46:06.447976 | 
2025-10-08 11:46:06.448042 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-08 11:46:06.839903 | controller | ok: "logs"
2025-10-08 11:46:06.840254 | controller | ok: All items complete
2025-10-08 11:46:06.840294 | 
2025-10-08 11:46:07.184705 | controller | ok: "artifacts"
2025-10-08 11:46:07.537022 | controller | ok: "docs"
2025-10-08 11:46:07.557575 | 
2025-10-08 11:46:07.557752 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-08 11:46:07.996801 | controller | changed: "logs"
2025-10-08 11:46:08.372944 | controller | changed: "artifacts"
2025-10-08 11:46:08.689009 | controller | changed: "docs"
2025-10-08 11:46:08.748744 | 
2025-10-08 11:46:08.748870 | PLAY RECAP
2025-10-08 11:46:08.748930 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 11:46:08.748967 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 11:46:08.748993 | 
2025-10-08 11:46:08.869312 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 11:46:08.870883 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 11:46:09.492505 | 
2025-10-08 11:46:09.492643 | PLAY [all]
2025-10-08 11:46:09.512739 | 
2025-10-08 11:46:09.512820 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 11:46:09.594172 | controller | ok
2025-10-08 11:46:09.599484 | 
2025-10-08 11:46:09.599564 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 11:46:10.168348 | controller | changed
2025-10-08 11:46:10.174032 | 
2025-10-08 11:46:10.174110 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 11:46:12.318105 | controller | changed
2025-10-08 11:46:12.346272 | 
2025-10-08 11:46:12.346655 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-08 11:46:12.967769 | controller | changed:
2025-10-08 11:46:12.968139 | controller | {
2025-10-08 11:46:12.968224 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-08 11:46:12.968301 | controller | }
2025-10-08 11:46:13.330399 | controller | changed:
2025-10-08 11:46:13.330609 | controller | {
2025-10-08 11:46:13.330761 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-08 11:46:13.330827 | controller | }
2025-10-08 11:46:13.722229 | controller | changed:
2025-10-08 11:46:13.722351 | controller | {
2025-10-08 11:46:13.722387 | 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-08 11:46:13.722416 | controller | }
2025-10-08 11:46:14.146242 | controller | changed:
2025-10-08 11:46:14.146350 | controller | {
2025-10-08 11:46:14.146379 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-08 11:46:14.146398 | controller | }
2025-10-08 11:46:14.590753 | controller | changed:
2025-10-08 11:46:14.590881 | controller | {
2025-10-08 11:46:14.590918 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-08 11:46:14.590946 | controller | }
2025-10-08 11:46:15.019300 | controller | changed:
2025-10-08 11:46:15.019465 | controller | {
2025-10-08 11:46:15.019524 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-08 11:46:15.019569 | controller | }
2025-10-08 11:46:15.422892 | controller | changed:
2025-10-08 11:46:15.423071 | controller | {
2025-10-08 11:46:15.423141 | 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-08 11:46:15.423202 | controller | }
2025-10-08 11:46:15.782590 | controller | changed:
2025-10-08 11:46:15.782741 | controller | {
2025-10-08 11:46:15.782893 | 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-08 11:46:15.782937 | controller | }
2025-10-08 11:46:16.203898 | controller | changed:
2025-10-08 11:46:16.204296 | controller | {
2025-10-08 11:46:16.204336 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-08 11:46:16.204355 | controller | }
2025-10-08 11:46:16.653347 | controller | changed:
2025-10-08 11:46:16.653464 | controller | {
2025-10-08 11:46:16.653488 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-08 11:46:16.653506 | controller | }
2025-10-08 11:46:17.047302 | controller | changed:
2025-10-08 11:46:17.047501 | controller | {
2025-10-08 11:46:17.047532 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-08 11:46:17.047552 | controller | }
2025-10-08 11:46:17.440810 | controller | changed:
2025-10-08 11:46:17.440977 | controller | {
2025-10-08 11:46:17.441028 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-08 11:46:17.441068 | controller | }
2025-10-08 11:46:17.813525 | controller | changed:
2025-10-08 11:46:17.813696 | controller | {
2025-10-08 11:46:17.813738 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-08 11:46:17.813769 | controller | }
2025-10-08 11:46:18.240446 | controller | changed:
2025-10-08 11:46:18.240802 | controller | {
2025-10-08 11:46:18.240878 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-08 11:46:18.240923 | controller | }
2025-10-08 11:46:18.692960 | controller | changed:
2025-10-08 11:46:18.693122 | controller | {
2025-10-08 11:46:18.693174 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-08 11:46:18.693214 | controller | }
2025-10-08 11:46:19.112801 | controller | changed:
2025-10-08 11:46:19.112976 | controller | {
2025-10-08 11:46:19.113028 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-08 11:46:19.113068 | controller | }
2025-10-08 11:46:19.539900 | controller | changed:
2025-10-08 11:46:19.540089 | controller | {
2025-10-08 11:46:19.540154 | 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-08 11:46:19.540204 | controller | }
2025-10-08 11:46:19.979187 | controller | changed:
2025-10-08 11:46:19.979588 | controller | {
2025-10-08 11:46:19.979657 | 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-08 11:46:19.979760 | controller | }
2025-10-08 11:46:20.404008 | controller | changed:
2025-10-08 11:46:20.404215 | controller | {
2025-10-08 11:46:20.404289 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-08 11:46:20.404353 | controller | }
2025-10-08 11:46:20.832950 | controller | changed:
2025-10-08 11:46:20.833139 | controller | {
2025-10-08 11:46:20.833204 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-08 11:46:20.833256 | controller | }
2025-10-08 11:46:21.294242 | controller | changed:
2025-10-08 11:46:21.294400 | controller | {
2025-10-08 11:46:21.294451 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-08 11:46:21.294491 | controller | }
2025-10-08 11:46:21.707712 | controller | changed:
2025-10-08 11:46:21.707880 | controller | {
2025-10-08 11:46:21.707934 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-08 11:46:21.707975 | controller | }
2025-10-08 11:46:22.179751 | controller | changed:
2025-10-08 11:46:22.179907 | controller | {
2025-10-08 11:46:22.179958 | 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-08 11:46:22.180000 | controller | }
2025-10-08 11:46:22.603814 | controller | changed:
2025-10-08 11:46:22.603965 | controller | {
2025-10-08 11:46:22.604012 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-08 11:46:22.604048 | controller | }
2025-10-08 11:46:22.998593 | controller | changed:
2025-10-08 11:46:22.998766 | controller | {
2025-10-08 11:46:22.998804 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-08 11:46:22.998831 | controller | }
2025-10-08 11:46:23.381362 | controller | changed:
2025-10-08 11:46:23.381542 | controller | {
2025-10-08 11:46:23.381597 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-08 11:46:23.381641 | controller | }
2025-10-08 11:46:23.424885 | 
2025-10-08 11:46:23.425092 | TASK [Set timezone to UTC]
2025-10-08 11:46:24.269431 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-08 11:46:24.280283 | 
2025-10-08 11:46:24.280418 | TASK [Create nodepool directory]
2025-10-08 11:46:24.726374 | controller | changed
2025-10-08 11:46:24.737326 | 
2025-10-08 11:46:24.737469 | TASK [Create nodepool sub_nodes file]
2025-10-08 11:46:26.327757 | controller | changed
2025-10-08 11:46:26.340430 | 
2025-10-08 11:46:26.340578 | TASK [Create nodepool sub_nodes_private file]
2025-10-08 11:46:27.879515 | controller | changed
2025-10-08 11:46:27.892157 | 
2025-10-08 11:46:27.892314 | LOOP [Populate nodepool sub_nodes file]
2025-10-08 11:46:27.944586 | 
2025-10-08 11:46:27.944883 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-08 11:46:27.994802 | 
2025-10-08 11:46:27.995066 | TASK [Create nodepool primary file]
2025-10-08 11:46:28.032999 | controller | skipping: Conditional result was False
2025-10-08 11:46:28.048150 | 
2025-10-08 11:46:28.048363 | TASK [Create nodepool node_private for this node]
2025-10-08 11:46:29.953716 | controller | changed
2025-10-08 11:46:29.962155 | 
2025-10-08 11:46:29.962247 | LOOP [Copy ssh keys to nodepool directory]
2025-10-08 11:46:30.566369 | controller | ok: Item: id_rsa Runtime: 0:00:00.010662
2025-10-08 11:46:30.566799 | 
2025-10-08 11:46:30.958434 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.007529
2025-10-08 11:46:30.973220 | 
2025-10-08 11:46:30.973474 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-08 11:46:32.769415 | controller | changed
2025-10-08 11:46:32.781499 | 
2025-10-08 11:46:32.781642 | TASK [Validate sudoers config after edits]
2025-10-08 11:46:33.223210 | controller | /etc/sudoers: parsed OK
2025-10-08 11:46:33.223421 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-08 11:46:33.223436 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-08 11:46:33.223444 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-08 11:46:33.333131 | controller | ok: Runtime: 0:00:00.010450
2025-10-08 11:46:33.347288 | 
2025-10-08 11:46:33.347478 | TASK [Show the environment passed in to job shell scripts]
2025-10-08 11:46:33.803039 | controller | SHELL=/bin/bash
2025-10-08 11:46:33.803137 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-08 11:46:33.803151 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-08 11:46:33.803161 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/37/3237/f73256b19c90fb94359fc527fcfcb675474058ed
2025-10-08 11:46:33.803169 | controller | PWD=/home/zuul
2025-10-08 11:46:33.803177 | controller | ZUUL_PIPELINE=github-check
2025-10-08 11:46:33.803185 | controller | LOGNAME=zuul
2025-10-08 11:46:33.803193 | controller | XDG_SESSION_TYPE=tty
2025-10-08 11:46:33.803201 | controller | _=/usr/bin/env
2025-10-08 11:46:33.803209 | controller | MOTD_SHOWN=pam
2025-10-08 11:46:33.803216 | controller | HOME=/home/zuul
2025-10-08 11:46:33.803224 | controller | LANG=en_US.UTF-8
2025-10-08 11:46:33.803232 | controller | SSH_CONNECTION=38.102.83.114 59106 38.102.83.227 22
2025-10-08 11:46:33.803240 | 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-08 11:46:33.803253 | controller | ZUUL_CHANGE_IDS=3237,f73256b19c90fb94359fc527fcfcb675474058ed
2025-10-08 11:46:33.803261 | controller | WORKSPACE=/home/zuul/workspace
2025-10-08 11:46:33.803270 | controller | XDG_SESSION_CLASS=user
2025-10-08 11:46:33.803278 | controller | SELINUX_ROLE_REQUESTED=
2025-10-08 11:46:33.803285 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-08 11:46:33.803296 | controller | USER=zuul
2025-10-08 11:46:33.803361 | controller | ZUUL_VOTING=True
2025-10-08 11:46:33.803374 | controller | BUILD_TIMEOUT=1800000
2025-10-08 11:46:33.803382 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-08 11:46:33.803389 | controller | SHLVL=1
2025-10-08 11:46:33.803397 | controller | ZUUL_PATCHSET=f73256b19c90fb94359fc527fcfcb675474058ed
2025-10-08 11:46:33.803405 | controller | XDG_SESSION_ID=1
2025-10-08 11:46:33.803413 | controller | ZUUL_BRANCH=main
2025-10-08 11:46:33.803422 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-08 11:46:33.803430 | controller | SSH_CLIENT=38.102.83.114 59106 22
2025-10-08 11:46:33.803438 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-08 11:46:33.803446 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-08 11:46:33.803454 | controller | which_declare=declare -f
2025-10-08 11:46:33.803463 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-08 11:46:33.803471 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-08 11:46:33.803479 | controller | ZUUL_CHANGE=3237
2025-10-08 11:46:33.803487 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-08 11:46:33.803495 | controller | ZUUL_UUID=ebc0cc789e484eaaa2c1ed28d287b182
2025-10-08 11:46:33.803503 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-08 11:46:33.803511 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-08 11:46:33.803518 | controller | }
2025-10-08 11:46:33.903502 | controller | ok: Runtime: 0:00:00.011851
2025-10-08 11:46:33.916716 | 
2025-10-08 11:46:33.916905 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-08 11:46:33.934403 | controller | skipping: Conditional result was False
2025-10-08 11:46:33.948127 | 
2025-10-08 11:46:33.948299 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-08 11:46:34.525781 | controller | skipping: Conditional result was False
2025-10-08 11:46:34.538401 | 
2025-10-08 11:46:34.538599 | TASK [Ensure legacy workspace directory]
2025-10-08 11:46:34.961642 | controller | changed
2025-10-08 11:46:34.981463 | 
2025-10-08 11:46:34.981525 | PLAY RECAP
2025-10-08 11:46:34.981570 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 11:46:34.981590 | 
2025-10-08 11:46:35.085074 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 11:46:35.086710 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 11:46:35.654033 | 
2025-10-08 11:46:35.654150 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-08 11:46:35.675066 | 
2025-10-08 11:46:35.675159 | TASK [Create zuul-output directory]
2025-10-08 11:46:36.182044 | controller | changed
2025-10-08 11:46:36.194381 | 
2025-10-08 11:46:36.194532 | TASK [Slurp Zuul inventory test]
2025-10-08 11:46:36.617880 | controller -> localhost | ok
2025-10-08 11:46:36.627227 | 
2025-10-08 11:46:36.627321 | TASK [Save zuul inventory]
2025-10-08 11:46:38.551863 | controller | changed
2025-10-08 11:46:38.564653 | 
2025-10-08 11:46:38.564819 | TASK [Save zuul vars without the change_message]
2025-10-08 11:46:40.301790 | controller | changed
2025-10-08 11:46:40.347489 | 
2025-10-08 11:46:40.347619 | PLAY RECAP
2025-10-08 11:46:40.347742 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 11:46:40.347787 | 
2025-10-08 11:46:40.469708 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 11:46:40.470514 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 11:46:41.118516 | 
2025-10-08 11:46:41.118636 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-08 11:46:41.141425 | 
2025-10-08 11:46:41.141549 | TASK [Read group_vars]
2025-10-08 11:46:41.164761 | controller | ok
2025-10-08 11:46:41.183568 | 
2025-10-08 11:46:41.183650 | TASK [cifmw_helpers : Check directory is available]
2025-10-08 11:46:41.657278 | controller | ok
2025-10-08 11:46:41.669157 | 
2025-10-08 11:46:41.669292 | TASK [cifmw_helpers : List dir in ansible user home]
2025-10-08 11:46:42.190071 | controller | var one
2025-10-08 11:46:42.193352 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars'$'\n': No such file or directory
2025-10-08 11:46:42.197200 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory
2025-10-08 11:46:42.200308 | controller | ls: cannot access '/root/src/github.com/openstack-k8s-operators/ci-framework/group_vars': Permission denied
2025-10-08 11:46:42.203605 | controller | ls: cannot access '/workspace/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory
2025-10-08 11:46:42.710137 | controller | ERROR
2025-10-08 11:46:42.710381 | controller | {
2025-10-08 11:46:42.710411 | controller |   "delta": "0:00:00.022357",
2025-10-08 11:46:42.710431 | controller |   "end": "2025-10-08 11:46:42.204711",
2025-10-08 11:46:42.710452 | controller |   "msg": "non-zero return code",
2025-10-08 11:46:42.710478 | controller |   "rc": 2,
2025-10-08 11:46:42.710501 | controller |   "start": "2025-10-08 11:46:42.182354"
2025-10-08 11:46:42.710518 | controller | }
2025-10-08 11:46:42.710544 | controller | ERROR: Ignoring Errors
2025-10-08 11:46:42.716981 | 
2025-10-08 11:46:42.717044 | TASK [cifmw_helpers : List available files]
2025-10-08 11:46:43.274913 | controller | skipping: Conditional result was False
2025-10-08 11:46:43.282970 | 
2025-10-08 11:46:43.283058 | LOOP [cifmw_helpers : Read vars]
2025-10-08 11:46:43.316204 | 
2025-10-08 11:46:43.316404 | LOOP [cifmw_helpers : Call task to parse all files as fact]
2025-10-08 11:46:43.356153 | 
2025-10-08 11:46:43.356340 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-08 11:46:43.379644 | controller | ok
2025-10-08 11:46:43.432646 | 
2025-10-08 11:46:43.432790 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 11:46:43.477710 | controller | skipping: Conditional result was False
2025-10-08 11:46:43.484262 | 
2025-10-08 11:46:43.484367 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 11:46:43.980718 | controller | ok
2025-10-08 11:46:43.994542 | 
2025-10-08 11:46:43.995010 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 11:46:45.468554 | controller | ok
2025-10-08 11:46:45.490970 | 
2025-10-08 11:46:45.491109 | TASK [Prepare workspace]
2025-10-08 11:46:45.518720 | controller | ok
2025-10-08 11:46:45.554861 | 
2025-10-08 11:46:45.554952 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-08 11:46:46.092061 | controller | ok
2025-10-08 11:46:46.098201 | 
2025-10-08 11:46:46.098297 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-08 11:46:48.901137 | controller | Output suppressed because no_log was given
2025-10-08 11:46:48.915871 | 
2025-10-08 11:46:48.915971 | LOOP [Create zuul-output directory]
2025-10-08 11:46:49.333203 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-08 11:46:49.333436 | 
2025-10-08 11:46:49.737715 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-08 11:46:49.758298 | 
2025-10-08 11:46:49.758456 | TASK [Install required packages]
2025-10-08 11:47:18.204754 | controller | ok: Nothing to do
2025-10-08 11:47:18.216119 | 
2025-10-08 11:47:18.216254 | TASK [Install venv]
2025-10-08 11:48:21.717165 | controller | changed
2025-10-08 11:48:21.769461 | 
2025-10-08 11:48:21.769574 | PLAY RECAP
2025-10-08 11:48:21.769630 | controller | ok: 9 changed: 4 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-08 11:48:21.769658 | 
2025-10-08 11:48:21.872886 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 11:48:21.873719 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 11:48:22.478258 | 
2025-10-08 11:48:22.478360 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-08 11:48:22.499289 | 
2025-10-08 11:48:22.499363 | TASK [Gather required facts]
2025-10-08 11:48:23.291411 | controller | ok
2025-10-08 11:48:23.304024 | 
2025-10-08 11:48:23.304181 | TASK [Load environment var if instructed to]
2025-10-08 11:48:23.343477 | controller | skipping: Conditional result was False
2025-10-08 11:48:23.357272 | 
2025-10-08 11:48:23.357417 | TASK [Run molecule]
2025-10-08 11:48:24.701163 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-08 11:48:24.776742 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-08 11:48:43.083101 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:48:43.084218 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:48:43.085331 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:48:43.086489 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:48:43.087667 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+f73256b1 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 1.0.0+f73256b1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:48:43.088779 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:48:43.089931 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:48:43.091021 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:48:43.092096 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:48:43.093169 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:48:43.094271 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:48:43.095339 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:48:43.096402 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:48:43.097500 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:48:43.098581 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:48:43.120189 | controller | INFO     Running default > prepare
2025-10-08 11:48:43.937752 | controller |
2025-10-08 11:48:43.937966 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 11:48:43.938164 | controller |
2025-10-08 11:48:43.938395 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 11:48:43.938605 | controller | Wednesday 08 October 2025  11:48:43 +0000 (0:00:00.021)       0:00:00.021 *****
2025-10-08 11:48:45.125571 | controller | ok: [instance]
2025-10-08 11:48:45.125700 | controller |
2025-10-08 11:48:45.125779 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-08 11:48:45.125922 | controller | Wednesday 08 October 2025  11:48:45 +0000 (0:00:01.188)       0:00:01.210 *****
2025-10-08 11:48:45.154520 | controller | skipping: [instance]
2025-10-08 11:48:45.154684 | controller |
2025-10-08 11:48:45.154721 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-08 11:48:45.154816 | controller | Wednesday 08 October 2025  11:48:45 +0000 (0:00:00.028)       0:00:01.239 *****
2025-10-08 11:48:45.229085 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-08 11:48:45.229171 | controller |
2025-10-08 11:48:45.229186 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-08 11:48:45.229199 | controller | Wednesday 08 October 2025  11:48:45 +0000 (0:00:00.074)       0:00:01.313 *****
2025-10-08 11:48:45.630777 | controller | ok: [instance]
2025-10-08 11:48:45.630822 | controller |
2025-10-08 11:48:45.630957 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-08 11:48:45.631065 | controller | Wednesday 08 October 2025  11:48:45 +0000 (0:00:00.401)       0:00:01.715 *****
2025-10-08 11:48:46.167387 | controller | ok: [instance]
2025-10-08 11:48:46.167612 | controller |
2025-10-08 11:48:46.167898 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-08 11:48:46.168169 | controller | Wednesday 08 October 2025  11:48:46 +0000 (0:00:00.535)       0:00:02.251 *****
2025-10-08 11:48:47.331796 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-08 11:48:47.332869 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-08 11:48:47.332952 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-08 11:48:47.332962 | controller |
2025-10-08 11:48:47.332970 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-08 11:48:47.333235 | controller | Wednesday 08 October 2025  11:48:47 +0000 (0:00:01.164)       0:00:03.415 *****
2025-10-08 11:48:47.443955 | controller |
2025-10-08 11:48:48.260881 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-08 11:48:48.260949 | controller | Wednesday 08 October 2025  11:48:47 +0000 (0:00:00.112)       0:00:03.528 *****
2025-10-08 11:48:48.260976 | controller | changed: [instance] => (item=tmp)
2025-10-08 11:48:49.279883 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-08 11:48:49.279948 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-08 11:48:49.279966 | controller |
2025-10-08 11:48:49.279980 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-08 11:48:49.279994 | controller | Wednesday 08 October 2025  11:48:48 +0000 (0:00:00.815)       0:00:04.344 *****
2025-10-08 11:48:49.280018 | controller | ok: [instance]
2025-10-08 11:48:50.595812 | controller |
2025-10-08 11:48:50.595864 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-08 11:48:50.595882 | controller | Wednesday 08 October 2025  11:48:49 +0000 (0:00:01.018)       0:00:05.362 *****
2025-10-08 11:48:50.595899 | controller | changed: [instance]
2025-10-08 11:48:58.739808 | controller |
2025-10-08 11:48:58.739877 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-08 11:48:58.739890 | controller | Wednesday 08 October 2025  11:48:50 +0000 (0:00:01.315)       0:00:06.678 *****
2025-10-08 11:48:58.739905 | controller | changed: [instance]
2025-10-08 11:48:59.648480 | controller |
2025-10-08 11:48:59.648539 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-08 11:48:59.648551 | controller | Wednesday 08 October 2025  11:48:58 +0000 (0:00:08.144)       0:00:14.823 *****
2025-10-08 11:48:59.648565 | controller | changed: [instance]
2025-10-08 11:48:59.672927 | controller |
2025-10-08 11:48:59.672991 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-08 11:48:59.673000 | controller | Wednesday 08 October 2025  11:48:59 +0000 (0:00:00.908)       0:00:15.732 *****
2025-10-08 11:48:59.673015 | controller | skipping: [instance]
2025-10-08 11:48:59.673041 | controller |
2025-10-08 11:48:59.673049 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-08 11:48:59.673058 | controller | Wednesday 08 October 2025  11:48:59 +0000 (0:00:00.025)       0:00:15.757 *****
2025-10-08 11:49:00.498970 | controller | changed: [instance]
2025-10-08 11:49:00.563897 | controller |
2025-10-08 11:49:00.563964 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-08 11:49:00.563985 | controller | Wednesday 08 October 2025  11:49:00 +0000 (0:00:00.824)       0:00:16.582 *****
2025-10-08 11:49:00.564012 | controller | skipping: [instance]
2025-10-08 11:49:00.621857 | controller |
2025-10-08 11:49:00.621925 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-08 11:49:00.621943 | controller | Wednesday 08 October 2025  11:49:00 +0000 (0:00:00.064)       0:00:16.647 *****
2025-10-08 11:49:00.621965 | controller | skipping: [instance]
2025-10-08 11:49:00.622274 | controller |
2025-10-08 11:49:00.622579 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-08 11:49:00.623452 | controller | Wednesday 08 October 2025  11:49:00 +0000 (0:00:00.058)       0:00:16.706 *****
2025-10-08 11:49:00.700390 | controller | skipping: [instance]
2025-10-08 11:49:00.700770 | controller |
2025-10-08 11:49:00.701083 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-08 11:49:00.701381 | controller | Wednesday 08 October 2025  11:49:00 +0000 (0:00:00.078)       0:00:16.784 *****
2025-10-08 11:49:01.322080 | controller | changed: [instance]
2025-10-08 11:49:02.003066 | controller |
2025-10-08 11:49:02.003101 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-08 11:49:02.003108 | controller | Wednesday 08 October 2025  11:49:01 +0000 (0:00:00.619)       0:00:17.404 *****
2025-10-08 11:49:02.003119 | controller | changed: [instance]
2025-10-08 11:49:02.053777 | controller |
2025-10-08 11:49:02.053804 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-08 11:49:02.053814 | controller | Wednesday 08 October 2025  11:49:01 +0000 (0:00:00.682)       0:00:18.087 *****
2025-10-08 11:49:02.053827 | controller | skipping: [instance]
2025-10-08 11:49:02.054336 | controller |
2025-10-08 11:49:02.054376 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-08 11:49:02.106574 | controller | Wednesday 08 October 2025  11:49:02 +0000 (0:00:00.050)       0:00:18.137 *****
2025-10-08 11:49:02.106649 | controller | skipping: [instance]
2025-10-08 11:49:02.156692 | controller |
2025-10-08 11:49:02.156749 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-08 11:49:02.156764 | controller | Wednesday 08 October 2025  11:49:02 +0000 (0:00:00.052)       0:00:18.190 *****
2025-10-08 11:49:02.156918 | controller | skipping: [instance]
2025-10-08 11:49:02.223121 | controller |
2025-10-08 11:49:02.223188 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-08 11:49:02.223209 | controller | Wednesday 08 October 2025  11:49:02 +0000 (0:00:00.049)       0:00:18.240 *****
2025-10-08 11:49:02.223236 | controller | ok: [instance]
2025-10-08 11:49:02.223715 | controller |
2025-10-08 11:49:02.224157 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-08 11:49:02.224608 | controller | Wednesday 08 October 2025  11:49:02 +0000 (0:00:00.066)       0:00:18.307 *****
2025-10-08 11:49:02.286904 | controller | skipping: [instance]
2025-10-08 11:49:02.287228 | controller |
2025-10-08 11:49:02.287542 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-08 11:49:02.287886 | controller | Wednesday 08 October 2025  11:49:02 +0000 (0:00:00.063)       0:00:18.370 *****
2025-10-08 11:49:02.347264 | controller | skipping: [instance]
2025-10-08 11:49:02.347586 | controller |
2025-10-08 11:49:02.347934 | controller | TASK [Download the RPM] ********************************************************
2025-10-08 11:49:02.348236 | controller | Wednesday 08 October 2025  11:49:02 +0000 (0:00:00.060)       0:00:18.431 *****
2025-10-08 11:49:02.397396 | controller | skipping: [instance]
2025-10-08 11:49:02.397768 | controller |
2025-10-08 11:49:02.398079 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-08 11:49:02.398375 | controller | Wednesday 08 October 2025  11:49:02 +0000 (0:00:00.050)       0:00:18.481 *****
2025-10-08 11:49:02.452137 | controller | skipping: [instance]
2025-10-08 11:49:02.452545 | controller |
2025-10-08 11:49:02.452893 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-08 11:49:02.453196 | controller | Wednesday 08 October 2025  11:49:02 +0000 (0:00:00.055)       0:00:18.536 *****
2025-10-08 11:49:02.508368 | controller | skipping: [instance]
2025-10-08 11:49:02.508762 | controller |
2025-10-08 11:49:02.509067 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-08 11:49:02.509358 | controller | Wednesday 08 October 2025  11:49:02 +0000 (0:00:00.056)       0:00:18.593 *****
2025-10-08 11:49:02.550613 | controller | skipping: [instance]
2025-10-08 11:49:02.550939 | controller |
2025-10-08 11:49:02.551191 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-08 11:49:02.551434 | controller | Wednesday 08 October 2025  11:49:02 +0000 (0:00:00.042)       0:00:18.635 *****
2025-10-08 11:49:02.600935 | controller | skipping: [instance]
2025-10-08 11:49:02.601161 | controller |
2025-10-08 11:49:02.601365 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-08 11:49:02.601564 | controller | Wednesday 08 October 2025  11:49:02 +0000 (0:00:00.050)       0:00:18.686 *****
2025-10-08 11:49:02.909512 | controller | ok: [instance]
2025-10-08 11:49:03.265953 | controller |
2025-10-08 11:49:03.266042 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-08 11:49:03.266062 | controller | Wednesday 08 October 2025  11:49:02 +0000 (0:00:00.306)       0:00:18.992 *****
2025-10-08 11:49:03.266085 | controller | changed: [instance]
2025-10-08 11:49:03.266128 | controller |
2025-10-08 11:49:03.266144 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-08 11:49:03.266162 | controller | Wednesday 08 October 2025  11:49:03 +0000 (0:00:00.357)       0:00:19.350 *****
2025-10-08 11:49:03.696870 | controller | changed: [instance]
2025-10-08 11:49:03.697121 | controller |
2025-10-08 11:49:03.697474 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-08 11:49:03.697851 | controller | Wednesday 08 October 2025  11:49:03 +0000 (0:00:00.430)       0:00:19.780 *****
2025-10-08 11:49:03.733075 | controller | skipping: [instance]
2025-10-08 11:49:03.733324 | controller |
2025-10-08 11:49:03.733720 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-08 11:49:03.733999 | controller | Wednesday 08 October 2025  11:49:03 +0000 (0:00:00.036)       0:00:19.817 *****
2025-10-08 11:49:03.774507 | controller | skipping: [instance]
2025-10-08 11:49:03.774825 | controller |
2025-10-08 11:49:03.775112 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-08 11:49:03.775397 | controller | Wednesday 08 October 2025  11:49:03 +0000 (0:00:00.041)       0:00:19.858 *****
2025-10-08 11:49:03.823241 | controller | skipping: [instance]
2025-10-08 11:49:03.823513 | controller |
2025-10-08 11:49:03.823886 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-08 11:49:03.824166 | controller | Wednesday 08 October 2025  11:49:03 +0000 (0:00:00.048)       0:00:19.907 *****
2025-10-08 11:49:03.872206 | controller | skipping: [instance]
2025-10-08 11:49:03.872447 | controller |
2025-10-08 11:49:03.872797 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-08 11:49:03.873106 | controller | Wednesday 08 October 2025  11:49:03 +0000 (0:00:00.048)       0:00:19.956 *****
2025-10-08 11:49:03.931156 | controller | skipping: [instance]
2025-10-08 11:49:03.931401 | controller |
2025-10-08 11:49:03.931716 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-08 11:49:03.932044 | controller | Wednesday 08 October 2025  11:49:03 +0000 (0:00:00.059)       0:00:20.015 *****
2025-10-08 11:49:03.985337 | controller | skipping: [instance]
2025-10-08 11:49:03.985579 | controller |
2025-10-08 11:49:03.985965 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-08 11:49:03.986247 | controller | Wednesday 08 October 2025  11:49:03 +0000 (0:00:00.054)       0:00:20.069 *****
2025-10-08 11:49:04.031159 | controller | skipping: [instance]
2025-10-08 11:49:04.031401 | controller |
2025-10-08 11:49:04.031715 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-08 11:49:04.032077 | controller | Wednesday 08 October 2025  11:49:04 +0000 (0:00:00.045)       0:00:20.115 *****
2025-10-08 11:49:04.103218 | controller | skipping: [instance]
2025-10-08 11:49:04.103328 | controller |
2025-10-08 11:49:04.103561 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-08 11:49:04.103853 | controller | Wednesday 08 October 2025  11:49:04 +0000 (0:00:00.072)       0:00:20.187 *****
2025-10-08 11:49:04.142663 | controller | skipping: [instance]
2025-10-08 11:49:04.142821 | controller |
2025-10-08 11:49:04.143019 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-08 11:49:04.143265 | controller | Wednesday 08 October 2025  11:49:04 +0000 (0:00:00.039)       0:00:20.227 *****
2025-10-08 11:49:04.199552 | controller | skipping: [instance]
2025-10-08 11:49:04.199744 | controller |
2025-10-08 11:49:04.199960 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-08 11:49:04.200178 | controller | Wednesday 08 October 2025  11:49:04 +0000 (0:00:00.056)       0:00:20.283 *****
2025-10-08 11:49:04.263804 | controller | skipping: [instance]
2025-10-08 11:49:04.263859 | controller |
2025-10-08 11:49:04.264004 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-08 11:49:04.264142 | controller | Wednesday 08 October 2025  11:49:04 +0000 (0:00:00.064)       0:00:20.348 *****
2025-10-08 11:49:04.324666 | controller | skipping: [instance]
2025-10-08 11:49:04.324875 | controller |
2025-10-08 11:49:04.324899 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-08 11:49:04.325047 | controller | Wednesday 08 October 2025  11:49:04 +0000 (0:00:00.060)       0:00:20.409 *****
2025-10-08 11:49:04.396026 | controller | skipping: [instance]
2025-10-08 11:49:04.396253 | controller |
2025-10-08 11:49:04.396499 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-08 11:49:04.396795 | controller | Wednesday 08 October 2025  11:49:04 +0000 (0:00:00.071)       0:00:20.480 *****
2025-10-08 11:49:04.443925 | controller | skipping: [instance]
2025-10-08 11:49:04.444063 | controller |
2025-10-08 11:49:04.444215 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-08 11:49:04.444362 | controller | Wednesday 08 October 2025  11:49:04 +0000 (0:00:00.048)       0:00:20.529 *****
2025-10-08 11:49:44.679818 | controller | ok: [instance]
2025-10-08 11:49:46.137401 | controller |
2025-10-08 11:49:46.137446 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-08 11:49:46.137456 | controller | Wednesday 08 October 2025  11:49:44 +0000 (0:00:40.235)       0:01:00.764 *****
2025-10-08 11:49:46.137469 | controller | ok: [instance]
2025-10-08 11:49:46.173870 | controller |
2025-10-08 11:49:46.173926 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-08 11:49:46.173946 | controller | Wednesday 08 October 2025  11:49:46 +0000 (0:00:01.457)       0:01:02.221 *****
2025-10-08 11:49:46.173969 | controller | skipping: [instance]
2025-10-08 11:49:46.173984 | controller |
2025-10-08 11:49:46.173999 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-10-08 11:49:46.174018 | controller | Wednesday 08 October 2025  11:49:46 +0000 (0:00:00.036)       0:01:02.258 *****
2025-10-08 11:49:46.226855 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-10-08 11:49:46.227160 | controller |
2025-10-08 11:49:46.227205 | controller | TASK [ci_setup : List packages to install] *************************************
2025-10-08 11:49:46.249923 | controller | Wednesday 08 October 2025  11:49:46 +0000 (0:00:00.053)       0:01:02.311 *****
2025-10-08 11:49:46.250014 | controller | ok: [instance] =>
2025-10-08 11:49:46.250075 | controller |   cifmw_ci_setup_packages:
2025-10-08 11:49:46.250094 | controller |   - bash-completion
2025-10-08 11:49:46.250108 | controller |   - ca-certificates
2025-10-08 11:49:46.250121 | controller |   - git-core
2025-10-08 11:49:46.250135 | controller |   - make
2025-10-08 11:49:46.250148 | controller |   - tar
2025-10-08 11:49:46.250161 | controller |   - tmux
2025-10-08 11:49:46.250174 | controller |   - python3-pip
2025-10-08 11:49:46.250192 | controller |
2025-10-08 11:49:52.301943 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-10-08 11:49:52.302027 | controller | Wednesday 08 October 2025  11:49:46 +0000 (0:00:00.022)       0:01:02.334 *****
2025-10-08 11:49:52.302054 | controller | changed: [instance]
2025-10-08 11:49:53.584294 | controller |
2025-10-08 11:49:53.584354 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-10-08 11:49:53.584373 | controller | Wednesday 08 October 2025  11:49:52 +0000 (0:00:06.051)       0:01:08.385 *****
2025-10-08 11:49:53.584399 | controller | ok: [instance]
2025-10-08 11:49:53.584454 | controller |
2025-10-08 11:49:53.584476 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-10-08 11:49:53.584530 | controller | Wednesday 08 October 2025  11:49:53 +0000 (0:00:01.282)       0:01:09.668 *****
2025-10-08 11:49:53.853003 | controller | changed: [instance]
2025-10-08 11:49:53.853071 | controller |
2025-10-08 11:49:53.853227 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-10-08 11:49:59.166812 | controller | Wednesday 08 October 2025  11:49:53 +0000 (0:00:00.268)       0:01:09.937 *****
2025-10-08 11:49:59.166882 | controller | changed: [instance]
2025-10-08 11:49:59.166920 | controller |
2025-10-08 11:49:59.166932 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-10-08 11:49:59.166943 | controller | Wednesday 08 October 2025  11:49:59 +0000 (0:00:05.313)       0:01:15.250 *****
2025-10-08 11:49:59.222406 | controller | ok: [instance]
2025-10-08 11:49:59.222809 | controller |
2025-10-08 11:49:59.223258 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-10-08 11:49:59.622735 | controller | Wednesday 08 October 2025  11:49:59 +0000 (0:00:00.056)       0:01:15.307 *****
2025-10-08 11:49:59.622779 | controller | changed: [instance]
2025-10-08 11:50:00.006750 | controller |
2025-10-08 11:50:00.006783 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-10-08 11:50:00.006791 | controller | Wednesday 08 October 2025  11:49:59 +0000 (0:00:00.399)       0:01:15.706 *****
2025-10-08 11:50:00.006802 | controller | changed: [instance]
2025-10-08 11:50:00.045766 | controller |
2025-10-08 11:50:00.045792 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-10-08 11:50:00.045800 | controller | Wednesday 08 October 2025  11:50:00 +0000 (0:00:00.384)       0:01:16.090 *****
2025-10-08 11:50:00.045810 | controller | skipping: [instance]
2025-10-08 11:50:00.045861 | controller |
2025-10-08 11:50:00.045893 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-10-08 11:50:00.045915 | controller | Wednesday 08 October 2025  11:50:00 +0000 (0:00:00.038)       0:01:16.129 *****
2025-10-08 11:50:00.081870 | controller | skipping: [instance]
2025-10-08 11:50:00.082231 | controller |
2025-10-08 11:50:00.082269 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-10-08 11:50:00.119904 | controller | Wednesday 08 October 2025  11:50:00 +0000 (0:00:00.035)       0:01:16.165 *****
2025-10-08 11:50:00.119969 | controller | skipping: [instance]
2025-10-08 11:50:00.156981 | controller |
2025-10-08 11:50:00.157045 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-10-08 11:50:00.157062 | controller | Wednesday 08 October 2025  11:50:00 +0000 (0:00:00.038)       0:01:16.203 *****
2025-10-08 11:50:00.157085 | controller | skipping: [instance]
2025-10-08 11:50:00.157273 | controller |
2025-10-08 11:50:00.157294 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-10-08 11:50:00.157397 | controller | Wednesday 08 October 2025  11:50:00 +0000 (0:00:00.037)       0:01:16.241 *****
2025-10-08 11:50:00.192755 | controller | skipping: [instance]
2025-10-08 11:50:00.192852 | controller |
2025-10-08 11:50:00.193028 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-10-08 11:50:00.193183 | controller | Wednesday 08 October 2025  11:50:00 +0000 (0:00:00.035)       0:01:16.277 *****
2025-10-08 11:50:00.239489 | controller | skipping: [instance]
2025-10-08 11:50:00.239552 | controller |
2025-10-08 11:50:00.239721 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-10-08 11:50:00.239845 | controller | Wednesday 08 October 2025  11:50:00 +0000 (0:00:00.046)       0:01:16.323 *****
2025-10-08 11:50:01.710879 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-10-08 11:50:01.710985 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-10-08 11:50:01.711004 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-10-08 11:50:01.711019 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-10-08 11:50:01.711033 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-10-08 11:50:01.711046 | controller |
2025-10-08 11:50:01.711065 | controller | TASK [Ensure CRC is started] ***************************************************
2025-10-08 11:50:01.711235 | controller | Wednesday 08 October 2025  11:50:01 +0000 (0:00:01.470)       0:01:17.793 *****
2025-10-08 11:59:39.740666 | controller | changed: [instance]
2025-10-08 11:59:39.740786 | controller |
2025-10-08 11:59:39.740798 | controller | PLAY RECAP *********************************************************************
2025-10-08 11:59:39.741284 | controller | instance                   : ok=30   changed=16   unreachable=0    failed=0    skipped=36   rescued=0    ignored=0
2025-10-08 11:59:39.741384 | controller |
2025-10-08 11:59:39.741825 | controller | Wednesday 08 October 2025  11:59:39 +0000 (0:09:38.029)       0:10:55.823 *****
2025-10-08 11:59:39.742174 | controller | ===============================================================================
2025-10-08 11:59:39.742581 | controller | Ensure CRC is started ------------------------------------------------- 578.03s
2025-10-08 11:59:39.742611 | controller | test_deps : Install selinux python libs -------------------------------- 40.24s
2025-10-08 11:59:39.743015 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.14s
2025-10-08 11:59:39.743119 | controller | ci_setup : Install needed packages -------------------------------------- 6.05s
2025-10-08 11:59:39.743350 | controller | ci_setup : Install openshift client ------------------------------------- 5.31s
2025-10-08 11:59:39.743651 | controller | ci_setup : Manage directories ------------------------------------------- 1.47s
2025-10-08 11:59:39.743922 | controller | test_deps : Install python yaml libs ------------------------------------ 1.46s
2025-10-08 11:59:39.744200 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.32s
2025-10-08 11:59:39.744474 | controller | ci_setup : Gather version of openshift client --------------------------- 1.28s
2025-10-08 11:59:39.744850 | controller | Gathering Facts --------------------------------------------------------- 1.19s
2025-10-08 11:59:39.745227 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 1.16s
2025-10-08 11:59:39.745539 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.02s
2025-10-08 11:59:39.745869 | controller | repo_setup : Install repo-setup package --------------------------------- 0.91s
2025-10-08 11:59:39.746219 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.82s
2025-10-08 11:59:39.746592 | controller | repo_setup : Ensure directories are present ----------------------------- 0.82s
2025-10-08 11:59:39.746892 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.68s
2025-10-08 11:59:39.747165 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.62s
2025-10-08 11:59:39.747522 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.54s
2025-10-08 11:59:39.747840 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.43s
2025-10-08 11:59:39.748124 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.40s
2025-10-08 11:59:39.836017 | controller | INFO     Running default > converge
2025-10-08 11:59:40.560544 | controller |
2025-10-08 11:59:40.560950 | controller | PLAY [Converge] ****************************************************************
2025-10-08 11:59:40.561244 | controller |
2025-10-08 11:59:40.561534 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 11:59:40.561868 | controller | Wednesday 08 October 2025  11:59:40 +0000 (0:00:00.022)       0:00:00.022 *****
2025-10-08 11:59:41.758270 | controller | ok: [instance]
2025-10-08 11:59:41.758399 | controller |
2025-10-08 11:59:41.758511 | controller | TASK [tofu : Fail If infrastructure State Is Not Correct] **********************
2025-10-08 11:59:41.758802 | controller | Wednesday 08 October 2025  11:59:41 +0000 (0:00:01.198)       0:00:01.221 *****
2025-10-08 11:59:41.795980 | controller | skipping: [instance]
2025-10-08 11:59:41.796181 | controller |
2025-10-08 11:59:41.796420 | controller | TASK [tofu : Fail If Check Mode Is Not Correct] ********************************
2025-10-08 11:59:41.796695 | controller | Wednesday 08 October 2025  11:59:41 +0000 (0:00:00.037)       0:00:01.259 *****
2025-10-08 11:59:41.853274 | controller | skipping: [instance]
2025-10-08 11:59:41.853422 | controller |
2025-10-08 11:59:41.853672 | controller | TASK [tofu : Fail If Tofu Project Path Not Defined] ****************************
2025-10-08 11:59:41.853893 | controller | Wednesday 08 October 2025  11:59:41 +0000 (0:00:00.057)       0:00:01.316 *****
2025-10-08 11:59:41.908738 | controller | skipping: [instance]
2025-10-08 11:59:41.908892 | controller |
2025-10-08 11:59:41.909108 | controller | TASK [Prepare Remote Environment If Required] **********************************
2025-10-08 11:59:41.909312 | controller | Wednesday 08 October 2025  11:59:41 +0000 (0:00:00.055)       0:00:01.371 *****
2025-10-08 11:59:41.943954 | controller |
2025-10-08 11:59:41.944111 | controller | TASK [tofu : Create Tofu Directory] ********************************************
2025-10-08 11:59:41.944315 | controller | Wednesday 08 October 2025  11:59:41 +0000 (0:00:00.035)       0:00:01.407 *****
2025-10-08 11:59:42.329890 | controller | changed: [instance]
2025-10-08 11:59:42.330653 | controller |
2025-10-08 11:59:42.330680 | controller | TASK [tofu : Copy Local Tufo Project Directory To Remote Host '{{ ansible_host }}'] ***
2025-10-08 11:59:43.148692 | controller | Wednesday 08 October 2025  11:59:42 +0000 (0:00:00.386)       0:00:01.793 *****
2025-10-08 11:59:43.148740 | controller | changed: [instance]
2025-10-08 11:59:43.196798 | controller |
2025-10-08 11:59:43.196834 | controller | TASK [tofu : Update Remote Execution Variable] *********************************
2025-10-08 11:59:43.196842 | controller | Wednesday 08 October 2025  11:59:43 +0000 (0:00:00.817)       0:00:02.610 *****
2025-10-08 11:59:43.196853 | controller | ok: [instance]
2025-10-08 11:59:43.197310 | controller |
2025-10-08 11:59:43.197327 | controller | TASK [tofu : Add OpenTofu repository] ******************************************
2025-10-08 11:59:43.585069 | controller | Wednesday 08 October 2025  11:59:43 +0000 (0:00:00.048)       0:00:02.659 *****
2025-10-08 11:59:43.585110 | controller | changed: [instance]
2025-10-08 11:59:43.585373 | controller |
2025-10-08 11:59:43.585494 | controller | TASK [tofu : Install OpenTofu RPM] *********************************************
2025-10-08 11:59:43.585506 | controller | Wednesday 08 October 2025  11:59:43 +0000 (0:00:00.389)       0:00:03.048 *****
2025-10-08 11:59:49.990507 | controller | changed: [instance]
2025-10-08 11:59:49.990596 | controller |
2025-10-08 11:59:49.990607 | controller | TASK [tofu : Return tofu to registered var] ************************************
2025-10-08 11:59:49.990627 | controller | Wednesday 08 October 2025  11:59:49 +0000 (0:00:06.404)       0:00:09.453 *****
2025-10-08 11:59:50.420717 | controller | changed: [instance]
2025-10-08 11:59:50.420783 | controller |
2025-10-08 11:59:50.420862 | controller | TASK [tofu : Tofu Action] ******************************************************
2025-10-08 11:59:50.420959 | controller | Wednesday 08 October 2025  11:59:50 +0000 (0:00:00.429)       0:00:09.882 *****
2025-10-08 11:59:55.013120 | controller | changed: [instance]
2025-10-08 11:59:55.013269 | controller |
2025-10-08 11:59:55.013484 | controller | TASK [tofu : Summarize Tofu Execution] *****************************************
2025-10-08 11:59:55.013740 | controller | Wednesday 08 October 2025  11:59:55 +0000 (0:00:04.592)       0:00:14.475 *****
2025-10-08 11:59:55.097402 | controller | ok: [instance] =>
2025-10-08 11:59:55.097571 | controller |   msg:
2025-10-08 11:59:55.097884 | controller |   - 'Execution failed: False'
2025-10-08 11:59:55.098755 | controller |   - 'Output:'
2025-10-08 11:59:55.099060 | controller |   - - ''
2025-10-08 11:59:55.099102 | controller |     - OpenTofu used the selected providers to generate the following execution
2025-10-08 11:59:55.099126 | controller |     - 'plan. Resource actions are indicated with the following symbols:'
2025-10-08 11:59:55.099310 | controller |     - '  + create'
2025-10-08 11:59:55.099342 | controller |     - ''
2025-10-08 11:59:55.099572 | controller |     - 'OpenTofu will perform the following actions:'
2025-10-08 11:59:55.099844 | controller |     - ''
2025-10-08 11:59:55.100107 | controller |     - '  # kubernetes_deployment.ubuntu will be created'
2025-10-08 11:59:55.100338 | controller |     - '  + resource "kubernetes_deployment" "ubuntu" {'
2025-10-08 11:59:55.100559 | controller |     - '      + id               = (known after apply)'
2025-10-08 11:59:55.100814 | controller |     - '      + wait_for_rollout = true'
2025-10-08 11:59:55.101091 | controller |     - ''
2025-10-08 11:59:55.101314 | controller |     - '      + metadata {'
2025-10-08 11:59:55.101550 | controller |     - '          + generation       = (known after apply)'
2025-10-08 11:59:55.101794 | controller |     - '          + name             = "ubuntu"'
2025-10-08 11:59:55.102004 | controller |     - '          + namespace        = "tofu-experiements"'
2025-10-08 11:59:55.102302 | controller |     - '          + resource_version = (known after apply)'
2025-10-08 11:59:55.102512 | controller |     - '          + uid              = (known after apply)'
2025-10-08 11:59:55.102787 | controller |     - '        }'
2025-10-08 11:59:55.103027 | controller |     - ''
2025-10-08 11:59:55.103255 | controller |     - '      + spec {'
2025-10-08 11:59:55.103482 | controller |     - '          + min_ready_seconds         = 0'
2025-10-08 11:59:55.103745 | controller |     - '          + paused                    = false'
2025-10-08 11:59:55.104038 | controller |     - '          + progress_deadline_seconds = 600'
2025-10-08 11:59:55.104263 | controller |     - '          + replicas                  = "3"'
2025-10-08 11:59:55.104465 | controller |     - '          + revision_history_limit    = 10'
2025-10-08 11:59:55.104720 | controller |     - ''
2025-10-08 11:59:55.104944 | controller |     - '          + selector {'
2025-10-08 11:59:55.105182 | controller |     - '              + match_labels = {'
2025-10-08 11:59:55.105392 | controller |     - '                  + "app" = "ubuntu"'
2025-10-08 11:59:55.105571 | controller |     - '                }'
2025-10-08 11:59:55.105859 | controller |     - '            }'
2025-10-08 11:59:55.106075 | controller |     - ''
2025-10-08 11:59:55.106319 | controller |     - '          + strategy (known after apply)'
2025-10-08 11:59:55.106543 | controller |     - ''
2025-10-08 11:59:55.106806 | controller |     - '          + template {'
2025-10-08 11:59:55.107045 | controller |     - '              + metadata {'
2025-10-08 11:59:55.107257 | controller |     - '                  + generation       = (known after apply)'
2025-10-08 11:59:55.107506 | controller |     - '                  + labels           = {'
2025-10-08 11:59:55.107749 | controller |     - '                      + "app" = "ubuntu"'
2025-10-08 11:59:55.108009 | controller |     - '                    }'
2025-10-08 11:59:55.108218 | controller |     - '                  + name             = (known after apply)'
2025-10-08 11:59:55.108432 | controller |     - '                  + resource_version = (known after apply)'
2025-10-08 11:59:55.108677 | controller |     - '                  + uid              = (known after apply)'
2025-10-08 11:59:55.108914 | controller |     - '                }'
2025-10-08 11:59:55.109138 | controller |     - '              + spec {'
2025-10-08 11:59:55.109539 | controller |     - '                  + automount_service_account_token  = true'
2025-10-08 11:59:55.109795 | controller |     - '                  + dns_policy                       = "ClusterFirst"'
2025-10-08 11:59:55.110024 | controller |     - '                  + enable_service_links             = true'
2025-10-08 11:59:55.110435 | controller |     - '                  + host_ipc                         = false'
2025-10-08 11:59:55.110682 | controller |     - '                  + host_network                     = false'
2025-10-08 11:59:55.110898 | controller |     - '                  + host_pid                         = false'
2025-10-08 11:59:55.111151 | controller |     - '                  + hostname                         = (known after apply)'
2025-10-08 11:59:55.111428 | controller |     - '                  + node_name                        = (known after apply)'
2025-10-08 11:59:55.111697 | controller |     - '                  + restart_policy                   = "Always"'
2025-10-08 11:59:55.111950 | controller |     - '                  + scheduler_name                   = (known after apply)'
2025-10-08 11:59:55.112199 | controller |     - '                  + service_account_name             = (known after apply)'
2025-10-08 11:59:55.112431 | controller |     - '                  + share_process_namespace          = false'
2025-10-08 11:59:55.112731 | controller |     - '                  + termination_grace_period_seconds = 30'
2025-10-08 11:59:55.112941 | controller |     - ''
2025-10-08 11:59:55.113153 | controller |     - '                  + container {'
2025-10-08 11:59:55.113385 | controller |     - '                      + args                       = ['
2025-10-08 11:59:55.113649 | controller |     - '                          + "while true; do sleep 30; done;",'
2025-10-08 11:59:55.113880 | controller |     - '                        ]'
2025-10-08 11:59:55.114080 | controller |     - '                      + command                    = ['
2025-10-08 11:59:55.114307 | controller |     - '                          + "/bin/bash",'
2025-10-08 11:59:55.114514 | controller |     - '                          + "-c",'
2025-10-08 11:59:55.114817 | controller |     - '                          + "--",'
2025-10-08 11:59:55.115018 | controller |     - '                        ]'
2025-10-08 11:59:55.115230 | controller |     - '                      + image                      = "ubuntu:latest"'
2025-10-08 11:59:55.115522 | controller |     - '                      + image_pull_policy          = (known after apply)'
2025-10-08 11:59:55.115789 | controller |     - '                      + name                       = "ubuntu"'
2025-10-08 11:59:55.116013 | controller |     - '                      + stdin                      = false'
2025-10-08 11:59:55.116291 | controller |     - '                      + stdin_once                 = false'
2025-10-08 11:59:55.116491 | controller |     - '                      + termination_message_path   = "/dev/termination-log"'
2025-10-08 11:59:55.116731 | controller |     - '                      + termination_message_policy = (known after apply)'
2025-10-08 11:59:55.116983 | controller |     - '                      + tty                        = false'
2025-10-08 11:59:55.117176 | controller |     - ''
2025-10-08 11:59:55.117413 | controller |     - '                      + resources (known after apply)'
2025-10-08 11:59:55.117664 | controller |     - '                    }'
2025-10-08 11:59:55.117907 | controller |     - ''
2025-10-08 11:59:55.118101 | controller |     - '                  + image_pull_secrets (known after apply)'
2025-10-08 11:59:55.118352 | controller |     - ''
2025-10-08 11:59:55.118578 | controller |     - '                  + readiness_gate (known after apply)'
2025-10-08 11:59:55.118872 | controller |     - '                }'
2025-10-08 11:59:55.119037 | controller |     - '            }'
2025-10-08 11:59:55.119286 | controller |     - '        }'
2025-10-08 11:59:55.119496 | controller |     - '    }'
2025-10-08 11:59:55.119724 | controller |     - ''
2025-10-08 11:59:55.119998 | controller |     - '  # kubernetes_namespace.tofu-tests will be created'
2025-10-08 11:59:55.120214 | controller |     - '  + resource "kubernetes_namespace" "tofu-tests" {'
2025-10-08 11:59:55.120446 | controller |     - '      + id                               = (known after apply)'
2025-10-08 11:59:55.120690 | controller |     - '      + wait_for_default_service_account = false'
2025-10-08 11:59:55.120887 | controller |     - ''
2025-10-08 11:59:55.121094 | controller |     - '      + metadata {'
2025-10-08 11:59:55.121293 | controller |     - '          + generation       = (known after apply)'
2025-10-08 11:59:55.121508 | controller |     - '          + name             = "tofu-experiements"'
2025-10-08 11:59:55.121729 | controller |     - '          + resource_version = (known after apply)'
2025-10-08 11:59:55.121954 | controller |     - '          + uid              = (known after apply)'
2025-10-08 11:59:55.122161 | controller |     - '        }'
2025-10-08 11:59:55.122354 | controller |     - '    }'
2025-10-08 11:59:55.122529 | controller |     - ''
2025-10-08 11:59:55.122779 | controller |     - 'Plan: 2 to add, 0 to change, 0 to destroy.'
2025-10-08 11:59:55.122985 | controller |   - 'Error:'
2025-10-08 11:59:55.123187 | controller |   - []
2025-10-08 11:59:55.123429 | controller |
2025-10-08 11:59:55.123606 | controller | PLAY RECAP *********************************************************************
2025-10-08 11:59:55.123921 | controller | instance                   : ok=9    changed=6    unreachable=0    failed=0    skipped=3    rescued=0    ignored=0
2025-10-08 11:59:55.124087 | controller |
2025-10-08 11:59:55.124334 | controller | Wednesday 08 October 2025  11:59:55 +0000 (0:00:00.084)       0:00:14.559 *****
2025-10-08 11:59:55.124527 | controller | ===============================================================================
2025-10-08 11:59:55.124764 | controller | tofu : Install OpenTofu RPM --------------------------------------------- 6.41s
2025-10-08 11:59:55.124975 | controller | tofu : Tofu Action ------------------------------------------------------ 4.59s
2025-10-08 11:59:55.125180 | controller | Gathering Facts --------------------------------------------------------- 1.20s
2025-10-08 11:59:55.125410 | controller | tofu : Copy Local Tufo Project Directory To Remote Host '{{ ansible_host }}' --- 0.82s
2025-10-08 11:59:55.125648 | controller | tofu : Return tofu to registered var ------------------------------------ 0.43s
2025-10-08 11:59:55.125857 | controller | tofu : Add OpenTofu repository ------------------------------------------ 0.39s
2025-10-08 11:59:55.126053 | controller | tofu : Create Tofu Directory -------------------------------------------- 0.39s
2025-10-08 11:59:55.126263 | controller | tofu : Summarize Tofu Execution ----------------------------------------- 0.08s
2025-10-08 11:59:55.126489 | controller | tofu : Fail If Check Mode Is Not Correct -------------------------------- 0.06s
2025-10-08 11:59:55.126675 | controller | tofu : Fail If Tofu Project Path Not Defined ---------------------------- 0.06s
2025-10-08 11:59:55.126938 | controller | tofu : Update Remote Execution Variable --------------------------------- 0.05s
2025-10-08 11:59:55.127121 | controller | tofu : Fail If infrastructure State Is Not Correct ---------------------- 0.04s
2025-10-08 11:59:55.127374 | controller | Prepare Remote Environment If Required ---------------------------------- 0.04s
2025-10-08 11:59:55.182221 | controller | INFO     Running default > cleanup
2025-10-08 11:59:55.183081 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-08 11:59:55.184247 | controller | INFO     Writing /tmp/report.html report.
2025-10-08 11:59:55.413535 | controller | changed
2025-10-08 11:59:55.450314 | 
2025-10-08 11:59:55.450461 | PLAY RECAP
2025-10-08 11:59:55.450505 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 11:59:55.450524 | 
2025-10-08 11:59:55.602053 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 11:59:55.604377 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 11:59:56.213132 | 
2025-10-08 11:59:56.213277 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-08 11:59:56.235384 | 
2025-10-08 11:59:56.235471 | TASK [Filter out host if needed]
2025-10-08 11:59:56.244412 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-08 11:59:56.249122 | 
2025-10-08 11:59:56.249192 | TASK [Ensure file is present]
2025-10-08 11:59:56.861925 | controller | ok
2025-10-08 11:59:56.874832 | 
2025-10-08 11:59:56.874969 | TASK [Manage molecule report file]
2025-10-08 11:59:57.916309 | controller | changed
2025-10-08 11:59:57.929935 | 
2025-10-08 11:59:57.930088 | TASK [Check if we get ci-framework-data basedir]
2025-10-08 11:59:58.271460 | controller | ok
2025-10-08 11:59:58.277363 | 
2025-10-08 11:59:58.277429 | TASK [Create ci-framework-data log directory for zuul]
2025-10-08 11:59:58.825959 | controller | changed
2025-10-08 11:59:58.841336 | 
2025-10-08 11:59:58.841795 | TASK [Copy ci-framework interesting files]
2025-10-08 11:59:59.218938 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-08 11:59:59.393468 | controller | changed
2025-10-08 11:59:59.401587 | 
2025-10-08 11:59:59.401724 | TASK [Get SELinux listing]
2025-10-08 11:59:59.955909 | controller | changed
2025-10-08 11:59:59.969583 | 
2025-10-08 11:59:59.969795 | TASK [Generate log index]
2025-10-08 12:00:01.726141 | controller | changed
2025-10-08 12:00:01.733536 | 
2025-10-08 12:00:01.733712 | TASK [Get some env related data]
2025-10-08 12:00:02.708098 | controller | /home/zuul/.local/bin/ansible
2025-10-08 12:00:03.777828 | controller | changed
2025-10-08 12:00:03.785529 | 
2025-10-08 12:00:03.785624 | TASK [Generate list of logs to collect in home directory]
2025-10-08 12:00:04.252300 | controller | ok: All paths examined
2025-10-08 12:00:04.267413 | 
2025-10-08 12:00:04.267568 | LOOP [Copy logs from home directory]
2025-10-08 12:00:05.157516 | controller | changed:
2025-10-08 12:00:05.158347 | controller | {
2025-10-08 12:00:05.158447 | controller |   "atime": 1743544925.4788878,
2025-10-08 12:00:05.158513 | controller |   "ctime": 1743545329.1409318,
2025-10-08 12:00:05.158570 | controller |   "dev": 64513,
2025-10-08 12:00:05.158623 | controller |   "gid": 1000,
2025-10-08 12:00:05.158735 | controller |   "gr_name": "zuul",
2025-10-08 12:00:05.158807 | controller |   "inode": 4518807,
2025-10-08 12:00:05.158869 | controller |   "isblk": false,
2025-10-08 12:00:05.158930 | controller |   "ischr": false,
2025-10-08 12:00:05.158983 | controller |   "isdir": false,
2025-10-08 12:00:05.159035 | controller |   "isfifo": false,
2025-10-08 12:00:05.159095 | controller |   "isgid": false,
2025-10-08 12:00:05.159158 | controller |   "islnk": false,
2025-10-08 12:00:05.159218 | controller |   "isreg": true,
2025-10-08 12:00:05.159280 | controller |   "issock": false,
2025-10-08 12:00:05.159344 | controller |   "isuid": false,
2025-10-08 12:00:05.159408 | controller |   "mode": "0644",
2025-10-08 12:00:05.159470 | controller |   "mtime": 1743545329.1409318,
2025-10-08 12:00:05.159529 | controller |   "nlink": 1,
2025-10-08 12:00:05.159586 | controller |   "path": "/home/zuul/crc-setup.log",
2025-10-08 12:00:05.159642 | controller |   "pw_name": "zuul",
2025-10-08 12:00:05.159782 | controller |   "rgrp": true,
2025-10-08 12:00:05.159846 | controller |   "roth": true,
2025-10-08 12:00:05.159899 | controller |   "rusr": true,
2025-10-08 12:00:05.159954 | controller |   "size": 4108,
2025-10-08 12:00:05.160008 | controller |   "uid": 1000,
2025-10-08 12:00:05.160059 | controller |   "wgrp": false,
2025-10-08 12:00:05.160112 | controller |   "woth": false,
2025-10-08 12:00:05.160173 | controller |   "wusr": true,
2025-10-08 12:00:05.160226 | controller |   "xgrp": false,
2025-10-08 12:00:05.160281 | controller |   "xoth": false,
2025-10-08 12:00:05.160332 | controller |   "xusr": false
2025-10-08 12:00:05.160383 | controller | }
2025-10-08 12:00:05.878224 | controller | changed:
2025-10-08 12:00:05.878453 | controller | {
2025-10-08 12:00:05.878506 | controller |   "atime": 1743545331.0429637,
2025-10-08 12:00:05.878547 | controller |   "ctime": 1743545842.0111232,
2025-10-08 12:00:05.878583 | controller |   "dev": 64513,
2025-10-08 12:00:05.878618 | controller |   "gid": 1000,
2025-10-08 12:00:05.878653 | controller |   "gr_name": "zuul",
2025-10-08 12:00:05.878725 | controller |   "inode": 4194437,
2025-10-08 12:00:05.878763 | controller |   "isblk": false,
2025-10-08 12:00:05.878797 | controller |   "ischr": false,
2025-10-08 12:00:05.878831 | controller |   "isdir": false,
2025-10-08 12:00:05.878864 | controller |   "isfifo": false,
2025-10-08 12:00:05.878897 | controller |   "isgid": false,
2025-10-08 12:00:05.878930 | controller |   "islnk": false,
2025-10-08 12:00:05.878963 | controller |   "isreg": true,
2025-10-08 12:00:05.878996 | controller |   "issock": false,
2025-10-08 12:00:05.879028 | controller |   "isuid": false,
2025-10-08 12:00:05.879061 | controller |   "mode": "0644",
2025-10-08 12:00:05.879095 | controller |   "mtime": 1743545842.0111232,
2025-10-08 12:00:05.879129 | controller |   "nlink": 1,
2025-10-08 12:00:05.879162 | controller |   "path": "/home/zuul/crc-start.log",
2025-10-08 12:00:05.879196 | controller |   "pw_name": "zuul",
2025-10-08 12:00:05.879231 | controller |   "rgrp": true,
2025-10-08 12:00:05.879265 | controller |   "roth": true,
2025-10-08 12:00:05.879298 | controller |   "rusr": true,
2025-10-08 12:00:05.879332 | controller |   "size": 4023,
2025-10-08 12:00:05.879366 | controller |   "uid": 1000,
2025-10-08 12:00:05.879399 | controller |   "wgrp": false,
2025-10-08 12:00:05.879433 | controller |   "woth": false,
2025-10-08 12:00:05.879466 | controller |   "wusr": true,
2025-10-08 12:00:05.879499 | controller |   "xgrp": false,
2025-10-08 12:00:05.879531 | controller |   "xoth": false,
2025-10-08 12:00:05.879564 | controller |   "xusr": false
2025-10-08 12:00:05.879597 | controller | }
2025-10-08 12:00:06.609242 | controller | changed:
2025-10-08 12:00:06.609524 | controller | {
2025-10-08 12:00:06.609588 | controller |   "atime": 1759924084.366988,
2025-10-08 12:00:06.609628 | controller |   "ctime": 1759924100.9414892,
2025-10-08 12:00:06.609703 | controller |   "dev": 64513,
2025-10-08 12:00:06.609745 | controller |   "gid": 1000,
2025-10-08 12:00:06.609780 | controller |   "gr_name": "zuul",
2025-10-08 12:00:06.609820 | controller |   "inode": 4518819,
2025-10-08 12:00:06.611491 | controller |   "isblk": false,
2025-10-08 12:00:06.611968 | controller |   "ischr": false,
2025-10-08 12:00:06.612056 | controller |   "isdir": false,
2025-10-08 12:00:06.612099 | controller |   "isfifo": false,
2025-10-08 12:00:06.612465 | controller |   "isgid": false,
2025-10-08 12:00:06.612530 | controller |   "islnk": false,
2025-10-08 12:00:06.612569 | controller |   "isreg": true,
2025-10-08 12:00:06.612605 | controller |   "issock": false,
2025-10-08 12:00:06.612640 | controller |   "isuid": false,
2025-10-08 12:00:06.612715 | controller |   "mode": "0644",
2025-10-08 12:00:06.612754 | controller |   "mtime": 1759924100.9414892,
2025-10-08 12:00:06.613085 | controller |   "nlink": 1,
2025-10-08 12:00:06.613126 | controller |   "path": "/home/zuul/ansible.log",
2025-10-08 12:00:06.613267 | controller |   "pw_name": "zuul",
2025-10-08 12:00:06.613315 | controller |   "rgrp": true,
2025-10-08 12:00:06.613352 | controller |   "roth": true,
2025-10-08 12:00:06.613403 | controller |   "rusr": true,
2025-10-08 12:00:06.613440 | controller |   "size": 6701,
2025-10-08 12:00:06.613475 | controller |   "uid": 1000,
2025-10-08 12:00:06.613508 | controller |   "wgrp": false,
2025-10-08 12:00:06.613541 | controller |   "woth": false,
2025-10-08 12:00:06.613575 | controller |   "wusr": true,
2025-10-08 12:00:06.613609 | controller |   "xgrp": false,
2025-10-08 12:00:06.613641 | controller |   "xoth": false,
2025-10-08 12:00:06.613719 | controller |   "xusr": false
2025-10-08 12:00:06.613757 | controller | }
2025-10-08 12:00:06.635890 | 
2025-10-08 12:00:06.636067 | TASK [Copy crio stats log file]
2025-10-08 12:00:06.675304 | controller | skipping: Conditional result was False
2025-10-08 12:00:06.687284 | 
2025-10-08 12:00:06.687423 | TASK [Get SELinux related data]
2025-10-08 12:00:07.055630 | controller | <no matches>
2025-10-08 12:00:07.243949 | controller | ERROR
2025-10-08 12:00:07.244334 | controller | {
2025-10-08 12:00:07.244395 | controller |   "delta": "0:00:00.013143",
2025-10-08 12:00:07.244438 | controller |   "end": "2025-10-08 12:00:07.056929",
2025-10-08 12:00:07.244478 | controller |   "msg": "non-zero return code",
2025-10-08 12:00:07.244514 | controller |   "rc": 1,
2025-10-08 12:00:07.244551 | controller |   "start": "2025-10-08 12:00:07.043786"
2025-10-08 12:00:07.244586 | controller | }
2025-10-08 12:00:07.244634 | controller | ERROR: Ignoring Errors
2025-10-08 12:00:07.256340 | 
2025-10-08 12:00:07.256478 | TASK [Create system configuration directory]
2025-10-08 12:00:07.624369 | controller | changed
2025-10-08 12:00:07.638258 | 
2025-10-08 12:00:07.639029 | TASK [Get some of the system configurations]
2025-10-08 12:00:08.188900 | controller | changed
2025-10-08 12:00:08.203243 | 
2025-10-08 12:00:08.203391 | TASK [Copy generated documentation if available]
2025-10-08 12:00:08.242387 | controller | skipping: Conditional result was False
2025-10-08 12:00:08.257104 | 
2025-10-08 12:00:08.258374 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-08 12:00:08.298735 | controller | skipping: Conditional result was False
2025-10-08 12:00:08.312714 | 
2025-10-08 12:00:08.312891 | TASK [Compress logs bigger than 2MB]
2025-10-08 12:00:08.865596 | controller | changed
2025-10-08 12:00:08.875253 | 
2025-10-08 12:00:08.875406 | TASK [Copy files from workspace on node]
2025-10-08 12:00:08.912762 | controller | ok
2025-10-08 12:00:08.948320 | 
2025-10-08 12:00:08.948506 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 12:00:08.986277 | controller | skipping: Conditional result was False
2025-10-08 12:00:08.995606 | 
2025-10-08 12:00:08.995757 | TASK [fetch-output : Set log path for single node]
2025-10-08 12:00:09.044411 | controller | ok
2025-10-08 12:00:09.052023 | 
2025-10-08 12:00:09.052124 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 12:00:09.389705 | controller -> localhost | ok: "/var/lib/zuul/builds/ebc0cc789e484eaaa2c1ed28d287b182/work/logs"
2025-10-08 12:00:09.390230 | controller -> localhost | changed: All items complete
2025-10-08 12:00:09.390294 | 
2025-10-08 12:00:09.674781 | controller -> localhost | changed: "/var/lib/zuul/builds/ebc0cc789e484eaaa2c1ed28d287b182/work/artifacts"
2025-10-08 12:00:09.952544 | controller -> localhost | changed: "/var/lib/zuul/builds/ebc0cc789e484eaaa2c1ed28d287b182/work/docs"
2025-10-08 12:00:09.963848 | 
2025-10-08 12:00:09.963962 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 12:00:10.823820 | controller | changed:
2025-10-08 12:00:10.824347 | controller | .d..t...... ./
2025-10-08 12:00:10.824426 | controller | >f+++++++++ README.html
2025-10-08 12:00:10.824475 | controller | >f+++++++++ ansible-execution.log
2025-10-08 12:00:10.824523 | controller | >f+++++++++ ansible.log
2025-10-08 12:00:10.824565 | controller | >f+++++++++ crc-setup.log
2025-10-08 12:00:10.824605 | controller | >f+++++++++ crc-start.log
2025-10-08 12:00:10.824643 | controller | >f+++++++++ dmesg.log
2025-10-08 12:00:10.824736 | controller | >f+++++++++ installed-pkgs.log
2025-10-08 12:00:10.824784 | controller | >f+++++++++ python.log
2025-10-08 12:00:10.824821 | controller | >f+++++++++ registries.conf
2025-10-08 12:00:10.824858 | controller | >f+++++++++ report.html
2025-10-08 12:00:10.824894 | controller | >f+++++++++ selinux-denials.log
2025-10-08 12:00:10.824930 | controller | >f+++++++++ selinux-listing.log
2025-10-08 12:00:10.824965 | controller | cd+++++++++ ci-framework-data/
2025-10-08 12:00:10.825001 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-08 12:00:10.825037 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-08 12:00:10.825085 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-10-08 12:00:10.825123 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-10-08 12:00:10.825160 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-10-08 12:00:10.825196 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-08 12:00:10.825231 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-08 12:00:10.825266 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-08 12:00:10.825308 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-08 12:00:10.825365 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-08 12:00:10.825427 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-08 12:00:10.825473 | controller | cd+++++++++ registries.conf.d/
2025-10-08 12:00:10.825511 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-08 12:00:10.825547 | controller | cd+++++++++ system-config/
2025-10-08 12:00:10.825583 | controller | cd+++++++++ system-config/libvirt/
2025-10-08 12:00:10.825619 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-08 12:00:10.825655 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-08 12:00:10.825766 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-10-08 12:00:10.825808 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-10-08 12:00:10.825844 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-10-08 12:00:10.825879 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-10-08 12:00:10.825914 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-10-08 12:00:10.825949 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-10-08 12:00:10.825984 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-10-08 12:00:10.826018 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-10-08 12:00:10.826053 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-10-08 12:00:10.826088 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-10-08 12:00:10.826122 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-10-08 12:00:10.826158 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-10-08 12:00:10.826193 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-10-08 12:00:10.826228 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-10-08 12:00:11.443445 | controller | changed: .d..t...... ./
2025-10-08 12:00:12.083741 | controller | changed: .d..t...... ./
2025-10-08 12:00:12.116434 | 
2025-10-08 12:00:12.116699 | TASK [Return artifact to Zuul]
2025-10-08 12:00:12.176889 | controller | ok
2025-10-08 12:00:12.212699 | 
2025-10-08 12:00:12.212918 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-08 12:00:12.213082 | 
2025-10-08 12:00:12.213121 | PLAY RECAP
2025-10-08 12:00:12.213289 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-08 12:00:12.213327 | 
2025-10-08 12:00:12.391928 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 12:00:12.394210 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 12:00:13.075524 | 
2025-10-08 12:00:13.075712 | PLAY [all]
2025-10-08 12:00:13.096062 | 
2025-10-08 12:00:13.096262 | TASK [include_role : fetch-output]
2025-10-08 12:00:13.152312 | controller | ok
2025-10-08 12:00:13.183144 | 
2025-10-08 12:00:13.183381 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 12:00:13.273743 | controller | skipping: Conditional result was False
2025-10-08 12:00:13.280964 | 
2025-10-08 12:00:13.281337 | TASK [fetch-output : Set log path for single node]
2025-10-08 12:00:13.345546 | controller | ok
2025-10-08 12:00:13.351438 | 
2025-10-08 12:00:13.351537 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 12:00:13.857367 | controller -> localhost | ok: "/var/lib/zuul/builds/ebc0cc789e484eaaa2c1ed28d287b182/work/logs"
2025-10-08 12:00:14.083861 | controller -> localhost | ok: "/var/lib/zuul/builds/ebc0cc789e484eaaa2c1ed28d287b182/work/artifacts"
2025-10-08 12:00:14.355706 | controller -> localhost | ok: "/var/lib/zuul/builds/ebc0cc789e484eaaa2c1ed28d287b182/work/docs"
2025-10-08 12:00:14.366796 | 
2025-10-08 12:00:14.366879 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 12:00:15.184004 | controller | ok
2025-10-08 12:00:15.184268 | controller | ok: All items complete
2025-10-08 12:00:15.184296 | 
2025-10-08 12:00:15.816094 | controller | ok
2025-10-08 12:00:16.473042 | controller | ok
2025-10-08 12:00:16.495733 | 
2025-10-08 12:00:16.495824 | TASK [include_role : fetch-output-openshift]
2025-10-08 12:00:16.521903 | controller | skipping: Conditional result was False
2025-10-08 12:00:16.530074 | 
2025-10-08 12:00:16.530174 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-08 12:00:17.037347 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.009573
2025-10-08 12:00:17.289007 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009271
2025-10-08 12:00:17.319492 | 
2025-10-08 12:00:17.319566 | PLAY [all]
2025-10-08 12:00:17.334762 | 
2025-10-08 12:00:17.334829 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-08 12:00:18.032727 | controller | changed
2025-10-08 12:00:18.084776 | 
2025-10-08 12:00:18.084986 | PLAY RECAP
2025-10-08 12:00:18.085074 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-08 12:00:18.085117 | 
2025-10-08 12:00:18.272897 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 12:00:18.273769 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-08 12:00:18.993857 | 
2025-10-08 12:00:18.994012 | PLAY [localhost]
2025-10-08 12:00:19.012597 | 
2025-10-08 12:00:19.012693 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-08 12:00:19.457285 | localhost | changed
2025-10-08 12:00:19.468104 | 
2025-10-08 12:00:19.468264 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-08 12:00:19.525436 | localhost | ok
2025-10-08 12:00:19.543308 | 
2025-10-08 12:00:19.543422 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-08 12:00:20.031509 | localhost | changed
2025-10-08 12:00:20.037382 | 
2025-10-08 12:00:20.037481 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-08 12:00:20.792115 | localhost | changed
2025-10-08 12:00:20.803344 | 
2025-10-08 12:00:20.803485 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-08 12:00:21.262705 | localhost | Identity added: /var/lib/zuul/builds/ebc0cc789e484eaaa2c1ed28d287b182/work/tmp/ansible.vtx03c0o (/var/lib/zuul/builds/ebc0cc789e484eaaa2c1ed28d287b182/work/tmp/ansible.vtx03c0o)
2025-10-08 12:00:21.262917 | localhost | ok: Runtime: 0:00:00.014043
2025-10-08 12:00:21.267437 | 
2025-10-08 12:00:21.267502 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-08 12:00:21.569793 | localhost | ok: Runtime: 0:00:00.009330
2025-10-08 12:00:21.575322 | 
2025-10-08 12:00:21.575387 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-08 12:00:21.655420 | localhost | changed
2025-10-08 12:00:21.660142 | 
2025-10-08 12:00:21.660206 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-08 12:00:22.137485 | localhost | changed
2025-10-08 12:00:22.159516 | 
2025-10-08 12:00:22.159679 | PLAY [localhost]
2025-10-08 12:00:22.174368 | 
2025-10-08 12:00:22.174509 | TASK [Generate bulk log download script]
2025-10-08 12:00:22.193380 | localhost | ok
2025-10-08 12:00:22.205965 | 
2025-10-08 12:00:22.206047 | TASK [local-log-download : Check API endpoint is defined]
2025-10-08 12:00:22.246781 | localhost | ok: All assertions passed
2025-10-08 12:00:22.253852 | 
2025-10-08 12:00:22.253948 | TASK [local-log-download : Create download script]
2025-10-08 12:00:22.727265 | localhost -> localhost | changed
2025-10-08 12:00:22.739930 | 
2025-10-08 12:00:22.740056 | TASK [Register quick-download link]
2025-10-08 12:00:22.807297 | localhost | ok
2025-10-08 12:00:22.822838 | 
2025-10-08 12:00:22.822910 | PLAY [logserver.rdoproject.org]
2025-10-08 12:00:22.832641 | 
2025-10-08 12:00:22.832717 | TASK [Set zuul-log-path fact]
2025-10-08 12:00:22.859432 | logserver.rdoproject.org | ok
2025-10-08 12:00:22.869436 | 
2025-10-08 12:00:22.869502 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 12:00:22.896471 | logserver.rdoproject.org | ok
2025-10-08 12:00:22.902326 | 
2025-10-08 12:00:22.902388 | TASK [upload-logs : Create log directories]
2025-10-08 12:00:23.639119 | logserver.rdoproject.org | changed
2025-10-08 12:00:23.648551 | 
2025-10-08 12:00:23.648845 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-08 12:00:23.963320 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.010913
2025-10-08 12:00:23.974366 | 
2025-10-08 12:00:23.974508 | TASK [upload-logs : Upload logs to log server]
2025-10-08 12:00:24.763737 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-08 12:00:24.768365 | 
2025-10-08 12:00:24.768456 | LOOP [upload-logs : Compress console log and json output]
2025-10-08 12:00:24.840545 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:00:24.852541 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:00:24.866219 | 
2025-10-08 12:00:24.866408 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-08 12:00:24.917691 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:00:24.918152 | 
2025-10-08 12:00:24.921546 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:00:24.931821 | 
2025-10-08 12:00:24.931959 | LOOP [upload-logs : Upload console log and json output]